Re: 2.6.23-rt1 lockup after loading HAL deamon

[Rok Markovic <kernel@kanardia.eu>]

[-- Attachment #1: Type: text/plain, Size: 1442 bytes --]

I forgot attachment.

Rok

Rok Markovic wrote:
> Hi
> 
> I enabled netconsole but I am affraid that it won't help. In attachment
> I am sending complete log over netconsole. While I was trying to make
> everything, I got a lockup on linux-2.6.23-rc8, but I am not sure into
> this. But all lockups happend in VGA console mode. Any ideas.
> 
> Rok
> 
> 
> Ingo Molnar wrote:
>>> I tried to recompile with those options enabled, kernel and linux 
>>> boots and loads fine, but after few minutes (if i try to recompile 
>>> kernel in console) the system locks completely (hard reset) without 
>>> any messages. Is there anything I can do, maybe console on serial 
>>> port? My new config is atached.
>> yes, serial console (or netconsole) output would be useful, if you can 
>> solve that. Another way would be to boot with nmi_watchdog=2, do the 
>> kernel recompile in a VGA text console, and wait for the lockup to 
>> occur. Either you get some crash message to the text console 
>> immediately, or you should get the NMI watchdog print something within a 
>> minute or so. If neither happens you just get a blank hard lockup then 
>> the wedge is very deep ...
>>
>> (also make sure that the NMI counter in /proc/interrupts is increasing 
>> on all CPUs properly, with nmi_watchdog=2. If not all CPUs/cores are 
>> increasing their NMI counters once per second then the NMI watchdog wont 
>> be able to print out a stackdump.)
>>
>> 	Ingo


[-- Attachment #2: linux.log --]
[-- Type: text/x-log, Size: 22008 bytes --]

[  859.950668] sd 2:0:0:1: [sdc] 494080 512-byte hardware sectors (253 MB)
[  859.951294] sd 2:0:0:1: [sdc] Write Protect is off
[  859.951313] sd 2:0:0:1: [sdc] Assuming drive cache: write through
[  860.618633] sd 2:0:0:1: [sdc] 494080 512-byte hardware sectors (253 MB)
[  860.619414] sd 2:0:0:1: [sdc] Write Protect is off
[  860.619433] sd 2:0:0:1: [sdc] Assuming drive cache: write through
[ 1007.878314] nfsd: last server has exited
[ 1007.926075] nfsd: unexporting all filesystems
[ 1324.899757] nfsd: last server has exited
[ 1324.952596] nfsd: unexporting all filesystems
[    0.000000] Linux version 2.6.23-rt1 (root@rok.spina.si) (gcc version 4.1.2 20070925 (Red Hat 4.1.2-27)) #4 SMP PREEMPT RT Fri Oct 12 22:07:05 CEST 2007
[    0.000000] Command line: ro root=/dev/VolGroup00/LogVol01 netconsole=@192.168.10.124/eth0,9354@192.168.10.1/ nmi_watchdog=2
[    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: 00000000000e6000 - 0000000000100000 (reserved)
[    0.000000]  BIOS-e820: 0000000000100000 - 000000003ffb0000 (usable)
[    0.000000]  BIOS-e820: 000000003ffb0000 - 000000003ffc0000 (ACPI data)
[    0.000000]  BIOS-e820: 000000003ffc0000 - 000000003fff0000 (ACPI NVS)
[    0.000000]  BIOS-e820: 000000003fff0000 - 0000000040000000 (reserved)
[    0.000000]  BIOS-e820: 00000000fee00000 - 00000000fee01000 (reserved)
[    0.000000]  BIOS-e820: 00000000ff780000 - 0000000100000000 (reserved)
[    0.000000] end_pfn_map = 1048576
[    0.000000] DMI 2.3 present.
[    0.000000] ACPI: RSDP 000F7C80, 0014 (r0 ACPIAM)
[    0.000000] ACPI: RSDT 3FFB0000, 0034 (r1 A M I  OEMRSDT  12000521 MSFT       97)
[    0.000000] ACPI: FACP 3FFB0200, 0084 (r2 A M I  OEMFACP  12000521 MSFT       97)
[    0.000000] ACPI: DSDT 3FFB0450, 3CF4 (r1  75D8P 75D8P004        4 INTL  2002026)
[    0.000000] ACPI: FACS 3FFC0000, 0040
[    0.000000] ACPI: APIC 3FFB0390, 0078 (r1 A M I  OEMAPIC  12000521 MSFT       97)
[    0.000000] ACPI: MCFG 3FFB0410, 003C (r1 A M I  OEMMCFG  12000521 MSFT       97)
[    0.000000] ACPI: OEMB 3FFC0040, 0046 (r1 A M I  AMI_OEM  12000521 MSFT       97)
[    0.000000] Zone PFN ranges:
[    0.000000]   DMA             0 ->     4096
[    0.000000]   DMA32        4096 ->  1048576
[    0.000000]   Normal    1048576 ->  1048576
[    0.000000] Movable zone start PFN for each node
[    0.000000] early_node_map[2] active PFN ranges
[    0.000000]     0:        0 ->      159
[    0.000000]     0:      256 ->   262064
[    0.000000] ACPI: PM-Timer IO Port: 0x808
[    0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x00] enabled)
[    0.000000] Processor #0 (Bootup-CPU)
[    0.000000] ACPI: LAPIC (acpi_id[0x02] lapic_id[0x01] enabled)
[    0.000000] Processor #1
[    0.000000] ACPI: LAPIC (acpi_id[0x03] lapic_id[0x82] disabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x04] lapic_id[0x83] disabled)
[    0.000000] ACPI: IOAPIC (id[0x02] address[0xfec00000] gsi_base[0])
[    0.000000] IOAPIC[0]: apic_id 2, address 0xfec00000, GSI 0-23
[    0.000000] ACPI: IOAPIC (id[0x03] address[0xfecc0000] gsi_base[24])
[    0.000000] IOAPIC[1]: apic_id 3, address 0xfecc0000, GSI 24-47
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 low level)
[    0.000000] Setting APIC routing to flat
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] Allocating PCI resources starting at 50000000 (gap: 40000000:bee00000)
[    0.000000] SMP: Allowing 4 CPUs, 2 hotplug CPUs
[    0.000000] PERCPU: Allocating 38456 bytes of per cpu data
[    0.000000] Real-Time Preemption Support (C) 2004-2007 Ingo Molnar
[    0.000000] Built 1 zonelists in Zone order.  Total pages: 245914
[    0.000000] Kernel command line: ro root=/dev/VolGroup00/LogVol01 netconsole=@192.168.10.124/eth0,9354@192.168.10.1/ nmi_watchdog=2
[    0.000000] netconsole: local port 6665
[    0.000000] netconsole: local IP 192.168.10.124
[    0.000000] netconsole: interface eth0
[    0.000000] netconsole: remote port 9354
[    0.000000] netconsole: remote IP 192.168.10.1
[    0.000000] netconsole: remote ethernet address ff:ff:ff:ff:ff:ff
[    0.000000] Initializing CPU#0
[    0.000000] WARNING: experimental RCU implementation.
[    0.000000] PID hash table entries: 4096 (order: 12, 32768 bytes)
[    0.000000] TSC calibrated against PM_TIMER
[   93.054085] time.c: Detected 3039.840 MHz processor.
[   93.057058] Console: colour VGA+ 80x25
[   93.057063] console [tty0] enabled
[   93.067620] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[   93.067886] ... MAX_LOCKDEP_SUBCLASSES:      8
[   93.068060] ... MAX_LOCK_DEPTH:             30
[   93.068221] ... MAX_LOCKDEP_KEYS:         2048
[   93.068381] ... CLASSHASH_SIZE:           1024
[   93.068542] ... MAX_LOCKDEP_ENTRIES:     16384
[   93.068703] ... MAX_LOCKDEP_CHAINS:      32768
[   93.068864] ... CHAINHASH_SIZE:          16384
[   93.069037]  memory used by lock dependency info: 2608 kB
[   93.069203]  per task-struct memory footprint: 1680 bytes
[   93.070085] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes)
[   93.070831] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes)
[   93.071115] Checking aperture...
[   93.109735] Memory: 977292k/1048256k available (3303k kernel code, 70100k reserved, 1806k data, 272k init)
[   93.170740] Calibrating delay using timer specific routine.. 6085.99 BogoMIPS (lpj=3042998)
[   93.171561] Mount-cache hash table entries: 256
[   93.173867] CPU: Trace cache: 12K uops, L1 D cache: 16K
[   93.174085] CPU: L2 cache: 2048K
[   93.174241] using mwait in idle threads.
[   93.174400] CPU: Physical Processor ID: 0
[   93.174558] CPU: Processor Core ID: 0
[   93.174740] CPU0: Thermal monitoring enabled (TM1)
[   93.174924] lockdep: not fixing up alternatives.
[   93.175088] ACPI: Core revision 20070126
[   93.199116] Using local APIC timer interrupts.
[   93.232100] Detected 12.665 MHz APIC timer.
[   93.234370] lockdep: not fixing up alternatives.
[   93.234606] Booting processor 1/2 APIC 0x1
[   93.911541] Initializing CPU#1
[   93.971169] Calibrating delay using timer specific routine.. 6079.30 BogoMIPS (lpj=3039650)
[   93.971186] CPU: Trace cache: 12K uops, L1 D cache: 16K
[   93.971190] CPU: L2 cache: 2048K
[   93.971195] CPU: Physical Processor ID: 0
[   93.971197] CPU: Processor Core ID: 0
[   93.971213] CPU1: Thermal monitoring enabled (TM1)
[   93.971736]               Intel(R) Pentium(R) 4 CPU 3.00GHz stepping 03
[   93.305728] checking TSC synchronization [CPU#0 -> CPU#1]:
[   93.327326] Measured 2029973100 cycles TSC warp between CPUs, turning off TSC clock.
[   93.327596] Marking TSC unstable due to check_tsc_sync_source failed
[   93.327838] Brought up 2 CPUs
[   93.328205] testing NMI watchdog ... OK.
[   93.349780] NET: Registered protocol family 16
[   93.350928] ACPI: bus type pci registered
[   93.351545] PCI: BIOS Bug: MCFG area at e0000000 is not E820-reserved
[   93.351718] PCI: Not using MMCONFIG.
[   93.351878] PCI: Using configuration type 1
[   94.054177] ACPI: Interpreter enabled
[   94.054338] ACPI: (supports S0 S1 S3 S5)
[   94.054779] ACPI: Using IOAPIC for interrupt routing
[   94.092159] ACPI: PCI Root Bridge [PCI0] (0000:00)
[   93.480417] ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 6 7 10 *11 12 14 15)
[   93.481489] ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 6 *7 10 11 12 14 15)
[   93.482560] ACPI: PCI Interrupt Link [LNKC] (IRQs *3 4 5 6 7 10 11 12 14 15)
[   93.483608] ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 5 6 7 *10 11 12 14 15)
[   93.484669] ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled.
[   93.485904] ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled.
[   93.487135] ACPI: PCI Interrupt Link [LNKG] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled.
[   93.488381] ACPI: PCI Interrupt Link [LNKH] (IRQs 3 4 5 6 7 *10 11 12 14 15)
[   93.489748] ACPI Warning (tbutils-0217): Incorrect checksum in table [OEMB] -  C2, should be B5 [20070126]
[   93.490132] Linux Plug and Play Support v0.97 (c) Adam Belay
[   93.490477] pnp: PnP ACPI init
[   93.490675] ACPI: bus type pnp registered
[   93.505890] pnp: PnP ACPI: found 14 devices
[   93.506052] ACPI: ACPI bus type pnp unregistered
[   93.507378] SCSI subsystem initialized
[   94.174860] PCI: Using ACPI for IRQ routing
[   94.175023] PCI: If a device doesn't work, try "pci=routeirq".  If it helps, post a report
[   93.518470] Bluetooth: Core ver 2.11
[   93.519015] NET: Registered protocol family 31
[   93.519176] Bluetooth: HCI device and connection manager initialized
[   93.519351] Bluetooth: HCI socket layer initialized
[   93.519547] PCI-GART: No AMD northbridge found.
[   93.519810] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[   93.520171] hpet0: 3 32-bit timers, 14318180 Hz
[   94.196097] pnp: 00:08: iomem range 0xfec00000-0xfec00fff has been reserved
[   94.196278] pnp: 00:08: iomem range 0xfee00000-0xfee00fff could not be reserved
[   94.196570] pnp: 00:0c: iomem range 0xe0000000-0xefffffff has been reserved
[   94.196765] pnp: 00:0d: iomem range 0x0-0x9ffff could not be reserved
[   94.196968] pnp: 00:0d: iomem range 0xc0000-0xcffff has been reserved
[   94.197145] pnp: 00:0d: iomem range 0xe0000-0xfffff could not be reserved
[   94.197322] pnp: 00:0d: iomem range 0x100000-0x3fffffff could not be reserved
[   94.199602] PCI: Bridge: 0000:00:01.0
[   94.199759]   IO window: disabled.
[   94.199946]   MEM window: disabled.
[   94.200104]   PREFETCH window: disabled.
[   94.200266] PCI: Bridge: 0000:00:02.0
[   94.200422]   IO window: disabled.
[   94.200581]   MEM window: f1e00000-fbefffff
[   94.200743]   PREFETCH window: cff00000-dfefffff
[   94.200997] ACPI: PCI Interrupt 0000:00:02.0[A] -> GSI 27 (level, low) -> IRQ 27
[   94.201433] NET: Registered protocol family 2
[   93.566824] IP route cache hash table entries: 32768 (order: 6, 262144 bytes)
[   93.568047] TCP established hash table entries: 16384 (order: 9, 3014656 bytes)
[   93.571333] TCP bind hash table entries: 16384 (order: 9, 2883584 bytes)
[   93.574399] TCP: Hash tables configured (established 16384 bind 16384)
[   93.574583] TCP reno registered
[   94.253859] checking if image is initramfs... it is
[   94.602233] Freeing initrd memory: 3678k freed
[   93.946587] Initializing RT-Tester: OK
[   94.613377] Total HugeTLB memory allocated, 0
[   93.947903] VFS: Disk quotas dquot_6.5.1
[   93.948095] Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[   93.948952] io scheduler noop registered
[   93.949111] io scheduler anticipatory registered
[   93.950721] io scheduler deadline registered
[   93.950941] io scheduler cfq registered (default)
[   93.951124] PCI: VIA PCI bridge detected. Disabling DAC.
[   93.953240] PCI: Bypassing VIA 8237 APIC De-Assert Message
[   94.620330] assign_interrupt_mode Found MSI capability
[   94.621974] vga16fb: mapped to 0xffff8100000a0000
[   94.194240] Console: switching to colour frame buffer device 80x30
[   94.448623] fb0: VGA16 VGA frame buffer device
[   94.451839] input: Power Button (FF) as /devices/virtual/input/input0
[   94.455506] ACPI: Power Button (FF) [PWRF]
[   94.460014] input: Power Button (CM) as /devices/virtual/input/input1
[   94.464928] ACPI: Power Button (CM) [PWRB]
[   94.471509] ACPI: SSDT 3FFB4150, 02FC (r1    AMI   CPU1PM        1 INTL  2002026)
[   94.485419] ACPI Warning (tbutils-0217): Incorrect checksum in table [SSDT] -  AE, should be 57 [20070126]
[   94.502770] ACPI: SSDT 3FFB4450, 02FC (r1    AMI   CPU2PM        1 INTL  2002026)
[   94.524084] ACPI Exception (processor_core-0819): AE_NOT_FOUND, Processor Device is not present [20070126]
[   94.548896] ACPI Exception (processor_core-0819): AE_NOT_FOUND, Processor Device is not present [20070126]
[   95.323865] Real Time Clock Driver v1.12ac
[   95.340173] Linux agpgart interface v0.102
[   95.355492] Serial: 8250/16550 driver $Revision: 1.90 $ 16 ports, IRQ sharing enabled
[   94.723020] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[   95.412021] 00:0b: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[   94.765752] ACPI: PCI Interrupt 0000:00:09.0[A] -> GSI 17 (level, low) -> IRQ 17
[   94.807195] 0000:00:09.0: ttyS4 at I/O 0xd000 (irq = 17) is a 16550A
[   94.829200] 0000:00:09.0: ttyS5 at I/O 0xcc00 (irq = 17) is a 16550A
[   94.850764] 0000:00:09.0: ttyS6 at I/O 0xc480 (irq = 17) is a 16550A
[   94.871918] 0000:00:09.0: ttyS7 at I/O 0xc400 (irq = 17) is a 16550A
[   94.892656] 0000:00:09.0: ttyS8 at I/O 0xc080 (irq = 17) is a 16550A
[   94.912963] 0000:00:09.0: ttyS9 at I/O 0xc000 (irq = 17) is a 16550A
[   94.936470] RAMDISK driver initialized: 16 RAM disks of 4096K size 1024 blocksize
[   94.977026] loop: module loaded
[   94.996106] via-rhine.c:v1.10-LK1.4.3 2007-03-06 Written by Donald Becker
[   95.015180] ACPI: PCI Interrupt 0000:00:12.0[A] -> GSI 23 (level, low) -> IRQ 23
[   95.054240] eth0: VIA Rhine II at 0xfbfffc00, 00:13:8f:27:34:8c, IRQ 23.
[   95.075223] eth0: MII PHY found at address 1, status 0x786d advertising 05e1 Link 45e1.
[   95.113808] netconsole: device eth0 not up yet, forcing it
[   95.133982] eth0: link up, 100Mbps, full-duplex, lpa 0x45E1
[   95.173815] netconsole: carrier detect appears untrustworthy, waiting 4 seconds
[   99.882109] console [netcon0] enabled
[  103.963986] netconsole: network logging started
[  103.317488] megaraid cmm: 2.20.2.7 (Release Date: Sun Jul 16 00:01:03 EST 2006)
[  103.355647] megaraid: 2.20.5.1 (Release Date: Thu Nov 16 15:32:35 EST 2006)
[  103.375428] megasas: 00.00.03.10-rc5 Thu May 17 10:09:32 PDT 2007
[  104.061849] PNP: PS/2 Controller [PNP0303:PS2K,PNP0f03:PS2M] at 0x60,0x64 irq 1,12
[  103.436546] serio: i8042 KBD port at 0x60,0x64 irq 1
[  103.457648] serio: i8042 AUX port at 0x60,0x64 irq 12
[  104.145577] mice: PS/2 mouse device common for all mice
[  104.185846] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input2
[  104.233042] device-mapper: ioctl: 4.11.0-ioctl (2006-10-12) initialised: dm-devel@redhat.com
[  104.277431] Advanced Linux Sound Architecture Driver Version 1.0.14 (Fri Jul 20 09:12:58 2007 UTC).
[  103.656495] ACPI: PCI Interrupt 0000:00:11.5[C] -> GSI 22 (level, low) -> IRQ 22
[  104.390010] logips2pp: Detected unknown logitech mouse model 127
[  104.878492] input: ImExPS/2 Logitech Explorer Mouse as /devices/platform/i8042/serio1/input/input3
[  104.224133] ALSA device list:
[  104.224137]   #0: VIA 8237 with ALC850 at 0xe400, irq 22
[  104.224904] oprofile: using NMI interrupt.
[  104.225258] TCP cubic registered
[  104.225261] Initializing XFRM netlink socket
[  104.225414] NET: Registered protocol family 1
[  104.225453] NET: Registered protocol family 17
[  105.072768] *****************************************************************************
[  104.444236] *                                                                           *
[  104.479459] *  REMINDER, the following debugging options are turned on in your .config: *
[  104.516370] *                                                                           *
[  104.516374] *        CONFIG_DEBUG_RT_MUTEXES                                            *
[  104.516377] *        CONFIG_DEBUG_SLAB                                                  *
[  104.516439] *        CONFIG_LOCKDEP                                                     *
[  104.516442] *                                                                           *
[  104.516445] *  they may increase runtime overhead and latencies.                        *
[  104.516448] *                                                                           *
[  104.516451] *****************************************************************************
[  104.516456] Freeing unused kernel memory: 272k freed
[  105.860996] usbcore: registered new interface driver usbfs
[  105.207493] usbcore: registered new interface driver hub
[  105.885904] usbcore: registered new device driver usb
[  105.248118] USB Universal Host Controller Interface driver v3.0
[  105.260987] ACPI: PCI Interrupt 0000:00:10.0[A] -> GSI 21 (level, low) -> IRQ 21
[  105.287330] uhci_hcd 0000:00:10.0: UHCI Host Controller
[  105.301786] uhci_hcd 0000:00:10.0: new USB bus registered, assigned bus number 1
[  105.996693] uhci_hcd 0000:00:10.0: irq 21, io base 0x0000ec00
[  105.345857] usb usb1: configuration #1 chosen from 1 choice
[  105.360932] hub 1-0:1.0: USB hub found
[  105.376179] hub 1-0:1.0: 2 ports detected
[  105.491424] ACPI: PCI Interrupt 0000:00:10.1[A] -> GSI 21 (level, low) -> IRQ 21
[  105.522246] uhci_hcd 0000:00:10.1: UHCI Host Controller
[  105.538133] uhci_hcd 0000:00:10.1: new USB bus registered, assigned bus number 2
[  105.570693] uhci_hcd 0000:00:10.1: irq 21, io base 0x0000e080
[  105.588798] usb usb2: configuration #1 chosen from 1 choice
[  105.606870] hub 2-0:1.0: USB hub found
[  105.625014] hub 2-0:1.0: 2 ports detected
[  105.696495] usb 1-1: new full speed USB device using uhci_hcd and address 2
[  105.743782] ACPI: PCI Interrupt 0000:00:10.2[B] -> GSI 21 (level, low) -> IRQ 21
[  106.448783] uhci_hcd 0000:00:10.2: UHCI Host Controller
[  105.803172] uhci_hcd 0000:00:10.2: new USB bus registered, assigned bus number 3
[  105.843731] uhci_hcd 0000:00:10.2: irq 21, io base 0x0000e000
[  105.865161] usb usb3: configuration #1 chosen from 1 choice
[  105.885712] hub 3-0:1.0: USB hub found
[  106.561793] usb 1-1: configuration #1 chosen from 1 choice
[  105.926602] hub 3-0:1.0: 2 ports detected
[  106.047044] ACPI: PCI Interrupt 0000:00:10.3[B] -> GSI 21 (level, low) -> IRQ 21
[  106.088337] uhci_hcd 0000:00:10.3: UHCI Host Controller
[  106.109515] uhci_hcd 0000:00:10.3: new USB bus registered, assigned bus number 4
[  106.151837] uhci_hcd 0000:00:10.3: irq 21, io base 0x0000dc00
[  106.839680] usb usb4: configuration #1 chosen from 1 choice
[  106.860932] hub 4-0:1.0: USB hub found
[  106.215926] hub 4-0:1.0: 2 ports detected
[  106.385272] ACPI: PCI Interrupt 0000:00:10.4[C] -> GSI 21 (level, low) -> IRQ 21
[  107.092451] ehci_hcd 0000:00:10.4: EHCI Host Controller
[  106.446848] ehci_hcd 0000:00:10.4: new USB bus registered, assigned bus number 5
[  106.486922] ehci_hcd 0000:00:10.4: irq 21, io mem 0xfbfff800
[  106.507322] ehci_hcd 0000:00:10.4: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
[  106.509190] usb usb5: configuration #1 chosen from 1 choice
[  106.509466] hub 5-0:1.0: USB hub found
[  106.509743] hub 5-0:1.0: 8 ports detected
[  107.347630] ACPI: PCI Interrupt 0000:00:0f.0[B] -> GSI 20 (level, low) -> IRQ 20
[  106.719466] sata_via 0000:00:0f.0: routed to hard irq line 7
[  107.396271] usb 1-1: USB disconnect, address 2
[  106.758126] scsi0 : sata_via
[  106.776933] scsi1 : sata_via
[  106.794893] ata1: SATA max UDMA/133 cmd 0x000000000001d880 ctl 0x000000000001d802 bmdma 0x000000000001d080 irq 20
[  106.831248] ata2: SATA max UDMA/133 cmd 0x000000000001d480 ctl 0x000000000001d402 bmdma 0x000000000001d088 irq 20
[  107.735274] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[  107.914894] usb 5-7: new high speed USB device using ehci_hcd and address 3
[  107.939191] ata1.00: ATA-6: WDC WD2000JD-00KLB0, 08.05J08, max UDMA/100
[  107.292919] ata1.00: 390721968 sectors, multi 16: LBA48 
[  107.297708] ata1.00: configured for UDMA/100
[  108.051557] usb 5-7: configuration #1 chosen from 1 choice
[  108.164235] ata2: SATA link down 1.5 Gbps (SStatus 0 SControl 300)
[  108.193515] scsi 0:0:0:0: Direct-Access     ATA      WDC WD2000JD-00K 08.0 PQ: 0 ANSI: 5
[  107.564640] sd 0:0:0:0: [sda] 390721968 512-byte hardware sectors (200050 MB)
[  108.249727] sd 0:0:0:0: [sda] Write Protect is off
[  108.268567] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[  107.610897] usb 1-1: new full speed USB device using uhci_hcd and address 3
[  107.662082] sd 0:0:0:0: [sda] 390721968 512-byte hardware sectors (200050 MB)
[  107.682620] sd 0:0:0:0: [sda] Write Protect is off
[  107.702581] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[  107.742074]  sda: sda1 sda2 sda3
[  107.778210] sd 0:0:0:0: [sda] Attached SCSI disk
[  108.450239] usb 1-1: configuration #1 chosen from 1 choice
[  110.384304] Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
[  110.403216] ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
[  110.486685] Initializing USB Mass Storage driver...
[  110.505469] scsi2 : SCSI emulation for USB Mass Storage devices
[  111.190443] usbcore: registered new interface driver usb-storage
[  111.208830] USB Mass Storage support registered.
[  116.179103] scsi 2:0:0:0: Direct-Access     Generic  USB Storage-CFC  I19A PQ: 0 ANSI: 0 CCS
[  116.215949] sd 2:0:0:0: [sdb] Attached SCSI removable disk
[  115.568742] scsi 2:0:0:1: Direct-Access     Generic  USB Storage-SDC  I19A PQ: 0 ANSI: 0 CCS
[  115.605137] sd 2:0:0:1: [sdc] 494080 512-byte hardware sectors (253 MB)
[  115.624090] sd 2:0:0:1: [sdc] Write Protect is off
[  115.642178] sd 2:0:0:1: [sdc] Assuming drive cache: write through
[  115.661490] sd 2:0:0:1: [sdc] 494080 512-byte hardware sectors (253 MB)
[  115.680070] sd 2:0:0:1: [sdc] Write Protect is off
[  115.697845] sd 2:0:0:1: [sdc] Assuming drive cache: write through
[  115.715526]  sdc: sdc1
[  115.736600] sd 2:0:0:1: [sdc] Attached SCSI removable disk
[  115.755651] scsi 2:0:0:2: Direct-Access     Generic  USB Storage-SMC  I19A PQ: 0 ANSI: 0 CCS
[  116.460943] sd 2:0:0:2: [sdd] Attached SCSI removable disk
[  115.815152] scsi 2:0:0:3: Direct-Access     Generic  USB Storage-MSC  I19A PQ: 0 ANSI: 0 CCS
[  115.855240] sd 2:0:0:3: [sde] Attached SCSI removable disk
[  119.559608] kjournald starting.  Commit interval 5 seconds
[  118.893579] EXT3-fs: mounted filesystem with ordered data mode.