Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Attaching USB input devices is slow when laptop-mode-tools is installed (laptop_mode not running) #140

Open
vozhyk- opened this issue Feb 16, 2019 · 15 comments

Comments

@vozhyk-
Copy link

vozhyk- commented Feb 16, 2019

When I close then open the lid in my laptop (I see the Wacom "Pen and multitouch sensor" USB device disconnecting and connecting in the kernel logs then), the touchscreen does not respond for 1 to 5 seconds. When I disconnect then connect a USB trackball, the cursor does not move for a similar period of time. Starting the laptop_mode and acpid services is disabled.

If I uninstall laptop-mode-tools, there is no noticeable delay when reconnecting those devices.

I've found that there is /lib/udev/rules.d/99-laptop-mode.rules that runs lmt-udev force when a USB device is added, and I see #137 mentioning a similar slowdown, but on boot on a different system.

  • Laptop: Thinkpad X1 Yoga 3rd Gen.
  • Distribution: Gentoo Linux, without systemd.
  • laptop-mode-tools version: app-laptop/laptop-mode-tools-1.72.2-r1.
  • eudev version: sys-fs/eudev-3.2.7-r1.
@rickysarraf
Copy link
Owner

THis is working as designed. Laptop Mode Tools applies aggressive power savings by default. You can blacklist the relevant usb device in /etc/laptop-mode/conf.d/runtime-pm.conf and then power savings will not be applied for that device.

@PF4Public
Copy link

You can blacklist the relevant usb device in /etc/laptop-mode/conf.d/runtime-pm.conf and then power savings will not be applied for that device.

I have the same issue with a mouse and LMT.
I have tried adding mouse ID and the USB hub, it is connected to, to the blacklist to no avail.
Also tried adding hid-generic and hid-raw to blacklist. This also didn't change anything.

What do we actually need to write into /etc/laptop-mode/conf.d/runtime-pm.conf to have a device blacklisted? It has already hub and usbhid among others, but this seems to be effectless.

@rickysarraf
Copy link
Owner

@PF4Public I put those defaults keeping in mind that pluggable USB devices are something people would want to not have power savings enabled for.

I just checked here locally to verify that is working or not. But, for me, a plugged-in usb mouse is working fine.

This is what I have in runtime-pm.conf

# The list of Device IDs that should not use autosuspend. Use system commands or
# look into sysfs to find out the IDs of your devices.
# Example: AUTOSUSPEND_RUNTIME_DEVID_BLACKLIST="046d:c025 0123:abcd"
AUTOSUSPEND_RUNTIME_DEVID_BLACKLIST="18d1:d00d ffff:5678 05c6:f00e"

# The list of 'device driver types' or 'device types' that should not use autosuspend.
# The driver type is given by "DRIVER=..." in a device's uevent file.
# The driver type is given by "DEVTYPE=..." in a device's uevent file.
# Example: AUTOSUSPEND_RUNTIME_DEVTYPE_BLACKLIST="usbhid usb-storage"
AUTOSUSPEND_RUNTIME_DEVTYPE_BLACKLIST="hub usbhid usb-storage"

And my external USB mouse is:

rrs@chutzpah:/sys/class$ sudo lsusb 
[sudo] password for rrs: 
Bus 001 Device 002: ID 8087:8000 Intel Corp. 
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 003 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub
Bus 002 Device 016: ID 093a:2510 Pixart Imaging, Inc. Optical Mouse
Bus 002 Device 003: ID 04ca:7032 Lite-On Technology Corp. HP HD Webcam
Bus 002 Device 015: ID 05c6:f00e Qualcomm, Inc. Mobile Router
Bus 002 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
14:32 ♒♒♒   ☺       

As you can see, I haven't blacklisted the mouse's device id. Because the generic usbhid blacklist is enough.

You can also tally the below reporting from powertop. It is inline with what I have set in my runtime-pm.conf file.

>> Bad           Wireless Power Saving for interface wlan0                                                              
   Good          NMI watchdog should be turned off
   Bad           VM writeback timeout
   Bad           Enable SATA link power management for host3
   Bad           Enable SATA link power management for host1
   Bad           Enable SATA link power management for host2
   Bad           Enable SATA link power management for host0
   Bad           Enable Audio codec power management
   Good          Autosuspend for USB device HP HD Webcam [DDZLE019I7MK1R]
   Good          Autosuspend for USB device xHCI Host Controller [usb2]
   Bad           Autosuspend for unknown USB device 1-1 (8087:8000)
   Bad           Autosuspend for USB device USB Optical Mouse [PixArt]   
   Good          Autosuspend for USB device EHCI Host Controller [usb1]
   Good          Autosuspend for USB device xHCI Host Controller [usb3]
   Good          Runtime PM for I2C Adapter i2c-3 (i915 gmbus dpc)
   Good          Runtime PM for I2C Adapter i2c-1 (i915 gmbus vga)
   Good          Runtime PM for I2C Adapter i2c-4 (i915 gmbus dpb)
   Good          Runtime PM for I2C Adapter i2c-2 (i915 gmbus panel)
   Good          Runtime PM for I2C Adapter i2c-0 (i915 gmbus ssc)
   Good          Runtime PM for I2C Adapter i2c-5 (i915 gmbus dpd)
   Bad           Autosuspend for USB device Mobile Router [Qualcomm]
   Good          Runtime PM for PCI Device Intel Corporation 8 Series PCI Express Root Port 1
   Good          Runtime PM for PCI Device Intel Corporation 8 Series PCI Express Root Port 2
   Good          Runtime PM for PCI Device Broadcom Inc. and subsidiaries BCM43228 802.11a/b/g/n
   Good          Runtime PM for PCI Device Intel Corporation 8 Series LPC Controller
   Good          Runtime PM for PCI Device Realtek Semiconductor Co., Ltd. RTL8111/8168/8411 PCI Express Gigabit Ethernet Controller
   Good          Runtime PM for PCI Device Intel Corporation 8 Series HD Audio Controller
   Good          Runtime PM for PCI Device Intel Corporation 8 Series HECI #0
   Good          Runtime PM for PCI Device Realtek Semiconductor Co., Ltd. RTS5227 PCI Express Card Reader
   Good          Runtime PM for PCI Device Intel Corporation 8 Series PCI Express Root Port 4
   Good          Runtime PM for PCI Device Intel Corporation 8 Series USB xHCI HC

It is unfortunate that the same is not working for you. Can you try out the things I have demoed above?
Ultimately, if you have a generic class device, you can blacklist it by its device type. Otherwise, if it is too confusing, you can always explicitly disable the device by its device ID

@matthijskooijman
Copy link

I was also running into this with serial devices (Arduinos) that take a few seconds to get their udev rules applied, which sometimes causes firmware uploads to fail due to permissions not being set yet.

I believe the problem is not so much that laptop-mode powersaving is applied for these devices, but that whenever any USB device is inserted, laptop_mode force is run synchronously from udev which re-applies the laptopt-mode state, but also blocks udev for a few seconds, delaying further processing of the USB device. This probably means that blacklisting will not help, since the full lmt state is reapplied (it will just skip this particular device, but still take some time).

I wonder:

  • Does lmt really need to force apply everything again? If it could maybe just apply things to the new USB device, it might be faster?
  • Does lmt really need to run synchronously from udev? If it would spawn into the background, udev could continue processing right away.

@matthijskooijman
Copy link

Does lmt really need to force apply everything again? If it could maybe just apply things to the new USB device, it might be faster?

It actually seems there is already a (commented) rule in the udev rules file that seems to do just this:

ACTION=="add", SUBSYSTEM=="usb", RUN+="lmt-udev force modules=runtime-pm devices=%k"

I just tried this, and that seems to be fast enough to make udev process the USB device a lot faster (250-300ms, vs 60-150ms when the udev rule is disabled completely, and just under 3s with the default rule). I measured these times by looking at udevadm monitor, looking at the difference between the first event (add) and last event (bind) for the USB device.

@rickysarraf
Copy link
Owner

I was also running into this with serial devices (Arduinos) that take a few seconds to get their udev rules applied, which sometimes causes firmware uploads to fail due to permissions not being set yet.

I believe the problem is not so much that laptop-mode powersaving is applied for these devices, but that whenever any USB device is inserted, laptop_mode force is run synchronously from udev which re-applies the laptopt-mode state, but also blocks udev for a few seconds, delaying further processing of the USB device. This probably means that blacklisting will not help, since the full lmt state is reapplied (it will just skip this particular device, but still take some time).

laptop-mode rules apply almost at the end in the udev rules processing chain.

When you say "delaying further processing of the USB device", do you mean the udev rules ? Because, given we run quite late in the chain, that shouldn't be the effect.

I wonder:

* Does lmt really need to force apply everything again? If it could maybe _just_ apply things to the new USB device, it might be faster?

I'll have to dig the git history and the bug report. But yes, there were reasons to make this the default mode. I left the other option commented out for this very reason. If there are users that need further fine grained control, they could uncomment and use it.

* Does lmt really need to run synchronously from udev? If it would spawn into the background, udev could continue processing right away.

@matthijskooijman
Copy link

When you say "delaying further processing of the USB device", do you mean the udev rules ? Because, given we run quite late in the chain, that shouldn't be the effect.

