iOS: MPEG Audio (Version 1, Layer 2) problems

macOS specific usage questions
januskopf
New Cone
New Cone
Posts: 2
Joined: 23 Jul 2012 10:50

iOS: MPEG Audio (Version 1, Layer 2) problems

Postby januskopf » 23 Jul 2012 11:54

Hi everyone,

This morning I compiled MobileVLC (which downloads vlc.git, MediaLibraryKit.git) using the official VideoLAN Wiki page and I'm able to build the XCode project and run VLC on my iPad1 with iOS 5.0. Now I'm trying to play an .MPG file which is an MPEG-2 stream rip from a DVB-T device. I've uploaded it here for review:
http://www.sendspace.com/file/qq08vd

The video plays fine on my iPad, but the audio sounds horrible, kind of like a really low sample rate being scaled up to a high rate (extreme "bitcrusher" effect). After stripping down to bare video/audio essentials, MediaInfo gives me the following info about the file:

General
ID : 2214 (0x8A6)
Complete name : tizi0001_0.mpg
Format : MPEG-TS
File size : 31.3 MiB
Duration : 1mn 5s
Start time : UTC 2012-07-20 14:37:02
End time : UTC 2012-07-20 14:38:07
Overall bit rate mode : Variable
Overall bit rate : 3 978 Kbps
Network name : Digitenne
Country : NLD
Timezone : +02:00:00

Video
ID : 7031 (0x1B77)
Menu ID : 1103 (0x44F)
Format : MPEG Video
Format version : Version 2
Format profile : Main@Main
Format settings, BVOP : Yes
Format settings, Matrix : Custom
Format settings, GOP : M=4, N=24
Codec ID : 2
Duration : 1mn 5s
Bit rate mode : Variable
Bit rate : 3 620 Kbps
Maximum bit rate : 15.0 Mbps
Width : 704 pixels
Height : 576 pixels
Display aspect ratio : 16:9
Active Format Description : Letterbox 16:9 image
Frame rate : 25.000 fps
Standard : PAL
Color space : YUV
Chroma subsampling : 4:2:0
Bit depth : 8 bits
Scan type : Interlaced
Scan order : Top Field First
Compression mode : Lossy
Bits/(Pixel*Frame) : 0.357
Stream size : 28.2 MiB (90%)

Audio
ID : 7032 (0x1B78)
Menu ID : 1103 (0x44F)
Format : MPEG Audio
Format version : Version 1
Format profile : Layer 2
Mode : Joint stereo
Codec ID : 3
Duration : 1mn 5s
Bit rate mode : Constant
Bit rate : 160 Kbps
Channel(s) : 2 channels
Sampling rate : 48.0 KHz
Compression mode : Lossy
Delay relative to video : -404ms
Stream size : 1.25 MiB (4%)

