Re: Debugging hotplug

[ritz <khadgaray gmail com>]

Hello

On Fri, 2008-01-25 at 07:33 -0500, Dan Williams wrote:
> 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 ?
Additionally, i would suggest you to try monitoring system and session
bus.

 $ man 1 dbus-monitor 

> 
> 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.
Nice. I was not aware of this.

> 
> Dan
> 
> 
-- 
Ritesh Khadgaray
ॐ मणि पद्मे हूँ
Desktop LinuX N Stuff
Ph: +919970164885
Eat Right, Exercise, Die Anyway.
Fedora is the best of what works today.  Enterprise Linux is the best of
what will work consistently for the next seven years.