Welcome! Log In Create A New Profile

Advanced

Kernel crash during boot 5.4.101-oxnas-tld-1

Posted by beRHav 
Kernel crash during boot 5.4.101-oxnas-tld-1
May 04, 2021 03:44AM
A few days ago I found my Pogoplug classic (no PCI) and was curious what I could do with it. On this forum I read that there is an enthusiastic group that embraces these low power devices. After reading the necessary forum threads, I decided to connect a serial console and a SATA disk. Following the instructions and several evenings of hobbies, the system now boots kernel version 4.1.0.

The system has been upgraded from wheezy, jessie, stretch to buster without errors. The system booted kernel 4.1.0 which is loaded from SATA and the rootfs is also on the SATA disk. Nothing is connected to the USB ports.

The last step should be to update the kernel following the steps listed at https://forum.doozan.com/read.php?2,16044
It seems to me that while the new kernel tries to mount the rootfs, it reads an empty config parameter.
Please append a correct "root =" boot option; here are the available partitions:

Does anyone have an idea where and how I can configure this?

The bootlog and the environment settings are below:

U-Boot SPL 2013.10-ga72eb8f-dirty (Feb 19 2014 - 15:21:12)
  Boot device: SATA
Attempting to set PLLA to 800 MHz ...
  plla_ctrl0 : 0000030a
  plla_ctrl1 : 00400000
  plla_ctrl2 : 007f0068
  plla_ctrl3 : 00000193

PLLA Set
Bus 0: OK
  Device 0:
reading bootargs.bin
reading u-boot.img
reading u-boot.img


U-Boot 2013.10-ga72eb8f-dirty (Feb 19 2014 - 15:21:12) for OXNAS

DRAM:  128 MiB
IDE:   Bus 0: OK
  Device 0: Model: ST98823AS  Firm: 3.04 Ser#: 5PK0ZQQV
            Type: Hard Disk
            Supports 48-bit addressing
            Capacity: 76319.0 MB = 74.5 GB (156301488 x 512)
NAND:  128 MiB
reading u-boot.env
In:    serial
Out:   serial
Err:   serial
Net:   mii0
## Error: "safeboot" not defined
Hit any key to stop autoboot:  0
5502688 bytes read in 1193 ms (4.4 MiB/s)
8618 bytes read in 24 ms (350.6 KiB/s)
magic:                  0xd00dfeed
totalsize:              0x21aa (8618)
off_dt_struct:          0x38
off_dt_strings:         0x1e80
off_mem_rsvmap:         0x28
version:                17
last_comp_version:      16
boot_cpuid_phys:        0x0
size_dt_strings:        0x32a
size_dt_struct:         0x1e48
number mem_rsv:         0x0

## Booting kernel from Legacy Image at 60000000 ...
   Image Name:   Linux-5.4.101-oxnas-tld-1
   Image Type:   ARM Linux Kernel Image (uncompressed)
   Data Size:    5502624 Bytes = 5.2 MiB
   Load Address: 60008000
   Entry Point:  60008000
   Verifying Checksum ... OK
## Flattened Device Tree blob at 61000000
   Booting using the fdt blob at 0x61000000
   Loading Kernel Image ... OK
   Loading Device Tree to 67e26000, end 67e2b1a9 ... OK

Starting kernel ...

