iLok Dongles randomly disconnect

Submitted by SlyLaM on #1

Hello,
I have a setup with multiple iLoks connected to a hub on a Raspberry Pi server.
It was working fine but a few months ago it started acting weird...
The iLoks randomly disconnect from the client, sometimes when releasing one iLok all the others disconnect from the same user or other users, and sometimes without doing anything they disappear.

At first I thought the Raspberry was the culprit, but after upgrading my setup to a brand new Raspi 5 the problem is still there...

Do you have any feedback on such issues?

 


Complete configuration :

Raspberry Pi 5 4GB

OS: Debian GNU/Linux 12 (bookworm) 

Kernel: Linux 6.6.31+rpt-rpi-2712

VH Server Version : 4.7.0

HUB : DLink DUB-H7

 

Thanks

Submitted by Michael on Tue, 2024/12/10 - 07:30

#2

Have a look in /var/log/syslog (or journalctl) about the time these issues happen. Does it have any interesting messages in there? Might be some hub issue.

 

Submitted by Sylvain on Wed, 2025/01/22 - 21:02

#3

Hi!
I installed syslog yesterday to check this (too busy before that...)

This morning there were 2 unexpected disconnections. 
First arround 9:46am, second arround 10:46am .(coincidence I think, cause nothing at 11:46am)
Each time, almost all USB dongles disappear and then reappear in the client...
Not every single one though...

I made a copy of syslog and purged it of what was from before this morning.
Can you check this for me? I don't know how to interpret this.

Thanks


