Attachment 881486 Details for Bug 1081227 – output of log file with libusb debug enabled

output of log file with libusb debug enabled

virt-manager.log (text/plain), 45.68 KB, created by Dr. David Alan Gilbert on 2014-04-01 18:45:06 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Dr. David Alan Gilbert

Created: 2014-04-01 18:45:06 UTC

Size: 45.68 KB

patch

obsolete

>[Tue, 01 Apr 2014 19:31:06 virt-manager 12103] DEBUG (cli:187) Launched with command line: /usr/share/virt-manager/virt-manager --no-fork --debug
>[Tue, 01 Apr 2014 19:31:06 virt-manager 12103] DEBUG (virt-manager:150) virt-manager version: 1.0.1
>[Tue, 01 Apr 2014 19:31:06 virt-manager 12103] DEBUG (virt-manager:151) virtManager import: <module 'virtManager' from '/usr/share/virt-manager/virtManager/__init__.pyc'>
>[Tue, 01 Apr 2014 19:31:06 virt-manager 12103] DEBUG (virt-manager:209) GTK version: 3.10.6
>[Tue, 01 Apr 2014 19:31:06 virt-manager 12103] ERROR (importer:51) Could not find any typelib for AppIndicator3
>[Tue, 01 Apr 2014 19:31:06 virt-manager 12103] DEBUG (engine:484) libguestfs inspection support: False
>[Tue, 01 Apr 2014 19:31:06 virt-manager 12103] DEBUG (systray:152) Showing systray: False
>[Tue, 01 Apr 2014 19:31:06 virt-manager 12103] DEBUG (engine:231) About to connect to uris ['qemu+ssh://root@beaker-pe2950-02/system', 'qemu+ssh://root@vl402/system', 'qemu+ssh://root@vl403/system', 'qemu:///system']
>[Tue, 01 Apr 2014 19:31:06 virt-manager 12103] DEBUG (manager:216) Showing manager
>[Tue, 01 Apr 2014 19:31:06 virt-manager 12103] DEBUG (engine:385) window counter incremented to 1
>[Tue, 01 Apr 2014 19:31:15 virt-manager 12103] DEBUG (connection:995) Scheduling background open thread for qemu+ssh://root@vl402/system
>[Tue, 01 Apr 2014 19:31:15 virt-manager 12103] DEBUG (connection:1012) Background 'open connection' thread is running
>[Tue, 01 Apr 2014 19:31:16 virt-manager 12103] DEBUG (connection:1063) Background open thread complete, scheduling notify
>[Tue, 01 Apr 2014 19:31:16 virt-manager 12103] DEBUG (connection:1068) Notifying open result
>[Tue, 01 Apr 2014 19:31:16 virt-manager 12103] DEBUG (connection:1074) libvirt version=1001003
>[Tue, 01 Apr 2014 19:31:16 virt-manager 12103] DEBUG (connection:1076) daemon version=1001001
>[Tue, 01 Apr 2014 19:31:16 virt-manager 12103] DEBUG (connection:1077) conn version=1005003
>[Tue, 01 Apr 2014 19:31:16 virt-manager 12103] DEBUG (connection:1079) qemu+ssh://root@vl402/system capabilities:
><capabilities>
>
>  <host>
>    <uuid>44454c4c-3100-104e-8035-c6c04f465831</uuid>
>    <cpu>
>      <arch>x86_64</arch>
>      <model>SandyBridge</model>
>      <vendor>Intel</vendor>
>      <topology sockets='1' cores='4' threads='1'/>
>      <feature name='pdpe1gb'/>
>      <feature name='osxsave'/>
>      <feature name='dca'/>
>      <feature name='pcid'/>
>      <feature name='pdcm'/>
>      <feature name='xtpr'/>
>      <feature name='tm2'/>
>      <feature name='est'/>
>      <feature name='smx'/>
>      <feature name='vmx'/>
>      <feature name='ds_cpl'/>
>      <feature name='monitor'/>
>      <feature name='dtes64'/>
>      <feature name='pbe'/>
>      <feature name='tm'/>
>      <feature name='ht'/>
>      <feature name='ss'/>
>      <feature name='acpi'/>
>      <feature name='ds'/>
>      <feature name='vme'/>
>    </cpu>
>    <power_management>
>      <suspend_disk/>
>      <suspend_hybrid/>
>    </power_management>
>    <migration_features>
>      <live/>
>      <uri_transports>
>        <uri_transport>tcp</uri_transport>
>      </uri_transports>
>    </migration_features>
>    <topology>
>      <cells num='2'>
>        <cell id='0'>
>          <memory unit='KiB'>12536364</memory>
>          <cpus num='4'>
>            <cpu id='0' socket_id='0' core_id='0' siblings='0'/>
>            <cpu id='2' socket_id='0' core_id='1' siblings='2'/>
>            <cpu id='4' socket_id='0' core_id='2' siblings='4'/>
>            <cpu id='6' socket_id='0' core_id='3' siblings='6'/>
>          </cpus>
>        </cell>
>        <cell id='1'>
>          <memory unit='KiB'>12582912</memory>
>          <cpus num='4'>
>            <cpu id='1' socket_id='1' core_id='0' siblings='1'/>
>            <cpu id='3' socket_id='1' core_id='1' siblings='3'/>
>            <cpu id='5' socket_id='1' core_id='2' siblings='5'/>
>            <cpu id='7' socket_id='1' core_id='3' siblings='7'/>
>          </cpus>
>        </cell>
>      </cells>
>    </topology>
>    <secmodel>
>      <model>selinux</model>
>      <doi>0</doi>
>    </secmodel>
>    <secmodel>
>      <model>dac</model>
>      <doi>0</doi>
>    </secmodel>
>  </host>
>
>  <guest>
>    <os_type>hvm</os_type>
>    <arch name='i686'>
>      <wordsize>32</wordsize>
>      <emulator>/usr/libexec/qemu-kvm</emulator>
>      <machine canonical='pc-i440fx-rhel7.0.0' maxCpus='255'>pc</machine>
>      <machine canonical='pc-q35-rhel7.0.0' maxCpus='255'>q35</machine>
>      <machine maxCpus='255'>rhel6.0.0</machine>
>      <machine maxCpus='255'>rhel6.1.0</machine>
>      <machine maxCpus='255'>rhel6.2.0</machine>
>      <machine maxCpus='255'>rhel6.3.0</machine>
>      <machine maxCpus='255'>rhel6.4.0</machine>
>      <machine maxCpus='255'>rhel6.5.0</machine>
>      <machine maxCpus='1'>none</machine>
>      <domain type='qemu'>
>      </domain>
>      <domain type='kvm'>
>        <emulator>/usr/libexec/qemu-kvm</emulator>
>      </domain>
>    </arch>
>    <features>
>      <cpuselection/>
>      <deviceboot/>
>      <acpi default='on' toggle='yes'/>
>      <apic default='on' toggle='no'/>
>      <pae/>
>      <nonpae/>
>    </features>
>  </guest>
>
>  <guest>
>    <os_type>hvm</os_type>
>    <arch name='x86_64'>
>      <wordsize>64</wordsize>
>      <emulator>/usr/libexec/qemu-kvm</emulator>
>      <machine canonical='pc-i440fx-rhel7.0.0' maxCpus='255'>pc</machine>
>      <machine canonical='pc-q35-rhel7.0.0' maxCpus='255'>q35</machine>
>      <machine maxCpus='255'>rhel6.0.0</machine>
>      <machine maxCpus='255'>rhel6.1.0</machine>
>      <machine maxCpus='255'>rhel6.2.0</machine>
>      <machine maxCpus='255'>rhel6.3.0</machine>
>      <machine maxCpus='255'>rhel6.4.0</machine>
>      <machine maxCpus='255'>rhel6.5.0</machine>
>      <machine maxCpus='1'>none</machine>
>      <domain type='qemu'>
>      </domain>
>      <domain type='kvm'>
>        <emulator>/usr/libexec/qemu-kvm</emulator>
>      </domain>
>    </arch>
>    <features>
>      <cpuselection/>
>      <deviceboot/>
>      <acpi default='on' toggle='yes'/>
>      <apic default='on' toggle='no'/>
>    </features>
>  </guest>
>
></capabilities>
>
>[Tue, 01 Apr 2014 19:31:17 virt-manager 12103] DEBUG (connection:879) Using domain events
>[Tue, 01 Apr 2014 19:31:17 virt-manager 12103] DEBUG (connection:892) Error registering network events: 'virConnect' object has no attribute 'networkEventRegisterAny'
>[Tue, 01 Apr 2014 19:31:47 virt-manager 12103] DEBUG (connection:217) Using libvirt API for netdev enumeration
>[Tue, 01 Apr 2014 19:31:47 virt-manager 12103] DEBUG (connection:239) Using libvirt API for mediadev enumeration
>[Tue, 01 Apr 2014 19:32:04 virt-manager 12103] DEBUG (connection:995) Scheduling background open thread for qemu+ssh://root@vl403/system
>[Tue, 01 Apr 2014 19:32:04 virt-manager 12103] DEBUG (connection:1012) Background 'open connection' thread is running
>[Tue, 01 Apr 2014 19:32:05 virt-manager 12103] DEBUG (connection:1063) Background open thread complete, scheduling notify
>[Tue, 01 Apr 2014 19:32:05 virt-manager 12103] DEBUG (connection:1068) Notifying open result
>[Tue, 01 Apr 2014 19:32:05 virt-manager 12103] DEBUG (connection:1074) libvirt version=1001003
>[Tue, 01 Apr 2014 19:32:05 virt-manager 12103] DEBUG (connection:1076) daemon version=1001001
>[Tue, 01 Apr 2014 19:32:06 virt-manager 12103] DEBUG (connection:1077) conn version=1005003
>[Tue, 01 Apr 2014 19:32:06 virt-manager 12103] DEBUG (connection:1079) qemu+ssh://root@vl403/system capabilities:
><capabilities>
>
>  <host>
>    <uuid>44454c4c-3100-104e-8034-c6c04f465831</uuid>
>    <cpu>
>      <arch>x86_64</arch>
>      <model>SandyBridge</model>
>      <vendor>Intel</vendor>
>      <topology sockets='1' cores='4' threads='1'/>
>      <feature name='pdpe1gb'/>
>      <feature name='osxsave'/>
>      <feature name='dca'/>
>      <feature name='pcid'/>
>      <feature name='pdcm'/>
>      <feature name='xtpr'/>
>      <feature name='tm2'/>
>      <feature name='est'/>
>      <feature name='smx'/>
>      <feature name='vmx'/>
>      <feature name='ds_cpl'/>
>      <feature name='monitor'/>
>      <feature name='dtes64'/>
>      <feature name='pbe'/>
>      <feature name='tm'/>
>      <feature name='ht'/>
>      <feature name='ss'/>
>      <feature name='acpi'/>
>      <feature name='ds'/>
>      <feature name='vme'/>
>    </cpu>
>    <power_management>
>      <suspend_disk/>
>      <suspend_hybrid/>
>    </power_management>
>    <migration_features>
>      <live/>
>      <uri_transports>
>        <uri_transport>tcp</uri_transport>
>      </uri_transports>
>    </migration_features>
>    <topology>
>      <cells num='2'>
>        <cell id='0'>
>          <memory unit='KiB'>12536364</memory>
>          <cpus num='4'>
>            <cpu id='0' socket_id='0' core_id='0' siblings='0'/>
>            <cpu id='2' socket_id='0' core_id='1' siblings='2'/>
>            <cpu id='4' socket_id='0' core_id='2' siblings='4'/>
>            <cpu id='6' socket_id='0' core_id='3' siblings='6'/>
>          </cpus>
>        </cell>
>        <cell id='1'>
>          <memory unit='KiB'>12582912</memory>
>          <cpus num='4'>
>            <cpu id='1' socket_id='1' core_id='0' siblings='1'/>
>            <cpu id='3' socket_id='1' core_id='1' siblings='3'/>
>            <cpu id='5' socket_id='1' core_id='2' siblings='5'/>
>            <cpu id='7' socket_id='1' core_id='3' siblings='7'/>
>          </cpus>
>        </cell>
>      </cells>
>    </topology>
>    <secmodel>
>      <model>selinux</model>
>      <doi>0</doi>
>    </secmodel>
>    <secmodel>
>      <model>dac</model>
>      <doi>0</doi>
>    </secmodel>
>  </host>
>
>  <guest>
>    <os_type>hvm</os_type>
>    <arch name='i686'>
>      <wordsize>32</wordsize>
>      <emulator>/usr/libexec/qemu-kvm</emulator>
>      <machine canonical='pc-i440fx-rhel7.0.0' maxCpus='255'>pc</machine>
>      <machine canonical='pc-q35-rhel7.0.0' maxCpus='255'>q35</machine>
>      <machine maxCpus='255'>rhel6.0.0</machine>
>      <machine maxCpus='255'>rhel6.1.0</machine>
>      <machine maxCpus='255'>rhel6.2.0</machine>
>      <machine maxCpus='255'>rhel6.3.0</machine>
>      <machine maxCpus='255'>rhel6.4.0</machine>
>      <machine maxCpus='255'>rhel6.5.0</machine>
>      <machine maxCpus='1'>none</machine>
>      <domain type='qemu'>
>      </domain>
>      <domain type='kvm'>
>        <emulator>/usr/libexec/qemu-kvm</emulator>
>      </domain>
>    </arch>
>    <features>
>      <cpuselection/>
>      <deviceboot/>
>      <acpi default='on' toggle='yes'/>
>      <apic default='on' toggle='no'/>
>      <pae/>
>      <nonpae/>
>    </features>
>  </guest>
>
>  <guest>
>    <os_type>hvm</os_type>
>    <arch name='x86_64'>
>      <wordsize>64</wordsize>
>      <emulator>/usr/libexec/qemu-kvm</emulator>
>      <machine canonical='pc-i440fx-rhel7.0.0' maxCpus='255'>pc</machine>
>      <machine canonical='pc-q35-rhel7.0.0' maxCpus='255'>q35</machine>
>      <machine maxCpus='255'>rhel6.0.0</machine>
>      <machine maxCpus='255'>rhel6.1.0</machine>
>      <machine maxCpus='255'>rhel6.2.0</machine>
>      <machine maxCpus='255'>rhel6.3.0</machine>
>      <machine maxCpus='255'>rhel6.4.0</machine>
>      <machine maxCpus='255'>rhel6.5.0</machine>
>      <machine maxCpus='1'>none</machine>
>      <domain type='qemu'>
>      </domain>
>      <domain type='kvm'>
>        <emulator>/usr/libexec/qemu-kvm</emulator>
>      </domain>
>    </arch>
>    <features>
>      <cpuselection/>
>      <deviceboot/>
>      <acpi default='on' toggle='yes'/>
>      <apic default='on' toggle='no'/>
>    </features>
>  </guest>
>
></capabilities>
>
>[Tue, 01 Apr 2014 19:32:06 virt-manager 12103] DEBUG (connection:879) Using domain events
>[Tue, 01 Apr 2014 19:32:06 virt-manager 12103] DEBUG (connection:892) Error registering network events: 'virConnect' object has no attribute 'networkEventRegisterAny'
>[Tue, 01 Apr 2014 19:32:36 virt-manager 12103] DEBUG (connection:217) Using libvirt API for netdev enumeration
>[Tue, 01 Apr 2014 19:32:36 virt-manager 12103] DEBUG (connection:239) Using libvirt API for mediadev enumeration
>[Tue, 01 Apr 2014 19:32:48 virt-manager 12103] DEBUG (details:718) Showing VM details: <vmmDomain object at 0x3183aa0 (virtManager+domain+vmmDomain at 0x7fea8c10fd60)>
>[Tue, 01 Apr 2014 19:32:48 virt-manager 12103] DEBUG (engine:385) window counter incremented to 2
>[Tue, 01 Apr 2014 19:33:51 virt-manager 12103] DEBUG (engine:1068) Starting vm 'rhel7b1-v3'
>[Tue, 01 Apr 2014 19:33:51 virt-manager 12103] DEBUG (asyncjob:194) Creating async job for function cb=<function tmpcb at 0x38ca578>
>[Tue, 01 Apr 2014 19:33:53 virt-manager 12103] DEBUG (console:1494) Starting connect process for proto=spice trans=ssh connhost=vl403 connuser=root connport=None gaddr=127.0.0.1 gport=5900 gtlsport=None gsocket=None
>[timestamp] [threadID] facility level [function call] <message>
>--------------------------------------------------------------------------------
>[ 0.000002] [00002f47] libusb: debug [libusb_init] created default context
>[ 0.000152] [00002f47] libusb: debug [libusb_init] libusb v1.0.18.10866
>[ 0.000247] [00002f47] libusb: debug [find_usbfs_path] found usbfs at /dev/bus/usb
>[ 0.000287] [00002f47] libusb: debug [op_init] bulk continuation flag supported
>[ 0.000315] [00002f47] libusb: debug [op_init] zero length packet flag supported
>[ 0.000389] [00002f47] libusb: debug [op_init] sysfs can relate devices
>[ 0.000435] [00002f47] libusb: debug [op_init] sysfs has complete descriptors
>[ 0.002789] [00002f75] libusb: debug [linux_udev_event_thread_main] udev event thread entering.
>[ 0.004481] [00002f47] libusb: debug [linux_get_device_address] getting address for device: usb3 detached: 0
>[ 0.004522] [00002f47] libusb: debug [linux_get_device_address] scan usb3
>[ 0.004764] [00002f47] libusb: debug [linux_get_device_address] bus=3 dev=1
>[ 0.004788] [00002f47] libusb: debug [linux_enumerate_device] busnum 3 devaddr 1 session_id 769
>[ 0.004813] [00002f47] libusb: debug [linux_enumerate_device] allocating new device for 3/1 (session 769)
>[ 0.005424] [00002f47] libusb: debug [linux_get_device_address] getting address for device: 3-1 detached: 0
>[ 0.005460] [00002f47] libusb: debug [linux_get_device_address] scan 3-1
>[ 0.005689] [00002f47] libusb: debug [linux_get_device_address] bus=3 dev=2
>[ 0.005712] [00002f47] libusb: debug [linux_enumerate_device] busnum 3 devaddr 2 session_id 770
>[ 0.005739] [00002f47] libusb: debug [linux_enumerate_device] allocating new device for 3/2 (session 770)
>[ 0.005955] [00002f47] libusb: debug [linux_get_parent_info] Dev 0x3dcd990 (3-1) has parent 0x3db1770 (usb3) port 1
>[ 0.006487] [00002f47] libusb: debug [linux_get_device_address] getting address for device: 3-2 detached: 0
>[ 0.006525] [00002f47] libusb: debug [linux_get_device_address] scan 3-2
>[ 0.006790] [00002f47] libusb: debug [linux_get_device_address] bus=3 dev=3
>[ 0.006815] [00002f47] libusb: debug [linux_enumerate_device] busnum 3 devaddr 3 session_id 771
>[ 0.006834] [00002f47] libusb: debug [linux_enumerate_device] allocating new device for 3/3 (session 771)
>[ 0.007074] [00002f47] libusb: debug [linux_get_parent_info] Dev 0x3d3fdf0 (3-2) has parent 0x3db1770 (usb3) port 2
>[ 0.007414] [00002f47] libusb: debug [linux_get_device_address] getting address for device: usb4 detached: 0
>[ 0.007447] [00002f47] libusb: debug [linux_get_device_address] scan usb4
>[ 0.007687] [00002f47] libusb: debug [linux_get_device_address] bus=4 dev=1
>[ 0.007710] [00002f47] libusb: debug [linux_enumerate_device] busnum 4 devaddr 1 session_id 1025
>[ 0.007734] [00002f47] libusb: debug [linux_enumerate_device] allocating new device for 4/1 (session 1025)
>[ 0.008222] [00002f47] libusb: debug [linux_get_device_address] getting address for device: usb1 detached: 0
>[ 0.008247] [00002f47] libusb: debug [linux_get_device_address] scan usb1
>[ 0.008501] [00002f47] libusb: debug [linux_get_device_address] bus=1 dev=1
>[ 0.008534] [00002f47] libusb: debug [linux_enumerate_device] busnum 1 devaddr 1 session_id 257
>[ 0.008559] [00002f47] libusb: debug [linux_enumerate_device] allocating new device for 1/1 (session 257)
>[ 0.009054] [00002f47] libusb: debug [linux_get_device_address] getting address for device: 1-1 detached: 0
>[ 0.009078] [00002f47] libusb: debug [linux_get_device_address] scan 1-1
>[ 0.009321] [00002f47] libusb: debug [linux_get_device_address] bus=1 dev=2
>[ 0.009353] [00002f47] libusb: debug [linux_enumerate_device] busnum 1 devaddr 2 session_id 258
>[ 0.009403] [00002f47] libusb: debug [linux_enumerate_device] allocating new device for 1/2 (session 258)
>[ 0.009625] [00002f47] libusb: debug [linux_get_parent_info] Dev 0x394fed0 (1-1) has parent 0x394fe10 (usb1) port 1
>[ 0.009934] [00002f47] libusb: debug [linux_get_device_address] getting address for device: 1-1.6 detached: 0
>[ 0.009957] [00002f47] libusb: debug [linux_get_device_address] scan 1-1.6
>[ 0.010188] [00002f47] libusb: debug [linux_get_device_address] bus=1 dev=3
>[ 0.010211] [00002f47] libusb: debug [linux_enumerate_device] busnum 1 devaddr 3 session_id 259
>[ 0.010231] [00002f47] libusb: debug [linux_enumerate_device] allocating new device for 1/3 (session 259)
>[ 0.010430] [00002f47] libusb: debug [linux_get_parent_info] Dev 0x3969ea0 (1-1.6) has parent 0x394fed0 (1-1) port 6
>[ 0.010996] [00002f47] libusb: debug [linux_get_device_address] getting address for device: usb2 detached: 0
>[ 0.011018] [00002f47] libusb: debug [linux_get_device_address] scan usb2
>[ 0.011249] [00002f47] libusb: debug [linux_get_device_address] bus=2 dev=1
>[ 0.011271] [00002f47] libusb: debug [linux_enumerate_device] busnum 2 devaddr 1 session_id 513
>[ 0.011292] [00002f47] libusb: debug [linux_enumerate_device] allocating new device for 2/1 (session 513)
>[ 0.011770] [00002f47] libusb: debug [linux_get_device_address] getting address for device: 2-1 detached: 0
>[ 0.011800] [00002f47] libusb: debug [linux_get_device_address] scan 2-1
>[ 0.012029] [00002f47] libusb: debug [linux_get_device_address] bus=2 dev=2
>[ 0.012052] [00002f47] libusb: debug [linux_enumerate_device] busnum 2 devaddr 2 session_id 514
>[ 0.012074] [00002f47] libusb: debug [linux_enumerate_device] allocating new device for 2/2 (session 514)
>[ 0.012282] [00002f47] libusb: debug [linux_get_parent_info] Dev 0x2732880 (2-1) has parent 0x3969f60 (usb2) port 1
>[ 0.012518] [00002f47] libusb: debug [usbi_add_pollfd] add fd 31 events 1
>[ 0.012570] [00002f47] libusb: debug [usbi_add_pollfd] add fd 33 events 1
>[ 0.012615] [00002f47] libusb: debug [usbi_io_init] using timerfd for timeouts
>[ 0.012634] [00002f47] libusb: debug [usbi_add_pollfd] add fd 35 events 1
>[ 0.012670] [00002f47] libusb: debug [libusb_get_device_list] 
>[ 0.012709] [00002f47] libusb: debug [discovered_devs_append] need to increase capacity
>[ 0.012953] [00002f76] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
>[ 0.013015] [00002f76] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
>[Tue, 01 Apr 2014 19:33:54 virt-manager 12103] DEBUG (console:271) Creating SSH tunnel: ssh -l root vl403 sh -c 'nc -q 2>&1 | grep "requires an argument" >/dev/null;if [ $? -eq 0 ] ; then   CMD="nc -q 0 127.0.0.1 5900";else   CMD="nc 127.0.0.1 5900";fi;eval "$CMD";'
>[Tue, 01 Apr 2014 19:33:54 virt-manager 12103] DEBUG (console:291) Open  tunnel PID=12151 OUTFD=24 ERRFD=26
>[ 1.319171] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[ 1.319302] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[ 1.319359] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[ 1.319484] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[ 1.319563] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[ 1.319606] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[ 1.319649] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[ 1.319676] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[ 1.319697] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[ 1.319719] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[ 1.319737] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[ 1.319760] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[Tue, 01 Apr 2014 19:33:57 virt-manager 12103] DEBUG (console:671) Opening tunnel for channel: <RecordChannel object at 0x3de2870 (SpiceRecordChannel at 0x3daf2a0)>
>[Tue, 01 Apr 2014 19:33:57 virt-manager 12103] DEBUG (console:671) Opening tunnel for channel: <PlaybackChannel object at 0x3de2870 (SpicePlaybackChannel at 0x3f79bd0)>
>[Tue, 01 Apr 2014 19:33:57 virt-manager 12103] DEBUG (console:671) Opening tunnel for channel: <DisplayChannel object at 0x3de2870 (SpiceDisplayChannel at 0x3db2a50)>
>[Tue, 01 Apr 2014 19:33:57 virt-manager 12103] DEBUG (console:671) Opening tunnel for channel: <CursorChannel object at 0x3de29b0 (SpiceCursorChannel at 0x396f890)>
>[Tue, 01 Apr 2014 19:33:57 virt-manager 12103] DEBUG (console:1401) Viewer connected
>[Tue, 01 Apr 2014 19:33:57 virt-manager 12103] DEBUG (console:671) Opening tunnel for channel: <InputsChannel object at 0x3ddd5f0 (SpiceInputsChannel at 0x3cff220)>
>[Tue, 01 Apr 2014 19:33:57 virt-manager 12103] DEBUG (console:271) Creating SSH tunnel: ssh -l root vl403 sh -c 'nc -q 2>&1 | grep "requires an argument" >/dev/null;if [ $? -eq 0 ] ; then   CMD="nc -q 0 127.0.0.1 5900";else   CMD="nc 127.0.0.1 5900";fi;eval "$CMD";'
>[Tue, 01 Apr 2014 19:33:57 virt-manager 12103] DEBUG (console:291) Open  tunnel PID=12153 OUTFD=27 ERRFD=37
>[Tue, 01 Apr 2014 19:33:58 virt-manager 12103] DEBUG (console:271) Creating SSH tunnel: ssh -l root vl403 sh -c 'nc -q 2>&1 | grep "requires an argument" >/dev/null;if [ $? -eq 0 ] ; then   CMD="nc -q 0 127.0.0.1 5900";else   CMD="nc 127.0.0.1 5900";fi;eval "$CMD";'
>[Tue, 01 Apr 2014 19:33:58 virt-manager 12103] DEBUG (console:291) Open  tunnel PID=12154 OUTFD=39 ERRFD=41
>[Tue, 01 Apr 2014 19:33:59 virt-manager 12103] DEBUG (console:271) Creating SSH tunnel: ssh -l root vl403 sh -c 'nc -q 2>&1 | grep "requires an argument" >/dev/null;if [ $? -eq 0 ] ; then   CMD="nc -q 0 127.0.0.1 5900";else   CMD="nc 127.0.0.1 5900";fi;eval "$CMD";'
>[Tue, 01 Apr 2014 19:33:59 virt-manager 12103] DEBUG (console:291) Open  tunnel PID=12155 OUTFD=43 ERRFD=45
>[Tue, 01 Apr 2014 19:33:59 virt-manager 12103] DEBUG (console:271) Creating SSH tunnel: ssh -l root vl403 sh -c 'nc -q 2>&1 | grep "requires an argument" >/dev/null;if [ $? -eq 0 ] ; then   CMD="nc -q 0 127.0.0.1 5900";else   CMD="nc 127.0.0.1 5900";fi;eval "$CMD";'
>[Tue, 01 Apr 2014 19:33:59 virt-manager 12103] DEBUG (console:291) Open  tunnel PID=12156 OUTFD=47 ERRFD=49
>[Tue, 01 Apr 2014 19:34:00 virt-manager 12103] DEBUG (console:271) Creating SSH tunnel: ssh -l root vl403 sh -c 'nc -q 2>&1 | grep "requires an argument" >/dev/null;if [ $? -eq 0 ] ; then   CMD="nc -q 0 127.0.0.1 5900";else   CMD="nc 127.0.0.1 5900";fi;eval "$CMD";'
>[Tue, 01 Apr 2014 19:34:00 virt-manager 12103] DEBUG (console:291) Open  tunnel PID=12157 OUTFD=51 ERRFD=53
>[60.038320] [00002f76] libusb: debug [handle_events] poll() returned 0
>[60.038467] [00002f76] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
>[60.038487] [00002f76] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
>[120.098516] [00002f76] libusb: debug [handle_events] poll() returned 0
>[120.098582] [00002f76] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
>[120.098613] [00002f76] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
>[Tue, 01 Apr 2014 19:36:30 virt-manager 12103] DEBUG (details:718) Showing VM details: <vmmDomain object at 0x2b38a50 (virtManager+domain+vmmDomain at 0x2cd3ca0)>
>[Tue, 01 Apr 2014 19:36:30 virt-manager 12103] DEBUG (engine:385) window counter incremented to 3
>[Tue, 01 Apr 2014 19:36:30 virt-manager 12103] DEBUG (console:1494) Starting connect process for proto=spice trans=ssh connhost=vl402 connuser=root connport=None gaddr=127.0.0.1 gport=5900 gtlsport=None gsocket=None
>[157.051915] [00002f47] libusb: debug [libusb_init] libusb v1.0.18.10866
>[157.051993] [00002f47] libusb: debug [find_usbfs_path] found usbfs at /dev/bus/usb
>[157.052016] [00002f47] libusb: debug [op_init] bulk continuation flag supported
>[157.052040] [00002f47] libusb: debug [op_init] zero length packet flag supported
>[157.052138] [00002f47] libusb: debug [op_init] sysfs can relate devices
>[157.052158] [00002f47] libusb: debug [op_init] sysfs has complete descriptors
>[157.053181] [00002f47] libusb: debug [linux_get_device_address] getting address for device: usb3 detached: 0
>[157.053213] [00002f47] libusb: debug [linux_get_device_address] scan usb3
>[157.053426] [00002f47] libusb: debug [linux_get_device_address] bus=3 dev=1
>[157.053456] [00002f47] libusb: debug [linux_enumerate_device] busnum 3 devaddr 1 session_id 769
>[157.053517] [00002f47] libusb: debug [linux_enumerate_device] allocating new device for 3/1 (session 769)
>[157.053951] [00002f47] libusb: debug [linux_get_device_address] getting address for device: 3-1 detached: 0
>[157.053973] [00002f47] libusb: debug [linux_get_device_address] scan 3-1
>[157.054169] [00002f47] libusb: debug [linux_get_device_address] bus=3 dev=2
>[157.054190] [00002f47] libusb: debug [linux_enumerate_device] busnum 3 devaddr 2 session_id 770
>[157.054214] [00002f47] libusb: debug [linux_enumerate_device] allocating new device for 3/2 (session 770)
>[157.054395] [00002f47] libusb: debug [linux_get_parent_info] Dev 0x423f010 (3-1) has parent 0x425fe70 (usb3) port 1
>[157.054816] [00002f47] libusb: debug [linux_get_device_address] getting address for device: 3-2 detached: 0
>[157.054837] [00002f47] libusb: debug [linux_get_device_address] scan 3-2
>[157.055034] [00002f47] libusb: debug [linux_get_device_address] bus=3 dev=3
>[157.055054] [00002f47] libusb: debug [linux_enumerate_device] busnum 3 devaddr 3 session_id 771
>[157.055078] [00002f47] libusb: debug [linux_enumerate_device] allocating new device for 3/3 (session 771)
>[157.055255] [00002f47] libusb: debug [linux_get_parent_info] Dev 0x423f0d0 (3-2) has parent 0x425fe70 (usb3) port 2
>[157.055530] [00002f47] libusb: debug [linux_get_device_address] getting address for device: usb4 detached: 0
>[157.055558] [00002f47] libusb: debug [linux_get_device_address] scan usb4
>[157.055755] [00002f47] libusb: debug [linux_get_device_address] bus=4 dev=1
>[157.055775] [00002f47] libusb: debug [linux_enumerate_device] busnum 4 devaddr 1 session_id 1025
>[157.055799] [00002f47] libusb: debug [linux_enumerate_device] allocating new device for 4/1 (session 1025)
>[157.056209] [00002f47] libusb: debug [linux_get_device_address] getting address for device: usb1 detached: 0
>[157.056230] [00002f47] libusb: debug [linux_get_device_address] scan usb1
>[157.056431] [00002f47] libusb: debug [linux_get_device_address] bus=1 dev=1
>[157.056459] [00002f47] libusb: debug [linux_enumerate_device] busnum 1 devaddr 1 session_id 257
>[157.056482] [00002f47] libusb: debug [linux_enumerate_device] allocating new device for 1/1 (session 257)
>[157.056897] [00002f47] libusb: debug [linux_get_device_address] getting address for device: 1-1 detached: 0
>[157.056917] [00002f47] libusb: debug [linux_get_device_address] scan 1-1
>[157.057113] [00002f47] libusb: debug [linux_get_device_address] bus=1 dev=2
>[157.057133] [00002f47] libusb: debug [linux_enumerate_device] busnum 1 devaddr 2 session_id 258
>[157.057158] [00002f47] libusb: debug [linux_enumerate_device] allocating new device for 1/2 (session 258)
>[157.057333] [00002f47] libusb: debug [linux_get_parent_info] Dev 0x3e80b90 (1-1) has parent 0x41f60d0 (usb1) port 1
>[157.057497] [00002f47] libusb: debug [linux_get_device_address] getting address for device: 1-1.6 detached: 0
>[157.057525] [00002f47] libusb: debug [linux_get_device_address] scan 1-1.6
>[157.057721] [00002f47] libusb: debug [linux_get_device_address] bus=1 dev=3
>[157.057741] [00002f47] libusb: debug [linux_enumerate_device] busnum 1 devaddr 3 session_id 259
>[157.057765] [00002f47] libusb: debug [linux_enumerate_device] allocating new device for 1/3 (session 259)
>[157.057941] [00002f47] libusb: debug [linux_get_parent_info] Dev 0x3e80c50 (1-1.6) has parent 0x3e80b90 (1-1) port 6
>[157.058459] [00002f47] libusb: debug [linux_get_device_address] getting address for device: usb2 detached: 0
>[157.058486] [00002f47] libusb: debug [linux_get_device_address] scan usb2
>[157.058678] [00002f47] libusb: debug [linux_get_device_address] bus=2 dev=1
>[157.058699] [00002f47] libusb: debug [linux_enumerate_device] busnum 2 devaddr 1 session_id 513
>[157.058722] [00002f47] libusb: debug [linux_enumerate_device] allocating new device for 2/1 (session 513)
>[157.059116] [00002f47] libusb: debug [linux_get_device_address] getting address for device: 2-1 detached: 0
>[157.059136] [00002f47] libusb: debug [linux_get_device_address] scan 2-1
>[157.059375] [00002f47] libusb: debug [linux_get_device_address] bus=2 dev=2
>[157.059427] [00002f47] libusb: debug [linux_enumerate_device] busnum 2 devaddr 2 session_id 514
>[157.059449] [00002f47] libusb: debug [linux_enumerate_device] allocating new device for 2/2 (session 514)
>[157.059622] [00002f47] libusb: debug [linux_get_parent_info] Dev 0x3f23eb0 (2-1) has parent 0x3f23e10 (usb2) port 1
>[157.059798] [00002f47] libusb: debug [usbi_add_pollfd] add fd 40 events 1
>[157.059846] [00002f47] libusb: debug [usbi_add_pollfd] add fd 43 events 1
>[157.059880] [00002f47] libusb: debug [usbi_io_init] using timerfd for timeouts
>[157.059900] [00002f47] libusb: debug [usbi_add_pollfd] add fd 46 events 1
>[157.059924] [00002f47] libusb: debug [libusb_get_device_list] 
>[157.059949] [00002f47] libusb: debug [discovered_devs_append] need to increase capacity
>[157.060197] [00002fbf] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
>[157.060305] [00002fbf] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
>[Tue, 01 Apr 2014 19:36:31 virt-manager 12103] DEBUG (console:271) Creating SSH tunnel: ssh -l root vl402 sh -c 'nc -q 2>&1 | grep "requires an argument" >/dev/null;if [ $? -eq 0 ] ; then   CMD="nc -q 0 127.0.0.1 5900";else   CMD="nc 127.0.0.1 5900";fi;eval "$CMD";'
>[Tue, 01 Apr 2014 19:36:31 virt-manager 12103] DEBUG (console:291) Open  tunnel PID=12224 OUTFD=27 ERRFD=38
>[157.482544] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[157.482608] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[157.482740] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[157.482829] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[157.482973] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[157.483019] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[157.483106] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[157.483200] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[157.483286] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[157.483412] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[157.483446] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[157.483543] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[Tue, 01 Apr 2014 19:36:33 virt-manager 12103] DEBUG (console:671) Opening tunnel for channel: <RecordChannel object at 0x38c5cd0 (SpiceRecordChannel at 0x42dca20)>
>[Tue, 01 Apr 2014 19:36:33 virt-manager 12103] DEBUG (console:671) Opening tunnel for channel: <PlaybackChannel object at 0x38c5cd0 (SpicePlaybackChannel at 0x42e14a0)>
>[Tue, 01 Apr 2014 19:36:33 virt-manager 12103] DEBUG (console:671) Opening tunnel for channel: <DisplayChannel object at 0x38c5cd0 (SpiceDisplayChannel at 0x42e2940)>
>[Tue, 01 Apr 2014 19:36:33 virt-manager 12103] DEBUG (console:671) Opening tunnel for channel: <CursorChannel object at 0x4167b90 (SpiceCursorChannel at 0x42e1ec0)>
>[Tue, 01 Apr 2014 19:36:33 virt-manager 12103] DEBUG (console:1401) Viewer connected
>
>(virt-manager:12103): GSpice-WARNING **: Error calling 'org.gnome.SessionManager.Inhibit': GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.gnome.SessionManager was not provided by any .service files
>[Tue, 01 Apr 2014 19:36:33 virt-manager 12103] DEBUG (console:671) Opening tunnel for channel: <InputsChannel object at 0x4167fa0 (SpiceInputsChannel at 0x42f1e10)>
>[Tue, 01 Apr 2014 19:36:33 virt-manager 12103] DEBUG (console:271) Creating SSH tunnel: ssh -l root vl402 sh -c 'nc -q 2>&1 | grep "requires an argument" >/dev/null;if [ $? -eq 0 ] ; then   CMD="nc -q 0 127.0.0.1 5900";else   CMD="nc 127.0.0.1 5900";fi;eval "$CMD";'
>[Tue, 01 Apr 2014 19:36:33 virt-manager 12103] DEBUG (console:291) Open  tunnel PID=12226 OUTFD=39 ERRFD=48
>[Tue, 01 Apr 2014 19:36:34 virt-manager 12103] DEBUG (console:271) Creating SSH tunnel: ssh -l root vl402 sh -c 'nc -q 2>&1 | grep "requires an argument" >/dev/null;if [ $? -eq 0 ] ; then   CMD="nc -q 0 127.0.0.1 5900";else   CMD="nc 127.0.0.1 5900";fi;eval "$CMD";'
>[Tue, 01 Apr 2014 19:36:34 virt-manager 12103] DEBUG (console:291) Open  tunnel PID=12227 OUTFD=51 ERRFD=54
>[Tue, 01 Apr 2014 19:36:35 virt-manager 12103] DEBUG (console:271) Creating SSH tunnel: ssh -l root vl402 sh -c 'nc -q 2>&1 | grep "requires an argument" >/dev/null;if [ $? -eq 0 ] ; then   CMD="nc -q 0 127.0.0.1 5900";else   CMD="nc 127.0.0.1 5900";fi;eval "$CMD";'
>[Tue, 01 Apr 2014 19:36:35 virt-manager 12103] DEBUG (console:291) Open  tunnel PID=12228 OUTFD=56 ERRFD=58
>[Tue, 01 Apr 2014 19:36:35 virt-manager 12103] DEBUG (console:271) Creating SSH tunnel: ssh -l root vl402 sh -c 'nc -q 2>&1 | grep "requires an argument" >/dev/null;if [ $? -eq 0 ] ; then   CMD="nc -q 0 127.0.0.1 5900";else   CMD="nc 127.0.0.1 5900";fi;eval "$CMD";'
>[Tue, 01 Apr 2014 19:36:35 virt-manager 12103] DEBUG (console:291) Open  tunnel PID=12229 OUTFD=60 ERRFD=62
>[Tue, 01 Apr 2014 19:36:36 virt-manager 12103] DEBUG (console:271) Creating SSH tunnel: ssh -l root vl402 sh -c 'nc -q 2>&1 | grep "requires an argument" >/dev/null;if [ $? -eq 0 ] ; then   CMD="nc -q 0 127.0.0.1 5900";else   CMD="nc 127.0.0.1 5900";fi;eval "$CMD";'
>[Tue, 01 Apr 2014 19:36:36 virt-manager 12103] DEBUG (console:291) Open  tunnel PID=12230 OUTFD=64 ERRFD=66
>[180.109164] [00002f76] libusb: debug [handle_events] poll() returned 0
>[180.109258] [00002f76] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
>[180.109294] [00002f76] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
>[Tue, 01 Apr 2014 19:37:11 virt-manager 12103] DEBUG (console:1494) Starting connect process for proto=spice trans=ssh connhost=vl403 connuser=root connport=None gaddr=127.0.0.1 gport=5900 gtlsport=None gsocket=None
>[197.642543] [00002f47] libusb: debug [libusb_init] libusb v1.0.18.10866
>[197.642618] [00002f47] libusb: debug [find_usbfs_path] found usbfs at /dev/bus/usb
>[197.642637] [00002f47] libusb: debug [op_init] bulk continuation flag supported
>[197.642659] [00002f47] libusb: debug [op_init] zero length packet flag supported
>[197.642728] [00002f47] libusb: debug [op_init] sysfs can relate devices
>[197.642753] [00002f47] libusb: debug [op_init] sysfs has complete descriptors
>[197.643719] [00002f47] libusb: debug [linux_get_device_address] getting address for device: usb3 detached: 0
>[197.643748] [00002f47] libusb: debug [linux_get_device_address] scan usb3
>[197.643977] [00002f47] libusb: debug [linux_get_device_address] bus=3 dev=1
>[197.644002] [00002f47] libusb: debug [linux_enumerate_device] busnum 3 devaddr 1 session_id 769
>[197.644030] [00002f47] libusb: debug [linux_enumerate_device] allocating new device for 3/1 (session 769)
>[197.644546] [00002f47] libusb: debug [linux_get_device_address] getting address for device: 3-1 detached: 0
>[197.644580] [00002f47] libusb: debug [linux_get_device_address] scan 3-1
>[197.644811] [00002f47] libusb: debug [linux_get_device_address] bus=3 dev=2
>[197.644836] [00002f47] libusb: debug [linux_enumerate_device] busnum 3 devaddr 2 session_id 770
>[197.644860] [00002f47] libusb: debug [linux_enumerate_device] allocating new device for 3/2 (session 770)
>[197.645063] [00002f47] libusb: debug [linux_get_parent_info] Dev 0x3ad3750 (3-1) has parent 0x3d6d160 (usb3) port 1
>[197.645500] [00002f47] libusb: debug [linux_get_device_address] getting address for device: 3-2 detached: 0
>[197.645527] [00002f47] libusb: debug [linux_get_device_address] scan 3-2
>[197.645722] [00002f47] libusb: debug [linux_get_device_address] bus=3 dev=3
>[197.645742] [00002f47] libusb: debug [linux_enumerate_device] busnum 3 devaddr 3 session_id 771
>[197.645762] [00002f47] libusb: debug [linux_enumerate_device] allocating new device for 3/3 (session 771)
>[197.645938] [00002f47] libusb: debug [linux_get_parent_info] Dev 0x3bc0200 (3-2) has parent 0x3d6d160 (usb3) port 2
>[197.646197] [00002f47] libusb: debug [linux_get_device_address] getting address for device: usb4 detached: 0
>[197.646218] [00002f47] libusb: debug [linux_get_device_address] scan usb4
>[197.646469] [00002f47] libusb: debug [linux_get_device_address] bus=4 dev=1
>[197.646497] [00002f47] libusb: debug [linux_enumerate_device] busnum 4 devaddr 1 session_id 1025
>[197.646514] [00002f47] libusb: debug [linux_enumerate_device] allocating new device for 4/1 (session 1025)
>[197.646954] [00002f47] libusb: debug [linux_get_device_address] getting address for device: usb1 detached: 0
>[197.646973] [00002f47] libusb: debug [linux_get_device_address] scan usb1
>[197.647167] [00002f47] libusb: debug [linux_get_device_address] bus=1 dev=1
>[197.647186] [00002f47] libusb: debug [linux_enumerate_device] busnum 1 devaddr 1 session_id 257
>[197.647206] [00002f47] libusb: debug [linux_enumerate_device] allocating new device for 1/1 (session 257)
>[197.647604] [00002f47] libusb: debug [linux_get_device_address] getting address for device: 1-1 detached: 0
>[197.647630] [00002f47] libusb: debug [linux_get_device_address] scan 1-1
>[197.647822] [00002f47] libusb: debug [linux_get_device_address] bus=1 dev=2
>[197.647841] [00002f47] libusb: debug [linux_enumerate_device] busnum 1 devaddr 2 session_id 258
>[197.647861] [00002f47] libusb: debug [linux_enumerate_device] allocating new device for 1/2 (session 258)
>[197.648036] [00002f47] libusb: debug [linux_get_parent_info] Dev 0x3c63ee0 (1-1) has parent 0x3b6bf80 (usb1) port 1
>[197.648187] [00002f47] libusb: debug [linux_get_device_address] getting address for device: 1-1.6 detached: 0
>[197.648206] [00002f47] libusb: debug [linux_get_device_address] scan 1-1.6
>[197.648409] [00002f47] libusb: debug [linux_get_device_address] bus=1 dev=3
>[197.648435] [00002f47] libusb: debug [linux_enumerate_device] busnum 1 devaddr 3 session_id 259
>[197.648455] [00002f47] libusb: debug [linux_enumerate_device] allocating new device for 1/3 (session 259)
>[197.648629] [00002f47] libusb: debug [linux_get_parent_info] Dev 0x4029c70 (1-1.6) has parent 0x3c63ee0 (1-1) port 6
>[197.649100] [00002f47] libusb: debug [linux_get_device_address] getting address for device: usb2 detached: 0
>[197.649119] [00002f47] libusb: debug [linux_get_device_address] scan usb2
>[197.649312] [00002f47] libusb: debug [linux_get_device_address] bus=2 dev=1
>[197.649331] [00002f47] libusb: debug [linux_enumerate_device] busnum 2 devaddr 1 session_id 513
>[197.649495] [00002f47] libusb: debug [linux_enumerate_device] allocating new device for 2/1 (session 513)
>[197.649880] [00002f47] libusb: debug [linux_get_device_address] getting address for device: 2-1 detached: 0
>[197.649898] [00002f47] libusb: debug [linux_get_device_address] scan 2-1
>[197.650087] [00002f47] libusb: debug [linux_get_device_address] bus=2 dev=2
>[197.650105] [00002f47] libusb: debug [linux_enumerate_device] busnum 2 devaddr 2 session_id 514
>[197.650124] [00002f47] libusb: debug [linux_enumerate_device] allocating new device for 2/2 (session 514)
>[197.650293] [00002f47] libusb: debug [linux_get_parent_info] Dev 0x3be55c0 (2-1) has parent 0x3d66bf0 (usb2) port 1
>[197.650464] [00002f47] libusb: debug [usbi_add_pollfd] add fd 39 events 1
>[197.650507] [00002f47] libusb: debug [usbi_add_pollfd] add fd 43 events 1
>[197.650534] [00002f47] libusb: debug [usbi_io_init] using timerfd for timeouts
>[197.650556] [00002f47] libusb: debug [usbi_add_pollfd] add fd 47 events 1
>[197.650585] [00002f47] libusb: debug [libusb_get_device_list] 
>[197.650609] [00002f47] libusb: debug [discovered_devs_append] need to increase capacity
>[197.650863] [00002fd7] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
>[197.650942] [00002fd7] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
>[217.120354] [00002fbf] libusb: debug [handle_events] poll() returned 0
>[217.120402] [00002fbf] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
>[217.120424] [00002fbf] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
>[240.169352] [00002f76] libusb: debug [handle_events] poll() returned 0
>[240.169419] [00002f76] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
>[240.169456] [00002f76] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
>[257.694255] [00002fd7] libusb: debug [handle_events] poll() returned 0
>[257.694323] [00002fd7] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
>[257.694407] [00002fd7] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
>[277.174507] [00002fbf] libusb: debug [handle_events] poll() returned 0
>[277.174571] [00002fbf] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
>[277.174590] [00002fbf] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
>[300.217640] [00002f76] libusb: debug [handle_events] poll() returned 0
>[300.217700] [00002f76] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
>[300.217719] [00002f76] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
>[317.701538] [00002fd7] libusb: debug [handle_events] poll() returned 0
>[317.701602] [00002fd7] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
>[317.701624] [00002fd7] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
>[337.181974] [00002fbf] libusb: debug [handle_events] poll() returned 0
>[337.182032] [00002fbf] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
>[337.182059] [00002fbf] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
>[360.240552] [00002f76] libusb: debug [handle_events] poll() returned 0
>[360.240635] [00002f76] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
>[360.240664] [00002f76] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
>[377.726240] [00002fd7] libusb: debug [handle_events] poll() returned 0
>[377.726301] [00002fd7] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
>[377.726323] [00002fd7] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
>[397.228605] [00002fbf] libusb: debug [handle_events] poll() returned 0
>[397.228682] [00002fbf] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
>[397.228710] [00002fbf] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
>[420.300698] [00002f76] libusb: debug [handle_events] poll() returned 0
>[420.300755] [00002f76] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
>[420.300778] [00002f76] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
>[437.784932] [00002fd7] libusb: debug [handle_events] poll() returned 0
>[437.785005] [00002fd7] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
>[437.785034] [00002fd7] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
>
>(virt-manager:12103): GSpice-WARNING **: Error calling 'org.gnome.SessionManager.Inhibit': GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.gnome.SessionManager was not provided by any .service files
>[Tue, 01 Apr 2014 19:41:23 virt-manager 12103] DEBUG (details:735) Closing VM details: <vmmDomain object at 0x2b38a50 (virtManager+domain+vmmDomain at 0x2cd3ca0)>
>[Tue, 01 Apr 2014 19:41:23 virt-manager 12103] DEBUG (engine:389) window counter decremented to 2
>[Tue, 01 Apr 2014 19:41:23 virt-manager 12103] DEBUG (console:271) Creating SSH tunnel: ssh -l root vl403 sh -c 'nc -q 2>&1 | grep "requires an argument" >/dev/null;if [ $? -eq 0 ] ; then   CMD="nc -q 0 127.0.0.1 5900";else   CMD="nc 127.0.0.1 5900";fi;eval "$CMD";'
>[Tue, 01 Apr 2014 19:41:23 virt-manager 12103] DEBUG (console:291) Open  tunnel PID=12391 OUTFD=24 ERRFD=27
>[449.816119] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[449.816168] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[449.816198] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[449.816216] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[449.816253] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[449.816279] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[449.816308] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[449.816332] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[449.816359] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[449.816413] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[449.816511] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>[449.816572] [00002f47] libusb: debug [libusb_get_device_descriptor] 
>
>(virt-manager:12103): GSpice-WARNING **: incomplete link header (0/16)
>[Tue, 01 Apr 2014 19:41:25 virt-manager 12103] DEBUG (details:735) Closing VM details: <vmmDomain object at 0x3183aa0 (virtManager+domain+vmmDomain at 0x7fea8c10fd60)>
>[Tue, 01 Apr 2014 19:41:25 virt-manager 12103] DEBUG (engine:389) window counter decremented to 1
>[Tue, 01 Apr 2014 19:41:27 virt-manager 12103] DEBUG (console:671) Opening tunnel for channel: <MainChannel object at 0x3de2b90 (SpiceMainChannel at 0x4028e90)>
>[Tue, 01 Apr 2014 19:41:27 virt-manager 12103] DEBUG (manager:227) Closing manager
>[Tue, 01 Apr 2014 19:41:27 virt-manager 12103] DEBUG (engine:389) window counter decremented to 0
>
>(virt-manager:12103): GSpice-WARNING **: incomplete link header (0/16)
>[Tue, 01 Apr 2014 19:41:27 virt-manager 12103] DEBUG (console:271) Creating SSH tunnel: ssh -l root vl403 sh -c 'nc -q 2>&1 | grep "requires an argument" >/dev/null;if [ $? -eq 0 ] ; then   CMD="nc -q 0 127.0.0.1 5900";else   CMD="nc 127.0.0.1 5900";fi;eval "$CMD";'
>[Tue, 01 Apr 2014 19:41:27 virt-manager 12103] DEBUG (console:291) Open  tunnel PID=12392 OUTFD=24 ERRFD=37
>[Tue, 01 Apr 2014 19:41:27 virt-manager 12103] DEBUG (details:735) Closing VM details: <vmmDomain object at 0x3183aa0 (virtManager+domain+vmmDomain at 0x7fea8c10fd60)>
>[Tue, 01 Apr 2014 19:41:27 virt-manager 12103] DEBUG (details:735) Closing VM details: <vmmDomain object at 0x2b38a50 (virtManager+domain+vmmDomain at 0x2cd3ca0)>
>[Tue, 01 Apr 2014 19:41:27 virt-manager 12103] DEBUG (engine:471) Exiting app normally.

Actions: View

Attachments on bug 1081227: 881486 | 941230