Menu ▾ ▴

#1199 Timeout reading yubikey on 0.9.3

v1.0_(example)
open
Rony Shapiro
ubuntu linux yubikey (1)
1
2017-11-15
2014-07-06
BaQs
No

passwordsafe-ubuntu-0.93BETA.amd64.deb

I'm using ubuntu 14.04, all packages up to date.

I created a db on windows, and now want to use it under linux.
I use password+yubikey (challenge response)

Everything works fine on windows, but not on linux (inside virtualbox):
"timeout" error in red right after clicking the yubikey icon.

Please do not hesitate to tell me if you need any further information.

Regards

Discussion

  • Rony Shapiro

    Rony Shapiro - 2014-07-07

    I've had mixed results using YubiKey under VirtualBox - worked on some machines, not on others.
    Does the 'lsusb' command recognize the Yubikey device? Here's what I get:
    $ lsusb
    Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
    Bus 002 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
    Bus 002 Device 002: ID 80ee:0021 VirtualBox USB Tablet
    Bus 002 Device 003: ID 1050:0110 Yubico.com

    If not, try adding it via Devices->USB from the VirtualBox toolbar for your VM. Note that it may take a while for the device to be recognized.

    If this doesn't help, make sure you're running the latest version of VirtualBox + guest additions.

     

  • BaQs

    BaQs - 2014-07-07

    The key is well recognized as I use it for LUKS.

    $ lsusb
    Bus 001 Device 003: ID 1050:0010 Yubico.com Yubikey
    Bus 001 Device 002: ID 80ee:0021 VirtualBox USB Tablet
    Bus 001 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub

    I just installed the last guest additions, still the same issue
    One more info: it seems pwsafe does access to the key, as the light shows up when I click on "yubikey" button in pwsafe.

    Also, probably another bug, but strace pwsafe I see it loads a lot of random, but it takes like 10s to boot.

    Is there any debug I could launch ?

     

  • BaQs

    BaQs - 2014-07-07

    Ok, stracing again, there seems to be an infinite loop.
    I was able to reproduce it again on a "non-vm" debian machine:
    $ dmesg | tail -n 20
    [ 566.838088] input: Yubico Yubico Yubikey II as /devices/pci0000:00/0000:00:06.0/usb1/1-2/1-2:1.0/input/input608
    [ 566.843849] hid-generic 0003:1050:0010.025B: input,hidraw1: USB HID v1.11 Keyboard [Yubico Yubico Yubikey II] on usb-0000:00:06.0-2/input0
    [ 567.051517] input: Yubico Yubico Yubikey II as /devices/pci0000:00/0000:00:06.0/usb1/1-2/1-2:1.0/input/input609
    [ 567.052307] hid-generic 0003:1050:0010.025C: input,hidraw1: USB HID v1.11 Keyboard [Yubico Yubico Yubikey II] on usb-0000:00:06.0-2/input0
    [ 567.353331] input: Yubico Yubico Yubikey II as /devices/pci0000:00/0000:00:06.0/usb1/1-2/1-2:1.0/input/input610
    [ 567.354529] hid-generic 0003:1050:0010.025D: input,hidraw1: USB HID v1.11 Keyboard [Yubico Yubico Yubikey II] on usb-0000:00:06.0-2/input0
    [ 567.549562] input: Yubico Yubico Yubikey II as /devices/pci0000:00/0000:00:06.0/usb1/1-2/1-2:1.0/input/input611
    [ 567.550529] hid-generic 0003:1050:0010.025E: input,hidraw1: USB HID v1.11 Keyboard [Yubico Yubico Yubikey II] on usb-0000:00:06.0-2/input0
    [ 567.850704] input: Yubico Yubico Yubikey II as /devices/pci0000:00/0000:00:06.0/usb1/1-2/1-2:1.0/input/input612
    [ 567.851001] hid-generic 0003:1050:0010.025F: input,hidraw1: USB HID v1.11 Keyboard [Yubico Yubico Yubikey II] on usb-0000:00:06.0-2/input0
    [ 568.085482] input: Yubico Yubico Yubikey II as /devices/pci0000:00/0000:00:06.0/usb1/1-2/1-2:1.0/input/input613
    [ 568.086823] hid-generic 0003:1050:0010.0260: input,hidraw1: USB HID v1.11 Keyboard [Yubico Yubico Yubikey II] on usb-0000:00:06.0-2/input0
    [ 568.348052] input: Yubico Yubico Yubikey II as /devices/pci0000:00/0000:00:06.0/usb1/1-2/1-2:1.0/input/input614
    [ 568.348799] hid-generic 0003:1050:0010.0261: input,hidraw1: USB HID v1.11 Keyboard [Yubico Yubico Yubikey II] on usb-0000:00:06.0-2/input0
    [ 568.547497] input: Yubico Yubico Yubikey II as /devices/pci0000:00/0000:00:06.0/usb1/1-2/1-2:1.0/input/input615
    [ 568.548347] hid-generic 0003:1050:0010.0262: input,hidraw1: USB HID v1.11 Keyboard [Yubico Yubico Yubikey II] on usb-0000:00:06.0-2/input0
    [ 568.831673] input: Yubico Yubico Yubikey II as /devices/pci0000:00/0000:00:06.0/usb1/1-2/1-2:1.0/input/input616
    [ 568.834613] hid-generic 0003:1050:0010.0263: input,hidraw1: USB HID v1.11 Keyboard [Yubico Yubico Yubikey II] on usb-0000:00:06.0-2/input0
    [ 569.049361] input: Yubico Yubico Yubikey II as /devices/pci0000:00/0000:00:06.0/usb1/1-2/1-2:1.0/input/input617
    [ 569.049495] hid-generic 0003:1050:0010.0264: input,hidraw1: USB HID v1.11 Keyboard [Yubico Yubico Yubikey II] on usb-0000:00:06.0-2/input0

    the strace:

    ioctl(18, USBDEVFS_REAPURBNDELAY, 0x7fffc3e45e58) = 0
    timerfd_settime(17, 0, {it_interval={0, 0}, it_value={0, 0}}, NULL) = 0
    ioctl(18, USBDEVFS_REAPURBNDELAY, 0x7fffc3e45e58) = -1 EAGAIN (Resource temporarily unavailable)
    ioctl(18, SNDRV_CTL_IOCTL_ELEM_LIST or USBDEVFS_RELEASEINTERFACE, 0x7fffc3e45f8c) = 0
    ioctl(18, SNDRV_CTL_IOCTL_ELEM_READ or USBDEVFS_IOCTL, 0x7fffc3e460a0) = 1
    write(14, "\1", 1) = 1
    read(13, "\1", 1) = 1
    close(18) = 0
    close(13) = 0
    close(14) = 0
    close(15) = 0
    close(16) = 0
    close(17) = 0
    write(12, "\1", 1) = 1
    close(10) = 0
    close(11) = 0
    close(12) = 0
    recvmsg(4, 0x7fffc3e46080, 0) = -1 EAGAIN (Resource temporarily unavailable)
    poll([{fd=3, events=POLLIN}, {fd=4, events=POLLIN}], 2, 0) = 0 (Timeout)
    recvmsg(4, 0x7fffc3e46080, 0) = -1 EAGAIN (Resource temporarily unavailable)
    poll([{fd=3, events=POLLIN}, {fd=4, events=POLLIN}], 2, 214) = 0 (Timeout)
    openat(AT_FDCWD, "/dev/bus/usb", O_RDONLY|O_NONBLOCK|O_DIRECTORY|O_CLOEXEC) = 10
    getdents(10, / 3 entries /, 32768) = 72
    close(10) = 0
    stat("/sys/bus/usb/devices", {st_mode=S_IFDIR|0755, st_size=0, ...}) = 0
    open("/etc/udev/udev.conf", O_RDONLY|O_CLOEXEC) = 10
    fstat(10, {st_mode=S_IFREG|0644, st_size=148, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(10, "# see udev(7) for details\n#\n# ud"..., 4096) = 148
    read(10, "", 4096) = 0
    close(10) = 0
    munmap(0x7f80e749d000, 4096) = 0
    socket(PF_NETLINK, SOCK_RAW|SOCK_CLOEXEC|SOCK_NONBLOCK, 15) = 10
    setsockopt(10, SOL_SOCKET, SO_ATTACH_FILTER, "\10\0\0\0\0\0\0\0pN\344\303\377\177\0\0", 16) = 0
    bind(10, {sa_family=AF_NETLINK, pid=0, groups=00000002}, 12) = 0
    getsockname(10, {sa_family=AF_NETLINK, pid=5117, groups=00000002}, [12]) = 0
    setsockopt(10, SOL_SOCKET, SO_PASSCRED, [1], 4) = 0
    fcntl(10, F_GETFL) = 0x802 (flags O_RDWR|O_NONBLOCK)
    fcntl(10, F_SETFL, O_RDWR|O_NONBLOCK) = 0
    pipe([11, 12]) = 0
    fcntl(12, F_GETFL) = 0x1 (flags O_WRONLY)
    fcntl(12, F_SETFL, O_WRONLY|O_NONBLOCK) = 0
    clone(child_stack=0x7f80d9373cf0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7f80d93749d0, tls=0x7f80d9374700, child_tidptr=0x7f80d93749d0) = 6624
    stat("/sys/subsystem", 0x7fffc3e45e70) = -1 ENOENT (No such file or directory)
    openat(AT_FDCWD, "/sys/bus", O_RDONLY|O_NONBLOCK|O_DIRECTORY|O_CLOEXEC) = 13
    getdents(13, / 29 entries /, 32768) = 800
    openat(AT_FDCWD, "/sys/bus/usb/devices", O_RDONLY|O_NONBLOCK|O_DIRECTORY|O_CLOEXEC) = 14
    getdents(14, / 8 entries /, 32768) = 216
    readlink("/sys/bus/usb/devices/1-1", "../../../devices/pci0000:00/0000"..., 1024) = 49
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-1/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    readlink("/sys/bus/usb/devices/1-2", "../../../devices/pci0000:00/0000"..., 1024) = 49
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-2/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    readlink("/sys/bus/usb/devices/usb1", "../../../devices/pci0000:00/0000"..., 1024) = 45
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    readlink("/sys/bus/usb/devices/1-0:1.0", "../../../devices/pci0000:00/0000"..., 1024) = 53
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-0:1.0/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    readlink("/sys/bus/usb/devices/1-1:1.0", "../../../devices/pci0000:00/0000"..., 1024) = 57
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-1/1-1:1.0/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    readlink("/sys/bus/usb/devices/1-2:1.0", "../../../devices/pci0000:00/0000"..., 1024) = 57
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-2/1-2:1.0/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    getdents(14, / 0 entries /, 32768) = 0
    close(14) = 0
    getdents(13, / 0 entries /, 32768) = 0
    close(13) = 0
    openat(AT_FDCWD, "/sys/class", O_RDONLY|O_NONBLOCK|O_DIRECTORY|O_CLOEXEC) = 13
    getdents(13, / 51 entries /, 32768) = 1512
    getdents(13, / 0 entries /, 32768) = 0
    close(13) = 0
    readlink("/sys/devices/pci0000:00/0000:00:06.0/usb1", 0x7fffc3e45210, 1024) = -1 EINVAL (Invalid argument)
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    open("/sys/devices/pci0000:00/0000:00:06.0/usb1/uevent", O_RDONLY|O_CLOEXEC) = 13
    fstat(13, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "MAJOR=189\nMINOR=0\nDEVNAME=bus/us"..., 4096) = 124
    read(13, "", 4096) = 0
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/usb1/busnum", O_RDONLY) = 13
    fstat(13, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "1\n", 4096) = 2
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/usb1/devnum", O_RDONLY) = 13
    fstat(13, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "1\n", 4096) = 2
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/usb1/speed", O_RDONLY) = 13
    fstat(13, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "12\n", 4096) = 3
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/usb1/descriptors", O_RDONLY) = 13
    read(13, "\22\1\20\1\t\0\0@k\35\1\0\23\3\3\2\1\1\t\2\31\0\1\1\0\340\0\t\4\0\0\1"..., 1024) = 43
    close(13) = 0
    readlink("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-0:1.0", 0x7fffc3e45210, 1024) = -1 EINVAL (Invalid argument)
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-0:1.0/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    open("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-0:1.0/uevent", O_RDONLY|O_CLOEXEC) = 13
    fstat(13, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "DEVTYPE=usb_interface\nDRIVER=hub"..., 4096) = 138
    read(13, "", 4096) = 0
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    readlink("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-1", 0x7fffc3e45210, 1024) = -1 EINVAL (Invalid argument)
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-1/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    open("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-1/uevent", O_RDONLY|O_CLOEXEC) = 13
    fstat(13, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "MAJOR=189\nMINOR=1\nDEVNAME=bus/us"..., 4096) = 125
    read(13, "", 4096) = 0
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/1-1/busnum", O_RDONLY) = 13
    fstat(13, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "1\n", 4096) = 2
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/1-1/devnum", O_RDONLY) = 13
    fstat(13, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "2\n", 4096) = 2
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/1-1/speed", O_RDONLY) = 13
    fstat(13, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "12\n", 4096) = 3
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/1-1/descriptors", O_RDONLY) = 13
    read(13, "\22\1\20\1\0\0\0\10\356\200!\0\0\1\1\3\0\1\t\2\"\0\1\1\0\2002\t\4\0\0\1"..., 1024) = 52
    close(13) = 0
    readlink("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-1/1-1:1.0", 0x7fffc3e45210, 1024) = -1 EINVAL (Invalid argument)
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-1/1-1:1.0/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    open("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-1/1-1:1.0/uevent", O_RDONLY|O_CLOEXEC) = 13
    fstat(13, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "DEVTYPE=usb_interface\nDRIVER=usb"..., 4096) = 142
    read(13, "", 4096) = 0
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    readlink("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-2", 0x7fffc3e45210, 1024) = -1 EINVAL (Invalid argument)
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-2/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    open("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-2/uevent", O_RDONLY|O_CLOEXEC) = 13
    fstat(13, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "MAJOR=189\nMINOR=2\nDEVNAME=bus/us"..., 4096) = 125
    read(13, "", 4096) = 0
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/1-2/busnum", O_RDONLY) = 13
    fstat(13, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "1\n", 4096) = 2
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/1-2/devnum", O_RDONLY) = 13
    fstat(13, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "3\n", 4096) = 2
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/1-2/speed", O_RDONLY) = 13
    fstat(13, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "12\n", 4096) = 3
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/1-2/descriptors", O_RDONLY) = 13
    read(13, "\22\1\0\2\0\0\0\10P\20\20\0B\2\1\2\0\1\t\2\"\0\1\1\0\200\17\t\4\0\0\1"..., 1024) = 52
    close(13) = 0
    readlink("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-2/1-2:1.0", 0x7fffc3e45210, 1024) = -1 EINVAL (Invalid argument)
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-2/1-2:1.0/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    open("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-2/1-2:1.0/uevent", O_RDONLY|O_CLOEXEC) = 13
    fstat(13, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "DEVTYPE=usb_interface\nDRIVER=usb"..., 4096) = 142
    read(13, "", 4096) = 0
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    pipe([13, 14]) = 0
    fcntl(14, F_GETFL) = 0x1 (flags O_WRONLY)
    fcntl(14, F_SETFL, O_WRONLY|O_NONBLOCK) = 0
    pipe([15, 16]) = 0
    fcntl(16, F_GETFL) = 0x1 (flags O_WRONLY)
    fcntl(16, F_SETFL, O_WRONLY|O_NONBLOCK) = 0
    timerfd_create(CLOCK_MONOTONIC, 0x800 / TFD_??? /) = 17
    recvmsg(10, 0x7fffc3e43e90, 0) = -1 EAGAIN (Resource temporarily unavailable)
    open("/dev/bus/usb/001/003", O_RDWR) = 18
    ioctl(18, SNDRV_CTL_IOCTL_TLV_READ or USBDEVFS_GET_CAPABILITIES, 0xd11fe0) = 0
    write(14, "\1", 1) = 1
    read(13, "\1", 1) = 1
    ioctl(18, USBDEVFS_GETDRIVER, 0x7fffc3e45ef0) = 0
    ioctl(18, SNDRV_CTL_IOCTL_ELEM_READ or USBDEVFS_IOCTL, 0x7fffc3e45ee0) = 0
    ioctl(18, USBDEVFS_SETCONFIGURATION, 0x7fffc3e45ffc) = 0
    ioctl(18, USBDEVFS_CLAIMINTERFACE, 0x7fffc3e45fac) = 0
    timerfd_settime(17, TFD_TIMER_ABSTIME, {it_interval={0, 0}, it_value={569, 819596000}}, NULL) = 0
    ioctl(18, USBDEVFS_SUBMITURB, 0xc8a2c0) = 0
    poll([{fd=13, events=POLLIN}, {fd=15, events=POLLIN}, {fd=17, events=POLLIN}, {fd=18, events=POLLOUT}], 4, 60000) = 1 ([{fd=18, revents=POLLOUT}])
    ioctl(18, USBDEVFS_REAPURBNDELAY, 0x7fffc3e45e58) = 0
    timerfd_settime(17, 0, {it_interval={0, 0}, it_value={0, 0}}, NULL) = 0
    ioctl(18, USBDEVFS_REAPURBNDELAY, 0x7fffc3e45e58) = -1 EAGAIN (Resource temporarily unavailable)
    ioctl(18, SNDRV_CTL_IOCTL_ELEM_LIST or USBDEVFS_RELEASEINTERFACE, 0x7fffc3e45f8c) = 0
    ioctl(18, SNDRV_CTL_IOCTL_ELEM_READ or USBDEVFS_IOCTL, 0x7fffc3e460a0) = 1
    write(14, "\1", 1) = 1
    read(13, "\1", 1) = 1
    close(18) = 0
    close(13) = 0
    close(14) = 0
    close(15) = 0
    close(16) = 0
    close(17) = 0
    write(12, "\1", 1) = 1
    close(10) = 0
    close(11) = 0
    close(12) = 0
    recvmsg(4, 0x7fffc3e46080, 0) = -1 EAGAIN (Resource temporarily unavailable)
    poll([{fd=3, events=POLLIN}, {fd=4, events=POLLIN}], 2, 0) = 0 (Timeout)
    recvmsg(4, 0x7fffc3e46080, 0) = -1 EAGAIN (Resource temporarily unavailable)
    poll([{fd=3, events=POLLIN}, {fd=4, events=POLLIN}], 2, 169) = 0 (Timeout)
    openat(AT_FDCWD, "/dev/bus/usb", O_RDONLY|O_NONBLOCK|O_DIRECTORY|O_CLOEXEC) = 10
    getdents(10, / 3 entries /, 32768) = 72
    close(10) = 0
    stat("/sys/bus/usb/devices", {st_mode=S_IFDIR|0755, st_size=0, ...}) = 0
    open("/etc/udev/udev.conf", O_RDONLY|O_CLOEXEC) = 10
    fstat(10, {st_mode=S_IFREG|0644, st_size=148, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(10, "# see udev(7) for details\n#\n# ud"..., 4096) = 148
    read(10, "", 4096) = 0
    close(10) = 0
    munmap(0x7f80e749d000, 4096) = 0
    socket(PF_NETLINK, SOCK_RAW|SOCK_CLOEXEC|SOCK_NONBLOCK, 15) = 10
    setsockopt(10, SOL_SOCKET, SO_ATTACH_FILTER, "\10\0\0\0\0\0\0\0pN\344\303\377\177\0\0", 16) = 0
    bind(10, {sa_family=AF_NETLINK, pid=0, groups=00000002}, 12) = 0
    getsockname(10, {sa_family=AF_NETLINK, pid=5117, groups=00000002}, [12]) = 0
    setsockopt(10, SOL_SOCKET, SO_PASSCRED, [1], 4) = 0
    fcntl(10, F_GETFL) = 0x802 (flags O_RDWR|O_NONBLOCK)
    fcntl(10, F_SETFL, O_RDWR|O_NONBLOCK) = 0
    pipe([11, 12]) = 0
    fcntl(12, F_GETFL) = 0x1 (flags O_WRONLY)
    fcntl(12, F_SETFL, O_WRONLY|O_NONBLOCK) = 0
    clone(child_stack=0x7f80d9373cf0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7f80d93749d0, tls=0x7f80d9374700, child_tidptr=0x7f80d93749d0) = 6629
    stat("/sys/subsystem", 0x7fffc3e45e70) = -1 ENOENT (No such file or directory)
    openat(AT_FDCWD, "/sys/bus", O_RDONLY|O_NONBLOCK|O_DIRECTORY|O_CLOEXEC) = 13
    getdents(13, / 29 entries /, 32768) = 800
    openat(AT_FDCWD, "/sys/bus/usb/devices", O_RDONLY|O_NONBLOCK|O_DIRECTORY|O_CLOEXEC) = 14
    getdents(14, / 8 entries /, 32768) = 216
    readlink("/sys/bus/usb/devices/1-1", "../../../devices/pci0000:00/0000"..., 1024) = 49
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-1/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    readlink("/sys/bus/usb/devices/1-2", "../../../devices/pci0000:00/0000"..., 1024) = 49
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-2/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    readlink("/sys/bus/usb/devices/usb1", "../../../devices/pci0000:00/0000"..., 1024) = 45
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    readlink("/sys/bus/usb/devices/1-0:1.0", "../../../devices/pci0000:00/0000"..., 1024) = 53
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-0:1.0/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    readlink("/sys/bus/usb/devices/1-1:1.0", "../../../devices/pci0000:00/0000"..., 1024) = 57
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-1/1-1:1.0/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    readlink("/sys/bus/usb/devices/1-2:1.0", "../../../devices/pci0000:00/0000"..., 1024) = 57
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-2/1-2:1.0/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    getdents(14, / 0 entries /, 32768) = 0
    close(14) = 0
    getdents(13, / 0 entries /, 32768) = 0
    close(13) = 0
    openat(AT_FDCWD, "/sys/class", O_RDONLY|O_NONBLOCK|O_DIRECTORY|O_CLOEXEC) = 13
    getdents(13, / 51 entries /, 32768) = 1512
    getdents(13, / 0 entries /, 32768) = 0
    close(13) = 0
    readlink("/sys/devices/pci0000:00/0000:00:06.0/usb1", 0x7fffc3e45210, 1024) = -1 EINVAL (Invalid argument)
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    open("/sys/devices/pci0000:00/0000:00:06.0/usb1/uevent", O_RDONLY|O_CLOEXEC) = 13
    fstat(13, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "MAJOR=189\nMINOR=0\nDEVNAME=bus/us"..., 4096) = 124
    read(13, "", 4096) = 0
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/usb1/busnum", O_RDONLY) = 13
    fstat(13, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "1\n", 4096) = 2
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/usb1/devnum", O_RDONLY) = 13
    fstat(13, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "1\n", 4096) = 2
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/usb1/speed", O_RDONLY) = 13
    fstat(13, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "12\n", 4096) = 3
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/usb1/descriptors", O_RDONLY) = 13
    read(13, "\22\1\20\1\t\0\0@k\35\1\0\23\3\3\2\1\1\t\2\31\0\1\1\0\340\0\t\4\0\0\1"..., 1024) = 43
    close(13) = 0
    readlink("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-0:1.0", 0x7fffc3e45210, 1024) = -1 EINVAL (Invalid argument)
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-0:1.0/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    open("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-0:1.0/uevent", O_RDONLY|O_CLOEXEC) = 13
    fstat(13, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "DEVTYPE=usb_interface\nDRIVER=hub"..., 4096) = 138
    read(13, "", 4096) = 0
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    readlink("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-1", 0x7fffc3e45210, 1024) = -1 EINVAL (Invalid argument)
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-1/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    open("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-1/uevent", O_RDONLY|O_CLOEXEC) = 13
    fstat(13, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "MAJOR=189\nMINOR=1\nDEVNAME=bus/us"..., 4096) = 125
    read(13, "", 4096) = 0
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/1-1/busnum", O_RDONLY) = 13
    fstat(13, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "1\n", 4096) = 2
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/1-1/devnum", O_RDONLY) = 13
    fstat(13, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "2\n", 4096) = 2
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/1-1/speed", O_RDONLY) = 13
    fstat(13, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "12\n", 4096) = 3
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/1-1/descriptors", O_RDONLY) = 13
    read(13, "\22\1\20\1\0\0\0\10\356\200!\0\0\1\1\3\0\1\t\2\"\0\1\1\0\2002\t\4\0\0\1"..., 1024) = 52
    close(13) = 0
    readlink("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-1/1-1:1.0", 0x7fffc3e45210, 1024) = -1 EINVAL (Invalid argument)
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-1/1-1:1.0/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    open("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-1/1-1:1.0/uevent", O_RDONLY|O_CLOEXEC) = 13
    fstat(13, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "DEVTYPE=usb_interface\nDRIVER=usb"..., 4096) = 142
    read(13, "", 4096) = 0
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    readlink("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-2", 0x7fffc3e45210, 1024) = -1 EINVAL (Invalid argument)
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-2/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    open("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-2/uevent", O_RDONLY|O_CLOEXEC) = 13
    fstat(13, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "MAJOR=189\nMINOR=2\nDEVNAME=bus/us"..., 4096) = 125
    read(13, "", 4096) = 0
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/1-2/busnum", O_RDONLY) = 13
    fstat(13, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "1\n", 4096) = 2
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/1-2/devnum", O_RDONLY) = 13
    fstat(13, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "3\n", 4096) = 2
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/1-2/speed", O_RDONLY) = 13
    fstat(13, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "12\n", 4096) = 3
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/1-2/descriptors", O_RDONLY) = 13
    read(13, "\22\1\0\2\0\0\0\10P\20\20\0B\2\1\2\0\1\t\2\"\0\1\1\0\200\17\t\4\0\0\1"..., 1024) = 52
    close(13) = 0
    readlink("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-2/1-2:1.0", 0x7fffc3e45210, 1024) = -1 EINVAL (Invalid argument)
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-2/1-2:1.0/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    open("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-2/1-2:1.0/uevent", O_RDONLY|O_CLOEXEC) = 13
    fstat(13, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "DEVTYPE=usb_interface\nDRIVER=usb"..., 4096) = 142
    read(13, "", 4096) = 0
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    pipe([13, 14]) = 0
    fcntl(14, F_GETFL) = 0x1 (flags O_WRONLY)
    fcntl(14, F_SETFL, O_WRONLY|O_NONBLOCK) = 0
    pipe([15, 16]) = 0
    fcntl(16, F_GETFL) = 0x1 (flags O_WRONLY)
    fcntl(16, F_SETFL, O_WRONLY|O_NONBLOCK) = 0
    timerfd_create(CLOCK_MONOTONIC, 0x800 / TFD_??? /) = 17
    recvmsg(10, 0x7fffc3e43e90, 0) = -1 EAGAIN (Resource temporarily unavailable)
    open("/dev/bus/usb/001/003", O_RDWR) = 18
    ioctl(18, SNDRV_CTL_IOCTL_TLV_READ or USBDEVFS_GET_CAPABILITIES, 0xd11fe0) = 0
    write(14, "\1", 1) = 1
    read(13, "\1", 1) = 1
    ioctl(18, USBDEVFS_GETDRIVER, 0x7fffc3e45ef0) = 0
    ioctl(18, SNDRV_CTL_IOCTL_ELEM_READ or USBDEVFS_IOCTL, 0x7fffc3e45ee0) = 0
    ioctl(18, USBDEVFS_SETCONFIGURATION, 0x7fffc3e45ffc) = 0
    ioctl(18, USBDEVFS_CLAIMINTERFACE, 0x7fffc3e45fac) = 0
    timerfd_settime(17, TFD_TIMER_ABSTIME, {it_interval={0, 0}, it_value={570, 38967000}}, NULL) = 0
    ioctl(18, USBDEVFS_SUBMITURB, 0xc8a2c0) = 0
    poll([{fd=13, events=POLLIN}, {fd=15, events=POLLIN}, {fd=17, events=POLLIN}, {fd=18, events=POLLOUT}], 4, 60000) = 1 ([{fd=18, revents=POLLOUT}])
    ioctl(18, USBDEVFS_REAPURBNDELAY, 0x7fffc3e45e58) = 0
    timerfd_settime(17, 0, {it_interval={0, 0}, it_value={0, 0}}, NULL) = 0
    ioctl(18, USBDEVFS_REAPURBNDELAY, 0x7fffc3e45e58) = -1 EAGAIN (Resource temporarily unavailable)
    ioctl(18, SNDRV_CTL_IOCTL_ELEM_LIST or USBDEVFS_RELEASEINTERFACE, 0x7fffc3e45f8c) = 0
    ioctl(18, SNDRV_CTL_IOCTL_ELEM_READ or USBDEVFS_IOCTL, 0x7fffc3e460a0) = 1
    write(14, "\1", 1) = 1
    read(13, "\1", 1) = 1
    close(18) = 0
    close(13) = 0
    close(14) = 0
    close(15) = 0
    close(16) = 0
    close(17) = 0
    write(12, "\1", 1) = 1
    close(10) = 0
    close(11) = 0
    close(12) = 0
    recvmsg(4, 0x7fffc3e46080, 0) = -1 EAGAIN (Resource temporarily unavailable)
    poll([{fd=3, events=POLLIN}, {fd=4, events=POLLIN}], 2, 0) = 0 (Timeout)
    recvmsg(4, 0x7fffc3e46080, 0) = -1 EAGAIN (Resource temporarily unavailable)
    poll([{fd=3, events=POLLIN}, {fd=4, events=POLLIN}], 2, 210) = 0 (Timeout)
    openat(AT_FDCWD, "/dev/bus/usb", O_RDONLY|O_NONBLOCK|O_DIRECTORY|O_CLOEXEC) = 10
    getdents(10, / 3 entries /, 32768) = 72
    close(10) = 0
    stat("/sys/bus/usb/devices", {st_mode=S_IFDIR|0755, st_size=0, ...}) = 0
    open("/etc/udev/udev.conf", O_RDONLY|O_CLOEXEC) = 10
    fstat(10, {st_mode=S_IFREG|0644, st_size=148, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(10, "# see udev(7) for details\n#\n# ud"..., 4096) = 148
    read(10, "", 4096) = 0
    close(10) = 0
    munmap(0x7f80e749d000, 4096) = 0
    socket(PF_NETLINK, SOCK_RAW|SOCK_CLOEXEC|SOCK_NONBLOCK, 15) = 10
    setsockopt(10, SOL_SOCKET, SO_ATTACH_FILTER, "\10\0\0\0\0\0\0\0pN\344\303\377\177\0\0", 16) = 0
    bind(10, {sa_family=AF_NETLINK, pid=0, groups=00000002}, 12) = 0
    getsockname(10, {sa_family=AF_NETLINK, pid=5117, groups=00000002}, [12]) = 0
    setsockopt(10, SOL_SOCKET, SO_PASSCRED, [1], 4) = 0
    fcntl(10, F_GETFL) = 0x802 (flags O_RDWR|O_NONBLOCK)
    fcntl(10, F_SETFL, O_RDWR|O_NONBLOCK) = 0
    pipe([11, 12]) = 0
    fcntl(12, F_GETFL) = 0x1 (flags O_WRONLY)
    fcntl(12, F_SETFL, O_WRONLY|O_NONBLOCK) = 0
    clone(child_stack=0x7f80d9373cf0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7f80d93749d0, tls=0x7f80d9374700, child_tidptr=0x7f80d93749d0) = 6634
    stat("/sys/subsystem", 0x7fffc3e45e70) = -1 ENOENT (No such file or directory)
    openat(AT_FDCWD, "/sys/bus", O_RDONLY|O_NONBLOCK|O_DIRECTORY|O_CLOEXEC) = 13
    getdents(13, / 29 entries /, 32768) = 800
    openat(AT_FDCWD, "/sys/bus/usb/devices", O_RDONLY|O_NONBLOCK|O_DIRECTORY|O_CLOEXEC) = 14
    getdents(14, / 8 entries /, 32768) = 216
    readlink("/sys/bus/usb/devices/1-1", "../../../devices/pci0000:00/0000"..., 1024) = 49
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-1/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    readlink("/sys/bus/usb/devices/1-2", "../../../devices/pci0000:00/0000"..., 1024) = 49
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-2/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    readlink("/sys/bus/usb/devices/usb1", "../../../devices/pci0000:00/0000"..., 1024) = 45
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    readlink("/sys/bus/usb/devices/1-0:1.0", "../../../devices/pci0000:00/0000"..., 1024) = 53
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-0:1.0/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    readlink("/sys/bus/usb/devices/1-1:1.0", "../../../devices/pci0000:00/0000"..., 1024) = 57
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-1/1-1:1.0/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    readlink("/sys/bus/usb/devices/1-2:1.0", "../../../devices/pci0000:00/0000"..., 1024) = 57
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-2/1-2:1.0/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    getdents(14, / 0 entries /, 32768) = 0
    close(14) = 0
    getdents(13, / 0 entries /, 32768) = 0
    close(13) = 0
    openat(AT_FDCWD, "/sys/class", O_RDONLY|O_NONBLOCK|O_DIRECTORY|O_CLOEXEC) = 13
    getdents(13, / 51 entries /, 32768) = 1512
    getdents(13, / 0 entries /, 32768) = 0
    close(13) = 0
    readlink("/sys/devices/pci0000:00/0000:00:06.0/usb1", 0x7fffc3e45210, 1024) = -1 EINVAL (Invalid argument)
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    open("/sys/devices/pci0000:00/0000:00:06.0/usb1/uevent", O_RDONLY|O_CLOEXEC) = 13
    fstat(13, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "MAJOR=189\nMINOR=0\nDEVNAME=bus/us"..., 4096) = 124
    read(13, "", 4096) = 0
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/usb1/busnum", O_RDONLY) = 13
    fstat(13, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "1\n", 4096) = 2
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/usb1/devnum", O_RDONLY) = 13
    fstat(13, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "1\n", 4096) = 2
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/usb1/speed", O_RDONLY) = 13
    fstat(13, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "12\n", 4096) = 3
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/usb1/descriptors", O_RDONLY) = 13
    read(13, "\22\1\20\1\t\0\0@k\35\1\0\23\3\3\2\1\1\t\2\31\0\1\1\0\340\0\t\4\0\0\1"..., 1024) = 43
    close(13) = 0
    readlink("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-0:1.0", 0x7fffc3e45210, 1024) = -1 EINVAL (Invalid argument)
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-0:1.0/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    open("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-0:1.0/uevent", O_RDONLY|O_CLOEXEC) = 13
    fstat(13, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "DEVTYPE=usb_interface\nDRIVER=hub"..., 4096) = 138
    read(13, "", 4096) = 0
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    readlink("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-1", 0x7fffc3e45210, 1024) = -1 EINVAL (Invalid argument)
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-1/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    open("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-1/uevent", O_RDONLY|O_CLOEXEC) = 13
    fstat(13, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "MAJOR=189\nMINOR=1\nDEVNAME=bus/us"..., 4096) = 125
    read(13, "", 4096) = 0
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/1-1/busnum", O_RDONLY) = 13
    fstat(13, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "1\n", 4096) = 2
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/1-1/devnum", O_RDONLY) = 13
    fstat(13, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "2\n", 4096) = 2
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/1-1/speed", O_RDONLY) = 13
    fstat(13, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "12\n", 4096) = 3
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/1-1/descriptors", O_RDONLY) = 13
    read(13, "\22\1\20\1\0\0\0\10\356\200!\0\0\1\1\3\0\1\t\2\"\0\1\1\0\2002\t\4\0\0\1"..., 1024) = 52
    close(13) = 0
    readlink("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-1/1-1:1.0", 0x7fffc3e45210, 1024) = -1 EINVAL (Invalid argument)
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-1/1-1:1.0/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    open("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-1/1-1:1.0/uevent", O_RDONLY|O_CLOEXEC) = 13
    fstat(13, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "DEVTYPE=usb_interface\nDRIVER=usb"..., 4096) = 142
    read(13, "", 4096) = 0
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    readlink("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-2", 0x7fffc3e45210, 1024) = -1 EINVAL (Invalid argument)
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-2/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    open("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-2/uevent", O_RDONLY|O_CLOEXEC) = 13
    fstat(13, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "MAJOR=189\nMINOR=2\nDEVNAME=bus/us"..., 4096) = 125
    read(13, "", 4096) = 0
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/1-2/busnum", O_RDONLY) = 13
    fstat(13, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "1\n", 4096) = 2
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/1-2/devnum", O_RDONLY) = 13
    fstat(13, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "3\n", 4096) = 2
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/1-2/speed", O_RDONLY) = 13
    fstat(13, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "12\n", 4096) = 3
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    open("/sys/bus/usb/devices/1-2/descriptors", O_RDONLY) = 13
    read(13, "\22\1\0\2\0\0\0\10P\20\20\0B\2\1\2\0\1\t\2\"\0\1\1\0\200\17\t\4\0\0\1"..., 1024) = 52
    close(13) = 0
    readlink("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-2/1-2:1.0", 0x7fffc3e45210, 1024) = -1 EINVAL (Invalid argument)
    stat("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-2/1-2:1.0/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    open("/sys/devices/pci0000:00/0000:00:06.0/usb1/1-2/1-2:1.0/uevent", O_RDONLY|O_CLOEXEC) = 13
    fstat(13, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
    mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f80e749d000
    read(13, "DEVTYPE=usb_interface\nDRIVER=usb"..., 4096) = 142
    read(13, "", 4096) = 0
    close(13) = 0
    munmap(0x7f80e749d000, 4096) = 0
    pipe([13, 14]) = 0
    fcntl(14, F_GETFL) = 0x1 (flags O_WRONLY)
    fcntl(14, F_SETFL, O_WRONLY|O_NONBLOCK) = 0
    pipe([15, 16]) = 0
    fcntl(16, F_GETFL) = 0x1 (flags O_WRONLY)
    fcntl(16, F_SETFL, O_WRONLY|O_NONBLOCK^C <ptrace(SYSCALL):No such="" process="">
    +++ killed by SIGINT +++

     

    Last edit: BaQs 2014-07-07

  • BaQs

    BaQs - 2014-07-08

    I tried your package, still the same.
    It does boot faster.

    Did you mean I should build the last version of the yubikey library myself ?

     

  • Rony Shapiro

    Rony Shapiro - 2014-07-14

    That would be one approach.

    Can you test on the current Ubuntu release, 14.04?

     

  • Rony Shapiro

    Rony Shapiro - 2014-07-15

    Could you see if
    $ ykchalresp -2 ""
    works?
    (ykchalresp is provided by the yubikey-personalization package)

     

  • BaQs

    BaQs - 2014-07-15

    gwen@gwen-vm-u1404:~$ ykchalresp -2 ""
    cfab79d343948cbe65626cbb631809be104b1a07

    I just found out creating a new safe works,
    but openning it back does not "time out"

    Hope it helps

     

  • Porcelain Mouse

    Porcelain Mouse - 2017-02-02

    Hmm, this bug is old, but I don't remember finding it when I searched for yubikey related bugs in the past.

    This is new behavior on my machine. I'm now seeing this in the journal when YubiKey is inserted but only when pwsafe is opening a new database:

    Feb 01 17:31:08 <hostname> kernel: input: Yubico Yubico Yubikey II as /devices/pci0000:00/0000:00:16.0/usb
7/7-3/7-3:1.0/0003:1050:0010.010A/input/input277
Feb 01 17:31:08 <hostname> kernel: hid-generic 0003:1050:0010.010A: input,hidraw0: USB HID v1.11 Keyboard 
[Yubico Yubico Yubikey II] on usb-0000:00:16.0-3/input0
Feb 01 17:31:08 <hostname> /usr/libexec/gdm-x-session[2428]: (II) config/udev: Adding input device Yubico 
Yubico Yubikey II (/dev/input/event2)
Feb 01 17:31:08 <hostname> /usr/libexec/gdm-x-session[2428]: (**) Yubico Yubico Yubikey II: Applying Input
Class "evdev keyboard catchall"
Feb 01 17:31:08 <hostname> /usr/libexec/gdm-x-session[2428]: (**) Yubico Yubico Yubikey II: Applying Input
Class "libinput keyboard catchall"
Feb 01 17:31:08 <hostname> /usr/libexec/gdm-x-session[2428]: (**) Yubico Yubico Yubikey II: Applying Input
Class "system-keyboard"
Feb 01 17:31:08 <hostname> /usr/libexec/gdm-x-session[2428]: (II) Using input driver 'libinput' for 'Yubic
o Yubico Yubikey II'
Feb 01 17:31:08 <hostname> /usr/libexec/gdm-x-session[2428]: (II) systemd-logind: got fd for /dev/input/ev
ent2 13:66 fd 83 paused 0
Feb 01 17:31:08 <hostname> /usr/libexec/gdm-x-session[2428]: (**) Yubico Yubico Yubikey II: always reports
 core events
Feb 01 17:31:08 <hostname> /usr/libexec/gdm-x-session[2428]: (**) Option "Device" "/dev/input/event2"
Feb 01 17:31:08 <hostname> /usr/libexec/gdm-x-session[2428]: (**) Option "_source" "server/udev"
Feb 01 17:31:08 <hostname> /usr/libexec/gdm-x-session[2428]: (II) input device 'Yubico Yubico Yubikey II',
 /dev/input/event2 is tagged by udev as: Keyboard
Feb 01 17:31:08 <hostname> /usr/libexec/gdm-x-session[2428]: (II) input device 'Yubico Yubico Yubikey II',
 /dev/input/event2 is a keyboard
Feb 01 17:31:08 <hostname> /usr/libexec/gdm-x-session[2428]: (**) Option "config_info" "udev:/sys/devices/
pci0000:00/0000:00:16.0/usb7/7-3/7-3:1.0/0003:1050:0010.010A/input/input277/event2"
Feb 01 17:31:08 <hostname> /usr/libexec/gdm-x-session[2428]: (II) XINPUT: Adding extended input device "Yu
bico Yubico Yubikey II" (type: KEYBOARD, id 14)
Feb 01 17:31:08 <hostname> /usr/libexec/gdm-x-session[2428]: (**) Option "xkb_model" "pc105+inet"
Feb 01 17:31:08 <hostname> /usr/libexec/gdm-x-session[2428]: (**) Option "xkb_layout" "us"
Feb 01 17:31:08 <hostname> /usr/libexec/gdm-x-session[2428]: (II) input device 'Yubico Yubico Yubikey II',
 /dev/input/event2 is tagged by udev as: Keyboard
Feb 01 17:31:08 <hostname> /usr/libexec/gdm-x-session[2428]: (II) input device 'Yubico Yubico Yubikey II',
 /dev/input/event2 is a keyboard
Feb 01 17:31:08 <hostname> /usr/libexec/gdm-x-session[2428]: (II) config/udev: removing device Yubico Yubi
co Yubikey II
Feb 01 17:31:08 <hostname> /usr/libexec/gdm-x-session[2428]: (**) Option "fd" "83"
Feb 01 17:31:08 <hostname> /usr/libexec/gdm-x-session[2428]: (II) UnloadModule: "libinput"
Feb 01 17:31:08 <hostname> /usr/libexec/gdm-x-session[2428]: (II) systemd-logind: releasing fd for 13:66
Feb 01 17:31:08 <hostname> kernel: input: Yubico Yubico Yubikey II as /devices/pci0000:00/0000:00:16.0/usb
7/7-3/7-3:1.0/0003:1050:0010.010B/input/input278
Feb 01 17:31:08 <hostname> kernel: hid-generic 0003:1050:0010.010B: input,hidraw0: USB HID v1.11 Keyboard 
[Yubico Yubico Yubikey II] on usb-0000:00:16.0-3/input0

    I use YK with other things and this doesn't happen. When this is happening, YK doesn't work because chars are lost when YK button is pressed.

     

  • Porcelain Mouse

    Porcelain Mouse - 2017-11-15

    I recently tried PWsafe again and this bug, or at least I think it is this bug, prevents me from using PWSafe at all on Linux with YK. Just to be clear, when PWsafe unlock dialog box exists, either when trying to open a database or when an open database locks automatically, all USB communication on the system is interrupted every second when Yubikey is inserted. This is a problem since it affects system login if PWsafe auto-locks while system is idle and locked--one is completely locked out of the desktop.

     

Log in to post a comment.