Skip to content

Instantly share code, notes, and snippets.

@jpluimers
Last active April 28, 2018 19:54
Show Gist options
  • Save jpluimers/9520fca6d643341dddf002067e772276 to your computer and use it in GitHub Desktop.
Save jpluimers/9520fca6d643341dddf002067e772276 to your computer and use it in GitHub Desktop.
Notes on broken Raspberry Pi 3 openSUSE Tumbleweed Kernel 4.12.9-1 boot
GNU GRUB version 2.02
┌────────────────────────────────────────────────────────────────────────────┐
│setparams 'openSUSE Tumbleweed' │
│ │
│ load_video │
│ set gfxpayload=keep │
│ insmod gzio │
│ insmod part_msdos │
│ insmod ext2 │
│ set root='hd0,msdos2' │
│ if [ x$feature_platform_search_hint = xy ]; then │
│ search --no-floppy --fs-uuid --set=root --hint='hd0,msdos2' 2123\│
│2e00-2bb5-4a1e-a621-1f7c078c0311 │
│ else │
│ search --no-floppy --fs-uuid --set=root 21232e00-2bb5-4a1e-a621-1\│
│f7c078c0311 │
│ fi │
│ echo 'Loading Linux 4.12.9-1-default ...' │
│ linux /boot/Image-4.12.9-1-default root=UUID=21232e00-2bb5-4\│
│a1e-a621-1f7c078c0311 root=/dev/disk/by-id/mmc-SL16G_0xe20a4af7-part2 disk\│
│=/dev/disk/by-id/mmc-SL16G_0xe20a4af7 resume=/dev/disk/by-id/mmc-SL16G_0xe2\│
│0a4af7-part3 quiet splash=silent plymouth.enable=0 swiotlb=512,force cma=3\│
│84M console=ttyS0,115200n8 console=tty quiet │
│ echo 'Loading initial ramdisk ...' │
│ initrd /boot/initrd-4.12.9-1-default │
│ │
└────────────────────────────────────────────────────────────────────────────┘
GNU GRUB version 2.02
┌────────────────────────────────────────────────────────────────────────────┐
│setparams 'openSUSE Tumbleweed' │
│ │
│ load_video │
│ set gfxpayload=keep │
│ insmod gzio │
│ insmod part_msdos │
│ insmod ext2 │
│ set root='hd0,msdos2' │
│ if [ x$feature_platform_search_hint = xy ]; then │
│ search --no-floppy --fs-uuid --set=root --hint='hd0,msdos2' e092\│
│4c25-d514-4c7f-b2b4-a9012d053dfd │
│ else │
│ search --no-floppy --fs-uuid --set=root e0924c25-d514-4c7f-b2b4-a\│
│9012d053dfd │
│ fi │
│ echo 'Loading Linux 4.12.9-1-default ...' │
│ linux /boot/Image-4.12.9-1-default root=UUID=e0924c25-d514-4\│
│c7f-b2b4-a9012d053dfd root=/dev/disk/by-id/mmc-SL64G_0xb84542e3-part2 disk\│
│=/dev/disk/by-id/mmc-SL64G_0xb84542e3 resume=swap quiet splash=silent plymo\│
│uth.enable=0 swiotlb=512,force cma=384M console=tty0 console=ttyS0,115200n\│
│8 quiet │
│ echo 'Loading initial ramdisk ...' │
│ initrd /boot/initrd-4.12.9-1-default │
│ │
└────────────────────────────────────────────────────────────────────────────┘
Booting `openSUSE Tumbleweed'
Loading Linux 4.12.9-1-default ...
Loading initial ramdisk ...
EFI stub: ERROR: Could not determine UEFI Secure Boot status.
[ 2.099090] kvm [1]: Invalid trigger for IRQ4, assuming level low
[ TIME ] Timed out waiting for device swap.device.
[DEPEND] Dependency failed for Resume from hibernation using device /swap.
[ OK ] Reached target Local File Systems (Pre).
Starting File System Check on /dev/…y-id/mmc-SL64G_0xb84542e3-part2...
[ OK ] Reached target Local File Systems.
[ OK ] Reached target System Initialization.
[ OK ] Reached target Basic System.
[ OK ] Started File System Check on /dev/d…/by-id/mmc-SL64G_0xb84542e3-part2.
Mounting /sysroot...
[ OK ] Mounted /sysroot.
[ OK ] Reached target Initrd Root File System.
Starting Reload Configuration from the Real Root...
[ OK ] Started Reload Configuration from the Real Root.
[ OK ] Reached target Initrd File Systems.
[ OK ] Reached target Initrd Default Target.
Starting Cleaning Up and Shutting Down Daemons...
[ OK ] Stopped target Remote File Systems.
[ OK ] Stopped target Remote File Systems (Pre).
[ OK ] Stopped target Initrd Default Target.
[ OK ] Stopped target Initrd Root Device.
[ OK ] Stopped target Basic System.
[ OK ] Stopped target Sockets.
[ OK ] Stopped target Slices.
[ OK ] Stopped target Paths.
[ OK ] Stopped target System Initialization.
[ OK ] Stopped target Swap.
[ OK ] Stopped target Local File Systems.
[ OK ] Stopped target Local File Systems (Pre).
Stopping udev Kernel Device Manager...
[ OK ] Stopped udev Coldplug all Devices.
[ OK ] Stopped Apply Kernel Variables.
[ OK ] Stopped Load Kernel Modules.
[ OK ] Stopped target Timers.
[ OK ] Stopped dracut cmdline hook.
[ OK ] Stopped dracut ask for additional cmdline parameters.
[ OK ] Stopped Dispatch Password Requests to Console Directory Watch.
[ OK ] Stopped udev Kernel Device Manager.
[ OK ] Stopped Create Static Device Nodes in /dev.
[ OK ] Stopped Create list of required sta…vice nodes for the current kernel.
[ OK ] Closed udev Kernel Socket.
[ OK ] Closed udev Control Socket.
Starting Cleanup udevd DB...
[ OK ] Started Cleaning Up and Shutting Down Daemons.
[ OK ] Started Cleanup udevd DB.
[ OK ] Reached target Switch Root.
Starting Switch Root...
[ 95.944306] systemd-fstab-generator[280]: Failed to create mount unit file /run/systemd/generator/-.mount, as it already exists. Duplicate entry in /etc/fstab?
[ 95.959156] systemd-fstab-generator[280]: Failed to create mount unit file /run/systemd/generator/boot-efi.mount, as it already exists. Duplicate entry in /etc/fstab?
[ 95.974560] systemd-fstab-generator[280]: Failed to create mount unit file /run/systemd/generator/-.mount, as it already exists. Duplicate entry in /etc/fstab?
[ 95.989357] systemd-fstab-generator[280]: Failed to create mount unit file /run/systemd/generator/boot-efi.mount, as it already exists. Duplicate entry in /etc/fstab?
[ 96.004887] systemd-fstab-generator[280]: Failed to create mount unit file /run/systemd/generator/-.mount, as it already exists. Duplicate entry in /etc/fstab?
[ 96.019838] systemd-fstab-generator[280]: Failed to create mount unit file /run/systemd/generator/boot-efi.mount, as it already exists. Duplicate entry in /etc/fstab?
[ OK ] Started wicked managed network interfaces.
[ OK ] Reached target Network.
Starting OpenSSH Daemon...
Starting SuSEfirewall2 phase 2...
Starting NTP Server Daemon...
Starting Permit User Sessions...
[ OK ] Started OpenSSH Daemon.
[ OK ] Started Permit User Sessions.
[ OK ] Started Serial Getty on ttyS0.
[ OK ] Started Getty on tty1.
[ OK ] Reached target Login Prompts.
[ OK ] Started /etc/init.d/after.local Compatibility.
[ OK ] Started NTP Server Daemon.
[ OK ] Reached target System Time Synchronized.
Starting Postfix Mail Transport Agent...
[ OK ] Started Discard unused blocks once a week.
[ OK ] Started Daily rotation of log files.
[ OK ] Reached target Timers.
[ OK ] Started SuSEfirewall2 phase 2.
[ OK ] Started Postfix Mail Transport Agent.
[ OK ] Started Command Scheduler.
[ OK ] Reached target Multi-User System.
[ OK ] Reached target Graphical Interface.
Starting Update UTMP about System Runlevel Changes...
[ OK ] Started Update UTMP about System Runlevel Changes.
Welcome to openSUSE Tumbleweed 20170831 - Kernel 4.12.9-1-default (ttyS0).
eth0: 192.168.71.68 fe80::ba27:ebff:fe40:5e62
kwak login: jeroenp
Password:
No mail.
Last login: Mon Feb 5 10:18:40 on ttyS0
Have a lot of fun...
jeroenp@kwak:~> cat /etc/os-release
NAME="openSUSE Tumbleweed"
# VERSION="20170831"
ID=opensuse
ID_LIKE="suse"
VERSION_ID="20170831"
PRETTY_NAME="openSUSE Tumbleweed"
ANSI_COLOR="0;32"
CPE_NAME="cpe:/o:opensuse:tumbleweed:20170831"
BUG_REPORT_URL="https://bugs.opensuse.org"
HOME_URL="https://www.opensuse.org/"
GNU GRUB version 2.02
┌────────────────────────────────────────────────────────────────────────────┐
│setparams 'openSUSE Tumbleweed' │
│ │
│ load_video │
│ set gfxpayload=keep │
│ insmod gzio │
│ insmod part_msdos │
│ insmod ext2 │
│ set root='hd0,msdos2' │
│ if [ x$feature_platform_search_hint = xy ]; then │
│ search --no-floppy --fs-uuid --set=root --hint='hd0,msdos2' e092\│
│4c25-d514-4c7f-b2b4-a9012d053dfd │
│ else │
│ search --no-floppy --fs-uuid --set=root e0924c25-d514-4c7f-b2b4-a\│
│9012d053dfd │
│ fi │
│ echo 'Loading Linux 4.12.9-1-default ...' │
│ linux /boot/Image-4.12.9-1-default root=UUID=e0924c25-d514-4\│
│c7f-b2b4-a9012d053dfd root=/dev/disk/by-id/mmc-SL64G_0xfa1d1e16-part2 disk\│
│=/dev/disk/by-id/mmc-SL64G_0xfa1d1e16 resume=/dev/disk/by-id/mmc-SL64G_0xfa\│
│1d1e16-part3 quiet splash=silent plymouth.enable=0 swiotlb=512,force cma=3\│
│84M console=tty0 console=ttyS0,115200n8 quiet │
│ echo 'Loading initial ramdisk ...' │
│ initrd /boot/initrd-4.12.9-1-default │
│ │
└────────────────────────────────────────────────────────────────────────────┘
Booting `openSUSE Tumbleweed'
Loading Linux 4.12.9-1-default ...
Loading initial ramdisk ...
EFI stub: ERROR: Could not determine UEFI Secure Boot status.
[ 2.098370] kvm [1]: Invalid trigger for IRQ4, assuming level low
[ OK ] Started wicked managed network interfaces.
[ OK ] Reached target Network.
Starting Permit User Sessions...
Starting OpenSSH Daemon...
Starting SuSEfirewall2 phase 2...
Starting NTP Server Daemon...
[ OK ] Started Permit User Sessions.
[ OK ] Started OpenSSH Daemon.
[ OK ] Started Getty on tty1.
[ OK ] Started Serial Getty on ttyS0.
[ OK ] Reached target Login Prompts.
[ OK ] Started /etc/init.d/after.local Compatibility.
[ OK ] Started NTP Server Daemon.
[ OK ] Reached target System Time Synchronized.
[ OK ] Started Daily rotation of log files.
Starting Postfix Mail Transport Agent...
[ OK ] Started Discard unused blocks once a week.
[ OK ] Reached target Timers.
[ OK ] Started SuSEfirewall2 phase 2.
[ OK ] Started Postfix Mail Transport Agent.
[ OK ] Started Command Scheduler.
[ OK ] Reached target Multi-User System.
[ OK ] Reached target Graphical Interface.
Starting Update UTMP about System Runlevel Changes...
[ OK ] Started Update UTMP about System Runlevel Changes.
Welcome to openSUSE Tumbleweed 20170831 - Kernel 4.12.9-1-default (ttyS0).
eth0: 192.168.71.67 fe80::ba27:ebff:fe0f:2e8a
kwek login: jeroenp
Password:
No mail.
Last login: Mon Feb 5 10:42:26 on ttyS0
Have a lot of fun...
jeroenp@kwek:~> cat /etc/os-release
NAME="openSUSE Tumbleweed"
# VERSION="20170831"
ID=opensuse
ID_LIKE="suse"
VERSION_ID="20170831"
PRETTY_NAME="openSUSE Tumbleweed"
ANSI_COLOR="0;32"
CPE_NAME="cpe:/o:opensuse:tumbleweed:20170831"
BUG_REPORT_URL="https://bugs.opensuse.org"
HOME_URL="https://www.opensuse.org/"
GNU GRUB version 2.02
┌────────────────────────────────────────────────────────────────────────────┐
│setparams 'openSUSE Tumbleweed' │
│ │
│ load_video │
│ set gfxpayload=keep │
│ insmod gzio │
│ insmod part_msdos │
│ insmod ext2 │
│ set root='hd0,msdos2' │
│ if [ x$feature_platform_search_hint = xy ]; then │
│ search --no-floppy --fs-uuid --set=root --hint='hd0,msdos2' e092\│
│4c25-d514-4c7f-b2b4-a9012d053dfd │
│ else │
│ search --no-floppy --fs-uuid --set=root e0924c25-d514-4c7f-b2b4-a\│
│9012d053dfd │
│ fi │
│ echo 'Loading Linux 4.13.2-1-default ...' │
│ linux /boot/Image-4.13.2-1-default root=UUID=e0924c25-d514-4\│
│c7f-b2b4-a9012d053dfd root=/dev/disk/by-id/mmc-SL64G_0x232e0d17-part2 disk\│
│=/dev/disk/by-id/mmc-SL64G_0x232e0d17 resume=/dev/disk/by-id/mmc-SL64G_0x23\│
│2e0d17-part3 quiet splash=silent plymouth.enable=0 swiotlb=512,force cma=3\│
│84M console=tty0 console=ttyS0,115200n8 quiet │
│ echo 'Loading initial ramdisk ...' │
│ initrd /boot/initrd-4.13.2-1-default │
│ │
└────────────────────────────────────────────────────────────────────────────┘
Booting a command list
Loading Linux 4.13.2-1-default ...
Loading initial ramdisk ...
EFI stub: ERROR: Could not determine UEFI Secure Boot status.
[ 4.250504] kvm [1]: Invalid trigger for IRQ4, assuming level low
[ 5.588740] OF: /soc/usb@7e980000: could not get #phy-cells for /phy
[ TIME ] Timed out waiting for device dev-di…dSL64G_0x232e0d17\x2dpart3.device.
[DEPEND] Dependency failed for Resume from h…/by-id/mmc-SL64G_0x232e0d17-part3.
[ OK ] Reached target Local File Systems (Pre).
[ OK ] Reached target Local File Systems.
[ OK ] Reached target System Initialization.
[ OK ] Reached target Basic System.
[ **] A start job is running for dev-disk…part2.device (1min 39s / no limit)
@jpluimers
Copy link
Author

