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)
