kubuntu jaunty beta KPC680: Dialing timed out

[Karl]

Upgraded from 8.10

to

$ lsb_release -rd
Description: Ubuntu jaunty (development branch)
Release: 9.04

 What I expected to happen:

networkmanager should dial and connect with verizon

 What happened instead

connection failed

Apr 11 21:37:16 nomad kernel: [ 193.012053] usb 2-6: new full speed USB device using ohci_hcd and address 3
Apr 11 21:37:17 nomad kernel: [ 193.232096] usb 2-6: configuration #1 chosen from 1 choice
Apr 11 21:37:17 nomad kernel: [ 193.315015] USB Serial support registered for GSM modem (1-port)
Apr 11 21:37:17 nomad kernel: [ 193.315167] option 2-6:1.0: GSM modem (1-port) converter detected
Apr 11 21:37:17 nomad kernel: [ 193.315370] usb 2-6: GSM modem (1-port) converter now attached to ttyUSB0
Apr 11 21:37:17 nomad kernel: [ 193.315417] option 2-6:1.1: GSM modem (1-port) converter detected
Apr 11 21:37:17 nomad kernel: [ 193.315520] usb 2-6: GSM modem (1-port) converter now attached to ttyUSB1
Apr 11 21:37:17 nomad kernel: [ 193.315549] usbcore: registered new interface driver option
Apr 11 21:37:17 nomad kernel: [ 193.315554] option: v0.7.2:USB Driver for GSM modems
Apr 11 21:37:17 nomad nm-system-settings: SCPlugin-Ifupdown: device added (udi: /org/freedesktop/Hal/devices/usb_device_c88_180a_noserial_if0_serial_usb_0, iface: (null)): iface not found
Apr 11 21:37:17 nomad NetworkManager: <info> (ttyUSB0): found serial port (udev:CDMA hal:CDMA)
Apr 11 21:37:17 nomad NetworkManager: <info> (ttyUSB0): new Modem device (driver: 'option')
Apr 11 21:37:17 nomad NetworkManager: <info> (ttyUSB0): exported as /org/freedesktop/Hal/devices/usb_device_c88_180a_noserial_if0_serial_usb_0
Apr 11 21:37:18 nomad kernel: [ 194.289051] CE: hpet increasing min_delta_ns to 15000 nsec
Apr 11 21:37:22 nomad NetworkManager: <info> (ttyUSB0): device state change: 1 -> 2
Apr 11 21:37:22 nomad NetworkManager: <info> (ttyUSB0): deactivating device (reason: 2).
Apr 11 21:37:22 nomad NetworkManager: nm_system_device_flush_ip4_routes_with_iface: assertion `iface_idx >= 0' failed
Apr 11 21:37:22 nomad NetworkManager: nm_system_device_flush_ip4_addresses_with_iface: assertion `iface_idx >= 0' failed
Apr 11 21:37:22 nomad NetworkManager: <info> (ttyUSB0): device state change: 2 -> 3
Apr 11 21:37:22 nomad NetworkManager: <info> Activation (ttyUSB0) starting connection 'Verizon'
Apr 11 21:37:22 nomad NetworkManager: <info> (ttyUSB0): device state change: 3 -> 4
Apr 11 21:37:22 nomad NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Apr 11 21:37:22 nomad NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Apr 11 21:37:22 nomad NetworkManager: <debug> [1239503842.011772] nm_serial_device_open(): (ttyUSB0) opening device...
Apr 11 21:37:22 nomad NetworkManager: Invalid baudrate '167732906'
Apr 11 21:37:22 nomad NetworkManager: Invalid bits (0). Valid values are 5, 6, 7, 8.
Apr 11 21:37:22 nomad NetworkManager: Invalid parity (
Apr 11 21:37:22 nomad NetworkManager: Invalid stop bits (0). Valid values are 1 and 2)
Apr 11 21:37:22 nomad NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Apr 11 21:38:12 nomad NetworkManager: <info> (ttyUSB0): powering up...
Apr 11 21:39:46 nomad NetworkManager: <info> (ttyUSB1): ignoring due to lack of mobile broadband capabilties
Apr 11 21:40:01 nomad /USR/SBIN/CRON[3806]: (root) CMD ([ -x /usr/sbin/update-motd ] && /usr/sbin/update-motd2>/dev/null)
Apr 11 21:41:44 nomad NetworkManager: <WARN> dial_done(): Dialing timed out
Apr 11 21:41:44 nomad NetworkManager: <info> (ttyUSB0): device state change: 4 -> 9
Apr 11 21:41:44 nomad NetworkManager: <debug> [1239504104.004963] nm_serial_device_close(): Closing device 'ttyUSB0'
Apr 11 21:41:44 nomad NetworkManager: <info> Marking connection 'Verizon' invalid.
Apr 11 21:41:44 nomad NetworkManager: <info> Activation (ttyUSB0) failed.
Apr 11 21:41:44 nomad NetworkManager: <info> (ttyUSB0): device state change: 9 -> 3
Apr 11 21:41:44 nomad NetworkManager: <info> (ttyUSB0): deactivating device (reason: 0).
Apr 11 21:41:44 nomad NetworkManager: nm_system_device_flush_ip4_routes_with_iface: assertion `iface_idx >= 0' failed
Apr 11 21:41:44 nomad NetworkManager: nm_system_device_flush_ip4_addresses_with_iface: assertion `iface_idx >= 0' failed

