[ 0.000000] PROMLIB: Sun IEEE Boot Prom 'OBP 3.31.0 2001/07/25 20:36' [ 0.000000] PROMLIB: Root node compatible: [ 0.000000] Linux version 2.6.33-rc6 (chrisk@stanley) (gcc version 4.3.2 (Debian 4.3.2-1.1) ) #1 Mon Feb 8 03:53:06 PST 2010 [ 0.000000] bootconsole [earlyprom0] enabled [ 0.000000] ARCH: SUN4U [ 0.000000] Ethernet address: 08:00:20:b0:8d:e9 [ 0.000000] Kernel: Using 4 locked TLB entries for main kernel image. [ 0.000000] Remapping the kernel... done. [ 0.000000] OF stdout device is: /pci@1f,0/pci@1,1/ebus@1/se@14,400000:a [ 0.000000] PROM: Built device tree with 42031 bytes of memory. [ 0.000000] Top of RAM: 0x17f42000, Total RAM: 0xff40000 [ 0.000000] Memory hole size: 128MB [ 0.000000] [0000010000000000-fffff80000400000] page_structs=131072 node=0 entry=0/0 [ 0.000000] [0000010000000000-fffff80000800000] page_structs=131072 node=0 entry=1/0 [ 0.000000] Zone PFN ranges: [ 0.000000] Normal 0x00000000 -> 0x0000bfa1 [ 0.000000] Movable zone start PFN for each node [ 0.000000] early_node_map[3] active PFN ranges [ 0.000000] 0: 0x00000000 -> 0x00004000 [ 0.000000] 0: 0x00008000 -> 0x0000bf7f [ 0.000000] 0: 0x0000bf80 -> 0x0000bfa1 [ 0.000000] On node 0 totalpages: 32672 [ 0.000000] Normal zone: 384 pages used for memmap [ 0.000000] Normal zone: 0 pages reserved [ 0.000000] Normal zone: 32288 pages, LIFO batch:7 [ 0.000000] Booting Linux... [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 32288 [ 0.000000] Kernel command line: root=/dev/sda4 ro console=ttyS0 [ 0.000000] PID hash table entries: 1024 (order: 0, 8192 bytes) [ 0.000000] Dentry cache hash table entries: 32768 (order: 5, 262144 bytes) [ 0.000000] Inode-cache hash table entries: 16384 (order: 4, 131072 bytes) [ 0.000000] Memory: 236696k available (3032k kernel code, 3160k data, 152k init) [fffff80000000000,0000000017f42000] [ 0.000000] SLUB: Genslabs=14, HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 [ 0.000000] NR_IRQS:255 [ 0.000000] clocksource: mult[ed097b42] shift[30] [ 0.000000] clockevent: mult[451eb851] shift[32] [ 0.000000] Console: colour dummy device 80x25 [ 0.000000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.000000] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.000000] ... MAX_LOCK_DEPTH: 48 [ 0.000000] ... MAX_LOCKDEP_KEYS: 8191 [ 0.000000] ... CLASSHASH_SIZE: 4096 [ 0.000000] ... MAX_LOCKDEP_ENTRIES: 16384 [ 0.000000] ... MAX_LOCKDEP_CHAINS: 32768 [ 0.000000] ... CHAINHASH_SIZE: 16384 [ 0.000000] memory used by lock dependency info: 6367 kB [ 0.000000] per task-struct memory footprint: 2688 bytes [ 0.000000] ------------------------ [ 0.000000] | Locking API testsuite: [ 0.000000] ---------------------------------------------------------------------------- [ 0.000000] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.000000] -------------------------------------------------------------------------- [ 0.000000] A-A deadlock: ok | ok | ok | ok | ok | ok | [ 0.000000] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | [ 0.000000] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | [ 0.000000] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | [ 0.000000] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | [ 0.000000] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | [ 0.000000] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | [ 0.000000] double unlock: ok | ok | ok | ok | ok | ok | [ 0.000000] initialize held: ok | ok | ok | ok | ok | ok | [ 0.000000] bad unlock order: ok | ok | ok | ok | ok | ok | [ 0.000000] -------------------------------------------------------------------------- [ 0.000000] recursive read-lock: | ok | | ok | [ 0.000000] recursive read-lock #2: | ok | | ok | [ 0.000000] mixed read-write-lock: | ok | | ok | [ 0.000000] mixed write-read-lock: | ok | | ok | [ 0.000000] -------------------------------------------------------------------------- [ 0.000000] hard-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.000000] soft-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.000000] hard-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.000000] soft-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.000000] sirq-safe-A => hirqs-on/12: ok | ok | ok | [ 0.000000] sirq-safe-A => hirqs-on/21: ok | ok | ok | [ 0.000000] hard-safe-A + irqs-on/12: ok | ok | ok | [ 0.000000] soft-safe-A + irqs-on/12: ok | ok | ok | [ 0.000000] hard-safe-A + irqs-on/21: ok | ok | ok | [ 0.000000] soft-safe-A + irqs-on/21: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.000000] hard-irq lock-inversion/123: ok | ok | ok | [ 0.000000] soft-irq lock-inversion/123: ok | ok | ok | [ 0.000000] hard-irq lock-inversion/132: ok | ok | ok | [ 0.000000] soft-irq lock-inversion/132: ok | ok | ok | [ 0.000000] hard-irq lock-inversion/213: ok | ok | ok | [ 0.000000] soft-irq lock-inversion/213: ok | ok | ok | [ 0.000000] hard-irq lock-inversion/231: ok | ok | ok | [ 0.000000] soft-irq lock-inversion/231: ok | ok | ok | [ 0.000000] hard-irq lock-inversion/312: ok | ok | ok | [ 0.000000] soft-irq lock-inversion/312: ok | ok | ok | [ 0.000000] hard-irq lock-inversion/321: ok | ok | ok | [ 0.000000] soft-irq lock-inversion/321: ok | ok | ok | [ 0.000000] hard-irq read-recursion/123: ok | [ 0.000000] soft-irq read-recursion/123: ok | [ 0.000000] hard-irq read-recursion/132: ok | [ 0.000000] soft-irq read-recursion/132: ok | [ 0.000000] hard-irq read-recursion/213: ok | [ 0.000000] soft-irq read-recursion/213: ok | [ 0.000000] hard-irq read-recursion/231: ok | [ 0.000000] soft-irq read-recursion/231: ok | [ 0.000000] hard-irq read-recursion/312: ok | [ 0.000000] soft-irq read-recursion/312: ok | [ 0.000000] hard-irq read-recursion/321: ok | [ 0.000000] soft-irq read-recursion/321: ok | [ 0.000000] ------------------------------------------------------- [ 0.000000] Good, all 218 testcases passed! | [ 0.000000] --------------------------------- [ 39.983113] Calibrating delay using timer specific routine.. 546.61 BogoMIPS (lpj=1093231) [ 40.081053] Mount-cache hash table entries: 512 [ 40.153833] NET: Registered protocol family 16 [ 40.297502] /pci@1f,0: PCI IO[1fe02000000] MEM[1ff00000000] [ 40.362973] /pci@1f,0: SABRE PCI Bus Module ver[0:0] [ 40.422004] PCI: Scanning PBM /pci@1f,0 [ 40.606932] bio: create slab at 0 [ 40.660174] vgaarb: device added: PCI:0000:01:02.0,decodes=io+mem,owns=none,locks=none [ 40.753474] vgaarb: loaded [ 40.791013] SCSI subsystem initialized [ 40.835190] libata version 3.00 loaded. [ 40.848609] /pci@1f,0/pci@1,1/ebus@1/eeprom@14,0: Mostek regs at 0x1fff1000000 [ 40.939257] AUXIO: Found device at /pci@1f,0/pci@1,1/ebus@1/auxio@14,726000 [ 41.022685] Switching to clocksource tick [ 41.070731] Slow work thread pool: Starting up [ 41.122748] Switched to NOHz mode on CPU #0 [ 41.173857] Slow work thread pool: Ready [ 41.219774] FS-Cache: Loaded [ 41.255813] CacheFiles: Loaded [ 41.306788] NET: Registered protocol family 2 [ 41.358418] IP route cache hash table entries: 2048 (order: 1, 16384 bytes) [ 41.442801] TCP established hash table entries: 8192 (order: 4, 131072 bytes) [ 41.528312] TCP bind hash table entries: 8192 (order: 6, 589824 bytes) [ 41.614699] TCP: Hash tables configured (established 8192 bind 8192) [ 41.689501] TCP reno registered [ 41.726751] UDP hash table entries: 128 (order: 1, 20480 bytes) [ 41.798245] UDP-Lite hash table entries: 128 (order: 1, 20480 bytes) [ 41.875478] NET: Registered protocol family 1 [ 41.927931] RPC: Registered udp transport module. [ 41.982566] RPC: Registered tcp transport module. [ 42.038793] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 42.116349] PCI: CLS 64 bytes, default 64 [ 42.117645] power: Control reg at 1fff1724000 [ 42.195891] Initializing RT-Tester: OK [ 42.429143] FS-Cache: Netfs 'nfs' registered for caching [ 42.503663] msgmni has been set to 463 [ 42.556307] alg: No test for stdrng (krng) [ 42.604348] io scheduler noop registered [ 42.650670] io scheduler deadline registered [ 42.704607] io scheduler cfq registered (default) [ 42.883379] f0061c64: ttyS0 at MMIO 0x1fff1400000 (irq = 5) is a SAB82532 V3.2 [ 42.968393] Console: ttyS0 (SAB82532) [ 43.012750] console [ttyS0] enabled, bootconsole disabled [ 43.142864] f0061c64: ttyS1 at MMIO 0x1fff1400040 (irq = 5) is a SAB82532 V3.2 [ 43.238478] scsi0 : pata_cmd64x [ 43.280608] scsi1 : pata_cmd64x [ 43.320945] ata1: PATA max MWDMA2 cmd 0x1fe02c00000 ctl 0x1fe02c00008 bmdma 0x1fe02c00020 irq 14 [ 43.425347] ata2: PATA max MWDMA2 cmd 0x1fe02c00010 ctl 0x1fe02c00018 bmdma 0x1fe02c00028 irq 14 [ 43.535305] pata_cmd64x: active 10 recovery 10 setup 3. [ 43.535414] pata_cmd64x: active 10 recovery 10 setup 3. [ 43.537065] PCI: Enabling device: (0000:01:01.1), cmd 2 [ 43.537191] sunhme.c:v3.10 August 26, 2008 David S. Miller (davem@davemloft.net) [ 43.630190] eth0: HAPPY MEAL (PCI/CheerIO) 10/100BaseT Ethernet 08:00:20:b0:8d:e9 [ 43.724353] /pci@1f,0/pci@1,1/ebus@1/flashprom@10,0: OBP Flash, RD 1fff0000000[100000] WR 1fff0000000[100000] [ 43.850519] ata1.00: ATA-3: ST39140A, 861000, max UDMA/33 [ 43.914235] ata1.00: 17803440 sectors, multi 16: LBA [ 43.974808] pata_cmd64x: active 3 recovery 1 setup 1. [ 43.974951] pata_cmd64x: active 3 recovery 1 setup 1. [ 43.976339] mice: PS/2 mouse device common for all mice [ 44.045270] rtc-m48t59 rtc-m48t59.0: rtc core: registered m48t59 as rtc0 [ 44.132230] TCP cubic registered [ 44.169934] NET: Registered protocol family 17 [ 44.226088] ata1.00: configured for MWDMA2 [ 44.278962] registered taskstats version 1 [ 44.329099] rtc-m48t59 rtc-m48t59.0: setting system clock to 2010-02-09 04:00:45 UTC (1265688045) [ 44.435917] pata_cmd64x: active 3 recovery 1 setup 1. [ 44.436057] pata_cmd64x: active 3 recovery 1 setup 1. [ 44.442352] ata1.00: configured for MWDMA2 [ 44.490455] ata1: EH complete [ 44.529301] scsi 0:0:0:0: Direct-Access ATA ST39140A 8610 PQ: 0 ANSI: 5 [ 44.636584] pata_cmd64x: active 10 recovery 10 setup 3. [ 44.636700] pata_cmd64x: active 10 recovery 10 setup 3. [ 44.637664] sd 0:0:0:0: [sda] 17803440 512-byte logical blocks: (9.11 GB/8.48 GiB) [ 44.729904] sd 0:0:0:0: [sda] Write Protect is off [ 44.786360] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00 [ 44.787356] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA [ 44.899419] ata2.00: ATAPI: CRD-8322B, 1.05, max MWDMA2 [ 44.961243] pata_cmd64x: active 3 recovery 1 setup 1. [ 44.961392] pata_cmd64x: active 3 recovery 1 setup 1. [ 44.966136] sda: [ 44.988167] ata2.00: configured for MWDMA2 [ 45.039524] sda1 sda2 sda3 sda4 [ 45.086263] scsi 1:0:0:0: CD-ROM LG CD-ROM CRD-8322B 1.05 PQ: 0 ANSI: 5 [ 45.195993] sd 0:0:0:0: [sda] Attached SCSI disk [ 45.280257] EXT3-fs (sda4): recovery required on readonly filesystem [ 45.355443] EXT3-fs (sda4): write access will be enabled during recovery [ 52.906084] kjournald starting. Commit interval 5 seconds [ 52.971538] EXT3-fs (sda4): recovery complete [ 53.031596] EXT3-fs (sda4): mounted filesystem with writeback data mode [ 53.110305] VFS: Mounted root (ext3 filesystem) readonly on device 8:4. [ 60.781896] sr0: scsi3-mmc drive: 32x/32x cd/rw xa/form2 cdda tray [ 60.855062] Uniform CD-ROM driver Revision: 3.20 [ 60.913648] sr 1:0:0:0: Attached scsi CD-ROM sr0 [ 62.886528] sd 0:0:0:0: Attached scsi generic sg0 type 0 [ 62.950223] sr 1:0:0:0: Attached scsi generic sg1 type 5 [ 66.296188] Adding 499952k swap on /dev/sda2. Priority:1 extents:1 across:499952k [ 67.084404] EXT3-fs (sda4): using internal journal [ 69.028777] loop: module loaded [ 80.972084] eth0: Link is up using internal transceiver at 100Mb/s, Full Duplex. [ 84.450954] Installing knfsd (copyright (C) 1996 okir@monad.swb.de). [ 85.130291] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory [ 85.245707] NFSD: starting 90-second grace period [21267.918636] JFS: nTxBlock = 926, nTxLock = 7413 [22696.229712] [22696.229736] ========================================================= [22696.323599] [ INFO: possible irq lock inversion dependency detected ] [22696.400694] 2.6.33-rc6 #1 [22696.431940] --------------------------------------------------------- [22696.509032] dbench/8886 just changed the state of lock: [22696.571536] (&jfs_ip->commit_mutex){+.+.+.}, at: [<00000000100b4d58>] jfs_mkdir+0x98/0x380 [jfs] [22696.677793] but this lock was taken by another, RECLAIM_FS-safe lock in the past: [22696.767379] (&jfs_ip->rdwrlock#3){+.+.-.} [22696.814258] [22696.814267] and interrupts could create inverse lock ordering between them. [22696.814283] [22696.935099] [22696.935108] other info that might help us debug this: [22697.013234] 3 locks held by dbench/8886: [22697.060108] #0: (&sb->s_type->i_mutex_key#12/1){+.+.+.}, at: [<00000000004cea38>] lookup_create+0x18/0xc0 [22697.176783] #1: (&jfs_ip->commit_mutex){+.+.+.}, at: [<00000000100b4d58>] jfs_mkdir+0x98/0x380 [jfs] [22697.288250] #2: (&jfs_ip->commit_mutex/1){+.+...}, at: [<00000000100b4d68>] jfs_mkdir+0xa8/0x380 [jfs] [22697.401799] [22697.401809] the shortest dependencies between 2nd lock and 1st lock: [22697.495561] -> (&jfs_ip->rdwrlock#3){+.+.-.} ops: 3929349614993408 { [22697.572642] HARDIRQ-ON-W at: [22697.611189] [<000000000047dff4>] lock_acquire+0x54/0x80 [22697.716402] [<000000000046edb4>] down_write_nested+0x34/0x80 [22697.826827] [<00000000100b32e0>] jfs_get_block+0x20/0x300 [jfs] [22697.940377] [<00000000004ee684>] nobh_write_begin+0x164/0x460 [22698.051841] [<00000000100b2f2c>] jfs_write_begin+0x2c/0x40 [jfs] [22698.166434] [<0000000000494d50>] generic_file_buffered_write+0x170/0x220 [22698.289358] [<0000000000496e58>] __generic_file_aio_write+0x258/0x3a0 [22698.409157] [<0000000000496fd8>] generic_file_aio_write+0x38/0xc0 [22698.524791] [<00000000004c5694>] do_sync_write+0x74/0xc0 [22698.631047] [<00000000004c6128>] vfs_write+0x68/0x140 [22698.734180] [<00000000004c6264>] SyS_pwrite64+0x64/0x80 [22698.839396] [<00000000004400f4>] sys32_pwrite64+0x14/0x40 [22698.946695] [<0000000000406114>] linux_sparc_syscall32+0x34/0x40 [22699.061291] SOFTIRQ-ON-W at: [22699.099832] [<000000000047dff4>] lock_acquire+0x54/0x80 [22699.205047] [<000000000046edb4>] down_write_nested+0x34/0x80 [22699.315470] [<00000000100b32e0>] jfs_get_block+0x20/0x300 [jfs] [22699.429021] [<00000000004ee684>] nobh_write_begin+0x164/0x460 [22699.540486] [<00000000100b2f2c>] jfs_write_begin+0x2c/0x40 [jfs] [22699.655079] [<0000000000494d50>] generic_file_buffered_write+0x170/0x220 [22699.778003] [<0000000000496e58>] __generic_file_aio_write+0x258/0x3a0 [22699.897803] [<0000000000496fd8>] generic_file_aio_write+0x38/0xc0 [22700.013436] [<00000000004c5694>] do_sync_write+0x74/0xc0 [22700.119693] [<00000000004c6128>] vfs_write+0x68/0x140 [22700.222825] [<00000000004c6264>] SyS_pwrite64+0x64/0x80 [22700.328041] [<00000000004400f4>] sys32_pwrite64+0x14/0x40 [22700.435340] [<0000000000406114>] linux_sparc_syscall32+0x34/0x40 [22700.549934] IN-RECLAIM_FS-W at: [22700.591602] [<000000000047dff4>] lock_acquire+0x54/0x80 [22700.699941] [<000000000046edb4>] down_write_nested+0x34/0x80 [22700.813491] [<00000000100b32e0>] jfs_get_block+0x20/0x300 [jfs] [22700.930168] [<00000000004ee0a8>] __block_write_full_page+0x248/0x4a0 [22701.052050] [<00000000004ee39c>] block_write_full_page_endio+0x9c/0xe0 [22701.176016] [<00000000004ee3f4>] block_write_full_page+0x14/0x40 [22701.293733] [<00000000100b2ff0>] jfs_writepage+0x10/0x40 [jfs] [22701.409367] [<00000000004a1df8>] shrink_page_list+0x518/0x880 [22701.523958] [<00000000004a27e8>] shrink_zone+0x688/0xb20 [22701.633341] [<00000000004a3838>] try_to_free_pages+0x2b8/0x360 [22701.748973] [<000000000049bf54>] __alloc_pages_nodemask+0x354/0x660 [22701.869815] [<0000000000496014>] grab_cache_page_write_begin+0x54/0xc0 [22701.993781] [<00000000004ee55c>] nobh_write_begin+0x3c/0x460 [22702.107330] [<00000000100b2f2c>] jfs_write_begin+0x2c/0x40 [jfs] [22702.225049] [<0000000000494d50>] generic_file_buffered_write+0x170/0x220 [22702.351099] [<0000000000496e58>] __generic_file_aio_write+0x258/0x3a0 [22702.474023] [<0000000000496fd8>] generic_file_aio_write+0x38/0xc0 [22702.592781] [<00000000004c5694>] do_sync_write+0x74/0xc0 [22702.702164] [<00000000004c6128>] vfs_write+0x68/0x140 [22702.808421] [<00000000004c6264>] SyS_pwrite64+0x64/0x80 [22702.916762] [<00000000004400f4>] sys32_pwrite64+0x14/0x40 [22703.027187] [<0000000000406114>] linux_sparc_syscall32+0x34/0x40 [22703.144906] INITIAL USE at: [22703.182406] [<000000000047dff4>] lock_acquire+0x54/0x80 [22703.286579] [<000000000046edb4>] down_write_nested+0x34/0x80 [22703.395962] [<00000000100b32e0>] jfs_get_block+0x20/0x300 [jfs] [22703.508470] [<00000000004ee684>] nobh_write_begin+0x164/0x460 [22703.618894] [<00000000100b2f2c>] jfs_write_begin+0x2c/0x40 [jfs] [22703.732444] [<0000000000494d50>] generic_file_buffered_write+0x170/0x220 [22703.854327] [<0000000000496e58>] __generic_file_aio_write+0x258/0x3a0 [22703.973084] [<0000000000496fd8>] generic_file_aio_write+0x38/0xc0 [22704.087676] [<00000000004c5694>] do_sync_write+0x74/0xc0 [22704.192892] [<00000000004c6128>] vfs_write+0x68/0x140 [22704.294983] [<00000000004c6264>] SyS_pwrite64+0x64/0x80 [22704.399156] [<00000000004400f4>] sys32_pwrite64+0x14/0x40 [22704.505414] [<0000000000406114>] linux_sparc_syscall32+0x34/0x40 [22704.618966] } [22704.639800] ... key at: [<00000000100dd194>] __key.23313+0x0/0xffffffffffffd194 [jfs] [22704.740848] ... acquired at: [22704.777309] [<000000000047dff4>] lock_acquire+0x54/0x80 [22704.842937] [<00000000006f7420>] mutex_lock_nested+0x40/0x360 [22704.914817] [<00000000100ce374>] extAlloc+0x34/0x540 [jfs] [22704.983572] [<00000000100b34e8>] jfs_get_block+0x228/0x300 [jfs] [22705.058579] [<00000000004ee684>] nobh_write_begin+0x164/0x460 [22705.130457] [<00000000100b2f2c>] jfs_write_begin+0x2c/0x40 [jfs] [22705.205463] [<0000000000494d50>] generic_file_buffered_write+0x170/0x220 [22705.288801] [<0000000000496e58>] __generic_file_aio_write+0x258/0x3a0 [22705.369015] [<0000000000496fd8>] generic_file_aio_write+0x38/0xc0 [22705.445061] [<00000000004c5694>] do_sync_write+0x74/0xc0 [22705.511733] [<00000000004c6128>] vfs_write+0x68/0x140 [22705.575280] [<00000000004c6264>] SyS_pwrite64+0x64/0x80 [22705.640908] [<00000000004400f4>] sys32_pwrite64+0x14/0x40 [22705.708623] [<0000000000406114>] linux_sparc_syscall32+0x34/0x40 [22705.783628] [22705.801339] -> (&jfs_ip->commit_mutex){+.+.+.} ops: 2011277350141952 { [22705.879469] HARDIRQ-ON-W at: [22705.916970] [<000000000047dff4>] lock_acquire+0x54/0x80 [22706.020102] [<00000000006f7420>] mutex_lock_nested+0x40/0x360 [22706.129485] [<00000000100b3070>] jfs_commit_inode+0x50/0x140 [jfs] [22706.244077] [<00000000100b3724>] jfs_write_inode+0x44/0x80 [jfs] [22706.356586] [<00000000004e444c>] writeback_single_inode+0x10c/0x3a0 [22706.472217] [<00000000004e52ac>] writeback_inodes_wb+0x32c/0x500 [22706.584724] [<00000000004e55bc>] wb_writeback+0x13c/0x200 [22706.689940] [<00000000004e5974>] wb_do_writeback+0x214/0x240 [22706.798281] [<00000000004e5a00>] bdi_writeback_task+0x60/0xa0 [22706.907663] [<00000000004a90a4>] bdi_start_fn+0x64/0xe0 [22707.010796] [<000000000046a7a0>] kthread+0x60/0x80 [22707.108720] [<000000000042b570>] kernel_thread+0x30/0x60 [22707.212894] [<000000000046a89c>] kthreadd+0xdc/0x160 [22707.312902] SOFTIRQ-ON-W at: [22707.350403] [<000000000047dff4>] lock_acquire+0x54/0x80 [22707.453534] [<00000000006f7420>] mutex_lock_nested+0x40/0x360 [22707.562917] [<00000000100b3070>] jfs_commit_inode+0x50/0x140 [jfs] [22707.677510] [<00000000100b3724>] jfs_write_inode+0x44/0x80 [jfs] [22707.790018] [<00000000004e444c>] writeback_single_inode+0x10c/0x3a0 [22707.905650] [<00000000004e52ac>] writeback_inodes_wb+0x32c/0x500 [22708.018157] [<00000000004e55bc>] wb_writeback+0x13c/0x200 [22708.123372] [<00000000004e5974>] wb_do_writeback+0x214/0x240 [22708.231714] [<00000000004e5a00>] bdi_writeback_task+0x60/0xa0 [22708.341097] [<00000000004a90a4>] bdi_start_fn+0x64/0xe0 [22708.444229] [<000000000046a7a0>] kthread+0x60/0x80 [22708.542153] [<000000000042b570>] kernel_thread+0x30/0x60 [22708.646325] [<000000000046a89c>] kthreadd+0xdc/0x160 [22708.746336] RECLAIM_FS-ON-W at: [22708.786961] [<000000000047b100>] lockdep_trace_alloc+0xe0/0x100 [22708.901552] [<00000000004c253c>] kmem_cache_alloc+0x1c/0x120 [22709.013017] [<00000000100cb384>] dtInsert+0x9a4/0x19e0 [jfs] [22709.124486] [<00000000100b4f4c>] jfs_mkdir+0x28c/0x380 [jfs] [22709.235951] [<00000000004cf19c>] vfs_mkdir+0x5c/0xa0 [22709.339083] [<00000000004d162c>] SyS_mkdirat+0xcc/0x100 [22709.445339] [<00000000004cf88c>] SyS_mkdir+0xc/0x20 [22709.547431] [<0000000000406114>] linux_sparc_syscall32+0x34/0x40 [22709.663066] INITIAL USE at: [22709.699524] [<000000000047dff4>] lock_acquire+0x54/0x80 [22709.801614] [<00000000006f7420>] mutex_lock_nested+0x40/0x360 [22709.909955] [<00000000100b3070>] jfs_commit_inode+0x50/0x140 [jfs] [22710.023506] [<00000000100b3724>] jfs_write_inode+0x44/0x80 [jfs] [22710.134971] [<00000000004e444c>] writeback_single_inode+0x10c/0x3a0 [22710.249562] [<00000000004e52ac>] writeback_inodes_wb+0x32c/0x500 [22710.361028] [<00000000004e55bc>] wb_writeback+0x13c/0x200 [22710.465202] [<00000000004e5974>] wb_do_writeback+0x214/0x240 [22710.572500] [<00000000004e5a00>] bdi_writeback_task+0x60/0xa0 [22710.680842] [<00000000004a90a4>] bdi_start_fn+0x64/0xe0 [22710.782932] [<000000000046a7a0>] kthread+0x60/0x80 [22710.879813] [<000000000042b570>] kernel_thread+0x30/0x60 [22710.982946] [<000000000046a89c>] kthreadd+0xdc/0x160 [22711.081912] } [22711.101705] ... key at: [<00000000100dd18c>] __key.23314+0x0/0xffffffffffffd19c [jfs] [22711.201713] ... acquired at: [22711.237133] [<000000000047aa88>] mark_lock+0x2a8/0x780 [22711.301718] [<000000000047afd4>] mark_held_locks+0x74/0xc0 [22711.370474] [<000000000047b100>] lockdep_trace_alloc+0xe0/0x100 [22711.444436] [<00000000004c253c>] kmem_cache_alloc+0x1c/0x120 [22711.515275] [<00000000100cb384>] dtInsert+0x9a4/0x19e0 [jfs] [22711.586114] [<00000000100b4f4c>] jfs_mkdir+0x28c/0x380 [jfs] [22711.656953] [<00000000004cf19c>] vfs_mkdir+0x5c/0xa0 [22711.719455] [<00000000004d162c>] SyS_mkdirat+0xcc/0x100 [22711.785086] [<00000000004cf88c>] SyS_mkdir+0xc/0x20 [22711.846548] [<0000000000406114>] linux_sparc_syscall32+0x34/0x40 [22711.921554] [22711.939263] [22711.939272] stack backtrace: [22711.991351] Call Trace: [22712.020532] [000000000047b758] print_irq_inversion_bug+0xf8/0x120 [22712.094494] [000000000047b948] check_usage_backwards+0xa8/0x120 [22712.166372] [000000000047aa88] mark_lock+0x2a8/0x780 [22712.226793] [000000000047afd4] mark_held_locks+0x74/0xc0 [22712.291382] [000000000047b100] lockdep_trace_alloc+0xe0/0x100 [22712.361179] [00000000004c253c] kmem_cache_alloc+0x1c/0x120 [22712.427885] [00000000100cb384] dtInsert+0x9a4/0x19e0 [jfs] [22712.494543] [00000000100b4f4c] jfs_mkdir+0x28c/0x380 [jfs] [22712.561197] [00000000004cf19c] vfs_mkdir+0x5c/0xa0 [22712.619529] [00000000004d162c] SyS_mkdirat+0xcc/0x100 [22712.680996] [00000000004cf88c] SyS_mkdir+0xc/0x20 [22712.738300] [0000000000406114] linux_sparc_syscall32+0x34/0x40