by wobble » Fri Dec 04, 2015 8:09 pm
Thanks for the pointer to the detailed docs in the u-boot post. That was very helpful!(Until now I didn't realize that I have to start both nc jobs simultaneously, so I never got to see the kernel output.)
Netconsole works now and so I was able to get logs from both a boot without initramfs (successful) and with initramfs (not successful).
First boot without initramfs (successful):
$this->bbcode_second_pass_code('', '
U-Boot 2015.10-1 (Nov 28 2015 - 01:55:29 -0700) Arch Linux ARM
Pogo E02
gcc (GCC) 5.2.0
GNU ld (GNU Binutils) 2.25.1
Hit any key to stop autoboot: 3 2 1 0
starting USB...
USB0: USB EHCI 1.00
scanning bus 0 for devices... 3 USB Device(s) found
scanning usb for storage devices... 1 Storage Device(s) found
USB device 0:
Device 0: Vendor: SanDisk Rev: 1.26 Prod: Cruzer Fit
Type: Removable Hard Disk
Capacity: 15267.0 MB = 14.9 GB (31266816 x 512)
... is now current device
usb found on device 0
Checking for: /boot/uEnv.txt ...
** File not found /boot/zImage **
4516016 bytes read in 312 ms (13.8 MiB/s)
loading /boot/dtbs/kirkwood-pogo_e02.dtb ...
** File not found /boot/dtbs/kirkwood-pogo_e02.dtb **
** File not found /boot/initramfs-linux.img **
## Booting kernel from Legacy Image at 00810000 ...
Image Name: Linux-4.3.0-1-ARCH
Image Type: ARM Linux Kernel Image (uncompressed)
Data Size: 4515952 Bytes = 4.3 MiB
Load Address: 00008000
Entry Point: 00008000
Verifying Checksum ... OK
[ 12.795448] console [netcon0] enabled
[ 12.799146] netconsole: network logging started
[ 12.803852] hctosys: unable to open rtc device (rtc0)
[ 12.811684] EXT4-fs (sda1): mounting ext3 file system using the ext4 subsystem
[ 12.832328] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null)
[ 12.840188] VFS: Mounted root (ext3 filesystem) on device 8:1.
[ 12.850579] devtmpfs: mounted
[ 12.854577] Freeing unused kernel memory: 296K (c0830000 - c087a000)
[ 13.333333] NET: Registered protocol family 10
[ 13.352371] ip_tables: (C) 2000-2006 Netfilter Core Team
[ 13.374290] random: systemd urandom read with 47 bits of entropy available
[ 13.399217] systemd[1]: systemd 228 running in system mode. (+PAM -AUDIT -SELINUX -IMA -APPARMOR +SMACK -SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN)
[ 13.417830] systemd[1]: Detected architecture arm.
[ 13.444427] systemd[1]: Set hostname to <pogo>.
[ 14.316358] systemd[1]: Reached target Swap.
[ 14.345617] systemd[1]: Created slice User and Session Slice.
[ 14.374071] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[ 14.404043] systemd[1]: Created slice System Slice.
[ 14.423176] systemd[1]: Reached target Encrypted Volumes.
[ 14.454096] systemd[1]: Created slice system-serial\x2dgetty.slice.
[ 14.483134] systemd[1]: Reached target Slices.
[ 14.503412] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[ 14.533302] systemd[1]: Listening on LVM2 metadata daemon socket.
[ 14.563313] systemd[1]: Listening on Device-mapper event daemon FIFOs.
[ 14.593371] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[ 14.623119] systemd[1]: Reached target Paths.
[ 14.671587] systemd[1]: Listening on Journal Socket (/dev/log).
[ 14.693436] systemd[1]: Listening on Journal Socket.
[ 14.717064] systemd[1]: Starting Setup Virtual Console...
[ 14.747764] systemd[1]: Mounting Debug File System...
[ 14.791198] systemd[1]: Mounting Temporary Directory...
[ 14.823443] systemd[1]: Listening on udev Kernel Socket.
[ 14.857761] systemd[1]: Starting Journal Service...
[ 14.887686] systemd[1]: Mounting POSIX Message Queue File System...
[ 14.923659] systemd[1]: Listening on Network Service Netlink Socket.
[ 14.953396] systemd[1]: Reached target Remote File Systems.
[ 15.012659] systemd[1]: Starting Load Kernel Modules...
[ 15.052183] systemd[1]: Starting Create list of required static device nodes for the current kernel...
[ 15.117088] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[ 15.151147] systemd[1]: Starting Remount Root and Kernel File Systems...
[ 15.183998] systemd[1]: Listening on udev Control Socket.
[ 15.197258] systemd[1]: Created slice system-getty.slice.
[ 15.204470] EXT4-fs (sda1): re-mounted. Opts: (null)
[ 15.310115] systemd[1]: Mounted POSIX Message Queue File System.
[ 15.333297] systemd[1]: Mounted Debug File System.
[ 15.353291] systemd[1]: Mounted Temporary Directory.
[ 15.385011] systemd[1]: Started Setup Virtual Console.
[ 15.416578] systemd[1]: Started Load Kernel Modules.
[ 15.445214] systemd[1]: Started Create list of required static device nodes for the current kernel.
[ 15.485012] systemd[1]: Started Remount Root and Kernel File Systems.
[ 15.575055] systemd[1]: Started Journal Service.
[ 16.502236] systemd-journald[118]: Received request to flush runtime journal from PID 1
[ 16.553610] random: nonblocking pool is initialized
[ 17.265853] MV-CESA:Could not register sha1 driver
[ 17.270751] MV-CESA:Could not register hmac-sha1 driver
[ 19.093813] nf_conntrack version 0.5.0 (3905 buckets, 15620 max)
')
Then I created the initramfs
$this->bbcode_second_pass_code('', '
> mkinitcpio -p linux-kirkwood
==> Building image from preset: /etc/mkinitcpio.d/linux-kirkwood.preset: 'default'
-> -k 4.3.0-1-ARCH -c /etc/mkinitcpio.conf -g /boot/initramfs-linux.img
==> Starting build: 4.3.0-1-ARCH
-> Running build hook: [base]
-> Running build hook: [udev]
-> Running build hook: [autodetect]
-> Running build hook: [modconf]
-> Running build hook: [block]
-> Running build hook: [filesystems]
-> Running build hook: [keyboard]
-> Running build hook: [fsck]
==> Generating module dependencies
==> Creating gzip-compressed initcpio image: /boot/initramfs-linux.img
==> Image generation successful
')
After that, I shut down, fscked the boot partition (to make sure it is clean in case the shutdown didn't properly unmount it - but it seems it does) and tried to boot:
$this->bbcode_second_pass_code('', '
U-Boot 2015.10-1 (Nov 28 2015 - 01:55:29 -0700) Arch Linux ARM
Pogo E02
gcc (GCC) 5.2.0
GNU ld (GNU Binutils) 2.25.1
Hit any key to stop autoboot: 3 2 1 0
starting USB...
USB0: USB EHCI 1.00
scanning bus 0 for devices... 3 USB Device(s) found
scanning usb for storage devices... 1 Storage Device(s) found
USB device 0:
Device 0: Vendor: SanDisk Rev: 1.26 Prod: Cruzer Fit
Type: Removable Hard Disk
Capacity: 15267.0 MB = 14.9 GB (31266816 x 512)
... is now current device
usb found on device 0
Checking for: /boot/uEnv.txt ...
** File not found /boot/zImage **
4516016 bytes read in 313 ms (13.8 MiB/s)
loading /boot/dtbs/kirkwood-pogo_e02.dtb ...
** File not found /boot/dtbs/kirkwood-pogo_e02.dtb **
2623741 bytes read in 277 ms (9 MiB/s)
Booting uImage with initrd
## Booting kernel from Legacy Image at 00810000 ...
Image Name: Linux-4.3.0-1-ARCH
Image Type: ARM Linux Kernel Image (uncompressed)
Data Size: 4515952 Bytes = 4.3 MiB
Load Address: 00008000
Entry Point: 00008000
Verifying Checksum ... OK
[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Initializing cgroup subsys cpuset
[ 0.000000] Initializing cgroup subsys cpu
[ 0.000000] Initializing cgroup subsys cpuacct
[ 0.000000] Linux version 4.3.0-1-ARCH (builduser@leming) (gcc version 5.2.0 (GCC) ) #1 PREEMPT Fri Nov 27 23:46:17 MST 2015
[ 0.000000] CPU: Feroceon 88FR131 [56251311] revision 1 (ARMv5TE), cr=0005397f
[ 0.000000] CPU: VIVT data cache, VIVT instruction cache
[ 0.000000] Machine: Pogoplug E02
[ 0.000000] debug: ignoring loglevel setting.
[ 0.000000] Memory policy: Data cache writeback
[ 0.000000] On node 0 totalpages: 65536
[ 0.000000] free_area_init_node: node 0, pgdat c08f6984, node_mem_map cfdba000
[ 0.000000] Normal zone: 576 pages used for memmap
[ 0.000000] Normal zone: 0 pages reserved
[ 0.000000] Normal zone: 65536 pages, LIFO batch:15
[ 0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[ 0.000000] pcpu-alloc: [0] 0
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 64960
[ 0.000000] Kernel command line: console=ttyS0,115200 mtdparts=orion_nand:1M(u-boot),-(rootfs) root=PARTUUID=00000000-01 rw rootwait ignore_loglevel netconsole=6665@10.10.10.3/eth0,6666@10.10.10.4/
[ 0.000000] PID hash table entries: 1024 (order: 0, 4096 bytes)
[ 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: 247092K/262144K available (6495K kernel code, 538K rwdata, 1852K rodata, 296K init, 725K bss, 15052K 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 - 0xff000000 ( 744 MB)
[ 0.000000] lowmem : 0xc0000000 - 0xd0000000 ( 256 MB)
[ 0.000000] modules : 0xbf000000 - 0xc0000000 ( 16 MB)
[ 0.000000] .text : 0xc0008000 - 0xc082f068 (8349 kB)
[ 0.000000] .init : 0xc0830000 - 0xc087a000 ( 296 kB)
[ 0.000000] .data : 0xc087a000 - 0xc090081c ( 539 kB)
[ 0.000000] .bss : 0xc090081c - 0xc09b5d58 ( 726 kB)
[ 0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[ 0.000000] Preemptible hierarchical RCU implementation.
[ 0.000000] Build-time adjustment of leaf fanout to 32.
[ 0.000000] NR_IRQS:114
[ 0.000013] sched_clock: 32 bits at 200MHz, resolution 5ns, wraps every 10737418237ns
[ 4.426681] clocksource: orion_clocksource: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 9556302233 ns
[ 4.426870] Console: colour dummy device 80x30
[ 4.426898] Calibrating delay loop... 1191.11 BogoMIPS (lpj=5955584)
[ 4.516766] pid_max: default: 32768 minimum: 301
[ 4.516856] Security Framework initialized
[ 4.516931] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 4.516949] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 4.517685] Initializing cgroup subsys io
[ 4.517730] Initializing cgroup subsys memory
[ 4.517797] Initializing cgroup subsys devices
[ 4.517826] Initializing cgroup subsys freezer
[ 4.517850] Initializing cgroup subsys net_cls
[ 4.517870] Initializing cgroup subsys bfqio
[ 4.517889] Initializing cgroup subsys perf_event
[ 4.517911] Initializing cgroup subsys net_prio
[ 4.517991] CPU: Testing write buffer coherency: ok
[ 4.518441] Setting up static identity map for 0x81e0 - 0x8238
[ 4.520317] devtmpfs: initialized
[ 4.522773] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 4.523204] pinctrl core: initialized pinctrl subsystem
[ 4.523987] NET: Registered protocol family 16
[ 4.524525] DMA: preallocated 256 KiB pool for atomic coherent allocations
[ 4.546771] cpuidle: using governor ladder
[ 4.576771] cpuidle: using governor menu
[ 4.576986] Kirkwood: MV88F6281-A0, TCLK=200000000.
[ 4.577013] Feroceon L2: Enabling L2
[ 4.577053] Feroceon L2: Cache support initialised.
[ 4.577812] initial MPP regs: 01111111 11113322 00001111 00100000 00000000 00000000 00000000
[ 4.577840] final MPP regs: 01111111 11113322 00001111 00000000 00000000 00000000 00000000
[ 4.618277] vgaarb: loaded
[ 4.618608] SCSI subsystem initialized
[ 4.618795] libata version 3.00 loaded.
[ 4.619123] usbcore: registered new interface driver usbfs
[ 4.619198] usbcore: registered new interface driver hub
[ 4.619260] usbcore: registered new device driver usb
[ 4.619431] pps_core: LinuxPPS API ver. 1 registered
[ 4.619441] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[ 4.619477] PTP clock support registered
[ 4.620592] clocksource: Switched to clocksource orion_clocksource
[ 4.659786] FS-Cache: Loaded
[ 4.672996] NET: Registered protocol family 2
[ 4.673789] TCP established hash table entries: 2048 (order: 1, 8192 bytes)
[ 4.673828] TCP bind hash table entries: 2048 (order: 1, 8192 bytes)
[ 4.673859] TCP: Hash tables configured (established 2048 bind 2048)
[ 4.673930] UDP hash table entries: 256 (order: 0, 4096 bytes)
[ 4.673953] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[ 4.674132] NET: Registered protocol family 1
[ 4.674450] RPC: Registered named UNIX socket transport module.
[ 4.674462] RPC: Registered udp transport module.
[ 4.674469] RPC: Registered tcp transport module.
[ 4.674476] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 4.674496] PCI: CLS 0 bytes, default 32
[ 4.674768] Trying to unpack rootfs image as initramfs...
[ 4.879569] Freeing initrd memory: 2564K (c1100000 - c1381000)
[ 4.881544] futex hash table entries: 256 (order: -1, 3072 bytes)
[ 4.894086] VFS: Disk quotas dquot_6.6.0
[ 4.894347] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 4.896373] FS-Cache: Netfs 'nfs' registered for caching
[ 4.897502] NFS: Registering the id_resolver key type
[ 4.897554] Key type id_resolver registered
[ 4.897562] Key type id_legacy registered
[ 4.897646] jffs2: version 2.2. (NAND) © 2001-2006 Red Hat, Inc.
[ 4.898743] fuse init (API version 7.23)
[ 4.901240] aufs 4.3-20151116
[ 4.906572] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
[ 4.906597] io scheduler noop registered
[ 4.906612] io scheduler deadline registered
[ 4.906896] io scheduler cfq registered
[ 4.907334] io scheduler bfq registered (default)
[ 4.907343] BFQ I/O-scheduler: v7r8
[ 4.907542] mv_xor mv_xor.0: Marvell shared XOR driver
[ 4.941817] mv_xor mv_xor.0: Marvell XOR (Registers Mode): ( xor cpy )
[ 4.942061] mv_xor mv_xor.1: Marvell shared XOR driver
[ 4.981772] mv_xor mv_xor.1: Marvell XOR (Registers Mode): ( xor cpy )
[ 4.987376] Serial: 8250/16550 driver, 2 ports, IRQ sharing disabled
[ 4.988482] console [ttyS0] disabled
[ 5.008613] serial8250.0: ttyS0 at MMIO 0xf1012000 (irq = 33, base_baud = 12500000) is a 16550A
[ 5.645514] console [ttyS0] enabled
[ 5.664154] brd: module loaded
[ 5.674360] loop: module loaded
[ 5.678632] nand: Could not find valid ONFI parameter page; aborting
[ 5.685167] nand: device found, Manufacturer ID: 0xad, Chip ID: 0xf1
[ 5.691570] nand: Hynix NAND 128MiB 3,3V 8-bit
[ 5.696031] nand: 128 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
[ 5.703660] Scanning device for bad blocks
[ 5.759255] Bad eraseblock 679 at 0x0000054e0000
[ 5.789976] 2 cmdlinepart partitions found on MTD device orion_nand
[ 5.796291] Creating 2 MTD partitions on "orion_nand":
[ 5.801474] 0x000000000000-0x000000100000 : "u-boot"
[ 5.806957] 0x000000100000-0x000008000000 : "rootfs"
[ 5.825446] libphy: orion_mdio_bus: probed
[ 5.829689] mv643xx_eth: MV-643xx 10/100/1000 ethernet driver version 1.4
[ 6.911521] mv643xx_eth_port mv643xx_eth_port.0 eth0: port 0 with MAC address 00:25:31:02:2b:40
[ 6.920473] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 6.927070] ehci-pci: EHCI PCI platform driver
[ 6.931615] ehci-orion: EHCI orion driver
[ 6.935724] orion-ehci orion-ehci.0: EHCI Host Controller
[ 6.941201] orion-ehci orion-ehci.0: new USB bus registered, assigned bus number 1
[ 6.948951] orion-ehci orion-ehci.0: irq 19, io mem 0xf1050000
[ 6.970622] orion-ehci orion-ehci.0: USB 2.0 started, EHCI 1.00
[ 6.977522] hub 1-0:1.0: USB hub found
[ 6.981376] hub 1-0:1.0: 1 port detected
[ 6.985923] usbcore: registered new interface driver uas
[ 6.991454] usbcore: registered new interface driver usb-storage
[ 6.997546] usbcore: registered new interface driver ums-alauda
[ 7.003600] usbcore: registered new interface driver ums-cypress
[ 7.009691] usbcore: registered new interface driver ums-datafab
[ 7.015801] usbcore: registered new interface driver ums_eneub6250
[ 7.022085] usbcore: registered new interface driver ums-freecom
[ 7.028167] usbcore: registered new interface driver ums-isd200
[ 7.034188] usbcore: registered new interface driver ums-jumpshot
[ 7.040356] usbcore: registered new interface driver ums-karma
[ 7.046293] usbcore: registered new interface driver ums-onetouch
[ 7.052513] usbcore: registered new interface driver ums-realtek
[ 7.058601] usbcore: registered new interface driver ums-sddr09
[ 7.064621] usbcore: registered new interface driver ums-sddr55
[ 7.070637] usbcore: registered new interface driver ums-usbat
[ 7.076940] mousedev: PS/2 mouse device common for all mice
[ 7.300621] usb 1-1: new high-speed USB device number 2 using orion-ehci
[ 7.453210] hub 1-1:1.0: USB hub found
[ 7.457339] hub 1-1:1.0: 4 ports detected
[ 7.740614] usb 1-1.1: new high-speed USB device number 3 using orion-ehci
[ 7.852488] usb-storage 1-1.1:1.0: USB Mass Storage device detected
[ 7.859099] scsi host0: usb-storage 1-1.1:1.0
[ 8.090614] rtc-mv rtc-mv: internal RTC not ticking
[ 8.095671] i2c /dev entries driver
[ 8.099802] sdhci: Secure Digital Host Controller Interface driver
[ 8.106037] sdhci: Copyright(c) Pierre Ossman
[ 8.110484] sdhci-pltfm: SDHCI platform and OF driver helper
[ 8.116736] ledtrig-cpu: registered to indicate activity on CPUs
[ 8.122961] usbcore: registered new interface driver r8712u
[ 8.128807] gre: GRE over IPv4 demultiplexor driver
[ 8.133774] NET: Registered protocol family 17
[ 8.138379] Key type dns_resolver registered
[ 8.143792] registered taskstats version 1
[ 8.169040] Key type encrypted registered
[ 8.173781] netpoll: netconsole: local port 6665
[ 8.178422] netpoll: netconsole: local IPv4 address 10.10.10.3
[ 8.184483] netpoll: netconsole: interface 'eth0'
[ 8.189208] netpoll: netconsole: remote port 6666
[ 8.193987] netpoll: netconsole: remote IPv4 address 10.10.10.4
[ 8.199934] netpoll: netconsole: remote ethernet address ff:ff:ff:ff:ff:ff
[ 8.206888] netpoll: netconsole: device eth0 not up yet, forcing it
[ 8.871684] scsi 0:0:0:0: Direct-Access SanDisk Cruzer Fit 1.26 PQ: 0 ANSI: 5
[ 8.881499] sd 0:0:0:0: [sda] 31266816 512-byte logical blocks: (16.0 GB/14.9 GiB)
[ 8.900620] sd 0:0:0:0: [sda] Write Protect is off
[ 8.905457] sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
[ 8.911262] sd 0:0:0:0: Attached scsi generic sg0 type 0
[ 8.920845] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[ 8.946481] sda: sda1 sda2
[ 8.953739] sd 0:0:0:0: [sda] Attached SCSI removable disk
[ 11.820923] mv643xx_eth_port mv643xx_eth_port.0 eth0: link up, 1000 Mb/s, full duplex, flow control disabled
[ 11.843181] console [netcon0] enabled
[ 11.846880] netconsole: network logging started
[ 11.851588] hctosys: unable to open rtc device (rtc0)
[ 11.858637] Freeing unused kernel memory: 296K (c0830000 - c087a000)
[ 11.918507] random: systemd-tmpfile urandom read with 24 bits of entropy available
[ 92.048544] random: nonblocking pool is initialized
')
So it seems to stop right before systemd is started in the successful boot. Any ideas?