Re: kernel: block nbd0: shutting down sockets

[Pascal]

from man udev :

Starting daemons or other long-running processes is not allowed; the forked processes, detached or not, will be unconditionally killed after the event handling has finished. In order to activate
long-running processes from udev rules, provide a service unit and pull it in from a udev device using the SYSTEMD_WANTS device property. See systemd.device(5) for details.

here is the main cause of the problem : sorry for all the fuss.

I played the same way with loop (losetup) and did not encounter the same problem since the script did not leave any children in place.

I'm going to search on my own to get the desired result but if someone has a ready-made answer, I'll take it ! :-)

regards, lacsaP.

Le ven. 20 mai 2022 à 16:04, Pascal <patatetom@gmail.com> a écrit :

qemu-nbd is killed :

00:02:19 systemd[1]: Child 962 (qemu-nbd) died (code=killed, status=9/KILL)

and probably by systemd which, when cleaning up after the script, considers qemu-nbd as a child process of the script which is no longer needed (the 9/KILL explains that the socket remains present in /var/lock/)...

does anyone know how to detach qemu-nbd from the script so that it is not considered a residue to be killed ?

regards, lacsaP.

Le ven. 20 mai 2022 à 15:10, Pascal <patatetom@gmail.com> a écrit :

here is the journalctl extract of the system started with the debug kernel parameter :

