Bug#1028417: udev deletes symlink to a device according to a rule and then deletes it as "no longer belonging to this device"

Tue Jan 10 20:00:37 GMT 2023

Package: udev
Version: 252.4-1
Severity: important
I have encountered an issue with `udev` after upgrade from the latest Ubuntu
Impish (the issue was not experienced on it). A symlink defined in the rules
for device is not visible. The investigation has shown that it was created and
then deleted by `udev`.
When the rule of the form `ATTR{idVendor}=="VID", ATTR{idProduct}=="PID",
SYMLINK+="symlink_name-%n", MODE="0666"` is used, the following records apper
in the log
```
Successfully created symlink '/dev/symlink_name-2' to '/dev/bus/usb/XXX/YYY'
...
Removing/updating old device symlink '/dev/symlink_name-2', which is no longer
belonging to this device.
No reference left for '/dev/symlink_name-2', removing
```
Notice the number 2. Why is it using 2, where is 1, there is no ` /dev/symlink_name-1` ? Only one physical device of the same type is connected to the PC.
The problem has encountered with a USB device emulating a serial port (I haven't investigated which chip is used internally), but since you likely have no such device, I have rewritten the rule to be used
against any widespread trash-tier flash drive with the identifier abcd:1234 most of you surely have access to.
The issue reproduces on both udev 252.4-1 from testing/unstable and on the one
from stable 247.3-7+deb11u1

In order to test if the issue is caused by kernel, I have tried udev version 252.4-1 on the 9 kernel images (and the corresponding 6 initrds).
From Debian:
```
linux-image-5.10.0-20-amd64/stable,now 5.10.158-2 amd64 [installed]
linux-image-5.18.0-0.deb11.4-amd64/bullseye-backports,now 5.18.16-1~bpo11+1 amd64 [installed]
linux-image-5.19.0-0.deb11.2-amd64/bullseye-backports,now 5.19.11-1~bpo11+1 amd64 [installed]
linux-image-6.0.0-6-amd64/testing,now 6.0.12-1 amd64 [installed]
```

from Ubuntu:
```
linux-image-5.13.0-35-generic/now 5.13.0-35.40~20.04.1 amd64 [installed,local]
linux-image-5.14.0-1055-oem/now 5.14.0-1055.62 amd64 [installed,local]
linux-image-5.15.0-57-generic/now 5.15.0-57.63 amd64 [installed,local]
linux-image-5.17.0-1003-oem/now 5.17.0-1003.3 amd64 [installed,local]
linux-image-5.19.0-21-generic/now 5.19.0-21.21 amd64 [installed,local]
```

The issue reproduces on all of them (including the ones there was no issue on when I used to be using Ubuntu).
So it is likely that the issue is in udev or in some of its rules and
not in the hardware or kernel.
1. Here is the udev rule (abcd:1234 are the real IDs exposed by some flash drives)
```
SUBSYSTEM!="usb_device", ACTION!="add", GOTO="test_rules_end"
ATTR{idVendor}=="abcd", ATTR{idProduct}=="1234", SYMLINK+="test_device-%n", MODE="0666"
LABEL="test_rules_end"
```
2. here are the commands used to reproduce
```
udevadm control --log-priority=debug
journalctl -f | grep systemd-udevd
```
After typing them the flash drive has been attached, then ctrl+c was hit. Here
is the relevant piece of the log (sensitive info was replaces with `censored`,
irrelevant info, like date and time (except seconds), removed).
```
20: 1-1.2: Device is queued (SEQNUM=2845, ACTION=add)
20: 1-1.2: Device ready for processing (SEQNUM=2845, ACTION=add)
20: Successfully forked off 'n/a' as PID 3710.
20: 1-1.2: Worker [3710] is forked for processing SEQNUM=2845.
20: 1-1.2: Device is queued (SEQNUM=2846, ACTION=bind)
20: 1-1.2: SEQNUM=2846 blocked by SEQNUM=2845
20: 1-1.2: Processing device (SEQNUM=2845, ACTION=add)
20: 1-c.e:n.s: Device is queued (SEQNUM=2847, ACTION=add)
20: 1-c.e:n.s: SEQNUM=2847 blocked by SEQNUM=2845
20: 1-1.2: Removing watch handle -1.
20: scsi_tmf_2: Device is queued (SEQNUM=2848, ACTION=add)
20: scsi_tmf_2: Device ready for processing (SEQNUM=2848, ACTION=add)
20: 1-1.2: /usr/lib/udev/rules.d/10-test.rules:13 MODE 0666
20: 1-1.2: /usr/lib/udev/rules.d/10-test.rules:13 LINK 'test_device'
20: Successfully forked off 'n/a' as PID 3711.
20: scsi_tmf_2: Worker [3711] is forked for processing SEQNUM=2848.
20: 1-1.2: /usr/lib/udev/rules.d/50-udev-default.rules:17 Importing properties from results of builtin command 'usb_id'
20: host2: Device is queued (SEQNUM=2849, ACTION=add)
20: host2: SEQNUM=2849 blocked by SEQNUM=2845
20: host2: Device is queued (SEQNUM=2850, ACTION=add)
20: host2: SEQNUM=2850 blocked by SEQNUM=2845
20: 1-1.2: /usr/lib/udev/rules.d/50-udev-default.rules:17 Importing properties from results of builtin command 'hwdb --subsystem=usb'
20: 1-1.2: hwdb modalias key: "usb:vABCDp1234:UDisk           "
20: 1-c.e:n.s: Device is queued (SEQNUM=2851, ACTION=bind)
20: scsi_tmf_2: Processing device (SEQNUM=2848, ACTION=add)
20: 1-1.2: /usr/lib/udev/rules.d/50-udev-default.rules:55 MODE 0664
20: 1-c.e:n.s: SEQNUM=2851 blocked by SEQNUM=2845
20: 1-1.2: /usr/lib/udev/rules.d/60-drm.rules:3 Importing properties from results of builtin command 'path_id'
20: 1-1.2: /usr/lib/udev/rules.d/60-virtualbox.rules:2 RUN 'VBoxCreateUSBNode.sh $major $minor $attr{bDeviceClass} vboxusers'
20: 1-1.2: /usr/lib/udev/rules.d/90-libiio0.rules:1 Running PROGRAM '/bin/sh -c '/usr/bin/iio_info -S usb=abcd:1234 | grep abcd:1234''
20: 1-1.2: Starting '/bin/sh -c '/usr/bin/iio_info -S usb=abcd:1234 | grep abcd:1234''
20: scsi_tmf_2: Device processed (SEQNUM=2848, ACTION=add)
20: scsi_tmf_2: sd-device-monitor(worker): Passed 180 byte to netlink monitor.
20: Successfully forked off '(spawn)' as PID 3712.
20: 1-1.2: '/bin/sh -c '/usr/bin/iio_info -S usb=abcd:1234 | grep abcd:1234''(err) '/bin/sh: 1: /usr/bin/iio_info: not found'
20: 1-1.2: Process '/bin/sh -c '/usr/bin/iio_info -S usb=abcd:1234 | grep abcd:1234'' failed with exit code 1.
20: 1-1.2: /usr/lib/udev/rules.d/90-libiio0.rules:1 Command "/bin/sh -c '/usr/bin/iio_info -S usb=abcd:1234 | grep abcd:1234'" returned 1 (error)
20: 1-1.2: Setting permissions /dev/bus/usb/001/008, uid=0, gid=0, mode=0664
20: 1-1.2: Successfully created symlink '/dev/test_device-2' to '/dev/bus/usb/001/008'
20: 1-1.2: Successfully created symlink '/dev/char/189:7' to '/dev/bus/usb/001/008'
20: 1-1.2: sd-device: Created db file '/run/udev/data/c189:7' for '/devices/pci0000:00/0000:00:12.0/usb1/1-1/1-1.2'
20: 1-1.2: Running command "VBoxCreateUSBNode.sh 189 7 00 vboxusers"
20: 1-1.2: Starting 'VBoxCreateUSBNode.sh 189 7 00 vboxusers'
20: Successfully forked off '(spawn)' as PID 3715.
20: 1-1.2: Process 'VBoxCreateUSBNode.sh 189 7 00 vboxusers' succeeded.
20: 1-1.2: Device processed (SEQNUM=2845, ACTION=add)
20: 1-1.2: sd-device-monitor(worker): Passed 1031 byte to netlink monitor.
20: 1-1.2: Device ready for processing (SEQNUM=2846, ACTION=bind)
20: 1-1.2: sd-device-monitor(manager): Passed 317 byte to netlink monitor.
20: 1-c.e:n.s: SEQNUM=2847 blocked by SEQNUM=2846
20: host2: SEQNUM=2849 blocked by SEQNUM=2846
20: host2: SEQNUM=2850 blocked by SEQNUM=2846
20: 1-c.e:n.s: SEQNUM=2851 blocked by SEQNUM=2846
20: 1-1.2: Processing device (SEQNUM=2846, ACTION=bind)
20: 1-1.2: Removing watch handle -1.
20: 1-1.2: /usr/lib/udev/rules.d/50-udev-default.rules:17 Importing properties from results of builtin command 'usb_id'
20: 1-1.2: /usr/lib/udev/rules.d/50-udev-default.rules:17 Importing properties from results of builtin command 'hwdb --subsystem=usb'
20: 1-1.2: hwdb modalias key: "usb:vABCDp1234:UDisk           "
20: 1-1.2: /usr/lib/udev/rules.d/60-drm.rules:3 Importing properties from results of builtin command 'path_id'
20: 1-1.2: /usr/lib/udev/rules.d/90-libiio0.rules:1 Running PROGRAM '/bin/sh -c '/usr/bin/iio_info -S usb=abcd:1234 | grep abcd:1234''
20: 1-1.2: Starting '/bin/sh -c '/usr/bin/iio_info -S usb=abcd:1234 | grep abcd:1234''
20: Successfully forked off '(spawn)' as PID 3727.
20: 1-1.2: '/bin/sh -c '/usr/bin/iio_info -S usb=abcd:1234 | grep abcd:1234''(err) '/bin/sh: 1: /usr/bin/iio_info: not found'
20: 1-1.2: Process '/bin/sh -c '/usr/bin/iio_info -S usb=abcd:1234 | grep abcd:1234'' failed with exit code 1.
20: 1-1.2: /usr/lib/udev/rules.d/90-libiio0.rules:1 Command "/bin/sh -c '/usr/bin/iio_info -S usb=abcd:1234 | grep abcd:1234'" returned 1 (error)
20: 1-1.2: Removing/updating old device symlink '/dev/test_device-2', which is no longer belonging to this device.
20: 1-1.2: No reference left for '/dev/test_device-2', removing
20: 1-1.2: Successfully created symlink '/dev/char/189:7' to '/dev/bus/usb/001/008'
20: 1-1.2: sd-device: Created db file '/run/udev/data/c189:7' for '/devices/pci0000:00/0000:00:12.0/usb1/1-1/1-1.2'
20: 1-1.2: Device processed (SEQNUM=2846, ACTION=bind)
20: 1-1.2: sd-device-monitor(worker): Passed 1006 byte to netlink monitor.
20: 1-c.e:n.s: Device ready for processing (SEQNUM=2847, ACTION=add)
20: 1-c.e:n.s: sd-device-monitor(manager): Passed 325 byte to netlink monitor.
20: host2: SEQNUM=2849 blocked by SEQNUM=2847
20: host2: SEQNUM=2850 blocked by SEQNUM=2847
20: 1-c.e:n.s: SEQNUM=2851 blocked by SEQNUM=2847
20: 1-c.e:n.s: Processing device (SEQNUM=2847, ACTION=add)
20: 1-c.e:n.s: /usr/lib/udev/rules.d/50-udev-default.rules:18 Importing properties from results of builtin command 'hwdb --subsystem=usb'
20: 1-c.e:n.s: hwdb modalias key: "usb:censored"
20: 1-c.e:n.s: /usr/lib/udev/rules.d/60-drm.rules:3 Importing properties from results of builtin command 'path_id'
20: 1-c.e:n.s: /usr/lib/udev/rules.d/60-libgphoto2-6.rules:9 Importing properties from results of builtin command 'usb_id'
20: 1-c.e:n.s: Failed to access usb_interface: No such file or directory
20: 1-c.e:n.s: /usr/lib/udev/rules.d/60-libgphoto2-6.rules:9 Failed to run builtin 'usb_id': No such file or directory
20: 1-c.e:n.s: /usr/lib/udev/rules.d/80-drivers.rules:5 RUN 'kmod load'
20: 1-c.e:n.s: /usr/lib/udev/rules.d/90-libiio0.rules:1 Running PROGRAM '/bin/sh -c '/usr/bin/iio_info -S usb=: | grep :''
20: 1-c.e:n.s: Starting '/bin/sh -c '/usr/bin/iio_info -S usb=: | grep :''
20: Successfully forked off '(spawn)' as PID 3733.
20: 1-c.e:n.s: '/bin/sh -c '/usr/bin/iio_info -S usb=: | grep :''(err) '/bin/sh: 1: /usr/bin/iio_info: not found'
20: 1-c.e:n.s: Process '/bin/sh -c '/usr/bin/iio_info -S usb=: | grep :'' failed with exit code 1.
20: 1-c.e:n.s: /usr/lib/udev/rules.d/90-libiio0.rules:1 Command "/bin/sh -c '/usr/bin/iio_info -S usb=: | grep :'" returned 1 (error)
20: 1-c.e:n.s: sd-device: Created db file '/run/udev/data/+usb:1-c.e:n.s' for '/devices/pci0000:00/0000:00:12.0/usb1/1-1/1-1.2/1-c.e:n.s'
20: 1-c.e:n.s: Running built-in command "kmod load"
20: Loading module: usb:censored
20: Module 'uas' is already loaded
20: Module 'usb_storage' is already loaded
20: 1-c.e:n.s: Device processed (SEQNUM=2847, ACTION=add)
20: 1-c.e:n.s: sd-device-monitor(worker): Passed 500 byte to netlink monitor.
20: host2: Device ready for processing (SEQNUM=2849, ACTION=add)
20: host2: sd-device-monitor(manager): Passed 219 byte to netlink monitor.
20: host2: SEQNUM=2850 blocked by SEQNUM=2849
20: 1-c.e:n.s: SEQNUM=2851 blocked by SEQNUM=2849
20: host2: Processing device (SEQNUM=2849, ACTION=add)
20: host2: Device processed (SEQNUM=2849, ACTION=add)
20: host2: sd-device-monitor(worker): Passed 219 byte to netlink monitor.
20: host2: Device ready for processing (SEQNUM=2850, ACTION=add)
20: host2: sd-device-monitor(manager): Passed 222 byte to netlink monitor.
20: 1-c.e:n.s: SEQNUM=2851 blocked by SEQNUM=2850
20: host2: Processing device (SEQNUM=2850, ACTION=add)
20: host2: Device processed (SEQNUM=2850, ACTION=add)
20: host2: sd-device-monitor(worker): Passed 222 byte to netlink monitor.
20: 1-c.e:n.s: Device ready for processing (SEQNUM=2851, ACTION=bind)
20: 1-c.e:n.s: sd-device-monitor(manager): Passed 345 byte to netlink monitor.
20: 1-c.e:n.s: Processing device (SEQNUM=2851, ACTION=bind)
20: 1-c.e:n.s: /usr/lib/udev/rules.d/50-udev-default.rules:18 Importing properties from results of builtin command 'hwdb --subsystem=usb'
20: 1-c.e:n.s: hwdb modalias key: "usb:censored"
20: 1-c.e:n.s: /usr/lib/udev/rules.d/60-drm.rules:3 Importing properties from results of builtin command 'path_id'
20: 1-c.e:n.s: /usr/lib/udev/rules.d/60-libgphoto2-6.rules:9 Importing properties from results of builtin command 'usb_id'
20: 1-c.e:n.s: Failed to access usb_interface: No such file or directory
20: 1-c.e:n.s: /usr/lib/udev/rules.d/60-libgphoto2-6.rules:9 Failed to run builtin 'usb_id': No such file or directory
20: 1-c.e:n.s: /usr/lib/udev/rules.d/90-libiio0.rules:1 Running PROGRAM '/bin/sh -c '/usr/bin/iio_info -S usb=: | grep :''
20: 1-c.e:n.s: Starting '/bin/sh -c '/usr/bin/iio_info -S usb=: | grep :''
20: Successfully forked off '(spawn)' as PID 3737.
20: 1-c.e:n.s: '/bin/sh -c '/usr/bin/iio_info -S usb=: | grep :''(err) '/bin/sh: 1: /usr/bin/iio_info: not found'
20: 1-c.e:n.s: Process '/bin/sh -c '/usr/bin/iio_info -S usb=: | grep :'' failed with exit code 1.
20: 1-c.e:n.s: /usr/lib/udev/rules.d/90-libiio0.rules:1 Command "/bin/sh -c '/usr/bin/iio_info -S usb=: | grep :'" returned 1 (error)
20: 1-c.e:n.s: sd-device: Created db file '/run/udev/data/+usb:1-c.e:n.s' for '/devices/pci0000:00/0000:00:12.0/usb1/1-1/1-1.2/1-c.e:n.s'
20: 1-c.e:n.s: Device processed (SEQNUM=2851, ACTION=bind)
20: 1-c.e:n.s: sd-device-monitor(worker): Passed 501 byte to netlink monitor.
20: No events are queued, removing /run/udev/queue.
21: target2:0:0: Device is queued (SEQNUM=2852, ACTION=add)
21: target2:0:0: Device ready for processing (SEQNUM=2852, ACTION=add)
21: target2:0:0: sd-device-monitor(manager): Passed 233 byte to netlink monitor.
21: 2:0:0:0: Device is queued (SEQNUM=2853, ACTION=add)
21: target2:0:0: Processing device (SEQNUM=2852, ACTION=add)
21: 2:0:0:0: SEQNUM=2853 blocked by SEQNUM=2852
21: 2:0:0:0: Device is queued (SEQNUM=2854, ACTION=add)
21: 2:0:0:0: SEQNUM=2854 blocked by SEQNUM=2852
21: sg2: Device is queued (SEQNUM=2855, ACTION=add)
21: sg2: SEQNUM=2855 blocked by SEQNUM=2852
21: 2:0:0:0: Device is queued (SEQNUM=2856, ACTION=add)
21: 2:0:0:0: SEQNUM=2856 blocked by SEQNUM=2852
21: 2:0:0:0: Device is queued (SEQNUM=2857, ACTION=add)
21: 2:0:0:0: SEQNUM=2857 blocked by SEQNUM=2852
21: 8:16: Device is queued (SEQNUM=2858, ACTION=add)
21: 8:16: Device ready for processing (SEQNUM=2858, ACTION=add)
21: 8:16: sd-device-monitor(manager): Passed 162 byte to netlink monitor.
21: 8:16: Processing device (SEQNUM=2858, ACTION=add)
21: target2:0:0: Device processed (SEQNUM=2852, ACTION=add)
21: 8:16: Device processed (SEQNUM=2858, ACTION=add)
21: target2:0:0: sd-device-monitor(worker): Passed 233 byte to netlink monitor.
21: 8:16: sd-device-monitor(worker): Passed 162 byte to netlink monitor.
21: 2:0:0:0: Device ready for processing (SEQNUM=2853, ACTION=add)
21: 2:0:0:0: sd-device-monitor(manager): Passed 262 byte to netlink monitor.
21: 2:0:0:0: SEQNUM=2854 blocked by SEQNUM=2853
21: sg2: SEQNUM=2855 blocked by SEQNUM=2853
21: 2:0:0:0: SEQNUM=2856 blocked by SEQNUM=2853
21: 2:0:0:0: SEQNUM=2857 blocked by SEQNUM=2853
21: 2:0:0:0: Processing device (SEQNUM=2853, ACTION=add)
21: 2:0:0:0: /usr/lib/udev/rules.d/50-udev-default.rules:18 Importing properties from results of builtin command 'hwdb --subsystem=scsi'
21: 2:0:0:0: hwdb modalias key: "scsi:t-0x00"
21: 2:0:0:0: No entry found from hwdb.
21: 2:0:0:0: /usr/lib/udev/rules.d/50-udev-default.rules:18 Failed to run builtin 'hwdb --subsystem=scsi': No data available
21: path '[module/sg]' is '/sys/module/sg'
21: 2:0:0:0: /usr/lib/udev/rules.d/80-drivers.rules:5 RUN 'kmod load'
21: 2:0:0:0: Running built-in command "kmod load"
21: Loading module: scsi:t-0x00
21: Module 'sd_mod' is already loaded
21: 2:0:0:0: Device processed (SEQNUM=2853, ACTION=add)
21: 2:0:0:0: sd-device-monitor(worker): Passed 272 byte to netlink monitor.
21: 2:0:0:0: Device ready for processing (SEQNUM=2854, ACTION=add)
21: 2:0:0:0: sd-device-monitor(manager): Passed 248 byte to netlink monitor.
21: sg2: Device ready for processing (SEQNUM=2855, ACTION=add)
21: sg2: sd-device-monitor(manager): Passed 280 byte to netlink monitor.
21: 2:0:0:0: Processing device (SEQNUM=2854, ACTION=add)
21: 2:0:0:0: Device ready for processing (SEQNUM=2856, ACTION=add)
21: Successfully forked off 'n/a' as PID 3742.
21: 2:0:0:0: Worker [3742] is forked for processing SEQNUM=2856.
21: 2:0:0:0: Device ready for processing (SEQNUM=2857, ACTION=add)
21: Successfully forked off 'n/a' as PID 3743.
21: 2:0:0:0: Worker [3743] is forked for processing SEQNUM=2857.
21: sg2: Processing device (SEQNUM=2855, ACTION=add)
21: sg2: Removing watch handle -1.
21: sg2: /usr/lib/udev/rules.d/50-udev-default.rules:82 GROUP 6
21: sdb: Device is queued (SEQNUM=2859, ACTION=add)
21: sdb: Device ready for processing (SEQNUM=2859, ACTION=add)
21: Successfully forked off 'n/a' as PID 3744.
21: sdb: Worker [3744] is forked for processing SEQNUM=2859.
21: sdb1: Device is queued (SEQNUM=2860, ACTION=add)
21: sdb1: SEQNUM=2860 blocked by SEQNUM=2859
21: 2:0:0:0: Device is queued (SEQNUM=2861, ACTION=bind)
21: 2:0:0:0: SEQNUM=2861 blocked by SEQNUM=2854
21: 2:0:0:0: Processing device (SEQNUM=2856, ACTION=add)
21: sg2: Setting permissions /dev/sg2, uid=0, gid=6, mode=0660
21: sg2: Successfully created symlink '/dev/char/21:2' to '/dev/sg2'
21: sg2: sd-device: Created empty file '/run/udev/data/cen:s' for '/devices/pci0000:00/0000:00:12.0/usb1/1-1/1-1.2/1-c.e:n.s/host2/target2:0:0/2:0:0:0/scsi_generic/sg2'
21: sg2: Device processed (SEQNUM=2855, ACTION=add)
21: sg2: sd-device-monitor(worker): Passed 280 byte to netlink monitor.
21: 2:0:0:0: Processing device (SEQNUM=2857, ACTION=add)
21: 2:0:0:0: Removing watch handle -1.
21: 2:0:0:0: Device processed (SEQNUM=2854, ACTION=add)
21: 2:0:0:0: sd-device-monitor(worker): Passed 248 byte to netlink monitor.
21: 2:0:0:0: SEQNUM=2861 blocked by SEQNUM=2856
21: sdb: Processing device (SEQNUM=2859, ACTION=add)
21: sdb: Removing watch handle -1.
21: sdb: /usr/lib/udev/rules.d/50-udev-default.rules:74 GROUP 6
21: 2:0:0:0: Preserve permissions of /dev/bsg/2:0:0:0, uid=0, gid=0, mode=0600
21: 2:0:0:0: Successfully created symlink '/dev/char/250:2' to '/dev/bsg/2:0:0:0'
21: 2:0:0:0: sd-device: Created empty file '/run/udev/data/c250:2' for '/devices/pci0000:00/0000:00:12.0/usb1/1-1/1-1.2/1-c.e:n.s/host2/target2:0:0/2:0:0:0/bsg/2:0:0:0'
21: 2:0:0:0: Device processed (SEQNUM=2857, ACTION=add)
21: 2:0:0:0: sd-device-monitor(worker): Passed 275 byte to netlink monitor.
21: sdb: /usr/lib/udev/rules.d/60-persistent-storage.rules:63 Importing properties from results of builtin command 'usb_id'
21: 2:0:0:0: Device processed (SEQNUM=2856, ACTION=add)
21: 2:0:0:0: sd-device-monitor(worker): Passed 244 byte to netlink monitor.
21: 1-c.e:n.s: if_class:8 protocol:6
21: 2:0:0:0: SEQNUM=2861 blocked by SEQNUM=2859
21: sdb: /usr/lib/udev/rules.d/60-persistent-storage.rules:68 LINK 'disk/by-id/usb-General_UDisk-0:0'
21: sdb: /usr/lib/udev/rules.d/60-persistent-storage.rules:74 LINK 'disk/by-id/usb-General_UDisk-0:0'
21: sdb: /usr/lib/udev/rules.d/60-persistent-storage.rules:96 Importing properties from results of builtin command 'path_id'
21: sdb: /usr/lib/udev/rules.d/60-persistent-storage.rules:99 LINK 'disk/by-path/pci-0000:00:12.0-usb-0:c.e:n.s-scsi-0:0:0:0'
21: sdb: /usr/lib/udev/rules.d/60-persistent-storage.rules:120 Importing properties from results of builtin command 'blkid'
21: sdb: Probe /dev/sdb with raid and offset=0
21: sdb: /usr/lib/udev/rules.d/60-persistent-storage.rules:139 LINK 'disk/by-diskseq/11'
21: sdb: /usr/lib/udev/rules.d/69-bcache.rules:16 Importing properties from results of 'probe-bcache -o udev /dev/sdb'
21: sdb: Starting 'probe-bcache -o udev /dev/sdb'
21: Successfully forked off '(spawn)' as PID 3749.
21: sdb: Process 'probe-bcache -o udev /dev/sdb' succeeded.
21: sdb: /usr/lib/udev/rules.d/85-hdparm.rules:1 RUN '/lib/udev/hdparm'
21: sdb: Setting permissions /dev/sdb, uid=0, gid=6, mode=0660
21: sdb: Successfully created symlink '/dev/disk/by-path/pci-0000:00:12.0-usb-0:c.e:n.s-scsi-0:0:0:0' to '/dev/sdb'
21: sdb: Successfully created symlink '/dev/disk/by-diskseq/11' to '/dev/sdb'
21: sdb: Successfully created symlink '/dev/disk/by-id/usb-General_UDisk-0:0' to '/dev/sdb'
21: sdb: Successfully created symlink '/dev/block/8:16' to '/dev/sdb'
21: sdb: sd-device: Created db file '/run/udev/data/b8:16' for '/devices/pci0000:00/0000:00:12.0/usb1/1-1/1-1.2/1-c.e:n.s/host2/target2:0:0/2:0:0:0/block/sdb'
21: sdb: Running command "/lib/udev/hdparm"
21: sdb: Starting '/lib/udev/hdparm'
21: Successfully forked off '(spawn)' as PID 3750.
21: sdb: Process '/lib/udev/hdparm' succeeded.
21: sdb: Adding watch on '/dev/sdb'
21: sdb: Device processed (SEQNUM=2859, ACTION=add)
21: sdb: sd-device-monitor(worker): Passed 1238 byte to netlink monitor.
21: sdb1: Device ready for processing (SEQNUM=2860, ACTION=add)
21: sdb1: sd-device-monitor(manager): Passed 339 byte to netlink monitor.
21: 2:0:0:0: SEQNUM=2861 blocked by SEQNUM=2860
21: sdb1: Processing device (SEQNUM=2860, ACTION=add)
21: sdb1: Removing watch handle -1.
21: sdb1: /usr/lib/udev/rules.d/50-udev-default.rules:74 GROUP 6
21: sdb1: /usr/lib/udev/rules.d/60-persistent-storage.rules:69 LINK 'disk/by-id/usb-General_UDisk-0:0-part1'
21: sdb1: /usr/lib/udev/rules.d/60-persistent-storage.rules:75 LINK 'disk/by-id/usb-General_UDisk-0:0-part1'
21: sdb1: /usr/lib/udev/rules.d/60-persistent-storage.rules:100 LINK 'disk/by-path/pci-0000:00:12.0-usb-c:e.n:s.o-scsi-0:0:0:0-part1'
21: sdb1: /usr/lib/udev/rules.d/60-persistent-storage.rules:120 Importing properties from results of builtin command 'blkid'
21: sdb1: Probe /dev/sdb1 with raid and offset=0
21: sdb1: /usr/lib/udev/rules.d/60-persistent-storage.rules:125 LINK 'disk/by-uuid/CENS-ORED'
21: sdb1: /usr/lib/udev/rules.d/60-persistent-storage.rules:126 LINK 'disk/by-label/censored'
21: sdb1: /usr/lib/udev/rules.d/60-persistent-storage.rules:133 LINK 'disk/by-partuuid/censored-cens-ored-cens-oredcensoredc'
21: sdb1: /usr/lib/udev/rules.d/60-persistent-storage.rules:134 LINK 'disk/by-partlabel/Microsoft\x20basic\x20data'
21: sdb1: Setting permissions /dev/sdb1, uid=0, gid=6, mode=0660
21: sdb1: Successfully created symlink '/dev/disk/by-id/usb-General_UDisk-0:0-part1' to '/dev/sdb1'
21: sdb1: Successfully created symlink '/dev/disk/by-uuid/CENS-ORED' to '/dev/sdb1'
21: sdb1: Successfully created symlink '/dev/disk/by-partuuid/censored-cens-ored-cens-oredcensoredc' to '/dev/sdb1'
21: sdb1: Successfully created symlink '/dev/disk/by-
partlabel/Microsoft\x20basic\x20data' to '/dev/sdb1'
21: sdb1: Successfully created symlink '/dev/disk/by-label/censored' to '/dev/sdb1'
21: sdb1: Successfully created symlink '/dev/disk/by-path/pci-0000:00:12.0-usb-c:e.n:s.o-scsi-0:0:0:0-part1' to '/dev/sdb1'
21: sdb1: Successfully created symlink '/dev/block/8:17' to '/dev/sdb1'
21: sdb1: sd-device: Created db file '/run/udev/data/b8:17' for '/devices/pci0000:00/0000:00:12.0/usb1/1-1/1-1.2/1-c.e:n.s/host2/target2:0:0/2:0:0:0/block/sdb/sdb1'
21: sdb1: Adding watch on '/dev/sdb1'
21: sdb1: Device processed (SEQNUM=2860, ACTION=add)
21: sdb1: sd-device-monitor(worker): Passed 1861 byte to netlink monitor.
21: 2:0:0:0: Device ready for processing (SEQNUM=2861, ACTION=bind)
21: 2:0:0:0: sd-device-monitor(manager): Passed 273 byte to netlink monitor.
21: 2:0:0:0: Processing device (SEQNUM=2861, ACTION=bind)
21: 2:0:0:0: /usr/lib/udev/rules.d/50-udev-default.rules:18 Importing properties from results of builtin command 'hwdb --subsystem=scsi'
21: 2:0:0:0: hwdb modalias key: "scsi:t-0x00"
21: 2:0:0:0: No entry found from hwdb.
21: 2:0:0:0: /usr/lib/udev/rules.d/50-udev-default.rules:18 Failed to run builtin 'hwdb --subsystem=scsi': No data available
21: path '[module/sg]' is '/sys/module/sg'
21: 2:0:0:0: Device processed (SEQNUM=2861, ACTION=bind)
21: 2:0:0:0: sd-device-monitor(worker): Passed 273 byte to netlink monitor.
21: No events are queued, removing /run/udev/queue.
```

-- Package-specific info:
-- System Information:
Debian Release: bookworm/sid
Architecture: amd64 (x86_64)
Kernel: Linux 6.0.0-6-amd64 (SMP w/4 CPU threads; PREEMPT)
Init: systemd (via /run/systemd/system)
LSM: AppArmor: enabled
Versions of packages udev depends on:
ii  adduser      3.130
ii  libacl1      2.3.1-2
ii  libblkid1    2.38.1-4
ii  libc6        2.36-8
ii  libcap2      1:2.66-3
ii  libkmod2     30+20221128-1
ii  libselinux1  3.4-1+b4
ii  libudev1     252.4-1
Versions of packages udev is related to:
ii  systemd  252.4-1
-- no debconf information

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://alioth-lists.debian.net/pipermail/pkg-systemd-maintainers/attachments/20230110/72705b0c/attachment-0001.htm>