search

raspberrypi / linux

Kernel source tree for Raspberry Pi-provided kernel builds. Issues unrelated to the linux kernel should be posted on the community forum at https://forums.raspberrypi.com/
Other
10.96k stars 4.93k forks source link

USB MIDI unplug + VL805 reset #6260

Open pietervandermeer opened 1 month ago

pietervandermeer commented 1 month ago

I've been testing for a few days and can conclude that the VL805 does not like USB MIDI devices getting unplugged. It may reset the entire USB bus. This means that mass storage devices are unmounted and then possibly remounted. If they contain your rootfs.. well then you can reboot. I've narrowed the issue down to systems with a VL805 on board. The Pi3 does not suffer from USB resets nor does the CM4 + CM4IO (I've unplugged 100's of times to confirm). The Pi4B is the easiest way to reproduce the problem. Since VL805 firmware is part of the Raspberry Pi firmware, I'm posting my issue here. I've also asked at https://forums.raspberrypi.com but there's no answer.

To reproduce The issue is easy to reproduce.

Here you can clearly see that usb 2-2 (the usb stick) gets disconnected.. but I'm not touching it. I'm only touching the Akai usb plug. It happens already after a few unplugs.

pi@raspberrypi:~ $ journalctl -fk jul 08 08:23:27 raspberrypi kernel: scsi host0: usb-storage 2-2:1.0 jul 08 08:23:28 raspberrypi kernel: scsi 0:0:0:0: Direct-Access Kingston DataTraveler 3.0 1100 PQ: 0 ANSI: 6 jul 08 08:23:28 raspberrypi kernel: sd 0:0:0:0: [sda] 60463659 512-byte logical blocks: (31.0 GB/28.8 GiB) jul 08 08:23:28 raspberrypi kernel: sd 0:0:0:0: [sda] Write Protect is off jul 08 08:23:28 raspberrypi kernel: sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00 jul 08 08:23:28 raspberrypi kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA jul 08 08:23:28 raspberrypi kernel: sda: sda1 jul 08 08:23:28 raspberrypi kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk jul 08 08:23:28 raspberrypi kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 jul 08 08:23:29 raspberrypi kernel: exFAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. jul 08 08:24:06 raspberrypi kernel: usb 1-1.3: new full-speed USB device number 3 using xhci_hcd jul 08 08:24:06 raspberrypi kernel: usb 1-1.3: New USB device found, idVendor=09e8, idProduct=0072, bcdDevice= 2.10 jul 08 08:24:06 raspberrypi kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0 jul 08 08:24:06 raspberrypi kernel: usb 1-1.3: Product: Akai MPK25 jul 08 08:24:06 raspberrypi kernel: usb 1-1.3: Manufacturer: Akai jul 08 08:24:06 raspberrypi kernel: usbcore: registered new interface driver snd-usb-audio jul 08 08:24:07 raspberrypi kernel: usb 1-1.3: USB disconnect, device number 3 jul 08 08:24:08 raspberrypi kernel: usb 1-1.3: new full-speed USB device number 4 using xhci_hcd jul 08 08:24:08 raspberrypi kernel: usb 1-1.3: New USB device found, idVendor=09e8, idProduct=0072, bcdDevice= 2.10 jul 08 08:24:08 raspberrypi kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0 jul 08 08:24:08 raspberrypi kernel: usb 1-1.3: Product: Akai MPK25 jul 08 08:24:08 raspberrypi kernel: usb 1-1.3: Manufacturer: Akai jul 08 08:24:08 raspberrypi kernel: usb 1-1.3: USB disconnect, device number 4 jul 08 08:24:09 raspberrypi kernel: usb 2-2: USB disconnect, device number 2 jul 08 08:24:09 raspberrypi kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache jul 08 08:24:09 raspberrypi kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x01 driverbyte=DRIVER_OK jul 08 08:24:09 raspberrypi kernel: usb 2-2: new SuperSpeed USB device number 3 using xhci_hcd jul 08 08:24:09 raspberrypi kernel: usb 2-2: New USB device found, idVendor=0951, idProduct=1666, bcdDevice=11.00 jul 08 08:24:09 raspberrypi kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 jul 08 08:24:09 raspberrypi kernel: usb 2-2: Product: DataTraveler 3.0 jul 08 08:24:09 raspberrypi kernel: usb 2-2: Manufacturer: Kingston jul 08 08:24:09 raspberrypi kernel: usb 2-2: SerialNumber: 049226C3130421A019118XD1 jul 08 08:24:09 raspberrypi kernel: usb-storage 2-2:1.0: USB Mass Storage device detected jul 08 08:24:09 raspberrypi kernel: scsi host0: usb-storage 2-2:1.0 jul 08 08:24:09 raspberrypi kernel: usb 1-1.3: new full-speed USB device number 5 using xhci_hcd jul 08 08:24:09 raspberrypi kernel: usb 1-1.3: New USB device found, idVendor=09e8, idProduct=0072, bcdDevice= 2.10 jul 08 08:24:09 raspberrypi kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0 jul 08 08:24:09 raspberrypi kernel: usb 1-1.3: Product: Akai MPK25 jul 08 08:24:09 raspberrypi kernel: usb 1-1.3: Manufacturer: Akai jul 08 08:24:09 raspberrypi kernel: usb 1-1.3: USB disconnect, device number 5 jul 08 08:24:10 raspberrypi kernel: usb 2-2: USB disconnect, device number 3 jul 08 08:24:10 raspberrypi kernel: usb 1-1.3: new full-speed USB device number 6 using xhci_hcd jul 08 08:24:10 raspberrypi kernel: usb 1-1.3: New USB device found, idVendor=09e8, idProduct=0072, bcdDevice= 2.10 jul 08 08:24:10 raspberrypi kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0 jul 08 08:24:10 raspberrypi kernel: usb 1-1.3: Product: Akai MPK25 jul 08 08:24:10 raspberrypi kernel: usb 1-1.3: Manufacturer: Akai jul 08 08:24:11 raspberrypi kernel: usb 2-2: new SuperSpeed USB device number 4 using xhci_hcd jul 08 08:24:11 raspberrypi kernel: usb 2-2: New USB device found, idVendor=0951, idProduct=1666, bcdDevice=11.00 jul 08 08:24:11 raspberrypi kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 jul 08 08:24:11 raspberrypi kernel: usb 2-2: Product: DataTraveler 3.0 jul 08 08:24:11 raspberrypi kernel: usb 2-2: Manufacturer: Kingston jul 08 08:24:11 raspberrypi kernel: usb 2-2: SerialNumber: 049226C3130421A019118XD1 jul 08 08:24:11 raspberrypi kernel: usb-storage 2-2:1.0: USB Mass Storage device detected jul 08 08:24:11 raspberrypi kernel: scsi host0: usb-storage 2-2:1.0 jul 08 08:24:11 raspberrypi kernel: usb 1-1.3: USB disconnect, device number 6 jul 08 08:24:12 raspberrypi kernel: usb 1-1.3: new full-speed USB device number 7 using xhci_hcd jul 08 08:24:12 raspberrypi kernel: scsi 0:0:0:0: Direct-Access Kingston DataTraveler 3.0 1100 PQ: 0 ANSI: 6 jul 08 08:24:12 raspberrypi kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 jul 08 08:24:12 raspberrypi kernel: sd 0:0:0:0: [sda] 60463659 512-byte logical blocks: (31.0 GB/28.8 GiB) jul 08 08:24:12 raspberrypi kernel: sd 0:0:0:0: [sda] Write Protect is off jul 08 08:24:12 raspberrypi kernel: sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00 jul 08 08:24:12 raspberrypi kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA jul 08 08:24:12 raspberrypi kernel: sda: sda1 jul 08 08:24:12 raspberrypi kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk jul 08 08:24:12 raspberrypi kernel: usb 1-1.3: New USB device found, idVendor=09e8, idProduct=0072, bcdDevice= 2.10 jul 08 08:24:12 raspberrypi kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0 jul 08 08:24:12 raspberrypi kernel: usb 1-1.3: Product: Akai MPK25 jul 08 08:24:12 raspberrypi kernel: usb 1-1.3: Manufacturer: Akai jul 08 08:24:12 raspberrypi kernel: exFAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. jul 08 08:24:12 raspberrypi kernel: usb 1-1.3: USB disconnect, device number 7