Jan 22 08:05:25 Raspi-5-Server systemd[1]: Starting apt-daily.service - Daily apt download activities...
Jan 22 08:05:26 Raspi-5-Server systemd[1]: apt-daily.service: Deactivated successfully.
Jan 22 08:05:26 Raspi-5-Server systemd[1]: Finished apt-daily.service - Daily apt download activities.
Jan 22 08:17:01 Raspi-5-Server CRON[3273261]: (root) CMD (cd / && run-parts --report /etc/cron.hourly)
Jan 22 08:56:53 Raspi-5-Server vhusbdarm64a76[1571758]: 192.168.10.30 connected as connection 581 (Standard TCP)
Jan 22 08:57:36 Raspi-5-Server vhusbdarm64a76[1571758]: 192.168.10.151 connected as connection 582 (Standard TCP)
Jan 22 08:58:06 Raspi-5-Server vhusbdarm64a76[1571758]: 192.168.10.20 connected as connection 583 (Standard TCP)
Jan 22 09:06:20 Raspi-5-Server kernel: usb 3-1.4.4: reset full-speed USB device number 88 using xhci-hcd
Jan 22 09:06:20 Raspi-5-Server vhusbdarm64a76[1571758]: Device 3144 [088e:5036] BOUND to connection 581
Jan 22 09:06:20 Raspi-5-Server kernel: usb 3-1.4.4: reset full-speed USB device number 88 using xhci-hcd
Jan 22 09:08:03 Raspi-5-Server vhusbdarm64a76[1571758]: 192.168.10.60 connected as connection 585 (Standard TCP)
Jan 22 09:09:26 Raspi-5-Server kernel: usb 3-1.4.2: reset full-speed USB device number 89 using xhci-hcd
Jan 22 09:09:26 Raspi-5-Server vhusbdarm64a76[1571758]: Device 3142 [088e:5036] BOUND to connection 585
Jan 22 09:09:26 Raspi-5-Server kernel: usb 3-1.4.2: reset full-speed USB device number 89 using xhci-hcd
Jan 22 09:13:12 Raspi-5-Server kernel: usb 3-1.2: reset full-speed USB device number 90 using xhci-hcd
Jan 22 09:13:12 Raspi-5-Server vhusbdarm64a76[1571758]: Device 312 [088e:5036] BOUND to connection 585
Jan 22 09:13:12 Raspi-5-Server kernel: usb 3-1.2: reset full-speed USB device number 90 using xhci-hcd
Jan 22 09:14:57 Raspi-5-Server systemd[1]: Starting man-db.service - Daily man-db regeneration...
Jan 22 09:14:58 Raspi-5-Server systemd[1]: man-db.service: Deactivated successfully.
Jan 22 09:14:58 Raspi-5-Server systemd[1]: Finished man-db.service - Daily man-db regeneration.
Jan 22 09:16:45 Raspi-5-Server vhusbdarm64a76[1571758]: Device 312 [088e:5036] UNBOUND from connection 585
Jan 22 09:16:45 Raspi-5-Server kernel: usb 3-1.4.2: USB disconnect, device number 89
Jan 22 09:16:46 Raspi-5-Server kernel: usb 3-1.4.2: new full-speed USB device number 91 using xhci-hcd
Jan 22 09:16:46 Raspi-5-Server kernel: usb 3-1.2: USB disconnect, device number 90
Jan 22 09:16:46 Raspi-5-Server kernel: usb 3-1.4.2: New USB device found, idVendor=088e, idProduct=5036, bcdDevice= 0.30
Jan 22 09:16:46 Raspi-5-Server kernel: usb 3-1.4.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jan 22 09:16:46 Raspi-5-Server kernel: usb 3-1.4.2: Product: iLok
Jan 22 09:16:46 Raspi-5-Server kernel: usb 3-1.4.2: Manufacturer: iLok
Jan 22 09:16:46 Raspi-5-Server kernel: usb 3-1.4.2: SerialNumber: 3ACBA729_0B211E44
Jan 22 09:16:46 Raspi-5-Server kernel: usb 3-1.2: new full-speed USB device number 92 using xhci-hcd
Jan 22 09:16:46 Raspi-5-Server kernel: usb 3-1.2: New USB device found, idVendor=088e, idProduct=5036, bcdDevice= 0.20
Jan 22 09:16:46 Raspi-5-Server kernel: usb 3-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jan 22 09:16:46 Raspi-5-Server kernel: usb 3-1.2: Product: iLok
Jan 22 09:16:46 Raspi-5-Server kernel: usb 3-1.2: Manufacturer: iLok
Jan 22 09:16:46 Raspi-5-Server kernel: usb 3-1.2: SerialNumber: 1C8CA52B_4E1B02E0
Jan 22 09:16:46 Raspi-5-Server mtp-probe[3276513]: checking bus 3, device 91: "/sys/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-1/3-1.4/3-1.4.2"
Jan 22 09:16:46 Raspi-5-Server mtp-probe[3276513]: bus: 3, device: 91 was not an MTP device
Jan 22 09:16:46 Raspi-5-Server mtp-probe[3276514]: checking bus 3, device 92: "/sys/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-1/3-1.2"
Jan 22 09:16:46 Raspi-5-Server mtp-probe[3276514]: bus: 3, device: 92 was not an MTP device
Jan 22 09:16:51 Raspi-5-Server kernel: usb 3-1.4.2: USB disconnect, device number 91
Jan 22 09:16:51 Raspi-5-Server vhusbdarm64a76[1571758]: Device 3142 [088e:5036] SURPRISE UNBOUND from connection 585
Jan 22 09:16:51 Raspi-5-Server vhusbdarm64a76[1571758]: Unmanaging device 3142 [088e:5036]
Jan 22 09:16:51 Raspi-5-Server kernel: usb 3-1.4.2: new full-speed USB device number 93 using xhci-hcd
Jan 22 09:16:51 Raspi-5-Server kernel: usb 3-1.2: USB disconnect, device number 92
Jan 22 09:16:51 Raspi-5-Server vhusbdarm64a76[1571758]: Unmanaging device 312 [088e:5036]
Jan 22 09:16:51 Raspi-5-Server kernel: usb 3-1.4.2: New USB device found, idVendor=088e, idProduct=5036, bcdDevice= 0.30
Jan 22 09:16:51 Raspi-5-Server kernel: usb 3-1.4.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jan 22 09:16:51 Raspi-5-Server kernel: usb 3-1.4.2: Product: iLok
Jan 22 09:16:51 Raspi-5-Server kernel: usb 3-1.4.2: Manufacturer: iLok
Jan 22 09:16:51 Raspi-5-Server kernel: usb 3-1.4.2: SerialNumber: 3ACBA729_0B211E44
Jan 22 09:16:51 Raspi-5-Server kernel: usb 3-1.2: new full-speed USB device number 94 using xhci-hcd
Jan 22 09:16:52 Raspi-5-Server kernel: usb 3-1.2: New USB device found, idVendor=088e, idProduct=5036, bcdDevice= 0.20
Jan 22 09:16:52 Raspi-5-Server kernel: usb 3-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jan 22 09:16:52 Raspi-5-Server kernel: usb 3-1.2: Product: iLok
Jan 22 09:16:52 Raspi-5-Server kernel: usb 3-1.2: Manufacturer: iLok
Jan 22 09:16:52 Raspi-5-Server kernel: usb 3-1.2: SerialNumber: 1C8CA52B_4E1B02E0
Jan 22 09:16:52 Raspi-5-Server vhusbdarm64a76[1571758]: Found Full speed device [088e:5036] "iLok, iLok" at address 3142
Jan 22 09:16:52 Raspi-5-Server mtp-probe[3276538]: checking bus 3, device 94: "/sys/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-1/3-1.2"
Jan 22 09:16:52 Raspi-5-Server mtp-probe[3276539]: checking bus 3, device 93: "/sys/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-1/3-1.4/3-1.4.2"
Jan 22 09:16:52 Raspi-5-Server mtp-probe[3276539]: bus: 3, device: 93 was not an MTP device
Jan 22 09:16:52 Raspi-5-Server mtp-probe[3276538]: bus: 3, device: 94 was not an MTP device
Jan 22 09:16:52 Raspi-5-Server vhusbdarm64a76[1571758]: Found Full speed device [088e:5036] "iLok, iLok" at address 312
Jan 22 09:16:52 Raspi-5-Server vhusbdarm64a76[1571758]: Unmanaging device 3142 [088e:5036]
Jan 22 09:16:53 Raspi-5-Server vhusbdarm64a76[1571758]: Unmanaging device 312 [088e:5036]
Jan 22 09:16:54 Raspi-5-Server vhusbdarm64a76[1571758]: Found Full speed device [088e:5036] "iLok, iLok" at address 3142
Jan 22 09:16:54 Raspi-5-Server vhusbdarm64a76[1571758]: Found Full speed device [088e:5036] "iLok, iLok" at address 312
Jan 22 09:17:01 Raspi-5-Server CRON[3276561]: (root) CMD (cd / && run-parts --report /etc/cron.hourly)
Jan 22 09:20:23 Raspi-5-Server vhusbdarm64a76[1571758]: 192.168.10.80 connected as connection 588 (Standard TCP)
Jan 22 09:20:28 Raspi-5-Server vhusbdarm64a76[1571758]: 192.168.10.81 connected as connection 589 (Standard TCP)
Jan 22 09:20:57 Raspi-5-Server kernel: usb 3-1.4.3: reset full-speed USB device number 87 using xhci-hcd
Jan 22 09:20:58 Raspi-5-Server vhusbdarm64a76[1571758]: Device 3143 [088e:5036] BOUND to connection 588
Jan 22 09:20:58 Raspi-5-Server kernel: usb 3-1.4.3: reset full-speed USB device number 87 using xhci-hcd
Jan 22 09:21:08 Raspi-5-Server vhusbdarm64a76[1571758]: Device 3143 [088e:5036] UNBOUND from connection 588
Jan 22 09:21:08 Raspi-5-Server vhusbdarm64a76[1571758]: Unmanaging device 313 [088e:5036]
Jan 22 09:21:08 Raspi-5-Server kernel: usb 3-1.4.3: USB disconnect, device number 87
Jan 22 09:21:08 Raspi-5-Server kernel: usb 3-1.3: USB disconnect, device number 82
Jan 22 09:21:08 Raspi-5-Server kernel: usb 3-1.3: new full-speed USB device number 95 using xhci-hcd
Jan 22 09:21:08 Raspi-5-Server vhusbdarm64a76[1571758]: Unmanaging device 3143 [088e:5036]
Jan 22 09:21:08 Raspi-5-Server kernel: usb 3-1.3: New USB device found, idVendor=088e, idProduct=5036, bcdDevice= 0.20
Jan 22 09:21:08 Raspi-5-Server kernel: usb 3-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jan 22 09:21:08 Raspi-5-Server kernel: usb 3-1.3: Product: iLok
Jan 22 09:21:08 Raspi-5-Server kernel: usb 3-1.3: Manufacturer: iLok
Jan 22 09:21:08 Raspi-5-Server kernel: usb 3-1.3: SerialNumber: 3988A22A_791F1616
Jan 22 09:21:08 Raspi-5-Server mtp-probe[3276792]: checking bus 3, device 95: "/sys/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-1/3-1.3"
Jan 22 09:21:08 Raspi-5-Server mtp-probe[3276792]: bus: 3, device: 95 was not an MTP device
Jan 22 09:21:08 Raspi-5-Server kernel: usb 3-1.4.3: new full-speed USB device number 96 using xhci-hcd
Jan 22 09:21:08 Raspi-5-Server kernel: usb 3-1.4.3: New USB device found, idVendor=088e, idProduct=5036, bcdDevice= 0.30
Jan 22 09:21:08 Raspi-5-Server kernel: usb 3-1.4.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jan 22 09:21:08 Raspi-5-Server kernel: usb 3-1.4.3: Product: iLok
Jan 22 09:21:08 Raspi-5-Server kernel: usb 3-1.4.3: Manufacturer: iLok
Jan 22 09:21:08 Raspi-5-Server kernel: usb 3-1.4.3: SerialNumber: 3FA0812A_7C391564
Jan 22 09:21:08 Raspi-5-Server mtp-probe[3276800]: checking bus 3, device 96: "/sys/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-1/3-1.4/3-1.4.3"
Jan 22 09:21:08 Raspi-5-Server mtp-probe[3276800]: bus: 3, device: 96 was not an MTP device
Jan 22 09:21:08 Raspi-5-Server vhusbdarm64a76[1571758]: Found Full speed device [088e:5036] "iLok, iLok" at address 313
Jan 22 09:21:08 Raspi-5-Server vhusbdarm64a76[1571758]: Found Full speed device [088e:5036] "iLok, iLok" at address 3143
Jan 22 09:21:09 Raspi-5-Server kernel: usb 3-1.4.3: reset full-speed USB device number 96 using xhci-hcd
Jan 22 09:21:09 Raspi-5-Server vhusbdarm64a76[1571758]: Device 3143 [088e:5036] BOUND to connection 588
Jan 22 09:21:09 Raspi-5-Server kernel: usb 3-1.4.3: reset full-speed USB device number 96 using xhci-hcd
Jan 22 09:37:23 Raspi-5-Server vhusbdarm64a76[1571758]: 192.168.10.40 connected as connection 592 (Standard TCP)
Jan 22 09:39:02 Raspi-5-Server kernel: usb 3-1.3: reset full-speed USB device number 95 using xhci-hcd
Jan 22 09:39:02 Raspi-5-Server vhusbdarm64a76[1571758]: Device 313 [088e:5036] BOUND to connection 592
Jan 22 09:39:02 Raspi-5-Server kernel: usb 3-1.3: reset full-speed USB device number 95 using xhci-hcd
Jan 22 09:39:10 Raspi-5-Server kernel: usb 3-1.4.2: reset full-speed USB device number 93 using xhci-hcd
Jan 22 09:39:11 Raspi-5-Server vhusbdarm64a76[1571758]: Device 3142 [088e:5036] BOUND to connection 585
Jan 22 09:39:11 Raspi-5-Server kernel: usb 3-1.4.2: reset full-speed USB device number 93 using xhci-hcd
Jan 22 09:46:02 Raspi-5-Server vhusbdarm64a76[1571758]: switchHubPortPower failed -1
Jan 22 09:46:02 Raspi-5-Server vhusbdarm64a76[1571758]: Device 3144 [088e:5036] UNBOUND from connection 581
Jan 22 09:46:08 Raspi-5-Server vhusbdarm64a76[1571758]: Unmanaging device 3141 [088e:5036]
Jan 22 09:46:08 Raspi-5-Server kernel: usb 3-1.4.1: USB disconnect, device number 85
Jan 22 09:46:08 Raspi-5-Server kernel: usb 3-1.4.2: USB disconnect, device number 93
Jan 22 09:46:08 Raspi-5-Server kernel: usb 3-1.4.3: USB disconnect, device number 96
Jan 22 09:46:08 Raspi-5-Server kernel: usb 3-1.4.4: USB disconnect, device number 88
Jan 22 09:46:09 Raspi-5-Server kernel: usb 3-1.4: USB disconnect, device number 84
Jan 22 09:46:09 Raspi-5-Server vhusbdarm64a76[1571758]: switchHubPortPower failed -1
Jan 22 09:46:09 Raspi-5-Server kernel: usb 3-1.4: new high-speed USB device number 97 using xhci-hcd
Jan 22 09:46:09 Raspi-5-Server kernel: usb 3-1.4: New USB device found, idVendor=05e3, idProduct=0608, bcdDevice=60.52
Jan 22 09:46:09 Raspi-5-Server kernel: usb 3-1.4: New USB device strings: Mfr=0, Product=1, SerialNumber=0
Jan 22 09:46:09 Raspi-5-Server kernel: usb 3-1.4: Product: USB2.0 Hub
Jan 22 09:46:14 Raspi-5-Server vhusbdarm64a76[1571758]: switchHubPortPower failed -1
Jan 22 09:46:14 Raspi-5-Server vhusbdarm64a76[1571758]: Device 3142 [088e:5036] SURPRISE UNBOUND from connection 585
Jan 22 09:46:14 Raspi-5-Server vhusbdarm64a76[1571758]: Unmanaging device 3142 [088e:5036]
Jan 22 09:46:14 Raspi-5-Server vhusbdarm64a76[1571758]: Error -1 resetting device 0x088e/0x5036 for capture
Jan 22 09:46:14 Raspi-5-Server vhusbdarm64a76[1571758]: Error binding device 3144 [088e:5036] to connection 592, BIND_ERROR
Jan 22 09:46:14 Raspi-5-Server vhusbdarm64a76[1571758]: switchHubPortPower failed -1
Jan 22 09:46:19 Raspi-5-Server vhusbdarm64a76[1571758]: switchHubPortPower failed -1
Jan 22 09:46:19 Raspi-5-Server vhusbdarm64a76[1571758]: Device 3143 [088e:5036] SURPRISE UNBOUND from connection 588
Jan 22 09:46:19 Raspi-5-Server vhusbdarm64a76[1571758]: Unmanaging device 3143 [088e:5036]
Jan 22 09:46:19 Raspi-5-Server vhusbdarm64a76[1571758]: Error -1 resetting device 0x088e/0x5036 for capture
Jan 22 09:46:19 Raspi-5-Server vhusbdarm64a76[1571758]: Error binding device 3144 [088e:5036] to connection 592, BIND_ERROR
Jan 22 09:46:19 Raspi-5-Server vhusbdarm64a76[1571758]: Unmanaging device 3144 [088e:5036]
Jan 22 09:46:19 Raspi-5-Server vhusbdarm64a76[1571758]: Unmanaging device 314 [05e3:0608]
Jan 22 09:46:19 Raspi-5-Server kernel: hub 3-1.4:1.0: USB hub found
Jan 22 09:46:19 Raspi-5-Server kernel: hub 3-1.4:1.0: 4 ports detected
Jan 22 09:46:19 Raspi-5-Server vhusbdarm64a76[1571758]: Found High speed device [05e3:0608] "0x05e3, USB2.0 Hub" at address 314
Jan 22 09:46:20 Raspi-5-Server kernel: usb 3-1.4.1: new full-speed USB device number 98 using xhci-hcd
Jan 22 09:46:20 Raspi-5-Server kernel: usb 3-1.4.1: New USB device found, idVendor=088e, idProduct=5036, bcdDevice= 0.20
Jan 22 09:46:20 Raspi-5-Server kernel: usb 3-1.4.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jan 22 09:46:20 Raspi-5-Server kernel: usb 3-1.4.1: Product: iLok
Jan 22 09:46:20 Raspi-5-Server kernel: usb 3-1.4.1: Manufacturer: iLok
Jan 22 09:46:20 Raspi-5-Server kernel: usb 3-1.4.1: SerialNumber: A188A42B_7D0913ED
Jan 22 09:46:20 Raspi-5-Server kernel: usb 3-1.4.2: new full-speed USB device number 99 using xhci-hcd
Jan 22 09:46:20 Raspi-5-Server kernel: usb 3-1.4.2: New USB device found, idVendor=088e, idProduct=5036, bcdDevice= 0.30
Jan 22 09:46:20 Raspi-5-Server kernel: usb 3-1.4.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jan 22 09:46:20 Raspi-5-Server kernel: usb 3-1.4.2: Product: iLok
Jan 22 09:46:20 Raspi-5-Server kernel: usb 3-1.4.2: Manufacturer: iLok
Jan 22 09:46:20 Raspi-5-Server kernel: usb 3-1.4.2: SerialNumber: 3ACBA729_0B211E44
Jan 22 09:46:20 Raspi-5-Server kernel: usb 3-1.4.3: new full-speed USB device number 100 using xhci-hcd
Jan 22 09:46:20 Raspi-5-Server kernel: usb 3-1.4.3: New USB device found, idVendor=088e, idProduct=5036, bcdDevice= 0.30
Jan 22 09:46:20 Raspi-5-Server kernel: usb 3-1.4.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jan 22 09:46:20 Raspi-5-Server kernel: usb 3-1.4.3: Product: iLok
Jan 22 09:46:20 Raspi-5-Server kernel: usb 3-1.4.3: Manufacturer: iLok
Jan 22 09:46:20 Raspi-5-Server kernel: usb 3-1.4.3: SerialNumber: 3FA0812A_7C391564
Jan 22 09:46:20 Raspi-5-Server kernel: usb 3-1.4.4: new full-speed USB device number 101 using xhci-hcd
Jan 22 09:46:20 Raspi-5-Server kernel: usb 3-1.4.4: New USB device found, idVendor=088e, idProduct=5036, bcdDevice= 0.30
Jan 22 09:46:20 Raspi-5-Server kernel: usb 3-1.4.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jan 22 09:46:20 Raspi-5-Server kernel: usb 3-1.4.4: Product: iLok
Jan 22 09:46:20 Raspi-5-Server kernel: usb 3-1.4.4: Manufacturer: iLok
Jan 22 09:46:20 Raspi-5-Server kernel: usb 3-1.4.4: SerialNumber: BA4FD92A_696B026C
Jan 22 09:46:20 Raspi-5-Server vhusbdarm64a76[1571758]: Found Full speed device [088e:5036] "iLok, iLok" at address 3141
Jan 22 09:46:20 Raspi-5-Server mtp-probe[3281773]: checking bus 3, device 100: "/sys/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-1/3-1.4/3-1.4.3"
Jan 22 09:46:20 Raspi-5-Server mtp-probe[3281773]: bus: 3, device: 100 was not an MTP device
Jan 22 09:46:20 Raspi-5-Server mtp-probe[3281771]: checking bus 3, device 98: "/sys/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-1/3-1.4/3-1.4.1"
Jan 22 09:46:20 Raspi-5-Server mtp-probe[3281772]: checking bus 3, device 99: "/sys/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-1/3-1.4/3-1.4.2"
Jan 22 09:46:20 Raspi-5-Server mtp-probe[3281771]: bus: 3, device: 98 was not an MTP device
Jan 22 09:46:20 Raspi-5-Server mtp-probe[3281772]: bus: 3, device: 99 was not an MTP device
Jan 22 09:46:20 Raspi-5-Server vhusbdarm64a76[1571758]: Found Full speed device [088e:5036] "iLok, iLok" at address 3142
Jan 22 09:46:20 Raspi-5-Server vhusbdarm64a76[1571758]: Found Full speed device [088e:5036] "iLok, iLok" at address 3143
Jan 22 09:46:20 Raspi-5-Server vhusbdarm64a76[1571758]: Found Full speed device [088e:5036] "iLok, iLok" at address 3144
Jan 22 09:46:20 Raspi-5-Server mtp-probe[3281780]: checking bus 3, device 101: "/sys/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-1/3-1.4/3-1.4.4"
Jan 22 09:46:20 Raspi-5-Server mtp-probe[3281780]: bus: 3, device: 101 was not an MTP device
Jan 22 09:46:27 Raspi-5-Server kernel: usb 3-1.4.3: reset full-speed USB device number 100 using xhci-hcd
Jan 22 09:46:27 Raspi-5-Server vhusbdarm64a76[1571758]: Device 3143 [088e:5036] BOUND to connection 592
Jan 22 09:46:27 Raspi-5-Server kernel: usb 3-1.4.3: reset full-speed USB device number 100 using xhci-hcd
Jan 22 09:48:32 Raspi-5-Server kernel: usb 3-1.4.4: reset full-speed USB device number 101 using xhci-hcd
Jan 22 09:48:32 Raspi-5-Server vhusbdarm64a76[1571758]: Device 3144 [088e:5036] BOUND to connection 581
Jan 22 09:48:32 Raspi-5-Server kernel: usb 3-1.4.4: reset full-speed USB device number 101 using xhci-hcd
Jan 22 09:51:56 Raspi-5-Server kernel: usb 3-1.2: reset full-speed USB device number 94 using xhci-hcd
Jan 22 09:51:56 Raspi-5-Server vhusbdarm64a76[1571758]: Device 312 [088e:5036] BOUND to connection 592
Jan 22 09:51:56 Raspi-5-Server kernel: usb 3-1.2: reset full-speed USB device number 94 using xhci-hcd
Jan 22 09:52:05 Raspi-5-Server vhusbdarm64a76[1571758]: Device 312 [088e:5036] UNBOUND from connection 592
Jan 22 09:52:05 Raspi-5-Server vhusbdarm64a76[1571758]: Unmanaging device 3142 [088e:5036]
Jan 22 09:52:05 Raspi-5-Server kernel: usb 3-1.4.2: USB disconnect, device number 99
Jan 22 09:52:05 Raspi-5-Server kernel: usb 3-1.2: USB disconnect, device number 94
Jan 22 09:52:06 Raspi-5-Server kernel: usb 3-1.2: new full-speed USB device number 102 using xhci-hcd
Jan 22 09:52:06 Raspi-5-Server kernel: usb 3-1.2: New USB device found, idVendor=088e, idProduct=5036, bcdDevice= 0.20
Jan 22 09:52:06 Raspi-5-Server kernel: usb 3-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jan 22 09:52:06 Raspi-5-Server kernel: usb 3-1.2: Product: iLok
Jan 22 09:52:06 Raspi-5-Server kernel: usb 3-1.2: Manufacturer: iLok
Jan 22 09:52:06 Raspi-5-Server kernel: usb 3-1.2: SerialNumber: 1C8CA52B_4E1B02E0
Jan 22 09:52:06 Raspi-5-Server mtp-probe[3283687]: checking bus 3, device 102: "/sys/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-1/3-1.2"
Jan 22 09:52:06 Raspi-5-Server mtp-probe[3283687]: bus: 3, device: 102 was not an MTP device
Jan 22 09:52:06 Raspi-5-Server kernel: usb 3-1.4.2: new full-speed USB device number 103 using xhci-hcd
Jan 22 09:52:06 Raspi-5-Server vhusbdarm64a76[1571758]: Unmanaging device 312 [088e:5036]
Jan 22 09:52:06 Raspi-5-Server kernel: usb 3-1.4.2: New USB device found, idVendor=088e, idProduct=5036, bcdDevice= 0.30
Jan 22 09:52:06 Raspi-5-Server kernel: usb 3-1.4.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jan 22 09:52:06 Raspi-5-Server kernel: usb 3-1.4.2: Product: iLok
Jan 22 09:52:06 Raspi-5-Server kernel: usb 3-1.4.2: Manufacturer: iLok
Jan 22 09:52:06 Raspi-5-Server kernel: usb 3-1.4.2: SerialNumber: 3ACBA729_0B211E44
Jan 22 09:52:06 Raspi-5-Server mtp-probe[3283695]: checking bus 3, device 103: "/sys/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-1/3-1.4/3-1.4.2"
Jan 22 09:52:06 Raspi-5-Server mtp-probe[3283695]: bus: 3, device: 103 was not an MTP device
Jan 22 09:52:06 Raspi-5-Server vhusbdarm64a76[1571758]: Found Full speed device [088e:5036] "iLok, iLok" at address 312
Jan 22 09:52:06 Raspi-5-Server vhusbdarm64a76[1571758]: Found Full speed device [088e:5036] "iLok, iLok" at address 3142
Jan 22 10:04:17 Raspi-5-Server kernel: usb 3-1.4.2: reset full-speed USB device number 103 using xhci-hcd
Jan 22 10:04:17 Raspi-5-Server vhusbdarm64a76[1571758]: Device 3142 [088e:5036] BOUND to connection 585
Jan 22 10:04:17 Raspi-5-Server kernel: usb 3-1.4.2: reset full-speed USB device number 103 using xhci-hcd
Jan 22 10:06:14 Raspi-5-Server vhusbdarm64a76[1571758]: 192.168.10.210 connected as connection 599 (Standard TCP)
Jan 22 10:08:01 Raspi-5-Server vhusbdarm64a76[1571758]: 192.168.10.10 connected as connection 600 (Standard TCP)
Jan 22 10:17:02 Raspi-5-Server CRON[3294205]: (root) CMD (cd / && run-parts --report /etc/cron.hourly)
Jan 22 10:27:35 Raspi-5-Server systemd[1]: Starting systemd-tmpfiles-clean.service - Cleanup of Temporary Directories...
Jan 22 10:27:35 Raspi-5-Server systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully.
Jan 22 10:27:35 Raspi-5-Server systemd[1]: Finished systemd-tmpfiles-clean.service - Cleanup of Temporary Directories.
Jan 22 10:27:35 Raspi-5-Server systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dclean.service.mount: Deactivated successfully.
Jan 22 10:32:51 Raspi-5-Server kernel: usb 3-1.1: reset full-speed USB device number 73 using xhci-hcd
Jan 22 10:32:51 Raspi-5-Server vhusbdarm64a76[1571758]: Device 311 [088e:5036] BOUND to connection 592
Jan 22 10:32:52 Raspi-5-Server kernel: usb 3-1.1: reset full-speed USB device number 73 using xhci-hcd
Jan 22 10:46:09 Raspi-5-Server kernel: usb 3-1.4: clear tt 1 (0080) error -71
Jan 22 10:46:10 Raspi-5-Server kernel: usb 3-1.4: clear tt 1 (0080) error -71
Jan 22 10:46:10 Raspi-5-Server kernel: usb 3-1.4: clear tt 1 (0080) error -71
Jan 22 10:46:11 Raspi-5-Server kernel: usb 3-1.4: clear tt 1 (0080) error -71
Jan 22 10:46:11 Raspi-5-Server kernel: usb 3-1.4: clear tt 1 (0070) error -71
Jan 22 10:46:11 Raspi-5-Server kernel: usb 3-1.4: clear tt 1 (0080) error -71
Jan 22 10:46:12 Raspi-5-Server kernel: usb 3-1.4: clear tt 1 (0080) error -71
Jan 22 10:46:12 Raspi-5-Server kernel: usb 3-1.4: clear tt 1 (0080) error -71
Jan 22 10:46:13 Raspi-5-Server kernel: usb 3-1.4: clear tt 1 (0080) error -71
Jan 22 10:46:14 Raspi-5-Server kernel: usb 3-1.4: clear tt 1 (0080) error -71
Jan 22 10:46:14 Raspi-5-Server vhusbdarm64a76[1571758]: switchHubPortPower failed -1
Jan 22 10:46:14 Raspi-5-Server vhusbdarm64a76[1571758]: Device 3144 [088e:5036] UNBOUND from connection 581
Jan 22 10:46:15 Raspi-5-Server kernel: usb 3-1.4: clear tt 1 (0070) error -71
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: cannot reset (err = -71)
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: cannot reset (err = -71)
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: cannot reset (err = -71)
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: cannot reset (err = -71)
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: cannot reset (err = -71)
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: Cannot enable. Maybe the USB cable is bad?
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: cannot disable (err = -71)
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: cannot reset (err = -71)
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: cannot reset (err = -71)
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: cannot reset (err = -71)
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: cannot reset (err = -71)
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: cannot reset (err = -71)
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: Cannot enable. Maybe the USB cable is bad?
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: cannot disable (err = -71)
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: cannot reset (err = -71)
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: cannot reset (err = -71)
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: cannot reset (err = -71)
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: cannot reset (err = -71)
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: cannot reset (err = -71)
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: Cannot enable. Maybe the USB cable is bad?
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: cannot disable (err = -71)
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: cannot reset (err = -71)
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: cannot reset (err = -71)
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: cannot reset (err = -71)
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: cannot reset (err = -71)
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: cannot reset (err = -71)
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: Cannot enable. Maybe the USB cable is bad?
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: cannot disable (err = -71)
Jan 22 10:46:16 Raspi-5-Server kernel: usb 3-1.4-port4: cannot disable (err = -71)
Jan 22 10:46:16 Raspi-5-Server vhusbdarm64a76[1571758]: Error -1 resetting device 0x088e/0x5036 for capture
Jan 22 10:46:16 Raspi-5-Server vhusbdarm64a76[1571758]: Error binding device 3144 [088e:5036] to connection 588, BIND_ERROR
Jan 22 10:46:16 Raspi-5-Server kernel: hub 3-1.4:1.0: hub_ext_port_status failed (err = -71)
Jan 22 10:46:19 Raspi-5-Server kernel: usb 3-1.4: clear tt 1 (0070) error -71
Jan 22 10:46:19 Raspi-5-Server vhusbdarm64a76[1571758]: Unmanaging device 3141 [088e:5036]
Jan 22 10:46:19 Raspi-5-Server kernel: usb 3-1.4.1: USB disconnect, device number 98
Jan 22 10:46:19 Raspi-5-Server kernel: usb 3-1.4.2: USB disconnect, device number 103
Jan 22 10:46:19 Raspi-5-Server kernel: usb 3-1.4.3: USB disconnect, device number 100
Jan 22 10:46:19 Raspi-5-Server kernel: usb 3-1.4.4: USB disconnect, device number 101
Jan 22 10:46:20 Raspi-5-Server kernel: usb 3-1.4: USB disconnect, device number 97
Jan 22 10:46:20 Raspi-5-Server vhusbdarm64a76[1571758]: switchHubPortPower failed -1
Jan 22 10:46:21 Raspi-5-Server kernel: usb 3-1.4: new high-speed USB device number 104 using xhci-hcd
Jan 22 10:46:21 Raspi-5-Server kernel: usb 3-1.4: New USB device found, idVendor=05e3, idProduct=0608, bcdDevice=60.52
Jan 22 10:46:21 Raspi-5-Server kernel: usb 3-1.4: New USB device strings: Mfr=0, Product=1, SerialNumber=0
Jan 22 10:46:21 Raspi-5-Server kernel: usb 3-1.4: Product: USB2.0 Hub
Jan 22 10:46:25 Raspi-5-Server vhusbdarm64a76[1571758]: switchHubPortPower failed -1
Jan 22 10:46:25 Raspi-5-Server vhusbdarm64a76[1571758]: Device 3142 [088e:5036] SURPRISE UNBOUND from connection 585
Jan 22 10:46:25 Raspi-5-Server vhusbdarm64a76[1571758]: Unmanaging device 3142 [088e:5036]
Jan 22 10:46:25 Raspi-5-Server vhusbdarm64a76[1571758]: Error -1 resetting device 0x088e/0x5036 for capture
Jan 22 10:46:25 Raspi-5-Server vhusbdarm64a76[1571758]: Error binding device 3144 [088e:5036] to connection 588, BIND_ERROR
Jan 22 10:46:25 Raspi-5-Server vhusbdarm64a76[1571758]: switchHubPortPower failed -1
Jan 22 10:46:30 Raspi-5-Server vhusbdarm64a76[1571758]: switchHubPortPower failed -1
Jan 22 10:46:30 Raspi-5-Server vhusbdarm64a76[1571758]: Device 3143 [088e:5036] SURPRISE UNBOUND from connection 592
Jan 22 10:46:30 Raspi-5-Server vhusbdarm64a76[1571758]: Unmanaging device 3143 [088e:5036]
Jan 22 10:46:31 Raspi-5-Server vhusbdarm64a76[1571758]: Error -1 resetting device 0x088e/0x5036 for capture
Jan 22 10:46:31 Raspi-5-Server vhusbdarm64a76[1571758]: Error binding device 3144 [088e:5036] to connection 588, BIND_ERROR
Jan 22 10:46:31 Raspi-5-Server vhusbdarm64a76[1571758]: Unmanaging device 3144 [088e:5036]
Jan 22 10:46:31 Raspi-5-Server vhusbdarm64a76[1571758]: Unmanaging device 314 [05e3:0608]
Jan 22 10:46:31 Raspi-5-Server kernel: hub 3-1.4:1.0: USB hub found
Jan 22 10:46:31 Raspi-5-Server kernel: hub 3-1.4:1.0: 4 ports detected
Jan 22 10:46:31 Raspi-5-Server vhusbdarm64a76[1571758]: Found High speed device [05e3:0608] "0x05e3, USB2.0 Hub" at address 314
Jan 22 10:46:31 Raspi-5-Server kernel: usb 3-1.4.1: new full-speed USB device number 105 using xhci-hcd
Jan 22 10:46:31 Raspi-5-Server kernel: usb 3-1.4.1: New USB device found, idVendor=088e, idProduct=5036, bcdDevice= 0.20
Jan 22 10:46:31 Raspi-5-Server kernel: usb 3-1.4.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jan 22 10:46:31 Raspi-5-Server kernel: usb 3-1.4.1: Product: iLok
Jan 22 10:46:31 Raspi-5-Server kernel: usb 3-1.4.1: Manufacturer: iLok
Jan 22 10:46:31 Raspi-5-Server kernel: usb 3-1.4.1: SerialNumber: A188A42B_7D0913ED
Jan 22 10:46:31 Raspi-5-Server kernel: usb 3-1.4.2: new full-speed USB device number 106 using xhci-hcd
Jan 22 10:46:31 Raspi-5-Server kernel: usb 3-1.4.2: New USB device found, idVendor=088e, idProduct=5036, bcdDevice= 0.30
Jan 22 10:46:31 Raspi-5-Server kernel: usb 3-1.4.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jan 22 10:46:31 Raspi-5-Server kernel: usb 3-1.4.2: Product: iLok
Jan 22 10:46:31 Raspi-5-Server kernel: usb 3-1.4.2: Manufacturer: iLok
Jan 22 10:46:31 Raspi-5-Server kernel: usb 3-1.4.2: SerialNumber: 3ACBA729_0B211E44
Jan 22 10:46:32 Raspi-5-Server kernel: usb 3-1.4.3: new full-speed USB device number 107 using xhci-hcd
Jan 22 10:46:32 Raspi-5-Server kernel: usb 3-1.4.3: New USB device found, idVendor=088e, idProduct=5036, bcdDevice= 0.30
Jan 22 10:46:32 Raspi-5-Server kernel: usb 3-1.4.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jan 22 10:46:32 Raspi-5-Server kernel: usb 3-1.4.3: Product: iLok
Jan 22 10:46:32 Raspi-5-Server kernel: usb 3-1.4.3: Manufacturer: iLok
Jan 22 10:46:32 Raspi-5-Server kernel: usb 3-1.4.3: SerialNumber: 3FA0812A_7C391564
Jan 22 10:46:32 Raspi-5-Server kernel: usb 3-1.4.4: new full-speed USB device number 108 using xhci-hcd
Jan 22 10:46:32 Raspi-5-Server kernel: usb 3-1.4.4: New USB device found, idVendor=088e, idProduct=5036, bcdDevice= 0.30
Jan 22 10:46:32 Raspi-5-Server kernel: usb 3-1.4.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jan 22 10:46:32 Raspi-5-Server kernel: usb 3-1.4.4: Product: iLok
Jan 22 10:46:32 Raspi-5-Server kernel: usb 3-1.4.4: Manufacturer: iLok
Jan 22 10:46:32 Raspi-5-Server kernel: usb 3-1.4.4: SerialNumber: BA4FD92A_696B026C
Jan 22 10:46:32 Raspi-5-Server vhusbdarm64a76[1571758]: Found Full speed device [088e:5036] "iLok, iLok" at address 3141
Jan 22 10:46:32 Raspi-5-Server mtp-probe[3312268]: checking bus 3, device 105: "/sys/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-1/3-1.4/3-1.4.1"
Jan 22 10:46:32 Raspi-5-Server mtp-probe[3312270]: checking bus 3, device 107: "/sys/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-1/3-1.4/3-1.4.3"
Jan 22 10:46:32 Raspi-5-Server mtp-probe[3312268]: bus: 3, device: 105 was not an MTP device
Jan 22 10:46:32 Raspi-5-Server mtp-probe[3312270]: bus: 3, device: 107 was not an MTP device
Jan 22 10:46:32 Raspi-5-Server mtp-probe[3312269]: checking bus 3, device 106: "/sys/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-1/3-1.4/3-1.4.2"
Jan 22 10:46:32 Raspi-5-Server mtp-probe[3312269]: bus: 3, device: 106 was not an MTP device
Jan 22 10:46:32 Raspi-5-Server vhusbdarm64a76[1571758]: Found Full speed device [088e:5036] "iLok, iLok" at address 3142
Jan 22 10:46:32 Raspi-5-Server mtp-probe[3312274]: checking bus 3, device 108: "/sys/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-1/3-1.4/3-1.4.4"
Jan 22 10:46:32 Raspi-5-Server mtp-probe[3312274]: bus: 3, device: 108 was not an MTP device
Jan 22 10:46:32 Raspi-5-Server vhusbdarm64a76[1571758]: Found Full speed device [088e:5036] "iLok, iLok" at address 3143
Jan 22 10:46:32 Raspi-5-Server vhusbdarm64a76[1571758]: Found Full speed device [088e:5036] "iLok, iLok" at address 3144
Jan 22 10:46:37 Raspi-5-Server kernel: usb 3-1.4.4: reset full-speed USB device number 108 using xhci-hcd
Jan 22 10:46:38 Raspi-5-Server vhusbdarm64a76[1571758]: Device 3144 [088e:5036] BOUND to connection 588
Jan 22 10:46:38 Raspi-5-Server kernel: usb 3-1.4.4: reset full-speed USB device number 108 using xhci-hcd
Jan 22 10:47:11 Raspi-5-Server kernel: usb 3-1.4.3: reset full-speed USB device number 107 using xhci-hcd
Jan 22 10:47:11 Raspi-5-Server vhusbdarm64a76[1571758]: Device 3143 [088e:5036] BOUND to connection 592
Jan 22 10:47:11 Raspi-5-Server kernel: usb 3-1.4.3: reset full-speed USB device number 107 using xhci-hcd
Jan 22 10:47:43 Raspi-5-Server vhusbdarm64a76[1571758]: Connection 585 successfully removed (reason:timeout)
Jan 22 10:48:03 Raspi-5-Server vhusbdarm64a76[1571758]: 192.168.10.60 connected as connection 604 (Standard TCP)
Jan 22 10:54:46 Raspi-5-Server kernel: usb 3-1.4.2: reset full-speed USB device number 106 using xhci-hcd
Jan 22 10:54:46 Raspi-5-Server vhusbdarm64a76[1571758]: Device 3142 [088e:5036] BOUND to connection 604
Jan 22 10:54:46 Raspi-5-Server kernel: usb 3-1.4.2: reset full-speed USB device number 106 using xhci-hcd
Jan 22 11:17:01 Raspi-5-Server CRON[3329280]: (root) CMD (cd / && run-parts --report /etc/cron.hourly)
Jan 22 11:23:41 Raspi-5-Server dbus-daemon[701]: [system] Activating via systemd: service name='org.freedesktop.PackageKit' unit='packagekit.service' requested by ':1.11167' (uid=1000 pid=3228613 comm="wf-panel-pi")
Jan 22 11:23:41 Raspi-5-Server systemd[1]: Starting packagekit.service - PackageKit Daemon...
Jan 22 11:23:41 Raspi-5-Server dbus-daemon[701]: [system] Successfully activated service 'org.freedesktop.PackageKit'
Jan 22 11:23:41 Raspi-5-Server systemd[1]: Started packagekit.service - PackageKit Daemon.
Jan 22 11:27:24 Raspi-5-Server NetworkManager[843]: <info>  [1737541644.5641] dhcp4 (eth0): state changed new lease, address=192.168.100.233
Jan 22 11:28:51 Raspi-5-Server systemd[1]: packagekit.service: Deactivated successfully.
Jan 22 11:28:51 Raspi-5-Server systemd[1]: packagekit.service: Consumed 4.426s CPU time.
Jan 22 11:30:59 Raspi-5-Server systemd[1]: Started session-1728.scope - Session 1728 of User pi.
Jan 22 11:32:29 Raspi-5-Server vhusbdarm64a76[1571758]: Device 3143 [088e:5036] UNBOUND from connection 592
Jan 22 11:32:29 Raspi-5-Server vhusbdarm64a76[1571758]: Unmanaging device 3143 [088e:5036]
Jan 22 11:32:29 Raspi-5-Server kernel: usb 3-1.4.3: USB disconnect, device number 107
Jan 22 11:32:29 Raspi-5-Server kernel: usb 3-1.3: USB disconnect, device number 95
Jan 22 11:32:29 Raspi-5-Server kernel: usb 3-1.3: new full-speed USB device number 109 using xhci-hcd
Jan 22 11:32:29 Raspi-5-Server kernel: usb 3-1.3: New USB device found, idVendor=088e, idProduct=5036, bcdDevice= 0.20
Jan 22 11:32:29 Raspi-5-Server kernel: usb 3-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jan 22 11:32:29 Raspi-5-Server kernel: usb 3-1.3: Product: iLok
Jan 22 11:32:29 Raspi-5-Server kernel: usb 3-1.3: Manufacturer: iLok
Jan 22 11:32:29 Raspi-5-Server kernel: usb 3-1.3: SerialNumber: 3988A22A_791F1616
Jan 22 11:32:29 Raspi-5-Server mtp-probe[3337136]: checking bus 3, device 109: "/sys/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-1/3-1.3"
Jan 22 11:32:29 Raspi-5-Server mtp-probe[3337136]: bus: 3, device: 109 was not an MTP device
Jan 22 11:32:29 Raspi-5-Server kernel: usb 3-1.4.3: new full-speed USB device number 110 using xhci-hcd
Jan 22 11:32:29 Raspi-5-Server kernel: usb 3-1.4.3: New USB device found, idVendor=088e, idProduct=5036, bcdDevice= 0.30
Jan 22 11:32:29 Raspi-5-Server kernel: usb 3-1.4.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jan 22 11:32:29 Raspi-5-Server kernel: usb 3-1.4.3: Product: iLok
Jan 22 11:32:29 Raspi-5-Server kernel: usb 3-1.4.3: Manufacturer: iLok
Jan 22 11:32:29 Raspi-5-Server kernel: usb 3-1.4.3: SerialNumber: 3FA0812A_7C391564
Jan 22 11:32:29 Raspi-5-Server mtp-probe[3337144]: checking bus 3, device 110: "/sys/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-1/3-1.4/3-1.4.3"
Jan 22 11:32:29 Raspi-5-Server mtp-probe[3337144]: bus: 3, device: 110 was not an MTP device
Jan 22 11:32:34 Raspi-5-Server vhusbdarm64a76[1571758]: Device 313 [088e:5036] SURPRISE UNBOUND from connection 592
Jan 22 11:32:34 Raspi-5-Server vhusbdarm64a76[1571758]: Unmanaging device 313 [088e:5036]
Jan 22 11:32:35 Raspi-5-Server vhusbdarm64a76[1571758]: Found Full speed device [088e:5036] "iLok, iLok" at address 313
Jan 22 11:32:35 Raspi-5-Server vhusbdarm64a76[1571758]: Found Full speed device [088e:5036] "iLok, iLok" at address 3143
Jan 22 11:32:35 Raspi-5-Server vhusbdarm64a76[1571758]: Unmanaging device 3143 [088e:5036]
Jan 22 11:32:35 Raspi-5-Server kernel: usb 3-1.4.3: USB disconnect, device number 110
Jan 22 11:32:35 Raspi-5-Server kernel: usb 3-1.3: USB disconnect, device number 109
Jan 22 11:32:35 Raspi-5-Server kernel: usb 3-1.3: new full-speed USB device number 111 using xhci-hcd
Jan 22 11:32:35 Raspi-5-Server kernel: usb 3-1.3: New USB device found, idVendor=088e, idProduct=5036, bcdDevice= 0.20
Jan 22 11:32:35 Raspi-5-Server kernel: usb 3-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jan 22 11:32:35 Raspi-5-Server kernel: usb 3-1.3: Product: iLok
Jan 22 11:32:35 Raspi-5-Server kernel: usb 3-1.3: Manufacturer: iLok
Jan 22 11:32:35 Raspi-5-Server kernel: usb 3-1.3: SerialNumber: 3988A22A_791F1616
Jan 22 11:32:35 Raspi-5-Server mtp-probe[3337239]: checking bus 3, device 111: "/sys/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-1/3-1.3"
Jan 22 11:32:35 Raspi-5-Server mtp-probe[3337239]: bus: 3, device: 111 was not an MTP device
Jan 22 11:32:35 Raspi-5-Server kernel: usb 3-1.4.3: new full-speed USB device number 112 using xhci-hcd
Jan 22 11:32:35 Raspi-5-Server kernel: usb 3-1.4.3: New USB device found, idVendor=088e, idProduct=5036, bcdDevice= 0.30
Jan 22 11:32:35 Raspi-5-Server kernel: usb 3-1.4.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jan 22 11:32:35 Raspi-5-Server kernel: usb 3-1.4.3: Product: iLok
Jan 22 11:32:35 Raspi-5-Server kernel: usb 3-1.4.3: Manufacturer: iLok
Jan 22 11:32:35 Raspi-5-Server kernel: usb 3-1.4.3: SerialNumber: 3FA0812A_7C391564
Jan 22 11:32:35 Raspi-5-Server mtp-probe[3337248]: checking bus 3, device 112: "/sys/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-1/3-1.4/3-1.4.3"
Jan 22 11:32:35 Raspi-5-Server mtp-probe[3337248]: bus: 3, device: 112 was not an MTP device
Jan 22 11:32:36 Raspi-5-Server vhusbdarm64a76[1571758]: Unmanaging device 313 [088e:5036]
Jan 22 11:32:36 Raspi-5-Server vhusbdarm64a76[1571758]: Found Full speed device [088e:5036] "iLok, iLok" at address 313
Jan 22 11:32:36 Raspi-5-Server vhusbdarm64a76[1571758]: Found Full speed device [088e:5036] "iLok, iLok" at address 3143
Jan 22 11:32:39 Raspi-5-Server kernel: usb 3-1.4.3: reset full-speed USB device number 112 using xhci-hcd
Jan 22 11:32:39 Raspi-5-Server vhusbdarm64a76[1571758]: Device 3143 [088e:5036] BOUND to connection 581
Jan 22 11:32:39 Raspi-5-Server kernel: usb 3-1.4.3: reset full-speed USB device number 112 using xhci-hcd
 

