Hi,
I have found the following problem with an Ericsson F5521gw 3G modem:
1. start system
2. bring up 3g connection by clicking on the configureation in nm-applet
3. wait until connection is established and can be used
4. disconnect from 3g connection
5. when trying to bring up the connection again it fails
A workaround is to deaktivate mobile broadband wait and activate mobile broadband again.
I have captured some debug for this situation.
Can someone help please?
Our systems are Ubuntu Lucid with newer packages for modem-manager, network-manager and network-manager-gnome:
ii modemmanager 0.4+git.20101103t150351.a2d54b1-lvm1 D-Bus service for managing modems
ii network-manager 0.8.4-0ubuntu0~lvm0 network management framework daemon
ii network-manager-gnome 0.8.4-0ubuntu0~lvm0 network management framework (GNOME frontend)
Jul 25 11:47:07 c457826 ntpd[5018]: ntp_io: estimated max descriptors: 1024, initial socket boundary: 16
Jul 25 11:47:07 c457826 ntpd[5018]: Listening on interface #0 wildcard, 0.0.0.0#123 Disabled
Jul 25 11:47:07 c457826 ntpd[5018]: Listening on interface #1 wildcard, ::#123 Disabled
Jul 25 11:47:07 c457826 ntpd[5018]: Listening on interface #2 eth0, fe80::f2de:f1ff:fe71:c33f#123 Enabled
Jul 25 11:47:07 c457826 ntpd[5018]: Listening on interface #3 lo, ::1#123 Enabled
Jul 25 11:47:07 c457826 ntpd[5018]: Listening on interface #4 lo, 127.0.0.1#123 Enabled
Jul 25 11:47:07 c457826 ntpd[5018]: Listening on interface #5 eth0, 10.180.24.92#123 Enabled
Jul 25 11:47:07 c457826 ntpd[5018]: Listening on interface #6 ppp0, 10.33.145.106#123 Enabled
Jul 25 11:47:07 c457826 ntpd[5018]: kernel time sync status 2040
Jul 25 11:47:07 c457826 ntpd[5018]: frequency initialized -6.546 PPM from /var/lib/ntp/ntp.drift
Jul 25 11:47:45 c457826 NetworkManager[1016]: <info> (ttyACM0): device state change: 8 -> 3 (reason 39)
Jul 25 11:47:45 c457826 NetworkManager[1016]: <info> (ttyACM0): deactivating device (reason: 39).
Jul 25 11:47:45 c457826 pppd[4620]: Terminating on signal 15
Jul 25 11:47:45 c457826 NetworkManager[1016]: <debug> [1311587265.813742] [nm-system.c:1349] flush_routes(): (ttyACM0) failed to lookup interface index
Jul 25 11:47:45 c457826 pppd[4620]: Connect time 0.8 minutes.
Jul 25 11:47:45 c457826 pppd[4620]: Sent 0 bytes, received 0 bytes.
Jul 25 11:47:45 c457826 NetworkManager[1016]: <info> (ttyACM0): writing resolv.conf to /sbin/resolvconf
Jul 25 11:47:45 c457826 pppd[4620]: Connection terminated.
Jul 25 11:47:46 c457826 NetworkManager[1016]: <info> Policy set 'Auto eth0' (eth0) as default for IPv4 routing and DNS.
Jul 25 11:47:46 c457826 NetworkManager[1016]: <info> Policy set 'Auto eth0' (eth0) as default for IPv4 routing and DNS.
Jul 25 11:47:46 c457826 NetworkManager[1016]: <debug> [1311587266.24733] [nm-netlink-monitor.c:117] link_msg_handler(): netlink link message: iface idx 6 flags 0x1090
Jul 25 11:47:46 c457826 NetworkManager[1016]: SCPlugin-Ifupdown: devices removed (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Jul 25 11:47:46 c457826 NetworkManager[1016]: <debug> [1311587266.26124] [nm-udev-manager.c:497] handle_uevent(): UDEV event: action 'remove' subsys 'net' device 'ppp0'
Jul 25 11:47:46 c457826 NetworkManager[1016]: <debug> [1311587266.27192] [nm-netlink-monitor.c:117] link_msg_handler(): netlink link message: iface idx 6 flags 0x1090
Jul 25 11:47:46 c457826 lvm-printing: unable to import /etc/createprinter/lvm-druckerliste.csv
Jul 25 11:47:46 c457826 lvm-printing: successfully accepted printer D347
Jul 25 11:47:46 c457826 lvm-printing: successfully accepted printer D347-C
Jul 25 11:47:46 c457826 lvm-openvpn: ttyACM0 down
Jul 25 11:47:46 c457826 pppd[4620]: Exit.
Jul 25 11:47:47 c457826 NetworkManager[1016]: <debug> [1311587267.180913] [nm-netlink-monitor.c:117] link_msg_handler(): netlink link message: iface idx 4 flags 0x1003
Jul 25 11:47:48 c457826 NetworkManager[1016]: <debug> [1311587268.570] [nm-ppp-manager.c:1034] ensure_killed(): waiting for pppd pid 4620 to exit
Jul 25 11:47:48 c457826 NetworkManager[1016]: <debug> [1311587268.823] [nm-ppp-manager.c:1036] ensure_killed(): pppd pid 4620 cleaned up
Jul 25 11:47:54 c457826 NetworkManager[1016]: <info> Activation (ttyACM0) starting connection 'LVM-Mobil'
Jul 25 11:47:54 c457826 NetworkManager[1016]: <info> (ttyACM0): device state change: 3 -> 4 (reason 0)
Jul 25 11:47:54 c457826 NetworkManager[1016]: <info> Activation (ttyACM0) Stage 1 of 5 (Device Prepare) scheduled...
Jul 25 11:47:54 c457826 NetworkManager[1016]: <info> Activation (ttyACM0) Stage 1 of 5 (Device Prepare) started...
Jul 25 11:47:54 c457826 NetworkManager[1016]: <info> (ttyACM0): device state change: 4 -> 6 (reason 0)
Jul 25 11:47:54 c457826 NetworkManager[1016]: <info> Activation (ttyACM0) Stage 1 of 5 (Device Prepare) complete.
Jul 25 11:47:54 c457826 NetworkManager[1016]: <info> Activation (ttyACM0) Stage 1 of 5 (Device Prepare) scheduled...
Jul 25 11:47:54 c457826 NetworkManager[1016]: <info> Activation (ttyACM0) Stage 1 of 5 (Device Prepare) started...
Jul 25 11:47:54 c457826 NetworkManager[1016]: <info> (ttyACM0): device state change: 6 -> 4 (reason 0)
Jul 25 11:47:54 c457826 NetworkManager[1016]: <info> Activation (ttyACM0) Stage 1 of 5 (Device Prepare) complete.
Jul 25 11:47:54 c457826 NetworkManager[1016]: <warn> GSM connection failed: (32) Unknown registration status response
Jul 25 11:47:54 c457826 NetworkManager[1016]: <info> (ttyACM0): device state change: 4 -> 9 (reason 1)
Jul 25 11:47:54 c457826 NetworkManager[1016]: <info> Marking connection 'LVM-Mobil' invalid.
Jul 25 11:47:54 c457826 NetworkManager[1016]: <warn> Activation (ttyACM0) failed.
Jul 25 11:47:54 c457826 NetworkManager[1016]: <debug> [1311587274.339515] [nm-device.c:3669] failed_to_disconnected(): (ttyACM0): running failed->disconnected transition
Jul 25 11:47:54 c457826 NetworkManager[1016]: <info> (ttyACM0): device state change: 9 -> 3 (reason 0)
Jul 25 11:47:54 c457826 NetworkManager[1016]: <info> (ttyACM0): deactivating device (reason: 0).
Jul 25 11:47:54 c457826 NetworkManager[1016]: <debug> [1311587274.340179] [nm-system.c:1349] flush_routes(): (ttyACM0) failed to lookup interface index
Jul 25 11:47:54 c457826 NetworkManager[1016]: <info> Policy set 'Auto eth0' (eth0) as default for IPv4 routing and DNS.