On Fri, Mar 28 2014, Alan Stern wrote:
> This just raises more questions. It looks like it worked almost okay,
> but not quite. Certainly it didn't hang, so it isn't an exact
> reproduction of the problem. Can you run the same test again, and then
> at the end, do:
>
> echo 1 >/sys/bus/usb/devices/4-2/bConfigurationValue
>
> (Actually this 1 should be whatever was in the file before you started
> the test -- it probably was 1 but it might be something else.)
>
> After that, the mouse should be working properly. Check it and see,
> and post the kernel log. (Don't bother to check the frame number
> values, since we already know they are changing.)
Hi Alan,
Yes, the mouse works again. Please find attached the log.
Before the test, the value was 1.
Just after the test, the file bConfigurationValue was empty.
And after "echo 1" the mouse works
> Back when you ran the system suspend test under the 3.9 kernel with
> CONFIG_USB_SUSPEND turned off, you said the system was okay after the
> resume. Does that mean the mouse and keyboard were working normally?
Yes.
> One more thing... The bad commit should have no effect on devices
> enabled for wakeup -- and keyboards generally _are_ enabled for wakeup.
> Can you check this? Under 3.12.x with no patches or reversions,
I've just added your diagnostic patch.
> plug the mouse into a front port and the keyboard into its usual rear
> port. Before going into suspend, make sure that the keyboard's
> power/wakeup file (/sys/bus/usb/devices/4-3/power/wakeup) contains
> "enabled".
Yes, it was already enabled.
> Then test if the suspend and resume work okay -- capture the log with
> netconsole and let's see what it says.
The log is in attached file "keyboard-rear.log".
The resume hangs the system as usual.
> Finally, I'd like to get more information about what causes the system
> to hang when things go wrong. Below is a diagnostic patch for the 3.12
> kernel -- you can keep the previous patch or remove it, it doesn't
> matter. This patch is intended to print information to the console
> every 10 seconds while the system is hung, so if it works then we
> should get some useful clues. Plug the mouse into the rear port and
> see what happens after a suspend.
The log is in attached file "mouse-rear.log".
--
Peter
2014-03-31T00:11:34.483878+02:00 micropit kernel: [ 161.952812] usb 4-2: unregistering interface 4-2:1.0
2014-03-31T00:11:34.483912+02:00 micropit kernel: [ 161.952919] ohci-pci 0000:00:12.1: shutdown urb ffff880120ba9e40 ep1in-intr
2014-03-31T00:11:34.523880+02:00 micropit kernel: [ 161.989995] usb 4-2: usb_disable_device nuking non-ep0 URBs
2014-03-31T00:11:37.271865+02:00 micropit kernel: [ 164.735103] usb 4-2: usb auto-suspend, wakeup 0
2014-03-31T00:11:37.271899+02:00 micropit kernel: [ 164.735124] hub 4-0:1.0: hub_suspend
2014-03-31T00:11:37.271903+02:00 micropit kernel: [ 164.735151] usb usb4: bus auto-suspend, wakeup 1
2014-03-31T00:11:37.271906+02:00 micropit kernel: [ 164.735161] ohci-pci 0000:00:12.1: suspend root hub
2014-03-31T00:11:37.271909+02:00 micropit kernel: [ 164.735513] ohci-pci 0000:00:12.1: hcd_pci_runtime_suspend: 0
2014-03-31T00:11:46.531887+02:00 micropit kernel: [ 173.990242] ohci-pci 0000:00:12.1: powerup ports
2014-03-31T00:11:46.555904+02:00 micropit kernel: [ 174.010817] ohci-pci 0000:00:12.1: hcd_pci_runtime_resume: 0
2014-03-31T00:11:46.555936+02:00 micropit kernel: [ 174.010839] usb usb4: usb auto-resume
2014-03-31T00:11:46.555939+02:00 micropit kernel: [ 174.010851] ohci-pci 0000:00:12.1: resume root hub
2014-03-31T00:11:46.555942+02:00 micropit kernel: [ 174.010866] usb usb4: usb wakeup-resume
2014-03-31T00:11:46.627927+02:00 micropit kernel: [ 174.085767] hub 4-0:1.0: hub_resume
2014-03-31T00:11:46.627963+02:00 micropit kernel: [ 174.085922] hub 4-0:1.0: port 2: status 0303 change 0000
2014-03-31T00:11:46.627967+02:00 micropit kernel: [ 174.086065] hub 4-0:1.0: state 7 ports 3 chg 0000 evt 0000
2014-03-31T00:11:46.627970+02:00 micropit kernel: [ 174.086099] usb 4-2: finish resume
2014-03-31T00:11:46.627973+02:00 micropit kernel: [ 174.086728] ohci-pci 0000:00:12.1: urb ffff880120ba9b40 path 2 ep0in 5ec20000 cc 5 --> status -62
2014-03-31T00:11:46.627984+02:00 micropit kernel: [ 174.086817] usb 4-2: retry with reset-resume
2014-03-31T00:11:46.659853+02:00 micropit kernel: [ 174.116643] ohci-pci 0000:00:12.1: port[1] reset timeout, stat 00000313
2014-03-31T00:11:46.663827+02:00 micropit kernel: [ 174.120241] hub 4-0:1.0: state 7 ports 3 chg 0000 evt 0004
2014-03-31T00:11:46.739879+02:00 micropit kernel: [ 174.194644] hub 4-0:1.0: state 7 ports 3 chg 0000 evt 0004
2014-03-31T00:11:46.863879+02:00 micropit kernel: [ 174.318514] ohci-pci 0000:00:12.1: GetStatus roothub.portstatus [1] = 0x00100303 PRSC LSDA PPS PES CCS
2014-03-31T00:11:46.919884+02:00 micropit kernel: [ 174.374499] usb 4-2: reset low-speed USB device number 2 using ohci-pci
2014-03-31T00:11:46.943879+02:00 micropit kernel: [ 174.400380] ohci-pci 0000:00:12.1: port[1] reset timeout, stat 00000313
2014-03-31T00:11:46.987856+02:00 micropit kernel: [ 174.442422] hub 4-0:1.0: state 7 ports 3 chg 0000 evt 0004
2014-03-31T00:11:47.147877+02:00 micropit kernel: [ 174.602258] ohci-pci 0000:00:12.1: GetStatus roothub.portstatus [1] = 0x00100303 PRSC LSDA PPS PES CCS
2014-03-31T00:12:15.391895+02:00 micropit kernel: [ 202.821728] usb 4-2: adding 4-2:1.0 (config #1, interface 0)
2014-03-31T00:12:15.391927+02:00 micropit kernel: [ 202.821862] usbhid 4-2:1.0: usb_probe_interface
2014-03-31T00:12:15.391930+02:00 micropit kernel: [ 202.821871] usbhid 4-2:1.0: usb_probe_interface - got id
2014-03-31T00:12:15.395884+02:00 micropit kernel: [ 202.827665] input: Microsoft Microsoft 3-Button Mouse with IntelliEye(TM) as /devices/pci0000:00/0000:00:12.1/usb4/4-2/4-2:1.0/input/input13
2014-03-31T00:12:15.395918+02:00 micropit kernel: [ 202.828139] hid-generic 0003:045E:0040.0003: input,hidraw0: USB HID v1.10 Mouse [Microsoft Microsoft 3-Button Mouse with IntelliEye(TM)] on usb-0000:00:12.1-2/input0
[ 769.534131] PM: Syncing filesystems ... done.
[ 769.607455] PM: Preparing system for mem sleep
[ 769.713442] ohci-pci 0000:00:13.1: Dequeue: ffff880121b70bc0 count 1
[ 769.714480] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 24 frame a8a3
[ 769.714523] ohci-pci 0000:00:13.1: Giveback: ffff880121b70bc0 count 1
[ 769.798451] Freezing user space processes ... (elapsed 0.002 seconds) done.
[ 769.800755] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
[ 769.802174] PM: Entering mem sleep
[ 769.802640] ohci-pci 0000:00:12.1: Dequeue: ffff880127280480 count 1
[ 769.802686] usb 5-2: usb suspend, wakeup 0
[ 769.802814] usb 7-3: usb suspend, wakeup 0
[ 769.802859] usb usb6: usb auto-resume
[ 769.802867] ohci-pci 0000:00:14.5: resume root hub
[ 769.802913] hub 5-0:1.0: hub_suspend
[ 769.802921] hub 7-0:1.0: hub_suspend
[ 769.802931] usb usb4: usb auto-resume
[ 769.802939] usb usb7: bus suspend, wakeup 0
[ 769.802943] ohci-pci 0000:00:13.0: resume root hub
[ 769.802945] ehci-pci 0000:00:13.2: suspend root hub
[ 769.803064] usb usb5: bus suspend, wakeup 0
[ 769.803091] ohci-pci 0000:00:13.1: suspend root hub
[ 769.803368] ohci-pci 0000:00:12.1: IRQ: count 1 intr-en 8000005e intr-stat 24 frame 1aae
[ 769.803549] ohci-pci 0000:00:12.1: Giveback: ffff880127280480 count 1
[ 769.803654] usb usb2: usb auto-resume
[ 769.803659] ohci-pci 0000:00:12.0: resume root hub
[ 769.803715] usb usb1: usb auto-resume
[ 769.803718] ehci-pci 0000:00:12.2: resume root hub
[ 769.804336] sd 2:0:0:0: [sdb] Synchronizing SCSI cache
[ 769.804400] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[ 769.804480] sd 2:0:0:0: [sdb] Stopping disk
[ 769.804584] sd 0:0:0:0: [sda] Stopping disk
[ 772.673989] r8169 0000:02:00.0 eth0: link up
[ 774.824006] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 774.854026] ata3.00: configured for UDMA/133
[ 774.858365] sd 2:0:0:0: [sdb] Starting disk
[ 776.066870] ohci-pci 0000:00:13.1: Dequeue: ffff880120a38180 count 1
[ 776.075443] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 776.092110] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 776.108790] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 776.125465] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 776.142140] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 776.158810] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 776.175479] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 776.498483] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 776.515537] ata1.00: configured for UDMA/133
[ 776.519788] sd 0:0:0:0: [sda] Starting disk
[ 777.019659] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 778.018725] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 779.017792] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 780.016857] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 781.015923] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 782.014988] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 783.014054] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 784.013120] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 785.012187] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 786.011253] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 786.073740] ohci-pci 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 787.010320] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 788.009385] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 789.008451] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 790.007517] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 791.006584] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 792.005650] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 793.004716] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 794.003782] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 795.002848] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 796.001914] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 796.080651] ohci-pci 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 797.000980] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 798.000046] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 798.999113] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 799.998179] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 800.997245] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 801.996312] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 802.995378] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 803.994445] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 804.993511] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 805.992576] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 806.087556] ohci-pci 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 806.991643] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 807.990709] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 808.989776] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 809.988841] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 810.987908] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 811.986972] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 812.986037] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 813.985105] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 814.984170] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 815.983237] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 816.094464] ohci-pci 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 816.982301] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 817.981366] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 818.980431] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 819.979496] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 820.978561] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 821.977626] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 822.976691] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 823.975757] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 824.974821] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 825.973888] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 826.101376] ohci-pci 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 826.972952] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 827.972018] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 828.971082] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 829.970147] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 830.969212] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 831.968278] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 832.967342] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 833.966408] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 834.965472] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 835.964538] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 836.108292] ohci-pci 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 836.963603] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 837.962668] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 838.961733] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 839.960797] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 840.959863] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 841.958928] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 842.957993] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 843.957058] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 844.956123] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 845.955187] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 846.115196] ohci-pci 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 846.954252] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 847.953316] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 848.952381] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 849.951446] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 850.950510] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 851.949575] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 20 frame a923
[ 417.998800] PM: Syncing filesystems ... done.
[ 418.295630] PM: Preparing system for mem sleep
[ 418.400211] ohci-pci 0000:00:12.1: Dequeue: ffff8801208a72c0 count 1
[ 418.400441] ohci-pci 0000:00:12.1: IRQ: count 1 intr-en 8000005e intr-stat 24 frame 52d0
[ 418.400484] ohci-pci 0000:00:12.1: Giveback: ffff8801208a72c0 count 1
[ 418.469353] Freezing user space processes ... (elapsed 0.002 seconds) done.
[ 418.471519] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
[ 418.472766] PM: Entering mem sleep
[ 418.473073] ohci-pci 0000:00:13.1: Dequeue: ffff880128a93780 count 1
[ 418.473185] usb 4-2: usb suspend, wakeup 0
[ 418.473250] usb 2-3: usb suspend, wakeup 0
[ 418.473271] usb usb7: usb auto-resume
[ 418.473281] ohci-pci 0000:00:14.5: resume root hub
[ 418.473531] usb usb5: usb auto-resume
[ 418.473586] ohci-pci 0000:00:13.0: resume root hub
[ 418.473691] hub 4-0:1.0: hub_suspend
[ 418.473828] usb usb4: bus suspend, wakeup 0
[ 418.473856] usb usb3: usb auto-resume
[ 418.473864] ohci-pci 0000:00:12.0: resume root hub
[ 418.473912] ohci-pci 0000:00:12.1: suspend root hub
[ 418.474112] hub 2-0:1.0: hub_suspend
[ 418.474122] ohci-pci 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 24 frame 4542
[ 418.474133] ohci-pci 0000:00:13.1: Giveback: ffff880128a93780 count 1
[ 418.474209] usb usb2: bus suspend, wakeup 0
[ 418.474231] ehci-pci 0000:00:13.2: suspend root hub
[ 418.474267] usb usb1: usb auto-resume
[ 418.474318] ehci-pci 0000:00:12.2: resume root hub
[ 418.474425] sd 2:0:0:0: [sdb] Synchronizing SCSI cache
[ 418.474599] sd 2:0:0:0: [sdb] Stopping disk
[ 418.474686] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[ 418.474838] sd 0:0:0:0: [sda] Stopping disk
[ 421.249343] r8169 0000:02:00.0 eth0: link up
[ 423.446452] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 423.474442] ata3.00: configured for UDMA/133
[ 423.476514] sd 2:0:0:0: [sdb] Starting disk
[ 424.651109] ohci-pci 0000:00:12.1: Dequeue: ffff8801263a1840 count 1
[ 425.071360] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 425.088703] ata1.00: configured for UDMA/133
[ 425.093198] sd 0:0:0:0: [sda] Starting disk
[ 434.676602] ohci-pci 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 5349
[ 444.698115] ohci-pci 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 5349
[ 454.719635] ohci-pci 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 5349
[ 464.741157] ohci-pci 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 5349
[ 474.762677] ohci-pci 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 5349
[ 484.784199] ohci-pci 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 5349
[ 494.805720] ohci-pci 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 5349
