Modify

Opened 6 years ago

Closed 5 years ago

Last modified 4 years ago

#11238 closed defect (fixed)

NSLU2 -- first boot after flash sometimes hangs

Reported by: Mike Brady <mikebrady@…> Owned by: developers
Priority: normal Milestone: Barrier Breaker 14.07
Component: packages Version: Trunk
Keywords: Cc:

Description

I'm using OpenWrt on a Linksys NSLU2 -- old but still a great little box.

The problem I'm having with trunk recently is that sometimes the very first reboot after flashing will hang.

The image is a basic one with a few additions -- avahi, usb-modeswitch, posix messages, htop, screen.

When the NSLU2 is booting, it flashes its lights and then turns them all off except for the Ethernet activity LED, and then boots. However, sometimes on the first boot after flashing, it hangs up at the point where all the LEDs stay lit.

Sometimes, if I "make clean" first, it will boot, but often it won't. Changing the build by adding or removing a package and rebuilding sometimes helps. I'm wondering if there's a miscalculated checksum or something.

Any thoughts or suggestions about how to debug gratefully received...

FWIW, here is the kernel log of a time when it did boot

[    0.000000] Linux version 2.6.37.6 (mike@ubuntu) (gcc version 4.6.3 20120201 (prerelease) (Linaro GCC 4.6-2012.02) ) #4 Fri Apr 6 15:24:26 IST 2012
[    0.000000] CPU: XScale-IXP42x Family [690541f2] revision 2 (ARMv5TE), cr=000039ff
[    0.000000] CPU: VIVT data cache, VIVT instruction cache
[    0.000000] Machine: Linksys NSLU2
[    0.000000] Memory policy: ECC disabled, Data cache writeback
[    0.000000] On node 0 totalpages: 8192
[    0.000000] free_area_init_node: node 0, pgdat c0251af4, node_mem_map c0261000
[    0.000000]   DMA zone: 64 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 8128 pages, LIFO batch:0
[    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: 8128
[    0.000000] Kernel command line: root=/dev/mtdblock4 rootfstype=squashfs,jffs2 console=ttyS0,115200 init=/etc/preinit noinitrd
[    0.000000] PID hash table entries: 128 (order: -3, 512 bytes)
[    0.000000] Dentry cache hash table entries: 4096 (order: 2, 16384 bytes)
[    0.000000] Inode-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.000000] Memory: 32MB = 32MB total
[    0.000000] Memory: 30008k/30008k available, 2760k reserved, 0K highmem
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xfff00000 - 0xfffe0000   ( 896 kB)
[    0.000000]     DMA     : 0xffc00000 - 0xffe00000   (   2 MB)
[    0.000000]     vmalloc : 0xc2800000 - 0xff000000   ( 968 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xc2000000   (  32 MB)
[    0.000000]     modules : 0xbf000000 - 0xc0000000   (  16 MB)
[    0.000000]       .init : 0xc0008000 - 0xc0022000   ( 104 kB)
[    0.000000]       .text : 0xc0022000 - 0xc0239000   (2140 kB)
[    0.000000]       .data : 0xc023a000 - 0xc02524a0   (  98 kB)
[    0.000000] NR_IRQS:64
[    0.000308] Calibrating delay loop... 266.24 BogoMIPS (lpj=1331200)
[    0.242512] pid_max: default: 32768 minimum: 301
[    0.243077] Mount-cache hash table entries: 512
[    0.243967] CPU: Testing write buffer coherency: ok
[    0.249046] NET: Registered protocol family 16
[    0.254456] IXP4xx: Using 16MiB expansion bus window size
[    0.258832] NSLU2: Using MAC address 00:1a:70:a1:93:72 for port 0
[    0.259536] PCI: IXP4xx is host
[    0.259576] PCI: IXP4xx Using direct access for memory space
[    0.260370] pci 0000:00:01.0: [1033:0035] type 0 class 0x000c03
[    0.260471] pci 0000:00:01.0: reg 10: [mem 0x00000000-0x00000fff]
[    0.260635] pci 0000:00:01.0: supports D1 D2
[    0.260677] pci 0000:00:01.0: PME# supported from D0 D1 D2 D3hot
[    0.260724] pci 0000:00:01.0: PME# disabled
[    0.260825] pci 0000:00:01.1: [1033:0035] type 0 class 0x000c03
[    0.260898] pci 0000:00:01.1: reg 10: [mem 0x00000000-0x00000fff]
[    0.261043] pci 0000:00:01.1: supports D1 D2
[    0.261083] pci 0000:00:01.1: PME# supported from D0 D1 D2 D3hot
[    0.261127] pci 0000:00:01.1: PME# disabled
[    0.261221] pci 0000:00:01.2: [1033:00e0] type 0 class 0x000c03
[    0.261294] pci 0000:00:01.2: reg 10: [mem 0x00000000-0x000000ff]
[    0.261439] pci 0000:00:01.2: supports D1 D2
[    0.261479] pci 0000:00:01.2: PME# supported from D0 D1 D2 D3hot
[    0.261523] pci 0000:00:01.2: PME# disabled
[    0.261712] PCI: bus0: Fast back to back transfers disabled
[    0.261794] pci 0000:00:01.0: dmabounce: registered device
[    0.262631] pci 0000:00:01.1: dmabounce: registered device
[    0.263559] pci 0000:00:01.2: dmabounce: registered device
[    0.264488] pci 0000:00:01.0: BAR 0: assigned [mem 0x48000000-0x48000fff]
[    0.264568] pci 0000:00:01.0: BAR 0: set to [mem 0x48000000-0x48000fff] (PCI address [0x48000000-0x48000fff])
[    0.264635] pci 0000:00:01.1: BAR 0: assigned [mem 0x48001000-0x48001fff]
[    0.264698] pci 0000:00:01.1: BAR 0: set to [mem 0x48001000-0x48001fff] (PCI address [0x48001000-0x48001fff])
[    0.264766] pci 0000:00:01.2: BAR 0: assigned [mem 0x48002000-0x480020ff]
[    0.264829] pci 0000:00:01.2: BAR 0: set to [mem 0x48002000-0x480020ff] (PCI address [0x48002000-0x480020ff])
[    0.273073] bio: create slab <bio-0> at 0
[    0.278499] i2c-gpio i2c-gpio.0: using pins 7 (SDA) and 6 (SCL)
[    0.282079] Switching to clocksource OSTS
[    0.289959] NET: Registered protocol family 2
[    0.290334] IP route cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.291104] TCP established hash table entries: 1024 (order: 1, 8192 bytes)
[    0.291222] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[    0.291304] TCP: Hash tables configured (established 1024 bind 1024)
[    0.291343] TCP reno registered
[    0.291386] UDP hash table entries: 256 (order: 0, 4096 bytes)
[    0.291466] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[    0.292030] NET: Registered protocol family 1
[    0.292195] PCI: CLS 32 bytes, default 32
[    0.294698] IXP4xx Queue Manager initialized.
[    0.299396] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.299455] JFFS2 version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    0.300791] msgmni has been set to 58
[    0.301081] io scheduler noop registered
[    0.301112] io scheduler deadline registered (default)
[    1.059611] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[    1.070020] serial8250.0: ttyS0 at MMIO 0xc8000000 (irq = 15) is a XScale
[    1.442248] console [ttyS0] enabled
[    1.449856] serial8250.0: ttyS1 at MMIO 0xc8001000 (irq = 13) is a XScale
[    1.463461] IXP4XX-Flash.0: Found 1 x16 devices at 0x0 in 16-bit bank. Manufacturer ID 0x000089 Chip ID 0x000017
[    1.473907] IXP4XX-Flash.0: Found an alias at 0x800000 for the chip at 0x0
[    1.473967] Intel/Sharp Extended Query Table at 0x0031
[    1.479195] Intel/Sharp Extended Query Table at 0x0031
[    1.484445] Using buffer write method
[    1.488151] cfi_cmdset_0001: Erase suspend on write enabled
[    1.493835] erase region 0: offset=0x0,size=0x20000,blocks=64
[    1.494126] Searching for RedBoot partition table in IXP4XX-Flash.0 at offset 0x7e0000
[    1.560642] 6 RedBoot partitions found on MTD device IXP4XX-Flash.0
[    1.567105] IXP4xx flash: using dynamic partition definition
[    1.572856] Creating 6 MTD partitions on "IXP4XX-Flash.0":
[    1.578432] 0x000000000000-0x000000040000 : "RedBoot"
[    1.588829] 0x000000040000-0x000000060000 : "SysConf"
[    1.599305] 0x000000060000-0x000000080000 : "Loader"
[    1.609660] 0x000000080000-0x000000180000 : "Kernel"
[    1.620088] 0x000000180000-0x0000007e0000 : "rootfs"
[    1.630489] mtd: partition "rootfs" set to be root filesystem
[    1.636475] mtd: partition "rootfs_data" created automatically, ofs=3C0000, len=420000 
[    1.644627] 0x0000003c0000-0x0000007e0000 : "rootfs_data"
[    1.655470] 0x0000007e0000-0x000000800000 : "FIS directory"
[    1.671012] IXP4xx MII Bus: probed
[    1.676612] eth0: MII PHY 1 on NPE-B
[    1.693997] rtc-x1205 0-006f: chip found, driver version 1.0.8
[    1.701413] rtc-x1205 0-006f: rtc core: registered rtc-x1205 as rtc0
[    1.709234] i2c /dev entries driver
[    1.726239] IXP4xx Watchdog Timer: heartbeat 60 sec
[    1.731987] Registered led device: nslu2:green:ready
[    1.732799] Registered led device: nslu2:red:status
[    1.733497] Registered led device: nslu2:green:disk-1
[    1.734191] Registered led device: nslu2:green:disk-2
[    1.736481] TCP westwood registered
[    1.740033] NET: Registered protocol family 17
[    1.744884] 802.1Q VLAN Support v1.8 Ben Greear <greearb@candelatech.com>
[    1.751765] All bugs added by David S. Miller <davem@redhat.com>
[    1.757957] XScale DSP coprocessor detected.
[    1.768395] rtc-x1205 0-006f: setting system clock to 2012-04-06 21:55:30 UTC (1333749330)
[    1.790604] VFS: Mounted root (squashfs filesystem) readonly on device 31:4.
[    1.797993] Freeing init memory: 104K
[   11.891763] JFFS2 notice: (1357) jffs2_build_xattr_subsystem: complete building xattr subsystem, 2 of xdatum (0 unchecked, 0 orphan) and 12 of xref (0 dead, 10 orphan) found.
[   19.942345] NET: Registered protocol family 10
[   19.948221] lo: Disabled Privacy Extensions
[   20.844760] SCSI subsystem initialized
[   21.431960] usbcore: registered new interface driver usbfs
[   21.437941] usbcore: registered new interface driver hub
[   21.448298] usbcore: registered new device driver usb
[   21.589901] PPP generic driver version 2.4.2
[   22.125343] ip_tables: (C) 2000-2006 Netfilter Core Team
[   22.671491] NET: Registered protocol family 24
[   23.180905] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[   23.187998] PCI: enabling device 0000:00:01.2 (0140 -> 0142)
[   23.194063] ehci_hcd 0000:00:01.2: EHCI Host Controller
[   23.199556] ehci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1
[   23.232485] ehci_hcd 0000:00:01.2: irq 26, io mem 0x48002000
[   23.252308] ehci_hcd 0000:00:01.2: USB 2.0 started, EHCI 1.00
[   23.260236] hub 1-0:1.0: USB hub found
[   23.264201] hub 1-0:1.0: 5 ports detected
[   23.473750] nf_conntrack version 0.5.0 (470 buckets, 1880 max)
[   24.930277] input: ixp4xx beeper as /devices/platform/ixp4xx-beeper.4/input/input0
[   25.001808] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[   25.008342] PCI: enabling device 0000:00:01.0 (0140 -> 0142)
[   25.014412] ohci_hcd 0000:00:01.0: OHCI Host Controller
[   25.019906] ohci_hcd 0000:00:01.0: new USB bus registered, assigned bus number 2
[   25.027675] ohci_hcd 0000:00:01.0: irq 28, io mem 0x48000000
[   25.124934] hub 2-0:1.0: USB hub found
[   25.128822] hub 2-0:1.0: 3 ports detected
[   25.133665] PCI: enabling device 0000:00:01.1 (0140 -> 0142)
[   25.139691] ohci_hcd 0000:00:01.1: OHCI Host Controller
[   25.145210] ohci_hcd 0000:00:01.1: new USB bus registered, assigned bus number 3
[   25.152910] ohci_hcd 0000:00:01.1: irq 27, io mem 0x48001000
[   25.244965] hub 3-0:1.0: USB hub found
[   25.248833] hub 3-0:1.0: 2 ports detected
[   25.393239] input: gpio-keys as /devices/platform/gpio-keys/input/input1
[   25.553356] usbcore: registered new interface driver usbserial
[   25.559534] USB Serial support registered for generic
[   25.564990] usbcore: registered new interface driver usbserial_generic
[   25.571604] usbserial: USB Serial Driver core
[   25.943433] Initializing USB Mass Storage driver...
[   25.949031] usbcore: registered new interface driver usb-storage
[   25.955212] USB Mass Storage support registered.
[   26.098107] USB Serial support registered for GSM modem (1-port)
[   26.105117] usbcore: registered new interface driver option
[   26.110770] option: v0.7.2:USB Driver for GSM modems
[   58.414359] NPE-B: firmware's license can be found in /usr/share/doc/LICENSE.IPL
[   58.421992] NPE-B: firmware functionality 0x2, revision 0x2:1
[   58.668747] device eth0 entered promiscuous mode
[   58.728238] br-lan: port 1(eth0) entering forwarding state
[   58.733812] br-lan: port 1(eth0) entering forwarding state
[   59.777252] eth0: link up, speed 100 Mb/s, full duplex

Attachments (0)

Change History (4)

comment:1 Changed 6 years ago by Mike Brady <mikebrady@…>

Hmm. The problem seems to have gone away since changing over to Linux 3.3.2. Curious.

One little thing that now shows up in dmesg is three lines of a polite bug warning, seven lines down this listing:

[    0.000000] Booting Linux on physical CPU 0
[    0.000000] Linux version 3.3.2 (mike@ubuntu) (gcc version 4.6.3 20120201 (prerelease) (Linaro GCC 4.6-2012.02) ) #5 Mon Apr 23 17:15:10 IST 2012
[    0.000000] CPU: XScale-IXP42x Family [690541f1] revision 1 (ARMv5TE), cr=000039ff
[    0.000000] CPU: VIVT data cache, VIVT instruction cache
[    0.000000] Machine: Linksys NSLU2
[    0.000000] Memory policy: ECC disabled, Data cache writeback
[    0.000000] BUG: mapping for 0xc8000000 at 0xffbeb000 out of vmalloc space
[    0.000000] BUG: mapping for 0xc4000000 at 0xffbfe000 out of vmalloc space
[    0.000000] BUG: mapping for 0xc0000000 at 0xffbff000 out of vmalloc space
[    0.000000] On node 0 totalpages: 8192
[    0.000000] free_area_init_node: node 0, pgdat c027a028, node_mem_map c0288000
[    0.000000]   DMA zone: 64 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 8128 pages, LIFO batch:0
[    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: 8128
[    0.000000] Kernel command line: root=/dev/mtdblock4 rootfstype=squashfs,jffs2 console=ttyS0,115200 init=/etc/preinit noinitrd
[    0.000000] PID hash table entries: 128 (order: -3, 512 bytes)
[    0.000000] Dentry cache hash table entries: 4096 (order: 2, 16384 bytes)

comment:2 Changed 5 years ago by Mike Brady <mikebrady@…>

This problem has gone away, and might have been something I was doing wrong. I'd be grateful if the issue was closed.

comment:3 Changed 5 years ago by florian

  • Resolution set to fixed
  • Status changed from new to closed

comment:4 Changed 4 years ago by jow

  • Milestone changed from Attitude Adjustment 12.09 to Barrier Breaker 14.07

Milestone Attitude Adjustment 12.09 deleted

Add Comment

Modify Ticket

Action
as closed .
The resolution will be deleted. Next status will be 'reopened'.
Author


E-mail address and user name can be saved in the Preferences.

 
Note: See TracTickets for help on using tickets.