Comment 8 for bug 289690

OK

Here is the output after just connecting E220 without unlocking PIN first, trying to connect:

NetworkManager: <info> ttyUSB0: driver is 'option'.
NetworkManager: <debug> [1225549631.683082] setup_monitor_device(): No monitoring udi provided
NetworkManager: <info> Found new Modem device 'ttyUSB0'.
NetworkManager: <info> (ttyUSB0): exported as /org/freedesktop/Hal/devices/usb_device_12d1_1003_noserial_if0_serial_usb_0
NetworkManager: <info> (ttyUSB0): device state change: 1 -> 2
NetworkManager: <info> (ttyUSB0): deactivating device (reason: 2).
NetworkManager: nm_system_device_flush_ip4_routes_with_iface: assertion `iface_idx >= 0' failed
NetworkManager: nm_system_device_flush_ip4_addresses_with_iface: assertion `iface_idx >= 0' failed
NetworkManager: <info> (ttyUSB0): device state change: 2 -> 3
NetworkManager: <info> Activation (ttyUSB0) starting connection 'Auto Mobile Broadband (GSM) connection'
NetworkManager: <info> (ttyUSB0): device state change: 3 -> 4
NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
NetworkManager: <debug> [1225549635.879568] nm_serial_device_open(): (ttyUSB0) opening device...
NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager: <WARN> init_done(): Modem initialization failed
NetworkManager: <info> (ttyUSB0): device state change: 4 -> 9
NetworkManager: <debug> [1225549647.004562] nm_serial_device_close(): Closing device 'ttyUSB0'
NetworkManager: <info> Marking connection 'Auto Mobile Broadband (GSM) connection' invalid.
NetworkManager: <info> Activation (ttyUSB0) failed.
NetworkManager: <info> (ttyUSB0): device state change: 9 -> 3
NetworkManager: <info> (ttyUSB0): deactivating device (reason: 0).
NetworkManager: nm_system_device_flush_ip4_routes_with_iface: assertion `iface_idx >= 0' failed
NetworkManager: nm_system_device_flush_ip4_addresses_with_iface: assertion `iface_idx >= 0' failed

Doing a wvdial pin - to unlock PIN. Trying to connect again:

NetworkManager: <info> Activation (ttyUSB0) starting connection 'Auto Mobile Broadband (GSM) connection'
NetworkManager: <info> (ttyUSB0): device state change: 3 -> 4
NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
NetworkManager: <debug> [1225549678.328756] nm_serial_device_open(): (ttyUSB0) opening device...
NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager: <WARN> init_done(): Modem initialization failed
NetworkManager: <info> (ttyUSB0): device state change: 4 -> 9
NetworkManager: <debug> [1225549689.004746] nm_serial_device_close(): Closing device 'ttyUSB0'
NetworkManager: <info> Marking connection 'Auto Mobile Broadband (GSM) connection' invalid.
NetworkManager: <info> Activation (ttyUSB0) failed.
NetworkManager: <info> (ttyUSB0): device state change: 9 -> 3
NetworkManager: <info> (ttyUSB0): deactivating device (reason: 0).
NetworkManager: nm_system_device_flush_ip4_routes_with_iface: assertion `iface_idx >= 0' failed
NetworkManager: nm_system_device_flush_ip4_addresses_with_iface: assertion `iface_idx >= 0' failed

