Ticket #706: backend.log.success.txt

File backend.log.success.txt, 26.0 KB (added by Jon Whitear, 20 years ago)

Backend log (successful recording from SBS)

Line 
12005-11-24 14:58:35.382 Using runtime prefix = /usr/local
22005-11-24 14:58:35.489 New DB connection, total: 1
32005-11-24 14:58:35.494 Enabling Settings Cache.
42005-11-24 14:58:35.494 Clearing Settings Cache.
52005-11-24 14:58:35.498 Current Schema Version: 1109
6Starting up as the master server.
72005-11-24 14:58:35.516 New DB connection, total: 2
82005-11-24 14:58:35.540 DVB#0 Opening DVB channel
92005-11-24 14:58:35.540 DVB#0 Using DVB card 0, with frontend 'DViCO FusionHDTV DVB-T Plus'.
102005-11-24 14:58:35.541 DVB#0 SetChannelByString(21)
112005-11-24 14:58:35.544 New DB connection, total: 3
122005-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
132005-11-24 14:58:35.549 DVB#0 Old Params: freq(0) type(OFDM)
142005-11-24 14:58:35.549 DVB#0 New Params: freq(226500000) type(OFDM)
152005-11-24 14:58:35.549 dvbchannel.cpp:wait_for_backend: Status:
162005-11-24 14:58:35.550 DVB#0 DVBChannel::Tune: Frequency tuning successful.
172005-11-24 14:58:35.550 DVB#0 Tuned to frequency for channel 21.
182005-11-24 14:58:35.589 TVRec(1): SetFlags(RunMainLoop,) -> RunMainLoop,
192005-11-24 14:58:35.590 TVRec(1): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop,
202005-11-24 14:58:35.594 DVB#1 Opening DVB channel
212005-11-24 14:58:35.594 DVB#1 Using DVB card 1, with frontend 'DST DVB-T'.
222005-11-24 14:58:35.595 DVB#1 SetChannelByString(3)
232005-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
242005-11-24 14:58:35.599 DVB#1 Old Params: freq(0) type(OFDM)
252005-11-24 14:58:35.599 DVB#1 New Params: freq(571500000) type(OFDM)
262005-11-24 14:58:35.599 dvbchannel.cpp:wait_for_backend: Status:
272005-11-24 14:58:35.600 DVB#1 DVBChannel::Tune: Frequency tuning successful.
282005-11-24 14:58:35.600 DVB#1 Tuned to frequency for channel 3.
292005-11-24 14:58:35.607 TVRec(2): SetFlags(RunMainLoop,) -> RunMainLoop,
302005-11-24 14:58:35.608 TVRec(2): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop,
312005-11-24 14:58:35.610 New DB scheduler connection
322005-11-24 14:58:35.642 mythbackend version: 0.19.20051122-1 www.mythtv.org
332005-11-24 14:58:35.643 Enabled verbose msgs : important general record file channel siparser
342005-11-24 14:58:35.643 AutoExpire: Found 2 recorders w/max rate of 277 MiB/min
352005-11-24 14:58:35.646 AutoExpire: Required Free Space: 2.2 GB w/freq: 5 min
362005-11-24 14:58:37.638 Reschedule requested for id -1.
372005-11-24 14:58:37.861 Scheduled 22 items in 0.2 = 0.03 match + 0.19 place
382005-11-24 14:58:37.870 Recording starts soon, AUTO-Startup assumed
392005-11-24 14:58:44.407 MainServer::HandleAnnounce Monitor
402005-11-24 14:58:44.407 adding: epia as a client (events: 0)
412005-11-24 14:58:44.415 MainServer::HandleAnnounce Monitor
422005-11-24 14:58:44.415 adding: epia as a client (events: 1)
432005-11-24 14:58:51.035 MainServer::HandleAnnounce Playback
442005-11-24 14:58:51.036 adding: server as a client (events: 0)
452005-11-24 14:58:55.660 AutoExpire: SendDeleteMessages, cycling through expire list.
462005-11-24 14:59:24.622 MainServer::HandleAnnounce Playback
472005-11-24 14:59:24.622 adding: server as a client (events: 0)
482005-11-24 14:59:29.915 MainServer::HandleAnnounce Playback
492005-11-24 14:59:29.915 adding: server as a client (events: 0)
502005-11-24 14:59:35.779 MainServer::HandleAnnounce Playback
512005-11-24 14:59:35.779 adding: server as a client (events: 0)
522005-11-24 14:59:35.956 Reschedule requested for id 173.
532005-11-24 14:59:36.152 Scheduled 23 items in 0.2 = 0.03 match + 0.17 place
542005-11-24 14:59:36.156 TVRec(1): ClearFlags(AskAllowRecording,) -> RunMainLoop,
552005-11-24 14:59:36.213 DB Error (Copy program data on record):
56Query was:
57REPLACE INTO recordedprogram SELECT * from program WHERE chanid = '1003' AND starttime = '2005-11-24T14:30:00';
58Driver error was [2/1136]:
59QMYSQL3: Unable to execute query
60Database error was:
61Column count doesn't match value count at row 1
62
632005-11-24 14:59:36.216 TVRec(1): StartedRecording(0x814a748) fn(/var/media/video/1003_20051124150000.mpg)
642005-11-24 14:59:36.217 TVRec(1): ClearFlags(CancelNextRecording,) -> RunMainLoop,
652005-11-24 14:59:36.218 TVRec(1): Changing from None to RecordingOnly
662005-11-24 14:59:36.218 TVRec(1): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,
672005-11-24 14:59:36.219 TVRec(1): SetFlags(AskAllowRecording,) -> RunMainLoop,AskAllowRecording,
682005-11-24 14:59:36.219 TVRec(1): Request: Program(yes) channel() input() flags(Recording,)
692005-11-24 14:59:36.219 TVRec(1): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,AskAllowRecording,
702005-11-24 14:59:36.221 DVB#0 Opening DVB channel
712005-11-24 14:59:36.222 DVB#0 SetChannelByString(3)
722005-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
732005-11-24 14:59:36.226 DVB#0 Old Params: freq(226500000) type(OFDM)
742005-11-24 14:59:36.227 DVB#0 New Params: freq(571500000) type(OFDM)
752005-11-24 14:59:36.227 dvbchannel.cpp:wait_for_backend: Status:
762005-11-24 14:59:36.227 DVB#0 DVBChannel::Tune: Frequency tuning successful.
772005-11-24 14:59:36.227 DVB#0 Tuned to frequency for channel 3.
782005-11-24 14:59:36.227 TVRec(1): Starting Signal Monitor
792005-11-24 14:59:36.228 TVRec(1): SetupSignalMonitor(1, 0)
802005-11-24 14:59:36.228 DVB#0 Opening DVB channel
812005-11-24 14:59:36.272 SM(0)::AddFlags: Seen() Match() Wait(Sig,SNR,BER,UB,)
822005-11-24 14:59:36.272 DVBSM(0)::constructor(): initial flags 0x7400000
832005-11-24 14:59:36.272 TVRec(1): Signal monitor successfully created
842005-11-24 14:59:36.272 TVRec(1): Setting up table monitoring.
852005-11-24 14:59:36.273 TVRec(1): MPEG program number: 769
862005-11-24 14:59:36.275 DTVSM(0)::SetProgramNumber(769):
872005-11-24 14:59:36.276 SM(0)::RemoveFlags: Seen(PMT,) Match(PMT,) Wait()
882005-11-24 14:59:36.276 SM(0)::AddFlags: Seen() Match() Wait(PMT,)
892005-11-24 14:59:36.276 SM(0)::AddFlags: Seen() Match() Wait(PAT,PMT,)
902005-11-24 14:59:36.276 TVRec(1): Successfully set up MPEG table monitoring.
912005-11-24 14:59:36.277 SM(0)::Start: begin
922005-11-24 14:59:36.284 SM(0)::Start: end
932005-11-24 14:59:36.284 TVRec(1): SetFlags(SignalMonitorRunning,) -> RunMainLoop,AskAllowRecording,SignalMonitorRunning,
942005-11-24 14:59:36.284 TVRec(1): SetFlags(WaitingForSignal,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,SignalMonitorRunning,
952005-11-24 14:59:36.284 TVRec(1): SetFlags(NeedToStartRecorder,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,
962005-11-24 14:59:36.300 Started recording: Italian News: channel 1003 on cardid 1, sourceid 1
972005-11-24 14:59:36.458 DVBSM(0)::UpdateValues: Signal Locked
982005-11-24 14:59:36.459 DVBSM(0)::AddPIDFilter(0x0):
992005-11-24 14:59:36.459 DVBSM(0)::UpdateValues: Waiting for table monitor to start
1002005-11-24 14:59:36.460 DVBSM(0)::UpdateValues: Table monitor started
1012005-11-24 14:59:36.494 DVBSM(0): Will use TS table monitoring
1022005-11-24 14:59:36.494 DVBSM(0)::RemovePIDFilter(0x0):
1032005-11-24 14:59:36.828 DVBSM(0): RunTableMonitorTS(): begin (# of pids 2)
1042005-11-24 14:59:36.829 DVBSM(0)::AddPIDFilter(0x0):
1052005-11-24 14:59:36.834 DVBSM(0)::AddPIDFilter(0x1ffb):
1062005-11-24 14:59:36.884 SM(0)::AddFlags: Seen(PAT,) Match() Wait()
1072005-11-24 14:59:36.884 SM(0)::AddFlags: Seen() Match(PAT,) Wait()
1082005-11-24 14:59:36.885 CreatePATSingleProgram()
1092005-11-24 14:59:36.885 PAT in input stream
1102005-11-24 14:59:36.885 Program Association Table
111 PSIP prefix(0x0) tableID(0x0) length(37) extension(0x300)
112 version(29) current(1) section(0) last_section(0)
113 tsid: 768
114 programCount: 7
115 program number 0 has PID 0x 10 data 0x0 0x0 0x224 0x16
116 program number 768 has PID 0x 400 data 0x3 0x0 0x228 0x0
117 program number 769 has PID 0x 401 data 0x3 0x1 0x228 0x1
118 program number 770 has PID 0x 402 data 0x3 0x2 0x228 0x2
119 program number 772 has PID 0x 408 data 0x3 0x4 0x228 0x8
120 program number 782 has PID 0x 403 data 0x3 0x14 0x228 0x3
121 program number 783 has PID 0x 404 data 0x3 0x15 0x228 0x4
122
1232005-11-24 14:59:36.886 desired_program(769) pid(0x401)
1242005-11-24 14:59:36.886 pmt_pid(0x401)
1252005-11-24 14:59:36.886 PAT for output stream
1262005-11-24 14:59:36.887 Program Association Table
127 PSIP prefix(0x0) tableID(0x0) length(13) extension(0x300)
128 version(29) current(1) section(0) last_section(0)
129 tsid: 768
130 programCount: 1
131 program number 1 has PID 0x 401 data 0x0 0x1 0x228 0x1
132
1332005-11-24 14:59:36.887 DVBSM(0)::AddPIDFilter(0x401):
1342005-11-24 14:59:36.914 Parsed PMT(0x0x8169618) Program Map Table ver(27) pid(0x401) pnum(769)
135
136 Stream #0 pid(0xa1) type(video-mpeg2 0x2)
137 ISO-639 Language Descriptor (0xa) length(4)
138 Stream Identifier Descriptor (0x52) length(1)
139 Stream #1 pid(0x51) type(audio-mp1-layer[1,2,3] 0x3)
140 ISO-639 Language Descriptor (0xa) length(4)
141 Stream Identifier Descriptor (0x52) length(1)
142 Stream #2 pid(0x29) type(private-data 0x6)
143 ISO-639 Language Descriptor (0xa) length(4)
144 Stream Identifier Descriptor (0x52) length(1)
145 Teletext Descriptor (0x56) length(5)
146 Stream #3 pid(0x384) type(dsmcc-b std data 0x11)
147 Stream Identifier Descriptor (0x52) length(1)
148 Unknown Descriptor (0x13) length(25)
149 Unknown Descriptor (0x14) length(13)
150 Data Broadcast Identifier Descriptor (0x66) length(2)
151 Stream #4 pid(0x1f4) type(private-sec 0x5)
152 Stream Identifier Descriptor (0x52) length(1)
153 Application Signalling Descriptor (0x6f) length(3)
154
1552005-11-24 14:59:36.916 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
1562005-11-24 14:59:36.916 SM(0)::AddFlags: Seen() Match(PMT,) Wait()
1572005-11-24 14:59:36.916 CreatePMTSingleProgram()
1582005-11-24 14:59:36.916 PMT in input stream
1592005-11-24 14:59:36.916 Program Map Table ver(27) pid(0x401) pnum(769)
160
161 Stream #0 pid(0xa1) type(video-mpeg2 0x2)
162 ISO-639 Language Descriptor (0xa) length(4)
163 Stream Identifier Descriptor (0x52) length(1)
164 Stream #1 pid(0x51) type(audio-mp1-layer[1,2,3] 0x3)
165 ISO-639 Language Descriptor (0xa) length(4)
166 Stream Identifier Descriptor (0x52) length(1)
167 Stream #2 pid(0x29) type(private-data 0x6)
168 ISO-639 Language Descriptor (0xa) length(4)
169 Stream Identifier Descriptor (0x52) length(1)
170 Teletext Descriptor (0x56) length(5)
171 Stream #3 pid(0x384) type(dsmcc-b std data 0x11)
172 Stream Identifier Descriptor (0x52) length(1)
173 Unknown Descriptor (0x13) length(25)
174 Unknown Descriptor (0x14) length(13)
175 Data Broadcast Identifier Descriptor (0x66) length(2)
176 Stream #4 pid(0x1f4) type(private-sec 0x5)
177 Stream Identifier Descriptor (0x52) length(1)
178 Application Signalling Descriptor (0x6f) length(3)
179
1802005-11-24 14:59:36.918 Parsed PMT(0x0x8169618) Program Map Table ver(27) pid(0x401) pnum(769)
181
182 Stream #0 pid(0xa1) type(video-mpeg2 0x2)
183 ISO-639 Language Descriptor (0xa) length(4)
184 Stream Identifier Descriptor (0x52) length(1)
185 Stream #1 pid(0x51) type(audio-mp1-layer[1,2,3] 0x3)
186 ISO-639 Language Descriptor (0xa) length(4)
187 Stream Identifier Descriptor (0x52) length(1)
188 Stream #2 pid(0x29) type(private-data 0x6)
189 ISO-639 Language Descriptor (0xa) length(4)
190 Stream Identifier Descriptor (0x52) length(1)
191 Teletext Descriptor (0x56) length(5)
192 Stream #3 pid(0x384) type(dsmcc-b std data 0x11)
193 Stream Identifier Descriptor (0x52) length(1)
194 Unknown Descriptor (0x13) length(25)
195 Unknown Descriptor (0x14) length(13)
196 Data Broadcast Identifier Descriptor (0x66) length(2)
197 Stream #4 pid(0x1f4) type(private-sec 0x5)
198 Stream Identifier Descriptor (0x52) length(1)
199 Application Signalling Descriptor (0x6f) length(3)
200
2012005-11-24 14:59:36.920 Parsed PMT(0x0x8169668) Program Map Table ver(0) pid(0x0) pnum(0)
2022005-11-24 14:59:36.920 PMT for output stream
2032005-11-24 14:59:36.920 Program Map Table ver(27) pid(0x401) pnum(1)
204
205 Stream #0 pid(0xa1) type(video-mpeg2 0x2)
206 Stream #1 pid(0x51) type(audio-mp1-layer[1,2,3] 0x3)
207
2082005-11-24 14:59:36.963 TVRec(1): Got good signal
2092005-11-24 14:59:36.964 TVRec(1): MPEG program num(769)
2102005-11-24 14:59:36.964 TVRec(1): TeardownSignalMonitor() -- begin
2112005-11-24 14:59:36.964 DVBSM(0)::Stop: begin
2122005-11-24 14:59:36.964 SM(0)::Stop: begin
2132005-11-24 14:59:37.019 SM(0)::Stop: end
2142005-11-24 14:59:37.031 DVBSM(0): RunTableMonitorTS(): shutdown
2152005-11-24 14:59:37.032 DVBSM(0)::RemovePIDFilter(0x0):
2162005-11-24 14:59:37.032 DVBSM(0)::RemovePIDFilter(0x401):
2172005-11-24 14:59:37.032 DVBSM(0)::RemovePIDFilter(0x1ffb):
2182005-11-24 14:59:37.343 DVBSM(0): RunTableMonitorTS(): end
2192005-11-24 14:59:37.343 DVBSM(0)::Stop: end
2202005-11-24 14:59:37.344 DVBSM(0)::Stop: begin
2212005-11-24 14:59:37.344 SM(0)::Stop: begin
2222005-11-24 14:59:37.344 SM(0)::Stop: end
2232005-11-24 14:59:37.344 DVBSM(0)::Stop: end
2242005-11-24 14:59:37.344 DVBSM(0)::Stop: begin
2252005-11-24 14:59:37.344 SM(0)::Stop: begin
2262005-11-24 14:59:37.345 SM(0)::Stop: end
2272005-11-24 14:59:37.345 DVBSM(0)::Stop: end
2282005-11-24 14:59:37.345 TVRec(1): TeardownSignalMonitor() -- end
2292005-11-24 14:59:37.345 TVRec(1): ClearFlags(WaitingForSignal,SignalMonitorRunning,) -> RunMainLoop,AskAllowRecording,NeedToStartRecorder,
2302005-11-24 14:59:37.345 TVRec(1): prog_num(769) vs. dvbc->srv_id(769)
2312005-11-24 14:59:37.346 SIParser: About to do a reset
2322005-11-24 14:59:37.347 SIParser: Closing all PIDs
2332005-11-24 14:59:37.347 SIParser: Using DelAllPids from SIParser which does nothing
2342005-11-24 14:59:37.347 SIParser: Resetting all Table Handlers
2352005-11-24 14:59:37.347 SIParser: SIParser Reset due to channel change
2362005-11-24 14:59:37.349 DVBSM(0)::Stop: begin
2372005-11-24 14:59:37.349 SM(0)::Stop: begin
2382005-11-24 14:59:37.349 SM(0)::Stop: end
2392005-11-24 14:59:37.350 DVBSM(0)::Stop: end
2402005-11-24 14:59:37.350 SM(0)::Stop: begin
2412005-11-24 14:59:37.350 SM(0)::Stop: end
2422005-11-24 14:59:37.356 SIParser: Added initial preferred language 'eng' with priority 1
2432005-11-24 14:59:37.356 SIParser: Added initial preferred language 'eng' with priority 2
2442005-11-24 14:59:37.356 DVB#0 DVB SI Table Parser Started
2452005-11-24 14:59:37.357 SIParser: ReinitSIParser(std dvb, service #769)
2462005-11-24 14:59:37.357 FillPMap(str dvb)
2472005-11-24 14:59:37.357 FillPMap(SIS dvb)
2482005-11-24 14:59:37.357 SIParser: Requesting PAT
2492005-11-24 14:59:37.358 SIParser: Adding the Service with ID 769 in the SDT to the request list
2502005-11-24 14:59:37.358 TVRec(1): SetFlags(WaitingForSIParser,SIParserRunning,) -> RunMainLoop,AskAllowRecording,WaitingForSIParser,NeedToStartRecorder,SIParserRunning,
2512005-11-24 14:59:37.358 SIParser: Starting DVB Section Reader thread
2522005-11-24 14:59:37.359 SIParser: Table[0]->RequirePIDs() == true
2532005-11-24 14:59:37.359 SIParser: Adding PID 0x 0 Filter 0x 0 Mask 0xff Buffer 40960
2542005-11-24 14:59:37.364 SIParser: Table[2]->RequirePIDs() == true
2552005-11-24 14:59:37.365 SIParser: Adding PID 0x1ffb Filter 0xff Mask 0x 0 Buffer 40960
2562005-11-24 14:59:37.365 SIParser: Table[3]->RequirePIDs() == true
2572005-11-24 14:59:37.366 SIParser: Adding PID 0x1ffb Filter 0xff Mask 0x 0 Buffer 40960
2582005-11-24 14:59:37.366 SIParser: Table[6]->RequirePIDs() == true
2592005-11-24 14:59:37.366 SIParser: Adding PID 0x 10 Filter 0x40 Mask 0xff Buffer 40960
2602005-11-24 14:59:37.503 SIParser: PAT Version = 26
2612005-11-24 14:59:37.503 SIParser: Tuned to TransportID: 768
2622005-11-24 14:59:37.503 SIParser: NIT Present on this transport on PID 0x10
2632005-11-24 14:59:37.504 SIParser: PMT #768 on PID 0x1024
2642005-11-24 14:59:37.504 SIParser: PMT #769 on PID 0x1025
2652005-11-24 14:59:37.504 SIParser: PMT #770 on PID 0x1026
2662005-11-24 14:59:37.504 SIParser: PMT #772 on PID 0x1032
2672005-11-24 14:59:37.504 SIParser: PMT #782 on PID 0x1027
2682005-11-24 14:59:37.505 SIParser: PMT #783 on PID 0x1028
2692005-11-24 14:59:37.505 SIParser: Services on this Transport: 768 769 770 772 782 783
2702005-11-24 14:59:37.505 SIParser: Table[0]->Complete() == true
2712005-11-24 14:59:37.505 SIParser: Table[1]->RequirePIDs() == true
2722005-11-24 14:59:37.506 SIParser: Adding PID 0x 401 Filter 0x 2 Mask 0xff Buffer 40960
2732005-11-24 14:59:37.622 SIParser: PMT ServiceID: 769 Version = 23
2742005-11-24 14:59:37.623 SIParser: PID: 161
2752005-11-24 14:59:37.623 SIParser: Unused Descriptor:
276 Stream Identifier Descriptor (0x52) length(1)
2772005-11-24 14:59:37.623 SIParser: PID: 81
2782005-11-24 14:59:37.623 SIParser: Unused Descriptor:
279 Stream Identifier Descriptor (0x52) length(1)
2802005-11-24 14:59:37.624 SIParser: PID: 41
2812005-11-24 14:59:37.624 SIParser: Unused Descriptor:
282 Stream Identifier Descriptor (0x52) length(1)
2832005-11-24 14:59:37.624 SIParser: Teletext Descriptor
2842005-11-24 14:59:37.624 SIParser: lang: eng, type: 2, mag: 0, page: 1
2852005-11-24 14:59:37.625 SIParser: PID: 900
2862005-11-24 14:59:37.625 SIParser: Unused Descriptor:
287 Stream Identifier Descriptor (0x52) length(1)
2882005-11-24 14:59:37.625 SIParser: Unused Descriptor:
289 Unknown Descriptor (0x13) length(25)
2902005-11-24 14:59:37.625 SIParser: Unused Descriptor:
291 Unknown Descriptor (0x14) length(13)
2922005-11-24 14:59:37.625 SIParser: Unused Descriptor:
293 Data Broadcast Identifier Descriptor (0x66) length(2)
2942005-11-24 14:59:37.626 SIParser: PID: 500
2952005-11-24 14:59:37.626 SIParser: Unused Descriptor:
296 Stream Identifier Descriptor (0x52) length(1)
2972005-11-24 14:59:37.626 SIParser: Unused Descriptor:
298 Application Signalling Descriptor (0x6f) length(3)
2992005-11-24 14:59:37.626 SIParser: Table[1]->EmitRequired() == true
3002005-11-24 14:59:37.627 DVB#0 SetPMT ServiceID=769, PCRPID=161 (0xa1)
3012005-11-24 14:59:37.627 TVRec(1): Got SIParser PMT
3022005-11-24 14:59:37.627 TVRec(1): ClearFlags(WaitingForSIParser,) -> RunMainLoop,AskAllowRecording,NeedToStartRecorder,SIParserRunning,
3032005-11-24 14:59:37.628 TVRec(1): Starting Recorder
3042005-11-24 14:59:37.633 TVRec(1): Using profile 'Default' to record
3052005-11-24 14:59:37.696 RecBase(0): SetRingBuffer(0x824c788) '/var/media/video/1003_20051124150000.mpg'
3062005-11-24 14:59:37.696 RecBase(0): SetRecording(0x814a748) title(Italian News)
3072005-11-24 14:59:37.699 DVBRec(0): Card opened successfully (using TS mode).
3082005-11-24 14:59:37.700 DVBRec(0): SetPMTObject()
3092005-11-24 14:59:37.700 DVBRec(0): AutoPID for MPEG Program Number(769), PCR PID(0xa1)
3102005-11-24 14:59:37.701 DVBRec(0): AutoPID selecting PID 0xa1, MPEG-2 Video (eng
3112005-11-24 14:59:37.701 DVBRec(0): AutoPID selecting PID 0x51, MPEG-1 Audio (eng
3122005-11-24 14:59:37.701 DVBRec(0): AutoPID selecting PID 0x29, Teletext (eng
3132005-11-24 14:59:37.702 DVBRec(0): AutoPID skipping PID 0x384, Data
3142005-11-24 14:59:37.702 DVBRec(0): AutoPID skipping PID 0x1f4, Unknown type: 5
3152005-11-24 14:59:37.702 DVBRec(0): AutoPID Complete - PAT/PMT Loaded for service
3162005-11-24 14:59:37.703 DVBRec(0): A/V Stream is unencrypted
3172005-11-24 14:59:37.703 DVBRec(0): Resetting Demux Filters
3182005-11-24 14:59:37.703 DVBRec(0): Adding pid 0xa1 size(3600384)
3192005-11-24 14:59:37.704 DVBRec(0): Set demux buffer size for pid 0xa1 to 3600384,
320 which gives us a 1500 msec buffer.
3212005-11-24 14:59:37.705 DVBRec(0): Adding pid 0x51 size(94208)
3222005-11-24 14:59:37.705 DVBRec(0): Set demux buffer size for pid 0x51 to 94208,
323 which gives us a 1500 msec buffer.
3242005-11-24 14:59:37.706 DVBRec(0): Adding pid 0x29 size(94208)
3252005-11-24 14:59:37.706 DVBRec(0): Set demux buffer size for pid 0x29 to 94208,
326 which gives us a 1500 msec buffer.
3272005-11-24 14:59:37.707 TVRec(1): SetFlags(RecorderRunning,) -> RunMainLoop,AskAllowRecording,NeedToStartRecorder,SIParserRunning,RecorderRunning,
3282005-11-24 14:59:37.708 TVRec(1): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,AskAllowRecording,SIParserRunning,RecorderRunning,
3292005-11-24 14:59:37.707 Parsed PMT(0x0x818ff28) Program Map Table ver(16) pid(0x20) pnum(1)
330
331 Stream #0 pid(0xa1) type(video-mpeg2 0x2)
332 ISO-639 Language Descriptor (0xa) length(4)
333 Stream Identifier Descriptor (0x52) length(1)
334 Stream #1 pid(0x51) type(audio-mp1-layer[1,2,3] 0x3)
335 ISO-639 Language Descriptor (0xa) length(4)
336 Stream Identifier Descriptor (0x52) length(1)
337 Stream #2 pid(0x29) type(private-data 0x6)
338 ISO-639 Language Descriptor (0xa) length(4)
339 Stream Identifier Descriptor (0x52) length(1)
340 Teletext Descriptor (0x56) length(5)
341
3422005-11-24 14:59:37.712 DVB#0 Data read from DMX - This is for debugging with transform.c
3432005-11-24 14:59:38.557 DVBRec(0): Found first payload start for PID 0xa1
3442005-11-24 14:59:38.577 DVBRec(0): Found first payload start for PID 0x29
3452005-11-24 14:59:38.627 DVBRec(0): Found first payload start for PID 0x51
3462005-11-24 14:59:40.500 DVBRec(0) Warning: ReadFromDMX took 23 ms
3472005-11-24 14:59:48.427 SIParser: Private Type channel_numbers = 131 defined for NetworkID 12802
3482005-11-24 14:59:48.427 SIParser: ChannelNumbers Present using Descriptor 131
3492005-11-24 14:59:48.430 SIParser: Table[6]->Complete() == true
3502005-11-24 14:59:48.430 SIParser: Table[5]->RequirePIDs() == true
3512005-11-24 14:59:48.430 SIParser: Adding PID 0x 11 Filter 0x46 Mask 0xfb Buffer 40960
3522005-11-24 14:59:50.634 SIParser: SDT: NetworkID=12802 TransportID=768
3532005-11-24 14:59:50.634 SIParser: SDT: sid=768 type=1 eit_present=0 eit_requested=0 name=SBS HD
3542005-11-24 14:59:50.634 SIParser: Unused Descriptor:
355 Data Broadcast Descriptor (0x64) length(24)
3562005-11-24 14:59:50.635 SIParser: SDT: sid=769 type=1 eit_present=0 eit_requested=0 name=SBS DIGITAL 1
3572005-11-24 14:59:50.635 SIParser: SDT: sid=770 type=1 eit_present=0 eit_requested=0 name=SBS DIGITAL 2
3582005-11-24 14:59:50.635 SIParser: SDT: sid=772 type=1 eit_present=0 eit_requested=0 name=SBS EPG
3592005-11-24 14:59:50.636 SIParser: SDT: sid=782 type=2 eit_present=0 eit_requested=0 name=SBS RADIO 1
3602005-11-24 14:59:50.636 SIParser: SDT: sid=783 type=2 eit_present=0 eit_requested=0 name=SBS RADIO 2
3612005-11-24 14:59:50.636 SIParser: Table[4]->RequirePIDs() == true
3622005-11-24 14:59:50.637 SIParser: Adding PID 0x 12 Filter 0x 0 Mask 0x 0 Buffer 4096000
3632005-11-24 14:59:55.714 AutoExpire: Adding Programs to 'Do Not Expire' List
3642005-11-24 14:59:55.714 1003 @ Thu Nov 24 15:00:00 2005
3652005-11-24 15:00:04.444 DVBRec(0) Warning: ReadFromDMX took 43 ms
3662005-11-24 15:00:18.869 DVBRec(0) Warning: ReadFromDMX took 65 ms
3672005-11-24 15:00:33.242 DVBRec(0) Warning: ReadFromDMX took 44 ms
3682005-11-24 15:00:47.408 DVBRec(0) Warning: ReadFromDMX took 45 ms
3692005-11-24 15:00:55.767 AutoExpire: Adding Programs to 'Do Not Expire' List
3702005-11-24 15:00:55.768 1003 @ Thu Nov 24 15:00:00 2005
3712005-11-24 15:00:55.771 AutoExpire: SendDeleteMessages, cycling through expire list.
3722005-11-24 15:00:55.773 AutoExpire: Checking episode count for each recording profile using max episodes
3732005-11-24 15:01:01.734 DVBRec(0) Warning: ReadFromDMX took 46 ms
3742005-11-24 15:01:16.124 DVBRec(0) Warning: ReadFromDMX took 42 ms
3752005-11-24 15:01:30.452 DVBRec(0) Warning: ReadFromDMX took 46 ms
3762005-11-24 15:01:44.855 DVBRec(0) Warning: ReadFromDMX took 45 ms
3772005-11-24 15:01:55.828 AutoExpire: Adding Programs to 'Do Not Expire' List
3782005-11-24 15:01:55.828 1003 @ Thu Nov 24 15:00:00 2005
3792005-11-24 15:01:59.078 DVBRec(0) Warning: ReadFromDMX took 43 ms
3802005-11-24 15:02:13.493 DVBRec(0) Warning: ReadFromDMX took 45 ms
3812005-11-24 15:02:27.888 DVBRec(0) Warning: ReadFromDMX took 45 ms
3822005-11-24 15:02:42.123 DVBRec(0) Warning: ReadFromDMX took 46 ms
3832005-11-24 15:02:55.881 AutoExpire: Adding Programs to 'Do Not Expire' List
3842005-11-24 15:02:55.882 1003 @ Thu Nov 24 15:00:00 2005
3852005-11-24 15:02:55.885 AutoExpire: SendDeleteMessages, cycling through expire list.
3862005-11-24 15:02:56.526 DVBRec(0) Warning: ReadFromDMX took 45 ms
3872005-11-24 15:03:10.849 DVBRec(0) Warning: ReadFromDMX took 43 ms
3882005-11-24 15:03:25.243 DVBRec(0) Warning: ReadFromDMX took 43 ms
3892005-11-24 15:03:39.160 DVBRec(0) Warning: ReadFromDMX took 44 ms
3902005-11-24 15:03:53.247 DVBRec(0) Warning: ReadFromDMX took 45 ms
3912005-11-24 15:03:55.939 AutoExpire: Adding Programs to 'Do Not Expire' List
3922005-11-24 15:03:55.939 1003 @ Thu Nov 24 15:00:00 2005
3932005-11-24 15:04:07.641 DVBRec(0) Warning: ReadFromDMX took 45 ms
3942005-11-24 15:04:22.051 DVBRec(0) Warning: ReadFromDMX took 53 ms
3952005-11-24 15:04:36.387 DVBRec(0) Warning: ReadFromDMX took 43 ms
3962005-11-24 15:04:50.524 DVBRec(0) Warning: ReadFromDMX took 45 ms
3972005-11-24 15:04:55.991 AutoExpire: Adding Programs to 'Do Not Expire' List
3982005-11-24 15:04:55.992 1003 @ Thu Nov 24 15:00:00 2005
3992005-11-24 15:04:55.995 AutoExpire: SendDeleteMessages, cycling through expire list.
4002005-11-24 15:05:00.518 TVRec(1): ClearFlags(FinishRecording,) -> RunMainLoop,AskAllowRecording,SIParserRunning,RecorderRunning,
4012005-11-24 15:05:00.519 TVRec(1): Changing from RecordingOnly to None
4022005-11-24 15:05:00.519 TVRec(1): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,AskAllowRecording,SIParserRunning,RecorderRunning,
4032005-11-24 15:05:00.519 TVRec(1): SetFlags(AskAllowRecording,) -> RunMainLoop,AskAllowRecording,SIParserRunning,RecorderRunning,
4042005-11-24 15:05:00.519 TVRec(1): Request: Program(no) channel() input() flags(CloseRec,KillRingBuffer,)
4052005-11-24 15:05:00.520 SIParser: Stopping DVB Section Reader
4062005-11-24 15:05:00.546 SIParser: DVB Section Reader thread stopped
4072005-11-24 15:05:00.548 TVRec(1): ClearFlags(SIParserRunning,) -> RunMainLoop,AskAllowRecording,RecorderRunning,
4082005-11-24 15:05:00.548 DVBRec(0): Close() fd(23) -- begin
4092005-11-24 15:05:00.865 DVBRec(0): Close() fd(-1) -- end
4102005-11-24 15:05:00.870 Reschedule requested for id 0.
4112005-11-24 15:05:00.879 Finished recording Italian News: channel 1003
4122005-11-24 15:05:00.942 DVBRec(0): Close() fd(-1) -- begin
4132005-11-24 15:05:01.030 TVRec(1): ClearFlags(RecorderRunning,) -> RunMainLoop,AskAllowRecording,
4142005-11-24 15:05:01.051 TVRec(1): ClearFlags(RecorderRunning,) -> RunMainLoop,AskAllowRecording,
4152005-11-24 15:05:01.052 TVRec(1): Tearing down RingBuffer
4162005-11-24 15:05:01.055 TVRec(1): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,AskAllowRecording,
4172005-11-24 15:05:01.107 Scheduled 19 items in 0.2 = 0.00 match + 0.24 place
4180: start_time: 3063.107 duration: 28.944
4191: start_time: 3063.077 duration: 28.944
420stream: start_time: 34034.194 duration: 321.934 bitrate=4265 kb/s
4212005-11-24 15:05:01.191 AFD: Opened codec 0x81946d0, id(MPEG2VIDEO) type(Video)
4222005-11-24 15:05:01.226 AFD: Opened codec 0x8194fd0, id(MP3) type(Audio)
423