[Spomky (spomky)]

I have the same problem with my data card (Option Globetrotter)

Upgrade from Intrepid Ibex

to

lsb_release -rd:
Description: Ubuntu 9.04
Release: 9.04

Here is the result shown in /var/log/syslog:
:37 spomky1 NetworkManager: <info> Activation (noz0) starting connection 'Orange'
Apr 14 19:49:37 spomky1 NetworkManager: <info> (noz0): device state change: 3 -> 4
Apr 14 19:49:37 spomky1 NetworkManager: <info> Activation (noz0) Stage 1 of 5 (Device Prepare) scheduled...
Apr 14 19:49:37 spomky1 NetworkManager: <info> Activation (noz0) Stage 1 of 5 (Device Prepare) started...
Apr 14 19:49:37 spomky1 NetworkManager: <debug> [1239731377.528259] nm_serial_device_open(): (noz0) opening device...
Apr 14 19:49:37 spomky1 NetworkManager: Invalid baudrate '11734922'
Apr 14 19:49:37 spomky1 NetworkManager: Invalid bits (0). Valid values are 5, 6, 7, 8.
Apr 14 19:49:37 spomky1 NetworkManager: Invalid parity (
Apr 14 19:49:37 spomky1 NetworkManager: Invalid stop bits (0). Valid values are 1 and 2)
Apr 14 19:49:37 spomky1 NetworkManager: <info> Activation (noz0) Stage 1 of 5 (Device Prepare) complete.
Apr 14 19:49:48 spomky1 NetworkManager: <WARN> init_done(): Modem initialization timed out
Apr 14 19:49:48 spomky1 NetworkManager: <info> (noz0): device state change: 4 -> 9
Apr 14 19:49:48 spomky1 NetworkManager: <debug> [1239731388.003138] nm_serial_device_close(): Closing device 'noz0'
Apr 14 19:49:48 spomky1 NetworkManager: <info> Marking connection 'Orange' invalid.
Apr 14 19:49:48 spomky1 NetworkManager: <info> Activation (noz0) failed.
Apr 14 19:49:48 spomky1 NetworkManager: <info> (noz0): device state change: 9 -> 3
Apr 14 19:49:48 spomky1 NetworkManager: <info> (noz0): deactivating device (reason: 0).
Apr 14 19:49:48 spomky1 NetworkManager: nm_system_device_flush_ip4_routes_with_iface: assertion `iface_idx >= 0' failed
Apr 14 19:49:48 spomky1 NetworkManager: nm_system_device_flush_ip4_addresses_with_iface: assertion `iface_idx >= 0' failed

[Dan Bowkley (danbowkley)]

Same problem using a Sierrra Wireless USB dongle with SprintPCS (USA). Quoth the Syslog, "

