linux icon indicating copy to clipboard operation
linux copied to clipboard

No signal using vc4-kms-v3d dtoverlay on pi4

Open giovariot opened this issue 3 years ago • 7 comments

Describe the bug

I'm using a raspberry pi 4B, unless I set dtoverlay to use vc4-fkms-v3d I can't manage to get any signal through the HDMI after the raspberry boot logo with the kms driver. Last raspbian version then doesn't really work unless I fix the config.txt file. Older raspbian versions such as the stable version seem to work as expected (apart from some hiccups such as the system getting stuck at boot showing the "_" at the top left of the screen, behaviour that also applies to the newer version too sometimes, even using fkms

I also have the same problem on libreelec version 10.0.1 onwards, which I think it might be using this linux kernel, but I'm gonna ask on their forum too. Their version 10.0.0 seems to work fine though but I think it was using an older graphics driver.

At first I thought it was because I was dual booting the two using PINN but even flashing the sdcard with raspian alone I get the same result.

I tried resetting the EEPROM a few times, tried different AC adapter and tried different sdcards and two different TVs getting the same result.

Steps to reproduce the behaviour

  • reset the EEPROM
  • flash raspbian last version
  • boot it

Device (s)

Raspberry Pi 4 Mod. B

System

$ cat /etc/rpi-issue
Raspberry Pi reference 2022-09-06
Generated using pi-gen, https://github.com/RPi-Distro/pi-gen, 66255495f29be5d09b765d081aff6fc0f11e59b4, stage4
$ vcgencmd version
Aug 26 2022 14:03:16 
Copyright (c) 2012 Broadcom
version 102f1e848393c2112206fadffaaf86db04e98326 (clean) (release) (start)
$ uname -a
Linux raspberrypi 5.15.61-v7l+ #1579 SMP Fri Aug 26 11:13:03 BST 2022 armv7l GNU/Linux

Logs

This is the log using vc4-fkms-v3d as I can't get the dmesg while using kms. I'll try to add one through ssh when I'll be able to do so...

