March 2016 - LKP - Ml01.01.Org

[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

5 years, 8 months

1
0
0 / 0

[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

5 years, 8 months

1
0
0 / 0

[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

5 years, 8 months

1
0
0 / 0

[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

5 years, 8 months

1
0
0 / 0

[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

5 years, 8 months

4
3
0 / 0

[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

5 years, 8 months

1
0
0 / 0

[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

5 years, 8 months

1
0
0 / 0

[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.

5 years, 8 months

1
0
0 / 0

[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

5 years, 8 months

1
0
0 / 0

[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.

5 years, 8 months

1
0
0 / 0

2021

2020

2019

2018

2017

2016

2015

2014

2013

LKP March 2016