Uncompressing Linux... done, booting the kernel.
[    0.000000][    T0] Booting Linux on physical CPU 0x0
[    0.000000][    T0] Linux version 5.4.101-oxnas-tld-1 (root@tldDebian) (gcc version 8.3.0 (Debian 8.3.0-6)) #1.0 SMP PREEMPT Sat Feb 27 20:19:53 PST 2021
[    0.000000][    T0] CPU: ARMv6-compatible processor [410fb025] revision 5 (ARMv7), cr=00c5787d
[    0.000000][    T0] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000][    T0] OF: fdt: Machine model: Pogoplug V3 Classic
[    0.000000][    T0] printk: bootconsole [earlycon0] enabled
[    0.000000][    T0] Memory policy: Data cache writealloc
[    0.000000][    T0] percpu: Embedded 15 pages/cpu s30508 r8192 d22740 u61440
[    0.000000][    T0] Built 1 zonelists, mobility grouping on.  Total pages: 32512
[    0.000000][    T0] Kernel command line: earlyprintk
[    0.000000][    T0] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes, linear)
[    0.000000][    T0] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    0.000000][    T0] mem auto-init: stack:off, heap alloc:on, heap free:off
[    0.000000][    T0] Memory: 116968K/131072K available (8192K kernel code, 232K rwdata, 2088K rodata, 1024K init, 260K bss, 14104K reserved, 0K cma-reserved)
[    0.000000][    T0] random: get_random_u32 called from cache_random_seq_create+0x78/0x12c with crng_init=0
[    0.000000][    T0] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000][    T0] rcu: Preemptible hierarchical RCU implementation.
[    0.000000][    T0]  Tasks RCU enabled.
[    0.000000][    T0] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[    0.000000][    T0] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000][    T0] FPGA IRQ chip 0 "interrupt-controller" @ (ptrval), 32 irqs, parent IRQ: 16
[    0.000020][    T0] sched_clock: 24 bits at 390kHz, resolution 2560ns, wraps every 21474835200ns
[    0.008780][    T0] clocksource: oxnas_rps_clocksource_timer: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 19112603332 ns
[    0.020119][    T0] timer_oxnas_rps: Registered clocksource rate 390625Hz
[    0.026887][    T0] timer_oxnas_rps: Registered clock event rate 6250000Hz prescaler 0 period 62500
[    0.036047][    T0] GIC: PPI13 is secure or misconfigured
[    0.041464][    T0] GIC: PPI13 is secure or misconfigured
[    0.047047][    T0] Console: colour dummy device 80x30
[    0.052218][    T0] printk: console [tty0] enabled
[    0.057000][    T0] printk: bootconsole [earlycon0] disabled
[    0.000000][    T0] Booting Linux on physical CPU 0x0
[    0.000000][    T0] Linux version 5.4.101-oxnas-tld-1 (root@tldDebian) (gcc version 8.3.0 (Debian 8.3.0-6)) #1.0 SMP PREEMPT Sat Feb 27 20:19:53 PST 2021
[    0.000000][    T0] CPU: ARMv6-compatible processor [410fb025] revision 5 (ARMv7), cr=00c5787d
[    0.000000][    T0] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000][    T0] OF: fdt: Machine model: Pogoplug V3 Classic
[    0.000000][    T0] printk: bootconsole [earlycon0] enabled
[    0.000000][    T0] Memory policy: Data cache writealloc
[    0.000000][    T0] percpu: Embedded 15 pages/cpu s30508 r8192 d22740 u61440
[    0.000000][    T0] Built 1 zonelists, mobility grouping on.  Total pages: 32512
[    0.000000][    T0] Kernel command line: earlyprintk
[    0.000000][    T0] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes, linear)
[    0.000000][    T0] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    0.000000][    T0] mem auto-init: stack:off, heap alloc:on, heap free:off
[    0.000000][    T0] Memory: 116968K/131072K available (8192K kernel code, 232K rwdata, 2088K rodata, 1024K init, 260K bss, 14104K reserved, 0K cma-reserved)
[    0.000000][    T0] random: get_random_u32 called from cache_random_seq_create+0x78/0x12c with crng_init=0
[    0.000000][    T0] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000][    T0] rcu: Preemptible hierarchical RCU implementation.
[    0.000000][    T0]  Tasks RCU enabled.
[    0.000000][    T0] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[    0.000000][    T0] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000][    T0] FPGA IRQ chip 0 "interrupt-controller" @ (ptrval), 32 irqs, parent IRQ: 16
[    0.000020][    T0] sched_clock: 24 bits at 390kHz, resolution 2560ns, wraps every 21474835200ns
[    0.008780][    T0] clocksource: oxnas_rps_clocksource_timer: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 19112603332 ns
[    0.020119][    T0] timer_oxnas_rps: Registered clocksource rate 390625Hz
[    0.026887][    T0] timer_oxnas_rps: Registered clock event rate 6250000Hz prescaler 0 period 62500
[    0.036047][    T0] GIC: PPI13 is secure or misconfigured
[    0.041464][    T0] GIC: PPI13 is secure or misconfigured
[    0.047047][    T0] Console: colour dummy device 80x30
[    0.052218][    T0] printk: console [tty0] enabled
[    0.057000][    T0] printk: bootconsole [earlycon0] disabled
[    0.062725][    T0] Calibrating delay loop... 319.48 BogoMIPS (lpj=1597440)
[    0.111585][    T0] pid_max: default: 32768 minimum: 301
[    0.112043][    T0] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.112097][    T0] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.112299][    T0] *** VALIDATE tmpfs ***
[    0.113349][    T0] *** VALIDATE proc ***
[    0.113635][    T0] *** VALIDATE cgroup1 ***
[    0.113679][    T0] *** VALIDATE cgroup2 ***
[    0.113830][    T0] CPU: Testing write buffer coherency: ok
[    0.116039][    T1] Setting up static identity map for 0x60100000 - 0x60100038
[    0.116367][    T1] rcu: Hierarchical SRCU implementation.
[    0.117283][    T1] smp: Bringing up secondary CPUs ...
[    0.118456][    T0] GIC: PPI13 is secure or misconfigured
[    0.171868][    T1] smp: Brought up 1 node, 2 CPUs
[    0.171937][    T1] SMP: Total of 2 processors activated (638.97 BogoMIPS).
[    0.172951][    T1] devtmpfs: initialized
[    0.178439][    T1] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.178539][    T1] futex hash table entries: 512 (order: 2, 16384 bytes, linear)
[    0.178726][    T1] prandom: seed boundary self test passed
[    0.183319][    T1] prandom: 100 self tests passed
[    0.183352][    T1] pinctrl core: initialized pinctrl subsystem
[    0.184862][    T1] NET: Registered protocol family 16
[    0.186703][    T1] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.189473][    T1] cpuidle: using governor ladder
[    0.189596][    T1] cpuidle: using governor menu
[    7.183969][    T1] vgaarb: loaded
[    7.184847][    T1] SCSI subsystem initialized
[    7.185664][    T1] usbcore: registered new interface driver usbfs
[    7.185804][    T1] usbcore: registered new interface driver hub
[    7.185914][    T1] usbcore: registered new device driver usb
[    7.186224][    T1] pps_core: LinuxPPS API ver. 1 registered
[    7.186270][    T1] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    7.186342][    T1] PTP clock support registered
[    7.187878][    T1] clocksource: Switched to clocksource oxnas_rps_clocksource_timer
[    7.187985][    T1] *** VALIDATE bpf ***
[    7.188474][    T1] *** VALIDATE ramfs ***
[    7.208192][    T1] NET: Registered protocol family 2
[    7.209323][    T1] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
[    7.209436][    T1] TCP established hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    7.209515][    T1] TCP bind hash table entries: 1024 (order: 1, 8192 bytes, linear)
[    7.209584][    T1] TCP: Hash tables configured (established 1024 bind 1024)
[    7.209832][    T1] UDP hash table entries: 256 (order: 1, 8192 bytes, linear)
[    7.209927][    T1] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes, linear)
[    7.210278][    T1] NET: Registered protocol family 1
[    7.211420][    T1] RPC: Registered named UNIX socket transport module.
[    7.211486][    T1] RPC: Registered udp transport module.
[    7.211522][    T1] RPC: Registered tcp transport module.
[    7.211558][    T1] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    7.211604][    T1] PCI: CLS 0 bytes, default 32
[    7.213276][    T1] Initialise system trusted keyrings
[    7.213386][    T1] Key type blacklist registered
[    7.213777][    T1] workingset: timestamp_bits=30 max_order=15 bucket_order=0
[    7.225996][    T1] zbud: loaded
[    7.231621][    T1] NFS: Registering the id_resolver key type
[    7.231713][    T1] Key type id_resolver registered
[    7.231749][    T1] Key type id_legacy registered
[    7.231800][    T1] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    7.231846][    T1] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[    7.233103][    T1] fuse: init (API version 7.31)
[    7.233344][    T1] *** VALIDATE fuse ***
[    7.233395][    T1] *** VALIDATE fuse ***
[    7.234270][    T1] orangefs_debugfs_init: called with debug mask: :none: :0:
[    7.234624][    T1] orangefs_init: module version upstream loaded
[    7.292026][    T1] Key type asymmetric registered
[    7.292106][    T1] Asymmetric key parser 'x509' registered
[    7.292221][    T1] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)
[    7.294689][    T1] Serial: 8250/16550 driver, 1 ports, IRQ sharing disabled
[    7.296445][    T1] 44200000.serial: ttyS0 at MMIO 0x44200000 (irq = 55, base_baud = 390625) is a 16550A
[    7.922531][    T1] printk: console [ttyS0] enabled
[    7.942126][    T1] loop: module loaded
[    7.948172][    T1] scsi host0: sata_oxnas
[    7.952924][    T1] ata1: SATA max UDMA/133 irq 57
[    7.957926][   T75] sata_oxnas: resetting SATA core
[    8.028236][    T1] Could not find a valid ONFI parameter page, trying bit-wise majority to recover it
[    8.037626][    T1] ONFI parameter recovery failed, aborting
[    8.043371][    T1] nand: device found, Manufacturer ID: 0xad, Chip ID: 0xf1
[    8.050455][    T1] nand: Hynix NAND 128MiB 3,3V 8-bit
[    8.055595][    T1] nand: 128 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
[    8.063910][    T1] Scanning device for bad blocks
[    8.152568][    T1] 2 fixed-partitions partitions found on MTD device 41000000.nand-controller
[    8.161226][    T1] Creating 2 MTD partitions on "41000000.nand-controller":
[    8.168317][    T1] 0x000000000000-0x000000e00000 : "boot"
[    8.176448][    T1] 0x000000e00000-0x000008000000 : "data"
[    8.185694][    T1] libphy: Fixed MDIO Bus: probed
[    8.190881][    T1] oxnas-dwmac 40400000.ethernet: IRQ eth_lpi not found
[    8.197757][    T1] oxnas-dwmac 40400000.ethernet: PTP uses main clock
[    8.204346][    T1] oxnas-dwmac 40400000.ethernet: no reset control found
[    8.278343][    T1] oxnas-dwmac 40400000.ethernet: User ID: 0x12, Synopsys ID: 0x35
[    8.286028][    T1] oxnas-dwmac 40400000.ethernet:   DWMAC1000
[    8.291834][    T1] oxnas-dwmac 40400000.ethernet: DMA HW capability register supported
[    8.299888][    T1] oxnas-dwmac 40400000.ethernet: RX Checksum Offload Engine supported
[    8.307932][    T1] oxnas-dwmac 40400000.ethernet: COE Type 2
[    8.313676][    T1] oxnas-dwmac 40400000.ethernet: TX Checksum insertion supported
[    8.321280][    T1] oxnas-dwmac 40400000.ethernet: Wake-Up On Lan supported
[    8.328284][    T1] oxnas-dwmac 40400000.ethernet: Enhanced/Alternate descriptors
[    8.335772][    T1] oxnas-dwmac 40400000.ethernet: Enabled extended descriptors
[    8.343114][    T1] oxnas-dwmac 40400000.ethernet: Ring mode enabled
[    8.349521][    T1] oxnas-dwmac 40400000.ethernet: Enable RX Mitigation via HW Watchdog Timer
[    8.368064][    T1] libphy: stmmac: probed
[    8.372193][    T1] mdio_bus stmmac-0:00: attached PHY driver [unbound] (mii_bus:phy_addr=stmmac-0:00, irq=POLL)
[    8.382487][    T1] mdio_bus stmmac-0:03: attached PHY driver [unbound] (mii_bus:phy_addr=stmmac-0:03, irq=POLL)
[    8.393799][    T1] PPP generic driver version 2.4.2
[    8.399255][    T1] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    8.406489][    T1] ehci-pci: EHCI PCI platform driver
[    8.467937][   T75] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[    8.476241][   T75] ata1.00: ATA-7: ST98823AS, 3.04, max UDMA/133
[    8.482449][   T75] ata1.00: 156301488 sectors, multi 0: LBA48 NCQ (depth 0/32)
[    8.491545][   T75] ata1.00: configured for UDMA/133
[    8.497159][    T7] scsi 0:0:0:0: Direct-Access     ATA      ST98823AS        3.04 PQ: 0 ANSI: 5
[    8.507806][   T54] sd 0:0:0:0: [sda] 156301488 512-byte logical blocks: (80.0 GB/74.5 GiB)
[    8.516933][   T54] sd 0:0:0:0: [sda] Write Protect is off
[    8.522780][   T54] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    8.543168][   T54]  sda: sda1 sda2 sda3
[    8.550625][   T54] sd 0:0:0:0: [sda] Attached SCSI disk
[    8.617945][    T1] oxnas-ehci 40200100.ehci: EHCI Host Controller
[    8.624194][    T1] oxnas-ehci 40200100.ehci: new USB bus registered, assigned bus number 1
[    8.632852][    T1] oxnas-ehci 40200100.ehci: irq 52, io mem 0x40200100
[    8.667932][    T1] oxnas-ehci 40200100.ehci: USB 2.0 started, EHCI 1.00
[    8.675033][    T1] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.04
[    8.684067][    T1] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    8.692042][    T1] usb usb1: Product: EHCI Host Controller
[    8.697612][    T1] usb usb1: Manufacturer: Linux 5.4.101-oxnas-tld-1 ehci_hcd
[    8.704865][    T1] usb usb1: SerialNumber: 40200100.ehci
[    8.711326][    T1] hub 1-0:1.0: USB hub found
[    8.715880][    T1] hub 1-0:1.0: 2 ports detected
[    8.721853][    T1] usbcore: registered new interface driver cdc_wdm
[    8.728483][    T1] usbcore: registered new interface driver usb-storage
[    8.735418][    T1] usbcore: registered new interface driver usbserial_generic
[    8.742760][    T1] usbserial: USB Serial support registered for generic
[    8.749941][    T1] mousedev: PS/2 mouse device common for all mice
[    8.756364][    T1] i2c /dev entries driver
[    8.762083][    T1] hidraw: raw HID events driver (C) Jiri Kosina
[    8.768504][    T1] usbcore: registered new interface driver usbhid
[    8.774778][    T1] usbhid: USB HID core driver
[    8.780917][    T1] NET: Registered protocol family 10
[    8.787773][    T1] Segment Routing with IPv6
[    8.792368][    T1] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[    8.801221][    T1] NET: Registered protocol family 17
[    8.806781][    T1] Key type dns_resolver registered
[    8.812431][    T1] registered taskstats version 1
[    8.817239][    T1] Loading compiled-in X.509 certificates
[    8.823047][    T1] zswap: loaded using pool lzo/zbud
[    8.829089][    T1] Key type ._fscrypt registered
[    8.833812][    T1] Key type .fscrypt registered
[    8.858181][    T1] Key type big_key registered
[    8.866913][    T1] Key type encrypted registered
[    8.875568][    T1] hctosys: unable to open rtc device (rtc0)
[    8.882321][    T1] VFS: Cannot open root device "(null)" or unknown-block(0,0): error -6
[    8.890644][    T1] Please append a correct "root=" boot option; here are the available partitions:
[    8.899758][    T1] 1f00           14336 mtdblock0
[    8.899763][    T1]  (driver?)
[    8.907688][    T1] 1f01          116736 mtdblock1
[    8.907694][    T1]  (driver?)
[    8.915660][    T1] 0800        78150744 sda
[    8.915665][    T1]  driver: sd
[    8.923220][    T1]   0801           16384 sda1 00008000-01
[    8.923225][    T1]
[    8.931018][    T1]   0802         4194304 sda2 00008000-02
[    8.931023][    T1]
[    8.939066][    T1]   0803        73939030 sda3 00008000-03
[    8.939072][    T1]
[    8.946833][    T1] Kernel panic - not syncing: VFS: Unable to mount root fs on unknown-block(0,0)
[    8.955783][    T1] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 5.4.101-oxnas-tld-1 #1.0
[    8.963681][    T1] Hardware name: Generic DT based system
[    8.969182][    T1] [<c01095c8>] (unwind_backtrace) from [<c0105b0c>] (show_stack+0x10/0x14)
[    8.977625][    T1] [<c0105b0c>] (show_stack) from [<c0863a14>] (dump_stack+0xb4/0xd0)
[    8.985546][    T1] [<c0863a14>] (dump_stack) from [<c085a3f0>] (panic+0x10c/0x30c)
[    8.993208][    T1] [<c085a3f0>] (panic) from [<c0c013cc>] (mount_block_root+0x1e0/0x2a4)
[    9.001387][    T1] [<c0c013cc>] (mount_block_root) from [<c0c01588>] (mount_root+0xf8/0x138)
[    9.009909][    T1] [<c0c01588>] (mount_root) from [<c0c016f8>] (prepare_namespace+0x130/0x194)
[    9.018606][    T1] [<c0c016f8>] (prepare_namespace) from [<c0863bc8>] (kernel_init+0x8/0x110)
[    9.027212][    T1] [<c0863bc8>] (kernel_init) from [<c01010e8>] (ret_from_fork+0x14/0x2c)
[    9.035461][    T1] Exception stack(0xc783ffb0 to 0xc783fff8)
[    9.041198][    T1] ffa0:                                     00000000 00000000 00000000 00000000
[    9.050058][    T1] ffc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[    9.058915][    T1] ffe0: 00000000 00000000 00000000 00000000 00000013 00000000
[    9.066229][    C1] CPU1: stopping
[    9.069655][    C1] CPU: 1 PID: 0 Comm: swapper/1 Not tainted 5.4.101-oxnas-tld-1 #1.0
[    9.077557][    C1] Hardware name: Generic DT based system
[    9.083054][    C1] [<c01095c8>] (unwind_backtrace) from [<c0105b0c>] (show_stack+0x10/0x14)
[    9.091491][    C1] [<c0105b0c>] (show_stack) from [<c0863a14>] (dump_stack+0xb4/0xd0)
[    9.099407][    C1] [<c0863a14>] (dump_stack) from [<c01081dc>] (handle_IPI+0x198/0x1e0)
[    9.107496][    C1] [<c01081dc>] (handle_IPI) from [<c050e3b0>] (gic_handle_irq+0x7c/0x8c)
[    9.115758][    C1] [<c050e3b0>] (gic_handle_irq) from [<c0101a6c>] (__irq_svc+0x6c/0xa8)
[    9.123921][    C1] Exception stack(0xc7871f60 to 0xc7871fa8)
[    9.129666][    C1] 1f60: 00000a74 00000000 c7ee94b4 00000000 00000000 ffffe000 c0d03c88 00000002
[    9.138531][    C1] 1f80: c0d03ccc 00000000 00000000 c0d03c68 00000000 c7871fb0 c0103574 c0103578
[    9.147389][    C1] 1fa0: 60000013 ffffffff
[    9.151575][    C1] [<c0101a6c>] (__irq_svc) from [<c0103578>] (arch_cpu_idle+0x28/0x30)
[    9.159662][    C1] [<c0103578>] (arch_cpu_idle) from [<c013ced0>] (do_idle+0x1f4/0x254)
[    9.167746][    C1] [<c013ced0>] (do_idle) from [<c013d210>] (cpu_startup_entry+0x18/0x1c)
[    9.176007][    C1] [<c013d210>] (cpu_startup_entry) from [<60102428>] (0x60102428)
[    9.183667][    T1] ---[ end Kernel panic - not syncing: VFS: Unable to mount root fs on unknown-block(0,0) ]---

