Ticket #13254: backend-f27-937-success.txt

File backend-f27-937-success.txt, 116.4 KB (added by Jerry Chapman <mythtv@…>, 6 years ago)

Backend with F27 rpms and channel 937 - success

Line 
1-- Logs begin at Thu 2013-12-19 10:20:27 EST, end at Wed 2018-04-04 09:14:06 EDT. --
2Apr 04 09:07:54 sirius mythbackend[10691]: 2018-04-04 09:07:54.039144 I  MainServer: MainServer::ANN Playback
3Apr 04 09:07:54 sirius mythbackend[10691]: 2018-04-04 09:07:54.039154 I  MainServer: adding: sirius(24cf2e0) as a client (events: 0)
4Apr 04 09:07:54 sirius mythbackend[10691]: 2018-04-04 09:07:54.039974 I  MainServer: MainServer::ANN Monitor
5Apr 04 09:07:54 sirius mythbackend[10691]: 2018-04-04 09:07:54.039984 I  MainServer: adding: sirius(22dd610) as a client (events: 1)
6Apr 04 09:07:54 sirius mythbackend[10691]: 2018-04-04 09:07:54.040730 N  MainServer: Verbose mask changed, new mask is:  general record playback
7Apr 04 09:07:54 sirius mythbackend[10691]: 2018-04-04 09:07:54.132319 I  Playback sock(24cf2e0) 'sirius' disconnected
8Apr 04 09:07:54 sirius mythbackend[10691]: 2018-04-04 09:07:54.132543 I  Monitor sock(22dd610) 'sirius' disconnected
9Apr 04 09:08:13 sirius mythbackend[10691]: 2018-04-04 09:08:13.098968 I  MainServer: MainServer::ANN Playback
10Apr 04 09:08:13 sirius mythbackend[10691]: 2018-04-04 09:08:13.098977 I  MainServer: adding: sirius(24cf2e0) as a client (events: 0)
11Apr 04 09:08:13 sirius mythbackend[10691]: 2018-04-04 09:08:13.099894 I  MainServer: MainServer::ANN Monitor
12Apr 04 09:08:13 sirius mythbackend[10691]: 2018-04-04 09:08:13.099903 I  MainServer: adding: sirius(227bff0) as a client (events: 1)
13Apr 04 09:08:13 sirius mythbackend[10691]: 2018-04-04 09:08:13.100513 N  MainServer: Log level changed, new level is: debug
14Apr 04 09:08:13 sirius mythbackend[10691]: 2018-04-04 09:08:13.192740 I  Playback sock(24cf2e0) 'sirius' disconnected
15Apr 04 09:08:13 sirius mythbackend[10691]: 2018-04-04 09:08:13.192907 I  Monitor sock(227bff0) 'sirius' disconnected
16Apr 04 09:08:58 sirius mythbackend[10691]: 2018-04-04 09:08:58.819185 D  Running HouseKeeper.
17Apr 04 09:08:58 sirius mythbackend[10691]: 2018-04-04 09:08:58.819194 D  Checking to run DBCleanup
18Apr 04 09:08:58 sirius mythbackend[10691]: 2018-04-04 09:08:58.819200 D  Checking to run HardwareProfiler
19Apr 04 09:08:58 sirius mythbackend[10691]: 2018-04-04 09:08:58.819213 D  Checking to run JobQueueRecover
20Apr 04 09:08:58 sirius mythbackend[10691]: 2018-04-04 09:08:58.819216 D  Checking to run LogClean
21Apr 04 09:08:58 sirius mythbackend[10691]: 2018-04-04 09:08:58.819219 D  Checking to run MythFillDB
22Apr 04 09:08:58 sirius mythbackend[10691]: 2018-04-04 09:08:58.819251 D  MythFillDatabase scheduled to run at Thu Apr 5 11:36:01 2018 GMT.
23Apr 04 09:08:58 sirius mythbackend[10691]: 2018-04-04 09:08:58.819256 D  Checking to run RecordedArtworkUpdate
24Apr 04 09:08:58 sirius mythbackend[10691]: 2018-04-04 09:08:58.819262 D  Checking to run ThemeUpdateNotifications
25Apr 04 09:08:58 sirius mythbackend[10691]: 2018-04-04 09:08:58.819266 D  Checking to run UpdateRadioStreams
26Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.210433 D  PortChecker::checkPort(): host localhost port 3306 timeLimit 30000 linkLocalOnly 1
27Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.268649 I  MainServer: MainServer::ANN Playback
28Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.268653 I  MainServer: adding: sirius(24cf2e0) as a client (events: 0)
29Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.270335 I  TVRec[5]: Found channel (937) on current input(5).
30Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.270545 D  LiveTVChain(): ctor
31Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.271019 I  TVRec[5]: Changing from None to WatchingLiveTV
32Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.271036 I  TVRec[5]: ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RingBufferReady, @ tv_rec.cpp:1316
33Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.271045 I  TVRec[5]: HandleTuning Request: Program(NULL) channel() input() flags(LiveTV,)
34Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.271058 I  CardUtil: GetConflictingInputs() input 5
35Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.271081 I  New DB connection, total: 10
36Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.271124 D  PortChecker::checkPort(): host localhost port 3306 timeLimit 30000 linkLocalOnly 1
37Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.271779 I  TVRec[5]: TuningShutdowns(Program(NULL) channel(937) input() flags(LiveTV,))
38Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.271784 I  TVRec[5]: request (Program(NULL) channel(937) input() flags(LiveTV,)) channum () inputname ()
39Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.271913 I  TVRec[5]: ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady, @ tv_rec.cpp:3683
40Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.271918 I  TVRec[5]: No recorder yet, calling TuningFrequency
41Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.271920 I  TVRec[5]: TuningFrequency
42Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.272043 I  CardUtil: GetConflictingInputs() input 5
43Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.273698 I  HDHRSH(14165FA2-2): TuneVChannel(937) from (none)
44Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.273702 I  HDHRSH(14165FA2-2): Tuning vchannel 937
45Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.282367 I  TVRec[5]: CreateLiveTVRingBuffer(937)
46Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.282837 I  TVRec[5]: GetProgramRingBufferForLiveTV()
47Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.285183 N  AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 14 min
48Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.298886 I  TVRec[5]: StartedRecording(1937_2018-04-04T13:09:22Z) fn(/storage/livetv/1937_20180404130922.ts)
49Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.299406 N  ApplyRecordRecGroupChange: LiveTV to LiveTV (2)
50Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.299558 I  Chain: Appended@0 '1937_20180404130922'
51Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.300516 I  TVRec[5]: Starting Signal Monitor
52Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.300519 I  TVRec[5]: SetupSignalMonitor(1, 1)
53Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.300542 I  HDHRSH: Using existing stream handler 14165FA2-2 for 14165FA2-2 (2 in use)
54Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.300545 I  TVRec[5]: Signal monitor successfully created
55Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.300547 I  TVRec[5]: Setting up table monitoring.
56Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.300559 D  Setting Lossless transcoding changed to 0
57Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.300563 D  Setting Resize video while transcoding changed to 0
58Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.300566 D  Setting Enable auto-transcode after recording changed to 0
59Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.301158 I  TVRec[5]: Using profile 'Live TV' to record
60Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.301163 I  TVRec[5]: MPEG program number: 0
61Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.301171 I  MPEGStream[5](0x7fc9541a0fe0): SetDesiredProgram(0)
62Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.301175 I  TVRec[5]: Successfully set up MPEG table monitoring.
63Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.301264 I  TVRec[5]: SetFlags(SignalMonitorRunning,) -> RunMainLoop,SignalMonitorRunning,RingBufferReady, @ tv_rec.cpp:3859
64Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.301268 I  TVRec[5]: ClearFlags(WaitingForSignal,) -> RunMainLoop,SignalMonitorRunning,RingBufferReady, @ tv_rec.cpp:3860
65Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.301273 I  TVRec[5]: SetFlags(WaitingForSignal,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,RingBufferReady, @ tv_rec.cpp:3865
66Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.301277 I  TVRec[5]: SetFlags(DummyRecorderRunning,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, @ tv_rec.cpp:3914
67Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.301278 I  DummyDTVRecorder -- started
68Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.301281 I  TVRec[5]: SetFlags(RingBufferReady,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, @ tv_rec.cpp:3916
69Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.301284 I  TVRec[5]: ClearFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, @ tv_rec.cpp:3926
70Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.301288 I  TVRec[5]: SetFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, @ tv_rec.cpp:3928
71Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.301314 I  TVRec[5]: TuningSignalCheck: Still waiting.  Will timeout @ 09:30:00.000
72Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.301336 I  TVRec[5]: SetFlags(CancelNextRecording,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, @ tv_rec.cpp:2683
73Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.302090 D  HDHRSigMon[5](14165FA2-2): Tuner status: '100:0:0'
74Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.352997 D  HDHRSigMon[5](14165FA2-2): Tuner status: '40:0:0'
75Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.403867 D  HDHRSigMon[5](14165FA2-2): Tuner status: '40:0:0'
76Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.454735 D  HDHRSigMon[5](14165FA2-2): Tuner status: '40:0:0'
77Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.505593 D  HDHRSigMon[5](14165FA2-2): Tuner status: '40:0:0'
78Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.529185 I  TVRec[5]: SetFlags(FrontendReady,) -> FrontendReady,RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, @ ../../libs/libmythtv/tv_rec.h:171
79Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.556501 D  HDHRSigMon[5](14165FA2-2): Tuner status: '100:0:0'
80Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.607399 D  HDHRSigMon[5](14165FA2-2): Tuner status: '100:0:0'
81Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.658337 D  HDHRSigMon[5](14165FA2-2): Tuner status: '100:0:0'
82Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.710371 D  HDHRSigMon[5](14165FA2-2): Tuner status: '100:91:0'
83Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.710394 I  SH(14165FA2-2): AddListener(0x7fc9541a0fe0) -- begin
84Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.710399 I  SH(14165FA2-2): AddListener(0x7fc9541a0fe0) -- locked
85Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.775243 I  HDHRSH(14165FA2-2): RunTS(): begin
86Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.775280 I  SH(14165FA2-2): AddListener(0x7fc9541a0fe0) -- end
87Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.855732 E  DTVSigMon[5](14165FA2-2): Program #0 not found in PAT!
88Apr 04 09:09:22 sirius mythbackend[10691]: Program Association Section
89Apr 04 09:09:22 sirius mythbackend[10691]:  PSIP tableID(0x0) length(13) extension(0x18e3)
90Apr 04 09:09:22 sirius mythbackend[10691]:       version(0) current(1) section(0) last_section(0)
91Apr 04 09:09:22 sirius mythbackend[10691]:       tsid(6371) programCount(1)
92Apr 04 09:09:22 sirius mythbackend[10691]:   program number   937 has PID 0x0060
93Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.855749 E  DTVSigMon[5](14165FA2-2): But there is only one program in the PAT, so we'll just use it
94Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.855759 I  MPEGStream[5](0x7fc9541a0fe0): SetDesiredProgram(937)
95Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.855767 D  MPEGStream[5](0x7fc9541a0fe0): CreatePATSingleProgram()
96Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.855776 D  MPEGStream[5](0x7fc9541a0fe0): PAT in input stream
97Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.855790 D  MPEGStream[5](0x7fc9541a0fe0): Program Association Section
98Apr 04 09:09:22 sirius mythbackend[10691]:  PSIP tableID(0x0) length(13) extension(0x18e3)
99Apr 04 09:09:22 sirius mythbackend[10691]:       version(0) current(1) section(0) last_section(0)
100Apr 04 09:09:22 sirius mythbackend[10691]:       tsid(6371) programCount(1)
101Apr 04 09:09:22 sirius mythbackend[10691]:   program number   937 has PID 0x0060
102Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.855795 D  MPEGStream[5](0x7fc9541a0fe0): desired_program(937) pid(0x60)
103Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.855808 D  MPEGStream[5](0x7fc9541a0fe0): pmt_pid(0x60)
104Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.855813 D  MPEGStream[5](0x7fc9541a0fe0): PAT for output stream
105Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.855826 D  MPEGStream[5](0x7fc9541a0fe0): Program Association Section
106Apr 04 09:09:22 sirius mythbackend[10691]:  PSIP tableID(0x0) length(13) extension(0x18e3)
107Apr 04 09:09:22 sirius mythbackend[10691]:       version(0) current(1) section(0) last_section(0)
108Apr 04 09:09:22 sirius mythbackend[10691]:       tsid(6371) programCount(1)
109Apr 04 09:09:22 sirius mythbackend[10691]:   program number     1 has PID 0x0060
110Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.855836 W  MPEGStream[5](0x7fc9541a0fe0): ProcessPAT: PAT is missing program, setting timeout
111Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.896141 N  DTVSigMon[5](14165FA2-2): PMT says program 937 is encrypted
112Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.896182 D  MPEGStream[5](0x7fc9541a0fe0): CreatePMTSingleProgram()
113Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.896190 D  MPEGStream[5](0x7fc9541a0fe0): PMT in input stream
114Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.896541 D  MPEGStream[5](0x7fc9541a0fe0): Program Map Section
115Apr 04 09:09:22 sirius mythbackend[10691]:  PSIP tableID(0x2) length(143) extension(0x3a9)
116Apr 04 09:09:22 sirius mythbackend[10691]:       version(1) current(1) section(0) last_section(0)
117Apr 04 09:09:22 sirius mythbackend[10691]:       pnum(937) pid(0x60) pcrpid(61)
118Apr 04 09:09:22 sirius mythbackend[10691]:   Registration Descriptor: 'GA94' ATSC program ID A/53
119Apr 04 09:09:22 sirius mythbackend[10691]:   Registration Descriptor: 'CUEI' SCTE 35 2003, Cable Digital Program Insertion Cueing Message
120Apr 04 09:09:22 sirius mythbackend[10691]:   Private Data Indicator Descriptor (0x0f) length(4). Dumping
121Apr 04 09:09:22 sirius mythbackend[10691]:       000  53 41 50 53                                       |SAPS|
122Apr 04 09:09:22 sirius mythbackend[10691]:   Stream #0 pid(0x61) type(0x02 video-mpeg2)
123Apr 04 09:09:22 sirius mythbackend[10691]:     Video Descriptor (0x02) length(3). Dumping
124Apr 04 09:09:22 sirius mythbackend[10691]:       000  c2 44 5f                                          |.D.|
125Apr 04 09:09:22 sirius mythbackend[10691]:     Data Stream Alignment Descriptor (0x06) length(1). Dumping
126Apr 04 09:09:22 sirius mythbackend[10691]:       000  02                                                |.|
127Apr 04 09:09:22 sirius mythbackend[10691]:     Conditional Access: sid(0xe00) pid(0x6d) data_size(3)
128Apr 04 09:09:22 sirius mythbackend[10691]:   Stream #1 pid(0x62) type(0x81 audio-ac3)
129Apr 04 09:09:22 sirius mythbackend[10691]:     Registration Descriptor: 'AC-3' ATSC audio stream A/52
130Apr 04 09:09:22 sirius mythbackend[10691]:     ISO-639 Language: code(eng) canonical(eng) eng(English)
131Apr 04 09:09:22 sirius mythbackend[10691]:     Audio Stream Descriptor  full_srv(1) sample_rate(48kbps) bit_rate(=384kbps, 14)
132Apr 04 09:09:22 sirius mythbackend[10691]:       bsid(6) bs_mode(0) channels(1 + 1) Dolby(Not indicated)
133Apr 04 09:09:22 sirius mythbackend[10691]:       mainID(0) isLatin-1(true) text_length(95) text(TODO)
134Apr 04 09:09:22 sirius mythbackend[10691]:     Conditional Access: sid(0xe00) pid(0x6d) data_size(3)
135Apr 04 09:09:22 sirius mythbackend[10691]:   Stream #2 pid(0x63) type(0x81 audio-ac3)
136Apr 04 09:09:22 sirius mythbackend[10691]:     Registration Descriptor: 'AC-3' ATSC audio stream A/52
137Apr 04 09:09:22 sirius mythbackend[10691]:     ISO-639 Language: code(eng) canonical(eng) eng(English)
138Apr 04 09:09:22 sirius mythbackend[10691]:     Audio Stream Descriptor  full_srv(1) sample_rate(48kbps) bit_rate(=192kbps, 10)
139Apr 04 09:09:22 sirius mythbackend[10691]:       bsid(6) bs_mode(0) channels(<= 2) Dolby(Not indicated)
140Apr 04 09:09:22 sirius mythbackend[10691]:       mainID(0)
141Apr 04 09:09:22 sirius mythbackend[10691]:     Conditional Access: sid(0xe00) pid(0x6d) data_size(3)
142Apr 04 09:09:22 sirius mythbackend[10691]:   Stream #3 pid(0x64) type(0x86 splice)
143Apr 04 09:09:22 sirius mythbackend[10691]:     Conditional Access: sid(0xe00) pid(0x6d) data_size(3)
144Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.896576 D  MPEGStream[5](0x7fc9541a0fe0): PMT for output stream
145Apr 04 09:09:22 sirius mythbackend[10691]: 2018-04-04 09:09:22.896653 D  MPEGStream[5](0x7fc9541a0fe0): Program Map Section
146Apr 04 09:09:22 sirius mythbackend[10691]:  PSIP tableID(0x2) length(107) extension(0x1)
147Apr 04 09:09:22 sirius mythbackend[10691]:       version(1) current(1) section(0) last_section(0)
148Apr 04 09:09:22 sirius mythbackend[10691]:       pnum(1) pid(0x60) pcrpid(61)
149Apr 04 09:09:22 sirius mythbackend[10691]:   Registration Descriptor: 'GA94' ATSC program ID A/53
150Apr 04 09:09:22 sirius mythbackend[10691]:   Registration Descriptor: 'CUEI' SCTE 35 2003, Cable Digital Program Insertion Cueing Message
151Apr 04 09:09:22 sirius mythbackend[10691]:   Private Data Indicator Descriptor (0x0f) length(4). Dumping
152Apr 04 09:09:22 sirius mythbackend[10691]:       000  53 41 50 53                                       |SAPS|
153Apr 04 09:09:22 sirius mythbackend[10691]:   Stream #0 pid(0x61) type(0x02 video-mpeg2)
154Apr 04 09:09:22 sirius mythbackend[10691]:     Video Descriptor (0x02) length(3). Dumping
155Apr 04 09:09:22 sirius mythbackend[10691]:       000  c2 44 5f                                          |.D.|
156Apr 04 09:09:22 sirius mythbackend[10691]:     Data Stream Alignment Descriptor (0x06) length(1). Dumping
157Apr 04 09:09:22 sirius mythbackend[10691]:       000  02                                                |.|
158Apr 04 09:09:22 sirius mythbackend[10691]:   Stream #1 pid(0x62) type(0x81 audio-ac3)
159Apr 04 09:09:22 sirius mythbackend[10691]:     Registration Descriptor: 'AC-3' ATSC audio stream A/52
160Apr 04 09:09:22 sirius mythbackend[10691]:     ISO-639 Language: code(eng) canonical(eng) eng(English)
161Apr 04 09:09:22 sirius mythbackend[10691]:     Audio Stream Descriptor  full_srv(1) sample_rate(48kbps) bit_rate(=384kbps, 14)
162Apr 04 09:09:22 sirius mythbackend[10691]:       bsid(6) bs_mode(0) channels(1 + 1) Dolby(Not indicated)
163Apr 04 09:09:22 sirius mythbackend[10691]:       mainID(0) isLatin-1(true) text_length(95) text(TODO)
164Apr 04 09:09:22 sirius mythbackend[10691]:   Stream #2 pid(0x63) type(0x81 audio-ac3)
165Apr 04 09:09:22 sirius mythbackend[10691]:     Registration Descriptor: 'AC-3' ATSC audio stream A/52
166Apr 04 09:09:22 sirius mythbackend[10691]:     ISO-639 Language: code(eng) canonical(eng) eng(English)
167Apr 04 09:09:22 sirius mythbackend[10691]:     Audio Stream Descriptor  full_srv(1) sample_rate(48kbps) bit_rate(=192kbps, 10)
168Apr 04 09:09:22 sirius mythbackend[10691]:       bsid(6) bs_mode(0) channels(<= 2) Dolby(Not indicated)
169Apr 04 09:09:22 sirius mythbackend[10691]:       mainID(0)
170Apr 04 09:09:22 sirius mythbackend[10691]:   Stream #3 pid(0x64) type(0x86 splice)
171Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:22.997382 I  MPEGStream[5](0x7fc9541a0fe0): ProcessPAT: Good PAT seen after a bad PAT
172Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:22.997390 D  MPEGStream[5](0x7fc9541a0fe0): CreatePATSingleProgram()
173Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:22.997393 D  MPEGStream[5](0x7fc9541a0fe0): PAT in input stream
174Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:22.997410 D  MPEGStream[5](0x7fc9541a0fe0): Program Association Section
175Apr 04 09:09:23 sirius mythbackend[10691]:  PSIP tableID(0x0) length(13) extension(0x18e3)
176Apr 04 09:09:23 sirius mythbackend[10691]:       version(0) current(1) section(0) last_section(0)
177Apr 04 09:09:23 sirius mythbackend[10691]:       tsid(6371) programCount(1)
178Apr 04 09:09:23 sirius mythbackend[10691]:   program number   937 has PID 0x0060
179Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:22.997415 D  MPEGStream[5](0x7fc9541a0fe0): desired_program(937) pid(0x60)
180Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:22.997423 D  MPEGStream[5](0x7fc9541a0fe0): pmt_pid(0x60)
181Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:22.997427 D  MPEGStream[5](0x7fc9541a0fe0): PAT for output stream
182Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:22.997439 D  MPEGStream[5](0x7fc9541a0fe0): Program Association Section
183Apr 04 09:09:23 sirius mythbackend[10691]:  PSIP tableID(0x0) length(13) extension(0x18e3)
184Apr 04 09:09:23 sirius mythbackend[10691]:       version(0) current(1) section(0) last_section(0)
185Apr 04 09:09:23 sirius mythbackend[10691]:       tsid(6371) programCount(1)
186Apr 04 09:09:23 sirius mythbackend[10691]:   program number     1 has PID 0x0060
187Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.017592 D  MPEGStream[5](0x7fc9541a0fe0): PID 0x61 status: Decrypted
188Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.057858 D  MPEGStream[5](0x7fc9541a0fe0): PID 0x62 status: Decrypted
189Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.057870 D  MPEGStream[5](0x7fc9541a0fe0): Program 937 status: Decrypted
190Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.076282 I  TVRec[5]: TuningSignalCheck: Good signal
191Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.076323 I  TVRec[5]: SetRecordingStatus(Recording->Recording) on line 4056
192Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.076363 I  TVRec[5]: TeardownSignalMonitor() -- begin
193Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.126508 I  SH(14165FA2-2): RemoveListener(0x7fc9541a0fe0) -- begin
194Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.126517 I  SH(14165FA2-2): RemoveListener(0x7fc9541a0fe0) -- locked
195Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.126523 D  SH(14165FA2-2): Stopping
196Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.138392 I  HDHRSH(14165FA2-2): RunTS(): shutdown
197Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.138407 E  HDHRSH(14165FA2-2): UpdateFilters called in wrong tune mode
198Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.138693 I  HDHRSH(14165FA2-2): stream stats: packet_count=221 network_errors=0 transport_errors=0 sequence_errors=0 overflow_errors=0
199Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.138700 I  HDHRSH(14165FA2-2): RunTS(): end
200Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.138704 I  HDHRSH(14165FA2-2): Release tuner lock.
201Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.138907 D  SH(14165FA2-2): Stopped
202Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.138917 I  SH(14165FA2-2): RemoveListener(0x7fc9541a0fe0) -- end
203Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.138963 I  TVRec[5]: TeardownSignalMonitor() -- end
204Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.138978 I  TVRec[5]: ClearFlags(SignalMonitorRunning,) -> FrontendReady,RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,DummyRecorderRunning,RingBufferReady, @ tv_rec.cpp:4082
205Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.138989 I  TVRec[5]: ClearFlags(WaitingForSignal,) -> FrontendReady,RunMainLoop,CancelNextRecording,NeedToStartRecorder,DummyRecorderRunning,RingBufferReady, @ tv_rec.cpp:4084
206Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.139574 I  TVRec[5]: Starting Recorder
207Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.140192 I  TVRec[5]: FinishedRecording(1937_2018-04-04T13:09:22Z) Good quality
208Apr 04 09:09:23 sirius mythbackend[10691]:                         title: Invasion Roswell
209Apr 04 09:09:23 sirius mythbackend[10691]:                         in recgroup: LiveTV status: Recording:Recorded is_dummy finished_now
210Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.140658 I  Chain: Updated endtime for '1937_20180404130922' to 20180404130923
211Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.141443 I  TVRec[5]: ClearFlags(DummyRecorderRunning,) -> FrontendReady,RunMainLoop,CancelNextRecording,NeedToStartRecorder,RingBufferReady, @ tv_rec.cpp:4215
212Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.142127 D  Setting Lossless transcoding changed to 0
213Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.142144 D  Setting Resize video while transcoding changed to 0
214Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.142157 D  Setting Enable auto-transcode after recording changed to 0
215Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.143483 I  TVRec[5]: Using profile 'Live TV' to record
216Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.143831 I  TVRec[5]: SwitchLiveTVRingBuffer(discont 1, set_next_rec 0) curRec(1937_2018-04-04T13:09:22Z) curRec.size(0)
217Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.144060 I  TVRec[5]: GetProgramRingBufferForLiveTV()
218Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.146238 N  AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 14 min
219Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.158977 I  TVRec[5]: StartedRecording(1937_2018-04-04T13:09:23Z) fn(/storage/livetv/1937_20180404130923.ts)
220Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.160487 N  ApplyRecordRecGroupChange: LiveTV to LiveTV (2)
221Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.160696 I  Chain: Appended@1 '1937_20180404130923'
222Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.160813 I  TVRec[5]: TuningNewRecorder - CreateRecorder()
223Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.160823 I  DTVRec[5]: ResetForNewFile(void)
224Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.161545 E  RecBase[5](14165FA2-2): SetStrOption(...recordingtype): Option not in profile.
225Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.161560 I  RecBase[5](14165FA2-2): SetRingBuffer(0x7fc9541a0aa0) '/storage/livetv/1937_20180404130923.ts'
226Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.161566 I  RecBase[5](14165FA2-2): SetRecording(0x7fc954196820) title(Invasion Roswell)
227Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.162202 I  MPEGStream[5](0x7fc9541a0fe0): SetDesiredProgram(937)
228Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.162208 I  DTVRec[5]: SetPAT(937 on 0x60)
229Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.162215 D  MPEGStream[5](0x7fc9541a0fe0): CreatePATSingleProgram()
230Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.162218 D  MPEGStream[5](0x7fc9541a0fe0): PAT in input stream
231Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.162230 D  MPEGStream[5](0x7fc9541a0fe0): Program Association Section
232Apr 04 09:09:23 sirius mythbackend[10691]:  PSIP tableID(0x0) length(13) extension(0x18e3)
233Apr 04 09:09:23 sirius mythbackend[10691]:       version(0) current(1) section(0) last_section(0)
234Apr 04 09:09:23 sirius mythbackend[10691]:       tsid(6371) programCount(1)
235Apr 04 09:09:23 sirius mythbackend[10691]:   program number   937 has PID 0x0060
236Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.162234 D  MPEGStream[5](0x7fc9541a0fe0): desired_program(937) pid(0x60)
237Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.162250 D  MPEGStream[5](0x7fc9541a0fe0): pmt_pid(0x60)
238Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.162252 D  MPEGStream[5](0x7fc9541a0fe0): PAT for output stream
239Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.162258 D  MPEGStream[5](0x7fc9541a0fe0): Program Association Section
240Apr 04 09:09:23 sirius mythbackend[10691]:  PSIP tableID(0x0) length(13) extension(0x18e3)
241Apr 04 09:09:23 sirius mythbackend[10691]:       version(0) current(1) section(0) last_section(0)
242Apr 04 09:09:23 sirius mythbackend[10691]:       tsid(6371) programCount(1)
243Apr 04 09:09:23 sirius mythbackend[10691]:   program number     1 has PID 0x0060
244Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.162264 I  DTVRec[5]: SetPMT(937, valid)
245Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.162266 I  DTVRec[5]: SetPMT(937)
246Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.162272 D  MPEGStream[5](0x7fc9541a0fe0): CreatePMTSingleProgram()
247Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.162274 D  MPEGStream[5](0x7fc9541a0fe0): PMT in input stream
248Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.162321 D  MPEGStream[5](0x7fc9541a0fe0): Program Map Section
249Apr 04 09:09:23 sirius mythbackend[10691]:  PSIP tableID(0x2) length(143) extension(0x3a9)
250Apr 04 09:09:23 sirius mythbackend[10691]:       version(1) current(1) section(0) last_section(0)
251Apr 04 09:09:23 sirius mythbackend[10691]:       pnum(937) pid(0x60) pcrpid(61)
252Apr 04 09:09:23 sirius mythbackend[10691]:   Registration Descriptor: 'GA94' ATSC program ID A/53
253Apr 04 09:09:23 sirius mythbackend[10691]:   Registration Descriptor: 'CUEI' SCTE 35 2003, Cable Digital Program Insertion Cueing Message
254Apr 04 09:09:23 sirius mythbackend[10691]:   Private Data Indicator Descriptor (0x0f) length(4). Dumping
255Apr 04 09:09:23 sirius mythbackend[10691]:       000  53 41 50 53                                       |SAPS|
256Apr 04 09:09:23 sirius mythbackend[10691]:   Stream #0 pid(0x61) type(0x02 video-mpeg2)
257Apr 04 09:09:23 sirius mythbackend[10691]:     Video Descriptor (0x02) length(3). Dumping
258Apr 04 09:09:23 sirius mythbackend[10691]:       000  c2 44 5f                                          |.D.|
259Apr 04 09:09:23 sirius mythbackend[10691]:     Data Stream Alignment Descriptor (0x06) length(1). Dumping
260Apr 04 09:09:23 sirius mythbackend[10691]:       000  02                                                |.|
261Apr 04 09:09:23 sirius mythbackend[10691]:     Conditional Access: sid(0xe00) pid(0x6d) data_size(3)
262Apr 04 09:09:23 sirius mythbackend[10691]:   Stream #1 pid(0x62) type(0x81 audio-ac3)
263Apr 04 09:09:23 sirius mythbackend[10691]:     Registration Descriptor: 'AC-3' ATSC audio stream A/52
264Apr 04 09:09:23 sirius mythbackend[10691]:     ISO-639 Language: code(eng) canonical(eng) eng(English)
265Apr 04 09:09:23 sirius mythbackend[10691]:     Audio Stream Descriptor  full_srv(1) sample_rate(48kbps) bit_rate(=384kbps, 14)
266Apr 04 09:09:23 sirius mythbackend[10691]:       bsid(6) bs_mode(0) channels(1 + 1) Dolby(Not indicated)
267Apr 04 09:09:23 sirius mythbackend[10691]:       mainID(0) isLatin-1(true) text_length(95) text(TODO)
268Apr 04 09:09:23 sirius mythbackend[10691]:     Conditional Access: sid(0xe00) pid(0x6d) data_size(3)
269Apr 04 09:09:23 sirius mythbackend[10691]:   Stream #2 pid(0x63) type(0x81 audio-ac3)
270Apr 04 09:09:23 sirius mythbackend[10691]:     Registration Descriptor: 'AC-3' ATSC audio stream A/52
271Apr 04 09:09:23 sirius mythbackend[10691]:     ISO-639 Language: code(eng) canonical(eng) eng(English)
272Apr 04 09:09:23 sirius mythbackend[10691]:     Audio Stream Descriptor  full_srv(1) sample_rate(48kbps) bit_rate(=192kbps, 10)
273Apr 04 09:09:23 sirius mythbackend[10691]:       bsid(6) bs_mode(0) channels(<= 2) Dolby(Not indicated)
274Apr 04 09:09:23 sirius mythbackend[10691]:       mainID(0)
275Apr 04 09:09:23 sirius mythbackend[10691]:     Conditional Access: sid(0xe00) pid(0x6d) data_size(3)
276Apr 04 09:09:23 sirius mythbackend[10691]:   Stream #3 pid(0x64) type(0x86 splice)
277Apr 04 09:09:23 sirius mythbackend[10691]:     Conditional Access: sid(0xe00) pid(0x6d) data_size(3)
278Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.162339 D  MPEGStream[5](0x7fc9541a0fe0): PMT for output stream
279Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.162372 D  MPEGStream[5](0x7fc9541a0fe0): Program Map Section
280Apr 04 09:09:23 sirius mythbackend[10691]:  PSIP tableID(0x2) length(107) extension(0x1)
281Apr 04 09:09:23 sirius mythbackend[10691]:       version(1) current(1) section(0) last_section(0)
282Apr 04 09:09:23 sirius mythbackend[10691]:       pnum(1) pid(0x60) pcrpid(61)
283Apr 04 09:09:23 sirius mythbackend[10691]:   Registration Descriptor: 'GA94' ATSC program ID A/53
284Apr 04 09:09:23 sirius mythbackend[10691]:   Registration Descriptor: 'CUEI' SCTE 35 2003, Cable Digital Program Insertion Cueing Message
285Apr 04 09:09:23 sirius mythbackend[10691]:   Private Data Indicator Descriptor (0x0f) length(4). Dumping
286Apr 04 09:09:23 sirius mythbackend[10691]:       000  53 41 50 53                                       |SAPS|
287Apr 04 09:09:23 sirius mythbackend[10691]:   Stream #0 pid(0x61) type(0x02 video-mpeg2)
288Apr 04 09:09:23 sirius mythbackend[10691]:     Video Descriptor (0x02) length(3). Dumping
289Apr 04 09:09:23 sirius mythbackend[10691]:       000  c2 44 5f                                          |.D.|
290Apr 04 09:09:23 sirius mythbackend[10691]:     Data Stream Alignment Descriptor (0x06) length(1). Dumping
291Apr 04 09:09:23 sirius mythbackend[10691]:       000  02                                                |.|
292Apr 04 09:09:23 sirius mythbackend[10691]:   Stream #1 pid(0x62) type(0x81 audio-ac3)
293Apr 04 09:09:23 sirius mythbackend[10691]:     Registration Descriptor: 'AC-3' ATSC audio stream A/52
294Apr 04 09:09:23 sirius mythbackend[10691]:     ISO-639 Language: code(eng) canonical(eng) eng(English)
295Apr 04 09:09:23 sirius mythbackend[10691]:     Audio Stream Descriptor  full_srv(1) sample_rate(48kbps) bit_rate(=384kbps, 14)
296Apr 04 09:09:23 sirius mythbackend[10691]:       bsid(6) bs_mode(0) channels(1 + 1) Dolby(Not indicated)
297Apr 04 09:09:23 sirius mythbackend[10691]:       mainID(0) isLatin-1(true) text_length(95) text(TODO)
298Apr 04 09:09:23 sirius mythbackend[10691]:   Stream #2 pid(0x63) type(0x81 audio-ac3)
299Apr 04 09:09:23 sirius mythbackend[10691]:     Registration Descriptor: 'AC-3' ATSC audio stream A/52
300Apr 04 09:09:23 sirius mythbackend[10691]:     ISO-639 Language: code(eng) canonical(eng) eng(English)
301Apr 04 09:09:23 sirius mythbackend[10691]:     Audio Stream Descriptor  full_srv(1) sample_rate(48kbps) bit_rate(=192kbps, 10)
302Apr 04 09:09:23 sirius mythbackend[10691]:       bsid(6) bs_mode(0) channels(<= 2) Dolby(Not indicated)
303Apr 04 09:09:23 sirius mythbackend[10691]:       mainID(0)
304Apr 04 09:09:23 sirius mythbackend[10691]:   Stream #3 pid(0x64) type(0x86 splice)
305Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.163230 I  HDHRRec[5]: run -- begin
306Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.163237 I  DTVRec[5]: ResetForNewFile(void)
307Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.163245 I  HDHRSH: Using existing stream handler 14165FA2-2 for 14165FA2-2 (2 in use)
308Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.163252 I  HDHRRec[5]: HDHR opened successfully
309Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.163263 I  SH[5](14165FA2-2): AddListener(0x7fc9541a0fe0) -- begin
310Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.163268 I  TVRec[5]: SetFlags(RecorderRunning,RingBufferReady,) -> FrontendReady,RunMainLoop,CancelNextRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady, @ tv_rec.cpp:4352
311Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.163269 I  SH[5](14165FA2-2): AddListener(0x7fc9541a0fe0) -- locked
312Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.163274 I  TVRec[5]: ClearFlags(NeedToStartRecorder,) -> FrontendReady,RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady, @ tv_rec.cpp:4354
313Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.227771 I  HDHRSH[5](14165FA2-2): RunTS(): begin
314Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.227803 I  SH[5](14165FA2-2): AddListener(0x7fc9541a0fe0) -- end
315Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.268031 D  DTVRec[5]: Frame @ 376 + 0 = 376
316Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.288242 D  DTVRec[5]: Frame @ 376 + 0 = 376
317Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.288267 D  DTVRec[5]: Frame @ 376 + 0 = 376
318Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.288301 D  DTVRec[5]: Frame @ 376 + 0 = 376
319Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.308563 D  DTVRec[5]: Frame @ 376 + 0 = 376
320Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.308640 D  DTVRec[5]: Frame @ 376 + 0 = 376
321Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.328878 D  PortChecker::checkPort(): host localhost port 3306 timeLimit 30000 linkLocalOnly 1
322Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.331483 I  DTVRec[5]: FindMPEG2Keyframes: frame rate = 29970
323Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.332299 D  DTVRec[5]: Keyframe @ 376 + 188 = 564
324Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.332352 D  DTVRec[5]: Frame @ 376 + 188 = 564
325Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.332366 D  count=1 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=2 tick_base=0 _total_dur=33.3667
326Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.332371 I  DTVRec[5]: PID 0x61 Found Payload Start
327Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.332399 I  DTVRec[5]: PID 0x63 Found Payload Start
328Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.332407 I  DTVRec[5]: PID 0x62 Found Payload Start
329Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.655244 D  DTVRec[5]: Frame @ 352500 + 0 = 352500
330Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.655259 D  count=2 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=4 tick_base=0 _total_dur=66.7333
331Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.675455 D  DTVRec[5]: Frame @ 375060 + 0 = 375060
332Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.675470 D  count=3 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=6 tick_base=0 _total_dur=100.1
333Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.675486 D  DTVRec[5]: Updating timeOfLatestData elapsed(343) interval(2000)
334Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.675561 D  DTVRec[5]: Frame @ 396868 + 0 = 396868
335Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.675570 D  count=4 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=8 tick_base=0 _total_dur=133.467
336Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.736318 D  DTVRec[5]: Frame @ 454208 + 0 = 454208
337Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.736337 D  count=5 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=10 tick_base=0 _total_dur=166.833
338Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.756632 D  DTVRec[5]: Frame @ 476016 + 0 = 476016
339Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.756650 D  count=6 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=12 tick_base=0 _total_dur=200.2
340Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.776883 D  DTVRec[5]: Frame @ 496884 + 0 = 496884
341Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.776902 D  count=7 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=14 tick_base=0 _total_dur=233.567
342Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.797332 D  DTVRec[5]: Frame @ 568888 + 0 = 568888
343Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.797340 D  count=8 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=16 tick_base=0 _total_dur=266.933
344Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.797389 D  DTVRec[5]: Frame @ 591448 + 0 = 591448
345Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.797393 D  count=9 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=18 tick_base=0 _total_dur=300.3
346Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.797464 D  DTVRec[5]: Frame @ 614760 + 0 = 614760
347Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.797469 D  count=10 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=20 tick_base=0 _total_dur=333.667
348Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.817647 D  DTVRec[5]: Frame @ 679808 + 0 = 679808
349Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.817655 D  count=11 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=22 tick_base=0 _total_dur=367.033
350Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.817670 D  DTVRec[5]: Frame @ 686388 + 0 = 686388
351Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.817675 D  count=12 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=24 tick_base=0 _total_dur=400.4
352Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.837833 D  DTVRec[5]: Frame @ 707256 + 0 = 707256
353Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.837840 D  count=13 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=26 tick_base=0 _total_dur=433.767
354Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.858093 D  DTVRec[5]: Frame @ 776252 + 0 = 776252
355Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.858103 D  count=14 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=28 tick_base=0 _total_dur=467.133
356Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.858169 D  DTVRec[5]: Frame @ 797120 + 0 = 797120
357Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.858177 D  count=15 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=30 tick_base=0 _total_dur=500.5
358Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.858199 D  DTVRec[5]: Updating timeOfLatestData elapsed(182) interval(2250)
359Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.858237 D  DTVRec[5]: Keyframe @ 808212 + 188 = 808400
360Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.858242 D  DTVRec[5]: Frame @ 808212 + 188 = 808400
361Apr 04 09:09:23 sirius mythbackend[10691]: 2018-04-04 09:09:23.858250 D  count=16 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=32 tick_base=0 _total_dur=533.867
362Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.160853 D  DTVRec[5]: Frame @ 1162780 + 0 = 1162780
363Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.160873 D  count=17 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=34 tick_base=0 _total_dur=567.233
364Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.160977 D  DTVRec[5]: Frame @ 1182520 + 0 = 1182520
365Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.160988 D  count=18 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=36 tick_base=0 _total_dur=600.6
366Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.165259 D  No progstart mark needed because delta=-5963161
367Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.181187 D  DTVRec[5]: Frame @ 1202824 + 0 = 1202824
368Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.181201 D  count=19 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=38 tick_base=0 _total_dur=633.967
369Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.241873 D  DTVRec[5]: Frame @ 1265992 + 0 = 1265992
370Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.241890 D  count=20 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=40 tick_base=0 _total_dur=667.333
371Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.241982 D  DTVRec[5]: Updating timeOfLatestData elapsed(383) interval(2531)
372Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.262156 D  DTVRec[5]: Frame @ 1284228 + 0 = 1284228
373Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.262171 D  count=21 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=42 tick_base=0 _total_dur=700.7
374Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.262242 D  DTVRec[5]: Frame @ 1300584 + 0 = 1300584
375Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.262251 D  count=22 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=44 tick_base=0 _total_dur=734.067
376Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.282682 D  DTVRec[5]: Frame @ 1379920 + 0 = 1379920
377Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.282722 D  count=23 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=46 tick_base=0 _total_dur=767.433
378Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.302913 D  DTVRec[5]: Frame @ 1400976 + 0 = 1400976
379Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.302939 D  count=24 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=48 tick_base=0 _total_dur=800.8
380Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.303075 D  DTVRec[5]: Frame @ 1422784 + 0 = 1422784
381Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.303113 D  count=25 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=50 tick_base=0 _total_dur=834.167
382Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.323496 D  DTVRec[5]: Frame @ 1485012 + 0 = 1485012
383Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.323510 D  count=26 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=52 tick_base=0 _total_dur=867.533
384Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.323547 D  DTVRec[5]: Frame @ 1491780 + 0 = 1491780
385Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.323556 D  count=27 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=54 tick_base=0 _total_dur=900.9
386Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.323637 D  DTVRec[5]: Frame @ 1511144 + 0 = 1511144
387Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.323646 D  count=28 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=56 tick_base=0 _total_dur=934.267
388Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.344107 D  DTVRec[5]: Frame @ 1576192 + 0 = 1576192
389Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.344120 D  count=29 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=58 tick_base=0 _total_dur=967.633
390Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.344240 D  DTVRec[5]: Frame @ 1593676 + 0 = 1593676
391Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.344251 D  count=30 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=60 tick_base=0 _total_dur=1001
392Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.344284 D  DTVRec[5]: Keyframe @ 1599128 + 188 = 1599316
393Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.344294 D  DTVRec[5]: Frame @ 1599128 + 188 = 1599316
394Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.344303 D  count=31 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=62 tick_base=0 _total_dur=1034.37
395Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.485293 D  DTVRec[5]: Updating timeOfLatestData elapsed(243) interval(2847)
396Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.585945 D  DTVRec[5]: Frame @ 1942040 + 0 = 1942040
397Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.585959 D  count=32 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=64 tick_base=0 _total_dur=1067.73
398Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.626173 D  DTVRec[5]: Frame @ 1964788 + 0 = 1964788
399Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.626182 D  count=33 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=66 tick_base=0 _total_dur=1101.1
400Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.666428 D  DTVRec[5]: Frame @ 1986784 + 0 = 1986784
401Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.666445 D  count=34 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=68 tick_base=0 _total_dur=1134.47
402Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.706761 D  DTVRec[5]: Frame @ 2053524 + 0 = 2053524
403Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.706769 D  count=35 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=70 tick_base=0 _total_dur=1167.83
404Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.746979 D  DTVRec[5]: Frame @ 2073452 + 0 = 2073452
405Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.746987 D  count=36 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=72 tick_base=0 _total_dur=1201.2
406Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.767122 D  DTVRec[5]: Frame @ 2093944 + 0 = 2093944
407Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.767130 D  count=37 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=74 tick_base=0 _total_dur=1234.57
408Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.787325 D  DTVRec[5]: Frame @ 2174972 + 0 = 2174972
409Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.787333 D  count=38 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=76 tick_base=0 _total_dur=1267.93
410Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.787387 D  DTVRec[5]: Frame @ 2196780 + 0 = 2196780
411Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.787392 D  count=39 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=78 tick_base=0 _total_dur=1301.3
412Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.807542 D  DTVRec[5]: Frame @ 2218776 + 0 = 2218776
413Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.807550 D  count=40 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=80 tick_base=0 _total_dur=1334.67
414Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.807682 D  DTVRec[5]: Frame @ 2280816 + 0 = 2280816
415Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.807688 D  count=41 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=82 tick_base=0 _total_dur=1368.03
416Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.812895 D  RecBase[5](14165FA2-2): GetKeyframePositions(16,9223372036854775807,#1) out of 3
417Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.813205 D  RecBase[5](14165FA2-2): GetKeyframeDurations(16,9223372036854775807,#1) out of 3
418Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.819318 D  RecBase[5](14165FA2-2): GetKeyframePositions(16,9223372036854775807,#1) out of 3
419Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.819673 D  RecBase[5](14165FA2-2): GetKeyframeDurations(16,9223372036854775807,#1) out of 3
420Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.827816 D  DTVRec[5]: Frame @ 2286456 + 0 = 2286456
421Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.827834 D  count=42 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=84 tick_base=0 _total_dur=1401.4
422Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.827890 D  DTVRec[5]: Frame @ 2305444 + 0 = 2305444
423Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.827897 D  count=43 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=86 tick_base=0 _total_dur=1434.77
424Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.848244 D  DTVRec[5]: Frame @ 2373312 + 0 = 2373312
425Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.848256 D  count=44 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=88 tick_base=0 _total_dur=1468.13
426Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.848304 D  DTVRec[5]: Frame @ 2391172 + 0 = 2391172
427Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.848310 D  count=45 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=90 tick_base=0 _total_dur=1501.5
428Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.848338 D  DTVRec[5]: Keyframe @ 2396436 + 0 = 2396436
429Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.848345 D  DTVRec[5]: Frame @ 2396436 + 0 = 2396436
430Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.848351 D  count=46 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=92 tick_base=0 _total_dur=1534.87
431Apr 04 09:09:24 sirius mythbackend[10691]: 2018-04-04 09:09:24.868562 D  DTVRec[5]: Updating timeOfLatestData elapsed(383) interval(3202)
432Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.191772 D  DTVRec[5]: Frame @ 2848200 + 0 = 2848200
433Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.191793 D  count=47 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=94 tick_base=0 _total_dur=1568.23
434Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.272429 D  DTVRec[5]: Frame @ 2882980 + 0 = 2882980
435Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.272446 D  count=48 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=96 tick_base=0 _total_dur=1601.6
436Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.272624 D  DTVRec[5]: Frame @ 2919264 + 0 = 2919264
437Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.272634 D  count=49 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=98 tick_base=0 _total_dur=1634.97
438Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.313386 D  DTVRec[5]: Frame @ 3012136 + 0 = 3012136
439Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.313404 D  count=50 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=100 tick_base=0 _total_dur=1668.33
440Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.313489 D  DTVRec[5]: Frame @ 3030936 + 0 = 3030936
441Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.313499 D  count=51 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=102 tick_base=0 _total_dur=1701.7
442Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.313594 D  DTVRec[5]: Frame @ 3050300 + 0 = 3050300
443Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.313604 D  count=52 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=104 tick_base=0 _total_dur=1735.07
444Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.333942 D  DTVRec[5]: Updating timeOfLatestData elapsed(465) interval(3602)
445Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.354498 D  DTVRec[5]: Frame @ 3180960 + 0 = 3180960
446Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.354516 D  count=53 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=106 tick_base=0 _total_dur=1768.43
447Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.354652 D  DTVRec[5]: Frame @ 3208596 + 0 = 3208596
448Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.354663 D  count=54 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=108 tick_base=0 _total_dur=1801.8
449Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.374965 D  DTVRec[5]: Frame @ 3238112 + 0 = 3238112
450Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.374982 D  count=55 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=110 tick_base=0 _total_dur=1835.17
451Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.435853 D  DTVRec[5]: Frame @ 3344896 + 0 = 3344896
452Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.435871 D  count=56 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=112 tick_base=0 _total_dur=1868.53
453Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.435903 D  DTVRec[5]: Frame @ 3351100 + 0 = 3351100
454Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.435912 D  count=57 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=114 tick_base=0 _total_dur=1901.9
455Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.456182 D  DTVRec[5]: Frame @ 3376856 + 0 = 3376856
456Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.456200 D  count=58 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=116 tick_base=0 _total_dur=1935.27
457Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.537209 D  DTVRec[5]: Frame @ 3485144 + 0 = 3485144
458Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.537228 D  count=59 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=118 tick_base=0 _total_dur=1968.63
459Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.557454 D  DTVRec[5]: Frame @ 3508456 + 0 = 3508456
460Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.557470 D  count=60 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=120 tick_base=0 _total_dur=2002
461Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.577670 D  DTVRec[5]: Keyframe @ 3514284 + 188 = 3514472
462Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.577683 D  DTVRec[5]: Frame @ 3514284 + 188 = 3514472
463Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.577698 D  count=61 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=122 tick_base=0 _total_dur=2035.37
464Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.820275 D  DTVRec[5]: Updating timeOfLatestData elapsed(486) interval(4052)
465Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.840695 D  DTVRec[5]: Frame @ 3924500 + 0 = 3924500
466Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.840716 D  count=62 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=124 tick_base=0 _total_dur=2068.73
467Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.840835 D  DTVRec[5]: Frame @ 3944804 + 0 = 3944804
468Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.840846 D  count=63 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=126 tick_base=0 _total_dur=2102.1
469Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.840945 D  DTVRec[5]: Frame @ 3963604 + 0 = 3963604
470Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.840954 D  count=64 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=128 tick_base=0 _total_dur=2135.47
471Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.861411 D  DTVRec[5]: Frame @ 4023012 + 0 = 4023012
472Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.861430 D  count=65 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=130 tick_base=0 _total_dur=2168.83
473Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.861517 D  DTVRec[5]: Frame @ 4043128 + 0 = 4043128
474Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.861528 D  count=66 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=132 tick_base=0 _total_dur=2202.2
475Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.881809 D  DTVRec[5]: Frame @ 4063432 + 0 = 4063432
476Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.881827 D  count=67 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=134 tick_base=0 _total_dur=2235.57
477Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.902313 D  DTVRec[5]: Frame @ 4135060 + 0 = 4135060
478Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.902333 D  count=68 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=136 tick_base=0 _total_dur=2268.93
479Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.902418 D  DTVRec[5]: Frame @ 4157432 + 0 = 4157432
480Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.902428 D  count=69 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=138 tick_base=0 _total_dur=2302.3
481Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.902511 D  DTVRec[5]: Frame @ 4179992 + 0 = 4179992
482Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.902520 D  count=70 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=140 tick_base=0 _total_dur=2335.67
483Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.922862 D  DTVRec[5]: Frame @ 4238084 + 0 = 4238084
484Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.922881 D  count=71 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=142 tick_base=0 _total_dur=2369.03
485Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.922923 D  DTVRec[5]: Frame @ 4245416 + 0 = 4245416
486Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.922950 D  count=72 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=144 tick_base=0 _total_dur=2402.4
487Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.943219 D  DTVRec[5]: Frame @ 4266096 + 0 = 4266096
488Apr 04 09:09:25 sirius mythbackend[10691]: 2018-04-04 09:09:25.943237 D  count=73 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=146 tick_base=0 _total_dur=2435.77
489Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:25.983812 D  DTVRec[5]: Frame @ 4329452 + 0 = 4329452
490Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:25.983830 D  count=74 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=148 tick_base=0 _total_dur=2469.13
491Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:25.983945 D  DTVRec[5]: Frame @ 4350696 + 0 = 4350696
492Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:25.983955 D  count=75 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=150 tick_base=0 _total_dur=2502.5
493Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.004142 D  DTVRec[5]: Keyframe @ 4359344 + 188 = 4359532
494Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.004155 D  DTVRec[5]: Frame @ 4359344 + 188 = 4359532
495Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.004170 D  count=76 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=152 tick_base=0 _total_dur=2535.87
496Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.167972 D  No progstart mark needed because delta=-5963161
497Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.186419 D  DTVRec[5]: Updating timeOfLatestData elapsed(366) interval(4558)
498Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.206683 D  DTVRec[5]: Frame @ 4753392 + 0 = 4753392
499Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.206699 D  count=77 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=154 tick_base=0 _total_dur=2569.23
500Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.247050 D  DTVRec[5]: Frame @ 4777080 + 0 = 4777080
501Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.247069 D  count=78 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=156 tick_base=0 _total_dur=2602.6
502Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.267313 D  DTVRec[5]: Frame @ 4797572 + 0 = 4797572
503Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.267336 D  count=79 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=158 tick_base=0 _total_dur=2635.97
504Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.287774 D  DTVRec[5]: Frame @ 4848144 + 0 = 4848144
505Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.287791 D  count=80 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=160 tick_base=0 _total_dur=2669.33
506Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.287870 D  DTVRec[5]: Frame @ 4866192 + 0 = 4866192
507Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.287879 D  count=81 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=162 tick_base=0 _total_dur=2702.7
508Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.287981 D  DTVRec[5]: Frame @ 4884428 + 0 = 4884428
509Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.287990 D  count=82 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=164 tick_base=0 _total_dur=2736.07
510Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.308418 D  DTVRec[5]: Frame @ 4947220 + 0 = 4947220
511Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.308449 D  count=83 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=166 tick_base=0 _total_dur=2769.43
512Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.308591 D  DTVRec[5]: Frame @ 4968464 + 0 = 4968464
513Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.308603 D  count=84 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=168 tick_base=0 _total_dur=2802.8
514Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.328864 D  DTVRec[5]: Frame @ 4989896 + 0 = 4989896
515Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.328886 D  count=85 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=170 tick_base=0 _total_dur=2836.17
516Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.329163 D  DTVRec[5]: Frame @ 5039340 + 0 = 5039340
517Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.329176 D  count=86 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=172 tick_base=0 _total_dur=2869.53
518Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.329242 D  DTVRec[5]: Frame @ 5051184 + 0 = 5051184
519Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.329252 D  count=87 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=174 tick_base=0 _total_dur=2902.9
520Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.349480 D  DTVRec[5]: Frame @ 5071676 + 0 = 5071676
521Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.349499 D  count=88 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=176 tick_base=0 _total_dur=2936.27
522Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.349750 D  DTVRec[5]: Frame @ 5122812 + 0 = 5122812
523Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.349763 D  count=89 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=178 tick_base=0 _total_dur=2969.63
524Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.370002 D  DTVRec[5]: Frame @ 5142176 + 0 = 5142176
525Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.370019 D  count=90 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=180 tick_base=0 _total_dur=3003
526Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.370132 D  DTVRec[5]: Keyframe @ 5154208 + 188 = 5154396
527Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.370145 D  DTVRec[5]: Frame @ 5154208 + 188 = 5154396
528Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.370157 D  count=91 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=182 tick_base=0 _total_dur=3036.37
529Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.673084 D  DTVRec[5]: Frame @ 5574952 + 0 = 5574952
530Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.673106 D  count=92 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=184 tick_base=0 _total_dur=3069.73
531Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.693341 D  DTVRec[5]: Frame @ 5595444 + 0 = 5595444
532Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.693358 D  count=93 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=186 tick_base=0 _total_dur=3103.1
533Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.713567 D  DTVRec[5]: Frame @ 5613680 + 0 = 5613680
534Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.713585 D  count=94 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=188 tick_base=0 _total_dur=3136.47
535Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.774186 D  DTVRec[5]: Frame @ 5660492 + 0 = 5660492
536Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.774208 D  count=95 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=190 tick_base=0 _total_dur=3169.83
537Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.774270 D  DTVRec[5]: Updating timeOfLatestData elapsed(587) interval(5127)
538Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.774298 D  DTVRec[5]: Frame @ 5678164 + 0 = 5678164
539Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.774310 D  count=96 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=192 tick_base=0 _total_dur=3203.2
540Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.794592 D  DTVRec[5]: Frame @ 5695272 + 0 = 5695272
541Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.794621 D  count=97 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=194 tick_base=0 _total_dur=3236.57
542Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.795017 D  DTVRec[5]: Frame @ 5749040 + 0 = 5749040
543Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.795037 D  count=98 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=196 tick_base=0 _total_dur=3269.93
544Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.795186 D  DTVRec[5]: Frame @ 5768968 + 0 = 5768968
545Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.795203 D  count=99 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=198 tick_base=0 _total_dur=3303.3
546Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.815467 D  DTVRec[5]: Frame @ 5789272 + 0 = 5789272
547Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.815491 D  count=100 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=200 tick_base=0 _total_dur=3336.67
548Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.815701 D  DTVRec[5]: Frame @ 5836084 + 0 = 5836084
549Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.815714 D  count=101 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=202 tick_base=0 _total_dur=3370.03
550Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.835962 D  DTVRec[5]: Frame @ 5849244 + 0 = 5849244
551Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.835989 D  count=102 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=204 tick_base=0 _total_dur=3403.4
552Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.836077 D  DTVRec[5]: Keyframe @ 5868044 + 188 = 5868232
553Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.836087 D  DTVRec[5]: Frame @ 5868044 + 188 = 5868232
554Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.836098 D  count=103 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=206 tick_base=0 _total_dur=3436.77
555Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.856695 D  DTVRec[5]: Frame @ 5975392 + 0 = 5975392
556Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.856712 D  count=104 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=208 tick_base=0 _total_dur=3470.13
557Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.856788 D  DTVRec[5]: Frame @ 5988364 + 0 = 5988364
558Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.856799 D  count=105 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=210 tick_base=0 _total_dur=3503.5
559Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.856828 D  DTVRec[5]: Keyframe @ 5989868 + 188 = 5990056
560Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.856837 D  DTVRec[5]: Frame @ 5989868 + 188 = 5990056
561Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.856847 D  count=106 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=212 tick_base=0 _total_dur=3536.87
562Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.918085 D  DTVRec[5]: Frame @ 6125416 + 0 = 6125416
563Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.918106 D  count=107 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=214 tick_base=0 _total_dur=3570.23
564Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.938287 D  DTVRec[5]: Frame @ 6138200 + 0 = 6138200
565Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.938305 D  count=108 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=216 tick_base=0 _total_dur=3603.6
566Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.958503 D  DTVRec[5]: Frame @ 6152488 + 0 = 6152488
567Apr 04 09:09:26 sirius mythbackend[10691]: 2018-04-04 09:09:26.958522 D  count=109 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=218 tick_base=0 _total_dur=3636.97
568Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:26.998935 D  DTVRec[5]: Frame @ 6186140 + 0 = 6186140
569Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:26.998952 D  count=110 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=220 tick_base=0 _total_dur=3670.33
570Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:26.999014 D  DTVRec[5]: Frame @ 6200428 + 0 = 6200428
571Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:26.999024 D  count=111 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=222 tick_base=0 _total_dur=3703.7
572Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.019212 D  DTVRec[5]: Frame @ 6216032 + 0 = 6216032
573Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.019222 D  count=112 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=224 tick_base=0 _total_dur=3737.07
574Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.079620 D  DTVRec[5]: Frame @ 6283524 + 0 = 6283524
575Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.079629 D  count=113 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=226 tick_base=0 _total_dur=3770.43
576Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.099848 D  DTVRec[5]: Frame @ 6309844 + 0 = 6309844
577Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.099863 D  count=114 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=228 tick_base=0 _total_dur=3803.8
578Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.160375 D  DTVRec[5]: Frame @ 6337856 + 0 = 6337856
579Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.160392 D  count=115 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=230 tick_base=0 _total_dur=3837.17
580Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.180656 D  DTVRec[5]: Frame @ 6390684 + 0 = 6390684
581Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.180674 D  count=116 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=232 tick_base=0 _total_dur=3870.53
582Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.221096 D  DTVRec[5]: Frame @ 6412492 + 0 = 6412492
583Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.221117 D  count=117 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=234 tick_base=0 _total_dur=3903.9
584Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.261496 D  DTVRec[5]: Frame @ 6442008 + 0 = 6442008
585Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.261514 D  count=118 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=236 tick_base=0 _total_dur=3937.27
586Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.281943 D  DTVRec[5]: Frame @ 6494836 + 0 = 6494836
587Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.281962 D  count=119 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=238 tick_base=0 _total_dur=3970.63
588Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.282091 D  DTVRec[5]: Frame @ 6520968 + 0 = 6520968
589Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.282101 D  count=120 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=240 tick_base=0 _total_dur=4004
590Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.302363 D  DTVRec[5]: Keyframe @ 6543904 + 188 = 6544092
591Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.302375 D  DTVRec[5]: Frame @ 6543904 + 188 = 6544092
592Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.302390 D  count=121 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=242 tick_base=0 _total_dur=4037.37
593Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.363555 D  DTVRec[5]: Updating timeOfLatestData elapsed(589) interval(5767)
594Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.404186 D  DTVRec[5]: Frame @ 6897156 + 0 = 6897156
595Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.404205 D  count=122 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=244 tick_base=0 _total_dur=4070.73
596Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.404325 D  DTVRec[5]: Frame @ 6916708 + 0 = 6916708
597Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.404335 D  count=123 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=246 tick_base=0 _total_dur=4104.1
598Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.444693 D  DTVRec[5]: Frame @ 6937576 + 0 = 6937576
599Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.444711 D  count=124 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=248 tick_base=0 _total_dur=4137.47
600Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.485221 D  DTVRec[5]: Frame @ 6981756 + 0 = 6981756
601Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.485241 D  count=125 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=250 tick_base=0 _total_dur=4170.83
602Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.485319 D  DTVRec[5]: Frame @ 6998676 + 0 = 6998676
603Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.485329 D  count=126 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=252 tick_base=0 _total_dur=4204.2
604Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.505539 D  DTVRec[5]: Frame @ 7015784 + 0 = 7015784
605Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.505556 D  count=127 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=254 tick_base=0 _total_dur=4237.57
606Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.566239 D  DTVRec[5]: Frame @ 7081396 + 0 = 7081396
607Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.566256 D  count=128 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=256 tick_base=0 _total_dur=4270.93
608Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.586495 D  DTVRec[5]: Frame @ 7101888 + 0 = 7101888
609Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.586512 D  count=129 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=258 tick_base=0 _total_dur=4304.3
610Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.647066 D  DTVRec[5]: Frame @ 7125576 + 0 = 7125576
611Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.647086 D  count=130 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=260 tick_base=0 _total_dur=4337.67
612Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.687528 D  DTVRec[5]: Frame @ 7192504 + 0 = 7192504
613Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.687546 D  count=131 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=262 tick_base=0 _total_dur=4371.03
614Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.687622 D  DTVRec[5]: Frame @ 7200212 + 0 = 7200212
615Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.687632 D  count=132 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=264 tick_base=0 _total_dur=4404.4
616Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.707884 D  DTVRec[5]: Frame @ 7224088 + 0 = 7224088
617Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.707900 D  count=133 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=266 tick_base=0 _total_dur=4437.77
618Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.768637 D  DTVRec[5]: Frame @ 7293648 + 0 = 7293648
619Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.768654 D  count=134 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=268 tick_base=0 _total_dur=4471.13
620Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.768747 D  DTVRec[5]: Frame @ 7315080 + 0 = 7315080
621Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.768758 D  count=135 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=270 tick_base=0 _total_dur=4504.5
622Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.788971 D  DTVRec[5]: Keyframe @ 7325608 + 188 = 7325796
623Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.788984 D  DTVRec[5]: Frame @ 7325608 + 188 = 7325796
624Apr 04 09:09:27 sirius mythbackend[10691]: 2018-04-04 09:09:27.788998 D  count=136 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=272 tick_base=0 _total_dur=4537.87
625Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.132087 D  DTVRec[5]: Frame @ 7708188 + 0 = 7708188
626Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.132109 D  count=137 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=274 tick_base=0 _total_dur=4571.23
627Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.170649 D  No progstart mark needed because delta=-5963161
628Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.172464 D  DTVRec[5]: Frame @ 7730748 + 0 = 7730748
629Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.172482 D  count=138 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=276 tick_base=0 _total_dur=4604.6
630Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.192746 D  DTVRec[5]: Frame @ 7751428 + 0 = 7751428
631Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.192772 D  count=139 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=278 tick_base=0 _total_dur=4637.97
632Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.253489 D  DTVRec[5]: Frame @ 7816476 + 0 = 7816476
633Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.253508 D  count=140 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=280 tick_base=0 _total_dur=4671.33
634Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.273729 D  DTVRec[5]: Frame @ 7838284 + 0 = 7838284
635Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.273747 D  count=141 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=282 tick_base=0 _total_dur=4704.7
636Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.273834 D  DTVRec[5]: Frame @ 7858588 + 0 = 7858588
637Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.273845 D  count=142 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=284 tick_base=0 _total_dur=4738.07
638Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.294213 D  DTVRec[5]: Frame @ 7929276 + 0 = 7929276
639Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.294232 D  count=143 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=286 tick_base=0 _total_dur=4771.43
640Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.294368 D  DTVRec[5]: Frame @ 7953528 + 0 = 7953528
641Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.294380 D  count=144 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=288 tick_base=0 _total_dur=4804.8
642Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.314621 D  DTVRec[5]: Keyframe @ 7976840 + 188 = 7977028
643Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.314635 D  DTVRec[5]: Frame @ 7976840 + 188 = 7977028
644Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.314650 D  count=145 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=290 tick_base=0 _total_dur=4838.17
645Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.314664 D  DTVRec[5]: Updating timeOfLatestData elapsed(951) interval(6487)
646Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.355627 D  DTVRec[5]: Frame @ 8119908 + 0 = 8119908
647Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.355645 D  count=146 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=292 tick_base=0 _total_dur=4871.53
648Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.355678 D  DTVRec[5]: Frame @ 8125172 + 0 = 8125172
649Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.355687 D  count=147 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=294 tick_base=0 _total_dur=4904.9
650Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.355870 D  DTVRec[5]: Frame @ 8159764 + 0 = 8159764
651Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.355880 D  count=148 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=296 tick_base=0 _total_dur=4938.27
652Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.376185 D  DTVRec[5]: Frame @ 8200184 + 0 = 8200184
653Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.376204 D  count=149 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=298 tick_base=0 _total_dur=4971.63
654Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.396425 D  DTVRec[5]: Frame @ 8212780 + 0 = 8212780
655Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.396442 D  count=150 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=300 tick_base=0 _total_dur=5005
656Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.396487 D  DTVRec[5]: Keyframe @ 8220864 + 188 = 8221052
657Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.396496 D  DTVRec[5]: Frame @ 8220864 + 188 = 8221052
658Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.396506 D  count=151 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=302 tick_base=0 _total_dur=5038.37
659Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.578221 D  DTVRec[5]: Frame @ 8421272 + 0 = 8421272
660Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.578241 D  count=152 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=304 tick_base=0 _total_dur=5071.73
661Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.578326 D  DTVRec[5]: Frame @ 8434996 + 0 = 8434996
662Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.578337 D  count=153 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=306 tick_base=0 _total_dur=5105.1
663Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.578407 D  DTVRec[5]: Frame @ 8448344 + 0 = 8448344
664Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.578416 D  count=154 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=308 tick_base=0 _total_dur=5138.47
665Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.638917 D  DTVRec[5]: Frame @ 8481244 + 0 = 8481244
666Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.638954 D  count=155 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=310 tick_base=0 _total_dur=5171.83
667Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.639039 D  DTVRec[5]: Frame @ 8493464 + 0 = 8493464
668Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.639050 D  count=156 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=312 tick_base=0 _total_dur=5205.2
669Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.659239 D  DTVRec[5]: Frame @ 8506248 + 0 = 8506248
670Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.659256 D  count=157 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=314 tick_base=0 _total_dur=5238.57
671Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.679578 D  DTVRec[5]: Frame @ 8549676 + 0 = 8549676
672Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.679596 D  count=158 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=316 tick_base=0 _total_dur=5271.93
673Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.699856 D  DTVRec[5]: Frame @ 8573928 + 0 = 8573928
674Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.699875 D  count=159 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=318 tick_base=0 _total_dur=5305.3
675Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.740262 D  DTVRec[5]: Frame @ 8601000 + 0 = 8601000
676Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.740279 D  count=160 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=320 tick_base=0 _total_dur=5338.67
677Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.780662 D  DTVRec[5]: Frame @ 8654392 + 0 = 8654392
678Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.780681 D  count=161 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=322 tick_base=0 _total_dur=5372.03
679Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.800926 D  DTVRec[5]: Frame @ 8685600 + 0 = 8685600
680Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.800942 D  count=162 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=324 tick_base=0 _total_dur=5405.4
681Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.801059 D  DTVRec[5]: Frame @ 8718876 + 0 = 8718876
682Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.801067 D  count=163 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=326 tick_base=0 _total_dur=5438.77
683Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.821492 D  DTVRec[5]: Frame @ 8772080 + 0 = 8772080
684Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.821518 D  count=164 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=328 tick_base=0 _total_dur=5472.13
685Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.841880 D  DTVRec[5]: Frame @ 8809680 + 0 = 8809680
686Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.841896 D  count=165 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=330 tick_base=0 _total_dur=5505.5
687Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.862174 D  DTVRec[5]: Keyframe @ 8840324 + 188 = 8840512
688Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.862186 D  DTVRec[5]: Frame @ 8840324 + 188 = 8840512
689Apr 04 09:09:28 sirius mythbackend[10691]: 2018-04-04 09:09:28.862200 D  count=166 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=332 tick_base=0 _total_dur=5538.87
690Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.044745 D  DTVRec[5]: Frame @ 9229672 + 0 = 9229672
691Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.044773 D  count=167 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=334 tick_base=0 _total_dur=5572.23
692Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.064992 D  DTVRec[5]: Frame @ 9247532 + 0 = 9247532
693Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.065006 D  count=168 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=336 tick_base=0 _total_dur=5605.6
694Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.085221 D  DTVRec[5]: Frame @ 9263700 + 0 = 9263700
695Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.085238 D  count=169 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=338 tick_base=0 _total_dur=5638.97
696Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.085390 D  DTVRec[5]: Frame @ 9296036 + 0 = 9296036
697Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.085401 D  count=170 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=340 tick_base=0 _total_dur=5672.33
698Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.085469 D  DTVRec[5]: Frame @ 9307316 + 0 = 9307316
699Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.085479 D  count=171 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=342 tick_base=0 _total_dur=5705.7
700Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.105669 D  DTVRec[5]: Frame @ 9319348 + 0 = 9319348
701Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.105689 D  count=172 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=344 tick_base=0 _total_dur=5739.07
702Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.105835 D  DTVRec[5]: Updating timeOfLatestData elapsed(791) interval(7297)
703Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.126096 D  DTVRec[5]: Frame @ 9366724 + 0 = 9366724
704Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.126112 D  count=173 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=346 tick_base=0 _total_dur=5772.43
705Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.126185 D  DTVRec[5]: Frame @ 9385712 + 0 = 9385712
706Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.126194 D  count=174 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=348 tick_base=0 _total_dur=5805.8
707Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.146428 D  DTVRec[5]: Frame @ 9407896 + 0 = 9407896
708Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.146444 D  count=175 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=350 tick_base=0 _total_dur=5839.17
709Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.187008 D  DTVRec[5]: Frame @ 9464672 + 0 = 9464672
710Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.187024 D  count=176 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=352 tick_base=0 _total_dur=5872.53
711Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.247558 D  DTVRec[5]: Frame @ 9497572 + 0 = 9497572
712Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.247578 D  count=177 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=354 tick_base=0 _total_dur=5905.9
713Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.267836 D  DTVRec[5]: Frame @ 9527840 + 0 = 9527840
714Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.267853 D  count=178 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=356 tick_base=0 _total_dur=5939.27
715Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.288272 D  DTVRec[5]: Frame @ 9586120 + 0 = 9586120
716Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.288289 D  count=179 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=358 tick_base=0 _total_dur=5972.63
717Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.308562 D  DTVRec[5]: Frame @ 9618832 + 0 = 9618832
718Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.308580 D  count=180 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=360 tick_base=0 _total_dur=6006
719Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.308719 D  DTVRec[5]: Keyframe @ 9643460 + 188 = 9643648
720Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.308729 D  DTVRec[5]: Frame @ 9643460 + 188 = 9643648
721Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.308741 D  count=181 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=362 tick_base=0 _total_dur=6039.37
722Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.712436 D  DTVRec[5]: Frame @ 10045216 + 0 = 10045216
723Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.712457 D  count=182 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=364 tick_base=0 _total_dur=6072.73
724Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.712549 D  DTVRec[5]: Frame @ 10061572 + 0 = 10061572
725Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.712560 D  count=183 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=366 tick_base=0 _total_dur=6106.1
726Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.732746 D  DTVRec[5]: Frame @ 10077552 + 0 = 10077552
727Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.732762 D  count=184 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=368 tick_base=0 _total_dur=6139.47
728Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.773179 D  DTVRec[5]: Frame @ 10119100 + 0 = 10119100
729Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.773197 D  count=185 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=370 tick_base=0 _total_dur=6172.83
730Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.773273 D  DTVRec[5]: Frame @ 10129816 + 0 = 10129816
731Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.773284 D  count=186 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=372 tick_base=0 _total_dur=6206.2
732Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.773334 D  DTVRec[5]: Frame @ 10140720 + 0 = 10140720
733Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.773343 D  count=187 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=374 tick_base=0 _total_dur=6239.57
734Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.793811 D  DTVRec[5]: Frame @ 10194864 + 0 = 10194864
735Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.793824 D  count=188 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=376 tick_base=0 _total_dur=6272.93
736Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.814003 D  DTVRec[5]: Frame @ 10214792 + 0 = 10214792
737Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.814019 D  count=189 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=378 tick_base=0 _total_dur=6306.3
738Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.814132 D  DTVRec[5]: Frame @ 10238292 + 0 = 10238292
739Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.814143 D  count=190 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=380 tick_base=0 _total_dur=6339.67
740Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.854582 D  DTVRec[5]: Frame @ 10292248 + 0 = 10292248
741Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.854598 D  count=191 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=382 tick_base=0 _total_dur=6373.03
742Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.854690 D  DTVRec[5]: Frame @ 10313868 + 0 = 10313868
743Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.854700 D  count=192 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=384 tick_base=0 _total_dur=6406.4
744Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.874955 D  DTVRec[5]: Frame @ 10342820 + 0 = 10342820
745Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.874969 D  count=193 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=386 tick_base=0 _total_dur=6439.77
746Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.895358 D  DTVRec[5]: Frame @ 10401100 + 0 = 10401100
747Apr 04 09:09:29 sirius mythbackend[10691]: 2018-04-04 09:09:29.895374 D  count=194 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=388 tick_base=0 _total_dur=6473.13
748Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:29.915609 D  DTVRec[5]: Frame @ 10429300 + 0 = 10429300
749Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:29.915622 D  count=195 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=390 tick_base=0 _total_dur=6506.5
750Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:29.915704 D  DTVRec[5]: Keyframe @ 10451484 + 188 = 10451672
751Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:29.915713 D  DTVRec[5]: Frame @ 10451484 + 188 = 10451672
752Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:29.915722 D  count=196 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=392 tick_base=0 _total_dur=6539.87
753Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.173216 D  No progstart mark needed because delta=-5963161
754Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.199015 D  DTVRec[5]: Frame @ 10859444 + 0 = 10859444
755Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.199035 D  count=197 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=394 tick_base=0 _total_dur=6573.23
756Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.199136 D  DTVRec[5]: Frame @ 10876176 + 0 = 10876176
757Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.199147 D  count=198 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=396 tick_base=0 _total_dur=6606.6
758Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.219324 D  DTVRec[5]: Frame @ 10893096 + 0 = 10893096
759Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.219342 D  count=199 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=398 tick_base=0 _total_dur=6639.97
760Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.219353 D  DTVRec[5]: Updating timeOfLatestData elapsed(1113) interval(8209)
761Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.279966 D  DTVRec[5]: Frame @ 10934832 + 0 = 10934832
762Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.279984 D  count=200 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=400 tick_base=0 _total_dur=6673.33
763Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.280052 D  DTVRec[5]: Frame @ 10946300 + 0 = 10946300
764Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.280060 D  count=201 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=402 tick_base=0 _total_dur=6706.7
765Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.280118 D  DTVRec[5]: Frame @ 10958332 + 0 = 10958332
766Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.280127 D  count=202 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=404 tick_base=0 _total_dur=6740.07
767Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.300498 D  DTVRec[5]: Frame @ 11015672 + 0 = 11015672
768Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.300507 D  count=203 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=406 tick_base=0 _total_dur=6773.43
769Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.300557 D  DTVRec[5]: Frame @ 11036916 + 0 = 11036916
770Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.300562 D  count=204 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=408 tick_base=0 _total_dur=6806.8
771Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.300613 D  DTVRec[5]: Frame @ 11060416 + 0 = 11060416
772Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.300618 D  count=205 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=410 tick_base=0 _total_dur=6840.17
773Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.320878 D  DTVRec[5]: Frame @ 11112492 + 0 = 11112492
774Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.320891 D  count=206 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=412 tick_base=0 _total_dur=6873.53
775Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.320960 D  DTVRec[5]: Frame @ 11124524 + 0 = 11124524
776Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.320967 D  count=207 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=414 tick_base=0 _total_dur=6906.9
777Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.341186 D  DTVRec[5]: Frame @ 11149716 + 0 = 11149716
778Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.341202 D  count=208 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=416 tick_base=0 _total_dur=6940.27
779Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.361576 D  DTVRec[5]: Frame @ 11204612 + 0 = 11204612
780Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.361594 D  count=209 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=418 tick_base=0 _total_dur=6973.63
781Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.361705 D  DTVRec[5]: Frame @ 11227924 + 0 = 11227924
782Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.361715 D  count=210 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=420 tick_base=0 _total_dur=7007
783Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.381957 D  DTVRec[5]: Keyframe @ 11246912 + 188 = 11247100
784Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.381970 D  DTVRec[5]: Frame @ 11246912 + 188 = 11247100
785Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.381985 D  count=211 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=422 tick_base=0 _total_dur=7040.37
786Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.685013 D  DTVRec[5]: Frame @ 11643780 + 0 = 11643780
787Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.685035 D  count=212 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=424 tick_base=0 _total_dur=7073.73
788Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.685153 D  DTVRec[5]: Frame @ 11662956 + 0 = 11662956
789Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.685164 D  count=213 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=426 tick_base=0 _total_dur=7107.1
790Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.705366 D  DTVRec[5]: Frame @ 11681380 + 0 = 11681380
791Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.705384 D  count=214 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=428 tick_base=0 _total_dur=7140.47
792Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.765996 D  DTVRec[5]: Frame @ 11729508 + 0 = 11729508
793Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.766014 D  count=215 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=430 tick_base=0 _total_dur=7173.83
794Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.766084 D  DTVRec[5]: Frame @ 11744548 + 0 = 11744548
795Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.766094 D  count=216 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=432 tick_base=0 _total_dur=7207.2
796Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.786323 D  DTVRec[5]: Frame @ 11760340 + 0 = 11760340
797Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.786340 D  count=217 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=434 tick_base=0 _total_dur=7240.57
798Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.786608 D  DTVRec[5]: Frame @ 11817868 + 0 = 11817868
799Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.786619 D  count=218 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=436 tick_base=0 _total_dur=7273.93
800Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.806852 D  DTVRec[5]: Frame @ 11839488 + 0 = 11839488
801Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.806869 D  count=219 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=438 tick_base=0 _total_dur=7307.3
802Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.807004 D  DTVRec[5]: Frame @ 11861296 + 0 = 11861296
803Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.807016 D  count=220 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=440 tick_base=0 _total_dur=7340.67
804Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.827404 D  DTVRec[5]: Frame @ 11916568 + 0 = 11916568
805Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.827422 D  count=221 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=442 tick_base=0 _total_dur=7374.03
806Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.847612 D  DTVRec[5]: Frame @ 11928976 + 0 = 11928976
807Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.847630 D  count=222 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=444 tick_base=0 _total_dur=7407.4
808Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.867830 D  DTVRec[5]: Keyframe @ 11952852 + 188 = 11953040
809Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.867846 D  DTVRec[5]: Frame @ 11952852 + 188 = 11953040
810Apr 04 09:09:30 sirius mythbackend[10691]: 2018-04-04 09:09:30.867863 D  count=223 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=446 tick_base=0 _total_dur=7440.77
811Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:30.908785 D  DTVRec[5]: Frame @ 12086144 + 0 = 12086144
812Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:30.908802 D  count=224 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=448 tick_base=0 _total_dur=7474.13
813Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:30.908946 D  DTVRec[5]: Frame @ 12109080 + 0 = 12109080
814Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:30.908960 D  count=225 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=450 tick_base=0 _total_dur=7507.5
815Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:30.908981 D  DTVRec[5]: Keyframe @ 12111148 + 0 = 12111148
816Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:30.908990 D  DTVRec[5]: Frame @ 12111148 + 0 = 12111148
817Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:30.908999 D  count=226 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=452 tick_base=0 _total_dur=7540.87
818Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:30.929426 D  DTVRec[5]: Frame @ 12169616 + 0 = 12169616
819Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:30.929444 D  count=227 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=454 tick_base=0 _total_dur=7574.23
820Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:30.969907 D  DTVRec[5]: Frame @ 12220564 + 0 = 12220564
821Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:30.969924 D  count=228 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=456 tick_base=0 _total_dur=7607.6
822Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:30.990282 D  DTVRec[5]: Frame @ 12259480 + 0 = 12259480
823Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:30.990299 D  count=229 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=458 tick_base=0 _total_dur=7640.97
824Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.171806 D  DTVRec[5]: Frame @ 12427364 + 0 = 12427364
825Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.171828 D  count=230 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=460 tick_base=0 _total_dur=7674.33
826Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.192198 D  DTVRec[5]: Frame @ 12466468 + 0 = 12466468
827Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.192215 D  count=231 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=462 tick_base=0 _total_dur=7707.7
828Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.212452 D  DTVRec[5]: Frame @ 12489592 + 0 = 12489592
829Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.212470 D  count=232 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=464 tick_base=0 _total_dur=7741.07
830Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.272993 D  DTVRec[5]: Frame @ 12526064 + 0 = 12526064
831Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.273011 D  count=233 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=466 tick_base=0 _total_dur=7774.43
832Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.273083 D  DTVRec[5]: Frame @ 12541668 + 0 = 12541668
833Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.273093 D  count=234 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=468 tick_base=0 _total_dur=7807.8
834Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.273207 D  DTVRec[5]: Frame @ 12557648 + 0 = 12557648
835Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.273218 D  count=235 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=470 tick_base=0 _total_dur=7841.17
836Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.313792 D  DTVRec[5]: Updating timeOfLatestData elapsed(1094) interval(9235)
837Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.313811 D  DTVRec[5]: Frame @ 12631720 + 0 = 12631720
838Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.313825 D  count=236 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=472 tick_base=0 _total_dur=7874.53
839Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.334132 D  DTVRec[5]: Frame @ 12678156 + 0 = 12678156
840Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.334150 D  count=237 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=474 tick_base=0 _total_dur=7907.9
841Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.354536 D  DTVRec[5]: Frame @ 12719892 + 0 = 12719892
842Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.354554 D  count=238 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=476 tick_base=0 _total_dur=7941.27
843Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.375103 D  DTVRec[5]: Frame @ 12810132 + 0 = 12810132
844Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.375120 D  count=239 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=478 tick_base=0 _total_dur=7974.63
845Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.395534 D  DTVRec[5]: Frame @ 12865592 + 0 = 12865592
846Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.395550 D  count=240 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=480 tick_base=0 _total_dur=8008
847Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.436059 D  DTVRec[5]: Keyframe @ 12917292 + 188 = 12917480
848Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.436073 D  DTVRec[5]: Frame @ 12917292 + 188 = 12917480
849Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.436087 D  count=241 m_frameRate=30000/1001 tick_frameRate=30000/1001 tick_cnt=482 tick_base=0 _total_dur=8041.37
850Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.476722 I  TVRec[5]: StopLiveTV(void) curRec: 0x7fc954196820 pseudoRec: 0x0
851Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.477641 I  TVRec[5]: Changing from WatchingLiveTV to None
852Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.478007 I  TVRec[5]: ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RecorderRunning,RingBufferReady, @ tv_rec.cpp:1316
853Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.478020 I  TVRec[5]: HandleTuning Request: Program(NULL) channel() input() flags(KillRec,KillRingBuffer,)
854Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.478029 I  TVRec[5]: TuningShutdowns(Program(NULL) channel() input() flags(KillRec,KillRingBuffer,))
855Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.478041 I  TVRec[5]: request (Program(NULL) channel() input() flags(KillRec,KillRingBuffer,)) channum () inputname ()
856Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.478054 I  TVRec[5]: TeardownRecorder(kFlagKillRec)
857Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.478119 I  HDHRRec[5]: run -- ending...
858Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.478128 I  SH[5](14165FA2-2): RemoveListener(0x7fc9541a0fe0) -- begin
859Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.478133 I  SH[5](14165FA2-2): RemoveListener(0x7fc9541a0fe0) -- locked
860Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.478138 D  SH[5](14165FA2-2): Stopping
861Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.496955 I  HDHRSH[5](14165FA2-2): RunTS(): shutdown
862Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.496969 E  HDHRSH[5](14165FA2-2): UpdateFilters called in wrong tune mode
863Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.497244 I  HDHRSH[5](14165FA2-2): stream stats: packet_count=10107 network_errors=0 transport_errors=0 sequence_errors=0 overflow_errors=0
864Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.497252 I  HDHRSH[5](14165FA2-2): RunTS(): end
865Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.497257 I  HDHRSH[5](14165FA2-2): Release tuner lock.
866Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.497547 D  SH[5](14165FA2-2): Stopped
867Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.497556 I  SH[5](14165FA2-2): RemoveListener(0x7fc9541a0fe0) -- end
868Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.497561 I  HDHRRec[5]: Close() -- begin
869Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.497568 I  HDHRRec[5]: Close() -- end
870Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.497729 D  PortChecker::checkPort(): host localhost port 3306 timeLimit 30000 linkLocalOnly 1
871Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.504547 D  No progstart mark needed because delta=-5963161
872Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.505398 N  Finished Recording: Container: MPEG2-TS Video Codec: mpeg2video (1920x1080 A/R: 3 29.97fps) Audio Codec: ac3
873Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.505409 I  HDHRRec[5]: run -- end
874Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.505493 I  TVRec[5]: ClearFlags(NeedToStartRecorder,RecorderRunning,) -> RunMainLoop,RingBufferReady, @ tv_rec.cpp:1148
875Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.505557 I  RecordingQuality() start(2018-04-04T11:30:00Z) end(2018-04-04T13:30:00Z) score(0)
876Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.505591 I  RecBase[5](14165FA2-2): SetRecording(0x0)
877Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.506092 I  TVRec[5]: FinishedRecording(1937_2018-04-04T13:09:23Z) damaged recq:<RecordingQuality overall_score="0" key="1937_2018-04-04T13:09:23Z" countinuity_error_count="0" packet_count="69085">
878Apr 04 09:09:31 sirius mythbackend[10691]:     <Gap start="2018-04-04T11:30:00Z" end="2018-04-04T13:09:23Z" duration="5963" />
879Apr 04 09:09:31 sirius mythbackend[10691]:     <Gap start="2018-04-04T13:09:31Z" end="2018-04-04T13:30:00Z" duration="1228" />
880Apr 04 09:09:31 sirius mythbackend[10691]: </RecordingQuality>
881Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.506123 I  TVRec[5]: FinishedRecording(1937_2018-04-04T13:09:23Z) Bad quality
882Apr 04 09:09:31 sirius mythbackend[10691]:                         title: Invasion Roswell
883Apr 04 09:09:31 sirius mythbackend[10691]:                         in recgroup: LiveTV status: Recorder Failed:Recorder Failed not_dummy finished_now
884Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.506564 I  Chain: Updated endtime for '1937_20180404130923' to 20180404130931
885Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.507327 I  SaveVideoProperties(0x3a, 0x32)
886Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.508125 I  ProgramInfo(1937_20180404130923.ts): RI Filesize=0, DB Filesize=13009036
887Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.509329 I  HDHRSH(14165FA2-2): Tuning channel none (was qam:255000000)
888Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.510823 I  TVRec[5]: Tearing down RingBuffer
889Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.511008 I  TVRec[5]: ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady, @ tv_rec.cpp:3683
890Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.511062 D  LiveTVChain(live-sirius-2018-04-04T13:09:22Z): dtor
891Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.511621 I  TVRec[5]: SetFlags(FrontendReady,) -> FrontendReady,RunMainLoop,RingBufferReady, @ ../../libs/libmythtv/tv_rec.h:171
892Apr 04 09:09:31 sirius mythbackend[10691]: 2018-04-04 09:09:31.524989 I  Playback sock(24cf2e0) 'sirius' disconnected