January 2016 - LKP - Ml01.01.Org

[lkp] [pci] 2910b5bdc5: pci 0000:06:00.0: Unable to allocate DMA alias mask.

by kernel test robot

FYI, we noticed the below changes on https://github.com/0day-ci/linux Jacek-Lawrynowicz/pci-Add-support-for-multiple-DMA-aliases/20160119-001005 commit 2910b5bdc5788de99e9c533be97b0e8f57be0386 ("pci: Add support for multiple DMA aliases") We found the following new message in kernel log after your commit. [ 0.217837] pci 0000:06:00.0: Unable to allocate DMA alias mask. Thanks, Ying Huang

4 years, 11 months

2
1
0 / 0

[lkp] [kallsyms] f5fc9abc4e: BUG: KASAN: null-ptr-deref on address (null)

by kernel test robot

FYI, we noticed the below changes on https://git.kernel.org/pub/scm/linux/kernel/git/kees/linux.git kallsyms commit f5fc9abc4e23dccfa1fc35892cf36a416d1e6aee ("kallsyms: add support for relative offsets in kallsyms address table") +-----------------------------------------------------------+----------+------------+ | | v4.5-rc1 | f5fc9abc4e | +-----------------------------------------------------------+----------+------------+ | boot_successes | 41 | 0 | | boot_failures | 197 | 26 | | Kernel_panic-not_syncing:Attempted_to_kill_init!exitcode= | 169 | | | BUG:kernel_test_oversize | 19 | | | BUG:workqueue_lockup-pool_cpus=#flags=#nice=#stuck_for#s | 1 | | | IP-Config:Auto-configuration_of_network_failed | 8 | | | BUG:KASAN:null-ptr-deref_on_address(null) | 0 | 26 | | BUG:unable_to_handle_kernel | 0 | 26 | +-----------------------------------------------------------+----------+------------+ [ 0.354394] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.355210] CPU: Intel QEMU Virtual CPU version 2.4.0 (family: 0x6, model: 0x6, stepping: 0x3) [ 0.363389] ================================================================== [ 0.364468] BUG: KASAN: null-ptr-deref on address (null) [ 0.365282] Read of size 8 by task swapper/1 [ 0.365967] CPU: 0 PID: 1 Comm: swapper Not tainted 4.5.0-rc1-00001-gf5fc9ab #2 [ 0.367025] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 [ 0.368241] ffff88000e0ff928 ffff88000e0ff720 ffffffff926c2d59 ffff88000e0ff7a8 [ 0.369551] ffffffff9221e54a 0000000000000000 0000000000000000 0000000000000286 [ 0.370894] 0000000000000000 0000000000000000 0000000000000000 0000000000000000 [ 0.372206] Call Trace: [ 0.372646] BUG: unable to handle kernel paging request at 0000000011000000 [ 0.373669] IP: Elapsed time: 10 qemu-system-x86_64 -enable-kvm -kernel /pkg/linux/x86_64-randconfig-s2-01260945/gcc-5/f5fc9abc4e23dccfa1fc35892cf36a416d1e6aee/vmlinuz-4.5.0-rc1-00001-gf5fc9ab -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-vp-quantal-x86_64-4/bisect_boot-1-quantal-core-x86_64.cgz-x86_64-randconfig-s2-01260945-f5fc9abc4e23dccfa1fc35892cf36a416d1e6aee-20160126-54763-e8aynf-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-s2-01260945 branch=linux-devel/devel-hourly-2016012607 commit=f5fc9abc4e23dccfa1fc35892cf36a416d1e6aee BOOT_IMAGE=/pkg/linux/x86_64-randconfig-s2-01260945/gcc-5/f5fc9abc4e23dccfa1fc35892cf36a416d1e6aee/vmlinuz-4.5.0-rc1-00001-gf5fc9ab max_uptime=600 RESULT_ROOT=/result/boot/1/vm-vp-quantal-x86_64/quantal-core-x86_64.cgz/x86_64-randconfig-s2-01260945/gcc-5/f5fc9abc4e23dccfa1fc35892cf36a416d1e6aee/0 LKP_SERVER=inn earlyprintk=ttyS0,115200 systemd.log_level=err debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal rw ip=::::vm-vp-quantal-x86_64-4::dhcp drbd.minor_count=8' -initrd /fs/sdh1/initrd-vm-vp-quantal-x86_64-4 -m 360 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -pidfile /dev/shm/kboot/pid-vm-vp-quantal-x86_64-4 -serial file:/dev/shm/kboot/serial-vm-vp-quantal-x86_64-4 -daemonize -display none -monitor null Thanks, Kernel Test Robot

4 years, 11 months

1
0
0 / 0

[kallsyms] f5fc9abc4e: BUG: KASAN: null-ptr-deref on address (null)

by kernel test robot

Greetings, 0day kernel testing robot got the below dmesg and the first bad commit is https://git.kernel.org/pub/scm/linux/kernel/git/kees/linux.git kallsyms commit f5fc9abc4e23dccfa1fc35892cf36a416d1e6aee Author: Ard Biesheuvel <ard.biesheuvel(a)linaro.org> AuthorDate: Fri Jan 15 10:51:22 2016 +0100 Commit: Kees Cook <keescook(a)chromium.org> CommitDate: Mon Jan 25 15:35:04 2016 -0800 kallsyms: add support for relative offsets in kallsyms address table Similar to how relative extables are implemented, it is possible to emit the kallsyms table in such a way that it contains offsets relative to some anchor point in the kernel image rather than absolute addresses. The benefit is that such table entries are no longer subject to dynamic relocation when the build time and runtime offsets of the kernel image are different. Also, on 64-bit architectures, it essentially cuts the size of the address table in half since offsets can typically be expressed in 32 bits. Since it is useful for some architectures (like x86) to retain the ability to emit absolute values as well, this patch adds support for both, by emitting absolute addresses as positive 32-bit values, and addresses relative to _text as negative values, which are subtracted from the runtime address of _text to produce the actual address. Positive values are used as they are found in the table. Support for the above is enabled by setting CONFIG_KALLSYMS_TEXT_RELATIVE. Signed-off-by: Ard Biesheuvel <ard.biesheuvel(a)linaro.org> +-----------------------------------------------------------+----------+------------+------------+ | | v4.5-rc1 | f5fc9abc4e | 475f06f1f8 | +-----------------------------------------------------------+----------+------------+------------+ | boot_successes | 41 | 0 | 0 | | boot_failures | 197 | 26 | 21 | | Kernel_panic-not_syncing:Attempted_to_kill_init!exitcode= | 169 | | | | BUG:kernel_test_oversize | 19 | | | | BUG:workqueue_lockup-pool_cpus=#flags=#nice=#stuck_for#s | 1 | | | | IP-Config:Auto-configuration_of_network_failed | 8 | | | | BUG:KASAN:null-ptr-deref_on_address(null) | 0 | 26 | | | BUG:unable_to_handle_kernel | 0 | 26 | 21 | | BUG:KASAN:null-ptr-deref_on_address | 0 | 0 | 21 | | Oops | 0 | 0 | 21 | | RIP:nand_transfer_oob | 0 | 0 | 21 | | Kernel_panic-not_syncing:Fatal_exception | 0 | 0 | 21 | | backtrace:nand_default_bbt | 0 | 0 | 21 | | backtrace:ns_init_module | 0 | 0 | 21 | | backtrace:kernel_init_freeable | 0 | 0 | 21 | +-----------------------------------------------------------+----------+------------+------------+ [ 0.985746] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.988523] CPU: Intel Core Processor (Haswell) (family: 0x6, model: 0x3c, stepping: 0x1) [ 1.015767] ================================================================== [ 1.017570] BUG: KASAN: null-ptr-deref on address (null) [ 1.022344] Read of size 8 by task swapper/1 [ 1.023822] CPU: 0 PID: 1 Comm: swapper Not tainted 4.5.0-rc1-00001-gf5fc9ab #2 [ 1.026918] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 [ 1.030873] ffff880009cef928 ffff880009cef720 ffffffff8cec2d59 ffff880009cef7a8 [ 1.038150] ffffffff8ca1e54a 0000000000000000 0000000000000000 0000000000000286 [ 1.040307] 0000000000000000 0000000000000000 0000000000000000 0000000000000000 [ 1.045503] Call Trace: [ 1.047625] BUG: unable to handle kernel paging request at 000000000b800000 [ 1.051404] IP: Elapsed time: 10 qemu-system-x86_64 -enable-kvm -cpu Haswell,+smep,+smap -kernel /pkg/linux/x86_64-randconfig-s2-01260945/gcc-5/f5fc9abc4e23dccfa1fc35892cf36a416d1e6aee/vmlinuz-4.5.0-rc1-00001-gf5fc9ab -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 link=/kbuild-tests/run-queue/kvm/x86_64-randconfig-s2-01260945/linux-devel:devel-spot-201601260852:f5fc9abc4e23dccfa1fc35892cf36a416d1e6aee:bisect-linux-3/.vmlinuz-f5fc9abc4e23dccfa1fc35892cf36a416d1e6aee-20160126111332-11-kbuild branch=linux-devel/devel-spot-201601260852 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-s2-01260945/gcc-5/f5fc9abc4e23dccfa1fc35892cf36a416d1e6aee/vmlinuz-4.5.0-rc1-00001-gf5fc9ab drbd.minor_count=8' -initrd /osimage/yocto/yocto-minimal-x86_64.cgz -m 256 -smp 1 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -drive file=/fs/sdd1/disk0-yocto-kbuild-12,media=disk,if=virtio -drive file=/fs/sdd1/disk1-yocto-kbuild-12,media=disk,if=virtio -drive file=/fs/sdd1/disk2-yocto-kbuild-12,media=disk,if=virtio -drive file=/fs/sdd1/disk3-yocto-kbuild-12,media=disk,if=virtio -drive file=/fs/sdd1/disk4-yocto-kbuild-12,media=disk,if=virtio -drive file=/fs/sdd1/disk5-yocto-kbuild-12,media=disk,if=virtio -drive file=/fs/sdd1/disk6-yocto-kbuild-12,media=disk,if=virtio -pidfile /dev/shm/kboot/pid-yocto-kbuild-12 -serial file:/dev/shm/kboot/serial-yocto-kbuild-12 -daemonize -display none -monitor null git bisect start 475f06f1f8f01127ba09765815de8ecc1c7ddac2 92e963f50fc74041b5e9e744c330dca48e04f08d -- git bisect bad 0dac8186981af5218ca88bfd681f60c2ec098c23 # 10:07 0- 26 Merge 'linux-review/Andrew-F-Davis/gpio-Add-driver-for-TI-TPIC2810/20160126-001749' into devel-spot-201601260852 git bisect bad fcc833de79d87038b3bca84aaf56a49ed38432ce # 10:14 0- 22 Merge 'tj-libata/for-4.5-fixes' into devel-spot-201601260852 git bisect bad 3df26655d31bf1a08cb79fc557a477798c58f598 # 10:21 0- 24 Merge 'kees/kallsyms' into devel-spot-201601260852 git bisect good 1a6a98cdd87bb7ae865ad493f0d74f74f6bc3442 # 10:28 22+ 24 Merge 'kees/seccomp/tip' into devel-spot-201601260852 git bisect good 0c71a9b4ab531c094926842379533b2351e70c54 # 10:35 22+ 7 Merge 'kees/mmap-clear-setid/delayed' into devel-spot-201601260852 git bisect good 89d395a2358456f37134ad31f60d693ca388d43a # 10:46 20+ 9 Merge 'jeffmerkey/master' into devel-spot-201601260852 git bisect good 0b3246b67c03e12705b4ce632d59e1792f525ba0 # 10:56 21+ 4 Merge 'kees/kspp/lkdtm-atomics' into devel-spot-201601260852 git bisect good 5ddfb2f6510c92b83994bffe932cdb335c231862 # 11:04 22+ 24 Merge 'kees/kaslr/arm' into devel-spot-201601260852 git bisect bad f5fc9abc4e23dccfa1fc35892cf36a416d1e6aee # 11:14 0- 24 kallsyms: add support for relative offsets in kallsyms address table # first bad commit: [f5fc9abc4e23dccfa1fc35892cf36a416d1e6aee] kallsyms: add support for relative offsets in kallsyms address table git bisect good 92e963f50fc74041b5e9e744c330dca48e04f08d # 11:18 64+ 159 Linux 4.5-rc1 # extra tests with DEBUG_INFO git bisect bad f5fc9abc4e23dccfa1fc35892cf36a416d1e6aee # 11:26 0- 66 kallsyms: add support for relative offsets in kallsyms address table # extra tests on HEAD of linux-devel/devel-spot-201601260852 git bisect bad 475f06f1f8f01127ba09765815de8ecc1c7ddac2 # 11:26 0- 21 0day head guard for 'devel-spot-201601260852' # extra tests on tree/branch kees/kallsyms git bisect bad f5fc9abc4e23dccfa1fc35892cf36a416d1e6aee # 11:29 0- 26 kallsyms: add support for relative offsets in kallsyms address table # extra tests with first bad commit reverted git bisect good 6fb38214532554e8baf6fb48ec369c7eca51513b # 11:37 61+ 42 Revert "kallsyms: add support for relative offsets in kallsyms address table" # extra tests on tree/branch linus/master git bisect good 92e963f50fc74041b5e9e744c330dca48e04f08d # 11:41 62+ 195 Linux 4.5-rc1 # extra tests on tree/branch linux-next/master git bisect bad e216cada8e1b4d6c278a2a9af051aeba9b7f1bbe # 11:45 0- 9 Add linux-next specific files for 20160125 This script may reproduce the error. ---------------------------------------------------------------------------- #!/bin/bash kernel=$1 kvm=( qemu-system-x86_64 -enable-kvm -cpu Haswell,+smep,+smap -kernel $kernel -m 256 -smp 1 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -serial stdio -display none -monitor null ) append=( hung_task_panic=1 earlyprintk=ttyS0,115200 systemd.log_level=err debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal root=/dev/ram0 rw drbd.minor_count=8 ) "${kvm[@]}" --append "${append[*]}" ---------------------------------------------------------------------------- --- 0-DAY kernel test infrastructure Open Source Technology Center https://lists.01.org/pipermail/lkp Intel Corporation

4 years, 11 months

1
0
0 / 0

[lkp] [UBSAN] c6d308534a: kmsg.WARNING:lockdep_init_error:lock'report_lock'was_acquired_before_lockdep_init()

by kernel test robot

4 years, 11 months

1
0
0 / 0

