[00000.000000][0][0][klog] INFO: zbi: @ 0xffffff8000c79000 (12016728 bytes) [00000.000000][0][0][klog] INFO: UART: FIFO depth 16 [00000.000000][0][0][klog] INFO: PMM: boot reserve add [0x100000, 0x2fefff] [00000.000000][0][0][klog] INFO: PMM: boot reserve add [0xc79000, 0x17eefff] [00000.000000][0][0][klog] INFO: PMM: boot reserve marking WIRED [0x100000, 0x2fefff] [00000.000000][0][0][klog] INFO: PMM: boot reserve marking WIRED [0xc79000, 0x17eefff] [00000.000000][0][0][klog] INFO: [00000.000000][0][0][klog] INFO: welcome to Zircon [00000.000000][0][0][klog] INFO: [00000.000000][0][0][klog] INFO: KASLR: .text section at 0xffffffff00100000 [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00114e2c (global_prng_seed) at level 0x30000, flags 0x1 [00000.000000][0][0][klog] INFO: initializing vm pre-heap [00000.000000][0][0][klog] INFO: VM: marking boot alloc used range [0x17ef000, 0x17ef080) [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff001221dc (elf_build_id) at level 0x4fffe, flags 0x1 [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00122368 (version) at level 0x4ffff, flags 0x1 [00000.000000][0][0][klog] INFO: version: [00000.000000][0][0][klog] INFO: arch: x86 [00000.000000][0][0][klog] INFO: platform: XXX [00000.000000][0][0][klog] INFO: target: XXX [00000.000000][0][0][klog] INFO: project: x64 [00000.000000][0][0][klog] INFO: buildid: git-a82f2c2966aec77ef8214f506cbc85974e68177c-dirty [00000.000000][0][0][klog] INFO: ELF build ID: c48ad6a98adb3f2953f0ef45f6e4a1b7a1699be5 [00000.000000][0][0][klog] INFO: initializing heap [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00101fc4 (console) at level 0x50000, flags 0x1 [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff0018262c (x86_resource_init) at level 0x50000, flags 0x1 [00000.000000][0][0][klog] INFO: initializing vm [00000.000000][0][0][klog] INFO: VM: reserving kernel region [0xffffffff00100000, 0xffffffff0021b000) flags 0x28 name 'kernel_code' [00000.000000][0][0][klog] INFO: VM: reserving kernel region [0xffffffff0021b000, 0xffffffff0026b000) flags 0x8 name 'kernel_rodata' [00000.000000][0][0][klog] INFO: VM: reserving kernel region [0xffffffff0026b000, 0xffffffff00273000) flags 0x18 name 'kernel_data' [00000.000000][0][0][klog] INFO: VM: reserving kernel region [0xffffffff00273000, 0xffffffff002ff000) flags 0x18 name 'kernel_bss' [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff001832f0 (display_memtype) at level 0x60001, flags 0x1 [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00186780 (acpi_tables) at level 0x60001, flags 0x1 [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00181430 (hpet) at level 0x60002, flags 0x1 [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff001817f0 (apic) at level 0x60002, flags 0x1 [00000.000000][0][0][klog] INFO: x2APIC enabled [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff001a7dd4 (system_topology_init) at level 0x60002, flags 0x1 [00000.000000][0][0][klog] INFO: WARNING: unable to find any cache levels. [00000.000000][0][0][klog] INFO: WARNING: unable to find any cache levels. [00000.000000][0][0][klog] INFO: Could not find SRAT table. ACPICA returned: 5 [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00185d70 (timer) at level 0x60003, flags 0x1 [00000.000000][0][0][klog] INFO: pvclock: Clocksource is stable [00000.000000][0][0][klog] INFO: HPET frequency: 100000 ticks/ms [00000.000000][0][0][klog] INFO: pvclock: Fetching TSC frequency [00000.000000][0][0][klog] INFO: TSC frequency: 2300000 ticks/ms [00000.569215][0][0][klog] INFO: timer features: constant_tsc 1 invariant_tsc 1 tsc_deadline 1 [00000.569215][0][0][klog] INFO: Using TSC as wallclock [00000.569217][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff002085fc (percpu_heap_init) at level 0x60003, flags 0x1 [00000.569220][0][0][klog] INFO: initializing kernel [00000.569221][0][0][klog] INFO: initializing mp [00000.569223][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00115080 (global_prng_thread_safe) at level 0x7ffff, flags 0x1 [00000.569227][0][0][klog] INFO: creating bootstrap completion thread [00000.569329][0][0][klog] INFO: top of bootstrap2() [00000.569330][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff0013169c (libobject) at level 0x80000, flags 0x1 [00000.569423][0][0][klog] INFO: OOM: started thread [00000.569424][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00205568 (dpc) at level 0x80000, flags 0x1 [00000.569469][0][0][klog] INFO: Processor Model Info: type 0 family 0x6 model 0xf stepping 0 [00000.569470][0][0][klog] INFO: display_family 0x6 display_model 0x3f [00000.569471][0][0][klog] INFO: Vendor: Intel [00000.569471][0][0][klog] INFO: Microarch: Haswell [00000.569472][0][0][klog] INFO: F/M/S: 6/3f/0 [00000.569472][0][0][klog] INFO: patch_level: 0 [00000.569472][0][0][klog] INFO: Brand: Intel(R) Xeon(R) CPU @ 2.30GHz [00000.569474][0][0][klog] INFO: Features: fpu sse sse2 sse3 ssse3 sse4.1 sse4.2 mmx avx avx2 fxsr pcid xsave aesni clflush [00000.569476][0][0][klog] INFO: fsgsbase tsc_adj smep erms rdrand syscall nx rdtscp tsc_deadline x2apic hypervisor [00000.569477][0][0][klog] INFO: Properties: meltdown l1tf pcid_good [00000.569492][0][0][klog] INFO: initializing platform [00000.569493][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff001a14e4 (x86_perfmon) at level 0x90000, flags 0x1 [00000.569514][0][0][klog] INFO: x86_perfmon_init_once:454: perfmon: unexpected programmable counter width 0 in cpuid.0AH [00000.570039][0][0][klog] INFO: UART: started IRQ driven RX [00000.570040][0][0][klog] INFO: UART: started IRQ driven TX [00000.570057][0][0][klog] INFO: cpu topology: [00000.570058][0][0][klog] INFO: 0: apic id 0x0 BSP [00000.570059][0][0][klog] INFO: 1: apic id 0x1 [00000.570060][0][0][klog] INFO: 2: apic id 0x2 [00000.570060][0][0][klog] INFO: 3: apic id 0x3 [00000.570061][0][0][klog] INFO: Found 4 cpus [00000.570471][0][0][klog] INFO: booting apic ids: 0x1 0x2 0x3 [00000.582269][0][0][klog] INFO: entering scheduler on cpu 3 [00000.582675][0][0][klog] INFO: entering scheduler on cpu 1 [00000.582695][0][0][klog] INFO: entering scheduler on cpu 2 [00000.584449][0][0][klog] INFO: smbios: manufacturer="QEMU" product="Standard PC (Q35 + ICH9, 2009)" [00000.584500][0][0][klog] INFO: initializing target [00000.584502][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00185020 (x86_pcie_init) at level 0xa0000, flags 0x1 [00000.584551][0][0][klog] INFO: moving to last init level [00000.584552][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00121434 (debuglog) at level 0xb0000, flags 0x1 [00000.584647][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00174ca8 (kcounters) at level 0xb0000, flags 0x1 [00000.584654][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00102aec (kernel_shell) at level 0xc0000, flags 0x1 [00000.584683][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff002027a0 (ktrace) at level 0xc0000, flags 0x1 [00000.930270][0][0][klog] INFO: ktrace: buffer at 0xffffff96866fd000 (33554432 bytes) [00000.930347][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff002181d0 (userboot) at level 0xc0000, flags 0x1 [00000.930349][0][0][klog] INFO: userboot: ramdisk 0xb76000 @ 0xffffff8000c79000 [00000.945408][0][0][klog] INFO: userboot: userboot rodata 0 @ [0x6aaac3960000,0x6aaac3963000) [00000.945434][0][0][klog] INFO: userboot: userboot code 0x3000 @ [0x6aaac3963000,0x6aaac396e000) [00000.945436][0][0][klog] INFO: userboot: vdso/full rodata 0 @ [0x6aaac396e000,0x6aaac3975000) [00000.945437][0][0][klog] INFO: userboot: vdso/full code 0x7000 @ [0x6aaac3975000,0x6aaac3976000) [00000.945477][0][0][klog] INFO: userboot: entry point @ 0x6aaac3963c90 [00000.968811][1034][1041][klog] INFO: userboot: option "kernel.serial=legacy" [00000.968847][1034][1041][klog] INFO: userboot: option "netsvc.interface=/dev/sys/pci/00:1f.6/e1000/ethernet" [00000.968848][1034][1041][klog] INFO: userboot: option "/b/s/w/ir/multiboot-created_by_recipe.bin=" [00000.968849][1034][1041][klog] INFO: userboot: option "zircon.autorun.system=/boot/bin/sh+/boot/infra/runcmds" [00000.968849][1034][1041][klog] INFO: userboot: option "kernel.halt-on-panic=true" [00000.968850][1034][1041][klog] INFO: userboot: option "devmgr.suspend-timeout-debug=true" [00000.968851][1034][1041][klog] INFO: userboot: option "TERM=dumb" [00000.968852][1034][1041][klog] INFO: userboot: option "kernel.serial=legacy" [00000.968852][1034][1041][klog] INFO: userboot: option "multiboot.boot_loader_name=qemu" [00001.003262][1034][1041][klog] INFO: userboot: searching bootfs for 'bin/bootsvc' [00001.003319][1034][1041][klog] INFO: userboot: 'bin/bootsvc' has PT_INTERP "lib/ld.so.1" [00001.003319][1034][1041][klog] INFO: userboot: searching bootfs for 'lib/ld.so.1' [00001.003358][1034][1041][klog] INFO: userboot: userboot: loaded lib/ld.so.1 at 0x51067e0be000, entry point 0x51067e0dc3d0 [00001.003376][1034][1041][klog] INFO: userboot: userboot: loaded vDSO at 0x564469eb8000, entry point 0x564469ebf8fa [00001.003481][1034][1041][klog] INFO: userboot: process bin/bootsvc started. [00001.003509][1034][1041][klog] INFO: userboot: waiting for loader-service requests... [00001.034843][1034][1041][klog] INFO: userboot: searching bootfs for 'lib/libasync-default.so' [00001.035139][1034][1041][klog] INFO: userboot: searching bootfs for 'lib/libfdio.so' [00001.035377][1034][1041][klog] INFO: userboot: searching bootfs for 'lib/libtrace-engine.so' [00001.035627][1034][1041][klog] INFO: userboot: searching bootfs for 'lib/liblaunchpad.so' [00001.051699][1048][1051][klog] INFO: {{{reset}}} [00001.051748][1048][1051][klog] INFO: {{{module:0:<application>:elf:e11c6540887b5aa52d04997ed755a8ccd1f828cf}}} [00001.051749][1048][1051][klog] INFO: {{{mmap:0x1bfc16bb4000:0x3a000:load:0:rx:0}}} [00001.051751][1048][1051][klog] INFO: {{{mmap:0x1bfc16bee000:0x4000:load:0:rw:0x3a000}}} [00001.051752][1048][1051][klog] INFO: dso: id=e11c6540887b5aa52d04997ed755a8ccd1f828cf base=0x00001bfc16bb4000 name=<application> [00001.051752][1048][1051][klog] INFO: {{{module:0x2:<vDSO>:elf:71712e0a455898a51e34d40a4013786ab1fa5335}}} [00001.051753][1048][1051][klog] INFO: {{{mmap:0x564469eb8000:0x7000:load:0x2:r:0}}} [00001.051754][1048][1051][klog] INFO: {{{mmap:0x564469ebf000:0x1000:load:0x2:rx:0x7000}}} [00001.051754][1048][1051][klog] INFO: dso: id=71712e0a455898a51e34d40a4013786ab1fa5335 base=0x0000564469eb8000 name=<vDSO> [00001.051755][1048][1051][klog] INFO: {{{module:0x3:libasync-default.so:elf:8c511b4202cf569c8fdde14ca97222102ba3a9e0}}} [00001.051755][1048][1051][klog] INFO: {{{mmap:0x56d0a72ce000:0x1000:load:0x3:rx:0}}} [00001.051756][1048][1051][klog] INFO: {{{mmap:0x56d0a72cf000:0x1000:load:0x3:rw:0x1000}}} [00001.051756][1048][1051][klog] INFO: dso: id=8c511b4202cf569c8fdde14ca97222102ba3a9e0 base=0x000056d0a72ce000 name=libasync-default.so [00005.442326][2025604607][0][netstack] INFO: main.go(73): started [00001.051757][1048][1051][klog] INFO: {{{module:0x4:libfdio.so:elf:3baf3f80fb1f1179e58a0a45ce82d1c9eafda45c}}} [00005.443012][2025604607][0][netstack] INFO: netstack.go(672): NIC lo added [00005.449279][2025604607][0][netstack] WARNING: main.go(162): OnInterfacesChanged failed: ErrPeerClosed: zx.Channel.Write [00001.051758][1048][1051][klog] INFO: {{{mmap:0x69cfdfe31000:0x32000:load:0x4:rx:0}}} [00001.051759][1048][1051][klog] INFO: {{{mmap:0x69cfdfe64000:0x4000:load:0x4:rw:0x33000}}} [00005.552875][6841][6853][pkg_resolver] INFO: starting package resolver [00005.553093][6841][6853][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/main.rs(114): error loading static repo config: file /config/data/pkg_resolver/repositories failed to parse: No such file or directory (os error 2) [00001.051759][1048][1051][klog] INFO: dso: id=3baf3f80fb1f1179e58a0a45ce82d1c9eafda45c base=0x000069cfdfe31000 name=libfdio.so [00001.051760][1048][1051][klog] INFO: {{{module:0x5:libtrace-engine.so:elf:b82e75659fdaaa34e91100dc98d0a90f14f77ded}}} [00001.051760][1048][1051][klog] INFO: {{{mmap:0x55eb60cee000:0x9000:load:0x5:rx:0}}} [00001.051761][1048][1051][klog] INFO: {{{mmap:0x55eb60cf7000:0x2000:load:0x5:rw:0x9000}}} [00001.051761][1048][1051][klog] INFO: dso: id=b82e75659fdaaa34e91100dc98d0a90f14f77ded base=0x000055eb60cee000 name=libtrace-engine.so [00001.051762][1048][1051][klog] INFO: {{{module:0x6:liblaunchpad.so:elf:92e47a1f2729d76d9db7aa559442851234aa01a2}}} [00001.051762][1048][1051][klog] INFO: {{{mmap:0x751ebf085000:0x7000:load:0x6:rx:0}}} [00001.051763][1048][1051][klog] INFO: {{{mmap:0x751ebf08c000:0x2000:load:0x6:rw:0x7000}}} [00001.051763][1048][1051][klog] INFO: dso: id=92e47a1f2729d76d9db7aa559442851234aa01a2 base=0x0000751ebf085000 name=liblaunchpad.so [00001.051765][1048][1051][klog] INFO: {{{module:0x1:libc.so:elf:5d417db61b3a48a58a8fa1ffbfc2e71b830e0857}}} [00001.051765][1048][1051][klog] INFO: {{{mmap:0x51067e0be000:0xa6000:load:0x1:rx:0}}} [00001.051766][1048][1051][klog] INFO: {{{mmap:0x51067e164000:0x5000:load:0x1:rw:0xa6000}}} [00001.051766][1048][1051][klog] INFO: dso: id=5d417db61b3a48a58a8fa1ffbfc2e71b830e0857 base=0x000051067e0be000 name=libc.so [00001.052010][1048][1051][klog] INFO: bootsvc: Starting... [00001.052155][1048][1051][klog] INFO: bootsvc: Creating bootfs service... [00001.052240][1034][1041][klog] INFO: userboot: loader-service channel peer closed [00001.052437][1034][1041][klog] INFO: userboot: finished! [00001.206913][1048][1051][klog] INFO: bootsvc: Retrieving boot image... [00001.207068][1048][1051][klog] INFO: bootsvc: Decommitted BOOTDATA VMO from 0 to 0xb75000 [00001.207102][1048][1051][klog] INFO: bootsvc: Loading boot arguments... [00001.207204][1048][1051][klog] INFO: bootsvc: Creating svcfs service... [00001.207331][1048][1051][klog] INFO: bootsvc: Loading kernel VMOs... [00001.207459][1048][1051][klog] INFO: bootsvc: Creating loader service... [00001.207509][1048][1051][klog] INFO: bootsvc: Launching next process... [00001.208379][1048][1106][klog] INFO: bootsvc: Launched bin/devcoordinator [00001.236986][1113][1128][klog] INFO: devcoordinator: launch /boot/bin/svchost (svchost) OK [00001.241367][1113][1128][klog] INFO: devcoordinator: launch /boot/bin/fshost (fshost) OK [00001.259293][1113][1321][klog] INFO: devcoordinator: launch /boot/bin/pwrbtn-monitor (pwrbtn-monitor) OK [00001.260353][1113][1358][klog] INFO: devcoordinator: launch /boot/bin/netsvc (netsvc) OK [00001.271099][1534][1561][klog] INFO: netsvc: looking for interface /dev/sys/pci/00:1f.6/e1000/ethernet [00001.276146][1113][1358][klog] INFO: devcoordinator: launch /boot/bin/virtual-console (virtual-console) OK [00001.305655][1113][1128][klog] INFO: devcoordinator: full system required, ignoring fallback drivers until /system is loaded [00001.313041][1113][1128][klog] INFO: devcoordinator: launch devhost 'devhost:sys': pid=2193 [00001.320063][1113][1128][klog] INFO: devcoordinator: launch devhost 'devhost:test': pid=2240 [00001.330185][2193][2207][klog] INFO: devhost: trace provider registry begun [00001.332505][1113][1128][klog] INFO: devcoordinator: launch devhost 'devhost:root': pid=2297 [00001.340073][2240][2261][klog] INFO: devhost: trace provider registry begun [00001.341392][1113][1128][klog] INFO: devcoordinator: launch devhost 'devhost:misc': pid=2406 [00001.342805][2297][2349][klog] INFO: devhost: trace provider registry begun [00001.346459][2406][2436][klog] INFO: devhost: trace provider registry begun [00001.360906][1113][1338][klog] INFO: devcoordinator: launch /boot/bin/sh (sh:console) OK [00001.375046][2193][2207][klog] INFO: acpi-bus: not using IOMMU [00001.378779][2193][2207][klog] INFO: GetHwProperties: No PM support [00001.378878][2193][2207][klog] INFO: IntelPT: No PT support [00001.378887][2193][2207][klog] INFO: devhost[sys/cpu-trace] bind driver '/boot/driver/cpu-trace.so' failed: -2 [00001.379012][1113][1128][klog] INFO: devcoordinator: rpc: bind-driver 'cpu-trace' status -2 [00001.379863][2193][2207][klog] INFO: [sysmem_init 28] async_get_default_dispatcher(): 0x1af082be40d0 [00001.379905][2193][2207][klog] INFO: [sysmem_bind 40] sysmem_bind() [00001.381442][2193][2805][klog] INFO: acpi-pwrbtn: initialized [00001.392338][1656][1679][klog] INFO: vc: new input device /dev/class/input/000 [00001.490826][2193][2805][klog] INFO: acpi: published device pci(0x1aefc2bf0730), parent=sys(0x1af102be2410), handle=0x1aef82bdb650 [00001.491243][2193][2805][klog] INFO: acpi: failed to get PCI base bus number for device 'PCI0' (status 4294967295) [00001.494602][2193][2805][klog] INFO: acpi: published device rtc(0x1aefc2bdf030), parent=acpi(0x1af102be2110), handle=0x1aef82bdb2d0 [00001.511505][2193][2805][klog] INFO: acpi: published device i8042(0x1aefc2be6770), parent=acpi(0x1af102be2110), handle=0x1aef82bdb890 [00001.521750][1113][1128][klog] INFO: devcoordinator: launch devhost 'devhost:pci#1:1234:1111': pid=2910 [00001.530233][2910][2924][klog] INFO: devhost: trace provider registry begun [00001.530393][1113][1128][klog] INFO: devcoordinator: launch devhost 'devhost:pci#2:1af4:1001': pid=2977 [00001.542725][1113][1128][klog] INFO: devcoordinator: launch devhost 'devhost:pci#3:1af4:1000': pid=3051 [00001.558538][1113][1128][klog] INFO: devcoordinator: launch devhost 'devhost:pci#4:1af4:1001': pid=3133 [00001.558736][2977][3012][klog] INFO: devhost: trace provider registry begun [00001.562428][3051][3071][klog] INFO: devhost: trace provider registry begun [00001.569209][3133][3184][klog] INFO: devhost: trace provider registry begun [00001.569715][1113][1128][klog] INFO: devcoordinator: launch devhost 'devhost:pci#6:8086:2922': pid=3279 [00001.579267][2193][2207][klog] INFO: devhost: WARNING: driver '/boot/driver/pc-ps2.so' did not add device in bind() [00001.582753][3279][3316][klog] INFO: devhost: trace provider registry begun [00001.588484][3279][3316][klog] INFO: ahci: using MSI interrupt [00001.594521][2193][3372][klog] INFO: i8042 device 0xab 0x41 [00001.598005][2910][2924][klog] INFO: bochs_vbe: initialized display, 1024 x 768 (stride=1024 format=00020001) [00001.606701][1656][1679][klog] INFO: vc: new display device /dev/class/display-controller/000 [00001.620406][1656][1679][klog] INFO: vc: new input device /dev/class/input/001 [00001.631652][1656][1679][klog] INFO: vc: Successfully attached to display 1 [00001.662271][1299][1313][klog] INFO: fshost: /dev/class/block/001: FVM? [00001.667927][1534][1561][klog] INFO: netifc: ? /dev/class/ethernet/000 [00001.681345][1299][1313][klog] INFO: devcoordinator: launch /boot/bin/blobfs (blobfs:/blob) OK [00001.741442][2193][3372][klog] INFO: i8042 device 0x00 [00001.746597][2193][2207][klog] INFO: hid: boot mouse hack for "hid-device-002": report count (0->1), inp sz (24->24), out sz (0->0), feat sz (0->0) [00001.755848][1656][1679][klog] INFO: vc: new input device /dev/class/input/002 [00001.852179][1299][1313][klog] INFO: fshost: starting 'bin/pkgsvr' '4143db711c059dd90249ec45dadeba772df5a35c74ca256079ef716022f2dd65'... [00001.865763][1299][1313][klog] INFO: devcoordinator: launch bin/pkgsvr (pkgfs) OK [00001.902538][4044][4256][klog] INFO: pkgsvr: system: will be served from 4143db711c059dd90249ec45dadeba772df5a35c74ca256079ef716022f2dd65 [00001.902622][4044][4256][klog] INFO: pkgsvr: pkgfs serving index /data/pkgfs_index from blobfs /blob [00001.917931][1299][1313][klog] INFO: fshost: mounting minfs [00001.928978][1299][1313][klog] INFO: devcoordinator: launch /boot/bin/minfs (minfs:/data) OK [00001.943216][1113][4308][klog] INFO: devcoordinator: adding system driver 'bt_host' '/system/driver/bt-host.so' [00001.972819][1113][1730][klog] INFO: devcoordinator: launch /system/bin/appmgr (appmgr) OK [00001.972853][1113][1730][klog] INFO: autorun: starting '/boot/bin/sh' '/boot/infra/runcmds'... [00001.986619][1113][4308][klog] INFO: devcoordinator: adding system driver 'qmi_usb' '/system/driver/qmi-transport.so' [00001.999676][1113][1730][klog] INFO: devcoordinator: launch /boot/bin/sh (autorun:system) OK [00004.870414][1113][4308][klog] INFO: devcoordinator: adding system driver 'wlan' '/system/driver/wlan.so' [00004.894196][1113][4308][klog] INFO: devcoordinator: adding system driver 'bt_hog' '/system/driver/bt-hog.so' [00004.903842][1113][4308][klog] INFO: devcoordinator: adding system driver 'rtl88xx' '/system/driver/rtl88xx.so' [00004.914659][1113][4308][klog] INFO: devcoordinator: adding system driver 'brcmfmac' '/system/driver/brcmfmac.so' [00004.927273][1113][4308][klog] INFO: devcoordinator: adding system driver 'bt_hci_atheros' '/system/driver/bt-hci-atheros.so' [00004.942429][1113][4308][klog] INFO: devcoordinator: adding system driver 'wlan' '/system/driver/wlanphy.so' [00004.952248][1113][4308][klog] INFO: devcoordinator: adding system driver 'bt_hci_passthrough' '/system/driver/bt-hci-passthrough.so' [00004.965718][1113][4308][klog] INFO: devcoordinator: adding system driver 'wlan' '/system/driver/wlanif.so' [00004.979156][1113][4308][klog] INFO: devcoordinator: adding system driver 'iwlwifi_pci' '/system/driver/iwlwifi.so' [00004.991208][1113][4308][klog] INFO: devcoordinator: adding system driver 'qmi_fake' '/system/driver/qmi-fake.so' [00005.023818][1113][4308][klog] INFO: devcoordinator: adding system driver 'ralink' '/system/driver/ralink.so' [00005.037896][1113][4308][klog] INFO: devcoordinator: adding system driver 'bt_hci_intel' '/system/driver/bt-hci-intel.so' [00005.048898][4505][4524][klog] INFO: [INFO:namespace_builder.cc(93)] config-data for sysmgr [00005.060522][1113][4308][klog] INFO: devcoordinator: adding system driver 'virtual_audio' '/system/driver/virtual_audio_driver.so' [00005.071613][1113][4308][klog] INFO: devcoordinator: adding system driver 'usb_video' '/system/driver/usb_video.so' [00005.086628][1113][4308][klog] INFO: devcoordinator: adding system driver 'virtual_camera' '/system/driver/virtual_camera_driver.so' [00005.099353][1113][4308][klog] INFO: devcoordinator: adding system driver 'wlantapctl' '/system/driver/wlantap.so' [00005.111411][1113][4308][klog] INFO: devcoordinator: adding system driver 'bt_hci_fake' '/system/driver/bt-hci-fake.so' [00005.125922][1113][4308][klog] INFO: devcoordinator: adding system driver 'gpu' '/system/driver/libmsd_intel.so' [00005.138487][1113][4308][klog] INFO: devcoordinator: adding system driver 'ath10k_pci' '/system/driver/ath10k.so' [00005.147323][1113][1128][klog] INFO: devcoordinator: driver 'ath10k_pci' added [00005.147389][1113][1128][klog] INFO: devcoordinator: driver 'gpu' added [00005.147412][1113][1128][klog] INFO: devcoordinator: driver 'bt_hci_fake' added [00005.151093][1113][1128][klog] INFO: devcoordinator: driver 'usb_video' added [00005.151153][1113][1128][klog] INFO: devcoordinator: driver 'bt_hci_intel' added [00005.151156][1113][1128][klog] INFO: devcoordinator: driver 'ralink' added [00005.151160][1113][1128][klog] INFO: devcoordinator: driver 'qmi_fake' added [00005.151164][1113][1128][klog] INFO: devcoordinator: driver 'iwlwifi_pci' added [00005.151167][1113][1128][klog] INFO: devcoordinator: driver 'wlan' added [00005.151170][1113][1128][klog] INFO: devcoordinator: driver 'wlan' added [00005.151173][1113][1128][klog] INFO: devcoordinator: driver 'bt_hci_atheros' added [00005.151176][1113][1128][klog] INFO: devcoordinator: driver 'brcmfmac' added [00005.151181][1113][1128][klog] INFO: devcoordinator: driver 'rtl88xx' added [00005.151184][1113][1128][klog] INFO: devcoordinator: driver 'bt_hog' added [00005.151189][1113][1128][klog] INFO: devcoordinator: driver 'wlan' added [00005.151192][1113][1128][klog] INFO: devcoordinator: driver 'qmi_usb' added [00005.151195][1113][1128][klog] INFO: devcoordinator: driver 'bt_host' added [00005.151198][1113][1128][klog] INFO: devcoordinator: driver 'bt_hci_passthrough' added [00005.151201][1113][1128][klog] INFO: devcoordinator: fallback driver 'usb_composite' is available [00005.151203][1113][1128][klog] INFO: devcoordinator: driver 'usb_composite' added [00005.151206][1113][1128][klog] INFO: devcoordinator: fallback driver 'intel_disp' is available [00005.151207][1113][1128][klog] INFO: devcoordinator: driver 'intel_disp' added [00005.448885][4505][4524][klog] INFO: [INFO:namespace_builder.cc(93)] config-data for amber [00005.529304][4505][4524][klog] INFO: [INFO:namespace_builder.cc(93)] config-data for pkg_resolver [00005.721805][7415][7427][pkg_cache] INFO: starting package cache service [00005.723287][7415][7503][pkg_cache] INFO: spawning package cache service [00005.724431][7260][7272][klog] INFO: [INFO:cobalt_main.cc(219)] Cobalt is starting with the following parameters: schedule_interval=3600 seconds, min_interval=10 seconds, initial_interval=60 seconds, max_bytes_per_observation_store=1048576, event_aggregator_bac [00005.724501][7260][7272][klog] INFO: kfill_days=2, start_event_aggregator_worker=1. [00005.780844][1344936915][0][amber] INFO: error opening /misc/ota/current_channel.json: open /misc/ota/current_channel.json: ErrNotFound: io.directory.Open [00005.785744][1344936915][0][amber] INFO: monitoring for updates [00005.786586][1344936915][0][amber] INFO: system update monitor exited [00005.788641][1344936915][0][amber] INFO: control_server: could not get update for netcfg/0: merkle not found for package netcfg/0 [00005.789371][6841][6952][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/netcfg/0 was not found: merkle not found for package netcfg/0 [00005.789433][6841][6952][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00005.790306][1344936915][0][amber] INFO: control_server: could not get update for wlancfg/0: merkle not found for package wlancfg/0 [00005.790839][6841][6946][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/wlancfg/0 was not found: merkle not found for package wlancfg/0 [00005.790887][6841][6946][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00005.791743][1344936915][0][amber] INFO: control_server: could not get update for mdns/0: merkle not found for package mdns/0 [00005.792688][1344936915][0][amber] INFO: control_server: could not get update for memory_monitor/0: merkle not found for package memory_monitor/0 [00005.793034][1344936915][0][amber] INFO: control_server: could not get update for cobalt_system_metrics/0: merkle not found for package cobalt_system_metrics/0 [00005.792029][6841][6952][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/mdns/0 was not found: merkle not found for package mdns/0 [00005.792063][6841][6952][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00005.792805][6841][6946][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/memory_monitor/0 was not found: merkle not found for package memory_monitor/0 [00005.792811][6841][6946][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00005.793465][6841][6952][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/cobalt_system_metrics/0 was not found: merkle not found for package cobalt_system_metrics/0 [00005.793500][6841][6952][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00005.794154][1344936915][0][amber] INFO: control_server: could not get update for network_time/0: merkle not found for package network_time/0 [00005.794372][6841][6946][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/network_time/0 was not found: merkle not found for package network_time/0 [00005.794729][6841][6946][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00005.795172][1344936915][0][amber] INFO: control_server: could not get update for sshd-host/0: merkle not found for package sshd-host/0 [00005.795693][6841][6952][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/sshd-host/0 was not found: merkle not found for package sshd-host/0 [00005.795735][6841][6952][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00005.796361][1344936915][0][amber] INFO: control_server: could not get update for dhcpd/0: merkle not found for package dhcpd/0 [00005.796616][6841][6952][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/dhcpd/0 was not found: merkle not found for package dhcpd/0 [00005.796642][6841][6952][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00005.797198][1344936915][0][amber] INFO: control_server: could not get update for thermd/0: merkle not found for package thermd/0 [00005.797588][6841][6946][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/thermd/0 was not found: merkle not found for package thermd/0 [00005.797621][6841][6946][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00005.798378][1344936915][0][amber] INFO: control_server: could not get update for kernel_crash_checker/0: merkle not found for package kernel_crash_checker/0 [00005.799554][1344936915][0][amber] INFO: control_server: could not get update for mkdir/0: merkle not found for package mkdir/0 [00005.798561][6841][6946][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/kernel_crash_checker/0 was not found: merkle not found for package kernel_crash_checker/0 [00005.798607][6841][6946][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00005.799917][6841][6946][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/mkdir/0 was not found: merkle not found for package mkdir/0 [00005.799948][6841][6946][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00005.800487][1344936915][0][amber] INFO: control_server: could not get update for crashpad_agent/0: merkle not found for package crashpad_agent/0 [00005.800772][6841][6952][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/crashpad_agent/0 was not found: merkle not found for package crashpad_agent/0 [00005.801049][6841][6952][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00005.801681][1344936915][0][amber] INFO: control_server: could not get update for trace_manager/0: merkle not found for package trace_manager/0 [00005.802020][6841][6946][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/trace_manager/0 was not found: merkle not found for package trace_manager/0 [00005.802050][6841][6946][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00005.802924][1344936915][0][amber] INFO: control_server: could not get update for device_settings_manager/0: merkle not found for package device_settings_manager/0 [00005.803378][6841][6946][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/device_settings_manager/0 was not found: merkle not found for package device_settings_manager/0 [00005.803418][6841][6946][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00005.902922][4505][4524][klog] INFO: [INFO:namespace_builder.cc(93)] config-data for wlancfg [00005.978115][4505][4524][klog] INFO: [INFO:namespace_builder.cc(93)] config-data for mdns [00005.877700][7884][7902][netcfg] INFO: Started [00006.291899][1344936915][0][amber] INFO: control_server: could not get update for wlanstack2/0: merkle not found for package wlanstack2/0 [00006.293608][6841][6946][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/wlanstack2/0 was not found: merkle not found for package wlanstack2/0 [00006.293685][6841][6946][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00006.142358][8898][8910][network_time] INFO: main.cc(29): Opening client config from /pkg/data/roughtime-servers.json [00006.142366][8898][8910][network_time] INFO: main.cc(33): Connecting to RTC device at /dev/class/rtc/000 [00006.142370][8898][8910][network_time] INFO: timezone.cc(28): started [00006.379436][4505][4524][klog] INFO: [INFO:namespace_builder.cc(93)] config-data for crashpad_agent [00006.433276][10245][10262][klog] INFO: [00006.433316][10245][10262][klog] INFO: ------------------------------------------------ [00006.433319][10245][10262][klog] INFO: RUNNING TEST: /pkgfs/packages/ril-qmi-tests/0/test/ril-qmi-tests [00006.433319][10245][10262][klog] INFO: [00006.524571][10245][10262][klog] INFO: [00006.524598][10245][10262][klog] INFO: running 2 tests [00006.524642][10245][10262][klog] INFO: test client::tests::request_id ... ok [00006.524725][10245][10262][klog] INFO: test client::tests::no_client ... ok [00006.524785][10245][10262][klog] INFO: [00006.524945][10245][10262][klog] INFO: test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [00006.525019][10245][10262][klog] INFO: [00006.553506][10245][10262][klog] INFO: PASSED: /pkgfs/packages/ril-qmi-tests/0/test/ril-qmi-tests passed [00006.603379][10747][10769][klog] INFO: [INFO:main.cc(35)] Trace Manager starting with config: /pkg/data/tracing.config [00006.367392][9622][9634][crash] INFO: main.cc(58): no kernel crash log found [00006.618914][2025604607][0][netstack] WARNING: client.go(115): ErrNotSupported: ConfigMulticastSetPromiscuousMode [00006.619381][8723][8739][klog] INFO: [INFO:system_metrics_main.cc(23)] Cobalt SystemMetricsDaemon: System metrics daemon created. [00006.631024][9373][9385][dhcpd] INFO: starting server [00006.631714][2025604607][0][netstack] INFO: netstack.go(672): NIC ethp0003 added [00006.632000][2025604607][0][netstack] INFO: netstack.go(699): NIC ethp0003: link-local IPv6: fe80::5054:ff:fe12:3456 [00006.654801][2025604607][0][netstack] INFO: netstack.go(449): NIC ethp0003: starting [00006.728201][2025604607][0][netstack] INFO: netstack.go(367): NIC ethp0003: DHCP acquired IP 10.0.2.15 for 24h0m0s [00006.728353][2025604607][0][netstack] INFO: netstack.go(368): NIC ethp0003: Adding DNS servers: [10.0.2.3] [00006.728378][2025604607][0][netstack] INFO: netstack.go(378): adding routes [{Destination:0.0.0.0 Mask:0.0.0.0 Gateway:10.0.2.2 NIC:2} {Destination::: Mask::: Gateway: NIC:2} {Destination:10.0.2.0 Mask:255.255.255.0 Gateway: NIC:2}] with metric=<not-set> dynamic=true [00006.735068][10245][10262][klog] INFO: [00006.735100][10245][10262][klog] INFO: ------------------------------------------------ [00006.735224][10245][10262][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan_tests/0/test/wlan_ap_mlme_unittest [00006.735226][10245][10262][klog] INFO: [00006.753746][2025604607][0][netstack] WARNING: netstack.go(534): getNodeName: device settings error: key not set [00006.753888][2025604607][0][netstack] WARNING: netstack.go(534): getNodeName: device settings error: key not set [00006.807699][8898][8910][network_time] INFO: timezone.cc(107): time set to: 2019-04-19T16:29:08Z [00006.817887][2025604607][0][netstack, listen] INFO: socket_server.go(708): 0x7d3c901c8550: local={NIC:0 Addr: Port:22} [00006.808520][11034][11298][device_settings] INFO: setting string key: "DeviceName", val: "swarm-donut-petri-acre" [00006.818315][1344936915][0][amber] INFO: control_server: could not get update for cpuperf_provider/0: merkle not found for package cpuperf_provider/0 [00006.830267][1344936915][0][amber] INFO: control_server: could not get update for ktrace_provider/0: merkle not found for package ktrace_provider/0 [00006.826790][6841][6952][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/cpuperf_provider/0 was not found: merkle not found for package cpuperf_provider/0 [00006.827817][6841][6952][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00006.831620][6841][6946][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/ktrace_provider/0 was not found: merkle not found for package ktrace_provider/0 [00006.832683][6841][6946][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00006.901604][11428][11445][klog] INFO: wlanstack2 [I]: Starting [00006.956739][10245][10262][klog] INFO: [==========] Running 32 tests from 3 test suites. [00006.956741][10245][10262][klog] INFO: [----------] Global test environment set-up. [00006.956741][10245][10262][klog] INFO: [----------] 29 tests from ApInfraBssTest [00006.956742][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.StartAp [00006.956742][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.StartAp (0 ms) [00006.956743][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.Authenticate_Success [00006.956743][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.Authenticate_Success (0 ms) [00006.956744][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.Authenticate_SmeRefuses [00006.956744][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.Authenticate_SmeRefuses (0 ms) [00006.956745][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.Authenticate_Timeout [00006.956746][10245][10262][klog] INFO: wlan: [W] [client] [94:3c:49:49:9f:2d] timed out authenticating [00006.956746][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.Authenticate_Timeout (0 ms) [00006.956746][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.ReauthenticateWhileAuthenticated [00006.956747][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.ReauthenticateWhileAuthenticated (0 ms) [00006.956748][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.DeauthenticateWhileAuthenticated [00006.956748][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.DeauthenticateWhileAuthenticated (0 ms) [00006.956749][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.Associate_Success [00006.956751][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.Associate_Success (0 ms) [00006.956751][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.Associate_AssociationContext [00007.054085][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.Associate_AssociationContext (0 ms) [00007.054134][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.Associate_MultipleClients [00007.054168][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.Associate_MultipleClients (0 ms) [00007.054171][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.Associate_SmeRefuses [00007.054194][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.Associate_SmeRefuses (1 ms) [00007.054353][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.Associate_Timeout [00007.054356][10245][10262][klog] INFO: wlan: [W] [client] [94:3c:49:49:9f:2d] timed out associating [00007.054358][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.Associate_Timeout (0 ms) [00007.054361][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.Associate_EmptySsid [00007.054388][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.Associate_EmptySsid (0 ms) [00007.054388][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.Associate_EmptyRsn [00007.054389][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.Associate_EmptyRsn (0 ms) [00007.054390][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.ReauthenticateWhileAssociated [00007.054391][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.ReauthenticateWhileAssociated (0 ms) [00007.054391][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.ReassociationFlowWhileAssociated [00007.054392][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.ReassociationFlowWhileAssociated (0 ms) [00007.054393][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.DeauthenticateWhileAssociated [00007.054393][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.DeauthenticateWhileAssociated (0 ms) [00007.054409][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.Disassociate [00007.054430][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.Disassociate (0 ms) [00007.054430][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.Exchange_Eapol_Frames [00007.054431][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.Exchange_Eapol_Frames (0 ms) [00007.054431][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.SendFrameAfterAssociation [00007.054432][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.SendFrameAfterAssociation (0 ms) [00007.054432][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.UnprotectedApReceiveFramesAfterAssociation [00007.054433][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.UnprotectedApReceiveFramesAfterAssociation (0 ms) [00007.054434][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.MlmeDeauthReqWhileAssociated [00007.054434][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.MlmeDeauthReqWhileAssociated (0 ms) [00007.054435][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.SetKeys [00007.054435][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.SetKeys (0 ms) [00007.054436][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.SetKeys_IgnoredForUnprotectedAp [00007.054436][10245][10262][klog] INFO: wlan: [W] [infra-bss] ignoring SetKeysRequest since AP is unprotected [00007.054437][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.SetKeys_IgnoredForUnprotectedAp (0 ms) [00007.054437][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.PowerSaving_IgnoredBeforeControlledPortOpens [00007.054438][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.PowerSaving_IgnoredBeforeControlledPortOpens (0 ms) [00007.056698][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.PowerSaving_AfterControlledPortOpens [00007.056761][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.PowerSaving_AfterControlledPortOpens (0 ms) [00007.056836][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.PowerSaving_UnprotectedAp [00007.056837][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.PowerSaving_UnprotectedAp (0 ms) [00007.056838][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.OutboundFramesAreProtectedAfterControlledPortOpens [00007.056839][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.OutboundFramesAreProtectedAfterControlledPortOpens (0 ms) [00007.056840][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.ReceiveFrames_BeforeControlledPortOpens [00007.056841][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.ReceiveFrames_BeforeControlledPortOpens (0 ms) [00007.056842][10245][10262][klog] INFO: [ RUN ] ApInfraBssTest.ReceiveFrames_AfterControlledPortOpens [00007.056843][10245][10262][klog] INFO: [ OK ] ApInfraBssTest.ReceiveFrames_AfterControlledPortOpens (0 ms) [00007.056844][10245][10262][klog] INFO: [----------] 29 tests from ApInfraBssTest (1 ms total) [00007.056844][10245][10262][klog] INFO: [00007.056845][10245][10262][klog] INFO: [----------] 2 tests from BeaconSenderTest [00007.056847][10245][10262][klog] INFO: [ RUN ] BeaconSenderTest.Start [00007.056848][10245][10262][klog] INFO: [ OK ] BeaconSenderTest.Start (0 ms) [00007.056849][10245][10262][klog] INFO: [ RUN ] BeaconSenderTest.ProbeRequest [00007.056850][10245][10262][klog] INFO: [ OK ] BeaconSenderTest.ProbeRequest (0 ms) [00007.056850][10245][10262][klog] INFO: [----------] 2 tests from BeaconSenderTest (0 ms total) [00007.056851][10245][10262][klog] INFO: [00007.056857][10245][10262][klog] INFO: [----------] 1 test from BeaconSender [00007.056858][10245][10262][klog] INFO: [ RUN ] BeaconSender.ShouldSendProbeResponse [00007.056858][10245][10262][klog] INFO: [ OK ] BeaconSender.ShouldSendProbeResponse (0 ms) [00007.056859][10245][10262][klog] INFO: [----------] 1 test from BeaconSender (0 ms total) [00007.056859][10245][10262][klog] INFO: [00007.056860][10245][10262][klog] INFO: [----------] Global test environment tear-down [00007.056861][10245][10262][klog] INFO: [==========] 32 tests from 3 test suites ran. (1 ms total) [00007.056861][10245][10262][klog] INFO: [ PASSED ] 32 tests. [00007.059677][10245][10262][klog] INFO: PASSED: /pkgfs/packages/wlan_tests/0/test/wlan_ap_mlme_unittest passed [00007.373799][10245][10262][klog] INFO: [00007.373854][10245][10262][klog] INFO: ------------------------------------------------ [00007.373857][10245][10262][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan_tests/0/test/wlan_buffer_utils_unittest [00007.373857][10245][10262][klog] INFO: [00007.426420][10245][10262][klog] INFO: [==========] Running 3 tests from 1 test suite. [00007.426454][10245][10262][klog] INFO: [----------] Global test environment set-up. [00007.426455][10245][10262][klog] INFO: [----------] 3 tests from BufferUtils [00007.426455][10245][10262][klog] INFO: [ RUN ] BufferUtils.Writer [00007.426456][10245][10262][klog] INFO: [ OK ] BufferUtils.Writer (0 ms) [00007.426457][10245][10262][klog] INFO: [ RUN ] BufferUtils.Reader [00007.426457][10245][10262][klog] INFO: [ OK ] BufferUtils.Reader (0 ms) [00007.426458][10245][10262][klog] INFO: [ RUN ] BufferUtils.Reader_ReadArray [00007.426458][10245][10262][klog] INFO: [ OK ] BufferUtils.Reader_ReadArray (0 ms) [00007.426459][10245][10262][klog] INFO: [----------] 3 tests from BufferUtils (0 ms total) [00007.426459][10245][10262][klog] INFO: [00007.426460][10245][10262][klog] INFO: [----------] Global test environment tear-down [00007.426461][10245][10262][klog] INFO: [==========] 3 tests from 1 test suite ran. (0 ms total) [00007.426461][10245][10262][klog] INFO: [ PASSED ] 3 tests. [00007.427504][10245][10262][klog] INFO: PASSED: /pkgfs/packages/wlan_tests/0/test/wlan_buffer_utils_unittest passed [00007.597823][10245][10262][klog] INFO: [00007.597952][10245][10262][klog] INFO: ------------------------------------------------ [00007.597962][10245][10262][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan_tests/0/test/wlan_client_mlme_unittest [00007.597990][10245][10262][klog] INFO: [00007.657736][10245][10262][klog] INFO: [==========] Running 28 tests from 1 test suite. [00007.657771][10245][10262][klog] INFO: [----------] Global test environment set-up. [00007.657772][10245][10262][klog] INFO: [----------] 28 tests from ClientTest [00007.657773][10245][10262][klog] INFO: [ RUN ] ClientTest.Join [00007.657773][10245][10262][klog] INFO: [ OK ] ClientTest.Join (0 ms) [00007.657774][10245][10262][klog] INFO: [ RUN ] ClientTest.Authenticate [00007.657774][10245][10262][klog] INFO: [ OK ] ClientTest.Authenticate (0 ms) [00007.657775][10245][10262][klog] INFO: [ RUN ] ClientTest.Associate_Protected [00007.657775][10245][10262][klog] INFO: wlan: [I] NIC 94:3c:49:49:9f:2d associated with "Fuchsia-AP"(b7:cd:3f:b0:93:01) in channel 36 CBW40, 5 GHz, 802.11n HT [00007.657776][10245][10262][klog] INFO: wlan: [I] lost BSS; deauthenticating... [00007.657777][10245][10262][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x76a4f96, actual: 0x672a8b1e [00007.657778][10245][10262][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x76a4f96, actual: 0x65335691 [00007.657778][10245][10262][klog] INFO: [ OK ] ClientTest.Associate_Protected (0 ms) [00007.657779][10245][10262][klog] INFO: [ RUN ] ClientTest.Associate_Unprotected [00007.657779][10245][10262][klog] INFO: wlan: [I] NIC 94:3c:49:49:9f:2d associated with "Fuchsia-AP"(b7:cd:3f:b0:93:01) in channel 36 CBW40, 5 GHz, 802.11n HT [00007.657780][10245][10262][klog] INFO: [ OK ] ClientTest.Associate_Unprotected (0 ms) [00007.657781][10245][10262][klog] INFO: [ RUN ] ClientTest.ExchangeEapolFrames [00007.658467][10245][10262][klog] INFO: wlan: [I] NIC 94:3c:49:49:9f:2d associated with "Fuchsia-AP"(b7:cd:3f:b0:93:01) in channel 36 CBW40, 5 GHz, 802.11n HT [00007.658607][10245][10262][klog] INFO: [ OK ] ClientTest.ExchangeEapolFrames (0 ms) [00007.658626][10245][10262][klog] INFO: [ RUN ] ClientTest.SetKeys [00007.658664][10245][10262][klog] INFO: wlan: [I] NIC 94:3c:49:49:9f:2d associated with "Fuchsia-AP"(b7:cd:3f:b0:93:01) in channel 36 CBW40, 5 GHz, 802.11n HT [00007.658666][10245][10262][klog] INFO: [ OK ] ClientTest.SetKeys (0 ms) [00007.658666][10245][10262][klog] INFO: [ RUN ] ClientTest.ConstructAssociateContext [00007.658667][10245][10262][klog] INFO: wlan: [I] NIC 94:3c:49:49:9f:2d associated with "Fuchsia-AP"(b7:cd:3f:b0:93:01) in channel 36 CBW40, 5 GHz, 802.11n HT [00007.658668][10245][10262][klog] INFO: [ OK ] ClientTest.ConstructAssociateContext (0 ms) [00007.658669][10245][10262][klog] INFO: [ RUN ] ClientTest.AuthTimeout [00007.658669][10245][10262][klog] INFO: [ OK ] ClientTest.AuthTimeout (0 ms) [00007.658670][10245][10262][klog] INFO: [ RUN ] ClientTest.AssocTimeout [00007.658671][10245][10262][klog] INFO: [ OK ] ClientTest.AssocTimeout (0 ms) [00007.658701][10245][10262][klog] INFO: [ RUN ] ClientTest.ReceiveDataAfterAssociation_Protected [00007.658702][10245][10262][klog] INFO: wlan: [I] NIC 94:3c:49:49:9f:2d associated with "Fuchsia-AP"(b7:cd:3f:b0:93:01) in channel 36 CBW40, 5 GHz, 802.11n HT [00007.658702][10245][10262][klog] INFO: [ OK ] ClientTest.ReceiveDataAfterAssociation_Protected (0 ms) [00007.658703][10245][10262][klog] INFO: [ RUN ] ClientTest.SendDataAfterAssociation_Protected [00007.658708][10245][10262][klog] INFO: wlan: [I] NIC 94:3c:49:49:9f:2d associated with "Fuchsia-AP"(b7:cd:3f:b0:93:01) in channel 36 CBW40, 5 GHz, 802.11n HT [00007.658709][10245][10262][klog] INFO: [ OK ] ClientTest.SendDataAfterAssociation_Protected (0 ms) [00007.658710][10245][10262][klog] INFO: [ RUN ] ClientTest.SendKeepAliveFrameAfterAssociation_Protected [00007.658710][10245][10262][klog] INFO: wlan: [I] NIC 94:3c:49:49:9f:2d associated with "Fuchsia-AP"(b7:cd:3f:b0:93:01) in channel 36 CBW40, 5 GHz, 802.11n HT [00007.658711][10245][10262][klog] INFO: [ OK ] ClientTest.SendKeepAliveFrameAfterAssociation_Protected (0 ms) [00007.658712][10245][10262][klog] INFO: [ RUN ] ClientTest.ReceiveDataAfterAssociation_Unprotected [00007.658713][10245][10262][klog] INFO: wlan: [I] NIC 94:3c:49:49:9f:2d associated with "Fuchsia-AP"(b7:cd:3f:b0:93:01) in channel 36 CBW40, 5 GHz, 802.11n HT [00007.658713][10245][10262][klog] INFO: [ OK ] ClientTest.ReceiveDataAfterAssociation_Unprotected (0 ms) [00007.658714][10245][10262][klog] INFO: [ RUN ] ClientTest.SendDataAfterAssociation_Unprotected [00007.658715][10245][10262][klog] INFO: wlan: [I] NIC 94:3c:49:49:9f:2d associated with "Fuchsia-AP"(b7:cd:3f:b0:93:01) in channel 36 CBW40, 5 GHz, 802.11n HT [00007.658716][10245][10262][klog] INFO: [ OK ] ClientTest.SendDataAfterAssociation_Unprotected (0 ms) [00007.658717][10245][10262][klog] INFO: [ RUN ] ClientTest.SendKeepAliveFrameAfterAssociation_Unprotected [00007.659512][10245][10262][klog] INFO: wlan: [I] NIC 94:3c:49:49:9f:2d associated with "Fuchsia-AP"(b7:cd:3f:b0:93:01) in channel 36 CBW40, 5 GHz, 802.11n HT [00007.659538][10245][10262][klog] INFO: [ OK ] ClientTest.SendKeepAliveFrameAfterAssociation_Unprotected (0 ms) [00007.659539][10245][10262][klog] INFO: [ RUN ] ClientTest.ProcessEmptyDataFrames [00007.659539][10245][10262][klog] INFO: wlan: [I] NIC 94:3c:49:49:9f:2d associated with "Fuchsia-AP"(b7:cd:3f:b0:93:01) in channel 36 CBW40, 5 GHz, 802.11n HT [00007.659541][10245][10262][klog] INFO: [ OK ] ClientTest.ProcessEmptyDataFrames (1 ms) [00007.659541][10245][10262][klog] INFO: [ RUN ] ClientTest.ProcessAmsduDataFrame [00007.659542][10245][10262][klog] INFO: wlan: [I] NIC 94:3c:49:49:9f:2d associated with "Fuchsia-AP"(b7:cd:3f:b0:93:01) in channel 36 CBW40, 5 GHz, 802.11n HT [00007.659545][10245][10262][klog] INFO: [ OK ] ClientTest.ProcessAmsduDataFrame (0 ms) [00007.659548][10245][10262][klog] INFO: [ RUN ] ClientTest.DropManagementFrames [00007.659548][10245][10262][klog] INFO: wlan: [I] NIC 94:3c:49:49:9f:2d associated with "Fuchsia-AP"(b7:cd:3f:b0:93:01) in channel 36 CBW40, 5 GHz, 802.11n HT [00007.659549][10245][10262][klog] INFO: [ OK ] ClientTest.DropManagementFrames (0 ms) [00007.659550][10245][10262][klog] INFO: [ RUN ] ClientTest.AutoDeauth_NoBeaconReceived [00007.659550][10245][10262][klog] INFO: wlan: [I] NIC 94:3c:49:49:9f:2d associated with "Fuchsia-AP"(b7:cd:3f:b0:93:01) in channel 36 CBW40, 5 GHz, 802.11n HT [00007.659555][10245][10262][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x672a8b1e [00007.659556][10245][10262][klog] INFO: wlan: [I] lost BSS; deauthenticating... [00007.659557][10245][10262][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x672a8b1e [00007.659557][10245][10262][klog] INFO: [ OK ] ClientTest.AutoDeauth_NoBeaconReceived (0 ms) [00007.659558][10245][10262][klog] INFO: [ RUN ] ClientTest.AutoDeauth_NoBeaconsShortlyAfterConnecting [00007.659559][10245][10262][klog] INFO: wlan: [I] NIC 94:3c:49:49:9f:2d associated with "Fuchsia-AP"(b7:cd:3f:b0:93:01) in channel 36 CBW40, 5 GHz, 802.11n HT [00007.659561][10245][10262][klog] INFO: wlan: [I] lost BSS; deauthenticating... [00007.659564][10245][10262][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x672a8b1e [00007.659688][10245][10262][klog] INFO: [ OK ] ClientTest.AutoDeauth_NoBeaconsShortlyAfterConnecting (0 ms) [00007.659689][10245][10262][klog] INFO: [ RUN ] ClientTest.AutoDeauth_DoNotDeauthWhileSwitchingChannel [00007.659690][10245][10262][klog] INFO: wlan: [I] NIC 94:3c:49:49:9f:2d associated with "Fuchsia-AP"(b7:cd:3f:b0:93:01) in channel 36 CBW40, 5 GHz, 802.11n HT [00007.659691][10245][10262][klog] INFO: wlan: [I] lost BSS; deauthenticating... [00007.659691][10245][10262][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x672a8b1e [00007.659692][10245][10262][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x672a8b1e [00007.659692][10245][10262][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x345b1b19 [00007.662123][10245][10262][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x672a8b1e [00007.662219][10245][10262][klog] INFO: [ OK ] ClientTest.AutoDeauth_DoNotDeauthWhileSwitchingChannel (0 ms) [00007.662222][10245][10262][klog] INFO: [ RUN ] ClientTest.AutoDeauth_InterleavingBeaconsAndChannelSwitches [00007.662223][10245][10262][klog] INFO: wlan: [I] NIC 94:3c:49:49:9f:2d associated with "Fuchsia-AP"(b7:cd:3f:b0:93:01) in channel 36 CBW40, 5 GHz, 802.11n HT [00007.662255][10245][10262][klog] INFO: wlan: [I] lost BSS; deauthenticating... [00007.662256][10245][10262][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x672a8b1e [00007.662336][10245][10262][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x345b1b19 [00007.662361][10245][10262][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x345b1b19 [00007.662362][10245][10262][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x672a8b1e [00007.662363][10245][10262][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x345b1b19 [00007.662363][10245][10262][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x672a8b1e [00007.662364][10245][10262][klog] INFO: [ OK ] ClientTest.AutoDeauth_InterleavingBeaconsAndChannelSwitches (0 ms) [00007.662365][10245][10262][klog] INFO: [ RUN ] ClientTest.AutoDeauth_SwitchingChannelBeforeDeauthTimeoutCouldTrigger [00007.662369][10245][10262][klog] INFO: wlan: [I] NIC 94:3c:49:49:9f:2d associated with "Fuchsia-AP"(b7:cd:3f:b0:93:01) in channel 36 CBW40, 5 GHz, 802.11n HT [00007.662369][10245][10262][klog] INFO: wlan: [I] lost BSS; deauthenticating... [00007.662371][10245][10262][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x672a8b1e [00007.662371][10245][10262][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x345b1b19 [00007.662372][10245][10262][klog] INFO: [ OK ] ClientTest.AutoDeauth_SwitchingChannelBeforeDeauthTimeoutCouldTrigger (0 ms) [00007.662373][10245][10262][klog] INFO: [ RUN ] ClientTest.AutoDeauth_ForeignBeaconShouldNotPreventDeauth [00007.662374][10245][10262][klog] INFO: wlan: [I] NIC 94:3c:49:49:9f:2d associated with "Fuchsia-AP"(b7:cd:3f:b0:93:01) in channel 36 CBW40, 5 GHz, 802.11n HT [00007.662374][10245][10262][klog] INFO: wlan: [I] lost BSS; deauthenticating... [00007.662375][10245][10262][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x672a8b1e [00007.662376][10245][10262][klog] INFO: [ OK ] ClientTest.AutoDeauth_ForeignBeaconShouldNotPreventDeauth (0 ms) [00007.662376][10245][10262][klog] INFO: [ RUN ] ClientTest.DropFramesWhileOffChannel [00007.662377][10245][10262][klog] INFO: wlan: [I] NIC 94:3c:49:49:9f:2d associated with "Fuchsia-AP"(b7:cd:3f:b0:93:01) in channel 36 CBW40, 5 GHz, 802.11n HT [00007.662377][10245][10262][klog] INFO: [ OK ] ClientTest.DropFramesWhileOffChannel (0 ms) [00007.662378][10245][10262][klog] INFO: [ RUN ] ClientTest.InvalidAuthenticationResponse [00007.662378][10245][10262][klog] INFO: [ OK ] ClientTest.InvalidAuthenticationResponse (0 ms) [00007.662632][10245][10262][klog] INFO: [ RUN ] ClientTest.FailureToAssociateWithAPWithUnsupportedBasicRate [00007.662683][10245][10262][klog] INFO: wlan: [E] Ap demands 1 basic rates. Client supports 0. [00007.662684][10245][10262][klog] INFO: [ OK ] ClientTest.FailureToAssociateWithAPWithUnsupportedBasicRate (0 ms) [00007.662685][10245][10262][klog] INFO: [ RUN ] ClientTest.FailureToAssociateWithAPWithoutAnySupportedRate [00007.662686][10245][10262][klog] INFO: [ OK ] ClientTest.FailureToAssociateWithAPWithoutAnySupportedRate (0 ms) [00007.662686][10245][10262][klog] INFO: [----------] 28 tests from ClientTest (1 ms total) [00007.662687][10245][10262][klog] INFO: [00007.662687][10245][10262][klog] INFO: [----------] Global test environment tear-down [00007.662688][10245][10262][klog] INFO: [==========] 28 tests from 1 test suite ran. (1 ms total) [00007.662688][10245][10262][klog] INFO: [ PASSED ] 28 tests. [00007.663082][10245][10262][klog] INFO: PASSED: /pkgfs/packages/wlan_tests/0/test/wlan_client_mlme_unittest passed [00007.793829][8296][8310][klog] INFO: Starting mDNS on interface ethp0003 10.0.2.15 [00007.798967][8296][8310][klog] INFO: [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v4.cc(68)] NET-2177 IP_TTL not supported (ENOPROTOOPT), continuing anyway. May cause spurious IP traffic [00007.800226][8296][8310][klog] INFO: Starting mDNS on interface ethp0003 [80fe::5450:ff00:12fe:5634] [00007.803415][2025604607][0][netstack] INFO: socket_conv.go(523): unimplemented setsockopt: SOL_IPV6 name=19 optVal=00 [00007.803552][8296][8310][klog] INFO: [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(29)] NET-291 IPV6_MULTICAST_LOOP not supported (ENOPROTOOPT), continuing anyway [00007.811195][8296][8310][klog] INFO: [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(50)] NET-2180 IPV6_JOIN_GROUP returned ENODEV, mDNS will not communicate via IPV6 [00007.812657][8296][8310][klog] INFO: mDNS: Verifying uniqueness of host name swarm-donut-petri-acre.local. [00008.014453][10245][10262][klog] INFO: [00008.014487][10245][10262][klog] INFO: ------------------------------------------------ [00008.014490][10245][10262][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan_tests/0/test/wlan_mac_unittest [00008.014490][10245][10262][klog] INFO: [00008.117511][10245][10262][klog] INFO: [==========] Running 254 tests from 36 test suites. [00008.117538][10245][10262][klog] INFO: [----------] Global test environment set-up. [00008.117539][10245][10262][klog] INFO: [----------] 4 tests from AssociationRatesTest [00008.117539][10245][10262][klog] INFO: [ RUN ] AssociationRatesTest.Success [00008.117540][10245][10262][klog] INFO: [ OK ] AssociationRatesTest.Success (0 ms) [00008.117541][10245][10262][klog] INFO: [ RUN ] AssociationRatesTest.SuccessWithDuplicateRates [00008.117541][10245][10262][klog] INFO: [ OK ] AssociationRatesTest.SuccessWithDuplicateRates (0 ms) [00008.117542][10245][10262][klog] INFO: [ RUN ] AssociationRatesTest.FailureNoApBasicRatesSupported [00008.117543][10245][10262][klog] INFO: wlan: [E] Ap demands 1 basic rates. Client supports 0. [00008.117544][10245][10262][klog] INFO: [ OK ] AssociationRatesTest.FailureNoApBasicRatesSupported (0 ms) [00008.117544][10245][10262][klog] INFO: [ RUN ] AssociationRatesTest.FailureApBasicRatesPartiallySupported [00008.117545][10245][10262][klog] INFO: wlan: [E] Ap demands 2 basic rates. Client supports 1. [00008.117546][10245][10262][klog] INFO: [ OK ] AssociationRatesTest.FailureApBasicRatesPartiallySupported (0 ms) [00008.117546][10245][10262][klog] INFO: [----------] 4 tests from AssociationRatesTest (0 ms total) [00008.117547][10245][10262][klog] INFO: [00008.117547][10245][10262][klog] INFO: [----------] 2 tests from ParseAssocRespIe [00008.117548][10245][10262][klog] INFO: [ RUN ] ParseAssocRespIe.ParseToFail [00008.117548][10245][10262][klog] INFO: [ OK ] ParseAssocRespIe.ParseToFail (0 ms) [00008.117549][10245][10262][klog] INFO: [ RUN ] ParseAssocRespIe.Parse [00008.118356][10245][10262][klog] INFO: [ OK ] ParseAssocRespIe.Parse (0 ms) [00008.118375][10245][10262][klog] INFO: [----------] 2 tests from ParseAssocRespIe (0 ms total) [00008.118376][10245][10262][klog] INFO: [00008.118376][10245][10262][klog] INFO: [----------] 8 tests from AssocContext [00008.118377][10245][10262][klog] INFO: [ RUN ] AssocContext.IntersectHtNoVht [00008.118380][10245][10262][klog] INFO: [ OK ] AssocContext.IntersectHtNoVht (0 ms) [00008.118380][10245][10262][klog] INFO: [ RUN ] AssocContext.IntersectClientNoHT [00008.118380][10245][10262][klog] INFO: [ OK ] AssocContext.IntersectClientNoHT (0 ms) [00008.118381][10245][10262][klog] INFO: [ RUN ] AssocContext.IntersectHtVht [00008.118381][10245][10262][klog] INFO: [ OK ] AssocContext.IntersectHtVht (0 ms) [00008.118382][10245][10262][klog] INFO: [ RUN ] AssocContext.IntersectClientNoVht [00008.118382][10245][10262][klog] INFO: [ OK ] AssocContext.IntersectClientNoVht (0 ms) [00008.118383][10245][10262][klog] INFO: [ RUN ] AssocContext.IntersectBssNoHT [00008.118383][10245][10262][klog] INFO: [ OK ] AssocContext.IntersectBssNoHT (0 ms) [00008.118383][10245][10262][klog] INFO: [ RUN ] AssocContext.IntersectBssNoVht [00008.118384][10245][10262][klog] INFO: [ OK ] AssocContext.IntersectBssNoVht (0 ms) [00008.118384][10245][10262][klog] INFO: [ RUN ] AssocContext.MakeBssAssocCtx [00008.118385][10245][10262][klog] INFO: [ OK ] AssocContext.MakeBssAssocCtx (0 ms) [00008.118385][10245][10262][klog] INFO: [ RUN ] AssocContext.ToDdk [00008.118386][10245][10262][klog] INFO: [ OK ] AssocContext.ToDdk (0 ms) [00008.118386][10245][10262][klog] INFO: [----------] 8 tests from AssocContext (0 ms total) [00008.118386][10245][10262][klog] INFO: [00008.118387][10245][10262][klog] INFO: [----------] 6 tests from ChannelTest [00008.118387][10245][10262][klog] INFO: [ RUN ] ChannelTest.ValidCombo [00008.118387][10245][10262][klog] INFO: [ OK ] ChannelTest.ValidCombo (0 ms) [00008.118391][10245][10262][klog] INFO: [ RUN ] ChannelTest.Equality [00008.118391][10245][10262][klog] INFO: [ OK ] ChannelTest.Equality (0 ms) [00008.118392][10245][10262][klog] INFO: [ RUN ] ChannelTest.InvalidCombo [00008.118392][10245][10262][klog] INFO: wlan: [E] invalid channel value: 0 [00008.118392][10245][10262][klog] INFO: wlan: [E] invalid channel value: 0 [00008.118393][10245][10262][klog] INFO: wlan: [E] invalid channel value: 15 [00008.118393][10245][10262][klog] INFO: wlan: [E] invalid channel value: 15 [00008.118393][10245][10262][klog] INFO: wlan: [E] invalid channel value: 8+ [00008.118394][10245][10262][klog] INFO: wlan: [E] invalid channel value: 8+ [00008.118394][10245][10262][klog] INFO: wlan: [E] invalid channel value: 4- [00008.118395][10245][10262][klog] INFO: wlan: [E] invalid channel value: 4- [00008.118404][10245][10262][klog] INFO: wlan: [E] invalid channel value: 32 [00008.118404][10245][10262][klog] INFO: wlan: [E] invalid channel value: 32 [00008.118405][10245][10262][klog] INFO: wlan: [E] invalid channel value: 68 [00008.118405][10245][10262][klog] INFO: wlan: [E] invalid channel value: 68 [00008.118406][10245][10262][klog] INFO: wlan: [E] invalid channel value: 96 [00008.118406][10245][10262][klog] INFO: wlan: [E] invalid channel value: 96 [00008.118406][10245][10262][klog] INFO: wlan: [E] invalid channel value: 148 [00008.118407][10245][10262][klog] INFO: wlan: [E] invalid channel value: 148 [00008.118408][10245][10262][klog] INFO: wlan: [E] invalid channel value: 183 [00008.118408][10245][10262][klog] INFO: wlan: [E] invalid channel value: 183 [00008.118409][10245][10262][klog] INFO: wlan: [E] invalid channel value: 36- [00008.118409][10245][10262][klog] INFO: wlan: [E] invalid channel value: 36- [00008.118410][10245][10262][klog] INFO: wlan: [E] invalid channel value: 40+ [00008.118410][10245][10262][klog] INFO: wlan: [E] invalid channel value: 40+ [00008.118411][10245][10262][klog] INFO: wlan: [E] invalid channel value: 149- [00008.118411][10245][10262][klog] INFO: wlan: [E] invalid channel value: 149- [00008.118412][10245][10262][klog] INFO: wlan: [E] invalid channel value: 153+ [00008.118950][10245][10262][klog] INFO: wlan: [E] invalid channel value: 153+ [00008.118977][10245][10262][klog] INFO: wlan: [E] invalid channel value: 165V [00008.118978][10245][10262][klog] INFO: wlan: [E] invalid channel value: 165V [00008.118978][10245][10262][klog] INFO: wlan: [E] invalid channel value: 36+0P [00008.118979][10245][10262][klog] INFO: wlan: [E] invalid channel value: 36+0P [00008.118980][10245][10262][klog] INFO: wlan: [E] invalid channel value: 48+42P [00008.118980][10245][10262][klog] INFO: wlan: [E] invalid channel value: 48+42P [00008.118981][10245][10262][klog] INFO: wlan: [E] invalid channel value: 149+155P [00008.118982][10245][10262][klog] INFO: wlan: [E] invalid channel value: 149+155P [00008.118982][10245][10262][klog] INFO: wlan: [E] invalid channel value: 132W [00008.118983][10245][10262][klog] INFO: wlan: [E] invalid channel value: 132W [00008.118984][10245][10262][klog] INFO: [ OK ] ChannelTest.InvalidCombo (0 ms) [00008.118984][10245][10262][klog] INFO: [ RUN ] ChannelTest.Conversion [00008.118985][10245][10262][klog] INFO: [ OK ] ChannelTest.Conversion (0 ms) [00008.118986][10245][10262][klog] INFO: [ RUN ] ChannelTest.GetCenterChanIdx [00008.118987][10245][10262][klog] INFO: [ OK ] ChannelTest.GetCenterChanIdx (0 ms) [00008.118987][10245][10262][klog] INFO: [ RUN ] ChannelTest.GetCenterFreq [00008.118987][10245][10262][klog] INFO: [ OK ] ChannelTest.GetCenterFreq (0 ms) [00008.118988][10245][10262][klog] INFO: [----------] 6 tests from ChannelTest (0 ms total) [00008.118988][10245][10262][klog] INFO: [00008.118989][10245][10262][klog] INFO: [----------] 4 tests from FidlToElement [00008.118989][10245][10262][klog] INFO: [ RUN ] FidlToElement.VhtMcsNssFidlToBitField [00008.118990][10245][10262][klog] INFO: [ OK ] FidlToElement.VhtMcsNssFidlToBitField (0 ms) [00008.118990][10245][10262][klog] INFO: [ RUN ] FidlToElement.HtCapabilitiesFidlToBitField [00008.118995][10245][10262][klog] INFO: [ OK ] FidlToElement.HtCapabilitiesFidlToBitField (0 ms) [00008.119082][10245][10262][klog] INFO: [ RUN ] FidlToElement.HtOperationFidlToBitField [00008.119101][10245][10262][klog] INFO: [ OK ] FidlToElement.HtOperationFidlToBitField (0 ms) [00008.119103][10245][10262][klog] INFO: [ RUN ] FidlToElement.VhtOperationFidlToBitField [00008.119104][10245][10262][klog] INFO: [ OK ] FidlToElement.VhtOperationFidlToBitField (0 ms) [00008.119104][10245][10262][klog] INFO: [----------] 4 tests from FidlToElement (0 ms total) [00008.119149][10245][10262][klog] INFO: [00008.119150][10245][10262][klog] INFO: [----------] 4 tests from ElementToFidl [00008.119151][10245][10262][klog] INFO: [ RUN ] ElementToFidl.VhtMcsNssBitFieldToFidl [00008.119151][10245][10262][klog] INFO: [ OK ] ElementToFidl.VhtMcsNssBitFieldToFidl (0 ms) [00008.119153][10245][10262][klog] INFO: [ RUN ] ElementToFidl.HtCapabilitiesToFidlHuman [00008.119154][10245][10262][klog] INFO: [ OK ] ElementToFidl.HtCapabilitiesToFidlHuman (0 ms) [00008.119154][10245][10262][klog] INFO: [ RUN ] ElementToFidl.HtOperationToFidl [00008.119156][10245][10262][klog] INFO: [ OK ] ElementToFidl.HtOperationToFidl (0 ms) [00008.119156][10245][10262][klog] INFO: [ RUN ] ElementToFidl.VhtOperationBitFieldToField [00008.119159][10245][10262][klog] INFO: [ OK ] ElementToFidl.VhtOperationBitFieldToField (0 ms) [00008.119159][10245][10262][klog] INFO: [----------] 4 tests from ElementToFidl (0 ms total) [00008.119160][10245][10262][klog] INFO: [00008.119160][10245][10262][klog] INFO: [----------] 1 test from ElementHumanAccessor [00008.119161][10245][10262][klog] INFO: [ RUN ] ElementHumanAccessor.HtCapabilitiesBitFieldOrHuman [00008.119161][10245][10262][klog] INFO: [ OK ] ElementHumanAccessor.HtCapabilitiesBitFieldOrHuman (0 ms) [00008.119809][10245][10262][klog] INFO: [----------] 1 test from ElementHumanAccessor (0 ms total) [00008.119830][10245][10262][klog] INFO: [00008.119831][10245][10262][klog] INFO: [----------] 8 tests from ElementSplitter [00008.119831][10245][10262][klog] INFO: [ RUN ] ElementSplitter.Empty [00008.119832][10245][10262][klog] INFO: [ OK ] ElementSplitter.Empty (0 ms) [00008.119832][10245][10262][klog] INFO: [ RUN ] ElementSplitter.LessThanHeader [00008.119833][10245][10262][klog] INFO: [ OK ] ElementSplitter.LessThanHeader (0 ms) [00008.119834][10245][10262][klog] INFO: [ RUN ] ElementSplitter.SingleElementWithEmptyBody [00008.119834][10245][10262][klog] INFO: [ OK ] ElementSplitter.SingleElementWithEmptyBody (0 ms) [00008.119835][10245][10262][klog] INFO: [ RUN ] ElementSplitter.SingleElementBufferTooSmall [00008.119836][10245][10262][klog] INFO: [ OK ] ElementSplitter.SingleElementBufferTooSmall (0 ms) [00008.119836][10245][10262][klog] INFO: [ RUN ] ElementSplitter.SingleElement [00008.119837][10245][10262][klog] INFO: [ OK ] ElementSplitter.SingleElement (0 ms) [00008.119837][10245][10262][klog] INFO: [ RUN ] ElementSplitter.SeveralElements [00008.119838][10245][10262][klog] INFO: [ OK ] ElementSplitter.SeveralElements (0 ms) [00008.119839][10245][10262][klog] INFO: [ RUN ] ElementSplitter.TwoElementsBufferTooSmallForHeader [00008.119839][10245][10262][klog] INFO: [ OK ] ElementSplitter.TwoElementsBufferTooSmallForHeader (0 ms) [00008.119840][10245][10262][klog] INFO: [ RUN ] ElementSplitter.TwoElementsBufferTooSmallForBody [00008.119840][10245][10262][klog] INFO: [ OK ] ElementSplitter.TwoElementsBufferTooSmallForBody (0 ms) [00008.119840][10245][10262][klog] INFO: [----------] 8 tests from ElementSplitter (1 ms total) [00008.119841][10245][10262][klog] INFO: [00008.119843][10245][10262][klog] INFO: [----------] 3 tests from Elements [00008.119844][10245][10262][klog] INFO: [ RUN ] Elements.Tspec [00008.119844][10245][10262][klog] INFO: [ OK ] Elements.Tspec (0 ms) [00008.119844][10245][10262][klog] INFO: [ RUN ] Elements.TsInfoAggregation [00008.119845][10245][10262][klog] INFO: [ OK ] Elements.TsInfoAggregation (0 ms) [00008.119845][10245][10262][klog] INFO: [ RUN ] Elements.TsInfoScheduleSetting [00008.119845][10245][10262][klog] INFO: [ OK ] Elements.TsInfoScheduleSetting (0 ms) [00008.119846][10245][10262][klog] INFO: [----------] 3 tests from Elements (0 ms total) [00008.119846][10245][10262][klog] INFO: [00008.119846][10245][10262][klog] INFO: [----------] 1 test from HtCapabilities [00008.119847][10245][10262][klog] INFO: [ RUN ] HtCapabilities.DdkConversion [00008.119847][10245][10262][klog] INFO: [ OK ] HtCapabilities.DdkConversion (0 ms) [00008.119848][10245][10262][klog] INFO: [----------] 1 test from HtCapabilities (0 ms total) [00008.119848][10245][10262][klog] INFO: [00008.119848][10245][10262][klog] INFO: [----------] 1 test from HtOperation [00008.119849][10245][10262][klog] INFO: [ RUN ] HtOperation.DdkConversion [00008.119849][10245][10262][klog] INFO: [ OK ] HtOperation.DdkConversion (0 ms) [00008.119849][10245][10262][klog] INFO: [----------] 1 test from HtOperation (0 ms total) [00008.119850][10245][10262][klog] INFO: [00008.119850][10245][10262][klog] INFO: [----------] 1 test from VhtCapabilities [00008.119850][10245][10262][klog] INFO: [ RUN ] VhtCapabilities.DdkConversion [00008.119851][10245][10262][klog] INFO: [ OK ] VhtCapabilities.DdkConversion (0 ms) [00008.119851][10245][10262][klog] INFO: [----------] 1 test from VhtCapabilities (0 ms total) [00008.119852][10245][10262][klog] INFO: [00008.119852][10245][10262][klog] INFO: [----------] 1 test from VhtOperation [00008.119852][10245][10262][klog] INFO: [ RUN ] VhtOperation.DdkConversion [00008.119853][10245][10262][klog] INFO: [ OK ] VhtOperation.DdkConversion (0 ms) [00008.120305][10245][10262][klog] INFO: [----------] 1 test from VhtOperation (0 ms total) [00008.120328][10245][10262][klog] INFO: [00008.120328][10245][10262][klog] INFO: [----------] 3 tests from SupportedRate [00008.120328][10245][10262][klog] INFO: [ RUN ] SupportedRate.Create [00008.120329][10245][10262][klog] INFO: [ OK ] SupportedRate.Create (0 ms) [00008.120329][10245][10262][klog] INFO: [ RUN ] SupportedRate.ToUint8 [00008.120330][10245][10262][klog] INFO: [ OK ] SupportedRate.ToUint8 (0 ms) [00008.120331][10245][10262][klog] INFO: [ RUN ] SupportedRate.Compare [00008.120332][10245][10262][klog] INFO: [ OK ] SupportedRate.Compare (0 ms) [00008.120332][10245][10262][klog] INFO: [----------] 3 tests from SupportedRate (0 ms total) [00008.120333][10245][10262][klog] INFO: [00008.120333][10245][10262][klog] INFO: [----------] 1 test from Intersector [00008.120334][10245][10262][klog] INFO: [ RUN ] Intersector.IntersectRates [00008.120334][10245][10262][klog] INFO: [ OK ] Intersector.IntersectRates (0 ms) [00008.120335][10245][10262][klog] INFO: [----------] 1 test from Intersector (0 ms total) [00008.120336][10245][10262][klog] INFO: [00008.120339][10245][10262][klog] INFO: [----------] 8 tests from EnergyTest [00008.120339][10245][10262][klog] INFO: [ RUN ] EnergyTest.Construct [00008.120340][10245][10262][klog] INFO: [ OK ] EnergyTest.Construct (0 ms) [00008.120340][10245][10262][klog] INFO: [ RUN ] EnergyTest.Assign [00008.120341][10245][10262][klog] INFO: [ OK ] EnergyTest.Assign (0 ms) [00008.120341][10245][10262][klog] INFO: [ RUN ] EnergyTest.Compare [00008.120342][10245][10262][klog] INFO: [ OK ] EnergyTest.Compare (0 ms) [00008.120342][10245][10262][klog] INFO: [ RUN ] EnergyTest.Comparem_Watt [00008.120342][10245][10262][klog] INFO: [ OK ] EnergyTest.Comparem_Watt (0 ms) [00008.120343][10245][10262][klog] INFO: [ RUN ] EnergyTest.Conversion [00008.120343][10245][10262][klog] INFO: [ OK ] EnergyTest.Conversion (0 ms) [00008.120344][10245][10262][klog] INFO: [ RUN ] EnergyTest.Arithmetics [00008.120344][10245][10262][klog] INFO: [ OK ] EnergyTest.Arithmetics (0 ms) [00008.120346][10245][10262][klog] INFO: [ RUN ] EnergyTest.DbmToFemtoWatt [00008.120444][10245][10262][klog] INFO: [ OK ] EnergyTest.DbmToFemtoWatt (0 ms) [00008.120490][10245][10262][klog] INFO: [ RUN ] EnergyTest.FemtoWattToDbm [00008.120491][10245][10262][klog] INFO: [ OK ] EnergyTest.FemtoWattToDbm (0 ms) [00008.120491][10245][10262][klog] INFO: [----------] 8 tests from EnergyTest (0 ms total) [00008.120492][10245][10262][klog] INFO: [00008.120492][10245][10262][klog] INFO: [----------] 23 tests from FrameValidation [00008.120492][10245][10262][klog] INFO: [ RUN ] FrameValidation.TestHdrLength [00008.120493][10245][10262][klog] INFO: [ OK ] FrameValidation.TestHdrLength (0 ms) [00008.120493][10245][10262][klog] INFO: [ RUN ] FrameValidation.TestHdrLength_IllegalBuffer [00008.120494][10245][10262][klog] INFO: [ OK ] FrameValidation.TestHdrLength_IllegalBuffer (0 ms) [00008.120494][10245][10262][klog] INFO: [ RUN ] FrameValidation.TestEmptyHdrLength [00008.120495][10245][10262][klog] INFO: [ OK ] FrameValidation.TestEmptyHdrLength (0 ms) [00008.120495][10245][10262][klog] INFO: [ RUN ] FrameValidation.TestEmptyHdrLength_IllegalBuffer [00008.120496][10245][10262][klog] INFO: [ OK ] FrameValidation.TestEmptyHdrLength_IllegalBuffer (0 ms) [00008.120496][10245][10262][klog] INFO: [ RUN ] FrameValidation.TestDynamicHdrLength [00008.120496][10245][10262][klog] INFO: [ OK ] FrameValidation.TestDynamicHdrLength (0 ms) [00008.120497][10245][10262][klog] INFO: [ RUN ] FrameValidation.TestFrameLength_NoPadding [00008.120497][10245][10262][klog] INFO: [ OK ] FrameValidation.TestFrameLength_NoPadding (0 ms) [00008.120498][10245][10262][klog] INFO: [ RUN ] FrameValidation.TestFrameLength_EmptyBody_NoPadding [00008.121057][10245][10262][klog] INFO: [ OK ] FrameValidation.TestFrameLength_EmptyBody_NoPadding (0 ms) [00008.121077][10245][10262][klog] INFO: [ RUN ] FrameValidation.TestFrameLength_Padding [00008.121077][10245][10262][klog] INFO: [ OK ] FrameValidation.TestFrameLength_Padding (0 ms) [00008.121078][10245][10262][klog] INFO: [ RUN ] FrameValidation.TestFrameLength_EmptyBody_Padding [00008.121079][10245][10262][klog] INFO: [ OK ] FrameValidation.TestFrameLength_EmptyBody_Padding (0 ms) [00008.121080][10245][10262][klog] INFO: [ RUN ] FrameValidation.ValidBeaconType [00008.121080][10245][10262][klog] INFO: [ OK ] FrameValidation.ValidBeaconType (0 ms) [00008.121081][10245][10262][klog] INFO: [ RUN ] FrameValidation.ValidPsPollFrameType [00008.121081][10245][10262][klog] INFO: [ OK ] FrameValidation.ValidPsPollFrameType (0 ms) [00008.121082][10245][10262][klog] INFO: [ RUN ] FrameValidation.ValidDeauthFrameType [00008.121083][10245][10262][klog] INFO: [ OK ] FrameValidation.ValidDeauthFrameType (0 ms) [00008.121084][10245][10262][klog] INFO: [ RUN ] FrameValidation.ValidActionFrameType [00008.121086][10245][10262][klog] INFO: [ OK ] FrameValidation.ValidActionFrameType (0 ms) [00008.121087][10245][10262][klog] INFO: [ RUN ] FrameValidation.ValidProbeRequestFrameType [00008.121087][10245][10262][klog] INFO: [ OK ] FrameValidation.ValidProbeRequestFrameType (0 ms) [00008.121088][10245][10262][klog] INFO: [ RUN ] FrameValidation.ValidAssocRequestFrameType [00008.121088][10245][10262][klog] INFO: [ OK ] FrameValidation.ValidAssocRequestFrameType (0 ms) [00008.121089][10245][10262][klog] INFO: [ RUN ] FrameValidation.ValidAssocResponseFrameType [00008.121092][10245][10262][klog] INFO: [ OK ] FrameValidation.ValidAssocResponseFrameType (0 ms) [00008.121092][10245][10262][klog] INFO: [ RUN ] FrameValidation.ValidAuthFrameType [00008.121093][10245][10262][klog] INFO: [ OK ] FrameValidation.ValidAuthFrameType (0 ms) [00008.121093][10245][10262][klog] INFO: [ RUN ] FrameValidation.ValidDisassocFrameType [00008.121093][10245][10262][klog] INFO: [ OK ] FrameValidation.ValidDisassocFrameType (0 ms) [00008.121094][10245][10262][klog] INFO: [ RUN ] FrameValidation.ValidNullDataFrameType [00008.121094][10245][10262][klog] INFO: [ OK ] FrameValidation.ValidNullDataFrameType (0 ms) [00008.121095][10245][10262][klog] INFO: [ RUN ] FrameValidation.ValidQosNullDataFrameType [00008.121095][10245][10262][klog] INFO: [ OK ] FrameValidation.ValidQosNullDataFrameType (0 ms) [00008.121095][10245][10262][klog] INFO: [ RUN ] FrameValidation.ValidDataFrameType [00008.121096][10245][10262][klog] INFO: [ OK ] FrameValidation.ValidDataFrameType (0 ms) [00008.121096][10245][10262][klog] INFO: [ RUN ] FrameValidation.ValidQosDataFrameType [00008.121096][10245][10262][klog] INFO: [ OK ] FrameValidation.ValidQosDataFrameType (0 ms) [00008.121097][10245][10262][klog] INFO: [ RUN ] FrameValidation.ValidAmsduDataFrameType [00008.121097][10245][10262][klog] INFO: [ OK ] FrameValidation.ValidAmsduDataFrameType (0 ms) [00008.121098][10245][10262][klog] INFO: [----------] 23 tests from FrameValidation (0 ms total) [00008.121098][10245][10262][klog] INFO: [00008.121098][10245][10262][klog] INFO: [----------] 1 test from JoinContext [00008.121099][10245][10262][klog] INFO: [ RUN ] JoinContext.Sanitize [00008.121099][10245][10262][klog] INFO: wlan: [E] invalid channel value: 136+ [00008.121099][10245][10262][klog] INFO: wlan: [E] Sanitize the invalid channel: 136 CBW40 to 136 CBW20 [00008.121578][10245][10262][klog] INFO: wlan: [E] invalid channel value: 132- [00008.121599][10245][10262][klog] INFO: wlan: [E] Sanitize the invalid channel: 132 CBW40B to 132 CBW20 [00008.121599][10245][10262][klog] INFO: [ OK ] JoinContext.Sanitize (0 ms) [00008.121600][10245][10262][klog] INFO: [----------] 1 test from JoinContext (0 ms total) [00008.121600][10245][10262][klog] INFO: [00008.121601][10245][10262][klog] INFO: [----------] 19 tests from Frame [00008.121602][10245][10262][klog] INFO: [ RUN ] Frame.General [00008.121602][10245][10262][klog] INFO: [ OK ] Frame.General (0 ms) [00008.121603][10245][10262][klog] INFO: [ RUN ] Frame.General_Const_Frame [00008.121603][10245][10262][klog] INFO: [ OK ] Frame.General_Const_Frame (0 ms) [00008.121603][10245][10262][klog] INFO: [ RUN ] Frame.Take [00008.121604][10245][10262][klog] INFO: [ OK ] Frame.Take (0 ms) [00008.121604][10245][10262][klog] INFO: [ RUN ] Frame.ExactlySizedBuffer_HdrOnly [00008.121605][10245][10262][klog] INFO: [ OK ] Frame.ExactlySizedBuffer_HdrOnly (0 ms) [00008.121605][10245][10262][klog] INFO: [ RUN ] Frame.ExactlySizedBuffer_Frame [00008.121608][10245][10262][klog] INFO: [ OK ] Frame.ExactlySizedBuffer_Frame (0 ms) [00008.121609][10245][10262][klog] INFO: [ RUN ] Frame.TooShortBuffer_NoHdr [00008.121609][10245][10262][klog] INFO: [ OK ] Frame.TooShortBuffer_NoHdr (0 ms) [00008.121609][10245][10262][klog] INFO: [ RUN ] Frame.RxInfo_MacFrame [00008.121610][10245][10262][klog] INFO: [ OK ] Frame.RxInfo_MacFrame (0 ms) [00008.121610][10245][10262][klog] INFO: [ RUN ] Frame.RxInfo_OtherFrame [00008.121611][10245][10262][klog] INFO: [ OK ] Frame.RxInfo_OtherFrame (0 ms) [00008.121611][10245][10262][klog] INFO: [ RUN ] Frame.RxInfo_PaddingAlignedBody [00008.121612][10245][10262][klog] INFO: [ OK ] Frame.RxInfo_PaddingAlignedBody (0 ms) [00008.121612][10245][10262][klog] INFO: [ RUN ] Frame.RxInfo_NoPaddingAlignedBody [00008.121615][10245][10262][klog] INFO: [ OK ] Frame.RxInfo_NoPaddingAlignedBody (0 ms) [00008.121615][10245][10262][klog] INFO: [ RUN ] Frame.ConstructEmptyFrame [00008.121616][10245][10262][klog] INFO: [ OK ] Frame.ConstructEmptyFrame (0 ms) [00008.121616][10245][10262][klog] INFO: [ RUN ] Frame.AdvanceThroughAmsduFrame [00008.121617][10245][10262][klog] INFO: [ OK ] Frame.AdvanceThroughAmsduFrame (0 ms) [00008.121619][10245][10262][klog] INFO: [ RUN ] Frame.AdvanceThroughEmptyFrame [00008.121620][10245][10262][klog] INFO: [ OK ] Frame.AdvanceThroughEmptyFrame (0 ms) [00008.121637][10245][10262][klog] INFO: [ RUN ] Frame.AdvanceOutOfBounds [00008.121661][10245][10262][klog] INFO: [ OK ] Frame.AdvanceOutOfBounds (0 ms) [00008.121661][10245][10262][klog] INFO: [ RUN ] Frame.AdvanceThroughEapolFrame [00008.121661][10245][10262][klog] INFO: [ OK ] Frame.AdvanceThroughEapolFrame (0 ms) [00008.121662][10245][10262][klog] INFO: [ RUN ] Frame.EmptyBodyData [00008.121663][10245][10262][klog] INFO: [ OK ] Frame.EmptyBodyData (0 ms) [00008.121663][10245][10262][klog] INFO: [ RUN ] Frame.PopulatedBodyData [00008.121664][10245][10262][klog] INFO: [ OK ] Frame.PopulatedBodyData (0 ms) [00008.121664][10245][10262][klog] INFO: [ RUN ] Frame.DdkConversion [00008.121665][10245][10262][klog] INFO: [ OK ] Frame.DdkConversion (0 ms) [00008.121665][10245][10262][klog] INFO: [ RUN ] Frame.ParseProbeRequests [00008.121666][10245][10262][klog] INFO: [ OK ] Frame.ParseProbeRequests (0 ms) [00008.121666][10245][10262][klog] INFO: [----------] 19 tests from Frame (0 ms total) [00008.121667][10245][10262][klog] INFO: [00008.121667][10245][10262][klog] INFO: [----------] 3 tests from MacAddrTest [00008.121667][10245][10262][klog] INFO: [ RUN ] MacAddrTest.Some [00008.121668][10245][10262][klog] INFO: [ OK ] MacAddrTest.Some (0 ms) [00008.121668][10245][10262][klog] INFO: [ RUN ] MacAddrTest.Constructors [00008.122532][10245][10262][klog] INFO: [ OK ] MacAddrTest.Constructors (0 ms) [00008.122560][10245][10262][klog] INFO: [ RUN ] MacAddrTest.Conversion [00008.122561][10245][10262][klog] INFO: [ OK ] MacAddrTest.Conversion (0 ms) [00008.122561][10245][10262][klog] INFO: [----------] 3 tests from MacAddrTest (0 ms total) [00008.122561][10245][10262][klog] INFO: [00008.122562][10245][10262][klog] INFO: [----------] 2 tests from McsTest [00008.122562][10245][10262][klog] INFO: [ RUN ] McsTest.Intersect [00008.122563][10245][10262][klog] INFO: [ OK ] McsTest.Intersect (0 ms) [00008.122563][10245][10262][klog] INFO: [ RUN ] McsTest.ConvertFromFidl [00008.122564][10245][10262][klog] INFO: [ OK ] McsTest.ConvertFromFidl (0 ms) [00008.122565][10245][10262][klog] INFO: [----------] 2 tests from McsTest (0 ms total) [00008.122565][10245][10262][klog] INFO: [00008.122566][10245][10262][klog] INFO: [----------] 3 tests from PacketTest [00008.122566][10245][10262][klog] INFO: [ RUN ] PacketTest.BufferAlloc [00008.122567][10245][10262][klog] INFO: [ OK ] PacketTest.BufferAlloc (0 ms) [00008.122567][10245][10262][klog] INFO: [ RUN ] PacketTest.BufferMaxOut [00008.122568][10245][10262][klog] INFO: wlan: [V:buf ]Huge buffer exhausted. [00008.122568][10245][10262][klog] INFO: wlan: [V:buf ]usage(in_use/in_use_max/current_capacity/max_capacity) [00008.122568][10245][10262][klog] INFO: Small: 0/2/512/20480, Large: 0/2/32/640, Huge: 16/16/16/16 [00008.122569][10245][10262][klog] INFO: [ OK ] PacketTest.BufferMaxOut (1 ms) [00008.122569][10245][10262][klog] INFO: [ RUN ] PacketTest.BufferFallback [00008.122570][10245][10262][klog] INFO: wlan: [V:buf ]Small buffer exhausted. [00008.122570][10245][10262][klog] INFO: wlan: [V:buf ]usage(in_use/in_use_max/current_capacity/max_capacity) [00008.122571][10245][10262][klog] INFO: Small: 20480/20480/20480/20480, Large: 0/2/32/640, Huge: 0/16/16/16 [00008.122571][10245][10262][klog] INFO: [ OK ] PacketTest.BufferFallback (11 ms) [00008.122575][10245][10262][klog] INFO: [----------] 3 tests from PacketTest (12 ms total) [00008.122575][10245][10262][klog] INFO: [00008.122576][10245][10262][klog] INFO: [----------] 5 tests from PacketQueueTest [00008.122576][10245][10262][klog] INFO: [ RUN ] PacketQueueTest.Empty [00008.122578][10245][10262][klog] INFO: [ OK ] PacketQueueTest.Empty (0 ms) [00008.122699][10245][10262][klog] INFO: [ RUN ] PacketQueueTest.EnqueueAndDequeue [00008.122719][10245][10262][klog] INFO: [ OK ] PacketQueueTest.EnqueueAndDequeue (0 ms) [00008.122753][10245][10262][klog] INFO: [ RUN ] PacketQueueTest.EnqueueAndUndoEnqueue [00008.122754][10245][10262][klog] INFO: [ OK ] PacketQueueTest.EnqueueAndUndoEnqueue (0 ms) [00008.122755][10245][10262][klog] INFO: [ RUN ] PacketQueueTest.Move [00008.122755][10245][10262][klog] INFO: [ OK ] PacketQueueTest.Move (0 ms) [00008.122756][10245][10262][klog] INFO: [ RUN ] PacketQueueTest.Drain [00008.122756][10245][10262][klog] INFO: [ OK ] PacketQueueTest.Drain (0 ms) [00008.122758][10245][10262][klog] INFO: [----------] 5 tests from PacketQueueTest (0 ms total) [00008.122758][10245][10262][klog] INFO: [00008.122759][10245][10262][klog] INFO: [----------] 4 tests from ParseBeaconTest [00008.122760][10245][10262][klog] INFO: [ RUN ] ParseBeaconTest.GetVhtCbw [00008.122761][10245][10262][klog] INFO: [ OK ] ParseBeaconTest.GetVhtCbw (0 ms) [00008.122762][10245][10262][klog] INFO: [ RUN ] ParseBeaconTest.DeriveChannel [00008.122762][10245][10262][klog] INFO: [ OK ] ParseBeaconTest.DeriveChannel (0 ms) [00008.122763][10245][10262][klog] INFO: [ RUN ] ParseBeaconTest.FillRates [00008.122764][10245][10262][klog] INFO: [ OK ] ParseBeaconTest.FillRates (1 ms) [00008.122764][10245][10262][klog] INFO: [ RUN ] ParseBeaconTest.ParseBeaconElements [00008.122765][10245][10262][klog] INFO: [ OK ] ParseBeaconTest.ParseBeaconElements (0 ms) [00008.125462][10245][10262][klog] INFO: [----------] 4 tests from ParseBeaconTest (1 ms total) [00008.125491][10245][10262][klog] INFO: [00008.125492][10245][10262][klog] INFO: [----------] 70 tests from ParseElement [00008.125493][10245][10262][klog] INFO: [ RUN ] ParseElement.Ssid [00008.125493][10245][10262][klog] INFO: [ OK ] ParseElement.Ssid (0 ms) [00008.125494][10245][10262][klog] INFO: [ RUN ] ParseElement.SsidTooLong [00008.125494][10245][10262][klog] INFO: [ OK ] ParseElement.SsidTooLong (0 ms) [00008.125494][10245][10262][klog] INFO: [ RUN ] ParseElement.SupportedRates [00008.125495][10245][10262][klog] INFO: [ OK ] ParseElement.SupportedRates (0 ms) [00008.125495][10245][10262][klog] INFO: [ RUN ] ParseElement.SupportedRatesEmpty [00008.125496][10245][10262][klog] INFO: [ OK ] ParseElement.SupportedRatesEmpty (0 ms) [00008.125496][10245][10262][klog] INFO: [ RUN ] ParseElement.SupportedRatesTooLong [00008.125497][10245][10262][klog] INFO: [ OK ] ParseElement.SupportedRatesTooLong (0 ms) [00008.125497][10245][10262][klog] INFO: [ RUN ] ParseElement.DsssParamSet [00008.125498][10245][10262][klog] INFO: [ OK ] ParseElement.DsssParamSet (0 ms) [00008.125498][10245][10262][klog] INFO: [ RUN ] ParseElement.DsssParamSetToShort [00008.125498][10245][10262][klog] INFO: [ OK ] ParseElement.DsssParamSetToShort (0 ms) [00008.125499][10245][10262][klog] INFO: [ RUN ] ParseElement.DsssParamSetToLong [00008.125500][10245][10262][klog] INFO: [ OK ] ParseElement.DsssParamSetToLong (0 ms) [00008.125500][10245][10262][klog] INFO: [ RUN ] ParseElement.CfParamSet [00008.125500][10245][10262][klog] INFO: [ OK ] ParseElement.CfParamSet (0 ms) [00008.125501][10245][10262][klog] INFO: [ RUN ] ParseElement.CfParamSetTooShort [00008.125501][10245][10262][klog] INFO: [ OK ] ParseElement.CfParamSetTooShort (0 ms) [00008.125501][10245][10262][klog] INFO: [ RUN ] ParseElement.CfParamSetTooLong [00008.125504][10245][10262][klog] INFO: [ OK ] ParseElement.CfParamSetTooLong (0 ms) [00008.125505][10245][10262][klog] INFO: [ RUN ] ParseElement.Tim [00008.125505][10245][10262][klog] INFO: [ OK ] ParseElement.Tim (0 ms) [00008.125507][10245][10262][klog] INFO: [ RUN ] ParseElement.TimEmptyBitmap [00008.125507][10245][10262][klog] INFO: [ OK ] ParseElement.TimEmptyBitmap (0 ms) [00008.125508][10245][10262][klog] INFO: [ RUN ] ParseElement.TimTooShort [00008.125508][10245][10262][klog] INFO: [ OK ] ParseElement.TimTooShort (0 ms) [00008.125509][10245][10262][klog] INFO: [ RUN ] ParseElement.CountryNoTriplets [00008.125510][10245][10262][klog] INFO: [ OK ] ParseElement.CountryNoTriplets (0 ms) [00008.125510][10245][10262][klog] INFO: [ RUN ] ParseElement.CountrySingleTriplet [00008.125512][10245][10262][klog] INFO: [ OK ] ParseElement.CountrySingleTriplet (0 ms) [00008.125512][10245][10262][klog] INFO: [ RUN ] ParseElement.CountryTwoTriplets [00008.125513][10245][10262][klog] INFO: [ OK ] ParseElement.CountryTwoTriplets (0 ms) [00008.125514][10245][10262][klog] INFO: [ RUN ] ParseElement.CountryTooShort [00008.125515][10245][10262][klog] INFO: [ OK ] ParseElement.CountryTooShort (0 ms) [00008.125516][10245][10262][klog] INFO: [ RUN ] ParseElement.ExtendedSupportedRates [00008.125516][10245][10262][klog] INFO: [ OK ] ParseElement.ExtendedSupportedRates (0 ms) [00008.125517][10245][10262][klog] INFO: [ RUN ] ParseElement.ExtendedSupportedRatesEmpty [00008.125518][10245][10262][klog] INFO: [ OK ] ParseElement.ExtendedSupportedRatesEmpty (0 ms) [00008.125519][10245][10262][klog] INFO: [ RUN ] ParseElement.MeshConfiguration [00008.125519][10245][10262][klog] INFO: [ OK ] ParseElement.MeshConfiguration (0 ms) [00008.125520][10245][10262][klog] INFO: [ RUN ] ParseElement.MeshConfigurationTooShort [00008.125825][10245][10262][klog] INFO: [ OK ] ParseElement.MeshConfigurationTooShort (0 ms) [00008.125826][10245][10262][klog] INFO: [ RUN ] ParseElement.MeshConfigurationTooLong [00008.125827][10245][10262][klog] INFO: [ OK ] ParseElement.MeshConfigurationTooLong (0 ms) [00008.125828][10245][10262][klog] INFO: [ RUN ] ParseElement.MeshId [00008.125828][10245][10262][klog] INFO: [ OK ] ParseElement.MeshId (0 ms) [00008.125829][10245][10262][klog] INFO: [ RUN ] ParseElement.MeshIdTooLong [00008.125830][10245][10262][klog] INFO: [ OK ] ParseElement.MeshIdTooLong (0 ms) [00008.125831][10245][10262][klog] INFO: [ RUN ] ParseElement.QosCapability [00008.125831][10245][10262][klog] INFO: [ OK ] ParseElement.QosCapability (0 ms) [00008.125832][10245][10262][klog] INFO: [ RUN ] ParseElement.QosCapabilityTooShort [00008.125833][10245][10262][klog] INFO: [ OK ] ParseElement.QosCapabilityTooShort (0 ms) [00008.125833][10245][10262][klog] INFO: [ RUN ] ParseElement.QosCapabilityTooLong [00008.125834][10245][10262][klog] INFO: [ OK ] ParseElement.QosCapabilityTooLong (0 ms) [00008.125834][10245][10262][klog] INFO: [ RUN ] ParseElement.GcrGroupAddress [00008.125835][10245][10262][klog] INFO: [ OK ] ParseElement.GcrGroupAddress (0 ms) [00008.125836][10245][10262][klog] INFO: [ RUN ] ParseElement.GcrGroupAddressTooShort [00008.125837][10245][10262][klog] INFO: [ OK ] ParseElement.GcrGroupAddressTooShort (0 ms) [00008.125838][10245][10262][klog] INFO: [ RUN ] ParseElement.GcrGroupAddressTooLong [00008.125839][10245][10262][klog] INFO: [ OK ] ParseElement.GcrGroupAddressTooLong (0 ms) [00008.125839][10245][10262][klog] INFO: [ RUN ] ParseElement.HtCapabilities [00008.125840][10245][10262][klog] INFO: [ OK ] ParseElement.HtCapabilities (0 ms) [00008.125843][10245][10262][klog] INFO: [ RUN ] ParseElement.HtCapabilitiesTooShort [00008.125844][10245][10262][klog] INFO: [ OK ] ParseElement.HtCapabilitiesTooShort (0 ms) [00008.125844][10245][10262][klog] INFO: [ RUN ] ParseElement.HtCapabilitiesTooLong [00008.125845][10245][10262][klog] INFO: [ OK ] ParseElement.HtCapabilitiesTooLong (0 ms) [00008.125846][10245][10262][klog] INFO: [ RUN ] ParseElement.HtOperation [00008.125846][10245][10262][klog] INFO: [ OK ] ParseElement.HtOperation (0 ms) [00008.125847][10245][10262][klog] INFO: [ RUN ] ParseElement.HtOperationTooShort [00008.125847][10245][10262][klog] INFO: [ OK ] ParseElement.HtOperationTooShort (0 ms) [00008.125848][10245][10262][klog] INFO: [ RUN ] ParseElement.HtOperationTooLong [00008.125848][10245][10262][klog] INFO: [ OK ] ParseElement.HtOperationTooLong (0 ms) [00008.125849][10245][10262][klog] INFO: [ RUN ] ParseElement.VhtCapabilities [00008.125850][10245][10262][klog] INFO: [ OK ] ParseElement.VhtCapabilities (0 ms) [00008.125850][10245][10262][klog] INFO: [ RUN ] ParseElement.VhtCapabilitiesTooShort [00008.125851][10245][10262][klog] INFO: [ OK ] ParseElement.VhtCapabilitiesTooShort (0 ms) [00008.125851][10245][10262][klog] INFO: [ RUN ] ParseElement.VhtCapabilitiesTooLong [00008.125852][10245][10262][klog] INFO: [ OK ] ParseElement.VhtCapabilitiesTooLong (0 ms) [00008.125853][10245][10262][klog] INFO: [ RUN ] ParseElement.VhtOperation [00008.125853][10245][10262][klog] INFO: [ OK ] ParseElement.VhtOperation (0 ms) [00008.125856][10245][10262][klog] INFO: [ RUN ] ParseElement.VhtOperationTooShort [00008.125857][10245][10262][klog] INFO: [ OK ] ParseElement.VhtOperationTooShort (0 ms) [00008.125858][10245][10262][klog] INFO: [ RUN ] ParseElement.VhtOperationTooLong [00008.128744][10245][10262][klog] INFO: [ OK ] ParseElement.VhtOperationTooLong (0 ms) [00008.128769][10245][10262][klog] INFO: [ RUN ] ParseElement.MpmOpenBad [00008.128770][10245][10262][klog] INFO: [ OK ] ParseElement.MpmOpenBad (0 ms) [00008.128772][10245][10262][klog] INFO: [ RUN ] ParseElement.MpmOpenGoodNoPmk [00008.128773][10245][10262][klog] INFO: [ OK ] ParseElement.MpmOpenGoodNoPmk (0 ms) [00008.128773][10245][10262][klog] INFO: [ RUN ] ParseElement.MpmOpenGoodWithPmk [00008.128774][10245][10262][klog] INFO: [ OK ] ParseElement.MpmOpenGoodWithPmk (0 ms) [00008.128775][10245][10262][klog] INFO: [ RUN ] ParseElement.MpmConfirmBad [00008.128775][10245][10262][klog] INFO: [ OK ] ParseElement.MpmConfirmBad (0 ms) [00008.128776][10245][10262][klog] INFO: [ RUN ] ParseElement.MpmConfirmGoodNoPmk [00008.128777][10245][10262][klog] INFO: [ OK ] ParseElement.MpmConfirmGoodNoPmk (0 ms) [00008.128779][10245][10262][klog] INFO: [ RUN ] ParseElement.MpmConfirmGoodWithPmk [00008.128779][10245][10262][klog] INFO: [ OK ] ParseElement.MpmConfirmGoodWithPmk (0 ms) [00008.128780][10245][10262][klog] INFO: [ RUN ] ParseElement.MpmCloseBad [00008.128780][10245][10262][klog] INFO: [ OK ] ParseElement.MpmCloseBad (0 ms) [00008.128781][10245][10262][klog] INFO: [ RUN ] ParseElement.MpmCloseGoodNoLinkIdNoPmk [00008.128781][10245][10262][klog] INFO: [ OK ] ParseElement.MpmCloseGoodNoLinkIdNoPmk (0 ms) [00008.128782][10245][10262][klog] INFO: [ RUN ] ParseElement.MpmCloseGoodWithLinkIdNoPmk [00008.128782][10245][10262][klog] INFO: [ OK ] ParseElement.MpmCloseGoodWithLinkIdNoPmk (0 ms) [00008.128783][10245][10262][klog] INFO: [ RUN ] ParseElement.MpmCloseGoodNoLinkIdWithPmk [00008.128783][10245][10262][klog] INFO: [ OK ] ParseElement.MpmCloseGoodNoLinkIdWithPmk (0 ms) [00008.128787][10245][10262][klog] INFO: [ RUN ] ParseElement.MpmCloseGoodWithLinkIdWithPmk [00008.128788][10245][10262][klog] INFO: [ OK ] ParseElement.MpmCloseGoodWithLinkIdWithPmk (0 ms) [00008.128788][10245][10262][klog] INFO: [ RUN ] ParseElement.PreqMinimal [00008.128789][10245][10262][klog] INFO: [ OK ] ParseElement.PreqMinimal (0 ms) [00008.128789][10245][10262][klog] INFO: [ RUN ] ParseElement.PreqFull [00008.128790][10245][10262][klog] INFO: [ OK ] ParseElement.PreqFull (0 ms) [00008.128791][10245][10262][klog] INFO: [ RUN ] ParseElement.PreqTooLong [00008.128791][10245][10262][klog] INFO: [ OK ] ParseElement.PreqTooLong (0 ms) [00008.128791][10245][10262][klog] INFO: [ RUN ] ParseElement.PreqTooShort_Header [00008.128792][10245][10262][klog] INFO: [ OK ] ParseElement.PreqTooShort_Header (0 ms) [00008.128793][10245][10262][klog] INFO: [ RUN ] ParseElement.PreqTooShort_OrigExtAddr [00008.128794][10245][10262][klog] INFO: [ OK ] ParseElement.PreqTooShort_OrigExtAddr (0 ms) [00008.128794][10245][10262][klog] INFO: [ RUN ] ParseElement.PreqTooShort_Middle [00008.128795][10245][10262][klog] INFO: [ OK ] ParseElement.PreqTooShort_Middle (0 ms) [00008.128795][10245][10262][klog] INFO: [ RUN ] ParseElement.PreqTooShort_PerTarget [00008.128796][10245][10262][klog] INFO: [ OK ] ParseElement.PreqTooShort_PerTarget (0 ms) [00008.128796][10245][10262][klog] INFO: [ RUN ] ParseElement.PrepNoExtAddr [00008.128796][10245][10262][klog] INFO: [ OK ] ParseElement.PrepNoExtAddr (0 ms) [00008.128797][10245][10262][klog] INFO: [ RUN ] ParseElement.PrepWithExtAddr [00008.128798][10245][10262][klog] INFO: [ OK ] ParseElement.PrepWithExtAddr (0 ms) [00008.128798][10245][10262][klog] INFO: [ RUN ] ParseElement.PrepTooShort_Header [00008.130424][10245][10262][klog] INFO: [ OK ] ParseElement.PrepTooShort_Header (0 ms) [00008.130425][10245][10262][klog] INFO: [ RUN ] ParseElement.PrepTooShort_Tail [00008.130426][10245][10262][klog] INFO: [ OK ] ParseElement.PrepTooShort_Tail (0 ms) [00008.130426][10245][10262][klog] INFO: [ RUN ] ParseElement.PrepTooShort_ExtAddr [00008.130427][10245][10262][klog] INFO: [ OK ] ParseElement.PrepTooShort_ExtAddr (0 ms) [00008.130428][10245][10262][klog] INFO: [ RUN ] ParseElement.PrepTooLong [00008.130446][10245][10262][klog] INFO: [ OK ] ParseElement.PrepTooLong (0 ms) [00008.130447][10245][10262][klog] INFO: [ RUN ] ParseElement.Perr [00008.130448][10245][10262][klog] INFO: [ OK ] ParseElement.Perr (0 ms) [00008.130449][10245][10262][klog] INFO: [ RUN ] ParseElement.PerrNoDestinationData [00008.130450][10245][10262][klog] INFO: [ OK ] ParseElement.PerrNoDestinationData (0 ms) [00008.130450][10245][10262][klog] INFO: [ RUN ] ParseElement.PerrTooShort [00008.130451][10245][10262][klog] INFO: [ OK ] ParseElement.PerrTooShort (0 ms) [00008.130452][10245][10262][klog] INFO: [----------] 70 tests from ParseElement (0 ms total) [00008.130453][10245][10262][klog] INFO: [00008.130453][10245][10262][klog] INFO: [----------] 6 tests from ParseDataFrameHeader [00008.130454][10245][10262][klog] INFO: [ RUN ] ParseDataFrameHeader.Minimal [00008.130455][10245][10262][klog] INFO: [ OK ] ParseDataFrameHeader.Minimal (0 ms) [00008.130456][10245][10262][klog] INFO: [ RUN ] ParseDataFrameHeader.Full [00008.130456][10245][10262][klog] INFO: [ OK ] ParseDataFrameHeader.Full (0 ms) [00008.130457][10245][10262][klog] INFO: [ RUN ] ParseDataFrameHeader.FixedPartTooShort [00008.130457][10245][10262][klog] INFO: [ OK ] ParseDataFrameHeader.FixedPartTooShort (0 ms) [00008.130457][10245][10262][klog] INFO: [ RUN ] ParseDataFrameHeader.Addr4TooShort [00008.130461][10245][10262][klog] INFO: [ OK ] ParseDataFrameHeader.Addr4TooShort (0 ms) [00008.130461][10245][10262][klog] INFO: [ RUN ] ParseDataFrameHeader.QosControlTooShort [00008.130462][10245][10262][klog] INFO: [ OK ] ParseDataFrameHeader.QosControlTooShort (0 ms) [00008.130462][10245][10262][klog] INFO: [ RUN ] ParseDataFrameHeader.HtControlTooShort [00008.130463][10245][10262][klog] INFO: [ OK ] ParseDataFrameHeader.HtControlTooShort (0 ms) [00008.130464][10245][10262][klog] INFO: [----------] 6 tests from ParseDataFrameHeader (0 ms total) [00008.130464][10245][10262][klog] INFO: [00008.130465][10245][10262][klog] INFO: [----------] 10 tests from ParseMeshDataHeader [00008.130466][10245][10262][klog] INFO: [ RUN ] ParseMeshDataHeader.NoAddrExt [00008.130466][10245][10262][klog] INFO: [ OK ] ParseMeshDataHeader.NoAddrExt (0 ms) [00008.130467][10245][10262][klog] INFO: [ RUN ] ParseMeshDataHeader.Addr4Ext [00008.130468][10245][10262][klog] INFO: [ OK ] ParseMeshDataHeader.Addr4Ext (0 ms) [00008.130468][10245][10262][klog] INFO: [ RUN ] ParseMeshDataHeader.Addr56Ext [00008.130469][10245][10262][klog] INFO: [ OK ] ParseMeshDataHeader.Addr56Ext (0 ms) [00008.130470][10245][10262][klog] INFO: [ RUN ] ParseMeshDataHeader.TooShort_MacHeader [00008.130470][10245][10262][klog] INFO: [ OK ] ParseMeshDataHeader.TooShort_MacHeader (0 ms) [00008.130471][10245][10262][klog] INFO: [ RUN ] ParseMeshDataHeader.TooShort_MeshControl [00008.130473][10245][10262][klog] INFO: [ OK ] ParseMeshDataHeader.TooShort_MeshControl (0 ms) [00008.130473][10245][10262][klog] INFO: [ RUN ] ParseMeshDataHeader.TooShort_AddrExt [00008.130474][10245][10262][klog] INFO: [ OK ] ParseMeshDataHeader.TooShort_AddrExt (0 ms) [00008.130474][10245][10262][klog] INFO: [ RUN ] ParseMeshDataHeader.TooShort_Llc [00008.130958][10245][10262][klog] INFO: [ OK ] ParseMeshDataHeader.TooShort_Llc (0 ms) [00008.130988][10245][10262][klog] INFO: [ RUN ] ParseMeshDataHeader.MissingQosBit [00008.130990][10245][10262][klog] INFO: [ OK ] ParseMeshDataHeader.MissingQosBit (0 ms) [00008.130990][10245][10262][klog] INFO: [ RUN ] ParseMeshDataHeader.MissingMeshControlPresentBit [00008.130991][10245][10262][klog] INFO: [ OK ] ParseMeshDataHeader.MissingMeshControlPresentBit (0 ms) [00008.130992][10245][10262][klog] INFO: [ RUN ] ParseMeshDataHeader.InvalidAddrExt [00008.130992][10245][10262][klog] INFO: [ OK ] ParseMeshDataHeader.InvalidAddrExt (0 ms) [00008.130993][10245][10262][klog] INFO: [----------] 10 tests from ParseMeshDataHeader (0 ms total) [00008.130994][10245][10262][klog] INFO: [00008.130995][10245][10262][klog] INFO: [----------] 5 tests from PerrDestinationParser [00008.130995][10245][10262][klog] INFO: [ RUN ] PerrDestinationParser.Empty [00008.130996][10245][10262][klog] INFO: [ OK ] PerrDestinationParser.Empty (0 ms) [00008.130996][10245][10262][klog] INFO: [ RUN ] PerrDestinationParser.TwoDestinations [00008.130998][10245][10262][klog] INFO: [ OK ] PerrDestinationParser.TwoDestinations (0 ms) [00008.130998][10245][10262][klog] INFO: [ RUN ] PerrDestinationParser.TooShortForHeader [00008.131001][10245][10262][klog] INFO: [ OK ] PerrDestinationParser.TooShortForHeader (0 ms) [00008.131002][10245][10262][klog] INFO: [ RUN ] PerrDestinationParser.TooShortForExtAddr [00008.131004][10245][10262][klog] INFO: [ OK ] PerrDestinationParser.TooShortForExtAddr (0 ms) [00008.131005][10245][10262][klog] INFO: [ RUN ] PerrDestinationParser.TooShortForTail [00008.131006][10245][10262][klog] INFO: [ OK ] PerrDestinationParser.TooShortForTail (0 ms) [00008.131009][10245][10262][klog] INFO: [----------] 5 tests from PerrDestinationParser (0 ms total) [00008.131010][10245][10262][klog] INFO: [00008.131010][10245][10262][klog] INFO: [----------] 2 tests from RateLimiter [00008.131010][10245][10262][klog] INFO: [ RUN ] RateLimiter.SingleEvent [00008.131011][10245][10262][klog] INFO: [ OK ] RateLimiter.SingleEvent (0 ms) [00008.131011][10245][10262][klog] INFO: [ RUN ] RateLimiter.TwoEvents [00008.131217][10245][10262][klog] INFO: [ OK ] RateLimiter.TwoEvents (0 ms) [00008.131243][10245][10262][klog] INFO: [----------] 2 tests from RateLimiter (0 ms total) [00008.131243][10245][10262][klog] INFO: [00008.131244][10245][10262][klog] INFO: [----------] 4 tests from TimElement [00008.131244][10245][10262][klog] INFO: [ RUN ] TimElement.IsTrafficBuffered [00008.131245][10245][10262][klog] INFO: [ OK ] TimElement.IsTrafficBuffered (0 ms) [00008.131246][10245][10262][klog] INFO: [ RUN ] TimElement.FindAndParseOk [00008.131246][10245][10262][klog] INFO: [ OK ] TimElement.FindAndParseOk (0 ms) [00008.131247][10245][10262][klog] INFO: [ RUN ] TimElement.FindAndParseAbsent [00008.131247][10245][10262][klog] INFO: [ OK ] TimElement.FindAndParseAbsent (0 ms) [00008.131248][10245][10262][klog] INFO: [ RUN ] TimElement.FindAndParseInvalid [00008.131248][10245][10262][klog] INFO: [ OK ] TimElement.FindAndParseInvalid (0 ms) [00008.131249][10245][10262][klog] INFO: [----------] 4 tests from TimElement (0 ms total) [00008.131249][10245][10262][klog] INFO: [00008.131250][10245][10262][klog] INFO: [----------] 11 tests from TimTest [00008.131250][10245][10262][klog] INFO: [ RUN ] TimTest.Initialization [00008.131251][10245][10262][klog] INFO: [ OK ] TimTest.Initialization (0 ms) [00008.131251][10245][10262][klog] INFO: [ RUN ] TimTest.HasTraffic [00008.131252][10245][10262][klog] INFO: [ OK ] TimTest.HasTraffic (0 ms) [00008.131252][10245][10262][klog] INFO: [ RUN ] TimTest.ClearTrafficIndication [00008.131777][10245][10262][klog] INFO: [ OK ] TimTest.ClearTrafficIndication (0 ms) [00008.131799][10245][10262][klog] INFO: [ RUN ] TimTest.ClearTraffic [00008.131800][10245][10262][klog] INFO: [ OK ] TimTest.ClearTraffic (0 ms) [00008.131802][10245][10262][klog] INFO: [ RUN ] TimTest.WriteEmptyPartialVirtualBitmap [00008.131803][10245][10262][klog] INFO: [ OK ] TimTest.WriteEmptyPartialVirtualBitmap (0 ms) [00008.131803][10245][10262][klog] INFO: [ RUN ] TimTest.WriteNoOffsetPopulatedPartialVirtualBitmap [00008.131804][10245][10262][klog] INFO: [ OK ] TimTest.WriteNoOffsetPopulatedPartialVirtualBitmap (0 ms) [00008.131804][10245][10262][klog] INFO: [ RUN ] TimTest.WriteOffsetPopulatedPartialVirtualBitmap [00008.131805][10245][10262][klog] INFO: [ OK ] TimTest.WriteOffsetPopulatedPartialVirtualBitmap (0 ms) [00008.131806][10245][10262][klog] INFO: [ RUN ] TimTest.WriteChangingPartialVirtualBitmap [00008.131807][10245][10262][klog] INFO: [ OK ] TimTest.WriteChangingPartialVirtualBitmap (0 ms) [00008.131807][10245][10262][klog] INFO: [ RUN ] TimTest.WriteLastBytePartialVirtualBitmap [00008.131808][10245][10262][klog] INFO: [ OK ] TimTest.WriteLastBytePartialVirtualBitmap (0 ms) [00008.131808][10245][10262][klog] INFO: [ RUN ] TimTest.WriteMaxSizedPartialVirtualBitmap [00008.131808][10245][10262][klog] INFO: [ OK ] TimTest.WriteMaxSizedPartialVirtualBitmap (0 ms) [00008.131809][10245][10262][klog] INFO: [ RUN ] TimTest.IgnoreGroupTrafficInPartialVirtualBitmap [00008.131809][10245][10262][klog] INFO: [ OK ] TimTest.IgnoreGroupTrafficInPartialVirtualBitmap (0 ms) [00008.131810][10245][10262][klog] INFO: [----------] 11 tests from TimTest (0 ms total) [00008.131811][10245][10262][klog] INFO: [00008.131814][10245][10262][klog] INFO: [----------] 2 tests from TxVectorIndexTest [00008.131815][10245][10262][klog] INFO: [ RUN ] TxVectorIndexTest.TxVectorMapping [00008.131815][10245][10262][klog] INFO: [ OK ] TxVectorIndexTest.TxVectorMapping (0 ms) [00008.131816][10245][10262][klog] INFO: [ RUN ] TxVectorIndexTest.NotUsedParam [00008.131819][10245][10262][klog] INFO: [ OK ] TxVectorIndexTest.NotUsedParam (0 ms) [00008.131843][10245][10262][klog] INFO: [----------] 2 tests from TxVectorIndexTest (0 ms total) [00008.131866][10245][10262][klog] INFO: [00008.131866][10245][10262][klog] INFO: [----------] 1 test from TxVectorTest [00008.131893][10245][10262][klog] INFO: [ RUN ] TxVectorTest.ValidIdx [00008.131894][10245][10262][klog] INFO: [ OK ] TxVectorTest.ValidIdx (0 ms) [00008.131895][10245][10262][klog] INFO: [----------] 1 test from TxVectorTest (0 ms total) [00008.131898][10245][10262][klog] INFO: [00008.131899][10245][10262][klog] INFO: [----------] 1 test from ErpRateTest [00008.131899][10245][10262][klog] INFO: [ RUN ] ErpRateTest.ErpRateToTxVector [00008.131900][10245][10262][klog] INFO: [ OK ] ErpRateTest.ErpRateToTxVector (0 ms) [00008.131901][10245][10262][klog] INFO: [----------] 1 test from ErpRateTest (0 ms total) [00008.131901][10245][10262][klog] INFO: [00008.131901][10245][10262][klog] INFO: [----------] 1 test from DebugTest [00008.131902][10245][10262][klog] INFO: [ RUN ] DebugTest.DebugStringVisualInspection [00008.131903][10245][10262][klog] INFO: [ OK ] DebugTest.DebugStringVisualInspection (1 ms) [00008.131903][10245][10262][klog] INFO: [----------] 1 test from DebugTest (1 ms total) [00008.131903][10245][10262][klog] INFO: [00008.131904][10245][10262][klog] INFO: [----------] 25 tests from WriteElement [00008.131904][10245][10262][klog] INFO: [ RUN ] WriteElement.Ssid [00008.131905][10245][10262][klog] INFO: [ OK ] WriteElement.Ssid (0 ms) [00008.131905][10245][10262][klog] INFO: [ RUN ] WriteElement.SupportedRates [00008.131906][10245][10262][klog] INFO: [ OK ] WriteElement.SupportedRates (0 ms) [00008.132027][10245][10262][klog] INFO: [ RUN ] WriteElement.DsssParamSet [00008.132053][10245][10262][klog] INFO: [ OK ] WriteElement.DsssParamSet (0 ms) [00008.132053][10245][10262][klog] INFO: [ RUN ] WriteElement.CfParamSet [00008.132054][10245][10262][klog] INFO: [ OK ] WriteElement.CfParamSet (0 ms) [00008.132054][10245][10262][klog] INFO: [ RUN ] WriteElement.Tim [00008.132055][10245][10262][klog] INFO: [ OK ] WriteElement.Tim (0 ms) [00008.132056][10245][10262][klog] INFO: [ RUN ] WriteElement.CountryPadded [00008.132056][10245][10262][klog] INFO: [ OK ] WriteElement.CountryPadded (0 ms) [00008.132057][10245][10262][klog] INFO: [ RUN ] WriteElement.CountryUnpadded [00008.132057][10245][10262][klog] INFO: [ OK ] WriteElement.CountryUnpadded (0 ms) [00008.132058][10245][10262][klog] INFO: [ RUN ] WriteElement.ExtendedSupportedRates [00008.132059][10245][10262][klog] INFO: [ OK ] WriteElement.ExtendedSupportedRates (0 ms) [00008.132059][10245][10262][klog] INFO: [ RUN ] WriteElement.MeshConfiguration [00008.132060][10245][10262][klog] INFO: [ OK ] WriteElement.MeshConfiguration (0 ms) [00008.132063][10245][10262][klog] INFO: [ RUN ] WriteElement.MeshId [00008.132063][10245][10262][klog] INFO: [ OK ] WriteElement.MeshId (0 ms) [00008.132064][10245][10262][klog] INFO: [ RUN ] WriteElement.QosCapability [00008.132065][10245][10262][klog] INFO: [ OK ] WriteElement.QosCapability (0 ms) [00008.132065][10245][10262][klog] INFO: [ RUN ] WriteElement.GcrGroupAddress [00008.132066][10245][10262][klog] INFO: [ OK ] WriteElement.GcrGroupAddress (0 ms) [00008.132066][10245][10262][klog] INFO: [ RUN ] WriteElement.HtCapabilities [00008.132067][10245][10262][klog] INFO: [ OK ] WriteElement.HtCapabilities (0 ms) [00008.132067][10245][10262][klog] INFO: [ RUN ] WriteElement.HtOperation [00008.132068][10245][10262][klog] INFO: [ OK ] WriteElement.HtOperation (0 ms) [00008.132071][10245][10262][klog] INFO: [ RUN ] WriteElement.VhtCapabilities [00008.132071][10245][10262][klog] INFO: [ OK ] WriteElement.VhtCapabilities (0 ms) [00008.132072][10245][10262][klog] INFO: [ RUN ] WriteElement.VhtOperation [00008.132072][10245][10262][klog] INFO: [ OK ] WriteElement.VhtOperation (0 ms) [00008.132073][10245][10262][klog] INFO: [ RUN ] WriteElement.MpmOpenNoPmk [00008.132074][10245][10262][klog] INFO: [ OK ] WriteElement.MpmOpenNoPmk (0 ms) [00008.132074][10245][10262][klog] INFO: [ RUN ] WriteElement.MpmOpenWithPmk [00008.132075][10245][10262][klog] INFO: [ OK ] WriteElement.MpmOpenWithPmk (0 ms) [00008.132076][10245][10262][klog] INFO: [ RUN ] WriteElement.MpmConfirmNoPmk [00008.132076][10245][10262][klog] INFO: [ OK ] WriteElement.MpmConfirmNoPmk (0 ms) [00008.132077][10245][10262][klog] INFO: [ RUN ] WriteElement.MpmConfirmWithPmk [00008.132077][10245][10262][klog] INFO: [ OK ] WriteElement.MpmConfirmWithPmk (0 ms) [00008.132078][10245][10262][klog] INFO: [ RUN ] WriteElement.PreqMinimal [00008.132078][10245][10262][klog] INFO: [ OK ] WriteElement.PreqMinimal (0 ms) [00008.132079][10245][10262][klog] INFO: [ RUN ] WriteElement.PreqFull [00008.132079][10245][10262][klog] INFO: [ OK ] WriteElement.PreqFull (0 ms) [00008.132080][10245][10262][klog] INFO: [ RUN ] WriteElement.PrepNoExtAddr [00008.132080][10245][10262][klog] INFO: [ OK ] WriteElement.PrepNoExtAddr (0 ms) [00008.132080][10245][10262][klog] INFO: [ RUN ] WriteElement.PrepWithExtAddr [00008.132081][10245][10262][klog] INFO: [ OK ] WriteElement.PrepWithExtAddr (0 ms) [00008.132081][10245][10262][klog] INFO: [ RUN ] WriteElement.Perr [00008.132082][10245][10262][klog] INFO: [ OK ] WriteElement.Perr (0 ms) [00008.132082][10245][10262][klog] INFO: [----------] 25 tests from WriteElement (0 ms total) [00008.132082][10245][10262][klog] INFO: [00008.132191][10245][10262][klog] INFO: [----------] Global test environment tear-down [00008.132215][10245][10262][klog] INFO: [==========] 254 tests from 36 test suites ran. (15 ms total) [00008.132216][10245][10262][klog] INFO: [ PASSED ] 254 tests. [00008.132244][10245][10262][klog] INFO: PASSED: /pkgfs/packages/wlan_tests/0/test/wlan_mac_unittest passed [00008.440763][10245][10262][klog] INFO: [00008.440801][10245][10262][klog] INFO: ------------------------------------------------ [00008.440805][10245][10262][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan_tests/0/test/wlan_mesh_unittest [00008.440806][10245][10262][klog] INFO: [00008.506077][10245][10262][klog] INFO: [==========] Running 39 tests from 7 test suites. [00008.506129][10245][10262][klog] INFO: [----------] Global test environment set-up. [00008.506129][10245][10262][klog] INFO: [----------] 1 test from Hwmp [00008.506130][10245][10262][klog] INFO: [ RUN ] Hwmp.HwmpSeqnoLessThan [00008.506131][10245][10262][klog] INFO: [ OK ] Hwmp.HwmpSeqnoLessThan (0 ms) [00008.506131][10245][10262][klog] INFO: [----------] 1 test from Hwmp (0 ms total) [00008.506132][10245][10262][klog] INFO: [00008.506133][10245][10262][klog] INFO: [----------] 11 tests from HwmpTest [00008.506133][10245][10262][klog] INFO: [ RUN ] HwmpTest.HandlePreqAddressedToUs [00008.506134][10245][10262][klog] INFO: [ OK ] HwmpTest.HandlePreqAddressedToUs (1 ms) [00008.506134][10245][10262][klog] INFO: [ RUN ] HwmpTest.ForwardPreq [00008.506135][10245][10262][klog] INFO: [ OK ] HwmpTest.ForwardPreq (0 ms) [00008.506135][10245][10262][klog] INFO: [ RUN ] HwmpTest.ReplyToPreqOnBehalfOfAnotherNode [00008.506136][10245][10262][klog] INFO: [ OK ] HwmpTest.ReplyToPreqOnBehalfOfAnotherNode (0 ms) [00008.506137][10245][10262][klog] INFO: [ RUN ] HwmpTest.DontReplyToPreqOnBehalfOfAnotherNode [00008.506137][10245][10262][klog] INFO: [ OK ] HwmpTest.DontReplyToPreqOnBehalfOfAnotherNode (0 ms) [00008.506138][10245][10262][klog] INFO: [ RUN ] HwmpTest.PreqTimeToDie [00008.506138][10245][10262][klog] INFO: [ OK ] HwmpTest.PreqTimeToDie (0 ms) [00008.506139][10245][10262][klog] INFO: [ RUN ] HwmpTest.PathDiscoveryWithRetry [00008.506140][10245][10262][klog] INFO: [ OK ] HwmpTest.PathDiscoveryWithRetry (0 ms) [00008.506140][10245][10262][klog] INFO: [ RUN ] HwmpTest.ForwardPrep [00008.506141][10245][10262][klog] INFO: [ OK ] HwmpTest.ForwardPrep (0 ms) [00008.506141][10245][10262][klog] INFO: [ RUN ] HwmpTest.PrepTimeToDie [00008.507087][10245][10262][klog] INFO: [ OK ] HwmpTest.PrepTimeToDie (0 ms) [00008.507138][10245][10262][klog] INFO: [ RUN ] HwmpTest.HandlePerrDestinationUnreachable [00008.507138][10245][10262][klog] INFO: [ OK ] HwmpTest.HandlePerrDestinationUnreachable (0 ms) [00008.507139][10245][10262][klog] INFO: [ RUN ] HwmpTest.HandlePerrNoForwardingInfo [00008.507140][10245][10262][klog] INFO: [ OK ] HwmpTest.HandlePerrNoForwardingInfo (0 ms) [00008.507141][10245][10262][klog] INFO: [ RUN ] HwmpTest.PerrTimeToDie [00008.507141][10245][10262][klog] INFO: [ OK ] HwmpTest.PerrTimeToDie (0 ms) [00008.507143][10245][10262][klog] INFO: [----------] 11 tests from HwmpTest (1 ms total) [00008.507144][10245][10262][klog] INFO: [00008.507145][10245][10262][klog] INFO: [----------] 1 test from DeDuplicator [00008.507145][10245][10262][klog] INFO: [ RUN ] DeDuplicator.HandleUniquePackets [00008.507146][10245][10262][klog] INFO: [ OK ] DeDuplicator.HandleUniquePackets (0 ms) [00008.507147][10245][10262][klog] INFO: [----------] 1 test from DeDuplicator (0 ms total) [00008.507147][10245][10262][klog] INFO: [00008.507147][10245][10262][klog] INFO: [----------] 11 tests from MeshMlmeTest [00008.507148][10245][10262][klog] INFO: [ RUN ] MeshMlmeTest.JoinLeave [00008.507149][10245][10262][klog] INFO: [ OK ] MeshMlmeTest.JoinLeave (0 ms) [00008.507149][10245][10262][klog] INFO: [ RUN ] MeshMlmeTest.HandleMpmOpen [00008.507150][10245][10262][klog] INFO: [ OK ] MeshMlmeTest.HandleMpmOpen (0 ms) [00008.507150][10245][10262][klog] INFO: [ RUN ] MeshMlmeTest.HandleMpmConfirm [00008.507151][10245][10262][klog] INFO: [ OK ] MeshMlmeTest.HandleMpmConfirm (0 ms) [00008.507152][10245][10262][klog] INFO: [ RUN ] MeshMlmeTest.GetPathTable [00008.507152][10245][10262][klog] INFO: [ OK ] MeshMlmeTest.GetPathTable (0 ms) [00008.507153][10245][10262][klog] INFO: [ RUN ] MeshMlmeTest.DeliverProxiedData [00008.507157][10245][10262][klog] INFO: [ OK ] MeshMlmeTest.DeliverProxiedData (0 ms) [00008.507157][10245][10262][klog] INFO: [ RUN ] MeshMlmeTest.DoNotDeliverWhenNotJoined [00008.507298][10245][10262][klog] INFO: [ OK ] MeshMlmeTest.DoNotDeliverWhenNotJoined (0 ms) [00008.507298][10245][10262][klog] INFO: [ RUN ] MeshMlmeTest.HandlePreq [00008.507299][10245][10262][klog] INFO: [ OK ] MeshMlmeTest.HandlePreq (0 ms) [00008.507300][10245][10262][klog] INFO: [ RUN ] MeshMlmeTest.DeliverDuplicateData [00008.507301][10245][10262][klog] INFO: [ OK ] MeshMlmeTest.DeliverDuplicateData (0 ms) [00008.507301][10245][10262][klog] INFO: [ RUN ] MeshMlmeTest.DataForwarding [00008.507302][10245][10262][klog] INFO: [ OK ] MeshMlmeTest.DataForwarding (0 ms) [00008.507303][10245][10262][klog] INFO: [ RUN ] MeshMlmeTest.OutgoingData [00008.507303][10245][10262][klog] INFO: [ OK ] MeshMlmeTest.OutgoingData (0 ms) [00008.507304][10245][10262][klog] INFO: [ RUN ] MeshMlmeTest.GeneratePerrIfMissingForwardingPath [00008.507305][10245][10262][klog] INFO: [ OK ] MeshMlmeTest.GeneratePerrIfMissingForwardingPath (0 ms) [00008.507305][10245][10262][klog] INFO: [----------] 11 tests from MeshMlmeTest (0 ms total) [00008.507306][10245][10262][klog] INFO: [00008.507307][10245][10262][klog] INFO: [----------] 8 tests from ParseMpOpen [00008.507307][10245][10262][klog] INFO: [ RUN ] ParseMpOpen.Full [00008.507308][10245][10262][klog] INFO: [ OK ] ParseMpOpen.Full (0 ms) [00008.507309][10245][10262][klog] INFO: [ RUN ] ParseMpOpen.Minimal [00008.507309][10245][10262][klog] INFO: [ OK ] ParseMpOpen.Minimal (0 ms) [00008.507310][10245][10262][klog] INFO: [ RUN ] ParseMpOpen.EmptyMeshId [00008.507311][10245][10262][klog] INFO: [ OK ] ParseMpOpen.EmptyMeshId (0 ms) [00008.507311][10245][10262][klog] INFO: [ RUN ] ParseMpOpen.TooShort [00008.507312][10245][10262][klog] INFO: [ OK ] ParseMpOpen.TooShort (0 ms) [00008.507969][10245][10262][klog] INFO: [ RUN ] ParseMpOpen.MissingRates [00008.507996][10245][10262][klog] INFO: [ OK ] ParseMpOpen.MissingRates (0 ms) [00008.507997][10245][10262][klog] INFO: [ RUN ] ParseMpOpen.MissingMeshId [00008.507997][10245][10262][klog] INFO: [ OK ] ParseMpOpen.MissingMeshId (0 ms) [00008.507999][10245][10262][klog] INFO: [ RUN ] ParseMpOpen.MissingMeshConfig [00008.508000][10245][10262][klog] INFO: [ OK ] ParseMpOpen.MissingMeshConfig (0 ms) [00008.508000][10245][10262][klog] INFO: [ RUN ] ParseMpOpen.MissingMpm [00008.508001][10245][10262][klog] INFO: [ OK ] ParseMpOpen.MissingMpm (0 ms) [00008.508002][10245][10262][klog] INFO: [----------] 8 tests from ParseMpOpen (0 ms total) [00008.508002][10245][10262][klog] INFO: [00008.508003][10245][10262][klog] INFO: [----------] 5 tests from ParseMpConfirm [00008.508004][10245][10262][klog] INFO: [ RUN ] ParseMpConfirm.Full [00008.508004][10245][10262][klog] INFO: [ OK ] ParseMpConfirm.Full (0 ms) [00008.508005][10245][10262][klog] INFO: [ RUN ] ParseMpConfirm.Minimal [00008.508005][10245][10262][klog] INFO: [ OK ] ParseMpConfirm.Minimal (0 ms) [00008.508006][10245][10262][klog] INFO: [ RUN ] ParseMpConfirm.TooShortForCapabilityInfo [00008.508007][10245][10262][klog] INFO: [ OK ] ParseMpConfirm.TooShortForCapabilityInfo (0 ms) [00008.508007][10245][10262][klog] INFO: [ RUN ] ParseMpConfirm.TooShortForAid [00008.508008][10245][10262][klog] INFO: [ OK ] ParseMpConfirm.TooShortForAid (0 ms) [00008.508009][10245][10262][klog] INFO: [ RUN ] ParseMpConfirm.MissingMpm [00008.508009][10245][10262][klog] INFO: [ OK ] ParseMpConfirm.MissingMpm (0 ms) [00008.508010][10245][10262][klog] INFO: [----------] 5 tests from ParseMpConfirm (0 ms total) [00008.508011][10245][10262][klog] INFO: [00008.508011][10245][10262][klog] INFO: [----------] 2 tests from WriteMpAction [00008.508012][10245][10262][klog] INFO: [ RUN ] WriteMpAction.Open [00008.508180][10245][10262][klog] INFO: [ OK ] WriteMpAction.Open (0 ms) [00008.508184][10245][10262][klog] INFO: [ RUN ] WriteMpAction.Confirm [00008.508184][10245][10262][klog] INFO: [ OK ] WriteMpAction.Confirm (0 ms) [00008.508185][10245][10262][klog] INFO: [----------] 2 tests from WriteMpAction (0 ms total) [00008.508185][10245][10262][klog] INFO: [00008.508186][10245][10262][klog] INFO: [----------] Global test environment tear-down [00008.508187][10245][10262][klog] INFO: [==========] 39 tests from 7 test suites ran. (1 ms total) [00008.508187][10245][10262][klog] INFO: [ PASSED ] 39 tests. [00008.509134][10245][10262][klog] INFO: PASSED: /pkgfs/packages/wlan_tests/0/test/wlan_mesh_unittest passed [00008.637298][8296][8310][klog] INFO: mDNS: Using unique host name swarm-donut-petri-acre.local. [00008.791245][10245][10262][klog] INFO: [00008.791270][10245][10262][klog] INFO: ------------------------------------------------ [00008.791274][10245][10262][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan_tests/0/test/wlan_scanner_unittest [00008.791274][10245][10262][klog] INFO: [00008.864738][10245][10262][klog] INFO: [==========] Running 10 tests from 1 test suite. [00008.864774][10245][10262][klog] INFO: [----------] Global test environment set-up. [00008.864774][10245][10262][klog] INFO: [----------] 10 tests from ScannerTest [00008.864775][10245][10262][klog] INFO: [ RUN ] ScannerTest.Start [00008.864775][10245][10262][klog] INFO: [ OK ] ScannerTest.Start (0 ms) [00008.864776][10245][10262][klog] INFO: [ RUN ] ScannerTest.Start_InvalidChannelTimes [00008.864776][10245][10262][klog] INFO: [ OK ] ScannerTest.Start_InvalidChannelTimes (0 ms) [00008.864777][10245][10262][klog] INFO: [ RUN ] ScannerTest.Start_NoChannels [00008.864777][10245][10262][klog] INFO: [ OK ] ScannerTest.Start_NoChannels (0 ms) [00008.864778][10245][10262][klog] INFO: [ RUN ] ScannerTest.Reset [00008.864778][10245][10262][klog] INFO: [ OK ] ScannerTest.Reset (0 ms) [00008.864779][10245][10262][klog] INFO: [ RUN ] ScannerTest.ScanChannel [00008.864780][10245][10262][klog] INFO: [ OK ] ScannerTest.ScanChannel (0 ms) [00008.864780][10245][10262][klog] INFO: [ RUN ] ScannerTest.Timeout_NextChannel [00008.864780][10245][10262][klog] INFO: [ OK ] ScannerTest.Timeout_NextChannel (0 ms) [00008.864781][10245][10262][klog] INFO: [ RUN ] ScannerTest.PassiveScanning [00008.864781][10245][10262][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x729b2b5f, actual: 0x345b1b19 [00008.864782][10245][10262][klog] INFO: [ OK ] ScannerTest.PassiveScanning (0 ms) [00008.864782][10245][10262][klog] INFO: [ RUN ] ScannerTest.ActiveScanning [00008.864783][10245][10262][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x729b2b5f, actual: 0x345b1b19 [00008.864783][10245][10262][klog] INFO: [ OK ] ScannerTest.ActiveScanning (0 ms) [00008.864785][10245][10262][klog] INFO: [ RUN ] ScannerTest.BeaconFromHiddenAp [00008.865711][10245][10262][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x729b2b5f, actual: 0x345b1b19 [00008.865732][10245][10262][klog] INFO: [ OK ] ScannerTest.BeaconFromHiddenAp (0 ms) [00008.865732][10245][10262][klog] INFO: [ RUN ] ScannerTest.ActiveScanningWithProbeDelay [00008.865733][10245][10262][klog] INFO: [ OK ] ScannerTest.ActiveScanningWithProbeDelay (0 ms) [00008.865735][10245][10262][klog] INFO: [----------] 10 tests from ScannerTest (0 ms total) [00008.865735][10245][10262][klog] INFO: [00008.865736][10245][10262][klog] INFO: [----------] Global test environment tear-down [00008.865736][10245][10262][klog] INFO: [==========] 10 tests from 1 test suite ran. (0 ms total) [00008.865737][10245][10262][klog] INFO: [ PASSED ] 10 tests. [00008.865959][10245][10262][klog] INFO: PASSED: /pkgfs/packages/wlan_tests/0/test/wlan_scanner_unittest passed [00009.091098][10245][10262][klog] INFO: [00009.091119][10245][10262][klog] INFO: ------------------------------------------------ [00009.091122][10245][10262][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan_tests/0/test/wlan_service_unittest [00009.091123][10245][10262][klog] INFO: [00009.160015][10245][10262][klog] INFO: [==========] Running 7 tests from 2 test suites. [00009.160048][10245][10262][klog] INFO: [----------] Global test environment set-up. [00009.160049][10245][10262][klog] INFO: [----------] 4 tests from MlmeMsg [00009.160050][10245][10262][klog] INFO: [ RUN ] MlmeMsg.General [00009.160050][10245][10262][klog] INFO: [ OK ] MlmeMsg.General (0 ms) [00009.160051][10245][10262][klog] INFO: [ RUN ] MlmeMsg.Generalize [00009.160051][10245][10262][klog] INFO: [ OK ] MlmeMsg.Generalize (0 ms) [00009.160052][10245][10262][klog] INFO: [ RUN ] MlmeMsg.CorruptedPacket [00009.160053][10245][10262][klog] INFO: wlan: [E] could not decode received message: Buffer is too small for first inline object [00009.160053][10245][10262][klog] INFO: [ OK ] MlmeMsg.CorruptedPacket (0 ms) [00009.160054][10245][10262][klog] INFO: [ RUN ] MlmeMsg.MismatchingOrdinal [00009.160054][10245][10262][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x2a [00009.160055][10245][10262][klog] INFO: [ OK ] MlmeMsg.MismatchingOrdinal (0 ms) [00009.160055][10245][10262][klog] INFO: [----------] 4 tests from MlmeMsg (0 ms total) [00009.160056][10245][10262][klog] INFO: [00009.160056][10245][10262][klog] INFO: [----------] 3 tests from ServiceTest [00009.160057][10245][10262][klog] INFO: [ RUN ] ServiceTest.SendAuthInd [00009.160057][10245][10262][klog] INFO: [ OK ] ServiceTest.SendAuthInd (0 ms) [00009.160057][10245][10262][klog] INFO: [ RUN ] ServiceTest.SendAssocInd [00009.160058][10245][10262][klog] INFO: [ OK ] ServiceTest.SendAssocInd (0 ms) [00009.160058][10245][10262][klog] INFO: [ RUN ] ServiceTest.SendAssocInd_EmptyRsne [00009.160059][10245][10262][klog] INFO: [ OK ] ServiceTest.SendAssocInd_EmptyRsne (0 ms) [00009.160059][10245][10262][klog] INFO: [----------] 3 tests from ServiceTest (0 ms total) [00009.160060][10245][10262][klog] INFO: [00009.160777][10245][10262][klog] INFO: [----------] Global test environment tear-down [00009.160810][10245][10262][klog] INFO: [==========] 7 tests from 2 test suites ran. (0 ms total) [00009.160811][10245][10262][klog] INFO: [ PASSED ] 7 tests. [00009.161375][10245][10262][klog] INFO: PASSED: /pkgfs/packages/wlan_tests/0/test/wlan_service_unittest passed [00009.387767][10245][10262][klog] INFO: [00009.387790][10245][10262][klog] INFO: ------------------------------------------------ [00009.387794][10245][10262][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan_tests/0/test/wlan_timer_manager_unittest [00009.387794][10245][10262][klog] INFO: [00009.465622][10245][10262][klog] INFO: [==========] Running 8 tests from 1 test suite. [00009.465680][10245][10262][klog] INFO: [----------] Global test environment set-up. [00009.465681][10245][10262][klog] INFO: [----------] 8 tests from TimerManagerTest [00009.465681][10245][10262][klog] INFO: [ RUN ] TimerManagerTest.HandleTimeout [00009.465682][10245][10262][klog] INFO: [ OK ] TimerManagerTest.HandleTimeout (0 ms) [00009.465682][10245][10262][klog] INFO: [ RUN ] TimerManagerTest.CancelNearestEvent [00009.465683][10245][10262][klog] INFO: [ OK ] TimerManagerTest.CancelNearestEvent (0 ms) [00009.465683][10245][10262][klog] INFO: [ RUN ] TimerManagerTest.HandleLastTimeout [00009.465684][10245][10262][klog] INFO: [ OK ] TimerManagerTest.HandleLastTimeout (0 ms) [00009.465685][10245][10262][klog] INFO: [ RUN ] TimerManagerTest.SchedulingAtLaterTimeDoesNotResetTimer [00009.465685][10245][10262][klog] INFO: [ OK ] TimerManagerTest.SchedulingAtLaterTimeDoesNotResetTimer (0 ms) [00009.465686][10245][10262][klog] INFO: [ RUN ] TimerManagerTest.SchedulingAtEarlierTimeResetsTimer [00009.465687][10245][10262][klog] INFO: [ OK ] TimerManagerTest.SchedulingAtEarlierTimeResetsTimer (0 ms) [00009.465687][10245][10262][klog] INFO: [ RUN ] TimerManagerTest.ScheduleAnotherTimeoutInCallback [00009.465688][10245][10262][klog] INFO: [ OK ] TimerManagerTest.ScheduleAnotherTimeoutInCallback (0 ms) [00009.465689][10245][10262][klog] INFO: [ RUN ] TimerManagerTest.EventsWithSameDeadlineReportedInSchedulingOrder [00009.465689][10245][10262][klog] INFO: [ OK ] TimerManagerTest.EventsWithSameDeadlineReportedInSchedulingOrder (0 ms) [00009.466873][10245][10262][klog] INFO: [ RUN ] TimerManagerTest.CancelAll [00009.466993][10245][10262][klog] INFO: [ OK ] TimerManagerTest.CancelAll (0 ms) [00009.467018][10245][10262][klog] INFO: [----------] 8 tests from TimerManagerTest (0 ms total) [00009.467018][10245][10262][klog] INFO: [00009.467046][10245][10262][klog] INFO: [----------] Global test environment tear-down [00009.467047][10245][10262][klog] INFO: [==========] 8 tests from 1 test suite ran. (0 ms total) [00009.467047][10245][10262][klog] INFO: [ PASSED ] 8 tests. [00009.467667][10245][10262][klog] INFO: PASSED: /pkgfs/packages/wlan_tests/0/test/wlan_timer_manager_unittest passed [00009.733179][10245][10262][klog] INFO: [00009.733206][10245][10262][klog] INFO: ------------------------------------------------ [00009.733209][10245][10262][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan_tests/0/test/wlan_common_unittest [00009.733209][10245][10262][klog] INFO: [00009.806473][10245][10262][klog] INFO: [==========] Running 34 tests from 8 test suites. [00009.806510][10245][10262][klog] INFO: [----------] Global test environment set-up. [00009.806511][10245][10262][klog] INFO: [----------] 7 tests from ChannelSchedulerTest [00009.806511][10245][10262][klog] INFO: [ RUN ] ChannelSchedulerTest.OnChannelFrame [00009.806512][10245][10262][klog] INFO: [ OK ] ChannelSchedulerTest.OnChannelFrame (0 ms) [00009.806512][10245][10262][klog] INFO: [ RUN ] ChannelSchedulerTest.RequestOffChannelTime [00009.806513][10245][10262][klog] INFO: [ OK ] ChannelSchedulerTest.RequestOffChannelTime (0 ms) [00009.806514][10245][10262][klog] INFO: [ RUN ] ChannelSchedulerTest.RequestOffChannelTimeChained [00009.806514][10245][10262][klog] INFO: [ OK ] ChannelSchedulerTest.RequestOffChannelTimeChained (0 ms) [00009.806515][10245][10262][klog] INFO: [ RUN ] ChannelSchedulerTest.SetChannelSwitchesWhenOnChannel [00009.806515][10245][10262][klog] INFO: [ OK ] ChannelSchedulerTest.SetChannelSwitchesWhenOnChannel (0 ms) [00009.806516][10245][10262][klog] INFO: [ RUN ] ChannelSchedulerTest.SetChannelDoesNotSwitchWhenOffChannel [00009.806516][10245][10262][klog] INFO: [ OK ] ChannelSchedulerTest.SetChannelDoesNotSwitchWhenOffChannel (0 ms) [00009.806517][10245][10262][klog] INFO: [ RUN ] ChannelSchedulerTest.EnsureOnChannelDelaysOffChannelRequest [00009.806517][10245][10262][klog] INFO: [ OK ] ChannelSchedulerTest.EnsureOnChannelDelaysOffChannelRequest (0 ms) [00009.806518][10245][10262][klog] INFO: [ RUN ] ChannelSchedulerTest.EnsureOnChannelCancelsOffChannelRequest [00009.807446][10245][10262][klog] INFO: [ OK ] ChannelSchedulerTest.EnsureOnChannelCancelsOffChannelRequest (0 ms) [00009.807487][10245][10262][klog] INFO: [----------] 7 tests from ChannelSchedulerTest (0 ms total) [00009.807487][10245][10262][klog] INFO: [00009.807488][10245][10262][klog] INFO: [----------] 2 tests from FindBandByChannel [00009.807488][10245][10262][klog] INFO: [ RUN ] FindBandByChannel.OneBands [00009.807489][10245][10262][klog] INFO: [ OK ] FindBandByChannel.OneBands (0 ms) [00009.807489][10245][10262][klog] INFO: [ RUN ] FindBandByChannel.TwoBands [00009.807489][10245][10262][klog] INFO: [ OK ] FindBandByChannel.TwoBands (0 ms) [00009.807490][10245][10262][klog] INFO: [----------] 2 tests from FindBandByChannel (0 ms total) [00009.807490][10245][10262][klog] INFO: [00009.807491][10245][10262][klog] INFO: [----------] 1 test from GetRatesByChannel [00009.807491][10245][10262][klog] INFO: [ RUN ] GetRatesByChannel.SimpleTest [00009.807492][10245][10262][klog] INFO: [ OK ] GetRatesByChannel.SimpleTest (0 ms) [00009.807492][10245][10262][klog] INFO: [----------] 1 test from GetRatesByChannel (0 ms total) [00009.807492][10245][10262][klog] INFO: [00009.807493][10245][10262][klog] INFO: [----------] 2 tests from MovingAverageTest [00009.807494][10245][10262][klog] INFO: [ RUN ] MovingAverageTest.MovingAverage [00009.807494][10245][10262][klog] INFO: [ OK ] MovingAverageTest.MovingAverage (0 ms) [00009.807495][10245][10262][klog] INFO: [ RUN ] MovingAverageTest.MovingAverageDbm [00009.807495][10245][10262][klog] INFO: [ OK ] MovingAverageTest.MovingAverageDbm (0 ms) [00009.807496][10245][10262][klog] INFO: [----------] 2 tests from MovingAverageTest (0 ms total) [00009.807496][10245][10262][klog] INFO: [00009.807497][10245][10262][klog] INFO: [----------] 3 tests from WriteSupportedRates [00009.807497][10245][10262][klog] INFO: [ RUN ] WriteSupportedRates.Zero [00009.807500][10245][10262][klog] INFO: [ OK ] WriteSupportedRates.Zero (0 ms) [00009.807501][10245][10262][klog] INFO: [ RUN ] WriteSupportedRates.Three [00009.807501][10245][10262][klog] INFO: [ OK ] WriteSupportedRates.Three (0 ms) [00009.807503][10245][10262][klog] INFO: [ RUN ] WriteSupportedRates.Nine [00009.807503][10245][10262][klog] INFO: [ OK ] WriteSupportedRates.Nine (0 ms) [00009.807504][10245][10262][klog] INFO: [----------] 3 tests from WriteSupportedRates (0 ms total) [00009.807504][10245][10262][klog] INFO: [00009.807505][10245][10262][klog] INFO: [----------] 2 tests from WriteExtendedSupportedRates [00009.807505][10245][10262][klog] INFO: [ RUN ] WriteExtendedSupportedRates.TooFew [00009.807505][10245][10262][klog] INFO: [ OK ] WriteExtendedSupportedRates.TooFew (0 ms) [00009.807506][10245][10262][klog] INFO: [ RUN ] WriteExtendedSupportedRates.One [00009.807506][10245][10262][klog] INFO: [ OK ] WriteExtendedSupportedRates.One (0 ms) [00009.807507][10245][10262][klog] INFO: [----------] 2 tests from WriteExtendedSupportedRates (0 ms total) [00009.807507][10245][10262][klog] INFO: [00009.807508][10245][10262][klog] INFO: [----------] 14 tests from Span [00009.807508][10245][10262][klog] INFO: [ RUN ] Span.DefaultConstructor [00009.807508][10245][10262][klog] INFO: [ OK ] Span.DefaultConstructor (0 ms) [00009.807509][10245][10262][klog] INFO: [ RUN ] Span.CopyConstructor [00009.807509][10245][10262][klog] INFO: [ OK ] Span.CopyConstructor (0 ms) [00009.807510][10245][10262][klog] INFO: [ RUN ] Span.ConstructFromTwoPointers [00009.807510][10245][10262][klog] INFO: [ OK ] Span.ConstructFromTwoPointers (0 ms) [00009.807510][10245][10262][klog] INFO: [ RUN ] Span.ImplicitConversionFromNonConstSpan [00009.807511][10245][10262][klog] INFO: [ OK ] Span.ImplicitConversionFromNonConstSpan (0 ms) [00009.807511][10245][10262][klog] INFO: [ RUN ] Span.ImplicitConversionFromArray [00009.807706][10245][10262][klog] INFO: [ OK ] Span.ImplicitConversionFromArray (0 ms) [00009.807707][10245][10262][klog] INFO: [ RUN ] Span.ImplicitConversionFromStdArray [00009.807708][10245][10262][klog] INFO: [ OK ] Span.ImplicitConversionFromStdArray (0 ms) [00009.807708][10245][10262][klog] INFO: [ RUN ] Span.ImplicitConversionFromVector [00009.807709][10245][10262][klog] INFO: [ OK ] Span.ImplicitConversionFromVector (0 ms) [00009.807709][10245][10262][klog] INFO: [ RUN ] Span.SizeInBytes [00009.807710][10245][10262][klog] INFO: [ OK ] Span.SizeInBytes (0 ms) [00009.807710][10245][10262][klog] INFO: [ RUN ] Span.IndexOperator [00009.807710][10245][10262][klog] INFO: [ OK ] Span.IndexOperator (0 ms) [00009.807711][10245][10262][klog] INFO: [ RUN ] Span.RangeBasedFor [00009.807711][10245][10262][klog] INFO: [ OK ] Span.RangeBasedFor (0 ms) [00009.807712][10245][10262][klog] INFO: [ RUN ] Span.Subspan [00009.807712][10245][10262][klog] INFO: [ OK ] Span.Subspan (0 ms) [00009.807712][10245][10262][klog] INFO: [ RUN ] Span.SubspanWithLength [00009.807713][10245][10262][klog] INFO: [ OK ] Span.SubspanWithLength (0 ms) [00009.807713][10245][10262][klog] INFO: [ RUN ] Span.AsBytes [00009.807714][10245][10262][klog] INFO: [ OK ] Span.AsBytes (0 ms) [00009.807714][10245][10262][klog] INFO: [ RUN ] Span.AsWritableBytes [00009.807715][10245][10262][klog] INFO: [ OK ] Span.AsWritableBytes (0 ms) [00009.807715][10245][10262][klog] INFO: [----------] 14 tests from Span (0 ms total) [00009.807716][10245][10262][klog] INFO: [00009.807716][10245][10262][klog] INFO: [----------] 3 tests from StatsTest [00009.807717][10245][10262][klog] INFO: [ RUN ] StatsTest.DispatcherStatsReset [00009.807717][10245][10262][klog] INFO: [ OK ] StatsTest.DispatcherStatsReset (0 ms) [00009.807718][10245][10262][klog] INFO: [ RUN ] StatsTest.RssiStatsReset [00009.807719][10245][10262][klog] INFO: [ OK ] StatsTest.RssiStatsReset (0 ms) [00009.807719][10245][10262][klog] INFO: [ RUN ] StatsTest.RssiStatsIncAndGet [00009.807722][10245][10262][klog] INFO: [ OK ] StatsTest.RssiStatsIncAndGet (0 ms) [00009.807723][10245][10262][klog] INFO: [----------] 3 tests from StatsTest (0 ms total) [00009.807724][10245][10262][klog] INFO: [00009.807724][10245][10262][klog] INFO: [----------] Global test environment tear-down [00009.807725][10245][10262][klog] INFO: [==========] 34 tests from 8 test suites ran. (0 ms total) [00009.807726][10245][10262][klog] INFO: [ PASSED ] 34 tests. [00009.808386][10245][10262][klog] INFO: PASSED: /pkgfs/packages/wlan_tests/0/test/wlan_common_unittest passed [00009.958259][10245][10262][klog] INFO: [00009.958284][10245][10262][klog] INFO: ------------------------------------------------ [00009.958287][10245][10262][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan-bitfield-tests/0/test/wlan_bitfield_wrapper_lib_test [00009.958288][10245][10262][klog] INFO: [00009.999606][10245][10262][klog] INFO: [00009.999637][10245][10262][klog] INFO: running 12 tests [00010.002511][10245][10262][klog] INFO: test tests::aliased ... ok [00010.003239][10245][10262][klog] INFO: test tests::bool_setter ... ok [00010.007306][10245][10262][klog] INFO: test tests::builders ... ok [00010.007691][10245][10262][klog] INFO: test tests::custom_bool_type ... ok [00010.007906][10245][10262][klog] INFO: test tests::custom_type ... ok [00010.008031][10245][10262][klog] INFO: test tests::debug ... ok [00010.012235][10245][10262][klog] INFO: test tests::getters ... ok [00010.017758][10245][10262][klog] INFO: test tests::debug_pretty ... ok [00010.017918][10245][10262][klog] INFO: test tests::getters_zero ... ok [00010.017951][10245][10262][klog] INFO: test tests::setters_dont_touch_other_fields ... ok [00010.021543][10245][10262][klog] INFO: test tests::setters_truncate ... ok [00010.021567][10245][10262][klog] INFO: test tests::u128 ... ok [00010.021569][10245][10262][klog] INFO: [00010.021569][10245][10262][klog] INFO: test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [00010.021570][10245][10262][klog] INFO: [00010.031025][10245][10262][klog] INFO: PASSED: /pkgfs/packages/wlan-bitfield-tests/0/test/wlan_bitfield_wrapper_lib_test passed [00010.098066][10245][10262][klog] INFO: [00010.098110][10245][10262][klog] INFO: ------------------------------------------------ [00010.098114][10245][10262][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan-common-tests/0/test/wlan_common_lib_test [00010.098115][10245][10262][klog] INFO: [00010.152927][10245][10262][klog] INFO: [00010.152949][10245][10262][klog] INFO: running 188 tests [00010.152951][10245][10262][klog] INFO: test appendable::tests::append_to_vec ... ok [00010.152952][10245][10262][klog] INFO: test big_endian::tests::big_endian_u16 ... ok [00010.152952][10245][10262][klog] INFO: test buffer_reader::tests::peek ... ok [00010.156973][10245][10262][klog] INFO: test buffer_reader::tests::peek_and_read_array ... ok [00010.157724][10245][10262][klog] INFO: test buffer_reader::tests::peek_and_read_value ... ok [00010.157726][10245][10262][klog] INFO: test buffer_reader::tests::peek_mut ... ok [00010.161712][10245][10262][klog] INFO: test buffer_reader::tests::peek_mut_and_modify ... ok [00010.161862][10245][10262][klog] INFO: test buffer_reader::tests::unaligned_access ... ok [00010.162532][10245][10262][klog] INFO: test buffer_writer::tests::append_value_zeroed ... ok [00010.171134][10245][10262][klog] INFO: test buffer_reader::tests::read ... ok [00010.171606][10245][10262][klog] INFO: test buffer_writer::tests::buffer_too_short ... ok [00010.175766][10245][10262][klog] INFO: test buffer_writer::tests::append_bytes ... ok [00010.177300][10245][10262][klog] INFO: test buffer_writer::tests::can_append ... ok [00010.177323][10245][10262][klog] INFO: test channel::tests::fmt_display ... ok [00010.182096][10245][10262][klog] INFO: test channel::tests::test_band_start_freq ... ok [00010.182322][10245][10262][klog] INFO: test channel::tests::test_convert_fidl_channel ... ok [00010.187591][10245][10262][klog] INFO: test channel::tests::test_convert_fidl_phy ... ok [00010.191449][10245][10262][klog] INFO: test channel::tests::test_get_center_chan_idx ... ok [00010.191765][10245][10262][klog] INFO: test channel::tests::test_get_center_freq ... ok [00010.197521][10245][10262][klog] INFO: test channel::tests::test_invalid_combo ... ok [00010.197654][10245][10262][klog] INFO: test channel::tests::test_is_2ghz_or_5ghz ... ok [00010.201151][10245][10262][klog] INFO: test channel::tests::test_is_dfs ... ok [00010.205801][10245][10262][klog] INFO: test channel::tests::test_is_primary_valid ... ok [00010.213744][10245][10262][klog] INFO: test channel::tests::test_valid_combo ... ok [00010.213746][10245][10262][klog] INFO: test data_writer::tests::addr4_is_present_but_to_from_are_invalid ... ok [00010.213748][10245][10262][klog] INFO: test data_writer::tests::client_to_ap ... ok [00010.213748][10245][10262][klog] INFO: test data_writer::tests::ht_ctrl_present_but_no_htc ... ok [00010.230103][10245][10262][klog] INFO: test data_writer::tests::htc_set_but_ht_ctrl_missing ... ok [00010.234031][10245][10262][klog] INFO: test data_writer::tests::qos_ctrl_present_but_no_qos_bit ... ok [00010.234052][10245][10262][klog] INFO: test data_writer::tests::qos_set_but_qos_ctrl_missing ... ok [00010.238950][10245][10262][klog] INFO: test data_writer::tests::to_from_ds_both_set_but_addr4_missing ... ok [00010.244525][10245][10262][klog] INFO: test data_writer::tests::too_small_buffer ... ok [00010.247778][10245][10262][klog] INFO: test data_writer::tests::write_addr4_ht_ctrl ... ok [00010.247831][10245][10262][klog] INFO: test data_writer::tests::write_fixed_fields_only ... ok [00010.247910][10245][10262][klog] INFO: test data_writer::tests::write_llc_hdr ... ok [00010.256249][10245][10262][klog] INFO: test data_writer::tests::write_qos_ctrl ... ok [00010.256872][10245][10262][klog] INFO: test data_writer::tests::wrong_frame_type ... ok [00010.260561][10245][10262][klog] INFO: test format::tests::format_mac_str ... ok [00010.264976][10245][10262][klog] INFO: test ie::fields::tests::perr_iter_empty ... ok [00010.269213][10245][10262][klog] INFO: test ie::fields::tests::perr_iter_too_short_for_ext_addr ... ok [00010.277353][10245][10262][klog] INFO: test ie::fields::tests::perr_iter_too_short_for_header ... ok [00010.277392][10245][10262][klog] INFO: test ie::fields::tests::perr_iter_too_short_for_reason_code ... ok [00010.281817][10245][10262][klog] INFO: test ie::fields::tests::perr_iter_two_destinations ... ok [00010.285593][10245][10262][klog] INFO: test ie::fields::tests::vht_mcs_nss_map_accessor ... ok [00010.289700][10245][10262][klog] INFO: test ie::fields::tests::vht_mcs_nss_map_accssor_error ... ok [00010.289829][10245][10262][klog] INFO: test ie::parse::tests::dsss_param_set_ok ... ok [00010.302567][10245][10262][klog] INFO: test ie::parse::tests::dsss_param_set_wrong_size ... ok [00010.302687][10245][10262][klog] INFO: test ie::parse::tests::ext_supported_rates_empty ... ok [00010.306749][10245][10262][klog] INFO: test ie::parse::tests::ext_supported_rates_ok ... ok [00010.309915][10245][10262][klog] INFO: test ie::parse::tests::ht_capabilities_ok ... ok [00010.314178][10245][10262][klog] INFO: test ie::parse::tests::ht_capabilities_wrong_size ... ok [00010.314210][10245][10262][klog] INFO: test ie::parse::tests::ht_operation_ok ... ok [00010.314344][10245][10262][klog] INFO: test ie::parse::tests::ht_operation_wrong_size ... ok [00010.314868][10245][10262][klog] INFO: test ie::parse::tests::mpm_close_ok_no_link_id_no_pmk ... ok [00010.318151][10245][10262][klog] INFO: test ie::parse::tests::mpm_close_ok_no_link_id_with_pmk ... ok [00010.318173][10245][10262][klog] INFO: test ie::parse::tests::mpm_close_ok_with_link_id_with_pmk ... ok [00010.322001][10245][10262][klog] INFO: test ie::parse::tests::mpm_close_ok_with_link_id_no_pmk ... ok [00010.325746][10245][10262][klog] INFO: test ie::parse::tests::mpm_close_too_long ... ok [00010.330541][10245][10262][klog] INFO: test ie::parse::tests::mpm_close_too_short_for_header ... ok [00010.331779][10245][10262][klog] INFO: test ie::parse::tests::mpm_close_too_short_for_reason_code ... ok [00010.334005][10245][10262][klog] INFO: test ie::parse::tests::mpm_close_weird_length_1 ... ok [00010.334072][10245][10262][klog] INFO: test ie::parse::tests::mpm_close_weird_length_2 ... ok [00010.337615][10245][10262][klog] INFO: test ie::parse::tests::mpm_confirm_ok_no_pmk ... ok [00010.341802][10245][10262][klog] INFO: test ie::parse::tests::mpm_confirm_ok_with_pmk ... ok [00010.341829][10245][10262][klog] INFO: test ie::parse::tests::mpm_confirm_too_long ... ok [00010.343798][10245][10262][klog] INFO: test ie::parse::tests::mpm_confirm_too_short_for_header ... ok [00010.346030][10245][10262][klog] INFO: test ie::parse::tests::mpm_confirm_too_short_for_peer_link_id ... ok [00010.347915][10245][10262][klog] INFO: test ie::parse::tests::mpm_confirm_weird_length ... ok [00010.348707][10245][10262][klog] INFO: test ie::parse::tests::mpm_open_ok_no_pmk ... ok [00010.351737][10245][10262][klog] INFO: test ie::parse::tests::mpm_open_ok_with_pmk ... ok [00010.355953][10245][10262][klog] INFO: test ie::parse::tests::mpm_open_too_long ... ok [00010.356238][10245][10262][klog] INFO: test ie::parse::tests::mpm_open_too_short ... ok [00010.356329][10245][10262][klog] INFO: test ie::parse::tests::mpm_open_weird_length ... ok [00010.359974][10245][10262][klog] INFO: test ie::parse::tests::perr_too_short_for_header ... ok [00010.373031][10245][10262][klog] INFO: test ie::parse::tests::prep_ok_no_ext ... ok [00010.373071][10245][10262][klog] INFO: test ie::parse::tests::perr_ok ... ok [00010.373072][10245][10262][klog] INFO: test ie::parse::tests::prep_too_long ... ok [00010.373198][10245][10262][klog] INFO: test ie::parse::tests::prep_ok_with_ext ... ok [00010.373225][10245][10262][klog] INFO: test ie::parse::tests::prep_too_short_for_ext_addr ... ok [00010.373226][10245][10262][klog] INFO: test ie::parse::tests::prep_too_short_for_header ... ok [00010.373232][10245][10262][klog] INFO: test ie::parse::tests::prep_too_short_for_tail ... ok [00010.373233][10245][10262][klog] INFO: test ie::parse::tests::preq_ok_full ... ok [00010.373233][10245][10262][klog] INFO: test ie::parse::tests::preq_ok_minimal ... ok [00010.373642][10245][10262][klog] INFO: test ie::parse::tests::preq_too_short_for_header ... ok [00010.377766][10245][10262][klog] INFO: test ie::parse::tests::preq_too_short_for_middle ... ok [00010.381811][10245][10262][klog] INFO: test ie::parse::tests::preq_too_long ... ok [00010.383130][10245][10262][klog] INFO: test ie::parse::tests::preq_too_short_for_ext_addr ... ok [00010.386950][10245][10262][klog] INFO: test ie::parse::tests::preq_too_short_for_targets ... ok [00010.387113][10245][10262][klog] INFO: test ie::parse::tests::ssid_ok ... ok [00010.387424][10245][10262][klog] INFO: test ie::parse::tests::ssid_too_long ... ok [00010.387447][10245][10262][klog] INFO: test ie::parse::tests::supported_rates_ok ... ok [00010.397262][10245][10262][klog] INFO: test ie::parse::tests::supported_rates_empty ... ok [00010.403068][10245][10262][klog] INFO: test ie::parse::tests::supported_rates_too_long ... ok [00010.403290][10245][10262][klog] INFO: test ie::parse::tests::tim_bitmap_too_long ... ok [00010.408390][10245][10262][klog] INFO: test ie::parse::tests::tim_empty_bitmap ... ok [00010.409068][10245][10262][klog] INFO: test ie::parse::tests::tim_ok ... ok [00010.410047][10245][10262][klog] INFO: test ie::parse::tests::tim_too_short_for_header ... ok [00010.410085][10245][10262][klog] INFO: test ie::parse::tests::vht_capabilities_ok ... ok [00010.410334][10245][10262][klog] INFO: test ie::parse::tests::vht_capabilities_wrong_size ... ok [00010.415139][10245][10262][klog] INFO: test ie::parse::tests::vht_operation_ok ... ok [00010.415193][10245][10262][klog] INFO: test ie::parse::tests::vht_operation_wrong_size ... ok [00010.425858][10245][10262][klog] INFO: test ie::reader::tests::body_too_short ... ok [00010.429752][10245][10262][klog] INFO: test ie::reader::tests::empty ... ok [00010.430387][10245][10262][klog] INFO: test ie::reader::tests::empty_body ... ok [00010.430388][10245][10262][klog] INFO: test ie::reader::tests::less_than_header ... ok [00010.430389][10245][10262][klog] INFO: test ie::reader::tests::two_elements ... ok [00010.437569][10245][10262][klog] INFO: test ie::rsn::rsne::tests::bench_parse_with_nom ... ok [00010.441962][10245][10262][klog] INFO: test ie::rsn::rsne::tests::test_as_bytes ... ok [00010.442109][10245][10262][klog] INFO: test ie::rsn::rsne::tests::test_rsn_capabilities_setters ... ok [00010.446682][10245][10262][klog] INFO: test ie::rsn::rsne::tests::test_rsn_fields_representation ... ok [00010.446684][10245][10262][klog] INFO: test ie::rsn::rsne::tests::test_short_buffer ... ok [00010.455704][10245][10262][klog] INFO: test ie::write::tests::dsss_param_set ... ok [00010.459287][10245][10262][klog] INFO: test ie::write::tests::ext_supported_rates_ok ... ok [00010.460113][10245][10262][klog] INFO: test ie::write::tests::ext_supported_rates_empty ... ok [00010.464091][10245][10262][klog] INFO: test ie::write::tests::mpm_close_full ... ok [00010.464234][10245][10262][klog] INFO: test ie::write::tests::mpm_close_minimal ... ok [00010.468733][10245][10262][klog] INFO: test ie::write::tests::mpm_confirm_no_pmk ... ok [00010.468767][10245][10262][klog] INFO: test ie::write::tests::mpm_confirm_with_pmk ... ok [00010.473751][10245][10262][klog] INFO: test ie::write::tests::mpm_open_no_pmk ... ok [00010.477681][10245][10262][klog] INFO: test ie::write::tests::mpm_open_with_pmk ... ok [00010.477728][10245][10262][klog] INFO: test ie::write::tests::perr ... ok [00010.482803][10245][10262][klog] INFO: test ie::write::tests::perr_destination_addr_ext_flag_set_but_no_addr_given ... ok [00010.482837][10245][10262][klog] INFO: test ie::write::tests::perr_destination_buffer_too_small ... ok [00010.482838][10245][10262][klog] INFO: test ie::write::tests::perr_destination_ext_addr_given_but_no_flag_set ... ok [00010.488826][10245][10262][klog] INFO: test ie::write::tests::perr_destination_ok_with_ext ... ok [00010.493888][10245][10262][klog] INFO: test ie::write::tests::perr_destination_ok_no_ext ... ok [00010.493976][10245][10262][klog] INFO: test ie::write::tests::prep_addr_ext_flag_set_but_no_addr_given ... ok [00010.499240][10245][10262][klog] INFO: test ie::write::tests::prep_ext_addr_given_but_no_flag_set ... ok [00010.500145][10245][10262][klog] INFO: test ie::write::tests::prep_no_ext ... ok [00010.500168][10245][10262][klog] INFO: test ie::write::tests::prep_with_ext ... ok [00010.504976][10245][10262][klog] INFO: test ie::write::tests::preq_addr_ext_flag_set_but_no_addr_given ... ok [00010.509407][10245][10262][klog] INFO: test ie::write::tests::preq_ext_addr_given_but_no_flag_set ... ok [00010.511790][10245][10262][klog] INFO: test ie::write::tests::preq_full ... ok [00010.511833][10245][10262][klog] INFO: test ie::write::tests::preq_minimal ... ok [00010.511838][10245][10262][klog] INFO: test ie::write::tests::preq_target_count_mismatch ... ok [00010.512136][10245][10262][klog] INFO: test ie::write::tests::ssid_ok ... ok [00010.516571][10245][10262][klog] INFO: test ie::write::tests::ssid_ok_empty ... ok [00010.520740][10245][10262][klog] INFO: test ie::write::tests::ssid_too_long ... ok [00010.524763][10245][10262][klog] INFO: test ie::write::tests::supported_rates_empty ... ok [00010.525362][10245][10262][klog] INFO: test ie::write::tests::supported_rates_ok ... ok [00010.532088][10245][10262][klog] INFO: test ie::write::tests::supported_rates_too_long ... ok [00010.532158][10245][10262][klog] INFO: test ie::write::tests::tim_bitmap_too_long ... ok [00010.532325][10245][10262][klog] INFO: test ie::write::tests::tim_empty_bitmap ... ok [00010.537541][10245][10262][klog] INFO: test ie::write::tests::tim_ok ... ok [00010.543440][10245][10262][klog] INFO: test ie::write::tests::write_ie_body_too_long ... ok [00010.543463][10245][10262][klog] INFO: test ie::write::tests::write_ie_buffer_exactly_long_enough ... ok [00010.543464][10245][10262][klog] INFO: test ie::write::tests::write_ie_buffer_too_small ... ok [00010.550030][10245][10262][klog] INFO: test mac::data::fields::tests::fixed_fields_bssid ... ok [00010.553655][10245][10262][klog] INFO: test mac::data::amsdu::tests::parse_data_amsdu_padding_too_short ... ok [00010.558773][10245][10262][klog] INFO: test mac::data::amsdu::tests::parse_data_amsdu ... ok [00010.559850][10245][10262][klog] INFO: test mac::data::fields::tests::fixed_fields_dst_addr ... ok [00010.564289][10245][10262][klog] INFO: test mac::data::fields::tests::fixed_fields_len ... ok [00010.564317][10245][10262][klog] INFO: test mac::data::fields::tests::fixed_fields_ra ... ok [00010.564600][10245][10262][klog] INFO: test mac::data::fields::tests::fixed_fields_src_addr ... ok [00010.569462][10245][10262][klog] INFO: test mac::data::fields::tests::fixed_fields_ta ... ok [00010.573709][10245][10262][klog] INFO: test mac::data::msdu::tests::msdu_iterator_single_llc_padding ... ok [00010.575276][10245][10262][klog] INFO: test mac::data::msdu::tests::msdu_iterator_single_llc ... ok [00010.575963][10245][10262][klog] INFO: test mac::data::msdu::tests::parse_llc_with_addr4_ht_ctrl ... ok [00010.579150][10245][10262][klog] INFO: test mac::data::tests::parse_data_frame ... ok [00010.587029][10245][10262][klog] INFO: test mac::data::tests::parse_data_frame_with_padding ... ok [00010.587140][10245][10262][klog] INFO: test mac::eth::tests::eth_hdr_big_endian ... ok [00010.587172][10245][10262][klog] INFO: test mac::frame_class::tests::action_frames ... ok [00010.587173][10245][10262][klog] INFO: test mac::frame_class::tests::ctrl_frame_class ... ok [00010.587173][10245][10262][klog] INFO: test mac::frame_class::tests::data_frame_class ... ok [00010.593824][10245][10262][klog] INFO: test mac::mgmt::tests::mgmt_hdr_len ... ok [00010.593902][10245][10262][klog] INFO: test mac::frame_class::tests::mgmt_frame_class ... ok [00010.598843][10245][10262][klog] INFO: test mac::mgmt::tests::parse_beacon_frame ... ok [00010.599183][10245][10262][klog] INFO: test mac::tests::parse_data_frame ... ok [00010.600718][10245][10262][klog] INFO: test mac::tests::parse_mgmt_frame ... ok [00010.604263][10245][10262][klog] INFO: test mac::tests::parse_mgmt_frame_too_short_unsupported ... ok [00010.604912][10245][10262][klog] INFO: test mac::tests::round_up_to_4 ... ok [00010.608838][10245][10262][klog] INFO: test mgmt_writer::tests::client_to_ap ... ok [00010.612759][10245][10262][klog] INFO: test mgmt_writer::tests::from_ds_set ... ok [00010.616280][10245][10262][klog] INFO: test mgmt_writer::tests::ht_ctrl_present_but_no_htc ... ok [00010.616387][10245][10262][klog] INFO: test mgmt_writer::tests::htc_set_but_ht_ctrl_is_missing ... ok [00010.616764][10245][10262][klog] INFO: test mgmt_writer::tests::to_ds_set ... ok [00010.624505][10245][10262][klog] INFO: test mgmt_writer::tests::too_small_buffer ... ok [00010.624535][10245][10262][klog] INFO: test mgmt_writer::tests::write_ht_ctrl ... ok [00010.625272][10245][10262][klog] INFO: test mgmt_writer::tests::write_fixed_fields_only ... ok [00010.632341][10245][10262][klog] INFO: test sequence::tests::mixed_sns_next ... ok [00010.636409][10245][10262][klog] INFO: test sequence::tests::sns1_next_multiple_peers ... ok [00010.637248][10245][10262][klog] INFO: test sequence::tests::sns1_next ... ok [00010.637279][10245][10262][klog] INFO: test sequence::tests::sns2_next_multiple_tids ... ok [00010.640792][10245][10262][klog] INFO: test sequence::tests::sns4_next_multiple_acis ... ok [00010.640910][10245][10262][klog] INFO: test tim::tests::zero_offset ... ok [00010.641125][10245][10262][klog] INFO: test tim::tests::with_offset ... ok [00010.641457][10245][10262][klog] INFO: test unaligned_view::tests::get ... ok [00010.646615][10245][10262][klog] INFO: test unaligned_view::tests::set ... ok [00010.646642][10245][10262][klog] INFO: [00010.646643][10245][10262][klog] INFO: test result: ok. 188 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [00010.646644][10245][10262][klog] INFO: [00010.653495][10245][10262][klog] INFO: PASSED: /pkgfs/packages/wlan-common-tests/0/test/wlan_common_lib_test passed [00010.720120][10245][10262][klog] INFO: [00010.720150][10245][10262][klog] INFO: ------------------------------------------------ [00010.720153][10245][10262][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan-inspect-tests/0/test/wlan_inspect_lib_test [00010.720154][10245][10262][klog] INFO: [00010.770895][10245][10262][klog] INFO: [00010.770926][10245][10262][klog] INFO: running 8 tests [00010.770928][10245][10262][klog] INFO: test iface_mgr::tests::test_iface_manager ... ok [00010.775377][10245][10262][klog] INFO: test log::tests::test_inspect_log_parsing ... ok [00010.775965][10245][10262][klog] INFO: test log::tests::test_inspect_log_macro_does_not_move_value ... ok [00010.777592][10245][10262][klog] INFO: test log::tests::test_inspect_log_macro ... ok [00010.778092][10245][10262][klog] INFO: test log::tests::test_log_inspect_bytes ... ok [00010.778093][10245][10262][klog] INFO: test log::tests::test_log_inspect_list ... ok [00010.778094][10245][10262][klog] INFO: test nodes::list::tests::test_bounded_list_node ... ok [00010.778116][10245][10262][klog] INFO: test nodes::tests::test_time_metadata_format ... ok [00010.778116][10245][10262][klog] INFO: [00010.778117][10245][10262][klog] INFO: test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [00010.778117][10245][10262][klog] INFO: [00010.780179][10245][10262][klog] INFO: PASSED: /pkgfs/packages/wlan-inspect-tests/0/test/wlan_inspect_lib_test passed [00010.867925][10245][10262][klog] INFO: [00010.867958][10245][10262][klog] INFO: ------------------------------------------------ [00010.867961][10245][10262][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan-mlme-tests/0/test/wlan_mlme_lib_test [00010.867962][10245][10262][klog] INFO: [00010.910465][10245][10262][klog] INFO: [00010.910490][10245][10262][klog] INFO: running 22 tests [00010.910491][10245][10262][klog] INFO: test auth::tests::valid_open_auth_resp ... ok [00010.910492][10245][10262][klog] INFO: test buffer::tests::as_mut_slice ... ok [00010.910493][10245][10262][klog] INFO: test auth::tests::invalid_open_auth_resp ... ok [00010.910493][10245][10262][klog] INFO: test buffer::tests::as_mut_slice_null_data ... ok [00010.910831][10245][10262][klog] INFO: test buffer::tests::as_slice ... ok [00010.916751][10245][10262][klog] INFO: test buffer::tests::as_slice_null_data ... ok [00010.916781][10245][10262][klog] INFO: test buffer::tests::from_in_buf ... ok [00010.923119][10245][10262][klog] INFO: test buffer::tests::return_out_of_scope_buffer ... ok [00010.923617][10245][10262][klog] INFO: test client::tests::data_frame ... ok [00010.923990][10245][10262][klog] INFO: test client::tests::data_frame_buffer_too_small ... ok [00010.924015][10245][10262][klog] INFO: test client::tests::data_frame_empty_payload ... ok [00010.930591][10245][10262][klog] INFO: test client::tests::data_frame_protected_qos ... ok [00010.930686][10245][10262][klog] INFO: test client::tests::data_frame_to_ethernet_amsdu_padding_too_short ... ok [00010.935238][10245][10262][klog] INFO: test client::tests::data_frame_to_ethernet_amsdu ... ok [00010.939221][10245][10262][klog] INFO: test client::tests::data_frame_to_ethernet_single_llc ... ok [00010.939249][10245][10262][klog] INFO: test client::tests::deauth_frame ... ok [00010.939516][10245][10262][klog] INFO: test client::tests::eth_frame_buffer_too_small ... ok [00010.939641][10245][10262][klog] INFO: test client::tests::eth_frame_empty_payload ... ok [00010.939860][10245][10262][klog] INFO: test client::tests::eth_frame_ok ... ok [00010.939861][10245][10262][klog] INFO: test client::tests::open_auth_frame ... ok [00010.940932][10245][10262][klog] INFO: test device::tests::fake_device_deliver_ethernet ... ok [00010.940959][10245][10262][klog] INFO: test client::tests::keep_alive_resp_frame ... ok [00010.940960][10245][10262][klog] INFO: [00010.940960][10245][10262][klog] INFO: test result: ok. 22 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [00010.940961][10245][10262][klog] INFO: [00010.947940][10245][10262][klog] INFO: PASSED: /pkgfs/packages/wlan-mlme-tests/0/test/wlan_mlme_lib_test passed [00011.051287][10245][10262][klog] INFO: [00011.051321][10245][10262][klog] INFO: ------------------------------------------------ [00011.051324][10245][10262][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan-rsn-tests/0/test/wlan_rsn_lib_test [00011.051325][10245][10262][klog] INFO: [00011.111668][10245][10262][klog] INFO: [00011.111696][10245][10262][klog] INFO: running 89 tests [00011.111697][10245][10262][klog] INFO: test auth::psk::tests::test_psk_invalid_char_password ... ok [00011.111699][10245][10262][klog] INFO: test auth::psk::tests::test_psk_invalid_unicode_char_password ... ok [00011.119152][10245][10262][klog] INFO: test auth::psk::tests::test_psk_test_case_2 ... ok [00011.123618][10245][10262][klog] INFO: test auth::psk::tests::test_psk_too_long_password ... ok [00011.127964][10245][10262][klog] INFO: test auth::psk::tests::test_psk_too_long_ssid ... ok [00011.127986][10245][10262][klog] INFO: test auth::psk::tests::test_psk_ascii_bounds_password ... ok [00011.132521][10245][10262][klog] INFO: test auth::psk::tests::test_psk_too_short_password ... ok [00011.133101][10245][10262][klog] INFO: test auth::psk::tests::test_psk_unicode_valid_length_password ... ok [00011.133103][10245][10262][klog] INFO: test auth::psk::tests::test_psk_test_case_1 ... ok [00011.143954][10245][10262][klog] INFO: test crypto_utils::tests::test_prf_all_empty ... ok [00011.143980][10245][10262][klog] INFO: test crypto_utils::nonce::tests::test_next_nonce ... ok [00011.150680][10245][10262][klog] INFO: test auth::psk::tests::test_psk_test_case_3 ... ok [00011.154390][10245][10262][klog] INFO: test crypto_utils::tests::test_prf_empty_data ... ok [00011.154391][10245][10262][klog] INFO: test crypto_utils::tests::test_prf_empty_key ... ok [00011.154392][10245][10262][klog] INFO: test crypto_utils::tests::test_prf_empty_prefix ... ok [00011.154603][10245][10262][klog] INFO: test crypto_utils::tests::test_prf_test_case_1 ... ok [00011.166350][10245][10262][klog] INFO: test crypto_utils::tests::test_prf_invalid_bit_sizes ... ok [00011.177450][10245][10262][klog] INFO: test crypto_utils::tests::test_prf_test_case_3 ... ok [00011.177477][10245][10262][klog] INFO: test crypto_utils::tests::test_prf_test_case_2 ... ok [00011.177478][10245][10262][klog] INFO: test crypto_utils::tests::test_prf_test_case_65_1 ... ok [00011.177478][10245][10262][klog] INFO: test crypto_utils::tests::test_prf_test_case_65_3 ... ok [00011.177841][10245][10262][klog] INFO: test crypto_utils::tests::test_prf_test_case_65_2 ... ok [00011.179323][10245][10262][klog] INFO: test crypto_utils::tests::test_prf_test_case_65_4 ... ok [00011.181166][10245][10262][klog] INFO: test crypto_utils::tests::test_prf_valid_bit_sizes ... ok [00011.185711][10245][10262][klog] INFO: test integrity::hmac_sha1::tests::test_test_case_1 ... ok [00011.185736][10245][10262][klog] INFO: test integrity::hmac_sha1::tests::test_test_case_2 ... ok [00011.189936][10245][10262][klog] INFO: test integrity::hmac_sha1::tests::test_test_case_3 ... ok [00011.194109][10245][10262][klog] INFO: test integrity::hmac_sha1::tests::test_test_case_4 ... ok [00011.194944][10245][10262][klog] INFO: test integrity::hmac_sha1::tests::test_test_case_5 ... ok [00011.198563][10245][10262][klog] INFO: test integrity::hmac_sha1::tests::test_test_case_6 ... ok [00011.199141][10245][10262][klog] INFO: test integrity::hmac_sha1::tests::test_test_case_7 ... ok [00011.202099][10245][10262][klog] INFO: test integrity::hmac_sha1::tests::test_test_case_8 ... ok [00011.202979][10245][10262][klog] INFO: test integrity::hmac_sha1::tests::test_test_case_9 ... ok [00011.205772][10245][10262][klog] INFO: test integrity::hmac_sha1::tests::test_verify_failure ... ok [00011.206991][10245][10262][klog] INFO: test key::exchange::handshake::fourway::tests::test_random_iv_msg1_v1 ... ok [00011.210019][10245][10262][klog] INFO: test key::exchange::handshake::fourway::tests::test_random_iv_msg1_v2 ... ok [00011.210049][10245][10262][klog] INFO: test key::exchange::handshake::fourway::tests::test_random_iv_msg3_v3 ... ok [00011.212129][10245][10262][klog] INFO: test key::exchange::handshake::fourway::tests::test_random_iv_msg3_v2 ... ok [00011.212166][10245][10262][klog] INFO: test key::exchange::handshake::fourway::tests::test_random_iv_msg3_v1 ... ok [00011.215007][10245][10262][klog] INFO: test key::exchange::handshake::fourway::tests::test_supplicant_with_authenticator ... ok [00011.219487][10245][10262][klog] INFO: test key::exchange::handshake::group_key::tests::random_iv_8021x2001 ... ok [00011.223867][10245][10262][klog] INFO: test key::exchange::handshake::group_key::tests::random_iv_8021x2004 ... ok [00011.233317][10245][10262][klog] INFO: test key::exchange::handshake::fourway::tests::test_zeroed_iv_msg3_v2 ... ok [00011.234084][10245][10262][klog] INFO: test key::exchange::handshake::group_key::tests::zeroed_iv_8021x2001 ... ok [00011.236737][10245][10262][klog] INFO: test key::exchange::handshake::group_key::tests::zeroed_iv_8021x2004 ... ok [00011.236764][10245][10262][klog] INFO: test key::ptk::tests::test_pairwise_key_hierarchy_ccmp ... ok [00011.236765][10245][10262][klog] INFO: test key::ptk::tests::test_pairwise_key_hierarchy_invalid_pmk ... ok [00011.244223][10245][10262][klog] INFO: test key::ptk::tests::test_pairwise_key_hierarchy_tkip ... ok [00011.244359][10245][10262][klog] INFO: test key::ptk::tests::test_pairwise_key_hierarchy_unsupported_akm ... ok [00011.244360][10245][10262][klog] INFO: test key::ptk::tests::test_pairwise_key_hierarchy_unsupported_cipher ... ok [00011.245917][10245][10262][klog] INFO: test key_data::kde::tests::test_create_gtk_element ... ok [00011.255759][10245][10262][klog] INFO: test key_data::kde::tests::test_gtk_as_bytes ... ok [00011.259078][10245][10262][klog] INFO: test key_data::kde::tests::test_gtk_as_bytes_too_short ... ok [00011.263075][10245][10262][klog] INFO: test key_data::kde::tests::test_gtk_len ... ok [00011.266801][10245][10262][klog] INFO: test key_data::kde::tests::test_hdr_as_bytes ... ok [00011.267664][10245][10262][klog] INFO: test key_data::tests::test_add_padding_8_multiple_length ... ok [00011.267666][10245][10262][klog] INFO: test key_data::tests::test_add_padding_min_length ... ok [00011.268348][10245][10262][klog] INFO: test key_data::tests::test_gtk ... ok [00011.276103][10245][10262][klog] INFO: test key_data::tests::test_long_gtk ... ok [00011.276300][10245][10262][klog] INFO: test key_data::tests::test_complex_key_data ... ok [00011.276305][10245][10262][klog] INFO: test key_data::tests::test_long_padding ... ok [00011.287199][10245][10262][klog] INFO: test key_data::tests::test_no_padding ... ok [00011.287252][10245][10262][klog] INFO: test key_data::tests::test_not_multiple_of_8 ... ok [00011.287256][10245][10262][klog] INFO: test key_data::tests::test_single_padding_byte ... ok [00011.290708][10245][10262][klog] INFO: test key_data::tests::test_too_short_key_data ... ok [00011.295452][10245][10262][klog] INFO: test keywrap::aes::tests::test_128_data_128_kek ... ok [00011.298944][10245][10262][klog] INFO: test keywrap::aes::tests::test_128_data_192_kek ... ok [00011.298984][10245][10262][klog] INFO: test key::gtk::tests::test_gtk_generation ... ok [00011.298985][10245][10262][klog] INFO: test keywrap::aes::tests::test_128_data_256_kek ... ok [00011.299510][10245][10262][klog] INFO: test keywrap::aes::tests::test_256_data_256_kek ... ok [00011.304845][10245][10262][klog] INFO: test keywrap::aes::tests::test_192_data_192_kek ... ok [00011.304873][10245][10262][klog] INFO: test keywrap::aes::tests::test_192_data_256_kek ... ok [00011.304875][10245][10262][klog] INFO: test keywrap::aes::tests::test_invalid_data_length ... ok [00011.311727][10245][10262][klog] INFO: test keywrap::aes::tests::test_invalid_key_length ... ok [00011.311757][10245][10262][klog] INFO: test keywrap::aes::tests::test_too_short_data ... ok [00011.311757][10245][10262][klog] INFO: test keywrap::aes::tests::test_unwrap_wrong_key ... ok [00011.344511][10245][10262][klog] INFO: test rsna::esssa::tests::test_nonzero_key_replay_counter_msg1 ... ok [00011.348598][10245][10262][klog] INFO: test rsna::esssa::tests::test_replay_first_message ... ok [00011.356066][10245][10262][klog] INFO: test rsna::esssa::tests::test_supplicant_with_authenticator ... ok [00011.371674][10245][10262][klog] INFO: test rsna::esssa::tests::test_supplicant_wpa2_ccmp128_psk ... ok [00011.371876][10245][10262][klog] INFO: test rsna::esssa::tests::test_replayed_msg1_ptk_installation ... ok [00011.378723][10245][10262][klog] INFO: test rsna::esssa::tests::test_zero_key_replay_counter_lower_msg3_counter ... ok [00011.381481][10245][10262][klog] INFO: test rsna::tests::test_negotiated_rsne_from_rsne ... ok [00011.381535][10245][10262][klog] INFO: test rsna::tests::test_supplicant_sends_random_key_length ... ok [00011.388807][10245][10262][klog] INFO: test rsna::tests::test_supplicant_sends_zeroed_and_non_zeroed_key_length ... ok [00011.395968][10245][10262][klog] INFO: test rsna::esssa::tests::test_zero_key_replay_counter_msg1 ... ok [00011.396002][10245][10262][klog] INFO: test rsna::tests::test_to_rsne ... ok [00011.398249][10245][10262][klog] INFO: test rsna::esssa::tests::test_zero_key_replay_counter_replayed_msg3 ... ok [00011.408445][10245][10262][klog] INFO: test rsna::esssa::tests::test_zero_key_replay_counter_valid_msg3 ... ok [00011.408489][10245][10262][klog] INFO: [00011.408490][10245][10262][klog] INFO: test result: ok. 89 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [00011.408491][10245][10262][klog] INFO: [00011.408597][10245][10262][klog] INFO: PASSED: /pkgfs/packages/wlan-rsn-tests/0/test/wlan_rsn_lib_test passed [00011.495528][10245][10262][klog] INFO: [00011.495564][10245][10262][klog] INFO: ------------------------------------------------ [00011.495567][10245][10262][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan-sme-tests/0/test/wlan_sme_lib_test [00011.495568][10245][10262][klog] INFO: [00011.546915][10245][10262][klog] INFO: [00011.546952][10245][10262][klog] INFO: running 111 tests [00011.552030][10245][10262][klog] INFO: test ap::aid::tests::test_map_no_available_assoc_id ... ok [00011.553810][10245][10262][klog] INFO: test ap::aid::tests::test_map_mixed_case ... ok [00011.557589][10245][10262][klog] INFO: test ap::aid::tests::test_map_never_assign_zero ... ok [00011.561812][10245][10262][klog] INFO: test ap::remote_client::tests::test_add_client_multiple_times ... ok [00011.562068][10245][10262][klog] INFO: test ap::remote_client::tests::test_remote_client_handle_eapol_ind_invalid ... ok [00011.570854][10245][10262][klog] INFO: test ap::remote_client::tests::test_remote_client_ignore_timeout_if_key_handshake_succeeds ... ok [00011.571050][10245][10262][klog] INFO: test ap::remote_client::tests::test_remote_client_key_handshake_timeout ... ok [00011.571176][10245][10262][klog] INFO: test ap::remote_client::tests::test_remote_client_map ... ok [00011.574715][10245][10262][klog] INFO: test ap::remote_client::tests::test_remote_client_key_handshake ... ok [00011.577806][10245][10262][klog] INFO: test ap::rsn::tests::test_authenticator_has_invalid_mgmt_frame_protection_fields ... ok [00011.578344][10245][10262][klog] INFO: test ap::rsn::tests::test_authenticator_missing_required_mpfc ... ok [00011.578346][10245][10262][klog] INFO: test ap::rsn::tests::test_rsne_unsupported_akm ... ok [00011.582201][10245][10262][klog] INFO: test ap::rsn::tests::test_rsne_unsupported_group_data_cipher ... ok [00011.591042][10245][10262][klog] INFO: test ap::rsn::tests::test_rsne_unsupported_pairwise_cipher ... ok [00011.591068][10245][10262][klog] INFO: test ap::rsn::tests::test_supplicant_has_invalid_mgmt_frame_protection_fields ... ok [00011.594737][10245][10262][klog] INFO: test ap::rsn::tests::test_supplicant_missing_required_mpfc ... ok [00011.606822][10245][10262][klog] INFO: test ap::rsn::tests::test_valid_rsne ... ok [00011.607546][10245][10262][klog] INFO: test ap::rsn::tests::test_supplicant_rsne_has_too_many_suites ... ok [00011.608983][10245][10262][klog] INFO: test ap::rsn::tests::test_wpa2_psk_rsne_bytes ... ok [00011.616553][10245][10262][klog] INFO: test ap::tests::ap_starts_fails ... ok [00011.616982][10245][10262][klog] INFO: test ap::tests::ap_starts_success ... ok [00011.624636][10245][10262][klog] INFO: test ap::tests::ap_starts_timeout ... ok [00011.625644][10245][10262][klog] INFO: test ap::tests::ap_stops_after_started ... ok [00011.629894][10245][10262][klog] INFO: test ap::tests::ap_stops_while_idle ... ok [00011.629919][10245][10262][klog] INFO: test ap::tests::authenticate_while_sme_is_idle ... ok [00011.643539][10245][10262][klog] INFO: test ap::tests::client_associates_unprotected_network ... ok [00011.648337][10245][10262][klog] INFO: test ap::tests::client_authenticates_unsupported_authentication_type ... ok [00011.648383][10245][10262][klog] INFO: test ap::tests::client_associates_invalid_rsne ... ok [00011.655801][10245][10262][klog] INFO: test ap::tests::client_authenticates_supported_authentication_type ... ok [00011.655825][10245][10262][klog] INFO: test ap::tests::client_restarts_authentication_flow ... ok [00011.655826][10245][10262][klog] INFO: test ap::tests::client_associates_valid_rsne ... ok [00011.667634][10245][10262][klog] INFO: test ap::tests::start_req_while_ap_is_starting ... ok [00011.667658][10245][10262][klog] INFO: test ap::tests::test_adapt_operation ... ok [00011.667659][10245][10262][klog] INFO: test ap::tests::stop_req_while_ap_is_starting ... ok [00011.679447][10245][10262][klog] INFO: test ap::tests::test_validate_config ... ok [00011.679535][10245][10262][klog] INFO: test ap::tests::multiple_clients_associate ... ok [00011.679536][10245][10262][klog] INFO: test client::bss::tests::compare ... ok [00011.681393][10245][10262][klog] INFO: test client::bss::tests::convert_bss ... ok [00011.686537][10245][10262][klog] INFO: test client::bss::tests::get_best_bss_empty_list ... ok [00011.686974][10245][10262][klog] INFO: test ap::tests::rsn_handshake_timeout ... ok [00011.686975][10245][10262][klog] INFO: test client::bss::tests::get_best_bss_nonempty_list ... ok [00011.687249][10245][10262][klog] INFO: test client::bss::tests::group_networks_by_ssid ... ok [00011.691418][10245][10262][klog] INFO: test client::bss::tests::verify_compatibility ... ok [00011.695223][10245][10262][klog] INFO: test client::rsn::tests::test_ccmp128_group_data_pairwise_cipher_psk ... ok [00011.696117][10245][10262][klog] INFO: test client::rsn::tests::test_get_rsna_invalid_psk ... ok [00011.699585][10245][10262][klog] INFO: test client::rsn::tests::test_get_rsna_no_password_for_protected_network ... ok [00011.702718][10245][10262][klog] INFO: test client::rsn::tests::test_get_rsna_password_for_unprotected_network ... ok [00011.705476][10245][10262][klog] INFO: test client::rsn::tests::test_get_rsna_psk ... ok [00011.705654][10245][10262][klog] INFO: test client::rsn::tests::test_incompatible_akm ... ok [00011.712430][10245][10262][klog] INFO: test client::rsn::tests::test_incompatible_group_data_cipher ... ok [00011.718581][10245][10262][klog] INFO: test client::rsn::tests::test_incompatible_pairwise_cipher ... ok [00011.722362][10245][10262][klog] INFO: test client::rsn::tests::test_mixed_mode ... ok [00011.723393][10245][10262][klog] INFO: test client::rsn::tests::test_no_akm ... ok [00011.726015][10245][10262][klog] INFO: test client::rsn::tests::test_no_group_data_cipher ... ok [00011.726699][10245][10262][klog] INFO: test client::rsn::tests::test_no_pairwise_cipher ... ok [00011.731084][10245][10262][klog] INFO: test client::rsn::tests::test_rsn_capabilities ... ok [00011.731666][10245][10262][klog] INFO: test client::rsn::tests::test_tkip_group_data_cipher ... ok [00011.734702][10245][10262][klog] INFO: test client::rsn::tests::test_tkip_pairwise_cipher ... ok [00011.738529][10245][10262][klog] INFO: test client::scan::tests::discovery_scan ... ok [00011.740272][10245][10262][klog] INFO: test client::scan::tests::get_join_scan ... ok [00011.740891][10245][10262][klog] INFO: test client::scan::tests::join_scan ... ok [00011.743551][10245][10262][klog] INFO: test client::scan::tests::test_active_discovery_scan_args ... ok [00011.743809][10245][10262][klog] INFO: test client::scan::tests::test_active_join_scan_args ... ok [00011.746794][10245][10262][klog] INFO: test client::scan::tests::test_discovery_scans_dedupe_multiple_groups ... ok [00011.746830][10245][10262][klog] INFO: test client::scan::tests::test_discovery_scans_dedupe_single_group ... ok [00011.750327][10245][10262][klog] INFO: test client::scan::tests::test_passive_discovery_scan_args ... ok [00011.750411][10245][10262][klog] INFO: test client::scan::tests::test_passive_join_scan_args ... ok [00011.750632][10245][10262][klog] INFO: test client::scan::tests::test_scan_channels_arg_when_dfs_channel_handling_not_supported ... ok [00011.750632][10245][10262][klog] INFO: test client::scan::tests::test_scan_channels_arg_when_dfs_channel_handling_supported ... ok [00011.754361][10245][10262][klog] INFO: test client::state::tests::associate_failure ... ok [00011.758817][10245][10262][klog] INFO: test client::state::tests::associate_happy_path_protected ... ok [00011.762639][10245][10262][klog] INFO: test client::state::tests::authenticate_failure ... ok [00011.762783][10245][10262][klog] INFO: test client::state::tests::associate_happy_path_unprotected ... ok [00011.762902][10245][10262][klog] INFO: test client::state::tests::bad_eapol_frame_while_establishing_rsna ... ok [00011.767303][10245][10262][klog] INFO: test client::state::tests::connect_while_associating ... ok [00011.767511][10245][10262][klog] INFO: test client::state::tests::connect_while_authenticating ... ok [00011.768376][10245][10262][klog] INFO: test client::state::tests::connect_while_joining ... ok [00011.772093][10245][10262][klog] INFO: test client::state::tests::disconnect_while_associating ... ok [00011.782010][10245][10262][klog] INFO: test client::state::tests::connect_while_link_up ... ok [00011.782326][10245][10262][klog] INFO: test client::state::tests::disconnect_while_authenticating ... ok [00011.786106][10245][10262][klog] INFO: test client::state::tests::disconnect_while_idle ... ok [00011.786132][10245][10262][klog] INFO: test client::state::tests::disconnect_while_joining ... ok [00011.786135][10245][10262][klog] INFO: test client::state::tests::disconnect_while_link_up ... ok [00011.794098][10245][10262][klog] INFO: test client::state::tests::increment_att_id_on_connect ... ok [00011.798015][10245][10262][klog] INFO: test client::state::tests::join_failure ... ok [00011.799487][10245][10262][klog] INFO: test client::state::tests::increment_att_id_on_disassociate_ind ... ok [00011.799521][10245][10262][klog] INFO: test client::state::tests::key_frame_exchange_timeout_while_establishing_rsna ... ok [00011.800228][10245][10262][klog] INFO: test client::state::tests::overall_timeout_while_establishing_rsna ... ok [00011.804991][10245][10262][klog] INFO: test client::state::tests::supplicant_fails_to_process_eapol_while_establishing_rsna ... ok [00011.805203][10245][10262][klog] INFO: test client::state::tests::supplicant_fails_to_start_while_associating ... ok [00011.806789][10245][10262][klog] INFO: test client::state::tests::wrong_password_while_establishing_rsna ... ok [00011.810876][10245][10262][klog] INFO: test client::tests::connecting_no_password_supplied_for_protected_network ... ok [00011.810898][10245][10262][klog] INFO: test client::tests::connecting_generates_info_events ... ok [00011.822047][10245][10262][klog] INFO: test client::tests::connecting_password_supplied_for_unprotected_network ... ok [00011.822944][10245][10262][klog] INFO: test client::tests::connecting_psk_supplied_for_protected_network ... ok [00011.826088][10245][10262][klog] INFO: test client::tests::connecting_psk_supplied_for_unprotected_network ... ok [00011.826094][10245][10262][klog] INFO: test mesh::tests::test_validate_config ... ok [00011.841933][10245][10262][klog] INFO: test phy_selection::tests::band_id ... ok [00011.842340][10245][10262][klog] INFO: test client::tests::status_connecting_to ... ok [00011.842368][10245][10262][klog] INFO: test client::tests::connecting_password_supplied_for_protected_network ... ok [00011.854436][10245][10262][klog] INFO: test phy_selection::tests::test_convert_secchan_offset_to_cbw ... ok [00011.854438][10245][10262][klog] INFO: test phy_selection::tests::test_convert_chanwidth_to_cbw ... ok [00011.854442][10245][10262][klog] INFO: test phy_selection::tests::test_convert_vht_segments_to_cbw ... ok [00011.854445][10245][10262][klog] INFO: test phy_selection::tests::test_derive_cbw_ht ... ok [00011.860414][10245][10262][klog] INFO: test phy_selection::tests::test_derive_cbw_vht ... ok [00011.864494][10245][10262][klog] INFO: test phy_selection::tests::test_derive_phy_cbw_for_ap ... ok [00011.865025][10245][10262][klog] INFO: test phy_selection::tests::test_derive_phy_cbw ... ok [00011.870654][10245][10262][klog] INFO: test phy_selection::tests::test_get_band_id ... ok [00011.870820][10245][10262][klog] INFO: test phy_selection::tests::test_get_device_band_info ... ok [00011.870883][10245][10262][klog] INFO: test timer::tests::test_timer_schedule ... ok [00011.870914][10245][10262][klog] INFO: test timer::tests::test_timer_schedule_at ... ok [00011.871637][10245][10262][klog] INFO: [00011.871664][10245][10262][klog] INFO: test result: ok. 111 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [00011.871664][10245][10262][klog] INFO: [00011.876555][10245][10262][klog] INFO: PASSED: /pkgfs/packages/wlan-sme-tests/0/test/wlan_sme_lib_test passed [00011.991367][10245][10262][klog] INFO: [00011.991414][10245][10262][klog] INFO: ------------------------------------------------ [00011.991417][10245][10262][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan_drivers_tests/0/test/wlan_minstrel_unittest [00011.991418][10245][10262][klog] INFO: [00012.021633][1344936915][0][amber] INFO: control_server: could not get update for run_test_component/0: merkle not found for package run_test_component/0 [00012.021939][6841][6952][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/run_test_component/0 was not found: merkle not found for package run_test_component/0 [00012.022443][6841][6952][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00012.037557][10245][10262][klog] INFO: FAILURE: Failed to launch /pkgfs/packages/wlan_drivers_tests/0/test/wlan_minstrel_unittest: -1 (ZX_ERR_INTERNAL): failed to resolve fuchsia-pkg://fuchsia.com/run_test_component#bin/run-test-component [00012.121671][10245][10262][klog] INFO: [00012.121709][10245][10262][klog] INFO: ------------------------------------------------ [00012.121712][10245][10262][klog] INFO: RUNNING TEST: /pkgfs/packages/wlantool2-tests/0/test/wlantool2_bin_test [00012.121713][10245][10262][klog] INFO: [00012.160981][10245][10262][klog] INFO: [00012.161087][10245][10262][klog] INFO: running 3 tests [00012.161087][10245][10262][klog] INFO: test tests::format_bssid ... ok [00012.161088][10245][10262][klog] INFO: test tests::mac_addr_from_str ... ok [00012.161088][10245][10262][klog] INFO: test tests::make_credentials ... ok [00012.161089][10245][10262][klog] INFO: [00012.161090][10245][10262][klog] INFO: test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [00012.161090][10245][10262][klog] INFO: [00012.161985][10245][10262][klog] INFO: PASSED: /pkgfs/packages/wlantool2-tests/0/test/wlantool2_bin_test passed [00012.292385][10245][10262][klog] INFO: [00012.292428][10245][10262][klog] INFO: ------------------------------------------------ [00012.292431][10245][10262][klog] INFO: RUNNING TEST: /pkgfs/packages/wlancfg-tests/0/test/wlancfg_bin_test [00012.292431][10245][10262][klog] INFO: [00012.328968][10245][10262][klog] INFO: [00012.328991][10245][10262][klog] INFO: running 17 tests [00012.335753][10245][10262][klog] INFO: test client::tests::disconnect_request_when_already_disconnected ... ok [00012.341703][10245][10262][klog] INFO: test client::tests::disconnect_request_when_manually_connecting ... ok [00012.350684][10245][10262][klog] INFO: test client::tests::manual_connect_after_sme_disconnected ... ok [00012.351534][10245][10262][klog] INFO: test client::tests::auto_connect_to_known_ess ... ok [00012.353021][10245][10262][klog] INFO: test client::tests::auto_connect_when_deauth ... ok [00012.355116][10245][10262][klog] INFO: test client::tests::disconnect_when_connected ... ok [00012.368977][10245][10262][klog] INFO: test client::tests::manual_connect_cancels_auto_connect ... ok [00012.371180][10245][10262][klog] INFO: test client::tests::manual_connect_cancels_manual_connect ... ok [00012.374043][10245][10262][klog] INFO: test client::tests::manual_connect_failure_triggers_auto_connect ... ok [00012.376682][2297][2349][klog] INFO: devhost_get_handles(0x7136ccb46610:null) open path='foo', r=-2 [00012.377117][10245][10262][klog] INFO: test known_ess_store::tests::bail_if_path_is_bad ... ok [00012.387021][10245][10262][klog] INFO: test client::tests::manual_connect_while_sme_is_disconnecting ... ok [00012.398807][10245][10262][klog] INFO: test client::tests::scans_only_requested_with_saved_networks ... ok [00012.407869][10245][10262][klog] INFO: test known_ess_store::tests::clear ... ok [00012.410043][10245][10262][klog] INFO: test state_machine::tests::state_machine ... ok [00012.413445][10245][10262][klog] INFO: test client::tests::manual_connect_when_already_connected ... ok [00012.419195][10245][10262][klog] INFO: test known_ess_store::tests::unwrap_or_else_from_bad_file ... ok [00012.439124][10245][10262][klog] INFO: test known_ess_store::tests::store_and_lookup ... ok [00012.439144][10245][10262][klog] INFO: [00012.439147][10245][10262][klog] INFO: test result: ok. 17 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [00012.439234][10245][10262][klog] INFO: [00012.441629][10245][10262][klog] INFO: PASSED: /pkgfs/packages/wlancfg-tests/0/test/wlancfg_bin_test passed [00012.558054][10245][10262][klog] INFO: [00012.558089][10245][10262][klog] INFO: ------------------------------------------------ [00012.558093][10245][10262][klog] INFO: RUNNING TEST: /pkgfs/packages/wlanstack2-tests/0/test/wlanstack2_bin_test [00012.558093][10245][10262][klog] INFO: [00012.601475][2240][2261][klog] INFO: wlantapctl: IOCTL_WLANTAP_CREATE_WLANPHY [00012.607256][2240][2261][klog] INFO: wlantap: creating phy [00012.607602][10245][10262][klog] INFO: [00012.607603][10245][10262][klog] INFO: running 33 tests [00012.607604][10245][10262][klog] INFO: test future_util::tests::empty ... ok [00012.607604][10245][10262][klog] INFO: test future_util::tests::buffer_error ... ok [00012.611518][2240][2261][klog] INFO: wlantap: phy successfully created [00012.611522][2240][2261][klog] INFO: wlantapctl: IOCTL_WLANTAP_CREATE_WLANPHY: success [00012.612152][2240][2261][klog] INFO: wlanphy: event loop started [00012.612176][2240][2261][klog] INFO: wlanphy_bind [00012.612301][10245][10262][klog] INFO: test future_util::tests::group_available_items ... ok [00012.612777][10245][10262][klog] INFO: test future_util::tests::pending ... ok [00012.615659][11428][11445][klog] INFO: wlanstack2::device [I]: new phy #0: /dev/test/wlantapctl/devwatchtap/wlanphy [00012.616042][10245][10262][klog] INFO: test service::tests::create_iface_not_found ... ok [00012.616060][8111][8125][klog] INFO: wlancfg got event: OnPhyAdded { phy_id: 0 } [00012.616067][8111][8125][klog] INFO: wlancfg: phy 0 added [00012.616322][11428][11445][klog] INFO: wlanstack2::service [I]: query_phy(id = 0) [00012.616847][2240][19582][klog] INFO: wlantap phy: received a 'Query' DDK request [00012.616909][2240][19582][klog] INFO: wlantap phy: responded to 'Query' with status ZX_OK [00012.617067][2240][19582][klog] INFO: wlantap phy: received a 'Query' DDK request [00012.617115][2240][19582][klog] INFO: wlantap phy: responded to 'Query' with status ZX_OK [00012.617323][11428][11445][klog] INFO: wlanstack2::service [I]: query_phy(id = 0): received a 'QueryResult' from device [00012.617785][8111][8125][klog] INFO: wlancfg: received a PhyInfo from phy #0: path is /dev/test/wlantapctl/devwatchtap/wlanphy [00012.617813][8111][8125][klog] INFO: using default wlan config entry for phy [00012.617817][8111][8125][klog] INFO: wlancfg: Creating Ap iface for phy 0 [00012.617996][2240][19582][klog] INFO: wlantap phy: received a 'CreateIface' DDK request [00012.617999][2240][19582][klog] INFO: wlantap phy: CreateIface: role not supported [00012.621755][10245][10262][klog] INFO: test service::tests::get_ap_sme_not_found ... ok [00012.621987][10245][10262][klog] INFO: test service::tests::create_iface_success ... ok [00012.622004][2240][19558][klog] INFO: wlantap phy: unbinding device because the channel was closed [00012.622011][2240][19558][klog] INFO: wlantap phy: done unbinding [00012.622415][2240][2261][klog] INFO: wlantap phy: DdkRelease [00012.622443][2240][2261][klog] INFO: wlantap phy: DdkRelease done [00012.622857][11428][11445][klog] INFO: wlanstack2::device [I]: phy removed: #0 [00012.623195][10245][10262][klog] INFO: test device_watch::tests::watch_phys ... ok [00012.623766][8111][8125][klog] INFO: wlancfg got event: OnPhyRemoved { phy_id: 0 } [00012.623794][8111][8125][klog] INFO: wlancfg: phy removed: 0 [00012.626624][10245][10262][klog] INFO: test service::tests::get_client_sme_not_found ... ok [00012.626996][10245][10262][klog] INFO: test service::tests::get_ap_sme_success ... ok [00012.637581][10245][10262][klog] INFO: test service::tests::get_ap_sme_wrong_role ... ok [00012.640105][10245][10262][klog] INFO: test service::tests::get_client_sme_success ... ok [00012.641403][10245][10262][klog] INFO: test service::tests::get_client_sme_wrong_role ... ok [00012.643881][10245][10262][klog] INFO: test service::tests::query_iface_not_found ... ok [00012.644435][10245][10262][klog] INFO: test service::tests::query_phy_not_found ... ok [00012.650952][10245][10262][klog] INFO: test service::tests::list_two_ifaces ... ok [00012.664877][10245][10262][klog] INFO: test service::tests::query_iface_success ... ok [00012.665824][10245][10262][klog] INFO: test service::tests::query_phy_success ... ok [00012.669807][10245][10262][klog] INFO: test service::tests::list_two_phys ... ok [00012.669843][10245][10262][klog] INFO: test station::tests::test_timer ... ok [00012.671728][10245][10262][klog] INFO: test stats_scheduler::tests::canceled_if_server_dropped_after_request ... ok [00012.679179][10245][10262][klog] INFO: test stats_scheduler::tests::canceled_if_server_dropped_before_request ... ok [00012.683048][10245][10262][klog] INFO: test stats_scheduler::tests::schedule ... ok [00012.683050][10245][10262][klog] INFO: test watchable_map::tests::events ... ok [00012.687744][10245][10262][klog] INFO: test watchable_map::tests::get_snapshot ... ok [00012.687840][10245][10262][klog] INFO: test watchable_map::tests::insert_remove_get ... ok [00012.687841][10245][10262][klog] INFO: test watcher_service::tests::add_remove_ifaces ... ok [00012.692774][10245][10262][klog] INFO: test watcher_service::tests::add_remove_phys ... ok [00012.697087][10245][10262][klog] INFO: test watcher_service::tests::reap_watchers ... ok [00012.697900][10245][10262][klog] INFO: test watcher_service::tests::remove_watcher_on_send_error ... ok [00012.697925][10245][10262][klog] INFO: test watcher_service::tests::snapshot_ifaces ... ok [00012.697941][10245][10262][klog] INFO: test watcher_service::tests::snapshot_phys ... ok [00012.698798][10245][10262][klog] INFO: test watcher_service::tests::two_watchers ... ok [00012.698830][10245][10262][klog] INFO: [00012.698831][10245][10262][klog] INFO: test result: ok. 33 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [00012.698831][10245][10262][klog] INFO: [00012.703834][10245][10262][klog] INFO: PASSED: /pkgfs/packages/wlanstack2-tests/0/test/wlanstack2_bin_test passed