[vlc-devel] Audio on MacOS X log
Jean-Alexis Montignies
ja at sente.ch
Sun Jan 26 15:10:41 CET 2003
Following my bug report about sound quality on MacOS X internal sound
card and (non random) noise on MOTU 828, here is a log of vlc -vvv.
I inserted a DVD (le tour de -M-) and played it for a few seconds then
switched the output to the MOTU 828 then quitted the application using
the Command-Q shortcut.
You can notice:
1) twice the line [00000132] main audio output debug: filter(s)
'fl32'->'fl32' 52800 Hz->44100 Hz Stereo->Stereo
and no place where 48000->44100 conversion is used.
2) the segmentation fault at the end.
3) Minor problem: the choice of the 44100 Hz format instead of the
48000 Hz supported by the MOTU that would have enhanced quality and
removed the need for a resampler.
Hope this helps, sorry I have no time to debug this the next weeks.
Jean-Alexis
VideoLAN Client 0.5.0-test3 Natalya
[00000000] main root debug: VideoLAN Client - version 0.5.0-test3
Natalya - (c) 1996-2003 VideoLAN
[00000000] main root debug: libvlc was configured with ./configure
--disable-x11 --disable-xvideo --disable-gtk --disable-sdl
--enable-ffmpeg --enable-mad --enable-libdvbpsi --enable-a52
--enable-dvdplay --enable-faad --enable-vorbis --enable-ogg
--with-included-gettext --enable-debug
[00000003] main vlc debug: translation test: code is "C"
[00000000] main root debug: checking builtin modules
[00000000] main root debug: checking plugin modules
[00000000] main root debug: recursively browsing `./modules'
[00000000] main root debug: recursively browsing `/usr/local/lib/vlc'
[00000000] main root debug: recursively browsing `./plugins'
[00000003] main vlc debug: module bank initialized, found 107 modules
[00000003] main vlc debug: opening config file
/Users/lja/Library/Preferences/VLC/vlcrc
[00000003] main vlc debug: CPU has capabilities AltiVec FPU
[00000003] main vlc debug: looking for memcpy module
[00000003] main vlc debug: probing 2 candidates
[00000007] main module debug: using memcpy module "memcpyaltivec"
[00000127] main playlist debug: waiting for thread completion
[00000127] main playlist debug: thread 6770704 (playlist) created at
priority 32 (src/playlist/playlist.c:77)
[00000128] main interface debug: looking for interface module
[00000128] main interface debug: probing 3 candidates
[00000010] main module debug: using interface module "macosx"
[00000128] main interface debug: interface initialized
[00000128] main interface debug: thread 35077328 (manager) created at
priority 32 (src/interface/interface.c:105)
[00000127] main playlist debug: adding playlist item ╴
dvdold:///dev/rdisk1@1,1 ╴
[00000127] main playlist debug: creating new input thread
[00000129] main input: playlist item `dvdold:///dev/rdisk1@1,1'
[00000129] main input debug: waiting for thread completion
[00000129] main input debug: access `dvdold', demux `', name
`/dev/rdisk1 at 1,1'
[00000129] main input debug: looking for access module
[00000129] main input debug: probing 2 candidates
[00000129] dvd input debug: dvd=/dev/rdisk1 raw= title=1 chapter=1
angle=1
[00000129] main input debug: thread 36700256 (input) created at
priority 35 (src/input/input.c:157)
[00000129] dvd input debug: number of titles: 4
[00000129] dvd input debug: title 1 vts_title 1 pgc 1
[00000129] dvd input debug: title first 28962, last 3270612, size
3270613
[00000048] main module debug: using access module "dvd"
[00000128] macosx interface debug: stream has changed, refreshing
interface
[00000129] main input debug: looking for demux module
[00000129] main input debug: probing 2 candidates
[00000129] main input debug: looking for mpeg-system module
[00000129] main input debug: probing 1 candidate
[00000088] main module debug: using mpeg-system module "mpeg_system"
[00000129] main input debug: selecting ES 0xe0
[00000130] main decoder debug: looking for decoder module
[00000130] main decoder debug: probing 11 candidates
[00000089] main module debug: using decoder module "mpeg_video"
[00000130] main decoder debug: thread 36756240 (decoder) created at
priority 31 (src/input/input_dec.c:141)
[00000129] main input debug: selecting ES 0x80bd
[00000131] main decoder debug: looking for decoder module
[00000131] main decoder debug: probing 11 candidates
[00000013] main module debug: using decoder module "a52"
[00000131] main decoder debug: thread 36761536 (decoder) created at
priority 36 (src/input/input_dec.c:141)
[00000049] main module debug: using demux module "dvd"
[00000130] main decoder debug: looking for motion compensation module
[00000130] main decoder debug: probing 2 candidates
[00000006] main module debug: using motion compensation module
"motionaltivec"
[00000130] main decoder debug: looking for idct module
[00000128] macosx interface debug: stream has changed, refreshing
interface
[00000130] main decoder debug: probing 3 candidates
[00000131] main decoder debug: no aout present, spawning one
[00000132] main audio output debug: looking for audio output module
[00000005] main module debug: using idct module "idctaltivec"
[00000132] main audio output debug: probing 4 candidates
[00000132] macosx audio output debug: system has [2] device(s)
[00000132] macosx audio output debug: device [0] has name [Built-in
audio controller]
[00000132] macosx audio output debug: device [0] has [1] streams
[00000132] macosx audio output debug: supported format:
[44100][lpcm][30][4][1][4][2][16]
[00000132] macosx audio output debug: device [1] has name [MOTU 828]
[00000132] macosx audio output debug: device [1] has [2] streams
[00000132] macosx audio output debug: supported format:
[44100][lpcm][30][24][1][24][8][24]
[00000132] macosx audio output debug: supported format:
[48000][lpcm][30][24][1][24][8][24]
[00000132] macosx audio output debug: supported format:
[44100][lpcm][30][6][1][6][2][24]
[00000132] macosx audio output debug: supported format:
[48000][lpcm][30][6][1][6][2][24]
[00000132] macosx audio output debug: getting device [0]
[00000132] macosx audio output debug: using format:
[44100][lpcm][11][8][1][8][2][32]
[00000132] macosx audio output debug: device buffer size: [4096]
[00000132] macosx audio output debug: device buffer frame size set to:
[512]
[00000012] main module debug: using audio output module "macosx"
[00000130] main decoder debug: no usable vout present, spawning one
[00000132] main audio output debug: output 'fl32' 44100 Hz Stereo
frame=1 samples/8 bytes
[00000132] main audio output debug: mixer 'fl32' 44100 Hz Stereo
frame=1 samples/8 bytes
[00000132] main audio output debug: no need for any filter
[00000132] main audio output debug: looking for audio mixer module
[00000132] main audio output debug: probing 3 candidates
[00000115] main module debug: using audio mixer module "trivial_mixer"
[00000132] main audio output debug: looking for audio mixer module
[00000132] main audio output debug: probing 3 candidates
[00000115] main module debug: using audio mixer module "trivial_mixer"
[00000132] main audio output debug: input 'a52 ' 48000 Hz Stereo
frame=1536 samples/896 bytes
[00000134] main video output debug: looking for video output module
[00000132] main audio output debug: filter(s) 'a52 '->'fl32' 48000
Hz->48000 Hz Stereo->Stereo
[00000135] main private debug: looking for audio filter module
[00000135] main private debug: probing 18 candidates
No accelerated IMDCT transform found
[00000015] main module debug: using audio filter module "a52tofloat32"
[00000132] main audio output debug: found a filter for the whole
conversion
[00000132] main audio output debug: filter(s) 'fl32'->'fl32' 52800
Hz->44100 Hz Stereo->Stereo
[00000136] main private debug: looking for audio filter module
[00000136] main private debug: probing 18 candidates
[00000078] main module debug: using audio filter module
"linear_resampler"
[00000132] main audio output debug: found a filter for the whole
conversion
[00000134] main video output debug: probing 4 candidates
[00000011] main module debug: using video output module "macosx"
[00000134] main video output debug: thread 36891856 (video output)
created at priority 31 (src/video_output/video_output.c:330)
[00000134] main video output debug: got 8 direct buffer(s)
[00000134] main video output debug: picture in 720x576, chroma
0x49343230 (I420), aspect ratio 4:3
[00000134] main video output debug: picture out 720x576, chroma
0x49343230 (I420), aspect ratio 4:3
[00000134] main video output debug: direct render, mapping render
pictures 0-6 to system pictures 1-7
[00000130] mpeg_video decoder warning: dts != current_pts (142741)
[00000130] mpeg_video decoder warning: backward_pts != current_pts
(-40000)
[00000134] main video output warning: late picture skipped (363829)
[00000130] mpeg_video decoder warning: stream periodicity changed from
B[1] to B[2]
[00000134] main video output warning: late picture skipped (288063)
[00000134] main video output warning: late picture skipped (248473)
[00000134] main video output warning: late picture skipped (208522)
[00000134] main video output warning: late picture skipped (168542)
[00000134] main video output warning: late picture skipped (128561)
[00000134] main video output warning: late picture skipped (108644)
[00000134] main video output warning: late picture skipped (29101)
[00000132] main audio output warning: buffer is 57037 late, triggering
upsampling
[00000130] mpeg_video decoder warning: dts != current_pts (57056)
[00000130] mpeg_video decoder warning: stream periodicity changed from
B[2] to B[1]
[00000130] mpeg_video decoder warning: stream periodicity changed from
B[1] to B[2]
[00000130] mpeg_video decoder debug: decoded 82/100 pictures
[00000132] main audio output warning: resampling stopped after 5954110
usec
[00000130] mpeg_video decoder debug: decoded 108/108 pictures
[00000134] main video output warning: late picture skipped (11289)
[00000134] main video output warning: late picture skipped (9944)
[00000130] mpeg_video decoder debug: decoded 108/108 pictures
[00000134] main video output warning: late picture skipped (4125)
[00000015] main module debug: unlocking module "a52tofloat32"
[00000078] main module debug: unlocking module "linear_resampler"
[00000115] main module debug: unlocking module "trivial_mixer"
[00000012] main module debug: unlocking module "macosx"
[00000132] main audio output debug: looking for audio output module
[00000132] main audio output debug: probing 4 candidates
[00000132] macosx audio output debug: system has [2] device(s)
[00000132] macosx audio output debug: device [0] has name [Built-in
audio controller]
[00000132] macosx audio output debug: device [0] has [1] streams
[00000132] macosx audio output debug: supported format:
[44100][lpcm][30][4][1][4][2][16]
[00000132] macosx audio output debug: device [1] has name [MOTU 828]
[00000132] macosx audio output debug: device [1] has [2] streams
[00000132] macosx audio output debug: supported format:
[44100][lpcm][30][24][1][24][8][24]
[00000132] macosx audio output debug: supported format:
[48000][lpcm][30][24][1][24][8][24]
[00000132] macosx audio output debug: supported format:
[44100][lpcm][30][6][1][6][2][24]
[00000132] macosx audio output debug: supported format:
[48000][lpcm][30][6][1][6][2][24]
[00000132] macosx audio output debug: getting device [1]
[00000132] macosx audio output debug: using format:
[44100][lpcm][11][32][1][32][8][32]
[00000132] macosx audio output debug: device buffer size: [16384]
[00000132] macosx audio output debug: device buffer frame size set to:
[512]
[00000012] main module debug: using audio output module "macosx"
[00000132] main audio output debug: output 'fl32' 44100 Hz Stereo
frame=1 samples/8 bytes
[00000132] main audio output debug: mixer 'fl32' 44100 Hz Stereo
frame=1 samples/8 bytes
[00000132] main audio output debug: no need for any filter
[00000132] main audio output debug: looking for audio mixer module
[00000132] main audio output debug: probing 3 candidates
[00000115] main module debug: using audio mixer module "trivial_mixer"
[00000132] main audio output debug: input 'a52 ' 48000 Hz Stereo
frame=1536 samples/896 bytes
[00000132] main audio output debug: filter(s) 'a52 '->'fl32' 48000
Hz->48000 Hz Stereo->Stereo
[00000137] main private debug: looking for audio filter module
[00000137] main private debug: probing 18 candidates
No accelerated IMDCT transform found
[00000015] main module debug: using audio filter module "a52tofloat32"
[00000132] main audio output debug: found a filter for the whole
conversion
[00000132] main audio output debug: filter(s) 'fl32'->'fl32' 52800
Hz->44100 Hz Stereo->Stereo
[00000138] main private debug: looking for audio filter module
[00000138] main private debug: probing 18 candidates
[00000078] main module debug: using audio filter module
"linear_resampler"
[00000132] main audio output debug: found a filter for the whole
conversion
[00000132] main audio output warning: PTS is out of range (416933),
dropping buffer
[00000132] main audio output warning: PTS is out of range (386649),
dropping buffer
[00000132] main audio output warning: PTS is out of range (354757),
dropping buffer
[00000132] main audio output warning: PTS is out of range (322806),
dropping buffer
[00000134] main video output warning: late picture skipped (315981)
[00000132] main audio output warning: PTS is out of range (321684),
dropping buffer
[00000132] main audio output warning: PTS is out of range (289872),
dropping buffer
[00000134] main video output warning: late picture skipped (241894)
[00000132] main audio output warning: PTS is out of range (257973),
dropping buffer
[00000132] main audio output warning: PTS is out of range (226148),
dropping buffer
[00000132] main audio output warning: PTS is out of range (194189),
dropping buffer
[00000132] main audio output warning: PTS is out of range (162229),
dropping buffer
[00000132] main audio output warning: PTS is out of range (130267),
dropping buffer
[00000132] main audio output warning: PTS is out of range (104034),
dropping buffer
[00000132] main audio output warning: PTS is out of range (72121),
dropping buffer
[00000132] main audio output warning: PTS is out of range (40161),
dropping buffer
[00000132] main audio output warning: PTS is out of range (15758),
dropping buffer
[00000132] main audio output warning: PTS is out of range (-16109),
dropping buffer
[00000132] main audio output warning: PTS is out of range (-25218),
dropping buffer
[00000132] main audio output debug: audio output is starving (40140),
playing silence
[00000130] mpeg_video decoder debug: decoded 96/108 pictures
[00000130] mpeg_video decoder debug: decoded 108/108 pictures
[00000128] macosx interface debug: removing all playlists
[00000129] main input debug: 243 loops consuming user: 1851, system: 0
[00000129] main input debug: freeing info structures...
[00000129] main input debug: dumping stream ID 0x0 [OK:0/D:8700]
[00000129] main input debug: seekable stream, position:
15845390/6698215424 (0:00:18/2:12:54)
[00000129] main input debug: dumping program 0x1, version 0 (complete)
[00000129] main input debug: dumping program 0x2, version 0 (partial)
[00000130] mpeg_video decoder warning: MPEG2NonIntra coeff is out of
bounds
[00000130] mpeg_video decoder warning: MPEG2NonIntra coeff is out of
bounds
[00000130] mpeg_video decoder debug: 1747 loops among 48 sequence(s)
[00000130] mpeg_video decoder debug: cpu usage (user: 1851, system: 0)
[00000130] mpeg_video decoder debug: read 571 frames/fields (I 48/P
150/B 373)
[00000130] mpeg_video decoder debug: decoded 541 frames/fields (I 48/P
140/B 353)
[00000130] mpeg_video decoder debug: read 0 malformed frames/fields (I
0/P 0/B 0)
[00000130] mpeg_video decoder debug: MPEG-2 stream (720x576), 25.0 pi/s
[00000130] mpeg_video decoder debug: Non-progressive, non-scalable,
matrix_coeff: 5
[00000005] main module debug: unlocking module "idctaltivec"
[00000006] main module debug: unlocking module "motionaltivec"
[00000130] main decoder debug: thread 36756240 joined
(src/input/input_dec.c:180)
[00000130] main decoder debug: killing decoder for 0xe0, fourcc `mpgv',
9 PES in FIFO
[00000089] main module debug: unlocking module "mpeg_video"
[00000015] main module debug: unlocking module "a52tofloat32"
[00000078] main module debug: unlocking module "linear_resampler"
Segmentation fault
**********
Date/Time: 2003-01-26 10:54:40 +0100
OS Version: 10.2.3 (Build 6G30)
Host: eushmunush.lan
Command: vlc
PID: 648
Exception: EXC_BAD_ACCESS (0x0001)
Codes: KERN_INVALID_ADDRESS (0x0001) at 0x0240a008
Thread 0:
#0 0x90073c48 in mach_msg_trap
#1 0x90005f90 in mach_msg
#2 0x90148b10 in __CFRunLoopRun
#3 0x90180fe4 in CFRunLoopRunSpecific
#4 0x969a36a0 in RunCurrentEventLoopInMode
#5 0x969b34c8 in ReceiveNextEventCommon
#6 0x969da584 in BlockUntilNextEventMatchingListInMode
#7 0x9308d8ac in _DPSNextEvent
#8 0x9309fb04 in -[NSApplication
nextEventMatchingMask:untilDate:inMode:dequeue:]
#9 0x930b15e4 in -[NSApplication run]
#10 0x00005ef4 in intf_RunThread (interface.c:113)
#11 0x00005374 in VLC_AddIntf (libvlc.c:572)
#12 0x00002914 in main (vlc.c:105)
#13 0x000025c4 in _start (crt.c:267)
#14 0x00002444 in start
Thread 1:
#0 0x90006ae8 in clock_sleep_trap
#1 0x900067ec in nanosleep
#2 0x0001fb20 in msleep (mtime.c:230)
#3 0x000081f4 in RunThread (playlist.c:464)
#4 0x90020d48 in _pthread_body
Thread 2:
#0 0x90034848 in semaphore_wait_trap
#1 0x90034784 in pthread_join
#2 0x0002223c in __vlc_thread_join (threads.c:754)
#3 0x00006264 in playlist_Destroy (playlist.c:101)
#4 0x00043658 in -[VLCMain terminate] (intf.m:546)
#5 0x00043594 in -[VLCMain manage] (intf.m:514)
#6 0x9081cd38 in forkThreadForFunction
#7 0x90020d48 in _pthread_body
Thread 3:
#0 0x9003eaa8 in semaphore_wait_signal_trap
#1 0x9003e8c4 in _pthread_cond_wait
#2 0x90824180 in -[NSConditionLock lockWhenCondition:]
#3 0x93081388 in -[NSUIHeartBeat _heartBeatThread:]
#4 0x9081cd38 in forkThreadForFunction
#5 0x90020d48 in _pthread_body
Thread 4:
#0 0x90034848 in semaphore_wait_trap
#1 0x90034784 in pthread_join
#2 0x0002223c in __vlc_thread_join (threads.c:754)
#3 0x0000e01c in input_EndDecoder (input_dec.c:425)
#4 0x0000f324 in input_EndStream (input_programs.c:517)
#5 0x00008f80 in RunThread (input.c:560)
#6 0x90020d48 in _pthread_body
Thread 5:
#0 0x90034848 in semaphore_wait_trap
#1 0x90009d38 in pthread_mutex_lock
#2 0x94d85308 in CAGuard::Lock()
#3 0x94d88cac in XThreadedCommand::Execute()
#4 0x94d8c6a8 in XBasicDevice::ProcessCommand(CACommand*, bool)
#5 0x94d960f4 in AudioDeviceStop
#6 0x0003ed40 in CloseAudio__macosx (aout.m:407)
#7 0x0002157c in __module_Unneed (modules.c:531)
#8 0x0001ba94 in aout_OutputDelete (output.c:217)
#9 0x0001862c in aout_DecDelete (dec.c:202)
#10 0x006f8e9c in RunDecoder (a52.c:266)
#11 0x90020d48 in _pthread_body
Thread 6:
#0 0x90073c48 in mach_msg_trap
#1 0x90005f90 in mach_msg
#2 0x90148b10 in __CFRunLoopRun
#3 0x90180fe4 in CFRunLoopRunSpecific
#4 0x94d9c1c0 in HALRunLoop::OwnThread(void*)
#5 0x94d911b0 in CAPThread::Entry(CAPThread*)
#6 0x90020d48 in _pthread_body
Thread 7:
#0 0x90006ae8 in clock_sleep_trap
#1 0x900067ec in nanosleep
#2 0x0001fb20 in msleep (mtime.c:230)
#3 0x000149a4 in RunThread (video_output.c:97)
#4 0x90020d48 in _pthread_body
Thread 8 Crashed:
#0 0x90074560 in memmove
#1 0x0003eeec in IOCallback (aout.m:467)
#2 0x94d84174 in XBasicDevice::CallIOProcs(AudioTimeStamp const*,
AudioTimeStamp const*, AudioTimeStamp const*)
#3 0x94d82ac8 in XThreadedDevice::HandleIO(AudioTimeStamp const&,
bool)
#4 0x94d82534 in XThreadedDevice::IOThread()
#5 0x94d95a24 in XThreadedDevice::IOThreadEntry(void*)
#6 0x94d911b0 in CAPThread::Entry(CAPThread*)
#7 0x90020d48 in _pthread_body
PPC Thread State:
srr0: 0x90074560 srr1: 0x0200f030 vrsave: 0xff000000
xer: 0x00000000 lr: 0x0003eeec ctr: 0x00000031 mq: 0x00000000
r0: 0x00000000 r1: 0xf01b29e0 r2: 0x00000000 r3: 0x01174000
r4: 0x02409ff8 r5: 0x00004000 r6: 0x00000010 r7: 0x00000020
r8: 0x00000030 r9: 0x00004000 r10: 0x00000060 r11: 0x00000080
r12: 0x011773c0 r13: 0x00000000 r14: 0x00000000 r15: 0x00000000
r16: 0x00000000 r17: 0x00000000 r18: 0x00000000 r19: 0xa4d81eec
r20: 0xa4d81eec r21: 0x00000000 r22: 0xf01b2d20 r23: 0xf01b2b40
r24: 0xf01b2b80 r25: 0x00000000 r26: 0x00000000 r27: 0x02316630
r28: 0x0003ee54 r29: 0x02920c30 r30: 0x007e0cf0 r31: 0x02406c10
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: text/enriched
Size: 20948 bytes
Desc: not available
URL: <http://mailman.videolan.org/pipermail/vlc-devel/attachments/20030126/799f5b13/attachment.bin>
More information about the vlc-devel
mailing list