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
-- 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 --