DAC+ADC PRO Bookworkm crash

gianluca

March 05, 2024 17:32

None

Hello,

I have a Rpi-4 with latest Bookwork and DAC+ADC Pro, did all the proper setup in config.txt

dtparam=i2c_arm=on
dtparam=i2s=on
dtoverlay=hifiberry-dacplusadcpro
dtparam=audio=off
dtoverlay=vc4-kms-v3d,noaudio

but it does not work ... and I see a weird crash in dmesg.

googled around but I do not see something similar to this issue ... any hint from the community?

===========================

[ 9.155545] ------------[ cut here ]------------
[ 9.155555] WARNING: CPU: 3 PID: 466 at drivers/regulator/core.c:2403 _regulator_put+0x170/0x180
[ 9.155596] Modules linked in: brcmfmac brcmutil cfg80211 snd_soc_pcm512x_i2c(+) snd_soc_pcm186x_i2c(+) raspberrypi_hwmon arducam_pivariety snd_soc_hifiberry_dacplusadcpro snd_soc_pcm512x snd_soc_pcm186x regmap_i2c binfmt_misc hci_uart joydev btbcm bluetooth bcm2835_unicam v4l2_dv_timings bcm2835_codec(C) v4l2_fwnode rpivid_hevc(C) bcm2835_isp(C) bcm2835_v4l2(C) v4l2_mem2mem bcm2835_mmal_vchiq(C) v4l2_async videobuf2_vmalloc videobuf2_dma_contig ecdh_generic videobuf2_memops videobuf2_v4l2 snd_soc_bcm2835_i2s ecc rfkill videobuf2_common vc_sm_cma(C) videodev libaes mc snd_bcm2835(C) raspberrypi_gpiomem nvmem_rmem uio_pdrv_genirq uio i2c_dev dm_mod fuse ip_tables x_tables ipv6 vc4 snd_soc_hdmi_codec drm_display_helper cec drm_dma_helper drm_kms_helper syscopyarea sysfillrect sysimgblt fb_sys_fops v3d gpu_sched snd_soc_core drm_shmem_helper snd_compress drm snd_pcm_dmaengine drm_panel_orientation_quirks snd_pcm snd_timer i2c_mux_pinctrl i2c_bcm2835 i2c_mux i2c_brcmstb snd backlight
[ 9.155989] clk_hifiberry_dacpro
[ 9.156008] CPU: 3 PID: 466 Comm: modprobe Tainted: G C 6.1.0-rpi8-rpi-v8 #1 Debian 1:6.1.73-1+rpt1
[ 9.156027] Hardware name: Raspberry Pi 4 Model B Rev 1.5 (DT)
[ 9.156034] pstate: 80000005 (Nzcv daif -PAN -UAO -TCO -DIT -SSBS BTYPE=--)
[ 9.156048] pc : _regulator_put+0x170/0x180
[ 9.156061] lr : regulator_bulk_free+0x54/0x80
[ 9.156073] sp : ffffffc008a0b7e0
[ 9.156080] x29: ffffffc008a0b7e0 x28: 0000000000000000 x27: 0000000000000000
[ 9.156104] x26: 0000000000000000 x25: ffffffdd1a156158 x24: ffffffdd8b86d858
[ 9.156124] x23: ffffffdd8b545008 x22: ffffff81017a20f8 x21: ffffff81017a2200
[ 9.156144] x20: ffffffdd8b9bb570 x19: ffffff81017a2200 x18: 00000000fffffffe
[ 9.156163] x17: 0000000000000000 x16: ffffffdd8aff16f0 x15: ffffffc008a0b2a0
[ 9.156182] x14: 0000000000000000 x13: 3031312d203a6563 x12: 6976656420657469
[ 9.156201] x11: 0000000000000001 x10: ffffff8102271c28 x9 : ffffffdd8ab2d254
[ 9.156220] x8 : ffffffc008a0b7f0 x7 : 0000000000000000 x6 : ffffff8102271c28
[ 9.156239] x5 : 0000000000000010 x4 : 0000000000000000 x3 : ffffffdd8b9bb570
[ 9.156258] x2 : ffffff8101271ec0 x1 : 0000000000000000 x0 : 0000000000000001
[ 9.156279] Call trace:
[ 9.156288] _regulator_put+0x170/0x180
[ 9.156302] regulator_bulk_free+0x54/0x80
[ 9.156313] devm_regulator_bulk_release+0x24/0x30
[ 9.156329] release_nodes+0x68/0x100
[ 9.156352] devres_release_group+0xd4/0x144
[ 9.156367] i2c_device_probe+0x170/0x310
[ 9.156389] really_probe+0xc4/0x2e0
[ 9.156401] __driver_probe_device+0x80/0x120
[ 9.156412] driver_probe_device+0xe0/0x164
[ 9.156422] __driver_attach+0x9c/0x1a4
[ 9.156433] bus_for_each_dev+0x7c/0xe0
[ 9.156452] driver_attach+0x2c/0x40
[ 9.156462] bus_add_driver+0x15c/0x210
[ 9.156471] driver_register+0x80/0x13c
[ 9.156482] i2c_register_driver+0x50/0xd0
[ 9.156499] pcm186x_i2c_driver_init+0x28/0x1000 [snd_soc_pcm186x_i2c]
[ 9.156537] do_one_initcall+0x60/0x2d0
[ 9.156553] do_init_module+0x50/0x1f0
[ 9.156570] load_module+0x1b1c/0x1f74
[ 9.156584] __do_sys_init_module+0x198/0x1d0
[ 9.156599] __arm64_sys_init_module+0x24/0x30
[ 9.156613] invoke_syscall+0x50/0x120
[ 9.156634] el0_svc_common.constprop.0+0xd4/0xf4
[ 9.156650] do_el0_svc+0x34/0xd0
[ 9.156665] el0_svc+0x2c/0x84
[ 9.156682] el0t_64_sync_handler+0xf4/0x120
[ 9.156695] el0t_64_sync+0x18c/0x190
[ 9.156707] ---[ end trace 0000000000000000 ]---
[ 9.169821] ------------[ cut here ]------------

Date Votes

14 comments

0

HiFiBerry team March 06, 2024 06:07

As there are no problems like this known, it could be from a corrupted SD card. Try a fresh installation on a new SD card.

Comment actions Permalink
0

gianluca March 07, 2024 00:49

used a new card, same issue.

Comment actions Permalink
0

HiFiBerry team March 08, 2024 07:55

Make sure you use

force_eeprom_read=0

Comment actions Permalink
0

gianluca March 08, 2024 08:58

still the same problem.

This is a good sdcard, if I comment out the hifiberry overlay everythings works fine, updated to the latest pkgs as of today.

the moment I enable the overlay, even with force_eeprom_read=0, I see a crash at boot in the dmesg and audio does not work.

please note: the board does boot, everything works as usual, simply I cannot use hifiberry dac+adc/pro at all

