You are not logged in.
NetworkManager started to crash on me recently. The problem emerged for the first time on April 19th after I upgraded to linux 4.16. It happens almost every day ever since. Any ideas?
Apr 25 21:38:24 plamen NetworkManager[885]: <error> [1524717504.4506] libsystemd: Assertion 'client->request_sent <= time_now' failed at src/systemd/src/libsystemd-network/sd-dhcp-client.c:1404, function client_set_lease_timeouts(). Aborting.
Apr 25 21:38:24 plamen NetworkManager[885]: **
Apr 25 21:38:24 plamen NetworkManager[885]: ERROR:src/systemd/src/libsystemd-network/sd-dhcp-client.c:1404:<unknown-fcn>: code should not be reached
Apr 25 21:38:24 plamen systemd[1]: Started Process Core Dump (PID 20818/UID 0).
Apr 25 21:38:24 plamen systemd[1]: NetworkManager.service: Main process exited, code=dumped, status=6/ABRT
Apr 25 21:38:24 plamen systemd[1]: NetworkManager.service: Failed with result 'core-dump'.
Apr 25 21:38:24 plamen nm-openvpn[8049]: event_wait : Interrupted system call (code=4)
Apr 25 21:38:24 plamen nm-openvpn[8069]: event_wait : Interrupted system call (code=4)
Apr 25 21:38:24 plamen plasmashell[3326]: qml: applet destroyed
Apr 25 21:38:24 plamen systemd-coredump[20819]: Process 885 (NetworkManager) of user 0 dumped core.
Stack trace of thread 885:
#0 0x00007f60ab919860 n/a (/usr/lib/libc-2.26.so (deleted))
This causes the machine to completely lose the network.
Last edited by pvn (2018-05-03 16:42:01)
Offline
Apr 25 21:38:24 plamen NetworkManager[885]: <error> [1524717504.4506] libsystemd: Assertion 'client->request_sent <= time_now'
Something odd w/ your system time? Big skew against NTP?
=> Check the the journal context of the incident.
Offline
Wow, you are on to something! When I read your post it hit me like a hammer. I instantly remembered that I recently changed my /etc/ntp.conf - added the local pfSense in the server's list.
# ls -l /etc/ntp.conf
-rw-r--r-- 1 root root 722 Apr 19 10:02 /etc/ntp.conf
#
The date matches with the date when I started having this problem. Bash history shows that I also did:
systemctl enable ntpd.service
systemctl enable ntpdate.service
I disabled both of them to test it for a few days. If there are no issues I will enable them one by one to identify which service is causing all this mess.
Very good call. Thank you very much!
=> Check the the journal context of the incident.
How do I do that?
Last edited by pvn (2018-04-27 17:16:35)
Offline
Just check your journal for the incident and look at the logged events around (notably: before) it for anything suspicious (especially ntp related messages ;-)
Offline
It happened again! I am baffled
Offline
Just check your journal for the incident and look at the logged events around (notably: before) it for anything suspicious (especially ntp related messages ;-)
Offline
I have done that. I see nothing relevant. I was not sure what you meant by "context of the incident".
It goes like this:
1. Reboot
2. After 1h and 45 min I get this:
Apr 27 12:03:27 plamen NetworkManager[891]: <info> [1524855807.4894] dhcp4 (enp0s25): address 10.0.0.19
Apr 27 12:03:27 plamen NetworkManager[891]: <info> [1524855807.4894] dhcp4 (enp0s25): plen 16
Apr 27 12:03:27 plamen dbus-daemon[887]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.2' (uid=0 pid=891 comm="/usr/bin/NetworkManager --no-daemon ")
Apr 27 12:03:27 plamen NetworkManager[891]: <info> [1524855807.4894] dhcp4 (enp0s25): expires in 7200 seconds
Apr 27 12:03:27 plamen NetworkManager[891]: <info> [1524855807.4895] dhcp4 (enp0s25): nameserver '10.0.0.1'
Apr 27 12:03:27 plamen NetworkManager[891]: <info> [1524855807.4895] dhcp4 (enp0s25): domain search 'domain1edited.com'
Apr 27 12:03:27 plamen NetworkManager[891]: <info> [1524855807.4895] dhcp4 (enp0s25): domain search 'domain2edited.com'
Apr 27 12:03:27 plamen NetworkManager[891]: <info> [1524855807.4896] dhcp4 (enp0s25): domain name 'domain1edited.com'
Apr 27 12:03:27 plamen NetworkManager[891]: <info> [1524855807.4896] dhcp4 (enp0s25): hostname 'plamen'
Apr 27 12:03:27 plamen NetworkManager[891]: <info> [1524855807.4896] dhcp4 (enp0s25): gateway 10.0.0.1
Apr 27 12:03:27 plamen NetworkManager[891]: <info> [1524855807.4901] dhcp4 (enp0s25): state changed bound -> bound
Apr 27 12:03:27 plamen systemd[1]: Starting Network Manager Script Dispatcher Service...
Apr 27 12:03:27 plamen dbus-daemon[887]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Apr 27 12:03:27 plamen systemd[1]: Started Network Manager Script Dispatcher Service.
Apr 27 12:03:27 plamen nm-dispatcher[15611]: req:1 'dhcp4-change' [enp0s25]: new request (0 scripts)
Apr 27 12:03:27 plamen nm-dispatcher[15611]: req:1 'dhcp4-change' [enp0s25]: completed: no scripts
The same snippet repeats 8 times every 1hour and 45min.
3. On the 9th time it crashes:
Apr 28 02:03:13 plamen NetworkManager[891]: <error> [1524906193.8587] libsystemd: Assertion 'client->request_sent <= time_now' failed at src/systemd/src/libsystemd-network/sd-dhcp-client.c:1404, function client_set_lease_timeouts(). Aborting.
Apr 28 02:03:13 plamen NetworkManager[891]: **
Apr 28 02:03:13 plamen NetworkManager[891]: ERROR:src/systemd/src/libsystemd-network/sd-dhcp-client.c:1404:<unknown-fcn>: code should not be reached
Apr 28 02:03:13 plamen systemd[1]: Created slice system-systemd\x2dcoredump.slice.
Apr 28 02:03:13 plamen systemd[1]: Started Process Core Dump (PID 29184/UID 0).
Apr 28 02:03:14 plamen systemd[1]: NetworkManager.service: Main process exited, code=dumped, status=6/ABRT
Apr 28 02:03:14 plamen systemd[1]: NetworkManager.service: Failed with result 'core-dump'.
Apr 28 02:03:14 plamen plasmashell[1209]: qml: applet destroyed
Apr 28 02:03:14 plamen nm-openvpn[15698]: event_wait : Interrupted system call (code=4)
Apr 28 02:03:14 plamen nm-openvpn[15698]: SIGTERM[hard,] received, process exiting
Apr 28 02:03:14 plamen systemd-coredump[29185]: Process 891 (NetworkManager) of user 0 dumped core.
Stack trace of thread 891:
#0 0x00007f5906115efb raise (libc.so.6)
#1 0x00007f59061172c1 abort (libc.so.6)
#2 0x00007f590768bb76 g_assertion_message (libglib-2.0.so.0)
#3 0x00007f590768bbdc g_assertion_message_expr (libglib-2.0.so.0)
#4 0x000055844ae17aeb n/a (NetworkManager)
#5 0x000055844ae18100 n/a (NetworkManager)
#6 0x000055844ae294b0 n/a (NetworkManager)
#7 0x000055844ae2ada3 n/a (NetworkManager)
#8 0x000055844ae12b1e n/a (NetworkManager)
#9 0x00007f5907664368 g_main_context_dispatch (libglib-2.0.so.0)
#10 0x00007f59076645b1 n/a (libglib-2.0.so.0)
#11 0x00007f59076648e2 g_main_loop_run (libglib-2.0.so.0)
#12 0x000055844ad4aece n/a (NetworkManager)
#13 0x00007f59061029a7 __libc_start_main (libc.so.6)
#14 0x000055844ad4b53a n/a (NetworkManager)
Stack trace of thread 896:
#0 0x00007f59061cdcd9 __poll (libc.so.6)
#1 0x00007f5907664523 n/a (libglib-2.0.so.0)
#2 0x00007f590766463e g_main_context_iteration (libglib-2.0.so.0)
#3 0x00007f5907664692 n/a (libglib-2.0.so.0)
#4 0x00007f590768ca2a n/a (libglib-2.0.so.0)
#5 0x00007f59064a30bc start_thread (libpthread.so.0)
#6 0x00007f59061d82ff __clone (libc.so.6)
Stack trace of thread 898:
#0 0x00007f59061cdcd9 __poll (libc.so.6)
#1 0x00007f5907664523 n/a (libglib-2.0.so.0)
#2 0x00007f59076648e2 g_main_loop_run (libglib-2.0.so.0)
#3 0x00007f5907e57348 n/a (libgio-2.0.so.0)
#4 0x00007f590768ca2a n/a (libglib-2.0.so.0)
#5 0x00007f59064a30bc start_thread (libpthread.so.0)
#6 0x00007f59061d82ff __clone (libc.so.6)
Apr 28 02:03:14 plamen systemd[1]: NetworkManager.service: Service hold-off time over, scheduling restart.
Apr 28 02:03:14 plamen systemd[1]: NetworkManager.service: Scheduled restart job, restart counter is at 1.
Apr 28 02:03:14 plamen systemd[1]: Stopped Network Manager.
Apr 28 02:03:14 plamen systemd[1]: NetworkManager.service: Found left-over process 15672 (nm-openvpn-serv) in control group while starting unit. Ignoring.
Apr 28 02:03:14 plamen systemd[1]: This usually indicates unclean termination of a previous run, or service implementation deficiencies.
Apr 28 02:03:14 plamen systemd[1]: NetworkManager.service: Found left-over process 15679 (openvpn) in control group while starting unit. Ignoring.
Apr 28 02:03:14 plamen systemd[1]: This usually indicates unclean termination of a previous run, or service implementation deficiencies.
Apr 28 02:03:14 plamen systemd[1]: Starting Network Manager...
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.4798] NetworkManager (version 1.10.6-3, Arch Linux) is starting... (after a restart)
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.4799] Read config: /etc/NetworkManager/NetworkManager.conf (lib: 20-connectivity.conf)
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.4881] manager[0x55ff0da490e0]: monitoring kernel firmware directory '/usr/lib/firmware'.
Apr 28 02:03:14 plamen dbus-daemon[887]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4908' (uid=0 pid=29198 comm="/usr/bin/NetworkManager --no-daemon ")
Apr 28 02:03:14 plamen systemd[1]: Starting Hostname Service...
Apr 28 02:03:14 plamen dbus-daemon[887]: [system] Successfully activated service 'org.freedesktop.hostname1'
Apr 28 02:03:14 plamen systemd[1]: Started Hostname Service.
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8265] hostname: hostname: using hostnamed
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8266] hostname: hostname changed from (none) to "plamen"
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8269] dns-mgr[0x55ff0da61960]: init: dns=default, rc-manager=symlink
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8275] manager[0x55ff0da490e0]: rfkill: WiFi hardware radio set enabled
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8276] manager[0x55ff0da490e0]: rfkill: WWAN hardware radio set enabled
Apr 28 02:03:14 plamen systemd[1]: Started Network Manager.
Apr 28 02:03:14 plamen dbus-daemon[887]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.4908' (uid=0 pid=29198 comm="/usr/bin/NetworkManager --no-daemon ")
Apr 28 02:03:14 plamen systemd[1]: Starting Network Manager Script Dispatcher Service...
Apr 28 02:03:14 plamen dbus-daemon[887]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Apr 28 02:03:14 plamen systemd[1]: Started Network Manager Script Dispatcher Service.
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8457] settings: loaded plugin keyfile: (c) 2007 - 2016 Red Hat, Inc. To report bugs please use the NetworkManager mailing list.
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8460] settings: loaded plugin iBFT: (c) 2014 Red Hat, Inc. To report bugs please use the NetworkManager mailing list. (/usr/lib/NetworkManager/libnm-settings-plugin-ibft.so)
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8633] keyfile: new connection /etc/NetworkManager/system-connections/rockdc-TCP4-1194-plamen (d1955e6b-41d9-485f-bcc9-e5bc756926e3,"rockdc-TCP4-1194-plamen")
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8727] keyfile: new connection /etc/NetworkManager/system-connections/dc-udp-50001-plamen (6b4198ce-f49d-4fd3-b86b-3080f67f7ef5,"dc-udp-50001-plamen")
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8738] manager: rfkill: WiFi enabled by radio killswitch; enabled by state file
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8739] manager: rfkill: WWAN enabled by radio killswitch; enabled by state file
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8739] manager: Networking is enabled by state file
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8741] dhcp-init: Using DHCP client 'internal'
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8741] Loaded device plugin: NMBondDeviceFactory (internal)
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8742] Loaded device plugin: NMBridgeDeviceFactory (internal)
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8781] Loaded device plugin: NMDummyDeviceFactory (internal)
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8781] Loaded device plugin: NMEthernetDeviceFactory (internal)
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8781] Loaded device plugin: NMInfinibandDeviceFactory (internal)
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8781] Loaded device plugin: NMIPTunnelDeviceFactory (internal)
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8781] Loaded device plugin: NMMacsecDeviceFactory (internal)
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8781] Loaded device plugin: NMMacvlanDeviceFactory (internal)
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8781] Loaded device plugin: NMPppDeviceFactory (internal)
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8781] Loaded device plugin: NMTunDeviceFactory (internal)
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8781] Loaded device plugin: NMVethDeviceFactory (internal)
Apr 28 02:03:14 plamen nm-dispatcher[29205]: req:1 'hostname': new request (0 scripts)
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8781] Loaded device plugin: NMVlanDeviceFactory (internal)
Apr 28 02:03:14 plamen nm-dispatcher[29205]: req:1 'hostname': completed: no scripts
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8781] Loaded device plugin: NMVxlanDeviceFactory (internal)
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8808] Loaded device plugin: NMTeamFactory (/usr/lib/NetworkManager/libnm-device-plugin-team.so)
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8811] Loaded device plugin: NMOvsFactory (/usr/lib/NetworkManager/libnm-device-plugin-ovs.so)
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8827] Loaded device plugin: NMWwanFactory (/usr/lib/NetworkManager/libnm-device-plugin-wwan.so)
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8832] Loaded device plugin: NMBluezManager (/usr/lib/NetworkManager/libnm-device-plugin-bluetooth.so)
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8835] Loaded device plugin: NMWifiFactory (/usr/lib/NetworkManager/libnm-device-plugin-wifi.so)
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8848] Loaded device plugin: NMAtmManager (/usr/lib/NetworkManager/libnm-device-plugin-adsl.so)
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8853] device (lo): carrier: link connected
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8863] manager: (lo): new Generic device (/org/freedesktop/NetworkManager/Devices/1)
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8881] device (enp0s25): carrier: link connected
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8881] manager: (enp0s25): new Ethernet device (/org/freedesktop/NetworkManager/Devices/2)
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8888] keyfile: add connection in-memory (41322d38-6234-3009-88dd-81e7f365704d,"Wired connection 1")
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8892] settings: (enp0s25): created default wired connection 'Wired connection 1'
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8914] keyfile: add connection in-memory (0dac95ff-83c1-44b2-94e8-91b329648f49,"enp0s25")
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8917] device (enp0s25): state change: unmanaged -> unavailable (reason 'connection-assumed', sys-iface-state: 'external')
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8920] device (enp0s25): state change: unavailable -> disconnected (reason 'connection-assumed', sys-iface-state: 'external')
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8929] device (enp0s25): Activation: starting connection 'enp0s25' (0dac95ff-83c1-44b2-94e8-91b329648f49)
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8938] manager: (tun0): new Tun device (/org/freedesktop/NetworkManager/Devices/3)
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8959] keyfile: add connection in-memory (0e202c72-b329-4a48-a1a7-80211acd3d2c,"tun0")
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8964] device (tun0): state change: unmanaged -> unavailable (reason 'connection-assumed', sys-iface-state: 'external')
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8967] device (tun0): state change: unavailable -> disconnected (reason 'connection-assumed', sys-iface-state: 'external')
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.8974] device (tun0): Activation: starting connection 'tun0' (0e202c72-b329-4a48-a1a7-80211acd3d2c)
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.9024] ovsdb: Could not connect: No such file or directory
Apr 28 02:03:14 plamen plasmashell[1209]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "AllDevices"
Apr 28 02:03:14 plamen plasmashell[1209]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "Capabilities"
Apr 28 02:03:14 plamen plasmashell[1209]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "ConnectivityCheckAvailable"
Apr 28 02:03:14 plamen plasmashell[1209]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "ConnectivityCheckEnabled"
Apr 28 02:03:14 plamen plasmashell[1209]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "Devices"
Apr 28 02:03:14 plamen plasmashell[1209]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "GlobalDnsConfiguration"
Apr 28 02:03:14 plamen org_kde_powerdevil[1232]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "AllDevices"
Apr 28 02:03:14 plamen org_kde_powerdevil[1232]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "Capabilities"
Apr 28 02:03:14 plamen org_kde_powerdevil[1232]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "ConnectivityCheckAvailable"
Apr 28 02:03:14 plamen nm-dispatcher[29205]: req:2 'up' [enp0s25]: new request (0 scripts)
Apr 28 02:03:14 plamen org_kde_powerdevil[1232]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "ConnectivityCheckEnabled"
Apr 28 02:03:14 plamen nm-dispatcher[29205]: req:2 'up' [enp0s25]: completed: no scripts
Apr 28 02:03:14 plamen org_kde_powerdevil[1232]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "Devices"
Apr 28 02:03:14 plamen nm-dispatcher[29205]: req:3 'up' [tun0]: new request (0 scripts)
Apr 28 02:03:14 plamen org_kde_powerdevil[1232]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "GlobalDnsConfiguration"
Apr 28 02:03:14 plamen nm-dispatcher[29205]: req:3 'up' [tun0]: completed: no scripts
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.9050] device (enp0s25): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'external')
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.9056] device (tun0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'external')
Apr 28 02:03:14 plamen org_kde_powerdevil[1232]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "AllDevices"
Apr 28 02:03:14 plamen org_kde_powerdevil[1232]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "Capabilities"
Apr 28 02:03:14 plamen org_kde_powerdevil[1232]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "Devices"
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.9078] device (enp0s25): state change: prepare -> config (reason 'none', sys-iface-state: 'external')
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.9079] device (tun0): state change: prepare -> config (reason 'none', sys-iface-state: 'external')
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.9082] device (enp0s25): state change: config -> ip-config (reason 'none', sys-iface-state: 'external')
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.9084] device (enp0s25): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'external')
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.9086] device (tun0): state change: config -> ip-config (reason 'none', sys-iface-state: 'external')
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.9087] device (tun0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'external')
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.9098] device (enp0s25): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'external')
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.9098] device (tun0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'external')
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.9100] device (enp0s25): state change: secondaries -> activated (reason 'none', sys-iface-state: 'external')
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.9102] manager: NetworkManager state is now CONNECTED_LOCAL
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.9150] device (enp0s25): Activation: successful, device activated.
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.9155] device (tun0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'external')
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.9248] device (tun0): Activation: successful, device activated.
Apr 28 02:03:14 plamen NetworkManager[29198]: <info> [1524906194.9331] manager: startup complete
Apr 28 02:03:14 plamen kdeinit5[1152]: networkmanager-qt: virtual void NetworkManager::ActiveConnectionPrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "StateFlags"
Apr 28 02:03:14 plamen plasmashell[1209]: networkmanager-qt: virtual void NetworkManager::ActiveConnectionPrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "StateFlags"
Apr 28 02:03:14 plamen kdeinit5[1152]: networkmanager-qt: virtual void NetworkManager::ActiveConnectionPrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "StateFlags"
Apr 28 02:03:14 plamen kdeinit5[1152]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "AllDevices"
Apr 28 02:03:14 plamen kdeinit5[1152]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "Capabilities"
Apr 28 02:03:14 plamen kdeinit5[1152]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "ConnectivityCheckAvailable"
Apr 28 02:03:14 plamen kdeinit5[1152]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "ConnectivityCheckEnabled"
Apr 28 02:03:14 plamen kdeinit5[1152]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "Devices"
Apr 28 02:03:14 plamen kdeinit5[1152]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "GlobalDnsConfiguration"
Apr 28 02:03:14 plamen plasmashell[1209]: networkmanager-qt: virtual void NetworkManager::ActiveConnectionPrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "StateFlags"
Apr 28 02:03:14 plamen kdeinit5[1152]: networkmanager-qt: virtual void NetworkManager::DevicePrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "LldpNeighbors"
Apr 28 02:03:14 plamen kdeinit5[1152]: networkmanager-qt: virtual void NetworkManager::DevicePrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "Real"
Apr 28 02:03:14 plamen plasmashell[1209]: networkmanager-qt: virtual void NetworkManager::DevicePrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "LldpNeighbors"
Apr 28 02:03:14 plamen plasmashell[1209]: networkmanager-qt: virtual void NetworkManager::DevicePrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "Real"
Apr 28 02:03:14 plamen kdeinit5[1152]: networkmanager-qt: virtual void NetworkManager::DevicePrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "LldpNeighbors"
Apr 28 02:03:14 plamen kdeinit5[1152]: networkmanager-qt: virtual void NetworkManager::DevicePrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "Real"
Apr 28 02:03:14 plamen plasmashell[1209]: networkmanager-qt: virtual void NetworkManager::DevicePrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "LldpNeighbors"
Apr 28 02:03:14 plamen plasmashell[1209]: networkmanager-qt: virtual void NetworkManager::DevicePrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "Real"
Apr 28 02:03:14 plamen kdeinit5[1152]: networkmanager-qt: virtual void NetworkManager::DevicePrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "LldpNeighbors"
Apr 28 02:03:14 plamen kdeinit5[1152]: networkmanager-qt: virtual void NetworkManager::DevicePrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "Real"
Apr 28 02:03:14 plamen plasmashell[1209]: networkmanager-qt: virtual void NetworkManager::DevicePrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "LldpNeighbors"
Apr 28 02:03:14 plamen plasmashell[1209]: networkmanager-qt: virtual void NetworkManager::DevicePrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "Real"
Apr 28 02:03:14 plamen kdeinit5[1152]: networkmanager-qt: virtual void NetworkManager::DevicePrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "LldpNeighbors"
Apr 28 02:03:14 plamen kdeinit5[1152]: networkmanager-qt: virtual void NetworkManager::DevicePrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "Real"
Apr 28 02:03:14 plamen plasmashell[1209]: networkmanager-qt: virtual void NetworkManager::DevicePrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "LldpNeighbors"
Apr 28 02:03:14 plamen plasmashell[1209]: networkmanager-qt: virtual void NetworkManager::DevicePrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "Real"
Apr 28 02:03:14 plamen kdeinit5[1152]: networkmanager-qt: virtual void NetworkManager::DevicePrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "LldpNeighbors"
Apr 28 02:03:14 plamen kdeinit5[1152]: networkmanager-qt: virtual void NetworkManager::DevicePrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "Real"
Apr 28 02:03:14 plamen plasmashell[1209]: networkmanager-qt: virtual void NetworkManager::DevicePrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "LldpNeighbors"
Apr 28 02:03:14 plamen plasmashell[1209]: networkmanager-qt: virtual void NetworkManager::DevicePrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "Real"
Apr 28 02:03:14 plamen kdeinit5[1152]: networkmanager-qt: virtual void NetworkManager::DevicePrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "LldpNeighbors"
Apr 28 02:03:14 plamen kdeinit5[1152]: networkmanager-qt: virtual void NetworkManager::DevicePrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "Real"
Apr 28 02:03:14 plamen plasmashell[1209]: networkmanager-qt: virtual void NetworkManager::DevicePrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "LldpNeighbors"
Apr 28 02:03:14 plamen plasmashell[1209]: networkmanager-qt: virtual void NetworkManager::DevicePrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "Real"
Apr 28 02:03:14 plamen kdeinit5[1152]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "AllDevices"
Apr 28 02:03:14 plamen kdeinit5[1152]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "Capabilities"
Apr 28 02:03:14 plamen kdeinit5[1152]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "Devices"
Apr 28 02:03:14 plamen kdeinit5[1152]: networkmanager-qt: virtual void NetworkManager::ActiveConnectionPrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "StateFlags"
Apr 28 02:03:14 plamen plasmashell[1209]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "AllDevices"
Apr 28 02:03:14 plamen plasmashell[1209]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "Capabilities"
Apr 28 02:03:14 plamen plasmashell[1209]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "Devices"
Apr 28 02:03:15 plamen ksmserver[1185]: CreateNotify: 49000350
Apr 28 02:03:15 plamen ksmserver[1185]: CreateNotify: 113246209
Apr 28 02:03:16 plamen NetworkManager[29198]: <info> [1524906196.2625] manager: NetworkManager state is now CONNECTED_GLOBAL
Apr 28 02:03:16 plamen nm-dispatcher[29205]: req:4 'connectivity-change': new request (0 scripts)
Apr 28 02:03:16 plamen nm-dispatcher[29205]: req:4 'connectivity-change': completed: no scripts
4. Then 15 min later:
Apr 28 02:18:15 plamen dhcpcd[905]: enp0s25: deleted IP address 10.0.0.19/16
Apr 28 02:18:15 plamen dhcpcd[905]: enp0s25: deleting route to 10.0.0.0/16
Apr 28 02:18:15 plamen NetworkManager[29198]: <info> [1524907095.2081] manager: NetworkManager state is now CONNECTED_LOCAL
Apr 28 02:18:15 plamen dhcpcd[905]: enp0s25: deleting default route via 10.0.0.1
Apr 28 02:18:15 plamen systemd[1]: Starting Network Manager Script Dispatcher Service...
Apr 28 02:18:15 plamen dbus-daemon[887]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.4908' (uid=0 pid=29198 comm="/usr/bin/NetworkManager --no-daemon ")
Apr 28 02:18:15 plamen systemd[1]: Started Network Manager Script Dispatcher Service.
Apr 28 02:18:15 plamen dbus-daemon[887]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Apr 28 02:18:15 plamen nm-dispatcher[30411]: req:1 'connectivity-change': new request (0 scripts)
Apr 28 02:18:15 plamen nm-dispatcher[30411]: req:1 'connectivity-change': completed: no scripts
Apr 28 02:18:20 plamen ksmserver[1185]: CreateNotify: 113246209
Apr 28 02:18:25 plamen ksmserver[1185]: CreateNotify: 113246209
Apr 28 02:18:30 plamen ksmserver[1185]: CreateNotify: 113246209
Apr 28 02:18:35 plamen ksmserver[1185]: CreateNotify: 113246209
Apr 28 02:18:40 plamen ksmserver[1185]: CreateNotify: 113246209
Apr 28 02:18:45 plamen NetworkManager[29198]: <info> [1524907125.0173] connectivity: (enp0s25) timed out
Apr 28 02:18:45 plamen dbus-daemon[887]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.4908' (uid=0 pid=29198 comm="/usr/bin/NetworkManager --no-daemon ")
Apr 28 02:18:45 plamen systemd[1]: Starting Network Manager Script Dispatcher Service...
Apr 28 02:18:45 plamen dbus-daemon[887]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Apr 28 02:18:45 plamen systemd[1]: Started Network Manager Script Dispatcher Service.
Apr 28 02:18:45 plamen nm-dispatcher[30482]: req:1 'connectivity-change': new request (0 scripts)
Apr 28 02:18:45 plamen nm-dispatcher[30482]: req:1 'connectivity-change': completed: no scripts
Last edited by pvn (2018-04-29 00:11:57)
Offline
Things that happen briefly before the assert but are not necessarily related to networkmanager.
The assert fails because a lease request seems to have been sent in the future, so either there's a problem w/ the dhcp server or the local time gets set back (or there's a bug in NM, but i'd expect more complaints in that case ;-)
However: there's "dhcpcd" and "dhcp4" so you seem to run dhcpcd and networkmanager concurrently?
systemctl list-unit-files --state=enabled
In case: don't.
Offline
Yes, dhcpcd.service is enabled. Are you saying that if NetworkManager is running and taking care of the network, dhcpcd.service should not be running?
Offline
Yes, precisely that. The concurrent lease requests also explain the error.
Disable dhcpcd or read up to maintain different interfaces with different network managing services (what though is usually pointless - pick one service and stick with that)
Offline
Thanks, I will try that first thing Monday morning. I will report back.
Offline
After three days uptime and no crashes I think we have a solution.
systemctl mask dhcpcd.service
Thanks seth!
Offline
You should™ not have to mask the service - unless you've enabled it, it should™ no start automatically, so disabling if would be sufficient.
Offline
Yeah, you are right. I did overshoot with "mask" and that was intentional. I wanted to make sure dhcpcd does not get started by some other service and it does not get in the way. I made three trips to my office last week because of this crash and I did not want to make a fourth one. I have not tested what happens if I only disable dhcpcd and this is why I did not provide it as a working solution. On the next reboot I will unmask and disable it to see if this is going to be sufficient. My guess is it will be.
Last edited by pvn (2018-05-03 19:42:10)
Offline