Re: Using NetworkMananger with Sony Ericsson K770i via USB (ttyACM0)

[Alex Buell <alex buell munted org uk>]

On Wed, 2010-08-04 at 01:52 -0500, Dan Williams wrote:

> On Tue, 2010-08-03 at 11:24 +0100, Alex Buell wrote:
> > Hi!
> > 
> > Whilst I have a fully working pppd connection via my K770i mobile phone
> > using manual pon / poff manual commands, I can't seem to set it up
> > properly with Network Mananger. 
> 
> Looks like it gets up to PPP at least, you can try toggling various
> settings in the PPP page in the connection editor.  Specifically, turn
> off compression (both BSD and deflate) and turn off header compression
> and see if that helps at all.

I had already done that, turned off all compression but it has made no
difference. 

> Next thing you can do is to stop NM and then:
> 
> NM_PPP_DEBUG=1 NetworkManager --no-daemon
> 
> and we'll get extra-verbose PPP logging so we can figure out what is
> actually going on in pppd.

OK, here's the logs from trying to connect with pppd. 

NetworkManager: <info>  (ttyACM0): new GSM device (driver: 'cdc_acm')
NetworkManager: <info>  (ttyACM0): exported as /org/freedesktop/NetworkManager/Devices/5
NetworkManager: <info>  (ttyACM0): now managed
NetworkManager: <info>  (ttyACM0): device state change: 1 -> 2 (reason 2)
NetworkManager: <info>  (ttyACM0): deactivating device (reason: 2).
NetworkManager: <info>  (ttyACM0): device state change: 2 -> 3 (reason 0)
NetworkManager: <info>  Activation (ttyACM0) starting connection 'Vodafone'
NetworkManager: <info>  (ttyACM0): device state change: 3 -> 4 (reason 0)
NetworkManager: <info>  Activation (ttyACM0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager: <info>  Activation (ttyACM0) Stage 1 of 5 (Device Prepare) started...
NetworkManager: <info>  Activation (ttyACM0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager: <info>  Activation (ttyACM0) Stage 2 of 5 (Device Configure) scheduled...
NetworkManager: <info>  Activation (ttyACM0) Stage 2 of 5 (Device Configure) starting...
NetworkManager: <info>  (ttyACM0): device state change: 4 -> 5 (reason 0)
NetworkManager: <info>  Activation (ttyACM0) Stage 2 of 5 (Device Configure) successful.
NetworkManager: <info>  Activation (ttyACM0) Stage 3 of 5 (IP Configure Start) scheduled.
NetworkManager: <info>  Activation (ttyACM0) Stage 2 of 5 (Device Configure) complete.
NetworkManager: <info>  Activation (ttyACM0) Stage 3 of 5 (IP Configure Start) started...
NetworkManager: <info>  (ttyACM0): device state change: 5 -> 7 (reason 0)
NetworkManager: <info>  Starting pppd connection
NetworkManager: <debug> [1280912081.147528] nm_ppp_manager_start(): Command line: /usr/sbin/pppd nodetach lock nodefaultroute debug ttyACM0 noipdefault noauth nobsdcomp nodeflate usepeerdns lcp-echo-failure 0 lcp-echo-interval 0 ipparam /org/freedesktop/NetworkManager/PPP/0 plugin /usr/lib/pppd/2.4.4/nm-pppd-plugin.so
NetworkManager: <debug> [1280912081.151654] nm_ppp_manager_start(): ppp started with pid 17433
NetworkManager: <info>  Activation (ttyACM0) Stage 4 of 5 (IP6 Configure Get) scheduled...
NetworkManager: <info>  Activation (ttyACM0) Stage 3 of 5 (IP Configure Start) complete.
NetworkManager: <info>  Activation (ttyACM0) Stage 4 of 5 (IP6 Configure Get) started...
NetworkManager: <info>  Activation (ttyACM0) Stage 4 of 5 (IP6 Configure Get) complete.
Plugin /usr/lib/pppd/2.4.4/nm-pppd-plugin.so loaded.
** Message: nm-ppp-plugin: (plugin_init): initializing
** Message: nm-ppp-plugin: (nm_phasechange): status 3 / phase 'serial connection'
using channel 48
Using interface ppp0
Connect: ppp0 <--> /dev/ttyACM0
** Message: nm-ppp-plugin: (nm_phasechange): status 5 / phase 'establish'
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x93d0cde2> <pcomp> <accomp>]
rcvd [LCP ConfReq id=0x2 <auth chap MD5> <accomp> <pcomp> <asyncmap 0x0> <magic 0xd7d19791>]
sent [LCP ConfAck id=0x2 <auth chap MD5> <accomp> <pcomp> <asyncmap 0x0> <magic 0xd7d19791>]
rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0x93d0cde2> <pcomp> <accomp>]
** Message: nm-ppp-plugin: (nm_phasechange): status 6 / phase 'authenticate'
rcvd [CHAP Challenge id=0x0 <d47d72c34079be1f6c35ca3b58b1961704ed22b370e96e0f9ca57a2b88214607345dd2a3a0591e>, name = "Kermit"]
** Message: nm-ppp-plugin: (get_credentials): passwd-hook, requesting credentials...
** Message: nm-ppp-plugin: (get_credentials): got credentials from NetworkManager
sent [CHAP Response id=0x0 <1b65a86c0f5ef93ed1f630c23ebd3832>, name = ""]
rcvd [CHAP Success id=0x0 "Congratulations!"]
CHAP authentication succeeded: Congratulations!
CHAP authentication succeeded
** Message: nm-ppp-plugin: (nm_phasechange): status 8 / phase 'network'
sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
rcvd [LCP TermReq id=0x1]
LCP terminated by peer
** Message: nm-ppp-plugin: (nm_phasechange): status 5 / phase 'establish'
sent [LCP TermAck id=0x1]
Modem hangup
** Message: nm-ppp-plugin: (nm_phasechange): status 11 / phase 'disconnect'
Connection terminated.
NetworkManager: <info>  (ttyACM0): device state change: 7 -> 9 (reason 13)
NetworkManager: <info>  Marking connection 'Vodafone' invalid.
NetworkManager: <info>  Activation (ttyACM0) failed.
NetworkManager: <info>  (ttyACM0): device state change: 9 -> 3 (reason 0)
NetworkManager: <info>  (ttyACM0): deactivating device (reason: 0).
** Message: nm-ppp-plugin: (nm_phasechange): status 1 / phase 'dead'
** Message: nm-ppp-plugin: (nm_exit_notify): cleaning up
NetworkManager: <debug> [1280912084.000671] ensure_killed(): waiting for ppp pid 17433 to exit
NetworkManager: <debug> [1280912084.000891] ensure_killed(): ppp pid 17433 cleaned up

Hopefully we can find the cause! 
-- 
http://www.munted.org.uk

One very high maintenance cat living here.