NetworkManager.service takes 25 secoonds at boot-time - GLib-GObject-CRITICAL after 23 seconds

Machine: Linux 3.16.7-24-desktop x86_64 – AMD A10-5750M Quad-Core APU – Qualcomm Atheros AR9485 Wireless Network Adapter
KDE Desktop – Lenovo Laptop

At boot-time the NetworkManager.service is taking about 25 seconds to complete.
systemd-analyze:
Startup finished in 2.507s (firmware) + 9.038s (loader) + 3.572s (kernel) + 5.693s (initrd) + 29.394s (userspace) = 50.206s

I setup /etc/NetworkManager/NetworkManager.conf as follows:


[main]
plugins=ifcfg-suse,keyfile
no-auto-default=*

[logging]
level=INFO
domains=ALL

[connectivity]


Journal (edited - reduced) then produced the information detailed in the fragment below.

Given the following Network Manager journal entries about 23 seconds after the NetworkManager.service was started at boot-time:**GLib-GObject-WARNING **: invalid (NULL) pointer instance
GLib-GObject-CRITICAL **: g_signal_connect_data: assertion ‘G_TYPE_CHECK_INSTANCE (instance)’ failed
GLib-GIO-CRITICAL : g_dbus_proxy_call_internal: assertion ‘G_IS_DBUS_PROXY (proxy)’ failed

could a possible cause be, that given the KDE environment and Network Manager’s GNOME heritage, a library that’s normally included by the GNOME Desktop has not been included with the KDE environment and therefore is the cause of the rather long Network Manager execution time during the system boot?

And another point – the login noted at the end of the journal fragment was first possible when the Network Manager startup had completed – the KDM Greeter was running and accepting logins but, first prompted at the time-stamp indicated at the end of the journal fragment below. If one attempts to login via the KDM Greeter before the Network Manager startup has completed, the KDE session is not very nice – almost unuseable . . .


