[ 0.000000] Linux version 2.6.25-rc7 (evil@sheep) (gcc version 4.2.3 (Debian 4.2.3-3)) #1 Thu Mar 27 23:20:30 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] Scan SMP from c0000000 for 1024 bytes. [ 0.000000] Scan SMP from c009fc00 for 1024 bytes. [ 0.000000] Scan SMP from c00f0000 for 65536 bytes. [ 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 netconsole=@/,514@192.168.10.13/00:14:51:02:05:86 [ 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=c0595000 soft=c0594000 [ 0.000000] PID hash table entries: 4096 (order: 12, 16384 bytes) [ 0.000000] Detected 1913.208 MHz processor. [ 0.010000] Console: colour VGA+ 80x25 [ 0.010000] console [tty0] enabled [ 0.010000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.010000] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.010000] ... MAX_LOCK_DEPTH: 48 [ 0.010000] ... MAX_LOCKDEP_KEYS: 2048 [ 0.010000] ... CLASSHASH_SIZE: 1024 [ 0.010000] ... MAX_LOCKDEP_ENTRIES: 8192 [ 0.010000] ... MAX_LOCKDEP_CHAINS: 16384 [ 0.010000] ... CHAINHASH_SIZE: 8192 [ 0.010000] memory used by lock dependency info: 992 kB [ 0.010000] per task-struct memory footprint: 1920 bytes [ 0.010000] ------------------------ [ 0.010000] | Locking API testsuite: [ 0.010000] ---------------------------------------------------------------------------- [ 0.010000] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.010000] -------------------------------------------------------------------------- [ 0.010000] A-A deadlock: ok | ok | ok | ok | ok | ok | [ 0.010000] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | [ 0.010000] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | [ 0.010000] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | [ 0.010000] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | [ 0.010000] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | [ 0.010000] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | [ 0.010000] double unlock: ok | ok | ok | ok | ok | ok | [ 0.010000] initialize held: ok | ok | ok | ok | ok | ok | [ 0.010000] bad unlock order: ok | ok | ok | ok | ok | ok | [ 0.010000] -------------------------------------------------------------------------- [ 0.010000] recursive read-lock: | ok | | ok | [ 0.010000] recursive read-lock #2: | ok | | ok | [ 0.010000] mixed read-write-lock: | ok | | ok | [ 0.010000] mixed write-read-lock: | ok | | ok | [ 0.010000] -------------------------------------------------------------------------- [ 0.010000] hard-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.010000] soft-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.010000] hard-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.010000] soft-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.010000] sirq-safe-A => hirqs-on/12: ok | ok | ok | [ 0.010000] sirq-safe-A => hirqs-on/21: ok | ok | ok | [ 0.010000] hard-safe-A + irqs-on/12: ok | ok | ok | [ 0.010000] soft-safe-A + irqs-on/12: ok | ok | ok | [ 0.010000] hard-safe-A + irqs-on/21: ok | ok | ok | [ 0.010000] soft-safe-A + irqs-on/21: ok | ok | ok | [ 0.010000] hard-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.010000] soft-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.010000] hard-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.010000] soft-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.010000] hard-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.010000] soft-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.010000] hard-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.010000] soft-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.010000] hard-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.010000] soft-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.010000] hard-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.010000] soft-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.010000] hard-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.010000] soft-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.010000] hard-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.010000] soft-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.010000] hard-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.010000] soft-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.010000] hard-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.010000] soft-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.010000] hard-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.010000] soft-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.010000] hard-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.010000] soft-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.010000] hard-irq lock-inversion/123: ok | ok | ok | [ 0.010000] soft-irq lock-inversion/123: ok | ok | ok | [ 0.010000] hard-irq lock-inversion/132: ok | ok | ok | [ 0.010000] soft-irq lock-inversion/132: ok | ok | ok | [ 0.010000] hard-irq lock-inversion/213: ok | ok | ok | [ 0.010000] soft-irq lock-inversion/213: ok | ok | ok | [ 0.010000] hard-irq lock-inversion/231: ok | ok | ok | [ 0.010000] soft-irq lock-inversion/231: ok | ok | ok | [ 0.010000] hard-irq lock-inversion/312: ok | ok | ok | [ 0.010000] soft-irq lock-inversion/312: ok | ok | ok | [ 0.010000] hard-irq lock-inversion/321: ok | ok | ok | [ 0.010000] soft-irq lock-inversion/321: ok | ok | ok | [ 0.010000] hard-irq read-recursion/123: ok | [ 0.010000] soft-irq read-recursion/123: ok | [ 0.010000] hard-irq read-recursion/132: ok | [ 0.010000] soft-irq read-recursion/132: ok | [ 0.010000] hard-irq read-recursion/213: ok | [ 0.010000] soft-irq read-recursion/213: ok | [ 0.010000] hard-irq read-recursion/231: ok | [ 0.010000] soft-irq read-recursion/231: ok | [ 0.010000] hard-irq read-recursion/312: ok | [ 0.010000] soft-irq read-recursion/312: ok | [ 0.010000] hard-irq read-recursion/321: ok | [ 0.010000] soft-irq read-recursion/321: ok | [ 0.010000] ------------------------------------------------------- [ 0.010000] Good, all 218 testcases passed! | [ 0.010000] --------------------------------- [ 0.010000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes) [ 0.010000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes) [ 0.010000] Memory: 2071528k/2097088k available (3331k kernel code, 24328k reserved, 1145k data, 192k init, 1179584k highmem) [ 0.010000] virtual kernel memory layout: [ 0.010000] fixmap : 0xfffad000 - 0xfffff000 ( 328 kB) [ 0.010000] pkmap : 0xff800000 - 0xffc00000 (4096 kB) [ 0.010000] vmalloc : 0xf8800000 - 0xff7fe000 ( 111 MB) [ 0.010000] lowmem : 0xc0000000 - 0xf8000000 ( 896 MB) [ 0.010000] .init : 0xc0561000 - 0xc0591000 ( 192 kB) [ 0.010000] .data : 0xc0440c26 - 0xc055f24c (1145 kB) [ 0.010000] .text : 0xc0100000 - 0xc0440c26 (3331 kB) [ 0.010000] Checking if this processor honours the WP bit even in supervisor mode...Ok. [ 0.010000] CPA: page pool initialized 1 of 1 pages preallocated [ 0.010000] SLUB: Genslabs=12, HWalign=32, Order=0-1, MinObjects=4, CPUs=1, Nodes=1 [ 0.160003] Calibrating delay using timer specific routine.. 3829.37 BogoMIPS (lpj=19146885) [ 0.160778] Mount-cache hash table entries: 512 [ 0.161813] CPU: L1 I Cache: 64K (64 bytes/line), D cache 64K (64 bytes/line) [ 0.162266] CPU: L2 Cache: 512K (64 bytes/line) [ 0.162641] Intel machine check architecture supported. [ 0.163036] Intel machine check reporting enabled on CPU#0. [ 0.163444] Compat vDSO mapped to ffffe000. [ 0.163814] CPU: AMD Athlon(tm) XP 2600+ stepping 00 [ 0.164289] Checking 'hlt' instruction... OK. [ 0.200511] Freeing SMP alternatives: 0k freed [ 0.320534] net_namespace: 600 bytes [ 0.321211] NET: Registered protocol family 16 [ 0.353907] PCI: PCI BIOS revision 2.10 entry at 0xfb4a0, last bus=2 [ 0.354336] PCI: Using configuration type 1 [ 0.354700] Setting up standard PCI resources [ 0.359663] SCSI subsystem initialized [ 0.360194] usbcore: registered new interface driver usbfs [ 0.360664] usbcore: registered new interface driver hub [ 0.361041] usbcore: registered new device driver usb [ 0.361452] PCI: Probing PCI hardware [ 0.361636] PCI: Probing PCI hardware (bus 00) [ 0.361796] pci 0000:00:00.0: nForce2 C1 Halt Disconnect fixup [ 0.364796] PCI: Discovered primary peer bus ff [IRQ] [ 0.370061] PCI: Using IRQ router default [10de/01e0] at 0000:00:00.0 [ 0.413749] PCI: Bridge: 0000:00:08.0 [ 0.414100] IO window: c000-cfff [ 0.414446] MEM window: 0xe2000000-0xe3ffffff [ 0.414824] PREFETCH window: 0x00000000e4000000-0x00000000e4ffffff [ 0.415256] PCI: Bridge: 0000:00:1e.0 [ 0.415605] IO window: disabled. [ 0.415949] MEM window: disabled. [ 0.416294] PREFETCH window: disabled. [ 0.416664] PCI: Setting latency timer of device 0000:00:08.0 to 64 [ 0.416683] NET: Registered protocol family 2 [ 0.417242] IP route cache hash table entries: 32768 (order: 5, 131072 bytes) [ 0.418241] TCP established hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.420246] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes) [ 0.425889] TCP: Hash tables configured (established 131072 bind 65536) [ 0.426365] TCP reno registered [ 0.427360] Machine check exception polling timer started. [ 0.428789] Initializing RT-Tester: OK [ 0.429353] highmem bounce pool size: 64 pages [ 0.433935] VFS: Disk quotas dquot_6.5.1 [ 0.434432] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 0.436127] JFS: nTxBlock = 8192, nTxLock = 65536 [ 0.443154] SGI XFS with ACLs, no debug enabled [ 0.445026] io scheduler noop registered [ 0.445384] io scheduler anticipatory registered [ 0.445762] io scheduler deadline registered (default) [ 0.446254] io scheduler cfq registered [ 0.510001] pci 0000:01:0a.0: Boot video device [ 0.510001] Real Time Clock Driver v1.12ac [ 0.510002] Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing disabled [ 0.510002] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A [ 0.510002] serial8250: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A [ 0.510002] Ethernet Channel Bonding Driver: v3.2.4 (January 28, 2008) [ 0.510002] 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. [ 0.510002] forcedeth: Reverse Engineered nForce ethernet driver. Version 0.61. [ 0.510002] PCI: Setting latency timer of device 0000:00:04.0 to 64 [ 1.050013] forcedeth 0000:00:04.0: ifname eth0, PHY OUI 0x20 @ 1, addr 00:11:2f:23:1e:7f [ 1.050013] forcedeth 0000:00:04.0: timirq lnktim desc-v1 [ 1.050013] netconsole: local port 6665 [ 1.050013] netconsole: local IP 0.0.0.0 [ 1.050013] netconsole: interface eth0 [ 1.050013] netconsole: remote port 514 [ 1.050013] netconsole: remote IP 192.168.10.13 [ 1.050013] netconsole: remote ethernet address 00:14:51:02:05:86 [ 1.050013] netconsole: device eth0 not up yet, forcing it [ 1.050013] eth0: no link during initialization. [ 2.860013] spurious 8259A interrupt: IRQ7. [ 5.050015] netconsole: timeout waiting for carrier [ 5.050400] netconsole: no IP address for eth0, aborting [ 5.050798] netconsole: cleaning up [ 5.051142] Uniform Multi-Platform E-IDE driver [ 5.051518] ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx [ 5.052313] NFORCE2: 0000:00:09.0 (rev a2) UDMA133 controller [ 5.052733] NFORCE2: IDE controller (0x10de:0x0065 rev 0xa2) at PCI slot 0000:00:09.0 [ 5.053429] NFORCE2: not 100% native mode: will probe irqs later [ 5.053851] NFORCE2: BIOS didn't set cable bits correctly. Enabling workaround. [ 5.054510] NFORCE2: BIOS didn't set cable bits correctly. Enabling workaround. [ 5.055180] ide0: BM-DMA at 0xf000-0xf007, BIOS settings: hda:DMA, hdb:DMA [ 5.055888] ide1: BM-DMA at 0xf008-0xf00f, BIOS settings: hdc:DMA, hdd:DMA [ 5.056594] Probing IDE interface ide0... [ 5.370034] hdb: SAMSUNG SP1604N, ATA DISK drive [ 5.732903] hda: SAMSUNG HD400LD, ATA DISK drive [ 5.733337] hda: host max PIO5 wanted PIO255(auto-tune) selected PIO4 [ 5.733429] hda: UDMA/100 mode selected [ 5.733876] hdb: host max PIO5 wanted PIO255(auto-tune) selected PIO4 [ 5.733932] hdb: UDMA/100 mode selected [ 5.734345] Probing IDE interface ide1... [ 5.924298] hdd: probing with STATUS(0x50) instead of ALTSTATUS(0x7f) [ 6.045372] hdd: ST3160023A, ATA DISK drive [ 6.287165] hdc: probing with STATUS(0x50) instead of ALTSTATUS(0x7f) [ 6.408239] hdc: SAMSUNG HD400LD, ATA DISK drive [ 6.408668] hdc: host max PIO5 wanted PIO255(auto-tune) selected PIO4 [ 6.408768] hdc: UDMA/100 mode selected [ 6.409227] hdd: host max PIO5 wanted PIO255(auto-tune) selected PIO4 [ 6.409640] hdd: UDMA/100 mode selected [ 6.409855] ide0 at 0x1f0-0x1f7,0x3f6 on irq 14 [ 6.409855] ide1 at 0x170-0x177,0x376 on irq 15 [ 6.409855] hda: max request size: 512KiB [ 6.410800] hda: 781422768 sectors (400088 MB) w/8192KiB Cache, CHS=48641/255/63 [ 6.410954] hda: cache flushes supported [ 6.410954] hda: hda1 hda2 hda3 [ 6.420936] hdb: max request size: 512KiB [ 6.421416] hdb: 312581808 sectors (160041 MB) w/2048KiB Cache, CHS=19457/255/63 [ 6.421601] hdb: cache flushes supported [ 6.421601] hdb: hdb1 hdb2 [ 6.426371] hdc: max request size: 512KiB [ 6.429368] hdc: Host Protected Area detected. [ 6.429369] current capacity is 781422768 sectors (400088 MB) [ 6.429371] native capacity is 162249630257328 sectors (83071810691 MB) [ 6.430716] hdc: task_no_data_intr: status=0x51 { DriveReady SeekComplete Error } [ 6.431550] hdc: task_no_data_intr: error=0x10 { SectorIdNotFound }, LBAsect=162249630257327, sector=0 [ 6.431601] ide: failed opcode was: 0x37 [ 6.434109] hdc: 781422768 sectors (400088 MB) w/8192KiB Cache, CHS=48641/255/63 [ 6.434278] hdc: cache flushes supported [ 6.434278] hdc: hdc1 hdc2 hdc3 [ 6.438895] hdd: max request size: 512KiB [ 6.439892] hdd: Host Protected Area detected. [ 6.439893] current capacity is 312581808 sectors (160041 MB) [ 6.439895] native capacity is 177702923509424 sectors (90983896836 MB) [ 6.594316] hdd: task_no_data_intr: status=0x51 { DriveReady SeekComplete Error } [ 6.594316] hdd: task_no_data_intr: error=0x04 { DriveStatusError } [ 6.594316] ide: failed opcode was: 0x37 [ 6.602876] hdd: 312581808 sectors (160041 MB) w/8192KiB Cache, CHS=19457/255/63 [ 6.603223] hdd: cache flushes supported [ 6.603272] hdd: hdd1 hdd2 [ 6.794173] sym0: <895> rev 0x1 at pci 0000:01:06.0 irq 11 [ 6.796860] sym0: Tekram NVRAM, ID 7, Fast-40, LVD, parity checking [ 6.803223] sym0: SCSI BUS has been reset. [ 6.803226] scsi0 : sym-2.2.3 [ 9.829392] target0:0:0: FAST-40 WIDE SCSI 80.0 MB/s ST (25 ns, offset 31) [ 9.831365] scsi 0:0:0:0: Direct-Access IBM-ESXS ST336607LW FN B258 PQ: 0 ANSI: 3 [ 9.831663] target0:0:0: tagged command queuing enabled, command queue depth 16. [ 9.832218] target0:0:0: Beginning Domain Validation [ 9.833694] target0:0:0: asynchronous [ 9.837453] target0:0:0: wide asynchronous [ 9.840210] target0:0:0: FAST-40 WIDE SCSI 80.0 MB/s ST (25 ns, offset 31) [ 9.845005] target0:0:0: Domain Validation skipping write tests [ 9.845425] target0:0:0: Ending Domain Validation [ 9.849883] target0:0:1: FAST-40 WIDE SCSI 80.0 MB/s ST (25 ns, offset 31) [ 9.855281] scsi 0:0:1:0: Direct-Access IBM-ESXS ST318305LW !# B244 PQ: 0 ANSI: 3 [ 9.855967] target0:0:1: tagged command queuing enabled, command queue depth 16. [ 9.856639] target0:0:1: Beginning Domain Validation [ 9.862589] target0:0:1: asynchronous [ 9.878127] target0:0:1: wide asynchronous [ 9.888614] target0:0:1: FAST-40 WIDE SCSI 80.0 MB/s ST (25 ns, offset 31) [ 9.899612] target0:0:1: Domain Validation skipping write tests [ 9.900031] target0:0:1: Ending Domain Validation [ 13.785798] Driver 'sd' needs updating - please use bus_type methods [ 13.787288] sd 0:0:0:0: [sda] 71096640 512-byte hardware sectors (36401 MB) [ 13.788410] sd 0:0:0:0: [sda] Write Protect is off [ 13.788410] sd 0:0:0:0: [sda] Mode Sense: b3 00 10 08 [ 13.789803] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA [ 13.790361] sd 0:0:0:0: [sda] 71096640 512-byte hardware sectors (36401 MB) [ 13.791485] sd 0:0:0:0: [sda] Write Protect is off [ 13.791488] sd 0:0:0:0: [sda] Mode Sense: b3 00 10 08 [ 13.794291] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA [ 13.794989] sda: sda1 sda2 < sda5 sda6 sda7 > [ 13.837745] sd 0:0:0:0: [sda] Attached SCSI disk [ 13.839308] sd 0:0:1:0: [sdb] 35548320 512-byte hardware sectors (18201 MB) [ 13.840652] sd 0:0:1:0: [sdb] Write Protect is off [ 13.840652] sd 0:0:1:0: [sdb] Mode Sense: ab 00 10 08 [ 13.842601] sd 0:0:1:0: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA [ 13.843516] sd 0:0:1:0: [sdb] 35548320 512-byte hardware sectors (18201 MB) [ 13.846133] sd 0:0:1:0: [sdb] Write Protect is off [ 13.846309] sd 0:0:1:0: [sdb] Mode Sense: ab 00 10 08 [ 13.849803] sd 0:0:1:0: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA [ 13.850502] sdb: sdb1 sdb2 [ 13.864494] sd 0:0:1:0: [sdb] Attached SCSI disk [ 13.864494] PCI: Setting latency timer of device 0000:00:02.2 to 64 [ 13.864494] ehci_hcd 0000:00:02.2: EHCI Host Controller [ 13.864494] ehci_hcd 0000:00:02.2: new USB bus registered, assigned bus number 1 [ 13.864494] ehci_hcd 0000:00:02.2: debug port 1 [ 13.864494] PCI: cache line size of 64 is not supported by device 0000:00:02.2 [ 13.864494] ehci_hcd 0000:00:02.2: irq 5, io mem 0xe5000000 [ 13.881861] ehci_hcd 0000:00:02.2: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004 [ 13.882625] usb usb1: configuration #1 chosen from 1 choice [ 13.883167] hub 1-0:1.0: USB hub found [ 13.883571] hub 1-0:1.0: 6 ports detected [ 13.993034] ohci_hcd: 2006 August 04 USB 1.1 'Open' Host Controller (OHCI) Driver [ 13.993071] PCI: Setting latency timer of device 0000:00:02.0 to 64 [ 13.993075] ohci_hcd 0000:00:02.0: OHCI Host Controller [ 13.993556] ohci_hcd 0000:00:02.0: new USB bus registered, assigned bus number 2 [ 13.994231] ohci_hcd 0000:00:02.0: irq 10, io mem 0xe5004000 [ 14.054495] usb usb2: configuration #1 chosen from 1 choice [ 14.054495] hub 2-0:1.0: USB hub found [ 14.054495] hub 2-0:1.0: 3 ports detected [ 14.164227] PCI: Setting latency timer of device 0000:00:02.1 to 64 [ 14.164232] ohci_hcd 0000:00:02.1: OHCI Host Controller [ 14.164495] ohci_hcd 0000:00:02.1: new USB bus registered, assigned bus number 3 [ 14.164519] ohci_hcd 0000:00:02.1: irq 11, io mem 0xe5005000 [ 14.236779] usb usb3: configuration #1 chosen from 1 choice [ 14.237256] hub 3-0:1.0: USB hub found [ 14.237621] hub 3-0:1.0: 3 ports detected [ 14.274901] usb 1-2: new high speed USB device using ehci_hcd and address 2 [ 14.427762] usb 1-2: configuration #1 chosen from 1 choice [ 14.709067] usb 1-3: new high speed USB device using ehci_hcd and address 3 [ 14.887868] usb 1-3: configuration #1 chosen from 1 choice [ 14.888471] usbcore: registered new interface driver libusual [ 15.145638] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 15.146100] mice: PS/2 mouse device common for all mice [ 15.146403] md: raid0 personality registered for level 0 [ 15.146585] md: raid1 personality registered for level 1 [ 15.147039] device-mapper: ioctl: 4.13.0-ioctl (2007-10-18) initialised: dm-devel@redhat.com [ 15.147516] lguest: mapped switcher at ffc00000 [ 15.147868] usbcore: registered new interface driver usbkbd [ 15.147868] drivers/hid/usbhid/usbkbd.c: :USB HID Boot Protocol keyboard driver [ 15.147868] TCP cubic registered [ 15.147868] NET: Registered protocol family 1 [ 15.147868] NET: Registered protocol family 10 [ 15.147868] lo: Disabled Privacy Extensions [ 15.147868] ADDRCONF(NETDEV_UP): eth0: link is not ready [ 15.147868] IPv6 over IPv4 tunneling driver [ 15.148531] sit0: Disabled Privacy Extensions [ 15.149413] NET: Registered protocol family 17 [ 15.149842] Using IPI Shortcut mode [ 15.150508] BIOS EDD facility v0.16 2004-Jun-25, 6 devices found [ 15.500280] md: Autodetecting RAID arrays. [ 15.728178] md: Scanned 11 and added 11 devices. [ 15.728178] md: autorun ... [ 15.728178] md: considering sdb2 ... [ 15.728178] md: adding sdb2 ... [ 15.728178] md: adding hdd2 ... [ 15.728178] md: hdd1 has different UUID to sdb2 [ 15.728178] md: hdc3 has different UUID to sdb2 [ 15.728178] md: hdc2 has different UUID to sdb2 [ 15.728178] md: hdc1 has different UUID to sdb2 [ 15.728178] md: adding hdb2 ... [ 15.728178] md: hdb1 has different UUID to sdb2 [ 15.728178] md: hda3 has different UUID to sdb2 [ 15.728178] md: hda2 has different UUID to sdb2 [ 15.728178] md: hda1 has different UUID to sdb2 [ 15.728178] md: created md4 [ 15.728178] md: bind [ 15.728178] md: bind [ 15.728178] md: bind [ 15.728178] md: running: [ 15.728820] md4: setting max_sectors to 128, segment boundary to 32767 [ 15.729255] raid0: looking at sdb2 [ 15.729596] raid0: comparing sdb2(12891072) with sdb2(12891072) [ 15.730060] raid0: END [ 15.730375] raid0: ==> UNIQUE [ 15.730708] raid0: 1 zones [ 15.731028] raid0: looking at hdd2 [ 15.731369] raid0: comparing hdd2(12297664) with sdb2(12891072) [ 15.731833] raid0: NOT EQUAL [ 15.732164] raid0: comparing hdd2(12297664) with hdd2(12297664) [ 15.732627] raid0: END [ 15.732942] raid0: ==> UNIQUE [ 15.733275] raid0: 2 zones [ 15.733596] raid0: looking at hdb2 [ 15.733936] raid0: comparing hdb2(12297664) with sdb2(12891072) [ 15.734399] raid0: NOT EQUAL [ 15.734730] raid0: comparing hdb2(12297664) with hdd2(12297664) [ 15.735192] raid0: EQUAL [ 15.735512] raid0: FINAL 2 zones [ 15.735849] raid0: zone 1 [ 15.736167] raid0: checking sdb2 ... contained as device 0 [ 15.736622] (12891072) is smallest!. [ 15.736854] raid0: checking hdb2 ... nope. [ 15.737061] raid0: checking hdd2 ... nope. [ 15.737268] raid0: zone->nb_dev: 1, size: 593408 [ 15.737441] raid0: current zone offset: 12891072 [ 15.737614] raid0: done. [ 15.737763] raid0 : md_size is 37486400 blocks. [ 15.737935] raid0 : conf->hash_spacing is 36892992 blocks. [ 15.738181] raid0 : nb_zone is 2. [ 15.738338] raid0 : Allocating 8 bytes for hash. [ 15.738840] md: considering hdd1 ... [ 15.739192] md: adding hdd1 ... [ 15.739531] md: hdc3 has different UUID to hdd1 [ 15.739908] md: hdc2 has different UUID to hdd1 [ 15.740284] md: hdc1 has different UUID to hdd1 [ 15.740662] md: adding hdb1 ... [ 15.740999] md: hda3 has different UUID to hdd1 [ 15.741376] md: hda2 has different UUID to hdd1 [ 15.741752] md: hda1 has different UUID to hdd1 [ 15.742222] md: created md3 [ 15.742547] md: bind [ 15.742884] md: bind [ 15.743225] md: running: [ 15.743892] raid1: raid set md3 active with 2 out of 2 mirrors [ 15.744455] md: considering hdc3 ... [ 15.744806] md: adding hdc3 ... [ 15.745145] md: hdc2 has different UUID to hdc3 [ 15.745523] md: hdc1 has different UUID to hdc3 [ 15.745901] md: adding hda3 ... [ 15.746238] md: hda2 has different UUID to hdc3 [ 15.746625] md: hda1 has different UUID to hdc3 [ 15.747138] md: created md2 [ 15.747463] md: bind [ 15.747801] md: bind [ 15.748201] md: running: [ 15.748746] md2: setting max_sectors to 128, segment boundary to 32767 [ 15.749181] raid0: looking at hdc3 [ 15.749523] raid0: comparing hdc3(771008) with hdc3(771008) [ 15.749975] raid0: END [ 15.750291] raid0: ==> UNIQUE [ 15.750624] raid0: 1 zones [ 15.750944] raid0: looking at hda3 [ 15.751207] raid0: comparing hda3(771008) with hdc3(771008) [ 15.751435] raid0: EQUAL [ 15.751585] raid0: FINAL 1 zones [ 15.751742] raid0: done. [ 15.751891] raid0 : md_size is 1542016 blocks. [ 15.752062] raid0 : conf->hash_spacing is 1542016 blocks. [ 15.752243] raid0 : nb_zone is 1. [ 15.752400] raid0 : Allocating 4 bytes for hash. [ 15.752627] md: considering hdc2 ... [ 15.752909] md: adding hdc2 ... [ 15.753247] md: hdc1 has different UUID to hdc2 [ 15.753625] md: adding hda2 ... [ 15.753964] md: hda1 has different UUID to hdc2 [ 15.754439] md: created md1 [ 15.754763] md: bind [ 15.755101] md: bind [ 15.755437] md: running: [ 15.756050] raid1: raid set md1 active with 2 out of 2 mirrors [ 15.756550] md: considering hdc1 ... [ 15.756902] md: adding hdc1 ... [ 15.757241] md: adding hda1 ... [ 15.757579] md: created md0 [ 15.757903] md: bind [ 15.758199] md: bind [ 15.758535] md: running: [ 15.759155] raid1: raid set md0 active with 2 out of 2 mirrors [ 15.759645] md: ... autorun DONE. [ 15.795275] kjournald starting. Commit interval 5 seconds [ 15.795275] EXT3-fs: mounted filesystem with ordered data mode. [ 15.795275] VFS: Mounted root (ext3 filesystem) readonly. [ 15.795275] Freeing unused kernel memory: 192k freed [ 17.708797] hda: UDMA/100 mode selected [ 17.710629] hdb: UDMA/100 mode selected [ 17.711559] hda: UDMA/100 mode selected [ 17.712991] hdb: UDMA/100 mode selected [ 17.713713] hda: UDMA/100 mode selected [ 17.715632] hdb: UDMA/100 mode selected [ 17.716552] hdc: UDMA/100 mode selected [ 17.719420] hdd: UDMA/100 mode selected [ 17.721667] hda: UDMA/100 mode selected [ 17.723082] hdb: UDMA/100 mode selected [ 17.723631] hda: UDMA/100 mode selected [ 17.725211] hdb: UDMA/100 mode selected [ 17.726029] hdc: UDMA/100 mode selected [ 17.728068] hdd: UDMA/100 mode selected [ 17.729420] hdc: UDMA/100 mode selected [ 17.730557] hdc: UDMA/100 mode selected [ 17.732694] hdd: UDMA/100 mode selected [ 17.734660] hdc: UDMA/100 mode selected [ 17.736602] hdd: UDMA/100 mode selected [ 17.737460] hdd: UDMA/100 mode selected [ 17.845974] hda: UDMA/100 mode selected [ 17.847825] hdb: UDMA/100 mode selected [ 17.854952] hdc: UDMA/100 mode selected [ 17.857146] hdd: UDMA/100 mode selected [ 17.869816] i2c-adapter i2c-0: nForce2 SMBus adapter at 0x5000 [ 17.870284] i2c-adapter i2c-1: nForce2 SMBus adapter at 0x5500 [ 18.464986] PCI: Setting latency timer of device 0000:00:06.0 to 64 [ 18.630516] usb 1-2: reset high speed USB device using ehci_hcd and address 2 [ 18.783441] phy0: Selected rate control algorithm 'pid' [ 18.787214] zd1211rw 1-2:1.0: phy0 [ 18.787214] usbcore: registered new interface driver zd1211rw [ 18.807258] intel8x0_measure_ac97_clock: measured 58422 usecs [ 18.807675] intel8x0: clocking to 48000 [ 19.296774] hda: UDMA/100 mode selected [ 19.298026] hdb: UDMA/100 mode selected [ 19.299479] hdc: UDMA/100 mode selected [ 19.301113] hdd: UDMA/100 mode selected [ 20.277428] EXT3 FS on sda5, internal journal [ 21.035724] Initializing USB Mass Storage driver... [ 21.037461] scsi1 : SCSI emulation for USB Mass Storage devices [ 21.039000] usbcore: registered new interface driver usb-storage [ 21.039426] USB Mass Storage support registered. [ 21.041617] usb-storage: device found at 3 [ 21.041621] usb-storage: waiting for device to settle before scanning [ 26.822191] scsi 1:0:0:0: CD-ROM HL-DT-ST DVD-RAM GSA-E60L 1.00 PQ: 0 ANSI: 0 [ 26.822191] usb-storage: device scan complete [ 26.848804] Driver 'sr' needs updating - please use bus_type methods [ 26.859979] sr0: scsi3-mmc drive: 48x/48x writer dvd-ram cd/rw xa/form2 cdda tray [ 26.859979] Uniform CD-ROM driver Revision: 3.20 [ 26.859979] sr 1:0:0:0: Attached scsi CD-ROM sr0 [ 26.882752] sd 0:0:0:0: Attached scsi generic sg0 type 0 [ 26.883189] sd 0:0:1:0: Attached scsi generic sg1 type 0 [ 26.883625] sr 1:0:0:0: Attached scsi generic sg2 type 5 [ 30.049498] fuse init (API version 7.9) [ 30.126097] ReiserFS: sdb1: found reiserfs format "3.6" with standard journal [ 30.126181] ReiserFS: sdb1: using ordered data mode [ 30.134649] 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 [ 30.134952] ReiserFS: sdb1: checking transaction log (sdb1) [ 30.183795] ReiserFS: sdb1: Using r5 hash to sort names [ 30.349355] kjournald2 starting. Commit interval 5 seconds [ 30.353261] EXT4 FS on md4, internal journal [ 30.353791] EXT4-fs: mounted filesystem with ordered data mode. [ 30.353791] EXT4-fs: file extents enabled [ 30.353791] EXT4-fs: mballoc enabled [ 30.404880] Adding 1542008k swap on /dev/mapper/md2. Priority:1 extents:1 across:1542008k [ 39.515418] zd1211rw 1-2:1.0: firmware version 4605 [ 39.577760] zd1211rw 1-2:1.0: zd1211 chip 0ace:1211 v4330 high 00-30-95 RF2959_RF pa0 ----- [ 39.673565] ADDRCONF(NETDEV_UP): wlan0: link is not ready [ 42.720008] wlan0: Initial auth_alg=0 [ 42.720008] wlan0: authenticate with AP 00:11:09:5a:dc:f3 [ 42.722572] wlan0: RX authentication from 00:11:09:5a:dc:f3 (alg=0 transaction=2 status=0) [ 42.722572] wlan0: authenticated [ 42.722572] wlan0: associate with AP 00:11:09:5a:dc:f3 [ 42.725458] wlan0: RX AssocResp from 00:11:09:5a:dc:f3 (capab=0x431 status=0 aid=3) [ 42.725461] wlan0: associated [ 42.725588] wlan0: CTS protection enabled (BSSID=00:11:09:5a:dc:f3) [ 42.726292] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [ 44.794894] RPC: Registered udp transport module. [ 44.794899] RPC: Registered tcp transport module. [ 47.575976] tun: Universal TUN/TAP device driver, 1.6 [ 47.575981] tun: (C) 1999-2004 Max Krasnyansky [ 48.056440] warning: `named' uses 32-bit capabilities (legacy support in use) [ 60.729592] Installing knfsd (copyright (C) 1996 okir@monad.swb.de). [ 60.948992] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory [ 60.950073] NFSD: starting 90-second grace period [ 71.075035] device wlan0 entered promiscuous mode [ 71.076440] device wlan0 left promiscuous mode [ 71.078250] device wlan0 entered promiscuous mode [ 82.496832] ip_tables: (C) 2000-2006 Netfilter Core Team [ 82.596532] nf_conntrack version 0.5.0 (16384 buckets, 65536 max) [ 92.084031] kjournald starting. Commit interval 5 seconds [ 92.084031] EXT3-fs: mounted filesystem with ordered data mode. [ 97.066806] XFS mounting filesystem dm-2 [ 97.389348] Ending clean XFS mount for filesystem: dm-2 [ 102.845339] XFS mounting filesystem dm-3 [ 103.293305] Ending clean XFS mount for filesystem: dm-3 [ 109.952781] kjournald starting. Commit interval 5 seconds [ 109.970178] EXT3 FS on dm-4, internal journal [ 109.970178] EXT3-fs: mounted filesystem with ordered data mode. [108910.975619] [108910.975624] ======================================================= [108910.976262] [ INFO: possible circular locking dependency detected ] [108910.976648] 2.6.25-rc7 #1 [108910.976925] ------------------------------------------------------- [108910.977310] rsync/21415 is trying to acquire lock: [108910.977652] (iprune_mutex){--..}, at: [] shrink_icache_memory+0x72/0x220 [108910.978281] [108910.978282] but task is already holding lock: [108910.978856] (&(&ip->i_iolock)->mr_lock){----}, at: [] xfs_ilock+0x96/0xb0 [108910.979487] [108910.979488] which lock already depends on the new lock. [108910.979489] [108910.980333] [108910.980333] the existing dependency chain (in reverse order) is: [108910.980955] [108910.980956] -> #1 (&(&ip->i_iolock)->mr_lock){----}: [108910.981548] [] add_lock_to_list+0x44/0xc0 [108910.981911] [] __lock_acquire+0xc26/0x10b0 [108910.982277] [] xfs_ilock+0x96/0xb0 [108910.982623] [] mark_held_locks+0x3d/0x70 [108910.982984] [] lock_acquire+0x5e/0x80 [108910.983337] [] xfs_ilock+0x96/0xb0 [108910.983684] [] down_write_nested+0x41/0x60 [108910.984050] [] xfs_ilock+0x96/0xb0 [108910.984396] [] xfs_ilock+0x96/0xb0 [108910.984741] [] xfs_ireclaim+0x1a/0x60 [108910.985095] [] xfs_finish_reclaim+0x53/0x1a0 [108910.985358] [] xfs_fs_clear_inode+0x5e/0x90 [108910.985358] [] clear_inode+0xa9/0x130 [108910.985358] [] dispose_list+0x1a/0xc0 [108910.985358] [] shrink_icache_memory+0x1e2/0x220 [108910.985358] [] shrink_slab+0x101/0x160 [108910.985358] [] kswapd+0x293/0x3e0 [108910.985358] [] isolate_pages_global+0x0/0x60 [108910.985358] [] autoremove_wake_function+0x0/0x40 [108910.985358] [] trace_hardirqs_on+0x9c/0x110 [108910.985358] [] kswapd+0x0/0x3e0 [108910.985358] [] kthread+0x42/0x70 [108910.985358] [] kthread+0x0/0x70 [108910.985358] [] kernel_thread_helper+0x7/0x18 [108910.985358] [] 0xffffffff [108910.985358] [108910.985358] -> #0 (iprune_mutex){--..}: [108910.985358] [] print_circular_bug_entry+0x40/0x50 [108910.985358] [] __lock_acquire+0xa27/0x10b0 [108910.985358] [] __lock_acquire+0x18f/0x10b0 [108910.985358] [] lock_acquire+0x5e/0x80 [108910.985358] [] shrink_icache_memory+0x72/0x220 [108910.985358] [] mutex_lock_nested+0x89/0x240 [108910.985358] [] shrink_icache_memory+0x72/0x220 [108910.985358] [] shrink_icache_memory+0x72/0x220 [108910.985358] [] shrink_icache_memory+0x72/0x220 [108910.985358] [] shrink_slab+0x21/0x160 [108910.985358] [] shrink_slab+0x101/0x160 [108910.985358] [] try_to_free_pages+0x143/0x220 [108910.985358] [] isolate_pages_global+0x0/0x60 [108910.985358] [] __alloc_pages+0x14b/0x370 [108910.985358] [] _read_unlock_irq+0x20/0x30 [108910.985358] [] __grab_cache_page+0x81/0xc0 [108910.985358] [] block_write_begin+0x76/0xe0 [108910.985358] [] xfs_vm_write_begin+0x46/0x50 [108910.985358] [] xfs_get_blocks+0x0/0x30 [108910.985358] [] generic_file_buffered_write+0x117/0x650 [108910.985358] [] xfs_ilock+0x6d/0xb0 [108910.985358] [] xfs_write+0x7ac/0x8a0 [108910.985358] [] core_sys_select+0x21/0x350 [108910.985358] [] xfs_file_aio_write+0x5c/0x70 [108910.985358] [] do_sync_write+0xd5/0x120 [108910.985358] [] __lock_acquire+0x537/0x10b0 [108910.985358] [] autoremove_wake_function+0x0/0x40 [108910.985358] [] dnotify_parent+0x35/0x90 [108910.985358] [] do_sync_write+0x0/0x120 [108910.985358] [] vfs_write+0x9f/0x140 [108910.985358] [] sys_write+0x41/0x70 [108910.985358] [] sysenter_past_esp+0x5f/0xa5 [108910.985358] [] 0xffffffff [108910.985358] [108910.985358] other info that might help us debug this: [108910.985358] [108910.985358] 3 locks held by rsync/21415: [108910.985358] #0: (&sb->s_type->i_mutex_key#12){--..}, at: [] xfs_write+0x3f9/0x8a0 [108910.985358] #1: (&(&ip->i_iolock)->mr_lock){----}, at: [] xfs_ilock+0x96/0xb0 [108910.985358] #2: (shrinker_rwsem){----}, at: [] shrink_slab+0x21/0x160 [108910.985358] [108910.985358] stack backtrace: [108910.985358] Pid: 21415, comm: rsync Not tainted 2.6.25-rc7 #1 [108910.985358] [] print_circular_bug_tail+0x72/0x80 [108910.985358] [] __lock_acquire+0xa27/0x10b0 [108910.985358] [] __lock_acquire+0x18f/0x10b0 [108910.985358] [] lock_acquire+0x5e/0x80 [108910.985358] [] shrink_icache_memory+0x72/0x220 [108910.985358] [] mutex_lock_nested+0x89/0x240 [108910.985358] [] shrink_icache_memory+0x72/0x220 [108910.985358] [] shrink_icache_memory+0x72/0x220 [108910.985358] [] shrink_icache_memory+0x72/0x220 [108910.985358] [] shrink_slab+0x21/0x160 [108910.985358] [] shrink_slab+0x101/0x160 [108910.985358] [] try_to_free_pages+0x143/0x220 [108910.985358] [] isolate_pages_global+0x0/0x60 [108910.985358] [] __alloc_pages+0x14b/0x370 [108910.985358] [] _read_unlock_irq+0x20/0x30 [108910.985358] [] __grab_cache_page+0x81/0xc0 [108910.985358] [] block_write_begin+0x76/0xe0 [108910.985358] [] xfs_vm_write_begin+0x46/0x50 [108910.985358] [] xfs_get_blocks+0x0/0x30 [108910.985358] [] generic_file_buffered_write+0x117/0x650 [108910.985358] [] xfs_ilock+0x6d/0xb0 [108910.985358] [] xfs_write+0x7ac/0x8a0 [108910.985358] [] core_sys_select+0x21/0x350 [108910.985358] [] xfs_file_aio_write+0x5c/0x70 [108910.985358] [] do_sync_write+0xd5/0x120 [108910.985358] [] __lock_acquire+0x537/0x10b0 [108910.985358] [] autoremove_wake_function+0x0/0x40 [108910.985358] [] dnotify_parent+0x35/0x90 [108910.985358] [] do_sync_write+0x0/0x120 [108910.985358] [] vfs_write+0x9f/0x140 [108910.985358] [] sys_write+0x41/0x70 [108910.985358] [] sysenter_past_esp+0x5f/0xa5 [108910.985358] =======================