[Spice-devel] Spice-gtk usbredir
Hans de Goede
hdegoede at redhat.com
Fri Sep 30 07:50:21 PDT 2011
Hi,
On 09/30/2011 04:35 PM, nicolas prochazka wrote:
> Hello again,
> With usb mass storage it's working well,
> now i'm trying with Etoken NG Flash ( mass storage + authentification token )
>
> Bus 001 Device 034: ID 0529:3000 Aladdin Knowledge Systems
> libusb:debug [libusb_get_device_descriptor]
> Bus 001 Device 035: ID 0529:0620 Aladdin Knowledge Systems
>
> on host it's working well,
> on guest, lsusb show the same thing : Bus 001 Device 034: ID 0529:3000
> Aladdin Knowledge Systems
> but i can not mount it :
Hmm, some questions:
1) What does dmesg on the guest say?
2) Are you using the same kernel (version) on the guest and the host?
Also can you please change the debug=3 to debug=5 on the qemu cmdline,
redirect qemu's output to a log file! and then mail me the log file after
attempting to use the Etoken NG Flash again ?
Thanks,
Hans
>
>
>
>
>
> colossus:/tmp/TEST/spice-gtk # spicy
> libusb:debug [libusb_init]
> libusb:debug [find_usbfs_path] found usbfs at /dev/bus/usb
> libusb:debug [op_init] bulk continuation flag supported
> libusb:debug [op_init] found usb devices in sysfs
> libusb:debug [usbi_add_pollfd] add fd 6 events 1
> libusb:debug [usbi_io_init] using timerfd for timeouts
> libusb:debug [usbi_add_pollfd] add fd 8 events 1
> libusb:debug [libusb_get_device_list]
> libusb:debug [sysfs_scan_device] scan usb1
> libusb:debug [sysfs_scan_device] bus=1 dev=1
> libusb:debug [enumerate_device] busnum 1 devaddr 1 session_id 257
> libusb:debug [enumerate_device] allocating new device for 1/1 (session 257)
> libusb:debug [sysfs_scan_device] scan usb2
> libusb:debug [sysfs_scan_device] bus=2 dev=1
> libusb:debug [enumerate_device] busnum 2 devaddr 1 session_id 513
> libusb:debug [enumerate_device] allocating new device for 2/1 (session 513)
> libusb:debug [sysfs_scan_device] scan usb3
> libusb:debug [sysfs_scan_device] bus=3 dev=1
> libusb:debug [enumerate_device] busnum 3 devaddr 1 session_id 769
> libusb:debug [enumerate_device] allocating new device for 3/1 (session 769)
> libusb:debug [sysfs_scan_device] scan usb4
> libusb:debug [sysfs_scan_device] bus=4 dev=1
> libusb:debug [enumerate_device] busnum 4 devaddr 1 session_id 1025
> libusb:debug [enumerate_device] allocating new device for 4/1 (session 1025)
> libusb:debug [sysfs_scan_device] scan usb5
> libusb:debug [sysfs_scan_device] bus=5 dev=1
> libusb:debug [enumerate_device] busnum 5 devaddr 1 session_id 1281
> libusb:debug [enumerate_device] allocating new device for 5/1 (session 1281)
> libusb:debug [sysfs_scan_device] scan usb6
> libusb:debug [sysfs_scan_device] bus=6 dev=1
> libusb:debug [enumerate_device] busnum 6 devaddr 1 session_id 1537
> libusb:debug [enumerate_device] allocating new device for 6/1 (session 1537)
> libusb:debug [sysfs_scan_device] scan usb7
> libusb:debug [sysfs_scan_device] bus=7 dev=1
> libusb:debug [enumerate_device] busnum 7 devaddr 1 session_id 1793
> libusb:debug [enumerate_device] allocating new device for 7/1 (session 1793)
> libusb:debug [sysfs_scan_device] scan usb8
> libusb:debug [sysfs_scan_device] bus=8 dev=1
> libusb:debug [enumerate_device] busnum 8 devaddr 1 session_id 2049
> libusb:debug [enumerate_device] allocating new device for 8/1 (session 2049)
> libusb:debug [sysfs_scan_device] scan 2-2
> libusb:debug [sysfs_scan_device] bus=2 dev=3
> libusb:debug [enumerate_device] busnum 2 devaddr 3 session_id 515
> libusb:debug [enumerate_device] allocating new device for 2/3 (session 515)
> libusb:debug [discovered_devs_append] need to increase capacity
> libusb:debug [sysfs_scan_device] scan 4-2
> libusb:debug [sysfs_scan_device] bus=4 dev=2
> libusb:debug [enumerate_device] busnum 4 devaddr 2 session_id 1026
> libusb:debug [enumerate_device] allocating new device for 4/2 (session 1026)
> libusb:debug [sysfs_scan_device] scan 6-1
> libusb:debug [sysfs_scan_device] bus=6 dev=3
> libusb:debug [enumerate_device] busnum 6 devaddr 3 session_id 1539
> libusb:debug [enumerate_device] allocating new device for 6/3 (session 1539)
> libusb:debug [libusb_unref_device] destroy device 1.1
> libusb:debug [libusb_unref_device] destroy device 2.1
> libusb:debug [libusb_unref_device] destroy device 3.1
> libusb:debug [libusb_unref_device] destroy device 4.1
> libusb:debug [libusb_unref_device] destroy device 5.1
> libusb:debug [libusb_unref_device] destroy device 6.1
> libusb:debug [libusb_unref_device] destroy device 7.1
> libusb:debug [libusb_unref_device] destroy device 8.1
> GSpice-Message: main channel: failed to connect
> GSpice-Message: main channel: opened
>
> (spicy:12220): GSpice-WARNING **: PulseAudio context failed Connection refused
> libusb:debug [libusb_get_device_list]
> libusb:debug [sysfs_scan_device] scan usb1
> libusb:debug [sysfs_scan_device] bus=1 dev=1
> libusb:debug [enumerate_device] busnum 1 devaddr 1 session_id 257
> libusb:debug [enumerate_device] allocating new device for 1/1 (session 257)
> libusb:debug [sysfs_scan_device] scan usb2
> libusb:debug [sysfs_scan_device] bus=2 dev=1
> libusb:debug [enumerate_device] busnum 2 devaddr 1 session_id 513
> libusb:debug [enumerate_device] allocating new device for 2/1 (session 513)
> libusb:debug [sysfs_scan_device] scan usb3
> libusb:debug [sysfs_scan_device] bus=3 dev=1
> libusb:debug [enumerate_device] busnum 3 devaddr 1 session_id 769
> libusb:debug [enumerate_device] allocating new device for 3/1 (session 769)
> libusb:debug [sysfs_scan_device] scan usb4
> libusb:debug [sysfs_scan_device] bus=4 dev=1
> libusb:debug [enumerate_device] busnum 4 devaddr 1 session_id 1025
> libusb:debug [enumerate_device] allocating new device for 4/1 (session 1025)
> libusb:debug [sysfs_scan_device] scan usb5
> libusb:debug [sysfs_scan_device] bus=5 dev=1
> libusb:debug [enumerate_device] busnum 5 devaddr 1 session_id 1281
> libusb:debug [enumerate_device] allocating new device for 5/1 (session 1281)
> libusb:debug [sysfs_scan_device] scan usb6
> libusb:debug [sysfs_scan_device] bus=6 dev=1
> libusb:debug [enumerate_device] busnum 6 devaddr 1 session_id 1537
> libusb:debug [enumerate_device] allocating new device for 6/1 (session 1537)
> libusb:debug [sysfs_scan_device] scan usb7
> libusb:debug [sysfs_scan_device] bus=7 dev=1
> libusb:debug [enumerate_device] busnum 7 devaddr 1 session_id 1793
> libusb:debug [enumerate_device] allocating new device for 7/1 (session 1793)
> libusb:debug [sysfs_scan_device] scan usb8
> libusb:debug [sysfs_scan_device] bus=8 dev=1
> libusb:debug [enumerate_device] busnum 8 devaddr 1 session_id 2049
> libusb:debug [enumerate_device] allocating new device for 8/1 (session 2049)
> libusb:debug [sysfs_scan_device] scan 2-2
> libusb:debug [sysfs_scan_device] bus=2 dev=3
> libusb:debug [enumerate_device] busnum 2 devaddr 3 session_id 515
> libusb:debug [enumerate_device] using existing device for 2/3 (session 515)
> libusb:debug [discovered_devs_append] need to increase capacity
> libusb:debug [sysfs_scan_device] scan 4-2
> libusb:debug [sysfs_scan_device] bus=4 dev=2
> libusb:debug [enumerate_device] busnum 4 devaddr 2 session_id 1026
> libusb:debug [enumerate_device] using existing device for 4/2 (session 1026)
> libusb:debug [sysfs_scan_device] scan 6-1
> libusb:debug [sysfs_scan_device] bus=6 dev=3
> libusb:debug [enumerate_device] busnum 6 devaddr 3 session_id 1539
> libusb:debug [enumerate_device] using existing device for 6/3 (session 1539)
> libusb:debug [sysfs_scan_device] scan 1-3
> libusb:debug [sysfs_scan_device] bus=1 dev=30
> libusb:debug [enumerate_device] busnum 1 devaddr 30 session_id 286
> libusb:debug [enumerate_device] allocating new device for 1/30 (session 286)
> libusb:debug [sysfs_scan_device] scan 1-3.1
> libusb:debug [sysfs_scan_device] bus=1 dev=31
> libusb:debug [enumerate_device] busnum 1 devaddr 31 session_id 287
> libusb:debug [enumerate_device] allocating new device for 1/31 (session 287)
> libusb:debug [sysfs_scan_device] scan 1-3.2
> libusb:debug [sysfs_scan_device] bus=1 dev=32
> libusb:debug [enumerate_device] busnum 1 devaddr 32 session_id 288
> libusb:debug [enumerate_device] allocating new device for 1/32 (session 288)
> libusb:debug [libusb_unref_device] destroy device 1.1
> libusb:debug [libusb_unref_device] destroy device 2.1
> libusb:debug [libusb_unref_device] destroy device 3.1
> libusb:debug [libusb_unref_device] destroy device 4.1
> libusb:debug [libusb_unref_device] destroy device 5.1
> libusb:debug [libusb_unref_device] destroy device 6.1
> libusb:debug [libusb_unref_device] destroy device 7.1
> libusb:debug [libusb_unref_device] destroy device 8.1
> libusb:debug [libusb_unref_device] destroy device 1.30
> libusb:debug [libusb_unref_device] destroy device 1.31
> libusb:debug [libusb_open] open 1.32
> libusb:debug [usbi_add_pollfd] add fd 17 events 4
> libusb:debug [libusb_get_configuration]
> libusb:debug [libusb_get_configuration] active config 1
> libusb:debug [libusb_get_device_descriptor]
> libusb:debug [usbi_get_config_index_by_value] value 1
> libusb:debug [libusb_get_config_descriptor] index 0
> libusb:debug [libusb_detach_kernel_driver] interface 0
> libusb:debug [libusb_claim_interface] interface 0
> libusb:debug [libusb_get_device_list]
> libusb:debug [sysfs_scan_device] scan usb1
> libusb:debug [sysfs_scan_device] bus=1 dev=1
> libusb:debug [enumerate_device] busnum 1 devaddr 1 session_id 257
> libusb:debug [enumerate_device] allocating new device for 1/1 (session 257)
> libusb:debug [sysfs_scan_device] scan usb2
> libusb:debug [sysfs_scan_device] bus=2 dev=1
> libusb:debug [enumerate_device] busnum 2 devaddr 1 session_id 513
> libusb:debug [enumerate_device] allocating new device for 2/1 (session 513)
> libusb:debug [sysfs_scan_device] scan usb3
> libusb:debug [sysfs_scan_device] bus=3 dev=1
> libusb:debug [enumerate_device] busnum 3 devaddr 1 session_id 769
> libusb:debug [enumerate_device] allocating new device for 3/1 (session 769)
> libusb:debug [sysfs_scan_device] scan usb4
> libusb:debug [sysfs_scan_device] bus=4 dev=1
> libusb:debug [enumerate_device] busnum 4 devaddr 1 session_id 1025
> libusb:debug [enumerate_device] allocating new device for 4/1 (session 1025)
> libusb:debug [sysfs_scan_device] scan usb5
> libusb:debug [sysfs_scan_device] bus=5 dev=1
> libusb:debug [enumerate_device] busnum 5 devaddr 1 session_id 1281
> libusb:debug [enumerate_device] allocating new device for 5/1 (session 1281)
> libusb:debug [sysfs_scan_device] scan usb6
> libusb:debug [sysfs_scan_device] bus=6 dev=1
> libusb:debug [enumerate_device] busnum 6 devaddr 1 session_id 1537
> libusb:debug [enumerate_device] allocating new device for 6/1 (session 1537)
> libusb:debug [sysfs_scan_device] scan usb7
> libusb:debug [sysfs_scan_device] bus=7 dev=1
> libusb:debug [enumerate_device] busnum 7 devaddr 1 session_id 1793
> libusb:debug [enumerate_device] allocating new device for 7/1 (session 1793)
> libusb:debug [sysfs_scan_device] scan usb8
> libusb:debug [sysfs_scan_device] bus=8 dev=1
> libusb:debug [enumerate_device] busnum 8 devaddr 1 session_id 2049
> libusb:debug [enumerate_device] allocating new device for 8/1 (session 2049)
> libusb:debug [sysfs_scan_device] scan 2-2
> libusb:debug [sysfs_scan_device] bus=2 dev=3
> libusb:debug [enumerate_device] busnum 2 devaddr 3 session_id 515
> libusb:debug [enumerate_device] using existing device for 2/3 (session 515)
> libusb:debug [discovered_devs_append] need to increase capacity
> libusb:debug [sysfs_scan_device] scan 4-2
> libusb:debug [sysfs_scan_device] bus=4 dev=2
> libusb:debug [enumerate_device] busnum 4 devaddr 2 session_id 1026
> libusb:debug [enumerate_device] using existing device for 4/2 (session 1026)
> libusb:debug [sysfs_scan_device] scan 6-1
> libusb:debug [sysfs_scan_device] bus=6 dev=3
> libusb:debug [enumerate_device] busnum 6 devaddr 3 session_id 1539
> libusb:debug [enumerate_device] using existing device for 6/3 (session 1539)
> libusb:debug [sysfs_scan_device] scan 1-3
> libusb:debug [sysfs_scan_device] bus=1 dev=30
> libusb:debug [enumerate_device] busnum 1 devaddr 30 session_id 286
> libusb:debug [enumerate_device] allocating new device for 1/30 (session 286)
> libusb:debug [sysfs_scan_device] scan 1-3.1
> libusb:debug [sysfs_scan_device] bus=1 dev=31
> libusb:debug [enumerate_device] busnum 1 devaddr 31 session_id 287
> libusb:debug [enumerate_device] allocating new device for 1/31 (session 287)
> libusb:debug [sysfs_scan_device] scan 1-3.2
> libusb:debug [sysfs_scan_device] bus=1 dev=32
> libusb:debug [enumerate_device] busnum 1 devaddr 32 session_id 288
> libusb:debug [enumerate_device] using existing device for 1/32 (session 288)
> libusb:debug [libusb_unref_device] destroy device 1.1
> libusb:debug [libusb_unref_device] destroy device 2.1
> libusb:debug [libusb_unref_device] destroy device 3.1
> libusb:debug [libusb_unref_device] destroy device 4.1
> libusb:debug [libusb_unref_device] destroy device 5.1
> libusb:debug [libusb_unref_device] destroy device 6.1
> libusb:debug [libusb_unref_device] destroy device 7.1
> libusb:debug [libusb_unref_device] destroy device 8.1
> libusb:debug [libusb_unref_device] destroy device 1.30
> libusb:debug [libusb_open] open 1.31
> libusb:debug [usbi_add_pollfd] add fd 18 events 4
> libusb:debug [libusb_get_configuration]
> libusb:debug [libusb_get_configuration] active config 1
> libusb:debug [libusb_get_device_descriptor]
> libusb:debug [usbi_get_config_index_by_value] value 1
> libusb:debug [libusb_get_config_descriptor] index 0
> libusb:debug [libusb_detach_kernel_driver] interface 0
> libusb:debug [libusb_claim_interface] interface 0
> libusb:debug [libusb_reset_device]
> libusb:debug [libusb_reset_device]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=18
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_reset_device]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=18
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=9
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=32
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=4
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=28
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=16
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=42
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_reset_device]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=18
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_reset_device]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=18
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=-32 transferred=0
> libusb:debug [handle_control_completion] handling completion status -32
> libusb:debug [handle_control_completion] unsupported control request
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=-32 transferred=0
> libusb:debug [handle_control_completion] handling completion status -32
> libusb:debug [handle_control_completion] unsupported control request
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=-32 transferred=0
> libusb:debug [handle_control_completion] handling completion status -32
> libusb:debug [handle_control_completion] unsupported control request
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=9
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=93
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=4
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=18
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=16
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=22
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=30
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=4
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=30
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=1
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 36
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=36
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 36
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=36
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 18
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=18
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 18
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=18
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 128
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=-121 transferred=35
> libusb:debug [handle_bulk_completion] handling completion status -121
> of bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=-32 transferred=0
> libusb:debug [handle_bulk_completion] handling completion status -32
> of bulk urb 1/1
> libusb:debug [handle_bulk_completion] detected endpoint stall
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=0
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [handle_events] timerfd triggered
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_cancel_transfer]
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=-2 transferred=0
> libusb:debug [handle_bulk_completion] handling completion status -2 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] abnormal reap: urb status -2
> libusb:debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
> libusb:debug [usbi_handle_transfer_cancellation] detected timeout cancellation
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_reset_device]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=18
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_reset_device]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=18
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=32
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=42
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=-32 transferred=0
> libusb:debug [handle_bulk_completion] handling completion status -32
> of bulk urb 1/1
> libusb:debug [handle_bulk_completion] detected endpoint stall
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=0
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [handle_events] timerfd triggered
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_cancel_transfer]
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=-2 transferred=0
> libusb:debug [handle_bulk_completion] handling completion status -2 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] abnormal reap: urb status -2
> libusb:debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
> libusb:debug [usbi_handle_transfer_cancellation] detected timeout cancellation
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_reset_device]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=18
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_reset_device]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=18
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=32
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=42
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=-32 transferred=0
> libusb:debug [handle_bulk_completion] handling completion status -32
> of bulk urb 1/1
> libusb:debug [handle_bulk_completion] detected endpoint stall
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=0
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [handle_events] timerfd triggered
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_cancel_transfer]
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=-2 transferred=0
> libusb:debug [handle_bulk_completion] handling completion status -2 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] abnormal reap: urb status -2
> libusb:debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
> libusb:debug [usbi_handle_transfer_cancellation] detected timeout cancellation
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_reset_device]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=18
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_reset_device]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=18
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=32
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=42
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=-32 transferred=0
> libusb:debug [handle_bulk_completion] handling completion status -32
> of bulk urb 1/1
> libusb:debug [handle_bulk_completion] detected endpoint stall
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=0
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [handle_events] timerfd triggered
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_cancel_transfer]
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=-2 transferred=0
> libusb:debug [handle_bulk_completion] handling completion status -2 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] abnormal reap: urb status -2
> libusb:debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
> libusb:debug [usbi_handle_transfer_cancellation] detected timeout cancellation
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_reset_device]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=18
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_reset_device]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=18
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=32
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=42
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 18
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=18
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=-32 transferred=0
> libusb:debug [handle_bulk_completion] handling completion status -32
> of bulk urb 1/1
> libusb:debug [handle_bulk_completion] detected endpoint stall
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=0
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 18
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=18
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=8
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 192
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=-121 transferred=36
> libusb:debug [handle_bulk_completion] handling completion status -121
> of bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 12
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=12
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 4
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=4
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 12
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=12
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 36
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=36
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 12
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=12
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 18
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=18
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=8
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 2
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=2
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=8
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 34
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=34
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 192
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=-121 transferred=36
> libusb:debug [handle_bulk_completion] handling completion status -121
> of bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=-32 transferred=0
> libusb:debug [handle_bulk_completion] handling completion status -32
> of bulk urb 1/1
> libusb:debug [handle_bulk_completion] detected endpoint stall
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=0
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 18
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=18
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 4
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=4
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 12
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=12
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 36
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=36
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with
> length 4096
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=4096
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 36
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=36
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=13
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 31
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=0 transferred=31
> libusb:debug [handle_bulk_completion] handling completion status 0 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] last URB in transfer --> complete!
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=-32 transferred=0
> libusb:debug [handle_bulk_completion] handling completion status -32
> of bulk urb 1/1
> libusb:debug [handle_bulk_completion] detected endpoint stall
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=2 status=0 transferred=0
> libusb:debug [handle_control_completion] handling completion status 0
> libusb:debug [disarm_timerfd]
> libusb:debug [submit_bulk_transfer] need 1 urbs for new transfer with length 13
> libusb:debug [libusb_submit_transfer] arm timerfd for timeout in
> 5000ms (first in line)
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [handle_events] timerfd triggered
> libusb:debug [disarm_timerfd]
> libusb:debug [libusb_cancel_transfer]
> libusb:debug [handle_events] poll() 4 fds with timeout in 0ms
> libusb:debug [handle_events] poll() returned 1
> libusb:debug [reap_for_handle] urb type=3 status=-2 transferred=0
> libusb:debug [handle_bulk_completion] handling completion status -2 of
> bulk urb 1/1
> libusb:debug [handle_bulk_completion] abnormal reap: urb status -2
> libusb:debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
> libusb:debug [usbi_handle_transfer_cancellation] detected timeout cancellation
> libusb:debug [disarm_timerfd]
>
> 2011/9/30 nicolas prochazka<prochazka.nicolas at gmail.com>:
>> you're right, it's running now
>> Regard,
>> Nicolas
>>
>> 2011/9/30 Hans de Goede<hdegoede at redhat.com>
>>>
>>> Hi,
>>>
>>> On 09/30/2011 03:32 PM, nicolas prochazka wrote:
>>>>
>>>> Hello,
>>>> no change , usb stick not seen, new idea ?
>>>>
>>>> 1 )
>>>>
>>>> /usr/local/bin/qemu -name M_fedora15 -spice port=11982,disable-ticketing -soundhw hda -vga qxl*-readconfig /tmp/ehci * -device virtio-serial -chardev spicevmc,id=vdagent,name=vdagent -device virtserialport,chardev=vdagent,name=com.redhat.spice.0 -cpu kvm64 -localtime -vnc 10.98.98.1:152<http://10.98.98.1:152> -monitor tcp:127.0.0.1:10152<http://127.0.0.1:10152>,server,nowait,nodelay -m 2048 -pidfile /var/run/qemu/M_fedora15.pid -net tap,vlan=5,name=externe,script=/etc/qemu-ifEup,downscript=/etc/qemu-ifEdown,ifname=vmEtap52 -net nic,vlan=5,macaddr=ac:de:49:29:58:26,model=rtl8139 -drive
>>>> file=/swapfile-guest/swap1,if=ide,index=1,media=disk,snapshot=on -rtc base=localtime -drive file=/mnt/vdisk/images/MASTER-fedora15.1317382859.042259.MASTER,index=0,media=disk,snapshot=off,cache=writeback -drive file=/swapfile-guest/swap1,if=ide,index=2,media=disk,snapshot=on,boot=off -drive file=/mnt/vdisk/iso/Fedora-15-i686-Live-Desktop.iso,index=3,media=cdrom -boot d -fda fat:floppy:/mnt/vdisk/diskconf/M_fedora15 -chardev spicevmc,name=usbredir,id=usbredirchardev1 -device usb-redir,chardev=usbredirchardev1,id=usbredirdev1,debug=3 -chardev spicevmc,name=usbredir,id=usbredirchardev2 -device
>>>> usb-redir,chardev=usbredirchardev2,id=usbredirdev2,debug=3 -chardev spicevmc,name=usbredir,id=usbredirchardev3 -device usb-redir,chardev=usbredirchardev3,id=usbredirdev3,debug=3
>>>>
>>>> 2 ) automounting is disable , just notifier of new usb plug.
>>>> Keyboard focus : I write into xterm under the fedora guest. : vm having keyboard focus
>>>> auto redirect usb devices checkbox checked : yes
>>>>
>>>
>>> Here:
>>>
>>>> (spicy:23239): GLib-GObject-WARNING **: g_object_set_valist: object class `SpiceDisplay' has no property named `auto-usbredir'
>>>
>>> Is a clue to your problem. Likely you've an older version of spice-gtk installed in another libdir then
>>> where you installed the latest version, run ldd un spicy, ie:
>>> ldd /usr/local/bin/spicy
>>>
>>> And see which libspice-client-* it is using. If the one used is not at the location where
>>> you did the latest make install (I assume you did do a make install, right?) you can
>>> set LD_LIBRARY_PATH to point to point to the latest, ie:
>>> export LD_LIBRARY_PATH=/usr/local/lib
>>> ldd /usr/local/bin/spicy
>>>
>>> The ldd command is to check the LD_LIBRARY_PATH setting helps, note that setting
>>> LD_LIBRARY_PATH won't work over sudo / su, so first do
>>> su -
>>> or:
>>> sudo su -
>>> and then set the LD_LIBRARY_PATH and run spicy without sudo.
>>>
>>> Regards,
>>>
>>> Hans
>>>
>>>
>>>
>>>> Regards,
>>>> Nicolas Prochazka.
>>>>
>>>>
>>>> ^Ccolossus:~ # spicy
>>>> libusb:debug [libusb_init]
>>>> libusb:debug [find_usbfs_path] found usbfs at /dev/bus/usb
>>>> libusb:debug [op_init] bulk continuation flag supported
>>>> libusb:debug [op_init] found usb devices in sysfs
>>>> libusb:debug [usbi_add_pollfd] add fd 6 events 1
>>>> libusb:debug [usbi_io_init] using timerfd for timeouts
>>>> libusb:debug [usbi_add_pollfd] add fd 8 events 1
>>>> libusb:debug [libusb_get_device_list]
>>>> libusb:debug [sysfs_scan_device] scan usb1
>>>> libusb:debug [sysfs_scan_device] bus=1 dev=1
>>>> libusb:debug [enumerate_device] busnum 1 devaddr 1 session_id 257
>>>> libusb:debug [enumerate_device] allocating new device for 1/1 (session 257)
>>>> libusb:debug [sysfs_scan_device] scan usb2
>>>> libusb:debug [sysfs_scan_device] bus=2 dev=1
>>>> libusb:debug [enumerate_device] busnum 2 devaddr 1 session_id 513
>>>> libusb:debug [enumerate_device] allocating new device for 2/1 (session 513)
>>>> libusb:debug [sysfs_scan_device] scan usb3
>>>> libusb:debug [sysfs_scan_device] bus=3 dev=1
>>>> libusb:debug [enumerate_device] busnum 3 devaddr 1 session_id 769
>>>> libusb:debug [enumerate_device] allocating new device for 3/1 (session 769)
>>>> libusb:debug [sysfs_scan_device] scan usb4
>>>> libusb:debug [sysfs_scan_device] bus=4 dev=1
>>>> libusb:debug [enumerate_device] busnum 4 devaddr 1 session_id 1025
>>>> libusb:debug [enumerate_device] allocating new device for 4/1 (session 1025)
>>>> libusb:debug [sysfs_scan_device] scan usb5
>>>> libusb:debug [sysfs_scan_device] bus=5 dev=1
>>>> libusb:debug [enumerate_device] busnum 5 devaddr 1 session_id 1281
>>>> libusb:debug [enumerate_device] allocating new device for 5/1 (session 1281)
>>>> libusb:debug [sysfs_scan_device] scan usb6
>>>> libusb:debug [sysfs_scan_device] bus=6 dev=1
>>>> libusb:debug [enumerate_device] busnum 6 devaddr 1 session_id 1537
>>>> libusb:debug [enumerate_device] allocating new device for 6/1 (session 1537)
>>>> libusb:debug [sysfs_scan_device] scan usb7
>>>> libusb:debug [sysfs_scan_device] bus=7 dev=1
>>>> libusb:debug [enumerate_device] busnum 7 devaddr 1 session_id 1793
>>>> libusb:debug [enumerate_device] allocating new device for 7/1 (session 1793)
>>>> libusb:debug [sysfs_scan_device] scan usb8
>>>> libusb:debug [sysfs_scan_device] bus=8 dev=1
>>>> libusb:debug [enumerate_device] busnum 8 devaddr 1 session_id 2049
>>>> libusb:debug [enumerate_device] allocating new device for 8/1 (session 2049)
>>>> libusb:debug [sysfs_scan_device] scan 2-2
>>>> libusb:debug [sysfs_scan_device] bus=2 dev=3
>>>> libusb:debug [enumerate_device] busnum 2 devaddr 3 session_id 515
>>>> libusb:debug [enumerate_device] allocating new device for 2/3 (session 515)
>>>> libusb:debug [discovered_devs_append] need to increase capacity
>>>> libusb:debug [sysfs_scan_device] scan 4-2
>>>> libusb:debug [sysfs_scan_device] bus=4 dev=2
>>>> libusb:debug [enumerate_device] busnum 4 devaddr 2 session_id 1026
>>>> libusb:debug [enumerate_device] allocating new device for 4/2 (session 1026)
>>>> libusb:debug [sysfs_scan_device] scan 6-1
>>>> libusb:debug [sysfs_scan_device] bus=6 dev=3
>>>> libusb:debug [enumerate_device] busnum 6 devaddr 3 session_id 1539
>>>> libusb:debug [enumerate_device] allocating new device for 6/3 (session 1539)
>>>> libusb:debug [sysfs_scan_device] scan 1-3
>>>> libusb:debug [sysfs_scan_device] bus=1 dev=11
>>>> libusb:debug [enumerate_device] busnum 1 devaddr 11 session_id 267
>>>> libusb:debug [enumerate_device] allocating new device for 1/11 (session 267)
>>>> libusb:debug [libusb_unref_device] destroy device 1.1
>>>> libusb:debug [libusb_unref_device] destroy device 2.1
>>>> libusb:debug [libusb_unref_device] destroy device 3.1
>>>> libusb:debug [libusb_unref_device] destroy device 4.1
>>>> libusb:debug [libusb_unref_device] destroy device 5.1
>>>> libusb:debug [libusb_unref_device] destroy device 6.1
>>>> libusb:debug [libusb_unref_device] destroy device 7.1
>>>> libusb:debug [libusb_unref_device] destroy device 8.1
>>>> GSpice-Message: main channel: failed to connect
>>>> GSpice-Message: main channel: opened
>>>>
>>>> (spicy:23239): GLib-GObject-WARNING **: g_object_set_valist: object class `SpiceDisplay' has no property named `auto-usbredir'
>>>>
>>>> (spicy:23239): GLib-GObject-WARNING **: g_object_get_valist: object class `SpiceDisplay' has no property named `auto-usbredir'
>>>>
>>>> (spicy:23239): GLib-GObject-WARNING **: g_object_set_valist: object class `SpiceDisplay' has no property named `auto-usbredir'
>>>>
>>>> (spicy:23239): GSpice-WARNING **: PulseAudio context failed Connection refused
>>>> libusb:debug [libusb_unref_device] destroy device 1.11
>>>>
>>>> (spicy:23239): GLib-GObject-WARNING **: g_object_set_valist: object class `SpiceDisplay' has no property named `auto-usbredir'
>>>>
>>>> (spicy:23239): GLib-GObject-WARNING **: g_object_set_valist: object class `SpiceDisplay' has no property named `auto-usbredir'
>>>> libusb:debug [libusb_get_device_list]
>>>> libusb:debug [sysfs_scan_device] scan usb1
>>>> libusb:debug [sysfs_scan_device] bus=1 dev=1
>>>> libusb:debug [enumerate_device] busnum 1 devaddr 1 session_id 257
>>>> libusb:debug [enumerate_device] allocating new device for 1/1 (session 257)
>>>> libusb:debug [sysfs_scan_device] scan usb2
>>>> libusb:debug [sysfs_scan_device] bus=2 dev=1
>>>> libusb:debug [enumerate_device] busnum 2 devaddr 1 session_id 513
>>>> libusb:debug [enumerate_device] allocating new device for 2/1 (session 513)
>>>> libusb:debug [sysfs_scan_device] scan usb3
>>>> libusb:debug [sysfs_scan_device] bus=3 dev=1
>>>> libusb:debug [enumerate_device] busnum 3 devaddr 1 session_id 769
>>>> libusb:debug [enumerate_device] allocating new device for 3/1 (session 769)
>>>> libusb:debug [sysfs_scan_device] scan usb4
>>>> libusb:debug [sysfs_scan_device] bus=4 dev=1
>>>> libusb:debug [enumerate_device] busnum 4 devaddr 1 session_id 1025
>>>> libusb:debug [enumerate_device] allocating new device for 4/1 (session 1025)
>>>> libusb:debug [sysfs_scan_device] scan usb5
>>>> libusb:debug [sysfs_scan_device] bus=5 dev=1
>>>> libusb:debug [enumerate_device] busnum 5 devaddr 1 session_id 1281
>>>> libusb:debug [enumerate_device] allocating new device for 5/1 (session 1281)
>>>> libusb:debug [sysfs_scan_device] scan usb6
>>>> libusb:debug [sysfs_scan_device] bus=6 dev=1
>>>> libusb:debug [enumerate_device] busnum 6 devaddr 1 session_id 1537
>>>> libusb:debug [enumerate_device] allocating new device for 6/1 (session 1537)
>>>> libusb:debug [sysfs_scan_device] scan usb7
>>>> libusb:debug [sysfs_scan_device] bus=7 dev=1
>>>> libusb:debug [enumerate_device] busnum 7 devaddr 1 session_id 1793
>>>> libusb:debug [enumerate_device] allocating new device for 7/1 (session 1793)
>>>> libusb:debug [sysfs_scan_device] scan usb8
>>>> libusb:debug [sysfs_scan_device] bus=8 dev=1
>>>> libusb:debug [enumerate_device] busnum 8 devaddr 1 session_id 2049
>>>> libusb:debug [enumerate_device] allocating new device for 8/1 (session 2049)
>>>> libusb:debug [sysfs_scan_device] scan 2-2
>>>> libusb:debug [sysfs_scan_device] bus=2 dev=3
>>>> libusb:debug [enumerate_device] busnum 2 devaddr 3 session_id 515
>>>> libusb:debug [enumerate_device] using existing device for 2/3 (session 515)
>>>> libusb:debug [discovered_devs_append] need to increase capacity
>>>> libusb:debug [sysfs_scan_device] scan 4-2
>>>> libusb:debug [sysfs_scan_device] bus=4 dev=2
>>>> libusb:debug [enumerate_device] busnum 4 devaddr 2 session_id 1026
>>>> libusb:debug [enumerate_device] using existing device for 4/2 (session 1026)
>>>> libusb:debug [sysfs_scan_device] scan 6-1
>>>> libusb:debug [sysfs_scan_device] bus=6 dev=3
>>>> libusb:debug [enumerate_device] busnum 6 devaddr 3 session_id 1539
>>>> libusb:debug [enumerate_device] using existing device for 6/3 (session 1539)
>>>> libusb:debug [sysfs_scan_device] scan 1-3
>>>> libusb:debug [sysfs_scan_device] bus=1 dev=12
>>>> libusb:debug [enumerate_device] busnum 1 devaddr 12 session_id 268
>>>> libusb:debug [enumerate_device] allocating new device for 1/12 (session 268)
>>>> libusb:debug [libusb_unref_device] destroy device 1.1
>>>> libusb:debug [libusb_unref_device] destroy device 2.1
>>>> libusb:debug [libusb_unref_device] destroy device 3.1
>>>> libusb:debug [libusb_unref_device] destroy device 4.1
>>>> libusb:debug [libusb_unref_device] destroy device 5.1
>>>> libusb:debug [libusb_unref_device] destroy device 6.1
>>>> libusb:debug [libusb_unref_device] destroy device 7.1
>>>> libusb:debug [libusb_unref_device] destroy device 8.1
>>>>
>>>>
>>>>
>>>>
>>>>
>>>>
>>>>
>>>> /usr/local/bin/qemu -name M_fedora15 -spice port=11982,disable-ticketing -soundhw hda -vga qxl -readconfig /tmp/ehci -device virtio-serial -chardev spicevmc,id=vdagent,name=vdagent -device virtserialport,chardev=vdagent,name=com.redhat.spice.0 -cpu kvm64 -localtime -vnc 10.98.98.1:152<http://10.98.98.1:152> -monitor tcp:127.0.0.1:10152<http://127.0.0.1:10152>,server,nowait,nodelay -m 2048 -pidfile /var/run/qemu/M_fedora15.pid -net tap,vlan=5,name=externe,script=/etc/qemu-ifEup,downscript=/etc/qemu-ifEdown,ifname=vmEtap52 -net nic,vlan=5,macaddr=ac:de:49:29:58:26,model=rtl8139 -drive
>>>> file=/swapfile-guest/swap1,if=ide,index=1,media=disk,snapshot=on -rtc base=localtime -drive file=/mnt/vdisk/images/MASTER-fedora15.1317382859.042259.MASTER,index=0,media=disk,snapshot=off,cache=writeback -drive file=/swapfile-guest/swap1,if=ide,index=2,media=disk,snapshot=on,boot=off -drive file=/mnt/vdisk/iso/Fedora-15-i686-Live-Desktop.iso,index=3,media=cdrom -boot d -fda fat:floppy:/mnt/vdisk/diskconf/M_fedora15 -chardev spicevmc,name=usbredir,id=usbredirchardev1 -device usb-redir,chardev=usbredirchardev1,id=usbredirdev1,debug=3 -chardev spicevmc,name=usbredir,id=usbredirchardev2 -device
>>>> usb-redir,chardev=usbredirchardev2,id=usbredirdev2,debug=3 -chardev spicevmc,name=usbredir,id=usbredirchardev3 -device usb-redir,chardev=usbredirchardev3,id=usbredirdev3,debug=3
>>>> do_spice_init: starting 0.9.1
>>>> spice_server_add_interface: SPICE_INTERFACE_KEYBOARD
>>>> spice_server_add_interface: SPICE_INTERFACE_MOUSE
>>>> spice_server_add_interface: SPICE_INTERFACE_QXL
>>>> red_worker_main: begin
>>>> ensure_display_channel_created: create display channel
>>>> ensure_cursor_channel_created: create cursor channel
>>>> spice_server_add_interface: SPICE_INTERFACE_PLAYBACK
>>>> spice_server_add_interface: SPICE_INTERFACE_RECORD
>>>> *** EHCI support is under development ***
>>>> spice_server_char_device_add_interface: CHAR_DEVICE usbredir
>>>> spice_server_char_device_add_interface: CHAR_DEVICE usbredir
>>>> spice_server_char_device_add_interface: CHAR_DEVICE usbredir
>>>> handle_dev_input: start
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> reds_handle_main_link:
>>>> reds_disconnect:
>>>> reds_show_new_channel: channel 1:0, connected successfully, over Non Secure link
>>>> main_channel_link: add main channel client
>>>> reds_handle_main_link: NEW Client 0x2dd87f0 mcc 0x2df5440 connect-id 1804289383
>>>> main_channel_handle_parsed: net test: latency 0.629000 ms, bitrate 856903765 bps (817.207112 Mbps)
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> reds_show_new_channel: channel 6:0, connected successfully, over Non Secure link
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> reds_show_new_channel: channel 5:0, connected successfully, over Non Secure link
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> reds_show_new_channel: channel 2:0, connected successfully, over Non Secure link
>>>> red_dispatcher_set_display_peer:
>>>> handle_dev_input: connect
>>>> handle_new_display_channel: add display channel client
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> handle_new_display_channel: New display (client 0x2dd87f0) dcc 0x2e2d610 stream 0x2de9800
>>>> handle_new_display_channel: jpeg disabled
>>>> handle_new_display_channel: zlib-over-glz disabled
>>>> listen_to_new_client_channel: NEW ID = 0
>>>> reds_show_new_channel: channel 4:0, connected successfully, over Non Secure link
>>>> red_dispatcher_set_cursor_peer:
>>>> display_channel_client_wait_for_init: creating encoder with id == 0
>>>> handle_dev_input: cursor connect
>>>> red_connect_cursor: add cursor channel client
>>>> listen_to_new_client_channel: NEW ID = 0
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> reds_show_new_channel: channel 3:0, connected successfully, over Non Secure link
>>>> inputs_connect: inputs channel client create
>>>> display_channel_release_item: not pushed (101)
>>>> handle_dev_input: OOM1 #draw=6, #red_draw=60, #glz_draw=56 current 6 pipes 0
>>>> handle_dev_input: OOM2 #draw=6, #red_draw=60, #glz_draw=56 current 6 pipes 0
>>>> handle_dev_input: OOM1 #draw=7, #red_draw=61, #glz_draw=57 current 7 pipes 0
>>>> red_free_some: #draw=7, #red_draw=61, #glz_draw=57
>>>> handle_dev_input: OOM2 #draw=0, #red_draw=0, #glz_draw=0 current 0 pipes 0
>>>> spice_server_add_interface: SPICE_INTERFACE_TABLET
>>>> handle_dev_input: mouse mode 2
>>>> red_channel_client_disconnect: 0x2e2d610 (channel 0x204fae0 type 2 id 0)
>>>> snd_channel_put: sound channel freed
>>>>
>>>> red_channel_client_disconnect: 0x2df5440 (channel 0x2df6660 type 1 id 0)
>>>> display_channel_client_on_disconnect:
>>>> main_channel_client_on_disconnect: rcc=0x2df5440
>>>> reds_client_disconnect:
>>>> red_client_destroy: destroy client with #channels 4
>>>> red_channel_client_disconnect: 0x2e195a0 (channel 0x1fb3fe0 type 3 id 0)
>>>> red_dispatcher_disconnect_cursor_peer:
>>>> display_channel_client_on_disconnect: #draw=41, #red_draw=41, #glz_draw=0
>>>> red_channel_client_disconnect: 0x2e2bb90 (channel 0x2050060 type 4 id 0)
>>>> handle_dev_input: disconnect cursor client
>>>> red_channel_client_disconnect: 0x2e2bb90 (channel 0x2050060 type 4 id 0)
>>>> red_dispatcher_disconnect_display_peer:
>>>> handle_dev_input: disconnect display client
>>>> red_channel_client_disconnect: 0x2e2d610 (channel 0x204fae0 type 2 id 0)
>>>> red_channel_client_disconnect: 0x2df5440 (channel 0x2df6660 type 1 id 0)
>>>> snd_channel_put: sound channel freed
>>>>
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> reds_handle_main_link:
>>>> reds_disconnect:
>>>> reds_show_new_channel: channel 1:0, connected successfully, over Non Secure link
>>>> main_channel_link: add main channel client
>>>> reds_handle_main_link: NEW Client 0x2f9f530 mcc 0x2a2c800 connect-id 846930886
>>>> main_channel_handle_parsed: net test: latency 0.724000 ms, bitrate 980842911 bps (935.404693 Mbps)
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> reds_show_new_channel: channel 6:0, connected successfully, over Non Secure link
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> reds_show_new_channel: channel 5:0, connected successfully, over Non Secure link
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> reds_show_new_channel: channel 2:0, connected successfully, over Non Secure link
>>>> red_dispatcher_set_display_peer:
>>>> handle_dev_input: connect
>>>> handle_new_display_channel: add display channel client
>>>> handle_new_display_channel: New display (client 0x2f9f530) dcc 0x7fdfd8e14f40 stream 0x2a19db0
>>>> handle_new_display_channel: jpeg disabled
>>>> handle_new_display_channel: zlib-over-glz disabled
>>>> listen_to_new_client_channel: NEW ID = 0
>>>> display_channel_client_wait_for_init: creating encoder with id == 0
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> reds_show_new_channel: channel 4:0, connected successfully, over Non Secure link
>>>> red_dispatcher_set_cursor_peer:
>>>> handle_dev_input: cursor connect
>>>> red_connect_cursor: add cursor channel client
>>>> listen_to_new_client_channel: NEW ID = 0
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> reds_show_new_channel: channel 3:0, connected successfully, over Non Secure link
>>>> inputs_connect: inputs channel client create
>>>>
>>>>
>>>>
>>>>
>>>>
>>>>
>>>> 2011/9/30 Hans de Goede<hdegoede at redhat.com<mailto:hdegoede at redhat.com>>
>>>>
>>>> Hi,
>>>>
>>>>
>>>> On 09/30/2011 03:03 PM, nicolas prochazka wrote:
>>>>
>>>> Hello,
>>>> I'm trying to play with usb redirect , I read http://hansdegoede. livejournal.com/<http://hansdegoede.livejournal.com/> howto,
>>>> but I cannot see usb stick under my guest vm.a
>>>>
>>>> I think i do something wrong.
>>>>
>>>>
>>>> So 2 things stand out wrt your qemu cmdline, which explain part of the problem:
>>>> 1) You've the first -device usb-redir,... on the cmdline before the -readconfig /tmp/ehci
>>>> 2) You also pass the old -usb, this gives you a usb1 only bus, just drop the -usb please
>>>>
>>>>
>>>> ( I 've keyboard focus in guest vm, but when I plug my usb, my opensuse mount it , is it normal ? )
>>>>
>>>>
>>>> Yes and no, in my testing spice-gtk usually manages to grab the device before it gets
>>>> mounted. and even if it does get mounted, spice-gtk should "steal" it from the native
>>>> linux driver, and the mount should go away (unless it is busy).
>>>>
>>>> Is the auto redirect usb devices checkbox checked? And when you say has keyboard focus,
>>>> did you first click and type something inside the vm? If the spice-gtk menu-bar has
>>>> keyboard focus that does not count as the vm having keyboard focus.
>>>>
>>>> Regards,
>>>>
>>>> Hans
>>>>
>>>>
>>>>
>>>>
>>>>
>>>>
>>>>
>>>> Regards,
>>>> Nicolas Prochazka
>>>>
>>>> Log of qemu /spice , and spicy
>>>>
>>>> DEV-10.98.98.1:~# /usr/local/bin/qemu -name M_fedora15 *-spice port=11982,disable-ticketing -soundhw hda -vga qxl -device virtio-serial -chardev spicevmc,id=vdagent,name= vdagent -device virtserialport,chardev= vdagent,name=com.redhat.spice. 0* -cpu kvm64 -localtime -usb -vnc 10.98.98.1:152<http://10.98.98.1:152> <http://10.98.98.1:152> -monitor tcp:127.0.0.1:10152<http://127.0.0.1:10152> <http://127.0.0.1:10152>, server,nowait,nodelay -m 2048 -pidfile /var/run/qemu/M_fedora15.pid -net tap,vlan=5,name=externe, script=/etc/qemu-ifEup, downscript=/etc/qemu-ifEdown, ifname=vmEtap52
>>>> -net nic,vlan=5,macaddr=ac:de:49: 29:58:26,model=rtl8139 -drive
>>>> file=/swapfile-guest/swap1,if= ide,index=1,media=disk, snapshot=on -rtc base=localtime -drive file=/mnt/vdisk/images/MASTER- fedora15.1317382859.042259. MASTER,index=0,media=disk, snapshot=off,cache=writeback -drive file=/swapfile-guest/swap1,if= ide,index=2,media=disk, snapshot=on,boot=off -drive file=/mnt/vdisk/iso/Fedora-15- i686-Live-Desktop.iso,index=3, media=cdrom -boot d -fda fat:floppy:/mnt/vdisk/ diskconf/M_fedora15 *-chardev spicevmc,name=usbredir,id= usbredirchardev1 -device usb-redir,chardev= usbredirchardev1,id= usbredirdev1,debug=3 -readconfig /tmp/ehci -chardev
>>>> spicevmc,name=usbredir,id= usbredirchardev2 -device usb-redir,chardev= usbredirchardev2,id= usbredirdev2,debug=3 -chardev spicevmc,name=usbredir,id= usbredirchardev3 -device usb-redir,chardev= usbredirchardev3,id= usbredirdev3,debug=3*
>>>>
>>>>
>>>> do_spice_init: starting 0.9.1
>>>> spice_server_add_interface: SPICE_INTERFACE_KEYBOARD
>>>> spice_server_add_interface: SPICE_INTERFACE_MOUSE
>>>> spice_server_add_interface: SPICE_INTERFACE_QXL
>>>> red_worker_main: begin
>>>> ensure_display_channel_ created: create display channel
>>>> ensure_cursor_channel_created: create cursor channel
>>>> spice_server_add_interface: SPICE_INTERFACE_PLAYBACK
>>>> spice_server_add_interface: SPICE_INTERFACE_RECORD
>>>> spice_server_char_device_add_ interface: CHAR_DEVICE usbredir
>>>> *** EHCI support is under development ***
>>>> spice_server_char_device_add_ interface: CHAR_DEVICE usbredir
>>>> spice_server_char_device_add_ interface: CHAR_DEVICE usbredir
>>>> handle_dev_input: start
>>>> spice_server_add_interface: SPICE_INTERFACE_TABLET
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> reds_handle_main_link:
>>>> reds_disconnect:
>>>> reds_show_new_channel: channel 1:0, connected successfully, over Non Secure link
>>>> main_channel_link: add main channel client
>>>> reds_handle_main_link: NEW Client 0x2de06a0 mcc 0x2de77f0 connect-id 1804289383
>>>> handle_dev_input: mouse mode 2
>>>> main_channel_handle_parsed: net test: latency 0.676000 ms, bitrate 840722495 bps (801.775451 Mbps)
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> reds_show_new_channel: channel 6:0, connected successfully, over Non Secure link
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> reds_show_new_channel: channel 5:0, connected successfully, over Non Secure link
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> reds_show_new_channel: channel 2:0, connected successfully, over Non Secure link
>>>> red_dispatcher_set_display_ peer:
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> handle_dev_input: connect
>>>> handle_new_display_channel: add display channel client
>>>> handle_new_display_channel: New display (client 0x2de06a0) dcc 0x2e31640 stream 0x2de7500
>>>> handle_new_display_channel: jpeg disabled
>>>> handle_new_display_channel: zlib-over-glz disabled
>>>> listen_to_new_client_channel: NEW ID = 0
>>>> reds_show_new_channel: channel 4:0, connected successfully, over Non Secure link
>>>> red_dispatcher_set_cursor_ peer:
>>>> display_channel_client_wait_ for_init: creating encoder with id == 0
>>>> handle_dev_input: cursor connect
>>>> red_connect_cursor: add cursor channel client
>>>> listen_to_new_client_channel: NEW ID = 0
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> reds_show_new_channel: channel 3:0, connected successfully, over Non Secure link
>>>> inputs_connect: inputs channel client create
>>>> display_channel_release_item: not pushed (101)
>>>> handle_dev_input: OOM1 #draw=7, #red_draw=60, #glz_draw=56 current 7 pipes 0
>>>> handle_dev_input: OOM2 #draw=7, #red_draw=60, #glz_draw=56 current 7 pipes 0
>>>> handle_dev_input: OOM1 #draw=7, #red_draw=60, #glz_draw=56 current 7 pipes 0
>>>> red_free_some: #draw=7, #red_draw=60, #glz_draw=56
>>>> handle_dev_input: OOM2 #draw=0, #red_draw=0, #glz_draw=0 current 0 pipes 0
>>>> handle_dev_input: OOM1 #draw=24, #red_draw=3587, #glz_draw=3567 current 24 pipes 0
>>>> handle_dev_input: OOM2 #draw=24, #red_draw=3587, #glz_draw=3567 current 24 pipes 0
>>>> handle_dev_input: OOM1 #draw=24, #red_draw=3587, #glz_draw=3567 current 24 pipes 0
>>>> handle_dev_input: OOM2 #draw=24, #red_draw=3587, #glz_draw=3567 current 24 pipes 0
>>>> handle_dev_input: OOM1 #draw=24, #red_draw=3587, #glz_draw=3567 current 24 pipes 0
>>>> red_free_some: #draw=24, #red_draw=3587, #glz_draw=3567
>>>> handle_dev_input: OOM2 #draw=24, #red_draw=3523, #glz_draw=3503 current 24 pipes 0
>>>> handle_dev_input: OOM1 #draw=21, #red_draw=3905, #glz_draw=3888 current 21 pipes 0
>>>> handle_dev_input: OOM2 #draw=21, #red_draw=3905, #glz_draw=3888 current 21 pipes 0
>>>> handle_dev_input: OOM1 #draw=21, #red_draw=3905, #glz_draw=3888 current 21 pipes 0
>>>> handle_dev_input: OOM2 #draw=21, #red_draw=3905, #glz_draw=3888 current 21 pipes 0
>>>> handle_dev_input: OOM1 #draw=21, #red_draw=3905, #glz_draw=3888 current 21 pipes 0
>>>> red_free_some: #draw=21, #red_draw=3905, #glz_draw=3888
>>>> handle_dev_input: OOM2 #draw=21, #red_draw=3841, #glz_draw=3824 current 21 pipes 0
>>>> handle_dev_input: OOM1 #draw=17, #red_draw=3849, #glz_draw=3835 current 17 pipes 0
>>>> handle_dev_input: OOM2 #draw=17, #red_draw=3849, #glz_draw=3835 current 17 pipes 0
>>>> handle_dev_input: OOM1 #draw=17, #red_draw=3849, #glz_draw=3835 current 17 pipes 0
>>>> red_free_some: #draw=17, #red_draw=3849, #glz_draw=3835
>>>> handle_dev_input: OOM2 #draw=17, #red_draw=3785, #glz_draw=3771 current 17 pipes 0
>>>> handle_dev_input: OOM1 #draw=16, #red_draw=3798, #glz_draw=3784 current 16 pipes 0
>>>> handle_dev_input: OOM2 #draw=16, #red_draw=3798, #glz_draw=3784 current 16 pipes 0
>>>> handle_dev_input: OOM1 #draw=16, #red_draw=3798, #glz_draw=3784 current 16 pipes 0
>>>> handle_dev_input: OOM2 #draw=16, #red_draw=3798, #glz_draw=3784 current 16 pipes 0
>>>> handle_dev_input: OOM1 #draw=16, #red_draw=3798, #glz_draw=3784 current 16 pipes 0
>>>> red_free_some: #draw=16, #red_draw=3798, #glz_draw=3784
>>>> handle_dev_input: OOM2 #draw=16, #red_draw=3734, #glz_draw=3720 current 16 pipes 0
>>>> handle_dev_input: OOM1 #draw=21, #red_draw=3756, #glz_draw=3740 current 21 pipes 0
>>>> handle_dev_input: OOM2 #draw=21, #red_draw=3756, #glz_draw=3740 current 21 pipes 0
>>>> handle_dev_input: OOM1 #draw=16, #red_draw=3754, #glz_draw=3740 current 16 pipes 0
>>>> handle_dev_input: OOM2 #draw=16, #red_draw=3754, #glz_draw=3740 current 16 pipes 0
>>>> handle_dev_input: OOM1 #draw=16, #red_draw=3754, #glz_draw=3740 current 16 pipes 0
>>>> red_free_some: #draw=16, #red_draw=3754, #glz_draw=3740
>>>> handle_dev_input: OOM2 #draw=16, #red_draw=3690, #glz_draw=3676 current 16 pipes 0
>>>> handle_dev_input: OOM1 #draw=15, #red_draw=3716, #glz_draw=3702 current 15 pipes 0
>>>> handle_dev_input: OOM2 #draw=15, #red_draw=3716, #glz_draw=3702 current 15 pipes 0
>>>> handle_dev_input: OOM1 #draw=15, #red_draw=3716, #glz_draw=3702 current 15 pipes 0
>>>> handle_dev_input: OOM2 #draw=15, #red_draw=3716, #glz_draw=3702 current 15 pipes 0
>>>> handle_dev_input: OOM1 #draw=15, #red_draw=3716, #glz_draw=3702 current 15 pipes 0
>>>> red_free_some: #draw=15, #red_draw=3716, #glz_draw=3702
>>>> handle_dev_input: OOM2 #draw=15, #red_draw=3652, #glz_draw=3638 current 15 pipes 0
>>>> handle_dev_input: OOM1 #draw=15, #red_draw=3654, #glz_draw=3640 current 15 pipes 0
>>>> red_free_some: #draw=15, #red_draw=3654, #glz_draw=3640
>>>> handle_dev_input: OOM2 #draw=15, #red_draw=3590, #glz_draw=3576 current 15 pipes 0
>>>> handle_dev_input: OOM1 #draw=16, #red_draw=3667, #glz_draw=3652 current 16 pipes 0
>>>> handle_dev_input: OOM2 #draw=16, #red_draw=3667, #glz_draw=3652 current 16 pipes 0
>>>> handle_dev_input: OOM1 #draw=16, #red_draw=3667, #glz_draw=3652 current 16 pipes 0
>>>> handle_dev_input: OOM2 #draw=16, #red_draw=3667, #glz_draw=3652 current 16 pipes 0
>>>> handle_dev_input: OOM1 #draw=16, #red_draw=3667, #glz_draw=3652 current 16 pipes 0
>>>> red_free_some: #draw=16, #red_draw=3667, #glz_draw=3652
>>>> handle_dev_input: OOM2 #draw=16, #red_draw=3603, #glz_draw=3588 current 16 pipes 0
>>>> handle_dev_input: OOM1 #draw=19, #red_draw=3625, #glz_draw=3607 current 15 pipes 5
>>>> handle_dev_input: OOM2 #draw=19, #red_draw=3625, #glz_draw=3607 current 15 pipes 5
>>>> handle_dev_input: OOM1 #draw=19, #red_draw=3625, #glz_draw=3607 current 15 pipes 6
>>>> handle_dev_input: OOM2 #draw=19, #red_draw=3625, #glz_draw=3607 current 15 pipes 6
>>>> handle_dev_input: OOM1 #draw=15, #red_draw=3623, #glz_draw=3609 current 15 pipes 0
>>>> handle_dev_input: OOM2 #draw=15, #red_draw=3623, #glz_draw=3609 current 15 pipes 0
>>>> handle_dev_input: OOM1 #draw=15, #red_draw=3623, #glz_draw=3609 current 15 pipes 0
>>>> red_free_some: #draw=15, #red_draw=3623, #glz_draw=3609
>>>> handle_dev_input: OOM2 #draw=15, #red_draw=3559, #glz_draw=3545 current 15 pipes 0
>>>> handle_dev_input: OOM1 #draw=19, #red_draw=3625, #glz_draw=3608 current 19 pipes 0
>>>> handle_dev_input: OOM2 #draw=19, #red_draw=3625, #glz_draw=3608 current 19 pipes 0
>>>> handle_dev_input: OOM1 #draw=19, #red_draw=3625, #glz_draw=3608 current 19 pipes 0
>>>> handle_dev_input: OOM2 #draw=19, #red_draw=3625, #glz_draw=3608 current 19 pipes 0
>>>> handle_dev_input: OOM1 #draw=19, #red_draw=3625, #glz_draw=3608 current 19 pipes 0
>>>> red_free_some: #draw=19, #red_draw=3625, #glz_draw=3608
>>>> handle_dev_input: OOM2 #draw=19, #red_draw=3561, #glz_draw=3544 current 19 pipes 0
>>>> handle_dev_input: OOM1 #draw=17, #red_draw=3561, #glz_draw=3545 current 17 pipes 0
>>>> handle_dev_input: OOM2 #draw=17, #red_draw=3561, #glz_draw=3545 current 17 pipes 0
>>>> handle_dev_input: OOM1 #draw=20, #red_draw=3637, #glz_draw=3618 current 20 pipes 0
>>>> handle_dev_input: OOM2 #draw=20, #red_draw=3637, #glz_draw=3618 current 20 pipes 0
>>>> handle_dev_input: OOM1 #draw=20, #red_draw=3637, #glz_draw=3618 current 20 pipes 0
>>>> handle_dev_input: OOM2 #draw=20, #red_draw=3637, #glz_draw=3618 current 20 pipes 0
>>>> handle_dev_input: OOM1 #draw=20, #red_draw=3637, #glz_draw=3618 current 20 pipes 0
>>>> red_free_some: #draw=20, #red_draw=3637, #glz_draw=3618
>>>> handle_dev_input: OOM2 #draw=20, #red_draw=3573, #glz_draw=3554 current 20 pipes 0
>>>> handle_dev_input: OOM1 #draw=25, #red_draw=3654, #glz_draw=3637 current 25 pipes 0
>>>> handle_dev_input: OOM2 #draw=25, #red_draw=3654, #glz_draw=3637 current 25 pipes 0
>>>> handle_dev_input: OOM1 #draw=25, #red_draw=3654, #glz_draw=3637 current 25 pipes 0
>>>> handle_dev_input: OOM2 #draw=25, #red_draw=3654, #glz_draw=3637 current 25 pipes 0
>>>> handle_dev_input: OOM1 #draw=25, #red_draw=3654, #glz_draw=3637 current 25 pipes 0
>>>> red_free_some: #draw=25, #red_draw=3654, #glz_draw=3637
>>>> handle_dev_input: OOM2 #draw=25, #red_draw=3590, #glz_draw=3573 current 25 pipes 0
>>>> handle_dev_input: OOM1 #draw=25, #red_draw=3617, #glz_draw=3599 current 25 pipes 0
>>>> handle_dev_input: OOM2 #draw=25, #red_draw=3617, #glz_draw=3599 current 25 pipes 0
>>>> handle_dev_input: OOM1 #draw=25, #red_draw=3617, #glz_draw=3599 current 25 pipes 0
>>>> handle_dev_input: OOM2 #draw=25, #red_draw=3617, #glz_draw=3599 current 25 pipes 0
>>>> handle_dev_input: OOM1 #draw=25, #red_draw=3617, #glz_draw=3599 current 25 pipes 0
>>>> red_free_some: #draw=25, #red_draw=3617, #glz_draw=3599
>>>> handle_dev_input: OOM2 #draw=25, #red_draw=3553, #glz_draw=3535 current 25 pipes 0
>>>> handle_dev_input: OOM1 #draw=22, #red_draw=3612, #glz_draw=3595 current 22 pipes 0
>>>> handle_dev_input: OOM2 #draw=22, #red_draw=3612, #glz_draw=3595 current 22 pipes 0
>>>> handle_dev_input: OOM1 #draw=22, #red_draw=3612, #glz_draw=3595 current 22 pipes 0
>>>> red_free_some: #draw=22, #red_draw=3612, #glz_draw=3595
>>>> handle_dev_input: OOM2 #draw=22, #red_draw=3548, #glz_draw=3531 current 22 pipes 0
>>>> handle_dev_input: OOM1 #draw=21, #red_draw=3599, #glz_draw=3583 current 21 pipes 0
>>>> handle_dev_input: OOM2 #draw=21, #red_draw=3599, #glz_draw=3583 current 21 pipes 0
>>>> handle_dev_input: OOM1 #draw=21, #red_draw=3599, #glz_draw=3583 current 21 pipes 0
>>>> red_free_some: #draw=21, #red_draw=3599, #glz_draw=3583
>>>> handle_dev_input: OOM2 #draw=21, #red_draw=3535, #glz_draw=3519 current 21 pipes 0
>>>> handle_dev_input: OOM1 #draw=21, #red_draw=3569, #glz_draw=3553 current 21 pipes 0
>>>> handle_dev_input: OOM2 #draw=21, #red_draw=3569, #glz_draw=3553 current 21 pipes 0
>>>> handle_dev_input: OOM1 #draw=21, #red_draw=3569, #glz_draw=3553 current 21 pipes 0
>>>> red_free_some: #draw=21, #red_draw=3569, #glz_draw=3553
>>>> handle_dev_input: OOM2 #draw=21, #red_draw=3505, #glz_draw=3489 current 21 pipes 0
>>>> handle_dev_input: OOM1 #draw=21, #red_draw=3540, #glz_draw=3524 current 21 pipes 0
>>>> handle_dev_input: OOM2 #draw=21, #red_draw=3540, #glz_draw=3524 current 21 pipes 0
>>>> handle_dev_input: OOM1 #draw=21, #red_draw=3540, #glz_draw=3524 current 21 pipes 0
>>>> handle_dev_input: OOM2 #draw=21, #red_draw=3540, #glz_draw=3524 current 21 pipes 0
>>>> handle_dev_input: OOM1 #draw=21, #red_draw=3540, #glz_draw=3524 current 21 pipes 0
>>>> red_free_some: #draw=21, #red_draw=3540, #glz_draw=3524
>>>> handle_dev_input: OOM2 #draw=21, #red_draw=3476, #glz_draw=3460 current 21 pipes 0
>>>> handle_dev_input: OOM1 #draw=24, #red_draw=3503, #glz_draw=3487 current 24 pipes 0
>>>> handle_dev_input: OOM2 #draw=24, #red_draw=3503, #glz_draw=3487 current 24 pipes 0
>>>> handle_dev_input: OOM1 #draw=24, #red_draw=3503, #glz_draw=3487 current 24 pipes 0
>>>> handle_dev_input: OOM2 #draw=24, #red_draw=3503, #glz_draw=3487 current 24 pipes 0
>>>> handle_dev_input: OOM1 #draw=24, #red_draw=3503, #glz_draw=3487 current 24 pipes 0
>>>> red_free_some: #draw=24, #red_draw=3503, #glz_draw=3487
>>>> handle_dev_input: OOM2 #draw=24, #red_draw=3439, #glz_draw=3423 current 24 pipes 0
>>>> handle_dev_input: OOM1 #draw=25, #red_draw=3601, #glz_draw=3582 current 25 pipes 0
>>>> handle_dev_input: OOM2 #draw=25, #red_draw=3601, #glz_draw=3582 current 25 pipes 0
>>>> handle_dev_input: OOM1 #draw=25, #red_draw=3601, #glz_draw=3582 current 25 pipes 0
>>>> red_free_some: #draw=25, #red_draw=3601, #glz_draw=3582
>>>> handle_dev_input: OOM2 #draw=25, #red_draw=3537, #glz_draw=3518 current 25 pipes 0
>>>> handle_dev_input: OOM1 #draw=25, #red_draw=3551, #glz_draw=3532 current 25 pipes 0
>>>> handle_dev_input: OOM2 #draw=25, #red_draw=3551, #glz_draw=3532 current 25 pipes 0
>>>> handle_dev_input: OOM1 #draw=25, #red_draw=3551, #glz_draw=3532 current 25 pipes 0
>>>> handle_dev_input: OOM2 #draw=25, #red_draw=3551, #glz_draw=3532 current 25 pipes 0
>>>> handle_dev_input: OOM1 #draw=25, #red_draw=3551, #glz_draw=3532 current 25 pipes 0
>>>> red_free_some: #draw=25, #red_draw=3551, #glz_draw=3532
>>>> handle_dev_input: OOM2 #draw=25, #red_draw=3487, #glz_draw=3468 current 25 pipes 0
>>>> handle_dev_input: OOM1 #draw=26, #red_draw=3518, #glz_draw=3498 current 26 pipes 0
>>>> handle_dev_input: OOM2 #draw=26, #red_draw=3518, #glz_draw=3498 current 26 pipes 0
>>>> handle_dev_input: OOM1 #draw=25, #red_draw=3517, #glz_draw=3498 current 25 pipes 0
>>>> handle_dev_input: OOM2 #draw=25, #red_draw=3517, #glz_draw=3498 current 25 pipes 0
>>>> handle_dev_input: OOM1 #draw=25, #red_draw=3517, #glz_draw=3498 current 25 pipes 0
>>>> red_free_some: #draw=25, #red_draw=3517, #glz_draw=3498
>>>> handle_dev_input: OOM2 #draw=25, #red_draw=3453, #glz_draw=3434 current 25 pipes 0
>>>> handle_dev_input: OOM1 #draw=25, #red_draw=3467, #glz_draw=3448 current 25 pipes 0
>>>> handle_dev_input: OOM2 #draw=25, #red_draw=3467, #glz_draw=3448 current 25 pipes 0
>>>> handle_dev_input: OOM1 #draw=25, #red_draw=3467, #glz_draw=3448 current 25 pipes 0
>>>> handle_dev_input: OOM2 #draw=25, #red_draw=3467, #glz_draw=3448 current 25 pipes 0
>>>> handle_dev_input: OOM1 #draw=25, #red_draw=3467, #glz_draw=3448 current 25 pipes 0
>>>> red_free_some: #draw=25, #red_draw=3467, #glz_draw=3448
>>>> handle_dev_input: OOM2 #draw=25, #red_draw=3403, #glz_draw=3384 current 25 pipes 0
>>>> handle_dev_input: OOM1 #draw=25, #red_draw=3413, #glz_draw=3394 current 25 pipes 0
>>>> red_free_some: #draw=25, #red_draw=3413, #glz_draw=3394
>>>> handle_dev_input: OOM2 #draw=25, #red_draw=3349, #glz_draw=3330 current 25 pipes 0
>>>> handle_dev_input: OOM1 #draw=26, #red_draw=3408, #glz_draw=3389 current 26 pipes 0
>>>> handle_dev_input: OOM2 #draw=26, #red_draw=3408, #glz_draw=3389 current 26 pipes 0
>>>> handle_dev_input: OOM1 #draw=26, #red_draw=3408, #glz_draw=3389 current 26 pipes 0
>>>> red_free_some: #draw=26, #red_draw=3408, #glz_draw=3389
>>>> handle_dev_input: OOM2 #draw=26, #red_draw=3344, #glz_draw=3325 current 26 pipes 0
>>>> handle_dev_input: OOM1 #draw=26, #red_draw=3364, #glz_draw=3345 current 26 pipes 0
>>>> handle_dev_input: OOM2 #draw=26, #red_draw=3364, #glz_draw=3345 current 26 pipes 0
>>>> handle_dev_input: OOM1 #draw=26, #red_draw=3364, #glz_draw=3345 current 26 pipes 0
>>>> handle_dev_input: OOM2 #draw=26, #red_draw=3364, #glz_draw=3345 current 26 pipes 0
>>>> handle_dev_input: OOM1 #draw=26, #red_draw=3364, #glz_draw=3345 current 26 pipes 0
>>>> red_free_some: #draw=26, #red_draw=3364, #glz_draw=3345
>>>> handle_dev_input: OOM2 #draw=26, #red_draw=3300, #glz_draw=3281 current 26 pipes 0
>>>> handle_dev_input: OOM1 #draw=26, #red_draw=3300, #glz_draw=3281 current 26 pipes 0
>>>> red_free_some: #draw=26, #red_draw=3300, #glz_draw=3281
>>>> handle_dev_input: OOM2 #draw=26, #red_draw=3236, #glz_draw=3217 current 26 pipes 0
>>>> handle_dev_input: OOM1 #draw=26, #red_draw=3258, #glz_draw=3238 current 26 pipes 0
>>>> handle_dev_input: OOM2 #draw=26, #red_draw=3258, #glz_draw=3238 current 26 pipes 0
>>>> handle_dev_input: OOM1 #draw=25, #red_draw=3257, #glz_draw=3238 current 25 pipes 0
>>>> handle_dev_input: OOM2 #draw=25, #red_draw=3257, #glz_draw=3238 current 25 pipes 0
>>>> handle_dev_input: OOM1 #draw=25, #red_draw=3257, #glz_draw=3238 current 25 pipes 0
>>>> red_free_some: #draw=25, #red_draw=3257, #glz_draw=3238
>>>> handle_dev_input: OOM2 #draw=25, #red_draw=3193, #glz_draw=3174 current 25 pipes 0
>>>> handle_dev_input: OOM1 #draw=25, #red_draw=3193, #glz_draw=3174 current 25 pipes 0
>>>> red_free_some: #draw=25, #red_draw=3193, #glz_draw=3174
>>>> handle_dev_input: OOM2 #draw=25, #red_draw=3129, #glz_draw=3110 current 25 pipes 0
>>>> handle_dev_input: OOM1 #draw=26, #red_draw=3131, #glz_draw=3112 current 26 pipes 0
>>>> red_free_some: #draw=26, #red_draw=3131, #glz_draw=3112
>>>> handle_dev_input: OOM2 #draw=26, #red_draw=3067, #glz_draw=3048 current 26 pipes 0
>>>> handle_dev_input: OOM1 #draw=25, #red_draw=3073, #glz_draw=3054 current 25 pipes 0
>>>> red_free_some: #draw=25, #red_draw=3073, #glz_draw=3054
>>>> handle_dev_input: OOM2 #draw=25, #red_draw=3009, #glz_draw=2990 current 25 pipes 0
>>>> handle_dev_input: OOM1 #draw=33, #red_draw=3031, #glz_draw=3006 current 33 pipes 0
>>>> handle_dev_input: OOM2 #draw=33, #red_draw=3031, #glz_draw=3006 current 33 pipes 0
>>>> handle_dev_input: OOM1 #draw=25, #red_draw=3025, #glz_draw=3006 current 25 pipes 0
>>>> handle_dev_input: OOM2 #draw=25, #red_draw=3025, #glz_draw=3006 current 25 pipes 0
>>>> handle_dev_input: OOM1 #draw=25, #red_draw=3025, #glz_draw=3006 current 25 pipes 0
>>>> red_free_some: #draw=25, #red_draw=3025, #glz_draw=3006
>>>> handle_dev_input: OOM2 #draw=25, #red_draw=2961, #glz_draw=2942 current 25 pipes 0
>>>> handle_dev_input: OOM1 #draw=25, #red_draw=2963, #glz_draw=2944 current 25 pipes 0
>>>> red_free_some: #draw=25, #red_draw=2963, #glz_draw=2944
>>>> handle_dev_input: OOM2 #draw=25, #red_draw=2899, #glz_draw=2880 current 25 pipes 0
>>>> handle_dev_input: OOM1 #draw=26, #red_draw=2904, #glz_draw=2885 current 26 pipes 0
>>>> red_free_some: #draw=26, #red_draw=2904, #glz_draw=2885
>>>> handle_dev_input: OOM2 #draw=26, #red_draw=2840, #glz_draw=2821 current 26 pipes 0
>>>> handle_dev_input: OOM1 #draw=25, #red_draw=2841, #glz_draw=2822 current 25 pipes 0
>>>> red_free_some: #draw=25, #red_draw=2841, #glz_draw=2822
>>>> handle_dev_input: OOM2 #draw=25, #red_draw=2777, #glz_draw=2758 current 25 pipes 0
>>>> handle_dev_input: OOM1 #draw=25, #red_draw=2779, #glz_draw=2760 current 25 pipes 0
>>>> red_free_some: #draw=25, #red_draw=2779, #glz_draw=2760
>>>> handle_dev_input: OOM2 #draw=25, #red_draw=2715, #glz_draw=2696 current 25 pipes 0
>>>> red_channel_client_disconnect: 0x2e31640 (channel 0x204fae0 type 2 id 0)
>>>> red_channel_client_disconnect: 0x2e2fe30 (channel 0x1fb3fe0 type 3 id 0)
>>>> display_channel_client_on_ disconnect:
>>>> snd_channel_put: sound channel freed
>>>>
>>>> snd_channel_put: sound channel freed
>>>>
>>>> red_channel_client_disconnect: 0x2de77f0 (channel 0x2de0770 type 1 id 0)
>>>> main_channel_client_on_ disconnect: rcc=0x2de77f0
>>>> reds_client_disconnect:
>>>> red_client_destroy: destroy client with #channels 4
>>>> red_channel_client_disconnect: 0x2e2fe30 (channel 0x1fb3fe0 type 3 id 0)
>>>> red_dispatcher_disconnect_ cursor_peer:
>>>> display_channel_client_on_ disconnect: #draw=37, #red_draw=37, #glz_draw=0
>>>> red_channel_client_disconnect: 0x2e2e370 (channel 0x2050060 type 4 id 0)
>>>> handle_dev_input: disconnect cursor client
>>>> red_channel_client_disconnect: 0x2e2e370 (channel 0x2050060 type 4 id 0)
>>>> red_dispatcher_disconnect_ display_peer:
>>>> handle_dev_input: disconnect display client
>>>> red_channel_client_disconnect: 0x2e31640 (channel 0x204fae0 type 2 id 0)
>>>> red_channel_client_disconnect: 0x2de77f0 (channel 0x2de0770 type 1 id 0)
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> reds_handle_main_link:
>>>> reds_disconnect:
>>>> reds_show_new_channel: channel 1:0, connected successfully, over Non Secure link
>>>> main_channel_link: add main channel client
>>>> reds_handle_main_link: NEW Client 0x2de88f0 mcc 0x2e2fdd0 connect-id 846930886
>>>> main_channel_handle_parsed: net test: latency 0.652000 ms, bitrate 864135021 bps (824.103375 Mbps)
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> reds_show_new_channel: channel 6:0, connected successfully, over Non Secure link
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> reds_show_new_channel: channel 5:0, connected successfully, over Non Secure link
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> reds_show_new_channel: channel 2:0, connected successfully, over Non Secure link
>>>> red_dispatcher_set_display_ peer:
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> handle_dev_input: connect
>>>> handle_new_display_channel: add display channel client
>>>> handle_new_display_channel: New display (client 0x2de88f0) dcc 0x7f7328480d50 stream 0x2a2c810
>>>> handle_new_display_channel: jpeg disabled
>>>> handle_new_display_channel: zlib-over-glz disabled
>>>> listen_to_new_client_channel: NEW ID = 0
>>>> reds_show_new_channel: channel 4:0, connected successfully, over Non Secure link
>>>> red_dispatcher_set_cursor_ peer:
>>>> display_channel_client_wait_ for_init: creating encoder with id == 0
>>>> handle_dev_input: cursor connect
>>>> red_connect_cursor: add cursor channel client
>>>> listen_to_new_client_channel: NEW ID = 0
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> reds_show_new_channel: channel 3:0, connected successfully, over Non Secure link
>>>> inputs_connect: inputs channel client create
>>>> red_channel_client_disconnect: 0x2e2fdd0 (channel 0x2de0770 type 1 id 0)
>>>> red_channel_client_disconnect: 0x7f7328480d50 (channel 0x204fae0 type 2 id 0)
>>>> main_channel_client_on_ disconnect: rcc=0x2e2fdd0
>>>> reds_client_disconnect:
>>>> red_client_destroy: destroy client with #channels 4
>>>> red_channel_client_disconnect: 0x2e30cd0 (channel 0x1fb3fe0 type 3 id 0)
>>>> display_channel_client_on_ disconnect:
>>>> red_dispatcher_disconnect_ cursor_peer:
>>>> display_channel_client_on_ disconnect: #draw=21, #red_draw=21, #glz_draw=0
>>>> red_channel_client_disconnect: 0x7f7328c430f0 (channel 0x2050060 type 4 id 0)
>>>> handle_dev_input: disconnect cursor client
>>>> red_channel_client_disconnect: 0x7f7328c430f0 (channel 0x2050060 type 4 id 0)
>>>> red_dispatcher_disconnect_ display_peer:
>>>> handle_dev_input: disconnect display client
>>>> red_channel_client_disconnect: 0x7f7328480d50 (channel 0x204fae0 type 2 id 0)
>>>> red_channel_client_disconnect: 0x2e2fdd0 (channel 0x2de0770 type 1 id 0)
>>>> snd_channel_put: sound channel freed
>>>>
>>>> snd_channel_put: sound channel freed
>>>>
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> reds_handle_main_link:
>>>> reds_disconnect:
>>>> reds_show_new_channel: channel 1:0, connected successfully, over Non Secure link
>>>> main_channel_link: add main channel client
>>>> reds_handle_main_link: NEW Client 0x33616b0 mcc 0x34ea040 connect-id 1681692777
>>>> main_channel_handle_parsed: net test: latency 0.627000 ms, bitrate 859060402 bps (819.263842 Mbps)
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> reds_show_new_channel: channel 6:0, connected successfully, over Non Secure link
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> reds_show_new_channel: channel 5:0, connected successfully, over Non Secure link
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> reds_show_new_channel: channel 2:0, connected successfully, over Non Secure link
>>>> red_dispatcher_set_display_ peer:
>>>> handle_dev_input: connect
>>>> handle_new_display_channel: add display channel client
>>>> handle_new_display_channel: New display (client 0x33616b0) dcc 0x7f7328480d00 stream 0x2dff670
>>>> handle_new_display_channel: jpeg disabled
>>>> handle_new_display_channel: zlib-over-glz disabled
>>>> listen_to_new_client_channel: NEW ID = 0
>>>> display_channel_client_wait_ for_init: creating encoder with id == 0
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> reds_show_new_channel: channel 4:0, connected successfully, over Non Secure link
>>>> red_dispatcher_set_cursor_ peer:
>>>> reds_handle_auth_mechanism: Auth method: 1
>>>> handle_dev_input: cursor connect
>>>> red_connect_cursor: add cursor channel client
>>>> listen_to_new_client_channel: NEW ID = 0
>>>> reds_show_new_channel: channel 3:0, connected successfully, over Non Secure link
>>>> inputs_connect: inputs channel client create
>>>>
>>>>
>>>>
>>>>
>>>>
>>>> root account
>>>>
>>>> *colossus:~ # spicy*
>>>>
>>>> libusb:debug [libusb_init]
>>>> libusb:debug [find_usbfs_path] found usbfs at /dev/bus/usb
>>>> libusb:debug [op_init] bulk continuation flag supported
>>>> libusb:debug [op_init] found usb devices in sysfs
>>>> libusb:debug [usbi_add_pollfd] add fd 6 events 1
>>>> libusb:debug [usbi_io_init] using timerfd for timeouts
>>>> libusb:debug [usbi_add_pollfd] add fd 8 events 1
>>>> libusb:debug [libusb_get_device_list]
>>>> libusb:debug [sysfs_scan_device] scan usb1
>>>> libusb:debug [sysfs_scan_device] bus=1 dev=1
>>>> libusb:debug [enumerate_device] busnum 1 devaddr 1 session_id 257
>>>> libusb:debug [enumerate_device] allocating new device for 1/1 (session 257)
>>>> libusb:debug [sysfs_scan_device] scan usb2
>>>> libusb:debug [sysfs_scan_device] bus=2 dev=1
>>>> libusb:debug [enumerate_device] busnum 2 devaddr 1 session_id 513
>>>> libusb:debug [enumerate_device] allocating new device for 2/1 (session 513)
>>>> libusb:debug [sysfs_scan_device] scan usb3
>>>> libusb:debug [sysfs_scan_device] bus=3 dev=1
>>>> libusb:debug [enumerate_device] busnum 3 devaddr 1 session_id 769
>>>> libusb:debug [enumerate_device] allocating new device for 3/1 (session 769)
>>>> libusb:debug [sysfs_scan_device] scan usb4
>>>> libusb:debug [sysfs_scan_device] bus=4 dev=1
>>>> libusb:debug [enumerate_device] busnum 4 devaddr 1 session_id 1025
>>>> libusb:debug [enumerate_device] allocating new device for 4/1 (session 1025)
>>>> libusb:debug [sysfs_scan_device] scan usb5
>>>> libusb:debug [sysfs_scan_device] bus=5 dev=1
>>>> libusb:debug [enumerate_device] busnum 5 devaddr 1 session_id 1281
>>>> libusb:debug [enumerate_device] allocating new device for 5/1 (session 1281)
>>>> libusb:debug [sysfs_scan_device] scan usb6
>>>> libusb:debug [sysfs_scan_device] bus=6 dev=1
>>>> libusb:debug [enumerate_device] busnum 6 devaddr 1 session_id 1537
>>>> libusb:debug [enumerate_device] allocating new device for 6/1 (session 1537)
>>>> libusb:debug [sysfs_scan_device] scan usb7
>>>> libusb:debug [sysfs_scan_device] bus=7 dev=1
>>>> libusb:debug [enumerate_device] busnum 7 devaddr 1 session_id 1793
>>>> libusb:debug [enumerate_device] allocating new device for 7/1 (session 1793)
>>>> libusb:debug [sysfs_scan_device] scan usb8
>>>> libusb:debug [sysfs_scan_device] bus=8 dev=1
>>>> libusb:debug [enumerate_device] busnum 8 devaddr 1 session_id 2049
>>>> libusb:debug [enumerate_device] allocating new device for 8/1 (session 2049)
>>>> libusb:debug [sysfs_scan_device] scan 2-2
>>>> libusb:debug [sysfs_scan_device] bus=2 dev=3
>>>> libusb:debug [enumerate_device] busnum 2 devaddr 3 session_id 515
>>>> libusb:debug [enumerate_device] allocating new device for 2/3 (session 515)
>>>> libusb:debug [discovered_devs_append] need to increase capacity
>>>> libusb:debug [sysfs_scan_device] scan 4-2
>>>> libusb:debug [sysfs_scan_device] bus=4 dev=2
>>>> libusb:debug [enumerate_device] busnum 4 devaddr 2 session_id 1026
>>>> libusb:debug [enumerate_device] allocating new device for 4/2 (session 1026)
>>>> libusb:debug [sysfs_scan_device] scan 6-1
>>>> libusb:debug [sysfs_scan_device] bus=6 dev=3
>>>> libusb:debug [enumerate_device] busnum 6 devaddr 3 session_id 1539
>>>> libusb:debug [enumerate_device] allocating new device for 6/3 (session 1539)
>>>> libusb:debug [sysfs_scan_device] scan 1-3
>>>> libusb:debug [sysfs_scan_device] bus=1 dev=8
>>>> libusb:debug [enumerate_device] busnum 1 devaddr 8 session_id 264
>>>> libusb:debug [enumerate_device] allocating new device for 1/8 (session 264)
>>>> libusb:debug [libusb_unref_device] destroy device 1.1
>>>> libusb:debug [libusb_unref_device] destroy device 2.1
>>>> libusb:debug [libusb_unref_device] destroy device 3.1
>>>> libusb:debug [libusb_unref_device] destroy device 4.1
>>>> libusb:debug [libusb_unref_device] destroy device 5.1
>>>> libusb:debug [libusb_unref_device] destroy device 6.1
>>>> libusb:debug [libusb_unref_device] destroy device 7.1
>>>> libusb:debug [libusb_unref_device] destroy device 8.1
>>>> GSpice-Message: main channel: failed to connect
>>>>
>>>> (spicy:18840): Gtk-CRITICAL **: gtk_render_slider: assertion `height> 0' failed
>>>> GSpice-Message: main channel: opened
>>>>
>>>> (spicy:18840): GLib-GObject-WARNING **: g_object_set_valist: object class `SpiceDisplay' has no property named `auto-usbredir'
>>>>
>>>> (spicy:18840): GLib-GObject-WARNING **: g_object_get_valist: object class `SpiceDisplay' has no property named `auto-usbredir'
>>>>
>>>> (spicy:18840): GLib-GObject-WARNING **: g_object_set_valist: object class `SpiceDisplay' has no property named `auto-usbredir'
>>>>
>>>> (spicy:18840): GSpice-WARNING **: PulseAudio context failed Connection refused
>>>> libusb:debug [libusb_unref_device] destroy device 1.8
>>>> libusb:debug [libusb_get_device_list]
>>>> libusb:debug [sysfs_scan_device] scan usb1
>>>> libusb:debug [sysfs_scan_device] bus=1 dev=1
>>>> libusb:debug [enumerate_device] busnum 1 devaddr 1 session_id 257
>>>> libusb:debug [enumerate_device] allocating new device for 1/1 (session 257)
>>>> libusb:debug [sysfs_scan_device] scan usb2
>>>> libusb:debug [sysfs_scan_device] bus=2 dev=1
>>>> libusb:debug [enumerate_device] busnum 2 devaddr 1 session_id 513
>>>> libusb:debug [enumerate_device] allocating new device for 2/1 (session 513)
>>>> libusb:debug [sysfs_scan_device] scan usb3
>>>> libusb:debug [sysfs_scan_device] bus=3 dev=1
>>>> libusb:debug [enumerate_device] busnum 3 devaddr 1 session_id 769
>>>> libusb:debug [enumerate_device] allocating new device for 3/1 (session 769)
>>>> libusb:debug [sysfs_scan_device] scan usb4
>>>> libusb:debug [sysfs_scan_device] bus=4 dev=1
>>>> libusb:debug [enumerate_device] busnum 4 devaddr 1 session_id 1025
>>>> libusb:debug [enumerate_device] allocating new device for 4/1 (session 1025)
>>>> libusb:debug [sysfs_scan_device] scan usb5
>>>> libusb:debug [sysfs_scan_device] bus=5 dev=1
>>>> libusb:debug [enumerate_device] busnum 5 devaddr 1 session_id 1281
>>>> libusb:debug [enumerate_device] allocating new device for 5/1 (session 1281)
>>>> libusb:debug [sysfs_scan_device] scan usb6
>>>> libusb:debug [sysfs_scan_device] bus=6 dev=1
>>>> libusb:debug [enumerate_device] busnum 6 devaddr 1 session_id 1537
>>>> libusb:debug [enumerate_device] allocating new device for 6/1 (session 1537)
>>>> libusb:debug [sysfs_scan_device] scan usb7
>>>> libusb:debug [sysfs_scan_device] bus=7 dev=1
>>>> libusb:debug [enumerate_device] busnum 7 devaddr 1 session_id 1793
>>>> libusb:debug [enumerate_device] allocating new device for 7/1 (session 1793)
>>>> libusb:debug [sysfs_scan_device] scan usb8
>>>> libusb:debug [sysfs_scan_device] bus=8 dev=1
>>>> libusb:debug [enumerate_device] busnum 8 devaddr 1 session_id 2049
>>>> libusb:debug [enumerate_device] allocating new device for 8/1 (session 2049)
>>>> libusb:debug [sysfs_scan_device] scan 2-2
>>>> libusb:debug [sysfs_scan_device] bus=2 dev=3
>>>> libusb:debug [enumerate_device] busnum 2 devaddr 3 session_id 515
>>>> libusb:debug [enumerate_device] using existing device for 2/3 (session 515)
>>>> libusb:debug [discovered_devs_append] need to increase capacity
>>>> libusb:debug [sysfs_scan_device] scan 4-2
>>>> libusb:debug [sysfs_scan_device] bus=4 dev=2
>>>> libusb:debug [enumerate_device] busnum 4 devaddr 2 session_id 1026
>>>> libusb:debug [enumerate_device] using existing device for 4/2 (session 1026)
>>>> libusb:debug [sysfs_scan_device] scan 6-1
>>>> libusb:debug [sysfs_scan_device] bus=6 dev=3
>>>> libusb:debug [enumerate_device] busnum 6 devaddr 3 session_id 1539
>>>> libusb:debug [enumerate_device] using existing device for 6/3 (session 1539)
>>>> libusb:debug [sysfs_scan_device] scan 1-3
>>>> libusb:debug [sysfs_scan_device] bus=1 dev=9
>>>> libusb:debug [enumerate_device] busnum 1 devaddr 9 session_id 265
>>>> libusb:debug [enumerate_device] allocating new device for 1/9 (session 265)
>>>> libusb:debug [libusb_unref_device] destroy device 1.1
>>>> libusb:debug [libusb_unref_device] destroy device 2.1
>>>> libusb:debug [libusb_unref_device] destroy device 3.1
>>>> libusb:debug [libusb_unref_device] destroy device 4.1
>>>> libusb:debug [libusb_unref_device] destroy device 5.1
>>>> libusb:debug [libusb_unref_device] destroy device 6.1
>>>> libusb:debug [libusb_unref_device] destroy device 7.1
>>>> libusb:debug [libusb_unref_device] destroy device 8.1
>>>>
>>>>
>>>>
>>>> ______________________________ _________________
>>>> Spice-devel mailing list
>>>> Spice-devel at lists.freedesktop. org<mailto:Spice-devel at lists.freedesktop.org>
>>>> http://lists.freedesktop.org/ mailman/listinfo/spice-devel<http://lists.freedesktop.org/mailman/listinfo/spice-devel>
>>>>
>>>>
>>
More information about the Spice-devel
mailing list