Audio glitch (underflow) when starting playback with a negative audio delay
Reproduced with
./vlc --play-and-exit -I dummy -V dummy -vv 'mock://audio_track_count=1;video_track_count=1;length=1000000000000000000000000000000000000000;can_control_pace=false' --audio-desync=-3000
╭─janniaux@unidan-tower ~/Projects/videolabs/vlc/build-asan ‹master*›
╰─$ ./vlc --play-and-exit -I dummy -V dummy -vv 'mock://audio_track_count=1;video_track_count=1;length=1000000000000000000000000000000000000000;can_control_pace=false' --audio-desync=-3000
VLC media player 4.0.0-dev Otto Chriek (revision 4.0.0-dev-22915-g3cb6645b94)
[000060e000000120] main libvlc debug: VLC media player - 4.0.0-dev Otto Chriek
[000060e000000120] main libvlc debug: Copyright © 1996-2023 the VideoLAN team
[000060e000000120] main libvlc debug: revision 4.0.0-dev-22915-g3cb6645b94
[000060e000000120] main libvlc debug: configured with ../configure '--with-sanitizer=address,undefined' 'LDFLAGS=-lasan -lubsan' '--disable-live555'
[000060e000000120] main libvlc debug: searching plug-in modules
[000060e000000120] main libvlc debug: loading plugins cache file /home/janniaux/Projects/videolabs/vlc/build-asan/modules/plugins/plugins.dat
[000060e000000120] main libvlc warning: cannot read /home/janniaux/Projects/videolabs/vlc/build-asan/modules/plugins/plugins.dat: No such file or directory
[000060e000000120] main libvlc debug: recursively browsing `/home/janniaux/Projects/videolabs/vlc/build-asan/modules/plugins'
[000060e000000120] main libvlc debug: loading plugins cache file /home/janniaux/Projects/videolabs/vlc/build-asan/modules/plugins.dat
[000060e000000120] main libvlc debug: recursively browsing `/home/janniaux/Projects/videolabs/vlc/build-asan/modules'
[000060e000000120] main libvlc debug: plug-ins loaded: 611 modules
[000060e000000120] main libvlc debug: opening config file (/home/janniaux/.config/vlc/vlcrc)
[00007ffc1d0c2d80] main generic debug: looking for logger module matching "any": 4 candidates
[00007ffc1d0c2d80] main generic debug: using logger module "console"
[00007ffc1d0c2d00] main generic debug: looking for tracer module matching "none": 0 candidates
[00007ffc1d0c2d00] main generic debug: no tracer modules matched with name none
[000060e000000120] main libvlc debug: translation test: code is "C"
[00007ffc1d0c2cf0] main generic debug: looking for keystore module matching "memory": 1 candidates
[00007ffc1d0c2cf0] main generic debug: using keystore module "memory"
[000060e000000120] main libvlc debug: CPU has capabilities SSE2 SSE3 SSSE3 SSE4.1 AVX AVX2 FPU
[00007ffc1d0c2d20] main generic debug: looking for medialibrary module matching "any": 1 candidates
[000060b000078800] medialibrary medialibrary debug: Opening medialibrary from /home/janniaux/.local/share/vlc/ml/ml.db, db at /home/janniaux/.local/share/vlc/ml/mlstorage/
[00007ffc1d0c2d20] main generic debug: using medialibrary module "medialibrary"
[0000617000004680] main player debug: creating audio output
[00007ffc1d0c2470] main generic debug: looking for audio output module matching "any": 4 candidates
[0000612000002d40] vlcpulse audio output debug: using library version 16.1.0
[0000612000002d40] vlcpulse audio output debug: (compiled with version 16.1.0, protocol 35)
[0000612000002d40] vlcpulse audio output debug: connected locally to /run/user/1000/pulse/native as client #675
[0000612000002d40] vlcpulse audio output debug: using protocol 35, server protocol 35
[0000612000002d40] pulse audio output debug: adding sink 284: alsa_output.pci-0000_0c_00.4.iec958-stereo (Starship/Matisse HD Audio Controller Digital Stereo (IEC958))
[0000612000002d40] pulse audio output debug: adding sink 424: raop-sink-2127-175 (Janni's TV)
[0000612000002d40] pulse audio output debug: adding sink 431: raop-sink-2127-173 (Janni's TV)
[0000612000002d40] pulse audio output debug: adding sink 438: raop-sink-2127-174 (Janni's TV)
[0000612000002d40] pulse audio output debug: adding sink 564: alsa_output.pci-0000_0a_00.1.hdmi-stereo-extra3.2 (Ellesmere HDMI Audio [Radeon RX 470/480 / 570/580/590] Digital Stereo (HDMI 4))
[00007ffc1d0c2470] main generic debug: using audio output module "pulse"
[0000617000004680] main player debug: keeping audio output
[00007ffc1d0c2980] main generic debug: looking for interface module matching "dbus,none": 1 candidates
[000060600002e640] dbus interface debug: listening on dbus as: org.mpris.MediaPlayer2.vlc
[00007ffc1d0c2980] main generic debug: using interface module "dbus"
[00007ffc1d0c2e90] main generic debug: looking for interface module matching "hotkeys": 1 candidates
[00007ffc1d0c2e90] main generic debug: using interface module "hotkeys"
[00007ffc1d0c2e90] main generic debug: looking for interface module matching "xcb_hotkeys": 1 candidates
[00007ffc1d0c2e90] main generic debug: using interface module "xcb_hotkeys"
[00007ffc1d0c2e90] main generic debug: looking for interface module matching "dummy": 1 candidates
[0000606000030260] dummy interface: using the dummy interface module...
[00007ffc1d0c2e90] main generic debug: using interface module "dummy"
[000061e000003080] main input debug: Creating an input for 'mock://'
[000060b000078800] medialibrary medialibrary debug: MediaLibrary successfully initialized
[00007ffc1d0c1e10] main generic debug: looking for services probe module matching "any": 11 candidates
[00007ffc1d0c1e10] main generic debug: no services probe modules matched with name any
[00007ffc1d0c1fd0] main generic debug: looking for services_discovery module matching "sap": 1 candidates
[00007ffc1d0c1fd0] main generic debug: using services_discovery module "sap"
[00007ffc1d0c1fd0] main generic debug: looking for services_discovery module matching "microdns": 1 candidates
[00006080000bcfa0] microdns services discovery debug: mDNS: listening to _ftp._tcp.local service
[00006080000bcfa0] microdns services discovery debug: mDNS: listening to _smb._tcp.local service
[00006080000bcfa0] microdns services discovery debug: mDNS: listening to _nfs._tcp.local service
[00006080000bcfa0] microdns services discovery debug: mDNS: listening to _sftp-ssh._tcp.local service
[00006080000bcfa0] microdns services discovery debug: mDNS: listening to _rtsp._tcp.local service
[00006080000bcf20] main services discovery debug: net: opening 224.2.127.254 datagram port 9875
[00006080000bcf20] main services discovery debug: net: opening 239.195.255.255 datagram port 9875
[00006080000bcf20] main services discovery debug: net: opening 239.255.255.255 datagram port 9875
[00006080000bcf20] main services discovery debug: net: opening 224.0.0.255 datagram port 9875
[00006080000bcf20] main services discovery debug: net: opening ff02::2:7ffe%lo datagram port 9875
[00007ffc1d0c1fd0] main generic debug: using services_discovery module "microdns"
[00007ffc1d0c1fd0] main generic debug: looking for services_discovery module matching "avahi": 1 candidates
[00006080000bcf20] main services discovery debug: net: opening ff02::2:7ffe%enp5s0 datagram port 9875
[00006080000bd0a0] avahi services discovery error: failed to create avahi client: Daemon not running
[00007ffc1d0c1fd0] main generic debug: no services_discovery modules matched with name avahi
[00006080000bd0a0] main services discovery error: no suitable services discovery module
[00007ffc1d0c1fd0] main generic debug: looking for services_discovery module matching "upnp": 1 candidates
[00006080000bd120] upnp services discovery: Initializing libupnp on 'default' interface
[00006080000bcfa0] microdns services discovery error: mDNS entry callback error: Required key not available
[00006080000bcf20] main services discovery debug: net: opening ff02::2:7ffe%wlp4s0 datagram port 9875
[00006080000bcf20] main services discovery debug: net: opening ff02::2:7ffe%virbr1 datagram port 9875
[00006080000bcf20] main services discovery debug: net: opening ff02::2:7ffe%wg0 datagram port 9875
[00006080000bcf20] main services discovery debug: net: opening ff02::2:7ffe%virbr0 datagram port 9875
[00006080000bcf20] main services discovery debug: net: opening ff02::2:7ffe%virbr2 datagram port 9875
[00006080000bcf20] main services discovery debug: net: opening ff02::2:7ffe%docker0 datagram port 9875
[00006080000bcf20] main services discovery debug: net: opening ff02::2:7ffe%br-882d5a84636f datagram port 9875
[00006080000bcf20] main services discovery debug: net: opening ff02::2:7ffe%wgshady datagram port 9875
[00006080000bcf20] main services discovery debug: net: opening ff01::2:7ffe datagram port 9875
[00006080000bcf20] main services discovery error: socket bind error: Invalid argument
[00006080000bcf20] main services discovery debug: net: opening ff04::2:7ffe datagram port 9875
[00006080000bcf20] main services discovery debug: net: opening ff05::2:7ffe datagram port 9875
[00006080000bcf20] main services discovery debug: net: opening ff06::2:7ffe datagram port 9875
[00006080000bcf20] main services discovery debug: net: opening ff07::2:7ffe datagram port 9875
[00006080000bcf20] main services discovery debug: net: opening ff08::2:7ffe datagram port 9875
[00006080000bcf20] main services discovery debug: net: opening ff09::2:7ffe datagram port 9875
[00006080000bcf20] main services discovery debug: net: opening ff0A::2:7ffe datagram port 9875
[00006080000bcf20] main services discovery debug: net: opening ff0B::2:7ffe datagram port 9875
[00006080000bcf20] main services discovery debug: net: opening ff0C::2:7ffe datagram port 9875
[00006080000bcf20] main services discovery debug: net: opening ff0D::2:7ffe datagram port 9875
[00006080000bcf20] main services discovery debug: net: opening ff0E::2:7ffe datagram port 9875
[00007ffc1d0c1fd0] main generic debug: using services_discovery module "upnp"
[000061e000003080] main input debug: using timeshift granularity of 50 MiB
[000061e000003080] main input debug: using default timeshift path
[000061e000003080] main input debug: `mock://audio_track_count=1;video_track_count=1;length=1000000000000000000000000000000000000000;can_control_pace=false' gives access `mock' demux `any' path `audio_track_count=1;video_track_count=1;length=1000000000000000000000000000000000000000;can_control_pace=false'
[0000611000039fc0] main access debug: creating access: mock://audio_track_count=1;video_track_count=1;length=1000000000000000000000000000000000000000;can_control_pace=false
[00007f5ba42a5c50] main generic debug: looking for access module matching "mock": 1 candidates
[000061e000003080] main input debug: selecting program id=0
[000061e000003080] main input debug: ES track added: 'audio/0' (fourcc: 'f32l')
[000061e000003080] main input debug: ES track added: 'video/0' (fourcc: 'I420')
[00007f5ba42a5c50] main generic debug: using access module "mock"
[00007f5ba42a4f10] main generic debug: looking for audio decoder module matching "any": 18 candidates
[000061d00001fe80] araw decoder debug: samplerate:44100Hz channels:2 bits/sample:32
[00007f5ba42a4f10] main generic debug: using audio decoder module "araw"
[000061e000003080] main input debug: ES track selected: 'audio/0' (fourcc: 'f32l')
[00007f5ba42a4f10] main generic debug: looking for video decoder module matching "any": 17 candidates
[00007f5ba42a4f10] main generic debug: using video decoder module "rawvideo"
[000061e000003080] main input debug: ES track selected: 'video/0' (fourcc: 'I420')
[00007f5ba42a61d0] main generic debug: looking for meta reader module matching "any": 2 candidates
[000060600005d2c0] lua demux meta debug: Trying Lua scripts in /home/janniaux/.local/share/vlc/lua/meta/reader
[000060600005d2c0] lua demux meta debug: Trying Lua scripts in /home/janniaux/Projects/videolabs/vlc/build-asan/modules/lua/meta/reader
[000060600005d2c0] lua demux meta debug: Trying Lua scripts in /home/janniaux/Projects/videolabs/vlc/share/lua/meta/reader
[000060600005d2c0] lua demux meta debug: Trying Lua playlist script /home/janniaux/Projects/videolabs/vlc/share/lua/meta/reader/filename.lua
[00007f5ba42a61d0] main generic debug: no meta reader modules matched with name any
[000061e000003080] main input debug: `mock://audio_track_count=1;video_track_count=1;length=1000000000000000000000000000000000000000;can_control_pace=false' successfully opened
[000061e000003080] main input debug: The input can't pace, selecting the input (PCR) as the clock source
[000061e000003080] main input debug: program(0): using clock source: 'input'
[000061e000003080] main input debug: Buffering 0%
[0000617000004680] main player debug: reusing audio output
[0000612000002d40] pulse audio output debug: using stereo channel map
[000061e000003080] main input debug: control type=29
[000061d000020880] main decoder debug: vout: none found
[000061d000020880] main decoder debug: vout change: decoder size
[000061d000020880] main decoder debug: vout change: visible size
[000061d000020880] main decoder debug: vout change: chroma
[00007f5b9e1fdd20] main generic debug: looking for text renderer module matching "any": 3 candidates
[0000616000070880] freetype spu text debug: Building font databases.
[0000616000070880] freetype spu text debug: Took 2456 microseconds
[00007f5b9e1fdd20] main generic debug: using text renderer module "freetype"
[00007f5b9e1fdd80] main generic debug: looking for video converter module matching "any": 22 candidates
[0000616000078980] swscale scale debug: 32x32 (32x32) chroma: YUVA colorspace: Undefined -> 16x16 (16x16) chroma: RGBA colorspace: Undefined with scaling using Bicubic (good quality)
[00007f5b9e1fdd80] main generic debug: using video converter module "swscale"
[00007f5b9e1fdd80] main generic debug: looking for video converter module matching "any": 22 candidates
[0000616000079e80] yuvp scale debug: YUVP to YUVA converter
[00007f5b9e1fdd80] main generic debug: using video converter module "yuvp"
[000061900008fc80] main video output debug: Deinterlacing available
[000061900008fc80] main video output debug: deinterlace -1, mode auto, is_needed 0
[00007f5b9e1fddf0] main generic debug: looking for vout window module matching "any": 7 candidates
[0000612000002d40] pulse audio output debug: changed buffer metrics: maxlength=4194304, tlength=42336, prebuf=0, minreq=14112
[0000612000002d40] pulse audio output debug: connected to sink alsa_output.pci-0000_0a_00.1.hdmi-stereo-extra3.2
[0000612000002d40] main audio output debug: output 'f32l' 44100 Hz Stereo frame=1 samples/8 bytes
[00007f5ba3eeb530] main generic debug: looking for audio volume module matching "any": 2 candidates
[00007f5ba3eeb530] main generic debug: using audio volume module "float_mixer"
[0000612000002d40] main audio output debug: input 'f32l' 44100 Hz Stereo frame=1 samples/8 bytes
[00007f5ba3eeb010] main generic debug: looking for audio filter module matching "scaletempo": 1 candidates
[000061600007fe80] scaletempo audio filter debug: format: 44100 rate, 2 nch, 4 bps, fl32
[000061600007fe80] scaletempo audio filter debug: params: 30 stride, 0.200 overlap, 14 search
[000061600007fe80] scaletempo audio filter debug: 1.000 scale, 1323.000 stride_in, 1323 stride_out, 1059 standing, 264 overlap, 617 search, 2204 queue, fl32 mode
[00007f5ba3eeb010] main generic debug: using audio filter module "scaletempo"
[0000612000002d40] main audio output debug: conversion: 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo
[0000612000002d40] main audio output debug: conversion pipeline complete
[0000612000002d40] main audio output debug: conversion: 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo
[0000612000002d40] main audio output debug: conversion pipeline complete
[00007f5ba3eeb170] main generic debug: looking for audio resampler module matching "any": 3 candidates
[000060e0000b0c40] xdg_shell window debug: global 1: wl_compositor version 5
[000060e0000b0c40] xdg_shell window debug: global 2: wl_drm version 2
[000060e0000b0c40] xdg_shell window debug: global 3: wl_shm version 1
[000060e0000b0c40] xdg_shell window debug: global 5: zxdg_output_manager_v1 version 3
[000060e0000b0c40] xdg_shell window debug: global 6: wl_data_device_manager version 3
[000060e0000b0c40] xdg_shell window debug: global 7: zwp_primary_selection_device_manager_v1 version 1
[000060e0000b0c40] xdg_shell window debug: global 8: wl_subcompositor version 1
[000060e0000b0c40] xdg_shell window debug: global 9: xdg_wm_base version 4
[000060e0000b0c40] xdg_shell window debug: global 10: gtk_shell1 version 5
[000060e0000b0c40] xdg_shell window debug: global 11: wp_viewporter version 1
[000060e0000b0c40] xdg_shell window debug: global 12: zwp_pointer_gestures_v1 version 3
[000060e0000b0c40] xdg_shell window debug: global 13: zwp_tablet_manager_v2 version 1
[000060e0000b0c40] xdg_shell window debug: global 14: wl_seat version 8
[000060e0000b0c40] xdg_shell window debug: global 15: zwp_relative_pointer_manager_v1 version 1
[000060e0000b0c40] xdg_shell window debug: global 16: zwp_pointer_constraints_v1 version 1
[000060e0000b0c40] xdg_shell window debug: global 17: zxdg_exporter_v1 version 1
[000060e0000b0c40] xdg_shell window debug: global 18: zxdg_importer_v1 version 1
[000060e0000b0c40] xdg_shell window debug: global 19: zwp_linux_dmabuf_v1 version 4
[000060e0000b0c40] xdg_shell window debug: global 20: wp_single_pixel_buffer_manager_v1 version 1
[000060e0000b0c40] xdg_shell window debug: global 21: zwp_keyboard_shortcuts_inhibit_manager_v1 version 1
[000060e0000b0c40] xdg_shell window debug: global 22: zwp_text_input_manager_v3 version 1
[000060e0000b0c40] xdg_shell window debug: global 23: wp_presentation version 1
[000060e0000b0c40] xdg_shell window debug: global 24: xdg_activation_v1 version 1
[000060e0000b0c40] xdg_shell window debug: global 27: wl_output version 3
[0000616000080180] soxr audio resampler debug: Using SoX Resampler with 'cr32s' engine and 'Medium 16-bit with medium roll-off' quality to convert f32l/44100Hz to f32l/44100Hz.
[00007f5ba3eeb170] main generic debug: using audio resampler module "soxr"
[00007f5b9e1fddf0] main generic debug: using vout window module "xdg_shell"
[00007f5b9e1fdd60] main generic debug: looking for inhibit module matching "any": 3 candidates
[000061100003a100] [clock] main generic warning: resetting master clock: coefficient too unstable: 76.000000
[000061e000003080] main input debug: Buffering 1%
[000060e0000b0c40] xdg_shell window debug: seat capabilities: 0x3
[000060e0000b0c40] xdg_shell window debug: seat name: seat0
[000060e0000b0c40] xdg_shell window debug: output 27 geometry: 600x340mm+0+0, subpixel 0, transform 0
[000060e0000b0c40] xdg_shell window debug: output 27 mode: 0x3 3840x2160, 59.996 Hz
[000060e0000b0c40] xdg_shell window debug: output 27 scale: 1
[000060e0000b0c40] main window debug: fullscreen output 27 (AOC - U2790B) added
[0000604000077f50] dbus_screensaver inhibit debug: found service org.freedesktop.ScreenSaver
[00007f5b9e1fdd60] main generic debug: using inhibit module "dbus_screensaver"
[000060e0000b0c40] main window debug: requested window size: 640x480
[000060e0000b0c40] main window debug: resized to 640x480
[000060e0000b0c40] xdg_shell window error: server-side decoration not supported
[000060e0000b0c40] main window debug: resized to 640x480
[000060e0000b0c40] xdg_shell window debug: format 1 keymap of 76045 bytes
[000060e0000b0c40] xdg_shell window debug: keyboard repeat info: 33 Hz after 500 ms
[000060e0000b0c40] xdg_shell window debug: window configure bounds: 3840x2096
[000060e0000b0c40] xdg_shell window debug: new configuration: 0x0
[000060e0000b0c40] main window debug: window set windowed
[000060e0000b0c40] main window debug: resized to 640x480
[000061900008fc80] main video output debug: deinterlace -1, mode auto, is_needed 0
[000061900008fc80] main video output debug: Opening vout display wrapper
[000061600007d780] main vout display debug: looking for vout display module matching "dummy": 1 candidates
[000061600007d780] main vout display debug: using vout display module "vdummy"
[000061900008fc80] main video output debug: original format sz 640x480, of (0,0), vsz 640x480, 4cc I420, sar 1:1, orient: normal, msk r0x0 g0x0 b0x0
[0000616000070880] main spu text debug: removing "text renderer" module "freetype"
[00007f5b9e1fdd60] main generic debug: looking for text renderer module matching "any": 3 candidates
[000061600007dd80] freetype spu text debug: Building font databases.
[000061600007dd80] freetype spu text debug: Took 2337 microseconds
[00007f5b9e1fdd60] main generic debug: using text renderer module "freetype"
[000060e0000b0c40] main window debug: requested window size: 640x480
[000060e0000b0c40] main window debug: resized to 640x480
[000061d000020880] main decoder debug: Received first picture
[000061c000020080] main subpicture warning: original picture size is undefined
[00007f5b997a3320] main generic debug: looking for video blending module matching "any": 1 candidates
[00007f5b997a3320] main generic debug: using video blending module "blend"
[00006080000bcfa0] main services discovery debug: adding: Mac mini
[00006080000bcfa0] main services discovery debug: adding: Mac mini
[000061e000003080] main input debug: Buffering 2%
[000061e000003080] main input debug: control type=27
[000061e000003080] main input debug: Buffering 3%
[000061e000003080] main input debug: Buffering 4%
[000061e000003080] main input debug: Buffering 6%
[000061e000003080] main input debug: Buffering 7%
[000061e000003080] main input debug: Buffering 8%
[000061e000003080] main input debug: Buffering 9%
[000061e000003080] main input debug: Buffering 10%
[000061e000003080] main input debug: Buffering 12%
[000061e000003080] main input debug: Buffering 13%
[000061e000003080] main input debug: Buffering 14%
[000061e000003080] main input debug: Buffering 15%
[000061e000003080] main input debug: Buffering 16%
[000061e000003080] main input debug: Buffering 18%
[000061e000003080] main input debug: Buffering 19%
[000061e000003080] main input debug: Buffering 20%
[000061e000003080] main input debug: Buffering 21%
[000061e000003080] main input debug: Buffering 23%
[000061e000003080] main input debug: Buffering 24%
[000061e000003080] main input debug: Buffering 25%
[000061e000003080] main input debug: Buffering 26%
[000061e000003080] main input debug: Buffering 27%
[000061e000003080] main input debug: Buffering 29%
[000061e000003080] main input debug: Buffering 30%
[000061e000003080] main input debug: Buffering 31%
[000061e000003080] main input debug: Buffering 32%
[000061e000003080] main input debug: Buffering 33%
[000061e000003080] main input debug: Buffering 35%
[000061e000003080] main input debug: Buffering 36%
[000061e000003080] main input debug: Buffering 37%
[000061e000003080] main input debug: Buffering 38%
[000061e000003080] main input debug: Buffering 40%
[000061e000003080] main input debug: Buffering 41%
[000061e000003080] main input debug: Buffering 42%
[000061e000003080] main input debug: Buffering 43%
[000061e000003080] main input debug: Buffering 44%
[000061e000003080] main input debug: Buffering 46%
[000061e000003080] main input debug: Buffering 47%
[000061e000003080] main input debug: Buffering 48%
[000061e000003080] main input debug: Buffering 49%
[000061e000003080] main input debug: Buffering 50%
[000061e000003080] main input debug: Buffering 52%
[000061e000003080] main input debug: Buffering 53%
[000061e000003080] main input debug: Buffering 54%
[000061e000003080] main input debug: Buffering 55%
[000061e000003080] main input debug: Buffering 56%
[000061e000003080] main input debug: Buffering 58%
[000061e000003080] main input debug: Buffering 59%
[000061e000003080] main input debug: Buffering 60%
[000061e000003080] main input debug: Buffering 61%
[000061e000003080] main input debug: Buffering 63%
[000061e000003080] main input debug: Buffering 64%
[000061e000003080] main input debug: Buffering 65%
[000061e000003080] main input debug: Buffering 66%
[000061e000003080] main input debug: Buffering 67%
[000061e000003080] main input debug: Buffering 69%
[000061e000003080] main input debug: Buffering 70%
[000061e000003080] main input debug: Buffering 71%
[000061e000003080] main input debug: Buffering 72%
[000061e000003080] main input debug: Buffering 73%
[000061e000003080] main input debug: Buffering 75%
[000061e000003080] main input debug: Buffering 76%
[000061e000003080] main input debug: Buffering 77%
[000061e000003080] main input debug: Buffering 78%
[000061e000003080] main input debug: Buffering 80%
[000061e000003080] main input debug: Buffering 81%
[000061e000003080] main input debug: Buffering 82%
[000061e000003080] main input debug: Buffering 83%
[000061e000003080] main input debug: Buffering 84%
[000061e000003080] main input debug: Buffering 86%
[000061e000003080] main input debug: Buffering 87%
[000061e000003080] main input debug: Buffering 88%
[000061e000003080] main input debug: Buffering 89%
[000061e000003080] main input debug: Buffering 90%
[000061e000003080] main input debug: Buffering 92%
[000061e000003080] main input debug: Buffering 93%
[000061e000003080] main input debug: Buffering 94%
[000061e000003080] main input debug: Buffering 95%
[000061e000003080] main input debug: Buffering 96%
[000061e000003080] main input debug: Buffering 98%
[000061e000003080] main input debug: Buffering 99%
[000061e000003080] main input debug: Stream buffering done (3320 ms in 3389 ms)
[000061e000003080] main input debug: Decoder wait done in 0 ms
[000061d00001fe80] main decoder debug: changing delay: -3000000
[0000612000002d40] pulse audio output warning: starting late (-3030204 us)
[0000612000002d40] pulse audio output debug: started
[000061100003a100] [clock] main generic warning: resetting master clock: coefficient too unstable: 0.263025
[0000612000002d40] main audio output warning: playback way too late (3058408): flushing buffers
[0000612000002d40] pulse audio output debug: deferring start (259592 us)
[0000612000002d40] pulse audio output debug: deferring start (249077 us)
[0000612000002d40] pulse audio output debug: deferring start (228966 us)
[000061900008fc80] main video output warning: picture is too late to be displayed (missing 61 ms)
[000061600007d780] main vout display debug: picture displayed late (missing 20 ms)
[0000612000002d40] pulse audio output debug: deferring start (219488 us)
[0000612000002d40] pulse audio output debug: deferring start (188353 us)
[0000612000002d40] pulse audio output debug: deferring start (179155 us)
[0000612000002d40] pulse audio output debug: deferring start (138857 us)
[0000612000002d40] pulse audio output debug: deferring start (107719 us)
[0000612000002d40] pulse audio output debug: deferring start (98506 us)
[0000612000002d40] pulse audio output debug: deferring start (59019 us)
[0000612000002d40] pulse audio output debug: deferring start (18683 us)
[0000612000002d40] pulse audio output debug: starting deferred
[0000612000002d40] pulse audio output debug: started
^C[000060e000000120] main libvlc debug: exiting
[000060e000000120] main libvlc debug: removing all interfaces
[0000606000030260] main interface debug: removing "interface" module "dummy"
[000060600002efa0] main interface debug: removing "interface" module "xcb_hotkeys"
[000060600002eca0] main interface debug: removing "interface" module "hotkeys"
[000060600002e640] main interface debug: removing "interface" module "dbus"
[000060600002b460] main keystore debug: removing "keystore" module "memory"
[000061d00001fe80] main decoder debug: killing decoder fourcc `f32l'
[000061d00001fe80] main decoder debug: removing "audio decoder" module "araw"
[0000616000080180] main audio resampler debug: removing "audio resampler" module "soxr"
[000061600007fe80] main audio filter debug: removing "audio filter" module "scaletempo"
[000060700008a810] main volume debug: removing "audio volume" module "float_mixer"
[0000617000004680] main player debug: keeping audio output
[000061e000003080] main input debug: ES track unselected: 'audio/0' (fourcc: 'f32l')
[000061d000020880] main decoder debug: killing decoder fourcc `I420'
[000061d000020880] main decoder debug: removing "video decoder" module "rawvideo"
[00006160000afe80] main blend debug: removing "video blending" module "blend"
[0000617000004680] main player debug: saving a free vout
[000061e000003080] main input debug: ES track unselected: 'video/0' (fourcc: 'I420')
[0000611000039fc0] main access debug: removing "access" module "mock"
[000061e000003080] main input debug: ES track deleted: 'audio/0' (fourcc: 'f32l')
[000061e000003080] main input debug: ES track deleted: 'video/0' (fourcc: 'I420')
[000061e000003080] main input debug: Program doesn't contain anymore ES
[000060e000000120] main libvlc debug: exiting
[000061e000003080] main input debug: destroying input for 'mock://'
[000061900008fc80] main video output debug: stop free vout
[0000604000077f50] dbus_screensaver inhibit debug: got cookie 1367109491
[000061600007dd80] main spu text debug: removing "text renderer" module "freetype"
[0000616000079e80] main scale debug: removing "video converter" module "yuvp"
[0000616000078980] main scale debug: removing "video converter" module "swscale"
[0000604000077f50] main inhibit debug: removing "inhibit" module "dbus_screensaver"
[000060e0000b0c40] main window debug: fullscreen output 27 removed
[00006080000bd120] upnp services discovery warning: search timeout
[0000612000002d40] main audio output debug: removing "audio output" module "pulse"
[000060b000078800] main medialibrary debug: removing "medialibrary" module "medialibrary"
[00006080000bcf20] main services discovery debug: removing "services_discovery" module "sap"
[00006080000bcfa0] main services discovery debug: removing "services_discovery" module "microdns"
^C[00006080000bd120] main services discovery debug: removing "services_discovery" module "upnp"
^C%