← Back to team overview

desktop-packages team mailing list archive

[Bug 393094] Re: network manager ppp timeout

 

I just read the syslog excerpt above.

I recently had a similar issue in 15.10 and want to share my experience:

The symptoms: Timeouts and connection termination after some minutes. I
used openconnect vpn connector.

After several experiments I figured the source of the problem being that
my home network is a class A network (10.x.x.x) and the destination
network is a class A network (10.x.x.x) as well.

Keep alive packets follow the routing table and in my case, this included the default route pointing to my router.
The router tunnels the vpn connection but does not route it which means that keep alives will not reach the remote endpoint and the connection runs into timeouts.

I found no solution to this issue other than using an alternative
network connection (using mobile modem which issues a different home IP
address 172.16.x.x). Now the connection is stable and keep alives are
forwarded correctly.

Note: Even static routes do not help as the endpoint of the vpn tunnel
has rolling IP addresses.

Why do I believe there is a connection?

see:
Jun 28 11:30:06 xb pppd[3306]: Cannot determine ethernet address for proxy ARP
Jun 28 11:30:06 xb pppd[3306]: local IP address 10.113.214.1    <========================
Jun 28 11:30:06 xb pppd[3306]: remote IP address 10.64.64.64  <========================

I hope this helps

-- 
You received this bug notification because you are a member of Desktop
Packages, which is subscribed to network-manager in Ubuntu.
https://bugs.launchpad.net/bugs/393094

Title:
  network manager ppp timeout

Status in network-manager package in Ubuntu:
  Incomplete

