On Sat, Jan 22, 2005 at 08:28:33PM +0000, Jean-Luc Coulon (f5ibh) wrote:
Okay, so hal is actually polling your drives... The odd thing is that nothing changed to that code between the versions..
Could you stop your hald... run hald --daemon=no --verbose=yes, and then after the startup sequence is done put in a cd. And mail the output, if none then i'll need to create a special debug package for you..
Ok, I have done the test and I'm really puzzled: when running hald with strace, the volume is mounted. If I restart dbus, then it doesnt work :-/
Attached the trace.
Sjoerd
Regards
Jean-Luc
18286: mount_root='/media' 18286: use_managed=0 18286: Releasing advisory lock on /etc/fstab 18286: Lock released 18286: fstab-sync exiting; remove udi=/org/freedesktop/Hal/devices/block_3_0-0 18286: ###################################
17:05:33.855 [I] callout.c:173: Child pid 18286 terminated 17:05:33.855 [I] hald.c:84: Removed device from GDL; udi=/org/freedesktop/Hal/devices/block_3_0-0 17:05:33.855 [I] callout.c:193: Callouts done for /org/freedesktop/Hal/devices/block_3_0-0 17:05:59.922 [I] linux/block_class_device.c:636: volume.num_blocks = 1441072 17:05:59.979 [I] linux/block_class_device.c:651: volume.block_size = 2048 17:05:59.979 [I] linux/block_class_device.c:655: volume.size = 737828864 17:06:00.028 [I] linux/block_class_device.c:706: Disc in /dev/hda has data 17:06:00.047 [I] linux/block_class_device.c:723: get_disc_type returned 0x0a 17:06:00.067 [I] linux/block_class_device.c:1056: Detecting if /dev/hda contains a fs 17:06:00.122 [I] linux/volume_id/volume_id.c:2103: called with size=0x0 17:06:00.122 [I] linux/volume_id/volume_id.c:202: get buffer off 0x400, len 0x800 17:06:00.122 [I] linux/volume_id/volume_id.c:213: read sbbuf len:0xc00 17:06:00.173 [I] linux/volume_id/volume_id.c:216: got 0xc00 (3072) bytes 17:06:00.173 [I] linux/volume_id/volume_id.c:202: get buffer off 0x0, len 0x800 17:06:00.173 [I] linux/volume_id/volume_id.c:202: get buffer off 0x1200, len 0x200 17:06:00.173 [I] linux/volume_id/volume_id.c:213: read sbbuf len:0x1400 17:06:00.173 [I] linux/volume_id/volume_id.c:216: got 0x1400 (5120) bytes 17:06:00.173 [I] linux/volume_id/volume_id.c:202: get buffer off 0x0, len 0x200 17:06:00.173 [I] linux/volume_id/volume_id.c:202: get buffer off 0x0, len 0x200 17:06:00.173 [I] linux/volume_id/volume_id.c:202: get buffer off 0x0, len 0x200 17:06:00.173 [I] linux/volume_id/volume_id.c:202: get buffer off 0x0, len 0x11000 17:06:00.173 [I] linux/volume_id/volume_id.c:213: read sbbuf len:0x11000 17:06:00.205 [I] linux/volume_id/volume_id.c:216: got 0x11000 (69632) bytes 17:06:00.205 [I] linux/volume_id/volume_id.c:202: get buffer off 0xff6, len 0xa 17:06:00.205 [I] linux/volume_id/volume_id.c:202: get buffer off 0x1ff6, len 0xa17:06:00.205 [I] linux/volume_id/volume_id.c:202: get buffer off 0x3ff6, len 0xa17:06:00.205 [I] linux/volume_id/volume_id.c:202: get buffer off 0x400, len 0x200 17:06:00.205 [I] linux/volume_id/volume_id.c:202: get buffer off 0x10000, len 0x200 17:06:00.205 [I] linux/volume_id/volume_id.c:202: get buffer off 0x2000, len 0x200 17:06:00.205 [I] linux/volume_id/volume_id.c:202: get buffer off 0x8000, len 0x200 17:06:00.205 [I] linux/volume_id/volume_id.c:202: get buffer off 0x8000, len 0x200 17:06:00.205 [I] linux/volume_id/volume_id.c:202: get buffer off 0x8800, len 0x800 17:06:00.205 [I] linux/volume_id/volume_id.c:1170: test for blocksize: 0x800 17:06:00.205 [I] linux/volume_id/volume_id.c:202: get buffer off 0x8000, len 0x800 17:06:00.205 [I] linux/volume_id/volume_id.c:1185: vsd: CD001 17:06:00.205 [I] linux/volume_id/volume_id.c:202: get buffer off 0x8800, len 0x800 17:06:00.205 [I] linux/volume_id/volume_id.c:1185: vsd: CD001 17:06:00.205 [I] linux/volume_id/volume_id.c:202: get buffer off 0x9000, len 0x800 17:06:00.205 [I] linux/volume_id/volume_id.c:1185: vsd: CD001 17:06:00.205 [I] linux/volume_id/volume_id.c:202: get buffer off 0x9800, len 0x800 17:06:00.206 [I] linux/volume_id/volume_id.c:1185: vsd: 17:06:00.206 [I] linux/volume_id/volume_id.c:202: get buffer off 0x8000, len 0x200 17:06:00.206 [I] linux/volume_id/volume_id.c:202: get buffer off 0x8800, len 0x200 17:06:00.231 [I] linux/volume_id/volume_id.c:1299: found ISO supplementary VD at offset 0x8800 17:06:00.231 [I] linux/block_class_device.c:1093: Media in no_partitions device /dev/hda 17:06:00.232 [I] linux/block_class_device.c:108: volume.fsusage = 'filesystem' 17:06:00.232 [I] linux/block_class_device.c:111: volume.fstype = 'iso9660' 17:06:00.232 [I] linux/block_class_device.c:117: volume.uuid = '' 17:06:00.232 [I] linux/block_class_device.c:119: volume.label = '' 17:06:00.232 [I] device_info.c:1160: scan_fdi_files: Processing file '6in1-card-reader.fdi' 17:06:00.232 [I] device_info.c:1160: scan_fdi_files: Processing file 'ide-drives.fdi' 17:06:00.233 [I] device_info.c:1160: scan_fdi_files: Processing file 'jetflash-mp3-player.fdi' 17:06:00.255 [I] device_info.c:1160: scan_fdi_files: Processing file 'lexar-media-cf-reader.fdi' 17:06:00.255 [I] device_info.c:1160: scan_fdi_files: Processing file 'lucent-pcmcia-wireless.fdi' 17:06:00.255 [I] device_info.c:1160: scan_fdi_files: Processing file 'usb-zip-drives.fdi' 17:06:00.256 [I] device_info.c:1160: scan_fdi_files: Processing file 'storage-policy.fdi' 17:06:00.257 [I] device_info.c:1163: *** Matched file /usr/share/hal/fdi/90defaultpolicy/storage-policy.fdi 17:06:00.278 [I] device_info.c:1160: scan_fdi_files: Processing file 'preferences.fdi' 17:06:00.278 [I] linux/common.c:582: Found a .fdi file for /org/freedesktop/Hal/devices/block_3_0-0 17:06:00.279 [I] callout.c:318: Invoking /etc/hal/device.d/40-hal-hotplug-map.hal 17:06:00.300 [I] callout.c:330: Child pid 18287 for 40-hal-hotplug-map.hal 17:06:00.349 [I] callout.c:173: Child pid 18287 terminated 17:06:00.349 [I] callout.c:318: Invoking /etc/hal/device.d/50-fstab-sync.hal 17:06:00.371 [I] callout.c:330: Child pid 18288 for 50-fstab-sync.hal 18288: ################################### 18288: fstab-sync entering; add udi=/org/freedesktop/Hal/devices/block_3_0-0 18288: Acquiring advisory lock on /etc/fstab 18288: Lock acquired 18288: mount_root='/media' 18288: use_managed=0 18288: using temporary file '/etc/.fstab.hal.z' 18288: entering add_hal_device, udi='/org/freedesktop/Hal/devices/block_3_0-0' 17:06:00.434 [W] hald_dbus.c:97: No property info.category on device with id /org/freedesktop/Hal/devices/ide_0_0 libhal.c 911 : Error sending msg: No property info.category on device with id /org/freedesktop/Hal/devices/ide_0_0 18288: Releasing advisory lock on /etc/fstab 18288: Lock released 18288: fstab-sync exiting; add udi=/org/freedesktop/Hal/devices/block_3_0-0 18288: ################################### 17:06:00.455 [I] callout.c:173: Child pid 18288 terminated 17:06:00.456 [I] hald.c:81: Added device to GDL; udi=/org/freedesktop/Hal/devices/block_3_0-0 17:06:00.456 [I] callout.c:193: Callouts done for /org/freedesktop/Hal/devices/block_3_0-0 17:06:02.047 [W] hald_dbus.c:97: No property volume.policy.mount_option.sync on device with id /org/freedesktop/Hal/devices/block_3_0-0 17:06:02.550 [W] hald_dbus.c:97: No property volume.policy.mount_option.noatime on device with id /org/freedesktop/Hal/devices/block_3_0-0 17:06:03.285 [I] linux/osspec.c:1665: SEQNUM=1705, TIMESTAMP=1106669163 17:06:03.285 [I] linux/osspec.c:1699: First SEQNUM=1705; sleeping 3500ms to get a few more events 17:06:03.285 [I] linux/osspec.c:1542: ******************************************17:06:03.285 [I] linux/osspec.c:1543: **** hotplug_counter is now 1 17:06:03.285 [I] linux/osspec.c:1544: ******************************************17:06:03.285 [I] linux/osspec.c:1665: SEQNUM=1706, TIMESTAMP=1106669163 17:06:03.285 [I] linux/osspec.c:1718: first hotplug sleep; got SEQNUM=1706 17:06:04.335 [I] linux/block_class_device.c:2394: Directory /etc changed 17:06:04.336 [I] linux/block_class_device.c:2255: /etc/mtab changed, processing all block devices 17:06:04.336 [I] linux/block_class_device.c:2134: /dev/hda is mounted at /media/dvdr, major:minor=3:0, fstype=iso9660, udi=/org/freedesktop/Hal/devices/block_3_0-0 17:06:06.785 [I] linux/osspec.c:1582: Slept 3500ms, now processing events 17:06:06.785 [I] linux/osspec.c:1591: *** msg->seqnum = 1705 17:06:06.785 [I] linux/osspec.c:1591: *** msg->seqnum = 1706 17:06:06.785 [I] linux/osspec.c:1599: Starting with last_hotplug_seqnum=1706 17:06:06.785 [I] linux/osspec.c:1555: ==========================================17:06:06.785 [I] linux/osspec.c:1556: ==== hotplug_counter is now 0 17:06:06.785 [I] linux/osspec.c:1557: ==========================================17:06:06.785 [I] linux/osspec.c:1454: Processing event around first hotplug with SEQNUM=1705 17:06:06.785 [I] linux/osspec.c:1347: action=add seqnum=1705 subsystem=module sysfs_path=/sys/module/nls_cp437 Invalid path to device /sys/module/nls_cp437 Could not get device bus Could not get device nls_cp437's driver Invalid path to device /sys/module/nls_cp437 Could not get device bus Could not get device nls_cp437's driver Invalid path to device /sys/module/nls_cp437 Could not get device bus Could not get device nls_cp437's driver Invalid path to device /sys/module/nls_cp437 Could not get device bus Could not get device nls_cp437's driver Invalid path to device /sys/module/nls_cp437 Could not get device bus Could not get device nls_cp437's driver Invalid path to device /sys/module/nls_cp437 Could not get device bus Could not get device nls_cp437's driver Invalid path to device /sys/module/nls_cp437 Could not get device bus Could not get device nls_cp437's driver Invalid path to device /sys/module/nls_cp437 Could not get device bus Could not get device nls_cp437's driver Invalid path to device /sys/module/nls_cp437 Could not get device bus Could not get device nls_cp437's driver 17:06:06.787 [E] linux/osspec.c:1372: d is NULL! 17:06:06.787 [I] linux/osspec.c:1454: Processing event around first hotplug with SEQNUM=1706 17:06:06.787 [I] linux/osspec.c:1347: action=add seqnum=1706 subsystem=module sysfs_path=/sys/module/nls_cp437/sections Invalid path to device /sys/module/nls_cp437/sections Could not get device bus Could not get device sections's driver Invalid path to device /sys/module/nls_cp437/sections Could not get device bus Could not get device sections's driver Invalid path to device /sys/module/nls_cp437/sections Could not get device bus Could not get device sections's driver Invalid path to device /sys/module/nls_cp437/sections Could not get device bus Could not get device sections's driver Invalid path to device /sys/module/nls_cp437/sections Could not get device bus Could not get device sections's driver Invalid path to device /sys/module/nls_cp437/sections Could not get device bus Could not get device sections's driver Invalid path to device /sys/module/nls_cp437/sections Could not get device bus Could not get device sections's driver Invalid path to device /sys/module/nls_cp437/sections Could not get device bus Could not get device sections's driver Invalid path to device /sys/module/nls_cp437/sections Could not get device bus Could not get device sections's driver 17:06:06.789 [E] linux/osspec.c:1372: d is NULL!
pgp7scNnZYiNu.pgp
Description: PGP signature