2009-07-07 20:32:23.128 Clearing Settings Cache.
2009-07-07 20:32:23.136 Truncating '/var/lib/mythtv/recordings/1009_20090707203055.mpg' by 2.78 MB every 500 milliseconds
2009-07-07 20:32:23.144 MSqlQuery: DELETE FROM inuseprograms WHERE chanid = '1009' AND starttime = '2009-07-07T20:30:55' AND hostname = 'tv' AND recusage = 'truncatingdelete' ;
2009-07-07 20:32:23.168 Finished truncating '/var/lib/mythtv/recordings/1009_20090707203055.mpg'
2009-07-07 20:32:39.840 MythSocket(7faeb80f2680:-1): DownRef: 0
2009-07-07 20:32:39.903 MythSocket(7faeb8169f40:-1): DownRef: 0
2009-07-07 20:32:39.903 MythSocket(7faeb80f2680:-1): DownRef: -1
2009-07-07 20:32:39.914 MythSocket(7faeb80f2680:-1): delete socket
2009-07-07 20:32:39.914 MythSocket(7faeb8169f40:-1): DownRef: -1
2009-07-07 20:32:39.920 MythSocket(7faeb8169f40:-1): delete socket
2009-07-07 20:32:49.359 MythSocket(1772d10:15): socket is readable
2009-07-07 20:32:49.381 MythSocket(1772d10:15): cb->readyRead()
2009-07-07 20:32:49.382 MythSocket(1772d10:15): UpRef: 2
2009-07-07 20:32:49.382 read  <- 15 23      GET_FREE_RECORDER_COUNT
2009-07-07 20:32:49.384 write -> 15 1       2
2009-07-07 20:32:49.385 MythSocket(1772d10:15): DownRef: 1
2009-07-07 20:32:49.385 MythSocket(1772d10:15): socket is readable
2009-07-07 20:32:49.388 MythSocket(1772d10:15): cb->readyRead()
2009-07-07 20:32:49.396 MythSocket(1772d10:15): UpRef: 2
2009-07-07 20:32:49.404 read  <- 15 29      GET_NEXT_FREE_RECORDER[]:[]-1
2009-07-07 20:32:49.412 Getting next free recorder after : -1
2009-07-07 20:32:49.436 MSqlQuery: SELECT data FROM settings WHERE value = 'LastFreeCard' AND hostname = 'tv' ;
2009-07-07 20:32:49.441 MSqlQuery: SELECT data FROM settings WHERE value = 'LastFreeCard' AND hostname IS NULL;
2009-07-07 20:32:49.448 Checking card 1. Best card so far 1
2009-07-07 20:32:49.456 Checking card 2. Best card so far 2
2009-07-07 20:32:49.480 MSqlQuery: SELECT data FROM settings WHERE value = 'BackendServerIP' AND hostname = 'tv' ;
2009-07-07 20:32:49.480 MSqlQuery: SELECT data FROM settings WHERE value = 'BackendServerPort' AND hostname = 'tv' ;
2009-07-07 20:32:49.502 write -> 15 27      2[]:[]192.168.1.11[]:[]6543
2009-07-07 20:32:49.502 MythSocket(1772d10:15): DownRef: 1
2009-07-07 20:32:49.503 MythSocket(7faeb805b640:23): new socket
2009-07-07 20:32:49.504 MythSocket(7faeb805b640:23): setSocket: 22
2009-07-07 20:32:49.505 MythSocket(7faeb805b640:22): state change Idle -> Connected
2009-07-07 20:32:49.505 MythSocket(7faeb805b640:22): UpRef: 1
2009-07-07 20:32:49.505 MythSocket(7faeb805b640:22): socket is readable
2009-07-07 20:32:49.508 MythSocket(7faeb805b640:22): cb->readyRead()
2009-07-07 20:32:49.509 MythSocket(7faeb805b640:22): UpRef: 2
2009-07-07 20:32:49.509 read  <- 22 21      MYTH_PROTO_VERSION 40
2009-07-07 20:32:49.513 write -> 22 13      ACCEPT[]:[]40
2009-07-07 20:32:49.513 MythSocket(7faeb805b640:22): DownRef: 1
2009-07-07 20:32:49.513 MythSocket(7faeb805b640:22): socket is readable
2009-07-07 20:32:49.524 MythSocket(7faeb805b640:22): cb->readyRead()
2009-07-07 20:32:49.524 MythSocket(7faeb805b640:22): UpRef: 2
2009-07-07 20:32:49.525 read  <- 22 17      ANN Playback tv 0
2009-07-07 20:32:49.528 MainServer::HandleAnnounce Playback
2009-07-07 20:32:49.529 adding: tv as a client (events: 0)
2009-07-07 20:32:49.529 write -> 22 2       OK
2009-07-07 20:32:49.531 MythSocket(7faeb805b640:22): DownRef: 1
2009-07-07 20:32:49.531 MythSocket(7faeb805b640:22): socket is readable
2009-07-07 20:32:49.544 MythSocket(7faeb805b640:22): cb->readyRead()
2009-07-07 20:32:49.568 MythSocket(7faeb805b640:22): UpRef: 2
2009-07-07 20:32:49.580 read  <- 22 76      QUERY_RECORDER 2[]:[]SPAWN_LIVETV[]:[]live-tv-2009-07-07T20:32:49[]:[]0[]:[]
2009-07-07 20:32:49.588 MSqlQuery: SELECT chanid, starttime, endtime, discontinuity, chainpos, hostprefix, cardtype, channame, input FROM tvchain WHERE chainid = 'live-tv-2009-07-07T20:32:49' ORDER BY chainpos;
2009-07-07 20:32:49.596 MSqlQuery: SELECT chanid, starttime, endtime, discontinuity, chainpos, hostprefix, cardtype, channame, input FROM tvchain WHERE chainid = 'live-tv-2009-07-07T20:32:49' ORDER BY chainpos;
2009-07-07 20:32:49.604 TVRec(2): Changing from None to WatchingLiveTV
2009-07-07 20:32:49.612 TVRec(2): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RingBufferReady,
2009-07-07 20:32:49.620 TVRec(2): Request: Program(no) channel() input() flags(LiveTV,)
2009-07-07 20:32:49.635 MSqlQuery: SELECT startchan FROM cardinput WHERE cardinput.cardid   = 2    AND       inputname          = 'DVBInput'
2009-07-07 20:32:49.640 TVRec(2): Start channel: 9.
2009-07-07 20:32:49.648 MSqlQuery: SELECT chanid FROM channel, cardinput WHERE cardinput.sourceid = channel.sourceid AND       channel.channum = '9' AND       cardinput.cardid = 2
2009-07-07 20:32:49.656 MSqlQuery: SELECT inputname, sourceid, cardid FROM cardinput WHERE cardinputid = 2
2009-07-07 20:32:49.664 MSqlQuery: SELECT mplexid FROM channel WHERE chanid = 1009
2009-07-07 20:32:49.680 MSqlQuery: SELECT chanid FROM channel, cardinput WHERE cardinput.sourceid = channel.sourceid AND       channel.channum = '9' AND       cardinput.cardid = 2
2009-07-07 20:32:49.684 MSqlQuery: SELECT inputgroupid FROM inputgroup WHERE cardinputid = 2 ORDER BY inputgroupid, cardinputid, inputgroupname
2009-07-07 20:32:49.692 MSqlQuery: SELECT finetune, freqid, tvformat, freqtable,        commmethod, mplexid,        atsc_major_chan, atsc_minor_chan, serviceid FROM channel, videosource WHERE videosource.sourceid = channel.sourceid AND       channum              = '9'         AND       channel.sourceid     = 1
2009-07-07 20:32:49.700 MSqlQuery: SELECT transportid, networkid, frequency, modulation, sistandard FROM dtv_multiplex WHERE mplexid = 2
2009-07-07 20:32:49.708 TVRec(2): HW Tuner: 2->2
2009-07-07 20:32:49.724 TVRec(2): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady,
2009-07-07 20:32:49.725 TVRec(2): No recorder yet, calling TuningFrequency
2009-07-07 20:32:49.736 MSqlQuery: SELECT quicktune FROM cardinput WHERE cardid    = 2 AND       inputname = 'DVBInput'
2009-07-07 20:32:49.744 DVBChan(2:1): Opening DVB channel
2009-07-07 20:32:49.757 DVBChan(2:1): SetChannelByString(9): 
2009-07-07 20:32:49.769 MSqlQuery: SELECT channel.chanid FROM channel, capturecard, cardinput WHERE channel.channum      = '9'           AND       channel.sourceid     = cardinput.sourceid AND       cardinput.inputname  = 'DVBInput'             AND       cardinput.cardid     = capturecard.cardid AND       capturecard.cardid   = 2            AND       capturecard.hostname = 'tv'
2009-07-07 20:32:49.770 MSqlQuery: SELECT chanid FROM channel, cardinput WHERE cardinput.sourceid = channel.sourceid AND       channel.channum = '9' AND       cardinput.cardid = 2
2009-07-07 20:32:49.776 MSqlQuery: SELECT inputname, sourceid, cardid FROM cardinput WHERE cardinputid = 2
2009-07-07 20:32:49.791 MSqlQuery: SELECT mplexid FROM channel WHERE chanid = 1009
2009-07-07 20:32:49.803 MSqlQuery: SELECT chanid FROM channel, cardinput WHERE cardinput.sourceid = channel.sourceid AND       channel.channum = '9' AND       cardinput.cardid = 2
2009-07-07 20:32:49.808 MSqlQuery: SELECT inputgroupid FROM inputgroup WHERE cardinputid = 2 ORDER BY inputgroupid, cardinputid, inputgroupname
2009-07-07 20:32:49.824 MSqlQuery: SELECT finetune, freqid, tvformat, freqtable,        commmethod, mplexid,        atsc_major_chan, atsc_minor_chan, serviceid FROM channel, videosource WHERE videosource.sourceid = channel.sourceid AND       channum              = '9'         AND       channel.sourceid     = 1
2009-07-07 20:32:49.825 MSqlQuery: SELECT transportid, networkid, frequency, modulation, sistandard FROM dtv_multiplex WHERE mplexid = 2
2009-07-07 20:32:49.836 MSqlQuery: SELECT frequency,         inversion,      symbolrate,        fec,               polarity,        hp_code_rate,      lp_code_rate,   constellation,        transmission_mode, guard_interval, hierarchy,        modulation,        bandwidth,      sistandard FROM dtv_multiplex WHERE dtv_multiplex.mplexid = 2
2009-07-07 20:32:49.844 DVBChan(2:1): 191625000 qam_64 a 3/4 3/4 7 8 1/16 n v
2009-07-07 20:32:49.857 DVBChan(2:1): Old Params: 191625000 qam_64 a 3/4 3/4 7 8 1/16 n v
			DVBChan(2:1): New Params: 191625000 qam_64 a 3/4 3/4 7 8 1/16 n v