.
.
Okt 22 17:16:30 eck005 systemd-journal[384]: Permanent journal is using 72.0M (max allowed 512.0M, trying to leave 4.0G free of 584.3G available → current limit 512.0M).
Okt 22 17:16:30 eck005 systemd-journal[384]: Time spent on flushing to /var is 38.678ms for 1124 entries.
.
.
Okt 22 17:16:31 eck005 smartd[800]: Device: /dev/sda [SAT], found in smartd database: Seagate Laptop SSHD
.
.
Okt 22 17:16:31 eck005 bluetoothd[788]: Bluetooth management interface 1.6 initialized
Okt 22 17:16:31 eck005 polkitd[826]: Started polkitd version 0.113
Okt 22 17:16:31 eck005 dbus[816]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
Okt 22 17:16:31 eck005 jexec[791]: Starting jexec services
Okt 22 17:16:31 eck005 NetworkManager[908]: <info> NetworkManager (version 0.9.10.0) is starting...
Okt 22 17:16:31 eck005 NetworkManager[908]: <info> Read config: /etc/NetworkManager/NetworkManager.conf
Okt 22 17:16:31 eck005 NetworkManager[908]: <info> WEXT support is enabled
Okt 22 17:16:31 eck005 NetworkManager[908]: <info> VPN: loaded org.freedesktop.NetworkManager.openvpn
Okt 22 17:16:31 eck005 NetworkManager[908]: <info> VPN: loaded org.freedesktop.NetworkManager.pptp
Okt 22 17:16:31 eck005 NetworkManager[908]: <info> VPN: loaded org.freedesktop.NetworkManager.vpnc
Okt 22 17:16:31 eck005 NetworkManager[908]: <info> Loaded plugin ifcfg-suse: (C) 2008 Novell, Inc.  To report bugs please use the NetworkManager mailing list.
Okt 22 17:16:31 eck005 NetworkManager[908]: <info> Loaded plugin keyfile: (c) 2007 - 2013 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list.
Okt 22 17:16:31 eck005 display-manager[834]: Starting service kdm..done
Okt 22 17:16:31 eck005 NetworkManager[908]: <info> new connection /etc/NetworkManager/system-connections/BilderRuhe
Okt 22 17:16:31 eck005 kdm[914]: plymouth is running
Okt 22 17:16:31 eck005 kdm[914]: plymouth is active on VT 7, reusing for :0
Okt 22 17:16:31 eck005 kdm[914]: plymouth should quit after server startup
Okt 22 17:16:31 eck005 NetworkManager[908]: <info> new connection /etc/NetworkManager/system-connections/Ethernet
Okt 22 17:16:31 eck005 NetworkManager[908]: <info> new connection /etc/NetworkManager/system-connections/Wohnzimmer
Okt 22 17:16:31 eck005 dbus[816]: [system] Activating via systemd: service name='org.freedesktop.login1' unit='dbus-org.freedesktop.login1.service'
Okt 22 17:16:32 eck005 kdm[914]: Quitting Plymouth with transition
Okt 22 17:16:32 eck005 kdm[914]: Is Plymouth still running? no
Okt 22 17:16:56 eck005 dbus[816]: [system] Failed to activate service 'org.freedesktop.login1': timed out
Okt 22 17:16:56 eck005 NetworkManager[908]: (NetworkManager:908): GLib-GObject-WARNING **: invalid (NULL) pointer instance
Okt 22 17:16:56 eck005 NetworkManager[908]: (NetworkManager:908): GLib-GObject-CRITICAL **: g_signal_connect_data: assertion 'G_TYPE_CHECK_INSTANCE (instance)' failed
Okt 22 17:16:56 eck005 NetworkManager[908]: (NetworkManager:908): GLib-GIO-CRITICAL **: g_dbus_proxy_call_internal: assertion 'G_IS_DBUS_PROXY (proxy)' failed
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> monitoring kernel firmware directory '/lib/firmware'.
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> rfkill1: found WiFi radio killswitch (at /sys/devices/pci0000:00/0000:00:05.0/0000:03:00.0/ieee80211/phy0/rfkill1) (driver ath9k)
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> rfkill2: found WiFi radio killswitch (at /sys/devices/pci0000:00/0000:00:14.3/PNP0C09:00/VPC2004:00/rfkill/rfkill2) (platform driver ideapad_acpi)
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> WiFi hardware radio set enabled
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> WWAN hardware radio set enabled
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> Loaded device plugin: /usr/lib64/NetworkManager/libnm-device-plugin-bluetooth.so
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> Loaded device plugin: /usr/lib64/NetworkManager/libnm-device-plugin-adsl.so
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> Loaded device plugin: /usr/lib64/NetworkManager/libnm-device-plugin-wifi.so
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> Loaded device plugin: /usr/lib64/NetworkManager/libnm-device-plugin-wwan.so
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> WiFi enabled by radio killswitch; enabled by state file
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> WWAN enabled by radio killswitch; enabled by state file
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> WiMAX enabled by radio killswitch; enabled by state file
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> Networking is enabled by state file
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> (lo): link connected
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> (lo): carrier is ON
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> (lo): new Generic device (driver: 'unknown' ifindex: 1)
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> (lo): exported as /org/freedesktop/NetworkManager/Devices/0
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> (enp2s0): carrier is OFF
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> (enp2s0): new Ethernet device (driver: 'alx' ifindex: 2)
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> (enp2s0): exported as /org/freedesktop/NetworkManager/Devices/1
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> (enp2s0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Okt 22 17:16:56 eck005 kernel: alx 0000:02:00.0: irq 49 for MSI/MSI-X
Okt 22 17:16:56 eck005 kernel: IPv6: ADDRCONF(NETDEV_UP): enp2s0: link is not ready
.
(xinetd reading it's configuration files)
.
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> (enp2s0): preparing device
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> (wlp3s0): using nl80211 for WiFi device control
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> (wlp3s0): driver supports Access Point (AP) mode
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> (wlp3s0): new 802.11 WiFi device (driver: 'ath9k' ifindex: 3)
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> (wlp3s0): exported as /org/freedesktop/NetworkManager/Devices/2
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> (wlp3s0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
.
(xinetd reading it's configuration files)
.
Okt 22 17:16:56 eck005 xinetd[959]: xinetd Version 2.3.15 started with libwrap loadavg options compiled in.
Okt 22 17:16:56 eck005 xinetd[959]: Started working: 0 available services
Okt 22 17:16:56 eck005 echo[965]: Starting mail service (Postfix)
Okt 22 17:16:56 eck005 kernel: IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> (wlp3s0): preparing device
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> use BlueZ version 5
Okt 22 17:16:56 eck005 kernel: NET: Registered protocol family 17
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> ModemManager available in the bus
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> (wlp3s0) supports 4 scan SSIDs
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> (wlp3s0): supplicant interface state: starting -> ready
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> (wlp3s0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> (wlp3s0): supplicant interface state: ready -> disconnected
Okt 22 17:16:56 eck005 NetworkManager[908]: <info> (wlp3s0) supports 4 scan SSIDs
Okt 22 17:16:56 eck005 ntpd[1008]: ntpd 4.2.6p5@1.2349-o Mon Apr 20 13:45:41 UTC 2015 (1)
Okt 22 17:16:56 eck005 start-ntpd[960]: Starting network time protocol daemon (NTPD)
Okt 22 17:16:56 eck005 ntpd[1009]: Listening on routing socket on fd #20 for interface updates
Okt 22 17:16:56 eck005 SuSEfirewall2[1010]: Setting up rules from /etc/sysconfig/SuSEfirewall2 ...
Okt 22 17:16:56 eck005 SuSEfirewall2[1128]: Firewall rules successfully set
Okt 22 17:16:57 eck005 postfix/postfix-script[1167]: starting the Postfix mail system
Okt 22 17:16:57 eck005 postfix/master[1169]: daemon started -- version 2.11.3, configuration /etc/postfix
Okt 22 17:16:57 eck005 NetworkManager[908]: <info> (wlp3s0): supplicant interface state: disconnected -> inactive
Okt 22 17:17:01 eck005 NetworkManager[908]: <info> startup complete
Okt 22 17:17:04 eck005 login[1021]: pam_unix(login:session): session opened for user root by LOGIN(uid=0)
Okt 22 17:17:04 eck005 systemd[1191]: pam_unix(systemd-user:session): session opened for user root by (uid=0)
Okt 22 17:17:04 eck005 login[1021]: ROOT LOGIN ON tty2

With the upgrade to Leap 42.1, this issue has been resolved:


 # systemd-analyze 
Startup finished in 1.918s (firmware) + 8.754s (loader) + 3.582s (kernel) + 5.534s (initrd) + 14.687s (userspace) = 34.476s
 # 
 # systemd-analyze blame | head
          6.324s ModemManager.service
          5.664s SuSEfirewall2_init.service
          3.794s dev-sda10.device
          3.294s systemd-fsck-root.service
          3.075s systemd-tmpfiles-setup-dev.service
          2.050s sys-kernel-debug.mount
          2.047s dev-hugepages.mount
          2.047s dev-mqueue.mount
          1.501s bluetooth.service
          1.115s systemd-udev-trigger.service
 #
 # systemd-analyze blame | grep -i 'etwork'
            49ms NetworkManager.service
 #

The Critical / Warning GLib messages have also disappeared from the systemd journal.