[sched] WARNING: CPU: 0 PID: 1 at kernel/sched/fair.c:260 put_prev_entity()
by Fengguang Wu
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
https://github.com/0day-ci/linux Joonwoo-Park/sched-fix-incorrect-wait-time-and-wait-count-statistics/20151027-094629
commit 4183fdd83194ded503e1cec873556dd560f43dd4
Author: Joonwoo Park <joonwoop(a)codeaurora.org>
AuthorDate: Mon Oct 26 18:44:48 2015 -0700
Commit: 0day robot <fengguang.wu(a)intel.com>
CommitDate: Tue Oct 27 09:46:30 2015 +0800
sched: fix incorrect wait time and wait count statistics
On 10/25/2015 03:26 AM, Peter Zijlstra wrote:
> On Sat, Oct 24, 2015 at 10:23:14PM -0700, Joonwoo Park wrote:
>> @@ -1069,7 +1069,7 @@ static struct rq *move_queued_task(struct rq *rq, struct task_struct *p, int new
>> {
>> lockdep_assert_held(&rq->lock);
>>
>> - dequeue_task(rq, p, 0);
>> + dequeue_task(rq, p, DEQUEUE_MIGRATING);
>> p->on_rq = TASK_ON_RQ_MIGRATING;
>> set_task_cpu(p, new_cpu);
>> raw_spin_unlock(&rq->lock);
>
>> @@ -5656,7 +5671,7 @@ static void detach_task(struct task_struct *p, struct lb_env *env)
>> {
>> lockdep_assert_held(&env->src_rq->lock);
>>
>> - deactivate_task(env->src_rq, p, 0);
>> + deactivate_task(env->src_rq, p, DEQUEUE_MIGRATING);
>> p->on_rq = TASK_ON_RQ_MIGRATING;
>> set_task_cpu(p, env->dst_cpu);
>> }
>
> Also note that on both sites we also set TASK_ON_RQ_MIGRATING -- albeit
> late. Can't you simply set that earlier (and back to QUEUED later) and
> test for task_on_rq_migrating() instead of blowing up the fastpath like
> you did?
>
Yes it's doable. I also find it's much simpler.
Please find patch v2. I verified v2 does same job as v1 by comparing sched_stat_wait time with sched_switch - sched_wakeup timestamp.
Thanks,
Joonwoo
From 98d615d46211a90482a0f9b7204265c54bba8520 Mon Sep 17 00:00:00 2001
From: Joonwoo Park <joonwoop(a)codeaurora.org>
Date: Mon, 26 Oct 2015 16:37:47 -0700
Subject: [PATCH v2] sched: fix incorrect wait time and wait count statistics
At present scheduler resets task's wait start timestamp when the task
migrates to another rq. This misleads scheduler itself into reporting
less wait time than actual by omitting time spent for waiting prior to
migration and also more wait count than actual by counting migration as
wait end event which can be seen by trace or /proc/<pid>/sched with
CONFIG_SCHEDSTATS=y.
Carry forward migrating task's wait time prior to migration and
don't count migration as a wait end event to fix such statistics error.
In order to determine whether task is migrating mark task->on_rq with
TASK_ON_RQ_MIGRATING while dequeuing and enqueuing due to migration.
To: Ingo Molnar <mingo(a)kernel.org>
To: Peter Zijlstra <peterz(a)infradead.org>
Cc: linux-kernel(a)vger.kernel.org
Signed-off-by: Joonwoo Park <joonwoop(a)codeaurora.org>
+--------------------------------------------------------+------------+------------+------------+
| | 8a5ec90292 | 4183fdd831 | 7cb0326d8e |
+--------------------------------------------------------+------------+------------+------------+
| boot_successes | 112 | 0 | 0 |
| boot_failures | 4 | 26 | 39 |
| IP-Config:Auto-configuration_of_network_failed | 4 | | |
| WARNING:at_kernel/sched/fair.c:#put_prev_entity() | 0 | 26 | 39 |
| BUG:KASan:out_of_bounds_access | 0 | 26 | 39 |
| BUG_kmalloc-#(Tainted:G_W):kasan:bad_access_detected | 0 | 26 | 39 |
| INFO:Slab#objects=#used=#fp=0x(null)flags= | 0 | 22 | 39 |
| INFO:Object#@offset=#fp= | 0 | 26 | 39 |
| BUG:KASan:use_after_free | 0 | 10 | 12 |
| BUG_kmalloc-#(Tainted:G_B_W):kasan:bad_access_detected | 0 | 26 | 39 |
| INFO:Slab#objects=#used=#fp=#flags= | 0 | 11 | |
| backtrace:do_execve | 0 | 2 | 4 |
| backtrace:SyS_execve | 0 | 2 | 4 |
| backtrace:do_wait | 0 | 10 | 16 |
| backtrace:SyS_wait4 | 0 | 10 | 16 |
| backtrace:torture_stutter | 0 | 24 | 37 |
| backtrace:torture_shuffle | 0 | 24 | 36 |
| backtrace:vfs_write | 0 | 4 | 7 |
| backtrace:SyS_write | 0 | 4 | 7 |
| backtrace:smpboot_thread_fn | 0 | 14 | 22 |
| backtrace:vfs_read | 0 | 4 | 7 |
| backtrace:SyS_read | 0 | 4 | 7 |
| backtrace:arch_ptrace | 0 | 9 | 12 |
| backtrace:SyS_ptrace | 0 | 9 | 12 |
| backtrace:lock_torture_writer | 0 | 20 | 32 |
| backtrace:core_sys_select | 0 | 10 | 14 |
| backtrace:SyS_select | 0 | 10 | 14 |
| backtrace:vm_munmap | 0 | 2 | 2 |
| backtrace:SyS_munmap | 0 | 2 | 2 |
| backtrace:hrtimer_nanosleep | 0 | 5 | |
| backtrace:SyS_nanosleep | 0 | 5 | |
| backtrace:__sys_sendmsg | 0 | 1 | 2 |
| backtrace:SyS_sendmsg | 0 | 1 | 2 |
| backtrace:compat_SyS_wait4 | 0 | 2 | 7 |
| backtrace:_do_fork | 0 | 1 | 3 |
| backtrace:SyS_clone | 0 | 1 | 3 |
| INFO:Object | 0 | 0 | 1 |
| backtrace:call_usermodehelper_exec_work | 0 | 0 | 4 |
| backtrace:vm_mmap_pgoff | 0 | 0 | 1 |
| backtrace:SyS_mmap_pgoff | 0 | 0 | 1 |
| backtrace:SyS_mmap | 0 | 0 | 1 |
| backtrace:compat_core_sys_select | 0 | 0 | 8 |
| backtrace:compat_SyS_select | 0 | 0 | 8 |
| INFO:Obje | 0 | 0 | 1 |
+--------------------------------------------------------+------------+------------+------------+
[ 64.057664] page_owner is disabled
[ 64.059765] Key type encrypted registered
[ 64.087483] Freeing unused kernel memory: 952K (ffffffff82190000 - ffffffff8227e000)
[ 64.109779] WARNING: CPU: 0 PID: 1 at kernel/sched/fair.c:260 put_prev_entity+0x20f/0x2d0()
[ 64.110020] Modules linked in:
[ 64.110020] CPU: 0 PID: 1 Comm: init Not tainted 4.3.0-rc6-00788-g4183fdd #1
[ 64.110020] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 64.110020] 0000000000000104 ffff88000eca7d00 ffffffff81415919 ffff88000eca7d40
[ 64.110020] ffffffff810a3308 ffffffff810dfd0f ffff880013961a00 ffffffff820728e0
[ 64.110020] ffff880013961a38 00000004ca03a0da ffffffff820728e0 ffff88000eca7d50
[ 64.110020] Call Trace:
[ 64.110020] [<ffffffff81415919>] dump_stack+0x19/0x20
[ 64.110020] [<ffffffff810a3308>] warn_slowpath_common+0x98/0xe0
[ 64.110020] [<ffffffff810dfd0f>] ? put_prev_entity+0x20f/0x2d0
[ 64.110020] [<ffffffff810a3405>] warn_slowpath_null+0x15/0x20
[ 64.110020] [<ffffffff810dfd0f>] put_prev_entity+0x20f/0x2d0
[ 64.110020] [<ffffffff810dd6db>] ? pick_next_entity+0x14b/0x2b0
[ 64.110020] [<ffffffff810dffde>] pick_next_task_fair+0x1be/0x2a0
[ 64.110020] [<ffffffff81b5eaca>] __schedule+0xea/0xb70
[ 64.110020] [<ffffffff81b5f74a>] preempt_schedule_common+0x2a/0x50
[ 64.110020] [<ffffffff81b5f786>] _cond_resched+0x16/0x20
[ 64.110020] [<ffffffff810c9356>] task_work_run+0xa6/0xe0
[ 64.110020] [<ffffffff81002a1f>] exit_to_usermode_loop+0xbf/0xd0
[ 64.110020] [<ffffffff810031b5>] do_syscall_32_irqs_off+0x175/0x190
[ 64.110020] [<ffffffff81b670d3>] entry_INT80_compat+0x33/0x40
[ 64.110020] ---[ end trace a212c87aecbb765b ]---
[ 64.182849] ==================================================================
git bisect start 7cb0326d8efd27c3936596f5abc6fac4400a5c48 32b88194f71d6ae7768a29f87fbba454728273ee --
git bisect good 515bb57a7bf106bb1a37e7a6ba0079d6322d94fd # 14:25 20+ 4 Merge 'l2-mtd/master' into devel-spot-201510271532
git bisect bad 5583b4496b3139343f9ba1c3e8d7869cb7d8d2d8 # 14:25 0- 41 Merge 'nvdimm/for-4.4/memremap' into devel-spot-201510271532
git bisect bad 2a8b8ef9e200879f7ee88706e4cf3d5625cf2a73 # 14:25 0- 24 Merge 'slave-dma/topic/edma' into devel-spot-201510271532
git bisect bad e0ba1a24b2093dd8195955039b918ba5f7301789 # 14:25 0- 54 Merge 'luto/x86/entry_compat' into devel-spot-201510271532
git bisect good 148a7732a83c50558948de14d79e7b34e602c007 # 15:17 21+ 4 Merge 'linux-review/Chao-Yu/f2fs-support-file-defragment/20151027-095717' into devel-spot-201510271532
git bisect bad 10eb6ed23a5f3e60087bf7276358b5b348a20a67 # 15:17 0- 26 Merge 'linux-review/Joonwoo-Park/sched-fix-incorrect-wait-time-and-wait-count-statistics/20151027-094629' into devel-spot-201510271532
git bisect good 295e5999f7bba512a3d744e11ed0327ba877fa3a # 15:27 22+ 0 Merge branch 'perf/core'
git bisect good 1b9b556081cbc68c6bf5a8e540899171cecb12dc # 15:31 22+ 4 Merge branch 'x86/apic'
git bisect good d58edcaab85edb3b1b72c0fee753d219cb4cee98 # 15:36 22+ 4 Merge branch 'x86/debug'
git bisect good daa0f3856bb0673158f1952f494483e590e1a6d5 # 15:48 22+ 0 manual merge of x86/fpu
git bisect good 2043d3d0c2ffb6afb71074df3f76a7bf179a31d6 # 15:56 22+ 0 Merge branch 'x86/headers'
git bisect good 5fb50b1a1654b879ed4c7a940aef64f9bb4e3831 # 16:41 21+ 4 Merge branch 'x86/platform'
git bisect bad 4183fdd83194ded503e1cec873556dd560f43dd4 # 16:41 0- 26 sched: fix incorrect wait time and wait count statistics
git bisect good 8a5ec90292d4a7e5b133fd8d91ea3d1ddb9c2bff # 16:44 22+ 4 Merge branch 'x86/urgent'
# first bad commit: [4183fdd83194ded503e1cec873556dd560f43dd4] sched: fix incorrect wait time and wait count statistics
git bisect good 8a5ec90292d4a7e5b133fd8d91ea3d1ddb9c2bff # 16:52 63+ 4 Merge branch 'x86/urgent'
# extra tests with DEBUG_INFO
git bisect bad 4183fdd83194ded503e1cec873556dd560f43dd4 # 17:02 0- 3 sched: fix incorrect wait time and wait count statistics
# extra tests on HEAD of linux-devel/devel-spot-201510271532
git bisect bad f727564839f222a773ceb14f5c5753d1ba550acf # 17:03 0- 1116 0day head guard for 'devel-spot-201510271532'
# extra tests on tree/branch linux-review/Joonwoo-Park/sched-fix-incorrect-wait-time-and-wait-count-statistics/20151027-094629
git bisect bad 4183fdd83194ded503e1cec873556dd560f43dd4 # 17:03 0- 26 sched: fix incorrect wait time and wait count statistics
# extra tests with first bad commit reverted
git bisect good 55f7afd3fcf8c06219adbb88fd94de4f60c18df2 # 17:22 61+ 0 Revert "sched: fix incorrect wait time and wait count statistics"
# extra tests on tree/branch linus/master
git bisect good 38dab9ac1c017e96dc98e978111e365134d41d13 # 17:38 62+ 0 Merge branch 'for-linus' of git://git.kernel.org/pub/scm/linux/kernel/git/dtor/input
# extra tests on tree/branch linux-next/master
git bisect good 6dcf94ff0c9e28e5790799e53641dd256745f425 # 17:50 62+ 0 Add linux-next specific files for 20151022
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
5 years, 11 months
[lkp] [mm, page_alloc] 43993977ba: +88% OOM possibility
by kernel test robot
FYI, we noticed the below changes on
https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git master
commit 43993977baecd838d66ccabc7f682342fc6ff635 ("mm, page_alloc: distinguish between being unable to sleep, unwilling to sleep and avoiding waking kswapd")
We found the OOM possibility increased 88% in a virtual machine with 1G memory.
=========================================================================================
tbox_group/testcase/rootfs/kconfig/compiler/disk/fs/test:
vm-kbuild-1G/xfstests/debian-x86_64-2015-02-07.cgz/x86_64-allyesdebian/gcc-4.9/4HDD/btrfs/generic-mid
commit:
74fad8a3a917b9e0a407af8a4150c61f7b836591
43993977baecd838d66ccabc7f682342fc6ff635
74fad8a3a917b9e0 43993977baecd838d66ccabc7f
---------------- --------------------------
fail:runs %reproduction fail:runs
| | |
1:24 -4% :24 xfstests.generic.192.fail
1:24 -4% :24 xfstests.nr_fail
:24 88% 21:24 dmesg.Mem-Info
:24 62% 15:24 dmesg.page_allocation_failure:order:#,mode
:24 88% 21:24 dmesg.warn_alloc_failed+0x
:24 75% 18:24 last_state.is_incomplete_run
1:24 -4% :24 last_state.xfstests.exit_code.1
:24 54% 13:24 last_state.xfstests.exit_code.143
:24 71% 17:24 kmsg.SLAB:Unable_to_allocate_memory_on_node#(gfp=#)
1:24 -4% :24 kmsg.TDH<#>
1:24 -4% :24 kmsg.TDH<c7>
1:24 -4% :24 kmsg.TDT<#>
1:24 -4% :24 kmsg.TDT<c7>
1:24 -4% :24 kmsg.Tx_Queue<#>
1:24 -4% :24 kmsg.buffer_info[next_to_clean]
1:24 -4% :24 kmsg.e1000#:#:#eth0:Detected_Tx_Unit_Hang
1:24 -4% :24 kmsg.jiffies<#>
1:24 -4% :24 kmsg.jiffies<#c5c>
1:24 -4% :24 kmsg.next_to_clean<#>
1:24 -4% :24 kmsg.next_to_clean<c7>
1:24 -4% :24 kmsg.next_to_use<#>
1:24 -4% :24 kmsg.next_to_use<c9>
1:24 -4% :24 kmsg.next_to_watch.status<#>
1:24 -4% :24 kmsg.next_to_watch<#>
1:24 -4% :24 kmsg.next_to_watch<c8>
1:24 -4% :24 kmsg.time_stamp<#>
1:24 -4% :24 kmsg.time_stamp<#afd>
vm-kbuild-1G: qemu-system-x86_64 -enable-kvm -cpu Haswell,+smep,+smap
Memory: 1G
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,
Ying Huang
5 years, 11 months
[lkp] [drm/i915] d1b4e18f22: WARNING: CPU: 3 PID: 603 at drivers/gpu/drm/i915/intel_display.c:3930 intel_atomic_commit+0xee8/0xf00 [i915]()
by kernel test robot
FYI, we noticed the below changes on
git://people.freedesktop.org/~mlankhorst/linux rework-page-flip
commit d1b4e18f227c199dd4f282851beb0021248e62df ("drm/i915: Rework intel_crtc_page_flip to be almost atomic.")
+----------------+------------+------------+
| | 9f5bfb2ceb | d1b4e18f22 |
+----------------+------------+------------+
| boot_successes | 15 | 15 |
+----------------+------------+------------+
<3>[ 26.450837] x86/PAT: bmc-watchdog:502 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back
<3>[ 26.465205] x86/PAT: bmc-watchdog:502 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back
<4>[ 269.015854] ------------[ cut here ]------------
<4>[ 269.020496] WARNING: CPU: 3 PID: 603 at drivers/gpu/drm/i915/intel_display.c:3930 intel_atomic_commit+0xee8/0xf00 [i915]()
<4>[ 269.032904] Removing stuck page flip
<4>[ 269.036480] Modules linked in: rpcsec_gss_krb5 auth_rpcgss nfsv4 dns_resolver netconsole btrfs xor raid6_pq sg sd_mod ata_generic pata_acpi x86_pkg_temp_thermal coretemp snd_hda_codec_hdmi kvm_intel i915 eeepc_wmi drm_kms_helper syscopyarea sysfillrect sysimgblt kvm snd_hda_codec_realtek crct10dif_pclmul crc32_pclmul asus_wmi crc32c_intel sparse_keymap ppdev rfkill fb_sys_fops snd_hda_codec_generic aesni_intel lrw gf128mul glue_helper ablk_helper cryptd pata_via ata_piix microcode serio_raw pcspkr snd_hda_intel snd_hda_codec snd_hda_core snd_hwdep libata snd_pcm snd_timer snd drm soundcore shpchp parport_pc parport tpm_infineon video wmi
<4>[ 269.093919] CPU: 3 PID: 603 Comm: kms_flip Not tainted 4.3.0-rc6-00978-gd1b4e18 #1
<4>[ 269.101478] Hardware name: System manufacturer System Product Name/P8H67-M PRO, BIOS 1002 04/01/2011
<4>[ 269.110588] ffffffffa06a7a68 ffff88007eef7b88 ffffffff81402902 ffff88007eef7bd0
<4>[ 269.118033] ffff88007eef7bc0 ffffffff81074726 ffff88007f0c61a8 ffff88007fa9c200
<4>[ 269.125482] ffff88019b58f000 0000000000000000 ffff88007fbd9a00 ffff88007eef7c20
<4>[ 269.132928] Call Trace:
<4>[ 269.135374] [<ffffffff81402902>] dump_stack+0x4b/0x69
<4>[ 269.140507] [<ffffffff81074726>] warn_slowpath_common+0x86/0xc0
<4>[ 269.146506] [<ffffffff810747ac>] warn_slowpath_fmt+0x4c/0x50
<4>[ 269.152241] [<ffffffff810b7477>] ? finish_wait+0x67/0x80
<4>[ 269.157650] [<ffffffffa0643c38>] intel_atomic_commit+0xee8/0xf00 [i915]
<4>[ 269.164339] [<ffffffff811c4af4>] ? __kmalloc_track_caller+0x1c4/0x270
<4>[ 269.170870] [<ffffffffa008b634>] ? drm_atomic_check_only+0x144/0x5d0 [drm]
<4>[ 269.177819] [<ffffffff810b7620>] ? wait_woken+0xb0/0xb0
<4>[ 269.183127] [<ffffffffa008baf7>] drm_atomic_commit+0x37/0x60 [drm]
<4>[ 269.189384] [<ffffffffa05b2dae>] drm_atomic_helper_connector_dpms+0xee/0x1a0 [drm_kms_helper]
<4>[ 269.197987] [<ffffffffa0080920>] drm_mode_obj_set_property_ioctl+0x240/0x250 [drm]
<4>[ 269.205640] [<ffffffffa0080960>] drm_mode_connector_property_set_ioctl+0x30/0x40 [drm]
<4>[ 269.213631] [<ffffffffa0071702>] drm_ioctl+0x142/0x590 [drm]
<4>[ 269.219373] [<ffffffffa0080930>] ? drm_mode_obj_set_property_ioctl+0x250/0x250 [drm]
<4>[ 269.227249] [<ffffffff8139f03c>] ? ioctl_has_perm+0xac/0xc0
<4>[ 269.232903] [<ffffffff811f39d1>] do_vfs_ioctl+0x301/0x560
<4>[ 269.238385] [<ffffffff8139f0a0>] ? selinux_file_ioctl+0x50/0xd0
<4>[ 269.244385] [<ffffffff81393903>] ? security_file_ioctl+0x43/0x60
<4>[ 269.250463] [<ffffffff811f3ca9>] SyS_ioctl+0x79/0x90
<4>[ 269.255503] [<ffffffff818ae26e>] entry_SYSCALL_64_fastpath+0x12/0x71
<4>[ 269.261927] ---[ end trace bfb2c114e35026cc ]---
<4>[ 269.261927] ---[ end trace bfb2c114e35026cc ]---
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
Thanks,
Ying Huang
5 years, 11 months
[lkp] [drm/i915] 1b7e515ce1: WARNING: CPU: 0 PID: 2747 at drivers/gpu/drm/i915/intel_display.c:3930 intel_atomic_commit+0x104b/0x1060 [i915]()
by kernel test robot
FYI, we noticed the below changes on
git://people.freedesktop.org/~mlankhorst/linux rework-page-flip
commit 1b7e515ce1b5f53e2deb752d4769b2acc81784ae ("drm/i915: Make unpin async.")
<3>[ 25.859472] x86/PAT: bmc-watchdog:492 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back
<6>[ 33.029292] [drm] Fake missed irq on blitter ring
<4>[ 487.818346] ------------[ cut here ]------------
<4>[ 487.823000] WARNING: CPU: 0 PID: 2747 at drivers/gpu/drm/i915/intel_display.c:3930 intel_atomic_commit+0x104b/0x1060 [i915]()
<4>[ 487.835670] Removing stuck page flip
<4>[ 487.839247] Modules linked in: rpcsec_gss_krb5 auth_rpcgss nfsv4 dns_resolver netconsole btrfs xor raid6_pq sg sd_mod ata_generic snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic pata_acpi i915 drm_kms_helper snd_hda_intel x86_pkg_temp_thermal coretemp snd_hda_codec kvm_intel snd_hda_core syscopyarea snd_hwdep sysfillrect sysimgblt kvm snd_pcm crct10dif_pclmul crc32_pclmul crc32c_intel eeepc_wmi asus_wmi sparse_keymap snd_timer fb_sys_fops aesni_intel lrw gf128mul glue_helper ablk_helper snd rfkill cryptd pata_via ppdev microcode serio_raw pcspkr ata_piix libata drm soundcore shpchp wmi parport_pc parport tpm_infineon video
<4>[ 487.896765] CPU: 0 PID: 2747 Comm: kms_universal_p Not tainted 4.3.0-rc6-00979-g1b7e515 #1
<4>[ 487.905015] Hardware name: System manufacturer System Product Name/P8H67-M PRO, BIOS 1002 04/01/2011
<4>[ 487.914134] ffffffffa048ca68 ffff880032ef3b20 ffffffff81402902 ffff880032ef3b68
<4>[ 487.921587] ffff880032ef3b58 ffffffff81074726 ffff88009af439a8 ffff8801bd697400
<4>[ 487.929036] ffff8801bd09d000 0000000000000000 ffff88018d2f0e00 ffff880032ef3bb8
<4>[ 487.936491] Call Trace:
<4>[ 487.938946] [<ffffffff81402902>] dump_stack+0x4b/0x69
<4>[ 487.944079] [<ffffffff81074726>] warn_slowpath_common+0x86/0xc0
<4>[ 487.950078] [<ffffffff810747ac>] warn_slowpath_fmt+0x4c/0x50
<4>[ 487.955822] [<ffffffff810b7477>] ? finish_wait+0x67/0x80
<4>[ 487.961235] [<ffffffffa042860b>] intel_atomic_commit+0x104b/0x1060 [i915]
<4>[ 487.968115] [<ffffffffa0086634>] ? drm_atomic_check_only+0x144/0x5d0 [drm]
<4>[ 487.975061] [<ffffffff810b7620>] ? wait_woken+0xb0/0xb0
<4>[ 487.980380] [<ffffffffa0086af7>] drm_atomic_commit+0x37/0x60 [drm]
<4>[ 487.986644] [<ffffffffa0389708>] drm_atomic_helper_disable_plane+0x98/0xd0 [drm_kms_helper]
<4>[ 487.995075] [<ffffffffa00853e8>] ? drm_modeset_lock+0x68/0xe0 [drm]
<4>[ 488.001431] [<ffffffffa0076677>] __setplane_internal+0x177/0x270 [drm]
<4>[ 488.008046] [<ffffffffa00854f0>] ? drm_modeset_lock_all_crtcs+0x90/0xa0 [drm]
<4>[ 488.015329] [<ffffffffa007a568>] drm_mode_setplane+0x138/0x1b0 [drm]
<4>[ 488.021768] [<ffffffffa006c702>] drm_ioctl+0x142/0x590 [drm]
<4>[ 488.027505] [<ffffffff81192001>] ? __do_fault+0x41/0xd0
<4>[ 488.032820] [<ffffffffa007a430>] ? drm_plane_check_pixel_format+0x50/0x50 [drm]
<4>[ 488.040208] [<ffffffff8139f03c>] ? ioctl_has_perm+0xac/0xc0
<4>[ 488.045862] [<ffffffff811f39d1>] do_vfs_ioctl+0x301/0x560
<4>[ 488.051341] [<ffffffff8139f0a0>] ? selinux_file_ioctl+0x50/0xd0
<4>[ 488.057337] [<ffffffff81393903>] ? security_file_ioctl+0x43/0x60
<4>[ 488.063423] [<ffffffff811f3ca9>] SyS_ioctl+0x79/0x90
<4>[ 488.068471] [<ffffffff81003ba5>] ? syscall_return_slowpath+0x55/0x160
<4>[ 488.074991] [<ffffffff818ae26e>] entry_SYSCALL_64_fastpath+0x12/0x71
<4>[ 488.081425] ---[ end trace 2fabf19ab8aad141 ]---
<47>
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
Thanks,
Ying Huang
5 years, 11 months
[lkp] [regression] c4e4528a6d: WARNING: CPU: 0 PID: 1 at kernel/trace/trace_functions_graph.c:223 ftrace_return_to_handler+0x94/0x11f()
by kernel test robot
FYI, we noticed the below changes on
https://github.com/alaahl/linux.git for-upstream
commit c4e4528a6dbfc0161a9445fdcbc53c26168adda9 ("regression: Disable FENTRY")
+-----------------------------------------------------------------------------+------------+------------+
| | b32721f46a | c4e4528a6d |
+-----------------------------------------------------------------------------+------------+------------+
| boot_successes | 9 | 0 |
| boot_failures | 6 | 13 |
| Out_of_memory:Kill_process | 5 | |
| BUG:kernel_boot_hang | 1 | |
| WARNING:at_kernel/trace/trace_functions_graph.c:#ftrace_return_to_handler() | 0 | 13 |
| backtrace:register_tracer | 0 | 10 |
| backtrace:init_graph_trace | 0 | 10 |
| backtrace:kernel_init_freeable | 0 | 10 |
| backtrace:cpu_startup_entry | 0 | 1 |
+-----------------------------------------------------------------------------+------------+------------+
[ 2.093113] Testing ftrace regs: PASSED
[ 2.192075] Testing tracer function_graph:
[ 2.280011] ------------[ cut here ]------------
[ 2.284000] WARNING: CPU: 0 PID: 1 at kernel/trace/trace_functions_graph.c:223 ftrace_return_to_handler+0x94/0x11f()
[ 2.284000] Bad frame pointer: expected ffff880013803e40, received ffff880013803ed0
[ 2.284000] from func __calc_delta return to ffffffff884e5684
[ 2.284000] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.3.0-rc6-01764-gc4e4528 #1
[ 2.284000] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 2.284000] 0000000000000000 ffff880013803d40 ffffffff88b30204 ffff880013803d88
[ 2.284000] ffff880013803d78 ffffffff884a7e22 ffffffff88562357 ffffffff885920c9
[ 2.284000] ffff8800138151c0 000000000002b800 ffff880012be0060 ffff880013803de0
[ 2.284000] Call Trace:
[ 2.284000] <IRQ> [<ffffffff88b30204>] dump_stack+0x72/0x9d
[ 2.284000] [<ffffffff884a7e22>] warn_slowpath_common+0xbc/0xd5
[ 2.284000] [<ffffffff88562357>] ? ftrace_return_to_handler+0x94/0x11f
[ 2.284000] [<ffffffff885920c9>] ? devm_memunmap+0x85/0x85
[ 2.284000] [<ffffffff884a7ea2>] warn_slowpath_fmt+0x48/0x50
[ 2.284000] [<ffffffff884e20f9>] ? __calc_delta+0x20/0x176
[ 2.284000] [<ffffffff88562357>] ftrace_return_to_handler+0x94/0x11f
[ 2.284000] [<ffffffff884e5684>] ? sched_slice+0xf3/0x102
[ 2.284000] [<ffffffff884e5684>] ? sched_slice+0xf3/0x102
[ 2.284000] [<ffffffff8a084f19>] return_to_handler+0x15/0x27
[ 2.284000] [<ffffffff8a084f04>] ? ftrace_graph_caller+0x94/0x94
[ 2.284000] [<ffffffff884e5684>] sched_slice+0xf3/0x102
[ 2.284000] [<ffffffff8a084f04>] ftrace_graph_caller+0x94/0x94
[ 2.284000] [<ffffffff884eaf21>] entity_tick+0x965/0xa30
[ 2.284000] [<ffffffff884eb783>] ? task_tick_fair+0x37/0x67
[ 2.284000] [<ffffffff8a084f04>] ftrace_graph_caller+0x94/0x94
[ 2.284000] [<ffffffff884ead98>] entity_tick+0x7dc/0xa30
[ 2.284000] [<ffffffff8a084f04>] ftrace_graph_caller+0x94/0x94
[ 2.284000] [<ffffffff884ea86b>] entity_tick+0x2af/0xa30
[ 2.284000] [<ffffffff8a084f04>] ftrace_graph_caller+0x94/0x94
[ 2.284000] [<ffffffff884eb783>] task_tick_fair+0x37/0x67
[ 2.284000] [<ffffffff8a084f04>] ftrace_graph_caller+0x94/0x94
[ 2.284000] [<ffffffff884dbe0b>] scheduler_tick+0x56/0x92
[ 2.284000] [<ffffffff8a084f04>] ftrace_graph_caller+0x94/0x94
[ 2.284000] [<ffffffff8851a4c7>] update_process_times+0x5a/0x67
[ 2.284000] [<ffffffff8a084f04>] ftrace_graph_caller+0x94/0x94
[ 2.284000] [<ffffffff88529cbe>] tick_periodic+0x83/0x85
[ 2.284000] [<ffffffff8a0832e7>] apic_timer_interrupt+0x87/0x90
[ 2.284000] <EOI> [<ffffffff88e2706b>] ? amd_sched_process_job+0x9/0xb0
[ 2.284000] [<ffffffff88e2706b>] ? amd_sched_process_job+0x9/0xb0
[ 2.284000] [<ffffffff8854faf8>] ? hash_contains_ip+0x22/0x5a
[ 2.284000] [<ffffffff885528ec>] ftrace_get_addr_new+0x29/0xa2
[ 2.284000] [<ffffffff8844e32e>] ftrace_replace_code+0x1b6/0x3f8
[ 2.284000] [<ffffffff88552d92>] ftrace_modify_all_code+0x41/0xca
[ 2.284000] [<ffffffff8844e580>] arch_ftrace_update_code+0x10/0x19
[ 2.284000] [<ffffffff88551f43>] ftrace_run_update_code+0x1e/0x3f
[ 2.284000] [<ffffffff88551f96>] ftrace_startup_enable+0x32/0x34
[ 2.284000] [<ffffffff8855215d>] ftrace_startup+0x1c5/0x1e1
[ 2.284000] [<ffffffff885626f3>] ? trace_graph_function+0x78/0x78
[ 2.284000] [<ffffffff885541e1>] register_ftrace_graph+0x241/0x25e
[ 2.284000] [<ffffffff8855ed64>] ? ftrace_dump+0x1fd/0x1fd
[ 2.284000] [<ffffffff8bef2d21>] trace_selftest_startup_function_graph+0x55/0xdf
[ 2.284000] [<ffffffff8855e32a>] register_tracer+0x12a/0x218
[ 2.284000] [<ffffffff8bef3492>] ? init_graph_tracefs+0x32/0x32
[ 2.284000] [<ffffffff8bef34f4>] init_graph_trace+0x62/0x64
[ 2.284000] [<ffffffff8bec9580>] do_one_initcall+0x131/0x224
[ 2.284000] [<ffffffff8bec9781>] kernel_init_freeable+0x10e/0x1d1
[ 2.284000] [<ffffffff8a07add5>] ? rest_init+0x8c/0x8c
[ 2.284000] [<ffffffff8a07ade3>] kernel_init+0xe/0x144
[ 2.284000] [<ffffffff8a0828df>] ret_from_fork+0x3f/0x70
[ 2.284000] [<ffffffff8a07add5>] ? rest_init+0x8c/0x8c
[ 2.284000] ---[ end trace 62f2af1e4e86c781 ]---
[ 2.284000] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G W 4.3.0-rc6-01764-gc4e4528 #1
Thanks,
Ying Huang
5 years, 11 months
[lkp] [freezer] e8de7ec5cc: WARNING: CPU: 1 PID: 699 at include/linux/freezer.h:62 svc_recv+0x3eb/0x80c()
by kernel test robot
FYI, we noticed the below changes on
https://github.com/0day-ci/linux Jiri-Kosina/PM-vfs-use-filesystem-freezing-instead-of-kthread-freezer/20151030-215223
commit e8de7ec5cc871dacba9149fe6b5aacc545baef2c ("freezer: warn if anyone is trying to use freezer on kthreads")
+------------------------------------------------+------------+------------+
| | f5121659bb | e8de7ec5cc |
+------------------------------------------------+------------+------------+
| boot_successes | 15 | 11 |
| boot_failures | 0 | 3 |
| WARNING:at_include/linux/freezer.h:#svc_recv() | 0 | 3 |
| backtrace:nfs4_callback_svc | 0 | 3 |
+------------------------------------------------+------------+------------+
wget --local-encoding=UTF-8 --retry-connrefused --waitretry 1000 --tries 1000 http://inn:80/~lkp/cgi-bin/lkp-jobfile-append-var?job_file=/lkp/scheduled... -q -O /dev/null
wait for background monitors: oom-killer watchdog
wget --local-encoding=UTF-8 --retry-connrefused --waitretry 1000 --tries 1000 http://inn:80/~lkp/cgi-bin/lkp-jobfile-append-var?job_file=/lkp/scheduled... 0.16 0.05 1/118 817&start_time=1446232943&end_time=1446232944&version=/lkp/lkp/.src-20151030-185949& -q -O /dev/null
[ 25.953285] WARNING: CPU: 1 PID: 699 at include/linux/freezer.h:62 svc_recv+0x3eb/0x80c()
[ 25.954875] CPU: 1 PID: 699 Comm: nfsv4.0-svc Not tainted 4.3.0-rc7-00003-ge8de7ec #304
[ 25.956198] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 25.958815] 0000000000000000 ffff88003ed9bd78 ffffffff813d2273 0000000000000000
[ 25.960135] ffff88003ed9bdb0 ffffffff8107708b ffffffff81c4c17c ffff88003e0d2000
[ 25.961434] ffff88003eee8000 ffff88003f3df240 7fffffffffffffff ffff88003ed9bdc0
[ 25.962727] Call Trace:
[ 25.963151] [<ffffffff813d2273>] dump_stack+0x4b/0x6c
[ 25.963999] [<ffffffff8107708b>] warn_slowpath_common+0x8a/0xa3
[ 25.965015] [<ffffffff81c4c17c>] ? svc_recv+0x3eb/0x80c
[ 25.965888] [<ffffffff8107716b>] warn_slowpath_null+0x15/0x17
[ 25.966839] [<ffffffff81c4c17c>] svc_recv+0x3eb/0x80c
[ 25.967687] [<ffffffff81c78ca7>] ? __schedule+0x5cf/0x823
[ 25.968629] [<ffffffff8123f93a>] ? nfs_callback_authenticate+0x29/0x29
[ 25.969704] [<ffffffff8123f95d>] nfs4_callback_svc+0x23/0x3c
[ 25.970644] [<ffffffff8108e0ad>] kthread+0xf6/0xfe
[ 25.971448] [<ffffffff8108dfb7>] ? __kthread_parkme+0x82/0x82
[ 25.972432] [<ffffffff81c7dfdf>] ret_from_fork+0x3f/0x70
[ 25.973328] [<ffffffff8108dfb7>] ? __kthread_parkme+0x82/0x82
[ 25.974283] ---[ end trace d811f9ece3901e91 ]---
[ 25.980080] ------------[ cut here ]------------
Thanks,
Ying Huang
5 years, 11 months
[lkp] [sched/fair] 3e386d56ba: 7.1% unixbench.score, -2.9% will-it-scale.per_process_ops
by kernel test robot
FYI, we noticed the below changes on
=========================================================================================
tbox_group/testcase/rootfs/kconfig/compiler/cpufreq_governor/test:
ivb42/unixbench/debian-x86_64-2015-02-07.cgz/x86_64-rhel/gcc-4.9/performance/execl
commit:
fde7d22e01aa0d252fc5c95fa11f0dac35a4dd59
3e386d56bafbb6d2540b49367444997fc671ea69
fde7d22e01aa0d25 3e386d56bafbb6d2540b493674
---------------- --------------------------
%stddev %change %stddev
\ | \
706.60 ± 0% +7.1% 756.42 ± 0% unixbench.score
271496 ± 0% +6.2% 288421 ± 0% unixbench.time.involuntary_context_switches
12630377 ± 0% +7.0% 13516262 ± 0% unixbench.time.minor_page_faults
11.78 ± 2% +18.5% 13.96 ± 0% time.user_time
2.932e+08 ± 14% -87.8% 35868049 ± 5% cpuidle.C3-IVT.time
12344 ± 3% -57.7% 5226 ± 9% cpuidle.C3-IVT.usage
5132879 ± 1% +16.5% 5979941 ± 7% numa-numastat.node1.local_node
5132891 ± 1% +16.5% 5979952 ± 7% numa-numastat.node1.numa_hit
16780 ± 4% -15.0% 14258 ± 4% slabinfo.kmalloc-256.active_objs
17113 ± 4% -14.8% 14586 ± 4% slabinfo.kmalloc-256.num_objs
1.86 ± 0% -1.9% 1.83 ± 0% turbostat.%Busy
10.10 ± 12% -86.8% 1.33 ± 4% turbostat.CPU%c3
79.45 ± 1% +11.5% 88.61 ± 0% turbostat.CPU%c6
60.59 ± 0% -11.0% 53.93 ± 1% turbostat.CorWatt
5.65 ± 4% +264.6% 20.61 ± 3% turbostat.Pkg%pc2
0.77 ± 58% -73.9% 0.20 ±100% turbostat.Pkg%pc3
88.20 ± 0% -7.8% 81.34 ± 1% turbostat.PkgWatt
5.09 ± 0% -2.8% 4.95 ± 1% turbostat.RAMWatt
38088 ± 6% -52.1% 18242 ± 34% sched_debug.cfs_rq[0]:/.min_vruntime
24954 ± 8% -60.3% 9916 ± 39% sched_debug.cfs_rq[10]:/.min_vruntime
30849 ± 25% -52.0% 14817 ± 39% sched_debug.cfs_rq[11]:/.min_vruntime
37.00 ± 82% -74.3% 9.50 ± 81% sched_debug.cfs_rq[11]:/.util_avg
30585 ± 8% -62.9% 11360 ± 21% sched_debug.cfs_rq[12]:/.min_vruntime
29033 ± 18% -64.0% 10445 ± 28% sched_debug.cfs_rq[13]:/.min_vruntime
32558 ± 10% -68.1% 10380 ± 25% sched_debug.cfs_rq[14]:/.min_vruntime
1059 ± 6% +83.6% 1945 ± 67% sched_debug.cfs_rq[15]:/.exec_clock
27378 ± 12% -58.3% 11429 ± 36% sched_debug.cfs_rq[15]:/.min_vruntime
25520 ± 15% -58.1% 10691 ± 47% sched_debug.cfs_rq[16]:/.min_vruntime
30656 ± 18% -67.6% 9934 ± 30% sched_debug.cfs_rq[17]:/.min_vruntime
3.00 ± 40% +2483.3% 77.50 ±156% sched_debug.cfs_rq[18]:/.load_avg
27897 ± 21% -63.6% 10160 ± 21% sched_debug.cfs_rq[18]:/.min_vruntime
3.00 ± 40% +2475.0% 77.25 ±156% sched_debug.cfs_rq[18]:/.tg_load_avg_contrib
27784 ± 13% -61.4% 10716 ± 28% sched_debug.cfs_rq[19]:/.min_vruntime
1.00 ±-100% +75.0% 1.75 ± 47% sched_debug.cfs_rq[19]:/.nr_spread_over
1949 ± 45% -45.7% 1057 ± 20% sched_debug.cfs_rq[1]:/.exec_clock
32447 ± 16% -69.0% 10058 ± 34% sched_debug.cfs_rq[1]:/.min_vruntime
28770 ± 19% -59.8% 11565 ± 36% sched_debug.cfs_rq[20]:/.min_vruntime
30065 ± 13% -61.1% 11706 ± 25% sched_debug.cfs_rq[21]:/.min_vruntime
28042 ± 16% -67.6% 9090 ± 23% sched_debug.cfs_rq[22]:/.min_vruntime
27289 ± 18% -58.2% 11419 ± 27% sched_debug.cfs_rq[23]:/.min_vruntime
23431 ± 21% -67.0% 7737 ± 35% sched_debug.cfs_rq[24]:/.min_vruntime
0.00 ± 0% +Inf% 116.25 ±170% sched_debug.cfs_rq[25]:/.load_avg
25502 ± 9% -66.3% 8603 ± 29% sched_debug.cfs_rq[25]:/.min_vruntime
0.00 ± 0% +Inf% 116.25 ±170% sched_debug.cfs_rq[25]:/.tg_load_avg_contrib
25720 ± 16% -64.8% 9058 ± 48% sched_debug.cfs_rq[26]:/.min_vruntime
26035 ± 15% -63.6% 9485 ± 39% sched_debug.cfs_rq[27]:/.min_vruntime
26648 ± 7% -68.8% 8316 ± 26% sched_debug.cfs_rq[28]:/.min_vruntime
24833 ± 20% -66.2% 8394 ± 41% sched_debug.cfs_rq[29]:/.min_vruntime
44.50 ± 50% -83.1% 7.50 ± 64% sched_debug.cfs_rq[29]:/.util_avg
29817 ± 3% -59.6% 12057 ± 21% sched_debug.cfs_rq[2]:/.min_vruntime
26058 ± 11% -62.0% 9900 ± 34% sched_debug.cfs_rq[30]:/.min_vruntime
22433 ± 13% -64.0% 8083 ± 45% sched_debug.cfs_rq[31]:/.min_vruntime
25121 ± 17% -67.8% 8092 ± 39% sched_debug.cfs_rq[32]:/.min_vruntime
22525 ± 15% -64.5% 7985 ± 47% sched_debug.cfs_rq[33]:/.min_vruntime
21583 ± 2% -60.8% 8451 ± 35% sched_debug.cfs_rq[34]:/.min_vruntime
23653 ± 18% -69.3% 7256 ± 29% sched_debug.cfs_rq[35]:/.min_vruntime
24629 ± 21% -58.0% 10337 ± 24% sched_debug.cfs_rq[36]:/.min_vruntime
0.00 ± 0% +Inf% 2.25 ± 48% sched_debug.cfs_rq[37]:/.load_avg
27117 ± 15% -70.2% 8093 ± 32% sched_debug.cfs_rq[37]:/.min_vruntime
0.00 ± 0% +Inf% 2.25 ± 48% sched_debug.cfs_rq[37]:/.tg_load_avg_contrib
0.50 ±100% +450.0% 2.75 ± 30% sched_debug.cfs_rq[38]:/.load_avg
24524 ± 15% -64.3% 8764 ± 28% sched_debug.cfs_rq[38]:/.min_vruntime
0.50 ±100% +450.0% 2.75 ± 30% sched_debug.cfs_rq[38]:/.tg_load_avg_contrib
24164 ± 23% -67.4% 7871 ± 45% sched_debug.cfs_rq[39]:/.min_vruntime
1100 ± 14% +40.9% 1550 ± 28% sched_debug.cfs_rq[3]:/.exec_clock
27801 ± 8% -53.8% 12833 ± 25% sched_debug.cfs_rq[3]:/.min_vruntime
25596 ± 24% -77.2% 5828 ± 37% sched_debug.cfs_rq[40]:/.min_vruntime
0.00 ± 0% +Inf% 1.75 ± 74% sched_debug.cfs_rq[41]:/.load_avg
25382 ± 16% -71.5% 7225 ± 39% sched_debug.cfs_rq[41]:/.min_vruntime
0.00 ± 0% +Inf% 1.75 ± 74% sched_debug.cfs_rq[41]:/.tg_load_avg_contrib
1.00 ±141% +325.0% 4.25 ± 50% sched_debug.cfs_rq[42]:/.load_avg
22692 ± 13% -61.6% 8702 ± 38% sched_debug.cfs_rq[42]:/.min_vruntime
1.00 ±141% +325.0% 4.25 ± 50% sched_debug.cfs_rq[42]:/.tg_load_avg_contrib
25387 ± 7% -70.2% 7574 ± 33% sched_debug.cfs_rq[43]:/.min_vruntime
23889 ± 14% -60.1% 9530 ± 67% sched_debug.cfs_rq[44]:/.min_vruntime
0.25 ±173% +1000.0% 2.75 ± 39% sched_debug.cfs_rq[45]:/.load_avg
21897 ± 15% -54.6% 9943 ± 28% sched_debug.cfs_rq[45]:/.min_vruntime
-16192 ± -7% -48.7% -8303 ±-51% sched_debug.cfs_rq[45]:/.spread0
0.25 ±173% +1000.0% 2.75 ± 39% sched_debug.cfs_rq[45]:/.tg_load_avg_contrib
24639 ± 21% -64.1% 8855 ± 39% sched_debug.cfs_rq[46]:/.min_vruntime
25239 ± 6% -59.0% 10353 ± 37% sched_debug.cfs_rq[47]:/.min_vruntime
3.25 ± 90% +1738.5% 59.75 ±148% sched_debug.cfs_rq[4]:/.load_avg
27745 ± 7% -59.1% 11337 ± 21% sched_debug.cfs_rq[4]:/.min_vruntime
3.25 ± 90% +1738.5% 59.75 ±148% sched_debug.cfs_rq[4]:/.tg_load_avg_contrib
26.00 ± 98% +309.6% 106.50 ± 82% sched_debug.cfs_rq[4]:/.util_avg
25027 ± 11% -52.7% 11834 ± 29% sched_debug.cfs_rq[5]:/.min_vruntime
26777 ± 13% -60.3% 10631 ± 61% sched_debug.cfs_rq[6]:/.min_vruntime
25.00 ± 45% -62.0% 9.50 ± 75% sched_debug.cfs_rq[6]:/.util_avg
25408 ± 9% -49.0% 12966 ± 51% sched_debug.cfs_rq[7]:/.min_vruntime
25245 ± 12% -58.6% 10460 ± 27% sched_debug.cfs_rq[8]:/.min_vruntime
850.74 ± 6% +24.9% 1062 ± 12% sched_debug.cfs_rq[9]:/.exec_clock
21282 ± 6% -47.5% 11170 ± 29% sched_debug.cfs_rq[9]:/.min_vruntime
-16808 ±-13% -57.9% -7076 ±-46% sched_debug.cfs_rq[9]:/.spread0
13055 ± 9% -12.4% 11442 ± 12% sched_debug.cpu#10.nr_switches
5695 ± 9% -16.9% 4734 ± 13% sched_debug.cpu#10.sched_goidle
7680 ± 7% +19.8% 9198 ± 11% sched_debug.cpu#15.nr_load_updates
12164 ± 4% +17.2% 14262 ± 7% sched_debug.cpu#18.sched_count
2403 ± 14% +22.4% 2942 ± 5% sched_debug.cpu#18.ttwu_local
1.00 ±212% -375.0% -2.75 ±-53% sched_debug.cpu#22.nr_uninterruptible
2288 ± 15% +24.2% 2841 ± 9% sched_debug.cpu#23.ttwu_local
2414 ± 23% -31.2% 1660 ± 15% sched_debug.cpu#24.ttwu_count
2156 ± 15% -28.2% 1549 ± 13% sched_debug.cpu#24.ttwu_local
2077 ± 15% -24.8% 1562 ± 14% sched_debug.cpu#29.ttwu_count
1719 ± 6% -14.5% 1470 ± 11% sched_debug.cpu#34.ttwu_local
2347 ± 25% -23.3% 1799 ± 11% sched_debug.cpu#47.ttwu_local
-4.00 ±-39% -137.5% 1.50 ±238% sched_debug.cpu#9.nr_uninterruptible
=========================================================================================
tbox_group/testcase/rootfs/kconfig/compiler/test:
nhm-white2/unixbench/debian-x86_64-2015-02-07.cgz/x86_64-rhel/gcc-4.9/shell1
commit:
fde7d22e01aa0d252fc5c95fa11f0dac35a4dd59
3e386d56bafbb6d2540b49367444997fc671ea69
fde7d22e01aa0d25 3e386d56bafbb6d2540b493674
---------------- --------------------------
%stddev %change %stddev
\ | \
7433 ± 0% -0.8% 7376 ± 0% unixbench.time.maximum_resident_set_size
4602 ± 19% +33.2% 6132 ± 0% meminfo.AnonHugePages
0.31 ± 72% -75.4% 0.08 ± 6% turbostat.Pkg%pc3
7552 ± 2% -11.9% 6656 ± 0% slabinfo.kmalloc-8.active_objs
7552 ± 2% -11.9% 6656 ± 0% slabinfo.kmalloc-8.num_objs
46156 ± 6% +17.4% 54180 ± 0% vmstat.system.cs
12893 ± 8% +27.1% 16386 ± 0% vmstat.system.in
3060906 ± 6% +34.5% 4117057 ± 1% cpuidle.C1-NHM.usage
7.226e+08 ± 23% -24.1% 5.487e+08 ± 0% cpuidle.C6-NHM.time
157908 ± 5% -40.7% 93649 ± 5% cpuidle.POLL.time
11920829 ±119% -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
2145589 ±105% -100.0% 404.50 ± 8% latency_stats.avg.wait_on_page_bit.filemap_fdatawait_range.filemap_fdatawait.sync_inodes_sb.sync_inodes_one_sb.iterate_supers.sys_sync.entry_SYSCALL_64_fastpath
19732421 ±101% -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
20091553 ±101% -100.0% 1856 ± 7% latency_stats.max.wait_on_page_bit.filemap_fdatawait_range.filemap_fdatawait.sync_inodes_sb.sync_inodes_one_sb.iterate_supers.sys_sync.entry_SYSCALL_64_fastpath
24618851 ±108% -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
20945539 ±101% -100.0% 4420 ± 1% latency_stats.sum.wait_on_page_bit.filemap_fdatawait_range.filemap_fdatawait.sync_inodes_sb.sync_inodes_one_sb.iterate_supers.sys_sync.entry_SYSCALL_64_fastpath
0.00 ± 0% +8.4e+13% 837002 ± 86% sched_debug.cfs_rq[0]:/.MIN_vruntime
0.00 ± 0% +8.4e+13% 837002 ± 86% sched_debug.cfs_rq[0]:/.max_vruntime
8.50 ± 54% +2647.1% 233.50 ± 93% sched_debug.cfs_rq[1]:/.load
204224 ± 47% -87.2% 26105 ± 16% sched_debug.cfs_rq[1]:/.spread0
10.25 ± 19% -51.2% 5.00 ± 0% sched_debug.cfs_rq[2]:/.nr_spread_over
173.00 ± 38% -100.0% 0.00 ± -1% sched_debug.cfs_rq[2]:/.removed_load_avg
170.00 ± 39% -100.0% 0.00 ± -1% sched_debug.cfs_rq[2]:/.removed_util_avg
5.00 ± 44% +70.0% 8.50 ± 5% sched_debug.cfs_rq[3]:/.nr_spread_over
7.25 ± 64% +127.6% 16.50 ± 21% sched_debug.cfs_rq[3]:/.util_avg
13.25 ± 11% -66.0% 4.50 ± 77% sched_debug.cfs_rq[4]:/.load
-11087 ±-722% +1314.6% -156849 ± 0% sched_debug.cfs_rq[4]:/.spread0
0.25 ±173% +3.3e+05% 829.50 ± 99% sched_debug.cfs_rq[6]:/.load_avg
41.67 ±141% +243.2% 143.00 ± 0% sched_debug.cfs_rq[6]:/.removed_load_avg
41.67 ±141% +243.2% 143.00 ± 0% sched_debug.cfs_rq[6]:/.removed_util_avg
5.25 ± 15% +195.2% 15.50 ± 54% sched_debug.cfs_rq[6]:/.runnable_load_avg
7903 ±1955% -4052.1% -312364 ± 0% sched_debug.cfs_rq[6]:/.spread0
0.25 ±173% +3.3e+05% 829.50 ± 99% sched_debug.cfs_rq[6]:/.tg_load_avg_contrib
4.75 ± 17% +36.8% 6.50 ± 7% sched_debug.cpu#0.cpu_load[0]
7.00 ± 17% -35.7% 4.50 ± 11% sched_debug.cpu#1.cpu_load[2]
8.00 ± 57% +93.8% 15.50 ± 9% sched_debug.cpu#1.load
4.00 ± 17% +37.5% 5.50 ± 9% sched_debug.cpu#2.cpu_load[1]
14.00 ± 13% -67.9% 4.50 ± 77% sched_debug.cpu#4.load
122580 ± 50% -35.6% 78933 ± 0% sched_debug.cpu#4.ttwu_local
537910 ± 8% +15.5% 621138 ± 7% sched_debug.cpu#5.avg_idle
4.00 ± 81% +1875.0% 79.00 ± 88% sched_debug.cpu#5.cpu_load[0]
117.75 ± 15% +22.3% 144.00 ± 0% sched_debug.cpu#5.nr_uninterruptible
3.50 ± 31% +342.9% 15.50 ± 54% sched_debug.cpu#6.cpu_load[0]
4.50 ± 19% +233.3% 15.00 ± 60% sched_debug.cpu#6.cpu_load[1]
1039435 ± 86% +469.1% 5915058 ± 4% sched_debug.cpu#6.nr_switches
1039727 ± 86% +468.9% 5915290 ± 4% sched_debug.cpu#6.sched_count
430761 ± 96% +528.8% 2708717 ± 4% sched_debug.cpu#6.sched_goidle
458677 ± 97% +533.5% 2905713 ± 4% sched_debug.cpu#6.ttwu_count
334147 ±135% +734.5% 2788322 ± 4% sched_debug.cpu#6.ttwu_local
481139 ± 12% +26.2% 607278 ± 5% sched_debug.cpu#7.avg_idle
14.75 ± 24% +66.1% 24.50 ± 2% sched_debug.cpu#7.load
2.65 ±133% -99.7% 0.01 ± 5% sched_debug.rt_rq[4]:/.rt_time
0.44 ±171% +1999.6% 9.32 ± 28% sched_debug.rt_rq[6]:/.rt_time
=========================================================================================
tbox_group/testcase/rootfs/kconfig/compiler/test:
nhm-white2/unixbench/debian-x86_64-2015-02-07.cgz/x86_64-rhel/gcc-4.9/spawn
commit:
fde7d22e01aa0d252fc5c95fa11f0dac35a4dd59
3e386d56bafbb6d2540b49367444997fc671ea69
fde7d22e01aa0d25 3e386d56bafbb6d2540b493674
---------------- --------------------------
%stddev %change %stddev
\ | \
352.00 ± 9% -27.3% 256.00 ± 0% slabinfo.kmem_cache_node.active_objs
352.00 ± 9% -27.3% 256.00 ± 0% slabinfo.kmem_cache_node.num_objs
1865 ± 4% -12.9% 1625 ± 2% slabinfo.proc_inode_cache.num_objs
10.00 ± 15% -35.0% 6.50 ± 7% sched_debug.cfs_rq[1]:/.nr_spread_over
18.75 ±136% +121.3% 41.50 ± 80% sched_debug.cfs_rq[1]:/.util_avg
4.75 ± 22% +173.7% 13.00 ± 0% sched_debug.cfs_rq[2]:/.nr_spread_over
7.50 ± 27% -53.3% 3.50 ± 14% sched_debug.cfs_rq[4]:/.nr_spread_over
28.25 ± 69% -69.9% 8.50 ± 17% sched_debug.cfs_rq[4]:/.tg_load_avg
27.25 ± 61% -74.3% 7.00 ± 0% sched_debug.cfs_rq[5]:/.tg_load_avg
3.75 ± 22% +180.0% 10.50 ± 14% sched_debug.cfs_rq[6]:/.nr_spread_over
28.00 ± 60% -75.0% 7.00 ± 0% sched_debug.cfs_rq[6]:/.tg_load_avg
28.00 ± 60% -75.0% 7.00 ± 0% sched_debug.cfs_rq[7]:/.tg_load_avg
196603 ± 40% +42.3% 279845 ± 2% sched_debug.cpu#0.avg_idle
56905 ± 7% +12.2% 63846 ± 2% sched_debug.cpu#1.nr_load_updates
693272 ± 6% +119.9% 1524462 ± 53% sched_debug.cpu#1.nr_switches
693317 ± 6% +119.9% 1524508 ± 53% sched_debug.cpu#1.sched_count
308355 ± 7% +126.2% 697610 ± 54% sched_debug.cpu#1.sched_goidle
33.25 ± 89% -98.5% 0.50 ±100% sched_debug.cpu#2.cpu_load[1]
5231 ± 29% -98.2% 94.00 ±100% sched_debug.cpu#2.curr->pid
26.25 ± 76% -96.2% 1.00 ±100% sched_debug.cpu#4.cpu_load[2]
28.50 ± 68% -82.5% 5.00 ± 20% sched_debug.cpu#4.cpu_load[3]
25.75 ± 57% -74.8% 6.50 ± 23% sched_debug.cpu#4.cpu_load[4]
42205 ± 2% +8.4% 45742 ± 2% sched_debug.cpu#4.nr_load_updates
0.75 ±110% +7300.0% 55.50 ± 72% sched_debug.cpu#5.cpu_load[1]
3.75 ± 78% +1806.7% 71.50 ± 31% sched_debug.cpu#5.cpu_load[2]
8.75 ± 50% +580.0% 59.50 ± 12% sched_debug.cpu#5.cpu_load[3]
11.75 ± 25% +257.4% 42.00 ± 7% sched_debug.cpu#5.cpu_load[4]
2713 ± 98% +111.7% 5745 ± 23% sched_debug.cpu#6.curr->pid
21.75 ± 17% -33.3% 14.50 ± 3% sched_debug.cpu#7.nr_uninterruptible
0.00 ±107% +1.3e+06% 30.31 ± 99% sched_debug.rt_rq[5]:/.rt_time
=========================================================================================
tbox_group/testcase/rootfs/kconfig/compiler/test:
nhm-white/unixbench/debian-x86_64-2015-02-07.cgz/x86_64-rhel/gcc-4.9/spawn
commit:
fde7d22e01aa0d252fc5c95fa11f0dac35a4dd59
3e386d56bafbb6d2540b49367444997fc671ea69
fde7d22e01aa0d25 3e386d56bafbb6d2540b493674
---------------- --------------------------
%stddev %change %stddev
\ | \
57856 ± 3% -10.6% 51712 ± 1% meminfo.DirectMap4k
545000 ±173% -98.4% 8731 ± 95% latency_stats.avg.wait_on_page_bit.filemap_fdatawait_range.filemap_fdatawait.sync_inodes_sb.sync_inodes_one_sb.iterate_supers.sys_sync.entry_SYSCALL_64_fastpath
4731410 ±173% -98.4% 75096 ± 97% latency_stats.max.wait_on_page_bit.filemap_fdatawait_range.filemap_fdatawait.sync_inodes_sb.sync_inodes_one_sb.iterate_supers.sys_sync.entry_SYSCALL_64_fastpath
6539353 ±173% -98.8% 78590 ± 95% latency_stats.sum.wait_on_page_bit.filemap_fdatawait_range.filemap_fdatawait.sync_inodes_sb.sync_inodes_one_sb.iterate_supers.sys_sync.entry_SYSCALL_64_fastpath
5.00 ± 50% +670.0% 38.50 ± 55% sched_debug.cfs_rq[0]:/.util_avg
0.50 ±100% +600.0% 3.50 ± 42% sched_debug.cfs_rq[1]:/.load
6.75 ± 21% -55.6% 3.00 ± 0% sched_debug.cfs_rq[2]:/.nr_spread_over
1.50 ±137% +333.3% 6.50 ± 23% sched_debug.cfs_rq[5]:/.load
2.50 ±103% -100.0% 0.00 ± -1% sched_debug.cfs_rq[6]:/.load_avg
2.50 ±103% -100.0% 0.00 ± -1% sched_debug.cfs_rq[6]:/.tg_load_avg_contrib
6.00 ± 35% +91.7% 11.50 ± 13% sched_debug.cfs_rq[7]:/.nr_spread_over
30.00 ± 78% -98.3% 0.50 ±100% sched_debug.cpu#0.cpu_load[2]
26.75 ± 47% -86.9% 3.50 ±100% sched_debug.cpu#0.cpu_load[3]
20.75 ± 31% -71.1% 6.00 ±100% sched_debug.cpu#0.cpu_load[4]
-9.50 ±-15% +126.3% -21.50 ±-11% sched_debug.cpu#0.nr_uninterruptible
0.00 ± 0% +Inf% 1.50 ± 33% sched_debug.cpu#1.cpu_load[0]
0.75 ± 57% +366.7% 3.50 ± 42% sched_debug.cpu#1.load
56707 ± 5% +9.7% 62228 ± 3% sched_debug.cpu#2.nr_load_updates
0.00 ± 0% +Inf% 1.50 ± 33% sched_debug.cpu#2.nr_running
659778 ± 6% +154.5% 1679293 ± 55% sched_debug.cpu#2.nr_switches
659820 ± 6% +154.5% 1679335 ± 55% sched_debug.cpu#2.sched_count
292590 ± 6% +161.8% 765983 ± 56% sched_debug.cpu#2.sched_goidle
467991 ± 10% -26.0% 346378 ± 3% sched_debug.cpu#5.avg_idle
0.00 ± 0% +Inf% 1.00 ± 0% sched_debug.cpu#5.cpu_load[1]
6696 ± 55% -91.5% 570.00 ± 0% sched_debug.cpu#5.curr->pid
2.00 ± 86% +225.0% 6.50 ± 23% sched_debug.cpu#5.load
0.75 ±1503% +3033.3% 23.50 ± 2% sched_debug.cpu#5.nr_uninterruptible
537025 ± 2% -38.0% 333103 ± 11% sched_debug.cpu#6.avg_idle
5586 ± 59% -100.0% 0.00 ± -1% sched_debug.cpu#6.curr->pid
11.50 ± 51% -234.8% -15.50 ±-22% sched_debug.cpu#6.nr_uninterruptible
50.50 ±132% -100.0% 0.00 ± 0% sched_debug.cpu#7.cpu_load[1]
15.75 ± 47% +61.9% 25.50 ± 17% sched_debug.cpu#7.nr_uninterruptible
0.21 ± 9% +925.9% 2.15 ± 88% sched_debug.rt_rq[2]:/.rt_time
0.01 ± 12% +2.8e+05% 17.68 ± 99% sched_debug.rt_rq[6]:/.rt_time
=========================================================================================
tbox_group/testcase/rootfs/kconfig/compiler/cpufreq_governor/test:
lkp-sbx04/will-it-scale/debian-x86_64-2015-02-07.cgz/x86_64-rhel/gcc-4.9/performance/sched_yield
commit:
fde7d22e01aa0d252fc5c95fa11f0dac35a4dd59
3e386d56bafbb6d2540b49367444997fc671ea69
fde7d22e01aa0d25 3e386d56bafbb6d2540b493674
---------------- --------------------------
%stddev %change %stddev
\ | \
6076732 ± 0% -2.9% 5899888 ± 0% will-it-scale.per_process_ops
6076808 ± 0% -2.9% 5900615 ± 0% will-it-scale.per_thread_ops
0.53 ± 0% +2.7% 0.54 ± 0% will-it-scale.scalability
436.80 ± 0% -3.3% 422.49 ± 0% will-it-scale.time.user_time
46.75 ± 82% +166.3% 124.50 ± 10% cpuidle.POLL.usage
0.04 ± 10% +88.2% 0.08 ± 23% turbostat.CPU%c3
2.22 ± 5% -23.4% 1.70 ± 1% perf-profile.cycles.__calc_delta.pick_next_task_fair.__schedule.schedule.sys_sched_yield
1.03 ± 2% +22.1% 1.26 ± 3% perf-profile.cycles.clear_buddies.pick_next_entity.pick_next_task_fair.__schedule.schedule
1.16 ± 3% -40.2% 0.70 ± 1% perf-profile.cycles.pick_next_entity.__schedule.schedule.sys_sched_yield.entry_SYSCALL_64_fastpath
5.07 ± 2% +18.8% 6.03 ± 2% perf-profile.cycles.pick_next_entity.pick_next_task_fair.__schedule.schedule.sys_sched_yield
12421 ± 2% -14.2% 10663 ± 3% slabinfo.kmalloc-192.active_objs
12422 ± 2% -12.7% 10847 ± 3% slabinfo.kmalloc-192.num_objs
13355 ± 4% -12.9% 11626 ± 3% slabinfo.kmalloc-512.active_objs
13462 ± 3% -12.2% 11817 ± 4% slabinfo.kmalloc-512.num_objs
10738466 ± 72% +144.2% 26226029 ±152% 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
13687263 ± 73% +91.6% 26226029 ±152% 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
268.25 ± 22% +3e+06% 8101959 ±173% 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
14282625 ± 75% +83.6% 26226029 ±152% 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
1758 ± 29% +4.6e+05% 8103711 ±173% 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
3871 ± 4% -15.4% 3276 ± 6% numa-vmstat.node0.nr_slab_reclaimable
149717 ± 3% -9.0% 136293 ± 2% numa-vmstat.node2.numa_local
2065 ± 28% +63.8% 3382 ± 28% numa-vmstat.node3.nr_active_anon
650.00 ±127% +240.7% 2214 ± 1% numa-vmstat.node3.nr_inactive_anon
1252 ± 50% +89.1% 2367 ± 0% numa-vmstat.node3.nr_mapped
672.75 ±123% +323.9% 2852 ± 37% numa-vmstat.node3.nr_shmem
35218 ± 14% -14.9% 29979 ± 3% numa-meminfo.node0.Active
1017 ± 11% -21.8% 795.50 ± 9% numa-meminfo.node0.PageTables
15486 ± 4% -15.4% 13105 ± 6% numa-meminfo.node0.SReclaimable
2069 ± 5% +12.4% 2325 ± 8% numa-meminfo.node2.KernelStack
8262 ± 28% +63.8% 13530 ± 28% numa-meminfo.node3.Active(anon)
2601 ±127% +240.5% 8858 ± 1% numa-meminfo.node3.Inactive(anon)
5011 ± 50% +89.0% 9472 ± 0% numa-meminfo.node3.Mapped
2692 ±122% +323.8% 11410 ± 37% numa-meminfo.node3.Shmem
3.75 ± 47% -100.0% 0.00 ± 0% sched_debug.cfs_rq[15]:/.nr_spread_over
197.50 ± 10% -13.9% 170.00 ± 1% sched_debug.cfs_rq[20]:/.util_avg
2.00 ± 35% -83.3% 0.33 ±141% sched_debug.cfs_rq[47]:/.nr_spread_over
8.50 ± 17% +23.5% 10.50 ± 4% sched_debug.cfs_rq[59]:/.load_avg
8.50 ± 17% +23.5% 10.50 ± 4% sched_debug.cfs_rq[59]:/.tg_load_avg_contrib
423.25 ± 17% +25.4% 530.75 ± 8% sched_debug.cfs_rq[59]:/.util_avg
1.00 ±-100% +150.0% 2.50 ± 44% sched_debug.cfs_rq[8]:/.nr_spread_over
2556 ± 13% -22.6% 1979 ± 13% sched_debug.cpu#0.sched_goidle
1581 ± 27% +93.8% 3064 ± 24% sched_debug.cpu#12.sched_goidle
-2.67 ±-98% +143.8% -6.50 ±-44% sched_debug.cpu#2.nr_uninterruptible
576.25 ± 26% +397.3% 2865 ± 75% sched_debug.cpu#22.ttwu_local
4410 ± 9% +68.0% 7411 ± 23% sched_debug.cpu#26.sched_goidle
3509 ± 32% -52.4% 1670 ± 12% sched_debug.cpu#27.curr->pid
-2.00 ±-106% -175.0% 1.50 ±100% sched_debug.cpu#27.nr_uninterruptible
4794 ± 35% -36.9% 3024 ± 8% sched_debug.cpu#28.ttwu_count
0.25 ±1307% -2400.0% -5.75 ±-60% sched_debug.cpu#31.nr_uninterruptible
6465 ± 20% -41.9% 3755 ± 27% sched_debug.cpu#31.sched_goidle
64512 ± 24% -70.5% 19049 ±165% sched_debug.cpu#32.nr_switches
65052 ± 21% -98.8% 804.50 ± 21% sched_debug.cpu#34.nr_switches
1403 ± 30% -75.8% 339.25 ± 33% sched_debug.cpu#35.ttwu_count
608.25 ± 88% -69.5% 185.25 ± 48% sched_debug.cpu#35.ttwu_local
1034 ± 58% -68.8% 322.50 ± 25% sched_debug.cpu#39.ttwu_count
130116 ± 14% -36.5% 82684 ± 33% sched_debug.cpu#4.nr_switches
46901 ± 62% -89.3% 5030 ±124% sched_debug.cpu#40.nr_switches
37807 ± 91% -96.8% 1206 ± 9% sched_debug.cpu#41.nr_switches
893.00 ± 53% -46.3% 479.50 ± 11% sched_debug.cpu#41.sched_goidle
74766 ± 56% -74.7% 18921 ±161% sched_debug.cpu#42.nr_switches
92018 ± 22% -98.4% 1462 ± 18% sched_debug.cpu#43.nr_switches
452.50 ± 28% -34.6% 295.75 ± 18% sched_debug.cpu#43.ttwu_local
74394 ± 37% -96.7% 2453 ± 76% sched_debug.cpu#45.nr_switches
75791 ± 4% -97.5% 1887 ± 56% sched_debug.cpu#46.nr_switches
75463 ± 3% -97.5% 1882 ± 62% sched_debug.cpu#47.nr_switches
42098 ± 12% -67.9% 13516 ±119% sched_debug.cpu#49.nr_switches
468.50 ± 9% +44.0% 674.50 ± 27% sched_debug.cpu#54.sched_goidle
3.75 ± 68% -106.7% -0.25 ±-591% sched_debug.cpu#56.nr_uninterruptible
brickland1: Brickland Ivy Bridge-EX
Memory: 128G
vm-intel12-yocto-x86_64: qemu-system-x86_64 -enable-kvm -cpu Nehalem
Memory: 320M
vm-vp-quantal-x86_64: qemu-system-x86_64 -enable-kvm
Memory: 360M
nhm4: Nehalem
Memory: 4G
ivb42: Ivytown Ivy Bridge-EP
Memory: 64G
nhm-white2: Nehalem
Memory: 4G
nhm-white: Nehalem
Memory: 6G
ivb43: Ivytown Ivy Bridge-EP
Memory: 64G
lkp-sbx04: Sandy Bridge-EX
Memory: 64G
will-it-scale.per_thread_ops
6.1e+06 ++------------------------------------------------*--------------+
| : *..*..*..*..*
6.05e+06 ++ : |
| : |
| : |
6e+06 ++ O O *..* |
*..*.. .*.. .*... .*..*..*.. .. |
5.95e+06 ++ *. *. *..*. *..*..* |
| O O |
5.9e+06 ++ O O O O O O |
O O O O O O |
| |
5.85e+06 ++ O O |
| |
5.8e+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,
Ying Huang
5 years, 11 months
[lkp] [Staging] ebd43516d3: BUG: scheduling while atomic: swapper/1/0x00000002
by kernel test robot
FYI, we noticed the below changes on
https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git master
commit ebd43516d3879f882a403836bba8bc5791f26a28 ("Staging: panel: usleep_range is preferred over udelay")
+---------------------------------------+------------+------------+
| | 46f566ed53 | ebd43516d3 |
+---------------------------------------+------------+------------+
| boot_successes | 0 | 0 |
| boot_failures | 21 | 21 |
| page_allocation_failure:order:#,mode | 21 | |
| warn_alloc_failed+0x | 21 | |
| Mem-Info | 19 | |
| backtrace:ring_buffer_consumer_thread | 21 | |
| BUG:scheduling_while_atomic | 0 | 21 |
| backtrace:lcd_write_cmd_p8 | 0 | 21 |
| backtrace:lcd_init_display | 0 | 21 |
| backtrace:__parport_register_driver | 0 | 21 |
| backtrace:panel_init_module | 0 | 21 |
| backtrace:kernel_init_freeable | 0 | 21 |
| backtrace:lcd_clear_display | 0 | 17 |
+---------------------------------------+------------+------------+
[ 17.089664] usbcore: registered new interface driver usbhid
[ 17.091149] usbhid: USB HID core driver
[ 17.091149] usbhid: USB HID core driver
[ 17.112294] BUG: scheduling while atomic: swapper/1/0x00000002
[ 17.112294] BUG: scheduling while atomic: swapper/1/0x00000002
[ 17.113858] 2 locks held by swapper/1:
[ 17.113858] 2 locks held by swapper/1:
[ 17.114845] #0:
[ 17.114845] #0: ( (registration_lockregistration_lock){+.+.+.}){+.+.+.}, at: , at: [<47696184>] __parport_register_driver+0xc4/0xf0
[<47696184>] __parport_register_driver+0xc4/0xf0
[ 17.116024] #1:
[ 17.116024] #1: ( (pprt_lockpprt_lock){......}){......}, at: , at: [<47cceb45>] lcd_write_cmd_p8+0x15/0x90
[<47cceb45>] lcd_write_cmd_p8+0x15/0x90
[ 17.116024] irq event stamp: 5212020
[ 17.116024] irq event stamp: 5212020
[ 17.116024] hardirqs last enabled at (5212019):
[ 17.116024] hardirqs last enabled at (5212019): [<47e5c495>] _raw_spin_unlock_irqrestore+0x65/0x80
[<47e5c495>] _raw_spin_unlock_irqrestore+0x65/0x80
[ 17.116024] hardirqs last disabled at (5212020):
[ 17.116024] hardirqs last disabled at (5212020): [<47e5c07a>] _raw_spin_lock_irq+0x1a/0x80
[<47e5c07a>] _raw_spin_lock_irq+0x1a/0x80
[ 17.116024] softirqs last enabled at (5206816):
[ 17.116024] softirqs last enabled at (5206816): [<46e51833>] __do_softirq+0x393/0x460
[<46e51833>] __do_softirq+0x393/0x460
[ 17.116024] softirqs last disabled at (5206811):
[ 17.116024] softirqs last disabled at (5206811): [<46e04d78>] do_softirq_own_stack+0x38/0x50
[<46e04d78>] do_softirq_own_stack+0x38/0x50
[ 17.116024] Preemption disabled at:
[ 17.116024] Preemption disabled at:[<47cceb45>] lcd_write_cmd_p8+0x15/0x90
[<47cceb45>] lcd_write_cmd_p8+0x15/0x90
[ 17.116024]
[ 17.116024]
[ 17.116024] CPU: 0 PID: 1 Comm: swapper Not tainted 4.3.0-rc7-02299-gebd4351 #1
[ 17.116024] CPU: 0 PID: 1 Comm: swapper Not tainted 4.3.0-rc7-02299-gebd4351 #1
[ 17.116024] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 17.116024] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 17.116024] 40292040
[ 17.116024] 40292040 40292040 40292040 40295d98 40295d98 472d8c3d 472d8c3d 40295db4 40295db4 46e71960 46e71960 4835c6ee 4835c6ee 47cceb45 47cceb45
[ 17.116024] 47cceb45
[ 17.116024] 47cceb45 00000002 00000002 00000000 00000000 40295df0 40295df0 47e56a06 47e56a06 47e5c478 47e5c478 00000000 00000000 40295e1c 40295e1c
[ 17.116024] 40295dfc
[ 17.116024] 40295dfc 46eb374e 46eb374e 00013880 00013880 00000000 00000000 46e7549c 46e7549c 00000000 00000000 40292040 40292040 40296000 40296000
[ 17.116024] Call Trace:
[ 17.116024] Call Trace:
[ 17.116024] [<472d8c3d>] dump_stack+0x16/0x19
[ 17.116024] [<472d8c3d>] dump_stack+0x16/0x19
[ 17.116024] [<46e71960>] __schedule_bug+0x70/0xc0
[ 17.116024] [<46e71960>] __schedule_bug+0x70/0xc0
[ 17.116024] [<47cceb45>] ? lcd_write_cmd_p8+0x15/0x90
[ 17.116024] [<47cceb45>] ? lcd_write_cmd_p8+0x15/0x90
[ 17.116024] [<47cceb45>] ? lcd_write_cmd_p8+0x15/0x90
[ 17.116024] [<47cceb45>] ? lcd_write_cmd_p8+0x15/0x90
[ 17.116024] [<47e56a06>] __schedule+0x876/0xd20
[ 17.116024] [<47e56a06>] __schedule+0x876/0xd20
[ 17.116024] [<47e5c478>] ? _raw_spin_unlock_irqrestore+0x48/0x80
[ 17.116024] [<47e5c478>] ? _raw_spin_unlock_irqrestore+0x48/0x80
[ 17.116024] [<46eb374e>] ? hrtimer_start_range_ns+0xae/0x280
[ 17.116024] [<46eb374e>] ? hrtimer_start_range_ns+0xae/0x280
[ 17.116024] [<46e7549c>] ? preempt_count_add+0xc/0xd0
[ 17.116024] [<46e7549c>] ? preempt_count_add+0xc/0xd0
[ 17.116024] [<47e56f13>] schedule+0x33/0x90
[ 17.116024] [<47e56f13>] schedule+0x33/0x90
[ 17.116024] [<47e5b61f>] schedule_hrtimeout_range_clock+0xdf/0x180
[ 17.116024] [<47e5b61f>] schedule_hrtimeout_range_clock+0xdf/0x180
[ 17.116024] [<46eb2f70>] ? enqueue_hrtimer+0x130/0x130
[ 17.116024] [<46eb2f70>] ? enqueue_hrtimer+0x130/0x130
[ 17.116024] [<47e5b6d7>] schedule_hrtimeout_range+0x17/0x20
[ 17.116024] [<47e5b6d7>] schedule_hrtimeout_range+0x17/0x20
[ 17.116024] [<47e5b386>] usleep_range+0x46/0x50
[ 17.116024] [<47e5b386>] usleep_range+0x46/0x50
[ 17.116024] [<47cceb67>] lcd_write_cmd_p8+0x37/0x90
[ 17.116024] [<47cceb67>] lcd_write_cmd_p8+0x37/0x90
[ 17.116024] [<47ccef26>] lcd_init_display+0x36/0x110
[ 17.116024] [<47ccef26>] lcd_init_display+0x36/0x110
[ 17.116024] [<47cd0031>] panel_attach+0x701/0xb30
[ 17.116024] [<47cd0031>] panel_attach+0x701/0xb30
[ 17.116024] [<47696000>] ? detach_driver_chain+0x50/0x50
[ 17.116024] [<47696000>] ? detach_driver_chain+0x50/0x50
[ 17.116024] [<4769601e>] port_check+0x1e/0x30
[ 17.116024] [<4769601e>] port_check+0x1e/0x30
[ 17.116024] [<476a251f>] bus_for_each_dev+0x4f/0x80
[ 17.116024] [<476a251f>] bus_for_each_dev+0x4f/0x80
[ 17.116024] [<476961a0>] __parport_register_driver+0xe0/0xf0
[ 17.116024] [<476961a0>] __parport_register_driver+0xe0/0xf0
[ 17.116024] [<47696000>] ? detach_driver_chain+0x50/0x50
[ 17.116024] [<47696000>] ? detach_driver_chain+0x50/0x50
[ 17.116024] [<4877aadc>] ? staging_init+0x7/0x7
[ 17.116024] [<4877aadc>] ? staging_init+0x7/0x7
[ 17.116024] [<4877acc8>] panel_init_module+0x1ec/0x238
[ 17.116024] [<4877acc8>] panel_init_module+0x1ec/0x238
[ 17.116024] [<48726bd5>] do_one_initcall+0xce/0x14c
[ 17.116024] [<48726bd5>] do_one_initcall+0xce/0x14c
[ 17.116024] [<46e6bf6f>] ? parse_args+0x23f/0x3f0
[ 17.116024] [<46e6bf6f>] ? parse_args+0x23f/0x3f0
[ 17.116024] [<47e5d000>] ? syscall_call+0x6/0x7
[ 17.116024] [<47e5d000>] ? syscall_call+0x6/0x7
[ 17.116024] [<48726d11>] ? kernel_init_freeable+0xbe/0x15b
[ 17.116024] [<48726d11>] ? kernel_init_freeable+0xbe/0x15b
[ 17.116024] [<48726d31>] kernel_init_freeable+0xde/0x15b
[ 17.116024] [<48726d31>] kernel_init_freeable+0xde/0x15b
[ 17.116024] [<47e54de0>] kernel_init+0x10/0xe0
[ 17.116024] [<47e54de0>] kernel_init+0x10/0xe0
[ 17.116024] [<46e759f5>] ? schedule_tail+0x25/0x80
[ 17.116024] [<46e759f5>] ? schedule_tail+0x25/0x80
[ 17.116024] [<47e5ce60>] ret_from_kernel_thread+0x20/0x30
[ 17.116024] [<47e5ce60>] ret_from_kernel_thread+0x20/0x30
[ 17.116024] [<47e54dd0>] ? rest_init+0x120/0x120
[ 17.116024] [<47e54dd0>] ? rest_init+0x120/0x120
[ 17.266557] BUG: scheduling while atomic: swapper/1/0x00000002
[ 17.266557] BUG: scheduling while atomic: swapper/1/0x00000002
[ 17.268236] 2 locks held by swapper/1:
[ 17.268236] 2 locks held by swapper/1:
[ 17.269302] #0:
[ 17.269302] #0: ( (registration_lockregistration_lock){+.+.+.}){+.+.+.}, at: , at: [<47696184>] __parport_register_driver+0xc4/0xf0
[<47696184>] __parport_register_driver+0xc4/0xf0
[ 17.271945] #1:
[ 17.271945] #1: ( (pprt_lockpprt_lock){+.+...}){+.+...}, at: , at: [<47cceb45>] lcd_write_cmd_p8+0x15/0x90
[<47cceb45>] lcd_write_cmd_p8+0x15/0x90
[ 17.274198] Preemption disabled at:
[ 17.274198] Preemption disabled at:[<47cceb45>] lcd_write_cmd_p8+0x15/0x90
[<47cceb45>] lcd_write_cmd_p8+0x15/0x90
[ 17.294247]
[ 17.294247]
[ 17.294681] CPU: 0 PID: 1 Comm: swapper Tainted: G W 4.3.0-rc7-02299-gebd4351 #1
[ 17.294681] CPU: 0 PID: 1 Comm: swapper Tainted: G W 4.3.0-rc7-02299-gebd4351 #1
[ 17.297108] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 17.297108] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 17.299593] 40292040
[ 17.299593] 40292040 40292040 40292040 40295d98 40295d98 472d8c3d 472d8c3d 40295db4 40295db4 46e71960 46e71960 4835c6ee 4835c6ee 47cceb45 47cceb45
[ 17.320046] 47cceb45
[ 17.320046] 47cceb45 00000002 00000002 00000000 00000000 40295df0 40295df0 47e56a06 47e56a06 47e5c478 47e5c478 00000000 00000000 40295e1c 40295e1c
[ 17.322382] 40295dfc
[ 17.322382] 40295dfc 46eb374e 46eb374e 0000ea60 0000ea60 00000000 00000000 46e7549c 46e7549c 00000000 00000000 40292040 40292040 40296000 40296000
[ 17.324736] Call Trace:
[ 17.324736] Call Trace:
[ 17.325444] [<472d8c3d>] dump_stack+0x16/0x19
[ 17.325444] [<472d8c3d>] dump_stack+0x16/0x19
[ 17.326703] [<46e71960>] __schedule_bug+0x70/0xc0
[ 17.326703] [<46e71960>] __schedule_bug+0x70/0xc0
[ 17.328081] [<47cceb45>] ? lcd_write_cmd_p8+0x15/0x90
[ 17.328081] [<47cceb45>] ? lcd_write_cmd_p8+0x15/0x90
[ 17.347621] [<47cceb45>] ? lcd_write_cmd_p8+0x15/0x90
[ 17.347621] [<47cceb45>] ? lcd_write_cmd_p8+0x15/0x90
[ 17.349107] [<47e56a06>] __schedule+0x876/0xd20
[ 17.349107] [<47e56a06>] __schedule+0x876/0xd20
[ 17.350412] [<47e5c478>] ? _raw_spin_unlock_irqrestore+0x48/0x80
[ 17.350412] [<47e5c478>] ? _raw_spin_unlock_irqrestore+0x48/0x80
[ 17.352162] [<46eb374e>] ? hrtimer_start_range_ns+0xae/0x280
[ 17.352162] [<46eb374e>] ? hrtimer_start_range_ns+0xae/0x280
[ 17.353788] [<46e7549c>] ? preempt_count_add+0xc/0xd0
[ 17.353788] [<46e7549c>] ? preempt_count_add+0xc/0xd0
[ 17.355205] [<47e56f13>] schedule+0x33/0x90
[ 17.355205] [<47e56f13>] schedule+0x33/0x90
[ 17.356340] [<47e5b61f>] schedule_hrtimeout_range_clock+0xdf/0x180
[ 17.356340] [<47e5b61f>] schedule_hrtimeout_range_clock+0xdf/0x180
[ 17.357868] [<46eb2f70>] ? enqueue_hrtimer+0x130/0x130
[ 17.357868] [<46eb2f70>] ? enqueue_hrtimer+0x130/0x130
[ 17.359126] [<47e5b6d7>] schedule_hrtimeout_range+0x17/0x20
[ 17.359126] [<47e5b6d7>] schedule_hrtimeout_range+0x17/0x20
[ 17.378704] [<47e5b386>] usleep_range+0x46/0x50
[ 17.378704] [<47e5b386>] usleep_range+0x46/0x50
[ 17.380033] [<47cceb90>] lcd_write_cmd_p8+0x60/0x90
[ 17.380033] [<47cceb90>] lcd_write_cmd_p8+0x60/0x90
[ 17.381348] [<47ccef26>] lcd_init_display+0x36/0x110
[ 17.381348] [<47ccef26>] lcd_init_display+0x36/0x110
[ 17.382563] [<47cd0031>] panel_attach+0x701/0xb30
[ 17.382563] [<47cd0031>] panel_attach+0x701/0xb30
[ 17.383734] [<47696000>] ? detach_driver_chain+0x50/0x50
[ 17.383734] [<47696000>] ? detach_driver_chain+0x50/0x50
[ 17.385072] [<4769601e>] port_check+0x1e/0x30
[ 17.385072] [<4769601e>] port_check+0x1e/0x30
[ 17.386153] [<476a251f>] bus_for_each_dev+0x4f/0x80
[ 17.386153] [<476a251f>] bus_for_each_dev+0x4f/0x80
[ 17.405529] [<476961a0>] __parport_register_driver+0xe0/0xf0
[ 17.405529] [<476961a0>] __parport_register_driver+0xe0/0xf0
[ 17.407111] [<47696000>] ? detach_driver_chain+0x50/0x50
[ 17.407111] [<47696000>] ? detach_driver_chain+0x50/0x50
[ 17.408437] [<4877aadc>] ? staging_init+0x7/0x7
[ 17.408437] [<4877aadc>] ? staging_init+0x7/0x7
[ 17.409553] [<4877acc8>] panel_init_module+0x1ec/0x238
[ 17.409553] [<4877acc8>] panel_init_module+0x1ec/0x238
[ 17.410823] [<48726bd5>] do_one_initcall+0xce/0x14c
[ 17.410823] [<48726bd5>] do_one_initcall+0xce/0x14c
[ 17.412051] [<46e6bf6f>] ? parse_args+0x23f/0x3f0
[ 17.412051] [<46e6bf6f>] ? parse_args+0x23f/0x3f0
[ 17.413218] [<47e5d000>] ? syscall_call+0x6/0x7
[ 17.413218] [<47e5d000>] ? syscall_call+0x6/0x7
[ 17.436422] [<48726d11>] ? kernel_init_freeable+0xbe/0x15b
[ 17.436422] [<48726d11>] ? kernel_init_freeable+0xbe/0x15b
[ 17.437796] [<48726d31>] kernel_init_freeable+0xde/0x15b
[ 17.437796] [<48726d31>] kernel_init_freeable+0xde/0x15b
[ 17.439112] [<47e54de0>] kernel_init+0x10/0xe0
[ 17.439112] [<47e54de0>] kernel_init+0x10/0xe0
[ 17.440252] [<46e759f5>] ? schedule_tail+0x25/0x80
[ 17.440252] [<46e759f5>] ? schedule_tail+0x25/0x80
Thanks,
Ying Huang
5 years, 11 months