2008-02-27 10:46:41.516 Checking card 1. Best card so far -1 2008-02-27 10:46:41.517 Checking card 2. Best card so far 2 2008-02-27 10:46:41.519 Checking card 3. Best card so far 2 2008-02-27 10:46:41.520 Checking card 4. Best card so far 4 2008-02-27 10:46:41.521 Checking card 5. Best card so far 5 2008-02-27 10:46:41.523 Checking card 12. Best card so far 5 2008-02-27 10:46:41.524 Checking card 105. Best card so far 105 2008-02-27 10:46:41.525 Checking card 205. Best card so far 205 2008-02-27 10:46:41.527 Checking card 1000. Best card so far 1000 2008-02-27 10:46:41.528 Checking card 1001. Best card so far 1001 2008-02-27 10:46:41.532 MainServer::HandleAnnounce Playback 2008-02-27 10:46:41.533 adding: pc4 as a client (events: 0) 2008-02-27 10:46:41.538 TVRec(1001): Changing from None to WatchingLiveTV 2008-02-27 10:46:41.540 TVRec(1001): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop, 2008-02-27 10:46:41.542 TVRec(1001): Request: Program(no) channel() input() flags(LiveTV,) 2008-02-27 10:46:41.544 TVRec(1001): Start channel: 10. 2008-02-27 10:46:41.553 TVRec(1001): HW Tuner: 1001->1001 2008-02-27 10:46:41.554 TVRec(1001): ClearFlags(PENDINGACTIONS,) -> RunMainLoop, 2008-02-27 10:46:41.556 TVRec(1001): No recorder yet, calling TuningFrequency 2008-02-27 10:46:41.558 Channel(/dev/video1): Device name 'WinTV PVR 500 (unit #2)' driver 'ivtv'. 2008-02-27 10:46:42.599 ChannelBase(1001): Input #2: 'S-Video 1' schan(10) sourceid(1) ccid(1001) 2008-02-27 10:46:42.601 ChannelBase(1001): Current Input #2: 'S-Video 1' 2008-02-27 10:46:42.602 Global TVFormat Setting 'NTSC' 2008-02-27 10:46:42.604 Channel(/dev/video1): Input #2: 'S-Video 1' schan(10) tun() v4l1(NTSC) v4l2(NTSC) 2008-02-27 10:46:42.605 Channel(/dev/video1): SetFormat(Default) fmt(NTSC) input(2) 2008-02-27 10:46:42.606 Channel(/dev/video1)::SwitchToInput(in 2, '') 2008-02-27 10:46:42.615 Channel(/dev/video1): SetInputAndFormat(2, NTSC) (v4l v2) 2008-02-27 10:46:42.617 Channel(/dev/video1): SetChannelByString(10) 2008-02-27 10:46:42.626 Channel(/dev/video1): SetFormat(Default) fmt(NTSC) input(2) 2008-02-27 10:46:42.628 External channel change: /usr/local/bin/RCA2.pl 10 2008-02-27 10:46:42.633 Waiting for External Tuning program to exit 102008-02-27 10:46:43.636 ret_pid(3355) child(3355) status(0x0) 2008-02-27 10:46:43.640 External Tuning program no longer running 2008-02-27 10:46:43.642 External Tuning program exited with no error 2008-02-27 10:46:43.646 Channel(/dev/video1): InitPictureAttribute(brightness): 128 [ 0, 255] dflt( 128, 0.50, 128) 2008-02-27 10:46:43.652 Channel(/dev/video1): InitPictureAttribute( contrast): 63 [ 0, 127] dflt( 64, 0.50, 257) 2008-02-27 10:46:43.657 Channel(/dev/video1): InitPictureAttribute( colour): 63 [ 0, 127] dflt( 64, 0.50, 257) 2008-02-27 10:46:43.664 Channel(/dev/video1): InitPictureAttribute( hue): 0 [ -128, 127] dflt( 0, 0.50, 128) 2008-02-27 10:46:43.667 TVRec(1001): ClearFlags(NeedToStartRecorder,) -> RunMainLoop, 2008-02-27 10:46:43.668 TVRec(1001): SetFlags(NeedToStartRecorder,) -> RunMainLoop,NeedToStartRecorder, 2008-02-27 10:46:43.670 TVRec(1001): Starting Recorder 2008-02-27 10:46:43.741 Using profile 'Live TV' to record 2008-02-27 10:46:43.743 TVRec(1001): CreateLiveTVRingBuffer() 2008-02-27 10:46:43.744 TVRec(1001): GetProgramRingBufferForLiveTV() 2008-02-27 10:46:44.887 TVRec(1001): StartedRecording(0x86fbe30) fn(/shares/recordings/LiveTV/1010_20080227104643.mpg) 2008-02-27 10:46:44.897 TFW: Using io multiplier 3, min/max = 16384/131072 2008-02-27 10:46:44.906 Chain: Appended@0 '1010_20080227104643' 2008-02-27 10:46:44.912 TVRec(1001): SetFlags(RingBufferReady,) -> RunMainLoop,NeedToStartRecorder,RingBufferReady, 2008-02-27 10:46:44.927 RecBase(1001:/dev/video1): SetRingBuffer(0xae3703b0) '/shares/recordings/LiveTV/1010_20080227104643.mpg' 2008-02-27 10:46:44.928 RecBase(1001:/dev/video1): SetRecording(0xae370908) title(Today) 2008-02-27 10:46:44.933 Channel(/dev/video1): InitPictureAttribute(brightness): 128 [ 0, 255] dflt( 128, 0.50, 128) 2008-02-27 10:46:44.939 Channel(/dev/video1): InitPictureAttribute( contrast): 63 [ 0, 127] dflt( 64, 0.50, 257) 2008-02-27 10:46:44.944 Channel(/dev/video1): InitPictureAttribute( colour): 63 [ 0, 127] dflt( 64, 0.50, 257) 2008-02-27 10:46:44.951 Channel(/dev/video1): InitPictureAttribute( hue): 0 [ -128, 127] dflt( 0, 0.50, 128) 2008-02-27 10:46:44.954 TVRec(1001): SetFlags(CancelNextRecording,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RingBufferReady, 2008-02-27 10:46:44.954 MPEGRec(/dev/video1): usingv4l2(1) has_v4l2_vbi(1) has_buggy_vbi(1) 2008-02-27 10:46:44.986 MPEGRec(/dev/video1) Warning: Stream type 'MPEG-2 TS' is not supported by ivtv driver, using 'MPEG-2 PS' instead. 2008-02-27 10:46:45.130 MPEGRec(/dev/video1) Warning: VBI recording with broken drivers. Upgrade to ivtv 0.10.0 if you experience problems. 2008-02-27 10:46:45.221 MPEGRec(/dev/video1): VBI service: 4096, io size: 0 2008-02-27 10:46:45.243 AutoExpire: CalcParams(): Max required Free Space: 6.0 GB w/freq: 15 min 2008-02-27 10:46:45.249 TVRec(1001): SetFlags(RecorderRunning,RingBufferReady,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady, 2008-02-27 10:46:45.252 TVRec(1001): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady, 2008-02-27 10:46:47.836 TVRec(1001): SetFlags(FrontendReady,) -> FrontendReady,RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady, 2008-02-27 10:46:55.752 TVRec(1001): StopLiveTV(void) curRec: 0x86fbe30 pseudoRec: 0 2008-02-27 10:46:55.758 TVRec(1001): Changing from WatchingLiveTV to None 2008-02-27 10:46:55.761 TVRec(1001): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RecorderRunning,RingBufferReady, 2008-02-27 10:46:55.762 TVRec(1001): Request: Program(no) channel() input() flags(KillRec,KillRingBuffer,) 2008-02-27 10:46:55.797 TVRec(1001): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady, 2008-02-27 10:46:55.837 TVRec(1001): FinishedRecording(Today) in recgroup: LiveTV 2008-02-27 10:46:55.841 Chain: Updated endtime for '1010_20080227104643' to 20080227104655 2008-02-27 10:46:55.846 Finished recording Today: channel 1010 2008-02-27 10:46:55.854 scheduler: Finished recording: Today: channel 1010 2008-02-27 10:46:55.858 TVRec(1001): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady, 2008-02-27 10:46:55.860 TVRec(1001): Tearing down RingBuffer 2008-02-27 10:46:55.863 TVRec(1001): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady, 2008-02-27 10:46:56.131 MainServer::HandleAnnounce Playback 2008-02-27 10:46:56.133 adding: pc4 as a client (events: 0) 2008-02-27 10:46:56.138 TVRec(5): Changing from None to WatchingLiveTV 2008-02-27 10:46:56.140 TVRec(5): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop, 2008-02-27 10:46:56.142 TVRec(5): Request: Program(no) channel() input() flags(LiveTV,) 2008-02-27 10:46:56.143 ChannelBase(5): Looking for startchannel '110' on input 'DiSEqC v1.3 Input 1' 2008-02-27 10:46:56.167 ChannelBase(5): Found startchannel '110' on input 'DiSEqC v1.3 Input 2' 2008-02-27 10:46:56.169 TVRec(5): HW Tuner: 5->5 2008-02-27 10:46:56.171 TVRec(5): ClearFlags(PENDINGACTIONS,) -> RunMainLoop, 2008-02-27 10:46:56.172 TVRec(5): No recorder yet, calling TuningFrequency 2008-02-27 10:46:56.175 DVBChan(5:5): Opening DVB channel 2008-02-27 10:46:56.177 DVBChan(5:5): SetChannelByString(110): 2008-02-27 10:46:56.191 DVBChan(5:5): 12296900 qpsk a auto auto a a auto a l 2008-02-27 10:46:56.195 DVBChan(5:5): Old Params: 12326060 qpsk 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-27 10:46:56.196 DVBChan(5:5): Tune(): Tuning to 1046900kHz 2008-02-27 10:46:56.212 dvbchannel.cpp:wait_for_backend: Status: 2008-02-27 10:46:56.214 DVBChan(5:5): Tune(): Frequency tuning successful. 2008-02-27 10:46:56.216 DVBChan(5:5): SetChannelByString(110): Tuned to frequency. 2008-02-27 10:46:56.217 TVRec(5): CreateLiveTVRingBuffer() 2008-02-27 10:46:56.218 TVRec(5): GetProgramRingBufferForLiveTV() 2008-02-27 10:46:57.288 TVRec(5): StartedRecording(0x872b648) fn(/shares/recordings/LiveTV/3110_20080227104656.mpg) 2008-02-27 10:46:57.297 TFW: Using io multiplier 3, min/max = 16384/131072 2008-02-27 10:46:57.306 Chain: Appended@1 '3110_20080227104656' 2008-02-27 10:46:57.312 TVRec(5): Starting Signal Monitor 2008-02-27 10:46:57.314 TVRec(5): SetupSignalMonitor(1, 1) 2008-02-27 10:46:57.315 DVBChan(5:5): Opening DVB channel 2008-02-27 10:46:57.330 DVBSM(5): Can measure Signal Strength 2008-02-27 10:46:57.331 DVBSM(5): Can measure S/N 2008-02-27 10:46:57.333 DVBSM(5): Can measure Bit Error Rate 2008-02-27 10:46:57.334 DVBSM(5): Can count Uncorrected Blocks 2008-02-27 10:46:57.336 SM(5)::RemoveFlags: Seen() Match() Wait() 2008-02-27 10:46:57.337 DVBSM(5): DVBSignalMonitor::ctor initial flags Seen() Match() Wait(Sig,SNR,BER,UB,) 2008-02-27 10:46:57.339 TVRec(5): Signal monitor successfully created 2008-02-27 10:46:57.340 TVRec(5): Setting up table monitoring. 2008-02-27 10:46:57.353 Using profile 'Live TV' to record 2008-02-27 10:46:57.355 TVRec(5): DVB service_id 110 on net_id 4100 tsid 6 2008-02-27 10:46:57.357 DTVSM(5)::SetDVBService(transport_id: 6, network_id: 4100, service_id: 110): 2008-02-27 10:46:57.358 SM(5)::RemoveFlags: Seen(PMT,SDT,Crypt,) Match(PMT,SDT,Crypt,) Wait() 2008-02-27 10:46:57.360 SM(5)::AddFlags: Seen() Match() Wait(PMT,NIT,) 2008-02-27 10:46:57.361 SM(5)::AddFlags: Seen() Match() Wait(PMT,NIT,Pos,) 2008-02-27 10:46:57.363 TVRec(5): Successfully set up DVB table monitoring. 2008-02-27 10:46:57.365 SM(5)::Start: begin 2008-02-27 10:46:57.366 DVBSH(5): Retuning for rotor completion 2008-02-27 10:46:57.368 SM(5)::Start: end 2008-02-27 10:46:57.368 DVBChan(5:5): Old Params: 12296900 qpsk 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-27 10:46:57.370 TVRec(5): SetFlags(SignalMonitorRunning,) -> RunMainLoop,SignalMonitorRunning, 2008-02-27 10:46:57.372 DVBChan(5:5): Tune(): Tuning to 1046900kHz 2008-02-27 10:46:57.374 TVRec(5): ClearFlags(WaitingForSignal,) -> RunMainLoop,SignalMonitorRunning, 2008-02-27 10:46:57.378 TVRec(5): SetFlags(WaitingForSignal,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning, 2008-02-27 10:46:57.380 TVRec(5): SetFlags(DummyRecorderRunning,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning, 2008-02-27 10:46:57.381 dvbchannel.cpp:wait_for_backend: Status: Signal,Carrier,FEC Stable,Sync,Lock, 2008-02-27 10:46:57.381 DummyDTVRecorder -- started 2008-02-27 10:46:57.384 DVBChan(5:5): Tune(): Frequency tuning successful. 2008-02-27 10:46:57.386 TVRec(5): SetFlags(RingBufferReady,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, 2008-02-27 10:46:57.388 DVBSM(5): UpdateValues -- Signal Locked 2008-02-27 10:46:57.390 TVRec(5): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, 2008-02-27 10:46:57.392 DVBSH(5): AddListener(0xa91cdf40) -- begin 2008-02-27 10:46:57.395 TVRec(5): SetFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, 2008-02-27 10:46:57.397 DVBSH(5): AddListener(0xa91cdf40) -- locked 2008-02-27 10:46:57.401 DVBSH(5): AddListener(0xa91cdf40) -- end 2008-02-27 10:46:57.401 TVRec(5): SetFlags(CancelNextRecording,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, 2008-02-27 10:46:57.402 DVBSH(5): AddPIDFilter(0x0) priority 2 2008-02-27 10:46:57.403 DTVSM(5)::GetStatusList: WaitForPMT seen(0) matching(0) 2008-02-27 10:46:57.408 PIDInfo(5): Opening filter for pid 0x0 2008-02-27 10:46:57.413 DVBSH(5): RemovePIDFilter(0x0) 2008-02-27 10:46:57.414 AutoExpire: CalcParams(): Max required Free Space: 7.0 GB w/freq: 15 min 2008-02-27 10:46:57.415 PIDInfo(5): Closing filter for pid 0x0 2008-02-27 10:46:57.427 DVBSH(5): RunTS(): begin 2008-02-27 10:46:57.429 DVBSH(5): AddPIDFilter(0x0) priority 2 2008-02-27 10:46:57.431 PIDInfo(5): Opening filter for pid 0x0 2008-02-27 10:46:57.432 DVBSH(5): AddPIDFilter(0x10) priority 2 2008-02-27 10:46:57.434 PIDInfo(5): Opening filter for pid 0x10 2008-02-27 10:46:57.436 DVBSH(5): AddPIDFilter(0x11) priority 2 2008-02-27 10:46:57.437 PIDInfo(5): Opening filter for pid 0x11 2008-02-27 10:46:57.439 DVBSH(5): AddPIDFilter(0x14) priority 2 2008-02-27 10:46:57.440 PIDInfo(5): Opening filter for pid 0x14 2008-02-27 10:46:57.470 SM(5)::AddFlags: Seen(PAT,) Match() Wait() 2008-02-27 10:46:57.472 SM(5)::AddFlags: Seen() Match(PAT,) Wait() 2008-02-27 10:46:57.473 CreatePATSingleProgram() 2008-02-27 10:46:57.475 PAT in input stream 2008-02-27 10:46:57.476 Program Association Table PSIP tableID(0x0) length(93) extension(0x6) version(2) current(1) section(0) last_section(0) tsid: 6 programCount: 21 program number 0 has PID 0x 10 data 0x 0 0x 0 0xe0 0x10 program number 110 has PID 0x 21 data 0x 0 0x6e 0xe0 0x21 program number 112 has PID 0x 21 data 0x 0 0x70 0xe0 0x21 program number 131 has PID 0x 21 data 0x 0 0x83 0xe0 0x21 program number 160 has PID 0x 21 data 0x 0 0xa0 0xe0 0x21 program number 174 has PID 0x 21 data 0x 0 0xae 0xe0 0x21 program number 205 has PID 0x 21 data 0x 0 0xcd 0xe0 0x21 program number 214 has PID 0x 21 data 0x 0 0xd6 0xe0 0x21 program number 215 has PID 0x 21 data 0x 0 0xd7 0xe0 0x21 program number 301 has PID 0x 21 data 0x 1 0x2d 0xe0 0x21 program number 302 has PID 0x 21 data 0x 1 0x2e 0xe0 0x21 program number 318 has PID 0x 21 data 0x 1 0x3e 0xe0 0x21 program number 502 has PID 0x 21 data 0x 1 0xf6 0xe0 0x21 program number 847 has PID 0x 21 data 0x 3 0x4f 0xe0 0x21 program number 894 has PID 0x 21 data 0x 3 0x7e 0xe0 0x21 program number 944 has PID 0x 21 data 0x 3 0xb0 0xe0 0x21 program number 5759 has PID 0x 21 data 0x16 0x7f 0xe0 0x21 program number 5816 has PID 0x 21 data 0x16 0xb8 0xe0 0x21 program number 5898 has PID 0x 21 data 0x17 0x a 0xe0 0x21 program number 5899 has PID 0x 21 data 0x17 0x b 0xe0 0x21 program number 19075 has PID 0x 21 data 0x4a 0x83 0xe0 0x21 2008-02-27 10:46:57.479 desired_program(110) pid(0x21) 2008-02-27 10:46:57.481 pmt_pid(0x21) 2008-02-27 10:46:57.482 PAT for output stream 2008-02-27 10:46:57.483 Program Association Table PSIP tableID(0x0) length(13) extension(0x6) version(2) current(1) section(0) last_section(0) tsid: 6 programCount: 1 program number 1 has PID 0x 21 data 0x 0 0x 1 0xe0 0x21 2008-02-27 10:46:57.485 DVBSH(5): AddPIDFilter(0x21) priority 2 2008-02-27 10:46:57.487 PIDInfo(5): Opening filter for pid 0x21 2008-02-27 10:46:57.492 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-27 10:46:57.493 DTVSM(5) Error: Wrong PMT; pmt->pn(131) desired(110) 2008-02-27 10:46:57.495 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-27 10:46:57.496 DTVSM(5) Error: Wrong PMT; pmt->pn(174) desired(110) 2008-02-27 10:46:57.515 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-27 10:46:57.516 DTVSM(5)::GetStatusList: WaitForPMT seen(1) matching(0) 2008-02-27 10:46:57.519 DTVSM(5) Error: Wrong PMT; pmt->pn(205) desired(110) 2008-02-27 10:46:57.522 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-27 10:46:57.524 DTVSM(5) Error: Wrong PMT; pmt->pn(301) desired(110) 2008-02-27 10:46:57.536 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-27 10:46:57.538 DTVSM(5) Error: Wrong PMT; pmt->pn(302) desired(110) 2008-02-27 10:46:57.539 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-27 10:46:57.541 DTVSM(5) Error: Wrong PMT; pmt->pn(160) desired(110) 2008-02-27 10:46:57.555 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-27 10:46:57.557 DTVSM(5) Error: Wrong PMT; pmt->pn(502) desired(110) 2008-02-27 10:46:57.559 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-27 10:46:57.560 DTVSM(5) Error: Wrong PMT; pmt->pn(847) desired(110) 2008-02-27 10:46:57.577 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-27 10:46:57.578 DTVSM(5) Error: Wrong PMT; pmt->pn(894) desired(110) 2008-02-27 10:46:57.580 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-27 10:46:57.582 DTVSM(5) Error: Wrong PMT; pmt->pn(944) desired(110) 2008-02-27 10:46:57.583 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-27 10:46:57.585 DTVSM(5) Error: Wrong PMT; pmt->pn(5816) desired(110) 2008-02-27 10:46:57.596 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-27 10:46:57.597 DTVSM(5) Error: Wrong PMT; pmt->pn(5898) desired(110) 2008-02-27 10:46:57.599 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-27 10:46:57.601 DTVSM(5) Error: Wrong PMT; pmt->pn(5899) desired(110) 2008-02-27 10:46:57.617 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-27 10:46:57.619 DTVSM(5) Error: Wrong PMT; pmt->pn(215) desired(110) 2008-02-27 10:46:57.621 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-27 10:46:57.622 DTVSM(5) Error: Wrong PMT; pmt->pn(5759) desired(110) 2008-02-27 10:46:57.624 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-27 10:46:57.626 DTVSM(5) Error: Wrong PMT; pmt->pn(19075) desired(110)