[ 0.000000] Linux version 2.6.24.1 (evil@sheep) (gcc version 4.2.3 (Debian 4.2.3-1)) #1 Sat Feb 9 15:14:38 CET 2008 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: 0000000000000000 - 000000000009fc00 (usable) [ 0.000000] BIOS-e820: 000000000009fc00 - 00000000000a0000 (reserved) [ 0.000000] BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved) [ 0.000000] BIOS-e820: 0000000000100000 - 000000007fff0000 (usable) [ 0.000000] BIOS-e820: 000000007fff0000 - 000000007fff3000 (ACPI NVS) [ 0.000000] BIOS-e820: 000000007fff3000 - 0000000080000000 (ACPI data) [ 0.000000] BIOS-e820: 00000000fec00000 - 00000000fec01000 (reserved) [ 0.000000] BIOS-e820: 00000000fee00000 - 00000000fee01000 (reserved) [ 0.000000] BIOS-e820: 00000000ffff0000 - 0000000100000000 (reserved) [ 0.000000] 1151MB HIGHMEM available. [ 0.000000] 896MB LOWMEM available. [ 0.000000] Entering add_active_range(0, 0, 524272) 0 entries of 256 used [ 0.000000] Zone PFN ranges: [ 0.000000] DMA 0 -> 4096 [ 0.000000] Normal 4096 -> 229376 [ 0.000000] HighMem 229376 -> 524272 [ 0.000000] Movable zone start PFN for each node [ 0.000000] early_node_map[1] active PFN ranges [ 0.000000] 0: 0 -> 524272 [ 0.000000] On node 0 totalpages: 524272 [ 0.000000] DMA zone: 32 pages used for memmap [ 0.000000] DMA zone: 0 pages reserved [ 0.000000] DMA zone: 4064 pages, LIFO batch:0 [ 0.000000] Normal zone: 1760 pages used for memmap [ 0.000000] Normal zone: 223520 pages, LIFO batch:31 [ 0.000000] HighMem zone: 2303 pages used for memmap [ 0.000000] HighMem zone: 292593 pages, LIFO batch:31 [ 0.000000] Movable zone: 0 pages used for memmap [ 0.000000] DMI 2.2 present. [ 0.000000] Allocating PCI resources starting at 88000000 (gap: 80000000:7ec00000) [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 520177 [ 0.000000] Kernel command line: root=/dev/sda5 ro [ 0.000000] Found and enabled local APIC! [ 0.000000] mapped APIC to ffffb000 (fee00000) [ 0.000000] Enabling fast FPU save and restore... done. [ 0.000000] Enabling unmasked SIMD FPU exception support... done. [ 0.000000] Initializing CPU#0 [ 0.000000] CPU 0 irqstacks, hard=c0584000 soft=c0583000 [ 0.000000] PID hash table entries: 4096 (order: 12, 16384 bytes) [ 0.000000] Detected 1913.186 MHz processor. [ 104.968638] Console: colour VGA+ 80x25 [ 104.968646] console [tty0] enabled [ 104.977988] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 104.978660] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 104.979027] ... MAX_LOCK_DEPTH: 30 [ 104.979391] ... MAX_LOCKDEP_KEYS: 2048 [ 104.979759] ... CLASSHASH_SIZE: 1024 [ 104.980131] ... MAX_LOCKDEP_ENTRIES: 8192 [ 104.980499] ... MAX_LOCKDEP_CHAINS: 16384 [ 104.980870] ... CHAINHASH_SIZE: 8192 [ 104.981239] memory used by lock dependency info: 992 kB [ 104.981636] per task-struct memory footprint: 1200 bytes [ 104.982035] ------------------------ [ 104.982370] | Locking API testsuite: [ 104.982529] ---------------------------------------------------------------------------- [ 104.982820] | spin |wlock |rlock |mutex | wsem | rsem | [ 104.983109] -------------------------------------------------------------------------- [ 104.983404] A-A deadlock: ok | ok | ok | ok | ok | ok | [ 104.985187] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | [ 104.987052] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | [ 104.988984] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | [ 104.990914] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | [ 104.992908] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | [ 104.994905] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | [ 104.996776] double unlock: ok | ok | ok | ok | ok | ok | [ 104.998193] initialize held: ok | ok | ok | ok | ok | ok | [ 105.000001] bad unlock order: ok | ok | ok | ok | ok | ok | [ 105.001875] -------------------------------------------------------------------------- [ 105.002557] recursive read-lock: | ok | | ok | [ 105.003729] recursive read-lock #2: | ok | | ok | [ 105.004899] mixed read-write-lock: | ok | | ok | [ 105.006075] mixed write-read-lock: | ok | | ok | [ 105.007252] -------------------------------------------------------------------------- [ 105.007935] hard-irqs-on + irq-safe-A/12: ok | ok | ok | [ 105.008950] soft-irqs-on + irq-safe-A/12: ok | ok | ok | [ 105.009970] hard-irqs-on + irq-safe-A/21: ok | ok | ok | [ 105.010933] soft-irqs-on + irq-safe-A/21: ok | ok | ok | [ 105.011710] sirq-safe-A => hirqs-on/12: ok | ok | ok | [ 105.012542] sirq-safe-A => hirqs-on/21: ok | ok | ok | [ 105.013558] hard-safe-A + irqs-on/12: ok | ok | ok | [ 105.014571] soft-safe-A + irqs-on/12: ok | ok | ok | [ 105.015590] hard-safe-A + irqs-on/21: ok | ok | ok | [ 105.016605] soft-safe-A + irqs-on/21: ok | ok | ok | [ 105.017621] hard-safe-A + unsafe-B #1/123: ok | ok | ok | [ 105.018668] soft-safe-A + unsafe-B #1/123: ok | ok | ok | [ 105.019718] hard-safe-A + unsafe-B #1/132: ok | ok | ok | [ 105.020765] soft-safe-A + unsafe-B #1/132: ok | ok | ok | [ 105.021814] hard-safe-A + unsafe-B #1/213: ok | ok | ok | [ 105.022863] soft-safe-A + unsafe-B #1/213: ok | ok | ok | [ 105.023911] hard-safe-A + unsafe-B #1/231: ok | ok | ok | [ 105.024947] soft-safe-A + unsafe-B #1/231: ok | ok | ok | [ 105.025825] hard-safe-A + unsafe-B #1/312: ok | ok | ok | [ 105.026607] soft-safe-A + unsafe-B #1/312: ok | ok | ok | [ 105.027556] hard-safe-A + unsafe-B #1/321: ok | ok | ok | [ 105.028588] soft-safe-A + unsafe-B #1/321: ok | ok | ok | [ 105.029625] hard-safe-A + unsafe-B #2/123: ok | ok | ok | [ 105.030660] soft-safe-A + unsafe-B #2/123: ok | ok | ok | [ 105.031701] hard-safe-A + unsafe-B #2/132: ok | ok | ok | [ 105.032730] soft-safe-A + unsafe-B #2/132: ok | ok | ok | [ 105.033771] hard-safe-A + unsafe-B #2/213: ok | ok | ok | [ 105.034810] soft-safe-A + unsafe-B #2/213: ok | ok | ok | [ 105.035853] hard-safe-A + unsafe-B #2/231: ok | ok | ok | [ 105.036884] soft-safe-A + unsafe-B #2/231: ok | ok | ok | [ 105.037923] hard-safe-A + unsafe-B #2/312: ok | ok | ok | [ 105.038956] soft-safe-A + unsafe-B #2/312: ok | ok | ok | [ 105.039862] hard-safe-A + unsafe-B #2/321: ok | ok | ok | [ 105.040652] soft-safe-A + unsafe-B #2/321: ok | ok | ok | [ 105.041601] hard-irq lock-inversion/123: ok | ok | ok | [ 105.042639] soft-irq lock-inversion/123: ok | ok | ok | [ 105.043687] hard-irq lock-inversion/132: ok | ok | ok | [ 105.044722] soft-irq lock-inversion/132: ok | ok | ok | [ 105.045768] hard-irq lock-inversion/213: ok | ok | ok | [ 105.046807] soft-irq lock-inversion/213: ok | ok | ok | [ 105.047853] hard-irq lock-inversion/231: ok | ok | ok | [ 105.048884] soft-irq lock-inversion/231: ok | ok | ok | [ 105.049922] hard-irq lock-inversion/312: ok | ok | ok | [ 105.050958] soft-irq lock-inversion/312: ok | ok | ok | [ 105.052005] hard-irq lock-inversion/321: ok | ok | ok | [ 105.053037] soft-irq lock-inversion/321: ok | ok | ok | [ 105.053948] hard-irq read-recursion/123: ok | [ 105.054354] soft-irq read-recursion/123: ok | [ 105.054762] hard-irq read-recursion/132: ok | [ 105.055169] soft-irq read-recursion/132: ok | [ 105.055725] hard-irq read-recursion/213: ok | [ 105.056346] soft-irq read-recursion/213: ok | [ 105.056968] hard-irq read-recursion/231: ok | [ 105.057590] soft-irq read-recursion/231: ok | [ 105.058214] hard-irq read-recursion/312: ok | [ 105.058835] soft-irq read-recursion/312: ok | [ 105.059459] hard-irq read-recursion/321: ok | [ 105.060082] soft-irq read-recursion/321: ok | [ 105.060706] ------------------------------------------------------- [ 105.061132] Good, all 218 testcases passed! | [ 105.061500] --------------------------------- [ 105.062430] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes) [ 105.063429] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes) [ 105.161228] Memory: 2071588k/2097088k available (3287k kernel code, 24272k reserved, 1136k data, 176k init, 1179584k highmem) [ 105.162012] virtual kernel memory layout: [ 105.162013] fixmap : 0xfffad000 - 0xfffff000 ( 328 kB) [ 105.162014] pkmap : 0xff800000 - 0xffc00000 (4096 kB) [ 105.162016] vmalloc : 0xf8800000 - 0xff7fe000 ( 111 MB) [ 105.162017] lowmem : 0xc0000000 - 0xf8000000 ( 896 MB) [ 105.162018] .init : 0xc0554000 - 0xc0580000 ( 176 kB) [ 105.162020] .data : 0xc0435d9a - 0xc0552178 (1136 kB) [ 105.162021] .text : 0xc0100000 - 0xc0435d9a (3287 kB) [ 105.165235] Checking if this processor honours the WP bit even in supervisor mode... Ok. [ 105.166015] SLUB: Genslabs=11, HWalign=32, Order=0-1, MinObjects=4, CPUs=1, Nodes=1 [ 105.316671] Calibrating delay using timer specific routine.. 3829.19 BogoMIPS (lpj=19145988) [ 105.317462] Mount-cache hash table entries: 512 [ 105.318404] CPU: After generic identify, caps: 0383fbff c1c3fbff 00000000 00000000 00000000 00000000 00000000 00000000 [ 105.318413] CPU: L1 I Cache: 64K (64 bytes/line), D cache 64K (64 bytes/line) [ 105.318865] CPU: L2 Cache: 512K (64 bytes/line) [ 105.319240] CPU: After all inits, caps: 0383fbff c1c3fbff 00000000 00000420 00000000 00000000 00000000 00000000 [ 105.319247] Intel machine check architecture supported. [ 105.319642] Intel machine check reporting enabled on CPU#0. [ 105.320050] Compat vDSO mapped to ffffe000. [ 105.320425] CPU: AMD Athlon(tm) XP 2600+ stepping 00 [ 105.320900] Checking 'hlt' instruction... OK. [ 105.357149] Freeing SMP alternatives: 0k freed [ 105.597045] net_namespace: 76 bytes [ 105.597813] NET: Registered protocol family 16 [ 105.630523] PCI: PCI BIOS revision 2.10 entry at 0xfb4a0, last bus=2 [ 105.630952] PCI: Using configuration type 1 [ 105.631317] Setting up standard PCI resources [ 105.636156] SCSI subsystem initialized [ 105.636689] usbcore: registered new interface driver usbfs [ 105.637161] usbcore: registered new interface driver hub [ 105.637647] usbcore: registered new device driver usb [ 105.638212] PCI: Probing PCI hardware [ 105.638588] PCI: Probing PCI hardware (bus 00) [ 105.638730] PCI: nForce2 C1 Halt Disconnect fixup [ 105.641816] PCI: Discovered primary peer bus ff [IRQ] [ 105.642209] PCI: Using IRQ router default [10de/01e0] at 0000:00:00.0 [ 105.697515] PCI: Bridge: 0000:00:08.0 [ 105.697867] IO window: c000-cfff [ 105.698213] MEM window: e2000000-e3ffffff [ 105.698586] PREFETCH window: e4000000-e4ffffff [ 105.698967] PCI: Bridge: 0000:00:1e.0 [ 105.699315] IO window: disabled. [ 105.699659] MEM window: disabled. [ 105.700005] PREFETCH window: disabled. [ 105.714057] Time: tsc clocksource has been installed. [ 105.714472] PCI: Setting latency timer of device 0000:00:08.0 to 64 [ 105.714493] NET: Registered protocol family 2 [ 105.806606] IP route cache hash table entries: 32768 (order: 5, 131072 bytes) [ 105.807674] TCP established hash table entries: 131072 (order: 8, 1048576 bytes) [ 105.809705] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes) [ 105.815159] TCP: Hash tables configured (established 131072 bind 65536) [ 105.815632] TCP reno registered [ 105.837048] Machine check exception polling timer started. [ 105.838519] Initializing RT-Tester: OK [ 105.838861] highmem bounce pool size: 64 pages [ 105.842897] VFS: Disk quotas dquot_6.5.1 [ 105.843396] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 105.844959] JFS: nTxBlock = 8192, nTxLock = 65536 [ 105.851992] SGI XFS with ACLs, no debug enabled [ 105.853796] io scheduler noop registered [ 105.853961] io scheduler anticipatory registered [ 105.854134] io scheduler deadline registered (default) [ 105.854605] io scheduler cfq registered [ 105.898606] spurious 8259A interrupt: IRQ7. [ 105.916454] Boot video device is 0000:01:0a.0 [ 105.920676] Real Time Clock Driver v1.12ac [ 105.921045] Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing disabled [ 105.921947] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A [ 105.922617] serial8250: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A [ 105.923447] Ethernet Channel Bonding Driver: v3.2.3 (December 6, 2007) [ 105.923883] bonding: Warning: either miimon or arp_interval and arp_ip_target module parameters must be specified, otherwise bonding will not detect link failures! see bonding.txt for details. [ 105.925222] 3c59x: Donald Becker and others. [ 105.925394] 0000:01:08.0: 3Com PCI 3c905B Cyclone 100baseTx at f8d1a000. [ 105.948013] forcedeth: Reverse Engineered nForce ethernet driver. Version 0.61. [ 105.948701] PCI: Setting latency timer of device 0000:00:04.0 to 64 [ 106.476883] forcedeth 0000:00:04.0: ifname eth1, PHY OUI 0x20 @ 1, addr 00:11:2f:23:1e:7f [ 106.477571] forcedeth 0000:00:04.0: timirq lnktim desc-v1 [ 106.477993] console [netcon0] enabled [ 106.568403] netconsole: network logging started [ 106.568778] Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2 [ 106.569208] ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx [ 106.569982] NFORCE2: IDE controller (0x10de:0x0065 rev 0xa2) at PCI slot 0000:00:09.0 [ 106.570676] NFORCE2: not 100% native mode: will probe irqs later [ 106.571097] NFORCE2: BIOS didn't set cable bits correctly. Enabling workaround. [ 106.571756] NFORCE2: BIOS didn't set cable bits correctly. Enabling workaround. [ 106.572419] NFORCE2: 0000:00:09.0 (rev a2) UDMA133 controller [ 106.572835] ide0: BM-DMA at 0xf000-0xf007, BIOS settings: hda:DMA, hdb:DMA [ 106.573585] ide1: BM-DMA at 0xf008-0xf00f, BIOS settings: hdc:DMA, hdd:DMA [ 106.574332] Probing IDE interface ide0... [ 106.686110] Clocksource tsc unstable (delta = 80008481 ns) [ 106.696114] Time: pit clocksource has been installed. [ 106.876151] hdb: SAMSUNG SP1604N, ATA DISK drive [ 107.235999] hda: SAMSUNG HD400LD, ATA DISK drive [ 107.236425] hda: host max PIO5 wanted PIO255(auto-tune) selected PIO4 [ 107.236519] hda: UDMA/100 mode selected [ 107.236759] hdb: host max PIO5 wanted PIO255(auto-tune) selected PIO4 [ 107.236815] hdb: UDMA/100 mode selected [ 107.237138] ide0 at 0x1f0-0x1f7,0x3f6 on irq 14 [ 107.237504] Probing IDE interface ide1... [ 107.425799] hdd: probing with STATUS(0x51) instead of ALTSTATUS(0x7f) [ 110.244724] hdd: ST3160023A, ATA DISK drive [ 110.484504] hdc: probing with STATUS(0x51) instead of ALTSTATUS(0x7f) [ 110.604572] hdc: SAMSUNG HD400LD, ATA DISK drive [ 110.604788] hdc: host max PIO5 wanted PIO255(auto-tune) selected PIO4 [ 110.604945] hdc: UDMA/100 mode selected [ 110.605356] hdd: host max PIO5 wanted PIO255(auto-tune) selected PIO4 [ 113.048375] hdd: UDMA/100 mode selected [ 113.049003] ide1 at 0x170-0x177,0x376 on irq 15 [ 113.049700] hda: max request size: 512KiB [ 113.050940] hda: 781422768 sectors (400088 MB) w/8192KiB Cache, CHS=48641/255/63 [ 113.051873] hda: cache flushes supported [ 113.052345] hda: hda1 hda2 hda3 [ 113.060665] hdb: max request size: 512KiB [ 113.061504] hdb: 312581808 sectors (160041 MB) w/2048KiB Cache, CHS=19457/255/63 [ 113.062453] hdb: cache flushes supported [ 113.062839] hdb: hdb1 hdb2 [ 113.065530] hdc: max request size: 512KiB [ 113.069311] hdc: Host Protected Area detected. [ 113.069312] current capacity is 781422768 sectors (400088 MB) [ 113.069313] native capacity is 162249630257328 sectors (83071810691 MB) [ 113.070897] hdc: task_no_data_intr: status=0x51 { DriveReady SeekComplete Error } [ 113.071689] hdc: task_no_data_intr: error=0x10 { SectorIdNotFound }, LBAsect=162249630257327, high=9670831, low=9670831, sector=0 [ 113.072231] ide: failed opcode was: 0x37 [ 113.072452] hdc: 781422768 sectors (400088 MB) w/8192KiB Cache, CHS=48641/255/63 [ 113.073079] hdc: cache flushes supported [ 113.073316] hdc: hdc1 hdc2 hdc3 [ 113.083470] hdd: max request size: 512KiB [ 115.857977] hdd: Host Protected Area detected. [ 115.857978] current capacity is 312581808 sectors (160041 MB) [ 115.857980] native capacity is 177702923509424 sectors (90983896836 MB) [ 116.012815] hdd: task_no_data_intr: status=0x51 { DriveReady SeekComplete Error } [ 116.013648] hdd: task_no_data_intr: error=0x04 { DriveStatusError } [ 116.014198] ide: failed opcode was: 0x37 [ 116.014618] hdd: 312581808 sectors (160041 MB) w/8192KiB Cache, CHS=19457/255/63 [ 116.236765] hdd: cache flushes supported [ 116.237156] hdd: hdd1 hdd2 [ 116.434541] sym0: <895> rev 0x1 at pci 0000:01:06.0 irq 11 [ 116.437182] sym0: Tekram NVRAM, ID 7, Fast-40, LVD, parity checking [ 116.444191] sym0: SCSI BUS has been reset. [ 116.444586] scsi0 : sym-2.2.3 [ 119.441677] target0:0:0: FAST-40 WIDE SCSI 80.0 MB/s ST (25 ns, offset 31) [ 119.443052] scsi 0:0:0:0: Direct-Access IBM-ESXS ST336607LW FN B258 PQ: 0 ANSI: 3 [ 119.443739] target0:0:0: tagged command queuing enabled, command queue depth 16. [ 119.444481] target0:0:0: Beginning Domain Validation [ 119.446316] target0:0:0: asynchronous [ 119.450111] target0:0:0: wide asynchronous [ 119.452001] target0:0:0: FAST-40 WIDE SCSI 80.0 MB/s ST (25 ns, offset 31) [ 119.454463] target0:0:0: Domain Validation skipping write tests [ 119.454881] target0:0:0: Ending Domain Validation [ 119.459031] target0:0:1: FAST-40 WIDE SCSI 80.0 MB/s ST (25 ns, offset 31) [ 119.462567] scsi 0:0:1:0: Direct-Access IBM-ESXS ST318305LW !# B244 PQ: 0 ANSI: 3 [ 119.463230] target0:0:1: tagged command queuing enabled, command queue depth 16. [ 119.463521] target0:0:1: Beginning Domain Validation [ 119.467429] target0:0:1: asynchronous [ 119.479696] target0:0:1: wide asynchronous [ 119.486042] target0:0:1: FAST-40 WIDE SCSI 80.0 MB/s ST (25 ns, offset 31) [ 119.494636] target0:0:1: Domain Validation skipping write tests [ 119.495054] target0:0:1: Ending Domain Validation [ 123.332835] Driver 'sd' needs updating - please use bus_type methods [ 123.348042] sd 0:0:0:0: [sda] 71096640 512-byte hardware sectors (36401 MB) [ 123.349621] sd 0:0:0:0: [sda] Write Protect is off [ 123.350004] sd 0:0:0:0: [sda] Mode Sense: b3 00 10 08 [ 123.351419] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA [ 123.352805] sd 0:0:0:0: [sda] 71096640 512-byte hardware sectors (36401 MB) [ 123.354384] sd 0:0:0:0: [sda] Write Protect is off [ 123.354767] sd 0:0:0:0: [sda] Mode Sense: b3 00 10 08 [ 123.356179] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA [ 123.356877] sda: sda1 sda2 < sda5 sda6 sda7 > [ 123.382734] sd 0:0:0:0: [sda] Attached SCSI disk [ 123.384617] sd 0:0:1:0: [sdb] 35548320 512-byte hardware sectors (18201 MB) [ 123.386429] sd 0:0:1:0: [sdb] Write Protect is off [ 123.386671] sd 0:0:1:0: [sdb] Mode Sense: ab 00 10 08 [ 123.388599] sd 0:0:1:0: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA [ 123.390296] sd 0:0:1:0: [sdb] 35548320 512-byte hardware sectors (18201 MB) [ 123.392110] sd 0:0:1:0: [sdb] Write Protect is off [ 123.392493] sd 0:0:1:0: [sdb] Mode Sense: ab 00 10 08 [ 123.394456] sd 0:0:1:0: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA [ 123.395156] sdb: sdb1 sdb2 [ 123.399379] sd 0:0:1:0: [sdb] Attached SCSI disk [ 123.399918] PCI: Setting latency timer of device 0000:00:02.2 to 64 [ 123.399923] ehci_hcd 0000:00:02.2: EHCI Host Controller [ 123.400838] ehci_hcd 0000:00:02.2: new USB bus registered, assigned bus number 1 [ 123.401176] ehci_hcd 0000:00:02.2: debug port 1 [ 123.401351] PCI: cache line size of 64 is not supported by device 0000:00:02.2 [ 123.401359] ehci_hcd 0000:00:02.2: irq 11, io mem 0xe5000000 [ 123.419021] ehci_hcd 0000:00:02.2: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004 [ 123.420149] usb usb1: configuration #1 chosen from 1 choice [ 123.420685] hub 1-0:1.0: USB hub found [ 123.421088] hub 1-0:1.0: 6 ports detected [ 123.529207] ohci_hcd: 2006 August 04 USB 1.1 'Open' Host Controller (OHCI) Driver [ 123.529317] PCI: Setting latency timer of device 0000:00:02.0 to 64 [ 123.529322] ohci_hcd 0000:00:02.0: OHCI Host Controller [ 123.529571] ohci_hcd 0000:00:02.0: new USB bus registered, assigned bus number 2 [ 123.529862] ohci_hcd 0000:00:02.0: irq 12, io mem 0xe5004000 [ 123.591184] usb usb2: configuration #1 chosen from 1 choice [ 123.591665] hub 2-0:1.0: USB hub found [ 123.592029] hub 2-0:1.0: 3 ports detected [ 123.699039] PCI: Setting latency timer of device 0000:00:02.1 to 64 [ 123.699043] ohci_hcd 0000:00:02.1: OHCI Host Controller [ 123.699513] ohci_hcd 0000:00:02.1: new USB bus registered, assigned bus number 3 [ 123.700187] ohci_hcd 0000:00:02.1: irq 10, io mem 0xe5005000 [ 123.761104] usb usb3: configuration #1 chosen from 1 choice [ 123.761586] hub 3-0:1.0: USB hub found [ 123.761949] hub 3-0:1.0: 3 ports detected [ 124.088735] usb 1-3: new high speed USB device using ehci_hcd and address 3 [ 124.258245] usb 1-3: configuration #1 chosen from 1 choice [ 124.538542] usb 1-4: new high speed USB device using ehci_hcd and address 4 [ 124.689366] usb 1-4: configuration #1 chosen from 1 choice [ 124.988350] usb 2-2: new full speed USB device using ohci_hcd and address 2 [ 125.175225] usb 2-2: configuration #1 chosen from 1 choice [ 125.178500] usbcore: registered new interface driver libusual [ 125.428656] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 125.429252] mice: PS/2 mouse device common for all mice [ 125.429769] md: raid0 personality registered for level 0 [ 125.430173] md: raid1 personality registered for level 1 [ 125.430817] device-mapper: ioctl: 4.12.0-ioctl (2007-10-02) initialised: dm-devel@redhat.com [ 125.431657] usbcore: registered new interface driver usbkbd [ 125.432065] drivers/hid/usbhid/usbkbd.c: :USB HID Boot Protocol keyboard driver [ 125.432838] TCP cubic registered [ 125.433186] NET: Registered protocol family 1 [ 125.433853] NET: Registered protocol family 10 [ 125.434727] lo: Disabled Privacy Extensions [ 125.437904] IPv6 over IPv4 tunneling driver [ 125.439029] sit0: Disabled Privacy Extensions [ 125.440042] NET: Registered protocol family 17 [ 125.440560] Bridge firewalling registered [ 125.440935] Using IPI Shortcut mode [ 125.441256] BIOS EDD facility v0.16 2004-Jun-25, 6 devices found [ 125.453145] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 [ 125.548523] md: Autodetecting RAID arrays. [ 126.938199] md: Scanned 11 and added 11 devices. [ 126.938576] md: autorun ... [ 126.938899] md: considering sdb2 ... [ 126.939104] md: adding sdb2 ... [ 126.939263] md: adding hdd2 ... [ 126.939422] md: hdd1 has different UUID to sdb2 [ 126.939601] md: hdc3 has different UUID to sdb2 [ 126.939774] md: hdc2 has different UUID to sdb2 [ 126.939946] md: hdc1 has different UUID to sdb2 [ 126.940122] md: adding hdb2 ... [ 126.940280] md: hdb1 has different UUID to sdb2 [ 126.940453] md: hda3 has different UUID to sdb2 [ 126.940738] md: hda2 has different UUID to sdb2 [ 126.941115] md: hda1 has different UUID to sdb2 [ 126.941618] md: created md4 [ 126.941944] md: bind [ 126.942361] md: bind [ 126.942699] md: bind [ 126.943036] md: running: [ 126.943787] md4: setting max_sectors to 128, segment boundary to 32767 [ 126.944227] raid0: looking at sdb2 [ 126.944568] raid0: comparing sdb2(12891072) with sdb2(12891072) [ 126.945031] raid0: END [ 126.945347] raid0: ==> UNIQUE [ 126.945680] raid0: 1 zones [ 126.946000] raid0: looking at hdd2 [ 126.946341] raid0: comparing hdd2(12297664) with sdb2(12891072) [ 126.946803] raid0: NOT EQUAL [ 126.947134] raid0: comparing hdd2(12297664) with hdd2(12297664) [ 126.947607] raid0: END [ 126.947922] raid0: ==> UNIQUE [ 126.948255] raid0: 2 zones [ 126.948575] raid0: looking at hdb2 [ 126.948916] raid0: comparing hdb2(12297664) with sdb2(12891072) [ 126.949378] raid0: NOT EQUAL [ 126.949716] raid0: comparing hdb2(12297664) with hdd2(12297664) [ 126.950179] raid0: EQUAL [ 126.950499] raid0: FINAL 2 zones [ 126.950836] raid0: zone 1 [ 126.951154] raid0: checking sdb2 ... contained as device 0 [ 126.951599] (12891072) is smallest!. [ 126.951950] raid0: checking hdb2 ... nope. [ 126.952353] raid0: checking hdd2 ... nope. [ 126.952756] raid0: zone->nb_dev: 1, size: 593408 [ 126.953133] raid0: current zone offset: 12891072 [ 126.953358] raid0: done. [ 126.953505] raid0 : md_size is 37486400 blocks. [ 126.953676] raid0 : conf->hash_spacing is 36892992 blocks. [ 126.953857] raid0 : nb_zone is 2. [ 126.954013] raid0 : Allocating 8 bytes for hash. [ 126.954339] md: considering hdd1 ... [ 126.954511] md: adding hdd1 ... [ 126.954669] md: hdc3 has different UUID to hdd1 [ 126.954872] md: hdc2 has different UUID to hdd1 [ 126.955248] md: hdc1 has different UUID to hdd1 [ 126.955626] md: adding hdb1 ... [ 126.955965] md: hda3 has different UUID to hdd1 [ 126.956341] md: hda2 has different UUID to hdd1 [ 126.956717] md: hda1 has different UUID to hdd1 [ 126.957189] md: created md3 [ 126.957524] md: bind [ 126.957862] md: bind [ 126.958199] md: running: [ 126.958852] raid1: raid set md3 active with 2 out of 2 mirrors [ 126.959408] md: considering hdc3 ... [ 126.959773] md: adding hdc3 ... [ 126.960112] md: hdc2 has different UUID to hdc3 [ 126.960489] md: hdc1 has different UUID to hdc3 [ 126.960868] md: adding hda3 ... [ 126.961206] md: hda2 has different UUID to hdc3 [ 126.961583] md: hda1 has different UUID to hdc3 [ 126.962079] md: created md2 [ 126.962404] md: bind [ 126.962744] md: bind [ 126.963080] md: running: [ 126.963638] md2: setting max_sectors to 128, segment boundary to 32767 [ 126.964072] raid0: looking at hdc3 [ 126.964413] raid0: comparing hdc3(771008) with hdc3(771008) [ 126.964866] raid0: END [ 126.965182] raid0: ==> UNIQUE [ 126.965515] raid0: 1 zones [ 126.965835] raid0: looking at hda3 [ 126.966176] raid0: comparing hda3(771008) with hdc3(771008) [ 126.966628] raid0: EQUAL [ 126.966949] raid0: FINAL 1 zones [ 126.967285] raid0: done. [ 126.967573] raid0 : md_size is 1542016 blocks. [ 126.967742] raid0 : conf->hash_spacing is 1542016 blocks. [ 126.967922] raid0 : nb_zone is 1. [ 126.968078] raid0 : Allocating 4 bytes for hash. [ 126.968311] md: considering hdc2 ... [ 126.968480] md: adding hdc2 ... [ 126.968637] md: hdc1 has different UUID to hdc2 [ 126.968812] md: adding hda2 ... [ 126.968971] md: hda1 has different UUID to hdc2 [ 126.969324] md: created md1 [ 126.969655] md: bind [ 126.969993] md: bind [ 126.970329] md: running: [ 126.970958] raid1: raid set md1 active with 2 out of 2 mirrors [ 126.971448] md: considering hdc1 ... [ 126.971807] md: adding hdc1 ... [ 126.972148] md: adding hda1 ... [ 126.972486] md: created md0 [ 126.972809] md: bind [ 126.973148] md: bind [ 126.973492] md: running: [ 126.987866] raid1: raid set md0 active with 2 out of 2 mirrors [ 126.988356] md: ... autorun DONE. [ 127.012366] kjournald starting. Commit interval 5 seconds [ 127.012950] EXT3-fs: mounted filesystem with ordered data mode. [ 127.013375] VFS: Mounted root (ext3 filesystem) readonly. [ 127.013998] Freeing unused kernel memory: 176k freed [ 128.477890] udev: renamed network interface eth0 to eth1 [ 128.518270] udev: renamed network interface eth1_rename to eth0 [ 128.600518] ieee80211_crypt: registered algorithm 'NULL' [ 128.725922] ieee80211: 802.11 data/management/control stack, git-1.1.13 [ 128.726363] ieee80211: Copyright (C) 2004-2005 Intel Corporation [ 128.988464] usbcore: registered new interface driver usbserial [ 128.988892] drivers/usb/serial/usb-serial.c: USB Serial Driver core [ 129.001610] drivers/usb/serial/usb-serial.c: USB Serial support registered for pl2303 [ 129.002330] pl2303 2-2:1.0: pl2303 converter detected [ 129.002912] usb 2-2: pl2303 converter now attached to ttyUSB0 [ 129.003337] usbcore: registered new interface driver pl2303 [ 129.003742] drivers/usb/serial/pl2303.c: Prolific PL2303 USB to serial adaptor driver [ 129.013080] i2c-adapter i2c-0: nForce2 SMBus adapter at 0x5000 [ 129.013525] i2c-adapter i2c-1: nForce2 SMBus adapter at 0x5500 [ 129.073444] PCI: Setting latency timer of device 0000:00:06.0 to 64 [ 129.246537] usb 1-4: reset high speed USB device using ehci_hcd and address 4 [ 129.398817] zd1211rw 1-4:1.0: eth2 [ 129.399186] usbcore: registered new interface driver zd1211rw [ 129.406491] intel8x0_measure_ac97_clock: measured 58333 usecs [ 129.406923] intel8x0: clocking to 47458 [ 156.259879] EXT3 FS on sda5, internal journal [ 165.149448] fuse init (API version 7.9) [ 165.205880] ReiserFS: sdb1: found reiserfs format "3.6" with standard journal [ 165.205979] ReiserFS: sdb1: using ordered data mode [ 165.212544] ReiserFS: sdb1: journal params: device sdb1, size 8192, journal first block 18, max trans len 1024, max batch 900, max commit age 30, max trans age 30 [ 165.213623] ReiserFS: sdb1: checking transaction log (sdb1) [ 165.248588] ReiserFS: sdb1: Using r5 hash to sort names [ 165.385465] kjournald2 starting. Commit interval 5 seconds [ 165.385585] EXT4-fs warning: maximal mount count reached, running e2fsck is recommended [ 165.387474] EXT4 FS on md4, internal journal [ 165.388199] EXT4-fs: mounted filesystem with ordered data mode. [ 165.388201] EXT4-fs: file extents enabled [ 165.402179] Adding 1542008k swap on /dev/mapper/md2. Priority:1 extents:1 across:1542008k [ 173.685940] zd1211rw 1-4:1.0: firmware version 4605 [ 173.725919] zd1211rw 1-4:1.0: zd1211 chip 0ace:1211 v4330 high 00-30-95 RF2959_RF pa0 ----- [ 173.752093] ADDRCONF(NETDEV_UP): eth2: link is not ready [ 175.406163] SoftMAC: Open Authentication completed with 00:04:0e:78:9f:2e [ 175.429037] ADDRCONF(NETDEV_CHANGE): eth2: link becomes ready [ 175.447687] ieee80211_crypt: registered algorithm 'CCMP' [ 175.459858] ieee80211_crypt: registered algorithm 'TKIP' [ 179.458390] tun: Universal TUN/TAP device driver, 1.6 [ 179.458394] tun: (C) 1999-2004 Max Krasnyansky [ 179.460398] tun: Disabled Privacy Extensions [ 181.098631] RPC: Registered udp transport module. [ 181.098636] RPC: Registered tcp transport module. [ 197.481287] Installing knfsd (copyright (C) 1996 okir@monad.swb.de). [ 197.748292] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory [ 197.757482] NFSD: starting 90-second grace period [ 210.781746] device eth2 entered promiscuous mode [ 210.811708] device eth2 left promiscuous mode [ 210.841708] device eth2 entered promiscuous mode [ 220.702069] ip_tables: (C) 2000-2006 Netfilter Core Team [ 220.818626] nf_conntrack version 0.5.0 (16384 buckets, 65536 max) [ 221.189765] u32 classifier [ 221.189770] Performance counters on [ 221.189772] Actions configured [ 425.280551] kjournald starting. Commit interval 5 seconds [ 425.281462] EXT3-fs: mounted filesystem with ordered data mode. [ 429.479446] XFS mounting filesystem dm-2 [ 429.785249] Ending clean XFS mount for filesystem: dm-2 [ 434.129864] XFS mounting filesystem dm-3 [ 434.488017] Ending clean XFS mount for filesystem: dm-3 [ 440.104259] kjournald starting. Commit interval 5 seconds [ 440.117205] EXT3 FS on dm-4, internal journal [ 440.117224] EXT3-fs: mounted filesystem with ordered data mode. [ 6163.626007] tun: Disabled Privacy Extensions [35011.918900] snort[3467]: segfault at 00000008 eip 080abdf0 esp bff74590 error 4 [35011.990575] device eth2 left promiscuous mode [39810.039912] w83l785ts 1-002e: Couldn't read value from register 0x53. Please report. [52585.524522] php-cgi[28651]: segfault at 288a75b4 eip 082c7d1e esp bf8a5e20 error 4 [70577.683874] usb 1-1: new high speed USB device using ehci_hcd and address 5 [70577.836560] usb 1-1: configuration #1 chosen from 1 choice [70577.981162] Initializing USB Mass Storage driver... [70577.985927] scsi1 : SCSI emulation for USB Mass Storage devices [70577.987006] usb-storage: device found at 3 [70577.987008] usb-storage: waiting for device to settle before scanning [70577.987282] scsi2 : SCSI emulation for USB Mass Storage devices [70577.990351] usbcore: registered new interface driver usb-storage [70577.990359] USB Mass Storage support registered. [70577.990635] usb-storage: device found at 5 [70577.990637] usb-storage: waiting for device to settle before scanning [70583.000545] scsi 2:0:0:0: Direct-Access Audio Player PQ: 0 ANSI: 0 CCS [70583.006908] sd 2:0:0:0: [sdc] 3979264 512-byte hardware sectors (2037 MB) [70583.007530] sd 2:0:0:0: [sdc] Write Protect is off [70583.007534] sd 2:0:0:0: [sdc] Mode Sense: 3b 00 00 00 [70583.007536] sd 2:0:0:0: [sdc] Assuming drive cache: write through [70583.011004] sd 2:0:0:0: [sdc] 3979264 512-byte hardware sectors (2037 MB) [70583.013734] sd 2:0:0:0: [sdc] Write Protect is off [70583.013739] sd 2:0:0:0: [sdc] Mode Sense: 3b 00 00 00 [70583.013742] sd 2:0:0:0: [sdc] Assuming drive cache: write through [70583.014131] sdc: sdc1 [70583.015898] sd 2:0:0:0: [sdc] Attached SCSI removable disk [70583.017944] scsi 1:0:0:0: CD-ROM HL-DT-ST DVD-RAM GSA-E60L 1.00 PQ: 0 ANSI: 0 [70583.018454] usb-storage: device scan complete [70583.019696] usb-storage: device scan complete [70583.114759] Driver 'sr' needs updating - please use bus_type methods [70583.127957] sr0: scsi3-mmc drive: 48x/48x writer dvd-ram cd/rw xa/form2 cdda tray [70583.127965] Uniform CD-ROM driver Revision: 3.20 [70583.128393] sr 1:0:0:0: Attached scsi CD-ROM sr0 [70583.157137] sd 0:0:0:0: Attached scsi generic sg0 type 0 [70583.157176] sd 0:0:1:0: Attached scsi generic sg1 type 0 [70583.157214] sd 2:0:0:0: Attached scsi generic sg2 type 0 [70583.157252] sr 1:0:0:0: Attached scsi generic sg3 type 5 [92455.322206] tun: Disabled Privacy Extensions [94258.239697] usb 1-1: USB disconnect, address 5 [178788.000242] tun: Disabled Privacy Extensions [265057.770119] tun: Disabled Privacy Extensions [351341.513623] tun: Disabled Privacy Extensions [389774.384146] usb 1-1: new high speed USB device using ehci_hcd and address 6 [389774.536968] usb 1-1: configuration #1 chosen from 1 choice [389774.545313] scsi3 : SCSI emulation for USB Mass Storage devices [389774.547071] usb-storage: device found at 6 [389774.547075] usb-storage: waiting for device to settle before scanning [389779.559558] scsi 3:0:0:0: Direct-Access Audio Player PQ: 0 ANSI: 0 CCS [389779.565796] sd 3:0:0:0: [sdc] 3979264 512-byte hardware sectors (2037 MB) [389779.566509] sd 3:0:0:0: [sdc] Write Protect is off [389779.566512] sd 3:0:0:0: [sdc] Mode Sense: 3b 00 00 00 [389779.566515] sd 3:0:0:0: [sdc] Assuming drive cache: write through [389779.568761] sd 3:0:0:0: [sdc] 3979264 512-byte hardware sectors (2037 MB) [389779.569383] sd 3:0:0:0: [sdc] Write Protect is off [389779.569386] sd 3:0:0:0: [sdc] Mode Sense: 3b 00 00 00 [389779.569389] sd 3:0:0:0: [sdc] Assuming drive cache: write through [389779.569804] sdc: sdc1 [389779.570897] sd 3:0:0:0: [sdc] Attached SCSI removable disk [389779.571422] sd 3:0:0:0: Attached scsi generic sg2 type 0 [389779.573620] usb-storage: device scan complete [393316.907888] EXT3 FS on dm-1, internal journal [394860.181635] [394860.181640] ======================================================= [394860.181896] [ INFO: possible circular locking dependency detected ] [394860.182052] 2.6.24.1 #1 [394860.182161] ------------------------------------------------------- [394860.182512] mv/29055 is trying to acquire lock: [394860.182846] (iprune_mutex){--..}, at: [] shrink_icache_memory+0x72/0x220 [394860.183475] [394860.183475] but task is already holding lock: [394860.184049] (&(&ip->i_iolock)->mr_lock){----}, at: [] xfs_ilock+0x96/0xb0 [394860.184681] [394860.184681] which lock already depends on the new lock. [394860.184683] [394860.185526] [394860.185527] the existing dependency chain (in reverse order) is: [394860.186149] [394860.186149] -> #1 (&(&ip->i_iolock)->mr_lock){----}: [394860.186741] [] __lock_acquire+0xbdc/0x10b0 [394860.187107] [] xfs_ilock+0x96/0xb0 [394860.187453] [] lock_acquire+0x5f/0x80 [394860.187806] [] xfs_ilock+0x96/0xb0 [394860.188152] [] down_write_nested+0x41/0x60 [394860.188518] [] xfs_ilock+0x96/0xb0 [394860.188863] [] xfs_ilock+0x96/0xb0 [394860.189209] [] xfs_ireclaim+0x1a/0x60 [394860.189562] [] xfs_finish_reclaim+0x53/0x1a0 [394860.189933] [] xfs_fs_clear_inode+0x5e/0x90 [394860.190302] [] clear_inode+0x82/0x160 [394860.190655] [] shrink_icache_memory+0x159/0x220 [394860.191034] [] dispose_list+0x1a/0xc0 [394860.191387] [] shrink_icache_memory+0x1e2/0x220 [394860.191766] [] shrink_slab+0x101/0x160 [394860.192122] [] kswapd+0x2aa/0x410 [394860.192467] [] autoremove_wake_function+0x0/0x40 [394860.192848] [] kswapd+0x0/0x410 [394860.193186] [] kthread+0x42/0x70 [394860.193527] [] kthread+0x0/0x70 [394860.193865] [] kernel_thread_helper+0x7/0x10 [394860.194236] [] 0xffffffff [394860.194651] [394860.194652] -> #0 (iprune_mutex){--..}: [394860.195022] [] print_circular_bug_entry+0x40/0x50 [394860.195184] [] __lock_acquire+0x9f9/0x10b0 [394860.195338] [] lock_acquire+0x5f/0x80 [394860.195489] [] shrink_icache_memory+0x72/0x220 [394860.195651] [] mutex_lock_nested+0x88/0x2c0 [394860.195808] [] shrink_icache_memory+0x72/0x220 [394860.195968] [] shrink_icache_memory+0x72/0x220 [394860.196126] [] shrink_icache_memory+0x72/0x220 [394860.196285] [] shrink_slab+0x21/0x160 [394860.196436] [] shrink_slab+0x101/0x160 [394860.196766] [] try_to_free_pages+0x154/0x230 [394860.197137] [] __alloc_pages+0x14b/0x370 [394860.197498] [] trace_hardirqs_on+0x9f/0x140 [394860.197867] [] find_lock_page+0x25/0xa0 [394860.198225] [] __grab_cache_page+0x81/0xc0 [394860.198591] [] block_write_begin+0x77/0xf0 [394860.198958] [] __lock_acquire+0x4f9/0x10b0 [394860.199325] [] xfs_vm_write_begin+0x46/0x50 [394860.199693] [] xfs_get_blocks+0x0/0x30 [394860.200049] [] generic_file_buffered_write+0x117/0x650 [394860.200446] [] xfs_ilock+0x6d/0xb0 [394860.200792] [] xfs_write+0x7ec/0x900 [394860.201142] [] do_generic_mapping_read+0x222/0x440 [394860.201529] [] xfs_file_aio_write+0x5c/0x70 [394860.201897] [] do_sync_write+0xd5/0x120 [394860.202256] [] trace_hardirqs_on+0x9f/0x140 [394860.202624] [] restore_nocheck+0x12/0x15 [394860.202985] [] autoremove_wake_function+0x0/0x40 [394860.203367] [] do_sync_write+0x0/0x120 [394860.203722] [] vfs_write+0x9f/0x140 [394860.204071] [] sys_write+0x41/0x70 [394860.204416] [] sysenter_past_esp+0x5f/0xa5 [394860.204782] [] 0xffffffff [394860.205106] [394860.205106] other info that might help us debug this: [394860.205108] [394860.205946] 3 locks held by mv/29055: [394860.206255] #0: (&sb->s_type->i_mutex_key#13){--..}, at: [] xfs_write+0x41f/0x900 [394860.206909] #1: (&(&ip->i_iolock)->mr_lock){----}, at: [] xfs_ilock+0x96/0xb0 [394860.207553] #2: (shrinker_rwsem){----}, at: [] shrink_slab+0x21/0x160 [394860.208177] [394860.208178] stack backtrace: [394860.208710] Pid: 29055, comm: mv Not tainted 2.6.24.1 #1 [394860.209067] [] print_circular_bug_tail+0x72/0x80 [394860.223139] [] __lock_acquire+0x9f9/0x10b0 [394860.223463] [] lock_acquire+0x5f/0x80 [394860.223598] [] shrink_icache_memory+0x72/0x220 [394860.223741] [] mutex_lock_nested+0x88/0x2c0 [394860.223884] [] shrink_icache_memory+0x72/0x220 [394860.224027] [] shrink_icache_memory+0x72/0x220 [394860.224175] [] shrink_icache_memory+0x72/0x220 [394860.224318] [] shrink_slab+0x21/0x160 [394860.224455] [] shrink_slab+0x101/0x160 [394860.224595] [] try_to_free_pages+0x154/0x230 [394860.224741] [] __alloc_pages+0x14b/0x370 [394860.224879] [] trace_hardirqs_on+0x9f/0x140 [394860.225069] [] find_lock_page+0x25/0xa0 [394860.225415] [] __grab_cache_page+0x81/0xc0 [394860.225766] [] block_write_begin+0x77/0xf0 [394860.226115] [] __lock_acquire+0x4f9/0x10b0 [394860.226467] [] xfs_vm_write_begin+0x46/0x50 [394860.226820] [] xfs_get_blocks+0x0/0x30 [394860.227159] [] generic_file_buffered_write+0x117/0x650 [394860.227546] [] xfs_ilock+0x6d/0xb0 [394860.227878] [] xfs_write+0x7ec/0x900 [394860.228213] [] do_generic_mapping_read+0x222/0x440 [394860.228592] [] xfs_file_aio_write+0x5c/0x70 [394860.228945] [] do_sync_write+0xd5/0x120 [394860.229287] [] trace_hardirqs_on+0x9f/0x140 [394860.229642] [] restore_nocheck+0x12/0x15 [394860.229986] [] autoremove_wake_function+0x0/0x40 [394860.230357] [] do_sync_write+0x0/0x120 [394860.230696] [] vfs_write+0x9f/0x140 [394860.231029] [] sys_write+0x41/0x70 [394860.231360] [] sysenter_past_esp+0x5f/0xa5 [394860.231715] =======================