00:02:14 kernel: usb 2-4: new SuperSpeed Gen 1 USB device number 3 using xhci_hcd
00:02:14 systemd-udevd[216]: 2-4: Device is queued (SEQNUM=2218, ACTION="">00:02:14 systemd-udevd[216]: Validate module index
00:02:14 systemd-udevd[216]: Check if link configuration needs reloading.
00:02:14 systemd-udevd[216]: 2-4: Device ready for processing (SEQNUM=2218, ACTION="">00:02:14 systemd-udevd[216]: Successfully forked off 'n/a' as PID 942.
00:02:14 systemd-udevd[216]: 2-4: Worker [942] is forked for processing SEQNUM=2218.
00:02:14 systemd-udevd[216]: 2-4:1.0: Device is queued (SEQNUM=2219, ACTION="">00:02:14 systemd-udevd[216]: 2-4:1.0: SEQNUM=2219 blocked by SEQNUM=2218
00:02:14 systemd[1]: systemd-udevd.service: Got notification message from PID 216 (WATCHDOG=1)
00:02:14 systemd-udevd[942]: 2-4: Processing device (SEQNUM=2218, ACTION="">00:02:14 systemd-udevd[942]: 2-4: Failed to get watch handle, ignoring: No such file or directory
00:02:14 systemd-udevd[942]: 2-4: /usr/lib/udev/rules.d/50-udev-default.rules:13 Importing properties from results of builtin command 'usb_id'
00:02:14 systemd-udevd[216]: scsi_tmf_6: Device is queued (SEQNUM=2220, ACTION="">00:02:14 systemd-udevd[216]: scsi_tmf_6: Device ready for processing (SEQNUM=2220, ACTION="">00:02:14 systemd-udevd[216]: Successfully forked off 'n/a' as PID 945.
00:02:14 systemd-udevd[216]: scsi_tmf_6: Worker [945] is forked for processing SEQNUM=2220.
00:02:14 systemd-udevd[945]: scsi_tmf_6: Processing device (SEQNUM=2220, ACTION="">00:02:14 systemd-udevd[945]: scsi_tmf_6: Device processed (SEQNUM=2220, ACTION="">00:02:14 systemd-udevd[945]: scsi_tmf_6: sd-device-monitor: Passed 156 byte to netlink monitor
00:02:14 kernel: usb 2-4: New USB device found, idVendor=46f4, idProduct=0001, bcdDevice= 0.00
00:02:14 kernel: usb 2-4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
00:02:14 kernel: usb 2-4: Product: QEMU USB HARDDRIVE
00:02:14 kernel: usb 2-4: Manufacturer: QEMU
00:02:14 kernel: usb 2-4: SerialNumber: 1-0000:00:03.0-4
00:02:14 kernel: usb-storage 2-4:1.0: USB Mass Storage device detected
00:02:14 kernel: scsi host6: usb-storage 2-4:1.0
00:02:14 systemd-udevd[216]: host6: Device is queued (SEQNUM=2221, ACTION="">00:02:14 systemd-udevd[216]: host6: SEQNUM=2221 blocked by SEQNUM=2218
00:02:14 systemd-udevd[216]: host6: Device is queued (SEQNUM=2222, ACTION="">00:02:14 systemd-udevd[216]: host6: SEQNUM=2222 blocked by SEQNUM=2218
00:02:14 systemd-udevd[216]: 2-4:1.0: Device is queued (SEQNUM=2223, ACTION="">00:02:14 systemd-udevd[216]: 2-4:1.0: SEQNUM=2223 blocked by SEQNUM=2218
00:02:14 systemd-udevd[216]: 2-4: Device is queued (SEQNUM=2224, ACTION="">00:02:14 systemd-udevd[216]: 2-4: SEQNUM=2224 blocked by SEQNUM=2218
00:02:14 systemd-udevd[942]: 2-4: /usr/lib/udev/rules.d/50-udev-default.rules:13 Importing properties from results of builtin command 'hwdb --subsystem=usb'
00:02:14 systemd-udevd[942]: 2-4: hwdb modalias key: "usb:v46F4p0001:QEMU USB HARDDRIVE"
00:02:14 systemd-udevd[942]: 2-4: /usr/lib/udev/rules.d/50-udev-default.rules:50 MODE 0664
00:02:14 systemd-udevd[942]: 2-4: /usr/lib/udev/rules.d/60-drm.rules:3 Importing properties from results of builtin command 'path_id'
00:02:14 systemd-udevd[942]: 2-4: /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-4 2 3'
00:02:14 systemd-udevd[942]: 2-4: Starting '/usr/lib/udev/mtp-probe /sys/devices/pci0000:00/0000:00:03.0/usb2/2-4 2 3'
00:02:14 systemd-udevd[942]: Successfully forked off '(spawn)' as PID 947.
00:02:14 mtp-probe[947]: checking bus 2, device 3: "/sys/devices/pci0000:00/0000:00:03.0/usb2/2-4"
00:02:14 mtp-probe[947]: bus: 2, device: 3 was not an MTP device
00:02:14 systemd-udevd[942]: 2-4: '/usr/lib/udev/mtp-probe /sys/devices/pci0000:00/0000:00:03.0/usb2/2-4 2 3'(out) '0'
00:02:14 systemd-udevd[942]: 2-4: Process '/usr/lib/udev/mtp-probe /sys/devices/pci0000:00/0000:00:03.0/usb2/2-4 2 3' succeeded.
00:02:14 systemd-udevd[942]: 2-4: Handling device node '/dev/bus/usb/002/003', devnum=c189:130
00:02:14 systemd-udevd[942]: 2-4: Setting permissions /dev/bus/usb/002/003, uid=0, gid=0, mode=0664
00:02:14 systemd-udevd[942]: 2-4: Creating symlink '/dev/char/189:130' to '../bus/usb/002/003'
00:02:14 systemd-udevd[942]: 2-4: sd-device: Created db file '/run/udev/data/c189:130' for '/devices/pci0000:00/0000:00:03.0/usb2/2-4'
00:02:14 systemd-udevd[942]: 2-4: Handling device node '/dev/bus/usb/002/003', devnum=c189:130
00:02:14 systemd-udevd[942]: 2-4: Preserve permissions of /dev/bus/usb/002/003, uid=0, gid=0, mode=0664
00:02:14 systemd-udevd[942]: 2-4: Preserve already existing symlink '/dev/char/189:130' to '../bus/usb/002/003'
00:02:14 systemd-udevd[942]: 2-4: Failed to get watch handle, ignoring: No such file or directory
00:02:14 systemd-udevd[942]: 2-4: Device processed (SEQNUM=2218, ACTION="">00:02:14 systemd-udevd[942]: 2-4: sd-device-monitor: Passed 718 byte to netlink monitor
00:02:14 systemd-udevd[216]: 2-4:1.0: Device ready for processing (SEQNUM=2219, ACTION="">00:02:14 systemd-udevd[216]: 2-4:1.0: sd-device-monitor: Passed 288 byte to netlink monitor
00:02:14 systemd-udevd[216]: host6: SEQNUM=2221 blocked by SEQNUM=2219
00:02:14 systemd-udevd[216]: host6: SEQNUM=2222 blocked by SEQNUM=2219
00:02:14 systemd-udevd[216]: 2-4:1.0: SEQNUM=2223 blocked by SEQNUM=2219
00:02:14 systemd-udevd[216]: 2-4: SEQNUM=2224 blocked by SEQNUM=2219
00:02:14 systemd-udevd[945]: 2-4:1.0: Processing device (SEQNUM=2219, ACTION="">00:02:14 systemd-udevd[945]: 2-4:1.0: /usr/lib/udev/rules.d/50-udev-default.rules:14 Importing properties from results of builtin command 'hwdb --subsystem=usb'
00:02:14 systemd-udevd[945]: 2-4:1.0: hwdb modalias key: "usb:v46F4p0001d0000dc00dsc00dp00ic08isc06ip50in00"
00:02:14 systemd-udevd[945]: 2-4:1.0: /usr/lib/udev/rules.d/60-drm.rules:3 Importing properties from results of builtin command 'path_id'
00:02:14 systemd-udevd[945]: 2-4:1.0: /usr/lib/udev/rules.d/80-drivers.rules:5 RUN 'kmod load '$env{MODALIAS}''
00:02:14 systemd-udevd[945]: 2-4:1.0: sd-device: Created db file '/run/udev/data/+usb:2-4:1.0' for '/devices/pci0000:00/0000:00:03.0/usb2/2-4/2-4:1.0'
00:02:14 systemd-udevd[945]: 2-4:1.0: Running built-in command "kmod load 'usb:v46F4p0001d0000dc00dsc00dp00ic08isc06ip50in00'"
00:02:14 systemd-udevd[945]: Loading module: usb:v46F4p0001d0000dc00dsc00dp00ic08isc06ip50in00
00:02:14 systemd-udevd[945]: Module 'uas' is already loaded
00:02:14 systemd-udevd[945]: Module 'usb_storage' is already loaded
00:02:14 systemd-udevd[945]: 2-4:1.0: Device processed (SEQNUM=2219, ACTION="">00:02:14 systemd-udevd[945]: 2-4:1.0: sd-device-monitor: Passed 395 byte to netlink monitor
00:02:14 systemd-udevd[216]: host6: Device ready for processing (SEQNUM=2221, ACTION="">00:02:14 systemd-udevd[216]: host6: sd-device-monitor: Passed 187 byte to netlink monitor
00:02:14 systemd-udevd[216]: host6: SEQNUM=2222 blocked by SEQNUM=2221
00:02:14 systemd-udevd[216]: 2-4:1.0: SEQNUM=2223 blocked by SEQNUM=2221
00:02:14 systemd-udevd[216]: 2-4: SEQNUM=2224 blocked by SEQNUM=2221
00:02:14 systemd-udevd[945]: host6: Processing device (SEQNUM=2221, ACTION="">00:02:14 systemd-udevd[945]: host6: Device processed (SEQNUM=2221, ACTION="">00:02:14 systemd-udevd[945]: host6: sd-device-monitor: Passed 187 byte to netlink monitor
00:02:14 systemd-udevd[216]: host6: Device ready for processing (SEQNUM=2222, ACTION="">00:02:14 systemd-udevd[216]: host6: sd-device-monitor: Passed 190 byte to netlink monitor
00:02:14 systemd-udevd[216]: 2-4:1.0: SEQNUM=2223 blocked by SEQNUM=2222
00:02:14 systemd-udevd[216]: 2-4: SEQNUM=2224 blocked by SEQNUM=2222
00:02:14 systemd-udevd[945]: host6: Processing device (SEQNUM=2222, ACTION="">00:02:14 systemd-udevd[945]: host6: Device processed (SEQNUM=2222, ACTION="">00:02:14 systemd-udevd[945]: host6: sd-device-monitor: Passed 190 byte to netlink monitor
00:02:14 systemd-udevd[216]: 2-4:1.0: Device ready for processing (SEQNUM=2223, ACTION="">00:02:14 systemd-udevd[216]: 2-4:1.0: sd-device-monitor: Passed 308 byte to netlink monitor
00:02:14 systemd-udevd[216]: 2-4: SEQNUM=2224 blocked by SEQNUM=2223
00:02:14 systemd-udevd[945]: 2-4:1.0: Processing device (SEQNUM=2223, ACTION="">00:02:14 systemd-udevd[945]: 2-4:1.0: /usr/lib/udev/rules.d/50-udev-default.rules:14 Importing properties from results of builtin command 'hwdb --subsystem=usb'
00:02:14 systemd-udevd[945]: 2-4:1.0: hwdb modalias key: "usb:v46F4p0001d0000dc00dsc00dp00ic08isc06ip50in00"
00:02:14 systemd-udevd[945]: 2-4:1.0: /usr/lib/udev/rules.d/60-drm.rules:3 Importing properties from results of builtin command 'path_id'
00:02:14 systemd-udevd[945]: 2-4:1.0: sd-device: Created db file '/run/udev/data/+usb:2-4:1.0' for '/devices/pci0000:00/0000:00:03.0/usb2/2-4/2-4:1.0'
00:02:14 systemd-udevd[945]: 2-4:1.0: Device processed (SEQNUM=2223, ACTION="">00:02:14 systemd-udevd[945]: 2-4:1.0: sd-device-monitor: Passed 415 byte to netlink monitor
00:02:14 systemd-udevd[216]: 2-4: Device ready for processing (SEQNUM=2224, ACTION="">00:02:14 systemd-udevd[216]: 2-4: sd-device-monitor: Passed 284 byte to netlink monitor
00:02:14 systemd-udevd[945]: 2-4: Processing device (SEQNUM=2224, ACTION="">00:02:14 systemd-udevd[945]: 2-4: Failed to get watch handle, ignoring: No such file or directory
00:02:14 systemd-udevd[945]: 2-4: /usr/lib/udev/rules.d/50-udev-default.rules:13 Importing properties from results of builtin command 'usb_id'
00:02:14 systemd-udevd[945]: 2-4: /usr/lib/udev/rules.d/50-udev-default.rules:13 Importing properties from results of builtin command 'hwdb --subsystem=usb'
00:02:14 systemd-udevd[945]: 2-4: hwdb modalias key: "usb:v46F4p0001:QEMU USB HARDDRIVE"
00:02:14 systemd-udevd[945]: 2-4: /usr/lib/udev/rules.d/60-drm.rules:3 Importing properties from results of builtin command 'path_id'
00:02:14 systemd-udevd[945]: 2-4: /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-4 2 3'
00:02:14 systemd-udevd[945]: 2-4: Starting '/usr/lib/udev/mtp-probe /sys/devices/pci0000:00/0000:00:03.0/usb2/2-4 2 3'
00:02:14 systemd-udevd[945]: Successfully forked off '(spawn)' as PID 948.
00:02:14 mtp-probe[948]: checking bus 2, device 3: "/sys/devices/pci0000:00/0000:00:03.0/usb2/2-4"
00:02:14 mtp-probe[948]: bus: 2, device: 3 was not an MTP device
00:02:14 systemd-udevd[945]: 2-4: '/usr/lib/udev/mtp-probe /sys/devices/pci0000:00/0000:00:03.0/usb2/2-4 2 3'(out) '0'
00:02:14 systemd-udevd[945]: 2-4: Process '/usr/lib/udev/mtp-probe /sys/devices/pci0000:00/0000:00:03.0/usb2/2-4 2 3' succeeded.
00:02:14 systemd-udevd[945]: 2-4: Handling device node '/dev/bus/usb/002/003', devnum=c189:130
00:02:14 systemd-udevd[945]: 2-4: Preserve already existing symlink '/dev/char/189:130' to '../bus/usb/002/003'
00:02:14 systemd-udevd[945]: 2-4: sd-device: Created db file '/run/udev/data/c189:130' for '/devices/pci0000:00/0000:00:03.0/usb2/2-4'
00:02:14 systemd-udevd[945]: 2-4: Handling device node '/dev/bus/usb/002/003', devnum=c189:130
00:02:14 systemd-udevd[945]: 2-4: Preserve already existing symlink '/dev/char/189:130' to '../bus/usb/002/003'
00:02:14 systemd-udevd[945]: 2-4: Failed to get watch handle, ignoring: No such file or directory
00:02:14 systemd-udevd[945]: 2-4: Device processed (SEQNUM=2224, ACTION="">00:02:14 systemd-udevd[945]: 2-4: sd-device-monitor: Passed 730 byte to netlink monitor
00:02:15 systemd-udevd[216]: target6:0:0: Device is queued (SEQNUM=2225, ACTION="">00:02:15 systemd-udevd[216]: target6:0:0: Device ready for processing (SEQNUM=2225, ACTION="">00:02:15 systemd-udevd[216]: target6:0:0: sd-device-monitor: Passed 201 byte to netlink monitor
00:02:15 systemd-udevd[216]: 6:0:0:0: Device is queued (SEQNUM=2226, ACTION="">00:02:15 systemd-udevd[216]: 6:0:0:0: SEQNUM=2226 blocked by SEQNUM=2225
00:02:15 systemd-udevd[216]: 6:0:0:0: Device is queued (SEQNUM=2227, ACTION="">00:02:15 systemd-udevd[216]: 6:0:0:0: SEQNUM=2227 blocked by SEQNUM=2225
00:02:15 systemd-udevd[945]: target6:0:0: Processing device (SEQNUM=2225, ACTION="">00:02:15 systemd-udevd[945]: target6:0:0: Device processed (SEQNUM=2225, ACTION="">00:02:15 systemd-udevd[945]: target6:0:0: sd-device-monitor: Passed 201 byte to netlink monitor
00:02:15 kernel: scsi 6:0:0:0: Direct-Access     QEMU     QEMU HARDDISK    2.5+ PQ: 0 ANSI: 5
00:02:15 kernel: sd 6:0:0:0: Power-on or device reset occurred
00:02:15 kernel: sd 6:0:0:0: [sdb] 524288 512-byte logical blocks: (268 MB/256 MiB)
00:02:15 kernel: sd 6:0:0:0: [sdb] Write Protect is off
00:02:15 kernel: sd 6:0:0:0: [sdb] Mode Sense: 63 00 00 08
00:02:15 kernel: sd 6:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
00:02:15 systemd-udevd[216]: 6:0:0:0: Device ready for processing (SEQNUM=2226, ACTION="">00:02:15 systemd-udevd[216]: 6:0:0:0: sd-device-monitor: Passed 230 byte to netlink monitor
00:02:15 systemd-udevd[216]: 6:0:0:0: SEQNUM=2227 blocked by SEQNUM=2226
00:02:15 systemd-udevd[216]: 6:0:0:0: Device is queued (SEQNUM=2228, ACTION="">00:02:15 systemd-udevd[216]: 6:0:0:0: SEQNUM=2228 blocked by SEQNUM=2226
00:02:15 systemd-udevd[216]: 6:0:0:0: Device is queued (SEQNUM=2229, ACTION="">00:02:15 systemd-udevd[216]: 6:0:0:0: SEQNUM=2229 blocked by SEQNUM=2226
00:02:15 systemd-udevd[216]: 6:0:0:0: Device is queued (SEQNUM=2230, ACTION="">00:02:15 systemd-udevd[216]: 6:0:0:0: SEQNUM=2230 blocked by SEQNUM=2226
00:02:15 systemd-udevd[216]: 8:16: Device is queued (SEQNUM=2231, ACTION="">00:02:15 systemd-udevd[216]: 8:16: Device ready for processing (SEQNUM=2231, ACTION="">00:02:15 systemd-udevd[942]: 8:16: Processing device (SEQNUM=2231, ACTION="">00:02:15 systemd-udevd[942]: 8:16: Device processed (SEQNUM=2231, ACTION="">00:02:15 systemd-udevd[942]: 8:16: sd-device-monitor: Passed 138 byte to netlink monitor
00:02:15 systemd-udevd[945]: 6:0:0:0: Processing device (SEQNUM=2226, ACTION="">00:02:15 systemd-udevd[945]: 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:02:15 systemd-udevd[945]: 6:0:0:0: hwdb modalias key: "scsi:t-0x00"
00:02:15 systemd-udevd[945]: 6:0:0:0: No entry found from hwdb.
00:02:15 systemd-udevd[945]: 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:02:15 systemd-udevd[945]: 6:0:0:0: /usr/lib/udev/rules.d/80-drivers.rules:5 RUN 'kmod load '$env{MODALIAS}''
00:02:15 systemd-udevd[945]: 6:0:0:0: Running built-in command "kmod load 'scsi:t-0x00'"
00:02:15 systemd-udevd[945]: Loading module: scsi:t-0x00
00:02:15 systemd-udevd[945]: Module 'sd_mod' is built in
00:02:15 systemd-udevd[945]: 6:0:0:0: Device processed (SEQNUM=2226, ACTION="">00:02:15 systemd-udevd[945]: 6:0:0:0: sd-device-monitor: Passed 230 byte to netlink monitor
00:02:15 systemd-udevd[216]: 8:16: sd-device-monitor: Passed 138 byte to netlink monitor
00:02:15 systemd-udevd[216]: 6:0:0:0: Device ready for processing (SEQNUM=2227, ACTION="">00:02:15 systemd-udevd[216]: 6:0:0:0: sd-device-monitor: Passed 216 byte to netlink monitor
00:02:15 systemd-udevd[216]: 6:0:0:0: Device ready for processing (SEQNUM=2228, ACTION="">00:02:15 systemd-udevd[942]: 6:0:0:0: Processing device (SEQNUM=2228, ACTION="">00:02:15 systemd-udevd[942]: 6:0:0:0: Failed to get watch handle, ignoring: No such file or directory
00:02:15 systemd-udevd[942]: 6:0:0:0: Handling device node '/dev/bsg/6:0:0:0', devnum=c243:2
00:02:15 systemd-udevd[942]: 6:0:0:0: Preserve permissions of /dev/bsg/6:0:0:0, uid=0, gid=0, mode=0600
00:02:15 systemd-udevd[942]: 6:0:0:0: Creating symlink '/dev/char/243:2' to '../bsg/6:0:0:0'
00:02:15 systemd-udevd[942]: 6:0:0:0: sd-device: Created empty file '/run/udev/data/c243:2' for '/devices/pci0000:00/0000:00:03.0/usb2/2-4/2-4:1.0/host6/target6:0:0/6:0:0:0/bsg/6:0:0:0'
00:02:15 systemd-udevd[942]: 6:0:0:0: Handling device node '/dev/bsg/6:0:0:0', devnum=c243:2
00:02:15 systemd-udevd[942]: 6:0:0:0: Preserve permissions of /dev/bsg/6:0:0:0, uid=0, gid=0, mode=0600
00:02:15 systemd-udevd[942]: 6:0:0:0: Preserve already existing symlink '/dev/char/243:2' to '../bsg/6:0:0:0'
00:02:15 systemd-udevd[942]: 6:0:0:0: Failed to get watch handle, ignoring: No such file or directory
00:02:15 systemd-udevd[942]: 6:0:0:0: Device processed (SEQNUM=2228, ACTION="">00:02:15 systemd-udevd[942]: 6:0:0:0: sd-device-monitor: Passed 243 byte to netlink monitor
00:02:15 systemd-udevd[216]: 6:0:0:0: sd-device-monitor: Passed 243 byte to netlink monitor
00:02:15 systemd-udevd[216]: 6:0:0:0: Device ready for processing (SEQNUM=2229, ACTION="">00:02:15 systemd-udevd[216]: Successfully forked off 'n/a' as PID 950.
00:02:15 systemd-udevd[216]: 6:0:0:0: Worker [950] is forked for processing SEQNUM=2229.
00:02:15 systemd-udevd[216]: 6:0:0:0: SEQNUM=2230 blocked by SEQNUM=2227
00:02:15 systemd-udevd[216]: sdb: Device is queued (SEQNUM=2232, ACTION="">00:02:15 systemd-udevd[216]: sdb: SEQNUM=2232 blocked by SEQNUM=2230
00:02:15 systemd-udevd[945]: 6:0:0:0: Processing device (SEQNUM=2227, ACTION="">00:02:15 systemd-udevd[945]: 6:0:0:0: Device processed (SEQNUM=2227, ACTION="">00:02:15 systemd-udevd[950]: 6:0:0:0: Processing device (SEQNUM=2229, ACTION="">00:02:15 systemd-udevd[945]: 6:0:0:0: sd-device-monitor: Passed 216 byte to netlink monitor
00:02:15 systemd-udevd[216]: 6:0:0:0: SEQNUM=2230 blocked by SEQNUM=2229
00:02:15 systemd-udevd[950]: 6:0:0:0: Device processed (SEQNUM=2229, ACTION="">00:02:15 systemd-udevd[950]: 6:0:0:0: sd-device-monitor: Passed 212 byte to netlink monitor
00:02:15 systemd-udevd[216]: 6:0:0:0: Device ready for processing (SEQNUM=2230, ACTION="">00:02:15 systemd-udevd[216]: 6:0:0:0: sd-device-monitor: Passed 275 byte to netlink monitor
00:02:15 systemd-udevd[945]: 6:0:0:0: Processing device (SEQNUM=2230, ACTION="">00:02:15 systemd-udevd[945]: 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:02:15 systemd-udevd[945]: 6:0:0:0: hwdb modalias key: "scsi:t-0x00"
00:02:15 systemd-udevd[945]: 6:0:0:0: No entry found from hwdb.
00:02:15 systemd-udevd[945]: 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:02:15 systemd-udevd[945]: 6:0:0:0: /usr/lib/udev/rules.d/60-block.rules:8 ATTR '/sys/devices/pci0000:00/0000:00:03.0/usb2/2-4/2-4:1.0/host6/target6:0:0/6:0:0:0/block/sdb/uevent' writing 'change'
00:02:15 systemd-udevd[216]: sdb: Device is queued (SEQNUM=2233, ACTION="">00:02:15 systemd-udevd[216]: sdb: SEQNUM=2233 blocked by SEQNUM=2230
00:02:15 systemd-udevd[945]: 6:0:0:0: Device processed (SEQNUM=2230, ACTION="">00:02:15 systemd-udevd[945]: 6:0:0:0: sd-device-monitor: Passed 275 byte to netlink monitor
00:02:15 systemd-udevd[216]: sdb: Device ready for processing (SEQNUM=2232, ACTION="">00:02:15 systemd-udevd[216]: sdb: sd-device-monitor: Passed 247 byte to netlink monitor
00:02:15 systemd-udevd[216]: sdb: SEQNUM=2233 blocked by SEQNUM=2232
00:02:15 systemd-udevd[945]: sdb: Processing device (SEQNUM=2232, ACTION="">00:02:15 systemd-udevd[945]: sdb: Failed to get watch handle, ignoring: No such file or directory
00:02:15 systemd-udevd[945]: sdb: /usr/lib/udev/rules.d/50-udev-default.rules:69 GROUP 995
00:02:15 systemd-udevd[945]: sdb: /usr/lib/udev/rules.d/60-persistent-storage.rules:60 Importing properties from results of 'ata_id --export /dev/sdb'
00:02:15 systemd-udevd[945]: sdb: Starting 'ata_id --export /dev/sdb'
00:02:15 systemd-udevd[216]: 6:0:0:0: Device is queued (SEQNUM=2234, ACTION="">00:02:15 systemd-udevd[216]: 6:0:0:0: SEQNUM=2234 blocked by SEQNUM=2232
00:02:15 systemd-udevd[945]: Successfully forked off '(spawn)' as PID 951.
00:02:15 systemd-udevd[945]: sdb: Process 'ata_id --export /dev/sdb' failed with exit code 2.
00:02:15 systemd-udevd[945]: sdb: /usr/lib/udev/rules.d/60-persistent-storage.rules:60 Command "ata_id --export /dev/sdb" returned 2 (error), ignoring
00:02:15 systemd-udevd[945]: sdb: /usr/lib/udev/rules.d/60-persistent-storage.rules:63 Importing properties from results of builtin command 'usb_id'
00:02:15 systemd-udevd[945]: 2-4:1.0: if_class:8 protocol:6
00:02:15 systemd-udevd[945]: sdb: /usr/lib/udev/rules.d/60-persistent-storage.rules:68 LINK 'disk/by-id/usb-QEMU_QEMU_HARDDISK_1-0000:00:03.0-4-0:0'
00:02:15 systemd-udevd[945]: sdb: /usr/lib/udev/rules.d/60-persistent-storage.rules:90 Importing properties from results of builtin command 'path_id'
00:02:15 systemd-udevd[945]: sdb: /usr/lib/udev/rules.d/60-persistent-storage.rules:92 LINK 'disk/by-path/pci-0000:00:03.0-usb-0:4:1.0-scsi-0:0:0:0'
00:02:15 systemd-udevd[945]: sdb: /usr/lib/udev/rules.d/60-persistent-storage.rules:110 Importing properties from results of builtin command 'blkid'
00:02:15 systemd-udevd[945]: sdb: Probe /dev/sdb with raid and offset=0
00:02:15 kernel: sd 6:0:0:0: [sdb] Attached SCSI disk
00:02:15 systemd-udevd[945]: sdb: /usr/lib/udev/rules.d/69-vdev.rules:5 Importing properties from results of '/usr/lib/udev/vdev_id -d sdb'
00:02:15 systemd-udevd[945]: sdb: Starting '/usr/lib/udev/vdev_id -d sdb'
00:02:15 systemd-udevd[945]: Successfully forked off '(spawn)' as PID 952.
00:02:15 systemd-udevd[945]: sdb: '/usr/lib/udev/vdev_id -d sdb'(out) 'Error: Config file "/etc/zfs/vdev_id.conf" not found'
00:02:15 systemd-udevd[945]: sdb: Process '/usr/lib/udev/vdev_id -d sdb' failed with exit code 1.
00:02:15 systemd-udevd[945]: sdb: /usr/lib/udev/rules.d/69-vdev.rules:5 Command "/usr/lib/udev/vdev_id -d sdb" returned 1 (error), ignoring
00:02:15 systemd-udevd[945]: sdb: /etc/udev/rules.d/99_sdb2nbd.rules:2 RUN '/usr/local/sbin/myscript'
00:02:15 systemd-udevd[945]: sdb: Handling device node '/dev/sdb', devnum=b8:16
00:02:15 systemd-udevd[945]: sdb: Setting permissions /dev/sdb, uid=0, gid=995, mode=0660
00:02:15 systemd-udevd[945]: sdb: Found 'b8:16' claiming '/run/udev/links/disk\x2fby-path\x2fpci-0000:00:03.0-usb-0:4:1.0-scsi-0:0:0:0'
00:02:15 systemd-udevd[945]: sdb: Creating symlink '/dev/disk/by-path/pci-0000:00:03.0-usb-0:4:1.0-scsi-0:0:0:0' to '../../sdb'
00:02:15 systemd-udevd[945]: sdb: Found 'b8:16' claiming '/run/udev/links/disk\x2fby-id\x2fusb-QEMU_QEMU_HARDDISK_1-0000:00:03.0-4-0:0'
00:02:15 systemd-udevd[945]: sdb: Creating symlink '/dev/disk/by-id/usb-QEMU_QEMU_HARDDISK_1-0000:00:03.0-4-0:0' to '../../sdb'
00:02:15 systemd-udevd[945]: sdb: Creating symlink '/dev/block/8:16' to '../sdb'
00:02:15 systemd-udevd[945]: sdb: sd-device: Created db file '/run/udev/data/b8:16' for '/devices/pci0000:00/0000:00:03.0/usb2/2-4/2-4:1.0/host6/target6:0:0/6:0:0:0/block/sdb'
00:02:15 systemd-udevd[945]: sdb: Handling device node '/dev/sdb', devnum=b8:16
00:02:15 systemd-udevd[945]: sdb: Preserve permissions of /dev/sdb, uid=0, gid=995, mode=0660
00:02:15 systemd-udevd[945]: sdb: Found 'b8:16' claiming '/run/udev/links/disk\x2fby-path\x2fpci-0000:00:03.0-usb-0:4:1.0-scsi-0:0:0:0'
00:02:15 systemd-udevd[945]: sdb: Preserve already existing symlink '/dev/disk/by-path/pci-0000:00:03.0-usb-0:4:1.0-scsi-0:0:0:0' to '../../sdb'
00:02:15 systemd-udevd[945]: sdb: Found 'b8:16' claiming '/run/udev/links/disk\x2fby-id\x2fusb-QEMU_QEMU_HARDDISK_1-0000:00:03.0-4-0:0'
00:02:15 systemd-udevd[945]: sdb: Preserve already existing symlink '/dev/disk/by-id/usb-QEMU_QEMU_HARDDISK_1-0000:00:03.0-4-0:0' to '../../sdb'
00:02:15 systemd-udevd[945]: sdb: Preserve already existing symlink '/dev/block/8:16' to '../sdb'
00:02:15 systemd-udevd[945]: sdb: Running command "/usr/local/sbin/myscript"
00:02:15 systemd-udevd[945]: sdb: Starting '/usr/local/sbin/myscript'
00:02:15 systemd-udevd[945]: Successfully forked off '(spawn)' as PID 953.
00:02:15 systemd[1]: systemd-journald.service: Got notification message from PID 200 (FDSTORE=1)
00:02:15 systemd[1]: systemd-journald.service: Added fd 61 (n/a) to fd store.

00:02:15 cat[955]: script running...

00:02:15 systemd[1]: Received SIGCHLD from PID 960 (qemu-nbd).
00:02:15 systemd[1]: Child 960 (qemu-nbd) died (code=exited, status=0/SUCCESS)
00:02:15 systemd[1]: systemd-udevd.service: Child 960 belongs to systemd-udevd.service.

00:02:15 cat[955]: script performed.

00:02:15 systemd[1]: systemd-journald.service: Received EPOLLHUP on stored fd 61 (stored), closing.
00:02:15 systemd-udevd[945]: sdb: Process '/usr/local/sbin/myscript' succeeded.
00:02:15 systemd-udevd[945]: sdb: Adding watch on '/dev/sdb'
00:02:15 systemd-udevd[945]: sdb: Device processed (SEQNUM=2232, ACTION="">00:02:15 systemd[295]: dev-disk-by\x2dpath-pci\x2d0000:00:03.0\x2dusb\x2d0:4:1.0\x2dscsi\x2d0:0:0:0.device: Changed dead -> plugged
00:02:15 systemd[295]: dev-disk-by\x2did-usb\x2dQEMU_QEMU_HARDDISK_1\x2d0000:00:03.0\x2d4\x2d0:0.device: Changed dead -> plugged
00:02:15 systemd[295]: dev-sdb.device: Changed dead -> plugged
00:02:15 systemd[295]: sys-devices-pci0000:00-0000:00:03.0-usb2-2\x2d4-2\x2d4:1.0-host6-target6:0:0-6:0:0:0-block-sdb.device: Changed dead -> plugged
00:02:15 systemd[1]: dev-disk-by\x2did-usb\x2dQEMU_QEMU_HARDDISK_1\x2d0000:00:03.0\x2d4\x2d0:0.device: Changed dead -> plugged
00:02:15 systemd[1]: dev-disk-by\x2dpath-pci\x2d0000:00:03.0\x2dusb\x2d0:4:1.0\x2dscsi\x2d0:0:0:0.device: Changed dead -> plugged
00:02:15 systemd[1]: dev-sdb.device: Changed dead -> plugged
00:02:15 systemd[1]: sys-devices-pci0000:00-0000:00:03.0-usb2-2\x2d4-2\x2d4:1.0-host6-target6:0:0-6:0:0:0-block-sdb.device: Changed dead -> plugged
00:02:15 systemd-udevd[945]: sdb: sd-device-monitor: Passed 885 byte to netlink monitor
00:02:15 systemd-udevd[216]: sdb: Device ready for processing (SEQNUM=2233, ACTION="">00:02:15 systemd-udevd[216]: sdb: sd-device-monitor: Passed 263 byte to netlink monitor
00:02:15 systemd-udevd[216]: 6:0:0:0: SEQNUM=2234 blocked by SEQNUM=2233
00:02:15 systemd-udevd[945]: sdb: Processing device (SEQNUM=2233, ACTION="">00:02:15 systemd-udevd[945]: sdb: Removing watch
00:02:15 systemd-udevd[216]: sdb: Inotify event: 8000 for /dev/sdb
00:02:15 systemd-udevd[945]: sdb: /usr/lib/udev/rules.d/60-persistent-storage.rules:60 Importing properties from results of 'ata_id --export /dev/sdb'
00:02:15 systemd-udevd[945]: sdb: Starting 'ata_id --export /dev/sdb'
00:02:15 systemd-udevd[945]: Successfully forked off '(spawn)' as PID 972.
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=UnitNew cookie=178 reply_cookie=0 signature=so error-name=n/a error-message=n/a
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=UnitNew cookie=179 reply_cookie=0 signature=so error-name=n/a error-message=n/a
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=UnitNew cookie=180 reply_cookie=0 signature=so error-name=n/a error-message=n/a
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=UnitNew cookie=181 reply_cookie=0 signature=so error-name=n/a error-message=n/a
00:02:15 systemd-udevd[216]: nbd0: Inotify event: 8 for /dev/nbd0
00:02:15 systemd-udevd[216]: nbd0: device is closed, synthesising 'change' on /sys/devices/virtual/block/nbd0
00:02:15 systemd-udevd[216]: nbd0: Device is queued (SEQNUM=2235, ACTION="">00:02:15 systemd-udevd[216]: nbd0: Device ready for processing (SEQNUM=2235, ACTION="">00:02:15 systemd-udevd[216]: nbd0: sd-device-monitor: Passed 203 byte to netlink monitor
00:02:15 systemd-udevd[950]: nbd0: Processing device (SEQNUM=2235, ACTION="">00:02:15 systemd-udevd[950]: nbd0: Removing watch
00:02:15 systemd-udevd[950]: nbd0: /usr/lib/udev/rules.d/60-persistent-storage.rules:110 Importing properties from results of builtin command 'blkid'
00:02:15 systemd-udevd[950]: nbd0: Probe /dev/nbd0 with raid and offset=0
00:02:15 systemd-udevd[216]: Failed to create sd_device object from watch handle, ignoring: No such file or directory
00:02:15 systemd-udevd[216]: nbd0: Device is queued (SEQNUM=2236, ACTION="">00:02:15 systemd-udevd[216]: nbd0: SEQNUM=2236 blocked by SEQNUM=2235
00:02:15 systemd-udevd[216]: nbd0: Device is queued (SEQNUM=2237, ACTION="">00:02:15 systemd-udevd[216]: nbd0: SEQNUM=2237 blocked by SEQNUM=2235
00:02:15 systemd-udevd[950]: nbd0: /usr/lib/udev/rules.d/69-vdev.rules:5 Importing properties from results of '/usr/lib/udev/vdev_id -d nbd0'
00:02:15 systemd-udevd[950]: nbd0: Starting '/usr/lib/udev/vdev_id -d nbd0'
00:02:15 systemd-udevd[950]: Successfully forked off '(spawn)' as PID 975.
00:02:15 systemd-udevd[945]: sdb: Process 'ata_id --export /dev/sdb' failed with exit code 2.
00:02:15 systemd-udevd[945]: sdb: /usr/lib/udev/rules.d/60-persistent-storage.rules:60 Command "ata_id --export /dev/sdb" returned 2 (error), ignoring
00:02:15 systemd-udevd[945]: sdb: /usr/lib/udev/rules.d/60-persistent-storage.rules:63 Importing properties from results of builtin command 'usb_id'
00:02:15 systemd-udevd[945]: 2-4:1.0: if_class:8 protocol:6
00:02:15 systemd-udevd[945]: sdb: /usr/lib/udev/rules.d/60-persistent-storage.rules:68 LINK 'disk/by-id/usb-QEMU_QEMU_HARDDISK_1-0000:00:03.0-4-0:0'
00:02:15 systemd-udevd[945]: sdb: /usr/lib/udev/rules.d/60-persistent-storage.rules:90 Importing properties from results of builtin command 'path_id'
00:02:15 systemd-udevd[945]: sdb: /usr/lib/udev/rules.d/60-persistent-storage.rules:92 LINK 'disk/by-path/pci-0000:00:03.0-usb-0:4:1.0-scsi-0:0:0:0'
00:02:15 systemd-udevd[945]: sdb: /usr/lib/udev/rules.d/60-persistent-storage.rules:110 Importing properties from results of builtin command 'blkid'
00:02:15 systemd-udevd[945]: sdb: Probe /dev/sdb with raid and offset=0
00:02:15 systemd-udevd[945]: sdb: /usr/lib/udev/rules.d/69-vdev.rules:5 Importing properties from results of '/usr/lib/udev/vdev_id -d sdb'
00:02:15 systemd-udevd[945]: sdb: Starting '/usr/lib/udev/vdev_id -d sdb'
00:02:15 systemd-udevd[945]: Successfully forked off '(spawn)' as PID 976.
00:02:15 systemd-udevd[950]: nbd0: '/usr/lib/udev/vdev_id -d nbd0'(out) 'Error: Config file "/etc/zfs/vdev_id.conf" not found'
00:02:15 systemd-udevd[945]: sdb: '/usr/lib/udev/vdev_id -d sdb'(out) 'Error: Config file "/etc/zfs/vdev_id.conf" not found'
00:02:15 systemd-udevd[950]: nbd0: Process '/usr/lib/udev/vdev_id -d nbd0' failed with exit code 1.
00:02:15 systemd-udevd[950]: nbd0: /usr/lib/udev/rules.d/69-vdev.rules:5 Command "/usr/lib/udev/vdev_id -d nbd0" returned 1 (error), ignoring
00:02:15 systemd-udevd[950]: nbd0: Handling device node '/dev/nbd0', devnum=b43:0
00:02:15 systemd-udevd[950]: nbd0: Preserve already existing symlink '/dev/block/43:0' to '../nbd0'
00:02:15 systemd-udevd[950]: nbd0: sd-device: Created db file '/run/udev/data/b43:0' for '/devices/virtual/block/nbd0'
00:02:15 systemd-udevd[950]: nbd0: Handling device node '/dev/nbd0', devnum=b43:0
00:02:15 systemd-udevd[950]: nbd0: Preserve already existing symlink '/dev/block/43:0' to '../nbd0'
00:02:15 systemd-udevd[950]: nbd0: Adding watch on '/dev/nbd0'
00:02:15 systemd-udevd[950]: nbd0: Device processed (SEQNUM=2235, ACTION="">00:02:15 systemd[295]: dev-nbd0.device: Changed dead -> plugged
00:02:15 systemd[295]: sys-devices-virtual-block-nbd0.device: Changed dead -> plugged
00:02:15 systemd[1]: dev-nbd0.device: Changed dead -> plugged
00:02:15 systemd[1]: sys-devices-virtual-block-nbd0.device: Changed dead -> plugged
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=UnitNew cookie=182 reply_cookie=0 signature=so error-name=n/a error-message=n/a
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=UnitNew cookie=183 reply_cookie=0 signature=so error-name=n/a error-message=n/a
00:02:15 systemd-udevd[950]: nbd0: sd-device-monitor: Passed 241 byte to netlink monitor
00:02:15 systemd-udevd[216]: nbd0: Device ready for processing (SEQNUM=2236, ACTION="">00:02:15 systemd-udevd[216]: nbd0: sd-device-monitor: Passed 193 byte to netlink monitor
00:02:15 systemd-udevd[216]: nbd0: SEQNUM=2237 blocked by SEQNUM=2236
00:02:15 systemd-udevd[950]: nbd0: Processing device (SEQNUM=2236, ACTION="">00:02:15 systemd-udevd[950]: nbd0: Removing watch
00:02:15 systemd-udevd[216]: nbd0: Inotify event: 8000 for /dev/nbd0
00:02:15 systemd-udevd[950]: nbd0: /usr/lib/udev/rules.d/60-persistent-storage.rules:110 Importing properties from results of builtin command 'blkid'
00:02:15 systemd-udevd[950]: nbd0: Probe /dev/nbd0 with raid and offset=0
00:02:15 systemd-udevd[950]: nbd0: /usr/lib/udev/rules.d/69-vdev.rules:5 Importing properties from results of '/usr/lib/udev/vdev_id -d nbd0'
00:02:15 systemd-udevd[950]: nbd0: Starting '/usr/lib/udev/vdev_id -d nbd0'
00:02:15 systemd-udevd[950]: Successfully forked off '(spawn)' as PID 977.
00:02:15 systemd-udevd[945]: sdb: Process '/usr/lib/udev/vdev_id -d sdb' failed with exit code 1.
00:02:15 systemd-udevd[945]: sdb: /usr/lib/udev/rules.d/69-vdev.rules:5 Command "/usr/lib/udev/vdev_id -d sdb" returned 1 (error), ignoring
00:02:15 systemd-udevd[945]: sdb: Handling device node '/dev/sdb', devnum=b8:16
00:02:15 systemd-udevd[945]: sdb: Found 'b8:16' claiming '/run/udev/links/disk\x2fby-path\x2fpci-0000:00:03.0-usb-0:4:1.0-scsi-0:0:0:0'
00:02:15 systemd-udevd[945]: sdb: Preserve already existing symlink '/dev/disk/by-path/pci-0000:00:03.0-usb-0:4:1.0-scsi-0:0:0:0' to '../../sdb'
00:02:15 systemd-udevd[945]: sdb: Found 'b8:16' claiming '/run/udev/links/disk\x2fby-id\x2fusb-QEMU_QEMU_HARDDISK_1-0000:00:03.0-4-0:0'
00:02:15 systemd-udevd[945]: sdb: Preserve already existing symlink '/dev/disk/by-id/usb-QEMU_QEMU_HARDDISK_1-0000:00:03.0-4-0:0' to '../../sdb'
00:02:15 systemd-udevd[945]: sdb: Preserve already existing symlink '/dev/block/8:16' to '../sdb'
00:02:15 systemd-udevd[945]: sdb: sd-device: Created db file '/run/udev/data/b8:16' for '/devices/pci0000:00/0000:00:03.0/usb2/2-4/2-4:1.0/host6/target6:0:0/6:0:0:0/block/sdb'
00:02:15 systemd-udevd[945]: sdb: Handling device node '/dev/sdb', devnum=b8:16
00:02:15 systemd-udevd[945]: sdb: Found 'b8:16' claiming '/run/udev/links/disk\x2fby-path\x2fpci-0000:00:03.0-usb-0:4:1.0-scsi-0:0:0:0'
00:02:15 systemd-udevd[945]: sdb: Preserve already existing symlink '/dev/disk/by-path/pci-0000:00:03.0-usb-0:4:1.0-scsi-0:0:0:0' to '../../sdb'
00:02:15 systemd-udevd[945]: sdb: Found 'b8:16' claiming '/run/udev/links/disk\x2fby-id\x2fusb-QEMU_QEMU_HARDDISK_1-0000:00:03.0-4-0:0'
00:02:15 systemd-udevd[945]: sdb: Preserve already existing symlink '/dev/disk/by-id/usb-QEMU_QEMU_HARDDISK_1-0000:00:03.0-4-0:0' to '../../sdb'
00:02:15 systemd-udevd[945]: sdb: Preserve already existing symlink '/dev/block/8:16' to '../sdb'
00:02:15 systemd-udevd[945]: sdb: Adding watch on '/dev/sdb'
00:02:15 systemd-udevd[945]: sdb: Device processed (SEQNUM=2233, ACTION="">00:02:15 systemd[295]: dev-disk-by\x2dpath-pci\x2d0000:00:03.0\x2dusb\x2d0:4:1.0\x2dscsi\x2d0:0:0:0.device: Installed new job dev-disk-by\x2dpath-pci\x2d0000:00:03.0\x2dusb\x2d0:4:1.0\x2dscsi\x2d0:0:0:0.device/nop as 46
00:02:15 systemd[295]: dev-disk-by\x2did-usb\x2dQEMU_QEMU_HARDDISK_1\x2d0000:00:03.0\x2d4\x2d0:0.device: Installed new job dev-disk-by\x2did-usb\x2dQEMU_QEMU_HARDDISK_1\x2d0000:00:03.0\x2d4\x2d0:0.device/nop as 47
00:02:15 systemd[295]: dev-sdb.device: Installed new job dev-sdb.device/nop as 48
00:02:15 systemd[295]: sys-devices-pci0000:00-0000:00:03.0-usb2-2\x2d4-2\x2d4: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\x2d4-2\x2d4:1.0-host6-target6:0:0-6:0:0:0-block-sdb.device/nop as 49
00:02:15 systemd[295]: dev-disk-by\x2did-usb\x2dQEMU_QEMU_HARDDISK_1\x2d0000:00:03.0\x2d4\x2d0:0.device: Job 47 dev-disk-by\x2did-usb\x2dQEMU_QEMU_HARDDISK_1\x2d0000:00:03.0\x2d4\x2d0:0.device/nop finished, result=done
00:02:15 systemd[295]: dev-disk-by\x2dpath-pci\x2d0000:00:03.0\x2dusb\x2d0:4:1.0\x2dscsi\x2d0:0:0:0.device: Job 46 dev-disk-by\x2dpath-pci\x2d0000:00:03.0\x2dusb\x2d0:4:1.0\x2dscsi\x2d0:0:0:0.device/nop finished, result=done
00:02:15 systemd[295]: dev-sdb.device: Job 48 dev-sdb.device/nop finished, result=done
00:02:15 systemd[295]: sys-devices-pci0000:00-0000:00:03.0-usb2-2\x2d4-2\x2d4:1.0-host6-target6:0:0-6:0:0:0-block-sdb.device: Job 49 sys-devices-pci0000:00-0000:00:03.0-usb2-2\x2d4-2\x2d4:1.0-host6-target6:0:0-6:0:0:0-block-sdb.device/nop finished, result=done
00:02:15 systemd[1]: dev-disk-by\x2did-usb\x2dQEMU_QEMU_HARDDISK_1\x2d0000:00:03.0\x2d4\x2d0:0.device: Installed new job dev-disk-by\x2did-usb\x2dQEMU_QEMU_HARDDISK_1\x2d0000:00:03.0\x2d4\x2d0:0.device/nop as 287
00:02:15 systemd[1]: dev-disk-by\x2dpath-pci\x2d0000:00:03.0\x2dusb\x2d0:4:1.0\x2dscsi\x2d0:0:0:0.device: Installed new job dev-disk-by\x2dpath-pci\x2d0000:00:03.0\x2dusb\x2d0:4:1.0\x2dscsi\x2d0:0:0:0.device/nop as 288
00:02:15 systemd[1]: dev-sdb.device: Installed new job dev-sdb.device/nop as 289
00:02:15 systemd[1]: sys-devices-pci0000:00-0000:00:03.0-usb2-2\x2d4-2\x2d4: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\x2d4-2\x2d4:1.0-host6-target6:0:0-6:0:0:0-block-sdb.device/nop as 290
00:02:15 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_5cx2d4_2d2_5cx2d4_3a1_2e0_2dhost6_2dtarget6_3a0_3a0_2d6_3a0_3a0_3a0_2dblock_2dsdb_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=184 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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_5cx2d4_2d2_5cx2d4_3a1_2e0_2dhost6_2dtarget6_3a0_3a0_2d6_3a0_3a0_3a0_2dblock_2dsdb_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=185 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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=186 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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=187 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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_3a4_3a1_2e0_5cx2dscsi_5cx2d0_3a0_3a0_3a0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=188 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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_3a4_3a1_2e0_5cx2dscsi_5cx2d0_3a0_3a0_3a0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=189 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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_5cx2d4_5cx2d0_3a0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=190 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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_5cx2d4_5cx2d0_3a0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=191 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=JobNew cookie=192 reply_cookie=0 signature=uos error-name=n/a error-message=n/a
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=JobNew cookie=193 reply_cookie=0 signature=uos error-name=n/a error-message=n/a
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=JobNew cookie=194 reply_cookie=0 signature=uos error-name=n/a error-message=n/a
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=JobNew cookie=195 reply_cookie=0 signature=uos error-name=n/a error-message=n/a
00:02:15 systemd[1]: dev-disk-by\x2did-usb\x2dQEMU_QEMU_HARDDISK_1\x2d0000:00:03.0\x2d4\x2d0:0.device: Job 287 dev-disk-by\x2did-usb\x2dQEMU_QEMU_HARDDISK_1\x2d0000:00:03.0\x2d4\x2d0:0.device/nop finished, result=done
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=JobRemoved cookie=196 reply_cookie=0 signature=uoss error-name=n/a error-message=n/a
00:02:15 systemd[1]: dev-disk-by\x2dpath-pci\x2d0000:00:03.0\x2dusb\x2d0:4:1.0\x2dscsi\x2d0:0:0:0.device: Job 288 dev-disk-by\x2dpath-pci\x2d0000:00:03.0\x2dusb\x2d0:4:1.0\x2dscsi\x2d0:0:0:0.device/nop finished, result=done
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=JobRemoved cookie=197 reply_cookie=0 signature=uoss error-name=n/a error-message=n/a
00:02:15 systemd[1]: dev-sdb.device: Job 289 dev-sdb.device/nop finished, result=done
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=JobRemoved cookie=198 reply_cookie=0 signature=uoss error-name=n/a error-message=n/a
00:02:15 systemd[1]: sys-devices-pci0000:00-0000:00:03.0-usb2-2\x2d4-2\x2d4:1.0-host6-target6:0:0-6:0:0:0-block-sdb.device: Job 290 sys-devices-pci0000:00-0000:00:03.0-usb2-2\x2d4-2\x2d4:1.0-host6-target6:0:0-6:0:0:0-block-sdb.device/nop finished, result=done
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=JobRemoved cookie=199 reply_cookie=0 signature=uoss error-name=n/a error-message=n/a
00:02:15 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_5cx2d4_2d2_5cx2d4_3a1_2e0_2dhost6_2dtarget6_3a0_3a0_2d6_3a0_3a0_3a0_2dblock_2dsdb_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=200 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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_5cx2d4_2d2_5cx2d4_3a1_2e0_2dhost6_2dtarget6_3a0_3a0_2d6_3a0_3a0_3a0_2dblock_2dsdb_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=201 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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=202 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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=203 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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_3a4_3a1_2e0_5cx2dscsi_5cx2d0_3a0_3a0_3a0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=204 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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_3a4_3a1_2e0_5cx2dscsi_5cx2d0_3a0_3a0_3a0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=205 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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_5cx2d4_5cx2d0_3a0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=206 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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_5cx2d4_5cx2d0_3a0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=207 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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_5cx2d4_2d2_5cx2d4_3a1_2e0_2dhost6_2dtarget6_3a0_3a0_2d6_3a0_3a0_3a0_2dblock_2dsdb_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=184 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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_5cx2d4_2d2_5cx2d4_3a1_2e0_2dhost6_2dtarget6_3a0_3a0_2d6_3a0_3a0_3a0_2dblock_2dsdb_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=185 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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=186 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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=187 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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_3a4_3a1_2e0_5cx2dscsi_5cx2d0_3a0_3a0_3a0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=188 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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_3a4_3a1_2e0_5cx2dscsi_5cx2d0_3a0_3a0_3a0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=189 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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_5cx2d4_5cx2d0_3a0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=190 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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_5cx2d4_5cx2d0_3a0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=191 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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=196 reply_cookie=0 signature=uoss error-name=n/a error-message=n/a
00:02:15 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=197 reply_cookie=0 signature=uoss error-name=n/a error-message=n/a
00:02:15 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=198 reply_cookie=0 signature=uoss error-name=n/a error-message=n/a
00:02:15 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=199 reply_cookie=0 signature=uoss error-name=n/a error-message=n/a
00:02:15 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_5cx2d4_2d2_5cx2d4_3a1_2e0_2dhost6_2dtarget6_3a0_3a0_2d6_3a0_3a0_3a0_2dblock_2dsdb_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=200 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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_5cx2d4_2d2_5cx2d4_3a1_2e0_2dhost6_2dtarget6_3a0_3a0_2d6_3a0_3a0_3a0_2dblock_2dsdb_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=201 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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=202 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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=203 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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_3a4_3a1_2e0_5cx2dscsi_5cx2d0_3a0_3a0_3a0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=204 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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_3a4_3a1_2e0_5cx2dscsi_5cx2d0_3a0_3a0_3a0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=205 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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_5cx2d4_5cx2d0_3a0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=206 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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_5cx2d4_5cx2d0_3a0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=207 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd[1]: systemd-logind.service: Got notification message from PID 290 (WATCHDOG=1)
00:02:15 systemd-udevd[945]: sdb: sd-device-monitor: Passed 901 byte to netlink monitor
00:02:15 systemd-udevd[216]: 6:0:0:0: Device ready for processing (SEQNUM=2234, ACTION="">00:02:15 systemd-udevd[216]: 6:0:0:0: sd-device-monitor: Passed 241 byte to netlink monitor
00:02:15 systemd-udevd[945]: 6:0:0:0: Processing device (SEQNUM=2234, ACTION="">00:02:15 systemd-udevd[945]: 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:02:15 systemd-udevd[945]: 6:0:0:0: hwdb modalias key: "scsi:t-0x00"
00:02:15 systemd-udevd[945]: 6:0:0:0: No entry found from hwdb.
00:02:15 systemd-udevd[945]: 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:02:15 systemd-udevd[945]: 6:0:0:0: Device processed (SEQNUM=2234, ACTION="">00:02:15 systemd-udevd[945]: 6:0:0:0: sd-device-monitor: Passed 241 byte to netlink monitor
00:02:15 systemd-udevd[950]: nbd0: '/usr/lib/udev/vdev_id -d nbd0'(out) 'Error: Config file "/etc/zfs/vdev_id.conf" not found'
00:02:15 systemd-udevd[950]: nbd0: Process '/usr/lib/udev/vdev_id -d nbd0' failed with exit code 1.
00:02:15 systemd-udevd[950]: nbd0: /usr/lib/udev/rules.d/69-vdev.rules:5 Command "/usr/lib/udev/vdev_id -d nbd0" returned 1 (error), ignoring
00:02:15 systemd-udevd[950]: nbd0: Handling device node '/dev/nbd0', devnum=b43:0
00:02:15 systemd-udevd[950]: nbd0: Preserve already existing symlink '/dev/block/43:0' to '../nbd0'
00:02:15 systemd-udevd[950]: nbd0: sd-device: Created db file '/run/udev/data/b43:0' for '/devices/virtual/block/nbd0'
00:02:15 systemd-udevd[950]: nbd0: Handling device node '/dev/nbd0', devnum=b43:0
00:02:15 systemd-udevd[950]: nbd0: Preserve already existing symlink '/dev/block/43:0' to '../nbd0'
00:02:15 systemd-udevd[950]: nbd0: Adding watch on '/dev/nbd0'
00:02:15 systemd-udevd[950]: nbd0: Device processed (SEQNUM=2236, ACTION="">00:02:15 systemd[295]: dev-nbd0.device: Installed new job dev-nbd0.device/nop as 50
00:02:15 systemd[295]: sys-devices-virtual-block-nbd0.device: Installed new job sys-devices-virtual-block-nbd0.device/nop as 51
00:02:15 systemd[295]: dev-nbd0.device: Job 50 dev-nbd0.device/nop finished, result=done
00:02:15 systemd[295]: sys-devices-virtual-block-nbd0.device: Job 51 sys-devices-virtual-block-nbd0.device/nop finished, result=done
00:02:15 systemd[1]: dev-nbd0.device: Installed new job dev-nbd0.device/nop as 291
00:02:15 systemd[1]: sys-devices-virtual-block-nbd0.device: Installed new job sys-devices-virtual-block-nbd0.device/nop as 292
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=208 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=209 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=210 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=211 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=JobNew cookie=212 reply_cookie=0 signature=uos error-name=n/a error-message=n/a
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=JobNew cookie=213 reply_cookie=0 signature=uos error-name=n/a error-message=n/a
00:02:15 systemd[1]: dev-nbd0.device: Job 291 dev-nbd0.device/nop finished, result=done
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=JobRemoved cookie=214 reply_cookie=0 signature=uoss error-name=n/a error-message=n/a
00:02:15 systemd[1]: sys-devices-virtual-block-nbd0.device: Job 292 sys-devices-virtual-block-nbd0.device/nop finished, result=done
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=JobRemoved cookie=215 reply_cookie=0 signature=uoss error-name=n/a error-message=n/a
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=216 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=217 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=218 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=219 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=208 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=209 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=210 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=211 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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=214 reply_cookie=0 signature=uoss error-name=n/a error-message=n/a
00:02:15 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=215 reply_cookie=0 signature=uoss error-name=n/a error-message=n/a
00:02:15 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=216 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=217 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=218 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=219 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd-udevd[950]: nbd0: sd-device-monitor: Passed 231 byte to netlink monitor
00:02:15 systemd-udevd[216]: nbd0: Device ready for processing (SEQNUM=2237, ACTION="">00:02:15 systemd-udevd[216]: nbd0: sd-device-monitor: Passed 206 byte to netlink monitor
00:02:15 systemd-udevd[945]: nbd0: Processing device (SEQNUM=2237, ACTION="">00:02:15 systemd-udevd[945]: nbd0: Removing watch
00:02:15 systemd-udevd[216]: nbd0: Inotify event: 8000 for /dev/nbd0
00:02:15 systemd-udevd[945]: nbd0: /usr/lib/udev/rules.d/60-persistent-storage.rules:110 Importing properties from results of builtin command 'blkid'
00:02:15 systemd-udevd[945]: nbd0: Probe /dev/nbd0 with raid and offset=0
00:02:15 systemd-udevd[945]: nbd0: /usr/lib/udev/rules.d/69-vdev.rules:5 Importing properties from results of '/usr/lib/udev/vdev_id -d nbd0'
00:02:15 systemd-udevd[945]: nbd0: Starting '/usr/lib/udev/vdev_id -d nbd0'
00:02:15 systemd-udevd[945]: Successfully forked off '(spawn)' as PID 978.
00:02:15 systemd-udevd[945]: nbd0: '/usr/lib/udev/vdev_id -d nbd0'(out) 'Error: Config file "/etc/zfs/vdev_id.conf" not found'
00:02:15 systemd-udevd[945]: nbd0: Process '/usr/lib/udev/vdev_id -d nbd0' failed with exit code 1.
00:02:15 systemd-udevd[945]: nbd0: /usr/lib/udev/rules.d/69-vdev.rules:5 Command "/usr/lib/udev/vdev_id -d nbd0" returned 1 (error), ignoring
00:02:15 systemd-udevd[945]: nbd0: Handling device node '/dev/nbd0', devnum=b43:0
00:02:15 systemd-udevd[945]: nbd0: Preserve already existing symlink '/dev/block/43:0' to '../nbd0'
00:02:15 systemd-udevd[945]: nbd0: sd-device: Created db file '/run/udev/data/b43:0' for '/devices/virtual/block/nbd0'
00:02:15 systemd-udevd[945]: nbd0: Handling device node '/dev/nbd0', devnum=b43:0
00:02:15 systemd-udevd[945]: nbd0: Preserve already existing symlink '/dev/block/43:0' to '../nbd0'
00:02:15 systemd-udevd[945]: nbd0: Adding watch on '/dev/nbd0'
00:02:15 systemd-udevd[945]: nbd0: Device processed (SEQNUM=2237, ACTION="">00:02:15 systemd[295]: dev-nbd0.device: Installed new job dev-nbd0.device/nop as 52
00:02:15 systemd[295]: sys-devices-virtual-block-nbd0.device: Installed new job sys-devices-virtual-block-nbd0.device/nop as 53
00:02:15 systemd[295]: dev-nbd0.device: Job 52 dev-nbd0.device/nop finished, result=done
00:02:15 systemd[295]: sys-devices-virtual-block-nbd0.device: Job 53 sys-devices-virtual-block-nbd0.device/nop finished, result=done
00:02:15 systemd[1]: dev-nbd0.device: Installed new job dev-nbd0.device/nop as 293
00:02:15 systemd[1]: sys-devices-virtual-block-nbd0.device: Installed new job sys-devices-virtual-block-nbd0.device/nop as 294
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=220 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=221 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=222 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=223 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=JobNew cookie=224 reply_cookie=0 signature=uos error-name=n/a error-message=n/a
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=JobNew cookie=225 reply_cookie=0 signature=uos error-name=n/a error-message=n/a
00:02:15 systemd[1]: dev-nbd0.device: Job 293 dev-nbd0.device/nop finished, result=done
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=JobRemoved cookie=226 reply_cookie=0 signature=uoss error-name=n/a error-message=n/a
00:02:15 systemd[1]: sys-devices-virtual-block-nbd0.device: Job 294 sys-devices-virtual-block-nbd0.device/nop finished, result=done
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=JobRemoved cookie=227 reply_cookie=0 signature=uoss error-name=n/a error-message=n/a
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=228 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=229 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=230 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=231 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=220 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=221 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=222 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=223 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 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=226 reply_cookie=0 signature=uoss error-name=n/a error-message=n/a
00:02:15 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=227 reply_cookie=0 signature=uoss error-name=n/a error-message=n/a
00:02:15 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=228 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=229 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=230 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=231 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:15 systemd-udevd[945]: nbd0: sd-device-monitor: Passed 244 byte to netlink monitor
00:02:19 systemd-udevd[216]: Cleanup idle workers
00:02:19 systemd-udevd[950]: Unload module index
00:02:19 systemd-udevd[950]: Unloaded link configuration context.
00:02:19 systemd-udevd[942]: Unload module index
00:02:19 systemd-udevd[216]: Worker [950] exited
00:02:19 systemd-udevd[945]: Unload module index
00:02:19 systemd-udevd[945]: Unloaded link configuration context.
00:02:19 systemd-udevd[942]: Unloaded link configuration context.
00:02:19 systemd-udevd[216]: Worker [945] exited
00:02:19 systemd-udevd[216]: Worker [942] exited
00:02:19 systemd[1]: Received SIGCHLD from PID 962 (qemu-nbd).
00:02:19 systemd[1]: Child 962 (qemu-nbd) died (code=killed, status=9/KILL)
00:02:19 systemd[1]: systemd-udevd.service: Child 962 belongs to systemd-udevd.service.
00:02:19 systemd-udevd[216]: nbd0: Inotify event: 8 for /dev/nbd0
00:02:19 systemd-udevd[216]: nbd0: device is closed, synthesising 'change' on /sys/devices/virtual/block/nbd0
00:02:19 systemd-udevd[216]: nbd0: Device is queued (SEQNUM=2238, ACTION="">00:02:19 systemd-udevd[216]: Validate module index
00:02:19 systemd-udevd[216]: Check if link configuration needs reloading.
00:02:19 systemd-udevd[216]: nbd0: Device ready for processing (SEQNUM=2238, ACTION="">00:02:19 systemd-udevd[216]: Successfully forked off 'n/a' as PID 979.
00:02:19 systemd-udevd[216]: nbd0: Worker [979] is forked for processing SEQNUM=2238.
00:02:19 systemd-udevd[216]: nbd0: Device is queued (SEQNUM=2239, ACTION="">00:02:19 systemd-udevd[216]: nbd0: SEQNUM=2239 blocked by SEQNUM=2238
00:02:19 systemd-udevd[979]: nbd0: Processing device (SEQNUM=2238, ACTION="">00:02:19 systemd-udevd[979]: nbd0: Removing watch
00:02:19 systemd-udevd[216]: nbd0: Inotify event: 8000 for /dev/nbd0
00:02:19 systemd-udevd[979]: nbd0: /usr/lib/udev/rules.d/60-persistent-storage.rules:110 Importing properties from results of builtin command 'blkid'
00:02:19 systemd-udevd[979]: nbd0: Probe /dev/nbd0 with raid and offset=0
00:02:19 systemd-udevd[979]: nbd0: /usr/lib/udev/rules.d/69-vdev.rules:5 Importing properties from results of '/usr/lib/udev/vdev_id -d nbd0'
00:02:19 systemd-udevd[979]: nbd0: Starting '/usr/lib/udev/vdev_id -d nbd0'
00:02:19 systemd-udevd[979]: Successfully forked off '(spawn)' as PID 980.
00:02:19 systemd-udevd[979]: nbd0: '/usr/lib/udev/vdev_id -d nbd0'(out) 'Error: Config file "/etc/zfs/vdev_id.conf" not found'
00:02:19 systemd-udevd[979]: nbd0: Process '/usr/lib/udev/vdev_id -d nbd0' failed with exit code 1.
00:02:19 systemd-udevd[979]: nbd0: /usr/lib/udev/rules.d/69-vdev.rules:5 Command "/usr/lib/udev/vdev_id -d nbd0" returned 1 (error), ignoring

00:02:19 kernel: block nbd0: shutting down sockets

00:02:19 systemd-udevd[979]: nbd0: Handling device node '/dev/nbd0', devnum=b43:0
00:02:19 systemd-udevd[979]: nbd0: Preserve already existing symlink '/dev/block/43:0' to '../nbd0'
00:02:19 systemd-udevd[979]: nbd0: sd-device: Created db file '/run/udev/data/b43:0' for '/devices/virtual/block/nbd0'
00:02:19 systemd-udevd[979]: nbd0: Handling device node '/dev/nbd0', devnum=b43:0
00:02:19 systemd-udevd[979]: nbd0: Preserve already existing symlink '/dev/block/43:0' to '../nbd0'
00:02:19 systemd-udevd[979]: nbd0: Adding watch on '/dev/nbd0'
00:02:19 systemd-udevd[979]: nbd0: Device processed (SEQNUM=2238, ACTION="">00:02:19 systemd[295]: dev-nbd0.device: Installed new job dev-nbd0.device/nop as 54
00:02:19 systemd[295]: sys-devices-virtual-block-nbd0.device: Installed new job sys-devices-virtual-block-nbd0.device/nop as 55
00:02:19 systemd[295]: dev-nbd0.device: Changed plugged -> dead
00:02:19 systemd[295]: sys-devices-virtual-block-nbd0.device: Changed plugged -> dead
00:02:19 systemd[295]: dev-nbd0.device: Job 54 dev-nbd0.device/nop finished, result=done
00:02:19 systemd[295]: sys-devices-virtual-block-nbd0.device: Job 55 sys-devices-virtual-block-nbd0.device/nop finished, result=done
00:02:19 systemd[295]: sys-devices-virtual-block-nbd0.device: Collecting.
00:02:19 systemd[295]: dev-nbd0.device: Collecting.
00:02:19 systemd[1]: dev-nbd0.device: Installed new job dev-nbd0.device/nop as 295
00:02:19 systemd[1]: sys-devices-virtual-block-nbd0.device: Installed new job sys-devices-virtual-block-nbd0.device/nop as 296
00:02:19 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=232 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:19 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=233 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:19 systemd[1]: dev-nbd0.device: Changed plugged -> dead
00:02:19 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=234 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:19 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=235 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:19 systemd[1]: sys-devices-virtual-block-nbd0.device: Changed plugged -> dead
00:02:19 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=236 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:19 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=237 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:19 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=238 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:19 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=239 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:19 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=JobNew cookie=240 reply_cookie=0 signature=uos error-name=n/a error-message=n/a
00:02:19 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=JobNew cookie=241 reply_cookie=0 signature=uos error-name=n/a error-message=n/a
00:02:19 systemd[1]: dev-nbd0.device: Job 295 dev-nbd0.device/nop finished, result=done
00:02:19 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=JobRemoved cookie=242 reply_cookie=0 signature=uoss error-name=n/a error-message=n/a
00:02:19 systemd[1]: sys-devices-virtual-block-nbd0.device: Job 296 sys-devices-virtual-block-nbd0.device/nop finished, result=done
00:02:19 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=JobRemoved cookie=243 reply_cookie=0 signature=uoss error-name=n/a error-message=n/a
00:02:19 systemd[1]: sys-devices-virtual-block-nbd0.device: Collecting.
00:02:19 systemd[1]: dev-nbd0.device: Collecting.
00:02:19 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=244 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:19 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=245 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:19 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=UnitRemoved cookie=246 reply_cookie=0 signature=so error-name=n/a error-message=n/a
00:02:19 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=247 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:19 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=248 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:19 systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=UnitRemoved cookie=249 reply_cookie=0 signature=so error-name=n/a error-message=n/a
00:02:19 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=232 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:19 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=233 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:19 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=234 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:19 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=235 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:19 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=236 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:19 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=237 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:19 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=238 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:19 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=239 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:19 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=242 reply_cookie=0 signature=uoss error-name=n/a error-message=n/a
00:02:19 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=243 reply_cookie=0 signature=uoss error-name=n/a error-message=n/a
00:02:19 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=244 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:19 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/dev_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=245 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:19 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=UnitRemoved cookie=246 reply_cookie=0 signature=so error-name=n/a error-message=n/a
00:02:19 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=247 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:19 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1/unit/sys_2ddevices_2dvirtual_2dblock_2dnbd0_2edevice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=248 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a
00:02:19 systemd-logind[290]: Got message type=signal sender=:1.0 destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=UnitRemoved cookie=249 reply_cookie=0 signature=so error-name=n/a error-message=n/a
00:02:19 systemd-udevd[979]: nbd0: sd-device-monitor: Passed 247 byte to netlink monitor
00:02:19 systemd-udevd[216]: nbd0: Device ready for processing (SEQNUM=2239, ACTION="">00:02:19 systemd-udevd[216]: nbd0: sd-device-monitor: Passed 206 byte to netlink monitor
00:02:19 systemd-udevd[979]: nbd0: Processing device (SEQNUM=2239, ACTION="">00:02:19 systemd-udevd[979]: nbd0: Removing watch
00:02:19 systemd-udevd[216]: nbd0: Inotify event: 8000 for /dev/nbd0
00:02:19 systemd-udevd[979]: nbd0: /usr/lib/udev/rules.d/60-persistent-storage.rules:110 Importing properties from results of builtin command 'blkid'
00:02:19 systemd-udevd[979]: nbd0: Probe /dev/nbd0 with raid and offset=0
00:02:19 systemd-udevd[979]: nbd0: /usr/lib/udev/rules.d/69-vdev.rules:5 Importing properties from results of '/usr/lib/udev/vdev_id -d nbd0'
00:02:19 systemd-udevd[979]: nbd0: Starting '/usr/lib/udev/vdev_id -d nbd0'
00:02:19 systemd-udevd[979]: Successfully forked off '(spawn)' as PID 981.
00:02:19 systemd-udevd[979]: nbd0: '/usr/lib/udev/vdev_id -d nbd0'(out) 'Error: Config file "/etc/zfs/vdev_id.conf" not found'
00:02:19 systemd-udevd[979]: nbd0: Process '/usr/lib/udev/vdev_id -d nbd0' failed with exit code 1.
00:02:19 systemd-udevd[979]: nbd0: /usr/lib/udev/rules.d/69-vdev.rules:5 Command "/usr/lib/udev/vdev_id -d nbd0" returned 1 (error), ignoring
00:02:19 systemd-udevd[979]: nbd0: Handling device node '/dev/nbd0', devnum=b43:0
00:02:19 systemd-udevd[979]: nbd0: Preserve already existing symlink '/dev/block/43:0' to '../nbd0'
00:02:19 systemd-udevd[979]: nbd0: sd-device: Created db file '/run/udev/data/b43:0' for '/devices/virtual/block/nbd0'
00:02:19 systemd-udevd[979]: nbd0: Handling device node '/dev/nbd0', devnum=b43:0
00:02:19 systemd-udevd[979]: nbd0: Preserve already existing symlink '/dev/block/43:0' to '../nbd0'
00:02:19 systemd-udevd[979]: nbd0: Adding watch on '/dev/nbd0'
00:02:19 systemd-udevd[979]: nbd0: Device processed (SEQNUM=2239, ACTION="">00:02:19 systemd-udevd[979]: nbd0: sd-device-monitor: Passed 260 byte to netlink monitor
00:02:23 systemd-udevd[216]: Cleanup idle workers
00:02:23 systemd-udevd[979]: Unload module index
00:02:23 systemd-udevd[979]: Unloaded link configuration context.
00:02:23 systemd-udevd[216]: Worker [979] exited

Le jeu. 19 mai 2022 à 17:09, Eric Blake <eblake@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