NetBSD Problem Report #57308

From www@netbsd.org  Wed Mar 29 08:43:06 2023
Return-Path: <www@netbsd.org>
Received: from mail.netbsd.org (mail.netbsd.org [199.233.217.200])
	(using TLSv1.3 with cipher TLS_AES_256_GCM_SHA384 (256/256 bits))
	(Client CN "mail.NetBSD.org", Issuer "mail.NetBSD.org CA" (not verified))
	by mollari.NetBSD.org (Postfix) with ESMTPS id 39A841A9239
	for <gnats-bugs@gnats.NetBSD.org>; Wed, 29 Mar 2023 08:43:06 +0000 (UTC)
Message-Id: <20230329084301.237E61A923C@mollari.NetBSD.org>
Date: Wed, 29 Mar 2023 08:43:01 +0000 (UTC)
From: bbartlomiej.mail@gmail.com
Reply-To: bbartlomiej.mail@gmail.com
To: gnats-bugs@NetBSD.org
Subject: Raspberry Pi 3 bwfm0 with wpa_supplicant stops working after few hours
X-Send-Pr-Version: www-1.0

>Number:         57308
>Category:       port-evbarm
>Synopsis:       Raspberry Pi 3 bwfm0 with wpa_supplicant stops working after few hours
>Confidential:   no
>Severity:       serious
>Priority:       medium
>Responsible:    port-evbarm-maintainer
>State:          open
>Class:          sw-bug
>Submitter-Id:   net
>Arrival-Date:   Wed Mar 29 08:45:00 +0000 2023
>Last-Modified:  Mon Apr 03 17:00:02 +0000 2023
>Originator:     Bartek Krawczyk
>Release:        10.0_BETA
>Organization:
>Environment:
NetBSD rpi3 10.0_BETA NetBSD 10.0_BETA (GENERIC64) #0: Thu Mar 16 07:19:51 UTC 2023  mkrepro@mkrepro.NetBSD.org:/usr/src/sys/arch/evbarm/compile/GENERIC64 evbarm
>Description:
I have installed 10.0_BETA on Raspberry Pi 3 a week ago and was compiling some pkgsrc packages. I have noticed that the wireless connection drops after few hours and never gets back - hence I exclude interferences. I have gathered logs during the outage over serial console. I have disabled automatic channel re-selection on the AP but it didn't help. Other devices (windows - intel wifi card, linux - intel wifi card and macos - airport bsd device name en0) work fine even with channel re-selection. It's a simple configuration with just a WPA2-PSK added. I tried looking but people have similar problems for years (not only on BSD) but no solution. One thread suggests it may be problem with missed disconnect/reconnect event from the driver: https://hostap.shmoo.narkive.com/qXvQMVLP/wpa-supplicant-wpa-eapol-key-replay-counter-did-not-increase-dropping-packet#post3

What helps is to restart wpa_supplicant and sometimes dhcpcd if it doesn't react to this action automatically afterwards.

LOGS:

MikroTik AP/router during outage:

 10:21:21 caps,debug B8:27:EB:E4:30:C8@5GHz-ap-salon-1 connected, signal strength -63
 10:21:27 caps,debug B8:27:EB:E4:30:C8@5GHz-ap-salon-1 disconnected, 4-way handshake timeout, signal strength -62
 10:21:30 caps,debug B8:27:EB:E4:30:C8@5GHz-ap-salon-1 connected, signal strength -63
 10:21:36 caps,debug B8:27:EB:E4:30:C8@5GHz-ap-salon-1 disconnected, 4-way handshake timeout, signal strength -63
 10:21:40 caps,debug B8:27:EB:E4:30:C8@5GHz-ap-salon-1 connected, signal strength -63
 10:21:46 caps,debug B8:27:EB:E4:30:C8@5GHz-ap-salon-1 disconnected, 4-way handshake timeout, signal strength -62
 10:21:49 caps,debug B8:27:EB:E4:30:C8@5GHz-ap-salon-1 connected, signal strength -63
 10:21:55 caps,debug B8:27:EB:E4:30:C8@5GHz-ap-salon-1 disconnected, 4-way handshake timeout, signal strength -62
 10:21:58 caps,debug B8:27:EB:E4:30:C8@5GHz-ap-salon-1 connected, signal strength -63
 10:22:04 caps,debug B8:27:EB:E4:30:C8@5GHz-ap-salon-1 disconnected, 4-way handshake timeout, signal strength -63
 10:22:08 caps,debug B8:27:EB:E4:30:C8@5GHz-ap-salon-1 connected, signal strength -61
 10:22:14 caps,debug B8:27:EB:E4:30:C8@5GHz-ap-salon-1 disconnected, 4-way handshake timeout, signal strength -58
 10:22:17 caps,debug B8:27:EB:E4:30:C8@5GHz-ap-salon-1 connected, signal strength -63
 10:22:23 caps,debug B8:27:EB:E4:30:C8@5GHz-ap-salon-1 disconnected, 4-way handshake timeout, signal strength -59
 10:22:27 caps,debug B8:27:EB:E4:30:C8@5GHz-ap-salon-1 connected, signal strength -63
 10:22:33 caps,debug B8:27:EB:E4:30:C8@5GHz-ap-salon-1 disconnected, 4-way handshake timeout, signal strength -60
 10:22:36 caps,debug B8:27:EB:E4:30:C8@5GHz-ap-salon-1 connected, signal strength -59
 10:22:42 caps,debug B8:27:EB:E4:30:C8@5GHz-ap-salon-1 disconnected, 4-way handshake timeout, signal strength -60
 10:22:45 caps,debug B8:27:EB:E4:30:C8@5GHz-ap-salon-1 connected, signal strength -62
 10:22:51 caps,debug B8:27:EB:E4:30:C8@5GHz-ap-salon-1 disconnected, 4-way handshake timeout, signal strength -64
 10:23:00 caps,debug B8:27:EB:E4:30:C8@5GHz-ap-salon-1 connected, signal strength -62

dmesg:

[     1.000000] Copyright (c) 1996, 1997, 1998, 1999, 2000, 2001, 2002, 2003,
[     1.000000]     2004, 2005, 2006, 2007, 2008, 2009, 2010, 2011, 2012, 2013,
[     1.000000]     2014, 2015, 2016, 2017, 2018, 2019, 2020, 2021, 2022, 2023
[     1.000000]     The NetBSD Foundation, Inc.  All rights reserved.
[     1.000000] Copyright (c) 1982, 1986, 1989, 1991, 1993
[     1.000000]     The Regents of the University of California.  All rights reserved.

