[mythtv-users] Frequent backend crashing

Fri Aug 17 01:54:39 UTC 2007

Hi,

I've seen a few threads on backend crash problems, so I thought I'd add 
my info to the heap.  I have not been able to diagnose this problem for 
some months now, despite quite a bit of hunting through this list.  I 
have set up a cron job to periodically check for the dead backend and 
restart it within a few minutes.  When this happens the restart script 
appends a message "BACKEND DEAD - RESTARTING" to the mythbackend.log so 
I know that it has corrected a dead backend situation.  Any ideas/clues 
how to test this problem further are greatly appreciated.  It is 
probably my #2 biggest myth problem at the moment, and would love to 
figure it out.

My configuration is two separate backend servers, with three tuner cards 
total.  One frontend server separate from those backends.  "mythbackend 
--version" reports:

================
Library API version: 0.20.20060828-3
Source code version: Unknown
Options compiled in:
 linux release using_xvmcw using_lmsensors using_v4l using_oss 
using_alsa using_arts using_jack using_ivtv using_firewire using_dbox2 
using_hdhr using_ip_rec using_freebox using_live using_lirc 
using_joystick_menu using_dvb using_x11 using_xv using_xrandr using_xvmc 
using_xvmc_vld using_opengl_vsync using_opengl using_frontend using_backend
================

With my current log file, "grep -B 5 BACKEND mythbackend.log" reports:

