Aug 31 15:36:14 ripley systemd[1]: Starting Network Manager...
Aug 31 15:36:14 ripley wpa_supplicant[5474]: dbus: Unregister BSS object '/fi/w1/wpa_supplicant1/Interfaces/1/BSSs/0'
[...]
Aug 31 15:36:14 ripley wpa_supplicant[5474]: dbus: Unregister BSS object '/fi/w1/wpa_supplicant1/Interfaces/1/BSSs/1'
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Cancelling scan request
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Cancelling authentication timeout
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wlxf4f26d10c509: P2P: Disable P2P since removing the management interface is being removed
Aug 31 15:36:14 ripley wpa_supplicant[5474]: P2P: Stopping find
Aug 31 15:36:14 ripley wpa_supplicant[5474]: P2P: Clear timeout (state=IDLE)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: P2P: State IDLE -> IDLE
Aug 31 15:36:14 ripley wpa_supplicant[5474]: Remove interface wlxf4f26d10c509 from radio phy0
Aug 31 15:36:14 ripley wpa_supplicant[5474]: Remove radio phy0
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: deinit ifname=wlxf4f26d10c509 disabled_11b_rates=0
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Remove monitor interface: refcount=0
Aug 31 15:36:14 ripley wpa_supplicant[5474]: netlink: Operstate: ifindex=3 linkmode=0 (kernel-control), operstate=6 (IF_OPER_UP)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Set mode ifindex 3 iftype 2 (STATION)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Unsubscribe mgmt frames handle 0x8888ddd327f98429 (mode change)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: dbus: Unregister interface object '/fi/w1/wpa_supplicant1/Interfaces/1'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0667] monotonic timestamp started counting 1.839667331 seconds ago with an offset of 3998.0 seconds to CLOCK_BOOTTIME (local time is 2016-08-31 15:36:14)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.0667] NetworkManager (version 1.4.0) is starting...
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.0667] Read config: /etc/NetworkManager/NetworkManager.conf
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0667] CONFIG: config-data[0x13670e0]: 3 groups
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0668] CONFIG: 
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0668] CONFIG: [main]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0668] CONFIG:   plugins=ifupdown,keyfile
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0668] CONFIG: 
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0668] CONFIG: [ifupdown]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0668] CONFIG:   managed=false
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0668] CONFIG: 
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0668] CONFIG: [logging]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0669] CONFIG:   level=TRACE
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0669] CONFIG:   domains=ALL
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0669] config: state: successfully read state file "/var/lib/NetworkManager/NetworkManager.state"
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0669] WEXT support is enabled
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0671] auth[0x13531a0]: create auth-manager: polkit enabled
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0676] auth[0x13531a0]: set instance
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0676] setup NMAuthManager singleton (0x13531a0)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0685] bus-manager: setup NMBusManager singleton (0x136ca80)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.0710] manager[0x1378050]: monitoring kernel firmware directory '/lib/firmware'.
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.0710] monitoring ifupdown state file '/run/network/ifstate'.
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.0713] exported-object[0x7f35e4003f40]: export: "/org/freedesktop/NetworkManager/AgentManager"
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0715] create NMSessionMonitor singleton (0x1372920)
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.0715] ignoring notification for prop g-object-path on type NMAgentManager
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0715] create NMAgentManager singleton (0x7f35e4003f40)
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.0717] exported-object[0x1359a00]: export: "/org/freedesktop/NetworkManager/Settings"
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.0717] ignoring notification for prop g-object-path on type NMSettings
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0727] firewall[0x1382aa0]: firewall constructed (notrunning)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0727] create NMFirewallManager singleton (0x1382aa0)
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.0727] dns-mgr[0x1372020]: creating...
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.0728] dns-mgr[0x1372020]: init: dns=default, rc-manager=resolvconf
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0728] create NMDnsManager singleton (0x1372020)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.0738] rfkill0: found WiFi radio killswitch (at /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.7/2-1.7:1.0/ieee80211/phy0/rfkill0) (driver ath9k_htc)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0739] WiFi rfkill switch rfkill0 state now 1/0
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.0739] manager[0x1378050]: WiFi hardware radio set enabled
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.0739] manager[0x1378050]: WWAN hardware radio set enabled
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0740] manager: setup NMManager singleton (0x1378050)
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.0743] exported-object[0x1378050]: export: "/org/freedesktop/NetworkManager"
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.0743] ignoring notification for prop g-object-path on type NMManager
Aug 31 15:36:14 ripley systemd[1]: Started Network Manager.
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0759] platform-linux[0x1367190]: create (ignore netns, initial netns, use udev)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0760] platform-linux[0x1367190]: Netlink socket for events established: port=1996494184, fd=12
Aug 31 15:36:14 ripley dbus[645]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0760] setup NMPlatform singleton (0x1367190, NMLinuxPlatform)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0760] platform-linux: populate platform cache
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0762] platform-linux: kernel support for IFLA_INET6_ADDR_GEN_MODE detected
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.0762] platform-linux: event-notification: NEWLINK, seq 1: 1: lo <UP,LOWER_UP;loopback,up,running,lowerup> mtu 65536 arp 772 loopback? not-init addrgenmode eui64 addr 00:00:00:00:00:00 rx:1796,507619 tx:1796,507619
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0762] ethtool: Request failed: Operation not supported
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0763] platform: signal: link   added: 1: lo <UP,LOWER_UP;loopback,up,running,lowerup> mtu 65536 arp 772 loopback? not-init addrgenmode eui64 addr 00:00:00:00:00:00 driver unknown rx:1796,507619 tx:1796,507619
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.0764] platform-linux: event-notification: NEWLINK, seq 1: 2: eth0 <UP;broadcast,multicast,up> mtu 1500 arp 1 ethernet? not-init addrgenmode eui64 addr 50:46:5D:51:D5:9B rx:0,0 tx:0,0
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0765] platform: signal: link   added: 2: eth0 <UP;broadcast,multicast,up> mtu 1500 arp 1 ethernet? not-init addrgenmode eui64 addr 50:46:5D:51:D5:9B driver r8169 rx:0,0 tx:0,0
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.0766] platform-linux: event-notification: NEWLINK, seq 1: 3: wlxf4f26d10c509 <DOWN;broadcast,multicast> mtu 1500 arp 1 wifi? not-init addrgenmode eui64 addr F4:F2:6D:10:C5:09 rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0766] platform: signal: link   added: 3: wlxf4f26d10c509 <DOWN;broadcast,multicast> mtu 1500 arp 1 wifi? not-init addrgenmode eui64 addr F4:F2:6D:10:C5:09 driver ath9k_htc rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.0767] platform-linux: event-notification: NEWADDR, seq 2: 127.0.0.1/8 lft forever pref forever lifetime 1-0[4294967295,4294967295] dev 1 flags permanent src kernel
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0767] platform: signal: address 4   added: 127.0.0.1/8 lft forever pref forever lifetime 1-0[4294967295,4294967295] dev 1 flags permanent src kernel
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0767] platform-linux: support: kernel-extended-ifa-flags: supported
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.0768] platform-linux: event-notification: NEWADDR, seq 3: ::1/128 lft forever pref forever lifetime 1-0[4294967295,4294967295] dev 1 flags permanent src kernel
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0768] platform: signal: address 6   added: ::1/128 lft forever pref forever lifetime 1-0[4294967295,4294967295] dev 1 flags permanent src kernel
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.0768] platform-linux: event-notification: NEWROUTE, seq 4: ignore
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.0769] platform-linux: event-notification: NEWROUTE, seq 4: ignore
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.0769] platform-linux: event-notification: NEWROUTE, seq 4: ignore
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.0769] platform-linux: event-notification: NEWROUTE, seq 4: ignore
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.0769] platform-linux: event-notification: NEWROUTE, seq 5: ignore
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.0769] platform-linux: event-notification: NEWROUTE, seq 5: ignore
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.0769] platform-linux: event-notification: NEWROUTE, seq 5: ignore
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.0777] platform-linux: udev-add[eth0,2]: device added
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0778] platform: signal: link changed: 2: eth0 <UP;broadcast,multicast,up> mtu 1500 arp 1 ethernet? init addrgenmode eui64 addr 50:46:5D:51:D5:9B driver r8169 rx:0,0 tx:0,0
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.0779] platform-linux: udev-add[wlxf4f26d10c509,3]: device added
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0779] platform: signal: link changed: 3: wlxf4f26d10c509 <DOWN;broadcast,multicast> mtu 1500 arp 1 wifi? init addrgenmode eui64 addr F4:F2:6D:10:C5:09 driver ath9k_htc rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.0780] platform-linux: udev-add[lo,1]: device added
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0780] ethtool: Request failed: Operation not supported
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0780] platform: signal: link changed: 1: lo <UP,LOWER_UP;loopback,up,running,lowerup> mtu 65536 arp 772 loopback? init addrgenmode eui64 addr 00:00:00:00:00:00 driver unknown rx:1796,507619 tx:1796,507619
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0781] dispatcher: default script directory '/etc/NetworkManager/dispatcher.d' has scripts
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0781] dispatcher: pre-up script directory '/etc/NetworkManager/dispatcher.d/pre-up.d' has no scripts
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0782] dispatcher: pre-down script directory '/etc/NetworkManager/dispatcher.d/pre-down.d' has no scripts
Aug 31 15:36:14 ripley systemd[1]: Starting Network Manager Script Dispatcher Service...
Aug 31 15:36:14 ripley dbus[645]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Aug 31 15:36:14 ripley systemd[1]: Started Network Manager Script Dispatcher Service.
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0899] create SettingsPluginIfupdown singleton (0x139a350)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.0899] init!
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.0901]       interface-parser: parsing file /etc/network/interfaces
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.0901]       interface-parser: finished parsing file /etc/network/interfaces
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.0901] management mode: unmanaged
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.0905] devices added (path: /sys/devices/pci0000:00/0000:00:1c.4/0000:03:00.0/net/eth0, iface: eth0)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.0905] device added (path: /sys/devices/pci0000:00/0000:00:1c.4/0000:03:00.0/net/eth0, iface: eth0): no ifupdown configuration found.
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.0905] devices added (path: /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.7/2-1.7:1.0/net/wlxf4f26d10c509, iface: wlxf4f26d10c509)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.0905] device added (path: /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.7/2-1.7:1.0/net/wlxf4f26d10c509, iface: wlxf4f26d10c509): no ifupdown configuration found.
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.0905] devices added (path: /sys/devices/virtual/net/lo, iface: lo)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.0905] device added (path: /sys/devices/virtual/net/lo, iface: lo): no ifupdown configuration found.
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.0905] end _init.
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.0906] settings: loaded plugin ifupdown: (C) 2008 Canonical Ltd.  To report bugs please use the NetworkManager mailing list. (/usr/lib/x86_64-linux-gnu/NetworkManager/libnm-settings-plugin-ifupdown.so)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.0906] settings: loaded plugin keyfile: (c) 2007 - 2015 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list.
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.0906] (20554576) ... get_connections.
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.0906] (20554576) ... get_connections (managed=false): return empty list.
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0907] keyfile: loading from file "/etc/NetworkManager/system-connections/SpeakFriendAndEnter"...
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.0997] settings-connection[0x1359af0]: constructed (NMKeyfileConnection)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1000] settings-connection[0x1359af0,62264738-2059-42ce-8d14-fb17bda97211]: replace settings from connection 0x13a9fa0 (SpeakFriendAndEnter)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.1005] keyfile: new connection /etc/NetworkManager/system-connections/SpeakFriendAndEnter (62264738-2059-42ce-8d14-fb17bda97211,"SpeakFriendAndEnter")
[...]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1092] keyfile: loading from file "/etc/NetworkManager/system-connections/Ethernet"...
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1169] settings-connection[0x1359cd0]: constructed (NMKeyfileConnection)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1171] settings-connection[0x1359cd0,653120a3-afd8-4af7-a7b3-dbfd96e4e196]: replace settings from connection 0x13aa120 (Ethernet)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.1174] keyfile: new connection /etc/NetworkManager/system-connections/Ethernet (653120a3-afd8-4af7-a7b3-dbfd96e4e196,"Ethernet")
[...]
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.1336] exported-object[0x1359cd0]: export: "/org/freedesktop/NetworkManager/Settings/0"
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.1336] ignoring notification for prop g-object-path on type NMKeyfileConnection
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1338] ++ connection 'new connection' (0x1359cd0/NMKeyfileConnection): [/org/freedesktop/NetworkManager/Settings/0]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1339] ++ connection                [ 0x13b6950 ]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1339] ++ connection.id             = 'Ethernet'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1339] ++ connection.permissions    = []
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1339] ++ connection.secondaries    = []
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1339] ++ connection.timestamp      = 1443213088
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1339] ++ connection.type           = '802-3-ethernet'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1340] ++ connection.uuid           = '653120a3-afd8-4af7-a7b3-dbfd96e4e196'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1340] ++ 802-3-ethernet            [ 0x1372320 ]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1340] ++ 802-3-ethernet.duplex     = 'full'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1340] ++ 802-3-ethernet.mac-address-blacklist = []
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1340] ++ 802-3-ethernet.s390-options = ((GHashTable*) 0x13b3c00)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1340] ++ ipv4                      [ 0x13b6e20 ]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1341] ++ ipv4.addresses            = ((GPtrArray*) 0x13c4480)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1341] ++ ipv4.dns                  = [\"122.56.237.1\", \"210.55.111.1\"]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1341] ++ ipv4.dns-search           = []
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1341] ++ ipv4.gateway              = '192.168.1.254'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1341] ++ ipv4.method               = 'manual'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1341] ++ ipv4.routes               = ((GPtrArray*) 0x13b42c0)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1342] ++ ipv6                      [ 0x13b6d70 ]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1342] ++ ipv6.addr-gen-mode        = 0
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1342] ++ ipv6.addresses            = ((GPtrArray*) 0x13b4260)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1342] ++ ipv6.dns                  = []
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1342] ++ ipv6.dns-search           = []
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1342] ++ ipv6.ip6-privacy          = ((NMSettingIP6ConfigPrivacy) NM_SETTING_IP6_CONFIG_PRIVACY_PREFER_TEMP_ADDR)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1342] ++ ipv6.method               = 'ignore'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1343] ++ ipv6.routes               = ((GPtrArray*) 0x13b4240)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1350] ++ ipv6.routes               = ((GPtrArray*) 0x13bf8c0)
[...]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1362] ++ connection 'new connection' (0x1359af0/NMKeyfileConnection): [/org/freedesktop/NetworkManager/Settings/3]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1362] ++ connection                [ 0x1361c30 ]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1362] ++ connection.id             = 'SpeakFriendAndEnter'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1362] ++ connection.permissions    = []
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1362] ++ connection.secondaries    = []
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1363] ++ connection.timestamp      = 1472341722
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1363] ++ connection.type           = '802-11-wireless'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1363] ++ connection.uuid           = '62264738-2059-42ce-8d14-fb17bda97211'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1363] ++ 802-11-wireless           [ 0x13721e0 ]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1363] ++ 802-11-wireless.cloned-mac-address = 'permanent'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1363] ++ 802-11-wireless.mac-address-blacklist = []
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1363] ++ 802-11-wireless.mac-address-randomization = 1
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1364] ++ 802-11-wireless.mode      = 'infrastructure'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1364] ++ 802-11-wireless.seen-bssids = [\"EC:CB:30:60:58:32\"]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1364] ++ 802-11-wireless.ssid      = ((GBytes*) 0x1372950)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1364] ++ 802-11-wireless-security  [ 0x1361ce0 ]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1364] ++ 802-11-wireless-security.group = []
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1364] ++ 802-11-wireless-security.key-mgmt = 'wpa-psk'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1364] ++ 802-11-wireless-security.pairwise = []
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1364] ++ 802-11-wireless-security.proto = []
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1365] ++ 802-11-wireless-security.psk = ****
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1365] ++ ipv4                      [ 0x1361d90 ]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1365] ++ ipv4.addresses            = ((GPtrArray*) 0x13bf9a0)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1365] ++ ipv4.dns                  = [\"122.56.237.1\", \"210.55.111.1\"]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1365] ++ ipv4.dns-search           = []
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1365] ++ ipv4.gateway              = '192.168.1.254'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1365] ++ ipv4.method               = 'manual'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1366] ++ ipv4.routes               = ((GPtrArray*) 0x13bf6a0)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1366] ++ ipv6                      [ 0x1361e40 ]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1366] ++ ipv6.addresses            = ((GPtrArray*) 0x13bf880)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1366] ++ ipv6.dns                  = []
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1366] ++ ipv6.dns-search           = []
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1366] ++ ipv6.ip6-privacy          = ((NMSettingIP6ConfigPrivacy) NM_SETTING_IP6_CONFIG_PRIVACY_DISABLED)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1366] ++ ipv6.method               = 'ignore'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.1366] ++ ipv6.routes               = ((GPtrArray*) 0x13bf940)
[...]
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.1375] ignoring notification for prop unmanaged-specs on type NMSettings
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.1375] ignoring notification for prop startup-complete on type NMSettings
Aug 31 15:36:14 ripley systemd[1]: Starting Hostname Service...
Aug 31 15:36:14 ripley dbus[645]: [system] Successfully activated service 'org.freedesktop.hostname1'
Aug 31 15:36:14 ripley systemd[1]: Started Hostname Service.
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2612] settings: hostname: using hostnamed
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2612] settings: hostname changed from (none) to "ripley"
Aug 31 15:36:14 ripley nm-dispatcher[5485]: req:1 'hostname': new request (2 scripts)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2612] dispatcher: (1) dispatching action 'hostname'
Aug 31 15:36:14 ripley nm-dispatcher[5485]: req:1 'hostname': environment: PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.2615] ignoring notification for prop hostname on type NMManager
Aug 31 15:36:14 ripley nm-dispatcher[5485]: req:1 'hostname': start running ordered scripts...
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2615] dhcp-init: Registered DHCP client 'internal' (NMDhcpSystemd)
Aug 31 15:36:14 ripley nm-dispatcher[5485]: req:1 'hostname', "/etc/NetworkManager/dispatcher.d/01ifupdown": run script
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2616] dhcp-init: Registered DHCP client 'dhcpcd' (NMDhcpDhcpcd)
Aug 31 15:36:14 ripley nm-dispatcher[5485]: req:1 'hostname', "/etc/NetworkManager/dispatcher.d/01ifupdown": complete
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2616] dhcp-init: Registered DHCP client 'dhclient' (NMDhcpDhclient)
Aug 31 15:36:14 ripley nm-dispatcher[5485]: req:1 'hostname', "/etc/NetworkManager/dispatcher.d/ntp": run script
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2616] dhcp-init: Using DHCP client 'dhclient'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2616] create NMDhcpManager singleton (0x13c9520)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2617] manager: WiFi enabled by radio killswitch; enabled by state file
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2617] manager: WWAN enabled by radio killswitch; enabled by state file
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2618] manager: Networking is enabled by state file
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2618] Loaded device plugin: NMVxlanFactory (internal)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2619] Loaded device plugin: NMVlanFactory (internal)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2619] Loaded device plugin: NMVethFactory (internal)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2619] Loaded device plugin: NMTunFactory (internal)
Aug 31 15:36:14 ripley nm-dispatcher[5485]: req:1 'hostname', "/etc/NetworkManager/dispatcher.d/ntp": complete
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2620] Loaded device plugin: NMMacvlanFactory (internal)
Aug 31 15:36:14 ripley nm-dispatcher[5485]: req:1 'hostname': completed (2 scripts)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2620] Loaded device plugin: NMIPTunnelFactory (internal)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2620] Loaded device plugin: NMInfinibandFactory (internal)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2621] Loaded device plugin: NMEthernetFactory (internal)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2621] Loaded device plugin: NMBridgeFactory (internal)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2621] Loaded device plugin: NMBondFactory (internal)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2628] Loaded device plugin: NMWifiFactory (/usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-wifi.so)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2645] Loaded device plugin: NMTeamFactory (/usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-team.so)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2673] Loaded device plugin: NMBluezManager (/usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-bluetooth.so)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2677] Loaded device plugin: NMWwanFactory (/usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-wwan.so)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2682] Loaded device plugin: NMAtmManager (/usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-adsl.so)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2692] device[0x13de040] (lo): unmanaged: flags set to [platform-init,by-default=0x110/0x110/unmanaged/unrealized, set-unmanaged [by-default=0x100])
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2693] device[0x13de040] (lo): constructed (NMDeviceGeneric)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2693] device[0x13de040] (lo): start setup of NMDeviceGeneric, kernel ifindex 1
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2694] platform-linux: error reading /sys/class/net/lo/phys_port_id: Failed to read from file '/sys/class/net/lo/phys_port_id': Operation not supported
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2695] platform-linux: sysctl: reading '/sys/class/net/lo/dev_id': '0x0'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2695] ethtool: Request failed: Operation not supported
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2696] device[0x13de040] (lo): hw-addr: hardware address now 00:00:00:00:00:00
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2696] device[0x13de040] (lo): hw-addr: update initial MAC address 00:00:00:00:00:00
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2696] device[0x13de040] (lo): hw-addr: unable to read permanent MAC address (use current: 00:00:00:00:00:00)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2697] device (lo): link connected
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2697] device[0x13de040] (lo): carrier is ON
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2697] device[0x13de040] (lo): unmanaged: flags set to [platform-init,by-default,!external-down=0x110/0x910/unmanaged/unrealized, set-managed [external-down=0x800])
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2697] device[0x13de040] (lo): unmanaged: flags set to [loopback,platform-init,by-default,!external-down=0x118/0x918/unmanaged/unrealized, set-unmanaged [loopback=0x8])
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2698] device[0x13de040] (lo): unmanaged: flags set to [loopback,by-default,!platform-init,!external-down=0x108/0x918/unmanaged/unrealized, set-managed [platform-init=0x10])
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2698] device[0x13de040] (lo): unmanaged: flags set to [loopback,by-default,!platform-init,!user-settings,!external-down=0x108/0x958/unmanaged/unrealized, set-managed [user-settings=0x40])
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2698] device[0x13de040] (lo): unmanaged: flags set to [loopback,by-default,!sleeping,!platform-init,!user-settings,!external-down=0x108/0x959/unmanaged/unrealized, set-managed [sleeping=0x1])
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.2707] exported-object[0x13de040]: export: "/org/freedesktop/NetworkManager/Devices/0"
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2712] manager: (lo): new Generic device (/org/freedesktop/NetworkManager/Devices/0)
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.2715] ignoring notification for prop g-object-path on type NMDeviceGeneric
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.2715] ignoring notification for prop carrier on type NMDeviceGeneric
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.2715] ignoring notification for prop perm-hw-address on type NMDeviceGeneric
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.2719] ignoring notification for prop ifindex on type NMDeviceGeneric
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2721] device[0x13ea8c0] (eth0): constructed (NMDeviceEthernet)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Ignore RTM_NEWLINK event for foreign ifindex 2
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2723] device[0x13ea8c0] (eth0): start setup of NMDeviceEthernet, kernel ifindex 2
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2723] platform-linux: error reading /sys/class/net/eth0/phys_port_id: Failed to read from file '/sys/class/net/eth0/phys_port_id': Operation not supported
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2724] platform-linux: sysctl: reading '/sys/class/net/eth0/dev_id': '0x0'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2724] device[0x13ea8c0] (eth0): hw-addr: hardware address now 50:46:5D:51:D5:9B
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2724] device[0x13ea8c0] (eth0): hw-addr: update initial MAC address 50:46:5D:51:D5:9B
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2725] device[0x13ea8c0] (eth0): hw-addr: read permanent MAC address '50:46:5D:51:D5:9B'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2725] device[0x13ea8c0] (eth0): carrier is OFF
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2725] device[0x13ea8c0] (eth0): unmanaged: flags set to [platform-init,!loopback=0x10/0x18/unmanaged/unrealized, set-managed [loopback=0x8])
Aug 31 15:36:14 ripley kernel: IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2725] device[0x13ea8c0] (eth0): unmanaged: flags set to [!loopback,!platform-init=0x0/0x18/managed/unrealized, set-managed [platform-init=0x10])
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2726] device[0x13ea8c0] (eth0): unmanaged: flags set to [!loopback,!platform-init,!user-settings=0x0/0x58/managed/unrealized, set-managed [user-settings=0x40])
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2726] device[0x13ea8c0] (eth0): unmanaged: flags set to [!sleeping,!loopback,!platform-init,!user-settings=0x0/0x59/managed/unrealized, set-managed [sleeping=0x1])
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.2734] exported-object[0x13ea8c0]: export: "/org/freedesktop/NetworkManager/Devices/1"
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2739] manager: (eth0): new Ethernet device (/org/freedesktop/NetworkManager/Devices/1)
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.2741] ignoring notification for prop g-object-path on type NMDeviceEthernet
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.2744] ignoring notification for prop ifindex on type NMDeviceEthernet
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2747] device[0x13ea8c0] (eth0): ip4-config: update (commit=0, routes-full-sync=0, new-config=0x13bd870)
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.2750] exported-object[0x13bd870]: export: "/org/freedesktop/NetworkManager/IP4Config/0"
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.2751] ignoring notification for prop g-object-path on type NMIP4Config
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2753] device[0x13ea8c0] (eth0): ip4-config: set IP4Config instance (/org/freedesktop/NetworkManager/IP4Config/0)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2753] create NMDefaultRouteManager singleton (0x13e9c30)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2754] dns-mgr: (device_ip4_config_changed): queueing DNS updates (1)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2754] dns-mgr: (device_ip4_config_changed): DNS configuration did not change
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2754] dns-mgr: (device_ip4_config_changed): no DNS changes to commit (0)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2757] device[0x13ea8c0] (eth0): ip6-config: update (commit=0, routes-full-sync=0, new-config=0x137cc00)
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.2760] exported-object[0x137cc00]: export: "/org/freedesktop/NetworkManager/IP6Config/0"
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.2760] ignoring notification for prop g-object-path on type NMIP6Config
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2762] device[0x13ea8c0] (eth0): ip6-config: set IP6Config instance (/org/freedesktop/NetworkManager/IP6Config/0)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2763] dns-mgr: (device_ip6_config_changed): queueing DNS updates (1)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2763] dns-mgr: (device_ip6_config_changed): DNS configuration did not change
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2763] dns-mgr: (device_ip6_config_changed): no DNS changes to commit (0)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2763] device[0x13ea8c0] (eth0): device has no existing configuration
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2763] manager: (eth0): can't assume; no connection
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2764] device (eth0): state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2764] platform-linux: sysctl: reading '/proc/sys/net/ipv6/conf/eth0/accept_ra': '0'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2764] platform-linux: sysctl: reading '/proc/sys/net/ipv6/conf/eth0/accept_ra_defrtr': '0'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2765] platform-linux: sysctl: reading '/proc/sys/net/ipv6/conf/eth0/accept_ra_pinfo': '0'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2765] platform-linux: sysctl: reading '/proc/sys/net/ipv6/conf/eth0/accept_ra_rtr_pref': '0'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2765] platform-linux: sysctl: reading '/proc/sys/net/ipv6/conf/eth0/disable_ipv6': '0'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2766] platform-linux: sysctl: reading '/proc/sys/net/ipv6/conf/eth0/hop_limit': '64'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2766] platform-linux: sysctl: reading '/proc/sys/net/ipv6/conf/eth0/use_tempaddr': '0'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2766] device[0x13ea8c0] (eth0): will enable userland IPv6LL
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2766] platform-linux: link: change 2: user-ipv6ll: set IPv6 address generation mode to none
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2767] platform-linux: do-request-link: 2 
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.2768] platform-linux: event-notification: NEWLINK, seq 0: 2: eth0 <UP;broadcast,multicast,up> mtu 1500 arp 1 ethernet? not-init addrgenmode none addr 50:46:5D:51:D5:9B rx:0,0 tx:0,0
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2768] platform: signal: link changed: 2: eth0 <UP;broadcast,multicast,up> mtu 1500 arp 1 ethernet? init addrgenmode none addr 50:46:5D:51:D5:9B driver r8169 rx:0,0 tx:0,0
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2769] device[0x13ea8c0] (eth0): queued link change for ifindex 2
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.2769] platform-linux: event-notification: NEWLINK, seq 7: 2: eth0 <UP;broadcast,multicast,up> mtu 1500 arp 1 ethernet? not-init addrgenmode none addr 50:46:5D:51:D5:9B rx:0,0 tx:0,0
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2770] platform-linux: do-change-link[2]: success changing link: success
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2770] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/eth0/disable_ipv6' to '1' (current value is '0')
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2771] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/eth0/disable_ipv6' to '0' (current value is '1')
Aug 31 15:36:14 ripley kernel: IPv6: ADDRCONF(NETDEV_UP): wlxf4f26d10c509: link is not ready
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2771] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/eth0/accept_ra_defrtr' to '0' (current value is identical)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2772] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/eth0/accept_ra_pinfo' to '0' (current value is identical)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2772] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/eth0/accept_ra_rtr_pref' to '0' (current value is identical)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2772] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/eth0/use_tempaddr' to '0' (current value is identical)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2773] device[0x13ea8c0] (eth0): bringing up device
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2773] platform: link: setting up 'eth0' (2)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2773] platform-linux: link: change 2: flags: set 0x1/0x1 ([up] / [up])
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2773] platform-linux: do-request-link: 2 
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.2774] platform-linux: event-notification: NEWLINK, seq 9: 2: eth0 <UP;broadcast,multicast,up> mtu 1500 arp 1 ethernet? not-init addrgenmode none addr 50:46:5D:51:D5:9B rx:0,0 tx:0,0
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2774] platform-linux: do-change-link[2]: success changing link: success
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2775] device[0x13ea8c0] (eth0): add_pending_action (1): 'carrier wait'
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.2775] ignoring notification for prop has-pending-action on type NMDeviceEthernet
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2775] device[0x13ea8c0] (eth0): preparing device
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2775] device[0x13ea8c0] (eth0): clearing queued IP4 config change
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2776] device[0x13ea8c0] (eth0): clearing queued IP6 config change
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2776] device[0x13ea8c0] (eth0): remove_pending_action (1): 'dhcp6' not pending (expected)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2776] device[0x13ea8c0] (eth0): remove_pending_action (1): 'autoconf6' not pending (expected)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2776] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/eth0/accept_ra' to '0' (current value is identical)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2777] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/eth0/use_tempaddr' to '0' (current value is identical)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2777] create NMRouteManager singleton (0x13fbba0)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2777] route-mgr4:   2: sync 0 IPv4 routes
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2778] route-mgr6:   2: sync 0 IPv6 routes
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2778] default-route: entry[0/dev:0x13ea8c0:eth0:0:+sync]: record:add    0.0.0.0/0 via 0.0.0.0 dev 2 metric 4294967295 mss 0 src unknown (4294967295)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2778] default-route: entry[0/dev:0x13ea8c0:eth0:0:+sync]: record:add    ::/0 via :: dev 2 metric 4294967295 mss 0 src unknown (4294967295)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2778] default-route: entry[0/dev:0x13ea8c0:eth0:0:+sync]: record:remove 0.0.0.0/0 via 0.0.0.0 dev 2 metric 4294967295 mss 0 src unknown (4294967295)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2779] default-route: entry[0/dev:0x13ea8c0:eth0:0:+sync]: record:remove ::/0 via :: dev 2 metric 4294967295 mss 0 src unknown (4294967295)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2779] device[0x13ea8c0] (eth0): ip4-config: update (commit=1, routes-full-sync=1, new-config=(nil))
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2779] device[0x13ea8c0] (eth0): ip4-config: clear IP4Config instance (/org/freedesktop/NetworkManager/IP4Config/0)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2780] dns-mgr: (device_ip4_config_changed): queueing DNS updates (1)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2780] dns-mgr: (device_ip4_config_changed): DNS configuration did not change
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2780] dns-mgr: (device_ip4_config_changed): no DNS changes to commit (0)
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.2780] exported-object[0x13bd870]: unexport: "/org/freedesktop/NetworkManager/IP4Config/0"
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2782] device[0x13ea8c0] (eth0): ip6-config: update (commit=1, routes-full-sync=1, new-config=(nil))
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2782] device[0x13ea8c0] (eth0): ip6-config: clear IP6Config instance (/org/freedesktop/NetworkManager/IP6Config/0)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2783] dns-mgr: (device_ip6_config_changed): queueing DNS updates (1)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2783] dns-mgr: (device_ip6_config_changed): DNS configuration did not change
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2783] dns-mgr: (device_ip6_config_changed): no DNS changes to commit (0)
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.2783] exported-object[0x137cc00]: unexport: "/org/freedesktop/NetworkManager/IP6Config/0"
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2786] device[0x13ea8c0] (eth0): device not yet available for transition to DISCONNECTED
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2788] genl_ctrl_resolve: resolved "nl80211" as 0x17
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2789] Don't know the meaning of NL80211_ATTR_CIPHER_SUITE 0x000fac0a.
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2790] Don't know the meaning of NL80211_ATTR_CIPHER_SUITE 0x000fac09.
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2790] Don't know the meaning of NL80211_ATTR_CIPHER_SUITE 0x000fac0d.
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2790] Don't know the meaning of NL80211_ATTR_CIPHER_SUITE 0x000fac0b.
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2790] Don't know the meaning of NL80211_ATTR_CIPHER_SUITE 0x000fac0c.
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2790] (wlxf4f26d10c509): using nl80211 for WiFi device control
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2793] device[0x137f9b0] (wlxf4f26d10c509): constructed (NMDeviceWifi)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2794] device (wlxf4f26d10c509): driver supports Access Point (AP) mode
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2794] create NMSupplicantManager singleton (0x13e9f00)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2795] device[0x137f9b0] (wlxf4f26d10c509): start setup of NMDeviceWifi, kernel ifindex 3
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2795] platform-linux: error reading /sys/class/net/wlxf4f26d10c509/phys_port_id: Failed to read from file '/sys/class/net/wlxf4f26d10c509/phys_port_id': Operation not supported
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2796] platform-linux: sysctl: reading '/sys/class/net/wlxf4f26d10c509/dev_id': '0x0'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2796] device[0x137f9b0] (wlxf4f26d10c509): hw-addr: hardware address now F4:F2:6D:10:C5:09
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2797] device[0x137f9b0] (wlxf4f26d10c509): hw-addr: update initial MAC address F4:F2:6D:10:C5:09
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2797] device[0x137f9b0] (wlxf4f26d10c509): hw-addr: read permanent MAC address 'F4:F2:6D:10:C5:09'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2797] device[0x137f9b0] (wlxf4f26d10c509): unmanaged: flags set to [platform-init,!loopback=0x10/0x18/unmanaged/unrealized, set-managed [loopback=0x8])
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2797] device[0x137f9b0] (wlxf4f26d10c509): unmanaged: flags set to [!loopback,!platform-init=0x0/0x18/managed/unrealized, set-managed [platform-init=0x10])
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2798] manager: WiFi hw-enabled 1 sw-enabled 1
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2798] device[0x137f9b0] (wlxf4f26d10c509): device now enabled
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2798] device[0x137f9b0] (wlxf4f26d10c509): (enable): device blocked by UNMANAGED state
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2798] device[0x137f9b0] (wlxf4f26d10c509): unmanaged: flags set to [!loopback,!platform-init,!user-settings=0x0/0x58/managed/unrealized, set-managed [user-settings=0x40])
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2799] device[0x137f9b0] (wlxf4f26d10c509): unmanaged: flags set to [!sleeping,!loopback,!platform-init,!user-settings=0x0/0x59/managed/unrealized, set-managed [sleeping=0x1])
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.2807] exported-object[0x137f9b0]: export: "/org/freedesktop/NetworkManager/Devices/2"
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2812] manager: (wlxf4f26d10c509): new 802.11 WiFi device (/org/freedesktop/NetworkManager/Devices/2)
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.2814] ignoring notification for prop g-object-path on type NMDeviceWifi
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.2816] ignoring notification for prop ifindex on type NMDeviceWifi
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2819] device[0x137f9b0] (wlxf4f26d10c509): ip4-config: update (commit=0, routes-full-sync=0, new-config=0x13bda50)
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.2822] exported-object[0x13bda50]: export: "/org/freedesktop/NetworkManager/IP4Config/1"
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.2822] ignoring notification for prop g-object-path on type NMIP4Config
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2824] device[0x137f9b0] (wlxf4f26d10c509): ip4-config: set IP4Config instance (/org/freedesktop/NetworkManager/IP4Config/1)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2825] dns-mgr: (device_ip4_config_changed): queueing DNS updates (1)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2825] dns-mgr: (device_ip4_config_changed): DNS configuration did not change
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2825] dns-mgr: (device_ip4_config_changed): no DNS changes to commit (0)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2826] device[0x137f9b0] (wlxf4f26d10c509): ip6-config: update (commit=0, routes-full-sync=0, new-config=0x137cc00)
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.2829] exported-object[0x137cc00]: export: "/org/freedesktop/NetworkManager/IP6Config/1"
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.2829] ignoring notification for prop g-object-path on type NMIP6Config
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2831] device[0x137f9b0] (wlxf4f26d10c509): ip6-config: set IP6Config instance (/org/freedesktop/NetworkManager/IP6Config/1)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2832] dns-mgr: (device_ip6_config_changed): queueing DNS updates (1)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2832] dns-mgr: (device_ip6_config_changed): DNS configuration did not change
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2832] dns-mgr: (device_ip6_config_changed): no DNS changes to commit (0)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2832] manager: (wlxf4f26d10c509): can't assume; no connection
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.2832] device (wlxf4f26d10c509): state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2833] platform-linux: sysctl: reading '/proc/sys/net/ipv6/conf/wlxf4f26d10c509/accept_ra': '0'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2833] platform-linux: sysctl: reading '/proc/sys/net/ipv6/conf/wlxf4f26d10c509/accept_ra_defrtr': '0'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2833] platform-linux: sysctl: reading '/proc/sys/net/ipv6/conf/wlxf4f26d10c509/accept_ra_pinfo': '0'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2834] platform-linux: sysctl: reading '/proc/sys/net/ipv6/conf/wlxf4f26d10c509/accept_ra_rtr_pref': '0'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2834] platform-linux: sysctl: reading '/proc/sys/net/ipv6/conf/wlxf4f26d10c509/disable_ipv6': '0'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2834] platform-linux: sysctl: reading '/proc/sys/net/ipv6/conf/wlxf4f26d10c509/hop_limit': '64'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2835] platform-linux: sysctl: reading '/proc/sys/net/ipv6/conf/wlxf4f26d10c509/use_tempaddr': '0'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2835] device[0x137f9b0] (wlxf4f26d10c509): will enable userland IPv6LL
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2835] platform-linux: link: change 3: user-ipv6ll: set IPv6 address generation mode to none
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2835] platform-linux: do-request-link: 3 
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.2836] platform-linux: event-notification: NEWLINK, seq 11: 3: wlxf4f26d10c509 <DOWN;broadcast,multicast> mtu 1500 arp 1 wifi? not-init addrgenmode none addr F4:F2:6D:10:C5:09 rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2837] platform: signal: link changed: 3: wlxf4f26d10c509 <DOWN;broadcast,multicast> mtu 1500 arp 1 wifi? init addrgenmode none addr F4:F2:6D:10:C5:09 driver ath9k_htc rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2837] device[0x137f9b0] (wlxf4f26d10c509): queued link change for ifindex 3
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2837] platform-linux: do-change-link[3]: success changing link: success
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2838] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/wlxf4f26d10c509/disable_ipv6' to '1' (current value is '0')
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2838] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/wlxf4f26d10c509/disable_ipv6' to '0' (current value is '1')
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2839] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/wlxf4f26d10c509/accept_ra_defrtr' to '0' (current value is identical)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2839] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/wlxf4f26d10c509/accept_ra_pinfo' to '0' (current value is identical)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2839] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/wlxf4f26d10c509/accept_ra_rtr_pref' to '0' (current value is identical)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2840] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/wlxf4f26d10c509/use_tempaddr' to '0' (current value is identical)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2840] device[0x137f9b0] (wlxf4f26d10c509): bringing up device
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2840] platform: link: setting up 'wlxf4f26d10c509' (3)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.2840] platform-linux: link: change 3: flags: set 0x1/0x1 ([up] / [up])
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Ignore RTM_NEWLINK event for foreign ifindex 3
Aug 31 15:36:14 ripley kernel: IPv6: ADDRCONF(NETDEV_UP): wlxf4f26d10c509: link is not ready
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.4546] platform-linux: do-request-link: 3 
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.4547] platform-linux: event-notification: NEWLINK, seq 0: 3: wlxf4f26d10c509 <UP;broadcast,multicast,up> mtu 1500 arp 1 wifi? not-init addrgenmode none addr F4:F2:6D:10:C5:09 rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.4548] platform: signal: link changed: 3: wlxf4f26d10c509 <UP;broadcast,multicast,up> mtu 1500 arp 1 wifi? init addrgenmode none addr F4:F2:6D:10:C5:09 driver ath9k_htc rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.4549] platform-linux: event-notification: NEWLINK, seq 13: 3: wlxf4f26d10c509 <UP;broadcast,multicast,up> mtu 1500 arp 1 wifi? not-init addrgenmode none addr F4:F2:6D:10:C5:09 rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.4549] platform-linux: do-change-link[3]: success changing link: success
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.4550] device[0x137f9b0] (wlxf4f26d10c509): preparing device
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.4550] device[0x137f9b0] (wlxf4f26d10c509): clearing queued IP4 config change
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.4551] device[0x137f9b0] (wlxf4f26d10c509): clearing queued IP6 config change
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.4551] device[0x137f9b0] (wlxf4f26d10c509): remove_pending_action (0): 'dhcp6' not pending (expected)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.4551] device[0x137f9b0] (wlxf4f26d10c509): remove_pending_action (0): 'autoconf6' not pending (expected)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.4552] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/wlxf4f26d10c509/accept_ra' to '0' (current value is identical)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.4553] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/wlxf4f26d10c509/use_tempaddr' to '0' (current value is identical)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.4554] route-mgr4:   3: sync 0 IPv4 routes
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.4554] route-mgr6:   3: sync 0 IPv6 routes
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.4554] device[0x137f9b0] (wlxf4f26d10c509): set-hw-addr: setting MAC address to '26:49:BE:DB:32:12' (set, scanning)...
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.4555] device[0x137f9b0] (wlxf4f26d10c509): taking down device
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.4555] platform: link: setting down 'wlxf4f26d10c509' (3)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.4555] platform-linux: link: change 3: flags: set 0x0/0x1 ([] / [up])
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Ignore RTM_NEWLINK event for foreign ifindex 3
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.4812] platform-linux: do-request-link: 3 
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.4813] platform-linux: event-notification: NEWLINK, seq 0: 3: wlxf4f26d10c509 <DOWN;broadcast,multicast> mtu 1500 arp 1 wifi? not-init addrgenmode none addr F4:F2:6D:10:C5:09 rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Ignore RTM_NEWLINK event for foreign ifindex 3
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.4813] platform: signal: link changed: 3: wlxf4f26d10c509 <DOWN;broadcast,multicast> mtu 1500 arp 1 wifi? init addrgenmode none addr F4:F2:6D:10:C5:09 driver ath9k_htc rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.4814] platform-linux: event-notification: NEWLINK, seq 15: 3: wlxf4f26d10c509 <DOWN;broadcast,multicast> mtu 1500 arp 1 wifi? not-init addrgenmode none addr F4:F2:6D:10:C5:09 rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.4815] platform-linux: event-notification: NEWROUTE, seq 16: ignore
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.4815] platform-linux: event-notification: NEWROUTE, seq 16: ignore
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.4815] platform-linux: event-notification: NEWROUTE, seq 16: ignore
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.4815] platform-linux: event-notification: NEWROUTE, seq 16: ignore
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.4815] platform-linux: event-notification: NEWROUTE, seq 17: ignore
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.4815] platform-linux: event-notification: NEWROUTE, seq 17: ignore
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.4816] platform-linux: event-notification: NEWROUTE, seq 17: ignore
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.4816] platform-linux: do-change-link[3]: success changing link: success
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.4816] platform: link: setting 'wlxf4f26d10c509' (3) hardware address
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.4816] platform-linux: link: change 3: address: 26:49:BE:DB:32:12 (6 bytes)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.4817] platform-linux: do-request-link: 3 
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.4817] platform-linux: event-notification: NEWLINK, seq 0: 3: wlxf4f26d10c509 <DOWN;broadcast,multicast> mtu 1500 arp 1 wifi? not-init addrgenmode none addr 26:49:BE:DB:32:12 rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.4817] platform: signal: link changed: 3: wlxf4f26d10c509 <DOWN;broadcast,multicast> mtu 1500 arp 1 wifi? init addrgenmode none addr 26:49:BE:DB:32:12 driver ath9k_htc rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.4818] platform-linux: event-notification: NEWLINK, seq 19: 3: wlxf4f26d10c509 <DOWN;broadcast,multicast> mtu 1500 arp 1 wifi? not-init addrgenmode none addr 26:49:BE:DB:32:12 rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.4818] platform-linux: do-change-link[3]: success changing link: success
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.4819] device[0x137f9b0] (wlxf4f26d10c509): hw-addr: hardware address now 26:49:BE:DB:32:12
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.4819] device (wlxf4f26d10c509): set-hw-addr: set MAC address to 26:49:BE:DB:32:12 (scanning)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.4819] device[0x137f9b0] (wlxf4f26d10c509): bringing up device
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.4819] platform: link: setting up 'wlxf4f26d10c509' (3)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.4820] platform-linux: link: change 3: flags: set 0x1/0x1 ([up] / [up])
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Ignore RTM_NEWLINK event for foreign ifindex 3
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6526] platform-linux: do-request-link: 3 
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.6527] platform-linux: event-notification: NEWLINK, seq 0: 3: wlxf4f26d10c509 <UP;broadcast,multicast,up> mtu 1500 arp 1 wifi? not-init addrgenmode none addr 26:49:BE:DB:32:12 rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6528] platform: signal: link changed: 3: wlxf4f26d10c509 <UP;broadcast,multicast,up> mtu 1500 arp 1 wifi? init addrgenmode none addr 26:49:BE:DB:32:12 driver ath9k_htc rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.6529] platform-linux: event-notification: NEWLINK, seq 21: 3: wlxf4f26d10c509 <UP;broadcast,multicast,up> mtu 1500 arp 1 wifi? not-init addrgenmode none addr 26:49:BE:DB:32:12 rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6529] platform-linux: do-change-link[3]: success changing link: success
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6530] default-route: entry[0/dev:0x137f9b0:wlxf4f26d10c509:0:+sync]: record:add    0.0.0.0/0 via 0.0.0.0 dev 3 metric 4294967295 mss 0 src unknown (4294967295)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6530] default-route: entry[0/dev:0x137f9b0:wlxf4f26d10c509:0:+sync]: record:add    ::/0 via :: dev 3 metric 4294967295 mss 0 src unknown (4294967295)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6530] default-route: entry[0/dev:0x137f9b0:wlxf4f26d10c509:0:+sync]: record:remove 0.0.0.0/0 via 0.0.0.0 dev 3 metric 4294967295 mss 0 src unknown (4294967295)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6530] default-route: entry[0/dev:0x137f9b0:wlxf4f26d10c509:0:+sync]: record:remove ::/0 via :: dev 3 metric 4294967295 mss 0 src unknown (4294967295)
Aug 31 15:36:14 ripley kernel: IPv6: ADDRCONF(NETDEV_UP): wlxf4f26d10c509: link is not ready
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6531] device[0x137f9b0] (wlxf4f26d10c509): ip4-config: update (commit=1, routes-full-sync=1, new-config=(nil))
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6531] device[0x137f9b0] (wlxf4f26d10c509): ip4-config: clear IP4Config instance (/org/freedesktop/NetworkManager/IP4Config/1)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6532] dns-mgr: (device_ip4_config_changed): queueing DNS updates (1)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6532] dns-mgr: (device_ip4_config_changed): DNS configuration did not change
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6532] dns-mgr: (device_ip4_config_changed): no DNS changes to commit (0)
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.6532] exported-object[0x13bda50]: unexport: "/org/freedesktop/NetworkManager/IP4Config/1"
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6535] device[0x137f9b0] (wlxf4f26d10c509): ip6-config: update (commit=1, routes-full-sync=1, new-config=(nil))
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6535] device[0x137f9b0] (wlxf4f26d10c509): ip6-config: clear IP6Config instance (/org/freedesktop/NetworkManager/IP6Config/1)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6536] dns-mgr: (device_ip6_config_changed): queueing DNS updates (1)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6536] dns-mgr: (device_ip6_config_changed): DNS configuration did not change
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6536] dns-mgr: (device_ip6_config_changed): no DNS changes to commit (0)
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.6537] exported-object[0x137cc00]: unexport: "/org/freedesktop/NetworkManager/IP6Config/1"
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6541] supplicant: (wlxf4f26d10c509): creating new supplicant interface
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6542] device[0x137f9b0] (wlxf4f26d10c509): add_pending_action (1): 'waiting for supplicant'
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.6542] ignoring notification for prop has-pending-action on type NMDeviceWifi
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6542] device[0x137f9b0] (wlxf4f26d10c509): device not yet available for transition to DISCONNECTED
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6545] create NMVpnManager singleton (0x13e9cd0)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6546] manager: creating virtual devices...
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6546] manager: check_if_startup_complete returns FALSE because of eth0
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6547] setting up local loopback
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6547] platform: link: setting up 'lo' (1)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6547] platform-linux: link: change 1: flags: set 0x1/0x1 ([up] / [up])
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6547] platform-linux: do-request-link: 1 
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.6548] platform-linux: event-notification: NEWLINK, seq 23: 1: lo <UP,LOWER_UP;loopback,up,running,lowerup> mtu 65536 arp 772 loopback? not-init addrgenmode eui64 addr 00:00:00:00:00:00 rx:1796,507619 tx:1796,507619
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6548] ethtool: Request failed: Operation not supported
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6549] platform-linux: do-change-link[1]: success changing link: success
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6576] auth: call[1]: CheckAuthorization(org.freedesktop.NetworkManager.enable-disable-network), subject=unix-process[pid=1226, uid=1000, start=1610] (wait for proxy)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6576] auth: call[2]: CheckAuthorization(org.freedesktop.NetworkManager.sleep-wake), subject=unix-process[pid=1226, uid=1000, start=1610] (wait for proxy)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6577] auth: call[3]: CheckAuthorization(org.freedesktop.NetworkManager.enable-disable-wifi), subject=unix-process[pid=1226, uid=1000, start=1610] (wait for proxy)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6577] auth: call[4]: CheckAuthorization(org.freedesktop.NetworkManager.enable-disable-wwan), subject=unix-process[pid=1226, uid=1000, start=1610] (wait for proxy)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6577] auth: call[5]: CheckAuthorization(org.freedesktop.NetworkManager.enable-disable-wimax), subject=unix-process[pid=1226, uid=1000, start=1610] (wait for proxy)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6578] auth: call[6]: CheckAuthorization(org.freedesktop.NetworkManager.network-control), subject=unix-process[pid=1226, uid=1000, start=1610] (wait for proxy)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6578] auth: call[7]: CheckAuthorization(org.freedesktop.NetworkManager.wifi.share.protected), subject=unix-process[pid=1226, uid=1000, start=1610] (wait for proxy)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6578] auth: call[8]: CheckAuthorization(org.freedesktop.NetworkManager.wifi.share.open), subject=unix-process[pid=1226, uid=1000, start=1610] (wait for proxy)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6579] auth: call[9]: CheckAuthorization(org.freedesktop.NetworkManager.settings.modify.system), subject=unix-process[pid=1226, uid=1000, start=1610] (wait for proxy)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6579] auth: call[10]: CheckAuthorization(org.freedesktop.NetworkManager.settings.modify.own), subject=unix-process[pid=1226, uid=1000, start=1610] (wait for proxy)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6580] auth: call[11]: CheckAuthorization(org.freedesktop.NetworkManager.settings.modify.hostname), subject=unix-process[pid=1226, uid=1000, start=1610] (wait for proxy)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6581] auth: call[12]: CheckAuthorization(org.freedesktop.NetworkManager.settings.modify.global-dns), subject=unix-process[pid=1226, uid=1000, start=1610] (wait for proxy)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6581] auth: call[13]: CheckAuthorization(org.freedesktop.NetworkManager.reload), subject=unix-process[pid=1226, uid=1000, start=1610] (wait for proxy)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6582] auth: call[14]: CheckAuthorization(org.freedesktop.NetworkManager.checkpoint-rollback), subject=unix-process[pid=1226, uid=1000, start=1610] (wait for proxy)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6582] auth: call[15]: CheckAuthorization(org.freedesktop.NetworkManager.enable-disable-statistics), subject=unix-process[pid=1226, uid=1000, start=1610] (wait for proxy)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6605] agent-manager: req[0x13fb6e0, :1.22/org.freedesktop.nm-applet/1000]: requesting permissions
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6605] auth: call[16]: CheckAuthorization(org.freedesktop.NetworkManager.wifi.share.protected), subject=unix-process[pid=1226, uid=1000, start=1610] (wait for proxy)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6606] auth: call[17]: CheckAuthorization(org.freedesktop.NetworkManager.wifi.share.open), subject=unix-process[pid=1226, uid=1000, start=1610] (wait for proxy)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6609] dispatcher: (1) 01ifupdown succeeded
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6609] dispatcher: (1) ntp succeeded
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6626] sleep-monitor-sd: inhibit: taking sleep inhibitor...
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6631] PropertiesChanged NMSettings 0x1359a00: {'Connections': <[objectpath '/org/freedesktop/NetworkManager/Settings/3', '/org/freedesktop/NetworkManager/Settings/4', '/org/freedesktop/NetworkManager/Settings/0', '/org/freedesktop/NetworkManager/Settings/1', '/org/freedesktop/NetworkManager/Settings/2']>, 'Hostname': <'ripley'>}
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6633] PropertiesChanged NMManager 0x1378050: {'ActiveConnections': <@ao []>, 'AllDevices': <[objectpath '/org/freedesktop/NetworkManager/Devices/0', '/org/freedesktop/NetworkManager/Devices/1', '/org/freedesktop/NetworkManager/Devices/2']>, 'Devices': <[objectpath '/org/freedesktop/NetworkManager/Devices/0', '/org/freedesktop/NetworkManager/Devices/1', '/org/freedesktop/NetworkManager/Devices/2']>, 'WirelessEnabled': <true>, 'WwanEnabled': <true>}
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6634] device[0x13de040] (lo): ip4-config: update (commit=0, routes-full-sync=0, new-config=0x13bd960)
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.6639] exported-object[0x13bd960]: export: "/org/freedesktop/NetworkManager/IP4Config/2"
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.6639] ignoring notification for prop g-object-path on type NMIP4Config
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6641] device[0x13de040] (lo): ip4-config: set IP4Config instance (/org/freedesktop/NetworkManager/IP4Config/2)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6642] dns-mgr: (device_ip4_config_changed): queueing DNS updates (1)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6643] dns-mgr: (device_ip4_config_changed): DNS configuration did not change
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6643] dns-mgr: (device_ip4_config_changed): no DNS changes to commit (0)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6644] device[0x13de040] (lo): ip6-config: update (commit=0, routes-full-sync=0, new-config=0x7f35e400f580)
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.6647] exported-object[0x7f35e400f580]: export: "/org/freedesktop/NetworkManager/IP6Config/2"
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.6648] ignoring notification for prop g-object-path on type NMIP6Config
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6650] device[0x13de040] (lo): ip6-config: set IP6Config instance (/org/freedesktop/NetworkManager/IP6Config/2)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6650] dns-mgr: (device_ip6_config_changed): queueing DNS updates (1)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6651] dns-mgr: (device_ip6_config_changed): DNS configuration did not change
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6651] dns-mgr: (device_ip6_config_changed): no DNS changes to commit (0)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6652] PropertiesChanged NMDeviceGeneric 0x13de040: {'Capabilities': <uint32 7>, 'Driver': <'unknown'>, 'HwAddress': <'00:00:00:00:00:00'>, 'Interface': <'lo'>, 'Ip4Address': <uint32 16777343>, 'Ip4Config': <objectpath '/org/freedesktop/NetworkManager/IP4Config/2'>, 'Ip6Config': <objectpath '/org/freedesktop/NetworkManager/IP6Config/2'>, 'Mtu': <uint32 65536>, 'PhysicalPortId': <''>, 'Real': <true>, 'RxBytes': <uint64 507619>, 'TxBytes': <uint64 507619>, 'Udi': <'/virtual/device/placeholder/0'>}
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6655] PropertiesChanged NMDeviceEthernet 0x13ea8c0: {'Dhcp4Config': <objectpath '/'>, 'Dhcp6Config': <objectpath '/'>, 'Driver': <'r8169'>, 'DriverVersion': <'2.3LK-NAPI'>, 'FirmwareVersion': <'rtl8168f-1_0.0.5 06/18/12'>, 'HwAddress': <'50:46:5D:51:D5:9B'>, 'Interface': <'eth0'>, 'Ip4Config': <objectpath '/'>, 'Ip6Config': <objectpath '/'>, 'IpInterface': <''>, 'Managed': <true>, 'Mtu': <uint32 1500>, 'PermHwAddress': <'50:46:5D:51:D5:9B'>, 'PhysicalPortId': <''>, 'Real': <true>, 'State': <uint32 20>, 'StateReason': <(uint32 20, uint32 2)>, 'Udi': <'/virtual/device/placeholder/1'>}
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6656] device[0x13ea8c0] (eth0): emit RECHECK_ASSUME signal
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6657] device[0x13ea8c0] (eth0): ip4-config: update (commit=0, routes-full-sync=0, new-config=0x1359f20)
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.6663] exported-object[0x1359f20]: export: "/org/freedesktop/NetworkManager/IP4Config/3"
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.6663] ignoring notification for prop g-object-path on type NMIP4Config
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6665] device[0x13ea8c0] (eth0): ip4-config: set IP4Config instance (/org/freedesktop/NetworkManager/IP4Config/3)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6666] dns-mgr: (device_ip4_config_changed): queueing DNS updates (1)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6666] dns-mgr: (device_ip4_config_changed): DNS configuration did not change
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6667] dns-mgr: (device_ip4_config_changed): no DNS changes to commit (0)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6667] device[0x13ea8c0] (eth0): ip6-config: update (commit=0, routes-full-sync=0, new-config=0x1369980)
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.6671] exported-object[0x1369980]: export: "/org/freedesktop/NetworkManager/IP6Config/3"
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.6671] ignoring notification for prop g-object-path on type NMIP6Config
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6674] device[0x13ea8c0] (eth0): ip6-config: set IP6Config instance (/org/freedesktop/NetworkManager/IP6Config/3)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: dbus: org.freedesktop.DBus.Properties.GetAll (/fi/w1/wpa_supplicant1) [s]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6674] dns-mgr: (device_ip6_config_changed): queueing DNS updates (1)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6675] dns-mgr: (device_ip6_config_changed): DNS configuration did not change
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6675] dns-mgr: (device_ip6_config_changed): no DNS changes to commit (0)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6675] device[0x13ea8c0] (eth0): device has no existing configuration
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6675] manager: (eth0): can't assume; no connection
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6677] PropertiesChanged NMDeviceWifi 0x137f9b0: {'Dhcp4Config': <objectpath '/'>, 'Dhcp6Config': <objectpath '/'>, 'Driver': <'ath9k_htc'>, 'DriverVersion': <'4.6.0-1-amd64'>, 'FirmwareVersion': <'1.4'>, 'HwAddress': <'26:49:BE:DB:32:12'>, 'Interface': <'wlxf4f26d10c509'>, 'Ip4Config': <objectpath '/'>, 'Ip6Config': <objectpath '/'>, 'IpInterface': <''>, 'Managed': <true>, 'Mtu': <uint32 1500>, 'PermHwAddress': <'F4:F2:6D:10:C5:09'>, 'PhysicalPortId': <''>, 'Real': <true>, 'RxBytes': <uint64 78457503>, 'State': <uint32 20>, 'StateReason': <(uint32 20, uint32 2)>, 'TxBytes': <uint64 3701342>, 'Udi': <'/virtual/device/placeholder/2'>}
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6684] device[0x13de040] (lo): emit RECHECK_ASSUME signal
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6684] PropertiesChanged NMDeviceEthernet 0x13ea8c0: {'Ip4Config': <objectpath '/'>, 'Ip6Config': <objectpath '/'>, 'Udi': <'/sys/devices/pci0000:00/0000:00:1c.4/0000:03:00.0/net/eth0'>}
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6685] device[0x13ea8c0] (eth0): emit RECHECK_ASSUME signal
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6686] device[0x13ea8c0] (eth0): ip4-config: update (commit=0, routes-full-sync=0, new-config=0x13bda50)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6687] device[0x13ea8c0] (eth0): ip6-config: update (commit=0, routes-full-sync=0, new-config=0x137cc00)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6687] device[0x13ea8c0] (eth0): device has no existing configuration
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6688] manager: (eth0): can't assume; no connection
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6688] PropertiesChanged NMDeviceWifi 0x137f9b0: {'Udi': <'/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.7/2-1.7:1.0/net/wlxf4f26d10c509'>}
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6720] auth: dbus name owner: ':1.6'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6720] auth: call[1]: CheckAuthorization invoke now
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6721] auth: call[2]: CheckAuthorization invoke now
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6722] auth: call[3]: CheckAuthorization invoke now
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6722] auth: call[4]: CheckAuthorization invoke now
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6723] auth: call[5]: CheckAuthorization invoke now
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6724] auth: call[6]: CheckAuthorization invoke now
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6725] auth: call[7]: CheckAuthorization invoke now
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6726] auth: call[8]: CheckAuthorization invoke now
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6726] auth: call[9]: CheckAuthorization invoke now
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6727] auth: call[10]: CheckAuthorization invoke now
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6728] auth: call[11]: CheckAuthorization invoke now
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6728] auth: call[12]: CheckAuthorization invoke now
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6729] auth: call[13]: CheckAuthorization invoke now
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6730] auth: call[14]: CheckAuthorization invoke now
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6731] auth: call[15]: CheckAuthorization invoke now
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6731] auth: call[16]: CheckAuthorization invoke now
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6732] auth: call[17]: CheckAuthorization invoke now
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6733] auth: emit changed signal
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6734] bluez: detecting BlueZ version failed: introspect failed with Cannot invoke method; proxy is for a well-known name without an owner and proxy was constructed with the G_DBUS_PROXY_FLAGS_DO_NOT_AUTO_START flag
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6745] sleep-monitor-sd: inhibit: inhibitor fd is 19
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.6897] ModemManager available in the bus
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.6905] supplicant: wpa_supplicant running
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6905] sup-iface[0x13c4d90,wlxf4f26d10c509]: adding interface to supplicant
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.6905] device (wlxf4f26d10c509): supplicant interface state: init -> starting
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6906] supplicant: AP mode is supported
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6906] supplicant: EAP-FAST is not supported
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6906] auth: call[1]: CheckAuthorization succeeded: (is_authorized=1, is_challenge=0)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6907] auth: call[2]: CheckAuthorization succeeded: (is_authorized=0, is_challenge=0)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6907] auth: call[3]: CheckAuthorization succeeded: (is_authorized=1, is_challenge=0)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: dbus: fi.w1.wpa_supplicant1.CreateInterface (/fi/w1/wpa_supplicant1) [a{sv}]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6907] auth: call[4]: CheckAuthorization succeeded: (is_authorized=1, is_challenge=0)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wpa_dbus_dict_open_read: start reading a dict entry
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6907] auth: call[5]: CheckAuthorization succeeded: (is_authorized=1, is_challenge=0)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wpa_dbus_dict_get_entry: dict entry key: Driver
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6907] auth: call[6]: CheckAuthorization succeeded: (is_authorized=1, is_challenge=0)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wpa_dbus_dict_get_entry: dict entry variant content type: s
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6917] auth: call[7]: CheckAuthorization succeeded: (is_authorized=1, is_challenge=0)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: _wpa_dbus_dict_fill_value_from_variant: string value: [omitted]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6925] auth: call[8]: CheckAuthorization succeeded: (is_authorized=1, is_challenge=0)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wpa_dbus_dict_get_entry: dict entry key: Ifname
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.6933] platform-linux: event-notification: NEWLINK, seq 0: 3: wlxf4f26d10c509 <UP;broadcast,multicast,up> mtu 1500 arp 1 wifi? not-init addrgenmode none addr 26:49:BE:DB:32:12 rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wpa_dbus_dict_get_entry: dict entry variant content type: s
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6938] auth: call[9]: CheckAuthorization succeeded: (is_authorized=1, is_challenge=0)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: _wpa_dbus_dict_fill_value_from_variant: string value: [omitted]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6944] auth: call[10]: CheckAuthorization succeeded: (is_authorized=1, is_challenge=0)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: Override interface parameter: ctrl_interface ('(null)' -> '/run/wpa_supplicant')
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6957] auth: call[11]: CheckAuthorization succeeded: (is_authorized=0, is_challenge=1)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: Initializing interface 'wlxf4f26d10c509' conf 'N/A' driver 'nl80211,wext' ctrl_interface '/run/wpa_supplicant' bridge 'N/A'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6973] auth: call[12]: CheckAuthorization succeeded: (is_authorized=0, is_challenge=1)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: rfkill: initial event: idx=0 type=1 op=0 soft=0 hard=0
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6983] auth: call[13]: CheckAuthorization succeeded: (is_authorized=0, is_challenge=1)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: TDLS supported
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: TDLS external setup
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Supported cipher 00-0f-ac:1
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.6996] auth: call[14]: CheckAuthorization succeeded: (is_authorized=0, is_challenge=1)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Supported cipher 00-0f-ac:5
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Supported cipher 00-0f-ac:2
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Supported cipher 00-0f-ac:4
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Supported cipher 00-0f-ac:10
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7003] auth: call[15]: CheckAuthorization succeeded: (is_authorized=1, is_challenge=0)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Supported cipher 00-0f-ac:8
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Supported cipher 00-0f-ac:9
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Supported cipher 00-0f-ac:6
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Supported cipher 00-0f-ac:13
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7012] auth: call[16]: CheckAuthorization succeeded: (is_authorized=1, is_challenge=0)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Supported cipher 00-0f-ac:11
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7016] auth: call[17]: CheckAuthorization succeeded: (is_authorized=1, is_challenge=0)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Supported cipher 00-0f-ac:12
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7017] agent-manager: req[0x13fb6e0, :1.22/org.freedesktop.nm-applet/1000]: agent registered
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Using driver-based off-channel TX
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7018] policy: re-enabling autoconnect for all connections with failed secrets
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Use separate P2P group interface (driver advertised support)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: interface wlxf4f26d10c509 in phy phy0
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Set mode ifindex 3 iftype 2 (STATION)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Subscribe to mgmt frames with non-AP handle 0x555baf735450
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x555baf735450 match=040a
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x555baf735450 match=040b
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x555baf735450 match=040c
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x555baf735450 match=040d
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x555baf735450 match=090a
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x555baf735450 match=090b
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x555baf735450 match=090c
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x555baf735450 match=090d
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x555baf735450 match=0409506f9a09
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x555baf735450 match=7f506f9a09
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x555baf735450 match=0801
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x555baf735450 match=040e
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x555baf735450 match=06
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x555baf735450 match=0a07
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x555baf735450 match=0a11
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x555baf735450 match=1101
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x555baf735450 match=1102
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x555baf735450 match=0505
Aug 31 15:36:14 ripley wpa_supplicant[5474]: netlink: Operstate: ifindex=3 linkmode=1 (userspace-control), operstate=5 (IF_OPER_DORMANT)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: driver param='(null)'
Aug 31 15:36:14 ripley wpa_supplicant[5474]: Add interface wlxf4f26d10c509 to a new radio phy0
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Regulatory information - country=00
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: 2402-2472 @ 40 MHz 20 mBm
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: 2457-2482 @ 40 MHz 20 mBm (no IR)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: 2474-2494 @ 20 MHz 20 mBm (no OFDM) (no IR)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: 5170-5250 @ 80 MHz 20 mBm (no IR)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: 5250-5330 @ 80 MHz 20 mBm (DFS) (no IR)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: 5490-5730 @ 160 MHz 20 mBm (DFS) (no IR)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: 5735-5835 @ 80 MHz 20 mBm (no IR)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: 57240-63720 @ 2160 MHz 0 mBm
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Added 802.11b mode based on 802.11g information
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Own MAC address: 26:49:be:db:32:12
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wpa_driver_nl80211_set_key: ifindex=3 (wlxf4f26d10c509) alg=0 addr=(nil) key_idx=0 set_tx=0 seq_len=0 key_len=0
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wpa_driver_nl80211_set_key: ifindex=3 (wlxf4f26d10c509) alg=0 addr=(nil) key_idx=1 set_tx=0 seq_len=0 key_len=0
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wpa_driver_nl80211_set_key: ifindex=3 (wlxf4f26d10c509) alg=0 addr=(nil) key_idx=2 set_tx=0 seq_len=0 key_len=0
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wpa_driver_nl80211_set_key: ifindex=3 (wlxf4f26d10c509) alg=0 addr=(nil) key_idx=3 set_tx=0 seq_len=0 key_len=0
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wpa_driver_nl80211_set_key: ifindex=3 (wlxf4f26d10c509) alg=0 addr=(nil) key_idx=4 set_tx=0 seq_len=0 key_len=0
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wpa_driver_nl80211_set_key: ifindex=3 (wlxf4f26d10c509) alg=0 addr=(nil) key_idx=5 set_tx=0 seq_len=0 key_len=0
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wlxf4f26d10c509: RSN: flushing PMKID list in the driver
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Flush PMKIDs
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wlxf4f26d10c509: State: DISCONNECTED -> INACTIVE
Aug 31 15:36:14 ripley wpa_supplicant[5474]: TDLS: TDLS operation supported by driver
Aug 31 15:36:14 ripley wpa_supplicant[5474]: TDLS: Driver uses external link setup
Aug 31 15:36:14 ripley wpa_supplicant[5474]: TDLS: Driver does not support TDLS channel switching
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wlxf4f26d10c509: WPS: UUID based on MAC address: 542e9077-1662-5d38-88c9-053b2438b2f7
Aug 31 15:36:14 ripley wpa_supplicant[5474]: ENGINE: Loading dynamic engine
Aug 31 15:36:14 ripley wpa_supplicant[5474]: ENGINE: Loading dynamic engine
Aug 31 15:36:14 ripley wpa_supplicant[5474]: EAPOL: SUPP_PAE entering state DISCONNECTED
Aug 31 15:36:14 ripley wpa_supplicant[5474]: EAPOL: Supplicant port status: Unauthorized
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Skip set_supp_port(unauthorized) while not associated
Aug 31 15:36:14 ripley wpa_supplicant[5474]: EAPOL: KEY_RX entering state NO_KEY_RECEIVE
Aug 31 15:36:14 ripley wpa_supplicant[5474]: EAPOL: SUPP_BE entering state INITIALIZE
Aug 31 15:36:14 ripley wpa_supplicant[5474]: EAP: EAP entering state DISABLED
Aug 31 15:36:14 ripley wpa_supplicant[5474]: P2P: Add operating class 81
[...]
Aug 31 15:36:14 ripley wpa_supplicant[5474]: P2P: Own listen channel: 81:6
Aug 31 15:36:14 ripley wpa_supplicant[5474]: P2P: Random operating channel: 81:6
Aug 31 15:36:14 ripley wpa_supplicant[5474]: P2P: initialized
Aug 31 15:36:14 ripley wpa_supplicant[5474]: P2P: channels: 81:1,2,3,4,5,6,7,8,9,10,11,12,13
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7431] sup-iface[0x13c4d90,wlxf4f26d10c509]: interface added to supplicant
Aug 31 15:36:14 ripley wpa_supplicant[5474]: P2P: cli_channels:
Aug 31 15:36:14 ripley wpa_supplicant[5474]: dbus: Register interface object '/fi/w1/wpa_supplicant1/Interfaces/3'
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Added interface wlxf4f26d10c509
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wlxf4f26d10c509: State: INACTIVE -> DISCONNECTED
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Set wlxf4f26d10c509 operstate 0->0 (DORMANT)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: netlink: Operstate: ifindex=3 linkmode=-1 (no change), operstate=5 (IF_OPER_DORMANT)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: RTM_NEWLINK: ifi_index=3 ifname= operstate=2 linkmode=1 ifi_family=0 ifi_flags=0x1003 ([UP])
Aug 31 15:36:14 ripley wpa_supplicant[5474]: dbus: org.freedesktop.DBus.Properties.GetAll (/fi/w1/wpa_supplicant1/Interfaces/3) [s]
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.7449] sup-iface[0x13c4d90,wlxf4f26d10c509]: supports 4 scan SSIDs
Aug 31 15:36:14 ripley wpa_supplicant[5474]: dbus: org.freedesktop.DBus.Properties.Set (/fi/w1/wpa_supplicant1/Interfaces/3) [ssv]
Aug 31 15:36:14 ripley wpa_supplicant[5474]: properties_get_or_set: Set(BSSExpireAge)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Setting bss expiration age: 250 sec
Aug 31 15:36:14 ripley wpa_supplicant[5474]: dbus: org.freedesktop.DBus.Properties.Set (/fi/w1/wpa_supplicant1/Interfaces/3) [ssv]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7457] sup-iface[0x13c4d90,wlxf4f26d10c509]: supplicant supports network credentials requests
Aug 31 15:36:14 ripley wpa_supplicant[5474]: properties_get_or_set: Set(BSSExpireCount)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.7460] device (wlxf4f26d10c509): supplicant interface state: starting -> ready
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Setting bss expiration scan count: 2
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7460] device[0x137f9b0] (wlxf4f26d10c509): supplicant ready
Aug 31 15:36:14 ripley wpa_supplicant[5474]: dbus: fi.w1.wpa_supplicant1.Interface.NetworkReply (/fi/w1/wpa_supplicant1/Interfaces/3) [oss]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7460] device[0x137f9b0] (wlxf4f26d10c509): remove_pending_action (0): 'waiting for supplicant'
Aug 31 15:36:14 ripley wpa_supplicant[5474]: dbus: org.freedesktop.DBus.Introspectable.Introspect (/fi/w1/wpa_supplicant1/Interfaces/3) []
Aug 31 15:36:14 ripley kernel: IPv6: ADDRCONF(NETDEV_UP): wlxf4f26d10c509: link is not ready
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.7460] ignoring notification for prop has-pending-action on type NMDeviceWifi
Aug 31 15:36:14 ripley wpa_supplicant[5474]: RTM_NEWLINK: ifi_index=3 ifname= operstate=2 linkmode=1 ifi_family=0 ifi_flags=0x1003 ([UP])
[...]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7460] manager: check_if_startup_complete returns FALSE because of eth0
Aug 31 15:36:14 ripley wpa_supplicant[5474]: dbus: fi.w1.wpa_supplicant1.Interface.Scan (/fi/w1/wpa_supplicant1/Interfaces/3) [a{sv}]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7461] device[0x137f9b0] (wlxf4f26d10c509): add_pending_action (1): 'queued state change to disconnected'
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Add radio work 'scan'@0x555baf72ba20
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.7461] ignoring notification for prop has-pending-action on type NMDeviceWifi
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wlxf4f26d10c509: First radio work item in the queue - schedule start immediately
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7461] manager: check_if_startup_complete returns FALSE because of eth0
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Starting radio work 'scan'@0x555baf72ba20 after 0.000043 second wait
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7461] device[0x137f9b0] (wlxf4f26d10c509): queued state change to disconnected due to supplicant-available (id 149)
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wlxf4f26d10c509: nl80211: scan request
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7462] device[0x137f9b0] (wlxf4f26d10c509): is available, will transition to disconnected
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7462] device[0x137f9b0] (wlxf4f26d10c509): running queued state change to disconnected (id 149)
Aug 31 15:36:14 ripley NetworkManager[5480]: <info>  [1472614574.7462] device (wlxf4f26d10c509): state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7462] device[0x137f9b0] (wlxf4f26d10c509): will enable userland IPv6LL
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7462] platform-linux: link: change 3: user-ipv6ll: set IPv6 address generation mode to none
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7463] platform-linux: do-request-link: 3 
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.7463] platform-linux: event-notification: NEWLINK, seq 0: 3: wlxf4f26d10c509 <UP;broadcast,multicast,up> mtu 1500 arp 1 wifi? not-init addrgenmode none addr 26:49:BE:DB:32:12 rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.7464] platform-linux: event-notification: NEWLINK, seq 25: 3: wlxf4f26d10c509 <UP;broadcast,multicast,up> mtu 1500 arp 1 wifi? not-init addrgenmode none addr 26:49:BE:DB:32:12 rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7464] platform-linux: do-change-link[3]: success changing link: success
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7465] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/wlxf4f26d10c509/disable_ipv6' to '1' (current value is '0')
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7465] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/wlxf4f26d10c509/disable_ipv6' to '0' (current value is '1')
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7466] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/wlxf4f26d10c509/accept_ra_defrtr' to '0' (current value is identical)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7466] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/wlxf4f26d10c509/accept_ra_pinfo' to '0' (current value is identical)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7466] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/wlxf4f26d10c509/accept_ra_rtr_pref' to '0' (current value is identical)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7466] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/wlxf4f26d10c509/use_tempaddr' to '0' (current value is identical)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7468] device[0x137f9b0] (wlxf4f26d10c509): scanning requested
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7469] device[0x137f9b0] (wlxf4f26d10c509): no SSIDs to probe scan
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.7469] device[0x137f9b0] (wlxf4f26d10c509): set-hw-addr: no MAC address change needed (26:49:BE:DB:32:12)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7469] device[0x137f9b0] (wlxf4f26d10c509): add_pending_action (2): 'scan'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7470] device[0x137f9b0] (wlxf4f26d10c509): scheduled scan in 3 seconds (interval now 23 seconds)
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7470] device[0x137f9b0] (wlxf4f26d10c509): add_pending_action (3): 'autoactivate'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7471] device[0x137f9b0] (wlxf4f26d10c509): remove_pending_action (2): 'queued state change to disconnected'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7472] PropertiesChanged NMDeviceWifi 0x137f9b0: {'State': <uint32 30>, 'StateReason': <(uint32 30, uint32 42)>}
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7473] PropertiesChanged NMManager 0x1378050: {'ActiveConnections': <@ao []>}
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7476] device[0x137f9b0] (wlxf4f26d10c509): remove_pending_action (1): 'autoactivate'
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7476] device[0x137f9b0] (wlxf4f26d10c509): scan successful
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7477] device[0x137f9b0] (wlxf4f26d10c509): remove_pending_action (0): 'scan'
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.7477] ignoring notification for prop has-pending-action on type NMDeviceWifi
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.7477] manager: check_if_startup_complete returns FALSE because of eth0
Aug 31 15:36:14 ripley wpa_supplicant[5474]: Scan requested (ret=0) - scan timeout 10 seconds
Aug 31 15:36:14 ripley wpa_supplicant[5474]: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/3
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Event message available
Aug 31 15:36:14 ripley wpa_supplicant[5474]: nl80211: Drv Event 33 (NL80211_CMD_TRIGGER_SCAN) received for wlxf4f26d10c509
Aug 31 15:36:14 ripley NetworkManager[5480]: <debug> [1472614574.8176] device[0x137f9b0] (wlxf4f26d10c509): now scanning
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wlxf4f26d10c509: nl80211: Scan trigger
Aug 31 15:36:14 ripley NetworkManager[5480]: <trace> [1472614574.8177] ignoring notification for prop scanning on type NMDeviceWifi
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Event SCAN_STARTED (47) received
Aug 31 15:36:14 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Own scan request started a scan in 0.000197 seconds
Aug 31 15:36:15 ripley wpa_supplicant[5474]: EAPOL: disable timer tick
Aug 31 15:36:15 ripley wpa_supplicant[5474]: nl80211: Event message available
Aug 31 15:36:15 ripley NetworkManager[5480]: <trace> [1472614575.8511] platform-linux: event-notification: NEWLINK, seq 0: 3: wlxf4f26d10c509 <UP;broadcast,multicast,up> mtu 0 arp 1 wifi? not-init addrgenmode none addr 26:49:BE:DB:32:12 rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8512] platform: signal: link changed: 3: wlxf4f26d10c509 <UP;broadcast,multicast,up> mtu 0 arp 1 wifi? init addrgenmode none addr 26:49:BE:DB:32:12 driver ath9k_htc rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8512] device[0x137f9b0] (wlxf4f26d10c509): queued link change for ifindex 3
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8514] PropertiesChanged NMDeviceWifi 0x137f9b0: {'Mtu': <uint32 0>}
Aug 31 15:36:15 ripley wpa_supplicant[5474]: nl80211: Drv Event 34 (NL80211_CMD_NEW_SCAN_RESULTS) received for wlxf4f26d10c509
Aug 31 15:36:15 ripley wpa_supplicant[5474]: wlxf4f26d10c509: nl80211: New scan results available
Aug 31 15:36:15 ripley wpa_supplicant[5474]: nl80211: Scan probed for SSID ''
Aug 31 15:36:15 ripley wpa_supplicant[5474]: nl80211: Scan included frequencies: 2412 2417 2422 2427 2432 2437 2442 2447 2452 2457 2462 2467 2472
Aug 31 15:36:15 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Event SCAN_RESULTS (3) received
Aug 31 15:36:15 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Scan completed in 1.035565 seconds
Aug 31 15:36:15 ripley wpa_supplicant[5474]: nl80211: Received scan results (2 BSSes)
Aug 31 15:36:15 ripley wpa_supplicant[5474]: Sorted scan results
Aug 31 15:36:15 ripley wpa_supplicant[5474]: ec:cb:30:60:58:32 freq=2447 qual=0 noise=-89~ level=-65 snr=24 flags=0xb age=324 est=65000
[...]
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8544] device[0x137f9b0] (wlxf4f26d10c509): scan successful
[...]
Aug 31 15:36:15 ripley wpa_supplicant[5474]: 94:fe:22:e7:11:14 freq=2432 qual=0 noise=-89~ level=-84 snr=5 flags=0xb age=680 est=9000
[...]
[...]
Aug 31 15:36:15 ripley wpa_supplicant[5474]: wlxf4f26d10c509: BSS: Start scan result update 1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: wlxf4f26d10c509: BSS: Add new id 0 BSSID ec:cb:30:60:58:32 SSID 'SpeakFriendAndEnter'
Aug 31 15:36:15 ripley wpa_supplicant[5474]: dbus: Register BSS object '/fi/w1/wpa_supplicant1/Interfaces/3/BSSs/0'
[...]
Aug 31 15:36:15 ripley wpa_supplicant[5474]: dbus: Register BSS object '/fi/w1/wpa_supplicant1/Interfaces/3/BSSs/1'
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x104a len=1
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8578] added   [EC:CB:30:60:58:32 ] SpeakFriendAndEnter             [  8  59% P W:0000 R:0188] [  0] wlxf4f26d10c509/0
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1044 len=1
Aug 31 15:36:15 ripley NetworkManager[5480]: <trace> [1472614575.8582] exported-object[0x13c4f60]: export: "/org/freedesktop/NetworkManager/AccessPoint/0"
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x103b len=1
[...]
Aug 31 15:36:15 ripley NetworkManager[5480]: <trace> [1472614575.8582] ignoring notification for prop g-object-path on type NMAccessPoint
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1047 len=16
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8586] device[0x137f9b0] (wlxf4f26d10c509): add_pending_action (1): 'autoactivate'
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1021 len=8
Aug 31 15:36:15 ripley NetworkManager[5480]: <trace> [1472614575.8586] ignoring notification for prop has-pending-action on type NMDeviceWifi
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1023 len=8
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8586] manager: check_if_startup_complete returns FALSE because of eth0
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1024 len=6
Aug 31 15:36:15 ripley NetworkManager[5480]: <info>  [1472614575.8589] device (wlxf4f26d10c509): supplicant interface state: ready -> inactive
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1042 len=4
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8590] device[0x137f9b0] (wlxf4f26d10c509): scanning requested
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1054 len=8
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8590] device[0x137f9b0] (wlxf4f26d10c509): no SSIDs to probe scan
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1011 len=10
Aug 31 15:36:15 ripley NetworkManager[5480]: <trace> [1472614575.8590] device[0x137f9b0] (wlxf4f26d10c509): set-hw-addr: no MAC address change needed (26:49:BE:DB:32:12)
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1008 len=2
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8592] device[0x137f9b0] (wlxf4f26d10c509): add_pending_action (2): 'scan'
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x103c len=1
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8592] device[0x137f9b0] (wlxf4f26d10c509): scheduled scan in 23 seconds (interval now 33 seconds)
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1049 len=6
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8593] device[0x137f9b0] (wlxf4f26d10c509): now idle
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: WFA subelement id=0 len=1
Aug 31 15:36:15 ripley NetworkManager[5480]: <trace> [1472614575.8593] ignoring notification for prop scanning on type NMDeviceWifi
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x104a len=1
[...]
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1044 len=1
Aug 31 15:36:15 ripley NetworkManager[5480]: <trace> [1472614575.8601] exported-object[0x13be070]: export: "/org/freedesktop/NetworkManager/AccessPoint/1"
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x103b len=1
Aug 31 15:36:15 ripley NetworkManager[5480]: <trace> [1472614575.8601] ignoring notification for prop g-object-path on type NMAccessPoint
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1047 len=16
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8608] device[0x137f9b0] (wlxf4f26d10c509): scan successful
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1021 len=8
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8608] device[0x137f9b0] (wlxf4f26d10c509): remove_pending_action (1): 'scan'
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1023 len=8
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8609] PropertiesChanged NMDeviceWifi 0x137f9b0: {'AccessPoints': <[objectpath '/org/freedesktop/NetworkManager/AccessPoint/0', '/org/freedesktop/NetworkManager/AccessPoint/1']>, 'AvailableConnections': <[objectpath '/org/freedesktop/NetworkManager/Settings/3']>}
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1024 len=6
Aug 31 15:36:15 ripley NetworkManager[5480]: <info>  [1472614575.8611] policy: auto-activating connection 'SpeakFriendAndEnter'
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1042 len=4
Aug 31 15:36:15 ripley NetworkManager[5480]: <trace> [1472614575.8615] active-connection[0x13884d0]: creating
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1054 len=8
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8623] active-connection[0x13884d0]: set device "wlxf4f26d10c509" [0x137f9b0]
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1011 len=10
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8624] device[0x137f9b0] (wlxf4f26d10c509): add_pending_action (2): 'activation::0x13884d0'
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1008 len=2
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8624] active-connection[0x13884d0]: constructed (NMActRequest, version-id 1)
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x103c len=1
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8625] device[0x137f9b0] (wlxf4f26d10c509): remove_pending_action (1): 'autoactivate'
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1049 len=6
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8625] device[0x137f9b0] (wlxf4f26d10c509): unmanaged: flags set to [!sleeping,!loopback,!platform-init,!user-explicit,!user-settings=0x0/0x79/managed, set-managed [user-explicit=0x20], reason user-requested)
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: WFA subelement id=0 len=1
Aug 31 15:36:15 ripley NetworkManager[5480]: <trace> [1472614575.8630] exported-object[0x13884d0]: export: "/org/freedesktop/NetworkManager/ActiveConnection/0"
Aug 31 15:36:15 ripley wpa_supplicant[5474]: BSS: last_scan_res_used=2/32
Aug 31 15:36:15 ripley NetworkManager[5480]: <trace> [1472614575.8630] ignoring notification for prop g-object-path on type NMActRequest
Aug 31 15:36:15 ripley wpa_supplicant[5474]: Add randomness: count=5 entropy=4
Aug 31 15:36:15 ripley NetworkManager[5480]: <info>  [1472614575.8633] device (wlxf4f26d10c509): Activation: starting connection 'SpeakFriendAndEnter' (62264738-2059-42ce-8d14-fb17bda97211)
[...]
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8633] device[0x137f9b0] (wlxf4f26d10c509): activation-stage: schedule activate_stage1_device_prepare,2 (id 194)
[...]
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8635] device[0x137f9b0] (wlxf4f26d10c509): activation-stage: invoke activate_stage1_device_prepare,2 (id 194)
[...]
Aug 31 15:36:15 ripley NetworkManager[5480]: <info>  [1472614575.8636] device (wlxf4f26d10c509): state change: disconnected -> prepare (reason 'none') [30 40 0]
[...]
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8638] active-connection[0x13884d0]: set state activating (was unknown)
Aug 31 15:36:15 ripley wpa_supplicant[5474]: Add randomness: count=6 entropy=5
Aug 31 15:36:15 ripley NetworkManager[5480]: <info>  [1472614575.8638] manager: NetworkManager state is now CONNECTING
[...]
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8640] active-connection[0x13884d0]: check-master-ready: not signalling (state activating, no master)
[...]
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8640] manager: ActivatingConnection now SpeakFriendAndEnter
[...]
Aug 31 15:36:15 ripley NetworkManager[5480]: <trace> [1472614575.8641] device[0x137f9b0] (wlxf4f26d10c509): set-hw-addr: setting MAC address to 'F4:F2:6D:10:C5:09' (set-cloned, permanent)...
[...]
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8641] device[0x137f9b0] (wlxf4f26d10c509): taking down device
Aug 31 15:36:15 ripley wpa_supplicant[5474]: wlxf4f26d10c509: New scan results available (own=1 ext=0)
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8641] platform: link: setting down 'wlxf4f26d10c509' (3)
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x104a len=1
Aug 31 15:36:15 ripley NetworkManager[5480]: <debug> [1472614575.8642] platform-linux: link: change 3: flags: set 0x0/0x1 ([] / [up])
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1044 len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x103b len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1047 len=16
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1021 len=8
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1023 len=8
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1024 len=6
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1042 len=4
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1054 len=8
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1011 len=10
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1008 len=2
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x103c len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1049 len=6
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: WFA subelement id=0 len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x104a len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1044 len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x103b len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1047 len=16
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1021 len=8
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1023 len=8
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1024 len=6
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1042 len=4
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1054 len=8
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1011 len=10
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1008 len=2
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x103c len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1049 len=6
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: WFA subelement id=0 len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x104a len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1044 len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x103b len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1047 len=16
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1021 len=8
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1023 len=8
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1024 len=6
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1042 len=4
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1054 len=8
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1011 len=10
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1008 len=2
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x103c len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1049 len=6
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: WFA subelement id=0 len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x104a len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1044 len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x103b len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1047 len=16
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1021 len=8
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1023 len=8
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1024 len=6
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1042 len=4
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1054 len=8
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1011 len=10
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1008 len=2
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x103c len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1049 len=6
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: WFA subelement id=0 len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: AP 94:fe:22:e7:11:14 type 0 added
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: AP[0] 94:fe:22:e7:11:14 type=0 tries=0 last_attempt=-1 sec ago blacklist=0
Aug 31 15:36:15 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Radio work 'scan'@0x555baf72ba20 done in 1.106716 seconds
Aug 31 15:36:15 ripley wpa_supplicant[5474]: wlxf4f26d10c509: No suitable network found
Aug 31 15:36:15 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Short-circuit new scan request since there are no enabled networks
Aug 31 15:36:15 ripley wpa_supplicant[5474]: wlxf4f26d10c509: State: DISCONNECTED -> INACTIVE
Aug 31 15:36:15 ripley wpa_supplicant[5474]: RTM_NEWLINK: ifi_index=3 ifname= wext ifi_family=0 ifi_flags=0x1003 ([UP])
Aug 31 15:36:15 ripley wpa_supplicant[5474]: dbus: org.freedesktop.DBus.Properties.GetAll (/fi/w1/wpa_supplicant1/Interfaces/3/BSSs/0) [s]
Aug 31 15:36:15 ripley wpa_supplicant[5474]: dbus: org.freedesktop.DBus.Properties.GetAll (/fi/w1/wpa_supplicant1/Interfaces/3/BSSs/1) [s]
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x104a len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1044 len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x103b len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1047 len=16
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1021 len=8
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1023 len=8
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1024 len=6
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1042 len=4
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1054 len=8
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1011 len=10
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1008 len=2
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x103c len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1049 len=6
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: WFA subelement id=0 len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x104a len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1044 len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x103b len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1047 len=16
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1021 len=8
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1023 len=8
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1024 len=6
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1042 len=4
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1054 len=8
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1011 len=10
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1008 len=2
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x103c len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: attr type=0x1049 len=6
Aug 31 15:36:15 ripley wpa_supplicant[5474]: WPS: WFA subelement id=0 len=1
Aug 31 15:36:15 ripley wpa_supplicant[5474]: dbus: fi.w1.wpa_supplicant1.Interface.Scan (/fi/w1/wpa_supplicant1/Interfaces/3) [a{sv}]
Aug 31 15:36:15 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Add radio work 'scan'@0x555baf729560
Aug 31 15:36:15 ripley wpa_supplicant[5474]: wlxf4f26d10c509: First radio work item in the queue - schedule start immediately
Aug 31 15:36:15 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Starting radio work 'scan'@0x555baf729560 after 0.000057 second wait
Aug 31 15:36:15 ripley wpa_supplicant[5474]: wlxf4f26d10c509: nl80211: scan request
Aug 31 15:36:15 ripley wpa_supplicant[5474]: Scan requested (ret=0) - scan timeout 30 seconds
Aug 31 15:36:15 ripley wpa_supplicant[5474]: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/3
Aug 31 15:36:15 ripley wpa_supplicant[5474]: nl80211: Event message available
Aug 31 15:36:15 ripley wpa_supplicant[5474]: nl80211: Drv Event 33 (NL80211_CMD_TRIGGER_SCAN) received for wlxf4f26d10c509
Aug 31 15:36:15 ripley wpa_supplicant[5474]: wlxf4f26d10c509: nl80211: Scan trigger
Aug 31 15:36:15 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Event SCAN_STARTED (47) received
Aug 31 15:36:15 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Own scan request started a scan in 0.000180 seconds
Aug 31 15:36:16 ripley wpa_supplicant[5474]: RTM_NEWLINK: ifi_index=3 ifname= operstate=2 linkmode=1 ifi_family=0 ifi_flags=0x1002 ()
Aug 31 15:36:16 ripley wpa_supplicant[5474]: nl80211: Interface down (wlxf4f26d10c509/)
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.1066] platform-linux: do-request-link: 3 
Aug 31 15:36:16 ripley wpa_supplicant[5474]: nl80211: Not the main interface (wlxf4f26d10c509) - do not indicate interface down
Aug 31 15:36:16 ripley NetworkManager[5480]: <trace> [1472614576.1067] platform-linux: event-notification: NEWLINK, seq 0: 3: wlxf4f26d10c509 <DOWN;broadcast,multicast> mtu 1500 arp 1 wifi? not-init addrgenmode none addr 26:49:BE:DB:32:12 rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:16 ripley wpa_supplicant[5474]: nl80211: Event message available
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.1068] platform: signal: link changed: 3: wlxf4f26d10c509 <DOWN;broadcast,multicast> mtu 1500 arp 1 wifi? init addrgenmode none addr 26:49:BE:DB:32:12 driver ath9k_htc rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:16 ripley wpa_supplicant[5474]: nl80211: Drv Event 35 (NL80211_CMD_SCAN_ABORTED) received for wlxf4f26d10c509
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.1069] device[0x137f9b0] (wlxf4f26d10c509): queued link change for ifindex 3
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: nl80211: Scan aborted
Aug 31 15:36:16 ripley NetworkManager[5480]: <trace> [1472614576.1070] platform-linux: event-notification: NEWLINK, seq 27: 3: wlxf4f26d10c509 <DOWN;broadcast,multicast> mtu 1500 arp 1 wifi? not-init addrgenmode none addr 26:49:BE:DB:32:12 rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:16 ripley wpa_supplicant[5474]: nl80211: Scan probed for SSID ''
Aug 31 15:36:16 ripley NetworkManager[5480]: <trace> [1472614576.1071] platform-linux: event-notification: NEWROUTE, seq 28: ignore
Aug 31 15:36:16 ripley wpa_supplicant[5474]: nl80211: Scan included frequencies: 2412 2417 2422 2427 2432 2437 2442 2447 2452 2457 2462 2467 2472
Aug 31 15:36:16 ripley NetworkManager[5480]: <trace> [1472614576.1071] platform-linux: event-notification: NEWROUTE, seq 28: ignore
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Event SCAN_RESULTS (3) received
Aug 31 15:36:16 ripley NetworkManager[5480]: <trace> [1472614576.1071] platform-linux: event-notification: NEWROUTE, seq 28: ignore
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Scan completed in 0.111877 seconds
Aug 31 15:36:16 ripley NetworkManager[5480]: <trace> [1472614576.1071] platform-linux: event-notification: NEWROUTE, seq 28: ignore
Aug 31 15:36:16 ripley wpa_supplicant[5474]: nl80211: Received scan results (2 BSSes)
Aug 31 15:36:16 ripley NetworkManager[5480]: <trace> [1472614576.1072] platform-linux: event-notification: NEWROUTE, seq 29: ignore
Aug 31 15:36:16 ripley wpa_supplicant[5474]: Sorted scan results
Aug 31 15:36:16 ripley NetworkManager[5480]: <trace> [1472614576.1072] platform-linux: event-notification: NEWROUTE, seq 29: ignore
Aug 31 15:36:16 ripley wpa_supplicant[5474]: ec:cb:30:60:58:32 freq=2447 qual=0 noise=-89~ level=-65 snr=24 flags=0xb age=180 est=65000
Aug 31 15:36:16 ripley NetworkManager[5480]: <trace> [1472614576.1072] platform-linux: event-notification: NEWROUTE, seq 29: ignore
[...]
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.1073] platform-linux: do-change-link[3]: success changing link: success
[...]
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.1073] platform: link: setting 'wlxf4f26d10c509' (3) hardware address
Aug 31 15:36:16 ripley wpa_supplicant[5474]: 94:fe:22:e7:11:14 freq=2432 qual=0 noise=-89~ level=-84 snr=5 flags=0xb age=180 est=9000
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.1073] platform-linux: link: change 3: address: F4:F2:6D:10:C5:09 (6 bytes)
[...]
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.1074] platform-linux: do-request-link: 3 
[...]
Aug 31 15:36:16 ripley NetworkManager[5480]: <trace> [1472614576.1075] platform-linux: event-notification: NEWLINK, seq 0: 3: wlxf4f26d10c509 <DOWN;broadcast,multicast> mtu 1500 arp 1 wifi? not-init addrgenmode none addr F4:F2:6D:10:C5:09 rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: BSS: Start scan result update 2
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.1075] platform: signal: link changed: 3: wlxf4f26d10c509 <DOWN;broadcast,multicast> mtu 1500 arp 1 wifi? init addrgenmode none addr F4:F2:6D:10:C5:09 driver ath9k_htc rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:16 ripley wpa_supplicant[5474]: BSS: last_scan_res_used=2/32
Aug 31 15:36:16 ripley NetworkManager[5480]: <trace> [1472614576.1076] platform-linux: event-notification: NEWLINK, seq 31: 3: wlxf4f26d10c509 <DOWN;broadcast,multicast> mtu 1500 arp 1 wifi? not-init addrgenmode none addr F4:F2:6D:10:C5:09 rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:16 ripley wpa_supplicant[5474]: Add randomness: count=7 entropy=6
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.1079] platform-linux: do-change-link[3]: success changing link: success
[...]
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.1079] device[0x137f9b0] (wlxf4f26d10c509): hw-addr: hardware address now F4:F2:6D:10:C5:09
[...]
Aug 31 15:36:16 ripley NetworkManager[5480]: <info>  [1472614576.1080] device (wlxf4f26d10c509): set-hw-addr: set-cloned MAC address to F4:F2:6D:10:C5:09 (permanent)
[...]
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.1080] device[0x137f9b0] (wlxf4f26d10c509): bringing up device
[...]
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.1080] platform: link: setting up 'wlxf4f26d10c509' (3)
Aug 31 15:36:16 ripley wpa_supplicant[5474]: Add randomness: count=8 entropy=7
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.1081] platform-linux: link: change 3: flags: set 0x1/0x1 ([up] / [up])
[...]
[...]
[...]
[...]
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: New scan results available (own=1 ext=0)
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x104a len=1
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1044 len=1
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x103b len=1
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1047 len=16
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1021 len=8
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1023 len=8
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1024 len=6
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1042 len=4
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1054 len=8
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1011 len=10
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1008 len=2
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x103c len=1
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1049 len=6
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: WFA subelement id=0 len=1
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x104a len=1
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1044 len=1
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x103b len=1
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1047 len=16
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1021 len=8
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1023 len=8
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1024 len=6
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1042 len=4
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1054 len=8
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1011 len=10
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1008 len=2
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x103c len=1
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1049 len=6
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: WFA subelement id=0 len=1
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x104a len=1
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1044 len=1
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x103b len=1
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1047 len=16
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1021 len=8
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1023 len=8
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1024 len=6
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1042 len=4
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1054 len=8
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1011 len=10
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1008 len=2
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x103c len=1
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1049 len=6
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: WFA subelement id=0 len=1
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x104a len=1
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1044 len=1
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x103b len=1
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1047 len=16
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1021 len=8
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1023 len=8
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1024 len=6
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1042 len=4
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1054 len=8
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1011 len=10
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1008 len=2
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x103c len=1
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: attr type=0x1049 len=6
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: WFA subelement id=0 len=1
Aug 31 15:36:16 ripley wpa_supplicant[5474]: WPS: AP[0] 94:fe:22:e7:11:14 type=0 tries=0 last_attempt=-1 sec ago blacklist=0
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Radio work 'scan'@0x555baf729560 done in 0.248353 seconds
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: No suitable network found
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Short-circuit new scan request since there are no enabled networks
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: State: INACTIVE -> INACTIVE
Aug 31 15:36:16 ripley wpa_supplicant[5474]: l2_packet_receive - recvfrom: Network is down
Aug 31 15:36:16 ripley wpa_supplicant[5474]: l2_packet_receive - recvfrom: Network is down
Aug 31 15:36:16 ripley wpa_supplicant[5474]: RTM_NEWLINK: ifi_index=3 ifname= operstate=2 linkmode=1 ifi_family=0 ifi_flags=0x1002 ()
Aug 31 15:36:16 ripley wpa_supplicant[5474]: nl80211: Interface down (wlxf4f26d10c509/)
Aug 31 15:36:16 ripley wpa_supplicant[5474]: nl80211: Not the main interface (wlxf4f26d10c509) - do not indicate interface down
Aug 31 15:36:16 ripley wpa_supplicant[5474]: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/3
Aug 31 15:36:16 ripley wpa_supplicant[5474]: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/3/BSSs/0
Aug 31 15:36:16 ripley wpa_supplicant[5474]: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/3/BSSs/1
Aug 31 15:36:16 ripley wpa_supplicant[5474]: RTM_NEWLINK: ifi_index=3 ifname= operstate=2 linkmode=1 ifi_family=0 ifi_flags=0x1003 ([UP])
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2799] platform-linux: do-request-link: 3 
Aug 31 15:36:16 ripley NetworkManager[5480]: <trace> [1472614576.2799] platform-linux: event-notification: NEWLINK, seq 0: 3: wlxf4f26d10c509 <UP;broadcast,multicast,up> mtu 1500 arp 1 wifi? not-init addrgenmode none addr F4:F2:6D:10:C5:09 rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2800] platform: signal: link changed: 3: wlxf4f26d10c509 <UP;broadcast,multicast,up> mtu 1500 arp 1 wifi? init addrgenmode none addr F4:F2:6D:10:C5:09 driver ath9k_htc rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:16 ripley NetworkManager[5480]: <trace> [1472614576.2801] platform-linux: event-notification: NEWLINK, seq 33: 3: wlxf4f26d10c509 <UP;broadcast,multicast,up> mtu 1500 arp 1 wifi? not-init addrgenmode none addr F4:F2:6D:10:C5:09 rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2802] platform-linux: do-change-link[3]: success changing link: success
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2803] device[0x137f9b0] (wlxf4f26d10c509): activation-stage: schedule activate_stage2_device_config,2 (id 215)
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2803] device[0x137f9b0] (wlxf4f26d10c509): activation-stage: complete activate_stage1_device_prepare,2 (id 194)
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2804] PropertiesChanged NMManager 0x1378050: {'ActivatingConnection': <objectpath '/org/freedesktop/NetworkManager/ActiveConnection/0'>, 'ActiveConnections': <[objectpath '/org/freedesktop/NetworkManager/ActiveConnection/0']>, 'State': <uint32 40>}
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2813] device[0x137f9b0] (wlxf4f26d10c509): now scanning
Aug 31 15:36:16 ripley NetworkManager[5480]: <trace> [1472614576.2813] ignoring notification for prop scanning on type NMDeviceWifi
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2815] device[0x137f9b0] (wlxf4f26d10c509): scan successful
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2819] updated [EC:CB:30:60:58:32 ] SpeakFriendAndEnter             [  8  59% P W:0000 R:0188] [  0] wlxf4f26d10c509/0
[...]
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2828] device[0x137f9b0] (wlxf4f26d10c509): now idle
Aug 31 15:36:16 ripley kernel: IPv6: ADDRCONF(NETDEV_UP): wlxf4f26d10c509: link is not ready
Aug 31 15:36:16 ripley NetworkManager[5480]: <trace> [1472614576.2828] ignoring notification for prop scanning on type NMDeviceWifi
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2829] updated [EC:CB:30:60:58:32 ] SpeakFriendAndEnter             [  8  59% P W:0000 R:0188] [  0] wlxf4f26d10c509/0
[...]
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2834] PropertiesChanged NMDeviceWifi 0x137f9b0: {'ActiveAccessPoint': <objectpath '/org/freedesktop/NetworkManager/AccessPoint/0'>, 'ActiveConnection': <objectpath '/org/freedesktop/NetworkManager/ActiveConnection/0'>, 'HwAddress': <'F4:F2:6D:10:C5:09'>, 'Mode': <uint32 2>, 'State': <uint32 40>, 'StateReason': <(uint32 40, uint32 0)>}
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2835] PropertiesChanged NMActRequest 0x13884d0: {'State': <uint32 1>}
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2836] device[0x137f9b0] (wlxf4f26d10c509): activation-stage: invoke activate_stage2_device_config,2 (id 215)
Aug 31 15:36:16 ripley NetworkManager[5480]: <info>  [1472614576.2837] device (wlxf4f26d10c509): state change: prepare -> config (reason 'none') [40 50 0]
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2839] device[0x137f9b0] (wlxf4f26d10c509): bringing up device
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2839] platform: link: setting up 'wlxf4f26d10c509' (3)
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2839] platform-linux: link: change 3: flags: set 0x1/0x1 ([up] / [up])
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2840] platform-linux: do-request-link: 3 
Aug 31 15:36:16 ripley NetworkManager[5480]: <trace> [1472614576.2840] platform-linux: event-notification: NEWLINK, seq 35: 3: wlxf4f26d10c509 <UP;broadcast,multicast,up> mtu 1500 arp 1 wifi? not-init addrgenmode none addr F4:F2:6D:10:C5:09 rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2841] platform-linux: do-change-link[3]: success changing link: success
Aug 31 15:36:16 ripley NetworkManager[5480]: <info>  [1472614576.2841] device (wlxf4f26d10c509): Activation: (wifi) access point 'SpeakFriendAndEnter' has security, but secrets are required.
Aug 31 15:36:16 ripley NetworkManager[5480]: <info>  [1472614576.2842] device (wlxf4f26d10c509): state change: config -> need-auth (reason 'none') [50 60 0]
Aug 31 15:36:16 ripley NetworkManager[5480]: <trace> [1472614576.2853] ignoring notification for prop flags on type NMKeyfileConnection
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2864] Secrets requested for connection /org/freedesktop/NetworkManager/Settings/3 (SpeakFriendAndEnter/802-11-wireless-security)
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2866] agent-manager: req[0x13fb6e0, :1.22/org.freedesktop.nm-applet/1000]: agent allowed for secrets request [0x1446710/"SpeakFriendAndEnter"/"802-11-wireless-security"]
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2866] settings-connection[0x1359af0,62264738-2059-42ce-8d14-fb17bda97211]: (802-11-wireless-security:0x1446710) secrets requested flags 0x1 hints '(none)'
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2866] device[0x137f9b0] (wlxf4f26d10c509): activation-stage: complete activate_stage2_device_config,2 (id 215)
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2867] PropertiesChanged NMAccessPoint 0x13c4f60: {'LastSeen': <4002>}
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2869] PropertiesChanged NMAccessPoint 0x13be070: {'LastSeen': <4002>}
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2874] PropertiesChanged NMDeviceWifi 0x137f9b0: {'Mtu': <uint32 1500>, 'State': <uint32 60>, 'StateReason': <(uint32 60, uint32 0)>}
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2876] PropertiesChanged NMKeyfileConnection 0x1359af0: {'Unsaved': <true>}
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2878] PropertiesChanged NMActRequest 0x13884d0: {'Id': <'SpeakFriendAndEnter'>, 'Type': <'802-11-wireless'>}
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2886] agent-manager: ([0x1446710/"SpeakFriendAndEnter"/"802-11-wireless-security"]) system settings secrets sufficient
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2893] settings-connection[0x1359af0,62264738-2059-42ce-8d14-fb17bda97211]: (802-11-wireless-security:0x13ff580) existing secrets returned
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2893] settings-connection[0x1359af0,62264738-2059-42ce-8d14-fb17bda97211]: (802-11-wireless-security:0x13ff580) secrets request completed
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2912] settings-connection[0x1359af0,62264738-2059-42ce-8d14-fb17bda97211]: (802-11-wireless-security:0x13ff580) new agent secrets processed
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2915] device[0x137f9b0] (wlxf4f26d10c509): activation-stage: schedule activate_stage1_device_prepare,2 (id 240)
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2957] PropertiesChanged NMActRequest 0x13884d0: {'Id': <'SpeakFriendAndEnter'>, 'Type': <'802-11-wireless'>}
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2957] device[0x137f9b0] (wlxf4f26d10c509): activation-stage: invoke activate_stage1_device_prepare,2 (id 240)
Aug 31 15:36:16 ripley NetworkManager[5480]: <info>  [1472614576.2958] device (wlxf4f26d10c509): state change: need-auth -> prepare (reason 'none') [60 40 0]
Aug 31 15:36:16 ripley NetworkManager[5480]: <trace> [1472614576.2959] device[0x137f9b0] (wlxf4f26d10c509): set-hw-addr: no MAC address change needed (F4:F2:6D:10:C5:09)
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2960] device[0x137f9b0] (wlxf4f26d10c509): activation-stage: schedule activate_stage2_device_config,2 (id 246)
Aug 31 15:36:16 ripley wpa_supplicant[5474]: dbus: org.freedesktop.DBus.Properties.Set (/fi/w1/wpa_supplicant1/Interfaces/3) [ssv]
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2960] device[0x137f9b0] (wlxf4f26d10c509): activation-stage: complete activate_stage1_device_prepare,2 (id 240)
Aug 31 15:36:16 ripley wpa_supplicant[5474]: properties_get_or_set: Set(ApScan)
[...]
Aug 31 15:36:16 ripley wpa_supplicant[5474]:      53 70 65 61 6b 46 72 69 65 6e 64 41 6e 64 45 6e   SpeakFriendAndEn
Aug 31 15:36:16 ripley wpa_supplicant[5474]:      74 65 72                                          ter
[...]
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2961] PropertiesChanged NMDeviceWifi 0x137f9b0: {'ActiveConnection': <objectpath '/org/freedesktop/NetworkManager/ActiveConnection/0'>, 'Mode': <uint32 2>, 'State': <uint32 40>, 'StateReason': <(uint32 40, uint32 0)>}
Aug 31 15:36:16 ripley wpa_supplicant[5474]: dbus: fi.w1.wpa_supplicant1.Interface.AddNetwork (/fi/w1/wpa_supplicant1/Interfaces/3) [a{sv}]
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2962] PropertiesChanged NMManager 0x1378050: {'ActiveConnections': <[objectpath '/org/freedesktop/NetworkManager/ActiveConnection/0']>}
Aug 31 15:36:16 ripley wpa_supplicant[5474]: dbus: Register network object '/fi/w1/wpa_supplicant1/Interfaces/3/Networks/0'
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2963] device[0x137f9b0] (wlxf4f26d10c509): activation-stage: invoke activate_stage2_device_config,2 (id 246)
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wpa_dbus_dict_open_read: start reading a dict entry
Aug 31 15:36:16 ripley NetworkManager[5480]: <info>  [1472614576.2963] device (wlxf4f26d10c509): state change: prepare -> config (reason 'none') [40 50 0]
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wpa_dbus_dict_get_entry: dict entry key: scan_ssid
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2964] device[0x137f9b0] (wlxf4f26d10c509): bringing up device
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wpa_dbus_dict_get_entry: dict entry variant content type: i
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2964] platform: link: setting up 'wlxf4f26d10c509' (3)
Aug 31 15:36:16 ripley wpa_supplicant[5474]: _wpa_dbus_dict_fill_value_from_variant: int32 value: 1
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2964] platform-linux: link: change 3: flags: set 0x1/0x1 ([up] / [up])
Aug 31 15:36:16 ripley wpa_supplicant[5474]: scan_ssid=1 (0x1)
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2965] platform-linux: do-request-link: 3 
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wpa_dbus_dict_get_entry: dict entry key: ssid
Aug 31 15:36:16 ripley NetworkManager[5480]: <trace> [1472614576.2965] platform-linux: event-notification: NEWLINK, seq 37: 3: wlxf4f26d10c509 <UP;broadcast,multicast,up> mtu 1500 arp 1 wifi? not-init addrgenmode none addr F4:F2:6D:10:C5:09 rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wpa_dbus_dict_get_entry: dict entry variant content type: a
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2965] platform-linux: do-change-link[3]: success changing link: success
Aug 31 15:36:16 ripley wpa_supplicant[5474]: _wpa_dbus_dict_entry_get_array: array_type y
Aug 31 15:36:16 ripley NetworkManager[5480]: <info>  [1472614576.2966] device (wlxf4f26d10c509): Activation: (wifi) connection 'SpeakFriendAndEnter' has security, and secrets exist.  No new secrets needed.
[...]
Aug 31 15:36:16 ripley NetworkManager[5480]: <trace> [1472614576.2966] device[0x137f9b0] (wlxf4f26d10c509): powersave is set to 1
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wpa_dbus_dict_get_entry: dict entry key: key_mgmt
Aug 31 15:36:16 ripley NetworkManager[5480]: <info>  [1472614576.2966] Config: added 'ssid' value 'SpeakFriendAndEnter'
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wpa_dbus_dict_get_entry: dict entry variant content type: s
Aug 31 15:36:16 ripley NetworkManager[5480]: <info>  [1472614576.2966] Config: added 'scan_ssid' value '1'
Aug 31 15:36:16 ripley wpa_supplicant[5474]: _wpa_dbus_dict_fill_value_from_variant: string value: [omitted]
Aug 31 15:36:16 ripley NetworkManager[5480]: <info>  [1472614576.2966] Config: added 'key_mgmt' value 'WPA-PSK'
Aug 31 15:36:16 ripley wpa_supplicant[5474]: key_mgmt: 0x2
Aug 31 15:36:16 ripley NetworkManager[5480]: <info>  [1472614576.2966] Config: added 'psk' value '<omitted>'
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wpa_dbus_dict_get_entry: dict entry key: psk
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2967] device[0x137f9b0] (wlxf4f26d10c509): activation-stage: complete activate_stage2_device_config,2 (id 246)
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wpa_dbus_dict_get_entry: dict entry variant content type: s
Aug 31 15:36:16 ripley NetworkManager[5480]: <debug> [1472614576.2968] PropertiesChanged NMDeviceWifi 0x137f9b0: {'State': <uint32 50>, 'StateReason': <(uint32 50, uint32 0)>}
Aug 31 15:36:16 ripley wpa_supplicant[5474]: _wpa_dbus_dict_fill_value_from_variant: string value: [omitted]
Aug 31 15:36:16 ripley NetworkManager[5480]: <info>  [1472614576.2977] sup-iface[0x13c4d90,wlxf4f26d10c509]: config: set interface ap_scan to 1
[...]
Aug 31 15:36:16 ripley wpa_supplicant[5474]: dbus: fi.w1.wpa_supplicant1.Interface.SelectNetwork (/fi/w1/wpa_supplicant1/Interfaces/3) [o]
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Selecting BSS from priority group 0
[...]
Aug 31 15:36:16 ripley wpa_supplicant[5474]:      53 70 65 61 6b 46 72 69 65 6e 64 41 6e 64 45 6e   SpeakFriendAndEn
Aug 31 15:36:16 ripley wpa_supplicant[5474]:      74 65 72                                          ter
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: 0: ec:cb:30:60:58:32 ssid='SpeakFriendAndEnter' wpa_ie_len=0 rsn_ie_len=20 caps=0x411 level=-65
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509:    selected based on RSN IE
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509:    selected BSS ec:cb:30:60:58:32 ssid='SpeakFriendAndEnter'
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Considering connect request: reassociate: 1  selected: ec:cb:30:60:58:32  bssid: 00:00:00:00:00:00  pending: 00:00:00:00:00:00  wpa_state: INACTIVE  ssid=0x555baf729eb0  current_ssid=0x555baf729eb0
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Request association with ec:cb:30:60:58:32
[...]
[...]
[...]
[...]
[...]
[...]
[...]
[...]
Aug 31 15:36:16 ripley kernel: wlxf4f26d10c509: authenticate with ec:cb:30:60:58:32
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Add radio work 'sme-connect'@0x555baf7307f0
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: First radio work item in the queue - schedule start immediately
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Starting radio work 'sme-connect'@0x555baf7307f0 after 0.000134 second wait
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Automatic auth_alg selection: 0x1
Aug 31 15:36:16 ripley wpa_supplicant[5474]: RSN: PMKSA cache search - network_ctx=0x555baf729eb0 try_opportunistic=0
Aug 31 15:36:16 ripley wpa_supplicant[5474]: RSN: Search for BSSID ec:cb:30:60:58:32
Aug 31 15:36:16 ripley wpa_supplicant[5474]: RSN: No PMKSA cache entry found
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: RSN: using IEEE 802.11i/D9.0
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: WPA: Selected cipher suites: group 16 pairwise 16 key_mgmt 2 proto 2
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: WPA: Selected mgmt group cipher 32
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: WPA: clearing AP WPA IE
[...]
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: WPA: using GTK CCMP
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: WPA: using PTK CCMP
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: WPA: using KEY_MGMT WPA-PSK
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: WPA: not using MGMT group cipher
[...]
[...]
Aug 31 15:36:16 ripley wpa_supplicant[5474]: RRM: Determining whether RRM can be used - device support: 0x0
Aug 31 15:36:16 ripley wpa_supplicant[5474]: RRM: No RRM in network
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Cancelling scan request
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: SME: Trying to authenticate with ec:cb:30:60:58:32 (SSID='SpeakFriendAndEnter' freq=2447 MHz)
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: State: INACTIVE -> AUTHENTICATING
Aug 31 15:36:16 ripley wpa_supplicant[5474]: EAPOL: External notification - EAP success=0
Aug 31 15:36:16 ripley wpa_supplicant[5474]: EAPOL: External notification - EAP fail=0
Aug 31 15:36:16 ripley wpa_supplicant[5474]: EAPOL: External notification - portControl=Auto
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Determining shared radio frequencies (max len 1)
Aug 31 15:36:16 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Shared frequencies (len=0): completed iteration
Aug 31 15:36:16 ripley wpa_supplicant[5474]: nl80211: Authenticate (ifindex=3)
Aug 31 15:36:16 ripley wpa_supplicant[5474]:   * bssid=ec:cb:30:60:58:32
Aug 31 15:36:16 ripley wpa_supplicant[5474]:   * freq=2447
[...]
Aug 31 15:36:16 ripley wpa_supplicant[5474]:   * Auth Type 0
Aug 31 15:36:16 ripley wpa_supplicant[5474]: nl80211: Authentication request send successfully
Aug 31 15:36:16 ripley wpa_supplicant[5474]: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/3
Aug 31 15:36:16 ripley wpa_supplicant[5474]: nl80211: Event message available
Aug 31 15:36:16 ripley wpa_supplicant[5474]: nl80211: Drv Event 19 (NL80211_CMD_NEW_STATION) received for wlxf4f26d10c509
Aug 31 15:36:16 ripley NetworkManager[5480]: <info>  [1472614576.4489] device (wlxf4f26d10c509): supplicant interface state: inactive -> authenticating
Aug 31 15:36:16 ripley wpa_supplicant[5474]: nl80211: New station ec:cb:30:60:58:32
Aug 31 15:36:16 ripley kernel: wlxf4f26d10c509: send auth to ec:cb:30:60:58:32 (try 1/3)
Aug 31 15:36:16 ripley wpa_supplicant[5474]: nl80211: Event message available
Aug 31 15:36:16 ripley wpa_supplicant[5474]: nl80211: Drv Event 37 (NL80211_CMD_AUTHENTICATE) received for wlxf4f26d10c509
Aug 31 15:36:16 ripley wpa_supplicant[5474]: nl80211: MLME event 37 (NL80211_CMD_AUTHENTICATE) on wlxf4f26d10c509(26:49:be:db:32:12) A1=f4:f2:6d:10:c5:09 A2=ec:cb:30:60:58:32
Aug 31 15:36:16 ripley wpa_supplicant[5474]: nl80211: wlxf4f26d10c509: Ignore MLME frame event for foreign address
Aug 31 15:36:16 ripley kernel: wlxf4f26d10c509: authenticated
Aug 31 15:36:17 ripley NetworkManager[5480]: <debug> [1472614577.2279] device[0x137f9b0] (wlxf4f26d10c509): APs: [now:5 last:4 next:26]
Aug 31 15:36:17 ripley NetworkManager[5480]: <debug> [1472614577.2279] dump    [EC:CB:30:60:58:32 ] SpeakFriendAndEnter             [  8  59% P W:0000 R:0188] [  1] wlxf4f26d10c509/0
[...]
Aug 31 15:36:19 ripley NetworkManager[5480]: <debug> [1472614579.2291] device[0x13ea8c0] (eth0): remove_pending_action (0): 'carrier wait'
Aug 31 15:36:19 ripley NetworkManager[5480]: <trace> [1472614579.2291] ignoring notification for prop has-pending-action on type NMDeviceEthernet
Aug 31 15:36:19 ripley NetworkManager[5480]: <debug> [1472614579.2292] manager: check_if_startup_complete returns FALSE because of wlxf4f26d10c509
Aug 31 15:36:21 ripley wpa_supplicant[5474]: wlxf4f26d10c509: SME: Authentication timeout
Aug 31 15:36:21 ripley wpa_supplicant[5474]: wpa_driver_nl80211_deauthenticate(addr=ec:cb:30:60:58:32 reason_code=3)
Aug 31 15:36:21 ripley kernel: wlxf4f26d10c509: aborting authentication with ec:cb:30:60:58:32 by local choice (Reason: 3=DEAUTH_LEAVING)
Aug 31 15:36:21 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Radio work 'sme-connect'@0x555baf7307f0 done in 5.217988 seconds
Aug 31 15:36:21 ripley wpa_supplicant[5474]: Added BSSID ec:cb:30:60:58:32 into blacklist
Aug 31 15:36:21 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Blacklist count 1 --> request scan in 100 ms
Aug 31 15:36:21 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Setting scan request: 0.100000 sec
Aug 31 15:36:21 ripley wpa_supplicant[5474]: wlxf4f26d10c509: State: AUTHENTICATING -> DISCONNECTED
Aug 31 15:36:21 ripley wpa_supplicant[5474]: nl80211: Set wlxf4f26d10c509 operstate 0->0 (DORMANT)
Aug 31 15:36:21 ripley wpa_supplicant[5474]: netlink: Operstate: ifindex=3 linkmode=-1 (no change), operstate=5 (IF_OPER_DORMANT)
Aug 31 15:36:21 ripley wpa_supplicant[5474]: nl80211: Event message available
Aug 31 15:36:21 ripley wpa_supplicant[5474]: nl80211: Drv Event 20 (NL80211_CMD_DEL_STATION) received for wlxf4f26d10c509
Aug 31 15:36:21 ripley wpa_supplicant[5474]: nl80211: Delete station ec:cb:30:60:58:32
Aug 31 15:36:21 ripley wpa_supplicant[5474]: nl80211: Event message available
Aug 31 15:36:21 ripley wpa_supplicant[5474]: nl80211: Drv Event 39 (NL80211_CMD_DEAUTHENTICATE) received for wlxf4f26d10c509
Aug 31 15:36:21 ripley wpa_supplicant[5474]: nl80211: MLME event 39 (NL80211_CMD_DEAUTHENTICATE) on wlxf4f26d10c509(26:49:be:db:32:12) A1=ec:cb:30:60:58:32 A2=f4:f2:6d:10:c5:09
Aug 31 15:36:21 ripley wpa_supplicant[5474]: nl80211: wlxf4f26d10c509: Ignore MLME frame event for foreign address
Aug 31 15:36:21 ripley wpa_supplicant[5474]: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/3
Aug 31 15:36:21 ripley NetworkManager[5480]: <info>  [1472614581.5317] device (wlxf4f26d10c509): supplicant interface state: authenticating -> disconnected
Aug 31 15:36:21 ripley wpa_supplicant[5474]: wlxf4f26d10c509: State: DISCONNECTED -> SCANNING
Aug 31 15:36:21 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Starting AP scan for wildcard SSID
[...]
Aug 31 15:36:21 ripley wpa_supplicant[5474]:      53 70 65 61 6b 46 72 69 65 6e 64 41 6e 64 45 6e   SpeakFriendAndEn
Aug 31 15:36:21 ripley wpa_supplicant[5474]:      74 65 72                                          ter
[...]
Aug 31 15:36:21 ripley wpa_supplicant[5474]:      53 70 65 61 6b 46 72 69 65 6e 64 41 6e 64 45 6e   SpeakFriendAndEn
Aug 31 15:36:21 ripley wpa_supplicant[5474]:      74 65 72                                          ter
[...]
Aug 31 15:36:21 ripley wpa_supplicant[5474]: WPS: Building WPS IE for Probe Request
Aug 31 15:36:21 ripley wpa_supplicant[5474]: WPS:  * Version (hardcoded 0x10)
Aug 31 15:36:21 ripley wpa_supplicant[5474]: WPS:  * Request Type
Aug 31 15:36:21 ripley wpa_supplicant[5474]: WPS:  * Config Methods (3148)
Aug 31 15:36:21 ripley wpa_supplicant[5474]: WPS:  * UUID-E
Aug 31 15:36:21 ripley wpa_supplicant[5474]: WPS:  * Primary Device Type
Aug 31 15:36:21 ripley wpa_supplicant[5474]: WPS:  * RF Bands (1)
Aug 31 15:36:21 ripley wpa_supplicant[5474]: WPS:  * Association State
Aug 31 15:36:21 ripley wpa_supplicant[5474]: WPS:  * Configuration Error (0)
Aug 31 15:36:21 ripley wpa_supplicant[5474]: WPS:  * Device Password ID (0)
Aug 31 15:36:21 ripley wpa_supplicant[5474]: WPS:  * Manufacturer
Aug 31 15:36:21 ripley wpa_supplicant[5474]: WPS:  * Model Name
Aug 31 15:36:21 ripley wpa_supplicant[5474]: WPS:  * Model Number
Aug 31 15:36:21 ripley wpa_supplicant[5474]: WPS:  * Device Name
Aug 31 15:36:21 ripley wpa_supplicant[5474]: WPS:  * Version2 (0x20)
Aug 31 15:36:21 ripley wpa_supplicant[5474]: P2P: * P2P IE header
Aug 31 15:36:21 ripley wpa_supplicant[5474]: P2P: * Capability dev=25 group=00
Aug 31 15:36:21 ripley wpa_supplicant[5474]: P2P: * Listen Channel: Regulatory Class 81 Channel 6
Aug 31 15:36:21 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Add radio work 'scan'@0x555baf72ba20
Aug 31 15:36:21 ripley wpa_supplicant[5474]: wlxf4f26d10c509: First radio work item in the queue - schedule start immediately
Aug 31 15:36:21 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Starting radio work 'scan'@0x555baf72ba20 after 0.000014 second wait
Aug 31 15:36:21 ripley wpa_supplicant[5474]: wlxf4f26d10c509: nl80211: scan request
[...]
Aug 31 15:36:21 ripley wpa_supplicant[5474]: Scan requested (ret=0) - scan timeout 30 seconds
Aug 31 15:36:21 ripley wpa_supplicant[5474]: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/3
Aug 31 15:36:21 ripley wpa_supplicant[5474]: nl80211: Event message available
Aug 31 15:36:21 ripley wpa_supplicant[5474]: nl80211: Drv Event 33 (NL80211_CMD_TRIGGER_SCAN) received for wlxf4f26d10c509
Aug 31 15:36:21 ripley NetworkManager[5480]: <info>  [1472614581.6971] device (wlxf4f26d10c509): supplicant interface state: disconnected -> scanning
Aug 31 15:36:21 ripley wpa_supplicant[5474]: wlxf4f26d10c509: nl80211: Scan trigger
Aug 31 15:36:21 ripley NetworkManager[5480]: <trace> [1472614581.6972] ignoring notification for prop scanning on type NMDeviceWifi
Aug 31 15:36:21 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Event SCAN_STARTED (47) received
Aug 31 15:36:21 ripley NetworkManager[5480]: <debug> [1472614581.6972] device[0x137f9b0] (wlxf4f26d10c509): now scanning
Aug 31 15:36:21 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Own scan request started a scan in 0.000211 seconds
Aug 31 15:36:21 ripley NetworkManager[5480]: <trace> [1472614581.6973] ignoring notification for prop scanning on type NMDeviceWifi
Aug 31 15:36:22 ripley wpa_supplicant[5474]: nl80211: Event message available
Aug 31 15:36:22 ripley wpa_supplicant[5474]: nl80211: Drv Event 34 (NL80211_CMD_NEW_SCAN_RESULTS) received for wlxf4f26d10c509
Aug 31 15:36:22 ripley NetworkManager[5480]: <trace> [1472614582.7312] platform-linux: event-notification: NEWLINK, seq 0: 3: wlxf4f26d10c509 <UP;broadcast,multicast,up> mtu 0 arp 1 wifi? not-init addrgenmode none addr F4:F2:6D:10:C5:09 rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: nl80211: New scan results available
Aug 31 15:36:22 ripley NetworkManager[5480]: <debug> [1472614582.7313] platform: signal: link changed: 3: wlxf4f26d10c509 <UP;broadcast,multicast,up> mtu 0 arp 1 wifi? init addrgenmode none addr F4:F2:6D:10:C5:09 driver ath9k_htc rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:22 ripley wpa_supplicant[5474]: nl80211: Scan probed for SSID 'SpeakFriendAndEnter'
[...]
Aug 31 15:36:22 ripley wpa_supplicant[5474]:      53 70 65 61 6b 46 72 69 65 6e 64 41 6e 64 45 6e   SpeakFriendAndEn
Aug 31 15:36:22 ripley wpa_supplicant[5474]:      74 65 72                                          ter
Aug 31 15:36:22 ripley NetworkManager[5480]: <debug> [1472614582.7313] device[0x137f9b0] (wlxf4f26d10c509): queued link change for ifindex 3
Aug 31 15:36:22 ripley wpa_supplicant[5474]: nl80211: Scan probed for SSID ''
Aug 31 15:36:22 ripley NetworkManager[5480]: <debug> [1472614582.7316] PropertiesChanged NMDeviceWifi 0x137f9b0: {'Mtu': <uint32 0>}
Aug 31 15:36:22 ripley wpa_supplicant[5474]: nl80211: Scan included frequencies: 2412 2417 2422 2427 2432 2437 2442 2447 2452 2457 2462 2467 2472
Aug 31 15:36:22 ripley NetworkManager[5480]: <debug> [1472614582.7330] device[0x137f9b0] (wlxf4f26d10c509): scan successful
Aug 31 15:36:22 ripley kernel: wlxf4f26d10c509: authenticate with ec:cb:30:60:58:32
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Event SCAN_RESULTS (3) received
Aug 31 15:36:22 ripley NetworkManager[5480]: <debug> [1472614582.7333] updated [EC:CB:30:60:58:32 ] SpeakFriendAndEnter             [  8  59% P W:0000 R:0188] [  0] wlxf4f26d10c509/0
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Scan completed in 1.034567 seconds
[...]
Aug 31 15:36:22 ripley wpa_supplicant[5474]: nl80211: Received scan results (2 BSSes)
Aug 31 15:36:22 ripley NetworkManager[5480]: <debug> [1472614582.7345] PropertiesChanged NMAccessPoint 0x13c4f60: {'LastSeen': <4008>}
Aug 31 15:36:22 ripley wpa_supplicant[5474]: Sorted scan results
Aug 31 15:36:22 ripley NetworkManager[5480]: <debug> [1472614582.7346] PropertiesChanged NMAccessPoint 0x13be070: {'LastSeen': <4008>}
Aug 31 15:36:22 ripley wpa_supplicant[5474]: ec:cb:30:60:58:32 freq=2447 qual=0 noise=-89~ level=-64 snr=25 flags=0xb age=328 est=65000
[...]
[...]
Aug 31 15:36:22 ripley wpa_supplicant[5474]: 94:fe:22:e7:11:14 freq=2432 qual=0 noise=-89~ level=-89 snr=0 flags=0xb age=680 est=6500
[...]
[...]
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: BSS: Start scan result update 3
Aug 31 15:36:22 ripley wpa_supplicant[5474]: BSS: last_scan_res_used=2/32
Aug 31 15:36:22 ripley wpa_supplicant[5474]: Add randomness: count=9 entropy=8
[...]
[...]
[...]
[...]
Aug 31 15:36:22 ripley wpa_supplicant[5474]: Add randomness: count=10 entropy=9
[...]
[...]
[...]
[...]
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: New scan results available (own=1 ext=0)
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x104a len=1
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1044 len=1
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x103b len=1
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1047 len=16
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1021 len=8
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1023 len=8
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1024 len=6
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1042 len=4
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1054 len=8
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1011 len=10
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1008 len=2
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x103c len=1
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1049 len=6
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: WFA subelement id=0 len=1
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x104a len=1
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1044 len=1
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x103b len=1
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1047 len=16
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1021 len=8
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1023 len=8
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1024 len=6
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1042 len=4
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1054 len=8
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1011 len=10
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1008 len=2
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x103c len=1
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1049 len=6
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: WFA subelement id=0 len=1
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x104a len=1
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1044 len=1
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x103b len=1
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1047 len=16
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1021 len=8
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1023 len=8
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1024 len=6
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1042 len=4
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1054 len=8
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1011 len=10
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1008 len=2
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x103c len=1
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1049 len=6
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: WFA subelement id=0 len=1
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x104a len=1
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1044 len=1
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x103b len=1
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1047 len=16
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1021 len=8
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1023 len=8
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1024 len=6
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1042 len=4
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1054 len=8
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1011 len=10
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1008 len=2
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x103c len=1
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: attr type=0x1049 len=6
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: WFA subelement id=0 len=1
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WPS: AP[0] 94:fe:22:e7:11:14 type=0 tries=0 last_attempt=-1 sec ago blacklist=0
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Radio work 'scan'@0x555baf72ba20 done in 1.105536 seconds
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Selecting BSS from priority group 0
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: 0: ec:cb:30:60:58:32 ssid='SpeakFriendAndEnter' wpa_ie_len=0 rsn_ie_len=20 caps=0x411 level=-64
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509:    skip - blacklisted (count=1 limit=0)
[...]
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509:    skip - SSID mismatch
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: No APs found - clear blacklist and try again
Aug 31 15:36:22 ripley wpa_supplicant[5474]: Removed BSSID ec:cb:30:60:58:32 from blacklist (clear)
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Selecting BSS from priority group 0
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: 0: ec:cb:30:60:58:32 ssid='SpeakFriendAndEnter' wpa_ie_len=0 rsn_ie_len=20 caps=0x411 level=-64
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509:    selected based on RSN IE
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509:    selected BSS ec:cb:30:60:58:32 ssid='SpeakFriendAndEnter'
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Considering connect request: reassociate: 0  selected: ec:cb:30:60:58:32  bssid: 00:00:00:00:00:00  pending: 00:00:00:00:00:00  wpa_state: SCANNING  ssid=0x555baf729eb0  current_ssid=0x555baf729eb0
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Request association with ec:cb:30:60:58:32
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Re-association to the same ESS
Aug 31 15:36:22 ripley wpa_supplicant[5474]: WMM AC: Save last configured tspecs
[...]
[...]
[...]
[...]
[...]
[...]
[...]
[...]
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Add radio work 'sme-connect'@0x555baf72ba20
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: First radio work item in the queue - schedule start immediately
Aug 31 15:36:22 ripley wpa_supplicant[5474]: RSN: Ignored PMKID candidate without preauth flag
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Starting radio work 'sme-connect'@0x555baf72ba20 after 0.000032 second wait
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Automatic auth_alg selection: 0x1
Aug 31 15:36:22 ripley wpa_supplicant[5474]: RSN: PMKSA cache search - network_ctx=0x555baf729eb0 try_opportunistic=0
Aug 31 15:36:22 ripley wpa_supplicant[5474]: RSN: Search for BSSID ec:cb:30:60:58:32
Aug 31 15:36:22 ripley wpa_supplicant[5474]: RSN: No PMKSA cache entry found
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: RSN: using IEEE 802.11i/D9.0
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: WPA: Selected cipher suites: group 16 pairwise 16 key_mgmt 2 proto 2
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: WPA: Selected mgmt group cipher 32
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: WPA: clearing AP WPA IE
[...]
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: WPA: using GTK CCMP
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: WPA: using PTK CCMP
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: WPA: using KEY_MGMT WPA-PSK
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: WPA: not using MGMT group cipher
[...]
[...]
Aug 31 15:36:22 ripley wpa_supplicant[5474]: RRM: Determining whether RRM can be used - device support: 0x0
Aug 31 15:36:22 ripley wpa_supplicant[5474]: RRM: No RRM in network
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Cancelling scan request
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: SME: Trying to authenticate with ec:cb:30:60:58:32 (SSID='SpeakFriendAndEnter' freq=2447 MHz)
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: State: SCANNING -> AUTHENTICATING
Aug 31 15:36:22 ripley wpa_supplicant[5474]: EAPOL: External notification - EAP success=0
Aug 31 15:36:22 ripley wpa_supplicant[5474]: EAPOL: External notification - EAP fail=0
Aug 31 15:36:22 ripley wpa_supplicant[5474]: EAPOL: External notification - portControl=Auto
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Determining shared radio frequencies (max len 1)
Aug 31 15:36:22 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Shared frequencies (len=0): completed iteration
Aug 31 15:36:22 ripley wpa_supplicant[5474]: nl80211: Authenticate (ifindex=3)
Aug 31 15:36:22 ripley wpa_supplicant[5474]:   * bssid=ec:cb:30:60:58:32
Aug 31 15:36:22 ripley wpa_supplicant[5474]:   * freq=2447
[...]
Aug 31 15:36:22 ripley wpa_supplicant[5474]:   * Auth Type 0
Aug 31 15:36:22 ripley wpa_supplicant[5474]: nl80211: Authentication request send successfully
Aug 31 15:36:22 ripley kernel: wlxf4f26d10c509: send auth to ec:cb:30:60:58:32 (try 1/3)
Aug 31 15:36:22 ripley wpa_supplicant[5474]: RTM_NEWLINK: ifi_index=3 ifname= wext ifi_family=0 ifi_flags=0x1003 ([UP])
Aug 31 15:36:22 ripley wpa_supplicant[5474]: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/3
Aug 31 15:36:22 ripley wpa_supplicant[5474]: nl80211: Event message available
Aug 31 15:36:22 ripley NetworkManager[5480]: <info>  [1472614582.9437] device (wlxf4f26d10c509): supplicant interface state: scanning -> authenticating
Aug 31 15:36:22 ripley wpa_supplicant[5474]: nl80211: Drv Event 19 (NL80211_CMD_NEW_STATION) received for wlxf4f26d10c509
Aug 31 15:36:22 ripley NetworkManager[5480]: <trace> [1472614582.9437] ignoring notification for prop scanning on type NMDeviceWifi
Aug 31 15:36:22 ripley wpa_supplicant[5474]: nl80211: New station ec:cb:30:60:58:32
Aug 31 15:36:22 ripley NetworkManager[5480]: <debug> [1472614582.9438] device[0x137f9b0] (wlxf4f26d10c509): now idle
Aug 31 15:36:22 ripley wpa_supplicant[5474]: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/3/BSSs/0
Aug 31 15:36:22 ripley NetworkManager[5480]: <trace> [1472614582.9438] ignoring notification for prop scanning on type NMDeviceWifi
Aug 31 15:36:22 ripley wpa_supplicant[5474]: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/3/BSSs/1
Aug 31 15:36:22 ripley NetworkManager[5480]: <debug> [1472614582.9442] updated [EC:CB:30:60:58:32 ] SpeakFriendAndEnter             [  8  59% P W:0000 R:0188] [  0] wlxf4f26d10c509/0
Aug 31 15:36:22 ripley kernel: wlxf4f26d10c509: authenticated
Aug 31 15:36:22 ripley wpa_supplicant[5474]: nl80211: Event message available
[...]
Aug 31 15:36:22 ripley wpa_supplicant[5474]: nl80211: Drv Event 37 (NL80211_CMD_AUTHENTICATE) received for wlxf4f26d10c509
Aug 31 15:36:22 ripley NetworkManager[5480]: <debug> [1472614582.9449] PropertiesChanged NMAccessPoint 0x13c4f60: {'Strength': <byte 0x3c>}
Aug 31 15:36:22 ripley wpa_supplicant[5474]: nl80211: MLME event 37 (NL80211_CMD_AUTHENTICATE) on wlxf4f26d10c509(26:49:be:db:32:12) A1=f4:f2:6d:10:c5:09 A2=ec:cb:30:60:58:32
Aug 31 15:36:22 ripley NetworkManager[5480]: <debug> [1472614582.9450] PropertiesChanged NMAccessPoint 0x13be070: {'Strength': <byte 0x13>}
Aug 31 15:36:22 ripley wpa_supplicant[5474]: nl80211: wlxf4f26d10c509: Ignore MLME frame event for foreign address
Aug 31 15:36:24 ripley NetworkManager[5480]: <debug> [1472614584.2284] device[0x137f9b0] (wlxf4f26d10c509): APs: [now:12 last:10 next:26]
Aug 31 15:36:24 ripley NetworkManager[5480]: <debug> [1472614584.2285] dump    [EC:CB:30:60:58:32 ] SpeakFriendAndEnter             [  8  60% P W:0000 R:0188] [  2] wlxf4f26d10c509/0
[...]
Aug 31 15:36:27 ripley wpa_supplicant[5474]: wlxf4f26d10c509: SME: Authentication timeout
Aug 31 15:36:27 ripley wpa_supplicant[5474]: wpa_driver_nl80211_deauthenticate(addr=ec:cb:30:60:58:32 reason_code=3)
Aug 31 15:36:27 ripley kernel: wlxf4f26d10c509: aborting authentication with ec:cb:30:60:58:32 by local choice (Reason: 3=DEAUTH_LEAVING)
Aug 31 15:36:28 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Radio work 'sme-connect'@0x555baf72ba20 done in 5.289072 seconds
Aug 31 15:36:28 ripley wpa_supplicant[5474]: Added BSSID ec:cb:30:60:58:32 into blacklist
Aug 31 15:36:28 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Blacklist count 2 --> request scan in 500 ms
Aug 31 15:36:28 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Setting scan request: 0.500000 sec
Aug 31 15:36:28 ripley wpa_supplicant[5474]: wlxf4f26d10c509: State: AUTHENTICATING -> DISCONNECTED
Aug 31 15:36:28 ripley wpa_supplicant[5474]: nl80211: Set wlxf4f26d10c509 operstate 0->0 (DORMANT)
Aug 31 15:36:28 ripley wpa_supplicant[5474]: netlink: Operstate: ifindex=3 linkmode=-1 (no change), operstate=5 (IF_OPER_DORMANT)
Aug 31 15:36:28 ripley wpa_supplicant[5474]: nl80211: Event message available
Aug 31 15:36:28 ripley wpa_supplicant[5474]: nl80211: Drv Event 20 (NL80211_CMD_DEL_STATION) received for wlxf4f26d10c509
Aug 31 15:36:28 ripley wpa_supplicant[5474]: nl80211: Delete station ec:cb:30:60:58:32
Aug 31 15:36:28 ripley wpa_supplicant[5474]: nl80211: Event message available
Aug 31 15:36:28 ripley wpa_supplicant[5474]: nl80211: Drv Event 39 (NL80211_CMD_DEAUTHENTICATE) received for wlxf4f26d10c509
Aug 31 15:36:28 ripley wpa_supplicant[5474]: nl80211: MLME event 39 (NL80211_CMD_DEAUTHENTICATE) on wlxf4f26d10c509(26:49:be:db:32:12) A1=ec:cb:30:60:58:32 A2=f4:f2:6d:10:c5:09
Aug 31 15:36:28 ripley wpa_supplicant[5474]: nl80211: wlxf4f26d10c509: Ignore MLME frame event for foreign address
Aug 31 15:36:28 ripley wpa_supplicant[5474]: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/3
Aug 31 15:36:28 ripley NetworkManager[5480]: <info>  [1472614588.0271] device (wlxf4f26d10c509): supplicant interface state: authenticating -> disconnected
Aug 31 15:36:28 ripley wpa_supplicant[5474]: wlxf4f26d10c509: State: DISCONNECTED -> SCANNING
Aug 31 15:36:28 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Starting AP scan for wildcard SSID
[...]
Aug 31 15:36:28 ripley wpa_supplicant[5474]:      53 70 65 61 6b 46 72 69 65 6e 64 41 6e 64 45 6e   SpeakFriendAndEn
Aug 31 15:36:28 ripley wpa_supplicant[5474]:      74 65 72                                          ter
[...]
Aug 31 15:36:28 ripley wpa_supplicant[5474]:      53 70 65 61 6b 46 72 69 65 6e 64 41 6e 64 45 6e   SpeakFriendAndEn
Aug 31 15:36:28 ripley wpa_supplicant[5474]:      74 65 72                                          ter
[...]
Aug 31 15:36:28 ripley wpa_supplicant[5474]: WPS: Building WPS IE for Probe Request
Aug 31 15:36:28 ripley wpa_supplicant[5474]: WPS:  * Version (hardcoded 0x10)
Aug 31 15:36:28 ripley wpa_supplicant[5474]: WPS:  * Request Type
Aug 31 15:36:28 ripley wpa_supplicant[5474]: WPS:  * Config Methods (3148)
Aug 31 15:36:28 ripley wpa_supplicant[5474]: WPS:  * UUID-E
Aug 31 15:36:28 ripley wpa_supplicant[5474]: WPS:  * Primary Device Type
Aug 31 15:36:28 ripley wpa_supplicant[5474]: WPS:  * RF Bands (1)
Aug 31 15:36:28 ripley wpa_supplicant[5474]: WPS:  * Association State
Aug 31 15:36:28 ripley wpa_supplicant[5474]: WPS:  * Configuration Error (0)
Aug 31 15:36:28 ripley wpa_supplicant[5474]: WPS:  * Device Password ID (0)
Aug 31 15:36:28 ripley wpa_supplicant[5474]: WPS:  * Manufacturer
Aug 31 15:36:28 ripley wpa_supplicant[5474]: WPS:  * Model Name
Aug 31 15:36:28 ripley wpa_supplicant[5474]: WPS:  * Model Number
Aug 31 15:36:28 ripley wpa_supplicant[5474]: WPS:  * Device Name
Aug 31 15:36:28 ripley wpa_supplicant[5474]: WPS:  * Version2 (0x20)
Aug 31 15:36:28 ripley wpa_supplicant[5474]: P2P: * P2P IE header
Aug 31 15:36:28 ripley wpa_supplicant[5474]: P2P: * Capability dev=25 group=00
Aug 31 15:36:28 ripley wpa_supplicant[5474]: P2P: * Listen Channel: Regulatory Class 81 Channel 6
Aug 31 15:36:28 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Add radio work 'scan'@0x555baf72ba20
Aug 31 15:36:28 ripley wpa_supplicant[5474]: wlxf4f26d10c509: First radio work item in the queue - schedule start immediately
Aug 31 15:36:28 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Starting radio work 'scan'@0x555baf72ba20 after 0.000019 second wait
Aug 31 15:36:28 ripley wpa_supplicant[5474]: wlxf4f26d10c509: nl80211: scan request
[...]
Aug 31 15:36:28 ripley wpa_supplicant[5474]: Scan requested (ret=0) - scan timeout 30 seconds
Aug 31 15:36:28 ripley wpa_supplicant[5474]: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/3
Aug 31 15:36:28 ripley wpa_supplicant[5474]: nl80211: Event message available
Aug 31 15:36:28 ripley wpa_supplicant[5474]: nl80211: Drv Event 33 (NL80211_CMD_TRIGGER_SCAN) received for wlxf4f26d10c509
Aug 31 15:36:28 ripley NetworkManager[5480]: <info>  [1472614588.5931] device (wlxf4f26d10c509): supplicant interface state: disconnected -> scanning
Aug 31 15:36:28 ripley wpa_supplicant[5474]: wlxf4f26d10c509: nl80211: Scan trigger
Aug 31 15:36:28 ripley NetworkManager[5480]: <trace> [1472614588.5931] ignoring notification for prop scanning on type NMDeviceWifi
Aug 31 15:36:28 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Event SCAN_STARTED (47) received
Aug 31 15:36:28 ripley NetworkManager[5480]: <debug> [1472614588.5932] device[0x137f9b0] (wlxf4f26d10c509): now scanning
Aug 31 15:36:28 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Own scan request started a scan in 0.000746 seconds
Aug 31 15:36:28 ripley NetworkManager[5480]: <trace> [1472614588.5932] ignoring notification for prop scanning on type NMDeviceWifi
Aug 31 15:36:29 ripley wpa_supplicant[5474]: RTM_NEWLINK: ifi_index=3 ifname= wext ifi_family=0 ifi_flags=0x1003 ([UP])
Aug 31 15:36:29 ripley wpa_supplicant[5474]: nl80211: Event message available
Aug 31 15:36:29 ripley NetworkManager[5480]: <trace> [1472614589.6272] platform-linux: event-notification: NEWLINK, seq 0: 3: wlxf4f26d10c509 <UP;broadcast,multicast,up> mtu 0 arp 1 wifi? not-init addrgenmode none addr F4:F2:6D:10:C5:09 rx:58219,78457503 tx:35596,3701342
Aug 31 15:36:29 ripley wpa_supplicant[5474]: nl80211: Drv Event 34 (NL80211_CMD_NEW_SCAN_RESULTS) received for wlxf4f26d10c509
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: nl80211: New scan results available
Aug 31 15:36:29 ripley wpa_supplicant[5474]: nl80211: Scan probed for SSID 'SpeakFriendAndEnter'
Aug 31 15:36:29 ripley NetworkManager[5480]: <debug> [1472614589.6286] device[0x137f9b0] (wlxf4f26d10c509): scan successful
[...]
Aug 31 15:36:29 ripley wpa_supplicant[5474]:      53 70 65 61 6b 46 72 69 65 6e 64 41 6e 64 45 6e   SpeakFriendAndEn
Aug 31 15:36:29 ripley wpa_supplicant[5474]:      74 65 72                                          ter
Aug 31 15:36:29 ripley wpa_supplicant[5474]: nl80211: Scan probed for SSID ''
Aug 31 15:36:29 ripley NetworkManager[5480]: <debug> [1472614589.6288] updated [EC:CB:30:60:58:32 ] SpeakFriendAndEnter             [  8  60% P W:0000 R:0188] [  0] wlxf4f26d10c509/0
Aug 31 15:36:29 ripley wpa_supplicant[5474]: nl80211: Scan included frequencies: 2412 2417 2422 2427 2432 2437 2442 2447 2452 2457 2462 2467 2472
[...]
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Event SCAN_RESULTS (3) received
Aug 31 15:36:29 ripley kernel: wlxf4f26d10c509: authenticate with ec:cb:30:60:58:32
Aug 31 15:36:29 ripley NetworkManager[5480]: <debug> [1472614589.6296] PropertiesChanged NMAccessPoint 0x13c4f60: {'LastSeen': <4015>}
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Scan completed in 1.034168 seconds
Aug 31 15:36:29 ripley NetworkManager[5480]: <debug> [1472614589.6297] PropertiesChanged NMAccessPoint 0x13be070: {'LastSeen': <4015>}
Aug 31 15:36:29 ripley wpa_supplicant[5474]: nl80211: Received scan results (2 BSSes)
Aug 31 15:36:29 ripley wpa_supplicant[5474]: Sorted scan results
Aug 31 15:36:29 ripley wpa_supplicant[5474]: ec:cb:30:60:58:32 freq=2447 qual=0 noise=-89~ level=-61 snr=28 flags=0xb age=404 est=65000
[...]
[...]
Aug 31 15:36:29 ripley wpa_supplicant[5474]: 94:fe:22:e7:11:14 freq=2432 qual=0 noise=-89~ level=-86 snr=3 flags=0xb age=676 est=6500
[...]
[...]
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: BSS: Start scan result update 4
Aug 31 15:36:29 ripley wpa_supplicant[5474]: BSS: last_scan_res_used=2/32
Aug 31 15:36:29 ripley wpa_supplicant[5474]: Add randomness: count=11 entropy=10
[...]
[...]
[...]
[...]
Aug 31 15:36:29 ripley wpa_supplicant[5474]: Add randomness: count=12 entropy=11
[...]
[...]
[...]
[...]
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: New scan results available (own=1 ext=0)
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x104a len=1
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1044 len=1
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x103b len=1
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1047 len=16
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1021 len=8
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1023 len=8
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1024 len=6
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1042 len=4
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1054 len=8
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1011 len=10
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1008 len=2
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x103c len=1
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1049 len=6
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: WFA subelement id=0 len=1
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x104a len=1
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1044 len=1
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x103b len=1
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1047 len=16
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1021 len=8
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1023 len=8
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1024 len=6
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1042 len=4
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1054 len=8
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1011 len=10
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1008 len=2
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x103c len=1
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1049 len=6
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: WFA subelement id=0 len=1
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x104a len=1
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1044 len=1
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x103b len=1
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1047 len=16
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1021 len=8
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1023 len=8
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1024 len=6
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1042 len=4
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1054 len=8
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1011 len=10
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1008 len=2
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x103c len=1
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1049 len=6
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: WFA subelement id=0 len=1
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x104a len=1
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1044 len=1
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x103b len=1
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1047 len=16
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1021 len=8
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1023 len=8
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1024 len=6
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1042 len=4
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1054 len=8
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1011 len=10
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1008 len=2
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x103c len=1
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: attr type=0x1049 len=6
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: WFA subelement id=0 len=1
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WPS: AP[0] 94:fe:22:e7:11:14 type=0 tries=0 last_attempt=-1 sec ago blacklist=0
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Radio work 'scan'@0x555baf72ba20 done in 1.106106 seconds
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Selecting BSS from priority group 0
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: 0: ec:cb:30:60:58:32 ssid='SpeakFriendAndEnter' wpa_ie_len=0 rsn_ie_len=20 caps=0x411 level=-61
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509:    skip - blacklisted (count=1 limit=0)
[...]
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509:    skip - SSID mismatch
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: No APs found - clear blacklist and try again
Aug 31 15:36:29 ripley wpa_supplicant[5474]: Removed BSSID ec:cb:30:60:58:32 from blacklist (clear)
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Selecting BSS from priority group 0
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: 0: ec:cb:30:60:58:32 ssid='SpeakFriendAndEnter' wpa_ie_len=0 rsn_ie_len=20 caps=0x411 level=-61
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509:    selected based on RSN IE
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509:    selected BSS ec:cb:30:60:58:32 ssid='SpeakFriendAndEnter'
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Considering connect request: reassociate: 0  selected: ec:cb:30:60:58:32  bssid: 00:00:00:00:00:00  pending: 00:00:00:00:00:00  wpa_state: SCANNING  ssid=0x555baf729eb0  current_ssid=0x555baf729eb0
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Request association with ec:cb:30:60:58:32
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Re-association to the same ESS
Aug 31 15:36:29 ripley wpa_supplicant[5474]: WMM AC: Save last configured tspecs
[...]
[...]
[...]
[...]
[...]
[...]
[...]
[...]
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Add radio work 'sme-connect'@0x555baf72ba20
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: First radio work item in the queue - schedule start immediately
Aug 31 15:36:29 ripley wpa_supplicant[5474]: RSN: Ignored PMKID candidate without preauth flag
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Starting radio work 'sme-connect'@0x555baf72ba20 after 0.000033 second wait
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Automatic auth_alg selection: 0x1
Aug 31 15:36:29 ripley wpa_supplicant[5474]: RSN: PMKSA cache search - network_ctx=0x555baf729eb0 try_opportunistic=0
Aug 31 15:36:29 ripley wpa_supplicant[5474]: RSN: Search for BSSID ec:cb:30:60:58:32
Aug 31 15:36:29 ripley wpa_supplicant[5474]: RSN: No PMKSA cache entry found
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: RSN: using IEEE 802.11i/D9.0
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: WPA: Selected cipher suites: group 16 pairwise 16 key_mgmt 2 proto 2
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: WPA: Selected mgmt group cipher 32
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: WPA: clearing AP WPA IE
[...]
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: WPA: using GTK CCMP
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: WPA: using PTK CCMP
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: WPA: using KEY_MGMT WPA-PSK
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: WPA: not using MGMT group cipher
[...]
[...]
Aug 31 15:36:29 ripley wpa_supplicant[5474]: RRM: Determining whether RRM can be used - device support: 0x0
Aug 31 15:36:29 ripley wpa_supplicant[5474]: RRM: No RRM in network
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Cancelling scan request
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: SME: Trying to authenticate with ec:cb:30:60:58:32 (SSID='SpeakFriendAndEnter' freq=2447 MHz)
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: State: SCANNING -> AUTHENTICATING
Aug 31 15:36:29 ripley wpa_supplicant[5474]: EAPOL: External notification - EAP success=0
Aug 31 15:36:29 ripley wpa_supplicant[5474]: EAPOL: External notification - EAP fail=0
Aug 31 15:36:29 ripley wpa_supplicant[5474]: EAPOL: External notification - portControl=Auto
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Determining shared radio frequencies (max len 1)
Aug 31 15:36:29 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Shared frequencies (len=0): completed iteration
Aug 31 15:36:29 ripley wpa_supplicant[5474]: nl80211: Authenticate (ifindex=3)
Aug 31 15:36:29 ripley wpa_supplicant[5474]:   * bssid=ec:cb:30:60:58:32
Aug 31 15:36:29 ripley wpa_supplicant[5474]:   * freq=2447
[...]
Aug 31 15:36:29 ripley wpa_supplicant[5474]:   * Auth Type 0
Aug 31 15:36:29 ripley wpa_supplicant[5474]: nl80211: Authentication request send successfully
Aug 31 15:36:29 ripley wpa_supplicant[5474]: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/3
Aug 31 15:36:29 ripley wpa_supplicant[5474]: nl80211: Event message available
Aug 31 15:36:29 ripley wpa_supplicant[5474]: nl80211: Drv Event 19 (NL80211_CMD_NEW_STATION) received for wlxf4f26d10c509
Aug 31 15:36:29 ripley NetworkManager[5480]: <info>  [1472614589.8409] device (wlxf4f26d10c509): supplicant interface state: scanning -> authenticating
Aug 31 15:36:29 ripley wpa_supplicant[5474]: nl80211: New station ec:cb:30:60:58:32
Aug 31 15:36:29 ripley NetworkManager[5480]: <trace> [1472614589.8410] ignoring notification for prop scanning on type NMDeviceWifi
Aug 31 15:36:29 ripley wpa_supplicant[5474]: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/3/BSSs/0
Aug 31 15:36:29 ripley NetworkManager[5480]: <debug> [1472614589.8410] device[0x137f9b0] (wlxf4f26d10c509): now idle
Aug 31 15:36:29 ripley wpa_supplicant[5474]: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/3/BSSs/1
Aug 31 15:36:29 ripley NetworkManager[5480]: <trace> [1472614589.8411] ignoring notification for prop scanning on type NMDeviceWifi
Aug 31 15:36:29 ripley kernel: wlxf4f26d10c509: send auth to ec:cb:30:60:58:32 (try 1/3)
Aug 31 15:36:29 ripley kernel: wlxf4f26d10c509: authenticated
Aug 31 15:36:29 ripley wpa_supplicant[5474]: nl80211: Event message available
Aug 31 15:36:29 ripley NetworkManager[5480]: <debug> [1472614589.8416] updated [EC:CB:30:60:58:32 ] SpeakFriendAndEnter             [  8  60% P W:0000 R:0188] [  0] wlxf4f26d10c509/0
Aug 31 15:36:29 ripley wpa_supplicant[5474]: nl80211: Drv Event 37 (NL80211_CMD_AUTHENTICATE) received for wlxf4f26d10c509
[...]
Aug 31 15:36:29 ripley wpa_supplicant[5474]: nl80211: MLME event 37 (NL80211_CMD_AUTHENTICATE) on wlxf4f26d10c509(26:49:be:db:32:12) A1=f4:f2:6d:10:c5:09 A2=ec:cb:30:60:58:32
Aug 31 15:36:29 ripley NetworkManager[5480]: <debug> [1472614589.8421] PropertiesChanged NMAccessPoint 0x13c4f60: {'Strength': <byte 0x41>}
Aug 31 15:36:29 ripley wpa_supplicant[5474]: nl80211: wlxf4f26d10c509: Ignore MLME frame event for foreign address
Aug 31 15:36:29 ripley NetworkManager[5480]: <debug> [1472614589.8422] PropertiesChanged NMAccessPoint 0x13be070: {'Strength': <byte 0x18>}
Aug 31 15:36:31 ripley NetworkManager[5480]: <debug> [1472614591.2287] device[0x137f9b0] (wlxf4f26d10c509): APs: [now:19 last:17 next:26]
Aug 31 15:36:31 ripley NetworkManager[5480]: <debug> [1472614591.2288] dump    [EC:CB:30:60:58:32 ] SpeakFriendAndEnter             [  8  65% P W:0000 R:0188] [  2] wlxf4f26d10c509/0
[...]
Aug 31 15:36:34 ripley wpa_supplicant[5474]: wlxf4f26d10c509: SME: Authentication timeout
Aug 31 15:36:34 ripley wpa_supplicant[5474]: wpa_driver_nl80211_deauthenticate(addr=ec:cb:30:60:58:32 reason_code=3)
Aug 31 15:36:34 ripley kernel: wlxf4f26d10c509: aborting authentication with ec:cb:30:60:58:32 by local choice (Reason: 3=DEAUTH_LEAVING)
Aug 31 15:36:34 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Radio work 'sme-connect'@0x555baf72ba20 done in 5.285641 seconds
Aug 31 15:36:34 ripley wpa_supplicant[5474]: Added BSSID ec:cb:30:60:58:32 into blacklist
Aug 31 15:36:34 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Blacklist count 3 --> request scan in 1000 ms
Aug 31 15:36:34 ripley wpa_supplicant[5474]: wlxf4f26d10c509: Setting scan request: 1.000000 sec
Aug 31 15:36:34 ripley wpa_supplicant[5474]: wlxf4f26d10c509: State: AUTHENTICATING -> DISCONNECTED
Aug 31 15:36:34 ripley wpa_supplicant[5474]: nl80211: Set wlxf4f26d10c509 operstate 0->0 (DORMANT)
Aug 31 15:36:34 ripley wpa_supplicant[5474]: netlink: Operstate: ifindex=3 linkmode=-1 (no change), operstate=5 (IF_OPER_DORMANT)
Aug 31 15:36:34 ripley wpa_supplicant[5474]: nl80211: Event message available
Aug 31 15:36:34 ripley wpa_supplicant[5474]: nl80211: Drv Event 20 (NL80211_CMD_DEL_STATION) received for wlxf4f26d10c509
Aug 31 15:36:34 ripley wpa_supplicant[5474]: nl80211: Delete station ec:cb:30:60:58:32
Aug 31 15:36:34 ripley wpa_supplicant[5474]: nl80211: Event message available
Aug 31 15:36:34 ripley wpa_supplicant[5474]: nl80211: Drv Event 39 (NL80211_CMD_DEAUTHENTICATE) received for wlxf4f26d10c509
Aug 31 15:36:34 ripley wpa_supplicant[5474]: nl80211: MLME event 39 (NL80211_CMD_DEAUTHENTICATE) on wlxf4f26d10c509(26:49:be:db:32:12) A1=ec:cb:30:60:58:32 A2=f4:f2:6d:10:c5:09
Aug 31 15:36:34 ripley wpa_supplicant[5474]: nl80211: wlxf4f26d10c509: Ignore MLME frame event for foreign address
Aug 31 15:36:34 ripley wpa_supplicant[5474]: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/3
Aug 31 15:36:34 ripley NetworkManager[5480]: <info>  [1472614594.9199] device (wlxf4f26d10c509): supplicant interface state: authenticating -> disconnected
