Re: Network manager auto-upgraded, ppp no longer connects.



On Wed, 2017-01-04 at 16:37 -0500, A. F. Cano wrote:
Hello everyone,

In the latest automatic software upgrade, I noticed that network-
manager was
upgraded.  I no longer can get ppp started like it did before.

Debian testing:

$ cat /etc/debian_version
stretch/sid

$ apt-cache show network-manager
Package: network-manager
Version: 1.4.4-1
...

If you're able, could you downgrade NetworkManager to make sure the
problem is certainly with the update?  If downgrading starts things
working again, then it's a lot easier to find the regression.

Could you also double-check that ModemManager was or was not updated at
the same time?

Before this latest upgrade (a few days ago) network-manager worked
just
fine with ppp.  The cdma phone got plugged in, the usb system
recognized
it, network manager set up the connection and I used to get a
CONNECTED_GLOBAL message.  Now I only get the CONNECTED_LOCAL one.


I think the relevant log lines are:

Dec 31 13:12:33 fbx NetworkManager[370]: <warn>  [1483207953.9875]
modem-broadband[ttyACM0]: failed to connect modem: Network timeout
...

This error is actually coming from ModemManager, not NetworkManager,
indicating that MM wasn't able to connect to the network within a
certain timeout.  One thing that could help narrow it down is to check
the system log output of ModemManager too.  You can enable verbose
debugging of MM with:

mmcli -G DEBUG

and it'll dump to whatever normal log location your system uses.  For
systemd-based distros, that's "journalctl -b -u ModemManager" and for
other distros that might be /var/log/messages or
/var/log/ModemManager.log depending on your distros syslog setup. 
That'll probably tell us a lot more about what's happening.

Dan

Dec 31 13:12:34 fbx NetworkManager[370]: <warn>  [1483207953.9924]
device (ttyACM0): Activation: failed for connection 'ppp'

The complete log is appended below.

If I start ppp the old-fashined way, via "sudo pon cell" it works
fine, I get
the connection established, local and remote ip addresses set and the
name
servers set.

