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

Shamus · September 10, 2021 at 2:41 AM

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

**Klojum** · September 10, 2021 at 4:36 AM Official Post

2021-09-09 21:00:38.478 T:765 INFO <general>: Disabled debug logging due to GUI setting. Level 0.

Not quite full yet.. Always enable debugging and restart Kodi when you start to troubleshoot problems.

Shamus · September 10, 2021 at 6:24 AM

Let me enable debugging to get the logs.

Shamus · September 10, 2021 at 12:29 PM

Please find full debug logs attached for both 9.95.5 and 10.0.0

9.95.5_Debug.log 10.0.0_Debug.log

Shamus · September 11, 2021 at 9:31 AM

Should I file this as a bug?

**chewitt** · September 11, 2021 at 2:46 PM Official Post

popcornmix ^

**popcornmix** · September 17, 2021 at 2:16 PM

Working log 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
DEBUG <general>: CAESinkALSA::InitializeHW - Got: periodSize 9600, bufferSize 38400
DEBUG <general>: CAESinkALSA::InitializeHW - Setting timeout to 200 ms
DEBUG <general>: CAESinkALSA::GetChannelLayout - Input Channel Count: 8 Output Channel Count: 8
DEBUG <general>: CAESinkALSA::GetChannelLayout - Requested Layout: RAW, RAW, RAW, RAW, RAW, RAW, RAW, RAW
DEBUG <general>: CAESinkALSA::GetChannelLayout - Got Layout: RAW, RAW, RAW, RAW, RAW, RAW, RAW, RAW (ALSA: none)
DEBUG <general>: CActiveAESink::OpenSink - ALSA Initialized:
DEBUG <general>:   Output Device : vc4-hdmi-0 (vc4hdmi0)
DEBUG <general>:   Sample Rate   : 192000
DEBUG <general>:   Sample Format : AE_FMT_S16NE
DEBUG <general>:   Channel Count : 8
DEBUG <general>:   Channel Layout: RAW, RAW, RAW, RAW, RAW, RAW, RAW, RAW
DEBUG <general>:   Frames        : 9600
DEBUG <general>:   Frame Size    : 16

Display More

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.

Shamus · September 23, 2021 at 5:25 AM

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.

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

Similar Threads

LibreELEC (Matrix) 10.0.0

Raspberry Pi 4B not reaching 4K 60FPS when playing HEVC HDR movie (9.97.1) (fresh install)

9.2.7 and 9.2.8 crash upon boot RPi 400