ATMOS pass-through not working after upgrade from LibreELEC (official): 9.95.5 (RPi4.arm) to LibreELEC (official): 10.0.0 (RPi4.arm)

1st Official Post

    Hi all,

    Thought I'd ask this question here in case this is already a known issue and there is a quick fix.

    I've been successfully running LibreELEC (official): 9.95.5 (RPi4.arm) since it was released, mainly to get ATMOS pass-through audio and it works really well.

    I upgraded to 9.97.1 when it came out and found MKV files with ATMOS stopped opening instantly, when they finally did open after a good 20 second delay no audio was being passed through but video plays, no setting changes were made post upgrade. A quick rollback to 9.95.5 and the files in question were working again opening quickly and passing audio through so I figured I'd wait till v10.0.0 release.

    With the release of 10.0.0 I performed an upgrade from 9.95.5. The exact same issue with ATMOS/MKV files explained above happened again - Has anyone else seen this behavior?

    The test file I have used is: Dolby_Amaze_Lossless-ATMOS-thedigitaltheater.mkv which can be obtained here: Dolby Trailers - The Digital Theater

    From my quick checking in the logs it appears related to CAESinkAlsa not being able to initialize correctly when opening the HDMI port, it then attempts default and goes into a loop? Maybe I need to disable my unused HDMI port - I use the port closest to the USB-C power.

    9.95.5

    2021-09-09 21:04:55.317 T:867 INFO <general>: Creating audio thread

    2021-09-09 21:04:55.317 T:870 INFO <general>: running thread: CVideoPlayerAudio::Process()

    2021-09-09 21:04:55.391 T:738 INFO <general>: VideoPlayer: OnResetDisplay received

    2021-09-09 21:04:55.432 T:870 INFO <general>: CAEStreamParser::SyncTrueHD - TrueHD stream detected (8 channels, 48000Hz)

    2021-09-09 21:04:55.433 T:870 INFO <general>: Creating audio stream (codec id: 86060, channels: 8, sample rate: 48000, pass-through)

    2021-09-09 21:04:55.447 T:744 INFO <general>: CActiveAESink::OpenSink - initialize sink

    2021-09-09 21:04:55.647 T:744 INFO <general>: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=vc4hdmi0,DEV=0"

    2021-09-09 21:04:55.652 T:744 INFO <general>: CAESinkALSA::Initialize - Opened device "hdmi:CARD=vc4hdmi0,DEV=0,AES0=0x06,AES1=0x82,AES2=0x00,AES3=0x09"

    2021-09-09 21:04:55.776 T:738 INFO <general>: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY

    2021-09-09 21:04:55.791 T:738 INFO <general>: [WHITELIST] Searching the whitelist for: width: 1920, height: 1080, fps: 24.000, 3D: false

    2021-09-09 21:04:55.798 T:738 INFO <general>: Display resolution ADJUST : 1920x1080 @ 24.000000 Hz (40)

    2021-09-09 21:05:03.973 T:738 INFO <general>: Loading skin file: VideoOSD.xml, load type: KEEP_IN_MEMORY

    2021-09-09 21:05:05.348 T:738 INFO <general>: CVideoPlayer::CloseFile()

    2021-09-09 21:05:05.348 T:738 INFO <general>: VideoPlayer: waiting for threads to exit

    2021-09-09 21:05:05.356 T:867 INFO <general>: CVideoPlayer::OnExit()

    2021-09-09 21:05:05.357 T:867 INFO <general>: Closing stream player 1

    2021-09-09 21:05:05.357 T:867 INFO <general>: Waiting for audio thread to exit

    2021-09-09 21:05:05.410 T:870 INFO <general>: thread end: CVideoPlayerAudio::OnExit()

    2021-09-09 21:05:05.411 T:867 INFO <general>: Closing audio device


    10.0.0

    2021-09-09 21:01:12.928 T:874 INFO <general>: Creating audio thread

    2021-09-09 21:01:12.928 T:877 INFO <general>: running thread: CVideoPlayerAudio::Process()

    2021-09-09 21:01:12.981 T:765 INFO <general>: VideoPlayer: OnResetDisplay received

    2021-09-09 21:01:13.040 T:877 INFO <general>: CAEStreamParser::SyncTrueHD - TrueHD stream detected (8 channels, 48000Hz)

    2021-09-09 21:01:13.044 T:877 INFO <general>: Creating audio stream (codec id: 86060, channels: 8, sample rate: 48000, pass-through)

    2021-09-09 21:01:13.089 T:771 INFO <general>: CActiveAESink::OpenSink - initialize sink

    2021-09-09 21:01:13.289 T:771 INFO <general>: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=vc4hdmi0,DEV=0"

    2021-09-09 21:01:13.292 T:771 INFO <general>: CAESinkALSA::Initialize - Opened device "hdmi:CARD=vc4hdmi0,DEV=0,AES0=0x06,AES1=0x82,AES2=0x00,AES3=0x09"

    2021-09-09 21:01:13.295 T:771 WARNING <general>: CAESinkAlsa::InitializeHW - Using default alsa values - set failed

    2021-09-09 21:01:13.296 T:771 INFO <general>: CAESinkALSA::Initialize - Attempting to open device "default"

    2021-09-09 21:01:13.299 T:771 INFO <general>: CAESinkALSA - Unable to open device "default:AES0=0x06,AES1=0x82,AES2=0x00,AES3=0x09" for playback

    2021-09-09 21:01:13.300 T:771 INFO <general>: CAESinkALSA::Initialize - Opened device "default"

    2021-09-09 21:01:13.305 T:771 WARNING <general>: CAESinkAlsa::InitializeHW - Using default alsa values - set failed

    2021-09-09 21:01:13.307 T:771 ERROR <general>: CActiveAESink::OpenSink - no sink was returned

    2021-09-09 21:01:13.307 T:770 ERROR <general>: ActiveAE::InitSink - returned error

    I have attached my full logs below.

    9.95.5.log 10.0.0.log

    Working log has:

    and non-working has:

    Code
    DEBUG <general>: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=vc4hdmi0,DEV=0
INFO <general>: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=vc4hdmi0,DEV=0"
INFO <general>: CAESinkALSA::Initialize - Opened device "hdmi:CARD=vc4hdmi0,DEV=0,AES0=0x06,AES1=0x82,AES2=0x00,AES3=0x09"
DEBUG <general>: CAESinkALSA::InitializeHW - Request: periodSize 9600, bufferSize 38400
ARNING <general>: CAESinkAlsa::InitializeHW - Using default alsa values - set failed
DEBUG <general>: CAESinkALSA::InitializeHW - Could not init a valid sink
DEBUG <general>: CActiveAESink::OpenSink - trying to open device ALSA:default

    and log file continues with this "could not init a valid sink".


    I've just tried and it worked fine for me on latest 10.0.0. My log:

    http://ix.io/3z9q

    Can I suggest you go to settings/system/audio and "Reset above settings to default", and then just enable "Allow passthrough" and "TrueHD capable receiver". Don't change any other settings in that page. That was enough for TrueHD passthrough to work for me.

    Thanks for the response. Should I be starting with a "fresh" 10.0.0? I have performed upgrades on my setup for a few versions now and this was an upgrade to 10.0.0 as well when I got the errors.

    I'll do some testing this coming weekend upgrading and resetting to defaults and reply back with my logs/results, I also have a spare RPi4 i'll load up 10.0.0 fresh install as well.