[lkp-robot] [rcu] d34303a1ef: unixbench.score -12.8% regression
by kernel test robot
Greeting,
FYI, we noticed a -12.8% regression of unixbench.score due to commit:
commit: d34303a1ef67e7e38e79d63d2b3aca1a12df3b37 ("rcu: Eliminate NOCBs CPU-state Kconfig options")
https://git.kernel.org/cgit/linux/kernel/git/paulmck/linux-rcu.git dev.2017.05.25a
in testcase: unixbench
on test machine: 12 threads Intel(R) Atom(R) CPU 3958 @ 2.00GHz with 64G memory
with following parameters:
runtime: 300s
nr_task: 1
test: spawn
cpufreq_governor: performance
test-description: UnixBench is the original BYTE UNIX benchmark suite aims to test performance of Unix-like system.
test-url: https://github.com/kdlucas/byte-unixbench
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: unixbench/300s-1-spawn-performance/lkp-denverton3
6e74c237c410c0c7 d34303a1ef67e7e38e79d63d2b
---------------- --------------------------
%stddev change %stddev
\ | \
480 -13% 418 unixbench.score
3539621 -12% 3125335 unixbench.time.voluntary_context_switches
42363545 -14% 36485173 unixbench.time.minor_page_faults
176944 76% 311173 ± 3% interrupts.CAL:Function_call_interrupts
28259 4% 29287 vmstat.system.in
55147 -7% 51021 vmstat.system.cs
0 1e+04 9887 ±103% latency_stats.avg.perf_event_alloc.SYSC_perf_event_open.SyS_perf_event_open.entry_SYSCALL_64_fastpath
0 1e+04 9887 ±103% latency_stats.max.perf_event_alloc.SYSC_perf_event_open.SyS_perf_event_open.entry_SYSCALL_64_fastpath
0 1e+04 9887 ±103% latency_stats.sum.perf_event_alloc.SYSC_perf_event_open.SyS_perf_event_open.entry_SYSCALL_64_fastpath
2.99 45% 4.34 perf-stat.branch-miss-rate%
8.00 26% 10.06 perf-stat.cache-miss-rate%
2.881e+09 26% 3.619e+09 perf-stat.branch-misses
3.134e+09 22% 3.831e+09 perf-stat.cache-misses
0.04 ± 5% 13% 0.04 ± 3% perf-stat.iTLB-load-miss-rate%
18327237 -7% 16964457 perf-stat.context-switches
0.51 -11% 0.45 perf-stat.ipc
5.116e+11 -12% 4.489e+11 perf-stat.iTLB-loads
2550 ± 5% -13% 2223 ± 5% perf-stat.instructions-per-iTLB-miss
5.113e+11 -13% 4.436e+11 perf-stat.instructions
9.62e+10 -13% 8.335e+10 perf-stat.branch-instructions
42719474 -14% 36840024 perf-stat.minor-faults
42719477 -14% 36840026 perf-stat.page-faults
437104 ± 3% -26% 322992 ± 3% perf-stat.cpu-migrations
interrupts.CAL:Function_call_interrupts
450000 ++-----------------------------------------------------------------+
| |
400000 ++ O |
| |
| |
350000 ++O O O |
O O O O O O O O O O O |
300000 ++ O O O O O O |
| |
250000 ++ |
| * |
| + + |
200000 ++ .*.. + *..*. .*. .*. .*.. .*.. |
*.*..* *.*..*.* *..*.*. *. *..* * *.*..*.*..*.*
150000 ++-----------------------------------------------------------------+
perf-stat.instructions
5.3e+11 ++----------------------------------------------------------------+
5.2e+11 ++ *.. |
| *..*.*.. .*. + .*.. .*..*. .*
5.1e+11 ++ *.*..*.*. *.*.. .*..*.*..*.* * * *..* |
5e+11 *+ .*..* |
| * |
4.9e+11 ++ |
4.8e+11 ++ |
4.7e+11 ++ |
| O O |
4.6e+11 ++ O |
4.5e+11 ++ O O O O O |
O O O O O O O O |
4.4e+11 ++ O O O O O |
4.3e+11 ++----------------------------------------------------------------+
perf-stat.branch-instructions
1e+11 ++----------------------------------------------------------------+
9.8e+10 ++ *.. |
| *.. .*.. .*. + .*.. .*..*. .*
9.6e+10 ++ * *.*..*.*. *.*.. .*..*.*..*.* * * *..* |
9.4e+10 *+ .*..* |
| * |
9.2e+10 ++ |
9e+10 ++ |
8.8e+10 ++ |
| O O |
8.6e+10 ++ O O O O |
8.4e+10 O+ O O O O O O O |
| O O O O O |
8.2e+10 ++ O O |
8e+10 ++----------------------------------------------------------------+
perf-stat.iTLB-loads
5.3e+11 ++-----*---------------------*------------------------------------+
5.2e+11 ++ :+ * * |
| : + .*. .. + .*.*
5.1e+11 *+*.. : *.*..*.*. *.* *..*.*..*.*. .*.*..*.*.. .*. |
5e+11 ++ * *. * |
| |
4.9e+11 ++ |
4.8e+11 ++ |
4.7e+11 ++ |
| |
4.6e+11 ++ O O O |
4.5e+11 ++O O O O O O |
O O O O O O |
4.4e+11 ++ O O O O O O |
4.3e+11 ++----------------------------------------------------------------+
perf-stat.page-faults
4.4e+07 ++----------------------------------------------------------------+
| *. *.. |
4.3e+07 ++ .*. .*.*..*. .. *. .*..*. .*. .*.*. .*. + .*..*.*
4.2e+07 *+*. *. * *..* *. *. *. *..* * |
| |
4.1e+07 ++ |
4e+07 ++ |
| |
3.9e+07 ++ |
3.8e+07 ++ |
| |
3.7e+07 ++ O O O O O O O O |
3.6e+07 O+ O O O O O O O O O O |
| O O |
3.5e+07 ++----------------------------------------------------------------+
perf-stat.context-switches
1.95e+07 ++---------------------------------------------------------------+
| .* |
1.9e+07 ++ * : |
| + : |
| .*.. + : |
1.85e+07 *+*..* *.*.*..*. .*.*.* *. .*. .*.*.. .*. .*.. .*
| *. *. *.*. * *..* * |
1.8e+07 ++ |
| |
1.75e+07 ++ |
| |
| O |
1.7e+07 ++O O O O O O O O O O O O O O |
O O O O O O |
1.65e+07 ++---------------------------------------------------------------+
perf-stat.minor-faults
4.4e+07 ++----------------------------------------------------------------+
| *. *.. |
4.3e+07 ++ .*. .*.*..*. .. *. .*..*. .*. .*.*. .*. + .*..*.*
4.2e+07 *+*. *. * *..* *. *. *. *..* * |
| |
4.1e+07 ++ |
4e+07 ++ |
| |
3.9e+07 ++ |
3.8e+07 ++ |
| |
3.7e+07 ++ O O O O O O O O |
3.6e+07 O+ O O O O O O O O O O |
| O O |
3.5e+07 ++----------------------------------------------------------------+
perf-stat.branch-miss-rate_
4.6 ++--------------------------------------------------------------------+
4.4 ++ O |
| O O O O O |
4.2 ++ O O O O O O O O O O O O |
4 O+ O O |
| |
3.8 ++ |
3.6 ++ |
3.4 ++ |
| |
3.2 ++ .*. .*.. |
3 *+.*.*. *..*.. .*..*.*..*..* *..*.*..*..*. .* *..*.*..*.*..*
| * : + *. |
2.8 ++ : .* |
2.6 ++-----------------------------*--------------------------------------+
unixbench.score
490 ++-------------------*------------------------------------------------+
*..*.*..*.*.. .*. *..*..*.*..*.*..*.*..*.. .*..*.*..*.. .*.*..|
480 ++ *..* * *.*. *
470 ++ |
| |
460 ++ |
450 ++ |
| |
440 ++ |
430 ++ |
| |
420 ++ O O O O |
410 ++ O O O O O O O O O O O |
O O O O O O |
400 ++--------------------------------------------------------------------+
unixbench.time.minor_page_faults
4.4e+07 ++----------------------------------------------------------------+
| *.. |
4.3e+07 ++ .*. .*. .*. .*.* .*.. + .*.*
4.2e+07 *+*. *. *. *. + .* *.*..*.*..*.*.*..*.*..* *.*. |
| *. |
4.1e+07 ++ |
4e+07 ++ |
| |
3.9e+07 ++ |
3.8e+07 ++ |
| |
3.7e+07 ++ O O |
3.6e+07 O+ O O O O O O O O O O O O |
| O O O O O |
3.5e+07 ++O---------------------------------------------------------------+
unixbench.time.voluntary_context_switches
3.7e+06 ++----------------------------------------------------------------+
| |
3.6e+06 ++ .*.. .*..*. .*.. .*..*. |
|.*..* *.*..* * * *..*.*..*. .*..*.*..*. .*.*..*.|
3.5e+06 *+ * *. *
| |
3.4e+06 ++ |
| |
3.3e+06 ++ |
| |
3.2e+06 ++ |
| O O O |
3.1e+06 ++O O O O O O O O O O |
O O O O O O O O |
3e+06 ++----------------------------------------------------------------+
[*] 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
3 years, 4 months
- 2
- 1
[lkp-robot] [fs/locks] 9d21d181d0: will-it-scale.per_process_ops -14.1% regression
by kernel test robot
Greeting,
FYI, we noticed a -14.1% regression of will-it-scale.per_process_ops due to commit:
commit: 9d21d181d06acab9a8e80eac2ec4eed77b656793 ("fs/locks: Set fl_nspid at file_lock allocation")
url: https://github.com/0day-ci/linux/commits/Benjamin-Coddington/fs-locks-All...
in testcase: will-it-scale
on test machine: 4 threads Intel(R) Core(TM) i3-3220 CPU @ 3.30GHz with 4G memory
with following parameters:
test: lock1
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
In addition to that, the commit also has significant impact on the following tests:
+------------------+----------------------------------------------------------------+
| testcase: change | will-it-scale: will-it-scale.per_process_ops -4.9% regression |
| test machine | 16 threads Intel(R) Atom(R) CPU 3958 @ 2.00GHz with 64G memory |
| test parameters | cpufreq_governor=performance |
| | mode=process |
| | nr_task=100% |
| | test=lock1 |
+------------------+----------------------------------------------------------------+
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/lock1-performance/lkp-ivb-d04
09790e423b32fba4 9d21d181d06acab9a8e80eac2e
---------------- --------------------------
0.51 19% 0.60 ± 7% will-it-scale.scalability
2462089 -14% 2114597 will-it-scale.per_process_ops
2195246 -26% 1631578 will-it-scale.per_thread_ops
350 356 will-it-scale.time.system_time
28.89 -24% 22.06 will-it-scale.time.user_time
32.78 31.97 turbostat.PkgWatt
15.58 -5% 14.80 turbostat.CorWatt
19284 18803 vmstat.system.in
32208 -4% 31052 vmstat.system.cs
1630 ±173% 2e+04 18278 ± 27% latency_stats.avg.perf_event_alloc.SYSC_perf_event_open.SyS_perf_event_open.entry_SYSCALL_64_fastpath
1630 ±173% 2e+04 18278 ± 27% latency_stats.max.perf_event_alloc.SYSC_perf_event_open.SyS_perf_event_open.entry_SYSCALL_64_fastpath
1630 ±173% 2e+04 18278 ± 27% latency_stats.sum.perf_event_alloc.SYSC_perf_event_open.SyS_perf_event_open.entry_SYSCALL_64_fastpath
1.911e+09 ± 6% 163% 5.022e+09 ± 5% perf-stat.cache-references
27.58 ± 12% 17% 32.14 ± 7% perf-stat.iTLB-load-miss-rate%
9881103 -4% 9527607 perf-stat.context-switches
9.567e+11 ± 9% -14% 8.181e+11 ± 9% perf-stat.dTLB-loads
6.85e+11 ± 4% -16% 5.761e+11 ± 6% perf-stat.branch-instructions
3.469e+12 ± 4% -17% 2.893e+12 ± 6% perf-stat.instructions
1.24 ± 4% -19% 1.00 perf-stat.ipc
3.18 ± 8% -62% 1.19 ± 19% perf-stat.cache-miss-rate%
perf-stat.cache-references
8e+09 ++------------------------------------------------------------------+
| |
7e+09 ++ O O |
| O |
6e+09 ++ O |
| O |
5e+09 ++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 |
4e+09 ++ O O O |
| |
3e+09 ++ |
| *. *.. *. |
2e+09 *+ + *.. .*. + *. .*. + *. .*.*. .*.*. .*..* |
| * *.*.*.*.* * * * *.*. *.* * |
1e+09 ++------------------------------------------------------------------+
will-it-scale.time.user_time
30 ++--*-------------------*-----------*----------------------------------+
29 *+* *.*.*.*..*.*.*.* *.*.*.*. *.*. *. .*. .*.*.* |
| *. .. * *.*. |
28 ++ * |
27 ++ |
| |
26 ++ |
25 ++ |
24 ++ |
| |
23 ++ O O O O O O O O O O O O O |
22 O+O O O O O O O O O O O O O O |
| O O O O
21 ++ O |
20 ++---------------------------------------------------------------------+
will-it-scale.time.system_time
358 ++--------------------------------------------------------------------+
357 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 |
356 ++ O O O O O O |
355 ++ |
| |
354 ++ |
353 ++ |
352 ++ |
| |
351 ++ *. .*. .*. |
350 *+*. .* * .*.*.*. .* *.*. .* + * *..* *.*.* |
| *. + + + .*. * + .. * + .* |
349 ++ * * * *. |
348 ++--------------------------------------------------------------------+
will-it-scale.per_thread_ops
2.3e+06 ++----------------------------------------------------------------+
| |
2.2e+06 ++*.*. .*. .*..*. .*.*. .*.*. .*.*..*.*.*.*.* |
* *.* * * *.*.* *.*. .*.* |
2.1e+06 ++ * |
2e+06 ++ |
| |
1.9e+06 ++ |
| |
1.8e+06 ++ |
1.7e+06 ++ O O O O O O |
O O O O O O O O O O |
1.6e+06 ++ O O O O O O O O O O O O O O O
| O O |
1.5e+06 ++----------------------------------------------------------------+
[*] 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
3 years, 4 months
- 3
- 3
[lkp-robot] [fs] b220238d4b: INFO:ltp-pan_reported_some_tests_FAIL
by kernel test robot
FYI, we noticed the following commit:
commit: b220238d4be26bfd019eebb5ad8e51b89a147355 ("fs: Use RWF_* flags for AIO operations")
https://github.com/goldwynr/linux nbaio
in testcase: ltp
with following parameters:
test: syscalls_part2
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 CPU 870 @ 2.93GHz with 4G memory
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+----------------------------------------------------+------------+------------+
| | 21599a51d7 | b220238d4b |
+----------------------------------------------------+------------+------------+
| boot_successes | 7 | 4 |
| boot_failures | 7 | 13 |
| INFO:creating/lkp/benchmarks/ltp/output_directory | 7 | 11 |
| INFO:creating/lkp/benchmarks/ltp/results_directory | 7 | 11 |
| INFO:ltp-pan_reported_all_tests_PASS | 6 | |
| invoked_oom-killer:gfp_mask=0x | 1 | |
| Mem-Info | 1 | |
| Out_of_memory:Kill_process | 1 | |
| INFO:ltp-pan_reported_some_tests_FAIL | 0 | 11 |
+----------------------------------------------------+------------+------------+
[ 267.870135] tst_test.c:878: INFO: Timeout per run is 0h 05m 00s
[ 267.870136]
[ 267.871649] madvise01.c:118: PASS: madvise test for MADV_NORMAL PASSED
[ 267.871651]
[ 267.873192] madvise01.c:118: PASS: madvise test for MADV_RANDOM PASSED
[ 267.873193]
[ 267.874873] madvise01.c:118: PASS: madvise test for MADV_SEQUENTIAL PASSED
[ 267.874875]
[ 267.876469] madvise01.c:118: PASS: madvise test for MADV_WILLNEED PASSED
[ 267.876470]
[ 267.878128] madvise01.c:118: PASS: madvise test for MADV_DONTNEED PASSED
[ 267.878130]
[ 267.879823] madvise01.c:118: PASS: madvise test for MADV_REMOVE PASSED
[ 267.879825]
[ 267.881406] madvise01.c:118: PASS: madvise test for MADV_DONTFORK PASSED
[ 267.881408]
[ 267.883019] madvise01.c:118: PASS: madvise test for MADV_DOFORK PASSED
[ 267.883020]
[ 267.884594] madvise01.c:118: PASS: madvise test for MADV_HWPOISON PASSED
[ 267.884596]
[ 267.886262] madvise01.c:118: PASS: madvise test for MADV_MERGEABLE PASSED
[ 267.886264]
[ 267.887909] madvise01.c:118: PASS: madvise test for MADV_UNMERGEABLE PASSED
[ 267.887910]
[ 267.889508] madvise01.c:118: PASS: madvise test for MADV_HUGEPAGE PASSED
[ 267.889509]
[ 267.891185] madvise01.c:118: PASS: madvise test for MADV_NOHUGEPAGE PASSED
[ 267.891187]
[ 267.892778] madvise01.c:118: PASS: madvise test for MADV_DONTDUMP PASSED
[ 267.892780]
[ 267.894394] madvise01.c:118: PASS: madvise test for MADV_DODUMP PASSED
[ 267.894396]
[ 267.896045] madvise01.c:118: PASS: madvise test for MADV_FREE PASSED
[ 267.896046]
[ 267.896550]
[ 267.896840] Summary:
[ 267.896841]
[ 267.897374] passed 16
[ 267.897375]
[ 267.897885] failed 0
[ 267.897886]
[ 267.898439] skipped 0
[ 267.898441]
[ 267.898943] warnings 0
[ 267.898944]
[ 267.899658] <<<execution_status>>>
[ 267.899659]
[ 267.900424] initiation_status="ok"
[ 267.900425]
[ 267.901933] duration=0 termination_type=exited termination_id=0 corefile=no
[ 267.901935]
[ 267.902737] cutime=0 cstime=3
[ 267.902738]
[ 267.903363] <<<test_end>>>
[ 267.903365]
[ 267.903989] <<<test_start>>>
[ 267.903990]
[ 267.904854] tag=madvise02 stime=1496201276
[ 267.904855]
[ 267.905585] cmdline="madvise02"
[ 267.905586]
[ 267.906196] contacts=""
[ 267.906198]
[ 267.906756] analysis=exit
[ 267.906757]
[ 267.907400] <<<test_output>>>
[ 267.907401]
[ 267.910400] tst_test.c:878: INFO: Timeout per run is 0h 05m 00s
[ 267.910401]
[ 267.911896] madvise02.c:164: PASS: failed as expected: EINVAL
[ 267.911898]
[ 267.913244] madvise02.c:164: PASS: failed as expected: EINVAL
[ 267.913245]
[ 267.914646] madvise02.c:164: PASS: failed as expected: EINVAL
[ 267.914647]
[ 267.916084] madvise02.c:157: CONF: MADV_MERGEABLE is not supported
[ 267.916085]
[ 267.917573] madvise02.c:157: CONF: MADV_UNMERGEABLE is not supported
[ 267.917574]
[ 267.918933] madvise02.c:164: PASS: failed as expected: ENOMEM
[ 267.918934]
[ 267.920275] madvise02.c:164: PASS: failed as expected: ENOMEM
[ 267.920276]
[ 267.921689] madvise02.c:157: CONF: MADV_WILLNEED is not supported
[ 267.921691]
[ 267.922224]
[ 267.922514] Summary:
[ 267.922515]
[ 267.923026] passed 5
[ 267.923028]
[ 267.923539] failed 0
[ 267.923540]
[ 267.924062] skipped 3
[ 267.924063]
[ 267.924570] warnings 0
[ 267.924572]
[ 267.925212] incrementing stop
[ 267.925213]
[ 267.925940] <<<execution_status>>>
[ 267.925942]
[ 267.926734] initiation_status="ok"
[ 267.926735]
[ 267.928408] duration=0 termination_type=exited termination_id=32 corefile=no
[ 267.928410]
[ 267.929236] cutime=0 cstime=0
[ 267.929237]
[ 267.929831] <<<test_end>>>
[ 267.929832]
[ 267.930873] INFO: ltp-pan reported some tests FAIL
[ 267.930874]
[ 267.931909] LTP Version: 20170516-18-gcbbe48a8e
[ 267.931911]
[ 267.932360]
[ 267.933781] ###############################################################
[ 267.933783]
[ 267.934436]
[ 267.935243] Done executing testcases.
[ 267.935244]
[ 267.936527] LTP Version: 20170516-18-gcbbe48a8e
[ 267.936528]
[ 267.938312] ###############################################################
[ 267.938313]
[ 267.938924]
[ 268.843563] kill 9447 vmstat --timestamp -n 10
[ 268.843564]
[ 268.846381] kill 9445 dmesg --follow --decode
[ 268.846382]
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
3 years, 4 months
- 2
- 2
[rcuperf] 7996fa1768: BUG: kernel hang in test stage
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 7996fa1768f36bb5d1477c88f7c410fd4e09d586
Author: Paul E. McKenney <paulmck(a)linux.vnet.ibm.com>
AuthorDate: Fri Apr 21 16:09:15 2017 -0700
Commit: Paul E. McKenney <paulmck(a)linux.vnet.ibm.com>
CommitDate: Tue May 16 15:51:58 2017 -0700
rcuperf: Set more user-friendly defaults
Common-case use of rcuperf must set rcuperf.nreaders=0 and if not built
as a module, rcuperf.shutdown. This commit therefore sets the default
for rcuperf.nreaders to zero and sets the default for rcuperf.shutdown
to zero if rcuperf is built as a module and to one otherwise.
Signed-off-by: Paul E. McKenney <paulmck(a)linux.vnet.ibm.com>
47b20cbf88 srcu: Shrink Tiny SRCU a bit more
7996fa1768 rcuperf: Set more user-friendly defaults
d90401eb78 srcu: Move rcu_scheduler_starting() from Tiny RCU to Tiny SRCU
f2882f46b6 Add linux-next specific files for 20170531
+-----------------------------------------------------------------------------------+------------+------------+------------+---------------+
| | 47b20cbf88 | 7996fa1768 | d90401eb78 | next-20170531 |
+-----------------------------------------------------------------------------------+------------+------------+------------+---------------+
| boot_successes | 65 | 0 | 3 | 0 |
| boot_failures | 2 | 26 | 27 | 25 |
| BUG:kernel_hang_in_test_stage | 2 | 26 | 27 | 22 |
| BUG:sleeping_function_called_from_invalid_context_at_include/linux/percpu-rwsem.h | 0 | 0 | 0 | 3 |
+-----------------------------------------------------------------------------------+------------+------------+------------+---------------+
[ 10.649585] ACPI: Preparing to enter system sleep state S5
[ 10.650251] reboot: Power down
[ 10.651379] acpi_power_off called
# HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start 2adee189a058a6d8cb981b45342aae99125ff254 2ea659a9ef488125eb46da6eb571de5eae5c43f6 --
git bisect bad b89ba7b92b7a89c7ec23ff8c079f5d60163c92cf # 04:23 B 0 2 14 0 Merge 'peterz-queue/x86/boot' into devel-catchup-201706010137
git bisect good edd65cd714bf131ec9db1d1ca80381af60a5bd94 # 04:42 G 12 0 0 0 Merge 'jlayton/linux-next' into devel-catchup-201706010137
git bisect good 44d12dda8e32d14679898aee44038e8cc6817e04 # 04:54 G 12 0 0 0 Merge 'linux-review/YT-Shen/dt-bindings-arm-Add-bindings-for-Mediatek-MT2712-SoC-Platform/20170601-010910' into devel-catchup-201706010137
git bisect bad ba3ef678b90c7c83b737c01dbbc2b88f4f5290b9 # 05:29 B 0 12 24 0 Merge 'rcu/rcu/next' into devel-catchup-201706010137
git bisect good 0ee62d4d5471b7c3dbb833377633c1071fac16d2 # 05:41 G 12 0 0 0 Merge 'shli/for-next' into devel-catchup-201706010137
git bisect bad 51a1bc4a1cb80018de5bd3ab2964799ddab78ff3 # 06:18 B 0 12 24 0 rcu: Move docbook comments out of rcupdate.h
git bisect good 98e394a69b980bee262d878fedad675429d40505 # 06:33 G 12 0 0 0 rcutorture: Reduce CPUs dedicated to testing Classic SRCU
git bisect bad 3e230108c5ea9e5bf917ddf656535e69d7a6ccf0 # 07:11 B 0 6 18 0 srcu: Print non-default exp_holdoff values at boot time
git bisect bad 461f7757e205b0279d4d7edec39c3f8cb119e970 # 07:43 B 2 1 2 2 srcu: Document auto-expediting requirement
git bisect bad 5f75056ca6c0c65eb85db6c64ab215cd1000efdd # 08:45 B 0 18 31 1 srcu-cbmc: Use /usr/bin/awk instead of /bin/awk
git bisect bad 7996fa1768f36bb5d1477c88f7c410fd4e09d586 # 09:35 B 0 21 33 0 rcuperf: Set more user-friendly defaults
git bisect good 47b20cbf88da0d39ae32c36d84cbcf5a141b7c85 # 09:53 G 22 0 0 0 srcu: Shrink Tiny SRCU a bit more
# first bad commit: [7996fa1768f36bb5d1477c88f7c410fd4e09d586] rcuperf: Set more user-friendly defaults
git bisect good 47b20cbf88da0d39ae32c36d84cbcf5a141b7c85 # 09:57 G 63 0 0 0 srcu: Shrink Tiny SRCU a bit more
# extra tests with CONFIG_DEBUG_INFO_REDUCED
git bisect bad 7996fa1768f36bb5d1477c88f7c410fd4e09d586 # 10:48 B 2 1 2 2 rcuperf: Set more user-friendly defaults
# extra tests on HEAD of linux-devel/devel-catchup-201706010137
git bisect bad 2adee189a058a6d8cb981b45342aae99125ff254 # 10:53 B 4 26 0 2 0day head guard for 'devel-catchup-201706010137'
# extra tests on tree/branch rcu/rcu/next
git bisect bad d90401eb78559d90c11687a50382c9dd15a5ac51 # 11:37 B 0 19 31 0 srcu: Move rcu_scheduler_starting() from Tiny RCU to Tiny SRCU
# extra tests with first bad commit reverted
git bisect good f539aa2af19ba5390d23a60dc8e182a70ea584eb # 11:55 G 22 0 0 0 Revert "rcuperf: Set more user-friendly defaults"
# extra tests on tree/branch linux-next/master
git bisect bad f2882f46b623423c573c4d029b016974b04da954 # 12:35 B 0 22 37 3 Add linux-next specific files for 20170531
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
3 years, 4 months
- 1
- 0
dc195b4428: kernel BUG at kernel/rcu/sync.c:166!
by kernel test robot
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
git://internal_merge_and_test_tree devel-spot-201706010531
commit dc195b442899f6798b67414fab1cf8be4d1edf1d
Merge: 2ad05ccc587f1 989194d092f0c
Author: 0day robot <fengguang.wu(a)intel.com>
AuthorDate: Thu Jun 1 05:57:28 2017 +0800
Commit: 0day robot <fengguang.wu(a)intel.com>
CommitDate: Thu Jun 1 05:57:28 2017 +0800
Merge 'chyyuu/llvmlinux-4.12-rc3' into devel-spot-201706010531
2ad05ccc58 Merge 'linux-review/Sascha-Hauer/tty-n_gsm-do-not-send-receive-in-ldisc-close-path/20170531-154838' into devel-spot-201706010531
989194d092 fs: exclude exofs
dc195b4428 Merge 'chyyuu/llvmlinux-4.12-rc3' into devel-spot-201706010531
69519411c2 0day head guard for 'devel-spot-201706010531'
+--------------------------------------------------------------------+------------+------------+------------+------------+
| | 2ad05ccc58 | 989194d092 | dc195b4428 | 69519411c2 |
+--------------------------------------------------------------------+------------+------------+------------+------------+
| boot_successes | 102 | 111 | 0 | 0 |
| boot_failures | 23 | 0 | 38 | 13 |
| BUG:kernel_hang_in_test_stage | 16 | | | |
| BUG:kernel_hang_in_early-boot_stage,last_printk:Booting_the_kernel | 1 | | | |
| BUG:kernel_in_stage | 6 | | | |
| kernel_BUG_at_mm/slub.c | 0 | 0 | 8 | 2 |
| invalid_opcode:#[##] | 0 | 0 | 22 | 7 |
| Kernel_panic-not_syncing:Fatal_exception_in_interrupt | 0 | 0 | 22 | 8 |
| WARNING:at_kernel/rcu/tree.c:#rcu_process_callbacks | 0 | 0 | 18 | 5 |
| kernel_BUG_at_kernel/rcu/sync.c | 0 | 0 | 14 | 5 |
| BUG:unable_to_handle_kernel | 0 | 0 | 3 | 2 |
| Oops:#[##] | 0 | 0 | 3 | 2 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 0 | 4 | 1 |
| general_protection_fault:#[##] | 0 | 0 | 1 | 1 |
+--------------------------------------------------------------------+------------+------------+------------+------------+
[ 6.491795] Write protecting the kernel read-only data: 32768k
[ 6.493348] Freeing unused kernel memory: 1068K
[ 6.501927] Freeing unused kernel memory: 1516K
[ 6.502661] rodata_test: all tests were successful
[ 6.588138] ------------[ cut here ]------------
[ 6.588896] kernel BUG at kernel/rcu/sync.c:166!
[ 6.589760] invalid opcode: 0000 [#1] SMP
[ 6.590429] CPU: 0 PID: 7 Comm: ksoftirqd/0 Not tainted 4.12.0-rc3-02477-gdc195b4 #1
[ 6.591671] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 6.593077] task: ffff88001f534c80 task.stack: ffffc90000100000
[ 6.593887] RIP: 0010:rcu_sync_func+0x5f/0x90
[ 6.594577] RSP: 0000:ffffc90000103dc8 EFLAGS: 00010293
[ 6.595327] RAX: ffff88001c23af60 RBX: c3efc787bd198842 RCX: b9b80ef4435f9ab1
[ 6.596224] RDX: ffffffff81154e90 RSI: ffffea0000710b00 RDI: ffff88001c23af60
[ 6.597122] RBP: ffffc90000103e40 R08: 0000000000000001 R09: 0000000000000000
[ 6.598021] R10: ffffc90000103d90 R11: 000000000001aa80 R12: ffff88001fa195c0
[ 6.598917] R13: ffffffff8303eb40 R14: ffff88001fa195f8 R15: ffff88001f534c80
[ 6.599816] FS: 0000000000000000(0000) GS:ffff88001fa00000(0000) knlGS:0000000000000000
[ 6.601056] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 6.601850] CR2: 00007f3449a905e6 CR3: 000000001c7e9000 CR4: 00000000001406b0
[ 6.602749] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[ 6.603649] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[ 6.604546] Call Trace:
[ 6.605087] ? rcu_process_callbacks+0x35e/0x7f0
[ 6.605804] __do_softirq+0xe7/0x200
[ 6.606431] ? smpboot_thread_fn+0x2e/0x290
[ 6.607105] run_ksoftirqd+0x17/0x40
[ 6.607734] smpboot_thread_fn+0x182/0x290
[ 6.608399] kthread+0x12a/0x170
[ 6.609003] ? sort_range+0x30/0x30
[ 6.609624] ? __kthread_create_on_node+0x1b0/0x1b0
[ 6.610349] ret_from_fork+0x22/0x30
[ 6.610981] Code: c7 43 f8 00 00 00 00 c7 43 d8 00 00 00 00 4c 89 e7 48 89 c6 e8 63 57 59 01 48 83 c4 08 5b 41 5c 5d c3 c7 43 f8 00 00 00 00 eb e3 <0f> 0b 0f 0b 8b 53 10 48 89 45 e8 48 c7 c6 90 4e 15 81 c7 43 f8
[ 6.613357] RIP: rcu_sync_func+0x5f/0x90 RSP: ffffc90000103dc8
[ 6.614167] ---[ end trace da9baaf0509d4a1f ]---
[ 6.614892] Kernel panic - not syncing: Fatal exception in interrupt
# HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start 69519411c2f429e4d9829267f6905720d0fe5cc1 5ed02dbb497422bf225783f46e6eadd237d23d6b --
git bisect good ba61ac25e15d9797d49602573e65c0e6ed2af2dd # 09:21 G 18 0 0 0 Merge 'linux-review/Leonard-Crestez/ARM-imx6ul-14x14-evk-Fix-suspend-over-nfs-by-phy/20170531-211344' into devel-spot-201706010531
git bisect bad 9166c6548aaa45b2e7c53adf097f2c581f43a140 # 09:32 B 8 3 8 8 Merge 'gpio/for-next' into devel-spot-201706010531
git bisect good c0e5ad09cd70fe80abae88d95b09c35eae8296b7 # 10:11 G 38 0 8 8 Merge 'bbrezillon-0day/nand/next' into devel-spot-201706010531
git bisect good 2ad05ccc587f12d7fea88021c5e84e464163acca # 10:44 G 38 0 9 9 Merge 'linux-review/Sascha-Hauer/tty-n_gsm-do-not-send-receive-in-ldisc-close-path/20170531-154838' into devel-spot-201706010531
git bisect bad 9d023aaddd08a75c85a56dc7adc88a972f0b563c # 10:56 B 0 7 18 0 Merge 'linux-review/fenglinw-codeaurora-org/leds-leds-qti-rgb-Add-LED-driver-for-QTI-TRI_LED-module/20170531-153634' into devel-spot-201706010531
git bisect bad 4f1b08647bc081223312f9d839b9285b0792e92f # 11:17 B 3 3 3 4 Merge 'chyyuu/llvmlinux-4.12-rc1' into devel-spot-201706010531
git bisect bad dc195b442899f6798b67414fab1cf8be4d1edf1d # 11:31 B 12 14 12 12 Merge 'chyyuu/llvmlinux-4.12-rc3' into devel-spot-201706010531
git bisect good 989194d092f0c82d27956a00b16715985d1db1b1 # 11:43 G 38 0 0 0 fs: exclude exofs
# first bad commit: [dc195b442899f6798b67414fab1cf8be4d1edf1d] Merge 'chyyuu/llvmlinux-4.12-rc3' into devel-spot-201706010531
git bisect good 2ad05ccc587f12d7fea88021c5e84e464163acca # 11:52 G 112 0 12 23 Merge 'linux-review/Sascha-Hauer/tty-n_gsm-do-not-send-receive-in-ldisc-close-path/20170531-154838' into devel-spot-201706010531
git bisect good 989194d092f0c82d27956a00b16715985d1db1b1 # 11:57 G 111 0 0 0 fs: exclude exofs
# extra tests with CONFIG_DEBUG_INFO_REDUCED
git bisect bad dc195b442899f6798b67414fab1cf8be4d1edf1d # 12:06 B 5 5 5 5 Merge 'chyyuu/llvmlinux-4.12-rc3' into devel-spot-201706010531
# extra tests on HEAD of linux-devel/devel-spot-201706010531
git bisect bad 69519411c2f429e4d9829267f6905720d0fe5cc1 # 12:06 B 0 5 27 8 0day head guard for 'devel-spot-201706010531'
# extra tests on tree/branch linux-devel/devel-spot-201706010531
git bisect bad 69519411c2f429e4d9829267f6905720d0fe5cc1 # 12:08 B 0 5 27 8 0day head guard for 'devel-spot-201706010531'
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
3 years, 4 months
- 1
- 0
[lkp-robot] [usercopy] 5a78d1fa1e: kernel BUG at mm/slub.c:3878!
by kernel test robot
FYI, we noticed the following commit:
commit: 5a78d1fa1ea08f87238f55b27871fb26116fd258 ("usercopy: split user-controlled slabs to separate caches")
https://github.com/dwindsor/kspp slabcache-usercopy
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -cpu IvyBridge -smp 2 -m 1G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
[ 0.000000] ------------[ cut here ]------------
[ 0.000000] kernel BUG at mm/slub.c:3878!
[ 0.000000] invalid opcode: 0000 [#1] SMP DEBUG_PAGEALLOC
[ 0.000000] Modules linked in:
[ 0.000000] CPU: 0 PID: 0 Comm: swapper Not tainted 4.12.0-rc2-next-20170524-00019-g5a78d1f #564
[ 0.000000] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 0.000000] task: ffffffffa2a14580 task.stack: ffffffffa2a00000
[ 0.000000] RIP: 0010:kfree+0x37e/0x578
[ 0.000000] RSP: 0000:ffffffffa2a03c10 EFLAGS: 00010002
[ 0.000000] RAX: 0000000000000003 RBX: 0000000000000001 RCX: 0000000000000000
[ 0.000000] RDX: 0000000000000000 RSI: 0000000000000001 RDI: ffffffffa2bcec80
[ 0.000000] RBP: ffffffffa2a03c50 R08: 0000000000000000 R09: ffffffffa2a14c68
[ 0.000000] R10: 00000000fff9e8e7 R11: 0000000000000000 R12: ffffea000031b300
[ 0.000000] R13: 0000000000000001 R14: 0000000000000000 R15: 0000000000000000
[ 0.000000] FS: 0000000000000000(0000) GS:ffff880033e00000(0000) knlGS:0000000000000000
[ 0.000000] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 0.000000] CR2: ffff8800341ff000 CR3: 000000000be0f000 CR4: 00000000000406b0
[ 0.000000] Call Trace:
[ 0.000000] kvfree+0x48/0x4a
[ 0.000000] pcpu_extend_area_map+0x10c/0x11f
[ 0.000000] pcpu_alloc+0x3ce/0x7bb
[ 0.000000] __alloc_percpu+0x15/0x17
[ 0.000000] kmem_cache_open+0x49f/0x5ed
[ 0.000000] ? trace_hardirqs_off_caller+0xb3/0xc3
[ 0.000000] ? trace_hardirqs_off+0xd/0xf
[ 0.000000] __kmem_cache_create+0x14/0x8b
[ 0.000000] create_boot_cache+0x7c/0xd6
[ 0.000000] create_kmalloc_cache_usercopy+0xaf/0x139
[ 0.000000] create_kmalloc_caches+0x4bf/0x514
[ 0.000000] kmem_cache_init+0x127/0x1ef
[ 0.000000] start_kernel+0x2ba/0x71e
[ 0.000000] ? early_idt_handler_array+0x120/0x120
[ 0.000000] x86_64_start_reservations+0x5a/0x7b
[ 0.000000] x86_64_start_kernel+0x16b/0x19c
[ 0.000000] secondary_startup_64+0x9f/0x9f
[ 0.000000] Code: 83 e3 01 83 f3 01 31 c9 31 d2 89 de 48 c7 c7 80 ec bc a2 e8 e9 57 f2 ff 48 63 c3 48 83 c0 02 48 ff 04 c5 18 21 d8 a2 85 db 74 02 <0f> 0b 49 8b 04 24 31 f6 48 c1 e8 0f 83 e0 01 48 83 f0 01 48 8d
[ 0.000000] RIP: kfree+0x37e/0x578 RSP: ffffffffa2a03c10
[ 0.000000] ---[ end trace 5a8e2fc8a5208125 ]---
[ 0.000000] Kernel panic - not syncing: Fatal exception
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
3 years, 4 months
- 1
- 0
[lkp-robot] [smp] 887c1923c5: vm-scalability.throughput 45.6% improvement
by kernel test robot
Greeting,
FYI, we noticed a 45% improvement of vm-scalability.throughput due to commit:
commit: 887c1923c5a759b0d39d0c9a43f5d9b86d2d29a8 ("smp: do not send IPI if call_single_queue not empty")
git://bee.sh.intel.com/git/aaron/linux.git ipi_queue_empty
in testcase: vm-scalability
on test machine: 72 threads Intel(R) Xeon(R) CPU E5-2699 v3 @ 2.30GHz with 128G memory
with following parameters:
runtime: 300
thp_enabled: never
thp_defrag: always
nr_task: 32
nr_pmem: 4
priority: 1
test: swap-w-rand-mt
cpufreq_governor: performance
test-description: The motivation behind this suite is to exercise functions and regions of the mm/ of the Linux kernel which are of interest to us.
test-url: https://git.kernel.org/cgit/linux/kernel/git/wfg/vm-scalability.git/
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: vm-scalability/300-never-always-32-4-1-swap-w-rand-mt-performance/lkp-hsw-ep2
4bcd31718c9a9be7 887c1923c5a759b0d39d0c9a43
---------------- --------------------------
3741293 ± 3% 45% 5438655 vm-scalability.throughput
113334 44% 162750 vm-scalability.median
61.46 ± 3% -7% 57.09 vm-scalability.time.user_time
2604 -8% 2386 vm-scalability.time.percent_of_cpu_this_job_got
11530 ± 23% -22% 9010 ± 23% vm-scalability.time.voluntary_context_switches
33.25 -28% 24.10 vm-scalability.time.elapsed_time
33.25 -28% 24.10 vm-scalability.time.elapsed_time.max
805 ± 3% -36% 518 vm-scalability.time.system_time
2.147e+08 ± 4% -66% 72739411 ± 4% interrupts.CAL:Function_call_interrupts
3:4 -75% :4 dmesg.Mem-Info
1680853 25% 2103672 ± 4% vmstat.swap.so
1322 ± 19% -53% 615 ± 5% vmstat.swap.si
6098025 ± 3% -54% 2828296 ± 4% vmstat.system.in
69 ± 19% 7e+03 6624 ±124% latency_stats.max.kthread_park.watchdog_park_threads.watchdog_enable_all_cpus.proc_watchdog_update.proc_watchdog_common.proc_nmi_watchdog.proc_sys_call_handler.proc_sys_write.__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
254824 ± 87% -2e+05 21065 ± 27% latency_stats.max.flush_work.drain_all_pages.__alloc_pages_slowpath.__alloc_pages_nodemask.alloc_pages_current.new_slab.___slab_alloc.__slab_alloc.kmem_cache_alloc_node.copy_process._do_fork.SyS_clone
1822 ± 14% 7e+03 8379 ±100% latency_stats.sum.kthread_park.watchdog_park_threads.watchdog_enable_all_cpus.proc_watchdog_update.proc_watchdog_common.proc_nmi_watchdog.proc_sys_call_handler.proc_sys_write.__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
56.97 6% 60.40 turbostat.RAMWatt
199 -5% 190 turbostat.PkgWatt
42.23 -8% 38.97 turbostat.%Busy
1178 -8% 1084 turbostat.Avg_MHz
0.33 25% 0.41 perf-stat.ipc
18461.07 ± 6% 16% 21359.28 ± 5% perf-stat.instructions-per-iTLB-miss
2.532e+08 ± 4% 12% 2.842e+08 ± 4% perf-stat.node-stores
26.10 10% 28.63 perf-stat.cache-miss-rate%
0.11 8% 0.12 perf-stat.dTLB-store-miss-rate%
62.92 3% 65.00 perf-stat.iTLB-load-miss-rate%
66.08 -5% 62.52 perf-stat.node-store-miss-rate%
1.423e+08 -8% 1.305e+08 perf-stat.dTLB-store-misses
0.11 ± 5% -12% 0.10 ± 6% perf-stat.dTLB-load-miss-rate%
8.695e+09 ± 4% -13% 7.608e+09 ± 4% perf-stat.cache-references
2.252e+11 ± 6% -14% 1.937e+11 ± 6% perf-stat.dTLB-loads
2.32e+11 ± 7% -14% 1.992e+11 ± 3% perf-stat.branch-instructions
1.275e+11 -15% 1.081e+11 perf-stat.dTLB-stores
9.431e+11 ± 6% -16% 7.953e+11 ± 3% perf-stat.instructions
2.534e+08 ± 7% -25% 1.906e+08 perf-stat.dTLB-load-misses
51086698 -27% 37318716 ± 5% perf-stat.iTLB-load-misses
0.48 -29% 0.34 perf-stat.branch-miss-rate%
4540 ± 14% -30% 3164 perf-stat.major-faults
2.86e+12 ± 5% -32% 1.931e+12 ± 3% perf-stat.cpu-cycles
30105185 ± 3% -33% 20073386 ± 3% perf-stat.iTLB-loads
1.12e+09 ± 5% -39% 6.822e+08 ± 4% perf-stat.branch-misses
17364 ± 10% -42% 10055 ± 11% perf-stat.cpu-migrations
vm-scalability.time.system_time
850 ++------------------------------------------**-------*-*--------------+
| .*. + + + + .*. *.*.* |
800 *+*.*.*.**.*.*.* *. * .*. .*.* *.*.* *.*.* * * + .*
750 ++ * : * * : : * |
| + : : : |
700 ++ * * |
650 ++ |
| |
600 ++ |
550 ++ O O |
| O O O O O O O O O |
500 O+ O O O O O O O |
450 ++O O O |
| O |
400 ++--------------------------------------------------------------------+
perf-stat.ipc
0.5 ++-------------------------------------------------------------------+
0.45 ++O O |
O O OO O O O O O O O O O |
0.4 ++ OO O O O O O O |
0.35 ++ .*. *. *. |
*.*.*.**.*.*.* *.*.* *.*.*.*.*.* * *.*.* *.*.*.*.*.**.*.*.*
0.3 ++ : : : : |
0.25 ++ : : : : |
0.2 ++ : : : : |
| : : : : |
0.15 ++ : : : : |
0.1 ++ : : : : |
| : : : : |
0.05 ++ : : : : |
0 ++-------------------------------------*-*------*-*------------------+
vm-scalability.time.elapsed_time
36 ++---------------------------------------------------------------------+
| .* .*. |
34 ++ .*. .* + .* **. .*.*.*. |
32 *+*.*.*.* **. .*.*.*. .*. .*. .*.* .*.*.* *.*.* * *.*
| * * * * * |
30 ++ |
| |
28 ++ |
| |
26 ++ O O |
24 ++ O O O O O O O |
O O O O OO O O O O O O |
22 ++O |
| O |
20 ++---------------------------------------------------------------------+
vm-scalability.time.elapsed_time.max
36 ++---------------------------------------------------------------------+
| .* .*. |
34 ++ .*. .* + .* **. .*.*.*. |
32 *+*.*.*.* **. .*.*.*. .*. .*. .*.* .*.*.* *.*.* * *.*
| * * * * * |
30 ++ |
| |
28 ++ |
| |
26 ++ O O |
24 ++ O O O O O O O |
O O O O OO O O O O O O |
22 ++O |
| O |
20 ++---------------------------------------------------------------------+
vmstat.system.in
7e+06 ++----------------------------------------------------------------+
| * *. |
6.5e+06 ++ * .*. .* .*. + : .*. + * .*.*.* |
6e+06 ++ * + + * ** + * * : * * * *. .*
|.*.* + * *.*.* + *. : :: * |
5.5e+06 *+ : .* :.* * * |
5e+06 ++ :.* * |
| * |
4.5e+06 ++ |
4e+06 ++ |
| |
3.5e+06 ++ |
3e+06 ++ O OO O O OO O |
O O OO O O O O O O O O OO |
2.5e+06 ++--------------O-------------------------------------------------+
interrupts.CAL:Function_call_interrupts
2.4e+08 ++----------------------------------------------------------------+
| .*.*.* .*.*.*.* .*.* |
2.2e+08 ++ .*. .*. *.* * + * *.* *. .*
2e+08 *+*. .*.*.** *.*. .* * + .* * * |
| ** * * * |
1.8e+08 ++ :+ |
1.6e+08 ++ * |
| |
1.4e+08 ++ |
1.2e+08 ++ |
| |
1e+08 ++ |
8e+07 ++ O OO O O OO |
O O OO O O O O O O O OO O |
6e+07 ++------------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
3 years, 4 months
- 1
- 0
[mmap_sem] e6b574fbfd: BUG mm_struct (Not tainted): Invalid object pointer 0xffff88001f347128
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 ms
commit e6b574fbfde17bd8fceb374bcb3be55c3ee23d2e
Author: Paul E. McKenney <paulmck(a)linux.vnet.ibm.com>
AuthorDate: Wed May 24 13:27:33 2017 -0700
Commit: Paul E. McKenney <paulmck(a)linux.vnet.ibm.com>
CommitDate: Wed May 24 16:53:50 2017 -0700
mmap_sem: Use percpu_rw_sema
This commit substitutes a percpu_rw_sema for mmap_sem's normal rw_sema.
Signed-off-by: Paul E. McKenney <paulmck(a)linux.vnet.ibm.com>
faaf16f1c1 percpu-rwsem: Add percpu_downgrade_write()
e6b574fbfd mmap_sem: Use percpu_rw_sema
851deed7a2 mmap_sem: Disable per-CPU nature of mmap_sem at build time
+-------------------------------------------------------+------------+------------+------------+
| | faaf16f1c1 | e6b574fbfd | 851deed7a2 |
+-------------------------------------------------------+------------+------------+------------+
| boot_successes | 102 | 0 | 0 |
| boot_failures | 2 | 83 | 66 |
| BUG:kernel_hang_in_test_stage | 2 | | |
| BUG_mm_struct(Not_tainted):Invalid_object_pointer | 0 | 53 | 22 |
| INFO:Slab#objects=#used=#fp=0x(null)flags= | 0 | 41 | 22 |
| WARNING:at_kernel/rcu/tree.c:#rcu_process_callbacks | 0 | 45 | 18 |
| BUG_mm_struct(Tainted:G_B_W):Invalid_object_pointer | 0 | 16 | 13 |
| general_protection_fault:#[##] | 0 | 75 | 63 |
| Kernel_panic-not_syncing:Fatal_exception_in_interrupt | 0 | 76 | 63 |
| INFO:Slab#objects=#used=#fp=#flags= | 0 | 28 | 16 |
| BUG_mm_struct(Not_tainted):Poison_overwritten | 0 | 7 | 8 |
| INFO:#-#.First_byte#instead_of | 0 | 10 | 11 |
| INFO:Allocated_in_mm_alloc_age=#cpu=#pid= | 0 | 9 | 2 |
| INFO:Freed_in__mmdrop_age=#cpu=#pid= | 0 | 9 | 9 |
| INFO:Object#@offset=#fp= | 0 | 10 | 9 |
| BUG_mm_struct(Tainted:G_B):Poison_overwritten | 0 | 2 | |
| BUG_mm_struct(Tainted:G_B):Invalid_object_pointer | 0 | 2 | |
| kernel_BUG_at_kernel/rcu/sync.c | 0 | 1 | |
| invalid_opcode:#[##] | 0 | 1 | |
| BUG_dentry(Not_tainted):Poison_overwritten | 0 | 1 | |
| INFO:Allocated_in__d_alloc_age=#cpu=#pid= | 0 | 1 | |
| INFO:Freed_in__d_free_age=#cpu=#pid= | 0 | 1 | |
| INFO:Allocated_in_copy_process_age=#cpu=#pid= | 0 | 0 | 7 |
| BUG_cred_jar(Not_tainted):Poison_overwritten | 0 | 0 | 2 |
| INFO:Allocated_in_prepare_creds_age=#cpu=#pid= | 0 | 0 | 2 |
| INFO:Freed_in_put_cred_rcu_age=#cpu=#pid= | 0 | 0 | 2 |
| INFO:Object#@offset=#fp=0x(null) | 0 | 0 | 2 |
| BUG_mm_struct(Tainted:G_B_W):Poison_overwritten | 0 | 0 | 1 |
+-------------------------------------------------------+------------+------------+------------+
[ 16.671544] rodata_test: all tests were successful
[ 16.700727] random: init: uninitialized urandom read (12 bytes read)
[ 16.700727] random: init: uninitialized urandom read (12 bytes read)
[ 16.738016] =============================================================================
[ 16.738016] =============================================================================
[ 16.743061] BUG mm_struct (Not tainted): Invalid object pointer 0xffff88001f347128
[ 16.743061] BUG mm_struct (Not tainted): Invalid object pointer 0xffff88001f347128
[ 16.745429] -----------------------------------------------------------------------------
[ 16.745429]
[ 16.745429] -----------------------------------------------------------------------------
[ 16.745429]
[ 16.748415] Disabling lock debugging due to kernel taint
[ 16.748415] Disabling lock debugging due to kernel taint
[ 16.750091] INFO: Slab 0xffffea00007cd000 objects=4 used=4 fp=0x (null) flags=0x1fffc0000008101
[ 16.750091] INFO: Slab 0xffffea00007cd000 objects=4 used=4 fp=0x (null) flags=0x1fffc0000008101
[ 16.753026] CPU: 0 PID: 7 Comm: ksoftirqd/0 Tainted: G B 4.12.0-rc1-00007-ge6b574f #1
[ 16.753026] CPU: 0 PID: 7 Comm: ksoftirqd/0 Tainted: G B 4.12.0-rc1-00007-ge6b574f #1
[ 16.755804] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 16.755804] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 16.758954] Call Trace:
[ 16.758954] Call Trace:
[ 16.759730] dump_stack+0x79/0x96
[ 16.759730] dump_stack+0x79/0x96
[ 16.760794] slab_err+0xa8/0xd0
[ 16.760794] slab_err+0xa8/0xd0
[ 16.761796] ? free_debug_processing+0x40/0x3a0
[ 16.761796] ? free_debug_processing+0x40/0x3a0
[ 16.763232] ? lock_acquire+0x68/0xa0
[ 16.763232] ? lock_acquire+0x68/0xa0
[ 16.764390] free_debug_processing+0x223/0x3a0
[ 16.764390] free_debug_processing+0x223/0x3a0
[ 16.765789] ? rcu_process_callbacks+0x250/0x520
[ 16.765789] ? rcu_process_callbacks+0x250/0x520
[ 16.767232] __slab_free+0x1db/0x300
[ 16.767232] __slab_free+0x1db/0x300
[ 16.768351] ? sched_clock+0x13/0x20
[ 16.768351] ? sched_clock+0x13/0x20
[ 16.769471] ? sched_clock_cpu+0x11/0xc0
[ 16.769471] ? sched_clock_cpu+0x11/0xc0
[ 16.770705] ? __lock_acquire+0x29f/0x8c0
[ 16.770705] ? __lock_acquire+0x29f/0x8c0
[ 16.772168] ? rcu_process_callbacks+0x250/0x520
[ 16.772168] ? rcu_process_callbacks+0x250/0x520
[ 16.773590] kfree+0x11b/0x160
[ 16.773590] kfree+0x11b/0x160
[ 16.774546] ? kfree+0x11b/0x160
[ 16.774546] ? kfree+0x11b/0x160
[ 16.775561] rcu_process_callbacks+0x250/0x520
[ 16.775561] rcu_process_callbacks+0x250/0x520
[ 16.776946] __do_softirq+0xfb/0x230
[ 16.776946] __do_softirq+0xfb/0x230
[ 16.778062] ? smpboot_thread_fn+0x2f/0x1f0
[ 16.778062] ? smpboot_thread_fn+0x2f/0x1f0
[ 16.779362] ? smpboot_thread_fn+0x128/0x1f0
[ 16.779362] ? smpboot_thread_fn+0x128/0x1f0
[ 16.780682] run_ksoftirqd+0x25/0x90
[ 16.780682] run_ksoftirqd+0x25/0x90
[ 16.781797] smpboot_thread_fn+0x123/0x1f0
[ 16.781797] smpboot_thread_fn+0x123/0x1f0
[ 16.783075] kthread+0x10d/0x140
[ 16.783075] kthread+0x10d/0x140
[ 16.784100] ? sort_range+0x20/0x20
[ 16.784100] ? sort_range+0x20/0x20
[ 16.785201] ? kthread_create_on_node+0x40/0x40
[ 16.785201] ? kthread_create_on_node+0x40/0x40
[ 16.786616] ret_from_fork+0x2c/0x40
[ 16.786616] ret_from_fork+0x2c/0x40
[ 16.787771] FIX mm_struct: Object at 0xffff88001f347128 not freed
[ 16.787771] FIX mm_struct: Object at 0xffff88001f347128 not freed
[ 16.794038] ------------[ cut here ]------------
[ 16.794038] ------------[ cut here ]------------
[ 16.795508] WARNING: CPU: 0 PID: 7 at kernel/rcu/tree.c:2834 rcu_process_callbacks+0x519/0x520
[ 16.795508] WARNING: CPU: 0 PID: 7 at kernel/rcu/tree.c:2834 rcu_process_callbacks+0x519/0x520
[ 16.798740] Modules linked in:
[ 16.798740] Modules linked in:
[ 16.799703] CPU: 0 PID: 7 Comm: ksoftirqd/0 Tainted: G B 4.12.0-rc1-00007-ge6b574f #1
[ 16.799703] CPU: 0 PID: 7 Comm: ksoftirqd/0 Tainted: G B 4.12.0-rc1-00007-ge6b574f #1
[ 16.802490] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 16.802490] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 16.805640] task: ffff88001d279000 task.stack: ffff88001d280000
[ 16.805640] task: ffff88001d279000 task.stack: ffff88001d280000
[ 16.807481] RIP: 0010:rcu_process_callbacks+0x519/0x520
[ 16.807481] RIP: 0010:rcu_process_callbacks+0x519/0x520
[ 16.809114] RSP: 0018:ffff88001d283dd0 EFLAGS: 00010002
[ 16.809114] RSP: 0018:ffff88001d283dd0 EFLAGS: 00010002
[ 16.810764] RAX: 0000000000000000 RBX: ffff88001d5da840 RCX: 7fffffffffffffff
[ 16.810764] RAX: 0000000000000000 RBX: ffff88001d5da840 RCX: 7fffffffffffffff
[ 16.813001] RDX: ffffffffffffd801 RSI: ffff88001d283df8 RDI: ffff88001d5da878
[ 16.813001] RDX: ffffffffffffd801 RSI: ffff88001d283df8 RDI: ffff88001d5da878
[ 16.815240] RBP: ffff88001d283e40 R08: ffff88001d5d9b18 R09: ffff88001f2c06f8
[ 16.815240] RBP: ffff88001d283e40 R08: ffff88001d5d9b18 R09: ffff88001f2c06f8
[ 16.817461] R10: ffff88001d283c08 R11: 00000000005b8d80 R12: ffffffff81c72000
[ 16.817461] R10: ffff88001d283c08 R11: 00000000005b8d80 R12: ffffffff81c72000
[ 16.819675] R13: ffff88001d5da878 R14: ffffffff81c36960 R15: fffffffffffffffa
[ 16.819675] R13: ffff88001d5da878 R14: ffffffff81c36960 R15: fffffffffffffffa
[ 16.821891] FS: 0000000000000000(0000) GS:ffff88001d400000(0000) knlGS:0000000000000000
[ 16.821891] FS: 0000000000000000(0000) GS:ffff88001d400000(0000) knlGS:0000000000000000
[ 16.824403] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 16.824403] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 16.826199] CR2: 00007fa0559ed000 CR3: 000000001f33c000 CR4: 00000000000006b0
[ 16.826199] CR2: 00007fa0559ed000 CR3: 000000001f33c000 CR4: 00000000000006b0
[ 16.828410] Call Trace:
[ 16.828410] Call Trace:
[ 16.829192] __do_softirq+0xfb/0x230
[ 16.829192] __do_softirq+0xfb/0x230
[ 16.830321] ? smpboot_thread_fn+0x2f/0x1f0
[ 16.830321] ? smpboot_thread_fn+0x2f/0x1f0
[ 16.831637] ? smpboot_thread_fn+0x128/0x1f0
[ 16.831637] ? smpboot_thread_fn+0x128/0x1f0
[ 16.832972] run_ksoftirqd+0x25/0x90
[ 16.832972] run_ksoftirqd+0x25/0x90
[ 16.834094] smpboot_thread_fn+0x123/0x1f0
[ 16.834094] smpboot_thread_fn+0x123/0x1f0
[ 16.835377] kthread+0x10d/0x140
[ 16.835377] kthread+0x10d/0x140
[ 16.836393] ? sort_range+0x20/0x20
[ 16.836393] ? sort_range+0x20/0x20
[ 16.837484] ? kthread_create_on_node+0x40/0x40
[ 16.837484] ? kthread_create_on_node+0x40/0x40
[ 16.838907] ret_from_fork+0x2c/0x40
[ 16.838907] ret_from_fork+0x2c/0x40
[ 16.840031] Code: 15 25 c5 b5 00 48 89 93 c0 00 00 00 e9 37 ff ff ff 0f 0b 0f 0b 0f ff e9 d4 fc ff ff 0f 0b 0f ff e9 db fc ff ff 0f 0b 0f 0b 0f 0b <0f> ff e9 57 ff ff ff 40 f6 c7 07 0f 85 2e 01 00 00 48 c7 07 00
[ 16.840031] Code: 15 25 c5 b5 00 48 89 93 c0 00 00 00 e9 37 ff ff ff 0f 0b 0f 0b 0f ff e9 d4 fc ff ff 0f 0b 0f ff e9 db fc ff ff 0f 0b 0f 0b 0f 0b <0f> ff e9 57 ff ff ff 40 f6 c7 07 0f 85 2e 01 00 00 48 c7 07 00
[ 16.845901] ---[ end trace e16dc72b4b646bff ]---
[ 16.845901] ---[ end trace e16dc72b4b646bff ]---
# HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start c8516dede5f430fd17b1387f58c6d6ca62e7cb77 5ed02dbb497422bf225783f46e6eadd237d23d6b --
git bisect bad 73c1f4cbb1f9a2fe08b74af199de6e3b820291ee # 06:03 B 0 11 23 0 Merge 'linux-review/Ganesh-Goudar/cxgb4-avoid-enabling-napi-twice-to-the-same-queue/20170601-001445' into devel-catchup-201706010512
git bisect bad 00d036be57c5c608df7d2b76fe657078dda4e4c2 # 06:14 B 0 11 23 0 Merge 'khilman-linux-amlogic/v4.13/dt64' into devel-catchup-201706010512
git bisect bad 5866e5de4af2f0647481aea0fa04c7cf58af8e8e # 06:24 B 0 3 15 0 Merge 'omap/omap-for-v4.13/dt' into devel-catchup-201706010512
git bisect good 75deae7b929d2c37953d7b1266bde44b46497a5d # 06:45 G 11 0 0 0 Merge 'rcu/dev.2017.05.31a' into devel-catchup-201706010512
git bisect bad 9c400b333615ad2db2ccf1ceb61c9b565708a908 # 06:59 B 0 4 16 0 Merge 'rcu/ms' into devel-catchup-201706010512
git bisect good 9c071a698a0c9f9df78b1acd60204151370f9eb4 # 07:18 G 11 0 0 0 Merge 'rcu/rcu/next' into devel-catchup-201706010512
git bisect good 0c7d987959942180fd1f943702030a5e58916760 # 07:38 G 11 0 0 0 Add percpu_down_write_killable() and percpu_down_write_nested()
git bisect good faaf16f1c1097c5589a46f30c45f0f9be53923fa # 08:02 G 10 0 0 0 percpu-rwsem: Add percpu_downgrade_write()
git bisect bad 851deed7a22b72f2be5babed461b6cdd6c78d7b2 # 08:02 B 0 8 20 0 mmap_sem: Disable per-CPU nature of mmap_sem at build time
git bisect bad e6b574fbfde17bd8fceb374bcb3be55c3ee23d2e # 08:30 B 0 1 18 5 mmap_sem: Use percpu_rw_sema
# first bad commit: [e6b574fbfde17bd8fceb374bcb3be55c3ee23d2e] mmap_sem: Use percpu_rw_sema
git bisect good faaf16f1c1097c5589a46f30c45f0f9be53923fa # 08:48 G 31 0 0 0 percpu-rwsem: Add percpu_downgrade_write()
# extra tests with CONFIG_DEBUG_INFO_REDUCED
git bisect bad e6b574fbfde17bd8fceb374bcb3be55c3ee23d2e # 09:14 B 0 11 23 0 mmap_sem: Use percpu_rw_sema
# extra tests on HEAD of linux-devel/devel-catchup-201706010512
git bisect bad c8516dede5f430fd17b1387f58c6d6ca62e7cb77 # 09:14 B 0 130 145 0 0day head guard for 'devel-catchup-201706010512'
# extra tests on tree/branch rcu/ms
git bisect bad 851deed7a22b72f2be5babed461b6cdd6c78d7b2 # 09:18 B 0 61 73 0 mmap_sem: Disable per-CPU nature of mmap_sem at build time
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
3 years, 4 months
- 1
- 0