[mythtv-users] Frontend can't connect to backend on system start

Michelle Dupuis support at ocg.ca
Thu Jul 19 23:15:13 UTC 2007


I have no firewall running (iptables)...however...I did discover that the
backend service gets "stuck" sometimes.  I ssh'd into the box and as sudo I
ran "mythbackend -v all".  The trace below is an example of a mythbackend
attempt that gets stuck.

I confirmed with "netstat -an" that port 6543 is NOT being listened
to...which explains the frontend hangup.  

Can anyone explain what is going on/wrong with the trace below?

Thanks,
MD

[mythtv at theatre.test.com ~]sudo mythbackend -v all
2007-07-19 18:51:36.306 Using runtime prefix = /usr/local
2007-07-19 18:51:36.320 New DB connection, total: 1
2007-07-19 18:51:36.324 Connected to database 'mythconverg' at host:
localhost
2007-07-19 18:51:36.325 Enabling Settings Cache.
2007-07-19 18:51:36.325 Clearing Settings Cache.
2007-07-19 18:51:36.325 Disabling Settings Cache.
2007-07-19 18:51:36.325 Clearing Settings Cache.
2007-07-19 18:51:36.326 MSqlQuery: SELECT data FROM settings WHERE value =
'DBSchemaVer' AND hostname = 'theatre.test.com' ;
2007-07-19 18:51:36.326 MSqlQuery: SELECT data FROM settings WHERE value =
'DBSchemaVer' AND hostname IS NULL;
2007-07-19 18:51:36.326 Current Schema Version: 1160
2007-07-19 18:51:36.326 Enabling Settings Cache.
2007-07-19 18:51:36.326 Clearing Settings Cache.
2007-07-19 18:51:36.327 MSqlQuery: SELECT data FROM settings WHERE value =
'BackendServerPort' AND hostname = 'theatre.test.com' ;
2007-07-19 18:51:36.327 MSqlQuery: SELECT data FROM settings WHERE value =
'BackendStatusPort' AND hostname = 'theatre.test.com' ;
2007-07-19 18:51:36.328 MSqlQuery: SELECT data FROM settings WHERE value =
'BackendServerIP' AND hostname = 'theatre.test.com' ;
2007-07-19 18:51:36.328 MSqlQuery: SELECT data FROM settings WHERE value =
'MasterServerIP' AND hostname = 'theatre.test.com' ;
2007-07-19 18:51:36.328 MSqlQuery: SELECT data FROM settings WHERE value =
'MasterServerIP' AND hostname IS NULL;
Starting up as the master server.
2007-07-19 18:51:36.329 MSqlQuery: SELECT data FROM settings WHERE value =
'LogEnabled' AND hostname = 'theatre.test.com' ;
2007-07-19 18:51:36.329 MSqlQuery: SELECT data FROM settings WHERE value =
'LogEnabled' AND hostname IS NULL;
2007-07-19 18:51:36.330 MSqlQuery: SELECT data FROM settings WHERE value =
'LogMaxCount' AND hostname = 'theatre.test.com' ;
2007-07-19 18:51:36.330 MSqlQuery: SELECT data FROM settings WHERE value =
'LogPrintLevel' AND hostname = 'theatre.test.com' ;
2007-07-19 18:51:36.331 MSqlQuery: DELETE FROM inuseprograms WHERE hostname
= 'theatre.test.com' AND ( recusage = 'recorder' OR recusage LIKE 'Unknown
%' );
2007-07-19 18:51:36.331 MSqlQuery: UPDATE recorded SET basename =
CONCAT(chanid, '_', DATE_FORMAT(starttime, '%Y%m%d%H%i00'), '_',
DATE_FORMAT(endtime, '%Y%m%d%H%i00'), '.nuv') WHERE basename = '';
2007-07-19 18:51:36.333 MSqlQuery: UPDATE channel SET callsign=chanid WHERE
callsign IS NULL OR callsign='';
2007-07-19 18:51:36.334 MSqlQuery: SELECT MIN(chanid) FROM channel;
2007-07-19 18:51:36.334 MSqlQuery: UPDATE record SET chanid = 1051 WHERE
chanid IS NULL;
2007-07-19 18:51:36.334 New DB connection, total: 2
2007-07-19 18:51:36.335 Connected to database 'mythconverg' at host:
localhost
2007-07-19 18:51:36.335 MSqlQuery: SELECT record.chanid, channel.callsign
FROM record LEFT JOIN channel ON record.chanid = channel.chanid WHERE
record.station='';
2007-07-19 18:51:36.336 MSqlQuery: SELECT cardid, hostname FROM capturecard
WHERE parentid = '0' ORDER BY cardid
2007-07-19 18:51:36.336 MSqlQuery: SELECT data FROM settings WHERE value =
'EITTimeOffset' AND hostname = 'theatre.test.com' ;
2007-07-19 18:51:36.337 MSqlQuery: SELECT data FROM settings WHERE value =
'EITTimeOffset' AND hostname IS NULL;
2007-07-19 18:51:36.337 EITHelper: localtime offset -4:00:00 
2007-07-19 18:51:36.337 MSqlQuery: SELECT data FROM settings WHERE value =
'ISO639Language0' AND hostname = 'theatre.test.com' ;
2007-07-19 18:51:36.338 MSqlQuery: SELECT data FROM settings WHERE value =
'ISO639Language1' AND hostname = 'theatre.test.com' ;
2007-07-19 18:51:36.338 MSqlQuery: SELECT data FROM settings WHERE value =
'ISO639Language2' AND hostname = 'theatre.test.com' ;
2007-07-19 18:51:36.339 MSqlQuery: SELECT data FROM settings WHERE value =
'ISO639Language2' AND hostname IS NULL;
2007-07-19 18:51:36.339 MSqlQuery: SELECT videodevice,      vbidevice,
audiodevice,            audioratelimit,   defaultinput,        cardtype,
skipbtaudio,      signal_timeout,      channel_timeout,
dvb_wait_for_seqstart,        dvb_on_demand,    dvb_tuning_delay,
firewire_port,    firewire_node,       firewire_speed,
firewire_model,   firewire_connection,                         dbox2_port,
dbox2_host,          dbox2_httpport   FROM capturecard WHERE cardid = 1
2007-07-19 18:51:36.340 MSqlQuery: SELECT startchan FROM capturecard,
cardinput WHERE capturecard.cardid = cardinput.cardid AND
capturecard.cardid = 1          AND       inputname          = 'Tuner 1'
2007-07-19 18:51:36.340 Channel(/dev/video0): Device name 'dvb' driver
'saa7146 v4l2'.
2007-07-19 18:51:36.340 MSqlQuery: SELECT cardinputid,        inputname,
startchan,        tunechan,    externalcommand,        sourceid,
childcardid FROM cardinput WHERE cardid = 1
2007-07-19 18:51:36.341 New DB connection, total: 3
2007-07-19 18:51:36.341 Connected to database 'mythconverg' at host:
localhost
2007-07-19 18:51:36.342 MSqlQuery: SELECT data FROM settings WHERE value =
'ChannelOrdering' AND hostname = 'theatre.test.com' ;
2007-07-19 18:51:36.342 MSqlQuery: SELECT chanid, favid FROM favorites
2007-07-19 18:51:36.343 MSqlQuery: SELECT channum, callsign, chanid,
atsc_major_chan, atsc_minor_chan,        name, icon, visible FROM channel
WHERE sourceid='2'
2007-07-19 18:51:36.352 ChannelBase(1): Input #1: 'Tuner 1' schan(51)
sourceid(2) ccid(1)
2007-07-19 18:51:36.352 ChannelBase(1): Current Input #1: 'Tuner 1'
2007-07-19 18:51:36.353 MSqlQuery: SELECT data FROM settings WHERE value =
'TVFormat' AND hostname = 'theatre.test.com' ;
2007-07-19 18:51:36.353 MSqlQuery: SELECT data FROM settings WHERE value =
'TVFormat' AND hostname IS NULL;
2007-07-19 18:51:36.353 Global TVFormat Setting 'NTSC'
2007-07-19 18:51:36.353 Channel(/dev/video0): Input #1: 'Tuner 1' schan(51)
tun() v4l1(PAL) v4l2(Unknown)
2007-07-19 18:51:46.333 MSqlQuery: SELECT data FROM settings WHERE value =
'MythFillEnabled' AND hostname = 'theatre.test.com' ;
2007-07-19 18:51:46.334 MSqlQuery: SELECT data FROM settings WHERE value =
'MythFillEnabled' AND hostname IS NULL;
2007-07-19 18:51:46.334 MSqlQuery: SELECT data FROM settings WHERE value =
'MythFillPeriod' AND hostname = 'theatre.test.com' ;
2007-07-19 18:51:46.335 MSqlQuery: SELECT data FROM settings WHERE value =
'MythFillPeriod' AND hostname IS NULL;
2007-07-19 18:51:46.335 MSqlQuery: SELECT data FROM settings WHERE value =
'MythFillMinHour' AND hostname = 'theatre.test.com' ;
2007-07-19 18:51:46.336 MSqlQuery: SELECT data FROM settings WHERE value =
'MythFillMinHour' AND hostname IS NULL;
2007-07-19 18:51:46.336 MSqlQuery: SELECT data FROM settings WHERE value =
'MythFillMaxHour' AND hostname = 'theatre.test.com' ;
2007-07-19 18:51:46.337 MSqlQuery: SELECT data FROM settings WHERE value =
'MythFillMaxHour' AND hostname IS NULL;
2007-07-19 18:51:46.337 MSqlQuery: SELECT COUNT(*) FROM videosource WHERE
xmltvgrabber IN ( 'datadirect', 'technovera' );
2007-07-19 18:51:46.338 MSqlQuery: SELECT data FROM settings WHERE value =
'MythFillGrabberSuggestsTime' AND hostname = 'theatre.test.com' ;
2007-07-19 18:51:46.338 MSqlQuery: SELECT data FROM settings WHERE value =
'MythFillGrabberSuggestsTime' AND hostname IS NULL;
2007-07-19 18:51:46.339 MSqlQuery: SELECT lastrun FROM housekeeping WHERE
tag = 'MythFillDB' ;
2007-07-19 18:51:46.339 MSqlQuery: SELECT lastrun FROM housekeeping WHERE
tag = 'DailyCleanup' ;
2007-07-19 18:51:46.340 MSqlQuery: DELETE FROM jobqueue WHERE (status in
(272, 288, 320) AND statustime < '2007-07-17T18:51:46') OR (status in (304)
AND statustime < '2007-07-15T18:51:46')
2007-07-19 18:51:46.340 MSqlQuery: DELETE FROM inuseprograms WHERE
lastupdatetime < '2007-07-19T14:51:46' ;
2007-07-19 18:51:46.344 MSqlQuery: SELECT DISTINCT p.chanid, p.starttime
FROM recordedprogram p LEFT JOIN recorded r ON p.chanid = r.chanid AND
p.starttime = r.progstart WHERE r.chanid IS NULL;
2007-07-19 18:51:46.344 MSqlQuery: DELETE FROM recordedprogram WHERE chanid
= '1051' AND starttime = '2007-07-18T18:00:00';
2007-07-19 18:51:46.345 MSqlQuery: DELETE FROM recordedprogram WHERE chanid
= '1052' AND starttime = '2007-07-18T18:00:00';
2007-07-19 18:51:46.345 MSqlQuery: DELETE FROM recordedprogram WHERE chanid
= '1090' AND starttime = '2007-07-18T18:00:00';
2007-07-19 18:51:46.345 MSqlQuery: DELETE FROM recordedprogram WHERE chanid
= '2006' AND starttime = '2007-07-17T21:00:00';
2007-07-19 18:51:46.346 MSqlQuery: DELETE FROM recordedprogram WHERE chanid
= '2006' AND starttime = '2007-07-17T22:00:00';
2007-07-19 18:51:46.346 MSqlQuery: DELETE FROM recordedprogram WHERE chanid
= '2006' AND starttime = '2007-07-18T19:30:00';
2007-07-19 18:51:46.346 MSqlQuery: DELETE FROM recordedprogram WHERE chanid
= '2006' AND starttime = '2007-07-18T22:00:00';
2007-07-19 18:51:46.347 MSqlQuery: DELETE FROM recordedprogram WHERE chanid
= '2043' AND starttime = '2007-07-18T20:00:00';
2007-07-19 18:51:46.347 MSqlQuery: DELETE FROM recordedprogram WHERE chanid
= '2044' AND starttime = '2007-07-18T20:00:00';
2007-07-19 18:51:46.347 MSqlQuery: DELETE FROM recordedprogram WHERE chanid
= '2047' AND starttime = '2007-07-18T22:00:00';
2007-07-19 18:51:46.348 MSqlQuery: DELETE FROM recordedprogram WHERE chanid
= '2051' AND starttime = '2007-07-18T21:00:00';
2007-07-19 18:51:46.351 MSqlQuery: SELECT DISTINCT p.chanid, p.starttime
FROM recordedrating p LEFT JOIN recorded r ON p.chanid = r.chanid AND
p.starttime = r.progstart WHERE r.chanid IS NULL;
2007-07-19 18:51:46.355 MSqlQuery: DELETE FROM recordedrating WHERE chanid =
'2043' AND starttime = '2007-07-18T20:00:00';
2007-07-19 18:51:46.356 MSqlQuery: DELETE FROM recordedrating WHERE chanid =
'2047' AND starttime = '2007-07-18T22:00:00';
2007-07-19 18:51:46.356 MSqlQuery: DELETE FROM recordedrating WHERE chanid =
'2051' AND starttime = '2007-07-18T21:00:00';
2007-07-19 18:51:46.362 MSqlQuery: SELECT DISTINCT p.chanid, p.starttime
FROM recordedcredits p LEFT JOIN recorded r ON p.chanid = r.chanid AND
p.starttime = r.progstart WHERE r.chanid IS NULL;
2007-07-19 18:51:46.367 MSqlQuery: DELETE FROM recordedcredits WHERE chanid
= '2043' AND starttime = '2007-07-18T20:00:00';
2007-07-19 18:51:46.368 MSqlQuery: DELETE FROM recordedcredits WHERE chanid
= '2047' AND starttime = '2007-07-18T22:00:00';
2007-07-19 18:51:46.368 MSqlQuery: DELETE FROM recordedcredits WHERE chanid
= '2051' AND starttime = '2007-07-18T21:00:00';
2007-07-19 18:51:46.369 MSqlQuery: DELETE FROM housekeeping WHERE tag =
'DailyCleanup' ;
2007-07-19 18:51:46.369 MSqlQuery: INSERT INTO housekeeping(tag,lastrun)
values('DailyCleanup' ,now()) ;
2007-07-19 18:51:46.370 MSqlQuery: SELECT lastrun FROM housekeeping WHERE
tag = 'JobQueueRecover-theatre.test.com' ;
2007-07-19 18:51:46.370 JobQueue: RecoverQueue: Checking for unfinished jobs
to recover.
2007-07-19 18:51:46.370 MSqlQuery: SELECT data FROM settings WHERE value =
'AutoCommflagWhileRecording' AND hostname = 'theatre.test.com' ;
2007-07-19 18:51:46.371 MSqlQuery: SELECT data FROM settings WHERE value =
'AutoCommflagWhileRecording' AND hostname IS NULL;
2007-07-19 18:51:46.371 MSqlQuery: SELECT j.id, j.chanid, j.starttime,
j.inserttime, j.type, j.cmds, j.flags, j.status, j.statustime, j.hostname,
j.args, j.comment, r.endtime FROM jobqueue j, recorded r WHERE j.chanid =
r.chanid AND j.starttime = r.starttime ORDER BY j.inserttime, j.chanid,
j.id;
2007-07-19 18:51:46.371 JobQueue: GetJobsInQueue: findJobs search bitmask 4,
found 4 total jobs
2007-07-19 18:51:46.372 JobQueue: GetJobsInQueue: Ignore 'Flag Commercials'
Job for 1246 @ 20070718015800 in Finished state.
2007-07-19 18:51:46.372 JobQueue: GetJobsInQueue: Ignore 'Flag Commercials'
Job for 2051 @ 20070718072200 in Finished state.
2007-07-19 18:51:46.372 JobQueue: GetJobsInQueue: Ignore 'Flag Commercials'
Job for 2051 @ 20070718114700 in Finished state.
2007-07-19 18:51:46.372 JobQueue: GetJobsInQueue: Ignore 'Flag Commercials'
Job for 2051 @ 20070718164600 in Finished state.
2007-07-19 18:51:46.373 MSqlQuery: DELETE FROM housekeeping WHERE tag =
'JobQueueRecover-theatre.test.com' ;
2007-07-19 18:51:46.373 MSqlQuery: INSERT INTO housekeeping(tag,lastrun)
values('JobQueueRecover-theatre.test.com' ,now()) ;
2007-07-19 18:56:46.376 MSqlQuery: SELECT COUNT(*) FROM videosource WHERE
xmltvgrabber IN ( 'datadirect', 'technovera' );
2007-07-19 18:56:46.376 MSqlQuery: SELECT lastrun FROM housekeeping WHERE
tag = 'MythFillDB' ;
2007-07-19 18:56:46.377 MSqlQuery: SELECT lastrun FROM housekeeping WHERE
tag = 'DailyCleanup' ;
2007-07-19 18:56:46.377 MSqlQuery: SELECT lastrun FROM housekeeping WHERE
tag = 'JobQueueRecover-theatre.test.com' ;

(I pressed CTRL-C here because it hung)



More information about the mythtv-users mailing list