Back to build
Raw log | Switch to full mode | Login

[00000.000000][0][0][klog] INFO: zbi: @ 0xffffff8000e97000 (14235952 bytes) [00000.000000][0][0][klog] INFO: UART: FIFO depth 16 [00000.000000][0][0][klog] INFO: PMM: boot reserve add [0x100000, 0x2fffff] [00000.000000][0][0][klog] INFO: PMM: boot reserve add [0xe97000, 0x1c2afff] [00000.000000][0][0][klog] INFO: PMM: boot reserve marking WIRED [0x100000, 0x2fffff] [00000.000000][0][0][klog] INFO: PMM: boot reserve marking WIRED [0xe97000, 0x1c2afff] [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 0xffffffff00114ec4 (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 [0x1c2b000, 0x1c2b080) [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00122274 (elf_build_id) at level 0x4fffe, flags 0x1 [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00122400 (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-b80a3f53755580232785d0c13781f74858c18352-dirty [00000.000000][0][0][klog] INFO: ELF build ID: 9089f64db2d7ec0132fe532c3ecdc9559418af53 [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 0xffffffff001826c4 (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, 0xffffffff0026c000) flags 0x8 name 'kernel_rodata' [00000.000000][0][0][klog] INFO: VM: reserving kernel region [0xffffffff0026c000, 0xffffffff00274000) flags 0x18 name 'kernel_data' [00000.000000][0][0][klog] INFO: VM: reserving kernel region [0xffffffff00274000, 0xffffffff00300000) flags 0x18 name 'kernel_bss' [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00183388 (display_memtype) at level 0x60001, flags 0x1 [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00186818 (acpi_tables) at level 0x60001, flags 0x1 [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff001814c8 (hpet) at level 0x60002, flags 0x1 [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00181888 (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 0xffffffff001a7e6c (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 0xffffffff00185e08 (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: 2299802 ticks/ms [00000.558266][0][0][klog] INFO: timer features: constant_tsc 1 invariant_tsc 1 tsc_deadline 1 [00000.558266][0][0][klog] INFO: Using TSC as wallclock [00000.558268][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00208930 (percpu_heap_init) at level 0x60003, flags 0x1 [00000.558271][0][0][klog] INFO: initializing kernel [00000.558272][0][0][klog] INFO: initializing mp [00000.558274][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00115118 (global_prng_thread_safe) at level 0x7ffff, flags 0x1 [00000.558279][0][0][klog] INFO: creating bootstrap completion thread [00000.558401][0][0][klog] INFO: top of bootstrap2() [00000.558402][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00131734 (libobject) at level 0x80000, flags 0x1 [00000.558504][0][0][klog] INFO: OOM: started thread [00000.558506][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff0020589c (dpc) at level 0x80000, flags 0x1 [00000.558551][0][0][klog] INFO: Processor Model Info: type 0 family 0x6 model 0xf stepping 0 [00000.558552][0][0][klog] INFO: display_family 0x6 display_model 0x3f [00000.558553][0][0][klog] INFO: Vendor: Intel [00000.558553][0][0][klog] INFO: Microarch: Haswell [00000.558554][0][0][klog] INFO: F/M/S: 6/3f/0 [00000.558554][0][0][klog] INFO: patch_level: 0 [00000.558555][0][0][klog] INFO: Brand: Intel(R) Xeon(R) CPU @ 2.30GHz [00000.558557][0][0][klog] INFO: Features: fpu sse sse2 sse3 ssse3 sse4.1 sse4.2 mmx avx avx2 fxsr pcid xsave aesni clflush [00000.558559][0][0][klog] INFO: fsgsbase tsc_adj smep erms rdrand syscall nx rdtscp tsc_deadline x2apic hypervisor [00000.558559][0][0][klog] INFO: Properties: meltdown l1tf pcid_good [00000.558576][0][0][klog] INFO: initializing platform [00000.558577][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff001a157c (x86_perfmon) at level 0x90000, flags 0x1 [00000.558599][0][0][klog] INFO: x86_perfmon_init_once:454: perfmon: unexpected programmable counter width 0 in cpuid.0AH [00000.559184][0][0][klog] INFO: UART: started IRQ driven RX [00000.559184][0][0][klog] INFO: UART: started IRQ driven TX [00000.559203][0][0][klog] INFO: cpu topology: [00000.559204][0][0][klog] INFO: 0: apic id 0x0 BSP [00000.559204][0][0][klog] INFO: 1: apic id 0x1 [00000.559205][0][0][klog] INFO: 2: apic id 0x2 [00000.559205][0][0][klog] INFO: 3: apic id 0x3 [00000.559206][0][0][klog] INFO: Found 4 cpus [00000.559737][0][0][klog] INFO: booting apic ids: 0x1 0x2 0x3 [00000.571994][0][0][klog] INFO: entering scheduler on cpu 1 [00000.572153][0][0][klog] INFO: entering scheduler on cpu 2 [00000.572522][0][0][klog] INFO: entering scheduler on cpu 3 [00000.574554][0][0][klog] INFO: smbios: manufacturer="QEMU" product="Standard PC (Q35 + ICH9, 2009)" [00000.574555][0][0][klog] INFO: initializing target [00000.574557][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff001850b8 (x86_pcie_init) at level 0xa0000, flags 0x1 [00000.574828][0][0][klog] INFO: moving to last init level [00000.574830][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff001214cc (debuglog) at level 0xb0000, flags 0x1 [00000.574925][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00174d40 (kcounters) at level 0xb0000, flags 0x1 [00000.574930][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00102aec (kernel_shell) at level 0xc0000, flags 0x1 [00000.574938][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00202ad4 (ktrace) at level 0xc0000, flags 0x1 [00000.964427][0][0][klog] INFO: ktrace: buffer at 0xffffff92618f8000 (33554432 bytes) [00000.964507][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff002184f8 (userboot) at level 0xc0000, flags 0x1 [00000.964509][0][0][klog] INFO: userboot: ramdisk 0xd94000 @ 0xffffff8000e97000 [00000.981214][0][0][klog] INFO: userboot: userboot rodata 0 @ [0x77ef8744c000,0x77ef8744f000) [00000.981243][0][0][klog] INFO: userboot: userboot code 0x3000 @ [0x77ef8744f000,0x77ef8745a000) [00000.981245][0][0][klog] INFO: userboot: vdso/full rodata 0 @ [0x77ef8745a000,0x77ef87461000) [00000.981247][0][0][klog] INFO: userboot: vdso/full code 0x7000 @ [0x77ef87461000,0x77ef87462000) [00000.981296][0][0][klog] INFO: userboot: entry point @ 0x77ef8744fc90 [00000.981969][1034][1041][klog] INFO: userboot: option "kernel.serial=legacy" [00000.982003][1034][1041][klog] INFO: userboot: option "netsvc.interface=/dev/sys/pci/00:1f.6/e1000/ethernet" [00000.982004][1034][1041][klog] INFO: userboot: option "/b/s/w/ir/multiboot-created_by_recipe.bin=" [00000.982005][1034][1041][klog] INFO: userboot: option "zircon.autorun.system=/boot/bin/sh+/boot/infra/runcmds" [00000.982005][1034][1041][klog] INFO: userboot: option "kernel.halt-on-panic=true" [00000.982006][1034][1041][klog] INFO: userboot: option "devmgr.suspend-timeout-debug=true" [00000.982006][1034][1041][klog] INFO: userboot: option "TERM=dumb" [00000.982007][1034][1041][klog] INFO: userboot: option "kernel.serial=legacy" [00000.982008][1034][1041][klog] INFO: userboot: option "multiboot.boot_loader_name=qemu" [00001.044246][1034][1041][klog] INFO: userboot: searching bootfs for 'bin/bootsvc' [00001.044305][1034][1041][klog] INFO: userboot: 'bin/bootsvc' has PT_INTERP "lib/ld.so.1" [00001.044305][1034][1041][klog] INFO: userboot: searching bootfs for 'lib/ld.so.1' [00001.044384][1034][1041][klog] INFO: userboot: userboot: loaded lib/ld.so.1 at 0x73bfabed2000, entry point 0x73bfabef03d0 [00001.044427][1034][1041][klog] INFO: userboot: userboot: loaded vDSO at 0x47874fc89000, entry point 0x47874fc908fa [00001.044485][1034][1041][klog] INFO: userboot: process bin/bootsvc started. [00001.044522][1034][1041][klog] INFO: userboot: waiting for loader-service requests... [00001.081919][1034][1041][klog] INFO: userboot: searching bootfs for 'lib/libasync-default.so' [00001.082255][1034][1041][klog] INFO: userboot: searching bootfs for 'lib/libfdio.so' [00001.082637][1034][1041][klog] INFO: userboot: searching bootfs for 'lib/libtrace-engine.so' [00001.082932][1034][1041][klog] INFO: userboot: searching bootfs for 'lib/liblaunchpad.so' [00001.101004][1048][1051][klog] INFO: {{{reset}}} [00001.101049][1048][1051][klog] INFO: {{{module:0:<application>:elf:04b4a34a2691363e32b2d7bb7f817640b16ec624}}} [00001.101050][1048][1051][klog] INFO: {{{mmap:0x239f72c53000:0x3a000:load:0:rx:0}}} [00001.101052][1048][1051][klog] INFO: {{{mmap:0x239f72c8d000:0x4000:load:0:rw:0x3a000}}} [00001.101053][1048][1051][klog] INFO: dso: id=04b4a34a2691363e32b2d7bb7f817640b16ec624 base=0x0000239f72c53000 name=<application> [00001.101053][1048][1051][klog] INFO: {{{module:0x2:<vDSO>:elf:71712e0a455898a51e34d40a4013786ab1fa5335}}} [00001.101054][1048][1051][klog] INFO: {{{mmap:0x47874fc89000:0x7000:load:0x2:r:0}}} [00001.101055][1048][1051][klog] INFO: {{{mmap:0x47874fc90000:0x1000:load:0x2:rx:0x7000}}} [00001.101055][1048][1051][klog] INFO: dso: id=71712e0a455898a51e34d40a4013786ab1fa5335 base=0x000047874fc89000 name=<vDSO> [00001.101056][1048][1051][klog] INFO: {{{module:0x3:libasync-default.so:elf:8c511b4202cf569c8fdde14ca97222102ba3a9e0}}} [00001.101056][1048][1051][klog] INFO: {{{mmap:0x728943e7a000:0x1000:load:0x3:rx:0}}} [00001.101057][1048][1051][klog] INFO: {{{mmap:0x728943e7b000:0x1000:load:0x3:rw:0x1000}}} [00001.101057][1048][1051][klog] INFO: dso: id=8c511b4202cf569c8fdde14ca97222102ba3a9e0 base=0x0000728943e7a000 name=libasync-default.so [00001.101058][1048][1051][klog] INFO: {{{module:0x4:libfdio.so:elf:57a73a8b04ea66e0b20945ca56238e6bc8a0b0bc}}} [00001.101059][1048][1051][klog] INFO: {{{mmap:0x113baaad9000:0x32000:load:0x4:rx:0}}} [00001.101059][1048][1051][klog] INFO: {{{mmap:0x113baab0c000:0x4000:load:0x4:rw:0x33000}}} [00001.101060][1048][1051][klog] INFO: dso: id=57a73a8b04ea66e0b20945ca56238e6bc8a0b0bc base=0x0000113baaad9000 name=libfdio.so [00001.101060][1048][1051][klog] INFO: {{{module:0x5:libtrace-engine.so:elf:95bc229c3cf2ab8a51f12c33a311105ff8976120}}} [00001.101061][1048][1051][klog] INFO: {{{mmap:0x7216218df000:0x9000:load:0x5:rx:0}}} [00001.101061][1048][1051][klog] INFO: {{{mmap:0x7216218e8000:0x2000:load:0x5:rw:0x9000}}} [00001.101062][1048][1051][klog] INFO: dso: id=95bc229c3cf2ab8a51f12c33a311105ff8976120 base=0x00007216218df000 name=libtrace-engine.so [00001.101062][1048][1051][klog] INFO: {{{module:0x6:liblaunchpad.so:elf:5b0854856213eae6bab93c982f39551f8f9591c5}}} [00001.101063][1048][1051][klog] INFO: {{{mmap:0x12fc9a0f7000:0x7000:load:0x6:rx:0}}} [00001.101063][1048][1051][klog] INFO: {{{mmap:0x12fc9a0fe000:0x2000:load:0x6:rw:0x7000}}} [00001.101064][1048][1051][klog] INFO: dso: id=5b0854856213eae6bab93c982f39551f8f9591c5 base=0x000012fc9a0f7000 name=liblaunchpad.so [00001.101064][1048][1051][klog] INFO: {{{module:0x1:libc.so:elf:5d417db61b3a48a58a8fa1ffbfc2e71b830e0857}}} [00001.101065][1048][1051][klog] INFO: {{{mmap:0x73bfabed2000:0xa6000:load:0x1:rx:0}}} [00001.101066][1048][1051][klog] INFO: {{{mmap:0x73bfabf78000:0x5000:load:0x1:rw:0xa6000}}} [00001.101066][1048][1051][klog] INFO: dso: id=5d417db61b3a48a58a8fa1ffbfc2e71b830e0857 base=0x000073bfabed2000 name=libc.so [00001.101340][1048][1051][klog] INFO: bootsvc: Starting... [00001.101480][1048][1051][klog] INFO: bootsvc: Creating bootfs service... [00001.101811][1034][1041][klog] INFO: userboot: loader-service channel peer closed [00001.101860][1034][1041][klog] INFO: userboot: finished! [00001.256707][1048][1051][klog] INFO: bootsvc: Retrieving boot image... [00001.256892][1048][1051][klog] INFO: bootsvc: Decommitted BOOTDATA VMO from 0 to 0xd93000 [00001.256903][1048][1051][klog] INFO: bootsvc: Loading boot arguments... [00001.257010][1048][1051][klog] INFO: bootsvc: Creating svcfs service... [00001.257080][1048][1051][klog] INFO: bootsvc: Loading kernel VMOs... [00001.257101][1048][1051][klog] INFO: bootsvc: Creating loader service... [00001.257124][1048][1051][klog] INFO: bootsvc: Launching next process... [00001.257926][1048][1106][klog] INFO: bootsvc: Launched bin/devcoordinator [00001.288515][1113][1128][klog] INFO: devcoordinator: launch /boot/bin/svchost (svchost) OK [00001.293378][1113][1128][klog] INFO: devcoordinator: launch /boot/bin/fshost (fshost) OK [00001.309727][1113][1349][klog] INFO: devcoordinator: launch /boot/bin/netsvc (netsvc) OK [00001.315704][1113][1323][klog] INFO: devcoordinator: launch /boot/bin/pwrbtn-monitor (pwrbtn-monitor) OK [00001.317287][1480][1498][klog] INFO: netsvc: looking for interface /dev/sys/pci/00:1f.6/e1000/ethernet [00001.324771][1113][1349][klog] INFO: devcoordinator: launch /boot/bin/virtual-console (virtual-console) OK [00001.364699][1113][1128][klog] INFO: devcoordinator: full system required, ignoring fallback drivers until /system is loaded [00001.373111][1113][1128][klog] INFO: devcoordinator: launch devhost 'devhost:sys': pid=2186 [00001.382253][2186][2200][klog] INFO: devhost: trace provider registry begun [00001.383193][1113][1128][klog] INFO: devcoordinator: launch devhost 'devhost:test': pid=2238 [00001.395487][1113][1128][klog] INFO: devcoordinator: launch devhost 'devhost:root': pid=2321 [00001.397171][2238][2268][klog] INFO: devhost: trace provider registry begun [00001.406882][1113][1128][klog] INFO: devcoordinator: launch devhost 'devhost:misc': pid=2406 [00001.418511][2321][2342][klog] INFO: devhost: trace provider registry begun [00001.421133][2406][2429][klog] INFO: devhost: trace provider registry begun [00001.441342][1113][1328][klog] INFO: devcoordinator: launch /boot/bin/sh (sh:console) OK [00001.455404][2186][2200][klog] INFO: acpi-bus: not using IOMMU [00001.462405][2186][2200][klog] INFO: GetHwProperties: No PM support [00001.462464][2186][2200][klog] INFO: IntelPT: No PT support [00001.462479][2186][2200][klog] INFO: devhost[sys/cpu-trace] bind driver '/boot/driver/cpu-trace.so' failed: -2 [00001.462567][1113][1128][klog] INFO: devcoordinator: rpc: bind-driver 'cpu-trace' status -2 [00001.463921][2186][2200][klog] INFO: [sysmem_init 28] async_get_default_dispatcher(): 0x4290da3098d0 [00001.463956][2186][2200][klog] INFO: [sysmem_bind 40] sysmem_bind() [00001.466621][2186][2798][klog] INFO: acpi-pwrbtn: initialized [00001.474846][1583][1721][klog] INFO: vc: new input device /dev/class/input/000 [00001.581477][2186][2798][klog] INFO: acpi: published device pci(0x42901a322dd0), parent=sys(0x42915a314e10), handle=0x428fda30a3d0 [00001.581678][2186][2798][klog] INFO: acpi: failed to get PCI base bus number for device 'PCI0' (status 4294967295) [00001.598910][2186][2798][klog] INFO: acpi: published device rtc(0x42901a311730), parent=acpi(0x42915a314c10), handle=0x428fda30a690 [00001.601318][2186][2798][klog] INFO: acpi: published device i8042(0x42901a318cf0), parent=acpi(0x42915a314c10), handle=0x428fda30a0d0 [00001.619773][1113][1128][klog] INFO: devcoordinator: launch devhost 'devhost:pci#1:1234:1111': pid=2903 [00001.631770][1113][1128][klog] INFO: devcoordinator: launch devhost 'devhost:pci#2:1af4:1001': pid=2965 [00001.632639][2903][2917][klog] INFO: devhost: trace provider registry begun [00001.647163][1113][1128][klog] INFO: devcoordinator: launch devhost 'devhost:pci#3:1af4:1000': pid=3064 [00001.656189][2965][2991][klog] INFO: devhost: trace provider registry begun [00001.662291][1113][1128][klog] INFO: devcoordinator: launch devhost 'devhost:pci#4:1af4:1001': pid=3163 [00001.662419][3064][3089][klog] INFO: devhost: trace provider registry begun [00001.667431][3163][3193][klog] INFO: devhost: trace provider registry begun [00001.679458][1113][1128][klog] INFO: devcoordinator: launch devhost 'devhost:pci#6:8086:2922': pid=3304 [00001.688141][2186][2200][klog] INFO: devhost: WARNING: driver '/boot/driver/pc-ps2.so' did not add device in bind() [00001.696992][2186][3365][klog] INFO: i8042 device 0xab 0x41 [00001.702958][3304][3318][klog] INFO: devhost: trace provider registry begun [00001.713891][2903][2917][klog] INFO: bochs_vbe: initialized display, 1024 x 768 (stride=1024 format=00020001) [00001.720067][1583][1721][klog] INFO: vc: new display device /dev/class/display-controller/000 [00001.720920][3304][3318][klog] INFO: ahci: using MSI interrupt [00001.721711][2903][2917][klog] INFO: GetSysmemConnection failed (continuing) - status: -2 [00001.723138][1583][1721][klog] INFO: vc: new input device /dev/class/input/001 [00001.728016][1583][1721][klog] INFO: vc: Successfully attached to display 1 [00001.789605][1297][1311][klog] INFO: fshost: /dev/class/block/000: FVM? [00001.798177][1480][1498][klog] INFO: netifc: ? /dev/class/ethernet/000 [00001.817058][1297][1311][klog] INFO: devcoordinator: launch /boot/bin/blobfs (blobfs:/blob) OK [00001.864339][2186][3365][klog] INFO: i8042 device 0x00 [00001.871308][2186][2200][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.885426][1583][1721][klog] INFO: vc: new input device /dev/class/input/002 [00002.031108][1297][1311][klog] INFO: fshost: starting 'bin/pkgsvr' 'edcf278bd2b336bed7410014dbd85caed4bcae0f25d3da72acdf5b53707cdcd3'... [00002.050288][1297][1311][klog] INFO: devcoordinator: launch bin/pkgsvr (pkgfs) OK [00002.087083][4037][4251][klog] INFO: pkgsvr: system: will be served from edcf278bd2b336bed7410014dbd85caed4bcae0f25d3da72acdf5b53707cdcd3 [00002.087283][4037][4251][klog] INFO: pkgsvr: pkgfs serving index /data/pkgfs_index from blobfs /blob [00002.103782][1297][1311][klog] INFO: fshost: mounting minfs [00002.109687][1297][1311][klog] INFO: devcoordinator: launch /boot/bin/minfs (minfs:/data) OK [00002.131526][1113][4300][klog] INFO: devcoordinator: adding system driver 'brcmfmac' '/system/driver/brcmfmac.so' [00002.185866][1113][1727][klog] INFO: devcoordinator: launch /system/bin/appmgr (appmgr) OK [00002.185914][1113][1727][klog] INFO: autorun: starting '/boot/bin/sh' '/boot/infra/runcmds'... [00002.210458][1113][4300][klog] INFO: devcoordinator: adding system driver 'qmi_usb' '/system/driver/qmi-transport.so' [00002.223901][1113][1727][klog] INFO: devcoordinator: launch /boot/bin/sh (autorun:system) OK [00002.236029][1113][4300][klog] INFO: devcoordinator: adding system driver 'iwlwifi_pci' '/system/driver/iwlwifi.so' [00002.269340][1113][4300][klog] INFO: devcoordinator: adding system driver 'rtl88xx' '/system/driver/rtl88xx.so' [00005.184770][1113][4300][klog] INFO: devcoordinator: adding system driver 'wlan' '/system/driver/wlan.so' [00005.210929][1113][4300][klog] INFO: devcoordinator: adding system driver 'wlan' '/system/driver/wlanif.so' [00005.238068][1113][4300][klog] INFO: devcoordinator: adding system driver 'wlantapctl' '/system/driver/wlantap.so' [00005.324651][1113][4300][klog] INFO: devcoordinator: adding system driver 'ralink' '/system/driver/ralink.so' [00005.353360][1113][4300][klog] INFO: devcoordinator: adding system driver 'wlan' '/system/driver/wlanphy.so' [00005.398941][1113][4300][klog] INFO: devcoordinator: adding system driver 'bt_hci_atheros' '/system/driver/bt-hci-atheros.so' [00005.445335][1113][4300][klog] INFO: devcoordinator: adding system driver 'bt_hci_intel' '/system/driver/bt-hci-intel.so' [00005.473713][1113][4300][klog] INFO: devcoordinator: adding system driver 'gpu' '/system/driver/libmsd_intel.so' [00005.503140][4500][4520][klog] INFO: [INFO:namespace_builder.cc(93)] config-data for sysmgr [00005.530294][1113][4300][klog] INFO: devcoordinator: adding system driver 'virtual_camera' '/system/driver/virtual_camera_driver.so' [00005.548122][1113][4300][klog] INFO: devcoordinator: adding system driver 'ath10k_pci' '/system/driver/ath10k.so' [00005.562460][1113][4300][klog] INFO: devcoordinator: adding system driver 'bt_hog' '/system/driver/bt-hog.so' [00005.586133][1113][4300][klog] INFO: devcoordinator: adding system driver 'usb_video' '/system/driver/usb_video.so' [00005.608765][1113][4300][klog] INFO: devcoordinator: adding system driver 'bt_hci_passthrough' '/system/driver/bt-hci-passthrough.so' [00005.675033][1113][4300][klog] INFO: devcoordinator: adding system driver 'bt_host' '/system/driver/bt-host.so' [00005.719911][1113][4300][klog] INFO: devcoordinator: adding system driver 'qmi_fake' '/system/driver/qmi-fake.so' [00005.748413][1113][4300][klog] INFO: devcoordinator: adding system driver 'virtual_audio' '/system/driver/virtual_audio_driver.so' [00005.781027][1113][4300][klog] INFO: devcoordinator: adding system driver 'bt_hci_fake' '/system/driver/bt-hci-fake.so' [00005.790038][1113][1128][klog] INFO: devcoordinator: driver 'bt_hci_fake' added [00005.790969][1113][1128][klog] INFO: devcoordinator: driver 'qmi_fake' added [00005.791029][1113][1128][klog] INFO: devcoordinator: driver 'bt_host' added [00005.791031][1113][1128][klog] INFO: devcoordinator: driver 'usb_video' added [00005.791034][1113][1128][klog] INFO: devcoordinator: driver 'bt_hog' added [00005.791062][1113][1128][klog] INFO: devcoordinator: driver 'ath10k_pci' added [00005.791160][1113][1128][klog] INFO: devcoordinator: driver 'gpu' added [00005.791163][1113][1128][klog] INFO: devcoordinator: driver 'bt_hci_intel' added [00005.791166][1113][1128][klog] INFO: devcoordinator: driver 'bt_hci_atheros' added [00005.791168][1113][1128][klog] INFO: devcoordinator: driver 'wlan' added [00005.791170][1113][1128][klog] INFO: devcoordinator: driver 'ralink' added [00005.791178][1113][1128][klog] INFO: devcoordinator: driver 'wlan' added [00005.791208][1113][1128][klog] INFO: devcoordinator: driver 'wlan' added [00005.791214][1113][1128][klog] INFO: devcoordinator: driver 'rtl88xx' added [00005.791217][1113][1128][klog] INFO: devcoordinator: driver 'iwlwifi_pci' added [00005.791219][1113][1128][klog] INFO: devcoordinator: driver 'qmi_usb' added [00005.791221][1113][1128][klog] INFO: devcoordinator: driver 'brcmfmac' added [00005.791251][1113][1128][klog] INFO: devcoordinator: driver 'bt_hci_passthrough' added [00005.791254][1113][1128][klog] INFO: devcoordinator: fallback driver 'usb_composite' is available [00005.791255][1113][1128][klog] INFO: devcoordinator: driver 'usb_composite' added [00005.791257][1113][1128][klog] INFO: devcoordinator: fallback driver 'intel_disp' is available [00005.791258][1113][1128][klog] INFO: devcoordinator: driver 'intel_disp' added [00006.263946][4500][4520][klog] INFO: [INFO:namespace_builder.cc(93)] config-data for amber [00007.417399][4500][4520][klog] INFO: [INFO:namespace_builder.cc(93)] config-data for pkg_resolver [00006.456023][131696379][0][netstack] INFO: netstack.go(674): NIC lo added [sniff = false] [00006.483621][131696379][0][netstack] WARNING: main.go(167): OnInterfacesChanged failed: ErrBadHandle: zx.Channel.Write [00007.460243][6878][6890][pkg_resolver] INFO: starting package resolver [00007.461690][6878][6890][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/main.rs(116): error loading static repo config: file /config/data/pkg_resolver/repositories failed to parse: No such file or directory (os error 2) [00009.747294][7289][7301][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 [00009.747459][7289][7301][klog] INFO: kfill_days=2, start_event_aggregator_worker=1. [00009.821511][7555][7570][pkg_cache] INFO: starting package cache service [00009.826948][7555][7642][pkg_cache] INFO: spawning package cache service [00009.855660][1719426487][0][amber] INFO: error opening /misc/ota/current_channel.json: open /misc/ota/current_channel.json: ErrNotFound: io.directory.Open [00009.860060][1719426487][0][amber] INFO: monitoring for updates [00009.863242][1719426487][0][amber] INFO: system update monitor exited [00009.866650][1719426487][0][amber] INFO: control_server: could not get update for netcfg/0: merkle not found for package netcfg/0 [00009.868633][6878][6975][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/netcfg/0 was not found: merkle not found for package netcfg/0 [00009.868723][6878][6975][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) [00009.870099][1719426487][0][amber] INFO: control_server: could not get update for mdns/0: merkle not found for package mdns/0 [00009.870509][6878][6975][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/mdns/0 was not found: merkle not found for package mdns/0 [00009.870561][6878][6975][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) [00009.871220][1719426487][0][amber] INFO: control_server: could not get update for wlancfg/0: merkle not found for package wlancfg/0 [00009.871799][6878][6975][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/wlancfg/0 was not found: merkle not found for package wlancfg/0 [00009.871843][6878][6975][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) [00009.873390][1719426487][0][amber] INFO: control_server: could not get update for cobalt_system_metrics/0: merkle not found for package cobalt_system_metrics/0 [00009.875285][6878][6975][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/cobalt_system_metrics/0 was not found: merkle not found for package cobalt_system_metrics/0 [00009.875355][6878][6975][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) [00009.876534][1719426487][0][amber] INFO: control_server: could not get update for network_time/0: merkle not found for package network_time/0 [00009.877036][6878][6980][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/network_time/0 was not found: merkle not found for package network_time/0 [00009.877258][6878][6980][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) [00009.878108][1719426487][0][amber] INFO: control_server: could not get update for sshd-host/0: merkle not found for package sshd-host/0 [00009.878616][6878][6975][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/sshd-host/0 was not found: merkle not found for package sshd-host/0 [00009.878794][6878][6975][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) [00009.880701][1719426487][0][amber] INFO: control_server: could not get update for dhcpd/0: merkle not found for package dhcpd/0 [00009.882687][6878][6980][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/dhcpd/0 was not found: merkle not found for package dhcpd/0 [00009.883491][6878][6980][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) [00009.884626][1719426487][0][amber] INFO: control_server: could not get update for kernel_crash_checker/0: merkle not found for package kernel_crash_checker/0 [00009.885211][6878][6975][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/kernel_crash_checker/0 was not found: merkle not found for package kernel_crash_checker/0 [00009.885268][6878][6975][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) [00009.886433][1719426487][0][amber] INFO: control_server: could not get update for memory_monitor/0: merkle not found for package memory_monitor/0 [00009.887106][6878][6975][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/memory_monitor/0 was not found: merkle not found for package memory_monitor/0 [00009.887179][6878][6975][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) [00009.887933][1719426487][0][amber] INFO: control_server: could not get update for thermd/0: merkle not found for package thermd/0 [00009.888526][6878][6980][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/thermd/0 was not found: merkle not found for package thermd/0 [00009.888580][6878][6980][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) [00009.889615][1719426487][0][amber] INFO: control_server: could not get update for log_listener/0: merkle not found for package log_listener/0 [00009.890082][6878][6980][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/log_listener/0 was not found: merkle not found for package log_listener/0 [00009.890117][6878][6980][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) [00009.890853][1719426487][0][amber] INFO: control_server: could not get update for mkdir/0: merkle not found for package mkdir/0 [00009.891043][6878][6975][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/mkdir/0 was not found: merkle not found for package mkdir/0 [00009.891131][6878][6975][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) [00009.891905][1719426487][0][amber] INFO: control_server: could not get update for crashpad_agent/0: merkle not found for package crashpad_agent/0 [00009.892075][6878][6980][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/crashpad_agent/0 was not found: merkle not found for package crashpad_agent/0 [00009.892137][6878][6980][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) [00009.892977][1719426487][0][amber] INFO: control_server: could not get update for trace_manager/0: merkle not found for package trace_manager/0 [00009.893363][6878][6975][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/trace_manager/0 was not found: merkle not found for package trace_manager/0 [00009.893409][6878][6975][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) [00009.894284][1719426487][0][amber] INFO: control_server: could not get update for device_settings_manager/0: merkle not found for package device_settings_manager/0 [00009.894605][6878][6980][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/device_settings_manager/0 was not found: merkle not found for package device_settings_manager/0 [00009.894920][6878][6980][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) [00010.107733][4500][4520][klog] INFO: [INFO:namespace_builder.cc(93)] config-data for mdns [00010.253401][4500][4520][klog] INFO: [INFO:namespace_builder.cc(93)] config-data for wlancfg [00010.046021][7961][7973][netcfg] INFO: Started [00010.503224][8835][8850][network_time] INFO: main.cc(29): Opening client config from /pkg/data/roughtime-servers.json [00010.503233][8835][8850][network_time] INFO: main.cc(33): Connecting to RTC device at /dev/class/rtc/000 [00010.503238][8835][8850][network_time] INFO: timezone.cc(28): started [00010.957525][1719426487][0][amber] INFO: control_server: could not get update for wlanstack2/0: merkle not found for package wlanstack2/0 [00010.962425][6878][6975][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/wlanstack2/0 was not found: merkle not found for package wlanstack2/0 [00010.962571][6878][6975][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) [00010.759584][9508][9520][crash] INFO: main.cc(57): no kernel crash log found [00011.033694][4500][4520][klog] INFO: [INFO:namespace_builder.cc(93)] config-data for crashpad_agent [00011.138314][10574][10588][klog] INFO: [00011.138362][10574][10588][klog] INFO: ------------------------------------------------ [00011.138370][10574][10588][klog] INFO: RUNNING TEST: /pkgfs/packages/ril-qmi-tests/0/test/ril-qmi-tests [00011.138371][10574][10588][klog] INFO: [00011.301200][10574][10588][klog] INFO: [00011.301251][10574][10588][klog] INFO: running 2 tests [00011.312727][10574][10588][klog] INFO: test client::tests::no_client ... ok [00011.312972][10574][10588][klog] INFO: test client::tests::request_id ... ok [00011.312973][10574][10588][klog] INFO: [00011.312974][10574][10588][klog] INFO: test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [00011.312974][10574][10588][klog] INFO: [00011.363974][10574][10588][klog] INFO: PASSED: /pkgfs/packages/ril-qmi-tests/0/test/ril-qmi-tests passed [00011.400577][10889][10932][klog] INFO: [INFO:main.cc(35)] Trace Manager starting with config: /pkg/data/tracing.config [00011.429155][131696379][0][netstack] WARNING: client.go(115): ErrNotSupported: ConfigMulticastSetPromiscuousMode [00011.432021][131696379][0][netstack] VLOG(1): socket_server.go:694: bad local address [00011.430242][8632][8645][klog] INFO: [INFO:system_metrics_main.cc(23)] Cobalt SystemMetricsDaemon: System metrics daemon created. [00011.443688][9371][9383][klog] INFO: Error: Os { code: 99, kind: AddrNotAvailable, message: "Address not available" } [00011.443751][9371][9383][klog] INFO: [00011.443942][9371][9383][klog] INFO: unable to bind socket [00011.458241][131696379][0][netstack] INFO: netstack.go(674): NIC ethp0003 added [sniff = false] [00011.459142][131696379][0][netstack] INFO: netstack.go(701): NIC ethp0003: link-local IPv6: fe80::5054:ff:fe12:3456 [00011.490867][131696379][0][netstack] INFO: netstack.go(451): NIC ethp0003: starting [00011.510269][10574][10588][klog] INFO: [00011.510368][10574][10588][klog] INFO: ------------------------------------------------ [00011.510374][10574][10588][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan_tests/0/test/wlan_ap_mlme_unittest [00011.510956][131696379][0][netstack] INFO: netstack.go(369): NIC ethp0003: DHCP acquired IP 10.0.2.15 for 24h0m0s [00011.511074][131696379][0][netstack] INFO: netstack.go(370): NIC ethp0003: Adding DNS servers: [10.0.2.3] [00011.511111][131696379][0][netstack] INFO: netstack.go(380): 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 [00011.510374][10574][10588][klog] INFO: [00011.538595][131696379][0][netstack] WARNING: netstack.go(536): getNodeName: device settings error: key not set [00011.543199][131696379][0][netstack] WARNING: netstack.go(536): getNodeName: device settings error: key not set [00011.620238][8835][8850][network_time] INFO: timezone.cc(107): time set to: 2019-04-20T03:36:13Z [00011.762394][11417][11609][device_settings] INFO: setting string key: "DeviceName", val: "swarm-donut-petri-acre" [00011.769708][131696379][0][netstack, listen] INFO: socket_server.go(708): 0xe4fe41b06e0: local={NIC:0 Addr: Port:22} [00011.789060][1719426487][0][amber] INFO: control_server: could not get update for cpuperf_provider/0: merkle not found for package cpuperf_provider/0 [00011.799660][6878][6975][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/cpuperf_provider/0 was not found: merkle not found for package cpuperf_provider/0 [00011.801182][6878][6975][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) [00011.804988][1719426487][0][amber] INFO: control_server: could not get update for ktrace_provider/0: merkle not found for package ktrace_provider/0 [00011.810790][6878][6980][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/ktrace_provider/0 was not found: merkle not found for package ktrace_provider/0 [00011.815482][6878][6980][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) [00011.842122][11753][11804][klog] INFO: wlanstack2 [I]: Starting [00011.878367][10574][10588][klog] INFO: [==========] Running 32 tests from 3 test suites. [00011.878394][10574][10588][klog] INFO: [----------] Global test environment set-up. [00011.878395][10574][10588][klog] INFO: [----------] 29 tests from ApInfraBssTest [00011.878396][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.StartAp [00011.878778][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.StartAp (0 ms) [00011.878780][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.Authenticate_Success [00011.879486][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.Authenticate_Success (1 ms) [00011.879487][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.Authenticate_SmeRefuses [00011.879625][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.Authenticate_SmeRefuses (0 ms) [00011.919873][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.Authenticate_Timeout [00011.924210][10574][10588][klog] INFO: wlan: [W] [client] [94:3c:49:49:9f:2d] timed out authenticating [00011.924259][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.Authenticate_Timeout (0 ms) [00011.924285][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.ReauthenticateWhileAuthenticated [00011.924286][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.ReauthenticateWhileAuthenticated (1 ms) [00011.924287][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.DeauthenticateWhileAuthenticated [00011.924288][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.DeauthenticateWhileAuthenticated (0 ms) [00011.924288][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.Associate_Success [00011.924289][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.Associate_Success (0 ms) [00011.924289][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.Associate_AssociationContext [00011.983195][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.Associate_AssociationContext (15 ms) [00011.983219][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.Associate_MultipleClients [00011.983225][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.Associate_MultipleClients (36 ms) [00011.983242][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.Associate_SmeRefuses [00011.983245][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.Associate_SmeRefuses (0 ms) [00011.983247][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.Associate_Timeout [00011.983371][10574][10588][klog] INFO: wlan: [W] [client] [94:3c:49:49:9f:2d] timed out associating [00011.983397][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.Associate_Timeout (0 ms) [00011.983398][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.Associate_EmptySsid [00011.983398][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.Associate_EmptySsid (0 ms) [00011.983399][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.Associate_EmptyRsn [00011.983500][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.Associate_EmptyRsn (0 ms) [00011.983501][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.ReauthenticateWhileAssociated [00011.983501][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.ReauthenticateWhileAssociated (0 ms) [00011.983502][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.ReassociationFlowWhileAssociated [00011.983503][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.ReassociationFlowWhileAssociated (1 ms) [00011.983503][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.DeauthenticateWhileAssociated [00011.983504][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.DeauthenticateWhileAssociated (0 ms) [00011.983505][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.Disassociate [00011.983506][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.Disassociate (0 ms) [00012.077944][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.Exchange_Eapol_Frames [00012.078024][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.Exchange_Eapol_Frames (0 ms) [00012.078024][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.SendFrameAfterAssociation [00012.078025][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.SendFrameAfterAssociation (1 ms) [00012.078025][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.UnprotectedApReceiveFramesAfterAssociation [00012.078026][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.UnprotectedApReceiveFramesAfterAssociation (0 ms) [00012.078026][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.MlmeDeauthReqWhileAssociated [00012.078027][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.MlmeDeauthReqWhileAssociated (0 ms) [00012.078027][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.SetKeys [00012.078028][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.SetKeys (0 ms) [00012.078028][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.SetKeys_IgnoredForUnprotectedAp [00012.078029][10574][10588][klog] INFO: wlan: [W] [infra-bss] ignoring SetKeysRequest since AP is unprotected [00012.078029][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.SetKeys_IgnoredForUnprotectedAp (0 ms) [00012.078030][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.PowerSaving_IgnoredBeforeControlledPortOpens [00012.078031][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.PowerSaving_IgnoredBeforeControlledPortOpens (1 ms) [00012.078031][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.PowerSaving_AfterControlledPortOpens [00012.078037][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.PowerSaving_AfterControlledPortOpens (1 ms) [00012.078037][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.PowerSaving_UnprotectedAp [00012.078038][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.PowerSaving_UnprotectedAp (0 ms) [00012.078038][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.OutboundFramesAreProtectedAfterControlledPortOpens [00012.078040][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.OutboundFramesAreProtectedAfterControlledPortOpens (1 ms) [00012.078040][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.ReceiveFrames_BeforeControlledPortOpens [00012.078041][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.ReceiveFrames_BeforeControlledPortOpens (0 ms) [00012.078041][10574][10588][klog] INFO: [ RUN ] ApInfraBssTest.ReceiveFrames_AfterControlledPortOpens [00012.078042][10574][10588][klog] INFO: [ OK ] ApInfraBssTest.ReceiveFrames_AfterControlledPortOpens (0 ms) [00012.078042][10574][10588][klog] INFO: [----------] 29 tests from ApInfraBssTest (98 ms total) [00012.078043][10574][10588][klog] INFO: [00012.078043][10574][10588][klog] INFO: [----------] 2 tests from BeaconSenderTest [00012.078043][10574][10588][klog] INFO: [ RUN ] BeaconSenderTest.Start [00012.078044][10574][10588][klog] INFO: [ OK ] BeaconSenderTest.Start (2 ms) [00012.078044][10574][10588][klog] INFO: [ RUN ] BeaconSenderTest.ProbeRequest [00012.078045][10574][10588][klog] INFO: [ OK ] BeaconSenderTest.ProbeRequest (0 ms) [00012.078045][10574][10588][klog] INFO: [----------] 2 tests from BeaconSenderTest (2 ms total) [00012.078045][10574][10588][klog] INFO: [00012.078046][10574][10588][klog] INFO: [----------] 1 test from BeaconSender [00012.078046][10574][10588][klog] INFO: [ RUN ] BeaconSender.ShouldSendProbeResponse [00012.081607][10574][10588][klog] INFO: [ OK ] BeaconSender.ShouldSendProbeResponse (0 ms) [00012.081610][10574][10588][klog] INFO: [----------] 1 test from BeaconSender (0 ms total) [00012.081611][10574][10588][klog] INFO: [00012.081611][10574][10588][klog] INFO: [----------] Global test environment tear-down [00012.081613][10574][10588][klog] INFO: [==========] 32 tests from 3 test suites ran. (100 ms total) [00012.081614][10574][10588][klog] INFO: [ PASSED ] 32 tests. [00012.092444][10574][10588][klog] INFO: PASSED: /pkgfs/packages/wlan_tests/0/test/wlan_ap_mlme_unittest passed [00012.344627][10574][10588][klog] INFO: [00012.344663][10574][10588][klog] INFO: ------------------------------------------------ [00012.344666][10574][10588][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan_tests/0/test/wlan_buffer_utils_unittest [00012.344666][10574][10588][klog] INFO: [00012.437437][10574][10588][klog] INFO: [==========] Running 3 tests from 1 test suite. [00012.437470][10574][10588][klog] INFO: [----------] Global test environment set-up. [00012.437471][10574][10588][klog] INFO: [----------] 3 tests from BufferUtils [00012.437471][10574][10588][klog] INFO: [ RUN ] BufferUtils.Writer [00012.437472][10574][10588][klog] INFO: [ OK ] BufferUtils.Writer (0 ms) [00012.437472][10574][10588][klog] INFO: [ RUN ] BufferUtils.Reader [00012.437473][10574][10588][klog] INFO: [ OK ] BufferUtils.Reader (0 ms) [00012.437473][10574][10588][klog] INFO: [ RUN ] BufferUtils.Reader_ReadArray [00012.437473][10574][10588][klog] INFO: [ OK ] BufferUtils.Reader_ReadArray (0 ms) [00012.437474][10574][10588][klog] INFO: [----------] 3 tests from BufferUtils (0 ms total) [00012.437474][10574][10588][klog] INFO: [00012.437475][10574][10588][klog] INFO: [----------] Global test environment tear-down [00012.437475][10574][10588][klog] INFO: [==========] 3 tests from 1 test suite ran. (0 ms total) [00012.437476][10574][10588][klog] INFO: [ PASSED ] 3 tests. [00012.493877][10574][10588][klog] INFO: PASSED: /pkgfs/packages/wlan_tests/0/test/wlan_buffer_utils_unittest passed [00012.557914][8113][8125][klog] INFO: Starting mDNS on interface ethp0003 10.0.2.15 [00012.568516][131696379][0][netstack] VLOG(1): socket_server.go:787: unknown option for protocol [00012.568907][8113][8125][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 [00012.572239][131696379][0][netstack] INFO: socket_conv.go(523): unimplemented setsockopt: SOL_IPV6 name=19 optVal=00 [00012.572311][131696379][0][netstack] VLOG(1): socket_server.go:787: unknown option for protocol [00012.572903][131696379][0][netstack] VLOG(1): socket_server.go:787: unknown device [00012.570108][8113][8125][klog] INFO: Starting mDNS on interface ethp0003 [80fe::5450:ff00:12fe:5634] [00012.572399][8113][8125][klog] INFO: [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(29)] NET-291 IPV6_MULTICAST_LOOP not supported (ENOPROTOOPT), continuing anyway [00012.572970][8113][8125][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 [00012.574161][8113][8125][klog] INFO: mDNS: Verifying uniqueness of host name swarm-donut-petri-acre.local. [00012.661390][10574][10588][klog] INFO: [00012.661421][10574][10588][klog] INFO: ------------------------------------------------ [00012.661424][10574][10588][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan_tests/0/test/wlan_client_mlme_unittest [00012.661424][10574][10588][klog] INFO: [00012.747545][10574][10588][klog] INFO: [==========] Running 28 tests from 1 test suite. [00012.747582][10574][10588][klog] INFO: [----------] Global test environment set-up. [00012.747583][10574][10588][klog] INFO: [----------] 28 tests from ClientTest [00012.747584][10574][10588][klog] INFO: [ RUN ] ClientTest.Join [00012.747584][10574][10588][klog] INFO: [ OK ] ClientTest.Join (0 ms) [00012.747585][10574][10588][klog] INFO: [ RUN ] ClientTest.Authenticate [00012.747585][10574][10588][klog] INFO: [ OK ] ClientTest.Authenticate (0 ms) [00012.747585][10574][10588][klog] INFO: [ RUN ] ClientTest.Associate_Protected [00012.747586][10574][10588][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 [00012.747587][10574][10588][klog] INFO: wlan: [I] lost BSS; deauthenticating... [00012.747587][10574][10588][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x76a4f96, actual: 0x672a8b1e [00012.747588][10574][10588][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x76a4f96, actual: 0x65335691 [00012.747588][10574][10588][klog] INFO: [ OK ] ClientTest.Associate_Protected (0 ms) [00012.747589][10574][10588][klog] INFO: [ RUN ] ClientTest.Associate_Unprotected [00012.747590][10574][10588][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 [00012.747590][10574][10588][klog] INFO: [ OK ] ClientTest.Associate_Unprotected (1 ms) [00012.747591][10574][10588][klog] INFO: [ RUN ] ClientTest.ExchangeEapolFrames [00012.748475][10574][10588][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 [00012.748514][10574][10588][klog] INFO: [ OK ] ClientTest.ExchangeEapolFrames (0 ms) [00012.748514][10574][10588][klog] INFO: [ RUN ] ClientTest.SetKeys [00012.748515][10574][10588][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 [00012.748515][10574][10588][klog] INFO: [ OK ] ClientTest.SetKeys (0 ms) [00012.748516][10574][10588][klog] INFO: [ RUN ] ClientTest.ConstructAssociateContext [00012.748516][10574][10588][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 [00012.748517][10574][10588][klog] INFO: [ OK ] ClientTest.ConstructAssociateContext (0 ms) [00012.748518][10574][10588][klog] INFO: [ RUN ] ClientTest.AuthTimeout [00012.748518][10574][10588][klog] INFO: [ OK ] ClientTest.AuthTimeout (0 ms) [00012.748518][10574][10588][klog] INFO: [ RUN ] ClientTest.AssocTimeout [00012.748519][10574][10588][klog] INFO: [ OK ] ClientTest.AssocTimeout (0 ms) [00012.748519][10574][10588][klog] INFO: [ RUN ] ClientTest.ReceiveDataAfterAssociation_Protected [00012.748520][10574][10588][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 [00012.748520][10574][10588][klog] INFO: [ OK ] ClientTest.ReceiveDataAfterAssociation_Protected (1 ms) [00012.748521][10574][10588][klog] INFO: [ RUN ] ClientTest.SendDataAfterAssociation_Protected [00012.748525][10574][10588][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 [00012.748525][10574][10588][klog] INFO: [ OK ] ClientTest.SendDataAfterAssociation_Protected (0 ms) [00012.748526][10574][10588][klog] INFO: [ RUN ] ClientTest.SendKeepAliveFrameAfterAssociation_Protected [00012.748526][10574][10588][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 [00012.748527][10574][10588][klog] INFO: [ OK ] ClientTest.SendKeepAliveFrameAfterAssociation_Protected (0 ms) [00012.748527][10574][10588][klog] INFO: [ RUN ] ClientTest.ReceiveDataAfterAssociation_Unprotected [00012.748528][10574][10588][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 [00012.748532][10574][10588][klog] INFO: [ OK ] ClientTest.ReceiveDataAfterAssociation_Unprotected (0 ms) [00012.748533][10574][10588][klog] INFO: [ RUN ] ClientTest.SendDataAfterAssociation_Unprotected [00012.748533][10574][10588][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 [00012.748534][10574][10588][klog] INFO: [ OK ] ClientTest.SendDataAfterAssociation_Unprotected (0 ms) [00012.748534][10574][10588][klog] INFO: [ RUN ] ClientTest.SendKeepAliveFrameAfterAssociation_Unprotected [00012.748973][10574][10588][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 [00012.749001][10574][10588][klog] INFO: [ OK ] ClientTest.SendKeepAliveFrameAfterAssociation_Unprotected (1 ms) [00012.749001][10574][10588][klog] INFO: [ RUN ] ClientTest.ProcessEmptyDataFrames [00012.749002][10574][10588][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 [00012.749003][10574][10588][klog] INFO: [ OK ] ClientTest.ProcessEmptyDataFrames (0 ms) [00012.749004][10574][10588][klog] INFO: [ RUN ] ClientTest.ProcessAmsduDataFrame [00012.749004][10574][10588][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 [00012.749005][10574][10588][klog] INFO: [ OK ] ClientTest.ProcessAmsduDataFrame (0 ms) [00012.749006][10574][10588][klog] INFO: [ RUN ] ClientTest.DropManagementFrames [00012.749010][10574][10588][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 [00012.749011][10574][10588][klog] INFO: [ OK ] ClientTest.DropManagementFrames (0 ms) [00012.749011][10574][10588][klog] INFO: [ RUN ] ClientTest.AutoDeauth_NoBeaconReceived [00012.749012][10574][10588][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 [00012.749012][10574][10588][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x672a8b1e [00012.749013][10574][10588][klog] INFO: wlan: [I] lost BSS; deauthenticating... [00012.749014][10574][10588][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x672a8b1e [00012.749014][10574][10588][klog] INFO: [ OK ] ClientTest.AutoDeauth_NoBeaconReceived (0 ms) [00012.749015][10574][10588][klog] INFO: [ RUN ] ClientTest.AutoDeauth_NoBeaconsShortlyAfterConnecting [00012.749015][10574][10588][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 [00012.749015][10574][10588][klog] INFO: wlan: [I] lost BSS; deauthenticating... [00012.749016][10574][10588][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x672a8b1e [00012.749016][10574][10588][klog] INFO: [ OK ] ClientTest.AutoDeauth_NoBeaconsShortlyAfterConnecting (0 ms) [00012.749017][10574][10588][klog] INFO: [ RUN ] ClientTest.AutoDeauth_DoNotDeauthWhileSwitchingChannel [00012.749642][10574][10588][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 [00012.749663][10574][10588][klog] INFO: wlan: [I] lost BSS; deauthenticating... [00012.749663][10574][10588][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x672a8b1e [00012.749664][10574][10588][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x672a8b1e [00012.749665][10574][10588][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x345b1b19 [00012.749665][10574][10588][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x672a8b1e [00012.749666][10574][10588][klog] INFO: [ OK ] ClientTest.AutoDeauth_DoNotDeauthWhileSwitchingChannel (1 ms) [00012.749666][10574][10588][klog] INFO: [ RUN ] ClientTest.AutoDeauth_InterleavingBeaconsAndChannelSwitches [00012.749668][10574][10588][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 [00012.749669][10574][10588][klog] INFO: wlan: [I] lost BSS; deauthenticating... [00012.749670][10574][10588][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x672a8b1e [00012.749670][10574][10588][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x345b1b19 [00012.749671][10574][10588][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x345b1b19 [00012.749672][10574][10588][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x672a8b1e [00012.749672][10574][10588][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x345b1b19 [00012.749673][10574][10588][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x672a8b1e [00012.749673][10574][10588][klog] INFO: [ OK ] ClientTest.AutoDeauth_InterleavingBeaconsAndChannelSwitches (0 ms) [00012.749674][10574][10588][klog] INFO: [ RUN ] ClientTest.AutoDeauth_SwitchingChannelBeforeDeauthTimeoutCouldTrigger [00012.749674][10574][10588][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 [00012.749675][10574][10588][klog] INFO: wlan: [I] lost BSS; deauthenticating... [00012.749675][10574][10588][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x672a8b1e [00012.750082][10574][10588][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x345b1b19 [00012.750109][10574][10588][klog] INFO: [ OK ] ClientTest.AutoDeauth_SwitchingChannelBeforeDeauthTimeoutCouldTrigger (0 ms) [00012.750109][10574][10588][klog] INFO: [ RUN ] ClientTest.AutoDeauth_ForeignBeaconShouldNotPreventDeauth [00012.750112][10574][10588][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 [00012.750112][10574][10588][klog] INFO: wlan: [I] lost BSS; deauthenticating... [00012.750113][10574][10588][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x672a8b1e [00012.750113][10574][10588][klog] INFO: [ OK ] ClientTest.AutoDeauth_ForeignBeaconShouldNotPreventDeauth (0 ms) [00012.750114][10574][10588][klog] INFO: [ RUN ] ClientTest.DropFramesWhileOffChannel [00012.750115][10574][10588][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 [00012.750121][10574][10588][klog] INFO: [ OK ] ClientTest.DropFramesWhileOffChannel (0 ms) [00012.750121][10574][10588][klog] INFO: [ RUN ] ClientTest.InvalidAuthenticationResponse [00012.751178][10574][10588][klog] INFO: [ OK ] ClientTest.InvalidAuthenticationResponse (1 ms) [00012.751207][10574][10588][klog] INFO: [ RUN ] ClientTest.FailureToAssociateWithAPWithUnsupportedBasicRate [00012.751475][10574][10588][klog] INFO: wlan: [E] Ap demands 1 basic rates. Client supports 0. [00012.751476][10574][10588][klog] INFO: [ OK ] ClientTest.FailureToAssociateWithAPWithUnsupportedBasicRate (0 ms) [00012.751476][10574][10588][klog] INFO: [ RUN ] ClientTest.FailureToAssociateWithAPWithoutAnySupportedRate [00012.759410][10574][10588][klog] INFO: [ OK ] ClientTest.FailureToAssociateWithAPWithoutAnySupportedRate (0 ms) [00012.759431][10574][10588][klog] INFO: [----------] 28 tests from ClientTest (6 ms total) [00012.759433][10574][10588][klog] INFO: [00012.759515][10574][10588][klog] INFO: [----------] Global test environment tear-down [00012.759622][10574][10588][klog] INFO: [==========] 28 tests from 1 test suite ran. (7 ms total) [00012.759642][10574][10588][klog] INFO: [ PASSED ] 28 tests. [00012.760515][10574][10588][klog] INFO: PASSED: /pkgfs/packages/wlan_tests/0/test/wlan_client_mlme_unittest passed [00013.047690][10574][10588][klog] INFO: [00013.047733][10574][10588][klog] INFO: ------------------------------------------------ [00013.047737][10574][10588][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan_tests/0/test/wlan_mac_unittest [00013.047738][10574][10588][klog] INFO: [00013.222419][10574][10588][klog] INFO: [==========] Running 254 tests from 36 test suites. [00013.222460][10574][10588][klog] INFO: [----------] Global test environment set-up. [00013.222461][10574][10588][klog] INFO: [----------] 4 tests from AssociationRatesTest [00013.222462][10574][10588][klog] INFO: [ RUN ] AssociationRatesTest.Success [00013.222462][10574][10588][klog] INFO: [ OK ] AssociationRatesTest.Success (0 ms) [00013.222464][10574][10588][klog] INFO: [ RUN ] AssociationRatesTest.SuccessWithDuplicateRates [00013.222464][10574][10588][klog] INFO: [ OK ] AssociationRatesTest.SuccessWithDuplicateRates (0 ms) [00013.222465][10574][10588][klog] INFO: [ RUN ] AssociationRatesTest.FailureNoApBasicRatesSupported [00013.222466][10574][10588][klog] INFO: wlan: [E] Ap demands 1 basic rates. Client supports 0. [00013.222466][10574][10588][klog] INFO: [ OK ] AssociationRatesTest.FailureNoApBasicRatesSupported (0 ms) [00013.222467][10574][10588][klog] INFO: [ RUN ] AssociationRatesTest.FailureApBasicRatesPartiallySupported [00013.222468][10574][10588][klog] INFO: wlan: [E] Ap demands 2 basic rates. Client supports 1. [00013.222468][10574][10588][klog] INFO: [ OK ] AssociationRatesTest.FailureApBasicRatesPartiallySupported (0 ms) [00013.222469][10574][10588][klog] INFO: [----------] 4 tests from AssociationRatesTest (0 ms total) [00013.222470][10574][10588][klog] INFO: [00013.222470][10574][10588][klog] INFO: [----------] 2 tests from ParseAssocRespIe [00013.222471][10574][10588][klog] INFO: [ RUN ] ParseAssocRespIe.ParseToFail [00013.222472][10574][10588][klog] INFO: [ OK ] ParseAssocRespIe.ParseToFail (0 ms) [00013.222472][10574][10588][klog] INFO: [ RUN ] ParseAssocRespIe.Parse [00013.225523][10574][10588][klog] INFO: [ OK ] ParseAssocRespIe.Parse (0 ms) [00013.225552][10574][10588][klog] INFO: [----------] 2 tests from ParseAssocRespIe (0 ms total) [00013.225554][10574][10588][klog] INFO: [00013.225674][10574][10588][klog] INFO: [----------] 8 tests from AssocContext [00013.225698][10574][10588][klog] INFO: [ RUN ] AssocContext.IntersectHtNoVht [00013.225699][10574][10588][klog] INFO: [ OK ] AssocContext.IntersectHtNoVht (0 ms) [00013.225700][10574][10588][klog] INFO: [ RUN ] AssocContext.IntersectClientNoHT [00013.225700][10574][10588][klog] INFO: [ OK ] AssocContext.IntersectClientNoHT (0 ms) [00013.225700][10574][10588][klog] INFO: [ RUN ] AssocContext.IntersectHtVht [00013.225701][10574][10588][klog] INFO: [ OK ] AssocContext.IntersectHtVht (1 ms) [00013.225701][10574][10588][klog] INFO: [ RUN ] AssocContext.IntersectClientNoVht [00013.225702][10574][10588][klog] INFO: [ OK ] AssocContext.IntersectClientNoVht (0 ms) [00013.225702][10574][10588][klog] INFO: [ RUN ] AssocContext.IntersectBssNoHT [00013.225702][10574][10588][klog] INFO: [ OK ] AssocContext.IntersectBssNoHT (0 ms) [00013.225703][10574][10588][klog] INFO: [ RUN ] AssocContext.IntersectBssNoVht [00013.225703][10574][10588][klog] INFO: [ OK ] AssocContext.IntersectBssNoVht (0 ms) [00013.225704][10574][10588][klog] INFO: [ RUN ] AssocContext.MakeBssAssocCtx [00013.225704][10574][10588][klog] INFO: [ OK ] AssocContext.MakeBssAssocCtx (0 ms) [00013.225704][10574][10588][klog] INFO: [ RUN ] AssocContext.ToDdk [00013.225705][10574][10588][klog] INFO: [ OK ] AssocContext.ToDdk (0 ms) [00013.225705][10574][10588][klog] INFO: [----------] 8 tests from AssocContext (1 ms total) [00013.225705][10574][10588][klog] INFO: [00013.225706][10574][10588][klog] INFO: [----------] 6 tests from ChannelTest [00013.225706][10574][10588][klog] INFO: [ RUN ] ChannelTest.ValidCombo [00013.225707][10574][10588][klog] INFO: [ OK ] ChannelTest.ValidCombo (0 ms) [00013.225713][10574][10588][klog] INFO: [ RUN ] ChannelTest.Equality [00013.225713][10574][10588][klog] INFO: [ OK ] ChannelTest.Equality (0 ms) [00013.225714][10574][10588][klog] INFO: [ RUN ] ChannelTest.InvalidCombo [00013.225714][10574][10588][klog] INFO: wlan: [E] invalid channel value: 0 [00013.225715][10574][10588][klog] INFO: wlan: [E] invalid channel value: 0 [00013.225715][10574][10588][klog] INFO: wlan: [E] invalid channel value: 15 [00013.225716][10574][10588][klog] INFO: wlan: [E] invalid channel value: 15 [00013.225716][10574][10588][klog] INFO: wlan: [E] invalid channel value: 8+ [00013.225717][10574][10588][klog] INFO: wlan: [E] invalid channel value: 8+ [00013.225717][10574][10588][klog] INFO: wlan: [E] invalid channel value: 4- [00013.225717][10574][10588][klog] INFO: wlan: [E] invalid channel value: 4- [00013.225718][10574][10588][klog] INFO: wlan: [E] invalid channel value: 32 [00013.225718][10574][10588][klog] INFO: wlan: [E] invalid channel value: 32 [00013.225718][10574][10588][klog] INFO: wlan: [E] invalid channel value: 68 [00013.225719][10574][10588][klog] INFO: wlan: [E] invalid channel value: 68 [00013.225721][10574][10588][klog] INFO: wlan: [E] invalid channel value: 96 [00013.225721][10574][10588][klog] INFO: wlan: [E] invalid channel value: 96 [00013.225724][10574][10588][klog] INFO: wlan: [E] invalid channel value: 148 [00013.225725][10574][10588][klog] INFO: wlan: [E] invalid channel value: 148 [00013.225756][10574][10588][klog] INFO: wlan: [E] invalid channel value: 183 [00013.225757][10574][10588][klog] INFO: wlan: [E] invalid channel value: 183 [00013.225757][10574][10588][klog] INFO: wlan: [E] invalid channel value: 36- [00013.225757][10574][10588][klog] INFO: wlan: [E] invalid channel value: 36- [00013.225758][10574][10588][klog] INFO: wlan: [E] invalid channel value: 40+ [00013.225758][10574][10588][klog] INFO: wlan: [E] invalid channel value: 40+ [00013.225758][10574][10588][klog] INFO: wlan: [E] invalid channel value: 149- [00013.225759][10574][10588][klog] INFO: wlan: [E] invalid channel value: 149- [00013.225759][10574][10588][klog] INFO: wlan: [E] invalid channel value: 153+ [00013.230918][10574][10588][klog] INFO: wlan: [E] invalid channel value: 153+ [00013.230942][10574][10588][klog] INFO: wlan: [E] invalid channel value: 165V [00013.230943][10574][10588][klog] INFO: wlan: [E] invalid channel value: 165V [00013.230945][10574][10588][klog] INFO: wlan: [E] invalid channel value: 36+0P [00013.230946][10574][10588][klog] INFO: wlan: [E] invalid channel value: 36+0P [00013.230946][10574][10588][klog] INFO: wlan: [E] invalid channel value: 48+42P [00013.230947][10574][10588][klog] INFO: wlan: [E] invalid channel value: 48+42P [00013.230947][10574][10588][klog] INFO: wlan: [E] invalid channel value: 149+155P [00013.230948][10574][10588][klog] INFO: wlan: [E] invalid channel value: 149+155P [00013.230948][10574][10588][klog] INFO: wlan: [E] invalid channel value: 132W [00013.230948][10574][10588][klog] INFO: wlan: [E] invalid channel value: 132W [00013.230949][10574][10588][klog] INFO: [ OK ] ChannelTest.InvalidCombo (0 ms) [00013.230949][10574][10588][klog] INFO: [ RUN ] ChannelTest.Conversion [00013.230950][10574][10588][klog] INFO: [ OK ] ChannelTest.Conversion (0 ms) [00013.230950][10574][10588][klog] INFO: [ RUN ] ChannelTest.GetCenterChanIdx [00013.230951][10574][10588][klog] INFO: [ OK ] ChannelTest.GetCenterChanIdx (0 ms) [00013.230951][10574][10588][klog] INFO: [ RUN ] ChannelTest.GetCenterFreq [00013.230951][10574][10588][klog] INFO: [ OK ] ChannelTest.GetCenterFreq (0 ms) [00013.230952][10574][10588][klog] INFO: [----------] 6 tests from ChannelTest (0 ms total) [00013.230952][10574][10588][klog] INFO: [00013.230952][10574][10588][klog] INFO: [----------] 4 tests from FidlToElement [00013.230953][10574][10588][klog] INFO: [ RUN ] FidlToElement.VhtMcsNssFidlToBitField [00013.230953][10574][10588][klog] INFO: [ OK ] FidlToElement.VhtMcsNssFidlToBitField (0 ms) [00013.230954][10574][10588][klog] INFO: [ RUN ] FidlToElement.HtCapabilitiesFidlToBitField [00013.230959][10574][10588][klog] INFO: [ OK ] FidlToElement.HtCapabilitiesFidlToBitField (0 ms) [00013.230960][10574][10588][klog] INFO: [ RUN ] FidlToElement.HtOperationFidlToBitField [00013.230960][10574][10588][klog] INFO: [ OK ] FidlToElement.HtOperationFidlToBitField (0 ms) [00013.230960][10574][10588][klog] INFO: [ RUN ] FidlToElement.VhtOperationFidlToBitField [00013.230961][10574][10588][klog] INFO: [ OK ] FidlToElement.VhtOperationFidlToBitField (0 ms) [00013.230961][10574][10588][klog] INFO: [----------] 4 tests from FidlToElement (0 ms total) [00013.230962][10574][10588][klog] INFO: [00013.230962][10574][10588][klog] INFO: [----------] 4 tests from ElementToFidl [00013.230962][10574][10588][klog] INFO: [ RUN ] ElementToFidl.VhtMcsNssBitFieldToFidl [00013.230963][10574][10588][klog] INFO: [ OK ] ElementToFidl.VhtMcsNssBitFieldToFidl (0 ms) [00013.230963][10574][10588][klog] INFO: [ RUN ] ElementToFidl.HtCapabilitiesToFidlHuman [00013.230967][10574][10588][klog] INFO: [ OK ] ElementToFidl.HtCapabilitiesToFidlHuman (0 ms) [00013.230967][10574][10588][klog] INFO: [ RUN ] ElementToFidl.HtOperationToFidl [00013.230968][10574][10588][klog] INFO: [ OK ] ElementToFidl.HtOperationToFidl (0 ms) [00013.230968][10574][10588][klog] INFO: [ RUN ] ElementToFidl.VhtOperationBitFieldToField [00013.230969][10574][10588][klog] INFO: [ OK ] ElementToFidl.VhtOperationBitFieldToField (0 ms) [00013.230969][10574][10588][klog] INFO: [----------] 4 tests from ElementToFidl (0 ms total) [00013.231083][10574][10588][klog] INFO: [00013.231112][10574][10588][klog] INFO: [----------] 1 test from ElementHumanAccessor [00013.231112][10574][10588][klog] INFO: [ RUN ] ElementHumanAccessor.HtCapabilitiesBitFieldOrHuman [00013.231113][10574][10588][klog] INFO: [ OK ] ElementHumanAccessor.HtCapabilitiesBitFieldOrHuman (0 ms) [00013.233700][10574][10588][klog] INFO: [----------] 1 test from ElementHumanAccessor (0 ms total) [00013.233726][10574][10588][klog] INFO: [00013.233727][10574][10588][klog] INFO: [----------] 8 tests from ElementSplitter [00013.233727][10574][10588][klog] INFO: [ RUN ] ElementSplitter.Empty [00013.233728][10574][10588][klog] INFO: [ OK ] ElementSplitter.Empty (0 ms) [00013.233728][10574][10588][klog] INFO: [ RUN ] ElementSplitter.LessThanHeader [00013.233729][10574][10588][klog] INFO: [ OK ] ElementSplitter.LessThanHeader (0 ms) [00013.233729][10574][10588][klog] INFO: [ RUN ] ElementSplitter.SingleElementWithEmptyBody [00013.233730][10574][10588][klog] INFO: [ OK ] ElementSplitter.SingleElementWithEmptyBody (0 ms) [00013.233730][10574][10588][klog] INFO: [ RUN ] ElementSplitter.SingleElementBufferTooSmall [00013.233731][10574][10588][klog] INFO: [ OK ] ElementSplitter.SingleElementBufferTooSmall (0 ms) [00013.233731][10574][10588][klog] INFO: [ RUN ] ElementSplitter.SingleElement [00013.233732][10574][10588][klog] INFO: [ OK ] ElementSplitter.SingleElement (0 ms) [00013.233732][10574][10588][klog] INFO: [ RUN ] ElementSplitter.SeveralElements [00013.233733][10574][10588][klog] INFO: [ OK ] ElementSplitter.SeveralElements (0 ms) [00013.233733][10574][10588][klog] INFO: [ RUN ] ElementSplitter.TwoElementsBufferTooSmallForHeader [00013.233734][10574][10588][klog] INFO: [ OK ] ElementSplitter.TwoElementsBufferTooSmallForHeader (0 ms) [00013.233734][10574][10588][klog] INFO: [ RUN ] ElementSplitter.TwoElementsBufferTooSmallForBody [00013.233735][10574][10588][klog] INFO: [ OK ] ElementSplitter.TwoElementsBufferTooSmallForBody (0 ms) [00013.233735][10574][10588][klog] INFO: [----------] 8 tests from ElementSplitter (0 ms total) [00013.233736][10574][10588][klog] INFO: [00013.233790][10574][10588][klog] INFO: [----------] 3 tests from Elements [00013.233790][10574][10588][klog] INFO: [ RUN ] Elements.Tspec [00013.233791][10574][10588][klog] INFO: [ OK ] Elements.Tspec (0 ms) [00013.233791][10574][10588][klog] INFO: [ RUN ] Elements.TsInfoAggregation [00013.233791][10574][10588][klog] INFO: [ OK ] Elements.TsInfoAggregation (0 ms) [00013.233792][10574][10588][klog] INFO: [ RUN ] Elements.TsInfoScheduleSetting [00013.233792][10574][10588][klog] INFO: [ OK ] Elements.TsInfoScheduleSetting (0 ms) [00013.233793][10574][10588][klog] INFO: [----------] 3 tests from Elements (0 ms total) [00013.233793][10574][10588][klog] INFO: [00013.233793][10574][10588][klog] INFO: [----------] 1 test from HtCapabilities [00013.233794][10574][10588][klog] INFO: [ RUN ] HtCapabilities.DdkConversion [00013.233794][10574][10588][klog] INFO: [ OK ] HtCapabilities.DdkConversion (0 ms) [00013.233794][10574][10588][klog] INFO: [----------] 1 test from HtCapabilities (0 ms total) [00013.233795][10574][10588][klog] INFO: [00013.233795][10574][10588][klog] INFO: [----------] 1 test from HtOperation [00013.233796][10574][10588][klog] INFO: [ RUN ] HtOperation.DdkConversion [00013.233796][10574][10588][klog] INFO: [ OK ] HtOperation.DdkConversion (0 ms) [00013.233797][10574][10588][klog] INFO: [----------] 1 test from HtOperation (0 ms total) [00013.233797][10574][10588][klog] INFO: [00013.233797][10574][10588][klog] INFO: [----------] 1 test from VhtCapabilities [00013.233798][10574][10588][klog] INFO: [ RUN ] VhtCapabilities.DdkConversion [00013.233798][10574][10588][klog] INFO: [ OK ] VhtCapabilities.DdkConversion (0 ms) [00013.233799][10574][10588][klog] INFO: [----------] 1 test from VhtCapabilities (0 ms total) [00013.233799][10574][10588][klog] INFO: [00013.233799][10574][10588][klog] INFO: [----------] 1 test from VhtOperation [00013.233800][10574][10588][klog] INFO: [ RUN ] VhtOperation.DdkConversion [00013.233800][10574][10588][klog] INFO: [ OK ] VhtOperation.DdkConversion (0 ms) [00013.233849][10574][10588][klog] INFO: [----------] 1 test from VhtOperation (0 ms total) [00013.233850][10574][10588][klog] INFO: [00013.233850][10574][10588][klog] INFO: [----------] 3 tests from SupportedRate [00013.233852][10574][10588][klog] INFO: [ RUN ] SupportedRate.Create [00013.233852][10574][10588][klog] INFO: [ OK ] SupportedRate.Create (0 ms) [00013.233853][10574][10588][klog] INFO: [ RUN ] SupportedRate.ToUint8 [00013.233853][10574][10588][klog] INFO: [ OK ] SupportedRate.ToUint8 (0 ms) [00013.233853][10574][10588][klog] INFO: [ RUN ] SupportedRate.Compare [00013.233854][10574][10588][klog] INFO: [ OK ] SupportedRate.Compare (0 ms) [00013.233855][10574][10588][klog] INFO: [----------] 3 tests from SupportedRate (0 ms total) [00013.233855][10574][10588][klog] INFO: [00013.233855][10574][10588][klog] INFO: [----------] 1 test from Intersector [00013.233856][10574][10588][klog] INFO: [ RUN ] Intersector.IntersectRates [00013.233856][10574][10588][klog] INFO: [ OK ] Intersector.IntersectRates (0 ms) [00013.233857][10574][10588][klog] INFO: [----------] 1 test from Intersector (0 ms total) [00013.233857][10574][10588][klog] INFO: [00013.233858][10574][10588][klog] INFO: [----------] 8 tests from EnergyTest [00013.233858][10574][10588][klog] INFO: [ RUN ] EnergyTest.Construct [00013.233858][10574][10588][klog] INFO: [ OK ] EnergyTest.Construct (0 ms) [00013.233859][10574][10588][klog] INFO: [ RUN ] EnergyTest.Assign [00013.233859][10574][10588][klog] INFO: [ OK ] EnergyTest.Assign (0 ms) [00013.233859][10574][10588][klog] INFO: [ RUN ] EnergyTest.Compare [00013.233860][10574][10588][klog] INFO: [ OK ] EnergyTest.Compare (0 ms) [00013.233860][10574][10588][klog] INFO: [ RUN ] EnergyTest.Comparem_Watt [00013.233860][10574][10588][klog] INFO: [ OK ] EnergyTest.Comparem_Watt (0 ms) [00013.233861][10574][10588][klog] INFO: [ RUN ] EnergyTest.Conversion [00013.233861][10574][10588][klog] INFO: [ OK ] EnergyTest.Conversion (0 ms) [00013.233862][10574][10588][klog] INFO: [ RUN ] EnergyTest.Arithmetics [00013.233862][10574][10588][klog] INFO: [ OK ] EnergyTest.Arithmetics (0 ms) [00013.233863][10574][10588][klog] INFO: [ RUN ] EnergyTest.DbmToFemtoWatt [00013.233864][10574][10588][klog] INFO: [ OK ] EnergyTest.DbmToFemtoWatt (0 ms) [00013.233864][10574][10588][klog] INFO: [ RUN ] EnergyTest.FemtoWattToDbm [00013.233865][10574][10588][klog] INFO: [ OK ] EnergyTest.FemtoWattToDbm (0 ms) [00013.233865][10574][10588][klog] INFO: [----------] 8 tests from EnergyTest (0 ms total) [00013.233866][10574][10588][klog] INFO: [00013.233866][10574][10588][klog] INFO: [----------] 23 tests from FrameValidation [00013.233866][10574][10588][klog] INFO: [ RUN ] FrameValidation.TestHdrLength [00013.233867][10574][10588][klog] INFO: [ OK ] FrameValidation.TestHdrLength (0 ms) [00013.233867][10574][10588][klog] INFO: [ RUN ] FrameValidation.TestHdrLength_IllegalBuffer [00013.233868][10574][10588][klog] INFO: [ OK ] FrameValidation.TestHdrLength_IllegalBuffer (0 ms) [00013.233868][10574][10588][klog] INFO: [ RUN ] FrameValidation.TestEmptyHdrLength [00013.233868][10574][10588][klog] INFO: [ OK ] FrameValidation.TestEmptyHdrLength (0 ms) [00013.233869][10574][10588][klog] INFO: [ RUN ] FrameValidation.TestEmptyHdrLength_IllegalBuffer [00013.233869][10574][10588][klog] INFO: [ OK ] FrameValidation.TestEmptyHdrLength_IllegalBuffer (0 ms) [00013.233870][10574][10588][klog] INFO: [ RUN ] FrameValidation.TestDynamicHdrLength [00013.233870][10574][10588][klog] INFO: [ OK ] FrameValidation.TestDynamicHdrLength (0 ms) [00013.233870][10574][10588][klog] INFO: [ RUN ] FrameValidation.TestFrameLength_NoPadding [00013.233871][10574][10588][klog] INFO: [ OK ] FrameValidation.TestFrameLength_NoPadding (0 ms) [00013.233871][10574][10588][klog] INFO: [ RUN ] FrameValidation.TestFrameLength_EmptyBody_NoPadding [00013.234635][10574][10588][klog] INFO: [ OK ] FrameValidation.TestFrameLength_EmptyBody_NoPadding (0 ms) [00013.234757][10574][10588][klog] INFO: [ RUN ] FrameValidation.TestFrameLength_Padding [00013.234759][10574][10588][klog] INFO: [ OK ] FrameValidation.TestFrameLength_Padding (0 ms) [00013.234759][10574][10588][klog] INFO: [ RUN ] FrameValidation.TestFrameLength_EmptyBody_Padding [00013.234760][10574][10588][klog] INFO: [ OK ] FrameValidation.TestFrameLength_EmptyBody_Padding (0 ms) [00013.234761][10574][10588][klog] INFO: [ RUN ] FrameValidation.ValidBeaconType [00013.234761][10574][10588][klog] INFO: [ OK ] FrameValidation.ValidBeaconType (0 ms) [00013.234775][10574][10588][klog] INFO: [ RUN ] FrameValidation.ValidPsPollFrameType [00013.234775][10574][10588][klog] INFO: [ OK ] FrameValidation.ValidPsPollFrameType (0 ms) [00013.234776][10574][10588][klog] INFO: [ RUN ] FrameValidation.ValidDeauthFrameType [00013.234777][10574][10588][klog] INFO: [ OK ] FrameValidation.ValidDeauthFrameType (0 ms) [00013.234778][10574][10588][klog] INFO: [ RUN ] FrameValidation.ValidActionFrameType [00013.234779][10574][10588][klog] INFO: [ OK ] FrameValidation.ValidActionFrameType (0 ms) [00013.234779][10574][10588][klog] INFO: [ RUN ] FrameValidation.ValidProbeRequestFrameType [00013.234780][10574][10588][klog] INFO: [ OK ] FrameValidation.ValidProbeRequestFrameType (0 ms) [00013.234780][10574][10588][klog] INFO: [ RUN ] FrameValidation.ValidAssocRequestFrameType [00013.234781][10574][10588][klog] INFO: [ OK ] FrameValidation.ValidAssocRequestFrameType (0 ms) [00013.234781][10574][10588][klog] INFO: [ RUN ] FrameValidation.ValidAssocResponseFrameType [00013.234816][10574][10588][klog] INFO: [ OK ] FrameValidation.ValidAssocResponseFrameType (0 ms) [00013.234816][10574][10588][klog] INFO: [ RUN ] FrameValidation.ValidAuthFrameType [00013.234817][10574][10588][klog] INFO: [ OK ] FrameValidation.ValidAuthFrameType (0 ms) [00013.234817][10574][10588][klog] INFO: [ RUN ] FrameValidation.ValidDisassocFrameType [00013.234818][10574][10588][klog] INFO: [ OK ] FrameValidation.ValidDisassocFrameType (0 ms) [00013.234819][10574][10588][klog] INFO: [ RUN ] FrameValidation.ValidNullDataFrameType [00013.234819][10574][10588][klog] INFO: [ OK ] FrameValidation.ValidNullDataFrameType (0 ms) [00013.234820][10574][10588][klog] INFO: [ RUN ] FrameValidation.ValidQosNullDataFrameType [00013.234821][10574][10588][klog] INFO: [ OK ] FrameValidation.ValidQosNullDataFrameType (0 ms) [00013.234821][10574][10588][klog] INFO: [ RUN ] FrameValidation.ValidDataFrameType [00013.234822][10574][10588][klog] INFO: [ OK ] FrameValidation.ValidDataFrameType (0 ms) [00013.234822][10574][10588][klog] INFO: [ RUN ] FrameValidation.ValidQosDataFrameType [00013.234823][10574][10588][klog] INFO: [ OK ] FrameValidation.ValidQosDataFrameType (0 ms) [00013.234824][10574][10588][klog] INFO: [ RUN ] FrameValidation.ValidAmsduDataFrameType [00013.234824][10574][10588][klog] INFO: [ OK ] FrameValidation.ValidAmsduDataFrameType (0 ms) [00013.234825][10574][10588][klog] INFO: [----------] 23 tests from FrameValidation (1 ms total) [00013.234825][10574][10588][klog] INFO: [00013.234825][10574][10588][klog] INFO: [----------] 1 test from JoinContext [00013.234826][10574][10588][klog] INFO: [ RUN ] JoinContext.Sanitize [00013.234826][10574][10588][klog] INFO: wlan: [E] invalid channel value: 136+ [00013.234827][10574][10588][klog] INFO: wlan: [E] Sanitize the invalid channel: 136 CBW40 to 136 CBW20 [00013.249266][10574][10588][klog] INFO: wlan: [E] invalid channel value: 132- [00013.249318][10574][10588][klog] INFO: wlan: [E] Sanitize the invalid channel: 132 CBW40B to 132 CBW20 [00013.249328][10574][10588][klog] INFO: [ OK ] JoinContext.Sanitize (0 ms) [00013.249332][10574][10588][klog] INFO: [----------] 1 test from JoinContext (0 ms total) [00013.249336][10574][10588][klog] INFO: [00013.249359][10574][10588][klog] INFO: [----------] 19 tests from Frame [00013.249363][10574][10588][klog] INFO: [ RUN ] Frame.General [00013.249384][10574][10588][klog] INFO: [ OK ] Frame.General (0 ms) [00013.249386][10574][10588][klog] INFO: [ RUN ] Frame.General_Const_Frame [00013.249387][10574][10588][klog] INFO: [ OK ] Frame.General_Const_Frame (0 ms) [00013.249388][10574][10588][klog] INFO: [ RUN ] Frame.Take [00013.249390][10574][10588][klog] INFO: [ OK ] Frame.Take (0 ms) [00013.249392][10574][10588][klog] INFO: [ RUN ] Frame.ExactlySizedBuffer_HdrOnly [00013.249422][10574][10588][klog] INFO: [ OK ] Frame.ExactlySizedBuffer_HdrOnly (0 ms) [00013.249445][10574][10588][klog] INFO: [ RUN ] Frame.ExactlySizedBuffer_Frame [00013.249447][10574][10588][klog] INFO: [ OK ] Frame.ExactlySizedBuffer_Frame (0 ms) [00013.249448][10574][10588][klog] INFO: [ RUN ] Frame.TooShortBuffer_NoHdr [00013.249452][10574][10588][klog] INFO: [ OK ] Frame.TooShortBuffer_NoHdr (0 ms) [00013.249453][10574][10588][klog] INFO: [ RUN ] Frame.RxInfo_MacFrame [00013.249455][10574][10588][klog] INFO: [ OK ] Frame.RxInfo_MacFrame (0 ms) [00013.249455][10574][10588][klog] INFO: [ RUN ] Frame.RxInfo_OtherFrame [00013.249456][10574][10588][klog] INFO: [ OK ] Frame.RxInfo_OtherFrame (0 ms) [00013.249456][10574][10588][klog] INFO: [ RUN ] Frame.RxInfo_PaddingAlignedBody [00013.249457][10574][10588][klog] INFO: [ OK ] Frame.RxInfo_PaddingAlignedBody (0 ms) [00013.249458][10574][10588][klog] INFO: [ RUN ] Frame.RxInfo_NoPaddingAlignedBody [00013.249465][10574][10588][klog] INFO: [ OK ] Frame.RxInfo_NoPaddingAlignedBody (0 ms) [00013.249484][10574][10588][klog] INFO: [ RUN ] Frame.ConstructEmptyFrame [00013.249486][10574][10588][klog] INFO: [ OK ] Frame.ConstructEmptyFrame (0 ms) [00013.249486][10574][10588][klog] INFO: [ RUN ] Frame.AdvanceThroughAmsduFrame [00013.249488][10574][10588][klog] INFO: [ OK ] Frame.AdvanceThroughAmsduFrame (0 ms) [00013.249525][10574][10588][klog] INFO: [ RUN ] Frame.AdvanceThroughEmptyFrame [00013.249527][10574][10588][klog] INFO: [ OK ] Frame.AdvanceThroughEmptyFrame (0 ms) [00013.249529][10574][10588][klog] INFO: [ RUN ] Frame.AdvanceOutOfBounds [00013.249533][10574][10588][klog] INFO: [ OK ] Frame.AdvanceOutOfBounds (0 ms) [00013.249570][10574][10588][klog] INFO: [ RUN ] Frame.AdvanceThroughEapolFrame [00013.249571][10574][10588][klog] INFO: [ OK ] Frame.AdvanceThroughEapolFrame (0 ms) [00013.249572][10574][10588][klog] INFO: [ RUN ] Frame.EmptyBodyData [00013.249573][10574][10588][klog] INFO: [ OK ] Frame.EmptyBodyData (0 ms) [00013.249573][10574][10588][klog] INFO: [ RUN ] Frame.PopulatedBodyData [00013.249574][10574][10588][klog] INFO: [ OK ] Frame.PopulatedBodyData (0 ms) [00013.249574][10574][10588][klog] INFO: [ RUN ] Frame.DdkConversion [00013.249575][10574][10588][klog] INFO: [ OK ] Frame.DdkConversion (0 ms) [00013.249597][10574][10588][klog] INFO: [ RUN ] Frame.ParseProbeRequests [00013.249598][10574][10588][klog] INFO: [ OK ] Frame.ParseProbeRequests (0 ms) [00013.249599][10574][10588][klog] INFO: [----------] 19 tests from Frame (0 ms total) [00013.249599][10574][10588][klog] INFO: [00013.249600][10574][10588][klog] INFO: [----------] 3 tests from MacAddrTest [00013.249601][10574][10588][klog] INFO: [ RUN ] MacAddrTest.Some [00013.249601][10574][10588][klog] INFO: [ OK ] MacAddrTest.Some (0 ms) [00013.249602][10574][10588][klog] INFO: [ RUN ] MacAddrTest.Constructors [00013.255634][10574][10588][klog] INFO: [ OK ] MacAddrTest.Constructors (0 ms) [00013.255664][10574][10588][klog] INFO: [ RUN ] MacAddrTest.Conversion [00013.255664][10574][10588][klog] INFO: [ OK ] MacAddrTest.Conversion (0 ms) [00013.255665][10574][10588][klog] INFO: [----------] 3 tests from MacAddrTest (0 ms total) [00013.255665][10574][10588][klog] INFO: [00013.255666][10574][10588][klog] INFO: [----------] 2 tests from McsTest [00013.255667][10574][10588][klog] INFO: [ RUN ] McsTest.Intersect [00013.255667][10574][10588][klog] INFO: [ OK ] McsTest.Intersect (0 ms) [00013.255668][10574][10588][klog] INFO: [ RUN ] McsTest.ConvertFromFidl [00013.255669][10574][10588][klog] INFO: [ OK ] McsTest.ConvertFromFidl (0 ms) [00013.255670][10574][10588][klog] INFO: [----------] 2 tests from McsTest (0 ms total) [00013.255670][10574][10588][klog] INFO: [00013.255670][10574][10588][klog] INFO: [----------] 3 tests from PacketTest [00013.255671][10574][10588][klog] INFO: [ RUN ] PacketTest.BufferAlloc [00013.255671][10574][10588][klog] INFO: [ OK ] PacketTest.BufferAlloc (0 ms) [00013.255671][10574][10588][klog] INFO: [ RUN ] PacketTest.BufferMaxOut [00013.255672][10574][10588][klog] INFO: wlan: [V:buf ]Huge buffer exhausted. [00013.255673][10574][10588][klog] INFO: wlan: [V:buf ]usage(in_use/in_use_max/current_capacity/max_capacity) [00013.255673][10574][10588][klog] INFO: Small: 0/2/512/20480, Large: 0/2/32/640, Huge: 16/16/16/16 [00013.255673][10574][10588][klog] INFO: [ OK ] PacketTest.BufferMaxOut (0 ms) [00013.255674][10574][10588][klog] INFO: [ RUN ] PacketTest.BufferFallback [00013.255674][10574][10588][klog] INFO: wlan: [V:buf ]Small buffer exhausted. [00013.255675][10574][10588][klog] INFO: wlan: [V:buf ]usage(in_use/in_use_max/current_capacity/max_capacity) [00013.255675][10574][10588][klog] INFO: Small: 20480/20480/20480/20480, Large: 0/2/32/640, Huge: 0/16/16/16 [00013.255676][10574][10588][klog] INFO: [ OK ] PacketTest.BufferFallback (23 ms) [00013.255682][10574][10588][klog] INFO: [----------] 3 tests from PacketTest (23 ms total) [00013.255683][10574][10588][klog] INFO: [00013.255683][10574][10588][klog] INFO: [----------] 5 tests from PacketQueueTest [00013.255684][10574][10588][klog] INFO: [ RUN ] PacketQueueTest.Empty [00013.255684][10574][10588][klog] INFO: [ OK ] PacketQueueTest.Empty (0 ms) [00013.255685][10574][10588][klog] INFO: [ RUN ] PacketQueueTest.EnqueueAndDequeue [00013.255685][10574][10588][klog] INFO: [ OK ] PacketQueueTest.EnqueueAndDequeue (0 ms) [00013.255686][10574][10588][klog] INFO: [ RUN ] PacketQueueTest.EnqueueAndUndoEnqueue [00013.255687][10574][10588][klog] INFO: [ OK ] PacketQueueTest.EnqueueAndUndoEnqueue (0 ms) [00013.255687][10574][10588][klog] INFO: [ RUN ] PacketQueueTest.Move [00013.255687][10574][10588][klog] INFO: [ OK ] PacketQueueTest.Move (0 ms) [00013.255688][10574][10588][klog] INFO: [ RUN ] PacketQueueTest.Drain [00013.255688][10574][10588][klog] INFO: [ OK ] PacketQueueTest.Drain (0 ms) [00013.255689][10574][10588][klog] INFO: [----------] 5 tests from PacketQueueTest (0 ms total) [00013.255692][10574][10588][klog] INFO: [00013.255692][10574][10588][klog] INFO: [----------] 4 tests from ParseBeaconTest [00013.255693][10574][10588][klog] INFO: [ RUN ] ParseBeaconTest.GetVhtCbw [00013.255693][10574][10588][klog] INFO: [ OK ] ParseBeaconTest.GetVhtCbw (0 ms) [00013.255694][10574][10588][klog] INFO: [ RUN ] ParseBeaconTest.DeriveChannel [00013.255694][10574][10588][klog] INFO: [ OK ] ParseBeaconTest.DeriveChannel (0 ms) [00013.255822][10574][10588][klog] INFO: [ RUN ] ParseBeaconTest.FillRates [00013.255884][10574][10588][klog] INFO: [ OK ] ParseBeaconTest.FillRates (0 ms) [00013.255910][10574][10588][klog] INFO: [ RUN ] ParseBeaconTest.ParseBeaconElements [00013.255911][10574][10588][klog] INFO: [ OK ] ParseBeaconTest.ParseBeaconElements (0 ms) [00013.260359][10574][10588][klog] INFO: [----------] 4 tests from ParseBeaconTest (0 ms total) [00013.260401][10574][10588][klog] INFO: [00013.260402][10574][10588][klog] INFO: [----------] 70 tests from ParseElement [00013.260403][10574][10588][klog] INFO: [ RUN ] ParseElement.Ssid [00013.260404][10574][10588][klog] INFO: [ OK ] ParseElement.Ssid (0 ms) [00013.260404][10574][10588][klog] INFO: [ RUN ] ParseElement.SsidTooLong [00013.260405][10574][10588][klog] INFO: [ OK ] ParseElement.SsidTooLong (0 ms) [00013.260405][10574][10588][klog] INFO: [ RUN ] ParseElement.SupportedRates [00013.260406][10574][10588][klog] INFO: [ OK ] ParseElement.SupportedRates (0 ms) [00013.260407][10574][10588][klog] INFO: [ RUN ] ParseElement.SupportedRatesEmpty [00013.260407][10574][10588][klog] INFO: [ OK ] ParseElement.SupportedRatesEmpty (0 ms) [00013.260408][10574][10588][klog] INFO: [ RUN ] ParseElement.SupportedRatesTooLong [00013.260408][10574][10588][klog] INFO: [ OK ] ParseElement.SupportedRatesTooLong (0 ms) [00013.260409][10574][10588][klog] INFO: [ RUN ] ParseElement.DsssParamSet [00013.260409][10574][10588][klog] INFO: [ OK ] ParseElement.DsssParamSet (0 ms) [00013.260409][10574][10588][klog] INFO: [ RUN ] ParseElement.DsssParamSetToShort [00013.260410][10574][10588][klog] INFO: [ OK ] ParseElement.DsssParamSetToShort (0 ms) [00013.260410][10574][10588][klog] INFO: [ RUN ] ParseElement.DsssParamSetToLong [00013.260410][10574][10588][klog] INFO: [ OK ] ParseElement.DsssParamSetToLong (0 ms) [00013.260411][10574][10588][klog] INFO: [ RUN ] ParseElement.CfParamSet [00013.260411][10574][10588][klog] INFO: [ OK ] ParseElement.CfParamSet (0 ms) [00013.260412][10574][10588][klog] INFO: [ RUN ] ParseElement.CfParamSetTooShort [00013.260412][10574][10588][klog] INFO: [ OK ] ParseElement.CfParamSetTooShort (0 ms) [00013.260412][10574][10588][klog] INFO: [ RUN ] ParseElement.CfParamSetTooLong [00013.260418][10574][10588][klog] INFO: [ OK ] ParseElement.CfParamSetTooLong (0 ms) [00013.260419][10574][10588][klog] INFO: [ RUN ] ParseElement.Tim [00013.260419][10574][10588][klog] INFO: [ OK ] ParseElement.Tim (0 ms) [00013.260420][10574][10588][klog] INFO: [ RUN ] ParseElement.TimEmptyBitmap [00013.260420][10574][10588][klog] INFO: [ OK ] ParseElement.TimEmptyBitmap (0 ms) [00013.260420][10574][10588][klog] INFO: [ RUN ] ParseElement.TimTooShort [00013.260421][10574][10588][klog] INFO: [ OK ] ParseElement.TimTooShort (0 ms) [00013.260422][10574][10588][klog] INFO: [ RUN ] ParseElement.CountryNoTriplets [00013.260423][10574][10588][klog] INFO: [ OK ] ParseElement.CountryNoTriplets (0 ms) [00013.260424][10574][10588][klog] INFO: [ RUN ] ParseElement.CountrySingleTriplet [00013.260424][10574][10588][klog] INFO: [ OK ] ParseElement.CountrySingleTriplet (0 ms) [00013.260425][10574][10588][klog] INFO: [ RUN ] ParseElement.CountryTwoTriplets [00013.260425][10574][10588][klog] INFO: [ OK ] ParseElement.CountryTwoTriplets (0 ms) [00013.260426][10574][10588][klog] INFO: [ RUN ] ParseElement.CountryTooShort [00013.260428][10574][10588][klog] INFO: [ OK ] ParseElement.CountryTooShort (0 ms) [00013.260429][10574][10588][klog] INFO: [ RUN ] ParseElement.ExtendedSupportedRates [00013.260429][10574][10588][klog] INFO: [ OK ] ParseElement.ExtendedSupportedRates (0 ms) [00013.260430][10574][10588][klog] INFO: [ RUN ] ParseElement.ExtendedSupportedRatesEmpty [00013.260430][10574][10588][klog] INFO: [ OK ] ParseElement.ExtendedSupportedRatesEmpty (0 ms) [00013.260430][10574][10588][klog] INFO: [ RUN ] ParseElement.MeshConfiguration [00013.260431][10574][10588][klog] INFO: [ OK ] ParseElement.MeshConfiguration (0 ms) [00013.260431][10574][10588][klog] INFO: [ RUN ] ParseElement.MeshConfigurationTooShort [00013.263592][10574][10588][klog] INFO: [ OK ] ParseElement.MeshConfigurationTooShort (0 ms) [00013.263620][10574][10588][klog] INFO: [ RUN ] ParseElement.MeshConfigurationTooLong [00013.263621][10574][10588][klog] INFO: [ OK ] ParseElement.MeshConfigurationTooLong (0 ms) [00013.263621][10574][10588][klog] INFO: [ RUN ] ParseElement.MeshId [00013.263622][10574][10588][klog] INFO: [ OK ] ParseElement.MeshId (0 ms) [00013.263623][10574][10588][klog] INFO: [ RUN ] ParseElement.MeshIdTooLong [00013.263623][10574][10588][klog] INFO: [ OK ] ParseElement.MeshIdTooLong (0 ms) [00013.263624][10574][10588][klog] INFO: [ RUN ] ParseElement.QosCapability [00013.263624][10574][10588][klog] INFO: [ OK ] ParseElement.QosCapability (0 ms) [00013.263625][10574][10588][klog] INFO: [ RUN ] ParseElement.QosCapabilityTooShort [00013.263625][10574][10588][klog] INFO: [ OK ] ParseElement.QosCapabilityTooShort (0 ms) [00013.263626][10574][10588][klog] INFO: [ RUN ] ParseElement.QosCapabilityTooLong [00013.263626][10574][10588][klog] INFO: [ OK ] ParseElement.QosCapabilityTooLong (0 ms) [00013.263626][10574][10588][klog] INFO: [ RUN ] ParseElement.GcrGroupAddress [00013.263627][10574][10588][klog] INFO: [ OK ] ParseElement.GcrGroupAddress (0 ms) [00013.263627][10574][10588][klog] INFO: [ RUN ] ParseElement.GcrGroupAddressTooShort [00013.263628][10574][10588][klog] INFO: [ OK ] ParseElement.GcrGroupAddressTooShort (0 ms) [00013.263628][10574][10588][klog] INFO: [ RUN ] ParseElement.GcrGroupAddressTooLong [00013.263629][10574][10588][klog] INFO: [ OK ] ParseElement.GcrGroupAddressTooLong (0 ms) [00013.263629][10574][10588][klog] INFO: [ RUN ] ParseElement.HtCapabilities [00013.263633][10574][10588][klog] INFO: [ OK ] ParseElement.HtCapabilities (0 ms) [00013.263639][10574][10588][klog] INFO: [ RUN ] ParseElement.HtCapabilitiesTooShort [00013.263639][10574][10588][klog] INFO: [ OK ] ParseElement.HtCapabilitiesTooShort (0 ms) [00013.263640][10574][10588][klog] INFO: [ RUN ] ParseElement.HtCapabilitiesTooLong [00013.263640][10574][10588][klog] INFO: [ OK ] ParseElement.HtCapabilitiesTooLong (0 ms) [00013.263640][10574][10588][klog] INFO: [ RUN ] ParseElement.HtOperation [00013.263641][10574][10588][klog] INFO: [ OK ] ParseElement.HtOperation (0 ms) [00013.263641][10574][10588][klog] INFO: [ RUN ] ParseElement.HtOperationTooShort [00013.263641][10574][10588][klog] INFO: [ OK ] ParseElement.HtOperationTooShort (0 ms) [00013.263642][10574][10588][klog] INFO: [ RUN ] ParseElement.HtOperationTooLong [00013.263642][10574][10588][klog] INFO: [ OK ] ParseElement.HtOperationTooLong (0 ms) [00013.263643][10574][10588][klog] INFO: [ RUN ] ParseElement.VhtCapabilities [00013.263643][10574][10588][klog] INFO: [ OK ] ParseElement.VhtCapabilities (0 ms) [00013.263643][10574][10588][klog] INFO: [ RUN ] ParseElement.VhtCapabilitiesTooShort [00013.263644][10574][10588][klog] INFO: [ OK ] ParseElement.VhtCapabilitiesTooShort (0 ms) [00013.263646][10574][10588][klog] INFO: [ RUN ] ParseElement.VhtCapabilitiesTooLong [00013.263646][10574][10588][klog] INFO: [ OK ] ParseElement.VhtCapabilitiesTooLong (0 ms) [00013.263647][10574][10588][klog] INFO: [ RUN ] ParseElement.VhtOperation [00013.263647][10574][10588][klog] INFO: [ OK ] ParseElement.VhtOperation (0 ms) [00013.263648][10574][10588][klog] INFO: [ RUN ] ParseElement.VhtOperationTooShort [00013.263648][10574][10588][klog] INFO: [ OK ] ParseElement.VhtOperationTooShort (0 ms) [00013.263649][10574][10588][klog] INFO: [ RUN ] ParseElement.VhtOperationTooLong [00013.265247][10574][10588][klog] INFO: [ OK ] ParseElement.VhtOperationTooLong (0 ms) [00013.265248][10574][10588][klog] INFO: [ RUN ] ParseElement.MpmOpenBad [00013.265248][10574][10588][klog] INFO: [ OK ] ParseElement.MpmOpenBad (0 ms) [00013.265249][10574][10588][klog] INFO: [ RUN ] ParseElement.MpmOpenGoodNoPmk [00013.265249][10574][10588][klog] INFO: [ OK ] ParseElement.MpmOpenGoodNoPmk (0 ms) [00013.265250][10574][10588][klog] INFO: [ RUN ] ParseElement.MpmOpenGoodWithPmk [00013.265251][10574][10588][klog] INFO: [ OK ] ParseElement.MpmOpenGoodWithPmk (0 ms) [00013.265252][10574][10588][klog] INFO: [ RUN ] ParseElement.MpmConfirmBad [00013.265253][10574][10588][klog] INFO: [ OK ] ParseElement.MpmConfirmBad (0 ms) [00013.265253][10574][10588][klog] INFO: [ RUN ] ParseElement.MpmConfirmGoodNoPmk [00013.265254][10574][10588][klog] INFO: [ OK ] ParseElement.MpmConfirmGoodNoPmk (0 ms) [00013.265254][10574][10588][klog] INFO: [ RUN ] ParseElement.MpmConfirmGoodWithPmk [00013.265255][10574][10588][klog] INFO: [ OK ] ParseElement.MpmConfirmGoodWithPmk (0 ms) [00013.265255][10574][10588][klog] INFO: [ RUN ] ParseElement.MpmCloseBad [00013.265256][10574][10588][klog] INFO: [ OK ] ParseElement.MpmCloseBad (0 ms) [00013.265256][10574][10588][klog] INFO: [ RUN ] ParseElement.MpmCloseGoodNoLinkIdNoPmk [00013.265256][10574][10588][klog] INFO: [ OK ] ParseElement.MpmCloseGoodNoLinkIdNoPmk (0 ms) [00013.265257][10574][10588][klog] INFO: [ RUN ] ParseElement.MpmCloseGoodWithLinkIdNoPmk [00013.265257][10574][10588][klog] INFO: [ OK ] ParseElement.MpmCloseGoodWithLinkIdNoPmk (0 ms) [00013.265259][10574][10588][klog] INFO: [ RUN ] ParseElement.MpmCloseGoodNoLinkIdWithPmk [00013.265259][10574][10588][klog] INFO: [ OK ] ParseElement.MpmCloseGoodNoLinkIdWithPmk (0 ms) [00013.265262][10574][10588][klog] INFO: [ RUN ] ParseElement.MpmCloseGoodWithLinkIdWithPmk [00013.265262][10574][10588][klog] INFO: [ OK ] ParseElement.MpmCloseGoodWithLinkIdWithPmk (0 ms) [00013.265263][10574][10588][klog] INFO: [ RUN ] ParseElement.PreqMinimal [00013.265263][10574][10588][klog] INFO: [ OK ] ParseElement.PreqMinimal (0 ms) [00013.265264][10574][10588][klog] INFO: [ RUN ] ParseElement.PreqFull [00013.265264][10574][10588][klog] INFO: [ OK ] ParseElement.PreqFull (0 ms) [00013.265264][10574][10588][klog] INFO: [ RUN ] ParseElement.PreqTooLong [00013.265265][10574][10588][klog] INFO: [ OK ] ParseElement.PreqTooLong (0 ms) [00013.265265][10574][10588][klog] INFO: [ RUN ] ParseElement.PreqTooShort_Header [00013.265266][10574][10588][klog] INFO: [ OK ] ParseElement.PreqTooShort_Header (0 ms) [00013.265266][10574][10588][klog] INFO: [ RUN ] ParseElement.PreqTooShort_OrigExtAddr [00013.265266][10574][10588][klog] INFO: [ OK ] ParseElement.PreqTooShort_OrigExtAddr (0 ms) [00013.265273][10574][10588][klog] INFO: [ RUN ] ParseElement.PreqTooShort_Middle [00013.265273][10574][10588][klog] INFO: [ OK ] ParseElement.PreqTooShort_Middle (0 ms) [00013.265273][10574][10588][klog] INFO: [ RUN ] ParseElement.PreqTooShort_PerTarget [00013.265274][10574][10588][klog] INFO: [ OK ] ParseElement.PreqTooShort_PerTarget (0 ms) [00013.265275][10574][10588][klog] INFO: [ RUN ] ParseElement.PrepNoExtAddr [00013.265275][10574][10588][klog] INFO: [ OK ] ParseElement.PrepNoExtAddr (0 ms) [00013.265276][10574][10588][klog] INFO: [ RUN ] ParseElement.PrepWithExtAddr [00013.265277][10574][10588][klog] INFO: [ OK ] ParseElement.PrepWithExtAddr (0 ms) [00013.265277][10574][10588][klog] INFO: [ RUN ] ParseElement.PrepTooShort_Header [00013.266066][10574][10588][klog] INFO: [ OK ] ParseElement.PrepTooShort_Header (0 ms) [00013.266090][10574][10588][klog] INFO: [ RUN ] ParseElement.PrepTooShort_Tail [00013.266092][10574][10588][klog] INFO: [ OK ] ParseElement.PrepTooShort_Tail (0 ms) [00013.266093][10574][10588][klog] INFO: [ RUN ] ParseElement.PrepTooShort_ExtAddr [00013.266094][10574][10588][klog] INFO: [ OK ] ParseElement.PrepTooShort_ExtAddr (0 ms) [00013.266095][10574][10588][klog] INFO: [ RUN ] ParseElement.PrepTooLong [00013.266096][10574][10588][klog] INFO: [ OK ] ParseElement.PrepTooLong (0 ms) [00013.266097][10574][10588][klog] INFO: [ RUN ] ParseElement.Perr [00013.266097][10574][10588][klog] INFO: [ OK ] ParseElement.Perr (0 ms) [00013.266099][10574][10588][klog] INFO: [ RUN ] ParseElement.PerrNoDestinationData [00013.266099][10574][10588][klog] INFO: [ OK ] ParseElement.PerrNoDestinationData (0 ms) [00013.266100][10574][10588][klog] INFO: [ RUN ] ParseElement.PerrTooShort [00013.266100][10574][10588][klog] INFO: [ OK ] ParseElement.PerrTooShort (0 ms) [00013.266101][10574][10588][klog] INFO: [----------] 70 tests from ParseElement (1 ms total) [00013.266101][10574][10588][klog] INFO: [00013.266102][10574][10588][klog] INFO: [----------] 6 tests from ParseDataFrameHeader [00013.266102][10574][10588][klog] INFO: [ RUN ] ParseDataFrameHeader.Minimal [00013.266104][10574][10588][klog] INFO: [ OK ] ParseDataFrameHeader.Minimal (0 ms) [00013.266105][10574][10588][klog] INFO: [ RUN ] ParseDataFrameHeader.Full [00013.266106][10574][10588][klog] INFO: [ OK ] ParseDataFrameHeader.Full (0 ms) [00013.266107][10574][10588][klog] INFO: [ RUN ] ParseDataFrameHeader.FixedPartTooShort [00013.266107][10574][10588][klog] INFO: [ OK ] ParseDataFrameHeader.FixedPartTooShort (0 ms) [00013.266107][10574][10588][klog] INFO: [ RUN ] ParseDataFrameHeader.Addr4TooShort [00013.266111][10574][10588][klog] INFO: [ OK ] ParseDataFrameHeader.Addr4TooShort (0 ms) [00013.266112][10574][10588][klog] INFO: [ RUN ] ParseDataFrameHeader.QosControlTooShort [00013.266113][10574][10588][klog] INFO: [ OK ] ParseDataFrameHeader.QosControlTooShort (0 ms) [00013.266114][10574][10588][klog] INFO: [ RUN ] ParseDataFrameHeader.HtControlTooShort [00013.266136][10574][10588][klog] INFO: [ OK ] ParseDataFrameHeader.HtControlTooShort (0 ms) [00013.266138][10574][10588][klog] INFO: [----------] 6 tests from ParseDataFrameHeader (0 ms total) [00013.266139][10574][10588][klog] INFO: [00013.266139][10574][10588][klog] INFO: [----------] 10 tests from ParseMeshDataHeader [00013.266140][10574][10588][klog] INFO: [ RUN ] ParseMeshDataHeader.NoAddrExt [00013.266140][10574][10588][klog] INFO: [ OK ] ParseMeshDataHeader.NoAddrExt (0 ms) [00013.266141][10574][10588][klog] INFO: [ RUN ] ParseMeshDataHeader.Addr4Ext [00013.266142][10574][10588][klog] INFO: [ OK ] ParseMeshDataHeader.Addr4Ext (0 ms) [00013.266143][10574][10588][klog] INFO: [ RUN ] ParseMeshDataHeader.Addr56Ext [00013.266143][10574][10588][klog] INFO: [ OK ] ParseMeshDataHeader.Addr56Ext (0 ms) [00013.266144][10574][10588][klog] INFO: [ RUN ] ParseMeshDataHeader.TooShort_MacHeader [00013.266144][10574][10588][klog] INFO: [ OK ] ParseMeshDataHeader.TooShort_MacHeader (0 ms) [00013.266145][10574][10588][klog] INFO: [ RUN ] ParseMeshDataHeader.TooShort_MeshControl [00013.266146][10574][10588][klog] INFO: [ OK ] ParseMeshDataHeader.TooShort_MeshControl (0 ms) [00013.266146][10574][10588][klog] INFO: [ RUN ] ParseMeshDataHeader.TooShort_AddrExt [00013.266147][10574][10588][klog] INFO: [ OK ] ParseMeshDataHeader.TooShort_AddrExt (0 ms) [00013.266147][10574][10588][klog] INFO: [ RUN ] ParseMeshDataHeader.TooShort_Llc [00013.266259][10574][10588][klog] INFO: [ OK ] ParseMeshDataHeader.TooShort_Llc (0 ms) [00013.266287][10574][10588][klog] INFO: [ RUN ] ParseMeshDataHeader.MissingQosBit [00013.266288][10574][10588][klog] INFO: [ OK ] ParseMeshDataHeader.MissingQosBit (0 ms) [00013.266289][10574][10588][klog] INFO: [ RUN ] ParseMeshDataHeader.MissingMeshControlPresentBit [00013.266290][10574][10588][klog] INFO: [ OK ] ParseMeshDataHeader.MissingMeshControlPresentBit (0 ms) [00013.266292][10574][10588][klog] INFO: [ RUN ] ParseMeshDataHeader.InvalidAddrExt [00013.266292][10574][10588][klog] INFO: [ OK ] ParseMeshDataHeader.InvalidAddrExt (0 ms) [00013.266293][10574][10588][klog] INFO: [----------] 10 tests from ParseMeshDataHeader (0 ms total) [00013.266296][10574][10588][klog] INFO: [00013.266297][10574][10588][klog] INFO: [----------] 5 tests from PerrDestinationParser [00013.266298][10574][10588][klog] INFO: [ RUN ] PerrDestinationParser.Empty [00013.266300][10574][10588][klog] INFO: [ OK ] PerrDestinationParser.Empty (0 ms) [00013.266301][10574][10588][klog] INFO: [ RUN ] PerrDestinationParser.TwoDestinations [00013.266301][10574][10588][klog] INFO: [ OK ] PerrDestinationParser.TwoDestinations (0 ms) [00013.266302][10574][10588][klog] INFO: [ RUN ] PerrDestinationParser.TooShortForHeader [00013.266303][10574][10588][klog] INFO: [ OK ] PerrDestinationParser.TooShortForHeader (0 ms) [00013.266304][10574][10588][klog] INFO: [ RUN ] PerrDestinationParser.TooShortForExtAddr [00013.266305][10574][10588][klog] INFO: [ OK ] PerrDestinationParser.TooShortForExtAddr (0 ms) [00013.266306][10574][10588][klog] INFO: [ RUN ] PerrDestinationParser.TooShortForTail [00013.266307][10574][10588][klog] INFO: [ OK ] PerrDestinationParser.TooShortForTail (0 ms) [00013.266310][10574][10588][klog] INFO: [----------] 5 tests from PerrDestinationParser (0 ms total) [00013.266311][10574][10588][klog] INFO: [00013.266311][10574][10588][klog] INFO: [----------] 2 tests from RateLimiter [00013.266312][10574][10588][klog] INFO: [ RUN ] RateLimiter.SingleEvent [00013.266313][10574][10588][klog] INFO: [ OK ] RateLimiter.SingleEvent (0 ms) [00013.266314][10574][10588][klog] INFO: [ RUN ] RateLimiter.TwoEvents [00013.266314][10574][10588][klog] INFO: [ OK ] RateLimiter.TwoEvents (0 ms) [00013.266315][10574][10588][klog] INFO: [----------] 2 tests from RateLimiter (0 ms total) [00013.266315][10574][10588][klog] INFO: [00013.266316][10574][10588][klog] INFO: [----------] 4 tests from TimElement [00013.266317][10574][10588][klog] INFO: [ RUN ] TimElement.IsTrafficBuffered [00013.266318][10574][10588][klog] INFO: [ OK ] TimElement.IsTrafficBuffered (0 ms) [00013.266318][10574][10588][klog] INFO: [ RUN ] TimElement.FindAndParseOk [00013.266319][10574][10588][klog] INFO: [ OK ] TimElement.FindAndParseOk (0 ms) [00013.266320][10574][10588][klog] INFO: [ RUN ] TimElement.FindAndParseAbsent [00013.266331][10574][10588][klog] INFO: [ OK ] TimElement.FindAndParseAbsent (0 ms) [00013.266331][10574][10588][klog] INFO: [ RUN ] TimElement.FindAndParseInvalid [00013.266332][10574][10588][klog] INFO: [ OK ] TimElement.FindAndParseInvalid (0 ms) [00013.266333][10574][10588][klog] INFO: [----------] 4 tests from TimElement (0 ms total) [00013.266334][10574][10588][klog] INFO: [00013.266335][10574][10588][klog] INFO: [----------] 11 tests from TimTest [00013.266336][10574][10588][klog] INFO: [ RUN ] TimTest.Initialization [00013.266336][10574][10588][klog] INFO: [ OK ] TimTest.Initialization (0 ms) [00013.266337][10574][10588][klog] INFO: [ RUN ] TimTest.HasTraffic [00013.266338][10574][10588][klog] INFO: [ OK ] TimTest.HasTraffic (0 ms) [00013.266338][10574][10588][klog] INFO: [ RUN ] TimTest.ClearTrafficIndication [00013.267047][10574][10588][klog] INFO: [ OK ] TimTest.ClearTrafficIndication (0 ms) [00013.267048][10574][10588][klog] INFO: [ RUN ] TimTest.ClearTraffic [00013.267049][10574][10588][klog] INFO: [ OK ] TimTest.ClearTraffic (0 ms) [00013.267049][10574][10588][klog] INFO: [ RUN ] TimTest.WriteEmptyPartialVirtualBitmap [00013.267050][10574][10588][klog] INFO: [ OK ] TimTest.WriteEmptyPartialVirtualBitmap (0 ms) [00013.267050][10574][10588][klog] INFO: [ RUN ] TimTest.WriteNoOffsetPopulatedPartialVirtualBitmap [00013.267051][10574][10588][klog] INFO: [ OK ] TimTest.WriteNoOffsetPopulatedPartialVirtualBitmap (0 ms) [00013.267051][10574][10588][klog] INFO: [ RUN ] TimTest.WriteOffsetPopulatedPartialVirtualBitmap [00013.267052][10574][10588][klog] INFO: [ OK ] TimTest.WriteOffsetPopulatedPartialVirtualBitmap (0 ms) [00013.267052][10574][10588][klog] INFO: [ RUN ] TimTest.WriteChangingPartialVirtualBitmap [00013.267052][10574][10588][klog] INFO: [ OK ] TimTest.WriteChangingPartialVirtualBitmap (0 ms) [00013.267053][10574][10588][klog] INFO: [ RUN ] TimTest.WriteLastBytePartialVirtualBitmap [00013.267053][10574][10588][klog] INFO: [ OK ] TimTest.WriteLastBytePartialVirtualBitmap (0 ms) [00013.267054][10574][10588][klog] INFO: [ RUN ] TimTest.WriteMaxSizedPartialVirtualBitmap [00013.267054][10574][10588][klog] INFO: [ OK ] TimTest.WriteMaxSizedPartialVirtualBitmap (0 ms) [00013.267056][10574][10588][klog] INFO: [ RUN ] TimTest.IgnoreGroupTrafficInPartialVirtualBitmap [00013.267057][10574][10588][klog] INFO: [ OK ] TimTest.IgnoreGroupTrafficInPartialVirtualBitmap (0 ms) [00013.267057][10574][10588][klog] INFO: [----------] 11 tests from TimTest (0 ms total) [00013.267058][10574][10588][klog] INFO: [00013.267060][10574][10588][klog] INFO: [----------] 2 tests from TxVectorIndexTest [00013.267060][10574][10588][klog] INFO: [ RUN ] TxVectorIndexTest.TxVectorMapping [00013.267061][10574][10588][klog] INFO: [ OK ] TxVectorIndexTest.TxVectorMapping (0 ms) [00013.267061][10574][10588][klog] INFO: [ RUN ] TxVectorIndexTest.NotUsedParam [00013.267062][10574][10588][klog] INFO: [ OK ] TxVectorIndexTest.NotUsedParam (0 ms) [00013.267062][10574][10588][klog] INFO: [----------] 2 tests from TxVectorIndexTest (0 ms total) [00013.267062][10574][10588][klog] INFO: [00013.267063][10574][10588][klog] INFO: [----------] 1 test from TxVectorTest [00013.267063][10574][10588][klog] INFO: [ RUN ] TxVectorTest.ValidIdx [00013.267064][10574][10588][klog] INFO: [ OK ] TxVectorTest.ValidIdx (0 ms) [00013.267064][10574][10588][klog] INFO: [----------] 1 test from TxVectorTest (0 ms total) [00013.267064][10574][10588][klog] INFO: [00013.267065][10574][10588][klog] INFO: [----------] 1 test from ErpRateTest [00013.267065][10574][10588][klog] INFO: [ RUN ] ErpRateTest.ErpRateToTxVector [00013.267066][10574][10588][klog] INFO: [ OK ] ErpRateTest.ErpRateToTxVector (0 ms) [00013.267066][10574][10588][klog] INFO: [----------] 1 test from ErpRateTest (0 ms total) [00013.267067][10574][10588][klog] INFO: [00013.267067][10574][10588][klog] INFO: [----------] 1 test from DebugTest [00013.267068][10574][10588][klog] INFO: [ RUN ] DebugTest.DebugStringVisualInspection [00013.267068][10574][10588][klog] INFO: [ OK ] DebugTest.DebugStringVisualInspection (1 ms) [00013.267069][10574][10588][klog] INFO: [----------] 1 test from DebugTest (1 ms total) [00013.267069][10574][10588][klog] INFO: [00013.267069][10574][10588][klog] INFO: [----------] 25 tests from WriteElement [00013.267070][10574][10588][klog] INFO: [ RUN ] WriteElement.Ssid [00013.267070][10574][10588][klog] INFO: [ OK ] WriteElement.Ssid (0 ms) [00013.267071][10574][10588][klog] INFO: [ RUN ] WriteElement.SupportedRates [00013.267071][10574][10588][klog] INFO: [ OK ] WriteElement.SupportedRates (0 ms) [00013.274110][10574][10588][klog] INFO: [ RUN ] WriteElement.DsssParamSet [00013.274113][10574][10588][klog] INFO: [ OK ] WriteElement.DsssParamSet (0 ms) [00013.274113][10574][10588][klog] INFO: [ RUN ] WriteElement.CfParamSet [00013.274114][10574][10588][klog] INFO: [ OK ] WriteElement.CfParamSet (0 ms) [00013.274114][10574][10588][klog] INFO: [ RUN ] WriteElement.Tim [00013.274115][10574][10588][klog] INFO: [ OK ] WriteElement.Tim (0 ms) [00013.274115][10574][10588][klog] INFO: [ RUN ] WriteElement.CountryPadded [00013.274116][10574][10588][klog] INFO: [ OK ] WriteElement.CountryPadded (0 ms) [00013.274116][10574][10588][klog] INFO: [ RUN ] WriteElement.CountryUnpadded [00013.274117][10574][10588][klog] INFO: [ OK ] WriteElement.CountryUnpadded (0 ms) [00013.274117][10574][10588][klog] INFO: [ RUN ] WriteElement.ExtendedSupportedRates [00013.274118][10574][10588][klog] INFO: [ OK ] WriteElement.ExtendedSupportedRates (0 ms) [00013.274118][10574][10588][klog] INFO: [ RUN ] WriteElement.MeshConfiguration [00013.274118][10574][10588][klog] INFO: [ OK ] WriteElement.MeshConfiguration (0 ms) [00013.274119][10574][10588][klog] INFO: [ RUN ] WriteElement.MeshId [00013.274167][10574][10588][klog] INFO: [ OK ] WriteElement.MeshId (0 ms) [00013.274168][10574][10588][klog] INFO: [ RUN ] WriteElement.QosCapability [00013.274168][10574][10588][klog] INFO: [ OK ] WriteElement.QosCapability (0 ms) [00013.274168][10574][10588][klog] INFO: [ RUN ] WriteElement.GcrGroupAddress [00013.274169][10574][10588][klog] INFO: [ OK ] WriteElement.GcrGroupAddress (0 ms) [00013.274169][10574][10588][klog] INFO: [ RUN ] WriteElement.HtCapabilities [00013.274169][10574][10588][klog] INFO: [ OK ] WriteElement.HtCapabilities (0 ms) [00013.274170][10574][10588][klog] INFO: [ RUN ] WriteElement.HtOperation [00013.274170][10574][10588][klog] INFO: [ OK ] WriteElement.HtOperation (0 ms) [00013.274178][10574][10588][klog] INFO: [ RUN ] WriteElement.VhtCapabilities [00013.274178][10574][10588][klog] INFO: [ OK ] WriteElement.VhtCapabilities (0 ms) [00013.274179][10574][10588][klog] INFO: [ RUN ] WriteElement.VhtOperation [00013.274179][10574][10588][klog] INFO: [ OK ] WriteElement.VhtOperation (0 ms) [00013.274180][10574][10588][klog] INFO: [ RUN ] WriteElement.MpmOpenNoPmk [00013.274181][10574][10588][klog] INFO: [ OK ] WriteElement.MpmOpenNoPmk (0 ms) [00013.274181][10574][10588][klog] INFO: [ RUN ] WriteElement.MpmOpenWithPmk [00013.274182][10574][10588][klog] INFO: [ OK ] WriteElement.MpmOpenWithPmk (0 ms) [00013.274182][10574][10588][klog] INFO: [ RUN ] WriteElement.MpmConfirmNoPmk [00013.274182][10574][10588][klog] INFO: [ OK ] WriteElement.MpmConfirmNoPmk (0 ms) [00013.274183][10574][10588][klog] INFO: [ RUN ] WriteElement.MpmConfirmWithPmk [00013.274183][10574][10588][klog] INFO: [ OK ] WriteElement.MpmConfirmWithPmk (0 ms) [00013.274184][10574][10588][klog] INFO: [ RUN ] WriteElement.PreqMinimal [00013.274184][10574][10588][klog] INFO: [ OK ] WriteElement.PreqMinimal (0 ms) [00013.274184][10574][10588][klog] INFO: [ RUN ] WriteElement.PreqFull [00013.274185][10574][10588][klog] INFO: [ OK ] WriteElement.PreqFull (1 ms) [00013.274185][10574][10588][klog] INFO: [ RUN ] WriteElement.PrepNoExtAddr [00013.274185][10574][10588][klog] INFO: [ OK ] WriteElement.PrepNoExtAddr (0 ms) [00013.274186][10574][10588][klog] INFO: [ RUN ] WriteElement.PrepWithExtAddr [00013.274187][10574][10588][klog] INFO: [ OK ] WriteElement.PrepWithExtAddr (0 ms) [00013.274188][10574][10588][klog] INFO: [ RUN ] WriteElement.Perr [00013.274188][10574][10588][klog] INFO: [ OK ] WriteElement.Perr (0 ms) [00013.274189][10574][10588][klog] INFO: [----------] 25 tests from WriteElement (1 ms total) [00013.274189][10574][10588][klog] INFO: [00013.278639][10574][10588][klog] INFO: [----------] Global test environment tear-down [00013.278664][10574][10588][klog] INFO: [==========] 254 tests from 36 test suites ran. (28 ms total) [00013.278664][10574][10588][klog] INFO: [ PASSED ] 254 tests. [00013.279158][10574][10588][klog] INFO: PASSED: /pkgfs/packages/wlan_tests/0/test/wlan_mac_unittest passed [00013.541233][10574][10588][klog] INFO: [00013.541237][10574][10588][klog] INFO: ------------------------------------------------ [00013.541239][10574][10588][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan_tests/0/test/wlan_mesh_unittest [00013.541240][10574][10588][klog] INFO: [00013.555787][8113][8125][klog] INFO: mDNS: Using unique host name swarm-donut-petri-acre.local. [00013.687212][10574][10588][klog] INFO: [==========] Running 39 tests from 7 test suites. [00013.687233][10574][10588][klog] INFO: [----------] Global test environment set-up. [00013.687234][10574][10588][klog] INFO: [----------] 1 test from Hwmp [00013.687235][10574][10588][klog] INFO: [ RUN ] Hwmp.HwmpSeqnoLessThan [00013.687235][10574][10588][klog] INFO: [ OK ] Hwmp.HwmpSeqnoLessThan (0 ms) [00013.687236][10574][10588][klog] INFO: [----------] 1 test from Hwmp (0 ms total) [00013.687236][10574][10588][klog] INFO: [00013.687237][10574][10588][klog] INFO: [----------] 11 tests from HwmpTest [00013.687237][10574][10588][klog] INFO: [ RUN ] HwmpTest.HandlePreqAddressedToUs [00013.687238][10574][10588][klog] INFO: [ OK ] HwmpTest.HandlePreqAddressedToUs (0 ms) [00013.687238][10574][10588][klog] INFO: [ RUN ] HwmpTest.ForwardPreq [00013.687239][10574][10588][klog] INFO: [ OK ] HwmpTest.ForwardPreq (0 ms) [00013.687239][10574][10588][klog] INFO: [ RUN ] HwmpTest.ReplyToPreqOnBehalfOfAnotherNode [00013.687240][10574][10588][klog] INFO: [ OK ] HwmpTest.ReplyToPreqOnBehalfOfAnotherNode (0 ms) [00013.687240][10574][10588][klog] INFO: [ RUN ] HwmpTest.DontReplyToPreqOnBehalfOfAnotherNode [00013.687241][10574][10588][klog] INFO: [ OK ] HwmpTest.DontReplyToPreqOnBehalfOfAnotherNode (0 ms) [00013.687241][10574][10588][klog] INFO: [ RUN ] HwmpTest.PreqTimeToDie [00013.687241][10574][10588][klog] INFO: [ OK ] HwmpTest.PreqTimeToDie (0 ms) [00013.687242][10574][10588][klog] INFO: [ RUN ] HwmpTest.PathDiscoveryWithRetry [00013.687242][10574][10588][klog] INFO: [ OK ] HwmpTest.PathDiscoveryWithRetry (0 ms) [00013.687243][10574][10588][klog] INFO: [ RUN ] HwmpTest.ForwardPrep [00013.687243][10574][10588][klog] INFO: [ OK ] HwmpTest.ForwardPrep (0 ms) [00013.687244][10574][10588][klog] INFO: [ RUN ] HwmpTest.PrepTimeToDie [00013.689360][10574][10588][klog] INFO: [ OK ] HwmpTest.PrepTimeToDie (0 ms) [00013.689382][10574][10588][klog] INFO: [ RUN ] HwmpTest.HandlePerrDestinationUnreachable [00013.689383][10574][10588][klog] INFO: [ OK ] HwmpTest.HandlePerrDestinationUnreachable (0 ms) [00013.689384][10574][10588][klog] INFO: [ RUN ] HwmpTest.HandlePerrNoForwardingInfo [00013.689385][10574][10588][klog] INFO: [ OK ] HwmpTest.HandlePerrNoForwardingInfo (1 ms) [00013.689386][10574][10588][klog] INFO: [ RUN ] HwmpTest.PerrTimeToDie [00013.689386][10574][10588][klog] INFO: [ OK ] HwmpTest.PerrTimeToDie (0 ms) [00013.689387][10574][10588][klog] INFO: [----------] 11 tests from HwmpTest (1 ms total) [00013.689388][10574][10588][klog] INFO: [00013.689388][10574][10588][klog] INFO: [----------] 1 test from DeDuplicator [00013.689389][10574][10588][klog] INFO: [ RUN ] DeDuplicator.HandleUniquePackets [00013.689389][10574][10588][klog] INFO: [ OK ] DeDuplicator.HandleUniquePackets (0 ms) [00013.689389][10574][10588][klog] INFO: [----------] 1 test from DeDuplicator (0 ms total) [00013.689390][10574][10588][klog] INFO: [00013.689390][10574][10588][klog] INFO: [----------] 11 tests from MeshMlmeTest [00013.689391][10574][10588][klog] INFO: [ RUN ] MeshMlmeTest.JoinLeave [00013.689391][10574][10588][klog] INFO: [ OK ] MeshMlmeTest.JoinLeave (0 ms) [00013.689392][10574][10588][klog] INFO: [ RUN ] MeshMlmeTest.HandleMpmOpen [00013.689392][10574][10588][klog] INFO: [ OK ] MeshMlmeTest.HandleMpmOpen (0 ms) [00013.689393][10574][10588][klog] INFO: [ RUN ] MeshMlmeTest.HandleMpmConfirm [00013.689393][10574][10588][klog] INFO: [ OK ] MeshMlmeTest.HandleMpmConfirm (0 ms) [00013.689394][10574][10588][klog] INFO: [ RUN ] MeshMlmeTest.GetPathTable [00013.689394][10574][10588][klog] INFO: [ OK ] MeshMlmeTest.GetPathTable (0 ms) [00013.689394][10574][10588][klog] INFO: [ RUN ] MeshMlmeTest.DeliverProxiedData [00013.689398][10574][10588][klog] INFO: [ OK ] MeshMlmeTest.DeliverProxiedData (0 ms) [00013.689399][10574][10588][klog] INFO: [ RUN ] MeshMlmeTest.DoNotDeliverWhenNotJoined [00013.689399][10574][10588][klog] INFO: [ OK ] MeshMlmeTest.DoNotDeliverWhenNotJoined (0 ms) [00013.689400][10574][10588][klog] INFO: [ RUN ] MeshMlmeTest.HandlePreq [00013.689400][10574][10588][klog] INFO: [ OK ] MeshMlmeTest.HandlePreq (0 ms) [00013.689400][10574][10588][klog] INFO: [ RUN ] MeshMlmeTest.DeliverDuplicateData [00013.689401][10574][10588][klog] INFO: [ OK ] MeshMlmeTest.DeliverDuplicateData (1 ms) [00013.689401][10574][10588][klog] INFO: [ RUN ] MeshMlmeTest.DataForwarding [00013.689402][10574][10588][klog] INFO: [ OK ] MeshMlmeTest.DataForwarding (0 ms) [00013.689404][10574][10588][klog] INFO: [ RUN ] MeshMlmeTest.OutgoingData [00013.689404][10574][10588][klog] INFO: [ OK ] MeshMlmeTest.OutgoingData (0 ms) [00013.689405][10574][10588][klog] INFO: [ RUN ] MeshMlmeTest.GeneratePerrIfMissingForwardingPath [00013.689405][10574][10588][klog] INFO: [ OK ] MeshMlmeTest.GeneratePerrIfMissingForwardingPath (0 ms) [00013.689406][10574][10588][klog] INFO: [----------] 11 tests from MeshMlmeTest (1 ms total) [00013.689526][10574][10588][klog] INFO: [00013.689546][10574][10588][klog] INFO: [----------] 8 tests from ParseMpOpen [00013.689677][10574][10588][klog] INFO: [ RUN ] ParseMpOpen.Full [00013.689702][10574][10588][klog] INFO: [ OK ] ParseMpOpen.Full (0 ms) [00013.689703][10574][10588][klog] INFO: [ RUN ] ParseMpOpen.Minimal [00013.689703][10574][10588][klog] INFO: [ OK ] ParseMpOpen.Minimal (0 ms) [00013.689704][10574][10588][klog] INFO: [ RUN ] ParseMpOpen.EmptyMeshId [00013.689705][10574][10588][klog] INFO: [ OK ] ParseMpOpen.EmptyMeshId (0 ms) [00013.689706][10574][10588][klog] INFO: [ RUN ] ParseMpOpen.TooShort [00013.689709][10574][10588][klog] INFO: [ OK ] ParseMpOpen.TooShort (0 ms) [00013.693587][10574][10588][klog] INFO: [ RUN ] ParseMpOpen.MissingRates [00013.693631][10574][10588][klog] INFO: [ OK ] ParseMpOpen.MissingRates (0 ms) [00013.693784][10574][10588][klog] INFO: [ RUN ] ParseMpOpen.MissingMeshId [00013.693785][10574][10588][klog] INFO: [ OK ] ParseMpOpen.MissingMeshId (0 ms) [00013.693821][10574][10588][klog] INFO: [ RUN ] ParseMpOpen.MissingMeshConfig [00013.693822][10574][10588][klog] INFO: [ OK ] ParseMpOpen.MissingMeshConfig (0 ms) [00013.693849][10574][10588][klog] INFO: [ RUN ] ParseMpOpen.MissingMpm [00013.693853][10574][10588][klog] INFO: [ OK ] ParseMpOpen.MissingMpm (0 ms) [00013.693854][10574][10588][klog] INFO: [----------] 8 tests from ParseMpOpen (0 ms total) [00013.693854][10574][10588][klog] INFO: [00013.693855][10574][10588][klog] INFO: [----------] 5 tests from ParseMpConfirm [00013.693855][10574][10588][klog] INFO: [ RUN ] ParseMpConfirm.Full [00013.693856][10574][10588][klog] INFO: [ OK ] ParseMpConfirm.Full (0 ms) [00013.693856][10574][10588][klog] INFO: [ RUN ] ParseMpConfirm.Minimal [00013.693857][10574][10588][klog] INFO: [ OK ] ParseMpConfirm.Minimal (0 ms) [00013.693857][10574][10588][klog] INFO: [ RUN ] ParseMpConfirm.TooShortForCapabilityInfo [00013.693858][10574][10588][klog] INFO: [ OK ] ParseMpConfirm.TooShortForCapabilityInfo (0 ms) [00013.693858][10574][10588][klog] INFO: [ RUN ] ParseMpConfirm.TooShortForAid [00013.693858][10574][10588][klog] INFO: [ OK ] ParseMpConfirm.TooShortForAid (0 ms) [00013.693859][10574][10588][klog] INFO: [ RUN ] ParseMpConfirm.MissingMpm [00013.693859][10574][10588][klog] INFO: [ OK ] ParseMpConfirm.MissingMpm (0 ms) [00013.693860][10574][10588][klog] INFO: [----------] 5 tests from ParseMpConfirm (0 ms total) [00013.693860][10574][10588][klog] INFO: [00013.693860][10574][10588][klog] INFO: [----------] 2 tests from WriteMpAction [00013.693861][10574][10588][klog] INFO: [ RUN ] WriteMpAction.Open [00013.693861][10574][10588][klog] INFO: [ OK ] WriteMpAction.Open (0 ms) [00013.693867][10574][10588][klog] INFO: [ RUN ] WriteMpAction.Confirm [00013.693868][10574][10588][klog] INFO: [ OK ] WriteMpAction.Confirm (0 ms) [00013.693869][10574][10588][klog] INFO: [----------] 2 tests from WriteMpAction (0 ms total) [00013.693869][10574][10588][klog] INFO: [00013.693872][10574][10588][klog] INFO: [----------] Global test environment tear-down [00013.693873][10574][10588][klog] INFO: [==========] 39 tests from 7 test suites ran. (2 ms total) [00013.693874][10574][10588][klog] INFO: [ PASSED ] 39 tests. [00013.694621][10574][10588][klog] INFO: PASSED: /pkgfs/packages/wlan_tests/0/test/wlan_mesh_unittest passed [00013.934239][10574][10588][klog] INFO: [00013.934286][10574][10588][klog] INFO: ------------------------------------------------ [00013.934289][10574][10588][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan_tests/0/test/wlan_scanner_unittest [00013.934289][10574][10588][klog] INFO: [00014.069223][10574][10588][klog] INFO: [==========] Running 10 tests from 1 test suite. [00014.069273][10574][10588][klog] INFO: [----------] Global test environment set-up. [00014.069274][10574][10588][klog] INFO: [----------] 10 tests from ScannerTest [00014.069274][10574][10588][klog] INFO: [ RUN ] ScannerTest.Start [00014.069275][10574][10588][klog] INFO: [ OK ] ScannerTest.Start (0 ms) [00014.069276][10574][10588][klog] INFO: [ RUN ] ScannerTest.Start_InvalidChannelTimes [00014.069276][10574][10588][klog] INFO: [ OK ] ScannerTest.Start_InvalidChannelTimes (1 ms) [00014.069277][10574][10588][klog] INFO: [ RUN ] ScannerTest.Start_NoChannels [00014.069278][10574][10588][klog] INFO: [ OK ] ScannerTest.Start_NoChannels (0 ms) [00014.069279][10574][10588][klog] INFO: [ RUN ] ScannerTest.Reset [00014.069279][10574][10588][klog] INFO: [ OK ] ScannerTest.Reset (0 ms) [00014.069280][10574][10588][klog] INFO: [ RUN ] ScannerTest.ScanChannel [00014.069281][10574][10588][klog] INFO: [ OK ] ScannerTest.ScanChannel (0 ms) [00014.069281][10574][10588][klog] INFO: [ RUN ] ScannerTest.Timeout_NextChannel [00014.069282][10574][10588][klog] INFO: [ OK ] ScannerTest.Timeout_NextChannel (0 ms) [00014.069282][10574][10588][klog] INFO: [ RUN ] ScannerTest.PassiveScanning [00014.069283][10574][10588][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x729b2b5f, actual: 0x345b1b19 [00014.069283][10574][10588][klog] INFO: [ OK ] ScannerTest.PassiveScanning (0 ms) [00014.069284][10574][10588][klog] INFO: [ RUN ] ScannerTest.ActiveScanning [00014.069285][10574][10588][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x729b2b5f, actual: 0x345b1b19 [00014.069285][10574][10588][klog] INFO: [ OK ] ScannerTest.ActiveScanning (0 ms) [00014.069286][10574][10588][klog] INFO: [ RUN ] ScannerTest.BeaconFromHiddenAp [00014.071648][10574][10588][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x729b2b5f, actual: 0x345b1b19 [00014.071673][10574][10588][klog] INFO: [ OK ] ScannerTest.BeaconFromHiddenAp (0 ms) [00014.071677][10574][10588][klog] INFO: [ RUN ] ScannerTest.ActiveScanningWithProbeDelay [00014.071678][10574][10588][klog] INFO: [ OK ] ScannerTest.ActiveScanningWithProbeDelay (0 ms) [00014.071679][10574][10588][klog] INFO: [----------] 10 tests from ScannerTest (1 ms total) [00014.071679][10574][10588][klog] INFO: [00014.071680][10574][10588][klog] INFO: [----------] Global test environment tear-down [00014.071680][10574][10588][klog] INFO: [==========] 10 tests from 1 test suite ran. (1 ms total) [00014.071681][10574][10588][klog] INFO: [ PASSED ] 10 tests. [00014.072124][10574][10588][klog] INFO: PASSED: /pkgfs/packages/wlan_tests/0/test/wlan_scanner_unittest passed [00014.302528][10574][10588][klog] INFO: [00014.302554][10574][10588][klog] INFO: ------------------------------------------------ [00014.302557][10574][10588][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan_tests/0/test/wlan_service_unittest [00014.302558][10574][10588][klog] INFO: [00014.443618][10574][10588][klog] INFO: [==========] Running 7 tests from 2 test suites. [00014.443642][10574][10588][klog] INFO: [----------] Global test environment set-up. [00014.443643][10574][10588][klog] INFO: [----------] 4 tests from MlmeMsg [00014.443643][10574][10588][klog] INFO: [ RUN ] MlmeMsg.General [00014.443644][10574][10588][klog] INFO: [ OK ] MlmeMsg.General (0 ms) [00014.443644][10574][10588][klog] INFO: [ RUN ] MlmeMsg.Generalize [00014.443644][10574][10588][klog] INFO: [ OK ] MlmeMsg.Generalize (0 ms) [00014.443645][10574][10588][klog] INFO: [ RUN ] MlmeMsg.CorruptedPacket [00014.443645][10574][10588][klog] INFO: wlan: [E] could not decode received message: Buffer is too small for first inline object [00014.443646][10574][10588][klog] INFO: [ OK ] MlmeMsg.CorruptedPacket (0 ms) [00014.443646][10574][10588][klog] INFO: [ RUN ] MlmeMsg.MismatchingOrdinal [00014.443647][10574][10588][klog] INFO: wlan: [W] Mismatched ordinal: expected: 0x65335691, actual: 0x2a [00014.443647][10574][10588][klog] INFO: [ OK ] MlmeMsg.MismatchingOrdinal (0 ms) [00014.443648][10574][10588][klog] INFO: [----------] 4 tests from MlmeMsg (0 ms total) [00014.443648][10574][10588][klog] INFO: [00014.443649][10574][10588][klog] INFO: [----------] 3 tests from ServiceTest [00014.443649][10574][10588][klog] INFO: [ RUN ] ServiceTest.SendAuthInd [00014.443649][10574][10588][klog] INFO: [ OK ] ServiceTest.SendAuthInd (0 ms) [00014.443650][10574][10588][klog] INFO: [ RUN ] ServiceTest.SendAssocInd [00014.443650][10574][10588][klog] INFO: [ OK ] ServiceTest.SendAssocInd (0 ms) [00014.443651][10574][10588][klog] INFO: [ RUN ] ServiceTest.SendAssocInd_EmptyRsne [00014.443651][10574][10588][klog] INFO: [ OK ] ServiceTest.SendAssocInd_EmptyRsne (0 ms) [00014.443652][10574][10588][klog] INFO: [----------] 3 tests from ServiceTest (0 ms total) [00014.443652][10574][10588][klog] INFO: [00014.446177][10574][10588][klog] INFO: [----------] Global test environment tear-down [00014.446217][10574][10588][klog] INFO: [==========] 7 tests from 2 test suites ran. (0 ms total) [00014.446303][10574][10588][klog] INFO: [ PASSED ] 7 tests. [00014.446644][10574][10588][klog] INFO: PASSED: /pkgfs/packages/wlan_tests/0/test/wlan_service_unittest passed [00014.712316][10574][10588][klog] INFO: [00014.712357][10574][10588][klog] INFO: ------------------------------------------------ [00014.712360][10574][10588][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan_tests/0/test/wlan_timer_manager_unittest [00014.712360][10574][10588][klog] INFO: [00014.844601][10574][10588][klog] INFO: [==========] Running 8 tests from 1 test suite. [00014.844632][10574][10588][klog] INFO: [----------] Global test environment set-up. [00014.844634][10574][10588][klog] INFO: [----------] 8 tests from TimerManagerTest [00014.844634][10574][10588][klog] INFO: [ RUN ] TimerManagerTest.HandleTimeout [00014.844635][10574][10588][klog] INFO: [ OK ] TimerManagerTest.HandleTimeout (0 ms) [00014.844636][10574][10588][klog] INFO: [ RUN ] TimerManagerTest.CancelNearestEvent [00014.844636][10574][10588][klog] INFO: [ OK ] TimerManagerTest.CancelNearestEvent (0 ms) [00014.844637][10574][10588][klog] INFO: [ RUN ] TimerManagerTest.HandleLastTimeout [00014.844637][10574][10588][klog] INFO: [ OK ] TimerManagerTest.HandleLastTimeout (0 ms) [00014.844640][10574][10588][klog] INFO: [ RUN ] TimerManagerTest.SchedulingAtLaterTimeDoesNotResetTimer [00014.844641][10574][10588][klog] INFO: [ OK ] TimerManagerTest.SchedulingAtLaterTimeDoesNotResetTimer (0 ms) [00014.844641][10574][10588][klog] INFO: [ RUN ] TimerManagerTest.SchedulingAtEarlierTimeResetsTimer [00014.844642][10574][10588][klog] INFO: [ OK ] TimerManagerTest.SchedulingAtEarlierTimeResetsTimer (0 ms) [00014.844642][10574][10588][klog] INFO: [ RUN ] TimerManagerTest.ScheduleAnotherTimeoutInCallback [00014.844643][10574][10588][klog] INFO: [ OK ] TimerManagerTest.ScheduleAnotherTimeoutInCallback (0 ms) [00014.844644][10574][10588][klog] INFO: [ RUN ] TimerManagerTest.EventsWithSameDeadlineReportedInSchedulingOrder [00014.844644][10574][10588][klog] INFO: [ OK ] TimerManagerTest.EventsWithSameDeadlineReportedInSchedulingOrder (0 ms) [00014.845573][10574][10588][klog] INFO: [ RUN ] TimerManagerTest.CancelAll [00014.845574][10574][10588][klog] INFO: [ OK ] TimerManagerTest.CancelAll (0 ms) [00014.845575][10574][10588][klog] INFO: [----------] 8 tests from TimerManagerTest (0 ms total) [00014.845576][10574][10588][klog] INFO: [00014.845576][10574][10588][klog] INFO: [----------] Global test environment tear-down [00014.845577][10574][10588][klog] INFO: [==========] 8 tests from 1 test suite ran. (0 ms total) [00014.845577][10574][10588][klog] INFO: [ PASSED ] 8 tests. [00014.846193][10574][10588][klog] INFO: PASSED: /pkgfs/packages/wlan_tests/0/test/wlan_timer_manager_unittest passed [00015.084873][10574][10588][klog] INFO: [00015.084918][10574][10588][klog] INFO: ------------------------------------------------ [00015.084922][10574][10588][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan_tests/0/test/wlan_common_unittest [00015.084922][10574][10588][klog] INFO: [00015.215262][10574][10588][klog] INFO: [==========] Running 34 tests from 8 test suites. [00015.215295][10574][10588][klog] INFO: [----------] Global test environment set-up. [00015.215296][10574][10588][klog] INFO: [----------] 7 tests from ChannelSchedulerTest [00015.215297][10574][10588][klog] INFO: [ RUN ] ChannelSchedulerTest.OnChannelFrame [00015.215298][10574][10588][klog] INFO: [ OK ] ChannelSchedulerTest.OnChannelFrame (0 ms) [00015.215298][10574][10588][klog] INFO: [ RUN ] ChannelSchedulerTest.RequestOffChannelTime [00015.215299][10574][10588][klog] INFO: [ OK ] ChannelSchedulerTest.RequestOffChannelTime (0 ms) [00015.215299][10574][10588][klog] INFO: [ RUN ] ChannelSchedulerTest.RequestOffChannelTimeChained [00015.215301][10574][10588][klog] INFO: [ OK ] ChannelSchedulerTest.RequestOffChannelTimeChained (0 ms) [00015.215301][10574][10588][klog] INFO: [ RUN ] ChannelSchedulerTest.SetChannelSwitchesWhenOnChannel [00015.215302][10574][10588][klog] INFO: [ OK ] ChannelSchedulerTest.SetChannelSwitchesWhenOnChannel (0 ms) [00015.215302][10574][10588][klog] INFO: [ RUN ] ChannelSchedulerTest.SetChannelDoesNotSwitchWhenOffChannel [00015.215303][10574][10588][klog] INFO: [ OK ] ChannelSchedulerTest.SetChannelDoesNotSwitchWhenOffChannel (0 ms) [00015.215304][10574][10588][klog] INFO: [ RUN ] ChannelSchedulerTest.EnsureOnChannelDelaysOffChannelRequest [00015.215304][10574][10588][klog] INFO: [ OK ] ChannelSchedulerTest.EnsureOnChannelDelaysOffChannelRequest (0 ms) [00015.215305][10574][10588][klog] INFO: [ RUN ] ChannelSchedulerTest.EnsureOnChannelCancelsOffChannelRequest [00015.216117][10574][10588][klog] INFO: [ OK ] ChannelSchedulerTest.EnsureOnChannelCancelsOffChannelRequest (0 ms) [00015.216119][10574][10588][klog] INFO: [----------] 7 tests from ChannelSchedulerTest (0 ms total) [00015.216119][10574][10588][klog] INFO: [00015.216171][10574][10588][klog] INFO: [----------] 2 tests from FindBandByChannel [00015.216171][10574][10588][klog] INFO: [ RUN ] FindBandByChannel.OneBands [00015.216172][10574][10588][klog] INFO: [ OK ] FindBandByChannel.OneBands (0 ms) [00015.216173][10574][10588][klog] INFO: [ RUN ] FindBandByChannel.TwoBands [00015.216174][10574][10588][klog] INFO: [ OK ] FindBandByChannel.TwoBands (0 ms) [00015.216174][10574][10588][klog] INFO: [----------] 2 tests from FindBandByChannel (0 ms total) [00015.216175][10574][10588][klog] INFO: [00015.216175][10574][10588][klog] INFO: [----------] 1 test from GetRatesByChannel [00015.216176][10574][10588][klog] INFO: [ RUN ] GetRatesByChannel.SimpleTest [00015.216179][10574][10588][klog] INFO: [ OK ] GetRatesByChannel.SimpleTest (0 ms) [00015.216180][10574][10588][klog] INFO: [----------] 1 test from GetRatesByChannel (0 ms total) [00015.216180][10574][10588][klog] INFO: [00015.216181][10574][10588][klog] INFO: [----------] 2 tests from MovingAverageTest [00015.216181][10574][10588][klog] INFO: [ RUN ] MovingAverageTest.MovingAverage [00015.216182][10574][10588][klog] INFO: [ OK ] MovingAverageTest.MovingAverage (0 ms) [00015.216183][10574][10588][klog] INFO: [ RUN ] MovingAverageTest.MovingAverageDbm [00015.216183][10574][10588][klog] INFO: [ OK ] MovingAverageTest.MovingAverageDbm (0 ms) [00015.216184][10574][10588][klog] INFO: [----------] 2 tests from MovingAverageTest (0 ms total) [00015.216185][10574][10588][klog] INFO: [00015.216185][10574][10588][klog] INFO: [----------] 3 tests from WriteSupportedRates [00015.216186][10574][10588][klog] INFO: [ RUN ] WriteSupportedRates.Zero [00015.216189][10574][10588][klog] INFO: [ OK ] WriteSupportedRates.Zero (0 ms) [00015.216190][10574][10588][klog] INFO: [ RUN ] WriteSupportedRates.Three [00015.216190][10574][10588][klog] INFO: [ OK ] WriteSupportedRates.Three (0 ms) [00015.216191][10574][10588][klog] INFO: [ RUN ] WriteSupportedRates.Nine [00015.216192][10574][10588][klog] INFO: [ OK ] WriteSupportedRates.Nine (0 ms) [00015.216192][10574][10588][klog] INFO: [----------] 3 tests from WriteSupportedRates (0 ms total) [00015.216193][10574][10588][klog] INFO: [00015.216194][10574][10588][klog] INFO: [----------] 2 tests from WriteExtendedSupportedRates [00015.216194][10574][10588][klog] INFO: [ RUN ] WriteExtendedSupportedRates.TooFew [00015.216195][10574][10588][klog] INFO: [ OK ] WriteExtendedSupportedRates.TooFew (0 ms) [00015.216196][10574][10588][klog] INFO: [ RUN ] WriteExtendedSupportedRates.One [00015.216197][10574][10588][klog] INFO: [ OK ] WriteExtendedSupportedRates.One (0 ms) [00015.216197][10574][10588][klog] INFO: [----------] 2 tests from WriteExtendedSupportedRates (0 ms total) [00015.216198][10574][10588][klog] INFO: [00015.216198][10574][10588][klog] INFO: [----------] 14 tests from Span [00015.216199][10574][10588][klog] INFO: [ RUN ] Span.DefaultConstructor [00015.216199][10574][10588][klog] INFO: [ OK ] Span.DefaultConstructor (0 ms) [00015.216200][10574][10588][klog] INFO: [ RUN ] Span.CopyConstructor [00015.216200][10574][10588][klog] INFO: [ OK ] Span.CopyConstructor (0 ms) [00015.216201][10574][10588][klog] INFO: [ RUN ] Span.ConstructFromTwoPointers [00015.216201][10574][10588][klog] INFO: [ OK ] Span.ConstructFromTwoPointers (0 ms) [00015.216202][10574][10588][klog] INFO: [ RUN ] Span.ImplicitConversionFromNonConstSpan [00015.216202][10574][10588][klog] INFO: [ OK ] Span.ImplicitConversionFromNonConstSpan (0 ms) [00015.216203][10574][10588][klog] INFO: [ RUN ] Span.ImplicitConversionFromArray [00015.219195][10574][10588][klog] INFO: [ OK ] Span.ImplicitConversionFromArray (0 ms) [00015.219224][10574][10588][klog] INFO: [ RUN ] Span.ImplicitConversionFromStdArray [00015.219225][10574][10588][klog] INFO: [ OK ] Span.ImplicitConversionFromStdArray (0 ms) [00015.219225][10574][10588][klog] INFO: [ RUN ] Span.ImplicitConversionFromVector [00015.219226][10574][10588][klog] INFO: [ OK ] Span.ImplicitConversionFromVector (0 ms) [00015.219227][10574][10588][klog] INFO: [ RUN ] Span.SizeInBytes [00015.219228][10574][10588][klog] INFO: [ OK ] Span.SizeInBytes (0 ms) [00015.219228][10574][10588][klog] INFO: [ RUN ] Span.IndexOperator [00015.219229][10574][10588][klog] INFO: [ OK ] Span.IndexOperator (0 ms) [00015.219229][10574][10588][klog] INFO: [ RUN ] Span.RangeBasedFor [00015.219230][10574][10588][klog] INFO: [ OK ] Span.RangeBasedFor (0 ms) [00015.219230][10574][10588][klog] INFO: [ RUN ] Span.Subspan [00015.219231][10574][10588][klog] INFO: [ OK ] Span.Subspan (0 ms) [00015.219232][10574][10588][klog] INFO: [ RUN ] Span.SubspanWithLength [00015.219232][10574][10588][klog] INFO: [ OK ] Span.SubspanWithLength (0 ms) [00015.219233][10574][10588][klog] INFO: [ RUN ] Span.AsBytes [00015.219233][10574][10588][klog] INFO: [ OK ] Span.AsBytes (0 ms) [00015.219234][10574][10588][klog] INFO: [ RUN ] Span.AsWritableBytes [00015.219234][10574][10588][klog] INFO: [ OK ] Span.AsWritableBytes (0 ms) [00015.219235][10574][10588][klog] INFO: [----------] 14 tests from Span (0 ms total) [00015.219236][10574][10588][klog] INFO: [00015.219237][10574][10588][klog] INFO: [----------] 3 tests from StatsTest [00015.219237][10574][10588][klog] INFO: [ RUN ] StatsTest.DispatcherStatsReset [00015.219238][10574][10588][klog] INFO: [ OK ] StatsTest.DispatcherStatsReset (0 ms) [00015.219239][10574][10588][klog] INFO: [ RUN ] StatsTest.RssiStatsReset [00015.219239][10574][10588][klog] INFO: [ OK ] StatsTest.RssiStatsReset (0 ms) [00015.219240][10574][10588][klog] INFO: [ RUN ] StatsTest.RssiStatsIncAndGet [00015.219244][10574][10588][klog] INFO: [ OK ] StatsTest.RssiStatsIncAndGet (0 ms) [00015.219245][10574][10588][klog] INFO: [----------] 3 tests from StatsTest (1 ms total) [00015.219245][10574][10588][klog] INFO: [00015.219246][10574][10588][klog] INFO: [----------] Global test environment tear-down [00015.219247][10574][10588][klog] INFO: [==========] 34 tests from 8 test suites ran. (1 ms total) [00015.219247][10574][10588][klog] INFO: [ PASSED ] 34 tests. [00015.228463][10574][10588][klog] INFO: PASSED: /pkgfs/packages/wlan_tests/0/test/wlan_common_unittest passed [00015.355725][10574][10588][klog] INFO: [00015.355790][10574][10588][klog] INFO: ------------------------------------------------ [00015.355794][10574][10588][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan-bitfield-tests/0/test/wlan_bitfield_wrapper_lib_test [00015.355794][10574][10588][klog] INFO: [00015.391806][10574][10588][klog] INFO: [00015.391827][10574][10588][klog] INFO: running 12 tests [00015.392416][10574][10588][klog] INFO: test tests::bool_setter ... ok [00015.398751][10574][10588][klog] INFO: test tests::aliased ... ok [00015.399592][10574][10588][klog] INFO: test tests::builders ... ok [00015.399623][10574][10588][klog] INFO: test tests::custom_bool_type ... ok [00015.404371][10574][10588][klog] INFO: test tests::custom_type ... ok [00015.404652][10574][10588][klog] INFO: test tests::debug ... ok [00015.404767][10574][10588][klog] INFO: test tests::debug_pretty ... ok [00015.405220][10574][10588][klog] INFO: test tests::setters_dont_touch_other_fields ... ok [00015.415659][10574][10588][klog] INFO: test tests::getters ... ok [00015.415684][10574][10588][klog] INFO: test tests::setters_truncate ... ok [00015.415685][10574][10588][klog] INFO: test tests::getters_zero ... ok [00015.415687][10574][10588][klog] INFO: test tests::u128 ... ok [00015.422247][10574][10588][klog] INFO: [00015.422271][10574][10588][klog] INFO: test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [00015.422308][10574][10588][klog] INFO: [00015.423243][10574][10588][klog] INFO: PASSED: /pkgfs/packages/wlan-bitfield-tests/0/test/wlan_bitfield_wrapper_lib_test passed [00015.495338][10574][10588][klog] INFO: [00015.495370][10574][10588][klog] INFO: ------------------------------------------------ [00015.495373][10574][10588][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan-common-tests/0/test/wlan_common_lib_test [00015.495373][10574][10588][klog] INFO: [00015.555441][10574][10588][klog] INFO: [00015.555517][10574][10588][klog] INFO: running 188 tests [00015.557408][10574][10588][klog] INFO: test big_endian::tests::big_endian_u16 ... ok [00015.560608][10574][10588][klog] INFO: test buffer_reader::tests::peek_and_read_array ... ok [00015.560675][10574][10588][klog] INFO: test buffer_reader::tests::peek ... ok [00015.560856][10574][10588][klog] INFO: test appendable::tests::append_to_vec ... ok [00015.560975][10574][10588][klog] INFO: test buffer_reader::tests::peek_and_read_value ... ok [00015.567947][10574][10588][klog] INFO: test buffer_reader::tests::peek_mut ... ok [00015.572807][10574][10588][klog] INFO: test buffer_reader::tests::peek_mut_and_modify ... ok [00015.577065][10574][10588][klog] INFO: test buffer_reader::tests::read ... ok [00015.577259][10574][10588][klog] INFO: test buffer_reader::tests::unaligned_access ... ok [00015.581489][10574][10588][klog] INFO: test buffer_writer::tests::append_bytes ... ok [00015.581685][10574][10588][klog] INFO: test buffer_writer::tests::append_value_zeroed ... ok [00015.586012][10574][10588][klog] INFO: test buffer_writer::tests::buffer_too_short ... ok [00015.586126][10574][10588][klog] INFO: test buffer_writer::tests::can_append ... ok [00015.586759][10574][10588][klog] INFO: test channel::tests::fmt_display ... ok [00015.592895][10574][10588][klog] INFO: test channel::tests::test_convert_fidl_channel ... ok [00015.596528][10574][10588][klog] INFO: test channel::tests::test_convert_fidl_phy ... ok [00015.602134][10574][10588][klog] INFO: test channel::tests::test_band_start_freq ... ok [00015.602253][10574][10588][klog] INFO: test channel::tests::test_get_center_chan_idx ... ok [00015.607990][10574][10588][klog] INFO: test channel::tests::test_get_center_freq ... ok [00015.608310][10574][10588][klog] INFO: test channel::tests::test_invalid_combo ... ok [00015.613952][10574][10588][klog] INFO: test channel::tests::test_is_2ghz_or_5ghz ... ok [00015.617077][10574][10588][klog] INFO: test channel::tests::test_is_dfs ... ok [00015.617221][10574][10588][klog] INFO: test channel::tests::test_is_primary_valid ... ok [00015.617264][10574][10588][klog] INFO: test channel::tests::test_valid_combo ... ok [00015.617777][10574][10588][klog] INFO: test data_writer::tests::addr4_is_present_but_to_from_are_invalid ... ok [00015.618016][10574][10588][klog] INFO: test data_writer::tests::client_to_ap ... ok [00015.622861][10574][10588][klog] INFO: test data_writer::tests::htc_set_but_ht_ctrl_missing ... ok [00015.627446][10574][10588][klog] INFO: test data_writer::tests::qos_ctrl_present_but_no_qos_bit ... ok [00015.627698][10574][10588][klog] INFO: test data_writer::tests::ht_ctrl_present_but_no_htc ... ok [00015.632750][10574][10588][klog] INFO: test data_writer::tests::qos_set_but_qos_ctrl_missing ... ok [00015.633366][10574][10588][klog] INFO: test data_writer::tests::to_from_ds_both_set_but_addr4_missing ... ok [00015.637870][10574][10588][klog] INFO: test data_writer::tests::too_small_buffer ... ok [00015.637894][10574][10588][klog] INFO: test data_writer::tests::write_addr4_ht_ctrl ... ok [00015.638258][10574][10588][klog] INFO: test data_writer::tests::write_fixed_fields_only ... ok [00015.642085][10574][10588][klog] INFO: test data_writer::tests::write_llc_hdr ... ok [00015.648920][10574][10588][klog] INFO: test data_writer::tests::wrong_frame_type ... ok [00015.648978][10574][10588][klog] INFO: test format::tests::format_mac_str ... ok [00015.649086][10574][10588][klog] INFO: test data_writer::tests::write_qos_ctrl ... ok [00015.649682][10574][10588][klog] INFO: test ie::fields::tests::perr_iter_empty ... ok [00015.662439][10574][10588][klog] INFO: test ie::fields::tests::perr_iter_too_short_for_ext_addr ... ok [00015.662464][10574][10588][klog] INFO: test ie::fields::tests::perr_iter_too_short_for_header ... ok [00015.662824][10574][10588][klog] INFO: test ie::fields::tests::perr_iter_too_short_for_reason_code ... ok [00015.668658][10574][10588][klog] INFO: test ie::fields::tests::perr_iter_two_destinations ... ok [00015.669779][10574][10588][klog] INFO: test ie::fields::tests::vht_mcs_nss_map_accessor ... ok [00015.670100][10574][10588][klog] INFO: test ie::fields::tests::vht_mcs_nss_map_accssor_error ... ok [00015.674992][10574][10588][klog] INFO: test ie::parse::tests::dsss_param_set_ok ... ok [00015.678670][10574][10588][klog] INFO: test ie::parse::tests::dsss_param_set_wrong_size ... ok [00015.678969][10574][10588][klog] INFO: test ie::parse::tests::ext_supported_rates_empty ... ok [00015.682621][10574][10588][klog] INFO: test ie::parse::tests::ext_supported_rates_ok ... ok [00015.686204][10574][10588][klog] INFO: test ie::parse::tests::ht_capabilities_ok ... ok [00015.686650][10574][10588][klog] INFO: test ie::parse::tests::ht_capabilities_wrong_size ... ok [00015.687853][10574][10588][klog] INFO: test ie::parse::tests::ht_operation_ok ... ok [00015.691635][10574][10588][klog] INFO: test ie::parse::tests::ht_operation_wrong_size ... ok [00015.691662][10574][10588][klog] INFO: test ie::parse::tests::mpm_close_ok_no_link_id_no_pmk ... ok [00015.695387][10574][10588][klog] INFO: test ie::parse::tests::mpm_close_ok_no_link_id_with_pmk ... ok [00015.699275][10574][10588][klog] INFO: test ie::parse::tests::mpm_close_ok_with_link_id_no_pmk ... ok [00015.703940][10574][10588][klog] INFO: test ie::parse::tests::mpm_close_ok_with_link_id_with_pmk ... ok [00015.703975][10574][10588][klog] INFO: test ie::parse::tests::mpm_close_too_long ... ok [00015.704307][10574][10588][klog] INFO: test ie::parse::tests::mpm_close_too_short_for_header ... ok [00015.714478][10574][10588][klog] INFO: test ie::parse::tests::mpm_close_too_short_for_reason_code ... ok [00015.714517][10574][10588][klog] INFO: test ie::parse::tests::mpm_close_weird_length_1 ... ok [00015.715511][10574][10588][klog] INFO: test ie::parse::tests::mpm_close_weird_length_2 ... ok [00015.719319][10574][10588][klog] INFO: test ie::parse::tests::mpm_confirm_ok_with_pmk ... ok [00015.719357][10574][10588][klog] INFO: test ie::parse::tests::mpm_confirm_ok_no_pmk ... ok [00015.723692][10574][10588][klog] INFO: test ie::parse::tests::mpm_confirm_too_long ... ok [00015.728420][10574][10588][klog] INFO: test ie::parse::tests::mpm_confirm_too_short_for_header ... ok [00015.728917][10574][10588][klog] INFO: test ie::parse::tests::mpm_confirm_too_short_for_peer_link_id ... ok [00015.733575][10574][10588][klog] INFO: test ie::parse::tests::mpm_confirm_weird_length ... ok [00015.737864][10574][10588][klog] INFO: test ie::parse::tests::mpm_open_ok_no_pmk ... ok [00015.737896][10574][10588][klog] INFO: test ie::parse::tests::mpm_open_ok_with_pmk ... ok [00015.742249][10574][10588][klog] INFO: test ie::parse::tests::mpm_open_too_long ... ok [00015.746912][10574][10588][klog] INFO: test ie::parse::tests::mpm_open_too_short ... ok [00015.752612][10574][10588][klog] INFO: test ie::parse::tests::mpm_open_weird_length ... ok [00015.756995][10574][10588][klog] INFO: test ie::parse::tests::perr_ok ... ok [00015.760625][10574][10588][klog] INFO: test ie::parse::tests::perr_too_short_for_header ... ok [00015.765005][10574][10588][klog] INFO: test ie::parse::tests::prep_ok_no_ext ... ok [00015.765888][10574][10588][klog] INFO: test ie::parse::tests::prep_ok_with_ext ... ok [00015.766086][10574][10588][klog] INFO: test ie::parse::tests::prep_too_long ... ok [00015.766160][10574][10588][klog] INFO: test ie::parse::tests::prep_too_short_for_ext_addr ... ok [00015.772602][10574][10588][klog] INFO: test ie::parse::tests::prep_too_short_for_tail ... ok [00015.775656][10574][10588][klog] INFO: test ie::parse::tests::prep_too_short_for_header ... ok [00015.776273][10574][10588][klog] INFO: test ie::parse::tests::preq_ok_full ... ok [00015.780389][10574][10588][klog] INFO: test ie::parse::tests::preq_ok_minimal ... ok [00015.780423][10574][10588][klog] INFO: test ie::parse::tests::preq_too_long ... ok [00015.784099][10574][10588][klog] INFO: test ie::parse::tests::preq_too_short_for_ext_addr ... ok [00015.784922][10574][10588][klog] INFO: test ie::parse::tests::preq_too_short_for_header ... ok [00015.785097][10574][10588][klog] INFO: test ie::parse::tests::preq_too_short_for_middle ... ok [00015.785120][10574][10588][klog] INFO: test ie::parse::tests::preq_too_short_for_targets ... ok [00015.785458][10574][10588][klog] INFO: test ie::parse::tests::ssid_ok ... ok [00015.793430][10574][10588][klog] INFO: test ie::parse::tests::supported_rates_empty ... ok [00015.797502][10574][10588][klog] INFO: test ie::parse::tests::supported_rates_ok ... ok [00015.802915][10574][10588][klog] INFO: test ie::parse::tests::ssid_too_long ... ok [00015.807491][10574][10588][klog] INFO: test ie::parse::tests::supported_rates_too_long ... ok [00015.812256][10574][10588][klog] INFO: test ie::parse::tests::tim_bitmap_too_long ... ok [00015.812282][10574][10588][klog] INFO: test ie::parse::tests::tim_empty_bitmap ... ok [00015.817294][10574][10588][klog] INFO: test ie::parse::tests::tim_ok ... ok [00015.817617][10574][10588][klog] INFO: test ie::parse::tests::tim_too_short_for_header ... ok [00015.817620][10574][10588][klog] INFO: test ie::parse::tests::vht_capabilities_ok ... ok [00015.822724][10574][10588][klog] INFO: test ie::parse::tests::vht_operation_ok ... ok [00015.827130][10574][10588][klog] INFO: test ie::parse::tests::vht_capabilities_wrong_size ... ok [00015.834340][10574][10588][klog] INFO: test ie::parse::tests::vht_operation_wrong_size ... ok [00015.834342][10574][10588][klog] INFO: test ie::reader::tests::body_too_short ... ok [00015.834343][10574][10588][klog] INFO: test ie::reader::tests::empty ... ok [00015.834344][10574][10588][klog] INFO: test ie::reader::tests::less_than_header ... ok [00015.834344][10574][10588][klog] INFO: test ie::reader::tests::empty_body ... ok [00015.835536][10574][10588][klog] INFO: test ie::reader::tests::two_elements ... ok [00015.842294][10574][10588][klog] INFO: test ie::rsn::rsne::tests::test_rsn_capabilities_setters ... ok [00015.842740][10574][10588][klog] INFO: test ie::rsn::rsne::tests::bench_parse_with_nom ... ok [00015.843891][10574][10588][klog] INFO: test ie::write::tests::dsss_param_set ... ok [00015.846239][10574][10588][klog] INFO: test ie::rsn::rsne::tests::test_as_bytes ... ok [00015.851064][10574][10588][klog] INFO: test ie::rsn::rsne::tests::test_short_buffer ... ok [00015.851065][10574][10588][klog] INFO: test ie::rsn::rsne::tests::test_rsn_fields_representation ... ok [00015.851454][10574][10588][klog] INFO: test ie::write::tests::ext_supported_rates_empty ... ok [00015.851474][10574][10588][klog] INFO: test ie::write::tests::mpm_close_full ... ok [00015.856389][10574][10588][klog] INFO: test ie::write::tests::ext_supported_rates_ok ... ok [00015.858363][10574][10588][klog] INFO: test ie::write::tests::mpm_close_minimal ... ok [00015.858800][10574][10588][klog] INFO: test ie::write::tests::mpm_confirm_no_pmk ... ok [00015.863192][10574][10588][klog] INFO: test ie::write::tests::mpm_confirm_with_pmk ... ok [00015.863215][10574][10588][klog] INFO: test ie::write::tests::mpm_open_no_pmk ... ok [00015.867366][10574][10588][klog] INFO: test ie::write::tests::mpm_open_with_pmk ... ok [00015.867750][10574][10588][klog] INFO: test ie::write::tests::perr ... ok [00015.874285][10574][10588][klog] INFO: test ie::write::tests::perr_destination_addr_ext_flag_set_but_no_addr_given ... ok [00015.874318][10574][10588][klog] INFO: test ie::write::tests::perr_destination_buffer_too_small ... ok [00015.877386][10574][10588][klog] INFO: test ie::write::tests::perr_destination_ext_addr_given_but_no_flag_set ... ok [00015.881310][10574][10588][klog] INFO: test ie::write::tests::perr_destination_ok_no_ext ... ok [00015.881638][10574][10588][klog] INFO: test ie::write::tests::perr_destination_ok_with_ext ... ok [00015.881679][10574][10588][klog] INFO: test ie::write::tests::prep_addr_ext_flag_set_but_no_addr_given ... ok [00015.881979][10574][10588][klog] INFO: test ie::write::tests::prep_ext_addr_given_but_no_flag_set ... ok [00015.885180][10574][10588][klog] INFO: test ie::write::tests::prep_no_ext ... ok [00015.893968][10574][10588][klog] INFO: test ie::write::tests::prep_with_ext ... ok [00015.893994][10574][10588][klog] INFO: test ie::write::tests::preq_ext_addr_given_but_no_flag_set ... ok [00015.898315][10574][10588][klog] INFO: test ie::write::tests::preq_addr_ext_flag_set_but_no_addr_given ... ok [00015.898316][10574][10588][klog] INFO: test ie::write::tests::preq_full ... ok [00015.902654][10574][10588][klog] INFO: test ie::write::tests::preq_minimal ... ok [00015.902837][10574][10588][klog] INFO: test ie::write::tests::preq_target_count_mismatch ... ok [00015.915893][10574][10588][klog] INFO: test ie::write::tests::ssid_ok ... ok [00015.919047][10574][10588][klog] INFO: test ie::write::tests::ssid_ok_empty ... ok [00015.922678][10574][10588][klog] INFO: test ie::write::tests::ssid_too_long ... ok [00015.926173][10574][10588][klog] INFO: test ie::write::tests::supported_rates_empty ... ok [00015.926300][10574][10588][klog] INFO: test ie::write::tests::supported_rates_ok ... ok [00015.927116][10574][10588][klog] INFO: test ie::write::tests::supported_rates_too_long ... ok [00015.931205][10574][10588][klog] INFO: test ie::write::tests::tim_bitmap_too_long ... ok [00015.931233][10574][10588][klog] INFO: test ie::write::tests::tim_empty_bitmap ... ok [00015.931237][10574][10588][klog] INFO: test ie::write::tests::tim_ok ... ok [00015.938448][10574][10588][klog] INFO: test ie::write::tests::write_ie_buffer_exactly_long_enough ... ok [00015.938449][10574][10588][klog] INFO: test ie::write::tests::write_ie_buffer_too_small ... ok [00015.940165][10574][10588][klog] INFO: test ie::write::tests::write_ie_body_too_long ... ok [00015.946569][10574][10588][klog] INFO: test mac::data::amsdu::tests::parse_data_amsdu ... ok [00015.950787][10574][10588][klog] INFO: test mac::data::amsdu::tests::parse_data_amsdu_padding_too_short ... ok [00015.951004][10574][10588][klog] INFO: test mac::data::fields::tests::fixed_fields_bssid ... ok [00015.956908][10574][10588][klog] INFO: test mac::data::fields::tests::fixed_fields_dst_addr ... ok [00015.957892][10574][10588][klog] INFO: test mac::data::fields::tests::fixed_fields_len ... ok [00015.962595][10574][10588][klog] INFO: test mac::data::fields::tests::fixed_fields_ra ... ok [00015.967412][10574][10588][klog] INFO: test mac::data::fields::tests::fixed_fields_src_addr ... ok [00015.973248][10574][10588][klog] INFO: test mac::data::fields::tests::fixed_fields_ta ... ok [00015.973274][10574][10588][klog] INFO: test mac::data::msdu::tests::msdu_iterator_single_llc ... ok [00015.973297][10574][10588][klog] INFO: test mac::data::msdu::tests::msdu_iterator_single_llc_padding ... ok [00015.973346][10574][10588][klog] INFO: test mac::data::msdu::tests::parse_llc_with_addr4_ht_ctrl ... ok [00015.978770][10574][10588][klog] INFO: test mac::data::tests::parse_data_frame ... ok [00015.979552][10574][10588][klog] INFO: test mac::data::tests::parse_data_frame_with_padding ... ok [00015.979572][10574][10588][klog] INFO: test mac::eth::tests::eth_hdr_big_endian ... ok [00015.983753][10574][10588][klog] INFO: test mac::frame_class::tests::action_frames ... ok [00015.988847][10574][10588][klog] INFO: test mac::frame_class::tests::ctrl_frame_class ... ok [00015.989188][10574][10588][klog] INFO: test mac::frame_class::tests::data_frame_class ... ok [00015.994073][10574][10588][klog] INFO: test mac::frame_class::tests::mgmt_frame_class ... ok [00015.994463][10574][10588][klog] INFO: test mac::mgmt::tests::mgmt_hdr_len ... ok [00015.999182][10574][10588][klog] INFO: test mac::mgmt::tests::parse_beacon_frame ... ok [00016.005800][10574][10588][klog] INFO: test mac::tests::parse_data_frame ... ok [00016.012392][10574][10588][klog] INFO: test mac::tests::parse_mgmt_frame ... ok [00016.017211][10574][10588][klog] INFO: test mac::tests::parse_mgmt_frame_too_short_unsupported ... ok [00016.018433][10574][10588][klog] INFO: test mac::tests::round_up_to_4 ... ok [00016.023010][10574][10588][klog] INFO: test mgmt_writer::tests::client_to_ap ... ok [00016.023190][10574][10588][klog] INFO: test mgmt_writer::tests::from_ds_set ... ok [00016.028058][10574][10588][klog] INFO: test mgmt_writer::tests::ht_ctrl_present_but_no_htc ... ok [00016.028486][10574][10588][klog] INFO: test mgmt_writer::tests::htc_set_but_ht_ctrl_is_missing ... ok [00016.028505][10574][10588][klog] INFO: test mgmt_writer::tests::to_ds_set ... ok [00016.028645][10574][10588][klog] INFO: test mgmt_writer::tests::too_small_buffer ... ok [00016.029310][10574][10588][klog] INFO: test mgmt_writer::tests::write_fixed_fields_only ... ok [00016.033937][10574][10588][klog] INFO: test sequence::tests::mixed_sns_next ... ok [00016.034465][10574][10588][klog] INFO: test sequence::tests::sns1_next_multiple_peers ... ok [00016.045036][10574][10588][klog] INFO: test sequence::tests::sns2_next_multiple_tids ... ok [00016.052230][10574][10588][klog] INFO: test mgmt_writer::tests::write_ht_ctrl ... ok [00016.052262][10574][10588][klog] INFO: test sequence::tests::sns1_next ... ok [00016.052263][10574][10588][klog] INFO: test sequence::tests::sns4_next_multiple_acis ... ok [00016.053051][10574][10588][klog] INFO: test tim::tests::with_offset ... ok [00016.053101][10574][10588][klog] INFO: test tim::tests::zero_offset ... ok [00016.059058][10574][10588][klog] INFO: test unaligned_view::tests::get ... ok [00016.059089][10574][10588][klog] INFO: test unaligned_view::tests::set ... ok [00016.059090][10574][10588][klog] INFO: [00016.059090][10574][10588][klog] INFO: test result: ok. 188 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [00016.059091][10574][10588][klog] INFO: [00016.061525][10574][10588][klog] INFO: PASSED: /pkgfs/packages/wlan-common-tests/0/test/wlan_common_lib_test passed [00016.128216][10574][10588][klog] INFO: [00016.128255][10574][10588][klog] INFO: ------------------------------------------------ [00016.128258][10574][10588][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan-inspect-tests/0/test/wlan_inspect_lib_test [00016.128259][10574][10588][klog] INFO: [00016.174385][10574][10588][klog] INFO: [00016.174428][10574][10588][klog] INFO: running 8 tests [00016.184209][10574][10588][klog] INFO: test log::tests::test_inspect_log_parsing ... ok [00016.192980][10574][10588][klog] INFO: test log::tests::test_inspect_log_macro_does_not_move_value ... ok [00016.198237][10574][10588][klog] INFO: test log::tests::test_inspect_log_macro ... ok [00016.201462][10574][10588][klog] INFO: test iface_mgr::tests::test_iface_manager ... ok [00016.201654][10574][10588][klog] INFO: test log::tests::test_log_inspect_bytes ... ok [00016.201655][10574][10588][klog] INFO: test log::tests::test_log_inspect_list ... ok [00016.202251][10574][10588][klog] INFO: test nodes::list::tests::test_bounded_list_node ... ok [00016.202276][10574][10588][klog] INFO: test nodes::tests::test_time_metadata_format ... ok [00016.205386][10574][10588][klog] INFO: [00016.205455][10574][10588][klog] INFO: test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [00016.205492][10574][10588][klog] INFO: [00016.210399][10574][10588][klog] INFO: PASSED: /pkgfs/packages/wlan-inspect-tests/0/test/wlan_inspect_lib_test passed [00016.268171][10574][10588][klog] INFO: [00016.268220][10574][10588][klog] INFO: ------------------------------------------------ [00016.268223][10574][10588][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan-mlme-tests/0/test/wlan_mlme_lib_test [00016.268224][10574][10588][klog] INFO: [00016.313702][10574][10588][klog] INFO: [00016.313734][10574][10588][klog] INFO: running 22 tests [00016.313735][10574][10588][klog] INFO: test auth::tests::invalid_open_auth_resp ... ok [00016.313735][10574][10588][klog] INFO: test auth::tests::valid_open_auth_resp ... ok [00016.317868][10574][10588][klog] INFO: test buffer::tests::as_mut_slice ... ok [00016.318011][10574][10588][klog] INFO: test buffer::tests::as_mut_slice_null_data ... ok [00016.318056][10574][10588][klog] INFO: test buffer::tests::as_slice ... ok [00016.318361][10574][10588][klog] INFO: test buffer::tests::as_slice_null_data ... ok [00016.318362][10574][10588][klog] INFO: test buffer::tests::return_out_of_scope_buffer ... ok [00016.318793][10574][10588][klog] INFO: test buffer::tests::from_in_buf ... ok [00016.323280][10574][10588][klog] INFO: test client::tests::data_frame ... ok [00016.323289][10574][10588][klog] INFO: test client::tests::data_frame_empty_payload ... ok [00016.326750][10574][10588][klog] INFO: test client::tests::data_frame_buffer_too_small ... ok [00016.330902][10574][10588][klog] INFO: test client::tests::data_frame_protected_qos ... ok [00016.330908][10574][10588][klog] INFO: test client::tests::data_frame_to_ethernet_amsdu ... ok [00016.335544][10574][10588][klog] INFO: test client::tests::data_frame_to_ethernet_amsdu_padding_too_short ... ok [00016.337019][10574][10588][klog] INFO: test client::tests::data_frame_to_ethernet_single_llc ... ok [00016.337088][10574][10588][klog] INFO: test client::tests::deauth_frame ... ok [00016.341459][10574][10588][klog] INFO: test client::tests::eth_frame_buffer_too_small ... ok [00016.341547][10574][10588][klog] INFO: test client::tests::eth_frame_empty_payload ... ok [00016.349873][10574][10588][klog] INFO: test client::tests::eth_frame_ok ... ok [00016.349997][10574][10588][klog] INFO: test client::tests::keep_alive_resp_frame ... ok [00016.349998][10574][10588][klog] INFO: test client::tests::open_auth_frame ... ok [00016.350001][10574][10588][klog] INFO: test device::tests::fake_device_deliver_ethernet ... ok [00016.350001][10574][10588][klog] INFO: [00016.350002][10574][10588][klog] INFO: test result: ok. 22 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [00016.350002][10574][10588][klog] INFO: [00016.351818][10574][10588][klog] INFO: PASSED: /pkgfs/packages/wlan-mlme-tests/0/test/wlan_mlme_lib_test passed [00016.411240][10574][10588][klog] INFO: [00016.411265][10574][10588][klog] INFO: ------------------------------------------------ [00016.411269][10574][10588][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan-rsn-tests/0/test/wlan_rsn_lib_test [00016.411269][10574][10588][klog] INFO: [00016.476164][10574][10588][klog] INFO: [00016.476192][10574][10588][klog] INFO: running 89 tests [00016.531841][10574][10588][klog] INFO: test auth::psk::tests::test_psk_invalid_char_password ... ok [00016.539219][10574][10588][klog] INFO: test auth::psk::tests::test_psk_invalid_unicode_char_password ... ok [00016.747904][10574][10588][klog] INFO: test auth::psk::tests::test_psk_test_case_1 ... ok [00016.751462][10574][10588][klog] INFO: test auth::psk::tests::test_psk_too_long_password ... ok [00016.753446][10574][10588][klog] INFO: test auth::psk::tests::test_psk_ascii_bounds_password ... ok [00016.757962][10574][10588][klog] INFO: test auth::psk::tests::test_psk_too_long_ssid ... ok [00016.758185][10574][10588][klog] INFO: test auth::psk::tests::test_psk_too_short_password ... ok [00016.759635][10574][10588][klog] INFO: test auth::psk::tests::test_psk_unicode_valid_length_password ... ok [00016.762943][10574][10588][klog] INFO: test crypto_utils::tests::test_prf_all_empty ... ok [00016.768844][10574][10588][klog] INFO: test crypto_utils::tests::test_prf_empty_data ... ok [00016.769160][10574][10588][klog] INFO: test crypto_utils::tests::test_prf_empty_key ... ok [00016.774161][10574][10588][klog] INFO: test crypto_utils::nonce::tests::test_next_nonce ... ok [00016.778088][10574][10588][klog] INFO: test crypto_utils::tests::test_prf_empty_prefix ... ok [00016.778115][10574][10588][klog] INFO: test auth::psk::tests::test_psk_test_case_2 ... ok [00016.781348][10574][10588][klog] INFO: test crypto_utils::tests::test_prf_invalid_bit_sizes ... ok [00016.781565][10574][10588][klog] INFO: test crypto_utils::tests::test_prf_test_case_1 ... ok [00016.782928][10574][10588][klog] INFO: test crypto_utils::tests::test_prf_test_case_2 ... ok [00016.788778][10574][10588][klog] INFO: test crypto_utils::tests::test_prf_test_case_3 ... ok [00016.788798][10574][10588][klog] INFO: test crypto_utils::tests::test_prf_test_case_65_1 ... ok [00016.794280][10574][10588][klog] INFO: test crypto_utils::tests::test_prf_test_case_65_2 ... ok [00016.794808][10574][10588][klog] INFO: test crypto_utils::tests::test_prf_test_case_65_3 ... ok [00016.797028][10574][10588][klog] INFO: test crypto_utils::tests::test_prf_test_case_65_4 ... ok [00016.798147][10574][10588][klog] INFO: test crypto_utils::tests::test_prf_valid_bit_sizes ... ok [00016.802059][10574][10588][klog] INFO: test integrity::hmac_sha1::tests::test_test_case_1 ... ok [00016.802060][10574][10588][klog] INFO: test integrity::hmac_sha1::tests::test_test_case_2 ... ok [00016.803409][10574][10588][klog] INFO: test integrity::hmac_sha1::tests::test_test_case_4 ... ok [00016.808376][10574][10588][klog] INFO: test integrity::hmac_sha1::tests::test_test_case_3 ... ok [00016.808402][10574][10588][klog] INFO: test integrity::hmac_sha1::tests::test_test_case_5 ... ok [00016.808967][10574][10588][klog] INFO: test integrity::hmac_sha1::tests::test_test_case_6 ... ok [00016.810156][10574][10588][klog] INFO: test integrity::hmac_sha1::tests::test_test_case_7 ... ok [00016.810157][10574][10588][klog] INFO: test integrity::hmac_sha1::tests::test_test_case_8 ... ok [00016.812131][10574][10588][klog] INFO: test integrity::hmac_sha1::tests::test_verify_failure ... ok [00016.812153][10574][10588][klog] INFO: test integrity::hmac_sha1::tests::test_test_case_9 ... ok [00016.825172][10574][10588][klog] INFO: test key::exchange::handshake::fourway::tests::test_random_iv_msg1_v2 ... ok [00016.827267][10574][10588][klog] INFO: test key::exchange::handshake::fourway::tests::test_random_iv_msg1_v1 ... ok [00016.849392][10574][10588][klog] INFO: test key::exchange::handshake::fourway::tests::test_random_iv_msg3_v3 ... ok [00016.849420][10574][10588][klog] INFO: test key::exchange::handshake::fourway::tests::test_random_iv_msg3_v2 ... ok [00016.849449][10574][10588][klog] INFO: test key::exchange::handshake::fourway::tests::test_random_iv_msg3_v1 ... ok [00016.849486][10574][10588][klog] INFO: test key::exchange::handshake::group_key::tests::random_iv_8021x2001 ... ok [00016.849487][10574][10588][klog] INFO: test key::exchange::handshake::group_key::tests::random_iv_8021x2004 ... ok [00016.849487][10574][10588][klog] INFO: test key::exchange::handshake::group_key::tests::zeroed_iv_8021x2001 ... ok [00016.849488][10574][10588][klog] INFO: test key::exchange::handshake::fourway::tests::test_supplicant_with_authenticator ... ok [00016.850705][10574][10588][klog] INFO: test key::exchange::handshake::group_key::tests::zeroed_iv_8021x2004 ... ok [00016.851737][10574][10588][klog] INFO: test key::exchange::handshake::fourway::tests::test_zeroed_iv_msg3_v2 ... ok [00016.856282][10574][10588][klog] INFO: test key::ptk::tests::test_pairwise_key_hierarchy_ccmp ... ok [00016.856862][10574][10588][klog] INFO: test key::ptk::tests::test_pairwise_key_hierarchy_invalid_pmk ... ok [00016.856863][10574][10588][klog] INFO: test key::ptk::tests::test_pairwise_key_hierarchy_tkip ... ok [00016.857789][10574][10588][klog] INFO: test key::ptk::tests::test_pairwise_key_hierarchy_unsupported_akm ... ok [00016.859157][10574][10588][klog] INFO: test key::ptk::tests::test_pairwise_key_hierarchy_unsupported_cipher ... ok [00016.859188][10574][10588][klog] INFO: test key_data::kde::tests::test_create_gtk_element ... ok [00016.864227][10574][10588][klog] INFO: test key_data::kde::tests::test_gtk_as_bytes ... ok [00016.866643][10574][10588][klog] INFO: test key_data::kde::tests::test_gtk_as_bytes_too_short ... ok [00016.867364][10574][10588][klog] INFO: test key_data::kde::tests::test_hdr_as_bytes ... ok [00016.871593][10574][10588][klog] INFO: test key_data::kde::tests::test_gtk_len ... ok [00016.872952][10574][10588][klog] INFO: test key_data::tests::test_add_padding_8_multiple_length ... ok [00016.877493][10574][10588][klog] INFO: test key_data::tests::test_add_padding_min_length ... ok [00016.879241][10574][10588][klog] INFO: test key_data::tests::test_gtk ... ok [00016.881569][10574][10588][klog] INFO: test key_data::tests::test_complex_key_data ... ok [00016.886778][10574][10588][klog] INFO: test key_data::tests::test_long_gtk ... ok [00016.887959][10574][10588][klog] INFO: test key_data::tests::test_long_padding ... ok [00016.888528][10574][10588][klog] INFO: test key_data::tests::test_no_padding ... ok [00016.889020][10574][10588][klog] INFO: test key_data::tests::test_single_padding_byte ... ok [00016.889464][10574][10588][klog] INFO: test key_data::tests::test_too_short_key_data ... ok [00016.894263][10574][10588][klog] INFO: test key_data::tests::test_not_multiple_of_8 ... ok [00016.899386][10574][10588][klog] INFO: test keywrap::aes::tests::test_128_data_192_kek ... ok [00016.900721][10574][10588][klog] INFO: test keywrap::aes::tests::test_128_data_128_kek ... ok [00016.906118][10574][10588][klog] INFO: test keywrap::aes::tests::test_192_data_192_kek ... ok [00016.907175][10574][10588][klog] INFO: test keywrap::aes::tests::test_128_data_256_kek ... ok [00016.914611][10574][10588][klog] INFO: test keywrap::aes::tests::test_192_data_256_kek ... ok [00016.915347][10574][10588][klog] INFO: test keywrap::aes::tests::test_invalid_data_length ... ok [00016.916454][10574][10588][klog] INFO: test keywrap::aes::tests::test_invalid_key_length ... ok [00016.916456][10574][10588][klog] INFO: test keywrap::aes::tests::test_too_short_data ... ok [00016.917975][10574][10588][klog] INFO: test keywrap::aes::tests::test_256_data_256_kek ... ok [00016.919017][10574][10588][klog] INFO: test keywrap::aes::tests::test_unwrap_wrong_key ... ok [00017.038289][10574][10588][klog] INFO: test key::gtk::tests::test_gtk_generation ... ok [00017.170435][10574][10588][klog] INFO: test rsna::esssa::tests::test_nonzero_key_replay_counter_msg1 ... ok [00017.172414][10574][10588][klog] INFO: test rsna::esssa::tests::test_replay_first_message ... ok [00017.417737][10574][10588][klog] INFO: test auth::psk::tests::test_psk_test_case_3 ... ok [00017.433975][10574][10588][klog] INFO: test rsna::esssa::tests::test_replayed_msg1_ptk_installation ... ok [00017.465991][10574][10588][klog] INFO: test rsna::esssa::tests::test_supplicant_wpa2_ccmp128_psk ... ok [00017.683267][10574][10588][klog] INFO: test rsna::esssa::tests::test_zero_key_replay_counter_lower_msg3_counter ... ok [00017.715528][10574][10588][klog] INFO: test rsna::esssa::tests::test_zero_key_replay_counter_msg1 ... ok [00017.722379][10574][10588][klog] INFO: test rsna::tests::test_negotiated_rsne_from_rsne ... ok [00017.728105][10574][10588][klog] INFO: test rsna::tests::test_supplicant_sends_random_key_length ... ok [00017.728132][10574][10588][klog] INFO: test rsna::tests::test_supplicant_sends_zeroed_and_non_zeroed_key_length ... ok [00017.728133][10574][10588][klog] INFO: test rsna::tests::test_to_rsne ... ok [00017.940850][10574][10588][klog] INFO: test rsna::esssa::tests::test_zero_key_replay_counter_valid_msg3 ... ok [00017.998652][10574][10588][klog] INFO: test rsna::esssa::tests::test_zero_key_replay_counter_replayed_msg3 ... ok [00018.077457][10574][10588][klog] INFO: test rsna::esssa::tests::test_supplicant_with_authenticator ... ok [00018.077471][10574][10588][klog] INFO: [00018.077471][10574][10588][klog] INFO: test result: ok. 89 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [00018.077471][10574][10588][klog] INFO: [00018.080829][10574][10588][klog] INFO: PASSED: /pkgfs/packages/wlan-rsn-tests/0/test/wlan_rsn_lib_test passed [00018.174035][10574][10588][klog] INFO: [00018.174059][10574][10588][klog] INFO: ------------------------------------------------ [00018.174062][10574][10588][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan-sme-tests/0/test/wlan_sme_lib_test [00018.174063][10574][10588][klog] INFO: [00018.283756][10574][10588][klog] INFO: [00018.283786][10574][10588][klog] INFO: running 111 tests [00018.299127][10574][10588][klog] INFO: test ap::aid::tests::test_map_never_assign_zero ... ok [00018.304737][10574][10588][klog] INFO: test ap::aid::tests::test_map_mixed_case ... ok [00018.304805][10574][10588][klog] INFO: test ap::aid::tests::test_map_no_available_assoc_id ... ok [00018.305087][10574][10588][klog] INFO: test ap::remote_client::tests::test_add_client_multiple_times ... ok [00018.319421][10574][10588][klog] INFO: test ap::remote_client::tests::test_remote_client_handle_eapol_ind_invalid ... ok [00018.329239][10574][10588][klog] INFO: test ap::remote_client::tests::test_remote_client_ignore_timeout_if_key_handshake_succeeds ... ok [00018.329952][10574][10588][klog] INFO: test ap::remote_client::tests::test_remote_client_key_handshake ... ok [00018.333670][10574][10588][klog] INFO: test ap::remote_client::tests::test_remote_client_key_handshake_timeout ... ok [00018.333908][10574][10588][klog] INFO: test ap::remote_client::tests::test_remote_client_map ... ok [00018.343983][10574][10588][klog] INFO: test ap::rsn::tests::test_authenticator_has_invalid_mgmt_frame_protection_fields ... ok [00018.348253][10574][10588][klog] INFO: test ap::rsn::tests::test_authenticator_missing_required_mpfc ... ok [00018.349112][10574][10588][klog] INFO: test ap::rsn::tests::test_rsne_unsupported_akm ... ok [00018.349144][10574][10588][klog] INFO: test ap::rsn::tests::test_rsne_unsupported_group_data_cipher ... ok [00018.358269][10574][10588][klog] INFO: test ap::rsn::tests::test_rsne_unsupported_pairwise_cipher ... ok [00018.360809][10574][10588][klog] INFO: test ap::rsn::tests::test_supplicant_has_invalid_mgmt_frame_protection_fields ... ok [00018.363557][10574][10588][klog] INFO: test ap::rsn::tests::test_supplicant_missing_required_mpfc ... ok [00018.365151][10574][10588][klog] INFO: test ap::rsn::tests::test_supplicant_rsne_has_too_many_suites ... ok [00018.368051][10574][10588][klog] INFO: test ap::rsn::tests::test_valid_rsne ... ok [00018.369208][10574][10588][klog] INFO: test ap::rsn::tests::test_wpa2_psk_rsne_bytes ... ok [00018.373537][10574][10588][klog] INFO: test ap::tests::ap_starts_fails ... ok [00018.373626][10574][10588][klog] INFO: test ap::tests::ap_starts_success ... ok [00018.373628][10574][10588][klog] INFO: test ap::tests::ap_starts_timeout ... ok [00018.384089][10574][10588][klog] INFO: test ap::tests::ap_stops_after_started ... ok [00018.384257][10574][10588][klog] INFO: test ap::tests::ap_stops_while_idle ... ok [00018.384383][10574][10588][klog] INFO: test ap::tests::authenticate_while_sme_is_idle ... ok [00018.387741][10574][10588][klog] INFO: test ap::tests::client_associates_unprotected_network ... ok [00018.390952][10574][10588][klog] INFO: test ap::tests::client_authenticates_supported_authentication_type ... ok [00018.391340][10574][10588][klog] INFO: test ap::tests::client_authenticates_unsupported_authentication_type ... ok [00018.395888][10574][10588][klog] INFO: test ap::tests::client_restarts_authentication_flow ... ok [00018.641229][10574][10588][klog] INFO: test ap::tests::client_associates_invalid_rsne ... ok [00018.646823][10574][10588][klog] INFO: test ap::tests::start_req_while_ap_is_starting ... ok [00018.652687][10574][10588][klog] INFO: test ap::tests::multiple_clients_associate ... ok [00018.653370][10574][10588][klog] INFO: test ap::tests::rsn_handshake_timeout ... ok [00018.653433][10574][10588][klog] INFO: test ap::tests::stop_req_while_ap_is_starting ... ok [00018.655539][10574][10588][klog] INFO: test ap::tests::test_validate_config ... ok [00018.656997][10574][10588][klog] INFO: test ap::tests::test_adapt_operation ... ok [00018.660750][10574][10588][klog] INFO: test client::bss::tests::compare ... ok [00018.660771][10574][10588][klog] INFO: test client::bss::tests::convert_bss ... ok [00018.661635][10574][10588][klog] INFO: test client::bss::tests::get_best_bss_empty_list ... ok [00018.663963][10574][10588][klog] INFO: test client::bss::tests::get_best_bss_nonempty_list ... ok [00018.668714][10574][10588][klog] INFO: test client::bss::tests::group_networks_by_ssid ... ok [00018.668947][10574][10588][klog] INFO: test client::bss::tests::verify_compatibility ... ok [00018.668986][10574][10588][klog] INFO: test client::rsn::tests::test_ccmp128_group_data_pairwise_cipher_psk ... ok [00018.670478][10574][10588][klog] INFO: test client::rsn::tests::test_get_rsna_no_password_for_protected_network ... ok [00018.676572][10574][10588][klog] INFO: test client::rsn::tests::test_get_rsna_password_for_unprotected_network ... ok [00018.676962][10574][10588][klog] INFO: test client::rsn::tests::test_get_rsna_invalid_psk ... ok [00018.678985][10574][10588][klog] INFO: test client::rsn::tests::test_incompatible_akm ... ok [00018.684389][10574][10588][klog] INFO: test client::rsn::tests::test_get_rsna_psk ... ok [00018.685315][10574][10588][klog] INFO: test client::rsn::tests::test_incompatible_group_data_cipher ... ok [00018.685589][10574][10588][klog] INFO: test client::rsn::tests::test_incompatible_pairwise_cipher ... ok [00018.687400][10574][10588][klog] INFO: test client::rsn::tests::test_mixed_mode ... ok [00018.692101][10574][10588][klog] INFO: test client::rsn::tests::test_no_akm ... ok [00018.692766][10574][10588][klog] INFO: test client::rsn::tests::test_no_group_data_cipher ... ok [00018.693658][10574][10588][klog] INFO: test client::rsn::tests::test_no_pairwise_cipher ... ok [00018.695384][10574][10588][klog] INFO: test client::rsn::tests::test_rsn_capabilities ... ok [00018.695440][10574][10588][klog] INFO: test client::rsn::tests::test_tkip_group_data_cipher ... ok [00018.700661][10574][10588][klog] INFO: test client::rsn::tests::test_tkip_pairwise_cipher ... ok [00018.702572][10574][10588][klog] INFO: test client::scan::tests::discovery_scan ... ok [00018.703527][10574][10588][klog] INFO: test client::scan::tests::get_join_scan ... ok [00018.707733][10574][10588][klog] INFO: test client::scan::tests::join_scan ... ok [00018.708388][10574][10588][klog] INFO: test client::scan::tests::test_active_discovery_scan_args ... ok [00018.709011][10574][10588][klog] INFO: test client::scan::tests::test_active_join_scan_args ... ok [00018.709036][10574][10588][klog] INFO: test client::scan::tests::test_discovery_scans_dedupe_multiple_groups ... ok [00018.714263][10574][10588][klog] INFO: test client::scan::tests::test_discovery_scans_dedupe_single_group ... ok [00018.714265][10574][10588][klog] INFO: test client::scan::tests::test_passive_discovery_scan_args ... ok [00018.715802][10574][10588][klog] INFO: test client::scan::tests::test_passive_join_scan_args ... ok [00018.716157][10574][10588][klog] INFO: test client::scan::tests::test_scan_channels_arg_when_dfs_channel_handling_not_supported ... ok [00018.722137][10574][10588][klog] INFO: test client::scan::tests::test_scan_channels_arg_when_dfs_channel_handling_supported ... ok [00018.722739][10574][10588][klog] INFO: test client::state::tests::associate_failure ... ok [00018.726816][10574][10588][klog] INFO: test client::state::tests::authenticate_failure ... ok [00018.729933][10574][10588][klog] INFO: test client::state::tests::associate_happy_path_unprotected ... ok [00018.730192][10574][10588][klog] INFO: test client::state::tests::associate_happy_path_protected ... ok [00018.732498][10574][10588][klog] INFO: test client::state::tests::bad_eapol_frame_while_establishing_rsna ... ok [00018.737098][10574][10588][klog] INFO: test client::state::tests::connect_while_associating ... ok [00018.737708][10574][10588][klog] INFO: test client::state::tests::connect_while_authenticating ... ok [00018.738084][10574][10588][klog] INFO: test client::state::tests::connect_while_joining ... ok [00018.743644][10574][10588][klog] INFO: test client::state::tests::disconnect_while_associating ... ok [00018.743995][10574][10588][klog] INFO: test client::state::tests::connect_while_link_up ... ok [00018.744671][10574][10588][klog] INFO: test client::state::tests::disconnect_while_idle ... ok [00018.746265][10574][10588][klog] INFO: test client::state::tests::disconnect_while_authenticating ... ok [00018.747356][10574][10588][klog] INFO: test client::state::tests::disconnect_while_joining ... ok [00018.752509][10574][10588][klog] INFO: test client::state::tests::disconnect_while_link_up ... ok [00018.753442][10574][10588][klog] INFO: test client::state::tests::increment_att_id_on_connect ... ok [00018.755351][10574][10588][klog] INFO: test client::state::tests::increment_att_id_on_disassociate_ind ... ok [00018.755882][10574][10588][klog] INFO: test client::state::tests::join_failure ... ok [00018.761644][10574][10588][klog] INFO: test client::state::tests::key_frame_exchange_timeout_while_establishing_rsna ... ok [00018.769258][10574][10588][klog] INFO: test client::state::tests::overall_timeout_while_establishing_rsna ... ok [00018.769818][10574][10588][klog] INFO: test client::state::tests::supplicant_fails_to_process_eapol_while_establishing_rsna ... ok [00018.772204][10574][10588][klog] INFO: test client::state::tests::supplicant_fails_to_start_while_associating ... ok [00018.776946][10574][10588][klog] INFO: test client::state::tests::wrong_password_while_establishing_rsna ... ok [00018.777908][10574][10588][klog] INFO: test client::tests::connecting_generates_info_events ... ok [00018.779944][10574][10588][klog] INFO: test client::tests::connecting_no_password_supplied_for_protected_network ... ok [00018.787753][10574][10588][klog] INFO: test client::tests::connecting_password_supplied_for_unprotected_network ... ok [00018.787783][10574][10588][klog] INFO: test client::tests::connecting_psk_supplied_for_unprotected_network ... ok [00018.795971][10574][10588][klog] INFO: test client::tests::connecting_psk_supplied_for_protected_network ... ok [00018.796006][10574][10588][klog] INFO: test mesh::tests::test_validate_config ... ok [00018.796007][10574][10588][klog] INFO: test phy_selection::tests::band_id ... ok [00018.803999][10574][10588][klog] INFO: test client::tests::status_connecting_to ... ok [00018.804345][10574][10588][klog] INFO: test phy_selection::tests::test_convert_chanwidth_to_cbw ... ok [00018.810813][10574][10588][klog] INFO: test phy_selection::tests::test_convert_secchan_offset_to_cbw ... ok [00018.814849][10574][10588][klog] INFO: test phy_selection::tests::test_convert_vht_segments_to_cbw ... ok [00018.816103][10574][10588][klog] INFO: test phy_selection::tests::test_derive_cbw_ht ... ok [00018.818273][10574][10588][klog] INFO: test phy_selection::tests::test_derive_cbw_vht ... ok [00018.818302][10574][10588][klog] INFO: test phy_selection::tests::test_derive_phy_cbw ... ok [00018.818356][10574][10588][klog] INFO: test phy_selection::tests::test_derive_phy_cbw_for_ap ... ok [00018.818357][10574][10588][klog] INFO: test phy_selection::tests::test_get_band_id ... ok [00018.823659][10574][10588][klog] INFO: test phy_selection::tests::test_get_device_band_info ... ok [00018.825144][10574][10588][klog] INFO: test timer::tests::test_timer_schedule ... ok [00018.825164][10574][10588][klog] INFO: test timer::tests::test_timer_schedule_at ... ok [00019.031665][10574][10588][klog] INFO: test client::tests::connecting_password_supplied_for_protected_network ... ok [00019.276928][10574][10588][klog] INFO: test ap::tests::client_associates_valid_rsne ... ok [00019.276962][10574][10588][klog] INFO: [00019.276962][10574][10588][klog] INFO: test result: ok. 111 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [00019.276963][10574][10588][klog] INFO: [00019.280800][10574][10588][klog] INFO: PASSED: /pkgfs/packages/wlan-sme-tests/0/test/wlan_sme_lib_test passed [00019.343848][10574][10588][klog] INFO: [00019.343874][10574][10588][klog] INFO: ------------------------------------------------ [00019.343876][10574][10588][klog] INFO: RUNNING TEST: /pkgfs/packages/wlan_drivers_tests/0/test/wlan_minstrel_unittest [00019.343876][10574][10588][klog] INFO: [00019.370047][1719426487][0][amber] INFO: control_server: could not get update for run_test_component/0: merkle not found for package run_test_component/0 [00019.370283][6878][6980][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/run_test_component/0 was not found: merkle not found for package run_test_component/0 [00019.370345][6878][6980][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) [00019.383145][10574][10588][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 [00019.454763][10574][10588][klog] INFO: [00019.454799][10574][10588][klog] INFO: ------------------------------------------------ [00019.454802][10574][10588][klog] INFO: RUNNING TEST: /pkgfs/packages/wlantool2-tests/0/test/wlantool2_bin_test [00019.454803][10574][10588][klog] INFO: [00019.515865][10574][10588][klog] INFO: [00019.515898][10574][10588][klog] INFO: running 3 tests [00019.515898][10574][10588][klog] INFO: test tests::format_bssid ... ok [00019.515899][10574][10588][klog] INFO: test tests::make_credentials ... ok [00019.515899][10574][10588][klog] INFO: test tests::mac_addr_from_str ... ok [00019.515900][10574][10588][klog] INFO: [00019.515900][10574][10588][klog] INFO: test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [00019.515901][10574][10588][klog] INFO: [00019.518061][10574][10588][klog] INFO: PASSED: /pkgfs/packages/wlantool2-tests/0/test/wlantool2_bin_test passed [00019.615519][10574][10588][klog] INFO: [00019.615550][10574][10588][klog] INFO: ------------------------------------------------ [00019.615552][10574][10588][klog] INFO: RUNNING TEST: /pkgfs/packages/wlancfg-tests/0/test/wlancfg_bin_test [00019.615553][10574][10588][klog] INFO: [00019.691852][10574][10588][klog] INFO: [00019.691882][10574][10588][klog] INFO: running 17 tests [00019.744320][10574][10588][klog] INFO: test client::tests::disconnect_request_when_manually_connecting ... ok [00019.754524][10574][10588][klog] INFO: test client::tests::disconnect_request_when_already_disconnected ... ok [00019.755149][10574][10588][klog] INFO: test client::tests::auto_connect_to_known_ess ... ok [00019.756992][10574][10588][klog] INFO: test client::tests::auto_connect_when_deauth ... ok [00019.773488][10574][10588][klog] INFO: test client::tests::manual_connect_after_sme_disconnected ... ok [00019.790404][10574][10588][klog] INFO: test client::tests::disconnect_when_connected ... ok [00019.794219][10574][10588][klog] INFO: test client::tests::manual_connect_cancels_auto_connect ... ok [00019.798818][10574][10588][klog] INFO: test client::tests::manual_connect_cancels_manual_connect ... ok [00019.806968][10574][10588][klog] INFO: test client::tests::manual_connect_failure_triggers_auto_connect ... ok [00019.811527][2321][2342][klog] INFO: devhost_get_handles(0x60c85bff8a10:null) open path='foo', r=-2 [00019.812051][10574][10588][klog] INFO: test known_ess_store::tests::bail_if_path_is_bad ... ok [00019.815850][10574][10588][klog] INFO: test client::tests::manual_connect_while_sme_is_disconnecting ... ok [00019.834189][10574][10588][klog] INFO: test client::tests::scans_only_requested_with_saved_networks ... ok [00019.845804][10574][10588][klog] INFO: test known_ess_store::tests::clear ... ok [00019.848541][10574][10588][klog] INFO: test state_machine::tests::state_machine ... ok [00019.851530][10574][10588][klog] INFO: test client::tests::manual_connect_when_already_connected ... ok [00019.856744][10574][10588][klog] INFO: test known_ess_store::tests::unwrap_or_else_from_bad_file ... ok [00019.882368][10574][10588][klog] INFO: test known_ess_store::tests::store_and_lookup ... ok [00019.882370][10574][10588][klog] INFO: [00019.882371][10574][10588][klog] INFO: test result: ok. 17 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [00019.882371][10574][10588][klog] INFO: [00019.883098][10574][10588][klog] INFO: PASSED: /pkgfs/packages/wlancfg-tests/0/test/wlancfg_bin_test passed [00019.955431][10574][10588][klog] INFO: [00019.955451][10574][10588][klog] INFO: ------------------------------------------------ [00019.955453][10574][10588][klog] INFO: RUNNING TEST: /pkgfs/packages/wlanstack2-tests/0/test/wlanstack2_bin_test [00019.955454][10574][10588][klog] INFO: [00020.036411][10574][10588][klog] INFO: [00020.036425][10574][10588][klog] INFO: running 33 tests [00020.047241][10574][10588][klog] INFO: test future_util::tests::empty ... ok [00020.050258][2238][2268][klog] INFO: wlantapctl: IOCTL_WLANTAP_CREATE_WLANPHY [00020.053846][2238][2268][klog] INFO: wlantap: creating phy [00020.053983][10574][10588][klog] INFO: test future_util::tests::buffer_error ... ok [00020.055184][10574][10588][klog] INFO: test future_util::tests::group_available_items ... ok [00020.057906][2238][2268][klog] INFO: wlantap: phy successfully created [00020.057932][2238][2268][klog] INFO: wlantapctl: IOCTL_WLANTAP_CREATE_WLANPHY: success [00020.061142][2238][2268][klog] INFO: wlanphy: event loop started [00020.061177][2238][2268][klog] INFO: wlanphy_bind [00020.061453][10574][10588][klog] INFO: test future_util::tests::pending ... ok [00020.063382][10574][10588][klog] INFO: test service::tests::create_iface_not_found ... ok [00020.066792][10574][10588][klog] INFO: test service::tests::get_ap_sme_not_found ... ok [00020.073789][11753][11804][klog] INFO: wlanstack2::device [I]: new phy #0: /dev/test/wlantapctl/devwatchtap/wlanphy [00020.074272][2238][21233][klog] INFO: wlantap phy: received a 'Query' DDK request [00020.074365][2238][21233][klog] INFO: wlantap phy: responded to 'Query' with status ZX_OK [00020.074515][8383][8423][klog] INFO: wlancfg got event: OnPhyAdded { phy_id: 0 } [00020.074525][8383][8423][klog] INFO: wlancfg: phy 0 added [00020.074815][11753][11804][klog] INFO: wlanstack2::service [I]: query_phy(id = 0) [00020.074978][2238][21233][klog] INFO: wlantap phy: received a 'Query' DDK request [00020.075005][2238][21233][klog] INFO: wlantap phy: responded to 'Query' with status ZX_OK [00020.075679][11753][11804][klog] INFO: wlanstack2::service [I]: query_phy(id = 0): received a 'QueryResult' from device [00020.076414][8383][8423][klog] INFO: wlancfg: received a PhyInfo from phy #0: path is /dev/test/wlantapctl/devwatchtap/wlanphy [00020.076431][8383][8423][klog] INFO: using default wlan config entry for phy [00020.076440][8383][8423][klog] INFO: wlancfg: Creating Ap iface for phy 0 [00020.076744][2238][21233][klog] INFO: wlantap phy: received a 'CreateIface' DDK request [00020.076746][2238][21233][klog] INFO: wlantap phy: CreateIface: role not supported [00020.077182][2238][21203][klog] INFO: wlantap phy: unbinding device because the channel was closed [00020.077196][2238][21203][klog] INFO: wlantap phy: done unbinding [00020.078530][10574][10588][klog] INFO: test service::tests::create_iface_success ... ok [00020.081427][11753][11804][klog] INFO: wlanstack2::device [I]: phy removed: #0 [00020.082068][2238][2268][klog] INFO: wlantap phy: DdkRelease [00020.082082][2238][2268][klog] INFO: wlantap phy: DdkRelease done [00020.082662][8383][8423][klog] INFO: wlancfg got event: OnPhyRemoved { phy_id: 0 } [00020.082689][8383][8423][klog] INFO: wlancfg: phy removed: 0 [00020.089433][10574][10588][klog] INFO: test service::tests::get_ap_sme_wrong_role ... ok [00020.091175][10574][10588][klog] INFO: test service::tests::get_ap_sme_success ... ok [00020.091199][10574][10588][klog] INFO: test device_watch::tests::watch_phys ... ok [00020.094833][10574][10588][klog] INFO: test service::tests::get_client_sme_not_found ... ok [00020.104532][10574][10588][klog] INFO: test service::tests::get_client_sme_success ... ok [00020.104535][10574][10588][klog] INFO: test service::tests::get_client_sme_wrong_role ... ok [00020.116757][10574][10588][klog] INFO: test service::tests::list_two_ifaces ... ok [00020.122831][10574][10588][klog] INFO: test service::tests::query_iface_not_found ... ok [00020.125173][10574][10588][klog] INFO: test service::tests::list_two_phys ... ok [00020.126594][10574][10588][klog] INFO: test service::tests::query_phy_not_found ... ok [00020.131042][10574][10588][klog] INFO: test service::tests::query_iface_success ... ok [00020.131576][10574][10588][klog] INFO: test service::tests::query_phy_success ... ok [00020.136482][10574][10588][klog] INFO: test station::tests::test_timer ... ok [00020.149110][10574][10588][klog] INFO: test stats_scheduler::tests::canceled_if_server_dropped_after_request ... ok [00020.149143][10574][10588][klog] INFO: test stats_scheduler::tests::canceled_if_server_dropped_before_request ... ok [00020.157914][10574][10588][klog] INFO: test stats_scheduler::tests::schedule ... ok [00020.157943][10574][10588][klog] INFO: test watchable_map::tests::events ... ok [00020.158030][10574][10588][klog] INFO: test watchable_map::tests::get_snapshot ... ok [00020.158464][10574][10588][klog] INFO: test watchable_map::tests::insert_remove_get ... ok [00020.164509][10574][10588][klog] INFO: test watcher_service::tests::reap_watchers ... ok [00020.164531][10574][10588][klog] INFO: test watcher_service::tests::add_remove_phys ... ok [00020.168994][10574][10588][klog] INFO: test watcher_service::tests::add_remove_ifaces ... ok [00020.169016][10574][10588][klog] INFO: test watcher_service::tests::snapshot_ifaces ... ok [00020.169017][10574][10588][klog] INFO: test watcher_service::tests::snapshot_phys ... ok [00020.169023][10574][10588][klog] INFO: test watcher_service::tests::remove_watcher_on_send_error ... ok [00020.170304][10574][10588][klog] INFO: test watcher_service::tests::two_watchers ... ok [00020.170344][10574][10588][klog] INFO: [00020.170344][10574][10588][klog] INFO: test result: ok. 33 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [00020.170345][10574][10588][klog] INFO: [00020.170902][10574][10588][klog] INFO: PASSED: /pkgfs/packages/wlanstack2-tests/0/test/wlanstack2_bin_test passed