The environment:
U-Boot 2013.10-ga72eb8f-dirty (Feb 19 2014 - 15:21:12) for OXNAS

DRAM:  128 MiB
IDE:   Bus 0: OK
  Device 0: Model: ST98823AS  Firm: 3.04 Ser#: 5PK0ZQQV
            Type: Hard Disk
            Supports 48-bit addressing
            Capacity: 76319.0 MB = 74.5 GB (156301488 x 512)
NAND:  128 MiB
reading u-boot.env
In:    serial
Out:   serial
Err:   serial
Net:   mii0
## Error: "safeboot" not defined
Hit any key to stop autoboot:  0
OX820 # printenv
SET_dtb=fdt addr ${fdtaddr} ; fdt header
autoload=no
baudrate=115200
bootcmd=run ideboot
bootdelay=3
bootnew=bootm 60000000 - 61000000
bootold=bootm 60000000
console=console=ttyS0,115200n8
ethact=mii0
ethaddr=74:F0:6D:6A:1D:17
ext2_dtb=ext2load ide 0:2 61000000 /boot/dts/ox820-pogoplug-classic.dtb
ext2_ideboot=run ext2_uimage ; run ext2_dtb ; run SET_dtb ; run bootnew
ext2_uimage=ext2load ide 0:2 60000000 /boot/uImage
ext4_dtb=ext4load ide 0:2 61000000 /boot/dts/ox820-pogoplug-classic.dtb
ext4_ideboot=run ext4_uimage ; run ext4_dtb ; run SET_dtb ; run bootnew
ext4_uimage=ext4load ide 0:2 60000000 /boot/uImage
fdtaddr=61000000
filesize=1b79
ideboot=run ext4_ideboot
idebootold=${loadold} ;${bootold}
imgaddr=60000000
ipaddr=10.0.0.71
keypressed=1
mtdids=nand0=41000000.nand
mtdparts=mtdparts=41000000.nand:14m(boot),-(data)
netcon=echo switch to net console;setenv stderr nc;setenv stdin nc;setenv stdout nc;echo switch to net console
preboot=run safeboot
serialcon=echo switch to serial console;setenv stderr serial;setenv stdin serial;setenv stdout serial;echo switch to serial console
stderr=serial
stdin=serial
stdout=serial
updboot=bootp;tftp 60000000 u-boot.img;ide write 60000000 400 400

