2005-11-24 14:58:35.382 Using runtime prefix = /usr/local 2005-11-24 14:58:35.489 New DB connection, total: 1 2005-11-24 14:58:35.494 Enabling Settings Cache. 2005-11-24 14:58:35.494 Clearing Settings Cache. 2005-11-24 14:58:35.498 Current Schema Version: 1109 Starting up as the master server. 2005-11-24 14:58:35.516 New DB connection, total: 2 2005-11-24 14:58:35.540 DVB#0 Opening DVB channel 2005-11-24 14:58:35.540 DVB#0 Using DVB card 0, with frontend 'DViCO FusionHDTV DVB-T Plus'. 2005-11-24 14:58:35.541 DVB#0 SetChannelByString(21) 2005-11-24 14:58:35.544 New DB connection, total: 3 2005-11-24 14:58:35.548 DVB#0 Frequency: 226500000 BW: 7MHz HP: 3/4 LP: 1/2C: QAM-64 TM: 8K H: None GI: 1/16 2005-11-24 14:58:35.549 DVB#0 Old Params: freq(0) type(OFDM) 2005-11-24 14:58:35.549 DVB#0 New Params: freq(226500000) type(OFDM) 2005-11-24 14:58:35.549 dvbchannel.cpp:wait_for_backend: Status: 2005-11-24 14:58:35.550 DVB#0 DVBChannel::Tune: Frequency tuning successful. 2005-11-24 14:58:35.550 DVB#0 Tuned to frequency for channel 21. 2005-11-24 14:58:35.589 TVRec(1): SetFlags(RunMainLoop,) -> RunMainLoop, 2005-11-24 14:58:35.590 TVRec(1): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, 2005-11-24 14:58:35.594 DVB#1 Opening DVB channel 2005-11-24 14:58:35.594 DVB#1 Using DVB card 1, with frontend 'DST DVB-T'. 2005-11-24 14:58:35.595 DVB#1 SetChannelByString(3) 2005-11-24 14:58:35.598 DVB#1 Frequency: 571500000 BW: 7MHz HP: 2/3 LP: 1/2C: QAM-64 TM: 8K H: None GI: 1/8 2005-11-24 14:58:35.599 DVB#1 Old Params: freq(0) type(OFDM) 2005-11-24 14:58:35.599 DVB#1 New Params: freq(571500000) type(OFDM) 2005-11-24 14:58:35.599 dvbchannel.cpp:wait_for_backend: Status: 2005-11-24 14:58:35.600 DVB#1 DVBChannel::Tune: Frequency tuning successful. 2005-11-24 14:58:35.600 DVB#1 Tuned to frequency for channel 3. 2005-11-24 14:58:35.607 TVRec(2): SetFlags(RunMainLoop,) -> RunMainLoop, 2005-11-24 14:58:35.608 TVRec(2): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, 2005-11-24 14:58:35.610 New DB scheduler connection 2005-11-24 14:58:35.642 mythbackend version: 0.19.20051122-1 www.mythtv.org 2005-11-24 14:58:35.643 Enabled verbose msgs : important general record file channel siparser 2005-11-24 14:58:35.643 AutoExpire: Found 2 recorders w/max rate of 277 MiB/min 2005-11-24 14:58:35.646 AutoExpire: Required Free Space: 2.2 GB w/freq: 5 min 2005-11-24 14:58:37.638 Reschedule requested for id -1. 2005-11-24 14:58:37.861 Scheduled 22 items in 0.2 = 0.03 match + 0.19 place 2005-11-24 14:58:37.870 Recording starts soon, AUTO-Startup assumed 2005-11-24 14:58:44.407 MainServer::HandleAnnounce Monitor 2005-11-24 14:58:44.407 adding: epia as a client (events: 0) 2005-11-24 14:58:44.415 MainServer::HandleAnnounce Monitor 2005-11-24 14:58:44.415 adding: epia as a client (events: 1) 2005-11-24 14:58:51.035 MainServer::HandleAnnounce Playback 2005-11-24 14:58:51.036 adding: server as a client (events: 0) 2005-11-24 14:58:55.660 AutoExpire: SendDeleteMessages, cycling through expire list. 2005-11-24 14:59:24.622 MainServer::HandleAnnounce Playback 2005-11-24 14:59:24.622 adding: server as a client (events: 0) 2005-11-24 14:59:29.915 MainServer::HandleAnnounce Playback 2005-11-24 14:59:29.915 adding: server as a client (events: 0) 2005-11-24 14:59:35.779 MainServer::HandleAnnounce Playback 2005-11-24 14:59:35.779 adding: server as a client (events: 0) 2005-11-24 14:59:35.956 Reschedule requested for id 173. 2005-11-24 14:59:36.152 Scheduled 23 items in 0.2 = 0.03 match + 0.17 place 2005-11-24 14:59:36.156 TVRec(1): ClearFlags(AskAllowRecording,) -> RunMainLoop, 2005-11-24 14:59:36.213 DB Error (Copy program data on record): Query was: REPLACE INTO recordedprogram SELECT * from program WHERE chanid = '1003' AND starttime = '2005-11-24T14:30: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 14:59:36.216 TVRec(1): StartedRecording(0x814a748) fn(/var/media/video/1003_20051124150000.mpg) 2005-11-24 14:59:36.217 TVRec(1): ClearFlags(CancelNextRecording,) -> RunMainLoop, 2005-11-24 14:59:36.218 TVRec(1): Changing from None to RecordingOnly 2005-11-24 14:59:36.218 TVRec(1): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop, 2005-11-24 14:59:36.219 TVRec(1): SetFlags(AskAllowRecording,) -> RunMainLoop,AskAllowRecording, 2005-11-24 14:59:36.219 TVRec(1): Request: Program(yes) channel() input() flags(Recording,) 2005-11-24 14:59:36.219 TVRec(1): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,AskAllowRecording, 2005-11-24 14:59:36.221 DVB#0 Opening DVB channel 2005-11-24 14:59:36.222 DVB#0 SetChannelByString(3) 2005-11-24 14:59:36.226 DVB#0 Frequency: 571500000 BW: 7MHz HP: 2/3 LP: 1/2C: QAM-64 TM: 8K H: None GI: 1/8 2005-11-24 14:59:36.226 DVB#0 Old Params: freq(226500000) type(OFDM) 2005-11-24 14:59:36.227 DVB#0 New Params: freq(571500000) type(OFDM) 2005-11-24 14:59:36.227 dvbchannel.cpp:wait_for_backend: Status: 2005-11-24 14:59:36.227 DVB#0 DVBChannel::Tune: Frequency tuning successful. 2005-11-24 14:59:36.227 DVB#0 Tuned to frequency for channel 3. 2005-11-24 14:59:36.227 TVRec(1): Starting Signal Monitor 2005-11-24 14:59:36.228 TVRec(1): SetupSignalMonitor(1, 0) 2005-11-24 14:59:36.228 DVB#0 Opening DVB channel 2005-11-24 14:59:36.272 SM(0)::AddFlags: Seen() Match() Wait(Sig,SNR,BER,UB,) 2005-11-24 14:59:36.272 DVBSM(0)::constructor(): initial flags 0x7400000 2005-11-24 14:59:36.272 TVRec(1): Signal monitor successfully created 2005-11-24 14:59:36.272 TVRec(1): Setting up table monitoring. 2005-11-24 14:59:36.273 TVRec(1): MPEG program number: 769 2005-11-24 14:59:36.275 DTVSM(0)::SetProgramNumber(769): 2005-11-24 14:59:36.276 SM(0)::RemoveFlags: Seen(PMT,) Match(PMT,) Wait() 2005-11-24 14:59:36.276 SM(0)::AddFlags: Seen() Match() Wait(PMT,) 2005-11-24 14:59:36.276 SM(0)::AddFlags: Seen() Match() Wait(PAT,PMT,) 2005-11-24 14:59:36.276 TVRec(1): Successfully set up MPEG table monitoring. 2005-11-24 14:59:36.277 SM(0)::Start: begin 2005-11-24 14:59:36.284 SM(0)::Start: end 2005-11-24 14:59:36.284 TVRec(1): SetFlags(SignalMonitorRunning,) -> RunMainLoop,AskAllowRecording,SignalMonitorRunning, 2005-11-24 14:59:36.284 TVRec(1): SetFlags(WaitingForSignal,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,SignalMonitorRunning, 2005-11-24 14:59:36.284 TVRec(1): SetFlags(NeedToStartRecorder,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning, 2005-11-24 14:59:36.300 Started recording: Italian News: channel 1003 on cardid 1, sourceid 1 2005-11-24 14:59:36.458 DVBSM(0)::UpdateValues: Signal Locked 2005-11-24 14:59:36.459 DVBSM(0)::AddPIDFilter(0x0): 2005-11-24 14:59:36.459 DVBSM(0)::UpdateValues: Waiting for table monitor to start 2005-11-24 14:59:36.460 DVBSM(0)::UpdateValues: Table monitor started 2005-11-24 14:59:36.494 DVBSM(0): Will use TS table monitoring 2005-11-24 14:59:36.494 DVBSM(0)::RemovePIDFilter(0x0): 2005-11-24 14:59:36.828 DVBSM(0): RunTableMonitorTS(): begin (# of pids 2) 2005-11-24 14:59:36.829 DVBSM(0)::AddPIDFilter(0x0): 2005-11-24 14:59:36.834 DVBSM(0)::AddPIDFilter(0x1ffb): 2005-11-24 14:59:36.884 SM(0)::AddFlags: Seen(PAT,) Match() Wait() 2005-11-24 14:59:36.884 SM(0)::AddFlags: Seen() Match(PAT,) Wait() 2005-11-24 14:59:36.885 CreatePATSingleProgram() 2005-11-24 14:59:36.885 PAT in input stream 2005-11-24 14:59:36.885 Program Association Table PSIP prefix(0x0) tableID(0x0) length(37) extension(0x300) version(29) current(1) section(0) last_section(0) tsid: 768 programCount: 7 program number 0 has PID 0x 10 data 0x0 0x0 0x224 0x16 program number 768 has PID 0x 400 data 0x3 0x0 0x228 0x0 program number 769 has PID 0x 401 data 0x3 0x1 0x228 0x1 program number 770 has PID 0x 402 data 0x3 0x2 0x228 0x2 program number 772 has PID 0x 408 data 0x3 0x4 0x228 0x8 program number 782 has PID 0x 403 data 0x3 0x14 0x228 0x3 program number 783 has PID 0x 404 data 0x3 0x15 0x228 0x4 2005-11-24 14:59:36.886 desired_program(769) pid(0x401) 2005-11-24 14:59:36.886 pmt_pid(0x401) 2005-11-24 14:59:36.886 PAT for output stream 2005-11-24 14:59:36.887 Program Association Table PSIP prefix(0x0) tableID(0x0) length(13) extension(0x300) version(29) current(1) section(0) last_section(0) tsid: 768 programCount: 1 program number 1 has PID 0x 401 data 0x0 0x1 0x228 0x1 2005-11-24 14:59:36.887 DVBSM(0)::AddPIDFilter(0x401): 2005-11-24 14:59:36.914 Parsed PMT(0x0x8169618) Program Map Table ver(27) pid(0x401) pnum(769) Stream #0 pid(0xa1) type(video-mpeg2 0x2) ISO-639 Language Descriptor (0xa) length(4) Stream Identifier Descriptor (0x52) length(1) Stream #1 pid(0x51) type(audio-mp1-layer[1,2,3] 0x3) ISO-639 Language Descriptor (0xa) length(4) Stream Identifier Descriptor (0x52) length(1) Stream #2 pid(0x29) type(private-data 0x6) ISO-639 Language Descriptor (0xa) length(4) Stream Identifier Descriptor (0x52) length(1) Teletext Descriptor (0x56) length(5) Stream #3 pid(0x384) type(dsmcc-b std data 0x11) Stream Identifier Descriptor (0x52) length(1) Unknown Descriptor (0x13) length(25) Unknown Descriptor (0x14) length(13) Data Broadcast Identifier Descriptor (0x66) length(2) Stream #4 pid(0x1f4) type(private-sec 0x5) Stream Identifier Descriptor (0x52) length(1) Application Signalling Descriptor (0x6f) length(3) 2005-11-24 14:59:36.916 SM(0)::AddFlags: Seen(PMT,) Match() Wait() 2005-11-24 14:59:36.916 SM(0)::AddFlags: Seen() Match(PMT,) Wait() 2005-11-24 14:59:36.916 CreatePMTSingleProgram() 2005-11-24 14:59:36.916 PMT in input stream 2005-11-24 14:59:36.916 Program Map Table ver(27) pid(0x401) pnum(769) Stream #0 pid(0xa1) type(video-mpeg2 0x2) ISO-639 Language Descriptor (0xa) length(4) Stream Identifier Descriptor (0x52) length(1) Stream #1 pid(0x51) type(audio-mp1-layer[1,2,3] 0x3) ISO-639 Language Descriptor (0xa) length(4) Stream Identifier Descriptor (0x52) length(1) Stream #2 pid(0x29) type(private-data 0x6) ISO-639 Language Descriptor (0xa) length(4) Stream Identifier Descriptor (0x52) length(1) Teletext Descriptor (0x56) length(5) Stream #3 pid(0x384) type(dsmcc-b std data 0x11) Stream Identifier Descriptor (0x52) length(1) Unknown Descriptor (0x13) length(25) Unknown Descriptor (0x14) length(13) Data Broadcast Identifier Descriptor (0x66) length(2) Stream #4 pid(0x1f4) type(private-sec 0x5) Stream Identifier Descriptor (0x52) length(1) Application Signalling Descriptor (0x6f) length(3) 2005-11-24 14:59:36.918 Parsed PMT(0x0x8169618) Program Map Table ver(27) pid(0x401) pnum(769) Stream #0 pid(0xa1) type(video-mpeg2 0x2) ISO-639 Language Descriptor (0xa) length(4) Stream Identifier Descriptor (0x52) length(1) Stream #1 pid(0x51) type(audio-mp1-layer[1,2,3] 0x3) ISO-639 Language Descriptor (0xa) length(4) Stream Identifier Descriptor (0x52) length(1) Stream #2 pid(0x29) type(private-data 0x6) ISO-639 Language Descriptor (0xa) length(4) Stream Identifier Descriptor (0x52) length(1) Teletext Descriptor (0x56) length(5) Stream #3 pid(0x384) type(dsmcc-b std data 0x11) Stream Identifier Descriptor (0x52) length(1) Unknown Descriptor (0x13) length(25) Unknown Descriptor (0x14) length(13) Data Broadcast Identifier Descriptor (0x66) length(2) Stream #4 pid(0x1f4) type(private-sec 0x5) Stream Identifier Descriptor (0x52) length(1) Application Signalling Descriptor (0x6f) length(3) 2005-11-24 14:59:36.920 Parsed PMT(0x0x8169668) Program Map Table ver(0) pid(0x0) pnum(0) 2005-11-24 14:59:36.920 PMT for output stream 2005-11-24 14:59:36.920 Program Map Table ver(27) pid(0x401) pnum(1) Stream #0 pid(0xa1) type(video-mpeg2 0x2) Stream #1 pid(0x51) type(audio-mp1-layer[1,2,3] 0x3) 2005-11-24 14:59:36.963 TVRec(1): Got good signal 2005-11-24 14:59:36.964 TVRec(1): MPEG program num(769) 2005-11-24 14:59:36.964 TVRec(1): TeardownSignalMonitor() -- begin 2005-11-24 14:59:36.964 DVBSM(0)::Stop: begin 2005-11-24 14:59:36.964 SM(0)::Stop: begin 2005-11-24 14:59:37.019 SM(0)::Stop: end 2005-11-24 14:59:37.031 DVBSM(0): RunTableMonitorTS(): shutdown 2005-11-24 14:59:37.032 DVBSM(0)::RemovePIDFilter(0x0): 2005-11-24 14:59:37.032 DVBSM(0)::RemovePIDFilter(0x401): 2005-11-24 14:59:37.032 DVBSM(0)::RemovePIDFilter(0x1ffb): 2005-11-24 14:59:37.343 DVBSM(0): RunTableMonitorTS(): end 2005-11-24 14:59:37.343 DVBSM(0)::Stop: end 2005-11-24 14:59:37.344 DVBSM(0)::Stop: begin 2005-11-24 14:59:37.344 SM(0)::Stop: begin 2005-11-24 14:59:37.344 SM(0)::Stop: end 2005-11-24 14:59:37.344 DVBSM(0)::Stop: end 2005-11-24 14:59:37.344 DVBSM(0)::Stop: begin 2005-11-24 14:59:37.344 SM(0)::Stop: begin 2005-11-24 14:59:37.345 SM(0)::Stop: end 2005-11-24 14:59:37.345 DVBSM(0)::Stop: end 2005-11-24 14:59:37.345 TVRec(1): TeardownSignalMonitor() -- end 2005-11-24 14:59:37.345 TVRec(1): ClearFlags(WaitingForSignal,SignalMonitorRunning,) -> RunMainLoop,AskAllowRecording,NeedToStartRecorder, 2005-11-24 14:59:37.345 TVRec(1): prog_num(769) vs. dvbc->srv_id(769) 2005-11-24 14:59:37.346 SIParser: About to do a reset 2005-11-24 14:59:37.347 SIParser: Closing all PIDs 2005-11-24 14:59:37.347 SIParser: Using DelAllPids from SIParser which does nothing 2005-11-24 14:59:37.347 SIParser: Resetting all Table Handlers 2005-11-24 14:59:37.347 SIParser: SIParser Reset due to channel change 2005-11-24 14:59:37.349 DVBSM(0)::Stop: begin 2005-11-24 14:59:37.349 SM(0)::Stop: begin 2005-11-24 14:59:37.349 SM(0)::Stop: end 2005-11-24 14:59:37.350 DVBSM(0)::Stop: end 2005-11-24 14:59:37.350 SM(0)::Stop: begin 2005-11-24 14:59:37.350 SM(0)::Stop: end 2005-11-24 14:59:37.356 SIParser: Added initial preferred language 'eng' with priority 1 2005-11-24 14:59:37.356 SIParser: Added initial preferred language 'eng' with priority 2 2005-11-24 14:59:37.356 DVB#0 DVB SI Table Parser Started 2005-11-24 14:59:37.357 SIParser: ReinitSIParser(std dvb, service #769) 2005-11-24 14:59:37.357 FillPMap(str dvb) 2005-11-24 14:59:37.357 FillPMap(SIS dvb) 2005-11-24 14:59:37.357 SIParser: Requesting PAT 2005-11-24 14:59:37.358 SIParser: Adding the Service with ID 769 in the SDT to the request list 2005-11-24 14:59:37.358 TVRec(1): SetFlags(WaitingForSIParser,SIParserRunning,) -> RunMainLoop,AskAllowRecording,WaitingForSIParser,NeedToStartRecorder,SIParserRunning, 2005-11-24 14:59:37.358 SIParser: Starting DVB Section Reader thread 2005-11-24 14:59:37.359 SIParser: Table[0]->RequirePIDs() == true 2005-11-24 14:59:37.359 SIParser: Adding PID 0x 0 Filter 0x 0 Mask 0xff Buffer 40960 2005-11-24 14:59:37.364 SIParser: Table[2]->RequirePIDs() == true 2005-11-24 14:59:37.365 SIParser: Adding PID 0x1ffb Filter 0xff Mask 0x 0 Buffer 40960 2005-11-24 14:59:37.365 SIParser: Table[3]->RequirePIDs() == true 2005-11-24 14:59:37.366 SIParser: Adding PID 0x1ffb Filter 0xff Mask 0x 0 Buffer 40960 2005-11-24 14:59:37.366 SIParser: Table[6]->RequirePIDs() == true 2005-11-24 14:59:37.366 SIParser: Adding PID 0x 10 Filter 0x40 Mask 0xff Buffer 40960 2005-11-24 14:59:37.503 SIParser: PAT Version = 26 2005-11-24 14:59:37.503 SIParser: Tuned to TransportID: 768 2005-11-24 14:59:37.503 SIParser: NIT Present on this transport on PID 0x10 2005-11-24 14:59:37.504 SIParser: PMT #768 on PID 0x1024 2005-11-24 14:59:37.504 SIParser: PMT #769 on PID 0x1025 2005-11-24 14:59:37.504 SIParser: PMT #770 on PID 0x1026 2005-11-24 14:59:37.504 SIParser: PMT #772 on PID 0x1032 2005-11-24 14:59:37.504 SIParser: PMT #782 on PID 0x1027 2005-11-24 14:59:37.505 SIParser: PMT #783 on PID 0x1028 2005-11-24 14:59:37.505 SIParser: Services on this Transport: 768 769 770 772 782 783 2005-11-24 14:59:37.505 SIParser: Table[0]->Complete() == true 2005-11-24 14:59:37.505 SIParser: Table[1]->RequirePIDs() == true 2005-11-24 14:59:37.506 SIParser: Adding PID 0x 401 Filter 0x 2 Mask 0xff Buffer 40960 2005-11-24 14:59:37.622 SIParser: PMT ServiceID: 769 Version = 23 2005-11-24 14:59:37.623 SIParser: PID: 161 2005-11-24 14:59:37.623 SIParser: Unused Descriptor: Stream Identifier Descriptor (0x52) length(1) 2005-11-24 14:59:37.623 SIParser: PID: 81 2005-11-24 14:59:37.623 SIParser: Unused Descriptor: Stream Identifier Descriptor (0x52) length(1) 2005-11-24 14:59:37.624 SIParser: PID: 41 2005-11-24 14:59:37.624 SIParser: Unused Descriptor: Stream Identifier Descriptor (0x52) length(1) 2005-11-24 14:59:37.624 SIParser: Teletext Descriptor 2005-11-24 14:59:37.624 SIParser: lang: eng, type: 2, mag: 0, page: 1 2005-11-24 14:59:37.625 SIParser: PID: 900 2005-11-24 14:59:37.625 SIParser: Unused Descriptor: Stream Identifier Descriptor (0x52) length(1) 2005-11-24 14:59:37.625 SIParser: Unused Descriptor: Unknown Descriptor (0x13) length(25) 2005-11-24 14:59:37.625 SIParser: Unused Descriptor: Unknown Descriptor (0x14) length(13) 2005-11-24 14:59:37.625 SIParser: Unused Descriptor: Data Broadcast Identifier Descriptor (0x66) length(2) 2005-11-24 14:59:37.626 SIParser: PID: 500 2005-11-24 14:59:37.626 SIParser: Unused Descriptor: Stream Identifier Descriptor (0x52) length(1) 2005-11-24 14:59:37.626 SIParser: Unused Descriptor: Application Signalling Descriptor (0x6f) length(3) 2005-11-24 14:59:37.626 SIParser: Table[1]->EmitRequired() == true 2005-11-24 14:59:37.627 DVB#0 SetPMT ServiceID=769, PCRPID=161 (0xa1) 2005-11-24 14:59:37.627 TVRec(1): Got SIParser PMT 2005-11-24 14:59:37.627 TVRec(1): ClearFlags(WaitingForSIParser,) -> RunMainLoop,AskAllowRecording,NeedToStartRecorder,SIParserRunning, 2005-11-24 14:59:37.628 TVRec(1): Starting Recorder 2005-11-24 14:59:37.633 TVRec(1): Using profile 'Default' to record 2005-11-24 14:59:37.696 RecBase(0): SetRingBuffer(0x824c788) '/var/media/video/1003_20051124150000.mpg' 2005-11-24 14:59:37.696 RecBase(0): SetRecording(0x814a748) title(Italian News) 2005-11-24 14:59:37.699 DVBRec(0): Card opened successfully (using TS mode). 2005-11-24 14:59:37.700 DVBRec(0): SetPMTObject() 2005-11-24 14:59:37.700 DVBRec(0): AutoPID for MPEG Program Number(769), PCR PID(0xa1) 2005-11-24 14:59:37.701 DVBRec(0): AutoPID selecting PID 0xa1, MPEG-2 Video (eng 2005-11-24 14:59:37.701 DVBRec(0): AutoPID selecting PID 0x51, MPEG-1 Audio (eng 2005-11-24 14:59:37.701 DVBRec(0): AutoPID selecting PID 0x29, Teletext (eng 2005-11-24 14:59:37.702 DVBRec(0): AutoPID skipping PID 0x384, Data 2005-11-24 14:59:37.702 DVBRec(0): AutoPID skipping PID 0x1f4, Unknown type: 5 2005-11-24 14:59:37.702 DVBRec(0): AutoPID Complete - PAT/PMT Loaded for service 2005-11-24 14:59:37.703 DVBRec(0): A/V Stream is unencrypted 2005-11-24 14:59:37.703 DVBRec(0): Resetting Demux Filters 2005-11-24 14:59:37.703 DVBRec(0): Adding pid 0xa1 size(3600384) 2005-11-24 14:59:37.704 DVBRec(0): Set demux buffer size for pid 0xa1 to 3600384, which gives us a 1500 msec buffer. 2005-11-24 14:59:37.705 DVBRec(0): Adding pid 0x51 size(94208) 2005-11-24 14:59:37.705 DVBRec(0): Set demux buffer size for pid 0x51 to 94208, which gives us a 1500 msec buffer. 2005-11-24 14:59:37.706 DVBRec(0): Adding pid 0x29 size(94208) 2005-11-24 14:59:37.706 DVBRec(0): Set demux buffer size for pid 0x29 to 94208, which gives us a 1500 msec buffer. 2005-11-24 14:59:37.707 TVRec(1): SetFlags(RecorderRunning,) -> RunMainLoop,AskAllowRecording,NeedToStartRecorder,SIParserRunning,RecorderRunning, 2005-11-24 14:59:37.708 TVRec(1): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,AskAllowRecording,SIParserRunning,RecorderRunning, 2005-11-24 14:59:37.707 Parsed PMT(0x0x818ff28) Program Map Table ver(16) pid(0x20) pnum(1) Stream #0 pid(0xa1) type(video-mpeg2 0x2) ISO-639 Language Descriptor (0xa) length(4) Stream Identifier Descriptor (0x52) length(1) Stream #1 pid(0x51) type(audio-mp1-layer[1,2,3] 0x3) ISO-639 Language Descriptor (0xa) length(4) Stream Identifier Descriptor (0x52) length(1) Stream #2 pid(0x29) type(private-data 0x6) ISO-639 Language Descriptor (0xa) length(4) Stream Identifier Descriptor (0x52) length(1) Teletext Descriptor (0x56) length(5) 2005-11-24 14:59:37.712 DVB#0 Data read from DMX - This is for debugging with transform.c 2005-11-24 14:59:38.557 DVBRec(0): Found first payload start for PID 0xa1 2005-11-24 14:59:38.577 DVBRec(0): Found first payload start for PID 0x29 2005-11-24 14:59:38.627 DVBRec(0): Found first payload start for PID 0x51 2005-11-24 14:59:40.500 DVBRec(0) Warning: ReadFromDMX took 23 ms 2005-11-24 14:59:48.427 SIParser: Private Type channel_numbers = 131 defined for NetworkID 12802 2005-11-24 14:59:48.427 SIParser: ChannelNumbers Present using Descriptor 131 2005-11-24 14:59:48.430 SIParser: Table[6]->Complete() == true 2005-11-24 14:59:48.430 SIParser: Table[5]->RequirePIDs() == true 2005-11-24 14:59:48.430 SIParser: Adding PID 0x 11 Filter 0x46 Mask 0xfb Buffer 40960 2005-11-24 14:59:50.634 SIParser: SDT: NetworkID=12802 TransportID=768 2005-11-24 14:59:50.634 SIParser: SDT: sid=768 type=1 eit_present=0 eit_requested=0 name=SBS HD 2005-11-24 14:59:50.634 SIParser: Unused Descriptor: Data Broadcast Descriptor (0x64) length(24) 2005-11-24 14:59:50.635 SIParser: SDT: sid=769 type=1 eit_present=0 eit_requested=0 name=SBS DIGITAL 1 2005-11-24 14:59:50.635 SIParser: SDT: sid=770 type=1 eit_present=0 eit_requested=0 name=SBS DIGITAL 2 2005-11-24 14:59:50.635 SIParser: SDT: sid=772 type=1 eit_present=0 eit_requested=0 name=SBS EPG 2005-11-24 14:59:50.636 SIParser: SDT: sid=782 type=2 eit_present=0 eit_requested=0 name=SBS RADIO 1 2005-11-24 14:59:50.636 SIParser: SDT: sid=783 type=2 eit_present=0 eit_requested=0 name=SBS RADIO 2 2005-11-24 14:59:50.636 SIParser: Table[4]->RequirePIDs() == true 2005-11-24 14:59:50.637 SIParser: Adding PID 0x 12 Filter 0x 0 Mask 0x 0 Buffer 4096000 2005-11-24 14:59:55.714 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 14:59:55.714 1003 @ Thu Nov 24 15:00:00 2005 2005-11-24 15:00:04.444 DVBRec(0) Warning: ReadFromDMX took 43 ms 2005-11-24 15:00:18.869 DVBRec(0) Warning: ReadFromDMX took 65 ms 2005-11-24 15:00:33.242 DVBRec(0) Warning: ReadFromDMX took 44 ms 2005-11-24 15:00:47.408 DVBRec(0) Warning: ReadFromDMX took 45 ms 2005-11-24 15:00:55.767 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:00:55.768 1003 @ Thu Nov 24 15:00:00 2005 2005-11-24 15:00:55.771 AutoExpire: SendDeleteMessages, cycling through expire list. 2005-11-24 15:00:55.773 AutoExpire: Checking episode count for each recording profile using max episodes 2005-11-24 15:01:01.734 DVBRec(0) Warning: ReadFromDMX took 46 ms 2005-11-24 15:01:16.124 DVBRec(0) Warning: ReadFromDMX took 42 ms 2005-11-24 15:01:30.452 DVBRec(0) Warning: ReadFromDMX took 46 ms 2005-11-24 15:01:44.855 DVBRec(0) Warning: ReadFromDMX took 45 ms 2005-11-24 15:01:55.828 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:01:55.828 1003 @ Thu Nov 24 15:00:00 2005 2005-11-24 15:01:59.078 DVBRec(0) Warning: ReadFromDMX took 43 ms 2005-11-24 15:02:13.493 DVBRec(0) Warning: ReadFromDMX took 45 ms 2005-11-24 15:02:27.888 DVBRec(0) Warning: ReadFromDMX took 45 ms 2005-11-24 15:02:42.123 DVBRec(0) Warning: ReadFromDMX took 46 ms 2005-11-24 15:02:55.881 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:02:55.882 1003 @ Thu Nov 24 15:00:00 2005 2005-11-24 15:02:55.885 AutoExpire: SendDeleteMessages, cycling through expire list. 2005-11-24 15:02:56.526 DVBRec(0) Warning: ReadFromDMX took 45 ms 2005-11-24 15:03:10.849 DVBRec(0) Warning: ReadFromDMX took 43 ms 2005-11-24 15:03:25.243 DVBRec(0) Warning: ReadFromDMX took 43 ms 2005-11-24 15:03:39.160 DVBRec(0) Warning: ReadFromDMX took 44 ms 2005-11-24 15:03:53.247 DVBRec(0) Warning: ReadFromDMX took 45 ms 2005-11-24 15:03:55.939 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:03:55.939 1003 @ Thu Nov 24 15:00:00 2005 2005-11-24 15:04:07.641 DVBRec(0) Warning: ReadFromDMX took 45 ms 2005-11-24 15:04:22.051 DVBRec(0) Warning: ReadFromDMX took 53 ms 2005-11-24 15:04:36.387 DVBRec(0) Warning: ReadFromDMX took 43 ms 2005-11-24 15:04:50.524 DVBRec(0) Warning: ReadFromDMX took 45 ms 2005-11-24 15:04:55.991 AutoExpire: Adding Programs to 'Do Not Expire' List 2005-11-24 15:04:55.992 1003 @ Thu Nov 24 15:00:00 2005 2005-11-24 15:04:55.995 AutoExpire: SendDeleteMessages, cycling through expire list. 2005-11-24 15:05:00.518 TVRec(1): ClearFlags(FinishRecording,) -> RunMainLoop,AskAllowRecording,SIParserRunning,RecorderRunning, 2005-11-24 15:05:00.519 TVRec(1): Changing from RecordingOnly to None 2005-11-24 15:05:00.519 TVRec(1): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,AskAllowRecording,SIParserRunning,RecorderRunning, 2005-11-24 15:05:00.519 TVRec(1): SetFlags(AskAllowRecording,) -> RunMainLoop,AskAllowRecording,SIParserRunning,RecorderRunning, 2005-11-24 15:05:00.519 TVRec(1): Request: Program(no) channel() input() flags(CloseRec,KillRingBuffer,) 2005-11-24 15:05:00.520 SIParser: Stopping DVB Section Reader 2005-11-24 15:05:00.546 SIParser: DVB Section Reader thread stopped 2005-11-24 15:05:00.548 TVRec(1): ClearFlags(SIParserRunning,) -> RunMainLoop,AskAllowRecording,RecorderRunning, 2005-11-24 15:05:00.548 DVBRec(0): Close() fd(23) -- begin 2005-11-24 15:05:00.865 DVBRec(0): Close() fd(-1) -- end 2005-11-24 15:05:00.870 Reschedule requested for id 0. 2005-11-24 15:05:00.879 Finished recording Italian News: channel 1003 2005-11-24 15:05:00.942 DVBRec(0): Close() fd(-1) -- begin 2005-11-24 15:05:01.030 TVRec(1): ClearFlags(RecorderRunning,) -> RunMainLoop,AskAllowRecording, 2005-11-24 15:05:01.051 TVRec(1): ClearFlags(RecorderRunning,) -> RunMainLoop,AskAllowRecording, 2005-11-24 15:05:01.052 TVRec(1): Tearing down RingBuffer 2005-11-24 15:05:01.055 TVRec(1): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,AskAllowRecording, 2005-11-24 15:05:01.107 Scheduled 19 items in 0.2 = 0.00 match + 0.24 place 0: start_time: 3063.107 duration: 28.944 1: start_time: 3063.077 duration: 28.944 stream: start_time: 34034.194 duration: 321.934 bitrate=4265 kb/s 2005-11-24 15:05:01.191 AFD: Opened codec 0x81946d0, id(MPEG2VIDEO) type(Video) 2005-11-24 15:05:01.226 AFD: Opened codec 0x8194fd0, id(MP3) type(Audio)