[printk] fbc14616f4: BUG:kernel_reboot-without-warning_in_test_stage
by kernel test robot
FYI, we noticed the following commit:
commit: fbc14616f483788afabe77d05bfb99883dc66c73 ("printk: enable printk offloading")
url: https://github.com/0day-ci/linux/commits/Sergey-Senozhatsky/printk-introd...
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 Westmere -m 512M
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+-------------------------------------------------+------------+------------+
| | fd8b6b120c | fbc14616f4 |
+-------------------------------------------------+------------+------------+
| boot_successes | 8 | 8 |
| boot_failures | 0 | 6 |
| BUG:kernel_reboot-without-warning_in_test_stage | 0 | 4 |
| BUG:kernel_hang_in_test_stage | 0 | 2 |
+-------------------------------------------------+------------+------------+
[ 21.009531] VFS: Warning: trinity-c2 using old stat() call. Recompile your binary.
[ 21.148898] VFS: Warning: trinity-c0 using old stat() call. Recompile your binary.
[ 22.298208] warning: process `trinity-c2' used the deprecated sysctl system call with
Elapsed time: 310
BUG: kernel reboot-without-warning in test stage
initrds=(
/osimage/yocto/yocto-tiny-i386-2016-04-22.cgz
/lkp/scheduled/vm-kbuild-yocto-ia32-9/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-fbc14616f483788afabe77d05bfb99883dc66c73-20170331-102676-cxuzks-0.cgz
/lkp/lkp/lkp-i386.cgz
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,
Kernel Test Robot
3 years, 8 months
[lkp-robot] [sched, cpumask] 9475ceda45: -6% regression of hackbench.throughput
by kernel test robot
Greeting,
We noticed a -6% regression of hackbench.throughput due to commit:
commit: 9475ceda453545fc55b2ccf30b1fbed0e590fdca ("sched,cpumask: Export for_each_cpu_wrap()")
https://git.kernel.org/cgit/linux/kernel/git/peterz/queue.git schd/wip
in testcase: hackbench
on test machine: 112 threads Skylake with 64G memory
with following parameters:
nr_threads: 50%
mode: threads
ipc: socket
cpufreq_governor: performance
test-description: Hackbench is both a benchmark and a stress test for the Linux kernel scheduler.
test-url: https://github.com/linux-test-project/ltp/blob/master/testcases/kernel/sc...
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: hackbench/50%-threads-socket-performance/lkp-skl-sp1
5b03a19dde4a5534 9475ceda453545fc55b2ccf30b
---------------- --------------------------
%stddev change %stddev
\ | \
138234 -6% 130058 hackbench.throughput
71906 -9% 65645 hackbench.time.system_time
666 -9% 607 hackbench.time.elapsed_time
666 -9% 607 hackbench.time.elapsed_time.max
34894 -14% 29944 hackbench.time.minor_page_faults
2042 -15% 1726 hackbench.time.user_time
6.75e+09 -16% 5.649e+09 hackbench.time.involuntary_context_switches
7.89e+09 -17% 6.543e+09 hackbench.time.voluntary_context_switches
866028 ± 3% -6% 813106 interrupts.CAL:Function_call_interrupts
973260 -10% 877436 vmstat.system.in
21951574 -9% 20069826 vmstat.system.cs
18.04 ± 18% 67% 30.12 ± 22% perf-stat.iTLB-load-miss-rate%
320730 ± 19% -34% 213019 ± 23% perf-stat.instructions-per-iTLB-miss
0.65 5% 0.68 perf-stat.ipc
2.672e+13 -6% 2.503e+13 perf-stat.dTLB-loads
9.582e+13 -4% 9.194e+13 perf-stat.instructions
2.007e+13 -10% 1.814e+13 perf-stat.branch-instructions
1.48e+14 -9% 1.348e+14 perf-stat.cpu-cycles
1251190 -8% 1152506 perf-stat.page-faults
1251190 -8% 1152609 perf-stat.minor-faults
0.35 -11% 0.31 perf-stat.branch-miss-rate%
3.1e+08 ± 19% 51% 4.668e+08 ± 31% perf-stat.iTLB-load-misses
2.754e+08 -17% 2.294e+08 ± 4% perf-stat.node-stores
0.00 -42% 0.00 perf-stat.dTLB-load-miss-rate%
3.983e+08 -46% 2.15e+08 perf-stat.dTLB-load-misses
1.467e+10 -17% 1.222e+10 perf-stat.context-switches
6.956e+10 -19% 5.627e+10 perf-stat.branch-misses
1.245e+12 -18% 1.027e+12 perf-stat.cache-references
4.977e+09 -17% 4.132e+09 perf-stat.cpu-migrations
21342293 ± 4% -17% 17740194 ± 3% perf-stat.dTLB-store-misses
0.00 ± 4% -17% 0.00 ± 3% perf-stat.dTLB-store-miss-rate%
1.41e+09 ± 8% -25% 1.057e+09 ± 7% perf-stat.iTLB-loads
perf-stat.cache-references
1.3e+12 ++---------------------------------------------------------------+
| **. .**. *.*. *.*. *. *. *.*. * *.*. *. *. .**. |
1.25e+12 ++ * * * * * *. : * + : * *.**.**.*.* * **.*
1.2e+12 *+ * * |
| |
1.15e+12 ++ |
1.1e+12 ++ |
| |
1.05e+12 ++ OO O O O O O OO O |
1e+12 ++ O O OO OO |
| |
9.5e+11 ++ O O |
9e+11 O+OO O O O |
| O |
8.5e+11 ++---------------------------------------------------------------+
perf-stat.branch-instructions
2.1e+13 ++------------------------*---------------------------------------+
| *.*.**.*.**.**.*.* : |
2e+13 ++ : : .**.*.**.**.*.**.*.**.*
*.**.*.* *.**.*.**.*.**.* |
| |
1.9e+13 ++ O O |
| O O OO O O |
1.8e+13 ++ O O O O OO O O |
| |
1.7e+13 ++ |
| |
| |
1.6e+13 ++ O O |
O O O OO O O |
1.5e+13 ++----------------------------------------------------------------+
perf-stat.branch-misses
8e+10 ++----------------------------------------------------------------+
| *. |
7.5e+10 ++ *.**.* *.** |
| + : |
7e+10 ++ *. .** : *.* .**.*.**.*
*.**.*.* * *.**.*.**.*.**.*.* + *.**.* |
6.5e+10 ++ * |
| |
6e+10 ++ |
| O OO OO O OO O OO O O O |
5.5e+10 ++ O O |
O OO O O |
5e+10 ++ O O OO |
| |
4.5e+10 ++----------------------------------------------------------------+
perf-stat.context-switches
1.5e+10 ++---------------*--*-----*---------------------------------------+
*.**.*.**.*.**.* * *.*.* *.**.*.**.*.**.*.**.*.**.**.*.**.*.**.*
1.4e+10 ++ |
| |
| |
1.3e+10 ++ |
| O O O OO O O O OO |
1.2e+10 ++ O O O O O O |
| |
1.1e+10 ++ |
| |
| |
1e+10 O+OO O OO O OO |
| |
9e+09 ++----------------------------------------------------------------+
perf-stat.cpu-migrations
5.2e+09 ++------------------------*---------------------------------------+
5e+09 ++ .*.* .*.**.* .*.* + *. .*. .*
*.**.*.** * * *.**.*.**.*.**.*.**.*.* **.*.** ** |
4.8e+09 ++ |
4.6e+09 ++ |
| |
4.4e+09 ++ O O |
4.2e+09 ++ O O O O O O O O OO |
4e+09 ++ O O O O |
| |
3.8e+09 ++ |
3.6e+09 ++ |
| O O O |
3.4e+09 O+O O O O |
3.2e+09 ++-----O----------------------------------------------------------+
hackbench.throughput
145000 ++-----------------------------------------------------------------+
140000 *+**.*.* *. .**.*.*.**.*.**.*.**.*. *.*.**. .* |
| *.*.**.*.**.*.* * * *.**.* *.*
135000 ++ |
130000 ++ O OO O OO |
| O OO OO O O O O |
125000 ++ O |
120000 ++ |
115000 ++ |
| |
110000 ++ O |
105000 ++ |
| O O OO |
100000 O+ O O O |
95000 ++-----------------------------------------------------------------+
hackbench.time.user_time
2200 ++-------------------------------------------------------------------+
| |
2100 *+**.*.*. *.*.* .*.**.*.*.**.*.**.*.*. *.|
2000 ++ **.*.*.* *.* **.*.**.*.*.**.*.*.* *
| |
1900 ++ |
| |
1800 ++ |
| OO O OO O O OO O O OO O O |
1700 ++ O |
1600 ++ |
| |
1500 ++ O O |
O OO O O OO |
1400 ++-------------------------------------------------------------------+
hackbench.time.minor_page_faults
35000 *+**-*-*-**-*-**-*-**-*-*-**-*-**-*-**-*-*-**-*-**-*-**-*-*-**-*-**-*
34000 ++ |
| |
33000 ++ |
32000 ++ |
31000 ++ |
30000 ++ O O OO O O OO O OO O OO O O |
| |
29000 ++ |
28000 ++ |
27000 ++ |
26000 ++ |
| |
25000 O+OO O O OO O O |
24000 ++------------------------------------------------------------------+
[*] 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, 8 months
3361eef782: BUG:stack_guard_page_was_hit_at#(stack_is#..#)
by kernel test robot
FYI, we noticed the following commit:
commit: 3361eef782f35ae681d265270067ee7effc37ea4 ("Add commands to create or update a superblock")
https://git.kernel.org/cgit/linux/kernel/git/dhowells/linux-fs.git mount-context
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -m 420M
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+------------------------------------------------------------------+------------+------------+
| | f9d949ec8b | 3361eef782 |
+------------------------------------------------------------------+------------+------------+
| boot_successes | 6 | 0 |
| boot_failures | 2 | 16 |
| invoked_oom-killer:gfp_mask=0x | 2 | |
| Mem-Info | 2 | |
| Kernel_panic-not_syncing:Out_of_memory_and_no_killable_processes | 2 | |
| BUG:stack_guard_page_was_hit_at#(stack_is#..#) | 0 | 16 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 16 |
+------------------------------------------------------------------+------------+------------+
[ 0.230249] BUG: stack guard page was hit at ffffc900000d4dae (stack is ffffc900000d0000..ffffc900000d3fff)
[ 0.232043] kernel stack overflow (page fault): 0000 [#1] SMP
[ 0.233048] Modules linked in:
[ 0.233773] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.12.0-rc1-00021-g3361eef #10
[ 0.236629] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 0.238465] task: ffff88000ba38000 task.stack: ffffc900000d0000
[ 0.239558] RIP: 0010:legacy_monolithic_mount_data+0x39/0xb0
[ 0.240542] RSP: 0000:ffffc900000d3d50 EFLAGS: 00010282
[ 0.241482] RAX: ffff88000bbea000 RBX: ffffc900000d3db6 RCX: 00000000000003cf
[ 0.242630] RDX: 657a697365676170 RSI: 00000000000001b0 RDI: ffff88000bbea008
[ 0.243814] RBP: ffffc900000d3d60 R08: ffff88000be1f300 R09: ffff88000b801b40
[ 0.244994] R10: ffff88000bbea000 R11: ffffc900000d3dc3 R12: ffff88000bbe2900
[ 0.246196] R13: ffffc900000d3db6 R14: ffffffff8242f5e0 R15: 0000000000000800
[ 0.247389] FS: 0000000000000000(0000) GS:ffff88000be00000(0000) knlGS:0000000000000000
[ 0.249056] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 0.250078] CR2: ffffc900000d4dae CR3: 0000000001e09000 CR4: 00000000000006f0
[ 0.251217] Call Trace:
[ 0.251912] vfs_kern_mount+0x78/0xf0
[ 0.252693] kern_mount_data+0x19/0x30
[ 0.253497] init_hugetlbfs_fs+0xe3/0x15a
[ 0.254313] ? proc_create_data+0x76/0xb0
[ 0.255132] ? init_ramfs_fs+0x25/0x25
[ 0.255975] ? set_debug_rodata+0x17/0x17
[ 0.256822] do_one_initcall+0x43/0x170
[ 0.257620] ? set_debug_rodata+0x17/0x17
[ 0.258438] kernel_init_freeable+0x1a7/0x22f
[ 0.259288] ? rest_init+0x90/0x90
[ 0.260076] kernel_init+0xe/0x110
[ 0.260835] ret_from_fork+0x2c/0x40
[ 0.261610] Code: 00 00 48 85 f6 74 7f 55 48 89 e5 41 54 53 48 8b 47 58 48 89 f3 49 89 fc 48 85 c0 74 42 48 8b 13 48 8d 78 08 48 83 e7 f8 48 89 10 <48> 8b 93 f8 0f 00 00 48 89 90 f8 0f 00 00 48 29 f8 48 29 c3 05
[ 0.264701] RIP: legacy_monolithic_mount_data+0x39/0xb0 RSP: ffffc900000d3d50
[ 0.265858] ---[ end trace c9f5c67c1f791f62 ]---
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,
Kernel Test Robot
3 years, 8 months
[lkp-robot] [asm] c1aad8dcc4: BUG:kernel_hang_in_boot_stage
by kernel test robot
FYI, we noticed the following commit:
commit: c1aad8dcc49382399f48541dc47b6e30b0ef1b62 ("asm-generic: zero in __get_user(), not __get_user_fn()")
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 -smp 2 -m 420M
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+-----------------------------------------------------------------------+------------+------------+
| | 3fb5007528 | c1aad8dcc4 |
+-----------------------------------------------------------------------+------------+------------+
| boot_successes | 0 | 0 |
| boot_failures | 13 | 14 |
| page_allocation_failure:order:#,mode:#(__GFP_NORETRY),nodemask=(null) | 9 | |
| Mem-Info | 9 | |
| WARNING:at_kernel/trace/trace.c:#trace_init | 9 | |
| page_allocation_failure:order:#,mode:#(__GFP_NOTRACK),nodemask=(null) | 2 | |
| Kernel_panic-not_syncing:cannot_initialize_percpu_area(err=-#) | 4 | |
| BUG:kernel_hang_in_boot_stage | 0 | 14 |
+-----------------------------------------------------------------------+------------+------------+
[ 0.000000] BRK [0x095c5000, 0x095c5fff] PGTABLE
[ 0.000000] BRK [0x095c6000, 0x095c6fff] PGTABLE
[ 0.000000] BRK [0x095c7000, 0x095c7fff] PGTABLE
Elapsed time: 440
BUG: kernel hang in boot stage
initrds=(
/osimage/yocto/yocto-tiny-i386-2016-04-22.cgz
/lkp/scheduled/vm-lkp-nhm-dp1-yocto-ia32-5/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-c1aad8dcc49382399f48541dc47b6e30b0ef1b62-20170515-127570-ve362v-0.cgz
/lkp/lkp/lkp-i386.cgz
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, 8 months
[lkp-robot] [MM] 3e38a07a66: ltp.proc01.fail
by kernel test robot
FYI, we noticed the following commit:
commit: 3e38a07a6692b3f1459822767872c79922d08593 ("MM: allow per-cpu vmstat_worker configuration")
url: https://github.com/0day-ci/linux/commits/Marcelo-Tosatti/MM-remove-unused...
in testcase: ltp
with following parameters:
test: fs
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):
[ 1195.162826] cmdline="proc01 -m 128"
[ 1195.162827]
[ 1195.163364] contacts=""
[ 1195.163365]
[ 1195.163923] analysis=exit
[ 1195.163924]
[ 1195.164566] <<<test_output>>>
[ 1195.164567]
[ 1195.178330] ICMPv6: process `proc01' is using deprecated sysctl (syscall) net.ipv6.neigh.default.base_reachable_time - use net.ipv6.neigh.default.base_reachable_time_ms instead
[ 1195.180105] nr_pdflush_threads exported in /proc is scheduled for removal
[ 1195.789360] proc01 0 TINFO : /proc/sys/fs/binfmt_misc/register: is write-only.
[ 1195.789363]
[ 1195.795586] proc01 0 TINFO : /proc/sys/net/ipv6/conf/all/stable_secret: known issue: errno=EIO(5): Input/output error
[ 1195.795589]
[ 1195.800163] proc01 0 TINFO : /proc/sys/net/ipv6/conf/default/stable_secret: known issue: errno=EIO(5): Input/output error
[ 1195.800166]
[ 1195.806069] proc01 0 TINFO : /proc/sys/net/ipv6/conf/eth0/stable_secret: known issue: errno=EIO(5): Input/output error
[ 1195.806073]
[ 1195.812436] proc01 0 TINFO : /proc/sys/net/ipv6/conf/eth1/stable_secret: known issue: errno=EIO(5): Input/output error
[ 1195.812440]
[ 1195.816969] proc01 0 TINFO : /proc/sys/net/ipv6/conf/lo/stable_secret: known issue: errno=EIO(5): Input/output error
[ 1195.816972]
[ 1195.822113] proc01 1 TFAIL : proc01.c:396: read failed: /proc/sys/vm/stat_refresh: errno=EINVAL(22): Invalid argument
[ 1195.822116]
[ 1195.826668] proc01 0 TINFO : /proc/kmsg: known issue: errno=EAGAIN/EWOULDBLOCK(11): Resource temporarily unavailable
[ 1195.826672]
[ 1195.829274] proc01 0 TINFO : /proc/sysrq-trigger: is write-only.
[ 1195.829276]
[ 1195.832891] proc01 0 TINFO : /proc/self/task/14436/mem: known issue: errno=EIO(5): Input/output error
[ 1195.832894]
[ 1195.835953] proc01 0 TINFO : /proc/self/task/14436/clear_refs: is write-only.
[ 1195.835956]
[ 1195.839587] proc01 0 TINFO : /proc/self/task/14436/pagemap: reached maxmbytes (-m)
[ 1195.839591]
[ 1195.843025] proc01 0 TINFO : /proc/self/mem: known issue: errno=EIO(5): Input/output error
[ 1195.843028]
[ 1195.845599] proc01 0 TINFO : /proc/self/clear_refs: is write-only.
[ 1195.845602]
[ 1195.848837] proc01 0 TINFO : /proc/self/pagemap: reached maxmbytes (-m)
[ 1195.848841]
[ 1195.851717] proc01 2 TFAIL : proc01.c:465: readproc() failed with 1 errors.
[ 1195.851720]
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, 8 months
[lkp-robot] eb0e7965df: BUG:stack_guard_page_was_hit_at#(stack_is#..#)
by kernel test robot
FYI, we noticed the following commit:
commit: eb0e7965df102d1f17bacaf096c6459a655cc4b6 ("Add commands to create or update a superblock")
https://git.kernel.org/cgit/linux/kernel/git/dhowells/linux-fs.git mount-context
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 -m 420M
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+------------------------------------------------------------------+------------+------------+
| | 767a8bf9ed | eb0e7965df |
+------------------------------------------------------------------+------------+------------+
| boot_successes | 2 | 0 |
| boot_failures | 2 | 18 |
| invoked_oom-killer:gfp_mask=0x | 2 | |
| Mem-Info | 2 | |
| Kernel_panic-not_syncing:Out_of_memory_and_no_killable_processes | 2 | |
| BUG:stack_guard_page_was_hit_at#(stack_is#..#) | 0 | 18 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 18 |
| BUG:kernel_in_stage | 0 | 1 |
+------------------------------------------------------------------+------------+------------+
[ 0.212451] BUG: stack guard page was hit at ffffc900000d4dae (stack is ffffc900000d0000..ffffc900000d3fff)
[ 0.213791] kernel stack overflow (page fault): 0000 [#1] SMP
[ 0.214574] Modules linked in:
[ 0.215121] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.11.0-04409-geb0e796 #4
[ 0.216274] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 0.217649] task: ffff88000b9b0000 task.stack: ffffc900000d0000
[ 0.218413] RIP: 0010:legacy_monolithic_mount_data+0x39/0xb0
[ 0.219157] RSP: 0000:ffffc900000d3d50 EFLAGS: 00010282
[ 0.219888] RAX: ffff88000bbd2000 RBX: ffffc900000d3db6 RCX: 00000000000003c7
[ 0.220756] RDX: 657a697365676170 RSI: 00000000000001b0 RDI: ffff88000bbd2008
[ 0.221615] RBP: ffffc900000d3d60 R08: ffff88000be1e880 R09: ffff88000b801b40
[ 0.222468] R10: ffff88000bbd2000 R11: ffffc900000d3dc3 R12: ffff88000bbce800
[ 0.223343] R13: ffffc900000d3db6 R14: ffffffff823591a0 R15: 0000000000000800
[ 0.224240] FS: 0000000000000000(0000) GS:ffff88000be00000(0000) knlGS:0000000000000000
[ 0.225466] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 0.226213] CR2: ffffc900000d4dae CR3: 0000000001e09000 CR4: 00000000000006f0
[ 0.227070] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[ 0.227947] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[ 0.228801] Call Trace:
[ 0.229305] vfs_kern_mount+0x78/0xf0
[ 0.229898] kern_mount_data+0x19/0x30
[ 0.230500] init_hugetlbfs_fs+0xde/0x155
[ 0.231118] ? proc_create_data+0x76/0xb0
[ 0.231737] ? init_ramfs_fs+0x20/0x20
[ 0.232359] ? set_debug_rodata+0x12/0x12
[ 0.232978] do_one_initcall+0x43/0x170
[ 0.233585] ? set_debug_rodata+0x12/0x12
[ 0.234226] kernel_init_freeable+0x1a2/0x22a
[ 0.234876] ? rest_init+0x90/0x90
[ 0.235447] kernel_init+0xe/0x100
[ 0.236022] ret_from_fork+0x2c/0x40
[ 0.236611] Code: 00 00 48 85 f6 74 7f 55 48 89 e5 41 54 53 48 8b 47 58 48 89 f3 49 89 fc 48 85 c0 74 42 48 8b 13 48 8d 78 08 48 83 e7 f8 48 89 10 <48> 8b 93 f8 0f 00 00 48 89 90 f8 0f 00 00 48 29 f8 48 29 c3 05
[ 0.238964] RIP: legacy_monolithic_mount_data+0x39/0xb0 RSP: ffffc900000d3d50
[ 0.239821] ---[ end trace d151a8672ac0b6e9 ]---
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, 8 months
[lkp-robot] [workqueue] 10302bc81d: WARNING:at_kernel/workqueue.c:#__queue_delayed_work
by kernel test robot
FYI, we noticed the following commit:
commit: 10302bc81d758d3b4e214a05ad3aef9ef38dcca3 ("workqueue: switch to modern timers")
git://git.infradead.org/users/hch/vfs timers
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):
+-----------------------------------------------------+------------+------------+
| | c1ea1bf983 | 10302bc81d |
+-----------------------------------------------------+------------+------------+
| boot_successes | 6 | 1 |
| boot_failures | 0 | 8 |
| WARNING:at_kernel/workqueue.c:#__queue_delayed_work | 0 | 8 |
| BUG:kernel_hang_in_test_stage | 0 | 2 |
+-----------------------------------------------------+------------+------------+
[ 0.292842] WARNING: CPU: 0 PID: 14 at kernel/workqueue.c:1512 __queue_delayed_work+0x1e/0x90
[ 0.294870] Modules linked in:
[ 0.295700] CPU: 0 PID: 14 Comm: cpuhp/0 Not tainted 4.12.0-rc1-00004-g10302bc #1
[ 0.297511] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 0.299600] task: ffff8800102cc900 task.stack: ffffc90000140000
[ 0.300792] RIP: 0010:__queue_delayed_work+0x1e/0x90
[ 0.301861] RSP: 0000:ffffc90000143d90 EFLAGS: 00010086
[ 0.302960] RAX: 0000000000000000 RBX: 0000000000000246 RCX: 0000000000000000
[ 0.304284] RDX: ffffc90000143db8 RSI: ffff880010006c00 RDI: ffffc90000143dd8
[ 0.305779] RBP: ffffc90000143d90 R08: 0000000011627800 R09: 0000000000000000
[ 0.307819] R10: ffffc900000d3b90 R11: 000000000000009e R12: 00000000000000ba
[ 0.309851] R13: ffffffff8105fe50 R14: ffffffff810a4224 R15: 0000000000000000
[ 0.311893] FS: 0000000000000000(0000) GS:ffff880010400000(0000) knlGS:0000000000000000
[ 0.314714] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 0.316496] CR2: 0000000000000000 CR3: 0000000001e09000 CR4: 00000000000006f0
[ 0.318537] Call Trace:
[ 0.319708] queue_delayed_work_on+0x3a/0x50
[ 0.321219] hpet_cpuhp_online+0x99/0xc0
[ 0.322663] ? hpet_cpuhp_dead+0x60/0x60
[ 0.324110] ? pick_next_task_fair+0x4c5/0x520
[ 0.325648] ? __queue_work+0x450/0x450
[ 0.327073] ? smpboot_thread_fn+0x12d/0x1f0
[ 0.328594] cpuhp_invoke_callback+0x80/0x400
[ 0.330113] ? smpboot_thread_fn+0x34/0x1f0
[ 0.331608] ? smpboot_thread_fn+0x12d/0x1f0
[ 0.333122] cpuhp_thread_fun+0x44/0xe0
[ 0.334558] smpboot_thread_fn+0x128/0x1f0
[ 0.336029] kthread+0x114/0x150
[ 0.337350] ? sort_range+0x30/0x30
[ 0.338710] ? kthread_create_on_node+0x40/0x40
[ 0.340274] ret_from_fork+0x2c/0x40
[ 0.341659] Code: e5 e8 97 fb ff ff 5d c3 0f 1f 44 00 00 0f 1f 44 00 00 55 48 85 f6 89 f8 48 8d 7a 20 48 89 e5 74 5a 48 81 7a 38 60 85 09 81 74 47 <0f> ff 48 83 7a 28 00 75 4b 4c 8b 42 08 4c 8d 4a 08 4d 39 c1 75
[ 0.345892] ---[ end trace d34c18692a10188b ]---
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, 8 months
[lkp-robot] [bpf] de05014aba: BUG:sleeping_function_called_from_invalid_context_at_mm/slab.h
by kernel test robot
FYI, we noticed the following commit:
commit: de05014aba8054e1353b720b814a0cd8ea7594e5 ("bpf: Introduce bpf_prog ID")
url: https://github.com/0day-ci/linux/commits/Martin-KaFai-Lau/bpf-Introduce-b...
in testcase: perf-sanity-tests
with following parameters:
on test machine: 8 threads Intel(R) Core(TM) i7-2600K CPU @ 3.40GHz with 8G memory
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+-----------------------------------------------------------------------------+------------+------------+
| | b1513c3531 | de05014aba |
+-----------------------------------------------------------------------------+------------+------------+
| boot_successes | 9 | 3 |
| boot_failures | 1 | 6 |
| BUG:sleeping_function_called_from_invalid_context_at_kernel/printk/printk.c | 1 | |
| BUG:sleeping_function_called_from_invalid_context_at_mm/slab.h | 0 | 4 |
| 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 |
+-----------------------------------------------------------------------------+------------+------------+
[ 45.458682] BUG: sleeping function called from invalid context at mm/slab.h:432
[ 45.458682] in_atomic(): 1, irqs_disabled(): 0, pid: 12359, name: perf
[ 45.458684] CPU: 5 PID: 12359 Comm: perf Not tainted 4.11.0-rc8-02027-gde05014 #1
[ 45.458684] Hardware name: /DH67GD, BIOS BLH6710H.86A.0132.2011.1007.1505 10/07/2011
[ 45.458685] Call Trace:
[ 45.458691] dump_stack+0x63/0x8a
[ 45.458693] ___might_sleep+0xd3/0x120
[ 45.458693] __might_sleep+0x4a/0x80
[ 45.458695] ? radix_tree_node_alloc+0x40/0xc0
[ 45.458697] kmem_cache_alloc+0x162/0x1c0
[ 45.458698] radix_tree_node_alloc+0x40/0xc0
[ 45.458699] idr_get_free+0x2af/0x310
[ 45.458700] idr_alloc+0x3c/0xd0
[ 45.458701] ? __might_sleep+0x4a/0x80
[ 45.458702] idr_alloc_cyclic+0x48/0x60
[ 45.458706] bpf_prog_load+0x28d/0x440
[ 45.458707] ? __might_sleep+0x4a/0x80
[ 45.458709] ? __might_fault+0x2f/0x40
[ 45.458710] SyS_bpf+0x3b1/0x680
[ 45.458713] entry_SYSCALL_64_fastpath+0x1a/0xa9
[ 45.458714] RIP: 0033:0x7f1239b5b099
[ 45.458715] RSP: 002b:00007fff75ac24f8 EFLAGS: 00000202 ORIG_RAX: 0000000000000141
[ 45.458716] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007f1239b5b099
[ 45.458717] RDX: 0000000000000030 RSI: 00007fff75ac2550 RDI: 0000000000000005
[ 45.458717] RBP: 00007fff75ac2590 R08: 0000000000000000 R09: 00007fff75ac2550
[ 45.458718] R10: 0000000000040b00 R11: 0000000000000202 R12: 0000000000000000
[ 45.458719] R13: 00007fff75ac25c8 R14: 0000000000000000 R15: 0000000000000025
[ 45.487788] 35: LLVM search and compile :
[ 45.487789]
[ 45.490511] 35.1: Basic BPF llvm compile : Skip
[ 45.490512]
[ 45.493036] 35.2: kbuild searching : Skip
[ 45.493038]
[ 45.495560] 35.3: Compile source for BPF prologue generation: Skip
[ 45.495561]
[ 45.498059] 35.4: Compile source for BPF relocation : Skip
[ 45.498060]
[ 45.500560] 36: Session topology : Ok
[ 45.500561]
[ 45.503153] 37: BPF filter :
[ 45.503154]
[ 45.505662] 37.1: Basic BPF filtering : Skip
[ 45.505663]
[ 45.508171] 37.2: BPF pinning : Skip
[ 45.508172]
[ 45.510674] 37.3: BPF prologue generation : Skip
[ 45.510675]
[ 45.513128] 37.4: BPF relocation checker : Skip
[ 45.513130]
[ 45.515614] 38: Synthesize thread map : Ok
[ 45.515615]
[ 45.518058] 39: Remove thread map : Ok
[ 45.518059]
[ 45.520547] 40: Synthesize cpu map : Ok
[ 45.520549]
[ 45.522959] 41: Synthesize stat config : Ok
[ 45.522960]
[ 45.525380] 42: Synthesize stat : Ok
[ 45.525381]
[ 45.527804] 43: Synthesize stat round : Ok
[ 45.527805]
[ 45.530224] 44: Synthesize attr update : Ok
[ 45.530225]
[ 45.532564] 45: Event times : Ok
[ 45.532565]
[ 45.534884] 46: Read backward ring buffer : Ok
[ 45.534884]
[ 45.537263] 47: Print cpu map : Ok
[ 45.537264]
[ 45.539571] 48: Probe SDT events : Skip
[ 45.539572]
[ 45.541851] 49: is_printable_array : Ok
[ 45.541852]
[ 45.544158] 50: Print bitmap : Ok
[ 45.544159]
[ 45.546411] 51: perf hooks : Ok
[ 45.546412]
[ 45.548930] 52: builtin clang support : Skip (not compiled in)
[ 45.548931]
[ 45.551294] 53: unit_number__scnprintf : Ok
[ 45.551295]
[ 45.721244] 54: x86 rdpmc : Ok
[ 45.721246]
[ 45.723626] 55: Convert perf time to TSC : Ok
[ 45.723627]
[ 45.742261] 56: DWARF unwind : Ok
[ 45.742263]
[ 45.745502] 57: x86 instruction decoder - new instructions : Ok
[ 45.745504]
[ 45.748675] 58: Intel cqm nmi context read : Skip
[ 45.748677]
[ 46.754453] kill 9536 vmstat --timestamp -n 10
[ 46.754454]
[ 46.756997] kill 9534 dmesg --follow --decode
[ 46.756998]
[ 46.759877] wait for background monitors: 9539 9547 oom-killer nfs-hang
[ 46.759878]
[ 51.548590] /usr/bin/curl -sSf http://inn:80/~lkp/cgi-bin/lkp-jobfile-append-var?job_file=/lkp/scheduled... 0.79 0.27 1/199 12430&start_time=1494729389&end_time=1494729408&version=/lkp/lkp/.src-20170512-113449& -o /dev/null
[ 51.548593]
[ 51.679043] /usr/bin/curl -sSf http://inn:80/~lkp/cgi-bin/lkp-jobfile-append-var?job_file=/lkp/scheduled... -o /dev/null
[ 51.679046]
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, 8 months
[lkp-robot] [sched, rcu] f4bec4091e: [No primary change] will-it-scale.time.involuntary_context_switches +92%
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: f4bec4091e143034b1da9978fe8c4ce9ae784a83 ("sched,rcu: Make cond_resched() provide RCU quiescent state")
https://git.kernel.org/cgit/linux/kernel/git/paulmck/linux-rcu.git dev.2017.05.07a
in testcase: will-it-scale
on test machine: 88 threads Intel(R) Xeon(R) CPU E5-2699 v4 @ 2.20GHz with 64G memory
with following parameters:
nr_task: 100%
mode: thread
test: open1
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-open1-performance/lkp-bdw-ep3b
38d7cd0b09017072 f4bec4091e143034b1da9978fe
---------------- --------------------------
%stddev change %stddev
\ | \
174757 92% 336040 ± 4% will-it-scale.time.involuntary_context_switches
15183 9% 16536 ± 4% vmstat.system.cs
8160767 26% 10318234 ± 9% perf-stat.dTLB-store-misses
0.00 25% 0.00 ± 7% perf-stat.dTLB-store-miss-rate%
65.35 20% 78.31 perf-stat.node-store-miss-rate%
23157 18% 27368 ± 7% perf-stat.cpu-migrations
4594349 9% 5000960 ± 4% perf-stat.context-switches
93.35 5% 98.30 perf-stat.node-load-miss-rate%
0.08 -5% 0.08 perf-stat.branch-miss-rate%
3.327e+09 -6% 3.131e+09 perf-stat.branch-misses
39.64 -16% 33.35 ± 3% perf-stat.cache-miss-rate%
9.98e+08 -46% 5.411e+08 perf-stat.node-stores
perf-stat.node-stores
1.1e+09 ++----------------------------------------------------------------+
| |
1e+09 ++ .*..*.*.*.* .*. .*. .*.*.*..*. .*
*.* + .*. .*.*. .*.* *. *. .*. .*.* * |
| *. * *..* * *. |
9e+08 ++ |
| |
8e+08 ++ |
| |
7e+08 ++ |
| |
| |
6e+08 ++ 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 |
5e+08 ++-------------O------O------------------------------------O------+
perf-stat.node-store-miss-rate_
80 ++---------------------------------------------------------------------+
| O O O O O O O O O O O O O O O O |
78 O+ O O O O O O O O O O O |
76 ++ O |
| |
74 ++ |
| |
72 ++ |
| |
70 ++ |
68 ++ |
| .*. .*. |
66 *+*.. .*.*.*. *.*. *.. .*.*..*.*.*..*. .*.*. .*.|
| *.*.*..*.*. *.*.*..* *. *. *
64 ++---------------------------------------------------------------------+
will-it-scale.time.involuntary_context_switches
360000 ++-----------------------------------------------------------------+
340000 ++ O O O O O O O O O |
| O O O O O O O O O O O O O |
320000 O+O O O |
300000 ++ O O O |
| |
280000 ++ |
260000 ++ |
240000 ++ |
| |
220000 ++ |
200000 ++ |
*. .*. .*. |
180000 ++*.*..*.*.*.*..*.*.*.*..*.* *..*.*.*.*.*..*.* *..*.*.*.*..*.*.*
160000 ++-----------------------------------------------------------------+
[*] 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, 8 months
[lkp-robot] [sched, rcu] 3f72c37075: [No primary change] unixbench.time.voluntary_context_switches +146%
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: 3f72c37075428088fcba3123122c8bd073a255b0 ("sched,rcu: Make cond_resched() provide RCU quiescent state")
https://git.kernel.org/cgit/linux/kernel/git/paulmck/linux-rcu.git dev.2017.04.26d
in testcase: unixbench
on test machine: 192 threads Skylake-4S with 768G memory
with following parameters:
runtime: 300s
nr_task: 100%
test: execl
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-100%-execl-performance/lkp-skl-4sp1
7b16dfa4616d65ec 3f72c37075428088fcba312312
---------------- --------------------------
%stddev change %stddev
\ | \
8765520 ± 6% 146% 21598333 ± 5% unixbench.time.voluntary_context_switches
127957 37% 175160 ± 5% unixbench.time.involuntary_context_switches
346 6% 366 ± 5% unixbench.time.user_time
3.13e+08 5% 3.277e+08 ± 4% unixbench.time.minor_page_faults
16628 -18% 13671 unixbench.time.percent_of_cpu_this_job_got
54577 -18% 44798 unixbench.time.system_time
755294 8% 814577 interrupts.CAL:Function_call_interrupts
56365 ± 6% 140% 135394 ± 5% vmstat.system.cs
87.40 -17% 72.74 turbostat.%Busy
1858 -17% 1538 turbostat.Avg_MHz
18712104 ± 6% 140% 44967273 ± 5% perf-stat.context-switches
255299 75% 445591 perf-stat.cpu-migrations
1.37e+09 31% 1.793e+09 ± 4% perf-stat.iTLB-loads
0.75 25% 0.93 ± 3% perf-stat.branch-miss-rate%
0.05 ± 7% 23% 0.06 perf-stat.dTLB-load-miss-rate%
1.832e+09 20% 2.196e+09 ± 4% perf-stat.node-store-misses
8.214e+10 13% 9.279e+10 ± 3% perf-stat.cache-references
2.166e+10 11% 2.399e+10 ± 4% perf-stat.branch-misses
6.524e+11 10% 7.175e+11 ± 4% perf-stat.dTLB-stores
8.656e+09 9% 9.477e+09 perf-stat.node-load-misses
0.12 7% 0.13 perf-stat.ipc
7.982e+08 6% 8.47e+08 ± 3% perf-stat.iTLB-load-misses
3.815e+10 6% 4.035e+10 perf-stat.cache-misses
3.014e+08 5% 3.156e+08 ± 4% perf-stat.minor-faults
3.014e+08 5% 3.156e+08 ± 4% perf-stat.page-faults
4.231e+08 4% 4.403e+08 ± 3% perf-stat.dTLB-store-misses
96.55 97.65 perf-stat.node-store-miss-rate%
87.39 88.33 perf-stat.node-load-miss-rate%
0.06 -5% 0.06 perf-stat.dTLB-store-miss-rate%
46.45 -6% 43.51 perf-stat.cache-miss-rate%
3.945e+12 -11% 3.507e+12 perf-stat.dTLB-loads
2.904e+12 -11% 2.577e+12 perf-stat.branch-instructions
1.467e+13 -11% 1.301e+13 perf-stat.instructions
36.81 -13% 32.09 perf-stat.iTLB-load-miss-rate%
18385 -16% 15374 perf-stat.instructions-per-iTLB-miss
1.175e+14 -17% 9.738e+13 perf-stat.cpu-cycles
65476740 -19% 52818574 ± 6% perf-stat.node-stores
turbostat.Avg_MHz
1900 ++-------------------------------------------------------------------+
*.*.*.*.*.*.* *.*.*.*.*.*..*.*.*.*.*.*.*.*.*.*.*.*.*.*.*.*.*.*
1800 ++ + .*. : |
| * *. : |
| * |
1700 ++ |
| |
1600 ++ O O |
| O O O O O O O O |
1500 ++ O O O |
| O O O |
| O O O O O |
1400 ++ O O O O O O O O |
O O O O |
1300 ++-------------------------------------------------------------------+
perf-stat.branch-miss-rate_
1.1 ++-------------------------------------------------------------------+
O O |
1.05 ++ O O |
1 ++ O O O O O O |
| O O O O O |
0.95 ++ O O O O O O O O O |
0.9 ++ O O O O O |
| O O O O |
0.85 ++ |
0.8 ++ |
| *. *. |
0.75 *+*.*.*.*. + *. + *. .*.*.*.*.. .*.*. .*.*.*.*. .*.*.*.*.*.*.*.*.*
0.7 ++ *.* * * * * * |
| |
0.65 ++-------------------------------------------------------------------+
[*] 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, 8 months