Mythwelcome locks up & crashes backend / corrupts ./mythconvg/recordmatch

Bob Jones · 03-12-2009, 08:00 PM

Hey all

I am having two basic problems:

(1) something is going on in the backend where the shutdown timer on mythwelcome continuously resets itself and the computer never shuts down through use of the Shutdown/Wakeup function. It is supposed to shutdown 20 minutes after recording (pending there are no other recordings within the next two hours). The log below shows this problem. Any ideas on this one?

**************************************************************************************************** ***********

Starting up as the master server.
2009-03-11 20:01:24.266 New DB connection, total: 3
2009-03-11 20:01:24.275 Connected to database 'mythconverg' at host: localhost
2009-03-11 20:01:26.666 New DB scheduler connection
2009-03-11 20:01:26.691 Connected to database 'mythconverg' at host: localhost
2009-03-11 20:01:26.777 MediaServer:: Loopback address specified - 127.0.0.1. Disabling UPnP
2009-03-11 20:01:26.787 Main::Registering HttpStatus Extension
2009-03-11 20:01:26.789 mythbackend version: 0.21.20080304-1 www.mythtv.org
2009-03-11 20:01:26.790 Enabled verbose msgs: important general
2009-03-11 20:01:26.812 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
2009-03-11 20:01:29.697 Reschedule requested for id -1.
2009-03-11 20:01:35.385 Scheduled 27 items in 5.7 = 5.59 match + 0.08 place
2009-03-11 20:01:35.447 Seem to be woken up by USER
2009-03-11 20:01:36.454 I'm idle now... shutdown will occur in 1200 seconds.
2009-03-11 20:01:49.427 MainServer::HandleAnnounce Monitor
2009-03-11 20:01:49.452 adding: localhost.localdomain as a client (events: 0)
2009-03-11 20:01:49.453 MainServer::HandleAnnounce Monitor
2009-03-11 20:01:49.453 adding: localhost.localdomain as a client (events: 1)
2009-03-11 20:02:02.113 MainServer::HandleAnnounce Playback
2009-03-11 20:02:02.118 adding: localhost.localdomain as a client (events: 0)
2009-03-11 20:02:02.127 MainServer::HandleAnnounce Monitor
2009-03-11 20:02:02.128 adding: localhost.localdomain as a client (events: 1)
2009-03-11 20:02:46.709 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
2009-03-11 20:02:52.599 Reschedule requested for id 0.
2009-03-11 20:02:52.710 Scheduled 27 items in 0.1 = 0.01 match + 0.09 place
2009-03-11 20:02:58.700 Reschedule requested for id 0.
2009-03-11 20:02:58.755 Scheduled 27 items in 0.1 = 0.00 match + 0.05 place
2009-03-11 20:04:26.979 Reschedule requested for id -1.
2009-03-11 20:04:27.354 Scheduled 27 items in 0.4 = 0.27 match + 0.10 place
2009-03-11 20:05:48.176 Error: offset>181, pes length & current can not be queried
2009-03-11 20:08:29.217 Error: offset>181, pes length & current can not be queried
2009-03-11 20:08:30.848 Error: offset>181, pes length & current can not be queried
2009-03-11 20:08:31.299 Error: offset>181, pes length & current can not be queried
2009-03-11 20:09:20.313 Reschedule requested for id -1.
2009-03-11 20:09:20.901 Scheduled 27 items in 0.6 = 0.34 match + 0.24 place
2009-03-11 20:13:38.854 New DB connection, total: 4
2009-03-11 20:13:38.993 Connected to database 'mythconverg' at host: localhost
2009-03-11 20:13:44.890 Error: offset>181, pes length & current can not be queried
2009-03-11 20:15:06.536 Reschedule requested for id -1.
2009-03-11 20:15:06.989 Scheduled 27 items in 0.5 = 0.23 match + 0.23 place
2009-03-11 20:17:46.813 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
2009-03-11 20:20:10.446 Reschedule requested for id -1.
2009-03-11 20:20:10.925 Scheduled 27 items in 0.5 = 0.26 match + 0.21 place
2009-03-11 20:25:46.408 Reschedule requested for id -1.
2009-03-11 20:25:46.949 Scheduled 27 items in 0.5 = 0.35 match + 0.18 place
2009-03-11 20:29:27.421 Reschedule requested for id 0.
2009-03-11 20:29:28.105 Scheduled 27 items in 0.7 = 0.25 match + 0.43 place
2009-03-11 20:29:29.319 I'm idle now... shutdown will occur in 1200 seconds.
2009-03-11 20:29:33.692 Reschedule requested for id 0.
2009-03-11 20:29:33.741 Scheduled 27 items in 0.0 = 0.01 match + 0.04 place
2009-03-11 20:29:34.745 I'm idle now... shutdown will occur in 1200 seconds.
2009-03-11 20:31:09.195 Reschedule requested for id -1.
2009-03-11 20:31:09.367 Scheduled 27 items in 0.2 = 0.13 match + 0.04 place
2009-03-11 20:31:10.377 I'm idle now... shutdown will occur in 1200 seconds.
2009-03-11 20:32:46.896 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
2009-03-11 20:34:36.150 Error: offset>181, pes length & current can not be queried
2009-03-11 20:34:36.351 Error: offset>181, pes length & current can not be queried
2009-03-11 20:34:36.501 Error: offset>181, pes length & current can not be queried
2009-03-11 20:34:36.602 Error: offset>181, pes length & current can not be queried
2009-03-11 20:34:38.803 Error: offset>181, pes length & current can not be queried
2009-03-11 20:34:38.904 Error: offset>181, pes length & current can not be queried
2009-03-11 20:36:36.022 Reschedule requested for id -1.
2009-03-11 20:36:36.125 Scheduled 27 items in 0.1 = 0.06 match + 0.04 place
2009-03-11 20:36:37.175 I'm idle now... shutdown will occur in 1200 seconds.
2009-03-11 20:42:05.370 Reschedule requested for id -1.
2009-03-11 20:42:05.475 Scheduled 27 items in 0.1 = 0.07 match + 0.04 place
2009-03-11 20:42:06.485 I'm idle now... shutdown will occur in 1200 seconds.
2009-03-11 20:47:32.916 Reschedule requested for id -1.
2009-03-11 20:47:33.350 Scheduled 27 items in 0.4 = 0.28 match + 0.14 place
2009-03-11 20:47:34.369 I'm idle now... shutdown will occur in 1200 seconds.
2009-03-11 20:47:46.934 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
2009-03-11 20:49:23.644 Error: offset>181, pes length & current can not be queried
2009-03-11 20:52:17.325 Error: offset>181, pes length & current can not be queried
2009-03-11 20:53:01.821 Reschedule requested for id -1.
2009-03-11 20:53:01.930 Scheduled 27 items in 0.1 = 0.07 match + 0.04 place
2009-03-11 20:53:02.938 I'm idle now... shutdown will occur in 1200 seconds.
2009-03-11 20:58:30.687 Reschedule requested for id -1.
2009-03-11 20:58:30.789 Scheduled 27 items in 0.1 = 0.06 match + 0.04 place
2009-03-11 20:58:31.797 I'm idle now... shutdown will occur in 1200 seconds.
2009-03-11 21:02:46.972 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
2009-03-11 21:03:50.953 MythSocket(20262a0:-1): writeStringList: Error, socket went unconnected.
2009-03-11 21:03:57.914 Reschedule requested for id -1.
2009-03-11 21:03:58.031 Scheduled 27 items in 0.1 = 0.07 match + 0.05 place
2009-03-11 21:03:59.056 I'm idle now... shutdown will occur in 1200 seconds.
2009-03-11 21:06:11.552 Error: offset>181, pes length & current can not be queried
2009-03-11 21:09:35.603 Reschedule requested for id -1.
2009-03-11 21:09:35.742 Scheduled 27 items in 0.1 = 0.10 match + 0.04 place
2009-03-11 21:09:36.750 I'm idle now... shutdown will occur in 1200 seconds.
2009-03-11 21:10:35.348 Error: offset>181, pes length & current can not be queried
2009-03-11 21:11:28.059 Error: offset>181, pes length & current can not be queried
2009-03-11 21:15:40.477 Reschedule requested for id -1.
2009-03-11 21:15:40.590 Scheduled 27 items in 0.1 = 0.07 match + 0.04 place
2009-03-11 21:15:41.599 I'm idle now... shutdown will occur in 1200 seconds.
2009-03-11 21:17:47.009 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min

