Skip to contentSkip to conversation

Question about debugging the source of uevents

DISCUSSION Tom 3 participants 8 messages Mar 30, 2009

lore
Share & tools
lore

Tom

author
#0 lore raw

Hi,

I have a spare hard disk I don't touch the most of the time. Due to a
strange reason, a change uevent is triggered some random time of the day (like 4 or more times) and then, of course, udev apply the rules, being one of them a rule which runs vol_id which then wakes up my spinned-down hard disk (debugged with echoing a 1 to /proc/sys/vm/block_dump and udevadm monitor).

Well, what i want to know is if it's possible to know why the kernel
is triggering the uevent in the first place. Grepping the documentation of the kernel I don't see anything about it. I know I can just comment the watch line in /lib/udev/rules.d/60-persistent-storage.rules but I wanted to know more.

I'm using Archlinux which currently uses kernel 2.6.28.8 and udev 140.

Thanks in advance.

#1 lore raw
Quoted 6 lines · Tom

 I have a spare hard disk I don't touch the most of the time. Due to a strange reason, a change uevent is triggered some random time of the day (like 4 or more times) and then, of course, udev apply the rules, being one of them a rule which runs vol_id which then wakes up my spinned-down hard disk (debugged with echoing a 1 to /proc/sys/vm/block_dump and udevadm monitor).

Does "monitor" show a kernel event, or only a udev event?

Any other events at the same time?

Are you running smartd?

Kay

author
#2 lore raw
In reply to Tom
Quoted 8 lines · Kay Sievers
Quoted 6 lines · Tom

I have a spare hard disk I don't touch the most of the time. Due to a
strange reason, a change uevent is triggered some random time of the day (like 4 or more times) and then, of course, udev apply the rules, being one of them a rule which runs vol_id which then wakes up my spinned-down hard disk (debugged with echoing a 1 to /proc/sys/vm/block_dump and udevadm monitor).

Quoted 4 lines

Does "monitor" show a kernel event, or only a udev event?

Any other events at the same time?

I don't remember exactly (if I remember correctly, it was one uevent everytime). Here I attach the logs. Later I could tell you better. I will be more aware of that.

Quoted 1 line

Are you running smartd?

Yes, but I have smartd configured to ignore the hard disk if it's in standby. In syslog I have:
smartd[7004]: Device: /dev/sda, is in STANDBY mode, suspending checks

And thanks for answering so fast (and wasting your time too).

Quoted 2 lines

Kay

Mar 30 14:33:48 reztho udevd-event[29897]: device 0x9385070 has devpath '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda'
Mar 30 14:33:48 reztho udevd-event[29897]: device 0x9385070 filled with db file data
Mar 30 14:33:48 reztho udevd-event[29897]: removing watch on '/dev/sda'
Mar 30 14:33:48 reztho udevd-event[29897]: LINK 'block/8:0' /lib/udev/rules.d/50-udev-default.rules:4
Mar 30 14:33:48 reztho udevd-event[29897]: GROUP 6 /lib/udev/rules.d/50-udev-default.rules:64
Mar 30 14:33:48 reztho udevd-event[29897]: device 0x93903b0 has devpath '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0'
Mar 30 14:33:48 reztho udevd-event[29897]: device 0x93908c0 has devpath '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0'
Mar 30 14:33:48 reztho udevd-event[29897]: device 0x9390ee8 has devpath '/devices/pci0000:00/0000:00:0f.1/host0'
Mar 30 14:33:48 reztho udevd-event[29897]: device 0x93910d0 has devpath '/devices/pci0000:00/0000:00:0f.1'
Mar 30 14:33:48 reztho udevd-event[29897]: device 0x9391288 has devpath '/devices/pci0000:00'
Mar 30 14:33:48 reztho udevd-event[29897]: GROUP 6 /lib/udev/rules.d/50-udev-default.rules:85
Mar 30 14:33:48 reztho udevd-event[29897]: IMPORT 'scsi_id --export --whitelisted -d /dev/block/8:0' /lib/udev/rules.d/60-persistent-storage.rules:31
Mar 30 14:33:48 reztho udevd-event[29897]: 'scsi_id --export --whitelisted -d /dev/block/8:0'
Mar 30 14:33:48 reztho udevd-event[29897]: '/lib/udev/scsi_id' (stdout) 'ID_VENDOR=ATA'
Mar 30 14:33:48 reztho udevd-event[29897]: '/lib/udev/scsi_id' (stdout) 'ID_VENDOR_ENC=ATA\x20\x20\x20\x20\x20'
Mar 30 14:33:48 reztho udevd-event[29897]: '/lib/udev/scsi_id' (stdout) 'ID_MODEL=ST3120022A'
Mar 30 14:33:48 reztho udevd-event[29897]: '/lib/udev/scsi_id' (stdout) 'ID_MODEL_ENC=ST3120022A\x20\x20\x20\x20\x20\x20'
Mar 30 14:33:48 reztho udevd-event[29897]: '/lib/udev/scsi_id' (stdout) 'ID_REVISION=3.06'
Mar 30 14:33:48 reztho udevd-event[29897]: '/lib/udev/scsi_id' (stdout) 'ID_TYPE=disk'
Mar 30 14:33:48 reztho udevd-event[29897]: '/lib/udev/scsi_id' (stdout) 'ID_SERIAL=SATA_ST3120022A_5JT02J8Y'
Mar 30 14:33:48 reztho udevd-event[29897]: '/lib/udev/scsi_id' (stdout) 'ID_SERIAL_SHORT=5JT02J8Y'
Mar 30 14:33:48 reztho udevd-event[29897]: '/lib/udev/scsi_id' returned with status 0
Mar 30 14:33:48 reztho udevd-event[29897]: LINK 'disk/by-id/scsi-SATA_ST3120022A_5JT02J8Y' /lib/udev/rules.d/60-persistent-storage.rules:33
Mar 30 14:33:48 reztho udevd-event[29897]: PROGRAM 'ata_id /dev/block/8:0' /lib/udev/rules.d/60-persistent-storage.rules:41
Mar 30 14:33:48 reztho udevd-event[29897]: 'ata_id /dev/block/8:0'
Mar 30 14:33:48 reztho udevd-event[29897]: '/lib/udev/ata_id' (stdout) 'ST3120022A_5JT02J8Y'
Mar 30 14:33:48 reztho udevd-event[29897]: '/lib/udev/ata_id' returned with status 0
Mar 30 14:33:48 reztho udevd-event[29897]: LINK 'disk/by-id/ata-ST3120022A_5JT02J8Y' /lib/udev/rules.d/60-persistent-storage.rules:41
Mar 30 14:33:48 reztho udevd-event[29897]: IMPORT 'path_id /devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda' /lib/udev/rules.d/60-persistent-storage.rules:50
Mar 30 14:33:48 reztho udevd-event[29897]: 'path_id /devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda'
Mar 30 14:33:48 reztho udevd-event[29897]: '/lib/udev/path_id' (stdout) 'ID_PATH=pci-0000:00:0f.1-scsi-0:0:0:0'
Mar 30 14:33:48 reztho udevd-event[29897]: '/lib/udev/path_id' returned with status 0
Mar 30 14:33:48 reztho udevd-event[29897]: LINK 'disk/by-path/pci-0000:00:0f.1-scsi-0:0:0:0' /lib/udev/rules.d/60-persistent-storage.rules:51
Mar 30 14:33:48 reztho udevd-event[29897]: IMPORT 'vol_id --export /dev/block/8:0' /lib/udev/rules.d/60-persistent-storage.rules:61
Mar 30 14:33:48 reztho udevd-event[29897]: 'vol_id --export /dev/block/8:0'
Mar 30 14:33:48 reztho udevd-event[29897]: '/lib/udev/vol_id' (stderr) 'unknown or non-unique volume type (--probe-all lists possibly conflicting types)'
Mar 30 14:33:48 reztho udevd-event[29897]: '/lib/udev/vol_id' returned with status 4
Mar 30 14:33:48 reztho udevd-event[29897]: IMPORT 'edd_id --export /dev/block/8:0' /lib/udev/rules.d/61-persistent-storage-edd.rules:8
Mar 30 14:33:48 reztho udevd-event[29897]: 'edd_id --export /dev/block/8:0'
Mar 30 14:33:48 reztho udevd-event[29897]: '/lib/udev/edd_id' (stderr) 'no kernel EDD support'
Mar 30 14:33:48 reztho udevd-event[29897]: '/lib/udev/edd_id' returned with status 2
Mar 30 14:33:48 reztho udevd-event[29897]: RUN 'root-link.sh' /lib/udev/rules.d/81-arch.rules:80
Mar 30 14:33:48 reztho udevd-event[29897]: RUN 'socket:/org/freedesktop/hal/udev_event' /etc/udev/rules.d/90-hal.rules:2
Mar 30 14:33:48 reztho udevd-event[29897]: RUN 'socket:@/org/kernel/udev/monitor' /lib/udev/rules.d/95-udev-late.rules:7
Mar 30 14:33:48 reztho udevd-event[29897]: no node name set, will use kernel name 'sda'
Mar 30 14:33:48 reztho udevd-event[29897]: created db file for '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda' in '/dev/.udev/db/\x2fdevices\x2fpci0000:00\x2f0000:00:0f.1\x2fhost0\x2ftarget0:0:0\x2f0:0:0:0\x2fblock\x2fsda'
Mar 30 14:33:48 reztho udevd-event[29897]: creating device node '/dev/sda', devnum=8:0, mode=0660, uid=0, gid=6
Mar 30 14:33:48 reztho udevd-event[29897]: preserve file '/dev/sda', because it has correct dev_t
Mar 30 14:33:48 reztho udevd-event[29897]: '/dev/block/8:0' with target '/dev/sda' has the highest priority 0, create it
Mar 30 14:33:48 reztho udevd-event[29897]: preserve already existing symlink '/dev/block/8:0' to '../sda'
Mar 30 14:33:48 reztho udevd-event[29897]: '/dev/disk/by-id/scsi-SATA_ST3120022A_5JT02J8Y' with target '/dev/sda' has the highest priority 0, create it
Mar 30 14:33:48 reztho udevd-event[29897]: preserve already existing symlink '/dev/disk/by-id/scsi-SATA_ST3120022A_5JT02J8Y' to '../../sda'
Mar 30 14:33:48 reztho udevd-event[29897]: '/dev/disk/by-id/ata-ST3120022A_5JT02J8Y' with target '/dev/sda' has the highest priority 0, create it
Mar 30 14:33:48 reztho udevd-event[29897]: preserve already existing symlink '/dev/disk/by-id/ata-ST3120022A_5JT02J8Y' to '../../sda'
Mar 30 14:33:48 reztho udevd-event[29897]: '/dev/disk/by-path/pci-0000:00:0f.1-scsi-0:0:0:0' with target '/dev/sda' has the highest priority 0, create it
Mar 30 14:33:48 reztho udevd-event[29897]: preserve already existing symlink '/dev/disk/by-path/pci-0000:00:0f.1-scsi-0:0:0:0' to '../../sda'
Mar 30 14:33:48 reztho udevd-event[29897]: 'root-link.sh'
Mar 30 14:33:48 reztho udevd-event[29897]: '/lib/udev/root-link.sh' returned with status 0
Mar 30 14:33:48 reztho udevd-event[29897]: passed 704 bytes to monitor 0x9385070, 
Mar 30 14:33:48 reztho udevd-event[29897]: passed 704 bytes to monitor 0x9385070, 
Mar 30 14:33:48 reztho udevd-event[29897]: adding watch on '/dev/sda'
Mar 30 14:33:48 reztho udevd-event[29897]: created db file for '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda' in '/dev/.udev/db/\x2fdevices\x2fpci0000:00\x2f0000:00:0f.1\x2fhost0\x2ftarget0:0:0\x2f0:0:0:0\x2fblock\x2fsda'
Mar 30 14:33:48 reztho udevd-event[29897]: seq 2312 exit with 0
Mar 30 14:52:01 reztho udevd-event[6256]: device 0x9390388 has devpath '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda'
Mar 30 14:52:01 reztho udevd-event[6256]: device 0x9390388 filled with db file data
Mar 30 14:52:01 reztho udevd-event[6256]: removing watch on '/dev/sda'
Mar 30 14:52:01 reztho udevd-event[6256]: LINK 'block/8:0' /lib/udev/rules.d/50-udev-default.rules:4
Mar 30 14:52:01 reztho udevd-event[6256]: GROUP 6 /lib/udev/rules.d/50-udev-default.rules:64
Mar 30 14:52:01 reztho udevd-event[6256]: device 0x9390690 has devpath '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0'
Mar 30 14:52:01 reztho udevd-event[6256]: device 0x9390ce8 has devpath '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0'
Mar 30 14:52:01 reztho udevd-event[6256]: device 0x9390ee8 has devpath '/devices/pci0000:00/0000:00:0f.1/host0'
Mar 30 14:52:01 reztho udevd-event[6256]: device 0x93910d0 has devpath '/devices/pci0000:00/0000:00:0f.1'
Mar 30 14:52:01 reztho udevd-event[6256]: device 0x9391288 has devpath '/devices/pci0000:00'
Mar 30 14:52:01 reztho udevd-event[6256]: GROUP 6 /lib/udev/rules.d/50-udev-default.rules:85
Mar 30 14:52:01 reztho udevd-event[6256]: IMPORT 'scsi_id --export --whitelisted -d /dev/block/8:0' /lib/udev/rules.d/60-persistent-storage.rules:31
Mar 30 14:52:01 reztho udevd-event[6256]: 'scsi_id --export --whitelisted -d /dev/block/8:0'
Mar 30 14:52:01 reztho udevd-event[6256]: '/lib/udev/scsi_id' (stdout) 'ID_VENDOR=ATA'
Mar 30 14:52:01 reztho udevd-event[6256]: '/lib/udev/scsi_id' (stdout) 'ID_VENDOR_ENC=ATA\x20\x20\x20\x20\x20'
Mar 30 14:52:01 reztho udevd-event[6256]: '/lib/udev/scsi_id' (stdout) 'ID_MODEL=ST3120022A'
Mar 30 14:52:01 reztho udevd-event[6256]: '/lib/udev/scsi_id' (stdout) 'ID_MODEL_ENC=ST3120022A\x20\x20\x20\x20\x20\x20'
Mar 30 14:52:01 reztho udevd-event[6256]: '/lib/udev/scsi_id' (stdout) 'ID_REVISION=3.06'
Mar 30 14:52:01 reztho udevd-event[6256]: '/lib/udev/scsi_id' (stdout) 'ID_TYPE=disk'
Mar 30 14:52:01 reztho udevd-event[6256]: '/lib/udev/scsi_id' (stdout) 'ID_SERIAL=SATA_ST3120022A_5JT02J8Y'
Mar 30 14:52:01 reztho udevd-event[6256]: '/lib/udev/scsi_id' (stdout) 'ID_SERIAL_SHORT=5JT02J8Y'
Mar 30 14:52:01 reztho udevd-event[6256]: '/lib/udev/scsi_id' returned with status 0
Mar 30 14:52:01 reztho udevd-event[6256]: LINK 'disk/by-id/scsi-SATA_ST3120022A_5JT02J8Y' /lib/udev/rules.d/60-persistent-storage.rules:33
Mar 30 14:52:01 reztho udevd-event[6256]: PROGRAM 'ata_id /dev/block/8:0' /lib/udev/rules.d/60-persistent-storage.rules:41
Mar 30 14:52:01 reztho udevd-event[6256]: 'ata_id /dev/block/8:0'
Mar 30 14:52:01 reztho udevd-event[6256]: '/lib/udev/ata_id' (stdout) 'ST3120022A_5JT02J8Y'
Mar 30 14:52:01 reztho udevd-event[6256]: '/lib/udev/ata_id' returned with status 0
Mar 30 14:52:01 reztho udevd-event[6256]: LINK 'disk/by-id/ata-ST3120022A_5JT02J8Y' /lib/udev/rules.d/60-persistent-storage.rules:41
Mar 30 14:52:01 reztho udevd-event[6256]: IMPORT 'path_id /devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda' /lib/udev/rules.d/60-persistent-storage.rules:50
Mar 30 14:52:01 reztho udevd-event[6256]: 'path_id /devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda'
Mar 30 14:52:01 reztho udevd-event[6256]: '/lib/udev/path_id' (stdout) 'ID_PATH=pci-0000:00:0f.1-scsi-0:0:0:0'
Mar 30 14:52:01 reztho udevd-event[6256]: '/lib/udev/path_id' returned with status 0
Mar 30 14:52:01 reztho udevd-event[6256]: LINK 'disk/by-path/pci-0000:00:0f.1-scsi-0:0:0:0' /lib/udev/rules.d/60-persistent-storage.rules:51
Mar 30 14:52:01 reztho udevd-event[6256]: IMPORT 'vol_id --export /dev/block/8:0' /lib/udev/rules.d/60-persistent-storage.rules:61
Mar 30 14:52:01 reztho udevd-event[6256]: 'vol_id --export /dev/block/8:0'
Mar 30 14:52:01 reztho udevd-event[6256]: '/lib/udev/vol_id' (stderr) 'unknown or non-unique volume type (--probe-all lists possibly conflicting types)'
Mar 30 14:52:01 reztho udevd-event[6256]: '/lib/udev/vol_id' returned with status 4
Mar 30 14:52:01 reztho udevd-event[6256]: IMPORT 'edd_id --export /dev/block/8:0' /lib/udev/rules.d/61-persistent-storage-edd.rules:8
Mar 30 14:52:01 reztho udevd-event[6256]: 'edd_id --export /dev/block/8:0'
Mar 30 14:52:02 reztho udevd-event[6256]: '/lib/udev/edd_id' (stderr) 'no kernel EDD support'
Mar 30 14:52:02 reztho udevd-event[6256]: '/lib/udev/edd_id' returned with status 2
Mar 30 14:52:02 reztho udevd-event[6256]: RUN 'root-link.sh' /lib/udev/rules.d/81-arch.rules:80
Mar 30 14:52:02 reztho udevd-event[6256]: RUN 'socket:/org/freedesktop/hal/udev_event' /etc/udev/rules.d/90-hal.rules:2
Mar 30 14:52:02 reztho udevd-event[6256]: RUN 'socket:@/org/kernel/udev/monitor' /lib/udev/rules.d/95-udev-late.rules:7
Mar 30 14:52:02 reztho udevd-event[6256]: no node name set, will use kernel name 'sda'
Mar 30 14:52:02 reztho udevd-event[6256]: created db file for '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda' in '/dev/.udev/db/\x2fdevices\x2fpci0000:00\x2f0000:00:0f.1\x2fhost0\x2ftarget0:0:0\x2f0:0:0:0\x2fblock\x2fsda'
Mar 30 14:52:02 reztho udevd-event[6256]: creating device node '/dev/sda', devnum=8:0, mode=0660, uid=0, gid=6
Mar 30 14:52:02 reztho udevd-event[6256]: preserve file '/dev/sda', because it has correct dev_t
Mar 30 14:52:02 reztho udevd-event[6256]: '/dev/block/8:0' with target '/dev/sda' has the highest priority 0, create it
Mar 30 14:52:02 reztho udevd-event[6256]: preserve already existing symlink '/dev/block/8:0' to '../sda'
Mar 30 14:52:02 reztho udevd-event[6256]: '/dev/disk/by-id/scsi-SATA_ST3120022A_5JT02J8Y' with target '/dev/sda' has the highest priority 0, create it
Mar 30 14:52:02 reztho udevd-event[6256]: preserve already existing symlink '/dev/disk/by-id/scsi-SATA_ST3120022A_5JT02J8Y' to '../../sda'
Mar 30 14:52:02 reztho udevd-event[6256]: '/dev/disk/by-id/ata-ST3120022A_5JT02J8Y' with target '/dev/sda' has the highest priority 0, create it
Mar 30 14:52:02 reztho udevd-event[6256]: preserve already existing symlink '/dev/disk/by-id/ata-ST3120022A_5JT02J8Y' to '../../sda'
Mar 30 14:52:02 reztho udevd-event[6256]: '/dev/disk/by-path/pci-0000:00:0f.1-scsi-0:0:0:0' with target '/dev/sda' has the highest priority 0, create it
Mar 30 14:52:02 reztho udevd-event[6256]: preserve already existing symlink '/dev/disk/by-path/pci-0000:00:0f.1-scsi-0:0:0:0' to '../../sda'
Mar 30 14:52:02 reztho udevd-event[6256]: 'root-link.sh'
Mar 30 14:52:02 reztho udevd-event[6256]: '/lib/udev/root-link.sh' returned with status 0
Mar 30 14:52:02 reztho udevd-event[6256]: passed 704 bytes to monitor 0x9390388, 
Mar 30 14:52:02 reztho udevd-event[6256]: passed 704 bytes to monitor 0x9390388, 
Mar 30 14:52:02 reztho udevd-event[6256]: adding watch on '/dev/sda'
Mar 30 14:52:02 reztho udevd-event[6256]: created db file for '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda' in '/dev/.udev/db/\x2fdevices\x2fpci0000:00\x2f0000:00:0f.1\x2fhost0\x2ftarget0:0:0\x2f0:0:0:0\x2fblock\x2fsda'
Mar 30 14:52:02 reztho udevd-event[6256]: seq 2313 exit with 0
Mar 30 15:22:02 reztho udevd-event[13988]: device 0x9390810 has devpath '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda'
Mar 30 15:22:02 reztho udevd-event[13988]: device 0x9390810 filled with db file data
Mar 30 15:22:02 reztho udevd-event[13988]: removing watch on '/dev/sda'
Mar 30 15:22:02 reztho udevd-event[13988]: LINK 'block/8:0' /lib/udev/rules.d/50-udev-default.rules:4
Mar 30 15:22:02 reztho udevd-event[13988]: GROUP 6 /lib/udev/rules.d/50-udev-default.rules:64
Mar 30 15:22:02 reztho udevd-event[13988]: device 0x9385070 has devpath '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0'
Mar 30 15:22:02 reztho udevd-event[13988]: device 0x93903c8 has devpath '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0'
Mar 30 15:22:02 reztho udevd-event[13988]: device 0x9390f58 has devpath '/devices/pci0000:00/0000:00:0f.1/host0'
Mar 30 15:22:02 reztho udevd-event[13988]: device 0x93910d0 has devpath '/devices/pci0000:00/0000:00:0f.1'
Mar 30 15:22:02 reztho udevd-event[13988]: device 0x9391288 has devpath '/devices/pci0000:00'
Mar 30 15:22:02 reztho udevd-event[13988]: GROUP 6 /lib/udev/rules.d/50-udev-default.rules:85
Mar 30 15:22:02 reztho udevd-event[13988]: IMPORT 'scsi_id --export --whitelisted -d /dev/block/8:0' /lib/udev/rules.d/60-persistent-storage.rules:31
Mar 30 15:22:02 reztho udevd-event[13988]: 'scsi_id --export --whitelisted -d /dev/block/8:0'
Mar 30 15:22:02 reztho udevd-event[13988]: '/lib/udev/scsi_id' (stdout) 'ID_VENDOR=ATA'
Mar 30 15:22:02 reztho udevd-event[13988]: '/lib/udev/scsi_id' (stdout) 'ID_VENDOR_ENC=ATA\x20\x20\x20\x20\x20'
Mar 30 15:22:02 reztho udevd-event[13988]: '/lib/udev/scsi_id' (stdout) 'ID_MODEL=ST3120022A'
Mar 30 15:22:02 reztho udevd-event[13988]: '/lib/udev/scsi_id' (stdout) 'ID_MODEL_ENC=ST3120022A\x20\x20\x20\x20\x20\x20'
Mar 30 15:22:02 reztho udevd-event[13988]: '/lib/udev/scsi_id' (stdout) 'ID_REVISION=3.06'
Mar 30 15:22:02 reztho udevd-event[13988]: '/lib/udev/scsi_id' (stdout) 'ID_TYPE=disk'
Mar 30 15:22:02 reztho udevd-event[13988]: '/lib/udev/scsi_id' (stdout) 'ID_SERIAL=SATA_ST3120022A_5JT02J8Y'
Mar 30 15:22:02 reztho udevd-event[13988]: '/lib/udev/scsi_id' (stdout) 'ID_SERIAL_SHORT=5JT02J8Y'
Mar 30 15:22:02 reztho udevd-event[13988]: '/lib/udev/scsi_id' returned with status 0
Mar 30 15:22:02 reztho udevd-event[13988]: LINK 'disk/by-id/scsi-SATA_ST3120022A_5JT02J8Y' /lib/udev/rules.d/60-persistent-storage.rules:33
Mar 30 15:22:02 reztho udevd-event[13988]: PROGRAM 'ata_id /dev/block/8:0' /lib/udev/rules.d/60-persistent-storage.rules:41
Mar 30 15:22:02 reztho udevd-event[13988]: 'ata_id /dev/block/8:0'
Mar 30 15:22:02 reztho udevd-event[13988]: '/lib/udev/ata_id' (stdout) 'ST3120022A_5JT02J8Y'
Mar 30 15:22:02 reztho udevd-event[13988]: '/lib/udev/ata_id' returned with status 0
Mar 30 15:22:02 reztho udevd-event[13988]: LINK 'disk/by-id/ata-ST3120022A_5JT02J8Y' /lib/udev/rules.d/60-persistent-storage.rules:41
Mar 30 15:22:02 reztho udevd-event[13988]: IMPORT 'path_id /devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda' /lib/udev/rules.d/60-persistent-storage.rules:50
Mar 30 15:22:02 reztho udevd-event[13988]: 'path_id /devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda'
Mar 30 15:22:02 reztho udevd-event[13988]: '/lib/udev/path_id' (stdout) 'ID_PATH=pci-0000:00:0f.1-scsi-0:0:0:0'
Mar 30 15:22:02 reztho udevd-event[13988]: '/lib/udev/path_id' returned with status 0
Mar 30 15:22:02 reztho udevd-event[13988]: LINK 'disk/by-path/pci-0000:00:0f.1-scsi-0:0:0:0' /lib/udev/rules.d/60-persistent-storage.rules:51
Mar 30 15:22:02 reztho udevd-event[13988]: IMPORT 'vol_id --export /dev/block/8:0' /lib/udev/rules.d/60-persistent-storage.rules:61
Mar 30 15:22:02 reztho udevd-event[13988]: 'vol_id --export /dev/block/8:0'
Mar 30 15:22:08 reztho udevd-event[13988]: '/lib/udev/vol_id' (stderr) 'unknown or non-unique volume type (--probe-all lists possibly conflicting types)'
Mar 30 15:22:08 reztho udevd-event[13988]: '/lib/udev/vol_id' returned with status 4
Mar 30 15:22:08 reztho udevd-event[13988]: IMPORT 'edd_id --export /dev/block/8:0' /lib/udev/rules.d/61-persistent-storage-edd.rules:8
Mar 30 15:22:08 reztho udevd-event[13988]: 'edd_id --export /dev/block/8:0'
Mar 30 15:22:08 reztho udevd-event[13988]: '/lib/udev/edd_id' (stderr) 'no kernel EDD support'
Mar 30 15:22:08 reztho udevd-event[13988]: '/lib/udev/edd_id' returned with status 2
Mar 30 15:22:08 reztho udevd-event[13988]: RUN 'root-link.sh' /lib/udev/rules.d/81-arch.rules:80
Mar 30 15:22:08 reztho udevd-event[13988]: RUN 'socket:/org/freedesktop/hal/udev_event' /etc/udev/rules.d/90-hal.rules:2
Mar 30 15:22:08 reztho udevd-event[13988]: RUN 'socket:@/org/kernel/udev/monitor' /lib/udev/rules.d/95-udev-late.rules:7
Mar 30 15:22:08 reztho udevd-event[13988]: no node name set, will use kernel name 'sda'
Mar 30 15:22:08 reztho udevd-event[13988]: created db file for '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda' in '/dev/.udev/db/\x2fdevices\x2fpci0000:00\x2f0000:00:0f.1\x2fhost0\x2ftarget0:0:0\x2f0:0:0:0\x2fblock\x2fsda'
Mar 30 15:22:08 reztho udevd-event[13988]: creating device node '/dev/sda', devnum=8:0, mode=0660, uid=0, gid=6
Mar 30 15:22:08 reztho udevd-event[13988]: preserve file '/dev/sda', because it has correct dev_t
Mar 30 15:22:08 reztho udevd-event[13988]: '/dev/block/8:0' with target '/dev/sda' has the highest priority 0, create it
Mar 30 15:22:08 reztho udevd-event[13988]: preserve already existing symlink '/dev/block/8:0' to '../sda'
Mar 30 15:22:08 reztho udevd-event[13988]: '/dev/disk/by-id/scsi-SATA_ST3120022A_5JT02J8Y' with target '/dev/sda' has the highest priority 0, create it
Mar 30 15:22:08 reztho udevd-event[13988]: preserve already existing symlink '/dev/disk/by-id/scsi-SATA_ST3120022A_5JT02J8Y' to '../../sda'
Mar 30 15:22:08 reztho udevd-event[13988]: '/dev/disk/by-id/ata-ST3120022A_5JT02J8Y' with target '/dev/sda' has the highest priority 0, create it
Mar 30 15:22:08 reztho udevd-event[13988]: preserve already existing symlink '/dev/disk/by-id/ata-ST3120022A_5JT02J8Y' to '../../sda'
Mar 30 15:22:08 reztho udevd-event[13988]: '/dev/disk/by-path/pci-0000:00:0f.1-scsi-0:0:0:0' with target '/dev/sda' has the highest priority 0, create it
Mar 30 15:22:08 reztho udevd-event[13988]: preserve already existing symlink '/dev/disk/by-path/pci-0000:00:0f.1-scsi-0:0:0:0' to '../../sda'
Mar 30 15:22:08 reztho udevd-event[13988]: 'root-link.sh'
Mar 30 15:22:08 reztho udevd-event[13988]: '/lib/udev/root-link.sh' returned with status 0
Mar 30 15:22:08 reztho udevd-event[13988]: passed 704 bytes to monitor 0x9390810, 
Mar 30 15:22:08 reztho udevd-event[13988]: passed 704 bytes to monitor 0x9390810, 
Mar 30 15:22:08 reztho udevd-event[13988]: adding watch on '/dev/sda'
Mar 30 15:22:08 reztho udevd-event[13988]: created db file for '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda' in '/dev/.udev/db/\x2fdevices\x2fpci0000:00\x2f0000:00:0f.1\x2fhost0\x2ftarget0:0:0\x2f0:0:0:0\x2fblock\x2fsda'
Mar 30 15:22:08 reztho udevd-event[13988]: seq 2314 exit with 0
Mar 30 15:42:17 reztho udevd-event[21250]: device 0x93905b8 has devpath '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda'
Mar 30 15:42:17 reztho udevd-event[21250]: device 0x93905b8 filled with db file data
Mar 30 15:42:17 reztho udevd-event[21250]: removing watch on '/dev/sda'
Mar 30 15:42:17 reztho udevd-event[21250]: LINK 'block/8:0' /lib/udev/rules.d/50-udev-default.rules:4
Mar 30 15:42:17 reztho udevd-event[21250]: GROUP 6 /lib/udev/rules.d/50-udev-default.rules:64
Mar 30 15:42:17 reztho udevd-event[21250]: device 0x9390388 has devpath '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0'
Mar 30 15:42:17 reztho udevd-event[21250]: device 0x9385070 has devpath '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0'
Mar 30 15:42:17 reztho udevd-event[21250]: device 0x9390ab0 has devpath '/devices/pci0000:00/0000:00:0f.1/host0'
Mar 30 15:42:17 reztho udevd-event[21250]: device 0x93910e0 has devpath '/devices/pci0000:00/0000:00:0f.1'
Mar 30 15:42:17 reztho udevd-event[21250]: device 0x9391298 has devpath '/devices/pci0000:00'
Mar 30 15:42:17 reztho udevd-event[21250]: GROUP 6 /lib/udev/rules.d/50-udev-default.rules:85
Mar 30 15:42:17 reztho udevd-event[21250]: IMPORT 'scsi_id --export --whitelisted -d /dev/block/8:0' /lib/udev/rules.d/60-persistent-storage.rules:31
Mar 30 15:42:17 reztho udevd-event[21250]: 'scsi_id --export --whitelisted -d /dev/block/8:0'
Mar 30 15:42:17 reztho udevd-event[21250]: '/lib/udev/scsi_id' (stdout) 'ID_VENDOR=ATA'
Mar 30 15:42:17 reztho udevd-event[21250]: '/lib/udev/scsi_id' (stdout) 'ID_VENDOR_ENC=ATA\x20\x20\x20\x20\x20'
Mar 30 15:42:17 reztho udevd-event[21250]: '/lib/udev/scsi_id' (stdout) 'ID_MODEL=ST3120022A'
Mar 30 15:42:17 reztho udevd-event[21250]: '/lib/udev/scsi_id' (stdout) 'ID_MODEL_ENC=ST3120022A\x20\x20\x20\x20\x20\x20'
Mar 30 15:42:17 reztho udevd-event[21250]: '/lib/udev/scsi_id' (stdout) 'ID_REVISION=3.06'
Mar 30 15:42:17 reztho udevd-event[21250]: '/lib/udev/scsi_id' (stdout) 'ID_TYPE=disk'
Mar 30 15:42:17 reztho udevd-event[21250]: '/lib/udev/scsi_id' (stdout) 'ID_SERIAL=SATA_ST3120022A_5JT02J8Y'
Mar 30 15:42:17 reztho udevd-event[21250]: '/lib/udev/scsi_id' (stdout) 'ID_SERIAL_SHORT=5JT02J8Y'
Mar 30 15:42:17 reztho udevd-event[21250]: '/lib/udev/scsi_id' returned with status 0
Mar 30 15:42:17 reztho udevd-event[21250]: LINK 'disk/by-id/scsi-SATA_ST3120022A_5JT02J8Y' /lib/udev/rules.d/60-persistent-storage.rules:33
Mar 30 15:42:17 reztho udevd-event[21250]: PROGRAM 'ata_id /dev/block/8:0' /lib/udev/rules.d/60-persistent-storage.rules:41
Mar 30 15:42:17 reztho udevd-event[21250]: 'ata_id /dev/block/8:0'
Mar 30 15:42:17 reztho udevd-event[21250]: '/lib/udev/ata_id' (stdout) 'ST3120022A_5JT02J8Y'
Mar 30 15:42:17 reztho udevd-event[21250]: '/lib/udev/ata_id' returned with status 0
Mar 30 15:42:17 reztho udevd-event[21250]: LINK 'disk/by-id/ata-ST3120022A_5JT02J8Y' /lib/udev/rules.d/60-persistent-storage.rules:41
Mar 30 15:42:17 reztho udevd-event[21250]: IMPORT 'path_id /devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda' /lib/udev/rules.d/60-persistent-storage.rules:50
Mar 30 15:42:17 reztho udevd-event[21250]: 'path_id /devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda'
Mar 30 15:42:17 reztho udevd-event[21250]: '/lib/udev/path_id' (stdout) 'ID_PATH=pci-0000:00:0f.1-scsi-0:0:0:0'
Mar 30 15:42:17 reztho udevd-event[21250]: '/lib/udev/path_id' returned with status 0
Mar 30 15:42:17 reztho udevd-event[21250]: LINK 'disk/by-path/pci-0000:00:0f.1-scsi-0:0:0:0' /lib/udev/rules.d/60-persistent-storage.rules:51
Mar 30 15:42:17 reztho udevd-event[21250]: IMPORT 'vol_id --export /dev/block/8:0' /lib/udev/rules.d/60-persistent-storage.rules:61
Mar 30 15:42:17 reztho udevd-event[21250]: 'vol_id --export /dev/block/8:0'
Mar 30 15:42:17 reztho udevd-event[21250]: '/lib/udev/vol_id' (stderr) 'unknown or non-unique volume type (--probe-all lists possibly conflicting types)'
Mar 30 15:42:17 reztho udevd-event[21250]: '/lib/udev/vol_id' returned with status 4
Mar 30 15:42:17 reztho udevd-event[21250]: IMPORT 'edd_id --export /dev/block/8:0' /lib/udev/rules.d/61-persistent-storage-edd.rules:8
Mar 30 15:42:17 reztho udevd-event[21250]: 'edd_id --export /dev/block/8:0'
Mar 30 15:42:17 reztho udevd-event[21250]: '/lib/udev/edd_id' (stderr) 'no kernel EDD support'
Mar 30 15:42:17 reztho udevd-event[21250]: '/lib/udev/edd_id' returned with status 2
Mar 30 15:42:17 reztho udevd-event[21250]: RUN 'root-link.sh' /lib/udev/rules.d/81-arch.rules:80
Mar 30 15:42:17 reztho udevd-event[21250]: RUN 'socket:/org/freedesktop/hal/udev_event' /etc/udev/rules.d/90-hal.rules:2
Mar 30 15:42:17 reztho udevd-event[21250]: RUN 'socket:@/org/kernel/udev/monitor' /lib/udev/rules.d/95-udev-late.rules:7
Mar 30 15:42:17 reztho udevd-event[21250]: no node name set, will use kernel name 'sda'
Mar 30 15:42:17 reztho udevd-event[21250]: created db file for '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda' in '/dev/.udev/db/\x2fdevices\x2fpci0000:00\x2f0000:00:0f.1\x2fhost0\x2ftarget0:0:0\x2f0:0:0:0\x2fblock\x2fsda'
Mar 30 15:42:17 reztho udevd-event[21250]: creating device node '/dev/sda', devnum=8:0, mode=0660, uid=0, gid=6
Mar 30 15:42:17 reztho udevd-event[21250]: preserve file '/dev/sda', because it has correct dev_t
Mar 30 15:42:17 reztho udevd-event[21250]: '/dev/block/8:0' with target '/dev/sda' has the highest priority 0, create it
Mar 30 15:42:17 reztho udevd-event[21250]: preserve already existing symlink '/dev/block/8:0' to '../sda'
Mar 30 15:42:17 reztho udevd-event[21250]: '/dev/disk/by-id/scsi-SATA_ST3120022A_5JT02J8Y' with target '/dev/sda' has the highest priority 0, create it
Mar 30 15:42:17 reztho udevd-event[21250]: preserve already existing symlink '/dev/disk/by-id/scsi-SATA_ST3120022A_5JT02J8Y' to '../../sda'
Mar 30 15:42:17 reztho udevd-event[21250]: '/dev/disk/by-id/ata-ST3120022A_5JT02J8Y' with target '/dev/sda' has the highest priority 0, create it
Mar 30 15:42:17 reztho udevd-event[21250]: preserve already existing symlink '/dev/disk/by-id/ata-ST3120022A_5JT02J8Y' to '../../sda'
Mar 30 15:42:17 reztho udevd-event[21250]: '/dev/disk/by-path/pci-0000:00:0f.1-scsi-0:0:0:0' with target '/dev/sda' has the highest priority 0, create it
Mar 30 15:42:17 reztho udevd-event[21250]: preserve already existing symlink '/dev/disk/by-path/pci-0000:00:0f.1-scsi-0:0:0:0' to '../../sda'
Mar 30 15:42:17 reztho udevd-event[21250]: 'root-link.sh'
Mar 30 15:42:17 reztho udevd-event[21250]: '/lib/udev/root-link.sh' returned with status 0
Mar 30 15:42:17 reztho udevd-event[21250]: passed 704 bytes to monitor 0x93905b8, 
Mar 30 15:42:17 reztho udevd-event[21250]: passed 704 bytes to monitor 0x93905b8, 
Mar 30 15:42:17 reztho udevd-event[21250]: adding watch on '/dev/sda'
Mar 30 15:42:17 reztho udevd-event[21250]: created db file for '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda' in '/dev/.udev/db/\x2fdevices\x2fpci0000:00\x2f0000:00:0f.1\x2fhost0\x2ftarget0:0:0\x2f0:0:0:0\x2fblock\x2fsda'
Mar 30 15:42:17 reztho udevd-event[21250]: seq 2315 exit with 0
Mar 30 15:52:01 reztho udevd-event[23306]: device 0x9385070 has devpath '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda'
Mar 30 15:52:01 reztho udevd-event[23306]: device 0x9385070 filled with db file data
Mar 30 15:52:01 reztho udevd-event[23306]: removing watch on '/dev/sda'
Mar 30 15:52:01 reztho udevd-event[23306]: LINK 'block/8:0' /lib/udev/rules.d/50-udev-default.rules:4
Mar 30 15:52:01 reztho udevd-event[23306]: GROUP 6 /lib/udev/rules.d/50-udev-default.rules:64
Mar 30 15:52:01 reztho udevd-event[23306]: device 0x9395250 has devpath '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0'
Mar 30 15:52:01 reztho udevd-event[23306]: device 0x9395478 has devpath '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0'
Mar 30 15:52:01 reztho udevd-event[23306]: device 0x9395968 has devpath '/devices/pci0000:00/0000:00:0f.1/host0'
Mar 30 15:52:01 reztho udevd-event[23306]: device 0x9395f90 has devpath '/devices/pci0000:00/0000:00:0f.1'
Mar 30 15:52:01 reztho udevd-event[23306]: device 0x9396148 has devpath '/devices/pci0000:00'
Mar 30 15:52:01 reztho udevd-event[23306]: GROUP 6 /lib/udev/rules.d/50-udev-default.rules:85
Mar 30 15:52:01 reztho udevd-event[23306]: IMPORT 'scsi_id --export --whitelisted -d /dev/block/8:0' /lib/udev/rules.d/60-persistent-storage.rules:31
Mar 30 15:52:01 reztho udevd-event[23306]: 'scsi_id --export --whitelisted -d /dev/block/8:0'
Mar 30 15:52:01 reztho udevd-event[23306]: '/lib/udev/scsi_id' (stdout) 'ID_VENDOR=ATA'
Mar 30 15:52:01 reztho udevd-event[23306]: '/lib/udev/scsi_id' (stdout) 'ID_VENDOR_ENC=ATA\x20\x20\x20\x20\x20'
Mar 30 15:52:01 reztho udevd-event[23306]: '/lib/udev/scsi_id' (stdout) 'ID_MODEL=ST3120022A'
Mar 30 15:52:01 reztho udevd-event[23306]: '/lib/udev/scsi_id' (stdout) 'ID_MODEL_ENC=ST3120022A\x20\x20\x20\x20\x20\x20'
Mar 30 15:52:01 reztho udevd-event[23306]: '/lib/udev/scsi_id' (stdout) 'ID_REVISION=3.06'
Mar 30 15:52:01 reztho udevd-event[23306]: '/lib/udev/scsi_id' (stdout) 'ID_TYPE=disk'
Mar 30 15:52:01 reztho udevd-event[23306]: '/lib/udev/scsi_id' (stdout) 'ID_SERIAL=SATA_ST3120022A_5JT02J8Y'
Mar 30 15:52:01 reztho udevd-event[23306]: '/lib/udev/scsi_id' (stdout) 'ID_SERIAL_SHORT=5JT02J8Y'
Mar 30 15:52:01 reztho udevd-event[23306]: '/lib/udev/scsi_id' returned with status 0
Mar 30 15:52:01 reztho udevd-event[23306]: LINK 'disk/by-id/scsi-SATA_ST3120022A_5JT02J8Y' /lib/udev/rules.d/60-persistent-storage.rules:33
Mar 30 15:52:01 reztho udevd-event[23306]: PROGRAM 'ata_id /dev/block/8:0' /lib/udev/rules.d/60-persistent-storage.rules:41
Mar 30 15:52:01 reztho udevd-event[23306]: 'ata_id /dev/block/8:0'
Mar 30 15:52:01 reztho udevd-event[23306]: '/lib/udev/ata_id' (stdout) 'ST3120022A_5JT02J8Y'
Mar 30 15:52:01 reztho udevd-event[23306]: '/lib/udev/ata_id' returned with status 0
Mar 30 15:52:01 reztho udevd-event[23306]: LINK 'disk/by-id/ata-ST3120022A_5JT02J8Y' /lib/udev/rules.d/60-persistent-storage.rules:41
Mar 30 15:52:01 reztho udevd-event[23306]: IMPORT 'path_id /devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda' /lib/udev/rules.d/60-persistent-storage.rules:50
Mar 30 15:52:01 reztho udevd-event[23306]: 'path_id /devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda'
Mar 30 15:52:01 reztho udevd-event[23306]: '/lib/udev/path_id' (stdout) 'ID_PATH=pci-0000:00:0f.1-scsi-0:0:0:0'
Mar 30 15:52:01 reztho udevd-event[23306]: '/lib/udev/path_id' returned with status 0
Mar 30 15:52:01 reztho udevd-event[23306]: LINK 'disk/by-path/pci-0000:00:0f.1-scsi-0:0:0:0' /lib/udev/rules.d/60-persistent-storage.rules:51
Mar 30 15:52:01 reztho udevd-event[23306]: IMPORT 'vol_id --export /dev/block/8:0' /lib/udev/rules.d/60-persistent-storage.rules:61
Mar 30 15:52:01 reztho udevd-event[23306]: 'vol_id --export /dev/block/8:0'
Mar 30 15:52:01 reztho udevd-event[23306]: '/lib/udev/vol_id' (stderr) 'unknown or non-unique volume type (--probe-all lists possibly conflicting types)'
Mar 30 15:52:01 reztho udevd-event[23306]: '/lib/udev/vol_id' returned with status 4
Mar 30 15:52:01 reztho udevd-event[23306]: IMPORT 'edd_id --export /dev/block/8:0' /lib/udev/rules.d/61-persistent-storage-edd.rules:8
Mar 30 15:52:01 reztho udevd-event[23306]: 'edd_id --export /dev/block/8:0'
Mar 30 15:52:01 reztho udevd-event[23306]: '/lib/udev/edd_id' (stderr) 'no kernel EDD support'
Mar 30 15:52:01 reztho udevd-event[23306]: '/lib/udev/edd_id' returned with status 2
Mar 30 15:52:01 reztho udevd-event[23306]: RUN 'root-link.sh' /lib/udev/rules.d/81-arch.rules:80
Mar 30 15:52:01 reztho udevd-event[23306]: RUN 'socket:/org/freedesktop/hal/udev_event' /etc/udev/rules.d/90-hal.rules:2
Mar 30 15:52:01 reztho udevd-event[23306]: RUN 'socket:@/org/kernel/udev/monitor' /lib/udev/rules.d/95-udev-late.rules:7
Mar 30 15:52:01 reztho udevd-event[23306]: no node name set, will use kernel name 'sda'
Mar 30 15:52:01 reztho udevd-event[23306]: created db file for '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda' in '/dev/.udev/db/\x2fdevices\x2fpci0000:00\x2f0000:00:0f.1\x2fhost0\x2ftarget0:0:0\x2f0:0:0:0\x2fblock\x2fsda'
Mar 30 15:52:01 reztho udevd-event[23306]: creating device node '/dev/sda', devnum=8:0, mode=0660, uid=0, gid=6
Mar 30 15:52:01 reztho udevd-event[23306]: preserve file '/dev/sda', because it has correct dev_t
Mar 30 15:52:01 reztho udevd-event[23306]: '/dev/block/8:0' with target '/dev/sda' has the highest priority 0, create it
Mar 30 15:52:01 reztho udevd-event[23306]: preserve already existing symlink '/dev/block/8:0' to '../sda'
Mar 30 15:52:01 reztho udevd-event[23306]: '/dev/disk/by-id/scsi-SATA_ST3120022A_5JT02J8Y' with target '/dev/sda' has the highest priority 0, create it
Mar 30 15:52:01 reztho udevd-event[23306]: preserve already existing symlink '/dev/disk/by-id/scsi-SATA_ST3120022A_5JT02J8Y' to '../../sda'
Mar 30 15:52:01 reztho udevd-event[23306]: '/dev/disk/by-id/ata-ST3120022A_5JT02J8Y' with target '/dev/sda' has the highest priority 0, create it
Mar 30 15:52:01 reztho udevd-event[23306]: preserve already existing symlink '/dev/disk/by-id/ata-ST3120022A_5JT02J8Y' to '../../sda'
Mar 30 15:52:01 reztho udevd-event[23306]: '/dev/disk/by-path/pci-0000:00:0f.1-scsi-0:0:0:0' with target '/dev/sda' has the highest priority 0, create it
Mar 30 15:52:01 reztho udevd-event[23306]: preserve already existing symlink '/dev/disk/by-path/pci-0000:00:0f.1-scsi-0:0:0:0' to '../../sda'
Mar 30 15:52:01 reztho udevd-event[23306]: 'root-link.sh'
Mar 30 15:52:01 reztho udevd-event[23306]: '/lib/udev/root-link.sh' returned with status 0
Mar 30 15:52:01 reztho udevd-event[23306]: passed 704 bytes to monitor 0x9385070, 
Mar 30 15:52:01 reztho udevd-event[23306]: passed -1 bytes to monitor 0x9385070, 
Mar 30 15:52:01 reztho udevd-event[23306]: adding watch on '/dev/sda'
Mar 30 15:52:01 reztho udevd-event[23306]: created db file for '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda' in '/dev/.udev/db/\x2fdevices\x2fpci0000:00\x2f0000:00:0f.1\x2fhost0\x2ftarget0:0:0\x2f0:0:0:0\x2fblock\x2fsda'
Mar 30 15:52:01 reztho udevd-event[23306]: seq 2316 exit with 0
Mar 30 16:22:02 reztho udevd-event[29260]: device 0x9395640 has devpath '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda'
Mar 30 16:22:02 reztho udevd-event[29260]: device 0x9395640 filled with db file data
Mar 30 16:22:02 reztho udevd-event[29260]: removing watch on '/dev/sda'
Mar 30 16:22:02 reztho udevd-event[29260]: LINK 'block/8:0' /lib/udev/rules.d/50-udev-default.rules:4
Mar 30 16:22:02 reztho udevd-event[29260]: GROUP 6 /lib/udev/rules.d/50-udev-default.rules:64
Mar 30 16:22:02 reztho udevd-event[29260]: device 0x9395520 has devpath '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0'
Mar 30 16:22:02 reztho udevd-event[29260]: device 0x9385070 has devpath '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0'
Mar 30 16:22:02 reztho udevd-event[29260]: device 0x9395948 has devpath '/devices/pci0000:00/0000:00:0f.1/host0'
Mar 30 16:22:02 reztho udevd-event[29260]: device 0x9395fa8 has devpath '/devices/pci0000:00/0000:00:0f.1'
Mar 30 16:22:02 reztho udevd-event[29260]: device 0x9396160 has devpath '/devices/pci0000:00'
Mar 30 16:22:02 reztho udevd-event[29260]: GROUP 6 /lib/udev/rules.d/50-udev-default.rules:85
Mar 30 16:22:02 reztho udevd-event[29260]: IMPORT 'scsi_id --export --whitelisted -d /dev/block/8:0' /lib/udev/rules.d/60-persistent-storage.rules:31
Mar 30 16:22:02 reztho udevd-event[29260]: 'scsi_id --export --whitelisted -d /dev/block/8:0'
Mar 30 16:22:02 reztho udevd-event[29260]: '/lib/udev/scsi_id' (stdout) 'ID_VENDOR=ATA'
Mar 30 16:22:02 reztho udevd-event[29260]: '/lib/udev/scsi_id' (stdout) 'ID_VENDOR_ENC=ATA\x20\x20\x20\x20\x20'
Mar 30 16:22:02 reztho udevd-event[29260]: '/lib/udev/scsi_id' (stdout) 'ID_MODEL=ST3120022A'
Mar 30 16:22:02 reztho udevd-event[29260]: '/lib/udev/scsi_id' (stdout) 'ID_MODEL_ENC=ST3120022A\x20\x20\x20\x20\x20\x20'
Mar 30 16:22:02 reztho udevd-event[29260]: '/lib/udev/scsi_id' (stdout) 'ID_REVISION=3.06'
Mar 30 16:22:02 reztho udevd-event[29260]: '/lib/udev/scsi_id' (stdout) 'ID_TYPE=disk'
Mar 30 16:22:02 reztho udevd-event[29260]: '/lib/udev/scsi_id' (stdout) 'ID_SERIAL=SATA_ST3120022A_5JT02J8Y'
Mar 30 16:22:02 reztho udevd-event[29260]: '/lib/udev/scsi_id' (stdout) 'ID_SERIAL_SHORT=5JT02J8Y'
Mar 30 16:22:02 reztho udevd-event[29260]: '/lib/udev/scsi_id' returned with status 0
Mar 30 16:22:02 reztho udevd-event[29260]: LINK 'disk/by-id/scsi-SATA_ST3120022A_5JT02J8Y' /lib/udev/rules.d/60-persistent-storage.rules:33
Mar 30 16:22:02 reztho udevd-event[29260]: PROGRAM 'ata_id /dev/block/8:0' /lib/udev/rules.d/60-persistent-storage.rules:41
Mar 30 16:22:02 reztho udevd-event[29260]: 'ata_id /dev/block/8:0'
Mar 30 16:22:02 reztho udevd-event[29260]: '/lib/udev/ata_id' (stdout) 'ST3120022A_5JT02J8Y'
Mar 30 16:22:02 reztho udevd-event[29260]: '/lib/udev/ata_id' returned with status 0
Mar 30 16:22:02 reztho udevd-event[29260]: LINK 'disk/by-id/ata-ST3120022A_5JT02J8Y' /lib/udev/rules.d/60-persistent-storage.rules:41
Mar 30 16:22:02 reztho udevd-event[29260]: IMPORT 'path_id /devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda' /lib/udev/rules.d/60-persistent-storage.rules:50
Mar 30 16:22:02 reztho udevd-event[29260]: 'path_id /devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda'
Mar 30 16:22:02 reztho udevd-event[29260]: '/lib/udev/path_id' (stdout) 'ID_PATH=pci-0000:00:0f.1-scsi-0:0:0:0'
Mar 30 16:22:02 reztho udevd-event[29260]: '/lib/udev/path_id' returned with status 0
Mar 30 16:22:02 reztho udevd-event[29260]: LINK 'disk/by-path/pci-0000:00:0f.1-scsi-0:0:0:0' /lib/udev/rules.d/60-persistent-storage.rules:51
Mar 30 16:22:02 reztho udevd-event[29260]: IMPORT 'vol_id --export /dev/block/8:0' /lib/udev/rules.d/60-persistent-storage.rules:61
Mar 30 16:22:02 reztho udevd-event[29260]: 'vol_id --export /dev/block/8:0'
Mar 30 16:22:08 reztho udevd-event[29260]: '/lib/udev/vol_id' (stderr) 'unknown or non-unique volume type (--probe-all lists possibly conflicting types)'
Mar 30 16:22:08 reztho udevd-event[29260]: '/lib/udev/vol_id' returned with status 4
Mar 30 16:22:08 reztho udevd-event[29260]: IMPORT 'edd_id --export /dev/block/8:0' /lib/udev/rules.d/61-persistent-storage-edd.rules:8
Mar 30 16:22:08 reztho udevd-event[29260]: 'edd_id --export /dev/block/8:0'
Mar 30 16:22:08 reztho udevd-event[29260]: '/lib/udev/edd_id' (stderr) 'no kernel EDD support'
Mar 30 16:22:08 reztho udevd-event[29260]: '/lib/udev/edd_id' returned with status 2
Mar 30 16:22:08 reztho udevd-event[29260]: RUN 'root-link.sh' /lib/udev/rules.d/81-arch.rules:80
Mar 30 16:22:08 reztho udevd-event[29260]: RUN 'socket:/org/freedesktop/hal/udev_event' /etc/udev/rules.d/90-hal.rules:2
Mar 30 16:22:08 reztho udevd-event[29260]: RUN 'socket:@/org/kernel/udev/monitor' /lib/udev/rules.d/95-udev-late.rules:7
Mar 30 16:22:08 reztho udevd-event[29260]: no node name set, will use kernel name 'sda'
Mar 30 16:22:08 reztho udevd-event[29260]: created db file for '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda' in '/dev/.udev/db/\x2fdevices\x2fpci0000:00\x2f0000:00:0f.1\x2fhost0\x2ftarget0:0:0\x2f0:0:0:0\x2fblock\x2fsda'
Mar 30 16:22:08 reztho udevd-event[29260]: creating device node '/dev/sda', devnum=8:0, mode=0660, uid=0, gid=6
Mar 30 16:22:08 reztho udevd-event[29260]: preserve file '/dev/sda', because it has correct dev_t
Mar 30 16:22:08 reztho udevd-event[29260]: '/dev/block/8:0' with target '/dev/sda' has the highest priority 0, create it
Mar 30 16:22:08 reztho udevd-event[29260]: preserve already existing symlink '/dev/block/8:0' to '../sda'
Mar 30 16:22:08 reztho udevd-event[29260]: '/dev/disk/by-id/scsi-SATA_ST3120022A_5JT02J8Y' with target '/dev/sda' has the highest priority 0, create it
Mar 30 16:22:08 reztho udevd-event[29260]: preserve already existing symlink '/dev/disk/by-id/scsi-SATA_ST3120022A_5JT02J8Y' to '../../sda'
Mar 30 16:22:08 reztho udevd-event[29260]: '/dev/disk/by-id/ata-ST3120022A_5JT02J8Y' with target '/dev/sda' has the highest priority 0, create it
Mar 30 16:22:08 reztho udevd-event[29260]: preserve already existing symlink '/dev/disk/by-id/ata-ST3120022A_5JT02J8Y' to '../../sda'
Mar 30 16:22:08 reztho udevd-event[29260]: '/dev/disk/by-path/pci-0000:00:0f.1-scsi-0:0:0:0' with target '/dev/sda' has the highest priority 0, create it
Mar 30 16:22:08 reztho udevd-event[29260]: preserve already existing symlink '/dev/disk/by-path/pci-0000:00:0f.1-scsi-0:0:0:0' to '../../sda'
Mar 30 16:22:08 reztho udevd-event[29260]: 'root-link.sh'
Mar 30 16:22:08 reztho udevd-event[29260]: '/lib/udev/root-link.sh' returned with status 0
Mar 30 16:22:08 reztho udevd-event[29260]: passed 704 bytes to monitor 0x9395640, 
Mar 30 16:22:08 reztho udevd-event[29260]: passed -1 bytes to monitor 0x9395640, 
Mar 30 16:22:08 reztho udevd-event[29260]: adding watch on '/dev/sda'
Mar 30 16:22:08 reztho udevd-event[29260]: created db file for '/devices/pci0000:00/0000:00:0f.1/host0/target0:0:0/0:0:0:0/block/sda' in '/dev/.udev/db/\x2fdevices\x2fpci0000:00\x2f0000:00:0f.1\x2fhost0\x2ftarget0:0:0\x2f0:0:0:0\x2fblock\x2fsda'
Mar 30 16:22:08 reztho udevd-event[29260]: seq 2317 exit with 0
#3 lore raw
In reply to Tom
Quoted 9 lines · Tom
Quoted 8 lines · Kay Sievers
Quoted 6 lines · Tom

 I have a spare hard disk I don't touch the most of the time. Due to a strange reason, a change uevent is triggered some random time of the day (like 4 or more times) and then, of course, udev apply the rules, being one of them a rule which runs vol_id which then wakes up my spinned-down hard disk (debugged with echoing a 1 to /proc/sys/vm/block_dump and udevadm monitor).

