Re: Debugging hotplug

[Dan Williams <dcbw redhat com>]

On Fri, 2008-01-25 at 10:46 +0530, ritz wrote:
> Hello
> 
> On Tue, 2008-01-22 at 18:37 +0000, Paulo J. Matos wrote:
> > On Jan 20, 2008 12:15 AM, George Vlahavas <vlahavas gmail com> wrote:
> > > 2008/1/19, MHR <mhullrich gmail com>:
> > >
> > > > On Jan 19, 2008 11:50 AM, Paulo J. Matos <pocm soton ac uk> wrote:
> > > > >
> > > > > On Jan 17, 2008 9:59 PM, Paulo J. Matos <pocm soton ac uk > wrote:
> > > > > > Hello all,
> > > > > >
> > > > > > In gnome 2.20, I've always had hotplug working. Each time I plug my
> > > > > > external HD, it gets mounted, or my camera, or mp3 player or whatever,
> > > >  > > but for a couple of days, I plug in something and nothing happens.
> > > > > > Is there a way to debug what's going wrong?
> > > > > >
> > > > >
> > > > > I wonder why nobody replies...
> > > > >
> > > > This is probably an OS problem (automount failure) - what OS are you
> > > > running?
> > > Or it could be some hal rule that got installed by another package
> > > that prevents automounting. I've seen that happen with the gparted
> > > package in some distros for example.
> > 
> > Can it be some problem with NetworkManager?
> > 
> > I'm getting this in the system logs when I attach something:
> > 
> > Jan 22 18:36:03 euler usb 5-1: new high speed USB device using
> > ehci_hcd and address 8
> > Jan 22 18:36:04 euler usb 5-1: configuration #1 chosen from 1 choice
> > Jan 22 18:36:04 euler hub 5-1:1.0: USB hub found
> > Jan 22 18:36:04 euler hub 5-1:1.0: 4 ports detected
> > Jan 22 18:36:04 euler NetworkManager: <debug> [1201026964.031222]
> > nm_hal_device_added(): New device added (hal udi is
> > '/org/freedesktop/Hal/devices/usb_device_5e3_606_noserial').
> > Jan 22 18:36:04 euler hub 1-0:1.0: over-current change on port 2
> > Jan 22 18:36:04 euler NetworkManager: <debug> [1201026964.232276]
> > nm_hal_device_added(): New device added (hal udi is
> > '/org/freedesktop/Hal/devices/usb_device_5e3_606_noserial_if0').
> > Jan 22 18:36:04 euler NetworkManager: <debug> [1201026964.316900]
> > nm_hal_device_added(): New device added (hal udi is
> > '/org/freedesktop/Hal/devices/usb_device_5e3_606_noserial_usbraw').
> > Jan 22 18:36:04 euler usb 5-1.2: new high speed USB device using
> > ehci_hcd and address 9
> > Jan 22 18:36:04 euler usb 5-1.2: configuration #1 chosen from 1 choice
> > Jan 22 18:36:04 euler scsi5 : SCSI emulation for USB Mass Storage devices
> > Jan 22 18:36:04 euler usb-storage: device found at 9
> > Jan 22 18:36:04 euler usb-storage: waiting for device to settle before scanning
> > Jan 22 18:36:04 euler NetworkManager: <debug> [1201026964.522854]
> > nm_hal_device_added(): New device added (hal udi is
> > '/org/freedesktop/Hal/devices/usb_device_8ec_2038_0F714B6150C388C7').
> > Jan 22 18:36:04 euler NetworkManager: <debug> [1201026964.667640]
> > nm_hal_device_added(): New device added (hal udi is
> > '/org/freedesktop/Hal/devices/usb_device_8ec_2038_0F714B6150C388C7_if0').
> > Jan 22 18:36:04 euler NetworkManager: <debug> [1201026964.716621]
> > nm_hal_device_added(): New device added (hal udi is
> > '/org/freedesktop/Hal/devices/usb_device_8ec_2038_0F714B6150C388C7_usbraw').
> > Jan 22 18:36:08 euler usb 1-2: new full speed USB device using
> > uhci_hcd and address 5
> > Jan 22 18:36:08 euler usb 1-2: not running at top speed; connect to a
> > high speed hub
> > Jan 22 18:36:08 euler usb 1-2: configuration #1 chosen from 1 choice
> > Jan 22 18:36:08 euler scsi6 : SCSI emulation for USB Mass Storage devices
> > Jan 22 18:36:08 euler usb-storage: device found at 5
> > Jan 22 18:36:08 euler usb-storage: waiting for device to settle before scanning
> > Jan 22 18:36:08 euler NetworkManager: <debug> [1201026968.973308]
> > nm_hal_device_added(): New device added (hal udi is
> > '/org/freedesktop/Hal/devices/usb_device_1058_701_DEF103C306F1').
> > Jan 22 18:36:09 euler NetworkManager: <debug> [1201026969.140181]
> > nm_hal_device_added(): New device added (hal udi is
> > '/org/freedesktop/Hal/devices/usb_device_1058_701_DEF103C306F1_if0').
> > Jan 22 18:36:09 euler NetworkManager: <debug> [1201026969.234992]
> > nm_hal_device_added(): New device added (hal udi is
> > '/org/freedesktop/Hal/devices/usb_device_1058_701_DEF103C306F1_usbraw').
> > Jan 22 18:36:09 euler scsi 5:0:0:0: Direct-Access     Kingston
> > DataTraveler 2.0 6.50 PQ: 0 ANSI: 0 CCS
> > Jan 22 18:36:09 euler SCSI device sda: 1970175 512-byte hdwr sectors (1009 MB)
> > Jan 22 18:36:09 euler sda: Write Protect is off
> > Jan 22 18:36:09 euler sda: Mode Sense: 45 00 00 08
> > Jan 22 18:36:09 euler sda: assuming drive cache: write through
> > Jan 22 18:36:09 euler SCSI device sda: 1970175 512-byte hdwr sectors (1009 MB)
> > Jan 22 18:36:09 euler sda: Write Protect is off
> > Jan 22 18:36:09 euler sda: Mode Sense: 45 00 00 08
> > Jan 22 18:36:09 euler sda: assuming drive cache: write through
> > Jan 22 18:36:09 euler sda: sda1
> > Jan 22 18:36:09 euler sd 5:0:0:0: Attached scsi removable disk sda
> > Jan 22 18:36:09 euler sd 5:0:0:0: Attached scsi generic sg0 type 0
> > Jan 22 18:36:09 euler usb-storage: device scan complete
> > Jan 22 18:36:09 euler NetworkManager: <debug> [1201026969.563906]
> > nm_hal_device_added(): New device added (hal udi is
> > '/org/freedesktop/Hal/devices/usb_device_8ec_2038_0F714B6150C388C7_if0_scsi_host').
> > Jan 22 18:36:09 euler NetworkManager: <debug> [1201026969.570017]
> > nm_hal_device_added(): New device added (hal udi is
> > '/org/freedesktop/Hal/devices/usb_device_8ec_2038_0F714B6150C388C7_if0_scsi_host_scsi_device_lun0').
> > Jan 22 18:36:09 euler NetworkManager: <debug> [1201026969.575826]
> > nm_hal_device_added(): New device added (hal udi is
> > '/org/freedesktop/Hal/devices/usb_device_8ec_2038_0F714B6150C388C7_if0_scsi_host_scsi_device_lun0_scsi_generic').
> > Jan 22 18:36:09 euler NetworkManager: <debug> [1201026969.727791]
> > nm_hal_device_added(): New device added (hal udi is
> > '/org/freedesktop/Hal/devices/storage_serial_Kingston_DataTraveler_2_0_0F714B6150C388C7_0_0').
> > Jan 22 18:36:09 euler NetworkManager: <debug> [1201026969.795003]
> > nm_hal_device_added(): New device added (hal udi is
> > '/org/freedesktop/Hal/devices/volume_uuid_d7720317_54c4_4ce6_b07d_fb985a5bab46').
> > Jan 22 18:36:13 euler scsi 6:0:0:0: Direct-Access     WDC WD12
> > 00UE-00KVT0      0000 PQ: 0 ANSI: 0
> > Jan 22 18:36:13 euler SCSI device sdb: 234441648 512-byte hdwr sectors
> > (120034 MB)
> > Jan 22 18:36:13 euler sdb: Write Protect is off
> > Jan 22 18:36:13 euler sdb: Mode Sense: 27 00 00 00
> > Jan 22 18:36:13 euler sdb: assuming drive cache: write through
> > Jan 22 18:36:13 euler SCSI device sdb: 234441648 512-byte hdwr sectors
> > (120034 MB)
> > Jan 22 18:36:13 euler sdb: Write Protect is off
> > Jan 22 18:36:13 euler sdb: Mode Sense: 27 00 00 00
> > Jan 22 18:36:13 euler sdb: assuming drive cache: write through
> > Jan 22 18:36:13 euler sdb: sdb1
> > Jan 22 18:36:13 euler sd 6:0:0:0: Attached scsi disk sdb
> > Jan 22 18:36:13 euler sd 6:0:0:0: Attached scsi generic sg1 type 0
> > Jan 22 18:36:13 euler usb-storage: device scan complete
> > Jan 22 18:36:14 euler NetworkManager: <debug> [1201026974.019744]
> > nm_hal_device_added(): New device added (hal udi is
> > '/org/freedesktop/Hal/devices/usb_device_1058_701_DEF103C306F1_if0_scsi_host').
> > Jan 22 18:36:14 euler NetworkManager: <debug> [1201026974.025084]
> > nm_hal_device_added(): New device added (hal udi is
> > '/org/freedesktop/Hal/devices/usb_device_1058_701_DEF103C306F1_if0_scsi_host_scsi_device_lun0').
> > Jan 22 18:36:14 euler NetworkManager: <debug> [1201026974.160125]
> > nm_hal_device_added(): New device added (hal udi is
> > '/org/freedesktop/Hal/devices/storage_serial_WDC_WD12_00UE_00KVT0_DEF103C306F1_0_0').
> > Jan 22 18:36:14 euler NetworkManager: <debug> [1201026974.168327]
> > nm_hal_device_added(): New device added (hal udi is
> > '/org/freedesktop/Hal/devices/usb_device_1058_701_DEF103C306F1_if0_scsi_host_scsi_device_lun0_scsi_generic').
> > Jan 22 18:36:14 euler NetworkManager: <debug> [1201026974.548287]
> > nm_hal_device_added(): New device added (hal udi is
> > '/org/freedesktop/Hal/devices/volume_uuid_8b15f11e_1008_4964_bc2f_4b5b3029d19f').
> > 
> 
> Looks rather odd to me. Which distro are you using ?
> Does disabling nm help ?

NM shouldn't actually touch anything that's not a network device, even
though it will log the insert.  So it'll show up here but NM will just
ignore it unless it has HAL network device properties.

Dan