**************************************************************************************************** ************
(2) mythwelcome locks a couple hours after a recording ends, seems like ever since I set up the computer to automatically shutdown/wakeup. I have some limited functionality with Fedora, but for the most part, I can't do much. Typically, the database that controls scheduling is corrupted - i.e. I can't schedule and/or record anything - live TV and the recordings database still work though. Everything works again after I follow these steps: http://www.mythdora.com/?q=node/4249#comment-18006 (but the system crashes again later). Please see the log file below - any ideas on this one too?

**************************************************************************************************** ************

2009-03-11 17:01:06.386 Using runtime prefix = /usr
2009-03-11 17:01:06.449 Unable to read configuration file mysql.txt
2009-03-11 17:01:06.453 Empty LocalHostName.
2009-03-11 17:01:06.453 Using localhost value of localhost.localdomain
2009-03-11 17:01:06.488 New DB connection, total: 1
2009-03-11 17:01:06.511 Connected to database 'mythconverg' at host: localhost
2009-03-11 17:01:06.514 Closing DB connection named 'DBManager0'
2009-03-11 17:01:06.528 Connected to database 'mythconverg' at host: localhost
2009-03-11 17:01:06.531 New DB connection, total: 2
2009-03-11 17:01:06.551 Connected to database 'mythconverg' at host: localhost
2009-03-11 17:01:06.558 Current Schema Version: 1214
Starting up as the master server.
2009-03-11 17:01:06.726 New DB connection, total: 3
2009-03-11 17:01:06.734 Connected to database 'mythconverg' at host: localhost
2009-03-11 17:01:08.996 New DB scheduler connection
2009-03-11 17:01:09.008 Connected to database 'mythconverg' at host: localhost
2009-03-11 17:01:09.019 MediaServer:: Loopback address specified - 127.0.0.1. Disabling UPnP
2009-03-11 17:01:09.021 Main::Registering HttpStatus Extension
2009-03-11 17:01:09.022 mythbackend version: 0.21.20080304-1 www.mythtv.org
2009-03-11 17:01:09.023 Enabled verbose msgs: important general
2009-03-11 17:01:09.040 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
2009-03-11 17:01:12.012 Reschedule requested for id -1.
2009-03-11 17:01:18.272 DB Error (UpdateMatches):
Query was:
DELETE FROM recordmatch
Driver error was [2/144]:
QMYSQL3: Unable to execute query
Database error was:
Table './mythconverg/recordmatch' is marked as crashed and last (automatic?) repair failed

