[lkp] [cpuidle] e132b9b3bc: No primary change, turbostat.%Busy -65.1% change
by kernel test robot
FYI, we noticed that turbostat.%Busy -65.1% change with your commit.
https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git master
commit e132b9b3bc7f19e9b158e42b323881d5dee5ecf3 ("cpuidle: menu: use high confidence factors only when considering polling")
=========================================================================================
compiler/disk/filesize/fs/iterations/kconfig/nr_directories/nr_files_per_directory/nr_threads/rootfs/sync_method/tbox_group/test_size/testcase:
gcc-4.9/1HDD/5K/btrfs/1x/x86_64-rhel/16d/256fpd/32t/debian-x86_64-2015-02-07.cgz/fsyncBeforeClose/lkp-ws02/400M/fsmark
commit:
3b99669b75db04e411bb298591224a9e8e4f57fb
e132b9b3bc7f19e9b158e42b323881d5dee5ecf3
3b99669b75db04e4 e132b9b3bc7f19e9b158e42b32
---------------- --------------------------
%stddev %change %stddev
\ | \
505.00 ± 7% +83.6% 927.00 ± 4% vmstat.memory.buff
6392 ± 35% +62.4% 10382 ± 0% numa-meminfo.node0.Mapped
2646 ±130% +226.2% 8631 ± 0% numa-meminfo.node0.Shmem
9065 ± 25% -44.8% 5008 ± 1% numa-meminfo.node1.Mapped
26.78 ± 1% -65.1% 9.34 ± 0% turbostat.%Busy
709.50 ± 1% -65.2% 246.75 ± 0% turbostat.Avg_MHz
40.46 ± 1% +39.7% 56.54 ± 1% turbostat.CPU%c1
1597 ± 35% +62.4% 2594 ± 0% numa-vmstat.node0.nr_mapped
661.25 ±130% +226.3% 2157 ± 0% numa-vmstat.node0.nr_shmem
106.00 ± 39% +220.0% 339.25 ± 61% numa-vmstat.node0.numa_other
2266 ± 25% -44.8% 1251 ± 1% numa-vmstat.node1.nr_mapped
4.795e+08 ± 4% +117.9% 1.045e+09 ± 2% cpuidle.C1-NHM.time
463937 ± 2% +73.2% 803714 ± 2% cpuidle.C1-NHM.usage
1.699e+08 ± 3% -8.6% 1.553e+08 ± 1% cpuidle.C1E-NHM.time
7.062e+08 ± 0% -84.0% 1.131e+08 ± 5% cpuidle.POLL.time
440162 ± 1% -79.7% 89501 ± 6% cpuidle.POLL.usage
0.00 ± -1% +Inf% 8824 ± 70% latency_stats.avg.blk_execute_rq.scsi_execute.scsi_execute_req_flags.ses_recv_diag.[ses].ses_enclosure_data_process.[ses].ses_match_to_enclosure.[ses].ses_intf_add.[ses].class_interface_register.scsi_register_interface.0xffffffffa0006013.do_one_initcall.do_init_module
0.00 ± -1% +Inf% 12106 ± 71% latency_stats.avg.blk_execute_rq.scsi_execute.scsi_execute_req_flags.ses_recv_diag.[ses].ses_get_page2_descriptor.[ses].ses_get_power_status.[ses].ses_enclosure_data_process.[ses].ses_match_to_enclosure.[ses].ses_intf_add.[ses].class_interface_register.scsi_register_interface.0xffffffffa0006013
0.00 ± -1% +Inf% 15174 ± 70% latency_stats.max.blk_execute_rq.scsi_execute.scsi_execute_req_flags.ses_recv_diag.[ses].ses_enclosure_data_process.[ses].ses_match_to_enclosure.[ses].ses_intf_add.[ses].class_interface_register.scsi_register_interface.0xffffffffa0006013.do_one_initcall.do_init_module
0.00 ± -1% +Inf% 108570 ± 80% latency_stats.max.blk_execute_rq.scsi_execute.scsi_execute_req_flags.ses_recv_diag.[ses].ses_get_page2_descriptor.[ses].ses_get_power_status.[ses].ses_enclosure_data_process.[ses].ses_match_to_enclosure.[ses].ses_intf_add.[ses].class_interface_register.scsi_register_interface.0xffffffffa0006013
0.00 ± -1% +Inf% 92833 ± 71% latency_stats.sum.blk_execute_rq.scsi_execute.scsi_execute_req_flags.ses_recv_diag.[ses].ses_enclosure_data_process.[ses].ses_match_to_enclosure.[ses].ses_intf_add.[ses].class_interface_register.scsi_register_interface.0xffffffffa0006013.do_one_initcall.do_init_module
0.00 ± -1% +Inf% 811913 ± 70% latency_stats.sum.blk_execute_rq.scsi_execute.scsi_execute_req_flags.ses_recv_diag.[ses].ses_get_page2_descriptor.[ses].ses_get_power_status.[ses].ses_enclosure_data_process.[ses].ses_match_to_enclosure.[ses].ses_intf_add.[ses].class_interface_register.scsi_register_interface.0xffffffffa0006013
-9221 ±-11% -19.9% -7385 ±-11% sched_debug.cfs_rq:/.spread0.avg
591.90 ± 62% +112.9% 1260 ± 30% sched_debug.cfs_rq:/.spread0.max
-14064 ± -6% -11.1% -12500 ± -6% sched_debug.cfs_rq:/.spread0.min
306.42 ± 40% -41.9% 178.00 ± 8% sched_debug.cpu.load.max
75.40 ± 31% -33.6% 50.09 ± 13% sched_debug.cpu.load.stddev
714.67 ± 1% -9.9% 644.00 ± 5% sched_debug.cpu.nr_uninterruptible.max
1149 ± 9% -15.9% 967.25 ± 3% slabinfo.avc_xperms_node.active_objs
1149 ± 9% -15.9% 967.25 ± 3% slabinfo.avc_xperms_node.num_objs
1020 ± 8% +28.2% 1308 ± 3% slabinfo.btrfs_trans_handle.active_objs
1020 ± 8% +28.2% 1308 ± 3% slabinfo.btrfs_trans_handle.num_objs
351.75 ± 11% +39.2% 489.50 ± 8% slabinfo.btrfs_transaction.active_objs
351.75 ± 11% +39.2% 489.50 ± 8% slabinfo.btrfs_transaction.num_objs
544.00 ± 10% +20.6% 656.00 ± 12% slabinfo.kmem_cache_node.active_objs
544.00 ± 10% +20.6% 656.00 ± 12% slabinfo.kmem_cache_node.num_objs
lkp-ws02: Westmere-EP
Memory: 16G
turbostat.Avg_MHz
800 ++--------------------------------------------------------------------+
**.****.****.* **.****.****.** *.****.****.****.****.****.** *. * |
700 ++ * * * ** *.***
| |
600 ++ |
| |
500 ++ |
| |
400 ++ |
| |
300 ++ OO O O O |
| O OO O O O OO OOOO OOO |
200 ++ |
OO OOOO OOO |
100 ++--------------------------------------------------------------------+
turbostat._Busy
30 ++---------------------------------------------------------------------+
**.****.****.* **.***.****.*** .***.* *.****.****.***.****.* * * |
| * * ** * *.* **.**
25 ++ |
| |
| |
20 ++ |
| |
15 ++ |
| |
| |
10 ++ O OOOO OOO OOOO |
| OOOO OOO O |
OO OOOO O O |
5 ++-------O-------------------------------------------------------------+
turbostat.CPU_c1
65 ++---------------------------------------------------------------------+
| O |
60 OO OO O O |
| O O |
| OOO OO O O OOOO OOO O |
55 ++ O O O |
| O O |
50 ++ |
| |
45 ++ |
| |
**.****. *. ***.***.****.****.***.* **.****.****.***.****.****.****. |
40 ++ *** * * **
| |
35 ++---------------------------------------------------------------------+
[*] bisect-good sample
[O] bisect-bad sample
To reproduce:
git clone git://git.kernel.org/pub/scm/linux/kernel/git/wfg/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
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 Ye
6 years, 1 month
[lkp] [mm] 56a17b8836: No primary change, will-it-scale.time.percent_of_cpu_this_job_got +41.4% change
by kernel test robot
FYI, we noticed that will-it-scale.time.percent_of_cpu_this_job_got +41.4% change
with your commit.
https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git master
commit 56a17b8836398ffab52c00dda8442f1680dc251b ("mm: temporarily mark THP broken")
=========================================================================================
compiler/cpufreq_governor/kconfig/rootfs/tbox_group/test/testcase:
gcc-4.9/performance/x86_64-rhel/debian-x86_64-2015-02-07.cgz/lkp-sbx04/malloc1/will-it-scale
commit:
122afea9626ab3f717b250a8dd3d5ebf57cdb56c
56a17b8836398ffab52c00dda8442f1680dc251b
122afea9626ab3f7 56a17b8836398ffab52c00dda8
---------------- --------------------------
%stddev %change %stddev
\ | \
78370 ± 0% +1.6% 79638 ± 0% will-it-scale.per_process_ops
39117 ± 0% +7.3% 41971 ± 0% will-it-scale.per_thread_ops
36425 ± 6% -74.4% 9327 ± 0% will-it-scale.time.maximum_resident_set_size
7633416 ± 0% +60.8% 12272010 ± 0% will-it-scale.time.minor_page_faults
67.00 ± 1% +41.4% 94.75 ± 0% will-it-scale.time.percent_of_cpu_this_job_got
200.68 ± 0% +38.5% 278.00 ± 0% will-it-scale.time.system_time
7.88 ± 2% +98.5% 15.65 ± 1% will-it-scale.time.user_time
3902893 ± 5% +302.9% 15723910 ± 0% will-it-scale.time.voluntary_context_switches
150273 ± 4% +34.7% 202476 ± 8% softirqs.RCU
27428 ± 4% +277.4% 103508 ± 0% vmstat.system.cs
20080 ± 3% +93.3% 38820 ± 1% vmstat.system.in
48628 ± 2% -15.6% 41041 ± 0% meminfo.Active(anon)
7893 ± 15% -100.0% 0.00 ± -1% meminfo.AnonHugePages
36334 ± 3% -21.2% 28619 ± 0% meminfo.AnonPages
33788 ± 0% +17.9% 39840 ± 0% numa-vmstat.node0.numa_interleave
3801 ± 31% -46.6% 2028 ± 36% numa-vmstat.node1.nr_active_anon
326.25 ± 3% -15.9% 274.50 ± 2% numa-vmstat.node1.nr_alloc_batch
3693 ± 10% -49.3% 1870 ± 28% numa-vmstat.node2.nr_anon_pages
0.94 ± 5% +11.7% 1.05 ± 3% perf-profile.cycles.___might_sleep.kmem_cache_alloc.mmap_region.do_mmap.vm_mmap_pgoff
1.18 ± 2% -11.3% 1.04 ± 1% perf-profile.cycles.___might_sleep.unmap_page_range.unmap_single_vma.unmap_vmas.unmap_region
1.03 ± 1% -7.5% 0.95 ± 4% perf-profile.cycles.__alloc_pages_nodemask.alloc_pages_current.pte_alloc_one.__pte_alloc.handle_mm_fault
1.14 ± 1% -7.7% 1.05 ± 3% perf-profile.cycles.alloc_pages_current.pte_alloc_one.__pte_alloc.handle_mm_fault.__do_page_fault
1647719 ± 3% +271.3% 6117589 ± 0% latency_stats.hits.call_rwsem_down_read_failed.__do_page_fault.do_page_fault.page_fault
1356059 ± 5% +315.7% 5636837 ± 0% latency_stats.hits.call_rwsem_down_write_failed.vm_munmap.SyS_munmap.entry_SYSCALL_64_fastpath
8333 ± 21% -75.6% 2032 ± 8% latency_stats.sum.call_rwsem_down_read_failed.do_exit.do_group_exit.SyS_exit_group.entry_SYSCALL_64_fastpath
93349 ± 11% -71.7% 26423 ± 7% latency_stats.sum.call_rwsem_down_read_failed.do_exit.do_group_exit.get_signal.do_signal.exit_to_usermode_loop.prepare_exit_to_usermode.retint_user
92440 ± 30% -69.1% 28576 ± 17% latency_stats.sum.call_rwsem_down_read_failed.do_exit.do_group_exit.get_signal.do_signal.exit_to_usermode_loop.syscall_return_slowpath.int_ret_from_sys_call
152444 ± 4% -67.8% 49052 ± 3% latency_stats.sum.call_rwsem_down_write_failed.SyS_mprotect.entry_SYSCALL_64_fastpath
36425 ± 6% -74.4% 9327 ± 0% time.maximum_resident_set_size
7633416 ± 0% +60.8% 12272010 ± 0% time.minor_page_faults
67.00 ± 1% +41.4% 94.75 ± 0% time.percent_of_cpu_this_job_got
200.68 ± 0% +38.5% 278.00 ± 0% time.system_time
7.88 ± 2% +98.5% 15.65 ± 1% time.user_time
3902893 ± 5% +302.9% 15723910 ± 0% time.voluntary_context_switches
983.75 ± 31% -100.0% 0.00 ± -1% numa-meminfo.node0.AnonHugePages
39941 ± 10% -18.2% 32681 ± 9% numa-meminfo.node1.Active
15187 ± 31% -46.5% 8119 ± 36% numa-meminfo.node1.Active(anon)
676.00 ± 66% -100.0% 0.00 ± -1% numa-meminfo.node1.AnonHugePages
5403 ± 20% -100.0% 0.00 ± -1% numa-meminfo.node2.AnonHugePages
14772 ± 10% -49.3% 7486 ± 28% numa-meminfo.node2.AnonPages
880.75 ± 48% -100.0% 0.00 ± -1% numa-meminfo.node3.AnonHugePages
12167 ± 2% -15.7% 10260 ± 0% proc-vmstat.nr_active_anon
1193 ± 0% -11.6% 1054 ± 1% proc-vmstat.nr_alloc_batch
9094 ± 3% -21.3% 7155 ± 0% proc-vmstat.nr_anon_pages
49373529 ± 1% -29.1% 35001216 ± 0% proc-vmstat.pgalloc_dma32
2.245e+08 ± 0% -15.2% 1.905e+08 ± 0% proc-vmstat.pgalloc_normal
2.739e+08 ± 0% -17.7% 2.255e+08 ± 0% proc-vmstat.pgfree
113422 ± 3% -100.0% 0.00 ± -1% proc-vmstat.thp_fault_alloc
113402 ± 3% -100.0% 0.00 ± -1% proc-vmstat.thp_split_page
22.63 ± 0% +3.0% 23.32 ± 0% turbostat.%Busy
652.50 ± 0% +2.4% 668.00 ± 0% turbostat.Avg_MHz
27.60 ± 7% +50.1% 41.43 ± 0% turbostat.CPU%c1
0.75 ± 21% -97.7% 0.02 ± 47% turbostat.CPU%c3
49.02 ± 3% -28.1% 35.23 ± 0% turbostat.CPU%c7
121.98 ± 0% +6.2% 129.50 ± 0% turbostat.CorWatt
37.39 ± 2% -19.0% 30.27 ± 1% turbostat.Pkg%pc2
176.87 ± 0% +4.2% 184.25 ± 0% turbostat.PkgWatt
70837840 ± 23% +157.4% 1.823e+08 ± 6% cpuidle.C1-SNB.time
352446 ±111% +1438.3% 5421651 ± 3% cpuidle.C1-SNB.usage
68045347 ± 7% +114.8% 1.462e+08 ± 1% cpuidle.C1E-SNB.time
152018 ± 29% +932.9% 1570128 ± 9% cpuidle.C1E-SNB.usage
36961996 ± 23% +550.6% 2.405e+08 ± 5% cpuidle.C3-SNB.time
87756 ± 24% +762.1% 756587 ± 4% cpuidle.C3-SNB.usage
3624705 ± 3% +168.4% 9729062 ± 0% cpuidle.C7-SNB.usage
34086233 ± 14% +30.4% 44449058 ± 13% cpuidle.POLL.time
71342 ± 5% +92.0% 136968 ± 3% cpuidle.POLL.usage
55973 ± 3% +11.6% 62441 ± 2% sched_debug.cpu.nr_load_updates.avg
23002 ± 3% +14.0% 26212 ± 1% sched_debug.cpu.nr_load_updates.stddev
56335 ± 8% +286.9% 217982 ± 0% sched_debug.cpu.nr_switches.avg
224611 ± 21% +358.7% 1030323 ± 3% sched_debug.cpu.nr_switches.max
6852 ± 4% +124.6% 15390 ± 26% sched_debug.cpu.nr_switches.min
57323 ± 21% +405.8% 289971 ± 2% sched_debug.cpu.nr_switches.stddev
155999 ± 2% +104.1% 318341 ± 0% sched_debug.cpu.sched_count.avg
7095 ± 5% +120.1% 15617 ± 27% sched_debug.cpu.sched_count.min
27854 ± 8% +290.2% 108690 ± 0% sched_debug.cpu.sched_goidle.avg
111721 ± 21% +360.8% 514811 ± 3% sched_debug.cpu.sched_goidle.max
3125 ± 6% +140.9% 7528 ± 27% sched_debug.cpu.sched_goidle.min
28656 ± 21% +406.0% 145007 ± 2% sched_debug.cpu.sched_goidle.stddev
28531 ± 8% +289.8% 111208 ± 0% sched_debug.cpu.ttwu_count.avg
113428 ± 20% +362.5% 524580 ± 3% sched_debug.cpu.ttwu_count.max
2957 ± 17% +152.7% 7472 ± 22% sched_debug.cpu.ttwu_count.min
29259 ± 20% +403.8% 147410 ± 2% sched_debug.cpu.ttwu_count.stddev
lkp-sbx04: Sandy Bridge-EX
Memory: 64G
To reproduce:
git clone git://git.kernel.org/pub/scm/linux/kernel/git/wfg/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
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 Ye
6 years, 1 month
[lkp] [sched/fair] 3a47d5124a: pigz.throughput -2.8% regression
by kernel test robot
FYI, we noticed that pigz.throughput -2.8% regression with your commit,
https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git master
commit 3a47d5124a957358274e9ca7b115b2f3a914f56d ("sched/fair: Fix fairness issue on migration")
=========================================================================================
blocksize/compiler/cpufreq_governor/kconfig/nr_threads/rootfs/tbox_group/testcase:
128K/gcc-4.9/performance/x86_64-rhel/100%/debian-x86_64-2015-02-07.cgz/lkp-snb01/pigz
commit:
2f5177f0fd7e531b26d54633be62d1d4cb94621c
3a47d5124a957358274e9ca7b115b2f3a914f56d
2f5177f0fd7e531b 3a47d5124a957358274e9ca7b1
---------------- --------------------------
fail:runs %reproduction fail:runs
%stddev %change %stddev
\ | \
3.565e+08 ± 0% -2.8% 3.466e+08 ± 0% pigz.throughput
3148624 ± 1% -61.5% 1213138 ± 1% pigz.time.involuntary_context_switches
3172 ± 0% -4.9% 3017 ± 0% pigz.time.percent_of_cpu_this_job_got
108.01 ± 0% -12.7% 94.28 ± 0% pigz.time.system_time
9416 ± 0% -4.8% 8963 ± 0% pigz.time.user_time
4435433 ± 0% -25.4% 3308219 ± 0% pigz.time.voluntary_context_switches
22606 ± 6% -8.5% 20692 ± 0% meminfo.Shmem
6211 ± 51% +65.1% 10253 ± 13% numa-meminfo.node1.AnonHugePages
82109 ± 4% +111.8% 173874 ± 5% softirqs.SCHED
17775779 ± 2% -8.2% 16323423 ± 5% numa-numastat.node0.local_node
17777847 ± 2% -8.2% 16324614 ± 5% numa-numastat.node0.numa_hit
10837718 ± 18% -23.4% 8299086 ± 3% numa-vmstat.node0.numa_hit
10771465 ± 18% -23.6% 8232199 ± 3% numa-vmstat.node0.numa_local
6147 ± 4% +9.7% 6742 ± 4% slabinfo.anon_vma.active_objs
6147 ± 4% +9.7% 6742 ± 4% slabinfo.anon_vma.num_objs
5652 ± 6% -8.5% 5171 ± 0% proc-vmstat.nr_shmem
33999 ± 6% +40.2% 47675 ± 14% proc-vmstat.numa_pages_migrated
33999 ± 6% +40.2% 47675 ± 14% proc-vmstat.pgmigrate_success
3148624 ± 1% -61.5% 1213138 ± 1% time.involuntary_context_switches
108.01 ± 0% -12.7% 94.28 ± 0% time.system_time
4435433 ± 0% -25.4% 3308219 ± 0% time.voluntary_context_switches
0.64 ± 5% +707.0% 5.17 ± 1% turbostat.CPU%c1
0.01 ± 0% +675.0% 0.08 ± 5% turbostat.CPU%c3
16.03 ±101% -99.6% 0.06 ±100% turbostat.Pkg%pc2
14964297 ± 21% +256.4% 53332691 ± 3% cpuidle.C1-SNB.time
58475 ± 5% +229.5% 192657 ± 1% cpuidle.C1-SNB.usage
18841654 ± 15% +709.6% 1.525e+08 ± 2% cpuidle.C1E-SNB.time
66706 ± 10% +629.5% 486650 ± 1% cpuidle.C1E-SNB.usage
4314242 ± 12% +575.3% 29134227 ± 1% cpuidle.C3-SNB.time
14262 ± 10% +543.3% 91745 ± 2% cpuidle.C3-SNB.usage
133764 ± 27% +434.9% 715504 ± 1% cpuidle.C7-SNB.usage
3.929e+08 ± 1% -100.0% 0.00 ± -1% latency_stats.avg.nfs_wait_on_request.nfs_updatepage.nfs_write_end.generic_perform_write.__generic_file_write_iter.generic_file_write_iter.nfs_file_write.__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
9059435 ±103% -100.0% 160.00 ± 44% latency_stats.avg.rpc_wait_bit_killable.__rpc_execute.rpc_execute.rpc_run_task.nfs4_call_sync_sequence.[nfsv4]._nfs4_proc_access.[nfsv4].nfs4_proc_access.[nfsv4].nfs_do_access.nfs_permission.__inode_permission.inode_permission.link_path_walk
4.578e+08 ± 5% -100.0% 0.00 ± -1% latency_stats.max.nfs_wait_on_request.nfs_updatepage.nfs_write_end.generic_perform_write.__generic_file_write_iter.generic_file_write_iter.nfs_file_write.__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
51327106 ±102% -100.0% 257.25 ± 51% latency_stats.max.rpc_wait_bit_killable.__rpc_execute.rpc_execute.rpc_run_task.nfs4_call_sync_sequence.[nfsv4]._nfs4_proc_access.[nfsv4].nfs4_proc_access.[nfsv4].nfs_do_access.nfs_permission.__inode_permission.inode_permission.link_path_walk
7.858e+08 ± 1% -100.0% 0.00 ± -1% latency_stats.sum.nfs_wait_on_request.nfs_updatepage.nfs_write_end.generic_perform_write.__generic_file_write_iter.generic_file_write_iter.nfs_file_write.__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
20816 ± 25% +361.7% 96106 ± 13% latency_stats.sum.pipe_read.__vfs_read.vfs_read.SyS_read.entry_SYSCALL_64_fastpath
1.014e+08 ±107% -100.0% 1445 ± 44% latency_stats.sum.rpc_wait_bit_killable.__rpc_execute.rpc_execute.rpc_run_task.nfs4_call_sync_sequence.[nfsv4]._nfs4_proc_access.[nfsv4].nfs4_proc_access.[nfsv4].nfs_do_access.nfs_permission.__inode_permission.inode_permission.link_path_walk
172662 ± 14% -18.4% 140966 ± 0% sched_debug.cfs_rq:/.exec_clock.avg
172933 ± 14% -18.0% 141779 ± 0% sched_debug.cfs_rq:/.exec_clock.max
171995 ± 14% -18.6% 139924 ± 0% sched_debug.cfs_rq:/.exec_clock.min
179.82 ± 33% +180.3% 504.10 ± 34% sched_debug.cfs_rq:/.exec_clock.stddev
18.12 ± 27% -67.6% 5.88 ± 35% sched_debug.cfs_rq:/.load.min
5948149 ± 14% -23.8% 4531737 ± 0% sched_debug.cfs_rq:/.min_vruntime.avg
5968318 ± 14% -23.6% 4561399 ± 0% sched_debug.cfs_rq:/.min_vruntime.max
5921026 ± 14% -24.1% 4496045 ± 0% sched_debug.cfs_rq:/.min_vruntime.min
0.67 ± 26% -69.0% 0.21 ± 34% sched_debug.cfs_rq:/.nr_running.min
0.10 ± 40% +185.3% 0.28 ± 9% sched_debug.cfs_rq:/.nr_running.stddev
0.38 ± 15% -39.2% 0.23 ± 17% sched_debug.cfs_rq:/.nr_spread_over.avg
11.22 ± 12% -46.1% 6.04 ± 28% sched_debug.cfs_rq:/.nr_spread_over.max
1.97 ± 12% -45.5% 1.07 ± 26% sched_debug.cfs_rq:/.nr_spread_over.stddev
18.97 ± 27% -69.7% 5.75 ± 36% sched_debug.cfs_rq:/.runnable_load_avg.min
4.44 ± 90% +157.1% 11.41 ± 37% sched_debug.cfs_rq:/.runnable_load_avg.stddev
13030 ±176% -199.0% -12895 ±-101% sched_debug.cfs_rq:/.spread0.avg
-13998 ±-100% +247.4% -48630 ±-36% sched_debug.cfs_rq:/.spread0.min
9880 ± 23% +59.9% 15795 ± 23% sched_debug.cfs_rq:/.spread0.stddev
34.89 ± 8% +120.4% 76.89 ± 13% sched_debug.cfs_rq:/.util_avg.stddev
196657 ± 16% +36.9% 269230 ± 6% sched_debug.cpu.avg_idle.stddev
17.42 ± 64% -79.4% 3.58 ± 11% sched_debug.cpu.clock.stddev
17.42 ± 64% -79.4% 3.58 ± 11% sched_debug.cpu.clock_task.stddev
18.10 ± 30% -51.7% 8.75 ± 27% sched_debug.cpu.cpu_load[0].min
18.74 ± 27% -44.9% 10.33 ± 18% sched_debug.cpu.cpu_load[1].min
19.22 ± 24% -36.7% 12.17 ± 6% sched_debug.cpu.cpu_load[2].min
1912 ± 23% -63.2% 704.04 ± 48% sched_debug.cpu.curr->pid.min
18.12 ± 27% -67.6% 5.88 ± 35% sched_debug.cpu.load.min
176320 ± 14% -15.5% 148922 ± 0% sched_debug.cpu.nr_load_updates.avg
178699 ± 14% -15.3% 151407 ± 0% sched_debug.cpu.nr_load_updates.max
174865 ± 14% -15.8% 147278 ± 0% sched_debug.cpu.nr_load_updates.min
0.67 ± 26% -69.0% 0.21 ± 34% sched_debug.cpu.nr_running.min
0.27 ± 8% +41.3% 0.39 ± 5% sched_debug.cpu.nr_running.stddev
152747 ± 14% -34.7% 99735 ± 0% sched_debug.cpu.nr_switches.avg
183979 ± 12% -31.3% 126457 ± 5% sched_debug.cpu.nr_switches.max
135220 ± 15% -41.5% 79108 ± 3% sched_debug.cpu.nr_switches.min
154367 ± 14% -34.5% 101165 ± 0% sched_debug.cpu.sched_count.avg
225427 ± 12% -20.1% 180086 ± 4% sched_debug.cpu.sched_count.max
134418 ± 14% -41.5% 78674 ± 3% sched_debug.cpu.sched_count.min
6588 ± 22% +264.1% 23990 ± 2% sched_debug.cpu.sched_goidle.avg
20233 ± 23% +73.8% 35174 ± 7% sched_debug.cpu.sched_goidle.max
3150 ± 22% +492.7% 18675 ± 2% sched_debug.cpu.sched_goidle.min
86325 ± 15% -36.0% 55212 ± 0% sched_debug.cpu.ttwu_count.avg
103976 ± 13% -33.4% 69252 ± 4% sched_debug.cpu.ttwu_count.max
74872 ± 14% -39.3% 45412 ± 3% sched_debug.cpu.ttwu_count.min
61351 ± 14% -60.3% 24369 ± 1% sched_debug.cpu.ttwu_local.avg
68724 ± 13% -56.3% 30060 ± 3% sched_debug.cpu.ttwu_local.max
54792 ± 14% -65.3% 19018 ± 6% sched_debug.cpu.ttwu_local.min
9.86 ± 9% -37.9% 6.13 ± 5% perf-profile.cycles.__account_scheduler_latency.enqueue_entity.enqueue_task_fair.activate_task.ttwu_do_activate
5.67 ± 3% -18.2% 4.64 ± 6% perf-profile.cycles.__alloc_pages_nodemask.alloc_pages_current.pipe_write.__vfs_write.vfs_write
0.60 ± 98% +306.6% 2.46 ± 19% perf-profile.cycles.__do_softirq.irq_exit.smp_apic_timer_interrupt.apic_timer_interrupt
2.69 ± 52% +282.2% 10.26 ± 5% perf-profile.cycles.__hrtimer_run_queues.hrtimer_interrupt.local_apic_timer_interrupt.smp_apic_timer_interrupt.apic_timer_interrupt
4.49 ± 9% -36.3% 2.86 ± 9% perf-profile.cycles.__kernel_text_address.print_context_stack.dump_trace.save_stack_trace_tsk.__account_scheduler_latency
0.74 ± 23% -40.4% 0.44 ± 20% perf-profile.cycles.__list_del_entry.list_del.__rmqueue.get_page_from_freelist.__alloc_pages_nodemask
0.88 ± 31% +395.7% 4.34 ± 6% perf-profile.cycles.__mutex_lock_slowpath.mutex_lock.pipe_read.__vfs_read.vfs_read
0.41 ± 38% +283.7% 1.59 ± 17% perf-profile.cycles.__mutex_lock_slowpath.mutex_lock.pipe_wait.pipe_write.__vfs_write
3.42 ± 11% -32.5% 2.31 ± 16% perf-profile.cycles.__put_page.anon_pipe_buf_release.pipe_read.__vfs_read.vfs_read
1.01 ± 8% -28.6% 0.72 ± 10% perf-profile.cycles.__rmqueue.get_page_from_freelist.__alloc_pages_nodemask.alloc_pages_current.pipe_write
1.05 ± 20% -64.2% 0.38 ± 26% perf-profile.cycles.__schedule.schedule.exit_to_usermode_loop.syscall_return_slowpath.entry_SYSCALL_64_fastpath
2.86 ± 10% -15.9% 2.40 ± 2% perf-profile.cycles.__schedule.schedule.futex_wait_queue_me.futex_wait.do_futex
0.92 ± 7% -22.4% 0.72 ± 12% perf-profile.cycles.__schedule.schedule.pipe_wait.pipe_write.__vfs_write
26.84 ± 3% -13.9% 23.11 ± 3% perf-profile.cycles.__vfs_write.vfs_write.sys_write.entry_SYSCALL_64_fastpath
3.23 ± 11% -33.6% 2.15 ± 7% perf-profile.cycles.__wake_up_common.__wake_up_sync_key.pipe_read.__vfs_read.vfs_read
3.27 ± 10% -33.1% 2.19 ± 5% perf-profile.cycles.__wake_up_sync_key.pipe_read.__vfs_read.vfs_read.sys_read
0.18 ± 59% +745.1% 1.50 ± 9% perf-profile.cycles.activate_task.ttwu_do_activate.sched_ttwu_pending.cpu_startup_entry.start_secondary
1.65 ± 10% -65.3% 0.57 ± 13% perf-profile.cycles.activate_task.ttwu_do_activate.sched_ttwu_pending.scheduler_ipi.smp_reschedule_interrupt
2.33 ± 12% -33.2% 1.56 ± 10% perf-profile.cycles.activate_task.ttwu_do_activate.try_to_wake_up.default_wake_function.autoremove_wake_function
5.23 ± 15% -60.1% 2.09 ± 11% perf-profile.cycles.activate_task.ttwu_do_activate.try_to_wake_up.wake_up_q.futex_requeue
3.28 ± 10% -35.9% 2.10 ± 13% perf-profile.cycles.activate_task.ttwu_do_activate.try_to_wake_up.wake_up_q.futex_wake
6.42 ± 3% -15.8% 5.41 ± 4% perf-profile.cycles.alloc_pages_current.pipe_write.__vfs_write.vfs_write.sys_write
2.05 ± 13% -37.9% 1.27 ± 4% perf-profile.cycles.anon_pipe_buf_release.__vfs_read.vfs_read.sys_read.entry_SYSCALL_64_fastpath
3.51 ± 11% -33.3% 2.35 ± 16% perf-profile.cycles.anon_pipe_buf_release.pipe_read.__vfs_read.vfs_read.sys_read
5.04 ± 49% +239.8% 17.13 ± 9% perf-profile.cycles.apic_timer_interrupt
3.18 ± 12% -34.0% 2.10 ± 7% perf-profile.cycles.autoremove_wake_function.__wake_up_common.__wake_up_sync_key.pipe_read.__vfs_read
1.06 ± 77% +158.9% 2.76 ± 5% perf-profile.cycles.call_cpuidle.cpu_startup_entry.start_secondary
0.27 ±133% +230.6% 0.89 ± 24% perf-profile.cycles.clockevents_program_event.tick_program_event.hrtimer_interrupt.local_apic_timer_interrupt.smp_apic_timer_interrupt
17.49 ± 3% -21.5% 13.73 ± 1% perf-profile.cycles.copy_page_from_iter.pipe_write.__vfs_write.vfs_write.sys_write
1.45 ± 9% -21.4% 1.14 ± 13% perf-profile.cycles.copy_page_from_iter_iovec.copy_page_from_iter.pipe_write.__vfs_write.vfs_write
24.42 ± 3% -15.2% 20.70 ± 2% perf-profile.cycles.copy_page_to_iter.pipe_read.__vfs_read.vfs_read.sys_read
15.67 ± 3% -22.7% 12.12 ± 1% perf-profile.cycles.copy_user_generic_string.copy_page_from_iter.pipe_write.__vfs_write.vfs_write
23.42 ± 3% -15.8% 19.72 ± 2% perf-profile.cycles.copy_user_generic_string.copy_page_to_iter.pipe_read.__vfs_read.vfs_read
1.80 ± 54% +329.2% 7.71 ± 5% perf-profile.cycles.cpu_startup_entry.start_secondary
1.06 ± 77% +157.0% 2.74 ± 5% perf-profile.cycles.cpuidle_enter.call_cpuidle.cpu_startup_entry.start_secondary
1.46 ± 12% -31.4% 1.00 ± 14% perf-profile.cycles.deactivate_task.__schedule.schedule.futex_wait_queue_me.futex_wait
3.17 ± 12% -34.1% 2.09 ± 7% perf-profile.cycles.default_wake_function.autoremove_wake_function.__wake_up_common.__wake_up_sync_key.pipe_read
17.95 ± 7% -42.3% 10.36 ± 2% perf-profile.cycles.do_futex.sys_futex.entry_SYSCALL_64_fastpath
8.78 ± 9% -36.6% 5.57 ± 4% perf-profile.cycles.dump_trace.save_stack_trace_tsk.__account_scheduler_latency.enqueue_entity.enqueue_task_fair
9.98 ± 10% -45.7% 5.42 ± 4% perf-profile.cycles.enqueue_entity.enqueue_task_fair.activate_task.ttwu_do_activate.try_to_wake_up
0.16 ± 60% +843.1% 1.53 ± 10% perf-profile.cycles.enqueue_task_fair.activate_task.ttwu_do_activate.sched_ttwu_pending.cpu_startup_entry
1.60 ± 13% -65.3% 0.55 ± 15% perf-profile.cycles.enqueue_task_fair.activate_task.ttwu_do_activate.sched_ttwu_pending.scheduler_ipi
2.29 ± 11% -34.1% 1.51 ± 9% perf-profile.cycles.enqueue_task_fair.activate_task.ttwu_do_activate.try_to_wake_up.default_wake_function
8.37 ± 14% -50.9% 4.11 ± 5% perf-profile.cycles.enqueue_task_fair.activate_task.ttwu_do_activate.try_to_wake_up.wake_up_q
83.20 ± 3% -19.0% 67.36 ± 2% perf-profile.cycles.entry_SYSCALL_64_fastpath
1.17 ± 19% -60.8% 0.46 ± 34% perf-profile.cycles.exit_to_usermode_loop.syscall_return_slowpath.entry_SYSCALL_64_fastpath
3.13 ± 12% -33.5% 2.08 ± 17% perf-profile.cycles.free_hot_cold_page.__put_page.anon_pipe_buf_release.pipe_read.__vfs_read
1.68 ± 13% -51.9% 0.81 ± 13% perf-profile.cycles.free_pcppages_bulk.free_hot_cold_page.__put_page.anon_pipe_buf_release.pipe_read
8.80 ± 7% -58.6% 3.64 ± 6% perf-profile.cycles.futex_requeue.do_futex.sys_futex.entry_SYSCALL_64_fastpath
4.01 ± 12% -21.3% 3.16 ± 3% perf-profile.cycles.futex_wait.do_futex.sys_futex.entry_SYSCALL_64_fastpath
3.38 ± 8% -17.7% 2.78 ± 3% perf-profile.cycles.futex_wait_queue_me.futex_wait.do_futex.sys_futex.entry_SYSCALL_64_fastpath
4.59 ± 10% -26.7% 3.37 ± 11% perf-profile.cycles.futex_wake.do_futex.sys_futex.entry_SYSCALL_64_fastpath
4.08 ± 3% -21.1% 3.22 ± 5% perf-profile.cycles.get_page_from_freelist.__alloc_pages_nodemask.alloc_pages_current.pipe_write.__vfs_write
3.29 ± 59% +274.5% 12.30 ± 8% perf-profile.cycles.hrtimer_interrupt.local_apic_timer_interrupt.smp_apic_timer_interrupt.apic_timer_interrupt
0.33 ± 32% +514.2% 2.06 ± 3% perf-profile.cycles.intel_idle.cpuidle_enter_state.cpuidle_enter.call_cpuidle.cpu_startup_entry
0.67 ± 99% +290.4% 2.63 ± 17% perf-profile.cycles.irq_exit.smp_apic_timer_interrupt.apic_timer_interrupt
1.07 ± 3% -49.4% 0.54 ± 27% perf-profile.cycles.is_ftrace_trampoline.__kernel_text_address.print_context_stack.dump_trace.save_stack_trace_tsk
1.74 ± 12% -32.8% 1.17 ± 10% perf-profile.cycles.is_module_text_address.__kernel_text_address.print_context_stack.dump_trace.save_stack_trace_tsk
0.76 ± 22% -39.6% 0.46 ± 17% perf-profile.cycles.list_del.__rmqueue.get_page_from_freelist.__alloc_pages_nodemask.alloc_pages_current
3.41 ± 59% +268.5% 12.55 ± 8% perf-profile.cycles.local_apic_timer_interrupt.smp_apic_timer_interrupt.apic_timer_interrupt
0.93 ± 29% +369.4% 4.37 ± 6% perf-profile.cycles.mutex_lock.pipe_read.__vfs_read.vfs_read.sys_read
0.44 ± 39% +273.3% 1.64 ± 16% perf-profile.cycles.mutex_lock.pipe_wait.pipe_write.__vfs_write.vfs_write
0.88 ± 31% +394.0% 4.32 ± 5% perf-profile.cycles.mutex_optimistic_spin.__mutex_lock_slowpath.mutex_lock.pipe_read.__vfs_read
0.41 ± 38% +283.7% 1.59 ± 17% perf-profile.cycles.mutex_optimistic_spin.__mutex_lock_slowpath.mutex_lock.pipe_wait.pipe_write
0.85 ± 33% +396.2% 4.24 ± 5% perf-profile.cycles.mutex_spin_on_owner.isra.4.mutex_optimistic_spin.__mutex_lock_slowpath.mutex_lock.pipe_read
0.41 ± 38% +276.5% 1.56 ± 18% perf-profile.cycles.mutex_spin_on_owner.isra.4.mutex_optimistic_spin.__mutex_lock_slowpath.mutex_lock.pipe_wait
0.23 ± 98% +298.9% 0.92 ± 12% perf-profile.cycles.perf_event_task_tick.scheduler_tick.update_process_times.tick_sched_handle.tick_sched_timer
1.55 ± 11% +60.2% 2.49 ± 12% perf-profile.cycles.pipe_wait.pipe_write.__vfs_write.vfs_write.sys_write
26.79 ± 3% -14.0% 23.05 ± 3% perf-profile.cycles.pipe_write.__vfs_write.vfs_write.sys_write.entry_SYSCALL_64_fastpath
0.67 ± 97% -90.7% 0.06 ± 54% perf-profile.cycles.poll_idle.cpuidle_enter_state.cpuidle_enter.call_cpuidle.cpu_startup_entry
7.72 ± 10% -36.3% 4.92 ± 5% perf-profile.cycles.print_context_stack.dump_trace.save_stack_trace_tsk.__account_scheduler_latency.enqueue_entity
0.22 ± 27% +448.9% 1.23 ± 18% perf-profile.cycles.rcu_check_callbacks.update_process_times.tick_sched_handle.tick_sched_timer.__hrtimer_run_queues
1.72 ± 10% -82.1% 0.31 ± 16% perf-profile.cycles.reschedule_interrupt
0.36 ± 82% +222.2% 1.16 ± 22% perf-profile.cycles.run_timer_softirq.__do_softirq.irq_exit.smp_apic_timer_interrupt.apic_timer_interrupt
8.82 ± 10% -36.9% 5.57 ± 3% perf-profile.cycles.save_stack_trace_tsk.__account_scheduler_latency.enqueue_entity.enqueue_task_fair.activate_task
0.23 ± 40% +625.8% 1.69 ± 4% perf-profile.cycles.sched_ttwu_pending.cpu_startup_entry.start_secondary
1.37 ± 11% -81.2% 0.26 ± 18% perf-profile.cycles.sched_ttwu_pending.scheduler_ipi.smp_reschedule_interrupt.reschedule_interrupt
1.09 ± 21% -63.3% 0.40 ± 29% perf-profile.cycles.schedule.exit_to_usermode_loop.syscall_return_slowpath.entry_SYSCALL_64_fastpath
3.00 ± 8% -14.5% 2.57 ± 4% perf-profile.cycles.schedule.futex_wait_queue_me.futex_wait.do_futex.sys_futex
0.96 ± 10% -24.1% 0.72 ± 13% perf-profile.cycles.schedule.pipe_wait.pipe_write.__vfs_write.vfs_write
0.11 ± 44% +675.6% 0.87 ± 19% perf-profile.cycles.schedule.schedule_preempt_disabled.cpu_startup_entry.start_secondary
0.11 ± 44% +675.6% 0.87 ± 19% perf-profile.cycles.schedule_preempt_disabled.cpu_startup_entry.start_secondary
1.47 ± 14% -82.0% 0.27 ± 21% perf-profile.cycles.scheduler_ipi.smp_reschedule_interrupt.reschedule_interrupt
1.65 ± 61% +266.8% 6.04 ± 9% perf-profile.cycles.scheduler_tick.update_process_times.tick_sched_handle.tick_sched_timer.__hrtimer_run_queues
1.38 ± 17% -66.1% 0.47 ± 13% perf-profile.cycles.select_task_rq_fair.try_to_wake_up.wake_up_q.futex_requeue.do_futex
4.41 ± 62% +258.0% 15.80 ± 9% perf-profile.cycles.smp_apic_timer_interrupt.apic_timer_interrupt
1.50 ± 14% -80.8% 0.29 ± 17% perf-profile.cycles.smp_reschedule_interrupt.reschedule_interrupt
1.81 ± 54% +336.0% 7.89 ± 4% perf-profile.cycles.start_secondary
18.17 ± 7% -42.4% 10.46 ± 2% perf-profile.cycles.sys_futex.entry_SYSCALL_64_fastpath
35.99 ± 2% -9.8% 32.45 ± 3% perf-profile.cycles.sys_read.entry_SYSCALL_64_fastpath
27.21 ± 3% -14.1% 23.37 ± 3% perf-profile.cycles.sys_write.entry_SYSCALL_64_fastpath
1.21 ± 17% -57.6% 0.52 ± 32% perf-profile.cycles.syscall_return_slowpath.entry_SYSCALL_64_fastpath
0.85 ± 52% +261.2% 3.07 ± 7% perf-profile.cycles.task_tick_fair.scheduler_tick.update_process_times.tick_sched_handle.tick_sched_timer
0.32 ±134% +244.2% 1.11 ± 21% perf-profile.cycles.tick_program_event.hrtimer_interrupt.local_apic_timer_interrupt.smp_apic_timer_interrupt.apic_timer_interrupt
2.31 ± 50% +274.6% 8.66 ± 6% perf-profile.cycles.tick_sched_handle.isra.19.tick_sched_timer.__hrtimer_run_queues.hrtimer_interrupt.local_apic_timer_interrupt
2.44 ± 53% +279.1% 9.23 ± 6% perf-profile.cycles.tick_sched_timer.__hrtimer_run_queues.hrtimer_interrupt.local_apic_timer_interrupt.smp_apic_timer_interrupt
3.17 ± 12% -33.4% 2.11 ± 7% perf-profile.cycles.try_to_wake_up.default_wake_function.autoremove_wake_function.__wake_up_common.__wake_up_sync_key
7.72 ± 8% -61.4% 2.98 ± 6% perf-profile.cycles.try_to_wake_up.wake_up_q.futex_requeue.do_futex.sys_futex
4.04 ± 8% -27.5% 2.93 ± 11% perf-profile.cycles.try_to_wake_up.wake_up_q.futex_wake.do_futex.sys_futex
0.19 ± 51% +728.6% 1.59 ± 7% perf-profile.cycles.ttwu_do_activate.constprop.89.sched_ttwu_pending.cpu_startup_entry.start_secondary
1.72 ± 11% -61.5% 0.66 ± 15% perf-profile.cycles.ttwu_do_activate.constprop.89.sched_ttwu_pending.scheduler_ipi.smp_reschedule_interrupt.reschedule_interrupt
2.43 ± 13% -31.6% 1.66 ± 7% perf-profile.cycles.ttwu_do_activate.constprop.89.try_to_wake_up.default_wake_function.autoremove_wake_function.__wake_up_common
5.57 ± 16% -60.2% 2.21 ± 11% perf-profile.cycles.ttwu_do_activate.constprop.89.try_to_wake_up.wake_up_q.futex_requeue.do_futex
3.43 ± 8% -36.8% 2.17 ± 12% perf-profile.cycles.ttwu_do_activate.constprop.89.try_to_wake_up.wake_up_q.futex_wake.do_futex
0.31 ± 83% +243.9% 1.06 ± 6% perf-profile.cycles.update_cfs_shares.task_tick_fair.scheduler_tick.update_process_times.tick_sched_handle
2.27 ± 52% +274.1% 8.49 ± 5% perf-profile.cycles.update_process_times.tick_sched_handle.tick_sched_timer.__hrtimer_run_queues.hrtimer_interrupt
35.85 ± 2% -9.7% 32.36 ± 3% perf-profile.cycles.vfs_read.sys_read.entry_SYSCALL_64_fastpath
27.07 ± 2% -14.1% 23.25 ± 3% perf-profile.cycles.vfs_write.sys_write.entry_SYSCALL_64_fastpath
7.87 ± 7% -60.7% 3.09 ± 5% perf-profile.cycles.wake_up_q.futex_requeue.do_futex.sys_futex.entry_SYSCALL_64_fastpath
4.09 ± 8% -27.0% 2.98 ± 10% perf-profile.cycles.wake_up_q.futex_wake.do_futex.sys_futex.entry_SYSCALL_64_fastpath
lkp-snb01: Sandy Bridge-EP
Memory: 32G
pigz.throughput
3.58e+08 ++---------------------------------------------------------------+
*. .*.*..*. .*.*.. .*. .*. |
3.56e+08 ++*..*.*..* *..*.*.*. *.*..* *..*.*..*.*.*. *..*.*
| |
| |
3.54e+08 ++ |
| |
3.52e+08 ++ |
| |
3.5e+08 ++ |
| |
| |
3.48e+08 ++ O O O O O |
O O O O O O O O |
3.46e+08 ++-----O----------------O----O------O----------------------------+
pigz.time.user_time
9450 ++-------------------------------*-----------------------------------+
9400 *+.*.*..*.*..*.*..*.*..*..*. .* *.*..*.*..*..*.*..*.*..*.*..*.*..*
| *. |
9350 ++ |
9300 ++ |
| |
9250 ++ |
9200 ++ |
9150 ++ |
| |
9100 ++ |
9050 ++ |
| O O |
9000 O+ O O O O O O O |
8950 ++------O-O---------------O----O----O-O--O---------------------------+
pigz.time.system_time
110 ++--------------------------------------------------------------------+
*.. .*.. .*.. .*.. .*.. .*.. .*.. .*
108 ++ *.*..*.*. *.*..* *..*.*..* *.*. *.*..*.*. * *.*. |
106 ++ |
| |
104 ++ |
102 ++ |
| |
100 ++ |
98 ++ |
| |
96 ++ |
94 O+ O O O O O O O O O |
| O O O O O O O |
92 ++--------------------------------------------------------------------+
pigz.time.percent_of_cpu_this_job_got
3180 *+-----------*-*--*--------------*----*----*------------*--*-*----*--+
| *.*..*.*. *..*..*.*..* * * *..*.*..* * *
3160 ++ |
3140 ++ |
| |
3120 ++ |
3100 ++ |
| |
3080 ++ |
3060 ++ |
| |
3040 ++ O O |
3020 ++ O O O O O O |
O O O O O O O O O |
3000 ++-------------------------------------------------------------------+
pigz.time.voluntary_context_switches
4.6e+06 ++----------------------------------------------------------------+
*. .*.. .*. .*. .*. .*. .*.|
4.4e+06 ++*..*.*..*.*..* *.*.*..*.*. *. *. *.*..*.*. *..*.*. *
| |
4.2e+06 ++ |
| |
4e+06 ++ |
| |
3.8e+06 ++ |
| |
3.6e+06 ++ |
| |
3.4e+06 ++ |
O O O O O O O O O O O O O O O O O |
3.2e+06 ++----------------------------------------------------------------+
pigz.time.involuntary_context_switches
3.5e+06 ++----------------------------------------------------------------+
| .*. |
*.*..*.*..*.*..*.*..*.*.*..*.*. *..*.*..*.*.*..*.*..*.*..*.*..*.*
3e+06 ++ |
| |
| |
2.5e+06 ++ |
| |
2e+06 ++ |
| |
| |
1.5e+06 ++ |
| O O O |
O O O O O O O O O O O O O O |
1e+06 ++----------------------------------------------------------------+
[*] bisect-good sample
[O] bisect-bad sample
To reproduce:
git clone git://git.kernel.org/pub/scm/linux/kernel/git/wfg/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
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 Ye
6 years, 1 month
[lkp] [scsi] cff4ea87f6: kernel BUG at drivers/scsi/scsi_scan.c:319!
by kernel test robot
FYI, we noticed the below changes on
https://github.com/0day-ci/linux Johannes-Thumshirn/scsi-Add-intermediate-STARGET_REMOVE-state-to-scsi_target_state/20160324-175806
commit cff4ea87f621a3ba6c6039909ea7460640ca3feb ("scsi: Add intermediate STARGET_REMOVE state to scsi_target_state")
+------------------------------------------+------------+------------+
| | fd30a352d5 | cff4ea87f6 |
+------------------------------------------+------------+------------+
| boot_successes | 10 | 15 |
| boot_failures | 1 | 7 |
| BUG:kernel_test_crashed | 1 | |
| kernel_BUG_at_drivers/scsi/scsi_scan.c | 0 | 7 |
| invalid_opcode:#[##]SMP | 0 | 7 |
| RIP:scsi_target_destroy | 0 | 7 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 7 |
| backtrace:async_run_entry_fn | 0 | 7 |
+------------------------------------------+------------+------------+
[ 8.809555] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc080 irq 14
[ 8.811547] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc088 irq 15
[ 8.814404] ------------[ cut here ]------------
[ 8.815538] kernel BUG at drivers/scsi/scsi_scan.c:319!
[ 8.816893] invalid opcode: 0000 [#1] SMP
[ 8.818243] Modules linked in: snd soundcore ata_piix aesni_intel(+) lrw gf128mul glue_helper ablk_helper cryptd pcspkr serio_raw virtio_scsi floppy(+) parport_pc(+) parport i2c_piix4 libata acpi_cpufreq
[ 8.823529] CPU: 1 PID: 63 Comm: kworker/u4:1 Not tainted 4.5.0-rc6-00276-gcff4ea8 #1
[ 8.825450] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 8.828769] Workqueue: events_unbound async_run_entry_fn
[ 8.830922] task: ffff8800177fa580 ti: ffff88003f8d4000 task.ti: ffff88003f8d4000
[ 8.834324] RIP: 0010:[<ffffffff815b4494>] [<ffffffff815b4494>] scsi_target_destroy+0xc4/0xd0
[ 8.837995] RSP: 0018:ffff88003f8d7c70 EFLAGS: 00010293
[ 8.839975] RAX: 0000000000000001 RBX: ffff8800350a2000 RCX: 000000000000102b
[ 8.842375] RDX: 0000000000000081 RSI: 0000000000000246 RDI: ffff8800350a2208
[ 8.844765] RBP: ffff88003f8d7c98 R08: 000000000001a5a0 R09: ffffffff8118e2f2
[ 8.846934] R10: ffffea0000fe50c0 R11: 0000000000000000 R12: ffff88003fbd9c00
[ 8.848631] R13: ffff88003fbd9c28 R14: 0000000000000000 R15: ffff8800350a2208
[ 8.850270] FS: 0000000000000000(0000) GS:ffff880037300000(0000) knlGS:0000000000000000
[ 8.853058] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 8.854533] CR2: 0000559f3a6e0038 CR3: 0000000017b09000 CR4: 00000000000406e0
[ 8.856200] Stack:
[ 8.857411] ffff88003fbd9c00 ffff88003fbd9c28 ffffffffffffffff 0000000000000000
[ 8.860228] ffff8800350a2208 ffff88003f8d7cb8 ffffffff815b44d5 ffff88003fbd9c00
[ 8.862413] ffff88003fbd9c28 ffff88003f8d7cc8 ffffffff815b5d9c ffff88003f8d7d10
[ 8.865692] Call Trace:
[ 8.867019] [<ffffffff815b44d5>] scsi_target_reap_ref_release+0x35/0x40
[ 8.869039] [<ffffffff815b5d9c>] scsi_target_reap+0x2c/0x30
[ 8.870302] [<ffffffff815b5f98>] __scsi_scan_target+0xa8/0x230
[ 8.871535] [<ffffffff815b626f>] scsi_scan_channel+0x5f/0x80
[ 8.872827] [<ffffffff815b6416>] scsi_scan_host_selected+0xf6/0x1a0
[ 8.874173] [<ffffffff815b654d>] do_scsi_scan_host+0x8d/0x90
[ 8.875410] [<ffffffff815b671c>] do_scan_async+0x1c/0x180
[ 8.876621] [<ffffffff8109b19a>] async_run_entry_fn+0x4a/0x140
[ 8.877859] [<ffffffff81092365>] process_one_work+0x155/0x440
[ 8.879228] [<ffffffff81092fae>] worker_thread+0x4e/0x4c0
[ 8.880482] [<ffffffff818e157c>] ? __schedule+0x35c/0x8e0
[ 8.881680] [<ffffffff81092f60>] ? rescuer_thread+0x350/0x350
[ 8.882907] [<ffffffff81092f60>] ? rescuer_thread+0x350/0x350
[ 8.884169] [<ffffffff81098554>] kthread+0xd4/0xf0
[ 8.885314] [<ffffffff81098480>] ? kthread_park+0x60/0x60
[ 8.886543] [<ffffffff818e633f>] ret_from_fork+0x3f/0x70
[ 8.887718] [<ffffffff81098480>] ? kthread_park+0x60/0x60
[ 8.889237] Code: 89 fe 48 8b 7b 58 e8 0c 15 33 00 4c 89 ef e8 94 b7 fb ff 5b 41 5c 41 5d 41 5e 41 5f 5d c3 31 db 41 83 bc 24 24 03 00 00 03 74 85 <0f> 0b 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 44 00 00 55 48 89 e5
[ 8.898654] RIP [<ffffffff815b4494>] scsi_target_destroy+0xc4/0xd0
[ 8.900072] RSP <ffff88003f8d7c70>
[ 8.901034] ---[ end trace a8392849b128d3d2 ]---
[ 8.902216] Kernel panic - not syncing: Fatal exception
FYI, raw QEMU command line is:
qemu-system-x86_64 -enable-kvm -cpu Haswell,+smep,+smap -kernel /pkg/linux/x86_64-rhel/gcc-4.9/cff4ea87f621a3ba6c6039909ea7460640ca3feb/vmlinuz-4.5.0-rc6-00276-gcff4ea8 -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-kbuild-1G-9/rand_boot-1-debian-x86_64-2015-02-07.cgz-x86_64-rhel-cff4ea87f621a3ba6c6039909ea7460640ca3feb-20160324-62719-rt4gmw-0.yaml ARCH=x86_64 kconfig=x86_64-rhel branch=linux-review/Johannes-Thumshirn/scsi-Add-intermediate-STARGET_REMOVE-state-to-scsi_target_state/20160324-175806 commit=cff4ea87f621a3ba6c6039909ea7460640ca3feb BOOT_IMAGE=/pkg/linux/x86_64-rhel/gcc-4.9/cff4ea87f621a3ba6c6039909ea7460640ca3feb/vmlinuz-4.5.0-rc6-00276-gcff4ea8 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-kbuild-1G/debian-x86_64-2015-02-07.cgz/x86_64-rhel/gcc-4.9/cff4ea87f621a3ba6c6039909ea7460640ca3feb/0 LKP_SERVER=inn earlyprintk=ttyS0,115200 systemd.log_level=err debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal rw ip=::::vm-kbuild-1G-9::dhcp' -initrd /fs/sdc1/initrd-vm-kbuild-1G-9 -m 1024 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0,hostfwd=tcp::23008-:22 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -device virtio-scsi-pci,id=scsi0 -drive file=/fs/sdc1/disk0-vm-kbuild-1G-9,if=none,id=hd0,media=disk,aio=native,cache=none -device scsi-hd,bus=scsi0.0,drive=hd0,scsi-id=1,lun=0 -drive file=/fs/sdc1/disk1-vm-kbuild-1G-9,if=none,id=hd1,media=disk,aio=native,cache=none -device scsi-hd,bus=scsi0.0,drive=hd1,scsi-id=1,lun=1 -drive file=/fs/sdc1/disk2-vm-kbuild-1G-9,if=none,id=hd2,media=disk,aio=native,cache=none -device scsi-hd,bus=scsi0.0,drive=hd2,scsi-id=1,lun=2 -drive file=/fs/sdc1/disk3-vm-kbuild-1G-9,if=none,id=hd3,media=disk,aio=native,cache=none -device scsi-hd,bus=scsi0.0,drive=hd3,scsi-id=1,lun=3 -drive file=/fs/sdc1/disk4-vm-kbuild-1G-9,if=none,id=hd4,media=disk,aio=native,cache=none -device scsi-hd,bus=scsi0.0,drive=hd4,scsi-id=1,lun=4 -pidfile /dev/shm/kboot/pid-vm-kbuild-1G-9 -serial file:/dev/shm/kboot/serial-vm-kbuild-1G-9 -daemonize -display none -monitor null
Thanks,
Kernel Test Robot
6 years, 1 month
[ppdev] e7223f1860: kernel BUG at drivers/base/driver.c:153!
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/gregkh/char-misc.git char-misc-testing
commit e7223f18603374d235d8bb0398532323e5f318b9
Author: Sudip Mukherjee <sudipm.mukherjee(a)gmail.com>
AuthorDate: Fri Feb 12 18:33:45 2016 +0530
Commit: Greg Kroah-Hartman <gregkh(a)linuxfoundation.org>
CommitDate: Sun Feb 14 17:43:50 2016 -0800
ppdev: use new parport device model
Modify ppdev driver to use the new parallel port device model.
Signed-off-by: Sudip Mukherjee <sudip(a)vectorindia.org>
Signed-off-by: Greg Kroah-Hartman <gregkh(a)linuxfoundation.org>
+---------------------------------------------------------------------+------------+------------+------------+
| | 3c8db58432 | e7223f1860 | 8eb81b9e40 |
+---------------------------------------------------------------------+------------+------------+------------+
| boot_successes | 29 | 0 | 0 |
| boot_failures | 67 | 30 | 26 |
| Kernel_panic-not_syncing:Attempted_to_kill_init!exitcode= | 67 | | |
| kernel_BUG_at_drivers/base/driver.c | 0 | 30 | 26 |
| invalid_opcode:#[##] | 0 | 30 | 26 |
| invalid_opcode:#[##]PREEMPT_PREEMPT_DEBUG_PAGEALLOC_DEBUG_PAGEALLOC | 0 | 30 | 26 |
| RIP:driver_register | 0 | 30 | 26 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 30 | 26 |
| backtrace:ppdev_init | 0 | 30 | 26 |
| backtrace:kernel_init_freeable | 0 | 30 | 26 |
+---------------------------------------------------------------------+------------+------------+------------+
[ 1.808834] Non-volatile memory driver v1.3
[ 1.810078] ------------[ cut here ]------------
[ 1.810078] ------------[ cut here ]------------
[ 1.810982] kernel BUG at drivers/base/driver.c:153!
[ 1.810982] kernel BUG at drivers/base/driver.c:153!
[ 1.812297] invalid opcode: 0000 [#1]
[ 1.812297] invalid opcode: 0000 [#1] PREEMPT PREEMPT DEBUG_PAGEALLOC DEBUG_PAGEALLOC
[ 1.813465] CPU: 0 PID: 1 Comm: swapper Not tainted 4.5.0-rc4-00154-ge7223f1 #2
[ 1.813465] CPU: 0 PID: 1 Comm: swapper Not tainted 4.5.0-rc4-00154-ge7223f1 #2
[ 1.814852] task: ffff88000f468000 ti: ffff88000f46c000 task.ti: ffff88000f46c000
[ 1.814852] task: ffff88000f468000 ti: ffff88000f46c000 task.ti: ffff88000f46c000
[ 1.816276] RIP: 0010:[<ffffffff816e0e51>]
[ 1.816276] RIP: 0010:[<ffffffff816e0e51>] [<ffffffff816e0e51>] driver_register+0xa1/0xd0
[<ffffffff816e0e51>] driver_register+0xa1/0xd0
[ 1.817855] RSP: 0000:ffff88000f46fe70 EFLAGS: 00010246
[ 1.817855] RSP: 0000:ffff88000f46fe70 EFLAGS: 00010246
[ 1.818867] RAX: ffffffff82ef039c RBX: 000000000e136c08 RCX: 0000000000000000
[ 1.818867] RAX: ffffffff82ef039c RBX: 000000000e136c08 RCX: 0000000000000000
[ 1.820206] RDX: ffffffff82ef039c RSI: ffffffff83459b80 RDI: ffffffff83456428
[ 1.820206] RDX: ffffffff82ef039c RSI: ffffffff83459b80 RDI: ffffffff83456428
[ 1.821559] RBP: ffff88000f46fe88 R08: 0000000000000001 R09: 0000000000000000
[ 1.821559] RBP: ffff88000f46fe88 R08: 0000000000000001 R09: 0000000000000000
[ 1.822913] R10: 0000000000000000 R11: 0000000000000000 R12: ffffffff83456400
[ 1.822913] R10: 0000000000000000 R11: 0000000000000000 R12: ffffffff83456400
[ 1.824259] R13: 0000000000000000 R14: ffffffff836bd0b0 R15: 0000000000000000
[ 1.824259] R13: 0000000000000000 R14: ffffffff836bd0b0 R15: 0000000000000000
[ 1.825613] FS: 0000000000000000(0000) GS:ffffffff83221000(0000) knlGS:0000000000000000
[ 1.825613] FS: 0000000000000000(0000) GS:ffffffff83221000(0000) knlGS:0000000000000000
[ 1.827147] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 1.827147] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 1.828240] CR2: 0000000000000000 CR3: 0000000003210000 CR4: 00000000001406b0
[ 1.828240] CR2: 0000000000000000 CR3: 0000000003210000 CR4: 00000000001406b0
[ 1.829590] Stack:
[ 1.829590] Stack:
[ 1.829988] ffffffff816d7c89
[ 1.829988] ffffffff816d7c89 000000000e136c08 000000000e136c08 ffffffff8364e617 ffffffff8364e617 ffff88000f46fea0 ffff88000f46fea0
[ 1.831442] ffffffff8364e695
[ 1.831442] ffffffff8364e695 ffff88000e136af0 ffff88000e136af0 ffff88000f46ff10 ffff88000f46ff10 ffffffff8361e0b5 ffffffff8361e0b5
[ 1.832903] 0000000000000000
[ 1.832903] 0000000000000000 ffffffff8312c928 ffffffff8312c928 000005ca00060006 000005ca00060006 ffffffff82f32904 ffffffff82f32904
[ 1.834368] Call Trace:
[ 1.834368] Call Trace:
[ 1.834841] [<ffffffff816d7c89>] ? __parport_register_driver+0x39/0x100
[ 1.834841] [<ffffffff816d7c89>] ? __parport_register_driver+0x39/0x100
[ 1.836121] [<ffffffff8364e617>] ? rng_init+0x12/0x12
[ 1.836121] [<ffffffff8364e617>] ? rng_init+0x12/0x12
[ 1.837099] [<ffffffff8364e695>] ppdev_init+0x7e/0xcb
[ 1.837099] [<ffffffff8364e695>] ppdev_init+0x7e/0xcb
[ 1.838081] [<ffffffff8361e0b5>] do_one_initcall+0xf0/0x183
[ 1.838081] [<ffffffff8361e0b5>] do_one_initcall+0xf0/0x183
[ 1.839164] [<ffffffff8361e249>] kernel_init_freeable+0x101/0x187
[ 1.839164] [<ffffffff8361e249>] kernel_init_freeable+0x101/0x187
[ 1.840344] [<ffffffff82695b10>] ? rest_init+0x130/0x130
[ 1.840344] [<ffffffff82695b10>] ? rest_init+0x130/0x130
[ 1.841379] [<ffffffff82695b19>] kernel_init+0x9/0xe0
[ 1.841379] [<ffffffff82695b19>] kernel_init+0x9/0xe0
[ 1.842366] [<ffffffff8269fb1f>] ret_from_fork+0x3f/0x70
[ 1.842366] [<ffffffff8269fb1f>] ret_from_fork+0x3f/0x70
[ 1.843405] [<ffffffff82695b10>] ? rest_init+0x130/0x130
[ 1.843405] [<ffffffff82695b10>] ? rest_init+0x130/0x130
[ 1.844437] Code:
[ 1.844437] Code: ff ff 85 85 c0 c0 41 41 89 89 c4 c4 75 75 13 13 48 48 8b 8b 7b 7b 70 70 31 31 f6 f6 e8 e8 47 47 d3 d3 e8 e8 ff ff 44 44 89 89 e0 e0 5b 5b 41 41 5c 5c 5d 5d c3 c3 48 48 89 89 df df e8 e8 b7 b7 df df ff ff ff ff 44 44 89 89 e0 e0 5b 5b 41 41 5c 5c 5d 5d c3 c3 <0f> <0f> 0b 0b 48 48 8b 8b 33 33 48 48 c7 c7 c7 c7 58 58 f3 f3 f8 f8 82 82 e8 e8 ed ed 24 24 a5 a5 ff ff 48 48 8b 8b 73 73 08 08
[ 1.848930] RIP
[ 1.848930] RIP [<ffffffff816e0e51>] driver_register+0xa1/0xd0
[<ffffffff816e0e51>] driver_register+0xa1/0xd0
[ 1.850075] RSP <ffff88000f46fe70>
[ 1.850075] RSP <ffff88000f46fe70>
[ 1.850780] ---[ end trace 301e5f19aca79aa0 ]---
[ 1.850780] ---[ end trace 301e5f19aca79aa0 ]---
git bisect start 8eb81b9e40322ecdc7c326ca3d429af82dc71c45 18558cae0272f8fd9647e69d3fec1565a7949865 --
git bisect good 81177251dd233e878a3ab7fa4fb34a9d985c69ab # 11:25 22+ 1 Merge 'tty/tty-testing' into devel-catchup-201602151050
git bisect bad 408808ab2ab758c6fa1bdec8fe4d7f7120779186 # 11:33 0- 12 Merge 'linux-review/Simon-Horman/MAINTAINERS-Update-mailing-list-for-Renesas-ARM64-SoC-Development/20160215-095242' into devel-catchup-201602151050
git bisect good 0a6b1d10601e41de57eeadcf17164d7e668425b7 # 11:41 22+ 0 Merge 'jsakkine-tpmdd/master' into devel-catchup-201602151050
git bisect bad 84e1e09d3107ab136b1e932d9d8e6fe9097e21be # 11:47 0- 24 Merge 'char-misc/char-misc-testing' into devel-catchup-201602151050
git bisect good 52210c8745e418f82f3f0aeeee01d7bc4858812a # 12:03 22+ 7 coresight: implementing 'cpu_id()' API
git bisect good 202cdb6f889bf45e1cb4883e1727e9909152b777 # 12:10 22+ 0 drivers: char: raw: Removed unnecessary braces
git bisect good 990162f038400bd229685316beea1155be095125 # 12:17 22+ 14 char: nwbutton: avoid unused variable warning
git bisect good 6ddf2f0cb1e1931d16e08e30130ed04e5a441f2c # 12:26 22+ 18 misc: mic: use after free printing error message
git bisect good 83e80605cc3ce6a2ac966151cd515f691e4835e0 # 12:32 22+ 24 ppdev: remove braces
git bisect good 3c8db584323875a50696718c89d94cef0ed54f30 # 12:39 22+ 22 ppdev: remove space before tab
git bisect bad 396ec3dea3d82d0a32906efe9803a8ef2647df51 # 12:47 0- 23 ppdev: use dev_* macros
git bisect bad e7223f18603374d235d8bb0398532323e5f318b9 # 12:51 0- 24 ppdev: use new parport device model
# first bad commit: [e7223f18603374d235d8bb0398532323e5f318b9] ppdev: use new parport device model
git bisect good 3c8db584323875a50696718c89d94cef0ed54f30 # 12:54 66+ 67 ppdev: remove space before tab
# extra tests with DEBUG_INFO
git bisect bad e7223f18603374d235d8bb0398532323e5f318b9 # 13:00 0- 3 ppdev: use new parport device model
# extra tests on HEAD of linux-devel/devel-catchup-201602151050
git bisect bad 8eb81b9e40322ecdc7c326ca3d429af82dc71c45 # 13:00 0- 26 0day head guard for 'devel-catchup-201602151050'
# extra tests on tree/branch char-misc/char-misc-testing
git bisect bad 396ec3dea3d82d0a32906efe9803a8ef2647df51 # 13:08 0- 30 ppdev: use dev_* macros
# extra tests with first bad commit reverted
git bisect good 695d7d11b66df48501e9b9f6f4bf94e28817b2ca # 15:48 62+ 44 Revert "ppdev: use new parport device model"
# extra tests on tree/branch linus/master
git bisect good 1926e54f115725a9248d0c4c65c22acaf94de4c4 # 16:07 62+ 8 MAINTAINERS: Update mailing list for Renesas ARM64 SoC Development
# extra tests on tree/branch linux-next/master
git bisect good 2625f908fc0cbd7e40483217772888529ecbdfd1 # 16:20 64+ 64 Add linux-next specific files for 20160215
This script may reproduce the error.
----------------------------------------------------------------------------
#!/bin/bash
kernel=$1
initrd=yocto-minimal-x86_64.cgz
wget --no-clobber https://github.com/fengguang/reproduce-kernel-bug/raw/master/initrd/$initrd
kvm=(
qemu-system-x86_64
-enable-kvm
-cpu Haswell,+smep,+smap
-kernel $kernel
-initrd $initrd
-m 256
-smp 1
-device e1000,netdev=net0
-netdev user,id=net0
-boot order=nc
-no-reboot
-watchdog i6300esb
-rtc base=localtime
-serial stdio
-display none
-monitor null
)
append=(
hung_task_panic=1
earlyprintk=ttyS0,115200
systemd.log_level=err
debug
apic=debug
sysrq_always_enabled
rcupdate.rcu_cpu_stall_timeout=100
panic=-1
softlockup_panic=1
nmi_watchdog=panic
oops=panic
load_ramdisk=2
prompt_ramdisk=0
console=ttyS0,115200
console=tty0
vga=normal
root=/dev/ram0
rw
drbd.minor_count=8
)
"${kvm[@]}" --append "${append[*]}"
----------------------------------------------------------------------------
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
6 years, 1 month
[lkp] [x86/mm] 5d230e335b: BUG: using smp_processor_id() in preemptible [00000000] code: swapper/0/1
by kernel test robot
FYI, we noticed the below changes on
https://git.kernel.org/pub/scm/linux/kernel/git/luto/linux.git x86/pcid
commit 5d230e335bd53c69be7498fe69fbdce0d4231c21 ("x86/mm: Give mmu contexts a generation id")
[ 3.428667] Write protecting the kernel read-only data: 14336k
[ 3.430040] Freeing unused kernel memory: 1252K (ffff8800018c7000 - ffff880001a00000)
[ 3.432811] Freeing unused kernel memory: 664K (ffff880001d5a000 - ffff880001e00000)
[ 3.434324] BUG: using smp_processor_id() in preemptible [00000000] code: swapper/0/1
[ 3.435848] caller is debug_smp_processor_id+0x17/0x20
[ 3.436742] CPU: 1 PID: 1 Comm: swapper/0 Not tainted 4.5.0-rc2-00216-g5d230e3 #1
[ 3.438227] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 3.439847] ffffffff81cb2e08 ffff88001349bde0 ffffffff814e8ecc 0000000000000001
[ 3.441539] ffff88001349be10 ffffffff81504229 ffff88001343aac0 ffff880013495540
[ 3.443215] ffff88001343aac0 ffff880010213000 ffff88001349be20 ffffffff81504247
[ 3.444913] Call Trace:
[ 3.445553] [<ffffffff814e8ecc>] dump_stack+0x4e/0x82
[ 3.446446] [<ffffffff81504229>] check_preemption_disabled+0xd9/0xe0
[ 3.447471] [<ffffffff81504247>] debug_smp_processor_id+0x17/0x20
[ 3.448471] [<ffffffff810521a7>] init_asid_context+0x17/0x40
[ 3.449431] [<ffffffff81013368>] init_new_context+0x18/0x100
[ 3.450388] [<ffffffff810b314e>] mm_init+0x15e/0x1b0
[ 3.451265] [<ffffffff810b341b>] mm_alloc+0x3b/0x50
[ 3.452139] [<ffffffff811a93f0>] do_execveat_common+0x240/0x650
[ 3.453184] [<ffffffff818b5d10>] ? rest_init+0x90/0x90
[ 3.454076] [<ffffffff811a9827>] do_execve+0x27/0x30
[ 3.454955] [<ffffffff81002026>] run_init_process+0x26/0x30
[ 3.455896] [<ffffffff818b5d4d>] kernel_init+0x3d/0xe0
[ 3.456807] [<ffffffff818c168f>] ret_from_fork+0x3f/0x70
[ 3.457720] [<ffffffff818b5d10>] ? rest_init+0x90/0x90
[ 3.459695] BUG: using smp_processor_id() in preemptible [00000000] code: init/1
[ 3.461175] caller is debug_smp_processor_id+0x17/0x20
[ 3.462059] CPU: 1 PID: 1 Comm: init Not tainted 4.5.0-rc2-00216-g5d230e3 #1
[ 3.463154] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 3.464748] ffffffff81cb2e08 ffff88001349bd50 ffffffff814e8ecc 0000000000000001
[ 3.466439] ffff88001349bd80 ffffffff81504229 ffff88001343a740 ffff8800101f6ac0
[ 3.468120] ffff88001343a740 0000000000000000 ffff88001349bd90 ffffffff81504247
[ 3.469815] Call Trace:
[ 3.479836] [<ffffffff814e8ecc>] dump_stack+0x4e/0x82
[ 3.480729] [<ffffffff81504229>] check_preemption_disabled+0xd9/0xe0
[ 3.481740] [<ffffffff81504247>] debug_smp_processor_id+0x17/0x20
[ 3.482727] [<ffffffff810521a7>] init_asid_context+0x17/0x40
[ 3.483962] [<ffffffff81013368>] init_new_context+0x18/0x100
[ 3.485032] [<ffffffff810b314e>] mm_init+0x15e/0x1b0
[ 3.485920] [<ffffffff810b42fd>] copy_process+0x9ed/0x1690
[ 3.486927] [<ffffffff81504247>] ? debug_smp_processor_id+0x17/0x20
[ 3.487936] [<ffffffff810b5161>] _do_fork+0xd1/0x370
[ 3.488845] [<ffffffff810db294>] ? __might_sleep+0x44/0x80
[ 3.489799] [<ffffffff8116db62>] ? __might_fault+0x32/0x40
[ 3.490733] [<ffffffff810b5484>] SyS_clone+0x14/0x20
[ 3.491620] [<ffffffff818c12f2>] entry_SYSCALL_64_fastpath+0x16/0x71
[ 3.492790] BUG: using smp_processor_id() in preemptible [00000000] code: init/140
[ 3.494283] caller is debug_smp_processor_id+0x17/0x20
[ 3.495183] CPU: 1 PID: 140 Comm: init Not tainted 4.5.0-rc2-00216-g5d230e3 #1
[ 3.496630] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 3.498252] ffffffff81cb2e08 ffff8800101b3d50 ffffffff814e8ecc 0000000000000001
[ 3.499942] ffff8800101b3d80 ffffffff81504229 ffff88001343a3c0 ffff880010670040
[ 3.501626] ffff88001343a3c0 0000000000000000 ffff8800101b3d90 ffffffff81504247
[ 3.503316] Call Trace:
[ 3.503943] [<ffffffff814e8ecc>] dump_stack+0x4e/0x82
[ 3.504850] [<ffffffff81504229>] check_preemption_disabled+0xd9/0xe0
[ 3.505869] [<ffffffff81504247>] debug_smp_processor_id+0x17/0x20
[ 3.506860] [<ffffffff810521a7>] init_asid_context+0x17/0x40
[ 3.507821] [<ffffffff81013368>] init_new_context+0x18/0x100
[ 3.508791] [<ffffffff810b314e>] mm_init+0x15e/0x1b0
[ 3.509677] [<ffffffff810b42fd>] copy_process+0x9ed/0x1690
[ 3.510681] [<ffffffff810b5161>] _do_fork+0xd1/0x370
[ 3.511564] [<ffffffff810b5484>] SyS_clone+0x14/0x20
[ 3.512451] [<ffffffff818c12f2>] entry_SYSCALL_64_fastpath+0x16/0x71
[ 3.513626] BUG: using smp_processor_id() in preemptible [00000000] code: init/141
[ 3.515130] caller is debug_smp_processor_id+0x17/0x20
[ 3.516019] CPU: 1 PID: 141 Comm: init Not tainted 4.5.0-rc2-00216-g5d230e3 #1
[ 3.517468] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 3.519096] ffffffff81cb2e08 ffff8800101bfde8 ffffffff814e8ecc 0000000000000001
[ 3.520770] ffff8800101bfe18 ffffffff81504229 ffff88001343a040 ffff880010670040
[ 3.522442] ffff88001343a040 ffff880010213000 ffff8800101bfe28 ffffffff81504247
[ 3.524124] Call Trace:
[ 3.524755] [<ffffffff814e8ecc>] dump_stack+0x4e/0x82
[ 3.525649] [<ffffffff81504229>] check_preemption_disabled+0xd9/0xe0
[ 3.526671] [<ffffffff81504247>] debug_smp_processor_id+0x17/0x20
[ 3.527676] [<ffffffff810521a7>] init_asid_context+0x17/0x40
[ 3.528636] [<ffffffff81013368>] init_new_context+0x18/0x100
[ 3.529603] [<ffffffff810b314e>] mm_init+0x15e/0x1b0
[ 3.530498] [<ffffffff810b341b>] mm_alloc+0x3b/0x50
[ 3.531377] [<ffffffff811a93f0>] do_execveat_common+0x240/0x650
[ 3.532417] [<ffffffff811a9aa5>] SyS_execve+0x35/0x40
[ 3.533307] [<ffffffff818c1595>] stub_execve+0x5/0x5
[ 3.534195] [<ffffffff818c12f2>] ? entry_SYSCALL_64_fastpath+0x16/0x71
[ 3.535578] BUG: using smp_processor_id() in preemptible [00000000] code: rcS/141
[ 3.537077] caller is debug_smp_processor_id+0x17/0x20
[ 3.537980] CPU: 1 PID: 141 Comm: rcS Not tainted 4.5.0-rc2-00216-g5d230e3 #1
[ 3.539106] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 3.540729] ffffffff81cb2e08 ffff8800101bfd50 ffffffff814e8ecc 0000000000000001
[ 3.542407] ffff8800101bfd80 ffffffff81504229 ffff88001343a3c0 ffff880013626ac0
[ 3.544094] ffff88001343a3c0 0000000000000000 ffff8800101bfd90 ffffffff81504247
[ 3.545771] Call Trace:
[ 3.546414] [<ffffffff814e8ecc>] dump_stack+0x4e/0x82
[ 3.547309] [<ffffffff81504229>] check_preemption_disabled+0xd9/0xe0
[ 3.548340] [<ffffffff81504247>] debug_smp_processor_id+0x17/0x20
[ 3.549348] [<ffffffff810521a7>] init_asid_context+0x17/0x40
[ 3.550310] [<ffffffff81013368>] init_new_context+0x18/0x100
[ 3.551272] [<ffffffff810b314e>] mm_init+0x15e/0x1b0
[ 3.552162] [<ffffffff810b42fd>] copy_process+0x9ed/0x1690
[ 3.553160] [<ffffffff811a726d>] ? cp_new_stat+0x14d/0x170
[ 3.554100] [<ffffffff810b5161>] _do_fork+0xd1/0x370
[ 3.554986] [<ffffffff810b5484>] SyS_clone+0x14/0x20
[ 3.555882] [<ffffffff818c12f2>] entry_SYSCALL_64_fastpath+0x16/0x71
[ 3.556994] BUG: using smp_processor_id() in preemptible [00000000] code: rcS/142
[ 3.558499] caller is debug_smp_processor_id+0x17/0x20
[ 3.559413] CPU: 1 PID: 142 Comm: rcS Not tainted 4.5.0-rc2-00216-g5d230e3 #1
[ 3.560506] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 3.562121] ffffffff81cb2e08 ffff8800136c3de8 ffffffff814e8ecc 0000000000000001
[ 3.563804] ffff8800136c3e18 ffffffff81504229 ffff8800136cfb00 ffff880013626ac0
[ 3.565913] ffff8800136cfb00 ffff880010213000 ffff8800136c3e28 ffffffff81504247
[ 3.567603] Call Trace:
[ 3.568246] [<ffffffff814e8ecc>] dump_stack+0x4e/0x82
[ 3.569212] [<ffffffff81504229>] check_preemption_disabled+0xd9/0xe0
[ 3.570245] [<ffffffff81504247>] debug_smp_processor_id+0x17/0x20
[ 3.571248] [<ffffffff810521a7>] init_asid_context+0x17/0x40
[ 3.572201] [<ffffffff81013368>] init_new_context+0x18/0x100
[ 3.573156] [<ffffffff810b314e>] mm_init+0x15e/0x1b0
[ 3.574040] [<ffffffff810b341b>] mm_alloc+0x3b/0x50
[ 3.574918] [<ffffffff811a93f0>] do_execveat_common+0x240/0x650
[ 3.575971] [<ffffffff811a9aa5>] SyS_execve+0x35/0x40
[ 3.576884] [<ffffffff818c1595>] stub_execve+0x5/0x5
[ 3.577776] [<ffffffff818c12f2>] ? entry_SYSCALL_64_fastpath+0x16/0x71
[ 3.579337] BUG: using smp_processor_id() in preemptible [00000000] code: rcS/141
[ 3.580830] caller is debug_smp_processor_id+0x17/0x20
[ 3.581732] CPU: 1 PID: 141 Comm: rcS Not tainted 4.5.0-rc2-00216-g5d230e3 #1
[ 3.582829] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 3.584435] ffffffff81cb2e08 ffff8800101bfde8 ffffffff814e8ecc 0000000000000001
[ 3.586128] ffff8800101bfe18 ffffffff81504229 ffff8800136cfb00 ffff880010670040
[ 3.587809] ffff8800136cfb00 ffff880010213000 ffff8800101bfe28 ffffffff81504247
[ 3.589532] Call Trace:
[ 3.590174] [<ffffffff814e8ecc>] dump_stack+0x4e/0x82
[ 3.591072] [<ffffffff81504229>] check_preemption_disabled+0xd9/0xe0
[ 3.592110] [<ffffffff81504247>] debug_smp_processor_id+0x17/0x20
[ 3.593107] [<ffffffff810521a7>] init_asid_context+0x17/0x40
[ 3.594050] [<ffffffff81013368>] init_new_context+0x18/0x100
[ 3.594998] [<ffffffff810b314e>] mm_init+0x15e/0x1b0
[ 3.595886] [<ffffffff810b341b>] mm_alloc+0x3b/0x50
[ 3.596761] [<ffffffff811a93f0>] do_execveat_common+0x240/0x650
[ 3.597799] [<ffffffff811a9aa5>] SyS_execve+0x35/0x40
[ 3.598699] [<ffffffff818c1595>] stub_execve+0x5/0x5
[ 3.599596] [<ffffffff818c12f2>] ? entry_SYSCALL_64_fastpath+0x16/0x71
[ 3.600986] BUG: using smp_processor_id() in preemptible [00000000] code: rc/141
[ 3.602478] caller is debug_smp_processor_id+0x17/0x20
[ 3.603373] CPU: 1 PID: 141 Comm: rc Not tainted 4.5.0-rc2-00216-g5d230e3 #1
[ 3.604452] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 3.606061] ffffffff81cb2e08 ffff8800101bfd50 ffffffff814e8ecc 0000000000000001
[ 3.607743] ffff8800101bfd80 ffffffff81504229 ffff88001343a040 ffff880010666ac0
[ 3.609446] ffff88001343a040 0000000000000000 ffff8800101bfd90 ffffffff81504247
[ 3.611124] Call Trace:
[ 3.611754] [<ffffffff814e8ecc>] dump_stack+0x4e/0x82
[ 3.612639] [<ffffffff81504229>] check_preemption_disabled+0xd9/0xe0
[ 3.613652] [<ffffffff81504247>] debug_smp_processor_id+0x17/0x20
[ 3.614645] [<ffffffff810521a7>] init_asid_context+0x17/0x40
[ 3.615597] [<ffffffff81013368>] init_new_context+0x18/0x100
[ 3.616547] [<ffffffff810b314e>] mm_init+0x15e/0x1b0
[ 3.617432] [<ffffffff810b42fd>] copy_process+0x9ed/0x1690
[ 3.618430] [<ffffffff811a726d>] ? cp_new_stat+0x14d/0x170
[ 3.619377] [<ffffffff810b5161>] _do_fork+0xd1/0x370
[ 3.620266] [<ffffffff810b5484>] SyS_clone+0x14/0x20
[ 3.621180] [<ffffffff818c12f2>] entry_SYSCALL_64_fastpath+0x16/0x71
[ 3.622283] BUG: using smp_processor_id() in preemptible [00000000] code: rc/143
[ 3.623747] caller is debug_smp_processor_id+0x17/0x20
[ 3.624641] CPU: 1 PID: 143 Comm: rc Not tainted 4.5.0-rc2-00216-g5d230e3 #1
[ 3.625714] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 3.627311] ffffffff81cb2e08 ffff88000f9dbde8 ffffffff814e8ecc 0000000000000001
[ 3.629433] ffff88000f9dbe18 ffffffff81504229 ffff88001343a3c0 ffff880010666ac0
[ 3.631113] ffff88001343a3c0 ffff880010213000 ffff88000f9dbe28 ffffffff81504247
[ 3.632780] Call Trace:
[ 3.633420] [<ffffffff814e8ecc>] dump_stack+0x4e/0x82
[ 3.634310] [<ffffffff81504229>] check_preemption_disabled+0xd9/0xe0
[ 3.635324] [<ffffffff81504247>] debug_smp_processor_id+0x17/0x20
[ 3.636453] [<ffffffff810521a7>] init_asid_context+0x17/0x40
[ 3.637727] [<ffffffff81013368>] init_new_context+0x18/0x100
[ 3.638691] [<ffffffff810b314e>] mm_init+0x15e/0x1b0
[ 3.639594] [<ffffffff810b341b>] mm_alloc+0x3b/0x50
[ 3.640489] [<ffffffff811a93f0>] do_execveat_common+0x240/0x650
[ 3.641549] [<ffffffff811a9aa5>] SyS_execve+0x35/0x40
[ 3.652373] [<ffffffff818c1595>] stub_execve+0x5/0x5
[ 3.653263] [<ffffffff818c12f2>] ? entry_SYSCALL_64_fastpath+0x16/0x71
[ 3.654894] BUG: using smp_processor_id() in preemptible [00000000] code: rc/141
[ 3.656379] caller is debug_smp_processor_id+0x17/0x20
[ 3.657463] CPU: 1 PID: 141 Comm: rc Not tainted 4.5.0-rc2-00216-g5d230e3 #1
[ 3.658745] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 3.660357] ffffffff81cb2e08 ffff8800101bfd50 ffffffff814e8ecc 0000000000000001
[ 3.662048] ffff8800101bfd80 ffffffff81504229 ffff88001343a3c0 ffff880013626ac0
[ 3.663733] ffff88001343a3c0 0000000000000000 ffff8800101bfd90 ffffffff81504247
[ 3.665417] Call Trace:
[ 3.666051] [<ffffffff814e8ecc>] dump_stack+0x4e/0x82
[ 3.666952] [<ffffffff81504229>] check_preemption_disabled+0xd9/0xe0
[ 3.667986] [<ffffffff81504247>] debug_smp_processor_id+0x17/0x20
[ 3.669017] [<ffffffff810521a7>] init_asid_context+0x17/0x40
[ 3.669987] [<ffffffff81013368>] init_new_context+0x18/0x100
[ 3.670948] [<ffffffff810b314e>] mm_init+0x15e/0x1b0
[ 3.671838] [<ffffffff810b42fd>] copy_process+0x9ed/0x1690
[ 3.672840] [<ffffffff810d7690>] ? preempt_count_add+0x80/0xc0
[ 3.673813] [<ffffffff810eace8>] ? remove_wait_queue+0x48/0x50
[ 3.674784] [<ffffffff810b5161>] _do_fork+0xd1/0x370
[ 3.675671] [<ffffffff810b5484>] SyS_clone+0x14/0x20
[ 3.676557] [<ffffffff818c12f2>] entry_SYSCALL_64_fastpath+0x16/0x71
[ 3.909872] gfs2: path_lookup on rootfs returned error -2
FYI, raw QEMU command line is:
qemu-system-x86_64 -enable-kvm -cpu Nehalem -kernel /pkg/linux/x86_64-randconfig-v0-03240154/gcc-5/5d230e335bd53c69be7498fe69fbdce0d4231c21/vmlinuz-4.5.0-rc2-00216-g5d230e3 -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-intel12-yocto-x86_64-9/bisect_boot-1-yocto-minimal-x86_64.cgz-x86_64-randconfig-v0-03240154-5d230e335bd53c69be7498fe69fbdce0d4231c21-20160324-49136-1pdtf4g-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-v0-03240154 branch=linux-devel/devel-catchup-201603240223 commit=5d230e335bd53c69be7498fe69fbdce0d4231c21 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-v0-03240154/gcc-5/5d230e335bd53c69be7498fe69fbdce0d4231c21/vmlinuz-4.5.0-rc2-00216-g5d230e3 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-intel12-yocto-x86_64/yocto-minimal-x86_64.cgz/x86_64-randconfig-v0-03240154/gcc-5/5d230e335bd53c69be7498fe69fbdce0d4231c21/0 LKP_SERVER=inn earlyprintk=ttyS0,115200 systemd.log_level=err debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal rw ip=::::vm-intel12-yocto-x86_64-9::dhcp drbd.minor_count=8' -initrd /fs/KVM/initrd-vm-intel12-yocto-x86_64-9 -m 320 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -drive file=/fs/KVM/disk0-vm-intel12-yocto-x86_64-9,media=disk,if=virtio -drive file=/fs/KVM/disk1-vm-intel12-yocto-x86_64-9,media=disk,if=virtio -pidfile /dev/shm/kboot/pid-vm-intel12-yocto-x86_64-9 -serial file:/dev/shm/kboot/serial-vm-intel12-yocto-x86_64-9 -daemonize -display none -monitor null
Thanks,
Kernel Test Robot
6 years, 1 month
[lkp] [chrdev] fbf32ec3dc: QAT: unable to allocate chrdev region
by kernel test robot
FYI, we noticed the below changes on
https://git.kernel.org/pub/scm/linux/kernel/git/linusw/linux-gpio.git chrdev-warn
commit fbf32ec3dc2890faa6a8e6d0e89664f178bb7476 ("chrdev: allocate dynamic chardevs in all unused holes")
As below, the log "QAT: unable to allocate chrdev region" showed with your commit.
[ 14.223507] watchdog: Software Watchdog: cannot register miscdev on minor=130 (err=-16).
[ 14.223507] watchdog: Software Watchdog: cannot register miscdev on minor=130 (err=-16).
[ 14.225849] watchdog: Software Watchdog: a legacy watchdog module is probably present.
[ 14.225849] watchdog: Software Watchdog: a legacy watchdog module is probably present.
[ 14.228320] softdog: Software Watchdog Timer: 0.08 initialized. soft_noboot=0 soft_margin=60 sec soft_panic=0 (nowayout=1)
[ 14.228320] softdog: Software Watchdog Timer: 0.08 initialized. soft_noboot=0 soft_margin=60 sec soft_panic=0 (nowayout=1)
[ 14.234554] dcdbas dcdbas: Dell Systems Management Base Driver (version 5.6.0-3.2)
[ 14.234554] dcdbas dcdbas: Dell Systems Management Base Driver (version 5.6.0-3.2)
[ 14.236682] No iBFT detected.
[ 14.236682] No iBFT detected.
[ 14.237508] QAT: unable to allocate chrdev region
[ 14.237508] QAT: unable to allocate chrdev region
FYI, raw QEMU command line is:
qemu-system-x86_64 -enable-kvm -cpu Nehalem -kernel /pkg/linux/x86_64-randconfig-a0-03231935/gcc-5/fbf32ec3dc2890faa6a8e6d0e89664f178bb7476/vmlinuz-4.5.0-rc2-00207-gfbf32ec -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-intel12-1G-3/bisect_boot-1-debian-x86_64-2015-02-07.cgz-x86_64-randconfig-a0-03231935-fbf32ec3dc2890faa6a8e6d0e89664f178bb7476-20160324-70544-l6nviw-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-a0-03231935 branch=linux-devel/devel-hourly-2016032317 commit=fbf32ec3dc2890faa6a8e6d0e89664f178bb7476 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-a0-03231935/gcc-5/fbf32ec3dc2890faa6a8e6d0e89664f178bb7476/vmlinuz-4.5.0-rc2-00207-gfbf32ec max_uptime=600 RESULT_ROOT=/result/boot/1/vm-intel12-1G/debian-x86_64-2015-02-07.cgz/x86_64-randconfig-a0-03231935/gcc-5/fbf32ec3dc2890faa6a8e6d0e89664f178bb7476/0 LKP_SERVER=inn earlyprintk=ttyS0,115200 systemd.log_level=err debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal rw ip=::::vm-intel12-1G-3::dhcp' -initrd /fs/KVM/initrd-vm-intel12-1G-3 -m 1024 -smp 1 -device e1000,netdev=net0 -netdev user,id=net0,hostfwd=tcp::23302-:22 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -drive file=/fs/KVM/disk0-vm-intel12-1G-3,media=disk,if=virtio -drive file=/fs/KVM/disk1-vm-intel12-1G-3,media=disk,if=virtio -drive file=/fs/KVM/disk2-vm-intel12-1G-3,media=disk,if=virtio -drive file=/fs/KVM/disk3-vm-intel12-1G-3,media=disk,if=virtio -drive file=/fs/KVM/disk4-vm-intel12-1G-3,media=disk,if=virtio -drive file=/fs/KVM/disk5-vm-intel12-1G-3,media=disk,if=virtio -pidfile /dev/shm/kboot/pid-vm-intel12-1G-3 -serial file:/dev/shm/kboot/serial-vm-intel12-1G-3 -daemonize -display none -monitor null
Thanks,
Xiaolong Ye
6 years, 1 month
[lkp] [x86/mm] a80e513582: BUG: sleeping function called from invalid context at kernel/locking/mutex.c:617
by kernel test robot
FYI, we noticed the below changes on
https://git.kernel.org/pub/scm/linux/kernel/git/luto/linux.git x86/pcid
commit a80e513582bdd0afcbe9140dcdd2c646fcdc32d1 ("x86/mm: Hold a mutex when propagating TLB flushes")
As below, the log "BUG: sleeping function called from invalid context at kernel/locking/mutex.c:617" showed with your commit.
[ 6.230877] No soundcards found.
[ 6.233829] Freeing unused kernel memory: 1236K (ffffffff8300b000 - ffffffff83140000)
[ 6.233829] Freeing unused kernel memory: 1236K (ffffffff8300b000 - ffffffff83140000)
[ 6.235855] BUG: sleeping function called from invalid context at kernel/locking/mutex.c:617
[ 6.235855] BUG: sleeping function called from invalid context at kernel/locking/mutex.c:617
[ 6.238053] in_atomic(): 1, irqs_disabled(): 0, pid: 1, name: init
[ 6.238053] in_atomic(): 1, irqs_disabled(): 0, pid: 1, name: init
[ 6.239639] 2 locks held by init/1:
[ 6.239639] 2 locks held by init/1:
[ 6.240586] #0:
[ 6.240586] #0: ( (&sig->cred_guard_mutex&sig->cred_guard_mutex){+.+.+.}){+.+.+.}, at: , at: [<ffffffff811926d0>] prepare_bprm_creds+0x30/0x70
[<ffffffff811926d0>] prepare_bprm_creds+0x30/0x70
[ 6.242902] #1:
[ 6.242902] #1: ( (&mm->mmap_sem&mm->mmap_sem){+.+.+.}){+.+.+.}, at: , at: [<ffffffff81191d38>] setup_arg_pages+0xb8/0x210
[<ffffffff81191d38>] setup_arg_pages+0xb8/0x210
[ 6.244610] CPU: 0 PID: 1 Comm: init Not tainted 4.5.0-rc2-00215-ga80e513 #327
[ 6.244610] CPU: 0 PID: 1 Comm: init Not tainted 4.5.0-rc2-00215-ga80e513 #327
[ 6.245934] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 6.245934] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 6.247661] ffffffff82b62ad0
[ 6.247661] ffffffff82b62ad0 ffff880013c97ac8 ffff880013c97ac8 ffffffff81523d99 ffffffff81523d99 ffff880013c90000 ffff880013c90000
[ 6.249112] ffff880013c97af0
[ 6.249112] ffff880013c97af0 ffffffff810bd7c2 ffffffff810bd7c2 ffffffff82b62ad0 ffffffff82b62ad0 0000000000000269 0000000000000269
[ 6.250583] 0000000000000000
[ 6.250583] 0000000000000000 ffff880013c97b18 ffff880013c97b18 ffffffff810bd8c4 ffffffff810bd8c4 0000000000000000 0000000000000000
[ 6.251978] Call Trace:
[ 6.251978] Call Trace:
[ 6.252479] [<ffffffff81523d99>] dump_stack+0x4b/0x72
[ 6.252479] [<ffffffff81523d99>] dump_stack+0x4b/0x72
[ 6.253478] [<ffffffff810bd7c2>] ___might_sleep+0x172/0x230
[ 6.253478] [<ffffffff810bd7c2>] ___might_sleep+0x172/0x230
[ 6.254578] [<ffffffff810bd8c4>] __might_sleep+0x44/0x80
[ 6.254578] [<ffffffff810bd8c4>] __might_sleep+0x44/0x80
[ 6.255565] [<ffffffff8242005d>] mutex_lock_nested+0x2d/0x3b0
[ 6.255565] [<ffffffff8242005d>] mutex_lock_nested+0x2d/0x3b0
[ 6.256713] [<ffffffff81041060>] propagate_tlb_flush+0x20/0x60
[ 6.256713] [<ffffffff81041060>] propagate_tlb_flush+0x20/0x60
[ 6.257931] [<ffffffff81041657>] flush_tlb_mm_range+0x37/0xb0
[ 6.257931] [<ffffffff81041657>] flush_tlb_mm_range+0x37/0xb0
[ 6.259191] [<ffffffff81165476>] move_page_tables+0x4e6/0x700
[ 6.259191] [<ffffffff81165476>] move_page_tables+0x4e6/0x700
[ 6.260363] [<ffffffff810cfc6a>] ? up_write+0x1a/0x40
[ 6.260363] [<ffffffff810cfc6a>] ? up_write+0x1a/0x40
[ 6.261361] [<ffffffff8119146a>] shift_arg_pages+0xaa/0x190
[ 6.261361] [<ffffffff8119146a>] shift_arg_pages+0xaa/0x190
[ 6.262562] [<ffffffff81191d38>] ? setup_arg_pages+0xb8/0x210
[ 6.262562] [<ffffffff81191d38>] ? setup_arg_pages+0xb8/0x210
[ 6.264042] [<ffffffff81191e57>] setup_arg_pages+0x1d7/0x210
[ 6.264042] [<ffffffff81191e57>] setup_arg_pages+0x1d7/0x210
[ 6.265160] [<ffffffff811d723f>] load_elf_binary+0x45f/0x16a0
[ 6.265160] [<ffffffff811d723f>] load_elf_binary+0x45f/0x16a0
[ 6.266235] [<ffffffff82423ec2>] ? _raw_read_unlock+0x22/0x30
[ 6.266235] [<ffffffff82423ec2>] ? _raw_read_unlock+0x22/0x30
[ 6.267294] [<ffffffff81192ad6>] ? search_binary_handler+0x46/0xe0
[ 6.267294] [<ffffffff81192ad6>] ? search_binary_handler+0x46/0xe0
[ 6.268491] [<ffffffff81192ae5>] search_binary_handler+0x55/0xe0
[ 6.268491] [<ffffffff81192ae5>] search_binary_handler+0x55/0xe0
[ 6.269624] [<ffffffff8119319c>] do_execveat_common+0x62c/0x7e0
[ 6.269624] [<ffffffff8119319c>] do_execveat_common+0x62c/0x7e0
[ 6.270815] [<ffffffff811930fa>] ? do_execveat_common+0x58a/0x7e0
[ 6.270815] [<ffffffff811930fa>] ? do_execveat_common+0x58a/0x7e0
[ 6.271973] [<ffffffff8241bb00>] ? rest_init+0x140/0x140
[ 6.271973] [<ffffffff8241bb00>] ? rest_init+0x140/0x140
[ 6.272982] [<ffffffff8119336a>] do_execve+0x1a/0x20
[ 6.272982] [<ffffffff8119336a>] do_execve+0x1a/0x20
[ 6.273941] [<ffffffff81002026>] run_init_process+0x26/0x30
[ 6.273941] [<ffffffff81002026>] run_init_process+0x26/0x30
[ 6.274995] [<ffffffff8241bb38>] kernel_init+0x38/0xd0
[ 6.274995] [<ffffffff8241bb38>] kernel_init+0x38/0xd0
[ 6.275965] [<ffffffff824245df>] ret_from_fork+0x3f/0x70
[ 6.275965] [<ffffffff824245df>] ret_from_fork+0x3f/0x70
[ 6.277027] [<ffffffff8241bb00>] ? rest_init+0x140/0x140
[ 6.277027] [<ffffffff8241bb00>] ? rest_init+0x140/0x140
[ 6.284658] random: init urandom read with 3 bits of entropy available
[ 6.284658] random: init urandom read with 3 bits of entropy available
[ 6.344527] init: Failed to create pty - disabling logging for job
FYI, raw QEMU command line is:
qemu-system-x86_64 -enable-kvm -kernel /pkg/linux/x86_64-randconfig-i0-201612/gcc-5/a80e513582bdd0afcbe9140dcdd2c646fcdc32d1/vmlinuz-4.5.0-rc2-00215-ga80e513 -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-vp-quantal-x86_64-53/bisect_boot-1-quantal-core-x86_64.cgz-x86_64-randconfig-i0-201612-a80e513582bdd0afcbe9140dcdd2c646fcdc32d1-20160323-39787-9dnifg-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-i0-201612 branch=luto/x86/pcid commit=a80e513582bdd0afcbe9140dcdd2c646fcdc32d1 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-i0-201612/gcc-5/a80e513582bdd0afcbe9140dcdd2c646fcdc32d1/vmlinuz-4.5.0-rc2-00215-ga80e513 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-vp-quantal-x86_64/quantal-core-x86_64.cgz/x86_64-randconfig-i0-201612/gcc-5/a80e513582bdd0afcbe9140dcdd2c646fcdc32d1/0 LKP_SERVER=inn earlyprintk=ttyS0,115200 systemd.log_level=err debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal rw ip=::::vm-vp-quantal-x86_64-53::dhcp drbd.minor_count=8' -initrd /fs/sdb1/initrd-vm-vp-quantal-x86_64-53 -m 360 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -pidfile /dev/shm/kboot/pid-vm-vp-quantal-x86_64-53 -serial file:/dev/shm/kboot/serial-vm-vp-quantal-x86_64-53 -daemonize -display none -monitor null
Thanks,
Xiaolong Ye.
6 years, 1 month
[lkp] [vmstat] 0eb77e9880: +2.7% pigz.throughput
by kernel test robot
FYI, we noticed that pigz.throughput +2.7% improvement on
https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git master
commit 0eb77e9880321915322d42913c3b53241739c8aa ("vmstat: make vmstat_updater deferrable again and shut down on idle")
=========================================================================================
blocksize/compiler/cpufreq_governor/kconfig/nr_threads/rootfs/tbox_group/testcase:
128K/gcc-4.9/performance/x86_64-rhel/100%/debian-x86_64-2015-02-07.cgz/brickland1/pigz
commit:
686739f6af5e8d5687ffebbf1193ff066aada6d9
0eb77e9880321915322d42913c3b53241739c8aa
686739f6af5e8d56 0eb77e9880321915322d42913c
---------------- --------------------------
%stddev %change %stddev
\ | \
1.989e+08 ± 0% +2.7% 2.043e+08 ± 0% pigz.throughput
19017 ± 2% -17.3% 15725 ± 4% pigz.time.involuntary_context_switches
1929 ± 0% +6.2% 2049 ± 0% pigz.time.percent_of_cpu_this_job_got
262.60 ± 0% +6.6% 279.90 ± 0% pigz.time.system_time
5532 ± 0% +6.2% 5873 ± 0% pigz.time.user_time
15031795 ± 0% +2.7% 15439431 ± 0% pigz.time.voluntary_context_switches
3978 ± 96% -99.8% 8.25 ± 33% numa-numastat.node3.other_node
19017 ± 2% -17.3% 15725 ± 4% time.involuntary_context_switches
1537 ± 1% -10.2% 1380 ± 1% proc-vmstat.nr_alloc_batch
768.25 ± 33% -100.0% 0.33 ±141% proc-vmstat.pgmigrate_fail
101204 ± 0% +2.4% 103648 ± 0% vmstat.system.cs
33988 ± 1% +4.6% 35542 ± 1% vmstat.system.in
16.57 ± 0% +6.3% 17.61 ± 0% turbostat.%Busy
346.00 ± 0% +3.0% 356.50 ± 0% turbostat.Avg_MHz
0.92 ± 1% -62.6% 0.35 ± 4% turbostat.CPU%c3
665.14 ± 0% +6.5% 708.22 ± 0% turbostat.CorWatt
677.67 ± 0% +6.4% 720.77 ± 0% turbostat.PkgWatt
401974 ± 10% +28.1% 514828 ± 10% numa-meminfo.node0.MemUsed
15203 ± 6% -11.9% 13388 ± 3% numa-meminfo.node2.SReclaimable
38174 ± 18% +25.5% 47898 ± 6% numa-meminfo.node3.Active(anon)
332312 ± 3% +14.4% 380028 ± 1% numa-meminfo.node3.MemUsed
15391 ± 19% +29.6% 19949 ± 6% numa-meminfo.node3.SReclaimable
51955 ± 7% +13.4% 58906 ± 2% numa-meminfo.node3.Slab
21485 ± 19% +23.2% 26468 ± 0% numa-vmstat.node1.numa_other
411.50 ± 4% -15.6% 347.50 ± 2% numa-vmstat.node2.nr_alloc_batch
3800 ± 6% -11.9% 3346 ± 3% numa-vmstat.node2.nr_slab_reclaimable
9542 ± 18% +25.4% 11969 ± 6% numa-vmstat.node3.nr_active_anon
403.25 ± 2% -12.6% 352.50 ± 3% numa-vmstat.node3.nr_alloc_batch
3847 ± 19% +29.6% 4986 ± 6% numa-vmstat.node3.nr_slab_reclaimable
428.00 ± 8% +38.0% 590.75 ± 21% slabinfo.blkdev_requests.active_objs
428.00 ± 8% +38.0% 590.75 ± 21% slabinfo.blkdev_requests.num_objs
26723 ± 2% -7.0% 24850 ± 3% slabinfo.kmalloc-256.active_objs
27745 ± 2% -7.0% 25801 ± 3% slabinfo.kmalloc-256.num_objs
1165 ± 5% +16.2% 1354 ± 3% slabinfo.mnt_cache.active_objs
1165 ± 5% +16.2% 1354 ± 3% slabinfo.mnt_cache.num_objs
5.87e+08 ± 2% +19.4% 7.012e+08 ± 7% cpuidle.C1-IVT-4S.time
651990 ± 0% -10.3% 584857 ± 0% cpuidle.C1-IVT-4S.usage
64334504 ± 2% -60.4% 25444312 ± 8% cpuidle.C1E-IVT-4S.time
121454 ± 1% -59.8% 48868 ± 7% cpuidle.C1E-IVT-4S.usage
2.634e+08 ± 1% -61.0% 1.028e+08 ± 5% cpuidle.C3-IVT-4S.time
451711 ± 1% -59.6% 182479 ± 7% cpuidle.C3-IVT-4S.usage
30487 ± 6% +41.4% 43112 ± 5% cpuidle.POLL.usage
1.15 ± 7% -83.2% 0.19 ± 10% perf-profile.cycles-pp.__remove_hrtimer.hrtimer_try_to_cancel.hrtimer_cancel.tick_nohz_restart.tick_nohz_idle_exit
2.65 ± 5% +11.7% 2.96 ± 2% perf-profile.cycles-pp.__schedule.schedule.futex_wait_queue_me.futex_wait.do_futex
3.67 ± 3% -25.4% 2.73 ± 8% perf-profile.cycles-pp.__tick_nohz_idle_enter.tick_nohz_idle_enter.cpu_startup_entry.start_secondary
3.70 ± 5% +8.9% 4.02 ± 2% perf-profile.cycles-pp.futex_wait.do_futex.sys_futex.entry_SYSCALL_64_fastpath
1.39 ± 6% -69.4% 0.42 ± 5% perf-profile.cycles-pp.hrtimer_cancel.tick_nohz_restart.tick_nohz_idle_exit.cpu_startup_entry.start_secondary
2.16 ± 4% -39.1% 1.31 ± 9% perf-profile.cycles-pp.hrtimer_start_range_ns.tick_nohz_stop_sched_tick.__tick_nohz_idle_enter.tick_nohz_idle_enter.cpu_startup_entry
1.36 ± 7% -72.1% 0.38 ± 8% perf-profile.cycles-pp.hrtimer_try_to_cancel.hrtimer_cancel.tick_nohz_restart.tick_nohz_idle_exit.cpu_startup_entry
0.88 ± 8% -15.3% 0.74 ± 9% perf-profile.cycles-pp.ktime_get.clockevents_program_event.tick_program_event.__remove_hrtimer.hrtimer_start_range_ns
0.97 ± 6% -10.1% 0.87 ± 6% perf-profile.cycles-pp.ktime_get.sched_clock_tick.sched_clock_idle_wakeup_event.tick_nohz_stop_idle.tick_nohz_idle_exit
0.65 ± 6% +44.1% 0.94 ± 8% perf-profile.cycles-pp.pick_next_task_fair.__schedule.schedule.futex_wait_queue_me.futex_wait
0.00 ± -1% +Inf% 2.02 ± 5% perf-profile.cycles-pp.quiet_vmstat.cpu_startup_entry.start_secondary
1.62 ± 6% -51.6% 0.78 ± 9% perf-profile.cycles-pp.read_hpet.ktime_get.clockevents_program_event.tick_program_event.__remove_hrtimer
1.67 ± 4% -49.9% 0.84 ± 6% perf-profile.cycles-pp.read_hpet.ktime_get.clockevents_program_event.tick_program_event.hrtimer_start_range_ns
0.00 ± -1% +Inf% 1.71 ± 5% perf-profile.cycles-pp.refresh_cpu_vm_stats.quiet_vmstat.cpu_startup_entry.start_secondary
2.79 ± 5% +10.1% 3.07 ± 2% perf-profile.cycles-pp.schedule.futex_wait_queue_me.futex_wait.do_futex.sys_futex
3.78 ± 3% -24.6% 2.85 ± 8% perf-profile.cycles-pp.tick_nohz_idle_enter.cpu_startup_entry.start_secondary
5.26 ± 4% -20.9% 4.16 ± 4% perf-profile.cycles-pp.tick_nohz_idle_exit.cpu_startup_entry.start_secondary
2.86 ± 6% -35.2% 1.85 ± 4% perf-profile.cycles-pp.tick_nohz_restart.tick_nohz_idle_exit.cpu_startup_entry.start_secondary
2.56 ± 3% -33.2% 1.71 ± 9% perf-profile.cycles-pp.tick_nohz_stop_sched_tick.__tick_nohz_idle_enter.tick_nohz_idle_enter.cpu_startup_entry.start_secondary
0.94 ± 4% -94.7% 0.05 ± 58% perf-profile.cycles-pp.tick_program_event.__remove_hrtimer.hrtimer_try_to_cancel.hrtimer_cancel.tick_nohz_restart
brickland1: Brickland Ivy Bridge-EX
Memory: 128G
pigz.time.voluntary_context_switches
1.6e+07 ++----------------------------------------------------------------+
| O O O O O O O O O O O O O O O |
1.5e+07 O+*.*.O.O.*.*.*..*.O.*.O.O.O.O.*.*.*.*.*.*.*.*.*..*.*.*.*.*.*.*.*.*
| : |
| : |
1.4e+07 ++ |
|: |
1.3e+07 ++ |
|: |
1.2e+07 ++ |
|: |
| |
1.1e+07 ++ |
* |
1e+07 ++----------------------------------------------------------------+
cpuidle.C1E-IVT-4S.time
9e+07 ++------------------------------------------------------------------+
| * |
8e+07 ++ :: |
| : : |
7e+07 ++ : *. .*. |
| .*.. .*.*.*.*. .*.. .*.*. .*. .*. : *.*.*. *.*. .*
6e+07 ++*.* * *.* *.* *.* *. *.* * |
| : |
5e+07 ++ |
|: |
4e+07 ++ |
|: O |
3e+07 O+ O O O O O O O O O |
* O O O O O O O O O O O |
2e+07 ++---------------------------------------------O--------------------+
cpuidle.C1E-IVT-4S.usage
130000 ++--------*-------------------------------------------*------------+
120000 ++ .*. + *.*.*.*.*.*. .*.*. .*.*.*.*.*. .*.*.* *.*.*.*.*.*
| *.* * * *..* * |
110000 ++: |
100000 ++: |
|: |
90000 ++ |
80000 ++ |
70000 ++ |
|: |
60000 O+O O O O |
50000 ++ O O O O O O O O O O O O O |
| O O O O O |
40000 *+ |
30000 ++-----------------------------------------------------------------+
cpuidle.C3-IVT-4S.time
2.8e+08 ++------------*---------------------------------------------------+
2.6e+08 ++ .*.*. .*.* *.*.*.*. .*.*. .*.*.*.*.*. .*..*.*.*.*.*.*.*.*.*
| * * * *.* * |
2.4e+08 ++: |
2.2e+08 ++: |
|: |
2e+08 ++ |
1.8e+08 ++ |
1.6e+08 ++ |
|: |
1.4e+08 ++ |
1.2e+08 ++O O O |
O O O O O O O O O O O O O O |
1e+08 ++ O O O O O O |
8e+07 ++----------------------------------------------------------------+
cpuidle.C3-IVT-4S.usage
500000 ++-----------------------------------------------------------------+
| *.. .*.*.. |
450000 ++*.*.*. + *.*.*.*.*.*. .*.*. .*.*.*.*.*.*.*.* *.*.*.*.*.*
| : * * *..* |
400000 ++: |
| : |
350000 ++ |
|: |
300000 ++ |
|: |
250000 ++ |
|: O |
200000 O+O O O O O O O O O O O O O |
| O O O O O O O O |
150000 *+-----------------------------------------------------------------+
turbostat.CPU_c3
1 ++--------------------------------------------------------------------+
| .*. .*.*.*..*.*. .*. .*.. .*. .*..*.*.*.*.*. .*. |
0.9 ++*.*. * * *.*..*.*.*.* * *.* *. *.*
| : |
0.8 ++: |
|: |
0.7 ++ |
|: |
0.6 ++ |
|: |
0.5 ++ |
| |
0.4 *+O O O |
O O O O O O O O O O O O O O O O O O O |
0.3 ++----------------------------------------------O---------------------+
[*] bisect-good sample
[O] bisect-bad sample
To reproduce:
git clone git://git.kernel.org/pub/scm/linux/kernel/git/wfg/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
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 Ye
6 years, 1 month
[lkp] [of] cd4f011a26: __of_overlay_create: of_build_overlay_info() failed for tree@/testcase-data/overlay19
by kernel test robot
FYI, we noticed the below changes on
https://github.com/pantoniou/linux-beagle-track-mainline.git bbb-overlays
commit cd4f011a264a6d72d8ab1602c034285e4461843b ("of: unittest: Unit-tests for target root overlays.")
As below, the log "__of_overlay_create: of_build_overlay_info() failed for tree@/testcase-data/overlay19" showed with your commit.
[ 6.704047] BIOS EDD facility v0.16 2004-Jun-25, 0 devices found
[ 6.704926] EDD information not available.
[ 6.706164] device-tree: Duplicate name in testcase-data, renamed to "duplicate-name#1"
[ 6.708885] ### dt-test ### start of unittest - you will see error messages
[ 6.710161] /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1
[ 6.711825] /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1
[ 6.713543] /testcase-data/phandle-tests/consumer-a: could not find phandle
[ 6.714534] /testcase-data/phandle-tests/consumer-a: could not find phandle
[ 6.715509] /testcase-data/phandle-tests/consumer-a: arguments longer than property
[ 6.716632] /testcase-data/phandle-tests/consumer-a: arguments longer than property
[ 6.718015] irq: no irq domain found for /testcase-data/interrupts/intc0 !
[ 6.722015] overlay_is_topmost: #5 clashes #6 @/testcase-data/overlay-node/test-bus/test-unittest8
[ 6.723341] overlay_removal_is_ok: overlay #5 is not topmost
[ 6.724150] of_overlay_destroy: removal check failed for overlay #5
[ 6.726757] find_target_node_direct: target "/testcase-data/overlay-node/test-bus/test-unittest18" not under target_root "/testcase-data/overlay-node/test-bus/test-unittest19"
[ 6.728944] __of_overlay_create: of_build_overlay_info() failed for tree@/testcase-data/overlay19
[ 6.733178] ### dt-test ### end of unittest - 152 passed, 0 failed
[ 6.736415] Freeing unused kernel memory: 1064K (ffffffff81fdf000 - ffffffff820e9000)
[ 6.737569] Write protecting the kernel read-only data: 14336k
[ 6.739879] Freeing unused kernel memory: 1984K (ffff880001810000 - ffff880001a00000)
[ 6.741326] Freeing unused kernel memory: 84K (ffff880001deb000 - ffff880001e00000)
FYI, raw QEMU command line is:
qemu-system-x86_64 -enable-kvm -kernel /pkg/linux/x86_64-randconfig-r0-03190143/gcc-5/cd4f011a264a6d72d8ab1602c034285e4461843b/vmlinuz-4.5.0-05779-gcd4f011 -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-vp-quantal-x86_64-21/bisect_boot-1-quantal-core-x86_64.cgz-x86_64-randconfig-r0-03190143-cd4f011a264a6d72d8ab1602c034285e4461843b-20160319-70730-1trp4bi-1.yaml ARCH=x86_64 kconfig=x86_64-randconfig-r0-03190143 branch=linux-devel/devel-catchup-201603190341 commit=cd4f011a264a6d72d8ab1602c034285e4461843b BOOT_IMAGE=/pkg/linux/x86_64-randconfig-r0-03190143/gcc-5/cd4f011a264a6d72d8ab1602c034285e4461843b/vmlinuz-4.5.0-05779-gcd4f011 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-vp-quantal-x86_64/quantal-core-x86_64.cgz/x86_64-randconfig-r0-03190143/gcc-5/cd4f011a264a6d72d8ab1602c034285e4461843b/0 LKP_SERVER=inn earlyprintk=ttyS0,115200 systemd.log_level=err debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal rw ip=::::vm-vp-quantal-x86_64-21::dhcp drbd.minor_count=8' -initrd /fs/sde1/initrd-vm-vp-quantal-x86_64-21 -m 360 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -pidfile /dev/shm/kboot/pid-vm-vp-quantal-x86_64-21 -serial file:/dev/shm/kboot/serial-vm-vp-quantal-x86_64-21 -daemonize -display none -monitor null
Thanks,
Xiaolong Ye.
6 years, 2 months