Linux 5.15.32 sometimes failing to initialize USB

[teletypo]

Describe the bug

Hi!

I have built a yocto-image for my CM4, and I'm using linux 5.15.32 from this repository. The CM4's USB2 lines are connected to a FE1.1S 4port hub.

I am normally seeing happy operation, and can see 2 devices connected to the hub's downstream ports. But very rarely, the system boots up and there's a crash/warning/calltrace in dmesg, and then USB initialization seem to fail ([ 13.984092] usb 3-1: device descriptor read/64, error -110), and neither of the 2 devices show up. I am puzzled how this can happen only sometimes, since the system boots from readonly-mounted storage, so every bootup should be the same.

The dmesg when it happens is attached. My questions are:

1. What is happening?
2. What does the call trace "say"?
3. Is this a known problem? Should I try to update to https://github.com/raspberrypi/linux/tree/rpi-5.19.y ?
4. What can I do to debug this better?

Thank you for your help!

Steps to reproduce the behaviour

Boot the system many times until it happens.

Device (s)

Raspberry Pi CM4 Lite

System

Linux version 5.15.32-v8

Logs

[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd083]
[    0.000000] Linux version 5.15.32-v8 (oe-user@oe-host) (aarch64-poky-linux-gcc (GCC) 11.2.0, GNU ld (GNU Binutils) 2.37.20210721) #1 SMP PREEMPT Tue Apr 5 18:36:06 UTC 2022
[    0.000000] random: fast init done
[    0.000000] Machine model: Raspberry Pi Compute Module 4 Rev 1.0
[    0.000000] efi: UEFI not found.
[    0.000000] Reserved memory: created CMA memory pool at 0x000000002ac00000, size 64 MiB
[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000000000-0x000000003fffffff]
[    0.000000]   DMA32    [mem 0x0000000040000000-0x00000000fbffffff]
[    0.000000]   Normal   empty
[    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 29 pages/cpu s78232 r8192 d32360 u118784
[    0.000000] pcpu-alloc: s78232 r8192 d32360 u118784 alloc=29*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
[    0.000000] Detected PIPT I-cache on CPU0
[    0.000000] CPU features: detected: Spectre-v2
[    0.000000] CPU features: detected: Spectre-v4
[    0.000000] CPU features: detected: Spectre-BHB
[    0.000000] CPU features: detected: ARM errata 1165522, 1319367, or 1530923
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 996912
[    0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=1 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 8250.nr_uarts=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=... vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  dwc_otg.lpm_enable=0 rootfstype=ext4 rootwait root=/dev/mmcblk0p2 logo.nologo console=ttyAMA0,115200
[    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
[    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] software IO TLB: mapped [mem 0x0000000037400000-0x000000003b400000] (64MB)
[    0.000000] Memory: 3814172K/4050944K available (12800K kernel code, 2002K rwdata, 4616K rodata, 3968K init, 975K bss, 171236K reserved, 65536K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 40528 entries in 159 pages
[    0.000000] ftrace: allocated 159 pages with 6 groups
[    0.000000] trace event string verifier disabled
[    0.000000] rcu: Preemptible hierarchical RCU implementation.
[    0.000000] rcu: 	RCU event tracing is enabled.
[    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4.
[    0.000000] 	Trampoline variant of Tasks RCU enabled.
[    0.000000] 	Rude variant of Tasks RCU enabled.
[    0.000000] 	Tracing variant of Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] Root IRQ handler: gic_handle_irq
[    0.000000] GIC: Using split EOI/Deactivate mode
[    0.000000] random: get_random_bytes called from start_kernel+0x510/0x704 with crng_init=1
[    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.000246] Console: colour dummy device 80x25
[    0.000323] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000)
[    0.000353] pid_max: default: 32768 minimum: 301
[    0.000490] LSM: Security Framework initializing
[    0.000666] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.000730] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.001935] cgroup: Disabling memory control group subsystem
[    0.004323] rcu: Hierarchical SRCU implementation.
[    0.005383] EFI services will not be available.
[    0.005908] smp: Bringing up secondary CPUs ...
[    0.006951] Detected PIPT I-cache on CPU1
[    0.007024] CPU1: Booted secondary processor 0x0000000001 [0x410fd083]
[    0.008245] Detected PIPT I-cache on CPU2
[    0.008294] CPU2: Booted secondary processor 0x0000000002 [0x410fd083]
[    0.009430] Detected PIPT I-cache on CPU3
[    0.009478] CPU3: Booted secondary processor 0x0000000003 [0x410fd083]
[    0.009658] smp: Brought up 1 node, 4 CPUs
[    0.009703] SMP: Total of 4 processors activated.
[    0.009718] CPU features: detected: 32-bit EL0 Support
[    0.009731] CPU features: detected: 32-bit EL1 Support
[    0.009747] CPU features: detected: CRC32 instructions
[    0.042364] CPU: All CPU(s) started at EL2
[    0.042449] alternatives: patching kernel code
[    0.044046] devtmpfs: initialized
[    0.054956] Enabled cp15_barrier support
[    0.055004] Enabled setend support
[    0.055323] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.055359] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.057462] pinctrl core: initialized pinctrl subsystem
[    0.058230] DMI not present or invalid.
[    0.058732] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.062121] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations
[    0.062428] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    0.063305] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    0.063404] audit: initializing netlink subsys (disabled)
[    0.063678] audit: type=2000 audit(0.060:1): state=initialized audit_enabled=0 res=1
[    0.064207] thermal_sys: Registered thermal governor 'step_wise'
[    0.064431] cpuidle: using governor menu
[    0.064645] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.064829] ASID allocator initialised with 65536 entries
[    0.064986] Serial: AMBA PL011 UART driver
[    0.076715] bcm2835-mbox fe00b880.mailbox: mailbox enabled
[    0.104289] raspberrypi-firmware soc:firmware: Attached to firmware from 2021-09-30T19:21:03, variant start
[    0.108307] raspberrypi-firmware soc:firmware: Firmware hash is 51215b4f6e3966401ecd99652a35cf1c25069113
[    0.156970] bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1
[    0.161004] vgaarb: loaded
[    0.161420] SCSI subsystem initialized
[    0.161616] usbcore: registered new interface driver usbfs
[    0.161685] usbcore: registered new interface driver hub
[    0.161753] usbcore: registered new device driver usb
[    0.162084] usb_phy_generic phy: supply vcc not found, using dummy regulator
[    0.162578] pps_core: LinuxPPS API ver. 1 registered
[    0.162596] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <[email protected]>
[    0.162631] PTP clock support registered
[    0.162870] Advanced Linux Sound Architecture Driver Initialized.
[    0.164017] clocksource: Switched to clocksource arch_sys_counter
[    0.256549] VFS: Disk quotas dquot_6.6.0
[    0.256666] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.256862] FS-Cache: Loaded
[    0.257089] CacheFiles: Loaded
[    0.257320] simple-framebuffer 3ea9b000.framebuffer: framebuffer at 0x3ea9b000, 0x12c000 bytes
[    0.257345] simple-framebuffer 3ea9b000.framebuffer: format=a8r8g8b8, mode=640x480x32, linelength=2560
[    0.259411] Console: switching to colour frame buffer device 80x30
[    0.261120] simple-framebuffer 3ea9b000.framebuffer: fb0: simplefb registered!
[    0.271811] NET: Registered PF_INET protocol family
[    0.272311] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)
[    0.275690] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)
[    0.275752] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)
[    0.275967] TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear)
[    0.276476] TCP: Hash tables configured (established 32768 bind 32768)
[    0.276707] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)
[    0.276778] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)
[    0.277074] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    0.278019] RPC: Registered named UNIX socket transport module.
[    0.278041] RPC: Registered udp transport module.
[    0.278055] RPC: Registered tcp transport module.
[    0.278069] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.278091] PCI: CLS 0 bytes, default 64
[    0.280337] hw perfevents: enabled with armv8_cortex_a72 PMU driver, 7 counters available
[    0.280664] kvm [1]: IPA Size Limit: 44 bits
[    0.281896] kvm [1]: vgic interrupt IRQ9
[    0.282157] kvm [1]: Hyp mode initialized successfully
[    1.178294] Initialise system trusted keyrings
[    1.178644] workingset: timestamp_bits=46 max_order=20 bucket_order=0
[    1.185530] zbud: loaded
[    1.187271] FS-Cache: Netfs 'nfs' registered for caching
[    1.187962] NFS: Registering the id_resolver key type
[    1.188042] Key type id_resolver registered
[    1.188059] Key type id_legacy registered
[    1.188159] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.188177] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    1.189342] Key type asymmetric registered
[    1.189362] Asymmetric key parser 'x509' registered
[    1.189435] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246)
[    1.189687] io scheduler mq-deadline registered
[    1.189707] io scheduler kyber registered
[    1.197032] gpio-507 (ant1): hogged as output/low
[    1.198410] gpio-511 (ant2): hogged as output/high
[    1.199647] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
[    1.199689] brcm-pcie fd500000.pcie:   No bus range found for /scb/pcie@7d500000, using [bus 00-ff]
[    1.199777] brcm-pcie fd500000.pcie:      MEM 0x0600000000..0x063fffffff -> 0x00c0000000
[    1.199863] brcm-pcie fd500000.pcie:   IB MEM 0x0000000000..0x007fffffff -> 0x0000000000
[    1.250117] brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC)
[    1.250502] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
[    1.250524] pci_bus 0000:00: root bus resource [bus 00-ff]
[    1.250546] pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff])
[    1.250651] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
[    1.250898] pci 0000:00:00.0: PME# supported from D0 D3hot
[    1.254870] pci 0000:01:00.0: [1b21:1806] type 01 class 0x060400
[    1.255020] pci 0000:01:00.0: enabling Extended Tags
[    1.255271] pci 0000:01:00.0: PME# supported from D0 D3hot D3cold
[    1.255355] pci 0000:01:00.0: 4.000 Gb/s available PCIe bandwidth, limited by 5.0 GT/s PCIe x1 link at 0000:00:00.0 (capable of 63.008 Gb/s with 8.0 GT/s PCIe x8 link)
[    1.267375] pci 0000:01:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[    1.267649] pci 0000:02:00.0: [1b21:1806] type 01 class 0x060400
[    1.267800] pci 0000:02:00.0: enabling Extended Tags
[    1.268083] pci 0000:02:00.0: PME# supported from D0 D3hot D3cold
[    1.268653] pci 0000:02:02.0: [1b21:1806] type 01 class 0x060400
[    1.268803] pci 0000:02:02.0: enabling Extended Tags
[    1.269061] pci 0000:02:02.0: PME# supported from D0 D3hot D3cold
[    1.269802] pci 0000:02:06.0: [1b21:1806] type 01 class 0x060400
[    1.269951] pci 0000:02:06.0: enabling Extended Tags
[    1.270209] pci 0000:02:06.0: PME# supported from D0 D3hot D3cold
[    1.271377] pci 0000:02:0e.0: [1b21:1806] type 01 class 0x060400
[    1.271525] pci 0000:02:0e.0: enabling Extended Tags
[    1.271782] pci 0000:02:0e.0: PME# supported from D0 D3hot D3cold
[    1.273979] pci 0000:02:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[    1.274018] pci 0000:02:02.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[    1.274052] pci 0000:02:06.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[    1.274086] pci 0000:02:0e.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[    1.274358] pci 0000:03:00.0: [10ec:5261] type 00 class 0xff0000
[    1.274425] pci 0000:03:00.0: reg 0x10: [mem 0x00000000-0x00000fff]
[    1.274819] pci 0000:03:00.0: supports D1 D2
[    1.274835] pci 0000:03:00.0: PME# supported from D1 D2 D3hot D3cold
[    1.287374] pci_bus 0000:03: busn_res: [bus 03-06] end is updated to 03
[    1.287660] pci 0000:04:00.0: [8086:1539] type 00 class 0x020000
[    1.287731] pci 0000:04:00.0: reg 0x10: [mem 0x00000000-0x0001ffff]
[    1.287793] pci 0000:04:00.0: reg 0x18: [io  0x0000-0x001f]
[    1.287833] pci 0000:04:00.0: reg 0x1c: [mem 0x00000000-0x00003fff]
[    1.288193] pci 0000:04:00.0: PME# supported from D0 D3hot D3cold
[    1.303344] pci_bus 0000:04: busn_res: [bus 04-06] end is updated to 04
[    1.303637] pci 0000:05:00.0: [8086:1539] type 00 class 0x020000
[    1.303708] pci 0000:05:00.0: reg 0x10: [mem 0x00000000-0x0001ffff]
[    1.303769] pci 0000:05:00.0: reg 0x18: [io  0x0000-0x001f]
[    1.303809] pci 0000:05:00.0: reg 0x1c: [mem 0x00000000-0x00003fff]
[    1.304170] pci 0000:05:00.0: PME# supported from D0 D3hot D3cold
[    1.319349] pci_bus 0000:05: busn_res: [bus 05-06] end is updated to 05
[    1.319616] pci 0000:06:00.0: [104c:8241] type 00 class 0x0c0330
[    1.319694] pci 0000:06:00.0: reg 0x10: [mem 0x00000000-0x0000ffff 64bit]
[    1.319746] pci 0000:06:00.0: reg 0x18: [mem 0x00000000-0x00001fff 64bit]
[    1.320046] pci 0000:06:00.0: supports D1 D2
[    1.320063] pci 0000:06:00.0: PME# supported from D0 D1 D2 D3hot
[    1.335347] pci_bus 0000:06: busn_res: [bus 06] end is updated to 06
[    1.335381] pci_bus 0000:02: busn_res: [bus 02-06] end is updated to 06
[    1.335462] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6003fffff]
[    1.335487] pci 0000:01:00.0: BAR 8: assigned [mem 0x600000000-0x6003fffff]
[    1.335505] pci 0000:01:00.0: BAR 7: no space for [io  size 0x2000]
[    1.335523] pci 0000:01:00.0: BAR 7: failed to assign [io  size 0x2000]
[    1.335545] pci 0000:02:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
[    1.335564] pci 0000:02:02.0: BAR 8: assigned [mem 0x600100000-0x6001fffff]
[    1.335581] pci 0000:02:06.0: BAR 8: assigned [mem 0x600200000-0x6002fffff]
[    1.335599] pci 0000:02:0e.0: BAR 8: assigned [mem 0x600300000-0x6003fffff]
[    1.335616] pci 0000:02:02.0: BAR 7: no space for [io  size 0x1000]
[    1.335632] pci 0000:02:02.0: BAR 7: failed to assign [io  size 0x1000]
[    1.335649] pci 0000:02:06.0: BAR 7: no space for [io  size 0x1000]
[    1.335665] pci 0000:02:06.0: BAR 7: failed to assign [io  size 0x1000]
[    1.335684] pci 0000:03:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff]
[    1.335712] pci 0000:02:00.0: PCI bridge to [bus 03]
[    1.335739] pci 0000:02:00.0:   bridge window [mem 0x600000000-0x6000fffff]
[    1.335777] pci 0000:04:00.0: BAR 0: assigned [mem 0x600100000-0x60011ffff]
[    1.335805] pci 0000:04:00.0: BAR 3: assigned [mem 0x600120000-0x600123fff]
[    1.335831] pci 0000:04:00.0: BAR 2: no space for [io  size 0x0020]
[    1.335848] pci 0000:04:00.0: BAR 2: failed to assign [io  size 0x0020]
[    1.335865] pci 0000:02:02.0: PCI bridge to [bus 04]
[    1.335891] pci 0000:02:02.0:   bridge window [mem 0x600100000-0x6001fffff]
[    1.335929] pci 0000:05:00.0: BAR 0: assigned [mem 0x600200000-0x60021ffff]
[    1.335956] pci 0000:05:00.0: BAR 3: assigned [mem 0x600220000-0x600223fff]
[    1.335982] pci 0000:05:00.0: BAR 2: no space for [io  size 0x0020]
[    1.336018] pci 0000:05:00.0: BAR 2: failed to assign [io  size 0x0020]
[    1.336038] pci 0000:02:06.0: PCI bridge to [bus 05]
[    1.336064] pci 0000:02:06.0:   bridge window [mem 0x600200000-0x6002fffff]
[    1.336104] pci 0000:06:00.0: BAR 0: assigned [mem 0x600300000-0x60030ffff 64bit]
[    1.336151] pci 0000:06:00.0: BAR 2: assigned [mem 0x600310000-0x600311fff 64bit]
[    1.336195] pci 0000:02:0e.0: PCI bridge to [bus 06]
[    1.336221] pci 0000:02:0e.0:   bridge window [mem 0x600300000-0x6003fffff]
[    1.336256] pci 0000:01:00.0: PCI bridge to [bus 02-06]
[    1.336282] pci 0000:01:00.0:   bridge window [mem 0x600000000-0x6003fffff]
[    1.336317] pci 0000:00:00.0: PCI bridge to [bus 01-06]
[    1.336340] pci 0000:00:00.0:   bridge window [mem 0x600000000-0x6003fffff]
[    1.336582] pci 0000:00:00.0: enabling device (0000 -> 0002)
[    1.336625] pci 0000:01:00.0: enabling device (0000 -> 0002)
[    1.336668] pci 0000:02:0e.0: enabling device (0000 -> 0002)
[    1.336704] pci 0000:06:00.0: enabling device (0000 -> 0002)
[    1.337425] bcm2708_fb soc:fb: FB found 1 display(s)
[    1.343417] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480
[    1.348543] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
[    1.351279] iproc-rng200 fe104000.rng: hwrng registered
[    1.351826] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    1.353220] gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000
[    1.356511] cacheinfo: Unable to detect cache hierarchy for CPU 0
[    1.368090] brd: module loaded
[    1.377823] loop: module loaded
[    1.378268] pci 0000:02:00.0: enabling device (0000 -> 0002)
[    1.378316] rtsx_pci 0000:03:00.0: enabling device (0000 -> 0002)
[    1.380178] Loading iSCSI transport class v2.0-870.
[    1.384946] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000
[    1.444177] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus
[    1.445215] igb: Intel(R) Gigabit Ethernet Network Driver
[    1.445234] igb: Copyright (c) 2007-2014 Intel Corporation.
[    1.445667] pci 0000:02:02.0: enabling device (0000 -> 0002)
[    1.445713] igb 0000:04:00.0: enabling device (0000 -> 0002)
[    1.476224] pps pps0: new PPS source ptp0
[    1.476436] igb 0000:04:00.0: added PHC on eth1
[    1.476456] igb 0000:04:00.0: Intel(R) Gigabit Ethernet Network Connection
[    1.476472] igb 0000:04:00.0: eth1: (PCIe:2.5Gb/s:Width x1) 0a:00:00:00:00:00
[    1.476492] igb 0000:04:00.0: eth1: PBA No: FFFFFF-0FF
[    1.476508] igb 0000:04:00.0: Using MSI-X interrupts. 2 rx queue(s), 2 tx queue(s)
[    1.476863] pci 0000:02:06.0: enabling device (0000 -> 0002)
[    1.476907] igb 0000:05:00.0: enabling device (0000 -> 0002)
[    1.507230] pps pps1: new PPS source ptp1
[    1.507433] igb 0000:05:00.0: added PHC on eth2
[    1.507453] igb 0000:05:00.0: Intel(R) Gigabit Ethernet Network Connection
[    1.507470] igb 0000:05:00.0: eth2: (PCIe:2.5Gb/s:Width x1) 0a:00:00:00:00:02
[    1.507489] igb 0000:05:00.0: eth2: PBA No: FFFFFF-0FF
[    1.507504] igb 0000:05:00.0: Using MSI-X interrupts. 2 rx queue(s), 2 tx queue(s)
[    1.507658] usbcore: registered new interface driver lan78xx
[    1.507724] usbcore: registered new interface driver smsc95xx
[    1.508262] xhci_hcd 0000:06:00.0: xHCI Host Controller
[    1.508299] xhci_hcd 0000:06:00.0: new USB bus registered, assigned bus number 1
[    1.508701] xhci_hcd 0000:06:00.0: hcc params 0x0270f06d hci version 0x96 quirks 0x0000000004000000
[    1.510032] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15
[    1.510058] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.510076] usb usb1: Product: xHCI Host Controller
[    1.510093] usb usb1: Manufacturer: Linux 5.15.32-v8 xhci-hcd
[    1.510108] usb usb1: SerialNumber: 0000:06:00.0
[    1.510736] hub 1-0:1.0: USB hub found
[    1.510808] hub 1-0:1.0: 2 ports detected
[    1.511535] xhci_hcd 0000:06:00.0: xHCI Host Controller
[    1.511561] xhci_hcd 0000:06:00.0: new USB bus registered, assigned bus number 2
[    1.511588] xhci_hcd 0000:06:00.0: Host supports USB 3.0 SuperSpeed
[    1.511729] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[    1.512026] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.15
[    1.512050] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.512068] usb usb2: Product: xHCI Host Controller
[    1.512083] usb usb2: Manufacturer: Linux 5.15.32-v8 xhci-hcd
[    1.512099] usb usb2: SerialNumber: 0000:06:00.0
[    1.512702] hub 2-0:1.0: USB hub found
[    1.512770] hub 2-0:1.0: 2 ports detected
[    1.513902] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    1.514188] dwc_otg: FIQ enabled
[    1.514200] dwc_otg: NAK holdoff enabled
[    1.514209] dwc_otg: FIQ split-transaction FSM enabled
[    1.514221] Module dwc_common_port init
[    1.514775] usbcore: registered new interface driver uas
[    1.514869] usbcore: registered new interface driver usb-storage
[    1.515080] mousedev: PS/2 mouse device common for all mice
[    1.520158] sdhci: Secure Digital Host Controller Interface driver
[    1.520181] sdhci: Copyright(c) Pierre Ossman
[    1.520931] sdhci-pltfm: SDHCI platform and OF driver helper
[    1.524320] ledtrig-cpu: registered to indicate activity on CPUs
[    1.524959] hid: raw HID events driver (C) Jiri Kosina
[    1.525121] usbcore: registered new interface driver usbhid
[    1.525139] usbhid: USB HID core driver
[    1.525446] ashmem: initialized
[    1.532530] Initializing XFRM netlink socket
[    1.532585] NET: Registered PF_PACKET protocol family
[    1.532723] Key type dns_resolver registered
[    1.533865] registered taskstats version 1
[    1.533899] Loading compiled-in X.509 certificates
[    1.534910] Key type ._fscrypt registered
[    1.534930] Key type .fscrypt registered
[    1.534943] Key type fscrypt-provisioning registered
[    1.546217] uart-pl011 fe201000.serial: cts_event_workaround enabled
[    1.546388] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 17, base_baud = 0) is a PL011 rev2
[    2.234719] mmc3: cannot verify signal voltage switch
[    2.235060] printk: console [ttyAMA0] enabled
[    2.376166] mmc3: new ultra high speed SDR104 SDXC card at address aaaa
[    2.380459] fe201400.serial: ttyAMA1 at MMIO 0xfe201400 (irq = 17, base_baud = 0) is a PL011 rev2
[    2.384748] mmcblk3: mmc3:aaaa SN256 238 GiB 
[    2.391065] fe201600.serial: ttyAMA2 at MMIO 0xfe201600 (irq = 17, base_baud = 0) is a PL011 rev2
[    2.404444]  mmcblk3: p1
[    2.414626] fe201800.serial: ttyAMA3 at MMIO 0xfe201800 (irq = 17, base_baud = 0) is a PL011 rev2
[    2.432322] mmcblk3: mmc3:aaaa SN256 238 GiB
[    2.448686] fe201a00.serial: ttyAMA4 at MMIO 0xfe201a00 (irq = 17, base_baud = 0) is a PL011 rev2
[    3.547572] pinctrl-bcm2835 fe200000.gpio: pin gpio14 already requested by fe201000.serial; cannot claim for fe215040.serial
[    3.558836] pinctrl-bcm2835 fe200000.gpio: pin-14 (fe215040.serial) status -22
[    3.566081] pinctrl-bcm2835 fe200000.gpio: could not request pin 14 (gpio14) from group gpio14  on device pinctrl-bcm2711
[    3.577062] bcm2835-aux-uart fe215040.serial: Error applying setting, reverse things back
[    3.586323] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    3.593190] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    3.602757] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0
[    3.608800] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated
[    3.644860] of_cfs_init
[    3.648097] of_cfs_init: OK
[    3.686780] mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA
[    3.694688] ALSA device list:
[    3.697739]   No soundcards found.
[    3.701538] uart-pl011 fe201000.serial: no DMA platform data
[    3.707615] Waiting for root device /dev/mmcblk0p2...
[    3.754028] mmc1: new high speed SDIO card at address 0001
[    3.802145] mmc0: new ultra high speed DDR50 SDXC card at address aaaa
[    3.809629] mmcblk0: mmc0:aaaa SD128 119 GiB 
[    3.818336]  mmcblk0: p1 p2 p3 p4 < p5 p6 p7 >
[    3.824258] mmcblk0: mmc0:aaaa SD128 119 GiB
[    3.848668] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
[    3.858578] VFS: Mounted root (ext4 filesystem) readonly on device 179:34.
[    3.866235] devtmpfs: mounted
[    3.876698] Freeing unused kernel memory: 3968K
[    3.881457] Run /sbin/init as init process
[    3.885577]   with arguments:
[    3.885587]     /sbin/init
[    3.885596]   with environment:
[    3.885605]     HOME=/
[    3.885614]     TERM=linux
[    4.396829] EXT4-fs (mmcblk0p6): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
[    4.681825] NET: Registered PF_INET6 protocol family
[    4.688812] Segment Routing with IPv6
[    4.692545] In-situ OAM (IOAM) with IPv6
[    4.724404] systemd[1]: systemd 249.7+ running in system mode (+PAM -AUDIT -SELINUX -APPARMOR +IMA -SMACK +SECCOMP -GCRYPT -GNUTLS -OPENSSL +ACL +BLKID -CURL -ELFUTILS -FIDO2 -IDN2 -IDN -IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK -PCRE2 -PWQUALITY -P11KIT -QRENCODE -BZIP2 -LZ4 -XZ -ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=hybrid)
[    4.755384] systemd[1]: Detected architecture arm64.
[    4.805372] systemd[1]: Hostname set to <cm4>.
[    5.370160] systemd[1]: Queued start job for default target Multi-User System.
[    5.378802] random: systemd: uninitialized urandom read (16 bytes read)
[    5.435787] systemd[1]: Created slice Slice /system/getty.
[    5.456391] random: systemd: uninitialized urandom read (16 bytes read)
[    5.465216] systemd[1]: Created slice Slice /system/modprobe.
[    5.484244] random: systemd: uninitialized urandom read (16 bytes read)
[    5.492960] systemd[1]: Created slice Slice /system/serial-getty.
[    5.518264] systemd[1]: Created slice Slice /system/systemd-fsck.
[    5.542291] systemd[1]: Created slice Slice /system/systemd-growfs.
[    5.565879] systemd[1]: Created slice User and Session Slice.
[    5.588717] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[    5.612587] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    5.636477] systemd[1]: Reached target Local Encrypted Volumes.
[    5.656433] systemd[1]: Reached target Path Units.
[    5.676246] systemd[1]: Reached target Remote Encrypted Volumes.
[    5.696222] systemd[1]: Reached target Remote File Systems.
[    5.716248] systemd[1]: Reached target Slice Units.
[    5.736330] systemd[1]: Reached target Local Verity Protected Volumes.
[    5.761192] systemd[1]: Listening on Syslog Socket.
[    5.780596] systemd[1]: Listening on initctl Compatibility Named Pipe.
[    5.805347] systemd[1]: Listening on Journal Audit Socket.
[    5.824869] systemd[1]: Listening on Journal Socket (/dev/log).
[    5.849132] systemd[1]: Listening on Journal Socket.
[    5.869258] systemd[1]: Listening on Network Service Netlink Socket.
[    5.893108] systemd[1]: Listening on udev Control Socket.
[    5.912833] systemd[1]: Listening on udev Kernel Socket.
[    5.932843] systemd[1]: Listening on User Database Manager Socket.
[    5.957042] systemd[1]: Condition check resulted in Huge Pages File System being skipped.
[    5.970094] systemd[1]: Mounting POSIX Message Queue File System...
[    5.997417] systemd[1]: Mounting Kernel Debug File System...
[    6.021340] systemd[1]: Mounting Kernel Trace File System...
[    6.046320] systemd[1]: Starting Create List of Static Device Nodes...
[    6.073598] systemd[1]: Starting Load Kernel Module configfs...
[    6.097368] systemd[1]: Starting Load Kernel Module drm...
[    6.121691] systemd[1]: Starting Load Kernel Module fuse...
[    6.140825] fuse: init (API version 7.34)
[    6.149838] systemd[1]: Starting Journal Service...
[    6.176525] systemd[1]: Starting Load Kernel Modules...
[    6.201743] systemd[1]: Starting Remount Root and Kernel File Systems...
[    6.205079] r8152: loading out-of-tree module taints kernel.
[    6.218718] usbcore: registered new interface driver r8152
[    6.228193] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null). Quota mode: none.
[    6.242414] systemd[1]: Starting Coldplug All udev Devices...
[    6.277704] systemd[1]: Mounted POSIX Message Queue File System.
[    6.301082] systemd[1]: Mounted Kernel Debug File System.
[    6.321128] systemd[1]: Mounted Kernel Trace File System.
[    6.343357] systemd[1]: Finished Create List of Static Device Nodes.
[    6.366128] systemd[1]: [email protected]: Deactivated successfully.
[    6.375755] systemd[1]: Finished Load Kernel Module configfs.
[    6.398413] systemd[1]: [email protected]: Deactivated successfully.
[    6.407197] systemd[1]: Finished Load Kernel Module drm.
[    6.430759] systemd[1]: [email protected]: Deactivated successfully.
[    6.439532] systemd[1]: Finished Load Kernel Module fuse.
[    6.463650] systemd[1]: Finished Load Kernel Modules.
[    6.487177] systemd[1]: Finished Remount Root and Kernel File Systems.
[    6.508981] systemd[1]: Started Journal Service.
[    6.607180] systemd-journald[139]: Received client request to flush runtime journal.
[    6.794741] audit: type=1334 audit(6.787:2): prog-id=5 op=LOAD
[    6.801378] audit: type=1334 audit(6.787:3): prog-id=6 op=LOAD
[    7.295639] bcmgenet fd580000.ethernet ethsensor1: renamed from eth0
[    7.337516] rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000
[    7.376487] rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000
[    7.401408] igb 0000:04:00.0 ethsensor0: renamed from eth1
[    7.459612] rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000
[    7.487217] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[    7.495531] rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000
[    7.510297] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[    7.519960] [vc_sm_connected_init]: start
[    7.549130] mc: Linux media interface: v0.10
[    7.563510] [vc_sm_connected_init]: installed successfully
[    7.594643] igb 0000:05:00.0 ethsensor2: renamed from eth2
[    7.614122] Adding 4095996k swap on /dev/mmcblk0p5.  Priority:-2 extents:1 across:4095996k SSFS
[    7.657009] dwc2 fe980000.usb: supply vusb_d not found, using dummy regulator
[    7.678625] dwc2 fe980000.usb: supply vusb_a not found, using dummy regulator
[    7.689101] videodev: Linux video capture interface: v2.00
[    7.752080] dwc2 fe980000.usb: DWC OTG Controller
[    7.757411] dwc2 fe980000.usb: new USB bus registered, assigned bus number 3
[    7.765195] dwc2 fe980000.usb: irq 20, io mem 0xfe980000
[    7.772067] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15
[    7.780493] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    7.787800] usb usb3: Product: DWC OTG Controller
[    7.792649] usb usb3: Manufacturer: Linux 5.15.32-v8 dwc2_hsotg
[    7.799182] usb usb3: SerialNumber: fe980000.usb
[    7.814394] i2c i2c-22: Added multiplexed i2c bus 0
[    7.821848] hub 3-0:1.0: USB hub found
[    7.827231] hub 3-0:1.0: 1 port detected
[    7.859406] i2c i2c-22: Added multiplexed i2c bus 10
[    7.874625] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    7.875405] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[    7.900554] input: soc:shutdown_button@3 as /devices/platform/soc/soc:shutdown_button@3/input/input0
[    7.921368] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[    7.944758] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[    7.962373] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[    7.980649] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[    8.107342] random: crng init done
[    8.110762] random: 7 urandom warning(s) missed due to ratelimiting
[    8.379418] irq 20: nobody cared (try booting with the "irqpoll" option)
[    8.386127] CPU: 0 PID: 175 Comm: systemd-udevd Tainted: G         C O      5.15.32-v8 #1
[    8.394309] Hardware name: Raspberry Pi Compute Module 4 Rev 1.0 (DT)
[    8.400747] Call trace:
[    8.403188]  dump_backtrace+0x0/0x1b0
[    8.406868]  show_stack+0x24/0x30
[    8.410189]  dump_stack_lvl+0x8c/0xb8
[    8.413855]  dump_stack+0x18/0x34
[    8.417169]  __report_bad_irq+0x54/0xe4
[    8.421004]  note_interrupt+0x32c/0x3ec
[    8.424843]  handle_irq_event+0xdc/0x15c
[    8.428766]  handle_fasteoi_irq+0xcc/0x230
[    8.432863]  handle_domain_irq+0xa4/0xf0
[    8.436785]  gic_handle_irq+0x58/0xe0
[    8.440446]  do_interrupt_handler+0x3c/0x70
[    8.444631]  el1_interrupt+0x30/0x80
[    8.448211]  el1h_64_irq_handler+0x18/0x24
[    8.452306]  el1h_64_irq+0x78/0x7c
[    8.455706]  __do_softirq+0x118/0x504
[    8.459366]  __irq_exit_rcu+0xd0/0x114
[    8.463118]  irq_exit+0x1c/0x54
[    8.466261]  handle_domain_irq+0xa8/0xf0
[    8.470183]  gic_handle_irq+0x58/0xe0
[    8.473843]  call_on_irq_stack+0x2c/0x5c
[    8.477766]  do_interrupt_handler+0x5c/0x70
[    8.481951]  el1_interrupt+0x30/0x80
[    8.485530]  el1h_64_irq_handler+0x18/0x24
[    8.489625]  el1h_64_irq+0x78/0x7c
[    8.493024]  _raw_spin_unlock_irqrestore+0x34/0x74
[    8.497819]  rwsem_wake.isra.0+0x78/0xb0
[    8.501747]  up_write+0x44/0x50
[    8.504891]  kernfs_add_one+0xd0/0x140
[    8.508647]  kernfs_create_dir_ns+0x94/0xe0
[    8.512833]  internal_create_group+0x2b4/0x3d0
[    8.517277]  sysfs_create_group+0x24/0x30
[    8.521285]  load_module+0x2548/0x28d0
[    8.525035]  __do_sys_finit_module+0xa4/0xec
[    8.529305]  __arm64_sys_finit_module+0x2c/0x40
[    8.533835]  invoke_syscall+0x50/0x120
[    8.537587]  el0_svc_common.constprop.0+0x104/0x124
[    8.542468]  do_el0_svc+0x34/0x9c
[    8.545785]  el0_svc+0x2c/0x90
[    8.548843]  el0t_64_sync_handler+0xa4/0x130
[    8.553112]  el0t_64_sync+0x1a0/0x1a4
[    8.556773] handlers:
[    8.559040] [<000000007df74e85>] dwc2_handle_common_intr [dwc2]
[    8.565006] [<00000000282e2e1c>] usb_hcd_irq
[    8.569282] Disabling IRQ #20
[    8.598325] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[    8.632147] usb 3-1: new high-speed USB device number 2 using dwc2
[    8.645171] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[    8.649251] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[    8.661152] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[    8.674575] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[    8.674898] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[    8.695107] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[    8.709531] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[    8.709587] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[    8.711807] bcm2835-codec bcm2835-codec: Device registered as /dev/video18
[    8.730230] bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx
[    8.744642] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[    8.752134] bcm2835-codec bcm2835-codec: Device registered as /dev/video31
[    8.761207] bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image
[    8.780084] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    8.789795] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    8.802180] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    8.812118] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    8.845906] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20
[    8.856912] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21
[    8.867511] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22
[    8.891367] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23
[    8.922063] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    8.932498] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    8.944572] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    8.952586] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    8.962780] brcmfmac: F1 signature read @0x18000000=0x15264345
[    9.008607] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    9.016443] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[    9.040979] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,4-compute-module.bin failed with error -2
[    9.079116] usbcore: registered new interface driver brcmfmac
[    9.140574] rtc-pcf85063 10-0051: registered as rtc0
[    9.146935] rtc-pcf85063 10-0051: setting system clock to 2022-06-17T15:59:00 UTC (1655481540)
[    9.321278] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    9.331320] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    9.558868] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Jan  4 2021 19:56:29 version 7.45.229 (617f1f5 CY) FWID 01-2dbd9d2e
[    9.630767] EXT4-fs (mmcblk0p7): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
[    9.797039] EXT4-fs (mmcblk0p7): resizing filesystem from 96196608 to 96196608 blocks
[   13.984092] usb 3-1: device descriptor read/64, error -110
[   29.600048] usb 3-1: device descriptor read/64, error -110
[   29.900038] usb 3-1: new high-speed USB device number 3 using dwc2
[   33.764069] cam-dummy-reg: disabling
[   35.232044] usb 3-1: device descriptor read/64, error -110
[   50.848045] usb 3-1: device descriptor read/64, error -110
[   50.960109] usb usb3-port1: attempt power cycle
[   52.031901] audit: type=1334 audit(1655481583.379:4): prog-id=7 op=LOAD
[   52.038701] audit: type=1334 audit(1655481583.383:5): prog-id=8 op=LOAD
[   52.365963] audit: type=1325 audit(1655481583.703:6): table=filter family=2 entries=0 op=xt_register pid=290 comm="iptables-restor"
[   52.381727] audit: type=1300 audit(1655481583.703:6): arch=c00000b7 syscall=209 success=yes exit=0 a0=5 a1=0 a2=40 a3=7fccc3fd80 items=0 ppid=1 pid=290 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables-restor" exe="/usr/sbin/xtables-legacy-multi" key=(null)
[   52.412319] audit: type=1327 audit(1655481583.703:6): proctitle=2F7573722F7362696E2F69707461626C65732D726573746F7265002D77002D2D002F6574632F69707461626C65732F69707461626C65732E72756C6573
[   52.429814] audit: type=1325 audit(1655481583.715:7): table=filter family=2 entries=4 op=xt_replace pid=290 comm="iptables-restor"
[   52.441779] audit: type=1300 audit(1655481583.715:7): arch=c00000b7 syscall=208 success=yes exit=0 a0=5 a1=0 a2=40 a3=55bd48eb10 items=0 ppid=1 pid=290 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables-restor" exe="/usr/sbin/xtables-legacy-multi" key=(null)
[   52.496192] audit: type=1327 audit(1655481583.715:7): proctitle=2F7573722F7362696E2F69707461626C65732D726573746F7265002D77002D2D002F6574632F69707461626C65732F69707461626C65732E72756C6573
[   52.524539] audit: type=1325 audit(1655481583.715:8): table=nat family=2 entries=0 op=xt_register pid=290 comm="iptables-restor"
[   52.536234] audit: type=1300 audit(1655481583.715:8): arch=c00000b7 syscall=209 success=yes exit=0 a0=5 a1=0 a2=40 a3=7fccc3fd80 items=0 ppid=1 pid=290 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables-restor" exe="/usr/sbin/xtables-legacy-multi" key=(null)
[   52.751877] exFAT-fs (mmcblk3p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
[   53.257115] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
[   53.336854] bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay)
[   53.345492] bcmgenet fd580000.ethernet ethsensor1: Link is Down
[   54.321896] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   54.325076] lo ate my IP address