May 18 14:24:45 dan-laptop kernel: [ 1794.744259] usb 4-1: new full speed USB device using ohci_hcd and address 4
May 18 14:24:45 dan-laptop kernel: [ 1794.964994] usb 4-1: configuration #1 chosen from 1 choice
May 18 14:24:45 dan-laptop kernel: [ 1794.990336] usb-storage: probe of 4-1:1.0 failed with error -5
May 18 14:24:45 dan-laptop kernel: [ 1794.994658] usb 4-1: USB disconnect, address 4
May 18 14:24:47 dan-laptop kernel: [ 1796.540260] usb 4-1: new full speed USB device using ohci_hcd and address 5
May 18 14:24:47 dan-laptop kernel: [ 1796.755469] usb 4-1: configuration #1 chosen from 1 choice
May 18 14:24:47 dan-laptop kernel: [ 1796.758431] sierra 4-1:1.0: Sierra USB modem converter detected
May 18 14:24:47 dan-laptop kernel: [ 1796.761473] usb 4-1: Sierra USB modem converter now attached to ttyUSB0
May 18 14:24:47 dan-laptop kernel: [ 1796.761552] usb 4-1: Sierra USB modem converter now attached to ttyUSB1
May 18 14:24:47 dan-laptop kernel: [ 1796.761622] usb 4-1: Sierra USB modem converter now attached to ttyUSB2
May 18 14:24:47 dan-laptop kernel: [ 1796.770653] scsi8 : SCSI emulation for USB Mass Storage devices
May 18 14:24:47 dan-laptop kernel: [ 1796.771878] usb-storage: device found at 5
May 18 14:24:47 dan-laptop kernel: [ 1796.771882] usb-storage: waiting for device to settle before scanning
May 18 14:24:48 dan-laptop nm-system-settings: SCPlugin-Ifupdown: device added (udi: /org/freedesktop/Hal/devices/usb_device_1199_23_noserial_if0_serial_usb_0, iface: (null)): iface not found
May 18 14:24:48 dan-laptop NetworkManager: <info> (ttyUSB0): found serial port (udev:CDMA hal:CDMA)
May 18 14:24:48 dan-laptop NetworkManager: <info> (ttyUSB0): new Modem device (driver: 'sierra')
May 18 14:24:48 dan-laptop NetworkManager: <info> (ttyUSB0): exported as /org/freedesktop/Hal/devices/usb_device_1199_23_noserial_if0_serial_usb_0
May 18 14:24:52 dan-laptop NetworkManager: <info> (ttyUSB0): device state change: 1 -> 2
May 18 14:24:52 dan-laptop NetworkManager: <info> (ttyUSB0): deactivating device (reason: 2).
May 18 14:24:52 dan-laptop NetworkManager: <info> Policy set 'Gemini_Techies' (wlan0) as default for routing and DNS.
May 18 14:24:52 dan-laptop NetworkManager: nm_system_device_flush_ip4_routes_with_iface: assertion `iface_idx >= 0' failed
May 18 14:24:52 dan-laptop NetworkManager: nm_system_device_flush_ip4_...

[b2ag (thomas-b2ag)]

It seems i have the same error as i already described (in german) at
http://forum.ubuntuusers.de/topic/kubuntu-9-05-umts-mit-o2-ueber-den-networkman/
and one other guy there also has these confusing line
"NetworkManager: Invalid parity ("
in his log.

[Sergiu Bivol (sergiu-bivol)]

The invalid parity is harmless.
Wrong mtu, mru, baud, bits and stop-bits are the issue (in the bug description). This prevents NM from properly initializing the modem. Please edit your connection settings file, located in ~/.kde/share/apps/networkmanagement/connections
Valid values are:
============
[ppp]
...
mru=1440
mtu=1440

[serial]
baud=0
bits=8
parity=
senddelay=12
stopbits=1
============

Relevant upstream bug: https://bugs.kde.org/show_bug.cgi?id=191658

[Spomky (spomky)]

Sorry but it did not solved the problem.

cat /var/log/syslog:
Jul 19 11:29:20 florent-laptop NetworkManager: <info> Activation (noz0) starting connection 'Orange'
Jul 19 11:29:20 florent-laptop NetworkManager: <info> (noz0): device state change: 3 -> 4
Jul 19 11:29:20 florent-laptop NetworkManager: <info> Activation (noz0) Stage 1 of 5 (Device Prepare) scheduled...
Jul 19 11:29:20 florent-laptop NetworkManager: <info> Activation (noz0) Stage 1 of 5 (Device Prepare) started...
Jul 19 11:29:20 florent-laptop NetworkManager: <debug> [1247995760.224581] nm_serial_device_open(): (noz0) opening device...
Jul 19 11:29:20 florent-laptop NetworkManager: Invalid parity (
Jul 19 11:29:20 florent-laptop NetworkManager: <info> Activation (noz0) Stage 1 of 5 (Device Prepare) complete.
Jul 19 11:29:22 florent-laptop kernel: [ 5307.377267] APIC error on CPU0: 40(40)
Jul 19 11:29:25 florent-laptop NetworkManager: <WARN> check_pin_done(): PIN checking failed to many times
Jul 19 11:29:25 florent-laptop NetworkManager: <info> (noz0): device state change: 4 -> 9
Jul 19 11:29:25 florent-laptop NetworkManager: <debug> [1247995765.003483] nm_serial_device_close(): Closing device 'noz0'
Jul 19 11:29:25 florent-laptop NetworkManager: <info> Marking connection 'Orange' invalid.
Jul 19 11:29:25 florent-laptop NetworkManager: <info> Activation (noz0) failed.
Jul 19 11:29:25 florent-laptop NetworkManager: <info> (noz0): device state change: 9 -> 3
Jul 19 11:29:25 florent-laptop NetworkManager: <info> (noz0): deactivating device (reason: 0).
Jul 19 11:29:25 florent-laptop NetworkManager: <info> Policy set 'spomky2' (wlan0) as default for routing and DNS.
Jul 19 11:29:25 florent-laptop NetworkManager: nm_system_device_flush_ip4_routes_with_iface: assertion `iface_idx >= 0' failed
Jul 19 11:29:25 florent-laptop NetworkManager: nm_system_device_flush_ip4_addresses_with_iface: assertion `iface_idx >= 0' failed

My connection settings file:
[connection]
autoconnect=false
id=Orange
timestamp=-4713,1,1,0,0,0
type=gsm
uuid={928acec3-1f7c-4577-9790-fcb7788833ce}

[gsm]
apn=orange.fr
band=5
networkid=
networktype=3473460
number=*99#
username=orange

[ipv4]
addresses=
ignoredhcpdns=false
method=Automatic
routes=

[ppp]
baud=0
crtscts=false
lcpechofailure=0
lcpechointerval=0
mppestateful=false
mru=1440
mtu=1440
noauth=false
nobsdcomp=true
nodeflate=false
novjcomp=false
refusechap=true
refuseeap=false
refusemschap=false
refusemschapv2=false
refusepap=false
requiremppe=false
requiremppe128=false

[serial]
baud=0
bits=8
parity=
senddelay=12
stopbits=1

[Sergiu Bivol (sergiu-bivol)]

It did solve the problem, but it's just one problem out of a million :)

You have another one:
====
Jul 19 11:29:25 florent-laptop NetworkManager: <WARN> check_pin_done(): PIN checking failed to many times
====

Insert your modem's SIM card in a cell phone and remove the PIN, as you would normally do with a regular SIM. Also, remove any PIN settings you set in NetworkManager. Then try connecting again and post your results.

[Spomky (spomky)]

I did not set any pin code.

Sometimes this ouput is present, sometimes not.
Here is the last output I got.

Jul 19 14:45:52 florent-laptop NetworkManager: <info> Activation (noz0) starting connection 'Orange'
Jul 19 14:45:52 florent-laptop NetworkManager: <info> (noz0): device state change: 3 -> 4
Jul 19 14:45:52 florent-laptop NetworkManager: <info> Activation (noz0) Stage 1 of 5 (Device Prepare) scheduled...
Jul 19 14:45:52 florent-laptop NetworkManager: <info> Activation (noz0) Stage 1 of 5 (Device Prepare) started...
Jul 19 14:45:52 florent-laptop NetworkManager: <debug> [1248007552.671188] nm_serial_device_open(): (noz0) opening device...
Jul 19 14:45:52 florent-laptop NetworkManager: Invalid parity (
Jul 19 14:45:52 florent-laptop NetworkManager: <info> Activation (noz0) Stage 1 of 5 (Device Prepare) complete.
Jul 19 14:46:03 florent-laptop NetworkManager: <WARN> init_done(): Modem initialization timed out
Jul 19 14:46:03 florent-laptop NetworkManager: <info> (noz0): device state change: 4 -> 9
Jul 19 14:46:03 florent-laptop NetworkManager: <debug> [1248007563.001756] nm_serial_device_close(): Closing device 'noz0'
Jul 19 14:46:03 florent-laptop NetworkManager: <info> Marking connection 'Orange' invalid.
Jul 19 14:46:03 florent-laptop NetworkManager: <info> Activation (noz0) failed.
Jul 19 14:46:03 florent-laptop NetworkManager: <info> (noz0): device state change: 9 -> 3
Jul 19 14:46:03 florent-laptop NetworkManager: <info> (noz0): deactivating device (reason: 0).
Jul 19 14:46:03 florent-laptop NetworkManager: nm_system_device_flush_ip4_routes_with_iface: assertion `iface_idx >= 0' failed
Jul 19 14:46:03 florent-laptop NetworkManager: nm_system_device_flush_ip4_addresses_with_iface: assertion `iface_idx >= 0' failed

[Spomky (spomky)]

It seems to be solved.
After a reboot the error occured but I set parity=1 in the connection settings and now it connects without any problem.

[Dan Bowkley (danbowkley)]

This is my config file...

[cdma]
number=#777
username=dan

[connection]
autoconnect=false
id=Sprint
timestamp=-4713,1,1,0,0,0
type=cdma
uuid={95133863-04df-4ec2-b7ea-3fec18dc36fe}

[ipv4]
addresses=
ignoredhcpdns=false
method=Automatic
routes=

[ppp]
baud=115200
crtscts=false
lcpechofailure=5
lcpechointerval=30
mppestateful=true
mru=7209071
mtu=3080307
noauth=false
nobsdcomp=false
nodeflate=true
novjcomp=true
refusechap=true
refuseeap=true
refusemschap=true
refusemschapv2=true
refusepap=false
requiremppe=false
requiremppe128=false

[serial]
baud=115200
bits=8
parity=1
senddelay=12
stopbits=1

When I try to connect it barfs with "invalid pppd options" and marks the connection as invalid.

[Dan Bowkley (danbowkley)]

FIXED:
Change your config file (~/.kde/share/apps/networkmanagement/connections/) to this:
[cdma]
number=#777
username=dan

[connection]
autoconnect=false
id=Sprint
timestamp=2009,7,22,12,32,28
type=cdma
uuid={7bbbc491-d716-49c4-bd1c-223e9d6068ef}

[ipv4]
addresses=
ignoredhcpdns=false
method=Automatic
routes=

[ppp]
baud=115200
crtscts=false
lcpechofailure=0
lcpechointerval=0
mppestateful=false
mru=16384
mtu=16384
noauth=false
nobsdcomp=false
nodeflate=true
novjcomp=true
refusechap=false
refuseeap=false
refusemschap=false
refusemschapv2=false
refusepap=false
requiremppe=false
requiremppe128=false

[serial]
baud=115200
bits=8
parity=0
senddelay=12
stopbits=1

Reboot the system and connect. For SprintPCS, in the USA, this works. I think this should also work for most if not all CDMA connections.

Next stop, make the network management applet create this file correctly from the start!

[Maarten Bezemer (veger)]

Thank you for taking the time to report this bug and helping to make Ubuntu better. We are sorry that we do not always have the capacity to look at all reported bugs in a timely manner. There have been many changes in Ubuntu since that time you reported the bug and your problem may have been fixed with some of the updates. It would help us a lot if you could test it on a currently supported Ubuntu version. When you test it and it is still an issue, kindly upload the updated logs by running apport-collect 359849 and any other logs that are relevant for this particular issue.