jpluimers commented Feb 5, 2018

IRC chat:

[1:36pm] <wiert>
Lets try #4
[1:40pm] <wiert>
USB to serial fails. Need a reboot. Be back in a few minutes. I’ve kept the log in the gist.
[1:53pm] <wiert>
Back. With some Earl Grey tea as well.
[1:53pm] <afaerber>
wiert, agraf: not having read the full scrollback, ~4.14 changed the name of the MMC module to bcm2835 I think
[1:53pm] <afaerber>
so your /etc/dracut.conf.d/raspberrypi_modules.conf may need a tweak
[1:56pm] <wiert>
#4 is doing a whole bunch of these:
[1:56pm] <wiert>
[    *] (2 of 2) A start job is running for…7\x2dpart2.device (25s / no limit)
[1:56pm] <wiert>
[**    ] (2 of 2) A start job is running for…7\x2dpart2.device (49s / no limit)
[1:56pm]
matthias_bgg left the chat room. (Ping timeout: 240 seconds)
[1:57pm] <wiert>
[ ***  ] (1 of 2) A start job is running for…dpart3.device (1min 5s / 1min 30s)
[1:57pm] <wiert>
sometimes with no limit (2 of 2) or a 90 second limit (1 of 2). Is that partisions being checked?
[1:58pm] <wiert>
[ TIME ] Timed out waiting for device dev-di…dSL64G_0x232e0d17\x2dpart3.device.
[1:58pm] <wiert>
[DEPEND] Dependency failed for Resume from h…/by-id/mmc-SL64G_0x232e0d17-part3.
[1:58pm] <wiert>
[  OK  ] Reached target Local File Systems (Pre).
[1:58pm] <wiert>
[  OK  ] Reached target Local File Systems.
[1:58pm] <wiert>
[  OK  ] Reached target System Initialization.
[1:58pm] <wiert>
[  OK  ] Reached target Basic System.
[1:58pm] <wiert>
[    *] A start job is running for dev-disk…part2.device (2min 22s / no limit)
[1:58pm] <wiert>
That’s what it eventually arrives at: a “no limit” thing.
[1:59pm] <wiert>
That’s on a SanDisk 64Gb card that is even newer and has hardly been used.
[2:03pm] <wiert>
I’ve put the full log at the end of the gist comments: https://gist.github.com/jpluimers/9520fca6d643341dddf002067e772276#gistcomment-2341836

