[SOLVED] HDMI Board mute on startup?

[challenge]

FIXED, see post #8
hello, I have the Suptronics X6000 7.1ch board on a raspberry pi 4. this one here
It's an HDMI hat that gives me 8 ouputs, that I use with camilladsp as (up to) 4 ways active stereo crossover.

It works great, aside a little issue:
on boot it's muted. 
No I don't mean muted on alsamixer, I can see it enabled and discovered. I mean it doesn't emit any sound.
I've found an unique routine to make it work: 
I first have to hit play on moode, and then power cycle the board while moode is playing something. Then it'll emit sounds right after.
If I power cycle the board while moode isn't playing music, it doesn't work.
Don't know if it's related with camilla as it's always on. I will try it without it. EDIT: it happens even with camilladsp OFF.

The board is supposed to delay the power to the Pi, as it first boots itself, then (a few seconds later) it powers the Pi. 
But I tried both ways (individually powering the board and the pi, joined only by the HDMI cable) and nothing changes, the issue always happens.

I'll post moode and mpd logs as soon I get my hands over it again.
- Do you need other logs/system's?

I remember also I noted this in system logs, is it normal/related to this issue?:

Code:

bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=1)

Thank you

[Tim Curtis]

Does the issue occur without CamillaDSP on?

[challenge]

yes, confirmed, it happens even when camilladsp is off. Rebooted and power cycled and it's confirmed every time.
Will edit the first post, camilladsp isn't at fault here.
Same "trick" by power cycling it while moode is playing and sound starts.

Maybe something happening only over HDMI? kernel? 
The pi and the board are connected only over hdmi. no other connections.


no new messages in syslogs when power cycle the board.
Will try outputting moode to a tv hdmi and see if it happens there too.
kernel/alsa backends are way over my head, if you need data/logs just ask.

sample moode log with camilladsp off:

Code:

20210530 152428 worker: -- Start
20210530 152428 worker: Successfully daemonized
20210530 152428 worker: Integrity check (passed)
20210530 152429 worker: File check (OK)
20210530 152429 worker: Session vacuumed
20210530 152429 worker: Session loaded
20210530 152429 worker: Debug logging (OFF)
20210530 152429 worker: -- Audio debug
20210530 152429 worker: ALSA cards: (0:b1 | 1:Headphones | 2:empty | 3:empty
20210530 152429 worker: MPD config: (0:Pi HDMI 1 | mixer:(HDMI) | card:0)
20210530 152429 worker: ALSA mixer actual (HDMI)
20210530 152429 worker: ALSA HDMI volume set to (0%)
20210530 152429 worker: -- System
20210530 152430 worker: Host     (moode)
20210530 152431 worker: moOde    (7.2.1 2021-05-06)
20210530 152431 worker: RaspiOS  (10.6)
20210530 152431 worker: Kernel   (5.4.77-v7l+ #1371)
20210530 152431 worker: Platform (Pi-4B 1.1 1GB)
20210530 152431 worker: ARM arch (armv7l, 32-bit kernel)
20210530 152431 worker: MPD ver  (0.22.6_p0x3)
20210530 152431 worker: CPU gov  (ondemand)
20210530 152431 worker: USB boot not available
20210530 152431 worker: File system expanded
20210530 152431 worker: HDMI port on
20210530 152431 worker: -- Network
20210530 152431 worker: eth0 exists
20210530 152431 worker: IP addr (192.168.1.35)
20210530 152431 worker: Netmask (255.255.255.0)
20210530 152431 worker: Gateway (192.168.1.1)
20210530 152431 worker: Pri DNS (192.168.1.1)
20210530 152431 worker: Domain  ((***censored***)
20210530 152431 worker: wlan0 exists
20210530 152431 worker: wifi country ((***censored***)
20210530 152431 worker: wlan0 trying SSID (***censored***)
20210530 152431 worker: IP addr (192.168.1.37)
20210530 152431 worker: Netmask (255.255.255.0)
20210530 152431 worker: Gateway (192.168.1.1)
20210530 152431 worker: Pri DNS (192.168.1.1)
20210530 152431 worker: Domain  ((***censored***)
20210530 152431 worker: Pi integrated wlan0 power save disabled
20210530 152431 worker: -- Audio config
20210530 152431 worker: ALSA card number (0)
20210530 152431 worker: MPD audio output (Pi HDMI 1)
20210530 152431 worker: Audio formats (U8, S16_LE)
20210530 152431 worker: ALSA mixer name (HDMI)
20210530 152431 worker: MPD volume control (hardware)
20210530 152431 worker: Hdwr volume controller exists
20210530 152431 worker: Max ALSA volume (100%)
20210530 152431 worker: ALSA loopback (Off)
20210530 152431 worker: Reset renderer active flags
20210530 152431 worker: CamillaDSP (off)
20210530 152431 worker: -- MPD startup
20210530 152432 worker: MPD started
20210530 152432 worker: MPD accepting connections
20210530 152432 worker: MPD output 1 ALSA Default (on)
20210530 152432 worker: MPD output 2 ALSA Bluetooth (off)
20210530 152432 worker: MPD output 3 HTTP Server (off)
20210530 152432 worker: MPD crossfade (off)
20210530 152432 worker: MPD ignore CUE files (yes)
20210530 152432 worker: -- Feature availability
20210530 152432 worker: Source select (available)
20210530 152432 worker: Source select (source: MPD)
20210530 152432 worker: Source select (output: Pi HDMI 1)
20210530 152432 worker: Bluetooth (available)
20210530 152432 worker: Airplay renderer (available)
20210530 152432 worker: Spotify renderer (available)
20210530 152432 worker: Squeezelite (available)
20210530 152432 worker: RoonBridge renderer (not installed)
20210530 152432 worker: UPnP renderer (available)
20210530 152432 worker: DLNA server (available)
20210530 152432 worker: UPnP browser (available)
20210530 152432 worker: GPIO button handler (available)
20210530 152432 worker: Stream recorder (n/a)
20210530 152432 worker: -- Music sources
20210530 152432 worker: USB sources (none attached)
20210530 152433 worker: NAS and UPnP sources (mountall initiated)
20210530 152433 worker: -- Other
20210530 152433 worker: USB volume knob (Off)
20210530 152433 worker: USB auto-mounter (udisks-glue)
20210530 152433 worker: LED0 (On)
20210530 152433 worker: LED1 (On)
20210530 152433 worker: Saved MPD vol level (0)
20210530 152433 worker: Preamp volume level (0)
20210530 152433 worker: MPD volume level (13) restored
20210530 152433 worker: ALSA HDMI volume (50%)
20210530 152433 worker: Auto-play (Off)
20210530 152433 worker: Maintenance interval (3 hours)
20210530 152433 worker: Screen saver activation (Never)
20210530 152433 worker: Session permissions (OK)
20210530 152433 worker: Watchdog started
20210530 152433 worker: Ready

aplay -l

Code:

pi@moode:~ $ aplay -l
**** List of PLAYBACK Hardware Devices ****
card 0: b1 [bcm2835 HDMI 1], device 0: bcm2835 HDMI 1 [bcm2835 HDMI 1]
 Subdevices: 4/4
 Subdevice #0: subdevice #0
 Subdevice #1: subdevice #1
 Subdevice #2: subdevice #2
 Subdevice #3: subdevice #3
card 1: Headphones [bcm2835 Headphones], device 0: bcm2835 Headphones [bcm2835 Headphones]
 Subdevices: 4/4
 Subdevice #0: subdevice #0
 Subdevice #1: subdevice #1
 Subdevice #2: subdevice #2
 Subdevice #3: subdevice #3

aplay -L

Code:

pi@moode:~ $ aplay -L
null
   Discard all samples (playback) or generate zero samples (capture)
btaplay_dmix
_audioout
_deviceout
_audioout__
alsaequal
plug_alsaequal
btstream
camilladsp
crossfeed
plug_bs2b
eqfa12p
plug_eqfa12p
invpolarity
default:CARD=b1
   bcm2835 HDMI 1, bcm2835 HDMI 1
   Default Audio Device
sysdefault:CARD=b1
   bcm2835 HDMI 1, bcm2835 HDMI 1
   Default Audio Device
dmix:CARD=b1,DEV=0
   bcm2835 HDMI 1, bcm2835 HDMI 1
   Direct sample mixing device
dsnoop:CARD=b1,DEV=0
   bcm2835 HDMI 1, bcm2835 HDMI 1
   Direct sample snooping device
hw:CARD=b1,DEV=0
   bcm2835 HDMI 1, bcm2835 HDMI 1
   Direct hardware device without any conversions
plughw:CARD=b1,DEV=0
   bcm2835 HDMI 1, bcm2835 HDMI 1
   Hardware device with all software conversions
default:CARD=Headphones
   bcm2835 Headphones, bcm2835 Headphones
   Default Audio Device
sysdefault:CARD=Headphones
   bcm2835 Headphones, bcm2835 Headphones
   Default Audio Device
dmix:CARD=Headphones,DEV=0
   bcm2835 Headphones, bcm2835 Headphones
   Direct sample mixing device
dsnoop:CARD=Headphones,DEV=0
   bcm2835 Headphones, bcm2835 Headphones
   Direct sample snooping device
hw:CARD=Headphones,DEV=0
   bcm2835 Headphones, bcm2835 Headphones
   Direct hardware device without any conversions
plughw:CARD=Headphones,DEV=0
   bcm2835 Headphones, bcm2835 Headphones
   Hardware device with all software conversions

mpd.log

Code:

May 30 15:50 : client: [24] opened from 127.0.0.1:35532
May 30 15:50 : client: [24] process command "status"
May 30 15:50 : client: [24] command returned 0
May 30 15:50 : client: [24] process command "idle"
May 30 15:50 : client: [24] command returned 1
May 30 15:50 : client: [25] opened from 127.0.0.1:35536
May 30 15:50 : client: [25] process command "playlistinfo"
May 30 15:50 : client: [25] command returned 0
May 30 15:50 : client: [25] process command "close"
May 30 15:50 : client: [25] command returned 4
May 30 15:50 : client: [25] closed
May 30 15:50 : client: [26] opened from 127.0.0.1:35540
May 30 15:50 : client: [26] process command "lsinfo "SDCARD""
May 30 15:50 : client: [26] command returned 0
May 30 15:50 : client: [26] process command "close"
May 30 15:50 : client: [26] command returned 4
May 30 15:50 : client: [26] closed
May 30 15:51 : client: [27] opened from 127.0.0.1:35544
May 30 15:51 : client: [27] process command "playlistfind file "SDCARD/Stereo Test""
May 30 15:51 : client: [27] command returned 0
May 30 15:51 : client: [27] process command "status"
May 30 15:51 : client: [27] command returned 0
May 30 15:51 : client: [27] process command list
May 30 15:51 : client: process command "add "SDCARD/Stereo Test""
May 30 15:51 : client: command returned 0
May 30 15:51 : client: process command "play 0"
May 30 15:51 : playlist: play 0:"SDCARD/Stereo Test/LRMonoPhase4.flac"
May 30 15:51 : client: command returned 0
May 30 15:51 : client: [27] process command list returned 0
May 30 15:51 : client: [27] process command "close"
May 30 15:51 : client: [27] command returned 4
May 30 15:51 : client: [27] closed
May 30 15:51 : client: [24] process command "status"
May 30 15:51 : client: [24] command returned 0
May 30 15:51 : decoder_thread: probing plugin flac
May 30 15:51 : client: [24] process command "currentsong"
May 30 15:51 : client: [24] command returned 0
May 30 15:51 : decoder: audio_format=48000:16:2, seekable=true
May 30 15:51 : decoder: converting to 96000:24:2
May 30 15:51 : soxr: soxr engine 'single-precision'
May 30 15:51 : soxr: soxr precision=20, phase_response=50.00, passband_end=0.91, stopband_begin=1.00
May 30 15:51 : soxr: samplerate conversion ratio to 2.00
May 30 15:51 : alsa_output: opened _audioout type=COPY
May 30 15:51 : alsa_output: buffer: size=960..32768 time=10000..341334
May 30 15:51 : alsa_output: period: size=960..32768 time=10000..341334
May 30 15:51 : alsa_output: default period_time = buffer_time/4 = 341333/4 = 85333
May 30 15:51 : alsa_output: format=S16_LE (Signed 16 bit Little Endian)
May 30 15:51 : alsa_output: buffer_size=32768 period_size=8192
May 30 15:51 : output: opened "ALSA Default" (alsa) audio_format=96000:16:2
May 30 15:51 : output: converting in=96000:24:2 -> f=96000:24:2 -> out=96000:16:2
May 30 15:51 : client: [24] process command "lsinfo "SDCARD/Stereo Test/LRMonoPhase4.flac""
May 30 15:51 : client: [24] command returned 0
May 30 15:51 : client: [24] process command "close"
May 30 15:51 : client: [24] command returned 4
May 30 15:51 : client: [24] closed
May 30 15:51 : client: [28] opened from 127.0.0.1:35548
May 30 15:51 : client: [28] process command "status"
May 30 15:51 : client: [28] command returned 0
May 30 15:51 : client: [28] process command "idle"
May 30 15:51 : client: [28] command returned 1
May 30 15:51 : client: [29] opened from 127.0.0.1:35552
May 30 15:51 : client: [29] process command "playlistinfo"
May 30 15:51 : client: [29] command returned 0
May 30 15:51 : client: [29] process command "close"
May 30 15:51 : client: [29] command returned 4
May 30 15:51 : client: [29] closed
May 30 15:51 : client: [30] opened from 127.0.0.1:35556
May 30 15:51 : client: [30] process command "setvol 30"
May 30 15:51 : client: [30] command returned 0
May 30 15:51 : client: [30] process command "close"
May 30 15:51 : client: [30] command returned 4
May 30 15:51 : client: [30] closed
May 30 15:51 : client: [28] process command "status"
May 30 15:51 : client: [28] command returned 0
May 30 15:51 : client: [28] process command "currentsong"
May 30 15:51 : client: [28] command returned 0
May 30 15:51 : client: [28] process command "lsinfo "SDCARD/Stereo Test/LRMonoPhase4.flac""
May 30 15:51 : client: [28] command returned 0
May 30 15:51 : client: [28] process command "close"
May 30 15:51 : client: [28] command returned 4
May 30 15:51 : client: [28] closed
May 30 15:51 : client: [31] opened from 127.0.0.1:35560
May 30 15:51 : client: [31] process command "status"
May 30 15:51 : client: [31] command returned 0
May 30 15:51 : client: [31] process command "idle"
May 30 15:51 : client: [31] command returned 1
May 30 15:51 : client: [32] opened from 127.0.0.1:35564
May 30 15:51 : client: [32] process command "pause"
May 30 15:51 : client: [32] command returned 0
May 30 15:51 : client: [32] process command "close"
May 30 15:51 : client: [32] command returned 4
May 30 15:51 : client: [32] closed
May 30 15:51 : output: closed "ALSA Default" (alsa)
May 30 15:51 : client: [31] process command "status"
May 30 15:51 : client: [31] command returned 0
May 30 15:51 : client: [31] process command "currentsong"
May 30 15:51 : client: [31] command returned 0
May 30 15:51 : client: [31] process command "lsinfo "SDCARD/Stereo Test/LRMonoPhase4.flac""
May 30 15:51 : client: [31] command returned 0
May 30 15:51 : client: [31] process command "close"
May 30 15:51 : client: [31] command returned 4
May 30 15:51 : client: [31] closed
May 30 15:51 : client: [33] opened from 127.0.0.1:35568
May 30 15:51 : client: [33] process command "status"
May 30 15:51 : client: [33] command returned 0
May 30 15:51 : client: [33] process command "idle"
May 30 15:51 : client: [33] command returned 1
May 30 15:51 : client: [34] opened from 127.0.0.1:35572
May 30 15:51 : client: [34] process command "playlistinfo"
May 30 15:51 : client: [34] command returned 0
May 30 15:51 : client: [34] process command "close"
May 30 15:51 : client: [34] command returned 4
May 30 15:51 : client: [34] closed
May 30 15:51 : client: [35] opened from 127.0.0.1:35576
May 30 15:51 : client: [35] process command "play 0"
May 30 15:51 : playlist: play 0:"SDCARD/Stereo Test/LRMonoPhase4.flac"
May 30 15:51 : alsa_output: opened _audioout type=COPY
May 30 15:51 : alsa_output: buffer: size=960..32768 time=10000..341334
May 30 15:51 : alsa_output: period: size=960..32768 time=10000..341334
May 30 15:51 : alsa_output: default period_time = buffer_time/4 = 341333/4 = 85333
May 30 15:51 : alsa_output: format=S16_LE (Signed 16 bit Little Endian)
May 30 15:51 : alsa_output: buffer_size=32768 period_size=8192
May 30 15:51 : output: opened "ALSA Default" (alsa) audio_format=96000:16:2
May 30 15:51 : output: converting in=96000:24:2 -> f=96000:24:2 -> out=96000:16:2
May 30 15:51 : client: [35] command returned 0
May 30 15:51 : client: [33] process command "status"
May 30 15:51 : client: [33] command returned 0
May 30 15:51 : client: [35] process command "close"
May 30 15:51 : client: [35] command returned 4
May 30 15:51 : client: [35] closed
May 30 15:51 : client: [33] process command "currentsong"
May 30 15:51 : client: [33] command returned 0
May 30 15:51 : client: [33] process command "lsinfo "SDCARD/Stereo Test/LRMonoPhase4.flac""
May 30 15:51 : client: [33] command returned 0
May 30 15:51 : client: [33] process command "close"
May 30 15:51 : client: [33] command returned 4
May 30 15:51 : client: [33] closed
May 30 15:51 : client: [36] opened from 127.0.0.1:35580
May 30 15:51 : client: [36] process command "status"
May 30 15:51 : client: [36] command returned 0
May 30 15:51 : client: [36] process command "idle"
May 30 15:51 : client: [36] command returned 1
May 30 15:51 : client: [37] opened from 127.0.0.1:35584
May 30 15:51 : client: [37] process command "playlistinfo"
May 30 15:51 : client: [37] command returned 0
May 30 15:51 : client: [37] process command "close"
May 30 15:51 : client: [37] command returned 4
May 30 15:51 : client: [37] closed
May 30 15:51 : client: [38] opened from 127.0.0.1:35588
May 30 15:51 : client: [38] process command "setvol 10"
May 30 15:51 : client: [38] command returned 0
May 30 15:51 : client: [38] process command "close"
May 30 15:51 : client: [38] command returned 4
May 30 15:51 : client: [38] closed
May 30 15:51 : client: [36] process command "status"
May 30 15:51 : client: [36] command returned 0
May 30 15:51 : client: [36] process command "currentsong"
May 30 15:51 : client: [36] command returned 0
May 30 15:51 : client: [36] process command "lsinfo "SDCARD/Stereo Test/LRMonoPhase4.flac""
May 30 15:51 : client: [36] command returned 0
May 30 15:51 : client: [36] process command "close"
May 30 15:51 : client: [36] command returned 4
May 30 15:51 : client: [36] closed
May 30 15:51 : client: [39] opened from 127.0.0.1:35592
May 30 15:51 : client: [39] process command "status"
May 30 15:51 : client: [39] command returned 0
May 30 15:51 : client: [39] process command "idle"
May 30 15:51 : client: [39] command returned 1
May 30 15:51 : state_file: Saving state file /var/lib/mpd/state
May 30 15:51 : client: [40] opened from 127.0.0.1:35596
May 30 15:51 : client: [40] process command "setvol 16"
May 30 15:51 : client: [40] command returned 0
May 30 15:51 : client: [40] process command "close"
May 30 15:51 : client: [40] command returned 4
May 30 15:51 : client: [40] closed
May 30 15:51 : client: [39] process command "status"
May 30 15:51 : client: [39] command returned 0
May 30 15:51 : client: [39] process command "currentsong"
May 30 15:51 : client: [39] command returned 0
May 30 15:51 : client: [39] process command "lsinfo "SDCARD/Stereo Test/LRMonoPhase4.flac""
May 30 15:51 : client: [39] command returned 0
May 30 15:51 : client: [39] process command "close"
May 30 15:51 : client: [39] command returned 4
May 30 15:51 : client: [39] closed
May 30 15:51 : client: [41] opened from 127.0.0.1:35600
May 30 15:51 : client: [41] process command "status"
May 30 15:51 : client: [41] command returned 0
May 30 15:51 : client: [41] process command "idle"
May 30 15:51 : client: [41] command returned 1
May 30 15:52 : client: [42] opened from 127.0.0.1:35604
May 30 15:52 : client: [42] process command "setvol 26"
May 30 15:52 : client: [42] command returned 0
May 30 15:52 : client: [42] process command "close"
May 30 15:52 : client: [42] command returned 4
May 30 15:52 : client: [42] closed
May 30 15:52 : client: [41] process command "status"
May 30 15:52 : client: [41] command returned 0
May 30 15:52 : client: [41] process command "currentsong"
May 30 15:52 : client: [41] command returned 0
May 30 15:52 : client: [41] process command "lsinfo "SDCARD/Stereo Test/LRMonoPhase4.flac""
May 30 15:52 : client: [41] command returned 0
May 30 15:52 : client: [41] process command "close"
May 30 15:52 : client: [41] command returned 4
May 30 15:52 : client: [41] closed
May 30 15:52 : client: [43] opened from 127.0.0.1:35608
May 30 15:52 : client: [43] process command "status"
May 30 15:52 : client: [43] command returned 0
May 30 15:52 : client: [43] process command "idle"
May 30 15:52 : client: [43] command returned 1
May 30 15:52 : client: [44] opened from 127.0.0.1:35612
May 30 15:52 : client: [44] process command "setvol 13"
May 30 15:52 : client: [44] command returned 0
May 30 15:52 : client: [44] process command "close"
May 30 15:52 : client: [44] command returned 4
May 30 15:52 : client: [44] closed
May 30 15:52 : client: [43] process command "status"
May 30 15:52 : client: [43] command returned 0
May 30 15:52 : client: [43] process command "currentsong"
May 30 15:52 : client: [43] command returned 0
May 30 15:52 : client: [43] process command "lsinfo "SDCARD/Stereo Test/LRMonoPhase4.flac""
May 30 15:52 : client: [43] command returned 0
May 30 15:52 : client: [43] process command "close"
May 30 15:52 : client: [43] command returned 4
May 30 15:52 : client: [43] closed
May 30 15:52 : client: [45] opened from 127.0.0.1:35616
May 30 15:52 : client: [45] process command "status"
May 30 15:52 : client: [45] command returned 0
May 30 15:52 : client: [45] process command "idle"
May 30 15:52 : client: [45] command returned 1
May 30 15:52 : client: [46] opened from 127.0.0.1:35620
May 30 15:52 : client: [46] process command "pause"
May 30 15:52 : client: [46] command returned 0
May 30 15:52 : client: [46] process command "close"
May 30 15:52 : client: [46] command returned 4
May 30 15:52 : client: [46] closed
May 30 15:52 : client: [45] process command "status"
May 30 15:52 : client: [45] command returned 0
May 30 15:52 : client: [45] process command "currentsong"
May 30 15:52 : output: closed "ALSA Default" (alsa)
May 30 15:52 : client: [45] command returned 0
May 30 15:52 : client: [45] process command "lsinfo "SDCARD/Stereo Test/LRMonoPhase4.flac""
May 30 15:52 : client: [45] command returned 0
May 30 15:52 : client: [45] process command "close"
May 30 15:52 : client: [45] command returned 4
May 30 15:52 : client: [45] closed
May 30 15:52 : client: [47] opened from 127.0.0.1:35624
May 30 15:52 : client: [47] process command "status"
May 30 15:52 : client: [47] command returned 0
May 30 15:52 : client: [47] process command "idle"
May 30 15:52 : client: [47] command returned 1
May 30 15:52 : client: [48] opened from 127.0.0.1:35628
May 30 15:52 : client: [48] process command "playlistinfo"
May 30 15:52 : client: [48] command returned 0
May 30 15:52 : client: [48] process command "close"
May 30 15:52 : client: [48] command returned 4
May 30 15:52 : client: [48] closed
May 30 15:53 : state_file: Saving state file /var/lib/mpd/state
May 30 15:54 : client: [49] opened from 127.0.0.1:35632
May 30 15:54 : client: [49] process command "subscribe dumy_channel"
May 30 15:54 : client: [49] command returned 0
May 30 15:54 : client: [49] process command "close"
May 30 15:54 : client: [49] command returned 4
May 30 15:54 : client: [49] closed
May 30 15:54 : client: [47] process command "status"
May 30 15:54 : client: [47] command returned 0
May 30 15:54 : client: [47] process command "currentsong"
May 30 15:54 : client: [47] command returned 0
May 30 15:54 : client: [47] process command "lsinfo "SDCARD/Stereo Test/LRMonoPhase4.flac""
May 30 15:54 : client: [47] command returned 0
May 30 15:54 : client: [47] process command "close"
May 30 15:54 : client: [47] command returned 4
May 30 15:54 : client: [47] closed
May 30 15:54 : client: [50] opened from 127.0.0.1:35636
May 30 15:54 : client: [50] process command "status"
May 30 15:54 : client: [50] command returned 0
May 30 15:54 : client: [50] process command "idle"
May 30 15:54 : client: [50] command returned 1
May 30 15:54 : client: [51] opened from 127.0.0.1:35640
May 30 15:54 : client: [51] process command "playlistinfo"
May 30 15:54 : client: [51] command returned 0
May 30 15:54 : client: [51] process command "close"
May 30 15:54 : client: [51] command returned 4
May 30 15:54 : client: [51] closed

[Tim Curtis]

If you have a Pi-3B+ see if same happens. Maybe it's unique to Pi-4 HDMI implementation.

[challenge]

tried this when it's mute:

Code:

pi@moode:~ $ aplay -D plughw:0,0 /usr/share/sounds/alsa/Front_Center.wav
Playing WAVE '/usr/share/sounds/alsa/Front_Center.wav' : Signed 16 bit Little Endian, Rate 48000 Hz, Mono

no errors as you can see but there's no sound from speakers.


Found some info HERE: Archlinux wiki Troubleshooting#HDMI:

If aplay does not output any errors, but still no sound is heard, "reboot" the receiver, monitor or tv set.
Since the HDMI interface executes a handshake on connection, it might have noticed before that there was no audio stream embedded, and disabled audio decoding.
If you are using a standalone window manager, you may need to have sound playing while plugging in the HDMI cable.

I tried the unplug and plugging back the HDMI cable while playing music but it doesn't work. 
I have to "reboot" (aka power cycle) the HDMI board while moode is playing and sound starts right there.
So could this be kernel?

[Tim Curtis]

There are a bunch of HDMI settings. Maybe some would influence the mute issue?
https://www.raspberrypi.org/documentatio...t/video.md
.

[challenge]

If you have a Pi-3B+ see if same happens. Maybe it's unique to Pi-4 HDMI implementation.

just for fun tried both Pi4 ports after a reboot. The second HDMI port it doesn't even output sound.
Only the first one works.
___
I think I only have a Pi 1 B+... does that count?  

There are a bunch of HDMI settings. Maybe some would influence the mute issue?
https://www.raspberrypi.org/documentatio...t/video.md
.

ok will try these and report back.

[challenge]

Fixed it, works on every boot!

The ignore_edid line is a must when using this board. Add to your /boot/config.txt:

Code:

hdmi_ignore_edid=0xa5000080

As soon as I remove that line it starts muted. When I add it fixes it.

My actual working /boot/config.txt, pretty sure some of those lines can be deleted, but I am posting so you see the other hdmi audio lines I use:

Code:

#disable_splash=1
disable_overscan=1
hdmi_drive=2
hdmi_blanking=1
hdmi_force_edid_audio=1
hdmi_force_hotplug=1
hdmi_ignore_edid=0xa5000080
hdmi_group=0
dtparam=i2c_arm=on
dtparam=i2s=on
dtparam=audio=on
#dtoverlay=disable-wifi
dtoverlay=disable-bt