Expected behaviour I expected that the PI4B would just disconnect the USB MIDI device alone.

Actual behaviour It disconnects two USB devices, including the mass storage device.

jul 08 08:24:08 raspberrypi kernel: usb 1-1.3: USB disconnect, device number 4
jul 08 08:24:10 raspberrypi kernel: usb 2-2: USB disconnect, device number 3

System

pi@raspberrypi:~ $ raspinfo
System Information
------------------

Raspberry Pi 4 Model B Rev 1.4
PRETTY_NAME="Debian GNU/Linux 12 (bookworm)"
NAME="Debian GNU/Linux"
VERSION_ID="12"
VERSION="12 (bookworm)"

Raspberry Pi reference 2024-03-15
Generated using pi-gen, https://github.com/RPi-Distro/pi-gen, f19ee211ddafcae300827f953d143de92a5c6624, stage2

Linux raspberrypi 6.6.31+rpt-rpi-v8 raspberrypi/firmware#1 SMP PREEMPT Debian 1:6.6.31-1+rpt1 (2024-05-29) aarch64 GNU/Linux
Revision    : d03114
Serial      : 10000000ca73ad92
Model       : Raspberry Pi 4 Model B Rev 1.4
Throttled flag  : throttled=0x0
Camera          : supported=0 detected=0, libcamera interfaces=0

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

May 24 2024 15:30:04 
Copyright (c) 2012 Broadcom
version 4942b7633c0ff1af1ee95a51a33b56a9dae47529 (clean) (release) (start)

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

Filesystem information
----------------------
Filesystem     1K-blocks    Used Available Use% Mounted on
udev             3728512       0   3728512   0% /dev
tmpfs             799748    2796    796952   1% /run
/dev/mmcblk0p2  29232280 3861860  23865972  14% /
tmpfs            3998736       0   3998736   0% /dev/shm
tmpfs               5120      16      5104   1% /run/lock
/dev/mmcblk0p1    522230   64608    457622  13% /boot/firmware
tmpfs             799744       0    799744   0% /run/user/1000

Filename                Type        Size        Used        Priority
/var/swap                               file        204796      0       -2

Package version information
---------------------------
raspberrypi-ui-mods:
  Installed: (none)
raspberrypi-sys-mods:
  Installed: 20240619
openbox:
  Installed: (none)
lxpanel:
  Installed: (none)
pcmanfm:
  Installed: (none)