[lkp] [3.14 stable regression don't remove from shrink list in select_collect()] a934d2e2ae: WARNING: CPU: 0 PID: 12302 at fs/dcache.c:411 d_lru_del+0x68/0xb0()

by kernel test robot

FYI, we noticed the below changes on https://github.com/0day-ci/linux Shawn-Bohrer/3-14-stable-regression-don-t-remove-from-shrink-list-in-select_collect/20160126-000835 commit a934d2e2aeed3ea22329ffe09d96187b7a52c14e ("3.14 stable regression don't remove from shrink list in select_collect()") [ 29.199848] Fix your initscripts? [ 62.720577] Writes: Total: 2 Max/Min: 0/0 Fail: 0 [ 78.443404] ------------[ cut here ]------------ [ 78.444184] WARNING: CPU: 0 PID: 12302 at fs/dcache.c:411 d_lru_del+0x68/0xb0() [ 78.445569] Modules linked in: [ 78.446210] CPU: 0 PID: 12302 Comm: umount Not tainted 4.5.0-rc1-00001-ga934d2e #2 [ 78.447434] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 [ 78.448816] c1a90980 cebe5df4 c13e62cd cebe5e24 c1049e93 c1a79d08 00000000 0000300e [ 78.450551] c1a90980 0000019b c118bca8 c118bca8 cda80420 cebe5ea8 cda8047c cebe5e34 [ 78.452281] c1049f62 00000009 00000000 cebe5e40 c118bca8 cda80420 cebe5e50 c118bd67 [ 78.453998] Call Trace: [ 78.454507] [<c13e62cd>] dump_stack+0x16/0x19 [ 78.455235] [<c1049e93>] warn_slowpath_common+0x83/0xb0 [ 78.456053] [<c118bca8>] ? d_lru_del+0x68/0xb0 [ 78.456786] [<c118bca8>] ? d_lru_del+0x68/0xb0 [ 78.457502] [<c1049f62>] warn_slowpath_null+0x22/0x30 [ 78.458339] [<c118bca8>] d_lru_del+0x68/0xb0 [ 78.459054] [<c118bd67>] select_collect+0x77/0xd0 [ 78.459809] [<c118edfe>] d_walk+0x10e/0x4f0 [ 78.460499] [<c118f293>] ? shrink_dcache_parent+0x63/0x80 [ 78.461340] [<c118bcf0>] ? d_lru_del+0xb0/0xb0 [ 78.462087] [<c118f293>] shrink_dcache_parent+0x63/0x80 [ 78.462929] [<c118f2c1>] do_one_tree+0x11/0x40 [ 78.463655] [<c118f5ca>] shrink_dcache_for_umount+0x2a/0x90 [ 78.464504] [<c1177300>] generic_shutdown_super+0x20/0xe0 [ 78.465344] [<c1177622>] kill_litter_super+0x22/0x30 [ 78.466136] [<c11777f5>] deactivate_locked_super+0x45/0x70 [ 78.466995] [<c1177c4f>] deactivate_super+0x6f/0x80 [ 78.467774] [<c1196c64>] cleanup_mnt+0x34/0x80 [ 78.468493] [<c1196d00>] __cleanup_mnt+0x10/0x20 [ 78.469251] [<c1066f77>] task_work_run+0x57/0xa0 [ 78.470008] [<c1001305>] exit_to_usermode_loop+0xb5/0xc0 [ 78.470843] [<c1001b07>] do_syscall_32_irqs_on+0xc7/0x140 [ 78.471696] [<c1834bc1>] entry_INT80_32+0x31/0x31 [ 78.472460] ---[ end trace 71d5438a8e8ce00a ]--- [ 78.473234] ------------[ cut here ]------------ Thanks, Kernel Test Robot

4 years, 11 months

1
0
0 / 0

[lkp] [ksm] 40e318e509: ltp.ksm01.fail

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 40e318e509d2c9f3cdb00ef32d2c14b9868af16b ("ksm: introduce ksm_max_page_sharing per page deduplication limit") We found LKP KSM test case failed for your patch. ========================================================================================= compiler/kconfig/rootfs/tbox_group/test/testcase: gcc-5/x86_64-nfsroot/debian-x86_64-2015-02-07.cgz/vm-lkp-wsx03-4G/mm_functional_positive/otc_kernel_qa-ts_ltp_ddt commit: 77505a33e539477f1fbe5e6136df5b265f39cccf 40e318e509d2c9f3cdb00ef32d2c14b9868af16b 77505a33e539477f 40e318e509d2c9f3cdb00ef32d ---------------- -------------------------- fail:runs %reproduction fail:runs | | | :4 100% 4:4 otc_kernel_qa-ts_ltp_ddt.ksm01.fail :4 100% 4:4 otc_kernel_qa-ts_ltp_ddt.ksm01_1.fail vm-lkp-wsx03-4G: qemu-system-x86_64 -enable-kvm -cpu host 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

4 years, 11 months

2
1
0 / 0

[lkp] [base] 9ea71534ad: kmsg.Diva:No_ISA, ISAPNP_or_PCI_card_found

by kernel test robot

FYI, we noticed the below changes on https://github.com/0day-ci/linux William-Breathitt-Gray/base-isa-Remove-X86_32-dependency/20160123-003152 commit 9ea71534addd0753b37b884b5d8a5fa54bfd95ea ("base: isa: Remove X86_32 dependency") With your commit, there are new message as follow in kernel log. [ 5.405484] Diva: No ISA, ISAPNP or PCI card found [ 5.405484] Diva: No ISA, ISAPNP or PCI card found Thanks, Ying Huang

4 years, 11 months

2
1
0 / 0

[lkp] [locking/mutexes] cb4bbc457b: -40.0% unixbench.score

by kernel test robot

FYI, we noticed the below changes on https://github.com/0day-ci/linux Ding-Tianhong/locking-mutexes-don-t-spin-on-owner-when-wait-list-is-not-NULL/20160121-173317 commit cb4bbc457bfed6194ffab1b10c7be73b3f16ca2d ("locking/mutexes: don't spin on owner when wait list is not NULL.") ========================================================================================= compiler/cpufreq_governor/kconfig/nr_task/rootfs/tbox_group/test/testcase: gcc-4.9/performance/x86_64-rhel/100%/debian-x86_64-2015-02-07.cgz/lkp-ivb-d01/fstime/unixbench commit: f249f40fd783437189a2ac2aea88fe4f1dd2ab37 cb4bbc457bfed6194ffab1b10c7be73b3f16ca2d f249f40fd7834371 cb4bbc457bfed6194ffab1b10c ---------------- -------------------------- %stddev %change %stddev \ | \ 6088 ± 0% -40.0% 3653 ± 1% unixbench.score 9363 ± 10% -59.7% 3773 ± 75% unixbench.time.involuntary_context_switches 558.75 ± 0% -67.6% 181.25 ± 1% unixbench.time.percent_of_cpu_this_job_got 795.37 ± 0% -68.5% 250.73 ± 1% unixbench.time.system_time 440962 ± 8% +9552.3% 42563214 ± 2% unixbench.time.voluntary_context_switches 505.14 ± 1% +117.3% 1097 ± 0% uptime.idle 21682 ± 5% +53.0% 33173 ± 4% softirqs.RCU 19435 ± 5% +350.6% 87578 ± 1% softirqs.SCHED 420271 ± 0% -70.0% 125973 ± 1% softirqs.TIMER 3.75 ± 11% -73.3% 1.00 ± 0% vmstat.procs.r 7011 ± 8% +8194.5% 581586 ± 1% vmstat.system.cs 5674 ± 0% +24.1% 7044 ± 8% vmstat.system.in 18847 ±137% -82.2% 3349 ±147% latency_stats.avg.wait_on_page_bit.__filemap_fdatawait_range.filemap_fdatawait_keep_errors.sync_inodes_sb.sync_inodes_one_sb.iterate_supers.sys_sync.entry_SYSCALL_64_fastpath 20617 ± 8% +50440.7% 10420359 ± 6% latency_stats.hits.generic_file_write_iter.__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath 420571 ±140% -84.9% 63576 ±164% latency_stats.max.wait_on_page_bit.__filemap_fdatawait_range.filemap_fdatawait_keep_errors.sync_inodes_sb.sync_inodes_one_sb.iterate_supers.sys_sync.entry_SYSCALL_64_fastpath 4406135 ± 4% +11665.3% 5.184e+08 ± 0% latency_stats.sum.generic_file_write_iter.__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath 430234 ±138% -82.8% 73849 ±146% latency_stats.sum.wait_on_page_bit.__filemap_fdatawait_range.filemap_fdatawait_keep_errors.sync_inodes_sb.sync_inodes_one_sb.iterate_supers.sys_sync.entry_SYSCALL_64_fastpath 9363 ± 10% -59.7% 3773 ± 75% time.involuntary_context_switches 558.75 ± 0% -67.6% 181.25 ± 1% time.percent_of_cpu_this_job_got 795.37 ± 0% -68.5% 250.73 ± 1% time.system_time 12.81 ± 1% -13.8% 11.04 ± 2% time.user_time 440962 ± 8% +9552.3% 42563214 ± 2% time.voluntary_context_switches 69.85 ± 0% -65.3% 24.25 ± 1% turbostat.%Busy 2578 ± 0% -65.3% 896.00 ± 1% turbostat.Avg_MHz 1.08 ± 27% +4194.0% 46.59 ± 0% turbostat.CPU%c1 0.15 ± 63% +313.6% 0.61 ± 26% turbostat.CPU%c3 29.14 ± 0% -25.1% 21.81 ± 0% turbostat.CorWatt 70.00 ± 3% -7.5% 64.75 ± 2% turbostat.PkgTmp 35.02 ± 0% -21.0% 27.66 ± 0% turbostat.PkgWatt 3977771 ± 42% +4729.4% 1.921e+08 ± 7% cpuidle.C1-IVB.time 27974 ± 4% +23907.8% 6716112 ± 10% cpuidle.C1-IVB.usage 1647001 ± 22% +15982.9% 2.649e+08 ± 0% cpuidle.C1E-IVB.time 4743 ± 7% +1.2e+05% 5603747 ± 7% cpuidle.C1E-IVB.usage 1647557 ± 50% +1186.1% 21188962 ± 31% cpuidle.C3-IVB.time 3255 ± 10% +5794.9% 191907 ± 24% cpuidle.C3-IVB.usage 3.437e+08 ± 1% +16.3% 3.998e+08 ± 2% cpuidle.C6-IVB.time 15488 ± 4% +1524.2% 251555 ± 18% cpuidle.C6-IVB.usage 2645730 ± 26% +151.8% 6662843 ± 29% cpuidle.POLL.time 152570 ± 12% +195.7% 451112 ± 20% cpuidle.POLL.usage 38345 ± 1% -58.7% 15844 ± 3% sched_debug.cfs_rq:/.exec_clock.0 37626 ± 0% -61.9% 14342 ± 1% sched_debug.cfs_rq:/.exec_clock.1 37864 ± 1% -61.7% 14486 ± 2% sched_debug.cfs_rq:/.exec_clock.2 38132 ± 1% -62.1% 14438 ± 1% sched_debug.cfs_rq:/.exec_clock.3 37376 ± 0% -72.4% 10318 ± 4% sched_debug.cfs_rq:/.exec_clock.4 37402 ± 0% -69.9% 11244 ± 1% sched_debug.cfs_rq:/.exec_clock.5 37433 ± 0% -70.4% 11089 ± 3% sched_debug.cfs_rq:/.exec_clock.6 37407 ± 0% -69.8% 11282 ± 1% sched_debug.cfs_rq:/.exec_clock.7 37698 ± 0% -65.8% 12880 ± 1% sched_debug.cfs_rq:/.exec_clock.avg 38910 ± 0% -59.1% 15902 ± 2% sched_debug.cfs_rq:/.exec_clock.max 37349 ± 0% -72.4% 10297 ± 4% sched_debug.cfs_rq:/.exec_clock.min 500.91 ± 17% +310.6% 2056 ± 2% sched_debug.cfs_rq:/.exec_clock.stddev 65.59 ± 7% +28.8% 84.51 ± 9% sched_debug.cfs_rq:/.load.stddev 128.00 ± 25% -55.5% 57.00 ± 45% sched_debug.cfs_rq:/.load_avg.0 92.75 ± 54% +57.1% 145.75 ± 27% sched_debug.cfs_rq:/.load_avg.3 199.25 ± 27% -71.3% 57.25 ± 50% sched_debug.cfs_rq:/.load_avg.4 146.50 ± 83% -95.1% 7.25 ± 38% sched_debug.cfs_rq:/.load_avg.6 119.26 ± 14% -33.4% 79.48 ± 7% sched_debug.cfs_rq:/.load_avg.avg 263.33 ± 22% -30.1% 184.08 ± 7% sched_debug.cfs_rq:/.load_avg.max 44.67 ± 6% -87.5% 5.58 ± 46% sched_debug.cfs_rq:/.load_avg.min 303665 ± 0% -75.2% 75238 ± 1% sched_debug.cfs_rq:/.min_vruntime.0 302562 ± 0% -75.8% 73288 ± 2% sched_debug.cfs_rq:/.min_vruntime.1 302728 ± 0% -75.8% 73154 ± 2% sched_debug.cfs_rq:/.min_vruntime.2 303591 ± 0% -75.8% 73426 ± 1% sched_debug.cfs_rq:/.min_vruntime.3 301962 ± 0% -81.6% 55478 ± 5% sched_debug.cfs_rq:/.min_vruntime.4 302458 ± 0% -80.5% 59083 ± 1% sched_debug.cfs_rq:/.min_vruntime.5 302281 ± 0% -80.7% 58358 ± 3% sched_debug.cfs_rq:/.min_vruntime.6 302717 ± 0% -80.4% 59383 ± 1% sched_debug.cfs_rq:/.min_vruntime.7 302745 ± 0% -78.2% 65926 ± 2% sched_debug.cfs_rq:/.min_vruntime.avg 304288 ± 0% -75.2% 75418 ± 2% sched_debug.cfs_rq:/.min_vruntime.max 301709 ± 0% -81.7% 55237 ± 5% sched_debug.cfs_rq:/.min_vruntime.min 795.06 ± 12% +921.0% 8117 ± 2% sched_debug.cfs_rq:/.min_vruntime.stddev 0.44 ± 10% -33.3% 0.29 ± 10% sched_debug.cfs_rq:/.nr_running.avg 42.00 ± 5% -93.5% 2.75 ± 30% sched_debug.cfs_rq:/.runnable_load_avg.2 44.75 ± 6% -89.9% 4.50 ± 72% sched_debug.cfs_rq:/.runnable_load_avg.3 41.25 ± 4% -92.7% 3.00 ± 62% sched_debug.cfs_rq:/.runnable_load_avg.5 43.50 ± 6% -96.0% 1.75 ± 74% sched_debug.cfs_rq:/.runnable_load_avg.6 33.50 ± 48% -96.3% 1.25 ± 87% sched_debug.cfs_rq:/.runnable_load_avg.7 38.30 ± 8% -91.1% 3.41 ± 12% sched_debug.cfs_rq:/.runnable_load_avg.avg 51.25 ± 2% -78.2% 11.17 ± 19% sched_debug.cfs_rq:/.runnable_load_avg.max 13.90 ± 39% -74.1% 3.60 ± 16% sched_debug.cfs_rq:/.runnable_load_avg.stddev -1707 ±-24% +1057.5% -19760 ± -8% sched_debug.cfs_rq:/.spread0.4 -1211 ±-89% +1233.8% -16156 ± -6% sched_debug.cfs_rq:/.spread0.5 -1388 ±-66% +1116.1% -16881 ± -8% sched_debug.cfs_rq:/.spread0.6 -953.45 ±-133% +1563.1% -15856 ± -6% sched_debug.cfs_rq:/.spread0.7 -922.60 ±-87% +909.4% -9312 ± -3% sched_debug.cfs_rq:/.spread0.avg -1960 ±-39% +920.5% -20002 ± -7% sched_debug.cfs_rq:/.spread0.min 795.42 ± 12% +920.6% 8117 ± 2% sched_debug.cfs_rq:/.spread0.stddev 461.50 ± 2% -55.2% 206.75 ± 6% sched_debug.cfs_rq:/.util_avg.0 450.25 ± 6% -34.0% 297.25 ± 2% sched_debug.cfs_rq:/.util_avg.1 497.25 ± 8% -42.1% 287.75 ± 19% sched_debug.cfs_rq:/.util_avg.2 471.75 ± 13% -30.1% 329.75 ± 15% sched_debug.cfs_rq:/.util_avg.3 491.75 ± 11% -70.3% 146.00 ± 42% sched_debug.cfs_rq:/.util_avg.4 414.00 ± 4% -49.3% 209.75 ± 44% sched_debug.cfs_rq:/.util_avg.5 490.25 ± 28% -66.7% 163.25 ± 28% sched_debug.cfs_rq:/.util_avg.6 463.25 ± 9% -49.2% 235.25 ± 18% sched_debug.cfs_rq:/.util_avg.7 467.84 ± 3% -49.8% 234.84 ± 2% sched_debug.cfs_rq:/.util_avg.avg 594.42 ± 14% -32.6% 400.50 ± 8% sched_debug.cfs_rq:/.util_avg.max 383.00 ± 3% -71.7% 108.33 ± 22% sched_debug.cfs_rq:/.util_avg.min 429518 ± 15% +37.1% 588985 ± 12% sched_debug.cpu.avg_idle.5 23.41 ±114% -90.7% 2.17 ± 56% sched_debug.cpu.clock.stddev 23.41 ±114% -90.7% 2.17 ± 56% sched_debug.cpu.clock_task.stddev 40.75 ± 6% -87.7% 5.00 ± 92% sched_debug.cpu.cpu_load[0].2 44.00 ± 6% -94.3% 2.50 ±173% sched_debug.cpu.cpu_load[0].3 32.50 ± 49% -91.5% 2.75 ± 78% sched_debug.cpu.cpu_load[0].4 40.25 ± 5% -93.8% 2.50 ± 91% sched_debug.cpu.cpu_load[0].5 43.50 ± 8% -93.9% 2.67 ± 63% sched_debug.cpu.cpu_load[0].6 35.03 ± 6% -92.0% 2.80 ± 33% sched_debug.cpu.cpu_load[0].avg 51.58 ± 2% -75.9% 12.42 ± 33% sched_debug.cpu.cpu_load[0].max 18.26 ± 10% -76.1% 4.37 ± 30% sched_debug.cpu.cpu_load[0].stddev 32.50 ± 32% -97.7% 0.75 ± 57% sched_debug.cpu.cpu_load[1].0 36.25 ± 24% -91.0% 3.25 ± 50% sched_debug.cpu.cpu_load[1].1 40.75 ± 5% -90.8% 3.75 ± 98% sched_debug.cpu.cpu_load[1].2 43.00 ± 4% -95.3% 2.00 ±173% sched_debug.cpu.cpu_load[1].3 37.00 ± 22% -95.3% 1.75 ± 62% sched_debug.cpu.cpu_load[1].4 40.50 ± 3% -95.7% 1.75 ± 74% sched_debug.cpu.cpu_load[1].5 42.75 ± 7% -95.3% 2.00 ±108% sched_debug.cpu.cpu_load[1].6 28.50 ± 53% -98.2% 0.50 ±173% sched_debug.cpu.cpu_load[1].7 38.00 ± 2% -94.2% 2.19 ± 28% sched_debug.cpu.cpu_load[1].avg 49.17 ± 1% -81.5% 9.08 ± 39% sched_debug.cpu.cpu_load[1].max 16.08 ± 39% -100.0% 0.00 ± -1% sched_debug.cpu.cpu_load[1].min 11.03 ± 15% -72.3% 3.05 ± 36% sched_debug.cpu.cpu_load[1].stddev 37.50 ± 13% -98.0% 0.75 ± 57% sched_debug.cpu.cpu_load[2].0 39.00 ± 12% -92.9% 2.75 ± 39% sched_debug.cpu.cpu_load[2].1 40.75 ± 5% -92.6% 3.00 ± 97% sched_debug.cpu.cpu_load[2].2 42.25 ± 3% -96.4% 1.50 ±173% sched_debug.cpu.cpu_load[2].3 39.25 ± 9% -96.8% 1.25 ± 66% sched_debug.cpu.cpu_load[2].4 40.50 ± 3% -96.3% 1.50 ± 57% sched_debug.cpu.cpu_load[2].5 42.50 ± 6% -97.1% 1.25 ±131% sched_debug.cpu.cpu_load[2].6 33.50 ± 30% -97.8% 0.75 ±110% sched_debug.cpu.cpu_load[2].7 39.82 ± 1% -95.3% 1.86 ± 26% sched_debug.cpu.cpu_load[2].avg 48.00 ± 2% -85.1% 7.17 ± 41% sched_debug.cpu.cpu_load[2].max 26.50 ± 21% -100.0% 0.00 ± -1% sched_debug.cpu.cpu_load[2].min 6.77 ± 20% -64.8% 2.38 ± 38% sched_debug.cpu.cpu_load[2].stddev 40.00 ± 7% -96.9% 1.25 ± 34% sched_debug.cpu.cpu_load[3].0 40.50 ± 7% -93.8% 2.50 ± 34% sched_debug.cpu.cpu_load[3].1 40.25 ± 2% -95.0% 2.00 ±122% sched_debug.cpu.cpu_load[3].2 42.25 ± 2% -97.0% 1.25 ±131% sched_debug.cpu.cpu_load[3].3 40.25 ± 5% -97.5% 1.00 ± 70% sched_debug.cpu.cpu_load[3].4 40.00 ± 3% -98.1% 0.75 ±110% sched_debug.cpu.cpu_load[3].5 42.25 ± 5% -96.8% 1.33 ±141% sched_debug.cpu.cpu_load[3].6 36.75 ± 18% -98.0% 0.75 ±110% sched_debug.cpu.cpu_load[3].7 40.66 ± 1% -96.0% 1.65 ± 25% sched_debug.cpu.cpu_load[3].avg 47.25 ± 2% -85.9% 6.67 ± 34% sched_debug.cpu.cpu_load[3].max 32.58 ± 10% -100.0% 0.00 ± -1% sched_debug.cpu.cpu_load[3].min 4.44 ± 20% -51.2% 2.17 ± 32% sched_debug.cpu.cpu_load[3].stddev 42.25 ± 5% -97.6% 1.00 ± 70% sched_debug.cpu.cpu_load[4].0 40.50 ± 4% -93.8% 2.50 ± 34% sched_debug.cpu.cpu_load[4].1 40.25 ± 2% -96.3% 1.50 ±110% sched_debug.cpu.cpu_load[4].2 42.25 ± 2% -97.0% 1.25 ±131% sched_debug.cpu.cpu_load[4].3 40.75 ± 4% -96.3% 1.50 ± 74% sched_debug.cpu.cpu_load[4].4 39.50 ± 2% -98.7% 0.50 ±173% sched_debug.cpu.cpu_load[4].5 42.00 ± 4% -96.0% 1.67 ±141% sched_debug.cpu.cpu_load[4].6 39.50 ± 11% -98.1% 0.75 ±110% sched_debug.cpu.cpu_load[4].7 41.25 ± 0% -96.1% 1.60 ± 17% sched_debug.cpu.cpu_load[4].avg 47.17 ± 3% -86.4% 6.42 ± 26% sched_debug.cpu.cpu_load[4].max 35.92 ± 6% -100.0% 0.00 ± -1% sched_debug.cpu.cpu_load[4].min 3.44 ± 23% -37.0% 2.17 ± 22% sched_debug.cpu.cpu_load[4].stddev 1059 ± 28% -64.3% 378.00 ± 71% sched_debug.cpu.curr->pid.2 857.25 ± 26% -25.6% 638.00 ± 13% sched_debug.cpu.curr->pid.5 757.75 ± 10% -44.7% 419.35 ± 25% sched_debug.cpu.curr->pid.avg 38227 ± 0% -14.3% 32746 ± 4% sched_debug.cpu.nr_load_updates.4 38089 ± 0% -13.4% 32980 ± 0% sched_debug.cpu.nr_load_updates.5 38315 ± 0% -15.5% 32362 ± 2% sched_debug.cpu.nr_load_updates.6 38187 ± 0% -11.4% 33843 ± 5% sched_debug.cpu.nr_load_updates.7 38058 ± 0% -16.8% 31648 ± 2% sched_debug.cpu.nr_load_updates.min 2260 ± 12% +104.4% 4619 ± 8% sched_debug.cpu.nr_load_updates.stddev 0.46 ± 11% -27.3% 0.33 ± 23% sched_debug.cpu.nr_running.avg 60237 ± 11% +7594.3% 4634892 ± 11% sched_debug.cpu.nr_switches.0 55226 ± 12% +8157.3% 4560209 ± 5% sched_debug.cpu.nr_switches.1 55047 ± 14% +7797.4% 4347263 ± 3% sched_debug.cpu.nr_switches.2 57289 ± 14% +7469.6% 4336552 ± 7% sched_debug.cpu.nr_switches.3 43239 ± 13% +7701.1% 3373163 ± 8% sched_debug.cpu.nr_switches.4 44998 ± 7% +7390.4% 3370525 ± 5% sched_debug.cpu.nr_switches.5 44707 ± 19% +7785.8% 3525502 ± 2% sched_debug.cpu.nr_switches.6 42020 ± 12% +7500.9% 3193917 ± 7% sched_debug.cpu.nr_switches.7 50345 ± 11% +7681.7% 3917753 ± 2% sched_debug.cpu.nr_switches.avg 64527 ± 8% +7405.8% 4843308 ± 7% sched_debug.cpu.nr_switches.max 40279 ± 11% +7577.4% 3092415 ± 6% sched_debug.cpu.nr_switches.min 8478 ± 11% +7117.2% 611929 ± 25% sched_debug.cpu.nr_switches.stddev 0.05 ± 34% +320.0% 0.22 ± 24% sched_debug.cpu.nr_uninterruptible.avg 95009 ± 7% +4814.6% 4669278 ± 11% sched_debug.cpu.sched_count.0 55259 ± 12% +8152.5% 4560280 ± 5% sched_debug.cpu.sched_count.1 55076 ± 14% +7793.3% 4347342 ± 3% sched_debug.cpu.sched_count.2 57315 ± 14% +7466.2% 4336636 ± 7% sched_debug.cpu.sched_count.3 43271 ± 13% +7695.6% 3373239 ± 8% sched_debug.cpu.sched_count.4 45025 ± 7% +7386.0% 3370599 ± 5% sched_debug.cpu.sched_count.5 44736 ± 19% +7780.8% 3525576 ± 2% sched_debug.cpu.sched_count.6 42050 ± 12% +7495.7% 3194026 ± 7% sched_debug.cpu.sched_count.7 54718 ± 10% +7067.8% 3922122 ± 2% sched_debug.cpu.sched_count.avg 95009 ± 7% +5021.3% 4865699 ± 7% sched_debug.cpu.sched_count.max 40308 ± 11% +7572.0% 3092488 ± 6% sched_debug.cpu.sched_count.min 17263 ± 4% +3482.1% 618390 ± 25% sched_debug.cpu.sched_count.stddev 28215 ± 11% +8107.6% 2315778 ± 11% sched_debug.cpu.sched_goidle.0 25818 ± 10% +8729.5% 2279654 ± 5% sched_debug.cpu.sched_goidle.1 26437 ± 16% +8118.1% 2172611 ± 3% sched_debug.cpu.sched_goidle.2 25648 ± 11% +8348.5% 2166947 ± 7% sched_debug.cpu.sched_goidle.3 20962 ± 13% +7940.0% 1685351 ± 8% sched_debug.cpu.sched_goidle.4 21369 ± 8% +7784.9% 1684972 ± 5% sched_debug.cpu.sched_goidle.5 21662 ± 20% +8035.7% 1762354 ± 2% sched_debug.cpu.sched_goidle.6 20316 ± 12% +7752.8% 1595435 ± 7% sched_debug.cpu.sched_goidle.7 23804 ± 11% +8125.0% 1957888 ± 2% sched_debug.cpu.sched_goidle.avg 30125 ± 8% +7935.5% 2420701 ± 7% sched_debug.cpu.sched_goidle.max 19379 ± 11% +7877.2% 1545896 ± 6% sched_debug.cpu.sched_goidle.min 3658 ± 18% +8263.0% 305987 ± 25% sched_debug.cpu.sched_goidle.stddev 30798 ± 9% +7479.9% 2334453 ± 5% sched_debug.cpu.ttwu_count.0 28062 ± 8% +8128.7% 2309129 ± 3% sched_debug.cpu.ttwu_count.1 25856 ± 17% +8831.7% 2309423 ± 1% sched_debug.cpu.ttwu_count.2 24822 ± 18% +9337.9% 2342745 ± 4% sched_debug.cpu.ttwu_count.3 21728 ± 16% +5352.6% 1184732 ± 2% sched_debug.cpu.ttwu_count.4 24081 ± 18% +7105.7% 1735222 ± 11% sched_debug.cpu.ttwu_count.5 24395 ± 7% +6596.3% 1633561 ± 3% sched_debug.cpu.ttwu_count.6 21052 ± 17% +8550.8% 1821237 ± 9% sched_debug.cpu.ttwu_count.7 25099 ± 11% +7704.1% 1958813 ± 2% sched_debug.cpu.ttwu_count.avg 31674 ± 9% +7601.4% 2439385 ± 2% sched_debug.cpu.ttwu_count.max 19256 ± 14% +6050.5% 1184359 ± 3% sched_debug.cpu.ttwu_count.min 4109 ± 16% +10090.0% 418801 ± 9% sched_debug.cpu.ttwu_count.stddev 7112 ± 5% -26.5% 5226 ± 37% sched_debug.cpu.ttwu_local.0 1112 ± 17% -43.9% 624.50 ± 14% sched_debug.cpu.ttwu_local.6 869.42 ± 7% -38.4% 535.83 ± 1% sched_debug.cpu.ttwu_local.min lkp-ivb-d01: Ivy Bridge Memory: 16G vm-kbuild-yocto-x86_64: qemu-system-x86_64 -enable-kvm -cpu SandyBridge Memory: 320M uptime.idle 1200 ++-------------------------------------------------------------------+ OO OO O O OOO OO OOO O | 1100 ++ O O OO O OO OO OOO | 1000 ++ | | | 900 ++ | | | 800 ++ | | | 700 ++ | 600 ++ * | | :+ | 500 **.**.**.**.***.**.**.***.**.**.**.***.**.**.***.**.**.**.***.* **.** | | 400 ++-------------------------------------------------------------------+ 1.2e+07 ++----------------------------------------------------------------+ | OO OO | 1e+07 ++ O O | | O O | | OO | 8e+06 ++ O | | OO O | 6e+06 O+ O O O O | |O O O O OO | 4e+06 ++ O O | | | | | 2e+06 ++ | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** 6e+08 ++------------------------------------------------------------------+ OO OO O OO OO O OO O | 5e+08 ++ O OOO O O OO OO OOO O | | | | | 4e+08 ++ | | | 3e+08 ++ | | | 2e+08 ++ | | | | | 1e+08 ++ | | | 0 **-**-**-***-**-***-**-**-***-**-***-**-**-***-**-***-**-**-***-**-** softirqs.TIMER 450000 ++-----------------------------------------------------------------+ **.**.***.**.***.**.***.**.***.**.***.***.**.***.**.***.**.***.**.** 400000 ++ | | | 350000 ++ | | | 300000 ++ | | | 250000 ++ | | | 200000 ++ | | | 150000 O+ OO OOO OO O OO OO | |O OO O OO OOO OO OOO | 100000 ++-----------------------------------------------------------------+ softirqs.SCHED 100000 ++------O---------O-O----------------------------------------------+ OO OO OO OO OOO O O | 90000 ++ O OO OOO OO OOO | 80000 ++ | | | 70000 ++ | 60000 ++ | | | 50000 ++ | 40000 ++ | | | 30000 ++ | 20000 +*.* .** .**.* .* *.* .** .**. **. **. *.* *. *.* .**. **. .** * * * ** *.** * * * * * * * ** * ** | 10000 ++-----------------------------------------------------------------+ cpuidle.C1-IVB.time 2.5e+08 ++----------------------------------------------------------------+ | | | O O | 2e+08 ++ O O O O | | | | O O | 1.5e+08 ++ O | | O | 1e+08 OO OO OO OOO O | | O O O | | O OOO | 5e+07 ++ | | | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** cpuidle.C1-IVB.usage 8e+06 ++------------------------------------------------------------------+ | O O | 7e+06 ++ O O O O | 6e+06 ++ | | O O | 5e+06 ++ | | | 4e+06 ++ | | O | 3e+06 ++ O | 2e+06 O+ O O | |O OO O OO OO OO OO O | 1e+06 ++ O | | | 0 **-**-**-***-**-***-**-**-***-**-***-**-**-***-**-***-**-**-***-**-** cpuidle.C1E-IVB.time 3.5e+08 ++----------------------------------------------------------------+ | O | 3e+08 ++ O O | | O OO O O OOO OOO O | 2.5e+08 OO OO O O O OO O | | O | 2e+08 ++ O | | | 1.5e+08 ++ | | | 1e+08 ++ | | | 5e+07 ++ | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** cpuidle.C1E-IVB.usage 7e+06 ++------------------------------------------------------------------+ | | 6e+06 ++ O O OO | | O O | 5e+06 ++ O O | | O | 4e+06 ++ OOO O | | O | 3e+06 ++ O | OO OO OO O OO O | 2e+06 ++ O O | | | 1e+06 ++ | | | 0 **-**-**-***-**-***-**-**-***-**-***-**-**-***-**-***-**-**-***-**-** turbostat.Avg_MHz 2800 ++-------------------------------------------------------------------+ 2600 ++ .* *. *. *. *.**.**. **. *. | **.**.** *.***.**.* ***.**.* **.***.**.* ***.* * * .* ** 2400 ++ * | 2200 ++ | | | 2000 ++ | 1800 ++ | 1600 ++ | | | 1400 ++ | 1200 ++ | | | 1000 ++ OO O O OO OO OOO | 800 OO-OO-OO-----OO-OO-OO-OOO-O------------------------------------------+ turbostat._Busy 75 ++---------------------------------------------------------------------+ 70 **.**.**.**.**.**.**.**.**.**.**.**.***.**.**.**. *.**.**.**.**.* **.** | * :+ | 65 ++ * | 60 ++ | 55 ++ | 50 ++ | | | 45 ++ | 40 ++ | 35 ++ | 30 ++ | | | 25 OO OO OO OO OO OO OO OO OO OO OO OO OOO | 20 ++---------------------------------------------------------------------+ turbostat.CPU_c1 50 ++---------------------------------------------------------------------+ 45 OO OO O OO OO OO OO OO OO OO OO OO OOO | | O | 40 ++ | 35 ++ | | | 30 ++ | 25 ++ | 20 ++ | | | 15 ++ | 10 ++ | | | 5 ++ | 0 **-**-**-**-**-**-**-**-**-**-**-**-***-**-**-**-**-**-**-**-**-**-**-** turbostat.PkgWatt 36 ++-------------------*------------------------------**-*---------------+ 35 **.**.**.**.**.**.** *.**.**.**.**.***.**.**.** + *.**.**.* *.** | + * : * | 34 ++ * :: | 33 ++ :: | | * | 32 ++ | 31 ++ | 30 ++ | | | 29 ++ | 28 ++ O O O O O | OO OO OO O O O OO O OO OO O OO O O | 27 ++ O | 26 ++---------------------------------------------------------------------+ turbostat.CorWatt 31 ++---------------------------------------------------------------------+ 30 ++ * | **.**.**.**.**.**.**.**.**.**.**.**.***.**.**.**. + *.**.**.**.* *.** 29 ++ ** : * | 28 ++ :: | 27 ++ :: | 26 ++ * | | | 25 ++ | 24 ++ | 23 ++ | 22 ++ O O O O | OO OO OO O O OO OO O OO OO O OO O O | 21 ++ O | 20 ++---------------------------------------------------------------------+ unixbench.score 6500 ++-------------------------------------------------------------------+ **. *. *.* .** .** *. *. *. .***.**. .** 6000 ++ **.**.**.** * * *.**.**.** *.**.**.** * * ** ** | 5500 ++ | | | 5000 ++ | | | 4500 ++ | | | 4000 ++ | 3500 ++ O OO OO OOO | | O O | 3000 O+ OO O O OO OO OOO O | |O O OO | 2500 ++-------------------------------------------------------------------+ unixbench.time.system_time 800 **-**-**-**-**-**-***-**-**-**-**-***-**-**-**-**-**-***-**-**-**-**-** | | 700 ++ | | | | | 600 ++ | | | 500 ++ | | | 400 ++ | | | | | 300 ++ | OO OO OO OO OO O OOO OO OO OO OO OOO O | 200 ++--------------O-----------------------------------------------------+ unixbench.time.percent_of_cpu_this_job_got 600 ++--------------------------------------------------------------------+ **.**.**.**.**.**.***.**.**.**.**.***.**.**.**.* .**.***.**.**.* **.** 550 ++ * :+ | 500 ++ * | | | 450 ++ | 400 ++ | | | 350 ++ | 300 ++ | | | 250 ++ | 200 ++ | | O OO O OO OO OO OOO O | 150 OO-OO-O------O-OO-O---OO-OO-------------------------------------------+ unixbench.time.voluntary_context_switches 6e+07 ++----O---------O---------------------------------------------------+ OO OO OO OO O | 5e+07 ++ O O OO O | | OO O | | OO OO OOO O | 4e+07 ++ | | | 3e+07 ++ | | | 2e+07 ++ | | | | | 1e+07 ++ | | | 0 **-**-**-***-**-***-**-**-***-**-***-**-**-***-**-***-**-**-***-**-** time.system_time 800 **-**-**-**-**-**-***-**-**-**-**-***-**-**-**-**-**-***-**-**-**-**-** | | 700 ++ | | | | | 600 ++ | | | 500 ++ | | | 400 ++ | | | | | 300 ++ | OO OO OO OO OO O OOO OO OO OO OO OOO O | 200 ++--------------O-----------------------------------------------------+ time.percent_of_cpu_this_job_got 600 ++--------------------------------------------------------------------+ **.**.**.**.**.**.***.**.**.**.**.***.**.**.**.* .**.***.**.**.* **.** 550 ++ * :+ | 500 ++ * | | | 450 ++ | 400 ++ | | | 350 ++ | 300 ++ | | | 250 ++ | 200 ++ | | O OO O OO OO OO OOO O | 150 OO-OO-O------O-OO-O---OO-OO-------------------------------------------+ time.voluntary_context_switches 6e+07 ++----O---------O---------------------------------------------------+ OO OO OO OO O | 5e+07 ++ O O OO O | | OO O | | OO OO OOO O | 4e+07 ++ | | | 3e+07 ++ | | | 2e+07 ++ | | | | | 1e+07 ++ | | | 0 **-**-**-***-**-***-**-**-***-**-***-**-**-***-**-***-**-**-***-**-** vmstat.procs.r 4 **----**-**-**-**-**--**----**-**-***----**-**-*--**-***-**-**-*--**-*+ |: : : :: : : : : : : : :| 3.5 ++: : : : : : : : : : : : :| | : : :: : : : : :: :: | | :: : :: :: : : | 3 ++ ** * ** ** * * * | | 2.5 ++ | | | 2 ++ | | | | | 1.5 ++ | | | 1 OO-OO-OO-OO-OO-OO-OOO-OO-OO-OO-OO-OOO-O-------------------------------+ vmstat.system.cs 900000 ++-----------------------------------------------------------------+ | O | 800000 OO OO OOO OO | 700000 ++ OO O O O | | OO O O | 600000 ++ OOO OO OOO | 500000 ++ | | | 400000 ++ | 300000 ++ | | | 200000 ++ | 100000 ++ | | | 0 **-**-***-**-***-**-***-**-***-**-***-***-**-***-**-***-**-***-**-** sched_debug.cpu.cpu_load_2_.0 70 ++---------------------------------------------------------------------+ | * * * | 60 ++ : :: :: | * : : * :: .* :: | 50 ++: *. .* : : * * : : * *. * | | : **.** : *.* :: *.**.* :: *.**.* * .* .* :+ * 40 ++: *.**.* :: : *.** * *.* ** *. | |: : : + : *| 30 +* * * * | | | 20 ++ | | | 10 ++ | | O O O O | 0 OO-O---O-OO-OO-OO-O--OO-OO--O-OO-OO-OOO--------------------------------+ sched_debug.cpu.cpu_load_3_.0 70 ++-*---------------------*------------*--------------------------------+ | : :: :: | 60 ++: : :: :: | * : : * : : .* : : | 50 ++: *. .* ::: * * :: * *. * * | |:: **.** : *.* :: *.**.* : *.**.* * + :.* :+ * 40 ++ *.**.* : : *.** * *.* **. *.*| |* * * * | 30 ++ | | | 20 ++ | | | 10 ++ | |O O O O O | 0 O+-O---O-OO-OO-OO-O--OO-OO--O-OO-OO-OOO--------------------------------+ sched_debug.cpu.cpu_load_4_.0 80 ++-*---------------------*------------*--------------------------------+ | : : : | 70 ++:: :: :: | 60 ++: : :: :: | * : : * : : .*: : * | 50 ++: : .* ::: : * :: : *. : : | |: *.**.** : *.* :: **.**.* : **.**.* * : *.**. *. * 40 +* *.**.* * * *.** * **.**.*| | | 30 ++ | 20 ++ | | | 10 ++ | | O O O O O | 0 OO-O---O--O--O-OO-OO-OO-OO--O-OO-OO-OOO--------------------------------+ sched_debug.cpu.ttwu_count.0 4e+06 ++----------------------------------------------------------------+ | | 3.5e+06 ++ O | 3e+06 ++ O O | | O O OO | 2.5e+06 OO O O O O O | | OOO O O O OO O O O | 2e+06 ++ O O | | | 1.5e+06 ++ | 1e+06 ++ | | | 500000 ++ | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cfs_rq:_.exec_clock.0 40000 **-*---*-***-**-***--*-**---*-**-***-*---*-***--*-***-**--*-***-*---+ | *.* * ** *.* * * *.** 35000 ++ | | | | | 30000 ++ | | | 25000 ++ | | | 20000 ++ OO O | O O OO O OO O | |O O O OO OO O OO OO O | 15000 ++ O O O | | | 10000 ++------------------------------------------------------------------+ sched_debug.cfs_rq:_.min_vruntime.0 350000 ++-----------------------------------------------------------------+ | | 300000 **.**.***.**.***.**.***.**.***.**.***.***.**.* *.**.***.**.***.**.** | * | | | 250000 ++ | | | 200000 ++ | | | 150000 ++ | | | | | 100000 O+ OO OO O O | |O OO OOO O OO OO O OOO OO OOO | 50000 ++-----------------------------------------------------------------+ sched_debug.cpu.nr_switches.1 1.6e+07 ++----------------------------------------------------------------+ | O | 1.4e+07 ++ | 1.2e+07 ++ | | | 1e+07 ++ | | | 8e+06 ++ O O | |O O O O O | 6e+06 ++ O O | 4e+06 O+ OO O O OOO OOO O | | O OO O O | 2e+06 ++ | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cpu.cpu_load_4_.1 70 ++---------------------------------------------------------------------+ | * * * | 60 ++ : : : | | :: * :: * :: * | 50 ++ : : :+ : : + : : : + : | **. * :.* **. *. *.* .* :* *.** .* :* * .* .* .**. *. | 40 ++ * * * * * * * * * + * : ** *.** * ** | : : * :: | 30 ++ :: :: | | * * | 20 ++ | | | 10 ++ | | OO O O O | 0 OO----O--OO-OO-OO-OO-OO-OO-OO--O-OO--OO--------------------------------+ sched_debug.cpu.sched_count.1 1.6e+07 ++----------------------------------------------------------------+ | O | 1.4e+07 ++ | 1.2e+07 ++ | | | 1e+07 ++ | | | 8e+06 ++ O O | |O O O O O | 6e+06 ++ O O | 4e+06 O+ OO O O OOO OOO O | | O OO O O | 2e+06 ++ | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cpu.sched_goidle.1 8e+06 ++------------------------------------------------------------------+ | O | 7e+06 ++ | 6e+06 ++ | | | 5e+06 ++ | | | 4e+06 ++ O O | |O OO O O | 3e+06 ++ O O | 2e+06 O+ OO O OO OO OOO O | | O O O O O | 1e+06 ++ | | | 0 **-**-**-***-**-***-**-**-***-**-***-**-**-***-**-***-**-**-***-**-** sched_debug.cpu.ttwu_count.1 4e+06 ++----O-----------------------------------------------------------+ O O | 3.5e+06 +O O OOO | 3e+06 ++ O OO O OO O | | O O O | 2.5e+06 ++ O | | O OOO OOO O | 2e+06 ++ | | | 1.5e+06 ++ | 1e+06 ++ | | | 500000 ++ | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cfs_rq:_.exec_clock.1 40000 ++------------------------------------------------------------------+ **.**.**.***.**.***.**.**.***.**.***.**.**.***. *.***.**.**.***.**.** 35000 ++ * | | | | | 30000 ++ | | | 25000 ++ | | | 20000 ++ | | | | O OO O | 15000 OO OO O OO O O O OO OOO OO OOO O | | O O | 10000 ++------------------------------------------------------------------+ sched_debug.cfs_rq:_.min_vruntime.1 350000 ++-----------------------------------------------------------------+ | | 300000 **.**.***.**.***.**.***.**.***.**.***.***.**.* *.**.***.**.***.**.** | * | | | 250000 ++ | | | 200000 ++ | | | 150000 ++ | | | | | 100000 ++ | OO OO OOO OO OOO OO OOO OO OOO OO OOO | 50000 ++-----------------------------------------------------------------+ sched_debug.cpu.nr_switches.2 9e+06 ++------------------------------------------------------------------+ | | 8e+06 ++ O | 7e+06 ++ O | | O | 6e+06 ++ O | 5e+06 O+ O O | | O O OO O O OO OO O | 4e+06 ++ OO O O | 3e+06 ++ O O O | |O O | 2e+06 ++ | 1e+06 ++ | | | 0 **-**-**-***-**-***-**-**-***-**-***-**-**-***-**-***-**-**-***-**-** sched_debug.cpu.cpu_load_3_.2 70 ++---------------------------------------------------------------------+ | | 60 ++ * | | :: | 50 ** *.* .** : : | | + *. .* .**. *.* : * * : * *. *. *.* : .* | 40 ++ * : ** * * *. + *.* *. + *.* * * ** *.**.**.** | *.* ** ** | 30 ++ | | | 20 ++ | | | 10 ++ | | O OO O O O O | 0 OO-OO-OO-OO-OO-OO--O-----O--O-O--OO-O-O--------------------------------+ sched_debug.cpu.cpu_load_4_.2 60 ++------------------------------------------------------*--------------+ | :: | 50 ++ : : | **. *.* .** *.* : .* | | * *.**.**.**.**.* *. .**.** *. .**.**. *.* ** *.**.* | 40 ++ *.* ** ** * *.** | | 30 ++ | | | 20 ++ | | | | | 10 ++ | | O O O O O | 0 OO-OO-OO-OO-OO-OO--O-O--OO--O-O--OO-O-O--------------------------------+ sched_debug.cpu.sched_count.2 9e+06 ++------------------------------------------------------------------+ | | 8e+06 ++ O | 7e+06 ++ O | | O | 6e+06 ++ O | 5e+06 O+ O O | | O O OO O O OO OO O | 4e+06 ++ OO O O | 3e+06 ++ O O O | |O O | 2e+06 ++ | 1e+06 ++ | | | 0 **-**-**-***-**-***-**-**-***-**-***-**-**-***-**-***-**-**-***-**-** sched_debug.cpu.sched_goidle.2 4.5e+06 ++----------------------------------------------------------------+ | | 4e+06 ++ O | 3.5e+06 ++ O | | O | 3e+06 ++ O | 2.5e+06 O+ O O | | O O O O O OOO OO O | 2e+06 ++ OO O O | 1.5e+06 ++ O O O | |O O | 1e+06 ++ | 500000 ++ | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cpu.ttwu_count.2 4e+06 ++----O--------O--------------------------------------------------+ |O O O O O | 3.5e+06 ++ O O O O | 3e+06 O+ O O O O | | O O O | 2.5e+06 ++ | | O OOO OOO O | 2e+06 ++ | | | 1.5e+06 ++ | 1e+06 ++ | | | 500000 ++ | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cfs_rq:_.exec_clock.2 40000 ++--*---------------*-----*-----------*----------------------------*+ **.* **.***.**.*** *.** **.**.***.* **.***. *.***.**.**.***.** * 35000 ++ * | | | | | 30000 ++ | | | 25000 ++ | | | 20000 ++ | | | | | 15000 OO OO OO OOO O OOO O OO OOO OO OOO O | | O O | 10000 ++------------------------------------------------------------------+ sched_debug.cfs_rq:_.min_vruntime.2 350000 ++-----------------------------------------------------------------+ | | 300000 **.**.***.**.***.**.***.**.***.**.***.***.**.* *.**.***.**.***.**.** | * | | | 250000 ++ | | | 200000 ++ | | | 150000 ++ | | | | | 100000 ++ | OO OO OOO OO OOO OO OOO OO OOO OO OOO | 50000 ++-----------------------------------------------------------------+ sched_debug.cpu.nr_switches.3 9e+06 ++------------------------------------------------------------------+ | O O | 8e+06 ++ | 7e+06 ++ | | | 6e+06 ++ | 5e+06 ++ O | |O O O O OO OO O | 4e+06 O+ O O OO O O O O O O | 3e+06 ++ O O O O | | | 2e+06 ++ | 1e+06 ++ | | | 0 **-**-**-***-**-***-**-**-***-**-***-**-**-***-**-***-**-**-***-**-** sched_debug.cpu.cpu_load_0_.3 60 ++---------------------------------------------------------------------+ | | 50 ++ * * * | | :+ * + : + : *. *. * **.* **.**.* + :.**.**.** *.**.**.*** *.**.**. *.** : * .* *.* *| 40 ++ * * * : : * *.* | | : : | 30 ++ : : | | : : | 20 ++ :: | | :: | | O : | 10 ++ O O O O O : | OO O O * | 0 ++--O----OO-OO--O--O-OO-O--OO-OO--O-OO---------------------------------+ sched_debug.cpu.cpu_load_1_.3 50 ++---------------------------------------------------------------------+ 45 ++ *. .* *. .* *. .* * * * **.* **.** :.* : **.** *.**.* *** *.**.* + .** *.* *. :+ | 40 ++ * : * ** : : * *.* * *| 35 ++ :: : : + : | | :: : : * | 30 ++ * :: | 25 ++ : | 20 ++ * | | | 15 ++ | 10 ++ | | O O O O | 5 OO OO O O O O O | 0 ++--O----O--OO--O--O--O-O---O-OO--O-OO---------------------------------+ sched_debug.cpu.cpu_load_2_.3 50 ++---------------------------------------------------------------------+ 45 ++ *. *. .* *. .* .* *. .* .* * | **.* * ** :.* : **.** * *.* *** * *.* + .** *.* *. *. * 40 ++ * : * ** : : *. *.* * *| 35 ++ :+ :: * | | * * | 30 ++ | 25 ++ | 20 ++ | | | 15 ++ | 10 ++ | | O O O | 5 OO OO OO O O O O O | 0 ++--O-------OO--O--O--O-O---O-OO--O-OO---------------------------------+ sched_debug.cpu.cpu_load_3_.3 50 ++-----*----------------------*------------*---------------------------+ | *. :+ .* .* + : *. .* + : * .* | **.* * ** :.* *.**.** * *.* *** * *.* + .** * : *.**. * 40 ++ * :.* ** + : *.**.* *| | * * | | | 30 ++ | | | 20 ++ | | | | | 10 ++ | | O | |O O OO OO O O O O O O O | 0 O+----------OO--O--O--O-O---O-OO--O-OO---------------------------------+ sched_debug.cpu.cpu_load_4_.3 60 ++---------------------------------------------------------------------+ | | 50 ++ * * * | | :+ .* + : *. + : * .* | **.**.* ** :.* .**.**.**.** *.* ***.** *.* + *.**. * *.* *.**.** 40 ++ * * * * *.* | | | 30 ++ | | | 20 ++ | | | | | 10 ++ O | | O O O O O O O | 0 OO--O--O--O-OO--O-OO-OO-O---O-OO--O-OO---------------------------------+ sched_debug.cpu.sched_count.3 9e+06 ++------------------------------------------------------------------+ | O O | 8e+06 ++ | 7e+06 ++ | | | 6e+06 ++ | 5e+06 ++ O | |O O O O OO OO O | 4e+06 O+ O O OO O O O O O O | 3e+06 ++ O O O O | | | 2e+06 ++ | 1e+06 ++ | | | 0 **-**-**-***-**-***-**-**-***-**-***-**-**-***-**-***-**-**-***-**-** sched_debug.cpu.sched_goidle.3 4.5e+06 ++----------------------------------------------------------------+ | O O | 4e+06 ++ | 3.5e+06 ++ | | | 3e+06 ++ | 2.5e+06 ++ O | |O O O OOO OO O | 2e+06 O+ O OO O O O OO O O | 1.5e+06 ++ O O O O | | | 1e+06 ++ | 500000 ++ | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cpu.ttwu_count.3 4.5e+06 ++----------------------------------------------------------------+ |O | 4e+06 O+ O O O | 3.5e+06 ++ O O | | OO O OO O | 3e+06 ++ O O O O | 2.5e+06 ++ O O O O | | OOO OO O | 2e+06 ++ | 1.5e+06 ++ | | | 1e+06 ++ | 500000 ++ | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cfs_rq:_.exec_clock.3 40000 ++-------------------------------------------------------*----------+ **.**.**.***.**.***.**.**.***.**.***.**.**.***. *.***.** *.***.**.** 35000 ++ * | | | | | 30000 ++ | | | 25000 ++ | | | 20000 ++ | | | | O | 15000 OO OO O OOO O OO O OOO OO OOO O | | O O O OO | 10000 ++------------------------------------------------------------------+ sched_debug.cfs_rq:_.min_vruntime.3 350000 ++-----------------------------------------------------------------+ | | 300000 **.**.***.**.***.**.***.**.***.**.***.***.**.* *.**.***.**.***.**.** | * | | | 250000 ++ | | | 200000 ++ | | | 150000 ++ | | | | | 100000 ++ | OO OO OOO OO OOO OO OOO OO OOO OO OOO | 50000 ++-----------------------------------------------------------------+ sched_debug.cpu.nr_switches.4 4e+06 ++----------------------------------------------------------------+ | O O | 3.5e+06 ++ O O | 3e+06 ++ O O O O | | | 2.5e+06 ++ O | |O O O | 2e+06 O+ O O O OO OO O | | O O O O | 1.5e+06 ++ O | 1e+06 ++ O | | | 500000 ++ | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cpu.cpu_load_2_.4 60 ++---------------------------------------------------------------------+ | | 50 ++ * * * | | :+ * + : + : | |*.**.* * .**.** + :.**.** *. *. **.** *. * * ** * * *.*| 40 *+ * : * * * * * + :+ + : :: *.* + : * | : : * ** :: : : * | 30 ++ * * * | | | 20 ++ | | | | | 10 ++ | | O O | 0 OO--O-OO-OO-OO-O--OO-OO-OO-OO-OO-OO-OOO--------------------------------+ sched_debug.cpu.cpu_load_3_.4 70 ++---------------------------------------------------------------------+ | | 60 ++ * | | : | 50 ++ * : : .* .* | | .* .* + .**. * : : *. * : *. *. * : * .** .*| 40 ** * ** * + * *.* * *.* ** * *.* + *. * + *. *.**. * * | * * * * * * | 30 ++ | | | 20 ++ | | | 10 ++ | | | 0 OO-OO-OO-OO-OO-OO-OO-OO-OO-OO-OO-OO-OOO--------------------------------+ sched_debug.cpu.cpu_load_4_.4 80 ++---------------------------------------------------------------------+ | * | 70 ++ : | 60 ++ :: | | : : | 50 ++ * : : * * | | .* :+ *.**. * : : *. + :.**. *. + :.**. *.**. .*| 40 ** *.* * * + * *.* ** * ** ** * **.* **.**.**.** * | * | 30 ++ | 20 ++ | | | 10 ++ | O O O | 0 +O-OO-OO-OO-OO-OO-OO-OO-OO-OO--O-OO--OO--------------------------------+ sched_debug.cpu.sched_count.4 4e+06 ++----------------------------------------------------------------+ | O O | 3.5e+06 ++ O O | 3e+06 ++ O O O O | | | 2.5e+06 ++ O | |O O O | 2e+06 O+ O O O OO OO O | | O O O O | 1.5e+06 ++ O | 1e+06 ++ O | | | 500000 ++ | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cpu.sched_goidle.4 2e+06 ++----------------------------------------------------------------+ 1.8e+06 ++ O O | | O O | 1.6e+06 ++ O O O O | 1.4e+06 ++ | | O | 1.2e+06 +O O O | 1e+06 O+ O O O OO OO O | 800000 ++ O O O O | | O | 600000 ++ O | 400000 ++ | | | 200000 ++ | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cpu.ttwu_count.4 2e+06 ++----------------------------------------------------------------+ 1.8e+06 ++ O | | O | 1.6e+06 ++ | 1.4e+06 ++ O O | O OO O O | 1.2e+06 +O O OOO O O O O O O OO O | 1e+06 ++ O | 800000 ++ OO O | | | 600000 ++ | 400000 ++ | | | 200000 ++ | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cfs_rq:_.exec_clock.4 40000 ++------------------------------------------------------------------+ **.**.**.***.**.***.**.**.***.**.***.**.**.***. *.***.**.**.***.**.** 35000 ++ * | | | 30000 ++ | | | 25000 ++ | | | 20000 ++ | | | 15000 ++ | | | 10000 ++ OO OO OOO O | OO OO OO OOO OO OOO OO OO O | 5000 ++------------------------------------------------------------------+ sched_debug.cfs_rq:_.min_vruntime.4 350000 ++-----------------------------------------------------------------+ | | 300000 **.**.***.**.***.**.***.**.***.**.***.***.**.***.**.***.**.***.**.** | | 250000 ++ | | | 200000 ++ | | | 150000 ++ | | | 100000 ++ | | | 50000 OO OO OO OO OOO OO OOO OO OOO OO OOO | | O | 0 ++-----------------------------------------------------------------+ sched_debug.cpu.nr_switches.5 5e+06 ++----------------O-----------------------------------------------+ 4.5e+06 ++ | | O | 4e+06 ++ | 3.5e+06 ++ O O O OO O | | O O O | 3e+06 ++ O O O | 2.5e+06 O+ O O O O O O | 2e+06 +O O O O | | O O | 1.5e+06 ++ | 1e+06 ++ | | | 500000 ++ | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cpu.cpu_load_3_.5 60 ++-------*---------------------*------------*--------------------------+ | * +: * : * : | 50 +++: * : :: * : :: * : | ** : : : *.* : : : .** : : : .* | | *.: : * .**.* * : : * * : : *.** *.**.**.* *.* | 40 ++ * : :*.** * : : * : : : : *.** | :: :: :: : : | 30 ++ :: : : :.* | | * * * * | 20 ++ | | | | | 10 ++ | | O | 0 OO-OO-OO-OO-OO-OO-OO-OO-OO--O-OO-OO-OOO--------------------------------+ sched_debug.cpu.cpu_load_4_.5 70 ++-------*---------------------*------------*--------------------------+ | : : : | 60 *+ :: * :: * :: | | * :: :: * : : +: * : : | 50 +++: * : * : : :: * : * ::: * : *. .* | |* : : : :: .* * : + : : * : + : : ** : *. *. | 40 ++ *.* :: *.**.** ** :: ** :: *.* * * *.**.** | :: : : *.* | 30 ++ * * * | | | 20 ++ | | | 10 ++ | O O O | 0 +O-OO-OO--O-OO-OO-OO-OO-OO--O-OO-OO-OOO--------------------------------+ sched_debug.cpu.sched_count.5 5e+06 ++----------------O-----------------------------------------------+ 4.5e+06 ++ | | O | 4e+06 ++ | 3.5e+06 ++ O O O OO O | | O O O | 3e+06 ++ O O O | 2.5e+06 O+ O O O O O O | 2e+06 +O O O O | | O O | 1.5e+06 ++ | 1e+06 ++ | | | 500000 ++ | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cpu.sched_goidle.5 2.5e+06 ++----------------O-----------------------------------------------+ | | | O | 2e+06 ++ | | O O O OO O | | O O O | 1.5e+06 ++ O O O | O O O O O O | 1e+06 +O O O O O | | O O | | | 500000 ++ | | | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cpu.ttwu_count.5 2.5e+06 ++----------------------------------------------------------------+ | | | O O O | 2e+06 ++ O O O O O O | OO O O O O O O O | | O O O | 1.5e+06 ++ O O O O O | | O | 1e+06 ++ | | | | | 500000 ++ | | | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cfs_rq:_.exec_clock.5 40000 ++------------------------------------------------------------------+ **.**.**.***.**.***.**.**.***.**.***.**.**.***. *.***.**.**.***.**.** 35000 ++ * | | | 30000 ++ | | | 25000 ++ | | | 20000 ++ | | | 15000 ++ | | OO O O | 10000 ++ OO O OOO O OO O OOO OO | OO O OO OO O | 5000 ++------------------------------------------------------------------+ sched_debug.cfs_rq:_.min_vruntime.5 350000 ++-----------------------------------------------------------------+ | | 300000 **.**.***.**.***.**.***.**.***.**.***.***.**.***.**.***.**.***.**.** | | 250000 ++ | | | 200000 ++ | | | 150000 ++ | | | 100000 ++ | | | 50000 OO OO OOO OO OOO OO OOO OO OOO OO OOO | | | 0 ++-----------------------------------------------------------------+ sched_debug.cpu.nr_switches.6 5.5e+06 ++----------------------------------------------------------------+ 5e+06 +O O | | | 4.5e+06 ++ | 4e+06 O+ | 3.5e+06 ++ O O O OOO OOO O | 3e+06 ++ O O | | O OO O | 2.5e+06 ++ O O OO O O O | 2e+06 ++ O | 1.5e+06 ++ | 1e+06 ++ | | | 500000 ++ | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cpu.cpu_load_2_.6 60 ++---------------------------------------------------------------------+ | *.* * ** .* ** .* | 50 ++ : : * + + : * : + : * : | | .* : : * * : : *. * : : * * .* | ** : : : *.* .**.* : : ** : : + * **.* * + * *.*| 40 ++ :: *. : * : : : : * + + :+ * * | :: * :: :: ** * | 30 ++ : : : | | * * * | 20 ++ | | | | | 10 ++ | | O OO O O | 0 OO-OO-OO--O-OO----OO-OO-OO-OO-O--OO-O-O--------------------------------+ sched_debug.cpu.cpu_load_3_.6 70 ++---------------------------------------------------------------------+ | * * * | 60 ++ :+ :: :: | | : * *. : * .* : * .* | 50 ++ : : * * : : * * : : * * | |*.* :+ : .* .** : : + ** : : + .**. .* | 40 *+ * *. *.** * * * * **. .**.** ** *.** | * ** | 30 ++ | | | 20 ++ | | | 10 ++ | | O O O O | 0 OO-OO-OO--O-OO-OO-OO-OO--O-OO-O--OO-O----------------------------------+ sched_debug.cpu.cpu_load_4_.6 80 ++---------------------------------------------------------------------+ | * * * | 70 ++ :: : : | 60 ++ :: : : : : | | : * * : *. * : *. * | 50 +*.: *. :+ *: * + : *: * + : | |: * * * .* + * * *. :* * *. * .**. .* | 40 *+ *.**.** * * **.* + *.** ** *.** | * | 30 ++ | 20 ++ | | | 10 ++ | | O O O | 0 OO-OO-OO-OO-OO-OO-OO-OO--O-OO-O--OO-O----------------------------------+ sched_debug.cpu.sched_count.6 5.5e+06 ++----------------------------------------------------------------+ 5e+06 +O O | | | 4.5e+06 ++ | 4e+06 O+ | 3.5e+06 ++ O O O OOO OOO O | 3e+06 ++ O O | | O OO O | 2.5e+06 ++ O O OO O O O | 2e+06 ++ O | 1.5e+06 ++ | 1e+06 ++ | | | 500000 ++ | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cpu.sched_goidle.6 3e+06 ++----------------------------------------------------------------+ | | 2.5e+06 +O O | | | | | 2e+06 O+ O O | | O O OOO OO O | 1.5e+06 ++ O O O O O | | O O O O O O | 1e+06 ++ OO O | | | | | 500000 ++ | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cpu.ttwu_count.6 2.5e+06 ++----------------------------------------------------------------+ | O O | |O O O O O | 2e+06 ++ O O O O | O O OO O O O | | O O O OOO OO O | 1.5e+06 ++ | | | 1e+06 ++ | | | | | 500000 ++ | | | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cfs_rq:_.exec_clock.6 40000 ++----*--------------------*------------*---------------------------+ **.** *.***.**.***.**.**.* *.**.***.** *.***. *.***.**.**.***.**.** 35000 ++ * | | | 30000 ++ | | | 25000 ++ | | | 20000 ++ | | | 15000 ++ | | O O O O | 10000 +O OO O OOO OO OOO OO O O O O O O | O O O | 5000 ++------------------------------------------------------------------+ sched_debug.cfs_rq:_.min_vruntime.6 350000 ++-----------------------------------------------------------------+ | | 300000 **.**.***.**.***.**.***.**.***.**.***.***.**.***.**.***.**.***.**.** | | 250000 ++ | | | 200000 ++ | | | 150000 ++ | | | 100000 ++ | | | 50000 OO OO OOO OO OOO OO OOO OO OOO OO OOO | | | 0 ++-----------------------------------------------------------------+ sched_debug.cpu.nr_switches.7 4e+06 ++----------------------------------------------------------------+ | O | 3.5e+06 ++ O O OO | 3e+06 ++ O O O | | O O O O | 2.5e+06 ++ O | | O O O O | 2e+06 O+ O O O O | |O O O O O | 1.5e+06 ++ | 1e+06 ++ | | | 500000 ++ | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cpu.cpu_load_3_.7 50 ++-----*-*--------------------**-----------**--------------------------+ **.**. : : **.**.* + + .***.* + + | | * *.**. *.* + * ** * ** *.**.**.* **. * 40 ++ * * + : *.* : * | | * : : : *| | :: :: | 30 ++ : :: | | * * | 20 ++ | | | | | 10 ++ | | | | O OO O O O | 0 OO-OO-OO-OO--O-OO----OO--O-OO-OO--O-OO---------------------------------+ sched_debug.cpu.cpu_load_4_.7 50 ++--*--*-*-----------------*--**--------*--**--------------------------+ **.* : : : .**.** : : + .*** : : + *. * * | :: *.**. *.** :: ** :: **. : **.**.**. :*. | 40 ++ * * * * * * : * *| | : : :+ | | : * | 30 ++ * | | | 20 ++ | | | | | 10 ++ | | | | O O O O | 0 OO-OO-OO-OO-O--OO--O-OO-OO-OO-O--OO-O-O--------------------------------+ sched_debug.cpu.sched_count.7 4e+06 ++----------------------------------------------------------------+ | O | 3.5e+06 ++ O O OO | 3e+06 ++ O O O | | O O O O | 2.5e+06 ++ O | | O O O O | 2e+06 O+ O O O O | |O O O O O | 1.5e+06 ++ | 1e+06 ++ | | | 500000 ++ | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cpu.sched_goidle.7 2e+06 ++----------------------------------------------------------------+ 1.8e+06 ++ O | | O O OO | 1.6e+06 ++ O O O | 1.4e+06 ++ O O O O | | O | 1.2e+06 ++ O O O O | 1e+06 O+ O O O O | 800000 +O O O O O | | | 600000 ++ | 400000 ++ | | | 200000 ++ | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cpu.ttwu_count.7 2.5e+06 ++----------------------------------------------------------------+ O O | |O O O | 2e+06 ++ O O O O OO O O O O O O | | O O | | O O O OO O O O | 1.5e+06 ++ | | | 1e+06 ++ | | | | | 500000 ++ | | | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cfs_rq:_.exec_clock.7 40000 ++------------------------------------------------------------------+ **.**.**.***.**.***.**.**.***.**.***.**.**.***. *.***.**.**.***.**.** 35000 ++ * | | | 30000 ++ | | | 25000 ++ | | | 20000 ++ | | | 15000 ++ | | O O OO | 10000 O+ O OOO OO O OO OO O O O O | |O OO O OO O | 5000 ++------------------------------------------------------------------+ sched_debug.cfs_rq:_.min_vruntime.7 350000 ++-----------------------------------------------------------------+ | | 300000 **.**.***.**.***.**.***.**.***.**.***.***.**.***.**.***.**.***.**.** | | 250000 ++ | | | 200000 ++ | | | 150000 ++ | | | 100000 ++ | | | 50000 OO OO OOO OO OOO OO OOO OO OOO OO OOO | | | 0 ++-----------------------------------------------------------------+ sched_debug.cpu.nr_switches.min 3.5e+06 ++--------------------------O----O--------------------------------+ | | 3e+06 ++ O O O O | | O O | 2.5e+06 ++ O | | O | 2e+06 O+ O O O OO O | |O O O O O O | 1.5e+06 ++ O O O | | O | 1e+06 ++ | | | 500000 ++ | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cpu.nr_switches.avg 6e+06 ++------------------------------------------------------------------+ | O | 5e+06 OO OO O OOO | | O O O O OO O | | OO O | 4e+06 ++ OO OO OOO O | | | 3e+06 ++ | | | 2e+06 ++ | | | | | 1e+06 ++ | | | 0 **-**-**-***-**-***-**-**-***-**-***-**-**-***-**-***-**-**-***-**-** sched_debug.cpu.nr_load_updates.min 40000 ++------------------------------------------------------------------+ | | 38000 **.**.**.***.**.***.**.**.***.**.***.**.**.***. *.***.**.**.***.**.** 36000 ++ * | | | 34000 ++ | | | 32000 ++ OO O OOO | | O O | 30000 ++ O O O | 28000 ++ O O O | OO O O O | 26000 ++ O OO O O | | O O | 24000 ++----O-------------------------------------------------------------+ sched_debug.cpu.nr_load_updates.avg 41000 ++------------------------------------------------------------------+ | | 40000 **.**.**.***.* **.**.**.***.**.***.**.**.*** *.* *.**.**.***. *.** | *.* : : * * | 39000 ++ :: | | * | 38000 ++ | | | 37000 ++ O OO O OOO | | O OO O O | 36000 ++ O O O | | OO OO O O | 35000 OO O O | | O O | 34000 ++------------------------------------------------------------------+ sched_debug.cpu.cpu_load_0_.max 120 ++-------*---------------------*------------*-------------------------+ | : : : | 100 ++ :: :: :: | | : : : : : : | | : : : : : : | 80 *+ * * *. * * * *. * * * *. | |:+ :.* * : :+ :.* * :+ :.* * | 60 +* * : : * * * * : * | | *. *.*** :+ *.***.**.**.**.**.** 40 ++ * * | | | | | 20 ++ O O O O O OO O O | OO O O OO OO O OO OO OO OO | 0 ++--------------------O-----------------------------------------------+ sched_debug.cpu.cpu_load_0_.avg 60 ++---------------------------------------------------------------------+ | * * * | 50 ++ : : :+ :+ | * : *.* *. : **.* : ** | |*.* * *.**.**.* **. * **. * : *. *.** | 40 ++ *. : * + * + : : * .* + *.**. * * | * * * * * * * + | 30 ++ *| | | 20 ++ | | | | | 10 ++ | |O O O O O O OO O OO OO O OOO | 0 O+--O--O-O---O-O------O----OO-----O------------------------------------+ sched_debug.cpu.cpu_load_1_.max 90 ++---------------------------------------------------------------------+ | * * * * | 80 ++ +: :: :: : | 70 ++ * * : * * : * * : :: | * +: : *. *. :: + * .* :: + * :: | 60 +* :.* * : * : * * * : * * : : | 50 ++ * : .* * * + *.**.: : .**. .* .** | *.**.** * * ** ** * | 40 ++ | 30 ++ | | | 20 ++ | 10 ++ O O O O OO | OO O OO OO OO OO OO O O OO O O O | 0 ++--------------------O------------------------------------------------+ sched_debug.cpu.cpu_load_1_.avg 50 ++-------*---------------------*------------*--------------------------+ 45 *+.* + *.* *. *. : **.* *. : ** * | |* *. * *.* .* * * .* * * .* : *. :+ | 40 ++ * *.** * * : : * .* **. *.**.* * 35 ++ * * * *.*| | | 30 ++ | 25 ++ | 20 ++ | | | 15 ++ | 10 ++ | | | 5 OO O O O O O O O OO O O OO | 0 ++--O--O--O--O-O---O--O----OO-O---O-O----------------------------------+ sched_debug.cpu.cpu_load_2_.max 80 ++---------------------------------------------------------------------+ | * * * * | 70 ++:: :: :: : | 60 *+: : *.**. **.: : .**.* .*: : .**.* :: | |* *.* * : * ** * * ** * : : | 50 ++ : : + *.* : : .**. .* * | *.**.** * *.* ** ** *.*| 40 ++ | | | 30 ++ | 20 ++ | | | 10 ++ O O O | OO O OO OO OO OO OO OO OO OO O OO O O | 0 ++---------------------------------------------------------------------+ sched_debug.cpu.cpu_load_2_.avg 50 ++---------------------------------------------------------------------+ 45 *+.* *.* .* **. *. .**. *.* *. .**. * * | |* *.* * *. + * ** * * ** * + *.* * + *. | 40 ++ **.** * :+ **.**.* **.** 35 ++ * | | | 30 ++ | 25 ++ | 20 ++ | | | 15 ++ | 10 ++ | | | 5 +O O O O O O O O OO O O O OO | 0 O+--O--O--O--O-O---O--O-----O-O---O-O----------------------------------+ sched_debug.cpu.cpu_load_3_.max 80 ++---------------------------------------------------------------------+ | * * * * | 70 ++ :: :: :+ :+ | 60 *+: *.**.**. : * : **.**.* .* : **.**.* * | |:: * : +: * : * :: | 50 +* : : * * + *.* .* : *. | | *.**.** * * **.* **.**.** 40 ++ | | | 30 ++ | 20 ++ | | | 10 ++ O O O O | OO O OO OO OO OO OO OO OO O O OO O O | 0 ++---------------------------------------------------------------------+ sched_debug.cpu.cpu_load_3_.avg 50 ++-*---------------------*------------*--------------------------------+ 45 *++ *.**.* .* **. : **.**. *.* : **.**. * *. | |* * *. *. + * * * * + *.* .* **.* *.* * 40 ++ * ** * * *.* *.*| 35 ++ | | | 30 ++ | 25 ++ | 20 ++ | | | 15 ++ | 10 ++ | | | 5 ++ O O O O | 0 OO--O-OO-OO-OO-OO-OO-OO-OO--O-O--OO-O-O--------------------------------+ sched_debug.cpu.cpu_load_4_.max 90 ++---------------------------------------------------------------------+ | * * *. *. | 80 ++ :*. * : : * * : * * | 70 ++: * +: : : : *. :: : *. :: | * : * : : *.: * : .*: * : | 60 +* *.* : * ** * ** .** | 50 ++ : : + *.** + .**. .** | *.**.** * ** **.** | 40 ++ | 30 ++ | | | 20 ++ | 10 ++ | O OO OO OO O OO OO OO OO OO OO OO OOO | 0 +O-----------O---------------------------------------------------------+ sched_debug.cpu.cpu_load_4_.avg 50 ++-*---------------------*------------*--------------------------------+ 45 ** *.**.**.* **.* **.**.**.** **.**.** *. | | *.**.* + + *.* .* **.* *.* .** 40 ++ * * * *.* * | 35 ++ | | | 30 ++ | 25 ++ | 20 ++ | | | 15 ++ | 10 ++ | | | 5 O+ OO O O O | 0 +O----OO-OO-OO-OO-OO-OO-OO--O-O--OO-O-O--------------------------------+ sched_debug.cpu.sched_count.min 3.5e+06 ++--------------------------O----O--------------------------------+ | | 3e+06 ++ O O O O | | O O | 2.5e+06 ++ O | | O | 2e+06 O+ O O O OO O | |O O O O O O | 1.5e+06 ++ O O O | | O | 1e+06 ++ | | | 500000 ++ | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cpu.sched_count.avg 6e+06 ++------------------------------------------------------------------+ | O | 5e+06 OO OO O OOO O | | O O O OO O | | OO O | 4e+06 ++ OO OO OOO O | | | 3e+06 ++ | | | 2e+06 ++ | | | | | 1e+06 ++ | | | 0 **-**-**-***-**-***-**-**-***-**-***-**-**-***-**-***-**-**-***-**-** sched_debug.cpu.sched_goidle.min 1.8e+06 ++----------------------------------------------------------------+ | O O | 1.6e+06 ++ O O O O | 1.4e+06 ++ O O | | | 1.2e+06 ++ O | 1e+06 O+ O O O O O O | |O O O O O O O | 800000 ++ O O | 600000 ++ O O | | | 400000 ++ | 200000 ++ | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cpu.sched_goidle.avg 3e+06 ++----------------------------------------------------------------+ | O | 2.5e+06 OO OO O O OO | | OO O O OO O | | OO O | 2e+06 ++ O OOO OOO O | | | 1.5e+06 ++ | | | 1e+06 ++ | | | | | 500000 ++ | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cpu.ttwu_count.max 4.5e+06 ++----------------------------------------------------------------+ |O O | 4e+06 O+ O O O | 3.5e+06 ++ OO O OO O | | OO O O O | 3e+06 ++ OO | 2.5e+06 ++ O O OO | | OO O O | 2e+06 ++ | 1.5e+06 ++ | | | 1e+06 ++ | 500000 ++ | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cpu.ttwu_count.min 1.6e+06 ++--O-------------------------------------------------------------+ | O | 1.4e+06 ++ O OO | OO O O O | 1.2e+06 ++ O OO O O O O O O OO O | 1e+06 ++ O O | | OO O | 800000 ++ | | | 600000 ++ | 400000 ++ | | | 200000 ++ | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cpu.ttwu_count.avg 3e+06 ++----------------------------------------------------------------+ | O | 2.5e+06 OO OO O O OO | | OO O O OO O | | OO O | 2e+06 ++ O OOO OOO O | | | 1.5e+06 ++ | | | 1e+06 ++ | | | | | 500000 ++ | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cpu.ttwu_count.stddev 1.2e+06 ++----------------------------------------------------------------+ | O | 1e+06 ++ | OO O O | | O O OO O | 800000 ++ O O | | OO O | 600000 ++ OO OO | | O O | 400000 ++ O O O O | | O O | | | 200000 ++ | | | 0 **-**-***-***-**-***-***-**-***-***-**-***-***-**-***-***-**-***-** sched_debug.cfs_rq:_.exec_clock.max 40000 **-**--*-***-**-***-**-**-*-*-**-***-**--*-***--*-***-**-**-***-----+ | * * * * **.** | | 35000 ++ | | | | | 30000 ++ | | | 25000 ++ | | | | | 20000 ++ OO O | O O OO O OO O | |O O O OOO OO O OO OO O | 15000 ++-------------------------O-----O----------------------------------+ sched_debug.cfs_rq:_.exec_clock.min 40000 ++------------------------------------------------------------------+ **.**.**.***.**.***.**.**.***.**.***.**.**.***. *.***.**.**.***.**.** 35000 ++ * | | | 30000 ++ | | | 25000 ++ | | | 20000 ++ | | | 15000 ++ | | | 10000 ++ OO OO OOO O | OO OO OO OOO OO OOO OO OO O | 5000 ++------------------------------------------------------------------+ sched_debug.cfs_rq:_.exec_clock.avg 40000 ++------------------------------------------------------------------+ **.**.**.***.**.***.**.**.***.**.***.**.**.***. *.***.**.**.***.**.** 35000 ++ * | | | | | 30000 ++ | | | 25000 ++ | | | 20000 ++ | | | | | 15000 ++ | OO OO OO OOO OO OOO OO OO OOO OO OOO O | 10000 ++------------------------------------------------------------------+ sched_debug.cfs_rq:_.min_vruntime.max 350000 ++-----------------------------------------------------------------+ | | 300000 **.**.***.**.***.**.***.**.***.**.***.***.**.* *.**.***.**.***.**.** | * | | | 250000 ++ | | | 200000 ++ | | | 150000 ++ | | | | | 100000 O+ OO OO O O | |O OO OOO O OO OO O OOO OO OOO | 50000 ++-----------------------------------------------------------------+ sched_debug.cfs_rq:_.min_vruntime.min 350000 ++-----------------------------------------------------------------+ | | 300000 **.**.***.**.***.**.***.**.***.**.***.***.**.***.**.***.**.***.**.** | | 250000 ++ | | | 200000 ++ | | | 150000 ++ | | | 100000 ++ | | | 50000 OO OO OO OO OOO OO OOO OO OOO OO OOO | | O | 0 ++-----------------------------------------------------------------+ sched_debug.cfs_rq:_.min_vruntime.avg 350000 ++-----------------------------------------------------------------+ | | 300000 **.**.***.**.***.**.***.**.***.**.***.***.**.* *.**.***.**.***.**.** | * | | | 250000 ++ | | | 200000 ++ | | | 150000 ++ | | | | | 100000 ++ | | O OO O O OOO OO OOO | 50000 OO-OO-OO-----OOO-O---OO-OO-----------------------------------------+ sched_debug.cfs_rq:_.min_vruntime.stddev 18000 ++------------------------------------------------------------------+ | O O | 16000 O+ O O O | 14000 ++ OO O OO O O | | O O O | 12000 +O O O | 10000 ++ | | O O | 8000 ++ OO O OOO | 6000 ++ | | | 4000 ++ | 2000 ++ | **.**.**.** .**.***.**.**.***.**. **.**.**.** .**.***. *.**.***.**.** 0 ++---------*---------------------*-----------*--------*-------------+ sched_debug.cfs_rq:_.spread0.stddev 18000 ++------------------------------------------------------------------+ | O O | 16000 O+ O O O | 14000 ++ OO O OO O O | | O O O | 12000 +O O O | 10000 ++ | | O O | 8000 ++ OO O OOO | 6000 ++ | | | 4000 ++ | 2000 ++ | **.**.**.** .**.***.**.**.***.**. **.**.**.** .**.***. *.**.***.**.** 0 ++---------*---------------------*-----------*--------*-------------+ sched_debug.cfs_rq:_.runnable_load_avg.max 100 ++-----*---------------------*------------*---------------------------+ 90 ++ : * : * : * | | :: :: :: : : :: :: | 80 *+ * : : :: * * : : : * * : : :: | 70 +++ *.: :* : ::+ *.: :* :+ *.: :* : | |* * * : : * * * * * * : | 60 ++ : : :.* .* | 50 ++ *. *.*** * *.***.** *.**.**.** 40 ++ * | | | 30 ++ | 20 ++ O | | O O O O O O OO O O O | 10 OO O O O OO OO OO O O O O | 0 ++--------------------------------------------------------------------+ sched_debug.cfs_rq:_.runnable_load_avg.avg 55 ++---------------------------------------------------------------------+ 50 ++ .* *. * | * * * * : * *. * * * *. * * + | 45 +++ * :: : :.**. * + : * :: : :: * :: : * .* .**.** * *. | 40 +* +: :: * * + * :: :: * :: :: * * + :+ : ** 35 ++ * * * * * * * * : * | 30 ++ *.* | | | 25 ++ | 20 ++ | 15 ++ | 10 ++ | | | 5 OO OO OO OO OO OO OO OO OO OO OO OO OOO | 0 ++---------------------------------------------------------------------+ sched_debug.cfs_rq:_.util_avg.min 450 ++--------------------------------------------------------------------+ | * .** * * 400 **.* *.* ** :+ *.**.* *.* ***.* *.* ** : : :.* .*| 350 ++ *.* :+ +: ** *.* :+ *.* :+ : **.**.* : * * | | * * * * * * | 300 ++ | 250 ++ | | | 200 ++ O | 150 ++ O O | OO O O O O O O | 100 ++ O O O OO O O OO O OO O | 50 ++ O O | | O | 0 ++--------------------------------------------------------------------+ sched_debug.cfs_rq:_.util_avg.avg 550 ++--------------------------------------------------------------------+ | * | 500 ++ *. * *. :* .*| 450 +*.**.**.* *.* ** + *.**.**.* *.**.**.* .* * + *.**.**. * * * *.* * *.** *.** * * | 400 ++ | | | 350 ++ | | | 300 ++ | 250 O+ O O | |O O O OO O OO O O O O OO OO OOO O | 200 ++ O O O | | | 150 ++--------------------O-----------------------------------------------+ sched_debug.cpu.cpu_load_2_.7 50 ++-----*----------------------*------------*---------------------------+ |*. : **.* * .* + *.**. * + *.** *. * | * **.* *. .* + * *.** * *.** : : **.**.**. :*. * 40 ++ ** * : : * : * | | : : : : :| | * : : *| 30 ++ * : :| | :: | 20 ++ :: | | * | | | 10 ++ | | | |O O O OO O O O O | 0 O+-OO--O-OO--O-OO----O---O-OO-OO--O-OO---------------------------------+ sched_debug.cpu.cpu_load_4_.min 42 ++---------------------------------------------------------------------+ | * * * *. * * * * | 40 **. .* : :* **.* * : : ** * : :: *. :+ :+ *. | 38 ++ ** : :: : + *. : *.* : : : *.* : : :: : * * * * : * | | ::: : * * : :: : : :: : : : : : : : : * 36 ++ * : : : : * : : * : : * : : : : : *| 34 ++ :: : : :: :: : : : : :+ | | :: : : :: :: : : : * * | 32 ++ : : : : : :: : : | 30 ++ : :: : : :: :: | | * :: * * :: :: | 28 ++ :: : :: | 26 ++ : : : | | : * * | 24 ++-----------------*---------------------------------------------------+ [*] 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

4 years, 11 months

4
3
0 / 0

[lkp] [spi] 2baed30cb3: BUG: scheduling while atomic: systemd-udevd/134/0x00000002

by kernel test robot

FYI, we noticed the below changes on https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git master commit 2baed30cb30727b2637d26eac5a8887875a13420 ("spi: lm70llp: use new parport device model") +----------------+------------+------------+ | | 74bdced4b4 | 2baed30cb3 | +----------------+------------+------------+ | boot_successes | 0 | 0 | +----------------+------------+------------+ [ 6.358390] i6300esb: Intel 6300ESB WatchDog Timer Driver v0.05 [ 6.358540] i6300esb: cannot register miscdev on minor=130 (err=-16) [ 6.358555] i6300ESB timer: probe of 0000:00:06.0 failed with error -16 [ 6.363357] BUG: scheduling while atomic: systemd-udevd/134/0x00000002 [ 6.363366] Modules linked in: crc32c_intel pcspkr evdev i6300esb ide_cd_mod cdrom intel_agp intel_gtt i2c_piix4 i2c_core virtio_pci virtio virtio_ring agpgart rtc_cmos(+) parport_pc(+) autofs4 [ 6.363369] CPU: 1 PID: 134 Comm: systemd-udevd Not tainted 4.4.0-rc1-00006-g2baed30 #1 [ 6.363370] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 [ 6.363372] 0000000000012880 ffff88007f8bb880 ffffffff878a5e4d ffff880078712880 [ 6.363374] ffff88007f8bb890 ffffffff876a64d6 ffff88007f8bb8d0 ffffffff87b05f69 [ 6.363375] ffff88005d04e340 ffff88007f8bc000 000000000000007f ffffffff879a6260 [ 6.363375] Call Trace: [ 6.363385] [<ffffffff878a5e4d>] dump_stack+0x4b/0x6e [ 6.363391] [<ffffffff876a64d6>] __schedule_bug+0x46/0x60 [ 6.363394] [<ffffffff87b05f69>] __schedule+0x549/0x780 [ 6.363398] [<ffffffff879a6260>] ? dead_read+0x10/0x10 [ 6.363399] [<ffffffff87b061d7>] schedule+0x37/0x80 [ 6.363406] [<ffffffff87b0930f>] schedule_hrtimeout_range_clock+0x9f/0x100 [ 6.363410] [<ffffffff876d22b0>] ? hrtimer_init+0x10/0x10 [ 6.363411] [<ffffffff87b0937e>] schedule_hrtimeout_range+0xe/0x10 [ 6.363412] [<ffffffff87b08f16>] usleep_range+0x46/0x50 [ 6.363418] [<ffffffff87a7f7a3>] lcd_write_cmd_p8+0x33/0x90 [ 6.363420] [<ffffffff87a7fba5>] lcd_init_display+0x35/0x130 [ 6.363421] [<ffffffff87a80ba3>] panel_attach+0x6f3/0xb30 [ 6.363423] [<ffffffff879a6260>] ? dead_read+0x10/0x10 [ 6.363424] [<ffffffff879a6272>] driver_check+0x12/0x20 [ 6.363429] [<ffffffff879af286>] bus_for_each_drv+0x56/0x90 [ 6.363430] [<ffffffff879a6504>] attach_driver_chain+0x54/0x60 [ 6.363432] [<ffffffff879a65fb>] parport_announce_port+0xeb/0x140 [ 6.363439] [<ffffffffc00e1219>] parport_pc_probe_port+0x729/0xc40 [parport_pc] [ 6.363441] [<ffffffff879ad3c7>] ? __dev_printk+0x37/0x80 [ 6.363442] [<ffffffff879ad757>] ? _dev_info+0x57/0x60 [ 6.363445] [<ffffffffc00e1ade>] parport_pc_pnp_probe+0x13e/0x1e0 [parport_pc] [ 6.363448] [<ffffffffc00e19a0>] ? parport_pc_pci_probe+0x270/0x270 [parport_pc] [ 6.363458] [<ffffffff8795aecc>] pnp_device_probe+0x5c/0xb0 [ 6.363461] [<ffffffff879b135d>] driver_probe_device+0x21d/0x490 [ 6.363463] [<ffffffff879b164f>] __driver_attach+0x7f/0x90 [ 6.363464] [<ffffffff879b15d0>] ? driver_probe_device+0x490/0x490 [ 6.363466] [<ffffffff879af1df>] bus_for_each_dev+0x5f/0x90 [ 6.363467] [<ffffffff879b0b69>] driver_attach+0x19/0x20 [ 6.363469] [<ffffffff879b0711>] bus_add_driver+0x1e1/0x280 [ 6.363470] [<ffffffff879b1adb>] driver_register+0x5b/0xd0 [ 6.363472] [<ffffffff8795ad3b>] pnp_register_driver+0x1b/0x20 [ 6.363475] [<ffffffffc00e84ef>] parport_pc_init+0x437/0xf48 [parport_pc] [ 6.363478] [<ffffffffc00e80b8>] ? parport_parse_param+0xb8/0xb8 [parport_pc] [ 6.363481] [<ffffffff876003de>] do_one_initcall+0xae/0x1f0 [ 6.363482] [<ffffffff876a67f4>] ? __might_sleep+0x44/0x80 [ 6.363485] [<ffffffff87706d8b>] do_init_module+0x5a/0x38e [ 6.363489] [<ffffffff876e7e1d>] load_module+0x1ded/0x2300 [ 6.363491] [<ffffffff876e5320>] ? m_show+0x100/0x100 [ 6.363492] [<ffffffff876e5605>] ? copy_module_from_fd+0xc5/0x120 [ 6.363494] [<ffffffff876e8535>] SyS_finit_module+0x95/0xc0 [ 6.363496] [<ffffffff87b09e2e>] entry_SYSCALL_64_fastpath+0x12/0x71 LKP: HOSTNAME vm-lkp-wsx03-2G-2, MAC , kernel 4.4.0-rc1-00006-g2baed30 1, serial console /dev/ttyS0 [ 6.476101] BUG: sleeping function called from invalid context at kernel/locking/mutex.c:97 [ 6.478575] in_atomic(): 1, irqs_disabled(): 0, pid: 134, name: systemd-udevd [ 6.480112] CPU: 0 PID: 134 Comm: systemd-udevd Tainted: G W 4.4.0-rc1-00006-g2baed30 #1 [ 6.482404] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 [ 6.484632] 0000000000000061 ffff88007f8bb908 ffffffff878a5e4d ffff88005d04e340 [ 6.484633] ffff88007f8bb920 ffffffff876a676d ffffffff87d46380 ffff88007f8bb948 [ 6.484634] ffffffff876a67f4 ffffffff87e51ea0 ffffffff87e6b718 000000000000009c [ 6.484635] Call Trace: [ 6.484641] [<ffffffff878a5e4d>] dump_stack+0x4b/0x6e [ 6.484644] [<ffffffff876a676d>] ___might_sleep+0xcd/0x110 [ 6.484648] [<ffffffff876a67f4>] __might_sleep+0x44/0x80 [ 6.484651] [<ffffffff87b07fab>] mutex_lock+0x1b/0x40 [ 6.484655] [<ffffffff8799b76b>] misc_register+0x2b/0x180 [ 6.484659] [<ffffffff87a7f7e7>] ? lcd_write_cmd_p8+0x77/0x90 [ 6.484661] [<ffffffff879a6260>] ? dead_read+0x10/0x10 [ 6.484662] [<ffffffff87a80bdd>] panel_attach+0x72d/0xb30 [ 6.484664] [<ffffffff879a6260>] ? dead_read+0x10/0x10 [ 6.484665] [<ffffffff879a6272>] driver_check+0x12/0x20 [ 6.484667] [<ffffffff879af286>] bus_for_each_drv+0x56/0x90 [ 6.484668] [<ffffffff879a6504>] attach_driver_chain+0x54/0x60 calling: settle [ 6.484670] [<ffffffff879a65fb>] parport_announce_port+0xeb/0x140 [ 6.484674] [<ffffffffc00e1219>] parport_pc_probe_port+0x729/0xc40 [parport_pc] ipconfig: no devices to configure ipconfig: no devices to configure [ 6.484676] [<ffffffff879ad3c7>] ? __dev_printk+0x37/0x80 ipconfig: no devices to configure ipconfig: no devices to configure ipconfig: no devices to configure ipconfig: no devices to configure [ 6.484677] [<ffffffff879ad757>] ? _dev_info+0x57/0x60 ipconfig: no devices to configure ipconfig: no devices to configure ipconfig: no devices to configure ipconfig: no devices to configure [ 6.484680] [<ffffffffc00e1ade>] parport_pc_pnp_probe+0x13e/0x1e0 [parport_pc] /lkp/lkp/src/bin/run-ipconfig: 246: .: Can't open /run/net-eth0.conf [ 6.484683] [<ffffffffc00e19a0>] ? parport_pc_pci_probe+0x270/0x270 [parport_pc] [ 6.484686] [<ffffffff8795aecc>] pnp_device_probe+0x5c/0xb0 [ 6.484687] [<ffffffff879b135d>] driver_probe_device+0x21d/0x490 [ 6.484689] [<ffffffff879b164f>] __driver_attach+0x7f/0x90 [ 6.484690] [<ffffffff879b15d0>] ? driver_probe_device+0x490/0x490 [ 6.484691] [<ffffffff879af1df>] bus_for_each_dev+0x5f/0x90 [ 6.484693] [<ffffffff879b0b69>] driver_attach+0x19/0x20 [ 6.484694] [<ffffffff879b0711>] bus_add_driver+0x1e1/0x280 [ 6.484695] [<ffffffff879b1adb>] driver_register+0x5b/0xd0 [ 6.484697] [<ffffffff8795ad3b>] pnp_register_driver+0x1b/0x20 !!! IP-Config: Auto-configuration of network failed !!! [ 6.484700] [<ffffffffc00e84ef>] parport_pc_init+0x437/0xf48 [parport_pc] [ 6.484702] [<ffffffffc00e80b8>] ? parport_parse_param+0xb8/0xb8 [parport_pc] [ 6.484704] [<ffffffff876003de>] do_one_initcall+0xae/0x1f0 [ 6.484705] [<ffffffff876a67f4>] ? __might_sleep+0x44/0x80 [ 6.484707] [<ffffffff87706d8b>] do_init_module+0x5a/0x38e [ 6.484710] [<ffffffff876e7e1d>] load_module+0x1ded/0x2300 [ 6.484711] [<ffffffff876e5320>] ? m_show+0x100/0x100 [ 6.484713] [<ffffffff876e5605>] ? copy_module_from_fd+0xc5/0x120 [ 6.484714] [<ffffffff876e8535>] SyS_finit_module+0x95/0xc0 [ 6.484716] [<ffffffff87b09e2e>] entry_SYSCALL_64_fastpath+0x12/0x71 [ 6.484750] BUG: scheduling while atomic: systemd-udevd/134/0x00000000 [ 6.484758] Modules linked in: crc32c_intel pcspkr evdev i6300esb ide_cd_mod cdrom intel_agp intel_gtt i2c_piix4 i2c_core virtio_pci virtio virtio_ring agpgart rtc_cmos(+) parport_pc(+) autofs4 [ 6.484760] CPU: 0 PID: 134 Comm: systemd-udevd Tainted: G W 4.4.0-rc1-00006-g2baed30 #1 [ 6.484761] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 [ 6.484762] 0000000000012880 ffff88007f8bb6b8 ffffffff878a5e4d ffff880078612880 [ 6.484763] ffff88007f8bb6c8 ffffffff876a64d6 ffff88007f8bb708 ffffffff87b05f69 [ 6.484764] ffff88005d04e340 ffff88007f8bc000 7fffffffffffffff ffff88005d04e340 [ 6.484765] Call Trace: [ 6.484767] [<ffffffff878a5e4d>] dump_stack+0x4b/0x6e [ 6.484768] [<ffffffff876a64d6>] __schedule_bug+0x46/0x60 [ 6.484770] [<ffffffff87b05f69>] __schedule+0x549/0x780 [ 6.484771] [<ffffffff87b061d7>] schedule+0x37/0x80 [ 6.484772] [<ffffffff87b09057>] schedule_timeout+0x137/0x180 [ 6.484773] [<ffffffff87b07528>] wait_for_completion+0xb8/0x120 [ 6.484775] [<ffffffff876a95c0>] ? wake_up_q+0x60/0x60 [ 6.484777] [<ffffffff879b6f44>] devtmpfs_create_node+0xf4/0x120 [ 6.484779] [<ffffffff879ae449>] device_add+0x5c9/0x5e0 [ 6.484781] [<ffffffff879ae646>] device_create_groups_vargs+0xd6/0xf0 [ 6.484782] [<ffffffff879ae6f1>] device_create_with_groups+0x31/0x40 [ 6.484784] [<ffffffff87b07fab>] ? mutex_lock+0x1b/0x40 [ 6.484785] [<ffffffff8799b7ee>] misc_register+0xae/0x180 [ 6.484787] [<ffffffff879a6260>] ? dead_read+0x10/0x10 [ 6.484788] [<ffffffff87a80bdd>] panel_attach+0x72d/0xb30 [ 6.484790] [<ffffffff879a6260>] ? dead_read+0x10/0x10 [ 6.484791] [<ffffffff879a6272>] driver_check+0x12/0x20 [ 6.484793] [<ffffffff879af286>] bus_for_each_drv+0x56/0x90 [ 6.484794] [<ffffffff879a6504>] attach_driver_chain+0x54/0x60 [ 6.484795] [<ffffffff879a65fb>] parport_announce_port+0xeb/0x140 [ 6.484798] [<ffffffffc00e1219>] parport_pc_probe_port+0x729/0xc40 [parport_pc] [ 6.484800] [<ffffffff879ad3c7>] ? __dev_printk+0x37/0x80 [ 6.484801] [<ffffffff879ad757>] ? _dev_info+0x57/0x60 [ 6.484804] [<ffffffffc00e1ade>] parport_pc_pnp_probe+0x13e/0x1e0 [parport_pc] [ 6.484807] [<ffffffffc00e19a0>] ? parport_pc_pci_probe+0x270/0x270 [parport_pc] [ 6.484808] [<ffffffff8795aecc>] pnp_device_probe+0x5c/0xb0 [ 6.484810] [<ffffffff879b135d>] driver_probe_device+0x21d/0x490 [ 6.484811] [<ffffffff879b164f>] __driver_attach+0x7f/0x90 [ 6.484812] [<ffffffff879b15d0>] ? driver_probe_device+0x490/0x490 [ 6.484814] [<ffffffff879af1df>] bus_for_each_dev+0x5f/0x90 [ 6.484815] [<ffffffff879b0b69>] driver_attach+0x19/0x20 [ 6.484816] [<ffffffff879b0711>] bus_add_driver+0x1e1/0x280 [ 6.484817] [<ffffffff879b1adb>] driver_register+0x5b/0xd0 [ 6.484819] [<ffffffff8795ad3b>] pnp_register_driver+0x1b/0x20 [ 6.484821] [<ffffffffc00e84ef>] parport_pc_init+0x437/0xf48 [parport_pc] [ 6.484823] [<ffffffffc00e80b8>] ? parport_parse_param+0xb8/0xb8 [parport_pc] [ 6.484824] [<ffffffff876003de>] do_one_initcall+0xae/0x1f0 [ 6.484826] [<ffffffff876a67f4>] ? __might_sleep+0x44/0x80 [ 6.484827] [<ffffffff87706d8b>] do_init_module+0x5a/0x38e [ 6.484829] [<ffffffff876e7e1d>] load_module+0x1ded/0x2300 [ 6.484830] [<ffffffff876e5320>] ? m_show+0x100/0x100 [ 6.484831] [<ffffffff876e5605>] ? copy_module_from_fd+0xc5/0x120 [ 6.484833] [<ffffffff876e8535>] SyS_finit_module+0x95/0xc0 [ 6.484835] [<ffffffff87b09e2e>] entry_SYSCALL_64_fastpath+0x12/0x71 [ 6.502415] rtc_cmos 00:00: RTC can wake from S4 [ 6.502722] rtc rtc0: alarm rollover: day [ 6.502841] rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0 [ 6.503016] rtc_cmos 00:00: alarms up to one day, 114 bytes nvram, hpet irqs !!! IP-Config: Auto-configuration of network failed !!! [ 7.087624] advantechwdt: Unexpected close, not stopping watchdog! [ 7.097583] systemd-journald[110]: Received SIGTERM from PID 1 (systemd-shutdow). [ 7.099582] systemd-journald[110]: systemd-journald stopped as pid 110 [ 7.101053] systemd-journald[110]: Assertion 's->event->state != SD_EVENT_FINISHED' failed at ../src/libsystemd/sd-event/sd-event.c:1538, function sd_event_source_set_time(). Ignoring. [ 7.182675] Unregister pv shared memory for cpu 0 [ 7.183875] Unregister pv shared memory for cpu 1 [ 7.185019] BUG: scheduling while atomic: systemd-shutdow/1/0x00000002 [ 7.186359] Modules linked in: crc32c_intel pcspkr evdev i6300esb ide_cd_mod cdrom intel_agp intel_gtt i2c_piix4 i2c_core virtio_pci virtio virtio_ring agpgart rtc_cmos parport_pc autofs4 [ 7.191095] CPU: 1 PID: 1 Comm: systemd-shutdow Tainted: G W 4.4.0-rc1-00006-g2baed30 #1 [ 7.193163] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 [ 7.195158] 0000000000012880 ffff8800760a3ba8 ffffffff878a5e4d ffff880078712880 [ 7.197294] ffff8800760a3bb8 ffffffff876a64d6 ffff8800760a3bf8 ffffffff87b05f69 [ 7.199436] ffff88007609d340 ffff8800760a4000 0000000000000020 0000000000000001 [ 7.201579] Call Trace: [ 7.202432] [<ffffffff878a5e4d>] dump_stack+0x4b/0x6e [ 7.203572] [<ffffffff876a64d6>] __schedule_bug+0x46/0x60 [ 7.204762] [<ffffffff87b05f69>] __schedule+0x549/0x780 [ 7.205929] [<ffffffff87b061d7>] schedule+0x37/0x80 [ 7.207050] [<ffffffff87b0930f>] schedule_hrtimeout_range_clock+0x9f/0x100 [ 7.208447] [<ffffffff876d22b0>] ? hrtimer_init+0x10/0x10 [ 7.209624] [<ffffffff87b0937e>] schedule_hrtimeout_range+0xe/0x10 [ 7.210915] [<ffffffff87b08f16>] usleep_range+0x46/0x50 [ 7.212106] [<ffffffff87a7f7a3>] lcd_write_cmd_p8+0x33/0x90 [ 7.213294] [<ffffffff87a7f10e>] lcd_gotoxy+0x4e/0x50 [ 7.214433] [<ffffffff87a7f60a>] lcd_clear_fast_p8+0x2a/0x100 [ 7.215645] [<ffffffff87a7fe59>] lcd_write_char+0x1b9/0x740 [ 7.216833] [<ffffffff87a8041b>] panel_lcd_print+0x3b/0x70 [ 7.218036] [<ffffffff87a80484>] panel_notify_sys+0x34/0x60 [ 7.219242] [<ffffffff876a2f85>] notifier_call_chain+0x45/0x70 [ 7.220461] [<ffffffff876a32b2>] __blocking_notifier_call_chain+0x42/0x60 [ 7.221813] [<ffffffff876a32e1>] blocking_notifier_call_chain+0x11/0x20 [ 7.223116] [<ffffffff876a3fb8>] kernel_restart_prepare+0x18/0x40 Thanks, Kernel Test Robot

4 years, 11 months

3
8
0 / 0

[perf/core] d3b24f87c8: [ INFO: suspicious RCU usage. ]

by kernel test robot

Greetings, 0day kernel testing robot got the below dmesg and the first bad commit is yhuang/dbg_d6980ef325 commit d3b24f87c85f0c96d7b90af7fb2fd6ddbf4c5dac Author: Kan Liang <kan.liang(a)intel.com> AuthorDate: Thu Jan 21 15:52:37 2016 +0000 Commit: Huang Ying <ying.huang(a)intel.com> CommitDate: Mon Jan 25 11:10:56 2016 +0800 perf/core: find auxiliary events in running pmus list perf_event_aux funciton goes through pmus list to find proper auxiliary events to output. The pmus list consists of all possible pmus in the system, that may or may not be running at the moment, while the auxiliary events must be from the running pmus. Therefore searching non-running pmus is unnecessary and expensive especially when there are many non-running pmus on the list. For example, the brk test case in lkp triggers many mmap operations, at the time, perf with cycles:pp is also running on the system. As a result, many perf_event_aux are invoked, and each would search the whole pmus list. If we enable the uncore support (even when uncore event are not really used), dozens of uncore pmus will be added into pmus list, which can significantly decrease brk_test's ops_per_sec. Based on our test, the ops_per_sec without uncore patch is 2647573, while the ops_per_sec with uncore patch is only 1768444, which is a 33.2% reduction. This patch introduces a running_pmus list which only tracks the running pmus in the system. The perf_event_aux uses running_pmus list instead of pmus list to find auxiliary events. Reported-by: Huang, Ying <ying.huang(a)linux.intel.com> Signed-off-by: Kan Liang <kan.liang(a)intel.com> +-----------------------------------------------------------------------------+------------+------------+------------+ | | 7fdec82af6 | d3b24f87c8 | 43a5a3ac22 | +-----------------------------------------------------------------------------+------------+------------+------------+ | boot_successes | 63 | 0 | 0 | | boot_failures | 3 | 22 | 13 | | BUG:unable_to_handle_kernel | 3 | 0 | 1 | | Oops | 3 | 0 | 1 | | EIP_is_at_perf_prepare_sample | 3 | | | | Kernel_panic-not_syncing:Fatal_exception | 3 | | | | backtrace:iterate_dir | 2 | | | | backtrace:SyS_getdents64 | 2 | | | | INFO:suspicious_RCU_usage | 0 | 22 | 13 | | BUG:sleeping_function_called_from_invalid_context_at_kernel/locking/mutex.c | 0 | 22 | 13 | | INFO:lockdep_is_turned_off | 0 | 22 | 13 | | backtrace:SyS_brk | 0 | 22 | 13 | | backtrace:do_brk | 0 | 22 | 13 | | backtrace:do_group_exit | 0 | 1 | 1 | | backtrace:SyS_exit_group | 0 | 1 | 1 | +-----------------------------------------------------------------------------+------------+------------+------------+ Kernel tests: Boot OK! [ 19.556874] [ 19.557455] =============================== [ 19.558347] [ INFO: suspicious RCU usage. ] [ 19.559219] 4.4.0-05594-gd3b24f8 #61 Not tainted [ 19.560073] ------------------------------- [ 19.560881] include/linux/rcupdate.h:573 Illegal context switch in RCU read-side critical section! [ 19.562719] [ 19.562719] other info that might help us debug this: [ 19.562719] [ 19.574393] [ 19.574393] rcu_scheduler_active = 1, debug_locks = 0 [ 19.575918] 2 locks held by trinity-main/388: [ 19.576795] #0: (&mm->mmap_sem){++++++}, at: [<c10bb11e>] SyS_brk+0x23/0x127 [ 19.578616] #1: (rcu_read_lock){......}, at: [<c108ec2c>] perf_event_aux+0xce/0x233 [ 19.580484] [ 19.580484] stack backtrace: [ 19.581675] CPU: 0 PID: 388 Comm: trinity-main Not tainted 4.4.0-05594-gd3b24f8 #61 [ 19.583196] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 [ 19.585088] 00000000 00000001 c011bdf4 c1327e55 c011be10 c1060115 c185b949 d20eb280 [ 19.587991] c185b3b8 00000269 00000000 c011be24 c1051334 d20eb280 00000000 00000000 [ 19.590021] c011be40 c1051514 00000000 c011be64 00000246 c1aae5e0 c011becc c011be74 [ 19.592049] Call Trace: [ 19.592744] [<c1327e55>] dump_stack+0x16/0x18 [ 19.593582] [<c1060115>] lockdep_rcu_suspicious+0xc6/0xcf [ 19.594548] [<c1051334>] ___might_sleep+0x47/0x1bd [ 19.595493] [<c1051514>] __might_sleep+0x6a/0x71 [ 19.596357] [<c165440f>] mutex_lock_nested+0x1e/0x2b8 [ 19.597260] [<c108ec2c>] ? perf_event_aux+0xce/0x233 [ 19.598229] [<c108ec90>] perf_event_aux+0x132/0x233 [ 19.599122] [<c132dc72>] ? strlcpy+0x15/0x35 [ 19.599956] [<c1094f82>] ? perf_event_comm_output+0x147/0x147 [ 19.600968] [<c109610f>] perf_event_mmap+0x28b/0x29e [ 19.601863] [<c10bb040>] do_brk+0x17b/0x236 [ 19.602718] [<c10bb1d8>] SyS_brk+0xdd/0x127 [ 19.603543] [<c1000e8b>] do_syscall_32_irqs_on+0x43/0xdc [ 19.604502] [<c165769e>] entry_INT80_32+0x2a/0x2a [ 19.605473] BUG: sleeping function called from invalid context at kernel/locking/mutex.c:617 [ 19.607196] in_atomic(): 1, irqs_disabled(): 0, pid: 388, name: trinity-main [ 19.608344] INFO: lockdep is turned off. [ 19.609134] CPU: 0 PID: 388 Comm: trinity-main Not tainted 4.4.0-05594-gd3b24f8 #61 [ 19.610650] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 [ 19.612378] 00000000 d20eb280 c011be10 c1327e55 c011be24 c10514a2 d20eb280 00000000 [ 19.614384] 00000000 c011be40 c1051514 00000000 c011be64 00000246 c1aae5e0 c011becc [ 19.616344] c011be74 c165440f c108ec2c 00000000 00000000 00000246 00000000 c011becc [ 19.618393] Call Trace: [ 19.619042] [<c1327e55>] dump_stack+0x16/0x18 [ 19.619887] [<c10514a2>] ___might_sleep+0x1b5/0x1bd [ 19.620777] [<c1051514>] __might_sleep+0x6a/0x71 [ 19.621641] [<c165440f>] mutex_lock_nested+0x1e/0x2b8 [ 19.622571] [<c108ec2c>] ? perf_event_aux+0xce/0x233 [ 19.623480] [<c108ec90>] perf_event_aux+0x132/0x233 [ 19.624418] [<c132dc72>] ? strlcpy+0x15/0x35 [ 19.625420] [<c1094f82>] ? perf_event_comm_output+0x147/0x147 [ 19.626406] [<c109610f>] perf_event_mmap+0x28b/0x29e [ 19.627327] [<c10bb040>] do_brk+0x17b/0x236 [ 19.628143] [<c10bb1d8>] SyS_brk+0xdd/0x127 [ 19.628962] [<c1000e8b>] do_syscall_32_irqs_on+0x43/0xdc [ 19.629927] [<c165769e>] entry_INT80_32+0x2a/0x2a [ 20.020006] VFS: Warning: trinity-c0 using old stat() call. Recompile your binary. [ 20.025280] mmap: trinity-c0 (389) uses deprecated remap_file_pages() syscall. See Documentation/vm/remap_file_pages.txt. [ 20.611965] BUG: sleeping function called from invalid context at kernel/locking/mutex.c:617 [ 20.613757] in_atomic(): 1, irqs_disabled(): 0, pid: 391, name: trinity-main [ 20.614871] INFO: lockdep is turned off. [ 20.615670] CPU: 0 PID: 391 Comm: trinity-main Not tainted 4.4.0-05594-gd3b24f8 #61 [ 20.617205] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 [ 20.618861] 00000000 d2171940 d215be10 c1327e55 d215be24 c10514a2 d2171940 00000000 [ 20.620845] 00000000 d215be40 c1051514 00000000 d215be64 00000246 c1aae5e0 d215becc [ 20.622870] d215be74 c165440f c108ec2c 00000000 00000000 00000246 00000000 d215becc [ 20.624854] Call Trace: [ 20.625505] [<c1327e55>] dump_stack+0x16/0x18 [ 20.626372] [<c10514a2>] ___might_sleep+0x1b5/0x1bd [ 20.627269] [<c1051514>] __might_sleep+0x6a/0x71 [ 20.628137] [<c165440f>] mutex_lock_nested+0x1e/0x2b8 [ 20.629067] [<c108ec2c>] ? perf_event_aux+0xce/0x233 [ 20.630018] [<c108ec90>] perf_event_aux+0x132/0x233 [ 20.630927] [<c132dc72>] ? strlcpy+0x15/0x35 [ 20.631769] [<c1094f82>] ? perf_event_comm_output+0x147/0x147 [ 20.632754] [<c109610f>] perf_event_mmap+0x28b/0x29e [ 20.633673] [<c10bb040>] do_brk+0x17b/0x236 [ 20.634526] [<c10bb1d8>] SyS_brk+0xdd/0x127 [ 20.635361] [<c1000e8b>] do_syscall_32_irqs_on+0x43/0xdc [ 20.636298] [<c165769e>] entry_INT80_32+0x2a/0x2a [ 20.865569] VFS: Warning: trinity-c0 using old stat() call. Recompile your binary. git bisect start 43a5a3ac2281f0f1b3b56533025ca7231b15c810 92e963f50fc74041b5e9e744c330dca48e04f08d -- git bisect bad 1d901e321a5371e9a6f2108a4e214f6725437ae7 # 11:48 0- 11 Merge 'arm-platforms/kvm-arm64/fixes-4.5-rc1' into devel-spot-201601251122 git bisect bad 8fc3dc5927bc073aa2bbe00f47e375f1538df1f7 # 11:51 0- 3 Merge 'nomadik/nhk15-clcd' into devel-spot-201601251122 git bisect bad db5170859dcd8a897c1c793525acca876e21c022 # 11:54 0- 24 Merge 'krzk/for-next' into devel-spot-201601251122 git bisect bad e9cc0be488e0d7c2966450f75e519b37da8b8794 # 11:56 0- 24 Merge 'yhuang/dbg_d6980ef325' into devel-spot-201601251122 git bisect good 073df5bdf000ee23c3e2528b92d0d036ff31a803 # 12:00 22+ 0 0day base guard for 'devel-spot-201601251122' git bisect bad d3b24f87c85f0c96d7b90af7fb2fd6ddbf4c5dac # 12:03 0- 22 perf/core: find auxiliary events in running pmus list # first bad commit: [d3b24f87c85f0c96d7b90af7fb2fd6ddbf4c5dac] perf/core: find auxiliary events in running pmus list git bisect good 7fdec82af6a9e190e53d07a1463d2a9ac49a8750 # 12:07 66+ 3 Merge tag 'xfs-for-linus-4.5' of git://git.kernel.org/pub/scm/linux/kernel/git/dgc/linux-xfs # extra tests with DEBUG_INFO git bisect bad d3b24f87c85f0c96d7b90af7fb2fd6ddbf4c5dac # 12:11 0- 57 perf/core: find auxiliary events in running pmus list # extra tests on HEAD of linux-devel/devel-spot-201601251122 git bisect bad 43a5a3ac2281f0f1b3b56533025ca7231b15c810 # 12:11 0- 13 0day head guard for 'devel-spot-201601251122' # extra tests on tree/branch yhuang/dbg_d6980ef325 git bisect bad d3b24f87c85f0c96d7b90af7fb2fd6ddbf4c5dac # 12:15 0- 22 perf/core: find auxiliary events in running pmus list # extra tests with first bad commit reverted git bisect good 352e4b0f6b8a9905372e9f3a363325cbaa8f20ae # 12:20 66+ 1 Revert "perf/core: find auxiliary events in running pmus list" # extra tests on tree/branch linus/master git bisect good 92e963f50fc74041b5e9e744c330dca48e04f08d # 12:22 66+ 13 Linux 4.5-rc1 # extra tests on tree/branch linux-next/master git bisect good e216cada8e1b4d6c278a2a9af051aeba9b7f1bbe # 12:28 66+ 3 Add linux-next specific files for 20160125 This script may reproduce the error. ---------------------------------------------------------------------------- #!/bin/bash kernel=$1 initrd=quantal-core-i386.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

4 years, 11 months

1
0
0 / 0

2021

2020

2019

2018

2017

2016

2015

2014

2013

LKP January 2016