I am new to mender and trying to get a test running with it. I downloaded the Pi 4B Image from the docs, however the device seems to fail after mounting /uboot.
Is there a newer image out there that I’m not seeing?
HI @rtruran,
Sorry to hear you’re having trouble getting started. I assume we are talking about the images linked here? Just giving it a try too and it indeed seems to behave strangely. Will take it to the team and keep you in the loop.
Greetz,
Josef
Yes thats the one, Thank you for looking into this.
Hi @rtruran,
Sure thing. Unfortunately the bits and pieces do not form a clear image yet. So can you maybe share a few more details?
- I assume that you see that
/ubootmounting fail on the serial console, right? If so, can you please try to capture a full boot log? - Did you use the official Raspberry Pi imager as described in the documentation, or some alternative? If so, which? In any case, is there a version number of it? (unless it was
dd…) - Have you tried another SD card?
- what size is the SD card you are using?
- are you using a specific or special version of the Pi4?
To correlate with my findings:
- I tried with the official imager on two different cards, different sizes
- On the serial console, the boot progresses for about 10 seconds, then it drops into a reboot-poweroff.
- A coworker who also checked the image reports that it works for him.
As you can see this is a bit mysterious at the moment… 
Greetz,
Josef
Interesting.
- I can try to get a log.
- I’m using the official Raspberry Pi imager v.1.7.3
- Yes.
- 128GB.
- I’m currently using an 8GB PI 4.
My device drops into a poweroff, but never loads up. no display on the HDMI Port, no response from ping, etc.
I am using the network config settings from the imager, perhaps thats the issue.
Hi @rtruran,
More news:
- I tried using the balena Etcher, and it worked right away.
- my coworker who confirmed the image working used
dd - another coworker tried with the Raspberry Pi Imager on Ubuntu 20.04, and he confirms it works.
- it certainly did not for me, but I am on macOS
So what OS are you on? Can you try again using balena Etcher, in case you’re on macOS or Windows, or dd on Linux?
Greetz,
Josef
I’m on MacOS, I tested out Balena etcher but got the same result.
Hi @rtruran,
tested the Raspberry Pi Imager 1.7.3 on Windows and Linux now, and both work like a charm. One observation is that they both take considerably longer to write (but that can also be due to other hardware), so at least by gut feeling I would now conclude that the Imager has some kind of issue on a recent macOS, presumably due to data syncing.
Can you try to write the image from some Windows or Linux box?
Greetz,
Josef
Hi, I have the exact same issue. Tried both Pi 3 B+ and Pi 4.
64GB SD-card.
I’m using the Windows 11 and Imager v1.7.3.
On my PI 4 I can only see that is says “Failed to start Command from Kernel Command Line” + “Dependency failed for Command from Kernel Command Line.” before it dies.
Update: I used a virtual Ubuntu machine to run Imager and it worked. Have no clue to why it wouldn’t work on my Windows machine.
On my Windows machine, before I gave up, I tried to set my Imager settings to default and I also turned off Auto Play thinking it might interfere.
@TheYoctoJester, I seem to be having a similar problem with Windows 10.0.19044 and Raspberry Pi Imager 1.7.4. You can see from the serial log below that it says reboot: Power down but it never powers back up again. If I manually power-cycle after that, it starts over at the very top and gets stuck at the same spot.
I can also try to flash the SD from either the RPi itself (running ‘normal’ Debian), or from another laptop I have here running Ubuntu on bare metal. I have WSL running on my Windows laptop, but that just confuses things further.
U-Boot 2020.01-g83cf4883ec (Dec 08 2022 - 09:34:13 +0000)
DRAM: 3.9 GiB
RPI 4 Model B (0xc03112)
MMC: mmcnr@7e300000: 1, mmc@7e340000: 0
Loading Environment from MMC... OK
In: serial
Out: serial
Err: serial
Net: Net Initialization Skipped
No ethernet found.
Hit any key to stop autoboot: 0
switch to partitions #0, OK
mmc0 is current device
Scanning mmc 0:1...
Found U-Boot script /boot.scr
568 bytes read in 19 ms (28.3 KiB/s)
## Executing script at 02400000
switch to partitions #0, OK
mmc0 is current device
7040160 bytes read in 1297 ms (5.2 MiB/s)
Kernel image @ 0x080000 [ 0x000000 - 0x6b6ca0 ]
## Flattened Device Tree blob at 2eff2d00
Booting using the fdt blob at 0x2eff2d00
Using Device Tree in place at 2eff2d00, end 2f002f17
Starting kernel ...
[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Linux version 5.15.61-v7l+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1579 SMP Fri Aug 26 11:13:03 BST 2022
[ 0.000000] CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d
[ 0.000000] CPU: div instructions available: patching division code
[ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
[ 0.000000] OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.2
[ 0.000000] random: crng init done
[ 0.000000] Memory policy: Data cache writealloc
[ 0.000000] Reserved memory: created CMA memory pool at 0x000000001ac00000, size 320 MiB
[ 0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[ 0.000000] Zone ranges:
[ 0.000000] DMA [mem 0x0000000000000000-0x000000002fffffff]
[ 0.000000] Normal empty
[ 0.000000] HighMem [mem 0x0000000030000000-0x00000000fbffffff]
[ 0.000000] Movable zone start for each node
[ 0.000000] Early memory node ranges
[ 0.000000] node 0: [mem 0x0000000000000000-0x000000003b3fffff]
[ 0.000000] node 0: [mem 0x0000000040000000-0x00000000fbffffff]
[ 0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x00000000fbffffff]
[ 0.000000] percpu: Embedded 16 pages/cpu s35788 r8192 d21556 u65536
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1011008
[ 0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=1 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 video=HDMI-A-1:1920x1080M@60 smsc95xx.macaddr=DC:A6:32:72:67:00 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 console=ttyS0,115200 console=tty1 root=/dev/mmcblk0p2 rootfstype=ext4 fsck.repair=yes rootwait systemd.run=/boot/firstrun.sh systemd.run_success_action=reboot systemd.unit=kernel-command-line.target
[ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)
[ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)
[ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[ 0.000000] software IO TLB: mapped [mem 0x0000000014890000-0x0000000018890000] (64MB)
[ 0.000000] Memory: 3601324K/4050944K available (10240K kernel code, 1385K rwdata, 3304K rodata, 2048K init, 592K bss, 121940K reserved, 327680K cma-reserved, 3264512K highmem)
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[ 0.000000] ftrace: allocating 35501 entries in 105 pages
[ 0.000000] ftrace: allocated 105 pages with 4 groups
[ 0.000000] trace event string verifier disabled
[ 0.000000] rcu: Hierarchical RCU implementation.
[ 0.000000] Rude variant of Tasks RCU enabled.
[ 0.000000] Tracing variant of Tasks RCU enabled.
[ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[ 0.000000] GIC: Using split EOI/Deactivate mode
[ 0.000000] irq_brcmstb_l2: registered L2 intc (/soc/interrupt-controller@7ef00100, parent irq: 25)
[ 0.000000] arch_timer: cp15 timer(s) running at 54.00MHz (phys).
[ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns
[ 0.000000] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns
[ 0.000021] Switching to timer-based delay loop, resolution 18ns
[ 0.000355] Console: colour dummy device 80x30
[ 0.001041] printk: console [tty1] enabled
[ 0.001120] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000)
[ 0.001172] pid_max: default: 32768 minimum: 301
[ 0.001324] LSM: Security Framework initializing
[ 0.001532] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[ 0.001578] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[ 0.003118] cgroup: Disabling memory control group subsystem
[ 0.003369] CPU: Testing write buffer coherency: ok
[ 0.003440] CPU0: Spectre BHB: enabling loop workaround for all CPUs
[ 0.003882] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[ 0.005230] Setting up static identity map for 0x200000 - 0x20003c
[ 0.005448] rcu: Hierarchical SRCU implementation.
[ 0.006439] smp: Bringing up secondary CPUs ...
[ 0.007588] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[ 0.008860] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[ 0.010156] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[ 0.010327] smp: Brought up 1 node, 4 CPUs
[ 0.010406] SMP: Total of 4 processors activated (432.00 BogoMIPS).
[ 0.010437] CPU: All CPU(s) started in HYP mode.
[ 0.010463] CPU: Virtualization extensions available.
[ 0.011217] devtmpfs: initialized
[ 0.026338] VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0
[ 0.026578] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.026635] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[ 0.036696] pinctrl core: initialized pinctrl subsystem
[ 0.037971] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[ 0.042180] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[ 0.042986] audit: initializing netlink subsys (disabled)
[ 0.043256] audit: type=2000 audit(0.040:1): state=initialized audit_enabled=0 res=1
[ 0.043893] thermal_sys: Registered thermal governor 'step_wise'
[ 0.044700] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[ 0.044757] hw-breakpoint: maximum watchpoint size is 8 bytes.
[ 0.045159] Serial: AMBA PL011 UART driver
[ 0.059219] bcm2835-mbox fe00b880.mailbox: mailbox enabled
[ 0.100053] raspberrypi-firmware soc:firmware: Attached to firmware from 2022-08-26T14:03:16, variant start
[ 0.110070] raspberrypi-firmware soc:firmware: Firmware hash is 102f1e848393c2112206fadffaaf86db04e98326
[ 0.168624] Kprobes globally optimized
[ 0.174566] bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1
[ 0.179071] vgaarb: loaded
[ 0.179542] SCSI subsystem initialized
[ 0.179799] usbcore: registered new interface driver usbfs
[ 0.179875] usbcore: registered new interface driver hub
[ 0.179979] usbcore: registered new device driver usb
[ 0.180453] usb_phy_generic phy: supply vcc not found, using dummy regulator
[ 0.180673] usb_phy_generic phy: dummy supplies not allowed for exclusive requests
[ 0.181006] pps_core: LinuxPPS API ver. 1 registered
[ 0.181035] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[ 0.181084] PTP clock support registered
[ 0.182790] clocksource: Switched to clocksource arch_sys_counter
[ 0.263467] VFS: Disk quotas dquot_6.6.0
[ 0.263594] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 0.263791] FS-Cache: Loaded
[ 0.263980] CacheFiles: Loaded
[ 0.265074] simple-framebuffer 3e3cf000.framebuffer: framebuffer at 0x3e3cf000, 0x7f8000 bytes
[ 0.265119] simple-framebuffer 3e3cf000.framebuffer: format=a8r8g8b8, mode=1920x1080x32, linelength=7680
[ 0.275629] Console: switching to colour frame buffer device 240x67
[ 0.285243] simple-framebuffer 3e3cf000.framebuffer: fb0: simplefb registered!
[ 0.293949] NET: Registered PF_INET protocol family
[ 0.294225] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
[ 0.295859] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
[ 0.295973] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[ 0.296070] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
[ 0.296207] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear)
[ 0.296342] TCP: Hash tables configured (established 8192 bind 8192)
[ 0.296549] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[ 0.296651] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[ 0.296952] NET: Registered PF_UNIX/PF_LOCAL protocol family
[ 0.297983] RPC: Registered named UNIX socket transport module.
[ 0.298063] RPC: Registered udp transport module.
[ 0.298124] RPC: Registered tcp transport module.
[ 0.298185] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 0.298268] PCI: CLS 0 bytes, default 64
[ 1.307473] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
[ 1.330864] Initialise system trusted keyrings
[ 1.331202] workingset: timestamp_bits=14 max_order=20 bucket_order=6
[ 1.340307] zbud: loaded
[ 1.342124] FS-Cache: Netfs 'nfs' registered for caching
[ 1.343046] NFS: Registering the id_resolver key type
[ 1.343160] Key type id_resolver registered
[ 1.343219] Key type id_legacy registered
[ 1.343395] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[ 1.343477] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[ 1.344752] Key type asymmetric registered
[ 1.344815] Asymmetric key parser 'x509' registered
[ 1.345093] bounce: pool size: 64 pages
[ 1.345198] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[ 1.345500] io scheduler mq-deadline registered
[ 1.345565] io scheduler kyber registered
[ 1.357356] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
[ 1.360195] brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff]
[ 1.363104] brcm-pcie fd500000.pcie: MEM 0x0600000000..0x063fffffff -> 0x00c0000000
[ 1.365964] brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x00bfffffff -> 0x0400000000
[ 1.424893] brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC)
[ 1.427972] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
[ 1.430685] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 1.433409] pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff])
[ 1.436217] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
[ 1.439192] pci 0000:00:00.0: PME# supported from D0 D3hot
[ 1.445566] PCI: bus0: Fast back to back transfers disabled
[ 1.448343] pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[ 1.451540] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
[ 1.454473] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
[ 1.457721] pci 0000:01:00.0: PME# supported from D0 D3cold
[ 1.464149] PCI: bus1: Fast back to back transfers disabled
[ 1.466930] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
[ 1.469734] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
[ 1.472514] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
[ 1.475359] pci 0000:00:00.0: PCI bridge to [bus 01]
[ 1.478117] pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff]
[ 1.481263] pcieport 0000:00:00.0: enabling device (0140 -> 0142)
[ 1.484250] pcieport 0000:00:00.0: PME: Signaling with IRQ 78
[ 1.496430] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
[ 1.502371] iproc-rng200 fe104000.rng: hwrng registered
[ 1.505537] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[ 1.509852] gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000
[ 1.525114] brd: module loaded
[ 1.538013] loop: module loaded
[ 1.541516] Loading iSCSI transport class v2.0-870.
[ 1.549394] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000
[ 1.652953] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus
[ 1.656823] usbcore: registered new interface driver r8152
[ 1.659543] usbcore: registered new interface driver lan78xx
[ 1.662228] usbcore: registered new interface driver smsc95xx
[ 1.666440] xhci_hcd 0000:01:00.0: enabling device (0140 -> 0142)
[ 1.669175] xhci_hcd 0000:01:00.0: xHCI Host Controller
[ 1.671764] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
[ 1.679971] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x00003c0000000890
[ 1.683625] xhci_hcd 0000:01:00.0: xHCI Host Controller
[ 1.686201] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
[ 1.688785] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
[ 1.691741] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15
[ 1.694411] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 1.697054] usb usb1: Product: xHCI Host Controller
[ 1.699675] usb usb1: Manufacturer: Linux 5.15.61-v7l+ xhci-hcd
[ 1.702277] usb usb1: SerialNumber: 0000:01:00.0
[ 1.705566] hub 1-0:1.0: USB hub found
[ 1.708190] hub 1-0:1.0: 1 port detected
[ 1.711758] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.15
[ 1.714389] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 1.716978] usb usb2: Product: xHCI Host Controller
[ 1.719543] usb usb2: Manufacturer: Linux 5.15.61-v7l+ xhci-hcd
[ 1.722137] usb usb2: SerialNumber: 0000:01:00.0
[ 1.725437] hub 2-0:1.0: USB hub found
[ 1.728072] hub 2-0:1.0: 4 ports detected
[ 1.732459] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[ 1.736090] usbcore: registered new interface driver uas
[ 1.738727] usbcore: registered new interface driver usb-storage
[ 1.741461] mousedev: PS/2 mouse device common for all mice
[ 1.749108] sdhci: Secure Digital Host Controller Interface driver
[ 1.751643] sdhci: Copyright(c) Pierre Ossman
[ 1.754889] sdhci-pltfm: SDHCI platform and OF driver helper
[ 1.761098] ledtrig-cpu: registered to indicate activity on CPUs
[ 1.763947] hid: raw HID events driver (C) Jiri Kosina
[ 1.766637] usbcore: registered new interface driver usbhid
[ 1.769146] usbhid: USB HID core driver
[ 1.777469] Initializing XFRM netlink socket
[ 1.780172] NET: Registered PF_PACKET protocol family
[ 1.782802] Key type dns_resolver registered
[ 1.785731] Registering SWP/SWPB emulation handler
[ 1.788950] registered taskstats version 1
[ 1.791409] Loading compiled-in X.509 certificates
[ 1.794735] Key type ._fscrypt registered
[ 1.797148] Key type .fscrypt registered
[ 1.799530] Key type fscrypt-provisioning registered
[ 1.814005] uart-pl011 fe201000.serial: there is not valid maps for state default
[ 1.816769] uart-pl011 fe201000.serial: cts_event_workaround enabled
[ 1.819313] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 34, base_baud = 0) is a PL011 rev2
[ 1.830059] bcm2835-aux-uart fe215040.serial: there is not valid maps for state default
[ 1.833365] printk: console [ttyS0] disabled
[ 1.835940] fe215040.serial: ttyS0 at MMIO 0xfe215040 (irq = 35, base_baud = 62500000) is a 16550
[ 2.032826] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[ 2.275795] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21
[ 2.283557] printk: console [ttyS0] enabled
[ 2.293535] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[ 2.293554] usb 1-1: Product: USB2.0 Hub
[ 3.224698] hub 1-1:1.0: USB hub found
[ 3.225010] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[ 3.240075] hub 1-1:1.0: 4 ports detected
[ 3.240555] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[ 3.257913] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0
[ 3.266649] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated
[ 3.305768] of_cfs_init
[ 3.310872] of_cfs_init: OK
[ 3.354063] mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA
[ 3.364455] Waiting for root device /dev/mmcblk0p2...
[ 3.400589] mmc0: new high speed SDHC card at address b368
[ 3.409817] mmcblk0: mmc0:b368 NCard 29.1 GiB
[ 3.427218] mmc1: new high speed SDIO card at address 0001
[ 3.435442] mmcblk0: p1 p2 p3 p4
[ 3.442705] mmcblk0: mmc0:b368 NCard 29.1 GiB
[ 3.468694] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
[ 3.481320] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[ 3.492188] devtmpfs: mounted
[ 3.509590] Freeing unused kernel image (initmem) memory: 2048K
[ 3.518536] Run /sbin/init as init process
[ 3.592829] usb 1-1.3: new full-speed USB device number 3 using xhci_hcd
[ 3.743227] usb 1-1.3: New USB device found, idVendor=046d, idProduct=c534, bcdDevice=29.01
[ 3.754472] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[ 3.764600] usb 1-1.3: Product: USB Receiver
[ 3.771609] usb 1-1.3: Manufacturer: Logitech
[ 3.790385] input: Logitech USB Receiver as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3:1.0/0003:046D:C534.0001/input/input0
[ 3.873766] hid-generic 0003:046D:C534.0001: input,hidraw0: USB HID v1.11 Keyboard [Logitech USB Receiver] on usb-0000:01:00.0-1.3/input0
[ 3.901427] input: Logitech USB Receiver Mouse as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3:1.1/0003:046D:C534.0002/input/input1
[ 3.921409] input: Logitech USB Receiver Consumer Control as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3:1.1/0003:046D:C534.0002/input/input2
[ 4.003225] input: Logitech USB Receiver System Control as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3:1.1/0003:046D:C534.0002/input/input3
[ 4.024192] hid-generic 0003:046D:C534.0002: input,hiddev96,hidraw1: USB HID v1.11 Mouse [Logitech USB Receiver] on usb-0000:01:00.0-1.3/input1
[ 4.189933] systemd[1]: System time before build time, advancing clock.
[ 4.476349] NET: Registered PF_INET6 protocol family
[ 4.486269] Segment Routing with IPv6
[ 4.493196] In-situ OAM (IOAM) with IPv6
[ 4.620736] systemd[1]: systemd 247.3-7+rpi1+deb11u1 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +ZSTD +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=unified)
[ 4.655235] systemd[1]: Detected architecture arm.
[ 4.692472] systemd[1]: Set hostname to <raspberrypi>.
[ 5.736080] systemd[1]: Created slice system-modprobe.slice.
[ 5.751247] systemd[1]: Created slice system-systemd\x2dgrowfs.slice.
[ 5.766476] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[ 5.783536] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[ 5.801672] systemd[1]: Reached target Local Encrypted Volumes.
[ 5.816174] systemd[1]: Reached target Swap.
[ 5.829554] systemd[1]: Listening on fsck to fsckd communication Socket.
[ 5.845735] systemd[1]: Listening on Journal Audit Socket.
[ 5.860236] systemd[1]: Listening on Journal Socket (/dev/log).
[ 5.875311] systemd[1]: Listening on Journal Socket.
[ 5.895602] systemd[1]: Listening on udev Control Socket.
[ 5.910180] systemd[1]: Listening on udev Kernel Socket.
[ 5.924529] systemd[1]: Condition check resulted in Huge Pages File System being skipped.
[ 5.941655] systemd[1]: Mounting POSIX Message Queue File System...
[ 5.962291] systemd[1]: Mounting Kernel Debug File System...
[ 5.982158] systemd[1]: Mounting Kernel Trace File System...
[ 6.011638] systemd[1]: Starting Restore / save the current clock...
[ 6.032353] systemd[1]: Starting Set the console keyboard layout...
[ 6.053675] systemd[1]: Starting Create list of static device nodes for the current kernel...
[ 6.077113] systemd[1]: Starting Mender service to grow data partition size...
[ 6.099070] systemd[1]: Starting Load Kernel Module configfs...
[ 6.119958] systemd[1]: Starting Load Kernel Module fuse...
[ 6.146040] systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped.
[ 6.165133] systemd[1]: Starting File System Check on Root Device...
[ 6.192430] systemd[1]: Starting Journal Service...
[ 6.198525] fuse: init (API version 7.34)
[ 6.241724] systemd[1]: Starting Load Kernel Modules...
[ 6.275790] systemd[1]: Starting Coldplug All udev Devices...
[ 6.305078] systemd[1]: Mounted POSIX Message Queue File System.
[ 6.320451] systemd[1]: Mounted Kernel Debug File System.
[ 6.334309] systemd[1]: Mounted Kernel Trace File System.
[ 6.350533] systemd[1]: Finished Restore / save the current clock.
[ 6.367850] systemd[1]: Finished Create list of static device nodes for the current kernel.
[ 6.386356] systemd[1]: modprobe@configfs.service: Succeeded.
[ 6.397391] systemd[1]: Finished Load Kernel Module configfs.
[ 6.413826] systemd[1]: modprobe@fuse.service: Succeeded.
[ 6.424156] systemd[1]: Finished Load Kernel Module fuse.
[ 6.439787] systemd[1]: Finished Load Kernel Modules.
[ 6.459276] systemd[1]: Mounting FUSE Control File System...
[ 6.478275] systemd[1]: Mounting Kernel Configuration File System...
[ 6.498296] systemd[1]: Started File System Check Daemon to report status.
[ 6.519189] systemd[1]: Starting Apply Kernel Variables...
[ 6.538597] systemd[1]: Mounted FUSE Control File System.
[ 6.552410] systemd[1]: Mounted Kernel Configuration File System.
[ 6.670591] systemd[1]: Finished Set the console keyboard layout.
[ 15.475790] reboot: Power down
Flashing the SD card using an RPi meant that it could be booted by an RPi, but repeated attempts to deploy to the demo artifact fail with the below log. However, it would seem that my /run has plenty of space.
don@raspberrypi:~ $ df
Filesystem 1K-blocks Used Available Use% Mounted on
/dev/root 3512352 1406616 1906876 43% /
devtmpfs 1800664 0 1800664 0% /dev
tmpfs 1965528 0 1965528 0% /dev/shm
tmpfs 786212 1120 785092 1% /run
tmpfs 5120 4 5116 1% /run/lock
/dev/mmcblk0p1 261108 44460 216648 18% /uboot
/dev/mmcblk0p4 22220692 56 21284560 1% /data
tmpfs 393104 0 393104 0% /run/user/1000
don@raspberrypi:~ $
Log for failed deployment…
2023-03-29 15:21:46 +0000 UTC info: Running Mender client version: 3.5.0
2023-03-29 15:21:47 +0000 UTC info: State transition: update-fetch [Download_Enter] -> update-store [Download_Enter]
2023-03-29 15:21:47 +0000 UTC info: No public key was provided for authenticating the artifact
2023-03-29 15:21:53 +0000 UTC info: State transition: update-store [Download_Enter] -> update-after-store [Download_Leave]
2023-03-29 15:21:53 +0000 UTC info: State transition: update-after-store [Download_Leave] -> mender-update-control-refresh-maps [none]
2023-03-29 15:21:53 +0000 UTC info: State transition: mender-update-control-refresh-maps [none] -> mender-update-control [none]
2023-03-29 15:21:53 +0000 UTC info: State transition: mender-update-control [none] -> update-install [ArtifactInstall]
2023-03-29 15:21:55 +0000 UTC info: State transition: update-install [ArtifactInstall] -> mender-update-control-refresh-maps [none]
2023-03-29 15:21:55 +0000 UTC info: Executing script: ArtifactInstall_Leave_50_choose_busybox_arch
2023-03-29 15:21:56 +0000 UTC info: Executing script: ArtifactInstall_Leave_90_install_systemd_unit
2023-03-29 15:21:57 +0000 UTC info: Collected output (stderr) while running script /var/lib/mender/scripts/ArtifactInstall_Leave_90_install_systemd_unit
Failed to reload daemon: Refusing to reload, not enough space available on /run/systemd. Currently, 3.4M are free, but a safety buffer of 16.0M is enforced.
---------- end of script output
2023-03-29 15:21:57 +0000 UTC error: transient error: error executing leave script for update-install state: error running leave state script(s) for ArtifactInstall state: statescript: error executing 'ArtifactInstall_Leave_90_install_systemd_unit': 1 : exit status 1
2023-03-29 15:21:57 +0000 UTC info: State transition: update-install [ArtifactInstall] -> rollback [ArtifactRollback]
2023-03-29 15:21:57 +0000 UTC info: Executing script: ArtifactRollback_Enter_00_remove_systemd_unit
2023-03-29 15:22:00 +0000 UTC info: Collected output (stderr) while running script /var/lib/mender/scripts/ArtifactRollback_Enter_00_remove_systemd_unit
Failed to reload daemon: Refusing to reload, not enough space available on /run/systemd. Currently, 3.4M are free, but a safety buffer of 16.0M is enforced.
Failed to reload daemon: Refusing to reload, not enough space available on /run/systemd. Currently, 3.4M are free, but a safety buffer of 16.0M is enforced.
---------- end of script output
2023-03-29 15:22:00 +0000 UTC error: statescript: Ignoring error executing 'ArtifactRollback_Enter_00_remove_systemd_unit': 1: exit status 1
2023-03-29 15:22:00 +0000 UTC info: Performing rollback
2023-03-29 15:22:01 +0000 UTC error: Unable to set the value returned from the update module in the database. Error: RebootRequested not assigned in order
2023-03-29 15:22:01 +0000 UTC info: State transition: rollback [ArtifactRollback] -> update-error [ArtifactFailure]
2023-03-29 15:22:01 +0000 UTC info: State transition: update-error [ArtifactFailure] -> cleanup [Error]
2023-03-29 15:22:01 +0000 UTC info: State transition: cleanup [Error] -> update-status-report [none]
Hi @DonPancoe,
I’m currently having the exact same issue using windows and the 1.7.4 imager. Have you found a solution to this problem?
Hi @Ries,
Sorry to hear! Which image are you putting onto the SD card? RPi3 or RPi4? Then I’ll try and give it a spin too.
Greetz,
Josef
Hey @TheYoctoJester,
I’m trying to put the image for the RPi4 on the SD card. So far, I managed to make it work by downgrading to Raspberry Pi Imager v1.7.3 and disabling the pre-enabled WiFi settings. After installation, I encountered no problems when re-enabling the settings on the Pi. However, when I installed them with the WiFi settings enabled in the imager, it resulted in a black screen after installation and the Pi became unresponsive.
Thanks for the quick reply,
Ries
Hi @Ries,
Thanks for the response. So to conclude: the images linked here work for you, if you use the RaspberryPi Imager on Windows, if you do not use any of its special features to inject some form of configuration. Is that correct? Have you maybe also tried Etcher?
If we know that a specific situation is problematic, then I add this as a warning to the documentation.
Greetz,
Josef
Hi @Ries,
After some investigation I have found the configuration injection mechanisms of the Raspberry Pi Imager to cause the boot problems. Downgrading does not seem to be correlated, I could reproduce with 1.7.5:
- no added configuration (user credentials, WiFi, SSH): works
- any added configuration: doesn’t work
For the time being, I have added a warning to the documentation. My understanding is that this effect is caused by the firstboot script generated via Raspberry Pi Imager misbehaving.
Greetz,
Josef
1 Like