USB stalling - is there a workaround/fix for this?

[Raziel]

Hello devs,

i was able to catch the usb.log for the first time after that cursed USB dropoffs i repeatedly get with keyboard/mouse.

Hidden Text - Click to Show :

I: [19:48:28] USB stack | | ==========
I: [19:48:28] USB stack | | USB System Software Task started
I: [19:48:28] USB stack | | Starting HCDs...
I: [19:48:29] USB stack | New Fkt | Fkt 0x6FF86DC0 at level -1
I: [19:48:29] USB stack | New Fkt | Fkt 0x6FF86E60 at level 0
I: [19:48:29] USB stack | New Fkt | Fkt 0x6FF86F00 at level -1
I: [19:48:29] USB stack | New Fkt | Fkt 0x6FDCF010 at level 0
I: [19:48:29] USB stack | | ...HCDs started.
I: [19:48:29] USB Fkt Init | Init Fkt | Fkt 0x6FF86E60 is {Vendor: 0x0000, Product: 0x0000, Class: 09.00}
I: [19:48:29] USB Fkt Init | Init Fkt | Fkt 0x6FDCF010 is {Vendor: 0x0000, Product: 0x0000, Class: 09.00}
I: [19:48:29] USB Fkt Init | Init Fkt | Fkt 0x6FF86E60 initialized
I: [19:48:29] USB Fkt Init | Init Fkt | Fkt 0x6FDCF010 initialized
I: [19:48:29] hub.usbfd | HUB | Hub "Rear Roothub (uhci.usbhcd/0)" has TT thinktime of 8 full speed bittimes and protocol 0
I: [19:48:29] hub.usbfd | HUB | Hub "Front Roothub (uhci.usbhcd/1)" has TT thinktime of 8 full speed bittimes and protocol 0
I: [19:48:29] hub.usbfd | HUB | Device found on port 1 of hub "Rear Roothub (uhci.usbhcd/0)".
I: [19:48:29] hub.usbfd | HUB | Device found on port 1 of hub "Front Roothub (uhci.usbhcd/1)".
I: [19:48:29] hub.usbfd | Hub port | Device at port 1 of hub "Rear Roothub (uhci.usbhcd/0)" is low-speed.
I: [19:48:29] USB stack | New Fkt | Fkt 0x6FDCF1F0 at level 1
I: [19:48:29] hub.usbfd | HUB | Device on hub port 1 successfully added.
I: [19:48:29] hub.usbfd | HUB | Device found on port 2 of hub "Rear Roothub (uhci.usbhcd/0)".
I: [19:48:29] USB Fkt Init | Init Fkt | Fkt 0x6FDCF1F0 is {Vendor: 0x046D, Product: 0xC517, Class: 00.00}
I: [19:48:29] USB Fkt Init | Init Fkt | Fkt 0x6FDCF1F0 initialized
I: [19:48:29] hub.usbfd | Hub port | Device at port 1 of hub "Front Roothub (uhci.usbhcd/1)" is full-speed.
I: [19:48:29] USB stack | New Fkt | Fkt 0x6FDCF290 at level 1
I: [19:48:29] hub.usbfd | HUB | Device on hub port 1 successfully added.
I: [19:48:29] hub.usbfd | HUB | Port 2 of hub "Front Roothub (uhci.usbhcd/1)" disabled - port is empty.
I: [19:48:29] hub.usbfd | HUB | Driver Running
I: [19:48:29] USB Fkt Init | Init Fkt | Fkt 0x6FDCF290 is {Vendor: 0x0424, Product: 0x2504, Class: 09.00}
I: [19:48:29] USB Fkt Init | Init Fkt | Fkt 0x6FDCF290 initialized
I: [19:48:29] hub.usbfd | HUB | Hub "" has TT thinktime of 8 full speed bittimes and protocol 0
I: [19:48:29] hub.usbfd | Hub port | Device at port 2 of hub "Rear Roothub (uhci.usbhcd/0)" is full-speed.
I: [19:48:29] USB stack | New Fkt | Fkt 0x6FDCF3D0 at level 1
I: [19:48:29] hub.usbfd | HUB | Device on hub port 2 successfully added.
I: [19:48:29] hub.usbfd | HUB | Driver Running
I: [19:48:29] USB Fkt Init | Init Fkt | Fkt 0x6FDCF3D0 is {Vendor: 0x0582, Product: 0x0052, Class: 00.00}
I: [19:48:30] USB Fkt Init | Init Fkt | Fkt 0x6FDCF3D0 initialized
W: [19:48:30] USB FD fkt start | BindInterfaceDriver | No interface driver of fkt 0x6FDCF3D0/ifc 0x6FE4AC90 {Class 01.03} has been found
W: [19:48:30] USB FD fkt start | FD launcher | Could not bind any suitable interface driver for fkt 0x6FDCF3D0
I: [19:48:30] hub.usbfd | HUB | All ports are empty
I: [19:48:30] hub.usbfd | Sys_EndInitialAttachmentPhase | Initial USB Attachment Phase terminated
I: [19:48:34] USB stack Process | | USB System Software Process started.
I: [19:48:34] USB stack Process | | Reading FD descriptors...
I: [19:48:34] USB stack Process | | ...FD infos read
I: [19:48:34] USB stack Process | Rebind FD | Weak FD "bootkeyboard.usbfd" for Ifc 0x6FE4A7D0 is being preempted.
I: [19:48:34] USB stack Process | Rebind FD | Weak FD "bootmouse.usbfd" for Ifc 0x6FE4A850 is being preempted.
I: [19:48:34] USB stack Process | | Starting HCDs...
I: [19:48:34] USB stack Process | | ...HCDs started.
I: [19:48:34] USB stack Process | | USB stack fullbooted
I: [19:48:34] USB FD fkt start | Sys_EndInitialAttachmentPhase | Initial USB Attachment Phase terminated
I: [19:48:34] hid.usbfd | HID driver | USB Interface bound
I: [19:48:34] hid.usbfd | HID driver | USB Interface bound
E: [19:56:13] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A7D0) - error -35
E: [19:56:13] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x6FE4A850) - error -35
E: [19:56:13] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A7D0) - error -35
E: [19:56:13] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x6FE4A850) - error -35
E: [19:56:13] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A7D0) - error -35
E: [19:56:13] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x6FE4A850) - error -35
E: [19:56:13] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A7D0) - error -35
E: [19:56:13] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x6FE4A850) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A7D0) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x6FE4A850) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A7D0) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x6FE4A850) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A7D0) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x6FE4A850) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A7D0) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x6FE4A850) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A7D0) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x6FE4A850) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A7D0) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x6FE4A850) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A7D0) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x6FE4A850) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A7D0) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x6FE4A850) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A7D0) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x6FE4A850) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A7D0) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x6FE4A850) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A7D0) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x6FE4A850) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A7D0) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x6FE4A850) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A7D0) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x6FE4A850) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A7D0) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x6FE4A850) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A7D0) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x6FE4A850) - error -35
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A7D0) - error -35
E: [19:56:14] hid.usbfd | HID Server | More than 20 successive errors - abandoning device.
E: [19:56:14] hid.usbfd | HID Server | More than 20 successive errors - abandoning device.
E: [19:56:14] hid.usbfd | HID Server | More than 20 successive errors - abandoning device.
E: [19:56:14] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x6FE4A850) - error -35
E: [19:56:14] hid.usbfd | HID Server | More than 20 successive errors - abandoning device.
E: [19:56:14] hid.usbfd | HID Server | More than 20 successive errors - abandoning device.
E: [19:56:14] hid.usbfd | HID Server | More than 20 successive errors - abandoning device.
I: [19:56:14] hub.usbfd | HUB | Detaching Function removed from hub port 1
I: [19:56:14] hub.usbfd | HUB | Port 1 of hub "Rear Roothub (uhci.usbhcd/0)" disabled - port is empty.
I: [19:56:15] hub.usbfd | HUB | Device found on port 1 of hub "Rear Roothub (uhci.usbhcd/0)".
I: [19:56:15] hub.usbfd | Hub port | Device at port 1 of hub "Rear Roothub (uhci.usbhcd/0)" is low-speed.
I: [19:56:15] USB stack | New Fkt | Fkt 0x6fdcf1f0 at level 1
I: [19:56:15] hub.usbfd | HUB | Device on hub port 1 successfully added.
I: [19:56:15] USB Fkt Init | Init Fkt | Fkt 0x6fdcf1f0 is {Vendor: 0x046D, Product: 0xC517, Class: 00.00}
I: [19:56:15] USB Fkt Init | Init Fkt | Fkt 0x6fdcf1f0 initialized
I: [19:56:15] hid.usbfd | HID driver | USB Interface bound
I: [19:56:15] hid.usbfd | HID driver | USB Interface bound
E: [19:56:41] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A810) - error -35
E: [19:56:41] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x686F5B40) - error -35
E: [19:56:41] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A810) - error -35
E: [19:56:41] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x686F5B40) - error -35
E: [19:56:41] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A810) - error -35
E: [19:56:41] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x686F5B40) - error -35
E: [19:56:41] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A810) - error -35
E: [19:56:41] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x686F5B40) - error -35
E: [19:56:41] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A810) - error -35
E: [19:56:41] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x686F5B40) - error -35
E: [19:56:41] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A810) - error -35
E: [19:56:41] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x686F5B40) - error -35
E: [19:56:41] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A810) - error -35
E: [19:56:41] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x686F5B40) - error -35
E: [19:56:41] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A810) - error -35
E: [19:56:41] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x686F5B40) - error -35
I: [19:56:41] hub.usbfd | HUB | Detaching Function removed from hub port 1
E: [19:56:41] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x686F5B40) - error -11
E: [19:56:41] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x686F5B40) - error -11
E: [19:56:41] hid.usbfd | HID Server | Failed to destall EndPoint 130 (Ifc 0x686F5B40) - error -11
I: [19:56:41] hub.usbfd | HUB | Port 1 of hub "Rear Roothub (uhci.usbhcd/0)" disabled - port is empty.
E: [19:56:41] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A810) - error -11
E: [19:56:41] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A810) - error -11
E: [19:56:41] hid.usbfd | HID Server | Failed to destall EndPoint 129 (Ifc 0x6FE4A810) - error -11
I: [19:56:43] hub.usbfd | HUB | Device found on port 1 of hub "Rear Roothub (uhci.usbhcd/0)".
I: [19:56:43] hub.usbfd | Hub port | Device at port 1 of hub "Rear Roothub (uhci.usbhcd/0)" is low-speed.
I: [19:56:43] USB stack | New Fkt | Fkt 0x6fdcf1f0 at level 1
I: [19:56:43] hub.usbfd | HUB | Device on hub port 1 successfully added.
I: [19:56:43] USB Fkt Init | Init Fkt | Fkt 0x6fdcf1f0 is {Vendor: 0x046D, Product: 0xC517, Class: 00.00}
I: [19:56:43] USB Fkt Init | Init Fkt | Fkt 0x6fdcf1f0 initialized
I: [19:56:43] hid.usbfd | HID driver | USB Interface bound
I: [19:56:43] hid.usbfd | HID driver | USB Interface bound

What do the errors mean (-35, -11) and why do they change after the first re-plug?

Can there be done something about it, maybe a workaround to catch those errors before it stalls?

Any advice on this greatly appreciated

[chris]

This is really bugging me now. It started with Update 3 and appears to be gradually getting worse. The problem relates to USB hubs as far as I can tell - I've moved my mouse so it is directly connected and haven't seen a problem with it since. The keyboard has a built-in hub, however, so whereever I connect it it drops off after a while. A disconnect/reconnect fixes it for an indeterminate amount of time.

I put my usb.log in another thread but I'll repeat it here for completeness. This is on a SAM440EP, with the mouse in the previous configuration of being plugged into the back of the keyboard. If anybody has a workaround to stop this please tell me, as it is driving me mad.

Hidden Text - Click to Show :

I: [10:48:03] USB stack | | ==========
I: [10:48:03] USB stack | | USB System Software Task started
I: [10:48:03] USB stack | | Starting HCDs...
I: [10:48:03] USB stack | New Fkt | Fkt 0x5FF8FDC0 at level -1
I: [10:48:03] USB stack | New Fkt | Fkt 0x5FF8FE60 at level 0
I: [10:48:04] USB stack | New Fkt | Fkt 0x5FF8FF00 at level -1
I: [10:48:04] USB stack | New Fkt | Fkt 0x5FDF7010 at level 0
I: [10:48:04] USB stack | New Fkt | Fkt 0x5FDF70B0 at level -1
I: [10:48:04] USB stack | New Fkt | Fkt 0x5FDF7150 at level 0
I: [10:48:04] USB stack | | ...HCDs started.
I: [10:48:04] USB Fkt Init | Init Fkt | Fkt 0x5FF8FE60 is {Vendor: 0x0000, Product: 0x0000, Class: 09.00}
I: [10:48:04] USB Fkt Init | Init Fkt | Fkt 0x5FDF7010 is {Vendor: 0x0000, Product: 0x0000, Class: 09.00}
I: [10:48:04] USB Fkt Init | Init Fkt | Fkt 0x5FDF7150 is {Vendor: 0x0000, Product: 0x0000, Class: 09.00}
I: [10:48:04] USB Fkt Init | Init Fkt | Fkt 0x5FF8FE60 initialized
I: [10:48:04] USB Fkt Init | Init Fkt | Fkt 0x5FDF7010 initialized
I: [10:48:04] USB Fkt Init | Init Fkt | Fkt 0x5FDF7150 initialized
I: [10:48:04] hub.usbfd | HUB | Hub "Rear Roothub (ohci.usbhcd/0)" has TT thinktime of 8 full speed bittimes and protocol 0
I: [10:48:04] hub.usbfd | HUB | Hub "Rear Roothub (ehci.usbhcd/0)" has TT thinktime of 8 full speed bittimes and protocol 0
I: [10:48:04] hub.usbfd | HUB | Hub "Front Roothub (ohci.usbhcd/1)" has TT thinktime of 8 full speed bittimes and protocol 0
I: [10:48:04] hub.usbfd | HUB | All ports are empty
I: [10:48:04] hub.usbfd | HUB | All ports are empty
I: [10:48:04] hub.usbfd | HUB | Device found on port 1 of hub "Rear Roothub (ehci.usbhcd/0)".
I: [10:48:04] EHCI Controller Task Unit 0 | EHCI | Device at port 0 is a fullspeed device. Giving up ownership.
I: [10:48:05] hub.usbfd | HUB | Port 1 of hub "Rear Roothub (ehci.usbhcd/0)" reset timed out
W: [10:48:05] hub.usbfd | HUB | Resetting hub port 1 and trying again
I: [10:48:05] hub.usbfd | HUB | Reset request for port 1 failed (error -23)
E: [10:48:05] hub.usbfd | HUB | Resetting port 1 of hub "Rear Roothub (ehci.usbhcd/0)" failed. Device adding aborted
I: [10:48:05] hub.usbfd | HUB | Device found on port 2 of hub "Rear Roothub (ehci.usbhcd/0)".
I: [10:48:05] hub.usbfd | Hub port | Device at port 2 of hub "Rear Roothub (ehci.usbhcd/0)" is high-speed.
I: [10:48:05] USB stack | New Fkt | Fkt 0x5FDF73D0 at level 1
I: [10:48:05] hub.usbfd | HUB | Device on hub port 2 successfully added.
I: [10:48:05] EHCI Controller Task Unit 0 | EHCI | Port 1 is not in reset state.
I: [10:48:05] hub.usbfd | HUB | Driver Running
I: [10:48:05] USB Fkt Init | Init Fkt | Fkt 0x5FDF73D0 is {Vendor: 0x05E3, Product: 0x0605, Class: 09.00}
I: [10:48:05] USB Fkt Init | Init Fkt | Fkt 0x5FDF73D0 initialized
I: [10:48:05] hub.usbfd | HUB | Hub "USB2.0 Hub" has TT thinktime of 32 full speed bittimes and protocol 1
I: [10:48:05] hub.usbfd | HUB | Device found on port 1 of hub "Rear Roothub (ohci.usbhcd/0)".
I: [10:48:05] hub.usbfd | Hub port | Device at port 1 of hub "Rear Roothub (ohci.usbhcd/0)" is full-speed.
I: [10:48:05] USB stack | New Fkt | Fkt 0x5FDF7510 at level 1
I: [10:48:05] hub.usbfd | HUB | Device on hub port 1 successfully added.
I: [10:48:05] USB Fkt Init | Init Fkt | Fkt 0x5FDF7510 is {Vendor: 0x05AC, Product: 0x1003, Class: 09.00}
I: [10:48:05] USB Fkt Init | Init Fkt | Fkt 0x5FDF7510 initialized
I: [10:48:05] hub.usbfd | HUB | Hub "Hub in Apple Extended USB Keybo" has TT thinktime of 8 full speed bittimes and protocol 0
I: [10:48:05] hub.usbfd | HUB | Device found on port 1 of hub "Hub in Apple Extended USB Keybo".
I: [10:48:05] hub.usbfd | HUB | All ports are empty
I: [10:48:05] hub.usbfd | Hub port | Device at port 1 of hub "Hub in Apple Extended USB Keybo" is low-speed.
I: [10:48:05] USB stack | New Fkt | Fkt 0x5FDF7650 at level 2
I: [10:48:05] hub.usbfd | HUB | Device on hub port 1 successfully added.
I: [10:48:05] USB Fkt Init | Init Fkt | Fkt 0x5FDF7650 is {Vendor: 0x046D, Product: 0xC014, Class: 00.00}
I: [10:48:05] hub.usbfd | HUB | Device found on port 3 of hub "Hub in Apple Extended USB Keybo".
I: [10:48:05] USB Fkt Init | Init Fkt | Fkt 0x5FDF7650 initialized
I: [10:48:05] hub.usbfd | Hub port | Device at port 3 of hub "Hub in Apple Extended USB Keybo" is full-speed.
I: [10:48:05] USB stack | New Fkt | Fkt 0x5FDF76F0 at level 2
I: [10:48:05] hub.usbfd | HUB | Device on hub port 3 successfully added.
I: [10:48:05] hub.usbfd | HUB | Driver Running
I: [10:48:05] USB Fkt Init | Init Fkt | Fkt 0x5FDF76F0 is {Vendor: 0x05AC, Product: 0x020C, Class: 00.00}
I: [10:48:05] USB Fkt Init | Init Fkt | Fkt 0x5FDF76F0 initialized
W: [10:48:05] USB FD fkt start | BindInterfaceDriver | No interface driver of fkt 0x5FDF76F0/ifc 0x5FD76140 {Class 03.00} has been found
I: [10:48:05] USB FD ifc start | Sys_EndInitialAttachmentPhase | Initial USB Attachment Phase terminated
I: [10:48:15] USB stack Process | | USB System Software Process started.
I: [10:48:15] USB stack Process | | Reading FD descriptors...
I: [10:48:15] USB stack Process | | ...FD infos read
I: [10:48:15] USB stack Process | Rebind FD | Weak FD "bootmouse.usbfd" for Ifc 0x5FE33E50 is being preempted.
I: [10:48:15] USB stack Process | Rebind FD | Weak FD "bootkeyboard.usbfd" for Ifc 0x5FD76040 is being preempted.
I: [10:48:15] USB stack Process | | Starting HCDs...
I: [10:48:15] USB stack Process | | ...HCDs started.
I: [10:48:15] USB stack Process | | USB stack fullbooted
I: [10:48:15] USB FD fkt start | Sys_EndInitialAttachmentPhase | Initial USB Attachment Phase terminated
I: [10:48:15] hid.usbfd | HID driver | USB Interface bound
I: [10:48:15] hid.usbfd | HID driver | USB Interface bound
I: [10:48:15] hid.usbfd | HID driver | USB Interface bound
I: [10:48:15] hid.usbfd | HID Server | Interrupt req. returned -33
I: [11:28:32] hub.usbfd | HUB | Status Change Pipe error -33
I: [11:28:32] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:28:32] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:28:32] hub.usbfd | HUB | Status Change Pipe error -35
I: [11:28:32] hub.usbfd | HUB | Detaching Function removed from hub port 2
I: [11:28:32] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:28:32] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:28:32] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:28:32] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:28:32] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:28:32] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:28:33] hub.usbfd | HUB | Detaching Function removed from hub port 1
I: [11:28:33] hub.usbfd | HUB | Device found on port 1 of hub "Rear Roothub (ehci.usbhcd/0)".
I: [11:28:33] EHCI Controller Task Unit 0 | EHCI | Device at port 0 is a fullspeed device. Giving up ownership.
I: [11:28:33] hub.usbfd | HUB | Port 1 of hub "Rear Roothub (ehci.usbhcd/0)" reset timed out
W: [11:28:33] hub.usbfd | HUB | Resetting hub port 1 and trying again
I: [11:28:33] hub.usbfd | HUB | Reset request for port 1 failed (error -23)
E: [11:28:33] hub.usbfd | HUB | Resetting port 1 of hub "Rear Roothub (ehci.usbhcd/0)" failed. Device adding aborted
I: [11:28:33] hub.usbfd | HUB | Device found on port 2 of hub "Rear Roothub (ehci.usbhcd/0)".
I: [11:28:34] hub.usbfd | HUB | Device found on port 1 of hub "Rear Roothub (ohci.usbhcd/0)".
I: [11:28:34] hub.usbfd | Hub port | Device at port 2 of hub "Rear Roothub (ehci.usbhcd/0)" is high-speed.
I: [11:28:34] USB stack | New Fkt | Fkt 0x5fdf7510 at level 1
I: [11:28:34] hub.usbfd | HUB | Device on hub port 2 successfully added.
I: [11:28:34] EHCI Controller Task Unit 0 | EHCI | Port 1 is not in reset state.
I: [11:28:34] hub.usbfd | Hub port | Device at port 1 of hub "Rear Roothub (ohci.usbhcd/0)" is full-speed.
I: [11:28:34] USB stack | New Fkt | Fkt 0x5fdf75b0 at level 1
I: [11:28:34] hub.usbfd | HUB | Device on hub port 1 successfully added.
I: [11:28:34] USB Fkt Init | Init Fkt | Fkt 0x5fdf7510 is {Vendor: 0x05E3, Product: 0x0605, Class: 09.00}
I: [11:28:34] USB Fkt Init | Init Fkt | Fkt 0x5fdf75b0 is {Vendor: 0x05AC, Product: 0x1003, Class: 09.00}
I: [11:28:34] USB Fkt Init | Init Fkt | Fkt 0x5fdf7510 initialized
I: [11:28:34] hub.usbfd | HUB | Hub "USB2.0 Hub" has TT thinktime of 32 full speed bittimes and protocol 1
I: [11:28:34] USB Fkt Init | Init Fkt | Fkt 0x5fdf75b0 initialized
I: [11:28:34] hub.usbfd | HUB | Hub "Hub in Apple Extended USB Keybo" has TT thinktime of 8 full speed bittimes and protocol 0
I: [11:28:34] hub.usbfd | HUB | Device found on port 1 of hub "Hub in Apple Extended USB Keybo".
I: [11:28:34] hub.usbfd | Hub port | Device at port 1 of hub "Hub in Apple Extended USB Keybo" is low-speed.
I: [11:28:34] USB stack | New Fkt | Fkt 0x5fdf73d0 at level 2
I: [11:28:34] hub.usbfd | HUB | Device on hub port 1 successfully added.
I: [11:28:34] hub.usbfd | HUB | Device found on port 3 of hub "Hub in Apple Extended USB Keybo".
I: [11:28:34] USB Fkt Init | Init Fkt | Fkt 0x5fdf73d0 is {Vendor: 0x046D, Product: 0xC014, Class: 00.00}
I: [11:28:34] USB Fkt Init | Init Fkt | Fkt 0x5fdf73d0 initialized
I: [11:28:34] hub.usbfd | HUB | All ports are empty
I: [11:28:34] hid.usbfd | HID driver | USB Interface bound
I: [11:28:34] hub.usbfd | Hub port | Device at port 3 of hub "Hub in Apple Extended USB Keybo" is full-speed.
I: [11:28:34] USB stack | New Fkt | Fkt 0x5c2e0330 at level 2
I: [11:28:34] hub.usbfd | HUB | Device on hub port 3 successfully added.
I: [11:28:34] hub.usbfd | HUB | Driver Running
I: [11:28:34] USB Fkt Init | Init Fkt | Fkt 0x5c2e0330 is {Vendor: 0x05AC, Product: 0x020C, Class: 00.00}
I: [11:28:34] USB Fkt Init | Init Fkt | Fkt 0x5c2e0330 initialized
I: [11:28:34] hid.usbfd | HID driver | USB Interface bound
I: [11:28:34] hid.usbfd | HID driver | USB Interface bound
I: [11:38:48] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:38:56] hub.usbfd | HUB | Status Change Pipe error -33
I: [11:38:56] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:38:56] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:38:56] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:38:56] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:38:56] hub.usbfd | HUB | Status Change Pipe error -35
I: [11:38:57] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:38:57] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:38:57] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:38:57] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:38:57] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:38:57] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:38:57] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:38:57] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:38:57] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:38:57] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:38:57] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:38:57] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:38:57] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:38:57] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:38:57] hub.usbfd | HUB | Detaching Function removed from hub port 1
I: [11:38:57] hub.usbfd | HUB | Detaching Function removed from hub port 2
I: [11:38:59] hub.usbfd | HUB | Device found on port 1 of hub "Rear Roothub (ehci.usbhcd/0)".
I: [11:38:59] EHCI Controller Task Unit 0 | EHCI | Device at port 0 is a fullspeed device. Giving up ownership.
I: [11:38:59] hub.usbfd | HUB | Port 1 of hub "Rear Roothub (ehci.usbhcd/0)" reset timed out
W: [11:38:59] hub.usbfd | HUB | Resetting hub port 1 and trying again
I: [11:38:59] hub.usbfd | HUB | Reset request for port 1 failed (error -23)
E: [11:38:59] hub.usbfd | HUB | Resetting port 1 of hub "Rear Roothub (ehci.usbhcd/0)" failed. Device adding aborted
I: [11:38:59] hub.usbfd | HUB | Device found on port 2 of hub "Rear Roothub (ehci.usbhcd/0)".
I: [11:38:59] hub.usbfd | HUB | Device found on port 1 of hub "Rear Roothub (ohci.usbhcd/0)".
I: [11:38:59] hub.usbfd | Hub port | Device at port 1 of hub "Rear Roothub (ohci.usbhcd/0)" is full-speed.
I: [11:38:59] USB stack | New Fkt | Fkt 0x5fdf7510 at level 1
I: [11:38:59] hub.usbfd | HUB | Device on hub port 1 successfully added.
I: [11:38:59] USB Fkt Init | Init Fkt | Fkt 0x5fdf7510 is {Vendor: 0x05AC, Product: 0x1003, Class: 09.00}
I: [11:38:59] USB Fkt Init | Init Fkt | Fkt 0x5fdf7510 initialized
I: [11:38:59] hub.usbfd | Hub port | Device at port 2 of hub "Rear Roothub (ehci.usbhcd/0)" is high-speed.
I: [11:38:59] USB stack | New Fkt | Fkt 0x5fdf75b0 at level 1
I: [11:38:59] hub.usbfd | HUB | Device on hub port 2 successfully added.
I: [11:38:59] hub.usbfd | HUB | Hub "Hub in Apple Extended USB Keybo" has TT thinktime of 8 full speed bittimes and protocol 0
I: [11:38:59] EHCI Controller Task Unit 0 | EHCI | Port 1 is not in reset state.
I: [11:38:59] USB Fkt Init | Init Fkt | Fkt 0x5fdf75b0 is {Vendor: 0x05E3, Product: 0x0605, Class: 09.00}
I: [11:38:59] USB Fkt Init | Init Fkt | Fkt 0x5fdf75b0 initialized
I: [11:38:59] hub.usbfd | HUB | Hub "USB2.0 Hub" has TT thinktime of 32 full speed bittimes and protocol 1
I: [11:39:00] hub.usbfd | HUB | Device found on port 1 of hub "Hub in Apple Extended USB Keybo".
I: [11:39:00] hub.usbfd | Hub port | Device at port 1 of hub "Hub in Apple Extended USB Keybo" is low-speed.
I: [11:39:00] USB stack | New Fkt | Fkt 0x5fdf73d0 at level 2
I: [11:39:00] hub.usbfd | HUB | Device on hub port 1 successfully added.
I: [11:39:00] hub.usbfd | HUB | Device found on port 3 of hub "Hub in Apple Extended USB Keybo".
I: [11:39:00] USB Fkt Init | Init Fkt | Fkt 0x5fdf73d0 is {Vendor: 0x046D, Product: 0xC014, Class: 00.00}
I: [11:39:00] USB Fkt Init | Init Fkt | Fkt 0x5fdf73d0 initialized
I: [11:39:00] hid.usbfd | HID driver | USB Interface bound
I: [11:39:00] hub.usbfd | Hub port | Device at port 3 of hub "Hub in Apple Extended USB Keybo" is full-speed.
I: [11:39:00] USB stack | New Fkt | Fkt 0x5c2e0330 at level 2
I: [11:39:00] hub.usbfd | HUB | Device on hub port 3 successfully added.
I: [11:39:00] hub.usbfd | HUB | Driver Running
I: [11:39:00] hub.usbfd | HUB | All ports are empty
I: [11:39:00] USB Fkt Init | Init Fkt | Fkt 0x5c2e0330 is {Vendor: 0x05AC, Product: 0x020C, Class: 00.00}
I: [11:39:00] USB Fkt Init | Init Fkt | Fkt 0x5c2e0330 initialized
I: [11:39:00] hid.usbfd | HID driver | USB Interface bound
I: [11:39:00] hid.usbfd | HID driver | USB Interface bound
I: [11:39:01] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:39:07] hub.usbfd | HUB | Detaching Function removed from hub port 1
I: [11:39:11] hub.usbfd | HUB | Device found on port 1 of hub "Hub in Apple Extended USB Keybo".
I: [11:39:11] hub.usbfd | Hub port | Device at port 1 of hub "Hub in Apple Extended USB Keybo" is low-speed.
I: [11:39:11] USB stack | New Fkt | Fkt 0x5fdf73d0 at level 2
I: [11:39:11] hub.usbfd | HUB | Device on hub port 1 successfully added.
I: [11:39:11] USB Fkt Init | Init Fkt | Fkt 0x5fdf73d0 is {Vendor: 0x046D, Product: 0xC014, Class: 00.00}
I: [11:39:11] USB Fkt Init | Init Fkt | Fkt 0x5fdf73d0 initialized
I: [11:39:11] hid.usbfd | HID driver | USB Interface bound
I: [11:39:14] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:39:17] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:39:17] hub.usbfd | HUB | Status Change Pipe error -35
I: [11:39:17] hub.usbfd | HUB | Status Change Pipe error -33
I: [11:39:17] hub.usbfd | HUB | Detaching Function removed from hub port 1
I: [11:39:17] hub.usbfd | HUB | Detaching Function removed from hub port 2
I: [11:39:19] hub.usbfd | HUB | Device found on port 1 of hub "Rear Roothub (ehci.usbhcd/0)".
I: [11:39:20] EHCI Controller Task Unit 0 | EHCI | Device at port 0 is a fullspeed device. Giving up ownership.
I: [11:39:20] hub.usbfd | HUB | Port 1 of hub "Rear Roothub (ehci.usbhcd/0)" reset timed out
W: [11:39:20] hub.usbfd | HUB | Resetting hub port 1 and trying again
I: [11:39:20] hub.usbfd | HUB | Reset request for port 1 failed (error -23)
E: [11:39:20] hub.usbfd | HUB | Resetting port 1 of hub "Rear Roothub (ehci.usbhcd/0)" failed. Device adding aborted
I: [11:39:20] hub.usbfd | HUB | Device found on port 2 of hub "Rear Roothub (ehci.usbhcd/0)".
I: [11:39:20] hub.usbfd | HUB | Device found on port 1 of hub "Rear Roothub (ohci.usbhcd/0)".
I: [11:39:20] hub.usbfd | Hub port | Device at port 1 of hub "Rear Roothub (ohci.usbhcd/0)" is full-speed.
I: [11:39:20] USB stack | New Fkt | Fkt 0x5fdf75b0 at level 1
I: [11:39:20] hub.usbfd | HUB | Device on hub port 1 successfully added.
I: [11:39:20] USB Fkt Init | Init Fkt | Fkt 0x5fdf75b0 is {Vendor: 0x05AC, Product: 0x1003, Class: 09.00}
I: [11:39:20] USB Fkt Init | Init Fkt | Fkt 0x5fdf75b0 initialized
I: [11:39:20] hub.usbfd | HUB | Hub "Hub in Apple Extended USB Keybo" has TT thinktime of 8 full speed bittimes and protocol 0
I: [11:39:20] hub.usbfd | Hub port | Device at port 2 of hub "Rear Roothub (ehci.usbhcd/0)" is high-speed.
I: [11:39:20] USB stack | New Fkt | Fkt 0x5fdf7510 at level 1
I: [11:39:20] hub.usbfd | HUB | Device on hub port 2 successfully added.
I: [11:39:20] EHCI Controller Task Unit 0 | EHCI | Port 1 is not in reset state.
I: [11:39:20] USB Fkt Init | Init Fkt | Fkt 0x5fdf7510 is {Vendor: 0x05E3, Product: 0x0605, Class: 09.00}
I: [11:39:20] USB Fkt Init | Init Fkt | Fkt 0x5fdf7510 initialized
I: [11:39:20] hub.usbfd | HUB | Hub "USB2.0 Hub" has TT thinktime of 32 full speed bittimes and protocol 1
I: [11:39:20] hub.usbfd | HUB | Device found on port 1 of hub "Hub in Apple Extended USB Keybo".
I: [11:39:20] hub.usbfd | Hub port | Device at port 1 of hub "Hub in Apple Extended USB Keybo" is low-speed.
I: [11:39:20] USB stack | New Fkt | Fkt 0x5fdf73d0 at level 2
I: [11:39:20] hub.usbfd | HUB | Device on hub port 1 successfully added.
I: [11:39:20] hub.usbfd | HUB | Device found on port 3 of hub "Hub in Apple Extended USB Keybo".
I: [11:39:20] USB Fkt Init | Init Fkt | Fkt 0x5fdf73d0 is {Vendor: 0x046D, Product: 0xC014, Class: 00.00}
I: [11:39:20] USB Fkt Init | Init Fkt | Fkt 0x5fdf73d0 initialized
I: [11:39:20] hid.usbfd | HID driver | USB Interface bound
I: [11:39:20] hub.usbfd | Hub port | Device at port 3 of hub "Hub in Apple Extended USB Keybo" is full-speed.
I: [11:39:20] USB stack | New Fkt | Fkt 0x5c2e0330 at level 2
I: [11:39:20] hub.usbfd | HUB | Device on hub port 3 successfully added.
I: [11:39:20] hub.usbfd | HUB | Driver Running
I: [11:39:20] USB Fkt Init | Init Fkt | Fkt 0x5c2e0330 is {Vendor: 0x05AC, Product: 0x020C, Class: 00.00}
I: [11:39:20] USB Fkt Init | Init Fkt | Fkt 0x5c2e0330 initialized
I: [11:39:20] hub.usbfd | HUB | All ports are empty
I: [11:39:20] hid.usbfd | HID driver | USB Interface bound
I: [11:39:20] hid.usbfd | HID driver | USB Interface bound
I: [11:39:24] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:39:24] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:39:24] hub.usbfd | HUB | Status Change Pipe error -33
I: [11:39:24] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:39:24] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:39:24] hub.usbfd | HUB | Status Change Pipe error -35
I: [11:39:24] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:39:24] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:39:24] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:39:24] hid.usbfd | HID Server | Interrupt req. returned -35
I: [11:39:24] hub.usbfd | HUB | Detaching Function removed from hub port 2
I: [11:39:24] hub.usbfd | HUB | Detaching Function removed from hub port 1
I: [11:39:26] hub.usbfd | HUB | Device found on port 1 of hub "Rear Roothub (ehci.usbhcd/0)".
I: [11:39:27] EHCI Controller Task Unit 0 | EHCI | Device at port 0 is a fullspeed device. Giving up ownership.
I: [11:39:27] hub.usbfd | HUB | Port 1 of hub "Rear Roothub (ehci.usbhcd/0)" reset timed out
W: [11:39:27] hub.usbfd | HUB | Resetting hub port 1 and trying again
I: [11:39:27] hub.usbfd | HUB | Reset request for port 1 failed (error -23)
E: [11:39:27] hub.usbfd | HUB | Resetting port 1 of hub "Rear Roothub (ehci.usbhcd/0)" failed. Device adding aborted
I: [11:39:27] hub.usbfd | HUB | Device found on port 2 of hub "Rear Roothub (ehci.usbhcd/0)".
I: [11:39:27] hub.usbfd | HUB | Device found on port 1 of hub "Rear Roothub (ohci.usbhcd/0)".
I: [11:39:27] hub.usbfd | Hub port | Device at port 1 of hub "Rear Roothub (ohci.usbhcd/0)" is full-speed.
I: [11:39:27] USB stack | New Fkt | Fkt 0x5fdf75b0 at level 1
I: [11:39:27] hub.usbfd | HUB | Device on hub port 1 successfully added.
I: [11:39:27] USB Fkt Init | Init Fkt | Fkt 0x5fdf75b0 is {Vendor: 0x05AC, Product: 0x1003, Class: 09.00}
I: [11:39:27] USB Fkt Init | Init Fkt | Fkt 0x5fdf75b0 initialized
I: [11:39:27] hub.usbfd | HUB | Hub "Hub in Apple Extended USB Keybo" has TT thinktime of 8 full speed bittimes and protocol 0
I: [11:39:27] hub.usbfd | Hub port | Device at port 2 of hub "Rear Roothub (ehci.usbhcd/0)" is high-speed.
I: [11:39:27] USB stack | New Fkt | Fkt 0x5fdf73d0 at level 1
I: [11:39:27] hub.usbfd | HUB | Device on hub port 2 successfully added.
I: [11:39:27] EHCI Controller Task Unit 0 | EHCI | Port 1 is not in reset state.
I: [11:39:27] USB Fkt Init | Init Fkt | Fkt 0x5fdf73d0 is {Vendor: 0x05E3, Product: 0x0605, Class: 09.00}
I: [11:39:27] USB Fkt Init | Init Fkt | Fkt 0x5fdf73d0 initialized
I: [11:39:27] hub.usbfd | HUB | Hub "USB2.0 Hub" has TT thinktime of 32 full speed bittimes and protocol 1
I: [11:39:27] hub.usbfd | HUB | Device found on port 1 of hub "Hub in Apple Extended USB Keybo".
I: [11:39:27] hub.usbfd | Hub port | Device at port 1 of hub "Hub in Apple Extended USB Keybo" is low-speed.
I: [11:39:27] USB stack | New Fkt | Fkt 0x5fdf7510 at level 2
I: [11:39:27] hub.usbfd | HUB | Device on hub port 1 successfully added.
I: [11:39:27] hub.usbfd | HUB | Device found on port 3 of hub "Hub in Apple Extended USB Keybo".
I: [11:39:27] USB Fkt Init | Init Fkt | Fkt 0x5fdf7510 is {Vendor: 0x046D, Product: 0xC014, Class: 00.00}
I: [11:39:27] USB Fkt Init | Init Fkt | Fkt 0x5fdf7510 initialized
I: [11:39:27] hub.usbfd | HUB | Reset request for port 3 failed (error -35)
W: [11:39:27] hub.usbfd | HUB | Resetting hub port 3 and trying again
I: [11:39:27] hub.usbfd | HUB | Reset request for port 3 failed (error -35)
W: [11:39:27] hub.usbfd | HUB | Resetting hub port 3 and trying again
I: [11:39:27] hub.usbfd | HUB | Reset request for port 3 failed (error -32)
W: [11:39:27] hub.usbfd | HUB | Resetting hub port 3 and trying again
I: [11:39:27] hub.usbfd | HUB | Reset request for port 3 failed (error -32)
W: [11:39:27] hub.usbfd | HUB | Hub port 3 attachment was not possible
E: [11:39:27] hub.usbfd | HUB | Resetting port 3 of hub "Hub in Apple Extended USB Keybo" failed. Device adding aborted
E: [11:39:27] hid.usbfd | DoControlXfer | Destall failed: io_Error = -32
E: [11:39:27] hid.usbfd | HID driver | Setup error: Error reading HID Report Descriptor (-32)
I: [11:39:27] hid.usbfd | HID driver | Failed to load ReportDescriptor
E: [11:39:27] hid.usbfd | HID driver | Interface binding failed. Error -32
I: [11:39:27] hub.usbfd | HUB | Unable to reset hub port.
Device adding aborted.
I: [11:39:27] hub.usbfd | HUB | Driver Running
I: [11:39:27] hub.usbfd | HUB | All ports are empty
I: [11:39:32] hub.usbfd | HUB | Status Change Pipe error -33
I: [11:39:32] hub.usbfd | HUB | Status Change Pipe error -35
I: [11:39:32] hub.usbfd | HUB | Detaching Function removed from hub port 1
I: [11:39:32] hub.usbfd | HUB | Detaching Function removed from hub port 2
I: [11:39:37] hub.usbfd | HUB | Device found on port 1 of hub "Rear Roothub (ehci.usbhcd/0)".
I: [11:39:38] EHCI Controller Task Unit 0 | EHCI | Device at port 0 is a fullspeed device. Giving up ownership.
I: [11:39:38] hub.usbfd | HUB | Port 1 of hub "Rear Roothub (ehci.usbhcd/0)" reset timed out
W: [11:39:38] hub.usbfd | HUB | Resetting hub port 1 and trying again
I: [11:39:38] hub.usbfd | HUB | Reset request for port 1 failed (error -23)
E: [11:39:38] hub.usbfd | HUB | Resetting port 1 of hub "Rear Roothub (ehci.usbhcd/0)" failed. Device adding aborted
I: [11:39:38] hub.usbfd | HUB | Device found on port 2 of hub "Rear Roothub (ehci.usbhcd/0)".
I: [11:39:38] hub.usbfd | HUB | Device found on port 1 of hub "Rear Roothub (ohci.usbhcd/0)".
I: [11:39:38] hub.usbfd | Hub port | Device at port 1 of hub "Rear Roothub (ohci.usbhcd/0)" is full-speed.
I: [11:39:38] USB stack | New Fkt | Fkt 0x5fdf73d0 at level 1
I: [11:39:38] hub.usbfd | HUB | Device on hub port 1 successfully added.
I: [11:39:38] USB Fkt Init | Init Fkt | Fkt 0x5fdf73d0 is {Vendor: 0x05AC, Product: 0x1003, Class: 09.00}
I: [11:39:38] USB Fkt Init | Init Fkt | Fkt 0x5fdf73d0 initialized
I: [11:39:38] hub.usbfd | HUB | Hub "Hub in Apple Extended USB Keybo" has TT thinktime of 8 full speed bittimes and protocol 0
I: [11:39:38] hub.usbfd | Hub port | Device at port 2 of hub "Rear Roothub (ehci.usbhcd/0)" is high-speed.
I: [11:39:38] USB stack | New Fkt | Fkt 0x5fdf75b0 at level 1
I: [11:39:38] hub.usbfd | HUB | Device on hub port 2 successfully added.
I: [11:39:38] EHCI Controller Task Unit 0 | EHCI | Port 1 is not in reset state.
I: [11:39:38] USB Fkt Init | Init Fkt | Fkt 0x5fdf75b0 is {Vendor: 0x05E3, Product: 0x0605, Class: 09.00}
I: [11:39:38] USB Fkt Init | Init Fkt | Fkt 0x5fdf75b0 initialized
I: [11:39:38] hub.usbfd | HUB | Hub "USB2.0 Hub" has TT thinktime of 32 full speed bittimes and protocol 1
I: [11:39:38] hub.usbfd | HUB | Device found on port 1 of hub "Hub in Apple Extended USB Keybo".
I: [11:39:38] hub.usbfd | Hub port | Device at port 1 of hub "Hub in Apple Extended USB Keybo" is low-speed.
I: [11:39:38] USB stack | New Fkt | Fkt 0x5fdf7510 at level 2
I: [11:39:38] hub.usbfd | HUB | Device on hub port 1 successfully added.
I: [11:39:38] hub.usbfd | HUB | Device found on port 3 of hub "Hub in Apple Extended USB Keybo".
I: [11:39:38] USB Fkt Init | Init Fkt | Fkt 0x5fdf7510 is {Vendor: 0x046D, Product: 0xC014, Class: 00.00}
I: [11:39:38] USB Fkt Init | Init Fkt | Fkt 0x5fdf7510 initialized
I: [11:39:38] hid.usbfd | HID driver | USB Interface bound
I: [11:39:38] hub.usbfd | Hub port | Device at port 3 of hub "Hub in Apple Extended USB Keybo" is full-speed.
I: [11:39:38] USB stack | New Fkt | Fkt 0x5c2e0330 at level 2
I: [11:39:38] hub.usbfd | HUB | Device on hub port 3 successfully added.
I: [11:39:38] hub.usbfd | HUB | Driver Running
I: [11:39:38] USB Fkt Init | Init Fkt | Fkt 0x5c2e0330 is {Vendor: 0x05AC, Product: 0x020C, Class: 00.00}
I: [11:39:38] USB Fkt Init | Init Fkt | Fkt 0x5c2e0330 initialized
I: [11:39:38] hub.usbfd | HUB | All ports are empty
I: [11:39:38] hid.usbfd | HID driver | USB Interface bound
I: [11:39:38] hid.usbfd | HID driver | USB Interface bound
I: [11:39:40] hid.usbfd | HID Server | Interrupt req. returned -35

[eliyahu]

@thread

just to add, this is happening on my SAM440ep-flex as well. i originally chalked this up to a general system hang, but in fact it is the same 'stall' symptom seen by others here. next time i'll attach my usb.log to this post for completeness, but it shows the same 'Interrupt req. returned -35' message seen by everyone else. i should note that the only USB devices attached to the system are an amigaone-branded keyboard and amigaone-branded mouse from amigakit/A-EON. no hub devices.

when this occurs intuition itself seems to 'freeze up,' i.e., gadgets do not update and no redraw is apparent. sound playing in the background, as from tunenet, continues uninterrupted. many tens of seconds later, everything comes back, as if nothing happened. any insight into the issue from the developers would be most welcome.

-- eliyahu

[xenic]

@eliyahu
I get keyboard dropouts on my SAM Flex as well. The mouse works reliably as long as it is directly connected to the USB port without going through a hub. The keyboard dropouts have been happening since I got my SAM but have gotten more frequent since update 3 & 4. In fact, I set up an icon on AmiDock that calls a script that executes a "USBCtrl restart" so I can reactivate my keyboard with the mouse. However, I recently discovered that if I execute "USBCtrl restart" while a USB media device (like memory stick) is connected, USB freezes completely and I have to reset the computer.

[Raziel]

Just for the record, i haven't gotten any dropouts of this kind since i changed the bus speed hardware-wise from 133MHz to 100MHz.
It also cured the "not able to use more than 512MB RAM" for me.

But i found a more serious problem.
USBCtrl seems to be incompatible with my hardware (I do hope not), but for that i will open another thread in the XE forums.

[chris]

I've ditched ehci.usbhcd from kicklayout, and that seems to have resolved the problem. I'm happy with my USB running at a lower speed if it is actually going to work.

[Raziel]

I've ditched ehci.usbhcd from kicklayout, and that seems to have resolved the problem. I'm happy with my USB running at a lower speed if it is actually going to work.

Well, praised too early, i still get the dropout on very cpu-heavy conditions.
I will certainly test your solution to see if i can cure mine too

[chris]

I may have been a bit quick, as it seems to have stopped working again. It is nowhere near as bad as before, though (the mouse is fine connected to the back of the keyboard, just the keyboard doesn't always get picked up on boot)

[xenic]

I may have been a bit quick, as it seems to have stopped working again. It is nowhere near as bad as before, though (the mouse is fine connected to the back of the keyboard, just the keyboard doesn't always get picked up on boot)

I've recently had to use my HP all-in-one printer a lot for copying, scanning & printing and noticed an increase in keyboard dropouts on my SAM. Turning on the printer seems to cause "USBCtrl Restart" to freeze USB input just like having a USB media device plugged in does. My workaround of having an icon on AmiDOck to call "USBCtrl Restart" has become almost useless. I've added a "Reboot" menu item to my WorkBench menus so I can reboot AmigaDOS with my mouse instead of doing a complete (cold reboot) reset of the computer. I'm really getting irritated with this keyboard problem. I even purchased a new Microsoft "wired" keyboard to see if the problem was my keyboard but that didn't help at all.

[chris]

*sigh* this is still present in Update 5. Can somebody please, please, please as a priority, fix this incredibly irritating bug, which often makes OS4 unuseable.

There is a longer thread about it here (I didn't bump it because the title is misleading/wrong): http://forum.hyperion-entertainment.biz ... f=14&t=338

An acknowledgement that this has been logged as a bug would be a start.

...and there goes my mouse. I had to reconnect my keyboard before I started typing this. Mouse still not working after a re-connect. Re-connect again... both items now working. That's how annoying and how frequent this is.

usb.log attached.