@jpluimers
Copy link
Author

Boot log of #4:

               Booting a command list

Loading Linux 4.13.2-1-default ...
Loading initial ramdisk ...
EFI stub: Booting Linux Kernel...
EFI stub: EFI_RNG_PROTOCOL unavailable, no randomness supplied
EFI stub: ERROR: Could not determine UEFI Secure Boot status.
EFI stub: Using DTB from configuration table
EFI stub: Exiting boot services and installing virtual address map...
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] random: get_random_bytes called from start_kernel+0x38/0x3d4 with crng_init=0
[    0.000000] Linux version 4.13.2-1-default (geeko@buildhost) (gcc version 7.2.1 20170901 [gcc-7-branch revision 251580] (SUSE Linux)) #1 SMP Thu Sep 14 18:42:01 UTC 2017 (68f4aee)
[    0.000000] Boot CPU: AArch64 Processor [410fd034]
[    0.000000] Machine model: Raspberry Pi 3 Model B
[    0.000000] efi: Getting EFI parameters from FDT:
[    0.000000] efi: EFI v2.05 by Das U-boot
[    0.000000] efi:  SMBIOS=0x36b2e000
[    0.000000] cma: Reserved 384 MiB at 0x000000000d800000
[    0.000000] NUMA: No NUMA configuration found
[    0.000000] NUMA: Faking a node at [mem 0x0000000000000000-0x0000000037ffffff]
[    0.000000] NUMA: NODE_DATA [mem 0x37fd7680-0x37fd917f]
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000000000-0x0000000037ffffff]
[    0.000000]   Normal   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x0000000000000fff]
[    0.000000]   node   0: [mem 0x0000000000001000-0x0000000036b2dfff]
[    0.000000]   node   0: [mem 0x0000000036b2e000-0x0000000036b2efff]
[    0.000000]   node   0: [mem 0x0000000036b2f000-0x0000000037faafff]
[    0.000000]   node   0: [mem 0x0000000037fab000-0x0000000037fabfff]
[    0.000000]   node   0: [mem 0x0000000037fac000-0x0000000037ffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x0000000037ffffff]
[    0.000000] percpu: Embedded 28 pages/cpu @ffff800037f3b000 s74776 r8192 d31720 u114688
[    0.000000] Detected VIPT I-cache on CPU0
[    0.000000] CPU features: enabling workaround for ARM erratum 845719
[    0.000000] Built 1 zonelists in Node order, mobility grouping on.  Total pages: 225792
[    0.000000] Policy zone: DMA
[    0.000000] Kernel command line: BOOT_IMAGE=/boot/Image-4.13.2-1-default root=UUID=e0924c25-d514-4c7f-b2b4-a9012d053dfd root=/dev/disk/by-id/mmc-SL64G_0x232e0d17-part2 disk=/dev/disk/by-id/mmc-SL64G_0x232e0d
17 resume=/dev/disk/by-id/mmc-SL64G_0x232e0d17-part3 splash=silent plymouth.enable=0 swiotlb=512,force cma=384M console=tty0 console=ttyS0,115200n8
[    0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[    0.000000] software IO TLB [mem 0x37e3b000-0x37f3b000] (1MB) mapped at [ffff800037e3b000-ffff800037f3afff]
[    0.000000] software IO TLB [mem 0x37e3b000-0x37f3b000] (1MB) mapped at [ffff800037e3b000-ffff800037f3afff]
[    0.000000] Memory: 474248K/917504K available (8828K kernel code, 1564K rwdata, 6388K rodata, 4864K init, 1035K bss, 50040K reserved, 393216K cma-reserved)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     modules : 0xffff000000000000 - 0xffff000008000000   (   128 MB)
[    0.000000]     vmalloc : 0xffff000008000000 - 0xffff7dffbfff0000   (129022 GB)
[    0.000000]       .text : 0xffff000008080000 - 0xffff000008920000   (  8832 KB)
[    0.000000]     .rodata : 0xffff000008920000 - 0xffff000008f60000   (  6400 KB)
[    0.000000]       .init : 0xffff000008f60000 - 0xffff000009420000   (  4864 KB)
[    0.000000]       .data : 0xffff000009420000 - 0xffff0000095a7200   (  1565 KB)
[    0.000000]        .bss : 0xffff0000095a7200 - 0xffff0000096a9e40   (  1036 KB)
[    0.000000]     fixed   : 0xffff7dfffe7fd000 - 0xffff7dfffec00000   (  4108 KB)
[    0.000000]     PCI I/O : 0xffff7dfffee00000 - 0xffff7dffffe00000   (    16 MB)
[    0.000000]     vmemmap : 0xffff7e0000000000 - 0xffff800000000000   (  2048 GB maximum)
[    0.000000]               0xffff7e0000000000 - 0xffff7e0000e00000   (    14 MB actual)
[    0.000000]     memory  : 0xffff800000000000 - 0xffff800038000000   (   896 MB)
[    0.000000] ftrace: allocating 33409 entries in 131 pages
[    0.000000] Hierarchical RCU implementation.
[    0.000000]  RCU restricting CPUs from NR_CPUS=128 to nr_cpu_ids=4.
[    0.000000]  Tasks RCU enabled.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] arch_timer: WARNING: Invalid trigger for IRQ2, assuming level low
[    0.000000] arch_timer: WARNING: Please fix your firmware
[    0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
[    0.000006] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
[    0.000608] Console: colour dummy device 80x25
[    0.001928] console [tty0] enabled
[    0.002028] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
[    0.002081] pid_max: default: 32768 minimum: 301
[    0.002320] Security Framework initialized
[    0.002444] AppArmor: AppArmor initialized
[    0.003905] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes)
[    0.004588] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes)  
[    0.004666] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes)
[    0.004718] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes)
[    0.006392] ASID allocator initialised with 65536 entries
[    0.006521] Hierarchical SRCU implementation.
[    0.008969] Remapping and enabling EFI services.
[    0.009042]   EFI remap 0x0000000036b2e000 => 000000002000e000
[    0.009072]   EFI remap 0x0000000037fab000 => 000000002001b000
[    0.009100]   EFI remap 0x000000003f100000 => 0000000020020000
[    0.009508] smp: Bringing up secondary CPUs ...
[    0.010274] Detected VIPT I-cache on CPU1
[    0.010333] arch_timer: WARNING: Invalid trigger for IRQ2, assuming level low
[    0.010336] arch_timer: WARNING: Please fix your firmware
[    0.010351] CPU1: Booted secondary processor [410fd034]
[    0.011109] Detected VIPT I-cache on CPU2
[    0.011148] arch_timer: WARNING: Invalid trigger for IRQ2, assuming level low
[    0.011151] arch_timer: WARNING: Please fix your firmware
[    0.011161] CPU2: Booted secondary processor [410fd034]
[    0.011836] Detected VIPT I-cache on CPU3
[    0.011868] arch_timer: WARNING: Invalid trigger for IRQ2, assuming level low
[    0.011871] arch_timer: WARNING: Please fix your firmware
[    0.011881] CPU3: Booted secondary processor [410fd034]
[    0.011996] smp: Brought up 1 node, 4 CPUs
[    0.012275] SMP: Total of 4 processors activated.
[    0.012303] CPU features: detected feature: 32-bit EL0 Support
[    0.012504] CPU: All CPU(s) started at EL2
[    0.012550] alternatives: patching kernel code
[    0.013589] devtmpfs: initialized
[    0.021074] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.021186] futex hash table entries: 1024 (order: 5, 131072 bytes)
[    0.040478] pinctrl core: initialized pinctrl subsystem
[    0.041821] random: fast init done
[    0.041853] SMBIOS 3.0 present.
[    0.041890] DMI: raspberrypi rpi/rpi, BIOS 2017.09 09/13/2017
[    0.042392] NET: Registered protocol family 16
[    0.046322] cpuidle: using governor ladder
[    0.046489] cpuidle: using governor menu
[    0.047447] vdso: 2 pages (1 code @ ffff000008926000, 1 data @ ffff000009425000)
[    0.047505] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.   
[    0.049409] DMA: preallocated 256 KiB pool for atomic allocations
[    0.050212] Serial: AMBA PL011 UART driver
[    0.067474] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.068721] ACPI: Interpreter disabled.
[    0.069331] vgaarb: loaded
[    0.069861] SCSI subsystem initialized
[    0.070503] EDAC MC: Ver: 3.0.0
[    0.070996] Registered efivars operations
[    0.071034] efivars: get_next_variable: status=8000000000000007
[    0.072301] NetLabel: Initializing
[    0.072326] NetLabel:  domain hash size = 128
[    0.072349] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
[    0.072459] NetLabel:  unlabeled traffic allowed by default
[    0.072957] clocksource: Switched to clocksource arch_sys_counter
[    0.158946] VFS: Disk quotas dquot_6.6.0
[    0.159084] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)  
[    0.159775] AppArmor: AppArmor Filesystem Enabled
[    0.160004] pnp: PnP ACPI: disabled
[    0.170543] NET: Registered protocol family 2
[    0.171312] TCP established hash table entries: 8192 (order: 4, 65536 bytes)
[    0.171489] TCP bind hash table entries: 8192 (order: 5, 131072 bytes)
[    0.171716] TCP: Hash tables configured (established 8192 bind 8192)
[    0.171975] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.172056] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.172350] NET: Registered protocol family 1
[    0.172711] Unpacking initramfs...
[    4.269651] Freeing initrd memory: 7156K
[    4.270609] kvm [1]: 8-bit VMID
[    4.270641] kvm [1]: IDMAP page: 910000
[    4.270666] kvm [1]: HYP VA range: 800000000000:ffffffffffff
[    4.272334] kvm [1]: Hyp mode initialized successfully
[    4.272391] kvm [1]: Invalid trigger for IRQ4, assuming level low
[    4.272440] kvm [1]: virtual timer IRQ4
[    4.274247] audit: initializing netlink subsys (disabled)
[    4.274504] audit: type=2000 audit(4.271:1): state=initialized audit_enabled=0 res=1
[    4.275136] workingset: timestamp_bits=44 max_order=18 bucket_order=0
[    4.275396] zbud: loaded
[    4.281649] Key type asymmetric registered
[    4.281691] Asymmetric key parser 'x509' registered
[    4.281849] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[    4.282049] io scheduler noop registered
[    4.282076] io scheduler deadline registered
[    4.282155] io scheduler cfq registered (default)
[    4.282182] io scheduler kyber registered
[    4.282243] io scheduler bfq registered
[    4.290162] efifb: probing for efifb
[    4.290162] efifb: probing for efifb
[    4.290355] efifb: framebuffer at 0x3e513000, using 7012k, total 7011k
[    4.290386] efifb: mode is 1824x984x32, linelength=7296, pages=1
[    4.290411] efifb: scrolling: redraw
[    4.290437] efifb: Truecolor: size=8:8:8:8, shift=24:0:8:16
[    4.333756] Console: switching to colour frame buffer device 228x61
[    4.376463] fb0: EFI VGA frame buffer device
[    4.382855] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    4.385450] console [ttyS0] disabled
[    4.385644] 3f215040.serial: ttyS0 at MMIO 0x0 (irq = 61, base_baud = 31250000) is a 16550
[    5.307876] console [ttyS0] enabled
[    5.312505] Serial: AMBA driver
[    5.316315] msm_serial: driver initialized
[    5.321522] cacheinfo: Unable to detect cache hierarchy for CPU 0
[    5.328783] libphy: Fixed MDIO Bus: probed
[    5.333658] mousedev: PS/2 mouse device common for all mice
[    5.341352] Machine is not compatible with mt8173-cpufreq
[    5.347296] ledtrig-cpu: registered to indicate activity on CPUs
[    5.353949] EFI Variables Facility v0.08 2004-May-17
[    5.359193] efivars: get_next_variable: status=8000000000000007
[    5.365523] hidraw: raw HID events driver (C) Jiri Kosina
[    5.371488] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
[    5.378434] NET: Registered protocol family 10
[    5.385037] modprobe[93]: undefined instruction: pc=0000ffff97736ff4
[    5.391752] Code: d503201f 8a180320 92750001 365ffc20 (d5380001)
[    5.398978] modprobe[94]: undefined instruction: pc=0000ffff9abaaff4
[    5.405702] Code: d503201f 8a180320 92750001 365ffc20 (d5380001)
[    5.413144] modprobe[97]: undefined instruction: pc=0000ffffa00e4ff4
[    5.419849] Code: d503201f 8a180320 92750001 365ffc20 (d5380001)
[    5.426912] modprobe[98]: undefined instruction: pc=0000ffffb72e4ff4
[    5.433631] Code: d503201f 8a180320 92750001 365ffc20 (d5380001)
[    5.440443] Segment Routing with IPv6
[    5.444711] Registered cp15_barrier emulation handler
[    5.450034] Registered setend emulation handler
[    5.455586] registered taskstats version 1
[    5.460023] zswap: loaded using pool lzo/zbud
[    5.477571] Key type big_key registered
[    5.493236] Key type encrypted registered
[    5.497500] AppArmor: AppArmor sha1 policy hashing enabled
[    5.507246] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 72, base_baud = 0) is a PL011 rev2
[    5.518375] raspberrypi-firmware soc:firmware: Attached to firmware from 2017-08-22 14:16
[    5.527578] hctosys: unable to open rtc device (rtc0)
[    5.563668] Freeing unused kernel memory: 4864K
[    5.602274] systemd[1]: System time before build time, advancing clock.
[    5.631003] efivars: get_next_variable: status=8000000000000007
[    5.654946] systemd[1]: systemd 234 running in system mode. (+PAM -AUDIT +SELINUX -IMA +APPARMOR -SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID -ELFUTILS +KMOD -IDN2 -IDN default-hierarchy=hybrid)
[    5.699629] systemd[1]: Detected architecture arm64.
[    5.716161] systemd[1]: Running in initial RAM disk.