Additional context

No response

[pelwell]

The problem reported by the kernel is that there is an interrupt that "nobody care[s]" about:

[    8.379418] irq 20: nobody cared (try booting with the "irqpoll" option)
...
[    8.417169]  __report_bad_irq+0x54/0xe4
[    8.421004]  note_interrupt+0x32c/0x3ec
[    8.424843]  handle_irq_event+0xdc/0x15c
[    8.428766]  handle_fasteoi_irq+0xcc/0x230
[    8.432863]  handle_domain_irq+0xa4/0xf0
[    8.436785]  gic_handle_irq+0x58/0xe0
[    8.440446]  do_interrupt_handler+0x3c/0x70
[    8.444631]  el1_interrupt+0x30/0x80
[    8.448211]  el1h_64_irq_handler+0x18/0x24
[    8.452306]  el1h_64_irq+0x78/0x7c
[    8.455706]  __do_softirq+0x118/0x504
[    8.459366]  __irq_exit_rcu+0xd0/0x114
[    8.463118]  irq_exit+0x1c/0x54
[    8.466261]  handle_domain_irq+0xa8/0xf0
[    8.470183]  gic_handle_irq+0x58/0xe0
[    8.473843]  call_on_irq_stack+0x2c/0x5c
[    8.477766]  do_interrupt_handler+0x5c/0x70
[    8.481951]  el1_interrupt+0x30/0x80
[    8.485530]  el1h_64_irq_handler+0x18/0x24
[    8.489625]  el1h_64_irq+0x78/0x7c
...
[    8.556773] handlers:
[    8.559040] [<000000007df74e85>] dwc2_handle_common_intr [dwc2]
[    8.565006] [<00000000282e2e1c>] usb_hcd_irq
[    8.569282] Disabling IRQ #20

