[mythtv-users] Backend Crashes?

Sat Oct 7 00:19:05 UTC 2006

Hi all,

I'm experiencing splitting of recordings. I've turned up the logging a
bit and am seeing the master server starting multiple time coincident
with the splits. I am assuming that this is occurring because the
backend is crashing and then being restarted? Can anyone point me in the
right direction?

I'm running Gentoo, and mythtv 0.20_p11281 with xvmc support compiled
in. I've attached a piece of the log file with three crashes (if that's
what they are), the first at 22:13, then 22:34, and 22:55. Myth went on
to record another couple of shows without splits, and then another with
two splits.

Thanks in advance,
Bill.

Log file follows:

Starting up as the master server.
2006-10-05 22:13:38.765 New DB connection, total: 2
2006-10-05 22:13:38.779 Connected to database 'mythconverg' at host:
localhost
2006-10-05 22:13:38.781 mythbackend: MythBackend started as master
server
2006-10-05 22:13:38.813 EITHelper: localtime offset -4:00:00
2006-10-05 22:13:38.836 New DB connection, total: 3
2006-10-05 22:13:38.842 Connected to database 'mythconverg' at host:
localhost
2006-10-05 22:13:38.887 New DB scheduler connection
2006-10-05 22:13:38.894 Connected to database 'mythconverg' at host:
localhost
2006-10-05 22:13:38.898 Main::Starting HttpServer
2006-10-05 22:13:38.974 Main::Registering HttpStatus Extension
2006-10-05 22:13:39.008 mythbackend version: 0.20.20060828-3
www.mythtv.org
2006-10-05 22:13:39.021 Enabled verbose msgs: important general
2006-10-05 22:13:39.022 AutoExpire: Found 1 recorders w/max rate of 72
MiB/min
2006-10-05 22:13:39.024 AutoExpire: Required Free Space: 2.1 GB w/freq:
10 min
2006-10-05 22:13:40.961 Reschedule requested for id -1.
2006-10-05 22:13:42.891 Scheduled 18 items in 1.9 = 1.90 match + 0.04
place
2006-10-05 22:13:42.904 scheduler: Scheduled items: Scheduled 18 items
in 1.9 = 1.90 match + 0.04 pla
ce
2006-10-05 22:13:42.912 Recording starts soon, AUTO-Startup assumed
2006-10-05 22:13:42.982 TVRec(1): Changing from None to RecordingOnly
2006-10-05 22:13:43.016 TVRec(1): HW Tuner: 1->1
2006-10-05 22:13:43.144 Started recording: ER "Somebody to Love":
channel 1010 on cardid 1, sourceid
1
2006-10-05 22:13:43.173 scheduler: Started recording: ER "Somebody to
Love": channel 1010 on cardid 1
, sourceid 1
2006-10-05 22:13:48.793 mythbackend: Running housekeeping thread
2006-10-05 22:34:36.325 Using runtime prefix = /usr
2006-10-05 22:34:36.432 New DB connection, total: 1
2006-10-05 22:34:36.484 Connected to database 'mythconverg' at host:
localhost
2006-10-05 22:34:36.511 Current Schema Version: 1160

Starting up as the master server.
2006-10-05 22:34:36.526 New DB connection, total: 2
2006-10-05 22:34:36.551 Connected to database 'mythconverg' at host:
localhost
2006-10-05 22:34:36.553 mythbackend: MythBackend started as master
server
2006-10-05 22:34:36.568 EITHelper: localtime offset -4:00:00
2006-10-05 22:34:36.587 New DB connection, total: 3
2006-10-05 22:34:36.594 Connected to database 'mythconverg' at host:
localhost
2006-10-05 22:34:36.659 New DB scheduler connection
2006-10-05 22:34:36.660 Connected to database 'mythconverg' at host:
localhost
2006-10-05 22:34:36.704 Main::Starting HttpServer
2006-10-05 22:34:36.711 Main::Registering HttpStatus Extension
2006-10-05 22:34:36.728 mythbackend version: 0.20.20060828-3
www.mythtv.org
2006-10-05 22:34:36.743 Enabled verbose msgs: important general
2006-10-05 22:34:36.744 AutoExpire: Found 1 recorders w/max rate of 72
MiB/min
2006-10-05 22:34:36.746 AutoExpire: Required Free Space: 2.1 GB w/freq:
10 min
2006-10-05 22:34:38.713 Reschedule requested for id -1.
2006-10-05 22:34:39.279 Scheduled 18 items in 0.6 = 0.53 match + 0.04
place
2006-10-05 22:34:39.295 scheduler: Scheduled items: Scheduled 18 items
in 0.6 = 0.53 match + 0.04 place
2006-10-05 22:34:39.299 Recording starts soon, AUTO-Startup assumed
2006-10-05 22:34:39.387 TVRec(1): Changing from None to RecordingOnly
2006-10-05 22:34:39.418 TVRec(1): HW Tuner: 1->1
2006-10-05 22:34:39.540 Started recording: ER "Somebody to Love":
channel 1010 on cardid 1, sourceid 1
2006-10-05 22:34:39.569 scheduler: Started recording: ER "Somebody to
Love": channel 1010 on cardid 1, sourceid 1
2006-10-05 22:34:46.575 mythbackend: Running housekeeping thread
2006-10-05 22:55:34.509 Using runtime prefix = /usr
2006-10-05 22:55:34.671 New DB connection, total: 1
2006-10-05 22:55:34.713 Connected to database 'mythconverg' at host:
localhost
2006-10-05 22:55:34.752 Current Schema Version: 1160

