Hi Thomas,

Thomas Haller <thal...@redhat.com> writes:

> Hi,
>
> There are very little dependencies of NetworkManager to systemd. Note
> that the src/systemd directory contains a self-contained copy of
> systemd sources. That is, you don't need an systemd dependency for
> those.
>
> Regarding session management, you'd only have to patch
> https://cgit.freedesktop.org/NetworkManager/NetworkManager/tree/src/nm-session-monitor.c?id=fa15543179aa8ca8e082267121e7df4a131bf642
> That seems doable.

That makes sense.  I've tried applying a patch like the one attached to
this email.  Even though NetworkManager builds, and the output in the
build log suggests that it's using systemd (really, elogind) for session
tracking, it still doesn't automatically connect.  There are messages
from NetworkManager in my syslog messages (see attached file
"excerpts-from-logs.txt"), but there appears to be no information about
why NetworkManager is not automatically connecting.

Does the patch and build output look reasonable to you?  Perhaps I
missed something obvious.  What's a good way to debug this?  What code
path is supposed to be executed when NetworkManager automatically
connects?  How can I verify if that code path is being executed?  Even a
simple tip to get me going in the right direction would be very helpful.

Thank you,

-- 
Chris
From eb143fdaf8cd7bcd5edcd26ae36a48170ac48a9e Mon Sep 17 00:00:00 2001
From: Chris Marusich <cmmarus...@gmail.com>
Date: Fri, 23 Dec 2016 00:33:29 -0800
Subject: [PATCH] Attempt to patch NetworkManager to use elogind

When building this in Guix, we pass options like the following to the
./configure script:

    --prefix=/gnu/store/vyhr2m9wriv6fgc05x4wjhc6520wlyq3-network-manager-1.4.2
    --enable-fast-install
    --docdir=/gnu/store/daf9ka1wdcafv86sd0aji1pxgw5kv770-network-manager-1.4.2-doc/share/doc/network-manager
    --build=x86_64-unknown-linux-gnu
    --with-systemd-logind=yes
    --with-consolekit=no
    --with-crypto=gnutls
    --disable-config-plugin-ibft
    --sysconfdir=/etc
    --localstatedir=/var
    --with-udev-dir=/gnu/store/vyhr2m9wriv6fgc05x4wjhc6520wlyq3-network-manager-1.4.2/lib/udev
    --with-dbus-sys-dir=/gnu/store/vyhr2m9wriv6fgc05x4wjhc6520wlyq3-network-manager-1.4.2/etc/dbus-1/system.d
    --with-html-dir=/gnu/store/daf9ka1wdcafv86sd0aji1pxgw5kv770-network-manager-1.4.2-doc/share/gtk-doc/html
    --with-dhclient=/gnu/store/q55gg423fc04rbx2qchvp2rwamw696na-isc-dhcp-4.3.5/sbin/dhclient
---
 NetworkManager-1.4.2/configure                | 72 +++++++++++++--------------
 NetworkManager-1.4.2/src/nm-session-monitor.c |  2 +-
 2 files changed, 37 insertions(+), 37 deletions(-)

diff --git a/NetworkManager-1.4.2/configure b/NetworkManager-1.4.2/configure
index 013461f..b9b6cf8 100755
--- a/NetworkManager-1.4.2/configure
+++ b/NetworkManager-1.4.2/configure
@@ -22903,12 +22903,12 @@ if test -n "$SYSTEMD_LOGIN_CFLAGS"; then
     pkg_cv_SYSTEMD_LOGIN_CFLAGS="$SYSTEMD_LOGIN_CFLAGS"
  elif test -n "$PKG_CONFIG"; then
     if test -n "$PKG_CONFIG" && \
