Re: NM and Nokia N95

[Dan Williams <dcbw redhat com>]

On Sun, 2009-09-13 at 22:47 +0100, Robert Piasek wrote:
> Hi,
> 
> I'm trying to connect my Nokia N95 using phonet driver and NM. Device is 
> picked up by kernel and properly initialized, but it doesn't connect due to 
> some problems with ppp. Please have a look on log below and let me know what 
> can be done to make it work.
> 
> 
> Sep 13 22:40:26 [kernel] [39436.837937] usb 2-1.2: new full speed USB device 
> using ehci_hcd and address 10
> Sep 13 22:40:26 [kernel] [39436.930958] usb 2-1.2: New USB device found, 
> idVendor=0421, idProduct=0070    
> Sep 13 22:40:26 [kernel] [39436.930966] usb 2-1.2: New USB device strings: 
> Mfr=1, Product=2, SerialNumber=0
> Sep 13 22:40:26 [kernel] [39436.930973] usb 2-1.2: Product: Nokia N95 8GB                                  
> Sep 13 22:40:26 [kernel] [39436.930977] usb 2-1.2: Manufacturer: Nokia                                     
> Sep 13 22:40:26 [kernel] [39436.931170] usb 2-1.2: configuration #1 chosen from 
> 1 choice                   
> Sep 13 22:40:26 [kernel] [39436.941261] cdc_acm 2-1.2:1.10: ttyACM1: USB ACM 
> device                        
> Sep 13 22:40:28 [NetworkManager] <info>  (ttyACM1): found serial port 
> (udev:GSM  hal:GSM)_                 
> Sep 13 22:40:28 [NetworkManager] <info>  (ttyACM1): new Modem device (driver: 
> 'cdc_acm')_                  
> Sep 13 22:40:28 [NetworkManager] <info>  (ttyACM1): exported as 
> /org/freedesktop/Hal/devices/usb_device_421_70_noserial_if0_0_serial_unknown_1_
> Sep 13 22:40:32 [NetworkManager] <info>  (ttyACM1): device state change: 1 -> 
> 2 (reason 2)_
> Sep 13 22:40:32 [NetworkManager] <info>  (ttyACM1): deactivating device 
> (reason: 2)._
> Sep 13 22:40:32 [NetworkManager] nm_ip4_config_add_nameserver: assertion 
> `nameserver != s' failed
> Sep 13 22:40:32 [NetworkManager] <info>  Policy set 'Auto eth2' (eth2) as 
> default for routing and DNS._
> Sep 13 22:40:32 [NetworkManager] nm_system_device_flush_ip4_routes_with_iface: 
> assertion `iface_idx >= 0' failed
> Sep 13 22:40:32 [NetworkManager] 
> nm_system_device_flush_ip4_addresses_with_iface: assertion `iface_idx >= 0' 
> failed
> Sep 13 22:40:32 [NetworkManager] <info>  (ttyACM1): device state change: 2 -> 
> 3 (reason 0)_
> Sep 13 22:40:44 [NetworkManager] <info>  Activation (ttyACM1) starting 
> connection 'Three'_
> Sep 13 22:40:44 [NetworkManager] <info>  (ttyACM1): device state change: 3 -> 
> 4 (reason 0)_
> Sep 13 22:40:44 [NetworkManager] <info>  Activation (ttyACM1) Stage 1 of 5 
> (Device Prepare) scheduled..._
> Sep 13 22:40:44 [NetworkManager] <info>  Activation (ttyACM1) Stage 1 of 5 
> (Device Prepare) started..._
> Sep 13 22:40:44 [NetworkManager] Invalid bits (0). Valid values are 5, 6, 7, 
> 8.
> Sep 13 22:40:44 [NetworkManager] Invalid parity (
> Sep 13 22:40:44 [NetworkManager] Invalid stop bits (0). Valid values are 1 and 
> 2)
> Sep 13 22:40:44 [NetworkManager] <info>  Activation (ttyACM1) Stage 1 of 5 
> (Device Prepare) complete._
> Sep 13 22:40:45 [NetworkManager] <info>  (ttyACM1): powering up..._
> Sep 13 22:40:45 [NetworkManager] <info>  Registered on Home network_
> Sep 13 22:40:45 [NetworkManager] <info>  Associated with network: +COPS: 
> 0,2,"23420",2_
> Sep 13 22:40:45 [NetworkManager] <info>  Connected, Woo!_
> Sep 13 22:40:45 [NetworkManager] <info>  Activation (ttyACM1) Stage 2 of 5 
> (Device Configure) scheduled..._
> Sep 13 22:40:45 [NetworkManager] <info>  Activation (ttyACM1) Stage 2 of 5 
> (Device Configure) starting..._
> Sep 13 22:40:45 [NetworkManager] <info>  (ttyACM1): device state change: 4 -> 
> 5 (reason 0)_
> Sep 13 22:40:45 [NetworkManager] <info>  Starting pppd connection_
> Sep 13 22:40:45 [NetworkManager] <info>  Activation (ttyACM1) Stage 2 of 5 
> (Device Configure) complete._
> Sep 13 22:40:45 [pppd] Plugin /usr/lib64/pppd/2.4.4/nm-pppd-plugin.so loaded.
> Sep 13 22:40:45 [pppd] pppd 2.4.4 started by root, uid 0
> Sep 13 22:40:45 [pppd] Baud rate for /dev/ttyACM1 is 0; need explicit baud 
> rate

So we ran into this with ModemManager recently, and while we sort of
know what the cause is, we haven't fixed in NM 0.7.x yet.  Not entirely
sure how it could happen in 0.7.x though, but there are a few reasons.

I knew I shouldn't have gotten rid of my N80 :)  In any case, did you
recently update your kernel version or something?  What kernel are you
running now?  This only recently started popping up, which leads me to
suspect kernel serial changes more than anything in NM that started to
trigger it (though there's still an NM bug anyway).

Dan

> Sep 13 22:40:46 [pppd] Exit.
> Sep 13 22:40:46 [NetworkManager] <WARN>  ppp_exit_code(): ppp pid 9500 exited 
> with error: Fatal pppd error_
> Sep 13 22:40:46 [NetworkManager] <info>  (ttyACM1): device state change: 5 -> 
> 9 (reason 14)_
> Sep 13 22:40:46 [NetworkManager] <info>  Marking connection 'Three' invalid._
> Sep 13 22:40:46 [NetworkManager] <info>  Activation (ttyACM1) failed._
> Sep 13 22:40:46 [NetworkManager] <info>  (ttyACM1): device state change: 9 -> 
> 3 (reason 0)_
> Sep 13 22:40:46 [NetworkManager] <info>  (ttyACM1): deactivating device 
> (reason: 0)._
> Sep 13 22:40:46 [NetworkManager] nm_ip4_config_add_nameserver: assertion 
> `nameserver != s' failed
> Sep 13 22:40:46 [NetworkManager] <info>  Policy set 'Auto eth2' (eth2) as 
> default for routing and DNS._
> Sep 13 22:40:46 [NetworkManager] nm_system_device_flush_ip4_routes_with_iface: 
> assertion `iface_idx >= 0' failed
> Sep 13 22:40:47 [NetworkManager] 
> nm_system_device_flush_ip4_addresses_with_iface: assertion `iface_idx >= 0' 
> failed
> 
> 
> Thanks,
> Rob
> _______________________________________________
> NetworkManager-list mailing list
> NetworkManager-list gnome org
> http://mail.gnome.org/mailman/listinfo/networkmanager-list