I am porting iguanaWorks' code to drive their USB IR transceiver to OS X. Their code uses libusb(x). I am testing both on my Macbook and on Ubuntu Linux inside Virtual Box. On Ubuntu Linux, a
But on OS X, if I set debug level to 3, I get an incessant stream of timeout messages. I wonder: timeouts of what? While the daemon is printing all those errors it is still responsive and able to handle new commands that get passed to the USB device. Below is the debug log. Some timeouts (from the device) are expected initially when getting its ID (this device does not have one). But the timeouts from 3.227234 and on are unexpected. And as one can see from the INFO messages in the middle of that chain of timeouts, the daemon can communicate with the device successfully and get responses. It _seems_ like the timeouts may be printed when there are no timeouts, really. Maybe a callback is happening too often, or something is armed that shouldn't be? I am not sure, and I don't know how to debug it. But that does not happen on Linux. I added the Linux log too. MAC LOG (DEBUG LEVEL 3) BELC02GH0FUDJY9% ./igdaemon -nvvvv May 24 23:02:47 2012 DEBUG: Parameters: May 24 23:02:47 2012 DEBUG: recvTimeout: 1000 May 24 23:02:47 2012 DEBUG: sendTimeout: 1000 May 24 23:02:47 2012 INFO: Loaded driver: /opt/local/lib/iguanaIR/iglibusb.so [timestamp] [threadID] facility level [function call] <message> -------------------------------------------------------------------------------- [ 0.000000] [00000307] libusbx: info [process_new_device] allocating new device for location 0xfa000000 [ 0.000163] [00000307] libusbx: info [darwin_check_configuration] active config: 1, first config: 1 [ 0.000191] [00000307] libusbx: info [process_new_device] found device with address 1 at 001-05ac-8006-09-00 [ 0.000947] [00000307] libusbx: info [process_new_device] allocating new device for location 0xfa200000 [ 0.001829] [00000307] libusbx: info [darwin_check_configuration] active config: 1, first config: 1 [ 0.001863] [00000307] libusbx: info [process_new_device] found device with address 2 at 002-05ac-850a-ef-02 [ 0.002520] [00000307] libusbx: info [process_new_device] allocating new device for location 0xfa100000 [ 0.002931] [00000307] libusbx: info [darwin_check_configuration] active config: 1, first config: 1 [ 0.002955] [00000307] libusbx: info [process_new_device] found device with address 3 at 003-0424-2513-09-00 [ 0.003618] [00000307] libusbx: info [process_new_device] allocating new device for location 0xfa120000 [ 0.004311] [00000307] libusbx: info [darwin_check_configuration] active config: 1, first config: 1 [ 0.004342] [00000307] libusbx: info [process_new_device] found device with address 4 at 004-05ac-0249-00-00 [ 0.005035] [00000307] libusbx: info [process_new_device] allocating new device for location 0xfa110000 [ 0.192131] [00000307] libusbx: info [process_new_device] allocating new device for location 0xfa113000 [ 0.377696] [00000307] libusbx: warning [darwin_cache_device_descriptor] could not retrieve device descriptor 05ac:821f: device not responding. skipping device [ 0.378507] [00000307] libusbx: info [process_new_device] allocating new device for location 0xfa130000 [ 0.379561] [00000307] libusbx: info [darwin_check_configuration] active config: 1, first config: 1 [ 0.379596] [00000307] libusbx: info [process_new_device] found device with address 6 at 006-1781-0938-00-00 [ 0.380262] [00000307] libusbx: info [process_new_device] allocating new device for location 0xfd000000 [ 0.380337] [00000307] libusbx: info [darwin_check_configuration] active config: 1, first config: 1 [ 0.380355] [00000307] libusbx: info [process_new_device] found device with address 1 at 001-05ac-8006-09-00 [ 0.380953] [00000307] libusbx: info [process_new_device] allocating new device for location 0xfd100000 [ 0.573214] [00000307] libusbx: info [darwin_open] device open for access [ 0.574012] [00000307] libusbx: info [get_endpoints] building table of endpoints. [ 0.574046] [00000307] libusbx: info [get_endpoints] interface: 0 pipe 1: dir: 1 number: 1 [ 0.574067] [00000307] libusbx: info [get_endpoints] interface: 0 pipe 2: dir: 0 number: 2 [ 0.574128] [00000307] libusbx: info [darwin_claim_interface] interface opened [ 0.574293] [00003703] libusbx: info [ep_to_pipeRef] May 24 23:02:47 2012 DEBUG: Handling 1 device(s): converting ep address 0x81 to pipeRef and interfaceMay 24 23:02:47 2012 INFO: Worker 0 starting May 24 23:02:47 2012 DEBUG: 0) usb:250.6 id=0 addr=0x7fbfa9c0a200 May 24 23:02:47 2012 DEBUG2: o[ 0.574447] [00003703] libusbx: info [ep_to_pipeRef] 0xpipe 1 on interface 0 matches00 00cd01 [ 0.574582] [00003803] libusbx: info [ep_to_pipeRef] converting ep address 0x02 to pipeRef and interface [ 0.574597] [00003803] libusbx: info [ep_to_pipeRef] pipe 2 on interface 0 matches [ 0.575967] [00001b07] libusbx: info [darwin_async_io_callback] an async io operation has completed [ 0.576021] [00003703] libusbx: info [op_handle_events] checking fd 5 with revents = 0 [ 0.576035] [00003703] libusbx: info [op_handle_events] checking fd 11 with revents = 1 [ 0.576052] [00003703] libusbx: info [darwin_handle_callback] handling interrupt completion with kernel status 0 [ 1.575955] [00003703] libusbx: info [ep_to_pipeRef] converting ep address 0x81 to pipeRef and interface [ 1.575993] [00003703] libusbx: info [ep_to_pipeRef] pipe 1 on interface 0 matches [ 1.576005] [00003703] libusbx: info [darwin_abort_transfers] WARNING: aborting all transactions on interface 0 pipe 1 May 24 23:02:48 2012 INFO: Timeout while waiting for response from device. May 24 23:02:48 2012 DEBUG2: o0x0000cd01 [ 1.577257] [00003703] libusbx: info [darwin_abort_transfers] calling clear pipe stall to clear the data toggle bit [ 1.577261] [00001b07] libusbx: info [darwin_async_io_callback] an async io operation has completed [ 1.579023] [00003803] libusbx: info [ep_to_pipeRef] converting ep address 0x02 to pipeRef and interface [ 1.579045] [00003703] libusbx: info [op_handle_events] [ 1.579056] [00003803] libusbx: info [ep_to_pipeRef] checking fd 5 with revents = 0pipe 2 on interface 0 matches [ 1.579108] [00003703] libusbx: info [op_handle_events] checking fd 11 with revents = 1 [ 1.579175] [00003703] libusbx: info [darwin_handle_callback] handling interrupt completion with kernel status -536870165 [ 1.579186] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out [ 1.579213] [00003703] libusbx: info [ep_to_pipeRef] converting ep address 0x81 to pipeRef and interface [ 1.579221] [00003703] libusbx: info [ep_to_pipeRef] pipe 1 on interface 0 matches [ 1.584004] [00001b07] libusbx: info [darwin_async_io_callback] an async io operation has completed [ 1.584068] [00003803] libusbx: info [op_handle_events] checking fd 5 with revents = 0 [ 1.584093] [00003803] libusbx: info [op_handle_events] checking fd 11 with revents = 1 [ 1.584114] [00003803] libusbx: info [darwin_handle_callback] handling interrupt completion with kernel status 0 [ 1.591187] [00001b07] libusbx: info [darwin_async_io_callback] an async io operation has completed [ 1.591363] [00003703] libusbx: info [op_handle_events] checking fd 5 with revents = 0 [ 1.591423] [00003703] libusbx: info [op_handle_events] checking fd 11 with revents = 1 [ 1.591466] [00003703] libusbx: info [darwin_handle_callback] handling interrupt completion with kernel status 0 May 24 23:02:48 2012 DEBUG2: i0x0000dc010703 May 24 23:02:48 2012 DEBUG: Received ctl header: 0x1 May 24 23:02:48 2012 DEBUG3: Notifying of packet: type = 0x01 [ 1.591695] [00003703] libusbx: info [ep_to_pipeRef] converting ep address 0x81 to pipeRef and interface [ 1.591717] [00003703] libusbx: info [ep_to_pipeRef] pipe 1 on interface 0 matches May 24 23:02:48 2012 INFO: Transaction: 0x1 (14489 microseconds) May 24 23:02:48 2012 INFO: Found device version 0x307 May 24 23:02:48 2012 DEBUG2: o0x0000cd1f [ 1.592125] [00003803] libusbx: info [ep_to_pipeRef] converting ep address 0x02 to pipeRef and interface [ 1.592150] [00003803] libusbx: info [ep_to_pipeRef] pipe 2 on interface 0 matches [ 1.599993] [00001b07] libusbx: info [darwin_async_io_callback] an async io operation has completed [ 1.600117] [00003703] libusbx: info [op_handle_events] checking fd 5 with revents = 0 [ 1.600158] [00003703] libusbx: info [op_handle_events] checking fd 11 with revents = 1 [ 1.600187] [00003703] libusbx: info [darwin_handle_callback] handling interrupt completion with kernel status 0 [ 2.593249] [00003703] libusbx: info [ep_to_pipeRef] converting ep address 0x81 to pipeRef and interface [ 2.593282] [00003703] libusbx: info [ep_to_pipeRef] pipe 1 on interface 0 matches [ 2.593293] [00003703] libusbx: info [darwin_abort_transfers] WARNING: aborting all transactions on interface 0 pipe 1 [ 2.594497] [00003703] libusbx: info [darwin_abort_transfers] calling clear pipe stall to clear the data toggle bit [ 2.594504] [00001b07] libusbx: info [darwin_async_io_callback] an async io operation has completed [ 2.596059] [00003703] libusbx: info [op_handle_events] checking fd 5 with revents = 0 [ 2.596088] [00003703] libusbx: info [op_handle_events] checking fd 11 with revents = 1 [ 2.596104] [00003703] libusbx: info [darwin_handle_callback] handling interrupt completion with kernel status -536870165 [ 2.596114] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out [ 2.596138] [00003703] libusbx: info [ep_to_pipeRef] converting ep address 0x81 to pipeRef and interface [ 2.596147] [00003703] libusbx: info [ep_to_pipeRef] pipe 1 on interface 0 matches May 24 23:02:49 2012 INFO: Timeout while waiting for response from device. May 24 23:02:49 2012 DEBUG: Cleaning up aliasing for device 0 (a.k.a. 000013FA for location ID 0xfa130000 / 6). May 24 23:02:49 2012 INFO: Failed to get id. Device may not have one assigned. [ 3.597269] [00003703] libusbx: info [ep_to_pipeRef] converting ep address 0x81 to pipeRef and interface [ 3.597342] [00003703] libusbx: info [ep_to_pipeRef] pipe 1 on interface 0 matches [ 3.597363] [00003703] libusbx: info [darwin_abort_transfers] WARNING: aborting all transactions on interface 0 pipe 1 [ 3.598718] [00003703] libusbx: info [darwin_abort_transfers] calling clear pipe stall to clear the data toggle bit [ 3.598774] [00001b07] libusbx: info [darwin_async_io_callback] an async io operation has completed [ 3.600268] [00003703] libusbx: info [op_handle_events] checking fd 5 with revents = 0 [ 3.600293] [00003703] libusbx: info [op_handle_events] checking fd 11 with revents = 1 [ 3.600311] [00003703] libusbx: info [darwin_handle_callback] handling interrupt completion with kernel status -536870165 [ 3.600322] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out [ 3.600347] [00003703] libusbx: info [ep_to_pipeRef] converting ep address 0x81 to pipeRef and interface [ 3.600358] [00003703] libusbx: info [ep_to_pipeRef] pipe 1 on interface 0 matches [ 4.601492] [00003703] libusbx: info [ep_to_pipeRef] converting ep address 0x81 to pipeRef and interface [ 4.601543] [00003703] libusbx: info [ep_to_pipeRef] pipe 1 on interface 0 matches [ 4.601557] [00003703] libusbx: info [darwin_abort_transfers] WARNING: aborting all transactions on interface 0 pipe 1 [ 4.602860] [00003703] libusbx: info [darwin_abort_transfers] calling clear pipe stall to clear the data toggle bit [ 4.602909] [00001b07] libusbx: info [darwin_async_io_callback] an async io operation has completed [ 4.604556] [00003703] libusbx: info [op_handle_events] checking fd 5 with revents = 0 [ 4.604586] [00003703] libusbx: info [op_handle_events] checking fd 11 with revents = 1 [ 4.604612] [00003703] libusbx: info [darwin_handle_callback] handling interrupt completion with kernel status -536870165 [ 4.604630] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out [ 4.604668] [00003703] libusbx: info [ep_to_pipeRef] converting ep address 0x81 to pipeRef and interface [ 4.604684] [00003703] libusbx: info [ep_to_pipeRef] pipe 1 on interface 0 matches [ 5.605811] [00003703] libusbx: info [ep_to_pipeRef] converting ep address 0x81 to pipeRef and interface [ 5.605857] [00003703] libusbx: info [ep_to_pipeRef] pipe 1 on interface 0 matches [ 5.605869] [00003703] libusbx: info [darwin_abort_transfers] WARNING: aborting all transactions on interface 0 pipe 1 [ 5.607138] [00003703] libusbx: info [darwin_abort_transfers] calling clear pipe stall to clear the data toggle bit [ 5.607191] [00001b07] libusbx: info [darwin_async_io_callback] an async io operation has completed [ 5.608877] [00003703] libusbx: info [op_handle_events] checking fd 5 with revents = 0 [ 5.608905] [00003703] libusbx: info [op_handle_events] checking fd 11 with revents = 1 [ 5.608922] [00003703] libusbx: info [darwin_handle_callback] handling interrupt completion with kernel status -536870165 [ 5.608934] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out [ 5.608959] [00003703] libusbx: info [ep_to_pipeRef] converting ep address 0x81 to pipeRef and interface [ 5.608970] [00003703] libusbx: info [ep_to_pipeRef] pipe 1 on interface 0 matches [ 6.610095] [00003703] libusbx: info [ep_to_pipeRef] converting ep address 0x81 to pipeRef and interface [ 6.610141] [00003703] libusbx: info [ep_to_pipeRef] pipe 1 on interface 0 matches [ 6.610153] [00003703] libusbx: info [darwin_abort_transfers] WARNING: aborting all transactions on interface 0 pipe 1 [ 6.611424] [00003703] libusbx: info [darwin_abort_transfers] calling clear pipe stall to clear the data toggle bit [ 6.611437] [00001b07] libusbx: info [darwin_async_io_callback] an async io operation has completed [ 6.613217] [00003703] libusbx: info [op_handle_events] checking fd 5 with revents = 0 [ 6.613263] [00003703] libusbx: info [op_handle_events] checking fd 11 with revents = 1 [ 6.613280] [00003703] libusbx: info [darwin_handle_callback] handling interrupt completion with kernel status -536870165 [ 6.613292] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out [ 6.613324] [00003703] libusbx: info [ep_to_pipeRef] converting ep address 0x81 to pipeRef and interface [ 6.613333] [00003703] libusbx: info [ep_to_pipeRef] pipe 1 on interface 0 matches [ 7.614417] [00003703] libusbx: info [ep_to_pipeRef] converting ep address 0x81 to pipeRef and interface [ 7.614483] [00003703] libusbx: info [ep_to_pipeRef] pipe 1 on interface 0 matches [ 7.614502] [00003703] libusbx: info [darwin_abort_transfers] WARNING: aborting all transactions on interface 0 pipe 1 [ 7.615787] [00003703] libusbx: info [darwin_abort_transfers] calling clear pipe stall to clear the data toggle bit [ 7.615851] [00001b07] libusbx: info [darwin_async_io_callback] an async io operation has completed [ 7.617483] [00003703] libusbx: info [op_handle_events] checking fd 5 with revents = 0 [ 7.617533] [00003703] libusbx: info [op_handle_events] checking fd 11 with revents = 1 [ 7.617564] [00003703] libusbx: info [darwin_handle_callback] handling interrupt completion with kernel status -536870165 [ 7.617583] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out [ 7.617625] [00003703] libusbx: info [ep_to_pipeRef] converting ep address 0x81 to pipeRef and interface [ 7.617644] [00003703] libusbx: info [ep_to_pipeRef] pipe 1 on interface 0 matches [ 8.617985] [00003703] libusbx: info [ep_to_pipeRef] converting ep address 0x81 to pipeRef and interface [ 8.618027] [00003703] libusbx: info [ep_to_pipeRef] pipe 1 on interface 0 matches [ 8.618038] [00003703] libusbx: info [darwin_abort_transfers] WARNING: aborting all transactions on interface 0 pipe 1 [ 8.619296] [00003703] libusbx: info [darwin_abort_transfers] calling clear pipe stall to clear the data toggle bit [ 8.619349] [00001b07] libusbx: info [darwin_async_io_callback] an async io operation has completed [ 8.621035] [00003703] libusbx: info [op_handle_events] checking fd 5 with revents = 0 [ 8.621067] [00003703] libusbx: info [op_handle_events] checking fd 11 with revents = 1 [ 8.621084] [00003703] libusbx: info [darwin_handle_callback] handling interrupt completion with kernel status -536870165 [ 8.621096] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out [ 8.621120] [00003703] libusbx: info [ep_to_pipeRef] converting ep address 0x81 to pipeRef and interface [ 8.621131] [00003703] libusbx: info [ep_to_pipeRef] pipe 1 on interface 0 matches [ 9.622255] [00003703] libusbx: info [ep_to_pipeRef] converting ep address 0x81 to pipeRef and interface [ 9.622309] [00003703] libusbx: info [ep_to_pipeRef] pipe 1 on interface 0 matches [ 9.622323] [00003703] libusbx: info [darwin_abort_transfers] WARNING: aborting all transactions on interface 0 pipe 1 [ 9.623637] [00003703] libusbx: info [darwin_abort_transfers] calling clear pipe stall to clear the data toggle bit [ 9.623686] [00001b07] libusbx: info [darwin_async_io_callback] an async io operation has completed [ 9.625391] [00003703] libusbx: info [op_handle_events] checking fd 5 with revents = 0 [ 9.625424] [00003703] libusbx: info [op_handle_events] checking fd 11 with revents = 1 [ 9.625443] [00003703] libusbx: info [darwin_handle_callback] handling interrupt completion with kernel status -536870165 [ 9.625457] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out [ 9.625486] [00003703] libusbx: info [ep_to_pipeRef] converting ep address 0x81 to pipeRef and interface [ 9.625499] [00003703] libusbx: info [ep_to_pipeRef] pipe 1 on interface 0 matches [10.626641] [00003703] libusbx: info [ep_to_pipeRef] converting ep address 0x81 to pipeRef and interface [10.626710] [00003703] libusbx: info [ep_to_pipeRef] pipe 1 on interface 0 matches [10.626730] [00003703] libusbx: info [darwin_abort_transfers] WARNING: aborting all transactions on interface 0 pipe 1 [10.628070] [00003703] libusbx: info [darwin_abort_transfers] calling clear pipe stall to clear the data toggle bit [10.628121] [00001b07] libusbx: info [darwin_async_io_callback] an async io operation has completed [10.629703] [00003703] libusbx: info [op_handle_events] checking fd 5 with revents = 0 [10.629744] [00003703] libusbx: info [op_handle_events] checking fd 11 with revents = 1 [10.629775] [00003703] libusbx: info [darwin_handle_callback] handling interrupt completion with kernel status -536870165 [10.629797] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out [10.629844] [00003703] libusbx: info [ep_to_pipeRef] converting ep address 0x81 to pipeRef and interface [10.629861] [00003703] libusbx: info [ep_to_pipeRef] pipe 1 on interface 0 matches May 24 23:02:58 2012 INFO: Found client using protocol version 1 May 24 23:02:58 2012 INFO: Request handled within daemon: 0xfe May 24 23:02:58 2012 DEBUG3: Successfully wrote packet: 0xfe00000002000000 0x0100 May 24 23:02:58 2012 INFO: Request handled within daemon: 0x18 May 24 23:02:58 2012 DEBUG3: Successfully wrote packet: 0x1800000004000000 0x00009470 [11.631008] [00003703] libusbx: info [ep_to_pipeRef] converting ep address 0x81 to pipeRef and interface [11.631061] [00003703] libusbx: info [ep_to_pipeRef] pipe 1 on interface 0 matches [11.631075] [00003703] libusbx: info [darwin_abort_transfers] WARNING: aborting all transactions on interface 0 pipe 1 [11.632391] [00003703] libusbx: info [darwin_abort_transfers] calling clear pipe stall to clear the data toggle bit [11.632441] [00001b07] libusbx: info [darwin_async_io_callback] an async io operation has completed [11.634180] [00003703] libusbx: info [op_handle_events] checking fd 5 with revents = 0 [11.634208] [00003703] libusbx: info [op_handle_events] checking fd 11 with revents = 1 [11.634225] [00003703] libusbx: info [darwin_handle_callback] handling interrupt completion with kernel status -536870165 [11.634237] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out [11.634262] [00003703] libusbx: info [ep_to_pipeRef] converting ep address 0x81 to pipeRef and interface [11.634273] [00003703] libusbx: info [ep_to_pipeRef] pipe 1 on interface 0 matches [12.635342] [00003703] libusbx: info [ep_to_pipeRef] converting ep address 0x81 to pipeRef and interface [12.635413] [00003703] libusbx: info [ep_to_pipeRef] pipe 1 on interface 0 matches [12.635442] [00003703] libusbx: info [darwin_abort_transfers] WARNING: aborting all transactions on interface 0 pipe 1 [12.636689] [00003703] libusbx: info [darwin_abort_transfers] calling clear pipe stall to clear the data toggle bit [12.636710] [00001b07] libusbx: info [darwin_async_io_callback] an async io operation has completed [12.638246] [00003703] libusbx: info [op_handle_events] checking fd 5 with revents = 0 [12.638282] [00003703] libusbx: info [op_handle_events] checking fd 11 with revents = 1 [12.638308] [00003703] libusbx: info [darwin_handle_callback] handling interrupt completion with kernel status -536870165 [12.638324] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out [12.638360] [00003703] libusbx: info [ep_to_pipeRef] converting ep address 0x81 to pipeRef and interface [12.638377] [00003703] libusbx: info [ep_to_pipeRef] pipe 1 on interface 0 matches ^C[13.639506] [00003703] libusbx: info [ep_to_pipeRef] converting ep address 0x81 to pipeRef and interface [13.639558] [00003703] libusbx: info [ep_to_pipeRef] pipe 1 on interface 0 matches [13.639578] [00003703] libusbx: info [darwin_abort_transfers] WARNING: aborting all transactions on interface 0 pipe 1 [13.640845] [00003703] libusbx: info [darwin_abort_transfers] [13.640854] [00001b07] libusbx: info [darwin_async_io_callback] calling clear pipe stall to clear the data toggle bitan async io operation has completed [13.642599] [00003703] libusbx: info [op_handle_events] checking fd 5 with revents = 0 [13.642631] [00003703] libusbx: info [op_handle_events] checking fd 11 with revents = 1 [13.642651] [00003703] libusbx: info [darwin_handle_callback] handling interrupt completion with kernel status -536870165 [13.642664] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out May 24 23:03:01 2012 INFO: Device 0 released May 24 23:03:01 2012 DEBUG: Cleaning up aliasing for device 0 (a.k.a. 000013FA for location ID 0xfa130000 / 6). May 24 23:03:01 2012 DEBUG: Unaliasing 000013FA -> 0. May 24 23:03:01 2012 INFO: Worker 0 exiting May 24 23:03:01 2012 DEBUG: Reaped child: 0x10e74e000 MAC LOG (LEVEL 2) % ./igdaemon -nvvv May 24 22:47:22 2012 DEBUG: Parameters: May 24 22:47:22 2012 DEBUG: recvTimeout: 1000 May 24 22:47:22 2012 DEBUG: sendTimeout: 1000 May 24 22:47:22 2012 INFO: Loaded driver: /opt/local/lib/iguanaIR/iglibusb.so [timestamp] [threadID] facility level [function call] <message> -------------------------------------------------------------------------------- [ 0.000000] [00000307] libusbx: warning [darwin_cache_device_descriptor] could not retrieve device descriptor 05ac:821f: device not responding. skipping device May 24 22:47:23 2012 DEBUG: Handling 1 device(s): May 24 22:47:23 2012 DEBUG: 0) usb:250.6 id=0 addr=0x7fcd12000380 May 24 22:47:23 2012 INFO: Worker 0 starting May 24 22:47:23 2012 DEBUG2: o0x0000cd01 [ 1.203810] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out May 24 22:47:24 2012 INFO: Timeout while waiting for response from device. May 24 22:47:24 2012 DEBUG2: o0x0000cd01 May 24 22:47:24 2012 DEBUG2: i0x0000dc010703 May 24 22:47:24 2012 DEBUG: Received ctl header: 0x1 May 24 22:47:24 2012 INFO: Transaction: 0x1 (14319 microseconds) May 24 22:47:24 2012 INFO: Found device version 0x307 May 24 22:47:24 2012 DEBUG2: o0x0000cd1f [ 2.223748] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out May 24 22:47:25 2012 INFO: Timeout while waiting for response from device. May 24 22:47:25 2012 DEBUG: Cleaning up aliasing for device 0 (a.k.a. 000013FA for location ID 0xfa130000 / 6). May 24 22:47:25 2012 INFO: Failed to get id. Device may not have one assigned. [ 3.227234] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out [ 4.231053] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out [ 5.235295] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out [ 6.239326] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out [ 7.242652] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out [ 8.246939] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out [ 9.250824] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out May 24 22:47:32 2012 INFO: Found client using protocol version 1 May 24 22:47:32 2012 INFO: Request handled within daemon: 0xfe May 24 22:47:32 2012 INFO: Request handled within daemon: 0x18 [10.254738] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out [11.258085] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out [12.262305] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out [13.266664] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out [14.270831] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out ^C[15.275107] [00003703] libusbx: error [darwin_transfer_status] transfer error: timed out May 24 22:47:38 2012 INFO: Device 0 released May 24 22:47:38 2012 DEBUG: Cleaning up aliasing for device 0 (a.k.a. 000013FA for location ID 0xfa130000 / 6). May 24 22:47:38 2012 DEBUG: Unaliasing 000013FA -> 0. May 24 22:47:38 2012 INFO: Worker 0 exiting May 24 22:47:38 2012 DEBUG: Reaped child: 0x1045ac000 LINUX LOG (LEVEL 2; the only reason I have sudo is that it seems that the udev rules to grant access to the USB device are not firing) $ sudo ./igdaemon -nvvvv May 24 22:56:16 2012 DEBUG: Parameters: May 24 22:56:16 2012 DEBUG: recvTimeout: 1000 May 24 22:56:16 2012 DEBUG: sendTimeout: 1000 May 24 22:56:16 2012 INFO: Loaded driver: /usr/local/lib/iguanaIR/libusb.so May 24 22:56:16 2012 DEBUG: Handling 1 device(s): May 24 22:56:16 2012 DEBUG: 0) usb:2.31 id=0 addr=0x975b6e0 May 24 22:56:16 2012 INFO: Worker 0 starting May 24 22:56:16 2012 DEBUG2: o0x0000cd01 May 24 22:56:16 2012 DEBUG2: i0x0000dc010703 May 24 22:56:16 2012 DEBUG: Received ctl header: 0x1 May 24 22:56:16 2012 DEBUG3: Notifying of packet: type = 0x01 May 24 22:56:16 2012 INFO: Transaction: 0x1 (24349 microseconds) May 24 22:56:16 2012 INFO: Found device version 0x307 May 24 22:56:16 2012 DEBUG2: o0x0000cd1f May 24 22:56:17 2012 INFO: Timeout while waiting for response from device. May 24 22:56:17 2012 DEBUG: Cleaning up aliasing for device 0. May 24 22:56:17 2012 INFO: Failed to get id. Device may not have one assigned. May 24 22:56:41 2012 INFO: Found client using protocol version 1 May 24 22:56:41 2012 INFO: Request handled within daemon: 0xfe May 24 22:56:41 2012 DEBUG3: Successfully wrote packet: 0xfe00000002000000 0x0100 May 24 22:56:41 2012 INFO: Request handled within daemon: 0x18 May 24 22:56:41 2012 DEBUG3: Successfully wrote packet: 0x1800000004000000 0x00009470 ^CMay 24 22:56:44 2012 INFO: Device 0 released May 24 22:56:44 2012 DEBUG: Cleaning up aliasing for device 0. May 24 22:56:44 2012 INFO: Worker 0 exiting May 24 22:56:44 2012 DEBUG: Reaped child: 0xb6da3b40 ------------------------------------------------------------------------------ Live Security Virtual Conference Exclusive live event will cover all the ways today's security and threat landscape has changed and how IT managers can respond. Discussions will include endpoint security, mobile security and the latest in malware threats. http://www.accelacomm.com/jaw/sfrnl04242012/114/50122263/ _______________________________________________ libusbx-devel mailing list libusbx-devel@lists.sourceforge.net https://lists.sourceforge.net/lists/listinfo/libusbx-devel
