Bug#719329: vlc: Crackling sound at the begining of anything that VLC plays.
Ross Vandegrift
ross at kallisti.us
Mon May 12 19:12:50 UTC 2014
Package: vlc
Version: 2.0.3-5
Followup-For: Bug #719329
Hello,
This problem is reproducible on my laptop with vlc 2.0.3-5. Like the original
poster, vlc is the only software affected. Unlike the original poster, the
crackling only lasts a few seconds. I've attached the log from "vlc
--extraintf=logger --log-verbose=2147483647 --logfile=vlc.log test.ogg"
I tried vlc 2.1.2-2+b3 in a jessie chroot. There is a new kind of artifact at
the beginning of playback, but this problem is gone.
Ross
-- System Information:
Debian Release: 7.5
APT prefers stable
APT policy: (500, 'stable'), (50, 'unstable'), (40, 'experimental')
Architecture: i386 (x86_64)
Kernel: Linux 3.13-0.bpo.1-amd64 (SMP w/4 CPU cores)
Locale: LANG=en_US.UTF-8, LC_CTYPE=en_US.UTF-8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/dash
Versions of packages vlc depends on:
ii dpkg 1.16.14
ii fonts-freefont-ttf 20120503-1
ii libaa1 1.4p5-40
ii libavcodec53 6:0.8.10-1
ii libavutil51 6:0.8.10-1
ii libc6 2.13-38+deb7u1
ii libcaca0 0.99.beta18-1
ii libfreetype6 2.4.9-1.1
ii libfribidi0 0.19.2-3
ii libgcc1 1:4.7.2-5
ii libgl1-mesa-glx [libgl1] 8.0.5-4+deb7u2
ii libice6 2:1.0.8-2
ii libqtcore4 4:4.8.2+dfsg-11
ii libqtgui4 4:4.8.2+dfsg-11
ii libsdl-image1.2 1.2.12-2
ii libsdl1.2debian 1.2.15-5
ii libsm6 2:1.2.1-2
ii libstdc++6 4.7.2-5
ii libtar0 1.2.16-1+deb7u2
ii libva-x11-1 1.0.15-4
ii libva1 1.0.15-4
ii libvlccore5 2.0.3-5
ii libx11-6 2:1.5.0-1+deb7u1
ii libxcb-composite0 1.8.1-2+deb7u1
ii libxcb-keysyms1 0.3.9-1
ii libxcb-randr0 1.8.1-2+deb7u1
ii libxcb-render0 1.8.1-2+deb7u1
ii libxcb-shape0 1.8.1-2+deb7u1
ii libxcb-shm0 1.8.1-2+deb7u1
ii libxcb-xfixes0 1.8.1-2+deb7u1
ii libxcb-xv0 1.8.1-2+deb7u1
ii libxcb1 1.8.1-2+deb7u1
ii libxext6 2:1.3.1-2+deb7u1
ii libxinerama1 2:1.1.2-1+deb7u1
ii libxpm4 1:3.5.10-1
ii vlc-nox 2.0.3-5
ii zlib1g 1:1.2.7.dfsg-13
Versions of packages vlc recommends:
ii vlc-plugin-notify 2.0.3-5
ii vlc-plugin-pulse 2.0.3-5
ii xdg-utils 1.1.0~rc1+git20111210-6
Versions of packages vlc suggests:
pn videolan-doc <none>
Versions of packages vlc-nox depends on:
ii dpkg 1.16.14
ii liba52-0.7.4 0.7.4-16
ii libasound2 1.0.25-4
ii libass4 0.10.0-3
ii libavahi-client3 0.6.31-2
ii libavahi-common3 0.6.31-2
ii libavc1394-0 0.5.4-2
ii libavcodec53 6:0.8.10-1
ii libavformat53 6:0.8.10-1
ii libavutil51 6:0.8.10-1
ii libbluray1 1:0.2.2-1
ii libc6 2.13-38+deb7u1
ii libcddb2 1.3.2-3
ii libcdio13 0.83-4
ii libcrystalhd3 1:0.0~git20110715.fdd2f19-9
ii libdbus-1-3 1.6.8-1+deb7u1
ii libdc1394-22 2.2.0-2
ii libdca0 0.0.5-5
ii libdirac-decoder0 1.0.2-6
ii libdirac-encoder0 1.0.2-6
ii libdirectfb-1.2-9 1.2.10.0-5
ii libdvbpsi7 0.2.2-1
ii libdvdnav4 4.2.0+20120524-2
ii libdvdread4 4.2.0+20120521-2
ii libebml3 1.2.2-2
ii libfaad2 2.7-8
ii libflac8 1.2.1-6
ii libfontconfig1 2.9.0-7.1
ii libfreetype6 2.4.9-1.1
ii libfribidi0 0.19.2-3
ii libgcc1 1:4.7.2-5
ii libgcrypt11 1.5.0-5+deb7u1
ii libgnutls26 2.12.20-8+deb7u1
ii libgpg-error0 1.10-3.1
ii libiso9660-8 0.83-4
ii libkate1 0.4.1-1
ii liblircclient0 0.9.0~pre1-1
ii liblua5.1-0 5.1.5-4
ii libmad0 0.15.1b-7
ii libmatroska5 1.3.0-2
ii libmodplug1 1:0.8.8.4-3+deb7u1+git20130828
ii libmpcdec6 2:0.1~r459-4
ii libmpeg2-4 0.4.1-3
ii libmtp9 1.1.3-35-g0ece104-5
ii libncursesw5 5.9-10
ii libogg0 1.3.0-4
ii libpng12-0 1.2.49-1
ii libpostproc52 6:0.8.10-1
ii libproxy0 0.3.1-6
ii libraw1394-11 2.0.9-1
ii libresid-builder0c2a 2.1.1-14
ii libsamplerate0 0.1.8-5
ii libschroedinger-1.0-0 1.0.11-2
ii libshout3 2.2.2-8
ii libsidplay2 2.1.1-14
ii libsmbclient 2:3.6.6-6+deb7u3
ii libspeex1 1.2~rc1-7
ii libspeexdsp1 1.2~rc1-7
ii libstdc++6 4.7.2-5
ii libswscale2 6:0.8.10-1
ii libtag1c2a 1.7.2-1
ii libtheora0 1.1.1+dfsg.1-3.1
ii libtinfo5 5.9-10
ii libtwolame0 0.3.13-1
ii libudev0 175-7.2
ii libupnp6 1:1.6.17-1.2
ii libusb-1.0-0 2:1.0.11-1
ii libv4l-0 0.8.8-3
ii libvcdinfo0 0.7.24+dfsg-0.1
ii libvlc5 2.0.3-5
ii libvlccore5 2.0.3-5
ii libvorbis0a 1.3.2-1.3
ii libvorbisenc2 1.3.2-1.3
ii libx264-123 2:0.123.2189+git35cf912-1
ii libxml2 2.8.0+dfsg1-7+nmu3
ii libzvbi0 0.2.33-6
ii zlib1g 1:1.2.7.dfsg-13
Versions of packages libvlc5 depends on:
ii dpkg 1.16.14
ii libc6 2.13-38+deb7u1
ii libvlccore5 2.0.3-5
Versions of packages libvlccore5 depends on:
ii dpkg 1.16.14
ii libc6 2.13-38+deb7u1
ii libdbus-1-3 1.6.8-1+deb7u1
ii vlc-data 2.0.3-5
Versions of packages vlc is related to:
pn libavutil50 <none>
ii libavutil51 6:0.8.10-1
-- no debconf information
-------------- next part --------------
?-- logger module started --
main debug: using interface module "logger"
main debug: TIMER module_need() : 0.289 ms - Total 0.289 ms / 1 intvls (Avg 0.289 ms)
main debug: looking for interface module: 1 candidate
main debug: using interface module "hotkeys"
main debug: TIMER module_need() : 0.173 ms - Total 0.173 ms / 1 intvls (Avg 0.173 ms)
main debug: looking for interface module: 1 candidate
dbus: listening on dbus as: org.mpris.MediaPlayer2.vlc
main debug: playlist threads correctly activated
dbus debug: Adding dbus watch on fd 7
dbus debug: Watching fd 7 for writeability
dbus debug: Adding dbus watch on fd 7
dbus debug: Watching fd 7 for readability
main debug: using interface module "dbus"
main debug: rebuilding array of current - root Playlist
main debug: rebuild done - 0 items, index -1
main debug: TIMER module_need() : 1.178 ms - Total 1.178 ms / 1 intvls (Avg 1.178 ms)
dbus debug: 8 ?s elapsed since last wakeup
dbus debug: Sleeping until something happens
main debug: looking for interface module: 1 candidate
main debug: using interface module "inhibit"
main debug: TIMER module_need() : 0.700 ms - Total 0.700 ms / 1 intvls (Avg 0.700 ms)
main debug: adding item `test.ogg' ( file:///home/ross/test.ogg )
dbus debug: Got a VLC event on playlist-item-append. The main loop needs to wake up in order to process it
dbus debug: Sending wakeup signal to the main loop
dbus debug: the main loop has been woken up
dbus debug: Removing a byte from the self-pipe
main debug: Creating an input for 'test.ogg'
dbus debug: Nothing happened on fd 7
dbus debug: Dispatching incoming dbus message
main debug: looking for interface module: 1 candidate
dbus debug: Dispatching incoming dbus message
dbus debug: DBus dispatch status changed to COMPLETE.
dbus debug: 1111 ?s elapsed since last wakeup
dbus debug: Sleeping until something happens
main debug: using interface module "globalhotkeys"
main debug: TIMER module_need() : 0.683 ms - Total 0.683 ms / 1 intvls (Avg 0.683 ms)
main: Running vlc with the default interface. Use 'cvlc' to use vlc without interface.
main debug: no fetch required for Kids On The Street (art currently (null))
main debug: looking for interface module: 6 candidates
main debug: looking for extension module: 1 candidate
lua debug: Opening Lua Extension module
lua debug: Trying Lua scripts in /home/ross/.local/share/vlc/lua/extensions
lua debug: Trying Lua scripts in /usr/lib/vlc/lua/extensions
lua debug: Trying Lua scripts in /usr/share/vlc/lua/extensions
main debug: using extension module "lua"
main debug: TIMER module_need() : 0.188 ms - Total 0.188 ms / 1 intvls (Avg 0.188 ms)
main debug: looking for services probe module: 10 candidates
main debug: no services probe module matching "any" could be loaded
main debug: TIMER module_need() : 3.527 ms - Total 3.527 ms / 1 intvls (Avg 3.527 ms)
qt4 debug: Sorting by column -1, order 0
qt4 debug: Sorting by column -1, order 0
qt4 debug: Sorting by column -1, order 0
qt4 debug: Normal PL/ML or SD
qt4 debug: Normal PL/ML or SD
main debug: using interface module "qt4"
main debug: TIMER module_need() : 108.294 ms - Total 108.294 ms / 1 intvls (Avg 108.294 ms)
main debug: rebuilding array of current - root Playlist
main debug: rebuild done - 1 items, index -1
main debug: processing request item: null, node: Playlist, skip: 0
main debug: starting playback of the new playlist item
main debug: resyncing on Kids On The Street
main debug: Kids On The Street is at 0
main debug: creating new input thread
main debug: Creating an input for 'Kids On The Street'
main debug: using timeshift granularity of 50 MiB, in path '/tmp'
main debug: `file:///home/ross/test.ogg' gives access `file' demux `' path `/home/ross/test.ogg'
main debug: creating demux: access='file' demux='' location='/home/ross/test.ogg' file='/home/ross/test.ogg'
main debug: looking for access_demux module: 3 candidates
main debug: no access_demux module matching "file" could be loaded
main debug: TIMER module_need() : 0.149 ms - Total 0.149 ms / 1 intvls (Avg 0.149 ms)
main debug: creating access 'file' location='/home/ross/test.ogg', path='/home/ross/test.ogg'
main debug: looking for access module: 2 candidates
filesystem debug: opening file `/home/ross/test.ogg'
main debug: using access module "filesystem"
main debug: TIMER module_need() : 0.143 ms - Total 0.143 ms / 1 intvls (Avg 0.143 ms)
main debug: Using stream method for AStream*
main debug: starting pre-buffering
main debug: received first data after 0 ms
main debug: pre-buffering done 1024 bytes in 0s - 40000 KiB/s
main debug: looking for stream_filter module: 7 candidates
main debug: no stream_filter module matching "any" could be loaded
main debug: TIMER module_need() : 0.098 ms - Total 0.098 ms / 1 intvls (Avg 0.098 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.106 ms - Total 0.106 ms / 1 intvls (Avg 0.106 ms)
main debug: creating demux: access='file' demux='' location='/home/ross/test.ogg' file='/home/ross/test.ogg'
main debug: looking for demux module: 54 candidates
main debug: using demux module "ogg"
main debug: TIMER module_need() : 0.121 ms - Total 0.121 ms / 1 intvls (Avg 0.121 ms)
main debug: looking for a subtitle file in /home/ross/
dbus debug: Got a VLC event on item-current. The main loop needs to wake up in order to process it
dbus debug: Sending wakeup signal to the main loop
dbus debug: the main loop has been woken up
dbus debug: Removing a byte from the self-pipe
dbus debug: Nothing happened on fd 7
dbus debug: 110832 ?s elapsed since last wakeup
dbus debug: Sleeping until something happens
main debug: looking for meta reader module: 2 candidates
qt4 debug: IM: Setting an input
main debug: using meta reader module "taglib"
main debug: TIMER module_need() : 0.401 ms - Total 0.401 ms / 1 intvls (Avg 0.401 ms)
main debug: removing module "taglib"
dbus debug: Got a VLC event on intf-event. The main loop needs to wake up in order to process it
dbus debug: Sending wakeup signal to the main loop
dbus debug: the main loop has been woken up
dbus debug: Removing a byte from the self-pipe
main debug: `file:///home/ross/test.ogg' successfully opened
dbus debug: Got a VLC event on intf-event. The main loop needs to wake up in order to process it
dbus debug: Sending wakeup signal to the main loop
ogg debug: File length is 2764680 bytes
dbus debug: Nothing happened on fd 7
dbus debug: 745 ?s elapsed since last wakeup
dbus debug: Sleeping until something happens
dbus debug: the main loop has been woken up
ogg debug: found vorbis header
dbus debug: Removing a byte from the self-pipe
main debug: selecting program id=0
dbus debug: Nothing happened on fd 7
dbus debug: Got a VLC event on intf-event. The main loop needs to wake up in order to process it
dbus debug: Sending wakeup signal to the main loop
dbus debug: 194 ?s elapsed since last wakeup
dbus debug: Sleeping until something happens
dbus debug: the main loop has been woken up
dbus debug: Removing a byte from the self-pipe
main debug: looking for decoder module: 30 candidates
dbus debug: Nothing happened on fd 7
dbus debug: 246 ?s elapsed since last wakeup
dbus debug: Sleeping until something happens
main debug: using decoder module "vorbis"
main debug: TIMER module_need() : 4.134 ms - Total 4.134 ms / 1 intvls (Avg 4.134 ms)
ogg debug: beginning of a group of logical streams
main error: ES_OUT_RESET_PCR called
main debug: removing module "vorbis"
main debug: killing decoder fourcc `vorb', 0 PES in FIFO
main debug: looking for decoder module: 30 candidates
main debug: using decoder module "vorbis"
main debug: TIMER module_need() : 0.279 ms - Total 0.279 ms / 1 intvls (Avg 0.279 ms)
vorbis debug: channels:2 samplerate:44100 bitrate:112001
main debug: Buffering 0%
dbus debug: Got a VLC event on intf-event. The main loop needs to wake up in order to process it
dbus debug: Sending wakeup signal to the main loop
dbus debug: the main loop has been woken up
dbus debug: Removing a byte from the self-pipe
main debug: Stream buffering done (477 ms in 0 ms)
dbus debug: Nothing happened on fd 7
dbus debug: 11814 ?s elapsed since last wakeup
dbus debug: Sleeping until something happens
main debug: creating audio output
main debug: looking for audio output module: 3 candidates
pulse debug: using stereo channel map
pulse debug: using library version 2.0.0
pulse debug: (compiled with version 2.0.0, protocol 26)
pulse debug: connected locally to /tmp/pulse-SAxJhTvLvVYf/native as client #35
pulse debug: using protocol 26, server protocol 26
pulse debug: using buffer metrics: maxlength=4194304, tlength=14296, prebuf=0, minreq=7056
pulse debug: connected to sink 0: alsa_output.pci-0000_00_1b.0.analog-stereo
main debug: using audio output module "pulse"
main debug: TIMER module_need() : 12.963 ms - Total 12.963 ms / 1 intvls (Avg 12.963 ms)
main debug: output 'f32l' 44100 Hz Stereo frame=1 samples/8 bytes
main debug: mixer 'f32l' 44100 Hz Stereo frame=1 samples/8 bytes
main debug: filter(s) 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo
main debug: conversion pipeline completed
main debug: looking for audio mixer module: 2 candidates
pulse debug: listing sink alsa_output.pci-0000_00_1b.0.analog-stereo (0): Built-in Audio Analog Stereo
pulse debug: base volume: 65536
main debug: using audio mixer module "float32_mixer"
main debug: TIMER module_need() : 0.474 ms - Total 0.474 ms / 1 intvls (Avg 0.474 ms)
main debug: input 'f32l' 44100 Hz Stereo frame=1 samples/8 bytes
main debug: looking for audio filter module: 1 candidate
scaletempo debug: format: 44100 rate, 2 nch, 4 bps, fl32
scaletempo debug: params: 30 stride, 0.200 overlap, 14 search
scaletempo debug: 1.000 scale, 1323.000 stride_in, 1323 stride_out, 1059 standing, 264 overlap, 617 search, 2204 queue, fl32 mode
main debug: using audio filter module "scaletempo"
main debug: TIMER module_need() : 0.368 ms - Total 0.368 ms / 1 intvls (Avg 0.368 ms)
main debug: filter(s) 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo
main debug: conversion pipeline completed
main debug: filter(s) 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo
main debug: conversion pipeline completed
main debug: filter(s) 'f32l'->'f32l' 48510 Hz->44100 Hz Stereo->Stereo
main debug: looking for audio filter module: 14 candidates
main debug: using audio filter module "samplerate"
main debug: TIMER module_need() : 0.955 ms - Total 0.955 ms / 1 intvls (Avg 0.955 ms)
main debug: conversion pipeline completed
main debug: End of audio preroll
main debug: Decoder buffering done in 16 ms
main warning: PTS is out of range (-9984), dropping buffer
main warning: PTS is out of range (-23025), dropping buffer
pulse debug: deferring start (68702 us)
pulse debug: starting deferred
pulse debug: suspended
pulse debug: suspended
pulse debug: missing latency from input
pulse debug: missing latency from input
pulse debug: missing latency from input
pulse debug: listing sink alsa_output.pci-0000_00_1b.0.analog-stereo (0): Built-in Audio Analog Stereo
pulse debug: missing latency from input
pulse warning: too late by 96955 us
pulse debug: changed sample rate to 44186 Hz
pulse warning: too late by 177017 us
pulse debug: changed sample rate to 44229 Hz
pulse debug: started
pulse warning: too late by 316909 us
pulse debug: changed sample rate to 44272 Hz
pulse warning: too late by 316365 us
pulse debug: changed sample rate to 44315 Hz
pulse warning: too late by 315473 us
pulse debug: changed sample rate to 44358 Hz
pulse warning: too late by 314693 us
pulse debug: changed sample rate to 44401 Hz
pulse warning: too late by 311703 us
pulse debug: changed sample rate to 44444 Hz
pulse warning: too late by 310588 us
pulse debug: changed sample rate to 44487 Hz
pulse warning: too late by 306535 us
pulse debug: changed sample rate to 44530 Hz
pulse warning: too late by 300527 us
pulse debug: changed sample rate to 44573 Hz
pulse warning: too late by 299486 us
pulse debug: changed sample rate to 44616 Hz
pulse warning: too late by 298626 us
pulse debug: changed sample rate to 44659 Hz
pulse warning: too late by 297625 us
pulse debug: changed sample rate to 44702 Hz
pulse warning: too late by 296891 us
pulse debug: changed sample rate to 44745 Hz
pulse warning: too late by 293970 us
pulse debug: changed sample rate to 44788 Hz
pulse warning: too late by 286522 us
pulse debug: changed sample rate to 44831 Hz
pulse warning: too late by 276149 us
pulse debug: changed sample rate to 44874 Hz
main debug: exiting
main debug: deactivating the playlist
main debug: deactivating the playlist
main debug: incoming request - stopping current input
main debug: dying input
main debug: control: stopping input
dbus debug: Got a VLC event on intf-event. The main loop needs to wake up in order to process it
dbus debug: Sending wakeup signal to the main loop
dbus debug: the main loop has been woken up
dbus debug: Removing a byte from the self-pipe
main debug: dying input
main debug: removing module "vorbis"
dbus debug: Nothing happened on fd 7
dbus debug: 3157589 ?s elapsed since last wakeup
dbus debug: Sleeping until something happens
main debug: killing decoder fourcc `vorb', 0 PES in FIFO
main debug: removing module "scaletempo"
main debug: removing module "samplerate"
main debug: removing module "pulse"
main debug: removing module "float32_mixer"
main debug: releasing audio output
main debug: removing module "ogg"
main debug: Program doesn't contain anymore ES
main debug: removing module "stream_filter_record"
main debug: removing module "filesystem"
main debug: dead input
main debug: saving Media Library to file /home/ross/.local/share/vlc/ml.xspf
main debug: looking for playlist export module: 1 candidate
qt4 debug: IM: Deleting the input
main debug: using playlist export module "export"
main debug: TIMER module_need() : 0.373 ms - Total 0.373 ms / 1 intvls (Avg 0.373 ms)
main debug: removing module "export"
main debug: playlist correctly deactivated
main debug: removing all services discovery tasks
main debug: removing all interfaces
main debug: removing module "qt4"
qt4 debug: requesting exit...
qt4 debug: waiting for UI thread...
qt4 debug: QApp exec() finished
qt4 debug: Video is not needed anymore
qt4 debug: Killing extension dialog provider
qt4 debug: ExtensionsDialogProvider is quitting...
main debug: removing module "lua"
lua debug: Deactivating all loaded extensions
lua debug: All extensions are now deactivated
qt4 debug: Playlist Destroyed
main debug: removing module "globalhotkeys"
main debug: removing module "inhibit"
main debug: removing module "dbus"
main debug: TIMER input launching for 'Kids On The Street' : 2.352 ms - Total 2.352 ms / 1 intvls (Avg 2.352 ms)
dbus debug: Removing dbus watch on fd 7
dbus debug: Removing dbus watch on fd 7
dbus debug: DBus dispatch status changed to DATA_REMAINS.
main debug: removing module "hotkeys"
main debug: removing module "logger"
-- logger module stopped --
More information about the pkg-multimedia-maintainers
mailing list