NetworkManager: <info> Activation (ttyUSB0) starting connection 'Auto Mobile Broadband (GSM) connection'
NetworkManager: <info> (ttyUSB0): device state change: 3 -> 4
NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
NetworkManager: <debug> [1225549748.386857] nm_serial_device_open(): (ttyUSB0) opening device...
NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager: <info> (ttyUSB0): powering up...
NetworkManager: <info> Registered on Home network
NetworkManager: <info> Associated with network: +COPS: 1,0,"NetCom",0
NetworkManager: <info> Connected, Woo!
NetworkManager: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) scheduled...
NetworkManager: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) starting...
NetworkManager: <info> (ttyUSB0): device state change: 4 -> 5
NetworkManager: <info> Starting pppd connection
NetworkManager: <debug> [1225549748.888944] nm_ppp_manager_start(): Command line: /usr/sbin/pppd nodetach lock user netcom ttyUSB0 noipdefault usepeerdns ipparam /org/freedesktop/NetworkManager/PPP/0 plugin /usr/lib/pppd/2.4.4/nm-pppd-plugin.so
NetworkManager: <debug> [1225549748.890639] nm_ppp_manager_start(): ppp started with pid 9095
NetworkManager: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) complete.
Plugin /usr/lib/pppd/2.4.4/nm-pppd-plugin.so loaded.
Using interface ppp0
Connect: ppp0 <--> /dev/ttyUSB0
NetworkManager: <info> (ttyUSB0): device state change: 5 -> 6
CHAP authentication succeeded
CHAP authentication succeeded
NetworkManager: <info> (ttyUSB0): device state change: 6 -> 7
NetworkManager: <WARN> pppd_timed_out(): Looks like pppd didn't initialize our dbus module
NetworkManager: <info> (ttyUSB0): device state change: 7 -> 9
NetworkManager: <debug> [1225549763.898624] nm_serial_device_close(): Closing device 'ttyUSB0'
Terminating on signal 15
NetworkManager: <info> Marking connection 'Auto Mobile Broadband (GSM) connection' invalid.
NetworkManager: <info> Activation (ttyUSB0) failed.
Connection terminated.
NetworkManager: <info> (ttyUSB0): device state change: 9 -> 3
NetworkManager: <info> (ttyUSB0): deactivating device (reason: 0).
NetworkManager: nm_system_device_flush_ip4_routes_with_iface: assertion `iface_idx >= 0' failed
NetworkManager: nm_system_device_flush_ip4_addresses_with_iface: assertion `iface_idx >= 0' failed
NetworkManager: <debug> [1225549765.901080] ensure_killed(): waiting for ppp pid 9095 to exit
NetworkManager: <debug> [1225549765.901608] ensure_killed(): ppp pid 9095 cleaned up

Successful connection

NetworkManager: <debug> [1225549830.729020] nm_ppp_manager_start(): Command line: /usr/sbin/pppd nodetach lock user netcom ttyUSB0 noipdefault usepeerdns ipparam /org/freedesktop/NetworkManager/PPP/2 plugin /usr/lib/pppd/2.4.4/nm-pppd-plugin.so
NetworkManager: <debug> [1225549830.730552] nm_ppp_manager_start(): ppp started with pid 9157
NetworkManager: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) complete.
Plugin /usr/lib/pppd/2.4.4/nm-pppd-plugin.so loaded.
Using interface ppp0
Connect: ppp0 <--> /dev/ttyUSB0
NetworkManager: <info> (ttyUSB0): device state change: 5 -> 6
CHAP authentication succeeded
CHAP authentication succeeded
NetworkManager: <info> (ttyUSB0): device state change: 6 -> 7
Could not determine remote IP address: defaulting to 10.64.64.64
Cannot determine ethernet address for proxy ARP
local IP address 89.8.158.248
remote IP address 10.64.64.64
primary DNS address 212.169.123.67
secondary DNS address 212.45.188.254
NetworkManager: <info> PPP manager(IP Config Get) reply received.
NetworkManager: <info> Activation (ttyUSB0) Stage 4 of 5 (IP Configure Get) scheduled...
NetworkManager: <info> Activation (ttyUSB0) Stage 4 of 5 (IP Configure Get) started...
NetworkManager: <info> Activation (ttyUSB0) Stage 5 of 5 (IP Configure Commit) scheduled...
NetworkManager: <info> Activation (ttyUSB0) Stage 4 of 5 (IP Configure Get) complete.
NetworkManager: <info> Activation (ttyUSB0) Stage 5 of 5 (IP Configure Commit) started...
NetworkManager: <info> (ttyUSB0): device state change: 7 -> 8
NetworkManager: <info> Policy set 'Auto Mobile Broadband (GSM) connection' (ppp0) as default for routing and DNS.
NetworkManager: <info> Activation (ttyUSB0) successful, device activated.
NetworkManager: <info> Activation (ttyUSB0) Stage 5 of 5 (IP Configure Commit) complete.