Bug#788400: systemd-logind only fires suspend on lid close once even though it sees the event

[Jessica Frazelle]

Here are the NetworkManager.service logs, it sees the first suspend
and sleeps but it is not even getting the others


-- Logs begin at Thu 2015-06-11 09:30:01 PDT, end at Thu 2015-06-11
09:47:32 PDT. -- Jun 11 09:30:02 debian systemd[1]: Starting Network
Manager... -- Subject: Unit NetworkManager.service has begun start-up
-- Defined-By: systemd -- Support:
http://lists.freedesktop.org/mailman/listinfo/systemd-devel -- -- Unit
NetworkManager.service has begun starting up. Jun 11 09:30:02 debian
NetworkManager[693]: NetworkManager (version 1.0.2) is starting... Jun
11 09:30:02 debian NetworkManager[693]: Read config:
/etc/NetworkManager/NetworkManager.conf Jun 11 09:30:02 debian
NetworkManager[693]: WEXT support is enabled Jun 11 09:30:02 debian
NetworkManager[693]: init! Jun 11 09:30:02 debian NetworkManager[693]:
update_system_hostname Jun 11 09:30:02 debian NetworkManager[693]:
interface-parser: parsing file /etc/network/interfaces Jun 11 09:30:02
debian NetworkManager[693]: interface-parser: source line includes
interfaces file(s) /etc/network/interfaces.d/* Jun 11 09:30:02 debian
NetworkManager[693]: interfaces file /etc/network/interfaces.d/*
doesn't exist Jun 11 09:30:02 debian NetworkManager[693]:
interface-parser: finished parsing file /etc/network/interfaces Jun 11
09:30:02 debian NetworkManager[693]: guessed connection type (eth0) =
802-3-ethernet Jun 11 09:30:02 debian NetworkManager[693]:
update_connection_setting_from_if_block: name:eth0,
type:802-3-ethernet, id:Ifupdown (eth0), uuid:
681b428f-beaf-8932-dce4-687ed5bae28e Jun 11 09:30:02 debian
NetworkManager[693]: adding eth0 to connections Jun 11 09:30:02 debian
NetworkManager[693]: adding iface eth0 to eni_ifaces Jun 11 09:30:02
debian NetworkManager[693]: autoconnect Jun 11 09:30:02 debian
NetworkManager[693]: management mode: unmanaged Jun 11 09:30:02 debian
NetworkManager[693]: devices added (path:
/sys/devices/pci0000:00/0000:00:1c.3/0000:02:00.0/net/wlan0, iface:
wlan0) Jun 11 09:30:02 debian NetworkManager[693]: device added (path:
/sys/devices/pci0000:00/0000:00:1c.3/0000:02:00.0/net/wlan0, iface:
wlan0): no ifupdown configuration found. Jun 11 09:30:02 debian
NetworkManager[693]: devices added (path: /sys/devices/virtual/net/lo,
iface: lo) Jun 11 09:30:02 debian NetworkManager[693]: device added
(path: /sys/devices/virtual/net/lo, iface: lo): no ifupdown
configuration found. Jun 11 09:30:02 debian NetworkManager[693]: end
_init. Jun 11 09:30:02 debian NetworkManager[693]: Loaded plugin
ifupdown: (C) 2008 Canonical Ltd. To report bugs please use the
NetworkManager mailing list. Jun 11 09:30:02 debian
NetworkManager[693]: Loaded plugin keyfile: (c) 2007 - 2015 Red Hat,
Inc. To report bugs please use the NetworkManager mailing list. Jun 11
09:30:02 debian NetworkManager[693]: (11672832) ... get_connections.
Jun 11 09:30:02 debian NetworkManager[693]: (11672832) ...
get_connections (managed=false): return empty list. Jun 11 09:30:02
debian NetworkManager[693]: keyfile: new connection
/etc/NetworkManager/system-connections/DockerEng_Slow
(b562ec97-fec0-4b24-bf7a-8812fe97ee8b,"DockerEng_Slow") Jun 11
09:30:02 debian NetworkManager[693]: keyfile: new connection
/etc/NetworkManager/system-connections/Not in Range
(1436f8b2-524c-490c-9b4c-72b8cfd0005c,"Not in Range") Jun 11 09:30:02
debian NetworkManager[693]: get unmanaged devices count: 0 Jun 11
09:30:02 debian NetworkManager[693]: monitoring kernel firmware
directory '/lib/firmware'. Jun 11 09:30:02 debian NetworkManager[693]:
monitoring ifupdown state file '/run/network/ifstate'. Jun 11 09:30:02
debian NetworkManager[693]: rfkill1: found WiFi radio killswitch (at
/sys/devices/pci0000:00/0000:00:1c.3/0000:02:00.0/ieee80211/phy0/rfkill1)
(driver wl) Jun 11 09:30:02 debian NetworkManager[693]: WiFi hardware
radio set enabled Jun 11 09:30:02 debian NetworkManager[693]: WWAN
hardware radio set enabled Jun 11 09:30:02 debian NetworkManager[693]:
Loaded device plugin:
/usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-wifi.so
Jun 11 09:30:02 debian NetworkManager[693]: Loaded device plugin:
/usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-adsl.so
Jun 11 09:30:02 debian NetworkManager[693]: Loaded device plugin:
/usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-wwan.so
Jun 11 09:30:02 debian NetworkManager[693]: Loaded device plugin:
/usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-bluetooth.so
Jun 11 09:30:02 debian NetworkManager[693]: Loaded device plugin:
/usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-team.so
Jun 11 09:30:02 debian NetworkManager[693]: WiFi enabled by radio
killswitch; enabled by state file Jun 11 09:30:02 debian
NetworkManager[693]: WWAN enabled by radio killswitch; enabled by
state file Jun 11 09:30:02 debian NetworkManager[693]: WiMAX enabled
by radio killswitch; enabled by state file Jun 11 09:30:02 debian
NetworkManager[693]: Networking is enabled by state file Jun 11
09:30:02 debian NetworkManager[693]: (lo): link connected Jun 11
09:30:02 debian NetworkManager[693]: (lo): carrier is ON Jun 11
09:30:02 debian NetworkManager[693]: (lo): new Generic device (driver:
'unknown' ifindex: 1) Jun 11 09:30:02 debian NetworkManager[693]:
(lo): exported as /org/freedesktop/NetworkManager/Devices/0 Jun 11
09:30:02 debian NetworkManager[693]: (wlan0): using nl80211 for WiFi
device control Jun 11 09:30:02 debian NetworkManager[693]: (wlan0):
new 802.11 WiFi device (driver: 'wl' ifindex: 2) Jun 11 09:30:02
debian NetworkManager[693]: (wlan0): exported as
/org/freedesktop/NetworkManager/Devices/1 Jun 11 09:30:02 debian
NetworkManager[693]: (wlan0): device state change: unmanaged ->
unavailable (reason 'managed') [10 20 2] Jun 11 09:30:02 debian
NetworkManager[693]: (wlan0): preparing device Jun 11 09:30:02 debian
NetworkManager[693]: use BlueZ version 5 Jun 11 09:30:02 debian
NetworkManager[693]: (docker0): carrier is OFF Jun 11 09:30:02 debian
NetworkManager[693]: (docker0): new Bridge device (driver: 'bridge'
ifindex: 3) Jun 11 09:30:02 debian NetworkManager[693]: (docker0):
exported as /org/freedesktop/NetworkManager/Devices/2 Jun 11 09:30:02
debian NetworkManager[693]: devices added (path:
/sys/devices/virtual/net/docker0, iface: docker0) Jun 11 09:30:02
debian NetworkManager[693]: device added (path:
/sys/devices/virtual/net/docker0, iface: docker0): no ifupdown
configuration found. Jun 11 09:30:02 debian NetworkManager[693]:
(docker0): device state change: unmanaged -> unavailable (reason
'connection-assumed') [10 20 41] Jun 11 09:30:02 debian
NetworkManager[693]: keyfile: add connection in-memory
(8d0cd8df-310a-4c57-bf14-b4e490e74bba,"docker0") Jun 11 09:30:02
debian NetworkManager[693]: (docker0): device state change:
unavailable -> disconnected (reason 'connection-assumed') [20 30 41]
Jun 11 09:30:02 debian NetworkManager[693]: (docker0): Activation:
starting connection 'docker0' Jun 11 09:30:02 debian
NetworkManager[693]: (docker0): Activation: Stage 1 of 5 (Device
Prepare) scheduled... Jun 11 09:30:02 debian NetworkManager[693]:
(docker0): Activation: Stage 1 of 5 (Device Prepare) started... Jun 11
09:30:02 debian NetworkManager[693]: (docker0): device state change:
disconnected -> prepare (reason 'none') [30 40 0] Jun 11 09:30:02
debian NetworkManager[693]: (docker0): Activation: Stage 2 of 5
(Device Configure) scheduled... Jun 11 09:30:02 debian
NetworkManager[693]: (docker0): Activation: Stage 1 of 5 (Device
Prepare) complete. Jun 11 09:30:02 debian NetworkManager[693]:
(docker0): Activation: Stage 2 of 5 (Device Configure) starting... Jun
11 09:30:02 debian NetworkManager[693]: (docker0): device state
change: prepare -> config (reason 'none') [40 50 0] Jun 11 09:30:02
debian NetworkManager[693]: (docker0): Activation: Stage 2 of 5
(Device Configure) successful. Jun 11 09:30:02 debian
NetworkManager[693]: (docker0): Activation: Stage 3 of 5 (IP Configure
Start) scheduled. Jun 11 09:30:02 debian NetworkManager[693]:
(docker0): Activation: Stage 2 of 5 (Device Configure) complete. Jun
11 09:30:02 debian NetworkManager[693]: (docker0): Activation: Stage 3
of 5 (IP Configure Start) started... Jun 11 09:30:02 debian
NetworkManager[693]: (docker0): device state change: config ->
ip-config (reason 'none') [50 70 0] Jun 11 09:30:02 debian
NetworkManager[693]: (docker0): Activation: Stage 5 of 5 (IPv4
Configure Commit) scheduled... Jun 11 09:30:02 debian
NetworkManager[693]: (docker0): Activation: Stage 3 of 5 (IP Configure
Start) complete. Jun 11 09:30:02 debian NetworkManager[693]:
(docker0): Activation: Stage 5 of 5 (IPv4 Commit) started... Jun 11
09:30:02 debian NetworkManager[693]: (docker0): device state change:
ip-config -> ip-check (reason 'none') [70 80 0] Jun 11 09:30:02 debian
NetworkManager[693]: (docker0): Activation: Stage 5 of 5 (IPv4 Commit)
complete. Jun 11 09:30:02 debian NetworkManager[693]: (docker0):
device state change: ip-check -> secondaries (reason 'none') [80 90 0]
Jun 11 09:30:02 debian NetworkManager[693]: (docker0): device state
change: secondaries -> activated (reason 'none') [90 100 0] Jun 11
09:30:02 debian NetworkManager[693]: NetworkManager state is now
CONNECTED_LOCAL Jun 11 09:30:02 debian NetworkManager[693]: (docker0):
Activation: successful, device activated. Jun 11 09:30:03 debian
systemd[1]: Started Network Manager. -- Subject: Unit
NetworkManager.service has finished start-up -- Defined-By: systemd --
Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
-- -- Unit NetworkManager.service has finished starting up. -- -- The
start-up result is done. Jun 11 09:30:03 debian NetworkManager[693]:
wpa_supplicant started Jun 11 09:30:03 debian NetworkManager[693]:
(vethe09295c): device is virtual, marking as unmanaged Jun 11 09:30:03
debian NetworkManager[693]: (vethe09295c): link connected Jun 11
09:30:03 debian NetworkManager[693]: (vethe09295c): carrier is ON Jun
11 09:30:03 debian NetworkManager[693]: (vethe09295c): new Veth device
(driver: 'unknown' ifindex: 4) Jun 11 09:30:03 debian
NetworkManager[693]: (vethe09295c): exported as
/org/freedesktop/NetworkManager/Devices/3 Jun 11 09:30:03 debian
NetworkManager[693]: (veth955b1ca): device is virtual, marking as
unmanaged Jun 11 09:30:03 debian NetworkManager[693]: (veth955b1ca):
link connected Jun 11 09:30:03 debian NetworkManager[693]:
(veth955b1ca): carrier is ON Jun 11 09:30:03 debian
NetworkManager[693]: (veth955b1ca): new Veth device (driver: 'unknown'
ifindex: 5) Jun 11 09:30:03 debian NetworkManager[693]: (veth955b1ca):
exported as /org/freedesktop/NetworkManager/Devices/4 Jun 11 09:30:03
debian NetworkManager[693]: (docker0): bridge port veth955b1ca was
attached Jun 11 09:30:03 debian NetworkManager[693]: (veth955b1ca):
enslaved to docker0 Jun 11 09:30:03 debian NetworkManager[693]:
keyfile: add connection in-memory
(a1dbb974-807b-4e22-a0de-3f14f56ba55e,"veth955b1ca") Jun 11 09:30:03
debian NetworkManager[693]: (veth955b1ca): device state change:
unmanaged -> unavailable (reason 'connection-assumed') [10 20 41] Jun
11 09:30:03 debian NetworkManager[693]: (veth955b1ca): device state
change: unavailable -> disconnected (reason 'connection-assumed') [20
30 41] Jun 11 09:30:03 debian NetworkManager[693]: (veth955b1ca):
Activation: starting connection 'veth955b1ca' Jun 11 09:30:03 debian
NetworkManager[693]: (veth955b1ca): Activation: Stage 1 of 5 (Device
Prepare) scheduled... Jun 11 09:30:03 debian NetworkManager[693]:
(docker0): link connected Jun 11 09:30:03 debian NetworkManager[693]:
(veth955b1ca): Activation: Stage 1 of 5 (Device Prepare) started...
Jun 11 09:30:03 debian NetworkManager[693]: (veth955b1ca): device
state change: disconnected -> prepare (reason 'none') [30 40 0] Jun 11
09:30:03 debian NetworkManager[693]: (veth955b1ca): Activation: Stage
2 of 5 (Device Configure) scheduled... Jun 11 09:30:03 debian
NetworkManager[693]: (veth955b1ca): Activation: Stage 1 of 5 (Device
Prepare) complete. Jun 11 09:30:03 debian NetworkManager[693]: devices
added (path: /sys/devices/virtual/net/vethe09295c, iface: vethe09295c)
Jun 11 09:30:03 debian NetworkManager[693]: device added (path:
/sys/devices/virtual/net/vethe09295c, iface: vethe09295c): no ifupdown
configuration found. Jun 11 09:30:03 debian NetworkManager[693]:
(veth955b1ca): Activation: Stage 2 of 5 (Device Configure) starting...
Jun 11 09:30:03 debian NetworkManager[693]: (veth955b1ca): device
state change: prepare -> config (reason 'none') [40 50 0] Jun 11
09:30:03 debian NetworkManager[693]: (veth955b1ca): Activation: Stage
2 of 5 (Device Configure) successful. Jun 11 09:30:03 debian
NetworkManager[693]: (veth955b1ca): Activation: Stage 3 of 5 (IP
Configure Start) scheduled. Jun 11 09:30:03 debian
NetworkManager[693]: (veth955b1ca): Activation: Stage 2 of 5 (Device
Configure) complete. Jun 11 09:30:03 debian NetworkManager[693]:
devices added (path: /sys/devices/virtual/net/veth955b1ca, iface:
veth955b1ca) Jun 11 09:30:03 debian NetworkManager[693]: device added
(path: /sys/devices/virtual/net/veth955b1ca, iface: veth955b1ca): no
ifupdown configuration found. Jun 11 09:30:03 debian
NetworkManager[693]: (veth955b1ca): Activation: Stage 3 of 5 (IP
Configure Start) started... Jun 11 09:30:03 debian
NetworkManager[693]: (veth955b1ca): device state change: config ->
ip-config (reason 'none') [50 70 0] Jun 11 09:30:03 debian
NetworkManager[693]: (veth955b1ca): Activation: Stage 3 of 5 (IP
Configure Start) complete. Jun 11 09:30:03 debian NetworkManager[693]:
(veth955b1ca): device state change: ip-config -> secondaries (reason
'none') [70 90 0] Jun 11 09:30:03 debian NetworkManager[693]:
(veth955b1ca): device state change: secondaries -> activated (reason
'none') [90 100 0] Jun 11 09:30:03 debian NetworkManager[693]:
(veth955b1ca): Activation: successful, device activated. Jun 11
09:30:03 debian NetworkManager[693]: (wlan0) supports 1 scan SSIDs Jun
11 09:30:03 debian NetworkManager[693]: devices removed (path:
/sys/devices/virtual/net/vethe09295c, iface: vethe09295c) Jun 11
09:30:03 debian NetworkManager[693]: ModemManager disappeared from bus
Jun 11 09:30:03 debian NetworkManager[693]: (wlan0): supplicant
interface state: starting -> ready Jun 11 09:30:03 debian
NetworkManager[693]: (wlan0): device state change: unavailable ->
disconnected (reason 'supplicant-available') [20 30 42] Jun 11
09:30:03 debian NetworkManager[693]: ModemManager available in the bus
Jun 11 09:30:03 debian NetworkManager[693]: (wlan0): supplicant
interface state: ready -> disconnected Jun 11 09:30:03 debian
NetworkManager[693]: (wlan0) supports 1 scan SSIDs Jun 11 09:30:03
debian NetworkManager[693]: startup complete Jun 11 09:30:04 debian
NetworkManager[693]: (wlan0): supplicant interface state: disconnected
-> inactive Jun 11 09:30:04 debian NetworkManager[693]:
Auto-activating connection 'DockerEng_Slow'. Jun 11 09:30:04 debian
NetworkManager[693]: (wlan0): Activation: starting connection
'DockerEng_Slow' Jun 11 09:30:04 debian NetworkManager[693]: (wlan0):
Activation: Stage 1 of 5 (Device Prepare) scheduled... Jun 11 09:30:04
debian NetworkManager[693]: (wlan0): Activation: Stage 1 of 5 (Device
Prepare) started... Jun 11 09:30:04 debian NetworkManager[693]:
(wlan0): device state change: disconnected -> prepare (reason 'none')
[30 40 0] Jun 11 09:30:04 debian NetworkManager[693]: NetworkManager
state is now CONNECTING Jun 11 09:30:04 debian NetworkManager[693]:
(wlan0): Activation: Stage 2 of 5 (Device Configure) scheduled... Jun
11 09:30:04 debian NetworkManager[693]: (wlan0): Activation: Stage 1
of 5 (Device Prepare) complete. Jun 11 09:30:04 debian
NetworkManager[693]: (wlan0): Activation: Stage 2 of 5 (Device
Configure) starting... Jun 11 09:30:04 debian NetworkManager[693]:
(wlan0): device state change: prepare -> config (reason 'none') [40 50
0] Jun 11 09:30:04 debian NetworkManager[693]: (wlan0): Activation:
(wifi) connection 'DockerEng_Slow' has security, and secrets exist. No
new secrets needed. Jun 11 09:30:04 debian NetworkManager[693]:
Config: added 'ssid' value 'DockerEng_Slow' Jun 11 09:30:04 debian
NetworkManager[693]: Config: added 'scan_ssid' value '1' Jun 11
09:30:04 debian NetworkManager[693]: Config: added 'key_mgmt' value
'WPA-PSK' Jun 11 09:30:04 debian NetworkManager[693]: Config: added
'auth_alg' value 'OPEN' Jun 11 09:30:04 debian NetworkManager[693]:
Config: added 'psk' value '' Jun 11 09:30:04 debian
NetworkManager[693]: (wlan0): Activation: Stage 2 of 5 (Device
Configure) complete. Jun 11 09:30:04 debian NetworkManager[693]:
Config: set interface ap_scan to 1 Jun 11 09:30:04 debian
NetworkManager[693]: (wlan0): supplicant interface state: inactive ->
associating Jun 11 09:30:05 debian NetworkManager[693]: (wlan0):
supplicant interface state: associating -> 4-way handshake Jun 11
09:30:05 debian NetworkManager[693]: (wlan0): supplicant interface
state: 4-way handshake -> completed Jun 11 09:30:05 debian
NetworkManager[693]: (wlan0): Activation: (wifi) Stage 2 of 5 (Device
Configure) successful. Connected to wireless network 'DockerEng_Slow'.
Jun 11 09:30:05 debian NetworkManager[693]: (wlan0): Activation: Stage
3 of 5 (IP Configure Start) scheduled. Jun 11 09:30:05 debian
NetworkManager[693]: (wlan0): Activation: Stage 3 of 5 (IP Configure
Start) started... Jun 11 09:30:05 debian NetworkManager[693]: (wlan0):
device state change: config -> ip-config (reason 'none') [50 70 0] Jun
11 09:30:05 debian NetworkManager[693]: Activation (wlan0) Beginning
DHCPv4 transaction (timeout in 45 seconds) Jun 11 09:30:05 debian
NetworkManager[693]: dhclient started with pid 1361 Jun 11 09:30:05
debian NetworkManager[693]: (wlan0): Activation: Stage 3 of 5 (IP
Configure Start) complete. Jun 11 09:30:05 debian dhclient[1361]:
DHCPREQUEST on wlan0 to 255.255.255.255 port 67 Jun 11 09:30:07 debian
NetworkManager[693]: (vethe09295c): device is virtual, marking as
unmanaged Jun 11 09:30:07 debian NetworkManager[693]: (vethe09295c):
link connected Jun 11 09:30:07 debian NetworkManager[693]:
(vethe09295c): carrier is ON Jun 11 09:30:07 debian
NetworkManager[693]: (vethe09295c): new Veth device (driver: 'unknown'
ifindex: 4) Jun 11 09:30:07 debian NetworkManager[693]: (vethe09295c):
exported as /org/freedesktop/NetworkManager/Devices/5 Jun 11 09:30:07
debian NetworkManager[693]: devices added (path:
/sys/devices/virtual/net/vethe09295c, iface: vethe09295c) Jun 11
09:30:07 debian NetworkManager[693]: device added (path:
/sys/devices/virtual/net/vethe09295c, iface: vethe09295c): no ifupdown
configuration found. Jun 11 09:30:07 debian NetworkManager[693]:
devices removed (path: /sys/devices/virtual/net/veth955b1ca, iface:
veth955b1ca) Jun 11 09:30:07 debian NetworkManager[693]:
(veth955b1ca): device state change: activated -> unmanaged (reason
'removed') [100 10 36] Jun 11 09:30:07 debian NetworkManager[693]:
(veth955b1ca): deactivating device (reason 'removed') [36] Jun 11
09:30:07 debian NetworkManager[693]: (docker0): failed to detach
bridge port veth955b1ca Jun 11 09:30:07 debian NetworkManager[693]:
devices removed (path: /sys/devices/virtual/net/vethe09295c, iface:
vethe09295c) Jun 11 09:30:07 debian NetworkManager[693]: (docker0):
link disconnected (deferring action for 4 seconds) Jun 11 09:30:07
debian NetworkManager[693]: (veth5d03d58): device is virtual, marking
as unmanaged Jun 11 09:30:07 debian NetworkManager[693]:
(veth5d03d58): carrier is OFF Jun 11 09:30:07 debian
NetworkManager[693]: (veth5d03d58): new Veth device (driver: 'unknown'
ifindex: 6) Jun 11 09:30:07 debian NetworkManager[693]: (veth5d03d58):
exported as /org/freedesktop/NetworkManager/Devices/6 Jun 11 09:30:07
debian NetworkManager[693]: (vethf0c3548): device is virtual, marking
as unmanaged Jun 11 09:30:07 debian NetworkManager[693]:
(vethf0c3548): link connected Jun 11 09:30:07 debian
NetworkManager[693]: (vethf0c3548): carrier is ON Jun 11 09:30:07
debian NetworkManager[693]: (vethf0c3548): new Veth device (driver:
'unknown' ifindex: 7) Jun 11 09:30:07 debian NetworkManager[693]:
(vethf0c3548): exported as /org/freedesktop/NetworkManager/Devices/7
Jun 11 09:30:07 debian NetworkManager[693]: (docker0): bridge port
vethf0c3548 was attached Jun 11 09:30:07 debian NetworkManager[693]:
(vethf0c3548): enslaved to docker0 Jun 11 09:30:07 debian
NetworkManager[693]: keyfile: add connection in-memory
(1d515f4a-5d3b-4619-bd05-da102a3ddbc2,"vethf0c3548") Jun 11 09:30:07
debian NetworkManager[693]: (vethf0c3548): device state change:
unmanaged -> unavailable (reason 'connection-assumed') [10 20 41] Jun
11 09:30:07 debian NetworkManager[693]: (vethf0c3548): device state
change: unavailable -> disconnected (reason 'connection-assumed') [20
30 41] Jun 11 09:30:07 debian NetworkManager[693]: (vethf0c3548):
Activation: starting connection 'vethf0c3548' Jun 11 09:30:07 debian
NetworkManager[693]: (vethf0c3548): Activation: Stage 1 of 5 (Device
Prepare) scheduled... Jun 11 09:30:07 debian NetworkManager[693]:
(veth5d03d58): link connected Jun 11 09:30:07 debian
NetworkManager[693]: (docker0): link connected Jun 11 09:30:07 debian
NetworkManager[693]: (vethf0c3548): Activation: Stage 1 of 5 (Device
Prepare) started... Jun 11 09:30:07 debian NetworkManager[693]:
(vethf0c3548): device state change: disconnected -> prepare (reason
'none') [30 40 0] Jun 11 09:30:07 debian NetworkManager[693]:
(vethf0c3548): Activation: Stage 2 of 5 (Device Configure)
scheduled... Jun 11 09:30:07 debian NetworkManager[693]:
(vethf0c3548): Activation: Stage 1 of 5 (Device Prepare) complete. Jun
11 09:30:07 debian NetworkManager[693]: devices added (path:
/sys/devices/virtual/net/vethf0c3548, iface: vethf0c3548) Jun 11
09:30:07 debian NetworkManager[693]: device added (path:
/sys/devices/virtual/net/vethf0c3548, iface: vethf0c3548): no ifupdown
configuration found. Jun 11 09:30:07 debian NetworkManager[693]:
(vethf0c3548): Activation: Stage 2 of 5 (Device Configure) starting...
Jun 11 09:30:07 debian NetworkManager[693]: (vethf0c3548): device
state change: prepare -> config (reason 'none') [40 50 0] Jun 11
09:30:07 debian NetworkManager[693]: (vethf0c3548): Activation: Stage
2 of 5 (Device Configure) successful. Jun 11 09:30:07 debian
NetworkManager[693]: (vethf0c3548): Activation: Stage 3 of 5 (IP
Configure Start) scheduled. Jun 11 09:30:07 debian
NetworkManager[693]: (vethf0c3548): Activation: Stage 2 of 5 (Device
Configure) complete. Jun 11 09:30:07 debian NetworkManager[693]:
(vethf0c3548): Activation: Stage 3 of 5 (IP Configure Start)
started... Jun 11 09:30:07 debian NetworkManager[693]: (vethf0c3548):
device state change: config -> ip-config (reason 'none') [50 70 0] Jun
11 09:30:07 debian NetworkManager[693]: (vethf0c3548): Activation:
Stage 3 of 5 (IP Configure Start) complete. Jun 11 09:30:07 debian
NetworkManager[693]: (vethf0c3548): device state change: ip-config ->
secondaries (reason 'none') [70 90 0] Jun 11 09:30:07 debian
NetworkManager[693]: devices added (path:
/sys/devices/virtual/net/veth5d03d58, iface: veth5d03d58) Jun 11
09:30:07 debian NetworkManager[693]: device added (path:
/sys/devices/virtual/net/veth5d03d58, iface: veth5d03d58): no ifupdown
configuration found. Jun 11 09:30:07 debian NetworkManager[693]:
(vethf0c3548): device state change: secondaries -> activated (reason
'none') [90 100 0] Jun 11 09:30:07 debian NetworkManager[693]:
(vethf0c3548): Activation: successful, device activated. Jun 11
09:30:08 debian NetworkManager[693]: devices removed (path:
/sys/devices/virtual/net/veth5d03d58, iface: veth5d03d58) Jun 11
09:30:10 debian dhclient[1361]: DHCPREQUEST on wlan0 to
255.255.255.255 port 67 Jun 11 09:30:11 debian dhclient[1361]: DHCPACK
from 192.168.200.1 Jun 11 09:30:11 debian NetworkManager[693]: address
192.168.200.133 Jun 11 09:30:11 debian NetworkManager[693]: plen 22
(255.255.252.0) Jun 11 09:30:11 debian NetworkManager[693]: gateway
192.168.200.1 Jun 11 09:30:11 debian NetworkManager[693]: server
identifier 192.168.200.1 Jun 11 09:30:11 debian NetworkManager[693]:
lease time 86400 Jun 11 09:30:11 debian NetworkManager[693]:
nameserver '208.67.222.222' Jun 11 09:30:11 debian
NetworkManager[693]: nameserver '208.67.220.220' Jun 11 09:30:11
debian NetworkManager[693]: domain search 'docker.com.' Jun 11
09:30:11 debian NetworkManager[693]: domain search 'docker.io.' Jun 11
09:30:11 debian NetworkManager[693]: domain search 'dckr.io.' Jun 11
09:30:11 debian NetworkManager[693]: (wlan0): DHCPv4 state changed
unknown -> bound Jun 11 09:30:11 debian NetworkManager[693]: (wlan0):
Activation: Stage 5 of 5 (IPv4 Configure Commit) scheduled... Jun 11
09:30:11 debian NetworkManager[693]: (wlan0): Activation: Stage 5 of 5
(IPv4 Commit) started... Jun 11 09:30:11 debian NetworkManager[693]:
(wlan0): device state change: ip-config -> ip-check (reason 'none')
[70 80 0] Jun 11 09:30:11 debian NetworkManager[693]: (wlan0):
Activation: Stage 5 of 5 (IPv4 Commit) complete. Jun 11 09:30:11
debian NetworkManager[693]: (wlan0): device state change: ip-check ->
secondaries (reason 'none') [80 90 0] Jun 11 09:30:11 debian
NetworkManager[693]: (wlan0): device state change: secondaries ->
activated (reason 'none') [90 100 0] Jun 11 09:30:11 debian
NetworkManager[693]: NetworkManager state is now CONNECTED_LOCAL Jun
11 09:30:11 debian dhclient[1361]: bound to 192.168.200.133 -- renewal
in 38634 seconds. Jun 11 09:30:11 debian NetworkManager[693]:
NetworkManager state is now CONNECTED_GLOBAL Jun 11 09:30:11 debian
NetworkManager[693]: Policy set 'DockerEng_Slow' (wlan0) as default
for IPv4 routing and DNS. Jun 11 09:30:11 debian NetworkManager[693]:
(wlan0): Activation: successful, device activated. Jun 11 09:30:26
debian NetworkManager[693]: (wlan0): supplicant interface state:
completed -> associating Jun 11 09:30:26 debian NetworkManager[693]:
(wlan0): supplicant interface state: associating -> associated Jun 11
09:30:26 debian NetworkManager[693]: (wlan0): supplicant interface
state: associated -> 4-way handshake Jun 11 09:30:26 debian
NetworkManager[693]: (wlan0): supplicant interface state: 4-way
handshake -> completed Jun 11 09:30:26 debian NetworkManager[693]:
(wlan0): roamed from BSSID 94:B4:0F:9E:8A:66 (DockerEng_Slow) to
94:B4:0F:9D:FF:A6 (DockerEng_Slow) Jun 11 09:30:36 debian
NetworkManager[693]: (wlan0): Activation: Stage 4 of 5 (IPv6 Configure
Timeout) scheduled... Jun 11 09:30:36 debian NetworkManager[693]:
(wlan0): Activation: Stage 4 of 5 (IPv6 Configure Timeout) started...
Jun 11 09:30:36 debian NetworkManager[693]: (wlan0): Activation: Stage
4 of 5 (IPv6 Configure Timeout) complete. Jun 11 09:31:20 debian
NetworkManager[693]: (veth5d03d58): device is virtual, marking as
unmanaged Jun 11 09:31:20 debian NetworkManager[693]: (veth5d03d58):
link connected Jun 11 09:31:20 debian NetworkManager[693]:
(veth5d03d58): carrier is ON Jun 11 09:31:20 debian
NetworkManager[693]: (veth5d03d58): new Veth device (driver: 'unknown'
ifindex: 6) Jun 11 09:31:20 debian NetworkManager[693]: (veth5d03d58):
exported as /org/freedesktop/NetworkManager/Devices/8 Jun 11 09:31:20
debian NetworkManager[693]: devices added (path:
/sys/devices/virtual/net/veth5d03d58, iface: veth5d03d58) Jun 11
09:31:20 debian NetworkManager[693]: device added (path:
/sys/devices/virtual/net/veth5d03d58, iface: veth5d03d58): no ifupdown
configuration found. Jun 11 09:31:20 debian NetworkManager[693]:
devices removed (path: /sys/devices/virtual/net/vethf0c3548, iface:
vethf0c3548) Jun 11 09:31:20 debian NetworkManager[693]:
(vethf0c3548): device state change: activated -> unmanaged (reason
'removed') [100 10 36] Jun 11 09:31:20 debian NetworkManager[693]:
(vethf0c3548): deactivating device (reason 'removed') [36] Jun 11
09:31:20 debian NetworkManager[693]: (docker0): failed to detach
bridge port vethf0c3548 Jun 11 09:31:20 debian NetworkManager[693]:
devices removed (path: /sys/devices/virtual/net/veth5d03d58, iface:
veth5d03d58) Jun 11 09:31:20 debian NetworkManager[693]: (docker0):
link disconnected (deferring action for 4 seconds) Jun 11 09:31:25
debian NetworkManager[693]: (docker0): device state change: activated
-> unmanaged (reason 'removed') [100 10 36] Jun 11 09:31:25 debian
NetworkManager[693]: (docker0): deactivating device (reason 'removed')
[36] Jun 11 09:31:25 debian NetworkManager[693]: devices removed
(path: /sys/devices/virtual/net/docker0, iface: docker0) Jun 11
09:31:25 debian NetworkManager[693]: (docker0): carrier is OFF Jun 11
09:31:25 debian NetworkManager[693]: (docker0): new Bridge device
(driver: 'bridge' ifindex: 8) Jun 11 09:31:25 debian
NetworkManager[693]: (docker0): exported as
/org/freedesktop/NetworkManager/Devices/9 Jun 11 09:31:25 debian
NetworkManager[693]: devices added (path:
/sys/devices/virtual/net/docker0, iface: docker0) Jun 11 09:31:25
debian NetworkManager[693]: device added (path:
/sys/devices/virtual/net/docker0, iface: docker0): no ifupdown
configuration found. Jun 11 09:31:25 debian NetworkManager[693]:
(docker0): device state change: unmanaged -> unavailable (reason
'connection-assumed') [10 20 41] Jun 11 09:31:25 debian
NetworkManager[693]: keyfile: add connection in-memory
(06653a2c-cf65-4878-9691-032121503aa6,"docker0") Jun 11 09:31:25
debian NetworkManager[693]: (docker0): device state change:
unavailable -> disconnected (reason 'connection-assumed') [20 30 41]
Jun 11 09:31:25 debian NetworkManager[693]: (docker0): Activation:
starting connection 'docker0' Jun 11 09:31:25 debian
NetworkManager[693]: (docker0): Activation: Stage 1 of 5 (Device
Prepare) scheduled... Jun 11 09:31:25 debian NetworkManager[693]:
(docker0): Activation: Stage 1 of 5 (Device Prepare) started... Jun 11
09:31:25 debian NetworkManager[693]: (docker0): device state change:
disconnected -> prepare (reason 'none') [30 40 0] Jun 11 09:31:25
debian NetworkManager[693]: (docker0): Activation: Stage 2 of 5
(Device Configure) scheduled... Jun 11 09:31:25 debian
NetworkManager[693]: (docker0): Activation: Stage 1 of 5 (Device
Prepare) complete. Jun 11 09:31:25 debian NetworkManager[693]:
(docker0): Activation: Stage 2 of 5 (Device Configure) starting... Jun
11 09:31:25 debian NetworkManager[693]: (docker0): device state
change: prepare -> config (reason 'none') [40 50 0] Jun 11 09:31:25
debian NetworkManager[693]: (docker0): Activation: Stage 2 of 5
(Device Configure) successful. Jun 11 09:31:25 debian
NetworkManager[693]: (docker0): Activation: Stage 3 of 5 (IP Configure
Start) scheduled. Jun 11 09:31:25 debian NetworkManager[693]:
(docker0): Activation: Stage 2 of 5 (Device Configure) complete. Jun
11 09:31:25 debian NetworkManager[693]: (docker0): Activation: Stage 3
of 5 (IP Configure Start) started... Jun 11 09:31:25 debian
NetworkManager[693]: (docker0): device state change: config ->
ip-config (reason 'none') [50 70 0] Jun 11 09:31:25 debian
NetworkManager[693]: (docker0): Activation: Stage 5 of 5 (IPv4
Configure Commit) scheduled... Jun 11 09:31:25 debian
NetworkManager[693]: (docker0): Activation: Stage 3 of 5 (IP Configure
Start) complete. Jun 11 09:31:25 debian NetworkManager[693]:
(docker0): Activation: Stage 5 of 5 (IPv4 Commit) started... Jun 11
09:31:25 debian NetworkManager[693]: (docker0): device state change:
ip-config -> ip-check (reason 'none') [70 80 0] Jun 11 09:31:25 debian
NetworkManager[693]: (docker0): Activation: Stage 5 of 5 (IPv4 Commit)
complete. Jun 11 09:31:25 debian NetworkManager[693]: (docker0):
device state change: ip-check -> secondaries (reason 'none') [80 90 0]
Jun 11 09:31:25 debian NetworkManager[693]: (docker0): device state
change: secondaries -> activated (reason 'none') [90 100 0] Jun 11
09:31:25 debian NetworkManager[693]: (docker0): Activation:
successful, device activated. Jun 11 09:31:25 debian
NetworkManager[693]: (veth9296c9a): device is virtual, marking as
unmanaged Jun 11 09:31:25 debian NetworkManager[693]: (veth9296c9a):
carrier is OFF Jun 11 09:31:25 debian NetworkManager[693]:
(veth9296c9a): new Veth device (driver: 'unknown' ifindex: 9) Jun 11
09:31:25 debian NetworkManager[693]: (veth9296c9a): exported as
/org/freedesktop/NetworkManager/Devices/10 Jun 11 09:31:25 debian
NetworkManager[693]: (veth5ad99a9): device is virtual, marking as
unmanaged Jun 11 09:31:25 debian NetworkManager[693]: (veth5ad99a9):
carrier is OFF Jun 11 09:31:25 debian NetworkManager[693]:
(veth5ad99a9): new Veth device (driver: 'unknown' ifindex: 10) Jun 11
09:31:25 debian NetworkManager[693]: (veth5ad99a9): exported as
/org/freedesktop/NetworkManager/Devices/11 Jun 11 09:31:25 debian
NetworkManager[693]: (docker0): bridge port veth5ad99a9 was attached
Jun 11 09:31:25 debian NetworkManager[693]: (veth5ad99a9): enslaved to
docker0 Jun 11 09:31:25 debian NetworkManager[693]: keyfile: add
connection in-memory
(bf2b07e6-b8cd-4ca0-98fa-bda0497db8c1,"veth5ad99a9") Jun 11 09:31:25
debian NetworkManager[693]: (veth5ad99a9): device state change:
unmanaged -> unavailable (reason 'connection-assumed') [10 20 41] Jun
11 09:31:25 debian NetworkManager[693]: (veth5ad99a9): device state
change: unavailable -> disconnected (reason 'connection-assumed') [20
30 41] Jun 11 09:31:25 debian NetworkManager[693]: (veth5ad99a9):
Activation: starting connection 'veth5ad99a9' Jun 11 09:31:25 debian
NetworkManager[693]: (veth5ad99a9): Activation: Stage 1 of 5 (Device
Prepare) scheduled... Jun 11 09:31:25 debian NetworkManager[693]:
devices added (path: /sys/devices/virtual/net/veth9296c9a, iface:
veth9296c9a) Jun 11 09:31:25 debian NetworkManager[693]: device added
(path: /sys/devices/virtual/net/veth9296c9a, iface: veth9296c9a): no
ifupdown configuration found. Jun 11 09:31:25 debian
NetworkManager[693]: (veth5ad99a9): Activation: Stage 1 of 5 (Device
Prepare) started... Jun 11 09:31:25 debian NetworkManager[693]:
(veth5ad99a9): device state change: disconnected -> prepare (reason
'none') [30 40 0] Jun 11 09:31:25 debian NetworkManager[693]:
(veth5ad99a9): Activation: Stage 2 of 5 (Device Configure)
scheduled... Jun 11 09:31:25 debian NetworkManager[693]:
(veth5ad99a9): Activation: Stage 1 of 5 (Device Prepare) complete. Jun
11 09:31:25 debian NetworkManager[693]: (veth5ad99a9): Activation:
Stage 2 of 5 (Device Configure) starting... Jun 11 09:31:25 debian
NetworkManager[693]: (veth5ad99a9): device state change: prepare ->
config (reason 'none') [40 50 0] Jun 11 09:31:25 debian
NetworkManager[693]: (veth5ad99a9): Activation: Stage 2 of 5 (Device
Configure) successful. Jun 11 09:31:25 debian NetworkManager[693]:
(veth5ad99a9): Activation: Stage 3 of 5 (IP Configure Start)
scheduled. Jun 11 09:31:25 debian NetworkManager[693]: (veth5ad99a9):
Activation: Stage 2 of 5 (Device Configure) complete. Jun 11 09:31:25
debian NetworkManager[693]: (veth5ad99a9): Activation: Stage 3 of 5
(IP Configure Start) started... Jun 11 09:31:25 debian
NetworkManager[693]: (veth5ad99a9): device state change: config ->
ip-config (reason 'none') [50 70 0] Jun 11 09:31:25 debian
NetworkManager[693]: (veth5ad99a9): Activation: Stage 3 of 5 (IP
Configure Start) complete. Jun 11 09:31:25 debian NetworkManager[693]:
(veth9296c9a): link connected Jun 11 09:31:25 debian
NetworkManager[693]: (veth5ad99a9): link connected Jun 11 09:31:25
debian NetworkManager[693]: (docker0): link connected Jun 11 09:31:25
debian NetworkManager[693]: (veth5ad99a9): device state change:
ip-config -> secondaries (reason 'none') [70 90 0] Jun 11 09:31:25
debian NetworkManager[693]: (veth5ad99a9): device state change:
secondaries -> activated (reason 'none') [90 100 0] Jun 11 09:31:25
debian NetworkManager[693]: (veth5ad99a9): Activation: successful,
device activated. Jun 11 09:31:25 debian NetworkManager[693]: devices
added (path: /sys/devices/virtual/net/veth5ad99a9, iface: veth5ad99a9)
Jun 11 09:31:25 debian NetworkManager[693]: device added (path:
/sys/devices/virtual/net/veth5ad99a9, iface: veth5ad99a9): no ifupdown
configuration found. Jun 11 09:31:25 debian NetworkManager[693]:
devices removed (path: /sys/devices/virtual/net/veth9296c9a, iface:
veth9296c9a) Jun 11 09:31:25 debian NetworkManager[693]: (docker0):
link disconnected (calling deferred action) Jun 11 09:31:36 debian
NetworkManager[693]: (vethea33109): device is virtual, marking as
unmanaged Jun 11 09:31:36 debian NetworkManager[693]: (vethea33109):
carrier is OFF Jun 11 09:31:36 debian NetworkManager[693]:
(vethea33109): new Veth device (driver: 'unknown' ifindex: 11) Jun 11
09:31:36 debian NetworkManager[693]: (vethea33109): exported as
/org/freedesktop/NetworkManager/Devices/12 Jun 11 09:31:36 debian
NetworkManager[693]: (vethaa8c234): device is virtual, marking as
unmanaged Jun 11 09:31:36 debian NetworkManager[693]: (vethaa8c234):
link connected Jun 11 09:31:36 debian NetworkManager[693]:
(vethaa8c234): carrier is ON Jun 11 09:31:36 debian
NetworkManager[693]: (vethaa8c234): new Veth device (driver: 'unknown'
ifindex: 12) Jun 11 09:31:36 debian NetworkManager[693]:
(vethaa8c234): exported as /org/freedesktop/NetworkManager/Devices/13
Jun 11 09:31:36 debian NetworkManager[693]: (docker0): bridge port
vethaa8c234 was attached Jun 11 09:31:36 debian NetworkManager[693]:
(vethaa8c234): enslaved to docker0 Jun 11 09:31:36 debian
NetworkManager[693]: keyfile: add connection in-memory
(4090b240-85d5-4e71-9ebf-09ac24ebcf14,"vethaa8c234") Jun 11 09:31:36
debian NetworkManager[693]: (vethaa8c234): device state change:
unmanaged -> unavailable (reason 'connection-assumed') [10 20 41] Jun
11 09:31:36 debian NetworkManager[693]: (vethaa8c234): device state
change: unavailable -> disconnected (reason 'connection-assumed') [20
30 41] Jun 11 09:31:36 debian NetworkManager[693]: (vethaa8c234):
Activation: starting connection 'vethaa8c234' Jun 11 09:31:36 debian
NetworkManager[693]: (vethaa8c234): Activation: Stage 1 of 5 (Device
Prepare) scheduled... Jun 11 09:31:36 debian NetworkManager[693]:
(vethea33109): link connected Jun 11 09:31:36 debian
NetworkManager[693]: (vethaa8c234): Activation: Stage 1 of 5 (Device
Prepare) started... Jun 11 09:31:36 debian NetworkManager[693]:
(vethaa8c234): device state change: disconnected -> prepare (reason
'none') [30 40 0] Jun 11 09:31:36 debian NetworkManager[693]:
(vethaa8c234): Activation: Stage 2 of 5 (Device Configure)
scheduled... Jun 11 09:31:36 debian NetworkManager[693]:
(vethaa8c234): Activation: Stage 1 of 5 (Device Prepare) complete. Jun
11 09:31:36 debian NetworkManager[693]: (vethaa8c234): Activation:
Stage 2 of 5 (Device Configure) starting... Jun 11 09:31:36 debian
NetworkManager[693]: (vethaa8c234): device state change: prepare ->
config (reason 'none') [40 50 0] Jun 11 09:31:36 debian
NetworkManager[693]: (vethaa8c234): Activation: Stage 2 of 5 (Device
Configure) successful. Jun 11 09:31:36 debian NetworkManager[693]:
(vethaa8c234): Activation: Stage 3 of 5 (IP Configure Start)
scheduled. Jun 11 09:31:36 debian NetworkManager[693]: (vethaa8c234):
Activation: Stage 2 of 5 (Device Configure) complete. Jun 11 09:31:36
debian NetworkManager[693]: (vethaa8c234): Activation: Stage 3 of 5
(IP Configure Start) started... Jun 11 09:31:36 debian
NetworkManager[693]: (vethaa8c234): device state change: config ->
ip-config (reason 'none') [50 70 0] Jun 11 09:31:36 debian
NetworkManager[693]: (vethaa8c234): Activation: Stage 3 of 5 (IP
Configure Start) complete. Jun 11 09:31:36 debian NetworkManager[693]:
(vethaa8c234): device state change: ip-config -> secondaries (reason
'none') [70 90 0] Jun 11 09:31:36 debian NetworkManager[693]:
(vethaa8c234): device state change: secondaries -> activated (reason
'none') [90 100 0] Jun 11 09:31:36 debian NetworkManager[693]:
(vethaa8c234): Activation: successful, device activated. Jun 11
09:31:36 debian NetworkManager[693]: devices added (path:
/sys/devices/virtual/net/vethea33109, iface: vethea33109) Jun 11
09:31:36 debian NetworkManager[693]: device added (path:
/sys/devices/virtual/net/vethea33109, iface: vethea33109): no ifupdown
configuration found. Jun 11 09:31:36 debian NetworkManager[693]:
devices added (path: /sys/devices/virtual/net/vethaa8c234, iface:
vethaa8c234) Jun 11 09:31:36 debian NetworkManager[693]: device added
(path: /sys/devices/virtual/net/vethaa8c234, iface: vethaa8c234): no
ifupdown configuration found. Jun 11 09:31:36 debian
NetworkManager[693]: devices removed (path:
/sys/devices/virtual/net/vethea33109, iface: vethea33109) Jun 11
09:46:10 debian NetworkManager[693]: sleep requested (sleeping: no
enabled: yes) Jun 11 09:46:10 debian NetworkManager[693]: sleeping...
Jun 11 09:46:10 debian NetworkManager[693]: (wlan0): device state
change: activated -> unmanaged (reason 'sleeping') [100 10 37] Jun 11
09:46:10 debian NetworkManager[693]: (wlan0): deactivating device
(reason 'sleeping') [37] Jun 11 09:46:10 debian NetworkManager[693]:
(wlan0): canceled DHCP transaction, DHCP client pid 1361 Jun 11
09:46:10 debian NetworkManager[693]: (wlan0): DHCPv4 state changed
bound -> done Jun 11 09:46:10 debian NetworkManager[693]:
NetworkManager state is now ASLEEP Jun 11 09:46:21 debian
NetworkManager[693]: wake requested (sleeping: yes enabled: yes) Jun
11 09:46:21 debian NetworkManager[693]: waking up... Jun 11 09:46:21
debian NetworkManager[693]: (wlan0): device state change: unmanaged ->
unavailable (reason 'managed') [10 20 2] Jun 11 09:46:21 debian
NetworkManager[693]: (wlan0): preparing device Jun 11 09:46:21 debian
NetworkManager[693]: NetworkManager state is now CONNECTED_LOCAL Jun
11 09:46:21 debian NetworkManager[693]: (wlan0) supports 1 scan SSIDs
Jun 11 09:46:21 debian NetworkManager[693]: (wlan0): supplicant
interface state: starting -> ready Jun 11 09:46:21 debian
NetworkManager[693]: (wlan0): device state change: unavailable ->
disconnected (reason 'supplicant-available') [20 30 42] Jun 11
09:46:21 debian NetworkManager[693]: (wlan0): supplicant interface
state: ready -> disconnected Jun 11 09:46:21 debian
NetworkManager[693]: (wlan0) supports 1 scan SSIDs Jun 11 09:46:45
debian NetworkManager[693]: (wlan0): supplicant interface state:
disconnected -> inactive Jun 11 09:46:45 debian NetworkManager[693]:
Auto-activating connection 'DockerEng_Slow'. Jun 11 09:46:45 debian
NetworkManager[693]: (wlan0): Activation: starting connection
'DockerEng_Slow' Jun 11 09:46:45 debian NetworkManager[693]: (wlan0):
Activation: Stage 1 of 5 (Device Prepare) scheduled... Jun 11 09:46:45
debian NetworkManager[693]: (wlan0): Activation: Stage 1 of 5 (Device
Prepare) started... Jun 11 09:46:45 debian NetworkManager[693]:
(wlan0): device state change: disconnected -> prepare (reason 'none')
[30 40 0] Jun 11 09:46:45 debian NetworkManager[693]: NetworkManager
state is now CONNECTING Jun 11 09:46:45 debian NetworkManager[693]:
(wlan0): Activation: Stage 2 of 5 (Device Configure) scheduled... Jun
11 09:46:45 debian NetworkManager[693]: (wlan0): Activation: Stage 1
of 5 (Device Prepare) complete. Jun 11 09:46:45 debian
NetworkManager[693]: (wlan0): Activation: Stage 2 of 5 (Device
Configure) starting... Jun 11 09:46:45 debian NetworkManager[693]:
(wlan0): device state change: prepare -> config (reason 'none') [40 50
0] Jun 11 09:46:45 debian NetworkManager[693]: (wlan0): Activation:
(wifi) access point 'DockerEng_Slow' has security, but secrets are
required. Jun 11 09:46:45 debian NetworkManager[693]: (wlan0): device
state change: config -> need-auth (reason 'none') [50 60 0] Jun 11
09:46:45 debian NetworkManager[693]: (wlan0): Activation: Stage 2 of 5
(Device Configure) complete. Jun 11 09:46:45 debian
NetworkManager[693]: (wlan0): Activation: Stage 1 of 5 (Device
Prepare) scheduled... Jun 11 09:46:45 debian NetworkManager[693]:
(wlan0): Activation: Stage 1 of 5 (Device Prepare) started... Jun 11
09:46:45 debian NetworkManager[693]: (wlan0): device state change:
need-auth -> prepare (reason 'none') [60 40 0] Jun 11 09:46:45 debian
NetworkManager[693]: (wlan0): Activation: Stage 2 of 5 (Device
Configure) scheduled... Jun 11 09:46:45 debian NetworkManager[693]:
(wlan0): Activation: Stage 1 of 5 (Device Prepare) complete. Jun 11
09:46:45 debian NetworkManager[693]: (wlan0): Activation: Stage 2 of 5
(Device Configure) starting... Jun 11 09:46:45 debian
NetworkManager[693]: (wlan0): device state change: prepare -> config
(reason 'none') [40 50 0] Jun 11 09:46:45 debian NetworkManager[693]:
(wlan0): Activation: (wifi) connection 'DockerEng_Slow' has security,
and secrets exist. No new secrets needed. Jun 11 09:46:45 debian
NetworkManager[693]: Config: added 'ssid' value 'DockerEng_Slow' Jun
11 09:46:45 debian NetworkManager[693]: Config: added 'scan_ssid'
value '1' Jun 11 09:46:45 debian NetworkManager[693]: Config: added
'key_mgmt' value 'WPA-PSK' Jun 11 09:46:45 debian NetworkManager[693]:
Config: added 'auth_alg' value 'OPEN' Jun 11 09:46:45 debian
NetworkManager[693]: Config: added 'psk' value '' Jun 11 09:46:45
debian NetworkManager[693]: (wlan0): Activation: Stage 2 of 5 (Device
Configure) complete. Jun 11 09:46:45 debian NetworkManager[693]:
Config: set interface ap_scan to 1 Jun 11 09:46:45 debian
NetworkManager[693]: (wlan0): supplicant interface state: inactive ->
associating Jun 11 09:46:45 debian NetworkManager[693]: (wlan0):
supplicant interface state: associating -> associated Jun 11 09:46:45
debian NetworkManager[693]: (wlan0): supplicant interface state:
associated -> 4-way handshake Jun 11 09:46:45 debian
NetworkManager[693]: (wlan0): supplicant interface state: 4-way
handshake -> completed Jun 11 09:46:45 debian NetworkManager[693]:
(wlan0): Activation: (wifi) Stage 2 of 5 (Device Configure)
successful. Connected to wireless network 'DockerEng_Slow'. Jun 11
09:46:45 debian NetworkManager[693]: (wlan0): Activation: Stage 3 of 5
(IP Configure Start) scheduled. Jun 11 09:46:45 debian
NetworkManager[693]: (wlan0): Activation: Stage 3 of 5 (IP Configure
Start) started... Jun 11 09:46:45 debian NetworkManager[693]: (wlan0):
device state change: config -> ip-config (reason 'none') [50 70 0] Jun
11 09:46:45 debian NetworkManager[693]: Activation (wlan0) Beginning
DHCPv4 transaction (timeout in 45 seconds) Jun 11 09:46:45 debian
NetworkManager[693]: dhclient started with pid 5243 Jun 11 09:46:45
debian NetworkManager[693]: (wlan0): Activation: Stage 3 of 5 (IP
Configure Start) complete. Jun 11 09:46:45 debian dhclient[5243]:
DHCPREQUEST on wlan0 to 255.255.255.255 port 67 Jun 11 09:46:45 debian
dhclient[5243]: DHCPACK from 192.168.200.1 Jun 11 09:46:45 debian
NetworkManager[693]: address 192.168.200.133 Jun 11 09:46:45 debian
NetworkManager[693]: plen 22 (255.255.252.0) Jun 11 09:46:45 debian
NetworkManager[693]: gateway 192.168.200.1 Jun 11 09:46:45 debian
NetworkManager[693]: server identifier 192.168.200.1 Jun 11 09:46:45
debian NetworkManager[693]: lease time 86400 Jun 11 09:46:45 debian
NetworkManager[693]: nameserver '208.67.222.222' Jun 11 09:46:45
debian NetworkManager[693]: nameserver '208.67.220.220' Jun 11
09:46:45 debian NetworkManager[693]: domain search 'docker.com.' Jun
11 09:46:45 debian NetworkManager[693]: domain search 'docker.io.' Jun
11 09:46:45 debian NetworkManager[693]: domain search 'dckr.io.' Jun
11 09:46:45 debian NetworkManager[693]: (wlan0): DHCPv4 state changed
unknown -> bound Jun 11 09:46:45 debian NetworkManager[693]: (wlan0):
Activation: Stage 5 of 5 (IPv4 Configure Commit) scheduled... Jun 11
09:46:45 debian NetworkManager[693]: (wlan0): Activation: Stage 5 of 5
(IPv4 Commit) started... Jun 11 09:46:45 debian NetworkManager[693]:
(wlan0): device state change: ip-config -> ip-check (reason 'none')
[70 80 0] Jun 11 09:46:45 debian NetworkManager[693]: (wlan0):
Activation: Stage 5 of 5 (IPv4 Commit) complete. Jun 11 09:46:45
debian NetworkManager[693]: (wlan0): device state change: ip-check ->
secondaries (reason 'none') [80 90 0] Jun 11 09:46:45 debian
NetworkManager[693]: (wlan0): device state change: secondaries ->
activated (reason 'none') [90 100 0] Jun 11 09:46:45 debian
NetworkManager[693]: NetworkManager state is now CONNECTED_LOCAL Jun
11 09:46:45 debian dhclient[5243]: bound to 192.168.200.133 -- renewal
in 33678 seconds. Jun 11 09:46:45 debian NetworkManager[693]:
NetworkManager state is now CONNECTED_GLOBAL Jun 11 09:46:45 debian
NetworkManager[693]: Policy set 'DockerEng_Slow' (wlan0) as default
for IPv4 routing and DNS. Jun 11 09:46:45 debian NetworkManager[693]:
(wlan0): Activation: successful, device activated. Jun 11 09:47:17
debian NetworkManager[693]: (wlan0): Activation: Stage 4 of 5 (IPv6
Configure Timeout) scheduled... Jun 11 09:47:17 debian
NetworkManager[693]: (wlan0): Activation: Stage 4 of 5 (IPv6 Configure
Timeout) started... Jun 11 09:47:17 debian NetworkManager[693]:
(wlan0): Activation: Stage 4 of 5 (IPv6 Configure Timeout) complete.

On Thu, Jun 11, 2015 at 9:44 AM, Jessica Frazelle <me at jessfraz.com> wrote:
>  systemd-inhibit
>      Who: NetworkManager (UID 0/root, PID 693/NetworkManager)
>     What: sleep
>      Why: NetworkManager needs to turn off networks
>     Mode: delay
>
> 1 inhibitors listed.
>
> On Thu, Jun 11, 2015 at 3:16 AM, Michael Biebl <biebl at debian.org> wrote:
>> Control: tags -1 moreinfo
>>
>> Am 11.06.2015 um 02:13 schrieb jessie:
>>
>>> And the output of the systemd-logind.service, as you can see it fires `suspend` on the first lid close but never on any after the very first lid close, it is very odd, it clearly sees the event:
>>>
>>> ```
>>> -- Logs begin at Wed 2015-06-10 16:49:40 PDT, end at Wed 2015-06-10 17:05:35 PDT. --
>>> Jun 10 16:49:41 debian systemd[1]: Starting Login Service...
>>> Jun 10 16:49:41 debian systemd-logind[559]: New seat seat0.
>>> Jun 10 16:49:41 debian systemd-logind[559]: Watching system buttons on /dev/input/event4 (Power But
>>> Jun 10 16:49:41 debian systemd-logind[559]: Watching system buttons on /dev/input/event9 (Video Bus
>>> Jun 10 16:49:41 debian systemd[1]: Started Login Service.
>>> Jun 10 16:49:41 debian systemd-logind[559]: Watching system buttons on /dev/input/event2 (Power But
>>> Jun 10 16:49:41 debian systemd-logind[559]: Watching system buttons on /dev/input/event1 (Lid Switc
>>> Jun 10 16:49:41 debian systemd-logind[559]: Watching system buttons on /dev/input/event3 (Sleep But
>>> Jun 10 16:49:47 debian systemd-logind[559]: New session 1 of user jessie.
>>> Jun 10 16:52:18 debian systemd-logind[559]: Lid closed.
>>> Jun 10 16:52:18 debian systemd-logind[559]: Suspending...
>>> Jun 10 16:52:31 debian systemd-logind[559]: Lid opened.
>>> Jun 10 16:52:31 debian systemd-logind[559]: Operation finished.
>>> Jun 10 16:54:44 debian systemd-logind[559]: Lid closed.
>>> Jun 10 16:54:57 debian systemd-logind[559]: Lid opened.
>>> Jun 10 16:56:48 debian systemd-logind[559]: Lid closed.
>>> Jun 10 16:56:51 debian systemd-logind[559]: Lid opened.
>>> Jun 10 16:56:57 debian systemd-logind[559]: Lid closed.
>>> Jun 10 16:57:06 debian systemd-logind[559]: Lid opened.
>>> Jun 10 16:58:33 debian systemd-logind[559]: Lid closed.
>>> Jun 10 16:58:39 debian systemd-logind[559]: Lid opened.
>>
>> What's the output of systemd-inhibit?
>>
>> Maybe some application is block the suspend request.
>>
>> --
>> Why is it that all of the instruments seeking intelligent life in the
>> universe are pointed away from Earth?
>>
>
>
>
> --
>
>
> Jessie Frazelle
> 4096R / D4C4 DD60 0D66 F65A 8EFC  511E 18F3 685C 0022 BFF3
> pgp.mit.edu



-- 


Jessie Frazelle
4096R / D4C4 DD60 0D66 F65A 8EFC  511E 18F3 685C 0022 BFF3
pgp.mit.edu