$ dmesg
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 5.15.61-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) #1579 SMP Fri Aug 26 11:13:03 BST 2022
[    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.1
[    0.000000] random: crng init done
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] Reserved memory: created CMA memory pool at 0x000000001ac00000, size 320 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] percpu: Embedded 16 pages/cpu s35788 r8192 d21556 u65536
[    0.000000] pcpu-alloc: s35788 r8192 d21556 u65536 alloc=16*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 video=HDMI-A-1:1920x1080M@60 smsc95xx.macaddr=DC:A6:32:32:68:2B vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  console=ttyS0,115200 console=tty1 root=PARTUUID=d9e688b1-02 rootfstype=ext4 fsck.repair=yes rootwait quiet splash plymouth.ignore-serial-consoles
[    0.000000] Unknown kernel command line parameters "splash", will be passed to user space.
[    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 0x0000000014890000-0x0000000018890000] (64MB)
[    0.000000] Memory: 3601324K/4050944K available (10240K kernel code, 1385K rwdata, 3304K rodata, 2048K init, 592K bss, 121940K reserved, 327680K cma-reserved, 3264512K highmem)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 35501 entries in 105 pages
[    0.000000] ftrace: allocated 105 pages with 4 groups
[    0.000000] trace event string verifier disabled
[    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] 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.000018] Switching to timer-based delay loop, resolution 18ns
[    0.000367] Console: colour dummy device 80x30
[    0.000431] printk: console [tty1] enabled
[    0.000499] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000)
[    0.000530] pid_max: default: 32768 minimum: 301
[    0.000671] LSM: Security Framework initializing
[    0.000880] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.000910] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.002499] cgroup: Disabling memory control group subsystem
[    0.002751] CPU: Testing write buffer coherency: ok
[    0.002809] CPU0: Spectre BHB: enabling loop workaround for all CPUs
[    0.003274] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.004633] Setting up static identity map for 0x200000 - 0x20003c
[    0.004859] rcu: Hierarchical SRCU implementation.
[    0.005892] smp: Bringing up secondary CPUs ...
[    0.007099] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.008428] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.009760] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.009929] smp: Brought up 1 node, 4 CPUs
[    0.009952] SMP: Total of 4 processors activated (432.00 BogoMIPS).
[    0.009970] CPU: All CPU(s) started in HYP mode.
[    0.009983] CPU: Virtualization extensions available.
[    0.010787] devtmpfs: initialized
[    0.026055] VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0
[    0.026295] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.026329] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.036843] pinctrl core: initialized pinctrl subsystem
[    0.038090] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.042675] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    0.043466] audit: initializing netlink subsys (disabled)
[    0.043728] audit: type=2000 audit(0.040:1): state=initialized audit_enabled=0 res=1
[    0.044398] thermal_sys: Registered thermal governor 'step_wise'
[    0.045214] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.045234] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.045655] Serial: AMBA PL011 UART driver
[    0.057123] bcm2835-mbox fe00b880.mailbox: mailbox enabled
[    0.090051] raspberrypi-firmware soc:firmware: Attached to firmware from 2022-08-26T14:03:16, variant start
[    0.100067] raspberrypi-firmware soc:firmware: Firmware hash is 102f1e848393c2112206fadffaaf86db04e98326
[    0.159040] Kprobes globally optimized
[    0.165153] bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1
[    0.169625] vgaarb: loaded
[    0.170125] SCSI subsystem initialized
[    0.170934] usbcore: registered new interface driver usbfs
[    0.171009] usbcore: registered new interface driver hub
[    0.171085] usbcore: registered new device driver usb
[    0.171436] usb_phy_generic phy: supply vcc not found, using dummy regulator
[    0.171629] usb_phy_generic phy: dummy supplies not allowed for exclusive requests
[    0.171965] pps_core: LinuxPPS API ver. 1 registered
[    0.171980] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <[email protected]>
[    0.172009] PTP clock support registered
[    0.173782] clocksource: Switched to clocksource arch_sys_counter
[    0.254521] VFS: Disk quotas dquot_6.6.0
[    0.254631] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.254816] FS-Cache: Loaded
[    0.255024] CacheFiles: Loaded
[    0.256103] simple-framebuffer 3e3cf000.framebuffer: framebuffer at 0x3e3cf000, 0x7f8000 bytes
[    0.256126] simple-framebuffer 3e3cf000.framebuffer: format=a8r8g8b8, mode=1920x1080x32, linelength=7680
[    0.256653] Console: switching to colour frame buffer device 240x67
[    0.267286] simple-framebuffer 3e3cf000.framebuffer: fb0: simplefb registered!
[    0.277036] NET: Registered PF_INET protocol family
[    0.277300] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    0.278933] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
[    0.278968] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    0.278993] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    0.279059] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.279133] TCP: Hash tables configured (established 8192 bind 8192)
[    0.279292] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[    0.279329] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[    0.279589] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    0.284712] RPC: Registered named UNIX socket transport module.
[    0.284730] RPC: Registered udp transport module.
[    0.284744] RPC: Registered tcp transport module.
[    0.284758] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.284781] PCI: CLS 0 bytes, default 64
[    1.323648] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
[    1.326154] Initialise system trusted keyrings
[    1.326462] workingset: timestamp_bits=14 max_order=20 bucket_order=6
[    1.335590] zbud: loaded
[    1.337572] FS-Cache: Netfs 'nfs' registered for caching
[    1.338420] NFS: Registering the id_resolver key type
[    1.338465] Key type id_resolver registered
[    1.338480] Key type id_legacy registered
[    1.338611] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.338627] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    1.339746] Key type asymmetric registered
[    1.339764] Asymmetric key parser 'x509' registered
[    1.339995] bounce: pool size: 64 pages
[    1.340071] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[    1.340322] io scheduler mq-deadline registered
[    1.340338] io scheduler kyber registered
[    1.349397] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
[    1.349440] brcm-pcie fd500000.pcie:   No bus range found for /scb/pcie@7d500000, using [bus 00-ff]
[    1.349537] brcm-pcie fd500000.pcie:      MEM 0x0600000000..0x063fffffff -> 0x00c0000000
[    1.349629] brcm-pcie fd500000.pcie:   IB MEM 0x0000000000..0x00bfffffff -> 0x0400000000
[    1.405884] brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC)
[    1.406303] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
[    1.406323] pci_bus 0000:00: root bus resource [bus 00-ff]
[    1.406345] pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff])
[    1.406439] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
[    1.406689] pci 0000:00:00.0: PME# supported from D0 D3hot
[    1.410366] PCI: bus0: Fast back to back transfers disabled
[    1.410391] pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[    1.410764] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
[    1.410894] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
[    1.411328] pci 0000:01:00.0: PME# supported from D0 D3cold
[    1.415020] PCI: bus1: Fast back to back transfers disabled
[    1.415040] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
[    1.415085] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
[    1.415113] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
[    1.415198] pci 0000:00:00.0: PCI bridge to [bus 01]
[    1.415223] pci 0000:00:00.0:   bridge window [mem 0x600000000-0x6000fffff]
[    1.415637] pcieport 0000:00:00.0: enabling device (0140 -> 0142)
[    1.415877] pcieport 0000:00:00.0: PME: Signaling with IRQ 60
[    1.428589] iproc-rng200 fe104000.rng: hwrng registered
[    1.428906] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    1.430363] gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000
[    1.443404] brd: module loaded
[    1.453938] loop: module loaded
[    1.454689] Loading iSCSI transport class v2.0-870.
[    1.459861] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000
[    1.553971] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus
[    1.555204] usbcore: registered new interface driver r8152
[    1.555280] usbcore: registered new interface driver lan78xx
[    1.555361] usbcore: registered new interface driver smsc95xx
[    1.557002] xhci_hcd 0000:01:00.0: enabling device (0140 -> 0142)
[    1.557187] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.557222] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
[    1.562939] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x00003c0000000890
[    1.564090] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.564119] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
[    1.564145] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
[    1.564538] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15
[    1.564563] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.564583] usb usb1: Product: xHCI Host Controller
[    1.564600] usb usb1: Manufacturer: Linux 5.15.61-v7l+ xhci-hcd
[    1.564616] usb usb1: SerialNumber: 0000:01:00.0
[    1.565379] hub 1-0:1.0: USB hub found
[    1.565448] hub 1-0:1.0: 1 port detected
[    1.566543] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.15
[    1.566567] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.566585] usb usb2: Product: xHCI Host Controller
[    1.566602] usb usb2: Manufacturer: Linux 5.15.61-v7l+ xhci-hcd
[    1.566618] usb usb2: SerialNumber: 0000:01:00.0
[    1.567322] hub 2-0:1.0: USB hub found
[    1.567394] hub 2-0:1.0: 4 ports detected
[    1.569172] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    1.569480] dwc_otg: FIQ enabled
[    1.569495] dwc_otg: NAK holdoff enabled
[    1.569508] dwc_otg: FIQ split-transaction FSM enabled
[    1.569526] Module dwc_common_port init
[    1.569995] usbcore: registered new interface driver uas
[    1.570108] usbcore: registered new interface driver usb-storage
[    1.570326] mousedev: PS/2 mouse device common for all mice
[    1.575363] sdhci: Secure Digital Host Controller Interface driver
[    1.575382] sdhci: Copyright(c) Pierre Ossman
[    1.576003] sdhci-pltfm: SDHCI platform and OF driver helper
[    1.579647] ledtrig-cpu: registered to indicate activity on CPUs
[    1.580005] hid: raw HID events driver (C) Jiri Kosina
[    1.580193] usbcore: registered new interface driver usbhid
[    1.580209] usbhid: USB HID core driver
[    1.586669] Initializing XFRM netlink socket
[    1.586714] NET: Registered PF_PACKET protocol family
[    1.586828] Key type dns_resolver registered
[    1.587314] Registering SWP/SWPB emulation handler
[    1.588113] registered taskstats version 1
[    1.588144] Loading compiled-in X.509 certificates
[    1.588976] Key type ._fscrypt registered
[    1.588993] Key type .fscrypt registered
[    1.589007] Key type fscrypt-provisioning registered
[    1.601108] uart-pl011 fe201000.serial: there is not valid maps for state default
[    1.601442] uart-pl011 fe201000.serial: cts_event_workaround enabled
[    1.601600] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 32, base_baud = 0) is a PL011 rev2
[    1.611045] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    1.611536] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    1.612841] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0
[    1.612861] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated
[    1.643226] of_cfs_init
[    1.643490] of_cfs_init: OK
[    1.681479] mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA
[    1.681922] Waiting for root device PARTUUID=d9e688b1-02...
[    1.737300] mmc1: new high speed SDIO card at address 0001
[    1.781225] mmc0: new ultra high speed DDR50 SDHC card at address 0001
[    1.782206] mmcblk0: mmc0:0001 BB1QT 29.8 GiB 
[    1.785350]  mmcblk0: p1 p2
[    1.785959] mmcblk0: mmc0:0001 BB1QT 29.8 GiB
[    1.811492] EXT4-fs (mmcblk0p2): INFO: recovery required on readonly filesystem
[    1.811514] EXT4-fs (mmcblk0p2): write access will be enabled during recovery
[    1.853843] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[    2.036485] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21
[    2.036515] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    2.036535] usb 1-1: Product: USB2.0 Hub
[    2.038654] hub 1-1:1.0: USB hub found
[    2.038919] hub 1-1:1.0: 4 ports detected
[    2.363813] usb 1-1.3: new full-speed USB device number 3 using xhci_hcd
[    2.503328] usb 1-1.3: New USB device found, idVendor=045e, idProduct=0800, bcdDevice= 9.44
[    2.503352] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    2.503371] usb 1-1.3: Product: Microsoft® Nano Transceiver v2.0
[    2.503388] usb 1-1.3: Manufacturer: Microsoft
[    2.514115] input: Microsoft Microsoft® Nano Transceiver v2.0 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:045E:0800.0001/input/input0
[    2.584578] hid-generic 0003:045E:0800.0001: input,hidraw0: USB HID v1.11 Keyboard [Microsoft Microsoft® Nano Transceiver v2.0] on usb-0000:01:00.0-1.3/input0
[    2.598759] input: Microsoft Microsoft® Nano Transceiver v2.0 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:045E:0800.0002/input/input1
[    2.599255] input: Microsoft Microsoft® Nano Transceiver v2.0 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:045E:0800.0002/input/input2
[    2.599573] hid-generic 0003:045E:0800.0002: input,hidraw1: USB HID v1.11 Mouse [Microsoft Microsoft® Nano Transceiver v2.0] on usb-0000:01:00.0-1.3/input1
[    2.612252] input: Microsoft Microsoft® Nano Transceiver v2.0 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.2/0003:045E:0800.0003/input/input3
[    2.674205] input: Microsoft Microsoft® Nano Transceiver v2.0 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.2/0003:045E:0800.0003/input/input5
[    2.674767] hid-generic 0003:045E:0800.0003: input,hiddev96,hidraw2: USB HID v1.11 Device [Microsoft Microsoft® Nano Transceiver v2.0] on usb-0000:01:00.0-1.3/input2
[    3.213725] EXT4-fs (mmcblk0p2): recovery complete
[    3.261999] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
[    3.262092] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[    3.262912] devtmpfs: mounted
[    3.274133] Freeing unused kernel image (initmem) memory: 2048K
[    3.274460] Run /sbin/init as init process
[    3.274475]   with arguments:
[    3.274487]     /sbin/init
[    3.274500]     splash
[    3.274513]   with environment:
[    3.274525]     HOME=/
[    3.274539]     TERM=linux
[    3.641966] systemd[1]: System time before build time, advancing clock.
[    3.854347] NET: Registered PF_INET6 protocol family
[    3.856109] Segment Routing with IPv6
[    3.856156] In-situ OAM (IOAM) with IPv6
[    3.934599] systemd[1]: systemd 247.3-7+rpi1+deb11u1 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +ZSTD +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=unified)
[    3.935513] systemd[1]: Detected architecture arm.
[    3.937748] systemd[1]: Set hostname to <raspberrypi>.
[    4.710301] systemd[1]: /lib/systemd/system/plymouth-start.service:16: Unit configured to use KillMode=none. This is unsafe, as it disables systemd's process lifecycle management for the service. Please update your service to use a safer KillMode=, such as 'mixed' or 'control-group'. Support for KillMode=none is deprecated and will eventually be removed.
[    4.901804] systemd[1]: Queued start job for default target Graphical Interface.
[    4.907760] systemd[1]: Created slice system-getty.slice.
[    4.909223] systemd[1]: Created slice system-modprobe.slice.
[    4.910256] systemd[1]: Created slice system-systemd\x2dfsck.slice.
[    4.911095] systemd[1]: Created slice User and Session Slice.
[    4.911612] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    4.912774] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    4.913516] systemd[1]: Reached target Slices.
[    4.913744] systemd[1]: Reached target Swap.
[    4.915738] systemd[1]: Listening on Syslog Socket.
[    4.919550] systemd[1]: Listening on fsck to fsckd communication Socket.
[    4.920008] systemd[1]: Listening on initctl Compatibility Named Pipe.
[    4.924440] systemd[1]: Listening on Journal Audit Socket.
[    4.925279] systemd[1]: Listening on Journal Socket (/dev/log).
[    4.926522] systemd[1]: Listening on Journal Socket.
[    4.928330] systemd[1]: Listening on udev Control Socket.
[    4.929171] systemd[1]: Listening on udev Kernel Socket.
[    4.930155] systemd[1]: Condition check resulted in Huge Pages File System being skipped.
[    4.935941] systemd[1]: Mounting POSIX Message Queue File System...
[    4.942981] systemd[1]: Mounting RPC Pipe File System...
[    4.950368] systemd[1]: Mounting Kernel Debug File System...
[    4.958647] systemd[1]: Mounting Kernel Trace File System...
[    4.959436] systemd[1]: Condition check resulted in Kernel Module supporting RPCSEC_GSS being skipped.
[    4.970850] systemd[1]: Starting Restore / save the current clock...
[    4.979311] systemd[1]: Starting Set the console keyboard layout...
[    4.988728] systemd[1]: Starting Create list of static device nodes for the current kernel...
[    4.998051] systemd[1]: Starting Load Kernel Module configfs...
[    5.006832] systemd[1]: Starting Load Kernel Module drm...
[    5.016605] systemd[1]: Starting Load Kernel Module fuse...
[    5.024093] systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped.
[    5.031591] systemd[1]: Starting File System Check on Root Device...
[    5.045123] systemd[1]: Starting Journal Service...
[    5.062295] systemd[1]: Starting Load Kernel Modules...
[    5.071361] systemd[1]: Starting Coldplug All udev Devices...
[    5.111331] systemd[1]: Mounted POSIX Message Queue File System.
[    5.112237] systemd[1]: Mounted RPC Pipe File System.
[    5.113586] systemd[1]: Mounted Kernel Debug File System.
[    5.115143] systemd[1]: Mounted Kernel Trace File System.
[    5.120564] systemd[1]: Finished Restore / save the current clock.
[    5.123471] systemd[1]: Finished Create list of static device nodes for the current kernel.
[    5.125605] systemd[1]: [email protected]: Succeeded.
[    5.127333] systemd[1]: Finished Load Kernel Module configfs.
[    5.135767] systemd[1]: Mounting Kernel Configuration File System...
[    5.147989] systemd[1]: Started File System Check Daemon to report status.
[    5.194170] fuse: init (API version 7.34)
[    5.206774] systemd[1]: [email protected]: Succeeded.
[    5.208559] systemd[1]: Finished Load Kernel Module fuse.
[    5.209790] systemd[1]: Mounted Kernel Configuration File System.
[    5.216403] systemd[1]: Mounting FUSE Control File System...
[    5.223413] i2c_dev: i2c /dev entries driver
[    5.246522] systemd[1]: Finished Load Kernel Modules.
[    5.254487] systemd[1]: Starting Apply Kernel Variables...
[    5.267168] systemd[1]: Mounted FUSE Control File System.
[    5.319858] systemd[1]: [email protected]: Succeeded.
[    5.321439] systemd[1]: Finished Load Kernel Module drm.
[    5.327855] systemd[1]: Finished Apply Kernel Variables.
[    5.378041] systemd[1]: Finished File System Check on Root Device.
[    5.385009] systemd[1]: Starting Remount Root and Kernel File Systems...
[    5.520192] systemd[1]: Started Journal Service.
[    5.573902] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null). Quota mode: none.
[    5.650681] systemd-journald[137]: Received client request to flush runtime journal.
[    5.746939] systemd-journald[137]: File /var/log/journal/13c7dcc8cae14fa8bf0df2919ba3a428/system.journal corrupted or uncleanly shut down, renaming and replacing.
[    7.231433] mc: Linux media interface: v0.10
[    7.530197] videodev: Linux video capture interface: v2.00
[    7.788189] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[    7.844746] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[    7.844828] [vc_sm_connected_init]: start
[    7.870462] [vc_sm_connected_init]: installed successfully
[    7.915807] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[    7.933050] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    7.944959] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[    7.949722] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    7.953391] rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned.
[    7.993331] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    8.010732] rpivid feb10000.codec: Device registered as /dev/video19
[    8.013566] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[    8.018650] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[    8.045286] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[    8.049407] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[    8.049648] bcm2835_audio bcm2835_audio: card created with 4 channels
[    8.050441] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[    8.053162] bcm2835_audio bcm2835_audio: card created with 4 channels
[    8.056149] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[    8.057602] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[    8.057695] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    8.057771] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    8.057845] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    8.057919] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    8.063752] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20
[    8.065317] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21
[    8.097822] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22
[    8.131566] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[    8.131650] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[    8.141073] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[    8.141148] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[    8.174818] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23
[    8.174865] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    8.174893] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    8.174918] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    8.174941] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    8.186347] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[    8.186416] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[    8.187854] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[    8.229935] bcm2835-codec bcm2835-codec: Device registered as /dev/video18
[    8.229987] bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx
[    8.240000] bcm2835-codec bcm2835-codec: Device registered as /dev/video31
[    8.247211] bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image
[    8.447032] [drm] Initialized v3d 1.0.0 20180419 for fec00000.v3d on minor 0
[    8.537291] checking generic (3e3cf000 7f8000) vs hw (0 ffffffffffffffff)
[    8.537319] fb0: switching to vc4 from simple
[    8.557576] Console: switching to colour dummy device 80x30
[    8.560570] vc4-drm gpu: bound fe600000.firmwarekms (ops vc4_fkms_ops [vc4])
[    8.693240] [drm] Initialized vc4 0.0.0 20140616 for gpu on minor 1
[    8.765085] Console: switching to colour frame buffer device 240x67
[    8.765141] vc4-drm gpu: [drm] fb0: vc4drmfb frame buffer device
[    8.930810] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[    9.056843] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[    9.085086] cfg80211: loaded regulatory.db is malformed or signature is missing/invalid
[    9.842128] brcmfmac: F1 signature read @0x18000000=0x15264345
[    9.870479] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    9.871761] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,4-model-b.bin failed with error -2
[   10.211615] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[   10.211757] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[   10.220602] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov  1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60
[   10.271227] usbcore: registered new interface driver brcmfmac
[   10.399657] uart-pl011 fe201000.serial: no DMA platform data
[   10.725776] dvbdev: DVB: registering new adapter (CXD2880)
[   10.795009] 8021q: 802.1Q VLAN Support v1.8
[   10.797761] cxd2880: cxd2880_attach: CXD2880 driver version: Ver 1.4.1 - 1.0.5
[   10.797795] cxd2880 spi0.0: DVB: registering adapter 0 frontend 0 (Sony CXD2880)...
[   11.093132] bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay)
[   11.096091] bcmgenet fd580000.ethernet eth0: Link is Down
[   11.105928] Adding 102396k swap on /var/swap.  Priority:-2 extents:1 across:102396k SSFS
[   11.115644] cxd2880_spi: cxd2880_spi_probe: Sony CXD2880 has successfully attached.
[   11.477425] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
[   16.235448] systemd-journald[137]: File /var/log/journal/13c7dcc8cae14fa8bf0df2919ba3a428/user-1000.journal corrupted or uncleanly shut down, renaming and replacing.
[   17.305227] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   17.366697] Bluetooth: Core ver 2.22
[   17.366762] NET: Registered PF_BLUETOOTH protocol family
[   17.366770] Bluetooth: HCI device and connection manager initialized
[   17.366788] Bluetooth: HCI socket layer initialized
[   17.366798] Bluetooth: L2CAP socket layer initialized
[   17.366815] Bluetooth: SCO socket layer initialized
[   17.376172] Bluetooth: HCI UART driver ver 2.3
[   17.376189] Bluetooth: HCI UART protocol H4 registered
[   17.376254] Bluetooth: HCI UART protocol Three-wire (H5) registered
[   17.376417] Bluetooth: HCI UART protocol Broadcom registered
[   17.658973] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[   17.658989] Bluetooth: BNEP filters: protocol multicast
[   17.659002] Bluetooth: BNEP socket layer initialized
[   17.867167] NET: Registered PF_ALG protocol family
[   18.341901] cryptd: max_cpu_qlen set to 1000
[   18.639093] ICMPv6: process `dhcpcd' is using deprecated sysctl (syscall) net.ipv6.neigh.wlan0.retrans_time - use net.ipv6.neigh.wlan0.retrans_time_ms instead
[   18.761105] Bluetooth: RFCOMM TTY layer initialized
[   18.761141] Bluetooth: RFCOMM socket layer initialized
[   18.761166] Bluetooth: RFCOMM ver 1.11
[   31.833854] cam-dummy-reg: disabling
[   44.737409] v3d fec00000.v3d: MMU error from client L2T (0) at 0x9921000, pte invalid

Additional context

No response

giovariot avatar Sep 20 '22 21:09 giovariot

Well... I've enabled ssh and rebooted just to check everything was alright, still using the fkms driver but this time I simply got a black screen...

pi@raspberrypi:~ $ dmesg
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 5.15.61-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) #1579 SMP Fri Aug 26 11:13:03 BST 2022
[    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.1
[    0.000000] random: crng init done
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] Reserved memory: created CMA memory pool at 0x000000001ac00000, size 320 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] percpu: Embedded 16 pages/cpu s35788 r8192 d21556 u65536
[    0.000000] pcpu-alloc: s35788 r8192 d21556 u65536 alloc=16*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 video=HDMI-A-1:1920x1080M@60 smsc95xx.macaddr=DC:A6:32:32:68:2B vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  console=ttyS0,115200 console=tty1 root=PARTUUID=d9e688b1-02 rootfstype=ext4 fsck.repair=yes rootwait quiet splash plymouth.ignore-serial-consoles
[    0.000000] Unknown kernel command line parameters "splash", will be passed to user space.
[    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 0x0000000014890000-0x0000000018890000] (64MB)
[    0.000000] Memory: 3601324K/4050944K available (10240K kernel code, 1385K rwdata, 3304K rodata, 2048K init, 592K bss, 121940K reserved, 327680K cma-reserved, 3264512K highmem)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 35501 entries in 105 pages
[    0.000000] ftrace: allocated 105 pages with 4 groups
[    0.000000] trace event string verifier disabled
[    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] 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.000018] Switching to timer-based delay loop, resolution 18ns
[    0.000366] Console: colour dummy device 80x30
[    0.000429] printk: console [tty1] enabled
[    0.000497] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000)
[    0.000528] pid_max: default: 32768 minimum: 301
[    0.000667] LSM: Security Framework initializing
[    0.000879] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.000908] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.002507] cgroup: Disabling memory control group subsystem
[    0.002756] CPU: Testing write buffer coherency: ok
[    0.002814] CPU0: Spectre BHB: enabling loop workaround for all CPUs
[    0.003283] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.004661] Setting up static identity map for 0x200000 - 0x20003c
[    0.004887] rcu: Hierarchical SRCU implementation.
[    0.005886] smp: Bringing up secondary CPUs ...
[    0.007098] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.008441] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.009753] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.009910] smp: Brought up 1 node, 4 CPUs
[    0.009934] SMP: Total of 4 processors activated (432.00 BogoMIPS).
[    0.009953] CPU: All CPU(s) started in HYP mode.
[    0.009967] CPU: Virtualization extensions available.
[    0.010773] devtmpfs: initialized
[    0.026045] VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0
[    0.026282] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.026316] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.036827] pinctrl core: initialized pinctrl subsystem
[    0.038133] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.042652] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    0.043422] audit: initializing netlink subsys (disabled)
[    0.043693] audit: type=2000 audit(0.040:1): state=initialized audit_enabled=0 res=1
[    0.044366] thermal_sys: Registered thermal governor 'step_wise'
[    0.045184] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.045204] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.045626] Serial: AMBA PL011 UART driver
[    0.057102] bcm2835-mbox fe00b880.mailbox: mailbox enabled
[    0.090051] raspberrypi-firmware soc:firmware: Attached to firmware from 2022-08-26T14:03:16, variant start
[    0.100068] raspberrypi-firmware soc:firmware: Firmware hash is 102f1e848393c2112206fadffaaf86db04e98326
[    0.158957] Kprobes globally optimized
[    0.165071] bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1
[    0.169535] vgaarb: loaded
[    0.170039] SCSI subsystem initialized
[    0.170857] usbcore: registered new interface driver usbfs
[    0.170933] usbcore: registered new interface driver hub
[    0.171007] usbcore: registered new device driver usb
[    0.171359] usb_phy_generic phy: supply vcc not found, using dummy regulator
[    0.171562] usb_phy_generic phy: dummy supplies not allowed for exclusive requests
[    0.171883] pps_core: LinuxPPS API ver. 1 registered
[    0.171898] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <[email protected]>
[    0.171927] PTP clock support registered
[    0.173663] clocksource: Switched to clocksource arch_sys_counter
[    0.254441] VFS: Disk quotas dquot_6.6.0
[    0.254550] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.254736] FS-Cache: Loaded
[    0.254949] CacheFiles: Loaded
[    0.255999] simple-framebuffer 3e3cf000.framebuffer: framebuffer at 0x3e3cf000, 0x7f8000 bytes
[    0.256024] simple-framebuffer 3e3cf000.framebuffer: format=a8r8g8b8, mode=1920x1080x32, linelength=7680
[    0.256560] Console: switching to colour frame buffer device 240x67
[    0.267195] simple-framebuffer 3e3cf000.framebuffer: fb0: simplefb registered!
[    0.277652] NET: Registered PF_INET protocol family
[    0.277914] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    0.279538] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
[    0.279573] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    0.279599] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    0.279665] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.279738] TCP: Hash tables configured (established 8192 bind 8192)
[    0.279903] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[    0.279943] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[    0.280207] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    0.284605] RPC: Registered named UNIX socket transport module.
[    0.284622] RPC: Registered udp transport module.
[    0.284637] RPC: Registered tcp transport module.
[    0.284650] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.284675] PCI: CLS 0 bytes, default 64
[    1.323072] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
[    1.325553] Initialise system trusted keyrings
[    1.325863] workingset: timestamp_bits=14 max_order=20 bucket_order=6
[    1.334970] zbud: loaded
[    1.336929] FS-Cache: Netfs 'nfs' registered for caching
[    1.337832] NFS: Registering the id_resolver key type
[    1.337878] Key type id_resolver registered
[    1.337893] Key type id_legacy registered
[    1.338028] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.338044] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    1.339135] Key type asymmetric registered
[    1.339152] Asymmetric key parser 'x509' registered
[    1.339387] bounce: pool size: 64 pages
[    1.339463] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[    1.339699] io scheduler mq-deadline registered
[    1.339714] io scheduler kyber registered
[    1.348767] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
[    1.348809] brcm-pcie fd500000.pcie:   No bus range found for /scb/pcie@7d500000, using [bus 00-ff]
[    1.348904] brcm-pcie fd500000.pcie:      MEM 0x0600000000..0x063fffffff -> 0x00c0000000
[    1.348997] brcm-pcie fd500000.pcie:   IB MEM 0x0000000000..0x00bfffffff -> 0x0400000000
[    1.405764] brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC)
[    1.406171] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
[    1.406191] pci_bus 0000:00: root bus resource [bus 00-ff]
[    1.406213] pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff])
[    1.406304] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
[    1.406556] pci 0000:00:00.0: PME# supported from D0 D3hot
[    1.410242] PCI: bus0: Fast back to back transfers disabled
[    1.410267] pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[    1.410642] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
[    1.410771] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
[    1.411202] pci 0000:01:00.0: PME# supported from D0 D3cold
[    1.414893] PCI: bus1: Fast back to back transfers disabled
[    1.414914] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
[    1.414959] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
[    1.414985] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
[    1.415070] pci 0000:00:00.0: PCI bridge to [bus 01]
[    1.415096] pci 0000:00:00.0:   bridge window [mem 0x600000000-0x6000fffff]
[    1.415517] pcieport 0000:00:00.0: enabling device (0140 -> 0142)
[    1.415752] pcieport 0000:00:00.0: PME: Signaling with IRQ 60
[    1.428492] iproc-rng200 fe104000.rng: hwrng registered
[    1.428808] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    1.430275] gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000
[    1.443318] brd: module loaded
[    1.453905] loop: module loaded
[    1.454667] Loading iSCSI transport class v2.0-870.
[    1.459854] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000
[    1.553826] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus
[    1.555065] usbcore: registered new interface driver r8152
[    1.555163] usbcore: registered new interface driver lan78xx
[    1.555226] usbcore: registered new interface driver smsc95xx
[    1.556849] xhci_hcd 0000:01:00.0: enabling device (0140 -> 0142)
[    1.557031] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.557066] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
[    1.562789] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x00003c0000000890
[    1.563905] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.563933] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
[    1.563959] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
[    1.564354] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15
[    1.564377] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.564396] usb usb1: Product: xHCI Host Controller
[    1.564412] usb usb1: Manufacturer: Linux 5.15.61-v7l+ xhci-hcd
[    1.564428] usb usb1: SerialNumber: 0000:01:00.0
[    1.565199] hub 1-0:1.0: USB hub found
[    1.565276] hub 1-0:1.0: 1 port detected
[    1.566340] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.15
[    1.566363] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.566382] usb usb2: Product: xHCI Host Controller
[    1.566399] usb usb2: Manufacturer: Linux 5.15.61-v7l+ xhci-hcd
[    1.566415] usb usb2: SerialNumber: 0000:01:00.0
[    1.567119] hub 2-0:1.0: USB hub found
[    1.567190] hub 2-0:1.0: 4 ports detected
[    1.568969] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    1.569276] dwc_otg: FIQ enabled
[    1.569290] dwc_otg: NAK holdoff enabled
[    1.569304] dwc_otg: FIQ split-transaction FSM enabled
[    1.569321] Module dwc_common_port init
[    1.569800] usbcore: registered new interface driver uas
[    1.569913] usbcore: registered new interface driver usb-storage
[    1.570134] mousedev: PS/2 mouse device common for all mice
[    1.575179] sdhci: Secure Digital Host Controller Interface driver
[    1.575198] sdhci: Copyright(c) Pierre Ossman
[    1.575818] sdhci-pltfm: SDHCI platform and OF driver helper
[    1.579472] ledtrig-cpu: registered to indicate activity on CPUs
[    1.579832] hid: raw HID events driver (C) Jiri Kosina
[    1.580019] usbcore: registered new interface driver usbhid
[    1.580036] usbhid: USB HID core driver
[    1.586566] Initializing XFRM netlink socket
[    1.586612] NET: Registered PF_PACKET protocol family
[    1.586735] Key type dns_resolver registered
[    1.587231] Registering SWP/SWPB emulation handler
[    1.588052] registered taskstats version 1
[    1.588083] Loading compiled-in X.509 certificates
[    1.588909] Key type ._fscrypt registered
[    1.588926] Key type .fscrypt registered
[    1.588940] Key type fscrypt-provisioning registered
[    1.601028] uart-pl011 fe201000.serial: there is not valid maps for state default
[    1.601364] uart-pl011 fe201000.serial: cts_event_workaround enabled
[    1.601514] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 32, base_baud = 0) is a PL011 rev2
[    1.610954] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    1.611442] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    1.612746] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0
[    1.612766] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated
[    1.643240] of_cfs_init
[    1.643498] of_cfs_init: OK
[    1.678809] mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA
[    1.679139] Waiting for root device PARTUUID=d9e688b1-02...
[    1.778012] mmc0: new ultra high speed DDR50 SDHC card at address 0001
[    1.779075] mmcblk0: mmc0:0001 BB1QT 29.8 GiB
[    1.784967] mmc1: new high speed SDIO card at address 0001
[    1.785618]  mmcblk0: p1 p2
[    1.786183] mmcblk0: mmc0:0001 BB1QT 29.8 GiB
[    1.801417] EXT4-fs (mmcblk0p2): INFO: recovery required on readonly filesystem
[    1.801438] EXT4-fs (mmcblk0p2): write access will be enabled during recovery
[    1.853709] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[    2.036311] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21
[    2.036342] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    2.036363] usb 1-1: Product: USB2.0 Hub
[    2.038455] hub 1-1:1.0: USB hub found
[    2.038769] hub 1-1:1.0: 4 ports detected
[    2.363686] usb 1-1.3: new full-speed USB device number 3 using xhci_hcd
[    2.503205] usb 1-1.3: New USB device found, idVendor=045e, idProduct=0800, bcdDevice= 9.44
[    2.503229] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    2.503249] usb 1-1.3: Product: Microsoft® Nano Transceiver v2.0
[    2.503265] usb 1-1.3: Manufacturer: Microsoft
[    2.514009] input: Microsoft Microsoft® Nano Transceiver v2.0 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:045E:0800.0001/input/input0
[    2.584430] hid-generic 0003:045E:0800.0001: input,hidraw0: USB HID v1.11 Keyboard [Microsoft Microsoft® Nano Transceiver v2.0] on usb-0000:01:00.0-1.3/input0
[    2.598627] input: Microsoft Microsoft® Nano Transceiver v2.0 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:045E:0800.0002/input/input1
[    2.599120] input: Microsoft Microsoft® Nano Transceiver v2.0 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:045E:0800.0002/input/input2
[    2.599441] hid-generic 0003:045E:0800.0002: input,hidraw1: USB HID v1.11 Mouse [Microsoft Microsoft® Nano Transceiver v2.0] on usb-0000:01:00.0-1.3/input1
[    2.612214] input: Microsoft Microsoft® Nano Transceiver v2.0 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.2/0003:045E:0800.0003/input/input3
[    2.674085] input: Microsoft Microsoft® Nano Transceiver v2.0 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.2/0003:045E:0800.0003/input/input5
[    2.674633] hid-generic 0003:045E:0800.0003: input,hiddev96,hidraw2: USB HID v1.11 Device [Microsoft Microsoft® Nano Transceiver v2.0] on usb-0000:01:00.0-1.3/input2
[    2.874040] EXT4-fs (mmcblk0p2): recovery complete
[    2.935793] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
[    2.935893] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[    2.936697] devtmpfs: mounted
[    2.947978] Freeing unused kernel image (initmem) memory: 2048K
[    2.948299] Run /sbin/init as init process
[    2.948315]   with arguments:
[    2.948329]     /sbin/init
[    2.948343]     splash
[    2.948356]   with environment:
[    2.948368]     HOME=/
[    2.948380]     TERM=linux
[    3.324632] systemd[1]: System time before build time, advancing clock.
[    3.535643] NET: Registered PF_INET6 protocol family
[    3.537471] Segment Routing with IPv6
[    3.537517] In-situ OAM (IOAM) with IPv6
[    3.613115] systemd[1]: systemd 247.3-7+rpi1+deb11u1 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +ZSTD +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=unified)
[    3.614106] systemd[1]: Detected architecture arm.
[    3.616323] systemd[1]: Set hostname to <raspberrypi>.
[    4.350729] systemd[1]: /lib/systemd/system/plymouth-start.service:16: Unit configured to use KillMode=none. This is unsafe, as it disables systemd's process lifecycle management for the service. Please update your service to use a safer KillMode=, such as 'mixed' or 'control-group'. Support for KillMode=none is deprecated and will eventually be removed.
[    4.543337] systemd[1]: Queued start job for default target Graphical Interface.
[    4.549300] systemd[1]: Created slice system-getty.slice.
[    4.550672] systemd[1]: Created slice system-modprobe.slice.
[    4.551780] systemd[1]: Created slice system-systemd\x2dfsck.slice.
[    4.552653] systemd[1]: Created slice User and Session Slice.
[    4.553182] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    4.554707] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    4.555449] systemd[1]: Reached target Slices.
[    4.555690] systemd[1]: Reached target Swap.
[    4.557564] systemd[1]: Listening on Syslog Socket.
[    4.561353] systemd[1]: Listening on fsck to fsckd communication Socket.
[    4.561825] systemd[1]: Listening on initctl Compatibility Named Pipe.
[    4.565977] systemd[1]: Listening on Journal Audit Socket.
[    4.566820] systemd[1]: Listening on Journal Socket (/dev/log).
[    4.567800] systemd[1]: Listening on Journal Socket.
[    4.569645] systemd[1]: Listening on udev Control Socket.
[    4.570479] systemd[1]: Listening on udev Kernel Socket.
[    4.571430] systemd[1]: Condition check resulted in Huge Pages File System being skipped.
[    4.577423] systemd[1]: Mounting POSIX Message Queue File System...
[    4.584039] systemd[1]: Mounting RPC Pipe File System...
[    4.591263] systemd[1]: Mounting Kernel Debug File System...
[    4.598703] systemd[1]: Mounting Kernel Trace File System...
[    4.599364] systemd[1]: Condition check resulted in Kernel Module supporting RPCSEC_GSS being skipped.
[    4.611234] systemd[1]: Starting Restore / save the current clock...
[    4.619027] systemd[1]: Starting Set the console keyboard layout...
[    4.627560] systemd[1]: Starting Create list of static device nodes for the current kernel...
[    4.635908] systemd[1]: Starting Load Kernel Module configfs...
[    4.644995] systemd[1]: Starting Load Kernel Module drm...
[    4.659434] systemd[1]: Starting Load Kernel Module fuse...
[    4.670614] systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped.
[    4.678187] systemd[1]: Starting File System Check on Root Device...
[    4.691762] systemd[1]: Starting Journal Service...
[    4.735161] fuse: init (API version 7.34)
[    4.735335] systemd[1]: Starting Load Kernel Modules...
[    4.754981] systemd[1]: Starting Coldplug All udev Devices...
[    4.783191] systemd[1]: Mounted POSIX Message Queue File System.
[    4.784153] systemd[1]: Mounted RPC Pipe File System.
[    4.784989] systemd[1]: Mounted Kernel Debug File System.
[    4.791481] systemd[1]: Mounted Kernel Trace File System.
[    4.801574] systemd[1]: Finished Restore / save the current clock.
[    4.804989] systemd[1]: Finished Create list of static device nodes for the current kernel.
[    4.807130] systemd[1]: [email protected]: Succeeded.
[    4.808783] systemd[1]: Finished Load Kernel Module configfs.
[    4.811009] systemd[1]: [email protected]: Succeeded.
[    4.812558] systemd[1]: Finished Load Kernel Module fuse.
[    4.840463] i2c_dev: i2c /dev entries driver
[    4.845597] systemd[1]: Mounting FUSE Control File System...
[    4.851907] systemd[1]: Mounting Kernel Configuration File System...
[    4.870325] systemd[1]: Started File System Check Daemon to report status.
[    4.889427] systemd[1]: Finished Load Kernel Modules.
[    4.890432] systemd[1]: Mounted FUSE Control File System.
[    4.923152] systemd[1]: Starting Apply Kernel Variables...
[    4.930269] systemd[1]: Mounted Kernel Configuration File System.
[    4.942598] systemd[1]: [email protected]: Succeeded.
[    4.955413] systemd[1]: Finished Load Kernel Module drm.
[    5.006415] systemd[1]: Finished File System Check on Root Device.
[    5.012823] systemd[1]: Starting Remount Root and Kernel File Systems...
[    5.021291] systemd[1]: Finished Apply Kernel Variables.
[    5.134286] systemd[1]: Started Journal Service.
[    5.220759] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null). Quota mode: none.
[    5.296210] systemd-journald[139]: Received client request to flush runtime journal.
[    5.409685] systemd-journald[139]: File /var/log/journal/13c7dcc8cae14fa8bf0df2919ba3a428/system.journal corrupted or uncleanly shut down, renaming and replacing.
[    6.906203] mc: Linux media interface: v0.10
[    7.002464] videodev: Linux video capture interface: v2.00
[    7.111482] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[    7.129138] rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned.
[    7.146963] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[    7.204531] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[    7.204566] [vc_sm_connected_init]: start
[    7.225690] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[    7.235594] [vc_sm_connected_init]: installed successfully
[    7.269448] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    7.274231] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    7.283267] rpivid feb10000.codec: Device registered as /dev/video19
[    7.299167] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    7.450764] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[    7.459389] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[    7.464087] bcm2835_audio bcm2835_audio: card created with 4 channels
[    7.484739] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[    7.491872] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[    7.491930] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[    7.503967] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[    7.504017] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[    7.523988] bcm2835_audio bcm2835_audio: card created with 4 channels
[    7.524159] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[    7.524205] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[    7.530934] bcm2835-codec bcm2835-codec: Device registered as /dev/video18
[    7.530991] bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx
[    7.542360] bcm2835-codec bcm2835-codec: Device registered as /dev/video31
[    7.542412] bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image
[    7.544256] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[    7.557422] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[    7.566490] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[    7.593995] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[    7.595661] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    7.595700] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    7.595724] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    7.595748] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    7.710975] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20
[    7.711719] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21
[    7.712287] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22
[    7.717751] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23
[    7.717795] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    7.717833] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    7.717867] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    7.717898] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    7.718257] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[    7.897632] checking generic (3e3cf000 7f8000) vs hw (0 ffffffffffffffff)
[    7.897664] fb0: switching to vc4 from simple
[    7.936909] [drm] Initialized v3d 1.0.0 20180419 for fec00000.v3d on minor 0
[    7.958358] Console: switching to colour dummy device 80x30
[    7.961252] vc4-drm gpu: bound fe600000.firmwarekms (ops vc4_fkms_ops [vc4])
[    8.072720] [drm] Initialized vc4 0.0.0 20140616 for gpu on minor 1
[    8.158025] Console: switching to colour frame buffer device 240x67
[    8.158087] vc4-drm gpu: [drm] fb0: vc4drmfb frame buffer device
[    8.361853] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[    8.427833] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[    8.442880] cfg80211: loaded regulatory.db is malformed or signature is missing/invalid
[    9.006185] brcmfmac: F1 signature read @0x18000000=0x15264345
[    9.088971] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    9.091065] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,4-model-b.bin failed with error -2
[    9.323838] usbcore: registered new interface driver brcmfmac
[    9.424991] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    9.425138] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    9.433100] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov  1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60
[    9.547231] uart-pl011 fe201000.serial: no DMA platform data
[    9.893170] 8021q: 802.1Q VLAN Support v1.8
[   10.018581] bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay)
[   10.020622] bcmgenet fd580000.ethernet eth0: Link is Down
[   10.204414] Adding 102396k swap on /var/swap.  Priority:-2 extents:1 across:102396k SSFS
[   10.778962] dvbdev: DVB: registering new adapter (CXD2880)
[   10.961239] cxd2880: cxd2880_attach: CXD2880 driver version: Ver 1.4.1 - 1.0.5
[   10.961272] cxd2880 spi0.0: DVB: registering adapter 0 frontend 0 (Sony CXD2880)...
[   11.047289] cxd2880_spi: cxd2880_spi_probe: Sony CXD2880 has successfully attached.
[   11.744448] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
[   15.956225] systemd-journald[139]: File /var/log/journal/13c7dcc8cae14fa8bf0df2919ba3a428/user-1000.journal corrupted or uncleanly shut down, renaming and replacing.
[   16.453269] Bluetooth: Core ver 2.22
[   16.453356] NET: Registered PF_BLUETOOTH protocol family
[   16.453363] Bluetooth: HCI device and connection manager initialized
[   16.453381] Bluetooth: HCI socket layer initialized
[   16.453390] Bluetooth: L2CAP socket layer initialized
[   16.453406] Bluetooth: SCO socket layer initialized
[   16.464838] Bluetooth: HCI UART driver ver 2.3
[   16.464856] Bluetooth: HCI UART protocol H4 registered
[   16.464929] Bluetooth: HCI UART protocol Three-wire (H5) registered
[   16.465084] Bluetooth: HCI UART protocol Broadcom registered
[   16.823135] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[   16.823151] Bluetooth: BNEP filters: protocol multicast
[   16.823166] Bluetooth: BNEP socket layer initialized
[   16.843147] NET: Registered PF_ALG protocol family
[   16.882559] cryptd: max_cpu_qlen set to 1000
[   17.009078] Bluetooth: RFCOMM TTY layer initialized
[   17.009104] Bluetooth: RFCOMM socket layer initialized
[   17.009128] Bluetooth: RFCOMM ver 1.11
[   17.121290] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   18.480919] ICMPv6: process `dhcpcd' is using deprecated sysctl (syscall) net.ipv6.neigh.wlan0.retrans_time - use net.ipv6.neigh.wlan0.retrans_time_ms instead
[   19.513693] ------------[ cut here ]------------
[   19.513717] WARNING: CPU: 0 PID: 641 at drivers/firmware/raspberrypi.c:67 rpi_firmware_property_list+0x1f8/0x268
[   19.513756] Firmware transaction timeout
[   19.513767] Modules linked in: rfcomm cmac algif_hash aes_arm_bs crypto_simd cryptd algif_skcipher af_alg bnep hci_uart btbcm bluetooth ecdh_generic ecc cxd2880 cxd2880_spi dvb_core 8021q spidev garp stp llc joydev brcmfmac brcmutil cfg80211 vc4 v3d rfkill gpu_sched cec bcm2835_isp(C) drm_kms_helper bcm2835_v4l2(C) bcm2835_codec(C) raspberrypi_hwmon bcm2835_mmal_vchiq(C) videobuf2_vmalloc snd_soc_core snd_bcm2835(C) rpivid_hevc(C) vc_sm_cma(C) spi_bcm2835 v4l2_mem2mem videobuf2_dma_contig videobuf2_memops videobuf2_v4l2 videobuf2_common snd_compress snd_pcm_dmaengine videodev snd_pcm mc snd_timer snd syscopyarea sysfillrect sysimgblt fb_sys_fops nvmem_rmem uio_pdrv_genirq uio drm i2c_dev fuse drm_panel_orientation_quirks backlight ip_tables x_tables ipv6
[   19.514573] CPU: 0 PID: 641 Comm: Xorg Tainted: G         C        5.15.61-v7l+ #1579
[   19.514589] Hardware name: BCM2711
[   19.514597] Backtrace:
[   19.514610] [<c0bd555c>] (dump_backtrace) from [<c0bd57a8>] (show_stack+0x20/0x24)
[   19.514640]  r7:00000043 r6:c0e3f314 r5:00000000 r4:60000013
[   19.514648] [<c0bd5788>] (show_stack) from [<c0bd9eb8>] (dump_stack_lvl+0x70/0x94)
[   19.514667] [<c0bd9e48>] (dump_stack_lvl) from [<c0bd9ef4>] (dump_stack+0x18/0x1c)
[   19.514689]  r7:00000043 r6:00000009 r5:c09f5de8 r4:c0eb9394
[   19.514696] [<c0bd9edc>] (dump_stack) from [<c02226c0>] (__warn+0xfc/0x114)
[   19.514719] [<c02225c4>] (__warn) from [<c0bd5e9c>] (warn_slowpath_fmt+0xa4/0xd8)
[   19.514739]  r7:00000043 r6:c0eb9394 r5:c1205048 r4:c0eb93b4
[   19.514746] [<c0bd5dfc>] (warn_slowpath_fmt) from [<c09f5de8>] (rpi_firmware_property_list+0x1f8/0x268)
[   19.514770]  r9:00000094 r8:c1205048 r7:c1695e80 r6:dac05000 r5:00001000 r4:00000010
[   19.514778] [<c09f5bf0>] (rpi_firmware_property_list) from [<bf38d448>] (vc4_fkms_get_edid_block+0x7c/0xb8 [vc4])
[   19.514940]  r10:00000000 r9:c275e800 r8:00000000 r7:00000080 r6:c42cb100 r5:c2a7c840
[   19.514948]  r4:c1205048
[   19.514956] [<bf38d3cc>] (vc4_fkms_get_edid_block [vc4]) from [<bf0e9acc>] (drm_do_get_edid+0x78/0x2a0 [drm])
[   19.515435]  r9:c275e800 r8:c2a7c840 r7:bf38d3cc r6:c2a7c840 r5:00000001 r4:c42cb100
[   19.515442] [<bf0e9a54>] (drm_do_get_edid [drm]) from [<bf38d640>] (vc4_fkms_connector_get_modes+0x94/0xcc [vc4])
[   19.515901]  r10:c2a7c894 r9:c275e800 r8:c2a7c840 r7:c275e8d8 r6:c2ddae80 r5:c2a7c840
[   19.515910]  r4:c1205048
[   19.515916] [<bf38d5ac>] (vc4_fkms_connector_get_modes [vc4]) from [<bf2e9b8c>] (drm_helper_probe_single_connector_modes+0x1c4/0x814 [drm_kms_helper])
[   19.516234]  r7:c275e8d8 r6:00000001 r5:c3c99e5c r4:bf30a01c
[   19.516240] [<bf2e99c8>] (drm_helper_probe_single_connector_modes [drm_kms_helper]) from [<bf0f8bbc>] (drm_mode_getconnector+0x30c/0x4ac [drm])
[   19.516744]  r10:c3c99e5c r9:c3b07e00 r8:00000000 r7:c275e8d8 r6:00000001 r5:c3c99e5c
[   19.516753]  r4:c2a7c854
[   19.516760] [<bf0f88b0>] (drm_mode_getconnector [drm]) from [<bf0dfce8>] (drm_ioctl_kernel+0xb8/0xfc [drm])
[   19.517403]  r10:c3c99e5c r9:bf0f88b0 r8:00000000 r7:00000000 r6:c3b07e00 r5:c275e800
[   19.517411]  r4:c1205048
[   19.517417] [<bf0dfc30>] (drm_ioctl_kernel [drm]) from [<bf0dff10>] (drm_ioctl+0x1e4/0x3bc [drm])
[   19.518056]  r10:00000050 r9:000000a7 r8:c3b07e00 r7:c05064a7 r6:bf0f88b0 r5:c1205048
[   19.518063]  r4:bf116b74
[   19.518070] [<bf0dfd2c>] (drm_ioctl [drm]) from [<c046f95c>] (sys_ioctl+0x12c/0xa20)
[   19.518403]  r10:0000000c r9:becf6920 r8:c3d10c00 r7:00000000 r6:c05064a7 r5:c1205048
[   19.518411]  r4:c05064a7
[   19.518417] [<c046f830>] (sys_ioctl) from [<c0200040>] (ret_fast_syscall+0x0/0x1c)
[   19.518436] Exception stack(0xc3c99fa8 to 0xc3c99ff0)
[   19.518449] 9fa0:                   00000001 becf6920 0000000c c05064a7 becf6920 becf6970
[   19.518462] 9fc0: 00000001 becf6920 c05064a7 00000036 0054ec18 0000000c becf6920 becf7c10
[   19.518472] 9fe0: b6d8c08c becf6904 b6d71c5c b69eed4c
[   19.518483]  r10:00000036 r9:c3c98000 r8:c0200244 r7:00000036 r6:c05064a7 r5:becf6920
[   19.518491]  r4:00000001
[   19.518499] ---[ end trace a1ee92975f42dd36 ]---
[   20.553717] raspberrypi-clk soc:firmware:clocks: Failed to change fw-clk-arm frequency: -110
[   21.593802] hwmon hwmon1: Failed to get throttled (-110)
[   23.683731] raspberrypi-clk soc:firmware:clocks: Failed to change fw-clk-arm frequency: -110
[   31.833710] cam-dummy-reg: disabling
[   32.473730] bcm2835_audio bcm2835_audio: vchi message timeout, msg=5
[  243.671778] INFO: task kworker/3:3:77 blocked for more than 122 seconds.
[  243.671816]       Tainted: G        WC        5.15.61-v7l+ #1579
[  243.671830] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  243.671844] task:kworker/3:3     state:D stack:    0 pid:   77 ppid:     2 flags:0x00000000
[  243.671877] Workqueue: events dbs_work_handler
[  243.671918] Backtrace:
[  243.671940] [<c0bdfb88>] (__schedule) from [<c0be06f0>] (schedule+0x7c/0x134)
[  243.671975]  r10:00000000 r9:c1724100 r8:00000002 r7:c174e000 r6:ffffe000 r5:c1724100
[  243.671989]  r4:ffffe000
[  243.672001] [<c0be0674>] (schedule) from [<c0be0b2c>] (schedule_preempt_disabled+0x18/0x1c)
[  243.672025]  r5:c174fbec r4:c1324818
[  243.672037] [<c0be0b14>] (schedule_preempt_disabled) from [<c0be23d8>] (__mutex_lock.constprop.9+0x354/0x618)
[  243.672061] [<c0be2084>] (__mutex_lock.constprop.9) from [<c0be27b8>] (__mutex_lock_slowpath+0x1c/0x20)
[  243.672087]  r10:00000000 r9:00000018 r8:c1205048 r7:c1695e80 r6:dac05000 r5:00001000
[  243.672100]  r4:c1324818
[  243.672112] [<c0be279c>] (__mutex_lock_slowpath) from [<c0be2818>] (mutex_lock+0x5c/0x60)
[  243.672133] [<c0be27bc>] (mutex_lock) from [<c09f5cc0>] (rpi_firmware_property_list+0xd0/0x268)
[  243.672162]  r5:00001000 r4:dac05024
[  243.672174] [<c09f5bf0>] (rpi_firmware_property_list) from [<c09f5ec8>] (rpi_firmware_property+0x70/0x118)
[  243.672205]  r10:c486a78c r9:00038002 r8:00000018 r7:c1695e80 r6:c174fce8 r5:0000000c
[  243.672218]  r4:c486a780
[  243.672230] [<c09f5e58>] (rpi_firmware_property) from [<c081e328>] (raspberrypi_clock_property+0x54/0x7c)
[  243.672259]  r10:23c34600 r9:ffffff92 r8:00000000 r7:c221bcc0 r6:00000000 r5:c174fd10
[  243.672272]  r4:c1205048 r3:0000000c
[  243.672284] [<c081e2d4>] (raspberrypi_clock_property) from [<c081e484>] (raspberrypi_fw_set_rate+0x4c/0xb8)
[  243.672305]  r5:c221bd40 r4:c1205048
[  243.672318] [<c081e438>] (raspberrypi_fw_set_rate) from [<c0815f14>] (clk_change_rate+0xf4/0x42c)
[  243.672343]  r7:23c34600 r6:00000000 r5:00000000 r4:c2088b40
[  243.672355] [<c0815e20>] (clk_change_rate) from [<c08163c0>] (clk_core_set_rate_nolock+0x174/0x19c)
[  243.672382]  r10:23c34600 r9:00000001 r8:c2088b40 r7:23c34600 r6:00000000 r5:c1205048
[  243.672395]  r4:c2088b40
[  243.672407] [<c081624c>] (clk_core_set_rate_nolock) from [<c0816428>] (clk_set_rate+0x40/0x98)
[  243.672433]  r8:efef2010 r7:c17cfb00 r6:c1205048 r5:23c34600 r4:c16ba000
[  243.672446] [<c08163e8>] (clk_set_rate) from [<c09c086c>] (_set_opp+0x25c/0x50c)
[  243.672473]  r5:c17cfa00 r4:c16c0e00
[  243.672485] [<c09c0610>] (_set_opp) from [<c09c0c28>] (dev_pm_opp_set_rate+0x10c/0x168)
[  243.672515]  r10:000927c0 r9:c17cfa00 r8:efef2010 r7:0000000a r6:23c34600 r5:c16c0e00
[  243.672528]  r4:c1205048
[  243.672540] [<c09c0b1c>] (dev_pm_opp_set_rate) from [<c09cad38>] (set_target+0x38/0x3c)
[  243.672569]  r9:00000000 r8:000c3500 r7:c16c1000 r6:c13c8028 r5:00000000 r4:c1205048
[  243.672581] [<c09cad00>] (set_target) from [<c09c5d98>] (__cpufreq_driver_target+0xf4/0x240)
[  243.672606] [<c09c5ca4>] (__cpufreq_driver_target) from [<c09c8dd0>] (od_dbs_update+0xc4/0x170)
[  243.672632]  r10:eff2d005 r9:c3b03700 r8:c3cbbc40 r7:c3b03f00 r6:c3b03700 r5:c3b03f00
[  243.672645]  r4:c16c1000
[  243.672658] [<c09c8d0c>] (od_dbs_update) from [<c09ca030>] (dbs_work_handler+0x3c/0x64)
[  243.672686]  r9:00000000 r8:c132279c r7:c16c1000 r6:c3b03f04 r5:00000000 r4:c3b03f38
[  243.672698] [<c09c9ff4>] (dbs_work_handler) from [<c0240108>] (process_one_work+0x250/0x57c)
[  243.672729]  r9:00000000 r8:eff2d000 r7:000000c0 r6:eff29bc0 r5:c2094c00 r4:c3b03f38
[  243.672742] [<c023feb8>] (process_one_work) from [<c0240494>] (worker_thread+0x60/0x5c4)
[  243.672771]  r10:eff29bc0 r9:c1203d00 r8:eff29bd8 r7:00000008 r6:eff29bc0 r5:c2094c18
[  243.672784]  r4:c2094c00
[  243.672796] [<c0240434>] (worker_thread) from [<c02487b4>] (kthread+0x178/0x194)
[  243.672825]  r10:c174e000 r9:c15abe74 r8:00000000 r7:c2094c00 r6:c0240434 r5:c221aac0
[  243.672837]  r4:c15c8300
[  243.672850] [<c024863c>] (kthread) from [<c02000d4>] (ret_from_fork+0x14/0x20)
[  243.672873] Exception stack(0xc174ffb0 to 0xc174fff8)
[  243.672890] ffa0:                                     00000000 00000000 00000000 00000000
[  243.672906] ffc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[  243.672922] ffe0: 00000000 00000000 00000000 00000000 00000013 00000000
[  243.672939]  r10:00000000 r9:00000000 r8:00000000 r7:00000000 r6:00000000 r5:c024863c
[  243.672952]  r4:c221aac0
[  243.672973] INFO: task kworker/1:2:107 blocked for more than 122 seconds.
[  243.672988]       Tainted: G        WC        5.15.61-v7l+ #1579
[  243.673002] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  243.673014] task:kworker/1:2     state:D stack:    0 pid:  107 ppid:     2 flags:0x00000000
[  243.673044] Workqueue: events get_values_poll [raspberrypi_hwmon]
[  243.673082] Backtrace:
[  243.673104] [<c0bdfb88>] (__schedule) from [<c0be06f0>] (schedule+0x7c/0x134)
[  243.673129]  r10:00000000 r9:c2859040 r8:00000002 r7:c256c000 r6:ffffe000 r5:c2859040
[  243.673142]  r4:ffffe000
[  243.673154] [<c0be0674>] (schedule) from [<c0be0b2c>] (schedule_preempt_disabled+0x18/0x1c)
[  243.673175]  r5:c256dddc r4:c1324818
[  243.673187] [<c0be0b14>] (schedule_preempt_disabled) from [<c0be23d8>] (__mutex_lock.constprop.9+0x354/0x618)
[  243.673208] [<c0be2084>] (__mutex_lock.constprop.9) from [<c0be27b8>] (__mutex_lock_slowpath+0x1c/0x20)
[  243.673234]  r10:00000000 r9:00000010 r8:c1205048 r7:c1695e80 r6:dac07000 r5:00001000
[  243.673247]  r4:c1324818
[  243.673258] [<c0be279c>] (__mutex_lock_slowpath) from [<c0be2818>] (mutex_lock+0x5c/0x60)
[  243.673280] [<c0be27bc>] (mutex_lock) from [<c09f5cc0>] (rpi_firmware_property_list+0xd0/0x268)
[  243.673305]  r5:00001000 r4:dac0701c
[  243.673317] [<c09f5bf0>] (rpi_firmware_property_list) from [<c09f5ec8>] (rpi_firmware_property+0x70/0x118)
[  243.673346]  r10:c173decc r9:00030046 r8:00000010 r7:c1695e80 r6:c256ded8 r5:00000004
[  243.673359]  r4:c173dec0
[  243.673371] [<c09f5e58>] (rpi_firmware_property) from [<bf27e0ac>] (get_values_poll+0x4c/0x11c [raspberrypi_hwmon])
[  243.673402]  r10:eff0d005 r9:00000000 r8:eff0d000 r7:00000040 r6:eff09bc0 r5:c386bd4c
[  243.673415]  r4:c1205048 r3:00000004
[  243.673428] [<bf27e060>] (get_values_poll [raspberrypi_hwmon]) from [<c0240108>] (process_one_work+0x250/0x57c)
[  243.673458]  r5:c15ceb80 r4:c386bd4c
[  243.673470] [<c023feb8>] (process_one_work) from [<c0240494>] (worker_thread+0x60/0x5c4)
[  243.673499]  r10:eff09bc0 r9:c1203d00 r8:eff09bd8 r7:00000008 r6:eff09bc0 r5:c15ceb98
[  243.673512]  r4:c15ceb80
[  243.673523] [<c0240434>] (worker_thread) from [<c02487b4>] (kthread+0x178/0x194)
[  243.673551]  r10:c256c000 r9:c1587e74 r8:00000000 r7:c15ceb80 r6:c0240434 r5:c283e040
[  243.673564]  r4:c2582080
[  243.673575] [<c024863c>] (kthread) from [<c02000d4>] (ret_from_fork+0x14/0x20)
[  243.673597] Exception stack(0xc256dfb0 to 0xc256dff8)
[  243.673613] dfa0:                                     00000000 00000000 00000000 00000000
[  243.673629] dfc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[  243.673644] dfe0: 00000000 00000000 00000000 00000000 00000013 00000000
[  243.673661]  r10:00000000 r9:00000000 r8:00000000 r7:00000000 r6:00000000 r5:c024863c
[  243.673674]  r4:c283e040
[  243.673705] INFO: task Xorg:641 blocked for more than 122 seconds.
[  243.673720]       Tainted: G        WC        5.15.61-v7l+ #1579
[  243.673734] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  243.673746] task:Xorg            state:D stack:    0 pid:  641 ppid:   603 flags:0x00000001
[  243.673773] Backtrace:
[  243.673795] [<c0bdfb88>] (__schedule) from [<c0be06f0>] (schedule+0x7c/0x134)
[  243.673818]  r10:00000000 r9:eff02540 r8:c1205048 r7:eff02540 r6:c1203d00 r5:c2701040
[  243.673831]  r4:ffffe000
[  243.673843] [<c0be0674>] (schedule) from [<c0be61c4>] (schedule_timeout+0x1d4/0x34c)
[  243.673865]  r5:c3c99a24 r4:00051388
[  243.673877] [<c0be5ff0>] (schedule_timeout) from [<c0be1424>] (wait_for_completion_timeout+0xc4/0x148)
[  243.673903]  r9:00000002 r8:ffffe000 r7:c166c350 r6:c1205048 r5:00057e40 r4:c166c34c
[  243.673915] [<c0be1360>] (wait_for_completion_timeout) from [<c0a260e8>] (mbox_send_message+0xb8/0x11c)
[  243.673942]  r9:00000094 r8:c1205048 r7:c3c99adc r6:c166c3b8 r5:00000004 r4:c166c340
[  243.673954] [<c0a26030>] (mbox_send_message) from [<c09f5cd0>] (rpi_firmware_property_list+0xe0/0x268)
[  243.673980]  r7:c1695e80 r6:dac06000 r5:00001000 r4:dac060a0
[  243.673992] [<c09f5bf0>] (rpi_firmware_property_list) from [<bf38d448>] (vc4_fkms_get_edid_block+0x7c/0xb8 [vc4])
[  243.674156]  r10:00000000 r9:c275e800 r8:00000000 r7:00000080 r6:c2cd3000 r5:c2a7c840
[  243.674169]  r4:c1205048
[  243.674180] [<bf38d3cc>] (vc4_fkms_get_edid_block [vc4]) from [<bf0e9acc>] (drm_do_get_edid+0x78/0x2a0 [drm])
[  243.674672]  r9:c275e800 r8:c2a7c840 r7:bf38d3cc r6:c2a7c840 r5:00000001 r4:c2cd3000
[  243.674684] [<bf0e9a54>] (drm_do_get_edid [drm]) from [<bf38d640>] (vc4_fkms_connector_get_modes+0x94/0xcc [vc4])
[  243.675150]  r10:c2a7c894 r9:c275e800 r8:c2a7c840 r7:c275e8d8 r6:c2ddae80 r5:c2a7c840
[  243.675163]  r4:c1205048
[  243.675174] [<bf38d5ac>] (vc4_fkms_connector_get_modes [vc4]) from [<bf2e9b8c>] (drm_helper_probe_single_connector_modes+0x1c4/0x814 [drm_kms_helper])
[  243.675497]  r7:c275e8d8 r6:00000001 r5:c3c99e5c r4:bf30a01c
[  243.675510] [<bf2e99c8>] (drm_helper_probe_single_connector_modes [drm_kms_helper]) from [<bf0f8bbc>] (drm_mode_getconnector+0x30c/0x4ac [drm])
[  243.676022]  r10:c3c99e5c r9:c3b07e00 r8:00000000 r7:c275e8d8 r6:00000001 r5:c3c99e5c
[  243.676035]  r4:c2a7c854
[  243.676047] [<bf0f88b0>] (drm_mode_getconnector [drm]) from [<bf0dfce8>] (drm_ioctl_kernel+0xb8/0xfc [drm])
[  243.676697]  r10:c3c99e5c r9:bf0f88b0 r8:00000000 r7:00000000 r6:c3b07e00 r5:c275e800
[  243.676709]  r4:c1205048
[  243.676721] [<bf0dfc30>] (drm_ioctl_kernel [drm]) from [<bf0dff10>] (drm_ioctl+0x1e4/0x3bc [drm])
[  243.677374]  r10:00000050 r9:000000a7 r8:c3b07e00 r7:c05064a7 r6:bf0f88b0 r5:c1205048
[  243.677386]  r4:bf116b74
[  243.677399] [<bf0dfd2c>] (drm_ioctl [drm]) from [<c046f95c>] (sys_ioctl+0x12c/0xa20)
[  243.677739]  r10:0000000c r9:becf78f0 r8:c3d10c00 r7:00000000 r6:c05064a7 r5:c1205048
[  243.677751]  r4:c05064a7
[  243.677763] [<c046f830>] (sys_ioctl) from [<c0200040>] (ret_fast_syscall+0x0/0x1c)
[  243.677786] Exception stack(0xc3c99fa8 to 0xc3c99ff0)
[  243.677803] 9fa0:                   00000001 becf78f0 0000000c c05064a7 becf78f0 becf7940
[  243.677820] 9fc0: 00000001 becf78f0 c05064a7 00000036 0054ec18 0000000c becf78f0 0020f100
[  243.677835] 9fe0: b6d8c08c becf78d4 b6d71c5c b69eed4c
[  243.677852]  r10:00000036 r9:c3c98000 r8:c0200244 r7:00000036 r6:c05064a7 r5:becf78f0
[  243.677865]  r4:00000001
[  366.550743] INFO: task kworker/3:3:77 blocked for more than 245 seconds.
[  366.550780]       Tainted: G        WC        5.15.61-v7l+ #1579
[  366.550795] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  366.550808] task:kworker/3:3     state:D stack:    0 pid:   77 ppid:     2 flags:0x00000000
[  366.550839] Workqueue: events dbs_work_handler
[  366.550880] Backtrace:
[  366.550902] [<c0bdfb88>] (__schedule) from [<c0be06f0>] (schedule+0x7c/0x134)
[  366.550937]  r10:00000000 r9:c1724100 r8:00000002 r7:c174e000 r6:ffffe000 r5:c1724100
[  366.550952]  r4:ffffe000
[  366.550963] [<c0be0674>] (schedule) from [<c0be0b2c>] (schedule_preempt_disabled+0x18/0x1c)
[  366.550987]  r5:c174fbec r4:c1324818
[  366.550999] [<c0be0b14>] (schedule_preempt_disabled) from [<c0be23d8>] (__mutex_lock.constprop.9+0x354/0x618)
[  366.551022] [<c0be2084>] (__mutex_lock.constprop.9) from [<c0be27b8>] (__mutex_lock_slowpath+0x1c/0x20)
[  366.551048]  r10:00000000 r9:00000018 r8:c1205048 r7:c1695e80 r6:dac05000 r5:00001000
[  366.551060]  r4:c1324818
[  366.551072] [<c0be279c>] (__mutex_lock_slowpath) from [<c0be2818>] (mutex_lock+0x5c/0x60)
[  366.551093] [<c0be27bc>] (mutex_lock) from [<c09f5cc0>] (rpi_firmware_property_list+0xd0/0x268)
[  366.551121]  r5:00001000 r4:dac05024
[  366.551133] [<c09f5bf0>] (rpi_firmware_property_list) from [<c09f5ec8>] (rpi_firmware_property+0x70/0x118)
[  366.551162]  r10:c486a78c r9:00038002 r8:00000018 r7:c1695e80 r6:c174fce8 r5:0000000c
[  366.551175]  r4:c486a780
[  366.551187] [<c09f5e58>] (rpi_firmware_property) from [<c081e328>] (raspberrypi_clock_property+0x54/0x7c)
[  366.551216]  r10:23c34600 r9:ffffff92 r8:00000000 r7:c221bcc0 r6:00000000 r5:c174fd10
[  366.551230]  r4:c1205048 r3:0000000c
[  366.551242] [<c081e2d4>] (raspberrypi_clock_property) from [<c081e484>] (raspberrypi_fw_set_rate+0x4c/0xb8)
[  366.551264]  r5:c221bd40 r4:c1205048
[  366.551276] [<c081e438>] (raspberrypi_fw_set_rate) from [<c0815f14>] (clk_change_rate+0xf4/0x42c)
[  366.551301]  r7:23c34600 r6:00000000 r5:00000000 r4:c2088b40
[  366.551313] [<c0815e20>] (clk_change_rate) from [<c08163c0>] (clk_core_set_rate_nolock+0x174/0x19c)
[  366.551340]  r10:23c34600 r9:00000001 r8:c2088b40 r7:23c34600 r6:00000000 r5:c1205048
[  366.551352]  r4:c2088b40
[  366.551364] [<c081624c>] (clk_core_set_rate_nolock) from [<c0816428>] (clk_set_rate+0x40/0x98)
[  366.551391]  r8:efef2010 r7:c17cfb00 r6:c1205048 r5:23c34600 r4:c16ba000
[  366.551403] [<c08163e8>] (clk_set_rate) from [<c09c086c>] (_set_opp+0x25c/0x50c)
[  366.551430]  r5:c17cfa00 r4:c16c0e00
[  366.551443] [<c09c0610>] (_set_opp) from [<c09c0c28>] (dev_pm_opp_set_rate+0x10c/0x168)
[  366.551472]  r10:000927c0 r9:c17cfa00 r8:efef2010 r7:0000000a r6:23c34600 r5:c16c0e00
[  366.551485]  r4:c1205048
[  366.551497] [<c09c0b1c>] (dev_pm_opp_set_rate) from [<c09cad38>] (set_target+0x38/0x3c)
[  366.551526]  r9:00000000 r8:000c3500 r7:c16c1000 r6:c13c8028 r5:00000000 r4:c1205048
[  366.551538] [<c09cad00>] (set_target) from [<c09c5d98>] (__cpufreq_driver_target+0xf4/0x240)
[  366.551562] [<c09c5ca4>] (__cpufreq_driver_target) from [<c09c8dd0>] (od_dbs_update+0xc4/0x170)
[  366.551589]  r10:eff2d005 r9:c3b03700 r8:c3cbbc40 r7:c3b03f00 r6:c3b03700 r5:c3b03f00
[  366.551601]  r4:c16c1000
[  366.551613] [<c09c8d0c>] (od_dbs_update) from [<c09ca030>] (dbs_work_handler+0x3c/0x64)
[  366.551640]  r9:00000000 r8:c132279c r7:c16c1000 r6:c3b03f04 r5:00000000 r4:c3b03f38
[  366.551653] [<c09c9ff4>] (dbs_work_handler) from [<c0240108>] (process_one_work+0x250/0x57c)
[  366.551685]  r9:00000000 r8:eff2d000 r7:000000c0 r6:eff29bc0 r5:c2094c00 r4:c3b03f38
[  366.551697] [<c023feb8>] (process_one_work) from [<c0240494>] (worker_thread+0x60/0x5c4)
[  366.551726]  r10:eff29bc0 r9:c1203d00 r8:eff29bd8 r7:00000008 r6:eff29bc0 r5:c2094c18
[  366.551738]  r4:c2094c00
[  366.551750] [<c0240434>] (worker_thread) from [<c02487b4>] (kthread+0x178/0x194)
[  366.551778]  r10:c174e000 r9:c15abe74 r8:00000000 r7:c2094c00 r6:c0240434 r5:c221aac0
[  366.551791]  r4:c15c8300
[  366.551803] [<c024863c>] (kthread) from [<c02000d4>] (ret_from_fork+0x14/0x20)
[  366.551827] Exception stack(0xc174ffb0 to 0xc174fff8)
[  366.551844] ffa0:                                     00000000 00000000 00000000 00000000
[  366.551860] ffc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[  366.551875] ffe0: 00000000 00000000 00000000 00000000 00000013 00000000
[  366.551892]  r10:00000000 r9:00000000 r8:00000000 r7:00000000 r6:00000000 r5:c024863c
[  366.551905]  r4:c221aac0
[  366.551924] INFO: task kworker/1:2:107 blocked for more than 245 seconds.
[  366.551939]       Tainted: G        WC        5.15.61-v7l+ #1579
[  366.551953] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  366.551965] task:kworker/1:2     state:D stack:    0 pid:  107 ppid:     2 flags:0x00000000
[  366.551994] Workqueue: events get_values_poll [raspberrypi_hwmon]
[  366.552035] Backtrace:
[  366.552057] [<c0bdfb88>] (__schedule) from [<c0be06f0>] (schedule+0x7c/0x134)
[  366.552082]  r10:00000000 r9:c2859040 r8:00000002 r7:c256c000 r6:ffffe000 r5:c2859040
[  366.552096]  r4:ffffe000
[  366.552107] [<c0be0674>] (schedule) from [<c0be0b2c>] (schedule_preempt_disabled+0x18/0x1c)
[  366.552128]  r5:c256dddc r4:c1324818
[  366.552140] [<c0be0b14>] (schedule_preempt_disabled) from [<c0be23d8>] (__mutex_lock.constprop.9+0x354/0x618)
[  366.552161] [<c0be2084>] (__mutex_lock.constprop.9) from [<c0be27b8>] (__mutex_lock_slowpath+0x1c/0x20)
[  366.552187]  r10:00000000 r9:00000010 r8:c1205048 r7:c1695e80 r6:dac07000 r5:00001000
[  366.552199]  r4:c1324818
[  366.552211] [<c0be279c>] (__mutex_lock_slowpath) from [<c0be2818>] (mutex_lock+0x5c/0x60)
[  366.552232] [<c0be27bc>] (mutex_lock) from [<c09f5cc0>] (rpi_firmware_property_list+0xd0/0x268)
[  366.552257]  r5:00001000 r4:dac0701c
[  366.552269] [<c09f5bf0>] (rpi_firmware_property_list) from [<c09f5ec8>] (rpi_firmware_property+0x70/0x118)
[  366.552297]  r10:c173decc r9:00030046 r8:00000010 r7:c1695e80 r6:c256ded8 r5:00000004
[  366.552310]  r4:c173dec0
[  366.552322] [<c09f5e58>] (rpi_firmware_property) from [<bf27e0ac>] (get_values_poll+0x4c/0x11c [raspberrypi_hwmon])
[  366.552353]  r10:eff0d005 r9:00000000 r8:eff0d000 r7:00000040 r6:eff09bc0 r5:c386bd4c
[  366.552367]  r4:c1205048 r3:00000004
[  366.552378] [<bf27e060>] (get_values_poll [raspberrypi_hwmon]) from [<c0240108>] (process_one_work+0x250/0x57c)
[  366.552409]  r5:c15ceb80 r4:c386bd4c
[  366.552420] [<c023feb8>] (process_one_work) from [<c0240494>] (worker_thread+0x60/0x5c4)
[  366.552449]  r10:eff09bc0 r9:c1203d00 r8:eff09bd8 r7:00000008 r6:eff09bc0 r5:c15ceb98
[  366.552461]  r4:c15ceb80
[  366.552474] [<c0240434>] (worker_thread) from [<c02487b4>] (kthread+0x178/0x194)
[  366.552501]  r10:c256c000 r9:c1587e74 r8:00000000 r7:c15ceb80 r6:c0240434 r5:c283e040
[  366.552514]  r4:c2582080
[  366.552525] [<c024863c>] (kthread) from [<c02000d4>] (ret_from_fork+0x14/0x20)
[  366.552546] Exception stack(0xc256dfb0 to 0xc256dff8)
[  366.552562] dfa0:                                     00000000 00000000 00000000 00000000
[  366.552579] dfc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[  366.552594] dfe0: 00000000 00000000 00000000 00000000 00000013 00000000
[  366.552611]  r10:00000000 r9:00000000 r8:00000000 r7:00000000 r6:00000000 r5:c024863c
[  366.552624]  r4:c283e040
[  366.552655] INFO: task Xorg:641 blocked for more than 245 seconds.
[  366.552670]       Tainted: G        WC        5.15.61-v7l+ #1579
[  366.552684] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  366.552696] task:Xorg            state:D stack:    0 pid:  641 ppid:   603 flags:0x00000001
[  366.552723] Backtrace:
[  366.552745] [<c0bdfb88>] (__schedule) from [<c0be06f0>] (schedule+0x7c/0x134)
[  366.552769]  r10:00000000 r9:eff02540 r8:c1205048 r7:eff02540 r6:c1203d00 r5:c2701040
[  366.552782]  r4:ffffe000
[  366.552794] [<c0be0674>] (schedule) from [<c0be61c4>] (schedule_timeout+0x1d4/0x34c)
[  366.552816]  r5:c3c99a24 r4:00051388
[  366.552828] [<c0be5ff0>] (schedule_timeout) from [<c0be1424>] (wait_for_completion_timeout+0xc4/0x148)
[  366.552854]  r9:00000002 r8:ffffe000 r7:c166c350 r6:c1205048 r5:00057e40 r4:c166c34c
[  366.552866] [<c0be1360>] (wait_for_completion_timeout) from [<c0a260e8>] (mbox_send_message+0xb8/0x11c)
[  366.552892]  r9:00000094 r8:c1205048 r7:c3c99adc r6:c166c3b8 r5:00000004 r4:c166c340
[  366.552905] [<c0a26030>] (mbox_send_message) from [<c09f5cd0>] (rpi_firmware_property_list+0xe0/0x268)
[  366.552931]  r7:c1695e80 r6:dac06000 r5:00001000 r4:dac060a0
[  366.552943] [<c09f5bf0>] (rpi_firmware_property_list) from [<bf38d448>] (vc4_fkms_get_edid_block+0x7c/0xb8 [vc4])
[  366.553106]  r10:00000000 r9:c275e800 r8:00000000 r7:00000080 r6:c2cd3000 r5:c2a7c840
[  366.553119]  r4:c1205048
[  366.553132] [<bf38d3cc>] (vc4_fkms_get_edid_block [vc4]) from [<bf0e9acc>] (drm_do_get_edid+0x78/0x2a0 [drm])
[  366.553616]  r9:c275e800 r8:c2a7c840 r7:bf38d3cc r6:c2a7c840 r5:00000001 r4:c2cd3000
[  366.553628] [<bf0e9a54>] (drm_do_get_edid [drm]) from [<bf38d640>] (vc4_fkms_connector_get_modes+0x94/0xcc [vc4])
[  366.554094]  r10:c2a7c894 r9:c275e800 r8:c2a7c840 r7:c275e8d8 r6:c2ddae80 r5:c2a7c840
[  366.554107]  r4:c1205048
[  366.554119] [<bf38d5ac>] (vc4_fkms_connector_get_modes [vc4]) from [<bf2e9b8c>] (drm_helper_probe_single_connector_modes+0x1c4/0x814 [drm_kms_helper])
[  366.554441]  r7:c275e8d8 r6:00000001 r5:c3c99e5c r4:bf30a01c
[  366.554454] [<bf2e99c8>] (drm_helper_probe_single_connector_modes [drm_kms_helper]) from [<bf0f8bbc>] (drm_mode_getconnector+0x30c/0x4ac [drm])
[  366.554965]  r10:c3c99e5c r9:c3b07e00 r8:00000000 r7:c275e8d8 r6:00000001 r5:c3c99e5c
[  366.554978]  r4:c2a7c854
[  366.554990] [<bf0f88b0>] (drm_mode_getconnector [drm]) from [<bf0dfce8>] (drm_ioctl_kernel+0xb8/0xfc [drm])
[  366.555639]  r10:c3c99e5c r9:bf0f88b0 r8:00000000 r7:00000000 r6:c3b07e00 r5:c275e800
[  366.555652]  r4:c1205048
[  366.555665] [<bf0dfc30>] (drm_ioctl_kernel [drm]) from [<bf0dff10>] (drm_ioctl+0x1e4/0x3bc [drm])
[  366.556317]  r10:00000050 r9:000000a7 r8:c3b07e00 r7:c05064a7 r6:bf0f88b0 r5:c1205048
[  366.556330]  r4:bf116b74
[  366.556341] [<bf0dfd2c>] (drm_ioctl [drm]) from [<c046f95c>] (sys_ioctl+0x12c/0xa20)
[  366.556677]  r10:0000000c r9:becf78f0 r8:c3d10c00 r7:00000000 r6:c05064a7 r5:c1205048
[  366.556690]  r4:c05064a7
[  366.556702] [<c046f830>] (sys_ioctl) from [<c0200040>] (ret_fast_syscall+0x0/0x1c)
[  366.556723] Exception stack(0xc3c99fa8 to 0xc3c99ff0)
[  366.556740] 9fa0:                   00000001 becf78f0 0000000c c05064a7 becf78f0 becf7940
[  366.556757] 9fc0: 00000001 becf78f0 c05064a7 00000036 0054ec18 0000000c becf78f0 0020f100
[  366.556772] 9fe0: b6d8c08c becf78d4 b6d71c5c b69eed4c
[  366.556789]  r10:00000036 r9:c3c98000 r8:c0200244 r7:00000036 r6:c05064a7 r5:becf78f0
[  366.556801]  r4:00000001
[  489.429774] INFO: task kworker/3:3:77 blocked for more than 368 seconds.
[  489.429809]       Tainted: G        WC        5.15.61-v7l+ #1579
[  489.429825] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  489.429839] task:kworker/3:3     state:D stack:    0 pid:   77 ppid:     2 flags:0x00000000
[  489.429873] Workqueue: events dbs_work_handler
[  489.429913] Backtrace:
[  489.429936] [<c0bdfb88>] (__schedule) from [<c0be06f0>] (schedule+0x7c/0x134)
[  489.429970]  r10:00000000 r9:c1724100 r8:00000002 r7:c174e000 r6:ffffe000 r5:c1724100
[  489.429984]  r4:ffffe000
[  489.429996] [<c0be0674>] (schedule) from [<c0be0b2c>] (schedule_preempt_disabled+0x18/0x1c)
[  489.430021]  r5:c174fbec r4:c1324818
[  489.430032] [<c0be0b14>] (schedule_preempt_disabled) from [<c0be23d8>] (__mutex_lock.constprop.9+0x354/0x618)
[  489.430056] [<c0be2084>] (__mutex_lock.constprop.9) from [<c0be27b8>] (__mutex_lock_slowpath+0x1c/0x20)
[  489.430083]  r10:00000000 r9:00000018 r8:c1205048 r7:c1695e80 r6:dac05000 r5:00001000
[  489.430096]  r4:c1324818
[  489.430107] [<c0be279c>] (__mutex_lock_slowpath) from [<c0be2818>] (mutex_lock+0x5c/0x60)
[  489.430129] [<c0be27bc>] (mutex_lock) from [<c09f5cc0>] (rpi_firmware_property_list+0xd0/0x268)
[  489.430156]  r5:00001000 r4:dac05024
[  489.430169] [<c09f5bf0>] (rpi_firmware_property_list) from [<c09f5ec8>] (rpi_firmware_property+0x70/0x118)
[  489.430199]  r10:c486a78c r9:00038002 r8:00000018 r7:c1695e80 r6:c174fce8 r5:0000000c
[  489.430212]  r4:c486a780
[  489.430223] [<c09f5e58>] (rpi_firmware_property) from [<c081e328>] (raspberrypi_clock_property+0x54/0x7c)
[  489.430254]  r10:23c34600 r9:ffffff92 r8:00000000 r7:c221bcc0 r6:00000000 r5:c174fd10
[  489.430268]  r4:c1205048 r3:0000000c
[  489.430280] [<c081e2d4>] (raspberrypi_clock_property) from [<c081e484>] (raspberrypi_fw_set_rate+0x4c/0xb8)
[  489.430302]  r5:c221bd40 r4:c1205048
[  489.430314] [<c081e438>] (raspberrypi_fw_set_rate) from [<c0815f14>] (clk_change_rate+0xf4/0x42c)
[  489.430339]  r7:23c34600 r6:00000000 r5:00000000 r4:c2088b40
[  489.430351] [<c0815e20>] (clk_change_rate) from [<c08163c0>] (clk_core_set_rate_nolock+0x174/0x19c)
[  489.430380]  r10:23c34600 r9:00000001 r8:c2088b40 r7:23c34600 r6:00000000 r5:c1205048
[  489.430392]  r4:c2088b40
[  489.430405] [<c081624c>] (clk_core_set_rate_nolock) from [<c0816428>] (clk_set_rate+0x40/0x98)
[  489.430432]  r8:efef2010 r7:c17cfb00 r6:c1205048 r5:23c34600 r4:c16ba000
[  489.430445] [<c08163e8>] (clk_set_rate) from [<c09c086c>] (_set_opp+0x25c/0x50c)
[  489.430473]  r5:c17cfa00 r4:c16c0e00
[  489.430485] [<c09c0610>] (_set_opp) from [<c09c0c28>] (dev_pm_opp_set_rate+0x10c/0x168)
[  489.430515]  r10:000927c0 r9:c17cfa00 r8:efef2010 r7:0000000a r6:23c34600 r5:c16c0e00
[  489.430528]  r4:c1205048
[  489.430539] [<c09c0b1c>] (dev_pm_opp_set_rate) from [<c09cad38>] (set_target+0x38/0x3c)
[  489.430569]  r9:00000000 r8:000c3500 r7:c16c1000 r6:c13c8028 r5:00000000 r4:c1205048
[  489.430582] [<c09cad00>] (set_target) from [<c09c5d98>] (__cpufreq_driver_target+0xf4/0x240)
[  489.430606] [<c09c5ca4>] (__cpufreq_driver_target) from [<c09c8dd0>] (od_dbs_update+0xc4/0x170)
[  489.430633]  r10:eff2d005 r9:c3b03700 r8:c3cbbc40 r7:c3b03f00 r6:c3b03700 r5:c3b03f00
[  489.430645]  r4:c16c1000
[  489.430658] [<c09c8d0c>] (od_dbs_update) from [<c09ca030>] (dbs_work_handler+0x3c/0x64)
[  489.430686]  r9:00000000 r8:c132279c r7:c16c1000 r6:c3b03f04 r5:00000000 r4:c3b03f38
[  489.430698] [<c09c9ff4>] (dbs_work_handler) from [<c0240108>] (process_one_work+0x250/0x57c)
[  489.430729]  r9:00000000 r8:eff2d000 r7:000000c0 r6:eff29bc0 r5:c2094c00 r4:c3b03f38
[  489.430741] [<c023feb8>] (process_one_work) from [<c0240494>] (worker_thread+0x60/0x5c4)
[  489.430770]  r10:eff29bc0 r9:c1203d00 r8:eff29bd8 r7:00000008 r6:eff29bc0 r5:c2094c18
[  489.430783]  r4:c2094c00
[  489.430795] [<c0240434>] (worker_thread) from [<c02487b4>] (kthread+0x178/0x194)
[  489.430825]  r10:c174e000 r9:c15abe74 r8:00000000 r7:c2094c00 r6:c0240434 r5:c221aac0
[  489.430837]  r4:c15c8300
[  489.430850] [<c024863c>] (kthread) from [<c02000d4>] (ret_from_fork+0x14/0x20)
[  489.430873] Exception stack(0xc174ffb0 to 0xc174fff8)
[  489.430889] ffa0:                                     00000000 00000000 00000000 00000000
[  489.430907] ffc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[  489.430922] ffe0: 00000000 00000000 00000000 00000000 00000013 00000000
[  489.430939]  r10:00000000 r9:00000000 r8:00000000 r7:00000000 r6:00000000 r5:c024863c
[  489.430952]  r4:c221aac0
[  489.430973] INFO: task kworker/1:2:107 blocked for more than 368 seconds.
[  489.430988]       Tainted: G        WC        5.15.61-v7l+ #1579
[  489.431002] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  489.431013] task:kworker/1:2     state:D stack:    0 pid:  107 ppid:     2 flags:0x00000000
[  489.431043] Workqueue: events get_values_poll [raspberrypi_hwmon]
[  489.431080] Backtrace:
[  489.431102] [<c0bdfb88>] (__schedule) from [<c0be06f0>] (schedule+0x7c/0x134)
[  489.431127]  r10:00000000 r9:c2859040 r8:00000002 r7:c256c000 r6:ffffe000 r5:c2859040
[  489.431141]  r4:ffffe000
[  489.431152] [<c0be0674>] (schedule) from [<c0be0b2c>] (schedule_preempt_disabled+0x18/0x1c)
[  489.431173]  r5:c256dddc r4:c1324818
[  489.431185] [<c0be0b14>] (schedule_preempt_disabled) from [<c0be23d8>] (__mutex_lock.constprop.9+0x354/0x618)
[  489.431207] [<c0be2084>] (__mutex_lock.constprop.9) from [<c0be27b8>] (__mutex_lock_slowpath+0x1c/0x20)
[  489.431232]  r10:00000000 r9:00000010 r8:c1205048 r7:c1695e80 r6:dac07000 r5:00001000
[  489.431245]  r4:c1324818
[  489.431256] [<c0be279c>] (__mutex_lock_slowpath) from [<c0be2818>] (mutex_lock+0x5c/0x60)
[  489.431279] [<c0be27bc>] (mutex_lock) from [<c09f5cc0>] (rpi_firmware_property_list+0xd0/0x268)
[  489.431304]  r5:00001000 r4:dac0701c
[  489.431316] [<c09f5bf0>] (rpi_firmware_property_list) from [<c09f5ec8>] (rpi_firmware_property+0x70/0x118)
[  489.431345]  r10:c173decc r9:00030046 r8:00000010 r7:c1695e80 r6:c256ded8 r5:00000004
[  489.431358]  r4:c173dec0
[  489.431370] [<c09f5e58>] (rpi_firmware_property) from [<bf27e0ac>] (get_values_poll+0x4c/0x11c [raspberrypi_hwmon])
[  489.431402]  r10:eff0d005 r9:00000000 r8:eff0d000 r7:00000040 r6:eff09bc0 r5:c386bd4c
[  489.431415]  r4:c1205048 r3:00000004
[  489.431428] [<bf27e060>] (get_values_poll [raspberrypi_hwmon]) from [<c0240108>] (process_one_work+0x250/0x57c)
[  489.431457]  r5:c15ceb80 r4:c386bd4c
[  489.431469] [<c023feb8>] (process_one_work) from [<c0240494>] (worker_thread+0x60/0x5c4)
[  489.431498]  r10:eff09bc0 r9:c1203d00 r8:eff09bd8 r7:00000008 r6:eff09bc0 r5:c15ceb98
[  489.431512]  r4:c15ceb80
[  489.431524] [<c0240434>] (worker_thread) from [<c02487b4>] (kthread+0x178/0x194)
[  489.431552]  r10:c256c000 r9:c1587e74 r8:00000000 r7:c15ceb80 r6:c0240434 r5:c283e040
[  489.431565]  r4:c2582080
[  489.431576] [<c024863c>] (kthread) from [<c02000d4>] (ret_from_fork+0x14/0x20)
[  489.431599] Exception stack(0xc256dfb0 to 0xc256dff8)
[  489.431615] dfa0:                                     00000000 00000000 00000000 00000000
[  489.431632] dfc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[  489.431648] dfe0: 00000000 00000000 00000000 00000000 00000013 00000000
[  489.431664]  r10:00000000 r9:00000000 r8:00000000 r7:00000000 r6:00000000 r5:c024863c
[  489.431677]  r4:c283e040
[  489.431708] INFO: task Xorg:641 blocked for more than 368 seconds.
[  489.431723]       Tainted: G        WC        5.15.61-v7l+ #1579
[  489.431736] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  489.431748] task:Xorg            state:D stack:    0 pid:  641 ppid:   603 flags:0x00000001
[  489.431776] Backtrace:
[  489.431798] [<c0bdfb88>] (__schedule) from [<c0be06f0>] (schedule+0x7c/0x134)
[  489.431823]  r10:00000000 r9:eff02540 r8:c1205048 r7:eff02540 r6:c1203d00 r5:c2701040
[  489.431836]  r4:ffffe000
[  489.431847] [<c0be0674>] (schedule) from [<c0be61c4>] (schedule_timeout+0x1d4/0x34c)
[  489.431871]  r5:c3c99a24 r4:00051388
[  489.431883] [<c0be5ff0>] (schedule_timeout) from [<c0be1424>] (wait_for_completion_timeout+0xc4/0x148)
[  489.431909]  r9:00000002 r8:ffffe000 r7:c166c350 r6:c1205048 r5:00057e40 r4:c166c34c
[  489.431922] [<c0be1360>] (wait_for_completion_timeout) from [<c0a260e8>] (mbox_send_message+0xb8/0x11c)
[  489.431949]  r9:00000094 r8:c1205048 r7:c3c99adc r6:c166c3b8 r5:00000004 r4:c166c340
[  489.431962] [<c0a26030>] (mbox_send_message) from [<c09f5cd0>] (rpi_firmware_property_list+0xe0/0x268)
[  489.431988]  r7:c1695e80 r6:dac06000 r5:00001000 r4:dac060a0
[  489.432000] [<c09f5bf0>] (rpi_firmware_property_list) from [<bf38d448>] (vc4_fkms_get_edid_block+0x7c/0xb8 [vc4])
[  489.432164]  r10:00000000 r9:c275e800 r8:00000000 r7:00000080 r6:c2cd3000 r5:c2a7c840
[  489.432177]  r4:c1205048
[  489.432189] [<bf38d3cc>] (vc4_fkms_get_edid_block [vc4]) from [<bf0e9acc>] (drm_do_get_edid+0x78/0x2a0 [drm])
[  489.432672]  r9:c275e800 r8:c2a7c840 r7:bf38d3cc r6:c2a7c840 r5:00000001 r4:c2cd3000
[  489.432687] [<bf0e9a54>] (drm_do_get_edid [drm]) from [<bf38d640>] (vc4_fkms_connector_get_modes+0x94/0xcc [vc4])
[  489.433153]  r10:c2a7c894 r9:c275e800 r8:c2a7c840 r7:c275e8d8 r6:c2ddae80 r5:c2a7c840
[  489.433166]  r4:c1205048
[  489.433178] [<bf38d5ac>] (vc4_fkms_connector_get_modes [vc4]) from [<bf2e9b8c>] (drm_helper_probe_single_connector_modes+0x1c4/0x814 [drm_kms_helper])
[  489.433498]  r7:c275e8d8 r6:00000001 r5:c3c99e5c r4:bf30a01c
[  489.433510] [<bf2e99c8>] (drm_helper_probe_single_connector_modes [drm_kms_helper]) from [<bf0f8bbc>] (drm_mode_getconnector+0x30c/0x4ac [drm])
[  489.434018]  r10:c3c99e5c r9:c3b07e00 r8:00000000 r7:c275e8d8 r6:00000001 r5:c3c99e5c
[  489.434031]  r4:c2a7c854
[  489.434043] [<bf0f88b0>] (drm_mode_getconnector [drm]) from [<bf0dfce8>] (drm_ioctl_kernel+0xb8/0xfc [drm])
[  489.434694]  r10:c3c99e5c r9:bf0f88b0 r8:00000000 r7:00000000 r6:c3b07e00 r5:c275e800
[  489.434706]  r4:c1205048
[  489.434718] [<bf0dfc30>] (drm_ioctl_kernel [drm]) from [<bf0dff10>] (drm_ioctl+0x1e4/0x3bc [drm])
[  489.435369]  r10:00000050 r9:000000a7 r8:c3b07e00 r7:c05064a7 r6:bf0f88b0 r5:c1205048
[  489.435382]  r4:bf116b74
[  489.435394] [<bf0dfd2c>] (drm_ioctl [drm]) from [<c046f95c>] (sys_ioctl+0x12c/0xa20)
[  489.435732]  r10:0000000c r9:becf78f0 r8:c3d10c00 r7:00000000 r6:c05064a7 r5:c1205048
[  489.435745]  r4:c05064a7
[  489.435756] [<c046f830>] (sys_ioctl) from [<c0200040>] (ret_fast_syscall+0x0/0x1c)
[  489.435778] Exception stack(0xc3c99fa8 to 0xc3c99ff0)
[  489.435794] 9fa0:                   00000001 becf78f0 0000000c c05064a7 becf78f0 becf7940
[  489.435812] 9fc0: 00000001 becf78f0 c05064a7 00000036 0054ec18 0000000c becf78f0 0020f100
[  489.435827] 9fe0: b6d8c08c becf78d4 b6d71c5c b69eed4c
[  489.435844]  r10:00000036 r9:c3c98000 r8:c0200244 r7:00000036 r6:c05064a7 r5:becf78f0
[  489.435857]  r4:00000001