:-(

here is the full dmesg:

=================

pi@phd2cam:~ $ dmesg
[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd083]
[ 0.000000] Linux version 6.1.0-rpi8-rpi-v8 (debian-kernel@lists.debian.org) (gcc-12 (Debian 12.2.0-14) 12.2.0, GNU ld (GNU Binutils for Debian) 2.40) #1 SMP PREEMPT Debian 1:6.1.73-1+rpt1 (2024-01-25)
[ 0.000000] random: crng init done
[ 0.000000] Machine model: Raspberry Pi 4 Model B Rev 1.5
[ 0.000000] efi: UEFI not found.
[ 0.000000] Reserved memory: created CMA memory pool at 0x000000000dc00000, size 512 MiB
[ 0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[ 0.000000] Zone ranges:
[ 0.000000] DMA [mem 0x0000000000000000-0x000000003fffffff]
[ 0.000000] DMA32 [mem 0x0000000040000000-0x00000000ffffffff]
[ 0.000000] Normal [mem 0x0000000100000000-0x00000001ffffffff]
[ 0.000000] Movable zone start for each node
[ 0.000000] Early memory node ranges
[ 0.000000] node 0: [mem 0x0000000000000000-0x000000002fffffff]
[ 0.000000] node 0: [mem 0x0000000040000000-0x00000000fbffffff]
[ 0.000000] node 0: [mem 0x0000000100000000-0x00000001ffffffff]
[ 0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x00000001ffffffff]
[ 0.000000] On node 0, zone Normal: 16384 pages in unavailable ranges
[ 0.000000] percpu: Embedded 29 pages/cpu s79144 r8192 d31448 u118784
[ 0.000000] pcpu-alloc: s79144 r8192 d31448 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-v3a
[ 0.000000] CPU features: detected: Spectre-v4
[ 0.000000] CPU features: detected: Spectre-BHB
[ 0.000000] CPU features: kernel page table isolation forced ON by KASLR
[ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)
[ 0.000000] CPU features: detected: ARM erratum 1742098
[ 0.000000] CPU features: detected: ARM errata 1165522, 1319367, or 1530923
[ 0.000000] alternatives: applying boot alternatives
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1983488
[ 0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_hdmi=0 snd_bcm2835.enable_hdmi=0 smsc95xx.macaddr=D8:3A:DD:5C:D5:0D vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 console=ttyS0,115200 console=tty1 root=PARTUUID=90cb2894-02 rootfstype=ext4 fsck.repair=yes rootwait quiet splash plymouth.ignore-serial-consoles cfg80211.ieee80211_regdom=IT
[ 0.000000] Unknown kernel command line parameters "splash", will be passed to user space.
[ 0.000000] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear)
[ 0.000000] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
[ 0.000000] mem auto-init: stack:all(zero), heap alloc:off, heap free:off
[ 0.000000] software IO TLB: area num 4.
[ 0.000000] software IO TLB: mapped [mem 0x0000000009c00000-0x000000000dc00000] (64MB)
[ 0.000000] Memory: 7273524K/8060928K available (12352K kernel code, 2172K rwdata, 4076K rodata, 4288K init, 1083K bss, 263116K reserved, 524288K cma-reserved)
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[ 0.000000] ftrace: allocating 40078 entries in 157 pages
[ 0.000000] ftrace: allocated 157 pages with 5 groups
[ 0.000000] trace event string verifier disabled
[ 0.000000] rcu: Preemptible hierarchical RCU implementation.
[ 0.000000] rcu: RCU event tracing is enabled.
[ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4.
[ 0.000000] Trampoline variant of Tasks RCU enabled.
[ 0.000000] Rude variant of Tasks RCU enabled.
[ 0.000000] Tracing variant of Tasks RCU enabled.
[ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[ 0.000000] Root IRQ handler: gic_handle_irq
[ 0.000000] GIC: Using split EOI/Deactivate mode
[ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.
[ 0.000000] arch_timer: cp15 timer(s) running at 54.00MHz (phys).
[ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns
[ 0.000001] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns
[ 0.000301] Console: colour dummy device 80x25
[ 0.000350] printk: console [tty1] enabled
[ 0.000397] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000)
[ 0.000416] pid_max: default: 32768 minimum: 301
[ 0.000507] LSM: Security Framework initializing
[ 0.000749] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)
[ 0.000863] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)
[ 0.001787] cgroup: Disabling memory control group subsystem
[ 0.003107] cblist_init_generic: Setting adjustable number of callback queues.
[ 0.003117] cblist_init_generic: Setting shift to 2 and lim to 1.
[ 0.003222] cblist_init_generic: Setting adjustable number of callback queues.
[ 0.003230] cblist_init_generic: Setting shift to 2 and lim to 1.
[ 0.003329] cblist_init_generic: Setting adjustable number of callback queues.
[ 0.003336] cblist_init_generic: Setting shift to 2 and lim to 1.
[ 0.003593] rcu: Hierarchical SRCU implementation.
[ 0.003601] rcu: Max phase no-delay instances is 1000.
[ 0.005500] EFI services will not be available.
[ 0.005816] smp: Bringing up secondary CPUs ...
[ 0.006442] Detected PIPT I-cache on CPU1
[ 0.006593] CPU1: Booted secondary processor 0x0000000001 [0x410fd083]
[ 0.007248] Detected PIPT I-cache on CPU2
[ 0.007368] CPU2: Booted secondary processor 0x0000000002 [0x410fd083]
[ 0.008036] Detected PIPT I-cache on CPU3
[ 0.008155] CPU3: Booted secondary processor 0x0000000003 [0x410fd083]
[ 0.008240] smp: Brought up 1 node, 4 CPUs
[ 0.008256] SMP: Total of 4 processors activated.
[ 0.008264] CPU features: detected: 32-bit EL0 Support
[ 0.008269] CPU features: detected: 32-bit EL1 Support
[ 0.008277] CPU features: detected: CRC32 instructions
[ 0.008376] CPU: All CPU(s) started at EL2
[ 0.008391] alternatives: applying system-wide alternatives
[ 0.010152] devtmpfs: initialized
[ 0.019194] Enabled cp15_barrier support
[ 0.019221] Enabled setend support
[ 0.019381] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[ 0.019406] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[ 0.033028] pinctrl core: initialized pinctrl subsystem
[ 0.033671] DMI not present or invalid.
[ 0.034174] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[ 0.035859] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations
[ 0.036141] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[ 0.036588] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[ 0.036659] audit: initializing netlink subsys (disabled)
[ 0.036824] audit: type=2000 audit(0.036:1): state=initialized audit_enabled=0 res=1
[ 0.037293] thermal_sys: Registered thermal governor 'step_wise'
[ 0.037344] cpuidle: using governor menu
[ 0.037571] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[ 0.037690] ASID allocator initialised with 32768 entries
[ 0.038567] Serial: AMBA PL011 UART driver
[ 0.046457] bcm2835-mbox fe00b880.mailbox: mailbox enabled
[ 0.060156] raspberrypi-firmware soc:firmware: Attached to firmware from 2023-10-17T15:39:16, variant start
[ 0.064166] raspberrypi-firmware soc:firmware: Firmware hash is 30f0c5e4d076da3ab4f341d88e7d505760b93ad7
[ 0.074082] KASLR enabled
[ 0.097900] bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1
[ 0.100492] iommu: Default domain type: Translated
[ 0.100504] iommu: DMA domain TLB invalidation policy: strict mode
[ 0.100844] SCSI subsystem initialized
[ 0.101010] usbcore: registered new interface driver usbfs
[ 0.101050] usbcore: registered new interface driver hub
[ 0.101088] usbcore: registered new device driver usb
[ 0.101242] usb_phy_generic phy: supply vcc not found, using dummy regulator
[ 0.101588] pps_core: LinuxPPS API ver. 1 registered
[ 0.101597] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[ 0.101614] PTP clock support registered
[ 0.102489] vgaarb: loaded
[ 0.102956] clocksource: Switched to clocksource arch_sys_counter
[ 0.103396] VFS: Disk quotas dquot_6.6.0
[ 0.103447] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[ 0.103538] FS-Cache: Loaded
[ 0.103668] CacheFiles: Loaded
[ 0.111096] NET: Registered PF_INET protocol family
[ 0.112212] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[ 0.120912] tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear)
[ 0.121007] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[ 0.121034] TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear)
[ 0.121482] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear)
[ 0.124243] TCP: Hash tables configured (established 65536 bind 65536)
[ 0.124799] MPTCP token hash table entries: 8192 (order: 5, 196608 bytes, linear)
[ 0.125119] UDP hash table entries: 4096 (order: 5, 131072 bytes, linear)
[ 0.125236] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear)
[ 0.125533] NET: Registered PF_UNIX/PF_LOCAL protocol family
[ 0.126127] RPC: Registered named UNIX socket transport module.
[ 0.126137] RPC: Registered udp transport module.
[ 0.126143] RPC: Registered tcp transport module.
[ 0.126148] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 0.126163] PCI: CLS 0 bytes, default 64
[ 0.126585] Trying to unpack rootfs image as initramfs...
[ 0.136451] hw perfevents: enabled with armv8_cortex_a72 PMU driver, 7 counters available
[ 0.136749] kvm [1]: IPA Size Limit: 44 bits
[ 0.138042] kvm [1]: vgic interrupt IRQ9
[ 0.138180] kvm [1]: Hyp mode initialized successfully
[ 0.936119] Freeing initrd memory: 16748K
[ 1.179217] Initialise system trusted keyrings
[ 1.179613] workingset: timestamp_bits=46 max_order=21 bucket_order=0
[ 1.185459] zbud: loaded
[ 1.187650] NFS: Registering the id_resolver key type
[ 1.187681] Key type id_resolver registered
[ 1.187688] Key type id_legacy registered
[ 1.187773] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[ 1.187782] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[ 1.188903] Key type asymmetric registered
[ 1.188914] Asymmetric key parser 'x509' registered
[ 1.188966] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[ 1.189144] io scheduler mq-deadline registered
[ 1.189154] io scheduler kyber registered
[ 1.190663] irq_brcmstb_l2: registered L2 intc (/soc/interrupt-controller@7ef00100, parent irq: 27)
[ 1.195921] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
[ 1.195954] brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff]
[ 1.195993] brcm-pcie fd500000.pcie: MEM 0x0600000000..0x063fffffff -> 0x00c0000000
[ 1.196027] brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x01ffffffff -> 0x0400000000
[ 1.196525] brcm-pcie fd500000.pcie: setting SCB_ACCESS_EN, READ_UR_MODE, MAX_BURST_SIZE
[ 1.196835] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
[ 1.196850] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 1.196864] pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff])
[ 1.196911] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
[ 1.197001] pci 0000:00:00.0: PME# supported from D0 D3hot
[ 1.200615] pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[ 1.200750] pci_bus 0000:01: supply vpcie3v3 not found, using dummy regulator
[ 1.200868] pci_bus 0000:01: supply vpcie3v3aux not found, using dummy regulator
[ 1.200919] pci_bus 0000:01: supply vpcie12v not found, using dummy regulator
[ 1.309041] brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC)
[ 1.309145] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
[ 1.309238] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
[ 1.309526] pci 0000:01:00.0: PME# supported from D0 D3hot
[ 1.319036] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
[ 1.319071] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
[ 1.319089] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
[ 1.319147] pci 0000:00:00.0: PCI bridge to [bus 01]
[ 1.319159] pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff]
[ 1.319370] pcieport 0000:00:00.0: enabling device (0000 -> 0002)
[ 1.319501] pcieport 0000:00:00.0: PME: Signaling with IRQ 31
[ 1.319809] pcieport 0000:00:00.0: AER: enabled with IRQ 31
[ 1.320156] brcm-pcie fd500000.pcie: clkreq control enabled
[ 1.329274] iproc-rng200 fe104000.rng: hwrng registered
[ 1.329531] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[ 1.339709] brd: module loaded
[ 1.345689] loop: module loaded
[ 1.346277] Loading iSCSI transport class v2.0-870.
[ 1.349987] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000
[ 1.455035] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus
[ 1.455879] usbcore: registered new device driver r8152-cfgselector
[ 1.455929] usbcore: registered new interface driver r8152
[ 1.455979] usbcore: registered new interface driver lan78xx
[ 1.456019] usbcore: registered new interface driver smsc95xx
[ 1.503636] xhci_hcd 0000:01:00.0: xHCI Host Controller
[ 1.503665] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
[ 1.504182] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0b00040000000890
[ 1.504887] xhci_hcd 0000:01:00.0: xHCI Host Controller
[ 1.504905] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
[ 1.504920] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
[ 1.505129] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.01
[ 1.505144] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 1.505154] usb usb1: Product: xHCI Host Controller
[ 1.505163] usb usb1: Manufacturer: Linux 6.1.0-rpi8-rpi-v8 xhci-hcd
[ 1.505171] usb usb1: SerialNumber: 0000:01:00.0
[ 1.505665] hub 1-0:1.0: USB hub found
[ 1.505706] hub 1-0:1.0: 1 port detected
[ 1.506200] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.01
[ 1.506215] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 1.506225] usb usb2: Product: xHCI Host Controller
[ 1.506234] usb usb2: Manufacturer: Linux 6.1.0-rpi8-rpi-v8 xhci-hcd
[ 1.506242] usb usb2: SerialNumber: 0000:01:00.0
[ 1.506650] hub 2-0:1.0: USB hub found
[ 1.506705] hub 2-0:1.0: 4 ports detected
[ 1.507667] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[ 1.507782] dwc_otg: FIQ enabled
[ 1.507788] dwc_otg: NAK holdoff enabled
[ 1.507793] dwc_otg: FIQ split-transaction FSM enabled
[ 1.507802] Module dwc_common_port init
[ 1.508485] usbcore: registered new interface driver uas
[ 1.508573] usbcore: registered new interface driver usb-storage
[ 1.508997] mousedev: PS/2 mouse device common for all mice
[ 1.512758] sdhci: Secure Digital Host Controller Interface driver
[ 1.512771] sdhci: Copyright(c) Pierre Ossman
[ 1.513002] sdhci-pltfm: SDHCI platform and OF driver helper
[ 1.515611] ledtrig-cpu: registered to indicate activity on CPUs
[ 1.515855] hid: raw HID events driver (C) Jiri Kosina
[ 1.515974] usbcore: registered new interface driver usbhid
[ 1.516006] usbhid: USB HID core driver
[ 1.521110] NET: Registered PF_PACKET protocol family
[ 1.521192] Key type dns_resolver registered
[ 1.522034] registered taskstats version 1
[ 1.522091] Loading compiled-in X.509 certificates
[ 1.543937] Loaded X.509 cert 'Debian Secure Boot CA: 6ccece7e4c6c0d1f6149f3dd27dfcc5cbb419ea1'
[ 1.544039] Loaded X.509 cert 'Debian Secure Boot Signer 2022 - linux: 14011249c2675ea8e5148542202005810584b25f'
[ 1.544605] Key type .fscrypt registered
[ 1.544615] Key type fscrypt-provisioning registered
[ 1.553478] uart-pl011 fe201000.serial: there is not valid maps for state default
[ 1.553833] uart-pl011 fe201000.serial: cts_event_workaround enabled
[ 1.553943] fe201000.serial: ttyAMA1 at MMIO 0xfe201000 (irq = 36, base_baud = 0) is a PL011 rev2
[ 1.554125] serial serial0: tty port ttyAMA1 registered
[ 1.560957] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[ 1.561206] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[ 1.561935] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0
[ 1.561951] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated
[ 1.588740] of_cfs_init
[ 1.588837] of_cfs_init: OK
[ 1.626899] mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA
[ 1.634310] Freeing unused kernel memory: 4288K
[ 1.634506] Run /init as init process
[ 1.634513] with arguments:
[ 1.634517] /init
[ 1.634523] splash
[ 1.634528] with environment:
[ 1.634532] HOME=/
[ 1.634537] TERM=linux
[ 1.670411] mmc1: new high speed SDIO card at address 0001
[ 1.726282] mmc0: new ultra high speed DDR50 SDHC card at address aaaa
[ 1.727250] mmcblk0: mmc0:aaaa SL16G 14.8 GiB
[ 1.730974] mmcblk0: p1 p2
[ 1.731501] mmcblk0: mmc0:aaaa SL16G 14.8 GiB (quirks 0x00004000)
[ 1.759139] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[ 1.909611] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21
[ 1.909642] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[ 1.909654] usb 1-1: Product: USB2.0 Hub
[ 1.915072] hub 1-1:1.0: USB hub found
[ 1.915386] hub 1-1:1.0: 4 ports detected
[ 2.211064] usb 1-1.3: new full-speed USB device number 3 using xhci_hcd
[ 2.309509] brcmstb-i2c fef04500.i2c: @97500hz registered in polling mode
[ 2.322509] usb 1-1.3: New USB device found, idVendor=8510, idProduct=13ab, bcdDevice= 1.00
[ 2.322540] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[ 2.322553] usb 1-1.3: Product: Wireless Receiver
[ 2.322564] usb 1-1.3: Manufacturer: Telink
[ 2.322572] usb 1-1.3: SerialNumber: TLSR8366
[ 2.331384] brcmstb-i2c fef09500.i2c: @97500hz registered in polling mode
[ 2.358019] i2c i2c-22: Added multiplexed i2c bus 0
[ 2.358393] i2c 10-000c: Fixed dependency cycle(s) with /soc/csi@7e801000/port/endpoint
[ 2.358729] i2c i2c-22: Added multiplexed i2c bus 10
[ 2.374799] input: Telink Wireless Receiver 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.0/0003:8510:13AB.0001/input/input0
[ 2.384388] input: Telink Wireless Receiver 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.0/0003:8510:13AB.0001/input/input1
[ 2.445058] input: Telink Wireless Receiver 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.0/0003:8510:13AB.0001/input/input2
[ 2.445464] hid-generic 0003:8510:13AB.0001: input,hidraw0: USB HID v1.11 Mouse [Telink Wireless Receiver] on usb-0000:01:00.0-1.3/input0
[ 2.453534] input: Telink Wireless Receiver 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:8510:13AB.0002/input/input3
[ 2.463030] [drm] Initialized v3d 1.0.0 20180419 for fec00000.v3d on minor 0
[ 2.494651] vc4-drm gpu: bound fe400000.hvs (ops vc4_hvs_ops [vc4])
[ 2.496496] Registered IR keymap rc-cec
[ 2.496655] rc rc0: vc4-hdmi-0 as /devices/platform/soc/fef00700.hdmi/rc/rc0
[ 2.496828] input: vc4-hdmi-0 as /devices/platform/soc/fef00700.hdmi/rc/rc0/input4
[ 2.511747] hid-generic 0003:8510:13AB.0002: input,hidraw1: USB HID v1.11 Keyboard [Telink Wireless Receiver] on usb-0000:01:00.0-1.3/input1
[ 2.511899] vc4_hdmi fef00700.hdmi: 'dmas' DT property is missing or empty, no HDMI audio
[ 2.511937] vc4-drm gpu: bound fef00700.hdmi (ops vc4_hdmi_ops [vc4])
[ 2.515042] Registered IR keymap rc-cec
[ 2.515208] rc rc1: vc4-hdmi-1 as /devices/platform/soc/fef05700.hdmi/rc/rc1
[ 2.515521] input: vc4-hdmi-1 as /devices/platform/soc/fef05700.hdmi/rc/rc1/input5
[ 2.515977] vc4_hdmi fef05700.hdmi: 'dmas' DT property is missing or empty, no HDMI audio
[ 2.516020] vc4-drm gpu: bound fef05700.hdmi (ops vc4_hdmi_ops [vc4])
[ 2.516485] vc4-drm gpu: bound fe004000.txp (ops vc4_txp_ops [vc4])
[ 2.516823] vc4-drm gpu: bound fe206000.pixelvalve (ops vc4_crtc_ops [vc4])
[ 2.517531] vc4-drm gpu: bound fe207000.pixelvalve (ops vc4_crtc_ops [vc4])
[ 2.517878] vc4-drm gpu: bound fe20a000.pixelvalve (ops vc4_crtc_ops [vc4])
[ 2.518100] vc4-drm gpu: bound fe216000.pixelvalve (ops vc4_crtc_ops [vc4])
[ 2.518388] vc4-drm gpu: bound fec12000.pixelvalve (ops vc4_crtc_ops [vc4])
[ 2.521221] [drm] Initialized vc4 0.0.0 20140616 for gpu on minor 1
[ 2.521738] vc4-drm gpu: [drm] Cannot find any crtc or sizes
[ 2.532738] vc4-drm gpu: [drm] Cannot find any crtc or sizes
[ 2.533171] vc4-drm gpu: [drm] Cannot find any crtc or sizes
[ 2.879793] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Quota mode: none.
[ 3.480980] systemd[1]: System time before build time, advancing clock.
[ 3.670546] NET: Registered PF_INET6 protocol family
[ 3.671921] Segment Routing with IPv6
[ 3.671951] In-situ OAM (IOAM) with IPv6
[ 3.751171] systemd[1]: systemd 252.22-1~deb12u1 running in system mode (+PAM +AUDIT +SELINUX +APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified)
[ 3.751211] systemd[1]: Detected architecture arm64.
[ 3.765618] systemd[1]: Hostname set to <phd2cam>.
[ 4.580433] systemd[1]: Queued start job for default target graphical.target.
[ 4.605987] systemd[1]: Created slice system-getty.slice - Slice /system/getty.
[ 4.607581] systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe.
[ 4.608786] systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck.
[ 4.609579] systemd[1]: Created slice user.slice - User and Session Slice.
[ 4.609990] systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch.
[ 4.610831] systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point.
[ 4.611027] systemd[1]: Expecting device dev-disk-by\x2dpartuuid-90cb2894\x2d01.device - /dev/disk/by-partuuid/90cb2894-01...
[ 4.611208] systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes.
[ 4.611408] systemd[1]: Reached target nss-user-lookup.target - User and Group Name Lookups.
[ 4.611582] systemd[1]: Reached target slices.target - Slice Units.
[ 4.611719] systemd[1]: Reached target swap.target - Swaps.
[ 4.611868] systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes.
[ 4.612593] systemd[1]: Listening on systemd-fsckd.socket - fsck to fsckd communication Socket.
[ 4.612904] systemd[1]: Listening on systemd-initctl.socket - initctl Compatibility Named Pipe.
[ 4.614099] systemd[1]: Listening on systemd-journald-audit.socket - Journal Audit Socket.
[ 4.614723] systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log).
[ 4.615456] systemd[1]: Listening on systemd-journald.socket - Journal Socket.
[ 4.616273] systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
[ 4.616850] systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
[ 4.617558] systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages).
[ 4.621161] systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System...
[ 4.625424] systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System...
[ 4.629762] systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System...
[ 4.630470] systemd[1]: auth-rpcgss-module.service - Kernel Module supporting RPCSEC_GSS was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab).
[ 4.636912] systemd[1]: Starting fake-hwclock.service - Restore / save the current clock...
[ 4.641861] systemd[1]: Starting keyboard-setup.service - Set the console keyboard layout...
[ 4.647073] systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
[ 4.652470] systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs...
[ 4.658204] systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
[ 4.664143] systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
[ 4.669833] systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
[ 4.675865] systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse...
[ 4.681779] systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
[ 4.682655] systemd[1]: systemd-fsck-root.service - File System Check on Root Device was skipped because of an unmet condition check (ConditionPathExists=!/run/initramfs/fsck-root).
[ 4.693978] systemd[1]: Starting systemd-journald.service - Journal Service...
[ 4.719879] systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
[ 4.725924] systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems...
[ 4.731235] systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
[ 4.735290] device-mapper: ioctl: 4.47.0-ioctl (2022-07-28) initialised: dm-devel@redhat.com
[ 4.744446] systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System.
[ 4.746810] systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System.
[ 4.749357] systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System.
[ 4.752152] systemd[1]: Finished fake-hwclock.service - Restore / save the current clock.
[ 4.755488] systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
[ 4.758596] systemd[1]: modprobe@configfs.service: Deactivated successfully.
[ 4.759602] systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs.
[ 4.761692] systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
[ 4.762574] systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
[ 4.765018] systemd[1]: modprobe@drm.service: Deactivated successfully.
[ 4.765967] systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm.
[ 4.768092] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
[ 4.769096] systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
[ 4.770881] fuse: init (API version 7.37)
[ 4.771440] systemd[1]: modprobe@loop.service: Deactivated successfully.
[ 4.772361] systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
[ 4.778528] systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System...
[ 4.779402] systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
[ 4.782100] systemd[1]: modprobe@fuse.service: Deactivated successfully.
[ 4.783013] systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse.
[ 4.795422] i2c_dev: i2c /dev entries driver
[ 4.797269] systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System...
[ 4.804925] systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System.
[ 4.814318] systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules.
[ 4.819504] systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System.
[ 4.827310] EXT4-fs (mmcblk0p2): re-mounted. Quota mode: none.
[ 4.837922] systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables...
[ 4.844902] systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems.
[ 4.846467] systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes).
[ 4.846754] systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
[ 4.851398] systemd[1]: Starting systemd-random-seed.service - Load/Save Random Seed...
[ 4.856425] systemd[1]: Starting systemd-sysusers.service - Create System Users...
[ 4.932226] systemd[1]: Finished systemd-random-seed.service - Load/Save Random Seed.
[ 4.933561] systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes).
[ 4.936760] systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables.
[ 4.974243] systemd[1]: Finished systemd-sysusers.service - Create System Users.
[ 5.000809] systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev...
[ 5.009664] systemd[1]: Started systemd-journald.service - Journal Service.
[ 5.064006] systemd-journald[282]: Received client request to flush runtime journal.
[ 5.069046] systemd-journald[282]: File /var/log/journal/19c3d12e7f614ffcb76aa18398d1ccae/system.journal corrupted or uncleanly shut down, renaming and replacing.
[ 6.507752] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[ 6.525259] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[ 6.525291] [vc_sm_connected_init]: start
[ 6.533347] mc: Linux media interface: v0.10
[ 6.538447] rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000
[ 6.567865] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[ 6.595566] rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem
[ 6.596682] [vc_sm_connected_init]: installed successfully
[ 6.707895] videodev: Linux video capture interface: v2.00
[ 7.094494] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[ 7.115613] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[ 7.122523] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[ 7.123073] rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned.
[ 7.147967] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[ 7.151309] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[ 7.159759] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[ 7.161534] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[ 7.162999] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[ 7.163041] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[ 7.163073] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[ 7.163086] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[ 7.163097] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[ 7.176720] rpivid feb10000.codec: Device registered as /dev/video19
[ 7.177892] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20
[ 7.189627] pcm512x 1-004d: supply AVDD not found, using dummy regulator
[ 7.191457] pcm512x 1-004d: supply DVDD not found, using dummy regulator
[ 7.191546] pcm512x 1-004d: supply CPVDD not found, using dummy regulator
[ 7.196743] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[ 7.196812] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[ 7.208050] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[ 7.208115] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[ 7.211915] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21
[ 7.213249] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22
[ 7.213636] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23
[ 7.213668] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[ 7.213685] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[ 7.213697] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[ 7.213708] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[ 7.213888] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[ 7.287796] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[ 7.287858] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[ 7.293167] pcm186x 1-004a: supply avdd not found, using dummy regulator
[ 7.293393] pcm186x 1-004a: supply dvdd not found, using dummy regulator
[ 7.293470] pcm186x 1-004a: supply iovdd not found, using dummy regulator
[ 7.308215] arducam-pivariety 10-000c: firmware version: 0x10004
[ 7.308359] bcm2835-codec bcm2835-codec: Device registered as /dev/video18
[ 7.308395] bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx
[ 7.321753] bcm2835-codec bcm2835-codec: Device registered as /dev/video31
[ 7.321803] bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image
[ 7.418490] Bluetooth: Core ver 2.22
[ 7.418619] NET: Registered PF_BLUETOOTH protocol family
[ 7.418628] Bluetooth: HCI device and connection manager initialized
[ 7.418654] Bluetooth: HCI socket layer initialized
[ 7.418674] Bluetooth: L2CAP socket layer initialized
[ 7.418697] Bluetooth: SCO socket layer initialized
[ 7.422404] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[ 7.423481] cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf'
[ 7.438178] cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328'
[ 7.443282] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[ 7.446555] cfg80211: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'
[ 7.466292] Bluetooth: HCI UART driver ver 2.3
[ 7.466330] Bluetooth: HCI UART protocol H4 registered
[ 7.467634] Bluetooth: HCI UART protocol Three-wire (H5) registered
[ 7.468580] Bluetooth: HCI UART protocol Broadcom registered
[ 7.470076] hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator
[ 7.470408] hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator
[ 7.587263] uart-pl011 fe201000.serial: no DMA platform data
[ 7.668430] arducam-pivariety 10-000c: Consider updating driver arducam-pivariety to match on endpoints
[ 7.823149] brcmfmac: F1 signature read @0x18000000=0x15264345
[ 7.827858] Bluetooth: hci0: BCM: chip id 107
[ 7.828215] Bluetooth: hci0: BCM: features 0x2f
[ 7.829411] Bluetooth: hci0: BCM4345C0
[ 7.829436] Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000
[ 7.836958] Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.raspberrypi,4-model-b.hcd' Patch
[ 7.894025] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[ 7.895638] usbcore: registered new interface driver brcmfmac
[ 7.969711] i2c-bcm2835 fe804000.i2c: i2c transfer timed out
[ 8.262869] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Apr 15 2021 03:03:20 version 7.45.234 (4ca95bb CY) FWID 01-996384e2
[ 8.574582] Adding 102396k swap on /var/swap. Priority:-2 extents:1 across:102396k SSFS
[ 8.725227] Bluetooth: hci0: BCM: features 0x2f
[ 8.726630] Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+-0190
[ 8.726662] Bluetooth: hci0: BCM4345C0 (003.001.025) build 0382
[ 8.991504] i2c-bcm2835 fe804000.i2c: i2c transfer timed out
[ 8.991542] pcm512x 1-004d: Failed to reset device: -110
[ 8.991881] pcm512x: probe of 1-004d failed with error -110
[ 9.749436] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[ 9.749453] Bluetooth: BNEP filters: protocol multicast
[ 9.749465] Bluetooth: BNEP socket layer initialized
[ 9.754244] Bluetooth: MGMT ver 1.22
[ 9.770738] NET: Registered PF_ALG protocol family
[ 10.018966] i2c-bcm2835 fe804000.i2c: i2c transfer timed out
[ 10.018989] pcm186x 1-004a: failed to write device: -110
[ 10.019028] ------------[ cut here ]------------
[ 10.019030] WARNING: CPU: 2 PID: 456 at drivers/regulator/core.c:2403 _regulator_put+0x170/0x180
[ 10.019043] Modules linked in: cmac algif_hash aes_arm64 aes_generic algif_skcipher af_alg bnep brcmfmac brcmutil hci_uart btbcm bluetooth cfg80211 arducam_pivariety snd_soc_pcm186x_i2c(+) snd_soc_pcm186x bcm2835_unicam snd_soc_pcm512x_i2c bcm2835_codec(C) snd_soc_pcm512x ecdh_generic rpivid_hevc(C) bcm2835_v4l2(C) bcm2835_isp(C) v4l2_dv_timings ecc bcm2835_mmal_vchiq(C) regmap_i2c libaes videobuf2_vmalloc v4l2_fwnode v4l2_mem2mem v4l2_async videobuf2_dma_contig videobuf2_memops raspberrypi_hwmon snd_soc_hifiberry_dacplusadcpro rfkill videobuf2_v4l2 binfmt_misc videobuf2_common snd_soc_bcm2835_i2s videodev snd_bcm2835(C) raspberrypi_gpiomem vc_sm_cma(C) mc joydev nvmem_rmem uio_pdrv_genirq uio i2c_dev fuse dm_mod ip_tables x_tables ipv6 vc4 snd_soc_hdmi_codec drm_display_helper cec drm_dma_helper drm_kms_helper syscopyarea sysfillrect sysimgblt fb_sys_fops v3d gpu_sched drm_shmem_helper i2c_mux_pinctrl drm snd_soc_core drm_panel_orientation_quirks i2c_mux snd_compress snd_pcm_dmaengine
[ 10.019160] i2c_brcmstb snd_pcm i2c_bcm2835 snd_timer snd backlight clk_hifiberry_dacpro
[ 10.019174] CPU: 2 PID: 456 Comm: modprobe Tainted: G C 6.1.0-rpi8-rpi-v8 #1 Debian 1:6.1.73-1+rpt1
[ 10.019180] Hardware name: Raspberry Pi 4 Model B Rev 1.5 (DT)
[ 10.019182] pstate: 80000005 (Nzcv daif -PAN -UAO -TCO -DIT -SSBS BTYPE=--)
[ 10.019186] pc : _regulator_put+0x170/0x180
[ 10.019190] lr : regulator_bulk_free+0x54/0x80
[ 10.019194] sp : ffffffc00895b7e0
[ 10.019196] x29: ffffffc00895b7e0 x28: 0000000000000000 x27: 0000000000000000
[ 10.019202] x26: 0000000000000000 x25: ffffffd513adc158 x24: ffffffd590e6d858
[ 10.019209] x23: ffffffd590b45008 x22: ffffff8103fa99f8 x21: ffffff8103fa9b00
[ 10.019214] x20: ffffffd590fbb570 x19: ffffff8103fa9b00 x18: 00000000fffffffe
[ 10.019220] x17: 0000000000000000 x16: ffffffd5905f16f0 x15: ffffffc00895b2a0
[ 10.019226] x14: 0000000000000000 x13: 3031312d203a6563 x12: 6976656420657469
[ 10.019232] x11: 0000000000000001 x10: ffffff8103fbfaa8 x9 : ffffffd59012d254
[ 10.019237] x8 : ffffffc00895b7f0 x7 : 0000000000000000 x6 : ffffff8103fbfaa8
[ 10.019243] x5 : 0000000000000010 x4 : 0000000000000000 x3 : ffffffd590fbb570
[ 10.019249] x2 : ffffff8101285c40 x1 : 0000000000000000 x0 : 0000000000000001
[ 10.019255] Call trace:
[ 10.019257] _regulator_put+0x170/0x180
[ 10.019262] regulator_bulk_free+0x54/0x80
[ 10.019265] devm_regulator_bulk_release+0x24/0x30
[ 10.019271] release_nodes+0x68/0x100
[ 10.019277] devres_release_group+0xd4/0x144
[ 10.019281] i2c_device_probe+0x170/0x310
[ 10.019288] really_probe+0xc4/0x2e0
[ 10.019292] __driver_probe_device+0x80/0x120
[ 10.019295] driver_probe_device+0xe0/0x164
[ 10.019299] __driver_attach+0x9c/0x1a4
[ 10.019302] bus_for_each_dev+0x7c/0xe0
[ 10.019308] driver_attach+0x2c/0x40
[ 10.019311] bus_add_driver+0x15c/0x210
[ 10.019314] driver_register+0x80/0x13c
[ 10.019317] i2c_register_driver+0x50/0xd0
[ 10.019322] pcm186x_i2c_driver_init+0x28/0x1000 [snd_soc_pcm186x_i2c]
[ 10.019336] do_one_initcall+0x60/0x2d0
[ 10.019341] do_init_module+0x50/0x1f0
[ 10.019347] load_module+0x1b1c/0x1f74
[ 10.019351] __do_sys_init_module+0x198/0x1d0
[ 10.019356] __arm64_sys_init_module+0x24/0x30
[ 10.019360] invoke_syscall+0x50/0x120
[ 10.019366] el0_svc_common.constprop.0+0xd4/0xf4
[ 10.019371] do_el0_svc+0x34/0xd0
[ 10.019375] el0_svc+0x2c/0x84
[ 10.019381] el0t_64_sync_handler+0xf4/0x120
[ 10.019386] el0t_64_sync+0x18c/0x190
[ 10.019389] ---[ end trace 0000000000000000 ]---
[ 10.019443] ------------[ cut here ]------------
[ 10.019444] WARNING: CPU: 2 PID: 456 at drivers/regulator/core.c:2403 _regulator_put+0x170/0x180
[ 10.019450] Modules linked in: cmac algif_hash aes_arm64 aes_generic algif_skcipher af_alg bnep brcmfmac brcmutil hci_uart btbcm bluetooth cfg80211 arducam_pivariety snd_soc_pcm186x_i2c(+) snd_soc_pcm186x bcm2835_unicam snd_soc_pcm512x_i2c bcm2835_codec(C) snd_soc_pcm512x ecdh_generic rpivid_hevc(C) bcm2835_v4l2(C) bcm2835_isp(C) v4l2_dv_timings ecc bcm2835_mmal_vchiq(C) regmap_i2c libaes videobuf2_vmalloc v4l2_fwnode v4l2_mem2mem v4l2_async videobuf2_dma_contig videobuf2_memops raspberrypi_hwmon snd_soc_hifiberry_dacplusadcpro rfkill videobuf2_v4l2 binfmt_misc videobuf2_common snd_soc_bcm2835_i2s videodev snd_bcm2835(C) raspberrypi_gpiomem vc_sm_cma(C) mc joydev nvmem_rmem uio_pdrv_genirq uio i2c_dev fuse dm_mod ip_tables x_tables ipv6 vc4 snd_soc_hdmi_codec drm_display_helper cec drm_dma_helper drm_kms_helper syscopyarea sysfillrect sysimgblt fb_sys_fops v3d gpu_sched drm_shmem_helper i2c_mux_pinctrl drm snd_soc_core drm_panel_orientation_quirks i2c_mux snd_compress snd_pcm_dmaengine
[ 10.019546] i2c_brcmstb snd_pcm i2c_bcm2835 snd_timer snd backlight clk_hifiberry_dacpro
[ 10.019557] CPU: 2 PID: 456 Comm: modprobe Tainted: G WC 6.1.0-rpi8-rpi-v8 #1 Debian 1:6.1.73-1+rpt1
[ 10.019561] Hardware name: Raspberry Pi 4 Model B Rev 1.5 (DT)
[ 10.019562] pstate: 80000005 (Nzcv daif -PAN -UAO -TCO -DIT -SSBS BTYPE=--)
[ 10.019566] pc : _regulator_put+0x170/0x180
[ 10.019569] lr : regulator_bulk_free+0x54/0x80
[ 10.019572] sp : ffffffc00895b7e0
[ 10.019574] x29: ffffffc00895b7e0 x28: 0000000000000000 x27: 0000000000000000
[ 10.019580] x26: 0000000000000000 x25: ffffffd513adc158 x24: ffffffd590e6d858
[ 10.019586] x23: ffffffd590b45008 x22: ffffff8103fa99f8 x21: ffffff8103fa9d00
[ 10.019592] x20: ffffffd590fbb570 x19: ffffff8103fa9d00 x18: 00000000fffffffe
[ 10.019598] x17: 0000000000000000 x16: ffffffd5905f16f0 x15: ffffffc00895b2a0
[ 10.019604] x14: 0000000000000004 x13: ffffff8100006a10 x12: 0000000000000000
[ 10.019610] x11: ffffff810061ba98 x10: ffffff810061b908 x9 : ffffffd59012d254
[ 10.019615] x8 : ffffffc00895b770 x7 : 0000000000000000 x6 : 0000000000000000
[ 10.019621] x5 : ffffff810002c8f8 x4 : 0000000000000000 x3 : ffffffd590fbb570
[ 10.019627] x2 : ffffff8101285c40 x1 : 0000000000000000 x0 : 0000000000000001
[ 10.019632] Call trace:
[ 10.019634] _regulator_put+0x170/0x180
[ 10.019637] regulator_bulk_free+0x54/0x80
[ 10.019641] devm_regulator_bulk_release+0x24/0x30
[ 10.019645] release_nodes+0x68/0x100
[ 10.019650] devres_release_group+0xd4/0x144
[ 10.019654] i2c_device_probe+0x170/0x310
[ 10.019660] really_probe+0xc4/0x2e0
[ 10.019663] __driver_probe_device+0x80/0x120
[ 10.019666] driver_probe_device+0xe0/0x164
[ 10.019669] __driver_attach+0x9c/0x1a4
[ 10.019672] bus_for_each_dev+0x7c/0xe0
[ 10.019678] driver_attach+0x2c/0x40
[ 10.019680] bus_add_driver+0x15c/0x210
[ 10.019683] driver_register+0x80/0x13c
[ 10.019687] i2c_register_driver+0x50/0xd0
[ 10.019692] pcm186x_i2c_driver_init+0x28/0x1000 [snd_soc_pcm186x_i2c]
[ 10.019700] do_one_initcall+0x60/0x2d0
[ 10.019704] do_init_module+0x50/0x1f0
[ 10.019708] load_module+0x1b1c/0x1f74
[ 10.019712] __do_sys_init_module+0x198/0x1d0
[ 10.019716] __arm64_sys_init_module+0x24/0x30
[ 10.019721] invoke_syscall+0x50/0x120
[ 10.019726] el0_svc_common.constprop.0+0xd4/0xf4
[ 10.019730] do_el0_svc+0x34/0xd0
[ 10.019735] el0_svc+0x2c/0x84
[ 10.019739] el0t_64_sync_handler+0xf4/0x120
[ 10.019743] el0t_64_sync+0x18c/0x190
[ 10.019747] ---[ end trace 0000000000000000 ]---
[ 10.019775] ------------[ cut here ]------------
[ 10.019777] WARNING: CPU: 2 PID: 456 at drivers/regulator/core.c:2403 _regulator_put+0x170/0x180
[ 10.019782] Modules linked in: cmac algif_hash aes_arm64 aes_generic algif_skcipher af_alg bnep brcmfmac brcmutil hci_uart btbcm bluetooth cfg80211 arducam_pivariety snd_soc_pcm186x_i2c(+) snd_soc_pcm186x bcm2835_unicam snd_soc_pcm512x_i2c bcm2835_codec(C) snd_soc_pcm512x ecdh_generic rpivid_hevc(C) bcm2835_v4l2(C) bcm2835_isp(C) v4l2_dv_timings ecc bcm2835_mmal_vchiq(C) regmap_i2c libaes videobuf2_vmalloc v4l2_fwnode v4l2_mem2mem v4l2_async videobuf2_dma_contig videobuf2_memops raspberrypi_hwmon snd_soc_hifiberry_dacplusadcpro rfkill videobuf2_v4l2 binfmt_misc videobuf2_common snd_soc_bcm2835_i2s videodev snd_bcm2835(C) raspberrypi_gpiomem vc_sm_cma(C) mc joydev nvmem_rmem uio_pdrv_genirq uio i2c_dev fuse dm_mod ip_tables x_tables ipv6 vc4 snd_soc_hdmi_codec drm_display_helper cec drm_dma_helper drm_kms_helper syscopyarea sysfillrect sysimgblt fb_sys_fops v3d gpu_sched drm_shmem_helper i2c_mux_pinctrl drm snd_soc_core drm_panel_orientation_quirks i2c_mux snd_compress snd_pcm_dmaengine
[ 10.019877] i2c_brcmstb snd_pcm i2c_bcm2835 snd_timer snd backlight clk_hifiberry_dacpro
[ 10.019887] CPU: 2 PID: 456 Comm: modprobe Tainted: G WC 6.1.0-rpi8-rpi-v8 #1 Debian 1:6.1.73-1+rpt1
[ 10.019891] Hardware name: Raspberry Pi 4 Model B Rev 1.5 (DT)
[ 10.019893] pstate: 80000005 (Nzcv daif -PAN -UAO -TCO -DIT -SSBS BTYPE=--)
[ 10.019897] pc : _regulator_put+0x170/0x180
[ 10.019900] lr : regulator_bulk_free+0x54/0x80
[ 10.019903] sp : ffffffc00895b7e0
[ 10.019905] x29: ffffffc00895b7e0 x28: 0000000000000000 x27: 0000000000000000
[ 10.019911] x26: 0000000000000000 x25: ffffffd513adc158 x24: ffffffd590e6d858
[ 10.019917] x23: ffffffd590b45008 x22: ffffff8103fa99f8 x21: ffffff8103fa9e00
[ 10.019923] x20: ffffffd590fbb570 x19: ffffff8103fa9e00 x18: 00000000fffffffe
[ 10.019928] x17: 0000000000000000 x16: ffffffd5905f16f0 x15: ffffffc00895b2a0
[ 10.019934] x14: 0000000000000004 x13: ffffff8100006a10 x12: 0000000000000000
[ 10.019940] x11: ffffff810061bb00 x10: ffffff810061b908 x9 : ffffffd59012d254
[ 10.019946] x8 : ffffffc00895b770 x7 : 0000000000000000 x6 : 0000000000000000
[ 10.019951] x5 : ffffff810002c8f8 x4 : 0000000000000000 x3 : ffffffd590fbb570
[ 10.019957] x2 : ffffff8101285c40 x1 : 0000000000000000 x0 : 0000000000000001
[ 10.019962] Call trace:
[ 10.019964] _regulator_put+0x170/0x180
[ 10.019967] regulator_bulk_free+0x54/0x80
[ 10.019971] devm_regulator_bulk_release+0x24/0x30
[ 10.019975] release_nodes+0x68/0x100
[ 10.019980] devres_release_group+0xd4/0x144
[ 10.019984] i2c_device_probe+0x170/0x310
[ 10.019989] really_probe+0xc4/0x2e0
[ 10.019992] __driver_probe_device+0x80/0x120
[ 10.019996] driver_probe_device+0xe0/0x164
[ 10.019999] __driver_attach+0x9c/0x1a4
[ 10.020002] bus_for_each_dev+0x7c/0xe0
[ 10.020007] driver_attach+0x2c/0x40
[ 10.020010] bus_add_driver+0x15c/0x210
[ 10.020013] driver_register+0x80/0x13c
[ 10.020017] i2c_register_driver+0x50/0xd0
[ 10.020022] pcm186x_i2c_driver_init+0x28/0x1000 [snd_soc_pcm186x_i2c]
[ 10.020029] do_one_initcall+0x60/0x2d0
[ 10.020033] do_init_module+0x50/0x1f0
[ 10.020037] load_module+0x1b1c/0x1f74
[ 10.020041] __do_sys_init_module+0x198/0x1d0
[ 10.020045] __arm64_sys_init_module+0x24/0x30
[ 10.020049] invoke_syscall+0x50/0x120
[ 10.020054] el0_svc_common.constprop.0+0xd4/0xf4
[ 10.020059] do_el0_svc+0x34/0xd0
[ 10.020064] el0_svc+0x2c/0x84
[ 10.020068] el0t_64_sync_handler+0xf4/0x120
[ 10.020072] el0t_64_sync+0x18c/0x190
[ 10.020075] ---[ end trace 0000000000000000 ]---
[ 10.020198] pcm186x: probe of 1-004a failed with error -110
[ 10.372746] bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay)
[ 10.373290] bcmgenet fd580000.ethernet eth0: Link is Down
[ 10.396104] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
[ 11.042973] i2c-bcm2835 fe804000.i2c: i2c transfer timed out
[ 13.497190] Bluetooth: RFCOMM TTY layer initialized
[ 13.497214] Bluetooth: RFCOMM socket layer initialized
[ 13.497226] Bluetooth: RFCOMM ver 1.11
[ 15.916288] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[ 21.214984] i2c-bcm2835 fe804000.i2c: i2c transfer timed out
[ 21.215083] platform soc:sound: deferred probe pending

