Closed kpishere closed 2 years ago
The driver does not start reliably after reboot ... investigating ..
Reboot dmesg log
dmesg [ 0.000000] Booting Linux on physical CPU 0x0 [ 0.000000] Linux version 5.15.32+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1538 Thu Mar 31 19:37:58 BST 2022 [ 0.000000] CPU: ARMv6-compatible processor [410fb767] revision 7 (ARMv7), cr=00c5387d [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT nonaliasing instruction cache [ 0.000000] OF: fdt: Machine model: Raspberry Pi Zero Rev 1.3 [ 0.000000] random: fast init done [ 0.000000] Memory policy: Data cache writeback [ 0.000000] Reserved memory: created CMA memory pool at 0x0e400000, size 256 MiB [ 0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool [ 0.000000] Zone ranges: [ 0.000000] Normal [mem 0x0000000000000000-0x000000001effffff] [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000000000-0x000000001effffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000001effffff] [ 0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768 [ 0.000000] pcpu-alloc: [0] 0 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 125860 [ 0.000000] Kernel command line: coherent_pool=1M snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 video=HDMI-A-1:720x576M@50,margin_left=32,margin_right=32,margin_top=32,margin_bottom=32 smsc95xx.macaddr=B8:27:EB:91:26:54 vc_mem.mem_base=0x1fa00000 vc_mem.mem_size=0x20000000 boot=overlay console=tty1 root=PARTUUID=de47510e-02 rootfstype=ext4 fsck.repair=yes rootwait modules-load=dwc2,g_ether [ 0.000000] Unknown kernel command line parameters "boot=overlay modules-load=dwc2,g_ether", will be passed to user space. [ 0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes, linear) [ 0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes, linear) [ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off [ 0.000000] Memory: 216784K/507904K available (8994K kernel code, 1338K rwdata, 2932K rodata, 436K init, 546K bss, 28976K reserved, 262144K cma-reserved) [ 0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 [ 0.000000] ftrace: allocating 31774 entries in 94 pages [ 0.000000] ftrace: allocated 94 pages with 5 groups [ 0.000000] trace event string verifier disabled [ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x474/0x6d8 with crng_init=1 [ 0.000006] sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns [ 0.000098] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns [ 0.000212] bcm2835: system timer (irq = 27) [ 0.000994] Console: colour dummy device 80x30 [ 0.001723] printk: console [tty1] enabled [ 0.001830] Calibrating delay loop... 697.95 BogoMIPS (lpj=3489792) [ 0.060384] pid_max: default: 32768 minimum: 301 [ 0.060666] LSM: Security Framework initializing [ 0.060949] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes, linear) [ 0.061037] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes, linear) [ 0.063476] cgroup: Disabling memory control group subsystem [ 0.063995] CPU: Testing write buffer coherency: ok [ 0.066185] Setting up static identity map for 0x8220 - 0x8258 [ 0.067781] devtmpfs: initialized [ 0.083001] VFP support v0.3: implementor 41 architecture 1 part 20 variant b rev 5 [ 0.083481] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns [ 0.083588] futex hash table entries: 256 (order: -1, 3072 bytes, linear) [ 0.125559] pinctrl core: initialized pinctrl subsystem [ 0.127986] NET: Registered PF_NETLINK/PF_ROUTE protocol family [ 0.132848] DMA: preallocated 1024 KiB pool for atomic coherent allocations [ 0.140409] audit: initializing netlink subsys (disabled) [ 0.141192] audit: type=2000 audit(0.140:1): state=initialized audit_enabled=0 res=1 [ 0.142444] thermal_sys: Registered thermal governor 'step_wise' [ 0.143471] hw-breakpoint: found 6 breakpoint and 1 watchpoint registers. [ 0.143581] hw-breakpoint: maximum watchpoint size is 4 bytes. [ 0.143924] Serial: AMBA PL011 UART driver [ 0.156607] bcm2835-mbox 2000b880.mailbox: mailbox enabled [ 0.191059] raspberrypi-firmware soc:firmware: Attached to firmware from 2022-03-24T13:21:11, variant start_cd [ 0.201092] raspberrypi-firmware soc:firmware: Firmware hash is e5a963efa66a1974127860b42e913d2374139ff5 [ 0.260307] Kprobes globally optimized [ 0.270785] bcm2835-dma 20007000.dma: DMA legacy API manager, dmachans=0x1 [ 0.274476] SCSI subsystem initialized [ 0.275115] usbcore: registered new interface driver usbfs [ 0.275299] usbcore: registered new interface driver hub [ 0.275461] usbcore: registered new device driver usb [ 0.276028] usb_phy_generic phy: supply vcc not found, using dummy regulator [ 0.276947] pps_core: LinuxPPS API ver. 1 registered [ 0.277021] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it> [ 0.277119] PTP clock support registered [ 0.280140] clocksource: Switched to clocksource timer [ 0.368461] VFS: Disk quotas dquot_6.6.0 [ 0.368698] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 0.369013] FS-Cache: Loaded [ 0.369494] CacheFiles: Loaded [ 0.370704] simple-framebuffer 1f957000.framebuffer: framebuffer at 0x1f957000, 0xa4000 bytes [ 0.370812] simple-framebuffer 1f957000.framebuffer: format=r5g6b5, mode=656x512x16, linelength=1312 [ 2.310779] Console: switching to colour frame buffer device 82x32 [ 2.313850] simple-framebuffer 1f957000.framebuffer: fb0: simplefb registered! [ 2.335545] NET: Registered PF_INET protocol family [ 2.338158] IP idents hash table entries: 8192 (order: 4, 65536 bytes, linear) [ 2.342223] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear) [ 2.346660] TCP established hash table entries: 4096 (order: 2, 16384 bytes, linear) [ 2.351213] TCP bind hash table entries: 4096 (order: 2, 16384 bytes, linear) [ 2.353623] TCP: Hash tables configured (established 4096 bind 4096) [ 2.356202] UDP hash table entries: 256 (order: 0, 4096 bytes, linear) [ 2.358665] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes, linear) [ 2.361658] NET: Registered PF_UNIX/PF_LOCAL protocol family [ 2.365631] RPC: Registered named UNIX socket transport module. [ 2.368120] RPC: Registered udp transport module. [ 2.370534] RPC: Registered tcp transport module. [ 2.372800] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 2.375702] Trying to unpack rootfs image as initramfs... [ 2.393025] armv6-pmu arm-pmu: hw perfevents: no irqs for PMU, sampling events not supported [ 2.397787] hw perfevents: enabled with armv6_1176 PMU driver, 3 counters available [ 2.415488] Initialise system trusted keyrings [ 2.430628] workingset: timestamp_bits=14 max_order=17 bucket_order=3 [ 2.458820] zbud: loaded [ 2.474041] FS-Cache: Netfs 'nfs' registered for caching [ 2.481738] NFS: Registering the id_resolver key type [ 2.484240] Key type id_resolver registered [ 2.486514] Key type id_legacy registered [ 2.488961] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 2.491366] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... [ 2.512913] Key type asymmetric registered [ 2.515202] Asymmetric key parser 'x509' registered [ 2.517536] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.521803] io scheduler mq-deadline registered [ 2.523926] io scheduler kyber registered [ 2.563673] bcm2835-rng 20104000.rng: hwrng registered [ 2.566659] vc-mem: phys_addr:0x00000000 mem_base=0x1fa00000 mem_size:0x20000000(512 MiB) [ 2.582743] gpiomem-bcm2835 20200000.gpiomem: Initialised: Registers at 0x20200000 [ 2.644381] brd: module loaded [ 2.671722] loop: module loaded [ 2.680885] Loading iSCSI transport class v2.0-870. [ 2.693690] usbcore: registered new interface driver smsc95xx [ 2.695931] dwc_otg: version 3.00a 10-AUG-2012 (platform bus) [ 2.698349] dwc_otg: FIQ enabled [ 2.698379] dwc_otg: NAK holdoff enabled [ 2.698396] dwc_otg: FIQ split-transaction FSM enabled [ 2.698427] Module dwc_common_port init [ 2.699007] usbcore: registered new interface driver usb-storage [ 2.711893] mousedev: PS/2 mouse device common for all mice [ 2.718242] sdhci: Secure Digital Host Controller Interface driver [ 2.720538] sdhci: Copyright(c) Pierre Ossman [ 2.732276] sdhci-pltfm: SDHCI platform and OF driver helper [ 2.736380] ledtrig-cpu: registered to indicate activity on CPUs [ 2.739122] hid: raw HID events driver (C) Jiri Kosina [ 2.750643] usbcore: registered new interface driver usbhid [ 2.752842] usbhid: USB HID core driver [ 2.781352] Initializing XFRM netlink socket [ 2.783532] NET: Registered PF_PACKET protocol family [ 2.785756] Key type dns_resolver registered [ 2.788849] registered taskstats version 1 [ 2.801741] Loading compiled-in X.509 certificates [ 2.844708] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer [ 2.847603] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver [ 2.862889] mmc-bcm2835 20300000.mmc: mmc_debug:0 mmc_debug2:0 [ 2.865054] mmc-bcm2835 20300000.mmc: DMA channel allocated [ 2.920463] sdhost: log_buf @ (ptrval) (8e542000) [ 3.001582] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) [ 3.027780] of_cfs_init [ 3.042295] of_cfs_init: OK [ 3.139446] mmc1: Controller never released inhibit bit(s). [ 3.141513] mmc1: Got command interrupt 0x00030000 even though no command operation was in progress. [ 3.161345] mmc0: host does not support reading read-only switch, assuming write-enable [ 3.169172] mmc0: new high speed SDHC card at address 0002 [ 3.181606] mmcblk0: mmc0:0002 N/A 29.8 GiB [ 3.187456] mmc1: Got command interrupt 0x00030000 even though no command operation was in progress. [ 3.191550] mmc1: Got command interrupt 0x00030000 even though no command operation was in progress. [ 3.211932] mmc1: Controller never released inhibit bit(s). [ 3.234220] mmcblk0: p1 p2 [ 3.237816] mmcblk0: mmc0:0002 N/A 29.8 GiB [ 4.072205] Freeing initrd memory: 9176K [ 4.083067] Freeing unused kernel image (initmem) memory: 436K [ 4.085353] Kernel memory protection not selected by kernel config. [ 4.087565] Run /init as init process [ 4.089717] with arguments: [ 4.089737] /init [ 4.089754] with environment: [ 4.089767] HOME=/ [ 4.089780] TERM=linux [ 4.089794] boot=overlay [ 4.089807] modules-load=dwc2,g_ether [ 4.451192] mmc1: Got command interrupt 0x00030000 even though no command operation was in progress. [ 4.455611] mmc1: Got command interrupt 0x00030000 even though no command operation was in progress. [ 4.480495] mmc1: Controller never released inhibit bit(s). [ 4.525648] mmc-bcm2835 20300000.mmc: no support for card's volts
Boot after cold start:
dmesg [ 0.000000] Booting Linux on physical CPU 0x0 [ 0.000000] Linux version 5.15.32+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1538 Thu Mar 31 19:37:58 BST 2022 [ 0.000000] CPU: ARMv6-compatible processor [410fb767] revision 7 (ARMv7), cr=00c5387d [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT nonaliasing instruction cache [ 0.000000] OF: fdt: Machine model: Raspberry Pi Zero Rev 1.3 [ 0.000000] random: fast init done [ 0.000000] Memory policy: Data cache writeback [ 0.000000] Reserved memory: created CMA memory pool at 0x0e400000, size 256 MiB [ 0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool [ 0.000000] Zone ranges: [ 0.000000] Normal [mem 0x0000000000000000-0x000000001effffff] [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000000000-0x000000001effffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000001effffff] [ 0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768 [ 0.000000] pcpu-alloc: [0] 0 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 125860 [ 0.000000] Kernel command line: coherent_pool=1M snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 video=HDMI-A-1:720x576M@50,margin_left=32,margin_right=32,margin_top=32,margin_bottom=32 smsc95xx.macaddr=B8:27:EB:91:26:54 vc_mem.mem_base=0x1fa00000 vc_mem.mem_size=0x20000000 boot=overlay console=tty1 root=PARTUUID=de47510e-02 rootfstype=ext4 fsck.repair=yes rootwait modules-load=dwc2,g_ether [ 0.000000] Unknown kernel command line parameters "boot=overlay modules-load=dwc2,g_ether", will be passed to user space. [ 0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes, linear) [ 0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes, linear) [ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off [ 0.000000] Memory: 216784K/507904K available (8994K kernel code, 1338K rwdata, 2932K rodata, 436K init, 546K bss, 28976K reserved, 262144K cma-reserved) [ 0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 [ 0.000000] ftrace: allocating 31774 entries in 94 pages [ 0.000000] ftrace: allocated 94 pages with 5 groups [ 0.000000] trace event string verifier disabled [ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x474/0x6d8 with crng_init=1 [ 0.000005] sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns [ 0.000096] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns [ 0.000208] bcm2835: system timer (irq = 27) [ 0.000991] Console: colour dummy device 80x30 [ 0.001717] printk: console [tty1] enabled [ 0.001824] Calibrating delay loop... 697.95 BogoMIPS (lpj=3489792) [ 0.060381] pid_max: default: 32768 minimum: 301 [ 0.060661] LSM: Security Framework initializing [ 0.060944] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes, linear) [ 0.061034] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes, linear) [ 0.063479] cgroup: Disabling memory control group subsystem [ 0.063996] CPU: Testing write buffer coherency: ok [ 0.066171] Setting up static identity map for 0x8220 - 0x8258 [ 0.067772] devtmpfs: initialized [ 0.082988] VFP support v0.3: implementor 41 architecture 1 part 20 variant b rev 5 [ 0.083466] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns [ 0.083574] futex hash table entries: 256 (order: -1, 3072 bytes, linear) [ 0.125521] pinctrl core: initialized pinctrl subsystem [ 0.127947] NET: Registered PF_NETLINK/PF_ROUTE protocol family [ 0.132814] DMA: preallocated 1024 KiB pool for atomic coherent allocations [ 0.140378] audit: initializing netlink subsys (disabled) [ 0.141169] audit: type=2000 audit(0.140:1): state=initialized audit_enabled=0 res=1 [ 0.142414] thermal_sys: Registered thermal governor 'step_wise' [ 0.143433] hw-breakpoint: found 6 breakpoint and 1 watchpoint registers. [ 0.143543] hw-breakpoint: maximum watchpoint size is 4 bytes. [ 0.143884] Serial: AMBA PL011 UART driver [ 0.156585] bcm2835-mbox 2000b880.mailbox: mailbox enabled [ 0.191056] raspberrypi-firmware soc:firmware: Attached to firmware from 2022-03-24T13:21:11, variant start_cd [ 0.201092] raspberrypi-firmware soc:firmware: Firmware hash is e5a963efa66a1974127860b42e913d2374139ff5 [ 0.260285] Kprobes globally optimized [ 0.270743] bcm2835-dma 20007000.dma: DMA legacy API manager, dmachans=0x1 [ 0.274426] SCSI subsystem initialized [ 0.275069] usbcore: registered new interface driver usbfs [ 0.275255] usbcore: registered new interface driver hub [ 0.275420] usbcore: registered new device driver usb [ 0.275990] usb_phy_generic phy: supply vcc not found, using dummy regulator [ 0.276908] pps_core: LinuxPPS API ver. 1 registered [ 0.276983] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it> [ 0.277084] PTP clock support registered [ 0.280115] clocksource: Switched to clocksource timer [ 0.368498] VFS: Disk quotas dquot_6.6.0 [ 0.368734] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 0.369047] FS-Cache: Loaded [ 0.369527] CacheFiles: Loaded [ 0.370736] simple-framebuffer 1f957000.framebuffer: framebuffer at 0x1f957000, 0xa4000 bytes [ 0.370844] simple-framebuffer 1f957000.framebuffer: format=r5g6b5, mode=656x512x16, linelength=1312 [ 2.310831] Console: switching to colour frame buffer device 82x32 [ 2.313903] simple-framebuffer 1f957000.framebuffer: fb0: simplefb registered! [ 2.335596] NET: Registered PF_INET protocol family [ 2.338214] IP idents hash table entries: 8192 (order: 4, 65536 bytes, linear) [ 2.342273] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear) [ 2.346715] TCP established hash table entries: 4096 (order: 2, 16384 bytes, linear) [ 2.351264] TCP bind hash table entries: 4096 (order: 2, 16384 bytes, linear) [ 2.353681] TCP: Hash tables configured (established 4096 bind 4096) [ 2.356273] UDP hash table entries: 256 (order: 0, 4096 bytes, linear) [ 2.358728] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes, linear) [ 2.361730] NET: Registered PF_UNIX/PF_LOCAL protocol family [ 2.365711] RPC: Registered named UNIX socket transport module. [ 2.368199] RPC: Registered udp transport module. [ 2.370611] RPC: Registered tcp transport module. [ 2.372884] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 2.375798] Trying to unpack rootfs image as initramfs... [ 2.393138] armv6-pmu arm-pmu: hw perfevents: no irqs for PMU, sampling events not supported [ 2.397908] hw perfevents: enabled with armv6_1176 PMU driver, 3 counters available [ 2.415461] Initialise system trusted keyrings [ 2.430842] workingset: timestamp_bits=14 max_order=17 bucket_order=3 [ 2.459053] zbud: loaded [ 2.474269] FS-Cache: Netfs 'nfs' registered for caching [ 2.481977] NFS: Registering the id_resolver key type [ 2.484473] Key type id_resolver registered [ 2.486752] Key type id_legacy registered [ 2.489196] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 2.491603] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... [ 2.513164] Key type asymmetric registered [ 2.515453] Asymmetric key parser 'x509' registered [ 2.517779] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.522056] io scheduler mq-deadline registered [ 2.524177] io scheduler kyber registered [ 2.563874] bcm2835-rng 20104000.rng: hwrng registered [ 2.566857] vc-mem: phys_addr:0x00000000 mem_base=0x1fa00000 mem_size:0x20000000(512 MiB) [ 2.582880] gpiomem-bcm2835 20200000.gpiomem: Initialised: Registers at 0x20200000 [ 2.644301] brd: module loaded [ 2.671656] loop: module loaded [ 2.680818] Loading iSCSI transport class v2.0-870. [ 2.693624] usbcore: registered new interface driver smsc95xx [ 2.695871] dwc_otg: version 3.00a 10-AUG-2012 (platform bus) [ 2.698293] dwc_otg: FIQ enabled [ 2.698323] dwc_otg: NAK holdoff enabled [ 2.698340] dwc_otg: FIQ split-transaction FSM enabled [ 2.698370] Module dwc_common_port init [ 2.710709] usbcore: registered new interface driver usb-storage [ 2.713449] mousedev: PS/2 mouse device common for all mice [ 2.719712] sdhci: Secure Digital Host Controller Interface driver [ 2.722010] sdhci: Copyright(c) Pierre Ossman [ 2.732210] sdhci-pltfm: SDHCI platform and OF driver helper [ 2.736319] ledtrig-cpu: registered to indicate activity on CPUs [ 2.739068] hid: raw HID events driver (C) Jiri Kosina [ 2.750628] usbcore: registered new interface driver usbhid [ 2.752820] usbhid: USB HID core driver [ 2.772093] Initializing XFRM netlink socket [ 2.774273] NET: Registered PF_PACKET protocol family [ 2.776501] Key type dns_resolver registered [ 2.779596] registered taskstats version 1 [ 2.791072] Loading compiled-in X.509 certificates [ 2.835042] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer [ 2.837930] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver [ 2.854155] mmc-bcm2835 20300000.mmc: mmc_debug:0 mmc_debug2:0 [ 2.856322] mmc-bcm2835 20300000.mmc: DMA channel allocated [ 2.893625] sdhost: log_buf @ (ptrval) (8e542000) [ 2.962027] mmc1: queuing unknown CIS tuple 0x01 [d9 01 ff] (3 bytes) [ 2.995914] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) [ 3.022463] of_cfs_init [ 3.024691] of_cfs_init: OK [ 3.032155] mmc1: queuing unknown CIS tuple 0x1a [01 01 00 02 07] (5 bytes) [ 3.061915] mmc1: queuing unknown CIS tuple 0x1b [c1 41 30 30 ff ff ff ff] (8 bytes) [ 3.082271] mmc1: new high speed SDIO card at address 0001 [ 3.128621] mmc0: host does not support reading read-only switch, assuming write-enable [ 3.147005] mmc0: new high speed SDHC card at address 0002 [ 3.161037] mmcblk0: mmc0:0002 N/A 29.8 GiB [ 3.168846] mmcblk0: p1 p2 [ 3.172320] mmcblk0: mmc0:0002 N/A 29.8 GiB [ 3.956992] Freeing initrd memory: 9176K [ 3.967613] Freeing unused kernel image (initmem) memory: 436K [ 3.969713] Kernel memory protection not selected by kernel config. [ 3.971813] Run /init as init process [ 3.973803] with arguments: [ 3.973822] /init [ 3.973839] with environment: [ 3.973852] HOME=/ [ 3.973866] TERM=linux [ 3.973880] boot=overlay [ 3.973893] modules-load=dwc2,g_ether [ 6.736292] dwc2 20980000.usb: supply vusb_d not found, using dummy regulator [ 6.738979] dwc2 20980000.usb: supply vusb_a not found, using dummy regulator [ 6.970471] dwc2 20980000.usb: EPs: 8, dedicated fifos, 4080 entries in SPRAM [ 6.974100] dwc2 20980000.usb: DWC OTG Controller [ 6.976197] dwc2 20980000.usb: new USB bus registered, assigned bus number 1 [ 6.978279] dwc2 20980000.usb: irq 33, io mem 0x20980000 [ 7.007175] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15 [ 7.011269] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 7.013353] usb usb1: Product: DWC OTG Controller [ 7.015437] usb usb1: Manufacturer: Linux 5.15.32+ dwc2_hsotg [ 7.017497] usb usb1: SerialNumber: 20980000.usb [ 7.076654] hub 1-0:1.0: USB hub found [ 7.085021] hub 1-0:1.0: 1 port detected [ 8.141334] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. [ 9.367410] systemd[1]: System time before build time, advancing clock. [ 9.615879] NET: Registered PF_INET6 protocol family [ 9.620890] Segment Routing with IPv6 [ 9.623165] In-situ OAM (IOAM) with IPv6 [ 9.751860] systemd[1]: systemd 247.3-7+rpi1 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +ZSTD +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=unified) [ 9.761911] systemd[1]: Detected architecture arm. [ 9.792335] systemd[1]: Set hostname to <klipper>. [ 13.339140] systemd[1]: Queued start job for default target Graphical Interface. [ 13.348346] random: systemd: uninitialized urandom read (16 bytes read) [ 13.361669] systemd[1]: Created slice system-getty.slice. [ 13.368078] random: systemd: uninitialized urandom read (16 bytes read) [ 13.373599] systemd[1]: Created slice system-modprobe.slice. [ 13.379529] random: systemd: uninitialized urandom read (16 bytes read) [ 13.385119] systemd[1]: Created slice system-systemd\x2dfsck.slice. [ 13.394047] systemd[1]: Created slice system-wpa_supplicant.slice. [ 13.402681] systemd[1]: Created slice User and Session Slice. [ 13.409779] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ 13.419347] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ 13.430854] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. [ 13.440532] systemd[1]: Reached target Local Encrypted Volumes. [ 13.448262] systemd[1]: Reached target Paths. [ 13.455577] systemd[1]: Reached target Slices. [ 13.462480] systemd[1]: Reached target Swap. [ 13.471433] systemd[1]: Listening on Syslog Socket. [ 13.479642] systemd[1]: Listening on fsck to fsckd communication Socket. [ 13.486953] systemd[1]: Listening on initctl Compatibility Named Pipe. [ 13.496263] systemd[1]: Listening on Journal Audit Socket. [ 13.504788] systemd[1]: Listening on Journal Socket (/dev/log). [ 13.513423] systemd[1]: Listening on Journal Socket. [ 13.525621] systemd[1]: Listening on udev Control Socket. [ 13.533685] systemd[1]: Listening on udev Kernel Socket. [ 13.542231] systemd[1]: Condition check resulted in Huge Pages File System being skipped. [ 13.557575] systemd[1]: Mounting POSIX Message Queue File System... [ 13.591101] systemd[1]: Mounting RPC Pipe File System... [ 13.645833] systemd[1]: Mounting Kernel Debug File System... [ 13.694037] systemd[1]: Mounting Kernel Trace File System... [ 13.732466] systemd[1]: Condition check resulted in Kernel Module supporting RPCSEC_GSS being skipped. [ 13.772727] systemd[1]: Starting Restore / save the current clock... [ 13.815443] systemd[1]: Starting Set the console keyboard layout... [ 13.873305] systemd[1]: Starting Create list of static device nodes for the current kernel... [ 13.952767] systemd[1]: Starting Load Kernel Module configfs... [ 14.091472] systemd[1]: Starting Load Kernel Module drm... [ 14.155901] systemd[1]: Starting Load Kernel Module fuse... [ 14.203341] systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. [ 14.243551] systemd[1]: Condition check resulted in File System Check on Root Device being skipped. [ 14.308276] systemd[1]: Starting Journal Service... [ 14.366015] fuse: init (API version 7.34) [ 14.422205] systemd[1]: Starting Load Kernel Modules... [ 14.521768] systemd[1]: Starting Remount Root and Kernel File Systems... [ 14.624349] systemd[1]: Starting Coldplug All udev Devices... [ 14.758793] systemd[1]: Mounted POSIX Message Queue File System. [ 14.827731] systemd[1]: Mounted RPC Pipe File System. [ 14.876334] systemd[1]: Mounted Kernel Debug File System. [ 14.934615] systemd[1]: Mounted Kernel Trace File System. [ 15.030461] systemd[1]: Finished Restore / save the current clock. [ 15.081012] systemd[1]: Finished Create list of static device nodes for the current kernel. [ 15.145603] systemd[1]: modprobe@configfs.service: Succeeded. [ 15.197654] systemd[1]: Finished Load Kernel Module configfs. [ 15.217445] systemd[1]: modprobe@drm.service: Succeeded. [ 15.300621] systemd[1]: Finished Load Kernel Module drm. [ 15.360965] systemd[1]: modprobe@fuse.service: Succeeded. [ 15.400988] systemd[1]: Finished Load Kernel Module fuse. [ 15.462681] using random self ethernet address [ 15.465010] using random host ethernet address [ 15.468646] usb0: HOST MAC 5a:c5:d5:5f:3f:a6 [ 15.470916] usb0: MAC 92:7b:03:ef:da:10 [ 15.520395] using random self ethernet address [ 15.522556] using random host ethernet address [ 15.524626] g_ether gadget: Ethernet Gadget, version: Memorial Day 2008 [ 15.526645] g_ether gadget: g_ether ready [ 15.528599] dwc2 20980000.usb: bound driver g_ether [ 15.571017] systemd[1]: Mounting FUSE Control File System... [ 15.721256] systemd[1]: Mounting Kernel Configuration File System... [ 15.786418] dwc2 20980000.usb: new device is high-speed [ 15.842429] dwc2 20980000.usb: new device is high-speed [ 15.897442] dwc2 20980000.usb: new device is high-speed [ 15.920602] systemd[1]: Finished Load Kernel Modules. [ 15.953079] dwc2 20980000.usb: new device is high-speed [ 16.000765] systemd[1]: Finished Remount Root and Kernel File Systems. [ 16.008585] dwc2 20980000.usb: new device is high-speed [ 16.037049] systemd[1]: Mounted FUSE Control File System. [ 16.060312] dwc2 20980000.usb: new address 21 [ 16.076229] systemd[1]: Mounted Kernel Configuration File System. [ 16.125032] systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. [ 16.129988] systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. [ 16.221054] systemd[1]: Starting Load/Save Random Seed... [ 16.321925] systemd[1]: Starting Apply Kernel Variables... [ 16.451177] systemd[1]: Starting Create System Users... [ 17.002300] systemd[1]: Finished Apply Kernel Variables. [ 17.106901] systemd[1]: Started Journal Service. [ 17.708288] systemd-journald[176]: Received client request to flush runtime journal. [ 18.148365] systemd-journald[176]: File /var/log/journal/6add6c07727f4a44a249ce70609f481a/system.journal corrupted or uncleanly shut down, renaming and replacing. [ 24.845334] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. [ 24.848194] bcm2835_vc_sm_cma_probe: Videocore shared memory driver [ 24.848255] [vc_sm_connected_init]: start [ 24.883166] vc_sm_cma_vchi_init: failed to open VCHI service (-1) [ 24.883221] [vc_sm_connected_init]: failed to initialize shared memory service [ 24.888348] mc: Linux media interface: v0.10 [ 25.230943] videodev: Linux video capture interface: v2.00 [ 25.410686] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. [ 25.414232] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. [ 25.418190] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. [ 25.489994] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. [ 25.508383] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. [ 25.575406] bcm2835_mmal_vchiq: Failed to open VCHI service connection (status=-1) [ 25.581525] : Unregister from media controller [ 25.581583] (efault): Unregistering node (null)[0] device node /dev/video0 [ 25.581616] (efault): Unregistering node (null)[0] device node /dev/video0 [ 25.581639] (efault): Unregistering node (null)[0] device node /dev/video0 [ 25.581661] (efault): Unregistering node (null)[0] device node /dev/video0 [ 25.613866] bcm2835_mmal_vchiq: Failed to open VCHI service connection (status=-1) [ 25.641317] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. [ 25.680218] bcm2835_mmal_vchiq: Failed to open VCHI service connection (status=-1) [ 28.595749] cfg80211: Loading compiled-in X.509 certificates for regulatory database [ 29.290217] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' [ 29.524283] cfg80211: loaded regulatory.db is malformed or signature is missing/invalid [ 32.165506] checking generic (1f957000 a4000) vs hw (0 ffffffff) [ 32.165563] fb0: switching to vc4 from simple [ 32.295902] Console: switching to colour dummy device 80x30 [ 32.311538] vc4-drm soc:gpu: bound 20400000.hvs (ops vc4_hvs_ops [vc4]) [ 32.367466] Registered IR keymap rc-cec [ 32.367882] rc rc0: vc4 as /devices/platform/soc/20902000.hdmi/rc/rc0 [ 32.368365] input: vc4 as /devices/platform/soc/20902000.hdmi/rc/rc0/input0 [ 32.399665] esp8089: loading out-of-tree module taints kernel. [ 32.518218] ***** EAGLE DRIVER VER:bdf5087c3deb***** [ 32.518385] ESP8089 reset via GPIO 5 [ 33.212099] mmc1: card 0001 removed [ 33.475811] mmc1: queuing unknown CIS tuple 0x01 [d9 01 ff] (3 bytes) [ 33.505760] mmc1: queuing unknown CIS tuple 0x1a [01 01 00 02 07] (5 bytes) [ 33.518755] mmc1: queuing unknown CIS tuple 0x1b [c1 41 30 30 ff ff ff ff] (8 bytes) [ 33.526965] mmc1: new high speed SDIO card at address 0001 [ 33.580777] esp_sdio_dummy_probe enter [ 33.590564] vc4-drm soc:gpu: bound 20400000.hvs (ops vc4_hvs_ops [vc4]) [ 33.612485] Registered IR keymap rc-cec [ 33.612916] rc rc0: vc4 as /devices/platform/soc/20902000.hdmi/rc/rc0 [ 33.613399] input: vc4 as /devices/platform/soc/20902000.hdmi/rc/rc0/input1 [ 33.802344] esp_sdio_init power up OK [ 34.410330] cam-dummy-reg: disabling [ 36.936375] vc4-drm soc:gpu: bound 20400000.hvs (ops vc4_hvs_ops [vc4]) [ 36.949516] Registered IR keymap rc-cec [ 36.949929] rc rc0: vc4 as /devices/platform/soc/20902000.hdmi/rc/rc0 [ 36.950526] input: vc4 as /devices/platform/soc/20902000.hdmi/rc/rc0/input2 [ 36.967822] vc4-drm soc:gpu: bound 20902000.hdmi (ops vc4_hdmi_ops [vc4]) [ 36.969083] vc4-drm soc:gpu: bound 20004000.txp (ops vc4_txp_ops [vc4])
Reverted back to kernel Linux 5.10.17+ and it is working fine.
The driver does not start reliably after reboot ... investigating ..
Reboot dmesg log
Boot after cold start: