[0x1fd8e48] main playlist debug: processing request item: G.R.I.T. - Piagga.wav, node: My Music, skip: 0
[0x1fd8e48] main playlist debug: resyncing on G.R.I.T. - Piagga.wav
[0x1fd8e48] main playlist debug: G.R.I.T. - Piagga.wav is at 31
[0x1fd8e48] main playlist debug: starting playback of the new playlist item
[0x1fd8e48] main playlist debug: resyncing on G.R.I.T. - Piagga.wav
[0x1fd8e48] main playlist debug: G.R.I.T. - Piagga.wav is at 31
[0x1fd8e48] main playlist debug: creating new input thread
[0x7f9134001568] main input debug: Creating an input for 'G.R.I.T. - Piagga.wav'
[0x7f9134001568] main input debug: using timeshift granularity of 50 MiB, in path '/tmp'
[0x7f9134001568] main input debug: `file:///home/me/Music/G.R.I.T.%20-%20Piagga.wav' gives access `file' demux `' path `/home/me/Music/G.R.I.T.%20-%20Piagga.wav'
[0x1ea6148] qt4 interface debug: IM: Setting an input
[0x7f9134001568] main input debug: creating demux: access='file' demux='' location='/home/me/Music/G.R.I.T.%20-%20Piagga.wav' file='/home/me/Music/G.R.I.T. - Piagga.wav'
[0x7f9138001be8] main demux debug: looking for access_demux module matching "file": 20 candidates
[0x7f9138001be8] main demux debug: no access_demux modules matched
[0x7f9134001568] main input debug: creating access 'file' location='/home/me/Music/G.R.I.T.%20-%20Piagga.wav', path='/home/me/Music/G.R.I.T. - Piagga.wav'
[0x7f91380031e8] main access debug: looking for access module matching "file": 25 candidates
[0x7f91380031e8] filesystem access debug: opening file `/home/me/Music/G.R.I.T. - Piagga.wav'
[0x7f91380031e8] main access debug: using access module "filesystem"
[0x7f9138001be8] main stream debug: Using stream method for AStream*
[0x7f9138001be8] main stream debug: starting pre-buffering
[0x7f9138001be8] main stream debug: received first data after 0 ms
[0x7f9138001be8] main stream debug: pre-buffering done 1024 bytes in 0s - 27027 KiB/s
[0x7f9138001a08] main stream debug: looking for stream_filter module matching "any": 9 candidates
[0x7f9138001a08] main stream debug: no stream_filter modules matched
[0x7f9138001a08] main stream debug: looking for stream_filter module matching "record": 9 candidates
[0x7f9138001a08] main stream debug: using stream_filter module "record"
[0x7f9134001568] main input debug: creating demux: access='file' demux='' location='/home/me/Music/G.R.I.T.%20-%20Piagga.wav' file='/home/me/Music/G.R.I.T. - Piagga.wav'
[0x7f9138002488] main demux debug: looking for demux module matching "any": 63 candidates
[0x1ea1c88] dbus interface debug: Getting property CanSeek
[0x7f9138002488] wav demux debug: chunk: fcc=`fmt ` size=16
[0x7f9138002488] wav demux debug: format: 0x0001, fourcc: araw, channels: 2, freq: 44100 Hz, bitrate: 172Ko/s, blockalign: 4, bits/samples: 16, extra size: 0
[0x7f9138002488] wav demux debug: found Raw audio audio format
[0x7f9138002488] wav demux debug: chunk: fcc=`smpl` size=36
[0x7f9138002488] wav demux debug: chunk: fcc=`data` size=100981772
[0x7f9134001568] main input debug: selecting program id=0
[0x7f9138002488] main demux debug: using demux module "wav"
[0x7f9134001568] main input debug: looking for a subtitle file in /home/me/Music/
[0x7f9138000958] main decoder debug: looking for decoder module matching "any": 39 candidates
[0x7f9138000958] araw decoder debug: samplerate:44100Hz channels:2 bits/sample:16
[0x7f9138000958] main decoder debug: using decoder module "araw"
[0x7f913800dce8] main demux meta debug: looking for meta reader module matching "any": 2 candidates
[0x1ea1c88] dbus interface debug: Getting property Position
[0x7f913800dce8] lua demux meta debug: Trying Lua scripts in /home/me/.local/share/vlc/lua/meta/reader
[0x7f913800dce8] lua demux meta debug: Trying Lua scripts in /usr/lib/vlc/lua/meta/reader
[0x7f913800dce8] lua demux meta debug: Trying Lua playlist script /usr/lib/vlc/lua/meta/reader/filename.luac
[0x1ea1c88] dbus interface debug: Getting property Position
[0x7f913800dce8] lua demux meta debug: Trying Lua scripts in /usr/share/vlc/lua/meta/reader
[0x7f913800dce8] main demux meta debug: no meta reader modules matched
[0x7f9134001568] main input debug: `file:///home/me/Music/G.R.I.T.%20-%20Piagga.wav' successfully opened
[0x7f9134001568] main input debug: Buffering 0%
[0x7f9134001568] main input debug: Buffering 16%
[0x1fd8e48] main playlist debug: reusing audio output
[0x7f9134001568] main input debug: Buffering 33%
[0x1ea24c8] pulse audio output debug: using stereo channel map
[0x7f9134001568] main input debug: Buffering 50%
[0x7f9134001568] main input debug: Buffering 66%
[0x7f9134001568] main input debug: Buffering 83%
[0x7f9134001568] main input debug: Buffering 100%
[0x7f9134001568] main input debug: Stream buffering done (350 ms in 0 ms)
[0x1ea24c8] pulse audio output debug: changed buffer metrics: maxlength=4194304, tlength=21168, prebuf=0, minreq=7056
[0x1ea24c8] pulse audio output debug: connected to sink alsa_output.pci-0000_00_1b.0.analog-stereo
[0x1ea24c8] pulse audio output debug: base volume: 65536
[0x1ea1c88] dbus interface debug: Getting property Position
[0x1ea24c8] pulse audio output debug: changing sink 0: alsa_output.pci-0000_00_1b.0.analog-stereo (Built-in Audio Analog Stereo)
[0x1ea24c8] main audio output debug: output 's16l' 48000 Hz Stereo frame=1 samples/4 bytes
[0x7f913c010fc8] main volume debug: looking for audio volume module matching "any": 2 candidates
[0x7f913c010fc8] main volume debug: using audio volume module "integer_mixer"
[0x1ea24c8] main audio output debug: input 's16l' 44100 Hz Stereo frame=1 samples/4 bytes
[0x7f913c01d8f8] main audio filter debug: looking for audio filter module matching "scaletempo": 14 candidates
[0x7f913c01d8f8] scaletempo audio filter debug: format: 44100 rate, 2 nch, 4 bps, fl32
[0x7f913c01d8f8] scaletempo audio filter debug: params: 30 stride, 0.200 overlap, 14 search
[0x7f913c01d8f8] scaletempo audio filter debug: 1.000 scale, 1323.000 stride_in, 1323 stride_out, 1059 standing, 264 overlap, 617 search, 2204 queue, fl32 mode
[0x7f913c01d8f8] main audio filter debug: using audio filter module "scaletempo"
[0x1ea24c8] main audio output debug: conversion: 's16l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo
[0x7f913c01e878] main audio converter debug: looking for audio converter module matching "any": 12 candidates
[0x7f913c01e878] audio_format audio converter debug: s16l->f32l, bits per sample: 16->32
[0x7f913c01e878] main audio converter debug: using audio converter module "audio_format"
[0x1ea24c8] main audio output debug: conversion pipeline complete
[0x1ea24c8] main audio output debug: conversion: 'f32l'->'s16l' 44100 Hz->44100 Hz Stereo->Stereo
[0x7f913c018d38] main audio converter debug: looking for audio converter module matching "any": 12 candidates
[0x7f913c018d38] audio_format audio converter debug: f32l->s16l, bits per sample: 16->16
[0x7f913c018d38] main audio converter debug: using audio converter module "audio_format"
[0x1ea24c8] main audio output debug: conversion pipeline complete
[0x7f913c019c58] main audio resampler debug: looking for audio resampler module matching "any": 3 candidates
[0x7f913c019c58] main audio resampler debug: using audio resampler module "ugly_resampler"
[0x7f9138000958] main decoder debug: End of audio preroll
[0x7f9134001568] main input debug: Decoder buffering done in 4 ms
[0x1ea24c8] pulse audio output debug: cannot synchronize start
[0x1ea24c8] pulse audio output debug: deferring start (9860 us)
[0x1ea24c8] pulse audio output warning: starting late (-13525 us)
[0x1ea24c8] pulse audio output debug: started
[0x1ea24c8] pulse audio output debug: underflow
[0x1ea24c8] pulse audio output debug: changing sink 0: alsa_output.pci-0000_00_1b.0.analog-stereo (Built-in Audio Analog Stereo)