Kernel 5.0.0-1 boot issues

This forum is for Marvell Kirkwood devices such as the GoFlex Home/Net, PogoPlug v1/v2, SheevaPlug, and ZyXEL devices.

Kernel 5.0.0-1 boot issues

Postby kriztioan » Sun Mar 10, 2019 5:39 pm

Upgrading my ArchLinux ARM system (Iomega ix2-200) to kernel 5.0.0-1 (linux-kirkwood-dt-5.0.0-1) results in very weird behaviors. Following the boot process via a serial connection shows the uImage and uInitrd being found and loaded. However, the following message appears

$this->bbcode_second_pass_code('', 'RAMDISK: gzip image found at block 0
RAMDISK: incomplete write (xxxx != yyyy)
')
after which there is a kernel panic and init is unable to mount the root filesystem and spews out a list of devices found:

$this->bbcode_second_pass_code('', 'kernel panic-not syncing: VFS: unable to mount root fs on unknown ....')

Now, by changing the kernel command line from using PARTUUID instead of LABEL the root filesystem does mount. However, this only works when booting from a power-on and the RAMDISK write error remains. Doing a simple reboot gives the same kernel panic as before.

When the root filesystem does get mounted and ArchLinux starts, my raid-arrays in /etc/fstab are not automatically mounted. dmesg does show the arrays being assembled and a simple 'mount -a' after boot is completed does mount the arrays.

Reverting back to 4.20.13-1 resolves all issues.

Did perhaps something change how uInitrd needs to be created? I am using the following command:

$this->bbcode_second_pass_code('', 'mkimage -A arm -O linux -T ramdisk -C gzip -a 0x01000000 -n "Arch Linux ARM initrd" -d /boot/initramfs-linux.img /boot/uInitrd > /dev/null')

or is image to large?
kriztioan
 
Posts: 51
Joined: Sat Apr 29, 2017 1:32 am

Re: Kernel 5.0.0-1 boot issues

Postby Sergeanter » Mon Mar 11, 2019 11:42 pm

Kristioan, I used to create ramdisk images at every kernel update for pogoplug but I believe the system began creating zImage on its own quite a while ago (since 2017). Does your device require a non standard boot ?
Sergeanter
 
Posts: 82
Joined: Wed Oct 02, 2013 5:14 am

Re: Kernel 5.0.0-1 boot issues

Postby kriztioan » Tue Mar 12, 2019 6:47 pm

@Sergeanter The system does indeed create a zImage by default, but my aging U-boot unfortunately still needs the legacy formatted uImage. This is image is automatically generated using a hook after pacman updates the linux-kirkwood-dt package. Nonetheless, the kernel (uImage) seems to boot fine, albeit with the weird issue where it will only find the root device when booting from power-on. Perhaps that has to do with the fact I'm booting from USB; I already need to unplug my APC-link (USB) from the NAS in order to have it being able to detect the USB-stick--funky U-boot stuff I suppose ...

I was hoping that yesterday's kernel 5.0.1-1 update would have resolved my issues, but unfortunately it did not.

My suspicion is that the 'RAMDISK: incomplete write'-message might have something to do with my issues. I am also seeing the following two messages:

$this->bbcode_second_pass_code('', 'rootfs image is not initramfs (junk in compressed archive); looks like an initrd
No ATAGs?')

This all seems to point to issues with uInitrd. Doing some searching on the internet seems to suggest that compression of the image might cause some memory to be overwritten during deflation. However, I did generate an uncompressed version of uInitrd and still have the same problems. Possibly the offset between the kernel load address and the ramdisk are not big enough, but my hex insn't good enough to get to the size-difference between load addresses 0x01000000 and 0x00008000 (if I even have that correct ...). Note that the only change I made to mkinitcpio.conf is adding udev_mdadm to HOOKS. For completeness I past the serial-output for a power-on boot below:

$this->bbcode_second_pass_code('', ' _ _ _ _
| \/ | __ _ _ ____ _____| | |
| |\/| |/ _` | '__\ \ / / _ \ | |
| | | | (_| | | \ V / __/ | |
|_| |_|\__,_|_| \_/ \___|_|_|
_ _ ____ _
| | | | | __ ) ___ ___ | |_
| | | |___| _ \ / _ \ / _ \| __|
| |_| |___| |_) | (_) | (_) | |_
\___/ |____/ \___/ \___/ \__|
** MARVELL BOARD: RD-88F6281A LE

U-Boot 1.1.4 (Sep 8 2009 - 09:31:54) Marvell version: 3.4.14

Mapower version: 2.0 (32MB) (2009/09/08)

U-Boot code: 00600000 -> 0067FFF0 BSS: -> 006CEE60

Soc: 88F6281 A0 (DDR2)
CPU running @ 1000Mhz L2 running @ 333Mhz
SysClock = 333Mhz , TClock = 200Mhz

DRAM CAS Latency = 5 tRP = 5 tRAS = 18 tRCD=6
DRAM CS[0] base 0x00000000 size 256MB
DRAM Total size 256MB 16bit width
Flash: 0 kB
Addresses 8M - 0M are saved for the U-Boot usage.
Mem malloc Initialization (8M - 7M): Done
NAND:32 MB

CPU : Marvell Feroceon (Rev 1)

Streaming disabled
Write allocate disabled

Module 0 is RGMII
Module 1 is TDM

USB 0: host mode
PEX 0: interface detected no Link.
Net: egiga0, egiga1 [PRIME]
Fan lookup table initialized.

Current remote temperature: 41
Current fan speed: 0

Hit any key to stop autoboot: 0
(Re)start USB...
USB: scanning bus for devices... 3 USB Device(s) found
scanning bus for storage devices... 1 Storage Device(s) found
.
.
...
......
............
.
........................
.
.........................
........................
.
.........................
.........................
.........................
........................
.
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
....................

5468520 bytes read
.
...
......
............
.
........................
.
.........................
........................
.
.........................
.........................
.........................
........................
.
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.............

7229116 bytes read
## Booting image at 00800000 ...
Image Name: Arch Linux ARM kernel
Created: 2019-03-12 2:40:02 UTC
Image Type: ARM Linux Kernel Image (uncompressed)
Data Size: 5468456 Bytes = 5.2 MB
Load Address: 00008000
Entry Point: 00008000
Verifying Checksum ... OK
OK
## Loading Ramdisk Image at 01000000 ...
Image Name: Arch Linux ARM initrd
Created: 2019-03-12 2:40:03 UTC
Image Type: ARM Linux RAMDisk Image (gzip compressed)
Data Size: 7229052 Bytes = 6.9 MB
Load Address: 01000000
Entry Point: 01000000
Verifying Checksum ... OK

Starting kernel ...

Uncompressing Linux... done, booting the kernel.
[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Linux version 5.0.1-1-ARCH (builduser@leming) (gcc version 8.2.1 20181127 (GCC)) #1 PREEMPT Sun Mar 10 21:09:25 UTC 2019
[ 0.000000] CPU: Feroceon 88FR131 [56251311] revision 1 (ARMv5TE), cr=0005397f
[ 0.000000] CPU: VIVT data cache, VIVT instruction cache
[ 0.000000] OF: fdt: Machine model: Iomega StorCenter ix2-200
[ 0.000000] Memory policy: Data cache writeback
[ 0.000000] random: get_random_bytes called from start_kernel+0x7c/0x4e4 with crng_init=0
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 64960
[ 0.000000] Kernel command line: console=ttyS0,115200 mtdparts=orion_nand:640k(u-boot)ro,16k(u-boot-env),-(iomega-firmware)ro root=PARTUUID=b76d429d-02 ro audit=0
[ 0.000000] audit: disabled (until reboot)
[ 0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
[ 0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
[ 0.000000] Memory: 240660K/262144K available (8000K kernel code, 632K rwdata, 1788K rodata, 360K init, 809K bss, 21484K reserved, 0K cma-reserved)
[ 0.000000] Virtual kernel memory layout:
[ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
[ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 kB)
[ 0.000000] vmalloc : 0xd0800000 - 0xff800000 ( 752 MB)
[ 0.000000] lowmem : 0xc0000000 - 0xd0000000 ( 256 MB)
[ 0.000000] modules : 0xbf000000 - 0xc0000000 ( 16 MB)
[ 0.000000] .text : 0x(ptrval) - 0x(ptrval) (8002 kB)
[ 0.000000] .init : 0x(ptrval) - 0x(ptrval) ( 360 kB)
[ 0.000000] .data : 0x(ptrval) - 0x(ptrval) ( 633 kB)
[ 0.000000] .bss : 0x(ptrval) - 0x(ptrval) ( 810 kB)
[ 0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[ 0.000000] rcu: Preemptible hierarchical RCU implementation.
[ 0.000000] 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] clocksource: orion_clocksource: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 9556302233 ns
[ 0.000011] sched_clock: 32 bits at 200MHz, resolution 5ns, wraps every 10737418237ns
[ 0.000048] Switching to timer-based delay loop, resolution 5ns
[ 0.000299] Console: colour dummy device 80x30
[ 0.000349] Calibrating delay loop (skipped), value calculated using timer frequency.. 400.00 BogoMIPS (lpj=2000000)
[ 0.000373] pid_max: default: 32768 minimum: 301
[ 0.000873] LSM: Security Framework initializing
[ 0.001098] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.001124] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.002505] CPU: Testing write buffer coherency: ok
[ 0.004218] Setting up static identity map for 0x8200 - 0x8258
[ 0.004538] mvebu-soc-id: MVEBU SoC ID=0x6281, Rev=0x2
[ 0.004830] rcu: Hierarchical SRCU implementation.
[ 0.006095] devtmpfs: initialized
[ 0.011726] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.011757] futex hash table entries: 256 (order: -1, 3072 bytes)
[ 0.012213] pinctrl core: initialized pinctrl subsystem
[ 0.013851] NET: Registered protocol family 16
[ 0.014471] DMA: preallocated 256 KiB pool for atomic coherent allocations
[ 0.016239] cpuidle: using governor ladder
[ 0.016318] cpuidle: using governor menu
[ 0.016882] Feroceon L2: Enabling L2
[ 0.016933] Feroceon L2: Cache support initialised.
[ 0.017364] [Firmware Info]: /ocp@f1000000/ethernet-controller@72000/ethernet0-port@0: local-mac-address is not set
[ 0.017490] [Firmware Info]: /ocp@f1000000/ethernet-controller@76000/ethernet1-port@0: local-mac-address is not set
[ 0.023899] No ATAGs?
[ 0.036045] vgaarb: loaded
[ 0.036397] SCSI subsystem initialized
[ 0.036924] usbcore: registered new interface driver usbfs
[ 0.037012] usbcore: registered new interface driver hub
[ 0.037087] usbcore: registered new device driver usb
[ 0.037223] pps_core: LinuxPPS API ver. 1 registered
[ 0.037233] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[ 0.037262] PTP clock support registered
[ 0.038468] clocksource: Switched to clocksource orion_clocksource
[ 0.589447] VFS: Disk quotas dquot_6.6.0
[ 0.589552] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 0.589796] FS-Cache: Loaded
[ 0.601884] NET: Registered protocol family 2
[ 0.602851] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes)
[ 0.602935] TCP established hash table entries: 2048 (order: 1, 8192 bytes)
[ 0.602976] TCP bind hash table entries: 2048 (order: 1, 8192 bytes)
[ 0.603011] TCP: Hash tables configured (established 2048 bind 2048)
[ 0.603133] UDP hash table entries: 256 (order: 0, 4096 bytes)
[ 0.603162] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[ 0.603425] NET: Registered protocol family 1
[ 0.604102] RPC: Registered named UNIX socket transport module.
[ 0.604115] RPC: Registered udp transport module.
[ 0.604123] RPC: Registered tcp transport module.
[ 0.604130] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 0.604443] Trying to unpack rootfs image as initramfs...
[ 0.610009] rootfs image is not initramfs (junk in compressed archive); looks like an initrd
[ 0.665748] Freeing initrd memory: 7060K
[ 0.669914] Initialise system trusted keyrings
[ 0.670210] workingset: timestamp_bits=14 max_order=16 bucket_order=2
[ 0.683229] FS-Cache: Netfs 'nfs' registered for caching
[ 0.684053] NFS: Registering the id_resolver key type
[ 0.684087] Key type id_resolver registered
[ 0.684095] Key type id_legacy registered
[ 0.684151] jffs2: version 2.2. (NAND) © 2001-2006 Red Hat, Inc.
[ 0.684918] fuse init (API version 7.28)
[ 0.700279] Key type asymmetric registered
[ 0.700297] Asymmetric key parser 'x509' registered
[ 0.700373] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[ 0.700394] io scheduler mq-deadline registered
[ 0.700404] io scheduler kyber registered
[ 0.700838] io scheduler bfq registered
[ 0.702844] kirkwood-pinctrl f1010000.pin-controller: registered pinctrl driver
[ 0.705044] mv_xor f1060800.xor: Marvell shared XOR driver
[ 0.759986] mv_xor f1060800.xor: Marvell XOR (Registers Mode): ( xor cpy intr )
[ 0.760287] mv_xor f1060900.xor: Marvell shared XOR driver
[ 0.819899] mv_xor f1060900.xor: Marvell XOR (Registers Mode): ( xor cpy intr )
[ 0.825013] Serial: 8250/16550 driver, 2 ports, IRQ sharing disabled
[ 0.826416] printk: console [ttyS0] disabled
[ 0.826492] f1012000.serial: ttyS0 at MMIO 0xf1012000 (irq = 26, base_baud = 12500000) is a 16550A
[ 1.439722] printk: console [ttyS0] enabled
[ 1.458214] brd: module loaded
[ 1.472126] loop: module loaded
[ 1.475997] sata_mv f1080000.sata: slots 32 ports 2
[ 1.484342] scsi host0: sata_mv
[ 1.488315] scsi host1: sata_mv
[ 1.491918] ata1: SATA max UDMA/133 irq 33
[ 1.496036] ata2: SATA max UDMA/133 irq 33
[ 1.501088] nand: device found, Manufacturer ID: 0xad, Chip ID: 0x75
[ 1.507576] nand: Hynix NAND 32MiB 3,3V 8-bit
[ 1.511996] nand: 32 MiB, SLC, erase size: 16 KiB, page size: 512, OOB size: 16
[ 1.519370] Scanning device for bad blocks
[ 1.750584] 3 cmdlinepart partitions found on MTD device orion_nand
[ 1.756913] Creating 3 MTD partitions on "orion_nand":
[ 1.762122] 0x000000000000-0x0000000a0000 : "u-boot"
[ 1.767762] 0x0000000a0000-0x0000000a4000 : "u-boot-env"
[ 1.773667] 0x0000000a4000-0x000002000000 : "iomega-firmware"
[ 1.782320] libphy: Fixed MDIO Bus: probed
[ 1.787585] libphy: orion_mdio_bus: probed
[ 1.792841] mv643xx_eth: MV-643xx 10/100/1000 ethernet driver version 1.4
[ 1.800818] mv643xx_eth_port mv643xx_eth_port.0: DMA mask not set
[ 1.807655] mv643xx_eth_port mv643xx_eth_port.0 eth0: port 0 with MAC address aa:00:00:00:00:01
[ 1.816599] mv643xx_eth_port mv643xx_eth_port.1: DMA mask not set
[ 2.539198] mv643xx_eth_port mv643xx_eth_port.1 eth1: port 0 with MAC address 00:d0:b8:0c:8c:b0
[ 2.548072] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 2.554672] ehci-pci: EHCI PCI platform driver
[ 2.559234] ehci-orion: EHCI orion driver
[ 2.563488] orion-ehci f1050000.ehci: EHCI Host Controller
[ 2.569068] orion-ehci f1050000.ehci: new USB bus registered, assigned bus number 1
[ 2.576988] orion-ehci f1050000.ehci: irq 30, io mem 0xf1050000
[ 2.608503] orion-ehci f1050000.ehci: USB 2.0 started, EHCI 1.00
[ 2.615471] hub 1-0:1.0: USB hub found
[ 2.619349] hub 1-0:1.0: 1 port detected
[ 2.624043] usbcore: registered new interface driver uas
[ 2.629578] usbcore: registered new interface driver usb-storage
[ 2.635659] usbcore: registered new interface driver ums-alauda
[ 2.641712] usbcore: registered new interface driver ums-cypress
[ 2.647786] usbcore: registered new interface driver ums-datafab
[ 2.653892] usbcore: registered new interface driver ums_eneub6250
[ 2.660178] usbcore: registered new interface driver ums-freecom
[ 2.666254] usbcore: registered new interface driver ums-isd200
[ 2.672263] usbcore: registered new interface driver ums-jumpshot
[ 2.678426] usbcore: registered new interface driver ums-karma
[ 2.684349] usbcore: registered new interface driver ums-onetouch
[ 2.690568] usbcore: registered new interface driver ums-realtek
[ 2.696645] usbcore: registered new interface driver ums-sddr09
[ 2.702685] usbcore: registered new interface driver ums-sddr55
[ 2.708698] usbcore: registered new interface driver ums-usbat
[ 2.714951] mousedev: PS/2 mouse device common for all mice
[ 2.721125] rtc-mv f1010300.rtc: registered as rtc0
[ 2.726133] i2c /dev entries driver
[ 2.730853] orion_wdt: Initial timeout 21 sec
[ 2.736322] sdhci: Secure Digital Host Controller Interface driver
[ 2.742615] sdhci: Copyright(c) Pierre Ossman
[ 2.747111] sdhci-pltfm: SDHCI platform and OF driver helper
[ 2.753934] ledtrig-cpu: registered to indicate activity on CPUs
[ 2.760117] usbcore: registered new interface driver r8712u
[ 2.766019] gre: GRE over IPv4 demultiplexor driver
[ 2.771020] NET: Registered protocol family 17
[ 2.775768] Key type dns_resolver registered
[ 2.781124] registered taskstats version 1
[ 2.785243] Loading compiled-in X.509 certificates
[ 2.792039] Key type encrypted registered
[ 2.798168] printk: console [netcon0] enabled
[ 2.802657] netconsole: network logging started
[ 2.808103] input: gpio-keys as /devices/platform/gpio-keys/input/input0
[ 2.815340] rtc-mv f1010300.rtc: setting system clock to 2019-03-12T02:42:26 UTC (1552358546)
[ 2.918505] random: fast init done
[ 2.988563] usb 1-1: new high-speed USB device number 2 using orion-ehci
[ 3.190858] hub 1-1:1.0: USB hub found
[ 3.194916] hub 1-1:1.0: 4 ports detected
[ 3.518551] usb 1-1.1: new high-speed USB device number 3 using orion-ehci
[ 3.672518] usb-storage 1-1.1:1.0: USB Mass Storage device detected
[ 3.679913] scsi host2: usb-storage 1-1.1:1.0
[ 4.730372] scsi 2:0:0:0: Direct-Access SanDisk Ultra Fit 1.00 PQ: 0 ANSI: 6
[ 4.740896] sd 2:0:0:0: [sda] 240254976 512-byte logical blocks: (123 GB/115 GiB)
[ 4.749074] sd 2:0:0:0: Attached scsi generic sg0 type 0
[ 4.756264] sd 2:0:0:0: [sda] Write Protect is off
[ 4.762549] sd 2:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[ 4.777753] sda: sda1 sda2 sda3 sda4
[ 4.785906] sd 2:0:0:0: [sda] Attached SCSI removable disk
[ 7.038538] ata1: link is slow to respond, please be patient (ready=0)
[ 11.518536] ata1: SRST failed (errno=-16)
[ 12.018573] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl F300)
[ 12.029654] ata1.00: ATA-8: ST2000DL003-9VT166, CC32, max UDMA/133
[ 12.035894] ata1.00: 3907029168 sectors, multi 0: LBA48 NCQ (depth 31/32)
[ 12.049787] ata1.00: configured for UDMA/133
[ 12.064840] scsi 0:0:0:0: Direct-Access ATA ST2000DL003-9VT1 CC32 PQ: 0 ANSI: 5
[ 12.074207] sd 0:0:0:0: [sdb] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB)
[ 12.082415] sd 0:0:0:0: Attached scsi generic sg1 type 0
[ 12.088021] sd 0:0:0:0: [sdb] Write Protect is off
[ 12.093255] sd 0:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 12.163623] sdb: sdb1 sdb2 sdb3
[ 12.172474] sd 0:0:0:0: [sdb] Attached SCSI disk
[ 17.638537] ata2: link is slow to respond, please be patient (ready=0)
[ 22.118536] ata2: SRST failed (errno=-16)
[ 22.968557] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl F300)
[ 22.979629] ata2.00: ATA-8: ST2000DL003-9VT166, CC32, max UDMA/133
[ 22.985868] ata2.00: 3907029168 sectors, multi 0: LBA48 NCQ (depth 31/32)
[ 22.999766] ata2.00: configured for UDMA/133
[ 23.014883] scsi 1:0:0:0: Direct-Access ATA ST2000DL003-9VT1 CC32 PQ: 0 ANSI: 5
[ 23.024264] sd 1:0:0:0: [sdc] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB)
[ 23.032487] sd 1:0:0:0: Attached scsi generic sg2 type 0
[ 23.038075] sd 1:0:0:0: [sdc] Write Protect is off
[ 23.043203] sd 1:0:0:0: [sdc] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 23.113286] sdc: sdc1 sdc2 sdc3
[ 23.122166] sd 1:0:0:0: [sdc] Attached SCSI disk
[ 23.127212] RAMDISK: gzip image found at block 0
[ 23.476398] RAMDISK: incomplete write (565 != 32180)
[ 23.481510] write error
[ 23.525891] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts: (null)
[ 23.548670] VFS: Mounted root (ext4 filesystem) readonly on device 8:2.
[ 23.579896] devtmpfs: mounted
[ 23.584693] Freeing unused kernel memory: 360K
[ 23.598570] This architecture does not have kernel memory protection.
[ 23.605073] Run /sbin/init as init process
[ 24.400265] NET: Registered protocol family 10
[ 24.486089] Segment Routing with IPv6
[ 24.536865] systemd[1]: systemd 241.7-2-arch running in system mode. (+PAM +AUDIT -SELINUX -IMA -APPARMOR +SMACK -SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=hybrid)
[ 24.561149] systemd[1]: Detected architecture arm.

Welcome to Arch Linux ARM!

[ 24.613452] systemd[1]: Set hostname to <Twonky>.
[ 26.210029] random: systemd: uninitialized urandom read (16 bytes read)
[ 26.237417] random: systemd: uninitialized urandom read (16 bytes read)
[ 26.244917] systemd[1]: Listening on initctl Compatibility Named Pipe.
[ OK ] Listening on initctl Compatibility Named Pipe.
[ 26.278762] random: systemd: uninitialized urandom read (16 bytes read)
[ 26.293013] systemd[1]: Created slice system-serial\x2dgetty.slice.
[ OK ] Created slice system-serial\x2dgetty.slice.
[ 26.329568] systemd[1]: Listening on udev Kernel Socket.
[ OK ] Listening on udev Kernel Socket.
[ 26.371239] systemd[1]: Listening on Journal Socket.
[ OK ] Listening on Journal Socket.
[ 26.423201] systemd[1]: Mounting POSIX Message Queue File System...
Mounting POSIX Message Queue File System...
[ 26.470353] systemd[1]: Listening on Network Service Netlink Socket.
[ OK ] Listening on Network Service Netlink Socket.
[ 26.522152] systemd[1]: Mounting NFSD configuration filesystem...
Mounting NFSD configuration filesystem...
[ OK ] Set up automount Arbitrary…s File [ 26.606145] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
System Automount Point.
Starting Remount Root and Kernel File Systems...
[ OK ] Listening on udev Control Socket.
Starting Apply Kernel Variables...
[ OK ] Created slice system-devmon.slice.
[ OK ] Listening on LVM2 metadata daemon socket.
Mounting Kernel Debug File System...
[ OK ] Listening on Process Core Dump Socket.
[ OK ] Started Forward Password R…uests to Wall Directory Watch.
Starting Create list of re…odes for the current kernel...
[ OK ] Listening on LVM2 poll daemon socket.
[ OK ] Created slice system-getty.slice.
[ OK ] Reached target Remote File Systems.
[ OK ] Created slice system-syncthing.slice.
[ OK ] Created slice User and Session Slice.
[ OK ] Reached target Slices.
Mounting FUSE Control File System...
[ 27.324538] random: crng init done
[ 27.327965] random: 7 urandom warning(s) missed due to ratelimiting
[ OK ] Listening on RPCbind Server Activation Socket.
[ OK ] Reached target RPC Port Mapper.
[ 27.406055] EXT4-fs (sda2): re-mounted. Opts: barrier=1
[ OK ] Listening on Journal Socket (/dev/log).
Starting Journal Service...
Starting udev Coldplug all Devices...
[ OK ] Started Dispatch Password …ts to Console Directory Watch.
[ OK ] Reached target Local Encrypted Volumes.
[ OK ] Reached target Paths.
[ OK ] Listening on Device-mapper event daemon FIFOs.
Starting Monitoring of LVM…meventd or progress polling...
[ OK ] Mounted POSIX Message Queue File System.
[ OK ] Mounted NFSD configuration filesystem.
[ OK ] Started Remount Root and Kernel File Systems.
[ OK ] Started Apply Kernel Variables.
[ OK ] Mounted Kernel Debug File System.
[ OK ] Started Create list of req… nodes for the current kernel.
[ OK ] Mounted FUSE Control File System.
[ OK ] Started LVM2 metadata daemon.
Starting Load/Save Random Seed...
Starting Create System Users...
[ OK ] Started Journal Service.
Starting Flush Journal to Persistent Storage...
[ OK ] Started Load/Save Random Seed.
[ OK ] Started Create System Users.
[ 28.549224] systemd-journald[164]: Received request to flush runtime journal from PID 1
Starting Create Static Device Nodes in /dev...
[ OK ] Started Flush Journal to Persistent Storage.
[ OK ] Started Create Static Device Nodes in /dev.
Starting udev Kernel Device Manager...
[ OK ] Started udev Kernel Device Manager.
Starting Network Service...
[ OK ] Started udev Coldplug all Devices.
[ OK ] Started Network Service.
[ OK ] Found device /dev/ttyS0.
[ 31.583422] marvell-cesa f1030000.crypto: CESA device successfully registered
[ 33.651069] md/raid1:md0: active with 2 out of 2 mirrors
[ 33.678680] md/raid1:md1: active with 2 out of 2 mirrors
[ 33.688954] md0: detected capacity change from 0 to 268369920
[ 33.702911] md1: detected capacity change from 0 to 8581545984
[ 33.747897] md/raid1:md2: active with 2 out of 2 mirrors
[ 33.908795] md2: detected capacity change from 0 to 1991405207552
[ OK ] Started Monitoring of LVM2… dmeventd or progress polling.
[ 34.774003] mv643xx_eth_port mv643xx_eth_port.1 eth1: link up, 1000 Mb/s, full duplex, flow control disabled
[ OK ] Found device Ultra_Fit USB_BOOT.
[ OK ] Found device Ultra_Fit USB_DATA.
[ OK ] Found device Ultra_Fit 3.
[ 35.900058] mv643xx_eth_port mv643xx_eth_port.1 eth1: link down
[ OK ] Found device /dev/md2.
Starting MD array monitor...
[ OK ] Started Timer to wait for …re activating degraded array..
[ OK ] Started Timer to wait for …re activating degraded array..
[ OK ] Started Timer to wait for …re activating degraded array..
Activating swap /dev/disk/…a87e-471f-a0e3-5e29f91d7435...
[ OK ] Reached target Local File Systems (Pre).
[ 37.774705] Adding 524284k swap on /dev/sda3. Priority:-2 extents:1 across:524284k
Mounting /mnt/usb_storage...
Mounting /mnt/soho_storage...
[ 37.880478] EXT4-fs (sda4): mounted filesystem with ordered data mode. Opts: barrier=1
Mounting /boot...
[ 37.930506] EXT4-fs (sda1): mounting ext2 file system using the ext4 subsystem
[ 37.972885] EXT4-fs (sda1): mounted filesystem without journal. Opts: (null)
[ OK ] Activated swap /dev/disk/b…5-a87e-471f-a0e3-5e29f91d7435.
[ OK ] Started MD array monitor.
[ OK ] Mounted /mnt/usb_storage.
[ OK ] Mounted /boot.
[ OK ] Reached target Swap.
Mounting Temporary Directory (/tmp)...
Mounting /var/lib/mysqltmp...
[ OK ] Mounted Temporary Directory (/tmp).
[ OK ] Mounted /var/lib/mysqltmp.
[ 38.709504] EXT4-fs (md2): mounting with "discard" option, but the device does not support discard
[ 38.718623] EXT4-fs (md2): mounted filesystem with ordered data mode. Opts: discard,barrier=1
[ OK ] Mounted /mnt/soho_storage.
[ OK ] Reached target Local File Systems.
Starting Rebuild Journal Catalog...
Starting Rebuild Dynamic Linker Cache...
Starting Create Volatile Files and Directories...
[ OK ] Started Rebuild Journal Catalog.
[ OK ] Started Create Volatile Files and Directories.
Starting Entropy Harvesting Daemon...
Starting Update UTMP about System Boot/Shutdown...
Starting Network Time Synchronization...
Mounting RPC Pipe File System...
Starting Network Name Resolution...
[ OK ] Started Entropy Harvesting Daemon.
[ OK ] Mounted RPC Pipe File System.
[ OK ] Reached target rpc_pipefs.target.
Starting NFSv4 ID-name mapping service...
[ OK ] Started Update UTMP about System Boot/Shutdown.
[ OK ] Started NFSv4 ID-name mapping service.
[ OK ] Started Network Time Synchronization.
[ OK ] Reached target System Time Synchronized.
[ OK ] Started Network Name Resolution.
[ OK ] Reached target Network.
[ OK ] Reached target Network is Online.
[ OK ] Set up automount Automount Netty.
Starting NFS Mount Daemon...
[ OK ] Reached target Host and Network Name Lookups.
Starting NFS status monitor for NFSv2/3 locking....
Starting RPC Bind...
[ OK ] Started Rebuild Dynamic Linker Cache.
[ 42.590197] mv643xx_eth_port mv643xx_eth_port.1 eth1: link up, 1000 Mb/s, full duplex, flow control disabled
Starting Update is Completed...
[ OK ] Started Update is Completed.
[ OK ] Reached target System Initialization.
[ OK ] Started Daily Cleanup of Temporary Directories.
[ OK ] Started Run periodic raid volume scrub.
[ OK ] Listening on D-Bus System Message Bus Socket.
[ OK ] Started Daily rotation of log files.
[ OK ] Started Periodically optimize MySQL tables.
[ OK ] Started Daily verification of password and group files.
[ OK ] Started Periodically perform system backup.
[ OK ] Started Daily man-db regeneration.
[ OK ] Reached target Timers.
[ OK ] Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
[ OK ] Reached target Sockets.
[ OK ] Reached target Basic System.
Starting Transmission BitTorrent Daemon...
[ OK ] Started Civetweb Webserver.
Starting GSSAPI Proxy Daemon...
Starting Avahi mDNS/DNS-SD Stack...
Starting Control WeMo Smart Plugs...
Starting APC UPS Power Control Daemon for Linux...
Starting MariaDB 10.3.13 database server...
[ 43.701090] mv643xx_eth_port mv643xx_eth_port.1 eth1: link down
Starting Permit User Sessions...
[ OK ] Started devmon Service.
Starting Self Monitoring a…g Technology (SMART) Daemon...
[ OK ] Started OpenSSH Daemon.
[ OK ] Started Syncthing - Open S…Synchronization for syncthing.
Starting Set more agressive fan curve at boot...
Starting Login Service...
[ OK ] Started D-Bus System Message Bus.
[ OK ] Started RPC Bind.
[ OK ] Started GSSAPI Proxy Daemon.
[ OK ] Started APC UPS Power Control Daemon for Linux.
[ OK ] Started Permit User Sessions.
[ OK ] Started Set more agressive fan curve at boot.
[ OK ] Started Getty on tty1.
[ OK ] Started Serial Getty on ttyS0.
[ OK ] Reached target Login Prompts.
[ OK ] Started Login Service.
[ OK ] Started NFS status monitor for NFSv2/3 locking..
[ OK ] Started Avahi mDNS/DNS-SD Stack.
[ OK ] Started NFS Mount Daemon.
Starting NFS server and services...
[ 46.654673] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory
[ 46.698953] NFSD: starting 90-second grace period (net f000001c)
[ OK ] Started NFS server and services.
Starting Notify NFS peers of a restart...
[ OK ] Started Notify NFS peers of a restart.
[ 50.471748] mv643xx_eth_port mv643xx_eth_port.1 eth1: link up, 1000 Mb/s, full duplex, flow control disabled

Arch Linux 5.0.1-1-ARCH (ttyS0)')
kriztioan
 
Posts: 51
Joined: Sat Apr 29, 2017 1:32 am

Re: Kernel 5.0.{0,1}-1 boot issues [resolved]

Postby kriztioan » Wed Mar 13, 2019 11:58 pm

OK, after brushing up my HEX I am arriving at the following. With 32-bit memory addresses and a total memory size of 256MiB (0x10000000) my address range is 0x00000000 -> 0x0FFFFFFF.

The uImage-img is loaded into memory at an offset of 8MiB (0x00800000) and the uInitrd-img at an offset of 16MiB (0x01000000). This gives 8MiB room for the uImage-image before running into the uInitrd-img. Since the uImage-img is only 5.3MiB, this should not be a problem. Subsequently, the uImage-img is unpacked at an offset of 32K (0x00008000) with just under 8MiB headroom before it runs into the uImage-img. I'm not sure what size the uImage-img will unpack to, but would think 8MiB is sufficient ... However, I did find mentioned online that the self-extracting kernel will avoid overwriting its img and relocate itself; perhaps it reallocates itself somewhere in the uInitrd-img address-space. Though, the v5.0.1 kernel-img is only 7.9K larger than the v4.20.13 one ... the v5.0.1 uInitrd-img is 77K larger than the v4.20.13.

Some more internet diving took me to https://www.kernel.org/doc/Documentation/arm/Booting, which recommends a different memory layout. Namely, load the uImage-img at an offset of 32MiB (0x02000000) and the uInitrd-img at an offset just above 128MiB (0x08000000).

Hence, I will try and update my (scripted) U-Boot parameters as

$this->bbcode_second_pass_code('', 'setenv memoffset_kernel '0x02000000'
setenv memoffset_initrd '0x08004000'')

and report back.

[Update: this has solved the problem; the system boots without issue after updating the U-Boot parameters.]

For reference, I'm pasting the serial output of a good kernel 4.20.13 boot.

$this->bbcode_second_pass_code('', ' __ __ _ _
| \/ | __ _ _ ____ _____| | |
| |\/| |/ _` | '__\ \ / / _ \ | |
| | | | (_| | | \ V / __/ | |
|_| |_|\__,_|_| \_/ \___|_|_|
_ _ ____ _
| | | | | __ ) ___ ___ | |_
| | | |___| _ \ / _ \ / _ \| __|
| |_| |___| |_) | (_) | (_) | |_
\___/ |____/ \___/ \___/ \__|
** MARVELL BOARD: RD-88F6281A LE

U-Boot 1.1.4 (Sep 8 2009 - 09:31:54) Marvell version: 3.4.14

Mapower version: 2.0 (32MB) (2009/09/08)

U-Boot code: 00600000 -> 0067FFF0 BSS: -> 006CEE60

Soc: 88F6281 A0 (DDR2)
CPU running @ 1000Mhz L2 running @ 333Mhz
SysClock = 333Mhz , TClock = 200Mhz

DRAM CAS Latency = 5 tRP = 5 tRAS = 18 tRCD=6
DRAM CS[0] base 0x00000000 size 256MB
DRAM Total size 256MB 16bit width
Flash: 0 kB
Addresses 8M - 0M are saved for the U-Boot usage.
Mem malloc Initialization (8M - 7M): Done
NAND:32 MB

CPU : Marvell Feroceon (Rev 1)

Streaming disabled
Write allocate disabled

Module 0 is RGMII
Module 1 is TDM

USB 0: host mode
PEX 0: interface detected no Link.
Net: egiga0, egiga1 [PRIME]
Fan lookup table initialized.

Current remote temperature: 47
Current fan speed: 1117

Hit any key to stop autoboot: 0
(Re)start USB...
USB: scanning bus for devices... 3 USB Device(s) found
scanning bus for storage devices... 1 Storage Device(s) found
.
.
...
......
............
.
........................
.
.........................
........................
.
.........................
.........................
.........................
........................
.
.........................
.........................
.........................
.........................
.........................
.........................
.........................
........................
.
.........................
.........................
.........................
.........................
....................

5460448 bytes read
.
.
...
......
............
.
........................
.
.........................
........................
.
.........................
.........................
.........................
........................
.
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.........................
.....

7149906 bytes read
## Booting image at 00800000 ...
Image Name: Arch Linux ARM kernel
Created: 2019-03-13 1:50:57 UTC
Image Type: ARM Linux Kernel Image (uncompressed)
Data Size: 5460384 Bytes = 5.2 MB
Load Address: 00008000
Entry Point: 00008000
Verifying Checksum ... OK
OK
## Loading Ramdisk Image at 01000000 ...
Image Name: Arch Linux ARM initrd
Created: 2019-03-13 1:51:00 UTC
Image Type: ARM Linux RAMDisk Image (gzip compressed)
Data Size: 7149842 Bytes = 6.8 MB
Load Address: 01000000
Entry Point: 01000000
Verifying Checksum ... OK

Starting kernel ...

Uncompressing Linux... done, booting the kernel.
[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Linux version 4.20.13-1-ARCH (builduser@leming) (gcc version 8.2.1 20181127 (GCC)) #1 PREEMPT Thu Feb 28 01:05:08 UTC 2019
[ 0.000000] CPU: Feroceon 88FR131 [56251311] revision 1 (ARMv5TE), cr=0005397f
[ 0.000000] CPU: VIVT data cache, VIVT instruction cache
[ 0.000000] OF: fdt: Machine model: Iomega StorCenter ix2-200
[ 0.000000] Memory policy: Data cache writeback
[ 0.000000] random: get_random_bytes called from start_kernel+0x7c/0x4e4 with crng_init=0
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 64960
[ 0.000000] Kernel command line: console=ttyS0,115200 mtdparts=orion_nand:640k(u-boot)ro,16k(u-boot-env),-(iomega-firmware)ro root=PARTUUID=b76d429d-02 ro audit=0
[ 0.000000] audit: disabled (until reboot)
[ 0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
[ 0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
[ 0.000000] Memory: 240736K/262144K available (7996K kernel code, 623K rwdata, 1796K rodata, 364K init, 809K bss, 21408K reserved, 0K cma-reserved)
[ 0.000000] Virtual kernel memory layout:
[ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
[ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 kB)
[ 0.000000] vmalloc : 0xd0800000 - 0xff800000 ( 752 MB)
[ 0.000000] lowmem : 0xc0000000 - 0xd0000000 ( 256 MB)
[ 0.000000] modules : 0xbf000000 - 0xc0000000 ( 16 MB)
[ 0.000000] .text : 0x(ptrval) - 0x(ptrval) (7997 kB)
[ 0.000000] .init : 0x(ptrval) - 0x(ptrval) ( 364 kB)
[ 0.000000] .data : 0x(ptrval) - 0x(ptrval) ( 624 kB)
[ 0.000000] .bss : 0x(ptrval) - 0x(ptrval) ( 810 kB)
[ 0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[ 0.000000] rcu: Preemptible hierarchical RCU implementation.
[ 0.000000] 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] clocksource: orion_clocksource: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 9556302233 ns
[ 0.000010] sched_clock: 32 bits at 200MHz, resolution 5ns, wraps every 10737418237ns
[ 0.000048] Switching to timer-based delay loop, resolution 5ns
[ 0.000306] Console: colour dummy device 80x30
[ 0.000358] Calibrating delay loop (skipped), value calculated using timer frequency.. 400.00 BogoMIPS (lpj=2000000)
[ 0.000382] pid_max: default: 32768 minimum: 301
[ 0.000877] LSM: Security Framework initializing
[ 0.001094] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.001120] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.002502] CPU: Testing write buffer coherency: ok
[ 0.004182] Setting up static identity map for 0x8200 - 0x8258
[ 0.004493] mvebu-soc-id: MVEBU SoC ID=0x6281, Rev=0x2
[ 0.004776] rcu: Hierarchical SRCU implementation.
[ 0.006050] devtmpfs: initialized
[ 0.011749] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.011781] futex hash table entries: 256 (order: -1, 3072 bytes)
[ 0.012277] pinctrl core: initialized pinctrl subsystem
[ 0.013914] NET: Registered protocol family 16
[ 0.014507] DMA: preallocated 256 KiB pool for atomic coherent allocations
[ 0.016357] cpuidle: using governor ladder
[ 0.016434] cpuidle: using governor menu
[ 0.016986] Feroceon L2: Enabling L2
[ 0.017039] Feroceon L2: Cache support initialised.
[ 0.017471] [Firmware Info]: /ocp@f1000000/ethernet-controller@72000/ethernet0-port@0: local-mac-address is not set
[ 0.017593] [Firmware Info]: /ocp@f1000000/ethernet-controller@76000/ethernet1-port@0: local-mac-address is not set
[ 0.023554] No ATAGs?
[ 0.035666] vgaarb: loaded
[ 0.036033] SCSI subsystem initialized
[ 0.036579] usbcore: registered new interface driver usbfs
[ 0.036667] usbcore: registered new interface driver hub
[ 0.036736] usbcore: registered new device driver usb
[ 0.036869] pps_core: LinuxPPS API ver. 1 registered
[ 0.036880] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[ 0.036909] PTP clock support registered
[ 0.038123] clocksource: Switched to clocksource orion_clocksource
[ 0.124315] VFS: Disk quotas dquot_6.6.0
[ 0.124420] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 0.124679] FS-Cache: Loaded
[ 0.136729] NET: Registered protocol family 2
[ 0.137735] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes)
[ 0.137784] TCP established hash table entries: 2048 (order: 1, 8192 bytes)
[ 0.137824] TCP bind hash table entries: 2048 (order: 1, 8192 bytes)
[ 0.137860] TCP: Hash tables configured (established 2048 bind 2048)
[ 0.137976] UDP hash table entries: 256 (order: 0, 4096 bytes)
[ 0.138014] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[ 0.138396] NET: Registered protocol family 1
[ 0.139041] RPC: Registered named UNIX socket transport module.
[ 0.139055] RPC: Registered udp transport module.
[ 0.139063] RPC: Registered tcp transport module.
[ 0.139071] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 0.139405] Trying to unpack rootfs image as initramfs...
[ 0.724311] Freeing initrd memory: 6984K
[ 0.787291] Initialise system trusted keyrings
[ 0.787550] workingset: timestamp_bits=14 max_order=16 bucket_order=2
[ 0.800964] FS-Cache: Netfs 'nfs' registered for caching
[ 0.801803] NFS: Registering the id_resolver key type
[ 0.801841] Key type id_resolver registered
[ 0.801850] Key type id_legacy registered
[ 0.801905] jffs2: version 2.2. (NAND) © 2001-2006 Red Hat, Inc.
[ 0.802675] fuse init (API version 7.28)
[ 1.112090] Key type asymmetric registered
[ 1.112109] Asymmetric key parser 'x509' registered
[ 1.112193] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[ 1.112217] io scheduler noop registered
[ 1.112227] io scheduler deadline registered
[ 1.112690] io scheduler cfq registered (default)
[ 1.112705] io scheduler mq-deadline registered
[ 1.112715] io scheduler kyber registered
[ 1.113090] io scheduler bfq registered
[ 1.115146] kirkwood-pinctrl f1010000.pin-controller: registered pinctrl driver
[ 1.117338] mv_xor f1060800.xor: Marvell shared XOR driver
[ 1.179475] mv_xor f1060800.xor: Marvell XOR (Registers Mode): ( xor cpy intr )
[ 1.179765] mv_xor f1060900.xor: Marvell shared XOR driver
[ 1.239474] mv_xor f1060900.xor: Marvell XOR (Registers Mode): ( xor cpy intr )
[ 1.244583] Serial: 8250/16550 driver, 2 ports, IRQ sharing disabled
[ 1.245985] printk: console [ttyS0] disabled
[ 1.246062] f1012000.serial: ttyS0 at MMIO 0xf1012000 (irq = 26, base_baud = 12500000) is a 16550A
[ 1.863945] printk: console [ttyS0] enabled
[ 1.882487] brd: module loaded
[ 1.895539] loop: module loaded
[ 1.899510] sata_mv f1080000.sata: slots 32 ports 2
[ 1.907744] scsi host0: sata_mv
[ 1.911663] scsi host1: sata_mv
[ 1.915076] ata1: SATA max UDMA/133 irq 33
[ 1.919264] ata2: SATA max UDMA/133 irq 33
[ 1.924244] nand: device found, Manufacturer ID: 0xad, Chip ID: 0x75
[ 1.930756] nand: Hynix NAND 32MiB 3,3V 8-bit
[ 1.935137] nand: 32 MiB, SLC, erase size: 16 KiB, page size: 512, OOB size: 16
[ 1.942520] Scanning device for bad blocks
[ 2.172820] 3 cmdlinepart partitions found on MTD device orion_nand
[ 2.179165] Creating 3 MTD partitions on "orion_nand":
[ 2.184361] 0x000000000000-0x0000000a0000 : "u-boot"
[ 2.189911] 0x0000000a0000-0x0000000a4000 : "u-boot-env"
[ 2.195716] 0x0000000a4000-0x000002000000 : "iomega-firmware"
[ 2.204252] libphy: Fixed MDIO Bus: probed
[ 2.209533] libphy: orion_mdio_bus: probed
[ 2.224294] mv643xx_eth: MV-643xx 10/100/1000 ethernet driver version 1.4
[ 2.232331] mv643xx_eth_port mv643xx_eth_port.0: DMA mask not set
[ 2.239193] mv643xx_eth_port mv643xx_eth_port.0 eth0: port 0 with MAC address aa:00:00:00:00:01
[ 2.248047] mv643xx_eth_port mv643xx_eth_port.1: DMA mask not set
[ 2.428168] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl F300)
[ 2.439051] ata1.00: ATA-8: ST2000DL003-9VT166, CC32, max UDMA/133
[ 2.445269] ata1.00: 3907029168 sectors, multi 0: LBA48 NCQ (depth 31/32)
[ 2.458893] ata1.00: configured for UDMA/133
[ 2.473779] scsi 0:0:0:0: Direct-Access ATA ST2000DL003-9VT1 CC32 PQ: 0 ANSI: 5
[ 2.483101] sd 0:0:0:0: [sda] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB)
[ 2.491287] sd 0:0:0:0: Attached scsi generic sg0 type 0
[ 2.496914] sd 0:0:0:0: [sda] Write Protect is off
[ 2.502135] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 2.526085] random: fast init done
[ 2.557644] sda: sda1 sda2 sda3
[ 2.562997] sd 0:0:0:0: [sda] Attached SCSI disk
[ 2.968859] mv643xx_eth_port mv643xx_eth_port.1 eth1: port 0 with MAC address 00:d0:b8:0c:8c:b0
[ 2.977750] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 2.984381] ehci-pci: EHCI PCI platform driver
[ 2.988922] ehci-orion: EHCI orion driver
[ 2.993188] orion-ehci f1050000.ehci: EHCI Host Controller
[ 2.998770] orion-ehci f1050000.ehci: new USB bus registered, assigned bus number 1
[ 3.006720] orion-ehci f1050000.ehci: irq 30, io mem 0xf1050000
[ 3.012737] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl F300)
[ 3.023639] ata2.00: ATA-8: ST2000DL003-9VT166, CC32, max UDMA/133
[ 3.029885] ata2.00: 3907029168 sectors, multi 0: LBA48 NCQ (depth 31/32)
[ 3.043479] ata2.00: configured for UDMA/133
[ 3.048158] orion-ehci f1050000.ehci: USB 2.0 started, EHCI 1.00
[ 3.055136] hub 1-0:1.0: USB hub found
[ 3.059456] scsi 1:0:0:0: Direct-Access ATA ST2000DL003-9VT1 CC32 PQ: 0 ANSI: 5
[ 3.067703] hub 1-0:1.0: 1 port detected
[ 3.072512] usbcore: registered new interface driver uas
[ 3.077960] usbcore: registered new interface driver usb-storage
[ 3.085161] sd 1:0:0:0: [sdb] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB)
[ 3.093336] sd 1:0:0:0: Attached scsi generic sg1 type 0
[ 3.098808] sd 1:0:0:0: [sdb] Write Protect is off
[ 3.104070] usbcore: registered new interface driver ums-alauda
[ 3.110078] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 3.119244] usbcore: registered new interface driver ums-cypress
[ 3.125329] usbcore: registered new interface driver ums-datafab
[ 3.132926] usbcore: registered new interface driver ums_eneub6250
[ 3.139227] usbcore: registered new interface driver ums-freecom
[ 3.145517] usbcore: registered new interface driver ums-isd200
[ 3.151836] usbcore: registered new interface driver ums-jumpshot
[ 3.158223] usbcore: registered new interface driver ums-karma
[ 3.164144] usbcore: registered new interface driver ums-onetouch
[ 3.170589] usbcore: registered new interface driver ums-realtek
[ 3.176674] usbcore: registered new interface driver ums-sddr09
[ 3.182818] usbcore: registered new interface driver ums-sddr55
[ 3.188840] usbcore: registered new interface driver ums-usbat
[ 3.195110] mousedev: PS/2 mouse device common for all mice
[ 3.201303] rtc-mv f1010300.rtc: rtc core: registered f1010300.rtc as rtc0
[ 3.208604] i2c /dev entries driver
[ 3.213176] sdb: sdb1 sdb2 sdb3
[ 3.217457] orion_wdt: Initial timeout 21 sec
[ 3.223757] sd 1:0:0:0: [sdb] Attached SCSI disk
[ 3.229608] sdhci: Secure Digital Host Controller Interface driver
[ 3.235819] sdhci: Copyright(c) Pierre Ossman
[ 3.240405] sdhci-pltfm: SDHCI platform and OF driver helper
[ 3.247160] ledtrig-cpu: registered to indicate activity on CPUs
[ 3.253432] usbcore: registered new interface driver r8712u
[ 3.259369] gre: GRE over IPv4 demultiplexor driver
[ 3.264285] NET: Registered protocol family 17
[ 3.269055] Key type dns_resolver registered
[ 3.274384] registered taskstats version 1
[ 3.278581] Loading compiled-in X.509 certificates
[ 3.313585] Key type encrypted registered
[ 3.320066] printk: console [netcon0] enabled
[ 3.324449] netconsole: network logging started
[ 3.330110] input: gpio-keys as /devices/platform/gpio-keys/input/input0
[ 3.337233] rtc-mv f1010300.rtc: setting system clock to 2019-03-13 01:52:09 UTC (1552441929)
[ 3.347571] Freeing unused kernel memory: 364K
[ 3.352152] This architecture does not have kernel memory protection.
[ 3.358677] Run /init as init process
:: running early hook [udev]
[ 3.438272] usb 1-1: new high-speed USB device number 2 using orion-ehci
Starting version 241.7-2-arch
:: running hook [udev]
:: Triggering uevents...
[ 3.659704] hub 1-1:1.0: USB hub found
[ 3.668317] hub 1-1:1.0: 4 ports detected
[ 4.008250] usb 1-1.1: new high-speed USB device number 3 using orion-ehci
[ 4.183497] usb-storage 1-1.1:1.0: USB Mass Storage device detected
[ 4.208412] scsi host2: usb-storage 1-1.1:1.0
[ 5.294015] scsi 2:0:0:0: Direct-Access SanDisk Ultra Fit 1.00 PQ: 0 ANSI: 6
[ 5.353843] sd 2:0:0:0: Attached scsi generic sg2 type 0
[ 5.361503] sd 2:0:0:0: [sdc] 240254976 512-byte logical blocks: (123 GB/115 GiB)
[ 5.413996] sd 2:0:0:0: [sdc] Write Protect is off
[ 5.447445] sd 2:0:0:0: [sdc] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[ 5.554646] sdc: sdc1 sdc2 sdc3 sdc4
[ 5.601414] sd 2:0:0:0: [sdc] Attached SCSI removable disk
[ 5.648909] md/raid1:md0: active with 2 out of 2 mirrors
[ 5.669947] md0: detected capacity change from 0 to 268369920
[ 5.698380] md/raid1:md1: active with 2 out of 2 mirrors
[ 5.706753] md1: detected capacity change from 0 to 8581545984
[ 5.728360] md/raid1:md2: active with 2 out of 2 mirrors
[ 5.878585] md2: detected capacity change from 0 to 1991405207552
[ 7.275675] random: crng init done
:: performing fsck on '/dev/sdc2'
USB_ROOT: clean, 76988/786432 files, 907337/3145728 blocks
********************** WARNING **********************
* *
* The root device is not configured to be mounted *
* read-write! It may be fsck'd again later. *
* *
*****************************************************
:: mounting '/dev/sdc2' on real root
[ 7.890189] EXT4-fs (sdc2): mounted filesystem with ordered data mode. Opts: (null)
:: running cleanup hook [udev]
[ 8.900510] NET: Registered protocol family 10
[ 8.986185] Segment Routing with IPv6
[ 9.036855] systemd[1]: systemd 241.7-2-arch running in system mode. (+PAM +AUDIT -SELINUX -IMA -APPARMOR +SMACK -SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=hybrid)
[ 9.060646] systemd[1]: Detected architecture arm.

Welcome to Arch Linux ARM!

[ 9.121741] systemd[1]: Set hostname to <Twonky>.
[ 12.918665] systemd[1]: Listening on RPCbind Server Activation Socket.
[ OK ] Listening on RPCbind Server Activation Socket.
[ 12.961108] systemd[1]: Listening on udev Control Socket.
[ OK ] Listening on udev Control Socket.
[ 13.001016] systemd[1]: Listening on Journal Socket.
[ OK ] Listening on Journal Socket.
[ 13.039723] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[ OK ] Started Forward Password R…uests to Wall Directory Watch.
[ 13.092323] systemd[1]: Starting Remount Root and Kernel File Systems...
Starting Remount Root and Kernel File Systems...
[ 13.140019] systemd[1]: Listening on Network Service Netlink Socket.
[ OK ] Listening on Network Service Netlink Socket.
[ 13.159330] systemd[1]: Listening on Device-mapper event daemon FIFOs.
[ OK ] Listening on Device-mapper event daemon FIFOs.
[ 13.218457] EXT4-fs (sdc2): re-mounted. Opts: barrier=1
Starting Create list of re…odes for the current kernel...
[ OK ] Reached target Remote File Systems.
[ OK ] Listening on udev Kernel Socket.
Starting udev Coldplug all Devices...
[ OK ] Listening on LVM2 metadata daemon socket.
Starting Monitoring of LVM…meventd or progress polling...
[ OK ] Reached target RPC Port Mapper.
[ OK ] Created slice User and Session Slice.
[ OK ] Reached target Slices.
[ OK ] Listening on Process Core Dump Socket.
[ OK ] Created slice system-serial\x2dgetty.slice.
[ OK ] Created slice system-devmon.slice.
[ OK ] Set up automount Arbitrary…s File System Automount Point.
[ OK ] Listening on Journal Socket (/dev/log).
[ OK ] Listening on LVM2 poll daemon socket.
Mounting Kernel Debug File System...
Starting Journal Service...
[ 14.070115] systemd[1]: Condition check resulted in Load Kernel Modules being skipped.
[ 14.094988] systemd[1]: Mounting FUSE Control File System...
Mounting FUSE Control File System...
[ 14.119105] systemd[1]: Condition check resulted in Kernel Configuration File System being skipped.
[ 14.152992] systemd[1]: Starting Apply Kernel Variables...
Starting Apply Kernel Variables...
[ 14.198630] systemd[1]: Condition check resulted in Kernel Module supporting RPCSEC_GSS being skipped.
[ 14.210855] systemd[1]: Created slice system-syncthing.slice.
[ OK ] Created slice system-syncthing.slice.
[ 14.239383] systemd[1]: Mounting NFSD configuration filesystem...
Mounting NFSD configuration filesystem...
[ 14.269159] systemd[1]: Listening on initctl Compatibility Named Pipe.
[ OK ] Listening on initctl Compatibility Named Pipe.
[ 14.290387] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[ OK ] Started Dispatch Password …ts to Console Directory Watch.
[ 14.358568] systemd[1]: Reached target Local Encrypted Volumes.
[ OK ] Reached target Local Encrypted Volumes.
Mounting POSIX Message Queue File System...
[ OK ] Created slice system-getty.slice.
[ OK ] Reached target Paths.
[ OK ] Started Remount Root[ 14.501602] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
and Kernel File Systems.
[ OK ] Started Journal Service.
[ OK ] Started Create list of req… nodes for the current kernel.
[ OK ] Mounted Kernel Debug File System.
[ OK ] Mounted FUSE Control File System.
[ OK ] Started Apply Kernel Variables.
[ OK ] Mounted NFSD configuration filesystem.
[ OK ] Mounted POSIX Message Queue File System.
[ OK ] Started LVM2 metadata daemon.
Starting Flush Journal to Persistent Storage...
Starting Create System Users...
Starting Load/Save Random Seed...
[ OK ] Started Load/Save Random Seed.
[ 15.339902] systemd-journald[215]: Received request to flush runtime journal from PID 1
[ OK ] Started Flush Journal to Persistent Storage.
[ OK ] Started Create System Users.
[ OK ] Started udev Coldplug all Devices.
Starting Create Static Device Nodes in /dev...
[ OK ] Started Create Static Device Nodes in /dev.
Starting udev Kernel Device Manager...
[ OK ] Started udev Kernel Device Manager.
Starting Network Service...
[ OK ] Started Network Service.
[ OK ] Found device /dev/ttyS0.
[ 18.694702] marvell-cesa f1030000.crypto: CESA device successfully registered
[ OK ] Started Monitoring of LVM2… dmeventd or progress polling.
[ OK ] Found device Ultra_Fit USB_DATA.
[ OK ] Found device Ultra_Fit 3.
[ OK ] Found device Ultra_Fit USB_BOOT.
[ OK ] Found device /dev/md2.
Starting MD array monitor...
Activating swap /dev/disk/…a87e-471f-a0e3-5e29f91d7435...
[ OK ] Reached target Local File Systems (Pre).
[ 24.195272] Adding 524284k swap on /dev/sdc3. Priority:-2 extents:1 across:524284k
Mounting /mnt/soho_storage...
Mounting /boot...
Mounting /mnt/usb_storage...
[ 24.329103] EXT4-fs (sdc1): mounting ext2 file system using the ext4 subsystem
[ 24.406818] EXT4-fs (sdc1): mounted filesystem without journal. Opts: (null)
[ 24.480960] EXT4-fs (sdc4): mounted filesystem with ordered data mode. Opts: barrier=1
[ OK ] Activated swap /dev/disk/b…5-a87e-471f-a0e3-5e29f91d7435.
[ OK ] Started MD array monitor.
[ OK ] Mounted /boot.
[ OK ] Mounted /mnt/usb_storage.
[ OK ] Reached target Swap.
Mounting Temporary Directory (/tmp)...
Mounting /var/lib/mysqltmp...
[ OK ] Mounted Temporary Directory (/tmp).
[ OK ] Mounted /var/lib/mysqltmp.
[ 25.167698] EXT4-fs (md2): mounting with "discard" option, but the device does not support discard
[ 25.176862] EXT4-fs (md2): mounted filesystem with ordered data mode. Opts: discard,barrier=1
[ OK ] Mounted /mnt/soho_storage.
[ OK ] Reached target Local File Systems.
Starting Create Volatile Files and Directories...
Starting Rebuild Journal Catalog...
Starting Rebuild Dynamic Linker Cache...
[ OK ] Started Create Volatile Files and Directories.
[ 25.999549] mv643xx_eth_port mv643xx_eth_port.1 eth1: link up, 1000 Mb/s, full duplex, flow control disabled
Starting Entropy Harvesting Daemon...
Starting Network Name Resolution...
Starting Update UTMP about System Boot/Shutdown...
Starting Network Time Synchronization...
Mounting RPC Pipe File System...
[ OK ] Started Rebuild Journal Catalog.
[ OK ] Started Entropy Harvesting Daemon.
[ OK ] Mounted RPC Pipe File System.
[ OK ] Started Update UTMP about System Boot/Shutdown.
[ OK ] Reached target rpc_pipefs.target.
Starting NFSv4 ID-name mapping service...
[ 27.113772] mv643xx_eth_port mv643xx_eth_port.1 eth1: link down
[ OK ] Started NFSv4 ID-name mapping service.
[ OK ] Started Network Name Resolution.
[ OK ] Reached target Network.
[ OK ] Reached target Network is Online.
[ OK ] Set up automount Automount Netty.
Starting NFS Mount Daemon...
[ OK ] Reached target Host and Network Name Lookups.
Starting NFS status monitor for NFSv2/3 locking....
[ OK ] Started Network Time Synchronization.
[ OK ] Reached target System Time Synchronized.
Starting RPC Bind...
[ OK ] Started Rebuild Dynamic Linker Cache.
[ OK ] Started RPC Bind.
Starting Update is Completed...
[ OK ] Started Update is Completed.
[ OK ] Reached target System Initialization.
[ OK ] Started Daily man-db regeneration.
[ OK ] Started Run periodic raid volume scrub.
[ OK ] Listening on D-Bus System Message Bus Socket.
[ OK ] Started Periodically optimize MySQL tables.
[ OK ] Started Periodically perform system backup.
[ OK ] Started Daily verification of password and group files.
[ OK ] Started Daily rotation of log files.
[ OK ] Started Daily Cleanup of Temporary Directories.
[ OK ] Reached target Timers.
[ OK ] Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
[ OK ] Reached target Sockets.
[ OK ] Reached target Basic System.
Starting Self Monitoring a…g Technology (SMART) Daemon...
[ OK ] Started devmon Service.
Starting Transmission BitTorrent Daemon...
[ OK ] Started Syncthing - Open S…Synchronization for syncthing.
Starting Avahi mDNS/DNS-SD Stack...
Starting Control WeMo Smart Plugs...
Starting APC UPS Power Control Daemon for Linux...
Starting Login Service...
Starting MariaDB 10.3.13 database server...
Starting GSSAPI Proxy Daemon...
[ OK ] Started D-Bus System Message Bus.
[ OK ] Started OpenSSH Daemon.
[ OK ] Started Civetweb Webserver.
Starting Set more agressive fan curve at boot...
Starting Permit User Sessions...
[ OK ] Started NFS Mount Daemon.
[ OK ] Started NFS status monitor for NFSv2/3 locking..
[ OK ] Started APC UPS Power Control Daemon for Linux.
[ OK ] Started GSSAPI Proxy Daemon.
Starting NFS server and services...
[ OK ] Started Login Service.
[ OK ] Started Set more agressive fan curve at boot.
[ OK ] Started Permit User Sessions.
[ OK ] Started Getty on tty1.
[ OK ] Started Serial Getty on ttyS0.
[ OK ] Reached target Login Prompts.
[ 32.798607] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory
[ 32.863424] NFSD: starting 90-second grace period (net f000001c)
[ OK ] Started Avahi mDNS/DNS-SD Stack.
[ OK ] Started NFS server and services.
Starting Notify NFS peers of a restart...
[ OK ] Started Notify NFS peers of a restart.
[ 33.767192] mv643xx_eth_port mv643xx_eth_port.1 eth1: link up, 1000 Mb/s, full duplex, flow control disabled
[ 34.872910] mv643xx_eth_port mv643xx_eth_port.1 eth1: link down
[ OK ] Started Self Monitoring an…ing Technology (SMART) Daemon.

Arch Linux 4.20.13-1-ARCH (ttyS0)
')

Perusing a diff of the kernel 4.20.13 and 5.0.1 console outputs shows a few interesting things:
    1. The 'rootfs image is not initramfs (junk in compressed archive); looks like an initrd'-message in v5.0.1
    2. The absence of the io schedulers noop, deadline and cfq in v5.0.1
    3. The 'RAMDISK: gzip image found at block 0'-message in v5.0.1
    4. The 'RAMDISK: incomplete write (565 != 32180)'-message in v5.0.1
    5. Running /init vs. /sbin/init for v4.20.13 and v5.0.1, respectively

Obviously, I would greatly appreciate any insights from other forum members :)
kriztioan
 
Posts: 51
Joined: Sat Apr 29, 2017 1:32 am

Re: Kernel 5.0.0-1 boot issues

Postby beijjjj » Mon Apr 29, 2019 12:17 pm

Hi, I have this problem on the same machine, could you tell me if the fix requires both the changing the kernel command line from using PARTUUID instead of LABEL and the uboot memory offset parameters, or is just the uboot change required?
The machine is currently running after I downgraded back to kernel 4.20.13 without any problems.
beijjjj
 
Posts: 2
Joined: Mon Apr 29, 2019 11:54 am

Re: Kernel 5.0.0-1 boot issues

Postby kriztioan » Tue Apr 30, 2019 12:02 am

@beijjjj Besides the U-Boot memory offsets you might also need to update your mkimage-command along the lines of:

$this->bbcode_second_pass_code('', 'mkimage -A arm -O linux -T ramdisk -C gzip -a 0x08004000 -n "Arch Linux ARM initrd" -d /boot/initramfs-linux.img /boot/uInitrd')

Although, I've read that the '-a' parameter is not needed for initrd.

The use of PARTUUID should not be required, but I left it in place as it was already there. If necessary, you can find the PARTUUID by running

$this->bbcode_second_pass_code('', 'blkid')
kriztioan
 
Posts: 51
Joined: Sat Apr 29, 2017 1:32 am

Re: Kernel 5.0.0-1 boot issues

Postby beijjjj » Tue Apr 30, 2019 6:37 am

@kriztioan I will give it a go and and let you know how it gos as soon as I am back in front of the machine, im away for a few days. Thanks for the information, very much appreciated!
beijjjj
 
Posts: 2
Joined: Mon Apr 29, 2019 11:54 am


Return to Marvell Kirkwood

Who is online

Users browsing this forum: No registered users and 13 guests