Ticket #12858: mythbackend.20160818132954.7322.log_extract

File mythbackend.20160818132954.7322.log_extract, 168.9 KB (added by Andy Harvey <Andy@…>, 3 years ago)

verbose debug mythbackend log file

Line 
12016-08-18 14:37:00.001023 I [7322/7386] Scheduler tv_rec.cpp:419 (StartRecording) - TVRec[7]: StartRecording("447 (Motors TV)":"2016-08-18 14:38:00")
22016-08-18 14:37:00.001045 I [7322/7386] Scheduler tv_rec.cpp:715 (SetRecordingStatus) - TVRec[7]: SetRecordingStatus(Unknown->Aborted) on line 425
32016-08-18 14:37:00.002182 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT count(*) FROM inuseprograms WHERE chanid   = '56378'   AND starttime = '2016-08-18T13:37:00Z' AND       hostname = 'mythtv.harvs.net' AND recusage  = 'recorder' <<<< Took 0ms, Returned 1 row(s)
42016-08-18 14:37:00.002210 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "count(*) = 0"
52016-08-18 14:37:00.002731 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) INSERT INTO inuseprograms  (chanid,         starttime,  recusage,  hostname,   lastupdatetime, rechost,    recdir) VALUES  ('56378',       '2016-08-18T13:37:00Z', 'recorder', 'mythtv.harvs.net',   '2016-08-18T13:37:00Z',   'mythtv.harvs.net',   '/video/Recordings') <<<< Took 0ms
62016-08-18 14:37:00.003213 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT DISTINCT recusage FROM inuseprograms WHERE lastupdatetime >= '2016-08-18T12:36:00Z' AND       chanid          = '56378'     AND       starttime       = '2016-08-18T13:37:00Z' <<<< Took 0ms, Returned 1 row(s)
72016-08-18 14:37:00.003231 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "recusage = recorder"
82016-08-18 14:37:00.003884 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT type, search, recpriority, prefinput, startoffset, endoffset, dupmethod, dupin, inactive, profile, recgroup, storagegroup, playgroup, autoexpire, maxepisodes, maxnewest, autocommflag, autotranscode, transcoder, autouserjob1, autouserjob2, autouserjob3, autouserjob4, autometadata, parentid, title, subtitle, description, season, episode, category, starttime, startdate, endtime, enddate, seriesid, programid, inetref, chanid, station, findday, findtime, findid, next_record, last_record, last_delete, avg_delay, filter, recgroupid FROM record WHERE recordid = '8706' ; <<<< Took 0ms, Returned 1 row(s)
92016-08-18 14:37:00.004075 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "type = 1, search = 5, recpriority = 1, prefinput = 0, startoffset = 1, endoffset = 10, dupmethod = 8, dupin = 15, inactive =
102016-08-18 14:37:00.004405 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT displayname, cardid, inputname FROM capturecard WHERE cardid = '7' <<<< Took 0ms, Returned 1 row(s)
112016-08-18 14:37:00.004426 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "displayname = Sky, cardid = 7, inputname = Composite"
122016-08-18 14:37:00.004645 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT recgroupid FROM recgroups WHERE recgroup = 'Andy' <<<< Took 0ms, Returned 1 row(s)
132016-08-18 14:37:00.004657 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "recgroupid = 4"
142016-08-18 14:37:00.004752 I [7322/7386] Scheduler mythdbcon.cpp:744 (exec) - MSqlQuery::exec(DBManager9) LOCK TABLES recorded WRITE
152016-08-18 14:37:00.004962 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT recordid     FROM recorded     WHERE chanid    = '56378' AND           starttime = '2016-08-18T13:37:00Z' <<<< Took 0ms, Returned 0 row(s)
162016-08-18 14:37:00.005526 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) INSERT INTO recorded    (chanid,    starttime,   endtime,         title,                subtitle,  description, season,          episode,              hostname,  category,    recgroup,        autoexpire,           recordid,  seriesid,    programid,       inetref,              stars,     previouslyshown,              originalairdate,      findid,    transcoder,  playgroup,       recpriority,          basename,  progstart,   progend,         profile,              duplicate, storagegroup, inputname,      recgroupid) VALUES  ('56378',   '2016-08-18T13:37:00Z',     '2016-08-18T14:00:00Z',           '447 (Motors TV)',               '2016-08-18 14:38:00', '',       '0',         '0',             'mythtv.harvs.net', '',   'Andy',       'true',             '8706', '',   '',      '',             '0',    'false',                      '0000-00-00',         '0',   '0', 'Default',      '1',         '56378_20160818133700.nuv', '2016-08-18T13:38:00Z',  '2016-08-18T13:50:00Z',        'Default',             0,         'Default',  'Sky',      'Andy'ID)  <<<< Took 0ms
172016-08-18 14:37:00.005649 I [7322/7386] Scheduler mythdbcon.cpp:744 (exec) - MSqlQuery::exec(DBManager9) UNLOCK TABLES
182016-08-18 14:37:00.005888 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) UPDATE channel SET last_record = NOW() WHERE chanid = '56378' <<<< Took 0ms
192016-08-18 14:37:00.006119 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) UPDATE record SET last_record = NOW() WHERE recordid = '8706' <<<< Took 0ms
202016-08-18 14:37:00.006144 I [7322/7386] Scheduler recordinginfo.cpp:935 (StartedRecording) - RecordingInfo(56378_20160818133700.nuv): StartedRecording: Recording to '/video/Recordings/56378_20160818133700.nuv'
212016-08-18 14:37:00.006348 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) DELETE FROM recordedseek WHERE chanid = '56378' AND starttime = '2016-08-18T13:37:00Z'; <<<< Took 0ms
222016-08-18 14:37:00.006526 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) DELETE FROM recordedmarkup WHERE chanid = '56378' AND starttime = '2016-08-18T13:37:00Z'; <<<< Took 0ms
232016-08-18 14:37:00.006815 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) REPLACE INTO recordedcredits SELECT * FROM credits WHERE chanid = '56378' AND starttime = '2016-08-18T13:38:00Z'; <<<< Took 0ms
242016-08-18 14:37:00.007422 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) REPLACE INTO recordedprogram SELECT * from program WHERE chanid = '56378' AND starttime = '2016-08-18T13:38:00Z' AND title = '447 (Motors TV)'; <<<< Took 0ms
252016-08-18 14:37:00.007771 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) REPLACE INTO recordedrating SELECT * from programrating WHERE chanid = '56378' AND starttime = '2016-08-18T13:38:00Z'; <<<< Took 0ms
262016-08-18 14:37:00.008102 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) INSERT INTO recordedfile SET basename = '56378_20160818133700.nuv', filesize = '0', width = '-1', height = '-1', fps = '0', aspect = '0', audio_sample_rate = '0', audio_avg_bitrate = '0', audio_channels = '0', audio_codec = '', video_codec = '', hostname = 'mythtv.harvs.net', storagegroup = 'Default', recordedid = '736', container = ''  <<<< Took 0ms
272016-08-18 14:37:00.008150 I [7322/7386] Scheduler tv_rec.cpp:814 (StartedRecording) - TVRec[7]: StartedRecording(56378_2016-08-18T13:37:00Z) fn(/video/Recordings/56378_20160818133700.nuv)
282016-08-18 14:37:00.008549 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT recordingprofiles.id, profilegroups.hostname,        profilegroups.is_default FROM recordingprofiles, profilegroups WHERE profilegroups.id       = recordingprofiles.profilegroup AND       profilegroups.cardtype = 'V4L'                      AND       recordingprofiles.name = 'Default' <<<< Took 0ms, Returned 1 row(s)
292016-08-18 14:37:00.008567 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "id = 1, hostname = , is_default = 1"
302016-08-18 14:37:00.009082 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT name  FROM recordingprofiles WHERE id = '1' <<<< Took 0ms, Returned 1 row(s)
312016-08-18 14:37:00.009094 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "name = Default"
322016-08-18 14:37:00.009300 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'transcodefilters' <<<< Took 0ms, Returned 0 row(s)
332016-08-18 14:37:00.009457 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'transcodelossless' <<<< Took 0ms, Returned 0 row(s)
342016-08-18 14:37:00.009600 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'transcoderesize' <<<< Took 0ms, Returned 0 row(s)
352016-08-18 14:37:00.009748 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'autotranscode' <<<< Took 0ms, Returned 1 row(s)
362016-08-18 14:37:00.009758 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 0"
372016-08-18 14:37:00.009915 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'width' <<<< Took 0ms, Returned 1 row(s)
382016-08-18 14:37:00.009923 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 720"
392016-08-18 14:37:00.010072 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'height' <<<< Took 0ms, Returned 1 row(s)
402016-08-18 14:37:00.010080 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 576"
412016-08-18 14:37:00.010213 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT videocodec  FROM recordingprofiles WHERE id = '1' <<<< Took 0ms, Returned 1 row(s)
422016-08-18 14:37:00.010221 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "videocodec = MPEG-4"
432016-08-18 14:37:00.010374 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'rtjpegquality' <<<< Took 0ms, Returned 1 row(s)
442016-08-18 14:37:00.010382 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 170"
452016-08-18 14:37:00.010520 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'rtjpeglumafilter' <<<< Took 0ms, Returned 0 row(s)
462016-08-18 14:37:00.010655 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'rtjpegchromafilter' <<<< Took 0ms, Returned 0 row(s)
472016-08-18 14:37:00.010800 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg4bitrate' <<<< Took 0ms, Returned 1 row(s)
482016-08-18 14:37:00.010808 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 2200"
492016-08-18 14:37:00.010956 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg4maxquality' <<<< Took 0ms, Returned 1 row(s)
502016-08-18 14:37:00.010964 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 2"
512016-08-18 14:37:00.011109 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg4minquality' <<<< Took 0ms, Returned 1 row(s)
522016-08-18 14:37:00.011117 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 15"
532016-08-18 14:37:00.011263 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg4qualdiff' <<<< Took 0ms, Returned 1 row(s)
542016-08-18 14:37:00.011270 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 3"
552016-08-18 14:37:00.011416 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'scalebitrate' <<<< Took 0ms, Returned 1 row(s)
562016-08-18 14:37:00.011423 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 1"
572016-08-18 14:37:00.011569 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg4optionvhq' <<<< Took 0ms, Returned 1 row(s)
582016-08-18 14:37:00.011577 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 1"
592016-08-18 14:37:00.011730 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg4option4mv' <<<< Took 0ms, Returned 1 row(s)
602016-08-18 14:37:00.011737 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 1"
612016-08-18 14:37:00.011887 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg4optionidct' <<<< Took 0ms, Returned 1 row(s)
622016-08-18 14:37:00.011894 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 0"
632016-08-18 14:37:00.012041 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg4optionime' <<<< Took 0ms, Returned 1 row(s)
642016-08-18 14:37:00.012048 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 0"
652016-08-18 14:37:00.012191 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'encodingthreadcount' <<<< Took 0ms, Returned 1 row(s)
662016-08-18 14:37:00.012199 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 2"
672016-08-18 14:37:00.012342 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg2bitrate' <<<< Took 0ms, Returned 1 row(s)
682016-08-18 14:37:00.012350 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 4500"
692016-08-18 14:37:00.012496 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'scalebitrate' <<<< Took 0ms, Returned 1 row(s)
702016-08-18 14:37:00.012504 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 1"
712016-08-18 14:37:00.012646 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'encodingthreadcount' <<<< Took 0ms, Returned 1 row(s)
722016-08-18 14:37:00.012653 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 2"
732016-08-18 14:37:00.012805 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'hardwaremjpegquality' <<<< Took 0ms, Returned 1 row(s)
742016-08-18 14:37:00.012812 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 100"
752016-08-18 14:37:00.012957 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'hardwaremjpeghdecimation' <<<< Took 0ms, Returned 1 row(s)
762016-08-18 14:37:00.012964 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 4"
772016-08-18 14:37:00.013107 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'hardwaremjpegvdecimation' <<<< Took 0ms, Returned 1 row(s)
782016-08-18 14:37:00.013115 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 4"
792016-08-18 14:37:00.013259 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg2streamtype' <<<< Took 0ms, Returned 1 row(s)
802016-08-18 14:37:00.013267 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = MPEG-2 PS"
812016-08-18 14:37:00.013412 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg2aspectratio' <<<< Took 0ms, Returned 1 row(s)
822016-08-18 14:37:00.013419 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 4:3"
832016-08-18 14:37:00.013562 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg2bitrate' <<<< Took 0ms, Returned 1 row(s)
842016-08-18 14:37:00.013569 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 4500"
852016-08-18 14:37:00.013721 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg2maxbitrate' <<<< Took 0ms, Returned 1 row(s)
862016-08-18 14:37:00.013729 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 6000"
872016-08-18 14:37:00.013872 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'low_mpeg4avgbitrate' <<<< Took 0ms, Returned 1 row(s)
882016-08-18 14:37:00.013879 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 4500"
892016-08-18 14:37:00.014022 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'low_mpeg4peakbitrate' <<<< Took 0ms, Returned 1 row(s)
902016-08-18 14:37:00.014029 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 6000"
912016-08-18 14:37:00.014172 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'medium_mpeg4avgbitrate' <<<< Took 0ms, Returned 1 row(s)
922016-08-18 14:37:00.014179 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 9000"
932016-08-18 14:37:00.014323 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'medium_mpeg4peakbitrate' <<<< Took 0ms, Returned 1 row(s)
942016-08-18 14:37:00.014330 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 11000"
952016-08-18 14:37:00.014472 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'high_mpeg4avgbitrate' <<<< Took 0ms, Returned 1 row(s)
962016-08-18 14:37:00.014479 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 13500"
972016-08-18 14:37:00.014622 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'high_mpeg4peakbitrate' <<<< Took 0ms, Returned 1 row(s)
982016-08-18 14:37:00.014630 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 20200"
992016-08-18 14:37:00.014768 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT audiocodec  FROM recordingprofiles WHERE id = '1' <<<< Took 0ms, Returned 1 row(s)
1002016-08-18 14:37:00.014776 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "audiocodec = MP3"
1012016-08-18 14:37:00.014925 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'samplerate' <<<< Took 0ms, Returned 1 row(s)
1022016-08-18 14:37:00.014933 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 32000"
1032016-08-18 14:37:00.015092 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mp3quality' <<<< Took 0ms, Returned 1 row(s)
1042016-08-18 14:37:00.015101 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 7"
1052016-08-18 14:37:00.015248 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'volume' <<<< Took 0ms, Returned 1 row(s)
1062016-08-18 14:37:00.015255 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 90"
1072016-08-18 14:37:00.015401 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'samplerate' <<<< Took 0ms, Returned 1 row(s)
1082016-08-18 14:37:00.015408 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 32000"
1092016-08-18 14:37:00.015414 E [7322/7386] Scheduler recordingprofile.cpp:162 (addSelection) - SampleRate: Attempted to add a rate 32000 Hz, which is not in the list of allowed rates.
1102016-08-18 14:37:00.015555 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg2audtype' <<<< Took 0ms, Returned 0 row(s)
1112016-08-18 14:37:00.015692 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg2audbitratel1' <<<< Took 0ms, Returned 0 row(s)
1122016-08-18 14:37:00.015826 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg2audbitratel2' <<<< Took 0ms, Returned 0 row(s)
1132016-08-18 14:37:00.015958 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg2audbitratel3' <<<< Took 0ms, Returned 0 row(s)
1142016-08-18 14:37:00.016089 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg2language' <<<< Took 0ms, Returned 0 row(s)
1152016-08-18 14:37:00.016219 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg2audvolume' <<<< Took 0ms, Returned 0 row(s)
1162016-08-18 14:37:00.016362 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'samplerate' <<<< Took 0ms, Returned 1 row(s)
1172016-08-18 14:37:00.016369 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 32000"
1182016-08-18 14:37:00.016521 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'volume' <<<< Took 0ms, Returned 1 row(s)
1192016-08-18 14:37:00.016529 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "value = 90"
1202016-08-18 14:37:00.016549 I [7322/7386] Scheduler tv_rec.cpp:4146 (LoadProfile) - TVRec[7]: Using profile 'Default' to record
1212016-08-18 14:37:00.016581 I [7322/7386] Scheduler tv_rec.cpp:2790 (InitAutoRunJobs) - InitAutoRunJobs for 56378_2016-08-18T13:37:00Z, line 821 -> 0x2
1222016-08-18 14:37:00.016597 I [7322/7386] Scheduler mythcorecontext.cpp:1592 (dispatch) - MythCoreContext::dispatch(): MythEvent: SYSTEM_EVENT REC_STARTED CARDID 7 CHANID 56378 STARTTIME 2016-08-18T13:37:00Z RECSTATUS -1 SENDER mythtv.harvs.net
1232016-08-18 14:37:00.016614 I [7322/7386] Scheduler tv_rec.cpp:4413 (ClearFlags) - TVRec[7]: ClearFlags(CancelNextRecording,) -> RunMainLoop,RingBufferReady, @ tv_rec.cpp:614
1242016-08-18 14:37:00.016626 I [7322/7386] Scheduler tv_rec.cpp:715 (SetRecordingStatus) - TVRec[7]: SetRecordingStatus(Aborted->Tuning) on line 617
1252016-08-18 14:37:00.016659 I [7322/7346] TVRecEvent tv_rec.cpp:1073 (HandleStateChange) - TVRec[7]: Changing from None to RecordingOnly
1262016-08-18 14:37:00.016651 D [7322/7322] CoreContext mythsystemevent.cpp:113 (SubstituteMatches) - MythSystemEventHandler: SubstituteMatches: BEFORE: /harvs/bin/mytheventhandler REC_STARTED "%TITLE%" %CARDID% %RECGROUP% %PLAYGROUP% %FILE% %RECSTATUS%
1272016-08-18 14:37:00.016670 I [7322/7346] TVRecEvent tv_rec.cpp:4413 (ClearFlags) - TVRec[7]: ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RingBufferReady, @ tv_rec.cpp:1301
1282016-08-18 14:37:00.016727 I [7322/7346] TVRecEvent tv_rec.cpp:3476 (HandleTuning) - TVRec[7]: HandleTuning Request: Program(ProgramInfo(56378_20160818133700.nuv): channame(Motors TV) startts(Thu Aug 18 13:38:00 2016 GMT) endts(Thu Aug 18 13:50:00 2016 GMT)
129             recstartts(Thu Aug 18 13:37:00 2016 GMT) recendts(Thu Aug 18 14:00:00 2016 GMT)
130             title(447 (Motors TV))) channel() input() flags(Recording,)
1312016-08-18 14:37:00.017028 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT channel.channum, capturecard.inputname FROM channel, capturecard WHERE channel.chanid       = '56378'            AND       capturecard.sourceid = '2'          AND       capturecard.cardid   = '7' <<<< Took 0ms, Returned 1 row(s)
1322016-08-18 14:37:00.017042 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "channum = 447, inputname = Composite"
1332016-08-18 14:37:00.017103 I [7322/7346] TVRecEvent tv_rec.cpp:3583 (TuningShutdowns) - TVRec[7]: TuningShutdowns(Program(ProgramInfo(56378_20160818133700.nuv): channame(Motors TV) startts(Thu Aug 18 13:38:00 2016 GMT) endts(Thu Aug 18 13:50:00 2016 GMT)
134             recstartts(Thu Aug 18 13:37:00 2016 GMT) recendts(Thu Aug 18 14:00:00 2016 GMT)
135             title(447 (Motors TV))) channel(447) input(Composite) flags(Recording,))
1362016-08-18 14:37:00.017313 I [7322/7322] CoreContext mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager1) SELECT r.title,            r.subtitle,     r.description,            r.season,           r.episode,      r.category,               r.chanid,           c.channum,      c.callsign,               c.name,             c.outputfilters,r.recgroup,               r.playgroup,        r.storagegroup, r.basename,               r.hostname,         r.recpriority,  r.seriesid,               r.programid,        r.inetref,      r.filesize,               r.progstart,        r.progend,      r.stars,                  r.starttime,        r.endtime,      p.airdate+0,              r.originalairdate,  r.lastmodified, r.recordid,               c.commmethod,       r.commflagged,  r.previouslyshown,        r.transcoder,       r.transcoded,   r.deletepending,          r.preserve,         r.cutlist,      r.autoexpire,             r.editing,          r.bookmark,     r.watched,                p.audioprop+0,      p.videoprop+0,  p.subtitletypes+0,        r.findid,           rec.dupin,      rec.dupmethod,            p.syndicatedepisodenumber, p.partnumber, p.parttotal,         p.season,           p.episode,      p.totalepisodes,          p.category_type,    r.recordedid,   r.inputname,              r.bookmarkupdate                                       FROM recorded AS r LEFT JOIN channel AS c ON (r.chanid    = c.chanid) LEFT JOIN recordedprogram AS p ON (r.chanid    = p.chanid AND     r.progstart = p.starttime) LEFT JOIN record AS rec ON (r.recordid = rec.recordid) WHERE r.chanid    = '56378' AND       r.starttime = '2016-08-18T13:37:00Z' <<<< Took 0ms, Returned 1 row(s)
1372016-08-18 14:37:00.017315 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT channel.channum, capturecard.inputname FROM channel, capturecard WHERE channel.chanid       = '56378'            AND       capturecard.sourceid = '2'          AND       capturecard.cardid   = '7' <<<< Took 0ms, Returned 1 row(s)
1382016-08-18 14:37:00.017330 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "channum = 447, inputname = Composite"
1392016-08-18 14:37:00.017349 I [7322/7346] TVRecEvent tv_rec.cpp:3563 (TuningCheckForHWChange) - TVRec[7]: HW Tuner: 7->7
1402016-08-18 14:37:00.017356 I [7322/7346] TVRecEvent tv_rec.cpp:4413 (ClearFlags) - TVRec[7]: ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady, @ tv_rec.cpp:3663
1412016-08-18 14:37:00.017361 I [7322/7346] TVRecEvent tv_rec.cpp:3498 (HandleTuning) - TVRec[7]: No recorder yet, calling TuningFrequency
1422016-08-18 14:37:00.017364 I [7322/7346] TVRecEvent tv_rec.cpp:3685 (TuningFrequency) - TVRec[7]: TuningFrequency
1432016-08-18 14:37:00.017492 D [7322/7322] CoreContext mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager1) Result: "title = 447 (Motors TV), subtitle = 2016-08-18 14:38:00, description = , season = 0, episode = 0, category = , chanid = 56378, channum = 447, callsign = Motors TV, name = Motors TV, outputfilters = , recgroup = Andy, playgroup = Default, storagegroup = Default, basename = 56378_20160818133700.nuv, hostname = mythtv.harvs.net, recpriority = 1, seriesid = , programid = , inetref = , filesize = 0, progstart = 2016-08-18T13:38:00, progend = 2016-08-18T13:50:00, stars = 0, starttime = 2016-08-18T13:37:00, endtime = 2016-08-18T14:00:00, p.airdate+0 = 0, originalairdate = , lastmodified = 2016-08-18T13:37:00, recordid = 8706, commmethod = -1, commflagged = 0, previouslyshown =
1442016-08-18 14:37:00.017522 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT quicktune FROM capturecard WHERE cardid    = '7' AND       inputname = 'Composite' <<<< Took 0ms, Returned 1 row(s)
1452016-08-18 14:37:00.017531 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "quicktune =
1462016-08-18 14:37:00.017591 I [7322/7346] TVRecEvent recorders/v4lchannel.cpp:104 (Open) - V4LChannel[7](/dev/video0): Device name 'usbtv' driver 'usbtv'.
1472016-08-18 14:37:00.017598 I [7322/7346] TVRecEvent recorders/v4lchannel.cpp:110 (Open) - V4LChannel[7](/dev/video0): v4l2: stream io: 1 std io: 1 async io: 0 tuner 0 sliced vbi 0
1482016-08-18 14:37:00.017757 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT sourceid,    inputname,        startchan,   externalcommand,        tunechan FROM capturecard WHERE cardid = '7' <<<< Took 0ms, Returned 1 row(s)
1492016-08-18 14:37:00.017777 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "sourceid = 2, inputname = Composite, startchan = 447, externalcommand = /harvs/bin/skychan, tunechan = "
1502016-08-18 14:37:00.017812 I [7322/7322] CoreContext mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager1) SELECT DISTINCT dirname FROM storagegroup WHERE groupname = 'Default' <<<< Took 0ms, Returned 1 row(s)
1512016-08-18 14:37:00.017822 D [7322/7322] CoreContext mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager1) Result: "dirname = /video/Recordings/"
1522016-08-18 14:37:00.017849 D [7322/7322] CoreContext storagegroup.cpp:608 (FindFile) - SG(Default): FindFile: Searching for '56378_20160818133700.nuv'
1532016-08-18 14:37:00.017856 D [7322/7322] CoreContext storagegroup.cpp:639 (FindFileDir) - SG(Default): FindFileDir: Checking '/video/Recordings' for '/video/Recordings/56378_20160818133700.nuv'
1542016-08-18 14:37:00.018032 I [7322/7322] CoreContext mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager1) SELECT DISTINCT dirname FROM storagegroup  <<<< Took 0ms, Returned 2 row(s)
1552016-08-18 14:37:00.018041 D [7322/7322] CoreContext mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager1) Result: "dirname = /video/Recordings/"
1562016-08-18 14:37:00.018056 D [7322/7322] CoreContext mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager1) Result: "dirname = /video/LiveTV/"
1572016-08-18 14:37:00.018076 D [7322/7322] CoreContext storagegroup.cpp:639 (FindFileDir) - SG(): FindFileDir: Checking '/video/Recordings' for '/video/Recordings/56378_20160818133700.nuv'
1582016-08-18 14:37:00.018083 D [7322/7322] CoreContext storagegroup.cpp:639 (FindFileDir) - SG(): FindFileDir: Checking '/video/LiveTV' for '/video/LiveTV/56378_20160818133700.nuv'
1592016-08-18 14:37:00.018103 E [7322/7322] CoreContext storagegroup.cpp:622 (FindFile) - SG(Default): FindFile: Unable to find '56378_20160818133700.nuv'!
1602016-08-18 14:37:00.018109 E [7322/7322] CoreContext programinfo.cpp:2594 (GetPlaybackURL) - ProgramInfo(56378_20160818133700.nuv): GetPlaybackURL: '56378_20160818133700.nuv' should be local, but it can not be found.
1612016-08-18 14:37:00.018174 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT channum, callsign, channel.chanid,        atsc_major_chan, atsc_minor_chan,        name, icon, mplexid, visible,        channel.sourceid, GROUP_CONCAT(DISTINCT capturecard.cardid),        GROUP_CONCAT(DISTINCT channelgroup.grpid),        xmltvid FROM channel LEFT JOIN channelgroup ON channel.chanid       = channelgroup.chanid    JOIN capturecard  ON capturecard.sourceid = channel.sourceid WHERE channel.sourceid='2'  GROUP BY chanid <<<< Took 0ms, Returned 1 row(s)
1622016-08-18 14:37:00.018205 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "channum = 447, callsign = Motors TV, chanid = 56378, atsc_major_chan = 0, atsc_minor_chan = 0, name = Motors TV, icon = motorstv.jpg, mplexid = 0, visible = , sourceid = 2, GROUP_CONCAT(DISTINCT capturecard.cardid) = 7, GROUP_CONCAT(DISTINCT channelgroup.grpid) = , xmltvid = 30141"
1632016-08-18 14:37:00.018225 D [7322/7322] CoreContext mythsystemevent.cpp:213 (SubstituteMatches) - MythSystemEventHandler: SubstituteMatches: AFTER : /harvs/bin/mytheventhandler REC_STARTED "447 (Motors TV)" 7 Andy Default 56378_20160818133700.nuv -1
1642016-08-18 14:37:00.018240 I [7322/7346] TVRecEvent recorders/channelbase.cpp:685 (InitializeInput) - ChannelBase[7]: Input #7: 'Composite' schan(447) sourceid(2)
1652016-08-18 14:37:00.018252 I [7322/7346] TVRecEvent recorders/v4lchannel.cpp:249 (InitializeInputs) - Global TVFormat Setting 'PAL'
1662016-08-18 14:37:00.018267 I [7322/7346] TVRecEvent recorders/v4lchannel.cpp:273 (InitializeInputs) - V4LChannel[7](/dev/video0): Input #7: 'Composite' schan(447) tun() v4l2(PAL)
1672016-08-18 14:37:00.018273 I [7322/7346] TVRecEvent recorders/v4lchannel.cpp:302 (SetFormat) - V4LChannel[7](/dev/video0): SetFormat(Default) fmt(PAL) input(7)
1682016-08-18 14:37:00.018276 I [7322/7322] CoreContext referencecounter.cpp:114 (IncrRef) - (0x7f7960012230)::IncrRef() -> 2
1692016-08-18 14:37:00.018277 I [7322/7346] TVRecEvent recorders/dtvchannel.cpp:178 (SetChannelByString) - DTVChan[7](/dev/video0): SetChannelByString(447):
1702016-08-18 14:37:00.018279 I [7322/7322] CoreContext referencecounter.cpp:114 (IncrRef) - (0x7f79600116f0)::IncrRef() -> 2
1712016-08-18 14:37:00.018287 I [7322/7322] CoreContext referencecounter.cpp:145 (DecrRef) - (0x7f7960012230)::DecrRef() -> 1
1722016-08-18 14:37:00.018289 I [7322/7322] CoreContext referencecounter.cpp:145 (DecrRef) - (0x7f79600116f0)::DecrRef() -> 1
1732016-08-18 14:37:00.018389 D [7322/7904] SystemEvent mythsystemunix.cpp:789 (Fork) - Launching: /harvs/bin/mytheventhandler REC_STARTED "447 (Motors TV)" 7 Andy Default 56378_20160818133700.nuv -1
1742016-08-18 14:37:00.020363 I [7322/7904] SystemEvent mythsystemunix.cpp:948 (Fork) - Managed child (PID: 7905) has started! * command=/harvs/bin/mytheventhandler REC_STARTED "447 (Motors TV)" 7 Andy Default 56378_20160818133700.nuv -1, timeout=0
1752016-08-18 14:37:00.020400 I [7322/7904] SystemEvent referencecounter.cpp:114 (IncrRef) - (0x7f796000ebf0)::IncrRef() -> 2
1762016-08-18 14:37:00.020426 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT chanid FROM channel WHERE channum  = '447' AND       sourceid = '2' <<<< Took 2ms, Returned 1 row(s)
1772016-08-18 14:37:00.020447 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "chanid = 56378"
1782016-08-18 14:37:00.020827 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT inputname, sourceid, livetvorder, schedorder, displayname, recpriority, quicktune FROM capturecard WHERE cardid = '7' <<<< Took 0ms, Returned 1 row(s)
1792016-08-18 14:37:00.020851 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "inputname = Composite, sourceid = 2, livetvorder = 7, schedorder = 7, displayname = Sky, recpriority = 0, quicktune =
1802016-08-18 14:37:00.021057 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT mplexid FROM channel WHERE chanid = '56378' <<<< Took 0ms, Returned 1 row(s)
1812016-08-18 14:37:00.021068 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "mplexid = 0"
1822016-08-18 14:37:00.021319 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT chanid FROM channel WHERE channum  = '447' AND       sourceid = '2' <<<< Took 0ms, Returned 1 row(s)
1832016-08-18 14:37:00.021334 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "chanid = 56378"
1842016-08-18 14:37:00.021501 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT inputgroupid FROM inputgroup WHERE cardinputid = '7' ORDER BY inputgroupid, cardinputid, inputgroupname <<<< Took 0ms, Returned 2 row(s)
1852016-08-18 14:37:00.021508 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "inputgroupid = 3"
1862016-08-18 14:37:00.021515 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "inputgroupid = 3"
1872016-08-18 14:37:00.021744 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT DISTINCT cardid FROM capturecard, inputgroup WHERE inputgroupid = '3' AND       capturecard.cardid = inputgroup.cardinputid ORDER BY cardid <<<< Took 0ms, Returned 1 row(s)
1882016-08-18 14:37:00.021751 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "cardid = 7"
1892016-08-18 14:37:00.022026 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT finetune, freqid, tvformat, freqtable,        commmethod, mplexid,        atsc_major_chan, atsc_minor_chan, serviceid, chanid FROM channel, videosource WHERE videosource.sourceid = channel.sourceid AND       channum              = '447'         AND       channel.sourceid     = '2' <<<< Took 0ms, Returned 1 row(s)
1902016-08-18 14:37:00.022051 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "finetune = 0, freqid = 447, tvformat = PAL, freqtable = default, commmethod = -1, mplexid = 0, atsc_major_chan = 0, atsc_minor_chan = 0, serviceid = 0, chanid = 56378"
1912016-08-18 14:37:00.022075 I [7322/7346] TVRecEvent recorders/v4lchannel.cpp:302 (SetFormat) - V4LChannel[7](/dev/video0): SetFormat(PAL) fmt(PAL) input(7)
1922016-08-18 14:37:00.022079 I [7322/7346] TVRecEvent recorders/dtvchannel.cpp:325 (SetChannelByString) - DTVChan[7](/dev/video0): SetChannelByString(447): success
1932016-08-18 14:37:00.022282 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT channel.brightness FROM channel WHERE channum  = '447' AND       sourceid = '2' <<<< Took 0ms, Returned 1 row(s)
1942016-08-18 14:37:00.022290 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "brightness = 32768"
1952016-08-18 14:37:00.022427 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT brightness FROM capturecard WHERE capturecard.cardid = '7' <<<< Took 0ms, Returned 1 row(s)
1962016-08-18 14:37:00.022434 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "brightness = 0"
1972016-08-18 14:37:00.022460 E [7322/7346] TVRecEvent recorders/v4lchannel.cpp:644 (InitPictureAttribute) - V4LChannel[7](/dev/video0): InitPictureAttribute(brightness): failed to query controls.
198                        eno: Inappropriate ioctl for device (25)
1992016-08-18 14:37:00.022466 I [7322/7346] TVRecEvent recorders/channelbase.cpp:520 (ChangeExternalChannel) - ChannelBase[7]: Running command: /harvs/bin/skychan 447
2002016-08-18 14:37:00.022493 D [7322/7346] TVRecEvent mythsystemunix.cpp:789 (Fork) - Launching: /harvs/bin/skychan 447
2012016-08-18 14:37:00.024309 I [7322/7346] TVRecEvent mythsystemunix.cpp:948 (Fork) - Managed child (PID: 7906) has started! *& command=/harvs/bin/skychan 447, timeout=0
2022016-08-18 14:37:00.024323 I [7322/7346] TVRecEvent referencecounter.cpp:114 (IncrRef) - (0x7f7a14026320)::IncrRef() -> 2
2032016-08-18 14:37:00.024365 I [7322/7346] TVRecEvent tv_rec.cpp:3812 (TuningFrequency) - TVRec[7]: Starting Signal Monitor
2042016-08-18 14:37:00.024373 I [7322/7346] TVRecEvent tv_rec.cpp:2022 (SetupSignalMonitor) - TVRec[7]: SetupSignalMonitor(1, 0)
2052016-08-18 14:37:00.024398 I [7322/7346] TVRecEvent tv_rec.cpp:2044 (SetupSignalMonitor) - TVRec[7]: Signal monitor successfully created
2062016-08-18 14:37:00.024405 D [7322/7346] TVRecEvent recorders/signalmonitor.cpp:273 (Start) - SigMon[7](/dev/video0)::Start: begin
2072016-08-18 14:37:00.024626 D [7322/7907] SignalMonitor recorders/channelbase.cpp:547 (GetScriptStatus) - ChannelBase[7]: GetScriptStatus() 142
2082016-08-18 14:37:00.024628 D [7322/7346] TVRecEvent recorders/signalmonitor.cpp:281 (Start) - SigMon[7](/dev/video0)::Start: end
2092016-08-18 14:37:00.024636 D [7322/7907] SignalMonitor recorders/channelbase.cpp:565 (GetScriptStatus) - ChannelBase[7]: GetScriptStatus() 142 -> 1
2102016-08-18 14:37:00.024641 I [7322/7346] TVRecEvent tv_rec.cpp:4404 (SetFlags) - TVRec[7]: SetFlags(SignalMonitorRunning,) -> RunMainLoop,SignalMonitorRunning,RingBufferReady, @ tv_rec.cpp:3839
2112016-08-18 14:37:00.024651 I [7322/7346] TVRecEvent tv_rec.cpp:4413 (ClearFlags) - TVRec[7]: ClearFlags(WaitingForSignal,) -> RunMainLoop,SignalMonitorRunning,RingBufferReady, @ tv_rec.cpp:3840
2122016-08-18 14:37:00.024656 I [7322/7346] TVRecEvent tv_rec.cpp:4404 (SetFlags) - TVRec[7]: SetFlags(WaitingForSignal,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,RingBufferReady, @ tv_rec.cpp:3845
2132016-08-18 14:37:00.024688 D [7322/7346] TVRecEvent tv_rec.cpp:3866 (TuningFrequency) - TVRec[7]: Start recording deadline: 14:37:03.024 Good signal deadline: 15:00:00.000
2142016-08-18 14:37:00.024700 I [7322/7346] TVRecEvent tv_rec.cpp:4413 (ClearFlags) - TVRec[7]: ClearFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,RingBufferReady, @ tv_rec.cpp:3901
2152016-08-18 14:37:00.024704 I [7322/7346] TVRecEvent tv_rec.cpp:4404 (SetFlags) - TVRec[7]: SetFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,RingBufferReady, @ tv_rec.cpp:3903
2162016-08-18 14:37:00.024718 I [7322/7346] TVRecEvent tv_rec.cpp:4017 (TuningSignalCheck) - TVRec[7]: TuningSignalCheck: Still waiting.  Will timeout @ 15:00:00.000
2172016-08-18 14:37:00.024894 I [7322/7386] Scheduler autoexpire.cpp:1084 (Update) - AutoExpire: Cardid 7: is starting a recording on an unknown fsID soon.
2182016-08-18 14:37:00.024945 I [7322/7386] Scheduler recordinginfo.cpp:1236 (AddHistory) - AddHistory: -10/0, 0, 0, 2016-08-18T13:38:00Z/447 (Motors TV)
2192016-08-18 14:37:00.025430 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) REPLACE INTO oldrecorded (chanid,starttime,endtime,title,subtitle,description,season,episode,category,seriesid,programid,inetref,findid,recordid,station,rectype,recstatus,duplicate,reactivate,future) VALUES('56378','2016-08-18T13:38:00Z','2016-08-18T13:50:00Z','447 (Motors TV)','2016-08-18 14:38:00','','0','0','','','','','0','8706','Motors TV','1','-10','false','0','false'); <<<< Took 0ms
2202016-08-18 14:37:00.025462 I [7322/7386] Scheduler scheduler.cpp:2820 (HandleRecordingStatusChange) - Tuning recording: "447 (Motors TV)":"2016-08-18 14:38:00": channel 56378 on cardid 7, sourceid 2
2212016-08-18 14:37:00.025889 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(SchedCon) SELECT recordid, next_record FROM record; <<<< Took 0ms, Returned 255 row(s)
2222016-08-18 14:37:00.025911 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 1388, next_record = "
2232016-08-18 14:37:00.025929 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 9, next_record = "
2242016-08-18 14:37:00.025951 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 5, next_record = 2016-08-26T21:35:00"
2252016-08-18 14:37:00.025962 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 1981, next_record = "
2262016-08-18 14:37:00.025972 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 17, next_record = "
2272016-08-18 14:37:00.025981 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 29, next_record = "
2282016-08-18 14:37:00.025989 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 1474, next_record = "
2292016-08-18 14:37:00.026007 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 6932, next_record = "
2302016-08-18 14:37:00.026016 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 6904, next_record = "
2312016-08-18 14:37:00.026034 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 108, next_record = "
2322016-08-18 14:37:00.026042 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 75, next_record = "
2332016-08-18 14:37:00.026051 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 1121, next_record = "
2342016-08-18 14:37:00.026061 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 6571, next_record = "
2352016-08-18 14:37:00.026078 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 731, next_record = 2016-08-22T19:25:00"
2362016-08-18 14:37:00.026087 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 1167, next_record = "
2372016-08-18 14:37:00.026097 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 119, next_record = "
2382016-08-18 14:37:00.026105 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 4970, next_record = "
2392016-08-18 14:37:00.026114 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 269, next_record = "
2402016-08-18 14:37:00.026122 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 1639, next_record = "
2412016-08-18 14:37:00.026130 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8318, next_record = "
2422016-08-18 14:37:00.026138 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 1654, next_record = "
2432016-08-18 14:37:00.026147 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 2031, next_record = "
2442016-08-18 14:37:00.026156 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 234, next_record = "
2452016-08-18 14:37:00.026165 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 253, next_record = "
2462016-08-18 14:37:00.026174 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 1834, next_record = "
2472016-08-18 14:37:00.026183 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 5917, next_record = "
2482016-08-18 14:37:00.026198 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 671, next_record = 2016-08-20T22:55:00"
2492016-08-18 14:37:00.026207 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 628, next_record = "
2502016-08-18 14:37:00.026215 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 1284, next_record = "
2512016-08-18 14:37:00.026224 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 743, next_record = "
2522016-08-18 14:37:00.026242 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 654, next_record = 2016-08-23T20:55:00"
2532016-08-18 14:37:00.026251 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 2081, next_record = "
2542016-08-18 14:37:00.026259 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 1005, next_record = "
2552016-08-18 14:37:00.026276 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 2510, next_record = 2016-08-26T19:55:00"
2562016-08-18 14:37:00.026286 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 875, next_record = "
2572016-08-18 14:37:00.026294 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 1706, next_record = "
2582016-08-18 14:37:00.026311 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 1488, next_record = 2016-08-22T20:55:00"
2592016-08-18 14:37:00.026327 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 3321, next_record = 2016-08-22T22:10:00"
2602016-08-18 14:37:00.026335 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 2446, next_record = "
2612016-08-18 14:37:00.026343 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 2532, next_record = "
2622016-08-18 14:37:00.026349 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 1008, next_record = "
2632016-08-18 14:37:00.026357 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 1145, next_record = "
2642016-08-18 14:37:00.026364 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8428, next_record = "
2652016-08-18 14:37:00.026373 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 3546, next_record = "
2662016-08-18 14:37:00.026388 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8275, next_record = 2016-08-23T21:00:00"
2672016-08-18 14:37:00.026396 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 1247, next_record = "
2682016-08-18 14:37:00.026405 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 1269, next_record = "
2692016-08-18 14:37:00.026413 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 1272, next_record = "
2702016-08-18 14:37:00.026429 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 1664, next_record = 2016-08-25T19:55:00"
2712016-08-18 14:37:00.026438 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 2468, next_record = "
2722016-08-18 14:37:00.026447 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 1999, next_record = "
2732016-08-18 14:37:00.026454 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 2000, next_record = "
2742016-08-18 14:37:00.026462 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 1958, next_record = "
2752016-08-18 14:37:00.026468 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 1581, next_record = "
2762016-08-18 14:37:00.026487 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 1660, next_record = 2016-08-20T14:20:00"
2772016-08-18 14:37:00.026505 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 1828, next_record = "
2782016-08-18 14:37:00.026513 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 1831, next_record = "
2792016-08-18 14:37:00.026533 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 1965, next_record = "
2802016-08-18 14:37:00.026550 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 2553, next_record = "
2812016-08-18 14:37:00.026559 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 2010, next_record = "
2822016-08-18 14:37:00.026569 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 2008, next_record = "
2832016-08-18 14:37:00.026585 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 2033, next_record = "
2842016-08-18 14:37:00.026592 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 2037, next_record = "
2852016-08-18 14:37:00.026600 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 6618, next_record = "
2862016-08-18 14:37:00.026607 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 2148, next_record = "
2872016-08-18 14:37:00.026616 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 2549, next_record = "
2882016-08-18 14:37:00.026624 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 2228, next_record = "
2892016-08-18 14:37:00.026633 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 6546, next_record = "
2902016-08-18 14:37:00.026640 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 2488, next_record = "
2912016-08-18 14:37:00.026653 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 2388, next_record = 2016-09-02T20:30:00"
2922016-08-18 14:37:00.026660 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 3696, next_record = "
2932016-08-18 14:37:00.026669 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8271, next_record = "
2942016-08-18 14:37:00.026684 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7590, next_record = "
2952016-08-18 14:37:00.026693 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 3622, next_record = "
2962016-08-18 14:37:00.026711 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 2694, next_record = "
2972016-08-18 14:37:00.026719 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 3110, next_record = "
2982016-08-18 14:37:00.026736 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 2627, next_record = "
2992016-08-18 14:37:00.026744 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 5760, next_record = "
3002016-08-18 14:37:00.026751 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7358, next_record = "
3012016-08-18 14:37:00.026760 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 3251, next_record = "
3022016-08-18 14:37:00.026767 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 6446, next_record = "
3032016-08-18 14:37:00.026777 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8452, next_record = "
3042016-08-18 14:37:00.026784 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 6930, next_record = "
3052016-08-18 14:37:00.026793 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 3576, next_record = "
3062016-08-18 14:37:00.026801 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 6058, next_record = "
3072016-08-18 14:37:00.026808 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 5061, next_record = "
3082016-08-18 14:37:00.026816 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 5058, next_record = "
3092016-08-18 14:37:00.026823 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8125, next_record = "
3102016-08-18 14:37:00.026832 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 3680, next_record = "
3112016-08-18 14:37:00.026849 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 3449, next_record = 2016-08-27T04:15:00"
3122016-08-18 14:37:00.026856 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 3550, next_record = "
3132016-08-18 14:37:00.026863 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 3057, next_record = "
3142016-08-18 14:37:00.026871 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 4631, next_record = "
3152016-08-18 14:37:00.026878 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 4421, next_record = "
3162016-08-18 14:37:00.026884 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 4548, next_record = "
3172016-08-18 14:37:00.026890 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 4265, next_record = "
3182016-08-18 14:37:00.026897 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 4067, next_record = "
3192016-08-18 14:37:00.026903 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8276, next_record = "
3202016-08-18 14:37:00.026920 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 6067, next_record = "
3212016-08-18 14:37:00.026926 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 6532, next_record = "
3222016-08-18 14:37:00.026933 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 6561, next_record = "
3232016-08-18 14:37:00.026938 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 4714, next_record = "
3242016-08-18 14:37:00.026945 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 6605, next_record = "
3252016-08-18 14:37:00.026952 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 6577, next_record = "
3262016-08-18 14:37:00.026958 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 4956, next_record = "
3272016-08-18 14:37:00.026974 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 4699, next_record = "
3282016-08-18 14:37:00.026979 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 5928, next_record = "
3292016-08-18 14:37:00.026985 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 4929, next_record = "
3302016-08-18 14:37:00.027001 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 4896, next_record = "
3312016-08-18 14:37:00.027008 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 4876, next_record = "
3322016-08-18 14:37:00.027015 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 4877, next_record = "
3332016-08-18 14:37:00.027021 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8213, next_record = "
3342016-08-18 14:37:00.027027 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 4924, next_record = "
3352016-08-18 14:37:00.027032 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8246, next_record = "
3362016-08-18 14:37:00.027038 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 5054, next_record = "
3372016-08-18 14:37:00.027044 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 5084, next_record = "
3382016-08-18 14:37:00.027050 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 5916, next_record = "
3392016-08-18 14:37:00.027057 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8244, next_record = "
3402016-08-18 14:37:00.027063 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 5979, next_record = "
3412016-08-18 14:37:00.027069 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 5890, next_record = "
3422016-08-18 14:37:00.027074 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 5113, next_record = "
3432016-08-18 14:37:00.027080 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 5437, next_record = "
3442016-08-18 14:37:00.027087 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 5850, next_record = "
3452016-08-18 14:37:00.027093 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 5165, next_record = "
3462016-08-18 14:37:00.027099 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7643, next_record = "
3472016-08-18 14:37:00.027105 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7653, next_record = "
3482016-08-18 14:37:00.027111 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7224, next_record = "
3492016-08-18 14:37:00.027116 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 6838, next_record = "
3502016-08-18 14:37:00.027123 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 5830, next_record = "
3512016-08-18 14:37:00.027129 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 5653, next_record = "
3522016-08-18 14:37:00.027134 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7050, next_record = "
3532016-08-18 14:37:00.027139 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8037, next_record = "
3542016-08-18 14:37:00.027145 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 6068, next_record = "
3552016-08-18 14:37:00.027151 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 5597, next_record = "
3562016-08-18 14:37:00.027156 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 5954, next_record = "
3572016-08-18 14:37:00.027161 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 5496, next_record = "
3582016-08-18 14:37:00.027166 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7932, next_record = "
3592016-08-18 14:37:00.027171 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 5750, next_record = "
3602016-08-18 14:37:00.027177 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 5667, next_record = "
3612016-08-18 14:37:00.027182 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8265, next_record = "
3622016-08-18 14:37:00.027188 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8380, next_record = "
3632016-08-18 14:37:00.027193 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7523, next_record = "
3642016-08-18 14:37:00.027198 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 6724, next_record = "
3652016-08-18 14:37:00.027203 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8092, next_record = "
3662016-08-18 14:37:00.027226 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8423, next_record = 2016-08-24T06:05:00"
3672016-08-18 14:37:00.027239 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8424, next_record = 2016-08-22T11:55:00"
3682016-08-18 14:37:00.027245 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8474, next_record = "
3692016-08-18 14:37:00.027261 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8483, next_record = "
3702016-08-18 14:37:00.027266 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7316, next_record = "
3712016-08-18 14:37:00.027271 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7560, next_record = "
3722016-08-18 14:37:00.027277 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 6462, next_record = "
3732016-08-18 14:37:00.027282 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8206, next_record = "
3742016-08-18 14:37:00.027289 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 6700, next_record = "
3752016-08-18 14:37:00.027294 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 6619, next_record = "
3762016-08-18 14:37:00.027305 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 6585, next_record = 2016-08-24T18:55:00"
3772016-08-18 14:37:00.027310 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 6509, next_record = "
3782016-08-18 14:37:00.027316 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 6638, next_record = "
3792016-08-18 14:37:00.027321 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 6530, next_record = "
3802016-08-18 14:37:00.027327 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 6799, next_record = "
3812016-08-18 14:37:00.027332 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8456, next_record = "
3822016-08-18 14:37:00.027337 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8443, next_record = "
3832016-08-18 14:37:00.027343 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 6959, next_record = "
3842016-08-18 14:37:00.027348 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 6715, next_record = "
3852016-08-18 14:37:00.027354 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 6961, next_record = "
3862016-08-18 14:37:00.027359 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7624, next_record = "
3872016-08-18 14:37:00.027365 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7155, next_record = "
3882016-08-18 14:37:00.027369 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7343, next_record = "
3892016-08-18 14:37:00.027375 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7578, next_record = "
3902016-08-18 14:37:00.027381 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7405, next_record = "
3912016-08-18 14:37:00.027386 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7988, next_record = "
3922016-08-18 14:37:00.027391 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7262, next_record = "
3932016-08-18 14:37:00.027395 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7640, next_record = "
3942016-08-18 14:37:00.027400 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7382, next_record = "
3952016-08-18 14:37:00.027405 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7170, next_record = "
3962016-08-18 14:37:00.027411 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7292, next_record = "
3972016-08-18 14:37:00.027416 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7520, next_record = "
3982016-08-18 14:37:00.027421 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8364, next_record = "
3992016-08-18 14:37:00.027426 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8311, next_record = "
4002016-08-18 14:37:00.027432 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8269, next_record = "
4012016-08-18 14:37:00.027437 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8073, next_record = "
4022016-08-18 14:37:00.027449 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7379, next_record = 2016-08-22T19:55:00"
4032016-08-18 14:37:00.027454 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7563, next_record = "
4042016-08-18 14:37:00.027459 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8278, next_record = "
4052016-08-18 14:37:00.027464 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8270, next_record = "
4062016-08-18 14:37:00.027474 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8252, next_record = 2016-08-22T18:55:00"
4072016-08-18 14:37:00.027480 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7310, next_record = "
4082016-08-18 14:37:00.027492 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7312, next_record = 2016-08-21T16:25:00"
4092016-08-18 14:37:00.027497 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8191, next_record = "
4102016-08-18 14:37:00.027502 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8146, next_record = "
4112016-08-18 14:37:00.027508 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7193, next_record = "
4122016-08-18 14:37:00.027513 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7642, next_record = "
4132016-08-18 14:37:00.027518 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7558, next_record = "
4142016-08-18 14:37:00.027523 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7594, next_record = "
4152016-08-18 14:37:00.027528 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7666, next_record = "
4162016-08-18 14:37:00.027533 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7892, next_record = "
4172016-08-18 14:37:00.027538 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8176, next_record = "
4182016-08-18 14:37:00.027544 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7700, next_record = "
4192016-08-18 14:37:00.027550 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7989, next_record = "
4202016-08-18 14:37:00.027555 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8295, next_record = "
4212016-08-18 14:37:00.027560 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8399, next_record = "
4222016-08-18 14:37:00.027566 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8285, next_record = "
4232016-08-18 14:37:00.027571 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7788, next_record = "
4242016-08-18 14:37:00.027577 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7697, next_record = "
4252016-08-18 14:37:00.027582 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7750, next_record = "
4262016-08-18 14:37:00.027587 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8600, next_record = "
4272016-08-18 14:37:00.027593 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8435, next_record = "
4282016-08-18 14:37:00.027599 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7758, next_record = "
4292016-08-18 14:37:00.027604 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7743, next_record = "
4302016-08-18 14:37:00.027618 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7960, next_record = "
4312016-08-18 14:37:00.027623 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7729, next_record = "
4322016-08-18 14:37:00.027638 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8009, next_record = "
4332016-08-18 14:37:00.027644 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8112, next_record = "
4342016-08-18 14:37:00.027649 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8228, next_record = "
4352016-08-18 14:37:00.027664 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8249, next_record = "
4362016-08-18 14:37:00.027669 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8128, next_record = "
4372016-08-18 14:37:00.027676 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8306, next_record = "
4382016-08-18 14:37:00.027704 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7940, next_record = 2016-08-26T18:55:00"
4392016-08-18 14:37:00.027720 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7787, next_record = "
4402016-08-18 14:37:00.027736 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7926, next_record = "
4412016-08-18 14:37:00.027742 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7920, next_record = "
4422016-08-18 14:37:00.027748 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 7962, next_record = "
4432016-08-18 14:37:00.027753 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8426, next_record = "
4442016-08-18 14:37:00.027758 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8242, next_record = "
4452016-08-18 14:37:00.027764 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8291, next_record = "
4462016-08-18 14:37:00.027770 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8284, next_record = "
4472016-08-18 14:37:00.027775 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8384, next_record = "
4482016-08-18 14:37:00.027781 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8358, next_record = "
4492016-08-18 14:37:00.027787 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8323, next_record = "
4502016-08-18 14:37:00.027792 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8419, next_record = "
4512016-08-18 14:37:00.027797 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8404, next_record = "
4522016-08-18 14:37:00.027803 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8393, next_record = "
4532016-08-18 14:37:00.027809 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8360, next_record = "
4542016-08-18 14:37:00.027815 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8420, next_record = "
4552016-08-18 14:37:00.027828 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8437, next_record = 2016-08-23T23:25:00"
4562016-08-18 14:37:00.027834 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8349, next_record = "
4572016-08-18 14:37:00.027841 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8351, next_record = "
4582016-08-18 14:37:00.027846 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8704, next_record = "
4592016-08-18 14:37:00.027852 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8705, next_record = "
4602016-08-18 14:37:00.027858 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8476, next_record = "
4612016-08-18 14:37:00.027864 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8471, next_record = "
4622016-08-18 14:37:00.027870 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8469, next_record = "
4632016-08-18 14:37:00.027883 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8681, next_record = 2016-08-21T18:55:00"
4642016-08-18 14:37:00.027890 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8532, next_record = "
4652016-08-18 14:37:00.027896 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8503, next_record = "
4662016-08-18 14:37:00.027902 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8615, next_record = "
4672016-08-18 14:37:00.027915 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8706, next_record = 2016-08-18T13:37:00"
4682016-08-18 14:37:00.028140 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(SchedCon) UPDATE record SET next_record = NULL WHERE recordid = '8706'; <<<< Took 0ms
4692016-08-18 14:37:00.028166 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8573, next_record = 2016-08-26T07:50:00"
4702016-08-18 14:37:00.028174 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8559, next_record = "
4712016-08-18 14:37:00.028181 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8647, next_record = "
4722016-08-18 14:37:00.028193 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8648, next_record = 2016-08-25T23:45:00"
4732016-08-18 14:37:00.028212 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8649, next_record = "
4742016-08-18 14:37:00.028228 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8650, next_record = "
4752016-08-18 14:37:00.028240 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8689, next_record = 2016-08-18T18:55:00"
4762016-08-18 14:37:00.028245 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8703, next_record = "
4772016-08-18 14:37:00.028250 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(SchedCon) Result: "recordid = 8673, next_record = "
4782016-08-18 14:37:00.028351 I [7322/7386] Scheduler mythcorecontext.cpp:1592 (dispatch) - MythCoreContext::dispatch(): MythEvent: SCHEDULE_CHANGE
4792016-08-18 14:37:00.028458 I [7322/7322] CoreContext referencecounter.cpp:114 (IncrRef) - (0x7f7960012230)::IncrRef() -> 2
4802016-08-18 14:37:00.028464 I [7322/7322] CoreContext referencecounter.cpp:114 (IncrRef) - (0x7f79600116f0)::IncrRef() -> 2
4812016-08-18 14:37:00.028524 I [7322/7455] MythSocketThread(85) mythsocket.cpp:789 (WriteStringListReal) - MythSocket(55c8b463e4c0:85): write -> 85 45      BACKEND_MESSAGE[]:[]SCHEDULE_CHANGE[]:[]empty
4822016-08-18 14:37:00.028578 I [7322/7322] CoreContext referencecounter.cpp:145 (DecrRef) - (0x7f7960012230)::DecrRef() -> 1
4832016-08-18 14:37:00.028584 I [7322/7322] CoreContext referencecounter.cpp:145 (DecrRef) - (0x7f79600116f0)::DecrRef() -> 1
4842016-08-18 14:37:00.028747 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT chanid FROM channel WHERE channum  = '447' AND       sourceid = '2' <<<< Took 0ms, Returned 1 row(s)
4852016-08-18 14:37:00.028757 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "chanid = 56378"
4862016-08-18 14:37:00.028925 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT inputname, sourceid, livetvorder, schedorder, displayname, recpriority, quicktune FROM capturecard WHERE cardid = '7' <<<< Took 0ms, Returned 1 row(s)
4872016-08-18 14:37:00.028940 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "inputname = Composite, sourceid = 2, livetvorder = 7, schedorder = 7, displayname = Sky, recpriority = 0, quicktune =
4882016-08-18 14:37:00.029098 I [7322/7386] Scheduler mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager9) SELECT mplexid FROM channel WHERE chanid = '56378' <<<< Took 0ms, Returned 1 row(s)
4892016-08-18 14:37:00.029104 D [7322/7386] Scheduler mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager9) Result: "mplexid = 0"
4902016-08-18 14:37:00.029112 N [7322/7386] Scheduler scheduler.cpp:3005 (HandleIdleShutdown) - Blocking shutdown because of a connected client
4912016-08-18 14:37:00.029118 I [7322/7386] Scheduler scheduler.cpp:2041 (run) - sleeping for 15000 ms (s2n: 8279 sr: 8 qr: 0 cs: 0)
4922016-08-18 14:37:00.074734 D [7322/7907] SignalMonitor recorders/channelbase.cpp:547 (GetScriptStatus) - ChannelBase[7]: GetScriptStatus() 142
4932016-08-18 14:37:00.074747 D [7322/7907] SignalMonitor recorders/channelbase.cpp:565 (GetScriptStatus) - ChannelBase[7]: GetScriptStatus() 142 -> 1
4942016-08-18 14:37:00.084646 I [7322/7341] SystemManager mythsystemunix.cpp:354 (run) - Managed child (PID: 7905) has exited! command=/harvs/bin/mytheventhandler REC_STARTED "447 (Motors TV)" 7 Andy Default 56378_20160818133700.nuv -1, status=0, result=0
4952016-08-18 14:37:00.089264 I [7322/7342] SystemSignalManager referencecounter.cpp:145 (DecrRef) - (0x7f796000ebf0)::DecrRef() -> 1
4962016-08-18 14:37:00.089284 I [7322/7904] SystemEvent referencecounter.cpp:145 (DecrRef) - (0x7f796000ebf0)::DecrRef() -> 0
4972016-08-18 14:37:00.089302 I [7322/7904] SystemEvent mythcorecontext.cpp:1592 (dispatch) - MythCoreContext::dispatch(): MythEvent: SYSTEM_EVENT_RESULT REC_STARTED SENDER mythtv.harvs.net RESULT 0
4982016-08-18 14:37:00.089363 I [7322/7322] CoreContext referencecounter.cpp:114 (IncrRef) - (0x7f7960012230)::IncrRef() -> 2
4992016-08-18 14:37:00.089368 I [7322/7322] CoreContext referencecounter.cpp:114 (IncrRef) - (0x7f79600116f0)::IncrRef() -> 2
5002016-08-18 14:37:00.089416 I [7322/7455] MythSocketThread(85) mythsocket.cpp:789 (WriteStringListReal) - MythSocket(55c8b463e4c0:85): write -> 85 94      BACKEND_MESSAGE[]:[]SYSTEM_EVENT_RESULT REC_STARTED SENDER mythtv.harvs.net RESULT 0[]:[]empty
5012016-08-18 14:37:00.089452 I [7322/7322] CoreContext referencecounter.cpp:145 (DecrRef) - (0x7f7960012230)::DecrRef() -> 1
5022016-08-18 14:37:00.089456 I [7322/7322] CoreContext referencecounter.cpp:145 (DecrRef) - (0x7f79600116f0)::DecrRef() -> 1
5032016-08-18 14:37:00.124821 D [7322/7907] SignalMonitor recorders/channelbase.cpp:547 (GetScriptStatus) - ChannelBase[7]: GetScriptStatus() 142
5042016-08-18 14:37:00.124828 D [7322/7907] SignalMonitor recorders/channelbase.cpp:565 (GetScriptStatus) - ChannelBase[7]: GetScriptStatus() 142 -> 1
5052016-08-18 14:37:00.144818 I [7322/7341] SystemManager mythsystemunix.cpp:354 (run) - Managed child (PID: 7906) has exited! command=/harvs/bin/skychan 447, status=0, result=0
5062016-08-18 14:37:00.174926 I [7322/7907] SignalMonitor referencecounter.cpp:145 (DecrRef) - (0x7f7a14026320)::DecrRef() -> 1
5072016-08-18 14:37:00.174940 D [7322/7907] SignalMonitor recorders/channelbase.cpp:547 (GetScriptStatus) - ChannelBase[7]: GetScriptStatus() 0
5082016-08-18 14:37:00.174944 D [7322/7907] SignalMonitor recorders/channelbase.cpp:565 (GetScriptStatus) - ChannelBase[7]: GetScriptStatus() 0 -> 3
5092016-08-18 14:37:00.175008 I [7322/7346] TVRecEvent tv_rec.cpp:3928 (TuningSignalCheck) - TVRec[7]: TuningSignalCheck: Good signal
5102016-08-18 14:37:00.175018 I [7322/7346] TVRecEvent tv_rec.cpp:715 (SetRecordingStatus) - TVRec[7]: SetRecordingStatus(Tuning->Recording) on line 4023
5112016-08-18 14:37:00.175036 I [7322/7346] TVRecEvent mythcorecontext.cpp:1592 (dispatch) - MythCoreContext::dispatch(): MythEvent: UPDATE_RECORDING_STATUS 7 56378 2016-08-18T13:38:00Z -2 2016-08-18T14:00:00Z
5122016-08-18 14:37:00.175047 I [7322/7346] TVRecEvent tv_rec.cpp:2077 (TeardownSignalMonitor) - TVRec[7]: TeardownSignalMonitor() -- begin
5132016-08-18 14:37:00.175051 D [7322/7346] TVRecEvent recorders/signalmonitor.cpp:289 (Stop) - SigMon[7](/dev/video0)::Stop: begin
5142016-08-18 14:37:00.175118 I [7322/7322] CoreContext scheduler.cpp:726 (UpdateRecStatus) - Updating status for "447 (Motors TV)":"2016-08-18 14:38:00" on cardid 7 (Tuning => Recording)
5152016-08-18 14:37:00.175133 I [7322/7322] CoreContext recordinginfo.cpp:1236 (AddHistory) - AddHistory: -2/-10, 0, 0, 2016-08-18T13:38:00Z/447 (Motors TV)
5162016-08-18 14:37:00.175719 I [7322/7322] CoreContext mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager1) REPLACE INTO oldrecorded (chanid,starttime,endtime,title,subtitle,description,season,episode,category,seriesid,programid,inetref,findid,recordid,station,rectype,recstatus,duplicate,reactivate,future) VALUES('56378','2016-08-18T13:38:00Z','2016-08-18T13:50:00Z','447 (Motors TV)','2016-08-18 14:38:00','','0','0','','','','','0','8706','Motors TV','1','-2','false','0','false'); <<<< Took 0ms
5172016-08-18 14:37:00.175744 I [7322/7322] CoreContext mythcorecontext.cpp:1592 (dispatch) - MythCoreContext::dispatch(): MythEvent: SCHEDULE_CHANGE
5182016-08-18 14:37:00.175772 I [7322/7322] CoreContext referencecounter.cpp:114 (IncrRef) - (0x7f7960012230)::IncrRef() -> 2
5192016-08-18 14:37:00.175774 I [7322/7322] CoreContext referencecounter.cpp:114 (IncrRef) - (0x7f79600116f0)::IncrRef() -> 2
5202016-08-18 14:37:00.175840 I [7322/7455] MythSocketThread(85) mythsocket.cpp:789 (WriteStringListReal) - MythSocket(55c8b463e4c0:85): write -> 85 45      BACKEND_MESSAGE[]:[]SCHEDULE_CHANGE[]:[]empty
5212016-08-18 14:37:00.175887 I [7322/7322] CoreContext referencecounter.cpp:145 (DecrRef) - (0x7f7960012230)::DecrRef() -> 1
5222016-08-18 14:37:00.175891 I [7322/7322] CoreContext referencecounter.cpp:145 (DecrRef) - (0x7f79600116f0)::DecrRef() -> 1
5232016-08-18 14:37:00.189411 I [7322/7342] SystemSignalManager referencecounter.cpp:145 (DecrRef) - (0x7f7a14026320)::DecrRef() -> 0
5242016-08-18 14:37:00.203506 I [7322/7903] ProgramInfoUpdater mythcorecontext.cpp:1592 (dispatch) - MythCoreContext::dispatch(): MythEvent: RECORDING_LIST_CHANGE ADD 736
5252016-08-18 14:37:00.203515 I [7322/7903] ProgramInfoUpdater mythcorecontext.cpp:1592 (dispatch) - MythCoreContext::dispatch(): MythEvent: MASTER_UPDATE_REC_INFO 0
5262016-08-18 14:37:00.203548 I [7322/7322] CoreContext referencecounter.cpp:114 (IncrRef) - (0x7f7960012230)::IncrRef() -> 2
5272016-08-18 14:37:00.203553 I [7322/7322] CoreContext referencecounter.cpp:114 (IncrRef) - (0x7f79600116f0)::IncrRef() -> 2
5282016-08-18 14:37:00.203614 I [7322/7455] MythSocketThread(85) mythsocket.cpp:789 (WriteStringListReal) - MythSocket(55c8b463e4c0:85): write -> 85 59      BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE ADD 736[]:[]empty
5292016-08-18 14:37:00.203662 I [7322/7322] CoreContext referencecounter.cpp:145 (DecrRef) - (0x7f7960012230)::DecrRef() -> 1
5302016-08-18 14:37:00.203665 I [7322/7322] CoreContext referencecounter.cpp:145 (DecrRef) - (0x7f79600116f0)::DecrRef() -> 1
5312016-08-18 14:37:00.204010 I [7322/7322] CoreContext mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager1) SELECT chanid, starttime FROM recorded WHERE recordedid = '0' <<<< Took 0ms, Returned 0 row(s)
5322016-08-18 14:37:00.204019 C [7322/7322] CoreContext programinfo.cpp:351 (ProgramInfo) - ProgramInfo(): Failed to find recorded entry for 0.
5332016-08-18 14:37:00.213977 D [7322/7451] MythSocketThread(81) mythsocket.cpp:291 (CallReadyReadHandler) - MythSocket(55c8b4614690:81): calling m_callback->readyRead()
5342016-08-18 14:37:00.213992 I [7322/7451] MythSocketThread(81) referencecounter.cpp:114 (IncrRef) - (0x55c8b46146a0)::IncrRef() -> 2
5352016-08-18 14:37:00.214044 I [7322/7557] ProcessRequest referencecounter.cpp:114 (IncrRef) - (0x7f7960012230)::IncrRef() -> 2
5362016-08-18 14:37:00.214047 I [7322/7557] ProcessRequest referencecounter.cpp:114 (IncrRef) - (0x55c8b46146a0)::IncrRef() -> 3
5372016-08-18 14:37:00.214079 I [7322/7451] MythSocketThread(81) mythsocket.cpp:990 (ReadStringListReal) - MythSocket(55c8b4614690:81): read  <- 81 21      QUERY_FREE_SPACE_LIST
5382016-08-18 14:37:00.214100 I [7322/7557] ProcessRequest referencecounter.cpp:145 (DecrRef) - (0x55c8b46146a0)::DecrRef() -> 2
5392016-08-18 14:37:00.214103 I [7322/7557] ProcessRequest referencecounter.cpp:145 (DecrRef) - (0x7f7960012230)::DecrRef() -> 1
5402016-08-18 14:37:00.214107 I [7322/7557] ProcessRequest referencecounter.cpp:114 (IncrRef) - (0x7f7960012230)::IncrRef() -> 2
5412016-08-18 14:37:00.214125 I [7322/7451] MythSocketThread(81) mythsocket.cpp:789 (WriteStringListReal) - MythSocket(55c8b4614690:81): write -> 81 215     mythtv.harvs.net[]:[]mythtv:/video/LiveTV,mythtv:/video/Recordings[]:[]1[]:[]1[]:[]2[]:[]4096[]:[]919164412[]:[]731168088[]:[]mythtv.harvs.net[]:[]TotalDiskSpace[]:[]0[]:[]-2[]:[]-2[]:[]0[]:[]919164412[]:[]731168088
5422016-08-18 14:37:00.214158 I [7322/7557] ProcessRequest referencecounter.cpp:145 (DecrRef) - (0x7f7960012230)::DecrRef() -> 1
5432016-08-18 14:37:00.214161 I [7322/7557] ProcessRequest referencecounter.cpp:145 (DecrRef) - (0x55c8b46146a0)::DecrRef() -> 1
5442016-08-18 14:37:00.225046 D [7322/7346] TVRecEvent recorders/signalmonitor.cpp:299 (Stop) - SigMon[7](/dev/video0)::Stop: end
5452016-08-18 14:37:00.225057 I [7322/7346] TVRecEvent tv_rec.cpp:2096 (TeardownSignalMonitor) - TVRec[7]: TeardownSignalMonitor() -- end
5462016-08-18 14:37:00.225063 I [7322/7346] TVRecEvent tv_rec.cpp:4413 (ClearFlags) - TVRec[7]: ClearFlags(SignalMonitorRunning,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,RingBufferReady, @ tv_rec.cpp:4049
5472016-08-18 14:37:00.225077 I [7322/7346] TVRecEvent tv_rec.cpp:4413 (ClearFlags) - TVRec[7]: ClearFlags(WaitingForSignal,) -> RunMainLoop,NeedToStartRecorder,RingBufferReady, @ tv_rec.cpp:4051
5482016-08-18 14:37:00.225086 I [7322/7346] TVRecEvent tv_rec.cpp:4176 (TuningNewRecorder) - TVRec[7]: Starting Recorder
5492016-08-18 14:37:00.225514 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT recordingprofiles.id, profilegroups.hostname,        profilegroups.is_default FROM recordingprofiles, profilegroups WHERE profilegroups.id       = recordingprofiles.profilegroup AND       profilegroups.cardtype = 'V4L'                      AND       recordingprofiles.name = 'Default' <<<< Took 0ms, Returned 1 row(s)
5502016-08-18 14:37:00.225526 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "id = 1, hostname = , is_default = 1"
5512016-08-18 14:37:00.225819 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT name  FROM recordingprofiles WHERE id = '1' <<<< Took 0ms, Returned 1 row(s)
5522016-08-18 14:37:00.225836 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "name = Default"
5532016-08-18 14:37:00.225944 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'transcodefilters' <<<< Took 0ms, Returned 0 row(s)
5542016-08-18 14:37:00.226029 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'transcodelossless' <<<< Took 0ms, Returned 0 row(s)
5552016-08-18 14:37:00.226110 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'transcoderesize' <<<< Took 0ms, Returned 0 row(s)
5562016-08-18 14:37:00.226195 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'autotranscode' <<<< Took 0ms, Returned 1 row(s)
5572016-08-18 14:37:00.226200 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 0"
5582016-08-18 14:37:00.226287 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'width' <<<< Took 0ms, Returned 1 row(s)
5592016-08-18 14:37:00.226292 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 720"
5602016-08-18 14:37:00.226377 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'height' <<<< Took 0ms, Returned 1 row(s)
5612016-08-18 14:37:00.226382 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 576"
5622016-08-18 14:37:00.226459 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT videocodec  FROM recordingprofiles WHERE id = '1' <<<< Took 0ms, Returned 1 row(s)
5632016-08-18 14:37:00.226464 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "videocodec = MPEG-4"
5642016-08-18 14:37:00.226554 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'rtjpegquality' <<<< Took 0ms, Returned 1 row(s)
5652016-08-18 14:37:00.226559 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 170"
5662016-08-18 14:37:00.226640 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'rtjpeglumafilter' <<<< Took 0ms, Returned 0 row(s)
5672016-08-18 14:37:00.226806 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'rtjpegchromafilter' <<<< Took 0ms, Returned 0 row(s)
5682016-08-18 14:37:00.226904 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg4bitrate' <<<< Took 0ms, Returned 1 row(s)
5692016-08-18 14:37:00.226909 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 2200"
5702016-08-18 14:37:00.226997 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg4maxquality' <<<< Took 0ms, Returned 1 row(s)
5712016-08-18 14:37:00.227001 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 2"
5722016-08-18 14:37:00.227088 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg4minquality' <<<< Took 0ms, Returned 1 row(s)
5732016-08-18 14:37:00.227093 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 15"
5742016-08-18 14:37:00.227179 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg4qualdiff' <<<< Took 0ms, Returned 1 row(s)
5752016-08-18 14:37:00.227183 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 3"
5762016-08-18 14:37:00.227268 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'scalebitrate' <<<< Took 0ms, Returned 1 row(s)
5772016-08-18 14:37:00.227273 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 1"
5782016-08-18 14:37:00.227360 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg4optionvhq' <<<< Took 0ms, Returned 1 row(s)
5792016-08-18 14:37:00.227364 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 1"
5802016-08-18 14:37:00.227449 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg4option4mv' <<<< Took 0ms, Returned 1 row(s)
5812016-08-18 14:37:00.227453 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 1"
5822016-08-18 14:37:00.227538 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg4optionidct' <<<< Took 0ms, Returned 1 row(s)
5832016-08-18 14:37:00.227543 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 0"
5842016-08-18 14:37:00.227630 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg4optionime' <<<< Took 0ms, Returned 1 row(s)
5852016-08-18 14:37:00.227635 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 0"
5862016-08-18 14:37:00.227722 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'encodingthreadcount' <<<< Took 0ms, Returned 1 row(s)
5872016-08-18 14:37:00.227726 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 2"
5882016-08-18 14:37:00.227815 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg2bitrate' <<<< Took 0ms, Returned 1 row(s)
5892016-08-18 14:37:00.227819 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 4500"
5902016-08-18 14:37:00.227905 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'scalebitrate' <<<< Took 0ms, Returned 1 row(s)
5912016-08-18 14:37:00.227910 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 1"
5922016-08-18 14:37:00.227995 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'encodingthreadcount' <<<< Took 0ms, Returned 1 row(s)
5932016-08-18 14:37:00.227999 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 2"
5942016-08-18 14:37:00.228088 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'hardwaremjpegquality' <<<< Took 0ms, Returned 1 row(s)
5952016-08-18 14:37:00.228092 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 100"
5962016-08-18 14:37:00.228179 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'hardwaremjpeghdecimation' <<<< Took 0ms, Returned 1 row(s)
5972016-08-18 14:37:00.228183 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 4"
5982016-08-18 14:37:00.228268 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'hardwaremjpegvdecimation' <<<< Took 0ms, Returned 1 row(s)
5992016-08-18 14:37:00.228273 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 4"
6002016-08-18 14:37:00.228359 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg2streamtype' <<<< Took 0ms, Returned 1 row(s)
6012016-08-18 14:37:00.228363 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = MPEG-2 PS"
6022016-08-18 14:37:00.228449 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg2aspectratio' <<<< Took 0ms, Returned 1 row(s)
6032016-08-18 14:37:00.228453 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 4:3"
6042016-08-18 14:37:00.228539 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg2bitrate' <<<< Took 0ms, Returned 1 row(s)
6052016-08-18 14:37:00.228543 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 4500"
6062016-08-18 14:37:00.228629 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg2maxbitrate' <<<< Took 0ms, Returned 1 row(s)
6072016-08-18 14:37:00.228633 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 6000"
6082016-08-18 14:37:00.228722 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'low_mpeg4avgbitrate' <<<< Took 0ms, Returned 1 row(s)
6092016-08-18 14:37:00.228727 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 4500"
6102016-08-18 14:37:00.228815 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'low_mpeg4peakbitrate' <<<< Took 0ms, Returned 1 row(s)
6112016-08-18 14:37:00.228819 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 6000"
6122016-08-18 14:37:00.228907 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'medium_mpeg4avgbitrate' <<<< Took 0ms, Returned 1 row(s)
6132016-08-18 14:37:00.228911 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 9000"
6142016-08-18 14:37:00.229000 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'medium_mpeg4peakbitrate' <<<< Took 0ms, Returned 1 row(s)
6152016-08-18 14:37:00.229004 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 11000"
6162016-08-18 14:37:00.229089 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'high_mpeg4avgbitrate' <<<< Took 0ms, Returned 1 row(s)
6172016-08-18 14:37:00.229094 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 13500"
6182016-08-18 14:37:00.229181 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'high_mpeg4peakbitrate' <<<< Took 0ms, Returned 1 row(s)
6192016-08-18 14:37:00.229186 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 20200"
6202016-08-18 14:37:00.229265 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT audiocodec  FROM recordingprofiles WHERE id = '1' <<<< Took 0ms, Returned 1 row(s)
6212016-08-18 14:37:00.229269 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "audiocodec = MP3"
6222016-08-18 14:37:00.229360 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'samplerate' <<<< Took 0ms, Returned 1 row(s)
6232016-08-18 14:37:00.229365 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 32000"
6242016-08-18 14:37:00.229456 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mp3quality' <<<< Took 0ms, Returned 1 row(s)
6252016-08-18 14:37:00.229461 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 7"
6262016-08-18 14:37:00.229546 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'volume' <<<< Took 0ms, Returned 1 row(s)
6272016-08-18 14:37:00.229551 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 90"
6282016-08-18 14:37:00.229636 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'samplerate' <<<< Took 0ms, Returned 1 row(s)
6292016-08-18 14:37:00.229641 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 32000"
6302016-08-18 14:37:00.229645 E [7322/7346] TVRecEvent recordingprofile.cpp:162 (addSelection) - SampleRate: Attempted to add a rate 32000 Hz, which is not in the list of allowed rates.
6312016-08-18 14:37:00.229750 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg2audtype' <<<< Took 0ms, Returned 0 row(s)
6322016-08-18 14:37:00.229853 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg2audbitratel1' <<<< Took 0ms, Returned 0 row(s)
6332016-08-18 14:37:00.229960 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg2audbitratel2' <<<< Took 0ms, Returned 0 row(s)
6342016-08-18 14:37:00.230119 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg2audbitratel3' <<<< Took 0ms, Returned 0 row(s)
6352016-08-18 14:37:00.230242 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg2language' <<<< Took 0ms, Returned 0 row(s)
6362016-08-18 14:37:00.230345 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'mpeg2audvolume' <<<< Took 0ms, Returned 0 row(s)
6372016-08-18 14:37:00.230461 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'samplerate' <<<< Took 0ms, Returned 1 row(s)
6382016-08-18 14:37:00.230469 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 32000"
6392016-08-18 14:37:00.230609 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT value  FROM codecparams WHERE profile = '1' AND name = 'volume' <<<< Took 0ms, Returned 1 row(s)
6402016-08-18 14:37:00.230616 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "value = 90"
6412016-08-18 14:37:00.230634 I [7322/7346] TVRecEvent tv_rec.cpp:4146 (LoadProfile) - TVRec[7]: Using profile 'Default' to record
6422016-08-18 14:37:00.230639 I [7322/7346] TVRecEvent tv_rec.cpp:4215 (TuningNewRecorder) - TVRec[7]: rec->GetPathname(): '/video/Recordings/56378_20160818133700.nuv'
6432016-08-18 14:37:00.230721 I [7322/7346] TVRecEvent mythcorecontext.cpp:1592 (dispatch) - MythCoreContext::dispatch(): MythEvent: FILE_WRITTEN /video/Recordings/56378_20160818133700.nuv 0
6442016-08-18 14:37:00.230757 D [7322/7346] TVRecEvent mythcorecontext.cpp:1965 (RegisterFileForWrite) - MythCoreContext::RegisterFileForWrite(): /video/Recordings/56378_20160818133700.nuv
6452016-08-18 14:37:00.230760 I [7322/7346] TVRecEvent threadedfilewriter.cpp:136 (Open) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Open() successful
6462016-08-18 14:37:00.230799 I [7322/7322] CoreContext referencecounter.cpp:114 (IncrRef) - (0x7f7960012230)::IncrRef() -> 2
6472016-08-18 14:37:00.230812 I [7322/7322] CoreContext referencecounter.cpp:114 (IncrRef) - (0x7f79600116f0)::IncrRef() -> 2
6482016-08-18 14:37:00.230825 I [7322/7346] TVRecEvent tv_rec.cpp:4247 (TuningNewRecorder) - TVRec[7]: TuningNewRecorder - CreateRecorder()
6492016-08-18 14:37:00.230855 I [7322/7455] MythSocketThread(85) mythsocket.cpp:789 (WriteStringListReal) - MythSocket(55c8b463e4c0:85): write -> 85 87      BACKEND_MESSAGE[]:[]FILE_WRITTEN /video/Recordings/56378_20160818133700.nuv 0[]:[]empty
6502016-08-18 14:37:00.230861 I [7322/7346] TVRecEvent recorders/dtvrecorder.cpp:181 (ResetForNewFile) - DTVRec[7]: ResetForNewFile(void)
6512016-08-18 14:37:00.230956 I [7322/7322] CoreContext referencecounter.cpp:145 (DecrRef) - (0x7f7960012230)::DecrRef() -> 1
6522016-08-18 14:37:00.230971 I [7322/7322] CoreContext referencecounter.cpp:145 (DecrRef) - (0x7f79600116f0)::DecrRef() -> 1
6532016-08-18 14:37:00.231062 I [7322/7346] TVRecEvent filtermanager.cpp:84 (FilterManager) - FilterManager: Loading filter '/usr/lib64/mythtv/filters/libadjust.so'
6542016-08-18 14:37:00.231397 D [7322/7346] TVRecEvent filtermanager.cpp:179 (LoadFilterLib) - FilterManager: filters[adjust] = 0x7f7a141a1590
6552016-08-18 14:37:00.231402 I [7322/7346] TVRecEvent filtermanager.cpp:84 (FilterManager) - FilterManager: Loading filter '/usr/lib64/mythtv/filters/libbobdeint.so'
6562016-08-18 14:37:00.231518 D [7322/7346] TVRecEvent filtermanager.cpp:179 (LoadFilterLib) - FilterManager: filters[bobdeint] = 0x7f7a141a1d90
6572016-08-18 14:37:00.231521 I [7322/7346] TVRecEvent filtermanager.cpp:84 (FilterManager) - FilterManager: Loading filter '/usr/lib64/mythtv/filters/libcrop.so'
6582016-08-18 14:37:00.231676 D [7322/7346] TVRecEvent filtermanager.cpp:179 (LoadFilterLib) - FilterManager: filters[crop] = 0x7f7a141a61a0
6592016-08-18 14:37:00.231684 I [7322/7346] TVRecEvent filtermanager.cpp:84 (FilterManager) - FilterManager: Loading filter '/usr/lib64/mythtv/filters/libdenoise3d.so'
6602016-08-18 14:37:00.231851 D [7322/7346] TVRecEvent filtermanager.cpp:179 (LoadFilterLib) - FilterManager: filters[denoise3d] = 0x7f7a141a6fa0
6612016-08-18 14:37:00.231853 I [7322/7346] TVRecEvent filtermanager.cpp:84 (FilterManager) - FilterManager: Loading filter '/usr/lib64/mythtv/filters/libfieldorder.so'
6622016-08-18 14:37:00.232003 D [7322/7346] TVRecEvent filtermanager.cpp:179 (LoadFilterLib) - FilterManager: filters[fieldorderdoubleprocessdeint] = 0x7f7a141a7e70
6632016-08-18 14:37:00.232006 I [7322/7346] TVRecEvent filtermanager.cpp:84 (FilterManager) - FilterManager: Loading filter '/usr/lib64/mythtv/filters/libforce.so'
6642016-08-18 14:37:00.232070 D [7322/7346] TVRecEvent filtermanager.cpp:179 (LoadFilterLib) - FilterManager: filters[forceyv12] = 0x7f7a141aaf40
6652016-08-18 14:37:00.232074 D [7322/7346] TVRecEvent filtermanager.cpp:179 (LoadFilterLib) - FilterManager: filters[forceyuv422p] = 0x7f7a141ac8b0
6662016-08-18 14:37:00.232077 D [7322/7346] TVRecEvent filtermanager.cpp:179 (LoadFilterLib) - FilterManager: filters[forcergb24] = 0x7f7a141ac750
6672016-08-18 14:37:00.232081 D [7322/7346] TVRecEvent filtermanager.cpp:179 (LoadFilterLib) - FilterManager: filters[forceargb32] = 0x7f7a141addf0
6682016-08-18 14:37:00.232083 I [7322/7346] TVRecEvent filtermanager.cpp:84 (FilterManager) - FilterManager: Loading filter '/usr/lib64/mythtv/filters/libgreedyhdeint.so'
6692016-08-18 14:37:00.232261 D [7322/7346] TVRecEvent filtermanager.cpp:179 (LoadFilterLib) - FilterManager: filters[greedyhdeint] = 0x7f7a141ac7a0
6702016-08-18 14:37:00.232265 D [7322/7346] TVRecEvent filtermanager.cpp:179 (LoadFilterLib) - FilterManager: filters[greedyhdoubleprocessdeint] = 0x7f7a141b0340
6712016-08-18 14:37:00.232267 I [7322/7346] TVRecEvent filtermanager.cpp:84 (FilterManager) - FilterManager: Loading filter '/usr/lib64/mythtv/filters/libinvert.so'
6722016-08-18 14:37:00.232342 D [7322/7346] TVRecEvent filtermanager.cpp:179 (LoadFilterLib) - FilterManager: filters[invert] = 0x7f7a141b0430
6732016-08-18 14:37:00.232344 I [7322/7346] TVRecEvent filtermanager.cpp:84 (FilterManager) - FilterManager: Loading filter '/usr/lib64/mythtv/filters/libivtc.so'
6742016-08-18 14:37:00.232531 D [7322/7346] TVRecEvent filtermanager.cpp:179 (LoadFilterLib) - FilterManager: filters[ivtc] = 0x7f7a141b28a0
6752016-08-18 14:37:00.232535 I [7322/7346] TVRecEvent filtermanager.cpp:84 (FilterManager) - FilterManager: Loading filter '/usr/lib64/mythtv/filters/libkerneldeint.so'
6762016-08-18 14:37:00.234100 D [7322/7346] TVRecEvent filtermanager.cpp:179 (LoadFilterLib) - FilterManager: filters[kerneldeint] = 0x7f7a141b4210
6772016-08-18 14:37:00.234105 D [7322/7346] TVRecEvent filtermanager.cpp:179 (LoadFilterLib) - FilterManager: filters[kerneldoubleprocessdeint] = 0x7f7a141b6720
6782016-08-18 14:37:00.234109 I [7322/7346] TVRecEvent filtermanager.cpp:84 (FilterManager) - FilterManager: Loading filter '/usr/lib64/mythtv/filters/liblinearblend.so'
6792016-08-18 14:37:00.234279 D [7322/7346] TVRecEvent filtermanager.cpp:179 (LoadFilterLib) - FilterManager: filters[linearblend] = 0x7f7a141b66f0
6802016-08-18 14:37:00.234283 I [7322/7346] TVRecEvent filtermanager.cpp:84 (FilterManager) - FilterManager: Loading filter '/usr/lib64/mythtv/filters/libonefield.so'
6812016-08-18 14:37:00.234384 D [7322/7346] TVRecEvent filtermanager.cpp:179 (LoadFilterLib) - FilterManager: filters[onefield] = 0x7f7a141b8ec0
6822016-08-18 14:37:00.234388 I [7322/7346] TVRecEvent filtermanager.cpp:84 (FilterManager) - FilterManager: Loading filter '/usr/lib64/mythtv/filters/libpostprocess.so'
6832016-08-18 14:37:00.234560 D [7322/7346] TVRecEvent filtermanager.cpp:179 (LoadFilterLib) - FilterManager: filters[postprocess] = 0x7f7a141ba6a0
6842016-08-18 14:37:00.234563 I [7322/7346] TVRecEvent filtermanager.cpp:84 (FilterManager) - FilterManager: Loading filter '/usr/lib64/mythtv/filters/libquickdnr.so'
6852016-08-18 14:37:00.234710 D [7322/7346] TVRecEvent filtermanager.cpp:179 (LoadFilterLib) - FilterManager: filters[quickdnr] = 0x7f7a141bc2a0
6862016-08-18 14:37:00.234713 I [7322/7346] TVRecEvent filtermanager.cpp:84 (FilterManager) - FilterManager: Loading filter '/usr/lib64/mythtv/filters/libvflip.so'
6872016-08-18 14:37:00.234850 D [7322/7346] TVRecEvent filtermanager.cpp:179 (LoadFilterLib) - FilterManager: filters[vflip] = 0x7f7a141bdfb0
6882016-08-18 14:37:00.234852 I [7322/7346] TVRecEvent filtermanager.cpp:84 (FilterManager) - FilterManager: Loading filter '/usr/lib64/mythtv/filters/libyadif.so'
6892016-08-18 14:37:00.235020 D [7322/7346] TVRecEvent filtermanager.cpp:179 (LoadFilterLib) - FilterManager: filters[yadifdeint] = 0x7f7a141c13a0
6902016-08-18 14:37:00.235023 D [7322/7346] TVRecEvent filtermanager.cpp:179 (LoadFilterLib) - FilterManager: filters[yadifdoubleprocessdeint] = 0x7f7a141c1670
6912016-08-18 14:37:00.235111 I [7322/7346] TVRecEvent recorders/recorderbase.cpp:98 (SetRingBuffer) - RecBase[7](/dev/video0): SetRingBuffer(0x7f7a14033870) '/video/Recordings/56378_20160818133700.nuv'
6922016-08-18 14:37:00.235636 D [7322/7346] TVRecEvent recorders/audioinputalsa.cpp:169 (PrepHwParams) - AudioInALSA(hw:CARD=usbtv): min channels 2, max channels 2, myth requests 2
6932016-08-18 14:37:00.235669 I [7322/7346] TVRecEvent recorders/audioinputalsa.cpp:212 (PrepHwParams) - AudioInALSA(hw:CARD=usbtv): channels 2, sample rate 48000, buffer_time 115.2 msec, period size 2765
6942016-08-18 14:37:00.235672 D [7322/7346] TVRecEvent recorders/audioinputalsa.cpp:214 (PrepHwParams) - AudioInALSA(hw:CARD=usbtv): myth block size 11060
6952016-08-18 14:37:00.235676 I [7322/7346] TVRecEvent recorders/audioinputalsa.cpp:62 (Open) - AudioInALSA(hw:CARD=usbtv): pcm open
6962016-08-18 14:37:00.235695 I [7322/7346] TVRecEvent recorders/audioinputalsa.cpp:83 (Stop) - AudioInALSA(hw:CARD=usbtv): capture stopped
6972016-08-18 14:37:00.235716 I [7322/7346] TVRecEvent recorders/NuppelVideoRecorder.cpp:805 (AudioInit) - NVR(/dev/video0): Audio device 11060 buffer size: 11060 bytes
6982016-08-18 14:37:00.237785 I [7322/7346] TVRecEvent filtermanager.cpp:192 (GetFilterInfo) - FilterManager: GetFilterInfo(convert) returning: 0x0
6992016-08-18 14:37:00.237791 I [7322/7346] TVRecEvent recorders/recorderbase.cpp:108 (SetRecording) - RecBase[7](/dev/video0): SetRecording(0x7f79f43fce90) title(447 (Motors TV))
7002016-08-18 14:37:00.238118 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT hostname, storagegroup, id, basename, filesize, video_codec, width, height, aspect, fps, audio_codec, audio_channels, audio_sample_rate, audio_avg_bitrate, container FROM recordedfile WHERE recordedid = '736'  <<<< Took 0ms, Returned 1 row(s)
7012016-08-18 14:37:00.238150 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "hostname = mythtv.harvs.net, storagegroup = Default, id = 723, basename = 56378_20160818133700.nuv, filesize = 0, video_codec = , width = 0, height = 0, aspect = 0, fps = 0, audio_codec = , audio_channels =
7022016-08-18 14:37:00.238376 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) UPDATE recordedfile SET basename = '56378_20160818133700.nuv', filesize = '0', width = '0', height = '0', fps = '0', aspect = '0', audio_sample_rate = '0', audio_avg_bitrate = '0', audio_channels = '0', audio_codec = '', video_codec = '', hostname = 'mythtv.harvs.net', storagegroup = 'Default', recordedid = '736', container = 'NUV' WHERE id = '723'  <<<< Took 0ms
7032016-08-18 14:37:00.238552 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT channel.videofilters FROM channel WHERE channum  = '447' AND       sourceid = '2' <<<< Took 0ms, Returned 1 row(s)
7042016-08-18 14:37:00.238558 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "videofilters = "
7052016-08-18 14:37:00.238700 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT channel.brightness FROM channel WHERE channum  = '447' AND       sourceid = '2' <<<< Took 0ms, Returned 1 row(s)
7062016-08-18 14:37:00.238705 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "brightness = 32768"
7072016-08-18 14:37:00.238796 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) SELECT brightness FROM capturecard WHERE capturecard.cardid = '7' <<<< Took 0ms, Returned 1 row(s)
7082016-08-18 14:37:00.238800 D [7322/7346] TVRecEvent mythdbcon.cpp:779 (seekDebug) - MSqlQuery::next(DBManager12) Result: "brightness = 0"
7092016-08-18 14:37:00.238817 E [7322/7346] TVRecEvent recorders/v4lchannel.cpp:644 (InitPictureAttribute) - V4LChannel[7](/dev/video0): InitPictureAttribute(brightness): failed to query controls.
710                        eno: Inappropriate ioctl for device (25)
7112016-08-18 14:37:00.238955 I [7322/7930] RecThread recorders/NuppelVideoRecorder.cpp:1433 (SetFormatV4L2) - NVR(/dev/video0): v4l2: format set, getting yuv420 from v4l
7122016-08-18 14:37:00.239469 I [7322/7930] RecThread mythdbcon.cpp:86 (MSqlDatabase) - Database object created: DBManager26
7132016-08-18 14:37:00.239474 I [7322/7930] RecThread mythdbcon.cpp:325 (popConnection) - New DB connection, total: 18
7142016-08-18 14:37:00.239715 I [7322/7930] RecThread mythdbcon.cpp:193 (OpenDatabase) - [DBManager26] Connected to database 'mythconverg' at host: localhost
7152016-08-18 14:37:00.239936 I [7322/7930] RecThread mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager26) UPDATE recordedfile SET basename = '56378_20160818133700.nuv', filesize = '0', width = '0', height = '0', fps = '0', aspect = '1.25', audio_sample_rate = '0', audio_avg_bitrate = '0', audio_channels = '0', audio_codec = '', video_codec = '', hostname = 'mythtv.harvs.net', storagegroup = 'Default', recordedid = '736', container = 'NUV' WHERE id = '723'  <<<< Took 0ms
7162016-08-18 14:37:00.240058 I [7322/7930] RecThread mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager26) INSERT INTO recordedmarkup (chanid, starttime, mark, type, data) VALUES ( '56378', '2016-08-18T13:37:00Z', '0', '14', '1250000'); <<<< Took 0ms
7172016-08-18 14:37:00.240188 I [7322/7930] RecThread mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager26) UPDATE recordedfile SET basename = '56378_20160818133700.nuv', filesize = '0', width = '720', height = '576', fps = '0', aspect = '1.25', audio_sample_rate = '0', audio_avg_bitrate = '0', audio_channels = '0', audio_codec = '', video_codec = '', hostname = 'mythtv.harvs.net', storagegroup = 'Default', recordedid = '736', container = 'NUV' WHERE id = '723'  <<<< Took 0ms
7182016-08-18 14:37:00.240279 I [7322/7930] RecThread mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager26) INSERT INTO recordedmarkup    (chanid, starttime, mark, type, data)    VALUES ( '56378', '2016-08-18T13:37:00Z', '0', '30', '720'); <<<< Took 0ms
7192016-08-18 14:37:00.240351 I [7322/7930] RecThread mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager26) INSERT INTO recordedmarkup    (chanid, starttime, mark, type, data)    VALUES ( '56378', '2016-08-18T13:37:00Z', '0', '31', '576'); <<<< Took 0ms
7202016-08-18 14:37:00.240363 I [7322/7930] RecThread filtermanager.cpp:192 (GetFilterInfo) - FilterManager: GetFilterInfo(convert) returning: 0x0
7212016-08-18 14:37:00.240379 D [7322/7930] RecThread threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   72) total 72 cnt 1
7222016-08-18 14:37:00.240383 D [7322/7930] RecThread threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 84 cnt 1
7232016-08-18 14:37:00.240386 D [7322/7930] RecThread threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 96 cnt 1
7242016-08-18 14:37:00.240389 D [7322/7930] RecThread threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  512) total 608 cnt 1
7252016-08-18 14:37:00.240437 E [7322/7930] RecThread recorders/v4lrecorder.cpp:143 (OpenVBIDevice) - V4LRec[7](/dev/video0): Can't open vbi device: ''
7262016-08-18 14:37:00.240443 I [7322/7930] RecThread filtermanager.cpp:192 (GetFilterInfo) - FilterManager: GetFilterInfo(convert) returning: 0x0
7272016-08-18 14:37:00.240455 E [7322/7930] RecThread recorders/NuppelVideoRecorder.cpp:1473 (DoV4L2) - NVR(/dev/video0): VIDIOC_S_CTRL:V4L2_CID_AUDIO_MUTE:
728                        eno: Inappropriate ioctl for device (25)
7292016-08-18 14:37:00.241224 D [7322/7935] NVRAudio recorders/audioinputalsa.cpp:169 (PrepHwParams) - AudioInALSA(hw:CARD=usbtv): min channels 2, max channels 2, myth requests 2
7302016-08-18 14:37:00.241252 I [7322/7935] NVRAudio recorders/audioinputalsa.cpp:212 (PrepHwParams) - AudioInALSA(hw:CARD=usbtv): channels 2, sample rate 48000, buffer_time 115.2 msec, period size 2765
7312016-08-18 14:37:00.241256 D [7322/7935] NVRAudio recorders/audioinputalsa.cpp:214 (PrepHwParams) - AudioInALSA(hw:CARD=usbtv): myth block size 11060
7322016-08-18 14:37:00.241261 I [7322/7935] NVRAudio recorders/audioinputalsa.cpp:62 (Open) - AudioInALSA(hw:CARD=usbtv): pcm open
7332016-08-18 14:37:00.243938 I [7322/7346] TVRecEvent tv_rec.cpp:4404 (SetFlags) - TVRec[7]: SetFlags(RecorderRunning,RingBufferReady,) -> RunMainLoop,NeedToStartRecorder,RecorderRunning,RingBufferReady, @ tv_rec.cpp:4319
7342016-08-18 14:37:00.243946 I [7322/7346] TVRecEvent tv_rec.cpp:4413 (ClearFlags) - TVRec[7]: ClearFlags(NeedToStartRecorder,) -> RunMainLoop,RecorderRunning,RingBufferReady, @ tv_rec.cpp:4321
7352016-08-18 14:37:00.244106 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) UPDATE capturecard SET startchan = '447' WHERE cardid = '7' <<<< Took 0ms
7362016-08-18 14:37:00.315610 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 620 cnt 1
7372016-08-18 14:37:00.315616 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  737) total 1357 cnt 1
7382016-08-18 14:37:00.315619 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   31) total 1388 cnt 1
7392016-08-18 14:37:00.366221 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 1400 cnt 1
7402016-08-18 14:37:00.366230 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 1412 cnt 1
7412016-08-18 14:37:00.366233 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 1424 cnt 1
7422016-08-18 14:37:00.369415 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 1436 cnt 1
7432016-08-18 14:37:00.369438 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 37704) total 39140 cnt 1
7442016-08-18 14:37:00.369455 I [7322/7934] NVRWrite mythcorecontext.cpp:1592 (dispatch) - MythCoreContext::dispatch(): MythEvent: SYSTEM_EVENT REC_STARTED_WRITING CARDID 7 CHANID 56378 STARTTIME 2016-08-18T13:37:00Z RECSTATUS -2 SENDER mythtv.harvs.net
7452016-08-18 14:37:00.369527 I [7322/7322] CoreContext referencecounter.cpp:114 (IncrRef) - (0x7f7960012230)::IncrRef() -> 2
7462016-08-18 14:37:00.369531 I [7322/7322] CoreContext referencecounter.cpp:114 (IncrRef) - (0x7f79600116f0)::IncrRef() -> 2
7472016-08-18 14:37:00.369537 I [7322/7322] CoreContext referencecounter.cpp:145 (DecrRef) - (0x7f7960012230)::DecrRef() -> 1
7482016-08-18 14:37:00.369539 I [7322/7322] CoreContext referencecounter.cpp:145 (DecrRef) - (0x7f79600116f0)::DecrRef() -> 1
7492016-08-18 14:37:00.371877 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 39152 cnt 1
7502016-08-18 14:37:00.371882 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  736) total 39888 cnt 1
7512016-08-18 14:37:00.371885 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   32) total 39920 cnt 1
7522016-08-18 14:37:00.410166 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 39932 cnt 1
7532016-08-18 14:37:00.410196 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 60694) total 100626 cnt 2
7542016-08-18 14:37:00.410224 D [7322/7928] TFWWrite threadedfilewriter.cpp:508 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): write(39932) cnt 1 total 60694
7552016-08-18 14:37:00.410267 D [7322/7928] TFWWrite threadedfilewriter.cpp:545 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): total written so far: 39932 bytes
7562016-08-18 14:37:00.429637 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 60706 cnt 1
7572016-08-18 14:37:00.429642 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 1118) total 61824 cnt 1
7582016-08-18 14:37:00.429644 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   34) total 61858 cnt 1
7592016-08-18 14:37:00.449332 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 61870 cnt 1
7602016-08-18 14:37:00.449342 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 33635) total 95505 cnt 2
7612016-08-18 14:37:00.449367 D [7322/7928] TFWWrite threadedfilewriter.cpp:508 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): write(61870) cnt 1 total 33635
7622016-08-18 14:37:00.449422 D [7322/7928] TFWWrite threadedfilewriter.cpp:545 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): total written so far: 101802 bytes
7632016-08-18 14:37:00.489273 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 33647 cnt 1
7642016-08-18 14:37:00.489284 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 36377) total 70024 cnt 2
7652016-08-18 14:37:00.489312 D [7322/7928] TFWWrite threadedfilewriter.cpp:508 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): write(33647) cnt 1 total 36377
7662016-08-18 14:37:00.489348 D [7322/7928] TFWWrite threadedfilewriter.cpp:545 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): total written so far: 135449 bytes
7672016-08-18 14:37:00.489666 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 36389 cnt 1
7682016-08-18 14:37:00.489670 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  696) total 37085 cnt 1
7692016-08-18 14:37:00.489685 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   72) total 37157 cnt 1
7702016-08-18 14:37:00.529378 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 37169 cnt 1
7712016-08-18 14:37:00.529388 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 40126) total 77295 cnt 2
7722016-08-18 14:37:00.529412 D [7322/7928] TFWWrite threadedfilewriter.cpp:508 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): write(37169) cnt 1 total 40126
7732016-08-18 14:37:00.529447 D [7322/7928] TFWWrite threadedfilewriter.cpp:545 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): total written so far: 172618 bytes
7742016-08-18 14:37:00.544367 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 40138 cnt 1
7752016-08-18 14:37:00.544372 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  721) total 40859 cnt 1
7762016-08-18 14:37:00.544375 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   47) total 40906 cnt 1
7772016-08-18 14:37:00.569392 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 40918 cnt 1
7782016-08-18 14:37:00.569404 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 37202) total 78120 cnt 2
7792016-08-18 14:37:00.569430 D [7322/7928] TFWWrite threadedfilewriter.cpp:508 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): write(40918) cnt 1 total 37202
7802016-08-18 14:37:00.569466 D [7322/7928] TFWWrite threadedfilewriter.cpp:545 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): total written so far: 213536 bytes
7812016-08-18 14:37:00.602079 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 37214 cnt 1
7822016-08-18 14:37:00.602084 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 1033) total 38247 cnt 1
7832016-08-18 14:37:00.602092 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  119) total 38366 cnt 1
7842016-08-18 14:37:00.609319 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 38378 cnt 1
7852016-08-18 14:37:00.609329 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 40802) total 79180 cnt 2
7862016-08-18 14:37:00.609355 D [7322/7928] TFWWrite threadedfilewriter.cpp:508 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): write(38378) cnt 1 total 40802
7872016-08-18 14:37:00.609387 D [7322/7928] TFWWrite threadedfilewriter.cpp:545 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): total written so far: 251914 bytes
7882016-08-18 14:37:00.649393 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 40814 cnt 1
7892016-08-18 14:37:00.649404 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 40777) total 81591 cnt 2
7902016-08-18 14:37:00.649428 D [7322/7928] TFWWrite threadedfilewriter.cpp:508 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): write(40814) cnt 1 total 40777
7912016-08-18 14:37:00.649465 D [7322/7928] TFWWrite threadedfilewriter.cpp:545 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): total written so far: 292728 bytes
7922016-08-18 14:37:00.659476 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 40789 cnt 1
7932016-08-18 14:37:00.659482 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  681) total 41470 cnt 1
7942016-08-18 14:37:00.659485 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   87) total 41557 cnt 1
7952016-08-18 14:37:00.689372 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 41569 cnt 1
7962016-08-18 14:37:00.689383 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 23028) total 64597 cnt 1
7972016-08-18 14:37:00.717003 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 64609 cnt 1
7982016-08-18 14:37:00.717012 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 1025) total 65634 cnt 2
7992016-08-18 14:37:00.717017 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  127) total 65761 cnt 2
8002016-08-18 14:37:00.717036 D [7322/7928] TFWWrite threadedfilewriter.cpp:508 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): write(64609) cnt 1 total 1152
8012016-08-18 14:37:00.717088 D [7322/7928] TFWWrite threadedfilewriter.cpp:545 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): total written so far: 357337 bytes
8022016-08-18 14:37:00.729211 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 1164 cnt 1
8032016-08-18 14:37:00.729219 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 25920) total 27084 cnt 1
8042016-08-18 14:37:00.769346 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 27096 cnt 1
8052016-08-18 14:37:00.769361 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 28182) total 55278 cnt 1
8062016-08-18 14:37:00.774311 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 55290 cnt 1
8072016-08-18 14:37:00.774315 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  666) total 55956 cnt 1
8082016-08-18 14:37:00.774318 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  102) total 56058 cnt 1
8092016-08-18 14:37:00.809124 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 56070 cnt 1
8102016-08-18 14:37:00.809134 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 23501) total 79571 cnt 2
8112016-08-18 14:37:00.809155 D [7322/7928] TFWWrite threadedfilewriter.cpp:508 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): write(56070) cnt 1 total 23501
8122016-08-18 14:37:00.809204 D [7322/7928] TFWWrite threadedfilewriter.cpp:545 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): total written so far: 413407 bytes
8132016-08-18 14:37:00.831890 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 23513 cnt 1
8142016-08-18 14:37:00.831894 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  673) total 24186 cnt 1
8152016-08-18 14:37:00.831898 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   95) total 24281 cnt 1
8162016-08-18 14:37:00.848806 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 24293 cnt 1
8172016-08-18 14:37:00.848813 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 17287) total 41580 cnt 1
8182016-08-18 14:37:00.889368 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 41592 cnt 1
8192016-08-18 14:37:00.889375 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 19183) total 60775 cnt 1
8202016-08-18 14:37:00.889877 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 60787 cnt 1
8212016-08-18 14:37:00.889881 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 1040) total 61827 cnt 1
8222016-08-18 14:37:00.889883 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  112) total 61939 cnt 1
8232016-08-18 14:37:00.928725 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 61951 cnt 1
8242016-08-18 14:37:00.928733 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 16087) total 78038 cnt 2
8252016-08-18 14:37:00.928759 D [7322/7928] TFWWrite threadedfilewriter.cpp:508 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): write(61951) cnt 1 total 16087
8262016-08-18 14:37:00.928809 D [7322/7928] TFWWrite threadedfilewriter.cpp:545 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): total written so far: 475358 bytes
8272016-08-18 14:37:00.946911 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 16099 cnt 1
8282016-08-18 14:37:00.946916 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  739) total 16838 cnt 1
8292016-08-18 14:37:00.946919 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   29) total 16867 cnt 1
8302016-08-18 14:37:00.968946 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 16879 cnt 1
8312016-08-18 14:37:00.968952 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 17763) total 34642 cnt 1
8322016-08-18 14:37:01.005986 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 34654 cnt 1
8332016-08-18 14:37:01.005992 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 1091) total 35745 cnt 1
8342016-08-18 14:37:01.005995 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   61) total 35806 cnt 1
8352016-08-18 14:37:01.009072 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 35818 cnt 1
8362016-08-18 14:37:01.009078 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 20653) total 56471 cnt 1
8372016-08-18 14:37:01.049230 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 56483 cnt 1
8382016-08-18 14:37:01.049242 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 17829) total 74312 cnt 2
8392016-08-18 14:37:01.049258 D [7322/7928] TFWWrite threadedfilewriter.cpp:508 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): write(56483) cnt 1 total 17829
8402016-08-18 14:37:01.049305 D [7322/7928] TFWWrite threadedfilewriter.cpp:545 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): total written so far: 531841 bytes
8412016-08-18 14:37:01.063094 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 17841 cnt 1
8422016-08-18 14:37:01.063100 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  688) total 18529 cnt 1
8432016-08-18 14:37:01.063104 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   80) total 18609 cnt 1
8442016-08-18 14:37:01.089334 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 18621 cnt 1
8452016-08-18 14:37:01.089342 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 18404) total 37025 cnt 1
8462016-08-18 14:37:01.120648 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 37037 cnt 1
8472016-08-18 14:37:01.120654 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  691) total 37728 cnt 1
8482016-08-18 14:37:01.120658 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   77) total 37805 cnt 1
8492016-08-18 14:37:01.129161 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 37817 cnt 1
8502016-08-18 14:37:01.129169 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 20646) total 58463 cnt 1
8512016-08-18 14:37:01.169092 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 58475 cnt 1
8522016-08-18 14:37:01.169102 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 18277) total 76752 cnt 2
8532016-08-18 14:37:01.169126 D [7322/7928] TFWWrite threadedfilewriter.cpp:508 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): write(58475) cnt 1 total 18277
8542016-08-18 14:37:01.169174 D [7322/7928] TFWWrite threadedfilewriter.cpp:545 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): total written so far: 590316 bytes
8552016-08-18 14:37:01.178351 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 18289 cnt 1
8562016-08-18 14:37:01.178355 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 1005) total 19294 cnt 1
8572016-08-18 14:37:01.178358 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  147) total 19441 cnt 1
8582016-08-18 14:37:01.209043 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 19453 cnt 1
8592016-08-18 14:37:01.209051 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 19695) total 39148 cnt 1
8602016-08-18 14:37:01.235655 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 39160 cnt 1
8612016-08-18 14:37:01.235660 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  677) total 39837 cnt 1
8622016-08-18 14:37:01.235663 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   91) total 39928 cnt 1
8632016-08-18 14:37:01.244462 I [7322/7346] TVRecEvent mythdbcon.cpp:709 (exec) - MSqlQuery::exec(DBManager12) INSERT INTO recordedseek (chanid, starttime, type, mark, offset) VALUES (56378,'2016-08-18T13:37:00Z',7,0,1400) <<<< Took 0ms
8642016-08-18 14:37:01.244478 D [7322/7346] TVRecEvent recorders/recorderbase.cpp:656 (TryWriteProgStartMark) - durationDeltaCopy.begin() = (0,0)
8652016-08-18 14:37:01.244525 C [7322/7322] CoreContext signalhandling.cpp:305 (handleSignal) - Received Segmentation fault: Code 1, PID 32, UID 0, Value 0x4fd207b896a38600
8662016-08-18 14:37:01.248948 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 39940 cnt 1
8672016-08-18 14:37:01.248959 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 19747) total 59687 cnt 1
8682016-08-18 14:37:01.288996 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 59699 cnt 1
8692016-08-18 14:37:01.289005 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 17324) total 77023 cnt 2
8702016-08-18 14:37:01.289030 D [7322/7928] TFWWrite threadedfilewriter.cpp:508 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): write(59699) cnt 1 total 17324
8712016-08-18 14:37:01.289071 D [7322/7928] TFWWrite threadedfilewriter.cpp:545 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): total written so far: 650015 bytes
8722016-08-18 14:37:01.293563 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 17336 cnt 1
8732016-08-18 14:37:01.293567 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 1123) total 18459 cnt 1
8742016-08-18 14:37:01.293570 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   29) total 18488 cnt 1
8752016-08-18 14:37:01.329061 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 18500 cnt 1
8762016-08-18 14:37:01.329068 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 19715) total 38215 cnt 1
8772016-08-18 14:37:01.350645 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 38227 cnt 1
8782016-08-18 14:37:01.350650 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  748) total 38975 cnt 1
8792016-08-18 14:37:01.350653 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   20) total 38995 cnt 1
8802016-08-18 14:37:01.369004 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 39007 cnt 1
8812016-08-18 14:37:01.369010 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 21460) total 60467 cnt 1
8822016-08-18 14:37:01.408980 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 60479 cnt 1
8832016-08-18 14:37:01.408989 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 17939) total 78418 cnt 2
8842016-08-18 14:37:01.409015 D [7322/7928] TFWWrite threadedfilewriter.cpp:508 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): write(60479) cnt 1 total 17939
8852016-08-18 14:37:01.409061 D [7322/7928] TFWWrite threadedfilewriter.cpp:545 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): total written so far: 710494 bytes
8862016-08-18 14:37:01.409356 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 17951 cnt 1
8872016-08-18 14:37:01.409359 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  707) total 18658 cnt 1
8882016-08-18 14:37:01.409367 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   61) total 18719 cnt 1
8892016-08-18 14:37:01.449072 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 18731 cnt 1
8902016-08-18 14:37:01.449078 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 18509) total 37240 cnt 1
8912016-08-18 14:37:01.465892 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 37252 cnt 1
8922016-08-18 14:37:01.465896 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  994) total 38246 cnt 1
8932016-08-18 14:37:01.465900 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  158) total 38404 cnt 1
8942016-08-18 14:37:01.489094 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 38416 cnt 1
8952016-08-18 14:37:01.489100 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 20585) total 59001 cnt 1
8962016-08-18 14:37:01.523055 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 59013 cnt 1
8972016-08-18 14:37:01.523059 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  668) total 59681 cnt 1
8982016-08-18 14:37:01.523063 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  100) total 59781 cnt 1
8992016-08-18 14:37:01.529093 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 59793 cnt 1
9002016-08-18 14:37:01.529103 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 18102) total 77895 cnt 2
9012016-08-18 14:37:01.529129 D [7322/7928] TFWWrite threadedfilewriter.cpp:508 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): write(59793) cnt 1 total 18102
9022016-08-18 14:37:01.529170 D [7322/7928] TFWWrite threadedfilewriter.cpp:545 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): total written so far: 770287 bytes
9032016-08-18 14:37:01.566152 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 18114 cnt 1
9042016-08-18 14:37:01.566158 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 18126 cnt 1
9052016-08-18 14:37:01.566161 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 18138 cnt 1
9062016-08-18 14:37:01.567697 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 18150 cnt 1
9072016-08-18 14:37:01.567712 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 65426) total 83576 cnt 2
9082016-08-18 14:37:01.567732 D [7322/7928] TFWWrite threadedfilewriter.cpp:508 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): write(18150) cnt 1 total 65426
9092016-08-18 14:37:01.567754 D [7322/7928] TFWWrite threadedfilewriter.cpp:545 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): total written so far: 788437 bytes
9102016-08-18 14:37:01.580817 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 65438 cnt 1
9112016-08-18 14:37:01.580824 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 1123) total 66561 cnt 2
9122016-08-18 14:37:01.580827 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   29) total 66590 cnt 2
9132016-08-18 14:37:01.580843 D [7322/7928] TFWWrite threadedfilewriter.cpp:508 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): write(65438) cnt 1 total 1152
9142016-08-18 14:37:01.580882 D [7322/7928] TFWWrite threadedfilewriter.cpp:545 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): total written so far: 853875 bytes
9152016-08-18 14:37:01.608933 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 1164 cnt 1
9162016-08-18 14:37:01.608940 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 17061) total 18225 cnt 1
9172016-08-18 14:37:01.638167 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 18237 cnt 1
9182016-08-18 14:37:01.638172 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  690) total 18927 cnt 1
9192016-08-18 14:37:01.638175 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   78) total 19005 cnt 1
9202016-08-18 14:37:01.649063 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 19017 cnt 1
9212016-08-18 14:37:01.649071 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 17721) total 36738 cnt 1
9222016-08-18 14:37:01.689037 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 36750 cnt 1
9232016-08-18 14:37:01.689044 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 19472) total 56222 cnt 1
9242016-08-18 14:37:01.696912 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 56234 cnt 1
9252016-08-18 14:37:01.696917 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  741) total 56975 cnt 1
9262016-08-18 14:37:01.696919 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   27) total 57002 cnt 1
9272016-08-18 14:37:01.728928 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 57014 cnt 1
9282016-08-18 14:37:01.728937 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 20298) total 77312 cnt 2
9292016-08-18 14:37:01.728964 D [7322/7928] TFWWrite threadedfilewriter.cpp:508 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): write(57014) cnt 1 total 20298
9302016-08-18 14:37:01.729009 D [7322/7928] TFWWrite threadedfilewriter.cpp:545 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): total written so far: 910889 bytes
9312016-08-18 14:37:01.754545 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 20310 cnt 1
9322016-08-18 14:37:01.754550 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 1126) total 21436 cnt 1
9332016-08-18 14:37:01.754553 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   26) total 21462 cnt 1
9342016-08-18 14:37:01.768958 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 21474 cnt 1
9352016-08-18 14:37:01.768965 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 17669) total 39143 cnt 1
9362016-08-18 14:37:01.809006 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 39155 cnt 1
9372016-08-18 14:37:01.809013 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 19269) total 58424 cnt 1
9382016-08-18 14:37:01.811865 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 58436 cnt 1
9392016-08-18 14:37:01.811869 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  647) total 59083 cnt 1
9402016-08-18 14:37:01.811872 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  121) total 59204 cnt 1
9412016-08-18 14:37:01.849110 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 59216 cnt 1
9422016-08-18 14:37:01.849121 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 21878) total 81094 cnt 2
9432016-08-18 14:37:01.849143 D [7322/7928] TFWWrite threadedfilewriter.cpp:508 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): write(59216) cnt 1 total 21878
9442016-08-18 14:37:01.849187 D [7322/7928] TFWWrite threadedfilewriter.cpp:545 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): total written so far: 970105 bytes
9452016-08-18 14:37:01.869638 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 21890 cnt 1
9462016-08-18 14:37:01.869643 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 1085) total 22975 cnt 1
9472016-08-18 14:37:01.869647 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   67) total 23042 cnt 1
9482016-08-18 14:37:01.888907 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 23054 cnt 1
9492016-08-18 14:37:01.888914 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 17541) total 40595 cnt 1
9502016-08-18 14:37:01.929035 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 40607 cnt 1
9512016-08-18 14:37:01.929041 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 19087) total 59694 cnt 1
9522016-08-18 14:37:01.929450 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 59706 cnt 1
9532016-08-18 14:37:01.929454 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  727) total 60433 cnt 1
9542016-08-18 14:37:01.929456 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   41) total 60474 cnt 1
9552016-08-18 14:37:01.968855 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 60486 cnt 1
9562016-08-18 14:37:01.968865 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 21015) total 81501 cnt 2
9572016-08-18 14:37:01.968887 D [7322/7928] TFWWrite threadedfilewriter.cpp:508 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): write(60486) cnt 1 total 21015
9582016-08-18 14:37:01.968938 D [7322/7928] TFWWrite threadedfilewriter.cpp:545 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): total written so far: 1030591 bytes
9592016-08-18 14:37:01.984441 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 21027 cnt 1
9602016-08-18 14:37:01.984445 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  690) total 21717 cnt 1
9612016-08-18 14:37:01.984448 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   78) total 21795 cnt 1
9622016-08-18 14:37:02.008739 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 21807 cnt 1
9632016-08-18 14:37:02.008745 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 18287) total 40094 cnt 1
9642016-08-18 14:37:02.042184 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 40106 cnt 1
9652016-08-18 14:37:02.042190 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 1080) total 41186 cnt 1
9662016-08-18 14:37:02.042192 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   72) total 41258 cnt 1
9672016-08-18 14:37:02.048828 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 41270 cnt 1
9682016-08-18 14:37:02.048834 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 19368) total 60638 cnt 1
9692016-08-18 14:37:02.088843 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 60650 cnt 1
9702016-08-18 14:37:02.088852 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 20278) total 80928 cnt 2
9712016-08-18 14:37:02.088876 D [7322/7928] TFWWrite threadedfilewriter.cpp:508 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): write(60650) cnt 1 total 20278
9722016-08-18 14:37:02.088928 D [7322/7928] TFWWrite threadedfilewriter.cpp:545 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): total written so far: 1091241 bytes
9732016-08-18 14:37:02.099515 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 20290 cnt 1
9742016-08-18 14:37:02.099519 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  673) total 20963 cnt 1
9752016-08-18 14:37:02.099522 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   95) total 21058 cnt 1
9762016-08-18 14:37:02.129199 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 21070 cnt 1
9772016-08-18 14:37:02.129205 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 18682) total 39752 cnt 1
9782016-08-18 14:37:02.157070 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 39764 cnt 1
9792016-08-18 14:37:02.157075 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 1003) total 40767 cnt 1
9802016-08-18 14:37:02.157078 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  149) total 40916 cnt 1
9812016-08-18 14:37:02.168831 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 40928 cnt 1
9822016-08-18 14:37:02.168837 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 19040) total 59968 cnt 1
9832016-08-18 14:37:02.208705 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 59980 cnt 1
9842016-08-18 14:37:02.208714 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*, 19901) total 79881 cnt 2
9852016-08-18 14:37:02.208743 D [7322/7928] TFWWrite threadedfilewriter.cpp:508 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): write(59980) cnt 1 total 19901
9862016-08-18 14:37:02.208796 D [7322/7928] TFWWrite threadedfilewriter.cpp:545 (DiskLoop) - TFW(/video/Recordings/56378_20160818133700.nuv:71): total written so far: 1151221 bytes
9872016-08-18 14:37:02.214465 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   12) total 19913 cnt 1
9882016-08-18 14:37:02.214470 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,  679) total 20592 cnt 1
9892016-08-18 14:37:02.214473 D [7322/7934] NVRWrite threadedfilewriter.cpp:303 (Write) - TFW(/video/Recordings/56378_20160818133700.nuv:71): Write(*,   89) total 20681 cnt 1