Log when connecting to PSK SSID with internal DHCP client

wpa-psk.txt (text/plain), 13.60 KB, created by Ferry on 2018-04-10 15:08:24 UTC

-- Logs begin at Tue 2017-12-26 16:26:37 CET. --
apr 10 16:57:10 flaptoppy plasmashell[12315]: networkmanager-qt: virtual void NetworkManager::ActiveConnectionPrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "StateFlags"
apr 10 16:57:14 flaptoppy plasmashell[12315]: Currrent active notifications: QHash()
apr 10 16:57:14 flaptoppy plasmashell[12315]: Guessing partOf as: 0
apr 10 16:57:28 flaptoppy plasmashell[12315]: Currrent active notifications: QHash()
apr 10 16:57:28 flaptoppy plasmashell[12315]: Guessing partOf as: 0
apr 10 16:57:40 flaptoppy plasmashell[12315]: Currrent active notifications: QHash()
apr 10 16:57:40 flaptoppy plasmashell[12315]: Guessing partOf as: 0
apr 10 16:58:23 flaptoppy kwin_x11[12309]: QXcbConnection: XCB error: 3 (BadWindow), sequence: 22221, resource id: 27267220, major code: 3 (GetWindowAttributes), minor code: 0
apr 10 16:58:23 flaptoppy kwin_x11[12309]: QXcbConnection: XCB error: 9 (BadDrawable), sequence: 22222, resource id: 27267220, major code: 14 (GetGeometry), minor code: 0
apr 10 16:58:37 flaptoppy kwin_x11[12309]: QXcbConnection: XCB error: 3 (BadWindow), sequence: 22478, resource id: 27267752, major code: 3 (GetWindowAttributes), minor code: 0
apr 10 16:58:37 flaptoppy kwin_x11[12309]: QXcbConnection: XCB error: 9 (BadDrawable), sequence: 22479, resource id: 27267752, major code: 14 (GetGeometry), minor code: 0
apr 10 16:58:39 flaptoppy plasmashell[12315]: org.kde.plasmaquick: Applet "Networks" loaded after 0 msec
apr 10 16:58:39 flaptoppy plasmashell[12315]: org.kde.plasmaquick: Increasing score for "Networks" to 56
apr 10 16:58:39 flaptoppy plasmashell[12315]: org.kde.plasmaquick: Applet "System Tray" loaded after 0 msec
apr 10 16:58:39 flaptoppy plasmashell[12315]: org.kde.plasmaquick: Applet "System Tray" loaded after 0 msec
apr 10 16:58:39 flaptoppy plasmashell[12315]: org.kde.plasmaquick: Increasing score for "System Tray" to 62
apr 10 16:58:41 flaptoppy NetworkManager[11263]: <info>  [1523372321.2479] device (wlp2s0): disconnecting for new activation request.
apr 10 16:58:41 flaptoppy NetworkManager[11263]: <info>  [1523372321.2480] device (wlp2s0): state change: activated -> deactivating (reason 'new-activation', sys-iface-state: 'managed')
apr 10 16:58:41 flaptoppy NetworkManager[11263]: <info>  [1523372321.2481] manager: NetworkManager state is now DISCONNECTING
apr 10 16:58:41 flaptoppy NetworkManager[11263]: <info>  [1523372321.2488] audit: op="connection-activate" uuid="d2f36e54-f3e4-4f35-a289-d487a71ee61b" name="Andriessen-Gast" pid=12315 uid=1000 result="success"
apr 10 16:58:41 flaptoppy dbus-daemon[346]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.223' (uid=0 pid=11263 comm="/usr/sbin/NetworkManager --no-daemon ")
apr 10 16:58:41 flaptoppy NetworkManager[11263]: <info>  [1523372321.2490] device (wlp2s0): state change: deactivating -> disconnected (reason 'new-activation', sys-iface-state: 'managed')
apr 10 16:58:41 flaptoppy NetworkManager[11263]: <info>  [1523372321.2492] dhcp4 (wlp2s0): canceled DHCP transaction
apr 10 16:58:41 flaptoppy NetworkManager[11263]: <info>  [1523372321.2492] dhcp4 (wlp2s0): state changed bound -> done
apr 10 16:58:41 flaptoppy kdeinit5[12264]: plasma-nm: Unhandled active connection state change:  3
apr 10 16:58:41 flaptoppy systemd[1]: Starting Network Manager Script Dispatcher Service...
apr 10 16:58:41 flaptoppy wpa_supplicant[386]: wlp2s0: CTRL-EVENT-DISCONNECTED bssid=82:2a:a8:8b:df:67 reason=3 locally_generated=1
apr 10 16:58:41 flaptoppy dbus-daemon[346]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
apr 10 16:58:41 flaptoppy systemd[1]: Started Network Manager Script Dispatcher Service.
apr 10 16:58:41 flaptoppy nm-dispatcher[12567]: req:1 'connectivity-change': new request (1 scripts)
apr 10 16:58:41 flaptoppy nm-dispatcher[12567]: req:1 'connectivity-change': start running ordered scripts...
apr 10 16:58:41 flaptoppy kernel: brcmfmac: brcmf_inetaddr_changed: fail to get arp ip table err:-52
apr 10 16:58:41 flaptoppy NetworkManager[11263]: <info>  [1523372321.7659] device (wlp2s0): set-hw-addr: set MAC address to 86:F5:68:C1:94:B7 (scanning)
apr 10 16:58:41 flaptoppy kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
apr 10 16:58:41 flaptoppy NetworkManager[11263]: <info>  [1523372321.7685] manager: NetworkManager state is now DISCONNECTED
apr 10 16:58:41 flaptoppy NetworkManager[11263]: <info>  [1523372321.7687] device (wlp2s0): Activation: starting connection 'Andriessen-Gast' (d2f36e54-f3e4-4f35-a289-d487a71ee61b)
apr 10 16:58:41 flaptoppy nm-dispatcher[12567]: req:2 'down' [wlp2s0]: new request (1 scripts)
apr 10 16:58:41 flaptoppy NetworkManager[11263]: <warn>  [1523372321.7696] sup-iface[0x555845a10970,wlp2s0]: connection disconnected (reason -3)
apr 10 16:58:41 flaptoppy nm-dispatcher[12567]: req:2 'down' [wlp2s0]: start running ordered scripts...
apr 10 16:58:41 flaptoppy NetworkManager[11263]: <info>  [1523372321.7698] device (wlp2s0): supplicant interface state: completed -> disconnected
apr 10 16:58:41 flaptoppy NetworkManager[11263]: <info>  [1523372321.7772] device (wlp2s0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
apr 10 16:58:41 flaptoppy NetworkManager[11263]: <info>  [1523372321.7774] manager: NetworkManager state is now CONNECTING
apr 10 16:58:42 flaptoppy NetworkManager[11263]: <info>  [1523372322.2829] device (wlp2s0): set-hw-addr: reset MAC address to 44:1C:A8:E1:72:A9 (preserve)
apr 10 16:58:42 flaptoppy kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
apr 10 16:58:42 flaptoppy NetworkManager[11263]: <info>  [1523372322.2851] device (wlp2s0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
apr 10 16:58:42 flaptoppy NetworkManager[11263]: <info>  [1523372322.2852] device (wlp2s0): Activation: (wifi) access point 'Andriessen-Gast' has security, but secrets are required.
apr 10 16:58:42 flaptoppy NetworkManager[11263]: <info>  [1523372322.2853] device (wlp2s0): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
apr 10 16:58:42 flaptoppy plasmashell[12315]: networkmanager-qt: virtual void NetworkManager::ActiveConnectionPrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "StateFlags"
apr 10 16:58:42 flaptoppy kdeinit5[12264]: networkmanager-qt: virtual void NetworkManager::ActiveConnectionPrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "StateFlags"
apr 10 16:58:42 flaptoppy kdeinit5[12264]: plasma-nm: Unhandled active connection state change:  1
apr 10 16:58:42 flaptoppy kdeinit5[12264]: plasma-nm: virtual NMVariantMapMap SecretAgent::GetSecrets(const NMVariantMapMap&, const QDBusObjectPath&, const QString&, const QStringList&, uint)
apr 10 16:58:42 flaptoppy kdeinit5[12264]: plasma-nm: Path: "/org/freedesktop/NetworkManager/Settings/12"
apr 10 16:58:42 flaptoppy kdeinit5[12264]: plasma-nm: Setting name: "802-11-wireless-security"
apr 10 16:58:42 flaptoppy kdeinit5[12264]: plasma-nm: Hints: ()
apr 10 16:58:42 flaptoppy kdeinit5[12264]: plasma-nm: Flags: 5
apr 10 16:58:42 flaptoppy NetworkManager[11263]: <info>  [1523372322.3006] device (wlp2s0): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
apr 10 16:58:42 flaptoppy NetworkManager[11263]: <info>  [1523372322.3008] device (wlp2s0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
apr 10 16:58:42 flaptoppy NetworkManager[11263]: <info>  [1523372322.3010] device (wlp2s0): Activation: (wifi) connection 'Andriessen-Gast' has security, and secrets exist.  No new secrets needed.
apr 10 16:58:42 flaptoppy NetworkManager[11263]: <info>  [1523372322.3011] Config: added 'ssid' value 'Andriessen-Gast'
apr 10 16:58:42 flaptoppy NetworkManager[11263]: <info>  [1523372322.3011] Config: added 'scan_ssid' value '1'
apr 10 16:58:42 flaptoppy NetworkManager[11263]: <info>  [1523372322.3011] Config: added 'bgscan' value 'simple:30:-80:86400'
apr 10 16:58:42 flaptoppy NetworkManager[11263]: <info>  [1523372322.3011] Config: added 'key_mgmt' value 'WPA-PSK'
apr 10 16:58:42 flaptoppy NetworkManager[11263]: <info>  [1523372322.3011] Config: added 'psk' value '<hidden>'
apr 10 16:58:42 flaptoppy NetworkManager[11263]: <info>  [1523372322.3113] device (wlp2s0): supplicant interface state: disconnected -> scanning
apr 10 16:58:45 flaptoppy wpa_supplicant[386]: wlp2s0: Trying to associate with SSID 'Andriessen-Gast'
apr 10 16:58:45 flaptoppy NetworkManager[11263]: <info>  [1523372325.4545] device (wlp2s0): supplicant interface state: scanning -> associating
apr 10 16:58:48 flaptoppy wpa_supplicant[386]: wlp2s0: Associated with 14:dd:a9:c9:f5:a0
apr 10 16:58:48 flaptoppy wpa_supplicant[386]: wlp2s0: CTRL-EVENT-EAP-SUCCESS EAP authentication completed successfully (based on lower layer success)
apr 10 16:58:48 flaptoppy wpa_supplicant[386]: wlp2s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
apr 10 16:58:48 flaptoppy NetworkManager[11263]: <info>  [1523372328.6894] device (wlp2s0): supplicant interface state: associating -> 4-way handshake
apr 10 16:58:48 flaptoppy wpa_supplicant[386]: wlp2s0: WPA: Key negotiation completed with 14:dd:a9:c9:f5:a0 [PTK=CCMP GTK=CCMP]
apr 10 16:58:48 flaptoppy wpa_supplicant[386]: wlp2s0: CTRL-EVENT-CONNECTED - Connection to 14:dd:a9:c9:f5:a0 completed [id=0 id_str=]
apr 10 16:58:48 flaptoppy kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlp2s0: link becomes ready
apr 10 16:58:48 flaptoppy NetworkManager[11263]: <info>  [1523372328.7085] device (wlp2s0): supplicant interface state: 4-way handshake -> completed
apr 10 16:58:48 flaptoppy NetworkManager[11263]: <info>  [1523372328.7085] device (wlp2s0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'Andriessen-Gast'.
apr 10 16:58:48 flaptoppy NetworkManager[11263]: <info>  [1523372328.7086] device (wlp2s0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
apr 10 16:58:48 flaptoppy NetworkManager[11263]: <info>  [1523372328.7089] dhcp4 (wlp2s0): activation: beginning transaction (timeout in 45 seconds)
apr 10 16:58:48 flaptoppy kdeinit5[12264]: networkmanager-qt: virtual void NetworkManager::ActiveConnectionPrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "StateFlags"
apr 10 16:58:48 flaptoppy plasmashell[12315]: networkmanager-qt: virtual void NetworkManager::ActiveConnectionPrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "StateFlags"
apr 10 16:58:51 flaptoppy NetworkManager[11263]: <info>  [1523372331.6760] dhcp4 (wlp2s0):   address 192.168.1.64
apr 10 16:58:51 flaptoppy NetworkManager[11263]: <info>  [1523372331.6760] dhcp4 (wlp2s0):   plen 24
apr 10 16:58:51 flaptoppy NetworkManager[11263]: <info>  [1523372331.6760] dhcp4 (wlp2s0):   expires in 86400 seconds
apr 10 16:58:51 flaptoppy NetworkManager[11263]: <info>  [1523372331.6760] dhcp4 (wlp2s0):   nameserver '192.168.1.1'
apr 10 16:58:51 flaptoppy NetworkManager[11263]: <info>  [1523372331.6760] dhcp4 (wlp2s0):   hostname 'flaptoppy'
apr 10 16:58:51 flaptoppy NetworkManager[11263]: <info>  [1523372331.6760] dhcp4 (wlp2s0):   gateway 192.168.1.1
apr 10 16:58:51 flaptoppy NetworkManager[11263]: <info>  [1523372331.6761] dhcp4 (wlp2s0): state changed unknown -> bound
apr 10 16:58:51 flaptoppy kernel: brcmfmac: brcmf_inetaddr_changed: fail to get arp ip table err:-52
apr 10 16:58:51 flaptoppy NetworkManager[11263]: <info>  [1523372331.6790] device (wlp2s0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
apr 10 16:58:51 flaptoppy NetworkManager[11263]: <info>  [1523372331.6796] device (wlp2s0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
apr 10 16:58:51 flaptoppy kdeinit5[12264]: networkmanager-qt: virtual void NetworkManager::ActiveConnectionPrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "StateFlags"
apr 10 16:58:51 flaptoppy NetworkManager[11263]: <info>  [1523372331.6797] device (wlp2s0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
apr 10 16:58:51 flaptoppy NetworkManager[11263]: <info>  [1523372331.6798] manager: NetworkManager state is now CONNECTED_LOCAL
apr 10 16:58:51 flaptoppy NetworkManager[11263]: <info>  [1523372331.6835] manager: NetworkManager state is now CONNECTED_SITE
apr 10 16:58:51 flaptoppy NetworkManager[11263]: <info>  [1523372331.6836] policy: set 'Andriessen-Gast' (wlp2s0) as default for IPv4 routing and DNS
apr 10 16:58:51 flaptoppy NetworkManager[11263]: <info>  [1523372331.6839] device (wlp2s0): Activation: successful, device activated.
apr 10 16:58:51 flaptoppy nm-dispatcher[12567]: req:3 'up' [wlp2s0]: new request (1 scripts)
apr 10 16:58:51 flaptoppy nm-dispatcher[12567]: req:3 'up' [wlp2s0]: start running ordered scripts...
apr 10 16:58:51 flaptoppy NetworkManager[11263]: <info>  [1523372331.6880] manager: NetworkManager state is now CONNECTED_GLOBAL
apr 10 16:58:51 flaptoppy nm-dispatcher[12567]: req:4 'connectivity-change': new request (1 scripts)
apr 10 16:58:51 flaptoppy nm-dispatcher[12567]: req:4 'connectivity-change': start running ordered scripts...
apr 10 16:58:51 flaptoppy plasmashell[12315]: networkmanager-qt: virtual void NetworkManager::ActiveConnectionPrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "StateFlags"
apr 10 16:58:52 flaptoppy plasmashell[12315]: kf5.kio.core: KLocalSocket(0x559b807e5f60) Jumbo packet of 39432 bytes
apr 10 16:58:52 flaptoppy plasmashell[12315]: kf5.kio.core: KLocalSocket(0x559b807e5f60) Jumbo packet of 39432 bytes
apr 10 16:59:00 flaptoppy plasmashell[12315]: Currrent active notifications: QHash()
apr 10 16:59:00 flaptoppy plasmashell[12315]: Guessing partOf as: 0