and here is the journalctl extract (one hair shorter) of the system started
again with the debug kernel parameter and the script called manually :
00:00:09 kernel: usb 2-3: new SuperSpeed Gen 1 USB device number 4 using
xhci_hcd
00:00:09 systemd-udevd[216]: 2-3: Device is queued (SEQNUM=2255, ACTION=add)
00:00:09 systemd-udevd[216]: Validate module index
00:00:09 systemd-udevd[216]: Check if link configuration needs reloading.
00:00:09 systemd-udevd[216]: 2-3: Device ready for processing (SEQNUM=2255,
ACTION=add)
00:00:09 systemd-udevd[216]: Successfully forked off 'n/a' as PID 985.
00:00:09 systemd-udevd[216]: 2-3: Worker [985] is forked for processing
SEQNUM=2255.
00:00:09 systemd-udevd[216]: 2-3:1.0: Device is queued (SEQNUM=2256,
ACTION=add)
00:00:09 systemd-udevd[216]: 2-3:1.0: SEQNUM=2256 blocked by SEQNUM=2255
00:00:09 systemd-udevd[985]: 2-3: Processing device (SEQNUM=2255,
ACTION=add)
00:00:09 systemd-udevd[985]: 2-3: Failed to get watch handle, ignoring: No
such file or directory
00:00:09 systemd-udevd[985]: 2-3:
/usr/lib/udev/rules.d/50-udev-default.rules:13 Importing properties from
results of builtin command 'usb_id'
00:00:09 systemd-udevd[216]: scsi_tmf_6: Device is queued (SEQNUM=2257,
ACTION=add)
00:00:09 systemd-udevd[216]: scsi_tmf_6: Device ready for processing
(SEQNUM=2257, ACTION=add)
00:00:09 systemd-udevd[216]: Successfully forked off 'n/a' as PID 988.
00:00:09 systemd-udevd[216]: scsi_tmf_6: Worker [988] is forked for
processing SEQNUM=2257.
00:00:09 systemd-udevd[988]: scsi_tmf_6: Processing device (SEQNUM=2257,
ACTION=add)
00:00:09 systemd-udevd[988]: scsi_tmf_6: Device processed (SEQNUM=2257,
ACTION=add)
00:00:09 systemd-udevd[988]: scsi_tmf_6: sd-device-monitor: Passed 156 byte
to netlink monitor
00:00:09 systemd-udevd[216]: host6: Device is queued (SEQNUM=2258,
ACTION=add)
00:00:09 systemd-udevd[216]: host6: SEQNUM=2258 blocked by SEQNUM=2255
00:00:09 systemd-udevd[216]: host6: Device is queued (SEQNUM=2259,
ACTION=add)
00:00:09 systemd-udevd[216]: host6: SEQNUM=2259 blocked by SEQNUM=2255
00:00:09 systemd-udevd[216]: 2-3:1.0: Device is queued (SEQNUM=2260,
ACTION=bind)
00:00:09 systemd-udevd[216]: 2-3:1.0: SEQNUM=2260 blocked by SEQNUM=2255
00:00:09 systemd-udevd[216]: 2-3: Device is queued (SEQNUM=2261,
ACTION=bind)
00:00:09 systemd-udevd[216]: 2-3: SEQNUM=2261 blocked by SEQNUM=2255
00:00:09 systemd-udevd[985]: 2-3:
/usr/lib/udev/rules.d/50-udev-default.rules:13 Importing properties from
results of builtin command 'hwdb --subsystem=usb'
00:00:09 systemd-udevd[985]: 2-3: hwdb modalias key: "usb:v46F4p0001:QEMU
USB HARDDRIVE"
00:00:09 systemd-udevd[985]: 2-3:
/usr/lib/udev/rules.d/50-udev-default.rules:50 MODE 0664
00:00:09 systemd-udevd[985]: 2-3: /usr/lib/udev/rules.d/60-drm.rules:3
Importing properties from results of builtin command 'path_id'
00:00:09 systemd-udevd[985]: 2-3: /usr/lib/udev/rules.d/69-libmtp.rules:39
Running PROGRAM '/usr/lib/udev/mtp-probe
/sys/devices/pci0000:00/0000:00:03.0/usb2/2-3 2 4'
00:00:09 systemd-udevd[985]: 2-3: Starting '/usr/lib/udev/mtp-probe
/sys/devices/pci0000:00/0000:00:03.0/usb2/2-3 2 4'
00:00:09 systemd-udevd[985]: Successfully forked off '(spawn)' as PID 990.
00:00:09 kernel: usb 2-3: New USB device found, idVendor=46f4,
idProduct=0001, bcdDevice= 0.00
00:00:09 kernel: usb 2-3: New USB device strings: Mfr=1, Product=2,
SerialNumber=3
00:00:09 kernel: usb 2-3: Product: QEMU USB HARDDRIVE
00:00:09 kernel: usb 2-3: Manufacturer: QEMU
00:00:09 kernel: usb 2-3: SerialNumber: 1-0000:00:03.0-3
00:00:09 kernel: usb-storage 2-3:1.0: USB Mass Storage device detected
00:00:09 kernel: scsi host6: usb-storage 2-3:1.0
00:00:09 mtp-probe[990]: checking bus 2, device 4:
"/sys/devices/pci0000:00/0000:00:03.0/usb2/2-3"
00:00:09 mtp-probe[990]: bus: 2, device: 4 was not an MTP device
00:00:09 systemd-udevd[985]: 2-3: '/usr/lib/udev/mtp-probe
/sys/devices/pci0000:00/0000:00:03.0/usb2/2-3 2 4'(out) '0'
00:00:09 systemd-udevd[985]: 2-3: Process '/usr/lib/udev/mtp-probe
/sys/devices/pci0000:00/0000:00:03.0/usb2/2-3 2 4' succeeded.
00:00:09 systemd-udevd[985]: 2-3: Handling device node
'/dev/bus/usb/002/004', devnum=c189:131
00:00:09 systemd-udevd[985]: 2-3: Setting permissions /dev/bus/usb/002/004,
uid=0, gid=0, mode=0664
00:00:09 systemd-udevd[985]: 2-3: Creating symlink '/dev/char/189:131' to
'../bus/usb/002/004'
00:00:09 systemd-udevd[985]: 2-3: sd-device: Created db file
'/run/udev/data/c189:131' for '/devices/pci0000:00/0000:00:03.0/usb2/2-3'
00:00:09 systemd-udevd[985]: 2-3: Handling device node
'/dev/bus/usb/002/004', devnum=c189:131
00:00:09 systemd-udevd[985]: 2-3: Preserve permissions of
/dev/bus/usb/002/004, uid=0, gid=0, mode=0664
00:00:09 systemd-udevd[985]: 2-3: Preserve already existing symlink
'/dev/char/189:131' to '../bus/usb/002/004'
00:00:09 systemd-udevd[985]: 2-3: Failed to get watch handle, ignoring: No
such file or directory
00:00:09 systemd-udevd[985]: 2-3: Device processed (SEQNUM=2255, ACTION=add)
00:00:09 systemd-udevd[985]: 2-3: sd-device-monitor: Passed 718 byte to
netlink monitor
00:00:09 systemd-udevd[216]: 2-3:1.0: Device ready for processing
(SEQNUM=2256, ACTION=add)
00:00:09 systemd-udevd[216]: 2-3:1.0: sd-device-monitor: Passed 288 byte to
netlink monitor
00:00:09 systemd-udevd[988]: 2-3:1.0: Processing device (SEQNUM=2256,
ACTION=add)
00:00:09 systemd-udevd[988]: 2-3:1.0:
/usr/lib/udev/rules.d/50-udev-default.rules:14 Importing properties from
results of builtin command 'hwdb --subsystem=usb'
00:00:09 systemd-udevd[216]: host6: SEQNUM=2258 blocked by SEQNUM=2256
00:00:09 systemd-udevd[216]: host6: SEQNUM=2259 blocked by SEQNUM=2256
00:00:09 systemd-udevd[216]: 2-3:1.0: SEQNUM=2260 blocked by SEQNUM=2256
00:00:09 systemd-udevd[216]: 2-3: SEQNUM=2261 blocked by SEQNUM=2256
00:00:09 systemd-udevd[988]: 2-3:1.0: hwdb modalias key:
"usb:v46F4p0001d0000dc00dsc00dp00ic08isc06ip50in00"
00:00:09 systemd-udevd[988]: 2-3:1.0: /usr/lib/udev/rules.d/60-drm.rules:3
Importing properties from results of builtin command 'path_id'
00:00:09 systemd-udevd[988]: 2-3:1.0:
/usr/lib/udev/rules.d/80-drivers.rules:5 RUN 'kmod load '$env{MODALIAS}''
00:00:09 systemd-udevd[988]: 2-3:1.0: sd-device: Created db file
'/run/udev/data/+usb:2-3:1.0' for
'/devices/pci0000:00/0000:00:03.0/usb2/2-3/2-3:1.0'
00:00:09 systemd-udevd[988]: 2-3:1.0: Running built-in command "kmod load
'usb:v46F4p0001d0000dc00dsc00dp00ic08isc06ip50in00'"
00:00:09 systemd-udevd[988]: Loading module:
usb:v46F4p0001d0000dc00dsc00dp00ic08isc06ip50in00
00:00:09 systemd-udevd[988]: Module 'uas' is already loaded
00:00:09 systemd-udevd[988]: Module 'usb_storage' is already loaded
00:00:09 systemd-udevd[988]: 2-3:1.0: Device processed (SEQNUM=2256,
ACTION=add)
00:00:09 systemd-udevd[988]: 2-3:1.0: sd-device-monitor: Passed 395 byte to
netlink monitor
00:00:09 systemd-udevd[216]: host6: Device ready for processing
(SEQNUM=2258, ACTION=add)
00:00:09 systemd-udevd[216]: host6: sd-device-monitor: Passed 187 byte to
netlink monitor
00:00:09 systemd-udevd[216]: host6: SEQNUM=2259 blocked by SEQNUM=2258
00:00:09 systemd-udevd[216]: 2-3:1.0: SEQNUM=2260 blocked by SEQNUM=2258
00:00:09 systemd-udevd[216]: 2-3: SEQNUM=2261 blocked by SEQNUM=2258
00:00:09 systemd-udevd[988]: host6: Processing device (SEQNUM=2258,
ACTION=add)
00:00:09 systemd-udevd[988]: host6: Device processed (SEQNUM=2258,
ACTION=add)
00:00:09 systemd-udevd[988]: host6: sd-device-monitor: Passed 187 byte to
netlink monitor
00:00:09 systemd-udevd[216]: host6: Device ready for processing
(SEQNUM=2259, ACTION=add)
00:00:09 systemd-udevd[216]: host6: sd-device-monitor: Passed 190 byte to
netlink monitor
00:00:09 systemd-udevd[216]: 2-3:1.0: SEQNUM=2260 blocked by SEQNUM=2259
00:00:09 systemd-udevd[216]: 2-3: SEQNUM=2261 blocked by SEQNUM=2259
00:00:09 systemd-udevd[988]: host6: Processing device (SEQNUM=2259,
ACTION=add)
00:00:09 systemd-udevd[988]: host6: Device processed (SEQNUM=2259,
ACTION=add)
00:00:09 systemd-udevd[988]: host6: sd-device-monitor: Passed 190 byte to
netlink monitor
00:00:09 systemd-udevd[216]: 2-3:1.0: Device ready for processing
(SEQNUM=2260, ACTION=bind)
00:00:09 systemd-udevd[216]: 2-3:1.0: sd-device-monitor: Passed 308 byte to
netlink monitor
00:00:09 systemd-udevd[216]: 2-3: SEQNUM=2261 blocked by SEQNUM=2260
00:00:09 systemd-udevd[988]: 2-3:1.0: Processing device (SEQNUM=2260,
ACTION=bind)
00:00:09 systemd-udevd[988]: 2-3:1.0:
/usr/lib/udev/rules.d/50-udev-default.rules:14 Importing properties from
results of builtin command 'hwdb --subsystem=usb'
00:00:09 systemd-udevd[988]: 2-3:1.0: hwdb modalias key:
"usb:v46F4p0001d0000dc00dsc00dp00ic08isc06ip50in00"
00:00:09 systemd-udevd[988]: 2-3:1.0: /usr/lib/udev/rules.d/60-drm.rules:3
Importing properties from results of builtin command 'path_id'
00:00:09 systemd-udevd[988]: 2-3:1.0: sd-device: Created db file
'/run/udev/data/+usb:2-3:1.0' for
'/devices/pci0000:00/0000:00:03.0/usb2/2-3/2-3:1.0'
00:00:09 systemd-udevd[988]: 2-3:1.0: Device processed (SEQNUM=2260,
ACTION=bind)
00:00:09 systemd-udevd[988]: 2-3:1.0: sd-device-monitor: Passed 415 byte to
netlink monitor
00:00:09 systemd-udevd[216]: 2-3: Device ready for processing (SEQNUM=2261,
ACTION=bind)
00:00:09 systemd-udevd[216]: 2-3: sd-device-monitor: Passed 284 byte to
netlink monitor
00:00:09 systemd-udevd[988]: 2-3: Processing device (SEQNUM=2261,
ACTION=bind)
00:00:09 systemd-udevd[988]: 2-3: Failed to get watch handle, ignoring: No
such file or directory
00:00:09 systemd-udevd[988]: 2-3:
/usr/lib/udev/rules.d/50-udev-default.rules:13 Importing properties from
results of builtin command 'usb_id'
00:00:09 systemd-udevd[988]: 2-3:
/usr/lib/udev/rules.d/50-udev-default.rules:13 Importing properties from
results of builtin command 'hwdb --subsystem=usb'
00:00:09 systemd-udevd[988]: 2-3: hwdb modalias key: "usb:v46F4p0001:QEMU
USB HARDDRIVE"
00:00:09 systemd-udevd[988]: 2-3: /usr/lib/udev/rules.d/60-drm.rules:3
Importing properties from results of builtin command 'path_id'
00:00:09 systemd-udevd[988]: 2-3: /usr/lib/udev/rules.d/69-libmtp.rules:39
Running PROGRAM '/usr/lib/udev/mtp-probe
/sys/devices/pci0000:00/0000:00:03.0/usb2/2-3 2 4'
00:00:09 systemd-udevd[988]: 2-3: Starting '/usr/lib/udev/mtp-probe
/sys/devices/pci0000:00/0000:00:03.0/usb2/2-3 2 4'
00:00:09 systemd-udevd[988]: Successfully forked off '(spawn)' as PID 991.
00:00:09 mtp-probe[991]: checking bus 2, device 4:
"/sys/devices/pci0000:00/0000:00:03.0/usb2/2-3"
00:00:09 mtp-probe[991]: bus: 2, device: 4 was not an MTP device
00:00:09 systemd-udevd[988]: 2-3: '/usr/lib/udev/mtp-probe
/sys/devices/pci0000:00/0000:00:03.0/usb2/2-3 2 4'(out) '0'
00:00:09 systemd-udevd[988]: 2-3: Process '/usr/lib/udev/mtp-probe
/sys/devices/pci0000:00/0000:00:03.0/usb2/2-3 2 4' succeeded.
00:00:09 systemd-udevd[988]: 2-3: Handling device node
'/dev/bus/usb/002/004', devnum=c189:131
00:00:09 systemd-udevd[988]: 2-3: Preserve already existing symlink
'/dev/char/189:131' to '../bus/usb/002/004'
00:00:09 systemd-udevd[988]: 2-3: sd-device: Created db file
'/run/udev/data/c189:131' for '/devices/pci0000:00/0000:00:03.0/usb2/2-3'
00:00:09 systemd-udevd[988]: 2-3: Handling device node
'/dev/bus/usb/002/004', devnum=c189:131
00:00:09 systemd-udevd[988]: 2-3: Preserve already existing symlink
'/dev/char/189:131' to '../bus/usb/002/004'
00:00:09 systemd-udevd[988]: 2-3: Failed to get watch handle, ignoring: No
such file or directory
00:00:09 systemd-udevd[988]: 2-3: Device processed (SEQNUM=2261,
ACTION=bind)
00:00:09 systemd-udevd[988]: 2-3: sd-device-monitor: Passed 730 byte to
netlink monitor
00:00:10 systemd-udevd[216]: target6:0:0: Device is queued (SEQNUM=2262,
ACTION=add)
00:00:10 systemd-udevd[216]: target6:0:0: Device ready for processing
(SEQNUM=2262, ACTION=add)
00:00:10 systemd-udevd[216]: target6:0:0: sd-device-monitor: Passed 201
byte to netlink monitor
00:00:10 systemd-udevd[216]: 6:0:0:0: Device is queued (SEQNUM=2263,
ACTION=add)
00:00:10 systemd-udevd[216]: 6:0:0:0: SEQNUM=2263 blocked by SEQNUM=2262
00:00:10 systemd-udevd[216]: 6:0:0:0: Device is queued (SEQNUM=2264,
ACTION=add)
00:00:10 systemd-udevd[216]: 6:0:0:0: SEQNUM=2264 blocked by SEQNUM=2262
00:00:10 systemd-udevd[988]: target6:0:0: Processing device (SEQNUM=2262,
ACTION=add)
00:00:10 systemd-udevd[988]: target6:0:0: Device processed (SEQNUM=2262,
ACTION=add)
00:00:10 systemd-udevd[988]: target6:0:0: sd-device-monitor: Passed 201
byte to netlink monitor
00:00:10 systemd-udevd[216]: 6:0:0:0: Device is queued (SEQNUM=2265,
ACTION=add)
00:00:10 systemd-udevd[216]: 6:0:0:0: SEQNUM=2265 blocked by SEQNUM=2262
00:00:10 systemd-udevd[216]: 6:0:0:0: Device ready for processing
(SEQNUM=2263, ACTION=add)
00:00:10 systemd-udevd[216]: 6:0:0:0: sd-device-monitor: Passed 230 byte to
netlink monitor
00:00:10 systemd-udevd[216]: 6:0:0:0: SEQNUM=2264 blocked by SEQNUM=2263
00:00:10 systemd-udevd[216]: 6:0:0:0: SEQNUM=2265 blocked by SEQNUM=2263
00:00:10 systemd-udevd[216]: 6:0:0:0: Device is queued (SEQNUM=2266,
ACTION=change)
00:00:10 systemd-udevd[216]: 6:0:0:0: SEQNUM=2266 blocked by SEQNUM=2263
00:00:10 systemd-udevd[216]: 6:0:0:0: Device is queued (SEQNUM=2267,
ACTION=add)
00:00:10 systemd-udevd[216]: 6:0:0:0: SEQNUM=2267 blocked by SEQNUM=2263
00:00:10 systemd-udevd[216]: 8:16: Device is queued (SEQNUM=2268,
ACTION=add)
00:00:10 systemd-udevd[216]: 8:16: Device ready for processing
(SEQNUM=2268, ACTION=add)
00:00:10 systemd-udevd[985]: 8:16: Processing device (SEQNUM=2268,
ACTION=add)
00:00:10 systemd-udevd[985]: 8:16: Device processed (SEQNUM=2268,
ACTION=add)
00:00:10 systemd-udevd[985]: 8:16: sd-device-monitor: Passed 138 byte to
netlink monitor
00:00:10 systemd-udevd[216]: 8:16: sd-device-monitor: Passed 138 byte to
netlink monitor
00:00:10 systemd-udevd[216]: sdb: Device is queued (SEQNUM=2269, ACTION=add)
00:00:10 systemd-udevd[216]: sdb: SEQNUM=2269 blocked by SEQNUM=2263
00:00:10 systemd-udevd[988]: 6:0:0:0: Processing device (SEQNUM=2263,
ACTION=add)
00:00:10 systemd-udevd[988]: 6:0:0:0:
/usr/lib/udev/rules.d/50-udev-default.rules:14 Importing properties from
results of builtin command 'hwdb --subsystem=scsi'
00:00:10 systemd-udevd[988]: 6:0:0:0: hwdb modalias key: "scsi:t-0x00"
00:00:10 systemd-udevd[988]: 6:0:0:0: No entry found from hwdb.
00:00:10 systemd-udevd[988]: 6:0:0:0:
/usr/lib/udev/rules.d/50-udev-default.rules:14 Failed to run builtin 'hwdb
--subsystem=scsi': No data available
00:00:10 systemd-udevd[988]: 6:0:0:0:
/usr/lib/udev/rules.d/80-drivers.rules:5 RUN 'kmod load '$env{MODALIAS}''
00:00:10 systemd-udevd[988]: 6:0:0:0: Running built-in command "kmod load
'scsi:t-0x00'"
00:00:10 systemd-udevd[988]: Loading module: scsi:t-0x00
00:00:10 systemd-udevd[988]: Module 'sd_mod' is built in
00:00:10 systemd-udevd[988]: 6:0:0:0: Device processed (SEQNUM=2263,
ACTION=add)
00:00:10 systemd-udevd[988]: 6:0:0:0: sd-device-monitor: Passed 230 byte to
netlink monitor
00:00:10 systemd-udevd[216]: 6:0:0:0: Device ready for processing
(SEQNUM=2264, ACTION=add)
00:00:10 systemd-udevd[216]: 6:0:0:0: sd-device-monitor: Passed 216 byte to
netlink monitor
00:00:10 systemd-udevd[216]: 6:0:0:0: Device ready for processing
(SEQNUM=2265, ACTION=add)
00:00:10 systemd-udevd[985]: 6:0:0:0: Processing device (SEQNUM=2265,
ACTION=add)
00:00:10 systemd-udevd[985]: 6:0:0:0: Device processed (SEQNUM=2265,
ACTION=add)
00:00:10 systemd-udevd[985]: 6:0:0:0: sd-device-monitor: Passed 212 byte to
netlink monitor
00:00:10 systemd-udevd[216]: 6:0:0:0: sd-device-monitor: Passed 212 byte to
netlink monitor
00:00:10 systemd-udevd[216]: 6:0:0:0: SEQNUM=2266 blocked by SEQNUM=2264
00:00:10 systemd-udevd[216]: 6:0:0:0: SEQNUM=2267 blocked by SEQNUM=2266
00:00:10 systemd-udevd[216]: sdb: SEQNUM=2269 blocked by SEQNUM=2266
00:00:10 systemd-udevd[988]: 6:0:0:0: Processing device (SEQNUM=2264,
ACTION=add)
00:00:10 systemd-udevd[988]: 6:0:0:0: Device processed (SEQNUM=2264,
ACTION=add)
00:00:10 systemd-udevd[988]: 6:0:0:0: sd-device-monitor: Passed 216 byte to
netlink monitor
00:00:10 systemd-udevd[216]: 6:0:0:0: Device ready for processing
(SEQNUM=2266, ACTION=change)
00:00:10 systemd-udevd[216]: 6:0:0:0: sd-device-monitor: Passed 275 byte to
netlink monitor
00:00:10 kernel: scsi 6:0:0:0: Direct-Access QEMU QEMU HARDDISK
2.5+ PQ: 0 ANSI: 5
00:00:10 kernel: sd 6:0:0:0: Power-on or device reset occurred
00:00:10 kernel: sd 6:0:0:0: [sdb] 524288 512-byte logical blocks: (268
MB/256 MiB)
00:00:10 kernel: sd 6:0:0:0: [sdb] Write Protect is off
00:00:10 kernel: sd 6:0:0:0: [sdb] Mode Sense: 63 00 00 08
00:00:10 kernel: sd 6:0:0:0: [sdb] Write cache: enabled, read cache:
enabled, doesn't support DPO or FUA
00:00:10 systemd-udevd[988]: 6:0:0:0: Processing device (SEQNUM=2266,
ACTION=change)
00:00:10 systemd-udevd[988]: 6:0:0:0:
/usr/lib/udev/rules.d/50-udev-default.rules:14 Importing properties from
results of builtin command 'hwdb --subsystem=scsi'
00:00:10 systemd-udevd[988]: 6:0:0:0: hwdb modalias key: "scsi:t-0x00"
00:00:10 systemd-udevd[988]: 6:0:0:0: No entry found from hwdb.
00:00:10 systemd-udevd[988]: 6:0:0:0:
/usr/lib/udev/rules.d/50-udev-default.rules:14 Failed to run builtin 'hwdb
--subsystem=scsi': No data available
00:00:10 systemd-udevd[988]: 6:0:0:0:
/usr/lib/udev/rules.d/60-block.rules:8 ATTR
'/sys/devices/pci0000:00/0000:00:03.0/usb2/2-3/2-3:1.0/host6/target6:0:0/6:0:0:0/block/sdb/uevent'
writing 'change'
00:00:10 systemd-udevd[988]: 6:0:0:0: Device processed (SEQNUM=2266,
ACTION=change)
00:00:10 systemd-udevd[988]: 6:0:0:0: sd-device-monitor: Passed 275 byte to
netlink monitor
00:00:10 systemd-udevd[216]: sdb: Device is queued (SEQNUM=2270,
ACTION=change)
00:00:10 systemd-udevd[216]: sdb: SEQNUM=2270 blocked by SEQNUM=2266
00:00:10 systemd-udevd[216]: 6:0:0:0: Device ready for processing
(SEQNUM=2267, ACTION=add)
00:00:10 systemd-udevd[216]: 6:0:0:0: sd-device-monitor: Passed 243 byte to
netlink monitor
00:00:10 systemd-udevd[216]: sdb: Device ready for processing (SEQNUM=2269,
ACTION=add)
00:00:10 systemd-udevd[985]: sdb: Processing device (SEQNUM=2269,
ACTION=add)
00:00:10 systemd-udevd[985]: sdb: Failed to get watch handle, ignoring: No
such file or directory
00:00:10 systemd-udevd[985]: sdb:
/usr/lib/udev/rules.d/50-udev-default.rules:69 GROUP 995
00:00:10 systemd-udevd[985]: sdb:
/usr/lib/udev/rules.d/60-persistent-storage.rules:60 Importing properties
from results of 'ata_id --export /dev/sdb'
00:00:10 systemd-udevd[985]: sdb: Starting 'ata_id --export /dev/sdb'
00:00:10 systemd-udevd[985]: Successfully forked off '(spawn)' as PID 992.
00:00:10 systemd-udevd[216]: sdb: sd-device-monitor: Passed 247 byte to
netlink monitor
00:00:10 systemd-udevd[216]: sdb: SEQNUM=2270 blocked by SEQNUM=2269
00:00:10 systemd-udevd[988]: 6:0:0:0: Processing device (SEQNUM=2267,
ACTION=add)
00:00:10 systemd-udevd[988]: 6:0:0:0: Failed to get watch handle, ignoring:
No such file or directory
00:00:10 systemd-udevd[988]: 6:0:0:0: Handling device node
'/dev/bsg/6:0:0:0', devnum=c243:2
00:00:10 systemd-udevd[988]: 6:0:0:0: Preserve permissions of
/dev/bsg/6:0:0:0, uid=0, gid=0, mode=0600
00:00:10 systemd-udevd[988]: 6:0:0:0: Creating symlink '/dev/char/243:2' to
'../bsg/6:0:0:0'
00:00:10 systemd-udevd[988]: 6:0:0:0: sd-device: Created empty file
'/run/udev/data/c243:2' for
'/devices/pci0000:00/0000:00:03.0/usb2/2-3/2-3:1.0/host6/target6:0:0/6:0:0:0/bsg/6:0:0:0'
00:00:10 systemd-udevd[988]: 6:0:0:0: Handling device node
'/dev/bsg/6:0:0:0', devnum=c243:2
00:00:10 systemd-udevd[988]: 6:0:0:0: Preserve permissions of
/dev/bsg/6:0:0:0, uid=0, gid=0, mode=0600
00:00:10 systemd-udevd[988]: 6:0:0:0: Preserve already existing symlink
'/dev/char/243:2' to '../bsg/6:0:0:0'
00:00:10 systemd-udevd[988]: 6:0:0:0: Failed to get watch handle, ignoring:
No such file or directory
00:00:10 systemd-udevd[988]: 6:0:0:0: Device processed (SEQNUM=2267,
ACTION=add)
00:00:10 systemd-udevd[988]: 6:0:0:0: sd-device-monitor: Passed 243 byte to
netlink monitor
00:00:10 systemd-udevd[216]: 6:0:0:0: Device is queued (SEQNUM=2271,
ACTION=bind)
00:00:10 systemd-udevd[216]: 6:0:0:0: SEQNUM=2271 blocked by SEQNUM=2269
00:00:10 systemd-udevd[985]: sdb: Process 'ata_id --export /dev/sdb' failed
with exit code 2.
00:00:10 systemd-udevd[985]: sdb:
/usr/lib/udev/rules.d/60-persistent-storage.rules:60 Command "ata_id
--export /dev/sdb" returned 2 (error), ignoring
00:00:10 systemd-udevd[985]: sdb:
/usr/lib/udev/rules.d/60-persistent-storage.rules:63 Importing properties
from results of builtin command 'usb_id'
00:00:10 systemd-udevd[985]: 2-3:1.0: if_class:8 protocol:6
00:00:10 systemd-udevd[985]: sdb:
/usr/lib/udev/rules.d/60-persistent-storage.rules:68 LINK
'disk/by-id/usb-QEMU_QEMU_HARDDISK_1-0000:00:03.0-3-0:0'
00:00:10 systemd-udevd[985]: sdb:
/usr/lib/udev/rules.d/60-persistent-storage.rules:90 Importing properties
from results of builtin command 'path_id'
00:00:10 systemd-udevd[985]: sdb:
/usr/lib/udev/rules.d/60-persistent-storage.rules:92 LINK
'disk/by-path/pci-0000:00:03.0-usb-0:3:1.0-scsi-0:0:0:0'
00:00:10 systemd-udevd[985]: sdb:
/usr/lib/udev/rules.d/60-persistent-storage.rules:110 Importing properties
from results of builtin command 'blkid'
00:00:10 systemd-udevd[985]: sdb: Probe /dev/sdb with raid and offset=0
00:00:10 kernel: sd 6:0:0:0: [sdb] Attached SCSI disk
00:00:10 systemd-udevd[985]: sdb: /usr/lib/udev/rules.d/69-vdev.rules:5
Importing properties from results of '/usr/lib/udev/vdev_id -d sdb'
00:00:10 systemd-udevd[985]: sdb: Starting '/usr/lib/udev/vdev_id -d sdb'
00:00:10 systemd-udevd[985]: Successfully forked off '(spawn)' as PID 993.
00:00:10 systemd-udevd[985]: sdb: '/usr/lib/udev/vdev_id -d sdb'(out)
'Error: Config file "/etc/zfs/vdev_id.conf" not found'
00:00:10 systemd-udevd[985]: sdb: Process '/usr/lib/udev/vdev_id -d sdb'
failed with exit code 1.
00:00:10 systemd-udevd[985]: sdb: /usr/lib/udev/rules.d/69-vdev.rules:5
Command "/usr/lib/udev/vdev_id -d sdb" returned 1 (error), ignoring
00:00:10 systemd-udevd[985]: sdb: Handling device node '/dev/sdb',
devnum=b8:16
00:00:10 systemd-udevd[985]: sdb: Setting permissions /dev/sdb, uid=0,
gid=995, mode=0660
00:00:10 systemd-udevd[985]: sdb: Found 'b8:16' claiming
'/run/udev/links/disk\x2fby-id\x2fusb-QEMU_QEMU_HARDDISK_1-0000:00:03.0-3-0:0'
00:00:10 systemd-udevd[985]: sdb: Creating symlink
'/dev/disk/by-id/usb-QEMU_QEMU_HARDDISK_1-0000:00:03.0-3-0:0' to '../../sdb'
00:00:10 systemd-udevd[985]: sdb: Found 'b8:16' claiming
'/run/udev/links/disk\x2fby-path\x2fpci-0000:00:03.0-usb-0:3:1.0-scsi-0:0:0:0'
00:00:10 systemd-udevd[985]: sdb: Creating symlink
'/dev/disk/by-path/pci-0000:00:03.0-usb-0:3:1.0-scsi-0:0:0:0' to '../../sdb'
00:00:10 systemd-udevd[985]: sdb: Creating symlink '/dev/block/8:16' to
'../sdb'
00:00:10 systemd-udevd[985]: sdb: sd-device: Created db file
'/run/udev/data/b8:16' for
'/devices/pci0000:00/0000:00:03.0/usb2/2-3/2-3:1.0/host6/target6:0:0/6:0:0:0/block/sdb'
00:00:10 systemd-udevd[985]: sdb: Handling device node '/dev/sdb',
devnum=b8:16
00:00:10 systemd-udevd[985]: sdb: Preserve permissions of /dev/sdb, uid=0,
gid=995, mode=0660
00:00:10 systemd-udevd[985]: sdb: Found 'b8:16' claiming
'/run/udev/links/disk\x2fby-id\x2fusb-QEMU_QEMU_HARDDISK_1-0000:00:03.0-3-0:0'
00:00:10 systemd-udevd[985]: sdb: Preserve already existing symlink
'/dev/disk/by-id/usb-QEMU_QEMU_HARDDISK_1-0000:00:03.0-3-0:0' to '../../sdb'
00:00:10 systemd-udevd[985]: sdb: Found 'b8:16' claiming
'/run/udev/links/disk\x2fby-path\x2fpci-0000:00:03.0-usb-0:3:1.0-scsi-0:0:0:0'
00:00:10 systemd-udevd[985]: sdb: Preserve already existing symlink
'/dev/disk/by-path/pci-0000:00:03.0-usb-0:3:1.0-scsi-0:0:0:0' to '../../sdb'
00:00:10 systemd-udevd[985]: sdb: Preserve already existing symlink
'/dev/block/8:16' to '../sdb'
00:00:10 systemd-udevd[985]: sdb: Adding watch on '/dev/sdb'
00:00:10 systemd-udevd[985]: sdb: Device processed (SEQNUM=2269, ACTION=add)
00:00:10 systemd[295]:
dev-disk-by\x2did-usb\x2dQEMU_QEMU_HARDDISK_1\x2d0000:00:03.0\x2d3\x2d0:0.device:
Changed dead -> plugged
00:00:10 systemd[1]:
dev-disk-by\x2dpath-pci\x2d0000:00:03.0\x2dusb\x2d0:3:1.0\x2dscsi\x2d0:0:0:0.device:
Changed dead -> plugged
00:00:10 systemd[1]:
dev-disk-by\x2did-usb\x2dQEMU_QEMU_HARDDISK_1\x2d0000:00:03.0\x2d3\x2d0:0.device:
Changed dead -> plugged
00:00:10 systemd[1]: dev-sdb.device: Changed dead -> plugged
00:00:10 systemd[1]:
sys-devices-pci0000:00-0000:00:03.0-usb2-2\x2d3-2\x2d3:1.0-host6-target6:0:0-6:0:0:0-block-sdb.device:
Changed dead -> plugged
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager
member=UnitNew cookie=290 reply_cookie=0 signature=so error-name=n/a
error-message=n/a
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager
member=UnitNew cookie=291 reply_cookie=0 signature=so error-name=n/a
error-message=n/a
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager
member=UnitNew cookie=292 reply_cookie=0 signature=so error-name=n/a
error-message=n/a
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager
member=UnitNew cookie=293 reply_cookie=0 signature=so error-name=n/a
error-message=n/a
00:00:10 systemd[295]:
dev-disk-by\x2dpath-pci\x2d0000:00:03.0\x2dusb\x2d0:3:1.0\x2dscsi\x2d0:0:0:0.device:
Changed dead -> plugged
00:00:10 systemd[295]: dev-sdb.device: Changed dead -> plugged
00:00:10 systemd[295]:
sys-devices-pci0000:00-0000:00:03.0-usb2-2\x2d3-2\x2d3:1.0-host6-target6:0:0-6:0:0:0-block-sdb.device:
Changed dead -> plugged
00:00:10 systemd-udevd[985]: sdb: sd-device-monitor: Passed 885 byte to
netlink monitor
00:00:10 systemd-udevd[216]: sdb: Device ready for processing (SEQNUM=2270,
ACTION=change)
00:00:10 systemd-udevd[216]: sdb: sd-device-monitor: Passed 263 byte to
netlink monitor
00:00:10 systemd-udevd[988]: sdb: Processing device (SEQNUM=2270,
ACTION=change)
00:00:10 systemd-udevd[988]: sdb: Removing watch
00:00:10 systemd-udevd[216]: 6:0:0:0: SEQNUM=2271 blocked by SEQNUM=2270
00:00:10 systemd-udevd[216]: sdb: Inotify event: 8000 for /dev/sdb
00:00:10 systemd-udevd[988]: sdb:
/usr/lib/udev/rules.d/60-persistent-storage.rules:60 Importing properties
from results of 'ata_id --export /dev/sdb'
00:00:10 systemd-udevd[988]: sdb: Starting 'ata_id --export /dev/sdb'
00:00:10 systemd-udevd[988]: Successfully forked off '(spawn)' as PID 994.
00:00:10 systemd-udevd[988]: sdb: Process 'ata_id --export /dev/sdb' failed
with exit code 2.
00:00:10 systemd-udevd[988]: sdb:
/usr/lib/udev/rules.d/60-persistent-storage.rules:60 Command "ata_id
--export /dev/sdb" returned 2 (error), ignoring
00:00:10 systemd-udevd[988]: sdb:
/usr/lib/udev/rules.d/60-persistent-storage.rules:63 Importing properties
from results of builtin command 'usb_id'
00:00:10 systemd-udevd[988]: 2-3:1.0: if_class:8 protocol:6
00:00:10 systemd-udevd[988]: sdb:
/usr/lib/udev/rules.d/60-persistent-storage.rules:68 LINK
'disk/by-id/usb-QEMU_QEMU_HARDDISK_1-0000:00:03.0-3-0:0'
00:00:10 systemd-udevd[988]: sdb:
/usr/lib/udev/rules.d/60-persistent-storage.rules:90 Importing properties
from results of builtin command 'path_id'
00:00:10 systemd-udevd[988]: sdb:
/usr/lib/udev/rules.d/60-persistent-storage.rules:92 LINK
'disk/by-path/pci-0000:00:03.0-usb-0:3:1.0-scsi-0:0:0:0'
00:00:10 systemd-udevd[988]: sdb:
/usr/lib/udev/rules.d/60-persistent-storage.rules:110 Importing properties
from results of builtin command 'blkid'
00:00:10 systemd-udevd[988]: sdb: Probe /dev/sdb with raid and offset=0
00:00:10 systemd-udevd[988]: sdb: /usr/lib/udev/rules.d/69-vdev.rules:5
Importing properties from results of '/usr/lib/udev/vdev_id -d sdb'
00:00:10 systemd-udevd[988]: sdb: Starting '/usr/lib/udev/vdev_id -d sdb'
00:00:10 systemd-udevd[988]: Successfully forked off '(spawn)' as PID 995.
00:00:10 systemd-udevd[988]: sdb: '/usr/lib/udev/vdev_id -d sdb'(out)
'Error: Config file "/etc/zfs/vdev_id.conf" not found'
00:00:10 systemd-udevd[988]: sdb: Process '/usr/lib/udev/vdev_id -d sdb'
failed with exit code 1.
00:00:10 systemd-udevd[988]: sdb: /usr/lib/udev/rules.d/69-vdev.rules:5
Command "/usr/lib/udev/vdev_id -d sdb" returned 1 (error), ignoring
00:00:10 systemd-udevd[988]: sdb: Handling device node '/dev/sdb',
devnum=b8:16
00:00:10 systemd-udevd[988]: sdb: Found 'b8:16' claiming
'/run/udev/links/disk\x2fby-id\x2fusb-QEMU_QEMU_HARDDISK_1-0000:00:03.0-3-0:0'
00:00:10 systemd-udevd[988]: sdb: Preserve already existing symlink
'/dev/disk/by-id/usb-QEMU_QEMU_HARDDISK_1-0000:00:03.0-3-0:0' to '../../sdb'
00:00:10 systemd-udevd[988]: sdb: Found 'b8:16' claiming
'/run/udev/links/disk\x2fby-path\x2fpci-0000:00:03.0-usb-0:3:1.0-scsi-0:0:0:0'
00:00:10 systemd-udevd[988]: sdb: Preserve already existing symlink
'/dev/disk/by-path/pci-0000:00:03.0-usb-0:3:1.0-scsi-0:0:0:0' to '../../sdb'
00:00:10 systemd-udevd[988]: sdb: Preserve already existing symlink
'/dev/block/8:16' to '../sdb'
00:00:10 systemd-udevd[988]: sdb: sd-device: Created db file
'/run/udev/data/b8:16' for
'/devices/pci0000:00/0000:00:03.0/usb2/2-3/2-3:1.0/host6/target6:0:0/6:0:0:0/block/sdb'
00:00:10 systemd-udevd[988]: sdb: Handling device node '/dev/sdb',
devnum=b8:16
00:00:10 systemd-udevd[988]: sdb: Found 'b8:16' claiming
'/run/udev/links/disk\x2fby-id\x2fusb-QEMU_QEMU_HARDDISK_1-0000:00:03.0-3-0:0'
00:00:10 systemd-udevd[988]: sdb: Preserve already existing symlink
'/dev/disk/by-id/usb-QEMU_QEMU_HARDDISK_1-0000:00:03.0-3-0:0' to '../../sdb'
00:00:10 systemd-udevd[988]: sdb: Found 'b8:16' claiming
'/run/udev/links/disk\x2fby-path\x2fpci-0000:00:03.0-usb-0:3:1.0-scsi-0:0:0:0'
00:00:10 systemd-udevd[988]: sdb: Preserve already existing symlink
'/dev/disk/by-path/pci-0000:00:03.0-usb-0:3:1.0-scsi-0:0:0:0' to '../../sdb'
00:00:10 systemd-udevd[988]: sdb: Preserve already existing symlink
'/dev/block/8:16' to '../sdb'
00:00:10 systemd-udevd[988]: sdb: Adding watch on '/dev/sdb'
00:00:10 systemd-udevd[988]: sdb: Device processed (SEQNUM=2270,
ACTION=change)
00:00:10 systemd[295]:
dev-disk-by\x2did-usb\x2dQEMU_QEMU_HARDDISK_1\x2d0000:00:03.0\x2d3\x2d0:0.device:
Installed new job
dev-disk-by\x2did-usb\x2dQEMU_QEMU_HARDDISK_1\x2d0000:00:03.0\x2d3\x2d0:0.device/nop
as 58
00:00:10 systemd[295]:
dev-disk-by\x2dpath-pci\x2d0000:00:03.0\x2dusb\x2d0:3:1.0\x2dscsi\x2d0:0:0:0.device:
Installed new job
dev-disk-by\x2dpath-pci\x2d0000:00:03.0\x2dusb\x2d0:3:1.0\x2dscsi\x2d0:0:0:0.device/nop
as 59
00:00:10 systemd[295]: dev-sdb.device: Installed new job dev-sdb.device/nop
as 60
00:00:10 systemd[295]:
sys-devices-pci0000:00-0000:00:03.0-usb2-2\x2d3-2\x2d3:1.0-host6-target6:0:0-6:0:0:0-block-sdb.device:
Installed new job
sys-devices-pci0000:00-0000:00:03.0-usb2-2\x2d3-2\x2d3:1.0-host6-target6:0:0-6:0:0:0-block-sdb.device/nop
as 61
00:00:10 systemd[295]:
dev-disk-by\x2did-usb\x2dQEMU_QEMU_HARDDISK_1\x2d0000:00:03.0\x2d3\x2d0:0.device:
Job 58
dev-disk-by\x2did-usb\x2dQEMU_QEMU_HARDDISK_1\x2d0000:00:03.0\x2d3\x2d0:0.device/nop
finished, result=done
00:00:10 systemd[295]:
dev-disk-by\x2dpath-pci\x2d0000:00:03.0\x2dusb\x2d0:3:1.0\x2dscsi\x2d0:0:0:0.device:
Job 59
dev-disk-by\x2dpath-pci\x2d0000:00:03.0\x2dusb\x2d0:3:1.0\x2dscsi\x2d0:0:0:0.device/nop
finished, result=done
00:00:10 systemd[295]: dev-sdb.device: Job 60 dev-sdb.device/nop finished,
result=done
00:00:10 systemd[295]:
sys-devices-pci0000:00-0000:00:03.0-usb2-2\x2d3-2\x2d3:1.0-host6-target6:0:0-6:0:0:0-block-sdb.device:
Job 61
sys-devices-pci0000:00-0000:00:03.0-usb2-2\x2d3-2\x2d3:1.0-host6-target6:0:0-6:0:0:0-block-sdb.device/nop
finished, result=done
00:00:10 systemd[1]:
dev-disk-by\x2dpath-pci\x2d0000:00:03.0\x2dusb\x2d0:3:1.0\x2dscsi\x2d0:0:0:0.device:
Installed new job
dev-disk-by\x2dpath-pci\x2d0000:00:03.0\x2dusb\x2d0:3:1.0\x2dscsi\x2d0:0:0:0.device/nop
as 300
00:00:10 systemd[1]:
dev-disk-by\x2did-usb\x2dQEMU_QEMU_HARDDISK_1\x2d0000:00:03.0\x2d3\x2d0:0.device:
Installed new job
dev-disk-by\x2did-usb\x2dQEMU_QEMU_HARDDISK_1\x2d0000:00:03.0\x2d3\x2d0:0.device/nop
as 301
00:00:10 systemd[1]: dev-sdb.device: Installed new job dev-sdb.device/nop
as 302
00:00:10 systemd[1]:
sys-devices-pci0000:00-0000:00:03.0-usb2-2\x2d3-2\x2d3:1.0-host6-target6:0:0-6:0:0:0-block-sdb.device:
Installed new job
sys-devices-pci0000:00-0000:00:03.0-usb2-2\x2d3-2\x2d3:1.0-host6-target6:0:0-6:0:0:0-block-sdb.device/nop
as 303
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dpci0000_3a00_2d0000_3a00_3a03_2e0_2dusb2_2d2_5cx2d3_2d2_5cx2d3_3a1_2e0_2dhost6_2dtarget6_3a0_3a0_2d6_3a0_3a0_3a0_2dblock_2dsdb_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=294 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dpci0000_3a00_2d0000_3a00_3a03_2e0_2dusb2_2d2_5cx2d3_2d2_5cx2d3_3a1_2e0_2dhost6_2dtarget6_3a0_3a0_2d6_3a0_3a0_3a0_2dblock_2dsdb_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=295 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1/unit/dev_2dsdb_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=296 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1/unit/dev_2dsdb_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=297 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1/unit/dev_2ddisk_2dby_5cx2did_2dusb_5cx2dQEMU_5fQEMU_5fHARDDISK_5f1_5cx2d0000_3a00_3a03_2e0_5cx2d3_5cx2d0_3a0_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=298 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1/unit/dev_2ddisk_2dby_5cx2did_2dusb_5cx2dQEMU_5fQEMU_5fHARDDISK_5f1_5cx2d0000_3a00_3a03_2e0_5cx2d3_5cx2d0_3a0_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=299 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1/unit/dev_2ddisk_2dby_5cx2dpath_2dpci_5cx2d0000_3a00_3a03_2e0_5cx2dusb_5cx2d0_3a3_3a1_2e0_5cx2dscsi_5cx2d0_3a0_3a0_3a0_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=300 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1/unit/dev_2ddisk_2dby_5cx2dpath_2dpci_5cx2d0000_3a00_3a03_2e0_5cx2dusb_5cx2d0_3a3_3a1_2e0_5cx2dscsi_5cx2d0_3a0_3a0_3a0_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=301 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager
member=JobNew cookie=302 reply_cookie=0 signature=uos error-name=n/a
error-message=n/a
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager
member=JobNew cookie=303 reply_cookie=0 signature=uos error-name=n/a
error-message=n/a
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager
member=JobNew cookie=304 reply_cookie=0 signature=uos error-name=n/a
error-message=n/a
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager
member=JobNew cookie=305 reply_cookie=0 signature=uos error-name=n/a
error-message=n/a
00:00:10 systemd[1]:
dev-disk-by\x2did-usb\x2dQEMU_QEMU_HARDDISK_1\x2d0000:00:03.0\x2d3\x2d0:0.device:
Job 301
dev-disk-by\x2did-usb\x2dQEMU_QEMU_HARDDISK_1\x2d0000:00:03.0\x2d3\x2d0:0.device/nop
finished, result=done
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager
member=JobRemoved cookie=306 reply_cookie=0 signature=uoss error-name=n/a
error-message=n/a
00:00:10 systemd[1]:
dev-disk-by\x2dpath-pci\x2d0000:00:03.0\x2dusb\x2d0:3:1.0\x2dscsi\x2d0:0:0:0.device:
Job 300
dev-disk-by\x2dpath-pci\x2d0000:00:03.0\x2dusb\x2d0:3:1.0\x2dscsi\x2d0:0:0:0.device/nop
finished, result=done
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager
member=JobRemoved cookie=307 reply_cookie=0 signature=uoss error-name=n/a
error-message=n/a
00:00:10 systemd[1]: dev-sdb.device: Job 302 dev-sdb.device/nop finished,
result=done
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager
member=JobRemoved cookie=308 reply_cookie=0 signature=uoss error-name=n/a
error-message=n/a
00:00:10 systemd[1]:
sys-devices-pci0000:00-0000:00:03.0-usb2-2\x2d3-2\x2d3:1.0-host6-target6:0:0-6:0:0:0-block-sdb.device:
Job 303
sys-devices-pci0000:00-0000:00:03.0-usb2-2\x2d3-2\x2d3:1.0-host6-target6:0:0-6:0:0:0-block-sdb.device/nop
finished, result=done
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager
member=JobRemoved cookie=309 reply_cookie=0 signature=uoss error-name=n/a
error-message=n/a
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dpci0000_3a00_2d0000_3a00_3a03_2e0_2dusb2_2d2_5cx2d3_2d2_5cx2d3_3a1_2e0_2dhost6_2dtarget6_3a0_3a0_2d6_3a0_3a0_3a0_2dblock_2dsdb_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=310 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dpci0000_3a00_2d0000_3a00_3a03_2e0_2dusb2_2d2_5cx2d3_2d2_5cx2d3_3a1_2e0_2dhost6_2dtarget6_3a0_3a0_2d6_3a0_3a0_3a0_2dblock_2dsdb_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=311 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1/unit/dev_2dsdb_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=312 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1/unit/dev_2dsdb_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=313 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1/unit/dev_2ddisk_2dby_5cx2dpath_2dpci_5cx2d0000_3a00_3a03_2e0_5cx2dusb_5cx2d0_3a3_3a1_2e0_5cx2dscsi_5cx2d0_3a0_3a0_3a0_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=314 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1/unit/dev_2ddisk_2dby_5cx2dpath_2dpci_5cx2d0000_3a00_3a03_2e0_5cx2dusb_5cx2d0_3a3_3a1_2e0_5cx2dscsi_5cx2d0_3a0_3a0_3a0_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=315 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1/unit/dev_2ddisk_2dby_5cx2did_2dusb_5cx2dQEMU_5fQEMU_5fHARDDISK_5f1_5cx2d0000_3a00_3a03_2e0_5cx2d3_5cx2d0_3a0_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=316 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1/unit/dev_2ddisk_2dby_5cx2did_2dusb_5cx2dQEMU_5fQEMU_5fHARDDISK_5f1_5cx2d0000_3a00_3a03_2e0_5cx2d3_5cx2d0_3a0_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=317 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd-logind[290]: Got message type=signal sender=:1.0
destination=n/a
path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dpci0000_3a00_2d0000_3a00_3a03_2e0_2dusb2_2d2_5cx2d3_2d2_5cx2d3_3a1_2e0_2dhost6_2dtarget6_3a0_3a0_2d6_3a0_3a0_3a0_2dblock_2dsdb_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=294 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd-logind[290]: Got message type=signal sender=:1.0
destination=n/a
path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dpci0000_3a00_2d0000_3a00_3a03_2e0_2dusb2_2d2_5cx2d3_2d2_5cx2d3_3a1_2e0_2dhost6_2dtarget6_3a0_3a0_2d6_3a0_3a0_3a0_2dblock_2dsdb_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=295 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd-logind[290]: Got message type=signal sender=:1.0
destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dsdb_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=296 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd-logind[290]: Got message type=signal sender=:1.0
destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dsdb_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=297 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd-logind[290]: Got message type=signal sender=:1.0
destination=n/a
path=/org/freedesktop/systemd1/unit/dev_2ddisk_2dby_5cx2did_2dusb_5cx2dQEMU_5fQEMU_5fHARDDISK_5f1_5cx2d0000_3a00_3a03_2e0_5cx2d3_5cx2d0_3a0_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=298 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd-logind[290]: Got message type=signal sender=:1.0
destination=n/a
path=/org/freedesktop/systemd1/unit/dev_2ddisk_2dby_5cx2did_2dusb_5cx2dQEMU_5fQEMU_5fHARDDISK_5f1_5cx2d0000_3a00_3a03_2e0_5cx2d3_5cx2d0_3a0_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=299 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd-logind[290]: Got message type=signal sender=:1.0
destination=n/a
path=/org/freedesktop/systemd1/unit/dev_2ddisk_2dby_5cx2dpath_2dpci_5cx2d0000_3a00_3a03_2e0_5cx2dusb_5cx2d0_3a3_3a1_2e0_5cx2dscsi_5cx2d0_3a0_3a0_3a0_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=300 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd-logind[290]: Got message type=signal sender=:1.0
destination=n/a
path=/org/freedesktop/systemd1/unit/dev_2ddisk_2dby_5cx2dpath_2dpci_5cx2d0000_3a00_3a03_2e0_5cx2dusb_5cx2d0_3a3_3a1_2e0_5cx2dscsi_5cx2d0_3a0_3a0_3a0_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=301 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd-logind[290]: Got message type=signal sender=:1.0
destination=n/a path=/org/freedesktop/systemd1
interface=org.freedesktop.systemd1.Manager member=JobRemoved cookie=306
reply_cookie=0 signature=uoss error-name=n/a error-message=n/a
00:00:10 systemd-logind[290]: Got message type=signal sender=:1.0
destination=n/a path=/org/freedesktop/systemd1
interface=org.freedesktop.systemd1.Manager member=JobRemoved cookie=307
reply_cookie=0 signature=uoss error-name=n/a error-message=n/a
00:00:10 systemd-logind[290]: Got message type=signal sender=:1.0
destination=n/a path=/org/freedesktop/systemd1
interface=org.freedesktop.systemd1.Manager member=JobRemoved cookie=308
reply_cookie=0 signature=uoss error-name=n/a error-message=n/a
00:00:10 systemd-logind[290]: Got message type=signal sender=:1.0
destination=n/a path=/org/freedesktop/systemd1
interface=org.freedesktop.systemd1.Manager member=JobRemoved cookie=309
reply_cookie=0 signature=uoss error-name=n/a error-message=n/a
00:00:10 systemd-logind[290]: Got message type=signal sender=:1.0
destination=n/a
path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dpci0000_3a00_2d0000_3a00_3a03_2e0_2dusb2_2d2_5cx2d3_2d2_5cx2d3_3a1_2e0_2dhost6_2dtarget6_3a0_3a0_2d6_3a0_3a0_3a0_2dblock_2dsdb_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=310 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd-logind[290]: Got message type=signal sender=:1.0
destination=n/a
path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dpci0000_3a00_2d0000_3a00_3a03_2e0_2dusb2_2d2_5cx2d3_2d2_5cx2d3_3a1_2e0_2dhost6_2dtarget6_3a0_3a0_2d6_3a0_3a0_3a0_2dblock_2dsdb_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=311 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd-logind[290]: Got message type=signal sender=:1.0
destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dsdb_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=312 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd-logind[290]: Got message type=signal sender=:1.0
destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dsdb_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=313 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd-logind[290]: Got message type=signal sender=:1.0
destination=n/a
path=/org/freedesktop/systemd1/unit/dev_2ddisk_2dby_5cx2dpath_2dpci_5cx2d0000_3a00_3a03_2e0_5cx2dusb_5cx2d0_3a3_3a1_2e0_5cx2dscsi_5cx2d0_3a0_3a0_3a0_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=314 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd-logind[290]: Got message type=signal sender=:1.0
destination=n/a
path=/org/freedesktop/systemd1/unit/dev_2ddisk_2dby_5cx2dpath_2dpci_5cx2d0000_3a00_3a03_2e0_5cx2dusb_5cx2d0_3a3_3a1_2e0_5cx2dscsi_5cx2d0_3a0_3a0_3a0_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=315 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd-logind[290]: Got message type=signal sender=:1.0
destination=n/a
path=/org/freedesktop/systemd1/unit/dev_2ddisk_2dby_5cx2did_2dusb_5cx2dQEMU_5fQEMU_5fHARDDISK_5f1_5cx2d0000_3a00_3a03_2e0_5cx2d3_5cx2d0_3a0_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=316 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd-logind[290]: Got message type=signal sender=:1.0
destination=n/a
path=/org/freedesktop/systemd1/unit/dev_2ddisk_2dby_5cx2did_2dusb_5cx2dQEMU_5fQEMU_5fHARDDISK_5f1_5cx2d0000_3a00_3a03_2e0_5cx2d3_5cx2d0_3a0_2edevice
interface=org.freedesktop.DBus.Properties member=PropertiesChanged
cookie=317 reply_cookie=0 signature=sa{sv}as error-name=n/a
error-message=n/a
00:00:10 systemd-udevd[988]: sdb: sd-device-monitor: Passed 901 byte to
netlink monitor
00:00:10 systemd-udevd[216]: 6:0:0:0: Device ready for processing
(SEQNUM=2271, ACTION=bind)
00:00:10 systemd-udevd[216]: 6:0:0:0: sd-device-monitor: Passed 241 byte to
netlink monitor
00:00:10 systemd-udevd[988]: 6:0:0:0: Processing device (SEQNUM=2271,
ACTION=bind)
00:00:10 systemd-udevd[988]: 6:0:0:0:
/usr/lib/udev/rules.d/50-udev-default.rules:14 Importing properties from
results of builtin command 'hwdb --subsystem=scsi'
00:00:10 systemd-udevd[988]: 6:0:0:0: hwdb modalias key: "scsi:t-0x00"
00:00:10 systemd-udevd[988]: 6:0:0:0: No entry found from hwdb.
00:00:10 systemd-udevd[988]: 6:0:0:0:
/usr/lib/udev/rules.d/50-udev-default.rules:14 Failed to run builtin 'hwdb
--subsystem=scsi': No data available
00:00:10 systemd-udevd[988]: 6:0:0:0: Device processed (SEQNUM=2271,
ACTION=bind)
00:00:10 systemd-udevd[988]: 6:0:0:0: sd-device-monitor: Passed 241 byte to
netlink monitor
00:00:13 systemd[1]: systemd-journald.service: Got notification message
from PID 200 (FDSTORE=1)
00:00:13 systemd[1]: systemd-journald.service: Added fd 61 (n/a) to fd
store.
00:00:13 cat[1005]: script running...
00:00:13 systemd[1]: Received SIGCHLD from PID 1010 (qemu-nbd).
00:00:13 systemd[1]: Child 1010 (qemu-nbd) died (code=exited,
status=0/SUCCESS)
00:00:13 systemd[1]: session-1.scope: Child 1010 belongs to session-1.scope.
00:00:13 cat[1005]: script performed.
00:00:13 systemd[1]: systemd-journald.service: Received EPOLLHUP on stored
fd 61 (stored), closing.
00:00:13 systemd-udevd[216]: nbd0: Inotify event: 8 for /dev/nbd0
00:00:13 systemd-udevd[216]: nbd0: device is closed, synthesising 'change'
on /sys/devices/virtual/block/nbd0
00:00:13 systemd-udevd[216]: nbd0: Device is queued (SEQNUM=2272,
ACTION=change)
00:00:13 systemd-udevd[216]: Validate module index
00:00:13 systemd-udevd[216]: Check if link configuration needs reloading.
00:00:13 systemd-udevd[216]: nbd0: Device ready for processing
(SEQNUM=2272, ACTION=change)
00:00:13 systemd-udevd[216]: nbd0: sd-device-monitor: Passed 206 byte to
netlink monitor
00:00:13 systemd-udevd[988]: nbd0: Processing device (SEQNUM=2272,
ACTION=change)
00:00:13 systemd-udevd[988]: nbd0: Removing watch
00:00:13 systemd-udevd[988]: nbd0:
/usr/lib/udev/rules.d/60-persistent-storage.rules:110 Importing properties
from results of builtin command 'blkid'
00:00:13 systemd-udevd[988]: nbd0: Probe /dev/nbd0 with raid and offset=0
00:00:13 systemd-udevd[988]: nbd0: /usr/lib/udev/rules.d/69-vdev.rules:5
Importing properties from results of '/usr/lib/udev/vdev_id -d nbd0'
00:00:13 systemd-udevd[988]: nbd0: Starting '/usr/lib/udev/vdev_id -d nbd0'
00:00:13 systemd-udevd[988]: Successfully forked off '(spawn)' as PID 1022.
00:00:13 systemd-udevd[216]: Failed to create sd_device object from watch
handle, ignoring: No such file or directory
00:00:13 systemd-udevd[988]: nbd0: '/usr/lib/udev/vdev_id -d nbd0'(out)
'Error: Config file "/etc/zfs/vdev_id.conf" not found'
00:00:13 systemd-udevd[988]: nbd0: Process '/usr/lib/udev/vdev_id -d nbd0'
failed with exit code 1.
00:00:13 systemd-udevd[988]: nbd0: /usr/lib/udev/rules.d/69-vdev.rules:5
Command "/usr/lib/udev/vdev_id -d nbd0" returned 1 (error), ignoring
00:00:13 systemd-udevd[988]: nbd0: Handling device node '/dev/nbd0',
devnum=b43:0
00:00:13 systemd-udevd[988]: nbd0: Preserve already existing symlink
'/dev/block/43:0' to '../nbd0'
00:00:13 systemd-udevd[988]: nbd0: sd-device: Created db file
'/run/udev/data/b43:0' for '/devices/virtual/block/nbd0'
00:00:13 systemd-udevd[988]: nbd0: Handling device node '/dev/nbd0',
devnum=b43:0
00:00:13 systemd-udevd[988]: nbd0: Preserve already existing symlink
'/dev/block/43:0' to '../nbd0'
00:00:13 systemd-udevd[988]: nbd0: Adding watch on '/dev/nbd0'
00:00:13 systemd-udevd[988]: nbd0: Device processed (SEQNUM=2272,
ACTION=change)
00:00:13 systemd-udevd[988]: nbd0: sd-device-monitor: Passed 260 byte to
netlink monitor
00:00:13 systemd-udevd[216]: nbd0: Device is queued (SEQNUM=2273,
ACTION=change)
00:00:13 systemd-udevd[216]: nbd0: Device ready for processing
(SEQNUM=2273, ACTION=change)
00:00:13 systemd-udevd[216]: nbd0: sd-device-monitor: Passed 193 byte to
netlink monitor
00:00:13 systemd-udevd[988]: nbd0: Processing device (SEQNUM=2273,
ACTION=change)
00:00:13 systemd-udevd[988]: nbd0: Removing watch
00:00:13 systemd-udevd[216]: nbd0: Inotify event: 8000 for /dev/nbd0
00:00:13 systemd-udevd[988]: nbd0:
/usr/lib/udev/rules.d/60-persistent-storage.rules:110 Importing properties
from results of builtin command 'blkid'
00:00:13 systemd-udevd[988]: nbd0: Probe /dev/nbd0 with raid and offset=0
00:00:13 systemd-udevd[988]: nbd0: /usr/lib/udev/rules.d/69-vdev.rules:5
Importing properties from results of '/usr/lib/udev/vdev_id -d nbd0'
00:00:13 systemd-udevd[988]: nbd0: Starting '/usr/lib/udev/vdev_id -d nbd0'
00:00:13 systemd-udevd[988]: Successfully forked off '(spawn)' as PID 1024.
00:00:13 systemd-udevd[988]: nbd0: '/usr/lib/udev/vdev_id -d nbd0'(out)
'Error: Config file "/etc/zfs/vdev_id.conf" not found'
00:00:13 systemd-udevd[988]: nbd0: Process '/usr/lib/udev/vdev_id -d nbd0'
failed with exit code 1.
00:00:13 systemd-udevd[988]: nbd0: /usr/lib/udev/rules.d/69-vdev.rules:5
Command "/usr/lib/udev/vdev_id -d nbd0" returned 1 (error), ignoring
00:00:13 systemd-udevd[988]: nbd0: Handling device node '/dev/nbd0',
devnum=b43:0
00:00:13 systemd-udevd[988]: nbd0: Preserve already existing symlink
'/dev/block/43:0' to '../nbd0'
00:00:13 systemd-udevd[988]: nbd0: sd-device: Created db file
'/run/udev/data/b43:0' for '/devices/virtual/block/nbd0'
00:00:13 systemd-udevd[988]: nbd0: Handling device node '/dev/nbd0',
devnum=b43:0
00:00:13 systemd-udevd[988]: nbd0: Preserve already existing symlink
'/dev/block/43:0' to '../nbd0'
00:00:13 systemd-udevd[988]: nbd0: Adding watch on '/dev/nbd0'
00:00:13 systemd-udevd[988]: nbd0: Device processed (SEQNUM=2273,
ACTION=change)
00:00:13 systemd[295]: dev-nbd0.device: Changed dead -> plugged
00:00:13 systemd[295]: sys-devices-virtual-block-nbd0.device: Changed dead
-> plugged
00:00:13 systemd[1]: dev-nbd0.device: Changed dead -> plugged
00:00:13 systemd[1]: sys-devices-virtual-block-nbd0.device: Changed dead ->
plugged
00:00:13 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager
member=UnitNew cookie=318 reply_cookie=0 signature=so error-name=n/a
error-message=n/a
00:00:13 systemd[1]: Sent message type=signal sender=n/a destination=n/a
path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager
member=UnitNew cookie=319 reply_cookie=0 signature=so error-name=n/a
error-message=n/a
00:00:13 systemd-udevd[988]: nbd0: sd-device-monitor: Passed 231 byte to
netlink monitor
00:00:16 systemd-udevd[216]: Cleanup idle workers
00:00:16 systemd-udevd[985]: Unload module index
00:00:16 systemd-udevd[985]: Unloaded link configuration context.
00:00:16 systemd-udevd[216]: Worker [985] exited
00:00:16 systemd-udevd[988]: Unload module index
00:00:16 systemd-udevd[988]: Unloaded link configuration context.
00:00:16 systemd-udevd[216]: Worker [988] exited
Le jeu. 19 mai 2022 à 17:09, Eric Blake <ebl...@redhat.com> a écrit :
> On Thu, May 19, 2022 at 04:40:27PM +0200, Pascal wrote:
> > hi,
> >
> > my system is a Linux (5.10.83) with SystemD (249.7) and Qemu (6.1.0) and
> > I've a poor small Bash script:
> >
> > #!/usr/bin/bash
> > systemd-cat -p warning <<< "script running..."
> > qemu-nbd -r -s -f raw -c /dev/nbd0 /dev/sdb |& systemd-cat -p warning
> >
> > after loading nbd module, if I plug a blank disk (/dev/sdb with no
> > partition) and call the script (as root) then all is ok and my disk is
> > accessible via /dev/nbd0.
> >
> > after rebooting and reloading nbd module, I install a Udev rule that
> calls
> > my script when a device is added to the system, plug the disk and observe
> > the system events with journalctl -f.
> > the script is called (eg. script running...) and /dev/nbd0 seems to be
> > linked with /dev/sdb but 2 seconds later, the message kernel: block nbd0:
> > shutting down sockets appears and /dev/nbd0 becomes unreachable.
> >
> > where can the problem come from ?
>
> git grep 'shutting down sockets'
>
> does not have any hits in qemu.git, so the message is coming from the
> kernel module, and not from the qemu-nbd side of things. Is the
> qemu-nbd process still alive when you see the error message? Could it
> be a permissions issue, where running your script via a udev rule
> creates sockets with different permissions than when running the
> script as root, such that the qemu-nbd process is locked out of access
> to sockets needed to drive the kernel module?
>
> Can you add a --trace parameter to qemu-nbd to cause it to log more
> details about what is going on?
>
> --
> Eric Blake, Principal Software Engineer
> Red Hat, Inc. +1-919-301-3266
> Virtualization: qemu.org | libvirt.org
>
>