Submitted by Michael on Thu, 2025/01/23 - 09:39

#4

Thanks for the log. I see two issues

  1. The VirtualHere server attempts to power cycle the USB port every time the iLok is stopped being used, before it used elsewhere. This is so that the iLok can be used between machines. This is expected behavior
  2. The kernel Hub driver is crashing then restarting, causing all the devices attached to that hub to disappear and reappear. I suspect there is a bug in the USB Hub driver in the Linux kernel (either just on the pi or on all Linux) where sending multiple USB Port Power cycle commands eventually triggers the driver (or physical hardware) to enter an invalid state which triggers the driver to restart.

A possible resolution -> Turn off automatic iLok power cycling like this: In the VirtualHere client, right click on one of the iLoks and select Properties->Custom Event Handler... and paste in exactly this:

onUnbind.$VENDOR_ID$.$PRODUCT_ID$=

then press OK. That will stop powercycling the iLok and may prevent the hub driver from crashing. 

 

Submitted by SlyLaM on Fri, 2025/01/24 - 00:33

#5

Ok, but isn't it going to prevent us from using it on another computer ?
Cause we switch iloks upon different computers depending on our needs ...

Submitted by Michael on Fri, 2025/01/24 - 08:02

#6

What hub do you have plugged into the pi5? Does it support per-port-power-switching?

Submitted by SlyLaM on Fri, 2025/01/24 - 22:37

#7

Yes, it’s on the first message. 
DLink DUB-H7

It used to work very well. 
I changed for a Pi5 because I thought my Pi3B+ was faulty. It seems thats not the case. 

Submitted by Michael on Sat, 2025/01/25 - 07:10

#8

OK its your hub i think. You can see that virtualhere asks it to switch power switchHubPortPower failed -1 andit returns an error (-1) then it resets its internal hub itself. Something is wrong with the hub

Submitted by Sylvain on Mon, 2025/01/27 - 18:58

#9

But this hub has been ok for years.
So maybe something's broken but else it's a software pb (OS based maybe).
Do you have a list of supported HUBs ?