[     1.000000] NetBSD 10.0_BETA (GENERIC64) #0: Thu Mar 16 07:19:51 UTC 2023
[     1.000000] 	mkrepro@mkrepro.NetBSD.org:/usr/src/sys/arch/evbarm/compile/GENERIC64
[     1.000000] total memory = 928 MB
[     1.000000] avail memory = 893 MB
[     1.000000] timecounter: Timecounters tick every 10.000 msec
[     1.000000] Kernelized RAIDframe activated
[     1.000000] armfdt0 (root)
[     1.000000] simplebus0 at armfdt0: Raspberry Pi 3 Model B Plus Rev 1.3
[     1.000000] simplebus1 at simplebus0
[     1.000000] simplebus2 at simplebus0
[     1.000000] cpus0 at simplebus0
[     1.000000] simplebus3 at simplebus0
[     1.000000] cpu0 at cpus0: Arm Cortex-A53 r0p4 (v8-A), id 0x0
[     1.000000] cpu0: package 0, core 0, smt 0
[     1.000000] cpu0: IC enabled, DC enabled, EL0/EL1 stack Alignment check enabled
[     1.000000] cpu0: Cache Writeback Granule 16B, Exclusives Reservation Granule 16B
[     1.000000] cpu0: Dcache line 64, Icache line 64, DIC=0, IDC=0, LoUU=1, LoC=2, LoUIS=1
[     1.000000] cpu0: L1 32KB/64B 2-way (256 set) VIPT Instruction cache
[     1.000000] cpu0: L1 32KB/64B 4-way (128 set) PIPT Data cache
[     1.000000] cpu0: L2 512KB/64B 16-way (512 set) PIPT Unified cache
[     1.000000] cpu0: revID=0x80, PMCv3, 4k table, 64k table, 16bit ASID
[     1.000000] cpu0: auxID=0x10000, FP, CRC32, NEON, rounding, NaN propagation, denormals, 32x64bitRegs, Fused Multiply-Add
[     1.000000] cpu1 at cpus0: Arm Cortex-A53 r0p4 (v8-A), id 0x1
[     1.000000] cpu1: package 0, core 1, smt 0
[     1.000000] cpu1: IC enabled, DC enabled, EL0/EL1 stack Alignment check enabled
[     1.000000] cpu1: Cache Writeback Granule 16B, Exclusives Reservation Granule 16B
[     1.000000] cpu1: Dcache line 64, Icache line 64, DIC=0, IDC=0, LoUU=1, LoC=2, LoUIS=1
[     1.000000] cpu1: L1 32KB/64B 2-way (256 set) VIPT Instruction cache
[     1.000000] cpu1: L1 32KB/64B 4-way (128 set) PIPT Data cache
[     1.000000] cpu1: L2 512KB/64B 16-way (512 set) PIPT Unified cache
[     1.000000] cpu1: revID=0x80, PMCv3, 4k table, 64k table, 16bit ASID
[     1.000000] cpu1: auxID=0x10000, FP, CRC32, NEON, rounding, NaN propagation, denormals, 32x64bitRegs, Fused Multiply-Add
[     1.000000] cpu2 at cpus0: Arm Cortex-A53 r0p4 (v8-A), id 0x2
[     1.000000] cpu2: package 0, core 2, smt 0
[     1.000000] cpu2: IC enabled, DC enabled, EL0/EL1 stack Alignment check enabled
[     1.000000] cpu2: Cache Writeback Granule 16B, Exclusives Reservation Granule 16B
[     1.000000] cpu2: Dcache line 64, Icache line 64, DIC=0, IDC=0, LoUU=1, LoC=2, LoUIS=1
[     1.000000] cpu2: L1 32KB/64B 2-way (256 set) VIPT Instruction cache
[     1.000000] cpu2: L1 32KB/64B 4-way (128 set) PIPT Data cache
[     1.000000] cpu2: L2 512KB/64B 16-way (512 set) PIPT Unified cache
[     1.000000] cpu2: revID=0x80, PMCv3, 4k table, 64k table, 16bit ASID
[     1.000000] cpu2: auxID=0x10000, FP, CRC32, NEON, rounding, NaN propagation, denormals, 32x64bitRegs, Fused Multiply-Add
[     1.000000] cpu3 at cpus0: Arm Cortex-A53 r0p4 (v8-A), id 0x3
[     1.000000] cpu3: package 0, core 3, smt 0
[     1.000000] cpu3: IC enabled, DC enabled, EL0/EL1 stack Alignment check enabled
[     1.000000] cpu3: Cache Writeback Granule 16B, Exclusives Reservation Granule 16B
[     1.000000] cpu3: Dcache line 64, Icache line 64, DIC=0, IDC=0, LoUU=1, LoC=2, LoUIS=1
[     1.000000] cpu3: L1 32KB/64B 2-way (256 set) VIPT Instruction cache
[     1.000000] cpu3: L1 32KB/64B 4-way (128 set) PIPT Data cache
[     1.000000] cpu3: L2 512KB/64B 16-way (512 set) PIPT Unified cache
[     1.000000] cpu3: revID=0x80, PMCv3, 4k table, 64k table, 16bit ASID
[     1.000000] cpu3: auxID=0x10000, FP, CRC32, NEON, rounding, NaN propagation, denormals, 32x64bitRegs, Fused Multiply-Add
[     1.000000] bcmicu0 at simplebus1
[     1.000000] fclock0 at simplebus2: 19200000 Hz fixed clock (osc)
[     1.000000] bcmcprman0 at simplebus1: BCM283x Clock Controller
[     1.000000] syscon0 at simplebus1autoconfiguration error: : couldn't get registers
[     1.000000] bcmaux0 at simplebus1
[     1.000000] fclock1 at simplebus2: 480000000 Hz fixed clock (otg)
[     1.000000] bcmicu1 at simplebus1: Multiprocessor
[     1.000000] gtmr0 at simplebus0: Generic Timer
[     1.000000] gtmr0: interrupting on local_intc irq 3
[     1.000000] armgtmr0 at gtmr0: Generic Timer (19200 kHz, virtual)
[     1.000000] timecounter: Timecounter "armgtmr0" frequency 19200000 Hz quality 500
[     1.000004] bcmgpio0 at simplebus1: GPIO controller 2835
[     1.000004] bcmgpio0: pins 0..31 interrupting on icu irq 49
[     1.000004] bcmgpio0: pins 32..54 interrupting on icu irq 50
[     1.000004] gpio0 at bcmgpio0: 54 pins
[     1.000004] plcom0 at simplebus1: ARM PL011 UART
[     1.000004] plcom0: txfifo disabled
[     1.000004] plcom0: interrupting on icu irq 57
[     1.000004] com0 at simplebus1: BCM AUX UART, 1-byte FIFO
[     1.000004] com0: console
[     1.000004] com0: interrupting on icu irq 29
[     1.000004] mmcpwrseq0 at simplebus0autoconfiguration error: : couldn't get reset GPIOs
[     1.000004] /soc/thermal@7e212000 at simplebus1 not configured
[     1.000004] bcmdmac0 at simplebus1: DMA0 DMA2 DMA4 DMA5 DMA8 DMA9 DMA10 DMA11
[     1.000004] /soc/power at simplebus1 not configured
[     1.000004] /phy at simplebus0 not configured
[     1.000004] bsciic0 at simplebus1: Broadcom Serial Controller
[     1.000004] bsciic0: interrupting on icu irq 53
[     1.000004] iic0 at bsciic0: I2C bus
[     1.000004] bcmmbox0 at simplebus1: VC mailbox
[     1.000004] bcmmbox0: interrupting on icu irq 65
[     1.000004] vcmbox0 at bcmmbox0
[     1.000004] /soc/timer@7e003000 at simplebus1 not configured
[     1.000004] /soc/txp@7e004000 at simplebus1 not configured
[     1.000004] bcmsdhost0 at simplebus1: SD HOST controller
[     1.000004] bcmsdhost0: interrupting on icu irq 56
[     1.000004] bsciic1 at simplebus1: Broadcom Serial Controller
[     1.000004] bsciic1: interrupting on icu irq 53
[     1.000004] iic1 at bsciic1: I2C bus
[     1.000004] /soc/pwm@7e20c000 at simplebus1 not configured
[     1.000004] sdhc0 at simplebus1: SDHC controller
[     1.000004] sdhc0: interrupting on icu irq 62
[     1.000004] bsciic2 at simplebus1: Broadcom Serial Controller
[     1.000004] bsciic2: interrupting on icu irq 53
[     1.000004] iic2 at bsciic2: I2C bus
[     1.000004] dwctwo0 at simplebus1: USB controller
[     1.000004] dwctwo0: interrupting on icu irq 9
[     1.000004] bcmpmwdog0 at simplebus1: Power management, Reset and Watchdog controller
[     1.000004] /soc/vec@7e806000 at simplebus1 not configured
[     1.000004] /soc/hdmi@7e902000 at simplebus1 not configured
[     1.000004] /soc/gpu at simplebus1 not configured
[     1.000004] genfb0 at simplebus1
[     1.000004] genfb0: framebuffer at 0xfddea000, size 1920x1280, depth 32, stride 10240
[     1.000004] wsdisplay0 at genfb0 kbdmux 1
[     1.000004] wsmux1: connecting to wsdisplay0
[     1.000004] wsdisplay0: screen 0-3 added (default, vt100 emulation)
[     1.000004] vchiq0 at simplebus1: BCM2835 VCHIQ
[     1.000004] armpmu0 at simplebus0: Performance Monitor Unit
[     1.000004] gpioleds0 at simplebus0: ACT
[     1.000004] bcmrng0 at simplebus1: RNG
[     1.000004] entropy: ready
[     1.000004] timecounter: Timecounter "clockinterrupt" frequency 100 Hz quality 0
[     1.422800] sdmmc0 at bcmsdhost0
[     1.422800] sdhc0: SDHC 3.0, rev 153, caps <01610000/00000000>, platform DMA, 200000 kHz, HS 3.3V, re-tuning mode 1, 1024 byte blocks
[     1.432802] sdmmc1 at sdhc0 slot 0
[     1.442802] dwctwo0: Core Release: 2.80a (snpsid=4f54280a)
[     1.442802] usb0 at dwctwo0: USB revision 2.0
[     1.452805] armpmu0: interrupting on local_intc irq 9
[     1.462807] uhub0 at usb0: NetBSD (0x0000) DWC2 root hub (0x0000), class 9/0, rev 2.00/1.00, addr 1
[     1.472812] uhub0: 1 port with 1 removable, self powered
[     1.472812] IPsec: Initialized Security Association Processing.
[     1.542815] sdmmc0: direct I/O error 5, r=6 p=0xffffc000ab0c2e3c write
[     1.562817] sdmmc0: SD card status: 4-bit, C10, U3, V30, A2
[     1.573928] ld0 at sdmmc0: <0x1d:0x4144:SD   :0x02:0xd7022ac5:0x13c>
[     1.573928] ld0: 117 GB, 15371 cyl, 255 head, 63 sec, 512 bytes/sect x 246947840 sectors
[     1.592824] ld0: GPT GUID: baaf947f-d58a-4846-b8ec-208954cd0977
[     1.592824] dk0 at ld0: "EFI", 163840 blocks at 32768, type: msdos
[     1.592824] dk1 at ld0: "netbsd-root", 246743040 blocks at 196608, type: ffs
[     1.606265] ld0: 4-bit width, High-Speed/SDR25, 50.000 MHz
[     1.662830] sdmmc1: 4-bit width, 50.000 MHz
[     1.672830] sdmmc1: SDIO function
[     1.672830] bwfm0 at sdmmc1 function 1
[     1.682838] (manufacturer 0x2d0, product 0xa9a6) at sdmmc1 function 2 not configured
[     1.682838] (manufacturer 0x2d0, product 0xa9a6, standard function interface code 0x2) at sdmmc1 function 3 not configured
[     2.092867] uhub1 at uhub0 port 1: vendor 0424 (0x0424) product 2514 (0x2514), class 9/0, rev 2.00/b.b3, addr 2
[     2.102869] uhub1: multiple transaction translators
[     2.112869] uhub1: 4 ports with 3 removable, self powered
[     2.932935] uhub2 at uhub1 port 1: vendor 0424 (0x0424) product 2514 (0x2514), class 9/0, rev 2.00/b.b3, addr 3
[     2.942938] uhub2: multiple transaction translators
[     2.952937] uhub2: 3 ports with 2 removable, self powered
[     3.282965] uhub0: autoconfiguration error: illegal enable change, port 1
[     3.292966] swwdog0: software watchdog initialized
[     3.302968] WARNING: 3 errors while detecting hardware; check system log.
[     3.302968] boot device: ld0
[     3.312967] root on dk1
[     3.312967] root file system type: ffs
[     3.322969] kern.module.path=/stand/evbarm/10.0/modules
[     3.322969] WARNING: no TOD clock present
[     3.332971] vchiq0: interrupting on icu irq 66
[     3.332971] WARNING: using filesystem time
[     3.332971] vchiq: vchiq_init_state: slot_zero = 0xffffc000ab5b8000, is_master = 0
[     3.343594] WARNING: CHECK AND RESET THE DATE!
[     3.343646] vchiq: local ver 8 (min 3), remote ver 8.
[     3.343646] vcaudio0 at vchiq0: auds
[     3.343646] bwfm0: Firmware file default:    brcmfmac43455-sdio.bin
[     3.343646] bwfm0: Firmware file model-spec: brcmfmac43455-sdio.raspberrypi,3-model-b-plus.bin
[     3.352975] audio0 at vcaudio0: playback
[     3.352975] audio0: slinear_le:16 -> slinear_le:16 2ch 48000Hz, blk 7680 bytes (40ms) for playback
[     3.371823] spkr0 at audio0: PC Speaker (synthesized)
[     3.371823] wsbell at spkr0 not configured
[     3.391823] bwfm0: Found Firmware file: brcmfmac43455-sdio.bin
[     3.411826] bwfm0: NVRAM file default:    brcmfmac43455-sdio.txt
[     3.411826] bwfm0: NVRAM file model-spec: brcmfmac43455-sdio.raspberrypi,3-model-b-plus.txt
[     3.421876] bwfm0: Found NVRAM file: brcmfmac43455-sdio.raspberrypi,3-model-b-plus.txt
[     3.421876] bwfm0: CLM file default:    brcmfmac43455-sdio.clm_blob
[     3.421876] bwfm0: CLM file model-spec: brcmfmac43455-sdio.raspberrypi,3-model-b-plus.clm_blob
[     4.221954] mue0 at uhub2 port 1
[     4.221954] mue0: SMSC (0x0424) LAN7800 USB 3.1 gigabit ethernet device (0x7800), rev 2.10/3.00, addr 4
[     4.511965] mue0: LAN7800 id 0x7800 rev 0x2
[     4.521968] ukphy0 at mue0 phy 1: SMSC SMSC LAN8742 10/100 media interface (OUI 0x00800f, model 0x0013), rev. 2
[     4.535954] ukphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, 1000baseT, 1000baseT-FDX, auto
[     4.541972] mue0: Ethernet address b8:27:eb:b1:65:9d
[     5.152025] bwfm0: CHIPACTIVE
[     5.252038] bwfm0: address b8:27:eb:e4:30:c8
[     5.252038] bwfm0: wl0: Mar  1 2015 07:29:38 version 7.45.18 (r538002) FWID 01-6a2c8ad4
[     8.162621] wsdisplay0: screen 4 added (default, vt100 emulation)


ifconfig strangely shows the status is active:

bwfm0: flags=0x8843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST> mtu 1500
	ssid "bat cave" nwkey 65536:"",0xb780227093b1588de735d3c44428d88a,0xa9200637d9447e94d5ee3b6bdc94bc1c,""
	powersave off
	bssid cc:2d:e0:ea:16:13 chan 8
	address: b8:27:eb:e4:30:c8
	media: IEEE802.11 autoselect (OFDM54 mode 11g)
	status: active
	inet6 fe80::e739:9d49:e1e3:c527%bwfm0/64 flags 0 scopeid 0x3
	inet 192.168.2.212/24 broadcast 192.168.2.255 flags 0

wpa_supplicant.conf:

# $NetBSD: wpa_supplicant.conf,v 1.1 2019/01/12 16:51:54 roy Exp $