2009-03-11 17:01:18.302 DB Error (AddNewRecords recordmatch):
Query was:
UPDATE recordmatch INNER JOIN sched_temp_record ON (recordmatch.recordid = sched_temp_record.recordid) INNER JOIN program ON (recordmatch.chanid = program.chanid AND recordmatch.starttime = program.starttime AND recordmatch.manualid = program.manualid) LEFT JOIN oldrecorded ON ( sched_temp_record.dupmethod > 1 AND oldrecorded.duplicate <> 0 AND program.title = oldrecorded.title AND ( (program.programid <> '' AND program.generic = 0 AND program.programid = oldrecorded.programid) OR (oldrecorded.findid <> 0 AND oldrecorded.findid = (CASE sched_temp_record.type WHEN 6 THEN sched_temp_record.findid WHEN 9 THEN to_days(date_sub(program.starttime, interval time_format(sched_temp_record.findtime, '%H:%i') hour_minute)) WHEN 10 THEN floor((to_days(date_sub(program.starttime, interval time_format(sched_temp_record.findtime, '%H:%i') hour_minute)) - sched_temp_record.findday)/7) * 7 + sched_temp_record.findday WHEN 7 THEN sched_temp_record.findid ELSE 0 END) ) OR ( program.generic = 0 AND (program.programid = '' OR oldrecorded.programid = '') AND (((sched_temp_record.dupmethod & 0x02) = 0) OR (program.subtitle <> '' AND program.subtitle = oldrecorded.subtitle)) AND (((sched_temp_record.dupmethod & 0x04) = 0) OR (program.description <> '' AND program.description = oldrecorded.description)) AND (((sched_temp_record.dupmethod & 0x08) = 0) OR (program.subtitle <> '' AND program.subtitle = oldrecorded.subtitle) OR (program.subtitle = '' AND oldrecorded.subtitle = '' AND program.description <> '' AND program.description = oldrecorded.description)) ) ) ) LEFT JOIN sched_temp_recorded recorded ON ( sched_temp_record.dupmethod > 1 AND recorded.duplicate <> 0 AND program.title = recorded.title AND recorded.recgroup NOT IN ('LiveTV','Deleted') AND ( (program.programid <> '' AND program.generic = 0 AND program.programid = recorded.programid) OR (recorded.findid <> 0 AND recorded.findid = (CASE sched_temp_record.type WHEN 6 THEN sched_temp_record.findid WHEN 9 THEN to_days(date_sub(program.starttime, interval time_format(sched_temp_record.findtime, '%H:%i') hour_minute)) WHEN 10 THEN floor((to_days(date_sub(program.starttime, interval time_format(sched_temp_record.findtime, '%H:%i') hour_minute)) - sched_temp_record.findday)/7) * 7 + sched_temp_record.findday WHEN 7 THEN sched_temp_record.findid ELSE 0 END) ) OR ( program.generic = 0 AND (program.programid = '' OR recorded.programid = '') AND (((sched_temp_record.dupmethod & 0x02) = 0) OR (program.subtitle <> '' AND program.subtitle = recorded.subtitle)) AND (((sched_temp_record.dupmethod & 0x04) = 0) OR (program.description <> '' AND program.description = recorded.description)) AND (((sched_temp_record.dupmethod & 0x08) = 0) OR (program.subtitle <> '' AND program.subtitle = recorded.subtitle) OR (program.subtitle = '' AND recorded.subtitle = '' AND program.description <> '' AND program.description = recorded.description)) ) ) ) LEFT JOIN oldfind ON (oldfind.recordid = recordmatch.recordid AND oldfind.findid = (CASE sched_temp_record.type WHEN 6 THEN sched_temp_record.findid WHEN 9 THEN to_days(date_sub(program.starttime, interval time_format(sched_temp_record.findtime, '%H:%i') hour_minute)) WHEN 10 THEN floor((to_days(date_sub(program.starttime, interval time_format(sched_temp_record.findtime, '%H:%i') hour_minute)) - sched_temp_record.findday)/7) * 7 + sched_temp_record.findday WHEN 7 THEN sched_temp_record.findid ELSE 0 END) ) SET oldrecduplicate = (oldrecorded.endtime IS NOT NULL), recduplicate = (recorded.endtime IS NOT NULL), findduplicate = (oldfind.findid IS NOT NULL), oldrecstatus = oldrecorded.recstatus
Driver error was [2/144]:
QMYSQL3: Unable to execute query
Database error was:
Table './mythconverg/recordmatch' is marked as crashed and last (automatic?) repair failed