The logging from the XCode console, after a quick deletion of EPG and channel listing debug info:
[0x14148a8] main input debug: Creating an input for 'fiets2.mpg'
[0x14148a8] main input debug: using timeshift granularity of 50 MiB, in path '/tmp'
[0x14148a8] main input debug: `file://localhost/var/mobile/Applications/8A45A32F-FE4F-4065-A41C-5BCD7D4B2CFF/Documents/fiets2.mpg' gives access `file' demux `' path `localhost/var/mobile/Applications/8A45A32F-FE4F-4065-A41C-5BCD7D4B2CFF/Documents/fiets2.mpg'
[0x14148a8] main input debug: creating demux: access='file' demux='' location='localhost/var/mobile/Applications/8A45A32F-FE4F-4065-A41C-5BCD7D4B2CFF/Documents/fiets2.mpg' file='/var/mobile/Applications/8A45A32F-FE4F-4065-A41C-5BCD7D4B2CFF/Documents/fiets2.mpg'
[0x142e928] main demux debug: looking for access_demux module: 1 candidate
[0x142e928] main demux debug: no access_demux module matching "file" could be loaded
[0x14148a8] main input debug: creating access 'file' location='localhost/var/mobile/Applications/8A45A32F-FE4F-4065-A41C-5BCD7D4B2CFF/Documents/fiets2.mpg', path='/var/mobile/Applications/8A45A32F-FE4F-4065-A41C-5BCD7D4B2CFF/Documents/fiets2.mpg'
[0x141f848] main access debug: looking for access module: 3 candidates
[0x141f848] filesystem access debug: opening file `/var/mobile/Applications/8A45A32F-FE4F-4065-A41C-5BCD7D4B2CFF/Documents/fiets2.mpg'
[0x141f848] main access debug: using access module "filesystem"
[0x1420298] main stream debug: Using stream method for AStream*
[0x1420298] main stream debug: starting pre-buffering
[0x1420298] main stream debug: received first data after 4 ms
[0x1420298] main stream debug: pre-buffering done 1024 bytes in 0s - 222 KiB/s
[0x14205b8] main stream debug: looking for stream_filter module: 6 candidates
[0x14205b8] main stream debug: no stream_filter module matching "any" could be loaded
[0x14205b8] main stream debug: looking for stream_filter module: 1 candidate
[0x14205b8] main stream debug: using stream_filter module "stream_filter_record"
[0x14148a8] main input debug: creating demux: access='file' demux='' location='localhost/var/mobile/Applications/8A45A32F-FE4F-4065-A41C-5BCD7D4B2CFF/Documents/fiets2.mpg' file='/var/mobile/Applications/8A45A32F-FE4F-4065-A41C-5BCD7D4B2CFF/Documents/fiets2.mpg'
[0x1420758] main demux debug: looking for demux module: 49 candidates
[0x1420758] ts demux warning: lost synchro
[0x1420758] ts demux debug: skipping 57 bytes of garbage
[0x1420758] ts demux warning: lost synchro
[0x1420758] ts demux debug: skipping 114 bytes of garbage
[0x1420758] ts demux warning: lost synchro
[0x1420758] ts demux debug: skipping 171 bytes of garbage
[0x1420758] ts demux warning: lost synchro
[0x1420758] ts demux debug: skipping 40 bytes of garbage
[0x1420758] ts demux warning: lost synchro
[0x1420758] ts demux debug: skipping 97 bytes of garbage
[0x1420758] ts demux warning: lost synchro
[0x1420758] ts demux debug: skipping 154 bytes of garbage
[0x1420758] ts demux warning: lost synchro
[0x1420758] ts demux debug: skipping 23 bytes of garbage
[0x1420758] ts demux warning: lost synchro
[0x1420758] ts demux debug: skipping 80 bytes of garbage
[0x1420758] ts demux warning: lost synchro
[0x1420758] ts demux debug: skipping 137 bytes of garbage
2012-07-23 11:29:22.517 VLC[8723:707] opening
[0x1420758] ts demux debug: pid[7031] unknown
[0x1420758] ts demux debug: pid[7033] unknown
[0x1420758] ts demux debug: pid[7032] unknown
[0x1420758] ts demux debug: pid[1] unknown
libdvbpsi error (PSI decoder): TS discontinuity (received 2, expected 0) for PID 17
libdvbpsi error (PSI decoder): TS discontinuity (received 14, expected 0) for PID 0
[0x1420758] ts demux debug: PATCallBack called
[0x1420758] ts demux debug: new PAT ts_id=2214 version=2 current_next=1
[0x1420758] ts demux debug: * number=0 pid=16
[0x1420758] ts demux debug: * number=1101 pid=7010
[0x1420758] ts demux debug: * number=1102 pid=7020
[0x1420758] ts demux debug: * number=1103 pid=7030
[0x1420758] ts demux debug: * number=1104 pid=7040
[0x1420758] ts demux debug: * number=1111 pid=7110
[0x1420758] ts demux debug: * number=1112 pid=7120
[0x1420758] ts demux debug: * number=1113 pid=7130
[0x1420758] ts demux debug: * number=1114 pid=7140
[0x1420758] ts demux debug: * number=1115 pid=7150
[0x1420758] ts demux debug: * number=1116 pid=7160
[0x1420758] ts demux debug: * number=1117 pid=7170
[0x1420758] ts demux debug: * number=1119 pid=7190
libdvbpsi error (PSI decoder): TS discontinuity (received 13, expected 0) for PID 7030
[0x1420758] ts demux debug: PMTCallBack called
[0x1420758] ts demux debug: new PMT program number=1103 version=2 pid_pcr=7031
[0x1420758] ts demux debug: * es pid=7031 type=2 fcc=mpgv
[0x14148a8] main input debug: selecting program id=1103
[0x1420758] ts demux debug: * es pid=7032 type=3 fcc=mpga
[0x1420758] ts demux debug: * es pid=7033 type=6 dr->i_tag=0x56
[0x1420758] ts demux debug: * ttxt type=Teletext lan=dut page=100
[0x1420758] ts demux debug: * es pid=7033 type=6 fcc=telx
[0x1420758] ts demux debug: * es pid=7034 type=5 dr->i_tag=0x6f
[0x1420758] ts demux debug: * es pid=7034 type=5 *unknown*
[0x1420758] main demux debug: using demux module "ts"
[0x14148a8] main input debug: looking for a subtitle file in /var/mobile/Applications/8A45A32F-FE4F-4065-A41C-5BCD7D4B2CFF/Documents/
[0x18f7c98] main decoder debug: looking for decoder module: 21 candidates
[0x18f7c98] avcodec decoder debug: trying to use direct rendering
[0x18f7c98] avcodec decoder debug: allowing 1 thread(s) for decoding
[0x18f7c98] avcodec decoder debug: ffmpeg codec (MPEG-1/2 Video) started
[0x18f7c98] main decoder debug: using decoder module "avcodec"
[0x1989a98] main packetizer debug: looking for packetizer module: 17 candidates
[0x1989a98] main packetizer debug: using packetizer module "packetizer_mpegvideo"
[0x1989e98] main decoder debug: looking for decoder module: 21 candidates
[0x1989e98] avcodec decoder debug: ffmpeg codec (MPEG Audio layer 1/2/3) started
[0x1989e98] avcodec decoder debug: Using 192000 bytes output buffer
[0x1989e98] main decoder debug: using decoder module "avcodec"
[0x198d298] main packetizer debug: looking for packetizer module: 17 candidates
[0x198d298] main packetizer debug: using packetizer module "mpeg_audio"
[0x1420758] ts demux debug: DEMUX_SET_GROUP 0 0x0
[0x141b4d8] main demux meta debug: looking for meta reader module: 1 candidate
[0x141b4d8] main demux meta debug: no meta reader module matching "any" could be loaded
[0x14148a8] main input debug: `file://localhost/var/mobile/Applications/8A45A32F-FE4F-4065-A41C-5BCD7D4B2CFF/Documents/fiets2.mpg' successfully opened
[0x1420758] ts demux warning: first packet for pid=7031 cc=0x8
[0x1420758] ts demux warning: first packet for pid=7032 cc=0xc
[0x1420758] ts demux warning: first packet for pid=7033 cc=0x2
[0x14148a8] main input debug: Buffering 0%
2012-07-23 11:29:23.237 VLC[8723:707] input playing
[0x14148a8] main input debug: Buffering 1%
[0x14148a8] main input debug: Buffering 4%
[0x14148a8] main input debug: Buffering 6%
[0x14148a8] main input debug: Buffering 8%
[0x1989a98] packetizer_mpegvideo packetizer debug: waiting for sequence start
[0x14148a8] main input debug: Buffering 10%
[0x14148a8] main input debug: Buffering 12%
[0x1989a98] packetizer_mpegvideo packetizer debug: waiting for sequence start
[0x14148a8] main input debug: Buffering 14%
[0x1989a98] packetizer_mpegvideo packetizer debug: waiting for sequence start
[0x14148a8] main input debug: Buffering 15%
[0x14148a8] main input debug: Buffering 18%
[0x14148a8] main input debug: Buffering 19%
[0x14148a8] main input debug: Buffering 22%
[0x14148a8] main input debug: Buffering 24%
[0x14148a8] main input debug: Buffering 26%
[0x1989a98] packetizer_mpegvideo packetizer debug: waiting for sequence start
[0x1989a98] packetizer_mpegvideo packetizer debug: size 704x576 fps=25.000
[0x14148a8] main input debug: Buffering 28%
[0x1991c98] main spu text debug: looking for text renderer module: 1 candidate
[0x1991c98] main spu text debug: using text renderer module "tdummy"
[0x1992698] main scale debug: looking for video filter2 module: 14 candidates
[0x1992698] swscale scale debug: 32x32 chroma: YUVA -> 16x16 chroma: RGBA with scaling using Bicubic (good quality)
[0x1992698] main scale debug: using video filter2 module "swscale"
[0x199b698] main scale debug: looking for video filter2 module: 14 candidates
[0x199b698] yuvp scale debug: YUVP to YUVA converter
[0x199b698] main scale debug: using video filter2 module "yuvp"
[0x199ba98] main video output debug: Deinterlacing available
[0x199ba98] main video output debug: deinterlace 0, mode blend, is_needed 0
[0x199ba98] main video output debug: Opening vout display wrapper
[0x1408858] main vout display debug: looking for vout display module: 1 candidate
[0x1408858] vout_ios vout display debug: Creating VLCOpenGLESVideoView
[0x14148a8] main input debug: Buffering 30%
[0x14148a8] main input debug: Buffering 32%
[0x1408858] vout_ios vout display debug: Creating framebuffer for layer 0x1408cb0 with bounds (0.0,0.0,768.0,1004.0)
[0x1408858] vout_ios vout display debug: Reshaping to 768x1004
[0x1408858] main vout display debug: VoutDisplayEvent 'resize' 768x1004 window
[0x14148a8] main input debug: Buffering 34%
[0x1408858] main vout display debug: VoutDisplayEvent 'fullscreen' 0
[0x1408858] main vout display debug: VoutDisplayEvent 'resize' 768x1004 window
[0x1408858] main vout display debug: using vout display module "vout_ios"
[0x1408858] main vout display debug: A filter to adapt decoder to display is needed
[0x19a0e98] main filter debug: looking for video filter2 module: 14 candidates
[swscaler @ 0x5fad000] No accelerated colorspace conversion found from yuv420p to rgba.
[0x19a0e98] swscale filter debug: 704x576 chroma: I420 -> 704x576 chroma: RV32 with scaling using Bicubic (good quality)
[0x19a0e98] main filter debug: using video filter2 module "swscale"
[0x1408858] main vout display debug: Filter 'Swscale' (0x19a0e98) appended to chain
[0x199ba98] main video output debug: original format sz 704x576, of (0,0), vsz 704x576, 4cc I420, sar 16:11, msk r0x0 g0x0 b0x0
[0x198d298] mpeg_audio packetizer debug: MPGA channels:2 samplerate:48000 bitrate:160
[0x14148a8] main input debug: Buffering 35%
[0x1991c98] main spu text debug: removing module "tdummy"
[0x1991c98] main spu text debug: looking for text renderer module: 1 candidate
[0x1991c98] main spu text debug: using text renderer module "tdummy"
[0x18f7c98] avcodec decoder debug: using direct rendering
[0x14f9eb8] main generic debug: creating audio output
[0x141b908] main audio output debug: looking for audio output module: 1 candidate
[0x18f7c98] main decoder debug: End of video preroll
[0x14148a8] main input debug: Buffering 38%
[0x18f7c98] main decoder debug: Received first picture
[0x141b908] audioqueue audio output debug: New AudioQueue output created (status = 0)
[0x141b908] main audio output error: option audioqueue-gain does not exist
[0x141b908] audioqueue audio output debug: Starting AudioQueue (status = 0)
[0x199ba98] main video output debug: Post-processing available
[0x199ba98] main video output debug: Detected interlaced video
[0x199ba98] main video output debug: deinterlace 0, mode blend, is_needed 1
[0x14148a8] main input debug: Buffering 40%
[0x14148a8] main input debug: Buffering 41%
[0x14148a8] main input debug: Buffering 44%
[0x14148a8] main input debug: Buffering 46%
[0x14148a8] main input debug: Buffering 48%
[0x14148a8] main input debug: Buffering 50%
[0x14148a8] main input debug: Buffering 52%
[0x14148a8] main input debug: Buffering 54%
libdvbpsi error (PSI decoder): TS discontinuity (received 3, expected 0) for PID 20
[0x14148a8] main input debug: Buffering 56%
[0x14148a8] main input debug: Buffering 58%
[0x14148a8] main input debug: Buffering 60%
[0x14148a8] main input debug: Buffering 62%
[0x14148a8] main input debug: Buffering 64%
[0x14148a8] main input debug: Buffering 66%
[0x14148a8] main input debug: Buffering 68%
[0x14148a8] main input debug: Buffering 70%
[0x14148a8] main input debug: Buffering 72%
[0x141b908] main audio output debug: using audio output module "audioqueue"
[0x141b908] main audio output debug: output 's16l' 44100 Hz Stereo frame=1 samples/4 bytes
[0x141b908] main audio output debug: mixer 'f32l' 44100 Hz Stereo frame=1 samples/8 bytes
[0x141b908] main audio output debug: filter(s) 'f32l'->'s16l' 44100 Hz->44100 Hz Stereo->Stereo
[0x19c4e98] main audio filter debug: looking for audio filter module: 9 candidates
[0x19c4e98] audio_format audio filter debug: f32l->s16l, bits per sample: 32->16
[0x19c4e98] main audio filter debug: using audio filter module "audio_format"
[0x141b908] main audio output debug: conversion pipeline completed
[0x141c188] main volume debug: looking for audio volume module: 2 candidates
[0x141c188] main volume debug: using audio volume module "float32_mixer"
[0x141b908] main audio output debug: input 's16l' 48000 Hz Stereo frame=1 samples/4 bytes
[0x19c5298] main audio filter debug: looking for audio filter module: 1 candidate
[0x19c5298] scaletempo audio filter debug: format: 48000 rate, 2 nch, 4 bps, fl32
[0x19c5298] scaletempo audio filter debug: params: 30 stride, 0.200 overlap, 14 search
[0x19c5298] scaletempo audio filter debug: 1.000 scale, 1440.000 stride_in, 1440 stride_out, 1152 standing, 288 overlap, 672 search, 2400 queue, fl32 mode
[0x19c5298] main audio filter debug: using audio filter module "scaletempo"
[0x141b908] main audio output debug: filter(s) 's16l'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo
[0x19db498] main audio filter debug: looking for audio filter module: 9 candidates
[0x19db498] audio_format audio filter debug: s16l->f32l, bits per sample: 16->32
[0x19db498] main audio filter debug: using audio filter module "audio_format"
[0x141b908] main audio output debug: conversion pipeline completed
[0x141b908] main audio output debug: filter(s) 'f32l'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo
[0x141b908] main audio output debug: conversion pipeline completed
[0x141b908] main audio output debug: filter(s) 'f32l'->'f32l' 52800 Hz->44100 Hz Stereo->Stereo
[0x19db898] main audio filter debug: looking for audio resampler module: 1 candidate
[0x19db898] main audio filter debug: using audio resampler module "ugly_resampler"
[0x141b908] main audio output debug: conversion pipeline completed
[0x1989e98] main decoder debug: End of audio preroll
[0x14148a8] main input debug: Buffering 74%
[0x14148a8] main input debug: Buffering 92%
[0x1420758] ts demux debug: PSINewTableCallBack: table 0x42(66) ext=0x8a6(2214)
[0x1420758] ts demux debug: SDTCallBack called
[0x1420758] ts demux debug: new SDT ts_id=2214 version=0 current_next=1 network_id=8720
[0x1420758] ts demux debug: * service id=1101 eit schedule=1 present=1 running=4 free_ca=0
[0x1420758] ts demux debug: - type=1 provider=Digitenne name=Nederland 1
[0x14148a8] main input debug: EsOutProgramMeta: number=1101
[0x1420758] ts demux debug: * service id=1102 eit schedule=1 present=1 running=4 free_ca=0
[0x1420758] ts demux debug: - type=1 provider=Digitenne name=Nederland 2
[0x14148a8] main input debug: EsOutProgramMeta: number=1102
[0x1420758] ts demux debug: * service id=1103 eit schedule=1 present=1 running=4 free_ca=0
[0x1420758] ts demux debug: - type=1 provider=Digitenne name=Nederland 3
[0x14148a8] main input debug: EsOutProgramMeta: number=1103
[0x1420758] ts demux debug: * service id=1104 eit schedule=1 present=1 running=4 free_ca=0
[0x1420758] ts demux debug: - type=1 provider=Digitenne name=RTV Utrecht
[0x14148a8] main input debug: EsOutProgramMeta: number=1104
[0x1420758] ts demux debug: * service id=1111 eit schedule=1 present=1 running=4 free_ca=0
[0x1420758] ts demux debug: - type=2 provider=Digitenne name=Radio M Utrecht
[0x14148a8] main input debug: EsOutProgramMeta: number=1111
[0x1420758] ts demux debug: * service id=1112 eit schedule=1 present=1 running=4 free_ca=0
[0x1420758] ts demux debug: - type=2 provider=Digitenne name=Radio 1
[0x14148a8] main input debug: EsOutProgramMeta: number=1112
[0x1420758] ts demux debug: * service id=1113 eit schedule=1 present=1 running=4 free_ca=0
[0x1420758] ts demux debug: - type=2 provider=Digitenne name=Radio 2
[0x14148a8] main input debug: EsOutProgramMeta: number=1113
[0x1420758] ts demux debug: * service id=1114 eit schedule=1 present=1 running=4 free_ca=0
[0x1420758] ts demux debug: - type=2 provider=Digitenne name=3FM
[0x14148a8] main input debug: EsOutProgramMeta: number=1114
[0x1420758] ts demux debug: * service id=1115 eit schedule=1 present=1 running=4 free_ca=0
[0x1420758] ts demux debug: - type=2 provider=Digitenne name=Radio 4
[0x14148a8] main input debug: EsOutProgramMeta: number=1115
[0x1420758] ts demux debug: * service id=1116 eit schedule=1 present=1 running=4 free_ca=0
[0x1420758] ts demux debug: - type=2 provider=Digitenne name=Radio 5
[0x14148a8] main input debug: EsOutProgramMeta: number=1116
[0x1420758] ts demux debug: * service id=1117 eit schedule=1 present=1 running=4 free_ca=0
[0x1420758] ts demux debug: - type=2 provider=Digitenne name=Radio 6
[0x14148a8] main input debug: EsOutProgramMeta: number=1117
[0x1420758] ts demux debug: * service id=1119 eit schedule=1 present=1 running=4 free_ca=0
[0x1420758] ts demux debug: - type=2 provider=Digitenne name=FunX
[0x14148a8] main input debug: EsOutProgramMeta: number=1119
[0x1420758] ts demux debug: PSINewTableCallBack: table 0x4e(78) ext=0x45f(1119)
[0x14148a8] main input debug: Buffering 94%
[0x1420758] ts demux debug: PSINewTableCallBack: table 0x4e(78) ext=0x45d(1117)
[0x1420758] ts demux debug: PSINewTableCallBack: table 0x4e(78) ext=0x44e(1102)
[0x14148a8] main input debug: Buffering 96%
[0x14148a8] main input debug: Buffering 98%
[0x1408858] main vout display debug: auto hiding mouse cursor
[0x14148a8] main input debug: Stream buffering done (1000 ms in 1155 ms)
[0x14148a8] main input debug: Decoder buffering done in 0 ms
[0x1420758] ts demux debug: PSINewTableCallBack: table 0x50(80) ext=0x44f(1103)
[0x14148a8] main input debug: control type=3
[0x18f7c98] main decoder warning: early picture skipped
[0x18f7c98] main decoder warning: early picture skipped
[0x18f7c98] main decoder warning: early picture skipped
[0x18f7c98] main decoder warning: early picture skipped
[0x1420758] ts demux debug: Seek():i_head_pos:0, i_tail_pos:3280600
[0x1420758] ts demux warning: lost synchro
[0x1420758] ts demux debug: skipping 94 bytes of garbage
[0x1420758] ts demux warning: lost synchro
[0x1420758] ts demux debug: skipping 47 bytes of garbage
[0x1420758] ts demux debug: Seek():can find a time position. i_cnt:3
[0x1420758] ts demux warning: discontinuity received 0x4 instead of 0xd (pid=7033)
[0x1420758] ts demux warning: discontinuity received 0x4 instead of 0x8 (pid=7031)
libdvbpsi error (PSI decoder): TS discontinuity (received 3, expected 11) for PID 18
[0x1420758] ts demux debug: PSINewTableCallBack: table 0x50(80) ext=0x458(1112)
[0x14148a8] main input debug: Buffering 0%
[0x1420758] ts demux debug: PSINewTableCallBack: table 0x50(80) ext=0x44d(1101)
[0x1420758] ts demux debug: PSINewTableCallBack: table 0x50(80) ext=0x45b(1115)
[0x1420758] ts demux debug: PSINewTableCallBack: table 0x50(80) ext=0x457(1111)
[0x14148a8] main input debug: Buffering 2%

[0x14148a8] main input debug: EsOutProgramEpg: number=1117 name=Radio 6 [Program 1117]

[0x14148a8] main input debug: EsOutProgramEpg: number=1102 name=Nederland 2 [Program 1102]
libdvbpsi error (PSI decoder): TS discontinuity (received 3, expected 9) for PID 0
[0x1420758] ts demux debug: PATCallBack called
[0x14148a8] main input debug: Buffering 4%
[0x14148a8] main input debug: Buffering 6%
libdvbpsi error (PSI decoder): TS discontinuity (received 2, expected 8) for PID 7030
[0x1420758] ts demux debug: PMTCallBack called
[0x14148a8] main input debug: Buffering 8%
[0x1420758] ts demux debug: PSINewTableCallBack: table 0x50(80) ext=0x459(1113)
[0x1420758] ts demux debug: PSINewTableCallBack: table 0x50(80) ext=0x45c(1116)
[0x1420758] ts demux debug: PSINewTableCallBack: table 0x50(80) ext=0x45a(1114)
[0x14148a8] main input debug: Buffering 10%
[0x1420758] ts demux debug: PSINewTableCallBack: table 0x50(80) ext=0x44e(1102)
[0x1420758] ts demux debug: PSINewTableCallBack: table 0x50(80) ext=0x45f(1119)
[0x14148a8] main input debug: Buffering 12%
[0x1420758] ts demux debug: PSINewTableCallBack: table 0x4e(78) ext=0x45c(1116)
[0x1420758] ts demux debug: PSINewTableCallBack: table 0x4e(78) ext=0x44d(1101)
libdvbpsi error (PSI decoder): TS discontinuity (received 3, expected 5) for PID 20
[0x1420758] ts demux debug: PSINewTableCallBack: table 0x70(112) ext=0x0(0)
[0x14148a8] main input debug: Buffering 14%
[0x14148a8] main input debug: Buffering 16%
[0x14148a8] main input debug: Buffering 18%
[0x1420758] ts demux debug: PSINewTableCallBack: table 0x50(80) ext=0x450(1104)
[0x1420758] ts demux debug: PSINewTableCallBack: table 0x50(80) ext=0x45d(1117)
[0x14148a8] main input debug: Buffering 20%
[0x14148a8] main input debug: Buffering 22%
[0x1420758] ts demux debug: PSINewTableCallBack: table 0x4e(78) ext=0x459(1113)
[0x1420758] ts demux debug: PSINewTableCallBack: table 0x4e(78) ext=0x45a(1114)
[0x14148a8] main input debug: Buffering 24%
[0x14148a8] main input debug: Buffering 26%
[0x14148a8] main input debug: Buffering 28%
[0x14148a8] main input debug: Buffering 30%
[0x14148a8] main input debug: Buffering 32%
[0x1420758] ts demux debug: PSINewTableCallBack: table 0x4e(78) ext=0x457(1111)
[0x1420758] ts demux debug: PSINewTableCallBack: table 0x4e(78) ext=0x45b(1115)
[0x14148a8] main input debug: Buffering 34%
[0x14148a8] main input debug: Buffering 36%
[0x14148a8] main input debug: Buffering 38%
[0x14148a8] main input debug: Buffering 40%
[0x14148a8] main input debug: Buffering 42%
[0x1420758] ts demux debug: PSINewTableCallBack: table 0x4e(78) ext=0x44f(1103)
[0x1420758] ts demux debug: PSINewTableCallBack: table 0x4e(78) ext=0x450(1104)
[0x1420758] ts demux debug: PSINewTableCallBack: table 0x4e(78) ext=0x458(1112)
[0x14148a8] main input debug: Buffering 44%
[0x14148a8] main input debug: Buffering 46%
[0x14148a8] main input debug: Buffering 48%
[0x14148a8] main input debug: Buffering 50%
libdvbpsi error (PSI decoder): TS discontinuity (received 4, expected 6) for PID 17
[0x1420758] ts demux debug: SDTCallBack called
[0x14148a8] main input debug: Buffering 52%
[0x14148a8] main input debug: Buffering 54%
[0x14148a8] main input debug: Buffering 56%
[0x14148a8] main input debug: Buffering 58%
[0x1989e98] main decoder debug: End of audio preroll
[0x198d298] mpeg_audio packetizer debug: emulated startcode (no startcode on following frame)
[0x14148a8] main input debug: Buffering 60%
[0x14148a8] main input debug: Buffering 62%
[0x14148a8] main input debug: Buffering 94%
[0x14148a8] main input debug: Buffering 96%
[0x14148a8] main input debug: Buffering 98%
[0x14148a8] main input debug: Stream buffering done (1000 ms in 171 ms)
[0x18f7c98] main decoder debug: End of video preroll
[0x18f7c98] main decoder debug: Received first picture
[0x14148a8] main input debug: Decoder buffering done in 27 ms
[0x198d298] mpeg_audio packetizer debug: emulated startcode (no startcode on following frame)
[0x141b908] main audio output warning: buffer way too late (575927), dropping buffer
[0x141b908] main audio output warning: buffer way too late (551927), dropping buffer
[0x141b908] main audio output warning: buffer way too late (527927), dropping buffer
[0x141b908] main audio output warning: buffer way too late (503927), dropping buffer
[0x141b908] main audio output warning: buffer way too late (479927), dropping buffer
[0x141b908] main audio output warning: buffer way too late (455927), dropping buffer
[0x141b908] main audio output warning: buffer way too late (431927), dropping buffer
[0x141b908] main audio output warning: buffer way too late (383927), dropping buffer
[0x141b908] main audio output warning: buffer way too late (359927), dropping buffer
[0x141b908] main audio output warning: buffer way too late (335927), dropping buffer
[0x141b908] main audio output warning: buffer way too late (311927), dropping buffer
[0x141b908] main audio output warning: buffer way too late (287927), dropping buffer
[0x141b908] main audio output warning: buffer way too late (263927), dropping buffer
[0x141b908] main audio output warning: buffer way too late (239927), dropping buffer
[0x141b908] main audio output warning: buffer way too late (215927), dropping buffer
[0x141b908] main audio output warning: buffer way too late (191927), dropping buffer
[0x141b908] main audio output warning: buffer too late (167927), up-sampling
[0x199ba98] main video output debug: picture might be displayed late (missing 1 ms)
[0x199ba98] main video output debug: picture might be displayed late (missing 19 ms)
[0x199ba98] main video output warning: picture is too late to be displayed (missing 36 ms)
[0x199ba98] main video output debug: picture might be displayed late (missing 5 ms)
[0x199ba98] main video output warning: picture is too late to be displayed (missing 36 ms)
[0x199ba98] main video output debug: picture might be displayed late (missing 4 ms)
[0x199ba98] main video output debug: picture might be displayed late (missing 12 ms)

[0x14148a8] main input debug: EsOutProgramEpg: number=1112 name=Radio 1 [Program 1112]
[0x199ba98] main video output warning: picture is too late to be displayed (missing 72 ms)
[0x199ba98] main video output warning: picture is too late to be displayed (missing 34 ms)
[0x14148a8] main input debug: EsOutProgramEpg: number=1101 name=Nederland 1 [Program 1101]
[0x14148a8] main input debug: EsOutProgramEpg: number=1115 name=Radio 4 [Program 1115]
[0x199ba98] main video output debug: picture might be displayed late (missing 4 ms)
[0x14148a8] main input debug: EsOutProgramEpg: number=1111 name=Radio M Utrecht [Program 1111]
[0x199ba98] main video output warning: picture is too late to be displayed (missing 29 ms)
[0x14148a8] main input debug: EsOutProgramEpg: number=1113 name=Radio 2 [Program 1113]
[0x14148a8] main input debug: EsOutProgramEpg: number=1116 name=Radio 5 [Program 1116]
[0x14148a8] main input debug: EsOutProgramEpg: number=1114 name=3FM [Program 1114]
[0x199ba98] main video output warning: picture is too late to be displayed (missing 75 ms)
[0x199ba98] main video output warning: picture is too late to be displayed (missing 35 ms)
[0x14148a8] main input debug: EsOutProgramEpg: number=1102 name=Nederland 2 [Program 1102]
[0x199ba98] main video output warning: picture is too late to be displayed (missing 85 ms)
[0x199ba98] main video output warning: picture is too late to be displayed (missing 45 ms)
[0x199ba98] main video output warning: picture is too late to be displayed (missing 49 ms)
[0x199ba98] main video output debug: picture might be displayed late (missing 9 ms)
[0x14148a8] main input debug: EsOutProgramEpg: number=1119 name=FunX [Program 1119]
[0x14148a8] main input debug: EsOutProgramEpg: number=1104 name=RTV Utrecht [Program 1104]
[0x199ba98] main video output warning: picture is too late to be displayed (missing 213 ms)
[0x199ba98] main video output warning: picture is too late to be displayed (missing 173 ms)
[0x199ba98] main video output warning: picture is too late to be displayed (missing 133 ms)
[0x199ba98] main video output warning: picture is too late to be displayed (missing 147 ms)
[0x199ba98] main video output warning: picture is too late to be displayed (missing 107 ms)
[0x199ba98] main video output warning: picture is too late to be displayed (missing 67 ms)
[0x14148a8] main input debug: EsOutProgramEpg: number=1117 name=Radio 6 [Program 1117]
[0x14148a8] main input error: ES_OUT_SET_(GROUP_)PCR is called too late (pts_delay increased to 1000 ms)
[0x14148a8] main input error: ES_OUT_RESET_PCR called
[0x18f7c98] main decoder warning: early picture skipped
[0x14148a8] main input debug: Buffering 0%
[0x14148a8] main input debug: Buffering 2%
[0x14148a8] main input debug: Buffering 4%
[0x1989e98] main decoder debug: End of audio preroll
[0x18f7c98] main decoder debug: End of video preroll
[0x18f7c98] main decoder debug: Received first picture
[0x14148a8] main input debug: Buffering 98%
[0x14148a8] main input debug: Buffering 99%
[0x14148a8] main input debug: Stream buffering done (1020 ms in 425 ms)
[0x14148a8] main input debug: Decoder buffering done in 10 ms
[0x141b908] main audio output warning: PTS is out of range (17759), dropping buffer
[0x141b908] main audio output warning: PTS is out of range (-6102), dropping buffer
[0x141b908] main audio output warning: PTS is out of range (-29987), dropping buffer
[0x199ba98] main video output debug: picture might be displayed late (missing 12 ms)

Can anybody please help me out and tell me what's going wrong?

Cheers,
Jan

pengjaycn
New Cone
New Cone
Posts: 2
Joined: 24 Jul 2012 14:24

Re: iOS: MPEG Audio (Version 1, Layer 2) problems

Postby pengjaycn » 24 Jul 2012 14:27

I also encountered this problem...who can help me.



[0x14259e8] main input debug: Creating an input for '2011-G3-01.avi'
[0x14259e8] main input debug: using timeshift granularity of 50 MiB, in path '/tmp'
[0x14259e8] main input debug: `file://localhost/var/mobile/Applications/DE5FD9CE-0478-49B0-AC95-E83F5E80EF9C/Documents/2011-G3-01.avi' gives access `file' demux `' path `localhost/var/mobile/Applications/DE5FD9CE-0478-49B0-AC95-E83F5E80EF9C/Documents/2011-G3-01.avi'
[0x14259e8] main input debug: creating demux: access='file' demux='' location='localhost/var/mobile/Applications/DE5FD9CE-0478-49B0-AC95-E83F5E80EF9C/Documents/2011-G3-01.avi' file='/var/mobile/Applications/DE5FD9CE-0478-49B0-AC95-E83F5E80EF9C/Documents/2011-G3-01.avi'
[0x142cdd8] main demux debug: looking for access_demux module: 1 candidate
[0x142cdd8] main demux debug: no access_demux module matching "file" could be loaded
[0x14259e8] main input debug: creating access 'file' location='localhost/var/mobile/Applications/DE5FD9CE-0478-49B0-AC95-E83F5E80EF9C/Documents/2011-G3-01.avi', path='/var/mobile/Applications/DE5FD9CE-0478-49B0-AC95-E83F5E80EF9C/Documents/2011-G3-01.avi'
[0x14239b8] main access debug: looking for access module: 3 candidates
[0x14239b8] filesystem access debug: opening file `/var/mobile/Applications/DE5FD9CE-0478-49B0-AC95-E83F5E80EF9C/Documents/2011-G3-01.avi'
[0x14239b8] main access debug: using access module "filesystem"
[0x1429388] main stream debug: Using stream method for AStream*
[0x1429388] main stream debug: starting pre-buffering
[0x1429388] main stream debug: received first data after 4 ms
[0x1429388] main stream debug: pre-buffering done 1024 bytes in 0s - 226 KiB/s
[0x14295c8] main stream debug: looking for stream_filter module: 6 candidates
[0x14295c8] main stream debug: no stream_filter module matching "any" could be loaded
[0x14295c8] main stream debug: looking for stream_filter module: 1 candidate
[0x14295c8] main stream debug: using stream_filter module "stream_filter_record"
[0x14259e8] main input debug: creating demux: access='file' demux='' location='localhost/var/mobile/Applications/DE5FD9CE-0478-49B0-AC95-E83F5E80EF9C/Documents/2011-G3-01.avi' file='/var/mobile/Applications/DE5FD9CE-0478-49B0-AC95-E83F5E80EF9C/Documents/2011-G3-01.avi'
[0x14296a8] main demux debug: looking for demux module: 49 candidates
[0x14295c8] avi stream debug: found chunk, fourcc: RIFF size:43377260 pos:0
[0x14295c8] avi stream debug: found LIST chunk: 'AVI '
[0x14295c8] avi stream debug: <list 'AVI '>
[0x14295c8] avi stream debug: found chunk, fourcc: LIST size:64648 pos:12
[0x14295c8] avi stream debug: found LIST chunk: 'hdrl'
[0x14295c8] avi stream debug: <list 'hdrl'>
[0x14295c8] avi stream debug: found chunk, fourcc: avih size:56 pos:24
[0x14295c8] avi stream debug: avih: streams:2 flags: HAS_INDEX TRUST_CKTYPE 720x480
[0x14295c8] avi stream debug: found chunk, fourcc: LIST size:32148 pos:88
[0x14295c8] avi stream debug: found LIST chunk: 'strl'
[0x14295c8] avi stream debug: <list 'strl'>
[0x14295c8] avi stream debug: found chunk, fourcc: strh size:56 pos:100
[0x14295c8] avi stream debug: strh: type:vids handler:0x44495658 samplesize:0 15.00fps
[0x14295c8] avi stream debug: found chunk, fourcc: strf size:40 pos:164
[0x14295c8] avi stream debug: strf: video:XVID 720x480 planes:1 24bpp
[0x14295c8] avi stream debug: found chunk, fourcc: indx size:32024 pos:212
[0x14295c8] avi stream debug: indx: type=0 subtype=0 entry=5
[0x14295c8] avi stream debug: </list 'strl'>
[0x14295c8] avi stream debug: found chunk, fourcc: LIST size:32148 pos:32244
[0x14295c8] avi stream debug: found LIST chunk: 'strl'
[0x14295c8] avi stream debug: <list 'strl'>
[0x14295c8] avi stream debug: found chunk, fourcc: strh size:56 pos:32256
[0x14295c8] avi stream debug: strh: type:auds handler:0x00000000 samplesize:1 16000.00fps
[0x14295c8] avi stream debug: found chunk, fourcc: strf size:30 pos:32320
[0x14295c8] avi stream debug: strf: audio:0x0055 channels:2 44100Hz 0bits/sample 125kb/s
[0x14295c8] avi stream debug: found chunk, fourcc: JUNK size:2 pos:32358
[0x14295c8] avi stream debug: found chunk, fourcc: indx size:32024 pos:32368
2012-07-24 19:55:07.636 VLC[14927:707] opening
[0x14295c8] avi stream debug: indx: type=0 subtype=0 entry=1
[0x14295c8] avi stream debug: </list 'strl'>
[0x14295c8] avi stream debug: found chunk, fourcc: LIST size:260 pos:64400
[0x14295c8] avi stream debug: found LIST chunk: 'odml'
[0x14295c8] avi stream debug: <list 'odml'>
[0x14295c8] avi stream debug: found chunk, fourcc: dmlh size:248 pos:64412
[0x14295c8] avi stream debug: dmlh: dwTotalFrames 19382
[0x14295c8] avi stream debug: </list 'odml'>
[0x14295c8] avi stream debug: </list 'hdrl'>
[0x14295c8] avi stream debug: found chunk, fourcc: LIST size:42992104 pos:64668
[0x14295c8] avi stream debug: skipping movi chunk
[0x14295c8] avi stream debug: found chunk, fourcc: idx1 size:320480 pos:43056780
[0x14295c8] avi stream debug: idx1: index entry:20030
[0x14295c8] avi stream debug: </list 'AVI '>
[0x14295c8] avi stream debug: * LIST-root size:43377268 pos:0
[0x14295c8] avi stream debug: + RIFF-AVI size:43377260 pos:0
[0x14295c8] avi stream debug: | + LIST-hdrl size:64648 pos:12
[0x14295c8] avi stream debug: | | + avih size:56 pos:24
[0x14295c8] avi stream debug: | | + LIST-strl size:32148 pos:88
[0x14295c8] avi stream debug: | | | + strh size:56 pos:100
[0x14295c8] avi stream debug: | | | + strf size:40 pos:164
[0x14295c8] avi stream debug: | | | + indx size:32024 pos:212
[0x14295c8] avi stream debug: | | + LIST-strl size:32148 pos:32244
[0x14295c8] avi stream debug: | | | + strh size:56 pos:32256
[0x14295c8] avi stream debug: | | | + strf size:30 pos:32320
[0x14295c8] avi stream debug: | | | + JUNK size:2 pos:32358
[0x14295c8] avi stream debug: | | | + indx size:32024 pos:32368
[0x14295c8] avi stream debug: | | + LIST-odml size:260 pos:64400
[0x14295c8] avi stream debug: | | | + dmlh size:248 pos:64412
[0x14295c8] avi stream debug: | + LIST-movi size:42992104 pos:64668
[0x14295c8] avi stream debug: | + idx1 size:320480 pos:43056780
[0x14296a8] avi demux debug: AVIH: 2 stream, flags HAS_INDEX TRUST_CKTYPE
[0x14296a8] avi demux debug: stream[0] rate:15 scale:1 samplesize:0
[0x14296a8] avi demux debug: stream[0] video(XVID) 720x480 24bpp 15.000000fps
[0x14259e8] main input debug: selecting program id=0
[0x14296a8] avi demux debug: stream[1] rate:16000 scale:1 samplesize:1
[0x14296a8] avi demux debug: stream[1] audio(0x55 - MPEG Audio layer 1/2/3) 2 channels 44100Hz 0bits
[0x14295c8] avi stream debug: found chunk, fourcc: ix00 size:32024 pos:64680
[0x14295c8] avi stream debug: indx: type=1 subtype=0 entry=4000
[0x14296a8] avi demux debug: loading subindex(0x1) 4000 entries
[0x14295c8] avi stream debug: free chunk indx
[0x14295c8] avi stream debug: found chunk, fourcc: ix00 size:32024 pos:13288554
[0x14295c8] avi stream debug: indx: type=1 subtype=0 entry=4000
[0x14296a8] avi demux debug: loading subindex(0x1) 4000 entries
[0x14295c8] avi stream debug: free chunk indx
[0x14295c8] avi stream debug: found chunk, fourcc: ix00 size:32024 pos:26955226
[0x14295c8] avi stream debug: indx: type=1 subtype=0 entry=4000
[0x14296a8] avi demux debug: loading subindex(0x1) 4000 entries
[0x14295c8] avi stream debug: free chunk indx
[0x14295c8] avi stream debug: found chunk, fourcc: ix00 size:32024 pos:32036672
[0x14295c8] avi stream debug: indx: type=1 subtype=0 entry=4000
[0x14296a8] avi demux debug: loading subindex(0x1) 4000 entries
[0x14295c8] avi stream debug: free chunk indx
[0x14295c8] avi stream debug: found chunk, fourcc: ix00 size:32024 pos:37165400
[0x14295c8] avi stream debug: indx: type=1 subtype=0 entry=3382
[0x14296a8] avi demux debug: loading subindex(0x1) 3382 entries

januskopf
New Cone
New Cone
Posts: 2
Joined: 23 Jul 2012 10:50

Re: iOS: MPEG Audio (Version 1, Layer 2) problems

Postby januskopf » 25 Jul 2012 10:07

Hey pengjaycn, after testing some more videos I found out that this audio problem occurs with all videos using "ffmpeg codec (MPEG Audio layer 1/2/3)" to decode the audio. AAC and WMA, for example, seem to work fine, both mono and stereo. Is something wrong with the decoder or does something go wrong when outputting the audio to the iPad's sound mixer...?

Any thoughts, people? If even XViD/MP3 doesn't work correctly, I'm pretty sure a lot of people are encountering this problem. What makes it weird: a colleague of mine tried out VLC in the Cydia store and that one seems to work fine? Did something change under the hood in the very last (GIT) version?

Jean-Baptiste Kempf
Site Administrator
Site Administrator
Posts: 37523
Joined: 22 Jul 2005 15:29
VLC version: 4.0.0-git
Operating System: Linux, Windows, Mac
Location: Cone, France
Contact:

Re: iOS: MPEG Audio (Version 1, Layer 2) problems

Postby Jean-Baptiste Kempf » 27 Jul 2012 00:16

Hm. Yes, can you see if the cydia version has the Mad plugin?
Jean-Baptiste Kempf
http://www.jbkempf.com/ - http://www.jbkempf.com/blog/category/Videolan
VLC media player developer, VideoLAN President and Sites administrator
If you want an answer to your question, just be specific and precise. Don't use Private Messages.

fkuehne
Developer
Developer
Posts: 7264
Joined: 16 Mar 2004 19:37
VLC version: 0.4.6 - present
Operating System: Darwin
Location: Germany
Contact:

Re: iOS: MPEG Audio (Version 1, Layer 2) problems

Postby fkuehne » 27 Jul 2012 04:34

The one on Cydia is 2 years old, so lot's of things changed since then.

We are aware of the issue with the current state. Since we neither distribute nor support VLC for iOS at this point, there is no date until which we are going to fix it. FYI: you can follow it on the bug tracker using ticket 7228.
VideoLAN
Felix Paul Kühne
Medic. VLC developer for appleOS since before you were born.
Blog: https://www.feepk.net

ebeckers
New Cone
New Cone
Posts: 6
Joined: 02 Aug 2012 11:41

Re: iOS: MPEG Audio (Version 1, Layer 2) problems

Postby ebeckers » 02 Aug 2012 11:54

I'm willing to fix this.
Could you perhaps point me in the right direction (which module/sourcefiles) where to fix this ?
Erwin

fkuehne
Developer
Developer
Posts: 7264
Joined: 16 Mar 2004 19:37
VLC version: 0.4.6 - present
Operating System: Darwin
Location: Germany
Contact:

Re: iOS: MPEG Audio (Version 1, Layer 2) problems

Postby fkuehne » 02 Aug 2012 12:34

This is an assembly issue in libavcodec's mp3 decoder. We're on it.
VideoLAN
Felix Paul Kühne
Medic. VLC developer for appleOS since before you were born.
Blog: https://www.feepk.net


Return to “VLC media player for macOS Troubleshooting”

Who is online

Users browsing this forum: No registered users and 12 guests