-    { { $as_echo "$as_me:${as_lineno-$LINENO}: \$PKG_CONFIG --exists --print-errors \"libsystemd-login\""; } >&5
-  ($PKG_CONFIG --exists --print-errors "libsystemd-login") 2>&5
+    { { $as_echo "$as_me:${as_lineno-$LINENO}: \$PKG_CONFIG --exists --print-errors \"libelogind\""; } >&5
+  ($PKG_CONFIG --exists --print-errors "libelogind") 2>&5
   ac_status=$?
   $as_echo "$as_me:${as_lineno-$LINENO}: \$? = $ac_status" >&5
   test $ac_status = 0; }; then
-  pkg_cv_SYSTEMD_LOGIN_CFLAGS=`$PKG_CONFIG --cflags "libsystemd-login" 2>/dev/null`
+  pkg_cv_SYSTEMD_LOGIN_CFLAGS=`$PKG_CONFIG --cflags "libelogind" 2>/dev/null`
 		      test "x$?" != "x0" && pkg_failed=yes
 else
   pkg_failed=yes
@@ -22920,12 +22920,12 @@ if test -n "$SYSTEMD_LOGIN_LIBS"; then
     pkg_cv_SYSTEMD_LOGIN_LIBS="$SYSTEMD_LOGIN_LIBS"
  elif test -n "$PKG_CONFIG"; then
     if test -n "$PKG_CONFIG" && \
-    { { $as_echo "$as_me:${as_lineno-$LINENO}: \$PKG_CONFIG --exists --print-errors \"libsystemd-login\""; } >&5
-  ($PKG_CONFIG --exists --print-errors "libsystemd-login") 2>&5
+    { { $as_echo "$as_me:${as_lineno-$LINENO}: \$PKG_CONFIG --exists --print-errors \"libelogind\""; } >&5
+  ($PKG_CONFIG --exists --print-errors "libelogind") 2>&5
   ac_status=$?
   $as_echo "$as_me:${as_lineno-$LINENO}: \$? = $ac_status" >&5
   test $ac_status = 0; }; then
-  pkg_cv_SYSTEMD_LOGIN_LIBS=`$PKG_CONFIG --libs "libsystemd-login" 2>/dev/null`
+  pkg_cv_SYSTEMD_LOGIN_LIBS=`$PKG_CONFIG --libs "libelogind" 2>/dev/null`
 		      test "x$?" != "x0" && pkg_failed=yes
 else
   pkg_failed=yes
@@ -22946,9 +22946,9 @@ else
         _pkg_short_errors_supported=no
 fi
         if test $_pkg_short_errors_supported = yes; then
-	        SYSTEMD_LOGIN_PKG_ERRORS=`$PKG_CONFIG --short-errors --print-errors --cflags --libs "libsystemd-login" 2>&1`
+	        SYSTEMD_LOGIN_PKG_ERRORS=`$PKG_CONFIG --short-errors --print-errors --cflags --libs "libelogind" 2>&1`
         else
-	        SYSTEMD_LOGIN_PKG_ERRORS=`$PKG_CONFIG --print-errors --cflags --libs "libsystemd-login" 2>&1`
+	        SYSTEMD_LOGIN_PKG_ERRORS=`$PKG_CONFIG --print-errors --cflags --libs "libelogind" 2>&1`
         fi
 	# Put the nasty error message in config.log where it belongs
 	echo "$SYSTEMD_LOGIN_PKG_ERRORS" >&5
@@ -22977,12 +22977,12 @@ if test -n "$SYSTEMD_LOGIN_CFLAGS"; then
     pkg_cv_SYSTEMD_LOGIN_CFLAGS="$SYSTEMD_LOGIN_CFLAGS"
  elif test -n "$PKG_CONFIG"; then
     if test -n "$PKG_CONFIG" && \
-    { { $as_echo "$as_me:${as_lineno-$LINENO}: \$PKG_CONFIG --exists --print-errors \"libsystemd-login\""; } >&5
-  ($PKG_CONFIG --exists --print-errors "libsystemd-login") 2>&5
+    { { $as_echo "$as_me:${as_lineno-$LINENO}: \$PKG_CONFIG --exists --print-errors \"libelogind\""; } >&5
+  ($PKG_CONFIG --exists --print-errors "libelogind") 2>&5
   ac_status=$?
   $as_echo "$as_me:${as_lineno-$LINENO}: \$? = $ac_status" >&5
   test $ac_status = 0; }; then
-  pkg_cv_SYSTEMD_LOGIN_CFLAGS=`$PKG_CONFIG --cflags "libsystemd-login" 2>/dev/null`
+  pkg_cv_SYSTEMD_LOGIN_CFLAGS=`$PKG_CONFIG --cflags "libelogind" 2>/dev/null`
 		      test "x$?" != "x0" && pkg_failed=yes
 else
   pkg_failed=yes
@@ -22994,12 +22994,12 @@ if test -n "$SYSTEMD_LOGIN_LIBS"; then
     pkg_cv_SYSTEMD_LOGIN_LIBS="$SYSTEMD_LOGIN_LIBS"
  elif test -n "$PKG_CONFIG"; then
     if test -n "$PKG_CONFIG" && \
-    { { $as_echo "$as_me:${as_lineno-$LINENO}: \$PKG_CONFIG --exists --print-errors \"libsystemd-login\""; } >&5
-  ($PKG_CONFIG --exists --print-errors "libsystemd-login") 2>&5
+    { { $as_echo "$as_me:${as_lineno-$LINENO}: \$PKG_CONFIG --exists --print-errors \"libelogind\""; } >&5
+  ($PKG_CONFIG --exists --print-errors "libelogind") 2>&5
   ac_status=$?
   $as_echo "$as_me:${as_lineno-$LINENO}: \$? = $ac_status" >&5
   test $ac_status = 0; }; then
-  pkg_cv_SYSTEMD_LOGIN_LIBS=`$PKG_CONFIG --libs "libsystemd-login" 2>/dev/null`
+  pkg_cv_SYSTEMD_LOGIN_LIBS=`$PKG_CONFIG --libs "libelogind" 2>/dev/null`
 		      test "x$?" != "x0" && pkg_failed=yes
 else
   pkg_failed=yes
@@ -23020,9 +23020,9 @@ else
         _pkg_short_errors_supported=no
 fi
         if test $_pkg_short_errors_supported = yes; then
-	        SYSTEMD_LOGIN_PKG_ERRORS=`$PKG_CONFIG --short-errors --print-errors --cflags --libs "libsystemd-login" 2>&1`
+	        SYSTEMD_LOGIN_PKG_ERRORS=`$PKG_CONFIG --short-errors --print-errors --cflags --libs "libelogind" 2>&1`
         else
-	        SYSTEMD_LOGIN_PKG_ERRORS=`$PKG_CONFIG --print-errors --cflags --libs "libsystemd-login" 2>&1`
+	        SYSTEMD_LOGIN_PKG_ERRORS=`$PKG_CONFIG --print-errors --cflags --libs "libelogind" 2>&1`
         fi
 	# Put the nasty error message in config.log where it belongs
 	echo "$SYSTEMD_LOGIN_PKG_ERRORS" >&5
@@ -23085,8 +23085,8 @@ if test "z$with_suspend_resume" = "z"; then
   have_systemd_inhibit=yes
 else
   if test -n "$PKG_CONFIG" && \
-    { { $as_echo "$as_me:${as_lineno-$LINENO}: \$PKG_CONFIG --exists --print-errors \"libsystemd-login >= 183\""; } >&5
-  ($PKG_CONFIG --exists --print-errors "libsystemd-login >= 183") 2>&5
+    { { $as_echo "$as_me:${as_lineno-$LINENO}: \$PKG_CONFIG --exists --print-errors \"libelogind >= 183\""; } >&5
+  ($PKG_CONFIG --exists --print-errors "libelogind >= 183") 2>&5
   ac_status=$?
   $as_echo "$as_me:${as_lineno-$LINENO}: \$? = $ac_status" >&5
   test $ac_status = 0; }; then
@@ -23184,12 +23184,12 @@ if test -n "$SYSTEMD_INHIBIT_CFLAGS"; then
     pkg_cv_SYSTEMD_INHIBIT_CFLAGS="$SYSTEMD_INHIBIT_CFLAGS"
  elif test -n "$PKG_CONFIG"; then
     if test -n "$PKG_CONFIG" && \
-    { { $as_echo "$as_me:${as_lineno-$LINENO}: \$PKG_CONFIG --exists --print-errors \"libsystemd-login >= 183\""; } >&5
-  ($PKG_CONFIG --exists --print-errors "libsystemd-login >= 183") 2>&5
+    { { $as_echo "$as_me:${as_lineno-$LINENO}: \$PKG_CONFIG --exists --print-errors \"libelogind >= 183\""; } >&5
+  ($PKG_CONFIG --exists --print-errors "libelogind >= 183") 2>&5
   ac_status=$?
   $as_echo "$as_me:${as_lineno-$LINENO}: \$? = $ac_status" >&5
   test $ac_status = 0; }; then
-  pkg_cv_SYSTEMD_INHIBIT_CFLAGS=`$PKG_CONFIG --cflags "libsystemd-login >= 183" 2>/dev/null`
+  pkg_cv_SYSTEMD_INHIBIT_CFLAGS=`$PKG_CONFIG --cflags "libelogind >= 183" 2>/dev/null`
 		      test "x$?" != "x0" && pkg_failed=yes
 else
   pkg_failed=yes
@@ -23201,12 +23201,12 @@ if test -n "$SYSTEMD_INHIBIT_LIBS"; then
     pkg_cv_SYSTEMD_INHIBIT_LIBS="$SYSTEMD_INHIBIT_LIBS"
  elif test -n "$PKG_CONFIG"; then
     if test -n "$PKG_CONFIG" && \
-    { { $as_echo "$as_me:${as_lineno-$LINENO}: \$PKG_CONFIG --exists --print-errors \"libsystemd-login >= 183\""; } >&5
-  ($PKG_CONFIG --exists --print-errors "libsystemd-login >= 183") 2>&5
+    { { $as_echo "$as_me:${as_lineno-$LINENO}: \$PKG_CONFIG --exists --print-errors \"libelogind >= 183\""; } >&5
+  ($PKG_CONFIG --exists --print-errors "libelogind >= 183") 2>&5
   ac_status=$?
   $as_echo "$as_me:${as_lineno-$LINENO}: \$? = $ac_status" >&5
   test $ac_status = 0; }; then
-  pkg_cv_SYSTEMD_INHIBIT_LIBS=`$PKG_CONFIG --libs "libsystemd-login >= 183" 2>/dev/null`
+  pkg_cv_SYSTEMD_INHIBIT_LIBS=`$PKG_CONFIG --libs "libelogind >= 183" 2>/dev/null`
 		      test "x$?" != "x0" && pkg_failed=yes
 else
   pkg_failed=yes
@@ -23227,14 +23227,14 @@ else
         _pkg_short_errors_supported=no
 fi
         if test $_pkg_short_errors_supported = yes; then
-	        SYSTEMD_INHIBIT_PKG_ERRORS=`$PKG_CONFIG --short-errors --print-errors --cflags --libs "libsystemd-login >= 183" 2>&1`
+	        SYSTEMD_INHIBIT_PKG_ERRORS=`$PKG_CONFIG --short-errors --print-errors --cflags --libs "libelogind >= 183" 2>&1`
         else
-	        SYSTEMD_INHIBIT_PKG_ERRORS=`$PKG_CONFIG --print-errors --cflags --libs "libsystemd-login >= 183" 2>&1`
+	        SYSTEMD_INHIBIT_PKG_ERRORS=`$PKG_CONFIG --print-errors --cflags --libs "libelogind >= 183" 2>&1`
         fi
 	# Put the nasty error message in config.log where it belongs
 	echo "$SYSTEMD_INHIBIT_PKG_ERRORS" >&5
 
-	as_fn_error $? "Package requirements (libsystemd-login >= 183) were not met:
+	as_fn_error $? "Package requirements (libelogind >= 183) were not met:
 
 $SYSTEMD_INHIBIT_PKG_ERRORS
 
@@ -23278,12 +23278,12 @@ if test -n "$SYSTEMD_INHIBIT_CFLAGS"; then
     pkg_cv_SYSTEMD_INHIBIT_CFLAGS="$SYSTEMD_INHIBIT_CFLAGS"
  elif test -n "$PKG_CONFIG"; then
     if test -n "$PKG_CONFIG" && \
-    { { $as_echo "$as_me:${as_lineno-$LINENO}: \$PKG_CONFIG --exists --print-errors \"libsystemd-login >= 183\""; } >&5
-  ($PKG_CONFIG --exists --print-errors "libsystemd-login >= 183") 2>&5
+    { { $as_echo "$as_me:${as_lineno-$LINENO}: \$PKG_CONFIG --exists --print-errors \"libelogind >= 183\""; } >&5
+  ($PKG_CONFIG --exists --print-errors "libelogind >= 183") 2>&5
   ac_status=$?
   $as_echo "$as_me:${as_lineno-$LINENO}: \$? = $ac_status" >&5
   test $ac_status = 0; }; then
-  pkg_cv_SYSTEMD_INHIBIT_CFLAGS=`$PKG_CONFIG --cflags "libsystemd-login >= 183" 2>/dev/null`
+  pkg_cv_SYSTEMD_INHIBIT_CFLAGS=`$PKG_CONFIG --cflags "libelogind >= 183" 2>/dev/null`
 		      test "x$?" != "x0" && pkg_failed=yes
 else
   pkg_failed=yes
@@ -23295,12 +23295,12 @@ if test -n "$SYSTEMD_INHIBIT_LIBS"; then
     pkg_cv_SYSTEMD_INHIBIT_LIBS="$SYSTEMD_INHIBIT_LIBS"
  elif test -n "$PKG_CONFIG"; then
     if test -n "$PKG_CONFIG" && \
-    { { $as_echo "$as_me:${as_lineno-$LINENO}: \$PKG_CONFIG --exists --print-errors \"libsystemd-login >= 183\""; } >&5
-  ($PKG_CONFIG --exists --print-errors "libsystemd-login >= 183") 2>&5
+    { { $as_echo "$as_me:${as_lineno-$LINENO}: \$PKG_CONFIG --exists --print-errors \"libelogind >= 183\""; } >&5
+  ($PKG_CONFIG --exists --print-errors "libelogind >= 183") 2>&5
   ac_status=$?
   $as_echo "$as_me:${as_lineno-$LINENO}: \$? = $ac_status" >&5
   test $ac_status = 0; }; then
-  pkg_cv_SYSTEMD_INHIBIT_LIBS=`$PKG_CONFIG --libs "libsystemd-login >= 183" 2>/dev/null`
+  pkg_cv_SYSTEMD_INHIBIT_LIBS=`$PKG_CONFIG --libs "libelogind >= 183" 2>/dev/null`
 		      test "x$?" != "x0" && pkg_failed=yes
 else
   pkg_failed=yes
@@ -23321,14 +23321,14 @@ else
         _pkg_short_errors_supported=no
 fi
         if test $_pkg_short_errors_supported = yes; then
-	        SYSTEMD_INHIBIT_PKG_ERRORS=`$PKG_CONFIG --short-errors --print-errors --cflags --libs "libsystemd-login >= 183" 2>&1`
+	        SYSTEMD_INHIBIT_PKG_ERRORS=`$PKG_CONFIG --short-errors --print-errors --cflags --libs "libelogind >= 183" 2>&1`
         else
-	        SYSTEMD_INHIBIT_PKG_ERRORS=`$PKG_CONFIG --print-errors --cflags --libs "libsystemd-login >= 183" 2>&1`
+	        SYSTEMD_INHIBIT_PKG_ERRORS=`$PKG_CONFIG --print-errors --cflags --libs "libelogind >= 183" 2>&1`
         fi
 	# Put the nasty error message in config.log where it belongs
 	echo "$SYSTEMD_INHIBIT_PKG_ERRORS" >&5
 
-	as_fn_error $? "Package requirements (libsystemd-login >= 183) were not met:
+	as_fn_error $? "Package requirements (libelogind >= 183) were not met:
 
 $SYSTEMD_INHIBIT_PKG_ERRORS
 
diff --git a/NetworkManager-1.4.2/src/nm-session-monitor.c b/NetworkManager-1.4.2/src/nm-session-monitor.c
index 279a888..f4c647a 100644
--- a/NetworkManager-1.4.2/src/nm-session-monitor.c
+++ b/NetworkManager-1.4.2/src/nm-session-monitor.c
@@ -30,7 +30,7 @@
 #include "NetworkManagerUtils.h"
 
 #ifdef SESSION_TRACKING_SYSTEMD
-#include <systemd/sd-login.h>
+#include <elogind/sd-login.h>
 #endif
 
 /********************************************************************/
-- 
2.11.0

Attachment: build-log.bz2
Description: Binary data

I boot the system:

Dec 23 01:27:32 localhost vmunix: [    3.867185] ath9k 0000:02:00.0 wlp2s0: 
renamed from wlan0
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.3233] 
NetworkManager (version 1.4.2) is starting...
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.3234] Read 
config: /etc/NetworkManager/NetworkManager.conf
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.3321] 
manager[0xfc5140]: monitoring kernel firmware directory '/lib/firmware'.
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.3368] 
dns-mgr[0xfbe830]: init: dns=default, rc-manager=symlink
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.3416] 
rfkill1: found WiFi radio killswitch (at 
/sys/devices/pci0000:00/0000:00:1c.1/0000:02:00.0/ieee80211/phy0/rfkill1) 
(driver ath9k)
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.3417] 
manager[0xfc5140]: WiFi hardware radio set enabled
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.3417] 
manager[0xfc5140]: WWAN hardware radio set enabled
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.3935] 
settings: loaded plugin keyfile: (c) 2007 - 2015 Red Hat, Inc.  To report bugs 
please use the NetworkManager mailing list.
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.4199] 
keyfile: new connection /etc/NetworkManager/system-connections/***REDACTED*** 
(82bd459b-781d-4ed6-8b52-66a01eb9fc8d,"***REDACTED***")
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.4349] 
settings: hostname: couldn't get property from hostnamed
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.4359] 
manager: WiFi enabled by radio killswitch; enabled by state file
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.4360] 
manager: WWAN enabled by radio killswitch; enabled by state file
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.4361] 
manager: Networking is enabled by state file
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.4362] Loaded 
device plugin: NMVxlanFactory (internal)
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.4396] Loaded 
device plugin: NMVlanFactory (internal)
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.4396] Loaded 
device plugin: NMVethFactory (internal)
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.4397] Loaded 
device plugin: NMTunFactory (internal)
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.4400] Loaded 
device plugin: NMMacvlanFactory (internal)
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.4401] Loaded 
device plugin: NMIPTunnelFactory (internal)
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.4402] Loaded 
device plugin: NMInfinibandFactory (internal)
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.4403] Loaded 
device plugin: NMEthernetFactory (internal)
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.4403] Loaded 
device plugin: NMBridgeFactory (internal)
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.4404] Loaded 
device plugin: NMBondFactory (internal)
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.4820] Loaded 
device plugin: NMWwanFactory 
(/gnu/store/nfvpwxasbxdr2rxr5rmxdvm4wclngg5v-network-manager-1.4.2/lib/NetworkManager/libnm-device-plugin-wwan.so)
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.4874] Loaded 
device plugin: NMBluezManager 
(/gnu/store/nfvpwxasbxdr2rxr5rmxdvm4wclngg5v-network-manager-1.4.2/lib/NetworkManager/libnm-device-plugin-bluetooth.so)
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.4927] Loaded 
device plugin: NMWifiFactory 
(/gnu/store/nfvpwxasbxdr2rxr5rmxdvm4wclngg5v-network-manager-1.4.2/lib/NetworkManager/libnm-device-plugin-wifi.so)
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.4975] Loaded 
device plugin: NMAtmManager 
(/gnu/store/nfvpwxasbxdr2rxr5rmxdvm4wclngg5v-network-manager-1.4.2/lib/NetworkManager/libnm-device-plugin-adsl.so)
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.4985] device 
(lo): link connected
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.5021] 
manager: (lo): new Generic device (/org/freedesktop/NetworkManager/Devices/0)
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.5027] 
(wlp2s0): using nl80211 for WiFi device control
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.5032] device 
(wlp2s0): driver supports Access Point (AP) mode
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.5043] 
manager: (wlp2s0): new 802.11 WiFi device 
(/org/freedesktop/NetworkManager/Devices/1)
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.5058] device 
(wlp2s0): state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Dec 23 01:27:32 localhost vmunix: [   14.976778] IPv6: ADDRCONF(NETDEV_UP): 
wlp2s0: link is not ready
Dec 23 01:27:32 localhost vmunix: [   14.992114] IPv6: ADDRCONF(NETDEV_UP): 
wlp2s0: link is not ready
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.5342] device 
(wlp2s0): set-hw-addr: set MAC address to ***REDACTED*** (scanning)
Dec 23 01:27:32 localhost vmunix: [   15.018452] IPv6: ADDRCONF(NETDEV_UP): 
wlp2s0: link is not ready
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.5514] 
manager: (enp0s25): new Ethernet device 
(/org/freedesktop/NetworkManager/Devices/2)
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.5528] 
keyfile: add connection in-memory (53bedc62-a9cc-336e-bd1a-ce1e7fdde33d,"Wired 
connection 1")
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.5533] 
settings: (enp0s25): created default wired connection 'Wired connection 1'
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.5547] device 
(enp0s25): state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Dec 23 01:27:32 localhost NetworkManager[342]: <warn>  [1482485252.7843] error 
poking ModemManager: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The 
name org.freedesktop.ModemManager1 was not provided by any .service files
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.7853] 
supplicant: wpa_supplicant running
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.7853] device 
(wlp2s0): supplicant interface state: init -> starting
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.8214] 
sup-iface[0x1001170,wlp2s0]: supports 4 scan SSIDs
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.8233] device 
(wlp2s0): supplicant interface state: starting -> ready
Dec 23 01:27:32 localhost NetworkManager[342]: <info>  [1482485252.8234] device 
(wlp2s0): state change: unavailable -> disconnected (reason 
'supplicant-available') [20 30 42]
Dec 23 01:27:32 localhost vmunix: [   15.294192] IPv6: ADDRCONF(NETDEV_UP): 
wlp2s0: link is not ready
Dec 23 01:27:33 localhost NetworkManager[342]: <info>  [1482485253.7940] device 
(wlp2s0): supplicant interface state: ready -> inactive
Dec 23 01:27:37 localhost NetworkManager[342]: <info>  [1482485257.5316] 
manager: startup complete

By this time, I've logged into GNOME.  I manually un-check the
"Connect automatically" box and click "Apply":

Dec 23 01:29:57 localhost NetworkManager[342]: <info>  [1482485397.6340] 
keyfile: update /etc/NetworkManager/system-connections/***REDACTED*** 
(82bd459b-781d-4ed6-8b52-66a01eb9fc8d,"***REDACTED***")
Dec 23 01:29:57 localhost NetworkManager[342]: <info>  [1482485397.6347] audit: 
op="connection-update" uuid="82bd459b-781d-4ed6-8b52-66a01eb9fc8d" 
name="***REDACTED***" args="connection.autoconnect" pid=629 uid=1000 
result="success"

I manually check the "Connect automatically" box and click "Apply":

Dec 23 01:30:21 localhost NetworkManager[342]: <info>  [1482485421.8215] 
keyfile: update /etc/NetworkManager/system-connections/***REDACTED*** 
(82bd459b-781d-4ed6-8b52-66a01eb9fc8d,"***REDACTED***")
Dec 23 01:30:21 localhost NetworkManager[342]: <info>  [1482485421.8222] audit: 
op="connection-update" uuid="82bd459b-781d-4ed6-8b52-66a01eb9fc8d" 
name="***REDACTED***" args="connection.autoconnect" pid=629 uid=1000 
result="success"

After noting that NetworkManager does not automatically connect, I
manually click on the connection to manually connect to the network:

Dec 23 01:30:45 localhost NetworkManager[342]: <info>  [1482485445.6109] device 
(wlp2s0): Activation: starting connection '***REDACTED***' 
(82bd459b-781d-4ed6-8b52-66a01eb9fc8d)
Dec 23 01:30:45 localhost NetworkManager[342]: <info>  [1482485445.6110] audit: 
op="connection-activate" uuid="82bd459b-781d-4ed6-8b52-66a01eb9fc8d" 
name="***REDACTED***" pid=807 uid=1000 result="success"
Dec 23 01:30:45 localhost NetworkManager[342]: <info>  [1482485445.6111] device 
(wlp2s0): state change: disconnected -> prepare (reason 'none') [30 40 0]
Dec 23 01:30:45 localhost NetworkManager[342]: <info>  [1482485445.6113] 
manager: NetworkManager state is now CONNECTING
Dec 23 01:30:45 localhost NetworkManager[342]: <info>  [1482485445.6221] device 
(wlp2s0): set-hw-addr: set-cloned MAC address to ***REDACTED*** (permanent)
Dec 23 01:30:45 localhost vmunix: [  208.106562] IPv6: ADDRCONF(NETDEV_UP): 
wlp2s0: link is not ready
Dec 23 01:30:45 localhost NetworkManager[342]: <info>  [1482485445.6433] device 
(wlp2s0): supplicant interface state: inactive -> disabled
Dec 23 01:30:45 localhost NetworkManager[342]: <info>  [1482485445.6439] device 
(wlp2s0): state change: prepare -> config (reason 'none') [40 50 0]
Dec 23 01:30:45 localhost NetworkManager[342]: <info>  [1482485445.6442] device 
(wlp2s0): Activation: (wifi) access point '***REDACTED***' has security, but 
secrets are required.
Dec 23 01:30:45 localhost NetworkManager[342]: <info>  [1482485445.6443] device 
(wlp2s0): state change: config -> need-auth (reason 'none') [50 60 0]
Dec 23 01:30:45 localhost NetworkManager[342]: <info>  [1482485445.6839] device 
(wlp2s0): supplicant interface state: disabled -> scanning
Dec 23 01:30:45 localhost NetworkManager[342]: <info>  [1482485445.6868] device 
(wlp2s0): state change: need-auth -> prepare (reason 'none') [60 40 0]
Dec 23 01:30:45 localhost NetworkManager[342]: <info>  [1482485445.6899] device 
(wlp2s0): state change: prepare -> config (reason 'none') [40 50 0]
Dec 23 01:30:45 localhost NetworkManager[342]: <info>  [1482485445.6902] device 
(wlp2s0): Activation: (wifi) connection '***REDACTED***' has security, and 
secrets exist.  No new secrets needed.
Dec 23 01:30:45 localhost NetworkManager[342]: <info>  [1482485445.6903] 
Config: added 'ssid' value '***REDACTED***'
Dec 23 01:30:45 localhost NetworkManager[342]: <info>  [1482485445.6904] 
Config: added 'scan_ssid' value '***REDACTED***'
Dec 23 01:30:45 localhost NetworkManager[342]: <info>  [1482485445.6905] 
Config: added 'key_mgmt' value '***REDACTED***'
Dec 23 01:30:45 localhost NetworkManager[342]: <info>  [1482485445.6905] 
Config: added 'auth_alg' value '***REDACTED***'
Dec 23 01:30:45 localhost NetworkManager[342]: <info>  [1482485445.6906] 
Config: added 'wep_key0' value '***REDACTED***'
Dec 23 01:30:45 localhost NetworkManager[342]: <info>  [1482485445.6906] 
Config: added 'wep_tx_keyidx' value '***REDACTED***'
Dec 23 01:30:45 localhost NetworkManager[342]: <info>  [1482485445.7008] 
sup-iface[0x1001170,wlp2s0]: config: set interface ap_scan to 1
Dec 23 01:30:46 localhost vmunix: [  209.073848] wlp2s0: authenticate with 
***REDACTED***
Dec 23 01:30:46 localhost vmunix: [  209.097821] wlp2s0: send auth to 
***REDACTED*** (try 1/3)
Dec 23 01:30:46 localhost NetworkManager[342]: <info>  [1482485446.6281] device 
(wlp2s0): supplicant interface state: scanning -> authenticating
Dec 23 01:30:46 localhost vmunix: [  209.099762] wlp2s0: authenticated
Dec 23 01:30:46 localhost vmunix: [  209.104056] wlp2s0: associate with 
***REDACTED*** (try 1/3)
Dec 23 01:30:46 localhost vmunix: [  209.106490] wlp2s0: RX AssocResp from 
***REDACTED*** (capab=0x411 status=0 aid=4)
Dec 23 01:30:46 localhost vmunix: [  209.106633] wlp2s0: associated
Dec 23 01:30:46 localhost vmunix: [  209.106681] IPv6: ADDRCONF(NETDEV_CHANGE): 
wlp2s0: link becomes ready
Dec 23 01:30:46 localhost NetworkManager[342]: <info>  [1482485446.6588] device 
(wlp2s0): supplicant interface state: authenticating -> associating
Dec 23 01:30:46 localhost NetworkManager[342]: <info>  [1482485446.6778] device 
(wlp2s0): supplicant interface state: associating -> completed
Dec 23 01:30:46 localhost NetworkManager[342]: <info>  [1482485446.6779] device 
(wlp2s0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful.  
Connected to wireless network '***REDACTED***'.
Dec 23 01:30:46 localhost NetworkManager[342]: <info>  [1482485446.6784] device 
(wlp2s0): state change: config -> ip-config (reason 'none') [50 70 0]
Dec 23 01:30:46 localhost NetworkManager[342]: <info>  [1482485446.6815] 
dhcp-init: Using DHCP client 'dhclient'
Dec 23 01:30:46 localhost NetworkManager[342]: <info>  [1482485446.6820] dhcp4 
(wlp2s0): activation: beginning transaction (timeout in 45 seconds)
Dec 23 01:30:46 localhost NetworkManager[342]: <info>  [1482485446.6853] dhcp4 
(wlp2s0): dhclient started with pid 827
Dec 23 01:30:46 localhost dhclient: DHCPDISCOVER on wlp2s0 to 255.255.255.255 
port 67 interval 4
Dec 23 01:30:47 localhost avahi-daemon[348]: Joining mDNS multicast group on 
interface wlp2s0.IPv6 with address ***REDACTED***.
Dec 23 01:30:47 localhost avahi-daemon[348]: New relevant interface wlp2s0.IPv6 
for mDNS.
Dec 23 01:30:47 localhost avahi-daemon[348]: Registering new address record for 
***REDACTED*** on wlp2s0.*.
Dec 23 01:30:47 localhost dhclient: DHCPREQUEST on wlp2s0 to 255.255.255.255 
port 67
Dec 23 01:30:47 localhost NetworkManager[342]: <info>  [1482485447.9858] dhcp4 
(wlp2s0):   address ***REDACTED***
Dec 23 01:30:47 localhost NetworkManager[342]: <info>  [1482485447.9858] dhcp4 
(wlp2s0):   plen ***REDACTED***
Dec 23 01:30:47 localhost NetworkManager[342]: <info>  [1482485447.9858] dhcp4 
(wlp2s0):   gateway ***REDACTED***
Dec 23 01:30:47 localhost NetworkManager[342]: <info>  [1482485447.9858] dhcp4 
(wlp2s0):   server identifier ***REDACTED***
Dec 23 01:30:47 localhost NetworkManager[342]: <info>  [1482485447.9859] dhcp4 
(wlp2s0):   lease time 86400
Dec 23 01:30:47 localhost NetworkManager[342]: <info>  [1482485447.9859] dhcp4 
(wlp2s0):   nameserver '***REDACTED***'
Dec 23 01:30:47 localhost NetworkManager[342]: <info>  [1482485447.9859] dhcp4 
(wlp2s0):   nameserver '***REDACTED***'
Dec 23 01:30:47 localhost NetworkManager[342]: <info>  [1482485447.9859] dhcp4 
(wlp2s0):   nameserver ***REDACTED***
Dec 23 01:30:47 localhost NetworkManager[342]: <info>  [1482485447.9859] dhcp4 
(wlp2s0):   domain name '***REDACTED***'
Dec 23 01:30:47 localhost NetworkManager[342]: <info>  [1482485447.9859] dhcp4 
(wlp2s0): state changed unknown -> bound
Dec 23 01:30:47 localhost avahi-daemon[348]: Joining mDNS multicast group on 
interface wlp2s0.IPv4 with address ***REDACTED***.
Dec 23 01:30:47 localhost avahi-daemon[348]: New relevant interface wlp2s0.IPv4 
for mDNS.
Dec 23 01:30:47 localhost avahi-daemon[348]: Registering new address record for 
***REDACTED*** on wlp2s0.IPv4.
Dec 23 01:30:47 localhost NetworkManager[342]: <info>  [1482485447.9878] device 
(wlp2s0): state change: ip-config -> ip-check (reason 'none') [70 80 0]
Dec 23 01:30:47 localhost NetworkManager[342]: <info>  [1482485447.9883] device 
(wlp2s0): state change: ip-check -> secondaries (reason 'none') [80 90 0]
Dec 23 01:30:47 localhost NetworkManager[342]: <info>  [1482485447.9886] device 
(wlp2s0): state change: secondaries -> activated (reason 'none') [90 100 0]
Dec 23 01:30:47 localhost NetworkManager[342]: <info>  [1482485447.9887] 
manager: NetworkManager state is now CONNECTED_LOCAL
Dec 23 01:30:47 localhost NetworkManager[342]: <info>  [1482485448.0012] 
manager: NetworkManager state is now CONNECTED_GLOBAL
Dec 23 01:30:47 localhost NetworkManager[342]: <info>  [1482485448.0013] 
policy: set '***REDACTED***' (wlp2s0) as default for IPv4 routing and DNS
Dec 23 01:30:48 localhost NetworkManager[342]: <info>  [1482485448.0038] device 
(wlp2s0): Activation: successful, device activated.
Dec 23 01:30:48 localhost NetworkManager[342]: <info>  [1482485448.7058] dhcp6 
(wlp2s0): activation: beginning transaction (timeout in 45 seconds)
Dec 23 01:30:48 localhost NetworkManager[342]: <info>  [1482485448.7076] dhcp6 
(wlp2s0): dhclient started with pid 839
Dec 23 01:30:48 localhost NetworkManager[342]: <info>  [1482485448.7096] 
policy: set '***REDACTED***' (wlp2s0) as default for IPv6 routing and DNS
Dec 23 01:30:48 localhost dhclient: XMT: Info-Request on wlp2s0, interval 
1080ms.
Dec 23 01:30:50 localhost dhclient: XMT: Info-Request on wlp2s0, interval 
2220ms.
Dec 23 01:30:50 localhost avahi-daemon[348]: Leaving mDNS multicast group on 
interface wlp2s0.IPv6 with address ***REDACTED***.
Dec 23 01:30:50 localhost avahi-daemon[348]: Joining mDNS multicast group on 
interface wlp2s0.IPv6 with address ***REDACTED***.
Dec 23 01:30:50 localhost avahi-daemon[348]: Registering new address record for 
***REDACTED*** on wlp2s0.*.
Dec 23 01:30:50 localhost avahi-daemon[348]: Withdrawing address record for 
***REDACTED*** on wlp2s0.
Dec 23 01:30:50 localhost avahi-daemon[348]: Registering new address record for 
***REDACTED*** on wlp2s0.*.
Dec 23 01:30:52 localhost dhclient: XMT: Info-Request on wlp2s0, interval 
4480ms.
Dec 23 01:30:52 localhost ntpd[343]: Listen normally on 4 wlp2s0 ***REDACTED***
Dec 23 01:30:52 localhost ntpd[343]: Listen normally on 5 wlp2s0 ***REDACTED***
Dec 23 01:30:52 localhost ntpd[343]: Listen normally on 6 wlp2s0 ***REDACTED***
Dec 23 01:30:52 localhost ntpd[343]: Listen normally on 7 wlp2s0 ***REDACTED***
Dec 23 01:30:56 localhost dhclient: XMT: Info-Request on wlp2s0, interval 
9350ms.
Dec 23 01:31:06 localhost dhclient: XMT: Info-Request on wlp2s0, interval 
18880ms.
Dec 23 01:31:24 localhost dhclient: XMT: Info-Request on wlp2s0, interval 
36840ms.
Dec 23 01:31:33 localhost NetworkManager[342]: <warn>  [1482485493.5364] dhcp6 
(wlp2s0): request timed out
Dec 23 01:31:33 localhost NetworkManager[342]: <info>  [1482485493.5365] dhcp6 
(wlp2s0): state changed unknown -> timeout
Dec 23 01:31:33 localhost NetworkManager[342]: <info>  [1482485493.5373] dhcp6 
(wlp2s0): canceled DHCP transaction, DHCP client pid 839
Dec 23 01:31:33 localhost NetworkManager[342]: <info>  [1482485493.5373] dhcp6 
(wlp2s0): state changed timeout -> done

I am now connected, but I had to do it manually.  No information in
these logs seems to shed light on why NetworkManager did not
automatically connect.

Attachment: signature.asc
Description: PGP signature

Reply via email to