In practice, I see that applying the chgrp dialout to my /dev/ttyACM0 serial device is delayed a few seconds, which is done using a udev rule (/lib/udev/rules.d/50-udev-default.rules).

What I suspect is that the add event for the USB device itself delays processing of the USB interfaces/subdevices, such as the serial device. Here's what usbadm monitor gives when plugging in with the default rules:

monitor will print the received events for:
UDEV - the event which udev sends out after rule processing
KERNEL - the kernel uevent

KERNEL[70089.912809] add      /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2 (usb)
KERNEL[70089.912929] add      /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.0 (usb)
KERNEL[70089.913322] add      /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.0/tty/ttyACM0 (tty)
KERNEL[70089.913496] bind     /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.0 (usb)
KERNEL[70089.913626] add      /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.1 (usb)
KERNEL[70089.913723] bind     /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.1 (usb)
KERNEL[70089.913808] add      /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.2 (usb)
KERNEL[70089.913903] bind     /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2 (usb)
KERNEL[70090.165970] add      /kernel/slab/:A-0000192/cgroup/cred_jar(18217:session-c93.scope) (cgroup)
KERNEL[70090.166032] add      /kernel/slab/:A-0000256/cgroup/filp(18217:session-c93.scope) (cgroup)
KERNEL[70090.166254] add      /kernel/slab/:A-0000128/cgroup/pid(18217:session-c93.scope) (cgroup)
KERNEL[70090.166287] add      /kernel/slab/:A-0000072/cgroup/eventpoll_pwq(18217:session-c93.scope) (cgroup)
KERNEL[70090.166315] add      /kernel/slab/sock_inode_cache/cgroup/sock_inode_cache(18217:session-c93.scope) (cgroup)
KERNEL[70090.166339] add      /kernel/slab/:A-0001024/cgroup/UNIX(18217:session-c93.scope) (cgroup)
KERNEL[70090.166363] add      /kernel/slab/dentry/cgroup/dentry(18217:session-c93.scope) (cgroup)
UDEV  [70090.169987] add      /kernel/slab/:A-0001024/cgroup/UNIX(18217:session-c93.scope) (cgroup)
KERNEL[70090.170269] add      /kernel/slab/skbuff_head_cache/cgroup/skbuff_head_cache(18217:session-c93.scope) (cgroup)
KERNEL[70090.170502] add      /kernel/slab/kmalloc-512/cgroup/kmalloc-512(18217:session-c93.scope) (cgroup)
UDEV  [70090.171731] add      /kernel/slab/:A-0000072/cgroup/eventpoll_pwq(18217:session-c93.scope) (cgroup)
KERNEL[70090.172004] add      /kernel/slab/kmalloc-64/cgroup/kmalloc-64(18217:session-c93.scope) (cgroup)
UDEV  [70090.172614] add      /kernel/slab/:A-0000192/cgroup/cred_jar(18217:session-c93.scope) (cgroup)
UDEV  [70090.173959] add      /kernel/slab/sock_inode_cache/cgroup/sock_inode_cache(18217:session-c93.scope) (cgroup)
UDEV  [70090.174501] add      /kernel/slab/:A-0000256/cgroup/filp(18217:session-c93.scope) (cgroup)
UDEV  [70090.175312] add      /kernel/slab/:A-0000128/cgroup/pid(18217:session-c93.scope) (cgroup)
UDEV  [70090.177830] add      /kernel/slab/dentry/cgroup/dentry(18217:session-c93.scope) (cgroup)
KERNEL[70090.179962] add      /kernel/slab/task_struct/cgroup/task_struct(18217:session-c93.scope) (cgroup)
KERNEL[70090.180002] add      /kernel/slab/:A-0000080/cgroup/task_delay_info(18217:session-c93.scope) (cgroup)
KERNEL[70090.180026] add      /kernel/slab/:A-0000704/cgroup/files_cache(18217:session-c93.scope) (cgroup)
KERNEL[70090.180048] add      /kernel/slab/:A-0000064/cgroup/anon_vma_chain(18217:session-c93.scope) (cgroup)
KERNEL[70090.180071] add      /kernel/slab/sighand_cache/cgroup/sighand_cache(18217:session-c93.scope) (cgroup)
KERNEL[70090.180092] add      /kernel/slab/:A-0001088/cgroup/signal_cache(18217:session-c93.scope) (cgroup)
KERNEL[70090.180112] add      /kernel/slab/mm_struct/cgroup/mm_struct(18217:session-c93.scope) (cgroup)
KERNEL[70090.180132] add      /kernel/slab/:A-0000208/cgroup/vm_area_struct(18217:session-c93.scope) (cgroup)
KERNEL[70090.180150] add      /kernel/slab/anon_vma/cgroup/anon_vma(18217:session-c93.scope) (cgroup)
UDEV  [70090.182889] add      /kernel/slab/:A-0000064/cgroup/anon_vma_chain(18217:session-c93.scope) (cgroup)
UDEV  [70090.184291] add      /kernel/slab/:A-0001088/cgroup/signal_cache(18217:session-c93.scope) (cgroup)
UDEV  [70090.186420] add      /kernel/slab/task_struct/cgroup/task_struct(18217:session-c93.scope) (cgroup)
UDEV  [70090.186671] add      /kernel/slab/skbuff_head_cache/cgroup/skbuff_head_cache(18217:session-c93.scope) (cgroup)
UDEV  [70090.187160] add      /kernel/slab/sighand_cache/cgroup/sighand_cache(18217:session-c93.scope) (cgroup)
UDEV  [70090.189724] add      /kernel/slab/mm_struct/cgroup/mm_struct(18217:session-c93.scope) (cgroup)
UDEV  [70090.190053] add      /kernel/slab/kmalloc-512/cgroup/kmalloc-512(18217:session-c93.scope) (cgroup)
UDEV  [70090.190610] add      /kernel/slab/:A-0000704/cgroup/files_cache(18217:session-c93.scope) (cgroup)
UDEV  [70090.193474] add      /kernel/slab/:A-0000080/cgroup/task_delay_info(18217:session-c93.scope) (cgroup)
UDEV  [70090.193533] add      /kernel/slab/kmalloc-64/cgroup/kmalloc-64(18217:session-c93.scope) (cgroup)
UDEV  [70090.197027] add      /kernel/slab/:A-0000208/cgroup/vm_area_struct(18217:session-c93.scope) (cgroup)
UDEV  [70090.198307] add      /kernel/slab/anon_vma/cgroup/anon_vma(18217:session-c93.scope) (cgroup)
KERNEL[70090.261763] remove   /kernel/slab/:A-0001024/cgroup/UNIX(18217:session-c93.scope) (cgroup)
KERNEL[70090.261819] remove   /kernel/slab/:A-0000072/cgroup/eventpoll_pwq(18217:session-c93.scope) (cgroup)
KERNEL[70090.261854] remove   /kernel/slab/sock_inode_cache/cgroup/sock_inode_cache(18217:session-c93.scope) (cgroup)
KERNEL[70090.261888] remove   /kernel/slab/skbuff_head_cache/cgroup/skbuff_head_cache(18217:session-c93.scope) (cgroup)
KERNEL[70090.261920] remove   /kernel/slab/:A-0000080/cgroup/task_delay_info(18217:session-c93.scope) (cgroup)
KERNEL[70090.261954] remove   /kernel/slab/:A-0000256/cgroup/filp(18217:session-c93.scope) (cgroup)
KERNEL[70090.261982] remove   /kernel/slab/:A-0000208/cgroup/vm_area_struct(18217:session-c93.scope) (cgroup)
KERNEL[70090.262019] remove   /kernel/slab/mm_struct/cgroup/mm_struct(18217:session-c93.scope) (cgroup)
KERNEL[70090.262047] remove   /kernel/slab/:A-0000704/cgroup/files_cache(18217:session-c93.scope) (cgroup)
KERNEL[70090.262075] remove   /kernel/slab/:A-0001088/cgroup/signal_cache(18217:session-c93.scope) (cgroup)
KERNEL[70090.262100] remove   /kernel/slab/sighand_cache/cgroup/sighand_cache(18217:session-c93.scope) (cgroup)
KERNEL[70090.262124] remove   /kernel/slab/task_struct/cgroup/task_struct(18217:session-c93.scope) (cgroup)
KERNEL[70090.262146] remove   /kernel/slab/:A-0000064/cgroup/anon_vma_chain(18217:session-c93.scope) (cgroup)
KERNEL[70090.262168] remove   /kernel/slab/anon_vma/cgroup/anon_vma(18217:session-c93.scope) (cgroup)
KERNEL[70090.262188] remove   /kernel/slab/:A-0000128/cgroup/pid(18217:session-c93.scope) (cgroup)
KERNEL[70090.262208] remove   /kernel/slab/kmalloc-512/cgroup/kmalloc-512(18217:session-c93.scope) (cgroup)
UDEV  [70090.263502] remove   /kernel/slab/:A-0000256/cgroup/filp(18217:session-c93.scope) (cgroup)
UDEV  [70090.264153] remove   /kernel/slab/:A-0001024/cgroup/UNIX(18217:session-c93.scope) (cgroup)
UDEV  [70090.264192] remove   /kernel/slab/:A-0000208/cgroup/vm_area_struct(18217:session-c93.scope) (cgroup)
UDEV  [70090.265125] remove   /kernel/slab/:A-0001088/cgroup/signal_cache(18217:session-c93.scope) (cgroup)
UDEV  [70090.266725] remove   /kernel/slab/mm_struct/cgroup/mm_struct(18217:session-c93.scope) (cgroup)
UDEV  [70090.267519] remove   /kernel/slab/:A-0000704/cgroup/files_cache(18217:session-c93.scope) (cgroup)
UDEV  [70090.268421] remove   /kernel/slab/sighand_cache/cgroup/sighand_cache(18217:session-c93.scope) (cgroup)
UDEV  [70090.269152] remove   /kernel/slab/sock_inode_cache/cgroup/sock_inode_cache(18217:session-c93.scope) (cgroup)
UDEV  [70090.269820] remove   /kernel/slab/skbuff_head_cache/cgroup/skbuff_head_cache(18217:session-c93.scope) (cgroup)
UDEV  [70090.270231] remove   /kernel/slab/:A-0000080/cgroup/task_delay_info(18217:session-c93.scope) (cgroup)
UDEV  [70090.270376] remove   /kernel/slab/task_struct/cgroup/task_struct(18217:session-c93.scope) (cgroup)
UDEV  [70090.271597] remove   /kernel/slab/:A-0000072/cgroup/eventpoll_pwq(18217:session-c93.scope) (cgroup)
UDEV  [70090.275151] remove   /kernel/slab/anon_vma/cgroup/anon_vma(18217:session-c93.scope) (cgroup)
UDEV  [70090.275258] remove   /kernel/slab/:A-0000128/cgroup/pid(18217:session-c93.scope) (cgroup)
UDEV  [70090.278053] remove   /kernel/slab/:A-0000064/cgroup/anon_vma_chain(18217:session-c93.scope) (cgroup)
UDEV  [70090.278734] remove   /kernel/slab/kmalloc-512/cgroup/kmalloc-512(18217:session-c93.scope) (cgroup)
KERNEL[70090.282116] remove   /cred_jar(18217:session-c93.scope) (cgroup)
KERNEL[70090.282199] remove   /kmalloc-64(18217:session-c93.scope) (cgroup)
UDEV  [70090.282987] remove   /cred_jar(18217:session-c93.scope) (cgroup)
UDEV  [70090.283917] remove   /kmalloc-64(18217:session-c93.scope) (cgroup)
KERNEL[70090.366107] remove   /dentry(18217:session-c93.scope) (cgroup)
UDEV  [70090.367643] remove   /dentry(18217:session-c93.scope) (cgroup)
UDEV  [70090.684625] add      /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2 (usb)
UDEV  [70090.771368] add      /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.2 (usb)
KERNEL[70090.990527] add      /kernel/slab/:A-0000192/cgroup/cred_jar(18223:session-c94.scope) (cgroup)
UDEV  [70090.992140] add      /kernel/slab/:A-0000192/cgroup/cred_jar(18223:session-c94.scope) (cgroup)
KERNEL[70090.992192] add      /kernel/slab/:A-0000256/cgroup/filp(18223:session-c94.scope) (cgroup)
KERNEL[70090.992220] add      /kernel/slab/:A-0000128/cgroup/pid(18223:session-c94.scope) (cgroup)
KERNEL[70090.992244] add      /kernel/slab/:A-0000072/cgroup/eventpoll_pwq(18223:session-c94.scope) (cgroup)
KERNEL[70090.992266] add      /kernel/slab/sock_inode_cache/cgroup/sock_inode_cache(18223:session-c94.scope) (cgroup)
KERNEL[70090.992290] add      /kernel/slab/:A-0001024/cgroup/UNIX(18223:session-c94.scope) (cgroup)
KERNEL[70090.992312] add      /kernel/slab/dentry/cgroup/dentry(18223:session-c94.scope) (cgroup)
KERNEL[70090.993622] add      /kernel/slab/skbuff_head_cache/cgroup/skbuff_head_cache(18223:session-c94.scope) (cgroup)
KERNEL[70090.993652] add      /kernel/slab/kmalloc-512/cgroup/kmalloc-512(18223:session-c94.scope) (cgroup)
UDEV  [70090.993965] add      /kernel/slab/:A-0001024/cgroup/UNIX(18223:session-c94.scope) (cgroup)
UDEV  [70090.994002] add      /kernel/slab/:A-0000256/cgroup/filp(18223:session-c94.scope) (cgroup)
UDEV  [70090.995161] add      /kernel/slab/:A-0000128/cgroup/pid(18223:session-c94.scope) (cgroup)
UDEV  [70090.996336] add      /kernel/slab/:A-0000072/cgroup/eventpoll_pwq(18223:session-c94.scope) (cgroup)
UDEV  [70090.997527] add      /kernel/slab/sock_inode_cache/cgroup/sock_inode_cache(18223:session-c94.scope) (cgroup)
KERNEL[70091.000460] add      /kernel/slab/kmalloc-64/cgroup/kmalloc-64(18223:session-c94.scope) (cgroup)
UDEV  [70091.000521] add      /kernel/slab/dentry/cgroup/dentry(18223:session-c94.scope) (cgroup)
UDEV  [70091.003597] add      /kernel/slab/skbuff_head_cache/cgroup/skbuff_head_cache(18223:session-c94.scope) (cgroup)
UDEV  [70091.004563] add      /kernel/slab/kmalloc-64/cgroup/kmalloc-64(18223:session-c94.scope) (cgroup)
UDEV  [70091.005266] add      /kernel/slab/kmalloc-512/cgroup/kmalloc-512(18223:session-c94.scope) (cgroup)
KERNEL[70091.007955] add      /kernel/slab/task_struct/cgroup/task_struct(18223:session-c94.scope) (cgroup)
KERNEL[70091.007997] add      /kernel/slab/:A-0000080/cgroup/task_delay_info(18223:session-c94.scope) (cgroup)
KERNEL[70091.008018] add      /kernel/slab/:A-0000704/cgroup/files_cache(18223:session-c94.scope) (cgroup)
KERNEL[70091.008038] add      /kernel/slab/:A-0000064/cgroup/anon_vma_chain(18223:session-c94.scope) (cgroup)
KERNEL[70091.008064] add      /kernel/slab/sighand_cache/cgroup/sighand_cache(18223:session-c94.scope) (cgroup)
KERNEL[70091.008086] add      /kernel/slab/:A-0001088/cgroup/signal_cache(18223:session-c94.scope) (cgroup)
KERNEL[70091.008109] add      /kernel/slab/mm_struct/cgroup/mm_struct(18223:session-c94.scope) (cgroup)
KERNEL[70091.008131] add      /kernel/slab/:A-0000208/cgroup/vm_area_struct(18223:session-c94.scope) (cgroup)
KERNEL[70091.008154] add      /kernel/slab/anon_vma/cgroup/anon_vma(18223:session-c94.scope) (cgroup)
UDEV  [70091.009763] add      /kernel/slab/:A-0000704/cgroup/files_cache(18223:session-c94.scope) (cgroup)
UDEV  [70091.010319] add      /kernel/slab/task_struct/cgroup/task_struct(18223:session-c94.scope) (cgroup)
UDEV  [70091.011267] add      /kernel/slab/sighand_cache/cgroup/sighand_cache(18223:session-c94.scope) (cgroup)
UDEV  [70091.011704] add      /kernel/slab/:A-0000080/cgroup/task_delay_info(18223:session-c94.scope) (cgroup)
UDEV  [70091.012941] add      /kernel/slab/:A-0000208/cgroup/vm_area_struct(18223:session-c94.scope) (cgroup)
UDEV  [70091.014284] add      /kernel/slab/anon_vma/cgroup/anon_vma(18223:session-c94.scope) (cgroup)
UDEV  [70091.016792] add      /kernel/slab/:A-0000064/cgroup/anon_vma_chain(18223:session-c94.scope) (cgroup)
UDEV  [70091.016847] add      /kernel/slab/:A-0001088/cgroup/signal_cache(18223:session-c94.scope) (cgroup)
UDEV  [70091.021511] add      /kernel/slab/mm_struct/cgroup/mm_struct(18223:session-c94.scope) (cgroup)
KERNEL[70091.069739] remove   /kernel/slab/:A-0001024/cgroup/UNIX(18223:session-c94.scope) (cgroup)
KERNEL[70091.069790] remove   /kernel/slab/:A-0000072/cgroup/eventpoll_pwq(18223:session-c94.scope) (cgroup)
KERNEL[70091.069813] remove   /kernel/slab/sock_inode_cache/cgroup/sock_inode_cache(18223:session-c94.scope) (cgroup)
KERNEL[70091.069832] remove   /kernel/slab/skbuff_head_cache/cgroup/skbuff_head_cache(18223:session-c94.scope) (cgroup)
KERNEL[70091.069855] remove   /kernel/slab/:A-0000080/cgroup/task_delay_info(18223:session-c94.scope) (cgroup)
KERNEL[70091.069880] remove   /kernel/slab/:A-0000256/cgroup/filp(18223:session-c94.scope) (cgroup)
KERNEL[70091.069933] remove   /kernel/slab/:A-0000208/cgroup/vm_area_struct(18223:session-c94.scope) (cgroup)
KERNEL[70091.069958] remove   /kernel/slab/mm_struct/cgroup/mm_struct(18223:session-c94.scope) (cgroup)
KERNEL[70091.069985] remove   /kernel/slab/:A-0000704/cgroup/files_cache(18223:session-c94.scope) (cgroup)
KERNEL[70091.070031] remove   /kernel/slab/:A-0001088/cgroup/signal_cache(18223:session-c94.scope) (cgroup)
KERNEL[70091.070059] remove   /kernel/slab/sighand_cache/cgroup/sighand_cache(18223:session-c94.scope) (cgroup)
KERNEL[70091.070084] remove   /kernel/slab/task_struct/cgroup/task_struct(18223:session-c94.scope) (cgroup)
KERNEL[70091.070110] remove   /kernel/slab/:A-0000192/cgroup/cred_jar(18223:session-c94.scope) (cgroup)
KERNEL[70091.070131] remove   /kernel/slab/:A-0000064/cgroup/anon_vma_chain(18223:session-c94.scope) (cgroup)
KERNEL[70091.070152] remove   /kernel/slab/anon_vma/cgroup/anon_vma(18223:session-c94.scope) (cgroup)
KERNEL[70091.070171] remove   /kernel/slab/:A-0000128/cgroup/pid(18223:session-c94.scope) (cgroup)
KERNEL[70091.070192] remove   /kernel/slab/kmalloc-512/cgroup/kmalloc-512(18223:session-c94.scope) (cgroup)
KERNEL[70091.070211] remove   /kernel/slab/kmalloc-64/cgroup/kmalloc-64(18223:session-c94.scope) (cgroup)
UDEV  [70091.071410] remove   /kernel/slab/sock_inode_cache/cgroup/sock_inode_cache(18223:session-c94.scope) (cgroup)
UDEV  [70091.072276] remove   /kernel/slab/:A-0000080/cgroup/task_delay_info(18223:session-c94.scope) (cgroup)
UDEV  [70091.073115] remove   /kernel/slab/mm_struct/cgroup/mm_struct(18223:session-c94.scope) (cgroup)
UDEV  [70091.073351] remove   /kernel/slab/:A-0000072/cgroup/eventpoll_pwq(18223:session-c94.scope) (cgroup)
UDEV  [70091.073965] remove   /kernel/slab/sighand_cache/cgroup/sighand_cache(18223:session-c94.scope) (cgroup)
UDEV  [70091.074816] remove   /kernel/slab/task_struct/cgroup/task_struct(18223:session-c94.scope) (cgroup)
UDEV  [70091.076887] remove   /kernel/slab/:A-0000704/cgroup/files_cache(18223:session-c94.scope) (cgroup)
UDEV  [70091.078418] remove   /kernel/slab/:A-0000192/cgroup/cred_jar(18223:session-c94.scope) (cgroup)
UDEV  [70091.078844] remove   /kernel/slab/skbuff_head_cache/cgroup/skbuff_head_cache(18223:session-c94.scope) (cgroup)
UDEV  [70091.079524] remove   /kernel/slab/:A-0000208/cgroup/vm_area_struct(18223:session-c94.scope) (cgroup)
UDEV  [70091.080090] remove   /kernel/slab/:A-0001088/cgroup/signal_cache(18223:session-c94.scope) (cgroup)
UDEV  [70091.082517] remove   /kernel/slab/:A-0000256/cgroup/filp(18223:session-c94.scope) (cgroup)
UDEV  [70091.082768] remove   /kernel/slab/:A-0001024/cgroup/UNIX(18223:session-c94.scope) (cgroup)
UDEV  [70091.091457] remove   /kernel/slab/:A-0000128/cgroup/pid(18223:session-c94.scope) (cgroup)
UDEV  [70091.094193] remove   /kernel/slab/anon_vma/cgroup/anon_vma(18223:session-c94.scope) (cgroup)
UDEV  [70091.099014] remove   /kernel/slab/kmalloc-64/cgroup/kmalloc-64(18223:session-c94.scope) (cgroup)
UDEV  [70091.099204] remove   /kernel/slab/kmalloc-512/cgroup/kmalloc-512(18223:session-c94.scope) (cgroup)
UDEV  [70091.101786] remove   /kernel/slab/:A-0000064/cgroup/anon_vma_chain(18223:session-c94.scope) (cgroup)
KERNEL[70091.213924] remove   /dentry(18223:session-c94.scope) (cgroup)
UDEV  [70091.215026] remove   /dentry(18223:session-c94.scope) (cgroup)
UDEV  [70091.639438] add      /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.1 (usb)
UDEV  [70091.645325] bind     /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.1 (usb)
KERNEL[70091.857914] add      /kernel/slab/:A-0000192/cgroup/cred_jar(18229:session-c95.scope) (cgroup)
KERNEL[70091.858464] add      /kernel/slab/:A-0000256/cgroup/filp(18229:session-c95.scope) (cgroup)
KERNEL[70091.858495] add      /kernel/slab/:A-0000128/cgroup/pid(18229:session-c95.scope) (cgroup)
KERNEL[70091.858520] add      /kernel/slab/:A-0000072/cgroup/eventpoll_pwq(18229:session-c95.scope) (cgroup)
KERNEL[70091.858540] add      /kernel/slab/sock_inode_cache/cgroup/sock_inode_cache(18229:session-c95.scope) (cgroup)
KERNEL[70091.858561] add      /kernel/slab/:A-0001024/cgroup/UNIX(18229:session-c95.scope) (cgroup)
KERNEL[70091.858581] add      /kernel/slab/dentry/cgroup/dentry(18229:session-c95.scope) (cgroup)
KERNEL[70091.861817] add      /kernel/slab/skbuff_head_cache/cgroup/skbuff_head_cache(18229:session-c95.scope) (cgroup)
KERNEL[70091.861863] add      /kernel/slab/kmalloc-512/cgroup/kmalloc-512(18229:session-c95.scope) (cgroup)
UDEV  [70091.862314] add      /kernel/slab/:A-0000072/cgroup/eventpoll_pwq(18229:session-c95.scope) (cgroup)
UDEV  [70091.862434] add      /kernel/slab/:A-0000192/cgroup/cred_jar(18229:session-c95.scope) (cgroup)
UDEV  [70091.863629] add      /kernel/slab/dentry/cgroup/dentry(18229:session-c95.scope) (cgroup)
UDEV  [70091.863798] add      /kernel/slab/:A-0001024/cgroup/UNIX(18229:session-c95.scope) (cgroup)
UDEV  [70091.865110] add      /kernel/slab/sock_inode_cache/cgroup/sock_inode_cache(18229:session-c95.scope) (cgroup)
UDEV  [70091.865922] add      /kernel/slab/:A-0000128/cgroup/pid(18229:session-c95.scope) (cgroup)
KERNEL[70091.869763] add      /kernel/slab/kmalloc-64/cgroup/kmalloc-64(18229:session-c95.scope) (cgroup)
UDEV  [70091.880121] add      /kernel/slab/kmalloc-64/cgroup/kmalloc-64(18229:session-c95.scope) (cgroup)
UDEV  [70091.882352] add      /kernel/slab/:A-0000256/cgroup/filp(18229:session-c95.scope) (cgroup)
UDEV  [70091.883339] add      /kernel/slab/skbuff_head_cache/cgroup/skbuff_head_cache(18229:session-c95.scope) (cgroup)
UDEV  [70091.884001] add      /kernel/slab/kmalloc-512/cgroup/kmalloc-512(18229:session-c95.scope) (cgroup)
KERNEL[70091.885588] add      /kernel/slab/task_struct/cgroup/task_struct(18229:session-c95.scope) (cgroup)
KERNEL[70091.885624] add      /kernel/slab/:A-0000080/cgroup/task_delay_info(18229:session-c95.scope) (cgroup)
KERNEL[70091.885647] add      /kernel/slab/:A-0000704/cgroup/files_cache(18229:session-c95.scope) (cgroup)
KERNEL[70091.885670] add      /kernel/slab/:A-0000064/cgroup/anon_vma_chain(18229:session-c95.scope) (cgroup)
KERNEL[70091.885693] add      /kernel/slab/sighand_cache/cgroup/sighand_cache(18229:session-c95.scope) (cgroup)
KERNEL[70091.885714] add      /kernel/slab/:A-0001088/cgroup/signal_cache(18229:session-c95.scope) (cgroup)
KERNEL[70091.885735] add      /kernel/slab/mm_struct/cgroup/mm_struct(18229:session-c95.scope) (cgroup)
KERNEL[70091.885755] add      /kernel/slab/:A-0000208/cgroup/vm_area_struct(18229:session-c95.scope) (cgroup)
KERNEL[70091.885774] add      /kernel/slab/anon_vma/cgroup/anon_vma(18229:session-c95.scope) (cgroup)
UDEV  [70091.887740] add      /kernel/slab/sighand_cache/cgroup/sighand_cache(18229:session-c95.scope) (cgroup)
UDEV  [70091.889111] add      /kernel/slab/:A-0000208/cgroup/vm_area_struct(18229:session-c95.scope) (cgroup)
UDEV  [70091.891318] add      /kernel/slab/:A-0000064/cgroup/anon_vma_chain(18229:session-c95.scope) (cgroup)
UDEV  [70091.893993] add      /kernel/slab/anon_vma/cgroup/anon_vma(18229:session-c95.scope) (cgroup)
UDEV  [70091.897705] add      /kernel/slab/:A-0000704/cgroup/files_cache(18229:session-c95.scope) (cgroup)
UDEV  [70091.898370] add      /kernel/slab/:A-0000080/cgroup/task_delay_info(18229:session-c95.scope) (cgroup)
UDEV  [70091.898775] add      /kernel/slab/mm_struct/cgroup/mm_struct(18229:session-c95.scope) (cgroup)
UDEV  [70091.899823] add      /kernel/slab/:A-0001088/cgroup/signal_cache(18229:session-c95.scope) (cgroup)
UDEV  [70091.903695] add      /kernel/slab/task_struct/cgroup/task_struct(18229:session-c95.scope) (cgroup)
KERNEL[70091.942063] remove   /kernel/slab/:A-0001024/cgroup/UNIX(18229:session-c95.scope) (cgroup)
KERNEL[70091.942111] remove   /kernel/slab/:A-0000072/cgroup/eventpoll_pwq(18229:session-c95.scope) (cgroup)
KERNEL[70091.942135] remove   /kernel/slab/sock_inode_cache/cgroup/sock_inode_cache(18229:session-c95.scope) (cgroup)
KERNEL[70091.942159] remove   /kernel/slab/skbuff_head_cache/cgroup/skbuff_head_cache(18229:session-c95.scope) (cgroup)
KERNEL[70091.942182] remove   /kernel/slab/:A-0000080/cgroup/task_delay_info(18229:session-c95.scope) (cgroup)
KERNEL[70091.942204] remove   /kernel/slab/:A-0000256/cgroup/filp(18229:session-c95.scope) (cgroup)
KERNEL[70091.942227] remove   /kernel/slab/:A-0000208/cgroup/vm_area_struct(18229:session-c95.scope) (cgroup)
KERNEL[70091.942251] remove   /kernel/slab/mm_struct/cgroup/mm_struct(18229:session-c95.scope) (cgroup)
KERNEL[70091.942272] remove   /kernel/slab/:A-0000704/cgroup/files_cache(18229:session-c95.scope) (cgroup)
KERNEL[70091.942296] remove   /kernel/slab/:A-0001088/cgroup/signal_cache(18229:session-c95.scope) (cgroup)
KERNEL[70091.942318] remove   /kernel/slab/sighand_cache/cgroup/sighand_cache(18229:session-c95.scope) (cgroup)
KERNEL[70091.942340] remove   /kernel/slab/task_struct/cgroup/task_struct(18229:session-c95.scope) (cgroup)
KERNEL[70091.942363] remove   /kernel/slab/:A-0000064/cgroup/anon_vma_chain(18229:session-c95.scope) (cgroup)
KERNEL[70091.942385] remove   /kernel/slab/anon_vma/cgroup/anon_vma(18229:session-c95.scope) (cgroup)
KERNEL[70091.942408] remove   /kernel/slab/:A-0000128/cgroup/pid(18229:session-c95.scope) (cgroup)
KERNEL[70091.942430] remove   /kernel/slab/kmalloc-512/cgroup/kmalloc-512(18229:session-c95.scope) (cgroup)
KERNEL[70091.942452] remove   /kernel/slab/kmalloc-64/cgroup/kmalloc-64(18229:session-c95.scope) (cgroup)
UDEV  [70091.942909] remove   /kernel/slab/:A-0001024/cgroup/UNIX(18229:session-c95.scope) (cgroup)
UDEV  [70091.942945] remove   /kernel/slab/skbuff_head_cache/cgroup/skbuff_head_cache(18229:session-c95.scope) (cgroup)
UDEV  [70091.944419] remove   /kernel/slab/mm_struct/cgroup/mm_struct(18229:session-c95.scope) (cgroup)
UDEV  [70091.945294] remove   /kernel/slab/task_struct/cgroup/task_struct(18229:session-c95.scope) (cgroup)
UDEV  [70091.947050] remove   /kernel/slab/:A-0000256/cgroup/filp(18229:session-c95.scope) (cgroup)
UDEV  [70091.949456] remove   /kernel/slab/:A-0000208/cgroup/vm_area_struct(18229:session-c95.scope) (cgroup)
UDEV  [70091.951988] remove   /kernel/slab/sighand_cache/cgroup/sighand_cache(18229:session-c95.scope) (cgroup)
UDEV  [70091.952796] remove   /kernel/slab/:A-0001088/cgroup/signal_cache(18229:session-c95.scope) (cgroup)
UDEV  [70091.953482] remove   /kernel/slab/sock_inode_cache/cgroup/sock_inode_cache(18229:session-c95.scope) (cgroup)
UDEV  [70091.954075] remove   /kernel/slab/:A-0000704/cgroup/files_cache(18229:session-c95.scope) (cgroup)
UDEV  [70091.954348] remove   /kernel/slab/:A-0000072/cgroup/eventpoll_pwq(18229:session-c95.scope) (cgroup)
UDEV  [70091.954519] remove   /kernel/slab/:A-0000080/cgroup/task_delay_info(18229:session-c95.scope) (cgroup)
UDEV  [70091.956801] remove   /kernel/slab/anon_vma/cgroup/anon_vma(18229:session-c95.scope) (cgroup)
UDEV  [70091.957455] remove   /kernel/slab/:A-0000128/cgroup/pid(18229:session-c95.scope) (cgroup)
UDEV  [70091.957572] remove   /kernel/slab/:A-0000064/cgroup/anon_vma_chain(18229:session-c95.scope) (cgroup)
UDEV  [70091.961557] remove   /kernel/slab/kmalloc-512/cgroup/kmalloc-512(18229:session-c95.scope) (cgroup)
UDEV  [70091.962581] remove   /kernel/slab/kmalloc-64/cgroup/kmalloc-64(18229:session-c95.scope) (cgroup)
KERNEL[70091.966030] remove   /cred_jar(18229:session-c95.scope) (cgroup)
UDEV  [70091.966783] remove   /cred_jar(18229:session-c95.scope) (cgroup)
KERNEL[70092.014001] remove   /dentry(18229:session-c95.scope) (cgroup)
UDEV  [70092.018226] remove   /dentry(18229:session-c95.scope) (cgroup)
UDEV  [70092.378484] add      /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.0 (usb)
UDEV  [70092.383572] add      /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.0/tty/ttyACM0 (tty)
UDEV  [70092.393660] bind     /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.0 (usb)
UDEV  [70092.399722] bind     /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2 (usb)

