2006-10-20 00:00:50.860 Using runtime prefix = /usr 2006-10-20 00:00:50.929 New DB connection, total: 1 2006-10-20 00:00:50.949 Connected to database 'mythconverg' at host: localhost 2006-10-20 00:00:50.953 Current Schema Version: 1160 2006-10-20 00:00:50.965 New DB connection, total: 2 2006-10-20 00:00:50.966 Connected to database 'mythconverg' at host: localhost 2006-10-20 00:00:50.967 mythbackend: MythBackend started as master server 2006-10-20 00:00:50.975 EITHelper: localtime offset 2:00:00 2006-10-20 00:00:51.209 DVBChan(0): Opening DVB channel 2006-10-20 00:00:51.209 DVBChan(0): Using DVB card 0, with frontend 'DST DVB-C'. 2006-10-20 00:00:51.530 DVB#0 CA: CI handler successfully initialized! 2006-10-20 00:00:51.531 New DB connection, total: 3 2006-10-20 00:00:51.532 Connected to database 'mythconverg' at host: localhost 2006-10-20 00:00:51.579 DVB#0 CA: CI handler thread running 2006-10-20 00:00:51.583 ChannelBase(3): Input #3: 'DVBInput' schan(4) sourceid(2) ccid(3) 2006-10-20 00:00:51.583 ChannelBase(3): Current Input #3: 'DVBInput' 2006-10-20 00:00:51.584 DVBChan(0): SetChannelByString(4): 2006-10-20 00:00:51.587 DVBChan(0): Frequency: 802000000 Symbol Rate: 6875000 Inversion: Auto Inner FEC: auto 2006-10-20 00:00:51.588 DVBChan(0): Old Params: Frequency: 0 Symbol Rate: 0 Inversion: Off Inner FEC: none DVBChan(0): New Params: Frequency: 802000000 Symbol Rate: 6875000 Inversion: Auto Inner FEC: auto 2006-10-20 00:00:51.588 DVBChan(0): Tune(): Tuning to 802000000Hz Debug: 159 128 49 2 5 0 0 1 5 0 171 255 255 255 255 255 255 255 255 255 2006-10-20 00:00:52.552 dvbchannel.cpp:wait_for_backend: Status: Signal,Carrier,FEC Stable,Sync,Lock, 2006-10-20 00:00:52.552 DVBChan(0): Tune(): Frequency tuning successful. 2006-10-20 00:00:52.553 DVBChan(0): SetChannelByString(4): Tuned to frequency. 2006-10-20 00:00:52.553 DVBChan(0): Closing DVB channel 2006-10-20 00:00:52.555 DVB#0 CA: CiHandler thread stopped 2006-10-20 00:00:52.570 TVRec(3): SetFlags(RunMainLoop,) -> RunMainLoop, 2006-10-20 00:00:52.570 TVRec(3): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, 2006-10-20 00:00:52.570 EITHelper: localtime offset 2:00:00 2006-10-20 00:00:52.573 Channel(/dev/video0): Device name 'Hauppauge WinTV PVR-150' driver 'ivtv'. 2006-10-20 00:00:52.581 ChannelBase(4): Input #4: 'Tuner 1' schan(20) sourceid(1) ccid(4) 2006-10-20 00:00:52.581 ChannelBase(4): Current Input #4: 'Tuner 1' 2006-10-20 00:00:52.583 Global TVFormat Setting 'PAL-BG' 2006-10-20 00:00:52.583 Channel(/dev/video0): Input #4: 'Tuner 1' schan(20) tun() v4l1(PAL) v4l2(Unknown) 2006-10-20 00:00:52.583 Channel(/dev/video0): SetFormat(Default) fmt(PAL-BG) input(4) 2006-10-20 00:00:52.583 Channel(/dev/video0): SetInputAndFormat(4, PAL-BG) (v4l v2) 2006-10-20 00:00:52.583 Channel(/dev/video0): SetFormat(PAL-BG) fmt(PAL-BG) input(4) 2006-10-20 00:00:52.585 Channel(/dev/video0)::SwitchToInput(in 4, '') 2006-10-20 00:00:52.585 Channel(/dev/video0): SetInputAndFormat(4, PAL-BG) (v4l v2) 2006-10-20 00:00:52.585 Channel(/dev/video0): SetChannelByString(20) 2006-10-20 00:00:52.586 ChannelBase(/dev/video0)::SetCachedATSCInfo(): 0--1 2006-10-20 00:00:52.587 Channel(/dev/video0): SetFormat(Default) fmt(PAL-BG) input(4) 2006-10-20 00:00:52.589 Channel(/dev/video0)::Tune(615250000, , analog) 2006-10-20 00:00:52.721 Channel(/dev/video0)::Tune(): Frequency is now 615250000 2006-10-20 00:00:52.721 ChannelBase(/dev/video0)::SetCachedATSCInfo(20_0): 20--1 2006-10-20 00:00:52.722 TVRec(4): SetFlags(RunMainLoop,) -> RunMainLoop, 2006-10-20 00:00:52.722 TVRec(4): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, 2006-10-20 00:00:52.723 New DB scheduler connection 2006-10-20 00:00:52.724 Connected to database 'mythconverg' at host: localhost 2006-10-20 00:00:52.732 Main::Starting HttpServer 2006-10-20 00:00:52.739 Main::Registering HttpStatus Extension 2006-10-20 00:00:52.746 mythbackend version: 0.20.20060828-3 www.mythtv.org 2006-10-20 00:00:52.746 Enabled verbose msgs: important general siparser record channel 2006-10-20 00:00:52.746 AutoExpire: Found 2 recorders w/max rate of 210 MiB/min 2006-10-20 00:00:52.748 AutoExpire: Required Free Space: 2.6 GB w/freq: 5 min 2006-10-20 00:00:54.735 Reschedule requested for id -1. 2006-10-20 00:00:55.072 Scheduled 37 items in 0.3 = 0.08 match + 0.26 place 2006-10-20 00:00:55.077 scheduler: Scheduled items: Scheduled 37 items in 0.3 = 0.08 match + 0.26 place 2006-10-20 00:00:55.080 Seem to be woken up by USER 2006-10-20 00:01:00.986 mythbackend: Running housekeeping thread 2006-10-20 00:01:10.647 MainServer::HandleAnnounce Monitor 2006-10-20 00:01:10.647 adding: el-mahico as a client (events: 0) 2006-10-20 00:01:10.648 MainServer::HandleAnnounce Monitor 2006-10-20 00:01:10.648 adding: el-mahico as a client (events: 1) 2006-10-20 00:01:10.649 Getting next free recorder after : -1 2006-10-20 00:01:10.651 Card 3 is local. 2006-10-20 00:01:10.658 MainServer::HandleAnnounce Playback 2006-10-20 00:01:10.658 adding: el-mahico as a client (events: 0) 2006-10-20 00:01:10.660 TVRec(3): Changing from None to WatchingLiveTV 2006-10-20 00:01:10.661 TVRec(3): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop, 2006-10-20 00:01:10.661 TVRec(3): SetFlags(AskAllowRecording,) -> RunMainLoop,AskAllowRecording, 2006-10-20 00:01:10.661 TVRec(3): Request: Program(no) channel() input() flags(LiveTV,) 2006-10-20 00:01:10.662 TVRec(3): HW Tuner: 3->3 2006-10-20 00:01:10.662 TVRec(3): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,AskAllowRecording, 2006-10-20 00:01:10.662 DVBChan(0): Opening DVB channel 2006-10-20 00:01:10.663 DVBChan(0): Using DVB card 0, with frontend 'DST DVB-C'. 2006-10-20 00:01:10.906 DVB#0 CA: CI handler successfully initialized! 2006-10-20 00:01:10.959 ChannelBase(3): Input #3: 'DVBInput' schan(4) sourceid(2) ccid(3) 2006-10-20 00:01:10.959 ChannelBase(3): Current Input #3: 'DVBInput' 2006-10-20 00:01:10.959 DVBChan(0): SetChannelByString(4): 2006-10-20 00:01:10.962 DVB#0 CA: CI handler thread running 2006-10-20 00:01:10.967 DVBChan(0): Frequency: 802000000 Symbol Rate: 6875000 Inversion: Auto Inner FEC: auto 2006-10-20 00:01:10.967 DVBChan(0): Old Params: Frequency: 802000000 Symbol Rate: 6875000 Inversion: Auto Inner FEC: auto DVBChan(0): New Params: Frequency: 802000000 Symbol Rate: 6875000 Inversion: Auto Inner FEC: auto 2006-10-20 00:01:10.968 DVBChan(0): Tune(): Tuning to 802000000Hz Debug: 159 128 49 2 5 0 0 1 5 0 171 255 255 255 255 255 255 255 255 255 2006-10-20 00:01:11.354 dvbchannel.cpp:wait_for_backend: Status: Signal,Carrier,FEC Stable,Sync,Lock, 2006-10-20 00:01:11.354 DVBChan(0): Tune(): Frequency tuning successful. 2006-10-20 00:01:11.354 DVBChan(0): SetChannelByString(4): Tuned to frequency. 2006-10-20 00:01:11.354 TVRec(3): CreateLiveTVRingBuffer() 2006-10-20 00:01:11.354 TVRec(3): GetProgramRingBufferForLiveTV() 2006-10-20 00:01:11.421 TVRec(3): StartedRecording(0x8632de0) fn(/recordings/2550_20061020000111.mpg) 2006-10-20 00:01:11.479 Chain: Appended@0 '2550_20061020000111' 2006-10-20 00:01:11.482 TVRec(3): Starting Signal Monitor 2006-10-20 00:01:11.482 TVRec(3): SetupSignalMonitor(1, 1) 2006-10-20 00:01:11.482 DVBChan(0): Opening DVB channel 2006-10-20 00:01:11.645 DVBSignalMonitor(0)::constructor(3,Warning, can not measure Bit Error Rate): Operation not supported 2006-10-20 00:01:11.645 DVBSignalMonitor(0)::constructor(3,Warning, can not count Uncorrected Blocks): Operation not supported 2006-10-20 00:01:11.646 SM(0)::AddFlags: Seen() Match() Wait(Sig,SNR,) 2006-10-20 00:01:11.646 DVBSM(0)::constructor(): initial flags 0x1400000 2006-10-20 00:01:11.646 TVRec(3): Signal monitor successfully created 2006-10-20 00:01:11.646 TVRec(3): Setting up table monitoring. 2006-10-20 00:01:11.646 DEBUG net_id 0 tsid 0 2006-10-20 00:01:11.692 Using profile 'Live TV' to record 2006-10-20 00:01:11.692 TVRec(3): DVB service_id 420 on net_id 0 tsid 0 2006-10-20 00:01:11.694 DTVSM(0)::SetDVBService(transport_id: 0, network_id: 0, service_id: 420): 2006-10-20 00:01:11.694 SM(0)::RemoveFlags: Seen(PMT,SDT,) Match(PMT,SDT,) Wait() 2006-10-20 00:01:11.694 SM(0)::AddFlags: Seen() Match() Wait(PMT,SDT,) 2006-10-20 00:01:11.694 SM(0)::AddFlags: Seen() Match() Wait(PMT,SDT,) 2006-10-20 00:01:11.694 SM(0)::AddFlags: Seen() Match() Wait() 2006-10-20 00:01:11.694 TVRec(3): Successfully set up DVB table monitoring. 2006-10-20 00:01:11.694 SM(0)::Start: begin 2006-10-20 00:01:11.695 DVBSM(0)::UpdateValues: Signal Locked 2006-10-20 00:01:11.695 DVBSM(0)::UpdateValues: Waiting for table monitor to start 2006-10-20 00:01:11.695 DVBSM(0)::AddPIDFilter(0x0): 2006-10-20 00:01:11.696 DVBSM(0)::RemovePIDFilter(0x0): 2006-10-20 00:01:11.696 SM(0)::Start: end 2006-10-20 00:01:11.696 TVRec(3): SetFlags(SignalMonitorRunning,) -> RunMainLoop,AskAllowRecording,SignalMonitorRunning, 2006-10-20 00:01:11.696 TVRec(3): ClearFlags(WaitingForSignal,) -> RunMainLoop,AskAllowRecording,SignalMonitorRunning, 2006-10-20 00:01:11.696 TVRec(3): SetFlags(WaitingForSignal,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,SignalMonitorRunning, 2006-10-20 00:01:11.697 TVRec(3): SetFlags(DummyRecorderRunning,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning, 2006-10-20 00:01:11.697 DummyDTVRecorder -- started 2006-10-20 00:01:11.697 TVRec(3): SetFlags(RingBufferReady,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, 2006-10-20 00:01:11.697 TVRec(3): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, 2006-10-20 00:01:11.697 TVRec(3): SetFlags(NeedToStartRecorder,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, 2006-10-20 00:01:11.697 TVRec(3): SetFlags(CancelNextRecording,) -> RunMainLoop,CancelNextRecording,AskAllowRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, 2006-10-20 00:01:11.801 DVBSM(0)::UpdateValues: Table monitor started 2006-10-20 00:01:11.802 DTVSM(0)::GetStatusList: WaitForPMT seen(0) matching(0) 2006-10-20 00:01:11.850 DVBSM(0): RunTableMonitorTS(): begin (# of pids 3) 2006-10-20 00:01:11.851 DVBSM(0)::AddPIDFilter(0x0): 2006-10-20 00:01:11.851 DVBSM(0)::AddPIDFilter(0x10): 2006-10-20 00:01:11.851 DVBSM(0)::AddPIDFilter(0x11): 2006-10-20 00:01:11.889 SM(0)::AddFlags: Seen(PAT,) Match() Wait() 2006-10-20 00:01:11.889 SM(0)::AddFlags: Seen() Match(PAT,) Wait() 2006-10-20 00:01:11.889 CreatePATSingleProgram() 2006-10-20 00:01:11.889 PAT in input stream 2006-10-20 00:01:11.889 Program Association Table PSIP tableID(0x0) length(49) extension(0x44c) version(4) current(1) section(0) last_section(0) tsid: 1100 programCount: 10 program number 0 has PID 0x 10 data 0x0 0x0 0x224 0x16 program number 410 has PID 0x 1a3 data 0x1 0x154 0x225 0x163 program number 430 has PID 0x 101 data 0x1 0x174 0x225 0x1 program number 928 has PID 0x 22 data 0x3 0x160 0x224 0x34 program number 420 has PID 0x 10a data 0x1 0x164 0x225 0x10 program number 1900 has PID 0x 105 data 0x7 0x108 0x225 0x5 program number 440 has PID 0x 20 data 0x1 0x184 0x224 0x32 program number 460 has PID 0x 1d1 data 0x1 0x204 0x225 0x209 program number 470 has PID 0x1b94 data 0x1 0x214 0x251 0x148 program number 920 has PID 0x 21 data 0x3 0x152 0x224 0x33 2006-10-20 00:01:11.890 desired_program(420) pid(0x10a) 2006-10-20 00:01:11.890 pmt_pid(0x10a) 2006-10-20 00:01:11.890 PAT for output stream 2006-10-20 00:01:11.890 Program Association Table PSIP tableID(0x0) length(13) extension(0x44c) version(4) current(1) section(0) last_section(0) tsid: 1100 programCount: 1 program number 1 has PID 0x 10a data 0x0 0x1 0x225 0x10 2006-10-20 00:01:11.890 DVBSM(0)::AddPIDFilter(0x10a): 2006-10-20 00:01:11.964 SM(0)::AddFlags: Seen(PMT,) Match() Wait() 2006-10-20 00:01:11.965 SM(0)::AddFlags: Seen() Match(PMT,) Wait() 2006-10-20 00:01:11.965 CreatePMTSingleProgram() 2006-10-20 00:01:11.965 PMT in input stream 2006-10-20 00:01:11.965 Program Map Table ver(8) pid(0x10a) pnum(420) len(69) Stream #0 pid(0x1a4) type(video-mpeg2 0x2) Conditional Access: sid(0x500) pid(0x2f) data_size(11) Stream #1 pid(0x1a5) type(audio-mp2-layer[1,2,3] 0x4) Conditional Access: sid(0x500) pid(0x2f) data_size(11) Stream #2 pid(0x1a6) type(private-data 0x6) Teletext Descriptor (0x56) length(5) 2006-10-20 00:01:11.965 PMT for output stream 2006-10-20 00:01:11.965 Program Map Table ver(8) pid(0x10a) pnum(1) len(23) Stream #0 pid(0x1a4) type(video-mpeg2 0x2) Stream #1 pid(0x1a5) type(audio-mp2-layer[1,2,3] 0x4) 2006-10-20 00:01:11.968 DTVSM(0)::GetStatusList: WaitForPMT seen(1) matching(1) 2006-10-20 00:01:12.073 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:13.092 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:13.098 TVRec(3): SetFlags(FrontendReady,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, 2006-10-20 00:01:14.114 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:14.488 DTVSM(0)::SetNIT(): net_id = 72 2006-10-20 00:01:14.488 SM(0)::AddFlags: Seen(NIT,) Match() Wait() 2006-10-20 00:01:14.540 DTVSM(0)::SetNIT(): net_id = 72 2006-10-20 00:01:14.540 SM(0)::AddFlags: Seen(NIT,) Match() Wait() 2006-10-20 00:01:15.072 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:16.073 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:17.097 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:18.105 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:19.105 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:20.093 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:21.098 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:21.426 ChannelBase(3): Failed to find channel() on current input (DVBInput) of card (3). 2006-10-20 00:01:21.427 ChannelBase(3): Failed to find channel() on any input of card (3). 2006-10-20 00:01:21.439 TVRec(3): PauseRecorder() called with no recorder 2006-10-20 00:01:21.440 TVRec(3): SetChannel() -- begin 2006-10-20 00:01:21.440 TVRec(3): ClearFlags(RingBufferReady,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning, 2006-10-20 00:01:21.440 TVRec(3): Request: Program(no) channel(103) input() flags(LiveTV,) 2006-10-20 00:01:21.441 TVRec(3): TeardownSignalMonitor() -- begin 2006-10-20 00:01:21.441 DVBSM(0)::Stop: begin 2006-10-20 00:01:21.441 SM(0)::Stop: begin 2006-10-20 00:01:21.464 SM(0)::Stop: end 2006-10-20 00:01:21.473 DVBSM(0): RunTableMonitorTS(): shutdown 2006-10-20 00:01:21.473 DVBSM(0)::RemovePIDFilter(0x0): 2006-10-20 00:01:21.473 DVBSM(0)::RemovePIDFilter(0x10): 2006-10-20 00:01:21.473 DVBSM(0)::RemovePIDFilter(0x11): 2006-10-20 00:01:21.473 DVBSM(0)::RemovePIDFilter(0x10a): 2006-10-20 00:01:21.474 DVBSM(0): RunTableMonitorTS(): end 2006-10-20 00:01:21.474 DVBSM(0)::Stop: end 2006-10-20 00:01:21.474 TVRec(3): TeardownSignalMonitor() -- end 2006-10-20 00:01:21.474 TVRec(3): ClearFlags(SignalMonitorRunning,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,WaitingForSignal,NeedToStartRecorder,DummyRecorderRunning, 2006-10-20 00:01:21.475 TVRec(3): ClearFlags(WaitingForSignal,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,NeedToStartRecorder,DummyRecorderRunning, 2006-10-20 00:01:21.475 TVRec(3): ClearFlags(PENDINGACTIONS,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,DummyRecorderRunning, 2006-10-20 00:01:21.475 DVBChan(0): Opening DVB channel 2006-10-20 00:01:21.475 DVBChan(0): SetChannelByString(103): 2006-10-20 00:01:21.480 DVBChan(0): Frequency: 762000000 Symbol Rate: 6875000 Inversion: Auto Inner FEC: auto 2006-10-20 00:01:21.480 DVBChan(0): Old Params: Frequency: 802000000 Symbol Rate: 6875000 Inversion: Auto Inner FEC: auto DVBChan(0): New Params: Frequency: 762000000 Symbol Rate: 6875000 Inversion: Auto Inner FEC: auto 2006-10-20 00:01:21.480 DVBChan(0): Tune(): Tuning to 762000000Hz 2006-10-20 00:01:22.226 dvbchannel.cpp:wait_for_backend: Status: Signal,Carrier,FEC Stable,Sync,Lock, 2006-10-20 00:01:22.226 DVBChan(0): Tune(): Frequency tuning successful. 2006-10-20 00:01:22.226 DVBChan(0): SetChannelByString(103): Tuned to frequency. 2006-10-20 00:01:22.227 TVRec(3): SwitchLiveTVRingBuffer(discont 1, set_rec 0) 2006-10-20 00:01:22.227 TVRec(3): GetProgramRingBufferForLiveTV() 2006-10-20 00:01:22.264 TVRec(3): StartedRecording(0x8676980) fn(/recordings/2612_20061020000122.mpg) 2006-10-20 00:01:22.310 TVRec(3): FinishedRecording(Scream 3) in recgroup: LiveTV 2006-10-20 00:01:22.341 Chain: Updated endtime for '2550_20061020000111' to 20061020000122 2006-10-20 00:01:22.364 Finished recording Scream 3: channel 2550 2006-10-20 00:01:22.369 scheduler: Finished recording: Scream 3: channel 2550 2006-10-20 00:01:22.369 Preview: 'myth://192.168.1.11:6543/2550_20061020000111.mpg' is not local, replacing with '/recordings/2550_20061020000111.mpg', which is local. 2006-10-20 00:01:22.375 Chain: Appended@1 '2612_20061020000122' 2006-10-20 00:01:22.378 TVRec(3): ClearFlags(DummyRecorderRunning,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording, 2006-10-20 00:01:22.379 TVRec(3): Starting Signal Monitor 2006-10-20 00:01:22.379 TVRec(3): SetupSignalMonitor(1, 1) 2006-10-20 00:01:22.379 DVBChan(0): Opening DVB channel 2006-10-20 00:01:22.387 Preview Error: Previewer file '/recordings/2550_20061020000111.mpg' is not valid. 2006-10-20 00:01:22.546 DVBSignalMonitor(0)::constructor(3,Warning, can not measure Bit Error Rate): Operation not supported 2006-10-20 00:01:22.546 DVBSignalMonitor(0)::constructor(3,Warning, can not count Uncorrected Blocks): Operation not supported 2006-10-20 00:01:22.546 SM(0)::AddFlags: Seen() Match() Wait(Sig,SNR,) 2006-10-20 00:01:22.546 DVBSM(0)::constructor(): initial flags 0x1400000 2006-10-20 00:01:22.546 TVRec(3): Signal monitor successfully created 2006-10-20 00:01:22.546 TVRec(3): Setting up table monitoring. 2006-10-20 00:01:22.547 DEBUG net_id 0 tsid 0 2006-10-20 00:01:22.555 Using profile 'Live TV' to record 2006-10-20 00:01:22.555 TVRec(3): DVB service_id 921 on net_id 0 tsid 0 2006-10-20 00:01:22.556 DTVSM(0)::SetDVBService(transport_id: 0, network_id: 0, service_id: 921): 2006-10-20 00:01:22.556 SM(0)::RemoveFlags: Seen(PMT,SDT,) Match(PMT,SDT,) Wait() 2006-10-20 00:01:22.556 SM(0)::AddFlags: Seen() Match() Wait(PMT,SDT,) 2006-10-20 00:01:22.556 SM(0)::AddFlags: Seen() Match() Wait(PMT,SDT,) 2006-10-20 00:01:22.556 SM(0)::AddFlags: Seen() Match() Wait() 2006-10-20 00:01:22.556 TVRec(3): Successfully set up DVB table monitoring. 2006-10-20 00:01:22.556 SM(0)::Start: begin 2006-10-20 00:01:22.556 DVBSM(0)::UpdateValues: Signal Locked 2006-10-20 00:01:22.557 DVBSM(0)::UpdateValues: Waiting for table monitor to start 2006-10-20 00:01:22.557 SM(0)::Start: end 2006-10-20 00:01:22.557 TVRec(3): SetFlags(SignalMonitorRunning,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,SignalMonitorRunning, 2006-10-20 00:01:22.557 TVRec(3): ClearFlags(WaitingForSignal,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,SignalMonitorRunning, 2006-10-20 00:01:22.557 TVRec(3): SetFlags(WaitingForSignal,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,WaitingForSignal,SignalMonitorRunning, 2006-10-20 00:01:22.557 TVRec(3): SetFlags(DummyRecorderRunning,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning, 2006-10-20 00:01:22.557 DummyDTVRecorder -- started 2006-10-20 00:01:22.557 TVRec(3): SetFlags(RingBufferReady,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, 2006-10-20 00:01:22.557 TVRec(3): ClearFlags(NeedToStartRecorder,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, 2006-10-20 00:01:22.557 TVRec(3): SetFlags(NeedToStartRecorder,) -> FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, 2006-10-20 00:01:22.558 TVRec(3): SetChannel() -- end 2006-10-20 00:01:22.563 DVBSM(0)::UpdateValues: Table monitor started 2006-10-20 00:01:22.563 DTVSM(0)::GetStatusList: WaitForPMT seen(0) matching(0) 2006-10-20 00:01:22.569 DVBSM(0): RunTableMonitorTS(): begin (# of pids 3) 2006-10-20 00:01:22.569 DVBSM(0)::AddPIDFilter(0x0): 2006-10-20 00:01:22.569 DVBSM(0)::AddPIDFilter(0x10): 2006-10-20 00:01:22.569 DVBSM(0)::AddPIDFilter(0x11): 2006-10-20 00:01:22.719 SM(0)::AddFlags: Seen(PAT,) Match() Wait() 2006-10-20 00:01:22.719 SM(0)::AddFlags: Seen() Match(PAT,) Wait() 2006-10-20 00:01:22.719 CreatePATSingleProgram() 2006-10-20 00:01:22.719 PAT in input stream 2006-10-20 00:01:22.719 Program Association Table PSIP tableID(0x0) length(161) extension(0x410) version(4) current(1) section(0) last_section(0) tsid: 1040 programCount: 38 program number 0 has PID 0x 10 data 0x0 0x0 0x224 0x16 program number 310 has PID 0x 101 data 0x1 0x54 0x225 0x1 program number 340 has PID 0x 159 data 0x1 0x84 0x225 0x89 program number 902 has PID 0x 21 data 0x3 0x134 0x224 0x33 program number 903 has PID 0x 21 data 0x3 0x135 0x224 0x33 program number 904 has PID 0x 21 data 0x3 0x136 0x224 0x33 program number 905 has PID 0x 20 data 0x3 0x137 0x224 0x32 program number 906 has PID 0x 26 data 0x3 0x138 0x224 0x38 program number 907 has PID 0x 101 data 0x3 0x139 0x225 0x1 program number 910 has PID 0x 22 data 0x3 0x142 0x224 0x34 program number 911 has PID 0x 101 data 0x3 0x143 0x225 0x1 program number 912 has PID 0x 23 data 0x3 0x144 0x224 0x35 program number 913 has PID 0x 23 data 0x3 0x145 0x224 0x35 program number 914 has PID 0x 23 data 0x3 0x146 0x224 0x35 program number 916 has PID 0x 20 data 0x3 0x148 0x224 0x32 program number 917 has PID 0x 20 data 0x3 0x149 0x224 0x32 program number 918 has PID 0x 20 data 0x3 0x150 0x224 0x32 program number 919 has PID 0x 101 data 0x3 0x151 0x225 0x1 program number 921 has PID 0x 20 data 0x3 0x153 0x224 0x32 program number 922 has PID 0x 101 data 0x3 0x154 0x225 0x1 program number 923 has PID 0x 101 data 0x3 0x155 0x225 0x1 program number 925 has PID 0x 101 data 0x3 0x157 0x225 0x1 program number 926 has PID 0x 20 data 0x3 0x158 0x224 0x32 program number 927 has PID 0x 101 data 0x3 0x159 0x225 0x1 program number 930 has PID 0x 21 data 0x3 0x162 0x224 0x33 program number 931 has PID 0x 21 data 0x3 0x163 0x224 0x33 program number 932 has PID 0x 21 data 0x3 0x164 0x224 0x33 program number 933 has PID 0x 21 data 0x3 0x165 0x224 0x33 program number 934 has PID 0x 21 data 0x3 0x166 0x224 0x33 program number 935 has PID 0x 21 data 0x3 0x167 0x224 0x33 program number 936 has PID 0x 21 data 0x3 0x168 0x224 0x33 program number 975 has PID 0x 20 data 0x3 0x207 0x224 0x32 program number 976 has PID 0x 101 data 0x3 0x208 0x225 0x1 program number 330 has PID 0x 102 data 0x1 0x74 0x225 0x2 program number 350 has PID 0x1888 data 0x1 0x94 0x248 0x136 program number 360 has PID 0x 852 data 0x1 0x104 0x232 0x82 program number 320 has PID 0x 24 data 0x1 0x64 0x224 0x36 program number 901 has PID 0x 25 data 0x3 0x133 0x224 0x37 2006-10-20 00:01:22.720 desired_program(921) pid(0x20) 2006-10-20 00:01:22.720 pmt_pid(0x20) 2006-10-20 00:01:22.720 PAT for output stream 2006-10-20 00:01:22.720 Program Association Table PSIP tableID(0x0) length(13) extension(0x410) version(4) current(1) section(0) last_section(0) tsid: 1040 programCount: 1 program number 1 has PID 0x 20 data 0x0 0x1 0x224 0x32 2006-10-20 00:01:22.720 DVBSM(0)::AddPIDFilter(0x20): 2006-10-20 00:01:22.751 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:22.751 SM(0)::AddFlags: Seen(PMT,) Match() Wait() 2006-10-20 00:01:22.751 DTVSM(0) Error: Wrong PMT; pmt->pn(975) desired(921) 2006-10-20 00:01:22.754 SM(0)::AddFlags: Seen(PMT,) Match() Wait() 2006-10-20 00:01:22.755 DTVSM(0) Error: Wrong PMT; pmt->pn(926) desired(921) 2006-10-20 00:01:22.755 SM(0)::AddFlags: Seen(PMT,) Match() Wait() 2006-10-20 00:01:22.755 SM(0)::AddFlags: Seen() Match(PMT,) Wait() 2006-10-20 00:01:22.755 CreatePMTSingleProgram() 2006-10-20 00:01:22.755 PMT in input stream 2006-10-20 00:01:22.755 Program Map Table ver(1) pid(0x20) pnum(921) len(18) Stream #0 pid(0x399) type(audio-mp2-layer[1,2,3] 0x4) 2006-10-20 00:01:22.755 PMT for output stream 2006-10-20 00:01:22.755 Program Map Table ver(1) pid(0x20) pnum(1) len(18) Stream #0 pid(0x399) type(audio-mp2-layer[1,2,3] 0x4) 2006-10-20 00:01:22.755 SM(0)::AddFlags: Seen(PMT,) Match() Wait() 2006-10-20 00:01:22.755 DTVSM(0) Error: Wrong PMT; pmt->pn(918) desired(921) 2006-10-20 00:01:22.756 SM(0)::AddFlags: Seen(PMT,) Match() Wait() 2006-10-20 00:01:22.756 DTVSM(0) Error: Wrong PMT; pmt->pn(917) desired(921) 2006-10-20 00:01:22.757 SM(0)::AddFlags: Seen(PMT,) Match() Wait() 2006-10-20 00:01:22.757 DTVSM(0) Error: Wrong PMT; pmt->pn(916) desired(921) 2006-10-20 00:01:22.757 SM(0)::AddFlags: Seen(PMT,) Match() Wait() 2006-10-20 00:01:22.758 DTVSM(0) Error: Wrong PMT; pmt->pn(905) desired(921) 2006-10-20 00:01:22.769 DTVSM(0)::GetStatusList: WaitForPMT seen(1) matching(1) 2006-10-20 00:01:23.141 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:23.765 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:24.154 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:24.782 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:25.137 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:25.753 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:25.857 DTVSM(0)::SetNIT(): net_id = 72 2006-10-20 00:01:25.858 SM(0)::AddFlags: Seen(NIT,) Match() Wait() 2006-10-20 00:01:26.150 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:26.761 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:27.184 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:27.762 DTVSM(0)::SetNIT(): net_id = 72 2006-10-20 00:01:27.762 SM(0)::AddFlags: Seen(NIT,) Match() Wait() 2006-10-20 00:01:27.762 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:28.173 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:28.781 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:29.145 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:29.750 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:30.142 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:30.758 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:31.194 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:31.754 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:32.166 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:32.734 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:33.150 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:33.786 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:34.162 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:34.782 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:35.138 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:35.750 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:36.138 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:36.751 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:37.194 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:37.772 SM(0)::AddFlags: Seen(SDT,) Match() Wait() 2006-10-20 00:01:37.850 StopLiveTV(void) curRec: 0x8676980 pseudoRec: 0 2006-10-20 00:01:37.867 TVRec(3): Changing from WatchingLiveTV to None 2006-10-20 00:01:37.867 TVRec(3): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, 2006-10-20 00:01:37.867 TVRec(3): SetFlags(AskAllowRecording,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, 2006-10-20 00:01:37.867 TVRec(3): Request: Program(no) channel() input() flags(KillRec,KillRingBuffer,) 2006-10-20 00:01:37.867 TVRec(3): TeardownSignalMonitor() -- begin 2006-10-20 00:01:37.867 DVBSM(0)::Stop: begin 2006-10-20 00:01:37.867 SM(0)::Stop: begin 2006-10-20 00:01:37.878 SM(0)::Stop: end 2006-10-20 00:01:37.930 DVBSM(0): RunTableMonitorTS(): shutdown 2006-10-20 00:01:37.930 DVBSM(0)::RemovePIDFilter(0x0): 2006-10-20 00:01:37.930 DVBSM(0)::RemovePIDFilter(0x10): 2006-10-20 00:01:37.930 DVBSM(0)::RemovePIDFilter(0x11): 2006-10-20 00:01:37.930 DVBSM(0)::RemovePIDFilter(0x20): 2006-10-20 00:01:37.931 DVBSM(0): RunTableMonitorTS(): end 2006-10-20 00:01:37.931 DVBSM(0)::Stop: end 2006-10-20 00:01:37.931 TVRec(3): TeardownSignalMonitor() -- end 2006-10-20 00:01:37.931 TVRec(3): ClearFlags(SignalMonitorRunning,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,NeedToStartRecorder,DummyRecorderRunning,RingBufferReady, 2006-10-20 00:01:37.931 TVRec(3): ClearFlags(WaitingForSignal,) -> RunMainLoop,AskAllowRecording,NeedToStartRecorder,DummyRecorderRunning,RingBufferReady, 2006-10-20 00:01:37.931 TVRec(3): ClearFlags(DummyRecorderRunning,) -> RunMainLoop,AskAllowRecording,NeedToStartRecorder,RingBufferReady, 2006-10-20 00:01:37.933 TVRec(3): FinishedRecording(Eddie Halliwell) in recgroup: LiveTV 2006-10-20 00:01:37.934 Chain: Updated endtime for '2612_20061020000122' to 20061020000137 2006-10-20 00:01:37.935 Finished recording Eddie Halliwell: channel 2612 2006-10-20 00:01:37.938 scheduler: Finished recording: Eddie Halliwell: channel 2612 2006-10-20 00:01:37.938 DVBChan(0): Closing DVB channel 2006-10-20 00:01:37.938 DVB#0 CA: CiHandler thread stopped 2006-10-20 00:01:37.938 TVRec(3): Tearing down RingBuffer 2006-10-20 00:01:37.939 TVRec(3): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,AskAllowRecording,RingBufferReady,