# Allow wpa_cli(8) to configure wpa_supplicant
ctrl_interface=/var/run/wpa_supplicant
ctrl_interface_group=wheel
update_config=1
network={
	ssid="bat cave"
	proto=WPA2
	psk=XYZ
}

rc.conf:

wpa_supplicant=YES
wpa_supplicant_flags="-Ms -i bwfm0 -K -dd -f /var/log/wpa_supplicant.log -c /etc/wpa_supplicant.conf"

/var/log/messages grepped for "wpa_supplicant" and "bwfm0":

Mar 27 14:03:10 rpi3 wpa_supplicant[6506]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 14:08:10 rpi3 wpa_supplicant[6506]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 14:12:09 rpi3 ntpd[1726]: Listen normally on 11 bwfm0 192.168.2.212:123
Mar 27 14:13:10 rpi3 wpa_supplicant[6506]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 14:18:10 rpi3 wpa_supplicant[6506]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 14:23:10 rpi3 wpa_supplicant[6506]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 14:28:10 rpi3 wpa_supplicant[6506]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 14:18:10 rpi3 wpa_supplicant[6506]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 14:18:10 rpi3 wpa_supplicant[6506]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 14:40:10 rpi3 dhcpcd[718]: bwfm0: carrier lost - roaming
Mar 27 14:40:10 rpi3 wpa_supplicant[6506]: bwfm0: CTRL-EVENT-DISCONNECTED bssid=cc:2d:e0:ea:16:14 reason=3 locally_generated=1
Mar 27 14:40:10 rpi3 wpa_supplicant[6506]: ioctl[SIOCS80211, op=20, val=0, arg_len=7]: Can't assign requested address
Mar 27 14:40:10 rpi3 wpa_supplicant[6506]: bwfm0: CTRL-EVENT-TERMINATING 
Mar 27 14:40:10 rpi3 wpa_supplicant[5976]: Successfully initialized wpa_supplicant
Mar 27 14:40:11 rpi3 ntpd[1726]: Deleting interface #10 bwfm0, fe80::e739:9d49:e1e3:c527%3#123, interface stats: received=0, sent=0, dropped=0, active_time=2548 secs
Mar 27 14:40:11 rpi3 ntpd[1726]: Deleting interface #11 bwfm0, 192.168.2.212#123, interface stats: received=120, sent=121, dropped=0, active_time=1682 secs
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: Sorted scan results
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: cc:2d:e0:ea:16:14 freq=2432 qual=206 noise=0 level=206 flags=0x0 age=0 est=54000
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: IEs - hexdump(len=45): 00 08 62 61 74 20 63 61 76 65 01 08 0c 12 18 24 30 48 60 6c 2a 01 00 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: 80:da:c2:9c:bd:21 freq=2462 qual=199 noise=0 level=199 flags=0x0 age=0 est=54000
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: IEs - hexdump(len=59): 00 0f 43 47 41 32 31 32 31 5f 68 57 4a 55 7a 6e 58 01 0f 82 84 8b 0c 0c 12 12 96 18 18 24 30 48 60 6c 2a 01 00 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 0c 00
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: 34:2c:c4:70:b0:94 freq=5220 qual=181 noise=0 level=181 flags=0x0 age=0 est=54000
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: IEs - hexdump(len=49): 00 0a 55 50 43 38 36 38 33 38 30 35 01 08 8c 12 98 24 b0 48 60 6c 2a 01 00 dd 16 00 50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: 36:2c:94:70:b0:9c freq=2462 qual=189 noise=0 level=189 flags=0x0 age=0 est=54000
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: IEs - hexdump(len=78): 00 1e 57 69 2d 46 72 65 65 20 23 49 6e 74 65 72 6e 65 74 55 50 43 4e 61 6a 73 7a 79 62 73 7a 79 01 0f 82 84 8b 0c 0c 12 96 18 18 24 30 30 48 60 6c 2a 01 04 30 18 01 00 00 0f ac 02 02 00 00 0f ac 04 00 0f ac 02 01 00 00 0f ac 01 00 00
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: 34:2c:c4:70:b0:9c freq=2462 qual=189 noise=0 level=189 flags=0x0 age=0 est=54000
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: IEs - hexdump(len=58): 00 0a 55 50 43 38 36 38 33 38 30 35 01 0f 82 84 8b 0c 0c 12 96 18 18 24 30 30 48 60 6c 2a 01 04 30 18 01 00 00 0f ac 02 02 00 00 0f ac 04 00 0f ac 02 01 00 00 0f ac 02 00 00
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: 64:d1:54:ee:0c:fc freq=2432 qual=188 noise=0 level=188 flags=0x0 age=0 est=54000
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: IEs - hexdump(len=45): 00 08 62 61 74 20 63 61 76 65 01 08 0c 12 18 24 30 48 60 6c 2a 01 00 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: 80:da:c2:9c:bd:23 freq=2432 qual=186 noise=0 level=186 flags=0x0 age=0 est=54000
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: IEs - hexdump(len=52): 00 0f 43 47 41 32 31 32 31 5f 68 57 4a 55 7a 6e 58 01 08 8c 12 98 24 b0 48 60 6c 2a 01 00 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 0c 00
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: 3a:43:1d:73:e1:59 freq=2437 qual=183 noise=0 level=183 flags=0x0 age=0 est=54000
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: IEs - hexdump(len=78): 00 1e 57 69 2d 46 72 65 65 20 23 49 6e 74 65 72 6e 65 74 55 50 43 4e 61 6a 73 7a 79 62 73 7a 79 01 0f 82 84 8b 0c 0c 12 96 18 18 24 30 30 48 60 6c 2a 01 04 30 18 01 00 00 0f ac 02 02 00 00 0f ac 04 00 0f ac 02 01 00 00 0f ac 01 00 00
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: 38:43:7d:73:e1:59 freq=2437 qual=183 noise=0 level=183 flags=0x0 age=0 est=54000
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: IEs - hexdump(len=58): 00 0a 55 50 43 31 33 36 36 38 35 31 01 0f 82 84 8b 0c 0c 12 96 18 18 24 30 30 48 60 6c 2a 01 04 30 18 01 00 00 0f ac 02 02 00 00 0f ac 04 00 0f ac 02 01 00 00 0f ac 02 00 00
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: 3c:17:10:45:3a:15 freq=5520 qual=173 noise=0 level=173 flags=0x0 age=0 est=54000
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: IEs - hexdump(len=64): 00 1b 4f 72 61 6e 67 65 5f 53 77 69 61 74 6c 6f 77 6f 64 5f 33 41 31 30 5f 35 47 48 7a 01 08 8c 12 18 24 30 48 60 6c 2a 01 00 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: ac:f8:cc:8d:47:53 freq=2462 qual=176 noise=0 level=176 flags=0x0 age=0 est=54000
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: IEs - hexdump(len=47): 00 0a 55 50 43 31 31 33 31 36 34 33 01 08 8c 12 98 24 b0 48 60 6c 2a 01 00 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 0c 00
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: 3c:17:10:45:3a:10 freq=2412 qual=174 noise=0 level=174 flags=0x0 age=0 est=54000
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: IEs - hexdump(len=73): 00 1d 4f 72 61 6e 67 65 5f 53 77 69 61 74 6c 6f 77 6f 64 5f 33 41 31 30 5f 32 2e 34 47 48 7a 01 0f 82 84 8b 0c 0c 12 12 96 18 18 24 30 48 60 6c 2a 01 04 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 0c 00
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: Add randomness: count=1 entropy=0
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random_mix_pool - hexdump(len=16): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random_mix_pool - hexdump(len=5): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: Add randomness: count=2 entropy=1
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random_mix_pool - hexdump(len=16): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random_mix_pool - hexdump(len=5): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: Add randomness: count=3 entropy=2
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random_mix_pool - hexdump(len=16): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random_mix_pool - hexdump(len=5): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: Add randomness: count=4 entropy=3
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random_mix_pool - hexdump(len=16): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random_mix_pool - hexdump(len=5): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: Add randomness: count=5 entropy=4
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random_mix_pool - hexdump(len=16): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random_mix_pool - hexdump(len=5): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: Add randomness: count=6 entropy=5
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random_mix_pool - hexdump(len=16): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random_mix_pool - hexdump(len=5): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: Add randomness: count=7 entropy=6
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random_mix_pool - hexdump(len=16): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random_mix_pool - hexdump(len=5): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: Add randomness: count=8 entropy=7
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random_mix_pool - hexdump(len=16): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random_mix_pool - hexdump(len=5): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: Add randomness: count=9 entropy=8
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random_mix_pool - hexdump(len=16): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random_mix_pool - hexdump(len=5): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: Add randomness: count=10 entropy=9
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random_mix_pool - hexdump(len=16): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random_mix_pool - hexdump(len=5): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:14 rpi3 wpa_supplicant[7717]: bwfm0: Trying to associate with cc:2d:e0:ea:16:14 (SSID='bat cave' freq=2432 MHz)
Mar 27 14:40:15 rpi3 wpa_supplicant[7717]: bwfm0: CTRL-EVENT-DISCONNECTED bssid=cc:2d:e0:ea:16:14 reason=3 locally_generated=1
Mar 27 14:40:15 rpi3 wpa_supplicant[7717]: bwfm0: CTRL-EVENT-TERMINATING 
Mar 27 14:40:17 rpi3 wpa_supplicant[8665]: Successfully initialized wpa_supplicant
Mar 27 14:40:19 rpi3 dhcpcd[718]: bwfm0: carrier acquired
Mar 27 14:40:19 rpi3 wpa_supplicant[8026]: bwfm0: Associated with 00:00:00:00:00:00
Mar 27 14:40:19 rpi3 dhcpcd[718]: bwfm0: connected to Access Point: 
Mar 27 14:40:19 rpi3 dhcpcd[718]: bwfm0: deleting address fe80::e739:9d49:e1e3:c527
Mar 27 14:40:19 rpi3 dhcpcd[718]: bwfm0: deleting route to 192.168.2.0/24
Mar 27 14:40:19 rpi3 dhcpcd[718]: bwfm0: deleting default route via 192.168.2.1
Mar 27 14:40:19 rpi3 dhcpcd[718]: bwfm0: IAID eb:e4:30:c8
Mar 27 14:40:19 rpi3 dhcpcd[718]: bwfm0: adding address fe80::95e3:844:1fc:eccd
Mar 27 14:40:20 rpi3 wpa_supplicant[8026]: bwfm0: WPA: No SSID info found (msg 1 of 4)
Mar 27 14:40:20 rpi3 dhcpcd[718]: bwfm0: soliciting an IPv6 router
Mar 27 14:40:21 rpi3 dhcpcd[718]: bwfm0: soliciting a DHCP lease
Mar 27 14:40:21 rpi3 wpa_supplicant[8026]: bwfm0: WPA: No SSID info found (msg 1 of 4)
Mar 27 14:40:21 rpi3 wpa_supplicant[8026]: bwfm0: CTRL-EVENT-TERMINATING 
Mar 27 14:40:21 rpi3 ntpd[1726]: Listen normally on 12 bwfm0 [fe80::95e3:844:1fc:eccd%3]:123
Mar 27 14:40:22 rpi3 wpa_supplicant[4256]: Successfully initialized wpa_supplicant
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: Sorted scan results
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: cc:2d:e0:ea:16:14 freq=2442 qual=206 noise=0 level=206 flags=0x0 age=0 est=54000
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: IEs - hexdump(len=45): 00 08 62 61 74 20 63 61 76 65 01 08 0c 12 18 24 30 48 60 6c 2a 01 00 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: 80:da:c2:9c:bd:21 freq=2462 qual=199 noise=0 level=199 flags=0x0 age=0 est=54000
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: IEs - hexdump(len=59): 00 0f 43 47 41 32 31 32 31 5f 68 57 4a 55 7a 6e 58 01 0f 82 84 8b 0c 0c 12 12 96 18 18 24 30 48 60 6c 2a 01 00 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 0c 00
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: 34:2c:c4:70:b0:94 freq=5220 qual=182 noise=0 level=182 flags=0x0 age=0 est=54000
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: IEs - hexdump(len=49): 00 0a 55 50 43 38 36 38 33 38 30 35 01 08 8c 12 98 24 b0 48 60 6c 2a 01 00 dd 16 00 50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: 64:d1:54:ee:0c:fb freq=2452 qual=190 noise=0 level=190 flags=0x0 age=0 est=54000
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: IEs - hexdump(len=45): 00 08 62 61 74 20 63 61 76 65 01 08 0c 12 18 24 30 48 60 6c 2a 01 00 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: 36:2c:94:70:b0:9c freq=2462 qual=188 noise=0 level=188 flags=0x0 age=0 est=54000
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: IEs - hexdump(len=78): 00 1e 57 69 2d 46 72 65 65 20 23 49 6e 74 65 72 6e 65 74 55 50 43 4e 61 6a 73 7a 79 62 73 7a 79 01 0f 82 84 8b 0c 0c 12 96 18 18 24 30 30 48 60 6c 2a 01 04 30 18 01 00 00 0f ac 02 02 00 00 0f ac 04 00 0f ac 02 01 00 00 0f ac 01 00 00
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: 34:2c:c4:70:b0:9c freq=2462 qual=187 noise=0 level=187 flags=0x0 age=0 est=54000
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: IEs - hexdump(len=58): 00 0a 55 50 43 38 36 38 33 38 30 35 01 0f 82 84 8b 0c 0c 12 96 18 18 24 30 30 48 60 6c 2a 01 04 30 18 01 00 00 0f ac 02 02 00 00 0f ac 04 00 0f ac 02 01 00 00 0f ac 02 00 00
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: 80:da:c2:9c:bd:23 freq=2442 qual=186 noise=0 level=186 flags=0x0 age=0 est=54000
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: IEs - hexdump(len=52): 00 0f 43 47 41 32 31 32 31 5f 68 57 4a 55 7a 6e 58 01 08 8c 12 98 24 b0 48 60 6c 2a 01 00 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 0c 00
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: 3c:17:10:45:3a:15 freq=5520 qual=175 noise=0 level=175 flags=0x0 age=0 est=54000
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: IEs - hexdump(len=64): 00 1b 4f 72 61 6e 67 65 5f 53 77 69 61 74 6c 6f 77 6f 64 5f 33 41 31 30 5f 35 47 48 7a 01 08 8c 12 18 24 30 48 60 6c 2a 01 00 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: 64:d1:54:ee:0c:fc freq=2442 qual=184 noise=0 level=184 flags=0x0 age=0 est=54000
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: IEs - hexdump(len=45): 00 08 62 61 74 20 63 61 76 65 01 08 0c 12 18 24 30 48 60 6c 2a 01 00 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: 38:43:7d:73:e1:59 freq=2437 qual=183 noise=0 level=183 flags=0x0 age=0 est=54000
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: IEs - hexdump(len=58): 00 0a 55 50 43 31 33 36 36 38 35 31 01 0f 82 84 8b 0c 0c 12 96 18 18 24 30 30 48 60 6c 2a 01 04 30 18 01 00 00 0f ac 02 02 00 00 0f ac 04 00 0f ac 02 01 00 00 0f ac 02 00 00
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: 3a:43:1d:73:e1:59 freq=2437 qual=183 noise=0 level=183 flags=0x0 age=0 est=54000
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: IEs - hexdump(len=78): 00 1e 57 69 2d 46 72 65 65 20 23 49 6e 74 65 72 6e 65 74 55 50 43 4e 61 6a 73 7a 79 62 73 7a 79 01 0f 82 84 8b 0c 0c 12 96 18 18 24 30 30 48 60 6c 2a 01 04 30 18 01 00 00 0f ac 02 02 00 00 0f ac 04 00 0f ac 02 01 00 00 0f ac 01 00 00
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: ae:f8:cc:8d:47:13 freq=2462 qual=180 noise=0 level=180 flags=0x0 age=0 est=54000
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: IEs - hexdump(len=67): 00 1e 57 69 2d 46 72 65 65 20 23 49 6e 74 65 72 6e 65 74 55 50 43 4e 61 6a 73 7a 79 62 73 7a 79 01 08 8c 12 98 24 b0 48 60 6c 2a 01 00 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 01 0c 00
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: 3c:17:10:45:3a:10 freq=2412 qual=173 noise=0 level=173 flags=0x0 age=0 est=54000
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: IEs - hexdump(len=73): 00 1d 4f 72 61 6e 67 65 5f 53 77 69 61 74 6c 6f 77 6f 64 5f 33 41 31 30 5f 32 2e 34 47 48 7a 01 0f 82 84 8b 0c 0c 12 12 96 18 18 24 30 48 60 6c 2a 01 04 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 0c 00
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: Add randomness: count=1 entropy=0
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random_mix_pool - hexdump(len=16): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random_mix_pool - hexdump(len=5): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: Add randomness: count=2 entropy=1
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random_mix_pool - hexdump(len=16): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random_mix_pool - hexdump(len=5): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: Add randomness: count=3 entropy=2
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random_mix_pool - hexdump(len=16): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random_mix_pool - hexdump(len=5): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: Add randomness: count=4 entropy=3
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random_mix_pool - hexdump(len=16): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random_mix_pool - hexdump(len=5): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: Add randomness: count=5 entropy=4
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random_mix_pool - hexdump(len=16): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random_mix_pool - hexdump(len=5): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: Add randomness: count=6 entropy=5
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random_mix_pool - hexdump(len=16): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random_mix_pool - hexdump(len=5): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: Add randomness: count=7 entropy=6
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random_mix_pool - hexdump(len=16): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random_mix_pool - hexdump(len=5): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: Add randomness: count=8 entropy=7
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random_mix_pool - hexdump(len=16): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random_mix_pool - hexdump(len=5): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: Add randomness: count=9 entropy=8
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random_mix_pool - hexdump(len=16): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random_mix_pool - hexdump(len=5): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: Add randomness: count=10 entropy=9
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random_mix_pool - hexdump(len=16): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random_mix_pool - hexdump(len=5): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:25 rpi3 wpa_supplicant[9252]: bwfm0: Trying to associate with cc:2d:e0:ea:16:14 (SSID='bat cave' freq=2442 MHz)
Mar 27 14:40:26 rpi3 dhcpcd[718]: bwfm0: probing for an IPv4LL address
Mar 27 14:40:26 rpi3 dhcpcd[718]: bwfm0: using IPv4LL address 169.254.44.53
Mar 27 14:40:30 rpi3 wpa_supplicant[9252]: Add randomness: count=11 entropy=10
Mar 27 14:40:30 rpi3 wpa_supplicant[9252]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:30 rpi3 wpa_supplicant[9252]: random_mix_pool - hexdump(len=16): [REMOVED]
Mar 27 14:40:30 rpi3 wpa_supplicant[9252]: random_mix_pool - hexdump(len=6): [REMOVED]
Mar 27 14:40:30 rpi3 wpa_supplicant[9252]: random pool - hexdump(len=128): [REMOVED]
Mar 27 14:40:30 rpi3 wpa_supplicant[9252]: bwfm0: Associated with cc:2d:e0:ea:16:14
Mar 27 14:40:30 rpi3 wpa_supplicant[9252]: random from os_get_random - hexdump(len=32): [REMOVED]
Mar 27 14:40:30 rpi3 wpa_supplicant[9252]: random_mix_pool - hexdump(len=20): [REMOVED]
Mar 27 14:40:30 rpi3 wpa_supplicant[9252]: random from internal pool - hexdump(len=16): [REMOVED]
Mar 27 14:40:30 rpi3 wpa_supplicant[9252]: random_mix_pool - hexdump(len=20): [REMOVED]
Mar 27 14:40:30 rpi3 wpa_supplicant[9252]: random from internal pool - hexdump(len=16): [REMOVED]
Mar 27 14:40:30 rpi3 wpa_supplicant[9252]: mixed random - hexdump(len=32): [REMOVED]
Mar 27 14:40:30 rpi3 wpa_supplicant[9252]: bwfm0: WPA: Key negotiation completed with cc:2d:e0:ea:16:14 [PTK=CCMP GTK=CCMP]
Mar 27 14:40:30 rpi3 wpa_supplicant[9252]: bwfm0: CTRL-EVENT-CONNECTED - Connection to cc:2d:e0:ea:16:14 completed [id=0 id_str=]
Mar 27 14:40:31 rpi3 dhcpcd[718]: bwfm0: adding route to 169.254.0.0/16
Mar 27 14:40:31 rpi3 dhcpcd[718]: bwfm0: adding default route
Mar 27 14:40:32 rpi3 dhcpcd[718]: bwfm0: no IPv6 Routers available
Mar 27 14:40:32 rpi3 ntpd[1726]: Listen normally on 13 bwfm0 169.254.44.53:123
Mar 27 14:40:33 rpi3 dhcpcd[718]: bwfm0: offered 192.168.2.212 from 192.168.2.1
Mar 27 14:40:39 rpi3 dhcpcd[718]: bwfm0: leased 192.168.2.212 for 28800 seconds
Mar 27 14:40:39 rpi3 dhcpcd[718]: bwfm0: adding route to 192.168.2.0/24
Mar 27 14:40:39 rpi3 dhcpcd[718]: bwfm0: changing default route via 192.168.2.1
Mar 27 14:40:40 rpi3 dhcpcd[718]: bwfm0: deleting route to 169.254.0.0/16
Mar 27 14:40:41 rpi3 ntpd[1726]: Listen normally on 14 bwfm0 192.168.2.212:123
Mar 27 14:40:41 rpi3 ntpd[1726]: Deleting interface #13 bwfm0, 169.254.44.53#123, interface stats: received=0, sent=7, dropped=0, active_time=9 secs
Mar 27 14:41:25 rpi3 dhcpcd[718]: bwfm0: carrier lost - roaming
Mar 27 14:41:25 rpi3 wpa_supplicant[9252]: bwfm0: CTRL-EVENT-DISCONNECTED bssid=cc:2d:e0:ea:16:14 reason=3 locally_generated=1
Mar 27 14:41:25 rpi3 wpa_supplicant[9252]: ioctl[SIOCS80211, op=20, val=0, arg_len=7]: Can't assign requested address
Mar 27 14:41:25 rpi3 wpa_supplicant[9252]: bwfm0: CTRL-EVENT-TERMINATING 
Mar 27 14:41:25 rpi3 wpa_supplicant[10300]: Successfully initialized wpa_supplicant
Mar 27 14:41:26 rpi3 wpa_supplicant[9562]: bwfm0: CTRL-EVENT-TERMINATING 
Mar 27 14:41:26 rpi3 ntpd[1726]: Deleting interface #12 bwfm0, fe80::95e3:844:1fc:eccd%3#123, interface stats: received=0, sent=0, dropped=0, active_time=65 secs
Mar 27 14:41:26 rpi3 ntpd[1726]: Deleting interface #14 bwfm0, 192.168.2.212#123, interface stats: received=42, sent=42, dropped=0, active_time=45 secs
Mar 27 14:41:27 rpi3 wpa_supplicant[10684]: Successfully initialized wpa_supplicant
Mar 27 14:41:35 rpi3 wpa_supplicant[8615]: bwfm0: Trying to associate with cc:2d:e0:ea:16:14 (SSID='bat cave' freq=2457 MHz)
Mar 27 14:41:40 rpi3 dhcpcd[718]: bwfm0: carrier acquired
Mar 27 14:41:40 rpi3 wpa_supplicant[8615]: bwfm0: Associated with cc:2d:e0:ea:16:14
Mar 27 14:41:40 rpi3 dhcpcd[718]: bwfm0: connected to Access Point: bat cave
Mar 27 14:41:40 rpi3 dhcpcd[718]: bwfm0: deleting address fe80::95e3:844:1fc:eccd
Mar 27 14:41:40 rpi3 dhcpcd[718]: bwfm0: deleting route to 192.168.2.0/24
Mar 27 14:41:40 rpi3 dhcpcd[718]: bwfm0: deleting default route via 192.168.2.1
Mar 27 14:41:40 rpi3 wpa_supplicant[8615]: bwfm0: WPA: Key negotiation completed with cc:2d:e0:ea:16:14 [PTK=CCMP GTK=CCMP]
Mar 27 14:41:40 rpi3 wpa_supplicant[8615]: bwfm0: CTRL-EVENT-CONNECTED - Connection to cc:2d:e0:ea:16:14 completed [id=0 id_str=]
Mar 27 14:41:41 rpi3 dhcpcd[718]: bwfm0: IAID eb:e4:30:c8
Mar 27 14:41:41 rpi3 dhcpcd[718]: bwfm0: adding address fe80::e739:9d49:e1e3:c527
Mar 27 14:41:41 rpi3 dhcpcd[718]: bwfm0: soliciting an IPv6 router
Mar 27 14:41:42 rpi3 dhcpcd[718]: bwfm0: rebinding lease of 192.168.2.212
Mar 27 14:41:43 rpi3 ntpd[1726]: Listen normally on 15 bwfm0 [fe80::e739:9d49:e1e3:c527%3]:123
Mar 27 14:41:49 rpi3 dhcpcd[718]: bwfm0: leased 192.168.2.212 for 28800 seconds
Mar 27 14:41:49 rpi3 dhcpcd[718]: bwfm0: adding route to 192.168.2.0/24
Mar 27 14:41:49 rpi3 dhcpcd[718]: bwfm0: adding default route via 192.168.2.1
Mar 27 14:41:50 rpi3 ntpd[1726]: Listen normally on 16 bwfm0 192.168.2.212:123
Mar 27 14:41:54 rpi3 dhcpcd[718]: bwfm0: no IPv6 Routers available
Mar 27 14:42:31 rpi3 dhcpcd[718]: bwfm0: carrier lost - roaming
Mar 27 14:42:31 rpi3 wpa_supplicant[8615]: bwfm0: CTRL-EVENT-DISCONNECTED bssid=cc:2d:e0:ea:16:14 reason=3 locally_generated=1
Mar 27 14:42:31 rpi3 wpa_supplicant[8615]: ioctl[SIOCS80211, op=20, val=0, arg_len=7]: Can't assign requested address
Mar 27 14:42:31 rpi3 wpa_supplicant[8615]: bwfm0: CTRL-EVENT-TERMINATING 
Mar 27 14:42:32 rpi3 ntpd[1726]: Deleting interface #15 bwfm0, fe80::e739:9d49:e1e3:c527%3#123, interface stats: received=0, sent=0, dropped=0, active_time=49 secs
Mar 27 14:42:32 rpi3 ntpd[1726]: Deleting interface #16 bwfm0, 192.168.2.212#123, interface stats: received=16, sent=19, dropped=0, active_time=42 secs
Mar 27 14:42:34 rpi3 wpa_supplicant[12402]: Successfully initialized wpa_supplicant
Mar 27 14:42:38 rpi3 wpa_supplicant[13371]: bwfm0: Trying to associate with cc:2d:e0:ea:16:14 (SSID='bat cave' freq=2462 MHz)
Mar 27 14:42:43 rpi3 dhcpcd[718]: bwfm0: carrier acquired
Mar 27 14:42:43 rpi3 wpa_supplicant[13371]: bwfm0: Associated with cc:2d:e0:ea:16:14
Mar 27 14:42:43 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Key negotiation completed with cc:2d:e0:ea:16:14 [PTK=CCMP GTK=CCMP]
Mar 27 14:42:43 rpi3 wpa_supplicant[13371]: bwfm0: CTRL-EVENT-CONNECTED - Connection to cc:2d:e0:ea:16:14 completed [id=0 id_str=]
Mar 27 14:42:43 rpi3 dhcpcd[718]: bwfm0: IAID eb:e4:30:c8
Mar 27 14:42:44 rpi3 dhcpcd[718]: bwfm0: soliciting an IPv6 router
Mar 27 14:42:45 rpi3 dhcpcd[718]: bwfm0: rebinding lease of 192.168.2.212
Mar 27 14:42:45 rpi3 dhcpcd[718]: bwfm0: waiting for DAD on 192.168.2.212
Mar 27 14:42:46 rpi3 ntpd[1726]: Listen normally on 17 bwfm0 [fe80::e739:9d49:e1e3:c527%3]:123
Mar 27 14:42:48 rpi3 dhcpcd[718]: bwfm0: leased 192.168.2.212 for 28800 seconds
Mar 27 14:42:51 rpi3 ntpd[1726]: Listen normally on 18 bwfm0 192.168.2.212:123
Mar 27 14:42:57 rpi3 dhcpcd[718]: bwfm0: no IPv6 Routers available
Mar 27 14:43:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 14:48:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 14:53:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 14:58:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 15:03:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 15:08:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 15:13:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 15:03:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 15:03:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 16:13:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 16:18:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 16:23:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 16:28:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 16:33:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 16:38:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 16:28:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 16:28:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 16:53:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 16:58:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 17:03:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 16:53:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 16:53:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 17:35:38 rpi3 dhcpcd[718]: bwfm0: carrier lost - roaming
Mar 27 17:35:38 rpi3 wpa_supplicant[13371]: bwfm0: CTRL-EVENT-DISCONNECTED bssid=cc:2d:e0:ea:16:14 reason=0
Mar 27 17:35:39 rpi3 ntpd[1726]: Deleting interface #17 bwfm0, fe80::e739:9d49:e1e3:c527%3#123, interface stats: received=0, sent=0, dropped=0, active_time=10373 secs
Mar 27 17:35:39 rpi3 ntpd[1726]: Deleting interface #18 bwfm0, 192.168.2.212#123, interface stats: received=830, sent=839, dropped=0, active_time=10368 secs
Mar 27 17:35:47 rpi3 wpa_supplicant[13371]: bwfm0: Trying to associate with cc:2d:e0:ea:16:13 (SSID='bat cave' freq=2422 MHz)
Mar 27 17:35:52 rpi3 dhcpcd[718]: bwfm0: carrier acquired
Mar 27 17:35:52 rpi3 wpa_supplicant[13371]: bwfm0: Associated with cc:2d:e0:ea:16:13
Mar 27 17:35:52 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Key negotiation completed with cc:2d:e0:ea:16:13 [PTK=CCMP GTK=CCMP]
Mar 27 17:35:52 rpi3 wpa_supplicant[13371]: bwfm0: CTRL-EVENT-CONNECTED - Connection to cc:2d:e0:ea:16:13 completed [id=0 id_str=]
Mar 27 17:35:52 rpi3 dhcpcd[718]: bwfm0: IAID eb:e4:30:c8
Mar 27 17:35:52 rpi3 dhcpcd[718]: bwfm0: soliciting an IPv6 router
Mar 27 17:35:53 rpi3 dhcpcd[718]: bwfm0: rebinding lease of 192.168.2.212
Mar 27 17:35:53 rpi3 dhcpcd[718]: bwfm0: waiting for DAD on 192.168.2.212
Mar 27 17:35:55 rpi3 ntpd[1726]: Listen normally on 19 bwfm0 [fe80::e739:9d49:e1e3:c527%3]:123
Mar 27 17:35:57 rpi3 dhcpcd[718]: bwfm0: leased 192.168.2.212 for 28800 seconds
Mar 27 17:36:05 rpi3 dhcpcd[718]: bwfm0: no IPv6 Routers available
Mar 27 17:38:41 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 27 18:51:22 rpi3 wpa_supplicant[13371]: bwfm0: CTRL-EVENT-DISCONNECTED bssid=cc:2d:e0:ea:16:13 reason=3 locally_generated=1
Mar 27 18:51:22 rpi3 dhcpcd[718]: bwfm0: carrier lost
Mar 27 18:51:23 rpi3 ntpd[1726]: Deleting interface #19 bwfm0, fe80::e739:9d49:e1e3:c527%3#123, interface stats: received=0, sent=0, dropped=0, active_time=4504 secs
Mar 27 18:51:32 rpi3 dhcpcd[718]: bwfm0: deleting address fe80::e739:9d49:e1e3:c527
Mar 27 18:51:32 rpi3 dhcpcd[718]: bwfm0: deleting route to 192.168.2.0/24
Mar 27 18:51:32 rpi3 dhcpcd[718]: bwfm0: deleting default route via 192.168.2.1
Mar 27 18:51:42 rpi3 dhcpcd[718]: bwfm0: carrier lost - roaming
Mar 27 18:51:45 rpi3 wpa_supplicant[13371]: bwfm0: Trying to associate with cc:2d:e0:ea:16:14 (SSID='bat cave' freq=2432 MHz)
Mar 27 18:51:45 rpi3 wpa_supplicant[13371]: ioctl[SIOCS80211, op=20, val=0, arg_len=7]: Can't assign requested address
Mar 27 18:51:49 rpi3 dhcpcd[718]: bwfm0: carrier acquired
Mar 27 18:51:49 rpi3 wpa_supplicant[13371]: bwfm0: Associated with cc:2d:e0:ea:16:14
Mar 27 18:51:49 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Key negotiation completed with cc:2d:e0:ea:16:14 [PTK=CCMP GTK=CCMP]
Mar 27 18:51:49 rpi3 wpa_supplicant[13371]: bwfm0: CTRL-EVENT-CONNECTED - Connection to cc:2d:e0:ea:16:14 completed [id=0 id_str=]
Mar 27 18:51:49 rpi3 dhcpcd[718]: bwfm0: IAID eb:e4:30:c8
Mar 27 18:51:49 rpi3 dhcpcd[718]: bwfm0: adding address fe80::e739:9d49:e1e3:c527
Mar 27 18:51:50 rpi3 dhcpcd[718]: bwfm0: soliciting an IPv6 router
Mar 27 18:51:50 rpi3 dhcpcd[718]: bwfm0: rebinding lease of 192.168.2.212
Mar 27 18:51:51 rpi3 ntpd[1726]: Listen normally on 20 bwfm0 [fe80::e739:9d49:e1e3:c527%3]:123
Mar 27 18:51:56 rpi3 dhcpcd[718]: bwfm0: leased 192.168.2.212 for 28800 seconds
Mar 27 18:51:56 rpi3 dhcpcd[718]: bwfm0: adding route to 192.168.2.0/24
Mar 27 18:51:56 rpi3 dhcpcd[718]: bwfm0: adding default route via 192.168.2.1
Mar 27 18:51:58 rpi3 ntpd[1726]: Listen normally on 21 bwfm0 192.168.2.212:123
Mar 27 18:52:02 rpi3 dhcpcd[718]: bwfm0: no IPv6 Routers available
Mar 27 18:53:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 18:58:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 19:03:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 18:53:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 27 21:20:06 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:23:53 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:25:27 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:26:07 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:27:14 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:28:20 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:28:45 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:29:24 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:30:28 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:31:34 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:32:36 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:33:46 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:34:47 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:35:53 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:37:00 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:38:04 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:39:11 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:40:15 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:41:22 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:42:26 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:43:31 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:44:37 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:45:43 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:46:49 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:47:56 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:49:21 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:50:26 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:51:32 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:51:56 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:52:01 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:52:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:52:20 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:52:25 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:52:57 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:53:21 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:54:03 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:54:26 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:55:03 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:55:30 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:56:07 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:56:37 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:57:12 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:57:44 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:58:18 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:58:51 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:59:18 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 22:59:56 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:00:22 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:01:03 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:01:26 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:02:07 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:02:32 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:03:13 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:03:34 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:04:20 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:04:38 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:05:27 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:05:43 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:06:33 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:06:47 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:07:44 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:07:53 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:08:45 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:08:59 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:09:49 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:09:59 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:10:54 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:11:04 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:12:01 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:12:08 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:13:07 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:13:13 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:14:14 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:14:14 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:14:19 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:15:19 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:16:23 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:16:24 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:17:28 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:17:29 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:18:34 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:18:34 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:18:36 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:19:40 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:19:43 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:20:41 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:20:50 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:21:44 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:21:56 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:22:48 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:23:00 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:23:54 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:24:05 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:24:55 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:25:12 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:26:00 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:26:16 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:27:04 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:27:23 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:28:09 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:28:28 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:29:15 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:29:33 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:30:15 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:30:40 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:31:19 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:31:45 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:32:23 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:32:51 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:33:29 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:33:57 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:34:31 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:35:03 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:35:34 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:36:09 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:36:38 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:37:15 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:37:44 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:38:21 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:38:44 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:39:27 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:39:47 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:40:33 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:40:52 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:41:39 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:41:54 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:42:45 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:42:58 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:43:51 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:44:02 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:44:57 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:45:07 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:45:59 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:46:13 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:47:05 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:47:13 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:48:15 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:48:16 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:49:16 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:49:16 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:49:21 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:50:22 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:50:22 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:50:27 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:51:27 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:51:28 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:52:31 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:52:34 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:53:36 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:53:39 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:54:42 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:54:46 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:55:42 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:55:52 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:56:47 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:56:56 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:57:51 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:58:00 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:58:56 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 27 23:59:06 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:00:02 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:00:12 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:01:08 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:01:18 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:02:08 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:02:24 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:03:12 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:03:30 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:04:17 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:04:36 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:05:23 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:05:42 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:06:24 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:06:48 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:07:29 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:07:54 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:08:33 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:08:59 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:09:38 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:10:05 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:10:43 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:11:11 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:11:49 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:12:18 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:12:55 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:13:23 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:13:56 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:14:29 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:15:01 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:15:35 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:16:05 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:16:42 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:17:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:17:42 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:18:16 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:18:47 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:19:15 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:19:51 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:20:19 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:20:56 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:21:24 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:22:02 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:22:30 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:23:08 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:23:32 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:24:14 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:24:36 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:25:20 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:25:40 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:26:26 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:26:45 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:27:32 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:27:51 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:28:38 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:28:52 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:29:44 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:29:57 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:30:50 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:31:00 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:31:56 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:32:05 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:32:56 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:33:11 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:34:00 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:34:13 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:35:05 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:35:17 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:36:10 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:36:22 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:37:16 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:37:26 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:38:22 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:38:32 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:39:28 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:39:37 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:40:34 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:40:38 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:41:40 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:41:40 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:41:42 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:42:46 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:43:52 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:44:54 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:44:58 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:45:58 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:45:59 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:47:03 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:48:27 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:49:32 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:50:19 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:51:25 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:52:27 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:53:30 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:55:02 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:55:34 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:55:43 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:56:38 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:56:47 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:57:42 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:57:51 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:58:48 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:58:57 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 00:59:54 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 01:00:03 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 01:00:55 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 01:01:09 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 01:02:00 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 01:02:16 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 01:03:03 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 01:03:21 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 01:04:09 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 01:04:27 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 01:51:56 rpi3 dhcpcd[718]: bwfm0: failed to renew DHCP, rebinding
Mar 28 02:51:56 rpi3 dhcpcd[718]: bwfm0: DHCP lease expired
Mar 28 02:51:56 rpi3 dhcpcd[718]: bwfm0: deleting route to 192.168.2.0/24
Mar 28 02:51:56 rpi3 dhcpcd[718]: bwfm0: deleting default route via 192.168.2.1
Mar 28 02:51:56 rpi3 dhcpcd[718]: bwfm0: soliciting a DHCP lease
Mar 28 02:51:57 rpi3 ntpd[1726]: Deleting interface #21 bwfm0, 192.168.2.212#123, interface stats: received=367, sent=421, dropped=204, active_time=28775 secs
Mar 28 02:52:01 rpi3 dhcpcd[718]: bwfm0: probing for an IPv4LL address
Mar 28 02:52:01 rpi3 dhcpcd[718]: bwfm0: using IPv4LL address 169.254.44.53
Mar 28 02:52:07 rpi3 dhcpcd[718]: bwfm0: adding route to 169.254.0.0/16
Mar 28 02:52:07 rpi3 dhcpcd[718]: bwfm0: adding default route
Mar 28 02:52:08 rpi3 ntpd[1726]: Listen normally on 22 bwfm0 169.254.44.53:123
Mar 28 08:40:29 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 08:40:54 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 08:42:00 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 08:43:06 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 08:44:12 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 08:45:18 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 08:46:24 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 08:47:30 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 08:48:36 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 08:49:41 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 08:50:47 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 08:51:53 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 08:52:59 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 08:54:05 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 08:55:11 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 08:56:17 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 08:57:23 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 08:58:29 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 08:59:35 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:00:41 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:01:42 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:02:48 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:03:53 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:04:57 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:06:04 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:07:11 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:08:16 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:09:21 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:10:28 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:11:32 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:12:39 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:13:44 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:14:50 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:15:57 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:17:01 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:18:07 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:19:13 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:20:19 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:21:25 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:22:30 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:23:37 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:24:42 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:25:48 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:26:54 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:28:00 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:29:02 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:30:08 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:31:14 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:32:24 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:33:30 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:34:43 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:35:40 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:36:46 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:37:51 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:38:52 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:38:52 rpi3 wpa_supplicant[13371]: bwfm0: WPA: EAPOL-Key Replay Counter did not increase - dropping packet
Mar 28 09:38:55 rpi3 wpa_supplicant[13371]: bwfm0: CTRL-EVENT-DISCONNECTED bssid=cc:2d:e0:ea:16:14 reason=3 locally_generated=1
Mar 28 09:38:55 rpi3 wpa_supplicant[13371]: ioctl[SIOCS80211, op=20, val=0, arg_len=7]: Can't assign requested address
Mar 28 09:38:55 rpi3 wpa_supplicant[13371]: bwfm0: CTRL-EVENT-TERMINATING 
Mar 28 09:38:55 rpi3 wpa_supplicant[19117]: Successfully initialized wpa_supplicant
Mar 28 09:38:59 rpi3 wpa_supplicant[20338]: bwfm0: Trying to associate with cc:2d:e0:ea:16:14 (SSID='bat cave' freq=2447 MHz)
Mar 28 09:39:04 rpi3 wpa_supplicant[20338]: bwfm0: Associated with cc:2d:e0:ea:16:14
Mar 28 09:39:04 rpi3 wpa_supplicant[20338]: bwfm0: WPA: Key negotiation completed with cc:2d:e0:ea:16:14 [PTK=CCMP GTK=CCMP]
Mar 28 09:39:04 rpi3 wpa_supplicant[20338]: bwfm0: CTRL-EVENT-CONNECTED - Connection to cc:2d:e0:ea:16:14 completed [id=0 id_str=]
Mar 28 09:39:17 rpi3 dhcpcd[718]: bwfm0: removing interface
Mar 28 09:39:17 rpi3 dhcpcd[27435]: bwfm0: connected to Access Point: bat cave
Mar 28 09:39:17 rpi3 dhcpcd[27435]: bwfm0: IAID eb:e4:30:c8
Mar 28 09:39:18 rpi3 dhcpcd[27435]: bwfm0: soliciting an IPv6 router
Mar 28 09:39:19 rpi3 dhcpcd[27435]: bwfm0: soliciting a DHCP lease
Mar 28 09:39:20 rpi3 dhcpcd[27435]: bwfm0: offered 192.168.2.212 from 192.168.2.1
Mar 28 09:39:29 rpi3 /netbsd: [   1.6728296] bwfm0 at sdmmc1 function 1
Mar 28 09:39:29 rpi3 /netbsd: [   3.3436458] bwfm0: Firmware file default:    brcmfmac43455-sdio.bin
Mar 28 09:39:29 rpi3 /netbsd: [   3.3436458] bwfm0: Firmware file model-spec: brcmfmac43455-sdio.raspberrypi,3-model-b-plus.bin
Mar 28 09:39:29 rpi3 /netbsd: [   3.3918233] bwfm0: Found Firmware file: brcmfmac43455-sdio.bin
Mar 28 09:39:29 rpi3 /netbsd: [   3.4118260] bwfm0: NVRAM file default:    brcmfmac43455-sdio.txt
Mar 28 09:39:29 rpi3 /netbsd: [   3.4118260] bwfm0: NVRAM file model-spec: brcmfmac43455-sdio.raspberrypi,3-model-b-plus.txt
Mar 28 09:39:29 rpi3 /netbsd: [   3.4218762] bwfm0: Found NVRAM file: brcmfmac43455-sdio.raspberrypi,3-model-b-plus.txt
Mar 28 09:39:29 rpi3 /netbsd: [   3.4218762] bwfm0: CLM file default:    brcmfmac43455-sdio.clm_blob
Mar 28 09:39:29 rpi3 /netbsd: [   3.4218762] bwfm0: CLM file model-spec: brcmfmac43455-sdio.raspberrypi,3-model-b-plus.clm_blob
Mar 28 09:39:29 rpi3 /netbsd: [   5.1520252] bwfm0: CHIPACTIVE
Mar 28 09:39:29 rpi3 /netbsd: [   5.2520383] bwfm0: address b8:27:eb:e4:30:c8
Mar 28 09:39:29 rpi3 /netbsd: [   5.2520383] bwfm0: wl0: Mar  1 2015 07:29:38 version 7.45.18 (r538002) FWID 01-6a2c8ad4
Mar 28 09:39:28 rpi3 dhcpcd[718]: bwfm0: carrier lost - roaming
Mar 28 09:39:31 rpi3 wpa_supplicant[672]: bwfm0: Trying to associate with cc:2d:e0:ea:16:14 (SSID='bat cave' freq=2417 MHz)
Mar 28 09:39:36 rpi3 dhcpcd[718]: bwfm0: carrier acquired
Mar 28 09:39:36 rpi3 dhcpcd[718]: bwfm0: connected to Access Point: bat cave
Mar 28 09:39:36 rpi3 wpa_supplicant[672]: bwfm0: Associated with cc:2d:e0:ea:16:14
Mar 28 09:39:36 rpi3 wpa_supplicant[672]: bwfm0: WPA: Key negotiation completed with cc:2d:e0:ea:16:14 [PTK=CCMP GTK=CCMP]
Mar 28 09:39:36 rpi3 wpa_supplicant[672]: bwfm0: CTRL-EVENT-CONNECTED - Connection to cc:2d:e0:ea:16:14 completed [id=0 id_str=]
Mar 28 09:39:36 rpi3 dhcpcd[718]: bwfm0: IAID eb:e4:30:c8
Mar 28 09:39:36 rpi3 dhcpcd[718]: bwfm0: adding address fe80::e739:9d49:e1e3:c527
Mar 28 09:39:37 rpi3 dhcpcd[718]: bwfm0: soliciting an IPv6 router
Mar 28 09:39:37 rpi3 dhcpcd[718]: bwfm0: soliciting a DHCP lease
Mar 28 09:39:37 rpi3 dhcpcd[718]: bwfm0: offered 192.168.2.212 from 192.168.2.1
Mar 28 09:39:39 rpi3 ntpd[1700]: Listen normally on 5 bwfm0 [fe80::e739:9d49:e1e3:c527%3]:123
Mar 28 09:39:42 rpi3 dhcpcd[718]: bwfm0: leased 192.168.2.212 for 28800 seconds
Mar 28 09:39:42 rpi3 dhcpcd[718]: bwfm0: adding route to 192.168.2.0/24
Mar 28 09:39:42 rpi3 dhcpcd[718]: bwfm0: adding default route via 192.168.2.1
Mar 28 09:39:44 rpi3 ntpd[1700]: Listen normally on 6 bwfm0 192.168.2.212:123
Mar 28 09:39:49 rpi3 dhcpcd[718]: bwfm0: no IPv6 Routers available
Mar 28 09:40:22 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 09:45:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 09:50:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 09:55:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 09:45:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 09:45:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 13:17:58 rpi3 dhcpcd[718]: bwfm0: carrier lost - roaming
Mar 28 13:17:58 rpi3 wpa_supplicant[672]: bwfm0: CTRL-EVENT-DISCONNECTED bssid=cc:2d:e0:ea:16:14 reason=0
Mar 28 13:17:59 rpi3 ntpd[1700]: Deleting interface #5 bwfm0, fe80::e739:9d49:e1e3:c527%3#123, interface stats: received=0, sent=0, dropped=0, active_time=13084 secs
Mar 28 13:17:59 rpi3 ntpd[1700]: Deleting interface #6 bwfm0, 192.168.2.212#123, interface stats: received=776, sent=776, dropped=0, active_time=13079 secs
Mar 28 13:18:07 rpi3 wpa_supplicant[672]: bwfm0: Trying to associate with 64:d1:54:ee:0c:fc (SSID='bat cave' freq=2427 MHz)
Mar 28 13:18:12 rpi3 dhcpcd[718]: bwfm0: carrier acquired
Mar 28 13:18:12 rpi3 wpa_supplicant[672]: bwfm0: Associated with 64:d1:54:ee:0c:fc
Mar 28 13:18:12 rpi3 wpa_supplicant[672]: bwfm0: WPA: Key negotiation completed with 64:d1:54:ee:0c:fc [PTK=CCMP GTK=CCMP]
Mar 28 13:18:12 rpi3 wpa_supplicant[672]: bwfm0: CTRL-EVENT-CONNECTED - Connection to 64:d1:54:ee:0c:fc completed [id=0 id_str=]
Mar 28 13:18:12 rpi3 dhcpcd[718]: bwfm0: IAID eb:e4:30:c8
Mar 28 13:18:13 rpi3 dhcpcd[718]: bwfm0: soliciting an IPv6 router
Mar 28 13:18:13 rpi3 dhcpcd[718]: bwfm0: rebinding lease of 192.168.2.212
Mar 28 13:18:13 rpi3 dhcpcd[718]: bwfm0: waiting for DAD on 192.168.2.212
Mar 28 13:18:15 rpi3 ntpd[1700]: Listen normally on 7 bwfm0 [fe80::e739:9d49:e1e3:c527%3]:123
Mar 28 13:18:17 rpi3 dhcpcd[718]: bwfm0: leased 192.168.2.212 for 28800 seconds
Mar 28 13:18:26 rpi3 dhcpcd[718]: bwfm0: no IPv6 Routers available
Mar 28 13:20:34 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with 64:d1:54:ee:0c:fc [GTK=CCMP]
Mar 28 13:23:16 rpi3 dhcpcd[718]: bwfm0: carrier lost - roaming
Mar 28 13:23:16 rpi3 wpa_supplicant[672]: bwfm0: CTRL-EVENT-DISCONNECTED bssid=64:d1:54:ee:0c:fc reason=0
Mar 28 13:23:17 rpi3 ntpd[1700]: Deleting interface #7 bwfm0, fe80::e739:9d49:e1e3:c527%3#123, interface stats: received=0, sent=0, dropped=0, active_time=302 secs
Mar 28 13:23:24 rpi3 wpa_supplicant[672]: bwfm0: Trying to associate with cc:2d:e0:ea:16:14 (SSID='bat cave' freq=2437 MHz)
Mar 28 13:23:30 rpi3 dhcpcd[718]: bwfm0: carrier acquired
Mar 28 13:23:30 rpi3 wpa_supplicant[672]: bwfm0: Associated with cc:2d:e0:ea:16:14
Mar 28 13:23:30 rpi3 wpa_supplicant[672]: bwfm0: WPA: Key negotiation completed with cc:2d:e0:ea:16:14 [PTK=CCMP GTK=CCMP]
Mar 28 13:23:30 rpi3 wpa_supplicant[672]: bwfm0: CTRL-EVENT-CONNECTED - Connection to cc:2d:e0:ea:16:14 completed [id=0 id_str=]
Mar 28 13:23:30 rpi3 dhcpcd[718]: bwfm0: IAID eb:e4:30:c8
Mar 28 13:23:31 rpi3 dhcpcd[718]: bwfm0: soliciting an IPv6 router
Mar 28 13:23:31 rpi3 dhcpcd[718]: bwfm0: rebinding lease of 192.168.2.212
Mar 28 13:23:31 rpi3 dhcpcd[718]: bwfm0: waiting for DAD on 192.168.2.212
Mar 28 13:23:32 rpi3 ntpd[1700]: Listen normally on 8 bwfm0 [fe80::e739:9d49:e1e3:c527%3]:123
Mar 28 13:23:35 rpi3 dhcpcd[718]: bwfm0: leased 192.168.2.212 for 28800 seconds
Mar 28 13:23:43 rpi3 dhcpcd[718]: bwfm0: no IPv6 Routers available
Mar 28 13:25:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 13:30:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 13:35:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 13:40:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 13:45:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 13:50:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 13:55:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 14:00:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 14:05:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 14:10:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 14:15:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 14:20:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 14:25:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 14:30:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 14:35:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 14:40:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 14:45:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 14:50:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 14:55:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 15:00:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 15:05:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 15:10:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 15:15:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 15:20:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 15:25:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 15:30:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 15:35:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 15:40:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 15:45:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 15:50:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 15:55:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 16:00:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 16:05:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 16:10:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 16:15:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 16:20:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 16:25:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 16:30:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 16:35:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 16:40:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 16:45:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 16:50:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 16:55:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 17:00:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 17:05:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 17:10:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 17:15:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 17:20:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 17:25:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 17:30:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 17:35:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 17:40:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 17:45:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 17:50:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 17:55:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 18:00:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 18:05:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 18:10:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 18:15:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 18:20:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 18:25:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 18:30:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 18:33:19 rpi3 ntpd[1700]: Listen normally on 9 bwfm0 192.168.2.212:123
Mar 28 18:35:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 18:40:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 18:45:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 18:35:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 18:35:38 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:14 [GTK=CCMP]
Mar 28 20:45:53 rpi3 wpa_supplicant[672]: bwfm0: CTRL-EVENT-DISCONNECTED bssid=cc:2d:e0:ea:16:14 reason=0
Mar 28 20:45:53 rpi3 dhcpcd[718]: bwfm0: carrier lost - roaming
Mar 28 20:45:54 rpi3 ntpd[1700]: Deleting interface #8 bwfm0, fe80::e739:9d49:e1e3:c527%3#123, interface stats: received=0, sent=0, dropped=0, active_time=26542 secs
Mar 28 20:45:54 rpi3 ntpd[1700]: Deleting interface #9 bwfm0, 192.168.2.212#123, interface stats: received=508, sent=509, dropped=0, active_time=7955 secs
Mar 28 20:46:01 rpi3 wpa_supplicant[672]: bwfm0: Trying to associate with cc:2d:e0:ea:16:13 (SSID='bat cave' freq=2447 MHz)
Mar 28 20:46:06 rpi3 wpa_supplicant[672]: bwfm0: Associated with cc:2d:e0:ea:16:13
Mar 28 20:46:06 rpi3 dhcpcd[718]: bwfm0: carrier acquired
Mar 28 20:46:06 rpi3 wpa_supplicant[672]: bwfm0: WPA: Key negotiation completed with cc:2d:e0:ea:16:13 [PTK=CCMP GTK=CCMP]
Mar 28 20:46:06 rpi3 wpa_supplicant[672]: bwfm0: CTRL-EVENT-CONNECTED - Connection to cc:2d:e0:ea:16:13 completed [id=0 id_str=]
Mar 28 20:46:07 rpi3 dhcpcd[718]: bwfm0: IAID eb:e4:30:c8
Mar 28 20:46:07 rpi3 dhcpcd[718]: bwfm0: soliciting an IPv6 router
Mar 28 20:46:08 rpi3 dhcpcd[718]: bwfm0: rebinding lease of 192.168.2.212
Mar 28 20:46:08 rpi3 dhcpcd[718]: bwfm0: waiting for DAD on 192.168.2.212
Mar 28 20:46:09 rpi3 ntpd[1700]: Listen normally on 10 bwfm0 [fe80::e739:9d49:e1e3:c527%3]:123
Mar 28 20:46:12 rpi3 dhcpcd[718]: bwfm0: leased 192.168.2.212 for 28800 seconds
Mar 28 20:46:20 rpi3 dhcpcd[718]: bwfm0: no IPv6 Routers available
Mar 28 20:49:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 20:54:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 20:59:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 21:04:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 21:09:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 21:14:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 21:19:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 21:24:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 21:29:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 21:34:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 21:39:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 21:44:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 21:49:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 21:54:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 21:59:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 22:04:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 22:09:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 22:14:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 22:19:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 22:24:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 22:29:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 22:34:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 22:39:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 22:44:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 22:49:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 22:54:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 22:59:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 23:04:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 23:09:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 23:14:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 23:19:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 23:24:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 23:29:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 23:34:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 23:39:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 23:44:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 23:49:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 23:54:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 28 23:59:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 00:04:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 00:09:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 00:14:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 00:19:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 00:24:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 00:29:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 00:34:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 00:39:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 00:44:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 00:49:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 00:54:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 00:59:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 01:04:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 01:09:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 01:14:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 01:19:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 01:24:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 01:29:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 01:34:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 01:39:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 01:44:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 01:49:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 01:54:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 01:59:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 02:04:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 02:09:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 02:14:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 02:19:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 02:24:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 02:29:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 02:34:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 02:39:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 02:44:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 02:49:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 02:54:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 02:59:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 03:04:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 03:09:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 03:14:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 03:19:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 03:24:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 03:29:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 03:34:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 03:39:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 03:44:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]
Mar 29 03:49:52 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying completed with cc:2d:e0:ea:16:13 [GTK=CCMP]