Starting up as the master server.
2006-10-05 22:55:34.839 New DB connection, total: 2
2006-10-05 22:55:34.906 Connected to database 'mythconverg' at host:
localhost
2006-10-05 22:55:34.908 mythbackend: MythBackend started as master
server
2006-10-05 22:55:34.937 EITHelper: localtime offset -4:00:00
2006-10-05 22:55:34.974 New DB connection, total: 3
2006-10-05 22:55:35.005 Connected to database 'mythconverg' at host:
localhost
2006-10-05 22:55:35.062 New DB scheduler connection
2006-10-05 22:55:35.063 Connected to database 'mythconverg' at host:
localhost
2006-10-05 22:55:35.082 Main::Starting HttpServer
2006-10-05 22:55:35.148 Main::Registering HttpStatus Extension
2006-10-05 22:55:35.173 mythbackend version: 0.20.20060828-3
www.mythtv.org
2006-10-05 22:55:35.195 Enabled verbose msgs: important general
2006-10-05 22:55:35.196 AutoExpire: Found 1 recorders w/max rate of 72
MiB/min
2006-10-05 22:55:35.198 AutoExpire: Required Free Space: 2.1 GB w/freq:
10 min
2006-10-05 22:55:37.135 Reschedule requested for id -1.
2006-10-05 22:55:39.158 Scheduled 18 items in 2.0 = 1.98 match + 0.04
place
2006-10-05 22:55:39.168 scheduler: Scheduled items: Scheduled 18 items
in 2.0 = 1.98 match + 0.04 place
2006-10-05 22:55:39.172 Recording starts soon, AUTO-Startup assumed
2006-10-05 22:55:39.273 TVRec(1): Changing from None to RecordingOnly
2006-10-05 22:55:39.310 TVRec(1): HW Tuner: 1->1
2006-10-05 22:55:39.435 Started recording: ER "Somebody to Love":
channel 1010 on cardid 1, sourceid 1
2006-10-05 22:55:39.453 scheduler: Started recording: ER "Somebody to
Love": channel 1010 on cardid 1, sourceid 1
2006-10-05 22:55:44.927 mythbackend: Running housekeeping thread
2006-10-05 23:00:00.089 TVRec(1): Changing from RecordingOnly to None
2006-10-05 23:00:00.103 Finished recording ER "Somebody to Love":
channel 1010
2006-10-05 23:00:00.107 scheduler: Finished recording: ER "Somebody to
Love": channel 1010
2006-10-05 23:00:00.450 Finished recording ER "Somebody to Love":
channel 1010
0: start_time: 0.036 duration: 23.369
1: start_time: 0.026 duration: 23.343
stream: start_time: 0.289 duration: 259.770 bitrate=5205 kb/s
2006-10-05 23:00:00.555 AFD: Opened codec 0x81e8690, id(MPEG2VIDEO)
type(Video)
2006-10-05 23:00:00.620 AFD: Opened codec 0x818f1f0, id(MP2) type(Audio)
2006-10-05 23:00:02.117 TVRec(1): Changing from None to RecordingOnly
2006-10-05 23:00:02.154 TVRec(1): HW Tuner: 1->1
2006-10-05 23:00:02.272 Started recording: The Daily Show With Jon
Stewart: channel 1045 on cardid 1, sourceid 1
2006-10-05 23:00:02.313 scheduler: Last message repeated 1 times:
Finished recording: ER "Somebody to Love": channel 1010
2006-10-05 23:00:02.317 scheduler: Started recording: The Daily Show
With Jon Stewart: channel 1045 on cardid 1, sourceid 1
2006-10-05 23:00:03.329 Reschedule requested for id 0.
2006-10-05 23:00:03.345 Scheduled 17 items in 0.0 = 0.00 match + 0.01
place
2006-10-05 23:00:03.347 scheduler: Scheduled items: Scheduled 17 items
in 0.0 = 0.00 match + 0.01 place
2006-10-05 23:00:45.180 JobQueue: Commercial Flagging Starting for ER
"Somebody to Love" recorded from channel 1010 at Thu Oct 5 22:56:00 2006
2006-10-05 23:00:45.214 commflag: Commercial Flagging Starting: ER
"Somebody to Love" recorded from channel 1010 at Thu Oct 5 22:56:00 2006
2006-10-05 23:00:45.435 Using runtime prefix = /usr
2006-10-05 23:00:45.479 New DB connection, total: 1