Welcome to openSUSE Tumbleweed dracut-044-5.1 (Initramfs)!

[    5.763099] systemd[1]: Set hostname to <kwik>.
[    6.063272] systemd[1]: Listening on udev Kernel Socket.
[  OK  ] Listening on udev Kernel Socket.
[    6.113525] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[  OK  ] Started Dispatch Password Requests to Console Directory Watch.
[    6.163099] systemd[1]: Reached target Paths.
[  OK  ] Reached target Paths.
[    6.203363] systemd[1]: Listening on Journal Socket.
[  OK  ] Listening on Journal Socket.
[    6.253486] systemd[1]: Listening on Journal Audit Socket.
[  OK  ] Listening on Journal Audit Socket.
[    6.303299] systemd[1]: Listening on udev Control Socket.
[  OK  ] Listening on udev Control Socket.
[  OK  ] Reached target Timers.
[  OK  ] Reached target Swap.
[  OK  ] Created slice System Slice.
         Starting Create list of required st…ce nodes for the current kernel...
[  OK  ] Created slice system-systemd\x2dhibernate\x2dresume.slice.
         Starting Load Kernel Modules...
[  OK  ] Reached target Slices.
         Starting dracut ask for additional cmdline parameters...
[  OK  ] Listening on Journal Socket (/dev/log).
         Starting Journal Service...