I even tried putting back all the ppp.* options that I once thought
were
necessary (they weren't) and it didn't make a difference.

The "nmcli con show ppp" is appended at the end.

This line (just <info>) should not be a problem, but who knows.  This
is a
cdma connection, not gsm.

Dec 31 13:12:33 fbx NetworkManager[370]: <info>  [1483207953.9877]
device (ttyACM0): state change: prepare -> failed (reason 'gsm-
registration-timeout') [40 120 32]


Does anyone know what happened?  Any hints as to what to change or
where to
look?  Did something change with this version that needs to be
corrected by
maybe adding some explicit fields?

Thank you and happy new year.

Augustine


This is the complete log:

Dec 31 13:11:33 fbx NetworkManager[370]: <info>  [1483207893.0539]
manager: (ttyACM0): new Broadband device
(/org/freedesktop/NetworkManager/Devices/8)
Dec 31 13:11:33 fbx NetworkManager[370]: <info>  [1483207893.0555]
device (ttyACM0): state change: unmanaged -> unavailable (reason
'managed') [10 20 2]
Dec 31 13:11:33 fbx NetworkManager[370]: <info>  [1483207893.0577]
device (ttyACM0): modem state 'enabling'
Dec 31 13:11:33 fbx NetworkManager[370]: <info>  [1483207893.0582]
modem-broadband[ttyACM0]: failed to retrieve SIM object: No SIM
object available
Dec 31 13:11:33 fbx NetworkManager[370]: <info>  [1483207893.0599]
device (ttyACM0): state change: unavailable -> disconnected (reason
'none') [20 30 0]
Dec 31 13:11:33 fbx NetworkManager[370]: <info>  [1483207893.0618]
policy: auto-activating connection 'ppp'
Dec 31 13:11:33 fbx NetworkManager[370]: <info>  [1483207893.0664]
device (ttyACM0): Activation: starting connection 'ppp' (f2cc6f30-
1726-42c2-b580-3ac8a98a21ec)
Dec 31 13:11:33 fbx NetworkManager[370]: <info>  [1483207893.0670]
device (ttyACM0): state change: disconnected -> prepare (reason
'none') [30 40 0]
Dec 31 13:11:33 fbx NetworkManager[370]: <info>  [1483207893.0675]
manager: NetworkManager state is now CONNECTING
Dec 31 13:11:33 fbx NetworkManager[370]: <info>  [1483207893.0747]
device (ttyACM0): state change: prepare -> need-auth (reason 'none')
[40 60 0]
Dec 31 13:11:33 fbx NetworkManager[370]: <info>  [1483207893.0839]
device (ttyACM0): state change: need-auth -> prepare (reason 'none')
[60 40 0]
Dec 31 13:12:33 fbx NetworkManager[370]: <warn>  [1483207953.9875]
modem-broadband[ttyACM0]: failed to connect modem: Network timeout
Dec 31 13:12:33 fbx NetworkManager[370]: <info>  [1483207953.9877]
device (ttyACM0): state change: prepare -> failed (reason 'gsm-
registration-timeout') [40 120 32]
Dec 31 13:12:33 fbx NetworkManager[370]: <info>  [1483207953.9885]
manager: NetworkManager state is now CONNECTED_LOCAL
Dec 31 13:12:34 fbx NetworkManager[370]: <warn>  [1483207953.9924]
device (ttyACM0): Activation: failed for connection 'ppp'
Dec 31 13:12:34 fbx NetworkManager[370]: <info>  [1483207953.9957]
device (ttyACM0): state change: failed -> disconnected (reason
'none') [120 30 0]
Dec 31 13:12:34 fbx NetworkManager[370]: <info>  [1483207954.0054]
policy: auto-activating connection 'ppp'
Dec 31 13:12:34 fbx NetworkManager[370]: <info>  [1483207954.0135]
device (ttyACM0): Activation: starting connection 'ppp' (f2cc6f30-
1726-42c2-b580-3ac8a98a21ec)
Dec 31 13:12:34 fbx NetworkManager[370]: <info>  [1483207954.0142]
device (ttyACM0): state change: disconnected -> prepare (reason
'none') [30 40 0]
Dec 31 13:12:34 fbx NetworkManager[370]: <info>  [1483207954.0146]
manager: NetworkManager state is now CONNECTING
Dec 31 13:12:34 fbx NetworkManager[370]: <info>  [1483207954.0180]
device (ttyACM0): state change: prepare -> need-auth (reason 'none')
[40 60 0]
Dec 31 13:12:34 fbx NetworkManager[370]: <warn>  [1483207954.0347]
firewall: [0x55f13cf2c8f0,remove:"ttyACM0"]: complete: request failed
(UNKNOWN_INTERFACE: 'ttyACM0' is not in any zone)
Dec 31 13:12:34 fbx NetworkManager[370]: <info>  [1483207954.0353]
device (ttyACM0): state change: need-auth -> prepare (reason 'none')
[60 40 0]

Dec 31 13:13:36 fbx NetworkManager[370]: <warn>  [1483208016.9931]
modem-broadband[ttyACM0]: failed to connect modem: Network timeout
Dec 31 13:13:36 fbx NetworkManager[370]: <info>  [1483208016.9933]
device (ttyACM0): state change: prepare -> failed (reason 'gsm-
registration-timeout') [40 120 32]
Dec 31 13:13:36 fbx NetworkManager[370]: <info>  [1483208016.9941]
manager: NetworkManager state is now CONNECTED_LOCAL
Dec 31 13:13:36 fbx NetworkManager[370]: <warn>  [1483208016.9967]
device (ttyACM0): Activation: failed for connection 'ppp'
Dec 31 13:13:37 fbx NetworkManager[370]: <info>  [1483208017.0010]
device (ttyACM0): state change: failed -> disconnected (reason
'none') [120 30 0]
Dec 31 13:13:37 fbx NetworkManager[370]: <info>  [1483208017.0120]
policy: auto-activating connection 'ppp'
Dec 31 13:13:37 fbx NetworkManager[370]: <info>  [1483208017.0318]
device (ttyACM0): Activation: starting connection 'ppp' (f2cc6f30-
1726-42c2-b580-3ac8a98a21ec)
Dec 31 13:13:37 fbx NetworkManager[370]: <warn>  [1483208017.0323]
firewall: [0x55f13cefd600,remove:"ttyACM0"]: complete: request failed
(UNKNOWN_INTERFACE: 'ttyACM0' is not in any zone)
Dec 31 13:13:37 fbx NetworkManager[370]: <info>  [1483208017.0369]
device (ttyACM0): state change: disconnected -> prepare (reason
'none') [30 40 0]
Dec 31 13:13:37 fbx NetworkManager[370]: <info>  [1483208017.0379]
manager: NetworkManager state is now CONNECTING
Dec 31 13:13:37 fbx NetworkManager[370]: <info>  [1483208017.0436]
device (ttyACM0): state change: prepare -> need-auth (reason 'none')
[40 60 0]
Dec 31 13:13:37 fbx NetworkManager[370]: <info>  [1483208017.0618]
device (ttyACM0): state change: need-auth -> prepare (reason 'none')
[60 40 0]
Dec 31 13:14:39 fbx NetworkManager[370]: <warn>  [1483208079.9893]
modem-broadband[ttyACM0]: failed to connect modem: Network timeout
Dec 31 13:14:39 fbx NetworkManager[370]: <info>  [1483208079.9895]
device (ttyACM0): state change: prepare -> failed (reason 'gsm-
registration-timeout') [40 120 32]
Dec 31 13:14:39 fbx NetworkManager[370]: <info>  [1483208079.9904]
manager: NetworkManager state is now CONNECTED_LOCAL
Dec 31 13:14:39 fbx NetworkManager[370]: <warn>  [1483208079.9928]
device (ttyACM0): Activation: failed for connection 'ppp'
Dec 31 13:14:40 fbx NetworkManager[370]: <info>  [1483208080.0037]
device (ttyACM0): state change: failed -> disconnected (reason
'none') [120 30 0]
Dec 31 13:14:40 fbx NetworkManager[370]: <info>  [1483208080.0120]
policy: auto-activating connection 'ppp'
Dec 31 13:14:40 fbx NetworkManager[370]: <info>  [1483208080.0153]
device (ttyACM0): Activation: starting connection 'ppp' (f2cc6f30-
1726-42c2-b580-3ac8a98a21ec)
Dec 31 13:14:40 fbx NetworkManager[370]: <info>  [1483208080.0158]
device (ttyACM0): state change: disconnected -> prepare (reason
'none') [30 40 0]
Dec 31 13:14:40 fbx NetworkManager[370]: <info>  [1483208080.0163]
manager: NetworkManager state is now CONNECTING
Dec 31 13:14:40 fbx NetworkManager[370]: <info>  [1483208080.0180]
device (ttyACM0): state change: prepare -> need-auth (reason 'none')
[40 60 0]
Dec 31 13:14:40 fbx NetworkManager[370]: <warn>  [1483208080.0339]
firewall: [0x7ff0d00054c0,remove:"ttyACM0"]: complete: request failed
(UNKNOWN_INTERFACE: 'ttyACM0' is not in any zone)
Dec 31 13:14:40 fbx NetworkManager[370]: <info>  [1483208080.0349]
device (ttyACM0): state change: need-auth -> prepare (reason 'none')
[60 40 0]
Dec 31 13:15:42 fbx NetworkManager[370]: <warn>  [1483208142.9909]
modem-broadband[ttyACM0]: failed to connect modem: Network timeout
Dec 31 13:15:42 fbx NetworkManager[370]: <info>  [1483208142.9911]
device (ttyACM0): state change: prepare -> failed (reason 'gsm-
registration-timeout') [40 120 32]
Dec 31 13:15:42 fbx NetworkManager[370]: <info>  [1483208142.9919]
manager: NetworkManager state is now CONNECTED_LOCAL
Dec 31 13:15:42 fbx NetworkManager[370]: <info>  [1483208142.9929]
policy: disabling autoconnect for connection 'ppp'.
Dec 31 13:15:43 fbx NetworkManager[370]: <warn>  [1483208142.9952]
device (ttyACM0): Activation: failed for connection 'ppp'
Dec 31 13:15:43 fbx NetworkManager[370]: <info>  [1483208143.0065]
device (ttyACM0): state change: failed -> disconnected (reason
'none') [120 30 0]
Dec 31 13:15:43 fbx NetworkManager[370]: <warn>  [1483208143.0169]
firewall: [0x55f13cf5c720,remove:"ttyACM0"]: complete: request failed
(UNKNOWN_INTERFACE: 'ttyACM0' is not in any zone)

$ nmcli con show ppp 
connection.id:                          ppp
connection.uuid:                        f2cc6f30-1726-42c2-b580-
3ac8a98a21ec
connection.stable-id:                   --
connection.interface-name:              --
connection.type:                        cdma
connection.autoconnect:                 yes
connection.autoconnect-priority:        0
connection.timestamp:                   1483189433
connection.read-only:                   no
connection.permissions:                 
connection.zone:                        external
connection.master:                      --
connection.slave-type:                  --
connection.autoconnect-slaves:          -1 (default)
connection.secondaries:                 
connection.gateway-ping-timeout:        0
connection.metered:                     unknown
connection.lldp:                        -1 (default)
ipv4.method:                            auto
ipv4.dns:                               
ipv4.dns-search:                        
ipv4.dns-options:                       (default)
ipv4.dns-priority:                      0
ipv4.addresses:                         
ipv4.gateway:                           --
ipv4.routes:                            
ipv4.route-metric:                      -1
ipv4.ignore-auto-routes:                no
ipv4.ignore-auto-dns:                   no
ipv4.dhcp-client-id:                    --
ipv4.dhcp-timeout:                      0
ipv4.dhcp-send-hostname:                yes
ipv4.dhcp-hostname:                     --
ipv4.dhcp-fqdn:                         --
ipv4.never-default:                     no
ipv4.may-fail:                          yes
ipv4.dad-timeout:                       -1 (default)
ipv6.method:                            auto
ipv6.dns:                               
ipv6.dns-search:                        
ipv6.dns-options:                       (default)
ipv6.dns-priority:                      0
ipv6.addresses:                         
ipv6.gateway:                           --
ipv6.routes:                            
ipv6.route-metric:                      -1
ipv6.ignore-auto-routes:                no
ipv6.ignore-auto-dns:                   no
ipv6.never-default:                     no
ipv6.may-fail:                          yes
ipv6.ip6-privacy:                       -1 (unknown)
ipv6.addr-gen-mode:                     stable-privacy
ipv6.dhcp-send-hostname:                yes
ipv6.dhcp-hostname:                     --
ipv6.token:                             --
cdma.number:                            #777
cdma.username:                          qnc
cdma.password:                          <hidden>
cdma.password-flags:                    0 (none)


_______________________________________________
networkmanager-list mailing list
networkmanager-list gnome org
https://mail.gnome.org/mailman/listinfo/networkmanager-list


[Date Prev][Date Next]   [Thread Prev][Thread Next]   [Thread Index] [Date Index] [Author Index]