Comment actions Permalink
0

HiFiBerry team March 08, 2024 09:20

Unfortunately I can't reproduce it here. That's a fresh Raspberry Pi OS installation:

uname -a
Linux raspberrypi 6.1.0-rpi7-rpi-v8 #1 SMP PREEMPT Debian 1:6.1.63-1+rpt1 (2023-11-24) aarch64 GNU/Linux

aplay -l
**** List of PLAYBACK Hardware Devices ****
card 0: sndrpihifiberry [snd_rpi_hifiberry_dacplusadcpro], device 0: HiFiBerry DAC+ADC Pro HiFi multicodec-0 [HiFiBerry DAC+ADC Pro HiFi multicodec-0]
Subdevices: 1/1
Subdevice #0: subdevice #0

cat /sys/firmware/devicetree/base/model
Raspberry Pi 4 Model B Rev 1.5

The only thing I can offer is to try to debug it with your hardware. We would need both the Pi and the DAC+ ADC Pro here to have a look at it.

Edited by HiFiBerry team March 08, 2024 09:21

Comment actions Permalink
0

gianluca March 08, 2024 11:23

ok but before going for the "last call" and ship it all to you ... what about the dmesg log I sent you? can you tell me something about the location of the kernel crash? it seems related to your software to me.

what is that regulator_put call in your code? how do we debug it?