>How-To-Repeat:
Instal evbarm aarch64 image for 10.0_BETA on Raspberry Pi 3. Connect to WPA2-PSK secured wireless network. Wait.
>Fix:
Workaround: login over serial console/keyboard and restart wpa_supplicant.

>Audit-Trail:
From: mlelstv@serpens.de (Michael van Elst)
To: gnats-bugs@netbsd.org
Cc: 
Subject: Re: port-evbarm/57308: Raspberry Pi 3 bwfm0 with wpa_supplicant stops working after few hours
Date: Wed, 29 Mar 2023 08:52:24 -0000 (UTC)

 bbartlomiej.mail@gmail.com writes:

 >What helps is to restart wpa_supplicant and sometimes dhcpcd if it doesn't react to this action automatically afterwards.

 Can you configure dhcpcd to ignore link states ?

 E.g. in /etc/dhcpcd.conf append:

 interface bwfm0
         nolink


From: Bartek Krawczyk <bbartlomiej.mail@gmail.com>
To: gnats-bugs@netbsd.org, port-evbarm-maintainer@netbsd.org,
 gnats-admin@netbsd.org, netbsd-bugs@netbsd.org
Cc: 
Subject: Re: port-evbarm/57308: Raspberry Pi 3 bwfm0 with wpa_supplicant stops
 working after few hours
