Page 1 of 1

Wrong program checked when playing live DVB-T - bug?

Posted: 20 Jan 2010 08:58
by stevedee3
Hi,

When playing Channel 7 in Melbourne, there are five separate programs transmitted on the transport stream ("7 Digital", "7 Digital 1", "7TWO", "7 Digital 3", "7 Digital HD"). Starting VLC on this frequency plays "7 Digital HD" by default. But the "Playback"-> "Program" menu shows "7 Digital" checked, even though this is not the program currently being played. A log of this is attached in case this helps.

Is this a bug or my misunderstanding?

(I'm running VLC 1.0.3 on Windows)

Thanks.

Code: Select all

qt4 debug: Initialization of Capture device panel dshow error: no video capture device was detected qt4 warning: Input option: dvb-bandwidth=7 main debug: adding item `dvb-t://frequency=177500000' ( dvb-t://frequency=177500000 ) qt4 debug: Adding a new MRL to recent ones: dvb-t://frequency=177500000 main debug: rebuilding array of current - root Playlist main debug: rebuild done - 1 items, index -1 main debug: processing request item dvb-t://frequency=177500000 node null skip 0 main debug: resyncing on dvb-t://frequency=177500000 main debug: dvb-t://frequency=177500000 is at 0 main debug: starting new item main debug: creating new input thread main debug: Creating an input for 'dvb-t://frequency=177500000' main debug: thread started main debug: using timeshift granularity of 50 MBytes main debug: using timeshift path 'j:\dvb' main debug: thread (input) created at priority 1 (../.././src/input/input.c:230) main debug: `dvb-t://frequency=177500000' gives access `dvb-t' demux `' path `frequency=177500000' main debug: creating demux: access='dvb-t' demux='' path='frequency=177500000' main debug: looking for access_demux module: 0 candidates main debug: no access_demux module matched "dvb-t" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: creating access 'dvb-t' path='frequency=177500000' main debug: looking for access module: 1 candidate bda debug: CreateTuneRequest: Using Tuning Space: ShowShifter-DVBt bda debug: Build: Using Tuning Space ID 6 bda debug: FindFilter: Connected THDTV DVB-t BDA Tuner Filter bda debug: BDAGraph: Using adapter 0 bda debug: FindFilter: Connected THDTV DVB-t BDA Capture Filter bda debug: Build: Trying connecting with subtype MEDIASUBTYPE_MPEG2_TRANSPORT bda warning: Build: Cannot connect Sample Grabber to Capture device: hr=0x80004005 (try 1/2) bda debug: Build: Trying connecting with subtype KSDATAFORMAT_SUBTYPE_BDA_MPEG2_TRANSPORT bda debug: FindFilter: Connected BDA MPEG2 Transport Information Filter bda debug: Graph started hr=0x0 main debug: using access module "bda" main debug: TIMER module_need() : 1950.000 ms - Total 1950.000 ms / 1 intvls (Avg 1950.000 ms) main debug: Using AStream*Block main debug: pre buffering bda warning: BDA SampleCB: Sample Discontinuity. main debug: received first data after 214 ms main debug: prebuffering done 32712 bytes in 0s - 149 kbytes/s main debug: looking for stream_filter module: 2 candidates main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: looking for stream_filter module: 1 candidate main debug: using stream_filter module "stream_filter_record" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: creating demux: access='dvb-t' demux='ts' path='frequency=177500000' main debug: looking for demux module: 1 candidate main debug: using demux module "ts" main debug: TIMER module_need() : 4.000 ms - Total 4.000 ms / 1 intvls (Avg 4.000 ms) main debug: looking for a subtitle file in C:\Program Files\VideoLAN_100pre2\VLC\ ts debug: DEMUX_SET_GROUP 0 0 main debug: `dvb-t://frequency=177500000' successfully opened ts debug: pid[833] unknown ts debug: pid[801] unknown ts debug: pid[769] unknown ts debug: pid[804] unknown ts debug: pid[802] unknown ts debug: pid[835] unknown ts debug: pid[770] unknown ts debug: pid[772] unknown ts debug: pid[832] unknown ts debug: pid[816] unknown ts debug: pid[800] unknown ts debug: pid[784] unknown ts debug: pid[768] unknown ts debug: PATCallBack called ts debug: new PAT ts_id=1283 version=1 current_next=1 ts debug: * number=0 pid=16 ts debug: * number=1328 pid=768 ts debug: * number=1329 pid=784 ts debug: * number=1330 pid=800 ts debug: * number=1331 pid=816 ts debug: * number=1332 pid=832 ts debug: pid[1] unknown ts debug: PMTCallBack called ts debug: new PMT program number=1332 version=1 pid_pcr=833 ts debug: * es pid=772 type=6 dr->i_tag=0x56 ts debug: * ttxt type=Teletext subtitles lan=eng page=801 ts debug: * es pid=772 type=6 fcc=telx main debug: selecting program id=1332 ts debug: * es pid=833 type=2 dr->i_tag=0x2 ts debug: * es pid=833 type=2 fcc=mpgv main debug: looking for decoder module: 35 candidates main debug: using decoder module "libmpeg2" main debug: TIMER module_need() : 4.000 ms - Total 4.000 ms / 1 intvls (Avg 4.000 ms) main debug: thread (decoder) created at priority 0 (../.././src/input/decoder.c:315) main debug: thread started ts debug: * es pid=835 type=6 dr->i_tag=0xa ts debug: * es pid=835 type=6 dr->i_tag=0x6a ts debug: found language: eng ts debug: * es pid=835 type=6 fcc=a52 main debug: looking for decoder module: 35 candidates main debug: using decoder module "a52" main debug: TIMER module_need() : 4.000 ms - Total 4.000 ms / 1 intvls (Avg 4.000 ms) main debug: thread (decoder) created at priority 2 (../.././src/input/decoder.c:315) main debug: thread started ts warning: first packet for pid=833 cc=0x5 ts debug: PMTCallBack called ts debug: new PMT program number=1331 version=1 pid_pcr=769 ts debug: * es pid=769 type=2 dr->i_tag=0x2 ts debug: * es pid=769 type=2 fcc=mpgv ts debug: * es pid=770 type=3 dr->i_tag=0xa ts debug: found language: eng ts debug: * es pid=770 type=3 fcc=mpga ts warning: pmt error: pid=772 already defined ts warning: first packet for pid=769 cc=0x1 ts warning: first packet for pid=770 cc=0x3 ts warning: first packet for pid=835 cc=0xf ts debug: PMTCallBack called ts debug: new PMT program number=1330 version=1 pid_pcr=801 ts debug: * es pid=801 type=2 dr->i_tag=0x2 ts debug: * es pid=801 type=2 fcc=mpgv ts debug: * es pid=802 type=3 dr->i_tag=0xa ts debug: found language: eng ts debug: * es pid=802 type=3 fcc=mpga ts debug: * es pid=804 type=6 dr->i_tag=0x56 ts debug: * ttxt type=Teletext subtitles lan=eng page=801 ts debug: * es pid=804 type=6 fcc=telx ts debug: PMTCallBack called ts debug: new PMT program number=1329 version=1 pid_pcr=769 ts warning: pmt error: pid=769 already defined ts warning: pmt error: pid=770 already defined ts warning: pmt error: pid=772 already defined ts warning: first packet for pid=801 cc=0xc ts debug: PMTCallBack called ts debug: new PMT program number=1328 version=1 pid_pcr=769 ts warning: pmt error: pid=769 already defined ts warning: pmt error: pid=770 already defined ts warning: pmt error: pid=772 already defined ts warning: first packet for pid=802 cc=0x6 ts debug: pid[21] unknown ts warning: first packet for pid=804 cc=0xb main debug: Buffering 0% ts warning: first packet for pid=772 cc=0xb main debug: Buffering 11% main debug: Buffering 23% main debug: Buffering 35% main debug: Buffering 46% main debug: Buffering 58% main debug: Buffering 70% main debug: Buffering 81% main debug: Buffering 93% main debug: Stream buffering done (315 ms in 308 ms) main debug: Decoder buffering done in 0 ms a52 info: A/52 channels:6 samplerate:48000 bitrate:384000 main debug: creating aout main debug: looking for audio output module: 3 candidates aout_directx debug: OpenAudio aout_directx debug: found device: Primary Sound Driver aout_directx debug: found device: Realtek HD Audio output libmpeg2 debug: 1440x1080 (display 1440,1080), aspect 768000, sar 4:3, 25.000 fps main debug: no usable vout present, spawning one main debug: looking for text renderer module: 2 candidates main debug: thread (fontlist builder) created at priority 0 (../../.././modules/misc/freetype.c:475) freetype debug: using fontsize: 2 main debug: using text renderer module "freetype" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: looking for video filter2 module: 20 candidates swscale debug: 32x32 chroma: YUVA -> 16x16 chroma: YUVA with scaling using Bicubic (good quality) main debug: using video filter2 module "swscale" main debug: TIMER module_need() : 4.000 ms - Total 4.000 ms / 1 intvls (Avg 4.000 ms) main debug: looking for video filter2 module: 20 candidates yuvp debug: YUVP to YUVA converter main debug: using video filter2 module "yuvp" main debug: TIMER module_need() : 4.000 ms - Total 4.000 ms / 1 intvls (Avg 4.000 ms) main debug: window size: 1920x1080 main debug: looking for video output module: 7 candidates main debug: thread started freetype debug: Building font database... freetype debug: Finished building font database. freetype debug: Took 0 microseconds main debug: thread ended aout_directx debug: device supports 5.1 channels direct3d debug: creating Vout EventThread main debug: thread (Vout Events Thread) created at priority 0 (../../../.././modules/video_output/msw/direct3d.c:218) aout_directx debug: device supports 4 channels aout_directx debug: device supports 2 channels main debug: thread started direct3d debug: DirectXCreateWindow main debug: looking for hwnd module: 3 candidates qt4 debug: requesting video... aout_directx debug: device supports 1 channel aout_directx debug: Windows says your SpeakerConfig is 5.1 aout_directx debug: device supports A/52 over S/PDIF aout_directx debug: channel reordering needed aout_directx debug: creating DirectSoundThread main debug: thread started aout_directx debug: DirectSoundThread ready main debug: thread (DirectSound Notification Thread) created at priority 15 (../../.././modules/audio_output/directx.c:427) main debug: using audio output module "aout_directx" main debug: TIMER module_need() : 238.000 ms - Total 238.000 ms / 1 intvls (Avg 238.000 ms) main debug: output 's16l' 48000 Hz 3F2R/LFE frame=1 samples/12 bytes main debug: mixer 'fl32' 48000 Hz 3F2R/LFE frame=1 samples/24 bytes main debug: filter(s) 'fl32'->'s16l' 48000 Hz->48000 Hz 3F2R/LFE->3F2R/LFE main debug: looking for audio filter module: 24 candidates main debug: using audio filter module "converter_float" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: found a filter for the whole conversion main debug: looking for audio mixer module: 3 candidates main debug: using audio mixer module "float32_mixer" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: input 'a52 ' 48000 Hz 3F2R/LFE frame=1536 samples/1536 bytes main debug: looking for audio filter module: 1 candidate scaletempo warning: bad input or output format main warning: no audio filter module matching "scaletempo" could be loaded main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: looking for audio filter module: 1 candidate scaletempo debug: format: 48000 rate, 6 nch, 4 bps, fl32 scaletempo debug: params: 30 stride, 0.200 overlap, 14 search scaletempo debug: 1.000 scale, 1440.000 stride_in, 1440 stride_out, 1152 standing, 288 overlap, 672 search, 2400 queue, fl32 mode main debug: using audio filter module "scaletempo" main debug: TIMER module_need() : 4.000 ms - Total 4.000 ms / 1 intvls (Avg 4.000 ms) main debug: filter(s) 'a52 '->'fl32' 48000 Hz->48000 Hz 3F2R/LFE->3F2R/LFE main debug: looking for audio filter module: 24 candidates main debug: using audio filter module "a52tofloat32" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: found a filter for the whole conversion main debug: filter(s) 'fl32'->'fl32' 52800 Hz->48000 Hz 3F2R/LFE->3F2R/LFE main debug: looking for audio filter module: 24 candidates main debug: using audio filter module "bandlimited_resampler" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: found a filter for the whole conversion main debug: End of audio preroll main warning: PTS is out of range (116458), dropping buffer main warning: PTS is out of range (84458), dropping buffer main warning: PTS is out of range (52458), dropping buffer main warning: PTS is out of range (20458), dropping buffer main warning: PTS is out of range (-11542), dropping buffer ts debug: PSINewTableCallBack: table 0x42(66) ext=0x503(1283) ts debug: SDTCallBack called ts debug: new SDT ts_id=1283 version=2 current_next=1 network_id=4115 ts debug: * service id=1328 eit schedule=1 present=1 running=4 free_ca=0 ts debug: - type=1 provider=Seven Network name=7 Digital main debug: EsOutProgramMeta: number=1328 ts debug: * service id=1329 eit schedule=1 present=1 running=4 free_ca=0 ts debug: - type=1 provider=Seven Network name=7 Digital 1 main debug: EsOutProgramMeta: number=1329 ts debug: * service id=1330 eit schedule=1 present=1 running=4 free_ca=0 ts debug: - type=1 provider=Seven Network name=7TWO main debug: EsOutProgramMeta: number=1330 ts debug: * service id=1331 eit schedule=1 present=1 running=4 free_ca=0 ts debug: - type=1 provider=Seven Network name=7 Digital 3 main debug: EsOutProgramMeta: number=1331 ts debug: * service id=1332 eit schedule=1 present=1 running=4 free_ca=0 ts debug: - type=1 provider=Seven Network name=7 HD Digital main debug: EsOutProgramMeta: number=1332 ts debug: PSINewTableCallBack: table 0x50(80) ext=0x534(1332) ts debug: PSINewTableCallBack: table 0x50(80) ext=0x531(1329) ts debug: PSINewTableCallBack: table 0x50(80) ext=0x533(1331) ts debug: PSINewTableCallBack: table 0x50(80) ext=0x530(1328) ts debug: PSINewTableCallBack: table 0x4e(78) ext=0x531(1329) ts debug: PSINewTableCallBack: table 0x51(81) ext=0x530(1328) ts debug: PSINewTableCallBack: table 0x52(82) ext=0x533(1331) ts debug: PSINewTableCallBack: table 0x51(81) ext=0x532(1330) ts debug: PSINewTableCallBack: table 0x4e(78) ext=0x532(1330) ts debug: PSINewTableCallBack: table 0x4e(78) ext=0x534(1332) main debug: audio output is too slow (160458), trashing 50000us main debug: audio output is too slow (110458), trashing 50000us main debug: audio output is too slow (60458), trashing 50000us main warning: output date isn't PTS date, requesting resampling (60458) main warning: buffer is 66842 late, triggering upsampling ts debug: PSINewTableCallBack: table 0x51(81) ext=0x531(1329) ts debug: PSINewTableCallBack: table 0x51(81) ext=0x533(1331) ts debug: PSINewTableCallBack: table 0x50(80) ext=0x532(1330) ts debug: PSINewTableCallBack: table 0x4e(78) ext=0x530(1328) ts debug: PSINewTableCallBack: table 0x4e(78) ext=0x533(1331) qt4 debug: IM: Setting an input qt4 debug: Updating the geometry qt4 debug: Updating the geometry ts debug: PSINewTableCallBack: table 0x51(81) ext=0x534(1332) ts debug: EITCallBack called ts debug: new EIT service_id=1329 version=28 current_next=1 ts_id=1283 network_id=4115 segment_last_section_number=1 last_table_id=78 ts debug: * event id=58221 start_time:1263972600 duration=1840 running=4 free_ca=0 ts debug: - short event lang=eng 'TODAY TONIGHT' : 'Matt White reports on the latest news and current social issues.' ts debug: - tag=0x54(84) ts debug: - tag=0x55(85) ts debug: - tag=0x76(118) ts debug: - tag=0x76(118) ts debug: * event id=58222 start_time:1263974440 duration=19760 running=1 free_ca=0 ts debug: - short event lang=eng 'AUSTRALIAN OPEN 2010 - DAY 3' : 'Tonight, Justine Henin takes on Elena Dementieva and Australian Bernard Tomic takes on Marin Cilic in Round 1 coverage.' ts debug: - tag=0x54(84) ts debug: - tag=0x55(85) ts debug: - tag=0x76(118) ts debug: - tag=0x76(118) main debug: EsOutProgramEpg: number=1329 name=7 Digital 1 ts debug: EITCallBack called ts debug: new EIT service_id=1328 version=28 current_next=1 ts_id=1283 network_id=4115 segment_last_section_number=1 last_table_id=78 ts debug: * event id=58221 start_time:1263972600 duration=1840 running=4 free_ca=0 ts debug: - short event lang=eng 'TODAY TONIGHT' : 'Matt White reports on the latest news and current social issues.' ts debug: - tag=0x54(84) ts debug: - tag=0x55(85) ts debug: - tag=0x76(118) ts debug: - tag=0x76(118) ts debug: * event id=58222 start_time:1263974440 duration=19760 running=1 free_ca=0 ts debug: - short event lang=eng 'AUSTRALIAN OPEN 2010 - DAY 3' : 'Tonight, Justine Henin takes on Elena Dementieva and Australian Bernard Tomic takes on Marin Cilic in Round 1 coverage.' ts debug: - tag=0x54(84) ts debug: - tag=0x55(85) ts debug: - tag=0x76(118) ts debug: - tag=0x76(118) main debug: EsOutProgramEpg: number=1328 name=7 Digital ts debug: EITCallBack called ts debug: new EIT service_id=1332 version=28 current_next=1 ts_id=1283 network_id=4115 segment_last_section_number=1 last_table_id=78 ts debug: * event id=58871 start_time:1263972600 duration=1840 running=4 free_ca=0 ts debug: - short event lang=eng 'TODAY TONIGHT' : 'Matt White reports on the latest news and current social issues.' ts debug: - tag=0x54(84) ts debug: - tag=0x55(85) ts debug: - tag=0x76(118) ts debug: - tag=0x76(118) ts debug: * event id=58872 start_time:1263974440 duration=19760 running=1 free_ca=0 ts debug: - short event lang=eng 'AUSTRALIAN OPEN 2010 - DAY 3' : 'Tonight, Justine Henin takes on Elena Dementieva and Australian Bernard Tomic takes on Marin Cilic in Round 1 coverage.' ts debug: - tag=0x54(84) ts debug: - tag=0x55(85) ts debug: - tag=0x76(118) ts debug: - tag=0x76(118) main debug: EsOutProgramEpg: number=1332 name=7 HD Digital qt4 debug: Video was requested -1, -1 qt4 debug: Video is resizing to: 1920 1080 qt4 debug: Updating the geometry main debug: using hwnd module "qt4" main debug: TIMER module_need() : 782.000 ms - Total 782.000 ms / 1 intvls (Avg 782.000 ms) main warning: ignoring broken aspect ratio direct3d debug: created video sub-window direct3d debug: Vout EventThread running direct3d debug: disabling screen saver main debug: using video output module "direct3d" main debug: TIMER module_need() : 844.000 ms - Total 844.000 ms / 1 intvls (Avg 844.000 ms) main debug: Deinterlacing available direct3d debug: Direct3D device adapter successfully initialized direct3d debug: selected surface pixel format is YUY2 ts debug: EITCallBack called ts debug: new EIT service_id=1330 version=13 current_next=1 ts_id=1283 network_id=4115 segment_last_section_number=1 last_table_id=78 ts debug: * event id=182 start_time:1263972590 duration=1790 running=4 free_ca=0 ts debug: - short event lang=eng 'FULL HOUSE' : 'Stephanie's reluctance to attend her first day of school evaporates when Joey and Jesse show up in her kindergarten class.' ts debug: - tag=0x54(84) ts debug: - tag=0x55(85) ts debug: - tag=0x76(118) ts debug: - tag=0x76(118) ts debug: * event id=183 start_time:1263974380 duration=2340 running=1 free_ca=0 ts debug: - short event lang=eng 'MOTHER AND SON' : 'Maggie's younger sister, Lorna, returns after losing her husband, and wants to take Maggie to England.' ts debug: - tag=0x54(84) ts debug: - tag=0x55(85) ts debug: - tag=0x76(118) ts debug: - tag=0x76(118) main debug: EsOutProgramEpg: number=1330 name=7TWO direct3d debug: 1 Direct3D pictures created successfully direct3d debug: Direct3D scene created successfully main debug: got 1 direct buffer(s) main debug: pic render sz 1440x1088, of (0,0), vsz 1440x1080, 4cc I420, ar 16:9, sar 4:3, msk r0x0 g0x0 b0x0 main debug: pic in sz 1440x1088, of (0,0), vsz 1440x1080, 4cc I420, ar 16:9, sar 4:3, msk r0x0 g0x0 b0x0 main debug: pic out sz 1440x1088, of (0,0), vsz 1440x1080, 4cc YUY2, ar 16:9, sar 4:3, msk r0x0 g0x0 b0x0 main debug: looking for video filter2 module: 20 candidates main debug: using video filter2 module "i420_yuy2_sse2" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: indirect render, mapping render pictures 0-15 to system pictures 1-16 main debug: looking for video filter2 module: 1 candidate main debug: using video filter2 module "adjust" main debug: TIMER module_need() : 4.000 ms - Total 4.000 ms / 1 intvls (Avg 4.000 ms) main debug: Filter 'adjust' (0x6046cdc) appended to chain main warning: dts != current_pts (-43524936476) main warning: decoder synchro warning: pts != current_date (-40000) main debug: End of video preroll main warning: late picture skipped (67802 > -10) freetype debug: using fontsize: 68 main debug: looking for video blending module: 1 candidate blend debug: chroma: YUVA -> YUY2 main debug: using video blending module "blend" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) ts debug: EITCallBack called ts debug: new EIT service_id=1331 version=28 current_next=1 ts_id=1283 network_id=4115 segment_last_section_number=1 last_table_id=78 ts debug: * event id=58221 start_time:1263972600 duration=1840 running=4 free_ca=0 ts debug: - short event lang=eng 'TODAY TONIGHT' : 'Matt White reports on the latest news and current social issues.' ts debug: - tag=0x54(84) ts debug: - tag=0x55(85) ts debug: - tag=0x76(118) ts debug: - tag=0x76(118) ts debug: * event id=58222 start_time:1263974440 duration=19760 running=1 free_ca=0 ts debug: - short event lang=eng 'AUSTRALIAN OPEN 2010 - DAY 3' : 'Tonight, Justine Henin takes on Elena Dementieva and Australian Bernard Tomic takes on Marin Cilic in Round 1 coverage.' ts debug: - tag=0x54(84) ts debug: - tag=0x55(85) ts debug: - tag=0x76(118) ts debug: - tag=0x76(118) main debug: EsOutProgramEpg: number=1331 name=7 Digital 3 ts debug: PSINewTableCallBack: table 0x52(82) ext=0x531(1329) qt4 debug: Qt: Entering Fullscreen ts debug: PSINewTableCallBack: table 0x52(82) ext=0x532(1330) ts debug: PSINewTableCallBack: table 0x52(82) ext=0x534(1332) ts debug: PSINewTableCallBack: table 0x52(82) ext=0x530(1328) ts debug: pid[20] unknown main warning: late picture skipped (-9695 > -11487) ts debug: pid[16] unknown main warning: late picture skipped (11305 > -9369) main warning: resampling stopped after 2066000 usec (drift: -56228) main warning: buffer is 56395 late, triggering upsampling main debug: decoded 103/108 pictures