I have the same hw model as you can see

Comment actions Permalink
0

gianluca March 08, 2024 11:25

also this line seems weird to me:

[ 8.611540] CPU: 3 PID: 443 Comm: modprobe Tainted: G WC 6.1.0-rpi8-rpi-v8 #1 Debian 1:6.1.73-1+rpt1

it is like there is a driver mismatch...

note:

pi@phd2cam:~ $ uname -a
Linux phd2cam 6.1.0-rpi8-rpi-v8 #1 SMP PREEMPT Debian 1:6.1.73-1+rpt1 (2024-01-25) aarch64 GNU/Linux

Comment actions Permalink
0

HiFiBerry team March 08, 2024 11:31

The dmesg output only shows that there is some crash. However, there is no way to "debug" it based on a stack trace. This crash happens deep in the I2C part of the kernel.

As you're the only user experiencing this, my best guess is that there is some hardware issue with your Pi. But that's just a guess. The best you can do by yourself is try with another Pi.

Comment actions Permalink
0

gianluca March 08, 2024 11:39

ok.

question: is there some eeprom on your hat that needs some update? asking because I'm seeing a timeout on i2c

[ 9.953505] i2c-bcm2835 fe804000.i2c: i2c transfer timed out
[ 9.953536] pcm186x 1-004a: failed to write device: -110