I think this means that either there was no handler for the interrupt or that any handlers returned IRQ_NONE (not mine). Earlier in the log we can see that IRQ 20 is the dwc2 USB interrupt:

[    7.765195] dwc2 fe980000.usb: irq 20, io mem 0xfe980000

and the list of handlers includes "dwc2_handle_common_intr". Without reading the driver I would guess that is in an unexpected kind of interrupt, either because it is a feature that isn't being used or because the driver just isn't ready to receive one at that time (both of which sound likes bugs or major limitations).

[P33M]

Are you specifying the dr_mode=host property for dwc2 in /boot/config.txt?

[pelwell]

i.e. dtoverlay=dwc2,dr_mode=host

[teletypo]

Thank you for the quick help, everybody! Here's the config.txt:

# cat /uboot/config.txt 
[cm4]
dtoverlay=pcie-32bit-dma
dtoverlay=dwc2,dr_mode=host
dtoverlay=gpio-shutdown,gpio_pin=3
hdmi_force_hotplug=1
hdmi_mode=16
gpio=17=op,dh
dtparam=i2c_vc=on
dtoverlay=i2c-rtc,pcf85063a,i2c_csi_dsi
dtparam=ant2
dtoverlay=uart0
dtoverlay=uart1
dtoverlay=uart2
dtoverlay=uart3
dtoverlay=uart4
dtoverlay=uart5
enable_uart=1

