Hal recognize certain usb scsi devices only the first time you connect them

[julioromano]

Binary package hint: hal

Ubuntu 6.06 i386 system.
hal package version: 0.5.7-1ubuntu18

Hotplugging a USB flashdrive succeeds only for the first time (because my system
uses only ide drives and so "sd_mod" is not loaded automatically at startup).
At plug time sd_mod is loaded automatically and the device is correctly mounted
and correctly listed in the hal device manager.
Then disconnecting and plugging the same device a second time makes the hal
device manager display only a "SCSI device" without any volumes.
The device node "/dev/sda1" is correctly created and the flashdrive can still be
mounted manually (with "mount" or "pmount").
This does not happen with all USB storage devices (with 3 flash drive I tried,
only one showed this problem, all 3 flashdrives works correctly in MacOSX and
WinXP).

I tried this:
1)disconnect the USB flashdrive
2)manually remove the "sg" kernel module (rmmod sg)
3)manually remove the "sd_mod" kernel module (rmmod sd_mod)
4)reconnect the USB flashdrive
and the device is detected properly.

Another note:
Killing the hal daemon and restarting it (without disconnecting the device) gets
the USB flashdrive automounted on the desktop (however I didn't investigate on
this option too much).

Thanks in advance to anyone who will read this.
Bye
Marco

the hal debug log of the second time connection is reported here:

15:24:19.132 [I] osspec.c:232: SEQNUM=2622, ACTION=add, SUBSYSTEM=usb,
DEVPATH=/sys/devices/pci0000:00/0000:00:1d.7/usb4/4-2, DEVNAME=, IFINDEX=0
15:24:19.133 [I] physdev.c:1511: phys_add: subsys=usb
sysfs_path=/sys/devices/pci0000:00/0000:00:1d.7/usb4/4-2, parent=0x08084e90
15:24:19.154 [I] physdev.c:1436: Add callouts completed
udi=/org/freedesktop/Hal/devices/usb_device_ea0_2168_106C44278B1F00EE
15:24:19.154 [I] hald.c:82: Added device to GDL;
udi=/org/freedesktop/Hal/devices/usb_device_ea0_2168_106C44278B1F00EE
15:24:19.162 [W] hald_dbus.c:87: No property info.capabilities on device with id
/org/freedesktop/Hal/devices/usb_device_ea0_2168_106C44278B1F00EE
15:24:19.166 [I] osspec.c:232: SEQNUM=2623, ACTION=add, SUBSYSTEM=usb,
DEVPATH=/sys/devices/pci0000:00/0000:00:1d.7/usb4/4-2/4-2:1.0, DEVNAME=, IFINDEX=0
15:24:19.167 [I] physdev.c:1511: phys_add: subsys=usb
sysfs_path=/sys/devices/pci0000:00/0000:00:1d.7/usb4/4-2/4-2:1.0, parent=0x080e7208
15:24:19.194 [I] physdev.c:1436: Add callouts completed
udi=/org/freedesktop/Hal/devices/usb_device_ea0_2168_106C44278B1F00EE_if0
15:24:19.194 [I] hald.c:82: Added device to GDL;
udi=/org/freedesktop/Hal/devices/usb_device_ea0_2168_106C44278B1F00EE_if0
15:24:19.200 [W] hald_dbus.c:87: No property info.capabilities on device with id
/org/freedesktop/Hal/devices/usb_device_ea0_2168_106C44278B1F00EE_if0
15:24:19.326 [I] osspec.c:232: SEQNUM=2624, ACTION=add, SUBSYSTEM=scsi_host,
DEVPATH=/sys/class/scsi_host/host1, DEVNAME=, IFINDEX=0
15:24:19.327 [I] classdev.c:1373: class_add: subsys=scsi_host
sysfs_path=/sys/class/scsi_host/host1 dev= physdev=0x080e7250
15:24:19.346 [I] classdev.c:1236: Add callouts completed
udi=/org/freedesktop/Hal/devices/usb_device_ea0_2168_106C44278B1F00EE_if0_scsi_host
15:24:19.346 [I] hald.c:82: Added device to GDL;
udi=/org/freedesktop/Hal/devices/usb_device_ea0_2168_106C44278B1F00EE_if0_scsi_host
15:24:19.437 [I] osspec.c:232: SEQNUM=2625, ACTION=add, SUBSYSTEM=usb_device,
DEVPATH=/sys/class/usb_device/usbdev4.3, DEVNAME=/dev/bus/usb/004/003, IFINDEX=0
15:24:19.438 [I] classdev.c:1373: class_add: subsys=usb_device
sysfs_path=/sys/class/usb_device/usbdev4.3 dev=/dev/bus/usb/004/003
physdev=0x080e7208
15:24:19.457 [I] classdev.c:1236: Add callouts completed
udi=/org/freedesktop/Hal/devices/usb_device_ea0_2168_106C44278B1F00EE_usbraw
15:24:19.457 [I] hald.c:82: Added device to GDL;
udi=/org/freedesktop/Hal/devices/usb_device_ea0_2168_106C44278B1F00EE_usbraw
15:24:25.002 [W] hald_dbus.c:87: No device with id
/org/freedesktop/Hal/devices/volume_uuid_C48A_ED35
15:24:25.965 [W] util.c:204: remaining_time *very* high, returning -1
15:24:27.730 [I] osspec.c:232: SEQNUM=2626, ACTION=add, SUBSYSTEM=scsi,
DEVPATH=/sys/devices/pci0000:00/0000:00:1d.7/usb4/4-2/4-2:1.0/host1/target1:0:0/1:0:0:0,
DEVNAME=, IFINDEX=0
15:24:27.730 [I] physdev.c:1511: phys_add: subsys=scsi
sysfs_path=/sys/devices/pci0000:00/0000:00:1d.7/usb4/4-2/4-2:1.0/host1/target1:0:0/1:0:0:0,
parent=0x080e7298
15:24:27.731 [E] util.c:463: Cannot open
'/sys/devices/pci0000:00/0000:00:1d.7/usb4/4-2/4-2:1.0/host1/target1:0:0/1:0:0:0/model'
15:24:27.731 [E] util.c:463: Cannot open
'/sys/devices/pci0000:00/0000:00:1d.7/usb4/4-2/4-2:1.0/host1/target1:0:0/1:0:0:0/vendor'
15:24:27.731 [E] util.c:322: Cannot open
'/sys/devices/pci0000:00/0000:00:1d.7/usb4/4-2/4-2:1.0/host1/target1:0:0/1:0:0:0/type'
15:24:27.749 [I] physdev.c:1436: Add callouts completed
udi=/org/freedesktop/Hal/devices/usb_device_ea0_2168_106C44278B1F00EE_if0_scsi_host_scsi_device_lun0
15:24:27.749 [I] hald.c:82: Added device to GDL;
udi=/org/freedesktop/Hal/devices/usb_device_ea0_2168_106C44278B1F00EE_if0_scsi_host_scsi_device_lun0
15:24:27.756 [W] hald_dbus.c:87: No property info.capabilities on device with id
/org/freedesktop/Hal/devices/usb_device_ea0_2168_106C44278B1F00EE_if0_scsi_host_scsi_device_lun0
15:24:29.314 [I] osspec.c:232: SEQNUM=2629, ACTION=add, SUBSYSTEM=scsi_device,
DEVPATH=/sys/class/scsi_device/1:0:0:0, DEVNAME=, IFINDEX=0
15:24:29.314 [I] classdev.c:1373: class_add: subsys=scsi_device
sysfs_path=/sys/class/scsi_device/1:0:0:0 dev= physdev=0x080e7300
15:24:29.382 [I] osspec.c:232: SEQNUM=2630, ACTION=add, SUBSYSTEM=scsi_generic,
DEVPATH=/sys/class/scsi_generic/sg0, DEVNAME=/dev/sg0, IFINDEX=0
15:24:29.383 [I] classdev.c:1373: class_add: subsys=scsi_generic
sysfs_path=/sys/class/scsi_generic/sg0 dev=/dev/sg0 physdev=0x080e7300
15:24:29.401 [I] classdev.c:1236: Add callouts completed
udi=/org/freedesktop/Hal/devices/usb_device_ea0_2168_106C44278B1F00EE_if0_scsi_host_scsi_device_lun0_scsi_generic
15:24:29.401 [I] hald.c:82: Added device to GDL;
udi=/org/freedesktop/Hal/devices/usb_device_ea0_2168_106C44278B1F00EE_if0_scsi_host_scsi_device_lun0_scsi_generic
15:24:29.415 [I] osspec.c:232: SEQNUM=2627, ACTION=add, SUBSYSTEM=block,
DEVPATH=/sys/block/sda, DEVNAME=/dev/sda, IFINDEX=0
15:24:29.416 [I] blockdev.c:589: block_add: sysfs_path=/sys/block/sda
dev=/dev/sda is_part=0, parent=0x080e7300
15:24:29.416 [I] blockdev.c:813: parent_bus is scsi
15:24:29.416 [W] blockdev.c:852: scsi.type is unknown
15:24:29.416 [W] blockdev.c:990: Not adding device object
15:24:29.515 [I] osspec.c:232: SEQNUM=2628, ACTION=add, SUBSYSTEM=block,
DEVPATH=/sys/block/sda/sda1, DEVNAME=/dev/sda1, IFINDEX=0
15:24:29.516 [I] blockdev.c:589: block_add: sysfs_path=/sys/block/sda/sda1
dev=/dev/sda1 is_part=1, parent=0x00000000
15:24:29.516 [I] blockdev.c:499: get_luks_uuid: device_file=/dev/sda1
15:24:29.516 [I] blockdev.c:625: Ignoring hotplug event - no parent
15:24:29.516 [W] blockdev.c:990: Not adding device object
15:24:40.006 [W] hald_dbus.c:87: No device with id
/org/freedesktop/Hal/devices/volume_uuid_C48A_ED35

[julioromano (romanomarco)]

Bug seems resolved by upgrading to "acpi-support" package to version 0.85.

[julioromano (romanomarco)]

However the issue still rarely happens (usually 1 out of four hotplugs fails).
It is sufficient to replug the usb device to get things working.