[sched] 51785b72f8: kernel BUG at kernel/smpboot.c:127!
by kernel test robot
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
git://internal_mailing_list_patch_tree Oleg-Nesterov/Was-sched-start-stopper-early
commit 51785b72f86aa310dc063fda129e1ef716a62599
Author: Oleg Nesterov <oleg(a)redhat.com>
AuthorDate: Sat Oct 10 20:53:09 2015 +0200
Commit: 0day robot <fengguang.wu(a)intel.com>
CommitDate: Sun Oct 11 08:04:28 2015 +0800
sched: select_task_rq() should check cpu_active() like select_fallback_rq()
I do not understand the cpu_active() check in select_fallback_rq().
x86 doesn't need it, and the recent commit dd9d3843755d "sched: Fix
cpu_active_mask/cpu_online_mask race" documents the fact that on any
architecture we can ignore !active starting from CPU_ONLINE stage.
But any possible reason why do we need this check in "fallback" must
equally apply to select_task_rq().
Signed-off-by: Oleg Nesterov <oleg(a)redhat.com>
+------------------------------------------+------------+------------+------------+
| | 5a4fd03685 | 51785b72f8 | 421dfb8624 |
+------------------------------------------+------------+------------+------------+
| boot_successes | 758 | 53 | 5 |
| boot_failures | 0 | 207 | 8 |
| kernel_BUG_at_kernel/smpboot.c | 0 | 207 | 8 |
| invalid_opcode:#[##]SMP | 0 | 207 | 8 |
| RIP:smpboot_thread_fn | 0 | 207 | 8 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 207 | 8 |
+------------------------------------------+------------+------------+------------+
[ 5.275234] BIOS EDD facility v0.16 2004-Jun-25, 6 devices found
[ 5.276429] Unregister pv shared memory for cpu 0
[ 5.292696] ------------[ cut here ]------------
[ 5.293331] kernel BUG at kernel/smpboot.c:127!
[ 5.294172] invalid opcode: 0000 [#1] SMP
[ 5.294747] Modules linked in:
[ 5.295183] CPU: 1 PID: 10 Comm: watchdog/0 Not tainted 4.3.0-rc4-00053-g51785b7 #2
[ 5.296182] task: ffff8800100a2480 ti: ffff8800100b0000 task.ti: ffff8800100b0000
[ 5.297180] RIP: 0010:[<ffffffff810b269c>] [<ffffffff810b269c>] smpboot_thread_fn+0x30c/0x370
[ 5.298319] RSP: 0000:ffff8800100b3e48 EFLAGS: 00010202
[ 5.299008] RAX: ffffffff81141ce0 RBX: ffff8800100a2480 RCX: 0000000000000000
[ 5.299933] RDX: 0000000000000001 RSI: 0000000000000000 RDI: 0000000000000000
[ 5.300887] RBP: ffff8800100b3e68 R08: 0000000000275b48 R09: 0000000000000000
[ 5.301823] R10: 0000000000000001 R11: 0000000000000000 R12: ffff880010002110
[ 5.302759] R13: ffffffff81ca17e0 R14: 0000000000000000 R15: 0000000000000000
[ 5.303692] FS: 0000000000000000(0000) GS:ffff880011300000(0000) knlGS:0000000000000000
[ 5.304764] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b
[ 5.318539] CR2: 0000000000000000 CR3: 0000000001c08000 CR4: 00000000000006e0
[ 5.319480] Stack:
[ 5.319757] 0000000000000000 ffff88001000ca80 ffff880010002110 ffffffff810b2390
[ 5.320813] ffff8800100b3f48 ffffffff810acf3a 0000000000000005 ffff880000000000
[ 5.321847] ffff880010002110 0000000000000000 dead4ead00000000 ffff8800ffffffff
[ 5.322880] Call Trace:
[ 5.323223] [<ffffffff810b2390>] ? sort_range+0x30/0x30
[ 5.323917] [<ffffffff810acf3a>] kthread+0x12a/0x160
[ 5.324606] [<ffffffff810ace10>] ? kthread_stop+0xd0/0xd0
[ 5.325335] [<ffffffff8179448f>] ret_from_fork+0x3f/0x70
[ 5.326040] [<ffffffff810ace10>] ? kthread_stop+0xd0/0xd0
[ 5.326764] Code: 05 39 4e f6 00 01 4c 89 e7 e8 31 a8 11 00 48 83 05 31 4e f6 00 01 48 83 c4 08 31 c0 5b 41 5c 41 5d 5d c3 48 83 05 3c 4e f6 00 01 <0f> 0b 48 83 05 42 4e f6 00 01 48 83 05 da 4d f6 00 01 80 3d ee
[ 5.330389] RIP [<ffffffff810b269c>] smpboot_thread_fn+0x30c/0x370
[ 5.334970] RSP <ffff8800100b3e48>
[ 5.335461] ---[ end trace a6811c21cba05161 ]---
[ 5.336069] Kernel panic - not syncing: Fatal exception
git bisect start 421dfb8624fd4fa3aa68725eb7bed13a971599b7 049e6dde7e57f0054fdc49102e7ef4830c698b46 --
git bisect bad 5afe5921940b84b7b7fc5873ade89e079d06bc35 # 13:35 0- 5 Merge 'linux-review/Laurent-Vivier/nouveau-fix-OpenFirmware-support' into devel-spot-201510110959
git bisect good 5f0e2842e854790b1811db629ead5a21aa2338e6 # 13:37 67+ 0 Merge 'linux-review/Yuval-Mintz/bnx2x-Prevent-UDP-4-tuple-configurations-on-older-adapters' into devel-spot-201510110959
git bisect good 61e910c0eb4571d11fcae6972fe67165783b11d6 # 13:39 63+ 0 Merge 'linux-review/Richard-Sailer/tcp-change-type-of-alive-from-int-to-bool' into devel-spot-201510110959
git bisect bad 56b80aa13f22eadacd9147a9922245774a505720 # 13:39 0- 9 Merge 'linux-review/Matt-Fleming/MAINTAINERS-Replace-Intel-email-address' into devel-spot-201510110959
git bisect good 46345ed3da887415893ef989f792ddf0a7e654b3 # 13:43 67+ 0 Merge 'linux-review/Parshuram-Thombare/staging-dgnc-simplify-a-trivial-if-return-sequence' into devel-spot-201510110959
git bisect bad 8cffd5b9d667055f3a3e667bd9195abb1749772e # 13:44 23- 1 Merge 'linux-review/Nikolay-Borisov/ext4-Fix-possible-deadlock-with-local-interrupts-disabled-and-page-draining-IPI' into devel-spot-201510110959
git bisect bad 952fa6a5a029c41741803487c325bbfcf5a6495c # 13:48 0- 8 Merge 'linux-review/Oleg-Nesterov/Was-sched-start-stopper-early' into devel-spot-201510110959
git bisect good 20150de6a633453d00a15df98294e11af8dabc35 # 14:02 259+ 0 Merge 'linux-review/Oleksij-Rempel/Add-support-for-ASM9260-interrupt-controller' into devel-spot-201510110959
git bisect bad 6eb69fbc5d2d1b4dd9ac8e6c8114495fb45425ce # 14:06 0- 7 sched: change select_fallback_rq() to use for_each_cpu_and()
git bisect bad 51785b72f86aa310dc063fda129e1ef716a62599 # 14:10 0- 12 sched: select_task_rq() should check cpu_active() like select_fallback_rq()
# first bad commit: [51785b72f86aa310dc063fda129e1ef716a62599] sched: select_task_rq() should check cpu_active() like select_fallback_rq()
git bisect good 5a4fd0368517bc5b5399ef958f6d30cbff492918 # 19:24 758+ 0 sched/core: Remove a parameter in the migrate_task_rq() function
# extra tests with DEBUG_INFO
git bisect bad 51785b72f86aa310dc063fda129e1ef716a62599 # 19:32 0- 11 sched: select_task_rq() should check cpu_active() like select_fallback_rq()
# extra tests on HEAD of linux-devel/devel-spot-201510110959
git bisect bad 421dfb8624fd4fa3aa68725eb7bed13a971599b7 # 19:32 0- 8 0day head guard for 'devel-spot-201510110959'
# extra tests on tree/branch linux-review/Oleg-Nesterov/Was-sched-start-stopper-early
git bisect bad 67aa12d3297aecf1bab16b4f8962f0feb2077cc9 # 19:44 0- 28 sched: don't scan all-offline ->cpus_allowed twice if !CONFIG_CPUSETS
# extra tests on tree/branch linus/master
git bisect good 4a06c8ac2fb3ef484579ce44f9b809bd310fad48 # 22:12 760+ 0 Merge tag 'usb-4.3-rc5' of git://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb
# extra tests on tree/branch linux-next/master
git bisect good 565e6168f262bb2c6af89f9f8147b63d6f73e3cd # 22:51 760+ 0 Add linux-next specific files for 20151009
This script may reproduce the error.
----------------------------------------------------------------------------
#!/bin/bash
kernel=$1
initrd=quantal-core-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 kvm64
-kernel $kernel
-initrd $initrd
-m 300
-smp 2
-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, 3 months
[lkp] [drm/i915] 437b15b801: [drm:gen8_irq_handler [i915]] *ERROR* The master control interrupt lied (SDE)!
by kernel test robot
FYI, we noticed the below changes on
git://anongit.freedesktop.org/drm-intel for-linux-next
commit 437b15b8017e0d946453c10794b0c5d4591cf180 ("drm/i915: use pch backlight override on hsw too")
<4>[ 25.650730] ------------[ cut here ]------------
<4>[ 25.650752] WARNING: CPU: 2 PID: 80 at drivers/gpu/drm/i915/intel_display.c:9234 hsw_enable_pc8+0x60b/0x740 [i915]()
<4>[ 25.650753] CPU PWM1 enabled
<4>[ 25.650773] Modules linked in: rpcsec_gss_krb5 auth_rpcgss nfsv4 dns_resolver snd_hda_codec_hdmi x86_pkg_temp_thermal coretemp kvm_intel snd_hda_intel kvm crct10dif_pclmul snd_hda_codec snd_hda_core crc32_pclmul snd_hwdep crc32c_intel i915 ghash_clmulni_intel snd_pcm drm_kms_helper snd_timer syscopyarea aesni_intel sysfillrect sdhci_acpi sysimgblt lrw fb_sys_fops gf128mul glue_helper ablk_helper cryptd ppdev drm shpchp snd microcode serio_raw pcspkr soundcore i2c_i801 winbond_cir rc_core i2c_hid sdhci video dw_dmac dw_dmac_core mmc_core parport_pc parport i2c_designware_platform i2c_designware_core acpi_pad spi_pxa2xx_platform
<4>[ 25.650775] CPU: 2 PID: 80 Comm: kworker/u16:1 Not tainted 4.2.0-rc8-01085-g437b15b #1
<4>[ 25.650776] Hardware name: Intel Corporation Broadwell Client platform/WhiteTip Mountain 1, BIOS BDW-E1R1.86C.0120.R00.1504020241 04/02/2015
<4>[ 25.650779] Workqueue: events_unbound async_run_entry_fn
<4>[ 25.650781] ffffffffa0433070 ffff880032b63be8 ffffffff8189e2e9 ffffffff81cf4238
<4>[ 25.650783] ffff880032b63c38 ffff880032b63c28 ffffffff8107348a 00000000fffbca4d
<4>[ 25.650784] ffff88006bc10000 ffff88006d184b70 ffff88006d184b80 ffff88006d184800
<4>[ 25.650784] Call Trace:
<4>[ 25.650788] [<ffffffff8189e2e9>] dump_stack+0x4c/0x65
<4>[ 25.650791] [<ffffffff8107348a>] warn_slowpath_common+0x8a/0xc0
<4>[ 25.650792] [<ffffffff81073506>] warn_slowpath_fmt+0x46/0x50
<4>[ 25.650808] [<ffffffffa03d64eb>] hsw_enable_pc8+0x60b/0x740 [i915]
<4>[ 25.650814] [<ffffffffa035a3cb>] intel_suspend_complete+0x65b/0x6e0 [i915]
<4>[ 25.650819] [<ffffffffa035a472>] i915_drm_suspend_late+0x22/0x80 [i915]
<4>[ 25.650825] [<ffffffffa035a5c0>] ? i915_pm_poweroff_late+0x30/0x30 [i915]
<4>[ 25.650831] [<ffffffffa035a5e9>] i915_pm_suspend_late+0x29/0x30 [i915]
<4>[ 25.650833] [<ffffffff81551c1c>] dpm_run_callback+0x4c/0x120
<4>[ 25.650835] [<ffffffff815524c9>] __device_suspend_late+0xa9/0x180
<4>[ 25.650837] [<ffffffff815525bf>] async_suspend_late+0x1f/0xa0
<4>[ 25.650838] [<ffffffff810944ca>] async_run_entry_fn+0x4a/0x140
<4>[ 25.650841] [<ffffffff8108b6e7>] process_one_work+0x157/0x420
<4>[ 25.650843] [<ffffffff8108c1c9>] worker_thread+0x69/0x4a0
<4>[ 25.650844] [<ffffffff8108c160>] ? rescuer_thread+0x380/0x380
<4>[ 25.650846] [<ffffffff8108c160>] ? rescuer_thread+0x380/0x380
<4>[ 25.650847] [<ffffffff8109197f>] kthread+0xef/0x110
<4>[ 25.650849] [<ffffffff81091890>] ? kthread_create_on_node+0x180/0x180
<4>[ 25.650851] [<ffffffff818a621f>] ret_from_fork+0x3f/0x70
<4>[ 25.650852] [<ffffffff81091890>] ? kthread_create_on_node+0x180/0x180
<4>[ 25.650853] ---[ end trace 597b97aad370829b ]---
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, 3 months
[lkp] [dm ioctl] e817ef9b89: kernel BUG at mm/slub.c:3517!
by kernel test robot
FYI, we noticed the below changes on
https://git.kernel.org/pub/scm/linux/kernel/git/device-mapper/linux-dm.git dm-4.4
commit e817ef9b89e5bbfdb2a67ca9896909f414b2af91 ("dm ioctl: use kvfree() in free_params()")
+------------------------------------------+------------+------------+
| | 9e632c9dd3 | e817ef9b89 |
+------------------------------------------+------------+------------+
| boot_successes | 14 | 11 |
| boot_failures | 0 | 10 |
| kernel_BUG_at_mm/slub.c | 0 | 10 |
| invalid_opcode:#[##]SMP | 0 | 10 |
| RIP:#:[<#>]:#echo_perform[<#>]kfree | 0 | 2 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 9 |
| backtrace:do_vfs_ioctl | 0 | 3 |
| backtrace:SyS_ioctl | 0 | 10 |
| RIP:#:[<#>]rmance>/sys[<#>]kfree | 0 | 1 |
| RIP:kfree | 0 | 1 |
| RIP:#:[<#>]ufreq/scaling_go[<#>]kfree | 0 | 2 |
| RIP:#:[<#>]ance>/sys/devi[<#>]kfree | 0 | 1 |
| RIP:#:[<#>]req/scaling_gove[<#>]kfree | 0 | 1 |
| RIP:#:[<#>]sys/devices/syst[<#>]kfree | 0 | 1 |
+------------------------------------------+------------+------------+
[ 19.514000] ------------[ cut here ]------------
o performance >
[ 19.519783] kernel BUG at mm/slub.c:3517!
/sys/devices/sys
[ 19.527366] invalid opcode: 0000 [#1] SMP
tem/cpu/cpu15/cp
[ 19.533520] Modules linked in: dm_modufreq/scaling_go rpcsec_gss_krb5 auth_rpcgssvernor
2015-10- nfsv4 dns_resolver07 07:14:27 echo btrfs xor performance > / raid6_pq sr_mod cdrom sgsys/devices/syst sd_mod mgag200em/cpu/cpu2/cpuf ttm drm_kms_helperreq/scaling_gove syscopyarearn or
2015-10-07sysfillrect sysimgblt 07:14:27 echo p fb_sys_fops ahcierformance > /sy libahcis/ devices/systemcoretemp kvm_intel/cpu/cpu3/cpufre i7core_edac snd_pcmq/scaling_govern snd_timer drmor
2015-10-07 0 kvm snd7:14:27 echo per soundcore crc32c_intelformance > /sys/ ipmi_si libatadevices/system/c usb_storagepu /cpu4/cpufreq/edac_core microcodescaling_governor pcspkr shpchp
2015-10-07 07: ipmi_msghandler14:27 echo perfo acpi_cpufreqrmance > /sys/de
vices/system/cpu
[ 19.607527] CPU: 14 PID: 669 Comm: dmsetup Not tainted 4.3.0-rc3-00005-ge817ef9 #1
/cpu5/cpufreq/sc
[ 19.617528] Hardware name: Intel Corporation S5520UR/S5520UR, BIOS S5500.86B.01.00.0050.050620101605 05/06/2010
aling_governor
[ 19.630345] task: ffff880356122400 ti: ffff8801dc96c000 task.ti: ffff8801dc96c000
2015-10-07 07:14
[ 19.640252] RIP: 0010:[<ffffffff811c08fe>] :27 echo perform [<ffffffff811c08fe>] kfree+0x12e/0x130
ance > /sys/devi
[ 19.651820] RSP: 0018:ffff8801dc96fcc8 EFLAGS: 00010246
ces/system/cpu/c
[ 19.659301] RAX: 002fffff80000000 RBX: ffff8801dc96fd28 RCX: 0000000000000131
pu6/cpufreq/scal
[ 19.668818] RDX: 000077ff80000000 RSI: ffff8801dc96fd28 RDI: ffff8801dc96fd28
ing_governor
20
[ 19.678335] RBP: ffff8801dc96fce0 R08: 0000000000000004 R09: ffffea0007725bc0
15-10-07 07:14:2
[ 19.687854] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000
7 echo performan
[ 19.697372] R13: ffffffff8118307a R14: 0000000000000000 R15: 0000000000000000
ce > /sys/device
[ 19.706890] FS: 00007f5704b1f800(0000) GS:ffff8801e9bc0000(0000) knlGS:0000000000000000
s/system/cpu/cpu
[ 19.717475] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
7/cpufreq/scalin
[ 19.725442] CR2: 00007f57047002dc CR3: 00000001dc4bc000 CR4: 00000000000006e0
g_governor
2015
[ 19.734959] Stack:
-10-07 07:14:27
[ 19.738757] 00005634dcf70120 0000000000000000echo performance 0000000000000000 ffff8801dc96fcf0 > /sys/devices/
system/cpu/cpu8/
[ 19.751708] ffffffff8118307a ffff8801dc96fe88cpufreq/scaling_ ffffffffa02cd0b8 ffff880356122400governor
2015-1
0-[07 07:14:27 ec 19.764649] ffff8801effdbb08 ffff880356122400ho performance > 0000000000000131 0000000000000131 /sys/devices/sy
stem/cpu/cpu9/cp
[ 19.777593] Call Trace:
ufreq/scaling_go
[ 19.781878] [<ffffffff8118307a>] kvfree+0x2a/0x40
vernor
[ 19.788779] [<ffffffffa02cd0b8>] ctl_ioctl+0x238/0x550 [dm_mod]
[ 19.796259] [<ffffffffa02cd3e3>] dm_ctl_ioctl+0x13/0x20 [dm_mod]
[ 19.803641] [<ffffffff811f3a61>] do_vfs_ioctl+0x301/0x560
[ 19.810321] [<ffffffff8139f0e0>] ? selinux_file_ioctl+0x50/0xd0
[ 19.817570] [<ffffffff81393943>] ? security_file_ioctl+0x43/0x60
[ 19.824926] [<ffffffff811f3d39>] SyS_ioctl+0x79/0x90
[ 19.831119] [<ffffffff818addae>] entry_SYSCALL_64_fastpath+0x12/0x71
[ 19.838876] Code: c4 40 74 04 41 8b 71 68 4c 89 cf e8 3d cc fa ff eb 9b 4d 8b 49 30 e9 44 ff ff ff 4c 89 d1 48 89 da 4c 89 ce e8 74 fa ff ff eb 82 <0f> 0b 66 66 66 66 90 55 48 89 e5 41 57 41 56 41 55 41 54 53 48
[ 19.861810] RIP [<ffffffff811c08fe>] kfree+0x12e/0x130
[ 19.868281] RSP <ffff8801dc96fcc8>
[ 19.872825] ---[ end trace f5be9e866bcf705a ]---
[ 19.880928] Kernel panic - not syncing: Fatal exception
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, 3 months
[lkp] [tcp] ca6fb06518: general protection fault: 0000 [#1] SMP
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 ca6fb06518836ef9b65dc0aac02ff97704d52a05 ("tcp: attach SYNACK messages to request sockets instead of listener")
+----------------------------------------------------------------+------------+------------+
| | 1b33bc3e9e | ca6fb06518 |
+----------------------------------------------------------------+------------+------------+
| boot_successes | 36 | 11 |
| boot_failures | 0 | 47 |
| general_protection_fault:#[##]SMP | 0 | 17 |
| RIP:selinux_ip_postroute_compat | 0 | 47 |
| Kernel_panic-not_syncing:Fatal_exception_in_interrupt | 0 | 42 |
| backtrace:__net_random_once_deferred | 0 | 3 |
| BUG:unable_to_handle_kernel | 0 | 30 |
| Oops | 0 | 30 |
| backtrace:cpu_startup_entry | 0 | 25 |
| backtrace:do_mount | 0 | 17 |
| backtrace:SyS_mount | 0 | 17 |
| backtrace:rpc_clone_client_set_auth | 0 | 9 |
| backtrace:nfs_init_server_rpcclient | 0 | 9 |
| backtrace:smpboot_thread_fn | 0 | 3 |
| backtrace:nfs4_discover_server_trunking | 0 | 2 |
| backtrace:nfs4_init_client | 0 | 2 |
| WARNING:at_arch/x86/kernel/smp.c:#native_smp_send_reschedule() | 0 | 1 |
| backtrace:security_inode_alloc | 0 | 2 |
| backtrace:inode_init_always | 0 | 2 |
+----------------------------------------------------------------+------------+------------+
led/lkp-ne04/bis
[ 28.307785] Key type id_legacy registered
ect_fsmark-performance-1x-32t-1HDD-btrfs-5K-400M-fsyncBeforeClose-16d-256fpd-debian-x86_64-2015-02-07.cgz-x86_64-rhel-ca6fb06518
[ 28.326509] general protection fault: 0000 [#1] SMP
[ 28.332201] Modules linked in: rpcsec_gss_krb5 auth_rpcgss nfsv4 dns_resolver btrfs xor raid6_pq sr_mod cdrom sg sd_mod mgag200 ttm drm_kms_helper syscopyarea ahci sysfillrect snd_pcm coretemp libahci sysimgblt fb_sys_fops snd_timer kvm_intel kvm drm snd crc32c_intel soundcore libata usb_storage i7core_edac pcspkr microcode ipmi_si edac_core shpchp ipmi_msghandler acpi_cpufreq
[ 28.370002] CPU: 0 PID: 232 Comm: kworker/0:2 Not tainted 4.3.0-rc3-00712-gca6fb06 #1
[ 28.378773] Hardware name: Intel Corporation S5520UR/S5520UR, BIOS S5500.86B.01.00.0050.050620101605 05/06/2010
[ 28.390079] Workqueue: events __net_random_once_deferred
[ 28.396041] task: ffff8801e8222400 ti: ffff8801de2ec000 task.ti: ffff8801de2ec000
[ 28.404422] RIP: 0010:[<ffffffff8139c68b>] [<ffffffff8139c68b>] selinux_ip_postroute_compat+0xab/0xd0
[ 28.414864] RSP: 0018:ffff8801e9a036a8 EFLAGS: 00010246
[ 28.420804] RAX: 0000000000000000 RBX: ffff88035e34ed00 RCX: 0000000000000006
[ 28.428797] RDX: ffff8801e9a036d8 RSI: 0000000000000014 RDI: ffff88035e34ed00
[ 28.436789] RBP: ffff8801e9a03720 R08: ffff8801e9a036af R09: ffff88008186c000
[ 28.444781] R10: ffff8801e9403180 R11: ffff88008186c08c R12: 48c7894800000180
[ 28.452775] R13: ffff8801e9a03780 R14: ffff88035e34ed00 R15: 0000000000000000
[ 28.460770] FS: 0000000000000000(0000) GS:ffff8801e9a00000(0000) knlGS:0000000000000000
[ 28.469834] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b
[ 28.476269] CR2: 0000561603345e40 CR3: 00000001dcae2000 CR4: 00000000000006f0
[ 28.484261] Stack:
[ 28.486512] 06ff8801e9a037b0 ffff8801e9a036f0 ffff8801e9a57302 ffff8801e9a036d8
[ 28.494830] ffff880082254800 ffff8801e9a036e8 0000000000000002 0000000000000000
[ 28.503148] 00004e918d030002 9201a8c00101a8c0 0000000000000000 0000000000000000
[ 28.511466] Call Trace:
[ 28.514203] <IRQ>
[ 28.516360] [<ffffffff8139c86b>] selinux_ip_postroute+0x1bb/0x390
[ 28.523491] [<ffffffff8109dd5d>] ? ttwu_do_activate+0x5d/0x70
[ 28.531290] [<ffffffff8139ca7a>] selinux_ipv4_postroute+0x1a/0x20
[ 28.538214] [<ffffffff817af2e2>] nf_iterate+0x52/0x80
[ 28.543969] [<ffffffff817af380>] nf_hook_slow+0x70/0xc0
[ 28.549922] [<ffffffff817be029>] ip_output+0xb9/0xd0
[ 28.555580] [<ffffffff817bc002>] ? __ip_local_out_sk+0xa2/0xf0
[ 28.562212] [<ffffffff817bcfe0>] ? ip_fragment+0xa0/0xa0
[ 28.570683] [<ffffffff817bc081>] ip_local_out_sk+0x31/0x40
[ 28.578082] [<ffffffff817bc1d8>] ip_build_and_send_pkt+0x148/0x1c0
[ 28.586266] [<ffffffff817dcc3d>] tcp_v4_send_synack+0x6d/0xc0
[ 28.593944] [<ffffffff817c21c9>] ? inet_ehash_insert+0x59/0x130
[ 28.601799] [<ffffffff817c34e6>] ? inet_csk_reqsk_queue_hash_add+0x76/0xa0
[ 28.610721] [<ffffffff817cc661>] tcp_conn_request+0x961/0x970
[ 28.618368] [<ffffffff817db30c>] tcp_v4_conn_request+0x4c/0x50
[ 28.626096] [<ffffffff817d2638>] tcp_rcv_state_process+0x188/0xca0
[ 28.634185] [<ffffffff817dc189>] tcp_v4_do_rcv+0x79/0x220
[ 28.641368] [<ffffffff817dd5b5>] tcp_v4_rcv+0x7c5/0x940
[ 28.648324] [<ffffffff817b813f>] ip_local_deliver_finish+0x9f/0x1e0
[ 28.656420] [<ffffffff817b8406>] ip_local_deliver+0x56/0xc0
[ 28.663716] [<ffffffff817b7e19>] ip_rcv_finish+0x99/0x320
[ 28.670811] [<ffffffff817b86ce>] ip_rcv+0x25e/0x380
[ 28.677320] [<ffffffff8116ebf3>] ? __alloc_pages_nodemask+0x193/0x9e0
[ 28.685586] [<ffffffff817b86ce>] ? ip_rcv+0x25e/0x380
[ 28.692296] [<ffffffff81779fcc>] __netif_receive_skb_core+0x6bc/0x920
[ 28.700541] [<ffffffff817e21d6>] ? tcp4_gro_receive+0x126/0x1d0
[ 28.708179] [<ffffffff8177a246>] __netif_receive_skb+0x16/0x70
[ 28.715685] [<ffffffff8177a2d8>] netif_receive_skb_internal+0x38/0xb0
[ 28.723849] [<ffffffff8177aeb3>] napi_gro_receive+0xb3/0xe0
[ 28.731023] [<ffffffff81605bf0>] igb_clean_rx_irq+0x740/0x7d0
[ 28.738366] [<ffffffff810d0b14>] ? handle_edge_irq+0x74/0x140
[ 28.745698] [<ffffffff81605fbe>] igb_poll+0x33e/0x6e0
[ 28.752235] [<ffffffff818b1794>] ? do_IRQ+0x54/0xd0
[ 28.758555] [<ffffffff818af387>] ? common_interrupt+0x87/0x87
[ 28.765842] [<ffffffff8177a7a9>] net_rx_action+0x1f9/0x310
[ 28.772827] [<ffffffff810792e3>] __do_softirq+0x113/0x2c0
[ 28.779715] [<ffffffff81079678>] irq_exit+0xe8/0xf0
[ 28.786017] [<ffffffff818b1794>] do_IRQ+0x54/0xd0
[ 28.792126] [<ffffffff818af387>] common_interrupt+0x87/0x87
[ 28.799180] <EOI>
[ 28.801336] [<ffffffff81402574>] ? alloc_cpumask_var_node+0x24/0x30
[ 28.810101] [<ffffffff8104d80d>] ? default_send_IPI_mask_allbutself_phys+0xbd/0xf0
[ 28.819388] [<ffffffff8101cf50>] ? setup_data_read+0xa0/0xa0
[ 28.826529] [<ffffffff810530a7>] physflat_send_IPI_allbutself+0x17/0x20
[ 28.834751] [<ffffffff810493e8>] native_send_call_func_ipi+0xc8/0xf0
[ 28.842682] [<ffffffff8101cf50>] ? setup_data_read+0xa0/0xa0
[ 28.849826] [<ffffffff810f388a>] smp_call_function_many+0x1aa/0x250
[ 28.857650] [<ffffffff8177121a>] ? secure_ipv4_port_ephemeral+0x1a/0x80
[ 28.865868] [<ffffffff8101cf50>] ? setup_data_read+0xa0/0xa0
[ 28.873015] [<ffffffff8177121b>] ? secure_ipv4_port_ephemeral+0x1b/0x80
[ 28.881231] [<ffffffff810f39ab>] on_each_cpu+0x3b/0x80
[ 28.887793] [<ffffffff8177121a>] ? secure_ipv4_port_ephemeral+0x1a/0x80
[ 28.896015] [<ffffffff8101dfbb>] text_poke_bp+0x5b/0xc0
[ 28.902688] [<ffffffff8177121a>] ? secure_ipv4_port_ephemeral+0x1a/0x80
[ 28.910922] [<ffffffff8101a93c>] arch_jump_label_transform+0x8c/0xf0
[ 28.918869] [<ffffffff811632cc>] __jump_label_update+0x6c/0x80
[ 28.926230] [<ffffffff81163376>] jump_label_update+0x96/0xa0
[ 28.933397] [<ffffffff811635d1>] __static_key_slow_dec+0x41/0x90
[ 28.940951] [<ffffffff81163642>] static_key_slow_dec+0x22/0x60
[ 28.948311] [<ffffffff8178ef1f>] __net_random_once_deferred+0x1f/0x30
[ 28.956353] [<ffffffff8108cd07>] process_one_work+0x157/0x420
[ 28.963614] [<ffffffff8108d979>] worker_thread+0x69/0x4a0
[ 28.970486] [<ffffffff818a9bae>] ? __schedule+0x34e/0x9f0
[ 28.977357] [<ffffffff8108d910>] ? rescuer_thread+0x380/0x380
[ 28.984607] [<ffffffff8108d910>] ? rescuer_thread+0x380/0x380
[ 28.991856] [<ffffffff81092eef>] kthread+0xef/0x110
[ 28.998133] [<ffffffff81092e00>] ? kthread_park+0x60/0x60
[ 29.004992] [<ffffffff818aec1f>] ret_from_fork+0x3f/0x70
[ 29.011750] [<ffffffff81092e00>] ? kthread_park+0x60/0x60
[ 29.018613] Code: 30 00 00 00 e8 e7 a9 ff ff 85 c0 74 1a b9 00 00 6f 00 48 83 c4 68 89 c8 5b 41 5c 5d c3 8b 05 a5 ba e4 00 85 c0 75 c4 0f b6 4d 8f <41> 8b 7c 24 10 48 8d 55 98 48 89 de e8 64 56 01 00 85 c0 b9 01
[ 29.041973] RIP [<ffffffff8139c68b>] selinux_ip_postroute_compat+0xab/0xd0
[ 29.050559] RSP <ffff8801e9a036a8>
836ef9b65dc0aac0
[ 29.055516] ---[ end trace 2623fcdeb12ad68c ]---
2ff97704d52a05-20151006-81887-pc
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, 3 months
[lkp] [x86, pkeys] 2205cb3c40: 22.4% will-it-scale.per_thread_ops
by kernel test robot
FYI, we noticed the below changes on
https://git.kernel.org/pub/scm/linux/kernel/git/daveh/x86-pkeys.git pkeys-v005-protsyscalls
commit 2205cb3c40c2ba6521427c9f8f36b2d4f2f2a98d ("x86, pkeys: arch-specific protection bits")
=========================================================================================
tbox_group/testcase/rootfs/kconfig/compiler/cpufreq_governor/test:
lkp-xbm/will-it-scale/debian-x86_64-2015-02-07.cgz/x86_64-rhel/gcc-4.9/performance/pread1
commit:
d1d70274836b555fdc790a4957ce5ca24b427bf4
2205cb3c40c2ba6521427c9f8f36b2d4f2f2a98d
d1d70274836b555f 2205cb3c40c2ba6521427c9f8f
---------------- --------------------------
%stddev %change %stddev
\ | \
1440317 ± 0% +22.4% 1762590 ± 0% will-it-scale.per_thread_ops
313.14 ± 0% -2.1% 306.57 ± 0% will-it-scale.time.system_time
27.99 ± 0% +24.0% 34.71 ± 0% will-it-scale.time.user_time
13.25 ± 20% -50.9% 6.50 ± 41% cpuidle.POLL.usage
27.99 ± 0% +24.0% 34.71 ± 0% time.user_time
1965 ± 0% -19.1% 1590 ± 3% vmstat.system.cs
37.50 ± 4% -24.0% 28.50 ± 9% sched_debug.cfs_rq[0]:/.nr_spread_over
144.50 ± 6% -17.8% 118.75 ± 9% sched_debug.cfs_rq[1]:/.load_avg
145.50 ± 6% -18.6% 118.50 ± 9% sched_debug.cfs_rq[1]:/.tg_load_avg_contrib
lkp-xbm: Sandy Bridge
Memory: 2G
will-it-scale.per_thread_ops
1.8e+06 ++------------O-O--O--O-O-----O--O---------O-----O-O--O----------+
O O O O O O O O O O O O O
1.75e+06 ++ O |
| |
1.7e+06 ++ |
1.65e+06 ++ |
| |
1.6e+06 ++ |
| |
1.55e+06 ++ |
1.5e+06 ++ |
| *..*.. |
1.45e+06 ++ .. .* |
*..*.*..*..*..*.*..*..*.* *.*..*..*.*..*..*.*..*. |
1.4e+06 ++---------------------------------------------------------------+
will-it-scale.time.user_time
36 ++---------------------------------------------------------------------+
| O O O |
35 ++ O O O O O O O O O O O O O O O
34 ++ O O O |
O O O |
33 ++ O |
32 ++ |
| |
31 ++ |
30 ++ |
| |
29 ++ *..*.. |
28 ++ .. .* |
*..*.. .*..*..*.. .* *.*..*..*..*..*..*..*..*. |
27 ++----*-----------*--*--*----------------------------------------------+
will-it-scale.time.system_time
315 ++--------------------------------------------------------------------+
314 ++ .*.. .*.*..*.. |
*..*. *..*..*. *.. *..*.. .*..*..*..*. .*.. |
313 ++ *.. .. *. *. * |
312 ++ * |
| |
311 ++ |
310 ++ |
309 ++ |
| O |
308 O+ O O |
307 ++ O O O O O O |
| O O O O O O O O O O O O O
306 ++ O O |
305 ++--------------------------------------------------------------------+
time.user_time
36 ++---------------------------------------------------------------------+
| O O O |
35 ++ O O O O O O O O O O O O O O O
34 ++ O O O |
O O O |
33 ++ O |
32 ++ |
| |
31 ++ |
30 ++ |
| |
29 ++ *..*.. |
28 ++ .. .* |
*..*.. .*..*..*.. .* *.*..*..*..*..*..*..*..*. |
27 ++----*-----------*--*--*----------------------------------------------+
[*] 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, 3 months
[lkp] [mm] 46cfb125d6: No primary result change, 11.3% fsmark.time.minor_page_faults
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 46cfb125d673f2f30acf06b3c02485bf693d54e9 ("mm: increase SWAP_CLUSTER_MAX to batch TLB flushes")
=========================================================================================
tbox_group/testcase/rootfs/kconfig/compiler/cpufreq_governor/iterations/nr_threads/disk/fs/filesize/test_size/sync_method/nr_directories/nr_files_per_directory:
nhm4/fsmark/debian-x86_64-2015-02-07.cgz/x86_64-rhel/gcc-4.9/performance/1x/32t/1HDD/btrfs/16MB/60G/fsyncBeforeClose/16d/256fpd
commit:
284f74b8c06045dcc1b5caaf5f412219f18cfc60
46cfb125d673f2f30acf06b3c02485bf693d54e9
284f74b8c06045dc 46cfb125d673f2f30acf06b3c0
---------------- --------------------------
%stddev %change %stddev
\ | \
14126 ± 3% +11.3% 15720 ± 4% fsmark.time.minor_page_faults
17.00 ± 0% +5.9% 18.00 ± 0% fsmark.time.percent_of_cpu_this_job_got
88.97 ± 0% +8.8% 96.80 ± 0% fsmark.time.system_time
52682 ± 0% -18.1% 43155 ± 0% softirqs.BLOCK
14126 ± 3% +11.3% 15720 ± 4% time.minor_page_faults
25179737 ± 8% -18.5% 20528816 ± 5% cpuidle.C1E-NHM.time
133847 ± 3% -14.1% 114908 ± 4% cpuidle.C3-NHM.usage
92678 ± 2% -20.8% 73391 ± 1% meminfo.Dirty
124930 ± 1% +21.4% 151629 ± 0% meminfo.Writeback
3.45 ± 1% +3.4% 3.56 ± 0% turbostat.%Busy
111.50 ± 1% +3.8% 115.75 ± 0% turbostat.Avg_MHz
101775 ±157% +329.9% 437551 ±169% latency_stats.max.btrfs_tree_lock.[btrfs].btrfs_lock_root_node.[btrfs].btrfs_search_slot.[btrfs].btrfs_insert_empty_items.[btrfs].btrfs_new_inode.[btrfs].btrfs_create.[btrfs].vfs_create.path_openat.do_filp_open.do_sys_open.SyS_open.entry_SYSCALL_64_fastpath
8005 ± 19% +5364.1% 437413 ±169% latency_stats.max.btrfs_tree_lock.[btrfs].btrfs_lock_root_node.[btrfs].btrfs_search_slot.[btrfs].btrfs_insert_empty_items.[btrfs].insert_with_overflow.[btrfs].btrfs_insert_dir_item.[btrfs].btrfs_add_link.[btrfs].btrfs_create.[btrfs].vfs_create.path_openat.do_filp_open.do_sys_open
469382 ± 66% -98.7% 6266 ± 18% latency_stats.max.btrfs_tree_lock.[btrfs].btrfs_search_slot.[btrfs].btrfs_lookup_inode.[btrfs].__btrfs_update_delayed_inode.[btrfs].btrfs_commit_inode_delayed_items.[btrfs].btrfs_log_inode.[btrfs].btrfs_log_inode_parent.[btrfs].btrfs_log_dentry_safe.[btrfs].btrfs_sync_file.[btrfs].vfs_fsync_range.do_fsync.SyS_fsync
363661 ±103% -100.0% 84.50 ± 19% latency_stats.max.btrfs_tree_read_lock.[btrfs].btrfs_read_lock_root_node.[btrfs].btrfs_search_slot.[btrfs].btrfs_insert_empty_items.[btrfs].copy_items.[btrfs].btrfs_log_inode.[btrfs].btrfs_log_inode_parent.[btrfs].btrfs_log_dentry_safe.[btrfs].btrfs_sync_file.[btrfs].vfs_fsync_range.do_fsync.SyS_fsync
404767 ±170% -98.6% 5611 ±114% latency_stats.max.btrfs_tree_read_lock.[btrfs].btrfs_read_lock_root_node.[btrfs].btrfs_search_slot.[btrfs].btrfs_lookup_dir_item.[btrfs].btrfs_lookup_dentry.[btrfs].btrfs_lookup.[btrfs].lookup_real.path_openat.do_filp_open.do_sys_open.SyS_open.entry_SYSCALL_64_fastpath
35516 ±145% +1126.2% 435514 ±170% latency_stats.max.btrfs_tree_read_lock.[btrfs].btrfs_read_lock_root_node.[btrfs].btrfs_search_slot.[btrfs].btrfs_lookup_xattr.[btrfs].__btrfs_getxattr.[btrfs].btrfs_getxattr.[btrfs].cap_inode_need_killpriv.security_inode_need_killpriv.dentry_needs_remove_privs.file_remove_privs.btrfs_file_write_iter.[btrfs].__vfs_write
1107650 ±130% -72.4% 305514 ± 96% latency_stats.max.wait_on_page_bit.__filemap_fdatawait_range.filemap_fdatawait_range.btrfs_wait_marked_extents.[btrfs].btrfs_write_and_wait_transaction.[btrfs].btrfs_commit_transaction.[btrfs].btrfs_sync_file.[btrfs].vfs_fsync_range.do_fsync.SyS_fsync.entry_SYSCALL_64_fastpath
199353 ± 84% +377.4% 951697 ±154% latency_stats.sum.btrfs_tree_lock.[btrfs].btrfs_lock_root_node.[btrfs].btrfs_search_slot.[btrfs].btrfs_insert_empty_items.[btrfs].btrfs_new_inode.[btrfs].btrfs_create.[btrfs].vfs_create.path_openat.do_filp_open.do_sys_open.SyS_open.entry_SYSCALL_64_fastpath
62503 ± 33% +657.0% 473167 ±155% latency_stats.sum.btrfs_tree_lock.[btrfs].btrfs_lock_root_node.[btrfs].btrfs_search_slot.[btrfs].btrfs_insert_empty_items.[btrfs].insert_with_overflow.[btrfs].btrfs_insert_dir_item.[btrfs].btrfs_add_link.[btrfs].btrfs_create.[btrfs].vfs_create.path_openat.do_filp_open.do_sys_open
5568453 ± 73% -75.9% 1342137 ± 11% latency_stats.sum.btrfs_tree_lock.[btrfs].btrfs_search_slot.[btrfs].btrfs_lookup_inode.[btrfs].__btrfs_update_delayed_inode.[btrfs].btrfs_commit_inode_delayed_items.[btrfs].btrfs_log_inode.[btrfs].btrfs_log_inode_parent.[btrfs].btrfs_log_dentry_safe.[btrfs].btrfs_sync_file.[btrfs].vfs_fsync_range.do_fsync.SyS_fsync
1647770 ±118% -99.5% 7733 ± 11% latency_stats.sum.btrfs_tree_read_lock.[btrfs].btrfs_read_lock_root_node.[btrfs].btrfs_search_slot.[btrfs].btrfs_insert_empty_items.[btrfs].copy_items.[btrfs].btrfs_log_inode.[btrfs].btrfs_log_inode_parent.[btrfs].btrfs_log_dentry_safe.[btrfs].btrfs_sync_file.[btrfs].vfs_fsync_range.do_fsync.SyS_fsync
4780008 ±170% -99.2% 36367 ± 61% latency_stats.sum.btrfs_tree_read_lock.[btrfs].btrfs_read_lock_root_node.[btrfs].btrfs_search_slot.[btrfs].btrfs_lookup_dir_item.[btrfs].btrfs_lookup_dentry.[btrfs].btrfs_lookup.[btrfs].lookup_real.path_openat.do_filp_open.do_sys_open.SyS_open.entry_SYSCALL_64_fastpath
88081 ±118% +919.9% 898339 ±164% latency_stats.sum.btrfs_tree_read_lock.[btrfs].btrfs_read_lock_root_node.[btrfs].btrfs_search_slot.[btrfs].btrfs_lookup_xattr.[btrfs].__btrfs_getxattr.[btrfs].btrfs_getxattr.[btrfs].cap_inode_need_killpriv.security_inode_need_killpriv.dentry_needs_remove_privs.file_remove_privs.btrfs_file_write_iter.[btrfs].__vfs_write
20472 ± 0% -24.3% 15507 ± 0% proc-vmstat.allocstall
1021 ± 34% -73.5% 271.00 ± 26% proc-vmstat.kswapd_high_wmark_hit_quickly
23142 ± 2% -20.9% 18301 ± 1% proc-vmstat.nr_dirty
2441 ± 28% -82.8% 419.50 ± 29% proc-vmstat.nr_vmscan_immediate_reclaim
31236 ± 1% +21.5% 37946 ± 1% proc-vmstat.nr_writeback
8479 ± 5% +18.8% 10075 ± 7% proc-vmstat.numa_hint_faults
8479 ± 5% +18.8% 10075 ± 7% proc-vmstat.numa_hint_faults_local
9293 ± 4% +18.1% 10977 ± 6% proc-vmstat.numa_pte_updates
683.50 ± 95% -86.0% 96.00 ± 67% proc-vmstat.pgdeactivate
1298 ± 11% -39.7% 783.50 ± 45% proc-vmstat.pgmigrate_success
796.75 ± 81% -71.9% 224.25 ± 30% proc-vmstat.pgrefill_dma
2969680 ± 0% +110.2% 6241700 ± 0% proc-vmstat.pgscan_direct_dma32
9735 ± 15% -86.3% 1332 ± 20% proc-vmstat.pgscan_kswapd_dma
12295232 ± 0% -26.7% 9017741 ± 0% proc-vmstat.pgscan_kswapd_dma32
2893713 ± 1% +113.0% 6162694 ± 0% proc-vmstat.pgsteal_direct_dma32
12278505 ± 0% -26.7% 9003970 ± 0% proc-vmstat.pgsteal_kswapd_dma32
7.00 ±141% +225.0% 22.75 ± 30% sched_debug.cfs_rq[0]:/.load
88.75 ± 47% +166.2% 236.25 ± 19% sched_debug.cfs_rq[0]:/.tg_load_avg
0.50 ±100% +3150.0% 16.25 ± 47% sched_debug.cfs_rq[1]:/.runnable_load_avg
86.25 ± 42% +175.1% 237.25 ± 19% sched_debug.cfs_rq[1]:/.tg_load_avg
87.25 ± 41% +175.4% 240.25 ± 20% sched_debug.cfs_rq[2]:/.tg_load_avg
22.75 ± 35% +217.6% 72.25 ± 44% sched_debug.cfs_rq[2]:/.util_avg
7.00 ± 82% +128.6% 16.00 ± 32% sched_debug.cfs_rq[3]:/.load_avg
-533786 ± -7% -15.0% -453453 ± -6% sched_debug.cfs_rq[3]:/.spread0
88.75 ± 40% +169.6% 239.25 ± 19% sched_debug.cfs_rq[3]:/.tg_load_avg
7.00 ± 82% +135.7% 16.50 ± 35% sched_debug.cfs_rq[3]:/.tg_load_avg_contrib
23.50 ± 58% +217.0% 74.50 ± 44% sched_debug.cfs_rq[3]:/.util_avg
89.00 ± 40% +168.8% 239.25 ± 19% sched_debug.cfs_rq[4]:/.tg_load_avg
89.50 ± 39% +169.6% 241.25 ± 18% sched_debug.cfs_rq[5]:/.tg_load_avg
28.75 ± 90% +173.0% 78.50 ± 61% sched_debug.cfs_rq[5]:/.util_avg
5799 ± 5% +14.2% 6619 ± 2% sched_debug.cfs_rq[6]:/.exec_clock
90.50 ± 39% +167.7% 242.25 ± 19% sched_debug.cfs_rq[6]:/.tg_load_avg
13.00 ±105% +142.3% 31.50 ± 60% sched_debug.cfs_rq[7]:/.load_avg
93.00 ± 40% +162.4% 244.00 ± 19% sched_debug.cfs_rq[7]:/.tg_load_avg
13.00 ±105% +165.4% 34.50 ± 64% sched_debug.cfs_rq[7]:/.tg_load_avg_contrib
0.75 ±110% +733.3% 6.25 ± 60% sched_debug.cpu#0.cpu_load[3]
0.75 ±110% +1033.3% 8.50 ± 45% sched_debug.cpu#0.cpu_load[4]
34.33 ±141% +759.2% 295.00 ± 42% sched_debug.cpu#0.curr->pid
5.75 ±153% +791.3% 51.25 ± 83% sched_debug.cpu#0.load
-821.75 ± -3% -13.2% -713.25 ± -2% sched_debug.cpu#0.nr_uninterruptible
1.00 ±100% +750.0% 8.50 ± 48% sched_debug.cpu#1.cpu_load[1]
1.75 ± 84% +400.0% 8.75 ± 48% sched_debug.cpu#1.cpu_load[2]
30329 ± 51% -46.2% 16309 ± 5% sched_debug.cpu#2.nr_load_updates
4.00 ± 98% +918.8% 40.75 ± 69% sched_debug.cpu#6.cpu_load[0]
2.25 ± 85% +1400.0% 33.75 ± 85% sched_debug.cpu#6.cpu_load[1]
1.75 ± 84% +1557.1% 29.00 ± 90% sched_debug.cpu#6.cpu_load[2]
1.25 ± 87% +1960.0% 25.75 ± 93% sched_debug.cpu#6.cpu_load[3]
149.50 ± 20% -29.3% 105.75 ± 15% sched_debug.cpu#6.nr_uninterruptible
36817 ± 8% +4975.8% 1868779 ±169% sched_debug.cpu#7.nr_switches
37382 ± 9% +4900.3% 1869233 ±169% sched_debug.cpu#7.sched_count
16197 ± 9% +5605.7% 924151 ±169% sched_debug.cpu#7.sched_goidle
0.49 ± 2% +1020.2% 5.53 ±103% sched_debug.rt_rq[0]:/.rt_time
nhm4: Nehalem
Memory: 4G
fsmark.time.system_time
100 ++--------------------------------------------------------------------+
| O |
98 O+ O O O O O O |
| O O O O O O O |
96 ++ O O O O |
| |
94 ++ |
| |
92 ++ |
| .*. .*.*.. .*.. |
90 ++ .*.*. *. *.*..* * |
*..*.*..*. .*. .*. + .*.*..*.*..*.*..*.*
88 ++ *. *..* *. |
| |
86 ++--------------------------------------------------------------------+
[*] 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, 3 months
[lkp] [drm/core] 12188d6e3a: WARNING: CPU: 2 PID: 144 at drivers/gpu/drm/drm_drv.c:569 drm_dev_alloc+0x257/0x320 [drm]()
by kernel test robot
FYI, we noticed the below changes on
git://anongit.freedesktop.org/drm-intel topic/drm-misc
commit 12188d6e3a5ad24423fdc7a05ed10e4af35832d1 ("drm/core: Preserve the framebuffer after removing it.")
[ 24.081524] thinkpad_acpi: Standard ACPI backlight interface available, not loading native one
[ 24.081756] IOAPIC[0]: Set routing entry (1-16 -> 0x22 -> IRQ 16 Mode:1 Active:1 Dest:15)
[ 24.081794] ------------[ cut here ]------------
[ 24.081825] WARNING: CPU: 2 PID: 144 at drivers/gpu/drm/drm_drv.c:569 drm_dev_alloc+0x257/0x320 [drm]()
[ 24.081860] Modules linked in: sdhci(+) v4l2_common libahci(+) intel_ips snd_pcsp(-) libata serio_raw pcspkr firewire_core crc_itu_t mmc_core snd_hda_intel i915(+) videodev snd_hda_codec snd_hda_core snd_hwdep shpchp drm_kms_helper syscopyarea snd_pcm sysfillrect sysimgblt fb_sys_fops snd_timer drm thinkpad_acpi(+) snd soundcore rfkill parport_pc parport wmi video acpi_cpufreq
[ 24.081865] CPU: 2 PID: 144 Comm: systemd-udevd Not tainted 4.3.0-rc2-00052-g12188d6 #1
[ 24.081867] Hardware name: LENOVO 25222AU/25222AU, BIOS 6IET52WW (1.12 ) 02/16/2010
[ 24.081872] ffffffffa00c92f0 ffff880076407a68 ffffffff81402692 0000000000000000
[ 24.081876] ffff880076407aa0 ffffffff81074756 ffff8800645ad000 0000000000000000
[ 24.081881] ffffffffa02d1100 ffffffffa02d1100 ffffffffa0299740 ffff880076407ab0
[ 24.081882] Call Trace:
[ 24.081892] [<ffffffff81402692>] dump_stack+0x4b/0x69
[ 24.081898] [<ffffffff81074756>] warn_slowpath_common+0x86/0xc0
[ 24.081903] [<ffffffff8107484a>] warn_slowpath_null+0x1a/0x20
[ 24.081924] [<ffffffffa009c3c7>] drm_dev_alloc+0x257/0x320 [drm]
[ 24.081966] [<ffffffffa009eb3b>] drm_get_pci_dev+0x3b/0x1e0 [drm]
[ 24.082015] [<ffffffffa01e7214>] i915_pci_probe+0x34/0x50 [i915]
[ 24.082020] [<ffffffff81448b85>] local_pci_probe+0x45/0xa0
[ 24.082025] [<ffffffff81449f77>] pci_device_probe+0xd7/0x120
[ 24.082032] [<ffffffff8154b614>] driver_probe_device+0x224/0x490
[ 24.082037] [<ffffffff8154b904>] __driver_attach+0x84/0x90
[ 24.082041] [<ffffffff8154b880>] ? driver_probe_device+0x490/0x490
[ 24.082045] [<ffffffff81549334>] bus_for_each_dev+0x64/0xa0
[ 24.082050] [<ffffffff8154afae>] driver_attach+0x1e/0x20
[ 24.082054] [<ffffffff8154ab11>] bus_add_driver+0x1f1/0x290
[ 24.082059] [<ffffffff8154c2a0>] driver_register+0x60/0xe0
[ 24.082063] [<ffffffff8144852c>] __pci_register_driver+0x4c/0x50
[ 24.082087] [<ffffffffa009edc0>] drm_pci_init+0xe0/0x110 [drm]
[ 24.082091] [<ffffffffa0300000>] ? 0xffffffffa0300000
[ 24.082143] [<ffffffffa03000a6>] i915_init+0xa6/0xad [i915]
[ 24.082149] [<ffffffff81002123>] do_one_initcall+0xb3/0x1d0
[ 24.082155] [<ffffffff811c1db4>] ? kmem_cache_alloc_trace+0x1b4/0x220
[ 24.082162] [<ffffffff811647b8>] ? do_init_module+0x27/0x1e8
[ 24.082166] [<ffffffff811647f1>] do_init_module+0x60/0x1e8
[ 24.082173] [<ffffffff810f9819>] load_module+0x2089/0x24b0
[ 24.082178] [<ffffffff810f5a20>] ? __symbol_put+0x40/0x40
[ 24.082183] [<ffffffff810f9e40>] SyS_finit_module+0x80/0xb0
[ 24.082189] [<ffffffff818ad3ae>] entry_SYSCALL_64_fastpath+0x12/0x71
[ 24.082192] ---[ end trace 5cbe1f0bd2dab019 ]---
--
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, 3 months
[lkp] [f2fs] 15bec0ff5a: -7.5% fsmark.files_per_sec
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 15bec0ff5a9ba6d203178fa8772259df6207942a ("f2fs: do not skip dentry block writes")
=========================================================================================
tbox_group/testcase/rootfs/kconfig/compiler/cpufreq_governor/iterations/nr_threads/disk/fs/filesize/test_size/sync_method/nr_directories/nr_files_per_directory:
nhm4/fsmark/debian-x86_64-2015-02-07.cgz/x86_64-rhel/gcc-4.9/performance/1x/32t/1HDD/f2fs/16MB/60G/NoSync/16d/256fpd
commit:
1583667acb21aba71a8ba16a5f1596bba1cdbbfa
15bec0ff5a9ba6d203178fa8772259df6207942a
1583667acb21aba7 15bec0ff5a9ba6d203178fa877
---------------- --------------------------
%stddev %change %stddev
\ | \
9.60 ± 0% -7.5% 8.88 ± 2% fsmark.files_per_sec
25828 ± 14% +43.3% 37001 ± 9% fsmark.time.involuntary_context_switches
346865 ± 1% +5.1% 364446 ± 2% fsmark.time.voluntary_context_switches
149762 ± 1% -10.8% 133532 ± 1% meminfo.Writeback
25828 ± 14% +43.3% 37001 ± 9% time.involuntary_context_switches
10567 ± 0% -1.4% 10423 ± 0% vmstat.system.in
35272 ± 1% +13.7% 40088 ± 2% softirqs.BLOCK
55788 ± 2% -9.2% 50649 ± 2% softirqs.RCU
86.40 ± 0% +2.9% 88.90 ± 0% turbostat.Avg_MHz
15.04 ± 4% +51.2% 22.74 ± 7% turbostat.CPU%c6
95420434 ± 2% +11.1% 1.06e+08 ± 3% cpuidle.C1-NHM.time
76306793 ± 3% -25.7% 56703791 ± 6% cpuidle.C1E-NHM.time
136043 ± 3% +10.9% 150901 ± 1% cpuidle.C6-NHM.usage
85.60 ± 40% +149.5% 213.60 ± 32% proc-vmstat.allocstall
761.80 ±114% +1601.0% 12958 ± 32% proc-vmstat.nr_vmscan_immediate_reclaim
37430 ± 1% -10.8% 33381 ± 1% proc-vmstat.nr_writeback
4375 ± 2% +45.5% 6367 ± 10% proc-vmstat.pgactivate
307.90 ±138% +4385.4% 13810 ± 25% proc-vmstat.pgrotated
11195 ± 43% +150.1% 27994 ± 34% proc-vmstat.pgsteal_direct_dma32
274804 ± 7% -13.2% 238436 ± 6% sched_debug.cfs_rq[1]:/.min_vruntime
2498 ± 9% +44.4% 3607 ± 5% sched_debug.cfs_rq[4]:/.exec_clock
2769 ± 7% +39.7% 3869 ± 16% sched_debug.cfs_rq[5]:/.exec_clock
2667 ± 12% +40.3% 3741 ± 6% sched_debug.cfs_rq[6]:/.exec_clock
2631 ± 9% +43.1% 3765 ± 7% sched_debug.cfs_rq[7]:/.exec_clock
-95.80 ±-32% +95.2% -187.00 ±-30% sched_debug.cpu#0.nr_uninterruptible
11444 ± 7% +57.4% 18012 ± 46% sched_debug.cpu#5.nr_load_updates
43.30 ± 29% +228.2% 142.10 ± 27% sched_debug.cpu#5.nr_uninterruptible
9204 ± 10% +3829.2% 361677 ±193% sched_debug.cpu#5.ttwu_local
0.00 ± -1% +Inf% 726873 ± 91% latency_stats.avg.get_request.blk_queue_bio.generic_make_request.submit_bio.__submit_merged_bio.[f2fs].f2fs_submit_merged_bio.[f2fs].ra_meta_pages.[f2fs].build_free_nids.[f2fs].alloc_nid.[f2fs].f2fs_new_inode.[f2fs].f2fs_create.[f2fs].vfs_create
0.00 ± -1% +Inf% 957001 ± 34% latency_stats.avg.get_request.blk_queue_bio.generic_make_request.submit_bio.__submit_merged_bio.[f2fs].f2fs_submit_merged_bio.[f2fs].ra_meta_pages.[f2fs].build_free_nids.[f2fs].alloc_nid.[f2fs].get_dnode_of_data.[f2fs].f2fs_reserve_block.[f2fs].f2fs_get_block.[f2fs]
0.00 ± -1% +Inf% 600712 ±106% latency_stats.avg.get_request.blk_queue_bio.generic_make_request.submit_bio.f2fs_submit_page_bio.[f2fs].get_read_data_page.[f2fs].find_data_page.[f2fs].f2fs_find_entry.[f2fs].f2fs_lookup.[f2fs].lookup_real.path_openat.do_filp_open
0.00 ± -1% +Inf% 60887 ± 77% latency_stats.avg.wait_iff_congested.shrink_inactive_list.shrink_lruvec.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_nodemask.alloc_pages_current.__page_cache_alloc.pagecache_get_page.grab_cache_page_write_begin.f2fs_write_begin.[f2fs]
83681 ± 43% +128.1% 190851 ± 70% latency_stats.avg.wait_on_page_bit.f2fs_wait_on_page_writeback.[f2fs].f2fs_wait_on_page_writeback.[f2fs].get_dnode_of_data.[f2fs].f2fs_reserve_block.[f2fs].f2fs_get_block.[f2fs].f2fs_write_begin.[f2fs].generic_perform_write.__generic_file_write_iter.generic_file_write_iter.f2fs_file_write_iter.[f2fs].__vfs_write
0.00 ± -1% +Inf% 339404 ±117% latency_stats.avg.wait_on_page_bit.find_data_page.[f2fs].f2fs_find_entry.[f2fs].f2fs_lookup.[f2fs].lookup_real.path_openat.do_filp_open.do_sys_open.SyS_open.entry_SYSCALL_64_fastpath
0.00 ± -1% +Inf% 765971 ± 90% latency_stats.max.get_request.blk_queue_bio.generic_make_request.submit_bio.__submit_merged_bio.[f2fs].f2fs_submit_merged_bio.[f2fs].ra_meta_pages.[f2fs].build_free_nids.[f2fs].alloc_nid.[f2fs].f2fs_new_inode.[f2fs].f2fs_create.[f2fs].vfs_create
0.00 ± -1% +Inf% 1376989 ± 27% latency_stats.max.get_request.blk_queue_bio.generic_make_request.submit_bio.__submit_merged_bio.[f2fs].f2fs_submit_merged_bio.[f2fs].ra_meta_pages.[f2fs].build_free_nids.[f2fs].alloc_nid.[f2fs].get_dnode_of_data.[f2fs].f2fs_reserve_block.[f2fs].f2fs_get_block.[f2fs]
0.00 ± -1% +Inf% 808830 ±126% latency_stats.max.get_request.blk_queue_bio.generic_make_request.submit_bio.f2fs_submit_page_bio.[f2fs].get_read_data_page.[f2fs].find_data_page.[f2fs].f2fs_find_entry.[f2fs].f2fs_lookup.[f2fs].lookup_real.path_openat.do_filp_open
0.00 ± -1% +Inf% 60934 ± 77% latency_stats.max.wait_iff_congested.shrink_inactive_list.shrink_lruvec.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_nodemask.alloc_pages_current.__page_cache_alloc.pagecache_get_page.grab_cache_page_write_begin.f2fs_write_begin.[f2fs]
113863 ±265% -76.8% 26423 ± 86% latency_stats.max.wait_on_page_bit.f2fs_wait_on_page_writeback.[f2fs].f2fs_wait_on_page_writeback.[f2fs].set_data_blkaddr.[f2fs].reserve_new_block.[f2fs].f2fs_reserve_block.[f2fs].get_new_data_page.[f2fs].__f2fs_add_link.[f2fs].f2fs_create.[f2fs].vfs_create.path_openat.do_filp_open
0.00 ± -1% +Inf% 646891 ±130% latency_stats.max.wait_on_page_bit.find_data_page.[f2fs].f2fs_find_entry.[f2fs].f2fs_lookup.[f2fs].lookup_real.path_openat.do_filp_open.do_sys_open.SyS_open.entry_SYSCALL_64_fastpath
1804998 ± 87% +5901.9% 1.083e+08 ± 9% latency_stats.sum.call_rwsem_down_read_failed.f2fs_wait_on_page_writeback.[f2fs].f2fs_wait_on_page_writeback.[f2fs].__f2fs_add_link.[f2fs].f2fs_create.[f2fs].vfs_create.path_openat.do_filp_open.do_sys_open.SyS_open.entry_SYSCALL_64_fastpath
337670 ± 90% +85.5% 626367 ±147% latency_stats.sum.call_rwsem_down_read_failed.f2fs_wait_on_page_writeback.[f2fs].f2fs_wait_on_page_writeback.[f2fs].get_dnode_of_data.[f2fs].f2fs_reserve_block.[f2fs].f2fs_get_block.[f2fs].f2fs_write_begin.[f2fs].generic_perform_write.__generic_file_write_iter.generic_file_write_iter.f2fs_file_write_iter.[f2fs].__vfs_write
17619 ± 18% +761.1% 151720 ± 14% latency_stats.sum.call_rwsem_down_read_failed.get_node_info.[f2fs].new_node_page.[f2fs].new_inode_page.[f2fs].init_inode_metadata.[f2fs].__f2fs_add_link.[f2fs].f2fs_create.[f2fs].vfs_create.path_openat.do_filp_open.do_sys_open.SyS_open
800.10 ±125% +7429.5% 60243 ± 27% latency_stats.sum.call_rwsem_down_write_failed.get_node_info.[f2fs].new_node_page.[f2fs].new_inode_page.[f2fs].init_inode_metadata.[f2fs].__f2fs_add_link.[f2fs].f2fs_create.[f2fs].vfs_create.path_openat.do_filp_open.do_sys_open.SyS_open
18545 ± 17% +670.2% 142844 ± 16% latency_stats.sum.call_rwsem_down_write_failed.set_node_addr.[f2fs].new_node_page.[f2fs].new_inode_page.[f2fs].init_inode_metadata.[f2fs].__f2fs_add_link.[f2fs].f2fs_create.[f2fs].vfs_create.path_openat.do_filp_open.do_sys_open.SyS_open
0.00 ± -1% +Inf% 1001773 ± 98% latency_stats.sum.get_request.blk_queue_bio.generic_make_request.submit_bio.__submit_merged_bio.[f2fs].f2fs_submit_merged_bio.[f2fs].ra_meta_pages.[f2fs].build_free_nids.[f2fs].alloc_nid.[f2fs].f2fs_new_inode.[f2fs].f2fs_create.[f2fs].vfs_create
0.00 ± -1% +Inf% 3896545 ± 63% latency_stats.sum.get_request.blk_queue_bio.generic_make_request.submit_bio.__submit_merged_bio.[f2fs].f2fs_submit_merged_bio.[f2fs].ra_meta_pages.[f2fs].build_free_nids.[f2fs].alloc_nid.[f2fs].get_dnode_of_data.[f2fs].f2fs_reserve_block.[f2fs].f2fs_get_block.[f2fs]
0.00 ± -1% +Inf% 1037029 ±109% latency_stats.sum.get_request.blk_queue_bio.generic_make_request.submit_bio.f2fs_submit_page_bio.[f2fs].get_read_data_page.[f2fs].find_data_page.[f2fs].f2fs_find_entry.[f2fs].f2fs_lookup.[f2fs].lookup_real.path_openat.do_filp_open
0.00 ± -1% +Inf% 1365444 ±172% latency_stats.sum.wait_iff_congested.shrink_inactive_list.shrink_lruvec.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_nodemask.alloc_pages_current.__page_cache_alloc.pagecache_get_page.grab_cache_page_write_begin.f2fs_write_begin.[f2fs]
1.318e+08 ± 62% +1624.3% 2.272e+09 ± 9% latency_stats.sum.wait_on_page_bit.f2fs_wait_on_page_writeback.[f2fs].f2fs_wait_on_page_writeback.[f2fs].__f2fs_add_link.[f2fs].f2fs_create.[f2fs].vfs_create.path_openat.do_filp_open.do_sys_open.SyS_open.entry_SYSCALL_64_fastpath
0.00 ± -1% +Inf% 1314116 ±111% latency_stats.sum.wait_on_page_bit.find_data_page.[f2fs].f2fs_find_entry.[f2fs].f2fs_lookup.[f2fs].lookup_real.path_openat.do_filp_open.do_sys_open.SyS_open.entry_SYSCALL_64_fastpath
nhm4: Nehalem
Memory: 4G
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, 3 months