[mythtv] Mythtv loosing scheduled recordings

Thu Apr 6 01:51:47 UTC 2006

Hi,

I have been using mythtv for a long time now (currently v0.19 on debian
testing). Recently it started loosing the scheduled recordings. The backend
log file has the following messages:

Usually the scheduler has about 450 items:

2006-04-05 05:42:23.491 Scheduled 441 items in 1.8 = 0.24 match + 1.55 place

About once a day something like the following happens:

2006-04-05 14:01:00.343 TVRec(1): Changing from RecordingOnly to None
2006-04-05 14:01:00.390 Finished recording Frasier "Agents in America, Part
III": channel 1041
2006-04-05 14:01:00.402 Reschedule requested for id 0.
2006-04-05 14:01:00.452 DB Error (CheckTooMany):
Query was:
SELECT recordid,title,maxepisodes,maxnewest FROM record;
Driver error was [2/2006]:
QMYSQL3: Unable to execute query
Database error was:
MySQL server has gone away

2006-04-05 14:01:00.509 DB Error (AddNotListed):
Query was:
SELECT record.recordid, record.type, record.chanid, record.starttime,
record.startdate, record.endtime, record.enddate, record.startoffset,
record.endo
ffset, record.title, record.subtitle, record.description, channel.channum,
channel.callsign, channel.name FROM record  INNER JOIN channel ON (channel.c
hanid = record.chanid)  LEFT JOIN recordmatch on record.recordid =
recordmatch.recordid WHERE (type = 1 OR type = 7) AND recordmatch.chanid IS
NULL
Driver error was [2/2006]:
QMYSQL3: Unable to execute query
Database error was:
MySQL server has gone away

2006-04-05 14:01:00.584 Finished recording Frasier "Agents in America, Part
III": channel 1041
2006-04-05 14:01:00.660 Scheduled 0 items in 0.3 = 0.01 match + 0.25 place
0: start_time: 0.036 duration: 171.859
1: start_time: 0.026 duration: 171.852
stream: start_time: 0.289 duration: 1909.652 bitrate=8732 kb/s
2006-04-05 14:01:00.865 AFD: Opened codec 0x82dd6d0, id(MPEG2VIDEO)
type(Video)

The mysql server is still alive. I can see all the recorded programs through
the frontend etc, but the scheduler does not have any recordings anymore.

If I do the following 

~# mythbackend --resched
2006-04-05 18:44:35.144 Using runtime prefix = /usr/local
2006-04-05 18:44:35.166 New DB connection, total: 1
2006-04-05 18:44:35.173 Connected to database 'mythconverg' at host:
localhost
2006-04-05 18:44:35.177 Current Schema Version: 1123
2006-04-05 18:44:35.180 Connecting to backend server: 192.168.1.1:6543 (try
1 of 5)
2006-04-05 18:44:35.187 Using protocol version 26
2006-04-05 18:44:35.266 Connected to master for reschedule

I see the following messages in the backend log:

2006-04-05 18:35:47.816 Running HouseKeeping
2006-04-05 18:40:47.839 Running HouseKeeping
2006-04-05 18:44:35.206 MainServer::HandleAnnounce Playback
2006-04-05 18:44:35.258 adding: florian as a client (events: 0)
2006-04-05 18:44:35.281 Unknown socket closing
2006-04-05 18:44:35.275 Reschedule requested for id -1.
2006-04-05 18:44:35.330 DB Error (UpdateMatches):
Query was:
DELETE FROM recordmatch WHERE recordid = -1 OR -1 = -1;
Driver error was [2/2006]:
QMYSQL3: Unable to execute query
Database error was:
MySQL server has gone away

2006-04-05 18:44:35.332 DB Error (CheckTooMany):
Query was:
SELECT recordid,title,maxepisodes,maxnewest FROM record;
Driver error was [2/2006]:
QMYSQL3: Unable to execute query
Database error was:
MySQL server has gone away

2006-04-05 18:44:35.336 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 = 7) AND
recordmatch.chanid IS NULL
Driver error was [2/2006]:
QMYSQL3: Unable to execute query
Database error was:
MySQL server has gone away

2006-04-05 18:44:35.340 Scheduled 0 items in 0.1 = 0.06 match + 0.01 place

Once I restart the backend every thing seems fine (I don’t need to do
anything with mysql):

Starting up as the master server.
2006-04-05 18:48:07.321 New DB connection, total: 2
2006-04-05 18:48:07.337 Connected to database 'mythconverg' at host:
localhost
2006-04-05 18:48:07.448 New DB connection, total: 3
2006-04-05 18:48:07.453 Connected to database 'mythconverg' at host:
localhost
2006-04-05 18:48:07.883 New DB scheduler connection
2006-04-05 18:48:07.885 Connected to database 'mythconverg' at host:
localhost
2006-04-05 18:48:07.896 mythbackend version: 0.19.20060121-2 www.mythtv.org
2006-04-05 18:48:07.916 Enabled verbose msgs:  important general
2006-04-05 18:48:07.951 AutoExpire: Found 2 recorders w/max rate of 144
MiB/min
2006-04-05 18:48:08.013 AutoExpire: Required Free Space: 3.1 GB w/freq: 10
min
2006-04-05 18:48:10.012 Reschedule requested for id -1.
2006-04-05 18:48:17.252 Running HouseKeeping
2006-04-05 18:48:20.543 Scheduled 433 items in 10.5 = 1.49 match + 9.04
place

I did not see anything suspicious in the mysql log file. I am using the
following version

mysql --version
mysql  Ver 14.12 Distrib 5.0.18, for pc-linux-gnu (i486) using readline 5.1

Any ideas gentlemen?

-- 
Analog-/ISDN-Nutzer sparen mit GMX SmartSurfer bis zu 70%!
Kostenlos downloaden: http://www.gmx.net/de/go/smartsurfer