[  OK  ] Reached target Sockets.
[  OK  ] Started Create list of required sta…vice nodes for the current kernel.
[  OK  ] Started Load Kernel Modules.
         Starting Apply Kernel Variables...
         Starting Create Static Device Nodes in /dev...
[  OK  ] Started Apply Kernel Variables.
[  OK  ] Started Create Static Device Nodes in /dev.
[  OK  ] Started Journal Service.
[  OK  ] Started dracut ask for additional cmdline parameters.
         Starting dracut cmdline hook...
[  OK  ] Started dracut cmdline hook.
         Starting udev Kernel Device Manager...
[  OK  ] Started udev Kernel Device Manager.
         Starting udev Coldplug all Devices...
         Mounting Kernel Configuration File System...
[  OK  ] Mounted Kernel Configuration File System.
[  OK  ] Started udev Coldplug all Devices.
[    7.548506] sdhci: Secure Digital Host Controller Interface driver
[    7.566273] sdhci: Copyright(c) Pierre Ossman
[    7.573653] usbcore: registered new interface driver usbfs
[    7.573720] usbcore: registered new interface driver hub
[    7.573839] usbcore: registered new device driver usb
[    7.597542] OF: /soc/usb@7e980000: could not get #phy-cells for /phy
[    7.597598] dwc2 3f980000.usb: 3f980000.usb supply vusb_d not found, using dummy regulator
[    7.597676] dwc2 3f980000.usb: 3f980000.usb supply vusb_a not found, using dummy regulator
[  OK      7.690534] dwc2 3f980000.usb: DWC OTG Controller
0m] Reached target Remote File Systems (Pre).
[    7.691293] sdhci-pltfm: SDHCI platform and OF driver helper
[    7.724399] dwc2 3f980000.usb: new USB bus registered, assigned bus number 1
[  OK  ] Reached target Remote File Systems.
[    7.724478] dwc2 3f980000.usb: irq 41, io mem 0x3f980000
[    7.724818] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002   
[    7.724827] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    7.724835] usb usb1: Product: DWC OTG Controller
[    7.724842] usb usb1: Manufacturer: Linux 4.13.2-1-default dwc2_hsotg
[    7.724848] usb usb1: SerialNumber: 3f980000.usb
[    7.725665] hub 1-0:1.0: USB hub found
[    7.725711] hub 1-0:1.0: 1 port detected
[    7.812984] mmc0: SDHCI controller on 3f300000.sdhci [3f300000.sdhci] using PIO
[    8.152987] usb 1-1: new high-speed USB device number 2 using dwc2
[    8.403182] usb 1-1: New USB device found, idVendor=0424, idProduct=9514
[    8.421121] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    8.440185] hub 1-1:1.0: USB hub found
[    8.455258] hub 1-1:1.0: 5 ports detected
[    8.782980] usb 1-1.1: new high-speed USB device number 3 using dwc2
[    8.923332] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00  
[    8.941316] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[   10.260798] random: crng init done
[ TIME ] Timed out waiting for device dev-di…dSL64G_0x232e0d17\x2dpart3.device.
[DEPEND] Dependency failed for Resume from h…/by-id/mmc-SL64G_0x232e0d17-part3.
[  OK  ] Reached target Local File Systems (Pre).
[  OK  ] Reached target Local File Systems.
[  OK  ] Reached target System Initialization.
[  OK  ] Reached target Basic System.
[ ***  ] A start job is running for dev-disk…part2.device (4min 58s / no limit)

@jpluimers
Copy link
Author

Old kernels are in the "Advanced" mode:

│ openSUSE Tumbleweed                                                        │ 
│*Advanced options for openSUSE Tumbleweed                                   │
                            GNU GRUB  version 2.02

┌────────────────────────────────────────────────────────────────────────────┐
│*openSUSE Tumbleweed, with Linux 4.13.2-1-default                           │ 
│ openSUSE Tumbleweed, with Linux 4.13.2-1-default (recovery mode)           │
│ openSUSE Tumbleweed, with Linux 4.12.9-1-default                           │
│ openSUSE Tumbleweed, with Linux 4.12.9-1-default (recovery mode)           │
│ openSUSE Tumbleweed, with Linux 4.12.8-1-default                           │
│ openSUSE Tumbleweed, with Linux 4.12.8-1-default (recovery mode)           │
│                                                                            │
│                                                                            │
│                                                                            │
│                                                                            │
│                                                                            │
│                                                                            │ 
└────────────────────────────────────────────────────────────────────────────┘

     Use the ▲ and ▼ keys to select which entry is highlighted.          
      Press enter to boot the selected OS, `e' to edit the commands     
      before booting or `c' for a command-line. ESC to return previous  
      menu.                                                             

@jpluimers
Copy link
Author

4.12.9-1-default fails too:

Loading Linux 4.12.9-1-default ...
Loading initial ramdisk ...
EFI stub: ERROR: Could not determine UEFI Secure Boot status.
[    4.147271] kvm [1]: Invalid trigger for IRQ4, assuming level low
[    5.638515] OF: /soc/usb@7e980000: could not get #phy-cells for /phy
[ TIME ] Timed out waiting for device dev-di…dSL64G_0x232e0d17\x2dpart3.device.
[DEPEND] Dependency failed for Resume from h…/by-id/mmc-SL64G_0x232e0d17-part3.
[  OK  ] Reached target Local File Systems (Pre).
[  OK  ] Reached target Local File Systems.
[  OK  ] Reached target System Initialization.
[  OK  ] Reached target Basic System.
[***   ] A start job is running for dev-disk…part2.device (6min 11s / no limit)

@jpluimers
Copy link
Author

And the oldest kernel also fails:

Loading Linux 4.12.8-1-default ...
Loading initial ramdisk ...
EFI stub: ERROR: Could not determine UEFI Secure Boot status.
[    2.106745] kvm [1]: Invalid trigger for IRQ4, assuming level low
[    2.998833] OF: /soc/usb@7e980000: could not get #phy-cells for /phy
[ TIME ] Timed out waiting for device dev-di…dSL64G_0x232e0d17\x2dpart3.device.
[DEPEND] Dependency failed for Resume from h…/by-id/mmc-SL64G_0x232e0d17-part3.
[  OK  ] Reached target Local File Systems (Pre).
[  OK  ] Reached target Local File Systems.
[  OK  ] Reached target System Initialization.
[  OK  ] Reached target Basic System.
[    **] A start job is running for dev-disk…part2.device (1min 59s / no limit)

@jpluimers
Copy link
Author

Copying /boot/efi/u-boot.bin from #2 to #4 fails as well:

DRAM:  896 MiB
RPI 3 Model B (0xa22082)
MMC:   sdhci@7e300000: 0
reading uboot.env

** Unable to read "uboot.env" from mmc0:1 **
Using default environment

In:    serial
Out:   vidconsole
Err:   vidconsole
Net:   No ethernet found.
starting USB...
USB0:   Core Release: 2.80a
scanning bus 0 for devices... 3 USB Device(s) found
       scanning usb for storage devices... 0 Storage Device(s) found
Hit any key to stop autoboot:  0 
switch to partitions #0, OK
mmc0 is current device
Scanning mmc 0:1...
22637 bytes read in 149 ms (147.5 KiB/s)

