5b86d4ff5d [ 52.328552] BUG: KASAN: use-after-free in rxrpc_rcu_destroy_call
by kernel test robot
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git master
commit 5b86d4ff5dce3271dff54119e06174dc22422903
Author: David Howells <dhowells(a)redhat.com>
AuthorDate: Fri May 18 11:46:15 2018 +0100
Commit: David Howells <dhowells(a)redhat.com>
CommitDate: Wed May 23 12:01:15 2018 +0100
afs: Implement network namespacing
Implement network namespacing within AFS, but don't yet let mounts occur
outside the init namespace. An additional patch will be required propagate
the network namespace across automounts.
Signed-off-by: David Howells <dhowells(a)redhat.com>
1588def91d afs: Mark afs_net::ws_cell as __rcu and set using rcu functions
5b86d4ff5d afs: Implement network namespacing
91b15613ce Merge git://git.kernel.org/pub/scm/linux/kernel/git/davem/net
8c60c36d0b Add linux-next specific files for 20181019
+------------------------------------------+------------+------------+------------+---------------+
| | 1588def91d | 5b86d4ff5d | 91b15613ce | next-20181019 |
+------------------------------------------+------------+------------+------------+---------------+
| boot_successes | 36 | 1 | 2 | 0 |
| boot_failures | 0 | 11 | 12 | 3 |
| BUG:KASAN:use-after-free_in_r | 0 | 10 | 9 | 3 |
| RIP:native_safe_halt | 0 | 8 | 7 | |
| RIP:alloc_set_pte | 0 | 1 | 1 | |
| RIP:copy_user_enhanced_fast_string | 0 | 1 | | |
| general_protection_fault:#[##] | 0 | 1 | 1 | |
| RIP:__rb_insert_augmented | 0 | 1 | | |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 1 | 1 | |
| RIP:find_next_bit | 0 | 1 | | |
| RIP:rb_next | 0 | 0 | 1 | |
| RIP:ksys_read | 0 | 0 | 1 | |
| RIP:_raw_spin_unlock_irqrestore | 0 | 0 | 2 | |
| Mem-Info | 0 | 0 | 1 | |
| RIP:default_idle | 0 | 0 | 0 | 3 |
+------------------------------------------+------------+------------+------------+---------------+
[child3:826] lookup_dcookie (212) returned ENOSYS, marking as inactive.
[child3:826] vm86old (113:[32BIT]) returned ENOSYS, marking as inactive.
[child3:826] nfsservctl (180) returned ENOSYS, marking as inactive.
[child3:863] userfaultfd (374:[32BIT]) returned ENOSYS, marking as inactive.
[ 52.316994] ==================================================================
[ 52.328552] BUG: KASAN: use-after-free in rxrpc_rcu_destroy_call+0xca/0x110
[ 52.340568] Write of size 4 at addr ffff88001798c028 by task swapper/0/0
[ 52.351683]
[ 52.355951] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G T 4.17.0-rc5-00051-g5b86d4f #2
[ 52.377010] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 52.383690] Call Trace:
[ 52.387014] <IRQ>
[ 52.392006] dump_stack+0x7b/0xb5
[ 52.397618] print_address_description+0xab/0x360
[ 52.403979] kasan_report+0x259/0x380
[ 52.410673] ? rxrpc_rcu_destroy_call+0xca/0x110
[ 52.425250] check_memory_region+0x13b/0x1a0
[ 52.426531] kasan_check_write+0x14/0x20
[ 52.427713] rxrpc_rcu_destroy_call+0xca/0x110
[ 52.429014] rcu_process_callbacks+0x65d/0x14d0
[ 52.430334] ? rcu_gp_kthread+0x1eb0/0x1eb0
[ 52.431597] ? sched_clock+0x9/0x10
[ 52.432677] __do_softirq+0x1a9/0x49e
[ 52.489578] ? ktime_get+0x58/0xd0
[ 52.567609] irq_exit+0xee/0x110
[ 52.570629] smp_apic_timer_interrupt+0xaa/0x130
[ 52.573667] apic_timer_interrupt+0xf/0x20
[ 52.576063] </IRQ>
[ 52.577864] RIP: 0010:native_safe_halt+0x6/0x10
[ 52.580645] RSP: 0018:ffffffff84007c78 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff13
[ 52.585874] RAX: 0000000080000000 RBX: ffffffff840147c0 RCX: ffffffff8123c8c2
[ 52.590009] RDX: 1ffffffff08028f8 RSI: 0000000000000004 RDI: ffff88001a622290
[ 52.594572] RBP: ffffffff84007c78 R08: ffffed00034c4453 R09: ffffed00034c4452
[ 52.598676] R10: ffff88001a622293 R11: ffffed00034c4453 R12: 0000000000000000
[ 52.604864] R13: 0000000000000000 R14: ffffffff840147c0 R15: ffffffff844ee7d8
[ 52.607929] ? rcu_dynticks_eqs_enter+0x22/0x30
[ 52.609627] ? rcu_dynticks_eqs_enter+0x22/0x30
[ 52.611451] default_idle+0x9/0x10
[ 52.612689] arch_cpu_idle+0xa/0x10
[ 52.614053] default_idle_call+0x36/0x50
[ 52.615604] do_idle+0x221/0x2f0
[ 52.616923] ? arch_cpu_idle_exit+0x40/0x40
[ 52.618563] cpu_startup_entry+0xc6/0xd0
[ 52.620292] ? play_idle+0x350/0x350
[ 52.621840] rest_init+0xec/0xf0
[ 52.623322] start_kernel+0xd22/0xe8f
[ 52.624896] ? thread_stack_cache_init+0x2e/0x2e
[ 52.626685] ? early_idt_handler_common+0x3b/0x52
[ 52.628500] x86_64_start_reservations+0x55/0x76
[ 52.630205] x86_64_start_kernel+0x83/0xa6
[ 52.631803] secondary_startup_64+0xa5/0xb0
[ 52.633423]
[ 52.634076] The buggy address belongs to the page:
[ 52.635904] page:ffff88001f1d5300 count:0 mapcount:-127 mapping:0000000000000000 index:0x0
[ 52.638854] flags: 0x402000000000()
[ 52.640205] raw: 0000402000000000 0000000000000000 0000000000000000 00000000ffffff80
# HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start v4.18 v4.17 --
git bisect bad c81b995f00c7a1c2ca9ad67f5bb4a50d02f98f84 # 19:57 B 0 1 16 0 Merge branch 'perf-urgent-for-linus' of git://git.kernel.org/pub/scm/linux/kernel/git/tip/tip
git bisect good 2a70ea5cda00214a1d573acf19fa0cd06d947e38 # 20:28 G 12 0 0 0 Merge tag 'hsi-for-4.18' of git://git.kernel.org/pub/scm/linux/kernel/git/sre/linux-hsi
git bisect good e7655d2b25466c534ed1f539367dae595bb0bd20 # 20:52 G 12 0 0 0 Merge tag 'for-4.18-part2-tag' of git://git.kernel.org/pub/scm/linux/kernel/git/kdave/linux
git bisect bad 6d90eb7ba341b3eb035121eff0b69d370cbc251e # 21:09 B 0 3 18 0 Merge tag 'dma-rename-4.18' of git://git.infradead.org/users/hch/dma-mapping
git bisect good 29d6849d88b61edf130aef500acad78206bda3cd # 21:32 G 11 0 0 0 Merge branch 'work.compat' of git://git.kernel.org/pub/scm/linux/kernel/git/viro/vfs
git bisect bad 3ed1d012ac3e60e0e95cda6fbd59352ec6dcbb88 # 21:52 B 0 4 19 0 Fix Documentation build due to rename of main.c to mtrr.c
git bisect bad dbb2816fc78abb0282a803bea1119e2f31354b20 # 22:14 B 0 1 16 0 Merge tag 'fsnotify_for_v4.18-rc1' of git://git.kernel.org/pub/scm/linux/kernel/git/jack/linux-fs
git bisect bad 35773c93817c5f2df264d013978e7551056a063a # 22:32 B 0 3 18 0 Merge branch 'afs-proc' of git://git.kernel.org/pub/scm/linux/kernel/git/viro/vfs
git bisect bad 5b86d4ff5dce3271dff54119e06174dc22422903 # 22:47 B 0 1 16 0 afs: Implement network namespacing
git bisect good 5d9de25d934b9a6e3c9efdce782b0d23d0f1fa2a # 23:26 G 12 0 0 0 afs: Rearrange fs/afs/proc.c to remove remaining predeclarations.
git bisect good c875c76a061df306ca82b69ba80b8da3ee758c87 # 23:49 G 11 0 0 0 afs: Fix a Sparse warning in xdr_decode_AFSFetchStatus()
git bisect good 1588def91d58bf70afe1acf9fc0331fa26e974f4 # 00:14 G 11 0 0 0 afs: Mark afs_net::ws_cell as __rcu and set using rcu functions
# first bad commit: [5b86d4ff5dce3271dff54119e06174dc22422903] afs: Implement network namespacing
git bisect good 1588def91d58bf70afe1acf9fc0331fa26e974f4 # 00:25 G 34 0 0 0 afs: Mark afs_net::ws_cell as __rcu and set using rcu functions
# extra tests with debug options
git bisect bad 5b86d4ff5dce3271dff54119e06174dc22422903 # 00:45 B 1 2 1 1 afs: Implement network namespacing
# extra tests on HEAD of linux-devel/devel-spot-201810190850
git bisect bad 2500a6c9a2c45cf82f0c6266097cde98375c1560 # 00:50 B 0 13 32 0 0day head guard for 'devel-spot-201810190850'
# extra tests on tree/branch linus/master
git bisect bad 91b15613ce7fb3e724ca0d433eef8e6bf15322af # 01:08 B 0 1 16 0 Merge git://git.kernel.org/pub/scm/linux/kernel/git/davem/net
# extra tests on tree/branch linux-next/master
git bisect bad 8c60c36d0b8c92599b8f0ec391b5250bc40e8e05 # 01:33 B 0 2 17 0 Add linux-next specific files for 20181019
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
2 years, 4 months
- 1
- 0
[mm/memory.c] 6558038e45: general_protection_fault:#[##]
by kernel test robot
FYI, we noticed the following commit (built with gcc-6):
commit: 6558038e4540a22ee4f99a5def74791189102bc0 ("mm/memory.c: recheck page table entry with page table lock held")
https://git.kernel.org/cgit/linux/kernel/git/next/linux-next.git master
in testcase: trinity
with following parameters:
runtime: 300s
test-description: Trinity is a linux system call fuzz tester.
test-url: http://codemonkey.org.uk/projects/trinity/
on test machine: qemu-system-x86_64 -enable-kvm -cpu qemu64,+ssse3 -smp 4 -m 4G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+---------------------------------------------------------+------------+------------+
| | 138bfb21b5 | 6558038e45 |
+---------------------------------------------------------+------------+------------+
| boot_successes | 96 | 79 |
| boot_failures | 2 | 25 |
| invoked_oom-killer:gfp_mask=0x | 2 | 4 |
| Mem-Info | 2 | 4 |
| Out_of_memory_and_no_killable_processes | 2 | 4 |
| Kernel_panic-not_syncing:System_is_deadlocked_on_memory | 2 | 4 |
| general_protection_fault:#[##] | 0 | 20 |
| RIP:__handle_mm_fault | 0 | 20 |
| RIP:copy_user_generic_string | 0 | 13 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 20 |
| RIP:strncpy_from_user | 0 | 2 |
| RIP:__get_user_4 | 0 | 1 |
| IP-Config:Auto-configuration_of_network_failed | 0 | 1 |
+---------------------------------------------------------+------------+------------+
[ 287.220279] [main] 91747 iterations. [F:66755 S:24485 HI:5262]
[ 287.220304]
[ 297.153086] futex_wake_op: trinity-c2 tries to shift op by 48; fix this program
[ 299.963834] kasan: CONFIG_KASAN_INLINE enabled
[ 299.974712] kasan: GPF could be caused by NULL-ptr deref or user memory access
[ 299.979477] general protection fault: 0000 [#1] PREEMPT KASAN PTI
[ 299.983209] CPU: 0 PID: 2174 Comm: trinity-c2 Not tainted 4.19.0-rc6-00392-g6558038 #1
[ 299.988863] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 299.995335] RIP: 0010:__handle_mm_fault+0xcba/0x25ce
[ 299.999216] Code: 48 83 c0 5c 48 89 84 24 10 01 00 00 e8 6c 87 eb ff 48 8b 9c 24 08 01 00 00 48 b8 00 00 00 00 00 fc ff df 48 89 da 48 c1 ea 03 <80> 3c 02 00 74 08 48 89 df e8 84 92 03 00 48 8b 03 48 83 e0 9f 48
[ 300.008531] RSP: 0018:ffff880079f079e0 EFLAGS: 00010246
[ 300.010074] RAX: dffffc0000000000 RBX: 0000000000000000 RCX: 000ffffffffff000
[ 300.012044] RDX: 0000000000000000 RSI: 00000000b9454067 RDI: ffff88007d816460
[ 300.014537] RBP: ffff88007d888008 R08: fffffbfff06960cc R09: fffffbfff06960cc
[ 300.016490] R10: fffffbfff06960cc R11: 0000000000000000 R12: ffff88007d888098
[ 300.018812] R13: ffff88007d888058 R14: ffff88007a94b4c0 R15: ffff88007d888048
[ 300.020769] FS: 00007ff4e650cb40(0000) GS:ffffffff8344b000(0000) knlGS:0000000000000000
[ 300.023159] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 300.024792] CR2: 00007ff4e63e9000 CR3: 00000000a52ec000 CR4: 00000000000006f0
[ 300.029539] Call Trace:
[ 300.030461] ? vm_insert_page+0x46c/0x46c
[ 300.031721] ? __read_once_size_nocheck+0x7/0x7
[ 300.033097] ? deref_stack_reg+0xb7/0xb7
[ 300.034142] ? entry_SYSCALL_64_after_hwframe+0x43/0xa9
[ 300.035435] ? proc_doulongvec_minmax+0x55/0x60
[ 300.036613] ? kernel_text_address+0x5b/0x6f
[ 300.037737] handle_mm_fault+0x3d2/0x512
[ 300.038778] __do_page_fault+0x4f4/0x956
[ 300.039837] ? bad_area_access_error+0x152/0x152
[ 300.041020] ? do_syscall_64+0x40b/0x5ba
[ 300.042064] ? __kernel_text_address+0x17/0x21
[ 300.043359] async_page_fault+0x1e/0x30
[ 300.044585] RIP: 0010:copy_user_generic_string+0x2c/0x40
[ 300.046147] Code: 00 83 fa 08 72 27 89 f9 83 e1 07 74 15 83 e9 08 f7 d9 29 ca 8a 06 88 07 48 ff c6 48 ff c7 ff c9 75 f2 89 d1 c1 e9 03 83 e2 07 <f3> 48 a5 89 d1 f3 a4 31 c0 0f 1f 00 c3 0f 1f 80 00 00 00 00 0f 1f
[ 300.051175] RSP: 0018:ffff880079f07d30 EFLAGS: 00010246
[ 300.052714] RAX: ffffed000f3e0fb2 RBX: 0000000000000010 RCX: 0000000000000002
[ 300.054666] RDX: 0000000000000000 RSI: 00007ff4e63e9000 RDI: ffff880079f07d80
[ 300.056649] RBP: 00007ff4e63e9000 R08: ffffed000f3e0fb2 R09: ffffed000f3e0fb0
[ 300.058626] R10: ffffed000f3e0fb1 R11: 0000000000000001 R12: 0000000000000010
[ 300.060582] R13: ffff880079f07d80 R14: 0000000000000007 R15: 0000000000000000
[ 300.062536] _copy_from_user+0x8a/0xae
[ 300.063751] get_timespec64+0x67/0xdf
[ 300.064926] ? timespec_trunc+0xb1/0xb1
[ 300.066156] __x64_sys_utimensat+0x10f/0x18c
[ 300.067463] ? __x64_sys_utime+0x13f/0x13f
[ 300.068696] ? mprotect_fixup+0x5e3/0x5e3
[ 300.069907] ? do_syscall_64+0x40b/0x5ba
[ 300.071144] do_syscall_64+0x40b/0x5ba
[ 300.072488] ? syscall_return_slowpath+0x10d/0x10d
[ 300.073925] ? schedule+0x179/0x194
[ 300.075071] ? exit_to_usermode_loop+0x40/0xec
[ 300.076441] entry_SYSCALL_64_after_hwframe+0x44/0xa9
[ 300.077941] RIP: 0033:0x7ff4e5e2f229
[ 300.079126] Code: 00 f3 c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 40 00 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 3f 4c 2b 00 f7 d8 64 89 01 48
[ 300.084181] RSP: 002b:00007ffd78f66ea8 EFLAGS: 00000246 ORIG_RAX: 0000000000000118
[ 300.086471] RAX: ffffffffffffffda RBX: 0000000000000118 RCX: 00007ff4e5e2f229
[ 300.088455] RDX: 00007ff4e63e9000 RSI: 000055b4a1f93a00 RDI: 0000000000000007
[ 300.090433] RBP: 00007ffd78f66f50 R08: 000000000000fffe R09: 0021b4190da100ab
[ 300.092373] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000002
[ 300.094341] R13: 00007ff4e64f7058 R14: 00007ff4e650cad8 R15: 00007ff4e64f7000
[ 300.096316] Modules linked in: input_leds psmouse i6300esb ide_pci_generic parport_pc parport qemu_fw_cfg
[ 300.099205] ---[ end trace 395ba4f367d74c50 ]---
[ 300.100612] RIP: 0010:__handle_mm_fault+0xcba/0x25ce
[ 300.102114] Code: 48 83 c0 5c 48 89 84 24 10 01 00 00 e8 6c 87 eb ff 48 8b 9c 24 08 01 00 00 48 b8 00 00 00 00 00 fc ff df 48 89 da 48 c1 ea 03 <80> 3c 02 00 74 08 48 89 df e8 84 92 03 00 48 8b 03 48 83 e0 9f 48
[ 300.107178] RSP: 0018:ffff880079f079e0 EFLAGS: 00010246
[ 300.108703] RAX: dffffc0000000000 RBX: 0000000000000000 RCX: 000ffffffffff000
[ 300.110673] RDX: 0000000000000000 RSI: 00000000b9454067 RDI: ffff88007d816460
[ 300.112666] RBP: ffff88007d888008 R08: fffffbfff06960cc R09: fffffbfff06960cc
[ 300.114636] R10: fffffbfff06960cc R11: 0000000000000000 R12: ffff88007d888098
[ 300.116613] R13: ffff88007d888058 R14: ffff88007a94b4c0 R15: ffff88007d888048
[ 300.118574] FS: 00007ff4e650cb40(0000) GS:ffffffff8344b000(0000) knlGS:0000000000000000
[ 300.120971] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 300.122646] CR2: 00007ff4e63e9000 CR3: 00000000a52ec000 CR4: 00000000000006f0
[ 300.124623] Kernel panic - not syncing: Fatal exception
[ 300.126174] Kernel Offset: disabled
Elapsed time: 300
#!/bin/bash
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
lkp
2 years, 4 months
- 3
- 2
262bd453bf: drm:i915_gem_init_hw[i915]]
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: 262bd453bf5f9a2a3d54eb50607000c06bfd2ef0 ("[Intel-gfx] [PATCH 2/2] HAX enable GuC for CI")
url: https://github.com/0day-ci/linux/commits/Michal-Wajdeczko/drm-i915-guc-Li...
base: git://anongit.freedesktop.org/drm-intel for-linux-next
in testcase: ltp
with following parameters:
disk: 1HDD
fs: btrfs
test: dio-01
test-description: The LTP testsuite contains a collection of tools for testing the Linux kernel and related features.
test-url: http://linux-test-project.github.io/
on test machine: 8 threads Intel(R) Core(TM) i7-6700 CPU @ 3.40GHz with 8G memory
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+----------------------------------------------------------------------------+------------+------------+
| | 8b7a80e90a | 262bd453bf |
+----------------------------------------------------------------------------+------------+------------+
| boot_successes | 10 | 2 |
| boot_failures | 0 | 4 |
| drm:i915_gem_init_hw[i915]] | 0 | 4 |
| WARNING:at_drivers/gpu/drm/drm_mode_config.c:#drm_mode_config_cleanup[drm] | 0 | 4 |
| RIP:drm_mode_config_cleanup[drm] | 0 | 4 |
+----------------------------------------------------------------------------+------------+------------+
[ 139.236054] diotest06 1 TPASS : Read with Direct IO, Write without
[ 139.236056]
[ 142.491343] [drm] HuC: Failed to fetch firmware i915/skl_huc_ver01_07_1398.bin (error -11)
[ 142.499630] [drm] HuC: Firmware can be downloaded from https://git.kernel.org/pub/scm/linux/kernel/git/firmware/linux-firmware.g...
[ 142.513132] i915 0000:00:02.0: GuC initialization failed -8
[ 142.518749] [drm:i915_gem_init_hw [i915]] *ERROR* Enabling uc failed (-8)
[ 162.116015] LTP: starting dio22 (diotest2 -b 65536 -i 1000 -o 104857600)
[ 162.117551] diotest06 1 TPASS : Read with Direct IO, Write without
[ 162.122746]
[ 162.131883] diotest06 2 TPASS : Write with Direct IO, Read without
[ 162.131884]
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
Thanks,
lkp
2 years, 4 months
- 1
- 0
[f2fs] 4d8253119c: aim7.jobs-per-min -11.0% regression
by kernel test robot
Greeting,
FYI, we noticed a -11.0% regression of aim7.jobs-per-min due to commit:
commit: 4d8253119c91ee549db724adac9ff3f7c1b6f318 ("f2fs: checkpoint disabling")
https://git.kernel.org/cgit/linux/kernel/git/jaegeuk/f2fs.git dev
in testcase: aim7
on test machine: 40 threads Intel(R) Xeon(R) CPU E5-2690 v2 @ 3.00GHz with 384G memory
with following parameters:
disk: 1BRD_48G
fs: f2fs
test: disk_rw
load: 3000
cpufreq_governor: performance
test-description: AIM7 is a traditional UNIX system level benchmark suite which is used to test and measure the performance of multiuser system.
test-url: https://sourceforge.net/projects/aimbench/files/aim-suite7/
Details are as below:
-------------------------------------------------------------------------------------------------->
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
=========================================================================================
compiler/cpufreq_governor/disk/fs/kconfig/load/rootfs/tbox_group/test/testcase:
gcc-7/performance/1BRD_48G/f2fs/x86_64-rhel-7.2/3000/debian-x86_64-2018-04-03.cgz/lkp-ivb-ep01/disk_rw/aim7
commit:
fb7d70db30 ("f2fs: clear PageError on the read path")
4d8253119c ("f2fs: checkpoint disabling")
fb7d70db305a1446 4d8253119c91ee549db724adac
---------------- --------------------------
fail:runs %reproduction fail:runs
| | |
1:4 -25% :4 dmesg.WARNING:at_ip__slab_free/0x
%stddev %change %stddev
\ | \
107974 ± 2% -11.0% 96114 aim7.jobs-per-min
167.22 ± 3% +12.2% 187.65 aim7.time.elapsed_time
167.22 ± 3% +12.2% 187.65 aim7.time.elapsed_time.max
795131 +26.6% 1006572 ± 4% aim7.time.involuntary_context_switches
6079 ± 3% +13.6% 6904 aim7.time.system_time
288452 ± 64% -61.4% 111309 ±134% cpuidle.C3.usage
69671 +25.6% 87542 ± 13% interrupts.CAL:Function_call_interrupts
1165 -100.0% 0.00 meminfo.Mlocked
1.94 ± 4% -0.3 1.68 ± 2% mpstat.cpu.usr%
870227 ± 2% +20.6% 1049363 ± 9% softirqs.RCU
2754729 ± 6% +13.9% 3137384 softirqs.TIMER
7056 ± 3% -12.0% 6208 vmstat.io.bo
202.00 ± 7% +33.0% 268.75 ± 10% vmstat.procs.r
50764 ± 16% -27.5% 36813 ± 14% numa-meminfo.node0.SReclaimable
153567 ± 26% -36.0% 98338 ± 42% numa-meminfo.node0.Slab
45293 ± 18% +32.7% 60114 ± 9% numa-meminfo.node1.SReclaimable
8716 ± 3% +19.6% 10428 ± 2% slabinfo.ext4_io_end.active_objs
8716 ± 3% +19.6% 10428 ± 2% slabinfo.ext4_io_end.num_objs
9434 ± 3% +14.9% 10842 slabinfo.f2fs_extent_tree.active_objs
9434 ± 3% +14.9% 10842 slabinfo.f2fs_extent_tree.num_objs
18819 ± 12% -26.8% 13776 ± 22% numa-vmstat.node0
156.25 ± 14% -100.0% 0.00 numa-vmstat.node0.nr_mlock
12688 ± 16% -27.5% 9202 ± 14% numa-vmstat.node0.nr_slab_reclaimable
14753 ± 16% +34.5% 19839 ± 15% numa-vmstat.node1
133.00 ± 14% -100.0% 0.00 numa-vmstat.node1.nr_mlock
11327 ± 18% +32.7% 15036 ± 9% numa-vmstat.node1.nr_slab_reclaimable
410794 +4.6% 429491 proc-vmstat.nr_dirty
652264 +2.8% 670355 proc-vmstat.nr_file_pages
409361 +4.5% 427950 proc-vmstat.nr_inactive_file
289.75 ± 2% -100.0% 0.00 proc-vmstat.nr_mlock
409361 +4.5% 427950 proc-vmstat.nr_zone_inactive_file
410651 +4.6% 429423 proc-vmstat.nr_zone_write_pending
590098 ± 2% +8.1% 638010 proc-vmstat.pgfault
1745 -4.3% 1669 proc-vmstat.unevictable_pgs_culled
2898 +1.9% 2954 turbostat.Avg_MHz
288234 ± 64% -61.4% 111193 ±134% turbostat.C3
4.03 ± 22% -26.4% 2.97 ± 14% turbostat.CPU%c1
156.41 +4.4% 163.25 turbostat.CorWatt
14079224 ± 3% +13.9% 16040215 turbostat.IRQ
184.51 +3.5% 190.99 turbostat.PkgWatt
40.65 ± 2% -5.8% 38.28 turbostat.RAMWatt
7981 ± 3% +13.5% 9060 ± 2% turbostat.SMI
1.139e+12 +16.9% 1.332e+12 perf-stat.branch-instructions
0.75 ± 3% -0.1 0.65 ± 3% perf-stat.branch-miss-rate%
36.50 ± 4% +3.5 40.04 perf-stat.cache-miss-rate%
1735749 ± 2% +12.3% 1949439 ± 3% perf-stat.context-switches
1.951e+13 ± 3% +15.7% 2.257e+13 perf-stat.cpu-cycles
493731 ± 4% +10.4% 545082 ± 2% perf-stat.cpu-migrations
1.393e+12 +13.9% 1.587e+12 perf-stat.dTLB-loads
5.084e+12 +15.2% 5.857e+12 perf-stat.instructions
578938 ± 2% +7.5% 622537 perf-stat.minor-faults
42.61 ± 2% -1.7 40.91 perf-stat.node-load-miss-rate%
42.96 -0.8 42.18 perf-stat.node-store-miss-rate%
6.905e+09 +6.5% 7.353e+09 perf-stat.node-stores
578951 ± 2% +7.5% 622540 perf-stat.page-faults
52097 +55.9% 81203 sched_debug.cfs_rq:/.exec_clock.avg
56166 +51.7% 85220 sched_debug.cfs_rq:/.exec_clock.max
51857 +56.0% 80884 sched_debug.cfs_rq:/.exec_clock.min
485.17 ± 35% -33.0% 325.25 ± 5% sched_debug.cfs_rq:/.load_avg.max
2235072 +59.4% 3562439 sched_debug.cfs_rq:/.min_vruntime.avg
2401666 +58.0% 3795568 sched_debug.cfs_rq:/.min_vruntime.max
2147122 +58.4% 3401299 sched_debug.cfs_rq:/.min_vruntime.min
56208 ± 27% +65.1% 92820 ± 30% sched_debug.cfs_rq:/.min_vruntime.stddev
9.62 ± 7% +114.2% 20.60 ± 80% sched_debug.cfs_rq:/.runnable_load_avg.stddev
56172 ± 27% +65.1% 92751 ± 30% sched_debug.cfs_rq:/.spread0.stddev
89807 +34.6% 120913 sched_debug.cpu.clock.avg
89833 +34.6% 120952 sched_debug.cpu.clock.max
89776 +34.6% 120860 sched_debug.cpu.clock.min
16.53 ± 23% +88.8% 31.21 ± 37% sched_debug.cpu.clock.stddev
89807 +34.6% 120913 sched_debug.cpu.clock_task.avg
89833 +34.6% 120952 sched_debug.cpu.clock_task.max
89776 +34.6% 120860 sched_debug.cpu.clock_task.min
16.53 ± 23% +88.8% 31.21 ± 37% sched_debug.cpu.clock_task.stddev
43.50 +10.6% 48.12 ± 5% sched_debug.cpu.cpu_load[0].max
9.78 ± 7% +18.1% 11.55 ± 6% sched_debug.cpu.cpu_load[0].stddev
4471 ± 8% +25.1% 5594 ± 5% sched_debug.cpu.curr->pid.max
872.98 ± 6% +19.2% 1040 ± 6% sched_debug.cpu.curr->pid.stddev
65019 +46.6% 95328 sched_debug.cpu.nr_load_updates.avg
73610 +42.2% 104667 sched_debug.cpu.nr_load_updates.max
62894 +48.5% 93410 sched_debug.cpu.nr_load_updates.min
16278 ± 5% +48.9% 24238 sched_debug.cpu.nr_switches.avg
22788 ± 7% +31.3% 29923 ± 5% sched_debug.cpu.nr_switches.max
14444 ± 5% +53.2% 22129 sched_debug.cpu.nr_switches.min
216.67 ± 2% +41.4% 306.31 ± 22% sched_debug.cpu.nr_uninterruptible.max
91.09 ± 10% +34.7% 122.70 ± 6% sched_debug.cpu.nr_uninterruptible.stddev
15319 ± 6% +52.1% 23302 sched_debug.cpu.sched_count.avg
17371 ± 6% +51.4% 26294 ± 4% sched_debug.cpu.sched_count.max
14141 ± 5% +55.2% 21948 sched_debug.cpu.sched_count.min
729.23 ± 7% +30.0% 948.12 ± 15% sched_debug.cpu.sched_count.stddev
6468 ± 7% +42.8% 9234 sched_debug.cpu.ttwu_count.avg
8790 ± 8% +41.8% 12466 ± 6% sched_debug.cpu.ttwu_count.max
5087 ± 5% +43.3% 7288 ± 2% sched_debug.cpu.ttwu_count.min
810.76 ± 12% +33.8% 1084 ± 12% sched_debug.cpu.ttwu_count.stddev
1794 ± 4% +59.2% 2857 ± 2% sched_debug.cpu.ttwu_local.avg
2499 ± 10% +51.1% 3777 ± 10% sched_debug.cpu.ttwu_local.max
1405 ± 4% +58.2% 2223 ± 2% sched_debug.cpu.ttwu_local.min
231.51 ± 13% +47.9% 342.52 ± 11% sched_debug.cpu.ttwu_local.stddev
89776 +34.6% 120860 sched_debug.cpu_clk
88952 +34.9% 120037 sched_debug.ktime
0.00 ± 16% -100.0% 0.00 sched_debug.rt_rq:/.rt_time.avg
0.02 ± 16% -100.0% 0.00 sched_debug.rt_rq:/.rt_time.max
0.00 ± 16% -100.0% 0.00 sched_debug.rt_rq:/.rt_time.stddev
90219 +34.5% 121306 sched_debug.sched_clk
1.63 ± 15% -0.5 1.12 ± 12% perf-profile.calltrace.cycles-pp.f2fs_lookup_extent_cache.f2fs_write_begin.generic_perform_write.__generic_file_write_iter.f2fs_file_write_iter
0.74 ± 25% -0.5 0.27 ±100% perf-profile.calltrace.cycles-pp.balance_dirty_pages_ratelimited.generic_perform_write.__generic_file_write_iter.f2fs_file_write_iter.__vfs_write
1.30 -0.2 1.10 ± 4% perf-profile.calltrace.cycles-pp.syscall_return_via_sysret
0.78 ± 9% -0.2 0.59 ± 3% perf-profile.calltrace.cycles-pp.f2fs_preallocate_blocks.f2fs_file_write_iter.__vfs_write.vfs_write.ksys_write
0.58 ± 4% -0.2 0.39 ± 57% perf-profile.calltrace.cycles-pp.security_file_permission.vfs_write.ksys_write.do_syscall_64.entry_SYSCALL_64_after_hwframe
1.60 -0.2 1.42 perf-profile.calltrace.cycles-pp.pagecache_get_page.f2fs_write_begin.generic_perform_write.__generic_file_write_iter.f2fs_file_write_iter
1.37 ± 2% -0.2 1.20 ± 6% perf-profile.calltrace.cycles-pp.__entry_SYSCALL_64_trampoline
1.31 ± 4% -0.1 1.18 perf-profile.calltrace.cycles-pp.iov_iter_copy_from_user_atomic.generic_perform_write.__generic_file_write_iter.f2fs_file_write_iter.__vfs_write
1.13 ± 4% -0.1 1.02 perf-profile.calltrace.cycles-pp.copyin.iov_iter_copy_from_user_atomic.generic_perform_write.__generic_file_write_iter.f2fs_file_write_iter
0.72 ± 6% -0.1 0.62 ± 3% perf-profile.calltrace.cycles-pp.pagecache_get_page.__get_node_page.f2fs_write_begin.generic_perform_write.__generic_file_write_iter
1.07 ± 5% -0.1 0.98 ± 2% perf-profile.calltrace.cycles-pp.copy_user_enhanced_fast_string.copyin.iov_iter_copy_from_user_atomic.generic_perform_write.__generic_file_write_iter
0.61 ± 3% -0.1 0.53 perf-profile.calltrace.cycles-pp.find_get_entry.pagecache_get_page.f2fs_write_begin.generic_perform_write.__generic_file_write_iter
96.44 +0.6 97.02 perf-profile.calltrace.cycles-pp.entry_SYSCALL_64_after_hwframe
96.32 +0.6 96.89 perf-profile.calltrace.cycles-pp.do_syscall_64.entry_SYSCALL_64_after_hwframe
12.03 ± 3% +0.8 12.81 perf-profile.calltrace.cycles-pp.native_queued_spin_lock_slowpath._raw_spin_lock.f2fs_inode_dirtied.f2fs_mark_inode_dirty_sync.f2fs_reserve_new_blocks
12.11 ± 3% +0.8 12.90 perf-profile.calltrace.cycles-pp.f2fs_inode_dirtied.f2fs_mark_inode_dirty_sync.f2fs_reserve_new_blocks.f2fs_reserve_block.f2fs_get_block
12.11 ± 3% +0.8 12.90 perf-profile.calltrace.cycles-pp.f2fs_mark_inode_dirty_sync.f2fs_reserve_new_blocks.f2fs_reserve_block.f2fs_get_block.f2fs_write_begin
12.09 ± 3% +0.8 12.89 perf-profile.calltrace.cycles-pp._raw_spin_lock.f2fs_inode_dirtied.f2fs_mark_inode_dirty_sync.f2fs_reserve_new_blocks.f2fs_reserve_block
93.07 +1.0 94.07 perf-profile.calltrace.cycles-pp.ksys_write.do_syscall_64.entry_SYSCALL_64_after_hwframe
92.77 +1.0 93.82 perf-profile.calltrace.cycles-pp.vfs_write.ksys_write.do_syscall_64.entry_SYSCALL_64_after_hwframe
25.85 ± 3% +1.1 26.98 perf-profile.calltrace.cycles-pp.f2fs_get_block.f2fs_write_begin.generic_perform_write.__generic_file_write_iter.f2fs_file_write_iter
25.65 ± 3% +1.2 26.82 perf-profile.calltrace.cycles-pp.f2fs_reserve_block.f2fs_get_block.f2fs_write_begin.generic_perform_write.__generic_file_write_iter
25.61 ± 3% +1.2 26.80 perf-profile.calltrace.cycles-pp.f2fs_reserve_new_blocks.f2fs_reserve_block.f2fs_get_block.f2fs_write_begin.generic_perform_write
91.35 +1.3 92.69 perf-profile.calltrace.cycles-pp.__vfs_write.vfs_write.ksys_write.do_syscall_64.entry_SYSCALL_64_after_hwframe
90.88 +1.7 92.56 perf-profile.calltrace.cycles-pp.f2fs_file_write_iter.__vfs_write.vfs_write.ksys_write.do_syscall_64
88.26 +2.7 90.92 perf-profile.calltrace.cycles-pp.__generic_file_write_iter.f2fs_file_write_iter.__vfs_write.vfs_write.ksys_write
86.91 ± 2% +3.2 90.10 perf-profile.calltrace.cycles-pp.generic_perform_write.__generic_file_write_iter.f2fs_file_write_iter.__vfs_write.vfs_write
52.04 ± 3% +3.3 55.37 perf-profile.calltrace.cycles-pp.f2fs_write_end.generic_perform_write.__generic_file_write_iter.f2fs_file_write_iter.__vfs_write
49.56 ± 3% +3.6 53.17 perf-profile.calltrace.cycles-pp.f2fs_mark_inode_dirty_sync.f2fs_write_end.generic_perform_write.__generic_file_write_iter.f2fs_file_write_iter
49.56 ± 3% +3.6 53.17 perf-profile.calltrace.cycles-pp.f2fs_inode_dirtied.f2fs_mark_inode_dirty_sync.f2fs_write_end.generic_perform_write.__generic_file_write_iter
48.87 ± 4% +3.6 52.49 perf-profile.calltrace.cycles-pp.native_queued_spin_lock_slowpath._raw_spin_lock.f2fs_inode_dirtied.f2fs_mark_inode_dirty_sync.f2fs_write_end
49.49 ± 3% +3.6 53.14 perf-profile.calltrace.cycles-pp._raw_spin_lock.f2fs_inode_dirtied.f2fs_mark_inode_dirty_sync.f2fs_write_end.generic_perform_write
1.82 ± 14% -0.6 1.26 ± 12% perf-profile.children.cycles-pp.f2fs_lookup_extent_cache
0.58 ± 63% -0.4 0.18 ± 11% perf-profile.children.cycles-pp.file_update_time
2.37 ± 2% -0.3 2.08 perf-profile.children.cycles-pp.pagecache_get_page
0.53 ± 16% -0.2 0.28 ± 34% perf-profile.children.cycles-pp._raw_read_lock
1.48 ± 2% -0.2 1.24 ± 4% perf-profile.children.cycles-pp.syscall_return_via_sysret
0.74 ± 25% -0.2 0.52 ± 6% perf-profile.children.cycles-pp.balance_dirty_pages_ratelimited
0.46 ± 38% -0.2 0.25 ± 3% perf-profile.children.cycles-pp.ksys_lseek
0.80 ± 9% -0.2 0.60 ± 4% perf-profile.children.cycles-pp.f2fs_preallocate_blocks
1.49 ± 2% -0.2 1.30 ± 6% perf-profile.children.cycles-pp.__entry_SYSCALL_64_trampoline
0.31 ± 54% -0.2 0.12 ± 10% perf-profile.children.cycles-pp.f2fs_llseek
0.23 ± 54% -0.2 0.07 ± 12% perf-profile.children.cycles-pp.f2fs_map_blocks
1.09 ± 3% -0.1 0.94 perf-profile.children.cycles-pp.find_get_entry
1.33 ± 4% -0.1 1.18 perf-profile.children.cycles-pp.iov_iter_copy_from_user_atomic
0.45 ± 11% -0.1 0.32 ± 7% perf-profile.children.cycles-pp.cpuidle_enter_state
0.46 ± 11% -0.1 0.33 ± 6% perf-profile.children.cycles-pp.secondary_startup_64
0.46 ± 11% -0.1 0.33 ± 6% perf-profile.children.cycles-pp.cpu_startup_entry
0.46 ± 11% -0.1 0.33 ± 6% perf-profile.children.cycles-pp.do_idle
0.44 ± 11% -0.1 0.32 ± 6% perf-profile.children.cycles-pp.intel_idle
0.45 ± 9% -0.1 0.33 ± 6% perf-profile.children.cycles-pp.start_secondary
0.25 ± 38% -0.1 0.13 ± 9% perf-profile.children.cycles-pp.read_node_page
1.14 ± 4% -0.1 1.03 ± 2% perf-profile.children.cycles-pp.copyin
1.08 ± 5% -0.1 0.99 ± 2% perf-profile.children.cycles-pp.copy_user_enhanced_fast_string
0.54 ± 7% -0.1 0.47 ± 4% perf-profile.children.cycles-pp.radix_tree_lookup_slot
0.58 ± 5% -0.1 0.52 ± 5% perf-profile.children.cycles-pp.security_file_permission
0.49 ± 8% -0.1 0.43 ± 6% perf-profile.children.cycles-pp.__radix_tree_lookup
0.48 ± 2% -0.1 0.43 ± 4% perf-profile.children.cycles-pp.add_to_page_cache_lru
0.16 ± 11% -0.1 0.11 ± 4% perf-profile.children.cycles-pp.iov_iter_advance
0.15 ± 12% -0.1 0.10 perf-profile.children.cycles-pp.fsnotify
0.18 ± 13% -0.0 0.14 ± 6% perf-profile.children.cycles-pp.delete_from_page_cache_batch
0.25 ± 9% -0.0 0.21 ± 5% perf-profile.children.cycles-pp.__set_page_dirty_nobuffers
0.12 ± 13% -0.0 0.08 ± 10% perf-profile.children.cycles-pp.__cancel_dirty_page
0.11 ± 7% -0.0 0.07 ± 10% perf-profile.children.cycles-pp.percpu_counter_add_batch
0.25 ± 4% -0.0 0.21 ± 8% perf-profile.children.cycles-pp.__pagevec_release
0.30 ± 3% -0.0 0.28 ± 5% perf-profile.children.cycles-pp.__add_to_page_cache_locked
0.27 ± 6% -0.0 0.24 perf-profile.children.cycles-pp.___might_sleep
0.15 ± 4% -0.0 0.12 ± 3% perf-profile.children.cycles-pp.__lru_cache_add
0.12 ± 17% -0.0 0.09 ± 7% perf-profile.children.cycles-pp.account_page_dirtied
0.14 ± 6% -0.0 0.11 ± 6% perf-profile.children.cycles-pp.pagevec_lru_move_fn
0.16 ± 2% -0.0 0.14 ± 8% perf-profile.children.cycles-pp.down_read
0.19 ± 5% -0.0 0.17 ± 2% perf-profile.children.cycles-pp._cond_resched
0.08 ± 5% -0.0 0.07 ± 7% perf-profile.children.cycles-pp.down_write_trylock
0.15 ± 3% -0.0 0.14 ± 5% perf-profile.children.cycles-pp.page_mapping
0.10 ± 5% -0.0 0.08 ± 5% perf-profile.children.cycles-pp.__x64_sys_write
0.17 ± 15% +0.0 0.20 ± 15% perf-profile.children.cycles-pp.down_write
0.17 ± 13% +0.0 0.20 ± 14% perf-profile.children.cycles-pp.call_rwsem_down_write_failed
0.17 ± 13% +0.0 0.20 ± 14% perf-profile.children.cycles-pp.rwsem_down_write_failed
0.08 ± 29% +0.0 0.12 ± 11% perf-profile.children.cycles-pp.console_unlock
0.08 ± 29% +0.0 0.12 ± 10% perf-profile.children.cycles-pp.irq_work_run_list
96.50 +0.6 97.06 perf-profile.children.cycles-pp.entry_SYSCALL_64_after_hwframe
96.37 +0.6 96.94 perf-profile.children.cycles-pp.do_syscall_64
93.09 +1.0 94.09 perf-profile.children.cycles-pp.ksys_write
92.81 +1.0 93.84 perf-profile.children.cycles-pp.vfs_write
25.85 ± 3% +1.1 26.98 perf-profile.children.cycles-pp.f2fs_get_block
25.76 ± 3% +1.2 26.94 perf-profile.children.cycles-pp.f2fs_reserve_block
25.71 ± 3% +1.2 26.90 perf-profile.children.cycles-pp.f2fs_reserve_new_blocks
91.38 +1.3 92.71 perf-profile.children.cycles-pp.__vfs_write
90.89 +1.7 92.58 perf-profile.children.cycles-pp.f2fs_file_write_iter
88.30 +2.6 90.94 perf-profile.children.cycles-pp.__generic_file_write_iter
87.36 +3.2 90.53 perf-profile.children.cycles-pp.generic_perform_write
52.06 ± 3% +3.3 55.39 perf-profile.children.cycles-pp.f2fs_write_end
62.34 ± 3% +4.4 66.77 perf-profile.children.cycles-pp.f2fs_mark_inode_dirty_sync
74.47 ± 4% +5.0 79.47 perf-profile.children.cycles-pp.native_queued_spin_lock_slowpath
75.70 ± 3% +5.0 80.72 perf-profile.children.cycles-pp._raw_spin_lock
75.17 ± 3% +5.0 80.20 perf-profile.children.cycles-pp.f2fs_inode_dirtied
1.26 ± 24% -0.5 0.74 ± 11% perf-profile.self.cycles-pp.f2fs_file_write_iter
0.44 ± 72% -0.3 0.10 ± 18% perf-profile.self.cycles-pp.__vfs_write
1.24 ± 14% -0.3 0.92 ± 7% perf-profile.self.cycles-pp.f2fs_lookup_extent_cache
0.53 ± 15% -0.3 0.28 ± 37% perf-profile.self.cycles-pp._raw_read_lock
1.48 ± 2% -0.2 1.24 ± 5% perf-profile.self.cycles-pp.syscall_return_via_sysret
0.32 ± 52% -0.2 0.11 ± 4% perf-profile.self.cycles-pp.balance_dirty_pages_ratelimited
1.47 ± 2% -0.2 1.28 ± 6% perf-profile.self.cycles-pp.__entry_SYSCALL_64_trampoline
0.23 ± 55% -0.2 0.06 ± 17% perf-profile.self.cycles-pp.f2fs_map_blocks
0.48 ± 27% -0.2 0.33 ± 2% perf-profile.self.cycles-pp.f2fs_write_end
0.44 ± 11% -0.1 0.32 ± 6% perf-profile.self.cycles-pp.intel_idle
0.24 ± 41% -0.1 0.12 ± 6% perf-profile.self.cycles-pp.read_node_page
0.77 ± 3% -0.1 0.67 ± 3% perf-profile.self.cycles-pp.generic_perform_write
1.07 ± 5% -0.1 0.98 ± 2% perf-profile.self.cycles-pp.copy_user_enhanced_fast_string
0.60 ± 3% -0.1 0.51 ± 5% perf-profile.self.cycles-pp.do_syscall_64
0.54 ± 4% -0.1 0.47 ± 4% perf-profile.self.cycles-pp.find_get_entry
0.48 ± 8% -0.1 0.42 ± 6% perf-profile.self.cycles-pp.__radix_tree_lookup
0.30 ± 10% -0.1 0.24 ± 5% perf-profile.self.cycles-pp.pagecache_get_page
0.14 ± 15% -0.0 0.10 ± 4% perf-profile.self.cycles-pp.fsnotify
0.15 ± 12% -0.0 0.11 ± 4% perf-profile.self.cycles-pp.iov_iter_advance
0.10 ± 10% -0.0 0.06 ± 17% perf-profile.self.cycles-pp.percpu_counter_add_batch
0.30 ± 4% -0.0 0.26 ± 7% perf-profile.self.cycles-pp.f2fs_set_data_page_dirty
0.19 ± 6% -0.0 0.16 ± 7% perf-profile.self.cycles-pp.iov_iter_copy_from_user_atomic
0.25 ± 7% -0.0 0.23 ± 3% perf-profile.self.cycles-pp.___might_sleep
0.12 ± 17% -0.0 0.09 ± 19% perf-profile.self.cycles-pp.f2fs_convert_inline_inode
0.08 ± 5% -0.0 0.07 ± 7% perf-profile.self.cycles-pp.down_write_trylock
0.15 ± 6% -0.0 0.13 ± 8% perf-profile.self.cycles-pp.page_mapping
0.52 ± 12% +0.3 0.84 ± 3% perf-profile.self.cycles-pp.__get_node_page
73.96 ± 4% +5.0 79.00 perf-profile.self.cycles-pp.native_queued_spin_lock_slowpath
aim7.jobs-per-min
115000 +-+----------------------------------------------------------------+
| .+ .+.. .+.. |
|. : + +..+ +.+..+..+.+..+. .+.+.. .+ + |
110000 +-+ : + + + +. +..+ + : |
| :+ + + + : |
| + + +. : |
105000 +-+ + |
| |
100000 +-+ |
| O |
O O O |
95000 +-+ O O O O O O O O O O O O O O O O
| O O O O O |
| O O |
90000 +-+----------------------------------------------------------------+
[*] bisect-good sample
[O] bisect-bad sample
Disclaimer:
Results have been estimated based on internal Intel analysis and are provided
for informational purposes only. Any difference in system hardware or software
design or configuration may affect actual performance.
Thanks,
Rong Chen
2 years, 4 months
- 1
- 0
[sched/numa] 8d8968f954: fio.latency_100ms% 62.9% improvement
by kernel test robot
Greeting,
FYI, we noticed a 62.9% improvement of fio.latency_100ms% due to commit:
commit: 8d8968f9542b26c7f42b3e3e402fbb63075fd97a ("sched/numa: Avoid task migration for small NUMA improvement")
https://git.kernel.org/cgit/linux/kernel/git/peterz/queue.git sched/urgent
in testcase: fio-basic
on test machine: 56 threads Intel(R) Xeon(R) CPU E5-2695 v3 @ 2.30GHz with 256G memory
with following parameters:
disk: 2pmem
fs: ext4
mount_option: dax
runtime: 200s
nr_task: 50%
time_based: tb
rw: rw
bs: 2M
ioengine: libaio
test_size: 200G
ucode: 0x3d
cpufreq_governor: performance
test-description: Fio is a tool that will spawn a number of threads or processes doing a particular type of I/O action as specified by the user.
test-url: https://github.com/axboe/fio
Details are as below:
-------------------------------------------------------------------------------------------------->
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
=========================================================================================
bs/compiler/cpufreq_governor/disk/fs/ioengine/kconfig/mount_option/nr_task/rootfs/runtime/rw/tbox_group/test_size/testcase/time_based/ucode:
2M/gcc-7/performance/2pmem/ext4/libaio/x86_64-rhel-7.2/dax/50%/debian-x86_64-2018-04-03.cgz/200s/rw/lkp-hsw-ep6/200G/fio-basic/tb/0x3d
commit:
be20c8fbc1 ("mm/migrate: Use trylock while resetting rate limit")
8d8968f954 ("sched/numa: Avoid task migration for small NUMA improvement")
be20c8fbc11b3eff 8d8968f9542b26c7f42b3e3e40
---------------- --------------------------
%stddev %change %stddev
\ | \
34.61 ± 14% +62.9 97.47 fio.latency_100ms%
0.08 ± 11% -0.0 0.04 ± 4% fio.latency_10ms%
33.16 ± 23% -32.7 0.50 ± 26% fio.latency_20ms%
30.77 ± 22% -30.1 0.70 ± 11% fio.latency_250ms%
0.03 ± 7% -0.0 0.02 ± 2% fio.latency_4ms%
119808 ± 2% -37.4% 75008 ± 5% fio.read_clat_90%_us
126976 ± 2% -40.3% 75776 ± 5% fio.read_clat_95%_us
153088 ± 10% -45.8% 82944 ± 3% fio.read_clat_99%_us
42763 ± 3% -74.1% 11082 ± 9% fio.read_clat_stddev
1295 ± 3% -68.0% 415.12 ± 11% fio.read_slat_stddev
5224 ± 11% -58.8% 2155 ± 6% fio.time.involuntary_context_switches
380840 ± 3% -74.1% 98521 fio.time.minor_page_faults
119808 ± 2% -37.4% 75008 ± 5% fio.write_clat_90%_us
126976 ± 2% -40.3% 75776 ± 5% fio.write_clat_95%_us
153088 ± 10% -45.8% 82944 ± 3% fio.write_clat_99%_us
42760 ± 2% -73.9% 11157 ± 9% fio.write_clat_stddev
2311 ± 10% -15.2% 1958 ± 5% fio.write_slat_mean_us
1538 ± 2% -73.9% 401.15 ± 7% fio.write_slat_stddev
186972 ±125% -76.0% 44842 ± 3% cpuidle.C1.usage
16867 ± 70% -51.1% 8255 softirqs.NET_RX
206.00 ± 2% +6.8% 220.00 ± 4% vmstat.io.bo
1519 -7.9% 1398 vmstat.system.cs
2542 ± 10% -19.1% 2055 ± 15% slabinfo.eventpoll_pwq.active_objs
2542 ± 10% -19.1% 2055 ± 15% slabinfo.eventpoll_pwq.num_objs
7422 ± 5% -12.2% 6515 slabinfo.kmalloc-96.active_objs
7566 ± 5% -10.4% 6776 slabinfo.kmalloc-96.num_objs
1142 +2.0% 1165 turbostat.Avg_MHz
184173 ±127% -78.0% 40550 ± 4% turbostat.C1
37.86 ± 2% +26.5% 47.88 turbostat.CPU%c1
3.63 ± 79% -92.2% 0.28 ±119% turbostat.CPU%c3
7.50 ± 44% -87.2% 0.96 ± 57% turbostat.CPU%c6
1.12 ± 89% -1.1 0.05 ± 66% turbostat.PKG_%
169.61 ± 2% +10.0% 186.61 turbostat.PkgWatt
4.27 ± 2% +3.6 7.84 ± 14% turbostat.RAM_%
22834 -46.5% 12208 turbostat.SMI
0.70 ± 21% -0.2 0.46 ± 7% perf-profile.children.cycles-pp.hrtimer_interrupt
0.49 ± 21% -0.2 0.34 ± 7% perf-profile.children.cycles-pp.__hrtimer_run_queues
0.27 ± 19% -0.2 0.12 ± 21% perf-profile.children.cycles-pp.menu_select
0.11 ± 19% -0.1 0.03 ±100% perf-profile.children.cycles-pp.tick_nohz_get_sleep_length
0.15 ± 20% -0.1 0.09 ± 26% perf-profile.children.cycles-pp.io_serial_in
0.09 ± 21% -0.1 0.03 ±100% perf-profile.children.cycles-pp.tick_nohz_next_event
0.07 ± 14% -0.0 0.03 ±100% perf-profile.children.cycles-pp.native_irq_return_iret
0.09 ± 12% -0.0 0.04 ± 58% perf-profile.children.cycles-pp.perf_mux_hrtimer_handler
0.11 ± 27% -0.1 0.03 ±100% perf-profile.self.cycles-pp.menu_select
0.07 ± 14% -0.0 0.03 ±100% perf-profile.self.cycles-pp.native_irq_return_iret
303731 ± 4% -92.9% 21710 ± 3% proc-vmstat.numa_hint_faults
284807 ± 5% -95.7% 12224 ± 4% proc-vmstat.numa_hint_faults_local
30240 -66.0% 10281 proc-vmstat.numa_huge_pte_updates
105836 ± 23% +114.1% 226556 ± 2% proc-vmstat.numa_pages_migrated
15756889 -66.5% 5277375 proc-vmstat.numa_pte_updates
4460 ± 14% +36.1% 6068 ± 16% proc-vmstat.pgactivate
1144309 ± 2% +10.2% 1261001 proc-vmstat.pgalloc_normal
908063 -30.9% 627107 proc-vmstat.pgfault
995056 ± 20% +25.9% 1253262 proc-vmstat.pgfree
105836 ± 23% +114.1% 226556 ± 2% proc-vmstat.pgmigrate_success
857.75 ± 39% +51.9% 1303 proc-vmstat.thp_deferred_split_page
167.53 ± 12% +272.7% 624.31 ± 16% sched_debug.cfs_rq:/.exec_clock.min
36482 ± 2% +12.4% 41015 sched_debug.cfs_rq:/.exec_clock.stddev
40031 ± 2% +12.3% 44969 ± 3% sched_debug.cfs_rq:/.min_vruntime.stddev
0.00 ±173% +652.5% 0.03 ± 40% sched_debug.cfs_rq:/.nr_spread_over.avg
0.19 ±173% +466.7% 1.06 ± 42% sched_debug.cfs_rq:/.nr_spread_over.max
0.03 ±173% +522.9% 0.16 ± 36% sched_debug.cfs_rq:/.nr_spread_over.stddev
-12497 -436.6% 42063 ± 16% sched_debug.cfs_rq:/.spread0.avg
67825 ± 41% +106.6% 140103 ± 17% sched_debug.cfs_rq:/.spread0.max
-64287 -79.1% -13441 sched_debug.cfs_rq:/.spread0.min
40031 ± 2% +12.3% 44971 ± 3% sched_debug.cfs_rq:/.spread0.stddev
300.13 ± 7% +12.5% 337.79 ± 5% sched_debug.cfs_rq:/.util_est_enqueued.avg
298.13 ± 7% +11.5% 332.44 ± 4% sched_debug.cfs_rq:/.util_est_enqueued.stddev
0.20 ± 23% -0.1 0.13 ± 10% perf-stat.branch-miss-rate%
9.105e+08 ± 20% -40.1% 5.455e+08 ± 7% perf-stat.branch-misses
28.28 +2.6 30.90 ± 2% perf-stat.cache-miss-rate%
304051 -8.8% 277145 perf-stat.context-switches
3252 ± 13% +81.4% 5900 ± 2% perf-stat.cpu-migrations
0.02 ± 19% -0.0 0.01 ± 4% perf-stat.dTLB-load-miss-rate%
1.605e+08 ± 15% -61.4% 61982937 ± 9% perf-stat.dTLB-load-misses
0.01 ± 13% -0.0 0.01 ± 4% perf-stat.dTLB-store-miss-rate%
95776088 ± 14% -40.3% 57174704 ± 11% perf-stat.dTLB-store-misses
32.77 ± 31% -16.0 16.78 ± 30% perf-stat.iTLB-load-miss-rate%
893672 -31.7% 610132 perf-stat.minor-faults
11.04 ± 31% -10.5 0.51 ± 34% perf-stat.node-store-miss-rate%
1.986e+09 ± 23% -95.5% 89918389 ± 29% perf-stat.node-store-misses
893677 -31.7% 609978 perf-stat.page-faults
fio.read_clat_stddev
60000 +-+-----------------------------------------------------------------+
| +. + |
50000 +-+ : +.. + : .+ |
|: : + : + +.+. + .+.. |
| +..+ +.+..+.+.+..+. : :.. + .. + +.+..+.+..+.|
40000 +-+ + .. + + + |
| + |
30000 +-+ |
| |
20000 +-+ |
| O O |
| O O O O O O |
10000 O-O O O O O O |
| O O |
0 +-+-----------------------------------------------------------------+
fio.read_clat_95__us
200000 +-+----------------------------------------------------------------+
| +.. : + |
180000 +-+ + : : : |
160000 +-+ :: : + : + |
|: : : : : .. : |
140000 +-+ : : +.. .+ : +.. +.+ : |
| +..+ +. + .+ :+ + +..+. .+.+..+.|
120000 +-+ + +.+. + + +.+. |
| |
100000 +-+ |
80000 O-+ O O O O O |
| O O O O O O O |
60000 +-O O O |
| O |
40000 +-+----------------------------------------------------------------+
fio.write_clat_stddev
60000 +-+-----------------------------------------------------------------+
| +. + |
50000 +-+ : +.. + : .+ |
|: : + : + +.+. + .+.. |
| +..+ +.+..+.+.+..+. : :.. + .. + +.+..+.+..+.|
40000 +-+ + .. + + + |
| + |
30000 +-+ |
| |
20000 +-+ |
| O O |
| O O O O O O |
10000 O-O O O O O O O |
| O |
0 +-+-----------------------------------------------------------------+
fio.write_clat_95__us
200000 +-+----------------------------------------------------------------+
| +.. : + |
180000 +-+ + : : : |
160000 +-+ :: : + : + |
|: : : : : .. : |
140000 +-+ : : +.. .+ : +.. +.+ : |
| +..+ +. + .+ :+ + +..+. .+.+..+.|
120000 +-+ + +.+. + + +.+. |
| |
100000 +-+ |
80000 O-+ O O O O O |
| O O O O O O O |
60000 +-O O O |
| O |
40000 +-+----------------------------------------------------------------+
fio.time.minor_page_faults
400000 +-+----------------------------------------------------------------+
|.+..+ +.+. .. +.. .+.. .+.. .+..+ .+. |
350000 +-+ + .. + +.+ + +.+.+..+.+ + + |
| + + .. |
300000 +-+ + |
| |
250000 +-+ |
| |
200000 +-+ |
| |
150000 +-+ |
| |
100000 O-O O O O O O O O O O O O O O O O |
| |
50000 +-+----------------------------------------------------------------+
fio.time.involuntary_context_switches
6500 +-+------------------------------------------------------------------+
6000 +-+ + + + |
| + :: : : : : + |
5500 +-+ .. : + + +.. : : : : + : : + + : :|
5000 +-++ + : + : : + +.. + : :: :.. + : :.. + + : :|
| + + :+ : : + + + + + + + + + : .+ |
4500 +-+ + + +..+ + +. |
4000 +-+ |
3500 +-+ |
| |
3000 +-+ |
2500 +-+ O |
| O O O O O O O O O O O O O |
2000 O-O O |
1500 +-+------------------------------------------------------------------+
[*] bisect-good sample
[O] bisect-bad sample
Disclaimer:
Results have been estimated based on internal Intel analysis and are provided
for informational purposes only. Any difference in system hardware or software
design or configuration may affect actual performance.
Thanks,
Rong Chen
2 years, 4 months
- 1
- 0
[xfs] 70b98b4e6b: Assertion_failed
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: 70b98b4e6bacdc8d37b69b186df3ab69400f4758 ("xfs: deferred inode inactivation")
https://git.kernel.org/cgit/linux/kernel/git/djwong/xfs-linux.git djwong-wtf
in testcase: xfstests
with following parameters:
disk: 4HDD
fs: xfs
test: generic-mid2
test-description: xfstests is a regression test suite for xfs and other files ystems.
test-url: git://git.kernel.org/pub/scm/fs/xfs/xfstests-dev.git
on test machine: qemu-system-x86_64 -enable-kvm -cpu host -smp 2 -m 4G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+------------------------------------------+------------+------------+
| | 92af26aeaa | 70b98b4e6b |
+------------------------------------------+------------+------------+
| boot_successes | 42 | 3 |
| boot_failures | 0 | 39 |
| Assertion_failed | 0 | 38 |
| kernel_BUG_at_fs/xfs/xfs_message.c | 0 | 38 |
| invalid_opcode:#[##] | 0 | 38 |
| RIP:assfail[xfs] | 0 | 38 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 38 |
| invoked_oom-killer:gfp_mask=0x | 0 | 1 |
| Mem-Info | 0 | 1 |
+------------------------------------------+------------+------------+
[ 4890.606079] XFS (vdd): Ending clean mount
[ 4890.612012] XFS (vdd): Quotacheck needed: Please wait.
[ 4901.787372] XFS (vdd): Quotacheck: Done.
[ 4929.452816] XFS (vdd): xlog_verify_grant_tail: space > BBTOB(tail_blocks)
[ 6446.947792] 270 (18674): drop_caches: 3
[ 6446.977006] XFS: Assertion failed: dqp->q_res_bcount >= be64_to_cpu(dqp->q_core.d_bcount), file: fs/xfs/xfs_trans_dquot.c, line: 714
[ 6446.988781] ------------[ cut here ]------------
[ 6446.991650] kernel BUG at fs/xfs/xfs_message.c:102!
[ 6446.996312] invalid opcode: 0000 [#1] SMP PTI
[ 6446.999076] CPU: 1 PID: 19430 Comm: kworker/1:5 Not tainted 4.19.0-rc7-00114-g70b98b4-dirty #1
[ 6447.004559] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 6447.010124] Workqueue: xfs-inactive/vdd xfs_inactive_worker [xfs]
[ 6447.014117] RIP: 0010:assfail+0x28/0x30 [xfs]
[ 6447.020593] Code: c3 90 0f 1f 44 00 00 48 89 f1 41 89 d0 48 c7 c6 d0 ce 2b a0 48 89 fa 31 ff e8 64 f9 ff ff 80 3d 85 08 0e 00 00 75 03 0f 0b c3 <0f> 0b 66 0f 1f 44 00 00 0f 1f 44 00 00 48 63 f6 6a 01 49 89 f9 56
[ 6447.044908] RSP: 0018:ffffc900016ab980 EFLAGS: 00010202
[ 6447.053131] RAX: 0000000000000000 RBX: ffff88004df797a0 RCX: 0000000000000000
[ 6447.061394] RDX: 00000000ffffffc0 RSI: 000000000000000a RDI: ffffffffa02afc3e
[ 6447.070205] RBP: 0000000000010000 R08: 0000000000000000 R09: 0000000000000000
[ 6447.080513] R10: 0000000000000000 R11: f000000000000000 R12: 0000000000000000
[ 6447.092377] R13: ffff88004df79938 R14: 0000000000000000 R15: fffffffffffffffa
[ 6447.104390] FS: 0000000000000000(0000) GS:ffff88013fd00000(0000) knlGS:0000000000000000
[ 6447.116238] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 6447.120731] CR2: 00007fff761cd050 CR3: 000000000260a003 CR4: 00000000001606e0
[ 6447.127477] Call Trace:
[ 6447.134047] xfs_trans_dqresv+0x3ee/0x5b0 [xfs]
[ 6447.140482] ? wait_for_completion+0x65/0x1c0
[ 6447.170340] xfs_trans_reserve_quota_bydquots+0x6a/0x180 [xfs]
[ 6447.175696] xfs_trans_reserve_quota_nblks+0xce/0x120 [xfs]
[ 6447.185384] xfs_bmap_del_extent_delay+0x106/0x7d0 [xfs]
[ 6447.196293] ? __radix_tree_create+0x161/0x1e0
[ 6447.206711] __xfs_bunmapi+0x6b1/0xf00 [xfs]
[ 6447.217592] ? xfs_log_reserve+0x1c0/0x280 [xfs]
[ 6447.226542] xfs_bunmapi+0x15/0x30 [xfs]
[ 6447.230969] xfs_itruncate_extents_flags+0x154/0x450 [xfs]
[ 6447.241624] xfs_free_eofblocks+0xf2/0x140 [xfs]
[ 6447.253197] xfs_inactive_inode+0x2a/0x140 [xfs]
[ 6447.262246] xfs_inactive_inodes_ag+0x120/0x1c0 [xfs]
[ 6447.274158] ? __switch_to_asm+0x40/0x70
[ 6447.280389] ? __switch_to_asm+0x34/0x70
[ 6447.284988] ? __switch_to_asm+0x40/0x70
[ 6447.293196] ? __switch_to_asm+0x34/0x70
[ 6447.308267] ? __switch_to_asm+0x40/0x70
[ 6447.320062] ? __switch_to_asm+0x34/0x70
[ 6447.327243] ? __switch_to_asm+0x40/0x70
[ 6447.333015] ? __switch_to_asm+0x34/0x70
[ 6447.342200] ? __switch_to_asm+0x40/0x70
[ 6447.353512] ? __switch_to_asm+0x34/0x70
[ 6447.363798] ? radix_tree_gang_lookup_tag+0xd6/0x160
[ 6447.372702] ? xfs_perag_get_tag+0x44/0x100 [xfs]
[ 6447.382564] xfs_inactive_inodes+0x39/0x80 [xfs]
[ 6447.395921] xfs_inactive_worker+0x17/0x40 [xfs]
[ 6447.412289] process_one_work+0x19c/0x3b0
[ 6447.420556] worker_thread+0x3c/0x3b0
[ 6447.425149] ? process_one_work+0x3b0/0x3b0
[ 6447.429250] kthread+0x11e/0x140
[ 6447.432509] ? kthread_associate_blkcg+0xb0/0xb0
[ 6447.436377] ret_from_fork+0x35/0x40
[ 6447.439516] Modules linked in: xfs dm_mod sr_mod cdrom sg ppdev sb_edac crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel pcbc snd_pcm ata_generic snd_timer aesni_intel pata_acpi crypto_simd snd cryptd glue_helper soundcore pcspkr serio_raw ata_piix libata i2c_piix4 parport_pc floppy parport ip_tables
[ 6447.570493] ---[ end trace 916f12f7d71d7f3f ]---
[ 6447.575509] RIP: 0010:assfail+0x28/0x30 [xfs]
[ 6447.582460] Code: c3 90 0f 1f 44 00 00 48 89 f1 41 89 d0 48 c7 c6 d0 ce 2b a0 48 89 fa 31 ff e8 64 f9 ff ff 80 3d 85 08 0e 00 00 75 03 0f 0b c3 <0f> 0b 66 0f 1f 44 00 00 0f 1f 44 00 00 48 63 f6 6a 01 49 89 f9 56
[ 6447.595053] RSP: 0018:ffffc900016ab980 EFLAGS: 00010202
[ 6447.607378] RAX: 0000000000000000 RBX: ffff88004df797a0 RCX: 0000000000000000
[ 6447.613320] RDX: 00000000ffffffc0 RSI: 000000000000000a RDI: ffffffffa02afc3e
[ 6447.622734] RBP: 0000000000010000 R08: 0000000000000000 R09: 0000000000000000
[ 6447.627606] R10: 0000000000000000 R11: f000000000000000 R12: 0000000000000000
[ 6447.638325] R13: ffff88004df79938 R14: 0000000000000000 R15: fffffffffffffffa
[ 6447.643359] FS: 0000000000000000(0000) GS:ffff88013fd00000(0000) knlGS:0000000000000000
[ 6447.652916] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 6447.656202] CR2: 00007fff761cd050 CR3: 000000000260a003 CR4: 00000000001606e0
[ 6447.662184] Kernel panic - not syncing: Fatal exception
[ 6447.665016] Kernel Offset: disabled
Elapsed time: 6370
#!/bin/bash
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
lkp
2 years, 4 months
- 1
- 0
bd00db8b16 [ 48.718069] BUG: spinlock trylock failure on UP on CPU#0, kworker/u2:1/27
by kernel test robot
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
https://github.com/bvanassche/linux for-next
commit bd00db8b160b8cb2a61e6321aee5a68c6d823075
Author: Bart Van Assche <bvanassche(a)acm.org>
AuthorDate: Tue Oct 16 13:57:06 2018 -0700
Commit: Bart Van Assche <bvanassche(a)acm.org>
CommitDate: Wed Oct 17 16:17:43 2018 -0700
drivers/base: Probe devices concurrently if requested by the driver
Instead of probing devices sequentially in the PROBE_PREFER_ASYNCHRONOUS
mode, scan devices concurrently. This helps when the wall clock time for
a single probe is significantly above the CPU time needed for a single
probe, e.g. when scanning SCSI LUNs over a storage network.
Cc: Lee Duncan <lduncan(a)suse.com>
Cc: Hannes Reinecke <hare(a)suse.com>
Cc: Luis Chamberlain <mcgrof(a)kernel.org>
Cc: Johannes Thumshirn <jthumshirn(a)suse.de>
Cc: Christoph Hellwig <hch(a)lst.de>
Cc: Greg Kroah-Hartman <gregkh(a)linuxfoundation.org>
Cc: Dan Williams <dan.j.williams(a)intel.com>
Signed-off-by: Bart Van Assche <bvanassche(a)acm.org>
49e249b0dd drivers/base: Verify struct device locking requirements at runtime
bd00db8b16 drivers/base: Probe devices concurrently if requested by the driver
9294000d6d IB/srp: Drain the send queue before destroying a QP
+----------------------------------------------------+------------+------------+---------------------+
| | 49e249b0dd | bd00db8b16 | bvanassche/for-next |
+----------------------------------------------------+------------+------------+---------------------+
| boot_successes | 39 | 0 | 12 |
| boot_failures | 0 | 12 | |
| BUG:spinlock_trylock_failure_on_UP_on_CPU | 0 | 11 | |
| BUG:unable_to_handle_kernel | 0 | 11 | |
| Oops:#[##] | 0 | 11 | |
| EIP:__list_add_valid | 0 | 11 | |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 11 | |
| genirq:Flags_mismatch_irq##(sir_ir)vs.#(serial_ir) | 0 | 1 | |
+----------------------------------------------------+------------+------------+---------------------+
[ 42.926637] CE: hpet increased min_delta_ns to 25312 nsec
[ 42.926776] CE: hpet increased min_delta_ns to 37968 nsec
[ 48.630057] [drm] Initialized vgem 1.0.0 20120112 for virtual device on minor 0
[ 48.631889] parport_pc 00:04: reported by Plug and Play ACPI
[ 48.633342] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE]
[ 48.718069] BUG: spinlock trylock failure on UP on CPU#0, kworker/u2:1/27
[ 48.719387] lock: 0xcd89bc44, .magic: c224d69c, .owner: <none>/-1, .owner_cpu: 0
[ 48.720968] CPU: 0 PID: 27 Comm: kworker/u2:1 Not tainted 4.19.0-rc1-00171-gbd00db8 #1
[ 48.722617] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 48.724360] Workqueue: events_unbound async_run_entry_fn
[ 48.725391] Call Trace:
[ 48.726152] dump_stack+0x16/0x26
[ 48.726948] spin_dump+0x66/0xa0
[ 48.727729] do_raw_spin_trylock+0x3c/0x60
[ 48.728586] _raw_spin_lock+0x2f/0x60
[ 48.729380] ? __mutex_lock+0xd9/0x460
[ 48.730190] __mutex_lock+0xd9/0x460
[ 48.730974] ? sched_clock_local+0x15/0x120
[ 48.732012] ? _raw_spin_unlock_irqrestore+0x32/0x70
[ 48.733027] mutex_lock_nested+0x14/0x20
[ 48.733904] ? __driver_probe_device_async+0x1f/0x50
[ 48.734889] __driver_probe_device_async+0x1f/0x50
[ 48.735841] async_run_entry_fn+0x34/0xe0
[ 48.736786] process_one_work+0x275/0x4b0
[ 48.737669] worker_thread+0x279/0x400
[ 48.738511] kthread+0xf9/0x100
[ 48.739250] ? process_one_work+0x4b0/0x4b0
[ 48.740117] ? __kthread_create_on_node+0x130/0x130
[ 48.741094] ret_from_fork+0x19/0x24
[ 48.741890] BUG: unable to handle kernel NULL pointer dereference at 00000000
[ 48.743166] *pde = 00000000
[ 48.743877] Oops: 0000 [#1] PREEMPT PTI
[ 48.744741] CPU: 0 PID: 27 Comm: kworker/u2:1 Not tainted 4.19.0-rc1-00171-gbd00db8 #1
[ 48.746368] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 48.746717] Workqueue: events_unbound async_run_entry_fn
[ 48.746717] EIP: __list_add_valid+0x20/0x60
[ 48.746717] Code: 90 90 90 90 90 90 90 90 90 90 55 89 e5 56 53 8b 59 04 39 d3 74 14 51 53 52 68 70 fb e0 c1 e8 68 96 c0 ff 0f 0b 90 8d 74 26 00 <8b> 33 39 ce 74 12 53 56 51 68 ec fb e0 c1 e8 4e 96 c0 ff 0f 0b 8d
[ 48.746717] EAX: cf5e7ec8 EBX: 00000000 ECX: cd89bc6c EDX: 00000000
[ 48.746717] ESI: cd89bc6c EDI: cd89bc40 EBP: cf5e7e84 ESP: cf5e7e7c
[ 48.746717] DS: 007b ES: 007b FS: 0000 GS: 0000 SS: 0068 EFLAGS: 00210246
[ 48.746717] CR0: 80050033 CR2: 00000000 CR3: 0223c000 CR4: 00140690
[ 48.746717] DR0: 00000000 DR1: 00000000 DR2: 00000000 DR3: 00000000
[ 48.746717] DR6: fffe0ff0 DR7: 00000400
[ 48.746717] Call Trace:
[ 48.746717] __mutex_add_waiter+0x2b/0x50
[ 48.746717] __mutex_lock+0x1c9/0x460
[ 48.746717] ? sched_clock_local+0x15/0x120
[ 48.746717] mutex_lock_nested+0x14/0x20
[ 48.746717] ? __driver_probe_device_async+0x1f/0x50
[ 48.746717] __driver_probe_device_async+0x1f/0x50
[ 48.746717] async_run_entry_fn+0x34/0xe0
[ 48.746717] process_one_work+0x275/0x4b0
[ 48.746717] worker_thread+0x279/0x400
[ 48.746717] kthread+0xf9/0x100
[ 48.746717] ? process_one_work+0x4b0/0x4b0
[ 48.746717] ? __kthread_create_on_node+0x130/0x130
[ 48.746717] ret_from_fork+0x19/0x24
[ 48.746717] CR2: 0000000000000000
[ 48.746717] ---[ end trace 82f5aba0cabbcb35 ]---
[ 48.746717] EIP: __list_add_valid+0x20/0x60
# HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start 598838757dde28a7e727b0ba2aa39c5d5fadc780 35a7f35ad1b150ddf59a41dcac7b2fa32982be0e --
git bisect bad 59d496ffa8596b2e65688fa987f7bbabf983b0b5 # 15:26 B 0 3 19 2 Merge 'pza/reset/next' into devel-spot-201810180841
git bisect bad 59f110ccd25e13c26da5f0a17f4ce1010fde9e46 # 15:31 B 0 2 18 2 Merge 'clk/clk-imx6-mmdc' into devel-spot-201810180841
git bisect bad 24af61ee7d85c4191863c50d18900a9872cb5750 # 15:36 B 1 9 0 12 Merge 'villemoes/rai' into devel-spot-201810180841
git bisect good 369bc2c5a563377903063671bc97b472ac67a7f1 # 15:36 G 12 0 0 0 Merge 'clk/clk-qoriq-t1023' into devel-spot-201810180841
git bisect bad 9cecccba969e6465dcfd235d60015affc2e1bdc1 # 15:36 B 1 10 0 3 Merge 'dhowells-fs/afs-yfs' into devel-spot-201810180841
git bisect bad 313ee6c8d58f5abe847f8a3ca3dee60558b13236 # 15:36 B 0 11 28 3 Merge 'bvanassche/for-next' into devel-spot-201810180841
git bisect good 986d7dbc4169a8afefe7f4081253a75b60b791a6 # 15:37 G 12 0 0 0 scsi: libsas: delete dead code in scsi_transport_sas.c
git bisect good df032bf27a414acf61c957ec2fad22a57d903b39 # 15:37 G 12 0 0 0 scsi: ufs: Add a bsg endpoint that supports UPIUs
git bisect good 1668e3b6f8f8ed2ce685691c92b90dfadeaa3f2f # 15:37 G 12 0 0 0 scsi: hisi_sas: Move evaluation of hisi_hba in hisi_sas_task_prep()
git bisect good 4240d448a483e8c2811dc914a1408f606fe13347 # 15:37 G 12 0 0 0 scsi: target/core: Fix spelling in two source code comments
git bisect good aa73237dcb2d96d7a3292af8ca943dd149fd39af # 15:37 G 12 0 0 0 scsi: target/core: Always call transport_complete_callback() upon failure
git bisect good 49e249b0dd85b212567370ba9eb1829662f72d27 # 15:37 G 35 0 0 0 drivers/base: Verify struct device locking requirements at runtime
git bisect bad d7c161555c802538c593a50218357d6f656c9e30 # 15:37 B 2 10 0 2 drivers/base, __device_release_driver(): Do not wait for asynchronous probing
git bisect bad bd00db8b160b8cb2a61e6321aee5a68c6d823075 # 15:37 B 0 11 41 1 drivers/base: Probe devices concurrently if requested by the driver
# first bad commit: [bd00db8b160b8cb2a61e6321aee5a68c6d823075] drivers/base: Probe devices concurrently if requested by the driver
git bisect good 49e249b0dd85b212567370ba9eb1829662f72d27 # 15:41 G 36 0 0 0 drivers/base: Verify struct device locking requirements at runtime
# extra tests on HEAD of linux-devel/devel-spot-201810180841
git bisect bad 598838757dde28a7e727b0ba2aa39c5d5fadc780 # 15:41 B 0 30 53 6 0day head guard for 'devel-spot-201810180841'
# extra tests on tree/branch bvanassche/for-next
git bisect good 9294000d6d895ad609f3cc4aff98c9c6175b466f # 15:45 G 12 0 0 0 IB/srp: Drain the send queue before destroying a QP
# extra tests with first bad commit reverted
git bisect good 57cb041e20ed5399b993e5949362d3df0b64bc56 # 15:46 G 12 0 0 0 Revert "drivers/base: Probe devices concurrently if requested by the driver"
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
2 years, 4 months
- 1
- 0
Re: [LKP] 8cb682da6b ("rcu: Affinity forward-progress test to avoid .."): BUG: unable to handle kernel NULL pointer dereference at 00000000
by kernel test robot
On Tue, Oct 16, 2018 at 05:35:31AM -0700, Paul E. McKenney wrote:
> On Tue, Oct 16, 2018 at 08:36:57AM +0800, kernel test robot wrote:
> > Greetings,
> >
> > 0day kernel testing robot got the below dmesg and the first bad commit is
> >
> > https://git.kernel.org/pub/scm/linux/kernel/git/paulmck/linux-rcu.git dev.2018.10.09a
> >
> > commit 8cb682da6b49f65c3acf4255d045430cec116c52
> > Author: Paul E. McKenney <paulmck(a)linux.ibm.com>
> > AuthorDate: Fri Sep 21 18:08:09 2018 -0700
> > Commit: Paul E. McKenney <paulmck(a)linux.ibm.com>
> > CommitDate: Wed Oct 3 17:36:12 2018 -0700
> >
> > rcu: Affinity forward-progress test to avoid housekeeping CPUs
> >
> > This commit affinities the forward-progress tests to avoid hogging a
> > housekeeping CPU on the theory that the offloaded callbacks will be
> > running on those housekeeping CPUs.
> >
> > Signed-off-by: Paul E. McKenney <paulmck(a)linux.ibm.com>
> >
> > 61fe6c84c4 MAINTAINERS: Update from @linux.vnet.ibm.com to @linux.ibm.com
> > 8cb682da6b rcu: Affinity forward-progress test to avoid housekeeping CPUs
> > +------------------------------------------+------------+------------+
> > | | 61fe6c84c4 | 8cb682da6b |
> > +------------------------------------------+------------+------------+
> > | boot_successes | 37 | 0 |
> > | boot_failures | 0 | 13 |
> > | BUG:unable_to_handle_kernel | 0 | 13 |
> > | Oops:#[##] | 0 | 13 |
> > | EIP:__bitmap_weight | 0 | 13 |
> > | Kernel_panic-not_syncing:Fatal_exception | 0 | 13 |
> > +------------------------------------------+------------+------------+
> >
> > [ 1.414107] rcu-torture: torture_stutter task started
> > [ 1.415593] Kprobe smoke test: started
> > [ 1.487589] Kprobe smoke test: passed successfully
> > [ 1.488532] Key type blacklist registered
> > [ 1.492230] rcu-torture: rcu_torture_fwd_progress task started
> > [ 1.493190] BUG: unable to handle kernel NULL pointer dereference at 00000000
> > [ 1.494373] *pde = 00000000
> > [ 1.494857] Oops: 0000 [#1] SMP DEBUG_PAGEALLOC PTI
> > [ 1.495277] CPU: 0 PID: 47 Comm: rcu_torture_fwd Tainted: G T 4.19.0-rc1-00167-g8cb682d #2
> > [ 1.495277] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
> > [ 1.495277] EIP: __bitmap_weight+0x44/0x61
> > [ 1.495277] Code: 90 01 c3 83 c1 01 39 f1 75 ef f6 c2 1f 0f 95 c0 0f b6 c8 83 04 8d 7c 57 13 48 01 84 c0 74 15 f7 da 89 d1 b8 ff ff ff ff d3 e8 <23> 04 b7 f3 0f b8 c0 90 01 c3 89 d8 5b 5e 5f 5d c3 85 d2 0f 95 c0
> > [ 1.495277] EAX: 00000001 EBX: 00000000 ECX: ffffffff EDX: ffffffff
> > [ 1.495277] ESI: 00000000 EDI: 00000000 EBP: 4df25ef0 ESP: 4df25ee4
> > [ 1.495277] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068 EFLAGS: 00210a03
> > [ 1.495277] CR0: 80050033 CR2: 00000000 CR3: 0853e000 CR4: 000406d0
> > [ 1.495277] Call Trace:
> > [ 1.495277] ? rcu_busted_torture_deferred_free+0xf/0xf
>
> If you are using the "busted" torture type, you should expect failures.
> But I must confess that I would not have expected this one.
>
> > [ 1.495277] ? rcu_bind_current_to_nocb+0x1d/0xaa
> > [ 1.495277] ? rcu_busted_torture_deferred_free+0xf/0xf
> > [ 1.495277] ? rcu_torture_fwd_prog+0x3a/0x7d5
>
> Ouch. It looks like I should disable forward-progress testing when
> someone is using the "busted" torture type.
>
> First, how about the patch below?
the patch still has the same error.
>
> Second, I really do need destructive tests whose whole purpose is to
> demonstrate that rcutorture can force failures to happen. So how should
> I communicate to you guys which Kconfig options and boot parameters
> are expected to result in failures? An RCU_DESTRUCTIVE_TEST Kconfig or
> some such?
an kconfig is fine, or we could ignore the errors if they are changeless,
we will only check the result(SUCCESS/FAIL) if the test is destructive.
Best Regards,
Rong Chen
>
> Thanx, Paul
>
> ------------------------------------------------------------------------
>
> diff --git a/kernel/rcu/rcutorture.c b/kernel/rcu/rcutorture.c
> index a4c4a24bdcaa..f6e85faa4ff4 100644
> --- a/kernel/rcu/rcutorture.c
> +++ b/kernel/rcu/rcutorture.c
> @@ -1900,7 +1900,8 @@ static int __init rcu_torture_fwd_prog_init(void)
> {
> if (!fwd_progress)
> return 0; /* Not requested, so don't do it. */
> - if (!cur_ops->stall_dur || cur_ops->stall_dur() <= 0) {
> + if (!cur_ops->stall_dur || cur_ops->stall_dur() <= 0 ||
> + cur_ops == &rcu_busted_ops) {
> VERBOSE_TOROUT_STRING("rcu_torture_fwd_prog_init: Disabled, unsupported by RCU flavor under test");
> return 0;
> }
>
2 years, 4 months
- 1
- 0
Re: [LKP] [fsnotify] 60f7ed8c7c: will-it-scale.per_thread_ops -5.9% regression
by kernel test robot
On Mon, Oct 15, 2018 at 03:26:13PM +0300, Amir Goldstein wrote:
> On Mon, Oct 15, 2018 at 12:27 PM Amir Goldstein <amir73il(a)gmail.com> wrote:
> >
> > On Mon, Oct 15, 2018 at 10:50 AM Rong Chen <rong.a.chen(a)intel.com> wrote:
> > [...]
> > > the patch seems not work.
> > >
> > > tests: 1
> > > testcase/path_params/tbox_group/run: will-it-scale/16-thread-unlink2-performance/lkp-bdw-ep3d
> > >
> > > commit:
> > > 1e6cb72399 ("fsnotify: add super block object type")
> > > 298cd0b2f4 (the below patch)
> > >
> > > 1e6cb72399fd58b3 298cd0b2f481d9cc2e2cd5bfd3
> > > ---------------- --------------------------
> > > %stddev change %stddev
> > > \ | \
> > > 103.21 -5% 98.54 will-it-scale.time.user_time
> > > 46266 -6% 43516 will-it-scale.time.involuntary_context_switches
> > > 54483 -7% 50610 will-it-scale.per_thread_ops
> > > 871749 -7% 809765 will-it-scale.workload
> >
> > Thanks for testing my patch. As Jan commented, it is not surprising
> > that the patch
> > makes no difference.
> >
> > I would like to clarify a few things about how you ran the test before
> > I continue to
> > investigate:
> >
> > 1. When I ran the workload I saw that it writes files to whatever filesystem is
> > mounted on /tmp. Can I assume you have tmpfs mounted at /tmp?
yes, it's tmpfs
> >
> > 2. Can you confirm that there is no fanotify mount mark on the /tmp mount?
> > for example:
> > # ls -l /proc/*/fd/*|grep fanotify
> > lrwx------ 1 root root 64 Oct 15 08:36 /proc/3927/fd/3 -> anon_inode:[fanotify]
> > # grep fanotify.mnt_id /proc/3927/fdinfo/3
> > fanotify mnt_id:33 mflags:0 mask:3b ignored_mask:0
> > # grep ^$(( 0x33 )) /proc/3927/mountinfo
> > 51 16 0:27 / /tmp rw,relatime shared:18 - tmpfs tmpfs rw
no fanotify mount
> >
> > 3. I saw that LKP caches the results for a specific commit
> > (i.e. 1e6cb72399 ("fsnotify: add super block object type")).
> > Did you use cached results when comparing to patch or did you re-run the
> > test with the "good" commit? The reason I am asking is because
> > sometimes performance result may differ between boots even with no
> > kernel code change.
> > Where all the "good" bisect samples taken from the same boot/machine?
> > or different boots/machines?
from the same boot/machine, the only difference is the commit.
> >
> > 4. If this regression is reliably reproduced, then our best bet is on the
> > cost of access to s_fsnotify_{marks,mask} fields.
> > The patch below moves those frequently accessed fields near the
> > frequently accessed fields s_time_gran,s_writers and moves
> > the seldom accessed fields s_id,s_uuid further away.
> > Could you please try this patch?
> >
>
> Better test this patch instead. It does a bit more re-organizing.
> If this works well for 16-thread-unlink2 workload, could you please
> also run it through other workloads to see if it improves them as well?
> and does not degrade them...
the patch looks good.
tests: 1
testcase/path_params/tbox_group/run: will-it-scale/16-thread-unlink2-performance/lkp-bdw-ep3d
1e6cb72399fd58b3 bfb397545bb4d4bbca5ffb5e52
---------------- --------------------------
%stddev change %stddev
\ | \
81035 -8% 74707 interrupts.CAL:Function_call_interrupts
137 135 turbostat.PkgWatt
1507 -8% 1382 vmstat.system.cs
452379 -8% 414960 perf-stat.context-switches
1.02e+08 ± 5% -17% 84231388 ± 7% perf-stat.dTLB-store-misses
0.01 ± 7% -20% 0.01 ± 7% perf-stat.dTLB-store-miss-rate%
3937 52% 5968 proc-vmstat.nr_zone_inactive_anon
3937 52% 5968 proc-vmstat.nr_inactive_anon
8101 ± 7% 17% 9456 ± 7% proc-vmstat.nr_shmem
6766 13% 7662 proc-vmstat.nr_mapped
18520 18807 proc-vmstat.nr_slab_reclaimable
4784 4724 proc-vmstat.numa_other
0 9e+05 917236 ±119% latency_stats.avg.io_schedule.nfs_lock_and_join_requests.nfs_updatepage.nfs_write_end.generic_perform_write.nfs_file_write.__vfs_write.vfs_write.ksys_write.do_syscall_64.entry_SYSCALL_64_after_hwframe
3597 ± 18% 9e+05 919831 ±118% latency_stats.avg.max
269 ± 81% 8e+03 8196 ±217% latency_stats.avg.rpc_wait_bit_killable.__rpc_execute.rpc_run_task.rpc_call_sync.nfs3_rpc_wrapper.nfs3_proc_lookup.nfs_lookup_revalidate.lookup_fast.walk_component.link_path_walk.path_lookupat.filename_lookup
0 7e+03 6926 ±185% latency_stats.avg.rpc_wait_bit_killable.__rpc_execute.rpc_run_task.rpc_call_sync.nfs3_rpc_wrapper.nfs3_do_create.nfs3_proc_create.nfs_create.path_openat.do_filp_open.do_sys_open.do_syscall_64
0 9e+05 929948 ±116% latency_stats.max.io_schedule.nfs_lock_and_join_requests.nfs_updatepage.nfs_write_end.generic_perform_write.nfs_file_write.__vfs_write.vfs_write.ksys_write.do_syscall_64.entry_SYSCALL_64_after_hwframe
4934 9e+05 932944 ±116% latency_stats.max.max
300 ± 86% 2e+04 16025 ±222% latency_stats.max.rpc_wait_bit_killable.__rpc_execute.rpc_run_task.rpc_call_sync.nfs3_rpc_wrapper.nfs3_proc_lookup.nfs_lookup_revalidate.lookup_fast.walk_component.link_path_walk.path_lookupat.filename_lookup
0 7e+03 6926 ±185% latency_stats.max.rpc_wait_bit_killable.__rpc_execute.rpc_run_task.rpc_call_sync.nfs3_rpc_wrapper.nfs3_do_create.nfs3_proc_create.nfs_create.path_openat.do_filp_open.do_sys_open.do_syscall_64
0 1e+06 1209492 ±123% latency_stats.sum.io_schedule.nfs_lock_and_join_requests.nfs_updatepage.nfs_write_end.generic_perform_write.nfs_file_write.__vfs_write.vfs_write.ksys_write.do_syscall_64.entry_SYSCALL_64_after_hwframe
10126 ± 27% 2e+04 32104 ± 72% latency_stats.sum.do_syslog.kmsg_read.proc_reg_read.__vfs_read.vfs_read.ksys_read.do_syscall_64.entry_SYSCALL_64_after_hwframe
663 ±113% 2e+04 16393 ±217% latency_stats.sum.rpc_wait_bit_killable.__rpc_execute.rpc_run_task.rpc_call_sync.nfs3_rpc_wrapper.nfs3_proc_lookup.nfs_lookup_revalidate.lookup_fast.walk_component.link_path_walk.path_lookupat.filename_lookup
0 7e+03 6926 ±185% latency_stats.sum.rpc_wait_bit_killable.__rpc_execute.rpc_run_task.rpc_call_sync.nfs3_rpc_wrapper.nfs3_do_create.nfs3_proc_create.nfs_create.path_openat.do_filp_open.do_sys_open.do_syscall_64
2357 ± 62% 3e+03 5243 ±123% latency_stats.sum.rpc_wait_bit_killable.__rpc_execute.rpc_run_task.rpc_call_sync.nfs3_rpc_wrapper.nfs3_proc_getattr.__nfs_revalidate_inode.nfs_do_access.nfs_permission.inode_permission.link_path_walk.path_lookupat
Best Regards,
Rong Chen
>
> Thanks,
> Amir.
>
> ---
> diff --git a/include/linux/fs.h b/include/linux/fs.h
> index 25a449f37bb1..baec0b3ff53f 100644
> --- a/include/linux/fs.h
> +++ b/include/linux/fs.h
> @@ -1393,17 +1393,24 @@ struct super_block {
>
> struct sb_writers s_writers;
>
> + /* START frequently accessed fields block */
> + void *s_fs_info; /* Filesystem private info */
> +
> + /* Granularity of c/m/atime in ns (cannot be worse than a second) */
> + u32 s_time_gran;
> +#ifdef CONFIG_FSNOTIFY
> + __u32 s_fsnotify_mask;
> + struct fsnotify_mark_connector __rcu *s_fsnotify_marks;
> +#endif
> + /* END frequently accessed fields block */
> +
> + /* START seldom accessed fields block */
> char s_id[32]; /* Informational name */
> uuid_t s_uuid; /* UUID */
>
> - void *s_fs_info; /* Filesystem private info */
> unsigned int s_max_links;
> fmode_t s_mode;
>
> - /* Granularity of c/m/atime in ns.
> - Cannot be worse than a second */
> - u32 s_time_gran;
> -
> /*
> * The next field is for VFS *only*. No filesystems have any business
> * even looking at it. You had been warned.
> @@ -1415,6 +1422,7 @@ struct super_block {
> * in /proc/mounts will be "type.subtype"
> */
> char *s_subtype;
> + /* END seldom accessed fields block */
>
> const struct dentry_operations *s_d_op; /* default d_op for dentries */
>
> @@ -1464,11 +1472,6 @@ struct super_block {
>
> spinlock_t s_inode_wblist_lock;
> struct list_head s_inodes_wb; /* writeback inodes */
> -
> -#ifdef CONFIG_FSNOTIFY
> - __u32 s_fsnotify_mask;
> - struct fsnotify_mark_connector __rcu *s_fsnotify_marks;
> -#endif
> } __randomize_layout;
2 years, 4 months
- 1
- 0
f77477b162: hwsim.ap_open_multicast_to_unicast.fail
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: f77477b1624c0ac20c0c85c8b529da48686ab54d ("[PATCH] New functionality for aborting ongoing CAC.")
url: https://github.com/0day-ci/linux/commits/Enrique-Giraldo/New-functionalit...
base: https://git.kernel.org/cgit/linux/kernel/git/jberg/mac80211-next.git master
in testcase: hwsim
with following parameters:
group: hwsim-01
on test machine: qemu-system-x86_64 -enable-kvm -cpu host -smp 2 -m 4G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
2018-10-09 07:25:55 export USER=root
2018-10-09 07:25:55 ./build.sh
Building TNC testing tools
Building wlantest
Building hostapd
Building wpa_supplicant
2018-10-09 07:27:08 ./start.sh
2018-10-09 07:27:08 ./run-tests.py ap_hs20_connect_deinit
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_connect_deinit 1/1
Test: Hotspot 2.0 connection interrupted with deinit
Starting AP wlan3
PASS ap_hs20_connect_deinit 0.504144 2018-10-09 07:27:09.885105
passed all 1 test case(s)
2018-10-09 07:27:09 ./run-tests.py ap_hs20_connect_no_full_match
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_connect_no_full_match 1/1
Test: Hotspot 2.0 connection and no full match
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1272, in test_ap_hs20_connect_no_full_match
interworking_connect(dev[0], bssid, "TTLS")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_connect_no_full_match 15.34892 2018-10-09 07:27:25.505669
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_connect_no_full_match
2018-10-09 07:27:25 ./run-tests.py ap_hs20_cred_and_no_roaming_consortium
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_cred_and_no_roaming_consortium 1/1
Test: Hotspot 2.0 network selection and no roaming consortium
Starting AP wlan3
PASS ap_hs20_cred_and_no_roaming_consortium 0.308686 2018-10-09 07:27:26.091778
passed all 1 test case(s)
2018-10-09 07:27:26 ./run-tests.py ap_hs20_cred_with_nai_realm
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_cred_with_nai_realm 1/1
Test: Hotspot 2.0 network selection and cred_with_nai_realm cred->realm
Starting AP wlan3
PASS ap_hs20_cred_with_nai_realm 0.322052 2018-10-09 07:27:26.714295
passed all 1 test case(s)
2018-10-09 07:27:26 ./run-tests.py ap_hs20_deauth_req_bss
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_deauth_req_bss 1/1
Test: Hotspot 2.0 connection and deauthentication request for BSS
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 2446, in test_ap_hs20_deauth_req_bss
_test_ap_hs20_deauth_req_bss(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 2452, in _test_ap_hs20_deauth_req_bss
eap_test(dev[0], apdev[0], "21[3:26]", "TTLS", "user")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 804, in eap_test
interworking_connect(dev, bssid, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_deauth_req_bss 15.441359 2018-10-09 07:27:42.406714
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_deauth_req_bss
2018-10-09 07:27:42 ./run-tests.py ap_hs20_deauth_req_ess
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_deauth_req_ess 1/1
Test: Hotspot 2.0 connection and deauthentication request for ESS
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 2416, in test_ap_hs20_deauth_req_ess
_test_ap_hs20_deauth_req_ess(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 2422, in _test_ap_hs20_deauth_req_ess
eap_test(dev[0], apdev[0], "21[3:26]", "TTLS", "user")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 804, in eap_test
interworking_connect(dev, bssid, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_deauth_req_ess 15.386923 2018-10-09 07:27:58.060001
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_deauth_req_ess
2018-10-09 07:27:58 ./run-tests.py ap_hs20_deauth_req_from_radius
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_deauth_req_from_radius 1/1
Test: Hotspot 2.0 connection and deauthentication request from RADIUS
Starting AP wlan3
PASS ap_hs20_deauth_req_from_radius 2.55118 2018-10-09 07:28:00.917511
passed all 1 test case(s)
2018-10-09 07:28:00 ./run-tests.py ap_hs20_deauth_req_without_pmf
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_deauth_req_without_pmf 1/1
Test: Hotspot 2.0 connection and deauthentication request without PMF
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 2507, in test_ap_hs20_deauth_req_without_pmf
eap_test(dev[0], apdev[0], "21[3:26]", "TTLS", "user")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 804, in eap_test
interworking_connect(dev, bssid, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_deauth_req_without_pmf 15.466664 2018-10-09 07:28:16.660916
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_deauth_req_without_pmf
2018-10-09 07:28:16 ./run-tests.py ap_hs20_disallow_aps
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_disallow_aps 1/1
Test: Hotspot 2.0 connection and disallow_aps
Starting AP wlan3
Verify disallow_aps bssid
Verify disallow_aps ssid
Verify disallow_aps clear
PASS ap_hs20_disallow_aps 4.399108 2018-10-09 07:28:21.363042
passed all 1 test case(s)
2018-10-09 07:28:21 ./run-tests.py ap_hs20_domain_suffix_match
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_domain_suffix_match 1/1
Test: Hotspot 2.0 and domain_suffix_match
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1850, in test_ap_hs20_domain_suffix_match
interworking_connect(dev[0], bssid, "TTLS")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_domain_suffix_match 15.385649 2018-10-09 07:28:37.028972
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_domain_suffix_match
2018-10-09 07:28:37 ./run-tests.py ap_hs20_domain_suffix_match_full
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_domain_suffix_match_full 1/1
Test: Hotspot 2.0 and domain_suffix_match
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1819, in test_ap_hs20_domain_suffix_match_full
interworking_connect(dev[0], bssid, "TTLS")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_domain_suffix_match_full 15.483943 2018-10-09 07:28:52.805631
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_domain_suffix_match_full
2018-10-09 07:28:52 ./run-tests.py ap_hs20_eap_cert_unknown
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_eap_cert_unknown 1/1
Test: Hotspot 2.0 connection with certificate, but unknown EAP method
Starting AP wlan3
PASS ap_hs20_eap_cert_unknown 0.421515 2018-10-09 07:28:53.532916
passed all 1 test case(s)
2018-10-09 07:28:53 ./run-tests.py ap_hs20_eap_cert_unsupported
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_eap_cert_unsupported 1/1
Test: Hotspot 2.0 connection with certificate, but unsupported TTLS
Starting AP wlan3
PASS ap_hs20_eap_cert_unsupported 0.251282 2018-10-09 07:28:54.071920
passed all 1 test case(s)
2018-10-09 07:28:54 ./run-tests.py ap_hs20_eap_fast_gtc
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_eap_fast_gtc 1/1
Test: Hotspot 2.0 connection with FAST/EAP-GTC
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 908, in test_ap_hs20_eap_fast_gtc
eap_test(dev[0], apdev[0], "43[3:6]", "FAST", "user")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 804, in eap_test
interworking_connect(dev, bssid, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_eap_fast_gtc 15.363108 2018-10-09 07:29:09.701437
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_eap_fast_gtc
2018-10-09 07:29:09 ./run-tests.py ap_hs20_eap_fast_mschapv2
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_eap_fast_mschapv2 1/1
Test: Hotspot 2.0 connection with FAST/EAP-MSCHAPV2
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 903, in test_ap_hs20_eap_fast_mschapv2
eap_test(dev[0], apdev[0], "43[3:26]", "FAST", "user")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 804, in eap_test
interworking_connect(dev, bssid, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_eap_fast_mschapv2 15.402692 2018-10-09 07:29:25.353728
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_eap_fast_mschapv2
2018-10-09 07:29:25 ./run-tests.py ap_hs20_eap_invalid_cred
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_eap_invalid_cred 1/1
Test: Hotspot 2.0 connection with invalid cred configuration
Starting AP wlan3
PASS ap_hs20_eap_invalid_cred 0.404461 2018-10-09 07:29:26.097370
passed all 1 test case(s)
2018-10-09 07:29:26 ./run-tests.py ap_hs20_eap_peap_default
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_eap_peap_default 1/1
Test: Hotspot 2.0 connection with PEAP/MSCHAPV2 (as default)
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 826, in test_ap_hs20_eap_peap_default
eap_test(dev[0], apdev[0], "25", "PEAP", "user")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 804, in eap_test
interworking_connect(dev, bssid, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_eap_peap_default 15.467899 2018-10-09 07:29:41.822040
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_eap_peap_default
2018-10-09 07:29:41 ./run-tests.py ap_hs20_eap_peap_gtc
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_eap_peap_gtc 1/1
Test: Hotspot 2.0 connection with PEAP/GTC
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 830, in test_ap_hs20_eap_peap_gtc
eap_test(dev[0], apdev[0], "25[3:6]", "PEAP", "user")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 804, in eap_test
interworking_connect(dev, bssid, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_eap_peap_gtc 15.417526 2018-10-09 07:29:57.532822
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_eap_peap_gtc
2018-10-09 07:29:57 ./run-tests.py ap_hs20_eap_peap_mschapv2
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_eap_peap_mschapv2 1/1
Test: Hotspot 2.0 connection with PEAP/MSCHAPV2
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 821, in test_ap_hs20_eap_peap_mschapv2
eap_test(dev[0], apdev[0], "25[3:26]", "PEAP", "user")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 804, in eap_test
interworking_connect(dev, bssid, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_eap_peap_mschapv2 15.420642 2018-10-09 07:30:13.249221
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_eap_peap_mschapv2
2018-10-09 07:30:13 ./run-tests.py ap_hs20_eap_peap_unknown
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_eap_peap_unknown 1/1
Test: Hotspot 2.0 connection with PEAP/unknown
Starting AP wlan3
PASS ap_hs20_eap_peap_unknown 0.247025 2018-10-09 07:30:13.811477
passed all 1 test case(s)
2018-10-09 07:30:13 ./run-tests.py ap_hs20_eap_tls
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_eap_tls 1/1
Test: Hotspot 2.0 connection with EAP-TLS
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 924, in test_ap_hs20_eap_tls
interworking_connect(dev[0], bssid, "TLS")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_eap_tls 15.402282 2018-10-09 07:30:29.462908
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_eap_tls
2018-10-09 07:30:29 ./run-tests.py ap_hs20_eap_ttls_chap
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_eap_ttls_chap 1/1
Test: Hotspot 2.0 connection with TTLS/CHAP
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 847, in test_ap_hs20_eap_ttls_chap
eap_test(dev[0], apdev[0], "21[2:2]", "TTLS", "chap user")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 804, in eap_test
interworking_connect(dev, bssid, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_eap_ttls_chap 15.386036 2018-10-09 07:30:45.118400
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_eap_ttls_chap
2018-10-09 07:30:45 ./run-tests.py ap_hs20_eap_ttls_default
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_eap_ttls_default 1/1
Test: Hotspot 2.0 connection with TTLS/default
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 857, in test_ap_hs20_eap_ttls_default
eap_test(dev[0], apdev[0], "21", "TTLS", "hs20-test")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 804, in eap_test
interworking_connect(dev, bssid, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_eap_ttls_default 15.536546 2018-10-09 07:31:00.951242
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_eap_ttls_default
2018-10-09 07:31:00 ./run-tests.py ap_hs20_eap_ttls_eap_mschapv2
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_eap_ttls_eap_mschapv2 1/1
Test: Hotspot 2.0 connection with TTLS/EAP-MSCHAPv2
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 862, in test_ap_hs20_eap_ttls_eap_mschapv2
eap_test(dev[0], apdev[0], "21[3:26][6:7][99:99]", "TTLS", "user")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 804, in eap_test
interworking_connect(dev, bssid, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_eap_ttls_eap_mschapv2 15.432154 2018-10-09 07:31:16.681085
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_eap_ttls_eap_mschapv2
2018-10-09 07:31:16 ./run-tests.py ap_hs20_eap_ttls_eap_unknown
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_eap_ttls_eap_unknown 1/1
Test: Hotspot 2.0 connection with TTLS/EAP-unknown
Starting AP wlan3
PASS ap_hs20_eap_ttls_eap_unknown 0.241261 2018-10-09 07:31:17.213562
passed all 1 test case(s)
2018-10-09 07:31:17 ./run-tests.py ap_hs20_eap_ttls_eap_unsupported
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_eap_ttls_eap_unsupported 1/1
Test: Hotspot 2.0 connection with TTLS/EAP-OTP(unsupported)
Starting AP wlan3
PASS ap_hs20_eap_ttls_eap_unsupported 0.263835 2018-10-09 07:31:17.759853
passed all 1 test case(s)
2018-10-09 07:31:17 ./run-tests.py ap_hs20_eap_ttls_mschap
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_eap_ttls_mschap 1/1
Test: Hotspot 2.0 connection with TTLS/MSCHAP
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 852, in test_ap_hs20_eap_ttls_mschap
eap_test(dev[0], apdev[0], "21[2:3]", "TTLS", "mschap user")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 804, in eap_test
interworking_connect(dev, bssid, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_eap_ttls_mschap 15.428637 2018-10-09 07:31:33.470478
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_eap_ttls_mschap
2018-10-09 07:31:33 ./run-tests.py ap_hs20_eap_ttls_unknown
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_eap_ttls_unknown 1/1
Test: Hotspot 2.0 connection with TTLS/unknown
Starting AP wlan3
PASS ap_hs20_eap_ttls_unknown 0.267953 2018-10-09 07:31:34.053620
passed all 1 test case(s)
2018-10-09 07:31:34 ./run-tests.py ap_hs20_eap_unknown
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_eap_unknown 1/1
Test: Hotspot 2.0 connection with unknown EAP method
Starting AP wlan3
PASS ap_hs20_eap_unknown 0.308999 2018-10-09 07:31:34.650307
passed all 1 test case(s)
2018-10-09 07:31:34 ./run-tests.py ap_hs20_excluded_ssid
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_excluded_ssid 1/1
Test: Hotspot 2.0 exclusion based on SSID
Starting AP wlan3
Starting AP wlan4
Verify network selection to AP wlan4
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1735, in test_ap_hs20_excluded_ssid
events = policy_test(dev[0], apdev[1], values)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1634, in policy_test
ev = dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_excluded_ssid 15.469377 2018-10-09 07:31:50.379632
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_excluded_ssid
2018-10-09 07:31:50 ./run-tests.py ap_hs20_ext_sim
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_ext_sim 1/1
Test: Hotspot 2.0 with external SIM processing
Starting AP wlan3
PASS ap_hs20_ext_sim 0.422684 2018-10-09 07:31:51.114120
passed all 1 test case(s)
2018-10-09 07:31:51 ./run-tests.py ap_hs20_ext_sim_roaming
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_ext_sim_roaming 1/1
Test: Hotspot 2.0 with external SIM processing in roaming network
Starting AP wlan3
PASS ap_hs20_ext_sim_roaming 0.427925 2018-10-09 07:31:51.836790
passed all 1 test case(s)
2018-10-09 07:31:51 ./run-tests.py ap_hs20_external_selection
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_external_selection 1/1
Test: Hotspot 2.0 connection using external network selection and creation
Starting AP wlan3
Connect STA wlan0 to AP
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 3799, in test_ap_hs20_external_selection
roaming_consortium_selection="1020304050")
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1053, in connect
self.connect_network(id, timeout=20)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 426, in connect_network
self.wait_connected(timeout=timeout)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_external_selection 20.242917 2018-10-09 07:32:12.329235
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_external_selection
2018-10-09 07:32:12 ./run-tests.py ap_hs20_fetch_osu
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_fetch_osu 1/1
Test: Hotspot 2.0 OSU provider and icon fetch
Starting AP wlan3
Starting AP wlan4
PASS ap_hs20_fetch_osu 4.545049 2018-10-09 07:32:17.181361
passed all 1 test case(s)
2018-10-09 07:32:17 ./run-tests.py ap_hs20_fetch_osu_invalid_dir
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_fetch_osu_invalid_dir 1/1
Test: Hotspot 2.0 OSU provider and invalid directory
Starting AP wlan3
PASS ap_hs20_fetch_osu_invalid_dir 0.292011 2018-10-09 07:32:17.758291
passed all 1 test case(s)
2018-10-09 07:32:17 ./run-tests.py ap_hs20_fetch_osu_no_icon
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_fetch_osu_no_icon 1/1
Test: Hotspot 2.0 OSU provider and no icon found
Starting AP wlan3
PASS ap_hs20_fetch_osu_no_icon 4.212548 2018-10-09 07:32:22.226697
passed all 1 test case(s)
2018-10-09 07:32:22 ./run-tests.py ap_hs20_fetch_osu_no_info
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_fetch_osu_no_info 1/1
Test: Hotspot 2.0 OSU provider and no AP with info
Starting AP wlan3
PASS ap_hs20_fetch_osu_no_info 12.292647 2018-10-09 07:32:34.807571
passed all 1 test case(s)
2018-10-09 07:32:34 ./run-tests.py ap_hs20_fetch_osu_oom
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_fetch_osu_oom 1/1
Test: Hotspot 2.0 OSU provider and OOM
Starting AP wlan3
PASS ap_hs20_fetch_osu_oom 0.398488 2018-10-09 07:32:35.493607
passed all 1 test case(s)
2018-10-09 07:32:35 ./run-tests.py ap_hs20_fetch_osu_proto
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_fetch_osu_proto 1/1
Test: Hotspot 2.0 OSU provider and protocol testing
Starting AP wlan3
PASS ap_hs20_fetch_osu_proto 3.087462 2018-10-09 07:32:38.924116
passed all 1 test case(s)
2018-10-09 07:32:38 ./run-tests.py ap_hs20_fetch_osu_single_ssid
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_fetch_osu_single_ssid 1/1
Test: Hotspot 2.0 OSU provider and single SSID
Starting AP wlan3
OSU-PROVIDER 02:00:00:00:03:00
uri=https://example.com/osu/
methods=00000002
osu_ssid=HS 2.0 OSU open
osu_ssid2=test-hs20
friendly_name=eng:Test OSU
friendly_name=fin:Testi-OSU
desc=eng:Example services
desc=fin:Esimerkkipalveluja
PASS ap_hs20_fetch_osu_single_ssid 4.232687 2018-10-09 07:32:43.416503
passed all 1 test case(s)
2018-10-09 07:32:43 ./run-tests.py ap_hs20_fetch_osu_stop
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_fetch_osu_stop 1/1
Test: Hotspot 2.0 OSU provider fetch stopped
Starting AP wlan3
FETCH_OSU accepted while in FETCH_OSU
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 3445, in test_ap_hs20_fetch_osu_stop
raise Exception("FETCH_OSU accepted while in FETCH_OSU")
Exception: FETCH_OSU accepted while in FETCH_OSU
FAIL ap_hs20_fetch_osu_stop 9.03411 2018-10-09 07:32:52.770390
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_fetch_osu_stop
2018-10-09 07:32:52 ./run-tests.py ap_hs20_ft
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_ft 1/1
Test: Hotspot 2.0 connection with FT
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 3700, in test_ap_hs20_ft
interworking_connect(dev[0], bssid, "TTLS")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_ft 15.488025 2018-10-09 07:33:08.563477
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_ft
2018-10-09 07:33:08 ./run-tests.py ap_hs20_gas_frag_while_associated
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_gas_frag_while_associated 1/1
Test: Hotspot 2.0 connection with fragmented GAS query while associated
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1508, in test_ap_hs20_gas_frag_while_associated
interworking_connect(dev[0], bssid, "TTLS")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_gas_frag_while_associated 15.377368 2018-10-09 07:33:24.219896
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_gas_frag_while_associated
2018-10-09 07:33:24 ./run-tests.py ap_hs20_gas_while_associated
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_gas_while_associated 1/1
Test: Hotspot 2.0 connection with GAS query while associated
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1363, in test_ap_hs20_gas_while_associated
interworking_connect(dev[0], bssid, "TTLS")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
wlan0: Waiting for scan operation to complete before continuing
Run FLUSH again since scan was in progress
FAIL ap_hs20_gas_while_associated 16.398509 2018-10-09 07:33:40.881032
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_gas_while_associated
2018-10-09 07:33:40 ./run-tests.py ap_hs20_gas_while_associated_with_pmf
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_gas_while_associated_with_pmf 1/1
Test: Hotspot 2.0 connection with GAS query while associated and using PMF
Starting AP wlan3
Starting AP wlan4
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1414, in test_ap_hs20_gas_while_associated_with_pmf
_test_ap_hs20_gas_while_associated_with_pmf(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1438, in _test_ap_hs20_gas_while_associated_with_pmf
interworking_connect(dev[0], bssid, "TTLS")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_gas_while_associated_with_pmf 15.576213 2018-10-09 07:33:56.716232
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_gas_while_associated_with_pmf
2018-10-09 07:33:56 ./run-tests.py ap_hs20_gas_with_another_ap_while_associated
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_gas_with_another_ap_while_associated 1/1
Test: GAS query with another AP while associated
Starting AP wlan3
Starting AP wlan4
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1393, in test_ap_hs20_gas_with_another_ap_while_associated
interworking_connect(dev[0], bssid, "TTLS")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_gas_with_another_ap_while_associated 15.582358 2018-10-09 07:34:12.555252
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_gas_with_another_ap_while_associated
2018-10-09 07:34:12 ./run-tests.py ap_hs20_gas_with_another_ap_while_using_pmf
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_gas_with_another_ap_while_using_pmf 1/1
Test: GAS query with another AP while associated and using PMF
Starting AP wlan3
Starting AP wlan4
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1451, in test_ap_hs20_gas_with_another_ap_while_using_pmf
_test_ap_hs20_gas_with_another_ap_while_using_pmf(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1475, in _test_ap_hs20_gas_with_another_ap_while_using_pmf
interworking_connect(dev[0], bssid, "TTLS")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_gas_with_another_ap_while_using_pmf 15.601833 2018-10-09 07:34:28.452850
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_gas_with_another_ap_while_using_pmf
2018-10-09 07:34:28 ./run-tests.py ap_hs20_hidden_ssid_in_scan_res
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_hidden_ssid_in_scan_res 1/1
Test: Hotspot 2.0 connection with hidden SSId in scan results
Starting AP wlan3
Could not find BSS 02:00:00:00:03:00 in scan
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 3987, in test_ap_hs20_hidden_ssid_in_scan_res
dev[0].scan_for_bss(bssid, freq=2412)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1095, in scan_for_bss
raise Exception("Could not find BSS " + bssid + " in scan")
Exception: Could not find BSS 02:00:00:00:03:00 in scan
FAIL ap_hs20_hidden_ssid_in_scan_res 0.68578 2018-10-09 07:34:29.611943
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_hidden_ssid_in_scan_res
2018-10-09 07:34:29 ./run-tests.py ap_hs20_interworking_add_network
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_interworking_add_network 1/1
Test: Hotspot 2.0 connection using INTERWORKING_ADD_NETWORK
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 3892, in test_ap_hs20_interworking_add_network
dev[0].wait_connected()
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_interworking_add_network 10.41593 2018-10-09 07:34:40.289714
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_interworking_add_network
2018-10-09 07:34:40 ./run-tests.py ap_hs20_interworking_oom
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_interworking_oom 1/1
Test: Hotspot 2.0 network selection and OOM
Starting AP wlan3
PASS ap_hs20_interworking_oom 1.61875 2018-10-09 07:34:42.188606
passed all 1 test case(s)
2018-10-09 07:34:42 ./run-tests.py ap_hs20_interworking_select_blocking_scan
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_interworking_select_blocking_scan 1/1
Test: Ongoing INTERWORKING_SELECT blocking SCAN
Starting AP wlan3
PASS ap_hs20_interworking_select_blocking_scan 0.751181 2018-10-09 07:34:43.328744
passed all 1 test case(s)
2018-10-09 07:34:43 ./run-tests.py ap_hs20_max_bss_load
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_max_bss_load 1/1
Test: Hotspot 2.0 and maximum BSS load
Starting AP wlan3
Starting AP wlan4
Verify maximum BSS load constraint
Verify network selection to AP wlan4
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1893, in test_ap_hs20_max_bss_load
events = policy_test(dev[0], apdev[1], values, only_one=False)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1634, in policy_test
ev = dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_max_bss_load 15.469254 2018-10-09 07:34:59.082917
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_max_bss_load
2018-10-09 07:34:59 ./run-tests.py ap_hs20_max_bss_load2
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_max_bss_load2 1/1
Test: Hotspot 2.0 and maximum BSS load with one AP not advertising
Starting AP wlan3
Starting AP wlan4
Verify maximum BSS load constraint with AP advertisement
Verify network selection to AP wlan4
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1928, in test_ap_hs20_max_bss_load2
events = policy_test(dev[0], apdev[1], values, only_one=False)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1634, in policy_test
ev = dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_max_bss_load2 15.523829 2018-10-09 07:35:14.893997
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_max_bss_load2
2018-10-09 07:35:14 ./run-tests.py ap_hs20_max_bss_load_roaming
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_max_bss_load_roaming 1/1
Test: Hotspot 2.0 and maximum BSS load (roaming)
Starting AP wlan3
Verify network selection to AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1947, in test_ap_hs20_max_bss_load_roaming
events = policy_test(dev[0], apdev[0], values, only_one=True)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1634, in policy_test
ev = dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_max_bss_load_roaming 15.346777 2018-10-09 07:35:30.561129
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_max_bss_load_roaming
2018-10-09 07:35:30 ./run-tests.py ap_hs20_min_bandwidth_and_roaming_partner_preference
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_min_bandwidth_and_roaming_partner_preference 1/1
Test: Hotspot 2.0 and minimum bandwidth with roaming partner preference
Starting AP wlan3
Starting AP wlan4
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 2390, in test_ap_hs20_min_bandwidth_and_roaming_partner_preference
check_auto_select(dev[0], bssid2)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 60, in check_auto_select
ev = dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_min_bandwidth_and_roaming_partner_preference 15.410901 2018-10-09 07:35:46.271795
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_min_bandwidth_and_roaming_partner_preference
2018-10-09 07:35:46 ./run-tests.py ap_hs20_min_bandwidth_home
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_min_bandwidth_home 1/1
Test: Hotspot 2.0 network selection with min bandwidth (home)
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 2254, in test_ap_hs20_min_bandwidth_home
check_auto_select(dev[0], bssid)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 60, in check_auto_select
ev = dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_min_bandwidth_home 15.481775 2018-10-09 07:36:02.007716
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_min_bandwidth_home
2018-10-09 07:36:02 ./run-tests.py ap_hs20_min_bandwidth_home2
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_min_bandwidth_home2 1/1
Test: Hotspot 2.0 network selection with min bandwidth - special cases
Starting AP wlan3
WAN link at capacity
Downlink/Uplink Load was not measured
Uplink and Downlink max values
PASS ap_hs20_min_bandwidth_home2 0.562041 2018-10-09 07:36:02.837793
passed all 1 test case(s)
2018-10-09 07:36:02 ./run-tests.py ap_hs20_min_bandwidth_home_hidden_ssid_in_scan_res
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_min_bandwidth_home_hidden_ssid_in_scan_res 1/1
Test: Hotspot 2.0 network selection with min bandwidth (home) while hidden SSID is included in scan results
Starting AP wlan3
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 2326, in test_ap_hs20_min_bandwidth_home_hidden_ssid_in_scan_res
check_auto_select(dev[0], bssid)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 60, in check_auto_select
ev = dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_min_bandwidth_home_hidden_ssid_in_scan_res 15.855541 2018-10-09 07:36:19.052171
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_min_bandwidth_home_hidden_ssid_in_scan_res
2018-10-09 07:36:19 ./run-tests.py ap_hs20_min_bandwidth_no_wan_metrics
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_min_bandwidth_no_wan_metrics 1/1
Test: Hotspot 2.0 network selection with min bandwidth but no WAN Metrics
Starting AP wlan3
PASS ap_hs20_min_bandwidth_no_wan_metrics 0.343495 2018-10-09 07:36:19.689106
passed all 1 test case(s)
2018-10-09 07:36:19 ./run-tests.py ap_hs20_min_bandwidth_roaming
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_min_bandwidth_roaming 1/1
Test: Hotspot 2.0 network selection with min bandwidth (roaming)
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 2364, in test_ap_hs20_min_bandwidth_roaming
check_auto_select(dev[0], bssid)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 60, in check_auto_select
ev = dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_min_bandwidth_roaming 15.632295 2018-10-09 07:36:35.607523
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_min_bandwidth_roaming
2018-10-09 07:36:35 ./run-tests.py ap_hs20_multi_cred_sp_prio
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_multi_cred_sp_prio 1/1
Test: Hotspot 2.0 multi-cred sp_priority
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1958, in test_ap_hs20_multi_cred_sp_prio
_test_ap_hs20_multi_cred_sp_prio(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1995, in _test_ap_hs20_multi_cred_sp_prio
interworking_auth(dev[0], "TTLS")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_multi_cred_sp_prio 15.674954 2018-10-09 07:36:51.670259
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_multi_cred_sp_prio
2018-10-09 07:36:51 ./run-tests.py ap_hs20_multi_cred_sp_prio2
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_multi_cred_sp_prio2 1/1
Test: Hotspot 2.0 multi-cred sp_priority with two BSSes
Starting AP wlan3
Starting AP wlan4
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 2002, in test_ap_hs20_multi_cred_sp_prio2
_test_ap_hs20_multi_cred_sp_prio2(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 2051, in _test_ap_hs20_multi_cred_sp_prio2
interworking_auth(dev[0], "TTLS")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_multi_cred_sp_prio2 15.817472 2018-10-09 07:37:07.774292
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_multi_cred_sp_prio2
2018-10-09 07:37:07 ./run-tests.py ap_hs20_multi_cred_sp_prio_same
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_multi_cred_sp_prio_same 1/1
Test: Hotspot 2.0 multi-cred and same sp_priority
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 2086, in test_ap_hs20_multi_cred_sp_prio_same
check_auto_select(dev[0], bssid)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 60, in check_auto_select
ev = dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_multi_cred_sp_prio_same 15.38755 2018-10-09 07:37:23.462439
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_multi_cred_sp_prio_same
2018-10-09 07:37:23 ./run-tests.py ap_hs20_multi_network_and_cred_removal
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_multi_network_and_cred_removal 1/1
Test: Multiple networks and cred removal
Starting AP wlan3
PASS ap_hs20_multi_network_and_cred_removal 0.929063 2018-10-09 07:37:24.689425
passed all 1 test case(s)
2018-10-09 07:37:24 ./run-tests.py ap_hs20_multiple_connects
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_multiple_connects 1/1
Test: Hotspot 2.0 connection through multiple network selections
Starting AP wlan3
Starting Interworking network selection
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1543, in test_ap_hs20_multiple_connects
raise Exception("Connection timed out")
Exception: Connection timed out
FAIL ap_hs20_multiple_connects 15.2542 2018-10-09 07:37:40.231904
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_multiple_connects
2018-10-09 07:37:40 ./run-tests.py ap_hs20_multiple_home_cred
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_multiple_home_cred 1/1
Test: Hotspot 2.0 and select with multiple matching home credentials
Starting AP wlan3
Starting AP wlan4
PASS ap_hs20_multiple_home_cred 0.866894 2018-10-09 07:37:41.384971
passed all 1 test case(s)
2018-10-09 07:37:41 ./run-tests.py ap_hs20_nai_realms
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_nai_realms 1/1
Test: Hotspot 2.0 connection and multiple NAI realms and TTLS/PAP
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 986, in test_ap_hs20_nai_realms
interworking_connect(dev[0], bssid, "TTLS")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
wlan0: Waiting for scan operation to complete before continuing
Run FLUSH again since scan was in progress
FAIL ap_hs20_nai_realms 16.432512 2018-10-09 07:37:58.082968
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_nai_realms
2018-10-09 07:37:58 ./run-tests.py ap_hs20_network_preference
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_network_preference 1/1
Test: Hotspot 2.0 network selection with preferred home network
Starting AP wlan3
Starting AP wlan4
PASS ap_hs20_network_preference 0.95703 2018-10-09 07:37:59.401405
passed all 1 test case(s)
2018-10-09 07:37:59 ./run-tests.py ap_hs20_network_preference2
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_network_preference2 1/1
Test: Hotspot 2.0 network selection with preferred credential
Starting AP wlan4
Starting AP wlan3
PASS ap_hs20_network_preference2 1.155994 2018-10-09 07:38:00.942297
passed all 1 test case(s)
2018-10-09 07:38:00 ./run-tests.py ap_hs20_network_preference3
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_network_preference3 1/1
Test: Hotspot 2.0 network selection with two credential (one preferred)
Starting AP wlan3
Starting AP wlan4
PASS ap_hs20_network_preference3 0.991586 2018-10-09 07:38:02.227424
passed all 1 test case(s)
2018-10-09 07:38:02 ./run-tests.py ap_hs20_network_preference4
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_network_preference4 1/1
Test: Hotspot 2.0 network selection with username vs. SIM credential
Starting AP wlan3
Starting AP wlan4
PASS ap_hs20_network_preference4 0.851743 2018-10-09 07:38:03.405884
passed all 1 test case(s)
2018-10-09 07:38:03 ./run-tests.py ap_hs20_no_cred_connect
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_no_cred_connect 1/1
Test: Hotspot 2.0 and connect attempt without credential
Starting AP wlan3
PASS ap_hs20_no_cred_connect 0.298975 2018-10-09 07:38:03.959890
passed all 1 test case(s)
2018-10-09 07:38:03 ./run-tests.py ap_hs20_no_match_connect
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_no_match_connect 1/1
Test: Hotspot 2.0 and connect attempt without matching cred
Starting AP wlan3
PASS ap_hs20_no_match_connect 0.449273 2018-10-09 07:38:04.693980
passed all 1 test case(s)
2018-10-09 07:38:04 ./run-tests.py ap_hs20_no_rsn_connect
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_no_rsn_connect 1/1
Test: Hotspot 2.0 and connect attempt without RSN
Starting AP wlan3
PASS ap_hs20_no_rsn_connect 0.456425 2018-10-09 07:38:05.420243
passed all 1 test case(s)
2018-10-09 07:38:05 ./run-tests.py ap_hs20_osen
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_osen 1/1
Test: Hotspot 2.0 OSEN connection
Starting AP wlan3
Connect STA wlan1 to AP
Connect STA wlan2 to AP
Connect STA wlan0 to AP
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 2641, in test_ap_hs20_osen
scan_freq="2412")
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1053, in connect
self.connect_network(id, timeout=20)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 426, in connect_network
self.wait_connected(timeout=timeout)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_osen 20.410485 2018-10-09 07:38:26.101172
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_osen
2018-10-09 07:38:26 ./run-tests.py ap_hs20_osen_single_ssid
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_osen_single_ssid 1/1
Test: Hotspot 2.0 OSEN-single-SSID connection
Starting AP wlan3
Connect STA wlan0 to AP
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 2673, in test_ap_hs20_osen_single_ssid
scan_freq="2412")
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1053, in connect
self.connect_network(id, timeout=20)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 426, in connect_network
self.wait_connected(timeout=timeout)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_osen_single_ssid 20.232318 2018-10-09 07:38:46.680555
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_osen_single_ssid
2018-10-09 07:38:46 ./run-tests.py ap_hs20_prefer_home
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_prefer_home 1/1
Test: Hotspot 2.0 required roaming consortium
Starting AP wlan3
Starting AP wlan4
Verify network selection to AP wlan4
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1669, in test_ap_hs20_prefer_home
policy_test(dev[0], apdev[1], values, only_one=False)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1634, in policy_test
ev = dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_prefer_home 15.547795 2018-10-09 07:39:02.490339
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_prefer_home
2018-10-09 07:39:02 ./run-tests.py ap_hs20_proxyarp
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_proxyarp 1/1
Test: Hotspot 2.0 and ProxyARP
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 4017, in test_ap_hs20_proxyarp
_test_ap_hs20_proxyarp(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 3930, in _test_ap_hs20_proxyarp
interworking_connect(dev[0], bssid, "TTLS")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_proxyarp 15.849055 2018-10-09 07:39:18.607624
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_proxyarp
2018-10-09 07:39:18 ./run-tests.py ap_hs20_proxyarp_disable_dgaf
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_proxyarp_disable_dgaf 1/1
Test: Hotspot 2.0 and ProxyARP with DGAF disabled
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 4114, in test_ap_hs20_proxyarp_disable_dgaf
_test_ap_hs20_proxyarp_dgaf(dev, apdev, True)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 4054, in _test_ap_hs20_proxyarp_dgaf
interworking_connect(dev[0], bssid, "TTLS")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_proxyarp_disable_dgaf 15.495723 2018-10-09 07:39:34.356652
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_proxyarp_disable_dgaf
2018-10-09 07:39:34 ./run-tests.py ap_hs20_proxyarp_enable_dgaf
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_proxyarp_enable_dgaf 1/1
Test: Hotspot 2.0 and ProxyARP with DGAF enabled
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 4125, in test_ap_hs20_proxyarp_enable_dgaf
_test_ap_hs20_proxyarp_dgaf(dev, apdev, False)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 4054, in _test_ap_hs20_proxyarp_dgaf
interworking_connect(dev[0], bssid, "TTLS")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_proxyarp_enable_dgaf 15.531267 2018-10-09 07:39:50.203299
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_proxyarp_enable_dgaf
2018-10-09 07:39:50 ./run-tests.py ap_hs20_random_mac_addr
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_random_mac_addr 1/1
Test: Hotspot 2.0 connection with random MAC address
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 3831, in test_ap_hs20_random_mac_addr
interworking_connect(wpas, bssid, "TTLS")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_random_mac_addr 15.694964 2018-10-09 07:40:06.198906
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_random_mac_addr
2018-10-09 07:40:06 ./run-tests.py ap_hs20_remediation_required
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_remediation_required 1/1
Test: Hotspot 2.0 connection and remediation required from RADIUS
Starting AP wlan3
PASS ap_hs20_remediation_required 0.569482 2018-10-09 07:40:07.010946
passed all 1 test case(s)
2018-10-09 07:40:07 ./run-tests.py ap_hs20_remediation_required_ctrl
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_remediation_required_ctrl 1/1
Test: Hotspot 2.0 connection and subrem from ctrl_iface
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 2550, in test_ap_hs20_remediation_required_ctrl
_test_ap_hs20_remediation_required_ctrl(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 2565, in _test_ap_hs20_remediation_required_ctrl
interworking_connect(dev[0], bssid, "TTLS")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_remediation_required_ctrl 15.496368 2018-10-09 07:40:22.798846
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_remediation_required_ctrl
2018-10-09 07:40:22 ./run-tests.py ap_hs20_remediation_sql
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_remediation_sql 1/1
Test: Hotspot 2.0 connection and remediation required using SQLite for user DB
Starting AP wlan4
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 464, in main
t(dev, apdev, params)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 3767, in test_ap_hs20_remediation_sql
interworking_connect(dev[0], bssid, "TTLS")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_remediation_sql 15.63687 2018-10-09 07:40:38.787798
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_remediation_sql
2018-10-09 07:40:38 ./run-tests.py ap_hs20_req_conn_capab
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_req_conn_capab 1/1
Test: Hotspot 2.0 network selection with req_conn_capab
Starting AP wlan3
Not used in home network
Used in roaming network
Verify that req_conn_capab does not prevent connection if no other network is available
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 2127, in test_ap_hs20_req_conn_capab
check_auto_select(dev[0], bssid)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 60, in check_auto_select
ev = dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
wlan0: Waiting for scan operation to complete before continuing
Run FLUSH again since scan was in progress
FAIL ap_hs20_req_conn_capab 16.428754 2018-10-09 07:40:55.512652
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_req_conn_capab
2018-10-09 07:40:55 ./run-tests.py ap_hs20_req_conn_capab2
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_req_conn_capab2 1/1
Test: Hotspot 2.0 network selection with req_conn_capab (not present)
Starting AP wlan3
PASS ap_hs20_req_conn_capab2 0.402655 2018-10-09 07:40:56.228634
passed all 1 test case(s)
2018-10-09 07:40:56 ./run-tests.py ap_hs20_req_conn_capab_and_roaming_partner_preference
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_req_conn_capab_and_roaming_partner_preference 1/1
Test: Hotspot 2.0 and req_conn_capab with roaming partner preference
Starting AP wlan3
Starting AP wlan4
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 2192, in test_ap_hs20_req_conn_capab_and_roaming_partner_preference
check_auto_select(dev[0], bssid2)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 60, in check_auto_select
ev = dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_req_conn_capab_and_roaming_partner_preference 15.474201 2018-10-09 07:41:11.954137
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_req_conn_capab_and_roaming_partner_preference
2018-10-09 07:41:11 ./run-tests.py ap_hs20_req_hs20_icon
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_req_hs20_icon 1/1
Test: Hotspot 2.0 OSU provider and multi-icon fetch with REQ_HS20_ICON
Starting AP wlan3
PASS ap_hs20_req_hs20_icon 0.440391 2018-10-09 07:41:12.680531
passed all 1 test case(s)
2018-10-09 07:41:12 ./run-tests.py ap_hs20_req_hs20_icon_oom
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_req_hs20_icon_oom 1/1
Test: Hotspot 2.0 icon fetch OOM with REQ_HS20_ICON
Starting AP wlan3
PASS ap_hs20_req_hs20_icon_oom 0.40413 2018-10-09 07:41:13.326445
passed all 1 test case(s)
2018-10-09 07:41:13 ./run-tests.py ap_hs20_req_hs20_icon_parallel
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_req_hs20_icon_parallel 1/1
Test: Hotspot 2.0 OSU provider and multi-icon parallel fetch with REQ_HS20_ICON
Starting AP wlan3
PASS ap_hs20_req_hs20_icon_parallel 0.233011 2018-10-09 07:41:13.825496
passed all 1 test case(s)
2018-10-09 07:41:13 ./run-tests.py ap_hs20_req_operator_icon
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_req_operator_icon 1/1
Test: Hotspot 2.0 operator icons
Starting AP wlan3
PASS ap_hs20_req_operator_icon 0.412755 2018-10-09 07:41:14.535595
passed all 1 test case(s)
2018-10-09 07:41:14 ./run-tests.py ap_hs20_req_roaming_consortium
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_req_roaming_consortium 1/1
Test: Hotspot 2.0 required roaming consortium
Starting AP wlan3
Starting AP wlan4
Verify network selection to AP wlan4
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1686, in test_ap_hs20_req_roaming_consortium
policy_test(dev[0], apdev[1], values)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1634, in policy_test
ev = dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_req_roaming_consortium 15.49824 2018-10-09 07:41:30.332943
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_req_roaming_consortium
2018-10-09 07:41:30 ./run-tests.py ap_hs20_req_roaming_consortium_no_match
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_req_roaming_consortium_no_match 1/1
Test: Hotspot 2.0 required roaming consortium and no match
Starting AP wlan3
Starting AP wlan4
PASS ap_hs20_req_roaming_consortium_no_match 0.53117 2018-10-09 07:41:31.149939
passed all 1 test case(s)
2018-10-09 07:41:31 ./run-tests.py ap_hs20_roam_to_higher_prio
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_roam_to_higher_prio 1/1
Test: Hotspot 2.0 and roaming from current to higher priority network
Starting AP wlan3
Connect to the only network option
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1780, in test_ap_hs20_roam_to_higher_prio
interworking_connect(dev[0], bssid, "TTLS")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_roam_to_higher_prio 15.379494 2018-10-09 07:41:46.833142
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_roam_to_higher_prio
2018-10-09 07:41:46 ./run-tests.py ap_hs20_roaming_consortium
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_roaming_consortium 1/1
Test: Hotspot 2.0 connection based on roaming consortium match
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1005, in test_ap_hs20_roaming_consortium
interworking_connect(dev[0], bssid, "PEAP")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_roaming_consortium 15.45433 2018-10-09 07:42:02.523491
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_roaming_consortium
2018-10-09 07:42:02 ./run-tests.py ap_hs20_roaming_consortium_constraints
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_roaming_consortium_constraints 1/1
Test: Hotspot 2.0 connection and roaming consortium constraints
Starting AP wlan3
PASS ap_hs20_roaming_consortium_constraints 0.570799 2018-10-09 07:42:03.439917
passed all 1 test case(s)
2018-10-09 07:42:03 ./run-tests.py ap_hs20_roaming_consortium_element
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_roaming_consortium_element 1/1
Test: Hotspot 2.0 connection and invalid roaming consortium element
Starting AP wlan3
PASS ap_hs20_roaming_consortium_element 0.62067 2018-10-09 07:42:04.436442
passed all 1 test case(s)
2018-10-09 07:42:04 ./run-tests.py ap_hs20_roaming_consortium_invalid
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_roaming_consortium_invalid 1/1
Test: Hotspot 2.0 connection and invalid roaming consortium ANQP-element
Starting AP wlan3
PASS ap_hs20_roaming_consortium_invalid 0.270464 2018-10-09 07:42:05.064998
passed all 1 test case(s)
2018-10-09 07:42:05 ./run-tests.py ap_hs20_roaming_consortiums_match
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_roaming_consortiums_match 1/1
Test: Hotspot 2.0 connection based on roaming_consortiums match
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1031, in test_ap_hs20_roaming_consortiums_match
interworking_connect(dev[0], bssid, "PEAP")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_roaming_consortiums_match 15.415827 2018-10-09 07:42:20.735615
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_roaming_consortiums_match
2018-10-09 07:42:20 ./run-tests.py ap_hs20_roaming_partner_preference
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_roaming_partner_preference 1/1
Test: Hotspot 2.0 and roaming partner preference
Starting AP wlan3
Starting AP wlan4
Verify default vs. specified preference
Verify network selection to AP wlan4
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1867, in test_ap_hs20_roaming_partner_preference
policy_test(dev[0], apdev[1], values, only_one=False)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1634, in policy_test
ev = dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_roaming_partner_preference 15.500289 2018-10-09 07:42:36.493332
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_roaming_partner_preference
2018-10-09 07:42:36 ./run-tests.py ap_hs20_select
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_select 1/1
Test: Hotspot 2.0 network selection
Starting AP wlan3
Starting AP wlan4
PASS ap_hs20_select 4.922799 2018-10-09 07:42:41.718302
passed all 1 test case(s)
2018-10-09 07:42:41 ./run-tests.py ap_hs20_session_info
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_session_info 1/1
Test: Hotspot 2.0 connection and session information from RADIUS
Starting AP wlan3
PASS ap_hs20_session_info 15.120321 2018-10-09 07:42:57.270642
passed all 1 test case(s)
2018-10-09 07:42:57 ./run-tests.py ap_hs20_set_profile_failures
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_set_profile_failures 1/1
Test: Hotspot 2.0 and failures during profile configuration
Starting AP wlan3
Starting AP wlan3
Starting AP wlan3
Starting AP wlan3
Starting AP wlan3
Starting AP wlan3
Starting AP wlan3
PASS ap_hs20_set_profile_failures 3.358089 2018-10-09 07:43:00.945001
passed all 1 test case(s)
2018-10-09 07:43:00 ./run-tests.py ap_hs20_sim
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_sim 1/1
Test: Hotspot 2.0 with simulated SIM and EAP-SIM
Starting AP wlan3
PASS ap_hs20_sim 0.489807 2018-10-09 07:43:01.786194
passed all 1 test case(s)
2018-10-09 07:43:01 ./run-tests.py ap_hs20_sim_invalid
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_sim_invalid 1/1
Test: Hotspot 2.0 with simulated SIM and EAP-SIM - invalid IMSI
Starting AP wlan3
PASS ap_hs20_sim_invalid 0.349036 2018-10-09 07:43:02.488294
passed all 1 test case(s)
2018-10-09 07:43:02 ./run-tests.py ap_hs20_sim_oom
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_sim_oom 1/1
Test: Hotspot 2.0 with simulated SIM and EAP-SIM - OOM
Starting AP wlan3
PASS ap_hs20_sim_oom 0.418016 2018-10-09 07:43:03.277834
passed all 1 test case(s)
2018-10-09 07:43:03 ./run-tests.py ap_hs20_terms_and_conditions
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_terms_and_conditions 1/1
Test: Hotspot 2.0 Terms and Conditions signaling
Starting AP wlan3
Connect STA wlan0 to AP
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 5846, in test_ap_hs20_terms_and_conditions
ieee80211w='2', scan_freq="2412")
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1053, in connect
self.connect_network(id, timeout=20)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 426, in connect_network
self.wait_connected(timeout=timeout)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_terms_and_conditions 20.420218 2018-10-09 07:43:24.016233
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_terms_and_conditions
2018-10-09 07:43:24 ./run-tests.py ap_hs20_terms_and_conditions_coa
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_terms_and_conditions_coa 1/1
Test: Hotspot 2.0 Terms and Conditions signaling - CoA
Starting AP wlan3
Connect STA wlan0 to AP
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 5880, in test_ap_hs20_terms_and_conditions_coa
ieee80211w='2', scan_freq="2412")
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1053, in connect
self.connect_network(id, timeout=20)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 426, in connect_network
self.wait_connected(timeout=timeout)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_terms_and_conditions_coa 20.482635 2018-10-09 07:43:45.086590
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_terms_and_conditions_coa
2018-10-09 07:43:45 ./run-tests.py ap_hs20_terms_and_conditions_sql
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_terms_and_conditions_sql 1/1
Test: Hotspot 2.0 Terms and Conditions using SQLite for user DB
Starting AP wlan4
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 464, in main
t(dev, apdev, params)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 5931, in test_ap_hs20_terms_and_conditions_sql
"https://example.com/t_and_c?addr=" + addr + "&ap=123")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 5995, in run_ap_hs20_terms_and_conditions_sql
interworking_connect(dev[0], bssid, "TTLS")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_terms_and_conditions_sql 16.365575 2018-10-09 07:44:02.084248
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_terms_and_conditions_sql
2018-10-09 07:44:02 ./run-tests.py ap_hs20_terms_and_conditions_sql2
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_terms_and_conditions_sql2 1/1
Test: Hotspot 2.0 Terms and Conditions using SQLite for user DB
Starting AP wlan4
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 464, in main
t(dev, apdev, params)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 5938, in test_ap_hs20_terms_and_conditions_sql2
"https://example.com/t_and_c?addr=" + addr)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 5995, in run_ap_hs20_terms_and_conditions_sql
interworking_connect(dev[0], bssid, "TTLS")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_terms_and_conditions_sql2 15.923426 2018-10-09 07:44:18.672177
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_terms_and_conditions_sql2
2018-10-09 07:44:18 ./run-tests.py ap_hs20_unexpected
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_unexpected 1/1
Test: Unexpected Hotspot 2.0 AP configuration
Starting AP wlan3
Connect STA wlan0 to AP
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 5783, in test_ap_hs20_unexpected
scan_freq="2412")
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1053, in connect
self.connect_network(id, timeout=20)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 426, in connect_network
self.wait_connected(timeout=timeout)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_unexpected 20.386525 2018-10-09 07:44:39.597318
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_unexpected
2018-10-09 07:44:39 ./run-tests.py ap_hs20_username
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_username 1/1
Test: Hotspot 2.0 connection in username/password credential
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 663, in test_ap_hs20_username
interworking_connect(dev[0], bssid, "TTLS")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_username 15.505185 2018-10-09 07:44:55.587798
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_username
2018-10-09 07:44:55 ./run-tests.py ap_hs20_username_roaming
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_username_roaming 1/1
Test: Hotspot 2.0 connection in username/password credential (roaming)
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1309, in test_ap_hs20_username_roaming
interworking_connect(dev[0], bssid, "TTLS")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_username_roaming 15.550698 2018-10-09 07:45:11.552400
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_username_roaming
2018-10-09 07:45:11 ./run-tests.py ap_hs20_username_unknown
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_username_unknown 1/1
Test: Hotspot 2.0 connection in username/password credential (no domain in cred)
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1326, in test_ap_hs20_username_unknown
interworking_connect(dev[0], bssid, "TTLS")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
FAIL ap_hs20_username_unknown 15.486489 2018-10-09 07:45:27.457590
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_username_unknown
2018-10-09 07:45:27 ./run-tests.py ap_hs20_username_unknown2
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_hs20_username_unknown2 1/1
Test: Hotspot 2.0 connection in username/password credential (no domain advertized)
Starting AP wlan3
Connection timed out
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 1345, in test_ap_hs20_username_unknown2
interworking_connect(dev[0], bssid, "TTLS")
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 142, in interworking_connect
interworking_auth(dev, method)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_hs20.py", line 151, in interworking_auth
dev.wait_connected(timeout=15)
File "/lkp/benchmarks/hwsim/tests/hwsim/wpasupplicant.py", line 1276, in wait_connected
raise Exception(error)
Exception: Connection timed out
wlan0: Waiting for scan operation to complete before continuing
Run FLUSH again since scan was in progress
FAIL ap_hs20_username_unknown2 16.493848 2018-10-09 07:45:44.368015
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_hs20_username_unknown2
2018-10-09 07:45:44 ./run-tests.py ap_ht40_5ghz_disabled_sec
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht40_5ghz_disabled_sec 1/1
Test: HT40 on 5 GHz with disabled secondary channel
Starting AP wlan3
PASS ap_ht40_5ghz_disabled_sec 0.370039 2018-10-09 07:45:45.141196
passed all 1 test case(s)
2018-10-09 07:45:45 ./run-tests.py ap_ht40_5ghz_invalid_pair
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht40_5ghz_invalid_pair 1/1
Test: HT40 on 5 GHz with invalid channel pair
Starting AP wlan3
PASS ap_ht40_5ghz_invalid_pair 0.698548 2018-10-09 07:45:46.243296
passed all 1 test case(s)
2018-10-09 07:45:46 ./run-tests.py ap_ht40_5ghz_match
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht40_5ghz_match 1/1
Test: HT40 co-ex scan on 5 GHz with matching pri/sec channel
Starting AP wlan4
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht40_5ghz_match 1.439261 2018-10-09 07:45:48.076489
passed all 1 test case(s)
2018-10-09 07:45:48 ./run-tests.py ap_ht40_5ghz_switch
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht40_5ghz_switch 1/1
Test: HT40 co-ex scan on 5 GHz switching pri/sec channel
Starting AP wlan4
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht40_5ghz_switch 1.320692 2018-10-09 07:45:49.803964
passed all 1 test case(s)
2018-10-09 07:45:49 ./run-tests.py ap_ht40_5ghz_switch2
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht40_5ghz_switch2 1/1
Test: HT40 co-ex scan on 5 GHz switching pri/sec channel (2)
Starting AP wlan4
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht40_5ghz_switch2 2.697455 2018-10-09 07:45:52.879182
passed all 1 test case(s)
2018-10-09 07:45:52 ./run-tests.py ap_ht40_csa
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht40_csa 1/1
Test: HT with 40 MHz channel width and CSA
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht40_csa 2.630794 2018-10-09 07:45:55.919505
passed all 1 test case(s)
2018-10-09 07:45:55 ./run-tests.py ap_ht40_csa2
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht40_csa2 1/1
Test: HT with 40 MHz channel width and CSA
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht40_csa2 2.653016 2018-10-09 07:45:58.955006
passed all 1 test case(s)
2018-10-09 07:45:58 ./run-tests.py ap_ht40_csa3
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht40_csa3 1/1
Test: HT with 40 MHz channel width and CSA
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht40_csa3 2.802199 2018-10-09 07:46:02.052892
passed all 1 test case(s)
2018-10-09 07:46:02 ./run-tests.py ap_ht40_disable
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht40_disable 1/1
Test: HT40 disabling
Starting AP wlan3
Connect STA wlan0 to AP
SIGNAL_POLL: ['RSSI=-30', 'LINKSPEED=1', 'NOISE=-92', 'FREQUENCY=2437', 'WIDTH=40 MHz', 'CENTER_FRQ1=2427', 'AVG_RSSI=-30', 'AVG_BEACON_RSSI=-30']
SIGNAL_POLL: ['RSSI=-30', 'LINKSPEED=1', 'NOISE=9999', 'FREQUENCY=2437', 'WIDTH=20 MHz', 'CENTER_FRQ1=2437', 'AVG_RSSI=-30', 'AVG_BEACON_RSSI=-30']
PASS ap_ht40_disable 2.520339 2018-10-09 07:46:04.943886
passed all 1 test case(s)
2018-10-09 07:46:04 ./run-tests.py ap_ht40_plus_minus1
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht40_plus_minus1 1/1
Test: HT40 with both plus and minus allowed (1)
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht40_plus_minus1 1.96521 2018-10-09 07:46:07.302866
passed all 1 test case(s)
2018-10-09 07:46:07 ./run-tests.py ap_ht40_plus_minus2
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht40_plus_minus2 1/1
Test: HT40 with both plus and minus allowed (2)
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht40_plus_minus2 1.932575 2018-10-09 07:46:09.532136
passed all 1 test case(s)
2018-10-09 07:46:09 ./run-tests.py ap_ht40_scan
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht40_scan 1/1
Test: HT40 co-ex scan
Starting AP wlan3
hostapd STATUS: {'olbc_ht': '0', 'cac_time_left_seconds': 'N/A', 'num_sta_no_short_slot_time': '0', 'olbc': '0', 'num_sta_non_erp': '0', 'supported_rates': '02 04 0b 16 0c 12 18 24 30 48 60 6c', 'ht_op_mode': '0x0', 'state': 'ENABLED', 'num_sta_ht40_intolerant': '0', 'channel': '5', 'ht_mcs_bitmask': 'ffff0000000000000000', 'bssid[0]': '02:00:00:00:03:00', 'ieee80211n': '1', 'cac_time_seconds': '0', 'num_sta[0]': '0', 'ieee80211ac': '0', 'phy': 'phy3', 'max_txpower': '20', 'num_sta_ht_no_gf': '0', 'freq': '2432', 'num_sta_ht_20_mhz': '0', 'num_sta_no_short_preamble': '0', 'secondary_channel': '-1', 'ssid[0]': 'test-ht40', 'dtim_period': '2', 'beacon_int': '100', 'num_sta_no_ht': '0', 'bss[0]': 'wlan3', 'ht_caps_info': '000e'}
Connect STA wlan0 to AP
hostapd STA: {'ht_mcs_bitmask': 'ffff0000000000000000', 'listen_interval': '5', 'rx_packets': '2', 'timeout_next': 'NULLFUNC POLL', 'ext_capab': '04000a82014000400001', 'addr': '02:00:00:00:00:00', 'rx_bytes': '127', 'tx_rate_info': '10', 'signal': '-30', 'inactive_msec': '55', 'rx_rate_info': '0', 'supported_rates': '02 04 0b 16 0c 12 18 24 30 48 60 6c', 'capability': '0x421', 'supp_op_classes': '5151525354737475767778797a7b7c7d7e7f808182', 'flags': '[AUTH][ASSOC][AUTHORIZED][SHORT_PREAMBLE][WMM][HT]', 'connected_time': '0', 'tx_bytes': '196', 'aid': '1', 'tx_packets': '3', 'ht_caps_info': '0x1076'}
PASS ap_ht40_scan 2.104113 2018-10-09 07:46:11.954009
passed all 1 test case(s)
2018-10-09 07:46:11 ./run-tests.py ap_ht40_scan_broken_ap
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht40_scan_broken_ap 1/1
Test: HT40 co-ex scan and broken legacy/HT AP
Starting AP wlan4
Starting AP wlan3
Connect STA wlan0 to AP
Connect STA wlan1 to AP
PASS ap_ht40_scan_broken_ap 2.533069 2018-10-09 07:46:14.852232
passed all 1 test case(s)
2018-10-09 07:46:14 ./run-tests.py ap_ht40_scan_conflict
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht40_scan_conflict 1/1
Test: HT40 co-ex scan conflict
Starting AP wlan4
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht40_scan_conflict 3.330552 2018-10-09 07:46:18.434686
passed all 1 test case(s)
2018-10-09 07:46:18 ./run-tests.py ap_ht40_scan_conflict2
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht40_scan_conflict2 1/1
Test: HT40 co-ex scan conflict (HT40-)
Starting AP wlan4
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht40_scan_conflict2 3.269775 2018-10-09 07:46:21.947639
passed all 1 test case(s)
2018-10-09 07:46:21 ./run-tests.py ap_ht40_scan_ht20_conflict
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht40_scan_ht20_conflict 1/1
Test: HT40 co-ex scan conflict with HT 20 MHz AP
Starting AP wlan4
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht40_scan_ht20_conflict 2.138279 2018-10-09 07:46:24.333550
passed all 1 test case(s)
2018-10-09 07:46:24 ./run-tests.py ap_ht40_scan_intolerant
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht40_scan_intolerant 1/1
Test: HT40 co-ex scan finding an AP advertising 40 MHz intolerant
Starting AP wlan4
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht40_scan_intolerant 2.183207 2018-10-09 07:46:26.974523
passed all 1 test case(s)
2018-10-09 07:46:27 ./run-tests.py ap_ht40_scan_legacy_conflict
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht40_scan_legacy_conflict 1/1
Test: HT40 co-ex scan conflict with legacy 20 MHz AP
Starting AP wlan4
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht40_scan_legacy_conflict 2.165514 2018-10-09 07:46:29.517991
passed all 1 test case(s)
2018-10-09 07:46:29 ./run-tests.py ap_ht40_scan_match
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht40_scan_match 1/1
Test: HT40 co-ex scan matching configuration
Starting AP wlan4
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht40_scan_match 3.56253 2018-10-09 07:46:33.330542
passed all 1 test case(s)
2018-10-09 07:46:33 ./run-tests.py ap_ht40_scan_not_affected
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht40_scan_not_affected 1/1
Test: HT40 co-ex scan and other BSS not affected
Starting AP wlan4
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht40_scan_not_affected 2.956187 2018-10-09 07:46:36.542071
passed all 1 test case(s)
2018-10-09 07:46:36 ./run-tests.py ap_ht_40mhz_intolerant_ap
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht_40mhz_intolerant_ap 1/1
Test: Associated STA reports 40 MHz intolerant AP after association
Starting AP wlan3
Connect STA wlan0 to AP
Start 40 MHz intolerant AP
Starting AP wlan4
Waiting for co-ex report from STA
AP moved to 20 MHz channel
Waiting for AP to move back to 40 MHz channel
AP moved to 40 MHz channel
PASS ap_ht_40mhz_intolerant_ap 30.724332 2018-10-09 07:47:07.828907
passed all 1 test case(s)
2018-10-09 07:47:07 ./run-tests.py ap_ht_40mhz_intolerant_sta
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht_40mhz_intolerant_sta 1/1
Test: Associated STA indicating 40 MHz intolerant
Starting AP wlan3
Connect STA wlan0 to AP
Connect STA wlan2 to AP
PASS ap_ht_40mhz_intolerant_sta 4.100242 2018-10-09 07:47:12.212858
passed all 1 test case(s)
2018-10-09 07:47:12 ./run-tests.py ap_ht_40mhz_intolerant_sta_deinit
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht_40mhz_intolerant_sta_deinit 1/1
Test: Associated STA indicating 40 MHz intolerant and hostapd deinit
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht_40mhz_intolerant_sta_deinit 2.955086 2018-10-09 07:47:15.471737
passed all 1 test case(s)
2018-10-09 07:47:15 ./run-tests.py ap_ht_capab_not_supported
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht_capab_not_supported 1/1
Test: HT configuration with driver not supporting all ht_capab entries
Starting AP wlan3
PASS ap_ht_capab_not_supported 0.123207 2018-10-09 07:47:15.913204
passed all 1 test case(s)
2018-10-09 07:47:15 ./run-tests.py ap_ht_op_class_115
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht_op_class_115 1/1
Test: HT20 on operationg class 115
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht_op_class_115 0.590592 2018-10-09 07:47:16.772129
passed all 1 test case(s)
2018-10-09 07:47:16 ./run-tests.py ap_ht_op_class_116
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht_op_class_116 1/1
Test: HT40 on operationg class 116
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht_op_class_116 0.787153 2018-10-09 07:47:17.813101
passed all 1 test case(s)
2018-10-09 07:47:17 ./run-tests.py ap_ht_op_class_117
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht_op_class_117 1/1
Test: HT40 on operationg class 117
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht_op_class_117 0.799738 2018-10-09 07:47:18.862946
passed all 1 test case(s)
2018-10-09 07:47:18 ./run-tests.py ap_ht_op_class_118
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht_op_class_118 1/1
Test: HT20 on operationg class 118
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht_op_class_118 0.558196 2018-10-09 07:47:19.679472
passed all 1 test case(s)
2018-10-09 07:47:19 ./run-tests.py ap_ht_op_class_119
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht_op_class_119 1/1
Test: HT40 on operationg class 119
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht_op_class_119 0.727704 2018-10-09 07:47:20.683981
passed all 1 test case(s)
2018-10-09 07:47:20 ./run-tests.py ap_ht_op_class_120
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht_op_class_120 1/1
Test: HT40 on operationg class 120
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht_op_class_120 0.931349 2018-10-09 07:47:21.877515
passed all 1 test case(s)
2018-10-09 07:47:21 ./run-tests.py ap_ht_op_class_121
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht_op_class_121 1/1
Test: HT20 on operationg class 121
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht_op_class_121 0.661515 2018-10-09 07:47:22.787459
passed all 1 test case(s)
2018-10-09 07:47:22 ./run-tests.py ap_ht_op_class_122
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht_op_class_122 1/1
Test: HT40 on operationg class 122
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht_op_class_122 0.835663 2018-10-09 07:47:23.893365
passed all 1 test case(s)
2018-10-09 07:47:23 ./run-tests.py ap_ht_op_class_123
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht_op_class_123 1/1
Test: HT40 on operationg class 123
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht_op_class_123 0.899596 2018-10-09 07:47:25.068752
passed all 1 test case(s)
2018-10-09 07:47:25 ./run-tests.py ap_ht_op_class_124
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht_op_class_124 1/1
Test: HT20 on operationg class 124
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht_op_class_124 0.689112 2018-10-09 07:47:26.029387
passed all 1 test case(s)
2018-10-09 07:47:26 ./run-tests.py ap_ht_op_class_125
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht_op_class_125 1/1
Test: HT20 on operationg class 125
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht_op_class_125 0.54827 2018-10-09 07:47:26.845073
passed all 1 test case(s)
2018-10-09 07:47:26 ./run-tests.py ap_ht_op_class_126
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht_op_class_126 1/1
Test: HT40 on operationg class 126
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht_op_class_126 0.956262 2018-10-09 07:47:28.064548
passed all 1 test case(s)
2018-10-09 07:47:28 ./run-tests.py ap_ht_op_class_127
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht_op_class_127 1/1
Test: HT40 on operationg class 127
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht_op_class_127 0.943255 2018-10-09 07:47:29.305042
passed all 1 test case(s)
2018-10-09 07:47:29 ./run-tests.py ap_ht_op_class_81
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht_op_class_81 1/1
Test: HT20 on operationg class 81
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht_op_class_81 0.625664 2018-10-09 07:47:30.208364
passed all 1 test case(s)
2018-10-09 07:47:30 ./run-tests.py ap_ht_op_class_83
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht_op_class_83 1/1
Test: HT40 on operationg class 83
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht_op_class_83 1.921763 2018-10-09 07:47:32.414387
passed all 1 test case(s)
2018-10-09 07:47:32 ./run-tests.py ap_ht_op_class_84
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht_op_class_84 1/1
Test: HT40 on operationg class 84
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_ht_op_class_84 1.940625 2018-10-09 07:47:34.638745
passed all 1 test case(s)
2018-10-09 07:47:34 ./run-tests.py ap_ht_smps
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_ht_smps 1/1
Test: SMPS AP configuration options
Starting AP wlan3
Starting AP wlan4
Connect STA wlan0 to AP
Connect STA wlan1 to AP
PASS ap_ht_smps 0.959218 2018-10-09 07:47:35.907179
passed all 1 test case(s)
ignored_by_lkp ap_iapp
2018-10-09 07:47:35 ./run-tests.py ap_inactivity_disconnect
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_inactivity_disconnect 1/1
Test: AP using inactivity disconnect
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_inactivity_disconnect 3.269876 2018-10-09 07:47:39.459742
passed all 1 test case(s)
2018-10-09 07:47:39 ./run-tests.py ap_inactivity_poll
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_inactivity_poll 1/1
Test: AP using inactivity poll
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_inactivity_poll 18.337685 2018-10-09 07:47:58.056984
passed all 1 test case(s)
2018-10-09 07:47:58 ./run-tests.py ap_interworking_element_update
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_interworking_element_update 1/1
Test: Dynamic Interworking element update
Starting AP wlan3
Before update: {'noise': '-89', 'ssid': 'test-hs20', 'bssid': '02:00:00:00:03:00', 'level': '-30', 'tsf': '1539071278517355', 'age': '0', 'snr': '59', 'capabilities': '0x0411', 'qual': '0', 'est_throughput': '65000', 'beacon_int': '100', 'flags': '[WPA2-EAP-CCMP][ESS][HS20]', 'freq': '2412', 'ie': '0009746573742d68733230010882848b960c1218240301012a010432043048606c30140100000fac040100000fac040100000fac018c003b0251002d1a0c001bffff0000000000000000000001000000000000000000003d16010000000000000000000000000000000000000000007f0804000082004000406b091e07010200000003006c027f006f1001531122331020304050010203040506dd180050f2020101000003a4000027a4000042435e0062322f00dd07506f9a10140000', 'id': '224', 'update_idx': '479'}
After update: {'noise': '-89', 'ssid': 'test-hs20', 'bssid': '02:00:00:00:03:00', 'level': '-30', 'tsf': '1539071278612202', 'age': '0', 'snr': '59', 'capabilities': '0x0411', 'qual': '0', 'est_throughput': '65000', 'beacon_int': '100', 'flags': '[WPA2-EAP-CCMP][ESS][HS20]', 'freq': '2412', 'ie': '0009746573742d68733230010882848b960c1218240301012a010432043048606c30140100000fac040100000fac040100000fac018c003b0251002d1a0c001bffff0000000000000000000001000000000000000000003d16010000000000000000000000000000000000000000007f0804000082004000406b09f202080200000003006c027f006f1001531122331020304050010203040506dd180050f2020101000003a4000027a4000042435e0062322f00dd07506f9a10140000', 'id': '225', 'update_idx': '480'}
PASS ap_interworking_element_update 0.402274 2018-10-09 07:47:58.820199
passed all 1 test case(s)
2018-10-09 07:47:58 ./run-tests.py ap_interworking_scan_filtering
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_interworking_scan_filtering 1/1
Test: Interworking scan filtering with HESSID and access network type
Starting AP wlan3
Starting AP wlan4
Check probe request filtering based on HESSID
Check probe request filtering based on access network type
Check probe request filtering based on HESSID and ANT
PASS ap_interworking_scan_filtering 1.134827 2018-10-09 07:48:00.375600
passed all 1 test case(s)
2018-10-09 07:48:00 ./run-tests.py ap_invalid_config
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_invalid_config 1/1
Test: Try to start AP with invalid configuration and fix configuration
Trying to start AP wlan3 with invalid configuration
Starting AP wlan3
Fix configuration and start AP again
Connect STA wlan0 to AP
PASS ap_invalid_config 0.69125 2018-10-09 07:48:01.413196
passed all 1 test case(s)
2018-10-09 07:48:01 ./run-tests.py ap_invalid_config2
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_invalid_config2 1/1
Test: Try to start AP with invalid configuration and remove interface
Trying to start AP wlan3 with invalid configuration
Starting AP wlan3
Remove interface with failed configuration
Removing BSS wlan3
PASS ap_invalid_config2 0.107666 2018-10-09 07:48:01.844988
passed all 1 test case(s)
2018-10-09 07:48:01 ./run-tests.py ap_long_preamble
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_long_preamble 1/1
Test: AP with long preamble
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_long_preamble 0.369476 2018-10-09 07:48:02.555361
passed all 1 test case(s)
2018-10-09 07:48:02 ./run-tests.py ap_max_listen_interval
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_max_listen_interval 1/1
Test: Open AP with maximum listen interval limit
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_max_listen_interval 0.40163 2018-10-09 07:48:03.217666
passed all 1 test case(s)
2018-10-09 07:48:03 ./run-tests.py ap_max_num_sta
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_max_num_sta 1/1
Test: Open AP with maximum STA count
Starting AP wlan3
Connect STA wlan1 to AP
Connect STA wlan0 to AP
PASS ap_max_num_sta 1.441202 2018-10-09 07:48:04.925170
passed all 1 test case(s)
2018-10-09 07:48:04 ./run-tests.py ap_max_num_sta_no_probe_resp
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_max_num_sta_no_probe_resp 1/1
Test: Maximum STA count and limit on Probe Response frames
Starting AP wlan3
Connect STA wlan1 to AP
PASS ap_max_num_sta_no_probe_resp 0.793166 2018-10-09 07:48:06.077469
passed all 1 test case(s)
2018-10-09 07:48:06 ./run-tests.py ap_missing_psk
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_missing_psk 1/1
Test: WPA2-PSK AP and no PSK configured
Starting AP wlan3
PASS ap_missing_psk 0.064943 2018-10-09 07:48:06.595199
passed all 1 test case(s)
2018-10-09 07:48:06 ./run-tests.py ap_mixed_security
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_mixed_security 1/1
Test: WPA/WPA2 with PSK, EAP, SAE, FT in a single BSS
Starting AP wlan3
Connect STA wlan0 to AP
Connect STA wlan1 to AP
Connect STA wlan2 to AP
Connect STA wlan0 to AP
Connect STA wlan1 to AP
Connect STA wlan2 to AP
Connect STA wlan0 to AP
Connect STA wlan1 to AP
Connect STA wlan2 to AP
PASS ap_mixed_security 2.226826 2018-10-09 07:48:09.243066
passed all 1 test case(s)
2018-10-09 07:48:09 ./run-tests.py ap_multi_bss
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_multi_bss 1/1
Test: Multiple BSSes with hostapd
Starting BSS phy=phy3 ifname=wlan3
Starting BSS phy=phy3 ifname=wlan3-2
Connect STA wlan0 to AP
Connect STA wlan1 to AP
PASS ap_multi_bss 0.998787 2018-10-09 07:48:10.698781
passed all 1 test case(s)
2018-10-09 07:48:10 ./run-tests.py ap_multi_bss_acs
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_multi_bss_acs 1/1
Test: hostapd start with a multi-BSS configuration file using ACS
Starting AP wlan3
Removing BSS wlan3
Starting interface wlan3
Connect STA wlan0 to AP
Connect STA wlan1 to AP
Connect STA wlan2 to AP
PASS ap_multi_bss_acs 8.798382 2018-10-09 07:48:20.183872
passed all 1 test case(s)
2018-10-09 07:48:20 ./run-tests.py ap_multi_bss_config
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_multi_bss_config 1/1
Test: hostapd start with a multi-BSS configuration file
Set up three BSSes with one configuration file
Starting interface wlan3
Connect STA wlan0 to AP
Connect STA wlan1 to AP
Connect STA wlan2 to AP
Removing BSS wlan3-2
Connect STA wlan1 to AP
Connect STA wlan0 to AP
Connect STA wlan2 to AP
Removing BSS wlan3-3
Connect STA wlan1 to AP
Connect STA wlan2 to AP
Connect STA wlan0 to AP
Removing BSS wlan3
Connect STA wlan0 to AP
Connect STA wlan1 to AP
Connect STA wlan2 to AP
Starting interface wlan3
Removing BSS wlan3
Connect STA wlan0 to AP
Connect STA wlan1 to AP
Connect STA wlan2 to AP
PASS ap_multi_bss_config 3.340578 2018-10-09 07:48:23.814484
passed all 1 test case(s)
2018-10-09 07:48:23 ./run-tests.py ap_nai_home_realm_query
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_nai_home_realm_query 1/1
Test: NAI Home Realm Query
Starting AP wlan3
PASS ap_nai_home_realm_query 0.364093 2018-10-09 07:48:24.730199
passed all 1 test case(s)
2018-10-09 07:48:24 ./run-tests.py ap_no_probe_resp
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_no_probe_resp 1/1
Test: AP with Probe Response frame sending from hostapd disabled
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_no_probe_resp 0.580387 2018-10-09 07:48:25.578454
passed all 1 test case(s)
2018-10-09 07:48:25 ./run-tests.py ap_open
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_open 1/1
Test: AP with open mode (no security) configuration
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_open 0.355396 2018-10-09 07:48:26.308672
passed all 1 test case(s)
2018-10-09 07:48:26 ./run-tests.py ap_open_assoc_timeout
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_open_assoc_timeout 1/1
Test: AP timing out association
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_open_assoc_timeout 1.440875 2018-10-09 07:48:28.065422
passed all 1 test case(s)
2018-10-09 07:48:28 ./run-tests.py ap_open_country_indoor
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_open_country_indoor 1/1
Test: AP with open mode and indoor country code
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_open_country_indoor 0.578011 2018-10-09 07:48:29.031429
passed all 1 test case(s)
2018-10-09 07:48:29 ./run-tests.py ap_open_country_outdoor
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_open_country_outdoor 1/1
Test: AP with open mode and outdoor country code
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_open_country_outdoor 0.483377 2018-10-09 07:48:29.781685
passed all 1 test case(s)
2018-10-09 07:48:29 ./run-tests.py ap_open_country_table_e4
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_open_country_table_e4 1/1
Test: AP with open mode and Table E-4 Country String
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_open_country_table_e4 0.630732 2018-10-09 07:48:30.735903
passed all 1 test case(s)
2018-10-09 07:48:30 ./run-tests.py ap_open_disable_enable
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_open_disable_enable 1/1
Test: AP with open mode getting disabled and re-enabled
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_open_disable_enable 0.966222 2018-10-09 07:48:32.068552
passed all 1 test case(s)
2018-10-09 07:48:32 ./run-tests.py ap_open_disable_select
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_open_disable_select 1/1
Test: DISABLE_NETWORK for connected AP followed by SELECT_NETWORK
Starting AP wlan3
Starting AP wlan4
Connect STA wlan0 to AP
PASS ap_open_disable_select 0.637699 2018-10-09 07:48:32.966505
passed all 1 test case(s)
2018-10-09 07:48:32 ./run-tests.py ap_open_disconnect_in_ps
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_open_disconnect_in_ps 1/1
Test: Disconnect with the client in PS to regression-test a kernel bug
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_open_disconnect_in_ps 1.561041 2018-10-09 07:48:34.784777
passed all 1 test case(s)
2018-10-09 07:48:34 ./run-tests.py ap_open_drop_duplicate
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_open_drop_duplicate 1/1
Test: AP dropping duplicate management frames
Starting AP wlan3
PASS ap_open_drop_duplicate 0.827102 2018-10-09 07:48:35.940199
passed all 1 test case(s)
2018-10-09 07:48:36 ./run-tests.py ap_open_drv_fail
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_open_drv_fail 1/1
Test: AP with open mode and driver operations failing
Starting AP wlan3
Connect STA wlan0 to AP
Connect STA wlan0 to AP
PASS ap_open_drv_fail 0.330468 2018-10-09 07:48:36.836439
passed all 1 test case(s)
2018-10-09 07:48:36 ./run-tests.py ap_open_external_assoc
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_open_external_assoc 1/1
Test: AP with open mode and external association
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_open_external_assoc 0.445825 2018-10-09 07:48:37.559942
passed all 1 test case(s)
2018-10-09 07:48:37 ./run-tests.py ap_open_id_str
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_open_id_str 1/1
Test: AP with open mode and id_str
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_open_id_str 0.426342 2018-10-09 07:48:38.249000
passed all 1 test case(s)
2018-10-09 07:48:38 ./run-tests.py ap_open_ifdown
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_open_ifdown 1/1
Test: AP with open mode and external ifconfig down
Starting AP wlan3
Connect STA wlan0 to AP
Connect STA wlan1 to AP
PASS ap_open_ifdown 1.468984 2018-10-09 07:48:39.990506
passed all 1 test case(s)
2018-10-09 07:48:40 ./run-tests.py ap_open_invalid_wmm_action
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_open_invalid_wmm_action 1/1
Test: AP with open mode configuration and invalid WMM Action frame
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_open_invalid_wmm_action 0.48419 2018-10-09 07:48:40.955060
passed all 1 test case(s)
2018-10-09 07:48:41 ./run-tests.py ap_open_multicast_to_unicast
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_open_multicast_to_unicast 1/1
Test: Multicast-to-unicast conversion enabled
Starting AP wlan3
Connect STA wlan0 to AP
Unexpected dev2->dev1 broadcast data result: multicast to unicast conversion missing
Traceback (most recent call last):
File "./run-tests.py", line 466, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_open.py", line 668, in test_ap_open_multicast_to_unicast
run_multicast_to_unicast(dev, apdev, True)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_ap_open.py", line 660, in run_multicast_to_unicast
hwsim_utils.test_connectivity(dev[0], hapd, multicast_to_unicast=convert)
File "/lkp/benchmarks/hwsim/tests/hwsim/hwsim_utils.py", line 169, in test_connectivity
raise Exception(last_err)
Exception: Unexpected dev2->dev1 broadcast data result: multicast to unicast conversion missing
FAIL ap_open_multicast_to_unicast 0.694044 2018-10-09 07:48:42.105816
passed 0 test case(s)
skipped 0 test case(s)
failed tests: ap_open_multicast_to_unicast
2018-10-09 07:48:42 ./run-tests.py ap_open_multicast_to_unicast_disabled
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_open_multicast_to_unicast_disabled 1/1
Test: Multicast-to-unicast conversion disabled
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_open_multicast_to_unicast_disabled 0.411295 2018-10-09 07:48:42.901197
passed all 1 test case(s)
2018-10-09 07:48:42 ./run-tests.py ap_open_noncountry
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_open_noncountry 1/1
Test: AP with open mode and noncountry entity as Country String
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_open_noncountry 5.790959 2018-10-09 07:48:49.002924
passed all 1 test case(s)
2018-10-09 07:48:49 ./run-tests.py ap_open_out_of_memory
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_open_out_of_memory 1/1
Test: hostapd failing to setup interface due to allocation failure
Starting AP wlan3
Starting AP wlan4
Starting AP wlan4
Starting AP wlan4
Starting AP wlan4
Starting AP wlan4
Starting AP wlan4
Starting AP wlan4
Starting AP wlan4
Starting AP wlan4
Starting AP wlan4
Starting AP wlan4
Starting AP wlan4
Starting AP wlan4
PASS ap_open_out_of_memory 0.530121 2018-10-09 07:48:50.005689
passed all 1 test case(s)
2018-10-09 07:48:50 ./run-tests.py ap_open_packet_loss
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_open_packet_loss 1/1
Test: AP with open mode configuration and large packet loss
Starting AP wlan3
Connect STA wlan0 to AP
Connect STA wlan1 to AP
Connect STA wlan2 to AP
PASS ap_open_packet_loss 6.354845 2018-10-09 07:48:56.755688
passed all 1 test case(s)
2018-10-09 07:48:56 ./run-tests.py ap_open_per_sta_vif
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_open_per_sta_vif 1/1
Test: AP VLAN with open network
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_open_per_sta_vif 0.65892 2018-10-09 07:48:57.893034
passed all 1 test case(s)
2018-10-09 07:48:57 ./run-tests.py ap_open_pmf_default
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_open_pmf_default 1/1
Test: AP with open mode (no security) configuration and pmf=2
Starting AP wlan3
Connect STA wlan1 to AP
Connect STA wlan2 to AP
Connect STA wlan0 to AP
PASS ap_open_pmf_default 0.765115 2018-10-09 07:48:59.098221
passed all 1 test case(s)
2018-10-09 07:48:59 ./run-tests.py ap_open_poll_sta
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_open_poll_sta 1/1
Test: AP with open mode and STA poll
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_open_poll_sta 0.594598 2018-10-09 07:49:00.110802
passed all 1 test case(s)
2018-10-09 07:49:00 ./run-tests.py ap_open_reassoc_not_found
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_open_reassoc_not_found 1/1
Test: AP with open mode and REASSOCIATE not finding a match
Connect STA wlan0 to AP
PASS ap_open_reassoc_not_found 0.236079 2018-10-09 07:49:00.710190
passed all 1 test case(s)
2018-10-09 07:49:00 ./run-tests.py ap_open_reassoc_same
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_open_reassoc_same 1/1
Test: AP with open mode and STA reassociating back to same AP without auth exchange
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_open_reassoc_same 0.634278 2018-10-09 07:49:01.672707
passed all 1 test case(s)
2018-10-09 07:49:01 ./run-tests.py ap_open_reconnect_on_inactivity_disconnect
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_open_reconnect_on_inactivity_disconnect 1/1
Test: Reconnect to open mode AP after inactivity related disconnection
Starting AP wlan3
Connect STA wlan0 to AP
PASS ap_open_reconnect_on_inactivity_disconnect 0.582104 2018-10-09 07:49:02.733246
passed all 1 test case(s)
2018-10-09 07:49:02 ./run-tests.py ap_open_select_any
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START ap_open_select_any 1/1
Test: AP with open mode and select any network
Starting AP wlan3
Connect STA wlan0 to AP
Connect STA wlan0 to AP
PASS ap_open_select_any 0.484016 2018-10-09 07:49:03.671680
passed all 1 test case(s)
2018-10-09 07:49:03 ./stop.sh
2018-10-09 07:49:04 ./start.sh channels=4
2018-10-09 07:49:05 ./stop.sh
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
Rong Chen
2 years, 4 months
- 2
- 1