Date: Wed, 29 Mar 2023 11:23:32 +0200

 On 29/03/2023 10:55, Michael van Elst wrote:
 > The following reply was made to PR port-evbarm/57308; it has been noted by GNATS.
 > 
 > From: mlelstv@serpens.de (Michael van Elst)
 > To: gnats-bugs@netbsd.org
 > Cc:
 > Subject: Re: port-evbarm/57308: Raspberry Pi 3 bwfm0 with wpa_supplicant stops working after few hours
 > Date: Wed, 29 Mar 2023 08:52:24 -0000 (UTC)
 > 
 >   bbartlomiej.mail@gmail.com writes:
 >   
 >   >What helps is to restart wpa_supplicant and sometimes dhcpcd if it doesn't react to this action automatically afterwards.
 >   
 >   Can you configure dhcpcd to ignore link states ?
 >   
 >   E.g. in /etc/dhcpcd.conf append:
 >   
 >   interface bwfm0
 >           nolink
 >   
 >   

 I've added that now. I understand it should help with reacquiring a 
 lease after I restart wpa_supplicant? Because during my outage the IP is 
 still assigned on the interface and interface is UP with "status: active".

 -- 
 Regards
 Bartłomiej Krawczyk

From: Bartek Krawczyk <bbartlomiej.mail@gmail.com>
To: gnats-bugs@netbsd.org, port-evbarm-maintainer@netbsd.org,
 gnats-admin@netbsd.org, netbsd-bugs@netbsd.org
