Ticket #689: borked-8234

File borked-8234, 15.8 KB (added by tephra@…, 16 years ago)

SVN 8235 backend log of zro byte recording.

Line 
1mythtv@mythbox:~$ mythbackend -v "record,siparser"
22005-12-12 14:07:35.168 Using runtime prefix = /usr/local
32005-12-12 14:07:35.180 New DB connection, total: 1
42005-12-12 14:07:35.182 Enabling Settings Cache.
52005-12-12 14:07:35.182 Clearing Settings Cache.
62005-12-12 14:07:35.183 Current Schema Version: 1119
7Starting up as the master server.
82005-12-12 14:07:35.188 mythbackend: MythBackend started as master server
92005-12-12 14:07:35.191 New DB connection, total: 2
102005-12-12 14:07:35.194 DVB#0 Using DVB card 0, with frontend 'Conexant CX22702 DVB-T'.
112005-12-12 14:07:35.195 New DB connection, total: 3
122005-12-12 14:07:35.232 TVRec(1): SetFlags(RunMainLoop,) -> RunMainLoop,
132005-12-12 14:07:35.232 TVRec(1): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop,
142005-12-12 14:07:35.250 DVB#1 Using DVB card 1, with frontend 'Zarlink MT352 DVB-T'.
152005-12-12 14:07:35.251 TVRec(2): SetFlags(RunMainLoop,) -> RunMainLoop,
162005-12-12 14:07:35.252 TVRec(2): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop,
172005-12-12 14:07:35.252 New DB scheduler connection
182005-12-12 14:07:35.277 mythbackend version: 0.19.20051208-1 www.mythtv.org
192005-12-12 14:07:35.277 Enabled verbose msgs:  important general record siparser
202005-12-12 14:07:35.278 AutoExpire: Found 2 recorders w/max rate of 277 MiB/min
212005-12-12 14:07:35.278 AutoExpire: Required Free Space: 2.2 GB w/freq: 5 min
222005-12-12 14:07:37.257 Reschedule requested for id -1.
232005-12-12 14:07:37.274 Scheduled 1 items in 0.0 = 0.01 match + 0.01 place
242005-12-12 14:07:37.276 scheduler: Scheduled items
252005-12-12 14:07:37.278 Recording starts soon, AUTO-Startup assumed
262005-12-12 14:07:37.280 TVRec(1): ClearFlags(AskAllowRecording,) -> RunMainLoop,
272005-12-12 14:07:37.302 TVRec(1): StartedRecording(0x8161880) fn(/mythtv/1012_20051212140800.mpg)
282005-12-12 14:07:37.302 TVRec(1): ClearFlags(CancelNextRecording,) -> RunMainLoop,
292005-12-12 14:07:37.302 TVRec(1): Changing from None to RecordingOnly
302005-12-12 14:07:37.303 TVRec(1): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,
312005-12-12 14:07:37.303 TVRec(1): SetFlags(AskAllowRecording,) -> RunMainLoop,AskAllowRecording,
322005-12-12 14:07:37.303 TVRec(1): Request: Program(yes) channel() input() flags(Recording,)
332005-12-12 14:07:37.303 TVRec(1): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,AskAllowRecording,
342005-12-12 14:07:37.303 DVB#0 Using DVB card 0, with frontend 'Conexant CX22702 DVB-T'.
352005-12-12 14:07:37.339 TVRec(1): Starting Signal Monitor
362005-12-12 14:07:37.339 TVRec(1): SetupSignalMonitor(1, 0)
372005-12-12 14:07:37.358 TVRec(1): Signal monitor successfully created
382005-12-12 14:07:37.358 TVRec(1): Setting up table monitoring.
392005-12-12 14:07:37.358 TVRec(1): MPEG program number: 1592
402005-12-12 14:07:37.359 TVRec(1): Successfully set up MPEG table monitoring.
412005-12-12 14:07:37.367 TVRec(1): SetFlags(SignalMonitorRunning,) -> RunMainLoop,AskAllowRecording,SignalMonitorRunning,
422005-12-12 14:07:37.367 TVRec(1): SetFlags(WaitingForSignal,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,SignalMonitorRunning,
432005-12-12 14:07:37.367 TVRec(1): SetFlags(NeedToStartRecorder,) -> RunMainLoop,AskAllowRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,
442005-12-12 14:07:37.368 Started recording: Oprah "A Hilarious Surprise For Michael Jordan": channel 1012 on cardid 1, sourceid 1
452005-12-12 14:07:37.371 scheduler: Started recording
462005-12-12 14:07:38.061 CreatePATSingleProgram()
472005-12-12 14:07:38.061 PAT in input stream
482005-12-12 14:07:38.061 Program Association Table
49 PSIP prefix(0x0) tableID(0x0) length(45) extension(0x603)
50      version(2) current(1) section(0) last_section(0)
51         tsid: 1539
52 programCount: 9
53  program number 0 has PID 0x  10   data  0x0 0x0 0x224 0x16
54  program number 1585 has PID 0x 101   data  0x6 0x49 0x225 0x1
55  program number 1586 has PID 0x 102   data  0x6 0x50 0x225 0x2
56  program number 1587 has PID 0x 103   data  0x6 0x51 0x225 0x3
57  program number 1588 has PID 0x 104   data  0x6 0x52 0x225 0x4
58  program number 1589 has PID 0x 100   data  0x6 0x53 0x225 0x0
59  program number 1591 has PID 0x 106   data  0x6 0x55 0x225 0x6
60  program number 1592 has PID 0x 107   data  0x6 0x56 0x225 0x7
61  program number 1593 has PID 0x 108   data  0x6 0x57 0x225 0x8
62
632005-12-12 14:07:38.062 desired_program(1592) pid(0x107)
642005-12-12 14:07:38.062 pmt_pid(0x107)
652005-12-12 14:07:38.062 PAT for output stream
662005-12-12 14:07:38.062 Program Association Table
67 PSIP prefix(0x0) tableID(0x0) length(13) extension(0x603)
68      version(2) current(1) section(0) last_section(0)
69         tsid: 1539
70 programCount: 1
71  program number 1 has PID 0x 107   data  0x0 0x1 0x225 0x7
72
732005-12-12 14:07:38.233 Parsed PMT(0x0x816c230) Program Map Table ver(11) pid(0x107) pnum(1592)
74  System Clock Descriptor (0xb) length(2)
75  Smoothing Buffer Descriptor (0x10) length(6)
76
77 Stream #0 pid(0x202) type(video-mpeg2  0x2)
78  Video Descriptor (0x2) length(3)
79 Stream #1 pid(0x2a0) type(private-data  0x6)
80  AC-3 Descriptor (0x6a) length(3)
81  ISO-639 Language: code(eng) canonical(eng) eng(English)
82
832005-12-12 14:07:38.234 CreatePMTSingleProgram()
842005-12-12 14:07:38.234 PMT in input stream
852005-12-12 14:07:38.234 Program Map Table ver(11) pid(0x107) pnum(1592)
86  System Clock Descriptor (0xb) length(2)
87  Smoothing Buffer Descriptor (0x10) length(6)
88
89 Stream #0 pid(0x202) type(video-mpeg2  0x2)
90  Video Descriptor (0x2) length(3)
91 Stream #1 pid(0x2a0) type(private-data  0x6)
92  AC-3 Descriptor (0x6a) length(3)
93  ISO-639 Language: code(eng) canonical(eng) eng(English)
94
952005-12-12 14:07:38.234 Parsed PMT(0x0x816c230) Program Map Table ver(11) pid(0x107) pnum(1592)
96  System Clock Descriptor (0xb) length(2)
97  Smoothing Buffer Descriptor (0x10) length(6)
98
99 Stream #0 pid(0x202) type(video-mpeg2  0x2)
100  Video Descriptor (0x2) length(3)
101 Stream #1 pid(0x2a0) type(private-data  0x6)
102  AC-3 Descriptor (0x6a) length(3)
103  ISO-639 Language: code(eng) canonical(eng) eng(English)
104
1052005-12-12 14:07:38.234 Parsed PMT(0x0x816c340) Program Map Table ver(0) pid(0x0) pnum(0)
106
107
1082005-12-12 14:07:38.234 PMT for output stream
1092005-12-12 14:07:38.234 Program Map Table ver(11) pid(0x107) pnum(1)
110
111 Stream #0 pid(0x202) type(video-mpeg2  0x2)
112 Stream #1 pid(0x2a0) type(private-data  0x6)
113
1142005-12-12 14:07:38.253 TVRec(1): Got good signal
1152005-12-12 14:07:38.253 TVRec(1): MPEG program num(1592)
1162005-12-12 14:07:38.254 TVRec(1): TeardownSignalMonitor() -- begin
1172005-12-12 14:07:38.613 TVRec(1): TeardownSignalMonitor() -- end
1182005-12-12 14:07:38.613 TVRec(1): ClearFlags(WaitingForSignal,SignalMonitorRunning,) -> RunMainLoop,AskAllowRecording,NeedToStartRecorder,
1192005-12-12 14:07:38.613 TVRec(1): prog_num(1592) vs. dvbc->srv_id(1592)
1202005-12-12 14:07:38.613 SIParser: About to do a reset
1212005-12-12 14:07:38.613 SIParser: Closing all PIDs
1222005-12-12 14:07:38.613 SIParser: Using DelAllPids from SIParser which does nothing
1232005-12-12 14:07:38.613 SIParser: Resetting all Table Handlers
1242005-12-12 14:07:38.613 SIParser: SIParser Reset due to channel change
1252005-12-12 14:07:38.615 SIParser: Added initial preferred language 'aar' with priority 1
1262005-12-12 14:07:38.615 SIParser: Added initial preferred language 'aar' with priority 2
1272005-12-12 14:07:38.615 DVB#0 DVB SI Table Parser Started
1282005-12-12 14:07:38.616 SIParser: ReinitSIParser(std dvb, service #1592)
1292005-12-12 14:07:38.616 FillPMap(str dvb)
1302005-12-12 14:07:38.616 FillPMap(SIS dvb)
1312005-12-12 14:07:38.616 SIParser: Requesting PAT
1322005-12-12 14:07:38.616 SIParser: Adding the Service with ID 1592 in the SDT to the request list
1332005-12-12 14:07:38.616 TVRec(1): SetFlags(WaitingForSIParser,SIParserRunning,) -> RunMainLoop,AskAllowRecording,WaitingForSIParser,NeedToStartRecorder,SIParserRunning,
1342005-12-12 14:07:38.617 SIParser: Starting DVB Section Reader thread
1352005-12-12 14:07:38.617 SIParser: Table[0]->RequirePIDs() == true
1362005-12-12 14:07:38.617 SIParser: Adding PID 0x   0 Filter 0x 0 Mask 0xff Buffer 40960
1372005-12-12 14:07:38.618 SIParser: Table[2]->RequirePIDs() == true
1382005-12-12 14:07:38.619 SIParser: Adding PID 0x1ffb Filter 0xff Mask 0x 0 Buffer 40960
1392005-12-12 14:07:38.619 SIParser: Table[3]->RequirePIDs() == true
1402005-12-12 14:07:38.619 SIParser: Adding PID 0x1ffb Filter 0xff Mask 0x 0 Buffer 40960
1412005-12-12 14:07:38.619 SIParser: Table[6]->RequirePIDs() == true
1422005-12-12 14:07:38.619 SIParser: Adding PID 0x  10 Filter 0x40 Mask 0xff Buffer 40960
1432005-12-12 14:07:38.693 SIParser: PAT Version = 2
1442005-12-12 14:07:38.694 SIParser: Tuned to TransportID: 1539
1452005-12-12 14:07:38.694 SIParser: NIT Present on this transport on PID 0x10
1462005-12-12 14:07:38.694 SIParser: PMT #1585 on PID 0x257
1472005-12-12 14:07:38.694 SIParser: PMT #1586 on PID 0x258
1482005-12-12 14:07:38.694 SIParser: PMT #1587 on PID 0x259
1492005-12-12 14:07:38.694 SIParser: PMT #1588 on PID 0x260
1502005-12-12 14:07:38.694 SIParser: PMT #1589 on PID 0x256
1512005-12-12 14:07:38.694 SIParser: PMT #1591 on PID 0x262
1522005-12-12 14:07:38.695 SIParser: PMT #1592 on PID 0x263
1532005-12-12 14:07:38.695 SIParser: PMT #1593 on PID 0x264
1542005-12-12 14:07:38.695 SIParser: Services on this Transport: 1585 1586 1587 1588 1589 1591 1592 1593
1552005-12-12 14:07:38.695 SIParser: Table[0]->Complete() == true
1562005-12-12 14:07:38.695 SIParser: Table[1]->RequirePIDs() == true
1572005-12-12 14:07:38.695 SIParser: Adding PID 0x 107 Filter 0x 2 Mask 0xff Buffer 40960
1582005-12-12 14:07:38.802 SIParser: PMT ServiceID: 1592 Version = 11
1592005-12-12 14:07:38.802 SIParser: Unused Descriptor:
160System Clock Descriptor (0xb) length(2)
1612005-12-12 14:07:38.802 SIParser: Unused Descriptor:
162Smoothing Buffer Descriptor (0x10) length(6)
1632005-12-12 14:07:38.802 SIParser: PID: 514
1642005-12-12 14:07:38.802 SIParser: Unused Descriptor:
165Video Descriptor (0x2) length(3)
1662005-12-12 14:07:38.802 SIParser: PID: 672
1672005-12-12 14:07:38.802 SIParser: AC3 Descriptor
1682005-12-12 14:07:38.802 SIParser: Table[1]->EmitRequired() == true
1692005-12-12 14:07:38.803 TVRec(1): Got SIParser PMT
1702005-12-12 14:07:38.803 TVRec(1): ClearFlags(WaitingForSIParser,) -> RunMainLoop,AskAllowRecording,NeedToStartRecorder,SIParserRunning,
1712005-12-12 14:07:38.803 TVRec(1): Starting Recorder
1722005-12-12 14:07:38.804 TVRec(1): Using profile 'Default' to record
1732005-12-12 14:07:38.818 RecBase(0): SetRingBuffer(0x817a770) '/mythtv/1012_20051212140800.mpg'
1742005-12-12 14:07:38.818 RecBase(0): SetRecording(0x8161880) title(Oprah)
1752005-12-12 14:07:38.819 DVBRec(0): Card opened successfully fd(18) (using TS mode).
1762005-12-12 14:07:38.820 DVBRec(0): SetPMTObject()
1772005-12-12 14:07:38.820 DVBRec(0): AutoPID for MPEG Program Number(1592), PCR PID(0x82)
1782005-12-12 14:07:38.820 DVBRec(0): AutoPID recording PID 0x202, MPEG-2 Video
1792005-12-12 14:07:38.820 DVBRec(0): AutoPID recording PID 0x2a0, AC3 Audio (eng
1802005-12-12 14:07:38.820 DVBRec(0): AutoPID Complete - PAT/PMT Loaded for service
181                        A/V Streams are unencrypted
1822005-12-12 14:07:38.820 DVBRecorder::SetPAT(NULL)
1832005-12-12 14:07:38.820 DVBRecorder::SetPMT(NULL)
1842005-12-12 14:07:38.829 TVRec(1): SetFlags(RecorderRunning,) -> RunMainLoop,AskAllowRecording,NeedToStartRecorder,SIParserRunning,RecorderRunning,
1852005-12-12 14:07:38.830 TVRec(1): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,AskAllowRecording,SIParserRunning,RecorderRunning,
1862005-12-12 14:07:38.862 DevRdB(0): buffer size 23500 KB
1872005-12-12 14:07:38.862 DVBRec(0): Resetting Demux Filters
1882005-12-12 14:07:38.862 DVBRec(0): Adding pid 0x202 size(3600384)
1892005-12-12 14:07:38.862 DVBRec(0): Set demux buffer size for pid 0x202 to 3600384,
190                        which gives us a 1500 msec buffer.
1912005-12-12 14:07:38.863 DVBRec(0): Adding pid 0x2a0 size(94208)
1922005-12-12 14:07:38.863 DVBRec(0): Set demux buffer size for pid 0x2a0 to 94208,
193                        which gives us a 1500 msec buffer.
1942005-12-12 14:07:38.863 DVBRec(0): Adding pid 0x82 size(94208)
1952005-12-12 14:07:38.863 DVBRec(0): Set demux buffer size for pid 0x82 to 94208,
196                        which gives us a 1500 msec buffer.
1972005-12-12 14:07:38.863 DVBRecorder::SetPAT()
198Program Association Table
199 PSIP prefix(0x0) tableID(0x0) length(13) extension(0x1)
200      version(0) current(1) section(0) last_section(0)
201         tsid: 1
202 programCount: 1
203  program number 1 has PID 0x  20   data  0x0 0x1 0x224 0x32
204
2052005-12-12 14:07:38.864 Parsed PMT(0x0x8175828) Program Map Table ver(0) pid(0x0) pnum(0)
206
207
2082005-12-12 14:07:38.864 DVBRecorder::SetPMT()
209Program Map Table ver(1) pid(0x20) pnum(1)
210  System Clock Descriptor (0xb) length(2)
211  Smoothing Buffer Descriptor (0x10) length(6)
212
213 Stream #0 pid(0x202) type(video-mpeg2  0x2)
214  Video Descriptor (0x2) length(3)
215 Stream #1 pid(0x2a0) type(audio-ac3  0x129)
216  AC-3 Descriptor (0x6a) length(3)
217  ISO-639 Language: code(eng) canonical(eng) eng(English)
218
2192005-12-12 14:07:40.022 SIParser: Private Type channel_numbers = 131 defined for NetworkID 4116
2202005-12-12 14:07:40.022 SIParser: ChannelNumbers Present using Descriptor 131
2212005-12-12 14:07:40.022 SIParser: Unused Descriptor:
222Private Data Specifier Descriptor (0x5f) length(4)
2232005-12-12 14:07:40.022 SIParser: Unused Descriptor:
224Service List Descriptor (0x41) length(24)
2252005-12-12 14:07:40.022 SIParser: Table[6]->Complete() == true
2262005-12-12 14:07:40.022 SIParser: Table[5]->RequirePIDs() == true
2272005-12-12 14:07:40.023 SIParser: Adding PID 0x  11 Filter 0x46 Mask 0xfb Buffer 40960
2282005-12-12 14:07:41.249 SIParser: SDT: NetworkID=4116 TransportID=1539
2292005-12-12 14:07:41.249 SIParser: Unused Descriptor:
230Country Availability Descriptor (0x49) length(4)
2312005-12-12 14:07:41.249 SIParser: Unused Descriptor:
232LinkageDescriptor(stub)
2332005-12-12 14:07:41.249 SIParser: Unused Descriptor:
234Multilingual Service Name Descriptor (0x5d) length(31)
2352005-12-12 14:07:41.249 SIParser: SDT: sid=1587 type=1 eit_present=0 eit_requested=0 name=TEN Digital 2
2362005-12-12 14:07:41.249 SIParser: Unused Descriptor:
237Multilingual Service Name Descriptor (0x5d) length(24)
2382005-12-12 14:07:41.249 SIParser: SDT: sid=1592 type=1 eit_present=0 eit_requested=0 name=TEN HD
2392005-12-12 14:07:41.250 SIParser: Unused Descriptor:
240Multilingual Service Name Descriptor (0x5d) length(27)
2412005-12-12 14:07:41.250 SIParser: SDT: sid=1593 type=1 eit_present=0 eit_requested=0 name=TEN Guide
2422005-12-12 14:07:41.250 SIParser: Unused Descriptor:
243Multilingual Service Name Descriptor (0x5d) length(27)
2442005-12-12 14:07:41.250 SIParser: SDT: sid=1591 type=1 eit_present=0 eit_requested=0 name=TEN Guide
2452005-12-12 14:07:41.250 SIParser: Unused Descriptor:
246Country Availability Descriptor (0x49) length(4)
2472005-12-12 14:07:41.250 SIParser: Unused Descriptor:
248Multilingual Service Name Descriptor (0x5d) length(29)
2492005-12-12 14:07:41.250 SIParser: SDT: sid=1589 type=1 eit_present=0 eit_requested=0 name=TEN Digital
2502005-12-12 14:07:41.250 SIParser: Unused Descriptor:
251Country Availability Descriptor (0x49) length(4)
2522005-12-12 14:07:41.250 SIParser: Unused Descriptor:
253Multilingual Service Name Descriptor (0x5d) length(29)
2542005-12-12 14:07:41.250 SIParser: SDT: sid=1585 type=1 eit_present=0 eit_requested=0 name=TEN Digital
2552005-12-12 14:07:41.250 SIParser: Unused Descriptor:
256Country Availability Descriptor (0x49) length(4)
2572005-12-12 14:07:41.250 SIParser: Unused Descriptor:
258Multilingual Service Name Descriptor (0x5d) length(31)
2592005-12-12 14:07:41.250 SIParser: SDT: sid=1586 type=1 eit_present=0 eit_requested=0 name=TEN Digital 1
2602005-12-12 14:07:41.250 SIParser: Unused Descriptor:
261Country Availability Descriptor (0x49) length(4)
2622005-12-12 14:07:41.250 SIParser: Unused Descriptor:
263Multilingual Service Name Descriptor (0x5d) length(31)
2642005-12-12 14:07:41.250 SIParser: SDT: sid=1588 type=1 eit_present=0 eit_requested=0 name=TEN Digital 3
2652005-12-12 14:07:41.251 SIParser: Table[4]->RequirePIDs() == true
2662005-12-12 14:07:41.251 SIParser: Adding PID 0x  12 Filter 0x 0 Mask 0x 0 Buffer 4096000