Ticket #4566: backend.3.txt

File backend.3.txt, 48.5 KB (added by Mark Buechler <Mark.Buechler@…>, 16 years ago)

Latest backend log

Line 
12008-02-07 19:50:32.692 MainServer::HandleAnnounce Monitor
22008-02-07 19:50:32.694 adding: pc4 as a client (events: 0)
32008-02-07 19:50:32.696 MainServer::HandleAnnounce Monitor
42008-02-07 19:50:32.697 adding: pc4 as a client (events: 1)
52008-02-07 19:50:33.168 MainServer::HandleAnnounce Monitor
62008-02-07 19:50:33.171 adding: pc4 as a client (events: 0)
72008-02-07 19:50:33.175 MainServer::HandleAnnounce Monitor
82008-02-07 19:50:33.176 adding: pc4 as a client (events: 1)
92008-02-07 19:50:33.492 MainServer::HandleAnnounce Monitor
102008-02-07 19:50:33.494 adding: pc4 as a client (events: 0)
112008-02-07 19:50:33.497 MainServer::HandleAnnounce Monitor
122008-02-07 19:50:33.499 adding: pc4 as a client (events: 1)
132008-02-07 19:50:33.816 MainServer::HandleAnnounce Monitor
142008-02-07 19:50:33.818 adding: pc4 as a client (events: 0)
152008-02-07 19:50:33.823 MainServer::HandleAnnounce Monitor
162008-02-07 19:50:33.825 adding: pc4 as a client (events: 1)
172008-02-07 19:50:34.037 MainServer::HandleAnnounce Monitor
182008-02-07 19:50:34.040 adding: pc4 as a client (events: 0)
192008-02-07 19:50:34.046 MainServer::HandleAnnounce Monitor
202008-02-07 19:50:34.048 adding: pc4 as a client (events: 1)
212008-02-07 19:50:34.429 MainServer::HandleAnnounce Monitor
222008-02-07 19:50:34.435 adding: pc4 as a client (events: 0)
232008-02-07 19:50:34.438 MainServer::HandleAnnounce Monitor
242008-02-07 19:50:34.441 adding: pc4 as a client (events: 1)
252008-02-07 19:50:34.803 MainServer::HandleAnnounce Monitor
262008-02-07 19:50:34.806 adding: pc4 as a client (events: 0)
272008-02-07 19:50:34.809 MainServer::HandleAnnounce Monitor
282008-02-07 19:50:34.811 adding: pc4 as a client (events: 1)
292008-02-07 19:50:35.123 MainServer::HandleAnnounce Monitor
302008-02-07 19:50:35.125 adding: pc4 as a client (events: 0)
312008-02-07 19:50:35.129 MainServer::HandleAnnounce Monitor
322008-02-07 19:50:35.131 adding: pc4 as a client (events: 1)
332008-02-07 19:50:35.514 MainServer::HandleAnnounce Monitor
342008-02-07 19:50:35.517 adding: pc4 as a client (events: 0)
352008-02-07 19:50:35.529 MainServer::HandleAnnounce Monitor
362008-02-07 19:50:35.531 adding: pc4 as a client (events: 1)
372008-02-07 19:50:35.794 MainServer::HandleAnnounce Monitor
382008-02-07 19:50:35.796 adding: pc4 as a client (events: 0)
392008-02-07 19:50:35.803 MainServer::HandleAnnounce Monitor
402008-02-07 19:50:35.805 adding: pc4 as a client (events: 1)
412008-02-07 19:51:54.051 Reschedule requested for id -1.
422008-02-07 19:52:00.659 Scheduled 226 items in 6.6 = 0.20 match + 6.41 place
432008-02-07 19:52:00.669 scheduler: Scheduled items: Scheduled 226 items in 6.6 = 0.20 match + 6.41 place
442008-02-07 19:52:26.158 TVRec(5): Found channel (8096) on current card(5).
452008-02-07 19:52:26.170 ChannelBase(5): Failed to find channel(8096) on current input (DiSEqC v1.3 Input 5) of card (5).
462008-02-07 19:52:26.176 ChannelBase(5): Found channel(8096) on another input (DiSEqC v1.3 Input 1) of card (5).
472008-02-07 19:52:26.180 TVRec(5): SetChannel(8096) -- begin
482008-02-07 19:52:26.181 TVRec(5): ClearFlags(RingBufferReady,) -> RunMainLoop,CancelNextRecording,RecorderRunning,
492008-02-07 19:52:26.183 TVRec(5): Request: Program(no) channel(8096) input() flags(LiveTV,)
502008-02-07 19:52:26.186 ChannelBase(5): Looking for startchannel '8096' on input 'DiSEqC v1.3 Input 1'
512008-02-07 19:52:26.204 DVBSH(5): RemoveListener(0xa95b6260) -- begin
522008-02-07 19:52:26.206 ChannelBase(5): Found startchannel '8096' on input 'DiSEqC v1.3 Input 1'
532008-02-07 19:52:26.208 DVBSH(5): RemoveListener(0xa95b6260) -- locked
542008-02-07 19:52:26.213 TVRec(5): HW Tuner: 5->5
552008-02-07 19:52:26.217 TVRec(5): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,CancelNextRecording,RecorderRunning,
562008-02-07 19:52:26.219 TVRec(5): Waiting for recorder pause..
572008-02-07 19:52:26.219 DVBSH(5): RunTS(): shutdown
582008-02-07 19:52:26.220 TVRec(5): SetFlags(WaitingForRecPause,) -> RunMainLoop,CancelNextRecording,WaitingForRecPause,RecorderRunning,
592008-02-07 19:52:26.223 DVBSH(5): RemovePIDFilter(0x0)
602008-02-07 19:52:26.229 PIDInfo(5): Closing filter for pid 0x0
612008-02-07 19:52:26.232 DVBSH(5): RemovePIDFilter(0x10)
622008-02-07 19:52:26.236 PIDInfo(5): Closing filter for pid 0x10
632008-02-07 19:52:26.240 DVBSH(5): RemovePIDFilter(0x11)
642008-02-07 19:52:26.242 PIDInfo(5): Closing filter for pid 0x11
652008-02-07 19:52:26.245 DVBSH(5): RemovePIDFilter(0x14)
662008-02-07 19:52:26.247 PIDInfo(5): Closing filter for pid 0x14
672008-02-07 19:52:26.249 DVBSH(5): RemovePIDFilter(0x21)
682008-02-07 19:52:26.251 PIDInfo(5): Closing filter for pid 0x21
692008-02-07 19:52:26.252 DVBSH(5): RemovePIDFilter(0x1322)
702008-02-07 19:52:26.254 PIDInfo(5): Closing filter for pid 0x1322
712008-02-07 19:52:26.256 DVBSH(5): RemovePIDFilter(0x1323)
722008-02-07 19:52:26.258 PIDInfo(5): Closing filter for pid 0x1323
732008-02-07 19:52:26.274 DVBSH(5): RunTS(): end
742008-02-07 19:52:26.277 DVBSH(5): RemoveListener(0xa95b6260) -- end
752008-02-07 19:52:26.281 TVRec(5): ClearFlags(WaitingForRecPause,) -> RunMainLoop,CancelNextRecording,RecorderRunning,
762008-02-07 19:52:26.284 TVRec(5): Recorder paused, calling TuningFrequency
772008-02-07 19:52:26.287 DVBChan(5:5): Opening DVB channel
782008-02-07 19:52:26.289 DVBChan(5:5): SetChannelByString(8096):
792008-02-07 19:52:26.291 ChannelBase(5): Failed to find channel(8096) on current input (DiSEqC v1.3 Input 5) of card (5).
802008-02-07 19:52:26.294 ChannelBase(5): Found channel(8096) on another input (DiSEqC v1.3 Input 1) of card (5).
812008-02-07 19:52:26.311 DVBChan(5:5): 12573920 qpsk a auto auto a a auto a r
822008-02-07 19:52:26.314 DiSEqCDevTree: Overriding voltage to 18V for faster rotor movement
832008-02-07 19:52:26.334 DiSEqCDevTree: USALS Rotor - Goto -110 (Azimuth -36.0197)
842008-02-07 19:52:26.338 DiSEqCDevTree: Sending DiSEqC Command: e0 31 6e d2 40
852008-02-07 19:52:26.539 DiSEqCDevTree: Changing to DiSEqC switch port 1/4
862008-02-07 19:52:26.542 DiSEqCDevTree: Sending DiSEqC Command: e0 10 38 f0
872008-02-07 19:52:26.975 DVBChan(5:5): Old Params: 12646820 t_8psk a auto auto a a auto a l
88                        DVBChan(5:5): New Params: 12573920 qpsk a auto auto a a auto a r
892008-02-07 19:52:26.978 DVBChan(5:5): Tune(): Tuning to 1323920kHz
902008-02-07 19:52:26.995 dvbchannel.cpp:wait_for_backend: Status:
912008-02-07 19:52:26.998 DVBChan(5:5): Tune(): Frequency tuning successful.
922008-02-07 19:52:27.000 DVBChan(5:5): SetChannelByString(8096): Tuned to frequency.
932008-02-07 19:52:27.002 TVRec(5): SwitchLiveTVRingBuffer(discont 1, set_rec 0)
942008-02-07 19:52:27.005 TVRec(5): GetProgramRingBufferForLiveTV()
952008-02-07 19:52:28.127 TVRec(5): StartedRecording(0xa503b7b0) fn(/shares/recordings/LiveTV/384_20080207195227.mpg)
962008-02-07 19:52:28.141 TFW: Using io multiplier 3, min/max = 16384/131072
972008-02-07 19:52:28.155 TVRec(5): FinishedRecording(Rezoned) in recgroup: LiveTV
982008-02-07 19:52:28.165 Chain: Updated endtime for '266_20080207193026' to 20080207195228
992008-02-07 19:52:28.194 Finished recording Rezoned "New York Grocery Warehouse; Wyoming Diary...": channel 266
1002008-02-07 19:52:28.208 scheduler: Finished recording: Rezoned "New York Grocery Warehouse; Wyoming Diary...": channel 266
1012008-02-07 19:52:28.215 Preview: 'myth://192.168.1.121:6543/266_20080207193026.mpg' is not local,
102                        replacing with '/shares/recordings/LiveTV/266_20080207193026.mpg', which is local.
1032008-02-07 19:52:28.234 Chain: Appended@12 '384_20080207195227'
1042008-02-07 19:52:28.249 TVRec(5): Starting Signal Monitor
1052008-02-07 19:52:28.254 TVRec(5): SetupSignalMonitor(1, 1)
1062008-02-07 19:52:28.259 DVBChan(5:5): Opening DVB channel
1072008-02-07 19:52:28.277 DVBSM(5): Can measure Signal Strength
1082008-02-07 19:52:28.288 DVBSM(5): Can measure S/N
1092008-02-07 19:52:28.293 DVBSM(5): Can measure Bit Error Rate
1102008-02-07 19:52:28.300 DVBSM(5): Can count Uncorrected Blocks
1112008-02-07 19:52:28.305 SM(5)::RemoveFlags: Seen() Match() Wait()
1122008-02-07 19:52:28.310 DVBSM(5): DVBSignalMonitor::ctor initial flags Seen() Match() Wait(Sig,SNR,BER,UB,)
1132008-02-07 19:52:28.316 TVRec(5): Signal monitor successfully created
1142008-02-07 19:52:28.320 TVRec(5): Setting up table monitoring.
1152008-02-07 19:52:28.338 Using profile 'Live TV' to record
1162008-02-07 19:52:28.343 TVRec(5): DVB service_id 8096 on net_id 4102 tsid 2595
1172008-02-07 19:52:28.348 DTVSM(5)::SetDVBService(transport_id: 2595, network_id: 4102, service_id: 8096):
1182008-02-07 19:52:28.351 SM(5)::RemoveFlags: Seen(PMT,SDT,Crypt,) Match(PMT,SDT,Crypt,) Wait()
1192008-02-07 19:52:28.354 SM(5)::AddFlags: Seen() Match() Wait(PMT,NIT,)
1202008-02-07 19:52:28.356 SM(5)::AddFlags: Seen() Match() Wait(PMT,NIT,Pos,)
1212008-02-07 19:52:28.357 TVRec(5): Successfully set up DVB table monitoring.
1222008-02-07 19:52:28.359 SM(5)::Start: begin
1232008-02-07 19:52:28.365 SM(5)::Start: end
1242008-02-07 19:52:28.372 TVRec(5): SetFlags(SignalMonitorRunning,) -> RunMainLoop,CancelNextRecording,SignalMonitorRunning,RecorderRunning,
1252008-02-07 19:52:28.373 TVRec(5): ClearFlags(WaitingForSignal,) -> RunMainLoop,CancelNextRecording,SignalMonitorRunning,RecorderRunning,
1262008-02-07 19:52:28.375 TVRec(5): SetFlags(WaitingForSignal,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,RecorderRunning,
1272008-02-07 19:52:28.365 DTVSM(5)::GetStatusList: WaitForPMT seen(0) matching(0)
1282008-02-07 19:52:28.377 RecBase(5:5): SetRingBuffer(0)
1292008-02-07 19:52:28.382 TVRec(5): SetFlags(DummyRecorderRunning,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING,
1302008-02-07 19:52:28.384 DummyDTVRecorder -- started
1312008-02-07 19:52:28.385 TVRec(5): SetFlags(RingBufferReady,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady,
1322008-02-07 19:52:28.387 TVRec(5): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady,
1332008-02-07 19:52:28.389 TVRec(5): SetFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady,
1342008-02-07 19:52:28.390 TVRec(5): SetChannel(8096) -- end
1352008-02-07 19:52:28.411 AutoExpire: CalcParams(): Max required Free Space: 7.0 GB w/freq: 10 min
1362008-02-07 19:52:28.627 Using runtime prefix = /opt/mns/mythtv.15682, libdir = /opt/mns/mythtv.15682/lib
1372008-02-07 19:52:28.641 Empty LocalHostName.
1382008-02-07 19:52:28.644 Using localhost value of corbin2
1392008-02-07 19:52:28.662 New DB connection, total: 1
1402008-02-07 19:52:28.687 Connected to database 'mythconverg' at host: corbin2
1412008-02-07 19:52:28.691 Closing DB connection named 'DBManager0'
1422008-02-07 19:52:28.695 Connected to database 'mythconverg' at host: corbin2
1432008-02-07 19:52:28.706 Current Schema Version: 1208
1442008-02-07 19:52:28.715 New DB connection, total: 2
1452008-02-07 19:52:28.718 Connected to database 'mythconverg' at host: corbin2
1462008-02-07 19:52:28.740 RingBuf(/shares/recordings/LiveTV/266_20080207193026.mpg): Using io multiplier 3, chunk/max = 8192/163840
1472008-02-07 19:52:28.820 [h264 @ 0xb7209188]non existing PPS referenced
1482008-02-07 19:52:28.822 [h264 @ 0xb7209188]decode_slice_header error
1492008-02-07 19:52:28.823 [h264 @ 0xb7209188]no frame!
1502008-02-07 19:52:28.825 [h264 @ 0xb7209188]non existing PPS referenced
1512008-02-07 19:52:28.827 [h264 @ 0xb7209188]decode_slice_header error
1522008-02-07 19:52:28.828 [h264 @ 0xb7209188]no frame!
1532008-02-07 19:52:28.830 [h264 @ 0xb7209188]non existing PPS referenced
1542008-02-07 19:52:28.833 [h264 @ 0xb7209188]decode_slice_header error
1552008-02-07 19:52:28.835 [h264 @ 0xb7209188]no frame!
1562008-02-07 19:52:28.855 [h264 @ 0xb7209188]top block unavailable for requested intra4x4 mode -1 at 1 0
1572008-02-07 19:52:28.856 [h264 @ 0xb7209188]error while decoding MB 1 0, bytestream (7466)
158QString::setNum: Invalid format char ''
1592008-02-07 19:52:31.348 AFD: Trying DirectShow for FOURCC 0x875967048
1602008-02-07 19:52:31.353 AFD: Opened codec 0x82ac610, id(H264) type(Video)
1612008-02-07 19:52:31.356 AFD: Opened codec 0x82acc00, id(AC3) type(Audio)
1622008-02-07 19:52:31.659 [h264 @ 0xb7209188]warning: first frame is no keyframe
1632008-02-07 19:52:31.663 [h264 @ 0xb7209188]reference picture missing during reorder
1642008-02-07 19:52:31.664 [h264 @ 0xb7209188]reference picture missing during reorder
1652008-02-07 19:52:31.699 [h264 @ 0xb7209188]Interlaced pictures + spatial direct mode is not implemented
1662008-02-07 19:52:31.755 [h264 @ 0xb7209188]Interlaced pictures + spatial direct mode is not implemented
1672008-02-07 19:52:31.780 [h264 @ 0xb7209188]error while decoding MB 85 41, bytestream (-3)
1682008-02-07 19:52:31.785 [h264 @ 0xb7209188]top block unavailable for requested intra4x4 mode -1 at 1 0
1692008-02-07 19:52:31.787 [h264 @ 0xb7209188]error while decoding MB 1 0, bytestream (7466)
1702008-02-07 19:52:31.791 [h264 @ 0xb7209188]Interlaced pictures + spatial direct mode is not implemented
1712008-02-07 19:52:31.831 [h264 @ 0xb7209188]Interlaced pictures + spatial direct mode is not implemented
1722008-02-07 19:52:32.165 Preview: Grabbed preview '/shares/recordings/LiveTV/266_20080207193026.mpg' 1440x1088@69s
1732008-02-07 19:52:37.790 DVBSH(5): Retuning for rotor completion
1742008-02-07 19:52:37.794 DiSEqCDevTree: Changing LNB voltage to 13V
1752008-02-07 19:52:37.796 DVBChan(5:5): Old Params: 12573920 qpsk a auto auto a a auto a r
176                        DVBChan(5:5): New Params: 12573920 qpsk a auto auto a a auto a r
1772008-02-07 19:52:37.798 DVBChan(5:5): Tune(): Tuning to 1323920kHz
1782008-02-07 19:52:37.800 dvbchannel.cpp:wait_for_backend: Status:
1792008-02-07 19:52:37.802 DVBChan(5:5): Tune(): Frequency tuning successful.
1802008-02-07 19:52:42.843 MythSocket(a9202900:43): writeStringList: No data written on writeBlock
1812008-02-07 19:52:54.567 MythSocket(8a43ed0:55): writeStringList: No data written on writeBlock
1822008-02-07 19:53:05.754 MythSocket(a9202900:43): writeStringList: No data written on writeBlock
183
1842008-02-07 19:53:16.412 MythSocket(8a43ed0:55): writeStringList: No data written on writeBlock
1852008-02-07 19:53:30.549 MythSocket(a9202900:43): writeStringList: No data written on writeBlock
1862008-02-07 19:53:44.002 MythSocket(8a43ed0:55): writeStringList: No data written on writeBlock
1872008-02-07 19:53:44.007 MythSocket(8743b98:-1): writeStringList: Error, socket went unconnected.
1882008-02-07 19:53:56.312 MythSocket(a9202900:43): writeStringList: No data written on writeBlock
1892008-02-07 19:54:08.169 MythSocket(8a43ed0:55): writeStringList: No data written on writeBlock
1902008-02-07 19:54:08.176 TVRec(5): StopLiveTV(void) curRec: 0xa503b7b0 pseudoRec: 0
1912008-02-07 19:54:08.185 TVRec(5): Changing from WatchingLiveTV to None
1922008-02-07 19:54:08.187 TVRec(5): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady,
1932008-02-07 19:54:08.188 TVRec(5): Request: Program(no) channel() input() flags(KillRec,KillRingBuffer,)
1942008-02-07 19:54:08.190 TVRec(5): TeardownSignalMonitor() -- begin
1952008-02-07 19:54:08.191 DVBSM(5): Stop() -- begin
1962008-02-07 19:54:08.192 SM(5)::Stop: begin
1972008-02-07 19:54:08.204 SM(5)::Stop: end
1982008-02-07 19:54:08.206 DVBSH(5): RemoveListener(0xa95b6260) -- begin
1992008-02-07 19:54:08.209 DVBSH(5): RemoveListener(0xa95b6260) -- locked
2002008-02-07 19:54:08.211 DVBSH(5): RemoveListener(0xa95b6260) -- end
2012008-02-07 19:54:08.212 DVBSM(5): Stop() -- end
2022008-02-07 19:54:08.213 DVBSM(5): Stop() -- begin
2032008-02-07 19:54:08.215 SM(5)::Stop: begin
2042008-02-07 19:54:08.216 SM(5)::Stop: end
2052008-02-07 19:54:08.218 DVBSM(5): Stop() -- end
2062008-02-07 19:54:08.219 TVRec(5): TeardownSignalMonitor() -- end
2072008-02-07 19:54:08.220 TVRec(5): ClearFlags(SignalMonitorRunning,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,ANYRECRUNNING,RingBufferReady,
2082008-02-07 19:54:08.221 TVRec(5): ClearFlags(WaitingForSignal,) -> RunMainLoop,NeedToStartRecorder,ANYRECRUNNING,RingBufferReady,
2092008-02-07 19:54:08.223 TVRec(5): ClearFlags(DummyRecorderRunning,) -> RunMainLoop,NeedToStartRecorder,RecorderRunning,RingBufferReady,
2102008-02-07 19:54:08.228 TVRec(5): FinishedRecording(The NewsHour With Jim Lehrer) in recgroup: LiveTV
2112008-02-07 19:54:08.231 Chain: Updated endtime for '384_20080207195227' to 20080207195408
2122008-02-07 19:54:08.236 Finished recording The NewsHour With Jim Lehrer: channel 384
2132008-02-07 19:54:08.245 scheduler: Finished recording: The NewsHour With Jim Lehrer: channel 384
2142008-02-07 19:54:08.381 DVBSH(5): RemoveListener(0xa95b6260) -- begin
2152008-02-07 19:54:08.383 DVBSH(5): RemoveListener(0xa95b6260) -- locked
2162008-02-07 19:54:08.385 DVBSH(5): RemoveListener(0xa95b6260) -- end
2172008-02-07 19:54:08.386 DVBRec(5:5): Close() fd(-1) -- begin
2182008-02-07 19:54:08.388 DVBRec(5:5): Close() fd(-1) -- end
2192008-02-07 19:54:08.433 TVRec(5): ClearFlags(RecorderRunning,) -> RunMainLoop,NeedToStartRecorder,RingBufferReady,
2202008-02-07 19:54:08.444 TVRec(5): FinishedRecording(The NewsHour With Jim Lehrer) in recgroup: LiveTV
2212008-02-07 19:54:08.447 Chain: Updated endtime for '384_20080207195227' to 20080207195408
2222008-02-07 19:54:08.450 Finished recording The NewsHour With Jim Lehrer: channel 384
2232008-02-07 19:54:08.452 TVRec(5): ClearFlags(RecorderRunning,) -> RunMainLoop,NeedToStartRecorder,RingBufferReady,
2242008-02-07 19:54:08.453 TVRec(5): Tearing down RingBuffer
2252008-02-07 19:54:08.455 TVRec(5): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady,
2262008-02-07 19:54:08.463 MythSocket(a68ec420:-1): writeStringList: Error, socket went unconnected.
2272008-02-07 19:54:08.469 MythSocket(8982590:-1): writeStringList: Error, socket went unconnected.
2282008-02-07 19:54:18.987 MythSocket(a9202900:43): writeStringList: No data written on writeBlock
2292008-02-07 19:54:31.033 MythSocket(8a43ed0:55): writeStringList: No data written on writeBlock
2302008-02-07 19:54:38.521 TVRec(5): SetFlags(EITScannerRunning,) -> RunMainLoop,EITScannerRunning,RingBufferReady,
2312008-02-07 19:54:38.813 TVRec(5): SetChannel(145) -- begin
2322008-02-07 19:54:38.816 TVRec(5): ClearFlags(RingBufferReady,) -> RunMainLoop,EITScannerRunning,
2332008-02-07 19:54:38.817 TVRec(5): Request: Program(no) channel(145) input() flags(EITScan,)
2342008-02-07 19:54:38.822 ChannelBase(5): Failed to find channel(145) on current input (DiSEqC v1.3 Input 1) of card (5).
2352008-02-07 19:54:38.826 ChannelBase(5): Found channel(145) on another input (DiSEqC v1.3 Input 19) of card (5).
2362008-02-07 19:54:38.827 TVRec(5): HW Tuner: 5->5
2372008-02-07 19:54:38.829 TVRec(5): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,EITScannerRunning,
2382008-02-07 19:54:38.830 TVRec(5): No recorder yet, calling TuningFrequency
2392008-02-07 19:54:38.832 DVBChan(5:5): Opening DVB channel
2402008-02-07 19:54:38.834 DVBChan(5:5): SetChannelByString(145):
2412008-02-07 19:54:38.836 ChannelBase(5): Failed to find channel(145) on current input (DiSEqC v1.3 Input 1) of card (5).
2422008-02-07 19:54:38.839 ChannelBase(5): Found channel(145) on another input (DiSEqC v1.3 Input 19) of card (5).
2432008-02-07 19:54:38.845 DVBChan(5:5): 12471860 qpsk a auto auto a a auto a l
2442008-02-07 19:54:38.847 DiSEqCDevTree: Overriding voltage to 18V for faster rotor movement
2452008-02-07 19:54:38.849 DiSEqCDevTree: Changing LNB voltage to 18V
2462008-02-07 19:54:38.868 DiSEqCDevTree: USALS Rotor - Goto -119 (Azimuth -45.8453)
2472008-02-07 19:54:38.873 DiSEqCDevTree: Sending DiSEqC Command: e0 31 6e d2 dd
2482008-02-07 19:54:39.073 DiSEqCDevTree: Changing to DiSEqC switch port 1/4
2492008-02-07 19:54:39.075 DiSEqCDevTree: Sending DiSEqC Command: e0 10 38 f2
2502008-02-07 19:54:39.509 DVBChan(5:5): Old Params: 12573920 qpsk a auto auto a a auto a r
251                        DVBChan(5:5): New Params: 12471860 qpsk a auto auto a a auto a l
2522008-02-07 19:54:39.511 DVBChan(5:5): Tune(): Tuning to 1221860kHz
2532008-02-07 19:54:39.527 dvbchannel.cpp:wait_for_backend: Status:
2542008-02-07 19:54:39.529 DVBChan(5:5): Tune(): Frequency tuning successful.
2552008-02-07 19:54:39.530 DVBChan(5:5): SetChannelByString(145): Tuned to frequency.
2562008-02-07 19:54:39.532 TVRec(5): Starting Signal Monitor
2572008-02-07 19:54:39.534 TVRec(5): SetupSignalMonitor(1, 0)
2582008-02-07 19:54:39.536 DVBChan(5:5): Opening DVB channel
2592008-02-07 19:54:39.550 DVBSM(5): Can measure Signal Strength
2602008-02-07 19:54:39.552 DVBSM(5): Can measure S/N
2612008-02-07 19:54:39.553 DVBSM(5): Can measure Bit Error Rate
2622008-02-07 19:54:39.554 DVBSM(5): Can count Uncorrected Blocks
2632008-02-07 19:54:39.555 SM(5)::RemoveFlags: Seen() Match() Wait()
2642008-02-07 19:54:39.557 DVBSM(5): DVBSignalMonitor::ctor initial flags Seen() Match() Wait(Sig,SNR,BER,UB,)
2652008-02-07 19:54:39.558 TVRec(5): Signal monitor successfully created
2662008-02-07 19:54:39.559 TVRec(5): Setting up table monitoring.
2672008-02-07 19:54:39.574 Using profile 'Live TV' to record
2682008-02-07 19:54:39.575 TVRec(5): DVB service_id 145 on net_id 4100 tsid 18
2692008-02-07 19:54:39.577 DTVSM(5)::SetDVBService(transport_id: 18, network_id: 4100, service_id: 145):
2702008-02-07 19:54:39.578 SM(5)::RemoveFlags: Seen(PMT,SDT,Crypt,) Match(PMT,SDT,Crypt,) Wait()
2712008-02-07 19:54:39.580 SM(5)::AddFlags: Seen() Match() Wait(PMT,NIT,)
2722008-02-07 19:54:39.581 SM(5)::AddFlags: Seen() Match() Wait(PMT,NIT,Pos,)
2732008-02-07 19:54:39.582 TVRec(5): Successfully set up DVB table monitoring.
2742008-02-07 19:54:39.584 SM(5)::Start: begin
2752008-02-07 19:54:39.587 SM(5)::Start: end
2762008-02-07 19:54:39.589 TVRec(5): SetFlags(SignalMonitorRunning,) -> RunMainLoop,SignalMonitorRunning,EITScannerRunning,
2772008-02-07 19:54:39.590 TVRec(5): ClearFlags(WaitingForSignal,) -> RunMainLoop,SignalMonitorRunning,EITScannerRunning,
2782008-02-07 19:54:39.591 TVRec(5): SetFlags(WaitingForSignal,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,EITScannerRunning,
2792008-02-07 19:54:39.593 TVRec(5): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,EITScannerRunning,
2802008-02-07 19:54:39.594 TVRec(5): SetChannel(145) -- end
2812008-02-07 19:54:39.819 DVBSM(5): UpdateValues -- Signal Locked
2822008-02-07 19:54:40.995 DVBSM(5): UpdateValues -- Signal Lost
2832008-02-07 19:54:44.332 DVBSH(5): Retuning for rotor completion
2842008-02-07 19:54:44.334 DVBChan(5:5): Old Params: 12471860 qpsk a auto auto a a auto a l
285                        DVBChan(5:5): New Params: 12471860 qpsk a auto auto a a auto a l
2862008-02-07 19:54:44.336 DVBChan(5:5): Tune(): Tuning to 1221860kHz
2872008-02-07 19:54:44.343 dvbchannel.cpp:wait_for_backend: Status:
2882008-02-07 19:54:44.345 DVBChan(5:5): Tune(): Frequency tuning successful.
2892008-02-07 19:54:44.412 DVBSM(5): UpdateValues -- Signal Locked
2902008-02-07 19:54:44.413 DVBSH(5): AddListener(0xa4db5000) -- begin
2912008-02-07 19:54:44.415 DVBSH(5): AddListener(0xa4db5000) -- locked
2922008-02-07 19:54:44.416 DVBSH(5): AddListener(0xa4db5000) -- end
2932008-02-07 19:54:44.425 DVBSH(5): RunTS(): begin
2942008-02-07 19:54:44.428 DVBSH(5): AddPIDFilter(0x0) priority 2
2952008-02-07 19:54:44.429 PIDInfo(5): Opening filter for pid 0x0
2962008-02-07 19:54:44.431 DVBSH(5): AddPIDFilter(0x10) priority 2
2972008-02-07 19:54:44.433 PIDInfo(5): Opening filter for pid 0x10
2982008-02-07 19:54:44.434 DVBSH(5): AddPIDFilter(0x11) priority 2
2992008-02-07 19:54:44.436 PIDInfo(5): Opening filter for pid 0x11
3002008-02-07 19:54:44.437 DVBSH(5): AddPIDFilter(0x14) priority 2
3012008-02-07 19:54:44.439 PIDInfo(5): Opening filter for pid 0x14
3022008-02-07 19:54:44.497 SM(5)::AddFlags: Seen(PAT,) Match() Wait()
3032008-02-07 19:54:44.499 SM(5)::AddFlags: Seen() Match(PAT,) Wait()
3042008-02-07 19:54:44.500 CreatePATSingleProgram()
3052008-02-07 19:54:44.502 PAT in input stream
3062008-02-07 19:54:44.503 Program Association Table
307 PSIP tableID(0x0) length(153) extension(0x12)
308      version(10) current(1) section(0) last_section(0)
309         tsid: 18
310 programCount: 36
311  program number     0 has PID 0x  10   data  0x 0 0x 0 0xe0 0x10
312  program number   145 has PID 0x  21   data  0x 0 0x91 0xe0 0x21
313  program number   239 has PID 0x  21   data  0x 0 0xef 0xe0 0x21
314  program number   418 has PID 0x  21   data  0x 1 0xa2 0xe0 0x21
315  program number   424 has PID 0x  21   data  0x 1 0xa8 0xe0 0x21
316  program number   427 has PID 0x  21   data  0x 1 0xab 0xe0 0x21
317  program number   436 has PID 0x  21   data  0x 1 0xb4 0xe0 0x21
318  program number   438 has PID 0x  21   data  0x 1 0xb6 0xe0 0x21
319  program number   444 has PID 0x  21   data  0x 1 0xbc 0xe0 0x21
320  program number   558 has PID 0x  21   data  0x 2 0x2e 0xe0 0x21
321  program number   628 has PID 0x  21   data  0x 2 0x74 0xe0 0x21
322  program number   632 has PID 0x  21   data  0x 2 0x78 0xe0 0x21
323  program number   633 has PID 0x  21   data  0x 2 0x79 0xe0 0x21
324  program number   636 has PID 0x  21   data  0x 2 0x7c 0xe0 0x21
325  program number   881 has PID 0x  21   data  0x 3 0x71 0xe0 0x21
326  program number   946 has PID 0x  21   data  0x 3 0xb2 0xe0 0x21
327  program number  5802 has PID 0x  21   data  0x16 0xaa 0xe0 0x21
328  program number  5814 has PID 0x  21   data  0x16 0xb6 0xe0 0x21
329  program number  6001 has PID 0x  21   data  0x17 0x71 0xe0 0x21
330  program number  6008 has PID 0x  21   data  0x17 0x78 0xe0 0x21
331  program number  6014 has PID 0x  21   data  0x17 0x7e 0xe0 0x21
332  program number  6021 has PID 0x  21   data  0x17 0x85 0xe0 0x21
333  program number  6026 has PID 0x  21   data  0x17 0x8a 0xe0 0x21
334  program number  6027 has PID 0x  21   data  0x17 0x8b 0xe0 0x21
335  program number  6028 has PID 0x  21   data  0x17 0x8c 0xe0 0x21
336  program number  6035 has PID 0x  21   data  0x17 0x93 0xe0 0x21
337  program number  6040 has PID 0x  21   data  0x17 0x98 0xe0 0x21
338  program number  6060 has PID 0x  21   data  0x17 0xac 0xe0 0x21
339  program number  6071 has PID 0x  21   data  0x17 0xb7 0xe0 0x21
340  program number  6073 has PID 0x  21   data  0x17 0xb9 0xe0 0x21
341  program number  6074 has PID 0x  21   data  0x17 0xba 0xe0 0x21
342  program number  6077 has PID 0x  21   data  0x17 0xbd 0xe0 0x21
343  program number  6080 has PID 0x  21   data  0x17 0xc0 0xe0 0x21
344  program number  6086 has PID 0x  21   data  0x17 0xc6 0xe0 0x21
345  program number  9800 has PID 0x  21   data  0x26 0x48 0xe0 0x21
346  program number 36862 has PID 0x  21   data  0x8f 0xfe 0xe0 0x21
347
3482008-02-07 19:54:44.507 desired_program(145) pid(0x21)
3492008-02-07 19:54:44.508 pmt_pid(0x21)
3502008-02-07 19:54:44.510 PAT for output stream
3512008-02-07 19:54:44.511 Program Association Table
352 PSIP tableID(0x0) length(13) extension(0x12)
353      version(10) current(1) section(0) last_section(0)
354         tsid: 18
355 programCount: 1
356  program number     1 has PID 0x  21   data  0x 0 0x 1 0xe0 0x21
357
3582008-02-07 19:54:44.513 DVBSH(5): AddPIDFilter(0x21) priority 2
3592008-02-07 19:54:44.514 PIDInfo(5): Opening filter for pid 0x21
3602008-02-07 19:54:44.542 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
3612008-02-07 19:54:44.543 DTVSM(5) Error: Wrong PMT; pmt->pn(6008) desired(145)
3622008-02-07 19:54:44.545 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
3632008-02-07 19:54:44.546 DTVSM(5) Error: Wrong PMT; pmt->pn(6014) desired(145)
3642008-02-07 19:54:44.548 DTVSM(5)::SetNIT(): net_id = 4100
3652008-02-07 19:54:44.550 SM(5)::AddFlags: Seen(NIT,) Match() Wait()
3662008-02-07 19:54:44.551 DTVSM(5)::setNIT(): nid = 4100
3672008-02-07 19:54:44.552 SM(5)::AddFlags: Seen() Match(NIT,) Wait()
3682008-02-07 19:54:44.557 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
3692008-02-07 19:54:44.558 DTVSM(5) Error: Wrong PMT; pmt->pn(6021) desired(145)
3702008-02-07 19:54:44.560 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
3712008-02-07 19:54:44.561 DTVSM(5) Error: Wrong PMT; pmt->pn(6026) desired(145)
3722008-02-07 19:54:44.563 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
3732008-02-07 19:54:44.566 DTVSM(5) Error: Wrong PMT; pmt->pn(6027) desired(145)
3742008-02-07 19:54:44.568 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
3752008-02-07 19:54:44.569 DTVSM(5) Error: Wrong PMT; pmt->pn(6028) desired(145)
3762008-02-07 19:54:44.580 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
3772008-02-07 19:54:44.582 DTVSM(5) Error: Wrong PMT; pmt->pn(6074) desired(145)
3782008-02-07 19:54:44.584 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
3792008-02-07 19:54:44.587 DTVSM(5) Error: Wrong PMT; pmt->pn(6060) desired(145)
3802008-02-07 19:54:44.589 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
3812008-02-07 19:54:44.590 DTVSM(5) Error: Wrong PMT; pmt->pn(6040) desired(145)
3822008-02-07 19:54:44.592 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
3832008-02-07 19:54:44.593 DTVSM(5) Error: Wrong PMT; pmt->pn(6035) desired(145)
3842008-02-07 19:54:44.597 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
3852008-02-07 19:54:44.599 DTVSM(5) Error: Wrong PMT; pmt->pn(6071) desired(145)
3862008-02-07 19:54:44.600 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
3872008-02-07 19:54:44.603 DTVSM(5) Error: Wrong PMT; pmt->pn(6073) desired(145)
3882008-02-07 19:54:44.606 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
3892008-02-07 19:54:44.608 DTVSM(5) Error: Wrong PMT; pmt->pn(6077) desired(145)
3902008-02-07 19:54:44.616 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
3912008-02-07 19:54:44.618 DTVSM(5) Error: Wrong PMT; pmt->pn(6086) desired(145)
3922008-02-07 19:54:44.619 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
3932008-02-07 19:54:44.621 DTVSM(5) Error: Wrong PMT; pmt->pn(6080) desired(145)
3942008-02-07 19:54:44.622 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
3952008-02-07 19:54:44.623 DTVSM(5) Error: Wrong PMT; pmt->pn(6001) desired(145)
3962008-02-07 19:54:44.641 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
3972008-02-07 19:54:44.642 DTVSM(5) Error: Wrong PMT; pmt->pn(633) desired(145)
3982008-02-07 19:54:44.644 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
3992008-02-07 19:54:44.645 DTVSM(5) Error: Wrong PMT; pmt->pn(9800) desired(145)
4002008-02-07 19:54:44.647 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
4012008-02-07 19:54:44.648 DTVSM(5) Error: Wrong PMT; pmt->pn(5802) desired(145)
4022008-02-07 19:54:44.661 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
4032008-02-07 19:54:44.663 DTVSM(5) Error: Wrong PMT; pmt->pn(5814) desired(145)
4042008-02-07 19:54:44.664 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
4052008-02-07 19:54:44.666 DTVSM(5) Error: Wrong PMT; pmt->pn(36862) desired(145)
4062008-02-07 19:54:44.678 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
4072008-02-07 19:54:44.680 DTVSM(5) Error: Wrong PMT; pmt->pn(239) desired(145)
4082008-02-07 19:54:44.682 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
4092008-02-07 19:54:44.684 SM(5)::AddFlags: Seen() Match(PMT,) Wait()
4102008-02-07 19:54:44.685 CreatePMTSingleProgram()
4112008-02-07 19:54:44.686 PMT in input stream
4122008-02-07 19:54:44.688 Program Map Table ver(11) pid(0x21) pnum(145) len(65)
413
414 Stream #0 pid(0x1e22) type(video-mpeg2  0x2)
415  Conditional Access: sid(0x1816) pid(0x63) data_size(0)
416  Conditional Access: sid(0x1810) pid(0x164) data_size(0)
417  Conditional Access: sid(0x1801) pid(0x164) data_size(0)
418 Stream #1 pid(0x1e23) type(audio-mp2-layer[1,2,3]  0x4)
419  Conditional Access: sid(0x1816) pid(0x63) data_size(0)
420  Conditional Access: sid(0x1810) pid(0x164) data_size(0)
421  Conditional Access: sid(0x1801) pid(0x164) data_size(0)
422  ISO-639 Language: code(eng) canonical(eng) eng(English)
423
4242008-02-07 19:54:44.690 PMT for output stream
4252008-02-07 19:54:44.695 Program Map Table ver(11) pid(0x21) pnum(1) len(29)
426
427 Stream #0 pid(0x1e22) type(video-mpeg2  0x2)
428 Stream #1 pid(0x1e23) type(audio-mp2-layer[1,2,3]  0x4)
429  ISO-639 Language: code(eng) canonical(eng) eng(English)
430
4312008-02-07 19:54:44.697 DVBSH(5): AddPIDFilter(0x1e22) priority 3
4322008-02-07 19:54:44.699 PIDInfo(5): Opening filter for pid 0x1e22
4332008-02-07 19:54:44.701 DVBSH(5): AddPIDFilter(0x1e23) priority 2
4342008-02-07 19:54:44.702 PIDInfo(5): Opening filter for pid 0x1e23
4352008-02-07 19:54:44.705 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
4362008-02-07 19:54:44.706 DTVSM(5) Error: Wrong PMT; pmt->pn(418) desired(145)
4372008-02-07 19:54:44.708 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
4382008-02-07 19:54:44.709 DTVSM(5) Error: Wrong PMT; pmt->pn(424) desired(145)
4392008-02-07 19:54:44.711 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
4402008-02-07 19:54:44.712 DTVSM(5) Error: Wrong PMT; pmt->pn(427) desired(145)
4412008-02-07 19:54:44.715 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
4422008-02-07 19:54:44.716 DTVSM(5) Error: Wrong PMT; pmt->pn(436) desired(145)
4432008-02-07 19:54:44.718 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
4442008-02-07 19:54:44.719 DTVSM(5) Error: Wrong PMT; pmt->pn(438) desired(145)
4452008-02-07 19:54:44.731 TVRec(5): Got good signal
4462008-02-07 19:54:44.734 TVRec(5): ClearFlags(WaitingForSignal,) -> RunMainLoop,SignalMonitorRunning,EITScannerRunning,
4472008-02-07 19:54:44.738 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
4482008-02-07 19:54:44.741 DTVSM(5) Error: Wrong PMT; pmt->pn(444) desired(145)
4492008-02-07 19:54:44.743 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
4502008-02-07 19:54:44.744 DTVSM(5) Error: Wrong PMT; pmt->pn(558) desired(145)
4512008-02-07 19:54:44.746 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
4522008-02-07 19:54:44.747 DTVSM(5) Error: Wrong PMT; pmt->pn(628) desired(145)
4532008-02-07 19:54:44.757 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
4542008-02-07 19:54:44.759 DTVSM(5) Error: Wrong PMT; pmt->pn(632) desired(145)
4552008-02-07 19:54:44.761 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
4562008-02-07 19:54:44.762 DTVSM(5) Error: Wrong PMT; pmt->pn(636) desired(145)
4572008-02-07 19:54:44.764 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
4582008-02-07 19:54:44.765 DTVSM(5) Error: Wrong PMT; pmt->pn(881) desired(145)
4592008-02-07 19:54:44.779 SM(5)::AddFlags: Seen(PMT,) Match() Wait()
4602008-02-07 19:54:44.780 DTVSM(5) Error: Wrong PMT; pmt->pn(946) desired(145)
4612008-02-07 19:54:44.961 MythSocket(a9202900:43): writeStringList: No data written on writeBlock
4622008-02-07 19:54:45.754 PID 0x1e22 status: Decrypted
4632008-02-07 19:54:45.759 PID 0x1e23 status: Decrypted
4642008-02-07 19:54:45.761 Program 145 status: Decrypted
4652008-02-07 19:54:45.762 SM(5)::AddFlags: Seen(Crypt,) Match() Wait()
4662008-02-07 19:54:45.764 SM(5)::AddFlags: Seen() Match(Crypt,) Wait()
4672008-02-07 19:54:47.196 DTVSM(5)::SetNIT(): net_id = 4100
4682008-02-07 19:54:47.198 SM(5)::AddFlags: Seen(NIT,) Match() Wait()
4692008-02-07 19:54:47.199 DTVSM(5)::setNIT(): nid = 4100
4702008-02-07 19:54:47.201 SM(5)::AddFlags: Seen() Match(NIT,) Wait()
4712008-02-07 19:54:47.203 DTVSM(5)::SetNIT(): net_id = 4100
4722008-02-07 19:54:47.205 SM(5)::AddFlags: Seen(NIT,) Match() Wait()
4732008-02-07 19:54:47.207 DTVSM(5)::setNIT(): nid = 4100
4742008-02-07 19:54:47.209 SM(5)::AddFlags: Seen() Match(NIT,) Wait()
4752008-02-07 19:54:47.211 DTVSM(5)::SetNIT(): net_id = 4100
4762008-02-07 19:54:47.213 SM(5)::AddFlags: Seen(NIT,) Match() Wait()
4772008-02-07 19:54:47.215 DTVSM(5)::setNIT(): nid = 4100
4782008-02-07 19:54:47.216 SM(5)::AddFlags: Seen() Match(NIT,) Wait()
4792008-02-07 19:54:58.399 MythSocket(8a43ed0:55): writeStringList: No data written on writeBlock
4802008-02-07 19:55:10.233 MythSocket(a9202900:43): writeStringList: No data written on writeBlock
4812008-02-07 19:55:14.186 SM(5)::AddFlags: Seen(SDT,) Match() Wait()
4822008-02-07 19:55:14.190 DTVSM(5)::SetSDT(): tsid = 18 orig_net_id = 4100
4832008-02-07 19:55:14.192 SM(5)::AddFlags: Seen() Match(SDT,) Wait()
4842008-02-07 19:55:14.193 SM(5)::RemoveFlags: Seen() Match() Wait(Pos,)
4852008-02-07 19:55:14.197 SM(5)::AddFlags: Seen(SDT,) Match() Wait()
4862008-02-07 19:55:14.200 DTVSM(5)::SetSDT(): tsid = 18 orig_net_id = 4100
4872008-02-07 19:55:14.202 SM(5)::AddFlags: Seen() Match(SDT,) Wait()
4882008-02-07 19:55:14.203 SM(5)::RemoveFlags: Seen() Match() Wait(Pos,)
4892008-02-07 19:55:14.206 DVBSH(5): AddPIDFilter(0x12) priority 2
4902008-02-07 19:55:14.207 PIDInfo(5): Opening filter for pid 0x12
4912008-02-07 19:55:14.209 DVBSH(5): AddPIDFilter(0x300) priority 2
4922008-02-07 19:55:14.210 PIDInfo(5): Opening filter for pid 0x300
4932008-02-07 19:55:14.212 DVBSH(5): AddPIDFilter(0x441) priority 2
4942008-02-07 19:55:14.213 PIDInfo(5): Opening filter for pid 0x441
4952008-02-07 19:55:21.791 MythSocket(8a43ed0:55): writeStringList: No data written on writeBlock
4962008-02-07 19:55:26.693 match[0]: 0 'Expedition Safari' vs. 'Field Sports'
4972008-02-07 19:55:27.215 match[0]: 0 'Escape to the Wild' vs. 'Field Sports'
4982008-02-07 19:55:33.130 MythSocket(a9202900:43): writeStringList: No data written on writeBlock
4992008-02-07 19:55:35.452 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x300, continuity counter: 2 (expected 6).
5002008-02-07 19:55:35.456 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x10, continuity counter: 0 (expected 6).
5012008-02-07 19:55:35.479 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x12, continuity counter: 13 (expected 8).
5022008-02-07 19:55:35.561 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x11, continuity counter: 7 (expected 1).
5032008-02-07 19:55:35.562 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x21, continuity counter: 13 (expected 2).
5042008-02-07 19:55:39.357 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x300, continuity counter: 6 (expected 13).
5052008-02-07 19:55:39.358 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x10, continuity counter: 8 (expected 15).
5062008-02-07 19:55:39.361 PSIP table is invalid
5072008-02-07 19:55:39.366 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x21, continuity counter: 4 (expected 11).
5082008-02-07 19:55:39.372 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x11, continuity counter: 5 (expected 1).
5092008-02-07 19:55:48.244 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x300, continuity counter: 5 (expected 13).
5102008-02-07 19:55:48.247 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x12, continuity counter: 6 (expected 1).
5112008-02-07 19:55:48.248 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x11, continuity counter: 0 (expected 7).
5122008-02-07 19:55:48.250 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x21, continuity counter: 10 (expected 8).
5132008-02-07 19:55:48.301 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x10, continuity counter: 14 (expected 11).
5142008-02-07 19:55:48.827 MythSocket(8a43ed0:55): writeStringList: No data written on writeBlock
5152008-02-07 19:55:48.833 MythSocket(ae60dde8:-1): writeStringList: Error, socket went unconnected.
5162008-02-07 19:55:53.903 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x300, continuity counter: 13 (expected 8).
5172008-02-07 19:55:53.941 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x21, continuity counter: 13 (expected 9).
5182008-02-07 19:55:53.944 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x10, continuity counter: 0 (expected 12).
5192008-02-07 19:55:58.420 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x300, continuity counter: 10 (expected 1).
5202008-02-07 19:55:58.431 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x11, continuity counter: 9 (expected 14).
5212008-02-07 19:55:58.457 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x12, continuity counter: 1 (expected 10).
5222008-02-07 19:55:58.460 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x10, continuity counter: 8 (expected 1).
5232008-02-07 19:55:58.469 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x21, continuity counter: 6 (expected 15).
5242008-02-07 19:56:03.990 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x12, continuity counter: 9 (expected 8).
5252008-02-07 19:56:03.994 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x10, continuity counter: 3 (expected 14).
5262008-02-07 19:56:04.002 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x21, continuity counter: 1 (expected 11).
5272008-02-07 19:56:04.201 PSIP table is invalid
5282008-02-07 19:58:42.449 PSIP table is invalid
5292008-02-07 19:58:42.459 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x10, continuity counter: 1 (expected 13).
5302008-02-07 19:58:42.495 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x441, continuity counter: 9 (expected 11).
5312008-02-07 19:58:42.498 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x10, continuity counter: 1 (expected 13).
5322008-02-07 19:58:42.545 UPnpMedia: BuildMediaMap VIDEO scan starting in :/shares/Videos:
5332008-02-07 19:58:45.661 UPnpMedia: BuildMediaMap Done. Found 1370 objects
5342008-02-07 19:58:46.316 MythSocket(a9202900:43): writeStringList: No data written on writeBlock
5352008-02-07 19:58:47.250 AutoExpire: CalcParams(): Max required Free Space: 6.0 GB w/freq: 15 min
5362008-02-07 19:58:47.262 Expiring 0 MBytes for 384 @ Thu Feb 7 19:00:00 2008 => The NewsHour With Jim Lehrer
5372008-02-07 19:58:47.275 autoexpire: Expiring Program: Expiring 0 MBytes for 384 @ Thu Feb 7 19:00:00 2008 => The NewsHour With Jim Lehrer
5382008-02-07 19:58:49.875 DVBSH(5): RemovePIDFilter(0x12)
5392008-02-07 19:58:49.878 PIDInfo(5): Closing filter for pid 0x12
5402008-02-07 19:58:49.880 DVBSH(5): RemovePIDFilter(0x300)
5412008-02-07 19:58:49.881 PIDInfo(5): Closing filter for pid 0x300
5422008-02-07 19:58:49.883 DVBSH(5): RemovePIDFilter(0x441)
5432008-02-07 19:58:49.884 PIDInfo(5): Closing filter for pid 0x441
5442008-02-07 19:58:50.420 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x21, continuity counter: 3 (expected 9).
5452008-02-07 19:58:50.421 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x10, continuity counter: 9 (expected 11).
5462008-02-07 19:58:50.423 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x11, continuity counter: 7 (expected 4).
5472008-02-07 19:58:57.317 MythSocket(8a43ed0:55): writeStringList: No data written on writeBlock
5482008-02-07 19:59:37.043 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x10, continuity counter: 5 (expected 4).
5492008-02-07 19:59:37.059 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x10, continuity counter: 2 (expected 4).
5502008-02-07 19:59:37.066 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x441, continuity counter: 8 (expected 14).
5512008-02-07 19:59:37.158 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x11, continuity counter: 2 (expected 8).
5522008-02-07 19:59:37.158 PSIP table is invalid
5532008-02-07 19:59:38.470 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x11, continuity counter: 12 (expected 2).
5542008-02-07 19:59:38.491 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x21, continuity counter: 1 (expected 3).
5552008-02-07 19:59:38.618 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x10, continuity counter: 4 (expected 9).
5562008-02-07 19:59:58.877 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x12, continuity counter: 6 (expected 4).
5572008-02-07 19:59:58.918 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x10, continuity counter: 2 (expected 12).
5582008-02-07 19:59:58.920 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x11, continuity counter: 15 (expected 4).
5592008-02-07 19:59:58.937 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x441, continuity counter: 8 (expected 0).
5602008-02-07 20:00:00.208 TVRec(1001): SwitchLiveTVRingBuffer(discont 0, set_rec 1)
5612008-02-07 20:00:00.212 TVRec(1001): GetProgramRingBufferForLiveTV()
5622008-02-07 20:01:49.112 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x10, continuity counter: 8 (expected 13).
5632008-02-07 20:01:49.130 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x11, continuity counter: 1 (expected 6).
5642008-02-07 20:01:49.142 TVRec(1001): StartedRecording(0xa6395fd8) fn(/shares/recordings/LiveTV/1501_20080207200000.mpg)
5652008-02-07 20:01:49.160 TFW: Using io multiplier 3, min/max = 16384/131072
5662008-02-07 20:01:49.231 TVRec(1001): FinishedRecording(Star Wars: Episode III -- Revenge of the Sith) in recgroup: LiveTV
5672008-02-07 20:01:49.236 Chain: Updated endtime for '1501_20080207183754' to 20080207200149
5682008-02-07 20:01:49.248 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x11, continuity counter: 9 (expected 6).
5692008-02-07 20:01:49.250 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x10, continuity counter: 4 (expected 13).
5702008-02-07 20:01:49.272 Finished recording Star Wars: Episode III -- Revenge of the Sith: channel 1501
5712008-02-07 20:01:49.284 scheduler: Last message repeated 1 times: Finished recording: The NewsHour With Jim Lehrer: channel 384
5722008-02-07 20:01:49.309 scheduler: Finished recording: Star Wars: Episode III -- Revenge of the Sith: channel 1501
5732008-02-07 20:01:49.314 Preview: 'myth://192.168.1.121:6543/1501_20080207183754.mpg' is not local,
574                        replacing with '/shares/recordings/LiveTV/1501_20080207183754.mpg', which is local.
5752008-02-07 20:01:49.352 Chain: Appended@2 '1501_20080207200000'
5762008-02-07 20:01:49.398 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x10, continuity counter: 4 (expected 10).
5772008-02-07 20:01:49.403 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x21, continuity counter: 11 (expected 9).
5782008-02-07 20:01:49.406 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x11, continuity counter: 0 (expected 6).
5792008-02-07 20:01:49.494 TVRec(1001): SetFlags(RingBufferReady,) -> FrontendReady,RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady,
5802008-02-07 20:01:49.504 TVRec(1001): !has_rec(1) !rec_soon(1) curRec(0x8886dc0) starttm(2008-02-07T18:37:54)
5812008-02-07 20:01:49.506 TVRec(1001): Enabling Full LiveTV UI.
5822008-02-07 20:01:49.579 RecBase(1001:/dev/video1): SetRingBuffer(0xa63b9a18) '/shares/recordings/LiveTV/1501_20080207200000.mpg'
5832008-02-07 20:01:49.583 TVRec(1001): RingBufferChanged()
5842008-02-07 20:01:49.629 TVRec(1001): FinishedRecording(Star Wars: Episode III -- Revenge of the Sith) in recgroup: LiveTV
5852008-02-07 20:01:49.638 Chain: Updated endtime for '1501_20080207183754' to 20080207200149
5862008-02-07 20:01:49.698 Finished recording Star Wars: Episode III -- Revenge of the Sith: channel 1501
5872008-02-07 20:01:49.706 Using runtime prefix = /opt/mns/mythtv.15682, libdir = /opt/mns/mythtv.15682/lib
5882008-02-07 20:01:49.711 Empty LocalHostName.
5892008-02-07 20:01:49.712 Using localhost value of corbin2
5902008-02-07 20:01:49.730 New DB connection, total: 1
5912008-02-07 20:01:49.743 Connected to database 'mythconverg' at host: corbin2
5922008-02-07 20:01:49.751 Closing DB connection named 'DBManager0'
5932008-02-07 20:01:49.754 Connected to database 'mythconverg' at host: corbin2
5942008-02-07 20:01:49.764 Current Schema Version: 1208
5952008-02-07 20:01:49.773 New DB connection, total: 2
5962008-02-07 20:01:49.776 Connected to database 'mythconverg' at host: corbin2
5972008-02-07 20:01:49.804 RingBuf(/shares/recordings/LiveTV/1501_20080207183754.mpg): Using io multiplier 3, chunk/max = 8192/163840
598QString::setNum: Invalid format char ''
5992008-02-07 20:01:52.442 AFD: Trying DirectShow for FOURCC 0x845639789
6002008-02-07 20:01:52.446 AFD: Opened codec 0x82a2360, id(MPEG2VIDEO) type(Video)
6012008-02-07 20:01:52.447 AFD: Opened codec 0x82b0e40, id(MP2) type(Audio)
6022008-02-07 20:01:52.898 Preview: Grabbed preview '/shares/recordings/LiveTV/1501_20080207183754.mpg' 720x480@69s
6032008-02-07 20:01:58.478 MythSocket(a9202900:43): writeStringList: No data written on writeBlock
6042008-02-07 20:02:00.447 Expiring 0 MBytes for 384 @ Thu Feb 7 19:00:00 2008 => The NewsHour With Jim Lehrer
6052008-02-07 20:02:03.461 match[0]: 0 'Friends' vs. 'The King of Queens'
6062008-02-07 20:02:04.178 match[0]: 0 'Wheel of Fortune' vs. 'Christina Cooks'
6072008-02-07 20:02:04.203 match[0]: -1800 'Lost' vs. 'Rick Steves' Europe'
6082008-02-07 20:02:04.235 match[1]: -1800 'Newschannel 5 at 6PM' vs. 'Curious George'
6092008-02-07 20:02:04.278 match[0]: 0 'Survivor: Fans vs. Favorites' vs. 'African American Lives'
6102008-02-07 20:02:10.321 MythSocket(8a43ed0:55): writeStringList: No data written on writeBlock
6112008-02-07 20:02:37.332 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x441, continuity counter: 4 (expected 2).
6122008-02-07 20:02:37.344 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x11, continuity counter: 2 (expected 0).
6132008-02-07 20:02:37.345 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x12, continuity counter: 7 (expected 14).
6142008-02-07 20:02:37.350 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x10, continuity counter: 2 (expected 12).
6152008-02-07 20:02:41.901 MythSocket(a9202900:43): writeStringList: No data written on writeBlock
6162008-02-07 20:02:55.274 MythSocket(8a43ed0:55): writeStringList: No data written on writeBlock
6172008-02-07 20:03:07.168 MythSocket(a9202900:43): writeStringList: No data written on writeBlock
6182008-02-07 20:03:18.618 MythSocket(8a43ed0:55): writeStringList: No data written on writeBlock
6192008-02-07 20:03:29.533 MythSocket(a9202900:43): writeStringList: No data written on writeBlock
6202008-02-07 20:03:44.479 MythSocket(8a43ed0:55): writeStringList: No data written on writeBlock
6212008-02-07 20:03:57.885 MythSocket(a9202900:43): writeStringList: No data written on writeBlock