Cc: 
Subject: Re: port-evbarm/57308: Raspberry Pi 3 bwfm0 with wpa_supplicant stops
 working after few hours
Date: Thu, 30 Mar 2023 10:04:57 +0200

 On 29/03/2023 11:25, Bartek Krawczyk wrote:
 >   > From: mlelstv@serpens.de (Michael van Elst)
 >   > To: gnats-bugs@netbsd.org
 >   > Cc:
 >   > Subject: Re: port-evbarm/57308: Raspberry Pi 3 bwfm0 with wpa_supplicant stops working after few hours
 >   > Date: Wed, 29 Mar 2023 08:52:24 -0000 (UTC)
 >   >
 >   >   bbartlomiej.mail@gmail.com writes:
 >   >
 >   >   >What helps is to restart wpa_supplicant and sometimes dhcpcd if it doesn't react to this action automatically afterwards.
 >   >
 >   >   Can you configure dhcpcd to ignore link states ?
 >   >
 >   >   E.g. in /etc/dhcpcd.conf append:
 >   >
 >   >   interface bwfm0
 >   >           nolink
 >   >
 >   >
 >   
 >   I've added that now. I understand it should help with reacquiring a
 >   lease after I restart wpa_supplicant? Because during my outage the IP is
 >   still assigned on the interface and interface is UP with "status: active".

 As expected, after dhcpcd update from above suggestion there is no 
 improvement. Today I can't login to the PI3 and on the serial console I 
 still see the IP is there, and wpa_supplicant thinks group rekeying was 
 completed:

 rpi3# ifconfig bwfm0
 bwfm0: flags=0x8843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST> mtu 1500
          ssid "bat cave" nwkey 
 65536:"",0xbd4e13e244b89677f712e72b404160e8,0x024ad83364a031bc990d75a7cc978ffb,""
          powersave off
          bssid 64:d1:54:ee:0c:fc chan 11
          address: b8:27:eb:e4:30:c8
          media: IEEE802.11 autoselect (VHT mode 11ac)
          status: active
          inet6 fe80::e739:9d49:e1e3:c527%bwfm0/64 flags 0 scopeid 0x3
          inet 192.168.2.212/24 broadcast 192.168.2.255 flags 0

 /var/log/messages:

 Mar 30 07:05:35 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying 
 completed with 64:d1:54:ee:0c:fc [GTK=CCMP]
 Mar 30 07:10:35 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying 
 completed with 64:d1:54:ee:0c:fc [GTK=CCMP]
 Mar 30 07:15:35 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying 
 completed with 64:d1:54:ee:0c:fc [GTK=CCMP]
 Mar 30 07:05:35 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying 
 completed with 64:d1:54:ee:0c:fc [GTK=CCMP]
 Mar 30 07:35:17 rpi3 syslogd[951]: last message repeated 2 times
 Mar 30 07:45:17 rpi3 syslogd[951]: last message repeated 2 times


 rpi3# ping 192.168.2.1
 PING 192.168.2.1 (192.168.2.1): 56 data bytes
 ping: sendto: Host is down
 ping: sendto: Host is down
 ping: sendto: Host is down
 ping: sendto: Host is down
 ^C
 ----192.168.2.1 PING Statistics----
 4 packets transmitted, 0 packets received, 100.0% packet loss

 The PI3 is of course in the same spot, not moved, not touched.
 Restarting wpa_supplicant helps:

 rpi3# service wpa_supplicant restart
 Stopping wpa_supplicant.
 Starting wpa_supplicant.
 rpi3# ping 192.168.2.1
 PING 192.168.2.1 (192.168.2.1): 56 data bytes
 64 bytes from 192.168.2.1: icmp_seq=0 ttl=64 time=1.395308 ms
 64 bytes from 192.168.2.1: icmp_seq=1 ttl=64 time=1.597339 ms
 64 bytes from 192.168.2.1: icmp_seq=2 ttl=64 time=1.534996 ms
 64 bytes from 192.168.2.1: icmp_seq=3 ttl=64 time=1.751297 ms
 64 bytes from 192.168.2.1: icmp_seq=4 ttl=64 time=1.564996 ms
 ^C
 ----192.168.2.1 PING Statistics----
 5 packets transmitted, 5 packets received, 0.0% packet loss
 round-trip min/avg/max/stddev = 1.395308/1.568787/1.751297/0.127844 ms

 -- 
 Regards
 Bartłomiej Krawczyk