2009-03-11 17:01:18.309 DB Error (AddNotListed):
Query was:
SELECT record.recordid, record.type, record.chanid, record.starttime, record.startdate, record.endtime, record.enddate, record.startoffset, record.endoffset, record.title, record.subtitle, record.description, channel.channum, channel.callsign, channel.name FROM record INNER JOIN channel ON (channel.chanid = record.chanid) LEFT JOIN recordmatch on record.recordid = recordmatch.recordid WHERE (type = 1 OR type = 2 OR type = 5 OR type = 7) AND recordmatch.chanid IS NULL
Driver error was [2/144]:
QMYSQL3: Unable to execute query
Database error was:
Table './mythconverg/recordmatch' is marked as crashed and last (automatic?) repair failed

2009-03-11 17:01:18.317 Scheduled 0 items in 6.3 = 6.27 match + 0.03 place
2009-03-11 17:01:18.324 Seem to be woken up by USER
2009-03-11 17:01:19.327 I'm idle now... shutdown will occur in 1200 seconds.
2009-03-11 17:01:34.339 MainServer::HandleAnnounce Monitor
2009-03-11 17:01:34.378 adding: localhost.localdomain as a client (events: 0)
2009-03-11 17:01:34.379 MainServer::HandleAnnounce Monitor
2009-03-11 17:01:34.381 adding: localhost.localdomain as a client (events: 1)
2009-03-11 17:02:29.014 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
2009-03-11 17:03:37.389 Reschedule requested for id -1.
2009-03-11 17:03:37.396 DB Error (UpdateMatches):