Environment size: 1267/16380 bytes
OX820 #
Re: Kernel crash during boot 5.4.101-oxnas-tld-1
May 04, 2021 04:29AM
beRHav,

> U-Boot SPL 2013.10-ga72eb8f-dirty (Feb 19 2014 -
> 15:21:12)

> U-Boot 2013.10-ga72eb8f-dirty (Feb 19 2014 -
> 15:21:12) for OXNAS

You are running an older u-boot version. Which is OK. Nothing wrong about this.

> OX820 # printenv
> SET_dtb=fdt addr ${fdtaddr} ; fdt header
> autoload=no
> baudrate=115200
> bootcmd=run ideboot
> bootdelay=3
> bootnew=bootm 60000000 - 61000000
> bootold=bootm 60000000
> console=console=ttyS0,115200n8
> ethact=mii0
> ethaddr=74:F0:6D:6A:1D:17
> ext2_dtb=ext2load ide 0:2 61000000
> /boot/dts/ox820-pogoplug-classic.dtb
> ext2_ideboot=run ext2_uimage ; run ext2_dtb ; run
> SET_dtb ; run bootnew
> ext2_uimage=ext2load ide 0:2 60000000
> /boot/uImage
> ext4_dtb=ext4load ide 0:2 61000000
> /boot/dts/ox820-pogoplug-classic.dtb
> ext4_ideboot=run ext4_uimage ; run ext4_dtb ; run
> SET_dtb ; run bootnew
> ext4_uimage=ext4load ide 0:2 60000000
> /boot/uImage
> fdtaddr=61000000
> filesize=1b79
> ideboot=run ext4_ideboot
> idebootold=${loadold} ;${bootold}
> imgaddr=60000000
> ipaddr=10.0.0.71
> keypressed=1
> mtdids=nand0=41000000.nand
> mtdparts=mtdparts=41000000.nand:14m(boot),-(data)
> netcon=echo switch to net console;setenv stderr
> nc;setenv stdin nc;setenv stdout nc;echo switch to
> net console
> preboot=run safeboot
> serialcon=echo switch to serial console;setenv
> stderr serial;setenv stdin serial;setenv stdout
> serial;echo switch to serial console
> stderr=serial
> stdin=serial
> stdout=serial
> updboot=bootp;tftp 60000000 u-boot.img;ide write
> 60000000 400 400
> 
> Environment size: 1267/16380 bytes
> OX820 #