From: Bartek Krawczyk <bbartlomiej.mail@gmail.com>
To: gnats-bugs@netbsd.org, port-evbarm-maintainer@netbsd.org,
 gnats-admin@netbsd.org, netbsd-bugs@netbsd.org
Cc: 
Subject: Re: port-evbarm/57308: Raspberry Pi 3 bwfm0 with wpa_supplicant stops
 working after few hours
Date: Mon, 3 Apr 2023 18:55:53 +0200

 On 30/03/2023 10:10, Bartek Krawczyk wrote:
 > The following reply was made to PR port-evbarm/57308; it has been noted by GNATS.
 >   As expected, after dhcpcd update from above suggestion there is no
 >   improvement. Today I can't login to the PI3 and on the serial console I
 >   still see the IP is there, and wpa_supplicant thinks group rekeying was
 >   completed:
 >   
 >   rpi3# ifconfig bwfm0
 >   bwfm0: flags=0x8843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST> mtu 1500
 >            ssid "bat cave" nwkey
 >   65536:"",0xbd4e13e244b89677f712e72b404160e8,0x024ad83364a031bc990d75a7cc978ffb,""
 >            powersave off
 >            bssid 64:d1:54:ee:0c:fc chan 11
 >            address: b8:27:eb:e4:30:c8
 >            media: IEEE802.11 autoselect (VHT mode 11ac)
 >            status: active
 >            inet6 fe80::e739:9d49:e1e3:c527%bwfm0/64 flags 0 scopeid 0x3
 >            inet 192.168.2.212/24 broadcast 192.168.2.255 flags 0
 >   
 >   /var/log/messages:
 >   
 >   Mar 30 07:05:35 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying
 >   completed with 64:d1:54:ee:0c:fc [GTK=CCMP]
 >   Mar 30 07:10:35 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying
 >   completed with 64:d1:54:ee:0c:fc [GTK=CCMP]
 >   Mar 30 07:15:35 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying
 >   completed with 64:d1:54:ee:0c:fc [GTK=CCMP]
 >   Mar 30 07:05:35 rpi3 wpa_supplicant[672]: bwfm0: WPA: Group rekeying
 >   completed with 64:d1:54:ee:0c:fc [GTK=CCMP]
 >   Mar 30 07:35:17 rpi3 syslogd[951]: last message repeated 2 times
 >   Mar 30 07:45:17 rpi3 syslogd[951]: last message repeated 2 times
 >   
 >   
 >   rpi3# ping 192.168.2.1
 >   PING 192.168.2.1 (192.168.2.1): 56 data bytes
 >   ping: sendto: Host is down
 >   ping: sendto: Host is down
 >   ping: sendto: Host is down
 >   ping: sendto: Host is down
 >   ^C
 >   ----192.168.2.1 PING Statistics----
 >   4 packets transmitted, 0 packets received, 100.0% packet loss
 >   
 >   The PI3 is of course in the same spot, not moved, not touched.
 >   Restarting wpa_supplicant helps:
 >   
 >   rpi3# service wpa_supplicant restart
 >   Stopping wpa_supplicant.
 >   Starting wpa_supplicant.
 >   rpi3# ping 192.168.2.1
 >   PING 192.168.2.1 (192.168.2.1): 56 data bytes
 >   64 bytes from 192.168.2.1: icmp_seq=0 ttl=64 time=1.395308 ms
 >   64 bytes from 192.168.2.1: icmp_seq=1 ttl=64 time=1.597339 ms
 >   64 bytes from 192.168.2.1: icmp_seq=2 ttl=64 time=1.534996 ms
 >   64 bytes from 192.168.2.1: icmp_seq=3 ttl=64 time=1.751297 ms
 >   64 bytes from 192.168.2.1: icmp_seq=4 ttl=64 time=1.564996 ms
 >   ^C
 >   ----192.168.2.1 PING Statistics----
 >   5 packets transmitted, 5 packets received, 0.0% packet loss
 >   round-trip min/avg/max/stddev = 1.395308/1.568787/1.751297/0.127844 ms

 I tested it with -current from today (10.99.2, 03.04.2023) and the same 
 thing happened. Just the kernel.

 -- 
 Regards
 Bartłomiej Krawczyk

Home	PR Database Search