rpd-plym-splash:
  Installed: (none)

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

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 y.y.y.y.y.y.y.y  prefixlen 64  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 437  bytes 67256 (65.6 KiB)
        RX errors 0  dropped 36  overruns 0  frame 0
        TX packets 226  bytes 37876 (36.9 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 23  bytes 4676 (4.5 KiB)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 23  bytes 4676 (4.5 KiB)
        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
/:  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

Display Information
-------------------

Running (F)KMS, console

/sys/class/drm/card1-HDMI-A-1

/sys/class/drm/card1-HDMI-A-2

/sys/class/drm/card1-Writeback-1

Connector 0 (32) HDMI-A-1 (disconnected)
  Encoder 0 (31) TMDS
Connector 1 (41) HDMI-A-2 (disconnected)
  Encoder 1 (40) TMDS

HDMI0:             HDMI_HOTPLUG = 0x00000000
HDMI1:             HDMI_HOTPLUG = 0x00000000

/sys/kernel/debug/dri/1/state:
plane[45]: plane-0
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=0
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
plane[62]: plane-1
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=0
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
plane[73]: plane-2
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=0
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
plane[84]: plane-3
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=0
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
plane[95]: plane-4
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=0
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
plane[106]: plane-5
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=0
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
plane[117]: plane-6
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=1
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
plane[127]: plane-7
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=2
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
plane[137]: plane-8
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=3
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
plane[147]: plane-9
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=4
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
plane[157]: plane-10
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=5
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
plane[167]: plane-11
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=6
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
plane[177]: plane-12
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=7
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
plane[187]: plane-13
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=8
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
plane[197]: plane-14
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=9
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
plane[207]: plane-15
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=a
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
plane[217]: plane-16
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=b
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
plane[227]: plane-17
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=c
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
plane[237]: plane-18
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=d
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
plane[247]: plane-19
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=e
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
plane[257]: plane-20
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=f
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
plane[267]: plane-21
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=10
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
plane[277]: plane-22
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=11
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
plane[287]: plane-23
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=11
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
plane[297]: plane-24
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=11
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
plane[307]: plane-25
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=11
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
plane[317]: plane-26
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=11
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
plane[327]: plane-27
    crtc=(null)
    fb=0
    crtc-pos=0x0+0+0
    src-pos=0.000000x0.000000+0.000000+0.000000
    rotation=1
    normalized-zpos=11
    color-encoding=ITU-R BT.709 YCbCr
    color-range=YCbCr limited range
crtc[55]: txp
    enable=0
    active=0
    self_refresh_active=0
    planes_changed=0
    mode_changed=0
    active_changed=0
    connectors_changed=0
    color_mgmt_changed=0
    plane_mask=0
    connector_mask=0
    encoder_mask=0
    mode: "": 0 0 0 0 0 0 0 0 0 0 0x0 0x0
crtc[72]: pixelvalve-0
    enable=0
    active=0
    self_refresh_active=0
    planes_changed=0
    mode_changed=0
    active_changed=0
    connectors_changed=0
    color_mgmt_changed=0
    plane_mask=0
    connector_mask=0
    encoder_mask=0
    mode: "": 0 0 0 0 0 0 0 0 0 0 0x0 0x0
crtc[83]: pixelvalve-1
    enable=0
    active=0
    self_refresh_active=0
    planes_changed=0
    mode_changed=0
    active_changed=0
    connectors_changed=0
    color_mgmt_changed=0
    plane_mask=0
    connector_mask=0
    encoder_mask=0
    mode: "": 0 0 0 0 0 0 0 0 0 0 0x0 0x0
crtc[94]: pixelvalve-2
    enable=0
    active=0
    self_refresh_active=0
    planes_changed=0
    mode_changed=0
    active_changed=0
    connectors_changed=0
    color_mgmt_changed=0
    plane_mask=0
    connector_mask=0
    encoder_mask=0
    mode: "": 0 0 0 0 0 0 0 0 0 0 0x0 0x0
crtc[105]: pixelvalve-4
    enable=0
    active=0
    self_refresh_active=0
    planes_changed=0
    mode_changed=0
    active_changed=0
    connectors_changed=0
    color_mgmt_changed=0
    plane_mask=0
    connector_mask=0
    encoder_mask=0
    mode: "": 0 0 0 0 0 0 0 0 0 0 0x0 0x0
crtc[116]: pixelvalve-3
    enable=0
    active=0
    self_refresh_active=0
    planes_changed=0
    mode_changed=0
    active_changed=0
    connectors_changed=0
    color_mgmt_changed=0
    plane_mask=0
    connector_mask=0
    encoder_mask=0
    mode: "": 0 0 0 0 0 0 0 0 0 0 0x0 0x0
connector[32]: HDMI-A-1
    crtc=(null)
    self_refresh_aware=0
    max_requested_bpc=8
    colorspace=Default
connector[41]: HDMI-A-2
    crtc=(null)
    self_refresh_aware=0
    max_requested_bpc=8
    colorspace=Default
connector[61]: Writeback-1
    crtc=(null)
    self_refresh_aware=0
    max_requested_bpc=0
    colorspace=Default

Audio Information
-------------------

**** List of PLAYBACK Hardware Devices ****
card 0: sndrpihifiberry [snd_rpi_hifiberry_dac], device 0: HifiBerry DAC HiFi pcm5102a-hifi-0 [HifiBerry DAC HiFi pcm5102a-hifi-0]
  Subdevices: 1/1
  Subdevice #0: subdevice #0
card 1: vc4hdmi0 [vc4-hdmi-0], device 0: MAI PCM i2s-hifi-0 [MAI PCM i2s-hifi-0]
  Subdevices: 1/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

null
    Discard all samples (playback) or generate zero samples (capture)
hw:CARD=sndrpihifiberry,DEV=0
    snd_rpi_hifiberry_dac, HifiBerry DAC HiFi pcm5102a-hifi-0
    Direct hardware device without any conversions
plughw:CARD=sndrpihifiberry,DEV=0
    snd_rpi_hifiberry_dac, HifiBerry DAC HiFi pcm5102a-hifi-0
    Hardware device with all software conversions
default:CARD=sndrpihifiberry
    snd_rpi_hifiberry_dac, HifiBerry DAC HiFi pcm5102a-hifi-0
    Default Audio Device
sysdefault:CARD=sndrpihifiberry
    snd_rpi_hifiberry_dac, HifiBerry DAC HiFi pcm5102a-hifi-0
    Default Audio Device
dmix:CARD=sndrpihifiberry,DEV=0
    snd_rpi_hifiberry_dac, HifiBerry DAC HiFi pcm5102a-hifi-0
    Direct sample mixing device
hw:CARD=vc4hdmi0,DEV=0
    vc4-hdmi-0, MAI PCM i2s-hifi-0
    Direct hardware device without any conversions
plughw:CARD=vc4hdmi0,DEV=0
    vc4-hdmi-0, MAI PCM i2s-hifi-0
    Hardware device with all software conversions
default:CARD=vc4hdmi0
    vc4-hdmi-0, MAI PCM i2s-hifi-0
    Default Audio Device
sysdefault:CARD=vc4hdmi0
    vc4-hdmi-0, MAI PCM i2s-hifi-0
    Default Audio Device
hdmi:CARD=vc4hdmi0,DEV=0
    vc4-hdmi-0, MAI PCM i2s-hifi-0
    HDMI Audio Output
dmix:CARD=vc4hdmi0,DEV=0
    vc4-hdmi-0, MAI PCM i2s-hifi-0
    Direct sample mixing device
hw:CARD=vc4hdmi1,DEV=0
    vc4-hdmi-1, MAI PCM i2s-hifi-0
    Direct hardware device without any conversions
plughw:CARD=vc4hdmi1,DEV=0
    vc4-hdmi-1, MAI PCM i2s-hifi-0
    Hardware device with all software conversions
default:CARD=vc4hdmi1
    vc4-hdmi-1, MAI PCM i2s-hifi-0
    Default Audio Device
sysdefault:CARD=vc4hdmi1
    vc4-hdmi-1, MAI PCM i2s-hifi-0
    Default Audio Device
hdmi:CARD=vc4hdmi1,DEV=0
    vc4-hdmi-1, MAI PCM i2s-hifi-0
    HDMI Audio Output
dmix:CARD=vc4hdmi1,DEV=0
    vc4-hdmi-1, MAI PCM i2s-hifi-0
    Direct sample mixing device

Unit pipewire.socket could not be found.
Unit pipewire.service could not be found.
Unit pulseaudio.service could not be found.
Unit pulseaudio.socket could not be found.

pactl not installed

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

arm_64bit=1
arm_boost=1
arm_freq=1000
audio_pwm_mode=514
auto_initramfs=1
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_auto_detect=1
display_hdmi_rotate=-1
display_lcd_rotate=-1
dvfs=3
enable_gic=1
enable_uart=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=2
over_voltage_avs=-30000
pause_burst_frames=1
pciex4_reset=1
pmic_turbo_threshold=600
program_serial_random=1
total_mem=8192
hdmi_force_cec_address:0=65535
hdmi_force_cec_address:1=65535
hdmi_group:0=2
hdmi_mode:0=87
hdmi_pixel_freq_limit:0=0x11e1a300
hdmi_pixel_freq_limit:1=0x11e1a300
device_tree=-
overlay_prefix=overlays/
hdmi_cvt:0=800 480 60 3 0 0 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=1 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_hdmi=0  smsc95xx.macaddr=E4:5F:01:1E:03:F1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  video=HDMI-A-1:800x480M@60,rotate=180 console=tty1 root=PARTUUID=83557f53-02 rootfstype=ext4 fsck.repair=yes rootwait

pin configuration
-----------------

 0: ip    pu | hi // ID_SDA/GPIO0 = input
 1: ip    pu | hi // ID_SCL/GPIO1 = input
 2: ip    pu | hi // GPIO2 = input
 3: ip    pu | hi // GPIO3 = input
 4: ip    pu | hi // GPIO4 = input
 5: ip    pu | hi // GPIO5 = input
 6: ip    pu | hi // GPIO6 = input
 7: ip    pu | hi // GPIO7 = input
 8: ip    pu | hi // GPIO8 = input
 9: ip    pd | lo // GPIO9 = input
10: ip    pd | lo // GPIO10 = input
11: ip    pd | lo // GPIO11 = input
12: ip    pd | lo // GPIO12 = input
13: ip    pd | lo // GPIO13 = input
14: a0    pn | hi // GPIO14 = TXD0
15: a0    pu | hi // GPIO15 = RXD0
16: ip    pd | lo // GPIO16 = input
17: ip    pd | lo // GPIO17 = input
18: a0    pd | lo // GPIO18 = PCM_CLK
19: a0    pd | lo // GPIO19 = PCM_FS
20: a0    pd | lo // GPIO20 = PCM_DIN
21: a0    pd | lo // GPIO21 = PCM_DOUT
22: ip    pd | lo // GPIO22 = input
23: ip    pd | lo // GPIO23 = input
24: ip    pd | lo // GPIO24 = input
25: ip    pd | lo // GPIO25 = input
26: ip    pd | lo // GPIO26 = input
27: ip    pd | lo // GPIO27 = input
28: a5    pu | hi // RGMII_MDIO/GPIO28 = RGMII_MDIO
29: a5    pd | lo // RGMIO_MDC/GPIO29 = RGMII_MDC
30: ip    pu | hi // CTS0/GPIO30 = input
31: ip    pn | hi // RTS0/GPIO31 = input
32: ip    pd | lo // TXD0/GPIO32 = input
33: ip    pd | lo // RXD0/GPIO33 = input
34: ip    pu | hi // SD1_CLK/GPIO34 = input
35: ip    pu | hi // SD1_CMD/GPIO35 = input
36: ip    pu | hi // SD1_DATA0/GPIO36 = input
37: ip    pu | hi // SD1_DATA1/GPIO37 = input
38: ip    pu | hi // SD1_DATA2/GPIO38 = input
39: ip    pu | hi // SD1_DATA3/GPIO39 = input
40: a0    pn | lo // PWM0_MISO/GPIO40 = PWM1_0
41: a0    pn | lo // PWM1_MOSI/GPIO41 = PWM1_1
42: op -- pu | lo // STATUS_LED_G_CLK/GPIO42 = output
43: ip    pu | hi // SPIFLASH_CE_N/GPIO43 = input
44: ip    pu | hi // SDA0/GPIO44 = input
45: ip    pu | hi // SCL0/GPIO45 = input
46: ip    pu | lo // RGMII_RXCLK/GPIO46 = input
47: ip    pu | lo // RGMII_RXCTL/GPIO47 = input
48: ip    pd | lo // RGMII_RXD0/GPIO48 = input
49: ip    pd | lo // RGMII_RXD1/GPIO49 = input
50: ip    pd | lo // RGMII_RXD2/GPIO50 = input
51: ip    pd | lo // RGMII_RXD3/GPIO51 = input
52: ip    pd | lo // RGMII_TXCLK/GPIO52 = input
53: ip    pd | lo // RGMII_TXCTL/GPIO53 = input

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

003899.705: arasan: arasan_emmc_open
003899.876: arasan: arasan_emmc_set_clock C0: 0x00800000 C1: 0x000e0047 emmc: 200000000 actual: 390625 div: 0x00000100 target: 400000 min: 400000 max: 400000 delay: 5
004004.642: arasan: arasan_emmc_set_clock C0: 0x00800000 C1: 0x000e0047 emmc: 200000000 actual: 390625 div: 0x00000100 target: 400000 min: 400000 max: 400000 delay: 5
004004.729: arasan: arasan_emmc_set_clock C0: 0x00800f00 C1: 0x000e0047 emmc: 200000000 actual: 390625 div: 0x00000100 target: 400000 min: 390000 max: 400000 delay: 5
004023.093: arasan: arasan_emmc_set_clock C0: 0x00800f06 C1: 0x000e0207 emmc: 200000000 actual: 50000000 div: 0x00000002 target: 50000000 min: 0 max: 50000000 delay: 1
004164.179: brfs: File read: /mfs/sd/config.txt
004165.000: brfs: File read: 1425 bytes
004186.780: HDMI0:EDID error reading EDID block 0 attempt 0
004187.797: HDMI0:EDID giving up on reading EDID block 0
004199.556: HDMI1:EDID error reading EDID block 0 attempt 0
004200.570: HDMI1:EDID giving up on reading EDID block 0
004202.413: brfs: File read: /mfs/sd/config.txt
004665.829: gpioman: gpioman_get_pin_num: pin DISPLAY_DSI_PORT not defined
004668.002: *** Restart logging
004668.022: brfs: File read: 1425 bytes
004673.503: hdmi: HDMI0:EDID error reading EDID block 0 attempt 0
004674.518: hdmi: HDMI0:EDID giving up on reading EDID block 0
004679.565: hdmi: HDMI0:EDID error reading EDID block 0 attempt 0
004680.582: hdmi: HDMI0:EDID giving up on reading EDID block 0
004680.599: hdmi: HDMI:hdmi_get_state is deprecated, use hdmi_get_display_state instead
004685.639: hdmi: HDMI1:EDID error reading EDID block 0 attempt 0
004686.657: hdmi: HDMI1:EDID giving up on reading EDID block 0
004691.697: hdmi: HDMI1:EDID error reading EDID block 0 attempt 0
004692.711: hdmi: HDMI1:EDID giving up on reading EDID block 0
004692.729: hdmi: HDMI:hdmi_get_state is deprecated, use hdmi_get_display_state instead
004692.745: HDMI0: hdmi_pixel_encoding: 300000000
004692.758: HDMI1: hdmi_pixel_encoding: 300000000
005662.673: brfs: File read: /mfs/sd/initramfs8
005662.687: Loaded 'initramfs8' to 0x0 size 0xb08c0a
005672.648: initramfs loaded to 0x2e4f7000 (size 0xb08c0a)
005682.095: dtb_file 'bcm2711-rpi-4-b.dtb'
005682.112: brfs: File read: 11570186 bytes
005688.263: brfs: File read: /mfs/sd/bcm2711-rpi-4-b.dtb
005688.280: Loaded 'bcm2711-rpi-4-b.dtb' to 0x100 size 0xd9fc
005701.871: brfs: File read: 55804 bytes
005713.416: brfs: File read: /mfs/sd/overlays/overlay_map.dtb
005736.963: brfs: File read: 5255 bytes
005739.126: brfs: File read: /mfs/sd/config.txt
005740.389: brfs: File read: 1425 bytes
005755.497: brfs: File read: /mfs/sd/overlays/vc4-kms-v3d-pi4.dtbo
005818.184: Loaded overlay 'vc4-kms-v3d-pi4'
005818.433: dtparam: i2s=on
005978.290: brfs: File read: 3913 bytes
005983.834: brfs: File read: /mfs/sd/overlays/hifiberry-dac.dtbo
005992.982: Loaded overlay 'hifiberry-dac'
006017.310: brfs: File read: 668 bytes
006020.998: brfs: File read: /mfs/sd/overlays/disable-wifi.dtbo
006029.743: Loaded overlay 'disable-wifi'
006043.264: brfs: File read: 387 bytes
006047.252: brfs: File read: /mfs/sd/overlays/disable-bt.dtbo
006062.479: Loaded overlay 'disable-bt'
006109.479: brfs: File read: 1073 bytes
006111.291: brfs: File read: /mfs/sd/cmdline.txt
006111.331: Read command line from file 'cmdline.txt':
006111.342: 'video=HDMI-A-1:800x480M@60,rotate=180 console=tty1 root=PARTUUID=83557f53-02 rootfstype=ext4 fsck.repair=yes rootwait'
006236.785: brfs: File read: 118 bytes
007012.767: brfs: File read: /mfs/sd/kernel8.img
007012.788: Loaded 'kernel8.img' to 0x200000 size 0x8d8bd7
008322.594: Device tree loaded to 0x2e4e9100 (size 0xde93)
008329.662: gpioman: gpioman_get_pin_num: pin SDCARD_CONTROL_POWER not defined
008437.898: sdram: sdram refresh 1562->6248 (1)

010763.059: vchiq_core: vchiq_init_state: slot_zero = 0xce880000, is_master = 1

dmesg log
---------

[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd083]
[    0.000000] Linux version 6.6.31+rpt-rpi-v8 (serge@raspberrypi.com) (gcc-12 (Debian 12.2.0-14) 12.2.0, GNU ld (GNU Binutils for Debian) 2.40) raspberrypi/firmware#1 SMP PREEMPT Debian 1:6.6.31-1+rpt1 (2024-05-29)
[    0.000000] KASLR enabled
[    0.000000] random: crng init done
[    0.000000] Machine model: Raspberry Pi 4 Model B Rev 1.4
[    0.000000] efi: UEFI not found.
[    0.000000] Reserved memory: created CMA memory pool at 0x000000000e400000, size 512 MiB
[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[    0.000000] OF: reserved mem: 0x000000000e400000..0x000000002e3fffff (524288 KiB) map reusable linux,cma
[    0.000000] OF: reserved mem: 0x000000003ef646a0..0x000000003ef64a9f (1 KiB) nomap non-reusable nvram@1
[    0.000000] OF: reserved mem: 0x000000003ef64ae0..0x000000003ef64b2c (0 KiB) nomap non-reusable nvram@0
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000000000-0x000000003fffffff]
[    0.000000]   DMA32    [mem 0x0000000040000000-0x00000000ffffffff]
[    0.000000]   Normal   [mem 0x0000000100000000-0x00000001ffffffff]
[    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]   node   0: [mem 0x0000000100000000-0x00000001ffffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x00000001ffffffff]
[    0.000000] On node 0, zone DMA32: 19456 pages in unavailable ranges
[    0.000000] On node 0, zone Normal: 16384 pages in unavailable ranges
[    0.000000] percpu: Embedded 30 pages/cpu s85672 r8192 d29016 u122880
[    0.000000] pcpu-alloc: s85672 r8192 d29016 u122880 alloc=30*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
[    0.000000] Detected PIPT I-cache on CPU0
[    0.000000] CPU features: detected: Spectre-v2
[    0.000000] CPU features: detected: Spectre-v3a
[    0.000000] CPU features: detected: Spectre-v4
[    0.000000] CPU features: detected: Spectre-BHB
[    0.000000] CPU features: kernel page table isolation forced ON by KASLR
[    0.000000] CPU features: detected: Kernel page table isolation (KPTI)
[    0.000000] CPU features: detected: ARM erratum 1742098
[    0.000000] CPU features: detected: ARM errata 1165522, 1319367, or 1530923
[    0.000000] alternatives: applying boot alternatives
[    0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=1 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_hdmi=0  smsc95xx.macaddr=m.m.m.m vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  video=HDMI-A-1:800x480M@60,rotate=180 console=tty1 root=PARTUUID=83557f53-02 rootfstype=ext4 fsck.repair=yes rootwait
[    0.000000] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear)
[    0.000000] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 2028848
[    0.000000] mem auto-init: stack:all(zero), heap alloc:off, heap free:off
[    0.000000] software IO TLB: area num 4.
[    0.000000] software IO TLB: mapped [mem 0x0000000037400000-0x000000003b400000] (64MB)
[    0.000000] Memory: 7457028K/8245248K available (13376K kernel code, 2210K rwdata, 4264K rodata, 4864K init, 1083K bss, 263932K reserved, 524288K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 43180 entries in 169 pages
[    0.000000] ftrace: allocated 169 pages with 4 groups
[    0.000000] trace event string verifier disabled
[    0.000000] rcu: Preemptible hierarchical RCU implementation.
[    0.000000] rcu:     RCU event tracing is enabled.
[    0.000000] rcu:     RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4.
[    0.000000]  Trampoline variant of Tasks RCU enabled.
[    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 25 jiffies.
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] Root IRQ handler: gic_handle_irq
[    0.000000] GIC: Using split EOI/Deactivate mode
[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.
[    0.000000] arch_timer: cp15 timer(s) running at 54.00MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns
[    0.000000] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns
[    0.000313] Console: colour dummy device 80x25
[    0.000328] printk: console [tty1] enabled
[    0.000961] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000)
[    0.000994] pid_max: default: 32768 minimum: 301
[    0.001074] LSM: initializing lsm=capability,integrity
[    0.001326] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    0.001437] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    0.002306] cgroup: Disabling memory control group subsystem
[    0.003842] RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1.
[    0.004001] RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1.
[    0.004131] RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1.
[    0.004394] rcu: Hierarchical SRCU implementation.
[    0.004414] rcu:     Max phase no-delay instances is 1000.
[    0.006286] EFI services will not be available.
[    0.006601] smp: Bringing up secondary CPUs ...
[    0.007191] Detected PIPT I-cache on CPU1
[    0.007312] CPU1: Booted secondary processor 0x0000000001 [0x410fd083]
[    0.008001] Detected PIPT I-cache on CPU2
[    0.008089] CPU2: Booted secondary processor 0x0000000002 [0x410fd083]
[    0.008723] Detected PIPT I-cache on CPU3
[    0.008816] CPU3: Booted secondary processor 0x0000000003 [0x410fd083]
[    0.008901] smp: Brought up 1 node, 4 CPUs
[    0.008977] SMP: Total of 4 processors activated.
[    0.008993] CPU features: detected: 32-bit EL0 Support
[    0.009006] CPU features: detected: 32-bit EL1 Support
[    0.009023] CPU features: detected: CRC32 instructions
[    0.009132] CPU: All CPU(s) started at EL2
[    0.009156] alternatives: applying system-wide alternatives
[    0.011672] devtmpfs: initialized
[    0.021329] Enabled cp15_barrier support
[    0.021382] Enabled setend support
[    0.021552] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.021590] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.033655] pinctrl core: initialized pinctrl subsystem
[    0.034203] DMI not present or invalid.
[    0.034689] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.036535] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations
[    0.036808] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    0.037249] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    0.037328] audit: initializing netlink subsys (disabled)
[    0.037512] audit: type=2000 audit(0.036:1): state=initialized audit_enabled=0 res=1
[    0.038008] thermal_sys: Registered thermal governor 'step_wise'
[    0.038058] cpuidle: using governor menu
[    0.038266] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.038393] ASID allocator initialised with 32768 entries
[    0.039346] Serial: AMBA PL011 UART driver
[    0.046193] bcm2835-mbox fe00b880.mailbox: mailbox enabled
[    0.056436] raspberrypi-firmware soc:firmware: Attached to firmware from 2024-05-24T15:30:04, variant start
[    0.060439] raspberrypi-firmware soc:firmware: Firmware hash is 4942b7633c0ff1af1ee95a51a33b56a9dae47529
[    0.070703] Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL
[    0.070734] Modules: 0 pages in range for non-PLT usage
[    0.070740] Modules: 517792 pages in range for PLT usage
[    0.077684] bcm2835-dma fe007000.dma-controller: DMA legacy API manager, dmachans=0x1
[    0.079445] iommu: Default domain type: Translated
[    0.079467] iommu: DMA domain TLB invalidation policy: strict mode
[    0.079813] SCSI subsystem initialized
[    0.079988] usbcore: registered new interface driver usbfs
[    0.080035] usbcore: registered new interface driver hub
[    0.080091] usbcore: registered new device driver usb
[    0.080531] pps_core: LinuxPPS API ver. 1 registered
[    0.080551] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.080582] PTP clock support registered
[    0.084533] vgaarb: loaded
[    0.084911] clocksource: Switched to clocksource arch_sys_counter
[    0.085318] VFS: Disk quotas dquot_6.6.0
[    0.085371] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.085482] FS-Cache: Loaded
[    0.085657] CacheFiles: Loaded
[    0.098352] NET: Registered PF_INET protocol family
[    0.099173] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[    0.107562] tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear)
[    0.107668] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    0.107704] TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear)
[    0.108095] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear)
[    0.110780] TCP: Hash tables configured (established 65536 bind 65536)
[    0.111304] MPTCP token hash table entries: 8192 (order: 5, 196608 bytes, linear)
[    0.111624] UDP hash table entries: 4096 (order: 5, 131072 bytes, linear)
[    0.111738] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear)
[    0.112013] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    0.112606] RPC: Registered named UNIX socket transport module.
[    0.112631] RPC: Registered udp transport module.
[    0.112645] RPC: Registered tcp transport module.
[    0.112658] RPC: Registered tcp-with-tls transport module.
[    0.112672] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.112709] PCI: CLS 0 bytes, default 64
[    0.113125] Trying to unpack rootfs image as initramfs...
[    0.121108] kvm [1]: IPA Size Limit: 44 bits
[    0.122374] kvm [1]: vgic interrupt IRQ9
[    0.122429] kvm [1]: Hyp mode initialized successfully
[    0.655683] Freeing initrd memory: 11296K
[    1.118221] Initialise system trusted keyrings
[    1.118570] workingset: timestamp_bits=46 max_order=21 bucket_order=0
[    1.118670] zbud: loaded
[    1.119406] NFS: Registering the id_resolver key type
[    1.119444] Key type id_resolver registered
[    1.119460] Key type id_legacy registered
[    1.119495] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.119515] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    1.120160] Key type asymmetric registered
[    1.120186] Asymmetric key parser 'x509' registered
[    1.120245] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[    1.120422] io scheduler mq-deadline registered
[    1.120447] io scheduler kyber registered
[    1.120495] io scheduler bfq registered
[    1.121408] irq_brcmstb_l2: registered L2 intc (/soc/interrupt-controller@7ef00100, parent irq: 23)
[    1.124060] pinctrl-bcm2835 fe200000.gpio: GPIO_OUT persistence: yes
[    1.126434] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
[    1.126477] brcm-pcie fd500000.pcie:   No bus range found for /scb/pcie@7d500000, using [bus 00-ff]
[    1.126533] brcm-pcie fd500000.pcie:      MEM 0x0600000000..0x063fffffff -> 0x00c0000000
[    1.126581] brcm-pcie fd500000.pcie:   IB MEM 0x0000000000..0x00bfffffff -> 0x0400000000
[    1.127614] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
[    1.127643] pci_bus 0000:00: root bus resource [bus 00-ff]
[    1.127666] pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff])
[    1.127726] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
[    1.127824] pci 0000:00:00.0: PME# supported from D0 D3hot
[    1.131328] pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[    1.131480] pci_bus 0000:01: supply vpcie3v3 not found, using dummy regulator
[    1.131638] pci_bus 0000:01: supply vpcie3v3aux not found, using dummy regulator
[    1.131706] pci_bus 0000:01: supply vpcie12v not found, using dummy regulator
[    1.238995] brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC)
[    1.239142] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
[    1.239245] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
[    1.239538] pci 0000:01:00.0: PME# supported from D0 D3hot
[    1.248992] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
[    1.249039] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
[    1.249066] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
[    1.249136] pci 0000:00:00.0: PCI bridge to [bus 01]
[    1.249156] pci 0000:00:00.0:   bridge window [mem 0x600000000-0x6000fffff]
[    1.249391] pcieport 0000:00:00.0: enabling device (0000 -> 0002)
[    1.249555] pcieport 0000:00:00.0: PME: Signaling with IRQ 27
[    1.249880] pcieport 0000:00:00.0: AER: enabled with IRQ 27
[    1.257961] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
[    1.259918] iproc-rng200 fe104000.rng: hwrng registered
[    1.260179] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    1.270836] brd: module loaded
[    1.277085] loop: module loaded
[    1.277655] Loading iSCSI transport class v2.0-870.
[    1.281787] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000
[    1.488986] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus
[    1.490040] usbcore: registered new device driver r8152-cfgselector
[    1.490092] usbcore: registered new interface driver r8152
[    1.490136] usbcore: registered new interface driver lan78xx
[    1.490182] usbcore: registered new interface driver smsc95xx
[    1.537944] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.537986] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
[    1.539754] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0300240000000890
[    1.540488] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.540518] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
[    1.540548] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
[    1.540783] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06
[    1.540812] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.540834] usb usb1: Product: xHCI Host Controller
[    1.540850] usb usb1: Manufacturer: Linux 6.6.31+rpt-rpi-v8 xhci-hcd
[    1.540868] usb usb1: SerialNumber: 0000:01:00.0
[    1.541450] hub 1-0:1.0: USB hub found
[    1.541501] hub 1-0:1.0: 1 port detected
[    1.542022] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06
[    1.542052] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.542073] usb usb2: Product: xHCI Host Controller
[    1.542089] usb usb2: Manufacturer: Linux 6.6.31+rpt-rpi-v8 xhci-hcd
[    1.542106] usb usb2: SerialNumber: 0000:01:00.0
[    1.542557] hub 2-0:1.0: USB hub found
[    1.542606] hub 2-0:1.0: 4 ports detected
[    1.543492] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    1.543615] dwc_otg: FIQ enabled
[    1.543622] dwc_otg: NAK holdoff enabled
[    1.543628] dwc_otg: FIQ split-transaction FSM enabled
[    1.543638] Module dwc_common_port init
[    1.544291] usbcore: registered new interface driver uas
[    1.544359] usbcore: registered new interface driver usb-storage
[    1.544818] mousedev: PS/2 mouse device common for all mice
[    1.547632] sdhci: Secure Digital Host Controller Interface driver
[    1.547659] sdhci: Copyright(c) Pierre Ossman
[    1.547872] sdhci-pltfm: SDHCI platform and OF driver helper
[    1.550480] ledtrig-cpu: registered to indicate activity on CPUs
[    1.550777] hid: raw HID events driver (C) Jiri Kosina
[    1.550911] usbcore: registered new interface driver usbhid
[    1.550930] usbhid: USB HID core driver
[    1.556046] hw perfevents: enabled with armv8_cortex_a72 PMU driver, 7 counters available
[    1.557423] NET: Registered PF_PACKET protocol family
[    1.557522] Key type dns_resolver registered
[    1.583428] registered taskstats version 1
[    1.583677] Loading compiled-in X.509 certificates
[    1.594506] Key type .fscrypt registered
[    1.594547] Key type fscrypt-provisioning registered
[    1.604321] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    1.604608] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    1.605135] uart-pl011 fe201000.serial: there is not valid maps for state default
[    1.605573] uart-pl011 fe201000.serial: cts_event_workaround enabled
[    1.605903] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 36, base_baud = 0) is a PL011 rev2
[    1.608047] of_cfs_init
[    1.608168] of_cfs_init: OK
[    1.608449] clk: Disabling unused clocks
[    1.645502] mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA
[    1.653944] Freeing unused kernel memory: 4864K
[    1.654156] Run /init as init process
[    1.654173]   with arguments:
[    1.654178]     /init
[    1.654184]   with environment:
[    1.654189]     HOME=/
[    1.654194]     TERM=linux
[    1.740777] mmc0: new ultra high speed DDR50 SDHC card at address 5048
[    1.741821] mmcblk0: mmc0:5048 SD32G 28.9 GiB
[    1.744237]  mmcblk0: p1 p2
[    1.744716] mmcblk0: mmc0:5048 SD32G 28.9 GiB
[    1.804948] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[    1.955561] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21
[    1.955616] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    1.955639] usb 1-1: Product: USB2.0 Hub
[    1.959146] hub 1-1:1.0: USB hub found
[    1.959544] hub 1-1:1.0: 4 ports detected
[    2.191306] brcmstb-i2c fef04500.i2c:  @97500hz registered in polling mode
[    2.191761] brcmstb-i2c fef09500.i2c:  @97500hz registered in polling mode
[    2.607666] EXT4-fs (mmcblk0p2): mounted filesystem 93c89e92-8f2e-4522-ad32-68faed883d2f ro with ordered data mode. Quota mode: none.
[    3.146583] systemd[1]: System time before build time, advancing clock.
[    3.307561] NET: Registered PF_INET6 protocol family
[    3.308876] Segment Routing with IPv6
[    3.308963] In-situ OAM (IOAM) with IPv6
[    3.374435] systemd[1]: systemd 252.22-1~deb12u1 running in system mode (+PAM +AUDIT +SELINUX +APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified)
[    3.374522] systemd[1]: Detected architecture arm64.
[    3.382945] systemd[1]: Hostname set to <raspberrypi>.
[    4.054675] systemd[1]: Queued start job for default target multi-user.target.
[    4.087765] systemd[1]: Created slice system-devmon.slice - Slice /system/devmon.
[    4.089559] systemd[1]: Created slice system-getty.slice - Slice /system/getty.
[    4.090943] systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe.
[    4.092392] systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck.
[    4.093451] systemd[1]: Created slice user.slice - User and Session Slice.
[    4.094038] systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
[    4.094518] systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch.
[    4.095478] systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point.
[    4.095808] systemd[1]: Expecting device dev-disk-by\x2dpartuuid-83557f53\x2d01.device - /dev/disk/by-partuuid/83557f53-01...
[    4.096079] systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes.
[    4.096373] systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes.
[    4.096757] systemd[1]: Reached target paths.target - Path Units.
[    4.097162] systemd[1]: Reached target slices.target - Slice Units.
[    4.097460] systemd[1]: Reached target swap.target - Swaps.
[    4.097759] systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes.
[    4.098585] systemd[1]: Listening on systemd-fsckd.socket - fsck to fsckd communication Socket.
[    4.099090] systemd[1]: Listening on systemd-initctl.socket - initctl Compatibility Named Pipe.
[    4.100400] systemd[1]: Listening on systemd-journald-audit.socket - Journal Audit Socket.
[    4.101310] systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log).
[    4.102172] systemd[1]: Listening on systemd-journald.socket - Journal Socket.
[    4.104159] systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
[    4.104893] systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
[    4.105836] systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages).
[    4.137275] systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System...
[    4.141702] systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System...
[    4.146436] systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System...
[    4.147389] systemd[1]: auth-rpcgss-module.service - Kernel Module supporting RPCSEC_GSS was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab).
[    4.156477] systemd[1]: Starting fake-hwclock.service - Restore / save the current clock...
[    4.161322] systemd[1]: Starting keyboard-setup.service - Set the console keyboard layout...
[    4.166761] systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
[    4.172139] systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs...
[    4.179164] systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
[    4.187330] systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
[    4.192952] systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
[    4.198625] systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse...
[    4.204876] systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
[    4.205869] systemd[1]: systemd-fsck-root.service - File System Check on Root Device was skipped because of an unmet condition check (ConditionPathExists=!/run/initramfs/fsck-root).
[    4.226140] systemd[1]: Starting systemd-journald.service - Journal Service...
[    4.244661] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com
[    4.247635] systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
[    4.262128] systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems...
[    4.277717] systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
[    4.289915] fuse: init (API version 7.39)
[    4.292395] systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System.
[    4.295166] systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System.
[    4.296486] systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System.
[    4.305714] systemd[1]: Finished fake-hwclock.service - Restore / save the current clock.
[    4.308206] systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
[    4.310342] systemd[1]: modprobe@configfs.service: Deactivated successfully.
[    4.311219] systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs.
[    4.317478] systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
[    4.318424] systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
[    4.320590] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
[    4.321467] systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
[    4.323470] systemd[1]: modprobe@fuse.service: Deactivated successfully.
[    4.325371] systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse.
[    4.328303] systemd[1]: modprobe@loop.service: Deactivated successfully.
[    4.333175] systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
[    4.335076] systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules.
[    4.372626] EXT4-fs (mmcblk0p2): re-mounted 93c89e92-8f2e-4522-ad32-68faed883d2f r/w. Quota mode: none.
[    4.373383] systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System...
[    4.380051] systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System...
[    4.380735] systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
[    4.385539] systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables...
[    4.407114] systemd[1]: modprobe@drm.service: Deactivated successfully.
[    4.413075] systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm.
[    4.414820] systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems.
[    4.416028] systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System.
[    4.417479] systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System.
[    4.418431] systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes).
[    4.418680] systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
[    4.449967] systemd[1]: Starting systemd-random-seed.service - Load/Save Random Seed...
[    4.459093] systemd[1]: Starting systemd-sysusers.service - Create System Users...
[    4.465839] systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables.
[    4.487273] systemd[1]: Started systemd-journald.service - Journal Service.
[    4.549491] systemd-journald[247]: Received client request to flush runtime journal.
[    4.557997] systemd-journald[247]: File /var/log/journal/200a928e01d949b9869da89c95108c7e/system.journal corrupted or uncleanly shut down, renaming and replacing.
[    5.700871] rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000
[    5.709088] rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem
[    5.822080] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[    5.830017] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[    5.830051] [vc_sm_connected_init]: start
[    5.835023] [vc_sm_connected_init]: installed successfully
[    5.856197] mc: Linux media interface: v0.10
[    5.915689] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[    6.032851] videodev: Linux video capture interface: v2.00
[    6.153988] [drm] Initialized v3d 1.0.0 20180419 for fec00000.v3d on minor 0
[    6.344592] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    6.370939] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[    6.411313] rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned.
[    6.421746] rpivid feb10000.codec: Device registered as /dev/video19
[    6.433400] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[    6.434823] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[    6.448708] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[    6.449935] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[    6.450415] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[    6.450747] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[    6.450776] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    6.450794] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    6.450809] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    6.450822] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    6.463467] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20
[    6.469150] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21
[    6.469922] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22
[    6.471499] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23
[    6.471540] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    6.471555] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    6.471573] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    6.471586] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    6.471765] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[    6.471789] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[    6.471809] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[    6.521071] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[    6.521123] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[    6.533553] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[    6.533600] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[    6.545094] bcm2835-codec bcm2835-codec: Device registered as /dev/video18
[    6.545142] bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx
[    6.609276] bcm2835-codec bcm2835-codec: Device registered as /dev/video31
[    6.609327] bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image
[    6.726425] vc4-drm gpu: bound fe400000.hvs (ops vc4_hvs_ops [vc4])
[    6.735049] Registered IR keymap rc-cec
[    6.735228] rc rc0: vc4-hdmi-0 as /devices/platform/soc/fef00700.hdmi/rc/rc0
[    6.735419] input: vc4-hdmi-0 as /devices/platform/soc/fef00700.hdmi/rc/rc0/input0
[    6.744317] input: vc4-hdmi-0 HDMI Jack as /devices/platform/soc/fef00700.hdmi/sound/card1/input1
[    6.746664] vc4-drm gpu: bound fef00700.hdmi (ops vc4_hdmi_ops [vc4])
[    6.749408] Registered IR keymap rc-cec
[    6.749598] rc rc1: vc4-hdmi-1 as /devices/platform/soc/fef05700.hdmi/rc/rc1
[    6.749799] input: vc4-hdmi-1 as /devices/platform/soc/fef05700.hdmi/rc/rc1/input2
[    6.755670] input: vc4-hdmi-1 HDMI Jack as /devices/platform/soc/fef05700.hdmi/sound/card2/input3
[    6.756630] vc4-drm gpu: bound fef05700.hdmi (ops vc4_hdmi_ops [vc4])
[    6.757185] vc4-drm gpu: bound fe004000.txp (ops vc4_txp_ops [vc4])
[    6.757605] vc4-drm gpu: bound fe206000.pixelvalve (ops vc4_crtc_ops [vc4])
[    6.757949] vc4-drm gpu: bound fe207000.pixelvalve (ops vc4_crtc_ops [vc4])
[    6.758292] vc4-drm gpu: bound fe20a000.pixelvalve (ops vc4_crtc_ops [vc4])
[    6.758537] vc4-drm gpu: bound fe216000.pixelvalve (ops vc4_crtc_ops [vc4])
[    6.758881] vc4-drm gpu: bound fec12000.pixelvalve (ops vc4_crtc_ops [vc4])
[    6.774991] [drm] Initialized vc4 0.0.0 20140616 for gpu on minor 1
[    6.778051] vc4-drm gpu: [drm] Cannot find any crtc or sizes
[    6.785290] vc4-drm gpu: [drm] Cannot find any crtc or sizes
[    6.785613] vc4-drm gpu: [drm] Cannot find any crtc or sizes
[    7.871915] Adding 204796k swap on /var/swap.  Priority:-2 extents:3 across:319488k SS
[    9.122791] bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay)
[    9.123928] bcmgenet fd580000.ethernet eth0: Link is Down
[   13.214552] bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
[  152.812811] systemd[780]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set

