pi5 second PCIe boot fails

[jaredmauch]

Describe the bug

Using a pi hat for PCIE <-> NVME to boot. (Pineberry pi)
Booting the SN530 NVME SSD (oh yeah, that one)

0001:01:00.0 Non-Volatile memory controller: Sandisk Corp PC SN530 NVMe SSD (DRAM-less) (rev 01) (prog-if 02 [NVM Express])
	Subsystem: Sandisk Corp PC SN530 NVMe SSD (DRAM-less)
	Flags: bus master, fast devsel, latency 0, IRQ 38
	Memory at 1b80000000 (64-bit, non-prefetchable) [size=16K]
	Memory at 1b80004000 (64-bit, non-prefetchable) [size=256]
	Capabilities: [80] Power Management version 3
	Capabilities: [90] MSI: Enable- Count=1/32 Maskable- 64bit+
	Capabilities: [b0] MSI-X: Enable+ Count=17 Masked-
	Capabilities: [c0] Express Endpoint, IntMsgNum 0
	Capabilities: [100] Advanced Error Reporting
	Capabilities: [150] Device Serial Number 00-00-00-00-00-00-00-00
	Capabilities: [1b8] Latency Tolerance Reporting
	Capabilities: [300] Secondary PCI Express
	Capabilities: [900] L1 PM Substates
	Kernel driver in use: nvme

First boot from power-on works fine, but if I then do a reboot without touching any of the physical aspects of the device it goes through and does not find the NVMe device the second time around even after successful kernel booting.

BOOTLOADER: up to date
   CURRENT: Fri 20 Jun 12:08:33 UTC 2025 (1750421313)
    LATEST: Fri 20 Jun 12:08:33 UTC 2025 (1750421313)

uname -a

Linux tv-pi2 6.12.25+rpt-rpi-2712 #1 SMP PREEMPT Debian 1:6.12.25-1+rpt1+trixie (2025-06-02) aarch64 GNU/Linux

I'm looking for anything more I can set in rpi-eeprom-config to debug/trace this at boot time. I've tried with and without PCIE_PROBE=1

Steps to reproduce the behaviour

boot once (success!), then sudo reboot

fails with pice timeout 0x0001e08f
"Failed to open device: 'nvme'"

Device (s)

Raspberry Pi 5

Bootloader configuration.

[all]
BOOT_UART=1
POWER_OFF_ON_HALT=0
BOOT_ORDER=0xf641

System

larger than 64kb will attach

Bootloader logs

I'll get the full/proper ones in a few when I hook up the console, but here's the failed boot 2nd time followed by successful dmesg data:

