Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

No audio when using vc4-kms-v3d on rpi4 #4543

Open
berndie opened this issue Aug 22, 2021 · 43 comments
Open

No audio when using vc4-kms-v3d on rpi4 #4543

berndie opened this issue Aug 22, 2021 · 43 comments

Comments

@berndie
Copy link

berndie commented Aug 22, 2021

Describe the bug

I'm using a raspberry pi 4 B with 4GB RAM (rev 1.2) (uname -a = Linux rpi4-ha 5.10.52-v7l+ #1441 SMP Tue Aug 3 18:11:56 BST 2021 armv7l GNU/Linux) with a HDMI connection (from port HDMI0) to a Samsung TV. When using the vc4-fkms-v3d option, everything works as expected (audio is played from the TV). However, when switching over to the vc4-kms-v3d, audio doesn't work anymore and I get multiple errors in dmesg (excerpt, you can see the full dmesg log below):

[    6.670739] vc4_hdmi fef00700.hdmi: Could not register sound card: -517
...

[   77.081367] hdmi-audio-codec hdmi-audio-codec.5.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19

...
[   88.674271] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.674283] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.674291]  MAI: ASoC: DAI prepare error: -22
...

When using alsamixer i can see both vc4-hdmi-0 and vc4-hdmi-1, but alsamixer reports that This sound device does not have any controls.