EEPROM
------

BOOTLOADER: up to date
   CURRENT: ma 15 apr 2024 13:12:14 UTC (1713186734)
    LATEST: ma 15 apr 2024 13:12:14 UTC (1713186734)
   RELEASE: default (/lib/firmware/raspberrypi/bootloader-2711/default)
            Use raspi-config to change the release.

  VL805_FW: Using bootloader EEPROM
     VL805: up to date
   CURRENT: 000138c0
    LATEST: 000138c0

cat /etc/rpi-issue Raspberry Pi reference 2024-03-15 Generated using pi-gen, https://github.com/RPi-Distro/pi-gen, f19ee211ddafcae300827f953d143de92a5c6624, stage2

vcgencmd version May 24 2024 15:30:04 Copyright (c) 2012 Broadcom version 4942b7633c0ff1af1ee95a51a33b56a9dae47529 (clean) (release) (start)

uname -a Linux raspberrypi 6.6.31+rpt-rpi-v8 raspberrypi/firmware#1 SMP PREEMPT Debian 1:6.6.31-1+rpt1 (2024-05-29) aarch64 GNU/Linux

Logs If applicable, add the relevant output from dmesg or similar. TODO: journalctl -fk log

Additional context Unplugging other devices such as usb mice, or a USB stick hundreds of times yield no such trouble. Tested this on the same Pi4B system and on several CM4 boards that have VL805's.

