Question about debugging the source of uevents

Question about debugging the source of uevents

[Tom]

[-- Attachment #1: Type: text/plain, Size: 805 bytes --]

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.




[-- Attachment #2: OpenPGP digital signature --]
[-- Type: application/pgp-signature, Size: 197 bytes --]

Re: Question about debugging the source of uevents

[Kay Sievers]

On Mon, Mar 30, 2009 at 16:43, Tom <tomgparchaur@gmail.com> wrote:
>  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

Re: Question about debugging the source of uevents

[Tom]

[-- Attachment #1.1: Type: text/plain, Size: 1050 bytes --]

Kay Sievers escribió:
> On Mon, Mar 30, 2009 at 16:43, Tom <tomgparchaur@gmail.com> wrote:
>>  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.

> 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).

> 
> Kay


[-- Attachment #1.2: udevd-event.log --]
[-- Type: text/plain, Size: 42463 bytes --]

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

[-- Attachment #2: OpenPGP digital signature --]
[-- Type: application/pgp-signature, Size: 197 bytes --]

Re: Question about debugging the source of uevents

[Kay Sievers]

On Mon, Mar 30, 2009 at 17:27, Tom <tomgparchaur@gmail.com> wrote:
> Kay Sievers escribió:
>> On Mon, Mar 30, 2009 at 16:43, Tom <tomgparchaur@gmail.com> wrote:
>>>  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.

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

>> 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

Re: Question about debugging the source of uevents

[Tom]

[-- Attachment #1: Type: text/plain, Size: 1407 bytes --]

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.

Tom escribió:
> Kay Sievers escribió:
>> On Mon, Mar 30, 2009 at 16:43, Tom <tomgparchaur@gmail.com> wrote:
>>>  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.
> 
>> 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).
> 
>> Kay
> 



[-- Attachment #2: OpenPGP digital signature --]
[-- Type: application/pgp-signature, Size: 197 bytes --]

Re: Question about debugging the source of uevents

[Kay Sievers]

On Mon, Mar 30, 2009 at 17:34, Tom <tomgparchaur@gmail.com> wrote:
> 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

Re: Question about debugging the source of uevents

[Jim Paris]

Kay Sievers wrote:
> On Mon, Mar 30, 2009 at 17:27, Tom <tomgparchaur@gmail.com> wrote:
> >> 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

Re: Question about debugging the source of uevents

[Tom]

[-- Attachment #1: Type: text/plain, Size: 1010 bytes --]

Jim Paris escribió:
> Kay Sievers wrote:
>> On Mon, Mar 30, 2009 at 17:27, Tom <tomgparchaur@gmail.com> wrote:
>>>> 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.
> 
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).

> -jim
> 
> 



[-- Attachment #2: OpenPGP digital signature --]
[-- Type: application/pgp-signature, Size: 197 bytes --]