Date: Tue, 2 Sep 2025 11:21:58 +0200 From: ltning-freebsd-wireless@anduin.net To: wireless@freebsd.org Subject: Re: iwlwifi: Really hard to connect to networks Message-ID: <cbaa3e61-5685-403e-bc43-d2076e7a46ac@anduin.net> In-Reply-To: <p75559q8-r6q5-3864-o2s3-37840q5p8999@SerrOFQ.bet> References: <13f405a6-e73c-4872-86f3-f74e97dc0969@anduin.net> <n1708583-1pr4-r615-op8n-5413r65983o4@SerrOFQ.bet> <08d692e5-77a4-4503-8716-883563fe9863@anduin.net> <p75559q8-r6q5-3864-o2s3-37840q5p8999@SerrOFQ.bet>
index | next in thread | previous in thread | raw e-mail
[-- Attachment #1 --] On 9/1/25 04:31, Bjoern A. Zeeb wrote: > On Sat, 30 Aug 2025, ltning-freebsd-wireless@anduin.net wrote: > >>> Yes, I saw that too. If it happens again after the update wee need to >>> see. Could be from changing bands; I need to go and have a look about >>> that. If it was crypto related there's also a catch for that in main. >> >> Attached dmesg from boot right now. The interface did not come up >> properly until I did a "service netif restart wlan0" (not included in >> the dmesg). >> >> Gotta run now, can collect more debug info later.. Hope this helps, >> though! > > So two issues: > > (1) you see beacons yet the firmware says you are not... > > iwlwifi0: Not associated and the session protection is over already... > iwlwifi0: linuxkpi_ieee80211_connection_loss: vif 0xfffffe01e11f0f00 vap > 0xfffffe01e11f0010 state AUTH (synched 1, assoc 0 beacons 13 dtim_period 1) > wlan0: link state changed to UP > iwlwifi0: linuxkpi_ieee80211_beacon_loss: vif 0xfffffe01e11f0f00 vap > 0xfffffe01e11f0010 state RUN (synched 1, assoc 1 beacons 4 dtim_period 1) > wlan0: link state changed to DOWN > iwlwifi0: Not associated and the session protection is over already... > iwlwifi0: linuxkpi_ieee80211_connection_loss: vif 0xfffffe01e11f0f00 vap > 0xfffffe01e11f0010 state AUTH (synched 1, assoc 0 beacons 6 dtim_period 1) > > As you can see: > (a) in all cases LinuxKPI 802.11 thinks it is in synch with net8011 > (b) in all cases we have received beacons > (c) in the middle case we are still assoc (RUN state) as it is a > beacon loss reported by firmware/driver > (d) in all cases we do have a dtim period set. > > I wonder why in the AUTH state. LinuxKPI does reset it. > Seems like we should only update from net80211 if we get assoc > as net80211 will always have it set after scan. I'll prepare a > patch. > The reason is that (at least for iwlwifi) that change notification > will be checked to my memory and if it happend earlier there's no > more state change. > > > (2) firmware crash after this due to ampdu-rx; I thought that is > disabled but I have a further change to put in to help this; > > Hope to make both during Monday, so yes, your log helps! > Thanks a lot for updating and testing and reporting back. In case it's useful, here's logs from a cold boot (dead battery) - it needs a "service netif restart" before it gets a DHCP lease, despite claiming to be associated. And now I'm connected at 2.4GHz again. Should I git pull and rebuild? /Eirik [-- Attachment #2 --] Jan 1 01:00:42 f13 syslogd: restart Jan 1 01:00:42 f13 syslogd: kernel boot file is /boot/kernel/kernel Jan 1 01:00:42 f13 kernel: ---<<BOOT>>--- Jan 1 01:00:42 f13 kernel: Copyright (c) 1992-2025 The FreeBSD Project. Jan 1 01:00:42 f13 kernel: Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994 Jan 1 01:00:42 f13 kernel: The Regents of the University of California. All rights reserved. Jan 1 01:00:42 f13 kernel: FreeBSD is a registered trademark of The FreeBSD Foundation. Jan 1 01:00:42 f13 kernel: FreeBSD 15.0-PRERELEASE #24 main-04907571427d: Sat Aug 30 12:25:14 CEST 2025 Jan 1 01:00:42 f13 kernel: root@f13:/usr/obj/usr/src/amd64.amd64/sys/GENERIC-NODEBUG amd64 Jan 1 01:00:42 f13 kernel: FreeBSD clang version 19.1.7 (https://github.com/llvm/llvm-project.git llvmorg-19.1.7-0-gcd708029e0b2) Jan 1 01:00:42 f13 kernel: VT(efifb): resolution 2256x1504 Jan 1 01:00:42 f13 kernel: CPU: 13th Gen Intel(R) Core(TM) i7-1370P (2188.80-MHz K8-class CPU) Jan 1 01:00:42 f13 kernel: Origin="GenuineIntel" Id=0xb06a2 Family=0x6 Model=0xba Stepping=2 Jan 1 01:00:42 f13 kernel: Features=0xbfebfbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CLFLUSH,DTS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE> Jan 1 01:00:42 f13 kernel: Features2=0x7ffafbff<SSE3,PCLMULQDQ,DTES64,MON,DS_CPL,VMX,SMX,EST,TM2,SSSE3,SDBG,FMA,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,x2APIC,MOVBE,POPCNT,TSCDLT,AESNI,XSAVE,OSXSAVE,AVX,F16C,RDRAND> Jan 1 01:00:42 f13 kernel: AMD Features=0x2c100800<SYSCALL,NX,Page1GB,RDTSCP,LM> Jan 1 01:00:42 f13 kernel: AMD Features2=0x121<LAHF,ABM,Prefetch> Jan 1 01:00:42 f13 kernel: Structured Extended Features=0x239c27eb<FSGSBASE,TSCADJ,BMI1,AVX2,FDPEXC,SMEP,BMI2,ERMS,INVPCID,NFPUSG,RDSEED,ADX,SMAP,CLFLUSHOPT,CLWB,PROCTRACE,SHA> Jan 1 01:00:42 f13 kernel: Structured Extended Features2=0x98c027bc<UMIP,PKU,OSPKE,WAITPKG,GFNI,VAES,VPCLMULQDQ,TME,RDPID,MOVDIRI,MOVDIR64B> Jan 1 01:00:42 f13 kernel: Structured Extended Features3=0xfc1cc410<FSRM,MD_CLEAR,PCONFIG,IBT,IBPB,STIBP,L1DFL,ARCH_CAP,CORE_CAP,SSBD> Jan 1 01:00:42 f13 kernel: XSAVE Features=0xf<XSAVEOPT,XSAVEC,XINUSE,XSAVES> Jan 1 01:00:42 f13 kernel: IA32_ARCH_CAPS=0x1488fd6b<RDCL_NO,IBRS_ALL,SKIP_L1DFL_VME,MDS_NO,TAA_NO> Jan 1 01:00:42 f13 kernel: VT-x: PAT,HLT,MTF,PAUSE,EPT,UG,VPID,VID,PostIntr Jan 1 01:00:42 f13 kernel: TSC: P-state invariant, performance statistics Jan 1 01:00:42 f13 kernel: real memory = 68717379584 (65534 MB) Jan 1 01:00:42 f13 kernel: avail memory = 64608698368 (61615 MB) Jan 1 01:00:42 f13 kernel: Event timer "LAPIC" quality 600 Jan 1 01:00:42 f13 kernel: ACPI APIC Table: <INSYDE ADL-P-M> Jan 1 01:00:42 f13 kernel: FreeBSD/SMP: Multiprocessor System Detected: 20 CPUs Jan 1 01:00:42 f13 kernel: FreeBSD/SMP: Non-uniform topology Jan 1 01:00:42 f13 kernel: random: registering fast source Intel Secure Key RNG Jan 1 01:00:42 f13 kernel: random: fast provider: "Intel Secure Key RNG" Jan 1 01:00:42 f13 kernel: random: unblocking device. Jan 1 01:00:42 f13 kernel: ioapic0 <Version 2.0> irqs 0-119 Jan 1 01:00:42 f13 kernel: Launching APs: 1 19 4 7 14 6 17 13 15 3 18 2 9 11 8 5 12 16 10 Jan 1 01:00:42 f13 kernel: random: entropy device external interface Jan 1 01:00:42 f13 kernel: kbd1 at kbdmux0 Jan 1 01:00:42 f13 kernel: efirtc0: <EFI Realtime Clock> Jan 1 01:00:42 f13 kernel: efirtc0: registered as a time-of-day clock, resolution 1.000000s Jan 1 01:00:42 f13 kernel: smbios0: <System Management BIOS> at iomem 0x3efda000-0x3efda01e Jan 1 01:00:42 f13 kernel: smbios0: Entry point: v2.1 (32-bit), Version: 3.4, BCD Revision: 3.4 Jan 1 01:00:42 f13 kernel: aesni0: <AES-CBC,AES-CCM,AES-GCM,AES-ICM,AES-XTS,SHA1,SHA256> Jan 1 01:00:42 f13 kernel: acpi0: <INSYDE ADL-P-M> Jan 1 01:00:42 f13 kernel: acpi_ec1: <Embedded Controller: GPE 0x6e, ECDT> port 0x62,0x66 on acpi0 Jan 1 01:00:42 f13 kernel: hpet0: <High Precision Event Timer> iomem 0xfed00000-0xfed003ff on acpi0 Jan 1 01:00:42 f13 kernel: Timecounter "HPET" frequency 19200000 Hz quality 950 Jan 1 01:00:42 f13 kernel: Event timer "HPET" frequency 19200000 Hz quality 350 Jan 1 01:00:42 f13 kernel: Event timer "HPET1" frequency 19200000 Hz quality 340 Jan 1 01:00:42 f13 kernel: Event timer "HPET2" frequency 19200000 Hz quality 340 Jan 1 01:00:42 f13 kernel: Event timer "HPET3" frequency 19200000 Hz quality 340 Jan 1 01:00:42 f13 kernel: Event timer "HPET4" frequency 19200000 Hz quality 340 Jan 1 01:00:42 f13 kernel: Event timer "HPET5" frequency 19200000 Hz quality 340 Jan 1 01:00:42 f13 kernel: Event timer "HPET6" frequency 19200000 Hz quality 340 Jan 1 01:00:42 f13 kernel: Event timer "HPET7" frequency 19200000 Hz quality 340 Jan 1 01:00:42 f13 kernel: atrtc0: <AT realtime clock> port 0x70-0x77 irq 8 on acpi0 Jan 1 01:00:42 f13 kernel: atrtc0: Warning: Couldn't map I/O. Jan 1 01:00:42 f13 kernel: atrtc0: registered as a time-of-day clock, resolution 1.000000s Jan 1 01:00:42 f13 kernel: Event timer "RTC" frequency 32768 Hz quality 0 Jan 1 01:00:42 f13 kernel: attimer0: <AT timer> port 0x40-0x43,0x50-0x53 irq 0 on acpi0 Jan 1 01:00:42 f13 kernel: Timecounter "i8254" frequency 1193182 Hz quality 0 Jan 1 01:00:42 f13 kernel: Event timer "i8254" frequency 1193182 Hz quality 100 Jan 1 01:00:42 f13 kernel: Timecounter "ACPI-fast" frequency 3579545 Hz quality 900 Jan 1 01:00:42 f13 kernel: acpi_timer0: <24-bit timer at 3.579545MHz> port 0x1808-0x180b on acpi0 Jan 1 01:00:42 f13 kernel: pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0 Jan 1 01:00:42 f13 kernel: pci0: <ACPI PCI bus> on pcib0 Jan 1 01:00:42 f13 kernel: vgapci0: <VGA-compatible display> port 0x3000-0x303f mem 0x605c000000-0x605cffffff,0x4000000000-0x400fffffff at device 2.0 on pci0 Jan 1 01:00:42 f13 kernel: acpi_video0: <ACPI video extension> on vgapci0 Jan 1 01:00:42 f13 kernel: vgapci0: Boot video device Jan 1 01:00:42 f13 kernel: pcib1: <ACPI PCI-PCI bridge> at device 6.0 on pci0 Jan 1 01:00:42 f13 kernel: pci1: <ACPI PCI bus> on pcib1 Jan 1 01:00:42 f13 kernel: nvme0: <Generic NVMe Device> mem 0x7a300000-0x7a303fff at device 0.0 on pci1 Jan 1 01:00:42 f13 kernel: pcib2: <ACPI PCI-PCI bridge> at device 7.0 on pci0 Jan 1 01:00:42 f13 kernel: pcib3: <ACPI PCI-PCI bridge> at device 7.1 on pci0 Jan 1 01:00:42 f13 kernel: pcib4: <ACPI PCI-PCI bridge> at device 7.2 on pci0 Jan 1 01:00:42 f13 kernel: pcib5: <ACPI PCI-PCI bridge> at device 7.3 on pci0 Jan 1 01:00:42 f13 kernel: xhci0: <XHCI (generic) USB 3.0 controller> mem 0x605d1a0000-0x605d1affff at device 13.0 on pci0 Jan 1 01:00:42 f13 kernel: xhci0: 32 bytes context size, 64-bit DMA Jan 1 01:00:42 f13 kernel: usbus0 on xhci0 Jan 1 01:00:42 f13 kernel: usbus0: 5.0Gbps Super Speed USB v3.0 Jan 1 01:00:42 f13 kernel: pci0: <serial bus, USB> at device 13.2 (no driver attached) Jan 1 01:00:42 f13 kernel: pci0: <serial bus, USB> at device 13.3 (no driver attached) Jan 1 01:00:42 f13 kernel: xhci1: <Intel Alder Lake USB 3.2 controller> mem 0x7a400000-0x7a40ffff at device 20.0 on pci0 Jan 1 01:00:42 f13 kernel: xhci1: 32 bytes context size, 64-bit DMA Jan 1 01:00:42 f13 kernel: usbus1: waiting for BIOS to give up control Jan 1 01:00:42 f13 kernel: xhci_interrupt: host controller halted Jan 1 01:00:42 f13 kernel: usbus1 on xhci1 Jan 1 01:00:42 f13 kernel: usbus1: 5.0Gbps Super Speed USB v3.0 Jan 1 01:00:42 f13 kernel: pci0: <memory, RAM> at device 20.2 (no driver attached) Jan 1 01:00:42 f13 kernel: pci0: <serial bus> at device 21.0 (no driver attached) Jan 1 01:00:42 f13 kernel: pci0: <serial bus> at device 21.1 (no driver attached) Jan 1 01:00:42 f13 kernel: pci0: <serial bus> at device 21.3 (no driver attached) Jan 1 01:00:42 f13 kernel: pci0: <simple comms> at device 22.0 (no driver attached) Jan 1 01:00:42 f13 kernel: pci0: <simple comms, UART> at device 22.3 (no driver attached) Jan 1 01:00:42 f13 kernel: pcib6: <ACPI PCI-PCI bridge> at device 29.0 on pci0 Jan 1 01:00:42 f13 kernel: pci2: <ACPI PCI bus> on pcib6 Jan 1 01:00:42 f13 kernel: pci2: <network> at device 0.0 (no driver attached) Jan 1 01:00:42 f13 kernel: isab0: <PCI-ISA bridge> at device 31.0 on pci0 Jan 1 01:00:42 f13 kernel: isa0: <ISA bus> on isab0 Jan 1 01:00:42 f13 kernel: hdac0: <Intel Raptor Lake-P HDA Controller> mem 0x605d1c0000-0x605d1c3fff,0x605d000000-0x605d0fffff at device 31.3 on pci0 Jan 1 01:00:42 f13 kernel: pci0: <serial bus> at device 31.5 (no driver attached) Jan 1 01:00:42 f13 kernel: cpu0: <ACPI CPU> on acpi0 Jan 1 01:00:42 f13 kernel: acpi_button0: <Power Button> on acpi0 Jan 1 01:00:42 f13 kernel: acpi_tz0: <Thermal Zone> on acpi0 Jan 1 01:00:42 f13 kernel: acpi_tz1: <Thermal Zone> on acpi0 Jan 1 01:00:42 f13 kernel: acpi_tz2: <Thermal Zone> on acpi0 Jan 1 01:00:42 f13 kernel: acpi_tz3: <Thermal Zone> on acpi0 Jan 1 01:00:42 f13 kernel: acpi_tz4: <Thermal Zone> on acpi0 Jan 1 01:00:42 f13 kernel: acpi_acad0: <AC Adapter> on acpi0 Jan 1 01:00:42 f13 kernel: atkbdc0: <Keyboard controller (i8042)> port 0x60,0x64 irq 1 on acpi0 Jan 1 01:00:42 f13 kernel: atkbd0: <AT Keyboard> irq 1 on atkbdc0 Jan 1 01:00:42 f13 kernel: kbd0 at atkbd0 Jan 1 01:00:42 f13 kernel: atkbd0: [GIANT-LOCKED] Jan 1 01:00:42 f13 kernel: battery0: <ACPI Control Method Battery> on acpi0 Jan 1 01:00:42 f13 kernel: acpi_lid0: <Control Method Lid Switch> on acpi0 Jan 1 01:00:42 f13 kernel: acpi_syscontainer0: <System Container> on acpi0 Jan 1 01:00:42 f13 kernel: acpi_syscontainer1: <System Container> on acpi0 Jan 1 01:00:42 f13 kernel: hwpstate_intel0: <Intel Speed Shift> on cpu0 Jan 1 01:00:42 f13 kernel: cpufreq0: <CPU frequency control> on cpu0 Jan 1 01:00:42 f13 kernel: hwpstate_intel1: <Intel Speed Shift> on cpu1 Jan 1 01:00:42 f13 kernel: cpufreq1: <CPU frequency control> on cpu1 Jan 1 01:00:42 f13 kernel: hwpstate_intel2: <Intel Speed Shift> on cpu2 Jan 1 01:00:42 f13 kernel: cpufreq2: <CPU frequency control> on cpu2 Jan 1 01:00:42 f13 kernel: hwpstate_intel3: <Intel Speed Shift> on cpu3 Jan 1 01:00:42 f13 kernel: cpufreq3: <CPU frequency control> on cpu3 Jan 1 01:00:42 f13 kernel: hwpstate_intel4: <Intel Speed Shift> on cpu4 Jan 1 01:00:42 f13 kernel: cpufreq4: <CPU frequency control> on cpu4 Jan 1 01:00:42 f13 kernel: hwpstate_intel5: <Intel Speed Shift> on cpu5 Jan 1 01:00:42 f13 kernel: cpufreq5: <CPU frequency control> on cpu5 Jan 1 01:00:42 f13 kernel: hwpstate_intel6: <Intel Speed Shift> on cpu6 Jan 1 01:00:42 f13 kernel: cpufreq6: <CPU frequency control> on cpu6 Jan 1 01:00:42 f13 kernel: hwpstate_intel7: <Intel Speed Shift> on cpu7 Jan 1 01:00:42 f13 kernel: cpufreq7: <CPU frequency control> on cpu7 Jan 1 01:00:42 f13 kernel: hwpstate_intel8: <Intel Speed Shift> on cpu8 Jan 1 01:00:42 f13 kernel: cpufreq8: <CPU frequency control> on cpu8 Jan 1 01:00:42 f13 kernel: hwpstate_intel9: <Intel Speed Shift> on cpu9 Jan 1 01:00:42 f13 kernel: cpufreq9: <CPU frequency control> on cpu9 Jan 1 01:00:42 f13 kernel: hwpstate_intel10: <Intel Speed Shift> on cpu10 Jan 1 01:00:42 f13 kernel: cpufreq10: <CPU frequency control> on cpu10 Jan 1 01:00:42 f13 kernel: hwpstate_intel11: <Intel Speed Shift> on cpu11 Jan 1 01:00:42 f13 kernel: cpufreq11: <CPU frequency control> on cpu11 Jan 1 01:00:42 f13 kernel: hwpstate_intel12: <Intel Speed Shift> on cpu12 Jan 1 01:00:42 f13 kernel: cpufreq12: <CPU frequency control> on cpu12 Jan 1 01:00:42 f13 kernel: hwpstate_intel13: <Intel Speed Shift> on cpu13 Jan 1 01:00:42 f13 kernel: cpufreq13: <CPU frequency control> on cpu13 Jan 1 01:00:42 f13 kernel: hwpstate_intel14: <Intel Speed Shift> on cpu14 Jan 1 01:00:42 f13 kernel: cpufreq14: <CPU frequency control> on cpu14 Jan 1 01:00:42 f13 kernel: hwpstate_intel15: <Intel Speed Shift> on cpu15 Jan 1 01:00:42 f13 kernel: cpufreq15: <CPU frequency control> on cpu15 Jan 1 01:00:42 f13 kernel: hwpstate_intel16: <Intel Speed Shift> on cpu16 Jan 1 01:00:42 f13 kernel: cpufreq16: <CPU frequency control> on cpu16 Jan 1 01:00:42 f13 kernel: hwpstate_intel17: <Intel Speed Shift> on cpu17 Jan 1 01:00:42 f13 kernel: cpufreq17: <CPU frequency control> on cpu17 Jan 1 01:00:42 f13 kernel: hwpstate_intel18: <Intel Speed Shift> on cpu18 Jan 1 01:00:42 f13 kernel: cpufreq18: <CPU frequency control> on cpu18 Jan 1 01:00:42 f13 kernel: hwpstate_intel19: <Intel Speed Shift> on cpu19 Jan 1 01:00:42 f13 kernel: cpufreq19: <CPU frequency control> on cpu19 Jan 1 01:00:42 f13 kernel: Timecounter "TSC-low" frequency 1094402496 Hz quality 1000 Jan 1 01:00:42 f13 kernel: Timecounters tick every 1.000 msec Jan 1 01:00:42 f13 kernel: ZFS filesystem version: 5 Jan 1 01:00:42 f13 kernel: ZFS storage pool version: features support (5000) Jan 1 01:00:42 f13 kernel: acpi_acad0: acline initialization start Jan 1 01:00:42 f13 kernel: battery0: battery initialization start Jan 1 01:00:42 f13 kernel: ugen0.1: <Intel XHCI root HUB> at usbus0 Jan 1 01:00:42 f13 kernel: ugen1.1: <Intel XHCI root HUB> at usbus1 Jan 1 01:00:42 f13 kernel: acpi_acad0: On Line Jan 1 01:00:42 f13 kernel: battery0: critically low charge! Jan 1 01:00:42 f13 kernel: uhub0acpi_acad0: acline initialization done, tried 1 times Jan 1 01:00:42 f13 kernel: battery0: rev = 0000 Jan 1 01:00:42 f13 kernel: on usbus0 Jan 1 01:00:42 f13 kernel: uhub0: <Intel XHCI root HUB, class 9/0, rev 3.00/1.00, addr 1> on usbus0 Jan 1 01:00:42 f13 kernel: uhub1battery0: units = 0 Jan 1 01:00:42 f13 kernel: battery0: dcap = 0 Jan 1 01:00:42 f13 kernel: battery0: lfcap = 0 Jan 1 01:00:42 f13 kernel: battery0: btech = 0 Jan 1 01:00:42 f13 kernel: battery0: dvol = 0 Jan 1 01:00:42 f13 kernel: battery0: wcap = 0 Jan 1 01:00:42 f13 kernel: battery0: lcap = 0 Jan 1 01:00:42 f13 kernel: battery0: cycles = 0 Jan 1 01:00:42 f13 kernel: battery0: accuracy = 0 Jan 1 01:00:42 f13 kernel: battery0: stmax = 0 Jan 1 01:00:42 f13 kernel: battery0: stmin = 0 Jan 1 01:00:42 f13 kernel: battery0: aimax = 0 Jan 1 01:00:42 f13 kernel: battery0: aimin = 0 Jan 1 01:00:42 f13 kernel: battery0: gra1 = 0 Jan 1 01:00:42 f13 kernel: battery0: gra2 = 0 Jan 1 01:00:42 f13 kernel: battery0: battery initialization done, tried 1 times Jan 1 01:00:42 f13 kernel: on usbus1 Jan 1 01:00:42 f13 kernel: uhub1: <Intel XHCI root HUB, class 9/0, rev 3.00/1.00, addr 1> on usbus1 Jan 1 01:00:42 f13 kernel: hdacc0: <Tempo 92HD95B HDA CODEC> at cad 0 on hdac0 Jan 1 01:00:42 f13 kernel: hdaa0: <Tempo 92HD95B Audio Function Group> at nid 1 on hdacc0 Jan 1 01:00:42 f13 kernel: pcm0: <Tempo 92HD95B (Analog 2.0+HP/2.0)> at nid 13,10 and 14,11 on hdaa0 Jan 1 01:00:42 f13 kernel: nda0 at nvme0 bus 0 scbus0 target 0 lun 1 Jan 1 01:00:42 f13 kernel: nda0: <WD_BLACK SN850X 2000GB 620311WD 23171F802850> Jan 1 01:00:42 f13 kernel: nda0: Serial Number 23171F802850 Jan 1 01:00:42 f13 kernel: nda0: nvme version 1.4 Jan 1 01:00:42 f13 kernel: nda0: 1907729MB (3907029168 512 byte sectors) Jan 1 01:00:42 f13 kernel: Trying to mount root from zfs:zroot/ROOT/default []... Jan 1 01:00:42 f13 kernel: uhub0: 5 ports with 5 removable, self powered Jan 1 01:00:42 f13 kernel: ugen0.2: <vendor 0x13fe USB DISK 3.2> at usbus0 Jan 1 01:00:42 f13 kernel: umass0 on uhub0 Jan 1 01:00:42 f13 kernel: umass0: <vendor 0x13fe USB DISK 3.2, class 0/0, rev 3.20/1.10, addr 1> on usbus0 Jan 1 01:00:42 f13 kernel: da0 at umass-sim0 bus 0 scbus1 target 0 lun 0 Jan 1 01:00:42 f13 kernel: da0: < USB DISK 3.2 PMAP> Fixed Direct Access SPC-4 SCSI device Jan 1 01:00:42 f13 kernel: da0: Serial Number 07001961C012DD01 Jan 1 01:00:42 f13 kernel: da0: 400.000MB/s transfers Jan 1 01:00:42 f13 kernel: da0: 953869MB (1953525168 512 byte sectors) Jan 1 01:00:42 f13 kernel: da0: quirks=0x2<NO_6_BYTE> Jan 1 01:00:42 f13 kernel: uhub1: 16 ports with 16 removable, self powered Jan 1 01:00:42 f13 kernel: ugen1.2: <Goodix Technology Co., Ltd. Goodix Fingerprint USB Device> at usbus1 Jan 1 01:00:42 f13 kernel: GEOM_ELI: Device nda0p4.eli created. Jan 1 01:00:42 f13 kernel: GEOM_ELI: Encryption: AES-XTS 128 Jan 1 01:00:42 f13 kernel: GEOM_ELI: Crypto: accelerated software Jan 1 01:00:42 f13 kernel: ugen1.3: <vendor 0x8087 product 0x0032> at usbus1 Jan 1 01:00:42 f13 kernel: GEOM_ELI: Device da0p4.eli created. Jan 1 01:00:42 f13 kernel: GEOM_ELI: Encryption: AES-XTS 256 Jan 1 01:00:42 f13 kernel: GEOM_ELI: Crypto: accelerated software Jan 1 01:00:42 f13 kernel: /etc/rc: WARNING: Kernel dumps will be written to the swap partition without encryption. Jan 1 01:00:42 f13 kernel: Setting hostuuid: 6df4ac53-ac24-ee11-9dfe-221456800199. Jan 1 01:00:42 f13 kernel: Setting hostid: 0x479a7c9c. Jan 1 01:00:42 f13 kernel: GEOM_ELI: Device nda0p5.eli created. Jan 1 01:00:42 f13 kernel: GEOM_ELI: Encryption: AES-XTS 128 Jan 1 01:00:42 f13 kernel: GEOM_ELI: Crypto: accelerated software Jan 1 01:00:42 f13 kernel: Starting file system checks: Jan 1 01:00:42 f13 kernel: /dev/gpt/efiboot0: 9 files, 259 MiB free (16545 clusters) Jan 1 01:00:42 f13 kernel: FIXED Jan 1 01:00:42 f13 kernel: /dev/gpt/efiboot0: MARKING FILE SYSTEM CLEAN Jan 1 01:00:42 f13 kernel: Mounting local filesystems:. Jan 1 01:00:42 f13 kernel: Loading key for data from file:///etc/keys/f13_data.key.. Jan 1 01:00:42 f13 kernel: Loading kernel modules: /boot/modules/i915kms.ko fusefs vboxdrv Jan 1 01:00:42 f13 kernel: [drm] Got Intel graphics stolen memory base 0x4c800000, size 0x3c00000 Jan 1 01:00:42 f13 kernel: drmn0: <drmn> on vgapci0 Jan 1 01:00:42 f13 kernel: vgapci0: child drmn0 requested pci_enable_io Jan 1 01:00:42 f13 syslogd: last message repeated 1 times Jan 1 01:00:42 f13 kernel: i915/adlp_dmc.bin: could not load binary firmware /boot/firmware/i915/adlp_dmc.bin either Jan 1 01:00:42 f13 kernel: adlp_dmc.bin: could not load binary firmware /boot/firmware/adlp_dmc.bin either Jan 1 01:00:42 f13 kernel: i915_adlp_dmc.bin: could not load binary firmware /boot/firmware/i915_adlp_dmc.bin either Jan 1 01:00:42 f13 kernel: drmn0: successfully loaded firmware image 'i915/adlp_dmc.bin' Jan 1 01:00:42 f13 kernel: lkpi_iic0: <LinuxKPI I2C> on drmn0 Jan 1 01:00:42 f13 kernel: iicbus0: <Philips I2C bus> on lkpi_iic0 Jan 1 01:00:42 f13 kernel: iic0: <I2C generic I/O> on iicbus0 Jan 1 01:00:42 f13 kernel: lkpi_iic1: <LinuxKPI I2C> on drmn0 Jan 1 01:00:42 f13 kernel: iicbus1: <Philips I2C bus> on lkpi_iic1 Jan 1 01:00:42 f13 kernel: iic1: <I2C generic I/O> on iicbus1 Jan 1 01:00:42 f13 kernel: lkpi_iic2: <LinuxKPI I2C> on drmn0 Jan 1 01:00:42 f13 kernel: iicbus2: <Philips I2C bus> on lkpi_iic2 Jan 1 01:00:42 f13 kernel: iic2: <I2C generic I/O> on iicbus2 Jan 1 01:00:42 f13 kernel: lkpi_iic3: <LinuxKPI I2C> on drmn0 Jan 1 01:00:42 f13 kernel: iicbus3: <Philips I2C bus> on lkpi_iic3 Jan 1 01:00:42 f13 kernel: iic3: <I2C generic I/O> on iicbus3 Jan 1 01:00:42 f13 kernel: lkpi_iic4: <LinuxKPI I2C> on drmn0 Jan 1 01:00:42 f13 kernel: iicbus4: <Philips I2C bus> on lkpi_iic4 Jan 1 01:00:42 f13 kernel: iic4: <I2C generic I/O> on iicbus4 Jan 1 01:00:42 f13 kernel: lkpi_iic5: <LinuxKPI I2C> on drmn0 Jan 1 01:00:42 f13 kernel: iicbus5: <Philips I2C bus> on lkpi_iic5 Jan 1 01:00:42 f13 kernel: iic5: <I2C generic I/O> on iicbus5 Jan 1 01:00:42 f13 kernel: lkpi_iic6: <LinuxKPI I2C> on drmn0 Jan 1 01:00:42 f13 kernel: iicbus6: <Philips I2C bus> on lkpi_iic6 Jan 1 01:00:42 f13 kernel: iic6: <I2C generic I/O> on iicbus6 Jan 1 01:00:42 f13 kernel: lkpi_iic7: <LinuxKPI I2C> on drmn0 Jan 1 01:00:42 f13 kernel: iicbus7: <Philips I2C bus> on lkpi_iic7 Jan 1 01:00:42 f13 kernel: iic7: <I2C generic I/O> on iicbus7 Jan 1 01:00:42 f13 kernel: lkpi_iic8: <LinuxKPI I2C> on drmn0 Jan 1 01:00:42 f13 kernel: iicbus8: <Philips I2C bus> on lkpi_iic8 Jan 1 01:00:42 f13 kernel: iic8: <I2C generic I/O> on iicbus8 Jan 1 01:00:42 f13 kernel: drmn0: [drm] Finished loading DMC firmware i915/adlp_dmc.bin (v2.20) Jan 1 01:00:42 f13 kernel: sysctl_add_oid: can't re-use a leaf (hw.dri.debug)! Jan 1 01:00:42 f13 kernel: lkpi_iic9: <LinuxKPI I2C> on drm1 Jan 1 01:00:42 f13 kernel: iicbus9: <Philips I2C bus> on lkpi_iic9 Jan 1 01:00:42 f13 kernel: iic9: <I2C generic I/O> on iicbus9 Jan 1 01:00:42 f13 kernel: lkpi_iic10: <LinuxKPI I2C> on drm2 Jan 1 01:00:42 f13 kernel: iicbus10: <Philips I2C bus> on lkpi_iic10 Jan 1 01:00:42 f13 kernel: iic10: <I2C generic I/O> on iicbus10 Jan 1 01:00:42 f13 kernel: lkpi_iic11: <LinuxKPI I2C> on drm3 Jan 1 01:00:42 f13 kernel: iicbus11: <Philips I2C bus> on lkpi_iic11 Jan 1 01:00:42 f13 kernel: iic11: <I2C generic I/O> on iicbus11 Jan 1 01:00:42 f13 kernel: lkpi_iic12: <LinuxKPI I2C> on drm4 Jan 1 01:00:42 f13 kernel: iicbus12: <Philips I2C bus> on lkpi_iic12 Jan 1 01:00:42 f13 kernel: iic12: <I2C generic I/O> on iicbus12 Jan 1 01:00:42 f13 kernel: lkpi_iic13: <LinuxKPI I2C> on drm5 Jan 1 01:00:42 f13 kernel: iicbus13: <Philips I2C bus> on lkpi_iic13 Jan 1 01:00:42 f13 kernel: iic13: <I2C generic I/O> on iicbus13 Jan 1 01:00:42 f13 kernel: [drm] Initialized i915 1.6.0 20201103 for drmn0 on minor 0 Jan 1 01:00:42 f13 kernel: VT: Replacing driver "efifb" with new "drmfb". Jan 1 01:00:42 f13 kernel: start FB_INFO: Jan 1 01:00:42 f13 kernel: height=1504 width=2256 depth=32 Jan 1 01:00:42 f13 kernel: pbase=0x4000000000 vbase=0xfffffe01d8600000 Jan 1 01:00:42 f13 kernel: name=drmn0 id=i915drmfb flags=0x0 stride=9024 Jan 1 01:00:42 f13 kernel: end FB_INFO Jan 1 01:00:42 f13 kernel: Key already loaded for data. Jan 1 01:00:42 f13 kernel: Autoloading module: acpi_wmi Jan 1 01:00:42 f13 kernel: Autoloading module: ichsmb Jan 1 01:00:42 f13 kernel: Autoloading module: if_iwlwifi Jan 1 01:00:42 f13 kernel: Intel(R) Wireless WiFi based driver for FreeBSD Jan 1 01:00:42 f13 kernel: Autoloading module: ig4 Jan 1 01:00:42 f13 kernel: ig4iic0: <Intel Alder Lake-P I2C Controller-0> at device 21.0 on pci0 Jan 1 01:00:42 f13 kernel: ig4iic0: Using MSI Jan 1 01:00:42 f13 kernel: iicbus14: <Philips I2C bus (ACPI-hinted)> on ig4iic0 Jan 1 01:00:42 f13 kernel: iic14: <I2C generic I/O> on iicbus14 Jan 1 01:00:42 f13 kernel: ig4iic1: <Intel Alder Lake-P I2C Controller-1> at device 21.1 on pci0 Jan 1 01:00:42 f13 kernel: ig4iic1: Using MSI Jan 1 01:00:42 f13 kernel: iicbus15: <Philips I2C bus (ACPI-hinted)> on ig4iic1 Jan 1 01:00:42 f13 kernel: iicbus15: <unknown card> at addr 0x50 Jan 1 01:00:42 f13 kernel: iicbus15: <unknown card> at addr 0x36 Jan 1 01:00:42 f13 kernel: iic15: <I2C generic I/O> on iicbus15 Jan 1 01:00:42 f13 kernel: ig4iic2: <Intel Alder Lake-P I2C Controller-3> at device 21.3 on pci0 Jan 1 01:00:42 f13 kernel: ig4iic2: Using MSI Jan 1 01:00:42 f13 kernel: iicbus16: <Philips I2C bus (ACPI-hinted)> on ig4iic2 Jan 1 01:00:42 f13 kernel: iicbus16: <unknown card> at addr 0x2c Jan 1 01:00:42 f13 kernel: iic16: <I2C generic I/O> on iicbus16 Jan 1 01:00:42 f13 kernel: ichsmb0: <Intel Alder Lake SMBus controller> port 0xefa0-0xefbf mem 0x605d1c8000-0x605d1c80ff at device 31.4 on pci0 Jan 1 01:00:42 f13 kernel: smbus0: <System Management Bus> on ichsmb0 Jan 1 01:00:42 f13 kernel: iwlwifi0: <iwlwifi> mem 0x7a200000-0x7a203fff at device 0.0 on pci2 Jan 1 01:00:42 f13 kernel: iwlwifi0: Detected crf-id 0x400410, cnv-id 0x400410 wfpm id 0x80000000 Jan 1 01:00:42 f13 kernel: iwlwifi0: PCI dev 2725/0020, rev=0x420, rfid=0x10d000 Jan 1 01:00:42 f13 kernel: iwlwifi0: Detected Intel(R) Wi-Fi 6 AX210 160MHz Jan 1 01:00:42 f13 kernel: iwlwifi0: successfully loaded firmware image 'iwlwifi-ty-a0-gf-a0-89.ucode' Jan 1 01:00:42 f13 kernel: iwlwifi0: TLV_FW_FSEQ_VERSION: FSEQ Version: 0.0.2.42 Jan 1 01:00:42 f13 kernel: iwl-debug-yoyo.bin: could not load binary firmware /boot/firmware/iwl-debug-yoyo.bin either Jan 1 01:00:42 f13 syslogd: last message repeated 1 times Jan 1 01:00:42 f13 kernel: iwl-debug-yoyo_bin: could not load binary firmware /boot/firmware/iwl-debug-yoyo_bin either Jan 1 01:00:42 f13 kernel: iwl_debug_yoyo_bin: could not load binary firmware /boot/firmware/iwl_debug_yoyo_bin either Jan 1 01:00:42 f13 kernel: iwlwifi0: loaded firmware version 89.6b44fa0b.0 ty-a0-gf-a0-89.ucode op_mode iwlmvm Jan 1 01:00:42 f13 kernel: iwlwifi0: WFPM_UMAC_PD_NOTIFICATION: 0x20 Jan 1 01:00:42 f13 kernel: iwlwifi0: WFPM_LMAC2_PD_NOTIFICATION: 0x1f Jan 1 01:00:42 f13 kernel: iwlwifi0: WFPM_AUTH_KEY_0: 0x90 Jan 1 01:00:42 f13 kernel: iwlwifi0: CNVI_SCU_SEQ_DATA_DW9: 0x0 Jan 1 01:00:42 f13 kernel: iwlwifi0: successfully loaded firmware image 'iwlwifi-ty-a0-gf-a0.pnvm' Jan 1 01:00:42 f13 kernel: iwlwifi0: loaded PNVM version 16611aa6 Jan 1 01:00:42 f13 kernel: iwlwifi0: Detected RF GF, rfid=0x10d000 Jan 1 01:00:42 f13 kernel: iwlwifi0: base HW address: 8c:b8:7e:c9:89:57 Jan 1 01:00:42 f13 kernel: acpi_wmi0: <ACPI-WMI mapping> on acpi0 Jan 1 01:00:42 f13 kernel: acpi_wmi0: cannot find EC device Jan 1 01:00:42 f13 kernel: acpi_wmi0: Embedded MOF found Jan 1 01:00:42 f13 kernel: acpi_wmi1: <ACPI-WMI mapping> on acpi0 Jan 1 01:00:42 f13 kernel: acpi_wmi1: cannot find EC device Jan 1 01:00:42 f13 kernel: acpi_wmi1: Embedded MOF found Jan 1 01:00:42 f13 kernel: acpi_wmi2: <ACPI-WMI mapping> on acpi0 Jan 1 01:00:42 f13 kernel: acpi_wmi2: cannot find EC device Jan 1 01:00:42 f13 kernel: acpi_wmi2: Embedded MOF found Jan 1 01:00:42 f13 kernel: Setting up harvesting: PURE_RDRAND,RANDOMDEV,[CALLOUT],[UMA],[FS_ATIME],SWI,INTERRUPT,NET_NG,[NET_ETHER],NET_TUN,MOUSE,KEYBOARD,ATTACH,CACHED Jan 1 01:00:42 f13 kernel: Feeding entropy: . Jan 1 01:00:42 f13 kernel: Setting hostname: f13. Jan 1 01:00:42 f13 kernel: ELF ldconfig path: /lib /usr/lib /usr/lib/compat /usr/local/lib /usr/local/lib/compat/pkg /usr/local/GNUstep/System/Library/Libraries /usr/local/ffmpeg4/lib /usr/local/lib/alsa-lib /usr/local/lib/compat/pkg /usr/local/lib/e2fsprogs /usr/local/lib/gcc12 /usr/local/lib/gcc13 /usr/local/lib/perl5/5.40/mach/CORE /usr/local/lib/qt5 /usr/local/lib/qt6 /usr/local/lib/samba4 /usr/local/llvm15/lib /usr/local/llvm16/lib /usr/local/llvm17/lib /usr/local/llvm19/lib /usr/local/llvm19/lib/x86_64-portbld-freebsd15.0 /usr/local/openjdk11/lib/server /usr/local/openjdk8/lib/amd64 Jan 1 01:00:42 f13 kernel: 32-bit compatibility ldconfig path: /usr/lib32 /usr/local/lib32/gcc12 /usr/local/lib32/gcc13 Jan 1 01:00:42 f13 kernel: Starting autounmountd. Jan 1 01:00:42 f13 kernel: iwlwifi0: WFPM_UMAC_PD_NOTIFICATION: 0x20 Jan 1 01:00:42 f13 kernel: iwlwifi0: WFPM_LMAC2_PD_NOTIFICATION: 0x1f Jan 1 01:00:42 f13 kernel: iwlwifi0: WFPM_AUTH_KEY_0: 0x90 Jan 1 01:00:42 f13 kernel: iwlwifi0: CNVI_SCU_SEQ_DATA_DW9: 0x0 Jan 1 01:00:42 f13 kernel: wlan0: Ethernet address: 8c:b8:7e:c9:89:57 Jan 1 01:00:42 f13 kernel: Created wlan(4) interfaces: wlan0. Jan 1 01:00:42 f13 kernel: lo0: link state changed to UP Jan 1 01:00:42 f13 kernel: Starting wpa_supplicant. Jan 1 01:00:42 f13 kernel: Starting Network: lo0 wlan0. Jan 1 01:00:42 f13 kernel: lo0: flags=1008049<UP,LOOPBACK,RUNNING,MULTICAST,LOWER_UP> metric 0 mtu 16384 Jan 1 01:00:42 f13 kernel: options=680003<RXCSUM,TXCSUM,LINKSTATE,RXCSUM_IPV6,TXCSUM_IPV6> Jan 1 01:00:42 f13 kernel: inet 127.0.0.1 netmask 0xff000000 Jan 1 01:00:42 f13 kernel: inet6 ::1 prefixlen 128 Jan 1 01:00:42 f13 kernel: inet6 fe80::1%lo0 prefixlen 64 scopeid 0x1 Jan 1 01:00:42 f13 kernel: groups: lo Jan 1 01:00:42 f13 kernel: nd6 options=21<PERFORMNUD,AUTO_LINKLOCAL> Jan 1 01:00:42 f13 kernel: wlan0: flags=8802<BROADCAST,SIMPLEX,MULTICAST> metric 0 mtu 1500 Jan 1 01:00:42 f13 kernel: options=0 Jan 1 01:00:42 f13 kernel: ether 8c:b8:7e:c9:89:57 Jan 1 01:00:42 f13 kernel: groups: wlan Jan 1 01:00:42 f13 kernel: ssid "" channel 1 (2412 MHz 11b) Jan 1 01:00:42 f13 kernel: regdomain ETSI country NO authmode OPEN privacy OFF txpower 30 Jan 1 01:00:42 f13 kernel: bmiss 7 scanvalid 60 wme bintval 0 Jan 1 01:00:42 f13 kernel: parent interface: iwlwifi0 Jan 1 01:00:42 f13 kernel: media: IEEE 802.11 Wireless Ethernet autoselect (autoselect) Jan 1 01:00:42 f13 kernel: status: no carrier Jan 1 01:00:42 f13 kernel: nd6 options=29<PERFORMNUD,IFDISABLED,AUTO_LINKLOCAL> Jan 1 01:00:42 f13 kernel: Cuse v0.1.37 @ /dev/cuse Jan 1 01:00:42 f13 kernel: Starting webcamd. Jan 1 01:00:42 f13 kernel: webcamd 658 - - webcamd: No USB device match found Jan 1 01:00:42 f13 kernel: Starting devd. Jan 1 01:00:42 f13 kernel: iwmbtfw: iwmbt_fw_read: open: /usr/local/share/iwmbt-firmware/ibt-0041-0041.sfi: No such file or directory Jan 1 01:00:42 f13 kernel: iwmbtfw: main: Firmware download failed! Jan 1 01:00:42 f13 kernel: Autoloading module: iichid Jan 1 01:00:42 f13 kernel: iichid0: <FRMW0001:05 32AC:0006 I2C HID device> at addr 0x50 on iicbus15 Jan 1 01:00:42 f13 kernel: iichid0: Using sampling mode Jan 1 01:00:42 f13 kernel: hidbus0: <HID bus> on iichid0 Jan 1 01:00:42 f13 kernel: iichid1: <PIXA3854:06 093A:0274 I2C HID device> at addr 0x2c on iicbus16 Jan 1 01:00:42 f13 kernel: iichid1: Using sampling mode Jan 1 01:00:42 f13 kernel: hidbus1: <HID bus> on iichid1 Jan 1 01:00:42 f13 kernel: Autoloading module: hcons Jan 1 01:00:42 f13 kernel: hcons0: <FRMW0001:05 32AC:0006 Consumer Control> on hidbus0 Jan 1 01:00:42 f13 kernel: Autoloading module: hms Jan 1 01:00:42 f13 kernel: hms0: <PIXA3854:06 093A:0274 Mouse> on hidbus1 Jan 1 01:00:42 f13 kernel: hms0: 2 buttons and [XY] coordinates ID=2 Jan 1 01:00:42 f13 kernel: Autoloading module: hmt Jan 1 01:00:42 f13 kernel: hmt0: <PIXA3854:06 093A:0274 TouchPad> on hidbus1 Jan 1 01:00:42 f13 kernel: hconf0: <PIXA3854:06 093A:0274 Configuration> on hidbus1 Jan 1 01:00:42 f13 kernel: hmt0: Multitouch touchpad with 2 external buttons, click-pad Jan 1 01:00:42 f13 kernel: hmt0: 5 contacts with [C] properties. Report range [0:0] - [1337:876] Jan 1 01:00:42 f13 kernel: Waiting 30s for the default route interface: . Jan 1 01:00:42 f13 kernel: iwlwifi0: Not associated and the session protection is over already... Jan 1 01:00:42 f13 kernel: iwlwifi0: linuxkpi_ieee80211_connection_loss: vif 0xfffffe01d4969f00 vap 0xfffffe01d4969010 state ASSOC (synched 1, assoc 0 beacons 8 dtim_period 0) Jan 1 01:00:42 f13 kernel: ....(no carrier) Jan 1 01:00:42 f13 kernel: Starting zfsd. Jan 1 01:00:42 f13 kernel: route: message indicates error: File exists Jan 1 01:00:42 f13 kernel: add host 127.0.0.1: gateway lo0 fib 0: route already in table Jan 1 01:00:42 f13 kernel: route: message indicates error: File exists Jan 1 01:00:42 f13 kernel: add host ::1: gateway lo0 fib 0: route already in table Jan 1 01:00:42 f13 kernel: add net fe80::: gateway ::1 Jan 1 01:00:42 f13 kernel: add net ff02::: gateway ::1 Jan 1 01:00:42 f13 kernel: add net ::ffff:0.0.0.0: gateway ::1 Jan 1 01:00:42 f13 kernel: add net ::0.0.0.0: gateway ::1 Jan 1 01:00:42 f13 kernel: Creating and/or trimming log files. Jan 1 01:00:42 f13 kernel: Starting syslogd. Jan 1 01:00:42 f13 kernel: savecore 4834 - - first and last dump headers disagree on /dev/nda0p5 Jan 1 01:00:42 f13 kernel: No core dumps found. Jan 1 01:00:42 f13 savecore[4834]: first and last dump headers disagree on /dev/nda0p5 Jan 1 01:00:42 f13 kernel: Jan 1 01:00:42 f13 savecore[4834]: first and last dump headers disagree on /dev/nda0p5 Jan 1 01:00:42 f13 wpa_supplicant[596]: RTM_IFANNOUNCE: Interface 'bridge0' added Jan 1 01:00:42 f13 wpa_supplicant[596]: RTM_IFANNOUNCE: Interface 'bridge0' removed Jan 1 01:00:42 f13 wpa_supplicant[596]: RTM_IFANNOUNCE: Interface 'vm-public' added Jan 1 01:00:42 f13 kernel: bridge0: Ethernet address: 58:9c:fc:10:fd:51 Jan 1 01:00:42 f13 kernel: bridge0: changing name to 'vm-public' Jan 1 01:00:42 f13 kernel: wlan0: promiscuous mode enabled Jan 1 01:00:42 f13 kernel: vm-public: link state changed to UP Jan 1 01:00:42 f13 kernel: Enabling pf. Jan 1 01:00:42 f13 kernel: Setting date via ntp. Jan 1 01:00:42 f13 kernel: Exiting, name server cannot be used: Non-recoverable failure in name resolution (4) 1 Jan 01:00:42 ntpdate[5063]: name server cannot be used: Non-recoverable failure in name resolution (4) Jan 1 01:00:42 f13 kernel: Updating /var/run/os-release done. Jan 1 01:00:42 f13 kernel: NFS access cache time=60 Jan 1 01:00:42 f13 kernel: Clearing /tmp (X related). Jan 1 01:00:42 f13 kernel: Updating motd: Jan 1 01:00:42 f13 rpc.statd[5217]: Starting Jan 1 01:00:42 f13 rpc.lockd[5250]: Starting Jan 1 01:00:42 f13 root[5301]: /etc/rc: WARNING: failed to start powerdxx Jan 1 01:00:42 f13 kernel: . Jan 1 01:00:42 f13 kernel: Starting rpcbind. Jan 1 01:00:42 f13 kernel: Starting statd. Jan 1 01:00:42 f13 kernel: Starting lockd. Jan 1 01:00:42 f13 kernel: Starting automountd. Jan 1 01:00:42 f13 kernel: Starting powerd. Jan 1 01:00:42 f13 kernel: Starting powerdxx. Jan 1 01:00:42 f13 kernel: powerd++: (EDRIVER) frequency control driver not supported: hwpstate_intel0 Jan 1 01:00:42 f13 kernel: /etc/rc: WARNING: failed to start powerdxx Jan 1 01:00:42 f13 kernel: Starting dbus. Jan 1 01:00:42 f13 ntpd[5349]: ntpd 4.2.8p18-a (1): Starting Jan 1 01:00:42 f13 ntpd[5349]: Command line: /usr/sbin/ntpd -p /var/db/ntp/ntpd.pid -c /etc/ntp.conf -f /var/db/ntp/ntpd.drift -g Jan 1 01:00:42 f13 ntpd[5349]: ---------------------------------------------------- Jan 1 01:00:42 f13 ntpd[5349]: ntp-4 is maintained by Network Time Foundation, Jan 1 01:00:42 f13 ntpd[5349]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Jan 1 01:00:42 f13 ntpd[5349]: corporation. Support and training for ntp-4 are Jan 1 01:00:42 f13 ntpd[5349]: available at https://www.nwtime.org/support Jan 1 01:00:42 f13 ntpd[5349]: ---------------------------------------------------- Jan 1 01:00:42 f13 ntpd[5350]: proto: precision = 0.141 usec (-23) Jan 1 01:00:42 f13 ntpd[5350]: initial drift restored to -18.898270 Jan 1 01:00:42 f13 ntpd[5350]: basedate set to 2018-08-07 Jan 1 01:00:42 f13 ntpd[5350]: gps base set to 2018-08-12 (week 2014) Jan 1 01:00:42 f13 ntpd[5350]: leapsecond file ('/var/db/ntpd.leap-seconds.list'): good hash signature Jan 1 01:00:42 f13 ntpd[5350]: leapsecond file ('/var/db/ntpd.leap-seconds.list'): loaded, expire=2025-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 1 01:00:42 f13 ntpd[5350]: Listen and drop on 0 v6wildcard [::]:123 Jan 1 01:00:42 f13 ntpd[5350]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 1 01:00:42 f13 ntpd[5350]: Listen normally on 2 lo0 [::1]:123 Jan 1 01:00:42 f13 ntpd[5350]: Listen normally on 3 lo0 [fe80::1%1]:123 Jan 1 01:00:42 f13 ntpd[5350]: Listen normally on 4 lo0 127.0.0.1:123 Jan 1 01:00:42 f13 ntpd[5350]: Listening on routing socket on fd #25 for interface updates Jan 1 01:00:42 f13 ntpd[5350]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 1 01:00:42 f13 syslogd: last message repeated 1 times Jan 1 01:00:43 f13 kernel: Security policy loaded: MAC/ntpd (mac_ntpd) Jan 1 01:00:43 f13 kernel: Starting ntpd. Jan 1 01:00:43 f13 kernel: Mounting late filesystems:. Jan 1 01:00:43 f13 kernel: Starting local daemons:sysctl: unknown oid 'dev.hwpstate_intel.{0..19}.epp' Jan 1 01:00:43 f13 kernel: . Jan 1 01:00:43 f13 kernel: Starting avahi-daemon. Jan 1 01:00:43 f13 avahi-daemon[5399]: Found user 'avahi' (UID 558) and group 'avahi' (GID 558). Jan 1 01:00:43 f13 avahi-daemon[5399]: Successfully dropped root privileges. Jan 1 01:00:43 f13 avahi-daemon[5399]: avahi-daemon 0.8 starting up. Jan 1 01:00:43 f13 avahi-daemon[5399]: Loading service file /usr/local/etc/avahi/services/sftp-ssh.service. Jan 1 01:00:43 f13 avahi-daemon[5399]: Loading service file /usr/local/etc/avahi/services/ssh.service. Jan 1 01:00:43 f13 avahi-daemon[5399]: Joining mDNS multicast group on interface lo0.IPv6 with address fe80::1. Jan 1 01:00:43 f13 avahi-daemon[5399]: New relevant interface lo0.IPv6 for mDNS. Jan 1 01:00:43 f13 avahi-daemon[5399]: Joining mDNS multicast group on interface lo0.IPv4 with address 127.0.0.1. Jan 1 01:00:43 f13 avahi-daemon[5399]: New relevant interface lo0.IPv4 for mDNS. Jan 1 01:00:43 f13 avahi-daemon[5399]: Network interface enumeration completed. Jan 1 01:00:43 f13 avahi-daemon[5399]: Registering new address record for fe80::1 on lo0.*. Jan 1 01:00:43 f13 avahi-daemon[5399]: Registering new address record for ::1 on lo0.*. Jan 1 01:00:43 f13 avahi-daemon[5399]: Registering new address record for 127.0.0.1 on lo0.IPv4. Jan 1 00:00:43 f13 dbus-daemon[5309]: [system] Activating service name='org.freedesktop.ColorManager' requested by ':1.1' (uid=0 pid=5431 comm="/usr/local/sbin/cupsd -C /usr/local/etc/cups/cupsd") (using servicehelper) Jan 1 01:00:43 f13 kernel: Starting cupsd. Jan 1 00:00:43 f13 dbus-daemon[5309]: [system] Successfully activated service 'org.freedesktop.ColorManager' Jan 1 00:00:43 f13 sshd[5488]: Server listening on :: port 22. Jan 1 00:00:43 f13 sshd[5488]: Server listening on 0.0.0.0 port 22. Jan 1 01:00:43 f13 kernel: Configuring vt: keymap. Jan 1 01:00:43 f13 kernel: Performing sanity check on sshd configuration. Jan 1 01:00:43 f13 kernel: Starting sshd. Jan 1 01:00:43 f13 kernel: Performing sanity check on nginx configuration: Jan 1 01:00:43 f13 kernel: nginx: the configuration file /usr/local/etc/nginx/nginx.conf syntax is ok Jan 1 01:00:43 f13 kernel: nginx: configuration file /usr/local/etc/nginx/nginx.conf test is successful Jan 1 01:00:43 f13 kernel: Starting nginx. Jan 1 01:00:43 f13 kernel: Starting pcscd. Jan 1 01:00:44 f13 kernel: Starting cron. Jan 1 01:00:44 f13 kernel: Starting background file system checks in 60 seconds. Jan 1 01:00:44 f13 kernel: Jan 1 01:00:44 f13 kernel: Sun Jan 1 01:00:43 CET 2023 Jan 1 01:00:44 f13 avahi-daemon[5399]: Server startup complete. Host name is f13.local. Local service cookie is 3717259505. Jan 1 01:00:45 f13 avahi-daemon[5399]: Service "f13" (/usr/local/etc/avahi/services/ssh.service) successfully established. Jan 1 01:00:45 f13 avahi-daemon[5399]: Service "f13" (/usr/local/etc/avahi/services/sftp-ssh.service) successfully established. Jan 1 01:00:47 f13 wpa_supplicant[596]: wlan0: Authentication with 98:ba:5f:d7:71:b3 timed out. Jan 1 01:00:47 f13 wpa_supplicant[596]: wlan0: Added BSSID 98:ba:5f:d7:71:b3 into ignore list, ignoring for 10 seconds Jan 1 01:00:47 f13 wpa_supplicant[596]: TDLS: Remove peers on disassociation Jan 1 01:00:47 f13 wpa_supplicant[596]: wlan0: WPA: Clear old PMK and PTK Jan 1 01:00:47 f13 wpa_supplicant[596]: wlan0: Request to deauthenticate - bssid=00:00:00:00:00:00 pending_bssid=98:ba:5f:d7:71:b3 reason=3 (DEAUTH_LEAVING) state=ASSOCIATING valid_links=0x0 ap_mld_addr=00:00:00:00:00:00 Jan 1 01:00:47 f13 wpa_supplicant[596]: TDLS: Tear down peers Jan 1 01:00:47 f13 wpa_supplicant[596]: wlan0: Event DEAUTH (11) received Jan 1 01:00:47 f13 wpa_supplicant[596]: wlan0: Deauthentication notification Jan 1 01:00:47 f13 wpa_supplicant[596]: wlan0: * reason 3 (DEAUTH_LEAVING) locally_generated=1 Jan 1 01:00:47 f13 wpa_supplicant[596]: Deauthentication frame IE(s) - hexdump(len=0): [NULL] Jan 1 01:00:47 f13 wpa_supplicant[596]: wlan0: CTRL-EVENT-DISCONNECTED bssid=98:ba:5f:d7:71:b3 reason=3 locally_generated=1 Jan 1 01:00:47 f13 wpa_supplicant[596]: wlan0: Auto connect enabled: try to reconnect (wps=0/0 wpa_state=5) Jan 1 01:00:47 f13 wpa_supplicant[596]: wlan0: Setting scan request: 0.100000 sec Jan 1 01:00:47 f13 wpa_supplicant[596]: wlan0: Radio work 'connect'@0x2f658ee09680 done in 10.013751 seconds Jan 1 01:00:47 f13 wpa_supplicant[596]: wlan0: radio_work_free('connect'@0x2f658ee09680): num_active_works --> 0 Jan 1 01:00:47 f13 wpa_supplicant[596]: wlan0: BSSID 98:ba:5f:d7:71:b3 ignore list count incremented to 2, ignoring for 10 seconds Jan 1 01:00:47 f13 wpa_supplicant[596]: wlan0: Consecutive connection failures: 1 --> request scan in 100 ms Jan 1 01:00:47 f13 wpa_supplicant[596]: wlan0: Ignore new scan request for 0.100000 sec since an earlier request is scheduled to trigger sooner Jan 1 01:00:47 f13 wpa_supplicant[596]: TDLS: Remove peers on disassociation Jan 1 01:00:47 f13 wpa_supplicant[596]: wlan0: WPA: Clear old PMK and PTK Jan 1 01:00:47 f13 wpa_supplicant[596]: wlan0: Disconnect event - remove keys Jan 1 01:00:47 f13 wpa_supplicant[596]: wlan0: State: ASSOCIATING -> DISCONNECTED Jan 1 01:00:47 f13 wpa_supplicant[596]: EAPOL: External notification - portEnabled=0 Jan 1 01:00:47 f13 wpa_supplicant[596]: EAPOL: External notification - portValid=0 Jan 1 01:00:47 f13 wpa_supplicant[596]: EAPOL: External notification - EAP success=0 Jan 1 01:00:47 f13 wpa_supplicant[596]: wlan0: State: DISCONNECTED -> DISCONNECTED Jan 1 01:00:47 f13 wpa_supplicant[596]: EAPOL: External notification - portEnabled=0 Jan 1 01:00:47 f13 wpa_supplicant[596]: EAPOL: External notification - portValid=0 Jan 1 01:00:47 f13 wpa_supplicant[596]: QM: Clear all active DSCP policies Jan 1 01:00:47 f13 wpa_supplicant[596]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jan 1 01:00:47 f13 wpa_supplicant[596]: wlan0: Ignore new scan request for 1.000000 sec since an earlier request is scheduled to trigger sooner Jan 1 01:00:47 f13 wpa_supplicant[596]: wlan0: State: DISCONNECTED -> SCANNING Jan 1 01:00:47 f13 wpa_supplicant[596]: wlan0: Starting AP scan for wildcard SSID Jan 1 01:00:47 f13 wpa_supplicant[596]: wlan0: Add radio work 'scan'@0x2f658ee09680 Jan 1 01:00:47 f13 wpa_supplicant[596]: wlan0: First radio work item in the queue - schedule start immediately Jan 1 01:00:47 f13 wpa_supplicant[596]: wlan0: Starting radio work 'scan'@0x2f658ee09680 after 0.000019 second wait Jan 1 01:00:47 f13 wpa_supplicant[596]: wpa_driver_bsd_set_wpa: enabled=1 Jan 1 01:00:47 f13 wpa_supplicant[596]: wpa_driver_bsd_set_wpa_internal: wpa=3 privacy=1 Jan 1 01:00:47 f13 wpa_supplicant[596]: bsd_ctrl_iface: if wlan0 (no change) enable 1 IFF_UP 1 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: Event SCAN_RESULTS (3) received Jan 1 01:00:48 f13 wpa_supplicant[596]: Received 5908 bytes of scan results (15 BSSes) Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: BSS: Start scan result update 2 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: BSS: Add new id 16 BSSID ea:d2:ff:ab:83:0f SSID '' freq 5500 HESSID 50:6f:9a:00:11:22 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: BSS: Add new id 17 BSSID 9e:ba:5f:d7:71:b2 SSID '' freq 2457 Jan 1 01:00:48 f13 wpa_supplicant[596]: BSS: last_scan_res_used=15/32 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: New scan results available (own=0 ext=0) Jan 1 01:00:48 f13 wpa_supplicant[596]: WPS: Unknown Vendor Extension (Vendor ID 311) Jan 1 01:00:48 f13 syslogd: last message repeated 5 times Jan 1 01:00:48 f13 wpa_supplicant[596]: WPS: AP[0] 98:ba:5f:d7:71:b3 type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=2 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPS: AP[1] c8:d3:a3:67:87:4e type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPS: AP[2] 58:2f:f7:1d:9d:c7 type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPS: AP[3] e8:d2:ff:ab:81:0c type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPS: AP[4] 98:ba:5f:d7:71:b2 type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPS: AP[5] e8:d2:ff:ab:81:0d type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPS: AP[6] 8c:c5:b4:db:f4:f8 type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPS: AP[7] 8e:c5:b4:db:f5:fa type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPS: AP[8] 98:ba:5f:d7:56:8e type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPS: AP[9] d0:6e:de:91:51:5c type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPS: AP[10] e6:e7:49:b5:14:79 type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPS: AP[11] c8:d3:a3:67:87:50 type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: Radio work 'scan'@0x2f658ee09680 done in 1.247034 seconds Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: radio_work_free('scan'@0x2f658ee09680): num_active_works --> 0 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: Selecting BSS from priority group 10 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: 0: 98:ba:5f:d7:71:b3 ssid='Casa di Mau' wpa_ie_len=0 rsn_ie_len=24 caps=0x11 level=-59 freq=5240 wps Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: skip - BSSID ignored (count=2 limit=1) Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: 1: a2:ba:5f:d7:71:b3 ssid='Casa di Mau MLO' wpa_ie_len=0 rsn_ie_len=20 caps=0x11 level=-59 freq=5240 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: skip - SSID mismatch Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: 2: c8:d3:a3:67:87:4e ssid='dlink-874E' wpa_ie_len=26 rsn_ie_len=24 caps=0x11 level=-58 freq=2422 wps Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: skip - SSID mismatch Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: 3: 58:2f:f7:1d:9d:c7 ssid='Telia-1D9DC1' wpa_ie_len=0 rsn_ie_len=26 caps=0x11 level=-63 freq=5500 wps Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: skip - SSID mismatch Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: 4: ea:d2:ff:ab:83:0f ssid='' wpa_ie_len=0 rsn_ie_len=20 caps=0x11 level=-67 freq=5500 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: skip - SSID not known Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: 5: e8:d2:ff:ab:81:0d ssid='Telia-AB8107' wpa_ie_len=0 rsn_ie_len=20 caps=0x11 level=-68 freq=5500 wps Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: skip - SSID mismatch Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: 6: 9e:ba:5f:d7:71:b2 ssid='' wpa_ie_len=0 rsn_ie_len=20 caps=0x31 level=-67 freq=2457 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: skip - SSID not known Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: 7: 98:ba:5f:d7:71:b2 ssid='Casa di Mau' wpa_ie_len=0 rsn_ie_len=24 caps=0x31 level=-68 freq=2457 wps Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: skip - SSID mismatch Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: 8: e8:d2:ff:ab:81:0c ssid='Telia-AB8107' wpa_ie_len=0 rsn_ie_len=20 caps=0x11 level=-68 freq=2432 wps Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: skip - SSID mismatch Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: 9: 8c:c5:b4:db:f4:f8 ssid='Telia-DBF4F3' wpa_ie_len=0 rsn_ie_len=20 caps=0x11 level=-78 freq=2462 wps Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: skip - SSID mismatch Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: 10: 8e:c5:b4:db:f5:fa ssid='Telia-2G-DBF4F3' wpa_ie_len=0 rsn_ie_len=20 caps=0x11 level=-78 freq=2462 wps Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: skip - SSID mismatch Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: 11: 98:ba:5f:d7:56:8e ssid='Casa di Mau' wpa_ie_len=0 rsn_ie_len=24 caps=0x31 level=-83 freq=2417 wps Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: skip - SSID mismatch Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: 12: e6:e7:49:b5:14:79 ssid='DIRECT-79-HP OfficeJet Pro 9010' wpa_ie_len=0 rsn_ie_len=20 caps=0x31 level=-86 freq=2437 wps p2p Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: skip - SSID mismatch Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: 13: d0:6e:de:91:51:5c ssid='Get-2G-915157' wpa_ie_len=0 rsn_ie_len=20 caps=0x11 level=-90 freq=2447 wps Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: skip - SSID mismatch Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: 14: fa:8f:ca:6d:4b:a6 ssid='Stue.v,' wpa_ie_len=0 rsn_ie_len=0 caps=0x21 level=-81 freq=2437 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: skip - SSID mismatch Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: Selecting BSS from priority group 5 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: 0: 98:ba:5f:d7:71:b3 ssid='Casa di Mau' wpa_ie_len=0 rsn_ie_len=24 caps=0x11 level=-59 freq=5240 wps Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: skip - BSSID ignored (count=2 limit=1) Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: 1: a2:ba:5f:d7:71:b3 ssid='Casa di Mau MLO' wpa_ie_len=0 rsn_ie_len=20 caps=0x11 level=-59 freq=5240 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: skip - SSID mismatch Jan 1 01:00:48 f13 syslogd: last message repeated 4 times Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: 2: c8:d3:a3:67:87:4e ssid='dlink-874E' wpa_ie_len=26 rsn_ie_len=24 caps=0x11 level=-58 freq=2422 wps Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: skip - SSID mismatch Jan 1 01:00:48 f13 syslogd: last message repeated 4 times Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: 3: 58:2f:f7:1d:9d:c7 ssid='Telia-1D9DC1' wpa_ie_len=0 rsn_ie_len=26 caps=0x11 level=-63 freq=5500 wps Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: skip - SSID mismatch Jan 1 01:00:48 f13 syslogd: last message repeated 4 times Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: 4: ea:d2:ff:ab:83:0f ssid='' wpa_ie_len=0 rsn_ie_len=20 caps=0x11 level=-67 freq=5500 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: skip - SSID not known Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: 5: e8:d2:ff:ab:81:0d ssid='Telia-AB8107' wpa_ie_len=0 rsn_ie_len=20 caps=0x11 level=-68 freq=5500 wps Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: skip - SSID mismatch Jan 1 01:00:48 f13 syslogd: last message repeated 4 times Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: 6: 9e:ba:5f:d7:71:b2 ssid='' wpa_ie_len=0 rsn_ie_len=20 caps=0x31 level=-67 freq=2457 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: skip - SSID not known Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: 7: 98:ba:5f:d7:71:b2 ssid='Casa di Mau' wpa_ie_len=0 rsn_ie_len=24 caps=0x31 level=-68 freq=2457 wps Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: skip - BSSID configured to be ignored Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: skip - SSID mismatch Jan 1 01:00:48 f13 syslogd: last message repeated 9 times Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: 11: 98:ba:5f:d7:56:8e ssid='Casa di Mau' wpa_ie_len=0 rsn_ie_len=24 caps=0x31 level=-83 freq=2417 wps Jan 1 01:00:48 f13 wpa_supplicant[596]: IEEE 802.11 element parse ignored unknown element (id=7 elen=6) Jan 1 01:00:48 f13 wpa_supplicant[596]: IEEE 802.11 element parsing ignored unknown element extension (ext_id=39 elen=1) Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: selected BSS 98:ba:5f:d7:56:8e ssid='Casa di Mau' Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: Request association with 98:ba:5f:d7:56:8e Jan 1 01:00:48 f13 wpa_supplicant[596]: Ignored duplicated SSID element Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: No ongoing scan/p2p-scan found to abort Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: Starting radio work 'connect'@0x2f658ee09680 after 0.000027 second wait Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: WPA: clearing own WPA/RSN IE Jan 1 01:00:48 f13 wpa_supplicant[596]: RSN: Search for BSSID 98:ba:5f:d7:56:8e Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: WPA: Selected cipher suites: group 16 pairwise 16 key_mgmt 1026 proto 2 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: set AP RSN IE - hexdump(len=26): 30 18 01 00 00 0f ac 04 01 00 00 0f ac 04 02 00 00 0f ac 02 00 0f ac 08 8c 00 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: WPA: AP group 0x10 network profile group 0x18; available group 0x10 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: WPA: AP pairwise 0x10 network profile pairwise 0x18; available pairwise 0x10 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: WPA: AP key_mgmt 0x402 network profile key_mgmt 0x3; available key_mgmt 0x2 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: WPA: using KEY_MGMT WPA-PSK Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: WPA: AP mgmt_group_cipher 0x20 network profile mgmt_group_cipher 0x0; available mgmt_group_cipher 0x0 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: WPA: not using MGMT group cipher Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: Set PMK based on external data - hexdump(len=32): [REMOVED] Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: Trying to associate with 98:ba:5f:d7:56:8e (SSID='Casa di Mau' freq=2417 MHz) Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: Cancelling scan request Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: State: SCANNING -> ASSOCIATING Jan 1 01:00:48 f13 wpa_supplicant[596]: Limit connection to BSSID 98:ba:5f:d7:56:8e freq=2417 MHz based on scan results (bssid_set=0 wps=0) Jan 1 01:00:48 f13 wpa_supplicant[596]: PSK (set in config) - hexdump(len=32): [REMOVED] Jan 1 01:00:48 f13 wpa_supplicant[596]: bsd_set_opt_ie: set WPA+RSN ie (len 22) Jan 1 01:00:48 f13 wpa_supplicant[596]: bsd_ctrl_iface: if wlan0 (no change) enable 1 IFF_UP 1 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: Setting authentication timeout: 10 sec 0 usec Jan 1 01:00:48 f13 wpa_supplicant[596]: EAPOL: External notification - EAP success=0 Jan 1 01:00:48 f13 wpa_supplicant[596]: EAPOL: External notification - EAP fail=0 Jan 1 01:00:48 f13 wpa_supplicant[596]: EAPOL: External notification - portControl=Auto Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: RX EAPOL from 98:ba:5f:d7:56:8e (encrypted=-1) Jan 1 01:00:48 f13 wpa_supplicant[596]: RX EAPOL - hexdump(len=99): 02 03 00 5f 02 00 8a 00 10 00 00 00 00 00 00 00 01 43 55 c8 d2 aa a7 75 aa 4d 6b f2 e5 97 22 73 11 21 0b 3a a5 b6 60 64 c4 d5 15 8a 86 7e 8a 56 93 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: Not associated - Delay processing of received EAPOL frame (state=ASSOCIATING connected_addr=00:00:00:00:00:00) Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: Event ASSOC (0) received Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: State: ASSOCIATING -> ASSOCIATED Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: Associated to a new BSS: BSSID=98:ba:5f:d7:56:8e Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: Associated with 98:ba:5f:d7:56:8e Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: WPA: Association event - clear replay counter Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: WPA: Clear old PTK Jan 1 01:00:48 f13 wpa_supplicant[596]: TDLS: Remove peers on association Jan 1 01:00:48 f13 wpa_supplicant[596]: EAPOL: External notification - portEnabled=0 Jan 1 01:00:48 f13 wpa_supplicant[596]: EAPOL: External notification - portValid=0 Jan 1 01:00:48 f13 wpa_supplicant[596]: EAPOL: External notification - EAP success=0 Jan 1 01:00:48 f13 wpa_supplicant[596]: EAPOL: External notification - portEnabled=1 Jan 1 01:00:48 f13 wpa_supplicant[596]: EAPOL: SUPP_PAE entering state CONNECTING Jan 1 01:00:48 f13 wpa_supplicant[596]: EAPOL: enable timer tick Jan 1 01:00:48 f13 wpa_supplicant[596]: EAPOL: SUPP_BE entering state IDLE Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: Setting authentication timeout: 10 sec 0 usec Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: Cancelling scan request Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: Process pending EAPOL frame that was received just before association notification Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: RX EAPOL from 98:ba:5f:d7:56:8e (encrypted=-1) Jan 1 01:00:48 f13 wpa_supplicant[596]: RX EAPOL - hexdump(len=99): 02 03 00 5f 02 00 8a 00 10 00 00 00 00 00 00 00 01 43 55 c8 d2 aa a7 75 aa 4d 6b f2 e5 97 22 73 11 21 0b 3a a5 b6 60 64 c4 d5 15 8a 86 7e 8a 56 93 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: Setting authentication timeout: 10 sec 0 usec Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: IEEE 802.1X RX: version=2 type=3 length=95 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: RX EAPOL-Key - hexdump(len=99): 02 03 00 5f 02 00 8a 00 10 00 00 00 00 00 00 00 01 43 55 c8 d2 aa a7 75 aa 4d 6b f2 e5 97 22 73 11 21 0b 3a a5 b6 60 64 c4 d5 15 8a 86 7e 8a 56 93 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: EAPOL-Key type=2 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: key_info 0x8a (ver=2 keyidx=0 rsvd=0 Pairwise Ack) Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: key_length=16 key_data_length=0 Jan 1 01:00:48 f13 wpa_supplicant[596]: replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 01 Jan 1 01:00:48 f13 wpa_supplicant[596]: key_nonce - hexdump(len=32): 43 55 c8 d2 aa a7 75 aa 4d 6b f2 e5 97 22 73 11 21 0b 3a a5 b6 60 64 c4 d5 15 8a 86 7e 8a 56 93 Jan 1 01:00:48 f13 wpa_supplicant[596]: key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Jan 1 01:00:48 f13 wpa_supplicant[596]: key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00 Jan 1 01:00:48 f13 wpa_supplicant[596]: key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00 Jan 1 01:00:48 f13 wpa_supplicant[596]: key_mic - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: WPA: RX message 1 of 4-Way Handshake from 98:ba:5f:d7:56:8e (ver=2) Jan 1 01:00:48 f13 wpa_supplicant[596]: RSN: msg 1/4 key data - hexdump(len=0): Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: State: ASSOCIATED -> 4WAY_HANDSHAKE Jan 1 01:00:48 f13 wpa_supplicant[596]: Get randomness: len=32 entropy=21 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: Renewed SNonce - hexdump(len=32): a1 c6 89 2f a6 bc d1 10 a9 fd 2f df 86 ed 52 bd ba 0d 5a 99 9b f3 d8 ec 93 de 1f 74 6c f1 d6 41 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: PTK derivation using PRF(SHA1) Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: PTK derivation - A1=8c:b8:7e:c9:89:57 A2=98:ba:5f:d7:56:8e Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: Nonce1 - hexdump(len=32): a1 c6 89 2f a6 bc d1 10 a9 fd 2f df 86 ed 52 bd ba 0d 5a 99 9b f3 d8 ec 93 de 1f 74 6c f1 d6 41 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: Nonce2 - hexdump(len=32): 43 55 c8 d2 aa a7 75 aa 4d 6b f2 e5 97 22 73 11 21 0b 3a a5 b6 60 64 c4 d5 15 8a 86 7e 8a 56 93 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: PMK - hexdump(len=32): [REMOVED] Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: PTK - hexdump(len=48): [REMOVED] Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: KCK - hexdump(len=16): [REMOVED] Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: KEK - hexdump(len=16): [REMOVED] Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: TK - hexdump(len=16): [REMOVED] Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: WPA IE for msg 2/4 - hexdump(len=22): 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 0c 00 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: Replay Counter - hexdump(len=8): 00 00 00 00 00 00 00 01 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: WPA: Sending EAPOL-Key 2/4 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: Send EAPOL-Key frame to 98:ba:5f:d7:56:8e ver=2 mic_len=16 key_mgmt=0x2 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: EAPOL-Key MIC using HMAC-SHA1 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: KCK - hexdump(len=16): [REMOVED] Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: Derived Key MIC - hexdump(len=16): 0e eb 93 0a 44 65 5e e5 ed 4a 3f d1 b9 51 67 b8 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: TX EAPOL-Key - hexdump(len=121): 01 03 00 75 02 01 0a 00 00 00 00 00 00 00 00 00 01 a1 c6 89 2f a6 bc d1 10 a9 fd 2f df 86 ed 52 bd ba 0d 5a 99 9b f3 d8 ec 93 de 1f 74 6c f1 d6 41 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 0e eb 93 0a 44 65 5e e5 ed 4a 3f d1 b9 51 67 b8 00 16 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 0c 00 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: RX EAPOL from 98:ba:5f:d7:56:8e (encrypted=-1) Jan 1 01:00:48 f13 wpa_supplicant[596]: RX EAPOL - hexdump(len=163): 02 03 00 9f 02 13 ca 00 10 00 00 00 00 00 00 00 02 43 55 c8 d2 aa a7 75 aa 4d 6b f2 e5 97 22 73 11 21 0b 3a a5 b6 60 64 c4 d5 15 8a 86 7e 8a 56 93 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 73 97 7b bf e3 1f 51 bf 99 e0 06 cb ba d6 fa 39 00 40 e5 90 14 ab d5 26 cd 9f 53 70 7f a9 f9 9f 71 f9 d0 30 ed 80 70 2f 72 43 00 c8 0d cc 24 a7 d2 95 f9 1d 5c d5 2e 6b 80 16 ae 1a 08 d7 2d 4b 61 f5 2f 76 30 50 d8 2a f6 8d 1a 94 a0 cc c8 b0 cc 87 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: IEEE 802.1X RX: version=2 type=3 length=159 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: RX EAPOL-Key - hexdump(len=163): 02 03 00 9f 02 13 ca 00 10 00 00 00 00 00 00 00 02 43 55 c8 d2 aa a7 75 aa 4d 6b f2 e5 97 22 73 11 21 0b 3a a5 b6 60 64 c4 d5 15 8a 86 7e 8a 56 93 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 73 97 7b bf e3 1f 51 bf 99 e0 06 cb ba d6 fa 39 00 40 e5 90 14 ab d5 26 cd 9f 53 70 7f a9 f9 9f 71 f9 d0 30 ed 80 70 2f 72 43 00 c8 0d cc 24 a7 d2 95 f9 1d 5c d5 2e 6b 80 16 ae 1a 08 d7 2d 4b 61 f5 2f 76 30 50 d8 2a f6 8d 1a 94 a0 cc c8 b0 cc 87 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: EAPOL-Key type=2 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: key_info 0x13ca (ver=2 keyidx=0 rsvd=0 Pairwise Install Ack MIC Secure Encr) Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: key_length=16 key_data_length=64 Jan 1 01:00:48 f13 wpa_supplicant[596]: replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 02 Jan 1 01:00:48 f13 wpa_supplicant[596]: key_nonce - hexdump(len=32): 43 55 c8 d2 aa a7 75 aa 4d 6b f2 e5 97 22 73 11 21 0b 3a a5 b6 60 64 c4 d5 15 8a 86 7e 8a 56 93 Jan 1 01:00:48 f13 wpa_supplicant[596]: key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Jan 1 01:00:48 f13 wpa_supplicant[596]: key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00 Jan 1 01:00:48 f13 wpa_supplicant[596]: key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00 Jan 1 01:00:48 f13 wpa_supplicant[596]: key_mic - hexdump(len=16): 73 97 7b bf e3 1f 51 bf 99 e0 06 cb ba d6 fa 39 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: EAPOL-Key MIC using HMAC-SHA1 Jan 1 01:00:48 f13 wpa_supplicant[596]: RSN: encrypted key data - hexdump(len=64): e5 90 14 ab d5 26 cd 9f 53 70 7f a9 f9 9f 71 f9 d0 30 ed 80 70 2f 72 43 00 c8 0d cc 24 a7 d2 95 f9 1d 5c d5 2e 6b 80 16 ae 1a 08 d7 2d 4b 61 f5 2f 76 30 50 d8 2a f6 8d 1a 94 a0 cc c8 b0 cc 87 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: Decrypt Key Data using AES-UNWRAP (KEK length 16) Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: decrypted EAPOL-Key key data - hexdump(len=56): [REMOVED] Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: State: 4WAY_HANDSHAKE -> 4WAY_HANDSHAKE Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: RSN: RX message 3 of 4-Way Handshake from 98:ba:5f:d7:56:8e (ver=2) Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: IE KeyData - hexdump(len=56): 30 18 01 00 00 0f ac 04 01 00 00 0f ac 04 02 00 00 0f ac 02 00 0f ac 08 8c 00 f4 01 20 dd 16 00 0f ac 01 01 00 f8 0e 0b 15 2c 1a 8f b7 93 f4 0b ce 03 3b 4c d7 dd 00 00 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: RSN IE in EAPOL-Key - hexdump(len=26): 30 18 01 00 00 0f ac 04 01 00 00 0f ac 04 02 00 00 0f ac 02 00 0f ac 08 8c 00 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: RSNXE in EAPOL-Key - hexdump(len=3): f4 01 20 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: GTK in EAPOL-Key - hexdump(len=24): [REMOVED] Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: WPA: Sending EAPOL-Key 4/4 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: Send EAPOL-Key frame to 98:ba:5f:d7:56:8e ver=2 mic_len=16 key_mgmt=0x2 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: EAPOL-Key MIC using HMAC-SHA1 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: KCK - hexdump(len=16): [REMOVED] Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: Derived Key MIC - hexdump(len=16): b5 48 e6 2f 8f a9 7c f0 19 7e 04 ee 7d 92 a2 01 Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: TX EAPOL-Key - hexdump(len=99): 01 03 00 5f 02 03 0a 00 00 00 00 00 00 00 00 00 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 b5 48 e6 2f 8f a9 7c f0 19 7e 04 ee 7d 92 a2 01 00 00 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: WPA: Installing PTK to the driver Jan 1 01:00:48 f13 wpa_supplicant[596]: bsd_set_key: alg=3 addr=0x2f658eedb570 key_idx=0 set_tx=1 seq_len=6 key_len=16 Jan 1 01:00:48 f13 wpa_supplicant[596]: Added PTKSA cache entry addr=98:ba:5f:d7:56:8e cipher=16 Jan 1 01:00:48 f13 wpa_supplicant[596]: EAPOL: External notification - portValid=1 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: State: 4WAY_HANDSHAKE -> GROUP_HANDSHAKE Jan 1 01:00:48 f13 wpa_supplicant[596]: RSN: received GTK in pairwise handshake - hexdump(len=18): [REMOVED] Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: Group Key - hexdump(len=16): [REMOVED] Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: WPA: Installing GTK to the driver (keyidx=1 tx=0 len=16) Jan 1 01:00:48 f13 wpa_supplicant[596]: WPA: RSC - hexdump(len=6): 00 00 00 00 00 00 Jan 1 01:00:48 f13 wpa_supplicant[596]: bsd_set_key: alg=3 addr=0x18929df7bb4a key_idx=1 set_tx=0 seq_len=6 key_len=16 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: WPA: Key negotiation completed with 98:ba:5f:d7:56:8e [PTK=CCMP GTK=CCMP] Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: Cancelling authentication timeout Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: State: GROUP_HANDSHAKE -> COMPLETED Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: Radio work 'connect'@0x2f658ee09680 done in 0.124962 seconds Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: radio_work_free('connect'@0x2f658ee09680): num_active_works --> 0 Jan 1 01:00:48 f13 wpa_supplicant[596]: wlan0: CTRL-EVENT-CONNECTED - Connection to 98:ba:5f:d7:56:8e completed [id=0 id_str=] Jan 1 01:00:48 f13 wpa_supplicant[596]: EAPOL: External notification - portValid=1 Jan 1 01:00:48 f13 wpa_supplicant[596]: EAPOL: External notification - EAP success=1 Jan 1 01:00:48 f13 wpa_supplicant[596]: EAPOL: SUPP_PAE entering state AUTHENTICATING Jan 1 01:00:48 f13 wpa_supplicant[596]: EAPOL: SUPP_BE entering state SUCCESS Jan 1 01:00:48 f13 wpa_supplicant[596]: EAP: EAP entering state DISABLED Jan 1 01:00:48 f13 wpa_supplicant[596]: EAPOL: SUPP_PAE entering state AUTHENTICATED Jan 1 01:00:48 f13 wpa_supplicant[596]: EAPOL: Supplicant port status: Authorized Jan 1 01:00:48 f13 wpa_supplicant[596]: EAPOL: SUPP_BE entering state IDLE Jan 1 01:00:48 f13 wpa_supplicant[596]: EAPOL authentication completed - result=SUCCESS Jan 1 01:00:48 f13 dhclient[5636]: DHCPREQUEST on wlan0 to 255.255.255.255 port 67 Jan 1 01:00:48 f13 kernel: wlan0: ieee80211_sta_join: BSS 98:ba:5f:d7:56:8e: 2GHz channel, VHT info; ignoring Jan 1 01:00:48 f13 kernel: wlan0: link state changed to UP Jan 1 01:00:50 f13 wpa_supplicant[596]: EAPOL: startWhen --> 0 Jan 1 01:00:50 f13 wpa_supplicant[596]: EAPOL: disable timer tick Jan 1 01:00:55 f13 dhclient[5636]: DHCPREQUEST on wlan0 to 255.255.255.255 port 67 Jan 1 01:00:55 f13 dhclient[5636]: DHCPACK from 192.168.20.1 Jan 1 01:00:55 f13 dhclient[5646]: New IP Address (wlan0): 192.168.20.10 Jan 1 01:00:55 f13 dhclient[5650]: New Subnet Mask (wlan0): 255.255.255.0 Jan 1 01:00:55 f13 dhclient[5654]: New Broadcast Address (wlan0): 192.168.20.255 Jan 1 01:00:55 f13 dhclient[5658]: New Routers (wlan0): 192.168.20.1 Jan 1 01:00:55 f13 dhclient[5636]: bound to 192.168.20.10 -- renewal in 3600 seconds. Jan 1 01:01:00 f13 /usr/sbin/cron[5704]: (root) CMD (adjkerntz -a) Jan 1 01:01:00 f13 /usr/sbin/cron[5705]: (root) CMD (periodic hourly) Jan 1 01:01:26 f13 login[5585]: login on ttyv2 as root Jan 1 01:01:26 f13 login[5585]: ROOT LOGIN (root) ON ttyv2 Jan 1 01:01:26 f13 kernel: Jan 1 01:01:26 f13 login[5585]: ROOT LOGIN (root) ON ttyv2 Jan 1 01:02:00 f13 /usr/sbin/cron[6226]: (root) CMD (/usr/local/sbin/battery-shutdown.sh > /var/log/battery-shutdown 2>&1) Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: Removing interface wlan0 Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: Request to deauthenticate - bssid=98:ba:5f:d7:56:8e pending_bssid=00:00:00:00:00:00 reason=3 (DEAUTH_LEAVING) state=COMPLETED valid_links=0x0 ap_mld_addr=00:00:00:00:00:00 Jan 1 01:02:16 f13 wpa_supplicant[596]: TDLS: Tear down peers Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: Event DEAUTH (11) received Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: Deauthentication notification Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: * reason 3 (DEAUTH_LEAVING) locally_generated=1 Jan 1 01:02:16 f13 wpa_supplicant[596]: Deauthentication frame IE(s) - hexdump(len=0): [NULL] Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: CTRL-EVENT-DISCONNECTED bssid=98:ba:5f:d7:56:8e reason=3 locally_generated=1 Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: Auto connect disabled: do not try to re-connect Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: Ignore connection failure indication since interface has been put into disconnected state Jan 1 01:02:16 f13 wpa_supplicant[596]: TDLS: Remove peers on disassociation Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: WPA: Clear old PMK and PTK Jan 1 01:02:16 f13 wpa_supplicant[596]: Flush PTKSA cache entry for 98:ba:5f:d7:56:8e Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: Disconnect event - remove keys Jan 1 01:02:16 f13 wpa_supplicant[596]: bsd_set_key: alg=0 addr=0x0 key_idx=0 set_tx=0 seq_len=0 key_len=0 Jan 1 01:02:16 f13 wpa_supplicant[596]: bsd_del_key: key_idx=0 Jan 1 01:02:16 f13 wpa_supplicant[596]: bsd_set_key: alg=0 addr=0x0 key_idx=1 set_tx=0 seq_len=0 key_len=0 Jan 1 01:02:16 f13 wpa_supplicant[596]: bsd_del_key: key_idx=1 Jan 1 01:02:16 f13 wpa_supplicant[596]: bsd_set_key: alg=0 addr=0x2f658ee2d140 key_idx=0 set_tx=0 seq_len=0 key_len=0 Jan 1 01:02:16 f13 wpa_supplicant[596]: bsd_del_key: addr=98:ba:5f:d7:56:8e Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: State: COMPLETED -> DISCONNECTED Jan 1 01:02:16 f13 wpa_supplicant[596]: EAPOL: External notification - portEnabled=0 Jan 1 01:02:16 f13 wpa_supplicant[596]: EAPOL: SUPP_PAE entering state DISCONNECTED Jan 1 01:02:16 f13 wpa_supplicant[596]: EAPOL: Supplicant port status: Unauthorized Jan 1 01:02:16 f13 wpa_supplicant[596]: EAPOL: SUPP_BE entering state INITIALIZE Jan 1 01:02:16 f13 dhclient[5636]: wlan0 link state up -> down Jan 1 01:02:16 f13 wpa_supplicant[596]: EAPOL: External notification - portValid=0 Jan 1 01:02:16 f13 wpa_supplicant[596]: EAPOL: External notification - EAP success=0 Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: State: DISCONNECTED -> DISCONNECTED Jan 1 01:02:16 f13 wpa_supplicant[596]: EAPOL: External notification - portEnabled=0 Jan 1 01:02:16 f13 wpa_supplicant[596]: EAPOL: External notification - portValid=0 Jan 1 01:02:16 f13 wpa_supplicant[596]: QM: Clear all active DSCP policies Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jan 1 01:02:16 f13 wpa_supplicant[596]: wpa_driver_bsd_set_countermeasures: enabled=0 Jan 1 01:02:16 f13 wpa_supplicant[596]: PTKSA: Deinit. n_ptksa=0 Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: WPA: Clear old PMK and PTK Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: Removed BSSID 98:ba:5f:d7:71:b3 from ignore list (clear) Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: BSS: Remove id 2 BSSID 9e:ba:5f:d7:71:b3 SSID '' due to wpa_bss_flush Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: BSS: Remove id 11 BSSID 9e:ba:5f:d7:56:8e SSID '' due to wpa_bss_flush Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: BSS: Remove id 14 BSSID c8:d3:a3:67:87:50 SSID 'dlink-874E-media' due to wpa_bss_flush Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: BSS: Remove id 0 BSSID 98:ba:5f:d7:71:b3 SSID 'Casa di Mau' due to wpa_bss_flush Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: BSS: Remove id 1 BSSID a2:ba:5f:d7:71:b3 SSID 'Casa di Mau MLO' due to wpa_bss_flush Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: BSS: Remove id 3 BSSID c8:d3:a3:67:87:4e SSID 'dlink-874E' due to wpa_bss_flush Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: BSS: Remove id 4 BSSID 58:2f:f7:1d:9d:c7 SSID 'Telia-1D9DC1' due to wpa_bss_flush Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: BSS: Remove id 16 BSSID ea:d2:ff:ab:83:0f SSID '' due to wpa_bss_flush Jan 1 01:02:16 f13 dhclient[5636]: Interface wlan0 is down, dhclient exiting Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: BSS: Remove id 7 BSSID e8:d2:ff:ab:81:0d SSID 'Telia-AB8107' due to wpa_bss_flush Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: BSS: Remove id 17 BSSID 9e:ba:5f:d7:71:b2 SSID '' due to wpa_bss_flush Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: BSS: Remove id 6 BSSID 98:ba:5f:d7:71:b2 SSID 'Casa di Mau' due to wpa_bss_flush Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: BSS: Remove id 5 BSSID e8:d2:ff:ab:81:0c SSID 'Telia-AB8107' due to wpa_bss_flush Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: BSS: Remove id 8 BSSID 8c:c5:b4:db:f4:f8 SSID 'Telia-DBF4F3' due to wpa_bss_flush Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: BSS: Remove id 9 BSSID 8e:c5:b4:db:f5:fa SSID 'Telia-2G-DBF4F3' due to wpa_bss_flush Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: BSS: Remove id 10 BSSID 98:ba:5f:d7:56:8e SSID 'Casa di Mau' due to wpa_bss_flush Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: BSS: Remove id 13 BSSID e6:e7:49:b5:14:79 SSID 'DIRECT-79-HP OfficeJet Pro 9010' due to wpa_bss_flush Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: BSS: Remove id 12 BSSID d0:6e:de:91:51:5c SSID 'Get-2G-915157' due to wpa_bss_flush Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: BSS: Remove id 15 BSSID fa:8f:ca:6d:4b:a6 SSID 'Stue.v,' due to wpa_bss_flush Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: Cancelling scan request Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: Cancelling authentication timeout Jan 1 01:02:16 f13 wpa_supplicant[596]: HS20: Delete all stored icons Jan 1 01:02:16 f13 wpa_supplicant[596]: QM: Clear all active DSCP policies Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jan 1 01:02:16 f13 wpa_supplicant[596]: Remove interface wlan0 from radio Jan 1 01:02:16 f13 wpa_supplicant[596]: Remove radio Jan 1 01:02:16 f13 wpa_supplicant[596]: wpa_driver_bsd_set_wpa: enabled=0 Jan 1 01:02:16 f13 wpa_supplicant[596]: wpa_driver_bsd_set_wpa_internal: wpa=0 privacy=0 Jan 1 01:02:16 f13 wpa_supplicant[596]: bsd_set_opt_ie: set WPA+RSN ie (len 0) Jan 1 01:02:16 f13 wpa_supplicant[596]: bsd_ctrl_iface: if wlan0 (changed) enable 0 IFF_UP 0 Jan 1 01:02:16 f13 wpa_supplicant[596]: wpa_driver_bsd_set_wpa_internal: wpa=0 privacy=0 Jan 1 01:02:16 f13 wpa_supplicant[596]: bsd_set_opt_ie: set WPA+RSN ie (len 0) Jan 1 01:02:16 f13 wpa_supplicant[596]: wlan0: CTRL-EVENT-TERMINATING Jan 1 01:02:16 f13 wpa_supplicant[596]: Control interface directory not empty - leaving it behind Jan 1 01:02:16 f13 dhclient[5636]: connection closed Jan 1 01:02:16 f13 dhclient[5636]: exiting. Jan 1 01:02:16 f13 kernel: wlan0: link state changed to DOWN Jan 1 01:02:16 f13 kernel: Jan 1 01:02:16 f13 dhclient[5636]: Interface wlan0 is down, dhclient exiting Jan 1 01:02:16 f13 kernel: vm-public: link state changed to DOWN Jan 1 01:02:16 f13 kernel: Jan 1 01:02:16 f13 dhclient[5636]: connection closed Jan 1 01:02:16 f13 kernel: Jan 1 01:02:16 f13 dhclient[5636]: exiting. Jan 1 01:02:16 f13 kernel: iwlwifi0: WFPM_UMAC_PD_NOTIFICATION: 0x20 Jan 1 01:02:16 f13 kernel: iwlwifi0: WFPM_LMAC2_PD_NOTIFICATION: 0x1f Jan 1 01:02:16 f13 kernel: iwlwifi0: WFPM_AUTH_KEY_0: 0x90 Jan 1 01:02:16 f13 kernel: iwlwifi0: CNVI_SCU_SEQ_DATA_DW9: 0x0 Jan 1 01:02:16 f13 kernel: wlan0: Ethernet address: 8c:b8:7e:c9:89:57 Jan 1 01:02:16 f13 wpa_supplicant[6500]: wpa_supplicant v2.11 Jan 1 01:02:16 f13 wpa_supplicant[6500]: Successfully initialized wpa_supplicant Jan 1 01:02:16 f13 wpa_supplicant[6500]: Initializing interface 'wlan0' conf '/etc/wpa_supplicant.conf' driver 'bsd' ctrl_interface 'N/A' bridge 'N/A' Jan 1 01:02:16 f13 wpa_supplicant[6500]: Configuration file '/etc/wpa_supplicant.conf' -> '/etc/wpa_supplicant.conf' Jan 1 01:02:16 f13 wpa_supplicant[6500]: Reading configuration file '/etc/wpa_supplicant.conf' Jan 1 01:02:16 f13 wpa_supplicant[6500]: ctrl_interface='/var/run/wpa_supplicant' Jan 1 01:02:16 f13 wpa_supplicant[6500]: Line: 6 - start of a new network block Jan 1 01:02:16 f13 wpa_supplicant[6500]: ssid - hexdump(len=11): 43 61 73 61 20 64 69 20 4d 61 75 Jan 1 01:02:16 f13 wpa_supplicant[6500]: PSK (ASCII passphrase) - hexdump(len=8): [REMOVED] Jan 1 01:02:16 f13 wpa_supplicant[6500]: priority=5 (0x5) Jan 1 01:02:16 f13 wpa_supplicant[6500]: bssid_ignore: addr=f0:9f:c2:03:c7:51 mask=ff:ff:ff:ff:ff:ff Jan 1 01:02:16 f13 wpa_supplicant[6500]: bssid_ignore: addr=f0:9f:c2:ad:40:6e mask=ff:ff:ff:ff:ff:ff Jan 1 01:02:16 f13 wpa_supplicant[6500]: bssid_ignore: addr=98:ba:5f:d7:71:b2 mask=ff:ff:ff:ff:ff:ff Jan 1 01:02:16 f13 wpa_supplicant[6500]: frequency=5240 (0x1478) Jan 1 01:02:16 f13 wpa_supplicant[6500]: PSK (from passphrase) - hexdump(len=32): [REMOVED] Jan 1 01:02:16 f13 wpa_supplicant[6500]: Line: 15 - start of a new network block Jan 1 01:02:16 f13 wpa_supplicant[6500]: ssid - hexdump(len=3): 59 54 34 Jan 1 01:02:16 f13 wpa_supplicant[6500]: PSK (ASCII passphrase) - hexdump(len=10): [REMOVED] Jan 1 01:02:16 f13 wpa_supplicant[6500]: priority=5 (0x5) Jan 1 01:02:16 f13 wpa_supplicant[6500]: bssid_ignore: addr=82:8a:20:0f:71:c3 mask=ff:ff:ff:ff:ff:ff Jan 1 01:02:16 f13 wpa_supplicant[6500]: bssid_ignore: addr=7e:8a:20:0f:59:17 mask=ff:ff:ff:ff:ff:ff Jan 1 01:02:16 f13 wpa_supplicant[6500]: frequency=5240 (0x1478) Jan 1 01:02:16 f13 wpa_supplicant[6500]: PSK (from passphrase) - hexdump(len=32): [REMOVED] Jan 1 01:02:16 f13 wpa_supplicant[6500]: Line: 24 - start of a new network block Jan 1 01:02:16 f13 wpa_supplicant[6500]: ssid - hexdump(len=10): 4d 69 64 69 53 61 75 72 6f 6e Jan 1 01:02:16 f13 wpa_supplicant[6500]: PSK (ASCII passphrase) - hexdump(len=8): [REMOVED] Jan 1 01:02:16 f13 wpa_supplicant[6500]: priority=10 (0xa) Jan 1 01:02:16 f13 wpa_supplicant[6500]: PSK (from passphrase) - hexdump(len=32): [REMOVED] Jan 1 01:02:16 f13 wpa_supplicant[6500]: Line: 31 - start of a new network block Jan 1 01:02:16 f13 wpa_supplicant[6500]: ssid - hexdump(len=11): 50 61 72 6b 76 65 69 65 6e 35 38 Jan 1 01:02:16 f13 wpa_supplicant[6500]: PSK (ASCII passphrase) - hexdump(len=8): [REMOVED] Jan 1 01:02:16 f13 wpa_supplicant[6500]: priority=5 (0x5) Jan 1 01:02:16 f13 wpa_supplicant[6500]: PSK (from passphrase) - hexdump(len=32): [REMOVED] Jan 1 01:02:16 f13 wpa_supplicant[6500]: Line: 38 - start of a new network block Jan 1 01:02:16 f13 wpa_supplicant[6500]: ssid - hexdump(len=4): 48 38 36 42 Jan 1 01:02:16 f13 wpa_supplicant[6500]: PSK (ASCII passphrase) - hexdump(len=8): [REMOVED] Jan 1 01:02:16 f13 wpa_supplicant[6500]: priority=5 (0x5) Jan 1 01:02:16 f13 wpa_supplicant[6500]: PSK (from passphrase) - hexdump(len=32): [REMOVED] Jan 1 01:02:16 f13 wpa_supplicant[6500]: Line: 45 - start of a new network block Jan 1 01:02:16 f13 wpa_supplicant[6500]: ssid - hexdump(len=17): 62 6c 61 63 6b 76 61 6c 6c 65 79 2e 70 61 72 74 79 Jan 1 01:02:16 f13 wpa_supplicant[6500]: PSK (ASCII passphrase) - hexdump(len=13): [REMOVED] Jan 1 01:02:16 f13 wpa_supplicant[6500]: priority=5 (0x5) Jan 1 01:02:16 f13 wpa_supplicant[6500]: PSK (from passphrase) - hexdump(len=32): [REMOVED] Jan 1 01:02:16 f13 wpa_supplicant[6500]: Priority group 10 Jan 1 01:02:16 f13 wpa_supplicant[6500]: id=2 ssid='MidiSauron' Jan 1 01:02:16 f13 wpa_supplicant[6500]: Priority group 5 Jan 1 01:02:16 f13 wpa_supplicant[6500]: id=0 ssid='Casa di Mau' Jan 1 01:02:16 f13 wpa_supplicant[6500]: id=1 ssid='YT4' Jan 1 01:02:16 f13 wpa_supplicant[6500]: id=3 ssid='Parkveien58' Jan 1 01:02:16 f13 wpa_supplicant[6500]: id=4 ssid='H86B' Jan 1 01:02:16 f13 wpa_supplicant[6500]: id=5 ssid='blackvalley.party' Jan 1 01:02:16 f13 wpa_supplicant[6500]: wpa_driver_bsd_capa: drivercaps=0x0581c001,cryptocaps=0x0000100b Jan 1 01:02:16 f13 wpa_supplicant[6500]: bsd_ctrl_iface: if wlan0 (no change) enable 0 IFF_UP 0 Jan 1 01:02:16 f13 wpa_supplicant[6500]: Add interface wlan0 to a new radio N/A Jan 1 01:02:16 f13 wpa_supplicant[6500]: PTKSA: Initializing Jan 1 01:02:16 f13 wpa_supplicant[6500]: wlan0: Failed to attach pkt_type filter Jan 1 01:02:16 f13 wpa_supplicant[6500]: wlan0: Own MAC address: 8c:b8:7e:c9:89:57 Jan 1 01:02:16 f13 wpa_supplicant[6500]: bsd_set_key: alg=0 addr=0x0 key_idx=0 set_tx=0 seq_len=0 key_len=0 Jan 1 01:02:16 f13 wpa_supplicant[6500]: bsd_del_key: key_idx=0 Jan 1 01:02:16 f13 wpa_supplicant[6500]: bsd_set_key: alg=0 addr=0x0 key_idx=1 set_tx=0 seq_len=0 key_len=0 Jan 1 01:02:16 f13 wpa_supplicant[6500]: bsd_del_key: key_idx=1 Jan 1 01:02:16 f13 wpa_supplicant[6500]: bsd_set_key: alg=0 addr=0x0 key_idx=2 set_tx=0 seq_len=0 key_len=0 Jan 1 01:02:16 f13 wpa_supplicant[6500]: bsd_del_key: key_idx=2 Jan 1 01:02:16 f13 wpa_supplicant[6500]: bsd_set_key: alg=0 addr=0x0 key_idx=3 set_tx=0 seq_len=0 key_len=0 Jan 1 01:02:16 f13 wpa_supplicant[6500]: bsd_del_key: key_idx=3 Jan 1 01:02:16 f13 wpa_supplicant[6500]: bsd_set_key: alg=0 addr=0x0 key_idx=4 set_tx=0 seq_len=0 key_len=0 Jan 1 01:02:16 f13 wpa_supplicant[6500]: bsd_del_key: key_idx=4 Jan 1 01:02:16 f13 wpa_supplicant[6500]: ioctl[SIOCS80211, op=20, val=0, arg_len=7]: Invalid argument Jan 1 01:02:16 f13 wpa_supplicant[6500]: bsd_set_key: alg=0 addr=0x0 key_idx=5 set_tx=0 seq_len=0 key_len=0 Jan 1 01:02:16 f13 wpa_supplicant[6500]: bsd_del_key: key_idx=5 Jan 1 01:02:16 f13 wpa_supplicant[6500]: ioctl[SIOCS80211, op=20, val=0, arg_len=7]: Invalid argument Jan 1 01:02:16 f13 wpa_supplicant[6500]: wpa_driver_bsd_set_countermeasures: enabled=0 Jan 1 01:02:16 f13 wpa_supplicant[6500]: wlan0: RSN: flushing PMKID list in the driver Jan 1 01:02:16 f13 wpa_supplicant[6500]: wlan0: Setting scan request: 0.100000 sec Jan 1 01:02:16 f13 wpa_supplicant[6500]: TDLS: TDLS operation not supported by driver Jan 1 01:02:16 f13 wpa_supplicant[6500]: TDLS: Driver uses internal link setup Jan 1 01:02:16 f13 wpa_supplicant[6500]: TDLS: Driver does not support TDLS channel switching Jan 1 01:02:16 f13 wpa_supplicant[6500]: wlan0: WPS: UUID based on MAC address: 37b6bb61-9e74-5760-b6c2-515f86b14ecb Jan 1 01:02:16 f13 wpa_supplicant[6500]: ENGINE: Loading builtin engines Jan 1 01:02:16 f13 syslogd: last message repeated 1 times Jan 1 01:02:16 f13 wpa_supplicant[6500]: EAPOL: SUPP_PAE entering state DISCONNECTED Jan 1 01:02:16 f13 wpa_supplicant[6500]: EAPOL: Supplicant port status: Unauthorized Jan 1 01:02:16 f13 wpa_supplicant[6500]: EAPOL: KEY_RX entering state NO_KEY_RECEIVE Jan 1 01:02:16 f13 wpa_supplicant[6500]: EAPOL: SUPP_BE entering state INITIALIZE Jan 1 01:02:16 f13 wpa_supplicant[6500]: EAP: EAP entering state DISABLED Jan 1 01:02:16 f13 wpa_supplicant[6500]: Using existing control interface directory. Jan 1 01:02:16 f13 wpa_supplicant[6500]: MBO: Update non-preferred channels, non_pref_chan=N/A Jan 1 01:02:16 f13 wpa_supplicant[6500]: wlan0: Added interface wlan0 Jan 1 01:02:16 f13 wpa_supplicant[6500]: wlan0: State: DISCONNECTED -> DISCONNECTED Jan 1 01:02:16 f13 wpa_supplicant[6500]: Daemonize.. Jan 1 01:02:16 f13 wpa_supplicant[6501]: wlan0: State: DISCONNECTED -> SCANNING Jan 1 01:02:16 f13 wpa_supplicant[6501]: wlan0: Starting AP scan for wildcard SSID Jan 1 01:02:16 f13 wpa_supplicant[6501]: wlan0: Add radio work 'scan'@0x3cbe1e209680 Jan 1 01:02:16 f13 wpa_supplicant[6501]: wlan0: First radio work item in the queue - schedule start immediately Jan 1 01:02:16 f13 wpa_supplicant[6501]: wlan0: Starting radio work 'scan'@0x3cbe1e209680 after 0.000026 second wait Jan 1 01:02:16 f13 wpa_supplicant[6501]: wpa_driver_bsd_set_wpa: enabled=1 Jan 1 01:02:16 f13 wpa_supplicant[6501]: wpa_driver_bsd_set_wpa_internal: wpa=3 privacy=1 Jan 1 01:02:16 f13 wpa_supplicant[6501]: bsd_ctrl_iface: if wlan0 (changed) enable 1 IFF_UP 1 Jan 1 01:02:16 f13 wpa_supplicant[6501]: RTM_IFINFO: Interface 'wlan0' UP Jan 1 01:02:16 f13 wpa_supplicant[6501]: wlan0: Event INTERFACE_ENABLED (25) received Jan 1 01:02:16 f13 wpa_supplicant[6501]: wlan0: Interface was enabled Jan 1 01:02:16 f13 kernel: Jan 1 01:02:16 f13 wpa_supplicant[6500]: ioctl[SIOCS80211, op=20, val=0, arg_len=7]: Invalid argument Jan 1 01:02:17 f13 wpa_supplicant[6501]: EAPOL: disable timer tick Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: Event SCAN_RESULTS (3) received Jan 1 01:02:20 f13 wpa_supplicant[6501]: Received 6904 bytes of scan results (16 BSSes) Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: BSS: Start scan result update 1 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: BSS: Add new id 0 BSSID a2:ba:5f:d7:71:b3 SSID 'Casa di Mau MLO' freq 5240 MLD ADDR 98:ba:5f:d7:71:b5 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: BSS: Add new id 1 BSSID 98:ba:5f:d7:71:b3 SSID 'Casa di Mau' freq 5240 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: BSS: Add new id 2 BSSID c8:d3:a3:67:87:4e SSID 'dlink-874E' freq 2422 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: BSS: Add new id 3 BSSID 98:ba:5f:d7:71:b2 SSID 'Casa di Mau' freq 2457 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: BSS: Add new id 4 BSSID 58:2f:f7:1d:9d:c7 SSID 'Telia-1D9DC1' freq 5500 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: BSS: Add new id 5 BSSID 9e:ba:5f:d7:71:b2 SSID '' freq 2457 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: BSS: Add new id 6 BSSID e8:d2:ff:ab:81:0c SSID 'Telia-AB8107' freq 2432 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: BSS: Add new id 7 BSSID e8:d2:ff:ab:81:0d SSID 'Telia-AB8107' freq 5500 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: BSS: Add new id 8 BSSID 8e:c5:b4:db:f5:fa SSID 'Telia-2G-DBF4F3' freq 2462 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: BSS: Add new id 9 BSSID 8c:c5:b4:db:f4:f8 SSID 'Telia-DBF4F3' freq 2462 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: BSS: Add new id 10 BSSID 98:ba:5f:d7:56:8e SSID 'Casa di Mau' freq 2417 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: BSS: Add new id 11 BSSID c8:d3:a3:67:87:50 SSID 'dlink-874E-media' freq 5220 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: BSS: Add new id 12 BSSID d0:6e:de:91:51:5c SSID 'Get-2G-915157' freq 2447 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: BSS: Add new id 13 BSSID a2:ba:5f:d7:56:8f SSID 'Casa di Mau MLO' freq 5240 MLD ADDR 98:ba:5f:d7:56:91 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: BSS: Add new id 14 BSSID 98:ba:5f:d7:56:8f SSID 'Casa di Mau' freq 5240 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: BSS: Add new id 15 BSSID fa:8f:ca:6d:4b:a6 SSID 'Stue.v,' freq 2437 Jan 1 01:02:20 f13 wpa_supplicant[6501]: BSS: last_scan_res_used=16/32 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: New scan results available (own=0 ext=0) Jan 1 01:02:20 f13 wpa_supplicant[6501]: WPS: AP 98:ba:5f:d7:71:b3 type 0 added Jan 1 01:02:20 f13 wpa_supplicant[6501]: WPS: AP c8:d3:a3:67:87:4e type 0 added Jan 1 01:02:20 f13 wpa_supplicant[6501]: WPS: AP 98:ba:5f:d7:71:b2 type 0 added Jan 1 01:02:20 f13 wpa_supplicant[6501]: WPS: AP 58:2f:f7:1d:9d:c7 type 0 added Jan 1 01:02:20 f13 wpa_supplicant[6501]: WPS: AP e8:d2:ff:ab:81:0c type 0 added Jan 1 01:02:20 f13 wpa_supplicant[6501]: WPS: AP e8:d2:ff:ab:81:0d type 0 added Jan 1 01:02:20 f13 wpa_supplicant[6501]: WPS: AP 8e:c5:b4:db:f5:fa type 0 added Jan 1 01:02:20 f13 wpa_supplicant[6501]: WPS: AP 8c:c5:b4:db:f4:f8 type 0 added Jan 1 01:02:20 f13 wpa_supplicant[6501]: WPS: AP 98:ba:5f:d7:56:8e type 0 added Jan 1 01:02:20 f13 wpa_supplicant[6501]: WPS: AP c8:d3:a3:67:87:50 type 0 added Jan 1 01:02:20 f13 wpa_supplicant[6501]: WPS: AP d0:6e:de:91:51:5c type 0 added Jan 1 01:02:20 f13 wpa_supplicant[6501]: WPS: AP 98:ba:5f:d7:56:8f type 0 added Jan 1 01:02:20 f13 wpa_supplicant[6501]: WPS: AP[0] 98:ba:5f:d7:71:b3 type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:02:20 f13 wpa_supplicant[6501]: WPS: AP[1] c8:d3:a3:67:87:4e type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:02:20 f13 wpa_supplicant[6501]: WPS: AP[2] 98:ba:5f:d7:71:b2 type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:02:20 f13 wpa_supplicant[6501]: WPS: AP[3] 58:2f:f7:1d:9d:c7 type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:02:20 f13 wpa_supplicant[6501]: WPS: AP[4] e8:d2:ff:ab:81:0c type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:02:20 f13 wpa_supplicant[6501]: WPS: AP[5] e8:d2:ff:ab:81:0d type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:02:20 f13 wpa_supplicant[6501]: WPS: AP[6] 8e:c5:b4:db:f5:fa type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:02:20 f13 wpa_supplicant[6501]: WPS: AP[7] 8c:c5:b4:db:f4:f8 type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:02:20 f13 wpa_supplicant[6501]: WPS: AP[8] 98:ba:5f:d7:56:8e type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:02:20 f13 wpa_supplicant[6501]: WPS: AP[9] c8:d3:a3:67:87:50 type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:02:20 f13 wpa_supplicant[6501]: WPS: AP[10] d0:6e:de:91:51:5c type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:02:20 f13 wpa_supplicant[6501]: WPS: AP[11] 98:ba:5f:d7:56:8f type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: Radio work 'scan'@0x3cbe1e209680 done in 3.482803 seconds Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: radio_work_free('scan'@0x3cbe1e209680): num_active_works --> 0 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: Selecting BSS from priority group 10 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: 0: a2:ba:5f:d7:71:b3 ssid='Casa di Mau MLO' wpa_ie_len=0 rsn_ie_len=20 caps=0x11 level=-54 freq=5240 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: skip - SSID mismatch Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: 1: 98:ba:5f:d7:71:b3 ssid='Casa di Mau' wpa_ie_len=0 rsn_ie_len=24 caps=0x11 level=-54 freq=5240 wps Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: skip - SSID mismatch Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: 2: c8:d3:a3:67:87:4e ssid='dlink-874E' wpa_ie_len=26 rsn_ie_len=24 caps=0x11 level=-55 freq=2422 wps Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: skip - SSID mismatch Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: 3: 98:ba:5f:d7:71:b2 ssid='Casa di Mau' wpa_ie_len=0 rsn_ie_len=24 caps=0x31 level=-64 freq=2457 wps Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: skip - SSID mismatch Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: 4: 58:2f:f7:1d:9d:c7 ssid='Telia-1D9DC1' wpa_ie_len=0 rsn_ie_len=26 caps=0x11 level=-65 freq=5500 wps Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: skip - SSID mismatch Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: 5: 9e:ba:5f:d7:71:b2 ssid='' wpa_ie_len=0 rsn_ie_len=20 caps=0x31 level=-62 freq=2457 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: skip - SSID not known Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: 6: e8:d2:ff:ab:81:0c ssid='Telia-AB8107' wpa_ie_len=0 rsn_ie_len=20 caps=0x11 level=-66 freq=2432 wps Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: skip - SSID mismatch Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: 7: e8:d2:ff:ab:81:0d ssid='Telia-AB8107' wpa_ie_len=0 rsn_ie_len=20 caps=0x11 level=-72 freq=5500 wps Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: skip - SSID mismatch Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: 8: 8e:c5:b4:db:f5:fa ssid='Telia-2G-DBF4F3' wpa_ie_len=0 rsn_ie_len=20 caps=0x11 level=-73 freq=2462 wps Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: skip - SSID mismatch Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: 9: 8c:c5:b4:db:f4:f8 ssid='Telia-DBF4F3' wpa_ie_len=0 rsn_ie_len=20 caps=0x11 level=-74 freq=2462 wps Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: skip - SSID mismatch Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: 10: 98:ba:5f:d7:56:8e ssid='Casa di Mau' wpa_ie_len=0 rsn_ie_len=24 caps=0x31 level=-75 freq=2417 wps Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: 11: c8:d3:a3:67:87:50 ssid='dlink-874E-media' wpa_ie_len=26 rsn_ie_len=24 caps=0x31 level=-80 freq=5220 wps Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: 13: a2:ba:5f:d7:56:8f ssid='Casa di Mau MLO' wpa_ie_len=0 rsn_ie_len=20 caps=0x11 level=-88 freq=5240 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: Selecting BSS from priority group 5 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: skip - SSID mismatch Jan 1 01:02:20 f13 wpa_supplicant[6501]: Ignored duplicated SSID element Jan 1 01:02:20 f13 wpa_supplicant[6501]: IEEE 802.11 element parse ignored unknown element (id=201 elen=52) Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: Considering connect request: reassociate: 0 selected: 98:ba:5f:d7:71:b3 bssid: 00:00:00:00:00:00 pending: 00:00:00:00:00:00 wpa_state: SCANNING ssid=0x3cbe1e241000 current_ssid=0x0 Jan 1 01:02:20 f13 wpa_supplicant[6501]: TDLS: TDLS is allowed in the target BSS Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: First radio work item in the queue - schedule start immediately Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: RSN: clearing own RSNXE Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: WPA: Selected cipher suites: group 16 pairwise 16 key_mgmt 1026 proto 2 Jan 1 01:02:20 f13 wpa_supplicant[6501]: WPA: set AP RSN IE - hexdump(len=26): 30 18 01 00 00 0f ac 04 01 00 00 0f ac 04 02 00 00 0f ac 02 00 0f ac 08 8c 00 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: WPA: AP pairwise 0x10 network profile pairwise 0x18; available pairwise 0x10 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: WPA: not using MGMT group cipher Jan 1 01:02:20 f13 wpa_supplicant[6501]: WPA: Set PMK based on external data - hexdump(len=32): [REMOVED] Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: Trying to associate with 98:ba:5f:d7:71:b3 (SSID='Casa di Mau' freq=5240 MHz) Jan 1 01:02:20 f13 wpa_supplicant[6501]: PSK (set in config) - hexdump(len=32): [REMOVED] Jan 1 01:02:20 f13 wpa_supplicant[6501]: bsd_set_opt_ie: set WPA+RSN ie (len 22) Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: Setting authentication timeout: 10 sec 0 usec Jan 1 01:02:20 f13 wpa_supplicant[6501]: EAPOL: External notification - EAP success=0 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: RX EAPOL from 98:ba:5f:d7:71:b3 (encrypted=-1) Jan 1 01:02:20 f13 wpa_supplicant[6501]: RX EAPOL - hexdump(len=99): 02 03 00 5f 02 00 8a 00 10 00 00 00 00 00 00 00 01 62 a5 9e 5a 53 8b dc 93 21 aa 47 fd 92 d2 a1 bc 56 47 77 aa a7 9e 3c 36 c6 52 c1 73 45 58 ab 31 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: Not associated - Delay processing of received EAPOL frame (state=ASSOCIATING connected_addr=00:00:00:00:00:00) Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: RX EAPOL from 98:ba:5f:d7:71:b3 (encrypted=-1) Jan 1 01:02:20 f13 wpa_supplicant[6501]: RX EAPOL - hexdump(len=99): 02 03 00 5f 02 00 8a 00 10 00 00 00 00 00 00 00 02 62 a5 9e 5a 53 8b dc 93 21 aa 47 fd 92 d2 a1 bc 56 47 77 aa a7 9e 3c 36 c6 52 c1 73 45 58 ab 31 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Jan 1 01:02:20 f13 wpa_supplicant[6501]: wlan0: Not associated - Delay processing of received EAPOL frame (state=ASSOCIATING connected_addr=00:00:00:00:00:00) Jan 1 01:02:21 f13 kernel: iwlwifi0: Not associated and the session protection is over already... Jan 1 01:02:21 f13 kernel: iwlwifi0: linuxkpi_ieee80211_connection_loss: vif 0xfffffe01d4969f00 vap 0xfffffe01d4969010 state ASSOC (synched 1, assoc 0 beacons 8 dtim_period 0) Jan 1 01:02:30 f13 wpa_supplicant[6501]: wlan0: Authentication with 98:ba:5f:d7:71:b3 timed out. Jan 1 01:02:30 f13 wpa_supplicant[6501]: wlan0: Added BSSID 98:ba:5f:d7:71:b3 into ignore list, ignoring for 10 seconds Jan 1 01:02:30 f13 wpa_supplicant[6501]: TDLS: Remove peers on disassociation Jan 1 01:02:30 f13 wpa_supplicant[6501]: wlan0: WPA: Clear old PMK and PTK Jan 1 01:02:30 f13 wpa_supplicant[6501]: wlan0: Request to deauthenticate - bssid=00:00:00:00:00:00 pending_bssid=98:ba:5f:d7:71:b3 reason=3 (DEAUTH_LEAVING) state=ASSOCIATING valid_links=0x0 ap_mld_addr=00:00:00:00:00:00 Jan 1 01:02:30 f13 wpa_supplicant[6501]: TDLS: Tear down peers Jan 1 01:02:30 f13 wpa_supplicant[6501]: wlan0: Event DEAUTH (11) received Jan 1 01:02:30 f13 wpa_supplicant[6501]: wlan0: Deauthentication notification Jan 1 01:02:30 f13 wpa_supplicant[6501]: wlan0: * reason 3 (DEAUTH_LEAVING) locally_generated=1 Jan 1 01:02:30 f13 wpa_supplicant[6501]: Deauthentication frame IE(s) - hexdump(len=0): [NULL] Jan 1 01:02:30 f13 wpa_supplicant[6501]: wlan0: CTRL-EVENT-DISCONNECTED bssid=98:ba:5f:d7:71:b3 reason=3 locally_generated=1 Jan 1 01:02:30 f13 wpa_supplicant[6501]: wlan0: Auto connect enabled: try to reconnect (wps=0/0 wpa_state=5) Jan 1 01:02:30 f13 wpa_supplicant[6501]: wlan0: Setting scan request: 0.100000 sec Jan 1 01:02:30 f13 wpa_supplicant[6501]: wlan0: Radio work 'connect'@0x3cbe1e209680 done in 10.166134 seconds Jan 1 01:02:30 f13 wpa_supplicant[6501]: wlan0: radio_work_free('connect'@0x3cbe1e209680): num_active_works --> 0 Jan 1 01:02:30 f13 wpa_supplicant[6501]: wlan0: BSSID 98:ba:5f:d7:71:b3 ignore list count incremented to 2, ignoring for 10 seconds Jan 1 01:02:30 f13 wpa_supplicant[6501]: wlan0: Consecutive connection failures: 1 --> request scan in 100 ms Jan 1 01:02:30 f13 wpa_supplicant[6501]: wlan0: Ignore new scan request for 0.100000 sec since an earlier request is scheduled to trigger sooner Jan 1 01:02:30 f13 wpa_supplicant[6501]: TDLS: Remove peers on disassociation Jan 1 01:02:30 f13 wpa_supplicant[6501]: wlan0: WPA: Clear old PMK and PTK Jan 1 01:02:30 f13 wpa_supplicant[6501]: wlan0: Disconnect event - remove keys Jan 1 01:02:30 f13 wpa_supplicant[6501]: wlan0: State: ASSOCIATING -> DISCONNECTED Jan 1 01:02:30 f13 wpa_supplicant[6501]: EAPOL: External notification - portEnabled=0 Jan 1 01:02:30 f13 wpa_supplicant[6501]: EAPOL: External notification - portValid=0 Jan 1 01:02:30 f13 wpa_supplicant[6501]: EAPOL: External notification - EAP success=0 Jan 1 01:02:30 f13 wpa_supplicant[6501]: wlan0: State: DISCONNECTED -> DISCONNECTED Jan 1 01:02:30 f13 wpa_supplicant[6501]: EAPOL: External notification - portEnabled=0 Jan 1 01:02:30 f13 wpa_supplicant[6501]: EAPOL: External notification - portValid=0 Jan 1 01:02:30 f13 wpa_supplicant[6501]: QM: Clear all active DSCP policies Jan 1 01:02:30 f13 wpa_supplicant[6501]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jan 1 01:02:30 f13 wpa_supplicant[6501]: wlan0: Ignore new scan request for 1.000000 sec since an earlier request is scheduled to trigger sooner Jan 1 01:02:30 f13 wpa_supplicant[6501]: wlan0: State: DISCONNECTED -> SCANNING Jan 1 01:02:30 f13 wpa_supplicant[6501]: wlan0: Starting AP scan for wildcard SSID Jan 1 01:02:30 f13 wpa_supplicant[6501]: wlan0: Add radio work 'scan'@0x3cbe1e209680 Jan 1 01:02:30 f13 wpa_supplicant[6501]: wlan0: First radio work item in the queue - schedule start immediately Jan 1 01:02:30 f13 wpa_supplicant[6501]: wlan0: Starting radio work 'scan'@0x3cbe1e209680 after 0.000014 second wait Jan 1 01:02:30 f13 wpa_supplicant[6501]: wpa_driver_bsd_set_wpa: enabled=1 Jan 1 01:02:30 f13 wpa_supplicant[6501]: wpa_driver_bsd_set_wpa_internal: wpa=3 privacy=1 Jan 1 01:02:30 f13 wpa_supplicant[6501]: bsd_ctrl_iface: if wlan0 (no change) enable 1 IFF_UP 1 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: Event SCAN_RESULTS (3) received Jan 1 01:02:32 f13 wpa_supplicant[6501]: Received 5480 bytes of scan results (13 BSSes) Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: BSS: Start scan result update 2 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: BSS: Add new id 16 BSSID ea:d2:ff:ab:83:0f SSID '' freq 5500 HESSID 50:6f:9a:00:11:22 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: BSS: Add new id 17 BSSID 58:2f:f7:1d:9d:c6 SSID 'Telia-2G-1D9DC1' freq 2447 Jan 1 01:02:32 f13 wpa_supplicant[6501]: BSS: last_scan_res_used=13/32 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: New scan results available (own=0 ext=0) Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPS: AP 58:2f:f7:1d:9d:c6 type 0 added Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPS: AP[0] 98:ba:5f:d7:71:b3 type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=2 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPS: AP[1] c8:d3:a3:67:87:4e type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPS: AP[2] 98:ba:5f:d7:71:b2 type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPS: AP[3] 58:2f:f7:1d:9d:c7 type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPS: AP[4] e8:d2:ff:ab:81:0c type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPS: AP[5] e8:d2:ff:ab:81:0d type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPS: AP[6] 8e:c5:b4:db:f5:fa type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPS: AP[7] 8c:c5:b4:db:f4:f8 type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPS: AP[8] 98:ba:5f:d7:56:8e type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPS: AP[9] c8:d3:a3:67:87:50 type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPS: AP[10] d0:6e:de:91:51:5c type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPS: AP[11] 98:ba:5f:d7:56:8f type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPS: AP[12] 58:2f:f7:1d:9d:c6 type=0 tries=0 last_attempt=-1 sec ago bssid_ignore=0 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: Radio work 'scan'@0x3cbe1e209680 done in 1.436888 seconds Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: radio_work_free('scan'@0x3cbe1e209680): num_active_works --> 0 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: Selecting BSS from priority group 10 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: 0: a2:ba:5f:d7:71:b3 ssid='Casa di Mau MLO' wpa_ie_len=0 rsn_ie_len=20 caps=0x11 level=-60 freq=5240 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: skip - SSID mismatch Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: 1: 98:ba:5f:d7:71:b3 ssid='Casa di Mau' wpa_ie_len=0 rsn_ie_len=24 caps=0x11 level=-60 freq=5240 wps Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: skip - BSSID ignored (count=2 limit=1) Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: 2: 58:2f:f7:1d:9d:c7 ssid='Telia-1D9DC1' wpa_ie_len=0 rsn_ie_len=26 caps=0x11 level=-64 freq=5500 wps Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: skip - SSID mismatch Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: 3: 98:ba:5f:d7:71:b2 ssid='Casa di Mau' wpa_ie_len=0 rsn_ie_len=24 caps=0x31 level=-66 freq=2457 wps Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: skip - SSID mismatch Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: 4: ea:d2:ff:ab:83:0f ssid='' wpa_ie_len=0 rsn_ie_len=20 caps=0x11 level=-66 freq=5500 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: skip - SSID not known Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: 5: e8:d2:ff:ab:81:0d ssid='Telia-AB8107' wpa_ie_len=0 rsn_ie_len=20 caps=0x11 level=-67 freq=5500 wps Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: skip - SSID mismatch Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: 6: c8:d3:a3:67:87:4e ssid='dlink-874E' wpa_ie_len=26 rsn_ie_len=24 caps=0x11 level=-63 freq=2422 wps Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: skip - SSID mismatch Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: 7: 58:2f:f7:1d:9d:c6 ssid='Telia-2G-1D9DC1' wpa_ie_len=0 rsn_ie_len=20 caps=0x11 level=-69 freq=2447 wps Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: skip - SSID mismatch Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: 8: e8:d2:ff:ab:81:0c ssid='Telia-AB8107' wpa_ie_len=0 rsn_ie_len=20 caps=0x11 level=-70 freq=2432 wps Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: skip - SSID mismatch Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: 9: 98:ba:5f:d7:56:8e ssid='Casa di Mau' wpa_ie_len=0 rsn_ie_len=24 caps=0x31 level=-81 freq=2417 wps Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: skip - SSID mismatch Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: 10: c8:d3:a3:67:87:50 ssid='dlink-874E-media' wpa_ie_len=26 rsn_ie_len=24 caps=0x31 level=-89 freq=5220 wps Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: skip - SSID mismatch Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: 11: 8e:c5:b4:db:f5:fa ssid='Telia-2G-DBF4F3' wpa_ie_len=0 rsn_ie_len=20 caps=0x11 level=-85 freq=2462 wps Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: skip - SSID mismatch Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: 12: 8c:c5:b4:db:f4:f8 ssid='Telia-DBF4F3' wpa_ie_len=0 rsn_ie_len=20 caps=0x11 level=-85 freq=2462 wps Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: skip - SSID mismatch Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: Selecting BSS from priority group 5 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: 0: a2:ba:5f:d7:71:b3 ssid='Casa di Mau MLO' wpa_ie_len=0 rsn_ie_len=20 caps=0x11 level=-60 freq=5240 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: skip - SSID mismatch Jan 1 01:02:32 f13 syslogd: last message repeated 4 times Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: 1: 98:ba:5f:d7:71:b3 ssid='Casa di Mau' wpa_ie_len=0 rsn_ie_len=24 caps=0x11 level=-60 freq=5240 wps Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: skip - BSSID ignored (count=2 limit=1) Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: 2: 58:2f:f7:1d:9d:c7 ssid='Telia-1D9DC1' wpa_ie_len=0 rsn_ie_len=26 caps=0x11 level=-64 freq=5500 wps Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: skip - SSID mismatch Jan 1 01:02:32 f13 syslogd: last message repeated 4 times Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: 3: 98:ba:5f:d7:71:b2 ssid='Casa di Mau' wpa_ie_len=0 rsn_ie_len=24 caps=0x31 level=-66 freq=2457 wps Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: skip - BSSID configured to be ignored Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: skip - SSID mismatch Jan 1 01:02:32 f13 syslogd: last message repeated 3 times Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: 4: ea:d2:ff:ab:83:0f ssid='' wpa_ie_len=0 rsn_ie_len=20 caps=0x11 level=-66 freq=5500 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: skip - SSID not known Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: 5: e8:d2:ff:ab:81:0d ssid='Telia-AB8107' wpa_ie_len=0 rsn_ie_len=20 caps=0x11 level=-67 freq=5500 wps Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: skip - SSID mismatch Jan 1 01:02:32 f13 syslogd: last message repeated 1 times Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: 6: c8:d3:a3:67:87:4e ssid='dlink-874E' wpa_ie_len=26 rsn_ie_len=24 caps=0x11 level=-63 freq=2422 wps Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: skip - SSID mismatch Jan 1 01:02:32 f13 syslogd: last message repeated 2 times Jan 1 01:02:32 f13 wpa_supplicant[6501]: Ignored duplicated SSID element Jan 1 01:02:32 f13 wpa_supplicant[6501]: IEEE 802.11 element parsing ignored unknown element extension (ext_id=38 elen=13) Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: Considering connect request: reassociate: 1 selected: 98:ba:5f:d7:56:8e bssid: 00:00:00:00:00:00 pending: 00:00:00:00:00:00 wpa_state: SCANNING ssid=0x3cbe1e241000 current_ssid=0x0 Jan 1 01:02:32 f13 wpa_supplicant[6501]: TDLS: TDLS channel switch allowed in the target BSS Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: Starting radio work 'connect'@0x3cbe1e209680 after 0.000019 second wait Jan 1 01:02:32 f13 wpa_supplicant[6501]: RSN: No PMKSA cache entry found Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: set AP RSN IE - hexdump(len=26): 30 18 01 00 00 0f ac 04 01 00 00 0f ac 04 02 00 00 0f ac 02 00 0f ac 08 8c 00 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: WPA: using GTK CCMP Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: WPA: AP mgmt_group_cipher 0x20 network profile mgmt_group_cipher 0x0; available mgmt_group_cipher 0x0 Jan 1 01:02:32 f13 wpa_supplicant[6501]: PSK (set in config) - hexdump(len=32): [REMOVED] Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: Trying to associate with 98:ba:5f:d7:56:8e (SSID='Casa di Mau' freq=2417 MHz) Jan 1 01:02:32 f13 wpa_supplicant[6501]: wpa_driver_bsd_associate: ssid 'Casa di Mau' wpa ie len 30 pairwise 16 group 16 key mgmt 2 Jan 1 01:02:32 f13 wpa_supplicant[6501]: bsd_ctrl_iface: if wlan0 (no change) enable 1 IFF_UP 1 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: Setting authentication timeout: 10 sec 0 usec Jan 1 01:02:32 f13 wpa_supplicant[6501]: EAPOL: External notification - EAP success=0 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: RX EAPOL from 98:ba:5f:d7:56:8e (encrypted=-1) Jan 1 01:02:32 f13 wpa_supplicant[6501]: RX EAPOL - hexdump(len=99): 02 03 00 5f 02 00 8a 00 10 00 00 00 00 00 00 00 01 18 35 f6 fe 81 6e 04 57 ac 66 bc de 4e f0 81 75 01 01 12 15 6c e2 f5 93 02 e5 67 f4 01 d2 0d e7 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: Not associated - Delay processing of received EAPOL frame (state=ASSOCIATING connected_addr=00:00:00:00:00:00) Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: Event ASSOC (0) received Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: State: ASSOCIATING -> ASSOCIATED Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: Associated to a new BSS: BSSID=98:ba:5f:d7:56:8e Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: Associated with 98:ba:5f:d7:56:8e Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: WPA: Association event - clear replay counter Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: WPA: Clear old PTK Jan 1 01:02:32 f13 wpa_supplicant[6501]: TDLS: Remove peers on association Jan 1 01:02:32 f13 wpa_supplicant[6501]: EAPOL: External notification - portEnabled=0 Jan 1 01:02:32 f13 wpa_supplicant[6501]: EAPOL: External notification - portValid=0 Jan 1 01:02:32 f13 wpa_supplicant[6501]: EAPOL: External notification - EAP success=0 Jan 1 01:02:32 f13 wpa_supplicant[6501]: EAPOL: External notification - portEnabled=1 Jan 1 01:02:32 f13 wpa_supplicant[6501]: EAPOL: SUPP_PAE entering state CONNECTING Jan 1 01:02:32 f13 wpa_supplicant[6501]: EAPOL: enable timer tick Jan 1 01:02:32 f13 wpa_supplicant[6501]: EAPOL: SUPP_BE entering state IDLE Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: Setting authentication timeout: 10 sec 0 usec Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: Cancelling scan request Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: Process pending EAPOL frame that was received just before association notification Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: RX EAPOL from 98:ba:5f:d7:56:8e (encrypted=-1) Jan 1 01:02:32 f13 wpa_supplicant[6501]: RX EAPOL - hexdump(len=99): 02 03 00 5f 02 00 8a 00 10 00 00 00 00 00 00 00 01 18 35 f6 fe 81 6e 04 57 ac 66 bc de 4e f0 81 75 01 01 12 15 6c e2 f5 93 02 e5 67 f4 01 d2 0d e7 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: Setting authentication timeout: 10 sec 0 usec Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: IEEE 802.1X RX: version=2 type=3 length=95 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: RX EAPOL-Key - hexdump(len=99): 02 03 00 5f 02 00 8a 00 10 00 00 00 00 00 00 00 01 18 35 f6 fe 81 6e 04 57 ac 66 bc de 4e f0 81 75 01 01 12 15 6c e2 f5 93 02 e5 67 f4 01 d2 0d e7 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: EAPOL-Key type=2 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: key_info 0x8a (ver=2 keyidx=0 rsvd=0 Pairwise Ack) Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: key_length=16 key_data_length=0 Jan 1 01:02:32 f13 wpa_supplicant[6501]: replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 01 Jan 1 01:02:32 f13 wpa_supplicant[6501]: key_nonce - hexdump(len=32): 18 35 f6 fe 81 6e 04 57 ac 66 bc de 4e f0 81 75 01 01 12 15 6c e2 f5 93 02 e5 67 f4 01 d2 0d e7 Jan 1 01:02:32 f13 wpa_supplicant[6501]: key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Jan 1 01:02:32 f13 wpa_supplicant[6501]: key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00 Jan 1 01:02:32 f13 wpa_supplicant[6501]: key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00 Jan 1 01:02:32 f13 wpa_supplicant[6501]: key_mic - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: WPA: RX message 1 of 4-Way Handshake from 98:ba:5f:d7:56:8e (ver=2) Jan 1 01:02:32 f13 wpa_supplicant[6501]: RSN: msg 1/4 key data - hexdump(len=0): Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: State: ASSOCIATED -> 4WAY_HANDSHAKE Jan 1 01:02:32 f13 wpa_supplicant[6501]: Get randomness: len=32 entropy=21 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: Renewed SNonce - hexdump(len=32): a3 19 86 6a cd 1e f0 e0 12 b9 6e 5c c1 f2 e6 17 1d f5 5e 5c 15 c5 bf e2 1e d8 05 f9 1e a8 0e 2e Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: PTK derivation using PRF(SHA1) Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: PTK derivation - A1=8c:b8:7e:c9:89:57 A2=98:ba:5f:d7:56:8e Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: Nonce1 - hexdump(len=32): a3 19 86 6a cd 1e f0 e0 12 b9 6e 5c c1 f2 e6 17 1d f5 5e 5c 15 c5 bf e2 1e d8 05 f9 1e a8 0e 2e Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: Nonce2 - hexdump(len=32): 18 35 f6 fe 81 6e 04 57 ac 66 bc de 4e f0 81 75 01 01 12 15 6c e2 f5 93 02 e5 67 f4 01 d2 0d e7 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: PMK - hexdump(len=32): [REMOVED] Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: PTK - hexdump(len=48): [REMOVED] Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: KCK - hexdump(len=16): [REMOVED] Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: KEK - hexdump(len=16): [REMOVED] Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: TK - hexdump(len=16): [REMOVED] Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: WPA IE for msg 2/4 - hexdump(len=22): 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 0c 00 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: Replay Counter - hexdump(len=8): 00 00 00 00 00 00 00 01 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: WPA: Sending EAPOL-Key 2/4 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: Send EAPOL-Key frame to 98:ba:5f:d7:56:8e ver=2 mic_len=16 key_mgmt=0x2 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: EAPOL-Key MIC using HMAC-SHA1 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: KCK - hexdump(len=16): [REMOVED] Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: Derived Key MIC - hexdump(len=16): 44 c8 a3 cb 9c b2 01 ee f5 e5 71 90 7d 2c 6d 76 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: TX EAPOL-Key - hexdump(len=121): 01 03 00 75 02 01 0a 00 00 00 00 00 00 00 00 00 01 a3 19 86 6a cd 1e f0 e0 12 b9 6e 5c c1 f2 e6 17 1d f5 5e 5c 15 c5 bf e2 1e d8 05 f9 1e a8 0e 2e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 44 c8 a3 cb 9c b2 01 ee f5 e5 71 90 7d 2c 6d 76 00 16 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 0c 00 Jan 1 01:02:32 f13 kernel: wlan0: ieee80211_sta_join: BSS 98:ba:5f:d7:56:8e: 2GHz channel, VHT info; ignoring Jan 1 01:02:32 f13 kernel: wlan0: link state changed to UP Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: RX EAPOL from 98:ba:5f:d7:56:8e (encrypted=-1) Jan 1 01:02:32 f13 wpa_supplicant[6501]: RX EAPOL - hexdump(len=163): 02 03 00 9f 02 13 ca 00 10 00 00 00 00 00 00 00 02 18 35 f6 fe 81 6e 04 57 ac 66 bc de 4e f0 81 75 01 01 12 15 6c e2 f5 93 02 e5 67 f4 01 d2 0d e7 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 83 bf 44 00 7b 5b 89 09 74 1c 54 ec 8d 5b cc b2 00 40 e1 09 db aa 71 1f cd 40 34 63 cc 5d f2 46 dd 6e 02 db a3 cb ef 2b 8e de 7d ce 73 62 fb c6 3d bd 94 08 2f ae 25 3f 83 5b 6d 15 56 0b 12 fb 29 b9 d1 ff 35 c6 1e d8 c6 0d 79 13 72 17 48 19 82 27 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: IEEE 802.1X RX: version=2 type=3 length=159 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: RX EAPOL-Key - hexdump(len=163): 02 03 00 9f 02 13 ca 00 10 00 00 00 00 00 00 00 02 18 35 f6 fe 81 6e 04 57 ac 66 bc de 4e f0 81 75 01 01 12 15 6c e2 f5 93 02 e5 67 f4 01 d2 0d e7 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 83 bf 44 00 7b 5b 89 09 74 1c 54 ec 8d 5b cc b2 00 40 e1 09 db aa 71 1f cd 40 34 63 cc 5d f2 46 dd 6e 02 db a3 cb ef 2b 8e de 7d ce 73 62 fb c6 3d bd 94 08 2f ae 25 3f 83 5b 6d 15 56 0b 12 fb 29 b9 d1 ff 35 c6 1e d8 c6 0d 79 13 72 17 48 19 82 27 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: EAPOL-Key type=2 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: key_info 0x13ca (ver=2 keyidx=0 rsvd=0 Pairwise Install Ack MIC Secure Encr) Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: key_length=16 key_data_length=64 Jan 1 01:02:32 f13 wpa_supplicant[6501]: replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 02 Jan 1 01:02:32 f13 wpa_supplicant[6501]: key_nonce - hexdump(len=32): 18 35 f6 fe 81 6e 04 57 ac 66 bc de 4e f0 81 75 01 01 12 15 6c e2 f5 93 02 e5 67 f4 01 d2 0d e7 Jan 1 01:02:32 f13 wpa_supplicant[6501]: key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Jan 1 01:02:32 f13 wpa_supplicant[6501]: key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00 Jan 1 01:02:32 f13 wpa_supplicant[6501]: key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00 Jan 1 01:02:32 f13 wpa_supplicant[6501]: key_mic - hexdump(len=16): 83 bf 44 00 7b 5b 89 09 74 1c 54 ec 8d 5b cc b2 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: EAPOL-Key MIC using HMAC-SHA1 Jan 1 01:02:32 f13 wpa_supplicant[6501]: RSN: encrypted key data - hexdump(len=64): e1 09 db aa 71 1f cd 40 34 63 cc 5d f2 46 dd 6e 02 db a3 cb ef 2b 8e de 7d ce 73 62 fb c6 3d bd 94 08 2f ae 25 3f 83 5b 6d 15 56 0b 12 fb 29 b9 d1 ff 35 c6 1e d8 c6 0d 79 13 72 17 48 19 82 27 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: Decrypt Key Data using AES-UNWRAP (KEK length 16) Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: decrypted EAPOL-Key key data - hexdump(len=56): [REMOVED] Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: State: 4WAY_HANDSHAKE -> 4WAY_HANDSHAKE Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: RSN: RX message 3 of 4-Way Handshake from 98:ba:5f:d7:56:8e (ver=2) Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: IE KeyData - hexdump(len=56): 30 18 01 00 00 0f ac 04 01 00 00 0f ac 04 02 00 00 0f ac 02 00 0f ac 08 8c 00 f4 01 20 dd 16 00 0f ac 01 01 00 f8 0e 0b 15 2c 1a 8f b7 93 f4 0b ce 03 3b 4c d7 dd 00 00 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: RSN IE in EAPOL-Key - hexdump(len=26): 30 18 01 00 00 0f ac 04 01 00 00 0f ac 04 02 00 00 0f ac 02 00 0f ac 08 8c 00 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: RSNXE in EAPOL-Key - hexdump(len=3): f4 01 20 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: GTK in EAPOL-Key - hexdump(len=24): [REMOVED] Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: WPA: Sending EAPOL-Key 4/4 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: Send EAPOL-Key frame to 98:ba:5f:d7:56:8e ver=2 mic_len=16 key_mgmt=0x2 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: EAPOL-Key MIC using HMAC-SHA1 Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: KCK - hexdump(len=16): [REMOVED] Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: Derived Key MIC - hexdump(len=16): c0 e5 6e 06 17 46 d8 c1 eb 1d 89 d5 3c f2 16 3d Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: TX EAPOL-Key - hexdump(len=99): 01 03 00 5f 02 03 0a 00 00 00 00 00 00 00 00 00 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 c0 e5 6e 06 17 46 d8 c1 eb 1d 89 d5 3c f2 16 3d 00 00 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: WPA: Installing PTK to the driver Jan 1 01:02:32 f13 wpa_supplicant[6501]: bsd_set_key: alg=3 addr=0x3cbe1e2db570 key_idx=0 set_tx=1 seq_len=6 key_len=16 Jan 1 01:02:32 f13 wpa_supplicant[6501]: Added PTKSA cache entry addr=98:ba:5f:d7:56:8e cipher=16 Jan 1 01:02:32 f13 wpa_supplicant[6501]: EAPOL: External notification - portValid=1 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: State: 4WAY_HANDSHAKE -> GROUP_HANDSHAKE Jan 1 01:02:32 f13 wpa_supplicant[6501]: RSN: received GTK in pairwise handshake - hexdump(len=18): [REMOVED] Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: Group Key - hexdump(len=16): [REMOVED] Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: WPA: Installing GTK to the driver (keyidx=1 tx=0 len=16) Jan 1 01:02:32 f13 wpa_supplicant[6501]: WPA: RSC - hexdump(len=6): 00 00 00 00 00 00 Jan 1 01:02:32 f13 wpa_supplicant[6501]: bsd_set_key: alg=3 addr=0x2180b6b45b4a key_idx=1 set_tx=0 seq_len=6 key_len=16 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: WPA: Key negotiation completed with 98:ba:5f:d7:56:8e [PTK=CCMP GTK=CCMP] Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: Cancelling authentication timeout Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: State: GROUP_HANDSHAKE -> COMPLETED Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: Radio work 'connect'@0x3cbe1e209680 done in 0.115897 seconds Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: radio_work_free('connect'@0x3cbe1e209680): num_active_works --> 0 Jan 1 01:02:32 f13 wpa_supplicant[6501]: wlan0: CTRL-EVENT-CONNECTED - Connection to 98:ba:5f:d7:56:8e completed [id=0 id_str=] Jan 1 01:02:32 f13 wpa_supplicant[6501]: EAPOL: External notification - portValid=1 Jan 1 01:02:32 f13 wpa_supplicant[6501]: EAPOL: External notification - EAP success=1 Jan 1 01:02:32 f13 wpa_supplicant[6501]: EAPOL: SUPP_PAE entering state AUTHENTICATING Jan 1 01:02:32 f13 wpa_supplicant[6501]: EAPOL: SUPP_BE entering state SUCCESS Jan 1 01:02:32 f13 wpa_supplicant[6501]: EAP: EAP entering state DISABLED Jan 1 01:02:32 f13 wpa_supplicant[6501]: EAPOL: SUPP_PAE entering state AUTHENTICATED Jan 1 01:02:32 f13 dhclient[6631]: DHCPREQUEST on wlan0 to 255.255.255.255 port 67 Jan 1 01:02:32 f13 dhclient[6631]: DHCPACK from 192.168.20.1 Jan 1 01:02:32 f13 avahi-daemon[5399]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.20.10. Jan 1 01:02:32 f13 avahi-daemon[5399]: New relevant interface wlan0.IPv4 for mDNS. Jan 1 01:02:32 f13 avahi-daemon[5399]: Registering new address record for 192.168.20.10 on wlan0.IPv4. Jan 1 01:02:32 f13 dhclient[6641]: New IP Address (wlan0): 192.168.20.10 Jan 1 01:02:32 f13 dhclient[6645]: New Subnet Mask (wlan0): 255.255.255.0 Jan 1 01:02:32 f13 dhclient[6649]: New Broadcast Address (wlan0): 192.168.20.255 Jan 1 01:02:32 f13 dhclient[6653]: New Routers (wlan0): 192.168.20.1 Jan 1 01:02:32 f13 dhclient[6631]: bound to 192.168.20.10 -- renewal in 3600 seconds. Jan 1 01:02:34 f13 wpa_supplicant[6501]: EAPOL: startWhen --> 0 Jan 1 01:02:34 f13 wpa_supplicant[6501]: EAPOL: disable timer tick Jan 1 01:02:34 f13 ntpd[5350]: Listen normally on 5 wlan0 192.168.20.10:123 Jan 1 01:02:54 f13 ntpd[5350]: Soliciting pool server 185.42.170.200 Jan 1 01:02:54 f13 ntpd[5350]: 185.42.170.200 local addr <null> -> 192.168.20.10 Jan 1 01:02:55 f13 ntpd[5350]: Soliciting pool server 185.175.56.95 Jan 1 01:02:55 f13 ntpd[5350]: 185.175.56.95 local addr <null> -> 192.168.20.10 Jan 1 01:02:56 f13 ntpd[5350]: Soliciting pool server 82.148.168.42 Jan 1 01:02:56 f13 ntpd[5350]: 82.148.168.42 local addr <null> -> 192.168.20.10 Jan 1 01:02:57 f13 ntpd[5350]: Soliciting pool server 152.65.32.101 Jan 1 01:02:57 f13 ntpd[5350]: 152.65.32.101 local addr <null> -> 192.168.20.10 Jan 1 01:02:57 f13 ntpd[5350]: Soliciting pool server 79.160.225.13 Jan 1 01:02:57 f13 ntpd[5350]: 79.160.225.13 local addr <null> -> 192.168.20.10 Jan 1 01:02:57 f13 ntpd[5350]: Soliciting pool server 91.189.182.32 Jan 1 01:02:57 f13 ntpd[5350]: 91.189.182.32 local addr <null> -> 192.168.20.10 Jan 1 01:02:59 f13 ntpd[5350]: Soliciting pool server 185.181.61.91 Jan 1 01:02:59 f13 ntpd[5350]: 185.181.61.91 local addr <null> -> 192.168.20.10 Jan 1 01:02:59 f13 ntpd[5350]: Soliciting pool server 192.36.143.130 Jan 1 01:03:00 f13 ntpd[5350]: 192.36.143.130 local addr <null> -> 192.168.20.10 Sep 2 10:49:22 f13 kernel: drmn0: [drm] Selective fetch area calculation failed in pipe A Sep 2 10:49:22 f13 kernel: Limiting tcp reset response from 193 to 192 packets/sec Sep 2 08:49:39 f13 sudo[6982]: ltning : TTY=pts/0 ; PWD=/home/ltning ; USER=root ; COMMAND=/usr/local/sbin/pkg upgrade -Fy Sep 2 10:50:11 f13 wpa_supplicant[6501]: wlan0: BSS: Remove id 5 BSSID 9e:ba:5f:d7:71:b2 SSID '' due to wpa_bss_flush_by_age Sep 2 10:50:11 f13 wpa_supplicant[6501]: wlan0: BSS: Remove id 12 BSSID d0:6e:de:91:51:5c SSID 'Get-2G-915157' due to wpa_bss_flush_by_age Sep 2 10:50:11 f13 wpa_supplicant[6501]: wlan0: BSS: Remove id 13 BSSID a2:ba:5f:d7:56:8f SSID 'Casa di Mau MLO' due to wpa_bss_flush_by_age Sep 2 10:50:11 f13 wpa_supplicant[6501]: wlan0: BSS: Remove id 14 BSSID 98:ba:5f:d7:56:8f SSID 'Casa di Mau' due to wpa_bss_flush_by_age Sep 2 10:50:11 f13 wpa_supplicant[6501]: wlan0: BSS: Remove id 15 BSSID fa:8f:ca:6d:4b:a6 SSID 'Stue.v,' due to wpa_bss_flush_by_age Sep 2 10:50:21 f13 wpa_supplicant[6501]: wlan0: BSS: Remove id 0 BSSID a2:ba:5f:d7:71:b3 SSID 'Casa di Mau MLO' due to wpa_bss_flush_by_age Sep 2 10:50:21 f13 wpa_supplicant[6501]: wlan0: BSS: Remove id 1 BSSID 98:ba:5f:d7:71:b3 SSID 'Casa di Mau' due to wpa_bss_flush_by_age Sep 2 10:50:21 f13 wpa_supplicant[6501]: wlan0: BSS: Remove id 4 BSSID 58:2f:f7:1d:9d:c7 SSID 'Telia-1D9DC1' due to wpa_bss_flush_by_age Sep 2 10:50:21 f13 wpa_supplicant[6501]: wlan0: BSS: Remove id 3 BSSID 98:ba:5f:d7:71:b2 SSID 'Casa di Mau' due to wpa_bss_flush_by_age Sep 2 10:50:21 f13 wpa_supplicant[6501]: wlan0: BSS: Remove id 16 BSSID ea:d2:ff:ab:83:0f SSID '' due to wpa_bss_flush_by_age Sep 2 10:50:21 f13 wpa_supplicant[6501]: wlan0: BSS: Remove id 7 BSSID e8:d2:ff:ab:81:0d SSID 'Telia-AB8107' due to wpa_bss_flush_by_age Sep 2 10:50:21 f13 wpa_supplicant[6501]: wlan0: BSS: Remove id 2 BSSID c8:d3:a3:67:87:4e SSID 'dlink-874E' due to wpa_bss_flush_by_age Sep 2 10:50:21 f13 wpa_supplicant[6501]: wlan0: BSS: Remove id 17 BSSID 58:2f:f7:1d:9d:c6 SSID 'Telia-2G-1D9DC1' due to wpa_bss_flush_by_age Sep 2 10:50:21 f13 wpa_supplicant[6501]: wlan0: BSS: Remove id 6 BSSID e8:d2:ff:ab:81:0c SSID 'Telia-AB8107' due to wpa_bss_flush_by_age Sep 2 10:50:21 f13 wpa_supplicant[6501]: wlan0: BSS: Remove id 11 BSSID c8:d3:a3:67:87:50 SSID 'dlink-874E-media' due to wpa_bss_flush_by_age Sep 2 10:50:21 f13 wpa_supplicant[6501]: wlan0: BSS: Remove id 8 BSSID 8e:c5:b4:db:f5:fa SSID 'Telia-2G-DBF4F3' due to wpa_bss_flush_by_age Sep 2 10:50:21 f13 wpa_supplicant[6501]: wlan0: BSS: Remove id 9 BSSID 8c:c5:b4:db:f4:f8 SSID 'Telia-DBF4F3' due to wpa_bss_flush_by_agehome | help
Want to link to this message? Use this
URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?cbaa3e61-5685-403e-bc43-d2076e7a46ac>