Re: [LKP] [mm] cc87317726f: WARNING: CPU: 0 PID:1atdrivers/iommu/io-pgtable-arm.c:413 __arm_lpae_unmap+0x341/0x380()
by Michal Hocko
On Fri 20-03-15 23:02:09, Tetsuo Handa wrote:
> Michal Hocko wrote:
> > On Fri 20-03-15 22:34:21, Tetsuo Handa wrote:
> > > Huang Ying wrote:
> > > > > > BTW: the test is run on 32 bit system.
> > > > >
> > > > > That sounds like the cause of your problem. The system might be out of
> > > > > address space available for the kernel (only 1GB if x86_32). You should
> > > > > try running tests on 64 bit systems.
> > > >
> > > > We run test on 32 bit and 64 bit systems. Try to catch problems on both
> > > > platforms. I think we still need to support 32 bit systems?
> > >
> > > Yes, testing on both platforms is good. But please read
> > > http://lwn.net/Articles/627419/ , http://lwn.net/Articles/635354/ and
> > > http://lwn.net/Articles/636017/ . Then please add __GFP_NORETRY to memory
> > > allocations in btrfs code if it is appropriate.
> >
> > I guess you meant __GFP_NOFAIL?
> >
> No. btrfs's selftest (which is not using __GFP_NOFAIL) is already looping
> forever. If we want to avoid btrfs's selftest from looping forever, btrfs
> needs __GFP_NORETRY than __GFP_NOFAIL (until we establish a way to safely
> allow small allocations to fail).
Sigh. If the code is using GFP_NOFS allocation (which seem to be the
case because it worked with the 9879de7373fc) and the proper fix for
this IMO is to simply not retry endlessly for these allocations. We
have to sort some other issues before we can make NOFS allocations fail
but let's not pile more workarounds on top in the meantime. But if btrfs
people really think __GFP_NORETRY then I do not really care much.
--
Michal Hocko
SUSE Labs
7 years, 1 month
[ipv4, ipv6] baf606d9c9b: INFO: possible circular locking dependency detected ]
by Huang Ying
FYI, we noticed the below changes on
git://git.kernel.org/pub/scm/linux/kernel/git/davem/net-next.git master
commit baf606d9c9b12517e47e0d1370e8aa9f7323f210 ("ipv4,ipv6: grab rtnl before locking the socket")
+----------------------------------------------------+------------+------------+
| | fdf9ef8999 | baf606d9c9 |
+----------------------------------------------------+------------+------------+
| boot_successes | 26 | 12 |
| boot_failures | 1 | 19 |
| BUG:kernel_test_crashed | 1 | |
| INFO:possible_circular_locking_dependency_detected | 0 | 19 |
| backtrace:sock_common_setsockopt | 0 | 19 |
| backtrace:SyS_setsockopt | 0 | 19 |
| page_allocation_failure:order:#,mode | 0 | 2 |
| backtrace:vmalloc | 0 | 2 |
| backtrace:SyS_init_module | 0 | 2 |
+----------------------------------------------------+------------+------------+
[ 19.815737]
[ 19.816009] ======================================================
[ 19.816645] [ INFO: possible circular locking dependency detected ]
[ 19.816665] 4.0.0-rc4-01766-gdccfae9 #6 Not tainted
[ 19.816665] -------------------------------------------------------
[ 19.816665] trinity-main/3132 is trying to acquire lock:
[ 19.816665] (rtnl_mutex){+.+.+.}, at: [<ffffffff81a552b4>] rtnl_lock+0x17/0x19
[ 19.816665]
[ 19.816665] but task is already holding lock:
[ 19.816665] (sk_lock-AF_INET6){+.+.+.}, at: [<ffffffff81b0f396>] do_ipv6_setsockopt+0xc3/0xd98
[ 19.816665]
[ 19.816665] which lock already depends on the new lock.
[ 19.816665]
[ 19.816665]
[ 19.816665] the existing dependency chain (in reverse order) is:
[ 19.816665]
-> #1 (sk_lock-AF_INET6){+.+.+.}:
[ 19.816665] [<ffffffff810c2c4d>] lock_acquire+0xb2/0x132
[ 19.816665] [<ffffffff81a358be>] lock_sock_nested+0x7d/0x9b
[ 19.816665] [<ffffffff81b0f396>] do_ipv6_setsockopt+0xc3/0xd98
[ 19.816665] [<ffffffff81b100bc>] ipv6_setsockopt+0x51/0x9c
[ 19.816665] [<ffffffff81aa81c1>] tcp_setsockopt+0x20/0x2e
[ 19.816665] [<ffffffff81a345a0>] sock_common_setsockopt+0x14/0x16
[ 19.816665] [<ffffffff81a33ad0>] SyS_setsockopt+0x78/0x99
[ 19.816665] [<ffffffff81b91289>] system_call_fastpath+0x12/0x17
[ 19.816665]
-> #0 (rtnl_mutex){+.+.+.}:
[ 19.816665] [<ffffffff810c1ea4>] __lock_acquire+0x13f0/0x1a86
[ 19.816665] [<ffffffff810c2c4d>] lock_acquire+0xb2/0x132
[ 19.816665] [<ffffffff81b8d97b>] mutex_lock_nested+0x6b/0x38d
[ 19.816665] [<ffffffff81a552b4>] rtnl_lock+0x17/0x19
[ 19.816665] [<ffffffff81afc5c6>] ipv6_sock_ac_drop+0x28/0xc4
[ 19.816665] [<ffffffff81b0fc30>] do_ipv6_setsockopt+0x95d/0xd98
[ 19.816665] [<ffffffff81b100bc>] ipv6_setsockopt+0x51/0x9c
[ 19.816665] [<ffffffff81b160af>] rawv6_setsockopt+0x7c/0x91
[ 19.816665] [<ffffffff81a345a0>] sock_common_setsockopt+0x14/0x16
[ 19.816665] [<ffffffff81a33ad0>] SyS_setsockopt+0x78/0x99
[ 19.816665] [<ffffffff81b91289>] system_call_fastpath+0x12/0x17
[ 19.816665]
[ 19.816665] other info that might help us debug this:
[ 19.816665]
[ 19.816665] Possible unsafe locking scenario:
[ 19.816665]
[ 19.816665] CPU0 CPU1
[ 19.816665] ---- ----
[ 19.816665] lock(sk_lock-AF_INET6);
[ 19.816665] lock(rtnl_mutex);
[ 19.816665] lock(sk_lock-AF_INET6);
[ 19.816665] lock(rtnl_mutex);
[ 19.816665]
[ 19.816665] *** DEADLOCK ***
[ 19.816665]
[ 19.816665] 1 lock held by trinity-main/3132:
[ 19.816665] #0: (sk_lock-AF_INET6){+.+.+.}, at: [<ffffffff81b0f396>] do_ipv6_setsockopt+0xc3/0xd98
[ 19.816665]
[ 19.816665] stack backtrace:
[ 19.816665] CPU: 1 PID: 3132 Comm: trinity-main Not tainted 4.0.0-rc4-01766-gdccfae9 #6
[ 19.816665] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.7.5-20140531_083030-gandalf 04/01/2014
[ 19.816665] ffffffff82e878c0 ffff880009e7ba78 ffffffff81b82dcf ffffffff810d0bdb
[ 19.816665] ffffffff82e45300 ffff880009e7bac8 ffffffff81b7e2ba ffff880009e7bab8
[ 19.816665] ffff880009e74b60 ffff880009e753f0 ffff880009e74b60 ffff880009e753f0
[ 19.816665] Call Trace:
[ 19.816665] [<ffffffff81b82dcf>] dump_stack+0x4c/0x65
[ 19.816665] [<ffffffff810d0bdb>] ? console_unlock+0x398/0x3c7
[ 19.816665] [<ffffffff81b7e2ba>] print_circular_bug+0x2b1/0x2c2
[ 19.816665] [<ffffffff810c1ea4>] __lock_acquire+0x13f0/0x1a86
[ 19.816665] [<ffffffff810aba93>] ? sched_clock_local+0x11/0x78
[ 19.816665] [<ffffffff810c2c4d>] lock_acquire+0xb2/0x132
[ 19.816665] [<ffffffff81a552b4>] ? rtnl_lock+0x17/0x19
[ 19.816665] [<ffffffff81a552b4>] ? rtnl_lock+0x17/0x19
[ 19.816665] [<ffffffff81b8d97b>] mutex_lock_nested+0x6b/0x38d
[ 19.816665] [<ffffffff81a552b4>] ? rtnl_lock+0x17/0x19
[ 19.816665] [<ffffffff810c2e64>] ? lock_release_non_nested+0x197/0x2d9
[ 19.816665] [<ffffffff81173752>] ? might_fault+0x58/0xb5
[ 19.816665] [<ffffffff81a552b4>] rtnl_lock+0x17/0x19
[ 19.816665] [<ffffffff81afc5c6>] ipv6_sock_ac_drop+0x28/0xc4
[ 19.816665] [<ffffffff81b0fc30>] do_ipv6_setsockopt+0x95d/0xd98
[ 19.816665] [<ffffffff81071c71>] ? kvm_clock_read+0x25/0x2e
[ 19.816665] [<ffffffff81045a03>] ? sched_clock+0x9/0xd
[ 19.816665] [<ffffffff810aba93>] ? sched_clock_local+0x11/0x78
[ 19.816665] [<ffffffff810abd27>] ? sched_clock_cpu+0x9a/0xaf
[ 19.816665] [<ffffffff81a35ec4>] ? release_sock+0x173/0x1b1
[ 19.816665] [<ffffffff81086678>] ? __local_bh_enable_ip+0xa8/0xb4
[ 19.816665] [<ffffffff810c07cc>] ? trace_hardirqs_on_caller+0x191/0x1ad
[ 19.816665] [<ffffffff81a35ec4>] ? release_sock+0x173/0x1b1
[ 19.816665] [<ffffffff810c07f5>] ? trace_hardirqs_on+0xd/0xf
[ 19.816665] [<ffffffff81086678>] ? __local_bh_enable_ip+0xa8/0xb4
[ 19.816665] [<ffffffff81b100bc>] ipv6_setsockopt+0x51/0x9c
[ 19.816665] [<ffffffff81b160af>] rawv6_setsockopt+0x7c/0x91
[ 19.816665] [<ffffffff811bce12>] ? __fdget+0x13/0x15
[ 19.816665] [<ffffffff81a345a0>] sock_common_setsockopt+0x14/0x16
[ 19.816665] [<ffffffff81a33ad0>] SyS_setsockopt+0x78/0x99
[ 19.816665] [<ffffffff81b91289>] system_call_fastpath+0x12/0x17
[ 62.401064] rcu-torture: rtc: ffffffff83417640 ver: 1 tfle: 0 rta: 1 rtaf: 0 rtf: 0 rtmbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 1 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=1000) barrier: 0/0:0 cbflood: 1
[ 62.404218] rcu-torture: Reader Pipe: 2 0 0 0 0 0 0 0 0 0 0
[ 62.405846] rcu-torture: Reader Batch: 2 0 0 0 0 0 0 0 0 0 0
Thanks,
Ying Huang
_______________________________________________
LKP mailing list
LKP(a)linux.intel.com
7 years, 1 month
[Merge remote] a92aac4f369: -- System halted
by Huang Ying
FYI, we noticed the below changes on
git://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git master
commit a92aac4f369eee8b410a94babc0b380159062f10 ("Merge remote-tracking branch 'tip/auto-latest'")
+----------------------+------------+------------+------------+
| | afb48ed702 | eddeaa800c | a92aac4f36 |
+----------------------+------------+------------+------------+
| boot_successes | 20 | 20 | 0 |
| early-boot-hang | 1 | 1 | |
| boot_failures | 0 | 0 | 15 |
| System_halted | 0 | 0 | 15 |
| BUG:kernel_boot_hang | 0 | 0 | 15 |
+----------------------+------------+------------+------------+
early console in setup code
early console in decompress_kernel
Decompressing Linux...
Decoding failed
-- System halted
BUG: kernel boot hang
Elapsed time: 305
qemu-system-x86_64 -enable-kvm -cpu SandyBridge -kernel /kernel/x86_64-randconfig-iv0-03191805/ce040cc379e3e1e094db5559f393d794fbfd01e4/vmlinuz-4.0.0-rc4-next-20150319 -append 'user=lkp job=/lkp/scheduled/vm-kbuild-yocto-x86_64-29/rand_boot-1-yocto-minimal-x86_64.cgz-x86_64-randconfig-iv0-03191805-ce040cc379e3e1e094db5559f393d794fbfd01e4-1-20150319-57492-1xfdchl.yaml ARCH=x86_64 BOOT_IMAGE=/kernel/x86_64-randconfig-iv0-03191805/ce040cc379e3e1e094db5559f393d794fbfd01e4/vmlinuz-4.0.0-rc4-next-20150319 kconfig=x86_64-randconfig-iv0-03191805 commit=ce040cc379e3e1e094db5559f393d794fbfd01e4 branch=next/master root=/dev/ram0 max_uptime=3600 RESULT_ROOT=/result/vm-kbuild-yocto-x86_64/boot/1/yocto-minimal-x86_64.cgz/x86_64-randconfig-iv0-03191805/ce040cc379e3e1e094db5559f393d794fbfd01e4/0 ip=::::vm-kbuild-yocto-x86_64-29::dhcp earlyprintk=ttyS0,115200 rd.udev.log-priority=err systemd.log_target=journal systemd.log_level=warning 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 drbd.minor_count=8' -initrd /fs/sdd1/initrd-vm-kbuild-yocto-x86_64-29 -m 320 -smp 1 -net nic,vlan=1,model=e1000 -net user,vlan=1 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -drive file=/fs/sdd1/disk0-vm-kbuild-yocto-x86_64-29,media=disk,if=virtio -pidfile /dev/shm/kboot/pid-vm-kbuild-yocto-x86_64-29 -serial file:/dev/shm/kboot/serial-vm-kbuild-yocto-x86_64-29 -daemonize -display none -monitor null
Thanks,
Ying Huang
_______________________________________________
LKP mailing list
LKP(a)linux.intel.com
7 years, 1 month
[x86/cacheinfo] 0d55ba46bfb: WARNING: CPU: 31 PID: 1 at fs/sysfs/group.c:102 internal_create_group+0x151/0x280()
by Huang Ying
FYI, we noticed the below changes on
git://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git master
commit 0d55ba46bfbee64fd2b492b87bfe2ec172e7b056 ("x86/cacheinfo: Move cacheinfo sysfs code to generic infrastructure")
+------------------------------------------------------+----------+-------------------+
| | v4.0-rc2 | tip_x86_cacheinfo |
+------------------------------------------------------+----------+-------------------+
| boot_successes | 851 | 10 |
| boot_failures | 19 | 9 |
| BUG:kernel_test_crashed | 10 | |
| BUG:kernel_boot_oversize | 4 | |
| BUG:kernel_test_oversize | 2 | |
| BUG:kernel_test_hang | 3 | |
| WARNING:at_fs/sysfs/group.c:#internal_create_group() | 0 | 9 |
| backtrace:cache_add_dev | 0 | 9 |
| backtrace:cacheinfo_sysfs_init | 0 | 9 |
| backtrace:kernel_init_freeable | 0 | 9 |
+------------------------------------------------------+----------+-------------------+
[ 8.700596] Non-volatile memory driver v1.3
[ 8.705684] Linux agpgart interface v0.103
[ 8.711362] ------------[ cut here ]------------
[ 8.716832] WARNING: CPU: 31 PID: 1 at fs/sysfs/group.c:102 internal_create_group+0x151/0x280()
[ 8.729104] sysfs: (bin_)attrs not set by subsystem for group: index3/
[ 8.736714] Modules linked in:
[ 8.740520] CPU: 31 PID: 1 Comm: swapper/0 Not tainted 4.0.0-rc4-next-20150317 #1
[ 8.754247] Hardware name: Intel Corporation S2600CP/S2600CP, BIOS SE5C600.86B.99.99.x036.091920111209 09/19/2011
[ 8.766240] ffffffff81baac48 ffff88042d64fb78 ffffffff818b2806 0000000000003fae
[ 8.775396] ffff88042d64fbc8 ffff88042d64fbb8 ffffffff8107293a ffff88042d64fba8
[ 8.784545] 0000000000000001 ffffffff81ddc9e0 0000000000000000 ffffffff81cca9c0
[ 8.793684] Call Trace:
[ 8.796724] [<ffffffff818b2806>] dump_stack+0x4c/0x65
[ 8.802777] [<ffffffff8107293a>] warn_slowpath_common+0x8a/0xc0
[ 8.809789] [<ffffffff810729b6>] warn_slowpath_fmt+0x46/0x50
[ 8.816515] [<ffffffff8140ee0d>] ? ida_get_new_above+0x1dd/0x210
[ 8.823624] [<ffffffff81265871>] internal_create_group+0x151/0x280
[ 8.830929] [<ffffffff81265ad9>] sysfs_create_groups+0x49/0xa0
[ 8.837855] [<ffffffff8155078a>] device_add+0x3fa/0x670
[ 8.844094] [<ffffffff81556750>] cpu_device_create+0xc0/0xe0
[ 8.850823] [<ffffffff811c8aae>] ? __kmalloc+0x27e/0x2b0
[ 8.857153] [<ffffffff8140ee0d>] ? ida_get_new_above+0x1dd/0x210
[ 8.864271] [<ffffffff81559852>] cache_add_dev+0x182/0x230
[ 8.870807] [<ffffffff81ee9eb5>] cacheinfo_sysfs_init+0x53/0x95
[ 8.877821] [<ffffffff81ee9e62>] ? container_dev_init+0x2f/0x2f
[ 8.884848] [<ffffffff81002130>] do_one_initcall+0xc0/0x1f0
[ 8.891489] [<ffffffff81e93169>] kernel_init_freeable+0x1bb/0x248
[ 8.898705] [<ffffffff818a5eb0>] ? rest_init+0x90/0x90
[ 8.904841] [<ffffffff818a5ebe>] kernel_init+0xe/0xf0
[ 8.910899] [<ffffffff818bb213>] ret_from_fork+0x53/0x80
[ 8.917229] [<ffffffff818a5eb0>] ? rest_init+0x90/0x90
[ 8.923374] ---[ end trace 316d11c18159d99b ]---
[ 8.928923] error populating cacheinfo..cpu0
Thanks,
Ying Huang
_______________________________________________
LKP mailing list
LKP(a)linux.intel.com
7 years, 1 month
[mm] cc87317726f: WARNING: CPU: 0 PID: 1 at drivers/iommu/io-pgtable-arm.c:413 __arm_lpae_unmap+0x341/0x380()
by Huang Ying
FYI, we noticed the below changes on
git://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git master
commit cc87317726f851531ae8422e0c2d3d6e2d7b1955 ("mm: page_alloc: revert inadvertent !__GFP_FS retry behavior change")
Before the commit, the page allocation failure is as follow (in prev_dmesg).
[ 3.069031] BTRFS: selftest: Running space stealing from bitmap to extent
[ 3.070243] BTRFS: selftest: Free space cache tests finished
[ 3.070919] BTRFS: selftest: Running extent buffer operation tests
[ 3.072111] BTRFS: selftest: Running btrfs_split_item tests
[ 3.072840] BTRFS: selftest: Running find delalloc tests
[ 3.295788] swapper/0: page allocation failure: order:0, mode:0x50
[ 3.296315] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G W 4.0.0-rc1-00038-g39afb5e #4
[ 3.297033] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011
[ 3.297490] 00000000 00000000 4002bdd4 4158716c 00000001 4002bdfc 410c64f1 41719e60
[ 3.298218] 4001b304 00000000 00000050 4002bdf8 4158da0d 00000000 00000000 4002be80
[ 3.298929] 410c8331 00000050 00000000 00000000 00000001 00000050 4001b000 00000040
[ 3.299644] Call Trace:
[ 3.299859] [<4158716c>] dump_stack+0x48/0x60
[ 3.300235] [<410c64f1>] warn_alloc_failed+0xa1/0xe0
[ 3.300640] [<4158da0d>] ? _raw_spin_unlock+0x1d/0x30
[ 3.301070] [<410c8331>] __alloc_pages_nodemask+0x4d1/0x810
[ 3.301517] [<410c04e3>] pagecache_get_page+0xf3/0x1c0
[ 3.301957] [<4124ccf7>] btrfs_test_extent_io+0x67/0x660
[ 3.302401] [<4124c5cb>] ? btrfs_test_extent_buffer_operations+0x54b/0x6c0
[ 3.302966] [<4184109b>] ? debugfs_init+0x4e/0x4e
[ 3.303360] [<41841192>] init_btrfs_fs+0xf7/0x172
[ 3.303750] [<41000472>] do_one_initcall+0xc2/0x1c0
[ 3.304155] [<41829462>] ? repair_env_string+0x12/0x54
[ 3.304566] [<41829400>] ? do_early_param+0x23/0x73
[ 3.304971] [<4104ca99>] ? parse_args+0x249/0x4e0
[ 3.305364] [<41829450>] ? do_early_param+0x73/0x73
[ 3.305767] [<41829bce>] kernel_init_freeable+0xe3/0x160
[ 3.306204] [<41829bce>] ? kernel_init_freeable+0xe3/0x160
[ 3.306632] [<41582b78>] kernel_init+0x8/0xc0
[ 3.307022] [<4158e281>] ret_from_kernel_thread+0x21/0x30
[ 3.307455] [<41582b70>] ? rest_init+0xb0/0xb0
[ 3.307826] Mem-Info:
[ 3.308024] Normal per-cpu:
[ 3.308251] CPU 0: hi: 90, btch: 15 usd: 82
[ 3.308630] CPU 1: hi: 90, btch: 15 usd: 2
[ 3.309026] active_anon:0 inactive_anon:0 isolated_anon:0
[ 3.309026] active_file:873 inactive_file:62554 isolated_file:0
[ 3.309026] unevictable:9425 dirty:0 writeback:0 unstable:0
[ 3.309026] free:539 slab_reclaimable:0 slab_unreclaimable:0
[ 3.309026] mapped:0 shmem:0 pagetables:0 bounce:0
[ 3.309026] free_cma:0
After the commit, the system hang at the same position (in .dmesg).
[ 3.303002] BTRFS: selftest: Running btrfs free space cache tests
[ 3.303636] BTRFS: selftest: Running extent only tests
[ 3.304190] BTRFS: selftest: Running bitmap only tests
[ 3.304726] BTRFS: selftest: Running bitmap and extent tests
[ 3.305346] BTRFS: selftest: Running space stealing from bitmap to extent
[ 3.306318] BTRFS: selftest: Free space cache tests finished
[ 3.306881] BTRFS: selftest: Running extent buffer operation tests
[ 3.307483] BTRFS: selftest: Running btrfs_split_item tests
[ 3.308134] BTRFS: selftest: Running find delalloc tests
BUG: kernel boot hang
Elapsed time: 305
Thanks,
Ying Huang
_______________________________________________
LKP mailing list
LKP(a)linux.intel.com
7 years, 1 month
30 PR9 backlinks, 30% OFF
by Manual SEO
We will create for you valuable Top Quality Profile Backlinks
from 30+ web 2.0 authority sites with PR9 domain.
- 30 PR 9 Backlinks from authority sites
- Permanent One-way Links
- Anchor Text + Ping
- All domains unique
- Completed in 7 business days
- Detailed Submission Report
For Full Details please read the attached .html file
Unsubscribe option available on the footer of our website
7 years, 1 month
[lockdep] ef9db2383c7: WARNING: CPU: 0 PID: 1 at kernel/locking/lockdep.c:3538 check_flags+0x22d/0x240()
by Huang Ying
FYI, we noticed the below changes on
git://git.kernel.org/pub/scm/linux/kernel/git/peterz/queue.git locking/urgent
commit ef9db2383c74a9cc0f7f4e0ec2740c865f6532d9 ("lockdep: Fix module unload")
+----------------------------------------------------+----------+------------+
| | v4.0-rc4 | ef9db2383c |
+----------------------------------------------------+----------+------------+
| boot_successes | 104 | 0 |
| boot_failures | 4 | 10 |
| BUG:kernel_boot_hang | 4 | |
| WARNING:at_kernel/locking/lockdep.c:#check_flags() | 0 | 10 |
| backtrace:rcu_read_lock_held | 0 | 10 |
| backtrace:lockdep_init_map | 0 | 10 |
| backtrace:__alloc_workqueue_key | 0 | 10 |
| backtrace:init_workqueues | 0 | 10 |
| backtrace:kernel_init_freeable | 0 | 10 |
+----------------------------------------------------+----------+------------+
[ 0.039021] Performance Events: Broken PMU hardware detected, using software events only.
[ 0.041003] Failed to access perfctr msr (MSR c1 is 0)
[ 0.043211] ------------[ cut here ]------------
[ 0.043778] WARNING: CPU: 0 PID: 1 at kernel/locking/lockdep.c:3538 check_flags+0x22d/0x240()
[ 0.044000] DEBUG_LOCKS_WARN_ON(current->hardirqs_enabled)
[ 0.044000] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.0.0-rc4-01920-g5d82bf5 #278
[ 0.044000] ffffffff82136e10 ffff88001495bb88 ffffffff81da3073 000000000000002e
[ 0.044000] ffff88001495bbd8 ffff88001495bbc8 ffffffff810d1e0b 0000000000000286
[ 0.044000] ffffffff82441420 0000000000000046 ffffffff827c53f1 ffff88001496e850
[ 0.044000] Call Trace:
[ 0.044000] [<ffffffff81da3073>] dump_stack+0x4f/0x7b
[ 0.044000] [<ffffffff810d1e0b>] warn_slowpath_common+0x7b/0xc0
[ 0.044000] [<ffffffff810d1eb1>] warn_slowpath_fmt+0x41/0x50
[ 0.044000] [<ffffffff810f7941>] ? get_parent_ip+0x11/0x50
[ 0.044000] [<ffffffff8111659d>] check_flags+0x22d/0x240
[ 0.044000] [<ffffffff81117636>] lock_is_held+0x36/0x90
[ 0.044000] [<ffffffff81129ce5>] rcu_read_lock_held+0x65/0x70
[ 0.044000] [<ffffffff81117d16>] lockdep_init_map+0x336/0x6f0
[ 0.044000] [<ffffffff810ea82f>] alloc_unbound_pwq+0x26f/0x4d0
[ 0.044000] [<ffffffff810eb484>] apply_workqueue_attrs+0x154/0x490
[ 0.044000] [<ffffffff810ebf44>] __alloc_workqueue_key+0x1c4/0x610
[ 0.044000] [<ffffffff81661368>] ? alloc_cpumask_var_node+0x48/0x80
[ 0.044000] [<ffffffff826554d8>] init_workqueues+0x414/0x528
[ 0.044000] [<ffffffff826550c4>] ? wq_sysfs_init+0x14/0x14
[ 0.044000] [<ffffffff826550c4>] ? wq_sysfs_init+0x14/0x14
[ 0.044000] [<ffffffff82640071>] do_one_initcall+0xe8/0x17f
[ 0.044000] [<ffffffff8264c55a>] ? native_smp_prepare_cpus+0x2c0/0x2cf
[ 0.044000] [<ffffffff8264017c>] kernel_init_freeable+0x74/0x1b0
[ 0.044000] [<ffffffff81d973e0>] ? rest_init+0x140/0x140
[ 0.044000] [<ffffffff81d973e9>] kernel_init+0x9/0xf0
[ 0.044000] [<ffffffff81db4943>] ret_from_fork+0x53/0x90
[ 0.044000] [<ffffffff81d973e0>] ? rest_init+0x140/0x140
[ 0.044000] ---[ end trace c79ceaf7cc379335 ]---
[ 0.044000] possible reason: unannotated irqs-off.
Thanks,
Ying Huang
_______________________________________________
LKP mailing list
LKP(a)linux.intel.com
7 years, 1 month
[regression] d7aa432ad5f: -5.6% will-it-scale.per_thread_ops
by Huang Ying
FYI, we noticed the below changes on
git://flatbed.openfabrics.org/~amirv/linux.git for-upstream
commit d7aa432ad5fa589c56a11bd7993cff05e9b87d05 ("regression: Disable FENTRY [topic: regression] [net-next:regression]")
testbox/testcase/testparams: lkp-sb03/will-it-scale/poll2
5cd08c4aa1751f04 d7aa432ad5fa589c56a11bd799
---------------- --------------------------
%stddev %change %stddev
\ | \
650868 ± 0% -2.9% 631737 ± 0% will-it-scale.per_process_ops
281122 ± 0% -5.6% 265461 ± 0% will-it-scale.per_thread_ops
153 ± 0% -7.9% 141 ± 0% will-it-scale.time.user_time
118 ± 0% +0.9% 119 ± 0% turbostat.CorWatt
2733 ± 3% +12.2% 3066 ± 2% proc-vmstat.nr_alloc_batch
1319 ± 3% +19.1% 1570 ± 5% numa-vmstat.node0.nr_alloc_batch
2250 ± 6% -13.0% 1958 ± 5% slabinfo.kmalloc-2048.active_objs
2380 ± 6% -13.2% 2066 ± 5% slabinfo.kmalloc-2048.num_objs
31.65 ± 0% -92.2% 2.46 ± 7% perf-profile.cpu-cycles.fput.sys_poll.system_call_fastpath
60.25 ± 0% +46.7% 88.37 ± 5% perf-profile.cpu-cycles.do_sys_poll.sys_poll.system_call_fastpath
3.57 ± 7% -10.2% 3.21 ± 3% perf-profile.cpu-cycles.copy_user_generic_string.sys_poll.system_call_fastpath
2.66 ± 2% -100.0% 0.00 ± 0% perf-profile.cpu-cycles.__fdget.sys_poll.system_call_fastpath
2.71 ± 0% -100.0% 0.00 ± 0% perf-profile.cpu-cycles.__fget_light.do_sys_poll.sys_poll.system_call_fastpath
0.00 ± 0% +Inf% 33.68 ± 8% perf-profile.cpu-cycles.fput.do_sys_poll.sys_poll.system_call_fastpath
25 ± 37% -33.0% 16 ± 4% sched_debug.cfs_rq[15]:/.runnable_load_avg
37159 ± 9% +20.7% 44850 ± 16% sched_debug.cfs_rq[24]:/.exec_clock
830542 ± 10% +18.8% 986752 ± 15% sched_debug.cfs_rq[24]:/.min_vruntime
18120 ± 8% -17.8% 14897 ± 19% sched_debug.cfs_rq[8]:/.avg->runnable_avg_sum
394 ± 8% -17.8% 323 ± 19% sched_debug.cfs_rq[8]:/.tg_runnable_contrib
26 ± 43% -36.2% 16 ± 4% sched_debug.cpu#15.cpu_load[1]
23 ± 30% -28.3% 16 ± 5% sched_debug.cpu#15.cpu_load[2]
1564 ± 21% +73.2% 2709 ± 47% sched_debug.cpu#20.nr_switches
617 ± 27% +99.6% 1232 ± 48% sched_debug.cpu#20.ttwu_count
2164 ± 20% -28.2% 1553 ± 35% sched_debug.cpu#25.ttwu_count
1902 ± 21% -26.6% 1397 ± 36% sched_debug.cpu#25.sched_goidle
1947 ± 35% -62.6% 728 ± 35% sched_debug.cpu#26.ttwu_local
5620 ± 23% -41.6% 3282 ± 15% sched_debug.cpu#26.nr_switches
2595 ± 26% -43.5% 1465 ± 19% sched_debug.cpu#26.ttwu_count
653 ± 9% +76.3% 1151 ± 34% sched_debug.cpu#31.ttwu_local
1162 ± 10% +34.5% 1563 ± 22% sched_debug.cpu#31.sched_goidle
2885 ± 9% +34.5% 3880 ± 22% sched_debug.cpu#31.nr_switches
lkp-sb03: Sandy Bridge-EP
Memory: 64G
perf-profile.cpu-cycles.do_sys_poll.sys_poll.system_call_fastpath
95 ++---------------------------------------------------------------------+
| O O O O O O O O O O O O O O O O O O O O |
90 ++ O O O |
85 ++ |
O |
80 ++ O
| |
75 ++ |
| |
70 ++ |
65 ++ |
| |
60 *+.*..*..*..*.. .*..*..*..*..*..*..*.*..*..*..*..*..*..*..* |
| *. |
55 ++---------------------------------------------------------------------+
perf-profile.cpu-cycles.fput.do_sys_poll.sys_poll.system_call_fastpath
37 ++---------------------------------------------------------------------+
| O |
36 ++ O O |
35 ++ O O O O O O O O O O O O O O |
| O O O |
34 ++ O O |
33 ++ O |
| |
32 ++ |
31 ++ |
| |
30 O+ |
29 ++ |
| O
28 ++---------------------------------------------------------------------+
perf-profile.cpu-cycles.fput.sys_poll.system_call_fastpath
35 ++---------------------------------------------------------------------+
*..*..*..*..*.. .*..*..*..*..*..*..*.*..*..*..*..*..*..*..* |
30 ++ *. |
| |
25 ++ |
| |
20 ++ |
| |
15 ++ |
| |
10 ++ |
| |
5 ++ |
O O O O O O O O O O O O O O O O O O O O O O O O O
0 ++---------------------------------------------------------------------+
will-it-scale.per_thread_ops
290000 ++-----------------------------------------------------------------+
| |
285000 ++ *..*.. |
| : |
*..*..*.*..*.. .*..* : * *..*..*..*.*.. .*..* |
280000 ++ *. + : + : *. |
| * + : |
275000 ++ * |
| |
270000 ++ |
| |
| O O O O O O O O |
265000 ++ O O O O O O O O O O O O |
O O O O
260000 ++---------------O-------------------------------------------------+
perf-profile.cpu-cycles.__fget_light.do_sys_poll.sys_poll.system_call_fastpath
3 ++--------------------------------------------------------------------+
*.. .*..*..*..*.. .*..*..*..*.. .*..*..*.. .*..*..*.*..* |
2.5 ++ *. * *. *. |
| |
| |
2 ++ |
| |
1.5 ++ |
| |
1 ++ |
| |
| |
0.5 ++ |
| |
0 O+-O--O--O--O--O--O-O--O--O--O--O--O--O--O--O--O--O--O-O--O--O--O--O--O
perf-profile.cpu-cycles.__fdget.sys_poll.system_call_fastpath
3 ++--------------------------------------------------------------------+
*..*..*..*..*..*..*.*..*..*..*..*..*.. .*.. .* |
2.5 ++ *..*..*..*. *.*. |
| |
| |
2 ++ |
| |
1.5 ++ |
| |
1 ++ |
| |
| |
0.5 ++ |
| |
0 O+-O--O--O--O--O--O-O--O--O--O--O--O--O--O--O--O--O--O-O--O--O--O--O--O
[*] bisect-good sample
[O] bisect-bad sample
To reproduce:
apt-get install ruby
git clone git://git.kernel.org/pub/scm/linux/kernel/git/wfg/lkp-tests.git
cd lkp-tests
bin/setup-local job.yaml # the job file attached in this email
bin/run-local 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
_______________________________________________
LKP mailing list
LKP(a)linux.intel.com
7 years, 1 month