================
2007-08-03 14:07:12.534 autoexpire: Expiring Program: Expiring 10 Years 
Younger "Starting Over" from Thu Aug 2 13:30:00 2007, 1125 MBytes, 
forced expire (LiveTV recording)
2007-08-03 14:08:43.273 TVRec(1): Changing from WatchingLiveTV to None
2007-08-03 14:08:43.377 Finished recording Family Feud: channel 1006
2007-08-03 14:08:43.388 scheduler: Finished recording: Family Feud: 
channel 1006
2007-08-03 14:08:43.434 Unknown file transfer socket: 0
BACKEND DEAD - RESTARTING
--
2007-08-08 09:30:25.252 MainServer::HandleAnnounce Monitor
2007-08-08 09:30:25.256 adding: grape as a client (events: 0)
2007-08-08 09:30:25.258 MainServer::HandleAnnounce Monitor
2007-08-08 09:30:25.260 adding: grape as a client (events: 1)
2007-08-08 09:33:17.402 Unknown file transfer socket: 0
BACKEND DEAD - RESTARTING
--
2007-08-08 15:30:04.544 AFD: Opened codec 0x9b94140, id(MPEG2VIDEO) 
type(Video)
2007-08-08 15:30:04.733 AFD: Opened codec 0x98af650, id(MP2) type(Audio)
2007-08-08 15:32:30.486 TVRec(1): Changing from WatchingLiveTV to None
2007-08-08 15:32:30.797 Finished recording Designers' Challenge "Golden 
Gate Game Rooms": channel 1053
2007-08-08 15:32:30.843 Unknown file transfer socket: 0
BACKEND DEAD - RESTARTING
--
2007-08-10 19:56:52.210 TVRec(1): Changing from WatchingLiveTV to None
2007-08-10 19:56:52.740 Finished recording Jeopardy!: channel 1007
2007-08-10 19:56:52.842 scheduler: Last message repeated 1 times: 
Finished recording: Wheel of Fortune "Far East Adventures": channel 1007
2007-08-10 19:56:52.858 scheduler: Finished recording: Jeopardy!: 
channel 1007
2007-08-10 19:56:52.905 Unknown file transfer socket: 0
BACKEND DEAD - RESTARTING
--
2007-08-13 02:03:01.813 TFW, Error: Write() -- IOBOUND begin cnt(2048) 
free(2047)
2007-08-13 02:03:03.186 TFW, Error: Write() -- IOBOUND end
2007-08-13 03:00:00.550 Finished recording My Music: The British Beat: 
channel 1011
2007-08-13 03:00:00.758 scheduler: Finished recording: My Music: The 
British Beat: channel 1011
2007-08-13 03:00:00.991 Unknown file transfer socket: 0
BACKEND DEAD - RESTARTING
--
2007-08-13 09:30:05.949 adding: cherry as a remote file transfer
2007-08-13 09:30:02.891 AFD: Opened codec 0x8fede00, id(MP2) type(Audio)
2007-08-13 09:30:13.148 RingBuf(/mnt/store//1011_20070813093000.mpg): 
Invalid file (fd -1) when opening '/mnt/store//1011_20070813093000.mpg'.
2007-08-13 09:30:13.257 MythSocket(b0d02398:-1): writeStringList: Error, 
socket went unconnected.
2007-08-13 09:30:21.009 Unknown file transfer socket: 0
BACKEND DEAD - RESTARTING
--
[mpeg2video @ 0x64ba24]Warning MVs not available
2007-08-13 19:09:27.661 commflag: Commercial Flagging Finished: Cash Cab 
recorded from channel 1044 at Mon Aug 13 18:30:00 2007: 4 commercial 
break(s)
2007-08-13 19:09:27.668 JobQueue: Commercial Flagging Finished, 4 
break(s) found.
2007-08-13 19:09:27.670 Preview Error: Run() file not local: 
'/mnt/store//1044_20070813183000.mpg'
2007-08-13 19:11:31.784 Unknown file transfer socket: 0
BACKEND DEAD - RESTARTING
--
[mpeg2video @ 0x64ba24]slice mismatch
[mpeg2video @ 0x64ba24]slice mismatch
[mpeg2video @ 0x64ba24]00 motion_type at 5 28
[mpeg2video @ 0x64ba24]00 motion_type at 2 29
[mpeg2video @ 0x64ba24]Warning MVs not available
BACKEND DEAD - RESTARTING
--
2007-08-15 12:07:43.258 TVRec(1): Changing from None to WatchingLiveTV
2007-08-15 12:07:43.261 TVRec(1): HW Tuner: 1->1
2007-08-15 12:07:43.518 TVRec(1): Changing from WatchingLiveTV to None
2007-08-15 12:07:44.076 Finished recording I Love Lucy "Ricky Needs an 
Agent": channel 1013
2007-08-15 12:07:44.124 Event socket closed. No connection to the backend.
BACKEND DEAD - RESTARTING
--
2007-08-15 17:21:33.651 Expiring Gilmore Girls "Die, Jerk" from Tue Aug 
14 17:00:00 2007, 104 MBytes, forced expire (LiveTV recording)
2007-08-15 17:21:33.666 autoexpire: Expiring Program: Expiring Gilmore 
Girls "Die, Jerk" from Tue Aug 14 17:00:00 2007, 104 MBytes, forced 
expire (LiveTV recording)
2007-08-15 17:21:33.669 Expiring Creating Keepsakes Magazine "Paper 
Crafting" from Tue Aug 14 16:00:00 2007, 124 MBytes, forced expire 
(LiveTV recording)
2007-08-15 17:21:33.682 autoexpire: Expiring Program: Expiring Creating 
Keepsakes Magazine "Paper Crafting" from Tue Aug 14 16:00:00 2007, 124 
MBytes, forced expire (LiveTV recording)
2007-08-15 17:27:58.710 Unknown file transfer socket: 0
BACKEND DEAD - RESTARTING
--
[mpeg2video @ 0x64ba24]Warning MVs not available
2007-08-15 19:09:50.251 commflag: Commercial Flagging Finished: Cash Cab 
recorded from channel 1044 at Wed Aug 15 18:30:00 2007: 3 commercial 
break(s)
2007-08-15 19:09:50.257 JobQueue: Commercial Flagging Finished, 3 
break(s) found.
2007-08-15 19:09:50.259 Preview Error: Run() file not local: 
'/mnt/store//1044_20070815183000.mpg'
2007-08-15 19:21:14.443 Unknown file transfer socket: 0
BACKEND DEAD - RESTARTING
--
2007-08-15 23:52:36.389 Scheduled 659 items in 22.1 = 0.42 match + 21.66 
place
2007-08-15 23:52:36.427 scheduler: Scheduled items: Scheduled 659 items 
in 22.1 = 0.42 match + 21.66 place
2007-08-15 23:53:04.457 Unknown socket closing
2007-08-15 23:53:04.470 MainServer::HandleAnnounce Monitor
2007-08-15 23:53:04.474 adding: cherry as a client (events: 0)
BACKEND DEAD - RESTARTING
================