But you envs are quite odd. Nothing like what I had created when I started building and releasing this u-boot here (my version is a fork of kref's GitHub). So that must have been built from kref's GitHub, or somebody else fork.

Now the problem was a bit obsfucated because you are not booting with uInitrd, either.

ext2_ideboot=run ext2_uimage ; run ext2_dtb ; run SET_dtb ; run bootnew

But that was OK. Even what you have is not the standard way of booting Debian kernel with this u-boot.

============

Now the real problem. We are going to fix this so you can boot again.

The bootargs is missing from the environment. I guess it was inadvertenly removed.

In your configuration (boot with SATA) the bootargs must be set to boot with root device /dev/sda1.

So at u-boot prompt:

setenv set_bootargs 'setenv bootargs console=ttyS0,115200 root=/dev/sda1 rootdelay=10 $mtdparts earlyprintk=serial'
setenv bootcmd 'run set_bootargs; run ideboot'

and then

boot

If you still have problem, we'll work on that tomorrow.

-bodhi
===========================
Forum Wiki
bodhi's corner



Edited 1 time(s). Last edit at 05/04/2021 04:37AM by bodhi.
Re: Kernel crash during boot 5.4.101-oxnas-tld-1
May 05, 2021 03:01AM
Bodhi,

Thank you for your quick response and correct analysis. The Pogo now boots with the latest kernel. Chapeaux! Awesome work!
About the U-Boot, I understood from the note 1 of https://forum.doozan.com/read.php?3,16017 that the last U-Boot would not be suitable for a full boot from SATA.
The configuration is basically taken from Schnee's script.
The first partition is 2048 sectors and contains the magic code. The 2nd stage boot partition was created with mkfs.ext2 instead of mkfs.ext4 because the dtb file could not be found. The third partition contains the rootfs. The fourth partition the rest of the disk space.
Interested in posting a full bootlog?
What environment variables should I use to avoid these kinds of issues with upgrades in the near future?
Re: Kernel crash during boot 5.4.101-oxnas-tld-1
May 05, 2021 04:33PM
beRHav,

> The configuration is basically taken from Schnee's
> script.

Ah, I see.

> Interested in posting a full bootlog?

Yes, it is always good to see the full log.

> What environment variables should I use to avoid
> these kinds of issues with upgrades in the near
> future?

If the setup was from Schnee's then I'd say it was pretty well done.

However, there must have been some assumption that Schnee had that no longer work for the new kernel.

Espcially the missing bootargs, if you have not messed with the envs at all, then I think I know why Schnee's old setup works but not with new kernel. I need to to a look at his script to confirm. Could you post the link to that post (I want to add that to the Wiki and forgot).

-bodhi
===========================
Forum Wiki
bodhi's corner
Re: Kernel crash during boot 5.4.101-oxnas-tld-1
May 07, 2021 06:35AM
Bodhi,

The link to the post from Schnee: https://forum.doozan.com/read.php?2,16044,23122#msg-23122.

The only line I had to change was line 107 of the script from:
mkfs.ext4 -L rootfs $HDD"2"

to
mkfs.ext2 -L rootfs $HDD"2"

Then it loaded the device tree and old kernel without problems. With the embedded script in the tar it could not find/read these files.

My current env vars:
OX820 # printenv
SET_dtb=fdt addr ${fdtaddr} ; fdt header
autoload=no
baudrate=115200
bootcmd=run set_bootargs; run ideboot
bootdelay=3
bootnew=bootm 60000000 - 61000000
bootold=bootm 60000000
console=console=ttyS0,115200n8
ethact=mii0
ethaddr=00:25:31:02:87:12
ext2_dtb=ext2load ide 0:2 61000000 /boot/dts/ox820-pogoplug-classic.dtb
ext2_ideboot=run ext2_uimage ; run ext2_dtb ; run SET_dtb ; run bootnew
ext2_uimage=ext2load ide 0:2 60000000 /boot/uImage
ext4_dtb=ext4load ide 0:2 61000000 /boot/dts/ox820-pogoplug-classic.dtb
ext4_ideboot=run ext4_uimage ; run ext4_dtb ; run SET_dtb ; run bootnew
ext4_uimage=ext4load ide 0:2 60000000 /boot/uImage
fdtaddr=61000000
filesize=1b79
ideboot=run ext4_ideboot
idebootold=${loadold} ;${bootold}
imgaddr=60000000
ipaddr=10.0.0.71
keypressed=1
mtdids=nand0=41000000.nand
mtdparts=mtdparts=41000000.nand:14m(boot),-(data)
netcon=echo switch to net console;setenv stderr nc;setenv stdin nc;setenv stdout nc;echo switch to net console
preboot=run safeboot
serialcon=echo switch to serial console;setenv stderr serial;setenv stdin serial;setenv stdout serial;echo switch to serial console
set_bootargs=setenv bootargs console=ttyS0,115200 root=/dev/sda2 rootdelay=10 $mtdparts earlyprintk=serial
stderr=serial
stdin=serial
stdout=serial
updboot=bootp;tftp 60000000 u-boot.img;ide write 60000000 400 400

Environment size: 1394/16380 bytes
OX820 #


The full bootlog:
=~=~=~=~=~=~=~=~=~=~=~= PuTTY log 2021.05.07 13:10:16 =~=~=~=~=~=~=~=~=~=~=~=

U-Boot SPL 2013.10-ga72eb8f-dirty (Feb 19 2014 - 15:21:12)
  Boot device: SATA
Attempting to set PLLA to 800 MHz ...
  plla_ctrl0 : 0000030a
  plla_ctrl1 : 00400000
  plla_ctrl2 : 007f0068
  plla_ctrl3 : 00000193

PLLA Set
Bus 0: OK 
  Device 0: 
reading bootargs.bin
reading u-boot.img
reading u-boot.img


U-Boot 2013.10-ga72eb8f-dirty (Feb 19 2014 - 15:21:12) for OXNAS

DRAM:  128 MiB
IDE:   Bus 0: OK 
  Device 0: Model: ST98823AS  Firm: 3.04 Ser#: 5PK0ZQQV
            Type: Hard Disk
            Supports 48-bit addressing
            Capacity: 76319.0 MB = 74.5 GB (156301488 x 512)
NAND:  128 MiB
reading u-boot.env
In:    serial
Out:   serial
Err:   serial
Net:   mii0
## Error: "safeboot" not defined
Hit any key to stop autoboot:  3  2  1  0 
5502688 bytes read in 1173 ms (4.5 MiB/s)
8618 bytes read in 34 ms (247.1 KiB/s)
magic:0xd00dfeed
totalsize:0x21aa (8618)
off_dt_struct:0x38
off_dt_strings:0x1e80
off_mem_rsvmap:0x28
version:17
last_comp_version:16
boot_cpuid_phys:0x0
size_dt_strings:0x32a
size_dt_struct:0x1e48
number mem_rsv:0x0

## Booting kernel from Legacy Image at 60000000 ...
   Image Name:   Linux-5.4.101-oxnas-tld-1
   Image Type:   ARM Linux Kernel Image (uncompressed)
   Data Size:    5502624 Bytes = 5.2 MiB
   Load Address: 60008000
   Entry Point:  60008000
   Verifying Checksum ... OK
## Flattened Device Tree blob at 61000000
   Booting using the fdt blob at 0x61000000
   Loading Kernel Image ... OK
   Loading Device Tree to 67e26000, end 67e2b1a9 ... OK

Starting kernel ...

Uncompressing Linux... done, booting the kernel.
[    0.000000][    T0] Booting Linux on physical CPU 0x0
[    0.000000][    T0] Linux version 5.4.101-oxnas-tld-1 (root@tldDebian) (gcc version 8.3.0 (Debian 8.3.0-6)) #1.0 SMP PREEMPT Sat Feb 27 20:19:53 PST 2021
[    0.000000][    T0] CPU: ARMv6-compatible processor [410fb025] revision 5 (ARMv7), cr=00c5787d
[    0.000000][    T0] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000][    T0] OF: fdt: Machine model: Pogoplug V3 Classic
[    0.000000][    T0] printk: bootconsole [earlycon0] enabled
[    0.000000][    T0] Memory policy: Data cache writealloc
[    0.000000][    T0] percpu: Embedded 15 pages/cpu s30508 r8192 d22740 u61440
[    0.000000][    T0] Built 1 zonelists, mobility grouping on.  Total pages: 32512
[    0.000000][    T0] Kernel command line: console=ttyS0,115200 root=/dev/sda2 rootdelay=10 mtdparts=41000000.nand:14m(boot),-(data) earlyprintk=serial
[    0.000000][    T0] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes, linear)
[    0.000000][    T0] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    0.000000][    T0] mem auto-init: stack:off, heap alloc:on, heap free:off
[    0.000000][    T0] Memory: 116968K/131072K available (8192K kernel code, 232K rwdata, 2088K rodata, 1024K init, 260K bss, 14104K reserved, 0K cma-reserved)
[    0.000000][    T0] random: get_random_u32 called from cache_random_seq_create+0x78/0x12c with crng_init=0
[    0.000000][    T0] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000][    T0] rcu: Preemptible hierarchical RCU implementation.
[    0.000000][    T0] Tasks RCU enabled.
[    0.000000][    T0] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[    0.000000][    T0] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000][    T0] FPGA IRQ chip 0 "interrupt-controller" @ (ptrval), 32 irqs, parent IRQ: 16
[    0.000017][    T0] sched_clock: 24 bits at 390kHz, resolution 2560ns, wraps every 21474835200ns
[    0.008783][    T0] clocksource: oxnas_rps_clocksource_timer: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 19112603332 ns
[    0.020119][    T0] timer_oxnas_rps: Registered clocksource rate 390625Hz
[    0.026882][    T0] timer_oxnas_rps: Registered clock event rate 6250000Hz prescaler 0 period 62500
[    0.036044][    T0] GIC: PPI13 is secure or misconfigured
[    0.041456][    T0] GIC: PPI13 is secure or misconfigured
[    0.047037][    T0] Console: colour dummy device 80x30
[    0.052267][    T0] Calibrating delay loop... 319.48 BogoMIPS (lpj=1597440)
[    0.108472][    T0] pid_max: default: 32768 minimum: 301
[    0.114196][    T0] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.122204][    T0] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.130767][    T0] *** VALIDATE tmpfs ***
[    0.135920][    T0] *** VALIDATE proc ***
[    0.140126][    T0] *** VALIDATE cgroup1 ***
[    0.144432][    T0] *** VALIDATE cgroup2 ***
[    0.148807][    T0] CPU: Testing write buffer coherency: ok
[    0.156577][    T1] Setting up static identity map for 0x60100000 - 0x60100038
[    0.164136][    T1] rcu: Hierarchical SRCU implementation.
[    0.170498][    T1] smp: Bringing up secondary CPUs ...
[    0.176936][    T0] GIC: PPI13 is secure or misconfigured
[    0.231864][    T1] smp: Brought up 1 node, 2 CPUs
[    0.242101][    T1] SMP: Total of 2 processors activated (638.97 BogoMIPS).
[    0.250019][    T1] devtmpfs: initialized
[    0.259842][    T1] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.270415][    T1] futex hash table entries: 512 (order: 2, 16384 bytes, linear)
[    0.278051][    T1] prandom: seed boundary self test passed
[    0.288066][    T1] prandom: 100 self tests passed
[    0.292864][    T1] pinctrl core: initialized pinctrl subsystem
[    0.300175][    T1] NET: Registered protocol family 16
[    0.307274][    T1] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.318072][    T1] cpuidle: using governor ladder
[    0.323074][    T1] cpuidle: using governor menu
[    7.325235][    T1] vgaarb: loaded
[    7.329456][    T1] SCSI subsystem initialized
[    7.334760][    T1] usbcore: registered new interface driver usbfs
[    7.340999][    T1] usbcore: registered new interface driver hub
[    7.347343][    T1] usbcore: registered new device driver usb
[    7.353413][    T1] pps_core: LinuxPPS API ver. 1 registered
[    7.359047][    T1] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    7.368903][    T1] PTP clock support registered
[    7.375168][    T1] clocksource: Switched to clocksource oxnas_rps_clocksource_timer
[    7.383370][    T1] *** VALIDATE bpf ***
[    7.387783][    T1] *** VALIDATE ramfs ***
[    7.412083][    T1] NET: Registered protocol family 2
[    7.418270][    T1] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
[    7.427412][    T1] TCP established hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    7.435814][    T1] TCP bind hash table entries: 1024 (order: 1, 8192 bytes, linear)
[    7.443545][    T1] TCP: Hash tables configured (established 1024 bind 1024)
[    7.450798][    T1] UDP hash table entries: 256 (order: 1, 8192 bytes, linear)
[    7.458094][    T1] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes, linear)
[    7.466058][    T1] NET: Registered protocol family 1
[    7.472335][    T1] RPC: Registered named UNIX socket transport module.
[    7.479029][    T1] RPC: Registered udp transport module.
[    7.484410][    T1] RPC: Registered tcp transport module.
[    7.489832][    T1] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    7.496993][    T1] PCI: CLS 0 bytes, default 32
[    7.503319][    T1] Initialise system trusted keyrings
[    7.508574][    T1] Key type blacklist registered
[    7.513653][    T1] workingset: timestamp_bits=30 max_order=15 bucket_order=0
[    7.531607][    T1] zbud: loaded
[    7.540011][    T1] NFS: Registering the id_resolver key type
[    7.545858][    T1] Key type id_resolver registered
[    7.550714][    T1] Key type id_legacy registered
[    7.555456][    T1] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    7.562816][    T1] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[    7.571097][    T1] fuse: init (API version 7.31)
[    7.576028][    T1] *** VALIDATE fuse ***
[    7.580032][    T1] *** VALIDATE fuse ***
[    7.584878][    T1] orangefs_debugfs_init: called with debug mask: :none: :0:
[    7.592394][    T1] orangefs_init: module version upstream loaded
[    7.652746][    T1] Key type asymmetric registered
[    7.657589][    T1] Asymmetric key parser 'x509' registered
[    7.663216][    T1] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)
[    7.673697][    T1] Serial: 8250/16550 driver, 1 ports, IRQ sharing disabled
[    7.682444][    T1] printk: console [ttyS0] disabled
[    7.687621][    T1] 44200000.serial: ttyS0 at MMIO 0x44200000 (irq = 55, base_baud = 390625) is a 16550A
[    7.697221][    T1] printk: console [ttyS0] enabled
[    7.697221][    T1] printk: console [ttyS0] enabled
[    7.706961][    T1] printk: bootconsole [earlycon0] disabled
[    7.706961][    T1] printk: bootconsole [earlycon0] disabled
[    7.732802][    T1] loop: module loaded
[    7.738872][    T1] scsi host0: sata_oxnas
[    7.743626][    T1] ata1: SATA max UDMA/133 irq 57
[    7.748610][   T75] sata_oxnas: resetting SATA core
[    7.815539][    T1] Could not find a valid ONFI parameter page, trying bit-wise majority to recover it
[    7.824883][    T1] ONFI parameter recovery failed, aborting
[    7.830602][    T1] nand: device found, Manufacturer ID: 0xad, Chip ID: 0xf1
[    7.837660][    T1] nand: Hynix NAND 128MiB 3,3V 8-bit
[    7.842782][    T1] nand: 128 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
[    7.851061][    T1] Scanning device for bad blocks
[    7.939212][    T1] 2 fixed-partitions partitions found on MTD device 41000000.nand-controller
[    7.947832][    T1] Creating 2 MTD partitions on "41000000.nand-controller":
[    7.954859][    T1] 0x000000000000-0x000000e00000 : "boot"
[    7.963000][    T1] 0x000000e00000-0x000008000000 : "data"
[    7.972129][    T1] libphy: Fixed MDIO Bus: probed
[    7.977287][    T1] oxnas-dwmac 40400000.ethernet: IRQ eth_lpi not found
[    7.984107][    T1] oxnas-dwmac 40400000.ethernet: PTP uses main clock
[    7.990671][    T1] oxnas-dwmac 40400000.ethernet: no reset control found
[    8.065582][    T1] oxnas-dwmac 40400000.ethernet: User ID: 0x12, Synopsys ID: 0x35
[    8.073233][    T1] oxnas-dwmac 40400000.ethernet: DWMAC1000
[    8.079022][    T1] oxnas-dwmac 40400000.ethernet: DMA HW capability register supported
[    8.087042][    T1] oxnas-dwmac 40400000.ethernet: RX Checksum Offload Engine supported
[    8.095029][    T1] oxnas-dwmac 40400000.ethernet: COE Type 2
[    8.100797][    T1] oxnas-dwmac 40400000.ethernet: TX Checksum insertion supported
[    8.108375][    T1] oxnas-dwmac 40400000.ethernet: Wake-Up On Lan supported
[    8.115432][    T1] oxnas-dwmac 40400000.ethernet: Enhanced/Alternate descriptors
[    8.122887][    T1] oxnas-dwmac 40400000.ethernet: Enabled extended descriptors
[    8.130216][    T1] oxnas-dwmac 40400000.ethernet: Ring mode enabled
[    8.136586][    T1] oxnas-dwmac 40400000.ethernet: Enable RX Mitigation via HW Watchdog Timer
[    8.155258][    T1] libphy: stmmac: probed
[    8.159367][    T1] mdio_bus stmmac-0:00: attached PHY driver [unbound] (mii_bus:phy_addr=stmmac-0:00, irq=POLL)
[    8.169617][    T1] mdio_bus stmmac-0:03: attached PHY driver [unbound] (mii_bus:phy_addr=stmmac-0:03, irq=POLL)
[    8.180828][    T1] PPP generic driver version 2.4.2
[    8.186224][    T1] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    8.193418][    T1] ehci-pci: EHCI PCI platform driver
[    8.255239][   T75] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[    8.263500][   T75] ata1.00: ATA-7: ST98823AS, 3.04, max UDMA/133
[    8.269683][   T75] ata1.00: 156301488 sectors, multi 0: LBA48 NCQ (depth 0/32)
[    8.278725][   T75] ata1.00: configured for UDMA/133
[    8.284334][    T7] scsi 0:0:0:0: Direct-Access     ATA      ST98823AS        3.04 PQ: 0 ANSI: 5
[    8.295485][   T54] sd 0:0:0:0: [sda] 156301488 512-byte logical blocks: (80.0 GB/74.5 GiB)
[    8.303946][   T54] sd 0:0:0:0: [sda] Write Protect is off
[    8.309739][   T54] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    8.334850][   T54]  sda: sda1 sda2 sda3
[    8.342054][   T54] sd 0:0:0:0: [sda] Attached SCSI disk
[    8.405248][    T1] oxnas-ehci 40200100.ehci: EHCI Host Controller
[    8.411453][    T1] oxnas-ehci 40200100.ehci: new USB bus registered, assigned bus number 1
[    8.420047][    T1] oxnas-ehci 40200100.ehci: irq 52, io mem 0x40200100
[    8.455226][    T1] oxnas-ehci 40200100.ehci: USB 2.0 started, EHCI 1.00
[    8.462295][    T1] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.04
[    8.471285][    T1] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    8.479216][    T1] usb usb1: Product: EHCI Host Controller
[    8.484769][    T1] usb usb1: Manufacturer: Linux 5.4.101-oxnas-tld-1 ehci_hcd
[    8.491993][    T1] usb usb1: SerialNumber: 40200100.ehci
[    8.498467][    T1] hub 1-0:1.0: USB hub found
[    8.503011][    T1] hub 1-0:1.0: 2 ports detected
[    8.509045][    T1] usbcore: registered new interface driver cdc_wdm
[    8.515609][    T1] usbcore: registered new interface driver usb-storage
[    8.522503][    T1] usbcore: registered new interface driver usbserial_generic
[    8.529825][    T1] usbserial: USB Serial support registered for generic
[    8.536983][    T1] mousedev: PS/2 mouse device common for all mice
[    8.543380][    T1] i2c /dev entries driver
[    8.549073][    T1] hidraw: raw HID events driver (C) Jiri Kosina
[    8.555461][    T1] usbcore: registered new interface driver usbhid
[    8.561704][    T1] usbhid: USB HID core driver
[    8.567859][    T1] NET: Registered protocol family 10
[    8.574694][    T1] Segment Routing with IPv6
[    8.579276][    T1] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[    8.588003][    T1] NET: Registered protocol family 17
[    8.593541][    T1] Key type dns_resolver registered
[    8.599280][    T1] registered taskstats version 1
[    8.604060][    T1] Loading compiled-in X.509 certificates
[    8.609843][    T1] zswap: loaded using pool lzo/zbud
[    8.615912][    T1] Key type ._fscrypt registered
[    8.620605][    T1] Key type .fscrypt registered
[    8.644352][    T1] Key type big_key registered
[    8.653276][    T1] Key type encrypted registered
[    8.662003][    T1] hctosys: unable to open rtc device (rtc0)
[    8.668577][    T1] Waiting 10 sec before mounting root device...
[    8.875230][   T21] usb 1-1: new high-speed USB device number 2 using oxnas-ehci
[    9.077084][   T21] usb 1-1: New USB device found, idVendor=05e3, idProduct=0608, bcdDevice=77.64
[    9.086000][   T21] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    9.093834][   T21] usb 1-1: Product: USB2.0 Hub
[    9.099665][   T21] hub 1-1:1.0: USB hub found
[    9.104591][   T21] hub 1-1:1.0: 4 ports detected
[   19.089267][    T1] EXT4-fs (sda2): mounted filesystem without journal. Opts: (null)
[   19.097162][    T1] VFS: Mounted root (ext4 filesystem) readonly on device 8:2.
[   19.160010][    T1] devtmpfs: mounted
[   19.165358][    T1] Freeing unused kernel memory: 1024K
[   19.171960][    T1] Checked W+X mappings: passed, no W+X pages found
[   19.178339][    T1] Run /sbin/init as init process
[   20.644945][    C0] random: fast init done
[   20.858004][    T1] systemd[1]: System time before build time, advancing clock.
[   21.132551][    T1] systemd[1]: Inserted module 'autofs4'
[   21.275102][    T1] systemd[1]: systemd 241 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)
[   21.298275][    T1] systemd[1]: Detected architecture arm.

