Ticket #5263: 1020_20080421200000_log.txt

Sunday, April 27, 2008  4:34:41 PM BST
ProjectX 0.90.4.00 (30.03.2006)

-> working with collection 0
 
-> save normal log file
-> write all video data
-> write all other data
-> patch c.d.flagged infos of pictures
-> add sequence end code
-> set resolution in SDE 
-> PVA: strictly specs. for audio streams
-> VOB: determine diff. Cell timelines
-> TS: ignore scrambled packets
-> TS: enhanced search for open packets
-> TS: join file segments (of Dreambox®)
-> TS: generate PMT stream dependent
-> get only enclosed PES/TS packets
-> concatenate different recordings
-> ensure 1st PES-packet start with video
-> generate PCR/SCR from PTS
 
-> write output files to: '/video2/exported/mytharchive/work/2'
-> 9 cutpoint(s) defined ( (2) use Frame number for cuts )

-> Input File 0:  '/video/1020_20080421200000.mpg' (1,183,187,588 bytes)
-> Filetype is TS (generic PES Container)
-> demux
-> Service ID 0x1
-> PMT 0x3EC refers to these usable streams:
Video:
PID: 0x12D(#1)
Audio:
PID: 0x12E(eng)(#2)
PID: 0x130(eng)(#6)
Teletext:
n/a
Subpict.:
PID: 0x12F(eng_0x10_p1_a1 )

-> special PIDs for searching defined: { 0x12D 0x12E 0x12F }
ok> PID 0x12E has PES-ID 0xC0 (MPEG Audio) (1504 #9) 
ok> PID 0x12D has PES-ID 0xE0 (MPEG Video) (5640 #31) 
ok> PID 0x12F has PES-ID 0xBD (private stream 1) (SubID 0x20) (929848 #4947) 
-> cut-in @ GOP# 14 / new vframe 0 / new Timecode 00:00:00.000 (378)
-> video basics: 720*576 @ 25fps @ 0.6735 (4:3) @ 15000000bps, vbvBuffer 112
-> starting export of video data @ GOP# 14
!> dropping useless B-Frames @ GOP# 14 / new Timecode 00:00:00.000
!> PID 0x12D -> packet 463839 @ pos. 87201544 out of sequence (6/4) (shifting..) (~00:04:21.240)
!> PID 0x12D -> packet 592237 @ pos. 111340368 out of sequence (6/5) (shifting..) (~00:05:38.920)
!> PID 0x12D -> packet 592263 @ pos. 111345256 out of sequence (14/13) (shifting..) (~00:05:38.920)
!> PID 0x12D -> packet 592413 @ pos. 111373456 out of sequence (12/10) (shifting..) (~00:05:38.920)
!> PID 0x12E -> packet 592429 @ pos. 111376464 out of sequence (2/1) (shifting..) (~00:05:38.920)
!> PID 0x12D -> packet 592443 @ pos. 111379096 out of sequence (9/8) (shifting..) (~00:05:38.920)
!> PID 0x12E -> packet 592452 @ pos. 111380788 out of sequence (4/3) (shifting..) (~00:05:38.920)
!> PID 0x12D -> packet 592538 @ pos. 111396956 out of sequence (3/14) (shifting..) (~00:05:38.920)
!> PID 0x12D -> packet 592542 @ pos. 111397708 out of sequence (11/7) (shifting..) (~00:05:38.920)
!> PID 0x12E -> packet 592558 @ pos. 111400716 out of sequence (12/11) (shifting..) (~00:05:38.920)
!> packet writing: length index out of bounds, shortened.. (12e / c0 / c0 / 2464 -- 2918 / 14 / 3456) @ PTS 10:37:22.616
!> PID 0x12E -> packet 593838 @ pos. 111641356 out of sequence (6/5) (shifting..) (~00:05:39.440)
!> packet writing: length index out of bounds, shortened.. (12e / c0 / c0 / 2467 -- 3286 / 14 / 3456) @ PTS 10:37:23.048
!> PID 0x12D -> packet 593941 @ pos. 111660720 out of sequence (10/14) (shifting..) (~00:05:39.440)
!> PID 0x12D -> packet 593942 @ pos. 111660908 out of sequence (12/11) (shifting..) (~00:05:39.440)
!> PID 0x12D -> packet 594034 @ pos. 111678204 out of sequence (4/6) (shifting..) (~00:05:39.440)
!> PID 0x12E -> packet 594065 @ pos. 111684032 out of sequence (1/0) (shifting..) (~00:05:39.440)
!> PID 0x12D -> packet 594104 @ pos. 111691364 out of sequence (9/8) (shifting..) (~00:05:39.440)
!> PID 0x12D -> packet 594111 @ pos. 111692680 out of sequence (1/0) (shifting..) (~00:05:39.440)
!> PID 0x12D -> packet 594176 @ pos. 111704900 out of sequence (0/15) (shifting..) (~00:05:39.440)
!> PID 0x12E -> packet 594202 @ pos. 111709788 out of sequence (6/5) (shifting..) (~00:05:39.440)
!> packet writing: length index out of bounds, shortened.. (12e / c0 / c0 / 2468 -- 3102 / 14 / 3456) @ PTS 10:37:23.192
!> dropping GOP# 306 @ orig.PTS 10:37:23.011 (3441871063), errorcode: 24
!> Pics exp/cnt 42/41, inGOP PTS diff. 0ms, new Timecode 00:05:39.440
-> cut-out @ GOP# 311 (9029)
!> PID 0x12D -> packet 615484 @ pos. 115710804 out of sequence (13/9) (shifting..) (~00:05:44.080)
!> PID 0x12D -> packet 734806 @ pos. 138143340 out of sequence (2/1) (shifting..) (~00:05:44.080)
!> PID 0x12D -> packet 883640 @ pos. 166124132 out of sequence (2/1) (shifting..) (~00:05:44.080)
-> cut-in @ GOP# 542 / new vframe 8602 / new Timecode 00:05:44.080 (15098)
!> PID 0x12D -> packet 1159225 @ pos. 217934112 out of sequence (9/7) (shifting..) (~00:07:35.560)
!> PID 0x12D -> packet 1349371 @ pos. 253681560 out of sequence (0/13) (shifting..) (~00:09:20.320)
!> PID 0x12D -> packet 1552813 @ pos. 291928656 out of sequence (5/4) (shifting..) (~00:11:32.320)
!> PID 0x12E -> packet 1552820 @ pos. 291929972 out of sequence (1/0) (shifting..) (~00:11:32.320)
!> PID 0x12D -> packet 1552833 @ pos. 291932416 out of sequence (9/6) (shifting..) (~00:11:32.320)
!> PID 0x12E -> packet 1552839 @ pos. 291933544 out of sequence (4/3) (shifting..) (~00:11:32.320)
!> packet writing: length index out of bounds, shortened.. (12e / c0 / c0 / 6614 -- 3128 / 14 / 3456) @ PTS 10:47:20.216
!> PID 0x12D -> packet 1552849 @ pos. 291935424 out of sequence (8/7) (shifting..) (~00:11:32.320)
!> PID 0x12D -> packet 1552871 @ pos. 291939560 out of sequence (14/10) (shifting..) (~00:11:32.320)
!> PID 0x12E -> packet 1552874 @ pos. 291940124 out of sequence (9/8) (shifting..) (~00:11:32.320)
!> packet writing: length index out of bounds, shortened.. (12e / c0 / c0 / 6615 -- 3286 / 14 / 3456) @ PTS 10:47:20.360
!> PID 0x12D -> packet 1809538 @ pos. 340192956 out of sequence (12/10) (shifting..) (~00:14:16.440)
!> PID 0x12D -> packet 2133819 @ pos. 401157784 out of sequence (8/6) (shifting..) (~00:17:02.200)
!> PID 0x12D -> packet 2146030 @ pos. 403453452 out of sequence (9/6) (shifting..) (~00:17:09.520)
!> PID 0x12D -> packet 2152427 @ pos. 404656088 out of sequence (9/6) (shifting..) (~00:17:12.040)
-> cut-out @ GOP# 1328 (38381)
!> PID 0x12D -> packet 2655874 @ pos. 499304124 out of sequence (2/1) (shifting..) (~00:21:15.360)
-> cut-in @ GOP# 1543 / new vframe 31884 / new Timecode 00:21:15.360 (44550)
!> PID 0x12D -> packet 3275188 @ pos. 615735156 out of sequence (0/15) (shifting..) (~00:23:35.680)
!> PID 0x12D -> packet 3548634 @ pos. 667143004 out of sequence (8/6) (shifting..) (~00:26:20.560)
!> PID 0x12D -> packet 3841320 @ pos. 722167972 out of sequence (4/1) (shifting..) (~00:29:18.160)
-> cut-out @ GOP# 2446 (71000)
!> PID 0x12D -> packet 4912330 @ pos. 923517852 out of sequence (8/6) (shifting..) (~00:38:54.160)
-> cut-in @ GOP# 2659 / new vframe 58354 / new Timecode 00:38:54.160 (76922)
!> PTS difference of 21276000 (00:03:56.400) to last exported GOP detected 
!> dropping useless B-Frames @ GOP# 2659 / new Timecode 00:38:54.160
!> PID 0x12D -> packet 5274824 @ pos. 991666724 out of sequence (9/5) (shifting..) (~00:39:49.640)
!> PID 0x12D -> packet 5408490 @ pos. 1016795932 out of sequence (5/2) (shifting..) (~00:41:04.040)
!> PID 0x12D -> packet 5469586 @ pos. 1028281980 out of sequence (9/8) (shifting..) (~00:41:35.840)
!> PID 0x12E -> packet 5469593 @ pos. 1028283296 out of sequence (2/1) (shifting..) (~00:41:35.840)
!> packet writing: length index out of bounds, shortened.. (12e / c0 / c0 / 22495 -- 3286 / 14 / 3456) @ PTS 11:25:27.080
-> cut-out @ GOP# 2935 (85103)
!> PID 0x12D -> packet 6168163 @ pos. 1159614456 out of sequence (0/7) (shifting..) (~00:44:20.760)
packs: 6147709 100% 1183187588

-> Video: fr/ ct/ 1p/ cg/ og/ dg -> 66519/ 534/ 23/ 2261/ 0/ 1
-> Video length: 66519 frames @ 00:44:20.760
-> GOP summary: min. 2, max. 90 fields; contains interlaced frames
-> avg. nom. bitrate 2266638bps (min/max: 577200/10474800)
-> set first sequenceheader bitrate to 9000000bps
---> new File: /video2/exported/mytharchive/work/2/1020_20080421200000.m2v

--> MPEG Audio (0xC0) on PID 0x12E
-> check CRC of AC-3 / MPEG-Audio L1,2
-> delete CRC in MPEG-Audio Layer1,2
-> add frames
Audio PTS: first packet 10:31:27.800, last packet 11:33:33.080
Video PTS: start 1.GOP 10:31:43.571, end last GOP 11:28:11.971
-> adjusting audio at video-timeline
-> src_audio: MPEG-1, Layer2, 48000Hz, stereo, 192kbps, CRC @ 00:00:00.000
!> missing syncword @  8515585, @ 00:05:39.048
!> found syncword @ 8516368
!> missing syncword @  8516945, @ 00:05:39.072
!> found syncword @ 8517912
!> 5 frame(s) (120ms) inserted @ 00:05:39.072
!> missing syncword @  8527705, @ 00:05:39.432
!> found syncword @ 8528096
!> missing syncword @  8529249, @ 00:05:39.432
!> found syncword @ 8529640
!> missing syncword @  8530217, @ 00:05:39.432
!> found syncword @ 8530608
!> missing syncword @  22859185, @ 00:11:32.424
!> found syncword @ 22859994
!> 2 frame(s) (48ms) inserted @ 00:11:32.424
!> missing syncword @  22860571, @ 00:11:32.472
!> found syncword @ 22860962
!> 3 frame(s) (72ms) inserted @ 00:11:32.544
!> missing syncword @  77741667, @ 00:41:35.904
!> found syncword @ 77742058
!> 2 frame(s) (48ms) inserted @ 00:41:35.976
audio frames: wri/pre/skip/ins/add 110865/0/0/12/0 @ 00:44:20.760 done...
---> new File: '/video2/exported/mytharchive/work/2/1020_20080421200000.mp2'

--> Subpicture (SubID 0x20)
-> selected DVB subpicture color model: (0) 4 colors ; fixed to page id: 

-> export format: sup
-> temp. file: 1020_20080421200000.sp (2712271 bytes)
Subpicture PTS: first packet 10:31:31.866, last packet 11:33:23.441
Video PTS: start 1.GOP 10:31:43.571, end last GOP 11:28:11.971
-> adjusting subpicture at video-timeline
-> source is DVB Subtitle...
551 subpictures written...
---> new File: /video2/exported/mytharchive/work/2/1020_20080421200000.sup

summary of created media files:
.Video (m2v):   66519 Frames    00:44:20.760            '/video2/exported/mytharchive/work/2/1020_20080421200000.m2v'
Audio 0 (mp2):  110865 Frames   00:44:20.760    0/0/12/0        '/video2/exported/mytharchive/work/2/1020_20080421200000.mp2'
SubPicture 0:   551 subpictures         '/video2/exported/mytharchive/work/2/1020_20080421200000.sup'
=> 819,319,048 bytes written...
-> we have 76 warnings/errors.