Quoted 7 lines
Quoted 4 lines

Does "monitor" show a kernel event, or only a udev event?

Any other events at the same time?

I don't remember exactly (if I remember correctly, it was one uevent everytime). Here I attach the logs. Later I could tell you better. I will be more aware of that.

If there is no kernel event, if might be udev itself watching for possible changes done by userspace programs to the disk.

Quoted 6 lines
Quoted 1 line

Are you running smartd?

Yes, but I have smartd configured to ignore the hard disk if it's in standby. In syslog I have:
smartd[7004]: Device: /dev/sda, is in STANDBY mode, suspending checks

And thanks for answering so fast (and wasting your time too).

But I guess it open the device node writable, so it might trigger a change event.

Kay

author
#4 lore raw
In reply to Tom

The "removing watch on '/dev/sda'" is my fault. I added this rule:
KERNEL=="sda*", OPTIONS-="watch", to /etc/udev/rules.d/99-user.rules , which I'm seeing it doesn't have the effect I wanted.

I'm running udevadm monitor right now, so if you need that information I will mail it to you later. Thanks.

Quoted 25 lines · Tom
Quoted 11 lines · Kay Sievers
Quoted 6 lines · Tom

I have a spare hard disk I don't touch the most of the time. Due to a
strange reason, a change uevent is triggered some random time of the day (like 4 or more times) and then, of course, udev apply the rules, being one of them a rule which runs vol_id which then wakes up my spinned-down hard disk (debugged with echoing a 1 to /proc/sys/vm/block_dump and udevadm monitor).

Does "monitor" show a kernel event, or only a udev event?

Any other events at the same time?

I don't remember exactly (if I remember correctly, it was one uevent everytime). Here I attach the logs. Later I could tell you better. I will be more aware of that.

Quoted 1 line

Are you running smartd?

Yes, but I have smartd configured to ignore the hard disk if it's in standby. In syslog I have:
smartd[7004]: Device: /dev/sda, is in STANDBY mode, suspending checks

And thanks for answering so fast (and wasting your time too).

Quoted 1 line

Kay

#5 lore raw
In reply to Tom
Quoted 3 lines · Tom

The "removing watch on '/dev/sda'" is my fault. I added this rule:
KERNEL="sda*", OPTIONS-="watch", to /etc/udev/rules.d/99-user.rules , which I'm seeing it doesn't have the effect I wanted.

There is no -= thing, you need to use "nowatch".

Kay

#6 lore raw
In reply to Tom
Quoted 10 lines · Kay Sievers
Quoted 6 lines · Tom
Quoted 1 line

Are you running smartd?

Yes, but I have smartd configured to ignore the hard disk if it's in standby. In syslog I have:
smartd[7004]: Device: /dev/sda, is in STANDBY mode, suspending checks

And thanks for answering so fast (and wasting your time too).

But I guess it open the device node writable, so it might trigger a change event.