Bootloader version:

# vcgencmd bootloader_version
Feb 16 2021 13:23:36
version d6d82cf99bcb3e9a166a34cfde53130957a36bd3 (release)
timestamp 1613481816
update-time 1613481816
capabilities 0x0000001f

Here's the dmesg from a happy boot just 10 minutes after the problem showed, no changes were done to the system. I overwrote some details with ....:

[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd083]
[    0.000000] Linux version 5.15.32-v8 (oe-user@oe-host) (aarch64-poky-linux-gcc (GCC) 11.2.0, GNU ld (GNU Binutils) 2.37.20210721) #1 SMP PREEMPT Tue Apr 5 18:36:06 UTC 2022
[    0.000000] random: fast init done
[    0.000000] Machine model: Raspberry Pi Compute Module 4 Rev 1.0
[    0.000000] efi: UEFI not found.
[    0.000000] Reserved memory: created CMA memory pool at 0x000000002ac00000, size 64 MiB
[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000000000-0x000000003fffffff]
[    0.000000]   DMA32    [mem 0x0000000040000000-0x00000000fbffffff]
[    0.000000]   Normal   empty
[    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 29 pages/cpu s78232 r8192 d32360 u118784
[    0.000000] pcpu-alloc: s78232 r8192 d32360 u118784 alloc=29*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
[    0.000000] Detected PIPT I-cache on CPU0
[    0.000000] CPU features: detected: Spectre-v2
[    0.000000] CPU features: detected: Spectre-v4
[    0.000000] CPU features: detected: Spectre-BHB
[    0.000000] CPU features: detected: ARM errata 1165522, 1319367, or 1530923
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 996912
[    0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=1 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 8250.nr_uarts=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=... vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  dwc_otg.lpm_enable=0 rootfstype=ext4 rootwait root=/dev/mmcblk0p2 logo.nologo console=ttyAMA0,115200
[    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
[    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] software IO TLB: mapped [mem 0x0000000037400000-0x000000003b400000] (64MB)
[    0.000000] Memory: 3814172K/4050944K available (12800K kernel code, 2002K rwdata, 4616K rodata, 3968K init, 975K bss, 171236K reserved, 65536K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 40528 entries in 159 pages
[    0.000000] ftrace: allocated 159 pages with 6 groups
[    0.000000] trace event string verifier disabled
[    0.000000] rcu: Preemptible hierarchical RCU implementation.
[    0.000000] rcu: 	RCU event tracing is enabled.
[    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4.
[    0.000000] 	Trampoline variant of Tasks RCU enabled.
[    0.000000] 	Rude variant of Tasks RCU enabled.
[    0.000000] 	Tracing variant of Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] Root IRQ handler: gic_handle_irq
[    0.000000] GIC: Using split EOI/Deactivate mode
[    0.000000] random: get_random_bytes called from start_kernel+0x510/0x704 with crng_init=1
[    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.000248] Console: colour dummy device 80x25
[    0.000326] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000)
[    0.000356] pid_max: default: 32768 minimum: 301
[    0.000492] LSM: Security Framework initializing
[    0.000670] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.000734] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.001957] cgroup: Disabling memory control group subsystem
[    0.004347] rcu: Hierarchical SRCU implementation.
[    0.005408] EFI services will not be available.
[    0.005935] smp: Bringing up secondary CPUs ...
[    0.006986] Detected PIPT I-cache on CPU1
[    0.007058] CPU1: Booted secondary processor 0x0000000001 [0x410fd083]
[    0.008280] Detected PIPT I-cache on CPU2
[    0.008330] CPU2: Booted secondary processor 0x0000000002 [0x410fd083]
[    0.009466] Detected PIPT I-cache on CPU3
[    0.009514] CPU3: Booted secondary processor 0x0000000003 [0x410fd083]
[    0.009693] smp: Brought up 1 node, 4 CPUs
[    0.009738] SMP: Total of 4 processors activated.
[    0.009753] CPU features: detected: 32-bit EL0 Support
[    0.009765] CPU features: detected: 32-bit EL1 Support
[    0.009781] CPU features: detected: CRC32 instructions
[    0.042406] CPU: All CPU(s) started at EL2
[    0.042491] alternatives: patching kernel code
[    0.044091] devtmpfs: initialized
[    0.054997] Enabled cp15_barrier support
[    0.055045] Enabled setend support
[    0.055365] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.055401] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.057493] pinctrl core: initialized pinctrl subsystem
[    0.058260] DMI not present or invalid.
[    0.058765] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.062160] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations
[    0.062469] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    0.063346] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    0.063444] audit: initializing netlink subsys (disabled)
[    0.063716] audit: type=2000 audit(0.060:1): state=initialized audit_enabled=0 res=1
[    0.064241] thermal_sys: Registered thermal governor 'step_wise'
[    0.064469] cpuidle: using governor menu
[    0.064685] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.064867] ASID allocator initialised with 65536 entries
[    0.065023] Serial: AMBA PL011 UART driver
[    0.076766] bcm2835-mbox fe00b880.mailbox: mailbox enabled
[    0.104285] raspberrypi-firmware soc:firmware: Attached to firmware from 2021-09-30T19:21:03, variant start
[    0.108304] raspberrypi-firmware soc:firmware: Firmware hash is 51215b4f6e3966401ecd99652a35cf1c25069113
[    0.156908] bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1
[    0.160933] vgaarb: loaded
[    0.161349] SCSI subsystem initialized
[    0.161556] usbcore: registered new interface driver usbfs
[    0.161612] usbcore: registered new interface driver hub
[    0.161678] usbcore: registered new device driver usb
[    0.162008] usb_phy_generic phy: supply vcc not found, using dummy regulator
[    0.162500] pps_core: LinuxPPS API ver. 1 registered
[    0.162518] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <[email protected]>
[    0.162554] PTP clock support registered
[    0.162785] Advanced Linux Sound Architecture Driver Initialized.
[    0.163923] clocksource: Switched to clocksource arch_sys_counter
[    0.256473] VFS: Disk quotas dquot_6.6.0
[    0.256590] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.256772] FS-Cache: Loaded
[    0.256999] CacheFiles: Loaded
[    0.257233] simple-framebuffer 3ea9b000.framebuffer: framebuffer at 0x3ea9b000, 0x12c000 bytes
[    0.257257] simple-framebuffer 3ea9b000.framebuffer: format=a8r8g8b8, mode=640x480x32, linelength=2560
[    0.259325] Console: switching to colour frame buffer device 80x30
[    0.261035] simple-framebuffer 3ea9b000.framebuffer: fb0: simplefb registered!
[    0.271672] NET: Registered PF_INET protocol family
[    0.272180] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)
[    0.275553] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)
[    0.275613] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)
[    0.275826] TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear)
[    0.276333] TCP: Hash tables configured (established 32768 bind 32768)
[    0.276559] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)
[    0.276641] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)
[    0.276939] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    0.277880] RPC: Registered named UNIX socket transport module.
[    0.277903] RPC: Registered udp transport module.
[    0.277917] RPC: Registered tcp transport module.
[    0.277930] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.277952] PCI: CLS 0 bytes, default 64
[    0.280038] hw perfevents: enabled with armv8_cortex_a72 PMU driver, 7 counters available
[    0.280365] kvm [1]: IPA Size Limit: 44 bits
[    0.281585] kvm [1]: vgic interrupt IRQ9
[    0.281847] kvm [1]: Hyp mode initialized successfully
[    1.178523] Initialise system trusted keyrings
[    1.178874] workingset: timestamp_bits=46 max_order=20 bucket_order=0
[    1.185733] zbud: loaded
[    1.187508] FS-Cache: Netfs 'nfs' registered for caching
[    1.188244] NFS: Registering the id_resolver key type
[    1.188291] Key type id_resolver registered
[    1.188306] Key type id_legacy registered
[    1.188406] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.188424] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    1.189584] Key type asymmetric registered
[    1.189605] Asymmetric key parser 'x509' registered
[    1.189678] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246)
[    1.189930] io scheduler mq-deadline registered
[    1.189950] io scheduler kyber registered
[    1.197294] gpio-507 (ant1): hogged as output/low
[    1.198675] gpio-511 (ant2): hogged as output/high
[    1.199893] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
[    1.199956] brcm-pcie fd500000.pcie:   No bus range found for /scb/pcie@7d500000, using [bus 00-ff]
[    1.200047] brcm-pcie fd500000.pcie:      MEM 0x0600000000..0x063fffffff -> 0x00c0000000
[    1.200133] brcm-pcie fd500000.pcie:   IB MEM 0x0000000000..0x007fffffff -> 0x0000000000
[    1.250023] brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC)
[    1.250409] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
[    1.250432] pci_bus 0000:00: root bus resource [bus 00-ff]
[    1.250454] pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff])
[    1.250551] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
[    1.250800] pci 0000:00:00.0: PME# supported from D0 D3hot
[    1.254745] pci 0000:01:00.0: [1b21:1806] type 01 class 0x060400
[    1.254895] pci 0000:01:00.0: enabling Extended Tags
[    1.255146] pci 0000:01:00.0: PME# supported from D0 D3hot D3cold
[    1.255229] pci 0000:01:00.0: 4.000 Gb/s available PCIe bandwidth, limited by 5.0 GT/s PCIe x1 link at 0000:00:00.0 (capable of 63.008 Gb/s with 8.0 GT/s PCIe x8 link)
[    1.267273] pci 0000:01:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[    1.267546] pci 0000:02:00.0: [1b21:1806] type 01 class 0x060400
[    1.267696] pci 0000:02:00.0: enabling Extended Tags
[    1.267978] pci 0000:02:00.0: PME# supported from D0 D3hot D3cold
[    1.268550] pci 0000:02:02.0: [1b21:1806] type 01 class 0x060400
[    1.268700] pci 0000:02:02.0: enabling Extended Tags
[    1.268957] pci 0000:02:02.0: PME# supported from D0 D3hot D3cold
[    1.269692] pci 0000:02:06.0: [1b21:1806] type 01 class 0x060400
[    1.269841] pci 0000:02:06.0: enabling Extended Tags
[    1.270106] pci 0000:02:06.0: PME# supported from D0 D3hot D3cold
[    1.271280] pci 0000:02:0e.0: [1b21:1806] type 01 class 0x060400
[    1.271428] pci 0000:02:0e.0: enabling Extended Tags
[    1.271687] pci 0000:02:0e.0: PME# supported from D0 D3hot D3cold
[    1.273860] pci 0000:02:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[    1.273899] pci 0000:02:02.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[    1.273933] pci 0000:02:06.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[    1.273967] pci 0000:02:0e.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[    1.274238] pci 0000:03:00.0: [10ec:5261] type 00 class 0xff0000
[    1.274305] pci 0000:03:00.0: reg 0x10: [mem 0x00000000-0x00000fff]
[    1.274698] pci 0000:03:00.0: supports D1 D2
[    1.274714] pci 0000:03:00.0: PME# supported from D1 D2 D3hot D3cold
[    1.287277] pci_bus 0000:03: busn_res: [bus 03-06] end is updated to 03
[    1.287562] pci 0000:04:00.0: [8086:1539] type 00 class 0x020000
[    1.287632] pci 0000:04:00.0: reg 0x10: [mem 0x00000000-0x0001ffff]
[    1.287693] pci 0000:04:00.0: reg 0x18: [io  0x0000-0x001f]
[    1.287733] pci 0000:04:00.0: reg 0x1c: [mem 0x00000000-0x00003fff]
[    1.288094] pci 0000:04:00.0: PME# supported from D0 D3hot D3cold
[    1.303248] pci_bus 0000:04: busn_res: [bus 04-06] end is updated to 04
[    1.303540] pci 0000:05:00.0: [8086:1539] type 00 class 0x020000
[    1.303609] pci 0000:05:00.0: reg 0x10: [mem 0x00000000-0x0001ffff]
[    1.303670] pci 0000:05:00.0: reg 0x18: [io  0x0000-0x001f]
[    1.303709] pci 0000:05:00.0: reg 0x1c: [mem 0x00000000-0x00003fff]
[    1.304071] pci 0000:05:00.0: PME# supported from D0 D3hot D3cold
[    1.319225] pci_bus 0000:05: busn_res: [bus 05-06] end is updated to 05
[    1.319492] pci 0000:06:00.0: [104c:8241] type 00 class 0x0c0330
[    1.319570] pci 0000:06:00.0: reg 0x10: [mem 0x00000000-0x0000ffff 64bit]
[    1.319622] pci 0000:06:00.0: reg 0x18: [mem 0x00000000-0x00001fff 64bit]
[    1.319920] pci 0000:06:00.0: supports D1 D2
[    1.319939] pci 0000:06:00.0: PME# supported from D0 D1 D2 D3hot
[    1.335255] pci_bus 0000:06: busn_res: [bus 06] end is updated to 06
[    1.335289] pci_bus 0000:02: busn_res: [bus 02-06] end is updated to 06
[    1.335369] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6003fffff]
[    1.335395] pci 0000:01:00.0: BAR 8: assigned [mem 0x600000000-0x6003fffff]
[    1.335413] pci 0000:01:00.0: BAR 7: no space for [io  size 0x2000]
[    1.335430] pci 0000:01:00.0: BAR 7: failed to assign [io  size 0x2000]
[    1.335452] pci 0000:02:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
[    1.335471] pci 0000:02:02.0: BAR 8: assigned [mem 0x600100000-0x6001fffff]
[    1.335490] pci 0000:02:06.0: BAR 8: assigned [mem 0x600200000-0x6002fffff]
[    1.335508] pci 0000:02:0e.0: BAR 8: assigned [mem 0x600300000-0x6003fffff]
[    1.335525] pci 0000:02:02.0: BAR 7: no space for [io  size 0x1000]
[    1.335541] pci 0000:02:02.0: BAR 7: failed to assign [io  size 0x1000]
[    1.335558] pci 0000:02:06.0: BAR 7: no space for [io  size 0x1000]
[    1.335574] pci 0000:02:06.0: BAR 7: failed to assign [io  size 0x1000]
[    1.335594] pci 0000:03:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff]
[    1.335622] pci 0000:02:00.0: PCI bridge to [bus 03]
[    1.335649] pci 0000:02:00.0:   bridge window [mem 0x600000000-0x6000fffff]
[    1.335689] pci 0000:04:00.0: BAR 0: assigned [mem 0x600100000-0x60011ffff]
[    1.335717] pci 0000:04:00.0: BAR 3: assigned [mem 0x600120000-0x600123fff]
[    1.335743] pci 0000:04:00.0: BAR 2: no space for [io  size 0x0020]
[    1.335759] pci 0000:04:00.0: BAR 2: failed to assign [io  size 0x0020]
[    1.335777] pci 0000:02:02.0: PCI bridge to [bus 04]
[    1.335803] pci 0000:02:02.0:   bridge window [mem 0x600100000-0x6001fffff]
[    1.335841] pci 0000:05:00.0: BAR 0: assigned [mem 0x600200000-0x60021ffff]
[    1.335868] pci 0000:05:00.0: BAR 3: assigned [mem 0x600220000-0x600223fff]
[    1.335894] pci 0000:05:00.0: BAR 2: no space for [io  size 0x0020]
[    1.335931] pci 0000:05:00.0: BAR 2: failed to assign [io  size 0x0020]
[    1.335950] pci 0000:02:06.0: PCI bridge to [bus 05]
[    1.335975] pci 0000:02:06.0:   bridge window [mem 0x600200000-0x6002fffff]
[    1.336015] pci 0000:06:00.0: BAR 0: assigned [mem 0x600300000-0x60030ffff 64bit]
[    1.336062] pci 0000:06:00.0: BAR 2: assigned [mem 0x600310000-0x600311fff 64bit]
[    1.336106] pci 0000:02:0e.0: PCI bridge to [bus 06]
[    1.336132] pci 0000:02:0e.0:   bridge window [mem 0x600300000-0x6003fffff]
[    1.336167] pci 0000:01:00.0: PCI bridge to [bus 02-06]
[    1.336193] pci 0000:01:00.0:   bridge window [mem 0x600000000-0x6003fffff]
[    1.336228] pci 0000:00:00.0: PCI bridge to [bus 01-06]
[    1.336251] pci 0000:00:00.0:   bridge window [mem 0x600000000-0x6003fffff]
[    1.336492] pci 0000:00:00.0: enabling device (0000 -> 0002)
[    1.336534] pci 0000:01:00.0: enabling device (0000 -> 0002)
[    1.336577] pci 0000:02:0e.0: enabling device (0000 -> 0002)
[    1.336612] pci 0000:06:00.0: enabling device (0000 -> 0002)
[    1.337333] bcm2708_fb soc:fb: FB found 1 display(s)
[    1.343314] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480
[    1.348386] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
[    1.351131] iproc-rng200 fe104000.rng: hwrng registered
[    1.351671] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    1.353068] gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000
[    1.356358] cacheinfo: Unable to detect cache hierarchy for CPU 0
[    1.367850] brd: module loaded
[    1.377605] loop: module loaded
[    1.378050] pci 0000:02:00.0: enabling device (0000 -> 0002)
[    1.378098] rtsx_pci 0000:03:00.0: enabling device (0000 -> 0002)
[    1.379938] Loading iSCSI transport class v2.0-870.
[    1.384708] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000
[    1.444085] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus
[    1.445128] igb: Intel(R) Gigabit Ethernet Network Driver
[    1.445148] igb: Copyright (c) 2007-2014 Intel Corporation.
[    1.445580] pci 0000:02:02.0: enabling device (0000 -> 0002)
[    1.445626] igb 0000:04:00.0: enabling device (0000 -> 0002)
[    1.476131] pps pps0: new PPS source ptp0
[    1.476349] igb 0000:04:00.0: added PHC on eth1
[    1.476369] igb 0000:04:00.0: Intel(R) Gigabit Ethernet Network Connection
[    1.476386] igb 0000:04:00.0: eth1: (PCIe:2.5Gb/s:Width x1) 0a:00:00:00:00:00
[    1.476407] igb 0000:04:00.0: eth1: PBA No: FFFFFF-0FF
[    1.476423] igb 0000:04:00.0: Using MSI-X interrupts. 2 rx queue(s), 2 tx queue(s)
[    1.476770] pci 0000:02:06.0: enabling device (0000 -> 0002)
[    1.476813] igb 0000:05:00.0: enabling device (0000 -> 0002)
[    1.507145] pps pps1: new PPS source ptp1
[    1.507350] igb 0000:05:00.0: added PHC on eth2
[    1.507370] igb 0000:05:00.0: Intel(R) Gigabit Ethernet Network Connection
[    1.507386] igb 0000:05:00.0: eth2: (PCIe:2.5Gb/s:Width x1) 0a:00:00:00:00:02
[    1.507405] igb 0000:05:00.0: eth2: PBA No: FFFFFF-0FF
[    1.507421] igb 0000:05:00.0: Using MSI-X interrupts. 2 rx queue(s), 2 tx queue(s)
[    1.507575] usbcore: registered new interface driver lan78xx
[    1.507642] usbcore: registered new interface driver smsc95xx
[    1.508183] xhci_hcd 0000:06:00.0: xHCI Host Controller
[    1.508228] xhci_hcd 0000:06:00.0: new USB bus registered, assigned bus number 1
[    1.508620] xhci_hcd 0000:06:00.0: hcc params 0x0270f06d hci version 0x96 quirks 0x0000000004000000
[    1.509964] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15
[    1.509990] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.510008] usb usb1: Product: xHCI Host Controller
[    1.510025] usb usb1: Manufacturer: Linux 5.15.32-v8 xhci-hcd
[    1.510040] usb usb1: SerialNumber: 0000:06:00.0
[    1.510655] hub 1-0:1.0: USB hub found
[    1.510727] hub 1-0:1.0: 2 ports detected
[    1.511460] xhci_hcd 0000:06:00.0: xHCI Host Controller
[    1.511486] xhci_hcd 0000:06:00.0: new USB bus registered, assigned bus number 2
[    1.511514] xhci_hcd 0000:06:00.0: Host supports USB 3.0 SuperSpeed
[    1.511663] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[    1.511934] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.15
[    1.511958] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.511975] usb usb2: Product: xHCI Host Controller
[    1.511991] usb usb2: Manufacturer: Linux 5.15.32-v8 xhci-hcd
[    1.512007] usb usb2: SerialNumber: 0000:06:00.0
[    1.512609] hub 2-0:1.0: USB hub found
[    1.512676] hub 2-0:1.0: 2 ports detected
[    1.513812] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    1.514101] dwc_otg: FIQ enabled
[    1.514111] dwc_otg: NAK holdoff enabled
[    1.514121] dwc_otg: FIQ split-transaction FSM enabled
[    1.514133] Module dwc_common_port init
[    1.514675] usbcore: registered new interface driver uas
[    1.514768] usbcore: registered new interface driver usb-storage
[    1.514979] mousedev: PS/2 mouse device common for all mice
[    1.520043] sdhci: Secure Digital Host Controller Interface driver
[    1.520067] sdhci: Copyright(c) Pierre Ossman
[    1.520815] sdhci-pltfm: SDHCI platform and OF driver helper
[    1.524210] ledtrig-cpu: registered to indicate activity on CPUs
[    1.524832] hid: raw HID events driver (C) Jiri Kosina
[    1.524996] usbcore: registered new interface driver usbhid
[    1.525014] usbhid: USB HID core driver
[    1.525318] ashmem: initialized
[    1.532423] Initializing XFRM netlink socket
[    1.532482] NET: Registered PF_PACKET protocol family
[    1.532611] Key type dns_resolver registered
[    1.533751] registered taskstats version 1
[    1.533786] Loading compiled-in X.509 certificates
[    1.534759] Key type ._fscrypt registered
[    1.534779] Key type .fscrypt registered
[    1.534793] Key type fscrypt-provisioning registered
[    1.546094] uart-pl011 fe201000.serial: cts_event_workaround enabled
[    1.546265] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 17, base_baud = 0) is a PL011 rev2
[    2.220788] mmc3: cannot verify signal voltage switch
[    2.228711] printk: console [ttyAMA0] enabled
[    2.367497] mmc3: new ultra high speed SDR104 SDXC card at address aaaa
[    2.371052] fe201400.serial: ttyAMA1 at MMIO 0xfe201400 (irq = 17, base_baud = 0) is a PL011 rev2
[    2.376414] mmcblk3: mmc3:aaaa SN256 238 GiB 
[    2.380267] fe201600.serial: ttyAMA2 at MMIO 0xfe201600 (irq = 17, base_baud = 0) is a PL011 rev2
[    2.392797]  mmcblk3: p1
[    2.407105] fe201800.serial: ttyAMA3 at MMIO 0xfe201800 (irq = 17, base_baud = 0) is a PL011 rev2
[    2.422692] mmcblk3: mmc3:aaaa SN256 238 GiB
[    2.439506] fe201a00.serial: ttyAMA4 at MMIO 0xfe201a00 (irq = 17, base_baud = 0) is a PL011 rev2
[    3.547396] pinctrl-bcm2835 fe200000.gpio: pin gpio14 already requested by fe201000.serial; cannot claim for fe215040.serial
[    3.558661] pinctrl-bcm2835 fe200000.gpio: pin-14 (fe215040.serial) status -22
[    3.565906] pinctrl-bcm2835 fe200000.gpio: could not request pin 14 (gpio14) from group gpio14  on device pinctrl-bcm2711
[    3.576887] bcm2835-aux-uart fe215040.serial: Error applying setting, reverse things back
[    3.586150] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    3.593017] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    3.602573] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0
[    3.608618] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated
[    3.646159] of_cfs_init
[    3.649800] of_cfs_init: OK
[    3.678806] mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA
[    3.686911] ALSA device list:
[    3.689980]   No soundcards found.
[    3.693962] uart-pl011 fe201000.serial: no DMA platform data
[    3.700270] Waiting for root device /dev/mmcblk0p2...
[    3.751890] mmc1: new high speed SDIO card at address 0001
[    3.801019] mmc0: new ultra high speed DDR50 SDXC card at address aaaa
[    3.808573] mmcblk0: mmc0:aaaa SD128 119 GiB 
[    3.817680]  mmcblk0: p1 p2 p3 p4 < p5 p6 p7 >
[    3.823642] mmcblk0: mmc0:aaaa SD128 119 GiB
[    3.840644] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
[    3.850565] VFS: Mounted root (ext4 filesystem) readonly on device 179:34.
[    3.858256] devtmpfs: mounted
[    3.868808] Freeing unused kernel memory: 3968K
[    3.873566] Run /sbin/init as init process
[    3.877691]   with arguments:
[    3.877701]     /sbin/init
[    3.877711]   with environment:
[    3.877719]     HOME=/
[    3.877728]     TERM=linux
[    4.432196] EXT4-fs (mmcblk0p6): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
[    4.717683] NET: Registered PF_INET6 protocol family
[    4.724705] Segment Routing with IPv6
[    4.728426] In-situ OAM (IOAM) with IPv6
[    4.759888] systemd[1]: systemd 249.7+ running in system mode (+PAM -AUDIT -SELINUX -APPARMOR +IMA -SMACK +SECCOMP -GCRYPT -GNUTLS -OPENSSL +ACL +BLKID -CURL -ELFUTILS -FIDO2 -IDN2 -IDN -IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK -PCRE2 -PWQUALITY -P11KIT -QRENCODE -BZIP2 -LZ4 -XZ -ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=hybrid)
[    4.790894] systemd[1]: Detected architecture arm64.
[    4.837398] systemd[1]: Hostname set to <cm4>.
[    5.379785] systemd[1]: Queued start job for default target Multi-User System.
[    5.388517] random: systemd: uninitialized urandom read (16 bytes read)
[    5.443596] systemd[1]: Created slice Slice /system/getty.
[    5.464233] random: systemd: uninitialized urandom read (16 bytes read)
[    5.473091] systemd[1]: Created slice Slice /system/modprobe.
[    5.492155] random: systemd: uninitialized urandom read (16 bytes read)
[    5.500899] systemd[1]: Created slice Slice /system/serial-getty.
[    5.526127] systemd[1]: Created slice Slice /system/systemd-fsck.
[    5.550131] systemd[1]: Created slice Slice /system/systemd-growfs.
[    5.573797] systemd[1]: Created slice User and Session Slice.
[    5.596638] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[    5.620504] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    5.644349] systemd[1]: Reached target Local Encrypted Volumes.
[    5.664335] systemd[1]: Reached target Path Units.
[    5.684171] systemd[1]: Reached target Remote Encrypted Volumes.
[    5.704143] systemd[1]: Reached target Remote File Systems.
[    5.724152] systemd[1]: Reached target Slice Units.
[    5.744374] systemd[1]: Reached target Local Verity Protected Volumes.
[    5.769187] systemd[1]: Listening on Syslog Socket.
[    5.788497] systemd[1]: Listening on initctl Compatibility Named Pipe.
[    5.813222] systemd[1]: Listening on Journal Audit Socket.
[    5.832761] systemd[1]: Listening on Journal Socket (/dev/log).
[    5.853037] systemd[1]: Listening on Journal Socket.
[    5.873129] systemd[1]: Listening on Network Service Netlink Socket.
[    5.896987] systemd[1]: Listening on udev Control Socket.
[    5.916724] systemd[1]: Listening on udev Kernel Socket.
[    5.936728] systemd[1]: Listening on User Database Manager Socket.
[    5.960922] systemd[1]: Condition check resulted in Huge Pages File System being skipped.
[    5.973975] systemd[1]: Mounting POSIX Message Queue File System...
[    6.001321] systemd[1]: Mounting Kernel Debug File System...
[    6.025146] systemd[1]: Mounting Kernel Trace File System...
[    6.050190] systemd[1]: Starting Create List of Static Device Nodes...
[    6.077444] systemd[1]: Starting Load Kernel Module configfs...
[    6.101333] systemd[1]: Starting Load Kernel Module drm...
[    6.125242] systemd[1]: Starting Load Kernel Module fuse...
[    6.144252] fuse: init (API version 7.34)
[    6.153871] systemd[1]: Starting Journal Service...
[    6.180332] systemd[1]: Starting Load Kernel Modules...
[    6.205548] systemd[1]: Starting Remount Root and Kernel File Systems...
[    6.208928] r8152: loading out-of-tree module taints kernel.
[    6.222544] usbcore: registered new interface driver r8152
[    6.238220] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null). Quota mode: none.
[    6.251033] systemd[1]: Starting Coldplug All udev Devices...
[    6.286338] systemd[1]: Mounted POSIX Message Queue File System.
[    6.309017] systemd[1]: Mounted Kernel Debug File System.
[    6.328929] systemd[1]: Mounted Kernel Trace File System.
[    6.351251] systemd[1]: Finished Create List of Static Device Nodes.
[    6.374322] systemd[1]: [email protected]: Deactivated successfully.
[    6.383658] systemd[1]: Finished Load Kernel Module configfs.
[    6.406170] systemd[1]: [email protected]: Deactivated successfully.
[    6.415173] systemd[1]: Finished Load Kernel Module drm.
[    6.438379] systemd[1]: [email protected]: Deactivated successfully.
[    6.447510] systemd[1]: Finished Load Kernel Module fuse.
[    6.471176] systemd[1]: Finished Load Kernel Modules.
[    6.492978] systemd[1]: Started Journal Service.
[    6.611008] systemd-journald[140]: Received client request to flush runtime journal.
[    6.790422] audit: type=1334 audit(6.783:2): prog-id=5 op=LOAD
[    6.798073] audit: type=1334 audit(6.791:3): prog-id=6 op=LOAD
[    7.278564] rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000
[    7.295311] rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000
[    7.310969] rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000
[    7.323747] rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000
[    7.343101] bcmgenet fd580000.ethernet ethsensor1: renamed from eth0
[    7.403690] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[    7.423177] mc: Linux media interface: v0.10
[    7.444980] igb 0000:05:00.0 ethsensor2: renamed from eth2
[    7.463511] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[    7.470674] [vc_sm_connected_init]: start
[    7.479503] [vc_sm_connected_init]: installed successfully
[    7.518262] igb 0000:04:00.0 ethsensor0: renamed from eth1
[    7.531713] videodev: Linux video capture interface: v2.00
[    7.628239] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    7.648464] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[    7.662688] dwc2 fe980000.usb: supply vusb_d not found, using dummy regulator
[    7.676422] dwc2 fe980000.usb: supply vusb_a not found, using dummy regulator
[    7.716865] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[    7.724684] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[    7.747250] dwc2 fe980000.usb: DWC OTG Controller
[    7.748401] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[    7.762316] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[    7.772383] dwc2 fe980000.usb: new USB bus registered, assigned bus number 3
[    7.779931] dwc2 fe980000.usb: irq 20, io mem 0xfe980000
[    7.786822] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15
[    7.795639] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    7.803752] usb usb3: Product: DWC OTG Controller
[    7.809211] usb usb3: Manufacturer: Linux 5.15.32-v8 dwc2_hsotg
[    7.815401] usb usb3: SerialNumber: fe980000.usb
[    7.816376] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[    7.816521] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[    7.816564] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[    7.820736] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[    7.844447] i2c i2c-22: Added multiplexed i2c bus 0
[    7.848039] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[    7.855073] i2c i2c-22: Added multiplexed i2c bus 10
[    7.865472] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[    7.881254] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[    7.892930] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[    7.900112] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[    7.909498] bcm2835-codec bcm2835-codec: Device registered as /dev/video18
[    7.916841] bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx
[    7.928507] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[    7.936699] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    7.944468] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    7.952674] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    7.953866] input: soc:shutdown_button@3 as /devices/platform/soc/soc:shutdown_button@3/input/input0
[    7.960343] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    7.980472] bcm2835-codec bcm2835-codec: Device registered as /dev/video31
[    7.987598] bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image
[    7.999419] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20
[    8.013108] brcmfmac: F1 signature read @0x18000000=0x15264345
[    8.026376] hub 3-0:1.0: USB hub found
[    8.030330] hub 3-0:1.0: 1 port detected
[    8.041179] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21
[    8.048698] Adding 4095996k swap on /dev/mmcblk0p5.  Priority:-2 extents:1 across:4095996k SSFS
[    8.063319] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22
[    8.064120] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    8.090936] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23
[    8.101331] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    8.109622] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    8.114557] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,4-compute-module.bin failed with error -2
[    8.117317] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    8.138856] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    8.149748] usbcore: registered new interface driver brcmfmac
[    8.157913] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[    8.356153] usb 3-1: new high-speed USB device number 2 using dwc2
[    8.400309] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    8.409115] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    8.568470] usb 3-1: New USB device found, idVendor=1a40, idProduct=0101, bcdDevice= 1.11
[    8.576730] usb 3-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    8.583997] usb 3-1: Product: USB 2.0 Hub
[    8.590877] hub 3-1:1.0: USB hub found
[    8.594867] hub 3-1:1.0: 4 ports detected
[    8.638890] rtc-pcf85063 10-0051: registered as rtc0
[    8.644336] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Jan  4 2021 19:56:29 version 7.45.229 (617f1f5 CY) FWID 01-2dbd9d2e
[    8.662833] rtc-pcf85063 10-0051: setting system clock to 2022-06-17T16:10:33 UTC (1655482233)
[    8.984039] usb 3-1.1: new high-speed USB device number 3 using dwc2
[    9.193674] usb 3-1.1: New USB device found, idVendor=...., idProduct=...., bcdDevice= 1.01
[    9.202134] usb 3-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    9.202168] usb 3-1.1: Product: ....
[    9.202182] usb 3-1.1: Manufacturer: ....
[    9.202194] usb 3-1.1: SerialNumber: ....
[    9.252228] random: crng init done
[    9.257725] random: 7 urandom warning(s) missed due to ratelimiting
[    9.407971] usb 3-1.4: new full-speed USB device number 4 using dwc2
[    9.441344] EXT4-fs (mmcblk0p7): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
[    9.564043] EXT4-fs (mmcblk0p7): resizing filesystem from 96196608 to 96196608 blocks
[    9.639631] usb 3-1.4: New USB device found, idVendor=239a, idProduct=800b, bcdDevice= 1.00
[    9.648076] usb 3-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    9.655440] usb 3-1.4: Product: Microcontroller
[    9.660083] usb 3-1.4: Manufacturer: ....
[    9.665278] usb 3-1.4: SerialNumber: ....
[    9.835460] usbcore: registered new interface driver usbserial_generic
[    9.842332] usbserial: USB Serial support registered for generic
[    9.849882] cdc_acm 3-1.4:1.0: ttyACM0: USB ACM device
[    9.856169] usbcore: registered new interface driver cdc_acm
[    9.862005] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[    9.862740] usbcore: registered new interface driver usb_serial_simple
[    9.876882] usbserial: USB Serial support registered for carelink
[    9.883147] usbserial: USB Serial support registered for zio
[    9.888944] usbserial: USB Serial support registered for funsoft
[    9.895075] usbserial: USB Serial support registered for flashloader
[    9.901543] usbserial: USB Serial support registered for google
[    9.907580] usbserial: USB Serial support registered for libtransistor
[    9.914210] usbserial: USB Serial support registered for vivopay
[    9.920328] usbserial: USB Serial support registered for moto_modem
[    9.926681] usbserial: USB Serial support registered for motorola_tetra
[    9.933390] usbserial: USB Serial support registered for novatel_gps
[    9.939827] usbserial: USB Serial support registered for hp4x
[    9.945664] usbserial: USB Serial support registered for suunto
[    9.951676] usbserial: USB Serial support registered for siemens_mpi
[    9.958202] usb_serial_simple 3-1.1:1.0: novatel_gps converter detected
[    9.965274] usb 3-1.1: novatel_gps converter now attached to ttyUSB0
[    9.972296] usb 3-1.1: novatel_gps converter now attached to ttyUSB1
[    9.979092] usb 3-1.1: novatel_gps converter now attached to ttyUSB2
[   10.136827] audit: type=1334 audit(1655482234.971:4): prog-id=7 op=LOAD
[   10.143559] audit: type=1334 audit(1655482234.971:5): prog-id=8 op=LOAD
[   10.471255] audit: type=1325 audit(1655482235.287:6): table=filter family=2 entries=0 op=xt_register pid=319 comm="iptables-restor"
[   10.486593] audit: type=1300 audit(1655482235.287:6): arch=c00000b7 syscall=209 success=yes exit=0 a0=5 a1=0 a2=40 a3=7fd9ea16b0 items=0 ppid=1 pid=319 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables-restor" exe="/usr/sbin/xtables-legacy-multi" key=(null)
[   10.515347] audit: type=1327 audit(1655482235.287:6): proctitle=2F7573722F7362696E2F69707461626C65732D726573746F7265002D77002D2D002F6574632F69707461626C65732F69707461626C65732E72756C6573
[   10.533471] audit: type=1325 audit(1655482235.303:7): table=filter family=2 entries=4 op=xt_replace pid=319 comm="iptables-restor"
[   10.548369] audit: type=1300 audit(1655482235.303:7): arch=c00000b7 syscall=208 success=yes exit=0 a0=5 a1=0 a2=40 a3=558bef0b10 items=0 ppid=1 pid=319 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables-restor" exe="/usr/sbin/xtables-legacy-multi" key=(null)
[   10.591537] audit: type=1327 audit(1655482235.303:7): proctitle=2F7573722F7362696E2F69707461626C65732D726573746F7265002D77002D2D002F6574632F69707461626C65732F69707461626C65732E72756C6573
[   10.809079] exFAT-fs (mmcblk3p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
[   11.303707] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
[   11.380721] bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay)
[   11.389436] bcmgenet fd580000.ethernet ethsensor1: Link is Down
[   12.116586] lo ate my IP address
[   12.361439] lo ate my IP address
[   12.364028] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   13.616953] igb 0000:04:00.0 ethsensor0: igb: ethsensor0 NIC Link is Up 10 Mbps Half Duplex, Flow Control: None
[   13.631931] igb 0000:04:00.0: EEE Disabled: unsupported at half duplex. Re-enable using ethtool when at full duplex.
[   13.642873] IPv6: ADDRCONF(NETDEV_CHANGE): ethsensor0: link becomes ready
[   33.764005] cam-dummy-reg: disabling

[lategoodbye]

I was able to reproduce a very similiar issue with Mainline on a Raspberry Pi 3A+ and a USB hub after suspend 2 idle.

The following branch contains the relevant fix ( usb: dwc2: Fix HCD resume ):
https://github.com/lategoodbye/linux-dev/commits/v6.12-dwc_hcd_fixes/

Maybe this also fixes the issue here.

[lategoodbye]

Please see at #6247 (comment) for a test build, which should fix the dwc2 IRQ issue.