2009-07-07 20:32:49.869 DVBChan(2:1): Tune(): Frequency tuning successful.
2009-07-07 20:32:49.870 DVBChan(2:1): SetChannelByString(9): Tuned to frequency.
2009-07-07 20:32:49.876 TVRec(2): CreateLiveTVRingBuffer()
2009-07-07 20:32:49.891 TVRec(2): GetProgramRingBufferForLiveTV()
2009-07-07 20:32:49.903 MSqlQuery: SELECT channel.chanid FROM channel WHERE channum  = '9' AND       sourceid = 1
2009-07-07 20:32:49.908 MSqlQuery: SELECT data FROM settings WHERE value = 'MaxHoursPerLiveTVRecording' AND hostname = 'tv' ;
2009-07-07 20:32:49.924 MSqlQuery: SELECT data FROM settings WHERE value = 'MaxHoursPerLiveTVRecording' AND hostname IS NULL;
2009-07-07 20:32:49.925 MSqlQuery: SELECT data FROM settings WHERE value = 'ChannelOrdering' AND hostname = 'tv' ;
2009-07-07 20:32:49.938 MSqlQuery: SELECT DISTINCT program.chanid, program.starttime, program.endtime,     program.title, program.subtitle, program.description,     program.category, channel.channum, channel.callsign,     channel.name, program.previouslyshown, channel.commmethod,     channel.outputfilters, program.seriesid, program.programid,     program.airdate, program.stars, program.originalairdate,     program.category_type, oldrecstatus.recordid,     oldrecstatus.rectype, oldrecstatus.recstatus,     oldrecstatus.findid FROM program LEFT JOIN channel ON program.chanid = channel.chanid LEFT JOIN oldrecorded AS oldrecstatus ON     program.title = oldrecstatus.title AND     channel.callsign = oldrecstatus.station AND     program.starttime = oldrecstatus.starttime WHERE program.chanid = '1009'   AND program.starttime < '2009-07-07T20:32:50'   AND program.endtime > '2009-07-07T20:32:50'  GROUP BY program.starttime, channel.channum,   channel.callsign, program.title  ORDER BY program.starttime, callsign  LIMIT 1000
2009-07-07 20:32:49.944 MythEvent: QUERY_NEXT_LIVETV_DIR 2
2009-07-07 20:32:49.957 Scheduler: FillRecordingDir: Starting
2009-07-07 20:32:49.969 MSqlQuery: SELECT DISTINCT dirname FROM storagegroup WHERE groupname = 'LiveTV' AND hostname = 'tv'
2009-07-07 20:32:49.970 SG(LiveTV): Unable to find storage group 'LiveTV', trying 'Default' group!
2009-07-07 20:32:49.976 MSqlQuery: SELECT DISTINCT dirname FROM storagegroup WHERE groupname = 'Default' AND hostname = 'tv'
2009-07-07 20:32:49.991 Scheduler: FillRecordingDir: The only directory in the LiveTV Storage Group is /var/lib/mythtv/recordings, so it will be used by default.
2009-07-07 20:32:50.003 Scheduler: FillRecordingDir: Finished
2009-07-07 20:32:50.008 AutoExpire: Cardid 2: is starting a recording on an unknown fsID soon.
2009-07-07 20:32:50.035 AutoExpire: CalcParams()
2009-07-07 20:32:50.036 MSqlQuery: SELECT MIN(id),dirname FROM storagegroup WHERE hostname = 'tv' GROUP BY dirname;
2009-07-07 20:32:50.046 Determining unique filesystems
2009-07-07 20:32:50.957 MSqlQuery: SELECT DISTINCT dirname FROM storagegroup WHERE groupname = 'LiveTV' AND hostname = 'tv'
2009-07-07 20:32:50.961 SG(LiveTV): Unable to find storage group 'LiveTV', trying 'Default' group!
2009-07-07 20:32:50.961 MSqlQuery: SELECT DISTINCT dirname FROM storagegroup WHERE groupname = 'Default' AND hostname = 'tv'
2009-07-07 20:32:50.962 SG(Default): FindNextDirMostFree: Starting'
2009-07-07 20:32:50.962 SG(Default): FindNextDirMostFree: '/var/lib/mythtv/recordings' has 858020520 KiB free
2009-07-07 20:32:50.963 SG(Default): FindNextDirMostFree: Using '/var/lib/mythtv/recordings'
2009-07-07 20:32:50.964 MSqlQuery: SELECT data FROM settings WHERE value = 'LongChannelFormat' AND hostname = 'tv' ;
2009-07-07 20:32:50.964 MSqlQuery: SELECT data FROM settings WHERE value = 'ChannelFormat' AND hostname = 'tv' ;
2009-07-07 20:32:50.972 MSqlQuery: SELECT data FROM settings WHERE value = 'TimeFormat' AND hostname = 'tv' ;
2009-07-07 20:32:50.981 MSqlQuery: SELECT data FROM settings WHERE value = 'DateFormat' AND hostname = 'tv' ;
2009-07-07 20:32:50.988 MSqlQuery: SELECT data FROM settings WHERE value = 'ShortDateFormat' AND hostname = 'tv' ;
2009-07-07 20:32:51.014 MSqlQuery: SELECT data FROM settings WHERE value = 'HaveRepeats' AND hostname = 'tv' ;
2009-07-07 20:32:51.035 MSqlQuery: SELECT data FROM settings WHERE value = 'HaveRepeats' AND hostname IS NULL;
2009-07-07 20:32:51.036 MSqlQuery: SELECT data FROM settings WHERE value = 'UserJobDesc1' AND hostname = 'tv' ;
2009-07-07 20:32:51.058 MSqlQuery: SELECT data FROM settings WHERE value = 'UserJobDesc1' AND hostname IS NULL;
2009-07-07 20:32:51.080 MSqlQuery: SELECT data FROM settings WHERE value = 'UserJobDesc2' AND hostname = 'tv' ;
2009-07-07 20:32:51.081 MSqlQuery: SELECT data FROM settings WHERE value = 'UserJobDesc2' AND hostname IS NULL;
2009-07-07 20:32:51.102 MSqlQuery: SELECT data FROM settings WHERE value = 'UserJobDesc3' AND hostname = 'tv' ;
2009-07-07 20:32:51.103 MSqlQuery: SELECT data FROM settings WHERE value = 'UserJobDesc3' AND hostname IS NULL;
2009-07-07 20:32:51.124 MSqlQuery: SELECT data FROM settings WHERE value = 'UserJobDesc4' AND hostname = 'tv' ;
2009-07-07 20:32:51.125 MSqlQuery: SELECT data FROM settings WHERE value = 'UserJobDesc4' AND hostname IS NULL;
2009-07-07 20:32:51.147 MSqlQuery: SELECT data FROM settings WHERE value = 'DefaultStartOffset' AND hostname = 'tv' ;
2009-07-07 20:32:51.148 MSqlQuery: SELECT data FROM settings WHERE value = 'DefaultStartOffset' AND hostname IS NULL;
2009-07-07 20:32:51.169 MSqlQuery: SELECT data FROM settings WHERE value = 'DefaultEndOffset' AND hostname = 'tv' ;
2009-07-07 20:32:51.191 MSqlQuery: SELECT data FROM settings WHERE value = 'DefaultEndOffset' AND hostname IS NULL;
2009-07-07 20:32:51.192 MSqlQuery: SELECT data FROM settings WHERE value = 'AutoExpireDefault' AND hostname = 'tv' ;
2009-07-07 20:32:51.197 MSqlQuery: SELECT data FROM settings WHERE value = 'AutoExpireDefault' AND hostname IS NULL;
2009-07-07 20:32:51.204 MSqlQuery: SELECT data FROM settings WHERE value = 'AutoTranscode' AND hostname = 'tv' ;
2009-07-07 20:32:51.226 MSqlQuery: SELECT data FROM settings WHERE value = 'AutoTranscode' AND hostname IS NULL;
2009-07-07 20:32:51.227 MSqlQuery: SELECT name, id FROM recordingprofiles WHERE profilegroup = 6 ORDER BY id
2009-07-07 20:32:51.236 MSqlQuery: SELECT data FROM settings WHERE value = 'DefaultTranscoder' AND hostname = 'tv' ;
2009-07-07 20:32:51.244 MSqlQuery: SELECT data FROM settings WHERE value = 'DefaultTranscoder' AND hostname IS NULL;
2009-07-07 20:32:51.258 MSqlQuery: SELECT data FROM settings WHERE value = 'AutoCommercialFlag' AND hostname = 'tv' ;
2009-07-07 20:32:51.264 MSqlQuery: SELECT data FROM settings WHERE value = 'AutoCommercialFlag' AND hostname IS NULL;
2009-07-07 20:32:51.272 MSqlQuery: SELECT data FROM settings WHERE value = 'AutoRunUserJob1' AND hostname = 'tv' ;
2009-07-07 20:32:51.281 MSqlQuery: SELECT data FROM settings WHERE value = 'AutoRunUserJob1' AND hostname IS NULL;
2009-07-07 20:32:51.288 MSqlQuery: SELECT data FROM settings WHERE value = 'AutoRunUserJob2' AND hostname = 'tv' ;
2009-07-07 20:32:51.302 MSqlQuery: SELECT data FROM settings WHERE value = 'AutoRunUserJob2' AND hostname IS NULL;
2009-07-07 20:32:51.314 MSqlQuery: SELECT data FROM settings WHERE value = 'AutoRunUserJob3' AND hostname = 'tv' ;
2009-07-07 20:32:51.315 MSqlQuery: SELECT data FROM settings WHERE value = 'AutoRunUserJob3' AND hostname IS NULL;
2009-07-07 20:32:51.320 MSqlQuery: SELECT data FROM settings WHERE value = 'AutoRunUserJob4' AND hostname = 'tv' ;
2009-07-07 20:32:51.328 MSqlQuery: SELECT data FROM settings WHERE value = 'AutoRunUserJob4' AND hostname IS NULL;
2009-07-07 20:32:51.336 MSqlQuery: SELECT DISTINCT recgroup FROM recorded
2009-07-07 20:32:51.344 MSqlQuery: SELECT DISTINCT recgroup FROM record
2009-07-07 20:32:51.352 MSqlQuery: SELECT DISTINCT groupname FROM storagegroup WHERE groupname NOT IN ( 'LiveTV', 'DB Backups' );
2009-07-07 20:32:51.369 MSqlQuery: SELECT name FROM playgroup WHERE name <> 'Default' ORDER BY name;
2009-07-07 20:32:51.372 MSqlQuery: SELECT cardinputid,cardid,inputname,displayname FROM cardinput ORDER BY cardinputid
2009-07-07 20:32:51.381 MSqlQuery: SELECT channum, callsign, name FROM channel WHERE chanid = '1009';
2009-07-07 20:32:51.388 MSqlQuery: SELECT name FROM playgroup WHERE name = 'Two and a Half Men' OR       name = 'Sitcom' OR       (titlematch <> '' AND        'Two and a Half Men' REGEXP titlematch)
2009-07-07 20:32:51.396 MSqlQuery: SELECT name FROM playgroup WHERE name = 'Two and a Half Men' OR       name = 'Sitcom' OR       (titlematch <> '' AND        'Two and a Half Men' REGEXP titlematch)
2009-07-07 20:32:51.413 MSqlQuery: LOCK TABLES recorded WRITE
2009-07-07 20:32:51.414 MSqlQuery: SELECT recordid     FROM recorded     WHERE chanid    = '1009' AND           starttime = '2009-07-07T20:32:49'
2009-07-07 20:32:51.425 MSqlQuery: INSERT INTO recorded    (chanid,    starttime,   endtime,         title,                subtitle,  description, hostname,        category,             recgroup,  autoexpire,  recordid,        seriesid,             programid, stars,       previouslyshown, originalairdate,      findid,    transcoder,  playgroup,       recpriority,          basename,  progstart,   progend,         profile,              duplicate, storagegroup) VALUES  ('1009',   '2009-07-07T20:32:49',     '2009-07-07T21:00:00',           'Two and a Half Men',               'A Jock Strap in Hell', 'Discovering Miss Pasternak, Jake''s former 5th grade teacher, turned to stripping after her fling with Charlie, tries to help turn her life around.',       'tv',       'Sitcom',            'Default', 1,    0,       '133198302',            '',0,      1,         '0000-00-00',         0,   0, 'Default',      0,         '1009_20090707203249.mpg', '2009-07-07T20:30:00',  '2009-07-07T21:00:00',        'Default',             0,         'LiveTV')
2009-07-07 20:32:51.432 MSqlQuery: UNLOCK TABLES
2009-07-07 20:32:51.446 ProgramInfo: StartedRecording: Recording to '/var/lib/mythtv/recordings/1009_20090707203249.mpg'
2009-07-07 20:32:51.458 MSqlQuery: DELETE FROM recordedseek WHERE chanid = '1009' AND starttime = '2009-07-07T20:32:49';
2009-07-07 20:32:51.461 MSqlQuery: DELETE FROM recordedmarkup WHERE chanid = '1009' AND starttime = '2009-07-07T20:32:49';
2009-07-07 20:32:51.480 MSqlQuery: REPLACE INTO recordedcredits SELECT * FROM credits WHERE chanid = '1009' AND starttime = '2009-07-07T20:30:00';
2009-07-07 20:32:51.481 MSqlQuery: REPLACE INTO recordedprogram SELECT * from program WHERE chanid = '1009' AND starttime = '2009-07-07T20:30:00' AND title = 'Two and a Half Men';
2009-07-07 20:32:51.492 MSqlQuery: REPLACE INTO recordedrating SELECT * from programrating WHERE chanid = '1009' AND starttime = '2009-07-07T20:30:00';
2009-07-07 20:32:51.496 TVRec(2): StartedRecording(0x7faeb810e010) fn(/var/lib/mythtv/recordings/1009_20090707203249.mpg)
2009-07-07 20:32:51.497 MythEvent: RECORDING_LIST_CHANGE
2009-07-07 20:32:51.524 MSqlQuery: UPDATE recorded SET autoexpire = 10000 WHERE chanid = '1009' AND starttime = '2009-07-07T20:32:49' ;
2009-07-07 20:32:51.524 MSqlQuery: UPDATE recorded SET recgroup = 'LiveTV' WHERE chanid = '1009' AND starttime = '2009-07-07T20:32:49' ;
2009-07-07 20:32:51.525 MSqlQuery: INSERT INTO tvchain (chanid, starttime, endtime, chainid, chainpos, discontinuity, watching, hostprefix, cardtype,  channame, input) VALUES('1009', '2009-07-07T20:32:49', '2009-07-07T21:00:00', 'live-tv-2009-07-07T20:32:49', 0,  0, 0, 'myth://192.168.1.11:6543/', 'DUMMY', '9',  'DVBInput' );
2009-07-07 20:32:51.526 Chain: Appended@0 '1009_20090707203249'
2009-07-07 20:32:51.526 MythEvent: LIVETV_CHAIN UPDATE live-tv-2009-07-07T20:32:49
2009-07-07 20:32:51.536 MSqlQuery: DELETE FROM inuseprograms WHERE chanid = '1009' AND starttime = '2009-07-07T20:32:49' AND hostname = 'tv' AND recusage = 'recorder' ;
2009-07-07 20:32:51.544 MSqlQuery: INSERT INTO inuseprograms  (chanid, starttime, recusage, hostname, lastupdatetime,  rechost, recdir )  VALUES  ('1009', '2009-07-07T20:32:49', 'recorder', 'tv', '2009-07-07T20:32:51',  'tv', '/var/lib/mythtv/recordings');
2009-07-07 20:32:51.558 TVRec(2): Starting Signal Monitor
2009-07-07 20:32:51.569 TVRec(2): SetupSignalMonitor(1, 1)
2009-07-07 20:32:51.576 DVBChan(2:1): Opening DVB channel
2009-07-07 20:32:51.593 DVBSM(1): Can measure Signal Strength
2009-07-07 20:32:51.603 DVBSM(1): Can measure S/N
2009-07-07 20:32:51.605 DVBSM(1): Can measure Bit Error Rate
2009-07-07 20:32:51.609 DVBSM(1): Can count Uncorrected Blocks
2009-07-07 20:32:51.624 SM(1)::RemoveFlags: Seen() Match() Wait()
2009-07-07 20:32:51.632 DVBSM(1): DVBSignalMonitor::ctor initial flags Seen() Match() Wait(Sig,SNR,BER,UB,)
2009-07-07 20:32:51.640 TVRec(2): Signal monitor successfully created
2009-07-07 20:32:51.648 TVRec(2): Setting up table monitoring.
2009-07-07 20:32:51.656 MSqlQuery: SELECT recordingprofiles.id, profilegroups.hostname,        profilegroups.is_default FROM recordingprofiles, profilegroups, capturecard WHERE profilegroups.id       = recordingprofiles.profilegroup AND       profilegroups.cardtype = 'DVB'                      AND       recordingprofiles.name = 'Live TV'
2009-07-07 20:32:51.705 MSqlQuery: SELECT cardtype, profilegroups.name FROM profilegroups, recordingprofiles WHERE profilegroups.id     = recordingprofiles.profilegroup AND       recordingprofiles.id = 18
2009-07-07 20:32:51.706 MSqlQuery: SELECT name FROM recordingprofiles WHERE id = 18
2009-07-07 20:32:51.747 MSqlQuery: SELECT value FROM codecparams WHERE profile = 18 AND name = 'transcodefilters'
2009-07-07 20:32:51.758 MSqlQuery: SELECT value FROM codecparams WHERE profile = 18 AND name = 'transcodelossless'
2009-07-07 20:32:51.759 MSqlQuery: SELECT value FROM codecparams WHERE profile = 18 AND name = 'transcoderesize'
2009-07-07 20:32:51.764 MSqlQuery: SELECT value FROM codecparams WHERE profile = 18 AND name = 'autotranscode'
2009-07-07 20:32:51.791 MSqlQuery: SELECT value FROM codecparams WHERE profile = 18 AND name = 'recordingtype'
2009-07-07 20:32:51.803 Using profile 'Live TV' to record
2009-07-07 20:32:51.803 TVRec(2): MPEG program number: 1
2009-07-07 20:32:51.808 DTVSM(1)::SetProgramNumber(1): 
2009-07-07 20:32:51.835 SM(1)::RemoveFlags: Seen(PMT,Crypt,) Match(PMT,Crypt,) Wait()
2009-07-07 20:32:51.838 SM(1)::AddFlags: Seen() Match() Wait(PMT,)
2009-07-07 20:32:51.847 SM(1)::AddFlags: Seen() Match() Wait(PAT,PMT,Pos,)
2009-07-07 20:32:51.852 TVRec(2): Successfully set up MPEG table monitoring.
2009-07-07 20:32:51.860 SM(1)::Start: begin
2009-07-07 20:32:51.868 SM(1)::AddFlags: Seen() Match() Wait(Sig,)
2009-07-07 20:32:51.876 SM(1)::RemoveFlags: Seen() Match() Wait(Pos,)
2009-07-07 20:32:51.888 SM(1)::Start: end
2009-07-07 20:32:51.905 TVRec(2): SetFlags(SignalMonitorRunning,) -> RunMainLoop,SignalMonitorRunning,RingBufferReady,
2009-07-07 20:32:51.908 MythEvent: SIGNAL 2
2009-07-07 20:32:51.924 TVRec(2): ClearFlags(WaitingForSignal,) -> RunMainLoop,SignalMonitorRunning,RingBufferReady,
2009-07-07 20:32:51.946 TVRec(2): SetFlags(WaitingForSignal,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,RingBufferReady,
2009-07-07 20:32:51.947 TVRec(2): SetFlags(DummyRecorderRunning,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
2009-07-07 20:32:51.952 DummyDTVRecorder -- started
2009-07-07 20:32:51.960 TVRec(2): SetFlags(RingBufferReady,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
2009-07-07 20:32:51.978 SM(1)::AddFlags: Seen() Match() Wait(Sig,)
2009-07-07 20:32:51.980 TVRec(2): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
2009-07-07 20:32:52.002 TVRec(2): SetFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
2009-07-07 20:32:52.002 TVRec(2): SetFlags(CancelNextRecording,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
2009-07-07 20:32:52.024 MSqlQuery: SELECT chanid FROM channel, cardinput WHERE cardinput.sourceid = channel.sourceid AND       channel.channum = '9' AND       cardinput.cardid = 2
2009-07-07 20:32:52.028 MSqlQuery: SELECT inputname, sourceid, cardid FROM cardinput WHERE cardinputid = 2
2009-07-07 20:32:52.047 MSqlQuery: SELECT mplexid FROM channel WHERE chanid = 1009
2009-07-07 20:32:52.047 MSqlQuery: SELECT chanid FROM channel, cardinput WHERE cardinput.sourceid = channel.sourceid AND       channel.channum = '9' AND       cardinput.cardid = 2
2009-07-07 20:32:52.069 MSqlQuery: SELECT inputname, sourceid, cardid FROM cardinput WHERE cardinputid = 2
2009-07-07 20:32:52.069 MSqlQuery: SELECT mplexid FROM channel WHERE chanid = 1009
2009-07-07 20:32:52.093 Cardid 2: max bitrate 142089 KB/min
2009-07-07 20:32:52.096 Maximal bitrate of busy encoders is 142089 KB/min
2009-07-07 20:32:52.104 tv:/var/lib/mythtv/recordings (fsID 1, dirID 1) using 594268960 out of 1452289480 KB, looking for matches
--- GetFilesystemInfos directory list start ---
Dir: tv:/var/lib/mythtv/recordings
     Location: Local
     fsID    : 1
     dirID   : 1
     TotalKB : 1452289480
     UsedKB  : 594268960
     FreeKB  : 858020520

--- GetFilesystemInfos directory list end ---
2009-07-07 20:32:52.025 write -> 22 2       ok
2009-07-07 20:32:52.213 MythSocket(7faeb805b640:22): DownRef: 1
2009-07-07 20:32:52.200 MSqlQuery: SELECT data FROM settings WHERE value = 'AutoExpireExtraSpace' AND hostname = 'tv' ;
2009-07-07 20:32:52.232 MSqlQuery: SELECT data FROM settings WHERE value = 'AutoExpireExtraSpace' AND hostname IS NULL;
2009-07-07 20:32:52.240 fsID #1: Total:  1385.0 GB   Used:   566.7 GB   Free:   818.3 GB
2009-07-07 20:32:52.248 MSqlQuery: SELECT chanid FROM channel, cardinput WHERE cardinput.sourceid = channel.sourceid AND       channel.channum = '9' AND       cardinput.cardid = 2
2009-07-07 20:32:52.256 MSqlQuery: SELECT inputname, sourceid, cardid FROM cardinput WHERE cardinputid = 2
2009-07-07 20:32:52.269 MSqlQuery: SELECT mplexid FROM channel WHERE chanid = 1009
2009-07-07 20:32:52.281     Cardid 2: max bitrate 18945 Kb/sec, fsID 1 max is now 142089 KB/min
2009-07-07 20:32:52.281   Max of 142089 KB/min for fsID 1 is higher than the existing Max of 0 so we'll use this Max instead
2009-07-07 20:32:52.288 AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 15 min
2009-07-07 20:32:52.296 Scheduler: FindNextLiveTVDir: next dir is '/var/lib/mythtv/recordings'
2009-07-07 20:32:52.313 MythSocket(7faeb8159760:18): UpRef: 2
2009-07-07 20:32:52.325 write -> 18 51      BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
2009-07-07 20:32:52.326 MythSocket(7faeb8159760:18): DownRef: 1
2009-07-07 20:32:52.332 MythSocket(7faeb8004130:19): UpRef: 2
2009-07-07 20:32:52.346 MythSocket(7faeb8004130:19): DownRef: 1
2009-07-07 20:32:52.358 MythSocket(7faeb8003eb0:20): UpRef: 2
2009-07-07 20:32:52.364 write -> 20 51      BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
2009-07-07 20:32:52.380 MythSocket(7faeb8003eb0:20): DownRef: 1
2009-07-07 20:32:52.392 MythSocket(7faeb8109650:38): UpRef: 2
2009-07-07 20:32:52.401 MythSocket(7faeb8109650:38): DownRef: 1
2009-07-07 20:32:52.408 MythSocket(7faeb81044a0:39): UpRef: 2
2009-07-07 20:32:52.424 write -> 39 51      BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
2009-07-07 20:32:52.425 MythSocket(7faeb81044a0:39): DownRef: 1
2009-07-07 20:32:52.436 MythSocket(7faeb8076b50:43): UpRef: 2
2009-07-07 20:32:52.444 MythSocket(7faeb8076b50:43): DownRef: 1
2009-07-07 20:32:52.458 MythSocket(1772d10:15): UpRef: 2
2009-07-07 20:32:52.469 MythSocket(1772d10:15): DownRef: 1
2009-07-07 20:32:52.472 MythSocket(17700a0:40): UpRef: 2
2009-07-07 20:32:52.480 write -> 40 51      BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
2009-07-07 20:32:52.491 MythSocket(17700a0:40): DownRef: 1
2009-07-07 20:32:52.503 MythSocket(7faeb805b640:22): UpRef: 2
2009-07-07 20:32:52.503 MythSocket(7faeb805b640:22): DownRef: 1
2009-07-07 20:32:52.504 MythSocket(7faeb8159760:18): UpRef: 2
2009-07-07 20:32:52.504 write -> 18 77      BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-tv-2009-07-07T20:32:49[]:[]empty
2009-07-07 20:32:52.505 MythSocket(7faeb8159760:18): DownRef: 1
2009-07-07 20:32:52.505 MythSocket(7faeb8004130:19): UpRef: 2
2009-07-07 20:32:52.506 MythSocket(7faeb8004130:19): DownRef: 1
2009-07-07 20:32:52.512 MythSocket(7faeb8003eb0:20): UpRef: 2
2009-07-07 20:32:52.524 write -> 20 77      BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-tv-2009-07-07T20:32:49[]:[]empty
2009-07-07 20:32:52.532 MythSocket(7faeb8003eb0:20): DownRef: 1
2009-07-07 20:32:52.547 MythSocket(7faeb8109650:38): UpRef: 2
2009-07-07 20:32:52.552 MythSocket(7faeb8109650:38): DownRef: 1
2009-07-07 20:32:52.569 MythSocket(7faeb81044a0:39): UpRef: 2
2009-07-07 20:32:52.581 write -> 39 77      BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-tv-2009-07-07T20:32:49[]:[]empty
2009-07-07 20:32:52.581 MythSocket(7faeb81044a0:39): DownRef: 1
2009-07-07 20:32:52.588 MythSocket(7faeb8076b50:43): UpRef: 2
2009-07-07 20:32:52.613 MythSocket(7faeb8076b50:43): DownRef: 1
2009-07-07 20:32:52.625 MythSocket(1772d10:15): UpRef: 2
2009-07-07 20:32:52.626 MythSocket(1772d10:15): DownRef: 1
2009-07-07 20:32:52.632 MythSocket(17700a0:40): UpRef: 2
2009-07-07 20:32:52.658 write -> 40 77      BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-tv-2009-07-07T20:32:49[]:[]empty
2009-07-07 20:32:52.658 MythSocket(17700a0:40): DownRef: 1
2009-07-07 20:32:52.669 MythSocket(7faeb805b640:22): UpRef: 2
2009-07-07 20:32:52.676 MythSocket(7faeb805b640:22): DownRef: 1
2009-07-07 20:32:52.702 MythSocket(7faeb8159760:18): UpRef: 2
2009-07-07 20:32:52.703 write -> 18 458     BACKEND_MESSAGE[]:[]SIGNAL 2[]:[]Signal Lock[]:[]slock 0 1 0 1 3000 1 1[]:[]Signal Power[]:[]signal 54224 0 0 65535 3000 1 1[]:[]Seen PAT[]:[]seen_pat 0 1 0 1 0 1 1[]:[]Matching PAT[]:[]matching_pat 0 1 0 1 0 1 1[]:[]Seen PMT[]:[]seen_pmt 0 1 0 1 0 1 1[]:[]Matching PMT[]:[]matching_pmt 0 1 0 1 0 1 1[]:[]Signal To Noise[]:[]snr 2827 0 0 65535 0 1 1[]:[]Bit Error Rate[]:[]ber 65535 65535 0 65535 0 0 1[]:[]Uncorrected Blocks[]:[]ucb 2827 65535 0 65535 0 0 1
2009-07-07 20:32:52.724 MythSocket(7faeb8159760:18): DownRef: 1
2009-07-07 20:32:52.725 MythSocket(7faeb8004130:19): UpRef: 2
2009-07-07 20:32:52.736 MythSocket(7faeb8004130:19): DownRef: 1
2009-07-07 20:32:52.744 MythSocket(7faeb8003eb0:20): UpRef: 2
2009-07-07 20:32:52.769 write -> 20 458     BACKEND_MESSAGE[]:[]SIGNAL 2[]:[]Signal Lock[]:[]slock 0 1 0 1 3000 1 1[]:[]Signal Power[]:[]signal 54224 0 0 65535 3000 1 1[]:[]Seen PAT[]:[]seen_pat 0 1 0 1 0 1 1[]:[]Matching PAT[]:[]matching_pat 0 1 0 1 0 1 1[]:[]Seen PMT[]:[]seen_pmt 0 1 0 1 0 1 1[]:[]Matching PMT[]:[]matching_pmt 0 1 0 1 0 1 1[]:[]Signal To Noise[]:[]snr 2827 0 0 65535 0 1 1[]:[]Bit Error Rate[]:[]ber 65535 65535 0 65535 0 0 1[]:[]Uncorrected Blocks[]:[]ucb 2827 65535 0 65535 0 0 1
2009-07-07 20:32:52.769 MythSocket(7faeb8003eb0:20): DownRef: 1
2009-07-07 20:32:52.791 MythSocket(7faeb8109650:38): UpRef: 2
2009-07-07 20:32:52.813 MythSocket(7faeb8109650:38): DownRef: 1
2009-07-07 20:32:52.814 MythSocket(7faeb81044a0:39): UpRef: 2
2009-07-07 20:32:52.836 write -> 39 458     BACKEND_MESSAGE[]:[]SIGNAL 2[]:[]Signal Lock[]:[]slock 0 1 0 1 3000 1 1[]:[]Signal Power[]:[]signal 54224 0 0 65535 3000 1 1[]:[]Seen PAT[]:[]seen_pat 0 1 0 1 0 1 1[]:[]Matching PAT[]:[]matching_pat 0 1 0 1 0 1 1[]:[]Seen PMT[]:[]seen_pmt 0 1 0 1 0 1 1[]:[]Matching PMT[]:[]matching_pmt 0 1 0 1 0 1 1[]:[]Signal To Noise[]:[]snr 2827 0 0 65535 0 1 1[]:[]Bit Error Rate[]:[]ber 65535 65535 0 65535 0 0 1[]:[]Uncorrected Blocks[]:[]ucb 2827 65535 0 65535 0 0 1
2009-07-07 20:32:52.837 MythSocket(7faeb81044a0:39): DownRef: 1
2009-07-07 20:32:52.858 MythSocket(7faeb8076b50:43): UpRef: 2
2009-07-07 20:32:52.858 MythSocket(7faeb8076b50:43): DownRef: 1
2009-07-07 20:32:52.880 MythSocket(1772d10:15): UpRef: 2
2009-07-07 20:32:52.880 MythSocket(1772d10:15): DownRef: 1
2009-07-07 20:32:52.902 MythSocket(17700a0:40): UpRef: 2
2009-07-07 20:32:52.924 write -> 40 458     BACKEND_MESSAGE[]:[]SIGNAL 2[]:[]Signal Lock[]:[]slock 0 1 0 1 3000 1 1[]:[]Signal Power[]:[]signal 54224 0 0 65535 3000 1 1[]:[]Seen PAT[]:[]seen_pat 0 1 0 1 0 1 1[]:[]Matching PAT[]:[]matching_pat 0 1 0 1 0 1 1[]:[]Seen PMT[]:[]seen_pmt 0 1 0 1 0 1 1[]:[]Matching PMT[]:[]matching_pmt 0 1 0 1 0 1 1[]:[]Signal To Noise[]:[]snr 2827 0 0 65535 0 1 1[]:[]Bit Error Rate[]:[]ber 65535 65535 0 65535 0 0 1[]:[]Uncorrected Blocks[]:[]ucb 2827 65535 0 65535 0 0 1
2009-07-07 20:32:52.925 MythSocket(17700a0:40): DownRef: 1
2009-07-07 20:32:52.946 MythSocket(7faeb805b640:22): UpRef: 2
2009-07-07 20:32:52.947 MythSocket(7faeb805b640:22): DownRef: 1
2009-07-07 20:32:52.215 MythSocket(7faeb805b640:22): socket is readable
2009-07-07 20:32:52.960 MythSocket(7faeb805b640:22): cb->readyRead()
2009-07-07 20:32:52.980 MythSocket(7faeb805b640:22): UpRef: 2
2009-07-07 20:32:52.980 MythSocket(7faeb8076b50:43): socket is readable
2009-07-07 20:32:53.002 MythSocket(7faeb8076b50:43): cb->readyRead()
2009-07-07 20:32:53.014 MythSocket(7faeb8076b50:43): UpRef: 2
2009-07-07 20:32:53.015 read  <- 43 35      QUERY_REMOTEENCODER 1[]:[]GET_STATE
2009-07-07 20:32:53.020 write -> 43 1       0
2009-07-07 20:32:53.028 MythSocket(7faeb8076b50:43): DownRef: 1
2009-07-07 20:32:52.980 read  <- 22 33      QUERY_RECORDER 2[]:[]IS_RECORDING
2009-07-07 20:32:53.044 write -> 22 1       1
2009-07-07 20:32:53.058 MythSocket(7faeb805b640:22): DownRef: 1
2009-07-07 20:32:52.131 MythEvent: SIGNAL 2
2009-07-07 20:32:53.047 MSqlQuery: SELECT chanid FROM channel, cardinput WHERE cardinput.sourceid = channel.sourceid AND       channel.channum = '9' AND       cardinput.cardid = 2
2009-07-07 20:32:53.084 MSqlQuery: SELECT inputname, sourceid, cardid FROM cardinput WHERE cardinputid = 2
2009-07-07 20:32:53.092 MSqlQuery: SELECT mplexid FROM channel WHERE chanid = 1009
2009-07-07 20:32:53.028 MythSocket(7faeb8076b50:43): socket is readable
2009-07-07 20:32:53.113 MythSocket(7faeb8076b50:43): cb->readyRead()
2009-07-07 20:32:53.076 MythSocket(7faeb8159760:18): UpRef: 2
2009-07-07 20:32:53.128 write -> 18 458     BACKEND_MESSAGE[]:[]SIGNAL 2[]:[]Signal Lock[]:[]slock 0 1 0 1 3000 1 1[]:[]Signal Power[]:[]signal 54224 0 0 65535 3000 1 1[]:[]Seen PAT[]:[]seen_pat 0 1 0 1 0 1 1[]:[]Matching PAT[]:[]matching_pat 0 1 0 1 0 1 1[]:[]Seen PMT[]:[]seen_pmt 0 1 0 1 0 1 1[]:[]Matching PMT[]:[]matching_pmt 0 1 0 1 0 1 1[]:[]Signal To Noise[]:[]snr 2827 0 0 65535 0 1 1[]:[]Bit Error Rate[]:[]ber 65535 65535 0 65535 0 0 1[]:[]Uncorrected Blocks[]:[]ucb 2827 65535 0 65535 0 0 1
2009-07-07 20:32:53.136 MythSocket(7faeb8159760:18): DownRef: 1
2009-07-07 20:32:53.147 MythSocket(7faeb8004130:19): UpRef: 2
2009-07-07 20:32:53.152 MythSocket(7faeb8004130:19): DownRef: 1