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
> [email protected]
> https://mail.gnome.org/mailman/listinfo/networkmanager-list
_______________________________________________
networkmanager-list mailing list
[email protected]
https://mail.gnome.org/mailman/listinfo/networkmanager-list

Reply via email to