Welcome to Debian GNU/Linux 10 (buster)!

[   21.355230][    T1] systemd[1]: Set hostname to <debian>.
[   22.928737][    T1] systemd[1]: File /lib/systemd/system/systemd-journald.service:12 configures an IP firewall (IPAddressDeny=any), but the local system does not support BPF/cgroup based firewalling.
[   22.946664][    T1] systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.)
[   23.577840][    T1] systemd[1]: /lib/systemd/system/smbd.service:9: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/smbd.pid  /run/samba/smbd.pid; please update the unit file accordingly.
[   23.658560][    T1] systemd[1]: /lib/systemd/system/nmbd.service:9: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/nmbd.pid  /run/samba/nmbd.pid; please update the unit file accordingly.
[   23.866734][    T1] systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid  /run/rpc.statd.pid; please update the unit file accordingly.
[   24.004677][    T1] random: systemd: uninitialized urandom read (16 bytes read)
[   24.031252][    T1] random: systemd: uninitialized urandom read (16 bytes read)
[   24.038781][    T1] systemd[1]: Reached target System Time Synchronized.
[  OK  ] Reached target System Time Synchronized.
[   24.075640][    T1] random: systemd: uninitialized urandom read (16 bytes read)
[   24.085376][    T1] systemd[1]: Created slice system-systemd\x2dfsck.slice.
[  OK  ] Created slice system-systemd\x2dfsck.slice.
[  OK  ] Listening on initctl Compatibility Named Pipe.
[  OK  ] Created slice system-getty.slice.
[  OK  ] Started Forward Password Ruests to Wall Directory Watch.
[  OK  ] Listening on RPCbind Server Activation Socket.
[  OK  ] Listening on fsck to fsckd communication Socket.
[  OK  ] Created slice system-serial\x2dgetty.slice.
[  OK  ] Listening on udev Control Socket.
[  OK  ] Set up automount Arbitrarys File System Automount Point.
[  OK  ] Created slice User and Session Slice.
[  OK  ] Reached target Slices.
[  OK  ] Started Dispatch Password ts to Console Directory Watch.
[  OK  ] Reached target Paths.
[  OK  ] Reached target Local Encrypted Volumes.
[  OK  ] Listening on Journal Socket.
         Mounting RPC Pipe File System...
         Mounting Kernel Debug File System...
         Starting File System Check on Root Device...
         Starting Create list of reodes for the current kernel...
         Starting Load Kernel Modules...
         Mounting NFSD configuration filesystem...
         Mounting POSIX Message Queue File System...
