From mboxrd@z Thu Jan 1 00:00:00 1970 Received: from eggs.gnu.org ([2001:4830:134:3::10]:39754) by lists.gnu.org with esmtp (Exim 4.71) (envelope-from ) id 1YLHwm-0000QM-Se for qemu-devel@nongnu.org; Tue, 10 Feb 2015 16:02:22 -0500 Received: from Debian-exim by eggs.gnu.org with spam-scanned (Exim 4.71) (envelope-from ) id 1YLHwh-0002tj-Qz for qemu-devel@nongnu.org; Tue, 10 Feb 2015 16:02:16 -0500 Received: from mailout03.t-online.de ([194.25.134.81]:38134) by eggs.gnu.org with esmtp (Exim 4.71) (envelope-from ) id 1YLHwh-0002ss-Ch for qemu-devel@nongnu.org; Tue, 10 Feb 2015 16:02:11 -0500 Message-ID: <54DA71CD.8040805@t-online.de> Date: Tue, 10 Feb 2015 22:02:05 +0100 From: Dennis Ostermann MIME-Version: 1.0 References: <54D921F0.2060809@t-online.de> <54D9BCBB.2030809@redhat.com> In-Reply-To: <54D9BCBB.2030809@redhat.com> Content-Type: text/plain; charset=utf-8; format=flowed Content-Transfer-Encoding: 7bit Subject: Re: [Qemu-devel] Revert commit 5af35d7feccaa7d26b72c6c3d14116421d736b36 - "usb-host-libusb: Fix reset handling" List-Id: List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , To: qemu-devel@nongnu.org Cc: Hans de Goede , kraxel@redhat.com Hello Hans, thanks for taking care. 09-02-15 09:09, Hans de Goede wrote: > Hi, > > On 09-02-15 22:09, Dennis Ostermann wrote: >> Hi there, >> >> please revert commit 5af35d7feccaa7d26b72c6c3d14116421d736b36 - "usb-host-libusb: Fix reset handling" >> >> This breaks usb pass through of FTDI based usb devices: >> >> On the host: >> >> lsusb | grep FT2232 >> Bus 003 Device 008: ID 0403:6010 Future Technology Devices International, Ltd FT2232C Dual USB-UART/FIFO IC >> >> >> ~/qemu-install/bin$ sudo ./qemu-system-x86_64 -monitor telnet:127.0.0.1:1234,server,nowait -hda /dev/sdd2 -redir tcp:20022::22 --enable-kvm -cpu host -smp 4 -vga vmware --vnc :0 -m 8192 -usb -device usb-ehci,id=ehci -device usb-host,bus=ehci.0,vendorid=0x0403,productid=0x6010 >> WARNING: Image format was not specified for '/dev/sdd2' and probing guessed raw. >> Automatically detecting the format is dangerous for raw images, write operations on block 0 will be restricted. >> Specify the 'raw' format explicitly to remove the restrictions. >> libusbx: error [_get_usbfs_fd] libusbx couldn't open USB device /dev/bus/usb/003/005: No such file or directory >> libusbx: error [_get_usbfs_fd] libusbx couldn't open USB device /dev/bus/usb/003/006: No such file or directory >> libusbx: error [_get_usbfs_fd] libusbx couldn't open USB device /dev/bus/usb/003/007: No such device >> >> The device gets reset again and again and is re-enumerated every time and finally not passed through. > This looks like the device drops of the bus when it is reset, that is not normal behavior, > there seems to be something unique to your setup causing this. Have you tried this on > multiple machines / different usb ports on your pc ? This may be something weird with the usb > controller in your machine. The machine uses Intel H97 chipset, so not that unique. I tried every port, every USB BIOS, with and without hub, it doesn't make any difference. But you're right, I also tried it on a T61 with Intel 900 series chipset and it worked alright. But this is an 'old' USB 2.0 chipset. I'll try on another box with USB 3.0 chipset tomorrow, if I can get one. With the commit reverted, it even works behind a no-name china USB 3.0 hub. >> >> After reverting the commit: >> >> ~/qemu-patched-install/bin$ sudo ./qemu-system-x86_64 -monitor telnet:127.0.0.1:1234,server,nowait -hda /dev/sdd2 -redir tcp:20022::22 --enable-kvm -cpu host -smp 4 -vga vmware --vnc :0 -m 8192 -usb -device usb-ehci,id=ehci -device usb-host,bus=ehci.0,vendorid=0x0403,productid=0x6010 >> WARNING: Image format was not specified for '/dev/sdd2' and probing guessed raw. >> Automatically detecting the format is dangerous for raw images, write operations on block 0 will be restricted. >> Specify the 'raw' format explicitly to remove the restrictions. >> >> >> The device appears in the guest OS and can be used. >> >> Tested with HEAD and several libusb versions. Affects at least FTDI FT2232H and FTDI FT232R. > Which versions of libusb have you tested exactly ? Latest HEAD, 1.0.16. and 1.0.17. Should I try any other? Couldn't find anything in the commit logs that might have s.th. to do with my issue. I compiled 1.0.16 with debug enabled. Here is what it shows with the original qemu: ~/qemu-install/bin$ sudo LD_LIBRARY_PATH=~/Devel/libusb-1.0.16-install/lib ./qemu-system-x86_64 -monitor telnet:127.0.0.1:1234,server,nowait -hda /dev/sdd2 -redir tcp:20022::22 --enable-kvm -cpu host -smp 4 -vga vmware --vnc :0 -m 8192 -usb -device usb-ehci,id=ehci -device usb-host,bus=ehci.0,vendorid=0x0403,productid=0x6010 WARNING: Image format was not specified for '/dev/sdd2' and probing guessed raw. Automatically detecting the format is dangerous for raw images, write operations on block 0 will be restricted. Specify the 'raw' format explicitly to remove the restrictions. [timestamp] [threadID] facility level [function call] -------------------------------------------------------------------------------- [ 0.000003] [0000081c] libusbx: debug [libusb_init] created default context [ 0.000037] [0000081c] libusbx: debug [libusb_init] libusbx v1.0.16.10774 [ 0.000057] [0000081c] libusbx: debug [find_usbfs_path] found usbfs at /dev/bus/usb [ 0.000067] [0000081c] libusbx: debug [op_init] bulk continuation flag supported [ 0.000070] [0000081c] libusbx: debug [op_init] zero length packet flag supported [ 0.000080] [0000081c] libusbx: debug [op_init] sysfs can relate devices [ 0.000085] [0000081c] libusbx: debug [op_init] sysfs has complete descriptors [ 0.000310] [00000824] libusbx: debug [linux_udev_event_thread_main] udev event thread entering. [ 0.000469] [0000081c] libusbx: debug [linux_get_device_address] getting address for device: usb1 detached: 0 [ 0.000477] [0000081c] libusbx: debug [linux_get_device_address] scan usb1 [ 0.000509] [0000081c] libusbx: debug [linux_get_device_address] bus=1 dev=1 [ 0.000514] [0000081c] libusbx: debug [linux_enumerate_device] busnum 1 devaddr 1 session_id 257 [ 0.000518] [0000081c] libusbx: debug [linux_enumerate_device] allocating new device for 1/1 (session 257) [ 0.000576] [0000081c] libusbx: debug [linux_get_device_address] getting address for device: 1-10 detached: 0 [ 0.000581] [0000081c] libusbx: debug [linux_get_device_address] scan 1-10 [ 0.000608] [0000081c] libusbx: debug [linux_get_device_address] bus=1 dev=19 [ 0.000612] [0000081c] libusbx: debug [linux_enumerate_device] busnum 1 devaddr 19 session_id 275 [ 0.000616] [0000081c] libusbx: debug [linux_enumerate_device] allocating new device for 1/19 (session 275) [ 0.000646] [0000081c] libusbx: debug [linux_get_parent_info] Dev 0x7f6fad6fdd20 (1-10) has parent 0x7f6fad6fdc20 (usb1) port 10 [ 0.000704] [0000081c] libusbx: debug [linux_get_device_address] getting address for device: 1-12 detached: 0 [ 0.000708] [0000081c] libusbx: debug [linux_get_device_address] scan 1-12 [ 0.000733] [0000081c] libusbx: debug [linux_get_device_address] bus=1 dev=13 [ 0.000738] [0000081c] libusbx: debug [linux_enumerate_device] busnum 1 devaddr 13 session_id 269 [ 0.000742] [0000081c] libusbx: debug [linux_enumerate_device] allocating new device for 1/13 (session 269) [ 0.000757] [0000081c] libusbx: debug [linux_get_parent_info] Dev 0x7f6fad6fd4b0 (1-12) has parent 0x7f6fad6fdc20 (usb1) port 12 [ 0.000812] [0000081c] libusbx: debug [linux_get_device_address] getting address for device: 1-4 detached: 0 [ 0.000817] [0000081c] libusbx: debug [linux_get_device_address] scan 1-4 [ 0.000851] [0000081c] libusbx: debug [linux_get_device_address] bus=1 dev=11 [ 0.000855] [0000081c] libusbx: debug [linux_enumerate_device] busnum 1 devaddr 11 session_id 267 [ 0.000858] [0000081c] libusbx: debug [linux_enumerate_device] allocating new device for 1/11 (session 267) [ 0.000872] [0000081c] libusbx: debug [linux_get_parent_info] Dev 0x7f6fad6fd550 (1-4) has parent 0x7f6fad6fdc20 (usb1) port 4 [ 0.000900] [0000081c] libusbx: debug [linux_get_device_address] getting address for device: 1-6 detached: 0 [ 0.000905] [0000081c] libusbx: debug [linux_get_device_address] scan 1-6 [ 0.000921] [0000081c] libusbx: debug [linux_get_device_address] bus=1 dev=2 [ 0.000925] [0000081c] libusbx: debug [linux_enumerate_device] busnum 1 devaddr 2 session_id 258 [ 0.000929] [0000081c] libusbx: debug [linux_enumerate_device] allocating new device for 1/2 (session 258) [ 0.000944] [0000081c] libusbx: debug [linux_get_parent_info] Dev 0x7f6fad6fd5f0 (1-6) has parent 0x7f6fad6fdc20 (usb1) port 6 [ 0.000971] [0000081c] libusbx: debug [linux_get_device_address] getting address for device: usb2 detached: 0 [ 0.000976] [0000081c] libusbx: debug [linux_get_device_address] scan usb2 [ 0.000991] [0000081c] libusbx: debug [linux_get_device_address] bus=2 dev=1 [ 0.000995] [0000081c] libusbx: debug [linux_enumerate_device] busnum 2 devaddr 1 session_id 513 [ 0.000999] [0000081c] libusbx: debug [linux_enumerate_device] allocating new device for 2/1 (session 513) [ 0.001035] [0000081c] libusbx: debug [linux_get_device_address] getting address for device: 2-4 detached: 0 [ 0.001040] [0000081c] libusbx: debug [linux_get_device_address] scan 2-4 [ 0.001056] [0000081c] libusbx: debug [linux_get_device_address] bus=2 dev=2 [ 0.001061] [0000081c] libusbx: debug [linux_enumerate_device] busnum 2 devaddr 2 session_id 514 [ 0.001064] [0000081c] libusbx: debug [linux_enumerate_device] allocating new device for 2/2 (session 514) [ 0.001079] [0000081c] libusbx: debug [linux_get_parent_info] Dev 0x7f6fad6fd690 (2-4) has parent 0x7f6fad650db0 (usb2) port 4 [ 0.001105] [0000081c] libusbx: debug [usbi_add_pollfd] add fd 16 events 1 [ 0.001111] [0000081c] libusbx: debug [usbi_add_pollfd] add fd 18 events 1 [ 0.001117] [0000081c] libusbx: debug [usbi_io_init] using timerfd for timeouts [ 0.001121] [0000081c] libusbx: debug [usbi_add_pollfd] add fd 20 events 1 [ 0.079545] [0000081c] libusbx: debug [libusb_get_device_list] [ 0.079674] [0000081c] libusbx: debug [libusb_get_device_descriptor] [ 0.079695] [0000081c] libusbx: debug [libusb_get_device_descriptor] [ 0.079709] [0000081c] libusbx: debug [libusb_get_device_descriptor] [ 0.079722] [0000081c] libusbx: debug [libusb_get_device_descriptor] [ 0.079736] [0000081c] libusbx: debug [libusb_get_device_descriptor] [ 0.079749] [0000081c] libusbx: debug [libusb_get_device_descriptor] [ 0.079765] [0000081c] libusbx: debug [libusb_open] open 1.19 [ 0.079821] [0000081c] libusbx: debug [usbi_add_pollfd] add fd 22 events 4 [ 0.079961] [0000081c] libusbx: debug [libusb_kernel_driver_active] interface 0 [ 0.079986] [0000081c] libusbx: error [op_kernel_driver_active] Get driver: ftdi_sio [ 0.080001] [0000081c] libusbx: debug [libusb_detach_kernel_driver] interface 0 [ 0.080279] [0000081c] libusbx: debug [libusb_kernel_driver_active] interface 1 [ 0.080326] [0000081c] libusbx: error [op_kernel_driver_active] Get driver: ftdi_sio [ 0.080342] [0000081c] libusbx: debug [libusb_detach_kernel_driver] interface 1 [ 0.080608] [0000081c] libusbx: debug [libusb_get_device_descriptor] [ 0.080738] [0000081c] libusbx: debug [libusb_get_config_descriptor] index 0 [ 0.080772] [0000081c] libusbx: debug [libusb_get_config_descriptor] index 1 [ 0.080796] [0000081c] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line) [ 0.080850] [0000081c] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.080870] [0000081c] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms [ 0.080891] [0000081c] libusbx: debug [handle_events] poll() returned 1 [ 0.080910] [0000081c] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=4 [ 0.080926] [0000081c] libusbx: debug [handle_control_completion] handling completion status 0 [ 0.080941] [0000081c] libusbx: debug [disarm_timerfd] [ 0.080957] [0000081c] libusbx: debug [usbi_handle_transfer_completion] transfer 0x7f6fad7002e8 has callback 0x7f6fa9540bd0 [ 0.080984] [0000081c] libusbx: debug [sync_transfer_cb] actual_length=4 [ 0.081010] [0000081c] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line) [ 0.081035] [0000081c] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.081050] [0000081c] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms [ 0.082213] [0000081c] libusbx: debug [handle_events] poll() returned 1 [ 0.082261] [0000081c] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=24 [ 0.082278] [0000081c] libusbx: debug [handle_control_completion] handling completion status 0 [ 0.082294] [0000081c] libusbx: debug [disarm_timerfd] [ 0.082310] [0000081c] libusbx: debug [usbi_handle_transfer_completion] transfer 0x7f6fad7002e8 has callback 0x7f6fa9540bd0 [ 0.082326] [0000081c] libusbx: debug [sync_transfer_cb] actual_length=24 [ 0.082359] [0000081c] libusbx: debug [libusb_get_device_descriptor] [ 0.141951] [0000081f] libusbx: debug [libusb_reset_device] [ 0.323290] [0000081c] libusbx: debug [libusb_reset_device] [ 0.323396] [0000081c] libusbx: debug [sysfs_get_active_config] device unconfigured [ 0.323402] [0000081c] libusbx: debug [libusb_close] [ 0.323408] [0000081c] libusbx: debug [usbi_remove_pollfd] remove fd 22 [ 0.323415] [0000081c] libusbx: debug [libusb_get_device_list] [ 0.323436] [0000081c] libusbx: debug [libusb_get_device_descriptor] [ 0.323439] [0000081c] libusbx: debug [libusb_get_device_descriptor] [ 0.323442] [0000081c] libusbx: debug [libusb_get_device_descriptor] [ 0.323445] [0000081c] libusbx: debug [libusb_get_device_descriptor] [ 0.323448] [0000081c] libusbx: debug [libusb_get_device_descriptor] [ 0.323451] [0000081c] libusbx: debug [libusb_get_device_descriptor] [ 0.323454] [0000081c] libusbx: debug [libusb_open] open 1.19 [ 0.323479] [0000081c] libusbx: error [_get_usbfs_fd] libusbx couldn't open USB device /dev/bus/usb/001/019: No such file or directory [ 0.323483] [0000081c] libusbx: debug [libusb_open] open 1.19 returns -4 [ 0.323486] [0000081c] libusbx: debug [libusb_get_device_descriptor] [ 0.323786] [00000824] libusbx: debug [linux_get_device_address] getting address for device: 1-10 detached: 1 [ 0.323798] [00000824] libusbx: debug [udev_hotplug_event] udev hotplug event. action: remove. [ 0.327153] [0000081c] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.327167] [0000081c] libusbx: debug [handle_events] poll() 3 fds with timeout in 0ms [ 0.327173] [0000081c] libusbx: debug [handle_events] poll() returned 1 [ 0.327176] [0000081c] libusbx: debug [handle_events] caught a fish on the hotplug pipe [ 0.327183] [0000081c] libusbx: debug [libusb_unref_device] destroy device 1.19 [ 0.458307] [00000824] libusbx: debug [linux_get_device_address] getting address for device: 1-10 detached: 0 [ 0.458370] [00000824] libusbx: debug [linux_get_device_address] scan 1-10 [ 0.458430] [00000824] libusbx: debug [linux_get_device_address] bus=1 dev=20 [ 0.458453] [00000824] libusbx: debug [udev_hotplug_event] udev hotplug event. action: add. [ 0.458470] [00000824] libusbx: debug [linux_enumerate_device] busnum 1 devaddr 20 session_id 276 [ 0.458487] [00000824] libusbx: debug [linux_enumerate_device] allocating new device for 1/20 (session 276) [ 0.458526] [00000824] libusbx: debug [linux_get_parent_info] Dev 0x7f6d88001ae0 (1-10) has parent 0x7f6fad6fdc20 (usb1) port 10 [ 0.458565] [0000081c] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.458572] [0000081c] libusbx: debug [handle_events] poll() 3 fds with timeout in 0ms [ 0.458576] [0000081c] libusbx: debug [handle_events] poll() returned 1 [ 0.458579] [0000081c] libusbx: debug [handle_events] caught a fish on the hotplug pipe [ 2.323468] [0000081c] libusbx: debug [libusb_get_device_list] [ 2.323479] [0000081c] libusbx: debug [libusb_get_device_descriptor] [ 2.323482] [0000081c] libusbx: debug [libusb_open] open 1.20 [ 2.323492] [0000081c] libusbx: debug [usbi_add_pollfd] add fd 22 events 4 [ 2.323534] [0000081c] libusbx: debug [libusb_kernel_driver_active] interface 0 [ 2.323536] [0000081c] libusbx: error [op_kernel_driver_active] Get driver: ftdi_sio [ 2.323538] [0000081c] libusbx: debug [libusb_detach_kernel_driver] interface 0 [ 2.323695] [0000081c] libusbx: debug [libusb_kernel_driver_active] interface 1 [ 2.323709] [0000081c] libusbx: error [op_kernel_driver_active] Get driver: ftdi_sio [ 2.323710] [0000081c] libusbx: debug [libusb_detach_kernel_driver] interface 1 [ 2.323922] [0000081c] libusbx: debug [libusb_get_device_descriptor] [ 2.323946] [0000081c] libusbx: debug [libusb_get_config_descriptor] index 0 [ 2.323948] [0000081c] libusbx: debug [libusb_get_config_descriptor] index 1 [ 2.323951] [0000081c] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line) [ 2.323957] [0000081c] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 2.323959] [0000081c] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms [ 2.324454] [0000081c] libusbx: debug [handle_events] poll() returned 1 [ 2.324460] [0000081c] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=4 [ 2.324462] [0000081c] libusbx: debug [handle_control_completion] handling completion status 0 [ 2.324464] [0000081c] libusbx: debug [disarm_timerfd] [ 2.324465] [0000081c] libusbx: debug [usbi_handle_transfer_completion] transfer 0x7f6fad67a3f8 has callback 0x7f6fa9540bd0 [ 2.324467] [0000081c] libusbx: debug [sync_transfer_cb] actual_length=4 [ 2.324470] [0000081c] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line) [ 2.324474] [0000081c] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 2.324475] [0000081c] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms [ 2.325679] [0000081c] libusbx: debug [handle_events] poll() returned 1 [ 2.325685] [0000081c] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=24 [ 2.325687] [0000081c] libusbx: debug [handle_control_completion] handling completion status 0 [ 2.325689] [0000081c] libusbx: debug [disarm_timerfd] [ 2.325690] [0000081c] libusbx: debug [usbi_handle_transfer_completion] transfer 0x7f6fad5ef7b8 has callback 0x7f6fa9540bd0 [ 2.325691] [0000081c] libusbx: debug [sync_transfer_cb] actual_length=24 [ 2.325696] [0000081c] libusbx: debug [libusb_get_device_descriptor] [ 2.325697] [0000081c] libusbx: debug [libusb_get_device_descriptor] [ 2.325698] [0000081c] libusbx: debug [libusb_get_device_descriptor] [ 2.325699] [0000081c] libusbx: debug [libusb_get_device_descriptor] [ 2.325700] [0000081c] libusbx: debug [libusb_get_device_descriptor] [ 2.325701] [0000081c] libusbx: debug [libusb_get_device_descriptor] And this is the latest qemu with the commit reverted (That doesn't reset unconfigured devices): ~/qemu-patched-install/bin$ sudo LD_LIBRARY_PATH=~/Devel/libusb-1.0.16-install/lib ./qemu-system-x86_64 -monitor telnet:127.0.0.1:1234,server,nowait -hda /dev/sdd2 -redir tcp:20022::22 --enable-kvm -cpu host -smp 4 -vga vmware --vnc :0 -m 8192 -usb -device usb-ehci,id=ehci -device usb-host,bus=ehci.0,vendorid=0x0403,productid=0x6010 WARNING: Image format was not specified for '/dev/sdd2' and probing guessed raw. Automatically detecting the format is dangerous for raw images, write operations on block 0 will be restricted. Specify the 'raw' format explicitly to remove the restrictions. [timestamp] [threadID] facility level [function call] -------------------------------------------------------------------------------- [ 0.000002] [00000838] libusbx: debug [libusb_init] created default context [ 0.000037] [00000838] libusbx: debug [libusb_init] libusbx v1.0.16.10774 [ 0.000059] [00000838] libusbx: debug [find_usbfs_path] found usbfs at /dev/bus/usb [ 0.000070] [00000838] libusbx: debug [op_init] bulk continuation flag supported [ 0.000073] [00000838] libusbx: debug [op_init] zero length packet flag supported [ 0.000082] [00000838] libusbx: debug [op_init] sysfs can relate devices [ 0.000086] [00000838] libusbx: debug [op_init] sysfs has complete descriptors [ 0.000326] [00000840] libusbx: debug [linux_udev_event_thread_main] udev event thread entering. [ 0.000468] [00000838] libusbx: debug [linux_get_device_address] getting address for device: usb1 detached: 0 [ 0.000476] [00000838] libusbx: debug [linux_get_device_address] scan usb1 [ 0.000500] [00000838] libusbx: debug [linux_get_device_address] bus=1 dev=1 [ 0.000528] [00000838] libusbx: debug [linux_enumerate_device] busnum 1 devaddr 1 session_id 257 [ 0.000531] [00000838] libusbx: debug [linux_enumerate_device] allocating new device for 1/1 (session 257) [ 0.000593] [00000838] libusbx: debug [linux_get_device_address] getting address for device: 1-10 detached: 0 [ 0.000598] [00000838] libusbx: debug [linux_get_device_address] scan 1-10 [ 0.000625] [00000838] libusbx: debug [linux_get_device_address] bus=1 dev=20 [ 0.000630] [00000838] libusbx: debug [linux_enumerate_device] busnum 1 devaddr 20 session_id 276 [ 0.000634] [00000838] libusbx: debug [linux_enumerate_device] allocating new device for 1/20 (session 276) [ 0.000663] [00000838] libusbx: debug [linux_get_parent_info] Dev 0x7fcb2532ebd0 (1-10) has parent 0x7fcb2532eb10 (usb1) port 10 [ 0.000728] [00000838] libusbx: debug [linux_get_device_address] getting address for device: 1-12 detached: 0 [ 0.000733] [00000838] libusbx: debug [linux_get_device_address] scan 1-12 [ 0.000760] [00000838] libusbx: debug [linux_get_device_address] bus=1 dev=13 [ 0.000765] [00000838] libusbx: debug [linux_enumerate_device] busnum 1 devaddr 13 session_id 269 [ 0.000768] [00000838] libusbx: debug [linux_enumerate_device] allocating new device for 1/13 (session 269) [ 0.000784] [00000838] libusbx: debug [linux_get_parent_info] Dev 0x7fcb2532ecb0 (1-12) has parent 0x7fcb2532eb10 (usb1) port 12 [ 0.000842] [00000838] libusbx: debug [linux_get_device_address] getting address for device: 1-4 detached: 0 [ 0.000847] [00000838] libusbx: debug [linux_get_device_address] scan 1-4 [ 0.000864] [00000838] libusbx: debug [linux_get_device_address] bus=1 dev=11 [ 0.000869] [00000838] libusbx: debug [linux_enumerate_device] busnum 1 devaddr 11 session_id 267 [ 0.000872] [00000838] libusbx: debug [linux_enumerate_device] allocating new device for 1/11 (session 267) [ 0.000889] [00000838] libusbx: debug [linux_get_parent_info] Dev 0x7fcb2532f6b0 (1-4) has parent 0x7fcb2532eb10 (usb1) port 4 [ 0.000918] [00000838] libusbx: debug [linux_get_device_address] getting address for device: 1-6 detached: 0 [ 0.000923] [00000838] libusbx: debug [linux_get_device_address] scan 1-6 [ 0.000940] [00000838] libusbx: debug [linux_get_device_address] bus=1 dev=2 [ 0.000944] [00000838] libusbx: debug [linux_enumerate_device] busnum 1 devaddr 2 session_id 258 [ 0.000948] [00000838] libusbx: debug [linux_enumerate_device] allocating new device for 1/2 (session 258) [ 0.000963] [00000838] libusbx: debug [linux_get_parent_info] Dev 0x7fcb2532f770 (1-6) has parent 0x7fcb2532eb10 (usb1) port 6 [ 0.000992] [00000838] libusbx: debug [linux_get_device_address] getting address for device: usb2 detached: 0 [ 0.000997] [00000838] libusbx: debug [linux_get_device_address] scan usb2 [ 0.001014] [00000838] libusbx: debug [linux_get_device_address] bus=2 dev=1 [ 0.001019] [00000838] libusbx: debug [linux_enumerate_device] busnum 2 devaddr 1 session_id 513 [ 0.001022] [00000838] libusbx: debug [linux_enumerate_device] allocating new device for 2/1 (session 513) [ 0.001060] [00000838] libusbx: debug [linux_get_device_address] getting address for device: 2-4 detached: 0 [ 0.001065] [00000838] libusbx: debug [linux_get_device_address] scan 2-4 [ 0.001082] [00000838] libusbx: debug [linux_get_device_address] bus=2 dev=2 [ 0.001087] [00000838] libusbx: debug [linux_enumerate_device] busnum 2 devaddr 2 session_id 514 [ 0.001090] [00000838] libusbx: debug [linux_enumerate_device] allocating new device for 2/2 (session 514) [ 0.001107] [00000838] libusbx: debug [linux_get_parent_info] Dev 0x7fcb252d02d0 (2-4) has parent 0x7fcb252d0230 (usb2) port 4 [ 0.001133] [00000838] libusbx: debug [usbi_add_pollfd] add fd 16 events 1 [ 0.001140] [00000838] libusbx: debug [usbi_add_pollfd] add fd 18 events 1 [ 0.001147] [00000838] libusbx: debug [usbi_io_init] using timerfd for timeouts [ 0.001150] [00000838] libusbx: debug [usbi_add_pollfd] add fd 20 events 1 [ 0.074566] [00000838] libusbx: debug [libusb_get_device_list] [ 0.074647] [00000838] libusbx: debug [libusb_get_device_descriptor] [ 0.074669] [00000838] libusbx: debug [libusb_get_device_descriptor] [ 0.074687] [00000838] libusbx: debug [libusb_get_device_descriptor] [ 0.074705] [00000838] libusbx: debug [libusb_get_device_descriptor] [ 0.074720] [00000838] libusbx: debug [libusb_get_device_descriptor] [ 0.074734] [00000838] libusbx: debug [libusb_get_device_descriptor] [ 0.074748] [00000838] libusbx: debug [libusb_open] open 1.20 [ 0.074804] [00000838] libusbx: debug [usbi_add_pollfd] add fd 22 events 4 [ 0.074962] [00000838] libusbx: debug [libusb_kernel_driver_active] interface 0 [ 0.074989] [00000838] libusbx: error [op_kernel_driver_active] Get driver: ftdi_sio [ 0.075006] [00000838] libusbx: debug [libusb_detach_kernel_driver] interface 0 [ 0.075311] [00000838] libusbx: debug [libusb_kernel_driver_active] interface 1 [ 0.075359] [00000838] libusbx: error [op_kernel_driver_active] Get driver: ftdi_sio [ 0.075375] [00000838] libusbx: debug [libusb_detach_kernel_driver] interface 1 [ 0.075641] [00000838] libusbx: debug [libusb_get_device_descriptor] [ 0.075749] [00000838] libusbx: debug [libusb_get_config_descriptor] index 0 [ 0.075761] [00000838] libusbx: debug [libusb_get_config_descriptor] index 1 [ 0.075775] [00000838] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line) [ 0.075819] [00000838] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.075827] [00000838] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms [ 0.075839] [00000838] libusbx: debug [handle_events] poll() returned 1 [ 0.075847] [00000838] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=4 [ 0.075854] [00000838] libusbx: debug [handle_control_completion] handling completion status 0 [ 0.075860] [00000838] libusbx: debug [disarm_timerfd] [ 0.075866] [00000838] libusbx: debug [usbi_handle_transfer_completion] transfer 0x7fcb25328998 has callback 0x7fcb22b21bd0 [ 0.075872] [00000838] libusbx: debug [sync_transfer_cb] actual_length=4 [ 0.075889] [00000838] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line) [ 0.075900] [00000838] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.075906] [00000838] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms [ 0.077092] [00000838] libusbx: debug [handle_events] poll() returned 1 [ 0.077144] [00000838] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=24 [ 0.077161] [00000838] libusbx: debug [handle_control_completion] handling completion status 0 [ 0.077177] [00000838] libusbx: debug [disarm_timerfd] [ 0.077192] [00000838] libusbx: debug [usbi_handle_transfer_completion] transfer 0x7fcb25328998 has callback 0x7fcb22b21bd0 [ 0.077208] [00000838] libusbx: debug [sync_transfer_cb] actual_length=24 [ 0.077244] [00000838] libusbx: debug [libusb_get_device_descriptor] [ 0.234815] [00000838] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 10000ms (first in line) [ 0.234924] [00000838] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.234933] [00000838] libusbx: debug [handle_events] poll() 4 fds with timeout in 0ms [ 0.234947] [00000838] libusbx: debug [handle_events] poll() returned 1 [ 0.234952] [00000838] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=8 [ 0.234955] [00000838] libusbx: debug [handle_control_completion] handling completion status 0 [ 0.234959] [00000838] libusbx: debug [disarm_timerfd] [ 0.234962] [00000838] libusbx: debug [usbi_handle_transfer_completion] transfer 0x7fcb252b5d98 has callback 0x7fcb24229710 [ 0.236001] [00000838] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 10000ms (first in line) [ 0.236133] [00000838] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.236143] [00000838] libusbx: debug [handle_events] poll() 4 fds with timeout in 0ms [ 0.236156] [00000838] libusbx: debug [handle_events] poll() returned 1 [ 0.236160] [00000838] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=9 [ 0.236164] [00000838] libusbx: debug [handle_control_completion] handling completion status 0 [ 0.236167] [00000838] libusbx: debug [disarm_timerfd] [ 0.236170] [00000838] libusbx: debug [usbi_handle_transfer_completion] transfer 0x7fcb2529f568 has callback 0x7fcb24229710 [ 0.237209] [00000838] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 10000ms (first in line) [ 0.237362] [00000838] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.237372] [00000838] libusbx: debug [handle_events] poll() 4 fds with timeout in 0ms [ 0.237385] [00000838] libusbx: debug [handle_events] poll() returned 1 [ 0.237389] [00000838] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=55 [ 0.237392] [00000838] libusbx: debug [handle_control_completion] handling completion status 0 [ 0.237395] [00000838] libusbx: debug [disarm_timerfd] [ 0.237408] [00000838] libusbx: debug [usbi_handle_transfer_completion] transfer 0x7fcb2527b358 has callback 0x7fcb24229710 [ 2.077254] [00000838] libusbx: debug [libusb_get_device_list] [ 2.077267] [00000838] libusbx: debug [libusb_get_device_descriptor] [ 2.077268] [00000838] libusbx: debug [libusb_get_device_descriptor] [ 2.077269] [00000838] libusbx: debug [libusb_get_device_descriptor] [ 2.077271] [00000838] libusbx: debug [libusb_get_device_descriptor] [ 2.077272] [00000838] libusbx: debug [libusb_get_device_descriptor] [ 2.077272] [00000838] libusbx: debug [libusb_get_device_descriptor] [ 2.077273] [00000838] libusbx: debug [libusb_get_device_descriptor] > Regards, > > Hans Best regards, Dennis