I had a similar issue on a different hat, after updating the onboard fw everything worked no problem..

maybe I got this hifiberry dac/adc/pro shield from a vendor who had a very old stock?

Comment actions Permalink
0

gianluca March 08, 2024 11:39

and of course the above i2c timeout only happens if I try to enable the hifiberry overlay .. and there is only your board attached on my pi-4

Comment actions Permalink
0

HiFiBerry team March 08, 2024 11:41

If you used force_eeprom_read=0 correctly, no EEPROM on the board is used at all.

Comment actions Permalink
0

gianluca March 08, 2024 11:46

ok, thanks a lot for your reply (and patience)

I'm going to try on a fresh rpi4 and fresh sdcard.

meantime could you please post your config.txt ?

Comment actions Permalink
0

HiFiBerry team March 08, 2024 11:51

Don't have it here now. But nothing special. There just are 2 important lines:

force_eeprom_read=0
dtoverlay=hifiberry-dacplusadcpro

Comment actions Permalink
0

gianluca March 08, 2024 12:31

hey... I just did a FRESH install of the default Bookworm image on a BRAND NEW rpi4 (8G ram).

it seems to work

**** List of PLAYBACK Hardware Devices ****
card 0: sndrpihifiberry [snd_rpi_hifiberry_dacplusadcpro], device 0: HiFiBerry DAC+ADC Pro HiFi multicodec-0 [HiFiBerry DAC+ADC Pro HiFi multicodec-0]
Subdevices: 1/1
Subdevice #0: subdevice #0

now I need to understand what is wrong on the other setup

:-(

thanks for your help

Comment actions Permalink

Please sign in to leave a comment.