Log output when connecting to WPA-EAP network

eap.log (text/x-log), 9.71 KB, created by Ferry on 2018-04-10 11:08:44 UTC

apr 10 11:47:30 flaptoppy wpa_supplicant[386]: wlp2s0: Trying to associate with SSID 'BCLD-Test-EAP'
apr 10 11:47:30 flaptoppy NetworkManager[4427]: <info>  [1523353650.2987] device (wlp2s0): supplicant interface state: scanning -> associating
apr 10 11:47:33 flaptoppy wpa_supplicant[386]: wlp2s0: Associated with 82:2a:a8:8b:df:67
apr 10 11:47:33 flaptoppy wpa_supplicant[386]: wlp2s0: CTRL-EVENT-EAP-STARTED EAP authentication started
apr 10 11:47:33 flaptoppy kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlp2s0: link becomes ready
apr 10 11:47:33 flaptoppy wpa_supplicant[386]: wlp2s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
apr 10 11:47:33 flaptoppy wpa_supplicant[386]: wlp2s0: CTRL-EVENT-EAP-PROPOSED-METHOD vendor=0 method=4 -> NAK
apr 10 11:47:33 flaptoppy wpa_supplicant[386]: wlp2s0: CTRL-EVENT-EAP-PROPOSED-METHOD vendor=0 method=25
apr 10 11:47:33 flaptoppy wpa_supplicant[386]: wlp2s0: CTRL-EVENT-EAP-METHOD EAP vendor 0 method 25 (PEAP) selected
apr 10 11:47:33 flaptoppy wpa_supplicant[386]: wlp2s0: CTRL-EVENT-EAP-PEER-CERT depth=0 subject='/CN=radius' hash=e93bd58fd395fb05d3a3626ba1ee7be217f54b16a18fe4620e7f537d80dc792d
apr 10 11:47:33 flaptoppy wpa_supplicant[386]: EAP-MSCHAPV2: Authentication succeeded
apr 10 11:47:33 flaptoppy wpa_supplicant[386]: EAP-TLV: TLV Result - Success - EAP-TLV/Phase2 Completed
apr 10 11:47:33 flaptoppy wpa_supplicant[386]: wlp2s0: CTRL-EVENT-EAP-SUCCESS EAP authentication completed successfully
apr 10 11:47:33 flaptoppy wpa_supplicant[386]: wlp2s0: WPA: Key negotiation completed with 82:2a:a8:8b:df:67 [PTK=CCMP GTK=CCMP]
apr 10 11:47:33 flaptoppy wpa_supplicant[386]: wlp2s0: CTRL-EVENT-CONNECTED - Connection to 82:2a:a8:8b:df:67 completed [id=0 id_str=]
apr 10 11:47:33 flaptoppy NetworkManager[4427]: <info>  [1523353653.5424] device (wlp2s0): supplicant interface state: associating -> associated
apr 10 11:47:33 flaptoppy NetworkManager[4427]: <info>  [1523353653.5803] device (wlp2s0): supplicant interface state: associated -> completed
apr 10 11:47:33 flaptoppy NetworkManager[4427]: <info>  [1523353653.5803] device (wlp2s0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'BCLD-Test-EAP'.
apr 10 11:47:33 flaptoppy NetworkManager[4427]: <info>  [1523353653.5805] device (wlp2s0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
apr 10 11:47:33 flaptoppy NetworkManager[4427]: <info>  [1523353653.5808] dhcp4 (wlp2s0): activation: beginning transaction (timeout in 45 seconds)
apr 10 11:47:33 flaptoppy NetworkManager[4427]: <info>  [1523353653.5819] dhcp4 (wlp2s0): dhcpcd started with pid 4791
apr 10 11:47:33 flaptoppy kdeinit5[488]: networkmanager-qt: virtual void NetworkManager::ActiveConnectionPrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "StateFlags"
apr 10 11:47:33 flaptoppy kcmshell5[1726]: networkmanager-qt: virtual void NetworkManager::ActiveConnectionPrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "StateFlags"
apr 10 11:47:33 flaptoppy plasmashell[541]: networkmanager-qt: virtual void NetworkManager::ActiveConnectionPrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "StateFlags"
apr 10 11:47:34 flaptoppy dhcpcd[4791]: wlp2s0: rebinding lease of 10.234.234.59
apr 10 11:47:34 flaptoppy dhcpcd[4791]: wlp2s0: leased 10.234.234.59 for 300 seconds
apr 10 11:47:34 flaptoppy dhcpcd[4791]: wlp2s0: adding route to 10.234.234.0/24
apr 10 11:47:34 flaptoppy kernel: brcmfmac: brcmf_inetaddr_changed: fail to get arp ip table err:-52
apr 10 11:47:34 flaptoppy NetworkManager[4427]: <info>  [1523353654.2015] dhcp4 (wlp2s0):   address 10.234.234.59
apr 10 11:47:34 flaptoppy NetworkManager[4427]: <info>  [1523353654.2015] dhcp4 (wlp2s0):   plen 24 (255.255.255.0)
apr 10 11:47:34 flaptoppy NetworkManager[4427]: <info>  [1523353654.2016] dhcp4 (wlp2s0):   gateway 10.234.234.254
apr 10 11:47:34 flaptoppy NetworkManager[4427]: <info>  [1523353654.2016] dhcp4 (wlp2s0):   lease time 300
apr 10 11:47:34 flaptoppy NetworkManager[4427]: <info>  [1523353654.2016] dhcp4 (wlp2s0):   nameserver '10.234.234.11'
apr 10 11:47:34 flaptoppy NetworkManager[4427]: <info>  [1523353654.2016] dhcp4 (wlp2s0):   domain name 'test.local'
apr 10 11:47:34 flaptoppy NetworkManager[4427]: <info>  [1523353654.2016] dhcp4 (wlp2s0): state changed unknown -> bound
apr 10 11:47:34 flaptoppy NetworkManager[4427]: <info>  [1523353654.2024] device (wlp2s0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
apr 10 11:47:34 flaptoppy NetworkManager[4427]: <info>  [1523353654.2030] device (wlp2s0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
apr 10 11:47:34 flaptoppy kdeinit5[488]: networkmanager-qt: virtual void NetworkManager::ActiveConnectionPrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "StateFlags"
apr 10 11:47:34 flaptoppy NetworkManager[4427]: <info>  [1523353654.2033] device (wlp2s0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
apr 10 11:47:34 flaptoppy NetworkManager[4427]: <info>  [1523353654.2034] manager: NetworkManager state is now CONNECTED_LOCAL
apr 10 11:47:34 flaptoppy NetworkManager[4427]: <info>  [1523353654.2065] manager: NetworkManager state is now CONNECTED_SITE
apr 10 11:47:34 flaptoppy NetworkManager[4427]: <info>  [1523353654.2066] policy: set 'BCLD-Test-EAP' (wlp2s0) as default for IPv4 routing and DNS
apr 10 11:47:34 flaptoppy NetworkManager[4427]: <info>  [1523353654.2069] device (wlp2s0): Activation: successful, device activated.
apr 10 11:47:34 flaptoppy dbus-daemon[346]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.83' (uid=0 pid=4427 comm="/usr/sbin/NetworkManager --no-daemon ")
apr 10 11:47:34 flaptoppy systemd[1]: Starting Network Manager Script Dispatcher Service...
apr 10 11:47:34 flaptoppy NetworkManager[4427]: <info>  [1523353654.2113] manager: NetworkManager state is now CONNECTED_GLOBAL
apr 10 11:47:34 flaptoppy dbus-daemon[346]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
apr 10 11:47:34 flaptoppy systemd[1]: Started Network Manager Script Dispatcher Service.
apr 10 11:47:34 flaptoppy nm-dispatcher[4800]: req:1 'up' [wlp2s0]: new request (1 scripts)
apr 10 11:47:34 flaptoppy nm-dispatcher[4800]: req:1 'up' [wlp2s0]: start running ordered scripts...
apr 10 11:47:34 flaptoppy nm-dispatcher[4800]: req:2 'connectivity-change': new request (1 scripts)
apr 10 11:47:34 flaptoppy nm-dispatcher[4800]: req:2 'connectivity-change': start running ordered scripts...
apr 10 11:47:34 flaptoppy kcmshell5[1726]: networkmanager-qt: virtual void NetworkManager::ActiveConnectionPrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "StateFlags"
apr 10 11:47:34 flaptoppy plasmashell[541]: networkmanager-qt: virtual void NetworkManager::ActiveConnectionPrivate::propertyChanged(const QString&, const QVariant&) Unhandled property "StateFlags"
apr 10 11:47:34 flaptoppy plasmashell[541]: kf5.kio.core: KLocalSocket(0x5655423d52b0) Jumbo packet of 39432 bytes
apr 10 11:47:34 flaptoppy plasmashell[541]: kf5.kio.core: KLocalSocket(0x5655423d52b0) Jumbo packet of 39432 bytes
apr 10 11:47:35 flaptoppy NetworkManager[4427]: <info>  [1523353655.2849] dhcp6 (wlp2s0): activation: beginning transaction (timeout in 45 seconds)
apr 10 11:47:35 flaptoppy NetworkManager[4427]: <warn>  [1523353655.2849] dhcp6 (wlp2s0): the dhcpcd backend does not support IPv6
apr 10 11:47:35 flaptoppy NetworkManager[4427]: <info>  [1523353655.2850] device (wlp2s0): state change: activated -> failed (reason 'dhcp-start-failed', sys-iface-state: 'managed')
apr 10 11:47:35 flaptoppy NetworkManager[4427]: <info>  [1523353655.2851] manager: NetworkManager state is now DISCONNECTED
apr 10 11:47:35 flaptoppy NetworkManager[4427]: <warn>  [1523353655.2858] device (wlp2s0): Activation: failed for connection 'BCLD-Test-EAP'
apr 10 11:47:35 flaptoppy NetworkManager[4427]: <info>  [1523353655.2866] device (wlp2s0): state change: failed -> disconnected (reason 'none', sys-iface-state: 'managed')
apr 10 11:47:35 flaptoppy nm-dispatcher[4800]: req:3 'down' [wlp2s0]: new request (1 scripts)
apr 10 11:47:35 flaptoppy nm-dispatcher[4800]: req:3 'down' [wlp2s0]: start running ordered scripts...
apr 10 11:47:35 flaptoppy dhcpcd[4791]: received SIGTERM, stopping
apr 10 11:47:35 flaptoppy dhcpcd[4791]: wlp2s0: removing interface
apr 10 11:47:35 flaptoppy nm-dispatcher[4800]: req:4 'connectivity-change': new request (1 scripts)
apr 10 11:47:35 flaptoppy nm-dispatcher[4800]: req:4 'connectivity-change': start running ordered scripts...
apr 10 11:47:35 flaptoppy NetworkManager[4427]: <info>  [1523353655.7950] dhcp4 (wlp2s0): canceled DHCP transaction, DHCP client pid 4791
apr 10 11:47:35 flaptoppy NetworkManager[4427]: <info>  [1523353655.7950] dhcp4 (wlp2s0): state changed bound -> done
apr 10 11:47:35 flaptoppy wpa_supplicant[386]: wlp2s0: CTRL-EVENT-DISCONNECTED bssid=82:2a:a8:8b:df:67 reason=3 locally_generated=1
apr 10 11:47:35 flaptoppy kernel: brcmfmac: brcmf_inetaddr_changed: fail to get arp ip table err:-52
apr 10 11:47:36 flaptoppy NetworkManager[4427]: <info>  [1523353656.3069] device (wlp2s0): set-hw-addr: set MAC address to 26:F7:BC:E4:01:71 (scanning)
apr 10 11:47:36 flaptoppy kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
apr 10 11:47:36 flaptoppy NetworkManager[4427]: <warn>  [1523353656.3097] sup-iface[0x562d3c337970,wlp2s0]: connection disconnected (reason -3)
apr 10 11:47:36 flaptoppy NetworkManager[4427]: <info>  [1523353656.3097] device (wlp2s0): supplicant interface state: completed -> disconnected
apr 10 11:47:36 flaptoppy NetworkManager[4427]: <warn>  [1523353656.3122] dhcp-listener: dhcp-event: (pid 4791) unhandled DHCP event for interface wlp2s0