[lkp-robot] [kvm] ae1e2d1082: kvm-unit-tests.vmx_EPT_AD_enabled.fail
by kernel test robot
FYI, we noticed the following commit:
commit: ae1e2d1082ae6969ff8c626ef80804d950bf256b ("kvm: nVMX: support EPT accessed/dirty bits")
https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git master
in testcase: kvm-unit-tests
with following parameters:
on test machine: 72 threads Intel(R) Xeon(R) CPU E5-2699 v3 @ 2.30GHz with 128G memory
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
ignored_by_lkp: taskswitch
ignored_by_lkp: taskswitch2
ignored_by_lkp: svm
2017-08-18 03:55:19 ./run_tests.sh
[32mPASS[0m apic-split (29 tests)
[32mPASS[0m ioapic-split (19 tests)
[32mPASS[0m apic (29 tests)
[32mPASS[0m ioapic (19 tests)
[32mPASS[0m smptest (1 tests)
[32mPASS[0m smptest3 (1 tests)
[32mPASS[0m vmexit_cpuid
[32mPASS[0m vmexit_vmcall
[32mPASS[0m vmexit_mov_from_cr8
[32mPASS[0m vmexit_mov_to_cr8
[32mPASS[0m vmexit_inl_pmtimer
[32mPASS[0m vmexit_ipi
[32mPASS[0m vmexit_ipi_halt
[32mPASS[0m vmexit_ple_round_robin
[32mPASS[0m vmexit_tscdeadline
[32mPASS[0m access
[33mSKIP[0m smap (0 tests)
[33mSKIP[0m pku (0 tests)
[32mPASS[0m emulator (131 tests, 1 skipped)
[32mPASS[0m eventinj (13 tests)
[32mPASS[0m hypercall (2 tests)
[32mPASS[0m idt_test (4 tests)
[32mPASS[0m msr (12 tests)
[32mPASS[0m pmu (67 tests)
[32mPASS[0m port80
[32mPASS[0m realmode
[32mPASS[0m s3
[32mPASS[0m sieve
[31mFAIL[0m syscall (2 tests, 1 unexpected failures)
[32mPASS[0m tsc (3 tests)
[32mPASS[0m tsc_adjust (5 tests)
[32mPASS[0m xsave (17 tests)
[32mPASS[0m rmap_chain
[32mPASS[0m kvmclock_test
[32mPASS[0m pcid (3 tests)
[31mFAIL[0m vmx (timeout; duration=90s)
[32mPASS[0m vmx_null (1 tests)
[33mSKIP[0m vmx_test_vmx_feature_control (0 tests)
[32mPASS[0m vmx_test_vmxon (4 tests)
[32mPASS[0m vmx_test_vmptrld (5 tests)
[32mPASS[0m vmx_test_vmclear (6 tests)
[32mPASS[0m vmx_test_vmptrst (1 tests)
[32mPASS[0m vmx_test_vmwrite_vmread (1 tests)
[32mPASS[0m vmx_test_vmcs_lifecycle (9 tests)
[32mPASS[0m vmx_test_vmx_caps (11 tests)
[32mPASS[0m vmx_vmenter (2 tests)
[32mPASS[0m vmx_preemption_timer (5 tests)
[32mPASS[0m vmx_control_field_PAT (3 tests)
[32mPASS[0m vmx_control_field_EFER (3 tests)
[32mPASS[0m vmx_CR_shadowing (12 tests)
[32mPASS[0m vmx_IO_bitmap (15 tests)
[32mPASS[0m vmx_instruction_intercept (40 tests)
[31mFAIL[0m vmx_EPT_AD_enabled (timeout; duration=90s)
[31mFAIL[0m vmx_EPT_AD_disabled (14 tests, 5 unexpected failures)
[33mSKIP[0m vmx_PML
[32mPASS[0m vmx_VPID (3 tests)
[32mPASS[0m vmx_interrupt (7 tests)
[32mPASS[0m vmx_debug_controls (4 tests)
[32mPASS[0m vmx_MSR_switch (4 tests)
[32mPASS[0m vmx_vmmcall (1 tests)
[32mPASS[0m vmx_disable_RDTSCP (2 tests)
[32mPASS[0m vmx_int3 (1 tests)
[32mPASS[0m vmx_into (1 tests)
[32mPASS[0m vmx_exit_monitor_from_l2_test
[32mPASS[0m vmx_v2 (29 tests)
[32mPASS[0m vmx_ept_access_test_not_present (188 tests)
[31mFAIL[0m vmx_ept_access_test_read_only (164 tests, 6 unexpected failures)
[32mPASS[0m vmx_ept_access_test_write_only (152 tests)
[31mFAIL[0m vmx_ept_access_test_read_write (131 tests, 3 unexpected failures)
[31mFAIL[0m vmx_ept_access_test_execute_only (164 tests, 6 unexpected failures)
[32mPASS[0m vmx_ept_access_test_read_execute (128 tests)
[32mPASS[0m vmx_ept_access_test_write_execute (152 tests)
[32mPASS[0m vmx_ept_access_test_read_write_execute (98 tests)
[32mPASS[0m vmx_ept_access_test_reserved_bits (4078 tests)
[32mPASS[0m vmx_ept_access_test_ignored_bits (2888 tests)
[31mFAIL[0m vmx_ept_access_test_paddr_not_present_ad_disabled (65 tests, 3 unexpected failures)
[31mFAIL[0m vmx_ept_access_test_paddr_not_present_ad_enabled (timeout; duration=90s)
[31mFAIL[0m vmx_ept_access_test_paddr_read_only_ad_disabled (69 tests, 1 unexpected failures)
[31mFAIL[0m vmx_ept_access_test_paddr_read_only_ad_enabled (timeout; duration=90s)
[32mPASS[0m vmx_ept_access_test_paddr_read_write (45 tests)
[32mPASS[0m vmx_ept_access_test_paddr_read_write_execute (45 tests)
[31mFAIL[0m vmx_ept_access_test_paddr_read_execute_ad_disabled (69 tests, 1 unexpected failures)
[31mFAIL[0m vmx_ept_access_test_paddr_read_execute_ad_enabled (timeout; duration=90s)
[32mPASS[0m vmx_ept_access_test_paddr_not_present_page_fault (8 tests)
[32mPASS[0m vmx_ept_access_test_force_2m_page (48 tests)
[31mFAIL[0m vmx_invvpid (1561 tests, 194 unexpected failures)
[32mPASS[0m debug (8 tests)
[32mPASS[0m hyperv_synic (1 tests)
[33mSKIP[0m hyperv_connections (1 tests, 1 skipped)
[32mPASS[0m hyperv_stimer (8 tests)
[32mPASS[0m hyperv_clock
[32mPASS[0m intel_iommu (11 tests)
To reproduce:
git clone https://github.com/01org/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
Thanks,
Xiaolong
4 years, 8 months
[lkp-robot] [mm] f5e7e49aa0: [No primary change] perf-stat.iTLB-load-misses +49%
by kernel test robot
Greeting,
There is no primary kpi change in this test, below is the data collected through multiple monitors running background just for your information.
commit: f5e7e49aa0d65cab51403b384498f3bcdd2d184f ("mm: oom: let oom_reap_task and exit_mmap to run concurrently")
https://git.kernel.org/cgit/linux/kernel/git/andrea/aa.git master
in testcase: will-it-scale
on test machine: 256 threads Phi with 96G memory
with following parameters:
nr_task: 100%
mode: thread
test: futex3
cpufreq_governor: performance
test-description: Will It Scale takes a testcase and runs it from 1 through to n parallel copies to see if the testcase will scale. It builds both a process and threads based test in order to see any differences between the two.
test-url: https://github.com/antonblanchard/will-it-scale
Details are as below:
-------------------------------------------------------------------------------------------------->
To reproduce:
git clone https://github.com/01org/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
testcase/path_params/tbox_group/run: will-it-scale/100%-thread-futex3-performance/lkp-knl-f1
91941a96242cc91a f5e7e49aa0d65cab51403b3844
---------------- --------------------------
%stddev change %stddev
\ | \
69070 17% 80559 ± 15% will-it-scale.time.involuntary_context_switches
72806 74065 vmstat.system.in
15544 15079 vmstat.system.cs
1.24e+11 49% 1.851e+11 perf-stat.iTLB-load-misses
0.59 48% 0.87 perf-stat.iTLB-load-miss-rate%
9.54e+09 9.798e+09 perf-stat.cache-misses
3.831e+11 3.793e+11 perf-stat.branch-misses
4666896 4533137 perf-stat.context-switches
168 -33% 112 perf-stat.instructions-per-iTLB-miss
perf-stat.iTLB-load-misses
1.9e+11 ++--------------------O-------------------------------------------+
| O O O O O O O |
1.8e+11 ++ |
1.7e+11 ++ |
| O O |
1.6e+11 O+ O O O O O O O O O O O O O O |
| O O |
1.5e+11 ++ |
| |
1.4e+11 ++ *.*.*..*.*..*. |
1.3e+11 ++ .*. .. *.. .*.. |
*. .*.*.. .*. * * *. .*.. .*.*
1.2e+11 ++*. * *.*..*.*..* *.*. |
| |
1.1e+11 ++----------------------------------------------------------------+
perf-stat.iTLB-load-miss-rate_
0.9 ++---------------------O--O-O--O-------------------------------------+
| O O O O |
0.85 ++ |
| |
0.8 ++ |
O O O O O O O O O O O O O O O O O O O |
0.75 ++ |
| |
0.7 ++ |
| |
0.65 ++ .*..*..*.*..*.*.. |
| .*..* *.*..*.*.. |
0.6 *+.*.*..*.*..* .*.. .*..*.*..*.*..*
| *..* * |
0.55 ++-------------------------------------------------------------------+
perf-stat.instructions-per-iTLB-miss
180 ++--------------------------------------------------------------------+
| |
170 *+. .*.. *.*..*.*..*..*.*..*.*..|
| *.*..* *.. .. *
160 ++ *.*.. .*. .* |
| .*. *. |
150 ++ *.*..*..*.*..* |
| |
140 ++ |
| |
130 ++ O O O O O O O O O |
O O O O O O O O |
120 ++ O O |
| O O |
110 ++---------------------O--O----O----------------------------O----O-O--+
[*] 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,
Xiaolong
4 years, 8 months
[lkp-robot] [selftests] 622b2fbe62: [No primary change] kernel_selftests.time.elapsed_time +188%
by kernel test robot
Greeting,
There is no primary kpi change in this test, below is the data collected through multiple monitors running background just for your information.
commit: 622b2fbe625bc255faa4ee69a0fbcab80d3e40e6 ("selftests: timers: freq-step: fix compile error")
https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git master
in testcase: kernel_selftests
on test machine: qemu-system-x86_64 -enable-kvm -cpu host -smp 2 -m 4G
with following parameters:
test-description: The kernel contains a set of "self tests" under the tools/testing/selftests/ directory. These are intended to be small unit tests to exercise individual code paths in the kernel.
test-url: https://www.kernel.org/doc/Documentation/kselftest.txt
Details are as below:
-------------------------------------------------------------------------------------------------->
To reproduce:
git clone https://github.com/01org/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
testcase/path_params/tbox_group/run: kernel_selftests/defaults/vm-lkp-nex04-4G
7ba190be873a86b2 622b2fbe625bc255faa4ee69a0
---------------- --------------------------
%stddev change %stddev
\ | \
265 188% 764 kernel_selftests.time.elapsed_time
265 188% 764 kernel_selftests.time.elapsed_time.max
116 74% 203 kernel_selftests.time.user_time
200 25% 251 kernel_selftests.time.system_time
2199280 2253617 kernel_selftests.time.minor_page_faults
119 -51% 59 kernel_selftests.time.percent_of_cpu_this_job_got
[*] 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,
Xiaolong
4 years, 8 months
[lkp-robot] [sched, rcu] 10f61faf3e: [No primary change] will-it-scale.time.involuntary_context_switches +144%
by kernel test robot
Greeting,
There is no primary kpi change in this test, below is the data collected through multiple monitors running background just for your information.
commit: 10f61faf3ea58dca90f0e5cae53d30ec2f7d70c4 ("sched,rcu: Make cond_resched() provide RCU quiescent state")
https://git.kernel.org/cgit/linux/kernel/git/paulmck/linux-rcu.git rcu/dev
in testcase: will-it-scale
on test machine: 112 threads Skylake with 64G memory
with following parameters:
nr_task: 100%
mode: thread
test: open2
cpufreq_governor: performance
test-description: Will It Scale takes a testcase and runs it from 1 through to n parallel copies to see if the testcase will scale. It builds both a process and threads based test in order to see any differences between the two.
test-url: https://github.com/antonblanchard/will-it-scale
Details are as below:
-------------------------------------------------------------------------------------------------->
To reproduce:
git clone https://github.com/01org/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
testcase/path_params/tbox_group/run: will-it-scale/100%-thread-open2-performance/lkp-skl-2sp2
656e7c0c0a2e8d89 10f61faf3ea58dca90f0e5cae5
---------------- --------------------------
%stddev change %stddev
\ | \
176825 144% 431306 will-it-scale.time.involuntary_context_switches
41.98 ± 3% 19% 50.15 ± 4% will-it-scale.time.user_time
11144 10837 will-it-scale.time.percent_of_cpu_this_job_got
33539 32586 will-it-scale.time.system_time
16126 ± 95% -2e+04 0 latency_stats.avg.flush_work.lru_add_drain_all_cpuslocked.lru_add_drain_all.SyS_mlockall.entry_SYSCALL_64_fastpath
21906 ± 93% -2e+04 0 latency_stats.max.flush_work.lru_add_drain_all_cpuslocked.lru_add_drain_all.SyS_mlockall.entry_SYSCALL_64_fastpath
10556 ± 79% -9e+03 1410 ±172% latency_stats.sum.io_schedule.__lock_page_or_retry.filemap_fault.__do_fault.__handle_mm_fault.handle_mm_fault.__do_page_fault.do_page_fault.page_fault
25746 ± 11% -2e+04 5463 ± 33% latency_stats.sum.ep_poll.SyS_epoll_wait.do_syscall_64.return_from_SYSCALL_64
26561 ± 5% -2e+04 4658 latency_stats.sum.ep_poll.SyS_epoll_wait.entry_SYSCALL_64_fastpath
21909 ± 93% -2e+04 0 latency_stats.sum.flush_work.lru_add_drain_all_cpuslocked.lru_add_drain_all.SyS_mlockall.entry_SYSCALL_64_fastpath
25397 ± 7% 44% 36686 perf-stat.cpu-migrations
7326942 ± 7% 26% 9241064 ± 9% perf-stat.dTLB-store-misses
85.97 15% 99.23 perf-stat.node-store-miss-rate%
1.255e+09 ± 4% 12% 1.408e+09 perf-stat.branch-misses
0.16 ± 5% 8% 0.17 perf-stat.branch-miss-rate%
92.23 4% 95.99 perf-stat.node-load-miss-rate%
9.683e+11 4% 1.008e+12 perf-stat.dTLB-loads
7.868e+11 4% 8.149e+11 perf-stat.branch-instructions
3.587e+12 4% 3.715e+12 perf-stat.instructions
0.01 ± 5% -9% 0.00 ± 4% perf-stat.dTLB-load-miss-rate%
43.77 ± 3% -17% 36.40 ± 3% perf-stat.cache-miss-rate%
1.232e+10 ± 11% -22% 9.625e+09 ± 6% perf-stat.cache-misses
1.706e+08 ± 19% -50% 85081523 ± 41% perf-stat.node-loads
2.36e+08 ± 7% -95% 11715618 ± 19% perf-stat.node-stores
perf-stat.cpu-cycles
7.36e+13 ++---------------------------------------------------------------+
| |
7.34e+13 O+ O O O O O O O O O O O O O O O O O O O O O O O O O
| O |
7.32e+13 ++ |
| |
7.3e+13 ++ |
| |
7.28e+13 ++ |
| *..*. .*.. *.. *.. *..*.*.. * |
7.26e+13 *+. + *..* .*.. *.. + + + + |
| * * + * * * * |
7.24e+13 ++ * |
| |
7.22e+13 ++---------------------------------------------------------------+
perf-stat.node-stores
3e+08 ++----------------------------------------------------------------+
| |
2.5e+08 ++ .*..* .*.. *.*.. |
| .* + .*.*..* *.*.. .*..*..*.*..*. .. * |
*. *. * * |
2e+08 ++ |
| |
1.5e+08 ++ |
| |
1e+08 ++ |
| |
| |
5e+07 ++ |
O O O O O O O O O O O O O O O O
0 ++---O--O---------O-O----O--O----O--O----O----O----------------O--+
perf-stat.node-store-miss-rate_
100 ++-----------------O--O----O-------O--O----------------------------O--+
O O O O O O O O O O O O O O O O O O O O O
98 ++ |
96 ++ |
| |
94 ++ |
| |
92 ++ |
| |
90 ++ |
88 ++ |
| *. |
86 *+.*. .. *..*..*..*.*..*..*.*.. .*. .*..*.*.. .* |
| *..* *. *. *. |
84 ++--------------------------------------------------------------------+
will-it-scale.time.system_time
33800 ++------------------------------------------------------------------+
| .*.. |
33600 *+.*. .*.*.. .*.*. .*.. *..*..*.*..* *..*. |
| *. *. * + + .. * |
33400 ++ * * |
| |
33200 ++ |
| |
33000 ++ |
| |
32800 ++ O |
| O O O O O O O O |
32600 ++ O O O O O O O O O O O
O O O O O O O |
32400 ++------------------------------------------------------------------+
will-it-scale.time.percent_of_cpu_this_job_got
11200 ++------------------------------------------------------------------+
*..*. .*.*.. .*.*..*.. .*.. *..*..*.*..* *..*. |
11150 ++ *. *. * + + .. * |
11100 ++ * * |
| |
11050 ++ |
11000 ++ |
| |
10950 ++ |
10900 ++ O |
| O O O O O |
10850 ++ O O O O O O O O O O
10800 O+ O O O O O O O O O |
| O |
10750 ++------------------------------------------------------------------+
will-it-scale.time.involuntary_context_switches
450000 ++-----------------------------------------------------------------+
O O O O O O O O O O O O O O O O O O O O O O O O O
400000 ++ O O |
| |
| |
350000 ++ |
| |
300000 ++ |
| |
250000 ++ |
| |
| |
200000 ++ .*..*.. |
*..*.*..*.*..*.*..*..*.*..* *.*..*.*..*.*..*..* |
150000 ++-----------------------------------------------------------------+
[*] 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,
Xiaolong
4 years, 8 months
[lkp-robot] [Input] 2ebdfecef5: BUG:KASAN:global-out-of-bounds
by kernel test robot
FYI, we noticed the following commit:
commit: 2ebdfecef50840a9c6fd6a2236a1bdf2942dc1a7 ("Input: axp20x-pek - use driver_data of platform_device_id instead of extended attributes")
https://git.kernel.org/cgit/linux/kernel/git/dtor/input.git next
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -smp 2 -m 512M
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+------------------------------------------------------------------+------------+------------+
| | 64954e3442 | 2ebdfecef5 |
+------------------------------------------------------------------+------------+------------+
| boot_successes | 16 | 0 |
| boot_failures | 0 | 14 |
| BUG:KASAN:global-out-of-bounds | 0 | 12 |
| invoked_oom-killer:gfp_mask=0x | 0 | 2 |
| Mem-Info | 0 | 2 |
| Kernel_panic-not_syncing:Out_of_memory_and_no_killable_processes | 0 | 2 |
+------------------------------------------------------------------+------------+------------+
[ 3.472769] BUG: KASAN: global-out-of-bounds in platform_match+0x26b/0x2a3
[ 3.475058] Read of size 1 at addr ffffffff84d9dd00 by task swapper/1
[ 3.477290]
[ 3.479003] CPU: 0 PID: 1 Comm: swapper Not tainted 4.12.0-00069-g2ebdfec #1
[ 3.481349] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 3.484077] Call Trace:
[ 3.485913] dump_stack+0x19/0x1b
[ 3.487820] print_address_description+0x1d1/0x2aa
[ 3.489872] ? platform_match+0x26b/0x2a3
[ 3.491858] kasan_report+0x212/0x32a
[ 3.493729] __asan_report_load1_noabort+0x14/0x16
[ 3.495745] platform_match+0x26b/0x2a3
[ 3.497671] ? driver_probe_device+0xc7f/0xc7f
[ 3.499682] __driver_attach+0x67/0x1e0
[ 3.501626] bus_for_each_dev+0x114/0x1ae
[ 3.503596] ? bus_remove_file+0xc3/0xc3
[ 3.505535] ? kasan_unpoison_shadow+0x35/0x43
[ 3.507521] driver_attach+0x38/0x49
[ 3.509423] bus_add_driver+0x3dc/0x72b
[ 3.511369] ? regulator_haptic_driver_init+0x14/0x14
[ 3.513483] driver_register+0x187/0x3b5
[ 3.515471] ? regulator_haptic_driver_init+0x14/0x14
[ 3.517597] __platform_driver_register+0xcc/0x119
[ 3.519711] axp20x_pek_driver_init+0x12/0x14
[ 3.521789] do_one_initcall+0x110/0x1df
[ 3.523791] ? start_kernel+0x538/0x538
[ 3.525754] kernel_init_freeable+0x265/0x2f5
[ 3.527796] ? rest_init+0xa2/0xa2
[ 3.529716] kernel_init+0xe/0x115
[ 3.531604] ? rest_init+0xa2/0xa2
[ 3.533465] ret_from_fork+0x25/0x30
[ 3.535305]
[ 3.536862] The buggy address belongs to the variable:
[ 3.538824] 0xffffffff84d9dd00
[ 3.540565]
[ 3.542120] Memory state around the buggy address:
[ 3.544082] ffffffff84d9dc00: 01 fa fa fa fa fa fa fa 00 00 00 00 07 fa fa fa
[ 3.546323] ffffffff84d9dc80: fa fa fa fa 00 fa fa fa fa fa fa fa 00 00 00 00
[ 3.548536] >ffffffff84d9dd00: fa fa fa fa 00 00 00 00 00 00 00 00 00 00 00 00
[ 3.550722] ^
[ 3.552445] ffffffff84d9dd80: 00 00 00 00 00 00 00 00 00 00 00 fa fa fa fa fa
[ 3.554648] ffffffff84d9de00: 00 00 00 00 00 fa fa fa fa fa fa fa 00 01 fa fa
[ 3.556827] ==================================================================
[ 3.559008] Disabling lock debugging due to kernel taint
[ 3.561224] usbcore: registered new interface driver yealink
[ 3.563226] ideapad_slidebar: DMI does not match
[ 3.565389] rtc_cmos 00:00: RTC can wake from S4
[ 3.567433] rtc rtc0: alarm rollover: day
[ 3.569376] rtc rtc0: rtc_cmos: dev (251:0)
[ 3.571146] rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0
[ 3.573325] rtc_cmos 00:00: alarms up to one day, y3k, 114 bytes nvram, hpet irqs
[ 3.576273] rtc rtc1: alarm rollover: day
[ 3.578100] rtc rtc1: test: dev (251:1)
[ 3.579747] rtc-test rtc-test.0: rtc core: registered test as rtc1
[ 3.581804] rtc rtc2: alarm rollover: day
[ 3.583634] rtc rtc2: test: dev (251:2)
[ 3.585288] rtc-test rtc-test.1: rtc core: registered test as rtc2
[ 3.587391] i2c /dev entries driver
[ 3.589634] i2c-parport: adapter type unspecified
[ 3.591422] i2c-parport-light: adapter type unspecified
[ 3.593291] usbcore: registered new interface driver RobotFuzz Open Source InterFace, OSIF
[ 3.595572] usbcore: registered new interface driver i2c-tiny-usb
[ 3.597814] saa7146: register extension 'Multimedia eXtension Board'
[ 3.599871] saa7146: register extension 'hexium HV-PCI6 Orion'
[ 3.601859] saa7146: register extension 'hexium gemini'
[ 3.603770] cx25821: driver version 0.0.106 loaded
[ 3.605827] pps pps0: new PPS source ktimer
[ 3.607615] pps pps0: ktimer PPS source registered
[ 3.609427] pps_ldisc: PPS line discipline registered
[ 3.611274] Driver for 1-wire Dallas network protocol.
[ 3.613412] __power_supply_register: Expected proper parent device for 'test_ac'
[ 3.615641] __power_supply_register: Expected proper parent device for 'test_battery'
[ 3.618031] (NULL device *): hwmon_device_register() is deprecated. Please convert the driver to use hwmon_device_register_with_info().
[ 3.622214] __power_supply_register: Expected proper parent device for 'test_usb'
[ 3.625566] applesmc: supported laptop not found!
[ 3.627573] applesmc: driver init failed (ret=-19)!
[ 3.631045] pc87360: PC8736x not detected, module not inserted
[ 3.633253] sch56xx_common: Unsupported device id: 0xff
[ 3.635445] sch56xx_common: Unsupported device id: 0xff
[ 3.638341] ib700wdt: WDT driver for IB700 single board computer initialising
[ 3.640883] wafer5823wdt: WDT driver for Wafer 5823 single board computer initialising
[ 3.643838] wafer5823wdt: I/O address 0x0443 already in use
[ 3.646052] it87_wdt: no device
[ 3.647972] sc1200wdt: build 20020303
[ 3.649909] sc1200wdt: io parameter must be specified
[ 3.652029] sbc60xxwdt: I/O address 0x0443 already in use
[ 3.654212] machzwd: MachZ ZF-Logic Watchdog driver initializing
[ 3.656409] machzwd: no ZF-Logic found
[ 3.658349] sbc_epx_c3: cannot register miscdev on minor=130 (err=-16)
[ 3.661002] CHRDEV "ttySDIO" major number 223 goes below the dynamic allocation range
[ 3.663457] sdhci: Secure Digital Host Controller Interface driver
[ 3.665684] sdhci: Copyright(c) Pierre Ossman
[ 3.667696] wbsd: Winbond W83L51xD SD/MMC card interface driver
[ 3.669890] wbsd: Copyright(c) Pierre Ossman
[ 3.672011] VUB300 Driver rom wait states = 1C irqpoll timeout = 0400
[ 3.672201] usbcore: registered new interface driver vub300
[ 3.676766] usbcore: registered new interface driver ushc
[ 3.678946] sdhci-pltfm: SDHCI platform and OF driver helper
[ 3.682070] ledtrig-cpu: registered to indicate activity on CPUs
[ 3.684771] dcdbas dcdbas: Dell Systems Management Base Driver (version 5.6.0-3.2)
[ 3.688095] CHRDEV "hidraw" major number 222 goes below the dynamic allocation range
To reproduce:
git clone https://github.com/01org/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
Xiaolong
4 years, 8 months
Re: [LKP] 71acb768f5 ("timers: Fix excessive granularity of new timers .."): watchdog: BUG: soft lockup - CPU#0 stuck for 23s! [ksoftirqd/0:7]
by Paul E. McKenney
On Sun, Aug 20, 2017 at 08:39:43PM +1000, Nicholas Piggin wrote:
> On Sun, 20 Aug 2017 16:55:11 +0800
> kernel test robot <fengguang.wu(a)intel.com> wrote:
>
> > Another approach I looked at first was to note
> > if the base was idle but not yet run or forwarded, however this just
> > seemed to add more branches and complexity when it seems we can just
> > cover it with this test.
>
> [...]
>
> > [ 28.023029] RIP: 0010:can_stat_update+0xe/0x23e
> > [ 28.023030] RSP: 0018:ffff880000103d48 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff10
> > [ 28.023032] RAX: 0000000000000000 RBX: ffffffff820a60f0 RCX: 0000000000000002
> > [ 28.023034] RDX: 0000000000000000 RSI: ffffffff81742fc6 RDI: ffffffff820a4f00
> > [ 28.023035] RBP: ffff880000103d50 R08: ffffffff82048368 R09: ffffffff82049560
> > [ 28.023036] R10: ffff880000103de8 R11: ffffffff820a60f0 R12: 0000000000000100
> > [ 28.023037] R13: ffffffff81742fc6 R14: ffffffff820a60f0 R15: ffffffff820a4f00
> > [ 28.023039] FS: 0000000000000000(0000) GS:ffffffff8202f000(0000) knlGS:0000000000000000
> > [ 28.023041] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
> > [ 28.023042] CR2: 00007f3aeeb41000 CR3: 000000001da89000 CR4: 00000000000406b0
> > [ 28.023046] Call Trace:
> > [ 28.023051] ? calc_rate+0x6a/0x6a
> > [ 28.023056] call_timer_fn+0x8c/0x1e3
> > [ 28.023059] expire_timers+0x12c/0x150
>
> Ah well that answers that question :P Must not try to forward from
> within a timer function it while expire_timers is currently running!
>
> I'm just re-testing my original approach and will send it out. Sorry
> guys I'm a timer noob, you might want to wait until that one gets some
> reviews...:)
Hey, at least you were willing to dig into it and find a problem!
Thanx, Paul
4 years, 9 months
71acb768f5 ("timers: Fix excessive granularity of new timers .."): watchdog: BUG: soft lockup - CPU#0 stuck for 23s! [ksoftirqd/0:7]
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/paulmck/linux-rcu.git rcu/next
commit 71acb768f5b39e223cd98090885d445f03293326
Author: Nicholas Piggin <npiggin(a)gmail.com>
AuthorDate: Sun Aug 20 14:45:53 2017 +1000
Commit: Paul E. McKenney <paulmck(a)linux.vnet.ibm.com>
CommitDate: Sat Aug 19 21:57:59 2017 -0700
timers: Fix excessive granularity of new timers after a nohz idle
When a timer base is idle, it is forwarded when a new timer is added to
ensure that granularity does not become excessive. When not idle, the
timer tick is expected to increment the base.
However there is a window after a timer is restarted from nohz, when it
is marked not-idle, and before the timer tick on this CPU, where a timer
may be added on an ancient base that does not get forwarded (beacause
the timer appears not-idle).
This results in excessive granularity. So much so that a 1 jiffy timeout
has blown out to 10s of seconds and triggered the RCU stall warning
detector.
Fix this by always forwarding the base when adding a new timer if it is
more than 1 jiffy behind. Another approach I looked at first was to note
if the base was idle but not yet run or forwarded, however this just
seemed to add more branches and complexity when it seems we can just
cover it with this test.
Also add a comment noting a case where we could get an unexpectedly
large granularity for a timer. I debugged this problem by adding
warnings for such cases, but it seems we can't add them in general due
to this corner case.
Signed-off-by: Nicholas Piggin <npiggin(a)gmail.com>
Signed-off-by: Paul E. McKenney <paulmck(a)linux.vnet.ibm.com>
3ed668659e membarrier: Document scheduler barrier requirements
71acb768f5 timers: Fix excessive granularity of new timers after a nohz idle
f42cc1fc1c rcutorture: Add interrupt-disable capability to stall-warning tests
+------------------------------------------------+------------+------------+------------+
| | 3ed668659e | 71acb768f5 | f42cc1fc1c |
+------------------------------------------------+------------+------------+------------+
| boot_successes | 55 | 13 | 14 |
| boot_failures | 0 | 6 | 7 |
| BUG:soft_lockup-CPU##stuck_for#s | 0 | 6 | 7 |
| Kernel_panic-not_syncing:softlockup:hung_tasks | 0 | 6 | 7 |
+------------------------------------------------+------------+------------+------------+
Starting udev
/etc/rcS.d/S03udev: line 72: can't create /proc/sys/kernel/hotplug: nonexistent directory
[ 1.313530] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3
[ 1.504091] tsc: Refined TSC clocksource calibration: 2693.504 MHz
[ 1.505249] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x26d345dbcd4, max_idle_ns: 440795302414 ns
[ 28.023014] watchdog: BUG: soft lockup - CPU#0 stuck for 23s! [ksoftirqd/0:7]
[ 28.023015] Modules linked in:
[ 28.023020] CPU: 0 PID: 7 Comm: ksoftirqd/0 Not tainted 4.13.0-rc2-00066-g71acb768f5b39 #1
[ 28.023021] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 28.023024] task: ffff88000005a000 task.stack: ffff880000100000
[ 28.023029] RIP: 0010:can_stat_update+0xe/0x23e
[ 28.023030] RSP: 0018:ffff880000103d48 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff10
[ 28.023032] RAX: 0000000000000000 RBX: ffffffff820a60f0 RCX: 0000000000000002
[ 28.023034] RDX: 0000000000000000 RSI: ffffffff81742fc6 RDI: ffffffff820a4f00
[ 28.023035] RBP: ffff880000103d50 R08: ffffffff82048368 R09: ffffffff82049560
[ 28.023036] R10: ffff880000103de8 R11: ffffffff820a60f0 R12: 0000000000000100
[ 28.023037] R13: ffffffff81742fc6 R14: ffffffff820a60f0 R15: ffffffff820a4f00
[ 28.023039] FS: 0000000000000000(0000) GS:ffffffff8202f000(0000) knlGS:0000000000000000
[ 28.023041] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 28.023042] CR2: 00007f3aeeb41000 CR3: 000000001da89000 CR4: 00000000000406b0
[ 28.023046] Call Trace:
[ 28.023051] ? calc_rate+0x6a/0x6a
[ 28.023056] call_timer_fn+0x8c/0x1e3
[ 28.023059] expire_timers+0x12c/0x150
[ 28.023061] ? calc_rate+0x6a/0x6a
[ 28.023063] run_timer_softirq+0x8b/0x169
[ 28.023068] ? run_ksoftirqd+0x32/0xa9
[ 28.023071] __do_softirq+0x16c/0x3af
[ 28.023074] run_ksoftirqd+0x32/0xa9
[ 28.023077] smpboot_thread_fn+0x24c/0x266
[ 28.023079] ? sort_range+0x1d/0x1d
[ 28.023083] kthread+0x164/0x16c
[ 28.023085] ? kthread_flush_worker+0x81/0x81
[ 28.023091] ret_from_fork+0x2a/0x40
[ 28.023093] Code: 47 bf 97 ff b8 ff e0 f5 05 5d eb 10 48 69 c2 e8 03 00 00 48 29 fe 31 d2 48 f7 f6 c3 c3 8b 15 88 a6 85 01 31 c0 55 48 89 e5 41 55 <4c> 8b 2d 25 80 8c 00 85 d2 41 54 49 89 fc 0f 95 c0 53 48 8b 9f
[ 28.023119] Kernel panic - not syncing: softlockup: hung tasks
[ 28.023121] CPU: 0 PID: 7 Comm: ksoftirqd/0 Tainted: G L 4.13.0-rc2-00066-g71acb768f5b39 #1
[ 28.023122] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 28.023123] Call Trace:
[ 28.023125] <IRQ>
[ 28.023129] dump_stack+0x19/0x1b
[ 28.023131] panic+0x111/0x2d7
[ 28.023135] watchdog_timer_fn+0x265/0x283
[ 28.023138] ? __touch_watchdog+0x16/0x16
[ 28.023140] hrtimer_run_queues+0x1d4/0x2ef
[ 28.023142] run_local_timers+0x9/0x7b
[ 28.023144] update_process_times+0x1b/0x4f
[ 28.023148] tick_nohz_handler+0xf9/0x163
[ 28.023150] ? calc_rate+0x6a/0x6a
[ 28.023153] local_apic_timer_interrupt+0x4f/0x51
[ 28.023156] smp_apic_timer_interrupt+0x31/0x40
[ 28.023158] apic_timer_interrupt+0x98/0xa0
[ 28.023161] RIP: 0010:can_stat_update+0xe/0x23e
[ 28.023162] RSP: 0018:ffff880000103d48 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff10
[ 28.023164] RAX: 0000000000000000 RBX: ffffffff820a60f0 RCX: 0000000000000002
[ 28.023165] RDX: 0000000000000000 RSI: ffffffff81742fc6 RDI: ffffffff820a4f00
[ 28.023166] RBP: ffff880000103d50 R08: ffffffff82048368 R09: ffffffff82049560
[ 28.023168] R10: ffff880000103de8 R11: ffffffff820a60f0 R12: 0000000000000100
[ 28.023169] R13: ffffffff81742fc6 R14: ffffffff820a60f0 R15: ffffffff820a4f00
[ 28.023170] </IRQ>
[ 28.023172] ? calc_rate+0x6a/0x6a
[ 28.023174] ? calc_rate+0x6a/0x6a
[ 28.023177] ? calc_rate+0x6a/0x6a
[ 28.023179] call_timer_fn+0x8c/0x1e3
[ 28.023181] expire_timers+0x12c/0x150
[ 28.023183] ? calc_rate+0x6a/0x6a
[ 28.023185] run_timer_softirq+0x8b/0x169
[ 28.023187] ? run_ksoftirqd+0x32/0xa9
[ 28.023189] __do_softirq+0x16c/0x3af
[ 28.023192] run_ksoftirqd+0x32/0xa9
[ 28.023194] smpboot_thread_fn+0x24c/0x266
[ 28.023196] ? sort_range+0x1d/0x1d
[ 28.023198] kthread+0x164/0x16c
[ 28.023201] ? kthread_flush_worker+0x81/0x81
[ 28.023203] ret_from_fork+0x2a/0x40
[ 28.023207] Kernel Offset: disabled
# HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start 23aff764893ffe91ac129d94667f4dd1c1b10d3f ef954844c7ace62f773f4f23e28d2d915adc419f --
git bisect bad 41d0506a5181eaf1034eb5e4d78aa912ebbef342 # 14:21 B 0 5 33 6 Merge 'net-next/master' into devel-catchup-201708201343
git bisect good c782b9e13b9cd8d8e2952291b5eb696a3dd2662a # 14:37 G 17 0 0 0 Merge 'linux-review/Waiman-Long/kernfs-Clarify-lockdep-name-for-kn-count/20170820-130848' into devel-catchup-201708201343
git bisect bad f059b8c15413e10553a5b0b67e22d59fd99c1976 # 14:46 B 0 9 22 0 Merge 'rcu/rcu/next' into devel-catchup-201708201343
git bisect good 16c0b106070f4760f7d1fffb0cd40393552b5294 # 14:56 G 17 0 0 0 rcu: Remove exports from rcu_idle_exit() and rcu_idle_enter()
git bisect good f34c8585ed70f0f9b5ff9cf59c0dd533cddb975f # 15:07 G 17 0 0 0 rcutorture: Invoke call_rcu() from timer handler
git bisect good 656e7c0c0a2e8d899f87fd7f081ea7a711146604 # 15:22 G 17 0 0 0 Merge branches 'doc.2017.08.17a', 'fixes.2017.08.17a', 'hotplug.2017.07.25b', 'misc.2017.08.17a', 'spin_unlock_wait_no.2017.08.17a', 'srcu.2017.07.27c' and 'torture.2017.07.24c' into HEAD
git bisect bad 100a9352b1fead8435c2fdc6352d661b64a58b4f # 15:32 B 0 10 23 0 documentation: RCU grace-period memory ordering guarantees
git bisect bad 24cb34942ec8a299e5a73cbe17f0a370fed731ea # 15:45 B 6 6 0 0 sched,rcu: Make cond_resched() provide RCU quiescent state
git bisect bad 71acb768f5b39e223cd98090885d445f03293326 # 15:59 B 0 6 20 0 timers: Fix excessive granularity of new timers after a nohz idle
git bisect good 3ed668659e95ecfb6f6be0a3e7ff0fa6d27b2f5c # 16:11 G 16 0 0 0 membarrier: Document scheduler barrier requirements
# first bad commit: [71acb768f5b39e223cd98090885d445f03293326] timers: Fix excessive granularity of new timers after a nohz idle
git bisect good 3ed668659e95ecfb6f6be0a3e7ff0fa6d27b2f5c # 16:14 G 51 0 0 0 membarrier: Document scheduler barrier requirements
# extra tests with CONFIG_DEBUG_INFO_REDUCED
git bisect bad 71acb768f5b39e223cd98090885d445f03293326 # 16:22 B 0 5 19 0 timers: Fix excessive granularity of new timers after a nohz idle
# extra tests on HEAD of linux-devel/devel-catchup-201708201343
git bisect bad 23aff764893ffe91ac129d94667f4dd1c1b10d3f # 16:22 B 5 8 0 3 0day head guard for 'devel-catchup-201708201343'
# extra tests on tree/branch rcu/rcu/next
git bisect bad f42cc1fc1c7ead03f96f604ec9a0383d817d9700 # 16:36 B 0 7 21 0 rcutorture: Add interrupt-disable capability to stall-warning tests
# extra tests with first bad commit reverted
git bisect good efa479c25fc7ba8763d9258e0d01939e2f42e232 # 16:55 G 17 0 0 0 Revert "timers: Fix excessive granularity of new timers after a nohz idle"
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
4 years, 9 months
d0541b0fa6 ("locking/lockdep: Make CONFIG_LOCKDEP_CROSSRELEASE .."): test_cycle_work+0x
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/tip/tip.git locking/core
commit d0541b0fa64b36665d6261079974a26943c75009
Author: Byungchul Park <byungchul.park(a)lge.com>
AuthorDate: Thu Aug 17 17:57:39 2017 +0900
Commit: Ingo Molnar <mingo(a)kernel.org>
CommitDate: Thu Aug 17 11:38:54 2017 +0200
locking/lockdep: Make CONFIG_LOCKDEP_CROSSRELEASE part of CONFIG_PROVE_LOCKING
Crossrelease support added the CONFIG_LOCKDEP_CROSSRELEASE and CONFIG_LOCKDEP_COMPLETE
options. It makes little sense to enable them when PROVE_LOCKING is disabled.
Make them non-interative options and part of PROVE_LOCKING to simplify the UI.
Signed-off-by: Byungchul Park <byungchul.park(a)lge.com>
Cc: Linus Torvalds <torvalds(a)linux-foundation.org>
Cc: Peter Zijlstra <peterz(a)infradead.org>
Cc: Thomas Gleixner <tglx(a)linutronix.de>
Cc: kernel-team(a)lge.com
Link: http://lkml.kernel.org/r/1502960261-16206-1-git-send-email-byungchul.park...
Signed-off-by: Ingo Molnar <mingo(a)kernel.org>
7a46ec0e2f locking/refcounts, x86/asm: Implement fast refcount overflow protection
d0541b0fa6 locking/lockdep: Make CONFIG_LOCKDEP_CROSSRELEASE part of CONFIG_PROVE_LOCKING
e26f34a407 locking/lockdep: Make CONFIG_LOCKDEP_CROSSRELEASE and CONFIG_LOCKDEP_COMPLETIONS truly non-interactive
1e96409c81 Merge branch 'locking/core'
+----------------+------------+------------+------------+------------+
| | 7a46ec0e2f | d0541b0fa6 | e26f34a407 | 1e96409c81 |
+----------------+------------+------------+------------+------------+
| boot_successes | 87 | 17 | 25 | 1 |
+----------------+------------+------------+------------+------------+
[ 2.128883]
[ 2.129205] ======================================================
[ 2.130327] WARNING: possible circular locking dependency detected
[ 2.131459] 4.13.0-rc4-00262-gd0541b0 #1 Not tainted
[ 2.132365] ------------------------------------------------------
[ 2.132828] kworker/u4:1/69 is trying to acquire lock:
[ 2.132828] (ww_class_mutex){+.+.}, at: [<ffffffff8116d0b7>] test_cycle_work+0xc7/0x2e0
[ 2.132828]
[ 2.132828] but now in release context of a crosslock acquired at the following:
[ 2.132828]
[ 2.132828] but now in release context of a crosslock acquired at the following:
[ 2.132828] ((complete)&cycle->b_signal){+.+.}, at: [<ffffffff8116d0cf>] test_cycle_work+0xdf/0x2e0
[ 2.132828]
[ 2.132828] which lock already depends on the new lock.
[ 2.132828]
[ 2.132828] which lock already depends on the new lock.
[ 2.132828]
[ 2.132828] the existing dependency chain (in reverse order) is:
[ 2.132828]
[ 2.132828] the existing dependency chain (in reverse order) is:
[ 2.132828]
# HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start c4c4bf934653e3efa3e33b594d963dbd1fbe169d ef954844c7ace62f773f4f23e28d2d915adc419f --
git bisect bad c4f4441fc722883389178012e962b2f018fa2b04 # 03:01 B 0 4 16 0 Merge 'ragnatech/for-renesas-drivers' into devel-hourly-2017081721
git bisect bad 1f109afa68cc1f4c93fc31c3f6537575db6fb578 # 03:29 B 2 9 0 0 Merge 'linux-review/Shawn-Guo/usb-dwc3-of-simple-remove-include-of-clk-provider-h/20170814-101901' into devel-hourly-2017081721
git bisect good 461588ebbc12b9ec018eedae7e7d6ef888b0aa25 # 03:56 G 12 0 0 0 Merge 'asoc/topic/spear' into devel-hourly-2017081721
git bisect good e6be551794640d75b3f5f3121de2ed50d0d521e6 # 04:23 G 12 0 0 0 Merge 'arm-soc/qcom/dt' into devel-hourly-2017081721
git bisect bad fc967d2ced73338b30fac4c9294fd9e003d55d0b # 04:42 B 0 1 14 0 Merge 'linux-review/Arvind-Yadav/thermal-max77620-constify-platform_device_id/20170816-003034' into devel-hourly-2017081721
git bisect bad 01043d21a825ecb715ac855fafa7a403cb1b3b59 # 05:22 B 0 2 15 0 Merge 'hid/for-4.14/battery' into devel-hourly-2017081721
git bisect good ddf3d87ceb3d32f777a8ec8576d2c1745cae69cb # 05:46 G 12 0 0 0 Merge 'linux-review/Juergen-Gross/net-i40evf-use-cpumask_copy-for-assigning-cpumask/20170815-021359' into devel-hourly-2017081721
git bisect good fe06b85236586409c24ae9e79ed26f695a463c9d # 06:22 G 12 0 0 0 Merge 'shawnguo/imx/dt64' into devel-hourly-2017081721
git bisect bad 6b567defcb20605ee5a4d6c997f70668fc45d80a # 06:48 B 3 6 0 0 Merge 'tip/master' into devel-hourly-2017081721
git bisect bad 069ca40b07b3f5c8de9455f93fe67f6be99d0e16 # 07:01 B 2 5 0 0 Merge branch 'ras/core'
git bisect good a641860550f05a4b8889dca61aab73c84b2d5e16 # 07:15 G 13 0 0 0 perf script python: Generate hooks with additional argument
git bisect good 1209b273a25ee60a267b606bea77e068b8556a8d # 07:34 G 13 0 0 0 perf test: Add infrastructure to run shell based tests
git bisect good 9881223c6cc0644cc3aeea41e1f19ea7e3041f33 # 07:46 G 13 0 0 0 Merge tag 'perf-core-for-mingo-4.14-20170816' of git://git.kernel.org/pub/scm/linux/kernel/git/acme/linux into perf/core
git bisect good 9c9947f893a254ec92cd5fe1439587e583302c9c # 07:59 G 13 0 0 0 Merge tag 'irqchip-4.13-3' of git://git.kernel.org/pub/scm/linux/kernel/git/maz/arm-platforms into irq/urgent
git bisect bad 52fa5bc5cbba089f09bc2c372e3432f3f3e48051 # 08:14 B 7 4 0 0 locking/lockdep: Explicitly initialize wq_barrier::done::map
git bisect good 7a46ec0e2f4850407de5e1d19a44edee6efa58ec # 08:35 G 19 0 0 0 locking/refcounts, x86/asm: Implement fast refcount overflow protection
git bisect bad 0f0a22260d613b4ee3f483ee1ea6fa27f92a9e40 # 08:44 B 8 5 0 0 locking/lockdep: Reword title of LOCKDEP_CROSSRELEASE config
git bisect bad d0541b0fa64b36665d6261079974a26943c75009 # 09:00 B 8 8 0 0 locking/lockdep: Make CONFIG_LOCKDEP_CROSSRELEASE part of CONFIG_PROVE_LOCKING
# first bad commit: [d0541b0fa64b36665d6261079974a26943c75009] locking/lockdep: Make CONFIG_LOCKDEP_CROSSRELEASE part of CONFIG_PROVE_LOCKING
git bisect good 7a46ec0e2f4850407de5e1d19a44edee6efa58ec # 09:07 G 64 0 0 0 locking/refcounts, x86/asm: Implement fast refcount overflow protection
# extra tests with CONFIG_DEBUG_INFO_REDUCED
git bisect bad d0541b0fa64b36665d6261079974a26943c75009 # 09:18 B 1 1 0 0 locking/lockdep: Make CONFIG_LOCKDEP_CROSSRELEASE part of CONFIG_PROVE_LOCKING
# extra tests on HEAD of linux-devel/devel-hourly-2017081721
git bisect bad c4c4bf934653e3efa3e33b594d963dbd1fbe169d # 09:25 B 0 17 40 8 0day head guard for 'devel-hourly-2017081721'
# extra tests on tree/branch tip/locking/core
git bisect bad e26f34a407aec9c65bce2bc0c838fabe4f051fc6 # 09:51 B 4 12 0 0 locking/lockdep: Make CONFIG_LOCKDEP_CROSSRELEASE and CONFIG_LOCKDEP_COMPLETIONS truly non-interactive
# extra tests on tree/branch tip/master
git bisect bad 1e96409c810399511c469640bc5a054764f1c8aa # 10:04 B 1 7 0 0 Merge branch 'locking/core'
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
4 years, 9 months
[lkp-robot] [block] 434f2ea20d: fileio.requests_per_sec -3.1% regression
by kernel test robot
Greeting,
FYI, we noticed a -3.1% regression of fileio.requests_per_sec due to commit:
commit: 434f2ea20d5b4da12d9de87cb2838f320173f6a1 ("block: enable multipage bvecs")
url: https://github.com/0day-ci/linux/commits/Ming-Lei/block-support-multipage...
in testcase: fileio
on test machine: 4 threads Intel(R) Core(TM) i5-2300 CPU @ 2.80GHz with 4G memory
with following parameters:
period: 600s
nr_threads: 100%
disk: 1HDD
fs: f2fs
size: 64G
filenum: 1024f
rwmode: seqwr
iomode: sync
cpufreq_governor: performance
test-description: fileio is a subtest of SysBench benchmark suite to measure file IO performance.
test-url: https://github.com/akopytov/sysbench
Details are as below:
-------------------------------------------------------------------------------------------------->
To reproduce:
git clone https://github.com/01org/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
testcase/path_params/tbox_group/run: fileio/600s-100%-1HDD-f2fs-64G-1024f-seqwr-sync-performance/lkp-sb02
3d5b6047555ee331 434f2ea20d5b4da12d9de87cb2
---------------- --------------------------
%stddev change %stddev
\ | \
7890 -3% 7642 fileio.requests_per_sec
531 3% 548 fileio.time.elapsed_time
531 3% 548 fileio.time.elapsed_time.max
28163 4% 29269 interrupts.CAL:Function_call_interrupts
564236 3% 581392 perf-stat.page-faults
564236 3% 581391 perf-stat.minor-faults
126044 -3% 122052 vmstat.io.bo
29771 -3% 28842 vmstat.system.cs
418 8% 450 iostat.sda.await
418 8% 450 iostat.sda.w_await
125990 -3% 122056 iostat.sda.wkB/s
5.28 ± 5% -43% 3.03 iostat.sda.wrqm/s
fileio.requests_per_sec
8000 *+*-*-*--*-*-*-*-*-*--*-*-*-*-*-*--*-*-*-*-*-*-*--*-*-*-*---*--*-*-*-*
O O O O O O O O O O O O O O O O O O O O O O O O O O O O O O O |
7000 ++ : : |
6000 ++ : : |
| : : |
5000 ++ : : |
| : : |
4000 ++ : : |
| : : |
3000 ++ : : |
2000 ++ : : |
| : |
1000 ++ : |
| : |
0 ++--------------------------------------------------------*----------+
perf-stat.page-faults
600000 O+O-O-O-O----O-O-O-O-O-----O-O-O--O-------O-O-----O-O------O-O-O---+
*.*.*.*.*.O..*.*.*.*.*.O.O.*.*.*..*.O.O.O.*.*.O.O.*.*.O O *.*.*.*.*
500000 ++ : : |
| : : |
| : : |
400000 ++ : : |
| : : |
300000 ++ : : |
| : : |
200000 ++ : : |
| : : |
| :: |
100000 ++ : |
| : |
0 ++-------------------------------------------------------*---------+
perf-stat.minor-faults
600000 O+O-O-O-O----O-O-O-O-O-----O-O-O--O-------O-O-----O-O------O-O-O---+
*.*.*.*.*.O..*.*.*.*.*.O.O.*.*.*..*.O.O.O.*.*.O.O.*.*.O O *.*.*.*.*
500000 ++ : : |
| : : |
| : : |
400000 ++ : : |
| : : |
300000 ++ : : |
| : : |
200000 ++ : : |
| : : |
| :: |
100000 ++ : |
| : |
0 ++-------------------------------------------------------*---------+
fileio.time.elapsed_time
600 ++--------------------------------------------------------------------+
O O O O O O O O O O O O O O O O O O O O O O O O O O.O O O O O |
500 *+*.*..*.*.*.*.*..*.*.*.*.*.*..*.*.*.*.*..*.*.*.*.*..*.* : *.*..*.*.*
| : : |
| : : |
400 ++ : : |
| : : |
300 ++ : : |
| : : |
200 ++ : : |
| : : |
| :: |
100 ++ : |
| : |
0 ++---------------------------------------------------------*----------+
fileio.time.elapsed_time.max
600 ++--------------------------------------------------------------------+
O O O O O O O O O O O O O O O O O O O O O O O O O O.O O O O O |
500 *+*.*..*.*.*.*.*..*.*.*.*.*.*..*.*.*.*.*..*.*.*.*.*..*.* : *.*..*.*.*
| : : |
| : : |
400 ++ : : |
| : : |
300 ++ : : |
| : : |
200 ++ : : |
| : : |
| :: |
100 ++ : |
| : |
0 ++---------------------------------------------------------*----------+
iostat.sda.wrqm_s
7 ++----------------------------------------------------------------------+
| |
6 ++ *.. .* *. |
*.*..*.*. + *. .*.*..*.*.*. .*.*.*.*.. .*.*.*. + .*.*..* : *.. .*.|
5 ++ * * *. * * : : * *
| : : |
4 ++ : : |
| : : |
3 O+O O O O O O O O O O O O O O O O O O O O O O O O O O:O:O O O |
| : : |
2 ++ : : |
| : : |
1 ++ : |
| : |
0 ++-----------------------------------------------------------*----------+
iostat.sda.wkB_s
140000 ++-----------------------------------------------------------------+
*.*.*.*.*.*..*.*.*.*.*.*.*.*.*.*..*.*.*.*.*.*.*.*.*.*. *.*.*.*.*
120000 O+O O O O O O O O O O O O O O O O O O O O O O O O O O O O O O |
| : : |
100000 ++ : : |
| : : |
80000 ++ : : |
| : : |
60000 ++ : : |
| : : |
40000 ++ : : |
| :: |
20000 ++ : |
| : |
0 ++-------------------------------------------------------*---------+
vmstat.io.bo
140000 ++-----------------------------------------------------------------+
*.*.*.*.*.*..*.*.*.*.*.*.*.*.*.*..*.*.*.*.*.*.*.*.*.*. *.*.*.*.*
120000 O+O O O O O O O O O O O O O O O O O O O O O O O O O O O O O O |
| : : |
100000 ++ : : |
| : : |
80000 ++ : : |
| : : |
60000 ++ : : |
| : : |
40000 ++ : : |
| :: |
20000 ++ : |
| : |
0 ++-------------------------------------------------------*---------+
[*] 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,
Xiaolong
4 years, 9 months
[lkp-robot] [net] 98cd1552ea: BUG:unable_to_handle_kernel
by kernel test robot
FYI, we noticed the following commit:
commit: 98cd1552ea27e512c7e99e2aa76042a26e4fb25c ("net: dsa: Mock-up driver")
https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.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 host -smp 2 -m 1G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+-----------------------------------------------------+------------+------------+
| | 772c3bdad1 | 98cd1552ea |
+-----------------------------------------------------+------------+------------+
| boot_successes | 0 | 0 |
| boot_failures | 30 | 30 |
| WARNING:at_arch/x86/mm/dump_pagetables.c:#note_page | 30 | 30 |
| invoked_oom-killer:gfp_mask=0x | 28 | 13 |
| Mem-Info | 28 | 13 |
| Out_of_memory:Kill_process | 28 | 13 |
| BUG:unable_to_handle_kernel | 0 | 16 |
| Oops:#[##] | 0 | 16 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 16 |
+-----------------------------------------------------+------------+------------+
[ 9.603869] BUG: unable to handle kernel NULL pointer dereference at 00000000000001f0
[ 9.606422] IP: dsa_cpu_port_get_sset_count+0x15/0x60
[ 9.607741] PGD 0
[ 9.607743]
[ 9.608670] Oops: 0000 [#1] SMP DEBUG_PAGEALLOC
[ 9.609858] Modules linked in: input_leds tpm_tis tpm_tis_core tpm e1000
[ 9.611615] CPU: 0 PID: 192 Comm: systemd-udevd Tainted: G W 4.11.0-rc3-00912-g98cd155 #1
[ 9.614054] task: ffff88000c668380 task.stack: ffffc90000154000
[ 9.615606] RIP: 0010:dsa_cpu_port_get_sset_count+0x15/0x60
[ 9.617065] RSP: 0018:ffffc90000157bd0 EFLAGS: 00010246
[ 9.618421] RAX: 0000000000000000 RBX: 0000000000000000 RCX: 0000000000000000
[ 9.620292] RDX: 0000000000000004 RSI: 0000000000000000 RDI: ffff88000b596000
[ 9.622158] RBP: ffffc90000157be8 R08: 00000000042909d0 R09: bed418bf00000000
[ 9.624021] R10: 0000000000000001 R11: 0000000000000001 R12: 0000000000000000
[ 9.625886] R13: ffffc90000157bfc R14: 00007ffd60809a80 R15: 00000000ffffffed
[ 9.627750] FS: 00007f9ce88cf8c0(0000) GS:ffff880034400000(0000) knlGS:0000000000000000
[ 9.629837] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 9.631322] CR2: 00000000000001f0 CR3: 000000000b454000 CR4: 00000000000006b0
[ 9.633184] Call Trace:
[ 9.633842] ethtool_get_drvinfo+0xc3/0x160
[ 9.634944] dev_ethtool+0x17b/0x2310
[ 9.635908] ? rtnl_lock+0x12/0x20
[ 9.636818] dev_ioctl+0x522/0x660
[ 9.637727] ? __fd_install+0x25b/0x290
[ 9.638749] ? kvm_sched_clock_read+0x25/0x40
[ 9.639902] sock_do_ioctl+0x2b/0x40
[ 9.641143] sock_ioctl+0x268/0x280
[ 9.642074] do_vfs_ioctl+0x68a/0x6e0
[ 9.643038] ? __fd_install+0x273/0x290
[ 9.644054] ? get_unused_fd_flags+0x30/0x30
[ 9.645172] ? sock_alloc_file+0xb2/0x100
[ 9.646218] SyS_ioctl+0x3c/0x70
[ 9.647076] entry_SYSCALL_64_fastpath+0x23/0xc6
[ 9.648294] RIP: 0033:0x7f9ce77477e7
[ 9.649238] RSP: 002b:00007ffd60809a38 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
[ 9.651214] RAX: ffffffffffffffda RBX: 000055c8e51c8670 RCX: 00007f9ce77477e7
[ 9.653071] RDX: 00007ffd60809a50 RSI: 0000000000008946 RDI: 0000000000000007
[ 9.654938] RBP: 00007f9ce6d55ce2 R08: 530f843e65edc95c R09: 0000000000000168
[ 9.656795] R10: 0000000000000001 R11: 0000000000000246 R12: 000055c8e51d14c0
[ 9.658638] R13: 0000000000000000 R14: 00007f9ce6d55ce2 R15: 00007f9ce6ca5207
[ 9.660508] Code: be f7 48 89 cf ff d0 48 83 c4 10 5b 41 5c 41 5d 41 5e 41 5f 5d c3 55 48 89 e5 41 55 41 54 41 89 f4 53 48 8b 87 10 03 00 00 31 db <4c> 8b a8 f0 01 00 00 48 8b 80 20 01 00 00 48 85 c0 74 06 ff d0
[ 9.665499] RIP: dsa_cpu_port_get_sset_count+0x15/0x60 RSP: ffffc90000157bd0
[ 9.667336] CR2: 00000000000001f0
[ 9.668510] ---[ end trace 171be0d4ea10840e ]---
To reproduce:
git clone https://github.com/01org/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
Xiaolong
4 years, 9 months