2005-11-24 15:06:07.051 TVRec(1): ClearFlags(AskAllowRecording,) -> RunMainLoop, 2005-11-24 15:06:07.107 DB Error (Copy program data on record): Query was: REPLACE INTO recordedprogram SELECT * from program WHERE chanid = '1010' AND starttime = '2005-11-24T15:00:00'; Driver error was [2/1136]: QMYSQL3: Unable to execute query Database error was: Column count doesn't match value count at row 1 2005-11-24 15:06:07.110 TVRec(1): StartedRecording(0x8139e78) fn(/var/media/video/1010_20051124150600.mpg) 2005-11-24 15:06:07.111 TVRec(1): ClearFlags(CancelNextRecording,) -> RunMainLoop, 2005-11-24 15:06:07.111 TVRec(1): Changing from None to RecordingOnly 2005-11-24 15:06:07.112 TVRec(1): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop, 2005-11-24 15:06:07.112 TVRec(1): SetFlags(AskAllowRecording,) -> RunMainLoop,AskAllowRecording, 2005-11-24 15:06:07.113 TVRec(1): Request: Program(yes) channel() input() flags(Recording,) 2005-11-24 15:06:07.113 TVRec(1): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,AskAllowRecording, 2005-11-24 15:06:07.115 DVB#0 Opening DVB channel 2005-11-24 15:06:07.116 DVB#0 SetChannelByString(10) 2005-11-24 15:06:07.120 DVB#0 Frequency: 219500000 BW: 7MHz HP: 3/4 LP: 1/2C: QAM-64 TM: 8K H: None GI: 1/16 2005-11-24 15:06:07.120 DVB#0 Old Params: freq(571500000) type(OFDM) 2005-11-24 15:06:07.121 DVB#0 New Params: freq(219500000) type(OFDM) 2005-11-24 15:06:07.148 dvbchannel.cpp:wait_for_backend: Status: Signal, 2005-11-24 15:06:07.148 DVB#0 DVBChannel::Tune: Frequency tuning successful. 2005-11-24 15:06:07.148 DVB#0 Tuned to frequency for channel 10. 2005-11-24 15:06:07.149 TVRec(1): Starting Signal Monitor 2005-11-24 15:06:07.149 TVRec(1): SetupSignalMonitor(1, 0) 2005-11-24 15:06:07.149 DVB#0 Opening DVB channel 2005-11-24 15:06:07.172 SM(0)::AddFlags: Seen() Match() Wait(Sig,SNR,BER,UB,) 2005-11-24 15:06:07.173 DVBSM(0)::constructor(): initial flags 0x7400000 2005-11-24 15:06:07.173 TVRec(1): Signal monitor successfully created 2005-11-24 15:06:07.173 TVRec(1): Setting up table monitoring. 2005-11-24 15:06:07.173 TVRec(1): MPEG program number: 1573 2005-11-24 15:06:07.175 DTVSM(0)::SetProgramNumber(1573): 2005-11-24 15:06:07.176 SM(0)::RemoveFlags: Seen(PMT,) Match(PMT,) Wait() 2005-11-24 15:06:07.176 SM(0)::AddFlags: Seen() Match() Wait(PMT,) 2005-11-24 15:06:07.176 SM(0)::AddFlags: Seen() Match() Wait(PAT,PMT,) 2005-11-24 15:06:07.177 TVRec(1): Successfully set up MPEG table monitoring. 2005-11-24 15:06:07.177 SM(0)::Start: begin 2005-11-24 15:06:07.200 SM(0)::Start: end 2005-11-24 15:06:07.201 TVRec(1): SetFlags(SignalMonitorRunning,) -> RunMainLoop,AskAllowRecording,SignalMonitorRunning, 2005-11-24 15:06:07.201 TVRec(1): SetFlags(WaitingForSignal,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,SignalMonitorRunning, 2005-11-24 15:06:07.201 TVRec(1): SetFlags(NeedToStartRecorder,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning, 2005-11-24 15:06:07.205 Started recording: Huey's Cooking Adventures: channel 1010 on cardid 1, sourceid 1 2005-11-24 15:06:07.358 DVBSM(0)::UpdateValues: Signal Locked 2005-11-24 15:06:07.368 DVBSM(0)::UpdateValues: Waiting for table monitor to start 2005-11-24 15:06:07.368 DVBSM(0)::UpdateValues: Table monitor started 2005-11-24 15:06:07.375 DVBSM(0): RunTableMonitorTS(): begin (# of pids 2) 2005-11-24 15:06:07.376 DVBSM(0)::AddPIDFilter(0x0): 2005-11-24 15:06:07.381 DVBSM(0)::AddPIDFilter(0x1ffb): 2005-11-24 15:06:07.492 SM(0)::AddFlags: Seen(PAT,) Match() Wait() 2005-11-24 15:06:07.492 SM(0)::AddFlags: Seen() Match(PAT,) Wait() 2005-11-24 15:06:07.493 CreatePATSingleProgram() 2005-11-24 15:06:07.493 PAT in input stream 2005-11-24 15:06:07.493 Program Association Table PSIP prefix(0x0) tableID(0x0) length(45) extension(0x602) version(16) current(1) section(0) last_section(0) tsid: 1538 programCount: 9 program number 0 has PID 0x 10 data 0x0 0x0 0x224 0x16 program number 1569 has PID 0x 101 data 0x6 0x33 0x225 0x1 program number 1570 has PID 0x 102 data 0x6 0x34 0x225 0x2 program number 1571 has PID 0x 103 data 0x6 0x35 0x225 0x3 program number 1572 has PID 0x 104 data 0x6 0x36 0x225 0x4 program number 1573 has PID 0x 100 data 0x6 0x37 0x225 0x0 program number 1575 has PID 0x 106 data 0x6 0x39 0x225 0x6 program number 1576 has PID 0x 107 data 0x6 0x40 0x225 0x7 program number 1577 has PID 0x 108 data 0x6 0x41 0x225 0x8 2005-11-24 15:06:07.494 desired_program(1573) pid(0x100) 2005-11-24 15:06:07.495 pmt_pid(0x100) 2005-11-24 15:06:07.495 PAT for output stream 2005-11-24 15:06:07.495 Program Association Table PSIP prefix(0x0) tableID(0x0) length(13) extension(0x602) version(16) current(1) section(0) last_section(0) tsid: 1538 programCount: 1 program number 1 has PID 0x 100 data 0x0 0x1 0x225 0x0 2005-11-24 15:06:07.495 DVBSM(0)::AddPIDFilter(0x100): 2005-11-24 15:06:07.604 Parsed PMT(0x0x8181c18) Program Map Table ver(16) pid(0x100) pnum(1573) Unknown Descriptor (0xb) length(2) Unknown Descriptor (0x10) length(6) Stream #0 pid(0x200) type(video-mpeg2 0x2) Unknown Descriptor (0x2) length(3) Stream #1 pid(0x28a) type(audio-mp2-layer[1,2,3] 0x4) Unknown Descriptor (0x3) length(1) ISO-639 Language Descriptor (0xa) length(4) Stream #2 pid(0x28b) type(private-data 0x6) AC-3 Descriptor (0x6a) length(3) ISO-639 Language Descriptor (0xa) length(4) Stream #3 pid(0x240) type(private-data 0x6) Teletext Descriptor (0x56) length(5) 2005-11-24 15:06:07.605 SM(0)::AddFlags: Seen(PMT,) Match() Wait() 2005-11-24 15:06:07.605 SM(0)::AddFlags: Seen() Match(PMT,) Wait() 2005-11-24 15:06:07.606 CreatePMTSingleProgram() 2005-11-24 15:06:07.606 PMT in input stream 2005-11-24 15:06:07.606 Program Map Table ver(16) pid(0x100) pnum(1573) Unknown Descriptor (0xb) length(2) Unknown Descriptor (0x10) length(6) Stream #0 pid(0x200) type(video-mpeg2 0x2) Unknown Descriptor (0x2) length(3) Stream #1 pid(0x28a) type(audio-mp2-layer[1,2,3] 0x4) Unknown Descriptor (0x3) length(1) ISO-639 Language Descriptor (0xa) length(4) Stream #2 pid(0x28b) type(private-data 0x6) AC-3 Descriptor (0x6a) length(3) ISO-639 Language Descriptor (0xa) length(4) Stream #3 pid(0x240) type(private-data 0x6) Teletext Descriptor (0x56) length(5) 2005-11-24 15:06:07.607 Parsed PMT(0x0x8181c18) Program Map Table ver(16) pid(0x100) pnum(1573) Unknown Descriptor (0xb) length(2) Unknown Descriptor (0x10) length(6) Stream #0 pid(0x200) type(video-mpeg2 0x2) Unknown Descriptor (0x2) length(3) Stream #1 pid(0x28a) type(audio-mp2-layer[1,2,3] 0x4) Unknown Descriptor (0x3) length(1) ISO-639 Language Descriptor (0xa) length(4) Stream #2 pid(0x28b) type(private-data 0x6) AC-3 Descriptor (0x6a) length(3) ISO-639 Language Descriptor (0xa) length(4) Stream #3 pid(0x240) type(private-data 0x6) Teletext Descriptor (0x56) length(5) 2005-11-24 15:06:07.608 Parsed PMT(0x0x8182140) Program Map Table ver(0) pid(0x0) pnum(0) 2005-11-24 15:06:07.609 PMT for output stream 2005-11-24 15:06:07.609 Program Map Table ver(16) pid(0x100) pnum(1) Stream #0 pid(0x200) type(video-mpeg2 0x2) Stream #1 pid(0x28a) type(audio-mp2-layer[1,2,3] 0x4) Stream #2 pid(0x28b) type(private-data 0x6) 2005-11-24 15:06:07.648 TVRec(1): Got good signal 2005-11-24 15:06:07.648 TVRec(1): MPEG program num(1573) 2005-11-24 15:06:07.648 TVRec(1): TeardownSignalMonitor() -- begin 2005-11-24 15:06:07.649 DVBSM(0)::Stop: begin 2005-11-24 15:06:07.649 SM(0)::Stop: begin 2005-11-24 15:06:07.704 SM(0)::Stop: end 2005-11-24 15:06:07.712 DVBSM(0): RunTableMonitorTS(): shutdown 2005-11-24 15:06:07.712 DVBSM(0)::RemovePIDFilter(0x0): 2005-11-24 15:06:07.712 DVBSM(0)::RemovePIDFilter(0x100): 2005-11-24 15:06:07.713 DVBSM(0)::RemovePIDFilter(0x1ffb): 2005-11-24 15:06:07.975 DVBSM(0): RunTableMonitorTS(): end 2005-11-24 15:06:07.976 DVBSM(0)::Stop: end 2005-11-24 15:06:07.976 DVBSM(0)::Stop: begin 2005-11-24 15:06:07.976 SM(0)::Stop: begin 2005-11-24 15:06:07.976 SM(0)::Stop: end 2005-11-24 15:06:07.976 DVBSM(0)::Stop: end 2005-11-24 15:06:07.977 DVBSM(0)::Stop: begin 2005-11-24 15:06:07.977 SM(0)::Stop: begin 2005-11-24 15:06:07.977 SM(0)::Stop: end 2005-11-24 15:06:07.977 DVBSM(0)::Stop: end 2005-11-24 15:06:07.977 TVRec(1): TeardownSignalMonitor() -- end 2005-11-24 15:06:07.977 TVRec(1): ClearFlags(WaitingForSignal,SignalMonitorRunning,) -> RunMainLoop,AskAllowRecording,NeedToStartRecorder, 2005-11-24 15:06:07.978 TVRec(1): prog_num(1573) vs. dvbc->srv_id(1573) 2005-11-24 15:06:07.979 SIParser: About to do a reset 2005-11-24 15:06:07.979 SIParser: Closing all PIDs 2005-11-24 15:06:07.979 SIParser: Using DelAllPids from SIParser which does nothing 2005-11-24 15:06:07.979 SIParser: Resetting all Table Handlers 2005-11-24 15:06:07.979 SIParser: SIParser Reset due to channel change 2005-11-24 15:06:07.979 SIParser: Added initial preferred language 'eng' with priority 1 2005-11-24 15:06:07.980 SIParser: Added initial preferred language 'eng' with priority 2 2005-11-24 15:06:07.980 DVB#0 DVB SI Table Parser Started 2005-11-24 15:06:07.981 DVBSM(0)::Stop: begin 2005-11-24 15:06:07.981 SM(0)::Stop: begin 2005-11-24 15:06:07.981 SM(0)::Stop: end 2005-11-24 15:06:07.981 DVBSM(0)::Stop: end 2005-11-24 15:06:07.981 SM(0)::Stop: begin 2005-11-24 15:06:07.982 SM(0)::Stop: end 2005-11-24 15:06:07.982 SIParser: ReinitSIParser(std dvb, service #1573) 2005-11-24 15:06:07.983 FillPMap(str dvb) 2005-11-24 15:06:07.983 FillPMap(SIS dvb) 2005-11-24 15:06:07.983 SIParser: Requesting PAT 2005-11-24 15:06:07.983 SIParser: Adding the Service with ID 1573 in the SDT to the request list 2005-11-24 15:06:07.983 TVRec(1): SetFlags(WaitingForSIParser,SIParserRunning,) -> RunMainLoop,AskAllowRecording,WaitingForSIParser,NeedToStartRecorder,SIParserRunning, 2005-11-24 15:06:07.984 SIParser: Starting DVB Section Reader thread 2005-11-24 15:06:07.985 SIParser: Table[0]->RequirePIDs() == true 2005-11-24 15:06:07.985 SIParser: Adding PID 0x 0 Filter 0x 0 Mask 0xff Buffer 40960 2005-11-24 15:06:07.990 SIParser: Table[2]->RequirePIDs() == true 2005-11-24 15:06:07.990 SIParser: Adding PID 0x1ffb Filter 0xff Mask 0x 0 Buffer 40960 2005-11-24 15:06:07.991 SIParser: Table[3]->RequirePIDs() == true 2005-11-24 15:06:07.991 SIParser: Adding PID 0x1ffb Filter 0xff Mask 0x 0 Buffer 40960 2005-11-24 15:06:07.992 SIParser: Table[6]->RequirePIDs() == true 2005-11-24 15:06:07.992 SIParser: Adding PID 0x 10 Filter 0x40 Mask 0xff Buffer 40960 2005-11-24 15:06:08.173 SIParser: PAT Version = 1 2005-11-24 15:06:08.173 SIParser: Tuned to TransportID: 1538 2005-11-24 15:06:08.174 SIParser: NIT Present on this transport on PID 0x10 2005-11-24 15:06:08.174 SIParser: PMT #1569 on PID 0x257 2005-11-24 15:06:08.174 SIParser: PMT #1570 on PID 0x258 2005-11-24 15:06:08.174 SIParser: PMT #1571 on PID 0x259 2005-11-24 15:06:08.174 SIParser: PMT #1572 on PID 0x260 2005-11-24 15:06:08.175 SIParser: PMT #1573 on PID 0x256 2005-11-24 15:06:08.175 SIParser: PMT #1575 on PID 0x262 2005-11-24 15:06:08.175 SIParser: PMT #1576 on PID 0x263 2005-11-24 15:06:08.175 SIParser: PMT #1577 on PID 0x264 2005-11-24 15:06:08.176 SIParser: Services on this Transport: 1569 1570 1571 1572 1573 1575 1576 1577 2005-11-24 15:06:08.176 SIParser: Table[0]->Complete() == true 2005-11-24 15:06:08.176 SIParser: Table[1]->RequirePIDs() == true 2005-11-24 15:06:08.177 SIParser: Adding PID 0x 100 Filter 0x 2 Mask 0xff Buffer 40960 2005-11-24 15:06:08.298 SIParser: PMT ServiceID: 1573 Version = 1 2005-11-24 15:06:08.299 SIParser: Unused Descriptor: Unknown Descriptor (0xb) length(2) 2005-11-24 15:06:08.299 SIParser: Unused Descriptor: Unknown Descriptor (0x10) length(6) 2005-11-24 15:06:08.299 SIParser: PID: 512 2005-11-24 15:06:08.299 SIParser: Unused Descriptor: Unknown Descriptor (0x2) length(3) 2005-11-24 15:06:08.300 SIParser: PID: 650 2005-11-24 15:06:08.300 SIParser: Unused Descriptor: Unknown Descriptor (0x3) length(1) 2005-11-24 15:06:08.300 SIParser: PID: 651 2005-11-24 15:06:08.300 SIParser: AC3 Descriptor 2005-11-24 15:06:08.300 SIParser: PID: 576 2005-11-24 15:06:08.301 SIParser: Teletext Descriptor 2005-11-24 15:06:08.301 SIParser: lang: eng, type: 2, mag: 0, page: 1 2005-11-24 15:06:08.301 SIParser: Table[1]->EmitRequired() == true 2005-11-24 15:06:08.301 DVB#0 SetPMT ServiceID=1573, PCRPID=128 (0x80) 2005-11-24 15:06:08.302 TVRec(1): Got SIParser PMT 2005-11-24 15:06:08.302 TVRec(1): ClearFlags(WaitingForSIParser,) -> RunMainLoop,AskAllowRecording,NeedToStartRecorder,SIParserRunning, 2005-11-24 15:06:08.302 TVRec(1): Starting Recorder 2005-11-24 15:06:08.308 TVRec(1): Using profile 'Default' to record 2005-11-24 15:06:08.367 RecBase(0): SetRingBuffer(0x81a5898) '/var/media/video/1010_20051124150600.mpg' 2005-11-24 15:06:08.368 RecBase(0): SetRecording(0x8139e78) title(Huey's Cooking Adventures) 2005-11-24 15:06:08.371 DVBRec(0): Card opened successfully (using TS mode). 2005-11-24 15:06:08.372 DVBRec(0): SetPMTObject() 2005-11-24 15:06:08.372 DVBRec(0): AutoPID for MPEG Program Number(1573), PCR PID(0x80) 2005-11-24 15:06:08.373 DVBRec(0): AutoPID selecting PID 0x200, MPEG-2 Video 2005-11-24 15:06:08.373 DVBRec(0): AutoPID selecting PID 0x28a, MPEG-2 Audio (eng 2005-11-24 15:06:08.373 DVBRec(0): AutoPID selecting PID 0x28b, AC3 Audio (eng 2005-11-24 15:06:08.374 DVBRec(0): AutoPID selecting PID 0x240, Teletext 2005-11-24 15:06:08.374 DVBRec(0): AutoPID Complete - PAT/PMT Loaded for service 2005-11-24 15:06:08.374 DVBRec(0): A/V Stream is unencrypted 2005-11-24 15:06:08.375 DVBRec(0): Resetting Demux Filters 2005-11-24 15:06:08.375 DVBRec(0): Adding pid 0x200 size(3600384) 2005-11-24 15:06:08.376 DVBRec(0): Set demux buffer size for pid 0x200 to 3600384, which gives us a 1500 msec buffer. 2005-11-24 15:06:08.376 DVBRec(0): Adding pid 0x80 size(3600384) 2005-11-24 15:06:08.377 DVBRec(0): Set demux buffer size for pid 0x80 to 3600384, which gives us a 1500 msec buffer. 2005-11-24 15:06:08.378 DVBRec(0): Adding pid 0x28a size(94208) 2005-11-24 15:06:08.378 DVBRec(0): Set demux buffer size for pid 0x28a to 94208, which gives us a 1500 msec buffer. 2005-11-24 15:06:08.379 DVBRec(0): Adding pid 0x28b size(94208) 2005-11-24 15:06:08.379 DVBRec(0): Set demux buffer size for pid 0x28b to 94208, which gives us a 1500 msec buffer. 2005-11-24 15:06:08.380 DVBRec(0): Adding pid 0x240 size(94208) 2005-11-24 15:06:08.380 TVRec(1): SetFlags(RecorderRunning,) -> RunMainLoop,AskAllowRecording,NeedToStartRecorder,SIParserRunning,RecorderRunning, 2005-11-24 15:06:08.381 TVRec(1): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,AskAllowRecording,SIParserRunning,RecorderRunning, 2005-11-24 15:06:08.380 DVBRec(0): Set demux buffer size for pid 0x240 to 94208, which gives us a 1500 msec buffer. 2005-11-24 15:06:08.384 Parsed PMT(0x0x818baf0) Program Map Table ver(16) pid(0x20) pnum(1) Unknown Descriptor (0xb) length(2) Unknown Descriptor (0x10) length(6) Stream #0 pid(0x200) type(video-mpeg2 0x2) Unknown Descriptor (0x2) length(3) Stream #1 pid(0x28a) type(audio-mp2-layer[1,2,3] 0x4) Unknown Descriptor (0x3) length(1) ISO-639 Language Descriptor (0xa) length(4) Stream #2 pid(0x28b) type(audio-ac3 0x129) AC-3 Descriptor (0x6a) length(3) ISO-639 Language Descriptor (0xa) length(4) Stream #3 pid(0x240) type(private-data 0x6) Teletext Descriptor (0x56) length(5) 2005-11-24 15:06:08.386 DVB#0 Data read from DMX - This is for debugging with transform.c 2005-11-24 15:06:09.029 SIParser: Private Type channel_numbers = 131 defined for NetworkID 4116 2005-11-24 15:06:09.029 SIParser: ChannelNumbers Present using Descriptor 131 2005-11-24 15:06:09.029 SIParser: Unused Descriptor: Private Data Specifier Descriptor (0x5f) length(4) 2005-11-24 15:06:09.030 SIParser: Unused Descriptor: Service List Descriptor (0x41) length(24) 2005-11-24 15:06:09.030 SIParser: Table[6]->Complete() == true 2005-11-24 15:06:09.030 SIParser: Table[5]->RequirePIDs() == true 2005-11-24 15:06:09.031 SIParser: Adding PID 0x 11 Filter 0x46 Mask 0xfb Buffer 40960 2005-11-24 15:06:10.319 SIParser: SDT: NetworkID=4116 TransportID=1538 2005-11-24 15:06:10.320 SIParser: Unused Descriptor: Country Availability Descriptor (0x49) length(4) 2005-11-24 15:06:10.320 SIParser: Unused Descriptor: LinkageDescriptor(stub) 2005-11-24 15:06:10.320 SIParser: Unused Descriptor: Multilingual Service Name Descriptor (0x5d) length(28) 2005-11-24 15:06:10.321 SIParser: SDT: sid=1571 type=1 eit_present=0 eit_requested=0 name=TEN Digital 2 2005-11-24 15:06:10.321 SIParser: Unused Descriptor: Country Availability Descriptor (0x49) length(4) 2005-11-24 15:06:10.321 SIParser: Unused Descriptor: Multilingual Service Name Descriptor (0x5d) length(26) 2005-11-24 15:06:10.321 SIParser: SDT: sid=1573 type=1 eit_present=0 eit_requested=0 name=TEN Digital 2005-11-24 15:06:10.322 SIParser: Unused Descriptor: Country Availability Descriptor (0x49) length(4) 2005-11-24 15:06:10.322 SIParser: Unused Descriptor: Multilingual Service Name Descriptor (0x5d) length(26) 2005-11-24 15:06:10.322 SIParser: SDT: sid=1569 type=1 eit_present=0 eit_requested=0 name=TEN Digital 2005-11-24 15:06:10.322 SIParser: Unused Descriptor: Country Availability Descriptor (0x49) length(4) 2005-11-24 15:06:10.323 SIParser: Unused Descriptor: Multilingual Service Name Descriptor (0x5d) length(28) 2005-11-24 15:06:10.323 SIParser: SDT: sid=1570 type=1 eit_present=0 eit_requested=0 name=TEN Digital 1 2005-11-24 15:06:10.323 SIParser: Unused Descriptor: Country Availability Descriptor (0x49) length(4) 2005-11-24 15:06:10.323 SIParser: Unused Descriptor: Multilingual Service Name Descriptor (0x5d) length(28) 2005-11-24 15:06:10.324 SIParser: SDT: sid=1572 type=1 eit_present=0 eit_requested=0 name=TEN Digital 3 2005-11-24 15:06:10.324 SIParser: Unused Descriptor: Multilingual Service Name Descriptor (0x5d) length(24) 2005-11-24 15:06:10.324 SIParser: SDT: sid=1575 type=1 eit_present=0 eit_requested=0 name=TEN Guide 2005-11-24 15:06:10.324 SIParser: Unused Descriptor: Multilingual Service Name Descriptor (0x5d) length(21) 2005-11-24 15:06:10.325 SIParser: SDT: sid=1576 type=1 eit_present=0 eit_requested=0 name=TEN HD 2005-11-24 15:06:10.325 SIParser: Unused Descriptor: Multilingual Service Name Descriptor (0x5d) length(24) 2005-11-24 15:06:10.325 SIParser: SDT: sid=1577 type=1 eit_present=0 eit_requested=0 name=TEN Guide 2005-11-24 15:06:10.325 SIParser: Table[4]->RequirePIDs() == true 2005-11-24 15:06:10.326 SIParser: Adding PID 0x 12 Filter 0x 0 Mask 0x 0 Buffer 4096000 2005-11-24 15:06:56.105 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:06:56.106 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:06:56.109 AutoExpire: SendDeleteMessages, cycling through expire list. 2005-11-24 15:07:56.162 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:07:56.163 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:08:56.215 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:08:56.216 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:08:56.219 AutoExpire: SendDeleteMessages, cycling through expire list. 2005-11-24 15:09:56.272 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:09:56.273 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:10:56.325 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:10:56.326 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:10:56.333 AutoExpire: SendDeleteMessages, cycling through expire list. 2005-11-24 15:10:56.335 AutoExpire: Checking episode count for each recording profile using max episodes 2005-11-24 15:11:56.386 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:11:56.387 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:12:56.440 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:12:56.440 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:12:56.444 AutoExpire: SendDeleteMessages, cycling through expire list. 2005-11-24 15:13:56.496 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:13:56.497 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:14:56.549 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:14:56.550 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:14:56.553 AutoExpire: SendDeleteMessages, cycling through expire list. 2005-11-24 15:15:56.606 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:15:56.607 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:15:56.609 AutoExpire: Checking episode count for each recording profile using max episodes 2005-11-24 15:16:56.663 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:16:56.664 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:16:56.668 AutoExpire: SendDeleteMessages, cycling through expire list. 2005-11-24 15:17:56.720 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:17:56.721 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:18:56.774 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:18:56.774 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:18:56.778 AutoExpire: SendDeleteMessages, cycling through expire list. 2005-11-24 15:19:56.831 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:19:56.831 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:20:56.884 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:20:56.884 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:20:56.887 AutoExpire: SendDeleteMessages, cycling through expire list. 2005-11-24 15:20:56.890 AutoExpire: Checking episode count for each recording profile using max episodes 2005-11-24 15:21:56.945 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:21:56.945 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:22:56.998 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:22:56.998 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:22:57.002 AutoExpire: SendDeleteMessages, cycling through expire list. 2005-11-24 15:23:57.055 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:23:57.055 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:24:57.108 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:24:57.108 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:24:57.112 AutoExpire: SendDeleteMessages, cycling through expire list. 2005-11-24 15:25:57.165 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:25:57.165 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:25:57.168 AutoExpire: Checking episode count for each recording profile using max episodes 2005-11-24 15:26:57.222 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:26:57.223 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:26:57.226 AutoExpire: SendDeleteMessages, cycling through expire list. 2005-11-24 15:27:57.279 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:27:57.280 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:28:57.332 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:28:57.333 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:28:57.336 AutoExpire: SendDeleteMessages, cycling through expire list. 2005-11-24 15:29:57.389 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:29:57.390 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:30:57.442 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:30:57.443 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:30:57.447 AutoExpire: SendDeleteMessages, cycling through expire list. 2005-11-24 15:30:57.449 AutoExpire: Checking episode count for each recording profile using max episodes 2005-11-24 15:31:57.503 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:31:57.504 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:32:57.556 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:32:57.557 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:32:57.560 AutoExpire: SendDeleteMessages, cycling through expire list. 2005-11-24 15:33:57.613 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:33:57.614 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:34:57.666 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:34:57.667 1010 @ Thu Nov 24 15:06:00 2005 2005-11-24 15:34:57.670 AutoExpire: SendDeleteMessages, cycling through expire list. 2005-11-24 15:35:00.437 TVRec(1): ClearFlags(FinishRecording,) -> RunMainLoop,AskAllowRecording,SIParserRunning,RecorderRunning, 2005-11-24 15:35:00.438 TVRec(1): Changing from RecordingOnly to None 2005-11-24 15:35:00.438 TVRec(1): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,AskAllowRecording,SIParserRunning,RecorderRunning, 2005-11-24 15:35:00.438 TVRec(1): SetFlags(AskAllowRecording,) -> RunMainLoop,AskAllowRecording,SIParserRunning,RecorderRunning, 2005-11-24 15:35:00.439 TVRec(1): Request: Program(no) channel() input() flags(CloseRec,KillRingBuffer,) 2005-11-24 15:35:00.439 SIParser: Stopping DVB Section Reader 2005-11-24 15:35:00.458 SIParser: DVB Section Reader thread stopped 2005-11-24 15:35:00.460 TVRec(1): ClearFlags(SIParserRunning,) -> RunMainLoop,AskAllowRecording,RecorderRunning, 2005-11-24 15:35:00.460 DVBRec(0): Close() fd(23) -- begin 2005-11-24 15:35:00.726 DVBRec(0): Close() fd(-1) -- end 2005-11-24 15:35:00.730 Reschedule requested for id 0. 2005-11-24 15:35:00.735 Finished recording Huey's Cooking Adventures: channel 1010 2005-11-24 15:35:00.793 DVBRec(0): Close() fd(-1) -- begin 2005-11-24 15:35:00.883 TVRec(1): ClearFlags(RecorderRunning,) -> RunMainLoop,AskAllowRecording, 2005-11-24 15:35:00.913 TVRec(1): ClearFlags(RecorderRunning,) -> RunMainLoop,AskAllowRecording, 2005-11-24 15:35:00.914 TVRec(1): Tearing down RingBuffer 2005-11-24 15:35:00.916 TVRec(1): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,AskAllowRecording, 2005-11-24 15:35:00.926 Scheduled 19 items in 0.2 = 0.00 match + 0.20 place 2005-11-24 15:35:00.928 Preview Error: Previewer file '/var/media/video/1010_20051124150600.mpg' is not valid. 2005-11-24 15:35:46.149 Commercial Flagging Starting for Huey's Cooking Adventures recorded from channel 1010 at Thu Nov 24 15:06:00 2005 2005-11-24 15:35:46.379 Using runtime prefix = /usr/local 2005-11-24 15:35:46.410 New DB connection, total: 1 2005-11-24 15:35:46.415 Enabling Settings Cache. 2005-11-24 15:35:46.416 Clearing Settings Cache. 2005-11-24 15:35:46.426 New DB connection, total: 2 2005-11-24 15:35:46.431 Invalid file handle when opening /var/media/video/1010_20051124150600.mpg. 6 retries remaining. 2005-11-24 15:35:46.933 Invalid file handle when opening /var/media/video/1010_20051124150600.mpg. 5 retries remaining. 2005-11-24 15:35:47.437 Invalid file handle when opening /var/media/video/1010_20051124150600.mpg. 4 retries remaining. 2005-11-24 15:35:47.941 Invalid file handle when opening /var/media/video/1010_20051124150600.mpg. 3 retries remaining. 2005-11-24 15:35:48.445 Invalid file handle when opening /var/media/video/1010_20051124150600.mpg. 2 retries remaining. 2005-11-24 15:35:48.949 Invalid file handle when opening /var/media/video/1010_20051124150600.mpg. 1 retries remaining. 2005-11-24 15:35:49.453 Invalid file handle when opening /var/media/video/1010_20051124150600.mpg. 0 retries remaining. 2005-11-24 15:35:49.975 Connecting to backend server: 192.168.1.100:6543 (try 1 of 5) 2005-11-24 15:35:49.986 Using protocol version 22 2005-11-24 15:35:49.993 MainServer::HandleAnnounce Monitor 2005-11-24 15:35:49.994 adding: server as a client (events: 0) 2005-11-24 15:35:50.059 NVP::OpenFile(): Error, file not found: /var/media/video/1010_20051124150600.mpg 2005-11-24 15:35:50.071 Unknown socket closing 2005-11-24 15:35:50.082 Commercial Flagging Finished, 0 break(s) found. 2005-11-24 15:35:50.084 Preview Error: Previewer file '/var/media/video/1010_20051124150600.mpg' is not valid. 2005-11-24 15:35:57.724 AutoExpire: Checking episode count for each recording profile using max episodes