There are different, but related problems, as well. At one point an I2S based alsa device was unreachable. If that's the case, it may also be there's a dangerous bug in one of the kernel modules.. which means i'll have to post this in the kernel repo as well.

Additionally, if you monitor incoming midi data with the standard alsa tool amidi:

amidi -d -phw:3,0,0

then you'll see hundreds, even thousands of urb status -32 messages flying around in journalctl -fk (or dmesg)

even up to the point of the kernel message buffer getting flooded over and over. this does not sound healthy. 

...
jul 08 08:33:38 raspberrypi kernel: usb 1-1.3: urb status -32
jul 08 08:33:38 raspberrypi kernel: usb 1-1.3: urb status -32
jul 08 08:33:38 raspberrypi systemd-journald[245]: /dev/kmsg buffer overrun, some messages lost.
jul 08 08:33:38 raspberrypi kernel: usb 1-1.3: urb status -32
jul 08 08:33:38 raspberrypi kernel: usb 1-1.3: urb status -32
jul 08 08:33:38 raspberrypi kernel: usb 1-1.3: urb status -32
jul 08 08:33:38 raspberrypi kernel: usb 1-1.3: urb status -32
jul 08 08:33:38 raspberrypi systemd-journald[245]: /dev/kmsg buffer overrun, some messages lost.
...

needless to say, this is a bog standard pi4b and a bog standard raspberry os lite bookworm.

6by9 commented 1 month ago

Duplicated by https://forums.raspberrypi.com/viewtopic.php?p=2235220 (now locked).

pietervandermeer commented 1 month ago

@6by9 so should I post this on the raspberry/linux repo instead? To me it seems like the VL805 is cycling its VBUS, although I have not used an oscilloscope to verify this..

popcornmix commented 1 month ago

@6by9 so should I post this on the raspberry/linux repo instead?

I've transferred it. It's more likely to a linux issue that a firmware issue (the firmware does nothing related to usb once kernel has started).

pelwell commented 1 month ago

Unless it's a VL805 firmware problem.

P33M commented 1 month ago

It looks like the device in question is a full-size keyboard. Does it back-power VBus?

An easy (but relatively inaccurate) way to tell is if you plug the keyboard into a powered Pi 4, let it enumerate, then unplug the USB-C port on the Pi. If you still have a red LED, then the keyboard is misbehaving.

pietervandermeer commented 1 month ago

It looks like the device in question is a full-size keyboard. Does it back-power VBus?

An easy (but relatively inaccurate) way to tell is if you plug the keyboard into a powered Pi 4, let it enumerate, then unplug the USB-C port on the Pi. If you still have a red LED, then the keyboard is misbehaving.