[  OK  ] Listening on udev Kernel Socket.
         Starting udev Coldplug all Devices...
[  OK  ] Listening on Journal Socket (/dev/log).
         Starting Journal Service...
[  OK  ] Mounted RPC Pipe File System.
[  OK  ] Mounted Kernel Debug File System.
[  OK  ] Started Create list of req nodes for the current kernel.
[  OK  ] Started Load Kernel Modules.
[  OK  ] Mounted NFSD configuration filesystem.
[  OK  ] Mounted POSIX Message Queue File System.
[  OK  ] Started File System Check Daemon to report status.
         Mounting FUSE Control File System...
         Starting Apply Kernel Variables...
         Starting pNFS block layout mapping daemon...
[  OK  ] Mounted FUSE Control File System.
[  OK  ] Started File System Check on Root Device.
[  OK  ] Started pNFS block layout mapping daemon.
[  OK  ] Started Journal Service.
[  OK  ] Started Apply Kernel Variables.
         Starting Remount Root and Kernel File Systems...
[   25.909207][  T133] __nand_correct_data: uncorrectable ECC error
[   25.915486][  T133] __nand_correct_data: uncorrectable ECC error
[   25.921505][  T133] blk_update_request: I/O error, dev mtdblock0, sector 512 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 0
[   25.935490][  T133] __nand_correct_data: uncorrectable ECC error
[   25.941493][  T133] __nand_correct_data: uncorrectable ECC error
[   25.948904][  T133] blk_update_request: I/O error, dev mtdblock0, sector 512 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 0
[   25.960680][  T133] Buffer I/O error on dev mtdblock0, logical block 64, async page read
[  OK  ] Started udev Coldplug all Devices.
         Starting Helper to synchronize boot up for ifupdown...
[   26.532925][  T133] EXT4-fs (sda2): re-mounted. Opts: errors=remount-ro
[  OK  ] Started Remount Root and Kernel File Systems.
         Activating swap /swapfile1...
         Starting Flush Journal to Persistent Storage...
         Starting Load/Save Random Seed...
         Starting Create System Users...
[   26.966520][  T122] systemd-journald[122]: Received request to flush runtime journal from PID 1
[  OK  ] Started Flush Journal to Persistent Storage.
[  OK  ] Started Load/Save Random Seed.
[  OK  ] Started Create System Users.
         Starting Create Static Device Nodes in /dev...