Bug description:
  NetworkManager: <WARN>  pppd_timed_out(): Looks like pppd didn't
  initialize our dbus module

  I cannot connect via usb UMTS modem.

  Here's the log (Machine: MSI X320, Ubuntu 9.04, Linux 2.6.28.13-generic #44-Ubuntu SMP)
  ---------------------------
  Jun 28 11:29:53 xb NetworkManager: <info>  Activation (ttyUSB0) starting connection 'Yesss' 
  Jun 28 11:29:53 xb NetworkManager: <info>  (ttyUSB0): device state change: 3 -> 4 
  Jun 28 11:29:53 xb NetworkManager: <info>  Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled... 
  Jun 28 11:29:53 xb NetworkManager: <info>  Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started... 
  Jun 28 11:29:53 xb NetworkManager: <debug> [1246181393.723285] nm_serial_device_open(): (ttyUSB0) opening device... 
  Jun 28 11:29:53 xb NetworkManager: <info>  Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete. 
  Jun 28 11:29:53 xb NetworkManager: <info>  (ttyUSB0): GSM pin secret required 
  Jun 28 11:29:53 xb NetworkManager: <info>  (ttyUSB0): device state change: 4 -> 6 
  Jun 28 11:29:53 xb NetworkManager: <info>  Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled... 
  Jun 28 11:29:53 xb NetworkManager: <info>  Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started... 
  Jun 28 11:29:53 xb NetworkManager: <info>  (ttyUSB0): device state change: 6 -> 4 
  Jun 28 11:29:53 xb NetworkManager: <debug> [1246181393.929608] nm_serial_device_open(): (ttyUSB0) opening device... 
  Jun 28 11:29:53 xb NetworkManager: <info>  Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete. 
  Jun 28 11:29:54 xb NetworkManager: <info>  (ttyUSB0): powering up... 
  Jun 28 11:29:56 xb NetworkManager: <info>  Searching for a network... 
  Jun 28 11:30:01 xb last message repeated 5 times
  Jun 28 11:30:02 xb NetworkManager: <info>  Registered on Roaming network 
  Jun 28 11:30:02 xb NetworkManager: <info>  Associated with network: +COPS: 0,2,"23205",2 
  Jun 28 11:30:02 xb NetworkManager: <info>  Connected, Woo! 
  Jun 28 11:30:02 xb NetworkManager: <info>  Activation (ttyUSB0) Stage 2 of 5 (Device Configure) scheduled... 
  Jun 28 11:30:02 xb NetworkManager: <info>  Activation (ttyUSB0) Stage 2 of 5 (Device Configure) starting... 
  Jun 28 11:30:02 xb NetworkManager: <info>  (ttyUSB0): device state change: 4 -> 5 
  Jun 28 11:30:02 xb NetworkManager: <info>  Starting pppd connection 
  Jun 28 11:30:02 xb NetworkManager: <debug> [1246181402.327197] nm_ppp_manager_start(): Command line: /usr/sbin/pppd nodetach lock nodefaultroute ttyUSB0 noipdefault noauth usepeerdns lcp-echo-failure 0 lcp-echo-interval 0 ipparam /org/freedesktop/NetworkManager/PPP/0 plugin /usr/lib/pppd/2.4.4/nm-pppd-plugin.so 
  Jun 28 11:30:02 xb NetworkManager: <debug> [1246181402.347065] nm_ppp_manager_start(): ppp started with pid 3306 
  Jun 28 11:30:02 xb NetworkManager: <info>  Activation (ttyUSB0) Stage 2 of 5 (Device Configure) complete. 
  Jun 28 11:30:02 xb pppd[3306]: Plugin /usr/lib/pppd/2.4.4/nm-pppd-plugin.so loaded.
  Jun 28 11:30:02 xb pppd[3306]: pppd 2.4.5 started by root, uid 0
  Jun 28 11:30:02 xb pppd[3306]: Using interface ppp0
  Jun 28 11:30:02 xb pppd[3306]: Connect: ppp0 <--> /dev/ttyUSB0
  Jun 28 11:30:02 xb pppd[3306]: Unable to obtain CHAP password for xb on UMTS_CHAP_SRVR from plugin
  Jun 28 11:30:02 xb pppd[3306]: No CHAP secret found for authenticating us to UMTS_CHAP_SRVR
  Jun 28 11:30:02 xb pppd[3306]: CHAP authentication succeeded
  Jun 28 11:30:02 xb pppd[3306]: CHAP authentication succeeded
  Jun 28 11:30:02 xb kernel: [  137.660149] PPP BSD Compression module registered
  Jun 28 11:30:02 xb kernel: [  137.717957] PPP Deflate Compression module registered
  Jun 28 11:30:06 xb pppd[3306]: Could not determine remote IP address: defaulting to 10.64.64.64
  Jun 28 11:30:06 xb pppd[3306]: Cannot determine ethernet address for proxy ARP
  Jun 28 11:30:06 xb pppd[3306]: local  IP address 10.113.214.1
  Jun 28 11:30:06 xb pppd[3306]: remote IP address 10.64.64.64
  Jun 28 11:30:06 xb pppd[3306]: primary   DNS address 81.3.216.100
  Jun 28 11:30:06 xb pppd[3306]: secondary DNS address 194.24.128.100
  Jun 28 11:30:18 xb NetworkManager: <WARN>  pppd_timed_out(): Looks like pppd didn't initialize our dbus module 
  Jun 28 11:30:18 xb NetworkManager: <info>  (ttyUSB0): device state change: 5 -> 9 
  Jun 28 11:30:18 xb pppd[3306]: Terminating on signal 15
  Jun 28 11:30:18 xb NetworkManager: <debug> [1246181418.011641] nm_serial_device_close(): Closing device 'ttyUSB0' 
  Jun 28 11:30:18 xb pppd[3306]: Connect time 0.2 minutes.
  Jun 28 11:30:18 xb pppd[3306]: Sent 0 bytes, received 0 bytes.
  Jun 28 11:30:18 xb NetworkManager: <info>  Marking connection 'Yesss' invalid. 
  Jun 28 11:30:18 xb NetworkManager: <info>  Activation (ttyUSB0) failed. 
  Jun 28 11:30:18 xb NetworkManager: <info>  (ttyUSB0): device state change: 9 -> 3 
  Jun 28 11:30:18 xb NetworkManager: <info>  (ttyUSB0): deactivating device (reason: 0). 
  Jun 28 11:30:18 xb NetworkManager: nm_system_device_flush_ip4_routes_with_iface: assertion `iface_idx >= 0' failed
  Jun 28 11:30:18 xb NetworkManager: nm_system_device_flush_ip4_addresses_with_iface: assertion `iface_idx >= 0' failed
  Jun 28 11:30:18 xb pppd[3306]: Connection terminated.
  Jun 28 11:30:18 xb pppd[3306]: Exit.
  Jun 28 11:30:20 xb NetworkManager: <debug> [1246181420.002615] ensure_killed(): waiting for ppp pid 3306 to exit 
  Jun 28 11:30:20 xb NetworkManager: <debug> [1246181420.002969] ensure_killed(): ppp pid 3306 cleaned up 
  ---------------------------

  It works fine on all my other machines.

To manage notifications about this bug go to:
https://bugs.launchpad.net/ubuntu/+source/network-manager/+bug/393094/+subscriptions