Bug#826378: linux: System immediately wakes up after suspend, faulty wake-on-lan on 8086:1502 e1000e NIC.
Source: linux
Severity: normal
Dear Maintainer, my system wakes up immediately after gone to suspend.
It seems to be caused by faulty e1000e wake-on-lan on 8086:1502 NIC. I can reproduce it
when any wol state is set on NIC except 'd'. Cable is not plugged.
can be worked around by either:
echo GLAN | sudo tee /proc/acpi/wakeup
or
sudo ethtool -s eno1 wol d
################################
Jun 05 07:39:18 hostname systemd-logind[899]: Lid closed.
Jun 05 07:39:18 hostname kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Jun 05 07:39:18 hostname kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Jun 05 07:39:18 hostname kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Jun 05 07:39:18 hostname kernel: pci_bus 0000:01: Allocating resources
Jun 05 07:39:18 hostname kernel: pci_bus 0000:02: Allocating resources
Jun 05 07:39:18 hostname kernel: pci_bus 0000:03: Allocating resources
Jun 05 07:39:18 hostname kernel: pci_bus 0000:07: Allocating resources
Jun 05 07:39:18 hostname kernel: pci_bus 0000:0b: Allocating resources
Jun 05 07:39:18 hostname kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Jun 05 07:39:18 hostname kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Jun 05 07:39:18 hostname kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Jun 05 07:39:18 hostname kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Jun 05 07:39:18 hostname kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Jun 05 07:39:18 hostname NetworkManager[972]: <info> [1465101558.8718] manager: sleep requested (sleeping: no enabled: yes)
Jun 05 07:39:18 hostname NetworkManager[972]: <info> [1465101558.8719] manager: sleeping...
Jun 05 07:39:18 hostname NetworkManager[972]: <info> [1465101558.8719] device (wlp2s0b1): state change: activated -> unmanaged (reason 'sleeping') [100 10 37]
Jun 05 07:39:18 hostname NetworkManager[972]: <info> [1465101558.9041] dhcp4 (wlp2s0b1): canceled DHCP transaction, DHCP client pid 12403
Jun 05 07:39:18 hostname NetworkManager[972]: <info> [1465101558.9042] dhcp4 (wlp2s0b1): state changed bound -> done
Jun 05 07:39:18 hostname avahi-daemon[923]: Withdrawing address record for fe80::7218:8bff:fe13:331d on wlp2s0b1.
Jun 05 07:39:18 hostname avahi-daemon[923]: Leaving mDNS multicast group on interface wlp2s0b1.IPv6 with address fe80::7218:8bff:fe13:331d.
Jun 05 07:39:18 hostname kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: arp filtering: 0 addresses (implement)
Jun 05 07:39:18 hostname kernel: wlp2s0b1: deauthenticating from XX:XX:XX:XX:XX:XX by local choice (Reason: 3=DEAUTH_LEAVING)
Jun 05 07:39:18 hostname avahi-daemon[923]: Interface wlp2s0b1.IPv6 no longer relevant for mDNS.
Jun 05 07:39:18 hostname avahi-daemon[923]: Withdrawing address record for X.X.X.X on wlp2s0b1.
Jun 05 07:39:18 hostname avahi-daemon[923]: Leaving mDNS multicast group on interface wlp2s0b1.IPv4 with address X.X.X.X.
Jun 05 07:39:18 hostname kernel: brcmsmac bcma0:1: brcmsmac: brcms_ops_bss_info_changed: disassociated
Jun 05 07:39:18 hostname kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement)
Jun 05 07:39:18 hostname wpa_supplicant[2555]: wlp2s0b1: CTRL-EVENT-DISCONNECTED bssid=XX:XX:XX:XX:XX:XX reason=3 locally_generated=1
Jun 05 07:39:18 hostname avahi-daemon[923]: Interface wlp2s0b1.IPv4 no longer relevant for mDNS.
Jun 05 07:39:18 hostname dnsmasq[4448]: setting upstream servers from DBus
Jun 05 07:39:18 hostname NetworkManager[972]: <info> [1465101558.9652] manager: NetworkManager state is now ASLEEP
Jun 05 07:39:18 hostname kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0b1: link is not ready
Jun 05 07:39:19 hostname dbus[953]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
Jun 05 07:39:19 hostname systemd[1]: Reached target Sleep.
Jun 05 07:39:19 hostname systemd[1]: Starting Suspend...
Jun 05 07:39:19 hostname systemd[1]: Starting Network Manager Script Dispatcher Service...
Jun 05 07:39:19 hostname systemd-sleep[18745]: Suspending system...
Jun 05 07:39:19 hostname dbus[953]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Jun 05 07:39:19 hostname systemd[1]: Started Network Manager Script Dispatcher Service.
Jun 05 07:39:19 hostname nm-dispatcher[18749]: req:1 'down' [wlp2s0b1]: new request (5 scripts)
Jun 05 07:39:19 hostname nm-dispatcher[18749]: req:1 'down' [wlp2s0b1]: start running ordered scripts...
Jun 05 07:39:19 hostname nm-dispatcher[18749]: Marking device and profile state
Jun 05 07:39:23 hostname kernel: PM: Syncing filesystems ... done.
Jun 05 07:39:23 hostname kernel: PM: Preparing system for sleep (mem)
Jun 05 07:39:23 hostname kernel: (NULL device *): firmware: direct-loading firmware brcm/bcm43xx_hdr-0.fw
Jun 05 07:39:23 hostname kernel: (NULL device *): firmware: direct-loading firmware brcm/bcm43xx-0.fw
Jun 05 07:39:23 hostname kernel: Freezing user space processes ... (elapsed 0.015 seconds) done.
Jun 05 07:39:23 hostname kernel: Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Jun 05 07:39:23 hostname kernel: PM: Suspending system (mem)
Jun 05 07:39:23 hostname kernel: Suspending console(s) (use no_console_suspend to debug)
Jun 05 07:39:23 hostname kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
Jun 05 07:39:23 hostname kernel: sd 0:0:0:0: [sda] Stopping disk
Jun 05 07:39:23 hostname kernel: e1000e: EEE TX LPI TIMER: 00000011
Jun 05 07:39:23 hostname kernel: PM: suspend of devices complete after 347.483 msecs
Jun 05 07:39:23 hostname kernel: PM: late suspend of devices complete after 15.394 msecs
Jun 05 07:39:23 hostname kernel: ehci-pci 0000:00:1d.0: System wakeup enabled by ACPI
Jun 05 07:39:23 hostname kernel: ehci-pci 0000:00:1a.0: System wakeup enabled by ACPI
Jun 05 07:39:23 hostname kernel: e1000e 0000:00:19.0: System wakeup enabled by ACPI
Jun 05 07:39:23 hostname kernel: xhci_hcd 0000:00:14.0: System wakeup enabled by ACPI
Jun 05 07:39:23 hostname kernel: PM: noirq suspend of devices complete after 17.699 msecs
Jun 05 07:39:23 hostname kernel: ACPI: Preparing to enter system sleep state S3
Jun 05 07:39:23 hostname kernel: ACPI : EC: EC stopped
Jun 05 07:39:23 hostname kernel: PM: Saving platform NVS memory
Jun 05 07:39:23 hostname kernel: Disabling non-boot CPUs ...
Jun 05 07:39:23 hostname kernel: Broke affinity for irq 28
Jun 05 07:39:23 hostname kernel: smpboot: CPU 1 is now offline
Jun 05 07:39:23 hostname kernel: Broke affinity for irq 16
Jun 05 07:39:23 hostname kernel: Broke affinity for irq 17
Jun 05 07:39:23 hostname kernel: Broke affinity for irq 21
Jun 05 07:39:23 hostname kernel: Broke affinity for irq 27
Jun 05 07:39:23 hostname kernel: Broke affinity for irq 28
Jun 05 07:39:23 hostname kernel: Broke affinity for irq 31
Jun 05 07:39:23 hostname kernel: smpboot: CPU 2 is now offline
Jun 05 07:39:23 hostname kernel: Broke affinity for irq 1
Jun 05 07:39:23 hostname kernel: Broke affinity for irq 8
Jun 05 07:39:23 hostname kernel: Broke affinity for irq 9
Jun 05 07:39:23 hostname kernel: Broke affinity for irq 12
Jun 05 07:39:23 hostname kernel: Broke affinity for irq 16
Jun 05 07:39:23 hostname kernel: Broke affinity for irq 17
Jun 05 07:39:23 hostname kernel: Broke affinity for irq 21
Jun 05 07:39:23 hostname kernel: Broke affinity for irq 23
Jun 05 07:39:23 hostname kernel: Broke affinity for irq 27
Jun 05 07:39:23 hostname kernel: Broke affinity for irq 28
Jun 05 07:39:23 hostname kernel: Broke affinity for irq 31
Jun 05 07:39:23 hostname kernel: smpboot: CPU 3 is now offline
Jun 05 07:39:23 hostname kernel: ACPI: Low-level resume complete
Jun 05 07:39:23 hostname kernel: ACPI : EC: EC started
Jun 05 07:39:23 hostname kernel: PM: Restoring platform NVS memory
Jun 05 07:39:23 hostname kernel: microcode: CPU0 microcode updated early to revision 0x1c, date = 2015-02-26
Jun 05 07:39:23 hostname kernel: Enabling non-boot CPUs ...
Jun 05 07:39:23 hostname kernel: x86: Booting SMP configuration:
Jun 05 07:39:23 hostname kernel: smpboot: Booting Node 0 Processor 1 APIC 0x2
Jun 05 07:39:23 hostname kernel: microcode: CPU1 microcode updated early to revision 0x1c, date = 2015-02-26
Jun 05 07:39:23 hostname kernel: cache: parent cpu1 should not be sleeping
Jun 05 07:39:23 hostname kernel: CPU1 is up
Jun 05 07:39:23 hostname kernel: smpboot: Booting Node 0 Processor 2 APIC 0x1
Jun 05 07:39:23 hostname kernel: cache: parent cpu2 should not be sleeping
Jun 05 07:39:23 hostname kernel: CPU2 is up
Jun 05 07:39:23 hostname kernel: smpboot: Booting Node 0 Processor 3 APIC 0x3
Jun 05 07:39:23 hostname kernel: cache: parent cpu3 should not be sleeping
Jun 05 07:39:23 hostname kernel: CPU3 is up
Jun 05 07:39:23 hostname kernel: ACPI: Waking up from system sleep state S3
Jun 05 07:39:23 hostname kernel: ehci-pci 0000:00:1a.0: System wakeup disabled by ACPI
Jun 05 07:39:23 hostname kernel: ehci-pci 0000:00:1d.0: System wakeup disabled by ACPI
Jun 05 07:39:23 hostname kernel: xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI
Jun 05 07:39:23 hostname kernel: PM: noirq resume of devices complete after 16.516 msecs
Jun 05 07:39:23 hostname kernel: PM: early resume of devices complete after 0.370 msecs
Jun 05 07:39:23 hostname kernel: sd 0:0:0:0: [sda] Starting disk
Jun 05 07:39:23 hostname kernel: e1000e 0000:00:19.0: System wakeup disabled by ACPI
Jun 05 07:39:23 hostname kernel: rtc_cmos 00:02: System wakeup disabled by ACPI
Jun 05 07:39:23 hostname kernel: ata6: SATA link down (SStatus 0 SControl 300)
Jun 05 07:39:23 hostname kernel: ata5: SATA link down (SStatus 0 SControl 300)
Jun 05 07:39:23 hostname kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Jun 05 07:39:23 hostname kernel: ata2.00: configured for UDMA/100
Jun 05 07:39:23 hostname kernel: PM: resume of devices complete after 519.314 msecs
Jun 05 07:39:23 hostname kernel: PM: Finishing wakeup.
Jun 05 07:39:23 hostname kernel: Restarting tasks ... done.
Jun 05 07:39:23 hostname systemd[1]: Time has been changed
Jun 05 07:39:23 hostname systemd[1]: apt-daily.timer: Adding 5h 46min 42.569185s random time.
Jun 05 07:39:23 hostname systemd[4571]: Time has been changed
Jun 05 07:39:23 hostname kasper-net-tweaks[18866]: doing nothing
Jun 05 07:39:24 hostname kernel: ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
Jun 05 07:39:24 hostname kernel: ata1.00: ACPI cmd ef/10:06:00:00:00:00 (SET FEATURES) succeeded
Jun 05 07:39:24 hostname kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out
Jun 05 07:39:24 hostname kernel: ata1.00: ACPI cmd b1/c1:00:00:00:00:00 (DEVICE CONFIGURATION OVERLAY) filtered out
Jun 05 07:39:24 hostname kernel: ata1.00: ACPI cmd 00/00:00:00:00:00:a0 (NOP) rejected by device (Stat=0x51 Err=0x04)
Jun 05 07:39:24 hostname kernel: ata1.00: supports DRM functions and may not be fully accessible
Jun 05 07:39:24 hostname kernel: ata1.00: disabling queued TRIM support
Jun 05 07:39:24 hostname kernel: ata1.00: ACPI cmd ef/10:06:00:00:00:00 (SET FEATURES) succeeded
Jun 05 07:39:24 hostname kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out
Jun 05 07:39:24 hostname kernel: ata1.00: ACPI cmd b1/c1:00:00:00:00:00 (DEVICE CONFIGURATION OVERLAY) filtered out
Jun 05 07:39:24 hostname kernel: ata1.00: ACPI cmd 00/00:00:00:00:00:a0 (NOP) rejected by device (Stat=0x51 Err=0x04)
Jun 05 07:39:24 hostname kernel: ata1.00: supports DRM functions and may not be fully accessible
Jun 05 07:39:24 hostname kernel: ata1.00: disabling queued TRIM support
Jun 05 07:39:24 hostname kernel: ata1.00: configured for UDMA/133
Jun 05 07:39:24 hostname kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Jun 05 07:39:24 hostname kernel: ata1.00: Enabling discard_zeroes_data
Jun 05 07:39:24 hostname kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Jun 05 07:39:24 hostname kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Jun 05 07:39:24 hostname kernel: pci_bus 0000:01: Allocating resources
Jun 05 07:39:24 hostname kernel: pci_bus 0000:02: Allocating resources
Jun 05 07:39:24 hostname kernel: pci_bus 0000:03: Allocating resources
Jun 05 07:39:24 hostname kernel: pci_bus 0000:07: Allocating resources
Jun 05 07:39:24 hostname kernel: pci_bus 0000:0b: Allocating resources
Jun 05 07:39:24 hostname kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Jun 05 07:39:24 hostname kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Jun 05 07:39:24 hostname kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Jun 05 07:39:24 hostname kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Jun 05 07:39:24 hostname kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Jun 05 07:39:24 hostname kernel: acpi PNP0401:00: Already enumerated
Jun 05 07:39:24 hostname ntpd[1138]: Deleting interface #17 wlp2s0b1, X.X.X.X#123, interface stats: received=62, sent=62, dropped=0, active_time=666 secs
Jun 05 07:39:24 hostname ntpd[1138]: X.X.X.X local addr X.X.X.X -> <null>
Jun 05 07:39:24 hostname ntpd[1138]: X.X.X.X local addr X.X.X.X -> <null>
Jun 05 07:39:24 hostname ntpd[1138]: X.X.X.X local addr X.X.X.X -> <null>
Jun 05 07:39:24 hostname ntpd[1138]: X.X.X.X local addr X.X.X.X -> <null>
Jun 05 07:39:24 hostname ntpd[1138]: X.X.X.X local addr X.X.X.X -> <null>
Jun 05 07:39:24 hostname ntpd[1138]: X.X.X.X local addr X.X.X.X -> <null>
Jun 05 07:39:24 hostname ntpd[1138]: X.X.X.X local addr X.X.X.X -> <null>
Jun 05 07:39:24 hostname ntpd[1138]: X.X.X.X local addr X.X.X.X -> <null>
Jun 05 07:39:24 hostname ntpd[1138]: Deleting interface #18 wlp2s0b1, fe80::7218:8bff:fe13:331d%3#123, interface stats: received=0, sent=0, dropped=0, active_time=666 secs
Jun 05 07:39:24 hostname kernel: acpi PNP0501:00: Still not present
Jun 05 07:39:24 hostname systemd-sleep[18745]: System resumed.
Jun 05 07:39:24 hostname systemd-sleep[18745]: /dev/sda:
Jun 05 07:39:24 hostname systemd-sleep[18745]: setting Advanced Power Management level to 0xfe (254)
Jun 05 07:39:24 hostname systemd-sleep[18745]: APM_level = 254
Jun 05 07:39:24 hostname systemd[1]: Started Suspend.
Jun 05 07:39:24 hostname systemd[1]: sleep.target: Unit not needed anymore. Stopping.
Jun 05 07:39:24 hostname systemd[1]: Stopped target Sleep.
Jun 05 07:39:24 hostname systemd[1]: Reached target Suspend.
Jun 05 07:39:24 hostname systemd[1]: suspend.target: Unit is bound to inactive unit systemd-suspend.service. Stopping, too.
Jun 05 07:39:24 hostname systemd[1]: Stopped target Suspend.
Jun 05 07:39:24 hostname systemd-logind[899]: Operation 'sleep' finished.
Jun 05 07:39:24 hostname NetworkManager[972]: <info> [1465101564.2325] manager: wake requested (sleeping: yes enabled: yes)
Jun 05 07:39:24 hostname NetworkManager[972]: <info> [1465101564.2326] manager: waking up...
Jun 05 07:39:24 hostname NetworkManager[972]: <info> [1465101564.2326] device (eno1): state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
Jun 05 07:39:24 hostname systemd[1]: Started Run anacron jobs at resume.
Jun 05 07:39:24 hostname systemd[1]: Started Run anacron jobs.
Jun 05 07:39:24 hostname anacron[18911]: Anacron 2.3 started on 2016-06-05
Jun 05 07:39:24 hostname anacron[18911]: Normal exit (0 jobs run)
Jun 05 07:39:24 hostname NetworkManager[972]: <info> [1465101564.3729] device (eno1): state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Jun 05 07:39:24 hostname kernel: e1000e: eno1 NIC Link is Down
Jun 05 07:39:24 hostname kernel: IPv6: ADDRCONF(NETDEV_UP): eno1: link is not ready
Jun 05 07:39:24 hostname NetworkManager[972]: <info> [1465101564.6286] device (wlp2s0b1): state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Jun 05 07:39:24 hostname kernel: IPv6: ADDRCONF(NETDEV_UP): eno1: link is not ready
Jun 05 07:39:24 hostname kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0b1: link is not ready
Jun 05 07:39:24 hostname NetworkManager[972]: <info> [1465101564.8053] manager: NetworkManager state is now DISCONNECTED
Jun 05 07:39:24 hostname kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement)
Jun 05 07:39:24 hostname kernel: brcmsmac bcma0:1: brcms_ops_config: change power-save mode: false (implement)
Jun 05 07:39:24 hostname kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0b1: link is not ready
Jun 05 07:39:24 hostname NetworkManager[972]: <info> [1465101564.8466] sup-iface[0x24c6f80,wlp2s0b1]: supports 4 scan SSIDs
Jun 05 07:39:24 hostname NetworkManager[972]: <info> [1465101564.8473] device (wlp2s0b1): supplicant interface state: starting -> ready
Jun 05 07:39:24 hostname NetworkManager[972]: <info> [1465101564.8474] device (wlp2s0b1): state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
Jun 05 07:39:24 hostname kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0b1: link is not ready
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.6836] device (wlp2s0b1): supplicant interface state: ready -> inactive
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.6846] policy: auto-activating connection 'wlanssid'
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.6855] device (wlp2s0b1): Activation: starting connection 'wlanssid' (86cc34b6-46a0-4211-8aed-454e6481fd74)
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.6856] device (wlp2s0b1): state change: disconnected -> prepare (reason 'none') [30 40 0]
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.6857] manager: NetworkManager state is now CONNECTING
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.6861] device (wlp2s0b1): state change: prepare -> config (reason 'none') [40 50 0]
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.6863] device (wlp2s0b1): Activation: (wifi) access point 'wlanssid' has security, but secrets are required.
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.6863] device (wlp2s0b1): state change: config -> need-auth (reason 'none') [50 60 0]
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.6890] device (wlp2s0b1): state change: need-auth -> prepare (reason 'none') [60 40 0]
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.6894] device (wlp2s0b1): state change: prepare -> config (reason 'none') [40 50 0]
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.6896] device (wlp2s0b1): Activation: (wifi) connection 'wlanssid' has security, and secrets exist. No new secrets needed.
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.6896] Config: added 'ssid' value 'wlanssid'
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.6896] Config: added 'scan_ssid' value '1'
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.6896] Config: added 'key_mgmt' value 'WPA-PSK'
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.6896] Config: added 'psk' value '<omitted>'
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.6935] sup-iface[0x24c6f80,wlp2s0b1]: config: set interface ap_scan to 1
Jun 05 07:39:25 hostname wpa_supplicant[2555]: wlp2s0b1: SME: Trying to authenticate with XX:XX:XX:XX:XX:XX (SSID='wlanssid' freq=2412 MHz)
Jun 05 07:39:25 hostname wpa_supplicant[2555]: wlp2s0b1: Trying to associate with XX:XX:XX:XX:XX:XX (SSID='wlanssid' freq=2412 MHz)
Jun 05 07:39:25 hostname kernel: wlp2s0b1: authenticate with XX:XX:XX:XX:XX:XX
Jun 05 07:39:25 hostname kernel: wlp2s0b1: send auth to XX:XX:XX:XX:XX:XX (try 1/3)
Jun 05 07:39:25 hostname kernel: wlp2s0b1: authenticated
Jun 05 07:39:25 hostname kernel: wlp2s0b1: associate with XX:XX:XX:XX:XX:XX (try 1/3)
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.7056] device (wlp2s0b1): supplicant interface state: inactive -> associating
Jun 05 07:39:25 hostname kernel: wlp2s0b1: RX AssocResp from XX:XX:XX:XX:XX:XX (capab=0x411 status=0 aid=1)
Jun 05 07:39:25 hostname wpa_supplicant[2555]: wlp2s0b1: Associated with XX:XX:XX:XX:XX:XX
Jun 05 07:39:25 hostname kernel: brcmsmac bcma0:1: brcmsmac: brcms_ops_bss_info_changed: associated
Jun 05 07:39:25 hostname kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: true (implement)
Jun 05 07:39:25 hostname kernel: wlp2s0b1: associated
Jun 05 07:39:25 hostname kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlp2s0b1: link becomes ready
Jun 05 07:39:25 hostname kernel: brcmsmac bcma0:1: wl0: brcms_c_d11hdrs_mac80211: txop exceeded phylen 159/256 dur 1778/1472
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.7133] device (wlp2s0b1): supplicant interface state: associating -> 4-way handshake
Jun 05 07:39:25 hostname wpa_supplicant[2555]: wlp2s0b1: WPA: Key negotiation completed with XX:XX:XX:XX:XX:XX [PTK=CCMP GTK=TKIP]
Jun 05 07:39:25 hostname wpa_supplicant[2555]: wlp2s0b1: CTRL-EVENT-CONNECTED - Connection to XX:XX:XX:XX:XX:XX completed [id=0 id_str=]
Jun 05 07:39:25 hostname kernel: brcmsmac bcma0:1: wl0: brcms_c_d11hdrs_mac80211: txop exceeded phylen 137/256 dur 1602/1472
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.7198] device (wlp2s0b1): supplicant interface state: 4-way handshake -> completed
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.7198] device (wlp2s0b1): Activation: (wifi) Stage 2 of 5 (Device Configure) successful. Connected to wireless network 'wlanssid'.
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.7199] device (wlp2s0b1): state change: config -> ip-config (reason 'none') [50 70 0]
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.7202] dhcp4 (wlp2s0b1): activation: beginning transaction (timeout in 45 seconds)
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.7215] dhcp4 (wlp2s0b1): dhclient started with pid 19015
Jun 05 07:39:25 hostname dhclient[19015]: DHCPREQUEST of X.X.X.X on wlp2s0b1 to X.X.X.X port 67
Jun 05 07:39:25 hostname dhclient[19015]: DHCPACK of X.X.X.X from X.X.X.X
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.7852] address X.X.X.X
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.7852] plen 24 (X.X.X.X)
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.7852] gateway X.X.X.X
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.7852] server identifier X.X.X.X
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.7852] lease time 259200
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.7853] hostname 'hostname'
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.7853] nameserver 'X.X.X.X'
Jun 05 07:39:25 hostname avahi-daemon[923]: Joining mDNS multicast group on interface wlp2s0b1.IPv4 with address X.X.X.X.
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.7853] domain name 'spacelair'
Jun 05 07:39:25 hostname avahi-daemon[923]: New relevant interface wlp2s0b1.IPv4 for mDNS.
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.7853] domain search 'spacelair.'
Jun 05 07:39:25 hostname avahi-daemon[923]: Registering new address record for X.X.X.X on wlp2s0b1.IPv4.
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.7853] domain search 'bugville.'
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.7853] domain search 'brickhouse.'
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.7853] dhcp4 (wlp2s0b1): state changed unknown -> bound
Jun 05 07:39:25 hostname kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: arp filtering: 1 addresses (implement)
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.7867] device (wlp2s0b1): state change: ip-config -> ip-check (reason 'none') [70 80 0]
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.7871] device (wlp2s0b1): state change: ip-check -> secondaries (reason 'none') [80 90 0]
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.7874] device (wlp2s0b1): state change: secondaries -> activated (reason 'none') [90 100 0]
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.7876] manager: NetworkManager state is now CONNECTED_LOCAL
Jun 05 07:39:25 hostname dhclient[19015]: bound to X.X.X.X -- renewal in 98548 seconds.
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.8060] manager: NetworkManager state is now CONNECTED_GLOBAL
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.8061] policy: set 'wlanssid' (wlp2s0b1) as default for IPv4 routing and DNS
Jun 05 07:39:25 hostname NetworkManager[972]: <info> [1465101565.8063] device (wlp2s0b1): Activation: successful, device activated.
Jun 05 07:39:25 hostname dnsmasq[4448]: setting upstream servers from DBus
Jun 05 07:39:25 hostname dnsmasq[4448]: using nameserver X.X.X.X#53
Jun 05 07:39:25 hostname nm-dispatcher[18749]: req:2 'up' [wlp2s0b1]: new request (5 scripts)
Jun 05 07:39:25 hostname nm-dispatcher[18749]: req:2 'up' [wlp2s0b1]: start running ordered scripts...
Jun 05 07:39:25 hostname nm-dispatcher[18749]: Marking device and profile state
Jun 05 07:39:25 hostname org.freedesktop.Notifications[4620]: Could not load icon: 'nm-signal-75'
Jun 05 07:39:25 hostname org.freedesktop.Notifications[4620]: Could not load icon: 'nm-signal-75'
Jun 05 07:39:25 hostname systemd[1]: Reloading OpenBSD Secure Shell server.
Jun 05 07:39:25 hostname sshd[984]: Received SIGHUP; restarting.
Jun 05 07:39:25 hostname systemd[1]: Reloaded OpenBSD Secure Shell server.
Jun 05 07:39:25 hostname sshd[984]: Server listening on X.X.X.X port 12323.
Jun 05 07:39:25 hostname sshd[984]: Server listening on :: port 12323.
Jun 05 07:39:25 hostname kasper-net-tweaks[19138]: doing nothing
Jun 05 07:39:27 hostname ntpd[1138]: Listen normally on 19 wlp2s0b1 X.X.X.X:123
Jun 05 07:39:27 hostname ntpd[1138]: bind(24) AF_INET6 fe80::7218:8bff:fe13:331d%3#123 flags 0x11 failed: Cannot assign requested address
Jun 05 07:39:27 hostname ntpd[1138]: unable to create socket on wlp2s0b1 (20) for fe80::7218:8bff:fe13:331d%3#123
Jun 05 07:39:27 hostname ntpd[1138]: failed to init interface for address fe80::7218:8bff:fe13:331d%3
Jun 05 07:39:27 hostname avahi-daemon[923]: Joining mDNS multicast group on interface wlp2s0b1.IPv6 with address fe80::7218:8bff:fe13:331d.
Jun 05 07:39:27 hostname avahi-daemon[923]: New relevant interface wlp2s0b1.IPv6 for mDNS.
Jun 05 07:39:27 hostname avahi-daemon[923]: Registering new address record for fe80::7218:8bff:fe13:331d on wlp2s0b1.*.
Jun 05 07:39:29 hostname ntpd[1138]: Listen normally on 21 wlp2s0b1 [fe80::7218:8bff:fe13:331d%3]:123
Jun 05 07:39:29 hostname systemd-logind[899]: Lid opened.
Jun 05 07:39:29 hostname kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Jun 05 07:39:29 hostname kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Jun 05 07:39:29 hostname kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Jun 05 07:39:29 hostname kernel: pci_bus 0000:01: Allocating resources
Jun 05 07:39:29 hostname kernel: pci_bus 0000:02: Allocating resources
Jun 05 07:39:29 hostname kernel: pci_bus 0000:03: Allocating resources
Jun 05 07:39:29 hostname kernel: pci_bus 0000:07: Allocating resources
Jun 05 07:39:29 hostname kernel: pci_bus 0000:0b: Allocating resources
Jun 05 07:39:29 hostname kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Jun 05 07:39:29 hostname kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Jun 05 07:39:29 hostname kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Jun 05 07:39:29 hostname kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Jun 05 07:39:29 hostname kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Jun 05 07:39:30 hostname wpa_supplicant[2555]: wlp2s0b1: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN
Jun 05 07:39:32 hostname ntpd[1138]: X.X.X.X local addr X.X.X.X -> <null>
################################
-- System Information:
Debian Release: stretch/sid
APT prefers testing
APT policy: (900, 'testing'), (400, 'unstable'), (300, 'experimental')
Architecture: amd64 (x86_64)
Foreign Architectures: i386
Kernel: Linux 4.5.0-2-amd64 (SMP w/4 CPU cores)
Locale: LANG=ru_RU.UTF-8, LC_CTYPE=ru_RU.UTF-8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/dash
Init: systemd (via /run/systemd/system)
Reply to: