Work at SourceForge, help us to make it a better place! We have an immediate need for a Support Technician in our San Francisco or Denver office.

Close

#265 Kernel panic in grab_metapage on NSLU2

bug
open
kernel (207)
5
3 hours ago
2008-05-30
sethml
No

I'm trying to use JFS on a LinkSys NSLU2 (small Arm-based NAS box), and it kernel panics as soon as I write to the filesystem.

Note that the hard disk is connected via USB, and the distro is Debian testing. Let me know if you need more info or experimentation - I'm happy to oblige.

Relevant info:

sluggy:~# mkfs.jfs -L disk /dev/sda2
mkfs.jfs version 1.1.11, 05-Jun-2006
Warning! All data on device /dev/sda2 will be lost!

Continue? (Y/N) y
\

Format completed successfully.

976262017 kilobytes total disk space.
sluggy:~# mount -o noatime /dev/sda2 /mnt/disk
sluggy:~# ls /mnt/disk
sluggy:~# cd /usr/share
sluggy:/usr/share# mkdir /mnt/disk/debian
sluggy:/usr/share# time cp -a doc /mnt/disk/debian/usr-share-doc
... hangs ...

From another shell:
sethml@sluggy:~$ dmesg
Linux version 2.6.24-1-ixp4xx (Debian 2.6.24-7) (dannf@debian.org) (gcc version 4.1.3 20080114 (prerelease) (Debian 4.1.2-19)) #1 Tue Apr 22 00:10:01 UTC 2008
CPU: XScale-IXP42x Family [690541f1] revision 1 (ARMv5TE), cr=0000397f
Machine: Linksys NSLU2
Memory policy: ECC disabled, Data cache writeback
On node 0 totalpages: 8192
DMA zone: 64 pages used for memmap
DMA zone: 0 pages reserved
DMA zone: 8128 pages, LIFO batch:0
Normal zone: 0 pages used for memmap
Movable zone: 0 pages used for memmap
CPU0: D VIVT undefined 5 cache
CPU0: I cache: 32768 bytes, associativity 32, 32 byte lines, 32 sets
CPU0: D cache: 32768 bytes, associativity 32, 32 byte lines, 32 sets
Built 1 zonelists in Zone order, mobility grouping on. Total pages: 8128
Kernel command line: console=ttyS0,115200 rtc-x1205.probe=0,0x6f noirqdebug
IRQ lockup detection disabled
PID hash table entries: 128 (order: 7, 512 bytes)
Console: colour dummy device 80x30
Dentry cache hash table entries: 4096 (order: 2, 16384 bytes)
Inode-cache hash table entries: 2048 (order: 1, 8192 bytes)
Memory: 32MB = 32MB total
Memory: 25512KB available (2496K code, 232K data, 104K init)
Calibrating delay loop... 266.24 BogoMIPS (lpj=1331200)
Security Framework initialized
SELinux: Disabled at boot.
Capability LSM initialized
Mount-cache hash table entries: 512
Initializing cgroup subsys ns
Initializing cgroup subsys cpuacct
CPU: Testing write buffer coherency: ok
net_namespace: 64 bytes
NET: Registered protocol family 16
IXP4xx: Using 16MiB expansion bus window size
NSLU2: Using MAC address 00:04:5a:0f:95:9b for port 0
PCI: IXP4xx is host
PCI: IXP4xx Using direct access for memory space
PCI: bus0: Fast back to back transfers disabled
dmabounce: registered device 0000:00:01.0 on pci bus
dmabounce: registered device 0000:00:01.1 on pci bus
dmabounce: registered device 0000:00:01.2 on pci bus
NET: Registered protocol family 2
Time: OSTS clocksource has been installed.
IP route cache hash table entries: 1024 (order: 0, 4096 bytes)
TCP established hash table entries: 1024 (order: 1, 8192 bytes)
TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
TCP: Hash tables configured (established 1024 bind 1024)
TCP reno registered
checking if image is initramfs... it is
Freeing initrd memory: 4096K
NetWinder Floating Point Emulator V0.97 (double precision)
audit: initializing netlink socket (disabled)
audit(4.178:1): initialized
VFS: Disk quotas dquot_6.5.1
Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
JFFS2 version 2.2. (NAND) © 2001-2006 Red Hat, Inc.
io scheduler noop registered
io scheduler anticipatory registered
io scheduler deadline registered
io scheduler cfq registered (default)
Serial: 8250/16550 driver $Revision: 1.90 $ 2 ports, IRQ sharing disabled
serial8250.0: ttyS0 at MMIO 0xc8000000 (irq = 15) is a XScale
console [ttyS0] enabled
serial8250.0: ttyS1 at MMIO 0xc8001000 (irq = 13) is a XScale
RAMDISK driver initialized: 16 RAM disks of 8192K size 1024 blocksize
IXP4XX-Flash.0: Found 1 x16 devices at 0x0 in 16-bit bank
IXP4XX-Flash.0: Found an alias at 0x800000 for the chip at 0x0
Intel/Sharp Extended Query Table at 0x0031
Using buffer write method
cfi_cmdset_0001: Erase suspend on write enabled
erase region 0: offset=0x0,size=0x20000,blocks=64
Searching for RedBoot partition table in IXP4XX-Flash.0 at offset 0x7e0000
6 RedBoot partitions found on MTD device IXP4XX-Flash.0
Creating 6 MTD partitions on "IXP4XX-Flash.0":
0x00000000-0x00040000 : "RedBoot"
0x00040000-0x00060000 : "SysConf"
0x00060000-0x00080000 : "Loader"
0x00080000-0x001e0000 : "Kernel"
0x001e0000-0x007e0000 : "Ramdisk"
0x007e0000-0x00800000 : "FIS directory"
mice: PS/2 mouse device common for all mice
i2c /dev entries driver
x1205 0-006f: chip found, driver version 1.0.7
x1205 0-006f: rtc core: registered x1205 as rtc0
i2c-gpio i2c-gpio.0: using pins 7 (SDA) and 6 (SCL)
IXP4xx Watchdog Timer: heartbeat 60 sec
Registered led device: ready
Registered led device: status
Registered led device: disk-1
Registered led device: disk-2
NET: Registered protocol family 26
TCP bic registered
NET: Registered protocol family 1
NET: Registered protocol family 17
XScale DSP coprocessor detected.
registered taskstats version 1
x1205 0-006f: setting system clock to 2008-05-30 19:43:31 UTC (1212176611)
Freeing init memory: 104K
usbcore: registered new interface driver usbfs
usbcore: registered new interface driver hub
usbcore: registered new device driver usb
PCI: enabling device 0000:00:01.2 (0140 -> 0142)
ehci_hcd 0000:00:01.2: EHCI Host Controller
ehci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1
ehci_hcd 0000:00:01.2: irq 26, io mem 0x48002000
ohci_hcd: 2006 August 04 USB 1.1 'Open' Host Controller (OHCI) Driver
ehci_hcd 0000:00:01.2: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
usb usb1: configuration #1 chosen from 1 choice
hub 1-0:1.0: USB hub found
hub 1-0:1.0: 5 ports detected
PCI: enabling device 0000:00:01.0 (0140 -> 0142)
ohci_hcd 0000:00:01.0: OHCI Host Controller
ohci_hcd 0000:00:01.0: new USB bus registered, assigned bus number 2
ohci_hcd 0000:00:01.0: irq 28, io mem 0x48000000
usb usb2: configuration #1 chosen from 1 choice
hub 2-0:1.0: USB hub found
hub 2-0:1.0: 3 ports detected
PCI: enabling device 0000:00:01.1 (0140 -> 0142)
ohci_hcd 0000:00:01.1: OHCI Host Controller
ohci_hcd 0000:00:01.1: new USB bus registered, assigned bus number 3
ohci_hcd 0000:00:01.1: irq 27, io mem 0x48001000
usb usb3: configuration #1 chosen from 1 choice
hub 3-0:1.0: USB hub found
hub 3-0:1.0: 2 ports detected
usb 1-1: new high speed USB device using ehci_hcd and address 2
usb 1-1: configuration #1 chosen from 1 choice
usb 1-2: new high speed USB device using ehci_hcd and address 3
usb 1-2: configuration #1 chosen from 1 choice
SCSI subsystem initialized
Initializing USB Mass Storage driver...
scsi0 : SCSI emulation for USB Mass Storage devices
usb-storage: device found at 2
usb-storage: waiting for device to settle before scanning
scsi1 : SCSI emulation for USB Mass Storage devices
usbcore: registered new interface driver usb-storage
USB Mass Storage support registered.
usb-storage: device found at 3
usb-storage: waiting for device to settle before scanning
usb-storage: device scan complete
scsi 0:0:0:0: Direct-Access PI-202US SATA/USB20 Drive 8.60 PQ: 0 ANSI: 0
usb-storage: device scan complete
scsi 1:0:0:0: Direct-Access LEXAR JUMPDRIVE PHOTO 1000 PQ: 0 ANSI: 0 CCS
Driver 'sd' needs updating - please use bus_type methods
sd 0:0:0:0: [sda] 1953525168 512-byte hardware sectors (1000205 MB)
sd 0:0:0:0: [sda] Write Protect is off
sd 0:0:0:0: [sda] Mode Sense: 23 00 00 00
sd 0:0:0:0: [sda] Assuming drive cache: write through
sd 0:0:0:0: [sda] 1953525168 512-byte hardware sectors (1000205 MB)
sd 0:0:0:0: [sda] Write Protect is off
sd 0:0:0:0: [sda] Mode Sense: 23 00 00 00
sd 0:0:0:0: [sda] Assuming drive cache: write through
sda: sda1 sda2
sd 0:0:0:0: [sda] Attached SCSI disk
sd 1:0:0:0: [sdb] 506880 512-byte hardware sectors (260 MB)
sd 1:0:0:0: [sdb] Write Protect is off
sd 1:0:0:0: [sdb] Mode Sense: 43 00 00 00
sd 1:0:0:0: [sdb] Assuming drive cache: write through
sd 1:0:0:0: [sdb] 506880 512-byte hardware sectors (260 MB)
sd 1:0:0:0: [sdb] Write Protect is off
sd 1:0:0:0: [sdb] Mode Sense: 43 00 00 00
sd 1:0:0:0: [sdb] Assuming drive cache: write through
sdb: unknown partition table
sd 1:0:0:0: [sdb] Attached SCSI removable disk
EXT3-fs: INFO: recovery required on readonly filesystem.
EXT3-fs: write access will be enabled during recovery.
kjournald starting. Commit interval 5 seconds
EXT3-fs: sdb: orphan cleanup on readonly fs
ext3_orphan_cleanup: deleting unreferenced inode 8256
EXT3-fs: sdb: 1 orphan inode deleted
EXT3-fs: recovery complete.
EXT3-fs: mounted filesystem with ordered data mode.
input: ixp4xx beeper as /class/input/input0
IXP4xx Queue Manager initialized.
eth0: MII PHY 1 on NPE-B
Adding 497972k swap on /dev/sda1. Priority:-1 extents:1 across:497972k
EXT3 FS on sdb, internal journal
device-mapper: uevent: version 1.0.3
device-mapper: ioctl: 4.12.0-ioctl (2007-10-02) initialised: dm-devel@redhat.com
NPE-B: firmware functionality 0x0, revision 0x2:1
eth0: link down
eth0: link up, 100Mbps, full-duplex, lpa 0xCDE1
NET: Registered protocol family 10
lo: Disabled Privacy Extensions
device-mapper: core: cleaned up
JFS: nTxBlock = 232, nTxLock = 1857
sethml@sluggy:~$ dmesg
Linux version 2.6.24-1-ixp4xx (Debian 2.6.24-7) (dannf@debian.org) (gcc version 4.1.3 20080114 (prerelease) (Debian 4.1.2-19)) #1 Tue Apr 22 00:10:01 UTC 2008
CPU: XScale-IXP42x Family [690541f1] revision 1 (ARMv5TE), cr=0000397f
Machine: Linksys NSLU2
Memory policy: ECC disabled, Data cache writeback
On node 0 totalpages: 8192
DMA zone: 64 pages used for memmap
DMA zone: 0 pages reserved
DMA zone: 8128 pages, LIFO batch:0
Normal zone: 0 pages used for memmap
Movable zone: 0 pages used for memmap
CPU0: D VIVT undefined 5 cache
CPU0: I cache: 32768 bytes, associativity 32, 32 byte lines, 32 sets
CPU0: D cache: 32768 bytes, associativity 32, 32 byte lines, 32 sets
Built 1 zonelists in Zone order, mobility grouping on. Total pages: 8128
Kernel command line: console=ttyS0,115200 rtc-x1205.probe=0,0x6f noirqdebug
IRQ lockup detection disabled
PID hash table entries: 128 (order: 7, 512 bytes)
Console: colour dummy device 80x30
Dentry cache hash table entries: 4096 (order: 2, 16384 bytes)
Inode-cache hash table entries: 2048 (order: 1, 8192 bytes)
Memory: 32MB = 32MB total
Memory: 25512KB available (2496K code, 232K data, 104K init)
Calibrating delay loop... 266.24 BogoMIPS (lpj=1331200)
Security Framework initialized
SELinux: Disabled at boot.
Capability LSM initialized
Mount-cache hash table entries: 512
Initializing cgroup subsys ns
Initializing cgroup subsys cpuacct
CPU: Testing write buffer coherency: ok
net_namespace: 64 bytes
NET: Registered protocol family 16
IXP4xx: Using 16MiB expansion bus window size
NSLU2: Using MAC address 00:04:5a:0f:95:9b for port 0
PCI: IXP4xx is host
PCI: IXP4xx Using direct access for memory space
PCI: bus0: Fast back to back transfers disabled
dmabounce: registered device 0000:00:01.0 on pci bus
dmabounce: registered device 0000:00:01.1 on pci bus
dmabounce: registered device 0000:00:01.2 on pci bus
NET: Registered protocol family 2
Time: OSTS clocksource has been installed.
IP route cache hash table entries: 1024 (order: 0, 4096 bytes)
TCP established hash table entries: 1024 (order: 1, 8192 bytes)
TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
TCP: Hash tables configured (established 1024 bind 1024)
TCP reno registered
checking if image is initramfs... it is
Freeing initrd memory: 4096K
NetWinder Floating Point Emulator V0.97 (double precision)
audit: initializing netlink socket (disabled)
audit(4.178:1): initialized
VFS: Disk quotas dquot_6.5.1
Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
JFFS2 version 2.2. (NAND) © 2001-2006 Red Hat, Inc.
io scheduler noop registered
io scheduler anticipatory registered
io scheduler deadline registered
io scheduler cfq registered (default)
Serial: 8250/16550 driver $Revision: 1.90 $ 2 ports, IRQ sharing disabled
serial8250.0: ttyS0 at MMIO 0xc8000000 (irq = 15) is a XScale
console [ttyS0] enabled
serial8250.0: ttyS1 at MMIO 0xc8001000 (irq = 13) is a XScale
RAMDISK driver initialized: 16 RAM disks of 8192K size 1024 blocksize
IXP4XX-Flash.0: Found 1 x16 devices at 0x0 in 16-bit bank
IXP4XX-Flash.0: Found an alias at 0x800000 for the chip at 0x0
Intel/Sharp Extended Query Table at 0x0031
Using buffer write method
cfi_cmdset_0001: Erase suspend on write enabled
erase region 0: offset=0x0,size=0x20000,blocks=64
Searching for RedBoot partition table in IXP4XX-Flash.0 at offset 0x7e0000
6 RedBoot partitions found on MTD device IXP4XX-Flash.0
Creating 6 MTD partitions on "IXP4XX-Flash.0":
0x00000000-0x00040000 : "RedBoot"
0x00040000-0x00060000 : "SysConf"
0x00060000-0x00080000 : "Loader"
0x00080000-0x001e0000 : "Kernel"
0x001e0000-0x007e0000 : "Ramdisk"
0x007e0000-0x00800000 : "FIS directory"
mice: PS/2 mouse device common for all mice
i2c /dev entries driver
x1205 0-006f: chip found, driver version 1.0.7
x1205 0-006f: rtc core: registered x1205 as rtc0
i2c-gpio i2c-gpio.0: using pins 7 (SDA) and 6 (SCL)
IXP4xx Watchdog Timer: heartbeat 60 sec
Registered led device: ready
Registered led device: status
Registered led device: disk-1
Registered led device: disk-2
NET: Registered protocol family 26
TCP bic registered
NET: Registered protocol family 1
NET: Registered protocol family 17
XScale DSP coprocessor detected.
registered taskstats version 1
x1205 0-006f: setting system clock to 2008-05-30 19:43:31 UTC (1212176611)
Freeing init memory: 104K
usbcore: registered new interface driver usbfs
usbcore: registered new interface driver hub
usbcore: registered new device driver usb
PCI: enabling device 0000:00:01.2 (0140 -> 0142)
ehci_hcd 0000:00:01.2: EHCI Host Controller
ehci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1
ehci_hcd 0000:00:01.2: irq 26, io mem 0x48002000
ohci_hcd: 2006 August 04 USB 1.1 'Open' Host Controller (OHCI) Driver
ehci_hcd 0000:00:01.2: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
usb usb1: configuration #1 chosen from 1 choice
hub 1-0:1.0: USB hub found
hub 1-0:1.0: 5 ports detected
PCI: enabling device 0000:00:01.0 (0140 -> 0142)
ohci_hcd 0000:00:01.0: OHCI Host Controller
ohci_hcd 0000:00:01.0: new USB bus registered, assigned bus number 2
ohci_hcd 0000:00:01.0: irq 28, io mem 0x48000000
usb usb2: configuration #1 chosen from 1 choice
hub 2-0:1.0: USB hub found
hub 2-0:1.0: 3 ports detected
PCI: enabling device 0000:00:01.1 (0140 -> 0142)
ohci_hcd 0000:00:01.1: OHCI Host Controller
ohci_hcd 0000:00:01.1: new USB bus registered, assigned bus number 3
ohci_hcd 0000:00:01.1: irq 27, io mem 0x48001000
usb usb3: configuration #1 chosen from 1 choice
hub 3-0:1.0: USB hub found
hub 3-0:1.0: 2 ports detected
usb 1-1: new high speed USB device using ehci_hcd and address 2
usb 1-1: configuration #1 chosen from 1 choice
usb 1-2: new high speed USB device using ehci_hcd and address 3
usb 1-2: configuration #1 chosen from 1 choice
SCSI subsystem initialized
Initializing USB Mass Storage driver...
scsi0 : SCSI emulation for USB Mass Storage devices
usb-storage: device found at 2
usb-storage: waiting for device to settle before scanning
scsi1 : SCSI emulation for USB Mass Storage devices
usbcore: registered new interface driver usb-storage
USB Mass Storage support registered.
usb-storage: device found at 3
usb-storage: waiting for device to settle before scanning
usb-storage: device scan complete
scsi 0:0:0:0: Direct-Access PI-202US SATA/USB20 Drive 8.60 PQ: 0 ANSI: 0
usb-storage: device scan complete
scsi 1:0:0:0: Direct-Access LEXAR JUMPDRIVE PHOTO 1000 PQ: 0 ANSI: 0 CCS
Driver 'sd' needs updating - please use bus_type methods
sd 0:0:0:0: [sda] 1953525168 512-byte hardware sectors (1000205 MB)
sd 0:0:0:0: [sda] Write Protect is off
sd 0:0:0:0: [sda] Mode Sense: 23 00 00 00
sd 0:0:0:0: [sda] Assuming drive cache: write through
sd 0:0:0:0: [sda] 1953525168 512-byte hardware sectors (1000205 MB)
sd 0:0:0:0: [sda] Write Protect is off
sd 0:0:0:0: [sda] Mode Sense: 23 00 00 00
sd 0:0:0:0: [sda] Assuming drive cache: write through
sda: sda1 sda2
sd 0:0:0:0: [sda] Attached SCSI disk
sd 1:0:0:0: [sdb] 506880 512-byte hardware sectors (260 MB)
sd 1:0:0:0: [sdb] Write Protect is off
sd 1:0:0:0: [sdb] Mode Sense: 43 00 00 00
sd 1:0:0:0: [sdb] Assuming drive cache: write through
sd 1:0:0:0: [sdb] 506880 512-byte hardware sectors (260 MB)
sd 1:0:0:0: [sdb] Write Protect is off
sd 1:0:0:0: [sdb] Mode Sense: 43 00 00 00
sd 1:0:0:0: [sdb] Assuming drive cache: write through
sdb: unknown partition table
sd 1:0:0:0: [sdb] Attached SCSI removable disk
EXT3-fs: INFO: recovery required on readonly filesystem.
EXT3-fs: write access will be enabled during recovery.
kjournald starting. Commit interval 5 seconds
EXT3-fs: sdb: orphan cleanup on readonly fs
ext3_orphan_cleanup: deleting unreferenced inode 8256
EXT3-fs: sdb: 1 orphan inode deleted
EXT3-fs: recovery complete.
EXT3-fs: mounted filesystem with ordered data mode.
input: ixp4xx beeper as /class/input/input0
IXP4xx Queue Manager initialized.
eth0: MII PHY 1 on NPE-B
Adding 497972k swap on /dev/sda1. Priority:-1 extents:1 across:497972k
EXT3 FS on sdb, internal journal
device-mapper: uevent: version 1.0.3
device-mapper: ioctl: 4.12.0-ioctl (2007-10-02) initialised: dm-devel@redhat.com
NPE-B: firmware functionality 0x0, revision 0x2:1
eth0: link down
eth0: link up, 100Mbps, full-duplex, lpa 0xCDE1
NET: Registered protocol family 10
lo: Disabled Privacy Extensions
device-mapper: core: cleaned up
JFS: nTxBlock = 232, nTxLock = 1857
Unable to handle kernel NULL pointer dereference at virtual address 0000007b
pgd = c1d74000
[0000007b] *pgd=01ee9031, *pte=00000000, *ppte=00000000
Internal error: Oops: f3 [#1]
Modules linked in: jfs ipv6 evdev ixp4xx_eth ixp4xx_npe firmware_class ixp4xx_qmgr ixp4xx_beeper ext3 jbd mbcache sd_mod usb_storage scsi_mod ohci_hcd ehci_hcd usbcore
CPU: 0 Not tainted (2.6.24-1-ixp4xx #1)
PC is at grab_metapage+0x10/0x17c [jfs]
LR is at txUpdateMap+0x6c/0x250 [jfs]
pc : [<bf13a6b4>] lr : [<bf13d4b0>] psr: 20000013
sp : c1cd9f38 ip : c1cd9f68 fp : c1cd9f64
r10: c1867c3c r9 : 00000001 r8 : 00000001
r7 : 0000004b r6 : c28b6840 r5 : c28ab0a8 r4 : c28b11e0
r3 : 0000ffff r2 : 40000013 r1 : 00000161 r0 : 0000004b
Flags: nzCv IRQs on FIQs on Mode SVC_32 ISA ARM Segment kernel
Control: 0000397f Table: 01d74000 DAC: 00000017
Process jfsCommit (pid: 1979, stack limit = 0xc1cd8258)
Stack: (0xc1cd9f38 to 0xc1cda000)
9f20: c28ab0a8 c28b11d0
9f40: c28ab0a8 c28b11c0 00000000 c28b11e0 c28ab0a8 c28b6840 c1cd9fa4 c1cd9f68
9f60: bf13d4b0 bf13a6b0 00000040 c003384c c00337ac 00000000 00000002 c28ab0a8
9f80: 60000013 c1edbce0 c1cd8000 00000000 00000000 00000000 c1cd9fd8 c1cd9fa8
9fa0: bf13f8ec bf13d450 00000000 c1c7d820 c00342e4 00100100 00200200 c1cd8000
9fc0: 00000000 bf13f84c 00000000 c1cd9ff4 c1cd9fdc c004d020 bf13f858 00000000
9fe0: 00000000 00000000 00000000 c1cd9ff8 c003bfdc c004cfd4 00000000 00000000
Backtrace:
[<bf13a6a4>] (grab_metapage+0x0/0x17c [jfs]) from [<bf13d4b0>] (txUpdateMap+0x6c/0x250 [jfs])
r6:c28b6840 r5:c28ab0a8 r4:c28b11e0
[<bf13d444>] (txUpdateMap+0x0/0x250 [jfs]) from [<bf13f8ec>] (jfs_lazycommit+0xa0/0x23c [jfs])
[<bf13f84c>] (jfs_lazycommit+0x0/0x23c [jfs]) from [<c004d020>] (kthread+0x58/0x90)
r7:00000000 r6:bf13f84c r5:00000000 r4:c1cd8000
[<c004cfc8>] (kthread+0x0/0x90) from [<c003bfdc>] (do_exit+0x0/0x78c)
r6:00000000 r5:00000000 r4:00000000
Code: e1a0c00d e92dd870 e24cb004 e24dd014 (e5901030)
---[ end trace 9182b80365ca7def ]---
sethml@sluggy:~$ mount
/dev/sdb on / type ext3 (rw,noatime,errors=remount-ro)
tmpfs on /lib/init/rw type tmpfs (rw,nosuid,mode=0755)
proc on /proc type proc (rw,noexec,nosuid,nodev)
sysfs on /sys type sysfs (rw,noexec,nosuid,nodev)
procbususb on /proc/bus/usb type usbfs (rw)
udev on /dev type tmpfs (rw,mode=0755)
tmpfs on /dev/shm type tmpfs (rw,nosuid,nodev)
devpts on /dev/pts type devpts (rw,noexec,nosuid,gid=5,mode=620)
/dev/sda2 on /mnt/disk type jfs (rw,noatime)
sethml@sluggy:~$ cat /proc/partitions
major minor #blocks name

31 0 256 mtdblock0
31 1 128 mtdblock1
31 2 128 mtdblock2
31 3 1408 mtdblock3
31 4 6144 mtdblock4
31 5 128 mtdblock5
8 0 976762584 sda
8 1 497983 sda1
8 2 976262017 sda2
8 16 253440 sdb
sethml@sluggy:~$ cat /proc/meminfo
MemTotal: 29712 kB
MemFree: 7852 kB
Buffers: 3396 kB
Cached: 6692 kB
SwapCached: 772 kB
Active: 8696 kB
Inactive: 4768 kB
SwapTotal: 497972 kB
SwapFree: 494384 kB
Dirty: 476 kB
Writeback: 0 kB
AnonPages: 3148 kB
Mapped: 3004 kB
Slab: 5424 kB
SReclaimable: 3732 kB
SUnreclaim: 1692 kB
PageTables: 284 kB
NFS_Unstable: 0 kB
Bounce: 0 kB
CommitLimit: 512828 kB
Committed_AS: 20780 kB
VmallocTotal: 991232 kB
VmallocUsed: 17204 kB
VmallocChunk: 966652 kB
sethml@sluggy:~$ cat /proc/swaps
Filename Type Size Used Priority
/dev/sda1 partition 497972 3588 -1

Discussion

  • David Kleikamp
    David Kleikamp
    2008-05-30

    • assigned_to: nobody --> shaggyk
     
  • David Kleikamp
    David Kleikamp
    2008-05-30

    Logged In: YES
    user_id=422440
    Originator: NO

    I admit to knowing next to nothing about the ARM architecture, so any debugging help would be appreciated.

    A quick look at the oops makes me think that mp, passed into grab_metpage() from txUpdateMap() is bad. The trapping memory access is at 0x7b, so I'm guessing mp is 0x4b, which is in both r7 and r0. That value comes from tlck->mp in txUpdateMap(), but how it got there, I have no clue.

    Does it always trap in the same place? I can write some debug code if you would be willing to compile a patched jfs.ko and reproduce it. It's quitting time right now, but I should have some time over the weekend and/or next week to work with you.

    Feel free to email me at shaggy@linux.vnet.ibm.com if that's more convenient than adding comments to this bug.

    Thanks,
    Shaggy

     
  • sethml
    sethml
    2008-05-31

    Logged In: YES
    user_id=1103704
    Originator: YES

    I can't attempt to repro right now, since the box is at home, and it won't reboot in the oopsed state (probably it's hanging when trying to unmount the filesystem?). I'm happy to try reproing with some other conditions and try to nail down the problem a bit more. It's entirely possible that it's linked to the fact that I'm using a USB disk, or that the disk is 1TB, or some other quirk of my setup. I'll try to scope the problem a bit this weekend.

     
  • sethml
    sethml
    2008-05-31

    Logged In: YES
    user_id=1103704
    Originator: YES

    I just did it again. This time I set up JFS on a smaller parition, and created a directory with a copy of /etc/* in it, and then did an 'rm -r' of the directory. Result:

    JFS: nTxBlock = 232, nTxLock = 1857
    Unable to handle kernel paging request at virtual address 69772e81
    pgd = c0508000
    [69772e81] *pgd=00000000
    Internal error: Oops: f3 [#1]
    Modules linked in: jfs ipv6 dm_snapshot dm_mirror dm_mod evdev ixp4xx_eth ixp4xx_npe firmware_class ixp4xx_qmgr ixp4xx_beeper ext3 jbd mbcache sd_mod usb_storage scsi_mod ehci_hcd ohci_hcd usbcore
    CPU: 0 Not tainted (2.6.24-1-ixp4xx #1)
    PC is at txCommit+0x1f4/0xf3c [jfs]
    LR is at lmLog+0x184/0x18c [jfs]
    pc : [<bf13dad8>] lr : [<bf13c498>] psr: 20000013
    sp : c0929e24 ip : c0929dfc fp : c0929eac
    r10: c0503c7c r9 : c05033d4 r8 : c286d150
    r7 : 00000002 r6 : c2875100 r5 : c1520a48 r4 : 00000000
    r3 : 69772d2d r2 : c0500107 r1 : 00000084 r0 : 0000f804
    Flags: nzCv IRQs on FIQs on Mode SVC_32 ISA ARM Segment user
    Control: 0000397f Table: 00508000 DAC: 00000015
    Process rm (pid: 1531, stack limit = 0xc0928258)
    Stack: (0xc0929e24 to 0xc092a000)
    9e20: c0929e78 00000000 c15e1e00 c0092d28 c163d000 00000010 cd59fdcb
    9e40: 00000007 c163d000 00000004 c0a92000 c15e1e00 c102f800 00000002 c0929ecc
    9e60: 00000052 0000f804 049c0800 00000000 00000010 00001020 00040080 00000001
    9e80: 0000204a c05035ec 00000000 00000000 00000004 00000000 c05033d4 c0503c7c
    9ea0: c0929f08 c0929eb0 bf123540 bf13d8f0 00000003 c0503438 c0503ad4 c0503444
    9ec0: c0503a64 4840d100 1ade8316 c05035ec c0503c7c 00000007 c1e30e20 00001022
    9ee0: c0503c7c 00000000 c0e6e81c c163d000 c0022f84 c0928000 00000003 c0929f24
    9f00: c0929f0c c009381c bf1232f8 c05035ec 00000000 c0e6e81c c0929f94 c0929f28
    9f20: c00960c8 c0093764 c07d4114 c1c0da20 cd59fdcb 00000007 c163d000 00000010
    9f40: 00000000 00000000 c0929f68 c0929f58 c00510c8 c005100c 00000000 c0929f7c
    9f60: c0929f6c c00510ec 00000000 c17f45e0 c0929fac c0929f80 40149000 0001c203
    9f80: 000030c4 00000148 c0929fa4 c0929f98 c00962a4 c0096008 00000000 c0929fa8
    9fa0: c0022de0 c0096284 40149000 0001c203 00000003 0001c203 00000000 00000000
    9fc0: 40149000 0001c203 000030c4 00000003 00000000 be927b48 00000003 be927a70
    9fe0: be927a74 be927a4c 0000a0ac 400dba58 60000010 00000003 00000000 00000000
    Backtrace:
    [<bf13d8e4>] (txCommit+0x0/0xf3c [jfs]) from [<bf123540>] (jfs_unlink+0x254/0x3c0 [jfs])
    [<bf1232ec>] (jfs_unlink+0x0/0x3c0 [jfs]) from [<c009381c>] (vfs_unlink+0xc4/0x114)
    [<c0093758>] (vfs_unlink+0x0/0x114) from [<c00960c8>] (do_unlinkat+0xcc/0x170)
    r6:c0e6e81c r5:00000000 r4:c05035ec
    [<c0095ffc>] (do_unlinkat+0x0/0x170) from [<c00962a4>] (sys_unlinkat+0x2c/0x38)
    r7:00000148 r6:000030c4 r5:0001c203 r4:40149000
    [<c0096278>] (sys_unlinkat+0x0/0x38) from [<c0022de0>] (ret_fast_syscall+0x0/0x2c)
    Code: e596200c e3833b02 e1c630b4 e592308c (e5933154)
    ---[ end trace 1d47eea84f35eebf ]---

     

    Related

    Patches: #1

  • sethml
    sethml
    2008-05-31

    Logged In: YES
    user_id=1103704
    Originator: YES

    Hmm, XFS also seems to fail in horrible ways. I'm beginning to think this isn't the fault of JFS specifically.

    sluggy:~# mkfs.xfs /dev/sda2
    mkfs.xfs: /dev/sda2 appears to contain an existing filesystem (jfs).
    mkfs.xfs: Use the -f option to force overwrite.
    sluggy:~# mkfs.xfs -f /dev/sda2
    meta-data=/dev/sda2 isize=256 agcount=4, agsize=62754 blks
    = sectsz=512 attr=2
    data = bsize=4096 blocks=251015, imaxpct=25
    = sunit=0 swidth=0 blks
    naming =version 2 bsize=4096
    log =internal log bsize=4096 blocks=1200, version=2
    = sectsz=512 sunit=0 blks, lazy-count=0
    realtime =none extsz=4096 blocks=0, rtextents=0
    sluggy:~# mount /dev/sda2 /mnt/test/
    sluggy:~# cd /mnt/test
    sluggy:/mnt/test# ls
    sluggy:/mnt/test# echo hi there > test1
    sluggy:/mnt/test# cat test1
    hi there
    sluggy:/mnt/test# cp -r /etc .
    sluggy:/mnt/test# du -sh etc
    5.5M etc
    sluggy:/mnt/test# tar c etc | md5sum
    b7d0837ede54c4b87be1f26584b2603f -
    sluggy:/mnt/test# rm -r etc
    rm: cannot remove directory `etc/initramfs-tools/scripts': Directory not empty
    rm: cannot remove `etc/initramfs-tools/conf.d': No such file or directory
    rm: cannot remove `etc/initramfs-tools/hooks': No such file or directory
    rm: cannot remove `etc/rc6.d/README': No such file or directory
    rm: cannot remove `etc/rc6.d/K11cron': No such file or directory
    rm: cannot remove `etc/rc6.d/': No such file or directory
    rm: cannot remove `etc/rcS.d/README': No such file or directory
    rm: cannot remove `etc/rcS.d/S30checkfs.sh': No such file or directory
    rm: cannot remove `etc/rcS.d/': No such file or directory
    rm: cannot remove `etc/ssh/ssh_host_rsa_key.pub.broken': No such file or directory
    rm: cannot remove `etc/ssh/ssh_': No such file or directory
    rm: cannot remove `etc/security/limits.conf': No such file or directory
    rm: cannot remove `etc/security/opasswd': No such file or directory
    rm: cannot remove `etc/rc2.d/S01leds_startup': Too many levels of symbolic links
    rm: cannot remove `etc/rc2.d/README': No such file or directory
    rm: cannot remove `etc/rc2.d/\372\300\\F\372\300': No such file or directory
    rm: cannot remove `etc/udev/rules.d/z25_persistent-net.rules': No such file or directory
    rm: cannot remove `etc/udev/cd-aliases-generator.rulia': Invalid argument
    rm: cannot remove `etc/udev/': No such file or directory
    rm: cannot remove `etc/rc4.d/S01leds_startup': Too many levels of symbolic links
    rm: cannot remove `etc/rc4.d/README': No such file or directory
    rm: cannot remove `etc/default/syslogd': Input/output error
    rm: cannot remove `etc/default/rmrecovery': Input/output error
    rm: cannot remove `etc/apt/trusted.gb.s': No such file or directory
    rm: cannot remove `etc/apt/\002': No such file or directory
    rm: cannot remove `etc/rc0.d/README': No such file or directory
    rm: cannot remove `etc/rc0.d/K11cron': Too many levels of symbolic links
    rm: cannot remove `etc/rc0.d/': No such file or directory
    rm: cannot remove `etc/cron.daily/apt': No such file or directory
    rm: cannot remove `etc/cron.daily/bsdmainutils': No such file or directory
    rm: cannot remove `etc/alternatives/mt.1.gz': Too many levels of symbolic links
    rm: cannot remove `etc/alternatives/ex.1.gz': Too many levels of symbolic links
    rm: cannot remove `etc/alternatives/rvim': No such file or directory
    rm: cannot remove `etc/alternatives/': No such file or directory
    rm: cannot remove `etc/pam.d/common-password': No such file or directory
    rm: cannot remove `etc/pam.d/common-session': No such file or directory
    rm: cannot remove `etc/rc3.d/S01leds_startup': Too many levels of symbolic links
    rm: cannot remove `etc/rc3.d/README': No such file or directory
    rm: cannot remove `etc/rc1.d/README': No such file or directory
    rm: cannot remove `etc/rc1.d/K11cron': Too many levels of symbolic links
    rm: cannot remove `etc/rc1.d/': No such file or directory
    rm: cannot remove `etc/rc5.d/S01leds_startup': Too many levels of symbolic links
    rm: cannot remove `etc/rc5.d/README': No such file or directory
    rm: cannot remove `etc/rc5.d/\237\300|r\237\300': No such file or directory
    rm: cannot remove `etc/init.d/openbsd-inetd': No such file or directory
    rm: cannot remove `etc/init.d/umountroot': No such file or directory
    rm: cannot remove `etc/init.d/\334+Z\300\334+Z\300': No such file or directory
    sluggy:/mnt/test# ls -l etc/rc5.d
    ls: cannot access etc/rc5.d/##|r##: No such file or directory
    total 0
    ?????????? ? ? ? ? ? ??|r??