Ok, this was a genius suggestion! I removed the AKAI's PSU and I could just plug and unplug hundreds of times! Using another USB-powered keyboard yields the same. Thanks a lot!

That still leaves the urb status -32 flood. I'll have to test if this destabilizes the system..

pietervandermeer commented 1 month ago

The answer is no: the urb status -32 flood doesn't destabilize the system, but of course it fills up the system logs.

pelwell commented 1 month ago

You may be able to use an unpowered hub as an "insulator"

pietervandermeer commented 1 month ago

@pelwell That's pragmatic, but otoh.. The VL805 itself is already a hub. And the Pi3B's hub doesn't suffer from this problem.

pelwell commented 1 month ago

The fact that some devices are tolerant of bad behaviour doesn't make the behaviour less bad.

6by9 commented 1 month ago

The USB specification states that the USB peripheral must not back power the USB host, so the Akai isn't compliant to the USB spec. Having found a review of the MPK25 it says it isn't supplied with a mains adapter as standard.

On all Pis all the USB connectors connect the VBUS lines together. Any one USB peripheral that does dubious things to that supply rail is likely to affect all USB devices connected directly to the Pi. Short VBUS of one USB to ground and any bus powered devices will disconnect as they have no power. The USB over current protection in the Pi should stop the Pi from rebooting.

As for the 3B not having the same issue, you're likely down to exactly how quickly the protection circuits recover from being reverse powered to having to supply power to all the USB devices.

pietervandermeer commented 1 month ago

Indeed, this backpowering behavior is against USB specifications. It's best to not let this issue go philosophical. I'll accept that some device manufacturers need to do their homework, but also that host manufacturers, if they have the budget (and the users), need to develop defensively. I'll leave it at that.

For the Linux kernel it's more important to understand why it prints a thousand times the same messages for something as common as a broken pipe..