All these kernel/cgroup lines seem to be generated by laptop-mode-tools (they are absent when I remove the lmt rule from udev). As you can see, at least some part of the ttyACM event is listed after all this cgroup stuff, so I suspect that the dialout rule happens there (though there is also a ttyACM event before the lmt stuff, so I'm not entirely sure how to interpret this output).

@rickysarraf
Copy link
Owner

I did a plug/unplug of my USB Type-C headphone and I don't get similar results.

rrs@priyasi:~$ udevadm monitor
monitor will print the received events for:
UDEV - the event which udev sends out after rule processing
KERNEL - the kernel uevent




KERNEL[104172.404179] change   /devices/platform/USBC000:00/typec/port0 (typec)
KERNEL[104172.404202] add      /devices/platform/USBC000:00/typec/port0/port0-partner (typec)
UDEV  [104172.405772] change   /devices/platform/USBC000:00/typec/port0 (typec)
UDEV  [104172.406712] add      /devices/platform/USBC000:00/typec/port0/port0-partner (typec)
KERNEL[104173.345985] add      /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/wakeup/wakeup45 (wakeup)
KERNEL[104173.346069] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/wakeup/wakeup46 (wakeup)
KERNEL[104173.346332] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0 (pci)
KERNEL[104173.346441] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/pci_bus/0000:04 (pci_bus)
UDEV  [104173.350720] add      /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/wakeup/wakeup45 (wakeup)
KERNEL[104173.474460] add      /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/device:26/wakeup/wakeup47 (wakeup)
KERNEL[104173.476850] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:00.0 (pci)
KERNEL[104173.476927] add      /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/device:27/wakeup/wakeup48 (wakeup)
UDEV  [104173.476986] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/wakeup/wakeup46 (wakeup)
KERNEL[104173.477057] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:01.0 (pci)
KERNEL[104173.477097] add      /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/device:28/wakeup/wakeup49 (wakeup)
KERNEL[104173.477162] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0 (pci)
KERNEL[104173.477226] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:04.0 (pci)
KERNEL[104173.477264] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:00.0/pci_bus/0000:05 (pci_bus)
KERNEL[104173.477299] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:01.0/pci_bus/0000:06 (pci_bus)
KERNEL[104173.477333] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/pci_bus/0000:39 (pci_bus)
KERNEL[104173.486911] add      /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/device:28/device:29/wakeup/wakeup50 (wakeup)
UDEV  [104173.487050] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0 (pci)
KERNEL[104173.487100] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0 (pci)
UDEV  [104173.487130] add      /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/device:28/device:29/wakeup/wakeup50 (wakeup)
KERNEL[104173.487156] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:04.0/pci_bus/0000:3a (pci_bus)
KERNEL[104173.487196] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0 (pci)
KERNEL[104173.487222] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:00.0/0000:04:00.0:pcie210 (pci_express)
KERNEL[104173.487262] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:00.0 (pci)
KERNEL[104173.487286] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:01.0/0000:04:01.0:pcie210 (pci_express)
KERNEL[104173.487326] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:01.0 (pci)
KERNEL[104173.487349] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:04:02.0:pcie210 (pci_express)
KERNEL[104173.487386] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0 (pci)
KERNEL[104173.487411] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:04.0/0000:04:04.0:pcie210 (pci_express)
KERNEL[104173.487447] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:04.0 (pci)
KERNEL[104173.487556] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3 (usb)
KERNEL[104173.487656] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-0:1.0 (usb)
UDEV  [104173.487819] add      /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/device:28/wakeup/wakeup49 (wakeup)
UDEV  [104173.487872] add      /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/device:27/wakeup/wakeup48 (wakeup)
KERNEL[104173.488250] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-0:1.0 (usb)
KERNEL[104173.488333] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3 (usb)
KERNEL[104173.488379] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/wakeup/wakeup51 (wakeup)
KERNEL[104173.489673] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb4 (usb)
UDEV  [104173.489742] add      /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/device:26/wakeup/wakeup47 (wakeup)
KERNEL[104173.489805] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb4/4-0:1.0 (usb)
KERNEL[104173.489867] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb4/4-0:1.0 (usb)
KERNEL[104173.489934] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb4 (usb)
KERNEL[104173.489998] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0 (pci)
UDEV  [104173.491631] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/pci_bus/0000:04 (pci_bus)
UDEV  [104173.492195] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:01.0 (pci)
UDEV  [104173.493666] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:00.0 (pci)
UDEV  [104173.497119] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:04.0 (pci)
UDEV  [104173.498649] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0 (pci)
UDEV  [104173.498701] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:01.0/pci_bus/0000:06 (pci_bus)
UDEV  [104173.498747] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:00.0/pci_bus/0000:05 (pci_bus)
UDEV  [104173.498794] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:04.0/pci_bus/0000:3a (pci_bus)
UDEV  [104173.500997] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/pci_bus/0000:39 (pci_bus)
UDEV  [104173.501493] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0 (pci)
UDEV  [104173.504884] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0 (pci)
UDEV  [104173.507430] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:00.0/0000:04:00.0:pcie210 (pci_express)
UDEV  [104173.507457] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:04:02.0:pcie210 (pci_express)
UDEV  [104173.507481] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:04.0/0000:04:04.0:pcie210 (pci_express)
UDEV  [104173.507512] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:01.0/0000:04:01.0:pcie210 (pci_express)
UDEV  [104173.508842] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:00.0 (pci)
UDEV  [104173.509265] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:04.0 (pci)
UDEV  [104173.509554] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0 (pci)
UDEV  [104173.510923] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:01.0 (pci)
UDEV  [104173.511471] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/wakeup/wakeup51 (wakeup)
KERNEL[104173.980835] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1 (usb)
KERNEL[104173.981543] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0 (usb)
KERNEL[104173.991907] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/sound/card1 (sound)
KERNEL[104173.992002] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/sound/card1/pcmC1D0p (sound)
KERNEL[104173.992032] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/sound/card1/pcmC1D0c (sound)
KERNEL[104173.992141] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/sound/card1/controlC1 (sound)
KERNEL[104173.992165] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0 (usb)
KERNEL[104173.992184] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.1 (usb)
KERNEL[104173.992200] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.1 (usb)
KERNEL[104173.992216] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.2 (usb)
KERNEL[104173.992230] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.2 (usb)
KERNEL[104173.992244] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3 (usb)
KERNEL[104173.992481] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3/0003:045E:070F.0005 (hid)
KERNEL[104173.992583] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3/0003:045E:070F.0005/input/input50 (input)
KERNEL[104174.055163] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3/0003:045E:070F.0005/input/input50/event20 (input)
KERNEL[104174.055197] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3/0003:045E:070F.0005/hidraw/hidraw1 (hidraw)
KERNEL[104174.055228] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3/0003:045E:070F.0005 (hid)
KERNEL[104174.055258] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3 (usb)
KERNEL[104174.055288] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1 (usb)
UDEV  [104174.090710] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3 (usb)
UDEV  [104174.131426] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-0:1.0 (usb)
UDEV  [104174.135895] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-0:1.0 (usb)
UDEV  [104174.139393] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3 (usb)
UDEV  [104174.604284] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb4 (usb)
UDEV  [104175.147761] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb4/4-0:1.0 (usb)
UDEV  [104175.149862] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb4/4-0:1.0 (usb)
UDEV  [104175.153074] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb4 (usb)
UDEV  [104175.154456] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0 (pci)
UDEV  [104175.710234] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1 (usb)
UDEV  [104175.795982] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3 (usb)
UDEV  [104175.800551] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3/0003:045E:070F.0005 (hid)
UDEV  [104175.804579] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3/0003:045E:070F.0005/input/input50 (input)
UDEV  [104175.814043] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.1 (usb)
UDEV  [104175.817182] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3/0003:045E:070F.0005/hidraw/hidraw1 (hidraw)
UDEV  [104175.817697] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.1 (usb)
UDEV  [104175.918639] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3/0003:045E:070F.0005/input/input50/event20 (input)
UDEV  [104175.923936] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3/0003:045E:070F.0005 (hid)
UDEV  [104175.926588] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3 (usb)
UDEV  [104176.400713] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.2 (usb)
UDEV  [104176.403811] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.2 (usb)
UDEV  [104177.019155] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0 (usb)
UDEV  [104177.022013] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/sound/card1 (sound)
UDEV  [104177.025771] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/sound/card1/pcmC1D0p (sound)
KERNEL[104177.027043] change   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/sound/card1 (sound)
UDEV  [104177.027338] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/sound/card1/pcmC1D0c (sound)
UDEV  [104177.035931] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/sound/card1/controlC1 (sound)
UDEV  [104177.037701] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0 (usb)
UDEV  [104177.046358] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1 (usb)
UDEV  [104177.048862] change   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/sound/card1 (sound)
KERNEL[104183.783857] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/sound/card1/controlC1 (sound)
KERNEL[104183.783922] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/sound/card1/pcmC1D0p (sound)
KERNEL[104183.784001] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/sound/card1/pcmC1D0c (sound)
KERNEL[104183.784060] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/sound/card1 (sound)
KERNEL[104183.784099] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0 (usb)
KERNEL[104183.784137] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0 (usb)
KERNEL[104183.784195] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.1 (usb)
KERNEL[104183.784229] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.1 (usb)
KERNEL[104183.784261] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.2 (usb)
KERNEL[104183.784289] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.2 (usb)
UDEV  [104183.790513] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/sound/card1/controlC1 (sound)
UDEV  [104183.790590] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/sound/card1/pcmC1D0p (sound)
UDEV  [104183.790646] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/sound/card1/pcmC1D0c (sound)
UDEV  [104183.791098] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.1 (usb)
UDEV  [104183.791889] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.2 (usb)
UDEV  [104183.792831] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/sound/card1 (sound)
UDEV  [104183.793539] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.1 (usb)
UDEV  [104183.794001] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.2 (usb)
UDEV  [104183.795551] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0 (usb)
UDEV  [104183.797640] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0 (usb)
KERNEL[104183.925822] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3/0003:045E:070F.0005/input/input50/event20 (input)
UDEV  [104183.928527] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3/0003:045E:070F.0005/input/input50/event20 (input)
KERNEL[104183.939233] offline  /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3 (usb)
KERNEL[104183.949060] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb4/4-0:1.0 (usb)
KERNEL[104183.949107] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb4/4-0:1.0 (usb)
KERNEL[104183.949252] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb4 (usb)
KERNEL[104183.949314] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb4 (usb)
UDEV  [104183.951298] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb4/4-0:1.0 (usb)
UDEV  [104183.953637] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb4/4-0:1.0 (usb)
KERNEL[104183.955095] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3/0003:045E:070F.0005/input/input50 (input)
KERNEL[104183.955365] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3/0003:045E:070F.0005/hidraw/hidraw1 (hidraw)
KERNEL[104183.955418] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3/0003:045E:070F.0005 (hid)
KERNEL[104183.955477] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3/0003:045E:070F.0005 (hid)
KERNEL[104183.955533] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3 (usb)
KERNEL[104183.955584] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3 (usb)
KERNEL[104183.956134] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1 (usb)
KERNEL[104183.956197] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1 (usb)
KERNEL[104183.956721] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-0:1.0 (usb)
KERNEL[104183.956781] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-0:1.0 (usb)
KERNEL[104183.956843] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3 (usb)
KERNEL[104183.956905] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3 (usb)
KERNEL[104183.957411] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0 (pci)
KERNEL[104183.957452] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/wakeup/wakeup51 (wakeup)
KERNEL[104183.957485] remove   /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/device:28/device:29/wakeup/wakeup50 (wakeup)
KERNEL[104183.957531] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0 (pci)
UDEV  [104183.957740] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb4 (usb)
UDEV  [104183.959765] offline  /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3 (usb)
UDEV  [104183.960290] remove   /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/device:28/device:29/wakeup/wakeup50 (wakeup)
UDEV  [104183.962720] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3/0003:045E:070F.0005/hidraw/hidraw1 (hidraw)
UDEV  [104183.962777] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-0:1.0 (usb)
UDEV  [104183.962845] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3/0003:045E:070F.0005/input/input50 (input)
UDEV  [104183.964818] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-0:1.0 (usb)
UDEV  [104183.965206] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3/0003:045E:070F.0005 (hid)
UDEV  [104183.966622] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3/0003:045E:070F.0005 (hid)
UDEV  [104183.967278] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb4 (usb)
UDEV  [104183.968433] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3 (usb)
UDEV  [104183.970061] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.3 (usb)
UDEV  [104183.971959] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1 (usb)
UDEV  [104183.978037] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1 (usb)
UDEV  [104183.979711] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3 (usb)
UDEV  [104183.986295] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3 (usb)
UDEV  [104183.989041] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0 (pci)
UDEV  [104183.990071] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/wakeup/wakeup51 (wakeup)
UDEV  [104183.991379] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0 (pci)
KERNEL[104184.133749] change   /devices/platform/USBC000:00/typec/port0 (typec)
KERNEL[104184.133782] remove   /devices/platform/USBC000:00/typec/port0/port0-partner (typec)
UDEV  [104184.134850] change   /devices/platform/USBC000:00/typec/port0 (typec)
UDEV  [104184.135565] remove   /devices/platform/USBC000:00/typec/port0/port0-partner (typec)
KERNEL[104189.004303] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:04.0/0000:04:04.0:pcie210 (pci_express)
KERNEL[104189.004492] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:04.0 (pci)
KERNEL[104189.004533] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:04:02.0:pcie210 (pci_express)
KERNEL[104189.004686] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0 (pci)
KERNEL[104189.004736] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:01.0/0000:04:01.0:pcie210 (pci_express)
KERNEL[104189.004883] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:01.0 (pci)
KERNEL[104189.004929] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:00.0/0000:04:00.0:pcie210 (pci_express)
KERNEL[104189.005080] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:00.0 (pci)
KERNEL[104189.005207] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0 (pci)
KERNEL[104189.005236] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:00.0/pci_bus/0000:05 (pci_bus)
KERNEL[104189.005370] remove   /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/device:26/wakeup/wakeup47 (wakeup)
KERNEL[104189.005477] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:00.0 (pci)
KERNEL[104189.005512] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:01.0/pci_bus/0000:06 (pci_bus)
KERNEL[104189.005608] remove   /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/device:27/wakeup/wakeup48 (wakeup)
KERNEL[104189.005721] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:01.0 (pci)
KERNEL[104189.005766] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/pci_bus/0000:39 (pci_bus)
KERNEL[104189.006251] remove   /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/device:28/wakeup/wakeup49 (wakeup)
KERNEL[104189.006318] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0 (pci)
KERNEL[104189.006356] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:04.0/pci_bus/0000:3a (pci_bus)
KERNEL[104189.006404] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:04.0 (pci)
KERNEL[104189.006442] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/pci_bus/0000:04 (pci_bus)
KERNEL[104189.006464] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/wakeup/wakeup46 (wakeup)
KERNEL[104189.006486] remove   /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/wakeup/wakeup45 (wakeup)
KERNEL[104189.006518] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0 (pci)
UDEV  [104189.007130] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:04.0/0000:04:04.0:pcie210 (pci_express)
UDEV  [104189.007707] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:04:02.0:pcie210 (pci_express)
UDEV  [104189.008495] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:01.0/0000:04:01.0:pcie210 (pci_express)
UDEV  [104189.009135] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:04.0 (pci)
UDEV  [104189.010189] remove   /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/device:26/wakeup/wakeup47 (wakeup)
UDEV  [104189.010344] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:00.0/0000:04:00.0:pcie210 (pci_express)
UDEV  [104189.010807] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0 (pci)
UDEV  [104189.010842] remove   /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/device:27/wakeup/wakeup48 (wakeup)
UDEV  [104189.011426] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:01.0 (pci)
UDEV  [104189.011577] remove   /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/device:28/wakeup/wakeup49 (wakeup)
UDEV  [104189.011926] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:00.0 (pci)
UDEV  [104189.012481] remove   /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/wakeup/wakeup45 (wakeup)
UDEV  [104189.012904] unbind   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0 (pci)
UDEV  [104189.013695] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:00.0/pci_bus/0000:05 (pci_bus)
UDEV  [104189.013732] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/wakeup/wakeup46 (wakeup)
UDEV  [104189.013869] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/pci_bus/0000:04 (pci_bus)
UDEV  [104189.013956] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:04.0/pci_bus/0000:3a (pci_bus)
UDEV  [104189.013988] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:01.0/pci_bus/0000:06 (pci_bus)
UDEV  [104189.014014] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/pci_bus/0000:39 (pci_bus)
UDEV  [104189.014589] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:00.0 (pci)
UDEV  [104189.014670] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0 (pci)
UDEV  [104189.014751] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:04.0 (pci)
UDEV  [104189.014802] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:01.0 (pci)
UDEV  [104189.015274] remove   /devices/pci0000:00/0000:00:1c.4/0000:03:00.0 (pci)

@rickysarraf
Copy link
Owner

And this one, an add event, for a USB mouse


KERNEL[104799.554493] change   /devices/platform/USBC000:00/typec/port0 (typec)
KERNEL[104799.554589] add      /devices/platform/USBC000:00/typec/port0/port0-partner (typec)
UDEV  [104799.559079] change   /devices/platform/USBC000:00/typec/port0 (typec)
UDEV  [104799.561896] add      /devices/platform/USBC000:00/typec/port0/port0-partner (typec)
KERNEL[104800.342694] add      /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/wakeup/wakeup45 (wakeup)
KERNEL[104800.342775] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/wakeup/wakeup46 (wakeup)
KERNEL[104800.345217] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0 (pci)
KERNEL[104800.345355] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/pci_bus/0000:04 (pci_bus)
KERNEL[104800.468079] add      /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/device:26/wakeup/wakeup47 (wakeup)
KERNEL[104800.468326] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:00.0 (pci)
KERNEL[104800.468814] add      /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/device:27/wakeup/wakeup48 (wakeup)
KERNEL[104800.469083] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:01.0 (pci)
UDEV  [104800.469153] add      /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/wakeup/wakeup45 (wakeup)
KERNEL[104800.474378] add      /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/device:28/wakeup/wakeup49 (wakeup)
UDEV  [104800.474429] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/wakeup/wakeup46 (wakeup)
KERNEL[104800.474474] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0 (pci)
KERNEL[104800.474529] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:04.0 (pci)
KERNEL[104800.474562] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:00.0/pci_bus/0000:05 (pci_bus)
KERNEL[104800.474593] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:01.0/pci_bus/0000:06 (pci_bus)
KERNEL[104800.474622] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/pci_bus/0000:39 (pci_bus)
KERNEL[104800.474652] add      /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/device:28/device:29/wakeup/wakeup50 (wakeup)
KERNEL[104800.474706] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0 (pci)
KERNEL[104800.474740] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:04.0/pci_bus/0000:3a (pci_bus)
KERNEL[104800.474791] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0 (pci)
KERNEL[104800.474823] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:00.0/0000:04:00.0:pcie210 (pci_express)
KERNEL[104800.474872] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:00.0 (pci)
UDEV  [104800.474920] add      /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/device:26/wakeup/wakeup47 (wakeup)
KERNEL[104800.474978] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:01.0/0000:04:01.0:pcie210 (pci_express)
KERNEL[104800.475048] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:01.0 (pci)
KERNEL[104800.475108] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:04:02.0:pcie210 (pci_express)
KERNEL[104800.475174] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0 (pci)
KERNEL[104800.475220] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:04.0/0000:04:04.0:pcie210 (pci_express)
KERNEL[104800.475285] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:04.0 (pci)
KERNEL[104800.477518] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3 (usb)
KERNEL[104800.477594] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-0:1.0 (usb)
UDEV  [104800.478161] add      /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/device:27/wakeup/wakeup48 (wakeup)
KERNEL[104800.478228] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-0:1.0 (usb)
KERNEL[104800.478290] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3 (usb)
KERNEL[104800.478335] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/wakeup/wakeup51 (wakeup)
KERNEL[104800.482494] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb4 (usb)
UDEV  [104800.482623] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0 (pci)
KERNEL[104800.482701] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb4/4-0:1.0 (usb)
UDEV  [104800.482765] add      /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/device:28/wakeup/wakeup49 (wakeup)
KERNEL[104800.482826] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb4/4-0:1.0 (usb)
KERNEL[104800.482892] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb4 (usb)
KERNEL[104800.482961] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0 (pci)
UDEV  [104800.483566] add      /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:24/device:25/device:28/device:29/wakeup/wakeup50 (wakeup)
UDEV  [104800.485610] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/pci_bus/0000:04 (pci_bus)
UDEV  [104800.486287] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0 (pci)
UDEV  [104800.486385] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:00.0 (pci)
UDEV  [104800.487193] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:01.0 (pci)
UDEV  [104800.488629] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:00.0/pci_bus/0000:05 (pci_bus)
UDEV  [104800.488917] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/pci_bus/0000:39 (pci_bus)
UDEV  [104800.489285] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:01.0/pci_bus/0000:06 (pci_bus)
UDEV  [104800.491026] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0 (pci)
UDEV  [104800.491312] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:04.0 (pci)
UDEV  [104800.493767] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:04.0/pci_bus/0000:3a (pci_bus)
UDEV  [104800.496154] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0 (pci)
UDEV  [104800.497331] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:00.0/0000:04:00.0:pcie210 (pci_express)
UDEV  [104800.497630] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:04:02.0:pcie210 (pci_express)
UDEV  [104800.498511] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:04.0/0000:04:04.0:pcie210 (pci_express)
UDEV  [104800.498553] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:00.0 (pci)
UDEV  [104800.498821] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:01.0/0000:04:01.0:pcie210 (pci_express)
UDEV  [104800.499812] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:04.0 (pci)
UDEV  [104800.500261] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:01.0 (pci)
UDEV  [104800.501031] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0 (pci)
UDEV  [104800.502648] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/wakeup/wakeup51 (wakeup)
KERNEL[104800.962586] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1 (usb)
KERNEL[104800.963317] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0 (usb)
KERNEL[104800.964685] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/0003:093A:2510.0007 (hid)
KERNEL[104800.964850] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/0003:093A:2510.0007/input/input52 (input)
KERNEL[104800.964874] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/0003:093A:2510.0007/input/input52/mouse2 (input)
KERNEL[104800.964888] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/0003:093A:2510.0007/input/input52/event20 (input)
KERNEL[104800.964999] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/0003:093A:2510.0007/hidraw/hidraw1 (hidraw)
KERNEL[104800.965027] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/0003:093A:2510.0007 (hid)
KERNEL[104800.965051] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0 (usb)
KERNEL[104800.965077] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1 (usb)
UDEV  [104801.091050] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3 (usb)
UDEV  [104801.132145] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-0:1.0 (usb)
UDEV  [104801.134617] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-0:1.0 (usb)
UDEV  [104801.138104] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3 (usb)
UDEV  [104801.611960] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb4 (usb)
UDEV  [104802.146848] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb4/4-0:1.0 (usb)
UDEV  [104802.148996] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb4/4-0:1.0 (usb)
UDEV  [104802.152206] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb4 (usb)
UDEV  [104802.153722] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0 (pci)
UDEV  [104802.721147] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1 (usb)
UDEV  [104803.247785] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0 (usb)
UDEV  [104803.249864] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/0003:093A:2510.0007 (hid)
UDEV  [104803.255082] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/0003:093A:2510.0007/input/input52 (input)
UDEV  [104803.258228] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/0003:093A:2510.0007/hidraw/hidraw1 (hidraw)
UDEV  [104803.259266] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/0003:093A:2510.0007/input/input52/mouse2 (input)
UDEV  [104803.334141] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/0003:093A:2510.0007/input/input52/event20 (input)
UDEV  [104803.337256] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0/0003:093A:2510.0007 (hid)
UDEV  [104803.340436] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1/3-1:1.0 (usb)
UDEV  [104803.349932] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1 (usb)

@rickysarraf
Copy link
Owner

I don't see laptop-mode-tools causing problems in device discovery and processing. In initial integration with systemd, we did have some issues on lmt's runtime, which if high could lead udev to kill the process. But that's a completely different behavior in between udev <=> lmt and has long been fixed.

By the time, lmt is processing your device (which where it just tries to switch on power saving knobs, if available), your device is supposed to be available and ready.

I can't say for sure if the device's settling is hindered by lmt. It may just be giving you an impression given its runtime cost but I can't see any reason how it'd hinder

@rickysarraf
Copy link
Owner

What I suspect is that the add event for the USB device itself delays processing of the USB interfaces/subdevices, such as the serial device. Here's what usbadm monitor gives when plugging in with the default rules:

Yes. THat could be a possibility. Depending on how many USB type sub-devices are generated. And how many add events they generate, this could cause many invocations.

In lmt, to ensure that we don't lose events, while at the same time to ensure that we don't get DoS-ed with too many events, we have a 2 lock system.

The first event processing acquires lock-1, any follow-up event gets to acquire lock-2. All other events during that time span are let go off. When first event (that acquired lock-1) is complete, the second event (that acquired lock-2) is run. The average runtime that I calculated for an lmt invocation was around 4 seconds. But that was very limited to my setup.

And the event that acquired lock-2 will loop for 10 seconds to see that event one is complete and that is can now acquire lock-1.

This is by design, to ensure we don't lose events to process.

@matthijskooijman
Copy link

I did a plug/unplug of my USB Type-C headphone and I don't get similar results.

It seems you don't have this cgroup stuff, but that might just be related to what lmt does exactly on my system. I do not think the cgroup stuff actually causes the problems, it's just an indication that lmt is doing something, the real problem is the delay it causes.

In your last post about the mouse, I see a similar delay (over 2s) between the first time the 3-1 usb device is mentioned (the kernel add event) and the last time it is mentioned (the udev add complete event I think?):

KERNEL[104800.962586] add      /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1 (usb)
...
UDEV  [104803.349932] bind     /devices/pci0000:00/0000:00:1c.4/0000:03:00.0/0000:04:02.0/0000:39:00.0/usb3/3-1 (usb)

By the time, lmt is processing your device (which where it just tries to switch on power saving knobs, if available), your device is supposed to be available and ready.

It looks like the kernel has made the device available, but udev has not finished processing, so the permissions on the /dev/ file are not set yet. I would think that something similar might happen to e.g. a USB mouse as well

Yes. THat could be a possibility. Depending on how many USB type sub-devices are generated. And how many add events they generate, this could cause many invocations.

Ah, I had not actually considered multiple invocations yet, I guess that could indeed be relevant as well. Doing a quick test indeed shows that it runs twice for my USB mouse, and four times for my serial device (probably because it has a few subdevices). If I modify the script to run only the first time, the delay I see in udevadm is about 1s, when it runs twice for the mouse its just under 2s, and for my serial device it was about 3s, so I suspect it's just running multiple times and the delay just stacks). I was thinking the locking might be influential (that the first invocation prevents the second invocation from returning quickly or something), but I don't think that is the case.

I did a quick test, where I let lmt-udev run only once for the first device, but added a big delay. E.g. at the top of lmt-udev, I added:

if [ -e /tmp/lmt ]; then
        exit 0;
fi

echo $$ >> /tmp/lmt
sleep 10

When then plugging in an USB mouse (taking care that /tmp/lmt does not exist), it takes 10 seconds for the mouse to become usable, so I think this shows that any longer runtime that lmt needs is problematic?

@rickysarraf
Copy link
Owner

When then plugging in an USB mouse (taking care that /tmp/lmt does not exist), it takes 10 seconds for the mouse to become usable, so I think this shows that any longer runtime that lmt needs is problematic?

Possibly, yes.
Now, after all this run through, I am not sure of the processing order of the rules by udev.

But yes, from what you are showing, if that is the case (runtime length of lmt), then this is an ugly trade-off.

@rickysarraf
Copy link
Owner

Yes. THat could be a possibility. Depending on how many USB type sub-devices are generated. And how many add events they generate, this could cause many invocations.

Ah, I had not actually considered multiple invocations yet, I guess that could indeed be relevant as well. Doing a quick test indeed shows that it runs twice for my USB mouse, and four times for my serial device (probably because it has a few subdevices). If I modify the script to run only the first time, the delay I see in udevadm is about 1s, when it runs twice for the mouse its just under 2s, and for my serial device it was about 3s, so I suspect it's just running multiple times and the delay just stacks). I was thinking the locking might be influential (that the first invocation prevents the second invocation from returning quickly or something), but I don't think that is the case.

In a short span of time, example a plug/unplug of a device, it usually should settle with 2 invocations. All other event invocations will be ignored. The reason I explained in one of the previous comments.

If you have more than 2 instances of the run, I'd be interested in the time span. Because the kernel can generate many many events on device plugs/unplugs, but because we do a global power savings settings re-apply, we only try to honor 2 instances (in the short time span)

lmt-udev force will re-apply settings on all devices. Not any specific one. The newly plugged device gets covered in it.

PS: I hope I have been able to explain in words. /me realizes my English still needs a lot of improvement :-)

@matthijskooijman
Copy link

Now, after all this run through, I am not sure of the processing order of the rules by udev.

Yeah, this might be something to further investigate.

If you have more than 2 instances of the run, I'd be interested in the time span.

I just counted the invocations of lmt-udev, I did not check if anything actually happens based on that invocation. In fact, your assertion that only 2 runs execute would make sense to explain that the delay is on 3s (not 4s) with 4 invocations.

PS: I hope I have been able to explain in words. /me realizes my English still needs a lot of improvement :-)

I've had no problem following your English, so don't worry :-)

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Projects
None yet
Development

No branches or pull requests

4 participants