System hangs when NetworkManager starts up/shuts down

akbjker · 2012-03-23 17:45:32

Recently, I finally got around to trying to figure out why my computer inexplicably hangs for a long time while suspending or shutting down. Unfortunately, the most I could do with my meager knowledge is narrowing it down to NetworkManager: For the most part, if I start it up/bring it down normally, it causes everything to hang for almost a full minute (input stops, display freezes, any audio becomes stuck in a loop), which causes me a *lot* of grief when I try to suspend or shut down (Sometimes my computer just gives up and crashes)

Sending SIGKILL to the NetworkManager daemon will prevent my computer from hanging, and restarting it afterwards won't cause a hang either, but I highly doubt that killing it is the correct solution.

Uninstalling NetworkManager (including its configuration files) and reinstalling it failed to solve this.

hadrons123 · 2012-03-23 19:15:16

akbjker wrote:

Recently, I finally got around to trying to figure out why my computer inexplicably hangs for a long time while suspending or shutting down. Unfortunately, the most I could do with my meager knowledge is narrowing it down to NetworkManager: For the most part, if I start it up/bring it down normally, it causes everything to hang for almost a full minute (input stops, display freezes, any audio becomes stuck in a loop), which causes me a *lot* of grief when I try to suspend or shut down (Sometimes my computer just gives up and crashes)
Sending SIGKILL to the NetworkManager daemon will prevent my computer from hanging, and restarting it afterwards won't cause a hang either, but I highly doubt that killing it is the correct solution.
Uninstalling NetworkManager (including its configuration files) and reinstalling it failed to solve this.

Which may mean your problems maynot be due to network manager.

Give us some logs to look for.

akbjker · 2012-03-23 19:40:08

hadrons123 wrote:

Which may mean your problems maynot be due to network manager.

...I was afraid of that.

Hopefully all the relevant stuff from everything.log:

Result of
rc.d start networkmanager