[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x414fd0b1]
[    0.000000] Linux version 6.12.25+rpt-rpi-2712 (serge@raspberrypi.com) (aarch64-linux-gnu-gcc-14 (Debian 14.2.0-19) 14.2.0, GNU ld (GNU Binutils for Debian) 2.44) #1 SMP PREEMPT Debian 1:6.12.25-1+rpt1+trixie (2025-06-02)
[    0.000000] KASLR enabled
[    0.000000] random: crng init done
[    0.000000] Machine model: Raspberry Pi 5 Model B Rev 1.0
[    0.000000] efi: UEFI not found.
[    0.000000] Reserved memory: created CMA memory pool at 0x000000003a000000, size 64 MiB
[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[    0.000000] OF: reserved mem: 0x000000003a000000..0x000000003dffffff (65536 KiB) map reusable linux,cma
[    0.000000] OF: reserved mem: 0x0000000000000000..0x000000000007ffff (512 KiB) nomap non-reusable atf@0
[    0.000000] OF: reserved mem: 0x000000003fd16620..0x000000003fd16658 (0 KiB) nomap non-reusable nvram@0
[    0.000000] NUMA: Faking a node at [mem 0x0000000000000000-0x00000001ffffffff]
[    0.000000] Faking node 0 at [mem 0x0000000000000000-0x000000003fffffff] (1024MB)
[    0.000000] Faking node 1 at [mem 0x0000000040000000-0x000000007fffffff] (1024MB)
[    0.000000] Faking node 2 at [mem 0x0000000080000000-0x00000000bfffffff] (1024MB)
[    0.000000] Faking node 3 at [mem 0x00000000c0000000-0x00000000ffffffff] (1024MB)
[    0.000000] Faking node 4 at [mem 0x0000000100000000-0x000000013fffffff] (1024MB)
[    0.000000] Faking node 5 at [mem 0x0000000140000000-0x000000017fffffff] (1024MB)
[    0.000000] Faking node 6 at [mem 0x0000000180000000-0x00000001bfffffff] (1024MB)
[    0.000000] Faking node 7 at [mem 0x00000001c0000000-0x00000001ffffffff] (1024MB)
[    0.000000] NUMA: Initialized distance table, cnt=8
[    0.000000] NODE_DATA(0) allocated [mem 0x3f7fd200-0x3f7fffff]
[    0.000000] NODE_DATA(1) allocated [mem 0x7fffd200-0x7fffffff]
[    0.000000] NODE_DATA(2) allocated [mem 0xbfffd200-0xbfffffff]
[    0.000000] NODE_DATA(3) allocated [mem 0xffffd200-0xffffffff]
[    0.000000] NODE_DATA(4) allocated [mem 0x13fffd200-0x13fffffff]
[    0.000000] NODE_DATA(5) allocated [mem 0x17fffd200-0x17fffffff]
[    0.000000] NODE_DATA(6) allocated [mem 0x1bfffd200-0x1bfffffff]
[    0.000000] NODE_DATA(7) allocated [mem 0x1ffb9c140-0x1ffb9ef3f]
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000000000-0x00000000ffffffff]
[    0.000000]   DMA32    empty
[    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-0x000000000007ffff]
[    0.000000]   node   0: [mem 0x0000000000080000-0x000000003f7fffff]
[    0.000000]   node   1: [mem 0x0000000040000000-0x000000007fffffff]
[    0.000000]   node   2: [mem 0x0000000080000000-0x00000000bfffffff]
[    0.000000]   node   3: [mem 0x00000000c0000000-0x00000000ffffffff]
[    0.000000]   node   4: [mem 0x0000000100000000-0x000000013fffffff]
[    0.000000]   node   5: [mem 0x0000000140000000-0x000000017fffffff]
[    0.000000]   node   6: [mem 0x0000000180000000-0x00000001bfffffff]
[    0.000000]   node   7: [mem 0x00000001c0000000-0x00000001ffffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000003f7fffff]
[    0.000000] Initmem setup node 1 [mem 0x0000000040000000-0x000000007fffffff]
[    0.000000] Initmem setup node 2 [mem 0x0000000080000000-0x00000000bfffffff]
[    0.000000] Initmem setup node 3 [mem 0x00000000c0000000-0x00000000ffffffff]
[    0.000000] Initmem setup node 4 [mem 0x0000000100000000-0x000000013fffffff]
[    0.000000] Initmem setup node 5 [mem 0x0000000140000000-0x000000017fffffff]
[    0.000000] Initmem setup node 6 [mem 0x0000000180000000-0x00000001bfffffff]
[    0.000000] Initmem setup node 7 [mem 0x00000001c0000000-0x00000001ffffffff]
[    0.000000] On node 1, zone DMA: 512 pages in unavailable ranges
[    0.000000] psci: probing for conduit method from DT.
[    0.000000] psci: PSCIv1.1 detected in firmware.
[    0.000000] psci: Using standard PSCI v0.2 function IDs
[    0.000000] psci: MIGRATE_INFO_TYPE not supported.
[    0.000000] psci: SMC Calling Convention v1.2
[    0.000000] percpu: Embedded 15 pages/cpu s193368 r8192 d44200 u245760
[    0.000000] pcpu-alloc: s193368 r8192 d44200 u245760 alloc=15*16384
[    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: Virtualization Host Extensions
[    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: SSBS not fully self-synchronizing
[    0.000000] alternatives: applying boot alternatives
[    0.000000] Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe cgroup_disable=memory numa_policy=interleave  numa=fake=8 system_heap.max_order=0 smsc95xx.macaddr=D8:3A:DD:E9:CC:AA vc_mem.mem_base=0x3fc00000 vc_mem.mem_size=0x40000000  console=ttyAMA10,115200 console=tty1 root=PARTUUID=f910caac-02 rootfstype=ext4 fsck.repair=yes rootwait video=HDMI-A-1:1280x1024@60.02
[    0.000000] cgroup: Disabling memory control group subsystem
[    0.000000] mempolicy: NUMA default policy overridden to 'interleave:0-7'
[    0.000000] Fallback order for Node 0: 0 1 2 3 4 5 6 7 
[    0.000000] Fallback order for Node 1: 1 2 3 4 5 6 7 0 
[    0.000000] Fallback order for Node 2: 2 3 4 5 6 7 0 1 
[    0.000000] Fallback order for Node 3: 3 4 5 6 7 0 1 2 
[    0.000000] Fallback order for Node 4: 4 5 6 7 0 1 2 3 
[    0.000000] Fallback order for Node 5: 5 6 7 0 1 2 3 4 
[    0.000000] Fallback order for Node 6: 6 7 0 1 2 3 4 5 
[    0.000000] Fallback order for Node 7: 7 0 1 2 3 4 5 6 
[    0.000000] Built 8 zonelists, mobility grouping on.  Total pages: 523776
[    0.000000] Policy zone: Normal
[    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 0x00000000fbffc000-0x00000000ffffc000] (64MB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=8
[    0.000000] ftrace: allocating 44893 entries in 44 pages
[    0.000000] ftrace: allocated 44 pages with 3 groups
[    0.000000] rcu: Preemptible hierarchical RCU implementation.
[    0.000000] rcu: 	RCU event tracing is enabled.
[    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 Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4.
[    0.000000] RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4.
[    0.000000] RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4.
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] Root IRQ handler: gic_handle_irq
[    0.000000] GIC: Using split EOI/Deactivate mode
[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.
[    0.000000] arch_timer: cp15 timer(s) running at 54.00MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns
[    0.000000] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns
[    0.000089] Console: colour dummy device 80x25
[    0.000093] printk: legacy console [tty1] enabled
[    0.000292] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000)
[    0.000298] pid_max: default: 32768 minimum: 301
[    0.000390] LSM: initializing lsm=capability
[    0.001904] Dentry cache hash table entries: 1048576 (order: 9, 8388608 bytes, vmalloc)
[    0.002653] Inode-cache hash table entries: 524288 (order: 8, 4194304 bytes, vmalloc)
[    0.002701] Mount-cache hash table entries: 16384 (order: 3, 131072 bytes, vmalloc)
[    0.002724] Mountpoint-cache hash table entries: 16384 (order: 3, 131072 bytes, vmalloc)
[    0.008068] rcu: Hierarchical SRCU implementation.
[    0.008080] rcu: 	Max phase no-delay instances is 1000.
[    0.008120] Timer migration: 1 hierarchy levels; 8 children per group; 0 crossnode level
[    0.008456] EFI services will not be available.
[    0.012015] smp: Bringing up secondary CPUs ...
[    0.020111] Detected PIPT I-cache on CPU1
[    0.020148] CPU1: Booted secondary processor 0x0000000100 [0x414fd0b1]
[    0.028078] Detected PIPT I-cache on CPU2
[    0.028107] CPU2: Booted secondary processor 0x0000000200 [0x414fd0b1]
[    0.036080] Detected PIPT I-cache on CPU3
[    0.036099] CPU3: Booted secondary processor 0x0000000300 [0x414fd0b1]
[    0.036130] smp: Brought up 8 nodes, 4 CPUs
[    0.036146] SMP: Total of 4 processors activated.
[    0.036149] CPU: All CPU(s) started at EL2
[    0.036152] CPU features: detected: 32-bit EL0 Support
[    0.036155] CPU features: detected: Data cache clean to the PoU not required for I/D coherence
[    0.036159] CPU features: detected: Common not Private translations
[    0.036161] CPU features: detected: CRC32 instructions
[    0.036166] CPU features: detected: RCpc load-acquire (LDAPR)
[    0.036168] CPU features: detected: LSE atomic instructions
[    0.036170] CPU features: detected: Privileged Access Never
[    0.036173] CPU features: detected: RAS Extension Support
[    0.036176] CPU features: detected: Speculative Store Bypassing Safe (SSBS)
[    0.036204] alternatives: applying system-wide alternatives
[    0.037631] CPU features: detected: Hardware dirty bit management on CPU0-3
[    0.037799] Memory: 8140288K/8380416K available (14144K kernel code, 2264K rwdata, 4800K rodata, 2176K init, 647K bss, 144384K reserved, 65536K cma-reserved)
[    0.037971] devtmpfs: initialized
[    0.039818] Enabled cp15_barrier support
[    0.039825] Enabled setend support
[    0.039868] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.039876] futex hash table entries: 1024 (order: 2, 65536 bytes, vmalloc)
[    0.039987] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL
[    0.039990] 0 pages in range for non-PLT usage
[    0.039991] 129548 pages in range for PLT usage
[    0.040091] pinctrl core: initialized pinctrl subsystem
[    0.040213] DMI not present or invalid.
[    0.043471] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.043795] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations
[    0.043826] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    0.043857] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    0.043873] audit: initializing netlink subsys (disabled)
[    0.043936] audit: type=2000 audit(0.040:1): state=initialized audit_enabled=0 res=1
[    0.044042] thermal_sys: Registered thermal governor 'step_wise'
[    0.044051] cpuidle: using governor menu
[    0.044102] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.044125] ASID allocator initialised with 32768 entries
[    0.044366] Serial: AMBA PL011 UART driver
[    0.045102] /soc@107c000000/interrupt-controller@7fff9000: Fixed dependency cycle(s) with /soc@107c000000/interrupt-controller@7fff9000
[    0.045258] bcm2835-mbox 107c013880.mailbox: mailbox enabled
[    0.045378] 107d001000.serial: ttyAMA10 at MMIO 0x107d001000 (irq = 16, base_baud = 0) is a PL011 rev3
[    0.045390] printk: legacy console [ttyAMA10] enabled
[    1.079483] raspberrypi-firmware soc@107c000000:firmware: Attached to firmware from 2025-06-20T12:08:33, variant start_cd
[    1.094494] raspberrypi-firmware soc@107c000000:firmware: Firmware hash is 960432b600000000000000000000000000000000
[    1.117496] bcm2835-dma 1000010000.dma: DMA legacy API manager, dmachans=0x1
[    1.124851] iommu: Default domain type: Translated
[    1.129662] iommu: DMA domain TLB invalidation policy: strict mode
[    1.140267] SCSI subsystem initialized
[    1.144055] usbcore: registered new interface driver usbfs
[    1.149564] usbcore: registered new interface driver hub
[    1.154900] usbcore: registered new device driver usb
[    1.160034] pps_core: LinuxPPS API ver. 1 registered
[    1.165016] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    1.174188] PTP clock support registered
[    1.178379] vgaarb: loaded
[    1.181167] clocksource: Switched to clocksource arch_sys_counter
[    1.290370] VFS: Disk quotas dquot_6.6.0
[    1.294346] VFS: Dquot-cache hash table entries: 2048 (order 0, 16384 bytes)
[    1.302629] NET: Registered PF_INET protocol family
[    1.307693] IP idents hash table entries: 131072 (order: 6, 1048576 bytes, vmalloc)
[    1.317008] tcp_listen_portaddr_hash hash table entries: 4096 (order: 2, 65536 bytes, vmalloc)
[    1.325698] Table-perturb hash table entries: 65536 (order: 4, 262144 bytes, vmalloc)
[    1.333566] TCP established hash table entries: 65536 (order: 5, 524288 bytes, vmalloc)
[    1.341775] TCP bind hash table entries: 65536 (order: 7, 2097152 bytes, vmalloc)
[    1.349967] TCP: Hash tables configured (established 65536 bind 65536)
[    1.356623] MPTCP token hash table entries: 8192 (order: 3, 196608 bytes, vmalloc)
[    1.364278] UDP hash table entries: 4096 (order: 3, 131072 bytes, vmalloc)
[    1.371242] UDP-Lite hash table entries: 4096 (order: 3, 131072 bytes, vmalloc)
[    1.378678] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    1.384485] RPC: Registered named UNIX socket transport module.
[    1.390429] RPC: Registered udp transport module.
[    1.395147] RPC: Registered tcp transport module.
[    1.399865] RPC: Registered tcp-with-tls transport module.
[    1.405371] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    1.411837] PCI: CLS 0 bytes, default 64
[    1.415842] Trying to unpack rootfs image as initramfs...
[    1.422448] kvm [1]: nv: 554 coarse grained trap handlers
[    1.427992] kvm [1]: IPA Size Limit: 40 bits
[    1.432299] kvm [1]: GICV region size/alignment is unsafe, using trapping (reduced performance)
[    1.441085] kvm [1]: vgic interrupt IRQ9
[    1.445036] kvm [1]: VHE mode initialized successfully
[    1.450743] Initialise system trusted keyrings
[    1.455389] workingset: timestamp_bits=42 max_order=19 bucket_order=0
[    1.462211] NFS: Registering the id_resolver key type
[    1.467299] Key type id_resolver registered
[    1.471507] Key type id_legacy registered
[    1.475556] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.482308] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    1.489983] Key type asymmetric registered
[    1.494110] Asymmetric key parser 'x509' registered
[    1.499061] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[    1.506547] io scheduler mq-deadline registered
[    1.511110] io scheduler kyber registered
[    1.515326] io scheduler bfq registered
[    1.516960] Freeing initrd memory: 14720K
[    1.523646] irq_brcmstb_l2: registered L2 intc (/soc@107c000000/interrupt-controller@7d510600, parent irq: 27)
[    1.533741] irq_brcmstb_l2: registered L2 intc (/soc@107c000000/interrupt-controller@7c502000, parent irq: 28)
[    1.543827] irq_brcmstb_l2: registered L2 intc (/soc@107c000000/intc@7d508380, parent irq: 29)
[    1.552516] irq_brcmstb_l2: registered L2 intc (/soc@107c000000/intc@7d508400, parent irq: 30)
[    1.561204] irq_brcmstb_l2: registered L2 intc (/soc@107c000000/intc@7d503000, parent irq: 31)
[    1.570769] ledtrig-cpu: registered to indicate activity on CPUs
[    1.577280] simple-framebuffer 3f800000.framebuffer: framebuffer at 0x3f800000, 0x3f4800 bytes
[    1.585938] simple-framebuffer 3f800000.framebuffer: format=r5g6b5, mode=1920x1080x16, linelength=3840
[    1.596335] Console: switching to colour frame buffer device 240x67
[    1.603599] simple-framebuffer 3f800000.framebuffer: fb0: simplefb registered!
[    1.639032] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
[    1.645609] 107d50c000.serial: ttyS0 at MMIO 0x107d50c000 (irq = 33, base_baud = 6000000) is a Broadcom BCM7271 UART
[    1.656431] iproc-rng200 107d208000.rng: hwrng registered
[    1.661884] vc-mem: phys_addr:0x00000000 mem_base=0x3fc00000 mem_size:0x40000000(1024 MiB)
[    1.670365] bcm2712-iommu-cache 1000005b00.iommuc: bcm2712_iommu_cache_probe
[    1.678706] brd: module loaded
[    1.682627] loop: module loaded
[    1.685866] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    1.693208] Loading iSCSI transport class v2.0-870.
[    1.698777] usbcore: registered new interface driver lan78xx
[    1.704469] usbcore: registered new interface driver smsc95xx
[    1.710285] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    1.716070] dwc_otg: FIQ enabled
[    1.716071] dwc_otg: NAK holdoff enabled
[    1.716072] dwc_otg: FIQ split-transaction FSM enabled
[    1.716074] Module dwc_common_port init
[    1.716170] usbcore: registered new interface driver uas
[    1.721509] usbcore: registered new interface driver usb-storage
[    1.727605] mousedev: PS/2 mouse device common for all mice
[    1.740635] rpi-rtc soc@107c000000:rpi_rtc: registered as rtc0
[    1.747866] rpi-rtc soc@107c000000:rpi_rtc: setting system clock to 1970-01-01T00:00:39 UTC (39)
[    1.756978] bcm2835-wdt bcm2835-wdt: Poweroff handler already present!
[    1.763540] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    1.771887] sdhci: Secure Digital Host Controller Interface driver
[    1.778109] sdhci: Copyright(c) Pierre Ossman
[    1.782522] sdhci-pltfm: SDHCI platform and OF driver helper
[    1.788273] SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping ....
[    1.794763] hid: raw HID events driver (C) Jiri Kosina
[    1.799939] usbcore: registered new interface driver usbhid
[    1.805536] usbhid: USB HID core driver
[    1.809670] hw perfevents: enabled with armv8_cortex_a76 PMU driver, 7 (0,8000003f) counters available
[    1.819252] NET: Registered PF_PACKET protocol family
[    1.824344] Key type dns_resolver registered
[    1.832034] registered taskstats version 1
[    1.836213] Loading compiled-in X.509 certificates
[    1.842474] Demotion targets for Node 0: null
[    1.846850] Demotion targets for Node 1: null
[    1.851223] Demotion targets for Node 2: null
[    1.855649] Demotion targets for Node 3: null
[    1.860024] Demotion targets for Node 4: null
[    1.864748] Demotion targets for Node 5: null
[    1.869456] Demotion targets for Node 6: null
[    1.874182] Demotion targets for Node 7: null
[    1.879269] Key type .fscrypt registered
[    1.883533] Key type fscrypt-provisioning registered
[    1.889532] brcm-pcie 1000110000.pcie: host bridge /axi/pcie@1000110000 ranges:
[    1.897212] brcm-pcie 1000110000.pcie:   No bus range found for /axi/pcie@1000110000, using [bus 00-ff]
[    1.906988] brcm-pcie 1000110000.pcie:      MEM 0x1b80000000..0x1bffffffff -> 0x0080000000
[    1.915626] brcm-pcie 1000110000.pcie:      MEM 0x1800000000..0x1b7fffffff -> 0x0400000000
[    1.924260] brcm-pcie 1000110000.pcie:   IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000
[    1.932893] brcm-pcie 1000110000.pcie:   IB MEM 0x1000131000..0x1000131fff -> 0xfffffff000
[    1.942846] brcm-pcie 1000110000.pcie: PCI host bridge to bus 0001:00
[    1.949648] pci_bus 0001:00: root bus resource [bus 00-ff]
[    1.955483] pci_bus 0001:00: root bus resource [mem 0x1b80000000-0x1bffffffff] (bus address [0x80000000-0xffffffff])
[    1.966382] pci_bus 0001:00: root bus resource [mem 0x1800000000-0x1b7fffffff pref] (bus address [0x400000000-0x77fffffff])
[    1.977901] pci 0001:00:00.0: [14e4:2712] type 01 class 0x060400 PCIe Root Port
[    1.985572] pci 0001:00:00.0: PCI bridge to [bus 00]
[    1.990886] pci 0001:00:00.0:   bridge window [mem 0x1b80000000-0x1bbfffffff]
[    1.998394] pci 0001:00:00.0: PME# supported from D0 D3hot
[    2.004800] pci 0001:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[    2.117172] brcm-pcie 1000110000.pcie: clkreq-mode set to safe
[    2.123356] brcm-pcie 1000110000.pcie: link up, 5.0 GT/s PCIe x1 (!SSC)
[    2.130343] pci 0001:01:00.0: [15b7:5008] type 00 class 0x010802 PCIe Endpoint
[    2.137937] pci 0001:01:00.0: BAR 0 [mem 0x00000000-0x00003fff 64bit]
[    2.144744] pci 0001:01:00.0: BAR 4 [mem 0x00000000-0x000000ff 64bit]
[    2.151644] pci 0001:01:00.0: 4.000 Gb/s available PCIe bandwidth, limited by 5.0 GT/s PCIe x1 link at 0001:00:00.0 (capable of 31.504 Gb/s with 8.0 GT/s PCIe x4 link)
[    2.173180] pci_bus 0001:01: busn_res: [bus 01-ff] end is updated to 01
[    2.180178] pci 0001:00:00.0: bridge window [mem 0x1b80000000-0x1b800fffff]: assigned
[    2.188385] pci 0001:01:00.0: BAR 0 [mem 0x1b80000000-0x1b80003fff 64bit]: assigned
[    2.196424] pci 0001:01:00.0: BAR 4 [mem 0x1b80004000-0x1b800040ff 64bit]: assigned
[    2.204457] pci 0001:00:00.0: PCI bridge to [bus 01]
[    2.209783] pci 0001:00:00.0:   bridge window [mem 0x1b80000000-0x1b800fffff]
[    2.217292] pci_bus 0001:00: resource 4 [mem 0x1b80000000-0x1bffffffff]
[    2.224273] pci_bus 0001:00: resource 5 [mem 0x1800000000-0x1b7fffffff pref]
[    2.231689] pci_bus 0001:01: resource 1 [mem 0x1b80000000-0x1b800fffff]
[    2.238668] pci 0001:00:00.0: Max Payload Size set to  512/ 512 (was  128), Max Read Rq  512
[    2.247491] pci 0001:01:00.0: Max Payload Size set to  512/ 512 (was  128), Max Read Rq  512
[    2.256354] pcieport 0001:00:00.0: enabling device (0000 -> 0002)
[    2.262846] pcieport 0001:00:00.0: PME: Signaling with IRQ 38
[    2.269011] pcieport 0001:00:00.0: AER: enabled with IRQ 38
[    2.275070] nvme nvme0: pci function 0001:01:00.0
[    2.280155] nvme 0001:01:00.0: enabling device (0000 -> 0002)
[    2.291387] hwmon hwmon1: temp1_input not attached to any thermal zone
[    2.298296] nvme nvme0: failed to allocate host memory buffer.
[    2.308019] nvme nvme0: 4/0/0 default/read/poll queues
[    2.317680]  nvme0n1: p1 p2
[    2.320980] brcm-pcie 1000120000.pcie: host bridge /axi/pcie@1000120000 ranges:
[    2.328709] brcm-pcie 1000120000.pcie:   No bus range found for /axi/pcie@1000120000, using [bus 00-ff]
[    2.338504] brcm-pcie 1000120000.pcie:      MEM 0x1f00000000..0x1ffffffffb -> 0x0000000000
[    2.347160] brcm-pcie 1000120000.pcie:      MEM 0x1c00000000..0x1effffffff -> 0x0400000000
[    2.355820] brcm-pcie 1000120000.pcie:   IB MEM 0x1f00000000..0x1f003fffff -> 0x0000000000
[    2.364471] brcm-pcie 1000120000.pcie:   IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000
[    2.373113] brcm-pcie 1000120000.pcie:   IB MEM 0x1000130000..0x1000130fff -> 0xfffffff000
[    2.382896] brcm-pcie 1000120000.pcie: PCI host bridge to bus 0002:00
[    2.389710] pci_bus 0002:00: root bus resource [bus 00-ff]
[    2.395557] pci_bus 0002:00: root bus resource [mem 0x1f00000000-0x1ffffffffb] (bus address [0x00000000-0xfffffffb])
[    2.406472] pci_bus 0002:00: root bus resource [mem 0x1c00000000-0x1effffffff pref] (bus address [0x400000000-0x6ffffffff])
[    2.418011] pci 0002:00:00.0: [14e4:2712] type 01 class 0x060400 PCIe Root Port
[    2.425713] pci 0002:00:00.0: PCI bridge to [bus 00]
[    2.431061] pci 0002:00:00.0:   bridge window [mem 0x1f80000000-0x1fbfffffff]
[    2.438603] pci 0002:00:00.0: PME# supported from D0 D3hot
[    2.445043] pci 0002:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[    2.557169] brcm-pcie 1000120000.pcie: clkreq-mode set to default
[    2.563666] brcm-pcie 1000120000.pcie: link up, 5.0 GT/s PCIe x4 (!SSC)
[    2.570702] pci 0002:01:00.0: [1de4:0001] type 00 class 0x020000 PCIe Endpoint
[    2.578343] pci 0002:01:00.0: BAR 0 [mem 0xffffc000-0xffffffff]
[    2.584667] pci 0002:01:00.0: BAR 1 [mem 0xffc00000-0xffffffff]
[    2.590988] pci 0002:01:00.0: BAR 2 [mem 0xffff0000-0xffffffff]
[    2.597363] pci 0002:01:00.0: supports D1
[    2.601748] pci 0002:01:00.0: PME# supported from D0 D1 D3hot D3cold
[    2.613176] pci_bus 0002:01: busn_res: [bus 01-ff] end is updated to 01
[    2.620197] pci 0002:00:00.0: bridge window [mem 0x1f00000000-0x1f005fffff]: assigned
[    2.628435] pci 0002:01:00.0: BAR 1 [mem 0x1f00000000-0x1f003fffff]: assigned
[    2.635979] pci 0002:01:00.0: BAR 2 [mem 0x1f00400000-0x1f0040ffff]: assigned
[    2.643518] pci 0002:01:00.0: BAR 0 [mem 0x1f00410000-0x1f00413fff]: assigned
[    2.651053] pci 0002:00:00.0: PCI bridge to [bus 01]
[    2.656401] pci 0002:00:00.0:   bridge window [mem 0x1f00000000-0x1f005fffff]
[    2.663923] pci_bus 0002:00: resource 4 [mem 0x1f00000000-0x1ffffffffb]
[    2.670922] pci_bus 0002:00: resource 5 [mem 0x1c00000000-0x1effffffff pref]
[    2.678366] pci_bus 0002:01: resource 1 [mem 0x1f00000000-0x1f005fffff]
[    2.685368] pci 0002:00:00.0: Max Payload Size set to  256/ 512 (was  128), Max Read Rq  512
[    2.694211] pci 0002:01:00.0: Max Payload Size set to  256/ 256 (was  128), Max Read Rq  512
[    2.703119] pcieport 0002:00:00.0: enabling device (0000 -> 0002)
[    2.709651] pcieport 0002:00:00.0: PME: Signaling with IRQ 44
[    2.715846] pcieport 0002:00:00.0: AER: enabled with IRQ 44
[    2.721870] rp1 0002:01:00.0: bar0 len 0x4000, start 0x1f00410000, end 0x1f00413fff, flags, 0x40200
[    2.731332] rp1 0002:01:00.0: bar1 len 0x400000, start 0x1f00000000, end 0x1f003fffff, flags, 0x40200
[    2.740959] rp1 0002:01:00.0: enabling device (0000 -> 0002)
[    2.747590] rp1 0002:01:00.0: chip_id 0x20001927
[    2.756551] genirq: irq_chip rp1_irq_chip did not update eff. affinity mask of irq 106
[    2.780795] macb 1f00100000.ethernet eth0: Cadence GEM rev 0x00070109 at 0x1f00100000 irq 112 (d8:3a:dd:e9:cc:aa)
[    2.792270] dw_axi_dmac_platform 1f00188000.dma: DesignWare AXI DMA Controller, 8 channels
[    2.801146] xhci-hcd xhci-hcd.0: xHCI Host Controller
[    2.806575] xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 1
[    2.814610] xhci-hcd xhci-hcd.0: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000808000000810
[    2.823969] xhci-hcd xhci-hcd.0: irq 137, io mem 0x1f00200000
[    2.830152] xhci-hcd xhci-hcd.0: xHCI Host Controller
[    2.835571] xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 2
[    2.843176] xhci-hcd xhci-hcd.0: Host supports USB 3.0 SuperSpeed
[    2.849681] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12
[    2.858337] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    2.865945] usb usb1: Product: xHCI Host Controller
[    2.871191] usb usb1: Manufacturer: Linux 6.12.25+rpt-rpi-2712 xhci-hcd
[    2.878186] usb usb1: SerialNumber: xhci-hcd.0
[    2.883078] hub 1-0:1.0: USB hub found
[    2.887203] hub 1-0:1.0: 2 ports detected
[    2.891671] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.12
[    2.900323] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    2.907920] usb usb2: Product: xHCI Host Controller
[    2.913154] usb usb2: Manufacturer: Linux 6.12.25+rpt-rpi-2712 xhci-hcd
[    2.920208] usb usb2: SerialNumber: xhci-hcd.0
[    2.925068] hub 2-0:1.0: USB hub found
[    2.929278] hub 2-0:1.0: 1 port detected
[    2.933784] xhci-hcd xhci-hcd.1: xHCI Host Controller
[    2.939199] xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 3
[    2.947263] xhci-hcd xhci-hcd.1: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000808000000810
[    2.956621] xhci-hcd xhci-hcd.1: irq 142, io mem 0x1f00300000
[    2.962788] xhci-hcd xhci-hcd.1: xHCI Host Controller
[    2.968192] xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 4
[    2.975775] xhci-hcd xhci-hcd.1: Host supports USB 3.0 SuperSpeed
[    2.982289] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12
[    2.990924] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    2.998508] usb usb3: Product: xHCI Host Controller
[    3.003732] usb usb3: Manufacturer: Linux 6.12.25+rpt-rpi-2712 xhci-hcd
[    3.010703] usb usb3: SerialNumber: xhci-hcd.1
[    3.015562] hub 3-0:1.0: USB hub found
[    3.019662] hub 3-0:1.0: 2 ports detected
[    3.024100] usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.12
[    3.032735] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    3.040373] usb usb4: Product: xHCI Host Controller
[    3.045654] usb usb4: Manufacturer: Linux 6.12.25+rpt-rpi-2712 xhci-hcd
[    3.052637] usb usb4: SerialNumber: xhci-hcd.1
[    3.057508] hub 4-0:1.0: USB hub found
[    3.061608] hub 4-0:1.0: 1 port detected
[    3.066647] bcm2712-iommu 1000005100.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774
[    3.075439] bcm2712-iommu 1000005100.iommu: bcm2712_iommu_probe: Success
[    3.082953] bcm2712-iommu 1000005200.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774
[    3.091717] bcm2712-iommu 1000005200.iommu: bcm2712_iommu_probe: Success
[    3.099291] bcm2712-iommu 1000005280.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774
[    3.108062] bcm2712-iommu 1000005280.iommu: bcm2712_iommu_probe: Success
[    3.115344] sdhci-brcmstb 1000fff000.mmc: Got CD GPIO
[    3.117347] mmc1: CQHCI version 5.10
[    3.120942] mmc0: CQHCI version 5.10
[    3.128956] of_cfs_init
[    3.131780] of_cfs_init: OK
[    3.134984] clk: Disabling unused clocks
[    3.147734] PM: genpd: Disabling unused power domains
[    3.170238] mmc0: SDHCI controller on 1000fff000.mmc [1000fff000.mmc] using ADMA 64-bit
[    3.269181] usb 3-2: new low-speed USB device number 2 using xhci-hcd
[    3.311755] mmc1: SDHCI controller on 1001100000.mmc [1001100000.mmc] using ADMA 64-bit
[    3.321298] Freeing unused kernel memory: 2176K
[    3.326239] Run /init as init process
[    3.330220]   with arguments:
[    3.330221]     /init
[    3.330222]   with environment:
[    3.330223]     HOME=/
[    3.330224]     TERM=linux
[    3.350599] mmc1: new ultra high speed DDR50 SDIO card at address 0001
[    3.422904] input: pwr_button as /devices/platform/pwr_button/input/input0
[    3.450268] brcmstb-i2c 107d508200.i2c:  @97500hz registered in interrupt mode
[    3.458338] usb 3-2: New USB device found, idVendor=05ac, idProduct=024f, bcdDevice= 1.01
[    3.460797] brcmstb-i2c 107d508280.i2c:  @97500hz registered in interrupt mode
[    3.467800] usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    3.484407] usb 3-2: Product: USB Keyboard
[    3.490526] usb 3-2: Manufacturer: ZXZK  F508US01
[    3.496405] usb 3-2: SerialNumber: 0000000002
[    3.597592] input: ZXZK  F508US01 USB Keyboard as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-2/3-2:1.0/0003:05AC:024F.0001/input/input1
[    3.705233] apple 0003:05AC:024F.0001: input,hidraw0: USB HID v1.11 Keyboard [ZXZK  F508US01 USB Keyboard] on usb-xhci-hcd.1-2/input0
[    3.734240] apple 0003:05AC:024F.0002: Fn key not found (Apple Wireless Keyboard clone?), disabling Fn key handling
[    3.745081] input: ZXZK  F508US01 USB Keyboard as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-2/3-2:1.1/0003:05AC:024F.0002/input/input2
[    3.760177] apple 0003:05AC:024F.0002: input,hiddev96,hidraw1: USB HID v1.11 Mouse [ZXZK  F508US01 USB Keyboard] on usb-xhci-hcd.1-2/input1
[    3.896252] EXT4-fs (nvme0n1p2): mounted filesystem 3b614a3f-4a65-4480-876a-8a998e01ac9b ro with ordered data mode. Quota mode: none.
[    3.982111] systemd[1]: System time advanced to timestamp on /var/lib/systemd/timesync/clock: Mon 2025-06-23 18:05:02 BST
[    4.021655] NET: Registered PF_INET6 protocol family
[    4.027338] Segment Routing with IPv6
[    4.031393] In-situ OAM (IOAM) with IPv6
[    4.048313] systemd[1]: systemd 257.6-1 running in system mode (+PAM +AUDIT +SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK +BTF -XKBCOMMON -UTMP +SYSVINIT +LIBARCHIVE)
[    4.082871] systemd[1]: Detected architecture arm64.
[    4.091301] systemd[1]: Hostname set to <tv-pi2>.
[    4.184832] systemd[1]: bpf-restrict-fs: BPF LSM hook not enabled in the kernel, BPF LSM not supported.
[    4.318964] systemd[1]: Queued start job for default target multi-user.target.
[    4.369936] systemd[1]: Created slice system-getty.slice - Slice /system/getty.
[    4.378510] systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe.
[    4.387500] systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty.
[    4.397467] systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck.
[    4.407351] systemd[1]: Created slice user.slice - User and Session Slice.
[    4.415258] systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
[    4.427380] systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch.
[    4.439017] systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point.
[    4.452648] systemd[1]: Expecting device dev-disk-by\x2dpartuuid-f910caac\x2d01.device - /dev/disk/by-partuuid/f910caac-01...
[    4.465033] systemd[1]: Expecting device dev-ttyAMA10.device - /dev/ttyAMA10...
[    4.473414] systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes.
[    4.482154] systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes.
[    4.492130] systemd[1]: Reached target paths.target - Path Units.
[    4.499295] systemd[1]: Reached target remote-fs.target - Remote File Systems.
[    4.507595] systemd[1]: Reached target slices.target - Slice Units.
[    4.514946] systemd[1]: Reached target swap.target - Swaps.
[    4.521591] systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes.
[    4.532196] systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption.
[    4.541853] systemd[1]: Listening on systemd-initctl.socket - initctl Compatibility Named Pipe.
[    4.551691] systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log).
[    4.561723] systemd[1]: Listening on systemd-journald.socket - Journal Sockets.
[    4.570126] systemd[1]: systemd-pcrextend.socket - TPM PCR Measurements was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
[    4.584457] systemd[1]: systemd-pcrlock.socket - Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
[    4.598586] systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
[    4.607808] systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
[    4.616916] systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages).
[    4.633052] systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System...
[    4.685654] systemd[1]: Mounting run-lock.mount - Legacy Locks Directory /run/lock...
[    4.695559] systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System...
[    4.705424] systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System...
[    4.715019] systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway.
[    4.724929] systemd[1]: Mounting tmp.mount - Temporary Directory /tmp...
[    4.733785] systemd[1]: Starting fake-hwclock-load.service - Restore the current clock...
[    4.744245] systemd[1]: Starting keyboard-setup.service - Set the console keyboard layout...
[    4.754850] systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
[    4.766347] systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs...
[    4.777692] systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
[    4.788299] systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
[    4.805410] systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse...
[    4.817004] systemd[1]: Starting modprobe@nvme_fabrics.service - Load Kernel Module nvme_fabrics...
[    4.827574] systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67).
[    4.828143] fuse: init (API version 7.41)
[    4.856298] systemd[1]: Starting systemd-journald.service - Journal Service...
[    4.865910] systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
[    4.875481] systemd[1]: systemd-pcrmachine.service - TPM PCR Machine ID Measurement was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
[    4.891112] systemd[1]: systemd-tpm2-setup-early.service - Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
[    4.906936] systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials...
[    4.919294] systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
[    4.924482] systemd-journald[309]: Collecting audit messages is disabled.
[    4.938830] systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System.
[    4.949327] systemd[1]: Mounted run-lock.mount - Legacy Locks Directory /run/lock.
[    4.958176] systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System.
[    4.967033] systemd[1]: Started systemd-journald.service - Journal Service.
[    5.061725] EXT4-fs (nvme0n1p2): re-mounted 3b614a3f-4a65-4480-876a-8a998e01ac9b r/w. Quota mode: none.
[    5.107144] systemd-journald[309]: Received client request to flush runtime journal.
[    5.114798] systemd-journald[309]: /var/log/journal/8e1d9197cc364c20888c3da0cb460e52/system.journal: Realtime clock jumped backwards relative to last journal entry, rotating.
[    5.114808] systemd-journald[309]: Rotating system journal.
[    5.268773] rp1-firmware rp1_firmware: RP1 Firmware version eb39cfd516f8c90628aa9d91f52370aade5d0a55
[    5.274552] rpi-gpiomem 107d508500.gpiomem: window base 0x107d508500 size 0x00000040
[    5.277651] rpi-gpiomem 107d508500.gpiomem: initialised 1 regions as /dev/gpiomem1
[    5.278325] rpi-gpiomem 107d517c00.gpiomem: window base 0x107d517c00 size 0x00000040
[    5.278398] rpi-gpiomem 107d517c00.gpiomem: initialised 1 regions as /dev/gpiomem2
[    5.278425] rpi-gpiomem 107d504100.gpiomem: window base 0x107d504100 size 0x00000020
[    5.278457] rpi-gpiomem 107d504100.gpiomem: initialised 1 regions as /dev/gpiomem3
[    5.278476] rpi-gpiomem 107d510700.gpiomem: window base 0x107d510700 size 0x00000020
[    5.292099] rp1-pio 1f00178000.pio: Created instance as pio0
[    5.292242] mc: Linux media interface: v0.10
[    5.301354] rpi-gpiomem 107d510700.gpiomem: initialised 1 regions as /dev/gpiomem4
[    5.301474] rpi-gpiomem 1f000d0000.gpiomem: window base 0x1f000d0000 size 0x00030000
[    5.302852] rpi-gpiomem 1f000d0000.gpiomem: initialised 1 regions as /dev/gpiomem0
[    5.319332] videodev: Linux video capture interface: v2.00
[    5.357066] v3d 1002000000.v3d: [drm] Transparent Hugepage support is recommended for optimal performance on this platform!
[    5.367573] [drm] Initialized v3d 1.0.0 for 1002000000.v3d on minor 0
[    5.367701] rpi-hevc-dec 1000800000.codec: bcm2712_iommu_of_xlate: MMU 1000005100.iommu
[    5.367707] rpi-hevc-dec 1000800000.codec: bcm2712_iommu_probe_device: MMU 1000005100.iommu
[    5.367714] rpi-hevc-dec 1000800000.codec: bcm2712_iommu_device_group: MMU 1000005100.iommu
[    5.367720] rpi-hevc-dec 1000800000.codec: Adding to iommu group 0
[    5.367725] rpi-hevc-dec 1000800000.codec: bcm2712_iommu_attach_dev: MMU 1000005100.iommu
[    5.370061] pispbe 1000880000.pisp_be: bcm2712_iommu_of_xlate: MMU 1000005100.iommu
[    5.370070] pispbe 1000880000.pisp_be: bcm2712_iommu_probe_device: MMU 1000005100.iommu
[    5.370079] pispbe 1000880000.pisp_be: bcm2712_iommu_device_group: MMU 1000005100.iommu
[    5.370085] pispbe 1000880000.pisp_be: Adding to iommu group 0
[    5.370087] pispbe 1000880000.pisp_be: bcm2712_iommu_attach_dev: MMU 1000005100.iommu
[    5.384880] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[    5.395401] Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf'
[    5.395635] Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328'
[    5.395838] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[    5.396025] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'
[    5.415704] rpi-hevc-dec 1000800000.codec: Device registered as /dev/video19
[    5.431082] pispbe 1000880000.pisp_be: Runtime PM usage count underflow!
[    5.439538] vc4_hvs 107c580000.hvs: bcm2712_iommu_of_xlate: MMU 1000005200.iommu
[    5.439548] vc4_hvs 107c580000.hvs: bcm2712_iommu_probe_device: MMU 1000005200.iommu
[    5.439555] vc4_hvs 107c580000.hvs: bcm2712_iommu_device_group: MMU 1000005200.iommu
[    5.439560] vc4_hvs 107c580000.hvs: Adding to iommu group 1
[    5.439565] vc4_hvs 107c580000.hvs: bcm2712_iommu_attach_dev: MMU 1000005200.iommu
[    5.439844] brcmfmac: F1 signature read @0x18000000=0x15264345
[    5.444179] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    5.496368] vc4-drm axi:gpu: bcm2712_iommu_of_xlate: MMU 1000005200.iommu
[    5.496379] vc4-drm axi:gpu: bcm2712_iommu_probe_device: MMU 1000005200.iommu
[    5.496389] vc4-drm axi:gpu: bcm2712_iommu_device_group: MMU 1000005200.iommu
[    5.496397] vc4-drm axi:gpu: Adding to iommu group 1
[    5.496400] vc4-drm axi:gpu: bcm2712_iommu_attach_dev: MMU 1000005200.iommu
[    5.497801] Console: switching to colour dummy device 80x25
[    5.509095] vc4-drm axi:gpu: bound 107c580000.hvs (ops vc4_hvs_ops [vc4])
[    5.510689] Registered IR keymap rc-cec
[    5.510733] rc rc0: vc4-hdmi-0 as /devices/platform/soc@107c000000/107c701400.hdmi/rc/rc0
[    5.510774] input: vc4-hdmi-0 as /devices/platform/soc@107c000000/107c701400.hdmi/rc/rc0/input3
[    5.512808] input: vc4-hdmi-0 HDMI Jack as /devices/platform/soc@107c000000/107c701400.hdmi/sound/card0/input4
[    5.515520] vc4-drm axi:gpu: bound 107c701400.hdmi (ops vc4_hdmi_ops [vc4])
[    5.516876] Registered IR keymap rc-cec
[    5.516918] rc rc1: vc4-hdmi-1 as /devices/platform/soc@107c000000/107c706400.hdmi/rc/rc1
[    5.516953] input: vc4-hdmi-1 as /devices/platform/soc@107c000000/107c706400.hdmi/rc/rc1/input5
[    5.517661] input: vc4-hdmi-1 HDMI Jack as /devices/platform/soc@107c000000/107c706400.hdmi/sound/card1/input6
[    5.518729] vc4-drm axi:gpu: bound 107c706400.hdmi (ops vc4_hdmi_ops [vc4])
[    5.518893] vc4-drm axi:gpu: bound 107c500000.mop (ops vc4_txp_ops [vc4])
[    5.518992] vc4-drm axi:gpu: bound 107c501000.moplet (ops vc4_txp_ops [vc4])
[    5.519064] vc4-drm axi:gpu: bound 107c410000.pixelvalve (ops vc4_crtc_ops [vc4])
[    5.519143] vc4-drm axi:gpu: bound 107c411000.pixelvalve (ops vc4_crtc_ops [vc4])
[    5.572087] [drm] Initialized vc4 0.0.0 for axi:gpu on minor 1
[    5.595938] usbcore: registered new interface driver brcmfmac
[    5.603245] brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2)
[    5.603476] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Aug 29 2023 01:47:08 version 7.45.265 (28bca26 CY) FWID 01-b677b91b
[    5.651816] Console: switching to colour frame buffer device 480x135
[    5.672171] vc4-drm axi:gpu: [drm] fb0: vc4drmfb frame buffer device
[    5.693553] systemd-journald[309]: Time jumped backwards, rotating.
[    6.100629] nvme nvme0: using unchecked data buffer
[    6.109104] Adding 204784k swap on /var/swap.  Priority:-2 extents:11 across:1638400k SS
[    6.291442] macb 1f00100000.ethernet eth0: PHY [1f00100000.ethernet-ffffffff:01] driver [Broadcom BCM54213PE] (irq=POLL)
[    6.291450] macb 1f00100000.ethernet eth0: configuring for phy/rgmii-id link mode
[    6.294287] macb 1f00100000.ethernet: gem-ptp-timer ptp clock registered.
[    6.321856] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
[    6.374323] warning: `lldpd' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211
[   10.379208] macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off

USB boot

No response

NVMe boot

Node                  Generic               SN                   Model                                    Namespace  Usage                      Format           FW Rev  
--------------------- --------------------- -------------------- ---------------------------------------- ---------- -------------------------- ---------------- --------
/dev/nvme0n1          /dev/ng0n1            21116W804277         WDC PC SN530 SDBPMPZ-256G-1101           0x1        256.06  GB / 256.06  GB    512   B +  0 B   21160001
sudo: unable to resolve host tv-pi2: Name or service not known
NVME Identify Controller:
vid       : 0x15b7
ssvid     : 0x15b7
sn        : 21116W804277        
mn        : WDC PC SN530 SDBPMPZ-256G-1101          
fr        : 21160001
rab       : 4
ieee      : 001b44
cmic      : 0
  [3:3] : 0	ANA not supported
  [2:2] : 0	PCI
  [1:1] : 0	Single Controller
  [0:0] : 0	Single Port

mdts      : 7
cntlid    : 0x1
ver       : 0x10400
rtd3r     : 0x7a120
rtd3e     : 0xf4240
oaes      : 0x200
  [31:31] : 0	Discovery Log Change Notice Not Supported
  [27:27] : 0	Zone Descriptor Changed Notices Not Supported
  [19:19] : 0	Allocated Namespace Attribute Notices Not Supported
  [17:17] : 0	Reachability Groups Change Notices Not Supported
  [16:16] : 0	Temperature Threshold Hysteresis Recovery Not Supported
  [15:15] : 0	Normal NSS Shutdown Event Not Supported
  [14:14] : 0	Endurance Group Event Aggregate Log Page Change Notice Not Supported
  [13:13] : 0	LBA Status Information Notices Not Supported
  [12:12] : 0	Predictable Latency Event Aggregate Log Change Notices Not Supported
  [11:11] : 0	Asymmetric Namespace Access Change Notices Not Supported
  [9:9] : 0x1	Firmware Activation Notices Supported
  [8:8] : 0	Namespace Attribute Changed Event Not Supported

ctratt    : 0x2
  [19:19] : 0	Flexible Data Placement Not Supported
  [18:18] : 0	Reservations and Host Identifier Interaction Not Supported
  [17:17] : 0	HMB Restrict Non-Operational Power State Access Not Supported
  [16:16] : 0	MDTS and Size Limits Exclude Metadata Not Supported
  [15:15] : 0	Extended LBA Formats Not Supported
  [14:14] : 0	Delete NVM Set Not Supported
  [13:13] : 0	Delete Endurance Group Not Supported
  [12:12] : 0	Variable Capacity Management Not Supported
  [11:11] : 0	Fixed Capacity Management Not Supported
  [10:10] : 0	Multi Domain Subsystem Not Supported
  [9:9] : 0	UUID List Not Supported
  [8:8] : 0	SQ Associations Not Supported
  [7:7] : 0	Namespace Granularity Not Supported
  [6:6] : 0	Traffic Based Keep Alive Not Supported
  [5:5] : 0	Predictable Latency Mode Not Supported
  [4:4] : 0	Endurance Groups Not Supported
  [3:3] : 0	Read Recovery Levels Not Supported
  [2:2] : 0	NVM Sets Not Supported
  [1:1] : 0x1	Non-Operational Power State Permissive Supported
  [0:0] : 0	128-bit Host Identifier Not Supported

rrls      : 0
bpcap     : 0
  [2:2] : 0	Set Features Boot Partition Write Protection Not Supported
  [1:0] : 0	RPMB Boot Partition Write Protection Support Not Specified

nssl      : 0
plsi      : 0
  [1:1] : 0	Power Loss Signaling with Forced Quiescence Not Supported
  [0:0] : 0	Power Loss Signaling with Emergency Power Fail Not Supported

cntrltype : 1
  [7:2] : 0	Reserved
  [1:0] : 0x1	I/O Controller
fguid     : 00000000-0000-0000-0000-000000000000
crdt1     : 0
crdt2     : 0
crdt3     : 0
crcap     : 0
  [1:1] : 0	RGRPID may change while the namespace is attached to any controller.
  [0:0] : 0	Reachability Reporting Not Supported

nvmsr     : 0
  [1:1] : 0	NVM subsystem Not part of an Enclosure
  [0:0] : 0	NVM subsystem Not part of a Storage Device

vwci      : 0
  [7:7] : 0	VPD Write Cycles Remaining field is Not valid.
  [6:0] : 0	VPD Write Cycles Remaining

mec       : 0
  [1:1] : 0	NVM subsystem Not contains a Management Endpoint on a PCIe port
  [0:0] : 0	NVM subsystem Not contains a Management Endpoint on an SMBus/I2C port

oacs      : 0x17
  [11:11] : 0	Host Managed Live Migration Not Supported
  [10:10] : 0	Lockdown Command and Feature Not Supported
  [9:9] : 0	Get LBA Status Capability Not Supported
  [8:8] : 0	Doorbell Buffer Config Not Supported
  [7:7] : 0	Virtualization Management Not Supported
  [6:6] : 0	NVMe-MI Send and Receive Not Supported
  [5:5] : 0	Directives Not Supported
  [4:4] : 0x1	Device Self-test Supported
  [3:3] : 0	NS Management and Attachment Not Supported
  [2:2] : 0x1	FW Commit and Download Supported
  [1:1] : 0x1	Format NVM Supported
  [0:0] : 0x1	Security Send and Receive Supported

acl       : 4
aerl      : 7
frmw      : 0x14
  [5:5] : 0	Multiple FW or Boot Update Detection Not Supported
  [4:4] : 0x1	Firmware Activate Without Reset Supported
  [3:1] : 0x2	Number of Firmware Slots
  [0:0] : 0	Firmware Slot 1 Read/Write

lpa       : 0x1e
  [6:6] : 0	Telemetry Log Data Area 4 Not Supported
  [5:5] : 0	LID 0x0, Scope of each command in LID 0x5, 0x12, 0x13 Not Supported
  [4:4] : 0x1	Persistent Event log Supported
  [3:3] : 0x1	Telemetry host/controller initiated log page Supported
  [2:2] : 0x1	Extended data for Get Log Page Supported
  [1:1] : 0x1	Command Effects Log Page Supported
  [0:0] : 0	SMART/Health Log Page per NS Not Supported

elpe      : 255
  [7:0] : 255 (0's based)	Error Log Page Entries (ELPE)

npss      : 4
  [7:0] : 4 (0's based)	Number of Power States Support (NPSS)

avscc     : 0x1
  [0:0] : 0x1	Admin Vendor Specific Commands uses NVMe Format

apsta     : 0x1
  [0:0] : 0x1	Autonomous Power State Transitions Supported

wctemp    : 353
 [15:0] : 80 °C (353 K)	Warning Composite Temperature Threshold (WCTEMP)

cctemp    : 358
 [15:0] : 85 °C (358 K)	Critical Composite Temperature Threshold (CCTEMP)

mtfa      : 50
hmpre     : 51200
hmmin     : 823
tnvmcap   : 256,060,514,304
[127:0] : 256,060,514,304
	Total NVM Capacity (TNVMCAP)

unvmcap   : 0
[127:0] : 0
	Unallocated NVM Capacity (UNVMCAP)

rpmbs     : 0
 [31:24]: 0	Access Size
 [23:16]: 0	Total Size
  [5:3] : 0	Authentication Method
  [2:0] : 0	Number of RPMB Units

edstt     : 44
dsto      : 1
  [1:1] : 0	Host-Initiated Refresh capability Not Supported
  [0:0] : 0x1	NVM subsystem supports only one device self-test operation in progress at a time

fwug      : 1
kas       : 0
hctma     : 0x1
  [0:0] : 0x1	Host Controlled Thermal Management Supported

mntmt     : 273
 [15:0] : 0 °C (273 K)	Minimum Thermal Management Temperature (MNTMT)

mxtmt     : 358
 [15:0] : 85 °C (358 K)	Maximum Thermal Management Temperature (MXTMT)

sanicap   : 0x60000002
  [31:30] : 0x1	Media is not additionally modified after sanitize operation completes successfully
  [29:29] : 0x1	No-Deallocate After Sanitize bit in Sanitize command Not Supported
  [3:3] : 0	Media Verification and Post-Verification Deallocation state Not Supported
  [2:2] : 0	Overwrite Sanitize Operation Not Supported
  [1:1] : 0x1	Block Erase Sanitize Operation Supported
  [0:0] : 0	Crypto Erase Sanitize Operation Not Supported

hmminds   : 0
hmmaxd    : 8
nsetidmax : 0
endgidmax : 0
anatt     : 0
anacap    : 0
  [7:7] : 0	Non-zero group ID Not Supported
  [6:6] : 0	Group ID does change
  [4:4] : 0	ANA Change state Not Supported
  [3:3] : 0	ANA Persistent Loss state Not Supported
  [2:2] : 0	ANA Inaccessible state Not Supported
  [1:1] : 0	ANA Non-optimized state Not Supported
  [0:0] : 0	ANA Optimized state Not Supported

anagrpmax : 0
nanagrpid : 0
pels      : 1
domainid  : 0
kpioc     : 0
  [1:1] : 0	Key Per I/O capability Not apply to all namespaces
  [0:0] : 0	Key Per I/O capability Not Supported

mptfawr   : 0
megcap    : 0
tmpthha   : 0
  [2:0] : 0	Temperature Threshold Maximum Hysteresis

cqt       : 0
sqes      : 0x66
  [7:4] : 0x6	Max SQ Entry Size (64)
  [3:0] : 0x6	Min SQ Entry Size (64)

cqes      : 0x44
  [7:4] : 0x4	Max CQ Entry Size (16)
  [3:0] : 0x4	Min CQ Entry Size (16)

maxcmd    : 0
nn        : 1
oncs      : 0x5f
  [12:12] : 0	Namespace Zeroes Not Supported
  [11:11] : 0	Maximum Write Zeroes with Deallocate Not Supported
  [10:10] : 0	All Fast Copy Not Supported
  [9:9] : 0	Copy Single Atomicity Not Supported
  [8:8] : 0	Copy Not Supported
  [7:7] : 0	Verify Not Supported
  [6:6] : 0x1	Timestamp Supported
  [5:5] : 0	Reservations Not Supported
  [4:4] : 0x1	Save and Select Supported
  [3:3] : 0x1	Write Zeroes Supported
  [2:2] : 0x1	Data Set Management Supported
  [1:1] : 0x1	Write Uncorrectable Supported
  [0:0] : 0x1	Compare Supported

fuses     : 0
  [0:0] : 0	Fused Compare and Write Not Supported

fna       : 0
  [3:3] : 0	Format NVM Broadcast NSID (FFFFFFFFh) Supported
  [2:2] : 0	Crypto Erase Not Supported as part of Secure Erase
  [1:1] : 0	Crypto Erase Applies to Single Namespace(s)
  [0:0] : 0	Format Applies to Single Namespace(s)

vwc       : 0x7
  [2:1] : 0x3	The Flush command supports NSID set to FFFFFFFFh
  [0:0] : 0x1	Volatile Write Cache Present

awun      : 0
awupf     : 0
icsvscc   : 1
  [0:0] : 0x1	NVM Vendor Specific Commands uses NVMe Format

nwpc      : 0
  [2:2] : 0	Permanent Write Protect Not Supported
  [1:1] : 0	Write Protect Until Power Supply Not Supported
  [0:0] : 0	No Write Protect and Write Protect Namespace Not Supported

acwu      : 0
ocfs      : 0
  [3:3] : 0	Controller Copy Format 3h Not Supported
  [2:2] : 0	Controller Copy Format 2h Not Supported
  [1:1] : 0	Controller Copy Format 1h Not Supported
  [0:0] : 0	Controller Copy Format 0h Not Supported

sgls      : 0
 [15:8] : 0	SGL Descriptor Threshold
 [1:0]  : 0	Scatter-Gather Lists Not Supported

mnan      : 0
maxdna    : 0
maxcna    : 0
oaqd      : 0
rhiri     : 0
hirt      : 0
cmmrtd    : 0
nmmrtd    : 0
minmrtg   : 0
maxmrtg   : 0
trattr    : 0
  [2:2] : 0	Memory Range Tracking Length Limit
  [1:1] : 0	Tracking User Data Changes Not Supported
  [0:0] : 0	Track Host Memory Changes Not Supported

mcudmq    : 0
mnsudmq   : 0
mcmr      : 0
nmcmr     : 0
mcdqpc    : 0
subnqn    : nqn.2018-01.com.wdc:guid:E8238FA6BF53-0001-001B448B48AD7030
ioccsz    : 0
iorcsz    : 0
icdoff    : 0
fcatt     : 0
  [0:0] : 0	Dynamic Controller Model

msdbd     : 0
ofcs      : 0
  [0:0] : 0	Disconnect command Not Supported

ps      0 : mp:3.50W operational enlat:0 exlat:0 rrt:0 rrl:0
            rwt:0 rwl:0 idle_power:0.6300W active_power:1.80W
            active_power_workload:80K 128KiB SW
            emergency power fail recovery time: -
            forced quiescence vault time: -
            emergency power fail vault time: -
ps      1 : mp:2.40W operational enlat:0 exlat:0 rrt:0 rrl:0
            rwt:0 rwl:0 idle_power:0.6300W active_power:1.60W
            active_power_workload:80K 128KiB SW
            emergency power fail recovery time: -
            forced quiescence vault time: -
            emergency power fail vault time: -
ps      2 : mp:1.90W operational enlat:0 exlat:0 rrt:0 rrl:0
            rwt:0 rwl:0 idle_power:0.6300W active_power:1.50W
            active_power_workload:80K 128KiB SW
            emergency power fail recovery time: -
            forced quiescence vault time: -
            emergency power fail vault time: -
ps      3 : mp:0.0250W non-operational enlat:3900 exlat:11000 rrt:3 rrl:3
            rwt:3 rwl:3 idle_power:0.0250W active_power:-
            active_power_workload:-
            emergency power fail recovery time: -
            forced quiescence vault time: -
            emergency power fail vault time: -
ps      4 : mp:0.0050W non-operational enlat:5000 exlat:39000 rrt:4 rrl:4
            rwt:4 rwl:4 idle_power:0.0050W active_power:-
            active_power_workload:-
            emergency power fail recovery time: -
            forced quiescence vault time: -
            emergency power fail vault time: -
sudo: unable to resolve host tv-pi2: Name or service not known
NVME Namespace List:
[   0]:0x1
sudo: unable to resolve host tv-pi2: Name or service not known
NVME Identify Namespace 1:
nsze    : 0x1dcf32b0	Total size in logical blocks
ncap    : 0x1dcf32b0	Maximum size in logical blocks
nuse    : 0x1dcf32b0	Current size in logical blocks
nsfeat  : 0x2
  [7:7] : 0	NPRG, NPRA and NORS are Not Supported
  [6:6] : 0	Single Atomicity Mode applies to write operations
  [5:4] : 0	NPWG, NPWA, NPDG, NPDGL, NPDA, and NOWS are Not Supported
  [3:3] : 0	NGUID and EUI64 fields if non-zero, Reused
  [2:2] : 0	Deallocated or Unwritten Logical Block error Not Supported
  [1:1] : 0x1	Namespace uses NAWUN, NAWUPF, and NACWU
  [0:0] : 0	Thin Provisioning Not Supported

nlbaf   : 1
flbas   : 0
  [6:5] : 0	Most significant 2 bits of Current LBA Format Selected
  [4:4] : 0	Metadata Transferred in Separate Contiguous Buffer
  [3:0] : 0	Least significant 4 bits of Current LBA Format Selected

mc      : 0
  [1:1] : 0	Metadata Pointer Not Supported
  [0:0] : 0	Metadata as Part of Extended Data LBA Not Supported

dpc     : 0
  [4:4] : 0	Protection Information Transferred as Last Bytes of Metadata Not Supported
  [3:3] : 0	Protection Information Transferred as First Bytes of Metadata Not Supported
  [2:2] : 0	Protection Information Type 3 Not Supported
  [1:1] : 0	Protection Information Type 2 Not Supported
  [0:0] : 0	Protection Information Type 1 Not Supported

dps     : 0
  [3:3] : 0	Protection Information is Transferred as Last Bytes of Metadata
  [2:0] : 0	Protection Information Disabled

nmic    : 0
  [1:1] : 0	Namespace is Not a Dispersed Namespace
  [0:0] : 0	Namespace Multipath Not Capable

rescap  : 0
  [7:7] : 0	Ignore Existing Key - Used as defined in revision 1.2.1 or earlier
  [6:6] : 0	Exclusive Access - All Registrants Not Supported
  [5:5] : 0	Write Exclusive - All Registrants Not Supported
  [4:4] : 0	Exclusive Access - Registrants Only Not Supported
  [3:3] : 0	Write Exclusive - Registrants Only Not Supported
  [2:2] : 0	Exclusive Access Not Supported
  [1:1] : 0	Write Exclusive Not Supported
  [0:0] : 0	Persist Through Power Loss Not Supported

fpi     : 0x80
  [7:7] : 0x1	Format Progress Indicator Supported
  [6:0] : 0	Format Progress Indicator (Remaining 0%)

dlfeat  : 9
  [4:4] : 0	Guard Field of Deallocated Logical Blocks is set to 0xFFFF
  [3:3] : 0x1	Deallocate Bit in the Write Zeroes Command is Supported
  [2:0] : 0x1	Bytes Read From a Deallocated Logical Block and its Metadata are 0x00

nawun   : 7
nawupf  : 7
nacwu   : 0
nabsn   : 7
nabo    : 7
nabspf  : 7
noiob   : 0
nvmcap  : 256,060,514,304
mssrl   : 0
mcl     : 0
msrc    : 0
kpios   : 0
  [1:1] : 0	Key Per I/O Capability Not Supported
  [0:0] : 0	Key Per I/O Capability Disabled

nulbaf  : 0
kpiodaag: 0
anagrpid: 0
nsattr	: 0
  [0:0] : 0	Namespace Not Write Protected

nvmsetid: 0
endgid  : 0
nguid   : 00000000000000000000000000000000
eui64   : 001b448b48ad7030
LBA Format  0 : Metadata Size: 0   bytes - Data Size: 512 bytes - Relative Performance: 0x2 Good (in use)
LBA Format  1 : Metadata Size: 0   bytes - Data Size: 4096 bytes - Relative Performance: 0x1 Better 

Network (TFTP boot)

No response

[peterharperuk]

Do you have the same problem if you boot from SD card? i.e. Duplicate the boot partition onto and sdcard, boot from that but use rootfs on nvme.

[jaredmauch]

I'm having trouble getting the pre-start.elf UART working but have these initial results/snapshots:

RP1_UART 0000001c00030000
[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x414fd0b1]
[    0.000000] Linux version 6.12.25+rpt-rpi-2712 (serge@raspberrypi.com) (aarch64-linux-gnu-gcc-14 (Debian 14.2.0-19) 14.2.0, GNU ld (GNU Binutils for Debian) 2.44) #1 SMP PREEMPT Debian 1:6.12.25-1+rpt1+trixie (2025-06-02
)
[    0.000000] KASLR enabled
[    0.000000] random: crng init done
[    0.000000] Machine model: Raspberry Pi 5 Model B Rev 1.0
[    0.000000] efi: UEFI not found.

If you want messages before that I need to tweak the right eprom settings.

I am able to subsequently boot with USB-MSD device, so have captured some hopefully relevant first/second boot messages:

0001:00:00.0 PCI bridge: Broadcom Inc. and subsidiaries BCM2712 PCIe Bridge (rev 21) (prog-if 00 [Normal decode])
        Flags: bus master, fast devsel, latency 0, IRQ 38
        Bus: primary=00, secondary=01, subordinate=01, sec-latency=0
        Memory behind bridge: 80000000-800fffff [size=1M] [32-bit]
        Prefetchable memory behind bridge: [disabled] [64-bit]
        Capabilities: [48] Power Management version 3
        Capabilities: [ac] Express Root Port (Slot-), IntMsgNum 0
        Capabilities: [100] Advanced Error Reporting
        Capabilities: [160] Virtual Channel
        Capabilities: [180] Vendor Specific Information: ID=0000 Rev=0 Len=028 <?>
        Capabilities: [300] Secondary PCI Express
        Kernel driver in use: pcieport

0001:01:00.0 Non-Volatile memory controller: Sandisk Corp PC SN530 NVMe SSD (DRAM-less) (rev 01) (prog-if 02 [NVM Express])
        Subsystem: Sandisk Corp PC SN530 NVMe SSD (DRAM-less)
        Flags: bus master, fast devsel, latency 0, IRQ 38
        Memory at 1b80000000 (64-bit, non-prefetchable) [size=16K]
        Memory at 1b80004000 (64-bit, non-prefetchable) [size=256]
        Capabilities: [80] Power Management version 3
        Capabilities: [90] MSI: Enable- Count=1/32 Maskable- 64bit+
        Capabilities: [b0] MSI-X: Enable+ Count=17 Masked-
        Capabilities: [c0] Express Endpoint, IntMsgNum 0
        Capabilities: [100] Advanced Error Reporting
        Capabilities: [150] Device Serial Number 00-00-00-00-00-00-00-00
        Capabilities: [1b8] Latency Tolerance Reporting
        Capabilities: [300] Secondary PCI Express
        Capabilities: [900] L1 PM Substates
        Kernel driver in use: nvme

0002:00:00.0 PCI bridge: Broadcom Inc. and subsidiaries BCM2712 PCIe Bridge (rev 21) (prog-if 00 [Normal decode])
        Flags: bus master, fast devsel, latency 0, IRQ 44
        Bus: primary=00, secondary=01, subordinate=01, sec-latency=0
        Memory behind bridge: 00000000-005fffff [size=6M] [32-bit]
        Prefetchable memory behind bridge: [disabled] [64-bit]
        Capabilities: [48] Power Management version 3
        Capabilities: [ac] Express Root Port (Slot-), IntMsgNum 0
        Capabilities: [100] Advanced Error Reporting
        Capabilities: [160] Virtual Channel
        Capabilities: [180] Vendor Specific Information: ID=0000 Rev=0 Len=028 <?>
        Capabilities: [240] L1 PM Substates
        Capabilities: [300] Secondary PCI Express
        Kernel driver in use: pcieport

0002:01:00.0 Ethernet controller: Raspberry Pi Ltd RP1 PCIe 2.0 South Bridge
        Flags: bus master, fast devsel, latency 0, IRQ 44
        Memory at 1f00410000 (32-bit, non-prefetchable) [size=16K]
        Memory at 1f00000000 (32-bit, non-prefetchable) [virtual] [size=4M]
        Memory at 1f00400000 (32-bit, non-prefetchable) [size=64K]
        Capabilities: [40] Power Management version 3
        Capabilities: [70] Express Endpoint, IntMsgNum 0
        Capabilities: [b0] MSI-X: Enable+ Count=61 Masked-
        Capabilities: [100] Advanced Error Reporting
        Kernel driver in use: rp1

and post-reboot:

0002:00:00.0 PCI bridge: Broadcom Inc. and subsidiaries BCM2712 PCIe Bridge (rev 21) (prog-if 00 [Normal decode])
        Flags: bus master, fast devsel, latency 0, IRQ 39
        Bus: primary=00, secondary=01, subordinate=01, sec-latency=0
        Memory behind bridge: 00000000-005fffff [size=6M] [32-bit]
        Prefetchable memory behind bridge: [disabled] [64-bit]
        Capabilities: [48] Power Management version 3
        Capabilities: [ac] Express Root Port (Slot-), IntMsgNum 0
        Capabilities: [100] Advanced Error Reporting
        Capabilities: [160] Virtual Channel
        Capabilities: [180] Vendor Specific Information: ID=0000 Rev=0 Len=028 <?>
        Capabilities: [240] L1 PM Substates
        Capabilities: [300] Secondary PCI Express
        Kernel driver in use: pcieport

0002:01:00.0 Ethernet controller: Raspberry Pi Ltd RP1 PCIe 2.0 South Bridge
        Flags: bus master, fast devsel, latency 0, IRQ 39
        Memory at 1f00410000 (32-bit, non-prefetchable) [size=16K]
        Memory at 1f00000000 (32-bit, non-prefetchable) [virtual] [size=4M]
        Memory at 1f00400000 (32-bit, non-prefetchable) [size=64K]
        Capabilities: [40] Power Management version 3
        Capabilities: [70] Express Endpoint, IntMsgNum 0
        Capabilities: [b0] MSI-X: Enable+ Count=61 Masked-
        Capabilities: [100] Advanced Error Reporting
        Kernel driver in use: rp1

It's not clear to me if I should see the same 0001 after reboot

[jaredmauch]

Do you have the same problem if you boot from SD card? i.e. Duplicate the boot partition onto and sdcard, boot from that but use rootfs on nvme.

I can reboot from something else, eg: USB afterwards. I've got the cable coming to get on the pi5 UART console and can get more details soon.

[jaredmauch]

wrong cable came - 2 vs 3 pin, too fast ordering or didn't pay enough attention right cables are coming tomorrow and I can do more extensive testing - It does work on first boot from power-on, but not subsequent, so it almost feels like a slight variance in the hardware process that doesn't probe the pcie in the same way, perhaps due to voltage sag or something else. It shows a 3A USB-PD in the boot messages

[peterharperuk]

Thanks. I have an SN530 on order so I'll test here when it arrives.

[jaredmauch]

If there's any additional traces I can set in the eeprom I can literally screen scrape with my eyes :/

[jaredmauch]

rpieeprom-issue-718.txt

Here's the full console logs. I can make sure you get full kernel logs if necessary, but I blame systemd

Procedure:

connect power adapter to usb port on pi5
system boots
login
reboot
system does not boot/see PCI-E device

[all]
BOOT_UART=1
UART_BAUD=115200
POWER_OFF_ON_HALT=1
BOOT_ORDER=0xf461
#NETCONSOLE=6655@192.168.4.142/,6666@/
HDMI_DELAY=0
#PCIE_PROBE=2

[jaredmauch]

Comparing the two boot processes against each other:

--- boot-ok.txt 2025-06-29 15:46:06
+++ boot-fail.txt       2025-06-29 15:44:25
@@ -1,11 +1,12 @@
   1.64 RPi: BOOTSYS release VERSION:960432b6 DATE: 2025/06/20 TIME: 13:08:33
-  1.xx BOOTMODE: 0x06 partition 0 build-ts BUILD_TIMESTAMP=1750421313 serial 65fc2aa3 boardrev d04170 stc 1648090
-  1.xx AON_RESET: 00000003 PM_RSTS 00001000
-  1.xx POWER_OFF_ON_HALT: 1 WAIT_FOR_POWER_BUTTON 0 power-on-reset 1
+  1.xx BOOTMODE: 0x06 partition 0 build-ts BUILD_TIMESTAMP=1750421313 serial 65fc2aa3 boardrev d04170 stc 1647938
+  1.xx AON_RESET: 00000003 PM_RSTS 00001420
+  1.xx POWER_OFF_ON_HALT: 1 WAIT_FOR_POWER_BUTTON 0 power-on-reset 0
   1.67 RP1_BOOT chip ID: 0x20001927
   1.67 PCIEx1: PWR 1 DET_WAKE 1
   1.67 part 00000000 reset_info 00000000
-  1.67 PMIC reset-event 00000000 rtc 00000001 alarm 00000000 enabled 0
+  1.67 PMIC reset-event 00000000 rtc 686191fb alarm 00000000 enabled 0
   1.68 uSD voltage 3.3V
   1.80 Initialising SDRAM rank 2 total-size: 64 Gbit 4267 (0x14 0x00)
   1.80 DDR 4267 1 0 64 152 BL:1
@@ -22,10 +23,10 @@
   5.07 RP1_CHIP_INFO 20001927
 
   5.07 RPi: BOOTLOADER release VERSION:960432b6 DATE: 2025/06/20 TIME: 13:08:33
-  5.08 BOOTMODE: 0x06 partition 0 build-ts BUILD_TIMESTAMP=1750421313 serial 65fc2aa3 boardrev d04170 stc 5082795
+  5.08 BOOTMODE: 0x06 partition 0 build-ts BUILD_TIMESTAMP=1750421313 serial 65fc2aa3 boardrev d04170 stc 5085956
   5.09 PCIEx1: PWR 1 DET_WAKE 1
   5.12 usb_pd_init status 3
-  5.12 USB_PD CONFIG 0 41
+  5.12 USB_PD CONFIG 0 43
   5.20 XHCI-STOP
   5.20 xHC0 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d
   5.xx USBSTS 1
@@ -58,79 +59,69 @@
   5.xx HID [01:00] 1.16 000000:02 register HID
   5.xx PCI1 init
   5.xx PCI1 reset
-  5.xx PCIe scan 000015b7:00005008
-  5.xx PCIe scan 000015b7:00005008
-  5.61 VID 0x15b7 MN WDC PC SN530 SDBPMPZ-256G-1101         
-  6.45 NVME on 0
-  6.14 MBR: 0x00002000, 1048576 type: 0x0c
-  6.14 MBR: 0x00102000,499061424 type: 0x83
-  6.15 MBR: 0x00000000,       0 type: 0x00
-  6.15 MBR: 0x00000000,       0 type: 0x00
-  6.59 Trying partition: 0
-  6.61 type: 32 lba: 8192 'mkfs.fat' ' bootfs     ' clusters 261116 (4)
-  6.16 rsc 32 fat-sectors 2040 root dir cluster 2 sectors 0 entries 0
-  6.35 FAT32 clusters 261116

Did some hand editing to tidy up slightly varying timestamps

I notice a few differences:

• PM_RSTS 00001000 vs PM_RSTS 00001420
• power-on-reset 1 vs power-on-reset 0
• STC 5082795 vs 5085956
• USB_PD CONFIG 0 41 vs USB_PD CONFIG 0 43
  -- Could this lead to a power dip even with the PSU?
• PCIe scan seems to fail or timeout

is the PCIE process different when power-on-reset=1 vs power-on-reset=0?

[jaredmauch]

Just updating as I saw the new image for 2712

  1.64 RPi: BOOTSYS release VERSION:6672d86c DATE: 2025/06/30 TIME: 00:16:51
  1.64 BOOTMODE: 0x06 partition 0 build-ts BUILD_TIMESTAMP=1751239011 serial 65fc2aa3 boardrev d04170 stc 1647810
  1.65 AON_RESET: 00000003 PM_RSTS 00001420
  1.66 POWER_OFF_ON_HALT: 1 WAIT_FOR_POWER_BUTTON 0 power-on-reset 0
  1.67 RP1_BOOT chip ID: 0x20001927
  1.67 PCIEx1: PWR 1 DET_WAKE 1
  1.67 part 00000000 reset_info 00000000
  1.67 PMIC reset-event 00000000 rtc 00000026 alarm 00000000 enabled 0
  1.68 uSD voltage 3.3V
  1.80 Initialising SDRAM rank 2 total-size: 64 Gbit 4267 (0x14 0x00)
  1.80 DDR 4267 1 0 64 152 BL:1
  3.71 OTP boardrev d04170 bootrom a a
  3.71 Customer key hash 0000000000000000000000000000000000000000000000000000000000000000
  3.72 VC-JTAG unlocked
  3.74 RP1_BOOT chip ID: 0x20001927

  4.38 RP1_BOOT chip ID: 0x20001927
  4.38 RP1_BOOT: fw size 46888
  5.02 PCI2 init
  5.02 PCI2 reset
  5.07 PCIe scan 00001de4:00000001
  5.07 RP1_CHIP_INFO 20001927

  5.07 RPi: BOOTLOADER release VERSION:6672d86c DATE: 2025/06/30 TIME: 00:16:51
  5.08 BOOTMODE: 0x06 partition 0 build-ts BUILD_TIMESTAMP=1751239011 serial 65fc2aa3 boardrev d04170 stc 5080834
  5.09 PCIEx1: PWR 1 DET_WAKE 1
  5.11 usb_pd_init status 3
  5.11 USB_PD CONFIG 0 43
  5.20 XHCI-STOP
  5.20 xHC0 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d
  5.20 USBSTS 1
  5.21 xHC0 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d
  5.21 xHC0 ports 3 slots 64 intrs 4
  5.22 XHCI-STOP
  5.22 xHC1 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d
  5.23 USBSTS 1
  5.23 xHC1 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d
  5.24 xHC1 ports 3 slots 64 intrs 4
  5.24 USB-PD: src-cap PDO object1 0x080191f4
  5.25 Current 5000 mA
  5.25 Voltage 5000 mV
  5.25 USB-PD: src-cap PDO object2 0x0002d12c
  5.25 Current 3000 mA
  5.26 Voltage 9000 mV
  5.26 USB-PD: src-cap PDO object3 0x0003c0e1
  5.26 Current 2250 mA
  5.26 Voltage 12000 mV
  5.27 USB-PD: src-cap PDO object4 0x0004b0b4
  5.27 Current 1800 mA
  5.27 Voltage 15000 mV
  5.32 SD_OC: 0
  5.20 Boot mode: NVME (06) order f14
  5.09 USB2[2] 00020ae1 connected
  5.60 USB2[2] 00200a03 connected enabled
  5.61 USB2 root HUB port 2 init
  5.48 DEV [01:00] 1.16 000000:02 class 0 VID 05ac PID 024f
  5.92 HID [01:00] 1.16 000000:02 register HID
  5.84 PCI1 init
  5.84 PCI1 reset
  6.67 PCIe timeout: 0x0001e08f
  6.47 Failed to open device: 'nvme'
  6.54 Retry NVME 1
  6.54 PCI1 init
  6.55 PCI1 reset
  7.73 PCIe timeout: 0x0001e08f
  7.53 Failed to open device: 'nvme'

[jaredmauch]

rpi-fw-20250630-firstboot-ok.txt

boot diff:

--- fb.txt      2025-06-30 14:39:06
+++ sb.txt      2025-06-30 14:39:15
@@ -1,11 +1,11 @@
 RPi: BOOTSYS release VERSION:6672d86c DATE: 2025/06/30 TIME: 00:16:51
-BOOTMODE: 0x06 partition 0 build-ts BUILD_TIMESTAMP=1751239011 serial 65fc2aa3 boardrev d04170 stc 1647945
-AON_RESET: 00000003 PM_RSTS 00001000
-POWER_OFF_ON_HALT: 1 WAIT_FOR_POWER_BUTTON 0 power-on-reset 1
+BOOTMODE: 0x06 partition 0 build-ts BUILD_TIMESTAMP=1751239011 serial 65fc2aa3 boardrev d04170 stc 1647794
+AON_RESET: 00000003 PM_RSTS 00001420
+POWER_OFF_ON_HALT: 1 WAIT_FOR_POWER_BUTTON 0 power-on-reset 0
 RP1_BOOT chip ID: 0x20001927
 PCIEx1: PWR 1 DET_WAKE 1
 part 00000000 reset_info 00000000
-PMIC reset-event 00000000 rtc 00000001 alarm 00000000 enabled 0
+PMIC reset-event 00000000 rtc 6862d968 alarm 00000000 enabled 0
 uSD voltage 3.3V
 Initialising SDRAM rank 2 total-size: 64 Gbit 4267 (0x14 0x00)
 DDR 4267 1 0 64 152 BL:1
@@ -22,10 +22,10 @@
 RP1_CHIP_INFO 20001927
 
 RPi: BOOTLOADER release VERSION:6672d86c DATE: 2025/06/30 TIME: 00:16:51
-BOOTMODE: 0x06 partition 0 build-ts BUILD_TIMESTAMP=1751239011 serial 65fc2aa3 boardrev d04170 stc 5088774
+BOOTMODE: 0x06 partition 0 build-ts BUILD_TIMESTAMP=1751239011 serial 65fc2aa3 boardrev d04170 stc 5059256
 PCIEx1: PWR 1 DET_WAKE 1
 usb_pd_init status 3
-USB_PD CONFIG 0 41
+USB_PD CONFIG 0 43
 XHCI-STOP
 xHC0 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d
 USBSTS 1
@@ -57,78 +57,33 @@
 HID [01:00] 1.16 000000:02 register HID
 PCI1 init
 PCI1 reset
-PCIe scan 000015b7:00005008
-PCIe scan 000015b7:00005008
-VID 0x15b7 MN WDC PC SN530 SDBPMPZ-256G-1101         
-NVME on 0
-MBR: 0x00002000, 1048576 type: 0x0c
-MBR: 0x00102000,499061424 type: 0x83
-MBR: 0x00000000,       0 type: 0x00
-MBR: 0x00000000,       0 type: 0x00
-Trying partition: 0
-type: 32 lba: 8192 'mkfs.fat' ' bootfs     ' clusters 261116 (4)
-rsc 32 fat-sectors 2040 root dir cluster 2 sectors 0 entries 0
-FAT32 clusters 261116
-[nvme] autoboot.txt not found
-Select partition rsts 0 C(boot_partition) 0 EEPROM config 0 result 1
-Trying partition: 1
-type: 32 lba: 8192 'mkfs.fat' ' bootfs     ' clusters 261115 (4)
-rsc 32 fat-sectors 2040 root dir cluster 2 sectors 0 entries 0
-FAT32 clusters 261115
-Read config.txt bytes     1367 hnd 0xffd
-[nvme] pieeprom.upd not found
-usb_max_current_enable forced to 1
-Read bcm2712-rpi-5-b.dtb bytes    78231 hnd 0x2db17
-dt-match: compatible: raspberrypi,5-model-b match: brcm,bcm2712
-dt-match: compatible: brcm,bcm2712 match: brcm,bcm2712
-MESS:00:00:06.829915:0: *** Restart logging
-Read /config.txt bytes     1367 hnd 0xffd
-Read /config.txt bytes     1367 hnd 0xffd
-MESS:00:00:07.024646:0: Initial voltage 800000 temp 57614
-MESS:00:00:07.225233:0: avs_2712: AVS pred 8656 865600 temp 58163
-MESS:00:00:07.228835:0: vpred 865 mV +0
-MESS:00:00:07.237358:0: FB framebuffer_swap 1
-MESS:00:00:07.239223:0: Select resolution HDMI0/2 hotplug 0 max_mode 2
-MESS:00:00:07.246113:0: Select resolution HDMI1/2 hotplug 1 max_mode 2
-MESS:00:00:07.253030:0: FB0 disp 1 max-fb 2 1920x1080 stride 3840 base 0x3f800000
-Loading 'initramfs_2712' to 0x00000000 offset 0x0
-Read initramfs_2712 bytes 16041534 hnd 0x3c8d8

[jaredmauch]

I added in a 1000uF capacitor on the 5v to make sure that even with the official PSU there's no issues with voltage sag, also tried the 250703 image as well and same results FYI.

[jaredmauch]

@peterharperuk anything I can do to help at the moment, I know it's summer time. They do boot fine with CM5. If you are unable to reproduce I can send you a unit to help triage.