9.10 beta - Samsung SGH-ZV50 - HSPA modem - cannot connect to EDGE/UMTS network

[Gino]

Binary package hint: modemmanager

Description: Ubuntu karmic (development branch)
Release: 9.10

Networkmanager / Modemmanager does not connect Samsung SGH-ZV50 mobile phone to UMTS/EDGE network. After selecting the connection network manager shows "GSM network - Disconnected" immediately.

I have verified that this used to work in jaunty with the 9.04 live boot disk.
I can also connect with the 9.10 beta using wvdial - so this is modemmanager specific.
Please ignore the wlan stuff in nm.log.txt - the phone device is ttyACM0.

Attached are the logs according to
https://wiki.ubuntu.com/DebuggingModemmanager

mm-test output:
GSM modem
Driver: 'cdc_acm'
Modem device: '/sys/devices/pci0000:00/0000:00:1d.1/usb3/3-2'
Data device: 'ttyACM0'
Vendor: SAMSUNG ELECTRONICS CORPORATION
Model: SGH-ZV50
Version: ZV50BUFE7
IMEI: <private>
IMSI: <private>
Signal quality: 29
Scanning...
Singtel Optus Li: available (GSM)
Singtel Optus Li: current (UMTS)
Telstra Mobile C: forbidden (GSM)
Vodafone Pacific: forbidden (GSM)
: forbidden (UMTS)
Vodafone Pacific: forbidden (UMTS)

nm.log.txt:
NetworkManager: <info> starting...
NetworkManager: <info> modem-manager is now available
NetworkManager: SCPlugin-Ifupdown: init!
NetworkManager: SCPlugin-Ifupdown: update_system_hostname
NetworkManager: SCPluginIfupdown: management mode: unmanaged
NetworkManager: SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0/net/eth0, iface: eth0)
NetworkManager: SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0/net/eth0, iface: eth0): no ifupdown configuration found.
NetworkManager: SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:1c.2/0000:01:00.0/net/wlan0, iface: wlan0)
NetworkManager: SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:1c.2/0000:01:00.0/net/wlan0, iface: wlan0): no ifupdown configuration found.
NetworkManager: SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:1c.2/0000:01:00.0/net/wmaster0, iface: wmaster0)
NetworkManager: SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:1c.2/0000:01:00.0/net/wmaster0, iface: wmaster0): no ifupdown configuration found.
NetworkManager: SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/lo, iface: lo)
NetworkManager: SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/lo, iface: lo): no ifupdown configuration found.
NetworkManager: SCPlugin-Ifupdown: end _init.
NetworkManager: Loaded plugin ifupdown: (C) 2008 Canonical Ltd. To report bugs please use the NetworkManager mailing list.
NetworkManager: Loaded plugin keyfile: (c) 2007 - 2008 Red Hat, Inc. To report bugs please use the NetworkManager mailing list.
NetworkManager: <info> Found radio killswitch rfkill1 (at /sys/devices/pci0000:00/0000:00:1c.2/0000:01:00.0/ieee80211/phy0/rfkill1) (driver <unknown>)
NetworkManager: <info> Found radio killswitch rfkill0 (at /sys/devices/platform/eeepc/rfkill/rfkill0) (driver eeepc)
NetworkManager: <info> Wireless now enabled by radio killswitch
NetworkManager: SCPlugin-Ifupdown: (136883488) ... get_connections.
NetworkManager: SCPlugin-Ifupdown: (136883488) ... get_connections (managed=false): return empty list.
NetworkManager: Ifupdown: get unmanaged devices count: 0
NetworkManager: <info> (eth0): carrier is OFF
NetworkManager: <info> (eth0): new Ethernet device (driver: 'atl2')
NetworkManager: <info> (eth0): exported as /org/freedesktop/NetworkManager/Devices/0
NetworkManager: <info> (eth0): now managed
NetworkManager: <info> (eth0): device state change: 1 -> 2 (reason 2)
NetworkManager: <info> (eth0): bringing up device.
NetworkManager: <info> (eth0): preparing device.
NetworkManager: <info> (eth0): deactivating device (reason: 2).
NetworkManager: Added default wired connection 'Auto eth0' for /sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0/net/eth0
NetworkManager: <info> (wlan0): driver supports SSID scans (scan_capa 0x01).
NetworkManager: <info> (wlan0): new 802.11 WiFi device (driver: 'ath5k')
NetworkManager: <info> (wlan0): exported as /org/freedesktop/NetworkManager/Devices/1
NetworkManager: <info> (wlan0): now managed
NetworkManager: <info> (wlan0): device state change: 1 -> 2 (reason 2)
NetworkManager: <info> (wlan0): bringing up device.
NetworkManager: <info> (wlan0): preparing device.
NetworkManager: <info> (wlan0): deactivating device (reason: 2).
NetworkManager: <info> (ttyACM0): new GSM device (driver: 'cdc_acm')
NetworkManager: <info> (ttyACM0): exported as /org/freedesktop/NetworkManager/Devices/2
NetworkManager: <info> (ttyACM0): now managed
NetworkManager: <info> (ttyACM0): device state change: 1 -> 2 (reason 2)
NetworkManager: <info> (ttyACM0): deactivating device (reason: 2).
NetworkManager: flush_routes: assertion `iface_idx >= 0' failed
NetworkManager: flush_addresses: assertion `iface_idx >= 0' failed
NetworkManager: <WARN> default_adapter_cb(): bluez error getting default adapter: The name org.bluez was not provided by any .service files
NetworkManager: <info> (ttyACM0): device state change: 2 -> 3 (reason 0)
NetworkManager: <info> (wlan0): supplicant interface state: starting -> ready
NetworkManager: <info> (wlan0): device state change: 2 -> 3 (reason 42)
NetworkManager: Tried to set deprecated property gsm/band
NetworkManager: Tried to set deprecated property gsm/band
NetworkManager: Tried to set deprecated property gsm/band
NetworkManager: <info> Activation (wlan0) starting connection 'Auto GPAero'
NetworkManager: <info> (wlan0): device state change: 3 -> 4 (reason 0)
NetworkManager: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
NetworkManager: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
NetworkManager: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
NetworkManager: <info> (wlan0): device state change: 4 -> 5 (reason 0)
NetworkManager: <info> Activation (wlan0/wireless): access point 'Auto GPAero' has security, but secrets are required.
NetworkManager: <info> (wlan0): device state change: 5 -> 6 (reason 0)
NetworkManager: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
NetworkManager: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
NetworkManager: <info> (wlan0): device state change: 6 -> 4 (reason 0)
NetworkManager: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
NetworkManager: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
NetworkManager: <info> (wlan0): device state change: 4 -> 5 (reason 0)
NetworkManager: <info> Activation (wlan0/wireless): connection 'Auto GPAero' has security, and secrets exist. No new secrets needed.
NetworkManager: <info> Config: added 'ssid' value 'GPAero'
NetworkManager: <info> Config: added 'scan_ssid' value '1'
NetworkManager: <info> Config: added 'key_mgmt' value 'NONE'
NetworkManager: <info> Config: added 'auth_alg' value 'OPEN'
NetworkManager: <info> Config: added 'wep_key0' value '<omitted>'
NetworkManager: <info> Config: added 'wep_tx_keyidx' value '0'
NetworkManager: nm_setting_802_1x_get_pkcs11_engine_path: assertion `NM_IS_SETTING_802_1X (setting)' failed
NetworkManager: nm_setting_802_1x_get_pkcs11_module_path: assertion `NM_IS_SETTING_802_1X (setting)' failed
NetworkManager: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
NetworkManager: <info> Config: set interface ap_scan to 1
NetworkManager: <info> (wlan0): supplicant connection state: scanning -> disconnected
NetworkManager: <info> (wlan0): supplicant connection state: disconnected -> scanning
NetworkManager: <info> (wlan0): supplicant connection state: scanning -> associating
NetworkManager: <info> (wlan0): supplicant connection state: associating -> associated
NetworkManager: <info> (wlan0): supplicant connection state: associated -> completed
NetworkManager: <info> Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful. Connected to wireless network 'GPAero'.
NetworkManager: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
NetworkManager: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
NetworkManager: <info> (wlan0): device state change: 5 -> 7 (reason 0)
NetworkManager: <info> Activation (wlan0) Beginning DHCP transaction (timeout in 45 seconds)
Internet Systems Consortium DHCP Client V3.1.2
Copyright 2004-2008 Internet Systems Consortium.
NetworkManager: <info> dhclient started with pid 2055
NetworkManager: <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) scheduled...
NetworkManager: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
NetworkManager: <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) started...
NetworkManager: <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) complete.
All rights reserved.
For info, please visit http://www.isc.org/sw/dhcp/

Listening on LPF/wlan0/00:15:af:80:d6:72
Sending on LPF/wlan0/00:15:af:80:d6:72
Sending on Socket/fallback
NetworkManager: <info> DHCP: device wlan0 state changed (null) -> preinit
DHCPREQUEST of 192.168.0.86 on wlan0 to 255.255.255.255 port 67
DHCPACK of 192.168.0.86 from 192.168.0.60
bound to 192.168.0.86 -- renewal in 36984 seconds.
NetworkManager: <info> DHCP: device wlan0 state changed preinit -> reboot
NetworkManager: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) scheduled...
NetworkManager: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) started...
NetworkManager: <info> address 192.168.0.86
NetworkManager: <info> prefix 24 (255.255.255.0)
NetworkManager: <info> gateway 192.168.0.60
NetworkManager: <info> hostname 'gpeee'
NetworkManager: <info> nameserver '192.168.0.60'
NetworkManager: <info> domain name 'ginoputrino.dyndns.org'
NetworkManager: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) scheduled...
NetworkManager: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) complete.
NetworkManager: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started...
NetworkManager: <info> (wlan0): device state change: 7 -> 8 (reason 0)
NetworkManager: <info> Policy set 'Auto GPAero' (wlan0) as default for routing and DNS.
NetworkManager: <info> Activation (wlan0) successful, device activated.
NetworkManager: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete.
NetworkManager: <info> Activation (ttyACM0) starting connection 'Exetel Default 1'
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: <WARN> stage1_prepare_done(): GSM modem connection failed: Serial command timed out
NetworkManager: <info> (ttyACM0): device state change: 4 -> 9 (reason 1)
NetworkManager: <info> Marking connection 'Exetel Default 1' invalid.
NetworkManager: <info> Activation (ttyACM0) failed.
NetworkManager: <info> (ttyACM0): device state change: 9 -> 3 (reason 0)
NetworkManager: <info> (ttyACM0): deactivating device (reason: 0).
NetworkManager: flush_routes: assertion `iface_idx >= 0' failed
NetworkManager: flush_addresses: assertion `iface_idx >= 0' failed
NetworkManager: <info> Policy set 'Auto GPAero' (wlan0) as default for routing and DNS.