[  OK  ] Started Create Static Device Nodes in /dev.
[  OK  ] Reached target Local File Systems (Pre).
         Starting udev Kernel Device Manager...
[  OK  ] Started udev Kernel Device Manager.
[  OK  ] Found device /dev/ttyS0.
[   29.455662][  T137] Adding 524284k swap on /swapfile1.  Priority:-2 extents:7 across:835580k FS
[  OK  ] Activated swap /swapfile1.
[  OK  ] Started Helper to synchronize boot up for ifupdown.
[  OK  ] Found device ST98823AS home.
         Starting File System Check on /dev/sda3...
[  OK  ] Reached target Swap.
         Mounting /tmp...
[  OK  ] Mounted /tmp.

Checking in progress on 1 disk (0.0% complete)
[   33.091737][    C0] random: crng init done
[   33.095838][    C0] random: 7 urandom warning(s) missed due to ratelimiting

Checking in progress on 1 disk (2.2% complete)

Checking in progress on 1 disk (100.0% complete)

Checking in progress on 1 disk (100.0% complete)

Checking in progress on 1 disk (100.0% complete)

Checking in progress on 1 disk (100.0% complete)

Checking in progress on 0 disks (100.0% complete)
[  OK  ] Started File System Check on /dev/sda3.
         Mounting /home...
[  101.528980][  T174] ext2 filesystem being mounted at /home supports timestamps until 2038 (0x7fffffff)
[  OK  ] Mounted /home.
[  OK  ] Reached target Local File Systems.
         Starting Raise network interfaces...
         Starting Create Volatile Files and Directories...
         Starting Preprocess NFS configuration...
[  OK  ] Started Preprocess NFS configuration.
         Starting NFSv4 ID-name mapping service...
[  OK  ] Reached target NFS client services.
[  OK  ] Started NFSv4 ID-name mapping service.
[  OK  ] Started Create Volatile Files and Directories.
         Starting RPC bind portmap service...
         Starting Update UTMP about System Boot/Shutdown...
[  OK  ] Started Update UTMP about System Boot/Shutdown.
[  OK  ] Reached target System Initialization.
[  OK  ] Listening on D-Bus System Message Bus Socket.
[  OK  ] Started Daily Cleanup of Temporary Directories.
[  OK  ] Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
[  OK  ] Reached target Sockets.
[  OK  ] Reached target Basic System.
         Starting Login Service...
         Starting Avahi mDNS/DNS-SD Stack...
[  OK  ] Started D-Bus System Message Bus.
[  OK  ] Started Daily man-db regeneration.
[  OK  ] Started Daily apt download activities.
[  OK  ] Started Daily apt upgrade and clean activities.
[  OK  ] Reached target Timers.
[  OK  ] Started RPC bind portmap service.
[  103.765442][  T213] oxnas-dwmac 40400000.ethernet eth0: PHY [stmmac-0:00] driver [Generic PHY]
[  103.781757][  T213] oxnas-dwmac 40400000.ethernet eth0: No Safety Features support found
[  103.790021][  T213] oxnas-dwmac 40400000.ethernet eth0: IEEE 1588-2008 Advanced Timestamp supported
[  OK  ] Reached target RPC Port Mapper.
[  103.805073][  T213] oxnas-dwmac 40400000.ethernet eth0: registered PTP clock

[  103.813450][  T213] oxnas-dwmac 40400000.ethernet eth0: configuring for phy/rgmii link mode
[  OK  ] Started Login Service.
[  OK  ] Started Avahi mDNS/DNS-SD Stack.
[  108.995801][  T123] oxnas-dwmac 40400000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx
[  109.004935][  T123] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[  OK  ] Started Raise network interfaces.
[  OK  ] Reached target Network.
         Starting OpenBSD Secure Shell server...
[  OK  ] Reached target Network is Online.
         Starting /etc/rc.local Compatibility...
         Starting Network Time Service...
         Starting Samba NMB Daemon...
         Starting NFS Mount Daemon...
[  OK  ] Started /etc/rc.local Compatibility.
[  OK  ] Started OpenBSD Secure Shell server.
[  OK  ] Started Network Time Service.
[  OK  ] Started NFS Mount Daemon.
         Starting NFS server and services...
[  116.479559][  T258] NFSD: Using UMH upcall client tracking operations.
[  116.486208][  T258] NFSD: starting 90-second grace period (net f0000129)
[  OK  ] Started NFS server and services.
[  OK  ] Reached target Remote File Systems (Pre).
[  OK  ] Reached target Remote File Systems.
         Starting LSB: Starts syslogd...
         Starting Permit User Sessions...
[  OK  ] Started Permit User Sessions.
[  OK  ] Started Serial Getty on ttyS0.
[  OK  ] Started Getty on tty1.
[  OK  ] Reached target Login Prompts.
[  OK  ] Started LSB: Starts syslogd.
         Starting LSB: Starts klogd...
[  OK  ] Started LSB: Starts klogd.
[  OK  ] Started Samba NMB Daemon.
         Starting Samba SMB Daemon...
[  OK  ] Started Samba SMB Daemon.
[  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.


Debian GNU/Linux 10 debian ttyS0

debian login: 
                                                   
Re: Kernel crash during boot 5.4.101-oxnas-tld-1
May 07, 2021 05:09PM
beRHav,

Cool! everything looks good.

Quote

The only line I had to change was line 107 of the script from:
mkfs.ext4 -L rootfs $HDD"2"

to
mkfs.ext2 -L rootfs $HDD"2"

I'll take a look at the script to see why the bootargs was missing.

BTW, you should use at least Ext3, not Ext2. Power outage will cause problem for Ext2. But Ext3 or Ext4 will be fine when that happens.

-bodhi
===========================
Forum Wiki
bodhi's corner
Re: Kernel crash during boot 5.4.101-oxnas-tld-1
May 08, 2021 11:09AM
Quote

BTW, you should use at least Ext3, not Ext2. Power outage will cause problem for Ext2. But Ext3 or Ext4 will be fine when that happens.

Yeah, I fully agree. Yesterday, I've already done the migration and attached a 2nd drive also with ext4. Boottime is joyfully improved!

The only thing not clear to me is:
         Starting Remount Root and Kernel File Systems...
[   25.909207][  T133] __nand_correct_data: uncorrectable ECC error
[   25.915486][  T133] __nand_correct_data: uncorrectable ECC error
[   25.921505][  T133] blk_update_request: I/O error, dev mtdblock0, sector 512 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 0
[   25.935490][  T133] __nand_correct_data: uncorrectable ECC error
[   25.941493][  T133] __nand_correct_data: uncorrectable ECC error
[   25.948904][  T133] blk_update_request: I/O error, dev mtdblock0, sector 512 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 0
[   25.960680][  T133] Buffer I/O error on dev mtdblock0, logical block 64, async page read

To my limited knowledge the NAND isn't touched at all during the SATA boot.
So why does it try to read the vars from NAND mtd0?
Re: Kernel crash during boot 5.4.101-oxnas-tld-1
May 08, 2021 04:34PM
beRHav,


> To my limited knowledge the NAND isn't touched at
> all during the SATA boot.

True.

> So why does it try to read the vars from NAND
> mtd0?

This is because systemd tried to scan all block devices. There is no harm in the scan, it just makes the log noisy. If you boot with sysvinit (the default) you should not see this.

To see for yourself, after the system is up and running, do

blkid
and then check dmesg

dmesg | tail

==============

However, this could be fixed in the kernel config option. I thought I did configure the kernel to recognize mtd block devices, but perhaps I've missed some switches. I'll make a note to check the next time I build this kernel.

-bodhi
===========================
Forum Wiki
bodhi's corner
Author:

Subject:


Spam prevention:
Please, enter the code that you see below in the input field. This is for blocking bots that try to post this form automatically. If the code is hard to read, then just try to guess it right. If you enter the wrong code, a new image is created and you get another chance to enter it right.
Message: