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

[Dan Williams <dcbw redhat com>]

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.

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.

Dan


> lsusb shows:
> 
> Bus 001 Device 021: ID 0fce:d0b7 Sony Ericsson Mobile Communications AB 
> 
> and it shows up as /dev/ttyACM0. 
> 
> Is there an HOWTO out there detailing what I need to do to get pppd to
> work with my K770i? 
> 
> When I tried to start the pppd connection with NetworkManager I got the
> following in the logs:
> 
> Aug  3 11:23:09 lithium NetworkManager: <info>  Activation (ttyACM0) starting connection 'Vodafone'
> Aug  3 11:23:09 lithium NetworkManager: <info>  (ttyACM0): device state change: 3 -> 4 (reason 0)
> Aug  3 11:23:09 lithium NetworkManager: <info>  Activation (ttyACM0) Stage 1 of 5 (Device Prepare) scheduled...
> Aug  3 11:23:09 lithium NetworkManager: <info>  Activation (ttyACM0) Stage 1 of 5 (Device Prepare) started...
> Aug  3 11:23:09 lithium NetworkManager: <info>  Activation (ttyACM0) Stage 1 of 5 (Device Prepare) complete.
> Aug  3 11:23:09 lithium modem-manager: (ttyACM0) opening serial device...
> Aug  3 11:23:09 lithium modem-manager: Modem /org/freedesktop/ModemManager/Modems/3: state changed (disabled -> enabling)
> Aug  3 11:23:09 lithium modem-manager: Got failure code 3: No carrier
> Aug  3 11:23:10 lithium modem-manager: Modem /org/freedesktop/ModemManager/Modems/3: state changed (enabling -> enabled)
> Aug  3 11:23:10 lithium modem-manager: Registration state changed: 1
> Aug  3 11:23:10 lithium modem-manager: Modem /org/freedesktop/ModemManager/Modems/3: state changed (enabled -> registered)
> Aug  3 11:23:10 lithium modem-manager: Modem /org/freedesktop/ModemManager/Modems/3: state changed (registered -> connecting)
> Aug  3 11:23:11 lithium modem-manager: Modem /org/freedesktop/ModemManager/Modems/3: state changed (connecting -> connected)
> Aug  3 11:23:11 lithium NetworkManager: <info>  Activation (ttyACM0) Stage 2 of 5 (Device Configure) scheduled...
> Aug  3 11:23:11 lithium NetworkManager: <info>  Activation (ttyACM0) Stage 2 of 5 (Device Configure) starting...
> Aug  3 11:23:11 lithium NetworkManager: <info>  (ttyACM0): device state change: 4 -> 5 (reason 0)
> Aug  3 11:23:11 lithium NetworkManager: <info>  Activation (ttyACM0) Stage 2 of 5 (Device Configure) successful.
> Aug  3 11:23:11 lithium NetworkManager: <info>  Activation (ttyACM0) Stage 3 of 5 (IP Configure Start) scheduled.
> Aug  3 11:23:11 lithium NetworkManager: <info>  Activation (ttyACM0) Stage 2 of 5 (Device Configure) complete.
> Aug  3 11:23:11 lithium NetworkManager: <info>  Activation (ttyACM0) Stage 3 of 5 (IP Configure Start) started...
> Aug  3 11:23:11 lithium NetworkManager: <info>  (ttyACM0): device state change: 5 -> 7 (reason 0)
> Aug  3 11:23:11 lithium NetworkManager: <info>  Starting pppd connection
> Aug  3 11:23:11 lithium NetworkManager: <debug> [1280830991.450278] nm_ppp_manager_start(): Command line: /usr/sbin/pppd nodetach lock nodefaultroute ttyACM0 noipdefault noauth usepeerdns lcp-echo-failure 0 lcp-echo-interval 0 ipparam /org/freedesktop/NetworkManager/PPP/2 plugin /usr/lib/pppd/2.4.4/nm-pppd-plugin.so
> Aug  3 11:23:11 lithium NetworkManager: <debug> [1280830991.457036] nm_ppp_manager_start(): ppp started with pid 4980
> Aug  3 11:23:11 lithium NetworkManager: <info>  Activation (ttyACM0) Stage 4 of 5 (IP6 Configure Get) scheduled...
> Aug  3 11:23:11 lithium NetworkManager: <info>  Activation (ttyACM0) Stage 3 of 5 (IP Configure Start) complete.
> Aug  3 11:23:11 lithium NetworkManager: <info>  Activation (ttyACM0) Stage 4 of 5 (IP6 Configure Get) started...
> Aug  3 11:23:11 lithium NetworkManager: <info>  Activation (ttyACM0) Stage 4 of 5 (IP6 Configure Get) complete.
> Aug  3 11:23:11 lithium pppd[4980]: Plugin /usr/lib/pppd/2.4.4/nm-pppd-plugin.so loaded.
> Aug  3 11:23:11 lithium pppd[4980]: pppd 2.4.4 started by root, uid 0
> Aug  3 11:23:11 lithium modem-manager: (net/ppp0): could not get port's parent device
> Aug  3 11:23:11 lithium pppd[4980]: Using interface ppp0
> Aug  3 11:23:11 lithium pppd[4980]: Connect: ppp0 <--> /dev/ttyACM0
> Aug  3 11:23:11 lithium pppd[4980]: CHAP authentication succeeded: Congratulations!
> Aug  3 11:23:11 lithium pppd[4980]: CHAP authentication succeeded
> Aug  3 11:23:12 lithium pppd[4980]: LCP terminated by peer
> Aug  3 11:23:12 lithium pppd[4980]: Modem hangup
> Aug  3 11:23:12 lithium pppd[4980]: Connection terminated.
> Aug  3 11:23:12 lithium NetworkManager: <info>  (ttyACM0): device state change: 7 -> 9 (reason 13)
> Aug  3 11:23:12 lithium modem-manager: (ttyACM0) closing serial device...
> Aug  3 11:23:12 lithium NetworkManager: <info>  Marking connection 'Vodafone' invalid.
> Aug  3 11:23:12 lithium NetworkManager: <info>  Activation (ttyACM0) failed.
> Aug  3 11:23:12 lithium NetworkManager: <info>  (ttyACM0): device state change: 9 -> 3 (reason 0)
> Aug  3 11:23:12 lithium NetworkManager: <info>  (ttyACM0): deactivating device (reason: 0).
> Aug  3 11:23:12 lithium modem-manager: Modem /org/freedesktop/ModemManager/Modems/3: state changed (connected -> disconnecting)
> Aug  3 11:23:12 lithium modem-manager: Modem /org/freedesktop/ModemManager/Modems/3: state changed (disconnecting -> connected)
> Aug  3 11:23:13 lithium pppd[4980]: Exit.
> Aug  3 11:23:13 lithium dbus-daemon: [system] Rejected send message, 1 matched rules; type="error", sender=":1.92" (uid=0 pid=23090 comm="/usr/sbin/NetworkManager) interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply=0 destination=":1.149" (uid=0 pid=4980 comm="/usr/sbin/pppd))
> Aug  3 11:23:14 lithium NetworkManager: <debug> [1280830994.999451] ensure_killed(): waiting for ppp pid 4980 to exit
> Aug  3 11:23:14 lithium NetworkManager: <debug> [1280830994.999632] ensure_killed(): ppp pid 4980 cleaned up
>