Ticket #6905: mythbackend.log.hang

File mythbackend.log.hang, 18.0 KB (added by David Asher <asherml@…>, 15 years ago)

mythbackend.log from the time where we become non-responsive

Line 
12009-08-24 17:41:25.911 mythbackend version: trunk [exported] www.mythtv.org
22009-08-24 17:41:25.923 Using runtime prefix = /usr
32009-08-24 17:41:25.958 Empty LocalHostName.
42009-08-24 17:41:25.969 Using localhost value of mythtv
52009-08-24 17:41:26.404 Cannot find default UPnP backend
62009-08-24 17:41:26.537 New DB connection, total: 1
72009-08-24 17:41:26.578 Connected to database 'mythconverg' at host: mythtv
82009-08-24 17:41:26.580 Closing DB connection named 'DBManager0'
92009-08-24 17:41:26.581 Deleting UPnP client...
102009-08-24 17:41:27.479 Connected to database 'mythconverg' at host: mythtv
112009-08-24 17:41:27.547 Current Schema Version: 1242
122009-08-24 17:41:27.551 MythBackend: Starting up as the master server.
132009-08-24 17:41:27.652 New DB connection, total: 2
142009-08-24 17:41:27.668 Connected to database 'mythconverg' at host: mythtv
152009-08-24 17:41:27.728 New DB connection, total: 3
162009-08-24 17:41:27.731 Connected to database 'mythconverg' at host: mythtv
172009-08-24 17:41:27.804 TVRec(2) Error: Problem finding starting channel, setting to default of '3'.
182009-08-24 17:41:27.823 ChannelBase(2) Error: InitializeInputs():
19                        Could not get inputs for the capturecard.
20                        Perhaps you have forgotten to bind video
21                        sources to your card's inputs?
222009-08-24 17:41:29.321 New DB scheduler connection
232009-08-24 17:41:29.324 Connected to database 'mythconverg' at host: mythtv
242009-08-24 17:41:29.328 Scheduler, Warning: Listings source 'DirecTV' is defined, but is not attached to a card input.
252009-08-24 17:41:29.377 Enabling Upnpmedia rebuild thread.
262009-08-24 17:41:30.437 Main::Registering HttpStatus Extension
272009-08-24 17:41:30.438 Enabled verbose msgs:  important general
282009-08-24 17:41:30.472 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
292009-08-24 17:41:32.338 Reschedule requested for id -1.
302009-08-24 17:41:33.596 Scheduled 127 items in 1.2 = 0.38 match + 0.87 place
312009-08-24 17:41:33.602 Seem to be woken up by USER
322009-08-24 17:41:39.406 New DB connection, total: 4
332009-08-24 17:41:39.434 Connected to database 'mythconverg' at host: mythtv
342009-08-24 17:41:39.560 UPnpMedia: BuildMediaMap VIDEO scan starting in :/myth/videos:
352009-08-24 17:41:39.648 JobQueue: Commercial Flagging Starting for All My Children recorded from channel 1051 at Mon Aug 24 13:00:00 2009
362009-08-24 17:41:41.315 MainServer::ANN Monitor
372009-08-24 17:41:41.324 adding: mythtv as a client (events: 0)
382009-08-24 17:41:41.326 MainServer::ANN Monitor
392009-08-24 17:41:41.327 adding: mythtv as a client (events: 1)
402009-08-24 17:41:42.075 UPnpMedia: BuildMediaMap Done. Found 367 objects
412009-08-24 17:42:49.335 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
422009-08-24 17:42:49.456 Expiring 1775 MBytes for 1051 @ Mon Aug 17 13:43:00 2009 => All My Children.  Too many episodes, we only want to keep 5.
432009-08-24 17:42:49.566 Reschedule requested for id 0.
442009-08-24 17:42:51.442 Scheduled 127 items in 1.9 = 0.01 match + 1.86 place
452009-08-24 17:42:59.770 Reschedule requested for id 0.
462009-08-24 17:43:01.119 Scheduled 127 items in 1.3 = 0.33 match + 1.01 place
472009-08-24 17:43:33.675 MainServer::ANN Monitor
482009-08-24 17:43:33.682 adding: mythtv as a client (events: 1)
492009-08-24 17:43:36.382 MainServer::ANN Monitor
502009-08-24 17:43:36.383 adding: mythtv as a client (events: 1)
512009-08-24 17:43:36.772 MainServer::ANN Monitor
522009-08-24 17:43:36.772 adding: mythtv as a client (events: 1)
532009-08-24 17:43:37.081 MainServer::ANN Monitor
542009-08-24 17:43:37.082 adding: mythtv as a client (events: 1)
552009-08-24 17:43:38.122 mythbackend version: trunk [exported] www.mythtv.org
562009-08-24 17:43:38.312 Using runtime prefix = /usr
572009-08-24 17:43:38.314 Empty LocalHostName.
582009-08-24 17:43:38.315 Using localhost value of mythtv
592009-08-24 17:43:38.651 Cannot find default UPnP backend
602009-08-24 17:43:38.659 New DB connection, total: 1
612009-08-24 17:43:38.665 Connected to database 'mythconverg' at host: mythtv
622009-08-24 17:43:38.666 Closing DB connection named 'DBManager0'
632009-08-24 17:43:38.666 Deleting UPnP client...
642009-08-24 17:43:40.836 Connected to database 'mythconverg' at host: mythtv
652009-08-24 17:43:40.842 Current Schema Version: 1242
662009-08-24 17:43:40.843 New DB connection, total: 2
672009-08-24 17:43:40.845 Connected to database 'mythconverg' at host: mythtv
682009-08-24 17:43:40.877 Filter dir '/usr/lib/mythtv/filters' doesn't exist?
692009-08-24 17:43:40.895 mpegts_read_header: TS packet size = 188
702009-08-24 17:43:41.050 AFD: Opened codec 0xbce4e0, id(MPEG2VIDEO) type(Video)
712009-08-24 17:43:41.051 AFD: codec AC3 has 6 channels
722009-08-24 17:43:41.052 AFD: Opened codec 0xbcd250, id(AC3) type(Audio)
732009-08-24 17:43:41.691 Preview: Grabbed preview '/myth/rec1/1051_20090824130000.mpg' 1920x1088@64s
742009-08-24 17:50:04.440 MainServer::ANN Monitor
752009-08-24 17:50:04.443 adding: mythtv as a client (events: 0)
762009-08-24 17:57:49.545 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
772009-08-24 18:00:03.817 MainServer::ANN Monitor
782009-08-24 18:00:03.822 adding: mythtv as a client (events: 0)
792009-08-24 18:10:03.456 MainServer::ANN Monitor
802009-08-24 18:10:03.471 adding: mythtv as a client (events: 0)
812009-08-24 18:11:46.276 UPnpMedia: BuildMediaMap VIDEO scan starting in :/myth/videos:
822009-08-24 18:11:48.501 UPnpMedia: BuildMediaMap Done. Found 367 objects
832009-08-24 18:12:49.724 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
842009-08-24 18:20:03.509 MainServer::ANN Monitor
852009-08-24 18:20:03.515 adding: mythtv as a client (events: 0)
862009-08-24 18:27:49.817 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
872009-08-24 18:30:03.468 MainServer::ANN Monitor
882009-08-24 18:30:03.471 adding: mythtv as a client (events: 0)
892009-08-24 18:40:03.462 MainServer::ANN Monitor
902009-08-24 18:40:03.471 adding: mythtv as a client (events: 0)
912009-08-24 18:41:31.468 MythSocket(1b53460:-1): writeStringList: Error, called with unconnected socket.
922009-08-24 18:41:31.553 MythSocket(1b19ba0:-1): writeStringList: Error, called with unconnected socket.
932009-08-24 18:41:31.561 MythSocket(1a9c290:-1): writeStringList: Error, called with unconnected socket.
942009-08-24 18:41:31.562 MythSocket(19db3b0:-1): writeStringList: Error, called with unconnected socket.
952009-08-24 18:41:31.958 MythSocket(1b53460:-1): writeStringList: Error, called with unconnected socket.
962009-08-24 18:41:31.959 MythSocket(1b19ba0:-1): writeStringList: Error, called with unconnected socket.
972009-08-24 18:41:31.960 MythSocket(1a9c290:-1): writeStringList: Error, called with unconnected socket.
982009-08-24 18:41:31.961 MythSocket(19db3b0:-1): writeStringList: Error, called with unconnected socket.
992009-08-24 18:41:35.926 mythbackend version: trunk [exported] www.mythtv.org
1002009-08-24 18:41:35.935 Using runtime prefix = /usr
1012009-08-24 18:41:35.985 Empty LocalHostName.
1022009-08-24 18:41:35.990 Using localhost value of mythtv
1032009-08-24 18:41:36.241 Cannot find default UPnP backend
1042009-08-24 18:41:36.463 New DB connection, total: 1
1052009-08-24 18:41:36.511 Connected to database 'mythconverg' at host: mythtv
1062009-08-24 18:41:36.514 Closing DB connection named 'DBManager0'
1072009-08-24 18:41:36.516 Deleting UPnP client...
1082009-08-24 18:41:37.626 Connected to database 'mythconverg' at host: mythtv
1092009-08-24 18:41:37.644 Current Schema Version: 1242
1102009-08-24 18:41:37.648 New DB connection, total: 2
1112009-08-24 18:41:37.650 Connected to database 'mythconverg' at host: mythtv
1122009-08-24 18:41:37.716 Filter dir '/usr/lib/mythtv/filters' doesn't exist?
1132009-08-24 18:41:37.784 mpegts_read_header: TS packet size = 188
1142009-08-24 18:41:38.062 AFD: Opened codec 0x7fd994068040, id(MPEG2VIDEO) type(Video)
1152009-08-24 18:41:38.063 AFD: codec AC3 has 6 channels
1162009-08-24 18:41:38.066 AFD: Opened codec 0x7fd99406ff50, id(AC3) type(Audio)
1172009-08-24 18:41:38.963 Preview: Grabbed preview '/myth/rec1/1051_20090824130000.mpg' 1920x1088@64s
1182009-08-24 18:41:53.658 UPnpMedia: BuildMediaMap VIDEO scan starting in :/myth/videos:
1192009-08-24 18:41:54.120 UPnpMedia: BuildMediaMap Done. Found 367 objects
1202009-08-24 18:42:49.935 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
1212009-08-24 18:50:03.223 MainServer::ANN Monitor
1222009-08-24 18:50:03.227 adding: mythtv as a client (events: 0)
1232009-08-24 18:57:50.023 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
1242009-08-24 19:00:02.995 MainServer::ANN Monitor
1252009-08-24 19:00:02.999 adding: mythtv as a client (events: 0)
1262009-08-24 19:10:02.778 MainServer::ANN Monitor
1272009-08-24 19:10:02.783 adding: mythtv as a client (events: 0)
1282009-08-24 19:11:58.174 UPnpMedia: BuildMediaMap VIDEO scan starting in :/myth/videos:
1292009-08-24 19:11:58.486 UPnpMedia: BuildMediaMap Done. Found 367 objects
1302009-08-24 19:12:50.107 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
1312009-08-24 19:20:02.488 MainServer::ANN Monitor
1322009-08-24 19:20:02.490 adding: mythtv as a client (events: 0)
1332009-08-24 19:27:50.192 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
1342009-08-24 19:30:03.239 MainServer::ANN Monitor
1352009-08-24 19:30:03.243 adding: mythtv as a client (events: 0)
1362009-08-24 19:40:02.939 MainServer::ANN Monitor
1372009-08-24 19:40:02.943 adding: mythtv as a client (events: 0)
1382009-08-24 19:42:02.540 UPnpMedia: BuildMediaMap VIDEO scan starting in :/myth/videos:
1392009-08-24 19:42:02.851 UPnpMedia: BuildMediaMap Done. Found 367 objects
1402009-08-24 19:42:50.280 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
1412009-08-24 19:50:02.602 MainServer::ANN Monitor
1422009-08-24 19:50:02.607 adding: mythtv as a client (events: 0)
1432009-08-24 19:57:50.368 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
1442009-08-24 19:59:01.594 Reschedule requested for id 0.
1452009-08-24 19:59:03.761 Scheduled 127 items in 2.2 = 0.00 match + 2.15 place
1462009-08-24 19:59:03.763 MythSocket(1b53460:-1): writeStringList: Error, called with unconnected socket.
1472009-08-24 19:59:03.770 MythSocket(1b19ba0:-1): writeStringList: Error, called with unconnected socket.
1482009-08-24 19:59:03.771 MythSocket(1a9c290:-1): writeStringList: Error, called with unconnected socket.
1492009-08-24 19:59:03.771 MythSocket(19db3b0:-1): writeStringList: Error, called with unconnected socket.
1502009-08-24 19:59:30.995 TVRec(1): ASK_RECORDING 1 30 0 0
1512009-08-24 19:59:30.998 MythSocket(1b53460:-1): writeStringList: Error, called with unconnected socket.
1522009-08-24 19:59:31.002 MythSocket(1b19ba0:-1): writeStringList: Error, called with unconnected socket.
1532009-08-24 19:59:31.002 MythSocket(1a9c290:-1): writeStringList: Error, called with unconnected socket.
1542009-08-24 19:59:31.003 MythSocket(19db3b0:-1): writeStringList: Error, called with unconnected socket.
1552009-08-24 19:59:31.162 TVRec(5): ASK_RECORDING 5 30 0 0
1562009-08-24 19:59:31.166 MythSocket(1b53460:-1): writeStringList: Error, called with unconnected socket.
1572009-08-24 19:59:31.170 MythSocket(1b19ba0:-1): writeStringList: Error, called with unconnected socket.
1582009-08-24 19:59:31.171 MythSocket(1a9c290:-1): writeStringList: Error, called with unconnected socket.
1592009-08-24 19:59:31.172 MythSocket(19db3b0:-1): writeStringList: Error, called with unconnected socket.
1602009-08-24 19:59:31.274 TVRec(6): ASK_RECORDING 6 30 0 0
1612009-08-24 19:59:31.278 MythSocket(1b53460:-1): writeStringList: Error, called with unconnected socket.
1622009-08-24 19:59:31.282 MythSocket(1b19ba0:-1): writeStringList: Error, called with unconnected socket.
1632009-08-24 19:59:31.283 MythSocket(1a9c290:-1): writeStringList: Error, called with unconnected socket.
1642009-08-24 19:59:31.283 MythSocket(19db3b0:-1): writeStringList: Error, called with unconnected socket.
1652009-08-24 19:59:31.475 TVRec(7): ASK_RECORDING 7 30 0 0
1662009-08-24 19:59:31.478 MythSocket(1b53460:-1): writeStringList: Error, called with unconnected socket.
1672009-08-24 19:59:31.482 MythSocket(1b19ba0:-1): writeStringList: Error, called with unconnected socket.
1682009-08-24 19:59:31.483 MythSocket(1a9c290:-1): writeStringList: Error, called with unconnected socket.
1692009-08-24 19:59:31.483 MythSocket(19db3b0:-1): writeStringList: Error, called with unconnected socket.
1702009-08-24 20:00:03.156 MythSocket(1b53460:-1): writeStringList: Error, called with unconnected socket.
1712009-08-24 20:00:03.156 TVRec(1): Changing from None to Watching RecordingOnly
1722009-08-24 20:00:03.166 MythSocket(1b19ba0:-1): writeStringList: Error, called with unconnected socket.
1732009-08-24 20:00:03.178 MythSocket(1a9c290:-1): writeStringList: Error, called with unconnected socket.
1742009-08-24 20:00:03.179 MythSocket(19db3b0:-1): writeStringList: Error, called with unconnected socket.
1752009-08-24 20:00:03.174 TVRec(1): HW Tuner: 1->1
1762009-08-24 20:00:03.339 MainServer::ANN Monitor
1772009-08-24 20:00:03.353 adding: mythtv as a client (events: 0)
1782009-08-24 20:00:03.440 AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 15 min
1792009-08-24 20:00:03.446 Started recording: How I Met Your Mother "Sorry, Bro": channel 1041 on cardid 1, sourceid 1
1802009-08-24 20:00:03.469 MythSocket(1b53460:-1): writeStringList: Error, called with unconnected socket.
1812009-08-24 20:00:03.479 MythSocket(1b19ba0:-1): writeStringList: Error, called with unconnected socket.
1822009-08-24 20:00:03.480 MythSocket(1a9c290:-1): writeStringList: Error, called with unconnected socket.
1832009-08-24 20:00:03.501 MythSocket(19db3b0:-1): writeStringList: Error, called with unconnected socket.
1842009-08-24 20:00:45.103 JobQueue: Commercial Flagging Starting for How I Met Your Mother "Sorry, Bro" recorded from channel 1041 at Mon Aug 24 20:00:00 2009
1852009-08-24 20:00:46.786 MainServer::ANN Monitor
1862009-08-24 20:00:46.791 adding: mythtv as a client (events: 0)
1872009-08-24 20:00:46.793 MainServer::ANN Monitor
1882009-08-24 20:00:46.794 adding: mythtv as a client (events: 1)
1892009-08-24 20:00:46.795 MythSocket(1b53460:-1): writeStringList: Error, called with unconnected socket.
1902009-08-24 20:00:46.798 MythSocket(1b19ba0:-1): writeStringList: Error, called with unconnected socket.
1912009-08-24 20:00:46.799 MythSocket(1a9c290:-1): writeStringList: Error, called with unconnected socket.
1922009-08-24 20:00:46.800 MythSocket(19db3b0:-1): writeStringList: Error, called with unconnected socket.
1932009-08-24 20:00:46.837 MythSocket(1b53460:-1): writeStringList: Error, called with unconnected socket.
1942009-08-24 20:00:46.839 MythSocket(1b19ba0:-1): writeStringList: Error, called with unconnected socket.
1952009-08-24 20:00:46.840 MythSocket(1a9c290:-1): writeStringList: Error, called with unconnected socket.
1962009-08-24 20:00:46.840 MythSocket(19db3b0:-1): writeStringList: Error, called with unconnected socket.
1972009-08-24 20:10:03.271 MainServer::ANN Monitor
1982009-08-24 20:10:03.275 adding: mythtv as a client (events: 0)
1992009-08-24 20:12:05.882 UPnpMedia: BuildMediaMap VIDEO scan starting in :/myth/videos:
2002009-08-24 20:12:06.094 UPnpMedia: BuildMediaMap Done. Found 367 objects
2012009-08-24 20:12:50.459 AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 15 min
2022009-08-24 20:20:02.436 MainServer::ANN Monitor
2032009-08-24 20:20:02.439 adding: mythtv as a client (events: 0)
2042009-08-24 20:27:50.584 AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 15 min
2052009-08-24 20:29:00.795 Reschedule requested for id 0.
2062009-08-24 20:29:02.942 Scheduled 127 items in 2.1 = 0.00 match + 2.12 place
2072009-08-24 20:29:03.085 MythSocket(1b53460:-1): writeStringList: Error, called with unconnected socket.
2082009-08-24 20:29:03.110 MythSocket(1b19ba0:-1): writeStringList: Error, called with unconnected socket.
2092009-08-24 20:29:03.111 MythSocket(1a9c290:-1): writeStringList: Error, called with unconnected socket.
2102009-08-24 20:29:03.112 MythSocket(19db3b0:-1): writeStringList: Error, called with unconnected socket.
2112009-08-24 20:29:29.360 TVRec(5): ASK_RECORDING 5 29 0 0
2122009-08-24 20:29:29.363 MythSocket(1b53460:-1): writeStringList: Error, called with unconnected socket.
2132009-08-24 20:29:29.370 MythSocket(1b19ba0:-1): writeStringList: Error, called with unconnected socket.
2142009-08-24 20:29:29.395 MythSocket(1a9c290:-1): writeStringList: Error, called with unconnected socket.
2152009-08-24 20:29:29.395 MythSocket(19db3b0:-1): writeStringList: Error, called with unconnected socket.
2162009-08-24 20:29:29.475 TVRec(6): ASK_RECORDING 6 29 0 0
2172009-08-24 20:29:29.479 MythSocket(1b53460:-1): writeStringList: Error, called with unconnected socket.
2182009-08-24 20:29:29.486 MythSocket(1b19ba0:-1): writeStringList: Error, called with unconnected socket.
2192009-08-24 20:29:29.486 MythSocket(1a9c290:-1): writeStringList: Error, called with unconnected socket.
2202009-08-24 20:29:29.487 MythSocket(19db3b0:-1): writeStringList: Error, called with unconnected socket.
2212009-08-24 20:29:29.644 TVRec(7): ASK_RECORDING 7 29 0 0
2222009-08-24 20:29:29.650 MythSocket(1b53460:-1): writeStringList: Error, called with unconnected socket.
2232009-08-24 20:29:29.658 MythSocket(1b19ba0:-1): writeStringList: Error, called with unconnected socket.
2242009-08-24 20:29:29.658 MythSocket(1a9c290:-1): writeStringList: Error, called with unconnected socket.
2252009-08-24 20:29:29.659 MythSocket(19db3b0:-1): writeStringList: Error, called with unconnected socket.
2262009-08-24 20:29:29.803 TVRec(1): ASK_RECORDING 1 29 0 0
2272009-08-24 20:29:29.806 MythSocket(1b53460:-1): writeStringList: Error, called with unconnected socket.
2282009-08-24 20:29:29.810 MythSocket(1b19ba0:-1): writeStringList: Error, called with unconnected socket.
2292009-08-24 20:29:29.811 MythSocket(1a9c290:-1): writeStringList: Error, called with unconnected socket.
2302009-08-24 20:29:29.812 MythSocket(19db3b0:-1): writeStringList: Error, called with unconnected socket.
2312009-08-24 20:30:50.699 New DB connection, total: 5
2322009-08-24 20:30:50.788 Connected to database 'mythconverg' at host: mythtv
2332009-08-24 20:32:41.337 New DB connection, total: 6
2342009-08-24 20:32:41.361 Connected to database 'mythconverg' at host: mythtv
2352009-08-24 20:33:24.772 New DB connection, total: 7
2362009-08-24 20:33:24.776 Connected to database 'mythconverg' at host: mythtv
2372009-08-24 20:42:06.097 New DB connection, total: 8
2382009-08-24 20:42:06.116 Connected to database 'mythconverg' at host: mythtv
2392009-08-24 20:42:06.117 New DB connection, total: 9
2402009-08-24 20:42:06.119 Connected to database 'mythconverg' at host: mythtv