2008-02-24 18:33:51.400 SM(5)::Stop: begin 2008-02-24 18:33:51.403 TVRec(5): ClearFlags(DummyRecorderRunning,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RingBufferReady, 2008-02-24 18:33:51.407 SM(5)::Stop: end 2008-02-24 18:33:51.420 TVRec(5): FinishedRecording(Zoolander) in recgroup: LiveTV 2008-02-24 18:33:51.425 Chain: Updated endtime for '638_20080224183344' to 20080224183351 2008-02-24 18:33:51.432 Finished recording Zoolander: channel 638 2008-02-24 18:33:51.450 scheduler: Finished recording: Zoolander: channel 638 2008-02-24 18:33:51.476 Using profile 'Live TV' to record 2008-02-24 18:33:51.479 TVRec(5): SwitchLiveTVRingBuffer(discont 1, set_rec 0) 2008-02-24 18:33:51.481 TVRec(5): GetProgramRingBufferForLiveTV() 2008-02-24 18:33:52.552 TVRec(5): StartedRecording(0xa2130050) fn(/shares/recordings/LiveTV/638_20080224183351.mpg) 2008-02-24 18:33:52.562 TFW: Using io multiplier 3, min/max = 16384/131072 2008-02-24 18:33:52.580 TVRec(5): FinishedRecording(Zoolander) in recgroup: LiveTV 2008-02-24 18:33:52.584 Chain: Updated endtime for '638_20080224183344' to 20080224183352 2008-02-24 18:33:52.627 Finished recording Zoolander: channel 638 2008-02-24 18:33:52.638 Preview: 'myth://192.168.1.121:6543/638_20080224183344.mpg' is not local, replacing with '/shares/recordings/LiveTV/638_20080224183344.mpg', which is local. 2008-02-24 18:33:52.655 Chain: Appended@2 '638_20080224183351' 2008-02-24 18:33:52.695 RecBase(5:5): SetRingBuffer(0xb12ef718) '/shares/recordings/LiveTV/638_20080224183351.mpg' 2008-02-24 18:33:52.700 DVBRec(5:5): SetPAT(9499 on 0x21) 2008-02-24 18:33:52.703 CreatePATSingleProgram() 2008-02-24 18:33:52.704 PAT in input stream 2008-02-24 18:33:52.706 Program Association Table PSIP tableID(0x0) length(37) extension(0x196) version(20) current(1) section(0) last_section(0) tsid: 406 programCount: 7 program number 0 has PID 0x 10 data 0x 0 0x 0 0xe0 0x10 program number 5352 has PID 0x 21 data 0x14 0xe8 0xe0 0x21 program number 5711 has PID 0x 21 data 0x16 0x4f 0xe0 0x21 program number 9499 has PID 0x 21 data 0x25 0x1b 0xe0 0x21 program number 9500 has PID 0x 21 data 0x25 0x1c 0xe0 0x21 program number 9508 has PID 0x 21 data 0x25 0x24 0xe0 0x21 program number 9509 has PID 0x 21 data 0x25 0x25 0xe0 0x21 2008-02-24 18:33:52.709 desired_program(9499) pid(0x21) 2008-02-24 18:33:52.711 pmt_pid(0x21) 2008-02-24 18:33:52.712 PAT for output stream 2008-02-24 18:33:52.714 Program Association Table PSIP tableID(0x0) length(13) extension(0x196) version(20) current(1) section(0) last_section(0) tsid: 406 programCount: 1 program number 1 has PID 0x 21 data 0x 0 0x 1 0xe0 0x21 2008-02-24 18:33:52.717 DVBRec(5:5): SetPMT(9499) 2008-02-24 18:33:52.719 CreatePMTSingleProgram() 2008-02-24 18:33:52.721 PMT in input stream 2008-02-24 18:33:52.722 Program Map Table ver(2) pid(0x21) pnum(9499) len(65) Stream #0 pid(0x1122) type(video-h264 0x1b) Conditional Access: sid(0x1816) pid(0x28) data_size(0) Conditional Access: sid(0x1810) pid(0x128) data_size(0) Conditional Access: sid(0x1801) pid(0x128) data_size(0) Stream #1 pid(0x1123) type(audio-ac3 0x81) Conditional Access: sid(0x1816) pid(0x28) data_size(0) Conditional Access: sid(0x1810) pid(0x128) data_size(0) Conditional Access: sid(0x1801) pid(0x128) data_size(0) ISO-639 Language: code(eng) canonical(eng) eng(English) 2008-02-24 18:33:52.725 PMT for output stream 2008-02-24 18:33:52.727 Program Map Table ver(2) pid(0x21) pnum(1) len(29) Stream #0 pid(0x1122) type(video-h264 0x1b) Stream #1 pid(0x1123) type(audio-ac3 0x81) ISO-639 Language: code(eng) canonical(eng) eng(English) 2008-02-24 18:33:52.729 RecBase(5:5): SetRecording(0xb12ef5d0) title(Zoolander) 2008-02-24 18:33:52.736 DVBRec(5:5): Card opened successfully fd(-1) 2008-02-24 18:33:52.739 DVBSH(5): AddListener(0xa69c0948) -- begin 2008-02-24 18:33:52.741 DVBSH(5): AddListener(0xa69c0948) -- locked 2008-02-24 18:33:52.743 TVRec(5): SetFlags(RecorderRunning,RingBufferReady,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady, 2008-02-24 18:33:52.746 TVRec(5): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady, 2008-02-24 18:33:52.748 DVBSH(5): AddListener(0xa69c0948) -- end 2008-02-24 18:33:52.824 AutoExpire: CalcParams(): Max required Free Space: 7.0 GB w/freq: 15 min 2008-02-24 18:33:53.041 DevRdB(5): buffer size 65800 KB 2008-02-24 18:33:53.044 DVBSH(5): RunTS(): begin 2008-02-24 18:33:53.046 DVBSH(5): AddPIDFilter(0x0) priority 2 2008-02-24 18:33:53.048 PIDInfo(5): Opening filter for pid 0x0 2008-02-24 18:33:53.051 DVBSH(5): AddPIDFilter(0x10) priority 2 2008-02-24 18:33:53.053 PIDInfo(5): Opening filter for pid 0x10 2008-02-24 18:33:53.055 DVBSH(5): AddPIDFilter(0x11) priority 2 2008-02-24 18:33:53.056 PIDInfo(5): Opening filter for pid 0x11 2008-02-24 18:33:53.058 DVBSH(5): AddPIDFilter(0x14) priority 2 2008-02-24 18:33:53.060 PIDInfo(5): Opening filter for pid 0x14 2008-02-24 18:33:53.061 DVBSH(5): AddPIDFilter(0x21) priority 2 2008-02-24 18:33:53.063 PIDInfo(5): Opening filter for pid 0x21 2008-02-24 18:33:53.065 DVBSH(5): AddPIDFilter(0x1122) priority 3 2008-02-24 18:33:53.067 PIDInfo(5): Opening filter for pid 0x1122 2008-02-24 18:33:53.069 DVBSH(5): AddPIDFilter(0x1123) priority 3 2008-02-24 18:33:53.071 PIDInfo(5): Opening filter for pid 0x1123 2008-02-24 18:33:53.073 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x10, continuity counter: 14 (expected 7). 2008-02-24 18:33:53.075 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x11, continuity counter: 0 (expected 3). 2008-02-24 18:33:53.108 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x21, continuity counter: 13 (expected 4). 2008-02-24 18:33:53.157 PID 0x1123 Found Payload Start 2008-02-24 18:33:53.262 Using runtime prefix = /opt/mns/mythtv.15682, libdir = /opt/mns/mythtv.15682/lib 2008-02-24 18:33:53.269 Empty LocalHostName. 2008-02-24 18:33:53.271 Using localhost value of corbin2 2008-02-24 18:33:53.345 New DB connection, total: 1 2008-02-24 18:33:53.367 Connected to database 'mythconverg' at host: corbin2 2008-02-24 18:33:53.373 Closing DB connection named 'DBManager0' 2008-02-24 18:33:53.377 Connected to database 'mythconverg' at host: corbin2 2008-02-24 18:33:53.385 Current Schema Version: 1208 2008-02-24 18:33:53.405 New DB connection, total: 2 2008-02-24 18:33:53.408 Connected to database 'mythconverg' at host: corbin2 2008-02-24 18:33:53.447 Preview Error: Previewer file '/shares/recordings/LiveTV/638_20080224183344.mpg' is not valid. 2008-02-24 18:33:53.451 Preview Error: Run() file not local: '/shares/recordings/LiveTV/638_20080224183344.mpg' 2008-02-24 18:33:53.482 Preview Error: Preview process not ok. fileinfo(/shares/recordings/LiveTV/638_20080224183344.mpg.png) exits: 0 readable: 0 size: 0 2008-02-24 18:33:53.532 DVBRec(5:5): PID 0x1123 discontinuity detected 2008-02-24 18:33:53.853 PID 0x1122 Found Payload Start 2008-02-24 18:33:54.862 SM(3)::AddFlags: Seen(SDT,) Match() Wait() 2008-02-24 18:33:54.869 DTVSM(3)::SetSDT(): tsid = 1 orig_net_id = 256 2008-02-24 18:33:54.871 SM(3)::AddFlags: Seen() Match(SDT,) Wait() 2008-02-24 18:33:54.872 SM(3)::RemoveFlags: Seen() Match() Wait(Pos,) 2008-02-24 18:33:54.874 SM(3)::AddFlags: Seen(SDT,) Match() Wait() 2008-02-24 18:33:54.876 DTVSM(3)::SetSDT(): tsid = 1 orig_net_id = 256 2008-02-24 18:33:54.878 SM(3)::AddFlags: Seen() Match(SDT,) Wait() 2008-02-24 18:33:54.880 SM(3)::RemoveFlags: Seen() Match() Wait(Pos,) 2008-02-24 18:33:54.882 DVBSH(3): AddPIDFilter(0x12) priority 2 2008-02-24 18:33:54.884 PIDInfo(3): Opening filter for pid 0x12 2008-02-24 18:33:54.887 DVBSH(3): AddPIDFilter(0x300) priority 2 2008-02-24 18:33:54.889 PIDInfo(3): Opening filter for pid 0x300 2008-02-24 18:33:54.892 DVBSH(3): AddPIDFilter(0x441) priority 2 2008-02-24 18:33:54.894 PIDInfo(3): Opening filter for pid 0x441 2008-02-24 18:33:56.815 Scheduled 216 items in 8.2 = 0.54 match + 7.61 place 2008-02-24 18:33:56.832 scheduler: Last message repeated 1 times: Finished recording: Zoolander: channel 638 2008-02-24 18:33:56.849 scheduler: Scheduled items: Scheduled 216 items in 8.2 = 0.54 match + 7.61 place 2008-02-24 18:33:58.752 DVBSH(3): RemovePIDFilter(0x12) 2008-02-24 18:33:58.762 PIDInfo(3): Closing filter for pid 0x12 2008-02-24 18:33:58.765 DVBSH(3): RemovePIDFilter(0x300) 2008-02-24 18:33:58.766 PIDInfo(3): Closing filter for pid 0x300 2008-02-24 18:33:58.769 DVBSH(3): RemovePIDFilter(0x441) 2008-02-24 18:33:58.770 PIDInfo(3): Closing filter for pid 0x441 2008-02-24 18:34:12.247 Expiring 10 MBytes for 1356 @ Sun Feb 24 18:00:00 2008 => Meet the Press 2008-02-24 18:34:12.262 autoexpire: Expiring Program: Expiring 10 MBytes for 1356 @ Sun Feb 24 18:00:00 2008 => Meet the Press 2008-02-24 18:34:12.264 Expiring 0 MBytes for 50627 @ Sun Feb 24 18:00:00 2008 => Terminator... "Dungeons & Dragons" 2008-02-24 18:34:12.280 autoexpire: Expiring Program: Expiring 0 MBytes for 50627 @ Sun Feb 24 18:00:00 2008 => Terminator... "Dungeons & Dragons" 2008-02-24 18:34:12.282 Expiring 2 MBytes for 50627 @ Sun Feb 24 18:00:00 2008 => Terminator... "Dungeons & Dragons" 2008-02-24 18:34:12.299 autoexpire: Expiring Program: Expiring 2 MBytes for 50627 @ Sun Feb 24 18:00:00 2008 => Terminator... "Dungeons & Dragons" 2008-02-24 18:34:12.301 Expiring 4 MBytes for 1356 @ Sun Feb 24 18:00:00 2008 => Meet the Press 2008-02-24 18:34:12.316 autoexpire: Expiring Program: Expiring 4 MBytes for 1356 @ Sun Feb 24 18:00:00 2008 => Meet the Press 2008-02-24 18:34:12.319 Expiring 0 MBytes for 50627 @ Sun Feb 24 18:00:00 2008 => Terminator... "Dungeons & Dragons" 2008-02-24 18:34:12.332 autoexpire: Expiring Program: Expiring 0 MBytes for 50627 @ Sun Feb 24 18:00:00 2008 => Terminator... "Dungeons & Dragons" 2008-02-24 18:34:12.334 Expiring 5 MBytes for 50627 @ Sun Feb 24 18:00:00 2008 => Terminator... "Dungeons & Dragons" 2008-02-24 18:34:12.349 autoexpire: Expiring Program: Expiring 5 MBytes for 50627 @ Sun Feb 24 18:00:00 2008 => Terminator... "Dungeons & Dragons" 2008-02-24 18:34:13.513 DVBSH(5): AddPIDFilter(0x12) priority 2 2008-02-24 18:34:13.518 PIDInfo(5): Opening filter for pid 0x12 2008-02-24 18:34:13.522 DVBSH(5): AddPIDFilter(0x300) priority 2 2008-02-24 18:34:13.523 PIDInfo(5): Opening filter for pid 0x300 2008-02-24 18:34:13.526 DVBSH(5): AddPIDFilter(0x441) priority 2 2008-02-24 18:34:13.527 PIDInfo(5): Opening filter for pid 0x441 2008-02-24 18:34:15.550 TVRec(5): StopLiveTV(void) curRec: 0xa2130050 pseudoRec: 0 2008-02-24 18:34:15.558 TVRec(5): Changing from WatchingLiveTV to None 2008-02-24 18:34:15.562 TVRec(5): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RecorderRunning,RingBufferReady, 2008-02-24 18:34:15.564 TVRec(5): Request: Program(no) channel() input() flags(KillRec,KillRingBuffer,) 2008-02-24 18:34:15.589 DVBSH(5): RemoveListener(0xa69c0948) -- begin 2008-02-24 18:34:15.591 DVBSH(5): RemoveListener(0xa69c0948) -- locked 2008-02-24 18:34:15.613 DVBSH(5): RunTS(): shutdown 2008-02-24 18:34:15.615 DVBSH(5): RemovePIDFilter(0x0) 2008-02-24 18:34:15.617 PIDInfo(5): Closing filter for pid 0x0 2008-02-24 18:34:15.619 DVBSH(5): RemovePIDFilter(0x10) 2008-02-24 18:34:15.620 PIDInfo(5): Closing filter for pid 0x10 2008-02-24 18:34:15.623 DVBSH(5): RemovePIDFilter(0x11) 2008-02-24 18:34:15.627 PIDInfo(5): Closing filter for pid 0x11 2008-02-24 18:34:15.629 DVBSH(5): RemovePIDFilter(0x12) 2008-02-24 18:34:15.630 PIDInfo(5): Closing filter for pid 0x12 2008-02-24 18:34:15.632 DVBSH(5): RemovePIDFilter(0x14) 2008-02-24 18:34:15.634 PIDInfo(5): Closing filter for pid 0x14 2008-02-24 18:34:15.636 DVBSH(5): RemovePIDFilter(0x21) 2008-02-24 18:34:15.638 PIDInfo(5): Closing filter for pid 0x21 2008-02-24 18:34:15.641 DVBSH(5): RemovePIDFilter(0x300) 2008-02-24 18:34:15.643 PIDInfo(5): Closing filter for pid 0x300 2008-02-24 18:34:15.645 DVBSH(5): RemovePIDFilter(0x441) 2008-02-24 18:34:15.646 PIDInfo(5): Closing filter for pid 0x441 2008-02-24 18:34:15.648 DVBSH(5): RemovePIDFilter(0x1122) 2008-02-24 18:34:15.650 PIDInfo(5): Closing filter for pid 0x1122 2008-02-24 18:34:15.651 DVBSH(4): AddPIDFilter(0x12) priority 2 2008-02-24 18:34:15.654 DVBSH(5): RemovePIDFilter(0x1123) 2008-02-24 18:34:15.656 PIDInfo(4): Opening filter for pid 0x12 2008-02-24 18:34:15.659 PIDInfo(5): Closing filter for pid 0x1123 2008-02-24 18:34:15.661 DVBSH(4): AddPIDFilter(0x300) priority 2 2008-02-24 18:34:15.677 PIDInfo(4): Opening filter for pid 0x300 2008-02-24 18:34:15.678 DVBSH(5): RunTS(): end 2008-02-24 18:34:15.680 DVBSH(4): AddPIDFilter(0x441) priority 2 2008-02-24 18:34:15.683 DVBSH(5): RemoveListener(0xa69c0948) -- end 2008-02-24 18:34:15.684 PIDInfo(4): Opening filter for pid 0x441 2008-02-24 18:34:15.686 DVBRec(5:5): Close() fd(-1) -- begin 2008-02-24 18:34:15.694 DVBRec(5:5): Close() fd(-1) -- end 2008-02-24 18:34:15.808 TVRec(5): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady, 2008-02-24 18:34:15.821 TVRec(5): FinishedRecording(Zoolander) in recgroup: LiveTV 2008-02-24 18:34:15.827 Chain: Updated endtime for '638_20080224183351' to 20080224183415 2008-02-24 18:34:15.832 Finished recording Zoolander: channel 638 2008-02-24 18:34:15.846 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x441, continuity counter: 0 (expected 14). 2008-02-24 18:34:15.847 scheduler: Finished recording: Zoolander: channel 638 2008-02-24 18:34:15.852 TVRec(5): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady, 2008-02-24 18:34:15.854 TVRec(5): Tearing down RingBuffer 2008-02-24 18:34:15.859 TVRec(5): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady, 2008-02-24 18:34:16.351 DVBSH(4): RemovePIDFilter(0x12) 2008-02-24 18:34:16.354 PIDInfo(4): Closing filter for pid 0x12 2008-02-24 18:34:16.356 DVBSH(4): RemovePIDFilter(0x300) 2008-02-24 18:34:16.359 PIDInfo(4): Closing filter for pid 0x300 2008-02-24 18:34:16.361 DVBSH(4): RemovePIDFilter(0x441) 2008-02-24 18:34:16.362 PIDInfo(4): Closing filter for pid 0x441 2008-02-24 18:34:18.485 Getting next free recorder after : -1 2008-02-24 18:34:18.488 Checking card 1. Best card so far -1 2008-02-24 18:34:18.490 Checking card 2. Best card so far 2 2008-02-24 18:34:18.491 Checking card 3. Best card so far 2 2008-02-24 18:34:18.493 Checking card 4. Best card so far 4 2008-02-24 18:34:18.495 Checking card 5. Best card so far 5 2008-02-24 18:34:18.496 Checking card 12. Best card so far 5 2008-02-24 18:34:18.498 Checking card 105. Best card so far 105 2008-02-24 18:34:18.499 Checking card 205. Best card so far 205 2008-02-24 18:34:18.501 Checking card 1000. Best card so far 1000 2008-02-24 18:34:18.503 Checking card 1001. Best card so far 1001 2008-02-24 18:34:18.508 MainServer::HandleAnnounce Playback 2008-02-24 18:34:18.510 adding: pc4 as a client (events: 0) 2008-02-24 18:34:18.517 TVRec(1001): Changing from None to WatchingLiveTV 2008-02-24 18:34:18.520 TVRec(1001): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RingBufferReady, 2008-02-24 18:34:18.523 TVRec(1001): Request: Program(no) channel() input() flags(LiveTV,) 2008-02-24 18:34:18.529 TVRec(1001): Start channel: 356. 2008-02-24 18:34:18.545 TVRec(1001): HW Tuner: 1001->1001 2008-02-24 18:34:18.547 TVRec(1001): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady, 2008-02-24 18:34:18.548 TVRec(1001): No recorder yet, calling TuningFrequency 2008-02-24 18:34:18.554 Channel(/dev/video1): Device name 'WinTV PVR 500 (unit #2)' driver 'ivtv'. 2008-02-24 18:34:19.681 ChannelBase(1001): Input #2: 'S-Video 1' schan(356) sourceid(1) ccid(1001) 2008-02-24 18:34:19.683 ChannelBase(1001): Current Input #2: 'S-Video 1' 2008-02-24 18:34:19.684 Global TVFormat Setting 'NTSC' 2008-02-24 18:34:19.686 Channel(/dev/video1): Input #2: 'S-Video 1' schan(356) tun() v4l1(NTSC) v4l2(NTSC) 2008-02-24 18:34:19.687 Channel(/dev/video1): SetFormat(Default) fmt(NTSC) input(2) 2008-02-24 18:34:19.689 Channel(/dev/video1)::SwitchToInput(in 2, '') 2008-02-24 18:34:19.696 Channel(/dev/video1): SetInputAndFormat(2, NTSC) (v4l v2) 2008-02-24 18:34:19.698 Channel(/dev/video1): SetChannelByString(356) 2008-02-24 18:34:19.709 Channel(/dev/video1): SetFormat(Default) fmt(NTSC) input(2) 2008-02-24 18:34:19.711 External channel change: /usr/local/bin/RCA2.pl 356 2008-02-24 18:34:19.717 Waiting for External Tuning program to exit 3562008-02-24 18:34:19.938 DVBSH(3): AddPIDFilter(0x12) priority 2 2008-02-24 18:34:19.943 PIDInfo(3): Opening filter for pid 0x12 2008-02-24 18:34:19.945 DVBSH(3): AddPIDFilter(0x300) priority 2 2008-02-24 18:34:19.947 PIDInfo(3): Opening filter for pid 0x300 2008-02-24 18:34:19.948 DVBSH(3): AddPIDFilter(0x441) priority 2 2008-02-24 18:34:19.950 PIDInfo(3): Opening filter for pid 0x441 2008-02-24 18:34:20.625 DVBSH(3): RemovePIDFilter(0x12) 2008-02-24 18:34:20.627 PIDInfo(3): Closing filter for pid 0x12 2008-02-24 18:34:20.629 DVBSH(3): RemovePIDFilter(0x300) 2008-02-24 18:34:20.630 PIDInfo(3): Closing filter for pid 0x300 2008-02-24 18:34:20.632 DVBSH(3): RemovePIDFilter(0x441) 2008-02-24 18:34:20.634 PIDInfo(3): Closing filter for pid 0x441 2008-02-24 18:34:20.721 ret_pid(5116) child(5116) status(0x0) 2008-02-24 18:34:20.723 External Tuning program no longer running 2008-02-24 18:34:20.725 External Tuning program exited with no error 2008-02-24 18:34:20.731 Channel(/dev/video1): InitPictureAttribute(brightness): 128 [ 0, 255] dflt( 128, 0.50, 128) 2008-02-24 18:34:20.738 Channel(/dev/video1): InitPictureAttribute( contrast): 63 [ 0, 127] dflt( 64, 0.50, 257) 2008-02-24 18:34:20.744 Channel(/dev/video1): InitPictureAttribute( colour): 63 [ 0, 127] dflt( 64, 0.50, 257) 2008-02-24 18:34:20.752 Channel(/dev/video1): InitPictureAttribute( hue): 0 [ -128, 127] dflt( 0, 0.50, 128) 2008-02-24 18:34:20.755 TVRec(1001): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,RingBufferReady, 2008-02-24 18:34:20.756 TVRec(1001): SetFlags(NeedToStartRecorder,) -> RunMainLoop,NeedToStartRecorder,RingBufferReady, 2008-02-24 18:34:20.758 TVRec(1001): Starting Recorder 2008-02-24 18:34:20.855 Using profile 'Live TV' to record 2008-02-24 18:34:20.857 TVRec(1001): CreateLiveTVRingBuffer() 2008-02-24 18:34:20.859 TVRec(1001): GetProgramRingBufferForLiveTV() 2008-02-24 18:34:21.929 TVRec(1001): StartedRecording(0xa216df88) fn(/shares/recordings/LiveTV/1356_20080224183420.mpg) 2008-02-24 18:34:21.939 TFW: Using io multiplier 3, min/max = 16384/131072 2008-02-24 18:34:21.947 Chain: Appended@0 '1356_20080224183420' 2008-02-24 18:34:21.953 TVRec(1001): SetFlags(RingBufferReady,) -> RunMainLoop,NeedToStartRecorder,RingBufferReady, 2008-02-24 18:34:21.964 RecBase(1001:/dev/video1): SetRingBuffer(0xa902f7d0) '/shares/recordings/LiveTV/1356_20080224183420.mpg' 2008-02-24 18:34:21.965 RecBase(1001:/dev/video1): SetRecording(0xa903c7a0) title(Meet the Press) 2008-02-24 18:34:21.971 Channel(/dev/video1): InitPictureAttribute(brightness): 128 [ 0, 255] dflt( 128, 0.50, 128) 2008-02-24 18:34:21.976 Channel(/dev/video1): InitPictureAttribute( contrast): 63 [ 0, 127] dflt( 64, 0.50, 257) 2008-02-24 18:34:21.982 Channel(/dev/video1): InitPictureAttribute( colour): 63 [ 0, 127] dflt( 64, 0.50, 257) 2008-02-24 18:34:21.989 Channel(/dev/video1): InitPictureAttribute( hue): 0 [ -128, 127] dflt( 0, 0.50, 128) 2008-02-24 18:34:21.992 TVRec(1001): SetFlags(CancelNextRecording,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RingBufferReady, 2008-02-24 18:34:21.992 MPEGRec(/dev/video1): usingv4l2(1) has_v4l2_vbi(1) has_buggy_vbi(1) 2008-02-24 18:34:22.022 MPEGRec(/dev/video1) Warning: Stream type 'MPEG-2 TS' is not supported by ivtv driver, using 'MPEG-2 PS' instead. 2008-02-24 18:34:22.165 MPEGRec(/dev/video1) Warning: VBI recording with broken drivers. Upgrade to ivtv 0.10.0 if you experience problems. 2008-02-24 18:34:22.258 MPEGRec(/dev/video1): VBI service: 4096, io size: 0 2008-02-24 18:34:22.284 AutoExpire: CalcParams(): Max required Free Space: 6.0 GB w/freq: 15 min 2008-02-24 18:34:22.284 TVRec(1001): SetFlags(RecorderRunning,RingBufferReady,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady, 2008-02-24 18:34:22.292 TVRec(1001): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady, 2008-02-24 18:34:24.738 TVRec(1001): SetFlags(FrontendReady,) -> FrontendReady,RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady, 2008-02-24 18:34:28.608 TVRec(1001): StopLiveTV(void) curRec: 0xa216df88 pseudoRec: 0 2008-02-24 18:34:28.618 TVRec(1001): Changing from WatchingLiveTV to None 2008-02-24 18:34:28.620 TVRec(1001): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RecorderRunning,RingBufferReady, 2008-02-24 18:34:28.622 TVRec(1001): Request: Program(no) channel() input() flags(KillRec,KillRingBuffer,) 2008-02-24 18:34:28.752 TVRec(1001): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady, 2008-02-24 18:34:28.783 TVRec(1001): FinishedRecording(Meet the Press) in recgroup: LiveTV 2008-02-24 18:34:28.786 Chain: Updated endtime for '1356_20080224183420' to 20080224183428 2008-02-24 18:34:28.800 Finished recording Meet the Press: channel 1356 2008-02-24 18:34:28.811 scheduler: Finished recording: Meet the Press: channel 1356 2008-02-24 18:34:28.815 TVRec(1001): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady, 2008-02-24 18:34:28.817 TVRec(1001): Tearing down RingBuffer 2008-02-24 18:34:28.820 TVRec(1001): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady, 2008-02-24 18:34:29.014 MainServer::HandleAnnounce Playback 2008-02-24 18:34:29.018 adding: pc4 as a client (events: 0) 2008-02-24 18:34:29.024 TVRec(5): Changing from None to WatchingLiveTV 2008-02-24 18:34:29.027 TVRec(5): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RingBufferReady, 2008-02-24 18:34:29.028 TVRec(5): Request: Program(no) channel() input() flags(LiveTV,) 2008-02-24 18:34:29.030 ChannelBase(5): Looking for startchannel '110' on input 'DiSEqC v1.3 Input 1' 2008-02-24 18:34:29.065 ChannelBase(5): Found startchannel '110' on input 'DiSEqC v1.3 Input 2' 2008-02-24 18:34:29.068 TVRec(5): HW Tuner: 5->5 2008-02-24 18:34:29.070 TVRec(5): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady, 2008-02-24 18:34:29.071 TVRec(5): No recorder yet, calling TuningFrequency 2008-02-24 18:34:29.076 DVBChan(5:5): Opening DVB channel 2008-02-24 18:34:29.077 DVBChan(5:5): SetChannelByString(110): 2008-02-24 18:34:29.084 ChannelBase(5): Failed to find channel(110) on current input (DiSEqC v1.3 Input 5) of card (5). 2008-02-24 18:34:29.090 ChannelBase(5): Found channel(110) on another input (DiSEqC v1.3 Input 2) of card (5). 2008-02-24 18:34:29.114 DVBChan(5:5): 12296900 qpsk a auto auto a a auto a l 2008-02-24 18:34:29.120 DiSEqCDevTree: Overriding voltage to 18V for faster rotor movement 2008-02-24 18:34:29.140 DiSEqCDevTree: USALS Rotor - Goto -119 (Azimuth -45.8453) 2008-02-24 18:34:29.143 DiSEqCDevTree: Sending DiSEqC Command: e0 31 6e d2 dd 2008-02-24 18:34:29.346 DVBChan(5:5): Old Params: 12296900 t_8psk a auto auto a a auto a l DVBChan(5:5): New Params: 12296900 qpsk a auto auto a a auto a l 2008-02-24 18:34:29.349 DVBChan(5:5): Tune(): Tuning to 1046900kHz 2008-02-24 18:34:29.365 dvbchannel.cpp:wait_for_backend: Status: 2008-02-24 18:34:29.367 DVBChan(5:5): Tune(): Frequency tuning successful. 2008-02-24 18:34:29.368 DVBChan(5:5): SetChannelByString(110): Tuned to frequency. 2008-02-24 18:34:29.370 TVRec(5): CreateLiveTVRingBuffer() 2008-02-24 18:34:29.371 TVRec(5): GetProgramRingBufferForLiveTV() 2008-02-24 18:34:30.456 TVRec(5): StartedRecording(0xb1341f00) fn(/shares/recordings/LiveTV/3110_20080224183429.mpg) 2008-02-24 18:34:30.468 TFW: Using io multiplier 3, min/max = 16384/131072 2008-02-24 18:34:30.481 Chain: Appended@1 '3110_20080224183429' 2008-02-24 18:34:30.491 TVRec(5): Starting Signal Monitor 2008-02-24 18:34:30.493 TVRec(5): SetupSignalMonitor(1, 1) 2008-02-24 18:34:30.494 DVBChan(5:5): Opening DVB channel 2008-02-24 18:34:30.510 DVBSM(5): Can measure Signal Strength 2008-02-24 18:34:30.511 DVBSM(5): Can measure S/N 2008-02-24 18:34:30.513 DVBSM(5): Can measure Bit Error Rate 2008-02-24 18:34:30.515 DVBSM(5): Can count Uncorrected Blocks 2008-02-24 18:34:30.517 SM(5)::RemoveFlags: Seen() Match() Wait() 2008-02-24 18:34:30.518 DVBSM(5): DVBSignalMonitor::ctor initial flags Seen() Match() Wait(Sig,SNR,BER,UB,) 2008-02-24 18:34:30.520 TVRec(5): Signal monitor successfully created 2008-02-24 18:34:30.521 TVRec(5): Setting up table monitoring. 2008-02-24 18:34:30.543 Using profile 'Live TV' to record 2008-02-24 18:34:30.545 TVRec(5): DVB service_id 110 on net_id 4100 tsid 6 2008-02-24 18:34:30.546 DTVSM(5)::SetDVBService(transport_id: 6, network_id: 4100, service_id: 110): 2008-02-24 18:34:30.548 SM(5)::RemoveFlags: Seen(PMT,SDT,Crypt,) Match(PMT,SDT,Crypt,) Wait() 2008-02-24 18:34:30.550 SM(5)::AddFlags: Seen() Match() Wait(PMT,NIT,) 2008-02-24 18:34:30.551 SM(5)::AddFlags: Seen() Match() Wait(PMT,NIT,Pos,) 2008-02-24 18:34:30.552 TVRec(5): Successfully set up DVB table monitoring. 2008-02-24 18:34:30.554 SM(5)::Start: begin 2008-02-24 18:34:30.556 DTVSM(5)::GetStatusList: WaitForPMT seen(0) matching(0) 2008-02-24 18:34:30.557 SM(5)::Start: end 2008-02-24 18:34:30.562 TVRec(5): SetFlags(SignalMonitorRunning,) -> RunMainLoop,SignalMonitorRunning,RingBufferReady, 2008-02-24 18:34:30.565 TVRec(5): ClearFlags(WaitingForSignal,) -> RunMainLoop,SignalMonitorRunning,RingBufferReady, 2008-02-24 18:34:30.567 TVRec(5): SetFlags(WaitingForSignal,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,RingBufferReady, 2008-02-24 18:34:30.568 TVRec(5): SetFlags(DummyRecorderRunning,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, 2008-02-24 18:34:30.569 DummyDTVRecorder -- started 2008-02-24 18:34:30.571 TVRec(5): SetFlags(RingBufferReady,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, 2008-02-24 18:34:30.573 TVRec(5): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, 2008-02-24 18:34:30.574 TVRec(5): SetFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, 2008-02-24 18:34:30.578 TVRec(5): SetFlags(CancelNextRecording,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, 2008-02-24 18:34:30.606 AutoExpire: CalcParams(): Max required Free Space: 7.0 GB w/freq: 15 min 2008-02-24 18:34:34.776 DVBSM(5): UpdateValues -- Signal Locked 2008-02-24 18:34:35.118 DVBSH(5): Retuning for rotor completion