FWIW, on my system with smartmontools 5.38,

smartctl -a /dev/sda => open("/dev/sda", O_RDWR|O_NONBLOCK)
smartctl -d ata -a /dev/sda => open("/dev/sda", O_RDONLY|O_NONBLOCK)

It behaves the same in both cases. Tom, you might try adding "-d ata" to /etc/smartd.conf to see if it works around your problem.

-jim

author
#7 lore raw
In reply to Tom
Quoted 11 lines · Jim Paris
Quoted 9 lines · Kay Sievers
Quoted 6 lines · Tom
Quoted 1 line

Are you running smartd?

Yes, but I have smartd configured to ignore the hard disk if it's in standby. In syslog I have:
smartd[7004]: Device: /dev/sda, is in STANDBY mode, suspending checks

And thanks for answering so fast (and wasting your time too).

But I guess it open the device node writable, so it might trigger a change event.

Quoted 8 lines

FWIW, on my system with smartmontools 5.38,

smartctl -a /dev/sda => open("/dev/sda", O_RDWR|O_NONBLOCK)
smartctl -d ata -a /dev/sda => open("/dev/sda", O_RDONLY|O_NONBLOCK)

It behaves the same in both cases. Tom, you might try adding "-d ata" to /etc/smartd.conf to see if it works around your problem.

Thanks Jim! That's even better. Now I don't have to use the nowatch udev rule. With '-d ata' parameter the uvent isn't triggered (and I straced it too).

Quoted 3 lines

-jim