To reproduce

  1. Using a raspberry pi 4 with 4GB RAM (rev 1.2) (uname -a = Linux rpi4-ha 5.10.52-v7l+ #1441 SMP Tue Aug 3 18:11:56 BST 2021 armv7l GNU/Linux) with a HDMI connection (from port HDMI0) to a Samsung TV.

  2. Contents of /boot/config.txt

dtoverlay=vc4-kms-v3d,cma-512
dtoverlay=rpivid-v4l2
disable_overscan=1
disable_fw_kms_setup=1
  1. Try to play audio in kodi/desktop

Expected behaviour

Hear audio from the TV

Actual behaviour

No audio is played from the TV.

System

raspinfo output:

System Information
------------------

Raspberry Pi 4 Model B Rev 1.2
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"

Raspberry Pi reference 2020-05-27
Generated using pi-gen, https://github.com/RPi-Distro/pi-gen, 825107f04027269db77426046f5085475b1ea22f, stage4

Linux rpi4-ha 5.10.52-v7l+ #1441 SMP Tue Aug 3 18:11:56 BST 2021 armv7l GNU/Linux
Revision	: c03112
Serial		: 10000000b0073e2b
Model		: Raspberry Pi 4 Model B Rev 1.2
Throttled flag  : throttled=0x0
Camera          : supported=0 detected=0

Videocore information
---------------------

Aug  3 2021 18:14:56 
Copyright (c) 2012 Broadcom
version 40787ee5905644f639a2a0f6e00ae12e517a2211 (clean) (release) (start)

alloc failures:     0
compactions:        0
legacy block fails: 0

Filesystem information
----------------------
Filesystem              1K-blocks       Used Available Use% Mounted on
/dev/root                28916760   18489940   9200876  67% /
devtmpfs                  1702068          0   1702068   0% /dev
tmpfs                     1965236        708   1964528   1% /dev/shm
tmpfs                     1965236      10344   1954892   1% /run
tmpfs                        5120          4      5116   1% /run/lock
tmpfs                     1965236          0   1965236   0% /sys/fs/cgroup
/dev/mmcblk0p1             258095      49172    208923  20% /boot
/dev/mapper/exporl_hdd 3844637680 2797430416 851840148  77% /media/exporl_hdd
tmpfs                      393044          0    393044   0% /run/user/998
tmpfs                      393044          0    393044   0% /run/user/1000
overlay                  28916760   18489940   9200876  67% /var/lib/docker/overlay2/df226a77a54a6b913eaeb3972349d50a2841d15dc18dde3826d868e726efc469/merged
overlay                  28916760   18489940   9200876  67% /var/lib/docker/overlay2/027d454cd0792313fdcac5774e5d324a0704252befee72efe379bed8d999c208/merged
overlay                  28916760   18489940   9200876  67% /var/lib/docker/overlay2/65a75e4ccb521c6a7bfb2450d95e4fc1b79939f8c5a7a38be6050b6c179f2222/merged
overlay                  28916760   18489940   9200876  67% /var/lib/docker/overlay2/1dba2962975d2a8707c0177925fa2a0efda0beafc8aedc7b533c7e3a2a9d9401/merged
overlay                  28916760   18489940   9200876  67% /var/lib/docker/overlay2/320bc0d7599477307102cc77b98c16ca384e5e49cfd4ab47ca64cd885615d899/merged
overlay                  28916760   18489940   9200876  67% /var/lib/docker/overlay2/cafed24814878ec5ca298a7ec7e0f2cd3f1a825fdba65ee3a26ed07e2084a4f3/merged
overlay                  28916760   18489940   9200876  67% /var/lib/docker/overlay2/37e32e9dac632fb9874335739ab99f4ef7c2d2120acae91e88514c225c4544ab/merged
overlay                  28916760   18489940   9200876  67% /var/lib/docker/overlay2/74d396c0022db81b606ac939ce7d3ae59284c10c9e87b3d439c5aef2665958d3/merged
overlay                  28916760   18489940   9200876  67% /var/lib/docker/overlay2/f9c5029ad6a4ba596637ea41dd009c964e0b8e19eb5be2d8d7f8ce49edcc5186/merged
overlay                  28916760   18489940   9200876  67% /var/lib/docker/overlay2/83190f9b1fc533126a317d013d4f866e33447763eb1250a9f4dca51dbf242e29/merged

Filename				Type		Size		Used		Priority
/media/exporl_hdd/swapfile              file		2097148		0		-2

Package version information
---------------------------
raspberrypi-ui-mods:
  Installed: 1.20210706
raspberrypi-sys-mods:
  Installed: 20210706
openbox:
  Installed: 3.6.1-8+rpt5
lxpanel:
  Installed: 0.10.0-2+rpt15
pcmanfm:
  Installed: 1.3.1-1+rpt27
rpd-plym-splash:
  Installed: 0.26

Networking Information
----------------------

docker0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500
        inet x.x.x.x  netmask x.x.x.x  broadcast x.x.x.x
        inet6 y::y.y.y.y  prefixlen 64  scopeid 0x20<link>
        ether m.m.m.m  txqueuelen 0  (Ethernet)
        RX packets 170  bytes 18131 (17.7 KiB)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 308  bytes 107603 (105.0 KiB)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

eth0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500
        inet x.x.x.x  netmask x.x.x.x  broadcast x.x.x.x
        inet6 fd9b:200a:y::y.y.y.y  prefixlen 128  scopeid 0x0<global>
        inet6 y::y.y.y.y  prefixlen 64  scopeid 0x20<link>
        inet6 y.y.y.y.y.y.y.y  prefixlen 64  scopeid 0x0<global>
        ether m.m.m.m  txqueuelen 1000  (Ethernet)
        RX packets 14020  bytes 6464232 (6.1 MiB)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 18946  bytes 2279550 (2.1 MiB)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

hassio: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500
        inet x.x.x.x  netmask x.x.x.x  broadcast x.x.x.x
        inet6 y::y.y.y.y  prefixlen 64  scopeid 0x20<link>
        ether m.m.m.m  txqueuelen 0  (Ethernet)
        RX packets 2038  bytes 278027 (271.5 KiB)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 3307  bytes 597724 (583.7 KiB)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

lo: flags=73<UP,LOOPBACK,RUNNING>  mtu 65536
        inet x.x.x.x  netmask x.x.x.x
        inet6 ::1  prefixlen 128  scopeid 0x10<host>
        loop  txqueuelen 1000  (Local Loopback)
        RX packets 27668  bytes 3404092 (3.2 MiB)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 27668  bytes 3404092 (3.2 MiB)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

veth0e9f061: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500
        inet x.x.x.x  netmask x.x.x.x  broadcast x.x.x.x
        inet6 y::y.y.y.y  prefixlen 64  scopeid 0x20<link>
        ether m.m.m.m  txqueuelen 0  (Ethernet)
        RX packets 45  bytes 4726 (4.6 KiB)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 6590  bytes 714996 (698.2 KiB)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

veth11b80d6: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500
        inet x.x.x.x  netmask x.x.x.x  broadcast x.x.x.x
        inet6 y::y.y.y.y  prefixlen 64  scopeid 0x20<link>
        inet6 y::y.y.y.y  prefixlen 64  scopeid 0x20<link>
        ether m.m.m.m  txqueuelen 0  (Ethernet)
        RX packets 18  bytes 2488 (2.4 KiB)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 1612  bytes 307418 (300.2 KiB)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

veth38b92d4: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500
        inet x.x.x.x  netmask x.x.x.x  broadcast x.x.x.x
        inet6 y::y.y.y.y  prefixlen 64  scopeid 0x20<link>
        ether m.m.m.m  txqueuelen 0  (Ethernet)
        RX packets 170  bytes 20511 (20.0 KiB)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 555  bytes 159503 (155.7 KiB)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

veth4e23989: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500
        inet x.x.x.x  netmask x.x.x.x  broadcast x.x.x.x
        inet6 y::y.y.y.y  prefixlen 64  scopeid 0x20<link>
        ether m.m.m.m  txqueuelen 0  (Ethernet)
        RX packets 9  bytes 2318 (2.2 KiB)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 1355  bytes 244632 (238.8 KiB)
        TX errors 0  dropped 1 overruns 0  carrier 0  collisions 0

veth6cec26e: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500
        inet x.x.x.x  netmask x.x.x.x  broadcast x.x.x.x
        inet6 y::y.y.y.y  prefixlen 64  scopeid 0x20<link>
        ether m.m.m.m  txqueuelen 0  (Ethernet)
        RX packets 512  bytes 96316 (94.0 KiB)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 2217  bytes 467372 (456.4 KiB)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

vethb54f9c8: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500
        inet x.x.x.x  netmask x.x.x.x  broadcast x.x.x.x
        inet6 y::y.y.y.y  prefixlen 64  scopeid 0x20<link>
        ether m.m.m.m  txqueuelen 0  (Ethernet)
        RX packets 4  bytes 686 (686.0 B)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 1731  bytes 342322 (334.2 KiB)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

vethc73fe8c: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500
        inet x.x.x.x  netmask x.x.x.x  broadcast x.x.x.x
        inet6 y::y.y.y.y  prefixlen 64  scopeid 0x20<link>
        ether m.m.m.m  txqueuelen 0  (Ethernet)
        RX packets 649  bytes 112947 (110.2 KiB)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 1969  bytes 376416 (367.5 KiB)
        TX errors 0  dropped 1 overruns 0  carrier 0  collisions 0

vethe777822: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500
        inet x.x.x.x  netmask x.x.x.x  broadcast x.x.x.x
        inet6 y::y.y.y.y  prefixlen 64  scopeid 0x20<link>
        ether m.m.m.m  txqueuelen 0  (Ethernet)
        RX packets 1030  bytes 108926 (106.3 KiB)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 2890  bytes 455712 (445.0 KiB)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

vethfcc7e5d: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500
        inet x.x.x.x  netmask x.x.x.x  broadcast x.x.x.x
        inet6 y::y.y.y.y  prefixlen 64  scopeid 0x20<link>
        inet6 y::y.y.y.y  prefixlen 64  scopeid 0x20<link>
        ether m.m.m.m  txqueuelen 0  (Ethernet)
        RX packets 5  bytes 728 (728.0 B)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 1744  bytes 345165 (337.0 KiB)
        TX errors 0  dropped 1 overruns 0  carrier 0  collisions 0

wlan0: flags=4099<UP,BROADCAST,MULTICAST>  mtu 1500
        ether m.m.m.m  txqueuelen 1000  (Ethernet)
        RX packets 0  bytes 0 (0.0 B)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 0  bytes 0 (0.0 B)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0


USB Information
---------------

/:  Bus 02.Port 1: Dev 1, Class=root_hub, Driver=xhci_hcd/4p, 5000M
    |__ Port 1: Dev 2, If 0, Class=Mass Storage, Driver=uas, 5000M
/:  Bus 01.Port 1: Dev 1, Class=root_hub, Driver=xhci_hcd/1p, 480M
    |__ Port 1: Dev 2, If 0, Class=Hub, Driver=hub/4p, 480M
        |__ Port 2: Dev 5, If 0, Class=Human Interface Device, Driver=usbhid, 12M
        |__ Port 3: Dev 4, If 0, Class=Human Interface Device, Driver=usbhid, 12M
        |__ Port 3: Dev 4, If 1, Class=Human Interface Device, Driver=usbhid, 12M

config.txt
----------

arm_freq=1500
audio_pwm_mode=514
config_hdmi_boost=5
core_freq=500
core_freq_min=200
disable_commandline_tags=2
disable_fw_kms_setup=1
disable_l2cache=1
disable_overscan=1
display_hdmi_rotate=-1
display_lcd_rotate=-1
dvfs=3
enable_gic=1
force_eeprom_read=1
force_pwm_open=1
framebuffer_ignore_alpha=1
framebuffer_swap=1
gpu_freq=500
gpu_freq_min=250
init_uart_clock=0x2dc6c00
lcd_framerate=60
mask_gpu_interrupt0=3072
mask_gpu_interrupt1=25635
max_framebuffers=-1
over_voltage_avs=-30000
pause_burst_frames=1
program_serial_random=1
total_mem=4096
hdmi_force_cec_address:0=65535
hdmi_force_cec_address:1=65535
hdmi_pixel_freq_limit:0=0x11e1a300
hdmi_pixel_freq_limit:1=0x11e1a300
device_tree=-
overlay_prefix=overlays/
hdmi_cvt:0=
hdmi_cvt:1=
hdmi_edid_filename:0=
hdmi_edid_filename:1=
hdmi_timings:0=
hdmi_timings:1=

cmdline.txt
-----------
coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1  smsc95xx.macaddr=DC:A6:32:AC:2D:BA vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  console=ttyS0,115200 console=tty1 root=PARTUUID=e1d1e748-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait quiet splash plymouth.ignore-serial-consoles plymouth.enable=0

raspi-gpio settings
-------------------

BANK0 (GPIO 0 to 27):
GPIO 0: level=1 fsel=0 func=INPUT pull=UP
GPIO 1: level=1 fsel=0 func=INPUT pull=UP
GPIO 2: level=1 fsel=0 func=INPUT pull=UP
GPIO 3: level=1 fsel=0 func=INPUT pull=UP
GPIO 4: level=1 fsel=0 func=INPUT pull=UP
GPIO 5: level=1 fsel=0 func=INPUT pull=UP
GPIO 6: level=1 fsel=0 func=INPUT pull=UP
GPIO 7: level=1 fsel=0 func=INPUT pull=UP
GPIO 8: level=1 fsel=0 func=INPUT pull=UP
GPIO 9: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 10: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 11: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 12: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 13: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 14: level=1 fsel=0 func=INPUT pull=NONE
GPIO 15: level=1 fsel=0 func=INPUT pull=UP
GPIO 16: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 17: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 18: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 19: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 20: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 21: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 22: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 23: level=1 fsel=1 func=OUTPUT pull=DOWN
GPIO 24: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 25: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 26: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 27: level=0 fsel=0 func=INPUT pull=DOWN
BANK1 (GPIO 28 to 45):
GPIO 28: level=1 fsel=2 alt=5 func=RGMII_MDIO pull=UP
GPIO 29: level=0 fsel=2 alt=5 func=RGMII_MDC pull=DOWN
GPIO 30: level=0 fsel=7 alt=3 func=CTS0 pull=UP
GPIO 31: level=0 fsel=7 alt=3 func=RTS0 pull=NONE
GPIO 32: level=1 fsel=7 alt=3 func=TXD0 pull=NONE
GPIO 33: level=1 fsel=7 alt=3 func=RXD0 pull=UP
GPIO 34: level=1 fsel=7 alt=3 func=SD1_CLK pull=NONE
GPIO 35: level=1 fsel=7 alt=3 func=SD1_CMD pull=UP
GPIO 36: level=1 fsel=7 alt=3 func=SD1_DAT0 pull=UP
GPIO 37: level=1 fsel=7 alt=3 func=SD1_DAT1 pull=UP
GPIO 38: level=1 fsel=7 alt=3 func=SD1_DAT2 pull=UP
GPIO 39: level=1 fsel=7 alt=3 func=SD1_DAT3 pull=UP
GPIO 40: level=0 fsel=4 alt=0 func=PWM1_0 pull=NONE
GPIO 41: level=0 fsel=4 alt=0 func=PWM1_1 pull=NONE
GPIO 42: level=0 fsel=1 func=OUTPUT pull=UP
GPIO 43: level=1 fsel=0 func=INPUT pull=UP
GPIO 44: level=1 fsel=0 func=INPUT pull=UP
GPIO 45: level=1 fsel=0 func=INPUT pull=UP
BANK2 (GPIO 46 to 53):
GPIO 46: level=0 fsel=0 func=INPUT pull=UP
GPIO 47: level=0 fsel=0 func=INPUT pull=UP
GPIO 48: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 49: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 50: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 51: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 52: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 53: level=0 fsel=0 func=INPUT pull=DOWN

vcdbg log messages
------------------

005788.260: arasan: arasan_emmc_open
006201.436: brfs: File read: /mfs/sd/config.txt
006202.290: brfs: File read: 2003 bytes
006320.088: HDMI1:EDID error reading EDID block 0 attempt 0
006326.111: HDMI1:EDID error reading EDID block 0 attempt 1
006332.136: HDMI1:EDID error reading EDID block 0 attempt 2
006338.158: HDMI1:EDID error reading EDID block 0 attempt 3
006344.182: HDMI1:EDID error reading EDID block 0 attempt 4
006350.205: HDMI1:EDID error reading EDID block 0 attempt 5
006356.229: HDMI1:EDID error reading EDID block 0 attempt 6
006362.251: HDMI1:EDID error reading EDID block 0 attempt 7
006368.275: HDMI1:EDID error reading EDID block 0 attempt 8
006374.298: HDMI1:EDID error reading EDID block 0 attempt 9
006375.312: HDMI1:EDID giving up on reading EDID block 0
006376.695: brfs: File read: /mfs/sd/config.txt
007484.099: gpioman: gpioman_get_pin_num: pin DISPLAY_DSI_PORT not defined
007486.604: *** Restart logging
007486.626: brfs: File read: 2003 bytes
007550.794: hdmi: HDMI1:EDID error reading EDID block 0 attempt 0
007556.819: hdmi: HDMI1:EDID error reading EDID block 0 attempt 1
007562.843: hdmi: HDMI1:EDID error reading EDID block 0 attempt 2
007568.869: hdmi: HDMI1:EDID error reading EDID block 0 attempt 3
007574.892: hdmi: HDMI1:EDID error reading EDID block 0 attempt 4
007580.918: hdmi: HDMI1:EDID error reading EDID block 0 attempt 5
007588.945: hdmi: HDMI1:EDID error reading EDID block 0 attempt 6
007594.986: hdmi: HDMI1:EDID error reading EDID block 0 attempt 7
007601.025: hdmi: HDMI1:EDID error reading EDID block 0 attempt 8
007607.067: hdmi: HDMI1:EDID error reading EDID block 0 attempt 9
007608.091: hdmi: HDMI1:EDID giving up on reading EDID block 0
007613.164: hdmi: HDMI1:EDID error reading EDID block 0 attempt 0
007619.205: hdmi: HDMI1:EDID error reading EDID block 0 attempt 1
007625.243: hdmi: HDMI1:EDID error reading EDID block 0 attempt 2
007631.284: hdmi: HDMI1:EDID error reading EDID block 0 attempt 3
007637.323: hdmi: HDMI1:EDID error reading EDID block 0 attempt 4
007643.364: hdmi: HDMI1:EDID error reading EDID block 0 attempt 5
007649.403: hdmi: HDMI1:EDID error reading EDID block 0 attempt 6
007655.444: hdmi: HDMI1:EDID error reading EDID block 0 attempt 7
007661.483: hdmi: HDMI1:EDID error reading EDID block 0 attempt 8
007667.524: hdmi: HDMI1:EDID error reading EDID block 0 attempt 9
007668.548: hdmi: HDMI1:EDID giving up on reading EDID block 0
007668.578: hdmi: HDMI:hdmi_get_state is deprecated, use hdmi_get_display_state instead
007668.601: HDMI0: hdmi_pixel_encoding: 300000000
007668.621: HDMI1: hdmi_pixel_encoding: 300000000
007674.001: dtb_file 'bcm2711-rpi-4-b.dtb'
007682.431: brfs: File read: /mfs/sd/bcm2711-rpi-4-b.dtb
007682.456: Loading 'bcm2711-rpi-4-b.dtb' to 0x100 size 0xc2a1
007706.479: brfs: File read: 49825 bytes
007719.263: brfs: File read: /mfs/sd/overlays/overlay_map.dtb
007837.773: brfs: File read: 1667 bytes
007840.214: brfs: File read: /mfs/sd/config.txt
007841.603: brfs: File read: 2003 bytes
007857.017: brfs: File read: /mfs/sd/overlays/vc4-kms-v3d-pi4.dtbo
007976.304: Loaded overlay 'vc4-kms-v3d'
007976.321: dtparam: cma-512=true
008265.440: brfs: File read: 3831 bytes
008277.031: brfs: File read: /mfs/sd/overlays/rpivid-v4l2.dtbo
008295.421: Loaded overlay 'rpivid-v4l2'
008334.215: brfs: File read: 924 bytes
008339.422: brfs: File read: /mfs/sd/overlays/gpio-fan.dtbo
008348.405: Loaded overlay 'gpio-fan'
008348.425: dtparam: gpiopin=23
008349.051: dtparam: temp=60000
008400.157: brfs: File read: 1259 bytes
008402.158: brfs: File read: /mfs/sd/cmdline.txt
008402.231: Read command line from file 'cmdline.txt':
008402.257: 'console=serial0,115200 console=tty1 root=PARTUUID=e1d1e748-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait quiet splash plymouth.ignore-serial-consoles plymouth.enable=0'
008653.075: brfs: File read: 184 bytes
009644.566: brfs: File read: /mfs/sd/kernel7l.img
009644.594: Loading 'kernel7l.img' to 0x8000 size 0x676690
009644.633: Device tree loaded to 0x2eff3400 (size 0xcb55)
009671.261: gpioman: gpioman_get_pin_num: pin SDCARD_CONTROL_POWER not defined
012966.240: vchiq_core: vchiq_init_state: slot_zero = 0xced80000, is_master = 1
012974.973: TV service:host side not connected, dropping notification 0x00000002, 0x00000001, 0x0000005f
017993.005: brfs: File read: 6776464 bytes

dmesg log
---------

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 5.10.52-v7l+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1441 SMP Tue Aug 3 18:11:56 BST 2021
[    0.000000] CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d
[    0.000000] CPU: div instructions available: patching division code
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
[    0.000000] OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.2
[    0.000000] random: fast init done
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] Reserved memory: created CMA memory pool at 0x000000000ec00000, size 512 MiB
[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000000000-0x000000002fffffff]
[    0.000000]   Normal   empty
[    0.000000]   HighMem  [mem 0x0000000030000000-0x00000000fbffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x000000003b3fffff]
[    0.000000]   node   0: [mem 0x0000000040000000-0x00000000fbffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x00000000fbffffff]
[    0.000000] On node 0 totalpages: 1012736
[    0.000000]   DMA zone: 1728 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 196608 pages, LIFO batch:63
[    0.000000]   HighMem zone: 816128 pages, LIFO batch:63
[    0.000000] percpu: Embedded 20 pages/cpu s50764 r8192 d22964 u81920
[    0.000000] pcpu-alloc: s50764 r8192 d22964 u81920 alloc=20*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 1011008
[    0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1  smsc95xx.macaddr=m.m.m.m vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  console=ttyS0,115200 console=tty1 root=PARTUUID=e1d1e748-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait quiet splash plymouth.ignore-serial-consoles plymouth.enable=0
[    0.000000] Kernel parameter elevator= does not have any effect anymore.
               Please use sysfs to set IO scheduler for individual devices.
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] software IO TLB: mapped [mem 0x0000000008890000-0x000000000c890000] (64MB)
[    0.000000] Memory: 3404140K/4050944K available (10240K kernel code, 1364K rwdata, 3188K rodata, 2048K init, 891K bss, 122516K reserved, 524288K cma-reserved, 3264512K highmem)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 34303 entries in 67 pages
[    0.000000] ftrace: allocated 67 pages with 3 groups
[    0.000000] rcu: Hierarchical RCU implementation.
[    0.000000] 	Rude variant of Tasks RCU enabled.
[    0.000000] 	Tracing variant of Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000] GIC: Using split EOI/Deactivate mode
[    0.000000] irq_brcmstb_l2: registered L2 intc (/soc/interrupt-controller@7ef00100, parent irq: 25)
[    0.000000] random: get_random_bytes called from start_kernel+0x3c8/0x59c with crng_init=1
[    0.000008] sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns
[    0.000026] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns
[    0.000082] bcm2835: system timer (irq = 26)
[    0.000734] arch_timer: cp15 timer(s) running at 54.00MHz (phys).
[    0.000751] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns
[    0.000769] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns
[    0.000784] Switching to timer-based delay loop, resolution 18ns
[    0.001046] Console: colour dummy device 80x30
[    0.001116] printk: console [tty1] enabled
[    0.001168] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000)
[    0.001200] pid_max: default: 32768 minimum: 301
[    0.001364] LSM: Security Framework initializing
[    0.001552] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.001578] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.003045] cgroup: Disabling memory control group subsystem
[    0.003275] CPU: Testing write buffer coherency: ok
[    0.003742] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.004961] Setting up static identity map for 0x200000 - 0x20003c
[    0.005159] rcu: Hierarchical SRCU implementation.
[    0.006094] smp: Bringing up secondary CPUs ...
[    0.007300] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.008664] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.009959] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.010118] smp: Brought up 1 node, 4 CPUs
[    0.010139] SMP: Total of 4 processors activated (432.00 BogoMIPS).
[    0.010154] CPU: All CPU(s) started in HYP mode.
[    0.010168] CPU: Virtualization extensions available.
[    0.011110] devtmpfs: initialized
[    0.025716] VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0
[    0.025947] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.025978] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.038529] pinctrl core: initialized pinctrl subsystem
[    0.039573] NET: Registered protocol family 16
[    0.043528] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    0.044318] audit: initializing netlink subsys (disabled)
[    0.044579] audit: type=2000 audit(0.040:1): state=initialized audit_enabled=0 res=1
[    0.045144] thermal_sys: Registered thermal governor 'step_wise'
[    0.046043] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.046060] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.046464] Serial: AMBA PL011 UART driver
[    0.089029] bcm2835-mbox fe00b880.mailbox: mailbox enabled
[    0.100860] raspberrypi-firmware soc:firmware: Attached to firmware from 2021-08-03T18:14:56, variant start
[    0.110873] raspberrypi-firmware soc:firmware: Firmware hash is 40787ee5905644f639a2a0f6e00ae12e517a2211
[    0.151987] Kprobes globally optimized
[    0.157157] bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1
[    0.161536] vgaarb: loaded
[    0.161993] SCSI subsystem initialized
[    0.162207] usbcore: registered new interface driver usbfs
[    0.162279] usbcore: registered new interface driver hub
[    0.162352] usbcore: registered new device driver usb
[    0.162752] usb_phy_generic phy: supply vcc not found, using dummy regulator
[    0.164761] clocksource: Switched to clocksource arch_sys_counter
[    1.123396] VFS: Disk quotas dquot_6.6.0
[    1.123491] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    1.123650] FS-Cache: Loaded
[    1.123845] CacheFiles: Loaded
[    1.124974] simple-framebuffer 3e267000.framebuffer: framebuffer at 0x3e267000, 0x960000 bytes, mapped to 0x(ptrval)
[    1.124995] simple-framebuffer 3e267000.framebuffer: format=a8r8g8b8, mode=1920x1280x32, linelength=7680
[    1.125519] Console: switching to colour frame buffer device 240x80
[    1.137366] simple-framebuffer 3e267000.framebuffer: fb0: simplefb registered!
[    1.147834] NET: Registered protocol family 2
[    1.148067] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    1.149565] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
[    1.149607] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    1.149672] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    1.149743] TCP: Hash tables configured (established 8192 bind 8192)
[    1.149898] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[    1.149934] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[    1.150186] NET: Registered protocol family 1
[    1.150914] RPC: Registered named UNIX socket transport module.
[    1.150930] RPC: Registered udp transport module.
[    1.150945] RPC: Registered tcp transport module.
[    1.150960] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    1.150984] PCI: CLS 0 bytes, default 64
[    1.154331] Initialise system trusted keyrings
[    1.154581] workingset: timestamp_bits=14 max_order=20 bucket_order=6
[    1.163121] zbud: loaded
[    1.165340] FS-Cache: Netfs 'nfs' registered for caching
[    1.166120] NFS: Registering the id_resolver key type
[    1.166159] Key type id_resolver registered
[    1.166175] Key type id_legacy registered
[    1.166325] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.166341] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    1.167371] Key type asymmetric registered
[    1.167388] Asymmetric key parser 'x509' registered
[    1.167583] bounce: pool size: 64 pages
[    1.167626] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[    1.167839] io scheduler mq-deadline registered
[    1.167856] io scheduler kyber registered
[    1.172482] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
[    1.172513] brcm-pcie fd500000.pcie:   No bus range found for /scb/pcie@7d500000, using [bus 00-ff]
[    1.172593] brcm-pcie fd500000.pcie:      MEM 0x0600000000..0x063fffffff -> 0x00c0000000
[    1.172695] brcm-pcie fd500000.pcie:   IB MEM 0x0000000000..0x00bfffffff -> 0x0400000000
[    1.226865] brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC)
[    1.227234] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
[    1.227254] pci_bus 0000:00: root bus resource [bus 00-ff]
[    1.227276] pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff])
[    1.227366] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
[    1.227611] pci 0000:00:00.0: PME# supported from D0 D3hot
[    1.231181] PCI: bus0: Fast back to back transfers disabled
[    1.231536] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
[    1.231665] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
[    1.232100] pci 0000:01:00.0: PME# supported from D0 D3cold
[    1.235666] PCI: bus1: Fast back to back transfers disabled
[    1.235766] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
[    1.235791] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
[    1.235875] pci 0000:00:00.0: PCI bridge to [bus 01]
[    1.235901] pci 0000:00:00.0:   bridge window [mem 0x600000000-0x6000fffff]
[    1.236338] pcieport 0000:00:00.0: enabling device (0140 -> 0142)
[    1.236562] pcieport 0000:00:00.0: PME: Signaling with IRQ 80
[    1.245991] iproc-rng200 fe104000.rng: hwrng registered
[    1.246348] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    1.247294] gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000
[    1.259487] brd: module loaded
[    1.271693] loop: module loaded
[    1.273516] Loading iSCSI transport class v2.0-870.
[    1.276827] libphy: Fixed MDIO Bus: probed
[    1.278684] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000
[    1.294788] libphy: bcmgenet MII bus: probed
[    1.384911] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus
[    1.386058] usbcore: registered new interface driver r8152
[    1.386153] usbcore: registered new interface driver lan78xx
[    1.386215] usbcore: registered new interface driver smsc95xx
[    1.387816] xhci_hcd 0000:01:00.0: enabling device (0140 -> 0142)
[    1.387979] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.388015] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
[    1.391318] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0000060000000890
[    1.392612] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10
[    1.392631] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.392649] usb usb1: Product: xHCI Host Controller
[    1.392666] usb usb1: Manufacturer: Linux 5.10.52-v7l+ xhci-hcd
[    1.392683] usb usb1: SerialNumber: 0000:01:00.0
[    1.393420] hub 1-0:1.0: USB hub found
[    1.393519] hub 1-0:1.0: 1 port detected
[    1.394207] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.394235] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
[    1.394261] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
[    1.394835] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10
[    1.394854] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.394871] usb usb2: Product: xHCI Host Controller
[    1.394888] usb usb2: Manufacturer: Linux 5.10.52-v7l+ xhci-hcd
[    1.394905] usb usb2: SerialNumber: 0000:01:00.0
[    1.395614] hub 2-0:1.0: USB hub found
[    1.395707] hub 2-0:1.0: 4 ports detected
[    1.397501] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    1.397863] dwc_otg: FIQ enabled
[    1.397878] dwc_otg: NAK holdoff enabled
[    1.397893] dwc_otg: FIQ split-transaction FSM enabled
[    1.397910] Module dwc_common_port init
[    1.398377] usbcore: registered new interface driver uas
[    1.398490] usbcore: registered new interface driver usb-storage
[    1.398688] mousedev: PS/2 mouse device common for all mice
[    1.400501] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    1.404014] sdhci: Secure Digital Host Controller Interface driver
[    1.404030] sdhci: Copyright(c) Pierre Ossman
[    1.404834] mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe
[    1.405526] sdhci-pltfm: SDHCI platform and OF driver helper
[    1.409726] ledtrig-cpu: registered to indicate activity on CPUs
[    1.410071] hid: raw HID events driver (C) Jiri Kosina
[    1.410272] usbcore: registered new interface driver usbhid
[    1.410288] usbhid: USB HID core driver
[    1.415780] Initializing XFRM netlink socket
[    1.415822] NET: Registered protocol family 17
[    1.415965] Key type dns_resolver registered
[    1.416436] Registering SWP/SWPB emulation handler
[    1.416603] registered taskstats version 1
[    1.416631] Loading compiled-in X.509 certificates
[    1.417505] Key type ._fscrypt registered
[    1.417522] Key type .fscrypt registered
[    1.417537] Key type fscrypt-provisioning registered
[    1.429495] uart-pl011 fe201000.serial: there is not valid maps for state default
[    1.429800] uart-pl011 fe201000.serial: cts_event_workaround enabled
[    1.429902] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 38, base_baud = 0) is a PL011 rev2
[    1.437339] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    1.438420] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0
[    1.438437] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated
[    1.468708] of_cfs_init
[    1.468977] of_cfs_init: OK
[    1.489469] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[    1.491167] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    1.492864] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    1.496022] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[    1.497708] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    1.505099] mmc0: SDHCI controller on fe340000.emmc2 [fe340000.emmc2] using ADMA
[    1.505738] Waiting for root device PARTUUID=e1d1e748-02...
[    1.575128] usb 2-1: new SuperSpeed Gen 1 USB device number 2 using xhci_hcd
[    1.580704] mmc1: new high speed SDIO card at address 0001
[    1.612450] mmc0: new ultra high speed DDR50 SDHC card at address 59b4
[    1.613296] mmcblk0: mmc0:59b4 USDU1 28.3 GiB
[    1.615167]  mmcblk0: p1 p2
[    1.616791] usb 2-1: New USB device found, idVendor=0bc2, idProduct=231a, bcdDevice= 7.10
[    1.616811] usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    1.616829] usb 2-1: Product: Expansion
[    1.616846] usb 2-1: Manufacturer: Seagate
[    1.616863] usb 2-1: SerialNumber: NAA7EVQA
[    1.640986] scsi host0: uas
[    1.642550] scsi 0:0:0:0: Direct-Access     Seagate  Expansion        0710 PQ: 0 ANSI: 6
[    1.764847] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[    1.947351] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21
[    1.947371] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    1.947388] usb 1-1: Product: USB2.0 Hub
[    1.948990] hub 1-1:1.0: USB hub found
[    1.949233] hub 1-1:1.0: 4 ports detected
[    2.474832] usb 1-1.2: new full-speed USB device number 3 using xhci_hcd
[    2.611506] usb 1-1.2: New USB device found, idVendor=057e, idProduct=2009, bcdDevice= 2.10
[    2.611526] usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    2.611543] usb 1-1.2: Product: Pro Controller
[    2.611560] usb 1-1.2: Manufacturer: Nintendo Co., Ltd.
[    2.611577] usb 1-1.2: SerialNumber: 000000000001
[    2.621459] input: Nintendo Co., Ltd. Pro Controller as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.2/1-1.2:1.0/0003:057E:2009.0001/input/input0
[    2.621849] hid-generic 0003:057E:2009.0001: input,hidraw0: USB HID v1.11 Joystick [Nintendo Co., Ltd. Pro Controller] on usb-0000:01:00.0-1.2/input0
[    2.653624] sd 0:0:0:0: [sda] 7814037167 512-byte logical blocks: (4.00 TB/3.64 TiB)
[    2.653644] sd 0:0:0:0: [sda] 4096-byte physical blocks
[    2.653910] sd 0:0:0:0: [sda] Write Protect is off
[    2.653931] sd 0:0:0:0: [sda] Mode Sense: 53 00 00 08
[    2.654428] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    2.655229] sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes not a multiple of physical block size (4096 bytes)
[    2.714807] usb 1-1.3: new full-speed USB device number 4 using xhci_hcd
[    2.793199]  sda: sda1
[    2.796715] sd 0:0:0:0: [sda] Attached SCSI disk
[    2.833791] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[    2.833859] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[    2.834434] devtmpfs: mounted
[    2.843388] Freeing unused kernel memory: 2048K
[    2.852415] usb 1-1.3: New USB device found, idVendor=4037, idProduct=2804, bcdDevice= 1.10
[    2.852435] usb 1-1.3: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    2.852453] usb 1-1.3: Product: 2.4G Composite Devic
[    2.863942] input: 2.4G Composite Devic as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3:1.0/0003:4037:2804.0002/input/input1
[    2.875101] Run /sbin/init as init process
[    2.875116]   with arguments:
[    2.875131]     /sbin/init
[    2.875145]     splash
[    2.875159]   with environment:
[    2.875173]     HOME=/
[    2.875187]     TERM=linux
[    2.925738] hid-generic 0003:4037:2804.0002: input,hidraw1: USB HID v1.10 Keyboard [2.4G Composite Devic] on usb-0000:01:00.0-1.3/input0
[    2.934947] input: 2.4G Composite Devic Mouse as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3:1.1/0003:4037:2804.0003/input/input2
[    2.935545] input: 2.4G Composite Devic Consumer Control as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3:1.1/0003:4037:2804.0003/input/input3
[    3.005467] input: 2.4G Composite Devic System Control as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3:1.1/0003:4037:2804.0003/input/input4
[    3.005854] hid-generic 0003:4037:2804.0003: input,hidraw2: USB HID v1.10 Mouse [2.4G Composite Devic] on usb-0000:01:00.0-1.3/input1
[    3.279434] systemd[1]: System time before build time, advancing clock.
[    3.380718] NET: Registered protocol family 10
[    3.382100] Segment Routing with IPv6
[    3.446075] systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid)
[    3.447765] systemd[1]: Detected architecture arm.
[    3.497507] systemd[1]: Set hostname to <rpi4-ha>.
[    3.852530] random: lvmconfig: uninitialized urandom read (4 bytes read)
[    4.251132] systemd[1]: /lib/systemd/system/smbd.service:9: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/smbd.pid → /run/samba/smbd.pid; please update the unit file accordingly.
[    4.308906] systemd[1]: /lib/systemd/system/nmbd.service:9: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/nmbd.pid → /run/samba/nmbd.pid; please update the unit file accordingly.
[    4.463774] random: systemd: uninitialized urandom read (16 bytes read)
[    4.481511] random: systemd: uninitialized urandom read (16 bytes read)
[    4.482177] systemd[1]: Listening on LVM2 poll daemon socket.
[    4.483431] systemd[1]: Listening on Journal Socket.
[    4.492046] systemd[1]: Mounting RPC Pipe File System...
[    4.493280] systemd[1]: Condition check resulted in Huge Pages File System being skipped.
[    4.495413] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    4.601237] i2c /dev entries driver
[    5.292194] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[    5.390861] systemd-journald[127]: Received request to flush runtime journal from PID 1
[    5.992047] gpio-fan gpio-fan@0: GPIO fan initialized
[    6.151419] mc: Linux media interface: v0.10
[    6.219092] videodev: Linux video capture interface: v2.00
[    6.349272] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[    6.351113] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[    6.351822] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[    6.351846] [vc_sm_connected_init]: start
[    6.362475] checking generic (3e267000 960000) vs hw (0 ffffffffffffffff)
[    6.362495] fb0: switching to vc4drmfb from simple
[    6.363222] Console: switching to colour dummy device 80x30
[    6.365494] [vc_sm_connected_init]: installed successfully
[    6.367818] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    6.367952] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    6.368602] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    6.372971] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[    6.374097] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[    6.379699] vc4-drm gpu: bound fe400000.hvs (ops vc4_hvs_ops [vc4])
[    6.385889] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[    6.388586] rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned.
[    6.401321] vc4-drm gpu: bound fe400000.hvs (ops vc4_hvs_ops [vc4])
[    6.436687] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[    6.436808] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[    6.440306] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[    6.441214] rpivid feb10000.codec: Device registered as /dev/video19
[    6.448021] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[    6.448278] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[    6.448326] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[    6.450915] vc4-drm gpu: bound fe400000.hvs (ops vc4_hvs_ops [vc4])
[    6.453799] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[    6.460104] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[    6.460141] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    6.460166] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    6.460203] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    6.460226] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    6.460487] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[    6.462578] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[    6.462625] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[    6.467557] vc4-drm gpu: bound fe400000.hvs (ops vc4_hvs_ops [vc4])
[    6.478950] vc4-drm gpu: bound fe400000.hvs (ops vc4_hvs_ops [vc4])
[    6.594882] sd 0:0:0:0: Attached scsi generic sg0 type 0
[    6.610173] brcmstb-i2c fef04500.i2c:  @97500hz registered in polling mode
[    6.616433] vc4-drm gpu: bound fe400000.hvs (ops vc4_hvs_ops [vc4])
[    6.630913] brcmstb-i2c fef09500.i2c:  @97500hz registered in polling mode
[    6.669058] Registered IR keymap rc-cec
[    6.669276] rc rc0: vc4 as /devices/platform/soc/fef00700.hdmi/rc/rc0
[    6.669581] input: vc4 as /devices/platform/soc/fef00700.hdmi/rc/rc0/input5
[    6.670739] vc4_hdmi fef00700.hdmi: Could not register sound card: -517
[    6.694372] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[    6.815439] vc4-drm gpu: bound fe400000.hvs (ops vc4_hvs_ops [vc4])
[    6.822701] [drm] Initialized v3d 1.0.0 20180419 for fec00000.v3d on minor 1
[    6.832951] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[    6.841090] Registered IR keymap rc-cec
[    6.841269] rc rc0: vc4 as /devices/platform/soc/fef00700.hdmi/rc/rc0
[    6.841520] input: vc4 as /devices/platform/soc/fef00700.hdmi/rc/rc0/input6
[    6.842569] vc4_hdmi fef00700.hdmi: Could not register sound card: -517
[    6.982852] vc4-drm gpu: bound fe400000.hvs (ops vc4_hvs_ops [vc4])
[    6.990880] brcmfmac: F1 signature read @0x18000000=0x15264345
[    6.998634] Registered IR keymap rc-cec
[    6.998837] rc rc0: vc4 as /devices/platform/soc/fef00700.hdmi/rc/rc0
[    6.999064] input: vc4 as /devices/platform/soc/fef00700.hdmi/rc/rc0/input7
[    7.000188] vc4_hdmi fef00700.hdmi: Could not register sound card: -517
[    7.075586] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    7.076855] usbcore: registered new interface driver brcmfmac
[    7.125203] vc4-drm gpu: bound fe400000.hvs (ops vc4_hvs_ops [vc4])
[    7.151956] Registered IR keymap rc-cec
[    7.152175] rc rc0: vc4 as /devices/platform/soc/fef00700.hdmi/rc/rc0
[    7.152503] input: vc4 as /devices/platform/soc/fef00700.hdmi/rc/rc0/input8
[    7.153595] vc4_hdmi fef00700.hdmi: Could not register sound card: -517
[    7.339290] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    7.339425] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    7.348984] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Jan  4 2021 19:56:29 version 7.45.229 (617f1f5 CY) FWID 01-2dbd9d2e
[    7.723394] vc4-drm gpu: bound fe400000.hvs (ops vc4_hvs_ops [vc4])
[    7.736907] Registered IR keymap rc-cec
[    7.737104] rc rc0: vc4 as /devices/platform/soc/fef00700.hdmi/rc/rc0
[    7.737337] input: vc4 as /devices/platform/soc/fef00700.hdmi/rc/rc0/input9
[    7.747528] vc4-drm gpu: bound fef00700.hdmi (ops vc4_hdmi_ops [vc4])
[    7.749358] Registered IR keymap rc-cec
[    7.749536] rc rc1: vc4 as /devices/platform/soc/fef05700.hdmi/rc/rc1
[    7.749768] input: vc4 as /devices/platform/soc/fef05700.hdmi/rc/rc1/input10
[    7.763083] vc4-drm gpu: bound fef05700.hdmi (ops vc4_hdmi_ops [vc4])
[    7.763487] vc4-drm gpu: bound fe004000.txp (ops vc4_txp_ops [vc4])
[    7.763887] vc4-drm gpu: bound fe206000.pixelvalve (ops vc4_crtc_ops [vc4])
[    7.764199] vc4-drm gpu: bound fe207000.pixelvalve (ops vc4_crtc_ops [vc4])
[    7.764599] vc4-drm gpu: bound fe20a000.pixelvalve (ops vc4_crtc_ops [vc4])
[    7.764960] vc4-drm gpu: bound fe216000.pixelvalve (ops vc4_crtc_ops [vc4])
[    7.765325] vc4-drm gpu: bound fec12000.pixelvalve (ops vc4_crtc_ops [vc4])
[    7.768652] [drm] Initialized vc4 0.0.0 20140616 for gpu on minor 0
[    7.808583] usb 1-1.2: USB disconnect, device number 3
[    7.831667] vc4-drm gpu: [drm] The core clock cannot reach frequencies high enough to support 4k @ 60Hz.
[    7.831689] vc4-drm gpu: [drm] Please change your config.txt file to add hdmi_enable_4kp60.
[    7.897086] Console: switching to colour frame buffer device 480x135
[    7.970650] vc4-drm gpu: [drm] fb0: vc4drmfb frame buffer device
[    8.224948] usb 1-1.2: new full-speed USB device number 5 using xhci_hcd
[    8.301141] device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: [email protected]
[    8.361112] usb 1-1.2: New USB device found, idVendor=2dc8, idProduct=3100, bcdDevice= 0.01
[    8.361135] usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    8.361154] usb 1-1.2: Product: 8BitDo Receiver
[    8.361171] usb 1-1.2: Manufacturer: 8BitDo
[    8.369479] input: 8BitDo 8BitDo Receiver as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.2/1-1.2:1.0/0003:2DC8:3100.0004/input/input11
[    8.370057] hid-generic 0003:2DC8:3100.0004: input,hidraw0: USB HID v1.11 Gamepad [8BitDo 8BitDo Receiver] on usb-0000:01:00.0-1.2/input0
[   21.697309] NET: Registered protocol family 38
[   21.732214] cryptd: max_cpu_qlen set to 1000
[   21.800392] random: crng init done
[   21.800412] random: 7 urandom warning(s) missed due to ratelimiting
[   37.266541] EXT4-fs (dm-0): mounted filesystem with ordered data mode. Opts: (null)
[   38.217487] 8021q: 802.1Q VLAN Support v1.8
[   38.416788] uart-pl011 fe201000.serial: no DMA platform data
[   38.585317] Bluetooth: Core ver 2.22
[   38.585424] NET: Registered protocol family 31
[   38.585441] Bluetooth: HCI device and connection manager initialized
[   38.585471] Bluetooth: HCI socket layer initialized
[   38.585494] Bluetooth: L2CAP socket layer initialized
[   38.585530] Bluetooth: SCO socket layer initialized
[   38.603941] Bluetooth: HCI UART driver ver 2.3
[   38.603971] Bluetooth: HCI UART protocol H4 registered
[   38.604100] Bluetooth: HCI UART protocol Three-wire (H5) registered
[   38.604508] Bluetooth: HCI UART protocol Broadcom registered
[   38.852842] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
[   38.855050] Adding 2097148k swap on /media/exporl_hdd/swapfile.  Priority:-2 extents:3 across:3145724k FS
[   39.492859] FS-Cache: Netfs 'cifs' registered for caching
[   39.550322] Key type cifs.spnego registered
[   39.550359] Key type cifs.idmap registered
[   39.556313] fuse: init (API version 7.32)
[   39.558650] CIFS: Attempting to mount //10.112.34.36/archive/GBW-0005_ExpORL
[   39.558810] CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount.
[   39.558996] CIFS: VFS: Error connecting to socket. Aborting operation.
[   39.559037] CIFS: VFS: cifs_mount failed w/return code = -101
[   39.889934] bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay)
[   39.890619] bcmgenet fd580000.ethernet eth0: Link is Down
[   40.296628] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[   40.296649] Bluetooth: BNEP filters: protocol multicast
[   40.296684] Bluetooth: BNEP socket layer initialized
[   40.720408] Bluetooth: RFCOMM TTY layer initialized
[   40.720454] Bluetooth: RFCOMM socket layer initialized
[   40.720499] Bluetooth: RFCOMM ver 1.11
[   41.994940] bcmgenet fd580000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx
[   41.994972] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   43.600465] ICMPv6: process `dhcpcd' is using deprecated sysctl (syscall) net.ipv6.neigh.eth0.retrans_time - use net.ipv6.neigh.eth0.retrans_time_ms instead
[   47.135187] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[   47.138192] Bridge firewalling registered
[   50.261486] hassio: port 1(veth0e9f061) entered blocking state
[   50.261498] hassio: port 1(veth0e9f061) entered disabled state
[   50.261727] device veth0e9f061 entered promiscuoupi@rpi4-ha:~ $ s mode
[   50.473391] IPv6: ADDRCONF(NETDEV_CHANGE): veth258ab1f: link becomes ready
[   50.473511] IPv6: ADDRCONF(NETDEV_CHANGE): veth0e9f061: link becomes ready
[   50.473570] hassio: port 1(veth0e9f061) entered blocking state
[   50.473596] hassio: port 1(veth0e9f061) entered forwarding state
[   50.473724] IPv6: ADDRCONF(NETDEV_CHANGE): hassio: link becomes ready
[   51.431640] cgroup: cgroup: disabling cgroup2 socket matching due to net_prio or net_cls activation
[   52.656441] hassio: port 1(veth0e9f061) entered disabled state
[   52.657803] eth0: renamed from veth258ab1f
[   52.698970] hassio: port 1(veth0e9f061) entered blocking state
[   52.698981] hassio: port 1(veth0e9f061) entered forwarding state
[   56.196895] docker0: port 1(veth38b92d4) entered blocking state
[   56.196906] docker0: port 1(veth38b92d4) entered disabled state
[   56.197248] device veth38b92d4 entered promiscuous mode
[   56.286666] hassio: port 2(veth6cec26e) entered blocking state
[   56.286677] hassio: port 2(veth6cec26e) entered disabled state
[   56.287000] device veth6cec26e entered promiscuous mode
[   56.287613] hassio: port 2(veth6cec26e) entered blocking state
[   56.287622] hassio: port 2(veth6cec26e) entered forwarding state
[   56.533748] IPv6: ADDRCONF(NETDEV_CHANGE): veth6cec26e: link becomes ready
[   56.576749] IPv6: ADDRCONF(NETDEV_CHANGE): veth923ea26: link becomes ready
[   56.576875] IPv6: ADDRCONF(NETDEV_CHANGE): veth38b92d4: link becomes ready
[   56.576965] docker0: port 1(veth38b92d4) entered blocking state
[   56.576974] docker0: port 1(veth38b92d4) entered forwarding state
[   56.577202] IPv6: ADDRCONF(NETDEV_CHANGE): docker0: link becomes ready
[   58.065533] docker0: port 1(veth38b92d4) entered disabled state
[   58.066665] eth0: renamed from veth923ea26
[   58.108752] docker0: port 1(veth38b92d4) entered blocking state
[   58.108765] docker0: port 1(veth38b92d4) entered forwarding state
[   59.005407] hassio: port 2(veth6cec26e) entered disabled state
[   59.006566] eth1: renamed from veth7663eb5
[   59.098806] hassio: port 2(veth6cec26e) entered blocking state
[   59.098817] hassio: port 2(veth6cec26e) entered forwarding state
[   60.142913] udevd[207]: starting version 3.2.9
[   60.166871] udevd[208]: starting eudev-3.2.9
[   77.081367] hdmi-audio-codec hdmi-audio-codec.5.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   77.082003] hdmi-audio-codec hdmi-audio-codec.5.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   77.083033] hdmi-audio-codec hdmi-audio-codec.5.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   77.140710] hdmi-audio-codec hdmi-audio-codec.5.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   77.141511] hdmi-audio-codec hdmi-audio-codec.5.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   77.143751] hdmi-audio-codec hdmi-audio-codec.5.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   77.144117] hdmi-audio-codec hdmi-audio-codec.5.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   78.391627] hassio: port 3(vethc73fe8c) entered blocking state
[   78.391642] hassio: port 3(vethc73fe8c) entered disabled state
[   78.392111] device vethc73fe8c entered promiscuous mode
[   78.585583] IPv6: ADDRCONF(NETDEV_CHANGE): vetha828d1f: link becomes ready
[   78.585723] IPv6: ADDRCONF(NETDEV_CHANGE): vethc73fe8c: link becomes ready
[   78.585785] hassio: port 3(vethc73fe8c) entered blocking state
[   78.585794] hassio: port 3(vethc73fe8c) entered forwarding state
[   79.565395] hassio: port 3(vethc73fe8c) entered disabled state
[   79.566738] eth0: renamed from vetha828d1f
[   79.620209] hassio: port 3(vethc73fe8c) entered blocking state
[   79.620220] hassio: port 3(vethc73fe8c) entered forwarding state
[   80.927886] hassio: port 4(vethfcc7e5d) entered blocking state
[   80.927897] hassio: port 4(vethfcc7e5d) entered disabled state
[   80.928264] device vethfcc7e5d entered promiscuous mode
[   81.044933] IPv6: ADDRCONF(NETDEV_CHANGE): veth3efe29d: link becomes ready
[   81.045096] IPv6: ADDRCONF(NETDEV_CHANGE): vethfcc7e5d: link becomes ready
[   81.045193] hassio: port 4(vethfcc7e5d) entered blocking state
[   81.045201] hassio: port 4(vethfcc7e5d) entered forwarding state
[   82.635264] hassio: port 4(vethfcc7e5d) entered disabled state
[   82.636412] eth0: renamed from veth3efe29d
[   82.699084] hassio: port 4(vethfcc7e5d) entered blocking state
[   82.699094] hassio: port 4(vethfcc7e5d) entered forwarding state
[   84.822565] hassio: port 5(vethb54f9c8) entered blocking state
[   84.822576] hassio: port 5(vethb54f9c8) entered disabled state
[   84.822796] device vethb54f9c8 entered promiscuous mode
[   84.939454] IPv6: ADDRCONF(NETDEV_CHANGE): vethcaf71b8: link becomes ready
[   84.939603] IPv6: ADDRCONF(NETDEV_CHANGE): vethb54f9c8: link becomes ready
[   84.939699] hassio: port 5(vethb54f9c8) entered blocking state
[   84.939707] hassio: port 5(vethb54f9c8) entered forwarding state
[   86.675833] hassio: port 5(vethb54f9c8) entered disabled state
[   86.676882] eth0: renamed from vethcaf71b8
[   86.730702] hassio: port 5(vethb54f9c8) entered blocking state
[   86.730712] hassio: port 5(vethb54f9c8) entered forwarding state
[   88.674271] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.674283] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.674291]  MAI: ASoC: DAI prepare error: -22
[   88.674584] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.674593] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.674600]  MAI: ASoC: DAI prepare error: -22
[   88.674961] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.674971] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.674978]  MAI: ASoC: DAI prepare error: -22
[   88.675400] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.675409] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.675416]  MAI: ASoC: DAI prepare error: -22
[   88.675902] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.675911] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.675918]  MAI: ASoC: DAI prepare error: -22
[   88.677647] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.677658] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.677665]  MAI: ASoC: DAI prepare error: -22
[   88.677969] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.677977] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.677984]  MAI: ASoC: DAI prepare error: -22
[   88.678260] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.678269] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.678276]  MAI: ASoC: DAI prepare error: -22
[   88.678727] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.678736] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.678743]  MAI: ASoC: DAI prepare error: -22
[   88.679226] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.679234] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.679240]  MAI: ASoC: DAI prepare error: -22
[   88.682134] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.682147] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.682154]  MAI: ASoC: DAI prepare error: -22
[   88.682829] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.682841] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.682848]  MAI: ASoC: DAI prepare error: -22
[   88.683523] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.683531] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.683538]  MAI: ASoC: DAI prepare error: -22
[   88.684250] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.684262] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.684268]  MAI: ASoC: DAI prepare error: -22
[   88.695251] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.695268] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.695277]  MAI: ASoC: DAI prepare error: -22
[   88.698427] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.698441] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.698449]  MAI: ASoC: DAI prepare error: -22
[   88.699522] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.699535] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.699542]  MAI: ASoC: DAI prepare error: -22
[   88.700590] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.700602] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.700609]  MAI: ASoC: DAI prepare error: -22
[   88.701603] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.701616] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.701623]  MAI: ASoC: DAI prepare error: -22
[   88.703524] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.703536] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.703543]  MAI: ASoC: DAI prepare error: -22
[   88.705766] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.705776] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.705783]  MAI: ASoC: DAI prepare error: -22
[   88.706085] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.706094] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.706100]  MAI: ASoC: DAI prepare error: -22
[   88.706368] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.706376] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.706382]  MAI: ASoC: DAI prepare error: -22
[   88.706749] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.706757] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.706764]  MAI: ASoC: DAI prepare error: -22
[   88.707206] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.707214] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.707221]  MAI: ASoC: DAI prepare error: -22
[   88.708822] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.708832] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.708840]  MAI: ASoC: DAI prepare error: -22
[   88.709275] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.709284] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.709291]  MAI: ASoC: DAI prepare error: -22
[   88.709785] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.709793] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.709800]  MAI: ASoC: DAI prepare error: -22
[   88.710625] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.710633] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.710639]  MAI: ASoC: DAI prepare error: -22
[   88.711253] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.711264] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.711271]  MAI: ASoC: DAI prepare error: -22
[   88.713677] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.713687] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.713694]  MAI: ASoC: DAI prepare error: -22
[   88.714314] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.714322] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.714328]  MAI: ASoC: DAI prepare error: -22
[   88.714991] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.715000] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.715006]  MAI: ASoC: DAI prepare error: -22
[   88.715771] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.715782] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.715789]  MAI: ASoC: DAI prepare error: -22
[   88.716730] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.716743] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.716750]  MAI: ASoC: DAI prepare error: -22
[   88.720002] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.720012] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.720020]  MAI: ASoC: DAI prepare error: -22
[   88.721045] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.721058] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.721065]  MAI: ASoC: DAI prepare error: -22
[   88.721917] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.721929] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.721935]  MAI: ASoC: DAI prepare error: -22
[   88.723538] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.723548] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.723555]  MAI: ASoC: DAI prepare error: -22
[   88.725122] hdmi-audio-codec hdmi-audio-codec.4.auto: Not able to map channels to speakers (-22)
[   88.725134] hdmi-audio-codec hdmi-audio-codec.4.auto: ASoC: error at snd_soc_pcm_dai_prepare on i2s-hifi: -22
[   88.725144]  MAI: ASoC: DAI prepare error: -22
[   88.787303] hdmi-audio-codec hdmi-audio-codec.5.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   88.788256] hdmi-audio-codec hdmi-audio-codec.5.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   88.789312] hdmi-audio-codec hdmi-audio-codec.5.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   88.817692] hdmi-audio-codec hdmi-audio-codec.5.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   88.818339] hdmi-audio-codec hdmi-audio-codec.5.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   88.818979] hdmi-audio-codec hdmi-audio-codec.5.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   99.106623] hassio: port 6(vethe777822) entered blocking state
[   99.106635] hassio: port 6(vethe777822) entered disabled state
[   99.106865] device vethe777822 entered promiscuous mode
[   99.222273] IPv6: ADDRCONF(NETDEV_CHANGE): veth09cb5e0: link becomes ready
[   99.222418] IPv6: ADDRCONF(NETDEV_CHANGE): vethe777822: link becomes ready
[   99.222511] hassio: port 6(vethe777822) entered blocking state
[   99.222519] hassio: port 6(vethe777822) entered forwarding state
[  101.835757] hassio: port 6(vethe777822) entered disabled state
[  101.836940] eth0: renamed from veth09cb5e0
[  101.899176] hassio: port 6(vethe777822) entered blocking state
[  101.899193] hassio: port 6(vethe777822) entered forwarding state
[  107.915998] hassio: port 7(veth11b80d6) entered blocking state
[  107.916008] hassio: port 7(veth11b80d6) entered disabled state
[  107.916514] device veth11b80d6 entered promiscuous mode
[  108.062485] IPv6: ADDRCONF(NETDEV_CHANGE): veth0e66975: link becomes ready
[  108.062620] IPv6: ADDRCONF(NETDEV_CHANGE): veth11b80d6: link becomes ready
[  108.062713] hassio: port 7(veth11b80d6) entered blocking state
[  108.062721] hassio: port 7(veth11b80d6) entered forwarding state
[  109.615876] hassio: port 7(veth11b80d6) entered disabled state
[  109.616958] eth0: renamed from veth0e66975
[  109.670929] hassio: port 7(veth11b80d6) entered blocking state
[  109.670939] hassio: port 7(veth11b80d6) entered forwarding state
[  197.064021] hassio: port 8(veth4e23989) entered blocking state
[  197.064031] hassio: port 8(veth4e23989) entered disabled state
[  197.071837] device veth4e23989 entered promiscuous mode
[  197.372982] IPv6: ADDRCONF(NETDEV_CHANGE): vethe0fa55d: link becomes ready
[  197.373137] IPv6: ADDRCONF(NETDEV_CHANGE): veth4e23989: link becomes ready
[  197.373242] hassio: port 8(veth4e23989) entered blocking state
[  197.373250] hassio: port 8(veth4e23989) entered forwarding state
[  200.106605] hassio: port 8(veth4e23989) entered disabled state
[  200.107743] eth0: renamed from vethe0fa55d
[  200.148225] hassio: port 8(veth4e23989) entered blocking state
[  200.148236] hassio: port 8(veth4e23989) entered forwarding state
@popcornmix
Copy link
Collaborator

You need to have a alsa card driver for vc4. See here.
RPiOS (buster) should have this by default. Can you post your current version, and if different try changing it to match the link.

@berndie
Copy link
Author

berndie commented Aug 23, 2021

Hi, thank you for your reply!

Indeed, there was a vc4-hdmi.conf present, and these were the contents:

#
# Configuration for the VC4-HDMI sound card using software IEC958
# subframe conversion
#

<confdir:pcm/front.conf>

vc4-hdmi.pcm.front.0 {
	@args [ CARD ]
	@args.CARD {
		type string
	}
	type hw
	card $CARD
}

# default with dmix
vc4-hdmi.pcm.default {
	@args [ CARD ]
	@args.CARD {
		type string
	}
	type asym
	playback.pcm {
		type plug
		slave.pcm {
			@func concat
			strings [ "dmix:" $CARD ]
		}
	}
}

<confdir:pcm/iec958.conf>

vc4-hdmi.pcm.iec958.0 {
	@args [ CARD AES0 AES1 AES2 AES3 ]
	@args.CARD {
		type string
	}
	@args.AES0 {
		type integer
	}
	@args.AES1 {
		type integer
	}
	@args.AES2 {
		type integer
	}
	@args.AES3 {
		type integer
	}
	type iec958
	slave {
		format IEC958_SUBFRAME_LE
		pcm {
			type plug
			slave.pcm {
				type hw
				card $CARD
			}
		}
	}
	status [ $AES0 $AES1 $AES2 $AES3 ]
}

I have tried changing the contents to the version of the link, but I still don't have audio playback from the TV and the same error messages are still present in the dmesg.

@mgrouch
Copy link

mgrouch commented Sep 22, 2021

I'm experiencing the same error:

[   32.663639] hdmi-audio-codec hdmi-audio-codec.8.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   32.664718] hdmi-audio-codec hdmi-audio-codec.8.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   32.666724] hdmi-audio-codec hdmi-audio-codec.8.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   32.750539] hdmi-audio-codec hdmi-audio-codec.8.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   32.752008] hdmi-audio-codec hdmi-audio-codec.8.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   32.756615] hdmi-audio-codec hdmi-audio-codec.8.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   32.757801] hdmi-audio-codec hdmi-audio-codec.8.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19

On

[0.000000] Linux version 5.10.60-v8+ (dom@buildbot) (aarch64-linux-gnu-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld 
(GNU Binutils for Ubuntu) 2.34) #1449 SMP PREEMPT Wed Aug 25 15:01:33 BST 2021

with the same in /boot/config.txt

dtoverlay=vc4-kms-v3d-pi4,cma-256
max_framebuffers=2
dtoverlay=rpivid-v412
disable_fw_kms_setup=1

@popcornmix
Copy link
Collaborator

These messages are expected:

[   32.757801] hdmi-audio-codec hdmi-audio-codec.8.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19

in a working system. See here for some discussion.

@solskogen
Copy link

This is probably the same problem as I've reported here: raspberrypi/Raspberry-Pi-OS-64bit#157

Try installing pulseaudio, see if that works.

@flsabourin
Copy link

I am also experiencing the same issue with kms. It appears quite randomly, sometime just pausing a working video with audio and trying to play again fails.

/boot/config.txt
-----------
hdmi_force_hotplug=1
hdmi_group=1
hdmi_drive=2
dtparam=spi=on
dtparam=audio=on
[pi4]
max_framebuffers=2
[all]
dtoverlay=rpivid-v4l2
dtoverlay=vc4-kms-v3d,cma-512
hdmi_enable_4kp60=1
System Information
------------------

Raspberry Pi 4 Model B Rev 1.1^@
PRETTY_NAME="Raspbian GNU/Linux 11 (bullseye)"
NAME="Raspbian GNU/Linux"
VERSION_ID="11"
VERSION="11 (bullseye)"

Raspberry Pi reference 2020-12-02
Generated using pi-gen, https://github.com/RPi-Distro/pi-gen, cce27bd6f44a3b2e83855645986b3e21f771e852, stage2

Linux XXXXXX 5.10.63-v7l+ #1459 SMP Wed Oct 6 16:41:57 BST 2021 armv7l GNU/Linux
Revision        : c03111
Serial          : 10000000a1f02ef4
Model           : Raspberry Pi 4 Model B Rev 1.1
Throttled flag  : throttled=0x0
Camera          : supported=0 detected=0
cmdline.txt
-----------
coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 video=HDMI-A-1:1920x1080M@60,margin_left=48,margin_right=48,margin_top=48,margin_botto
m=48 smsc95xx.macaddr=DC:A6:32:0C:53:B0 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  console=ttyS0,115200 console=tty1 root=PARTUUID=51e1da13-02 rootfstype=ext4 elevato
r=deadline fsck.repair=yes rootwait

@sermayoral
Copy link

sermayoral commented Dec 8, 2021

Same problem here in a clean install of raspios_arm64-2021-11-08 on a rpi4 4gb. I have not HDMI sound

/boot/config.txt
-----------
# Enable audio (loads snd_bcm2835)
dtparam=audio=on
# Automatically load overlays for detected cameras
camera_auto_detect=1
# Automatically load overlays for detected DSI displays
display_auto_detect=1
# Enable DRM VC4 V3D driver
dtoverlay=vc4-kms-v3d
max_framebuffers=2
# Run in 64-bit mode
arm_64bit=1
# Disable compensation for displays with overscan
disable_overscan=1
[cm4]
# Enable host mode on the 2711 built-in XHCI USB controller.
# This line should be removed if the legacy DWC2 controller is required
# (e.g. for USB device mode) or if USB support is not required.
otg_mode=1
[pi4]
# Run as fast as firmware / board allows
arm_boost=1
[all]
# Usar hevc hardware para Kodi
dtoverlay=rpivid-v4l2
cmdline.txt
-----------
console=serial0,115200 console=tty1 root=PARTUUID=bb080b59-02 rootfstype=ext4 fsck.repair=yes rootwait quiet splash plymouth.ignore-serial-consoles
aplay -l
------------
**** List of PLAYBACK Hardware Devices ****
card 0: Headphones [bcm2835 Headphones], device 0: bcm2835 Headphones [bcm2835 Headphones]
  Subdevices: 8/8
  Subdevice #0: subdevice #0
  Subdevice #1: subdevice #1
  Subdevice #2: subdevice #2
  Subdevice #3: subdevice #3
  Subdevice #4: subdevice #4
  Subdevice #5: subdevice #5
  Subdevice #6: subdevice #6
  Subdevice #7: subdevice #7
card 1: vc4hdmi0 [vc4-hdmi-0], device 0: MAI PCM i2s-hifi-0 [MAI PCM i2s-hifi-0]
  Subdevices: 0/1
  Subdevice #0: subdevice #0
card 2: vc4hdmi1 [vc4-hdmi-1], device 0: MAI PCM i2s-hifi-0 [MAI PCM i2s-hifi-0]
  Subdevices: 1/1
  Subdevice #0: subdevice #0
cat /etc/os-release
-----------
PRETTY_NAME="Debian GNU/Linux 11 (bullseye)"
NAME="Debian GNU/Linux"
VERSION_ID="11"
VERSION="11 (bullseye)"
VERSION_CODENAME=bullseye
ID=debian
HOME_URL="https://www.debian.org/"
SUPPORT_URL="https://www.debian.org/support"
BUG_REPORT_URL="https://bugs.debian.org/"

@himijendrix24
Copy link

commenting the analog audio output in /boot/config.txt solved the problem for me.
No need for a custom ~/.asoundrc

#dtparam=audio=on

https://forums.raspberrypi.com/viewtopic.php?t=322751&start=25

@flatsiedatsie
Copy link

The solution to remove dtparam=audio=on also works for me.. kinda. I have two issues with it:

  • The headphone jack no longer shows up? Is this normal?
**** List of PLAYBACK Hardware Devices ****
card 0: vc4hdmi0 [vc4-hdmi-0], device 0: MAI PCM i2s-hifi-0 [MAI PCM i2s-hifi-0]
  Subdevices: 0/1
  Subdevice #0: subdevice #0
card 1: vc4hdmi1 [vc4-hdmi-1], device 0: MAI PCM i2s-hifi-0 [MAI PCM i2s-hifi-0]
  Subdevices: 1/1
  Subdevice #0: subdevice #0
  • Audio is no longer mixed. Before I could play music and have a privacy friendly voice assistant talk over the music. Perhaps this can be fixed with some Alsa magic?

@popcornmix
Copy link
Collaborator

The solution to remove dtparam=audio=on also works for me.. kinda. I have two issues with it:

* The headphone jack no longer shows up? Is this normal?

Yes, you've just disabled it.

* Audio is no longer mixed. Before I could play music and have a privacy friendly voice assistant talk over the music. Perhaps this can be fixed with some Alsa magic?

Pulse audio is installed by default on RPiOS and can handle the mixing.

@flatsiedatsie
Copy link

flatsiedatsie commented Aug 9, 2022

Thanks for the quick reply!

  • Is there a way to keep the headphone jack alive?
  • I'm using Bullseye Lite 32bit, so PulseAudio doesn't come pre-installed. More importantly, I won't be able to install it for my use case. Is there a way to avoid Pulse Audio but still mix the audio?

@popcornmix
Copy link
Collaborator

Thanks for the quick reply!

* Is there a way to keep the headphone jack alive?

dtparam=audio=on is explicitly there to enable the alsa driver for the headphone jack. You can't have that output without it.

* I'm using Bullseye Lite 32bit, so PulseAudio doesn't come pre-installed. More importantly, I won't be able to install it for my use case. Is there a way to avoid Pulse Audio but still mix the audio?

You can't do it through alsa. You need a sound server, of which pulse audio is recommended.
Possibly some of these could work, but it's not something I've knowledge of:
https://en.wikipedia.org/wiki/Sound_server#List_of_sound_servers

@flatsiedatsie
Copy link

Whoa, OK. Is this the intended way forward for the Raspberry Pi?

  • Will we have to choose between analog and digital?
  • Will audio servers be the norm?

Or is all this just a temporary situation until this is "fixed"?

@popcornmix
Copy link
Collaborator

Using dtparam=audio=on doesn't prevent the hdmi output from working.
It just provides an additional alsa device that supports headphone output.

If having an additional alsa device breaks hdmi output, then it suggests a configuration issue.
I don't believe you would see that with a fresh install of RPiOS bullseye with desktop (which includes desktop controls for switching also devices).

Yes, I'm afraid an audio server is required when using the vc4 hdmi audio device if you require mixing of multiple channels.

@flatsiedatsie
Copy link

flatsiedatsie commented Aug 9, 2022

If having an additional alsa device breaks hdmi output

It doesn't break HDMI output, but it does break audio-over-hdmi, which is what this Github issue is all about. I would like to have audio output via the headphone jack and audio output over HDMI work at the same time. Currently my users can select where they want their audio to go "on the fly".

candle_internet_radio_audio_output

It would be sad if switching between the headphone jack or HDMI audio output would require a reboot.

Yes, I'm afraid an audio server is required when using the vc4 hdmi audio device if you require mixing of multiple channels.

If true, then that would be a loss over the situation with the previous vc4-Fkms-v3d driver, in which Alsa is able to mix audio into a single stream without issue. It would mean more memory use, and a rewrite for a lot of projects.

For now I am forced to keep using the Fkms driver.

@popcornmix
Copy link
Collaborator

It doesn't break HDMI output, but it does break audio-over-hdmi, which is what this Github issue is all about. I would like to have audio output via the headphone jack and audio output over HDMI work at the same time. Currently my users can select where they want their audio to go "on the fly".
It would be sad if switching between the headphone jack or HDMI audio output would require a reboot.

I'm saying it doesn't inherently break audio over hdmi. On RPiOS you can switch between HDMI audio and desktop "on the fly" using the audio widgets. No reboot needed.

We need to work out why this doesn't work for you.

If true, then that would be a loss over the situation with the previous vc4-Fkms-v3d driver, in which Alsa is able to mix audio into a single stream without issue. It would mean more memory use, and a rewrite for a lot of projects.

Alsa didn't mix the audio with fkms. The firmware did (by reporting multiple output channels, and then mixing them itself).
We are trying to move as much code as possible from the firmware to standard linux interfaces
Unfortunately the standard linux way of doing this is to use a sound server.

@6by9
Copy link
Contributor

6by9 commented Aug 9, 2022

For reference - https://patchwork.kernel.org/project/alsa-devel/patch/1488451773-22010-1-git-send-email-boris.brezillon@free-electrons.com/#20340067

And I'm afraid that dmix won't work with IEC958 subframe type.  dmix
can deal with the direct hw access, and it's limited with the normal
linear PCM types.  In theory, we can apply the dmix-style plugin onto
iec958 subframes, but the current code doesn't do it.

That said, the default should be rather passing iec958 or hdmi without
the mixing.  If the stream mixing is required, you need to use some
sound server instead.

Takashi Iwai is one of the main ALSA developers.

@flatsiedatsie
Copy link

We need to work out why this doesn't work for you.

To be clear, my issue is the exact same as all the other people in this thread.

We are trying to move as much code as possible from the firmware to standard linux interfaces
Unfortunately the standard linux way of doing this is to use a sound server.

I see the value in that.

In theory, we can apply the dmix-style plugin onto iec958 subframes, but the current code doesn't do it.

Here's hoping that will come to pass.

@giddie
Copy link

giddie commented Aug 10, 2022

Yes, I'm afraid an audio server is required when using the vc4 hdmi audio device if you require mixing of multiple channels.

[...] It would mean more memory use, and a rewrite for a lot of projects.

Would it really be that much more memory? I'd recommend trying Pipewire rather than PulseAudio. But the runtime memory and CPU overhead is pretty much negligible given the RPi's available resources in either case.

@flatsiedatsie
Copy link

@giddie I'm more worried about the work. I made everything "pure alsa" because I thought that would make it simpler and more future proof, and because I read so many complaints about Pulse Audio. Now it turns out Pulse Audio (et al) is the future :-D

@giddie
Copy link

giddie commented Aug 13, 2022

Yeah, I understand the frustration. But to be fair, PulseAudio (and now Pipewire, really) has been the unambiguous future of audio in the Linux ecosystem for the last decade or so 😆

@giddie
Copy link

giddie commented Aug 15, 2022

Oh, by the way, if you're not aware - ALSA can work just fine on top of PulseAudio. So you can probably continue to work with ALSA, and with PulseAudio installed ALSA will simply forward audio for your app to PA, which will handle the mixing. This configuration is usually automatic when PA is installed.

@antonio-gt
Copy link

antonio-gt commented Aug 19, 2022

Hi all,

On my Raspberry 4 I had the same issue of no audio over HDMI port but only when using shairport-sync, other sounds (i.e. youtube videos) were working fine.

I had jack and HDMI working fine togheter till RP4 OS was Buster, then Bullsey broke something.

I verified that audio via shairport-sync is not working only if running the program as a service using sudo service shairport-sync start while executing a simple shairport-sync from the command line HDMI audio was working.

Commenting dtparam=audio=on on /boot/config.txt solved the issue as suggested on #4543 (comment) but still I'm wondering about the cause of the issue and if there's a way to have HDMI and jack-audio being able to work togheter.

Any idea?

@popcornmix
Copy link
Collaborator

Commenting dtparam=spi=on on /boot/config.txt solved the issue as suggested

did you mean spi or audio here? I don't see how spi is related.

@antonio-gt
Copy link

antonio-gt commented Aug 19, 2022

oh, sorry, late night... you are right it is #dtparam=audio=on, I'll fix my previous comment

@symbios24
Copy link

The solution #dtparam=audio=on
Works great in Raspbian buster but not in Raspbian bullseye
by running alsamixer it shows hdmi1 as the default it should be hdmi0 ???

@popcornmix
Copy link
Collaborator

by running alsamixer it shows hdmi1 as the default it should be hdmi0 ???

Do you have one or two displays connected?
Have you connected to first or second connector? (on pi4 hdmi0 is closest to power connector).
show output of aplay -l

@symbios24
Copy link

symbios24 commented Jan 20, 2023

I have one display only and i have the hdmi0 connected the one next to the power connector

@popcornmix
Copy link
Collaborator

show output of aplay -l

@symbios24
Copy link

IMG-20230120-175737

@popcornmix
Copy link
Collaborator

Okay, you have hdmi0 and hdmi1 devices.
Are you using full (with desktop) RpiOS image?

If so, you should be able to change default audio device by right-clicking the volume control on taskbar.

@symbios24
Copy link

No im using raspbian lite

@popcornmix
Copy link
Collaborator

It will be in one of the alsa config files, depending on how you selected it.
Do you have a ~/.soundrc file? What is the contents?

@symbios24
Copy link

IMG-20230121-094910

@symbios24
Copy link

Any progress??

@popcornmix
Copy link
Collaborator

On bullseye that file shouldn't exist. Try renaming it.

@symbios24
Copy link

Sorry rename what exactly??

@popcornmix
Copy link
Collaborator

~/.soundrc

@symbios24
Copy link

Ok,so i rename it to anything?

@popcornmix
Copy link
Collaborator

Yes. Anything other than it's current name, so it doesn't get used. e.g.
mv ~/.soundrc ~/.soundrc.bak

@alecov
Copy link

alecov commented May 6, 2024

Facing exactly the same issue here.

Using dtparam=audio=on doesn't prevent the hdmi output from working.
It just provides an additional alsa device that supports headphone output.

@popcornmix
In my case, yes it does. I'm attaching three alsactl info outputs: one for FKMS, one for KMS with dtparam=audio=on, and one for KMS without dtparam=audio=on.

With FKMS, audio works. alsactl_fkms.txt
With KMS+dtparam, audio does not work. alsactl_kms.txt
With KMS only (no dtparam), audio does work.
alsactl_kms_nodtparam.txt

I'm running a custom setup without PulseAudio (but the software I'm running (RetroArch) outputs directly to ALSA; it also runs over (F)KMS just fine without X). Kernel and firmware are up to date:

# uname -a
Linux rasp 6.6.28-2-rpi #1 SMP PREEMPT Fri Apr 19 12:03:08 MDT 2024 aarch64 GNU/Linux
# config.txt
gpu_mem=128
arm_freq=1950
core_freq=650
v3d_freq=650
over_voltage=4
temp_limit=75
disable_splash=1
disable_overscan=1
dtoverlay=vc4-kms-v3d
#dtparam=audio=on
initramfs initramfs-linux.img followkernel

Alsa didn't mix the audio with fkms. The firmware did (by reporting multiple output channels, and then mixing them itself).

That's one reason why RPi is great -- the firmware fills the gaps where Linux actually sucks.

We are trying to move as much code as possible from the firmware to standard linux interfaces

That's a good approach, but please, please, hear my plea below:

Unfortunately the standard linux way of doing this is to use a sound server.

Can we at least hope that FKMS and all the excellent "old" firmware support will be at least kept in the future?

With the old ways of doing things (that is, FKMS, "deprecated" config.txt things such as hdmi_mode/hdmi_group, userland such as tvservice), everything just worked. Now it feels like things are breaking in unexpected ways because the "standard" way Linux does many things is just... bullshit.

With the deprecation of old userland it is hard to keep things working. For example, I had a tvservice-based service that adjusted the correct HDMI mode in certain situations where the software I was running did not set it correctly*; without tvservice I cannot do this any longer (and I am not even sure it would work with KMS, I'm not versed in the details of KMS vs. FKMS). I need to try kmsprint but I'm not optimistic about this -- there does not seem to be any replacement to tvservice.

Another example: using PulseAudio introduces cracks/buffer underruns and I need to increase audio latency in my setup for it to work more or less OK. With firmware-based mixing it works wonders with minimal latency and I don't need an additional sound server sticking around.

Again, the best thing about RPi is indeed the firmware support -- it fills the gap where Linux sucks and RPi userland tools work and have always worked very well. Deprecating these feels like a huge loss to me.

*: I agree the piece of code in question should be fixed; but for reasons too long to enumerate it can't/won't be done, and the value of "the workaround works very well" cannot be overstated here.

EDIT: Added config.txt.

@popcornmix
Copy link
Collaborator

Be aware that Pi5 (and any future Pi models) have no "start.elf" firmware, so running audio on the firmware is just not possible.

Relying on that functionality is limiting you to older Pi models.

fkms gets no new features or bug fixes. We have no plans to remove it imminently, but also make no promises to maintain it forever.

Obviously as you are already restricting yourself to older models of Pi, you can continue using older software and use fkms features for as long as you want.

But I would suggest moving forward.

@alecov
Copy link

alecov commented May 6, 2024 via email

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests