K T wrote:
Have you used USBProber to sniff the connection for clues?
Hi,
I have tried the USB Prober (but this doesn't seem to give any useful feedback) and also the usbtracer program. I have looked at various forums online and below is a common response.
" The main architectureal difference between the 27" i7 iMac (as well as any of the new MacBook Pro's 15" and 17") is that there are no OHCI or UHCI USB controllers; there are 2 EHCI controllers, along with 2 high speed hubs inside the machine. That means that any device that you attach will be attached to the high speed hub. All devices are still supported, but now they are ultimately attached to the high speed controller and so the low level timing might be different. They are also attached through a hub and not directly to the root hub (even high speed devices).
The errors that you describe indicate that the device is either not responding to the requests or stalling the requests.
"
My question is why can I transfer data in 32 bit mode on the same machine, yet in 64 bit I cannot. The above response doesnt seem to answer that because writepipe works fine in 32 bit mode on the same machine. Is USB timing different in 32 bit mode regardless of the architectureal difference ?
Below is snippet of the usbtracer tool output:
"
32521.035 EHCI::powerChangeDone (0xffffff8007dae000) from state (4) to state (4)
32521.060 -> Controller::Control Start (0xffffff8007dae000) Bus: 0xfa, Address: 2, Endpoint: 0, (out) bmRequestType: 0x23, bRequest: 0x03, wValue: 0x0004, wIndex: 0x0003, wLength: 0x0000
32521.095 <- Controller::Control End (0xffffff8007dae000) error 0x0
32521.114 EHCI::Primary Interrupt (0xffffff8007dae000) enabledInterrupts 0x0000001f activeInterrupts 0x00000001, Frame: 206478, microframe: 1
32521.164 -> EHCI::Begin PollInterrupts (0xffffff8007dae000) _errorInterrupt 0 _completeInterrupt 1 _portChangeInterrupt 0
32521.165 EHCI::PollInterrupts (0xffffff8007dae000) Completion Interrupt
32521.168 -> Controller::Return Isoc Done Q Start (0xffffff8007dae000) pEP 0x7f9e300 pTD 0x0
32521.169 <- Controller::Return Isoc Done Q End (0xffffff8007dae000)
32521.170 -> Controller::Return Isoc Done Q Start (0xffffff8007dae000) pEP 0x7f9e400 pTD 0x0
32521.170 <- Controller::Return Isoc Done Q End (0xffffff8007dae000)
32521.175 Controller::Complete (0xffffff8007dae000) completion: 0xffffff7f809754fe, status: 0x0
32521.176 -> Controller::PacketHandler (0xffffff8007dae000) Bus: 0xfa, Address: 2, Endpoint: 0 status 0x0 bufferSizeRemaining 0
32521.177 <- Controller::PacketHandler (0xffffff8007dae000) Bus: 0xfa, Address: 2, Endpoint: 0 todo 0x40 status 0x0
32521.178 Controller::Complete (0xffffff8007dae000) completion: 0xffffff7f809754fe, status: 0x0
32521.178 -> Controller::PacketHandler (0xffffff8007dae000) Bus: 0xfa, Address: 2, Endpoint: 0 status 0x0 bufferSizeRemaining 0
32521.189 Controller::Complete (0xffffff8007dae000) completion: 0xffffff7f8096fb25, status: 0x0
32521.194 <- Controller::PacketHandler (0xffffff8007dae000) Bus: 0xfa, Address: 2, Endpoint: 0 todo 0x0 status 0x0
32521.208 EHCI::PollInterrupts (0xffffff8007dae000) Frame Rollover Interrupt
32521.209 <- EHCI::End PollInterrupts (0xffffff8007dae000)
32521.260 EHCI::powerChangeDone (0xffffff8007dae000) from state (4) to state (4)
32523.382 OutstandingIO::HubDecrement (0xffffff8007f6cc00) serial #: 549, IO: 1, needInterruptRead: no
32523.505 EHCI::Primary Interrupt (0xffffff8007dae000) enabledInterrupts 0x0000001f activeInterrupts 0x00000003, Frame: 206480, microframe: 1
32523.531 -> EHCI::Begin PollInterrupts (0xffffff8007dae000) _errorInterrupt 2 _completeInterrupt 1 _portChangeInterrupt 0
32523.532 EHCI::PollInterrupts (0xffffff8007dae000) Error Interrupt
32523.536 -> Controller::Return Isoc Done Q Start (0xffffff8007dae000) pEP 0x7f9e300 pTD 0x0
32523.537 <- Controller::Return Isoc Done Q End (0xffffff8007dae000)
32523.538 -> Controller::Return Isoc Done Q Start (0xffffff8007dae000) pEP 0x7f9e400 pTD 0x0
32523.539 <- Controller::Return Isoc Done Q End (0xffffff8007dae000)
32523.556 Controller::Complete (0xffffff8007dae000) completion: 0xffffff7f80973952, status: 0xe00002ed
32523.562 Controller::Interrupt Complete (0xffffff8007dae000) Bus: 0xfa, Address: 5, Endpoint: 1, (in) actual: 0, status: 0xe00002ed
32523.570 Controller::CompleteTS (0xffffff8007dae000) completion: 0xffffff7f80af89c2, status: 0xe00002ed
32523.573 HID::InterruptRead (0xffffff8009648400) error: 0xe00002ed, _deviceHasBeenDisconnected = 0
32523.578 HID::InterruptRead (0xffffff8009648400) Checking to see if HID device is still connected
32523.617 -> Controller::Interrupt Start (0xffffff8007dae000) Bus: 0xfa, Address: 5, Endpoint: 1, (in) reqCount: 50
32523.624 <- Controller::Interrupt End (0xffffff8007dae000) error 0x0, (completion timeout: 0, noData timeout: 0)
32523.625 HID::RearmInterruptRead (0xffffff8009648400) returning error 0x0
32523.635 EHCI::PollInterrupts (0xffffff8007dae000) Completion Interrupt
32523.636 -> Controller::Return Isoc Done Q Start (0xffffff8007dae000) pEP 0x7f9e300 pTD 0x0
32523.636 <- Controller::Return Isoc Done Q End (0xffffff8007dae000)
32523.637 -> Controller::Return Isoc Done Q Start (0xffffff8007dae000) pEP 0x7f9e400 pTD 0x0
32523.637 <- Controller::Return Isoc Done Q End (0xffffff8007dae000)
32523.645 <- EHCI::End PollInterrupts (0xffffff8007dae000)
32523.706 HID::CheckForDeadDevice (0xffffff8009648400) _retryCount: 3, _deviceHasBeenDisconnected = 0
32523.736 -> Controller::Control Start (0xffffff8007dae000) Bus: 0xfa, Address: 2, Endpoint: 0, (in) bmRequestType: 0xa3, bRequest: 0x00, wValue: 0x0000, wIndex: 0x0003, wLength: 0x0004
32523.738 EHCI::powerChangeDone (0xffffff8007dae000) from state (4) to state (4)
32523.767 <- Controller::Control End (0xffffff8007dae000) error 0x0
32523.861 EHCI::Primary Interrupt (0xffffff8007dae000) enabledInterrupts 0x0000001f activeInterrupts 0x00000001, Frame: 206480, microframe: 1
32523.901 -> EHCI::Begin PollInterrupts (0xffffff8007dae000) _errorInterrupt 0 _completeInterrupt 1 _portChangeInterrupt 0
32523.901 EHCI::PollInterrupts (0xffffff8007dae000) Completion Interrupt
32523.902 -> Controller::Return Isoc Done Q Start (0xffffff8007dae000) pEP 0x7f9e300 pTD 0x0
32523.903 <- Controller::Return Isoc Done Q End (0xffffff8007dae000)
32523.903 -> Controller::Return Isoc Done Q Start (0xffffff8007dae000) pEP 0x7f9e400 pTD 0x0
32523.903 <- Controller::Return Isoc Done Q End (0xffffff8007dae000)
32523.904 Controller::Complete (0xffffff8007dae000) completion: 0xffffff7f809754fe, status: 0x0
32523.905 -> Controller::PacketHandler (0xffffff8007dae000) Bus: 0xfa, Address: 2, Endpoint: 0 status 0x0 bufferSizeRemaining 0
32523.905 <- Controller::PacketHandler (0xffffff8007dae000) Bus: 0xfa, Address: 2, Endpoint: 0 todo 0x60 status 0x0
32523.906 Controller::Complete (0xffffff8007dae000) completion: 0xffffff7f809754fe, status: 0x0
32523.906 -> Controller::PacketHandler (0xffffff8007dae000) Bus: 0xfa, Address: 2, Endpoint: 0 status 0x0 bufferSizeRemaining 0
32523.906 <- Controller::PacketHandler (0xffffff8007dae000) Bus: 0xfa, Address: 2, Endpoint: 0 todo 0x40 status 0x0
32523.906 Controller::Complete (0xffffff8007dae000) completion: 0xffffff7f809754fe, status: 0x0
32523.906 -> Controller::PacketHandler (0xffffff8007dae000) Bus: 0xfa, Address: 2, Endpoint: 0 status 0x0 bufferSizeRemaining 0
32523.909 Controller::Packet Handler (0x0000000000000000) Bus: 0xfa, Address: 2, Endpoint: 0, (out) length: 4, data: 0x1101000000000000 0x0000000000000000
32523.913 Controller::Complete (0xffffff8007dae000) completion: 0xffffff7f8096fb25, status: 0x0
32523.916 <- Controller::PacketHandler (0xffffff8007dae000) Bus: 0xfa, Address: 2, Endpoint: 0 todo 0x0 status 0x0
32523.923 <- EHCI::End PollInterrupts (0xffffff8007dae000)
32523.939 HID::CheckForDeadDevice (0xffffff8009648400) GetDeviceInformation returned info: 0x48, retryCount: 3
32523.939 HID::CheckForDeadDevice (0xffffff8009648400) device is still connected (and enabled) _retryCount: 3
32523.940 HID::CheckForDeadDevice (0xffffff8009648400) Still connected but retry count (2) not reached
32523.949 EHCI::powerChangeDone (0xffffff8007dae000) from state (4) to state (4)
32526.618 EHCI::Primary Interrupt (0xffffff8007dae000) enabledInterrupts 0x0000001f activeInterrupts 0x00000003, Frame: 206483, microframe: 1
32526.665 -> EHCI::Begin PollInterrupts (0xffffff8007dae000) _errorInterrupt 2 _completeInterrupt 1 _portChangeInterrupt 0
32526.666 EHCI::PollInterrupts (0xffffff8007dae000) Error Interrupt
32526.672 -> Controller::Return Isoc Done Q Start (0xffffff8007dae000) pEP 0x7f9e300 pTD 0x0
32526.672 <- Controller::Return Isoc Done Q End (0xffffff8007dae000)
32526.673 -> Controller::Return Isoc Done Q Start (0xffffff8007dae000) pEP 0x7f9e400 pTD 0x0
32526.674 <- Controller::Return Isoc Done Q End (0xffffff8007dae000)
32526.689 Controller::Complete (0xffffff8007dae000) completion: 0xffffff7f80973952, status: 0xe00002ed
32526.694 Controller::Interrupt Complete (0xffffff8007dae000) Bus: 0xfa, Address: 5, Endpoint: 1, (in) actual: 0, status: 0xe00002ed
32526.699 Controller::CompleteTS (0xffffff8007dae000) completion: 0xffffff7f80af89c2, status: 0xe00002ed
32526.702 HID::InterruptRead (0xffffff8009648400) error: 0xe00002ed, _deviceHasBeenDisconnected = 0
32526.705 HID::InterruptRead (0xffffff8009648400) Checking to see if HID device is still connected
32526.741 -> Controller::Interrupt Start (0xffffff8007dae000) Bus: 0xfa, Address: 5, Endpoint: 1, (in) reqCount: 50
32526.748 <- Controller::Interrupt End (0xffffff8007dae000) error 0x0, (completion timeout: 0, noData timeout: 0)
32526.749 HID::RearmInterruptRead (0xffffff8009648400) returning error 0x0
32526.759 EHCI::PollInterrupts (0xffffff8007dae000) Completion Interrupt
32526.760 -> Controller::Return Isoc Done Q Start (0xffffff8007dae000) pEP 0x7f9e300 pTD 0x0
32526.760 <- Controller::Return Isoc Done Q End (0xffffff8007dae000)
32526.761 -> Controller::Return Isoc Done Q Start (0xffffff8007dae000) pEP 0x7f9e400 pTD 0x0
32521.035 EHCI::powerChangeDone (0xffffff8007dae000) from state (4) to state (4)
32521.060 -> Controller::Control Start (0xffffff8007dae000) Bus: 0xfa, Address: 2, Endpoint: 0, (out) bmRequestType: 0x23, bRequest: 0x03, wValue: 0x0004, wIndex: 0x0003, wLength: 0x0000
32521.095 <- Controller::Control End (0xffffff8007dae000) error 0x0
32521.114 EHCI::Primary Interrupt (0xffffff8007dae000) enabledInterrupts 0x0000001f activeInterrupts 0x00000001, Frame: 206478, microframe: 1
32521.164 -> EHCI::Begin PollInterrupts (0xffffff8007dae000) _errorInterrupt 0 _completeInterrupt 1 _portChangeInterrupt 0
32521.165 EHCI::PollInterrupts (0xffffff8007dae000) Completion Interrupt
32521.168 -> Controller::Return Isoc Done Q Start (0xffffff8007dae000) pEP 0x7f9e300 pTD 0x0
32521.169 <- Controller::Return Isoc Done Q End (0xffffff8007dae000)
32521.170 -> Controller::Return Isoc Done Q Start (0xffffff8007dae000) pEP 0x7f9e400 pTD 0x0
32521.170 <- Controller::Return Isoc Done Q End (0xffffff8007dae000)
32521.175 Controller::Complete (0xffffff8007dae000) completion: 0xffffff7f809754fe, status: 0x0
32521.176 -> Controller::PacketHandler (0xffffff8007dae000) Bus: 0xfa, Address: 2, Endpoint: 0 status 0x0 bufferSizeRemaining 0
32521.177 <- Controller::PacketHandler (0xffffff8007dae000) Bus: 0xfa, Address: 2, Endpoint: 0 todo 0x40 status 0x0
32521.178 Controller::Complete (0xffffff8007dae000) completion: 0xffffff7f809754fe, status: 0x0
32521.178 -> Controller::PacketHandler (0xffffff8007dae000) Bus: 0xfa, Address: 2, Endpoint: 0 status 0x0 bufferSizeRemaining 0
32521.189 Controller::Complete (0xffffff8007dae000) completion: 0xffffff7f8096fb25, status: 0x0
32521.194 <- Controller::PacketHandler (0xffffff8007dae000) Bus: 0xfa, Address: 2, Endpoint: 0 todo 0x0 status 0x0
32521.208 EHCI::PollInterrupts (0xffffff8007dae000) Frame Rollover Interrupt
32521.209 <- EHCI::End PollInterrupts (0xffffff8007dae000)
32521.260 EHCI::powerChangeDone (0xffffff8007dae000) from state (4) to state (4)
32523.382 OutstandingIO::HubDecrement (0xffffff8007f6cc00) serial #: 549, IO: 1, needInterruptRead: no
32523.505 EHCI::Primary Interrupt (0xffffff8007dae000) enabledInterrupts 0x0000001f activeInterrupts 0x00000003, Frame: 206480, microframe: 1
32523.531 -> EHCI::Begin PollInterrupts (0xffffff8007dae000) _errorInterrupt 2 _completeInterrupt 1 _portChangeInterrupt 0
32523.532 EHCI::PollInterrupts (0xffffff8007dae000) Error Interrupt
32523.536 -> Controller::Return Isoc Done Q Start (0xffffff8007dae000) pEP 0x7f9e300 pTD 0x0
32523.537 <- Controller::Return Isoc Done Q End (0xffffff8007dae000)
32523.538 -> Controller::Return Isoc Done Q Start (0xffffff8007dae000) pEP 0x7f9e400 pTD 0x0
32523.539 <- Controller::Return Isoc Done Q End (0xffffff8007dae000)
32523.556 Controller::Complete (0xffffff8007dae000) completion: 0xffffff7f80973952, status: 0xe00002ed
32523.562 Controller::Interrupt Complete (0xffffff8007dae000) Bus: 0xfa, Address: 5, Endpoint: 1, (in) actual: 0, status: 0xe00002ed
32523.570 Controller::CompleteTS (0xffffff8007dae000) completion: 0xffffff7f80af89c2, status: 0xe00002ed
32523.573 HID::InterruptRead (0xffffff8009648400) error: 0xe00002ed, _deviceHasBeenDisconnected = 0
32523.578 HID::InterruptRead (0xffffff8009648400) Checking to see if HID device is still connected
32523.617 -> Controller::Interrupt Start (0xffffff8007dae000) Bus: 0xfa, Address: 5, Endpoint: 1, (in) reqCount: 50
32523.624 <- Controller::Interrupt End (0xffffff8007dae000) error 0x0, (completion timeout: 0, noData timeout: 0)
32523.625 HID::RearmInterruptRead (0xffffff8009648400) returning error 0x0
32523.635 EHCI::PollInterrupts (0xffffff8007dae000) Completion Interrupt
32523.636 -> Controller::Return Isoc Done Q Start (0xffffff8007dae000) pEP 0x7f9e300 pTD 0x0
32523.636 <- Controller::Return Isoc Done Q End (0xffffff8007dae000)
32523.637 -> Controller::Return Isoc Done Q Start (0xffffff8007dae000) pEP 0x7f9e400 pTD 0x0
32523.637 <- Controller::Return Isoc Done Q End (0xffffff8007dae000)
32523.645 <- EHCI::End PollInterrupts (0xffffff8007dae000)
32523.706 HID::CheckForDeadDevice (0xffffff8009648400) _retryCount: 3, _deviceHasBeenDisconnected = 0
32523.736 -> Controller::Control Start (0xffffff8007dae000) Bus: 0xfa, Address: 2, Endpoint: 0, (in) bmRequestType: 0xa3, bRequest: 0x00, wValue: 0x0000, wIndex: 0x0003, wLength: 0x0004
32523.738 EHCI::powerChangeDone (0xffffff8007dae000) from state (4) to state (4)
32523.767 <- Controller::Control End (0xffffff8007dae000) error 0x0
32523.861 EHCI::Primary Interrupt (0xffffff8007dae000) enabledInterrupts 0x0000001f activeInterrupts 0x00000001, Frame: 206480, microframe: 1
32523.901 -> EHCI::Begin PollInterrupts (0xffffff8007dae000) _errorInterrupt 0 _completeInterrupt 1 _portChangeInterrupt 0
32523.901 EHCI::PollInterrupts (0xffffff8007dae000) Completion Interrupt
32523.902 -> Controller::Return Isoc Done Q Start (0xffffff8007dae000) pEP 0x7f9e300 pTD 0x0
32523.903 <- Controller::Return Isoc Done Q End (0xffffff8007dae000)
32523.903 -> Controller::Return Isoc Done Q Start (0xffffff8007dae000) pEP 0x7f9e400 pTD 0x0
32523.903 <- Controller::Return Isoc Done Q End (0xffffff8007dae000)
32523.904 Controller::Complete (0xffffff8007dae000) completion: 0xffffff7f809754fe, status: 0x0
32523.905 -> Controller::PacketHandler (0xffffff8007dae000) Bus: 0xfa, Address: 2, Endpoint: 0 status 0x0 bufferSizeRemaining 0
32523.905 <- Controller::PacketHandler (0xffffff8007dae000) Bus: 0xfa, Address: 2, Endpoint: 0 todo 0x60 status 0x0
32523.906 Controller::Complete (0xffffff8007dae000) completion: 0xffffff7f809754fe, status: 0x0
32523.906 -> Controller::PacketHandler (0xffffff8007dae000) Bus: 0xfa, Address: 2, Endpoint: 0 status 0x0 bufferSizeRemaining 0
32523.906 <- Controller::PacketHandler (0xffffff8007dae000) Bus: 0xfa, Address: 2, Endpoint: 0 todo 0x40 status 0x0
32523.906 Controller::Complete (0xffffff8007dae000) completion: 0xffffff7f809754fe, status: 0x0
32523.906 -> Controller::PacketHandler (0xffffff8007dae000) Bus: 0xfa, Address: 2, Endpoint: 0 status 0x0 bufferSizeRemaining 0
32523.909 Controller::Packet Handler (0x0000000000000000) Bus: 0xfa, Address: 2, Endpoint: 0, (out) length: 4, data: 0x1101000000000000 0x0000000000000000
32523.913 Controller::Complete (0xffffff8007dae000) completion: 0xffffff7f8096fb25, status: 0x0
32523.916 <- Controller::PacketHandler (0xffffff8007dae000) Bus: 0xfa, Address: 2, Endpoint: 0 todo 0x0 status 0x0
32523.923 <- EHCI::End PollInterrupts (0xffffff8007dae000)
32523.939 HID::CheckForDeadDevice (0xffffff8009648400) GetDeviceInformation returned info: 0x48, retryCount: 3
32523.939 HID::CheckForDeadDevice (0xffffff8009648400) device is still connected (and enabled) _retryCount: 3
32523.940 HID::CheckForDeadDevice (0xffffff8009648400) Still connected but retry count (2) not reached
32523.949 EHCI::powerChangeDone (0xffffff8007dae000) from state (4) to state (4)
32526.618 EHCI::Primary Interrupt (0xffffff8007dae000) enabledInterrupts 0x0000001f activeInterrupts 0x00000003, Frame: 206483, microframe: 1
32526.665 -> EHCI::Begin PollInterrupts (0xffffff8007dae000) _errorInterrupt 2 _completeInterrupt 1 _portChangeInterrupt 0
32526.666 EHCI::PollInterrupts (0xffffff8007dae000) Error Interrupt
32526.672 -> Controller::Return Isoc Done Q Start (0xffffff8007dae000) pEP 0x7f9e300 pTD 0x0
32526.672 <- Controller::Return Isoc Done Q End (0xffffff8007dae000)
32526.673 -> Controller::Return Isoc Done Q Start (0xffffff8007dae000) pEP 0x7f9e400 pTD 0x0
32526.674 <- Controller::Return Isoc Done Q End (0xffffff8007dae000)
32526.689 Controller::Complete (0xffffff8007dae000) completion: 0xffffff7f80973952, status: 0xe00002ed
32526.694 Controller::Interrupt Complete (0xffffff8007dae000) Bus: 0xfa, Address: 5, Endpoint: 1, (in) actual: 0, status: 0xe00002ed
32526.699 Controller::CompleteTS (0xffffff8007dae000) completion: 0xffffff7f80af89c2, status: 0xe00002ed
32526.702 HID::InterruptRead (0xffffff8009648400) error: 0xe00002ed, _deviceHasBeenDisconnected = 0
32526.705 HID::InterruptRead (0xffffff8009648400) Checking to see if HID device is still connected
32526.741 -> Controller::Interrupt Start (0xffffff8007dae000) Bus: 0xfa, Address: 5, Endpoint: 1, (in) reqCount: 50
32526.748 <- Controller::Interrupt End (0xffffff8007dae000) error 0x0, (completion timeout: 0, noData timeout: 0)
32526.749 HID::RearmInterruptRead (0xffffff8009648400) returning error 0x0
32526.759 EHCI::PollInterrupts (0xffffff8007dae000) Completion Interrupt
32526.760 -> Controller::Return Isoc Done Q Start (0xffffff8007dae000) pEP 0x7f9e300 pTD 0x0
32526.760 <- Controller::Return Isoc Done Q End (0xffffff8007dae000)
32526.761 -> Controller::Return Isoc Done Q Start (0xffffff8007dae000) pEP 0x7f9e400 pTD 0x0
"
The error "0xe00002ed" is listed above:
32523.556 Controller::Complete (0xffffff8007dae000) completion: 0xffffff7f80973952, status: 0xe00002ed
32523.562 Controller::Interrupt Complete (0xffffff8007dae000) Bus: 0xfa, Address: 5, Endpoint: 1, (in) actual: 0, status: 0xe00002ed
32523.570 Controller::CompleteTS (0xffffff8007dae000) completion: 0xffffff7f80af89c2, status: 0xe00002ed
32523.573 HID::InterruptRead (0xffffff8009648400) error: 0xe00002ed, _deviceHasBeenDisconnected = 0
What does any of this mean? I do not want to re-write my app but rather would like to understand what WRITEPIPE is doing different in 32 and 64 bit.
Regards,
Saj
---