USB device 0: unknown device
Waiting for Ethernet connection... unable to connect.
missing environment variable: pxeuuid
missing environment variable: bootfile
Retrieving file: pxelinux.cfg/01-b8-27-eb-24-d3-ee
Waiting for Ethernet connection... unable to connect.
missing environment variable: bootfile
Retrieving file: pxelinux.cfg/00000000
Waiting for Ethernet connection... unable to connect.
missing environment variable: bootfile
Retrieving file: pxelinux.cfg/0000000
Waiting for Ethernet connection... unable to connect.
missing environment variable: bootfile
Retrieving file: pxelinux.cfg/000000
Waiting for Ethernet connection... unable to connect.
missing environment variable: bootfile
Retrieving file: pxelinux.cfg/00000
Waiting for Ethernet connection... unable to connect.
missing environment variable: bootfile
Retrieving file: pxelinux.cfg/0000
Waiting for Ethernet connection... unable to connect.
missing environment variable: bootfile
Retrieving file: pxelinux.cfg/000
Waiting for Ethernet connection... unable to connect.
missing environment variable: bootfile
Retrieving file: pxelinux.cfg/00
Waiting for Ethernet connection... unable to connect.
missing environment variable: bootfile
Retrieving file: pxelinux.cfg/0
Waiting for Ethernet connection... unable to connect.
missing environment variable: bootfile
Retrieving file: pxelinux.cfg/default-arm-bcm283x
Waiting for Ethernet connection... unable to connect.
missing environment variable: bootfile
Retrieving file: pxelinux.cfg/default-arm

@jpluimers
Copy link
Author

U-Boot> ls mmc 0:1
     6694   fixup.dat 
  2868644   start.elf 
   385734   u-boot.bin 
    50248   bootcode.bin 
      938   config.txt 
        9   startup.nsh 
            .spotlight-v100/
            .fseventsd/
      938   config.txt.rpmsave 
   393800   u-boot.bad.bin 

8 file(s), 2 dir(s)

U-Boot> 

@jpluimers
Copy link
Author

[2:04pm] <wiert>
So how do I edit /etc/dracut.conf.d/raspberrypi_modules.conf when it does not boot?
[2:05pm] <afaerber>
as I said, that's because the correct module is not in the initrd - you need to boot using an older kernel, change the config, re-create the new initrd via mkinitrd and reboot with the new kernel
[2:08pm] <wiert>
So dracult.conf.d configures the initrd ?
[2:09pm] <wiert>
How can I find which other kernels are available? Can grub show that?
[2:10pm] <agraf>
there should be an advanced menu entry ,yes
[2:10pm] <agraf>
which lists old versions
[2:13pm] <wiert>
Ah, that’s where the old kernels are.
[2:15pm] <wiert>
Do I need the “recovery” version os the regular one?
[2:16pm] <wiert>
After that: should I continue with modifying the dracult configuration, or just try to zypper dist-upgrade to the most recent build?
[2:21pm] <agraf>
the normal one
[2:22pm] <agraf>
and yes, you will need to modify the dracut config
[2:22pm] <agraf>
that's the downside of our image approach :(
[2:25pm] <wiert>
4.12.9-1-default has the same problem https://gist.github.com/jpluimers/9520fca6d643341dddf002067e772276#gistcomment-2341855
[2:27pm] <wiert>
What’s the meaning of “OF: /soc/usb@7e980000: could not get #phy-cells for /phy” ?
[2:30pm] <wiert>
Oldest kernel fails too. Boy, I’ve trouble (:
[2:32pm] <wiert>
Any more options?
[2:38pm] <agraf>
wiert: try to pull in an older u-boot then from one of the working systems
[2:39pm] <agraf>
wiert: it's in /boot/efi/u-boot.bin
[2:44pm] <wiert>
Let me check that out.
[3:29pm] <wiert>
agraf: managed to do the copY (finally found the USB Micro SD adapter), but that fails too: https://gist.github.com/jpluimers/9520fca6d643341dddf002067e772276#gistcomment-2341909
[3:30pm] <wiert>
Should I’ve copied more than just the /boot/efi/u-boot.bin to the vfat partition?
[3:31pm] <agraf>
wiert: did you overwrite/remove any other files?
[3:32pm] <wiert>
no, just that one
[3:33pm]
adrianS_ left the chat room. (Ping timeout: 240 seconds)
[3:33pm]
adrianS_ joined the chat room.
[3:33pm] <wiert>
mounted the vfat partition to a new directory in /mnt on #2, then rsync -avloz /voot/efi/ /mnt/boot-efi/efi/efi/ then moved the u-boot.bin file one directory down.
[3:35pm] <wiert>
I do have a “U-Boot>” prompt now.
[3:35pm] <wiert>
Anything I can try there?
[3:39pm] <agraf>
just replacing u-boot should've fully preserved the existing boot logic
[3:39pm] <agraf>
on the u-boot prompt, do "ls mmc 0:1"
[3:44pm] <wiert>
That shows files: https://gist.github.com/jpluimers/9520fca6d643341dddf002067e772276#gistcomment-2341928
[3:46pm] <wiert>
Dang: after creating that disk image originally, it looks like Mac OS put some extra stuff there.
[3:53pm] <wiert>
What else could I try?
[4:00pm] <wiert>
What USB serial cables are reliable
[4:00pm] <wiert>
(these hang my screen connection every now and then: https://www.amazon.de/COM-FOUR%C2%AE-PL2303HX-RS232-Kabel-Modul-Konverter/dp/B00WE051XK )
[4:31pm] <wiert>
@afaerber: I’m going to need to assist my brother, so if you’re there: lets continue tomorrow.
[4:31pm] <wiert>
(He’s about half an hour away so I’m gone for at least the next hours)
[4:33pm] <wiert>
and for @afarber as well: thanks for all the help so far; much appreciated, I already learned a lot.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment