[ 0.000000] Linux version 2.6.25-rc6 (evil@sheep) (gcc version 4.2.3 (Debian 4.2.3-2)) #5 Sat Mar 22 20:13:34 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.165 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 (3330k kernel code, 24328k reserved, 1146k 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 : 0xc044094e - 0xc055f20c (1146 kB) [ 0.010000] .text : 0xc0100000 - 0xc044094e (3330 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.160002] Calibrating delay using timer specific routine.. 3829.31 BogoMIPS (lpj=19146584) [ 0.160778] Mount-cache hash table entries: 512 [ 0.161812] CPU: L1 I Cache: 64K (64 bytes/line), D cache 64K (64 bytes/line) [ 0.162264] CPU: L2 Cache: 512K (64 bytes/line) [ 0.162640] 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.200507] Freeing SMP alternatives: 0k freed [ 0.320534] net_namespace: 600 bytes [ 0.321212] NET: Registered protocol family 16 [ 0.353907] PCI: PCI BIOS revision 2.10 entry at 0xfb4a0, last bus=2 [ 0.354337] PCI: Using configuration type 1 [ 0.354702] Setting up standard PCI resources [ 0.359677] SCSI subsystem initialized [ 0.360193] usbcore: registered new interface driver usbfs [ 0.360661] usbcore: registered new interface driver hub [ 0.361152] usbcore: registered new device driver usb [ 0.361847] PCI: Probing PCI hardware [ 0.362030] PCI: Probing PCI hardware (bus 00) [ 0.362184] pci 0000:00:00.0: nForce2 C1 Halt Disconnect fixup [ 0.365161] PCI: Discovered primary peer bus ff [IRQ] [ 0.370062] PCI: Using IRQ router default [10de/01e0] at 0000:00:00.0 [ 0.413752] PCI: Bridge: 0000:00:08.0 [ 0.414106] IO window: c000-cfff [ 0.414451] MEM window: 0xe2000000-0xe3ffffff [ 0.414828] PREFETCH window: 0x00000000e4000000-0x00000000e4ffffff [ 0.415261] PCI: Bridge: 0000:00:1e.0 [ 0.415610] IO window: disabled. [ 0.415953] MEM window: disabled. [ 0.416299] PREFETCH window: disabled. [ 0.416669] PCI: Setting latency timer of device 0000:00:08.0 to 64 [ 0.416688] NET: Registered protocol family 2 [ 0.417249] IP route cache hash table entries: 32768 (order: 5, 131072 bytes) [ 0.418271] TCP established hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.419305] Time: tsc clocksource has been installed. [ 0.420671] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes) [ 0.426312] TCP: Hash tables configured (established 131072 bind 65536) [ 0.426789] TCP reno registered [ 0.427784] Machine check exception polling timer started. [ 0.429214] Initializing RT-Tester: OK [ 0.430087] highmem bounce pool size: 64 pages [ 0.434593] VFS: Disk quotas dquot_6.5.1 [ 0.435089] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 0.436759] JFS: nTxBlock = 8192, nTxLock = 65536 [ 0.443790] SGI XFS with ACLs, no debug enabled [ 0.445646] io scheduler noop registered [ 0.446005] io scheduler anticipatory registered [ 0.446382] io scheduler deadline registered (default) [ 0.446883] io scheduler cfq registered [ 0.510001] pci 0000:01:0a.0: Boot video device [ 0.510002] 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.040013] forcedeth 0000:00:04.0: ifname eth0, PHY OUI 0x20 @ 1, addr 00:11:2f:23:1e:7f [ 1.040013] forcedeth 0000:00:04.0: timirq lnktim desc-v1 [ 1.040013] netconsole: local port 6665 [ 1.040013] netconsole: local IP 0.0.0.0 [ 1.040013] netconsole: interface eth0 [ 1.040013] netconsole: remote port 514 [ 1.040014] netconsole: remote IP 192.168.10.13 [ 1.040014] netconsole: remote ethernet address 00:14:51:02:05:86 [ 1.040014] netconsole: device eth0 not up yet, forcing it [ 1.040014] eth0: no link during initialization. [ 5.040015] netconsole: timeout waiting for carrier [ 5.040400] netconsole: no IP address for eth0, aborting [ 5.040798] netconsole: cleaning up [ 5.041142] Uniform Multi-Platform E-IDE driver [ 5.041526] ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx [ 5.042320] NFORCE2: 0000:00:09.0 (rev a2) UDMA133 controller [ 5.042732] NFORCE2: IDE controller (0x10de:0x0065 rev 0xa2) at PCI slot 0000:00:09.0 [ 5.043183] NFORCE2: not 100% native mode: will probe irqs later [ 5.043376] NFORCE2: BIOS didn't set cable bits correctly. Enabling workaround. [ 5.043660] NFORCE2: BIOS didn't set cable bits correctly. Enabling workaround. [ 5.043956] ide0: BM-DMA at 0xf000-0xf007, BIOS settings: hda:DMA, hdb:DMA [ 5.044286] ide1: BM-DMA at 0xf008-0xf00f, BIOS settings: hdc:DMA, hdd:DMA [ 5.044699] Probing IDE interface ide0... [ 5.361191] hdb: SAMSUNG SP1604N, ATA DISK drive [ 5.724058] hda: SAMSUNG HD400LD, ATA DISK drive [ 5.724493] hda: host max PIO5 wanted PIO255(auto-tune) selected PIO4 [ 5.724584] hda: UDMA/100 mode selected [ 5.725030] hdb: host max PIO5 wanted PIO255(auto-tune) selected PIO4 [ 5.725086] hdb: UDMA/100 mode selected [ 5.725501] Probing IDE interface ide1... [ 5.915453] hdd: probing with STATUS(0x51) instead of ALTSTATUS(0x7f) [ 6.036527] hdd: ST3160023A, ATA DISK drive [ 6.278321] hdc: probing with STATUS(0x51) instead of ALTSTATUS(0x7f) [ 6.399395] hdc: SAMSUNG HD400LD, ATA DISK drive [ 6.399833] hdc: host max PIO5 wanted PIO255(auto-tune) selected PIO4 [ 6.399856] hdc: UDMA/100 mode selected [ 6.399856] hdd: host max PIO5 wanted PIO255(auto-tune) selected PIO4 [ 6.399856] hdd: UDMA/100 mode selected [ 6.399856] ide0 at 0x1f0-0x1f7,0x3f6 on irq 14 [ 6.399856] ide1 at 0x170-0x177,0x376 on irq 15 [ 6.399856] hda: max request size: 512KiB [ 6.400608] hda: 781422768 sectors (400088 MB) w/8192KiB Cache, CHS=48641/255/63 [ 6.400763] hda: cache flushes supported [ 6.400763] hda: hda1 hda2 hda3 [ 6.406058] hdb: max request size: 512KiB [ 6.406959] hdb: 312581808 sectors (160041 MB) w/2048KiB Cache, CHS=19457/255/63 [ 6.408071] hdb: cache flushes supported [ 6.408462] hdb: hdb1 hdb2 [ 6.419427] hdc: max request size: 512KiB [ 6.422667] hdc: Host Protected Area detected. [ 6.422669] current capacity is 781422768 sectors (400088 MB) [ 6.422670] native capacity is 162249630257328 sectors (83071810691 MB) [ 6.424183] hdc: task_no_data_intr: status=0x51 { DriveReady SeekComplete Error } [ 6.425015] hdc: task_no_data_intr: error=0x10 { SectorIdNotFound }, LBAsect=162249630257327, sector=0 [ 6.425991] ide: failed opcode was: 0x37 [ 6.428893] hdc: 781422768 sectors (400088 MB) w/8192KiB Cache, CHS=48641/255/63 [ 6.429650] hdc: cache flushes supported [ 6.429650] hdc: hdc1 hdc2 hdc3 [ 6.431548] hdd: max request size: 512KiB [ 6.432457] hdd: Host Protected Area detected. [ 6.432459] current capacity is 312581808 sectors (160041 MB) [ 6.432460] native capacity is 177702923509424 sectors (90983896836 MB) [ 6.596983] hdd: task_no_data_intr: status=0x51 { DriveReady SeekComplete Error } [ 6.597816] hdd: task_no_data_intr: error=0x04 { DriveStatusError } [ 6.598408] ide: failed opcode was: 0x37 [ 6.605489] hdd: 312581808 sectors (160041 MB) w/8192KiB Cache, CHS=19457/255/63 [ 6.605805] hdd: cache flushes supported [ 6.605805] hdd: hdd1 hdd2 [ 6.814994] sym0: <895> rev 0x1 at pci 0000:01:06.0 irq 11 [ 6.817685] sym0: Tekram NVRAM, ID 7, Fast-40, LVD, parity checking [ 6.824701] sym0: SCSI BUS has been reset. [ 6.825092] scsi0 : sym-2.2.3 [ 9.844953] target0:0:0: FAST-40 WIDE SCSI 80.0 MB/s ST (25 ns, offset 31) [ 9.847176] scsi 0:0:0:0: Direct-Access IBM-ESXS ST336607LW FN B258 PQ: 0 ANSI: 3 [ 9.847241] target0:0:0: tagged command queuing enabled, command queue depth 16. [ 9.847241] target0:0:0: Beginning Domain Validation [ 9.848181] target0:0:0: asynchronous [ 9.851944] target0:0:0: wide asynchronous [ 9.854655] target0:0:0: FAST-40 WIDE SCSI 80.0 MB/s ST (25 ns, offset 31) [ 9.858823] target0:0:0: Domain Validation skipping write tests [ 9.859242] target0:0:0: Ending Domain Validation [ 9.863684] target0:0:1: FAST-40 WIDE SCSI 80.0 MB/s ST (25 ns, offset 31) [ 9.869031] scsi 0:0:1:0: Direct-Access IBM-ESXS ST318305LW !# B244 PQ: 0 ANSI: 3 [ 9.869716] target0:0:1: tagged command queuing enabled, command queue depth 16. [ 9.870397] target0:0:1: Beginning Domain Validation [ 9.876789] target0:0:1: asynchronous [ 9.892024] target0:0:1: wide asynchronous [ 9.903271] target0:0:1: FAST-40 WIDE SCSI 80.0 MB/s ST (25 ns, offset 31) [ 9.914824] target0:0:1: Domain Validation skipping write tests [ 9.915243] target0:0:1: Ending Domain Validation [ 13.826223] Driver 'sd' needs updating - please use bus_type methods [ 13.827075] sd 0:0:0:0: [sda] 71096640 512-byte hardware sectors (36401 MB) [ 13.829780] sd 0:0:0:0: [sda] Write Protect is off [ 13.830173] sd 0:0:0:0: [sda] Mode Sense: b3 00 10 08 [ 13.832978] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA [ 13.834961] sd 0:0:0:0: [sda] 71096640 512-byte hardware sectors (36401 MB) [ 13.837660] sd 0:0:0:0: [sda] Write Protect is off [ 13.837660] sd 0:0:0:0: [sda] Mode Sense: b3 00 10 08 [ 13.839053] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA [ 13.839053] sda: sda1 sda2 < sda5 sda6 sda7 > [ 13.888650] sd 0:0:0:0: [sda] Attached SCSI disk [ 13.890064] sd 0:0:1:0: [sdb] 35548320 512-byte hardware sectors (18201 MB) [ 13.891419] sd 0:0:1:0: [sdb] Write Protect is off [ 13.891419] sd 0:0:1:0: [sdb] Mode Sense: ab 00 10 08 [ 13.893387] sd 0:0:1:0: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA [ 13.894335] sd 0:0:1:0: [sdb] 35548320 512-byte hardware sectors (18201 MB) [ 13.896996] sd 0:0:1:0: [sdb] Write Protect is off [ 13.897388] sd 0:0:1:0: [sdb] Mode Sense: ab 00 10 08 [ 13.901319] sd 0:0:1:0: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA [ 13.902018] sdb: sdb1 sdb2 [ 13.909699] sd 0:0:1:0: [sdb] Attached SCSI disk [ 13.910229] PCI: Setting latency timer of device 0000:00:02.2 to 64 [ 13.910234] ehci_hcd 0000:00:02.2: EHCI Host Controller [ 13.911267] ehci_hcd 0000:00:02.2: new USB bus registered, assigned bus number 1 [ 13.911982] ehci_hcd 0000:00:02.2: debug port 1 [ 13.912361] PCI: cache line size of 64 is not supported by device 0000:00:02.2 [ 13.912373] ehci_hcd 0000:00:02.2: irq 5, io mem 0xe5000000 [ 13.938158] ehci_hcd 0000:00:02.2: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004 [ 13.939313] usb usb1: configuration #1 chosen from 1 choice [ 13.939478] hub 1-0:1.0: USB hub found [ 13.939478] hub 1-0:1.0: 6 ports detected [ 14.049330] ohci_hcd: 2006 August 04 USB 1.1 'Open' Host Controller (OHCI) Driver [ 14.049368] PCI: Setting latency timer of device 0000:00:02.0 to 64 [ 14.049372] ohci_hcd 0000:00:02.0: OHCI Host Controller [ 14.049478] ohci_hcd 0000:00:02.0: new USB bus registered, assigned bus number 2 [ 14.049478] ohci_hcd 0000:00:02.0: irq 10, io mem 0xe5004000 [ 14.111658] usb usb2: configuration #1 chosen from 1 choice [ 14.112134] hub 2-0:1.0: USB hub found [ 14.112499] hub 2-0:1.0: 3 ports detected [ 14.220445] PCI: Setting latency timer of device 0000:00:02.1 to 64 [ 14.220450] ohci_hcd 0000:00:02.1: OHCI Host Controller [ 14.229320] spurious 8259A interrupt: IRQ7. [ 14.229400] ohci_hcd 0000:00:02.1: new USB bus registered, assigned bus number 3 [ 14.230075] ohci_hcd 0000:00:02.1: irq 11, io mem 0xe5005000 [ 14.293073] usb usb3: configuration #1 chosen from 1 choice [ 14.293550] hub 3-0:1.0: USB hub found [ 14.293914] hub 3-0:1.0: 3 ports detected [ 14.331200] usb 1-2: new high speed USB device using ehci_hcd and address 2 [ 14.483943] usb 1-2: configuration #1 chosen from 1 choice [ 14.485158] usbcore: registered new interface driver libusual [ 14.735561] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 14.736164] mice: PS/2 mouse device common for all mice [ 14.736680] md: raid0 personality registered for level 0 [ 14.737078] md: raid1 personality registered for level 1 [ 14.737763] device-mapper: ioctl: 4.13.0-ioctl (2007-10-18) initialised: dm-devel@redhat.com [ 14.738635] lguest: mapped switcher at ffc00000 [ 14.739139] usbcore: registered new interface driver usbkbd [ 14.739547] drivers/hid/usbhid/usbkbd.c: :USB HID Boot Protocol keyboard driver [ 14.740059] TCP cubic registered [ 14.740059] NET: Registered protocol family 1 [ 14.740059] NET: Registered protocol family 10 [ 14.740059] lo: Disabled Privacy Extensions [ 14.740459] ADDRCONF(NETDEV_UP): eth0: link is not ready [ 14.740965] IPv6 over IPv4 tunneling driver [ 14.742012] sit0: Disabled Privacy Extensions [ 14.742902] NET: Registered protocol family 17 [ 14.743205] Using IPI Shortcut mode [ 14.743672] BIOS EDD facility v0.16 2004-Jun-25, 6 devices found [ 14.762973] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 [ 14.859454] md: Autodetecting RAID arrays. [ 15.057256] md: Scanned 11 and added 11 devices. [ 15.057256] md: autorun ... [ 15.057256] md: considering sdb2 ... [ 15.057256] md: adding sdb2 ... [ 15.057256] md: adding hdd2 ... [ 15.057256] md: hdd1 has different UUID to sdb2 [ 15.057256] md: hdc3 has different UUID to sdb2 [ 15.057256] md: hdc2 has different UUID to sdb2 [ 15.057256] md: hdc1 has different UUID to sdb2 [ 15.057256] md: adding hdb2 ... [ 15.057261] md: hdb1 has different UUID to sdb2 [ 15.057637] md: hda3 has different UUID to sdb2 [ 15.058013] md: hda2 has different UUID to sdb2 [ 15.058389] md: hda1 has different UUID to sdb2 [ 15.058898] md: created md4 [ 15.059223] md: bind [ 15.059663] md: bind [ 15.060001] md: bind [ 15.060336] md: running: [ 15.061113] md4: setting max_sectors to 128, segment boundary to 32767 [ 15.061549] raid0: looking at sdb2 [ 15.061890] raid0: comparing sdb2(12891072) with sdb2(12891072) [ 15.062353] raid0: END [ 15.062669] raid0: ==> UNIQUE [ 15.063002] raid0: 1 zones [ 15.063322] raid0: looking at hdd2 [ 15.063663] raid0: comparing hdd2(12297664) with sdb2(12891072) [ 15.064125] raid0: NOT EQUAL [ 15.064456] raid0: comparing hdd2(12297664) with hdd2(12297664) [ 15.064918] raid0: END [ 15.065233] raid0: ==> UNIQUE [ 15.065530] raid0: 2 zones [ 15.065679] raid0: looking at hdb2 [ 15.065839] raid0: comparing hdb2(12297664) with sdb2(12891072) [ 15.066069] raid0: NOT EQUAL [ 15.066224] raid0: comparing hdb2(12297664) with hdd2(12297664) [ 15.066454] raid0: EQUAL [ 15.066604] raid0: FINAL 2 zones [ 15.066763] raid0: zone 1 [ 15.066914] raid0: checking sdb2 ... contained as device 0 [ 15.067259] (12891072) is smallest!. [ 15.067610] raid0: checking hdb2 ... nope. [ 15.068013] raid0: checking hdd2 ... nope. [ 15.068416] raid0: zone->nb_dev: 1, size: 593408 [ 15.068792] raid0: current zone offset: 12891072 [ 15.069168] raid0: done. [ 15.069483] raid0 : md_size is 37486400 blocks. [ 15.069868] raid0 : conf->hash_spacing is 36892992 blocks. [ 15.070270] raid0 : nb_zone is 2. [ 15.070608] raid0 : Allocating 8 bytes for hash. [ 15.071125] md: considering hdd1 ... [ 15.071479] md: adding hdd1 ... [ 15.071819] md: hdc3 has different UUID to hdd1 [ 15.072196] md: hdc2 has different UUID to hdd1 [ 15.072573] md: hdc1 has different UUID to hdd1 [ 15.072952] md: adding hdb1 ... [ 15.073290] md: hda3 has different UUID to hdd1 [ 15.073666] md: hda2 has different UUID to hdd1 [ 15.074043] md: hda1 has different UUID to hdd1 [ 15.074519] md: created md3 [ 15.074844] md: bind [ 15.075182] md: bind [ 15.075518] md: running: [ 15.076190] raid1: raid set md3 active with 2 out of 2 mirrors [ 15.076755] md: considering hdc3 ... [ 15.077264] md: adding hdc3 ... [ 15.077603] md: hdc2 has different UUID to hdc3 [ 15.077980] md: hdc1 has different UUID to hdc3 [ 15.078358] md: adding hda3 ... [ 15.078697] md: hda2 has different UUID to hdc3 [ 15.079073] md: hda1 has different UUID to hdc3 [ 15.079584] md: created md2 [ 15.079920] md: bind [ 15.080093] md: bind [ 15.080258] md: running: [ 15.080615] md2: setting max_sectors to 128, segment boundary to 32767 [ 15.080812] raid0: looking at hdc3 [ 15.080970] raid0: comparing hdc3(771008) with hdc3(771008) [ 15.081197] raid0: END [ 15.081345] raid0: ==> UNIQUE [ 15.081527] raid0: 1 zones [ 15.081848] raid0: looking at hda3 [ 15.082188] raid0: comparing hda3(771008) with hdc3(771008) [ 15.082640] raid0: EQUAL [ 15.082961] raid0: FINAL 1 zones [ 15.083297] raid0: done. [ 15.083611] raid0 : md_size is 1542016 blocks. [ 15.083983] raid0 : conf->hash_spacing is 1542016 blocks. [ 15.084381] raid0 : nb_zone is 1. [ 15.084720] raid0 : Allocating 4 bytes for hash. [ 15.085150] md: considering hdc2 ... [ 15.085502] md: adding hdc2 ... [ 15.085841] md: hdc1 has different UUID to hdc2 [ 15.086220] md: adding hda2 ... [ 15.086559] md: hda1 has different UUID to hdc2 [ 15.087050] md: created md1 [ 15.087267] md: bind [ 15.087604] md: bind [ 15.087940] md: running: [ 15.088549] raid1: raid set md1 active with 2 out of 2 mirrors [ 15.089036] md: considering hdc1 ... [ 15.089399] md: adding hdc1 ... [ 15.089740] md: adding hda1 ... [ 15.090078] md: created md0 [ 15.090401] md: bind [ 15.090739] md: bind [ 15.091079] md: running: [ 15.091701] raid1: raid set md0 active with 2 out of 2 mirrors [ 15.092190] md: ... autorun DONE. [ 15.125040] EXT3-fs: INFO: recovery required on readonly filesystem. [ 15.125040] EXT3-fs: write access will be enabled during recovery. [ 15.377852] kjournald starting. Commit interval 5 seconds [ 15.377852] EXT3-fs: recovery complete. [ 15.386986] EXT3-fs: mounted filesystem with ordered data mode. [ 15.387475] VFS: Mounted root (ext3 filesystem) readonly. [ 15.387852] Freeing unused kernel memory: 192k freed [ 17.096856] i2c-adapter i2c-0: nForce2 SMBus adapter at 0x5000 [ 17.097504] i2c-adapter i2c-1: nForce2 SMBus adapter at 0x5500 [ 17.399108] hda: UDMA/100 mode selected [ 17.400085] hda: UDMA/100 mode selected [ 17.401356] hda: UDMA/100 mode selected [ 17.402827] hdb: UDMA/100 mode selected [ 17.402827] hda: UDMA/100 mode selected [ 17.403816] hdb: UDMA/100 mode selected [ 17.404406] hdb: UDMA/100 mode selected [ 17.406018] hdc: UDMA/100 mode selected [ 17.407194] hdb: UDMA/100 mode selected [ 17.408547] hdc: UDMA/100 mode selected [ 17.410788] hdd: UDMA/100 mode selected [ 17.414443] hdc: UDMA/100 mode selected [ 17.415946] hdc: UDMA/100 mode selected [ 17.417699] hdd: UDMA/100 mode selected [ 17.418530] hdd: UDMA/100 mode selected [ 17.420923] hdd: UDMA/100 mode selected [ 18.087676] PCI: Setting latency timer of device 0000:00:06.0 to 64 [ 18.266061] usb 1-2: reset high speed USB device using ehci_hcd and address 2 [ 18.421295] phy0: Selected rate control algorithm 'pid' [ 18.426728] zd1211rw 1-2:1.0: phy0 [ 18.427104] usbcore: registered new interface driver zd1211rw [ 18.438637] intel8x0_measure_ac97_clock: measured 59882 usecs [ 18.439055] intel8x0: clocking to 48000 [ 18.925994] hda: UDMA/100 mode selected [ 18.927284] hdb: UDMA/100 mode selected [ 18.931557] hdc: UDMA/100 mode selected [ 18.933647] hdd: UDMA/100 mode selected [ 20.567059] EXT3 FS on sda5, internal journal [ 21.255909] Initializing USB Mass Storage driver... [ 21.256343] usbcore: registered new interface driver usb-storage [ 21.256763] USB Mass Storage support registered. [ 40.474760] fuse init (API version 7.9) [ 40.551278] ReiserFS: sdb1: found reiserfs format "3.6" with standard journal [ 40.551353] ReiserFS: sdb1: using ordered data mode [ 40.560053] 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 [ 40.560053] ReiserFS: sdb1: checking transaction log (sdb1) [ 40.609737] ReiserFS: sdb1: Using r5 hash to sort names [ 40.641147] ReiserFS: sdb1: Removing [72979 138650 0x0 SD]..done [ 40.641147] ReiserFS: sdb1: Removing [72979 138288 0x0 SD]..done [ 40.641147] ReiserFS: sdb1: Removing [72979 132434 0x0 SD]..done [ 40.641147] ReiserFS: sdb1: Removing [72979 132412 0x0 SD]..done [ 40.649568] ReiserFS: sdb1: Removing [72979 132327 0x0 SD]..done [ 40.649602] ReiserFS: sdb1: There were 5 uncompleted unlinks/truncates. Completed [ 40.853906] kjournald2 starting. Commit interval 5 seconds [ 40.857710] EXT4 FS on md4, internal journal [ 40.857714] EXT4-fs: recovery complete. [ 40.864208] EXT4-fs: mounted filesystem with ordered data mode. [ 40.864208] EXT4-fs: file extents enabled [ 40.864208] EXT4-fs: mballoc enabled [ 40.894600] Adding 1542008k swap on /dev/mapper/md2. Priority:1 extents:1 across:1542008k [ 50.100542] zd1211rw 1-2:1.0: firmware version 4605 [ 50.157433] zd1211rw 1-2:1.0: zd1211 chip 0ace:1211 v4330 high 00-30-95 RF2959_RF pa0 ----- [ 50.256833] ADDRCONF(NETDEV_UP): wlan0: link is not ready [ 52.254685] wlan0: Initial auth_alg=0 [ 52.254692] wlan0: authenticate with AP 00:11:09:5a:dc:f3 [ 52.256315] wlan0: RX authentication from 00:11:09:5a:dc:f3 (alg=0 transaction=2 status=0) [ 52.256315] wlan0: authenticated [ 52.256315] wlan0: associate with AP 00:11:09:5a:dc:f3 [ 52.258582] wlan0: RX AssocResp from 00:11:09:5a:dc:f3 (capab=0x431 status=0 aid=1) [ 52.258582] wlan0: associated [ 52.258582] wlan0: CTS protection enabled (BSSID=00:11:09:5a:dc:f3) [ 52.258751] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [ 54.312743] RPC: Registered udp transport module. [ 54.312749] RPC: Registered tcp transport module. [ 55.863629] tun: Universal TUN/TAP device driver, 1.6 [ 55.863629] tun: (C) 1999-2004 Max Krasnyansky [ 56.319012] warning: `named' uses 32-bit capabilities (legacy support in use) [ 70.032274] Installing knfsd (copyright (C) 1996 okir@monad.swb.de). [ 70.270054] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory [ 70.270429] NFSD: starting 90-second grace period [ 81.518373] device wlan0 entered promiscuous mode [ 81.519688] device wlan0 left promiscuous mode [ 81.521427] device wlan0 entered promiscuous mode [ 92.080650] ip_tables: (C) 2000-2006 Netfilter Core Team [ 92.176034] nf_conntrack version 0.5.0 (16384 buckets, 65536 max) [ 92.498363] u32 classifier [ 92.498363] Performance counters on [ 92.498363] Actions configured [ 1406.070342] kjournald starting. Commit interval 5 seconds [ 1406.071300] EXT3-fs: mounted filesystem with ordered data mode. [ 1413.050943] XFS mounting filesystem dm-2 [ 1413.322418] Ending clean XFS mount for filesystem: dm-2 [ 1420.674852] XFS mounting filesystem dm-3 [ 1421.054131] Ending clean XFS mount for filesystem: dm-3 [ 1432.750340] kjournald starting. Commit interval 5 seconds [ 1432.761993] EXT3 FS on dm-4, internal journal [ 1432.762003] EXT3-fs: recovery complete. [ 1432.772518] EXT3-fs: mounted filesystem with ordered data mode. [ 3448.076762] parport0: PC-style at 0x378 (0x778) [PCSPP,TRISTATE] [ 3448.211744] parport0: irq 7 detected [ 3448.293333] lp0: using parport0 (polling). [ 4657.715875] [ 4657.715881] ======================================================= [ 4657.716512] [ INFO: possible circular locking dependency detected ] [ 4657.716895] 2.6.25-rc6 #5 [ 4657.717170] ------------------------------------------------------- [ 4657.717552] rsync/14184 is trying to acquire lock: [ 4657.717892] (iprune_mutex){--..}, at: [] shrink_icache_memory+0x72/0x220 [ 4657.718239] [ 4657.718239] but task is already holding lock: [ 4657.718463] (&(&ip->i_iolock)->mr_lock){----}, at: [] xfs_ilock+0x96/0xb0 [ 4657.718714] [ 4657.718715] which lock already depends on the new lock. [ 4657.718716] [ 4657.719047] [ 4657.719047] the existing dependency chain (in reverse order) is: [ 4657.719291] [ 4657.719292] -> #1 (&(&ip->i_iolock)->mr_lock){----}: [ 4657.719526] [] add_lock_to_list+0x44/0xc0 [ 4657.719779] [] __lock_acquire+0xc26/0x10b0 [ 4657.720142] [] xfs_ilock+0x96/0xb0 [ 4657.720483] [] mark_held_locks+0x3d/0x70 [ 4657.720483] [] lock_acquire+0x5e/0x80 [ 4657.720483] [] xfs_ilock+0x96/0xb0 [ 4657.720483] [] down_write_nested+0x41/0x60 [ 4657.720483] [] xfs_ilock+0x96/0xb0 [ 4657.720483] [] xfs_ilock+0x96/0xb0 [ 4657.720483] [] xfs_ireclaim+0x1a/0x60 [ 4657.720483] [] xfs_finish_reclaim+0x53/0x1a0 [ 4657.720483] [] xfs_fs_clear_inode+0x5e/0x90 [ 4657.720483] [] clear_inode+0xa9/0x130 [ 4657.720483] [] destroy_inode+0x20/0x40 [ 4657.720483] [] dispose_list+0x1a/0xc0 [ 4657.720483] [] shrink_icache_memory+0x1e2/0x220 [ 4657.720483] [] shrink_slab+0x101/0x160 [ 4657.720483] [] kswapd+0x298/0x3f0 [ 4657.720483] [] isolate_pages_global+0x0/0x60 [ 4657.720483] [] autoremove_wake_function+0x0/0x40 [ 4657.720483] [] trace_hardirqs_on+0x9c/0x110 [ 4657.720483] [] kswapd+0x0/0x3f0 [ 4657.720483] [] kthread+0x42/0x70 [ 4657.720483] [] kthread+0x0/0x70 [ 4657.720483] [] kernel_thread_helper+0x7/0x18 [ 4657.720483] [] 0xffffffff [ 4657.720483] [ 4657.720483] -> #0 (iprune_mutex){--..}: [ 4657.720483] [] print_circular_bug_entry+0x40/0x50 [ 4657.720483] [] __lock_acquire+0xa27/0x10b0 [ 4657.720483] [] __lock_acquire+0x18f/0x10b0 [ 4657.720483] [] lock_acquire+0x5e/0x80 [ 4657.720483] [] shrink_icache_memory+0x72/0x220 [ 4657.720483] [] mutex_lock_nested+0x89/0x240 [ 4657.720483] [] shrink_icache_memory+0x72/0x220 [ 4657.720483] [] shrink_icache_memory+0x72/0x220 [ 4657.720483] [] shrink_icache_memory+0x72/0x220 [ 4657.720483] [] shrink_slab+0x21/0x160 [ 4657.720483] [] shrink_slab+0x101/0x160 [ 4657.720483] [] try_to_free_pages+0x152/0x230 [ 4657.720483] [] isolate_pages_global+0x0/0x60 [ 4657.720483] [] __alloc_pages+0x14b/0x370 [ 4657.720483] [] _read_unlock_irq+0x20/0x30 [ 4657.720483] [] __grab_cache_page+0x81/0xc0 [ 4657.720483] [] block_write_begin+0x76/0xe0 [ 4657.720483] [] xfs_vm_write_begin+0x46/0x50 [ 4657.720483] [] xfs_get_blocks+0x0/0x30 [ 4657.720483] [] generic_file_buffered_write+0x117/0x650 [ 4657.720483] [] __mark_inode_dirty+0x53/0x180 [ 4657.720483] [] _spin_lock+0x29/0x40 [ 4657.720483] [] __mark_inode_dirty+0x53/0x180 [ 4657.720483] [] xfs_write+0x7ac/0x8a0 [ 4657.720483] [] core_sys_select+0x21/0x350 [ 4657.720483] [] xfs_file_aio_write+0x5c/0x70 [ 4657.720483] [] do_sync_write+0xd5/0x120 [ 4657.720483] [] autoremove_wake_function+0x0/0x40 [ 4657.720483] [] dnotify_parent+0x35/0x90 [ 4657.720483] [] do_sync_write+0x0/0x120 [ 4657.720483] [] vfs_write+0x9f/0x140 [ 4657.720483] [] sys_write+0x41/0x70 [ 4657.720483] [] sysenter_past_esp+0x5f/0xa5 [ 4657.720483] [] 0xffffffff [ 4657.720483] [ 4657.720483] other info that might help us debug this: [ 4657.720483] [ 4657.720483] 3 locks held by rsync/14184: [ 4657.720483] #0: (&sb->s_type->i_mutex_key#12){--..}, at: [] xfs_write+0x3f9/0x8a0 [ 4657.720483] #1: (&(&ip->i_iolock)->mr_lock){----}, at: [] xfs_ilock+0x96/0xb0 [ 4657.720483] #2: (shrinker_rwsem){----}, at: [] shrink_slab+0x21/0x160 [ 4657.720483] [ 4657.720483] stack backtrace: [ 4657.720483] Pid: 14184, comm: rsync Not tainted 2.6.25-rc6 #5 [ 4657.720483] [] print_circular_bug_tail+0x72/0x80 [ 4657.720483] [] __lock_acquire+0xa27/0x10b0 [ 4657.720483] [] __lock_acquire+0x18f/0x10b0 [ 4657.720483] [] lock_acquire+0x5e/0x80 [ 4657.720483] [] shrink_icache_memory+0x72/0x220 [ 4657.720483] [] mutex_lock_nested+0x89/0x240 [ 4657.720483] [] shrink_icache_memory+0x72/0x220 [ 4657.720483] [] shrink_icache_memory+0x72/0x220 [ 4657.720483] [] shrink_icache_memory+0x72/0x220 [ 4657.720483] [] shrink_slab+0x21/0x160 [ 4657.720483] [] shrink_slab+0x101/0x160 [ 4657.720483] [] try_to_free_pages+0x152/0x230 [ 4657.720483] [] isolate_pages_global+0x0/0x60 [ 4657.720483] [] __alloc_pages+0x14b/0x370 [ 4657.720483] [] _read_unlock_irq+0x20/0x30 [ 4657.720483] [] __grab_cache_page+0x81/0xc0 [ 4657.720483] [] block_write_begin+0x76/0xe0 [ 4657.720483] [] xfs_vm_write_begin+0x46/0x50 [ 4657.720483] [] xfs_get_blocks+0x0/0x30 [ 4657.720483] [] generic_file_buffered_write+0x117/0x650 [ 4657.720483] [] __mark_inode_dirty+0x53/0x180 [ 4657.720483] [] _spin_lock+0x29/0x40 [ 4657.720483] [] __mark_inode_dirty+0x53/0x180 [ 4657.720483] [] xfs_write+0x7ac/0x8a0 [ 4657.720483] [] core_sys_select+0x21/0x350 [ 4657.720483] [] xfs_file_aio_write+0x5c/0x70 [ 4657.720483] [] do_sync_write+0xd5/0x120 [ 4657.720483] [] autoremove_wake_function+0x0/0x40 [ 4657.720483] [] dnotify_parent+0x35/0x90 [ 4657.720483] [] do_sync_write+0x0/0x120 [ 4657.720483] [] vfs_write+0x9f/0x140 [ 4657.720483] [] sys_write+0x41/0x70 [ 4657.720483] [] sysenter_past_esp+0x5f/0xa5 [ 4657.720483] =======================