VLC 2.2.1 (Debian) and VLC 3.0.0 (compiled) lack audio with Everest and Mad Max: Fury Road Blurays

[Jason_25]

With 3.0.0 the error I get is:

Code: Select all

core audio output error: module not functional core decoder error: failed to create audio output

The menus and previews playback fine with functional sound.

With 2.2.1 there are no sound related errors at all but sound does not play. It also does not play the menus or previews.

All other bluray discs play sound fine.

Is there anything I can do on my end?

[Rémi Denis-Courmont]

Looks like missing audio output plugin, but can't really say without verbose logs.

[Jason_25]

I have a log here from VLC 3.0.0. I was forced to take a lot of time to remove lines which were bloating up the file beyond 60000 characters:

Code: Select all

core debug: processing request item: bluray:///media/cdrom0, node: null, skip: 0 core debug: rebuilding array of current - root Playlist core debug: rebuild done - 1 items, index 0 core debug: starting playback of new item core debug: resyncing on bluray:///media/cdrom0 core debug: bluray:///media/cdrom0 is at 0 core debug: creating new input thread core debug: Creating an input for 'bluray:///media/cdrom0' core debug: requesting art for new input thread core debug: using timeshift granularity of 50 MiB core debug: using default timeshift path core debug: `bluray:///media/cdrom0' gives access `bluray' demux `' path `/media/cdrom0' core debug: specified demux: any core debug: creating demux: access='bluray' demux='any' location='/media/cdrom0' file='/media/cdrom0' core debug: looking for access_demux module matching "bluray": 22 candidates core debug: looking for meta fetcher module matching "any": 1 candidates core debug: no art finder modules matched core debug: art not found for bluray:///media/cdrom0 libbluray info: First play: 1, Top menu: 1 HDMV Titles: 12, BD-J Titles: 0, Other: 0 libbluray debug: Disc is using AACS core debug: using access_demux module "libbluray" core debug: looking for meta reader module matching "any": 2 candidates lua debug: Trying Lua scripts in /home/jason/.local/share/vlc/lua/meta/reader lua debug: Trying Lua scripts in /home/jason/vlc/src/.libs/vlc/lua/meta/reader lua debug: Trying Lua scripts in /home/jason/vlc/share/lua/meta/reader lua debug: Trying Lua playlist script /home/jason/vlc/share/lua/meta/reader/filename.lua lua debug: Trying Lua playlist script /home/jason/vlc/share/lua/meta/reader/filename.luac core debug: creating demux: access='' demux='ts' location='' file='(null)' core debug: no meta reader modules matched core debug: `bluray:///media/cdrom0' successfully opened core debug: looking for demux module matching "ts": 67 candidates core debug: no demux modules matched core debug: creating demux: access='' demux='ts' location='' file='(null)' core debug: looking for demux module matching "ts": 67 candidates ts debug: Standard set to Auto core debug: using demux module "ts" ts debug: DEMUX_SET_GROUP -1 (nil) ts debug: PATCallBack called ts debug: new PAT ts_id=0 version=0 current_next=1 ts debug: * number=0 pid=31 ts debug: * number=1 pid=256 ts debug: temporary receiving program 1 ts debug: PMTCallBack called for program 1 ts debug: new PMT program number=1 version=0 pid_pcr=4097 ts debug: * PMT descriptor: Registration (0x5) ts debug: : HDMV registration ts debug: * PMT descriptor: Unknown Private (0x88) ts debug: * pid=4113 type=0x1b AVC video stream as defined in ITU-T Rec. H.264 ts debug: - ES descriptor tag 0x5 ts debug: => pid 4113 has now es fcc=h264 core debug: selecting program id=1 core debug: looking for packetizer module matching "any": 24 candidates core debug: using packetizer module "h264" core debug: looking for decoder module matching "any": 48 candidates avcodec debug: CPU flags: 0x000fd3db avcodec debug: allowing 4 thread(s) for decoding avcodec debug: codec (h264) started avcodec debug: using frame thread mode with 4 threads core debug: using decoder module "avcodec" libbluray info: Adding ES 4113 ts debug: Default program is 1 ts debug: * pid=4352 type=0x81 User Private ts debug: - ES descriptor tag 0x5 ts debug: - ES descriptor tag 0x81 ts debug: + HDMV registration applied to pid 4352 type 0x81 ts debug: => pid 4352 has now es fcc=a52 libbluray info: Adding ES 4352 ts debug: pid[31] unknown ts debug: pid[4097] unknown core debug: Buffering 0% core debug: Buffering 29% core debug: Buffering 59% h264 debug: found NAL_SPS (sps_id=0) h264 debug: found NAL_PPS (pps_id=0 sps_id=0) h264 debug: Seen SEI recovery point, 0 recovery frames h264 debug: Recovery from SEI recovery point complete core debug: Buffering 89% core debug: restarting module due to input format change core debug: Stream buffering done (359 ms in 0 ms) core debug: removing module "avcodec" avcodec debug: ffmpeg codec (h264) stopped core debug: looking for decoder module matching "any": 48 candidates avcodec debug: CPU flags: 0x000fd3db avcodec debug: allowing 4 thread(s) for decoding avcodec debug: codec (h264) started avcodec debug: using frame thread mode with 4 threads core debug: using decoder module "avcodec" avcodec debug: available hardware decoder output format 109 (vdpau) avcodec debug: available hardware decoder output format 53 (vaapi_vld) avcodec debug: available software decoder output format 0 (yuv420p) avcodec warning: thread type 1: disabling hardware acceleration core debug: looking for text renderer module matching "any": 3 candidates freetype debug: Building font databases. freetype debug: Took 0 microseconds core debug: using text renderer module "freetype" core debug: looking for video filter module matching "any": 65 candidates swscale debug: 32x32 (32x32) chroma: YUVA -> 16x16 (16x16) chroma: RGBA with scaling using Bicubic (good quality) core debug: using video filter module "swscale" core debug: looking for video filter module matching "any": 65 candidates yuvp debug: YUVP to YUVA converter core debug: using video filter module "yuvp" core debug: Deinterlacing available core debug: deinterlace -1, mode blend, is_needed 0 core debug: looking for vout window module matching "qt,any": 5 candidates qt debug: requesting video window... core debug: resized to 1920x934 core debug: resized to 1920x1080 core debug: using vout window module "qt" core debug: looking for inhibit module matching "any": 2 candidates core debug: resized to 1920x934 dbus_screensaver debug: found service org.freedesktop.ScreenSaver core debug: using inhibit module "dbus_screensaver" core debug: Opening vout display wrapper core debug: looking for vout display module matching "xcb_x11": 14 candidates core debug: VoutDisplayEvent 'resize' 1920x934 xcb debug: connected to X11.0 server xcb debug: vendor : The X.Org Foundation xcb debug: version: 11604000 xcb debug: using screen 0xb4 xcb_x11 debug: using X11 visual ID 0x20 xcb_x11 debug: 24 bits depth xcb_x11 debug: 32 bits per pixel xcb_x11 debug: 32 bits line pad xcb_x11 debug: using X11 window 04400000 xcb_x11 debug: using X11 graphic context 04400001 core debug: using vout display module "xcb_x11" core debug: A filter to adapt decoder I420 to display RV32 is needed core debug: looking for video filter module matching "any": 65 candidates swscale debug: 1920x1080 (1920x1090) chroma: I420 -> 1920x1080 (1920x1090) chroma: RV32 with scaling using Bicubic (good quality) core debug: using video filter module "swscale" core debug: Filter 'Swscale' (0x7fda98009558) appended to chain core debug: original format sz 1920x1090, of (0,0), vsz 1920x1080, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0 core warning: VoutDisplayEvent 'pictures invalid' core warning: VoutDisplayEvent 'pictures invalid' core debug: removing module "freetype" core warning: VoutDisplayEvent 'pictures invalid' core debug: removing module "swscale" core debug: Filter 0x7fda98009558 removed from chain core debug: A filter to adapt decoder I420 to display RV32 is needed core debug: looking for text renderer module matching "any": 3 candidates core debug: looking for video filter module matching "any": 65 candidates freetype debug: Building font databases. freetype debug: Took 0 microseconds core debug: using text renderer module "freetype" swscale debug: 1920x1080 (1920x1090) chroma: I420 -> 1660x934 (1660x942) chroma: RV32 with scaling using Bicubic (good quality) core debug: using video filter module "swscale" core debug: Filter 'Swscale' (0x7fda980091d8) appended to chain avcodec warning: plane 0 not aligned: disabling direct rendering core debug: Received first picture core debug: Decoder wait done in 96 ms core debug: looking for packetizer module matching "any": 24 candidates core debug: looking for video blending module matching "any": 1 candidates core debug: using packetizer module "a52" core debug: looking for decoder module matching "any": 48 candidates avcodec debug: CPU flags: 0x000fd3db avcodec debug: codec (ac3) started core debug: using decoder module "avcodec" core debug: using video blending module "blend" a52 debug: A/52 channels:6 samplerate:48000 bitrate:448000 core debug: restarting module due to input format change core debug: removing module "avcodec" avcodec debug: ffmpeg codec (ac3) stopped core debug: looking for decoder module matching "any": 48 candidates core debug: reusing audio output alsa debug: using ALSA device: hw:CARD=PCH,DEV=3 alsa debug: Hardware PCM card 0 'HDA Intel PCH' device 3 subdevice 0 alsa debug: device name : HDMI 0 alsa debug: device ID : HDMI 0 alsa debug: subdevice name: subdevice #0 alsa debug: initial hardware setup: ACCESS: MMAP_INTERLEAVED RW_INTERLEAVED FORMAT: S16_LE S32_LE SUBFORMAT: STD SAMPLE_BITS: [16 32] FRAME_BITS: [32 64] CHANNELS: 2 RATE: [32000 192000] PERIOD_TIME: (83 256000] PERIOD_SIZE: [16 8192] PERIOD_BYTES: [128 65536] PERIODS: [2 32] BUFFER_TIME: (166 512000] BUFFER_SIZE: [32 16384] BUFFER_BYTES: [128 65536] TICK_TIME: ALL alsa debug: unsupported channel 2 position 12 alsa debug: unsupported channel 3 position 12 alsa debug: unsupported channel 3 position 12 alsa debug: unsupported channel 3 position 12 alsa debug: unsupported channel 4 position 14 alsa debug: unsupported channel 4 position 12 alsa debug: unsupported channel 4 position 12 alsa debug: unsupported channel 4 position 12 alsa debug: unsupported channel 4 position 12 alsa debug: unsupported channel 5 position 24 alsa debug: unsupported channel 5 position 21 alsa debug: unsupported channel 4 position 22 alsa debug: unsupported channel 4 position 16 alsa debug: unsupported channel 5 position 14 alsa debug: unsupported channel 5 position 14 alsa debug: unsupported channel 5 position 12 alsa debug: unsupported channel 5 position 12 alsa debug: unsupported channel 5 position 12 alsa debug: unsupported channel 6 position 24 alsa debug: unsupported channel 6 position 21 alsa debug: unsupported channel 5 position 22 alsa debug: unsupported channel 5 position 16 alsa debug: unsupported channel 6 position 21 alsa debug: unsupported channel 6 position 24 alsa debug: unsupported channel 5 position 24 alsa debug: unsupported channel 5 position 22 alsa debug: unsupported channel 5 position 16 alsa debug: unsupported channel 6 position 14 alsa debug: unsupported channel 6 position 12 alsa debug: unsupported channel 7 position 21 alsa debug: unsupported channel 7 position 24 alsa debug: unsupported channel 6 position 24 alsa debug: unsupported channel 6 position 22 alsa debug: unsupported channel 6 position 16 alsa debug: using channels map 0, type 2, 2 channel(s) alsa debug: final HW setup: ACCESS: RW_INTERLEAVED FORMAT: S32_LE SUBFORMAT: STD SAMPLE_BITS: 32 FRAME_BITS: 64 CHANNELS: 2 RATE: 48000 PERIOD_TIME: 40000 PERIOD_SIZE: 1920 PERIOD_BYTES: 15360 PERIODS: 4 BUFFER_TIME: 160000 BUFFER_SIZE: 7680 BUFFER_BYTES: 61440 TICK_TIME: 0 alsa debug: initial software parameters: tstamp_mode: NONE period_step: 1 avail_min: 1920 start_threshold: 1 stop_threshold: 7680 silence_threshold: 0 silence_size: 0 boundary: 8646911284551352320 alsa debug: final software parameters: tstamp_mode: NONE period_step: 1 avail_min: 1920 start_threshold: 1 stop_threshold: 7680 silence_threshold: 0 silence_size: 0 boundary: 8646911284551352320 core debug: output 's32l' 48000 Hz Stereo frame=1 samples/8 bytes core debug: looking for audio volume module matching "any": 2 candidates core debug: using audio volume module "integer_mixer" core debug: input 'a52 ' 48000 Hz 3F2R/LFE frame=1536 samples/1792 bytes core debug: looking for audio filter module matching "scaletempo": 14 candidates scaletempo debug: format: 48000 rate, 6 nch, 4 bps, fl32 scaletempo debug: params: 30 stride, 0.200 overlap, 14 search scaletempo debug: 1.000 scale, 1440.000 stride_in, 1440 stride_out, 1152 standing, 288 overlap, 672 search, 2400 queue, fl32 mode core debug: using audio filter module "scaletempo" core debug: conversion: 'a52 '->'f32l' 48000 Hz->48000 Hz 3F2R/LFE->3F2R/LFE core debug: looking for audio converter module matching "any": 8 candidates core debug: no audio converter modules matched core debug: looking for audio converter module matching "any": 8 candidates core debug: no audio converter modules matched core error: cannot find decoder for conversion pipeline core error: cannot add user audio filter "scaletempo" (skipped) core debug: removing module "scaletempo" core debug: conversion: 'a52 '->'s32l' 48000 Hz->48000 Hz 3F2R/LFE->Stereo core debug: looking for audio converter module matching "any": 8 candidates core debug: no audio converter modules matched core debug: looking for audio converter module matching "any": 8 candidates core debug: no audio converter modules matched core error: cannot find decoder for conversion pipeline core error: cannot setup filtering pipeline core debug: removing module "integer_mixer" core debug: keeping audio output core error: failed to create audio output avcodec debug: CPU flags: 0x000fd3db avcodec debug: codec (ac3) started core debug: using decoder module "avcodec" core debug: reusing audio output alsa debug: using ALSA device: hw:CARD=PCH,DEV=3 alsa debug: Hardware PCM card 0 'HDA Intel PCH' device 3 subdevice 0 alsa debug: device name : HDMI 0 alsa debug: device ID : HDMI 0 alsa debug: subdevice name: subdevice #0 alsa debug: initial hardware setup: ACCESS: MMAP_INTERLEAVED RW_INTERLEAVED FORMAT: S16_LE S32_LE SUBFORMAT: STD SAMPLE_BITS: [16 32] FRAME_BITS: [32 64] CHANNELS: 2 RATE: [32000 192000] PERIOD_TIME: (83 256000] PERIOD_SIZE: [16 8192] PERIOD_BYTES: [128 65536] PERIODS: [2 32] BUFFER_TIME: (166 512000] BUFFER_SIZE: [32 16384] BUFFER_BYTES: [128 65536] TICK_TIME: ALL alsa debug: unsupported channel 2 position 12 alsa debug: unsupported channel 3 position 12 alsa debug: unsupported channel 3 position 12 alsa debug: unsupported channel 3 position 12 alsa debug: unsupported channel 4 position 14 alsa debug: unsupported channel 4 position 12 alsa debug: unsupported channel 4 position 12 alsa debug: unsupported channel 4 position 12 alsa debug: unsupported channel 4 position 12 alsa debug: unsupported channel 5 position 24 alsa debug: unsupported channel 5 position 21 alsa debug: unsupported channel 4 position 22 alsa debug: unsupported channel 4 position 16 alsa debug: unsupported channel 5 position 14 alsa debug: unsupported channel 5 position 14 alsa debug: unsupported channel 5 position 12 alsa debug: unsupported channel 5 position 12 alsa debug: unsupported channel 5 position 12 alsa debug: unsupported channel 6 position 24 alsa debug: unsupported channel 6 position 21 alsa debug: unsupported channel 5 position 22 alsa debug: unsupported channel 5 position 16 alsa debug: unsupported channel 6 position 21 alsa debug: unsupported channel 6 position 24 alsa debug: unsupported channel 5 position 24 alsa debug: unsupported channel 5 position 22 alsa debug: unsupported channel 5 position 16 alsa debug: unsupported channel 6 position 14 alsa debug: unsupported channel 6 position 12 alsa debug: unsupported channel 7 position 21 alsa debug: unsupported channel 7 position 24 alsa debug: unsupported channel 6 position 24 alsa debug: unsupported channel 6 position 22 alsa debug: unsupported channel 6 position 16 alsa debug: using channels map 0, type 2, 2 channel(s) alsa debug: final HW setup: ACCESS: RW_INTERLEAVED FORMAT: S32_LE SUBFORMAT: STD SAMPLE_BITS: 32 FRAME_BITS: 64 CHANNELS: 2 RATE: 48000 PERIOD_TIME: 40000 PERIOD_SIZE: 1920 PERIOD_BYTES: 15360 PERIODS: 4 BUFFER_TIME: 160000 BUFFER_SIZE: 7680 BUFFER_BYTES: 61440 TICK_TIME: 0 alsa debug: initial software parameters: tstamp_mode: NONE period_step: 1 avail_min: 1920 start_threshold: 1 stop_threshold: 7680 silence_threshold: 0 silence_size: 0 boundary: 8646911284551352320 alsa debug: final software parameters: tstamp_mode: NONE period_step: 1 avail_min: 1920 start_threshold: 1 stop_threshold: 7680 silence_threshold: 0 silence_size: 0 boundary: 8646911284551352320 core debug: output 's32l' 48000 Hz Stereo frame=1 samples/8 bytes core debug: looking for audio volume module matching "any": 2 candidates core debug: using audio volume module "integer_mixer" core debug: input 'f32l' 48000 Hz 3F2M/LFE frame=1 samples/24 bytes core debug: looking for audio filter module matching "scaletempo": 14 candidates scaletempo debug: format: 48000 rate, 6 nch, 4 bps, fl32 scaletempo debug: params: 30 stride, 0.200 overlap, 14 search scaletempo debug: 1.000 scale, 1440.000 stride_in, 1440 stride_out, 1152 standing, 288 overlap, 672 search, 2400 queue, fl32 mode core debug: using audio filter module "scaletempo" core debug: conversion: 'f32l'->'f32l' 48000 Hz->48000 Hz 3F2M/LFE->3F2M/LFE core debug: conversion pipeline complete core debug: conversion: 'f32l'->'s32l' 48000 Hz->48000 Hz 3F2M/LFE->Stereo core debug: looking for audio converter module matching "any": 8 candidates core debug: using audio converter module "simple_channel_mixer" core debug: looking for audio converter module matching "any": 8 candidates audio_format debug: f32l->s32l, bits per sample: 32->32 core debug: using audio converter module "audio_format" core debug: conversion pipeline complete core debug: looking for audio resampler module matching "any": 3 candidates core debug: using audio resampler module "ugly" core debug: inserting 36173 zeroes libbluray info: Initializing overlay ts debug: Can't read TS packet at 1996800 core debug: removing module "ts" core debug: killing decoder fourcc `h264', 0 PES in FIFO core debug: removing module "avcodec" avcodec debug: ffmpeg codec (h264) stopped core debug: saving a free vout core debug: reusing provided vout qt debug: Qt: Fullscreen state changed core debug: removing module "h264" core debug: killing decoder fourcc `a52 ', 12 PES in FIFO core debug: removing module "avcodec" avcodec debug: ffmpeg codec (ac3) stopped core debug: removing module "ugly" core debug: removing module "scaletempo" core debug: removing module "simple_channel_mixer" core debug: removing module "audio_format" core debug: removing module "integer_mixer" core debug: keeping audio output core debug: removing module "a52" core debug: Program doesn't contain anymore ES core debug: creating demux: access='' demux='ts' location='' file='(null)' libbluray info: Stopping playlist playback core debug: looking for demux module matching "ts": 67 candidates core debug: no demux modules matched core debug: ES_OUT_RESET_PCR called core debug: creating demux: access='' demux='ts' location='' file='(null)' libbluray warning: event: 21 param: 0 core debug: looking for demux module matching "ts": 67 candidates ts debug: Standard set to Auto core debug: using demux module "ts" ts debug: DEMUX_SET_GROUP -1 (nil) ts debug: PATCallBack called ts debug: new PAT ts_id=0 version=0 current_next=1 ts debug: * number=0 pid=31 ts debug: * number=1 pid=256 ts debug: temporary receiving program 1 ts debug: PMTCallBack called for program 1 ts debug: new PMT program number=1 version=0 pid_pcr=4097 ts debug: * PMT descriptor: Registration (0x5) ts debug: : HDMV registration ts debug: * PMT descriptor: Unknown Private (0x88) ts debug: * pid=4113 type=0x1b AVC video stream as defined in ITU-T Rec. H.264 ts debug: - ES descriptor tag 0x5 ts debug: => pid 4113 has now es fcc=h264 core debug: selecting program id=1 core debug: looking for packetizer module matching "any": 24 candidates core debug: using packetizer module "h264" core debug: looking for decoder module matching "any": 48 candidates avcodec debug: CPU flags: 0x000fd3db avcodec debug: allowing 4 thread(s) for decoding avcodec debug: codec (h264) started avcodec debug: using frame thread mode with 4 threads core debug: using decoder module "avcodec" libbluray info: Adding ES 4113 ts debug: Default program is 1 core debug: destroying useless vout core debug: removing module "blend" core debug: removing module "swscale" core debug: Filter 0x7fda980091d8 removed from chain core debug: removing module "xcb_x11" dbus_screensaver debug: got cookie 6806 core debug: removing module "dbus_screensaver" qt debug: releasing video... qt debug: Video is not needed anymore core debug: removing module "freetype" core debug: removing module "yuvp" core debug: removing module "swscale" libbluray info: Adding ES 4615 libbluray info: Adding ES 4635 ts debug: pid[31] unknown ts debug: pid[4097] unknown core debug: Buffering 0% core debug: Buffering 29% core debug: Buffering 59% core debug: Buffering 89% libbluray debug: Still image (infinite) core debug: Stream buffering done (269 ms in 11 ms) core error: buffer deadlock prevented core debug: Decoder wait done in 0 ms h264 debug: found NAL_SPS (sps_id=0) h264 debug: found NAL_PPS (pps_id=0 sps_id=0) core debug: restarting module due to input format change core debug: removing module "avcodec" avcodec debug: ffmpeg codec (h264) stopped core debug: looking for decoder module matching "any": 48 candidates avcodec debug: CPU flags: 0x000fd3db avcodec debug: allowing 4 thread(s) for decoding avcodec debug: codec (h264) started avcodec debug: using frame thread mode with 4 threads core debug: using decoder module "avcodec" avcodec debug: available hardware decoder output format 109 (vdpau) avcodec debug: available hardware decoder output format 53 (vaapi_vld) avcodec debug: available software decoder output format 0 (yuv420p) avcodec warning: thread type 1: disabling hardware acceleration core debug: looking for text renderer module matching "any": 3 candidates freetype debug: Building font databases. freetype debug: Took 0 microseconds core debug: using text renderer module "freetype" core debug: looking for video filter module matching "any": 65 candidates swscale debug: 32x32 (32x32) chroma: YUVA -> 16x16 (16x16) chroma: RGBA with scaling using Bicubic (good quality) core debug: using video filter module "swscale" core debug: looking for video filter module matching "any": 65 candidates yuvp debug: YUVP to YUVA converter core debug: using video filter module "yuvp" core debug: Deinterlacing available core debug: deinterlace -1, mode blend, is_needed 0 core debug: looking for vout window module matching "qt,any": 5 candidates qt debug: requesting video window... core debug: resized to 1920x1080 core debug: using vout window module "qt" core debug: looking for inhibit module matching "any": 2 candidates dbus_screensaver debug: found service org.freedesktop.ScreenSaver core debug: using inhibit module "dbus_screensaver" core debug: Opening vout display wrapper core debug: looking for vout display module matching "xcb_x11": 14 candidates core debug: VoutDisplayEvent 'resize' 1920x1080 xcb debug: connected to X11.0 server xcb debug: vendor : The X.Org Foundation xcb debug: version: 11604000 xcb debug: using screen 0xb4 xcb_x11 debug: using X11 visual ID 0x20 xcb_x11 debug: 24 bits depth xcb_x11 debug: 32 bits per pixel xcb_x11 debug: 32 bits line pad xcb_x11 debug: using X11 window 04400000 xcb_x11 debug: using X11 graphic context 04400001 core debug: using vout display module "xcb_x11" core debug: A filter to adapt decoder I420 to display RV32 is needed core debug: looking for video filter module matching "any": 65 candidates swscale debug: 1920x1080 (1920x1090) chroma: I420 -> 1920x1080 (1920x1090) chroma: RV32 with scaling using Bicubic (good quality) core debug: using video filter module "swscale" core debug: Filter 'Swscale' (0x7fda8c008848) appended to chain core debug: original format sz 1920x1090, of (0,0), vsz 1920x1080, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0 core warning: VoutDisplayEvent 'pictures invalid' core warning: VoutDisplayEvent 'pictures invalid' core debug: removing module "freetype" core debug: removing module "swscale" core debug: Filter 0x7fda8c008848 removed from chain core debug: A filter to adapt decoder I420 to display RV32 is needed core debug: looking for text renderer module matching "any": 3 candidates core debug: looking for video filter module matching "any": 65 candidates freetype debug: Building font databases. freetype debug: Took 0 microseconds swscale debug: 1920x1080 (1920x1090) chroma: I420 -> 1920x1080 (1920x1090) chroma: RV32 with scaling using Bicubic (good quality) core debug: using video filter module "swscale" core debug: Filter 'Swscale' (0x7fda8c035488) appended to chain core debug: using text renderer module "freetype" core debug: resized to 1920x934 core debug: VoutDisplayEvent 'resize' 1920x934 avcodec warning: plane 0 not aligned: disabling direct rendering core debug: looking for video blending module matching "any": 1 candidates core debug: using video blending module "blend" core warning: VoutDisplayEvent 'pictures invalid' core debug: removing module "swscale" core debug: Filter 0x7fda8c035488 removed from chain core debug: A filter to adapt decoder I420 to display RV32 is needed core debug: looking for video filter module matching "any": 65 candidates swscale debug: 1920x1080 (1920x1090) chroma: I420 -> 1660x934 (1660x942) chroma: RV32 with scaling using Bicubic (good quality) core debug: using video filter module "swscale" core debug: Filter 'Swscale' (0x7fda8c035488) appended to chain ts debug: Can't read TS packet at 712896 core debug: removing module "ts" core debug: killing decoder fourcc `h264', 0 PES in FIFO core debug: removing module "avcodec" avcodec debug: ffmpeg codec (h264) stopped core debug: saving a free vout core debug: reusing provided vout qt debug: Qt: Fullscreen state changed core debug: removing module "h264" core debug: Program doesn't contain anymore ES core debug: creating demux: access='' demux='ts' location='' file='(null)' core debug: looking for demux module matching "ts": 67 candidates core debug: no demux modules matched core debug: ES_OUT_RESET_PCR called core debug: creating demux: access='' demux='ts' location='' file='(null)' libbluray info: Stopping playlist playback core debug: looking for demux module matching "ts": 67 candidates core debug: no demux modules matched core debug: ES_OUT_RESET_PCR called core debug: creating demux: access='' demux='ts' location='' file='(null)' core debug: looking for demux module matching "ts": 67 candidates ts debug: Standard set to Auto core debug: using demux module "ts" ts debug: DEMUX_SET_GROUP -1 (nil) ts debug: PATCallBack called ts debug: new PAT ts_id=0 version=0 current_next=1 ts debug: * number=0 pid=31 ts debug: * number=1 pid=256 ts debug: temporary receiving program 1 ts debug: PMTCallBack called for program 1 ts debug: new PMT program number=1 version=0 pid_pcr=4097 ts debug: * PMT descriptor: Registration (0x5) ts debug: : HDMV registration ts debug: * PMT descriptor: Unknown Private (0x88) ts debug: * pid=4113 type=0x1b AVC video stream as defined in ITU-T Rec. H.264 ts debug: - ES descriptor tag 0x5 ts debug: => pid 4113 has now es fcc=h264 core debug: selecting program id=1 core debug: looking for packetizer module matching "any": 24 candidates core debug: using packetizer module "h264" core debug: looking for decoder module matching "any": 48 candidates avcodec debug: CPU flags: 0x000fd3db avcodec debug: allowing 4 thread(s) for decoding avcodec debug: codec (h264) started avcodec debug: using frame thread mode with 4 threads core debug: using decoder module "avcodec" libbluray info: Adding ES 4113 ts debug: Default program is 1 ts debug: pid[31] unknown ts debug: pid[4097] unknown core debug: Buffering 0% core debug: Buffering 29% core debug: Buffering 59% core debug: Buffering 89% core debug: Stream buffering done (359 ms in 3 ms) h264 debug: found NAL_SPS (sps_id=0) h264 debug: found NAL_PPS (pps_id=0 sps_id=0) core debug: restarting module due to input format change core debug: removing module "avcodec" avcodec debug: ffmpeg codec (h264) stopped core debug: looking for decoder module matching "any": 48 candidates avcodec debug: CPU flags: 0x000fd3db avcodec debug: allowing 4 thread(s) for decoding avcodec debug: codec (h264) started avcodec debug: using frame thread mode with 4 threads core debug: using decoder module "avcodec" avcodec debug: available hardware decoder output format 109 (vdpau) avcodec debug: available hardware decoder output format 53 (vaapi_vld) avcodec debug: available software decoder output format 0 (yuv420p) avcodec warning: thread type 1: disabling hardware acceleration core debug: trying to reuse free vout core debug: removing module "freetype" core debug: looking for text renderer module matching "any": 3 candidates freetype debug: Building font databases. freetype debug: Took 1 microseconds core debug: using text renderer module "freetype" core debug: reusing provided vout avcodec warning: plane 0 not aligned: disabling direct rendering core warning: VoutDisplayEvent 'pictures invalid' core warning: VoutDisplayEvent 'pictures invalid' core debug: removing module "swscale" core debug: Filter 0x7fda8c035488 removed from chain core debug: A filter to adapt decoder I420 to display RV32 is needed core debug: looking for video filter module matching "any": 65 candidates swscale debug: 1920x1080 (1920x1090) chroma: I420 -> 1660x934 (1660x942) chroma: RV32 with scaling using Bicubic (good quality) core debug: using video filter module "swscale" core debug: Filter 'Swscale' (0x7fda8c035488) appended to chain core debug: Received first picture core debug: Decoder wait done in 52 ms core debug: VoutDisplayEvent 'mouse button' 0 t=8 ts debug: Can't read TS packet at 1161216 core debug: removing module "ts" core debug: killing decoder fourcc `h264', 23 PES in FIFO core debug: removing module "avcodec" avcodec debug: ffmpeg codec (h264) stopped core debug: saving a free vout core debug: reusing provided vout qt debug: Qt: Fullscreen state changed core debug: removing module "h264" core debug: Program doesn't contain anymore ES core debug: creating demux: access='' demux='ts' location='' file='(null)' core debug: looking for demux module matching "ts": 67 candidates ts debug: Standard set to Auto core debug: using demux module "ts" ts debug: DEMUX_SET_GROUP -1 (nil) ts debug: pid[4113] unknown ts debug: pid[4097] unknown ts debug: PATCallBack called ts debug: new PAT ts_id=0 version=0 current_next=1 ts debug: * number=0 pid=31 ts debug: * number=1 pid=256 ts debug: temporary receiving program 1 ts debug: PMTCallBack called for program 1 ts debug: new PMT program number=1 version=0 pid_pcr=4097 ts debug: * PMT descriptor: Registration (0x5) ts debug: : HDMV registration ts debug: * PMT descriptor: Unknown Private (0x88) ts debug: * pid=4113 type=0x1b AVC video stream as defined in ITU-T Rec. H.264 ts debug: - ES descriptor tag 0x5 ts debug: => pid 4113 has now es fcc=h264 core debug: selecting program id=1 core debug: looking for packetizer module matching "any": 24 candidates core debug: using packetizer module "h264" core debug: looking for decoder module matching "any": 48 candidates avcodec debug: CPU flags: 0x000fd3db avcodec debug: allowing 4 thread(s) for decoding avcodec debug: codec (h264) started avcodec debug: using frame thread mode with 4 threads core debug: using decoder module "avcodec" libbluray info: Adding ES 4113 ts debug: Default program is 1 ts warning: first packet for pid=4113 cc=0xc ts debug: pid[31] unknown h264 warning: waiting for SPS/PPS h264 warning: waiting for SPS/PPS h264 warning: waiting for SPS/PPS h264 warning: waiting for SPS/PPS h264 warning: waiting for SPS/PPS h264 warning: waiting for SPS/PPS h264 warning: waiting for SPS/PPS h264 warning: waiting for SPS/PPS h264 warning: waiting for SPS/PPS h264 warning: waiting for SPS/PPS h264 warning: waiting for SPS/PPS h264 warning: waiting for SPS/PPS h264 warning: waiting for SPS/PPS h264 warning: waiting for SPS/PPS h264 warning: waiting for SPS/PPS h264 warning: waiting for SPS/PPS h264 warning: waiting for SPS/PPS h264 warning: waiting for SPS/PPS h264 warning: waiting for SPS/PPS h264 warning: waiting for SPS/PPS core warning: VoutDisplayEvent 'pictures invalid' core warning: VoutDisplayEvent 'pictures invalid' core debug: removing module "swscale" core debug: Filter 0x7fda8c035488 removed from chain core debug: A filter to adapt decoder I420 to display RV32 is needed core debug: looking for video filter module matching "any": 65 candidates swscale debug: 1920x1080 (1920x1090) chroma: I420 -> 1660x934 (1660x942) chroma: RV32 with scaling using Bicubic (good quality) core debug: using video filter module "swscale" core debug: Filter 'Swscale' (0x7fda8c035488) appended to chain h264 warning: waiting for SPS/PPS h264 debug: found NAL_SPS (sps_id=0) h264 debug: found NAL_PPS (pps_id=0 sps_id=0) h264 debug: Seen SEI recovery point, 0 recovery frames h264 debug: Recovery from SEI recovery point complete core debug: restarting module due to input format change core debug: removing module "avcodec" avcodec debug: ffmpeg codec (h264) stopped core debug: looking for decoder module matching "any": 48 candidates avcodec debug: CPU flags: 0x000fd3db avcodec debug: allowing 4 thread(s) for decoding avcodec debug: codec (h264) started avcodec debug: using frame thread mode with 4 threads core debug: using decoder module "avcodec" avcodec debug: available hardware decoder output format 109 (vdpau) avcodec debug: available hardware decoder output format 53 (vaapi_vld) avcodec debug: available software decoder output format 0 (yuv420p) avcodec warning: thread type 1: disabling hardware acceleration core debug: trying to reuse free vout core debug: removing module "freetype" core debug: looking for text renderer module matching "any": 3 candidates freetype debug: Building font databases. freetype debug: Took 0 microseconds core debug: using text renderer module "freetype" core debug: reusing provided vout avcodec warning: plane 0 not aligned: disabling direct rendering core warning: VoutDisplayEvent 'pictures invalid' core warning: VoutDisplayEvent 'pictures invalid' core debug: removing module "swscale" core debug: Filter 0x7fda8c035488 removed from chain core debug: A filter to adapt decoder I420 to display RV32 is needed core debug: looking for video filter module matching "any": 65 candidates swscale debug: 1920x1080 (1920x1090) chroma: I420 -> 1660x934 (1660x942) chroma: RV32 with scaling using Bicubic (good quality) core debug: using video filter module "swscale" core debug: Filter 'Swscale' (0x7fda8c035488) appended to chain ts debug: Can't read TS packet at 797952 core debug: removing module "ts" core debug: killing decoder fourcc `h264', 22 PES in FIFO core debug: removing module "avcodec" avcodec debug: ffmpeg codec (h264) stopped core debug: saving a free vout core debug: reusing provided vout qt debug: Qt: Fullscreen state changed core debug: removing module "h264" core debug: Program doesn't contain anymore ES core debug: creating demux: access='' demux='ts' location='' file='(null)' core debug: looking for demux module matching "ts": 67 candidates ts debug: Standard set to Auto core debug: using demux module "ts" ts debug: DEMUX_SET_GROUP -1 (nil) ts debug: PATCallBack called ts debug: new PAT ts_id=0 version=0 current_next=1 ts debug: * number=0 pid=31 ts debug: * number=1 pid=256 ts debug: temporary receiving program 1 ts debug: PMTCallBack called for program 1 ts debug: new PMT program number=1 version=0 pid_pcr=4097 ts debug: * PMT descriptor: Registration (0x5) ts debug: : HDMV registration ts debug: * PMT descriptor: Unknown Private (0x88) ts debug: * pid=4113 type=0x1b AVC video stream as defined in ITU-T Rec. H.264 ts debug: - ES descriptor tag 0x5 ts debug: => pid 4113 has now es fcc=h264 core debug: selecting program id=1 core debug: looking for packetizer module matching "any": 24 candidates core debug: using packetizer module "h264" core debug: looking for decoder module matching "any": 48 candidates avcodec debug: CPU flags: 0x000fd3db avcodec debug: allowing 4 thread(s) for decoding avcodec debug: codec (h264) started avcodec debug: using frame thread mode with 4 threads core debug: using decoder module "avcodec" libbluray info: Adding ES 4113 ts debug: Default program is 1 ts debug: pid[31] unknown ts debug: pid[4097] unknown h264 debug: found NAL_SPS (sps_id=0) h264 debug: found NAL_PPS (pps_id=0 sps_id=0) h264 debug: Seen SEI recovery point, 0 recovery frames h264 debug: Recovery from SEI recovery point complete core debug: restarting module due to input format change core debug: removing module "avcodec" avcodec debug: ffmpeg codec (h264) stopped core debug: looking for decoder module matching "any": 48 candidates avcodec debug: CPU flags: 0x000fd3db avcodec debug: allowing 4 thread(s) for decoding avcodec debug: codec (h264) started avcodec debug: using frame thread mode with 4 threads core debug: using decoder module "avcodec" avcodec debug: available hardware decoder output format 109 (vdpau) avcodec debug: available hardware decoder output format 53 (vaapi_vld) avcodec debug: available software decoder output format 0 (yuv420p) avcodec warning: thread type 1: disabling hardware acceleration core debug: trying to reuse free vout core debug: removing module "freetype" core debug: looking for text renderer module matching "any": 3 candidates freetype debug: Building font databases. freetype debug: Took 0 microseconds core debug: using text renderer module "freetype" core debug: reusing provided vout qt debug: Qt: Fullscreen state changed avcodec warning: plane 0 not aligned: disabling direct rendering core warning: VoutDisplayEvent 'pictures invalid' core warning: VoutDisplayEvent 'pictures invalid' core debug: removing module "swscale" core debug: Filter 0x7fda8c035488 removed from chain core debug: A filter to adapt decoder I420 to display RV32 is needed core debug: looking for video filter module matching "any": 65 candidates swscale debug: 1920x1080 (1920x1090) chroma: I420 -> 1660x934 (1660x942) chroma: RV32 with scaling using Bicubic (good quality) core debug: using video filter module "swscale" core debug: Filter 'Swscale' (0x7fda8c022298) appended to chain ts debug: Can't read TS packet at 17399808 core debug: removing module "ts" core debug: killing decoder fourcc `h264', 7 PES in FIFO core debug: removing module "avcodec" avcodec debug: ffmpeg codec (h264) stopped core debug: saving a free vout core debug: reusing provided vout qt debug: Qt: Fullscreen state changed core debug: removing module "h264" core debug: Program doesn't contain anymore ES core debug: creating demux: access='' demux='ts' location='' file='(null)' core debug: looking for demux module matching "ts": 67 candidates ts debug: Standard set to Auto core debug: using demux module "ts" ts debug: DEMUX_SET_GROUP -1 (nil) ts debug: PATCallBack called ts debug: new PAT ts_id=0 version=0 current_next=1 ts debug: * number=0 pid=31 ts debug: * number=1 pid=256 ts debug: temporary receiving program 1 ts debug: PMTCallBack called for program 1 ts debug: new PMT program number=1 version=0 pid_pcr=4097 ts debug: * PMT descriptor: Registration (0x5) ts debug: : HDMV registration ts debug: * PMT descriptor: Unknown Private (0x88) ts debug: * pid=4113 type=0x1b AVC video stream as defined in ITU-T Rec. H.264 ts debug: - ES descriptor tag 0x5 ts debug: => pid 4113 has now es fcc=h264 core debug: selecting program id=1 core debug: looking for packetizer module matching "any": 24 candidates core debug: using packetizer module "h264" core debug: looking for decoder module matching "any": 48 candidates avcodec debug: CPU flags: 0x000fd3db avcodec debug: allowing 4 thread(s) for decoding avcodec debug: codec (h264) started avcodec debug: using frame thread mode with 4 threads core debug: using decoder module "avcodec" libbluray info: Adding ES 4113 ts debug: Default program is 1 core debug: looking for packetizer module matching "any": 24 candidates core debug: using packetizer module "mlp" core debug: looking for decoder module matching "any": 48 candidates avcodec debug: CPU flags: 0x000fd3db avcodec debug: codec (truehd) started core debug: using decoder module "avcodec" core debug: destroying useless vout core debug: removing module "blend" core debug: removing module "swscale" core debug: Filter 0x7fda8c022298 removed from chain core debug: removing module "xcb_x11" dbus_screensaver debug: got cookie 6807 core debug: removing module "dbus_screensaver" qt debug: releasing video... qt debug: Video is not needed anymore core debug: removing module "freetype" core debug: removing module "yuvp" core debug: removing module "swscale" libbluray info: Adding ES 4612 ts debug: * pid=4613 type=0x90 User Private ts debug: + HDMV registration applied to pid 4613 type 0x90 ts debug: => pid 4613 has now es fcc=bdpg libbluray info: Adding ES 4613 ts debug: * pid=4614 type=0x90 User Private ts debug: + HDMV registration applied to pid 4614 type 0x90 ts debug: => pid 4614 has now es fcc=bdpg libbluray info: Adding ES 4614 ts debug: pid[31] unknown ts debug: pid[4097] unknown h264 debug: found NAL_SPS (sps_id=0) h264 debug: found NAL_PPS (pps_id=0 sps_id=0) h264 debug: Seen SEI recovery point, 0 recovery frames h264 debug: Recovery from SEI recovery point complete core debug: restarting module due to input format change core debug: removing module "avcodec" mlp debug: emulated sync word (no sync on following frame) avcodec debug: ffmpeg codec (h264) stopped mlp debug: emulated sync word (no sync on following frame) mlp debug: emulated sync word (no sync on following frame) core debug: looking for decoder module matching "any": 48 candidates avcodec debug: CPU flags: 0x000fd3db avcodec debug: allowing 4 thread(s) for decoding mlp debug: emulated sync word (no sync on following frame) avcodec debug: codec (h264) started avcodec debug: using frame thread mode with 4 threads core debug: using decoder module "avcodec" mlp debug: emulated sync word (no sync on following frame) mlp debug: emulated sync word (no sync on following frame) mlp debug: emulated sync word (no sync on following frame) mlp debug: emulated sync word (no sync on following frame) mlp debug: emulated sync word (no sync on following frame) mlp debug: emulated sync word (no sync on following frame) mlp debug: emulated sync word (no sync on following frame) avcodec debug: available hardware decoder output format 109 (vdpau) avcodec debug: available hardware decoder output format 53 (vaapi_vld) avcodec debug: available software decoder output format 0 (yuv420p) avcodec warning: thread type 1: disabling hardware acceleration core debug: looking for text renderer module matching "any": 3 candidates freetype debug: Building font databases. freetype debug: Took 0 microseconds core debug: using text renderer module "freetype" core debug: looking for video filter module matching "any": 65 candidates swscale debug: 32x32 (32x32) chroma: YUVA -> 16x16 (16x16) chroma: RGBA with scaling using Bicubic (good quality) core debug: using video filter module "swscale" core debug: looking for video filter module matching "any": 65 candidates yuvp debug: YUVP to YUVA converter core debug: using video filter module "yuvp" core debug: Deinterlacing available core debug: deinterlace -1, mode blend, is_needed 0 core debug: looking for vout window module matching "qt,any": 5 candidates qt debug: requesting video window... core debug: resized to 1920x1080 core debug: using vout window module "qt" core debug: looking for inhibit module matching "any": 2 candidates dbus_screensaver debug: found service org.freedesktop.ScreenSaver core debug: using inhibit module "dbus_screensaver" core debug: Opening vout display wrapper core debug: looking for vout display module matching "xcb_x11": 14 candidates core debug: VoutDisplayEvent 'resize' 1920x1080 xcb debug: connected to X11.0 server xcb debug: vendor : The X.Org Foundation xcb debug: version: 11604000 xcb debug: using screen 0xb4 xcb_x11 debug: using X11 visual ID 0x20 xcb_x11 debug: 24 bits depth xcb_x11 debug: 32 bits per pixel xcb_x11 debug: 32 bits line pad xcb_x11 debug: using X11 window 04400000 xcb_x11 debug: using X11 graphic context 04400001 core debug: using vout display module "xcb_x11" core debug: A filter to adapt decoder I420 to display RV32 is needed core debug: looking for video filter module matching "any": 65 candidates swscale debug: 1920x1080 (1920x1090) chroma: I420 -> 1920x1080 (1920x1090) chroma: RV32 with scaling using Bicubic (good quality) core debug: using video filter module "swscale" core debug: Filter 'Swscale' (0x7fda88008848) appended to chain core debug: original format sz 1920x1090, of (0,0), vsz 1920x1080, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0 core warning: VoutDisplayEvent 'pictures invalid' core warning: VoutDisplayEvent 'pictures invalid' core debug: removing module "swscale" core debug: Filter 0x7fda88008848 removed from chain core debug: A filter to adapt decoder I420 to display RV32 is needed core debug: looking for video filter module matching "any": 65 candidates core debug: removing module "freetype" core debug: looking for text renderer module matching "any": 3 candidates freetype debug: Building font databases. freetype debug: Took 0 microseconds swscale debug: 1920x1080 (1920x1090) chroma: I420 -> 1920x1080 (1920x1090) chroma: RV32 with scaling using Bicubic (good quality) core debug: using video filter module "swscale" core debug: Filter 'Swscale' (0x7fda88035488) appended to chain core debug: using text renderer module "freetype" avcodec warning: plane 0 not aligned: disabling direct rendering core debug: resized to 1920x934 core debug: VoutDisplayEvent 'resize' 1920x934 core debug: looking for video blending module matching "any": 1 candidates core debug: using video blending module "blend" core warning: VoutDisplayEvent 'pictures invalid' core debug: removing module "swscale" core debug: Filter 0x7fda88035488 removed from chain core debug: A filter to adapt decoder I420 to display RV32 is needed core debug: looking for video filter module matching "any": 65 candidates swscale debug: 1920x1080 (1920x1090) chroma: I420 -> 1660x934 (1660x942) chroma: RV32 with scaling using Bicubic (good quality) core debug: using video filter module "swscale" core debug: Filter 'Swscale' (0x7fda88008348) appended to chain mlp info: MLP channels: 8 samplerate: 48000 core debug: restarting module due to input format change core debug: removing module "avcodec" avcodec debug: ffmpeg codec (truehd) stopped core debug: looking for decoder module matching "any": 48 candidates core debug: reusing audio output core error: module not functional core debug: keeping audio output core error: failed to create audio output avcodec debug: CPU flags: 0x000fd3db avcodec debug: codec (truehd) started core debug: using decoder module "avcodec" core debug: reusing audio output alsa debug: using ALSA device: hw:CARD=PCH,DEV=3 alsa debug: Hardware PCM card 0 'HDA Intel PCH' device 3 subdevice 0 alsa debug: device name : HDMI 0 alsa debug: device ID : HDMI 0 alsa debug: subdevice name: subdevice #0 alsa debug: initial hardware setup: ACCESS: MMAP_INTERLEAVED RW_INTERLEAVED FORMAT: S16_LE S32_LE SUBFORMAT: STD SAMPLE_BITS: [16 32] FRAME_BITS: [32 64] CHANNELS: 2 RATE: [32000 192000] PERIOD_TIME: (83 256000] PERIOD_SIZE: [16 8192] PERIOD_BYTES: [128 65536] PERIODS: [2 32] BUFFER_TIME: (166 512000] BUFFER_SIZE: [32 16384] BUFFER_BYTES: [128 65536] TICK_TIME: ALL alsa debug: unsupported channel 2 position 12 alsa debug: unsupported channel 3 position 12 alsa debug: unsupported channel 3 position 12 alsa debug: unsupported channel 3 position 12 alsa debug: unsupported channel 4 position 14 alsa debug: unsupported channel 4 position 12 alsa debug: unsupported channel 4 position 12 alsa debug: unsupported channel 4 position 12 alsa debug: unsupported channel 4 position 12 alsa debug: unsupported channel 5 position 24 alsa debug: unsupported channel 5 position 21 alsa debug: unsupported channel 4 position 22 alsa debug: unsupported channel 4 position 16 alsa debug: unsupported channel 5 position 14 alsa debug: unsupported channel 5 position 14 alsa debug: unsupported channel 5 position 12 alsa debug: unsupported channel 5 position 12 alsa debug: unsupported channel 5 position 12 alsa debug: unsupported channel 6 position 24 alsa debug: unsupported channel 6 position 21 alsa debug: unsupported channel 5 position 22 alsa debug: unsupported channel 5 position 16 alsa debug: unsupported channel 6 position 21 alsa debug: unsupported channel 6 position 24 alsa debug: unsupported channel 5 position 24 alsa debug: unsupported channel 5 position 22 alsa debug: unsupported channel 5 position 16 alsa debug: unsupported channel 6 position 14 alsa debug: unsupported channel 6 position 12 alsa debug: unsupported channel 7 position 21 alsa debug: unsupported channel 7 position 24 alsa debug: unsupported channel 6 position 24 alsa debug: unsupported channel 6 position 22 alsa debug: unsupported channel 6 position 16 alsa debug: using channels map 0, type 2, 2 channel(s) alsa debug: final HW setup: ACCESS: RW_INTERLEAVED FORMAT: S16_LE SUBFORMAT: STD SAMPLE_BITS: 16 FRAME_BITS: 32 CHANNELS: 2 RATE: 48000 PERIOD_TIME: 40000 PERIOD_SIZE: 1920 PERIOD_BYTES: 7680 PERIODS: 8 BUFFER_TIME: 320000 BUFFER_SIZE: 15360 BUFFER_BYTES: 61440 TICK_TIME: 0 alsa debug: initial software parameters: tstamp_mode: NONE period_step: 1 avail_min: 1920 start_threshold: 1 stop_threshold: 15360 silence_threshold: 0 silence_size: 0 boundary: 8646911284551352320 alsa debug: final software parameters: tstamp_mode: NONE period_step: 1 avail_min: 1920 start_threshold: 1 stop_threshold: 15360 silence_threshold: 0 silence_size: 0 boundary: 8646911284551352320 core debug: output 's16l' 48000 Hz Stereo frame=1 samples/4 bytes core debug: looking for audio volume module matching "any": 2 candidates core debug: using audio volume module "integer_mixer" core debug: input 's16l' 48000 Hz 3F2M2R/LFE frame=1 samples/16 bytes core debug: looking for audio filter module matching "scaletempo": 14 candidates scaletempo debug: format: 48000 rate, 8 nch, 4 bps, fl32 scaletempo debug: params: 30 stride, 0.200 overlap, 14 search scaletempo debug: 1.000 scale, 1440.000 stride_in, 1440 stride_out, 1152 standing, 288 overlap, 672 search, 2400 queue, fl32 mode core debug: using audio filter module "scaletempo" core debug: conversion: 's16l'->'f32l' 48000 Hz->48000 Hz 3F2M2R/LFE->3F2M2R/LFE core debug: looking for audio converter module matching "any": 8 candidates audio_format debug: s16l->f32l, bits per sample: 16->32 core debug: using audio converter module "audio_format" core debug: conversion pipeline complete core debug: conversion: 'f32l'->'s16l' 48000 Hz->48000 Hz 3F2M2R/LFE->Stereo core debug: looking for audio converter module matching "any": 8 candidates core debug: using audio converter module "simple_channel_mixer" core debug: looking for audio converter module matching "any": 8 candidates audio_format debug: f32l->s16l, bits per sample: 32->16 core debug: using audio converter module "audio_format" core debug: conversion pipeline complete core debug: looking for audio resampler module matching "any": 3 candidates core debug: using audio resampler module "ugly" mlp debug: emulated sync word (no sync on following frame) mlp debug: emulated sync word (no sync on following frame) mlp debug: emulated sync word (no sync on following frame) mlp debug: emulated sync word (no sync on following frame) core debug: removing module "ugly" core debug: removing module "audio_format" core debug: removing module "scaletempo" core debug: removing module "simple_channel_mixer" core debug: removing module "audio_format" core debug: removing module "integer_mixer" core debug: keeping audio output core debug: reusing audio output alsa debug: using ALSA device: hw:CARD=PCH,DEV=3 alsa debug: Hardware PCM card 0 'HDA Intel PCH' device 3 subdevice 0 alsa debug: device name : HDMI 0 alsa debug: device ID : HDMI 0 alsa debug: subdevice name: subdevice #0 alsa debug: initial hardware setup: ACCESS: MMAP_INTERLEAVED RW_INTERLEAVED FORMAT: S16_LE S32_LE SUBFORMAT: STD SAMPLE_BITS: [16 32] FRAME_BITS: [32 64] CHANNELS: 2 RATE: [32000 192000] PERIOD_TIME: (83 256000] PERIOD_SIZE: [16 8192] PERIOD_BYTES: [128 65536] PERIODS: [2 32] BUFFER_TIME: (166 512000] BUFFER_SIZE: [32 16384] BUFFER_BYTES: [128 65536] TICK_TIME: ALL alsa debug: unsupported channel 2 position 12 alsa debug: unsupported channel 3 position 12 alsa debug: unsupported channel 3 position 12 alsa debug: unsupported channel 3 position 12 alsa debug: unsupported channel 4 position 14 alsa debug: unsupported channel 4 position 12 alsa debug: unsupported channel 4 position 12 alsa debug: unsupported channel 4 position 12 alsa debug: unsupported channel 4 position 12 alsa debug: unsupported channel 5 position 24 alsa debug: unsupported channel 5 position 21 alsa debug: unsupported channel 4 position 22 alsa debug: unsupported channel 4 position 16 alsa debug: unsupported channel 5 position 14 alsa debug: unsupported channel 5 position 14 alsa debug: unsupported channel 5 position 12 alsa debug: unsupported channel 5 position 12 alsa debug: unsupported channel 5 position 12 alsa debug: unsupported channel 6 position 24 alsa debug: unsupported channel 6 position 21 alsa debug: unsupported channel 5 position 22 alsa debug: unsupported channel 5 position 16 alsa debug: unsupported channel 6 position 21 alsa debug: unsupported channel 6 position 24 alsa debug: unsupported channel 5 position 24 alsa debug: unsupported channel 5 position 22 alsa debug: unsupported channel 5 position 16 alsa debug: unsupported channel 6 position 14 alsa debug: unsupported channel 6 position 12 alsa debug: unsupported channel 7 position 21 alsa debug: unsupported channel 7 position 24 alsa debug: unsupported channel 6 position 24 alsa debug: unsupported channel 6 position 22 alsa debug: unsupported channel 6 position 16 alsa debug: using channels map 0, type 2, 2 channel(s) alsa debug: final HW setup: ACCESS: RW_INTERLEAVED FORMAT: S32_LE SUBFORMAT: STD SAMPLE_BITS: 32 FRAME_BITS: 64 CHANNELS: 2 RATE: 48000 PERIOD_TIME: 40000 PERIOD_SIZE: 1920 PERIOD_BYTES: 15360 PERIODS: 4 BUFFER_TIME: 160000 BUFFER_SIZE: 7680 BUFFER_BYTES: 61440 TICK_TIME: 0 alsa debug: initial software parameters: tstamp_mode: NONE period_step: 1 avail_min: 1920 start_threshold: 1 stop_threshold: 7680 silence_threshold: 0 silence_size: 0 boundary: 8646911284551352320 alsa debug: final software parameters: tstamp_mode: NONE period_step: 1 avail_min: 1920 start_threshold: 1 stop_threshold: 7680 silence_threshold: 0 silence_size: 0 boundary: 8646911284551352320 core debug: output 's32l' 48000 Hz Stereo frame=1 samples/8 bytes core debug: looking for audio volume module matching "any": 2 candidates core debug: using audio volume module "integer_mixer" core debug: input 's32l' 48000 Hz 3F2M2R/LFE frame=1 samples/32 bytes core debug: looking for audio filter module matching "scaletempo": 14 candidates scaletempo debug: format: 48000 rate, 8 nch, 4 bps, fl32 scaletempo debug: params: 30 stride, 0.200 overlap, 14 search scaletempo debug: 1.000 scale, 1440.000 stride_in, 1440 stride_out, 1152 standing, 288 overlap, 672 search, 2400 queue, fl32 mode core debug: using audio filter module "scaletempo" core debug: conversion: 's32l'->'f32l' 48000 Hz->48000 Hz 3F2M2R/LFE->3F2M2R/LFE core debug: looking for audio converter module matching "any": 8 candidates audio_format debug: s32l->f32l, bits per sample: 32->32 core debug: using audio converter module "audio_format" core debug: conversion pipeline complete core debug: conversion: 'f32l'->'s32l' 48000 Hz->48000 Hz 3F2M2R/LFE->Stereo core debug: looking for audio converter module matching "any": 8 candidates core debug: using audio converter module "simple_channel_mixer" core debug: looking for audio converter module matching "any": 8 candidates audio_format debug: f32l->s32l, bits per sample: 32->32 core debug: using audio converter module "audio_format" core debug: conversion pipeline complete core debug: looking for audio resampler module matching "any": 3 candidates core debug: using audio resampler module "ugly" core debug: inserting 37638 zeroes mlp debug: emulated sync word (no sync on following frame) mlp debug: emulated sync word (no sync on following frame) mlp debug: emulated sync word (no sync on following frame) mlp debug: emulated sync word (no sync on following frame) mlp debug: emulated sync word (no sync on following frame) mlp debug: emulated sync word (no sync on following frame) mlp debug: emulated sync word (no sync on following frame) mlp debug: emulated sync word (no sync on following frame) mlp debug: emulated sync word (no sync on following frame) mlp debug: emulated sync word (no sync on following frame)

Hopefully there is something useful to debug the problem there.