Mar 23 15:25:24 myhost NetworkManager[1065]: <info> trying to start the modem manager...
Mar 23 15:25:24 myhost NetworkManager[1065]: <info> monitoring kernel firmware directory '/lib/firmware'.
Mar 23 15:25:24 myhost NetworkManager[1065]: <info> found WiFi radio killswitch rfkill0 (at /sys/devices/pci0000:00/0000:00:1c.1/0000:02:00.0/ieee80211/phy0/rfkill0) (driver (unknown))
Mar 23 15:25:24 myhost NetworkManager[1065]: <info> WiFi enabled by radio killswitch; enabled by state file
Mar 23 15:25:24 myhost NetworkManager[1065]: <info> WWAN enabled by radio killswitch; enabled by state file
Mar 23 15:25:24 myhost NetworkManager[1065]: <info> WiMAX enabled by radio killswitch; enabled by state file
Mar 23 15:25:24 myhost NetworkManager[1065]: <info> Networking is enabled by state file
Mar 23 15:25:24 myhost NetworkManager[1065]: <info> (eth0): carrier is OFF
Mar 23 15:25:24 myhost NetworkManager[1065]: <info> (eth0): new Ethernet device (driver: 'atl1c' ifindex: 2)
Mar 23 15:25:24 myhost NetworkManager[1065]: <info> (eth0): exported as /org/freedesktop/NetworkManager/Devices/0
Mar 23 15:25:24 myhost NetworkManager[1065]: <info> (eth0): now managed
Mar 23 15:25:24 myhost NetworkManager[1065]: <info> (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Mar 23 15:25:24 myhost NetworkManager[1065]: <info> (eth0): bringing up device.
Mar 23 19:25:24 myhost rtkit-daemon[645]: The canary thread is apparently starving. Taking action.
Mar 23 19:25:24 myhost rtkit-daemon[645]: Demoting known real-time threads.
Mar 23 19:25:24 myhost rtkit-daemon[645]: Successfully demoted thread 663 of process 643 (/usr/bin/pulseaudio).
Mar 23 19:25:24 myhost rtkit-daemon[645]: Successfully demoted thread 662 of process 643 (/usr/bin/pulseaudio).
Mar 23 19:25:24 myhost rtkit-daemon[645]: Successfully demoted thread 643 of process 643 (/usr/bin/pulseaudio).
Mar 23 19:25:24 myhost rtkit-daemon[645]: Demoted 3 threads.
Mar 23 19:25:48 myhost rtkit-daemon[645]: The canary thread is apparently starving. Taking action.
Mar 23 19:25:48 myhost rtkit-daemon[645]: Demoting known real-time threads.
Mar 23 19:25:48 myhost rtkit-daemon[645]: Successfully demoted thread 663 of process 643 (/usr/bin/pulseaudio).
Mar 23 19:25:48 myhost rtkit-daemon[645]: Successfully demoted thread 662 of process 643 (/usr/bin/pulseaudio).
Mar 23 19:25:48 myhost rtkit-daemon[645]: Successfully demoted thread 643 of process 643 (/usr/bin/pulseaudio).
Mar 23 19:25:48 myhost rtkit-daemon[645]: Demoted 3 threads.
Mar 23 15:25:48 myhost kernel: [  214.716712] atl1c 0000:01:00.0: irq 44 for MSI/MSI-X
Mar 23 19:26:09 myhost rtkit-daemon[645]: The canary thread is apparently starving. Taking action.
Mar 23 19:26:09 myhost rtkit-daemon[645]: Demoting known real-time threads.
Mar 23 19:26:09 myhost rtkit-daemon[645]: Successfully demoted thread 663 of process 643 (/usr/bin/pulseaudio).
Mar 23 19:26:09 myhost rtkit-daemon[645]: Successfully demoted thread 662 of process 643 (/usr/bin/pulseaudio).
Mar 23 19:26:09 myhost rtkit-daemon[645]: Successfully demoted thread 643 of process 643 (/usr/bin/pulseaudio).
Mar 23 19:26:09 myhost rtkit-daemon[645]: Demoted 3 threads.
Mar 23 19:26:19 myhost rtkit-daemon[645]: The canary thread is apparently starving. Taking action.
Mar 23 19:26:19 myhost rtkit-daemon[645]: Demoting known real-time threads.
Mar 23 19:26:19 myhost rtkit-daemon[645]: Successfully demoted thread 663 of process 643 (/usr/bin/pulseaudio).
Mar 23 19:26:19 myhost rtkit-daemon[645]: Successfully demoted thread 662 of process 643 (/usr/bin/pulseaudio).
Mar 23 19:26:19 myhost rtkit-daemon[645]: Successfully demoted thread 643 of process 643 (/usr/bin/pulseaudio).
Mar 23 19:26:19 myhost rtkit-daemon[645]: Demoted 3 threads.
Mar 23 15:26:19 myhost kernel: [  245.824391] ADDRCONF(NETDEV_UP): eth0: link is not ready
Mar 23 15:26:19 myhost NetworkManager[1065]: <info> (eth0): preparing device.
Mar 23 15:26:19 myhost NetworkManager[1065]: <info> (eth0): deactivating device (reason 'managed') [2]
Mar 23 15:26:19 myhost NetworkManager[1065]: <info> Added default wired connection 'Wired connection 1' for /sys/devices/pci0000:00/0000:00:1c.0/0000:01:00.0/net/eth0
Mar 23 15:26:19 myhost NetworkManager[1065]: <info> (wlan0): driver supports SSID scans (scan_capa 0x01).
Mar 23 15:26:19 myhost NetworkManager[1065]: <info> (wlan0): new 802.11 WiFi device (driver: 'ath9k' ifindex: 3)
Mar 23 15:26:19 myhost NetworkManager[1065]: <info> (wlan0): exported as /org/freedesktop/NetworkManager/Devices/1
Mar 23 15:26:19 myhost NetworkManager[1065]: <info> (wlan0): now managed

Result of
rc.d stop networkmanager

Mar 23 15:27:33 myhost NetworkManager[1065]: <info> caught signal 15, shutting down normally.
Mar 23 15:27:33 myhost NetworkManager[1065]: <warn> quit request received, terminating...
Mar 23 15:27:33 myhost NetworkManager[1065]: <info> (eth0): now unmanaged
Mar 23 15:27:33 myhost NetworkManager[1065]: <info> (eth0): device state change: unavailable -> unmanaged (reason 'removed') [20 10 36]
Mar 23 15:27:33 myhost NetworkManager[1065]: <info> (eth0): cleaning up...
Mar 23 15:27:33 myhost NetworkManager[1065]: <info> (eth0): taking down device.
Mar 23 19:27:43 myhost rtkit-daemon[645]: The canary thread is apparently starving. Taking action.
Mar 23 19:27:43 myhost rtkit-daemon[645]: Demoting known real-time threads.
Mar 23 19:27:43 myhost rtkit-daemon[645]: Successfully demoted thread 663 of process 643 (/usr/bin/pulseaudio).
Mar 23 19:27:43 myhost rtkit-daemon[645]: Successfully demoted thread 662 of process 643 (/usr/bin/pulseaudio).
Mar 23 19:27:43 myhost rtkit-daemon[645]: Successfully demoted thread 643 of process 643 (/usr/bin/pulseaudio).
Mar 23 19:27:43 myhost rtkit-daemon[645]: Demoted 3 threads.
Mar 23 19:27:54 myhost rtkit-daemon[645]: The canary thread is apparently starving. Taking action.
Mar 23 19:27:54 myhost rtkit-daemon[645]: Demoting known real-time threads.
Mar 23 19:27:54 myhost rtkit-daemon[645]: Successfully demoted thread 663 of process 643 (/usr/bin/pulseaudio).
Mar 23 19:27:54 myhost rtkit-daemon[645]: Successfully demoted thread 662 of process 643 (/usr/bin/pulseaudio).
Mar 23 19:27:54 myhost rtkit-daemon[645]: Successfully demoted thread 643 of process 643 (/usr/bin/pulseaudio).
Mar 23 19:27:54 myhost rtkit-daemon[645]: Demoted 3 threads.
Mar 23 15:27:54 myhost NetworkManager[1065]: <info> (wlan0): now unmanaged
Mar 23 15:27:54 myhost NetworkManager[1065]: <info> (wlan0): device state change: activated -> unmanaged (reason 'removed') [100 10 36]
Mar 23 15:27:54 myhost NetworkManager[1065]: <info> (wlan0): deactivating device (reason 'removed') [36]
Mar 23 15:27:54 myhost dhcpcd[1070]: received SIGTERM, stopping
Mar 23 15:27:54 myhost dhcpcd[1070]: wlan0: removing interface
Mar 23 15:27:54 myhost NetworkManager[1065]: <info> (wlan0): canceled DHCP transaction, DHCP client pid 1070
Mar 23 15:27:54 myhost kernel: [  340.728423] wlan0: deauthenticating from 00:1a:1e:26:99:61 by local choice (reason=3)
Mar 23 15:27:54 myhost kernel: [  340.886389] cfg80211: Calling CRDA to update world regulatory domain
Mar 23 15:27:54 myhost NetworkManager[1065]: <info> (wlan0): cleaning up...
Mar 23 15:27:54 myhost NetworkManager[1065]: <info> (wlan0): taking down device.
Mar 23 15:27:54 myhost NetworkManager[1065]: <info> exiting (success)
Mar 23 15:27:54 myhost dbus[476]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
Mar 23 15:27:54 myhost dbus[476]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'

Last edited by akbjker (2012-07-28 04:45:27)

Arch Linux

#1 2012-03-23 17:45:32

System hangs when NetworkManager starts up/shuts down

#2 2012-03-23 19:15:16

Re: System hangs when NetworkManager starts up/shuts down

#3 2012-03-23 19:40:08

Re: System hangs when NetworkManager starts up/shuts down

Board footer