I'll post another dmesg using kms soon

giovariot avatar Sep 20 '22 22:09 giovariot

Here is using vc4-kms-v3d

pi@raspberrypi:~ $ dmesg
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 5.15.61-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) #1579 SMP Fri Aug 26 11:13:03 BST 2022
[    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.1
[    0.000000] random: crng init done
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] Reserved memory: created CMA memory pool at 0x000000001ac00000, size 320 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] percpu: Embedded 16 pages/cpu s35788 r8192 d21556 u65536
[    0.000000] pcpu-alloc: s35788 r8192 d21556 u65536 alloc=16*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 video=HDMI-A-1:1920x1080M@60 smsc95xx.macaddr=DC:A6:32:32:68:2B vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  console=ttyS0,115200 console=tty1 root=PARTUUID=d9e688b1-02 rootfstype=ext4 fsck.repair=yes rootwait quiet splash plymouth.ignore-serial-consoles
[    0.000000] Unknown kernel command line parameters "splash", will be passed to user space.
[    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 0x0000000014890000-0x0000000018890000] (64MB)
[    0.000000] Memory: 3601324K/4050944K available (10240K kernel code, 1385K rwdata, 3304K rodata, 2048K init, 592K bss, 121940K reserved, 327680K cma-reserved, 3264512K highmem)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 35501 entries in 105 pages
[    0.000000] ftrace: allocated 105 pages with 4 groups
[    0.000000] trace event string verifier disabled
[    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] 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.000018] Switching to timer-based delay loop, resolution 18ns
[    0.000365] Console: colour dummy device 80x30
[    0.000430] printk: console [tty1] enabled
[    0.000499] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000)
[    0.000530] pid_max: default: 32768 minimum: 301
[    0.000670] LSM: Security Framework initializing
[    0.000880] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.000909] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.002498] cgroup: Disabling memory control group subsystem
[    0.002749] CPU: Testing write buffer coherency: ok
[    0.002806] CPU0: Spectre BHB: enabling loop workaround for all CPUs
[    0.003272] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.004664] Setting up static identity map for 0x200000 - 0x20003c
[    0.004868] rcu: Hierarchical SRCU implementation.
[    0.005885] smp: Bringing up secondary CPUs ...
[    0.007092] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.008428] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.009732] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.009916] smp: Brought up 1 node, 4 CPUs
[    0.009940] SMP: Total of 4 processors activated (432.00 BogoMIPS).
[    0.009958] CPU: All CPU(s) started in HYP mode.
[    0.009971] CPU: Virtualization extensions available.
[    0.010775] devtmpfs: initialized
[    0.026133] VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0
[    0.026371] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.026405] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.036886] pinctrl core: initialized pinctrl subsystem
[    0.038176] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.042822] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    0.043623] audit: initializing netlink subsys (disabled)
[    0.043890] audit: type=2000 audit(0.040:1): state=initialized audit_enabled=0 res=1
[    0.044548] thermal_sys: Registered thermal governor 'step_wise'
[    0.045375] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.045396] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.045816] Serial: AMBA PL011 UART driver
[    0.060144] bcm2835-mbox fe00b880.mailbox: mailbox enabled
[    0.100067] raspberrypi-firmware soc:firmware: Attached to firmware from 2022-08-26T14:03:16, variant start
[    0.110084] raspberrypi-firmware soc:firmware: Firmware hash is 102f1e848393c2112206fadffaaf86db04e98326
[    0.168299] Kprobes globally optimized
[    0.174424] bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1
[    0.179064] vgaarb: loaded
[    0.179547] SCSI subsystem initialized
[    0.179772] usbcore: registered new interface driver usbfs
[    0.179845] usbcore: registered new interface driver hub
[    0.179918] usbcore: registered new device driver usb
[    0.180922] usb_phy_generic phy: supply vcc not found, using dummy regulator
[    0.181120] usb_phy_generic phy: dummy supplies not allowed for exclusive requests
[    0.181457] pps_core: LinuxPPS API ver. 1 registered
[    0.181473] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <[email protected]>
[    0.181501] PTP clock support registered
[    0.183279] clocksource: Switched to clocksource arch_sys_counter
[    0.264056] VFS: Disk quotas dquot_6.6.0
[    0.264167] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.264354] FS-Cache: Loaded
[    0.264573] CacheFiles: Loaded
[    0.265724] simple-framebuffer 3e3cf000.framebuffer: framebuffer at 0x3e3cf000, 0x7f8000 bytes
[    0.265747] simple-framebuffer 3e3cf000.framebuffer: format=a8r8g8b8, mode=1920x1080x32, linelength=7680
[    0.266282] Console: switching to colour frame buffer device 240x67
[    0.276903] simple-framebuffer 3e3cf000.framebuffer: fb0: simplefb registered!
[    0.287404] NET: Registered PF_INET protocol family
[    0.287657] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    0.289301] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
[    0.289336] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    0.289361] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    0.289426] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.289500] TCP: Hash tables configured (established 8192 bind 8192)
[    0.289658] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[    0.289697] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[    0.289959] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    0.294244] RPC: Registered named UNIX socket transport module.
[    0.294261] RPC: Registered udp transport module.
[    0.294275] RPC: Registered tcp transport module.
[    0.294289] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.294312] PCI: CLS 0 bytes, default 64
[    1.332742] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
[    1.335472] Initialise system trusted keyrings
[    1.335786] workingset: timestamp_bits=14 max_order=20 bucket_order=6
[    1.344820] zbud: loaded
[    1.346811] FS-Cache: Netfs 'nfs' registered for caching
[    1.347704] NFS: Registering the id_resolver key type
[    1.347747] Key type id_resolver registered
[    1.347762] Key type id_legacy registered
[    1.347894] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.347910] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    1.349008] Key type asymmetric registered
[    1.349025] Asymmetric key parser 'x509' registered
[    1.349256] bounce: pool size: 64 pages
[    1.349332] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[    1.349565] io scheduler mq-deadline registered
[    1.349581] io scheduler kyber registered
[    1.359101] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
[    1.359146] brcm-pcie fd500000.pcie:   No bus range found for /scb/pcie@7d500000, using [bus 00-ff]
[    1.359239] brcm-pcie fd500000.pcie:      MEM 0x0600000000..0x063fffffff -> 0x00c0000000
[    1.359332] brcm-pcie fd500000.pcie:   IB MEM 0x0000000000..0x00bfffffff -> 0x0400000000
[    1.415383] brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC)
[    1.415806] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
[    1.415826] pci_bus 0000:00: root bus resource [bus 00-ff]
[    1.415848] pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff])
[    1.415942] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
[    1.416189] pci 0000:00:00.0: PME# supported from D0 D3hot
[    1.419886] PCI: bus0: Fast back to back transfers disabled
[    1.419911] pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[    1.420286] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
[    1.420415] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
[    1.420848] pci 0000:01:00.0: PME# supported from D0 D3cold
[    1.424568] PCI: bus1: Fast back to back transfers disabled
[    1.424588] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
[    1.424633] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
[    1.424659] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
[    1.424744] pci 0000:00:00.0: PCI bridge to [bus 01]
[    1.424770] pci 0000:00:00.0:   bridge window [mem 0x600000000-0x6000fffff]
[    1.425186] pcieport 0000:00:00.0: enabling device (0140 -> 0142)
[    1.425423] pcieport 0000:00:00.0: PME: Signaling with IRQ 78
[    1.439494] iproc-rng200 fe104000.rng: hwrng registered
[    1.439885] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    1.441503] gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000
[    1.454661] brd: module loaded
[    1.465173] loop: module loaded
[    1.466008] Loading iSCSI transport class v2.0-870.
[    1.471348] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000
[    1.563444] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus
[    1.564712] usbcore: registered new interface driver r8152
[    1.564786] usbcore: registered new interface driver lan78xx
[    1.564848] usbcore: registered new interface driver smsc95xx
[    1.566470] xhci_hcd 0000:01:00.0: enabling device (0140 -> 0142)
[    1.566639] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.566677] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
[    1.572380] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x00003c0000000890
[    1.573523] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.573552] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
[    1.573577] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
[    1.573969] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15
[    1.573993] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.574013] usb usb1: Product: xHCI Host Controller
[    1.574029] usb usb1: Manufacturer: Linux 5.15.61-v7l+ xhci-hcd
[    1.574045] usb usb1: SerialNumber: 0000:01:00.0
[    1.574834] hub 1-0:1.0: USB hub found
[    1.574931] hub 1-0:1.0: 1 port detected
[    1.575977] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.15
[    1.576000] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.576019] usb usb2: Product: xHCI Host Controller
[    1.576036] usb usb2: Manufacturer: Linux 5.15.61-v7l+ xhci-hcd
[    1.576051] usb usb2: SerialNumber: 0000:01:00.0
[    1.576749] hub 2-0:1.0: USB hub found
[    1.576819] hub 2-0:1.0: 4 ports detected
[    1.578682] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    1.579047] dwc_otg: FIQ enabled
[    1.579062] dwc_otg: NAK holdoff enabled
[    1.579075] dwc_otg: FIQ split-transaction FSM enabled
[    1.579093] Module dwc_common_port init
[    1.579557] usbcore: registered new interface driver uas
[    1.579664] usbcore: registered new interface driver usb-storage
[    1.579876] mousedev: PS/2 mouse device common for all mice
[    1.586793] sdhci: Secure Digital Host Controller Interface driver
[    1.586812] sdhci: Copyright(c) Pierre Ossman
[    1.587564] sdhci-pltfm: SDHCI platform and OF driver helper
[    1.591416] ledtrig-cpu: registered to indicate activity on CPUs
[    1.591778] hid: raw HID events driver (C) Jiri Kosina
[    1.591965] usbcore: registered new interface driver usbhid
[    1.591981] usbhid: USB HID core driver
[    1.598649] Initializing XFRM netlink socket
[    1.598695] NET: Registered PF_PACKET protocol family
[    1.598820] Key type dns_resolver registered
[    1.599317] Registering SWP/SWPB emulation handler
[    1.600124] registered taskstats version 1
[    1.600156] Loading compiled-in X.509 certificates
[    1.600998] Key type ._fscrypt registered
[    1.601016] Key type .fscrypt registered
[    1.601031] Key type fscrypt-provisioning registered
[    1.613521] uart-pl011 fe201000.serial: there is not valid maps for state default
[    1.613850] uart-pl011 fe201000.serial: cts_event_workaround enabled
[    1.614004] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 34, base_baud = 0) is a PL011 rev2
[    1.623462] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    1.623957] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    1.625268] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0
[    1.625289] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated
[    1.655669] of_cfs_init
[    1.655929] of_cfs_init: OK
[    1.693908] mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA
[    1.694352] Waiting for root device PARTUUID=d9e688b1-02...
[    1.749822] mmc1: new high speed SDIO card at address 0001
[    1.793911] mmc0: new ultra high speed DDR50 SDHC card at address 0001
[    1.794903] mmcblk0: mmc0:0001 BB1QT 29.8 GiB
[    1.797981]  mmcblk0: p1 p2
[    1.798547] mmcblk0: mmc0:0001 BB1QT 29.8 GiB
[    1.821013] EXT4-fs (mmcblk0p2): INFO: recovery required on readonly filesystem
[    1.821037] EXT4-fs (mmcblk0p2): write access will be enabled during recovery
[    1.863322] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[    2.046076] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21
[    2.046107] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    2.046127] usb 1-1: Product: USB2.0 Hub
[    2.048556] hub 1-1:1.0: USB hub found
[    2.048906] hub 1-1:1.0: 4 ports detected
[    2.296773] EXT4-fs (mmcblk0p2): recovery complete
[    2.299978] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
[    2.300079] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[    2.300895] devtmpfs: mounted
[    2.312094] Freeing unused kernel image (initmem) memory: 2048K
[    2.312426] Run /sbin/init as init process
[    2.312440]   with arguments:
[    2.312453]     /sbin/init
[    2.312466]     splash
[    2.312478]   with environment:
[    2.312491]     HOME=/
[    2.312504]     TERM=linux
[    2.373331] usb 1-1.3: new full-speed USB device number 3 using xhci_hcd
[    2.522804] usb 1-1.3: New USB device found, idVendor=045e, idProduct=0800, bcdDevice= 9.44
[    2.522836] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    2.522856] usb 1-1.3: Product: Microsoft® Nano Transceiver v2.0
[    2.522873] usb 1-1.3: Manufacturer: Microsoft
[    2.535696] input: Microsoft Microsoft® Nano Transceiver v2.0 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:045E:0800.0001/input/input0
[    2.604100] hid-generic 0003:045E:0800.0001: input,hidraw0: USB HID v1.11 Keyboard [Microsoft Microsoft® Nano Transceiver v2.0] on usb-0000:01:00.0-1.3/input0
[    2.618246] input: Microsoft Microsoft® Nano Transceiver v2.0 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:045E:0800.0002/input/input1
[    2.618746] input: Microsoft Microsoft® Nano Transceiver v2.0 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:045E:0800.0002/input/input2
[    2.619045] hid-generic 0003:045E:0800.0002: input,hidraw1: USB HID v1.11 Mouse [Microsoft Microsoft® Nano Transceiver v2.0] on usb-0000:01:00.0-1.3/input1
[    2.633957] input: Microsoft Microsoft® Nano Transceiver v2.0 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.2/0003:045E:0800.0003/input/input3
[    2.693377] systemd[1]: System time before build time, advancing clock.
[    2.703728] input: Microsoft Microsoft® Nano Transceiver v2.0 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.2/0003:045E:0800.0003/input/input5
[    2.704205] hid-generic 0003:045E:0800.0003: input,hiddev96,hidraw2: USB HID v1.11 Device [Microsoft Microsoft® Nano Transceiver v2.0] on usb-0000:01:00.0-1.3/input2
[    2.901431] NET: Registered PF_INET6 protocol family
[    2.903198] Segment Routing with IPv6
[    2.903242] In-situ OAM (IOAM) with IPv6
[    2.979469] systemd[1]: systemd 247.3-7+rpi1+deb11u1 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +ZSTD +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=unified)
[    2.980356] systemd[1]: Detected architecture arm.
[    2.982555] systemd[1]: Set hostname to <raspberrypi>.
[    3.758722] systemd[1]: /lib/systemd/system/plymouth-start.service:16: Unit configured to use KillMode=none. This is unsafe, as it disables systemd's process lifecycle management for the service. Please update your service to use a safer KillMode=, such as 'mixed' or 'control-group'. Support for KillMode=none is deprecated and will eventually be removed.
[    3.952064] systemd[1]: Queued start job for default target Graphical Interface.
[    3.958138] systemd[1]: Created slice system-getty.slice.
[    3.959515] systemd[1]: Created slice system-modprobe.slice.
[    3.960540] systemd[1]: Created slice system-systemd\x2dfsck.slice.
[    3.961420] systemd[1]: Created slice User and Session Slice.
[    3.961953] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    3.963057] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    3.964090] systemd[1]: Reached target Slices.
[    3.964296] systemd[1]: Reached target Swap.
[    3.966235] systemd[1]: Listening on Syslog Socket.
[    3.970041] systemd[1]: Listening on fsck to fsckd communication Socket.
[    3.970502] systemd[1]: Listening on initctl Compatibility Named Pipe.
[    3.974645] systemd[1]: Listening on Journal Audit Socket.
[    3.975518] systemd[1]: Listening on Journal Socket (/dev/log).
[    3.976794] systemd[1]: Listening on Journal Socket.
[    3.978586] systemd[1]: Listening on udev Control Socket.
[    3.979422] systemd[1]: Listening on udev Kernel Socket.
[    3.980333] systemd[1]: Condition check resulted in Huge Pages File System being skipped.
[    3.986147] systemd[1]: Mounting POSIX Message Queue File System...
[    3.992731] systemd[1]: Mounting RPC Pipe File System...
[    4.000052] systemd[1]: Mounting Kernel Debug File System...
[    4.007875] systemd[1]: Mounting Kernel Trace File System...
[    4.008789] systemd[1]: Condition check resulted in Kernel Module supporting RPCSEC_GSS being skipped.
[    4.020329] systemd[1]: Starting Restore / save the current clock...
[    4.028909] systemd[1]: Starting Set the console keyboard layout...
[    4.038500] systemd[1]: Starting Create list of static device nodes for the current kernel...
[    4.047341] systemd[1]: Starting Load Kernel Module configfs...
[    4.056891] systemd[1]: Starting Load Kernel Module drm...
[    4.065239] systemd[1]: Starting Load Kernel Module fuse...
[    4.069803] systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped.
[    4.077412] systemd[1]: Starting File System Check on Root Device...
[    4.091240] systemd[1]: Starting Journal Service...
[    4.102141] systemd[1]: Starting Load Kernel Modules...
[    4.110761] systemd[1]: Starting Coldplug All udev Devices...
[    4.131138] systemd[1]: Mounted POSIX Message Queue File System.
[    4.132017] systemd[1]: Mounted RPC Pipe File System.
[    4.133027] systemd[1]: Mounted Kernel Debug File System.
[    4.134183] systemd[1]: Mounted Kernel Trace File System.
[    4.157144] systemd[1]: Finished Create list of static device nodes for the current kernel.
[    4.160133] systemd[1]: [email protected]: Succeeded.
[    4.168656] systemd[1]: Finished Load Kernel Module configfs.
[    4.174262] systemd[1]: Finished Restore / save the current clock.
[    4.182805] systemd[1]: Mounting Kernel Configuration File System...
[    4.205494] fuse: init (API version 7.34)
[    4.210326] systemd[1]: Started File System Check Daemon to report status.
[    4.219732] systemd[1]: [email protected]: Succeeded.
[    4.221390] systemd[1]: Finished Load Kernel Module fuse.
[    4.235005] i2c_dev: i2c /dev entries driver
[    4.261594] systemd[1]: Mounting FUSE Control File System...
[    4.272871] systemd[1]: Finished Load Kernel Modules.
[    4.274178] systemd[1]: Mounted Kernel Configuration File System.
[    4.280656] systemd[1]: Starting Apply Kernel Variables...
[    4.296292] systemd[1]: Mounted FUSE Control File System.
[    4.368143] systemd[1]: Finished Apply Kernel Variables.
[    4.378714] systemd[1]: [email protected]: Succeeded.
[    4.380152] systemd[1]: Finished Load Kernel Module drm.
[    4.406773] systemd[1]: Finished File System Check on Root Device.
[    4.413001] systemd[1]: Starting Remount Root and Kernel File Systems...
[    4.519291] systemd[1]: Started Journal Service.
[    4.592097] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null). Quota mode: none.
[    4.668610] systemd-journald[138]: Received client request to flush runtime journal.
[    4.702771] systemd-journald[138]: File /var/log/journal/13c7dcc8cae14fa8bf0df2919ba3a428/system.journal corrupted or uncleanly shut down, renaming and replacing.
[    5.955863] mc: Linux media interface: v0.10
[    6.068951] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[    6.075858] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[    6.081035] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[    6.081063] [vc_sm_connected_init]: start
[    6.117960] videodev: Linux video capture interface: v2.00
[    6.167380] bcm2835_audio bcm2835_audio: card created with 8 channels
[    6.176038] [vc_sm_connected_init]: installed successfully
[    6.265938] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    6.278455] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[    6.290753] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[    6.298921] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[    6.299454] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[    6.299516] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[    6.299602] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[    6.300227] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[    6.300695] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[    6.300737] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    6.300765] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    6.300788] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    6.300812] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    6.329091] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[    6.329142] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[    6.329746] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20
[    6.330293] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21
[    6.330722] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22
[    6.331054] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23
[    6.331085] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    6.331112] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    6.331135] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    6.331158] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    6.331363] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[    6.339661] rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned.
[    6.353713] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[    6.353765] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[    6.371010] rpivid feb10000.codec: Device registered as /dev/video19
[    6.371231] bcm2835-codec bcm2835-codec: Device registered as /dev/video18
[    6.371272] bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx
[    6.391021] bcm2835-codec bcm2835-codec: Device registered as /dev/video31
[    6.391076] bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image
[    6.544972] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[    6.600625] brcmstb-i2c fef04500.i2c:  @97500hz registered in polling mode
[    6.605475] brcmstb-i2c fef09500.i2c:  @97500hz registered in polling mode
[    7.419999] [drm] Initialized v3d 1.0.0 20180419 for fec00000.v3d on minor 0
[    7.432373] checking generic (3e3cf000 7f8000) vs hw (0 ffffffffffffffff)
[    7.432405] fb0: switching to vc4 from simple
[    7.435909] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[    7.469854] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[    7.484783] cfg80211: loaded regulatory.db is malformed or signature is missing/invalid
[    7.502528] Console: switching to colour dummy device 80x30
[    7.544884] vc4-drm gpu: bound fe400000.hvs (ops vc4_hvs_ops [vc4])
[    7.548811] Registered IR keymap rc-cec
[    7.569218] rc rc0: vc4 as /devices/platform/soc/fef00700.hdmi/rc/rc0
[    7.666438] input: vc4 as /devices/platform/soc/fef00700.hdmi/rc/rc0/input6
[    7.723167] brcmfmac: F1 signature read @0x18000000=0x15264345
[    7.735115] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    7.736049] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,4-model-b.bin failed with error -2
[    7.833550] usbcore: registered new interface driver brcmfmac
[    8.014622] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    8.014801] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    8.029992] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov  1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60
[    8.232469] vc4-drm gpu: bound fe400000.hvs (ops vc4_hvs_ops [vc4])
[    8.245741] Registered IR keymap rc-cec
[    8.246171] rc rc0: vc4 as /devices/platform/soc/fef00700.hdmi/rc/rc0
[    8.246881] input: vc4 as /devices/platform/soc/fef00700.hdmi/rc/rc0/input7
[    8.584276] uart-pl011 fe201000.serial: no DMA platform data
[    8.999915] Adding 102396k swap on /var/swap.  Priority:-2 extents:1 across:102396k SSFS
[    9.511628] 8021q: 802.1Q VLAN Support v1.8
[    9.651021] bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay)
[    9.656807] bcmgenet fd580000.ethernet eth0: Link is Down
[   10.476410] vc4-drm gpu: bound fe400000.hvs (ops vc4_hvs_ops [vc4])
[   10.513641] Registered IR keymap rc-cec
[   10.513872] rc rc0: vc4 as /devices/platform/soc/fef00700.hdmi/rc/rc0
[   10.514196] input: vc4 as /devices/platform/soc/fef00700.hdmi/rc/rc0/input8
[   10.518132] vc4-drm gpu: bound fef00700.hdmi (ops vc4_hdmi_ops [vc4])
[   10.566465] Registered IR keymap rc-cec
[   10.566674] rc rc1: vc4 as /devices/platform/soc/fef05700.hdmi/rc/rc1
[   10.566931] input: vc4 as /devices/platform/soc/fef05700.hdmi/rc/rc1/input9
[   10.571310] vc4-drm gpu: bound fef05700.hdmi (ops vc4_hdmi_ops [vc4])
[   10.571848] vc4-drm gpu: bound fe004000.txp (ops vc4_txp_ops [vc4])
[   10.572271] vc4-drm gpu: bound fe206000.pixelvalve (ops vc4_crtc_ops [vc4])
[   10.572729] vc4-drm gpu: bound fe207000.pixelvalve (ops vc4_crtc_ops [vc4])
[   10.573208] vc4-drm gpu: bound fe20a000.pixelvalve (ops vc4_crtc_ops [vc4])
[   10.573748] vc4-drm gpu: bound fe216000.pixelvalve (ops vc4_crtc_ops [vc4])
[   10.574165] vc4-drm gpu: bound fec12000.pixelvalve (ops vc4_crtc_ops [vc4])
[   10.595779] [drm] Initialized vc4 0.0.0 20140616 for gpu on minor 1
[   10.675088] Console: switching to colour frame buffer device 240x67
[   10.675116] vc4-drm gpu: [drm] fb0: vc4drmfb frame buffer device
[   10.791533] dvbdev: DVB: registering new adapter (CXD2880)
[   10.837113] cxd2880: cxd2880_attach: CXD2880 driver version: Ver 1.4.1 - 1.0.5
[   10.837343] cxd2880 spi0.0: DVB: registering adapter 0 frontend 0 (Sony CXD2880)...
[   10.837818] cxd2880_spi: cxd2880_spi_probe: Sony CXD2880 has successfully attached.
[   10.963302] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
[   15.651024] Bluetooth: Core ver 2.22
[   15.651092] NET: Registered PF_BLUETOOTH protocol family
[   15.651099] Bluetooth: HCI device and connection manager initialized
[   15.651113] Bluetooth: HCI socket layer initialized
[   15.651122] Bluetooth: L2CAP socket layer initialized
[   15.651142] Bluetooth: SCO socket layer initialized
[   15.660292] Bluetooth: HCI UART driver ver 2.3
[   15.660307] Bluetooth: HCI UART protocol H4 registered
[   15.660357] Bluetooth: HCI UART protocol Three-wire (H5) registered
[   15.660518] Bluetooth: HCI UART protocol Broadcom registered
[   15.949760] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[   15.949774] Bluetooth: BNEP filters: protocol multicast
[   15.949788] Bluetooth: BNEP socket layer initialized
[   15.997539] NET: Registered PF_ALG protocol family
[   16.016861] cryptd: max_cpu_qlen set to 1000
[   16.278495] systemd-journald[138]: File /var/log/journal/13c7dcc8cae14fa8bf0df2919ba3a428/user-1000.journal corrupted or uncleanly shut down, renaming and replacing.
[   16.423748] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   17.703758] hdmi-audio-codec hdmi-audio-codec.2.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   17.703788]  MAI: soc_pcm_open() failed (-19)
[   17.704645] hdmi-audio-codec hdmi-audio-codec.2.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   17.704670]  MAI: soc_pcm_open() failed (-19)
[   17.705377] hdmi-audio-codec hdmi-audio-codec.2.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   17.705404]  MAI: soc_pcm_open() failed (-19)
[   17.730906] hdmi-audio-codec hdmi-audio-codec.2.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   17.730937]  MAI: soc_pcm_open() failed (-19)
[   17.731375] hdmi-audio-codec hdmi-audio-codec.2.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   17.731392]  MAI: soc_pcm_open() failed (-19)
[   17.731654] hdmi-audio-codec hdmi-audio-codec.2.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   17.731667]  MAI: soc_pcm_open() failed (-19)
[   17.767701] hdmi-audio-codec hdmi-audio-codec.3.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   17.767732]  MAI: soc_pcm_open() failed (-19)
[   17.768357] hdmi-audio-codec hdmi-audio-codec.3.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   17.768375]  MAI: soc_pcm_open() failed (-19)
[   17.768973] hdmi-audio-codec hdmi-audio-codec.3.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   17.768990]  MAI: soc_pcm_open() failed (-19)
[   17.777017] hdmi-audio-codec hdmi-audio-codec.3.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   17.777044]  MAI: soc_pcm_open() failed (-19)
[   17.777334] hdmi-audio-codec hdmi-audio-codec.3.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   17.777350]  MAI: soc_pcm_open() failed (-19)
[   17.777614] hdmi-audio-codec hdmi-audio-codec.3.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   17.777630]  MAI: soc_pcm_open() failed (-19)
[   17.848648] ICMPv6: process `dhcpcd' is using deprecated sysctl (syscall) net.ipv6.neigh.wlan0.retrans_time - use net.ipv6.neigh.wlan0.retrans_time_ms instead
[   17.929816] Bluetooth: RFCOMM TTY layer initialized
[   17.929835] Bluetooth: RFCOMM socket layer initialized
[   17.929854] Bluetooth: RFCOMM ver 1.11
[   31.833294] cam-dummy-reg: disabling

giovariot avatar Sep 20 '22 22:09 giovariot

When booting with fkms, post output of vcdbg log msg Also post output of

tvservice -s
tvservice -m CEA
tvservice -m DMT
tvservice -d edid.dat
base64 edid.dat

popcornmix avatar Sep 21 '22 11:09 popcornmix

funny, it only correctly boots through fkms only if the time before I've tried booting using kms (of course only getting the no signal screen), if I'll reboot from a fkms "X session" to another fkms session all i get is a black screen (not a no signal one, just a black blank screen).

Nonetheless I managed to correctly boot fkms and here is the output of the commands you suggested

output of vcdbg log msc

$ sudo vcdbg log msg
005703.378: arasan: arasan_emmc_open
005852.393: brfs: File read: /mfs/sd/config.txt
005853.336: brfs: File read: 2076 bytes
006028.519: HDMI1:EDID error reading EDID block 0 attempt 0
006029.533: HDMI1:EDID giving up on reading EDID block 0
006031.458: brfs: File read: /mfs/sd/config.txt
006479.705: gpioman: gpioman_get_pin_num: pin DISPLAY_DSI_PORT not defined
006482.220: *** Restart logging
006482.240: brfs: File read: 2076 bytes
006647.433: hdmi: HDMI1:EDID error reading EDID block 0 attempt 0
006648.467: hdmi: HDMI1:EDID giving up on reading EDID block 0
006748.681: hdmi: HDMI1:EDID error reading EDID block 0 attempt 0
006749.732: hdmi: HDMI1:EDID giving up on reading EDID block 0
006749.767: hdmi: HDMI:hdmi_get_state is deprecated, use hdmi_get_display_state instead
006749.795: HDMI0: hdmi_pixel_encoding: 300000000
006749.822: HDMI1: hdmi_pixel_encoding: 300000000
006766.650: dtb_file 'bcm2711-rpi-4-b.dtb'
006778.398: brfs: File read: /mfs/sd/bcm2711-rpi-4-b.dtb
006778.432: Loaded 'bcm2711-rpi-4-b.dtb' to 0x100 size 0xcaf3
006811.049: brfs: File read: 51955 bytes
006825.827: brfs: File read: /mfs/sd/overlays/overlay_map.dtb
007005.993: brfs: File read: 2043 bytes
007009.470: brfs: File read: /mfs/sd/config.txt
007011.458: brfs: File read: 2076 bytes
007027.174: brfs: File read: /mfs/sd/overlays/rpi-tv.dtbo
007045.807: Loaded overlay 'rpi-tv'
007045.822: Loaded HAT overlay
007045.840: dtparam: audio=on
007109.267: brfs: File read: 567 bytes
007129.238: brfs: File read: /mfs/sd/overlays/vc4-fkms-v3d-pi4.dtbo
007173.227: Loaded overlay 'vc4-fkms-v3d'
007275.733: brfs: File read: 1446 bytes
007278.449: brfs: File read: /mfs/sd/cmdline.txt
007278.529: Read command line from file 'cmdline.txt':
007278.556: 'console=serial0,115200 console=tty1 root=PARTUUID=d9e688b1-02 rootfstype=ext4 fsck.repair=yes rootwait quiet splash plymouth.ignore-serial-consoles'
007645.853: brfs: File read: 148 bytes
009001.810: brfs: File read: /mfs/sd/kernel7l.img
009001.849: Loaded 'kernel7l.img' to 0x8000 size 0x6b6ca0
009001.914: Device tree loaded to 0x2eff2c00 (size 0xd331)
009009.068: gpioman: gpioman_get_pin_num: pin SDCARD_CONTROL_POWER not defined
012515.557: vchiq_core: vchiq_init_state: slot_zero = 0xdad80000, is_master = 1
012519.094: hdmi: HDMI:hdmi_get_state is deprecated, use hdmi_get_display_state instead
012526.153: TV service:host side not connected, dropping notification 0x00000002, 0x00000001, 0x00000010
018245.126: TV service:host side not connected, dropping notification 0x00000001, 0x00000002, 0x00000000
018291.572: TV service:host side not connected, dropping notification 0x00000002, 0x00000001, 0x00000010
020980.887: TV service:host side not connected, dropping notification 0x00000001, 0x00000002, 0x00000000
021024.234: TV service:host side not connected, dropping notification 0x00000002, 0x00000001, 0x00000010
021587.518: TV service:host side not connected, dropping notification 0x00000008, 0x00000004, 0x00000000
023478.345: TV service:host side not connected, dropping notification 0x00000001, 0x00000002, 0x00000000
023521.165: TV service:host side not connected, dropping notification 0x00000002, 0x00000004, 0x00000000
023533.007: TV service:host side not connected, dropping notification 0x00000001, 0x00000002, 0x00000000
023575.812: TV service:host side not connected, dropping notification 0x00000002, 0x00000004, 0x00000000
028704.792: TV service:host side not connected, dropping notification 0x00000001, 0x00000002, 0x00000000
028752.243: TV service:host side not connected, dropping notification 0x00000002, 0x00000004, 0x00000000
028761.869: TV service:host side not connected, dropping notification 0x00000001, 0x00000002, 0x00000000
028803.465: TV service:host side not connected, dropping notification 0x00000002, 0x00000004, 0x00000000
029019.260: TV service:host side not connected, dropping notification 0x00000001, 0x00000002, 0x00000000
029063.586: TV service:host side not connected, dropping notification 0x00000002, 0x00000004, 0x00000000
031457.516: TV service:host side not connected, dropping notification 0x00000001, 0x00000002, 0x00000000
031501.078: TV service:host side not connected, dropping notification 0x00000002, 0x00000004, 0x00000000

tvservice -s

$ tvservice -s
state 0xa [HDMI CUSTOM RGB lim 16:9], 1920x1080 @ 60.00Hz, progressive

tvservice -m CEA

$ tvservice -m CEA
Group CEA has 14 modes:
           mode 1: 640x480 @ 60Hz 4:3, clock:25MHz progressive 
           mode 2: 720x480 @ 60Hz 4:3, clock:27MHz progressive 
           mode 3: 720x480 @ 60Hz 16:9, clock:27MHz progressive 
           mode 4: 1280x720 @ 60Hz 16:9, clock:74MHz progressive 
           mode 5: 1920x1080 @ 60Hz 16:9, clock:74MHz interlaced 
  (prefer) mode 16: 1920x1080 @ 60Hz 16:9, clock:148MHz progressive 
           mode 17: 720x576 @ 50Hz 4:3, clock:27MHz progressive 
           mode 18: 720x576 @ 50Hz 16:9, clock:27MHz progressive 
           mode 19: 1280x720 @ 50Hz 16:9, clock:74MHz progressive 
           mode 20: 1920x1080 @ 50Hz 16:9, clock:74MHz interlaced 
           mode 31: 1920x1080 @ 50Hz 16:9, clock:148MHz progressive 
           mode 32: 1920x1080 @ 24Hz 16:9, clock:74MHz progressive 
           mode 33: 1920x1080 @ 25Hz 16:9, clock:74MHz progressive 
           mode 34: 1920x1080 @ 30Hz 16:9, clock:74MHz progressive 

tvservice -m DMT

$ tvservice -m DMT
Group DMT has 19 modes:
           mode 4: 640x480 @ 60Hz 4:3, clock:25MHz progressive 
           mode 5: 640x480 @ 72Hz 4:3, clock:31MHz progressive 
           mode 6: 640x480 @ 75Hz 4:3, clock:31MHz progressive 
           mode 8: 800x600 @ 56Hz 4:3, clock:36MHz progressive 
           mode 9: 800x600 @ 60Hz 4:3, clock:40MHz progressive 
           mode 10: 800x600 @ 72Hz 4:3, clock:50MHz progressive 
           mode 11: 800x600 @ 75Hz 4:3, clock:49MHz progressive 
           mode 16: 1024x768 @ 60Hz 4:3, clock:65MHz progressive 
           mode 17: 1024x768 @ 70Hz 4:3, clock:75MHz progressive 
           mode 18: 1024x768 @ 75Hz 4:3, clock:78MHz progressive 
           mode 21: 1152x864 @ 75Hz 4:3, clock:108MHz progressive 
           mode 28: 1280x800 @ 60Hz 16:10, clock:83MHz progressive 
           mode 32: 1280x960 @ 60Hz 4:3, clock:108MHz progressive 
           mode 35: 1280x1024 @ 60Hz 5:4, clock:108MHz progressive 
           mode 36: 1280x1024 @ 75Hz 5:4, clock:135MHz progressive 
           mode 39: 1360x768 @ 60Hz 16:9, clock:85MHz progressive 
           mode 47: 1440x900 @ 60Hz 16:10, clock:106MHz progressive 
           mode 48: 1440x900 @ 75Hz 16:10, clock:136MHz progressive 
           mode 58: 1680x1050 @ 60Hz 16:10, clock:146MHz progressive 

tvservice -d edid.dat

$ tvservice -d edid.dat
Written 256 bytes to edid.dat

base64 edid.dat

$ base64 edid.dat
AP///////wBMLXoIAAAAABkVAQOAPCJ4CrpBoVlVnSgNUFS/74BxT4EAgUCBgJUAlQ+zAAEBAjqA
GHE4LUBYLEUAVlAhAAAeZiFQsFEAGzBAcDYAVlAhAAAeAAAA/QAYSxpRFwAKICAgICAgAAAA/ABT
TVQyN0EzMDAKICAgAdACAyPxS5AfBBMFFAMSICEiIwkHB4MBAADiAA9nAwwAIAC4LQEdAHJR0B4g
bihVAKBaAAAAHgEdALxS0B4guChVQKBaAAAAHgEdgBhxHBYgWCwlAKBaAAAAngEdgNByHBYgECwl
gKBaAAAAngAAAAAAAAAAAAAAAAAAAAAAAAAA/Q==

giovariot avatar Sep 21 '22 20:09 giovariot

funny, it only correctly boots through fkms only if the time before I've tried booting using kms (of course only getting the no signal screen), if I'll reboot from a fkms "X session" to another fkms session all i get is a black screen (not a no signal one, just a black blank screen).

In the black screen case, can you still ssh in and run commands? If so the output of the previous commands would be useful. I'm suspecting edid reading issues, although on the successful run it worked okay.

I'm curious if in the fkms case you copy edid.dat to /boot and add to config.txt:

hdmi_edid_file=1
hdmi_force_hotplug=1
hdmi_group=1
hdmi_mode=16

Also in the kms case (with the fkms config.txt test lines removed), copy edid.dat to /lib/firmware and add to cmdline.txt (end of existing line)

drm.edid_firmware=edid.dat video=HDMI-A-1:1920x1080@60D

and report if either case is reliable.

Also have you tried a different hdmi cable? Are you using a case (such as the argon one) that reroutes hdmi?

popcornmix avatar Sep 22 '22 11:09 popcornmix

using just vc4-fkms-v3d

I kept the session open from yesterday, copied edid.dat to /boot and to /lib/firmware.

Just to check everything was(n't) working as expected I tried rebooting from the fkms X session before editing the config file. Got to a "_" on the top left of the screen: in this case it seems that tvservice gets stuck, can't even get out of the command using CTRL+C

pi@raspberrypi:~ $ sudo vcdbg log msc
vcdbg log - unrecognized command: 'msc'
pi@raspberrypi:~ $ tvservice -s

And it doesn't seem to want to reboot from "sudo reboot". Forced reboot a few times trying to get to the "black" screen but really couldn't reach it.

But anyways... of course at the next reboot it boots correctly to X, the next reboot gets me to the stuck "_"


using the hdmi options

I edit the config.txt file adding what you told me

hdmi_edid_file=1
hdmi_force_hotplug=1
hdmi_group=1
hdmi_mode=16

now it's finally booting correctly every time


using kms

Edited:

  • config.txt commenting the aforementioned hdmi options and corrected vc4-fkms-v3d --> vc4-kms-v3d as per default
  • cmdline.txt adding drm.edid_firmware=edid.dat video=HDMI-A-1:1920x1080@60D.

so now it works perfectly at every reboot even using kms.


About my config

  • tried both with my parents' tv (which is actually the one I want to configure the system for)
  • tried 2 different hdmi cables (the one here and the one at my parents', which had been working with this same rpi for an year or so
  • the case is the standard plastic raspberry one

Two new questions

  1. why is this happening both on this tv and my parents' one? Why did old raspbian images use to boot correctly every time and now they seem to work every now and then both on this tv and my parents' one? Why do new version can't manage to get any signal onto the display? And why is PINN bootloader, for example, working as expected at every boot without needing to alter its config in any way?
  2. now it works on my tv (thanks a lot @popcornmix!) but I'm only using it on my tv as I tried to setup it at my parent's place only to get to a no signal screen, so I brought it home to find a solution. Now I'd like to make it work on theirs. What can I do?

I was thinking to load an old raspbian version just to extract the edid.dat from my parents' tv and the export it to both raspbian and libreelec. Would that work?

Thanks a lot for helping me!

giovariot avatar Sep 22 '22 22:09 giovariot

pi@raspberrypi:~ $ sudo vcdbg log msc vcdbg log - unrecognized command: 'msc'

This should be sudo vcdbg log msg (as you ran before).

I was thinking to load an old raspbian version just to extract the edid.dat from my parents' tv and the export it to both raspbian and libreelec. Would that work?

Yes, the edid will be the same however you boot, so you can capture it from any operating system. Libreelec has a slightly different scheme of capturing the edid

All the evidence so far suggests a hardware issue in reading the edid. That could be in the cables, the TV or the Pi itself (the cable is most common cause, the TV less common and I've never hard of a Pi fault causing this, but it is possible). If the TV has multiple HDMI ports, then try them all.

kms relies on the edid completely. fkms is more tolerant and often displays a picture without a valid edid, but it's just guessing what the TV actually supports. And you'll probably find issues with audio, cec and ability to change hdmi mode.

I can't think of a reason why newer fkms is failing but older works. It has been deprecated a long time so doesn't get any code changes. If you can capture "vcgencmd version" for a working image and a not working image (ideally find the oldest working/newest not-working) then I may be able to speculate if there's been any relevant change.

popcornmix avatar Sep 23 '22 10:09 popcornmix

FWIW, this same thing happened to me today, after upgrading RasPiOS to kernel 6.1. I'd had the vc4-kms-v3d overlay enabled on that machine/display combo for years, so long that I've forgotten why. Today the screen went blank at the same point yours did during boot. I swapped to a newer monitor and it worked fine. But I wasn't satisfied, and googling the name of that overlay brought me to this issue report. Something about that older [Dell] monitor wasn't compatible with the latest version of that overlay. Removing the overlay did the trick. I wonder what I'm missing with it gone? Will keep reading ...

glyndon avatar Mar 18 '23 19:03 glyndon

Hey @popcornmix I've found myself here after being unable to reply to the forum thread for 2 days, and looking for any other solutions out there (for getting my setup working right, using vc4-kms-v3d not vc4-fkms-v3d). It seems like my situation is nearly identical as the above, and I've tried the steps mentioned above, but that then leaves me in an odd situation with things not displaying correctly.

Specifically this surprised me,

tvservice -l
2 attached device(s), display ID's are :
Display Number 2, type HDMI 0
Display Number 7, type HDMI 1

As well as what I thought was HDMI-A-1 was actually HDMI-A-2 and vice versa.

Should I dump my logs here, and try to debug here, or create a new issue?

seaders avatar Jun 26 '23 13:06 seaders

Start a new issue please.

It's not clear what your issue is, but be aware that tvservice (and the fkms driver) are deprecated. So please report what goes wrong with the (default) kms driver. Reporting any difference in behaviour of fkms driver may be useful for diagnosing the issue, but any fixes are likely to come through the kms driver.

popcornmix avatar Jun 26 '23 13:06 popcornmix

No need now actually, I've figured my issue out. I actually needed only the hotplug fix, but not for hdmi 1, for hdmi 2. Issue with following the above steps for me was, because tvservice only works when in fkms mode, it was showing everything as hdmi 1, so, I thought I was dealing with hdmi 1.

When I finally copped that, I first changed A-1 to A-2 in cmdline.txt, then I changed hdmi_force_hotplug=1 to hdmi_force_hotplug:1=1, rebooted and pruned, and pruned again, and then everything was perfect.

Just a thing though, you said that tvservice is deprecated, and, sure enough it doesn't really work with the kms driver, but what's the alternatiive? Specifically to try do what you suggest above for edid? But I wasn't even able to find where that info was put out. Played with kmsprint, as suggested by trying to use tvservice, but no luck.

seaders avatar Jun 26 '23 23:06 seaders

raspinfo is a debug tool that include a lot of information about display. Look at the output of that.

It can get all the information that tvservice does using standard linux interfaces when using kms. It uses kmsprint to get info about modes supported, and /sys/class/drm/card*/edid to get the edid.

popcornmix avatar Jun 27 '23 10:06 popcornmix