[RDMA/core] 3ac42319fc: WARNING:at_drivers/infiniband/core/device.c:#rdma_dev_exit_net[ib_core]
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: 3ac42319fc9a178b88817c5e8a1f8d3ce4f21a0e ("RDMA/core: Implement compat device/sysfs tree in net namespace")
https://github.com/jgunthorpe/linux for-parav
in testcase: trinity
with following parameters:
runtime: 300s
test-description: Trinity is a linux system call fuzz tester.
test-url: http://codemonkey.org.uk/projects/trinity/
on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 4G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+-------------------------------------------------------------------------+------------+------------+
| | d05d5ba842 | 3ac42319fc |
+-------------------------------------------------------------------------+------------+------------+
| boot_successes | 4 | 0 |
| boot_failures | 0 | 3 |
| WARNING:at_drivers/infiniband/core/device.c:#rdma_dev_exit_net[ib_core] | 0 | 3 |
| RIP:rdma_dev_exit_net[ib_core] | 0 | 3 |
+-------------------------------------------------------------------------+------------+------------+
[ 48.785659] WARNING: CPU: 0 PID: 43 at drivers/infiniband/core/device.c:843 rdma_dev_exit_net+0x141/0x150 [ib_core]
[ 48.794221] Modules linked in: af_key rose ieee802154_socket ieee802154 mpls_router ip_tunnel kcm nfc hidp cmtp kernelcapi bnep rfcomm bluetooth ecdh_generic rfkill can_bcm can_raw can pptp gre l2tp_ppp l2tp_netlink l2tp_core ip6_udp_tunnel udp_tunnel pppoe pppox ppp_generic slhc crypto_user ib_core nfnetlink scsi_transport_iscsi dccp_ipv6 atm netrom appletalk psnap llc ax25 sctp libcrc32c dccp_ipv4 dccp virtio_blk ppdev bochs_drm crct10dif_pclmul ttm crc32_pclmul crc32c_intel drm_kms_helper ghash_clmulni_intel drm snd_pcm snd_timer snd joydev soundcore pcspkr serio_raw i6300esb virtio_pci virtio_ring virtio ata_generic i2c_piix4 pata_acpi parport_pc floppy parport qemu_fw_cfg
[ 48.813887] CPU: 0 PID: 43 Comm: kworker/u4:1 Not tainted 5.0.0-rc5-00274-g3ac4231 #1
[ 48.816649] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 48.819451] Workqueue: netns cleanup_net
[ 48.821702] RIP: 0010:rdma_dev_exit_net+0x141/0x150 [ib_core]
[ 48.824130] Code: 8b 75 08 48 c7 c7 10 2d 49 c0 e8 fa 81 53 d0 48 8b 44 24 08 65 48 33 04 25 28 00 00 00 75 12 48 83 c4 10 5b 5d 41 5c 41 5d c3 <0f> 0b e9 22 ff ff ff e8 b3 02 c7 cf 0f 1f 00 66 66 66 66 90 55 53
[ 48.829982] RSP: 0018:ffffb66000877de0 EFLAGS: 00010246
[ 48.832419] RAX: ffffa0cc7b2c41f0 RBX: ffffa0cc7ac96100 RCX: 0000000000000000
[ 48.835024] RDX: 0000000000000000 RSI: 0000000000000000 RDI: ffffb66000877d58
[ 48.837566] RBP: ffffb66000877e48 R08: ffffa0cc7d410d90 R09: 0000000000000228
[ 48.840163] R10: fffffffffffffbf9 R11: ffffa0cc7b36e600 R12: ffffffffc0492bf8
[ 48.842721] R13: ffffa0cc7b2c41f0 R14: ffffb66000877e48 R15: 00000000ffffffff
[ 48.845307] FS: 0000000000000000(0000) GS:ffffa0cd3ba00000(0000) knlGS:0000000000000000
[ 48.848036] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 48.850421] CR2: 00007f37410ead30 CR3: 000000013a20e000 CR4: 00000000000406f0
[ 48.852928] DR0: 00007f373f491000 DR1: 0000000000000000 DR2: 0000000000000000
[ 48.855427] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000600
[ 48.857922] Call Trace:
[ 48.859794] ops_exit_list+0x36/0x70
[ 48.862390] cleanup_net+0x1bf/0x2d0
[ 48.864380] process_one_work+0x196/0x3c0
[ 48.866405] worker_thread+0x3c/0x3a0
[ 48.868294] ? process_one_work+0x3c0/0x3c0
[ 48.870283] kthread+0x11e/0x140
[ 48.872128] ? kthread_park+0x90/0x90
[ 48.873964] ret_from_fork+0x35/0x40
[ 48.875787] ---[ end trace 68ac3d581d2c598f ]---
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
lkp
1 year, 11 months
3ac42319fc ("RDMA/core: Implement compat device/sysfs tree in .."): WARNING: CPU: 1 PID: 75 at drivers/infiniband/core/device.c:843 rdma_dev_exit_net
by kernel test robot
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
https://github.com/jgunthorpe/linux for-parav
commit 3ac42319fc9a178b88817c5e8a1f8d3ce4f21a0e
Author: Parav Pandit <parav(a)mellanox.com>
AuthorDate: Wed Feb 13 19:23:08 2019 +0200
Commit: Jason Gunthorpe <jgg(a)mellanox.com>
CommitDate: Tue Feb 19 17:10:55 2019 -0700
RDMA/core: Implement compat device/sysfs tree in net namespace
Implement compatibility sysfs entries of ib_core so that non init_net net
namespaces can also discover rdma devices.
Each non init_net net namespace has ib_core_device created in it. Such
ib_core_device sysfs tree resembles rdma devices found in init_net
namespace, but only serves as a proxy to the real struct ib_device.
This allows discovering rdma devices in multiple non init_net net
namespaces via sysfs entries and helpful to rdma-core userspace.
Signed-off-by: Parav Pandit <parav(a)mellanox.com>
Signed-off-by: Leon Romanovsky <leonro(a)mellanox.com>
Signed-off-by: Jason Gunthorpe <jgg(a)mellanox.com>
d05d5ba842 RDMA/core: Restrict sysfs entries view to init_net
3ac42319fc RDMA/core: Implement compat device/sysfs tree in net namespace
d05c908f9c RDMA/core: Support core port attributes in non init_net
+----------------------------------------------------------------+------------+------------+------------+
| | d05d5ba842 | 3ac42319fc | d05c908f9c |
+----------------------------------------------------------------+------------+------------+------------+
| boot_successes | 33 | 0 | 0 |
| boot_failures | 0 | 11 | 6 |
| RIP:rdma_dev_exit_net | 0 | 11 | 6 |
| WARNING:at_drivers/infiniband/core/device.c:#rdma_dev_exit_net | 0 | 11 | 6 |
+----------------------------------------------------------------+------------+------------+------------+
shm:0x7f20c3f5a0
01 00 00 00 68 00 00 00 08 00 00 00 00 00 00 00 00 00 00 0
00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 02 00 00 00 00 00 00 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 0
05 00 00 00 70 00 00 00 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 05 00 00 00 00 00 00 00 ca e5 10 0d 00 00 00 00 00 00 00 00 02 00 00 00 00 30 fa a9 a7 7f 00 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 509.495460] WARNING: CPU: 1 PID: 75 at drivers/infiniband/core/device.c:843 rdma_dev_exit_net+0x164/0x170
[ 509.522353] CPU: 1 PID: 75 Comm: kworker/u4:1 Not tainted 5.0.0-rc5-00274-g3ac4231 #1
[ 509.546209] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 509.570937] Workqueue: netns cleanup_net
[ 509.582405] RIP: 0010:rdma_dev_exit_net+0x164/0x170
[ 509.597310] Code: a6 48 c7 c7 20 15 88 82 e8 29 ee 9a ff 41 8b 75 08 48 c7 c7 a0 13 88 82 e8 49 7a 41 00 48 8d 65 e0 5b 41 5c 41 5d 41 5e 5d c3 <0f> 0b e9 2b ff ff ff 0f 1f 44 00 00 41 55 41 54 55 53 0f b6 97 de
[ 509.647386] RSP: 0018:ffffc900001dbda8 EFLAGS: 00010246
[ 509.661864] RAX: ffff88801b80bb40 RBX: 0000000000000005 RCX: 01a2d04c00000000
[ 509.681312] RDX: ffff88801ccdac20 RSI: 00000000b057d8e1 RDI: ffffffff828813a0
[ 509.698708] RBP: ffffc900001dbdd0 R08: 0000000051087f0f R09: 0000000000000001
[ 509.718255] R10: 0000000000000000 R11: 0000000000000000 R12: ffffffff828811f8
[ 509.741791] R13: ffff88801b80bb40 R14: ffff88801b6ec040 R15: ffffffff828b7a60
[ 509.760585] FS: 0000000000000000(0000) GS:ffff88801f000000(0000) knlGS:0000000000000000
[ 509.781134] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 509.798690] CR2: 0000000002579058 CR3: 000000001c0a6000 CR4: 00000000000006a0
[ 509.820247] Call Trace:
[ 509.827798] ops_exit_list+0x31/0x60
[ 509.840692] cleanup_net+0x196/0x270
[ 509.850677] process_one_work+0x248/0x470
[ 509.862433] ? process_one_work+0x1df/0x470
[ 509.874910] worker_thread+0x34/0x3d0
[ 509.884794] ? process_one_work+0x470/0x470
[ 509.897366] kthread+0x116/0x130
[ 509.906925] ? __kthread_create_on_node+0x150/0x150
[ 509.921189] ret_from_fork+0x35/0x40
[ 509.932558] ---[ end trace d77a7990945b15fd ]---
# HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start 326922febf1821539356516803728495a695965d f17b5f06cb92ef2250513a1e154c47b78df07d40 --
git bisect good 51e68a365d313f7c1902ebaeb4e729e02c6c6ec9 # 18:18 G 11 0 1 1 Merge 'linux-review/Thomas-Zimmermann/staging-vboxvideo-Use-same-BO-mmap-offset-as-other-drivers/20190208-041833' into devel-hourly-2019022108
git bisect good e69794781f59d647b30dc3d33990b9ee941c58e5 # 18:53 G 11 0 1 1 Merge 'linux-review/Florian-Fainelli/selftests-forwarding-Add-some-missing-configuration-symbols/20190216-122629' into devel-hourly-2019022108
git bisect bad 188b57d9a2c1cfdf703924dbea9dcb57bbf4b313 # 19:24 B 0 11 25 0 Merge 'linux-review/Hyun-Kwon/staging-android-ion-add-the-GPL-exception-note-to-header/20190215-012326' into devel-hourly-2019022108
git bisect good dd8ad1416193b58b9b84dc130be60e4bac47d1c0 # 20:07 G 10 0 2 4 Merge 'linux-review/Andrii-Nakryiko/tools-bpf-smaller-clean-ups/20190214-063401' into devel-hourly-2019022108
git bisect good 4cd20d2e0b91f517f4453ab738b4586d1eb7fc2f # 22:06 G 11 0 2 2 Merge 'linux-review/Jesper-Dangaard-Brouer/Fix-page_pool-API-and-dma-address-storage/20190213-193419' into devel-hourly-2019022108
git bisect bad b9576b905eb61257e882ea05f58a017f8cfcafac # 22:52 B 0 11 25 0 Merge 'jgunthorpe/for-parav' into devel-hourly-2019022108
git bisect good 32975692bc8d730a3d516c11737c0e09307fb73a # 23:44 G 10 0 2 2 Merge 'csky-linux/linux-next' into devel-hourly-2019022108
git bisect good fbc0e26d06f3e6f4e4092735693a14dd9db21f36 # 00:27 G 10 0 1 1 Merge 'vfio/next' into devel-hourly-2019022108
git bisect good 0a55ac1977c32d54b1d5bf3cdce58ed68d330884 # 01:19 G 11 0 0 0 Merge 'martinetd-linux/kcm' into devel-hourly-2019022108
git bisect good 681d57783d6942b3717e7909517443baead16c41 # 13:32 G 10 0 0 2 Merge 'linux-review/Greg-KH/rpc-properly-check-debugfs-dentry-before-using-it/20190213-184139' into devel-hourly-2019022108
git bisect bad 3ac42319fc9a178b88817c5e8a1f8d3ce4f21a0e # 14:16 B 0 3 17 0 RDMA/core: Implement compat device/sysfs tree in net namespace
git bisect good d05d5ba842f1b0425c41a89c34572606aa176972 # 14:54 G 10 0 2 2 RDMA/core: Restrict sysfs entries view to init_net
# first bad commit: [3ac42319fc9a178b88817c5e8a1f8d3ce4f21a0e] RDMA/core: Implement compat device/sysfs tree in net namespace
git bisect good d05d5ba842f1b0425c41a89c34572606aa176972 # 15:02 G 30 0 0 2 RDMA/core: Restrict sysfs entries view to init_net
# extra tests with debug options
git bisect bad 3ac42319fc9a178b88817c5e8a1f8d3ce4f21a0e # 15:25 B 0 2 16 0 RDMA/core: Implement compat device/sysfs tree in net namespace
# extra tests on HEAD of linux-devel/devel-hourly-2019022108
git bisect bad 326922febf1821539356516803728495a695965d # 15:30 B 0 341 363 4 0day head guard for 'devel-hourly-2019022108'
# extra tests on tree/branch jgunthorpe/for-parav
git bisect bad d05c908f9c242204bd20911fbe3574e2a2b296e4 # 16:02 B 0 1 15 0 RDMA/core: Support core port attributes in non init_net
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
1 year, 11 months
[mm, memory_hotplug] efad4e475c: BUG:unable_to_handle_kernel
by kernel test robot
FYI, we noticed the following commit (built with gcc-8):
commit: efad4e475c312456edb3c789d0996d12ed744c13 ("mm, memory_hotplug: is_mem_section_removable do not pass the end of a zone")
https://git.kernel.org/cgit/linux/kernel/git/next/linux-next.git master
in testcase: trinity
with following parameters:
runtime: 300s
test-description: Trinity is a linux system call fuzz tester.
test-url: http://codemonkey.org.uk/projects/trinity/
on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 1G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+---------------------------------------------------------+------------+------------+
| | 9bcdeb51bd | efad4e475c |
+---------------------------------------------------------+------------+------------+
| boot_successes | 0 | 0 |
| boot_failures | 6 | 4 |
| WARNING:at_mm/page_alloc.c:#__alloc_pages_nodemask | 6 | 4 |
| RIP:__alloc_pages_nodemask | 6 | 4 |
| Mem-Info | 6 | 4 |
| WARNING:at_lib/debugobjects.c:#__debug_object_init.cold | 6 | 4 |
| RIP:__debug_object_init.cold | 6 | 4 |
| BUG:unable_to_handle_kernel | 0 | 4 |
| Oops:#[##] | 0 | 4 |
| RIP:page_mapping | 0 | 4 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 4 |
| WARNING:at_kernel/locking/lockdep.c:#lock_downgrade | 0 | 1 |
| RIP:lock_downgrade | 0 | 1 |
+---------------------------------------------------------+------------+------------+
[ 50.067610] BUG: unable to handle kernel NULL pointer dereference at 0000000000000006
[ 50.069651] #PF error: [normal kernel read fault]
[ 50.070222] PGD 800000003549b067 P4D 800000003549b067 PUD 0
[ 50.070899] Oops: 0000 [#1] SMP PTI
[ 50.071353] CPU: 1 PID: 410 Comm: udevd Tainted: G W 5.0.0-rc4-00149-gefad4e4 #1
[ 50.072399] RIP: 0010:page_mapping+0xe/0x70
[ 50.072905] Code: c2 48 83 e0 fc 83 e2 03 48 83 fa 01 ba 00 00 00 00 48 0f 45 c2 c3 66 0f 1f 44 00 00 48 8b 47 08 48 8d 50 ff a8 01 48 0f 45 fa <48> 8b 57 08 48 8d 42 ff 83 e2 01 48 0f 44 c7 48 83 38 ff 74 2e 48
[ 50.075101] RSP: 0000:ffffc90000477d40 EFLAGS: 00010202
[ 50.075726] RAX: ffffffffffffffff RBX: ffff88803dfc0000 RCX: 0000000000000080
[ 50.076581] RDX: fffffffffffffffe RSI: ffffffffa29e34e8 RDI: fffffffffffffffe
[ 50.077448] RBP: 6db6db6db6db6db7 R08: 0000000000000001 R09: 0000000000000400
[ 50.078310] R10: ffff88803ffd4000 R11: ffff88803e3fd000 R12: ffffffffa29e34e8
[ 50.079143] R13: 0000000000000001 R14: ffff888033e74d40 R15: ffff888035abd1c8
[ 50.080002] FS: 0000000000000000(0000) GS:ffff88803d300000(0063) knlGS:00000000f7d23b00
[ 50.080984] CS: 0010 DS: 002b ES: 002b CR0: 0000000080050033
[ 50.081704] CR2: 0000000000000006 CR3: 0000000035ee6000 CR4: 00000000000406a0
[ 50.082544] Call Trace:
[ 50.082861] __dump_page+0xf/0x120
[ 50.083286] dump_page+0x9/0x20
[ 50.083683] is_mem_section_removable+0x210/0x240
[ 50.084258] removable_show+0x6b/0xa0
[ 50.084718] dev_attr_show+0x14/0x40
[ 50.085174] sysfs_kf_seq_show+0xa8/0xf0
[ 50.085629] seq_read+0x141/0x3d0
[ 50.086017] __vfs_read+0x1e/0x170
[ 50.086438] ? free_unref_page_prepare+0xd1/0x180
[ 50.087002] vfs_read+0x9e/0x130
[ 50.087420] ksys_read+0x3d/0xa0
[ 50.087837] do_int80_syscall_32+0x45/0xf0
[ 50.088345] entry_INT80_compat+0x82/0x87
[ 50.088844] CR2: 0000000000000006
[ 50.089276] ---[ end trace d2d035d77c3b428d ]---
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
Rong Chen
1 year, 11 months
[bonding] 86838ad7bd: BUG:sleeping_function_called_from_invalid_context_at_kernel/locking/mutex.c
by kernel test robot
FYI, we noticed the following commit (built with gcc-8):
commit: 86838ad7bde0d7741b272670d94d535580173d95 ("[RFC PATCH] bonding: use mutex lock in bond_get_stats()")
url: https://github.com/0day-ci/linux/commits/Kefeng-Wang/bonding-use-mutex-lo...
in testcase: rcutorture
with following parameters:
runtime: 300s
test: cpuhotplug
torture_type: srcu
test-description: rcutorture is rcutorture kernel module load/unload test.
test-url: https://www.kernel.org/doc/Documentation/RCU/torture.txt
on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 1G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+-----------------------------------------------------------------------------+------------+------------+
| | f9bcc9f3ee | 86838ad7bd |
+-----------------------------------------------------------------------------+------------+------------+
| boot_successes | 50 | 24 |
| boot_failures | 10 | 41 |
| invoked_oom-killer:gfp_mask=0x | 1 | |
| Mem-Info | 1 | |
| Out_of_memory:Kill_process | 1 | |
| BUG:kernel_in_stage | 6 | 8 |
| BUG:kernel_reboot-without-warning_in_early-boot_stage | 2 | |
| BUG:kernel_hang_in_test_stage | 1 | 1 |
| BUG:sleeping_function_called_from_invalid_context_at_kernel/locking/mutex.c | 0 | 32 |
+-----------------------------------------------------------------------------+------------+------------+
[ 17.153431] BUG: sleeping function called from invalid context at kernel/locking/mutex.c:908
[ 17.157556] in_atomic(): 0, irqs_disabled(): 0, pid: 599, name: dropbearkey
[ 17.159141] 2 locks held by dropbearkey/599:
[ 17.159779] #0: (____ptrval____) (&p->lock){+.+.}, at: seq_read+0x41/0x3f0
[ 17.160802] #1: (____ptrval____) (rcu_read_lock){....}, at: dev_seq_start+0x5/0x110
[ 17.161928] Preemption disabled at:
[ 17.161935] [<ffffffff818eafe0>] __mutex_lock+0x50/0x980
[ 17.163150] CPU: 1 PID: 599 Comm: dropbearkey Not tainted 5.0.0-rc5-00320-g86838ad #1
[ 17.164213] Call Trace:
[ 17.164571] dump_stack+0x67/0x90
[ 17.165051] ___might_sleep.cold.14+0xf0/0x106
[ 17.165675] ? bond_get_stats+0x3b/0x260
[ 17.166232] __mutex_lock+0x46/0x980
[ 17.166743] ? kvm_clock_read+0x14/0x30
[ 17.167288] ? validate_chain+0xb5/0xad0
[ 17.167936] ? number+0x2fc/0x340
[ 17.168416] ? bond_get_stats+0x3b/0x260
[ 17.168966] bond_get_stats+0x3b/0x260
[ 17.169500] ? seq_vprintf+0x30/0x50
[ 17.170007] ? seq_printf+0x43/0x50
[ 17.170498] ? validate_chain+0xb5/0xad0
[ 17.171136] ? dev_seq_printf_stats+0x9a/0xb0
[ 17.171736] dev_get_stats+0x55/0xb0
[ 17.172221] dev_seq_printf_stats+0x1f/0xb0
[ 17.172817] dev_seq_show+0x10/0x30
[ 17.173289] seq_read+0x2cd/0x3f0
[ 17.173777] proc_reg_read+0x36/0x60
[ 17.174288] __vfs_read+0x23/0x160
[ 17.174779] ? _copy_to_user+0x5e/0x70
[ 17.175312] ? entry_INT80_compat+0x7c/0x90
[ 17.175903] vfs_read+0xa6/0x140
[ 17.176366] ksys_read+0x42/0xa0
[ 17.176834] do_int80_syscall_32+0x4f/0x150
[ 17.177418] entry_INT80_compat+0x84/0x90
LKP: HOSTNAME vm-snb-openwrt-ia32-524, MAC , kernel 5.0.0-rc5-00320-g86838ad 1, serial console /dev/ttyS0
[ 18.404764] Kernel tests: Boot OK!
[ 18.404764] LKP: waiting for network...
[ 19.256982] e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX
[ 19.271235] _warn_unseeded_randomness: 1329 callbacks suppressed
[ 19.271244] random: get_random_u64 called from arch_pick_mmap_layout+0x65/0x170 with crng_init=0
[ 19.273507] random: get_random_u64 called from arch_pick_mmap_layout+0x10e/0x170 with crng_init=0
[ 19.274790] random: get_random_u64 called from load_elf_binary+0x87d/0x1120 with crng_init=0
[ 20.407748] /lkp/lkp/src/bin/run-lkp
[ 20.407748] RESULT_ROOT=/result/rcutorture/300s-cpuhotplug-srcu/vm-snb-openwrt-ia32/openwrt-i386-2016-03-16.cgz/x86_64-randconfig-s4-02200400/gcc-8/86838ad7bde0d7741b272670d94d535580173d95/3
[ 20.407748] job=/lkp/jobs/scheduled/vm-snb-openwrt-ia32-524/rcutorture-300s-cpuhotplug-srcu-openwrt-i386-2016-03-16.cgz-20190220-89648-gjivxf-3.yaml
[ 20.407748] run-job /lkp/jobs/scheduled/vm-snb-openwrt-ia32-524/rcutorture-300s-cpuhotplug-srcu-openwrt-i386-2016-03-16.cgz-20190220-89648-gjivxf-3.yaml
[ 20.407748] /bin/busybox wget -q http://inn:80/~lkp/cgi-bin/lkp-jobfile-append-var?job_file=/lkp/jobs/sche... -O /dev/null
[ 20.407748] target ucode:
[ 20.416008] wget: can't connect to remote host (192.168.1.1): Network is unreachable
[ 20.416008] /lkp/lkp/src/monitors/heartbeat: line 9: vmstat: not found
[ 20.416008] /lkp/lkp/src/monitors/heartbeat: exec: line 23: vmstat: not found
[ 20.678602] _warn_unseeded_randomness: 431 callbacks suppressed
[ 20.678610] random: get_random_u64 called from arch_pick_mmap_layout+0x65/0x170 with crng_init=0
[ 20.681816] random: get_random_u64 called from arch_pick_mmap_layout+0x10e/0x170 with crng_init=0
[ 20.683622] random: get_random_u64 called from load_elf_binary+0x87d/0x1120 with crng_init=0
[ 21.418765] BusyBox v1.23.2 (2016-01-02 14:31:17 CET) multi-call binary.
[ 21.418765]
[ 21.418765] Usage: time [-v] PROG ARGS
[ 21.418765]
[ 21.418765] Run PROG, display resource usage when it exits
[ 21.418765]
[ 21.418765] -v Verbose
[ 21.418765]
[ 21.418765] failed to load rcutorture module, try to enable CONFIG_RCU_TORTURE_TEST and build rcutorture.ko
[ 21.418765] wget: can't connect to remote host (192.168.1.1): Network is unreachable
[ 21.423344] 2019-02-20 06:29:25 modprobe rcutorture onoff_interval=3 onoff_holdoff=30 torture_type=srcu
[ 21.423344] /bin/busybox wget -q http://inn:80/~lkp/cgi-bin/lkp-jobfile-append-var?job_file=/lkp/jobs/sche... -O /dev/null
[ 21.818029] _warn_unseeded_randomness: 210 callbacks suppressed
[ 21.818037] random: get_random_u64 called from arch_pick_mmap_layout+0x65/0x170 with crng_init=0
[ 21.821714] random: get_random_u64 called from arch_pick_mmap_layout+0x10e/0x170 with crng_init=0
[ 21.823918] random: get_random_u64 called from load_elf_binary+0x87d/0x1120 with crng_init=0
[ 22.190264] e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX
[ 22.444763] kill 804 cat /proc/kmsg
[ 22.444763] wait for background processes: 812 810 oom-killer meminfo
Elapsed time: 20
qemu-img create -f qcow2 disk-vm-snb-openwrt-ia32-524-0 256G
qemu-img create -f qcow2 disk-vm-snb-openwrt-ia32-524-1 256G
kvm=(
qemu-system-x86_64
-enable-kvm
-cpu SandyBridge
-kernel $kernel
-initrd initrd-vm-snb-openwrt-ia32-524
-m 1024
-smp 2
-device e1000,netdev=net0
-netdev user,id=net0
-boot order=nc
-no-reboot
-watchdog i6300esb
-watchdog-action debug
-rtc base=localtime
-drive file=disk-vm-snb-openwrt-ia32-524-0,media=disk,if=virtio
-drive file=disk-vm-snb-openwrt-ia32-524-1,media=disk,if=virtio
-serial stdio
-display none
-monitor null
)
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
Rong Chen
1 year, 11 months
[bpf] 568f196756: BUG:assuming_atomic_context_at_kernel/seccomp.c
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: 568f196756ad9fe2b49c46bbf6a9de1b190438b4 ("bpf: check that BPF programs run with preemption disabled")
https://git.kernel.org/cgit/linux/kernel/git/next/linux-next.git master
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 2G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+---------------------------------------------------------+------------+------------+
| | a5d9265e01 | 568f196756 |
+---------------------------------------------------------+------------+------------+
| boot_successes | 12 | 1 |
| boot_failures | 6 | 13 |
| BUG:kernel_in_stage | 2 | 1 |
| invoked_oom-killer:gfp_mask=0x | 4 | 2 |
| Mem-Info | 4 | 2 |
| Out_of_memory_and_no_killable_processes | 4 | 2 |
| Kernel_panic-not_syncing:System_is_deadlocked_on_memory | 4 | 2 |
| BUG:assuming_atomic_context_at_kernel/seccomp.c | 0 | 9 |
| BUG:kernel_hang_in_test_stage | 0 | 1 |
+---------------------------------------------------------+------------+------------+
[ 11.245441] BUG: assuming atomic context at kernel/seccomp.c:271
[ 11.247417] in_atomic(): 0, irqs_disabled(): 0, pid: 212, name: (journald)
[ 11.248632] CPU: 0 PID: 212 Comm: (journald) Not tainted 5.0.0-rc6-01569-g568f196 #1
[ 11.249957] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 11.251327] Call Trace:
[ 11.252457] dump_stack+0x5c/0x7b
[ 11.253095] __cant_sleep+0xb2/0xc0
[ 11.253717] __seccomp_filter+0x71/0x650
[ 11.254439] ? __mmdrop+0x58/0x100
[ 11.255076] ? finish_task_switch+0x229/0x2b0
[ 11.255831] syscall_trace_enter+0x145/0x2f0
[ 11.269622] do_syscall_64+0x11a/0x180
[ 11.269626] entry_SYSCALL_64_after_hwframe+0x44/0xa9
[ 11.269629] RIP: 0033:0x7fad2ad08229
[ 11.269631] Code: 00 f3 c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 40 00 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 3f 4c 2b 00 f7 d8 64 89 01 48
[ 11.269632] RSP: 002b:00007ffe7ed36cf8 EFLAGS: 00000246 ORIG_RAX: 000000000000013d
[ 11.269634] RAX: ffffffffffffffda RBX: 000055b74891c510 RCX: 00007fad2ad08229
[ 11.269635] RDX: 000055b74891c510 RSI: 0000000000000000 RDI: 0000000000000001
[ 11.269636] RBP: 000055b748916e50 R08: 0000000013248000 R09: 00000000c000003e
[ 11.269636] R10: 000055b74891a250 R11: 0000000000000246 R12: 00007ffe7ed36d50
[ 11.269637] R13: 00007ffe7ed36d48 R14: 00007fad2c455c18 R15: 000000000000002a
Starting udev Kernel Device Manager...
Starting Load/Save Random Seed...
Starting udev Coldplug all Devices...
Starting Preprocess NFS configuration...
Starting Raise network interfaces...
Starting Flush Journal to Persistent Storage...
Starting Create Volatile Files and Directories...
Starting Network Time Synchronization...
Starting RPC bind portmap service...
Starting Update UTMP about System Boot/Shutdown...
Starting LSB: Start and stop bmc-watchdog...
Starting Permit User Sessions...
Starting LSB: Execute the kexec -e command to reboot system...
Starting System Logging Service...
Starting Login Service...
Starting LKP bootstrap...
Starting /etc/rc.local Compatibility...
Starting OpenBSD Secure Shell server...
[
[ 11.748987] random: fast init done
[ 11.554086] rc.local[287]: PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/lkp/lkp/src/bin
Starting LSB: Load kernel image with kexec...
[ 12.034052] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
[ 12.035513] PCI Interrupt Link [LNKD] enabled at IRQ 10
[ 12.087300] parport_pc 00:04: reported by Plug and Play ACPI
LKP: HOSTNAME vm-snb-2G-658, MAC 52:54:00:12:34:56, kernel 5.0.0-rc6-01569-g568f196 1, serial console /dev/ttyS0
[ 12.100785] Floppy drive(s): fd0 is 2.88M AMI BIOS
[ 12.102716] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE]
[ 12.113388] FDC 0 is a S82078B
[ 12.120530] PCI Interrupt Link [LNKA] enabled at IRQ 10
[ 12.206747] PCI Interrupt Link [LNKB] enabled at IRQ 11
[ 12.246019] BUG: assuming atomic context at kernel/seccomp.c:271
[ 12.247435] in_atomic(): 0, irqs_disabled(): 0, pid: 254, name: systemd-udevd
[ 12.248526] CPU: 1 PID: 254 Comm: systemd-udevd Tainted: G W 5.0.0-rc6-01569-g568f196 #1
[ 12.249996] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 12.251340] Call Trace:
[ 12.251840] dump_stack+0x5c/0x7b
[ 12.252443] __cant_sleep+0xb2/0xc0
[ 12.253062] __seccomp_filter+0x71/0x650
[ 12.253733] syscall_trace_enter+0x145/0x2f0
[ 12.254450] do_syscall_64+0x11a/0x180
[ 12.268149] entry_SYSCALL_64_after_hwframe+0x44/0xa9
[ 12.268978] RIP: 0033:0x7f053d851347
[ 12.269606] Code: e2 4c 89 e6 31 ff e8 88 4d 00 00 31 c0 48 83 ec 80 5b 5d 41 5c c3 0f 1f 44 00 00 b8 ff ff ff ff eb eb 90 b8 5f 00 00 00 0f 05 <c3> 0f 1f 84 00 00 00 00 00 b8 5a 00 00 00 0f 05 48 3d 01 f0 ff ff
[ 12.272325] RSP: 002b:00007fff12b543e8 EFLAGS: 00000202 ORIG_RAX: 000000000000005f
[ 12.273559] RAX: ffffffffffffffda RBX: 000055c460880800 RCX: 00007f053d851347
[ 12.274622] RDX: 0000000000000180 RSI: 00000000000800c2 RDI: 0000000000000012
[ 12.275686] RBP: 0000000000000007 R08: 0000000000000220 R09: 0000000000000030
[ 12.276745] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fff12b54480
[ 12.277807] R13: 00007fff12b54478 R14: 0000000000000012 R15: 000000000000000e
[ 12.389426] i6300ESB timer 0000:00:0b.0: initialized (0x(____ptrval____)). heartbeat=30 sec (nowayout=0)
[ 12.412221] [drm] Found bochs VGA, ID 0xb0c0.
[ 12.412971] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebf0000.
[ 12.435573] [TTM] Zone kernel: Available graphics memory: 1019336 kiB
[ 12.436614] [TTM] Initializing pool allocator
[ 12.457635] input: PC Speaker as /devices/platform/pcspkr/input/input5
[ 12.488919] [TTM] Initializing DMA pool allocator
[ 12.490408] bochs-drm 0000:00:02.0: Virtual-1: EDID is invalid:
[ 12.491363] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 12.492360] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 12.493358] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 12.494347] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 12.508397] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 12.509436] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 12.510470] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 12.511515] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 12.664881] fbcon: bochsdrmfb (fb0) is primary device
[ 12.756640] Console: switching to colour frame buffer device 128x48
[ 12.799400] bochs-drm 0000:00:02.0: fb0: bochsdrmfb frame buffer device
[ 12.808113] [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:02.0 on minor 0
[ 12.878102] Error: Driver 'pcspkr' is already registered, aborting...
[ 13.237244] ppdev: user-space parallel port driver
[ 13.254323] BUG: assuming atomic context at kernel/seccomp.c:271
[ 13.255192] in_atomic(): 0, irqs_disabled(): 0, pid: 251, name: systemd-udevd
[ 13.256032] CPU: 1 PID: 251 Comm: systemd-udevd Tainted: G W 5.0.0-rc6-01569-g568f196 #1
[ 13.257051] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 13.257933] Call Trace:
[ 13.258229] dump_stack+0x5c/0x7b
[ 13.258599] __cant_sleep+0xb2/0xc0
[ 13.259010] __seccomp_filter+0x71/0x650
[ 13.259456] ? __switch_to_asm+0x40/0x70
[ 13.259905] ? __switch_to_asm+0x34/0x70
[ 13.260351] ? __switch_to_asm+0x40/0x70
[ 13.260791] ? __switch_to_asm+0x34/0x70
[ 13.261241] ? __switch_to_asm+0x40/0x70
[ 13.261681] ? __switch_to_asm+0x34/0x70
[ 13.262141] ? __switch_to_asm+0x40/0x70
[ 13.262591] ? __switch_to_asm+0x34/0x70
[ 13.263023] ? __switch_to+0x115/0x440
[ 13.263434] syscall_trace_enter+0x145/0x2f0
[ 13.264161] do_syscall_64+0x11a/0x180
[ 13.264825] entry_SYSCALL_64_after_hwframe+0x44/0xa9
[ 13.278730] RIP: 0033:0x7f053d85a4a7
[ 13.279406] Code: c7 c0 ff ff ff ff eb 8d 48 8b 15 ec 49 2b 00 f7 d8 64 89 02 e9 5b ff ff ff 66 2e 0f 1f 84 00 00 00 00 00 b8 0b 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d c1 49 2b 00 f7 d8 64 89 01 48
[ 13.281995] RSP: 002b:00007fff12b53c38 EFLAGS: 00000246 ORIG_RAX: 000000000000000b
[ 13.283132] RAX: ffffffffffffffda RBX: 000055c460880ea0 RCX: 00007f053d85a4a7
[ 13.284240] RDX: 00000000ffffffff RSI: 000000000001ee66 RDI: 00007f053be8b000
[ 13.285359] RBP: 000055c460880780 R08: 000055c460881510 R09: 00007fff12b54000
[ 13.286480] R10: 000000000000000f R11: 0000000000000246 R12: 00007fff12b540e0
[ 13.287611] R13: 00007fff12b540d0 R14: 00007fff12b53c70 R15: 000055c45f589cbc
Starting Preprocess NFS configuration...
Starting Notify NFS peers of a restart...
Starting NFS status monitor for NFSv2/3 locking....
[ 13.453715] virtio_blk virtio0: [vda] 536870912 512-byte logical blocks (275 GB/256 GiB)
[ 13.482007] virtio_blk virtio1: [vdb] 536870912 512-byte logical blocks (275 GB/256 GiB)
[ 13.503013] virtio_blk virtio2: [vdc] 536870912 512-byte logical blocks (275 GB/256 GiB)
[ 13.513350] virtio_blk virtio3: [vdd] 536870912 512-byte logical blocks (275 GB/256 GiB)
[ 13.582059] virtio_blk virtio4: [vde] 536870912 512-byte logical blocks (275 GB/256 GiB)
[ 13.588240] virtio_blk virtio5: [vdf] 536870912 512-byte logical blocks (275 GB/256 GiB)
[ 13.663961] virtio_blk virtio6: [vdg] 536870912 512-byte logical blocks (275 GB/256 GiB)
[ 16.968012] BUG: assuming atomic context at kernel/seccomp.c:271
[ 16.973115] in_atomic(): 0, irqs_disabled(): 0, pid: 212, name: systemd-journal
[ 16.973118] CPU: 1 PID: 212 Comm: systemd-journal Tainted: G W 5.0.0-rc6-01569-g568f196 #1
[ 16.973119] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 16.973122] Call Trace:
[ 16.976267] dump_stack+0x5c/0x7b
[ 16.976274] __cant_sleep+0xb2/0xc0
[ 16.981204] __seccomp_filter+0x71/0x650
[ 16.981207] ? ep_poll+0x2af/0x4b0
[ 16.981213] syscall_trace_enter+0x145/0x2f0
[ 16.982529] do_syscall_64+0x11a/0x180
[ 16.982534] entry_SYSCALL_64_after_hwframe+0x44/0xa9
[ 16.982537] RIP: 0033:0x7ffebb5957ee
[ 16.983930] Code: 48 89 e5 77 1a b8 01 00 00 00 89 f9 d3 e0 a9 03 08 00 00 74 06 5d e9 31 fe ff ff a8 60 75 0c 48 63 ff b8 e4 00 00 00 5d 0f 05 <c3> 48 63 c7 48 8d 15 87 c8 ff ff 48 83 c0 02 48 c1 e0 04 48 01 d0
[ 16.983931] RSP: 002b:00007ffebb506198 EFLAGS: 00000246 ORIG_RAX: 00000000000000e4
[ 16.983933] RAX: ffffffffffffffda RBX: 0000000000000007 RCX: 00007ffebb5957ee
[ 16.983934] RDX: ffffffffffffffff RSI: 00007ffebb5061c0 RDI: 0000000000000007
[ 16.983936] RBP: 00007ffebb5061c0 R08: 00007ffebb5920b0 R09: 00007ffebb592080
[ 16.992337] R10: 00007ffebb5061a0 R11: 0000000000000246 R12: 00007ffebb506200
[ 16.992338] R13: 0000000000000001 R14: 0000000000000001 R15: 0005825da748ccf6
[ 21.982938] BUG: assuming atomic context at kernel/seccomp.c:271
[ 21.986706] in_atomic(): 0, irqs_disabled(): 0, pid: 275, name: systemd-timesyn
[ 21.996001] CPU: 0 PID: 275 Comm: systemd-timesyn Tainted: G W 5.0.0-rc6-01569-g568f196 #1
[ 21.997096] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 21.997983] Call Trace:
[ 21.998272] dump_stack+0x5c/0x7b
[ 21.998639] __cant_sleep+0xb2/0xc0
[ 21.999024] __seccomp_filter+0x71/0x650
[ 21.999448] ? ep_poll+0x2af/0x4b0
[ 21.999821] syscall_trace_enter+0x145/0x2f0
[ 22.000285] do_syscall_64+0x11a/0x180
[ 22.000692] entry_SYSCALL_64_after_hwframe+0x44/0xa9
[ 22.005292] RIP: 0033:0x7ffc165aa7ee
[ 22.005680] Code: 48 89 e5 77 1a b8 01 00 00 00 89 f9 d3 e0 a9 03 08 00 00 74 06 5d e9 31 fe ff ff a8 60 75 0c 48 63 ff b8 e4 00 00 00 5d 0f 05 <c3> 48 63 c7 48 8d 15 87 c8 ff ff 48 83 c0 02 48 c1 e0 04 48 01 d0
[ 22.009716] RSP: 002b:00007ffc1643a2f8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e4
[ 22.011559] RAX: ffffffffffffffda RBX: 0000000000000007 RCX: 00007ffc165aa7ee
[ 22.013366] RDX: ffffffffffffffff RSI: 00007ffc1643a320 RDI: 0000000000000007
[ 22.015184] RBP: 00007ffc1643a320 R08: 00007ffc165a70b0 R09: 00007ffc165a7080
[ 22.015971] R10: 00007ffc1643a300 R11: 0000000000000246 R12: 00007ffc1643a360
[ 22.017806] R13: 0000000000000001 R14: 0000000000000001 R15: 0000000000000000
Elapsed time: 20
qemu-img create -f qcow2 disk-vm-snb-2G-658-0 256G
qemu-img create -f qcow2 disk-vm-snb-2G-658-1 256G
qemu-img create -f qcow2 disk-vm-snb-2G-658-2 256G
qemu-img create -f qcow2 disk-vm-snb-2G-658-3 256G
qemu-img create -f qcow2 disk-vm-snb-2G-658-4 256G
qemu-img create -f qcow2 disk-vm-snb-2G-658-5 256G
qemu-img create -f qcow2 disk-vm-snb-2G-658-6 256G
kvm=(
qemu-system-x86_64
-enable-kvm
-cpu SandyBridge
-kernel $kernel
-initrd initrd-vm-snb-2G-658
-m 2048
-smp 2
-device e1000,netdev=net0
-netdev user,id=net0,hostfwd=tcp::23765-:22
-boot order=nc
-no-reboot
-watchdog i6300esb
-watchdog-action debug
-rtc base=localtime
-drive file=disk-vm-snb-2G-658-0,media=disk,if=virtio
-drive file=disk-vm-snb-2G-658-1,media=disk,if=virtio
-drive file=disk-vm-snb-2G-658-2,media=disk,if=virtio
-drive file=disk-vm-snb-2G-658-3,media=disk,if=virtio
-drive file=disk-vm-snb-2G-658-4,media=disk,if=virtio
-drive file=disk-vm-snb-2G-658-5,media=disk,if=virtio
-drive file=disk-vm-snb-2G-658-6,media=disk,if=virtio
-serial stdio
-display none
-monitor null
)
append=(
ip=::::vm-snb-2G-658::dhcp
root=/dev/ram0
user=lkp
job=/job-script
ARCH=x86_64
kconfig=x86_64-fedora-25
branch=bpf-next/master
commit=568f196756ad9fe2b49c46bbf6a9de1b190438b4
BOOT_IMAGE=/pkg/linux/x86_64-fedora-25/gcc-7/568f196756ad9fe2b49c46bbf6a9de1b190438b4/vmlinuz-5.0.0-rc6-01569-g568f196
max_uptime=600
RESULT_ROOT=/result/boot/1/vm-snb-2G/debian-x86_64-2018-04-03.cgz/x86_64-fedora-25/gcc-7/568f196756ad9fe2b49c46bbf6a9de1b190438b4/8
result_service=tmpfs
debug
apic=debug
sysrq_always_enabled
rcupdate.rcu_cpu_stall_timeout=100
net.ifnames=0
printk.devkmsg=on
panic=-1
softlockup_panic=1
nmi_watchdog=panic
oops=panic
load_ramdisk=2
prompt_ramdisk=0
drbd.minor_count=8
systemd.log_level=err
ignore_loglevel
console=tty0
earlyprintk=ttyS0,115200
console=ttyS0,115200
vga=normal
rw
rcuperf.shutdown=0
)
"${kvm[@]}" -append "${append[*]}"
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
Rong Chen
1 year, 11 months
[driver core] 570d020012: will-it-scale.per_thread_ops -12.2% regression
by kernel test robot
Greeting,
FYI, we noticed a -12.2% regression of will-it-scale.per_thread_ops due to commit:
commit: 570d0200123fb4f809aa2f6226e93a458d664d70 ("driver core: move device->knode_class to device_private")
https://git.kernel.org/cgit/linux/kernel/git/next/linux-next.git master
in testcase: will-it-scale
on test machine: 288 threads Knights Mill with 80G memory
with following parameters:
nr_task: 100%
mode: thread
test: unlink2
cpufreq_governor: performance
test-description: Will It Scale takes a testcase and runs it from 1 through to n parallel copies to see if the testcase will scale. It builds both a process and threads based test in order to see any differences between the two.
test-url: https://github.com/antonblanchard/will-it-scale
In addition to that, the commit also has significant impact on the following tests:
+------------------+---------------------------------------------------------------+
| testcase: change | will-it-scale: will-it-scale.per_thread_ops -29.9% regression |
| test machine | 288 threads Knights Mill with 80G memory |
| test parameters | cpufreq_governor=performance |
| | mode=thread |
| | nr_task=100% |
| | test=signal1 |
+------------------+---------------------------------------------------------------+
| testcase: change | will-it-scale: will-it-scale.per_thread_ops -16.5% regression |
| test machine | 288 threads Knights Mill with 80G memory |
| test parameters | cpufreq_governor=performance |
| | mode=thread |
| | nr_task=100% |
| | test=open1 |
+------------------+---------------------------------------------------------------+
Details are as below:
-------------------------------------------------------------------------------------------------->
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
=========================================================================================
compiler/cpufreq_governor/kconfig/mode/nr_task/rootfs/tbox_group/test/testcase:
gcc-7/performance/x86_64-rhel-7.2/thread/100%/debian-x86_64-2018-04-03.cgz/lkp-knm01/unlink2/will-it-scale
commit:
4bd4e92cfe ("sysfs: fix blank line coding style warning")
570d020012 ("driver core: move device->knode_class to device_private")
4bd4e92cfe6d2af7 570d0200123fb4f809aa2f6226
---------------- --------------------------
%stddev %change %stddev
\ | \
937.00 -12.2% 823.00 will-it-scale.per_thread_ops
906625 -1.7% 891438 will-it-scale.time.involuntary_context_switches
269989 -12.2% 237096 will-it-scale.workload
17104244 -12.3% 15001702 softirqs.RCU
6886 -1.7% 6768 vmstat.system.cs
2.088e+08 ± 17% -56.9% 90059912 ± 57% cpuidle.C1.time
683051 ± 15% -42.5% 392628 ± 40% cpuidle.C1.usage
4.519e+08 ± 6% +27.8% 5.777e+08 ± 9% cpuidle.C6.time
648490 ± 14% -42.7% 371724 ± 43% turbostat.C1
0.23 ± 18% -0.1 0.10 ± 62% turbostat.C1%
0.42 ± 9% +0.1 0.55 ± 11% turbostat.C6%
0.41 ± 5% -18.1% 0.34 ± 16% turbostat.CPU%c1
0.30 ± 6% +27.9% 0.39 ± 13% turbostat.CPU%c6
5.22 ± 13% -1.9 3.35 ± 4% perf-profile.calltrace.cycles-pp.path_openat.do_filp_open.do_sys_open.do_syscall_64.entry_SYSCALL_64_after_hwframe
5.27 ± 13% -1.9 3.40 ± 3% perf-profile.calltrace.cycles-pp.do_filp_open.do_sys_open.do_syscall_64.entry_SYSCALL_64_after_hwframe.__GI___libc_open
4.71 ± 14% -1.8 2.92 ± 4% perf-profile.calltrace.cycles-pp.unlink
4.58 ± 15% -1.8 2.81 ± 4% perf-profile.calltrace.cycles-pp.entry_SYSCALL_64_after_hwframe.unlink
4.56 ± 15% -1.8 2.80 ± 4% perf-profile.calltrace.cycles-pp.do_syscall_64.entry_SYSCALL_64_after_hwframe.unlink
4.40 ± 15% -1.8 2.64 ± 5% perf-profile.calltrace.cycles-pp.do_unlinkat.do_syscall_64.entry_SYSCALL_64_after_hwframe.unlink
3.18 ± 23% -1.6 1.53 ± 9% perf-profile.calltrace.cycles-pp.shmem_mknod.path_openat.do_filp_open.do_sys_open.do_syscall_64
1.70 ± 19% -0.7 0.95 ± 14% perf-profile.calltrace.cycles-pp.shmem_get_inode.shmem_mknod.path_openat.do_filp_open.do_sys_open
1.47 ± 20% -0.7 0.76 ± 18% perf-profile.calltrace.cycles-pp.evict.do_unlinkat.do_syscall_64.entry_SYSCALL_64_after_hwframe.unlink
1.43 ± 23% -0.7 0.73 ± 18% perf-profile.calltrace.cycles-pp.new_inode.shmem_get_inode.shmem_mknod.path_openat.do_filp_open
1.57 ± 5% -0.5 1.04 ± 57% perf-profile.calltrace.cycles-pp.perf_mux_hrtimer_handler.__hrtimer_run_queues.hrtimer_interrupt.smp_apic_timer_interrupt.apic_timer_interrupt
0.58 -0.1 0.52 ± 2% perf-profile.calltrace.cycles-pp.filename_parentat.do_unlinkat.do_syscall_64.entry_SYSCALL_64_after_hwframe.unlink
2.31 ± 16% +0.7 3.00 ± 9% perf-profile.calltrace.cycles-pp.tick_sched_timer.__hrtimer_run_queues.hrtimer_interrupt.smp_apic_timer_interrupt.apic_timer_interrupt
2.04 ± 17% +0.7 2.76 ± 7% perf-profile.calltrace.cycles-pp.update_process_times.tick_sched_handle.tick_sched_timer.__hrtimer_run_queues.hrtimer_interrupt
2.09 ± 17% +0.7 2.82 ± 7% perf-profile.calltrace.cycles-pp.tick_sched_handle.tick_sched_timer.__hrtimer_run_queues.hrtimer_interrupt.smp_apic_timer_interrupt
44.55 +1.7 46.29 perf-profile.calltrace.cycles-pp.__GI___libc_close
44.26 +1.8 46.07 perf-profile.calltrace.cycles-pp.entry_SYSCALL_64_after_hwframe.__GI___libc_close
44.24 +1.8 46.05 perf-profile.calltrace.cycles-pp.do_syscall_64.entry_SYSCALL_64_after_hwframe.__GI___libc_close
43.67 +1.8 45.49 perf-profile.calltrace.cycles-pp.__close_fd.__x64_sys_close.do_syscall_64.entry_SYSCALL_64_after_hwframe.__GI___libc_close
43.80 +1.9 45.67 perf-profile.calltrace.cycles-pp.__x64_sys_close.do_syscall_64.entry_SYSCALL_64_after_hwframe.__GI___libc_close
43.67 +1.9 45.61 perf-profile.calltrace.cycles-pp.__alloc_fd.do_sys_open.do_syscall_64.entry_SYSCALL_64_after_hwframe.__GI___libc_open
43.11 +2.0 45.13 perf-profile.calltrace.cycles-pp._raw_spin_lock.__close_fd.__x64_sys_close.do_syscall_64.entry_SYSCALL_64_after_hwframe
43.17 +2.0 45.22 perf-profile.calltrace.cycles-pp.native_queued_spin_lock_slowpath._raw_spin_lock.__close_fd.__x64_sys_close.do_syscall_64
43.08 +2.1 45.17 perf-profile.calltrace.cycles-pp._raw_spin_lock.__alloc_fd.do_sys_open.do_syscall_64.entry_SYSCALL_64_after_hwframe
43.13 +2.1 45.26 perf-profile.calltrace.cycles-pp.native_queued_spin_lock_slowpath._raw_spin_lock.__alloc_fd.do_sys_open.do_syscall_64
12.29 -10.0% 11.06 ± 2% perf-stat.i.MPKI
8.564e+09 -1.7% 8.421e+09 perf-stat.i.branch-instructions
1.59 -0.1 1.46 ± 2% perf-stat.i.branch-miss-rate%
1.356e+08 -9.7% 1.225e+08 perf-stat.i.branch-misses
16.95 +1.1 18.02 perf-stat.i.cache-miss-rate%
74122780 -6.2% 69544118 perf-stat.i.cache-misses
4.377e+08 -11.8% 3.862e+08 perf-stat.i.cache-references
6523 -1.6% 6416 perf-stat.i.context-switches
12.02 +1.6% 12.21 perf-stat.i.cpi
5778 +6.2% 6134 perf-stat.i.cycles-between-cache-misses
0.33 -0.0 0.29 ± 2% perf-stat.i.iTLB-load-miss-rate%
1.185e+08 -15.6% 1e+08 ± 2% perf-stat.i.iTLB-load-misses
3.555e+10 -2.0% 3.484e+10 perf-stat.i.iTLB-loads
3.563e+10 -2.0% 3.492e+10 perf-stat.i.instructions
300.74 +16.2% 349.51 ± 2% perf-stat.i.instructions-per-iTLB-miss
0.08 -1.6% 0.08 perf-stat.i.ipc
12.28 -10.0% 11.06 ± 2% perf-stat.overall.MPKI
1.58 -0.1 1.45 ± 2% perf-stat.overall.branch-miss-rate%
16.94 +1.1 18.01 perf-stat.overall.cache-miss-rate%
12.02 +1.6% 12.21 perf-stat.overall.cpi
5776 +6.2% 6132 perf-stat.overall.cycles-between-cache-misses
0.33 -0.0 0.29 ± 2% perf-stat.overall.iTLB-load-miss-rate%
300.68 +16.2% 349.34 ± 2% perf-stat.overall.instructions-per-iTLB-miss
0.08 -1.6% 0.08 perf-stat.overall.ipc
39542686 +11.7% 44150058 perf-stat.overall.path-length
8.53e+09 -1.7% 8.388e+09 perf-stat.ps.branch-instructions
1.351e+08 -9.7% 1.22e+08 perf-stat.ps.branch-misses
73835513 -6.2% 69273046 perf-stat.ps.cache-misses
4.36e+08 -11.8% 3.847e+08 perf-stat.ps.cache-references
6498 -1.7% 6390 perf-stat.ps.context-switches
1.18e+08 -15.6% 99644821 ± 2% perf-stat.ps.iTLB-load-misses
3.541e+10 -2.0% 3.47e+10 perf-stat.ps.iTLB-loads
3.549e+10 -2.0% 3.479e+10 perf-stat.ps.instructions
1.067e+13 -1.9% 1.047e+13 perf-stat.total.instructions
595740 ± 18% -43.0% 339338 ± 28% sched_debug.cfs_rq:/.MIN_vruntime.avg
4833300 ± 8% -31.2% 3327465 ± 24% sched_debug.cfs_rq:/.MIN_vruntime.stddev
15804 ± 7% -28.3% 11329 ± 22% sched_debug.cfs_rq:/.load.avg
1096777 -24.1% 832275 ± 27% sched_debug.cfs_rq:/.load.max
108529 ± 4% -27.8% 78378 ± 26% sched_debug.cfs_rq:/.load.stddev
12.36 ± 3% -11.3% 10.96 sched_debug.cfs_rq:/.load_avg.avg
522.40 ± 8% -32.1% 354.80 ± 23% sched_debug.cfs_rq:/.load_avg.max
35.08 ± 7% -28.4% 25.13 ± 21% sched_debug.cfs_rq:/.load_avg.stddev
595740 ± 18% -43.0% 339338 ± 28% sched_debug.cfs_rq:/.max_vruntime.avg
4833300 ± 8% -31.2% 3327465 ± 24% sched_debug.cfs_rq:/.max_vruntime.stddev
0.12 ± 9% -21.9% 0.10 ± 18% sched_debug.cfs_rq:/.nr_running.stddev
77.95 ± 6% +37.6% 107.25 ± 19% sched_debug.cfs_rq:/.nr_spread_over.max
7.10 ± 2% +20.5% 8.55 ± 15% sched_debug.cfs_rq:/.nr_spread_over.stddev
4.39 -15.0% 3.73 ± 8% sched_debug.cfs_rq:/.runnable_load_avg.avg
468.00 -35.1% 303.60 ± 30% sched_debug.cfs_rq:/.runnable_load_avg.max
28.56 -35.1% 18.54 ± 29% sched_debug.cfs_rq:/.runnable_load_avg.stddev
15797 ± 7% -28.3% 11323 ± 22% sched_debug.cfs_rq:/.runnable_weight.avg
1096533 -24.1% 832050 ± 27% sched_debug.cfs_rq:/.runnable_weight.max
108522 ± 4% -27.8% 78365 ± 26% sched_debug.cfs_rq:/.runnable_weight.stddev
772.59 ± 6% -28.1% 555.30 ± 5% sched_debug.cpu.clock.stddev
772.58 ± 6% -28.1% 555.30 ± 5% sched_debug.cpu.clock_task.stddev
4.43 -15.1% 3.76 ± 8% sched_debug.cpu.cpu_load[0].avg
28.58 -33.9% 18.88 ± 27% sched_debug.cpu.cpu_load[0].stddev
4.51 ± 3% -16.6% 3.76 ± 8% sched_debug.cpu.cpu_load[1].avg
29.07 ± 6% -35.8% 18.66 ± 28% sched_debug.cpu.cpu_load[1].stddev
4.45 ± 2% -15.7% 3.75 ± 8% sched_debug.cpu.cpu_load[2].avg
28.36 ± 4% -34.7% 18.53 ± 28% sched_debug.cpu.cpu_load[2].stddev
4.40 -15.0% 3.73 ± 8% sched_debug.cpu.cpu_load[3].avg
27.95 ± 3% -34.1% 18.43 ± 28% sched_debug.cpu.cpu_load[3].stddev
4.34 ± 2% -14.3% 3.72 ± 8% sched_debug.cpu.cpu_load[4].avg
456.00 ± 3% -34.2% 300.10 ± 29% sched_debug.cpu.cpu_load[4].max
27.74 ± 2% -33.6% 18.41 ± 28% sched_debug.cpu.cpu_load[4].stddev
15602 ± 7% -20.4% 12420 ± 19% sched_debug.cpu.load.avg
106214 ± 3% -19.2% 85804 ± 18% sched_debug.cpu.load.stddev
0.00 ± 4% -24.5% 0.00 ± 3% sched_debug.cpu.next_balance.stddev
0.50 ± 26% -54.8% 0.23 ± 79% sched_debug.rt_rq:/.rt_time.avg
143.90 ± 26% -54.8% 65.01 ± 79% sched_debug.rt_rq:/.rt_time.max
8.46 ± 26% -54.8% 3.82 ± 79% sched_debug.rt_rq:/.rt_time.stddev
will-it-scale.per_thread_ops
1000 +-+------------------------------------------------------------------+
| |
|.. +.. .+ +.. .+.. .+ + |
950 +-+ + +. + .. .+..+.. .+. + + +.. .. |
| + + + + + + + |
| + |
900 +-+ |
| O O |
850 +-+ O O O O |
O O O O O O O O O |
| O O O O O O O
800 +-+O O O |
| O |
| O |
750 +-+------------------------------------------------------------------+
[*] bisect-good sample
[O] bisect-bad sample
***************************************************************************************************
lkp-knm01: 288 threads Knights Mill with 80G memory
=========================================================================================
compiler/cpufreq_governor/kconfig/mode/nr_task/rootfs/tbox_group/test/testcase:
gcc-7/performance/x86_64-rhel-7.2/thread/100%/debian-x86_64-2018-04-03.cgz/lkp-knm01/signal1/will-it-scale
commit:
4bd4e92cfe ("sysfs: fix blank line coding style warning")
570d020012 ("driver core: move device->knode_class to device_private")
4bd4e92cfe6d2af7 570d0200123fb4f809aa2f6226
---------------- --------------------------
fail:runs %reproduction fail:runs
| | |
:4 25% 1:4 dmesg.WARNING:at#for_ip_interrupt_entry/0x
%stddev %change %stddev
\ | \
266.75 ± 2% -29.9% 187.00 will-it-scale.per_thread_ops
131.40 ± 6% -29.3% 92.91 ± 3% will-it-scale.time.user_time
76931 ± 2% -29.9% 53963 will-it-scale.workload
788152 ± 8% +17.8% 928405 ± 5% cpuidle.C6.usage
33255 ± 4% -8.2% 30514 ± 2% numa-meminfo.node1.Slab
34856762 +14.5% 39908744 softirqs.TIMER
0.00 ± 26% +0.0 0.01 ± 67% mpstat.cpu.soft%
0.28 ± 3% -0.0 0.23 ± 2% mpstat.cpu.usr%
981.00 ± 7% -12.8% 855.00 ± 2% slabinfo.skbuff_ext_cache.active_objs
981.00 ± 7% -12.8% 855.00 ± 2% slabinfo.skbuff_ext_cache.num_objs
714927 ± 11% +21.5% 868290 ± 5% turbostat.C6
1.71 ± 5% +39.9% 2.39 turbostat.RAMWatt
1121 ± 2% -23.8% 854.06 ± 4% sched_debug.cfs_rq:/.exec_clock.stddev
20.90 ± 11% +42.0% 29.68 ± 17% sched_debug.cfs_rq:/.nr_spread_over.avg
54.08 ± 4% +18.2% 63.93 ± 7% sched_debug.cfs_rq:/.util_est_enqueued.stddev
349403 ± 73% +94.9% 680874 ± 2% sched_debug.cpu.avg_idle.min
860.45 ± 4% -37.1% 540.88 ± 7% sched_debug.cpu.clock.stddev
860.45 ± 4% -37.1% 540.88 ± 7% sched_debug.cpu.clock_task.stddev
5355 ± 14% +21.7% 6517 sched_debug.cpu.curr->pid.max
0.00 ± 3% -36.6% 0.00 ± 6% sched_debug.cpu.next_balance.stddev
16.16 -0.2 16.00 perf-profile.calltrace.cycles-pp._raw_spin_lock_irq.__set_current_blocked.signal_setup_done.do_signal.exit_to_usermode_loop
16.14 -0.2 15.99 perf-profile.calltrace.cycles-pp.native_queued_spin_lock_slowpath._raw_spin_lock_irq.__set_current_blocked.signal_setup_done.do_signal
32.16 -0.1 32.04 perf-profile.calltrace.cycles-pp.native_queued_spin_lock_slowpath._raw_spin_lock_irq.__set_current_blocked.sigprocmask.__x64_sys_rt_sigprocmask
16.12 -0.1 16.01 perf-profile.calltrace.cycles-pp.native_queued_spin_lock_slowpath._raw_spin_lock_irq.get_signal.do_signal.exit_to_usermode_loop
16.13 -0.1 16.01 perf-profile.calltrace.cycles-pp._raw_spin_lock_irq.get_signal.do_signal.exit_to_usermode_loop.do_syscall_64
32.17 -0.1 32.05 perf-profile.calltrace.cycles-pp._raw_spin_lock_irq.__set_current_blocked.sigprocmask.__x64_sys_rt_sigprocmask.do_syscall_64
16.11 -0.1 16.00 perf-profile.calltrace.cycles-pp.native_queued_spin_lock_slowpath._raw_spin_lock_irq.__set_current_blocked.__x64_sys_rt_sigreturn.do_syscall_64
16.12 -0.1 16.01 perf-profile.calltrace.cycles-pp._raw_spin_lock_irq.__set_current_blocked.__x64_sys_rt_sigreturn.do_syscall_64.entry_SYSCALL_64_after_hwframe
16.63 -0.1 16.54 perf-profile.calltrace.cycles-pp.handler
16.49 -0.1 16.40 perf-profile.calltrace.cycles-pp.__set_current_blocked.signal_setup_done.do_signal.exit_to_usermode_loop.do_syscall_64
16.57 -0.1 16.48 perf-profile.calltrace.cycles-pp.do_signal.exit_to_usermode_loop.do_syscall_64.entry_SYSCALL_64_after_hwframe.handler
16.58 -0.1 16.49 perf-profile.calltrace.cycles-pp.entry_SYSCALL_64_after_hwframe.handler
16.58 -0.1 16.49 perf-profile.calltrace.cycles-pp.do_syscall_64.entry_SYSCALL_64_after_hwframe.handler
16.57 -0.1 16.49 perf-profile.calltrace.cycles-pp.exit_to_usermode_loop.do_syscall_64.entry_SYSCALL_64_after_hwframe.handler
16.52 -0.1 16.44 perf-profile.calltrace.cycles-pp.signal_setup_done.do_signal.exit_to_usermode_loop.do_syscall_64.entry_SYSCALL_64_after_hwframe
16.63 -0.1 16.56 perf-profile.calltrace.cycles-pp.do_signal.exit_to_usermode_loop.do_syscall_64.entry_SYSCALL_64_after_hwframe.raise
16.65 -0.1 16.58 perf-profile.calltrace.cycles-pp.exit_to_usermode_loop.do_syscall_64.entry_SYSCALL_64_after_hwframe.raise
16.52 -0.1 16.46 perf-profile.calltrace.cycles-pp.get_signal.do_signal.exit_to_usermode_loop.do_syscall_64.entry_SYSCALL_64_after_hwframe
0.59 ± 4% +0.1 0.71 ± 2% perf-profile.calltrace.cycles-pp.smp_apic_timer_interrupt.apic_timer_interrupt.__set_current_blocked.sigprocmask.__x64_sys_rt_sigprocmask
0.64 ± 5% +0.1 0.77 ± 2% perf-profile.calltrace.cycles-pp.apic_timer_interrupt.__set_current_blocked.sigprocmask.__x64_sys_rt_sigprocmask.do_syscall_64
0.27 ±100% +0.3 0.59 ± 2% perf-profile.calltrace.cycles-pp.hrtimer_interrupt.smp_apic_timer_interrupt.apic_timer_interrupt.__set_current_blocked.sigprocmask
4.55 ± 2% -8.7% 4.15 perf-stat.i.MPKI
0.52 -0.1 0.47 perf-stat.i.branch-miss-rate%
47148684 -8.9% 42963996 perf-stat.i.branch-misses
35569495 ± 2% -4.9% 33832492 perf-stat.i.cache-misses
1.66e+08 -7.7% 1.533e+08 perf-stat.i.cache-references
12.38 -1.2% 12.24 perf-stat.i.cpi
12726 ± 2% +5.1% 13374 perf-stat.i.cycles-between-cache-misses
0.09 ± 4% -0.0 0.07 perf-stat.i.iTLB-load-miss-rate%
32470934 ± 5% -14.8% 27651795 perf-stat.i.iTLB-load-misses
1128 ± 4% +18.6% 1339 perf-stat.i.instructions-per-iTLB-miss
4.54 ± 2% -8.7% 4.15 perf-stat.overall.MPKI
0.52 -0.1 0.47 ± 2% perf-stat.overall.branch-miss-rate%
12.38 -1.2% 12.24 perf-stat.overall.cpi
12722 ± 2% +5.1% 13367 perf-stat.overall.cycles-between-cache-misses
0.09 ± 4% -0.0 0.07 perf-stat.overall.iTLB-load-miss-rate%
1127 ± 4% +18.5% 1336 perf-stat.overall.instructions-per-iTLB-miss
1.414e+08 ± 2% +43.9% 2.035e+08 ± 2% perf-stat.overall.path-length
46584988 -9.3% 42262634 perf-stat.ps.branch-misses
35172756 ± 2% -5.0% 33400721 perf-stat.ps.cache-misses
1.641e+08 -7.8% 1.513e+08 perf-stat.ps.cache-references
32116706 ± 5% -15.0% 27304782 perf-stat.ps.iTLB-load-misses
***************************************************************************************************
lkp-knm01: 288 threads Knights Mill with 80G memory
=========================================================================================
compiler/cpufreq_governor/kconfig/mode/nr_task/rootfs/tbox_group/test/testcase:
gcc-7/performance/x86_64-rhel-7.2/thread/100%/debian-x86_64-2018-04-03.cgz/lkp-knm01/open1/will-it-scale
commit:
4bd4e92cfe ("sysfs: fix blank line coding style warning")
570d020012 ("driver core: move device->knode_class to device_private")
4bd4e92cfe6d2af7 570d0200123fb4f809aa2f6226
---------------- --------------------------
%stddev %change %stddev
\ | \
945.00 -16.5% 788.75 will-it-scale.per_thread_ops
568697 ± 7% -21.3% 447754 will-it-scale.time.involuntary_context_switches
303.55 ± 2% -7.2% 281.60 ± 2% will-it-scale.time.user_time
272400 -16.6% 227268 will-it-scale.workload
0.08 ± 9% -0.0 0.05 ± 5% mpstat.cpu.soft%
192099 -11.9% 169161 ± 3% slabinfo.filp.active_objs
5043716 -12.1% 4431600 ± 2% softirqs.RCU
2.31 ± 2% +11.2% 2.58 turbostat.RAMWatt
4620 ± 6% -19.2% 3732 vmstat.system.cs
5458 +1.4% 5532 proc-vmstat.nr_inactive_anon
5458 +1.4% 5532 proc-vmstat.nr_zone_inactive_anon
7.27 ± 4% -0.8 6.50 ± 4% perf-profile.calltrace.cycles-pp.hrtimer_interrupt.smp_apic_timer_interrupt.apic_timer_interrupt.native_queued_spin_lock_slowpath._raw_spin_lock
6.02 ± 5% -0.6 5.40 ± 5% perf-profile.calltrace.cycles-pp.__hrtimer_run_queues.hrtimer_interrupt.smp_apic_timer_interrupt.apic_timer_interrupt.native_queued_spin_lock_slowpath
1.22 ± 2% -0.2 1.07 ± 2% perf-profile.calltrace.cycles-pp.do_filp_open.do_sys_open.do_syscall_64.entry_SYSCALL_64_after_hwframe.__GI___libc_open
1.16 ± 2% -0.1 1.02 ± 2% perf-profile.calltrace.cycles-pp.path_openat.do_filp_open.do_sys_open.do_syscall_64.entry_SYSCALL_64_after_hwframe
50.11 -0.1 49.98 perf-profile.calltrace.cycles-pp.__GI___libc_open
47.70 +0.2 47.86 perf-profile.calltrace.cycles-pp._raw_spin_lock.__alloc_fd.do_sys_open.do_syscall_64.entry_SYSCALL_64_after_hwframe
47.89 +0.2 48.12 perf-profile.calltrace.cycles-pp._raw_spin_lock.__close_fd.__x64_sys_close.do_syscall_64.entry_SYSCALL_64_after_hwframe
306780 ± 31% -46.2% 165114 ± 27% sched_debug.cfs_rq:/.MIN_vruntime.avg
37943697 ± 12% -23.6% 28986155 ± 20% sched_debug.cfs_rq:/.MIN_vruntime.max
3199728 ± 21% -33.9% 2114259 ± 22% sched_debug.cfs_rq:/.MIN_vruntime.stddev
1368 ± 7% -17.0% 1135 ± 9% sched_debug.cfs_rq:/.exec_clock.stddev
10913 ± 25% -29.9% 7650 ± 10% sched_debug.cfs_rq:/.load.avg
76190 ± 22% -21.5% 59825 ± 7% sched_debug.cfs_rq:/.load.stddev
306780 ± 31% -46.2% 165114 ± 27% sched_debug.cfs_rq:/.max_vruntime.avg
37943697 ± 12% -23.6% 28986155 ± 20% sched_debug.cfs_rq:/.max_vruntime.max
3199728 ± 21% -33.9% 2114259 ± 22% sched_debug.cfs_rq:/.max_vruntime.stddev
10906 ± 25% -29.9% 7642 ± 10% sched_debug.cfs_rq:/.runnable_weight.avg
76186 ± 22% -21.5% 59800 ± 7% sched_debug.cfs_rq:/.runnable_weight.stddev
234073 ± 39% +134.5% 548907 ± 71% sched_debug.cfs_rq:/.spread0.avg
1117 ± 11% -26.5% 821.87 ± 5% sched_debug.cpu.clock.stddev
1117 ± 11% -26.5% 821.87 ± 5% sched_debug.cpu.clock_task.stddev
0.00 ± 8% -25.6% 0.00 ± 6% sched_debug.cpu.next_balance.stddev
2333 ± 3% -14.3% 1998 sched_debug.cpu.nr_switches.avg
1323 ± 4% -20.1% 1057 sched_debug.cpu.nr_switches.min
2160 ± 4% -12.5% 1891 ± 2% sched_debug.cpu.sched_count.avg
1179 ± 5% -17.0% 978.15 sched_debug.cpu.sched_count.min
1.55 ± 66% -100.0% 0.00 sched_debug.cpu.sched_goidle.min
912.99 ± 4% -18.1% 747.65 sched_debug.cpu.ttwu_count.avg
590.45 ± 5% -21.5% 463.60 ± 2% sched_debug.cpu.ttwu_count.min
835.45 ± 5% -19.8% 670.09 sched_debug.cpu.ttwu_local.avg
561.95 ± 5% -22.5% 435.70 ± 3% sched_debug.cpu.ttwu_local.min
6.47 -8.2% 5.93 ± 3% perf-stat.i.MPKI
8.137e+09 +1.8% 8.282e+09 perf-stat.i.branch-instructions
0.85 -0.1 0.77 ± 2% perf-stat.i.branch-miss-rate%
69084658 -7.5% 63892585 ± 2% perf-stat.i.branch-misses
44409176 -6.8% 41391448 ± 3% perf-stat.i.cache-misses
2.151e+08 -6.9% 2.003e+08 ± 3% perf-stat.i.cache-references
4250 ± 7% -19.1% 3436 perf-stat.i.context-switches
13.05 -1.8% 12.82 perf-stat.i.cpi
9785 +7.2% 10490 ± 3% perf-stat.i.cycles-between-cache-misses
0.20 -0.0 0.18 ± 6% perf-stat.i.iTLB-load-miss-rate%
67639103 -11.5% 59865979 ± 6% perf-stat.i.iTLB-load-misses
3.324e+10 +1.4% 3.371e+10 perf-stat.i.iTLB-loads
3.328e+10 +1.5% 3.379e+10 perf-stat.i.instructions
492.13 +15.2% 566.96 ± 6% perf-stat.i.instructions-per-iTLB-miss
0.08 +1.8% 0.08 perf-stat.i.ipc
6.46 -8.3% 5.93 ± 3% perf-stat.overall.MPKI
0.85 -0.1 0.77 ± 2% perf-stat.overall.branch-miss-rate%
13.05 -1.8% 12.82 perf-stat.overall.cpi
9782 +7.1% 10480 ± 3% perf-stat.overall.cycles-between-cache-misses
0.20 -0.0 0.18 ± 6% perf-stat.overall.iTLB-load-miss-rate%
492.04 +15.2% 566.73 ± 6% perf-stat.overall.instructions-per-iTLB-miss
0.08 +1.8% 0.08 perf-stat.overall.ipc
36640385 +21.7% 44597360 perf-stat.overall.path-length
8.103e+09 +1.8% 8.249e+09 perf-stat.ps.branch-instructions
68811380 -7.5% 63646780 ± 2% perf-stat.ps.branch-misses
44229668 -6.8% 41229829 ± 3% perf-stat.ps.cache-misses
2.142e+08 -6.9% 1.995e+08 ± 3% perf-stat.ps.cache-references
4233 ± 7% -19.1% 3423 perf-stat.ps.context-switches
67364890 -11.5% 59634016 ± 6% perf-stat.ps.iTLB-load-misses
3.315e+10 +1.5% 3.365e+10 perf-stat.ps.instructions
9.98e+12 +1.5% 1.013e+13 perf-stat.total.instructions
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,
Rong Chen
1 year, 11 months
5b7fddbab3 ("i2c: Convert i2c_adapter_idr to XArray"): WARNING: CPU: 1 PID: 1 at lib/xarray.c:1638 __xa_alloc
by kernel test robot
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
git://git.infradead.org/users/willy/linux-dax.git xarray-conv
commit 5b7fddbab3a7bdcfea0b8f59ad24f6e1edf7d568
Author: Matthew Wilcox <willy(a)infradead.org>
AuthorDate: Mon Feb 18 13:39:27 2019 -0500
Commit: Matthew Wilcox <willy(a)infradead.org>
CommitDate: Tue Feb 19 08:07:20 2019 -0500
i2c: Convert i2c_adapter_idr to XArray
Signed-off-by: Matthew Wilcox <willy(a)infradead.org>
81360c028d ib/bnxt: Remove mention of idr_alloc from comment
5b7fddbab3 i2c: Convert i2c_adapter_idr to XArray
1125c1e181 radix-tree, idr: Remove IDR
+---------------------------------------------------------+------------+------------+------------+
| | 81360c028d | 5b7fddbab3 | 1125c1e181 |
+---------------------------------------------------------+------------+------------+------------+
| boot_successes | 51 | 0 | 0 |
| boot_failures | 0 | 26 | 24 |
| WARNING:at_lib/xarray.c:#__xa_alloc | 0 | 26 | 24 |
| EIP:__xa_alloc | 0 | 26 | 24 |
| WARNING:at_drivers/i2c/i2c-core-base.c:#i2c_add_adapter | 0 | 26 | 24 |
| EIP:i2c_add_adapter | 0 | 26 | 24 |
+---------------------------------------------------------+------------+------------+------------+
[ 10.795881] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest7/status
[ 10.798162] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/status
[ 10.812931] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/property-foo
[ 10.814750] OF: overlay: node_overlaps_later_cs: #6 overlaps with #7 @/testcase-data/overlay-node/test-bus/test-unittest8
[ 10.816170] OF: overlay: overlay #6 is not topmost
[ 10.818970] WARNING: CPU: 1 PID: 1 at lib/xarray.c:1638 __xa_alloc+0xf0/0x100
[ 10.819930] Modules linked in:
[ 10.820344] CPU: 1 PID: 1 Comm: swapper/0 Not tainted 5.0.0-rc5-00236-g5b7fddb #242
[ 10.821338] EIP: __xa_alloc+0xf0/0x100
[ 10.821850] Code: 83 c4 20 5b 5e 5f 5d c3 83 c4 20 c1 f8 02 5b 5e 5f 5d c3 8d b4 26 00 00 00 00 0f 0b 83 c4 20 b8 ea ff ff ff 5b 5e 5f 5d c3 90 <0f> 0b b8 ea ff ff ff eb cd 8d b4 26 00 00 00 00 55 89 e5 83 ec 24
[ 10.824228] EAX: bbcf0560 EBX: cdf21814 ECX: cdf21814 EDX: 00000000
[ 10.825063] ESI: 00000000 EDI: cdf21b7c EBP: b0031d34 ESP: b0031d08
[ 10.825983] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068 EFLAGS: 00210246
[ 10.826906] CR0: 80050033 CR2: ffffffff CR3: 0bf37000 CR4: 001406d0
[ 10.827764] Call Trace:
[ 10.828106] i2c_add_adapter+0x63/0xc0
[ 10.828612] i2c_add_numbered_adapter+0x25/0x30
[ 10.829321] unittest_i2c_bus_probe+0xa9/0x110
[ 10.829934] platform_drv_probe+0x4b/0xa0
[ 10.830487] ? driver_sysfs_add+0x6d/0xa0
[ 10.831030] really_probe+0xd5/0x280
[ 10.831537] ? _raw_spin_unlock_irq+0x28/0x50
[ 10.832105] driver_probe_device+0x49/0xe0
[ 10.832664] ? mutex_lock_nested+0x29/0x30
[ 10.833219] __driver_attach+0xc1/0xe0
[ 10.833738] ? driver_probe_device+0xe0/0xe0
[ 10.834318] bus_for_each_dev+0x55/0x90
[ 10.834848] driver_attach+0x1c/0x30
[ 10.835335] ? driver_probe_device+0xe0/0xe0
[ 10.835918] bus_add_driver+0x10a/0x1b0
[ 10.836452] driver_register+0x64/0xb0
[ 10.836954] ? i2c_for_each_dev+0x39/0x50
[ 10.837504] ? __process_new_adapter+0x30/0x30
[ 10.838108] __platform_driver_register+0x37/0x40
[ 10.838768] of_unittest+0x1df1/0x252f
[ 10.839358] ? of_unittest_apply_overlay_check+0x174/0x174
[ 10.840097] do_one_initcall+0x33/0x155
[ 10.840626] ? parse_args+0x18e/0x260
[ 10.841135] ? kernel_init_freeable+0x169/0x24c
[ 10.841757] kernel_init_freeable+0x19a/0x24c
[ 10.842331] ? rest_init+0x12d/0x12d
[ 10.842833] kernel_init+0x8/0xe3
[ 10.843289] ret_from_fork+0x33/0x40
[ 10.843802] ---[ end trace 79d25881531488fa ]---
[ 10.844512] ------------[ cut here ]------------
[ 10.844512] ------------[ cut here ]------------
[ 10.845145] couldn't allocate adapter id
[ 10.845174] WARNING: CPU: 1 PID: 1 at drivers/i2c/i2c-core-base.c:1359 i2c_add_adapter+0xb4/0xc0
[ 10.846900] Modules linked in:
[ 10.847326] CPU: 1 PID: 1 Comm: swapper/0 Tainted: G W 5.0.0-rc5-00236-g5b7fddb #242
[ 10.848568] EIP: i2c_add_adapter+0xb4/0xc0
[ 10.849153] Code: f0 5b 5e 5d c3 90 89 83 68 03 00 00 89 d8 e8 e3 fe ff ff 83 c4 0c 89 c6 5b 89 f0 5e 5d c3 c7 04 24 88 c9 bf bb e8 bd 17 9e ff <0f> 0b eb ce 8d b4 26 00 00 00 00 90 55 89 e5 53 89 c3 83 bb 68 03
[ 10.851586] EAX: 0000001c EBX: cdf21814 ECX: 00000000 EDX: 00000000
[ 10.852406] ESI: ffffffea EDI: cdeed80c EBP: b0031d50 ESP: b0031d3c
[ 10.853252] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068 EFLAGS: 00210292
[ 10.854162] CR0: 80050033 CR2: ffffffff CR3: 0bf37000 CR4: 001406d0
[ 10.855006] Call Trace:
[ 10.855358] i2c_add_numbered_adapter+0x25/0x30
[ 10.855974] unittest_i2c_bus_probe+0xa9/0x110
[ 10.856578] platform_drv_probe+0x4b/0xa0
[ 10.857122] ? driver_sysfs_add+0x6d/0xa0
[ 10.857674] really_probe+0xd5/0x280
[ 10.858162] ? _raw_spin_unlock_irq+0x28/0x50
[ 10.858762] driver_probe_device+0x49/0xe0
[ 10.859407] ? mutex_lock_nested+0x29/0x30
[ 10.859970] __driver_attach+0xc1/0xe0
[ 10.860488] ? driver_probe_device+0xe0/0xe0
[ 10.861065] bus_for_each_dev+0x55/0x90
[ 10.861595] driver_attach+0x1c/0x30
[ 10.862082] ? driver_probe_device+0xe0/0xe0
[ 10.862672] bus_add_driver+0x10a/0x1b0
[ 10.863178] driver_register+0x64/0xb0
[ 10.863697] ? i2c_for_each_dev+0x39/0x50
[ 10.864241] ? __process_new_adapter+0x30/0x30
[ 10.864851] __platform_driver_register+0x37/0x40
[ 10.865495] of_unittest+0x1df1/0x252f
[ 10.866009] ? of_unittest_apply_overlay_check+0x174/0x174
[ 10.866726] do_one_initcall+0x33/0x155
[ 10.867236] ? parse_args+0x18e/0x260
[ 10.867751] ? kernel_init_freeable+0x169/0x24c
[ 10.868372] kernel_init_freeable+0x19a/0x24c
[ 10.869021] ? rest_init+0x12d/0x12d
[ 10.869510] kernel_init+0x8/0xe3
[ 10.869952] ret_from_fork+0x33/0x40
[ 10.870420] ---[ end trace 79d25881531488fb ]---
[ 10.883503] unittest-i2c-bus testcase-data:overlay-node:test-bus:i2c-test-bus: Failed to add I2C adapter
# HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start df12a67b29fc729f827d0c296c110b70d9f80240 8834f5600cf3c8db365e18a3d5cac2c2780c81e5 --
git bisect good 7b1dd18a15c2c4cedaae4ece19ecfa6c14151447 # 11:47 G 11 0 0 0 cxgb3: Convert qpidr to XArray
git bisect good a59f4e57a295e9cd0c6f264a5400a19dd1ac76fc # 12:00 G 11 0 0 3 arm_scmi: Convert tx_idr to XArray
git bisect bad 0551296a8286b608a259be03735c8332582cf695 # 12:07 B 0 3 29 11 i3c: Convert i3c_bus_idr to XArray
git bisect good 8cf6f81003d1cf11f6296493dadc79199c9e29c2 # 13:12 G 10 0 0 0 drm/amdgpu: Convert ctx_handles to XArray
git bisect good beb253e384d92f5c938443de94406b4b91063036 # 13:30 G 11 0 0 0 ac97: Convert ac97_adapter_idr to XArray
git bisect good c9b4da38947252ff7d78c56f41f8f4dab106a26f # 13:43 G 11 0 0 0 drm/i915: Convert vgpus_idr to XArray
git bisect good 7155162c11a40c7eb03625c0f3547281d5ac151d # 14:01 G 11 0 0 0 via: Convert object_idr to XArray
git bisect good 81360c028d0368083dd05db744804c69710c7cbe # 14:20 G 11 0 0 0 ib/bnxt: Remove mention of idr_alloc from comment
git bisect bad 5b7fddbab3a7bdcfea0b8f59ad24f6e1edf7d568 # 14:54 B 0 10 36 11 i2c: Convert i2c_adapter_idr to XArray
# first bad commit: [5b7fddbab3a7bdcfea0b8f59ad24f6e1edf7d568] i2c: Convert i2c_adapter_idr to XArray
git bisect good 81360c028d0368083dd05db744804c69710c7cbe # 14:57 G 30 0 0 0 ib/bnxt: Remove mention of idr_alloc from comment
# extra tests with debug options
git bisect bad 5b7fddbab3a7bdcfea0b8f59ad24f6e1edf7d568 # 15:17 B 0 6 21 0 i2c: Convert i2c_adapter_idr to XArray
# extra tests on HEAD of dax/xarray-conv
git bisect bad df12a67b29fc729f827d0c296c110b70d9f80240 # 15:17 B 0 13 31 0 radix-tree, idr: Remove IDR
# extra tests on tree/branch dax/xarray-conv
git bisect bad 1125c1e181651a41ba5cfd36756cda3e7e464609 # 15:27 B 0 9 34 10 radix-tree, idr: Remove IDR
# extra tests with first bad commit reverted
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
1 year, 11 months
efad4e475c [ 40.308255] Oops: 0000 [#1] PREEMPT SMP PTI
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/torvalds/linux.git master
commit efad4e475c312456edb3c789d0996d12ed744c13
Author: Michal Hocko <mhocko(a)suse.com>
AuthorDate: Fri Feb 1 14:20:34 2019 -0800
Commit: Linus Torvalds <torvalds(a)linux-foundation.org>
CommitDate: Fri Feb 1 15:46:23 2019 -0800
mm, memory_hotplug: is_mem_section_removable do not pass the end of a zone
Patch series "mm, memory_hotplug: fix uninitialized pages fallouts", v2.
Mikhail Zaslonko has posted fixes for the two bugs quite some time ago
[1]. I have pushed back on those fixes because I believed that it is
much better to plug the problem at the initialization time rather than
play whack-a-mole all over the hotplug code and find all the places
which expect the full memory section to be initialized.
We have ended up with commit 2830bf6f05fb ("mm, memory_hotplug:
initialize struct pages for the full memory section") merged and cause a
regression [2][3]. The reason is that there might be memory layouts
when two NUMA nodes share the same memory section so the merged fix is
simply incorrect.
In order to plug this hole we really have to be zone range aware in
those handlers. I have split up the original patch into two. One is
unchanged (patch 2) and I took a different approach for `removable'
crash.
[1] http://lkml.kernel.org/r/20181105150401.97287-2-zaslonko@linux.ibm.com
[2] https://bugzilla.redhat.com/show_bug.cgi?id=1666948
[3] http://lkml.kernel.org/r/20190125163938.GA20411@dhcp22.suse.cz
This patch (of 2):
Mikhail has reported the following VM_BUG_ON triggered when reading sysfs
removable state of a memory block:
page:000003d08300c000 is uninitialized and poisoned
page dumped because: VM_BUG_ON_PAGE(PagePoisoned(p))
Call Trace:
is_mem_section_removable+0xb4/0x190
show_mem_removable+0x9a/0xd8
dev_attr_show+0x34/0x70
sysfs_kf_seq_show+0xc8/0x148
seq_read+0x204/0x480
__vfs_read+0x32/0x178
vfs_read+0x82/0x138
ksys_read+0x5a/0xb0
system_call+0xdc/0x2d8
Last Breaking-Event-Address:
is_mem_section_removable+0xb4/0x190
Kernel panic - not syncing: Fatal exception: panic_on_oops
The reason is that the memory block spans the zone boundary and we are
stumbling over an unitialized struct page. Fix this by enforcing zone
range in is_mem_section_removable so that we never run away from a zone.
Link: http://lkml.kernel.org/r/20190128144506.15603-2-mhocko@kernel.org
Signed-off-by: Michal Hocko <mhocko(a)suse.com>
Reported-by: Mikhail Zaslonko <zaslonko(a)linux.ibm.com>
Debugged-by: Mikhail Zaslonko <zaslonko(a)linux.ibm.com>
Tested-by: Gerald Schaefer <gerald.schaefer(a)de.ibm.com>
Tested-by: Mikhail Gavrilov <mikhail.v.gavrilov(a)gmail.com>
Reviewed-by: Oscar Salvador <osalvador(a)suse.de>
Cc: Pavel Tatashin <pasha.tatashin(a)soleen.com>
Cc: Heiko Carstens <heiko.carstens(a)de.ibm.com>
Cc: Martin Schwidefsky <schwidefsky(a)de.ibm.com>
Signed-off-by: Andrew Morton <akpm(a)linux-foundation.org>
Signed-off-by: Linus Torvalds <torvalds(a)linux-foundation.org>
9bcdeb51bd oom, oom_reaper: do not enqueue same task twice
efad4e475c mm, memory_hotplug: is_mem_section_removable do not pass the end of a zone
f17b5f06cb Linux 5.0-rc4
7a92eb7cc1 Add linux-next specific files for 20190215
+-----------------------------------------------------+------------+------------+----------+---------------+
| | 9bcdeb51bd | efad4e475c | v5.0-rc4 | next-20190215 |
+-----------------------------------------------------+------------+------------+----------+---------------+
| boot_successes | 31 | 2 | 21 | 0 |
| boot_failures | 0 | 11 | 6 | 10 |
| Oops:#[##] | 0 | 11 | | |
| RIP:page_mapping | 0 | 11 | | |
| WARNING:at_kernel/locking/lockdep.c:#lock_downgrade | 0 | 3 | | |
| RIP:lock_downgrade | 0 | 3 | | |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 11 | 0 | 10 |
| BUG:unable_to_handle_kernel | 0 | 6 | | |
| BUG:kernel_in_stage | 0 | 0 | 6 | |
| kernel_BUG_at_include/linux/mm.h | 0 | 0 | 0 | 10 |
| invalid_opcode:#[##] | 0 | 0 | 0 | 10 |
| RIP:is_mem_section_removable | 0 | 0 | 0 | 10 |
+-----------------------------------------------------+------------+------------+----------+---------------+
udevd[311]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv pci:v00001234d00001111sv00001AF4sd00001100bc03sc00i00': No such file or directory
udevd[312]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:QEMU0002:': No such file or directory
udevd[314]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv platform:Fixed MDIO bus': No such file or directory
udevd[315]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0103:': No such file or directory
[ 40.305212] PGD 0 P4D 0
[ 40.308255] Oops: 0000 [#1] PREEMPT SMP PTI
[ 40.313055] CPU: 1 PID: 239 Comm: udevd Not tainted 5.0.0-rc4-00149-gefad4e4 #1
[ 40.321348] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 40.330813] RIP: 0010:page_mapping+0x12/0x80
[ 40.335709] Code: 5d c3 48 89 df e8 0e ad 02 00 85 c0 75 da 89 e8 5b 5d c3 0f 1f 44 00 00 53 48 89 fb 48 8b 43 08 48 8d 50 ff a8 01 48 0f 45 da <48> 8b 53 08 48 8d 42 ff 83 e2 01 48 0f 44 c3 48 83 38 ff 74 2f 48
[ 40.356704] RSP: 0018:ffff88801fa87cd8 EFLAGS: 00010202
[ 40.362714] RAX: ffffffffffffffff RBX: fffffffffffffffe RCX: 000000000000000a
[ 40.370798] RDX: fffffffffffffffe RSI: ffffffff820b9a20 RDI: ffff88801e5c0000
[ 40.378830] RBP: 6db6db6db6db6db7 R08: ffff88801e8bb000 R09: 0000000001b64d13
[ 40.386902] R10: ffff88801fa87cf8 R11: 0000000000000001 R12: ffff88801e640000
[ 40.395033] R13: ffffffff820b9a20 R14: ffff88801f145258 R15: 0000000000000001
[ 40.403138] FS: 00007fb2079817c0(0000) GS:ffff88801dd00000(0000) knlGS:0000000000000000
[ 40.412243] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 40.418846] CR2: 0000000000000006 CR3: 000000001fa82000 CR4: 00000000000006a0
[ 40.426951] Call Trace:
[ 40.429843] __dump_page+0x14/0x2c0
[ 40.433947] is_mem_section_removable+0x24c/0x2c0
[ 40.439327] removable_show+0x87/0xa0
[ 40.443613] dev_attr_show+0x25/0x60
[ 40.447763] sysfs_kf_seq_show+0xba/0x110
[ 40.452363] seq_read+0x196/0x3f0
[ 40.456282] __vfs_read+0x34/0x180
[ 40.460233] ? lock_acquire+0xb6/0x1e0
[ 40.464610] vfs_read+0xa0/0x150
[ 40.468372] ksys_read+0x44/0xb0
[ 40.472129] ? do_syscall_64+0x1f/0x4a0
[ 40.476593] do_syscall_64+0x5e/0x4a0
[ 40.480809] ? trace_hardirqs_off_thunk+0x1a/0x1c
[ 40.486195] entry_SYSCALL_64_after_hwframe+0x49/0xbe
[ 40.491961] RIP: 0033:0x7fb2070680a0
[ 40.496078] Code: 73 01 c3 48 8b 0d a0 0d 2d 00 31 d2 48 29 c2 64 89 11 48 83 c8 ff eb ea 90 90 83 3d 3d 71 2d 00 00 75 10 b8 00 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 31 c3 48 83 ec 08 e8 3e b1 01 00 48 89 04 24
[ 40.517047] RSP: 002b:00007ffeee09f0b8 EFLAGS: 00000246 ORIG_RAX: 0000000000000000
[ 40.525660] RAX: ffffffffffffffda RBX: 0000000000000005 RCX: 00007fb2070680a0
[ 40.533780] RDX: 0000000000001000 RSI: 00007ffeee09f158 RDI: 0000000000000005
[ 40.541853] RBP: 000056092c0f0ac3 R08: 7379732f73656369 R09: 6f6d656d2f6d6574
[ 40.549930] R10: 726f6d656d2f7972 R11: 0000000000000246 R12: 0000000000000000
[ 40.557982] R13: 000056092c0ef7a0 R14: 0000000000000000 R15: 00007ffeee0a4f08
[ 40.566089] Modules linked in:
[ 40.569651] CR2: 0000000000000006
udevd[316]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv platform:i5k_amb': No such file or directory
[ 40.609875] WARNING: CPU: 1 PID: 235 at kernel/locking/lockdep.c:3553 lock_downgrade+0x167/0x1b0
[ 40.626045] Modules linked in:
[ 40.629632] CPU: 1 PID: 235 Comm: udevd Tainted: G D 5.0.0-rc4-00149-gefad4e4 #1
[ 40.639486] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 40.648956] RIP: 0010:lock_downgrade+0x167/0x1b0
[ 40.654231] Code: c9 75 a9 48 c7 c6 c7 08 0c 82 48 c7 c7 58 f9 0a 82 e8 dd e6 fa ff 0f 0b eb 92 48 c7 c7 eb 08 0c 82 48 89 04 24 e8 c9 e6 fa ff <0f> 0b 8b 54 24 0c 48 8b 04 24 e9 2e ff ff ff e8 e5 fb 1e 00 85 c0
[ 40.675231] RSP: 0018:ffff88801fa13de8 EFLAGS: 00010096
[ 40.681229] RAX: 0000000000000017 RBX: ffff88801fa0c000 RCX: 0000000000000000
[ 40.689326] RDX: ffffffff811285f4 RSI: 0000000000000001 RDI: ffffffff81128610
[ 40.697401] RBP: ffff88801f93e0f8 R08: 0000000000000000 R09: 6572206120676e69
[ 40.705498] R10: ffff88801fa13e08 R11: 6b636f6c20646165 R12: 0000000000000246
[ 40.713630] R13: ffffffff812145c1 R14: 0000000000000001 R15: ffff88801f16a1d0
[ 40.721734] FS: 00007fb2079817c0(0000) GS:ffff88801dd00000(0000) knlGS:0000000000000000
[ 40.730878] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 40.737418] CR2: 0000000000fa8000 CR3: 000000001fa0e000 CR4: 00000000000006a0
[ 40.745516] Call Trace:
[ 40.748404] downgrade_write+0x12/0x80
[ 40.752748] __do_munmap+0x3f1/0x430
[ 40.756926] __vm_munmap+0x5d/0x90
[ 40.760854] __x64_sys_munmap+0x25/0x30
[ 40.765257] do_syscall_64+0x5e/0x4a0
[ 40.769566] ? trace_hardirqs_off_thunk+0x1a/0x1c
[ 40.774950] entry_SYSCALL_64_after_hwframe+0x49/0xbe
[ 40.780753] RIP: 0033:0x7fb207071897
[ 40.784895] Code: f0 ff ff 73 01 c3 48 8b 0d a6 75 2c 00 31 d2 48 29 c2 64 89 11 48 83 c8 ff eb ea 90 90 90 90 90 90 90 90 b8 0b 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 79 75 2c 00 31 d2 48 29 c2 64
[ 40.806706] RSP: 002b:00007ffeee09c9e8 EFLAGS: 00000206 ORIG_RAX: 000000000000000b
[ 40.816041] RAX: ffffffffffffffda RBX: 000056092c0e9720 RCX: 00007fb207071897
[ 40.824406] RDX: 0000000000000000 RSI: 0000000000001000 RDI: 00007fb207986000
[ 40.832697] RBP: 0000000000000000 R08: 00007fb2079817c0 R09: 00000000ffffffff
[ 40.840871] R10: 0000000000000022 R11: 0000000000000206 R12: 0000000000000000
[ 40.848911] R13: 0000000000000000 R14: 0000000000000000 R15: 00007ffeee09ca6e
[ 40.857009] irq event stamp: 8258
[ 40.860875] hardirqs last enabled at (8257): [<ffffffff8191b0cb>] preempt_schedule_irq+0x3b/0x90
[ 40.870941] hardirqs last disabled at (8258): [<ffffffff8191a2a9>] __schedule+0x99/0x9e0
[ 40.880106] softirqs last enabled at (8256): [<ffffffff81c003f4>] __do_softirq+0x3f4/0x4c1
[ 40.889506] softirqs last disabled at (8249): [<ffffffff810d108d>] irq_exit+0xdd/0xf0
[ 40.898329] ---[ end trace 0f9a24fdf9c73c71 ]---
# HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start 5bb0643c4108bb06d8766b4bd48d20215deef4af f17b5f06cb92ef2250513a1e154c47b78df07d40 --
git bisect bad 8e26062e1c829f1656e91461f95a7b83bda16ffd # 02:34 B 0 10 25 0 Merge 'tip/ras/core' into devel-hourly-2019021719
git bisect bad 39b94eff9f252bd7b6f2dfe716f6b5dd894ada6f # 02:49 B 0 4 19 0 Merge 'sunxi/sunxi/h3-h5-for-5.1' into devel-hourly-2019021719
git bisect bad cce96fc008ac0e3a5f96280557b02dcb83e70eee # 03:02 B 0 10 25 0 Merge 'linux-review/Gustavo-A-R-Silva/igc-Use-struct_size-helper/20190208-163630' into devel-hourly-2019021719
git bisect bad 544d67be09fcf4054db60b0b2b6fcb7386c095fe # 03:13 B 0 7 22 0 Merge 'linux-review/Noralf-Tr-nnes/drm-drv-Rework-drm_dev_unplug-was-Remove-drm_dev_unplug/20190208-223952' into devel-hourly-2019021719
git bisect good 6dfcfd278beadb8857b94c0382348625943044be # 03:25 G 11 0 0 0 Merge 'linux-review/Qing-Xia/staging-android-ion-fix-sys-heap-pool-s-gfp_flags/20190204-124705' into devel-hourly-2019021719
git bisect bad 238358184e8bfb7c34701fc858f93400ffd8207d # 03:35 B 0 10 25 0 Merge 'linux-review/Colin-King-via-dri-devel/video-fbdev-savage-fix-indentation-issue/20190212-234031' into devel-hourly-2019021719
git bisect good 8833753cc966fbe02ec9dadcd73601f23da7dc2d # 03:44 G 10 0 0 0 Merge 'linux-review/Kamalesh-Babulal/static_keys-txt-Fix-trivial-spelling-mistake/20190204-230620' into devel-hourly-2019021719
git bisect bad efcb5c0b0e4e5bd29320ef5d7ef3e0654c182abf # 03:52 B 0 8 23 0 Merge 'net/master' into devel-hourly-2019021719
git bisect good 9312d5340da6a6018c851d03107ae24ef1a7ccb5 # 04:08 G 11 0 0 0 Merge 'linux-review/Yuri-Benditovich/virtio_net-Introduce-extended-RSC-feature/20190204-114604' into devel-hourly-2019021719
git bisect bad 680905431b9de8c7224b15b76b1826a1481cfeaf # 04:18 B 0 9 24 0 Merge tag 'char-misc-5.0-rc6' of git://git.kernel.org/pub/scm/linux/kernel/git/gregkh/char-misc
git bisect bad b9de6efed25cb713c1648e71302f4af83bd14ee6 # 04:31 B 0 11 26 0 Merge branch 'akpm' (patches from Andrew)
git bisect good 44e56f325b7d63e8a53008956ce7b28e4272a599 # 04:39 G 11 0 0 0 Merge tag 'pci-v5.0-fixes-3' of git://git.kernel.org/pub/scm/linux/kernel/git/helgaas/pci
git bisect good a8e911d13540487942d53137c156bd7707f66e5d # 04:50 G 10 0 0 0 x86_64: increase stack size for KASAN_EXTRA
git bisect good cd984a5be21549273a3f13b52a8b7b84097b32a7 # 05:01 G 11 0 0 0 Merge tag 'xtensa-20190201' of git://github.com/jcmvbkbc/linux-xtensa
git bisect bad db7ddeab3ce5d64c9696e70d61f45ea9909cd196 # 05:10 B 0 7 22 0 lib/test_kmod.c: potential double free in error handling
git bisect bad 24feb47c5fa5b825efb0151f28906dfdad027e61 # 05:20 B 0 4 19 0 mm, memory_hotplug: test_pages_in_a_zone do not pass the end of zone
git bisect good 80409c65e2c6cd1540045ee01fc55e50d95e0983 # 05:50 G 11 0 1 1 mm: migrate: make buffer_migrate_page_norefs() actually succeed
git bisect bad efad4e475c312456edb3c789d0996d12ed744c13 # 06:03 B 0 3 18 0 mm, memory_hotplug: is_mem_section_removable do not pass the end of a zone
git bisect good 9bcdeb51bd7d2ae9fe65ea4d60643d2aeef5bfe3 # 06:25 G 11 0 0 0 oom, oom_reaper: do not enqueue same task twice
# first bad commit: [efad4e475c312456edb3c789d0996d12ed744c13] mm, memory_hotplug: is_mem_section_removable do not pass the end of a zone
git bisect good 9bcdeb51bd7d2ae9fe65ea4d60643d2aeef5bfe3 # 06:29 G 31 0 0 0 oom, oom_reaper: do not enqueue same task twice
# extra tests with debug options
git bisect bad efad4e475c312456edb3c789d0996d12ed744c13 # 06:50 B 0 2 17 0 mm, memory_hotplug: is_mem_section_removable do not pass the end of a zone
# extra tests on HEAD of linux-devel/devel-hourly-2019021719
git bisect bad 5bb0643c4108bb06d8766b4bd48d20215deef4af # 06:55 B 0 12 31 1 0day head guard for 'devel-hourly-2019021719'
# extra tests on tree/branch linus/master
git bisect good f17b5f06cb92ef2250513a1e154c47b78df07d40 # 06:56 G 10 0 0 6 Linux 5.0-rc4
# extra tests with first bad commit reverted
git bisect good cc8685c9af14503b93c6aca3330789384fcb62ac # 07:25 G 10 0 0 0 Revert "mm, memory_hotplug: is_mem_section_removable do not pass the end of a zone"
# extra tests on tree/branch linux-next/master
git bisect bad 7a92eb7cc1dc4c63e3a2fa9ab8e3c1049f199249 # 07:50 B 0 10 25 0 Add linux-next specific files for 20190215
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
1 year, 11 months
b4295f00a3 [ 21.370777 ] EIP: key_alloc+0x1b3/0x375
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/dhowells/linux-fs.git keys-acl
commit b4295f00a3c2b45eb97f110b0c994a5f80faf685
Author: David Howells <dhowells(a)redhat.com>
AuthorDate: Wed Feb 20 15:35:09 2019 +0000
Commit: David Howells <dhowells(a)redhat.com>
CommitDate: Wed Feb 20 15:35:09 2019 +0000
KEYS: Replace uid/gid/perm permissions checking with an ACL
Replace the uid/gid/perm permissions checking on a key with an ACL to allow
the SETATTR and SEARCH permissions to be split. This will also allow a
greater range of subjects to represented.
============
WHY DO THIS?
============
The problem is that SETATTR and SEARCH cover a slew of actions, not all of
which should be grouped together.
For SETATTR, this includes actions that are about controlling access to a
key:
(1) Changing a key's ownership.
(2) Changing a key's security information.
(3) Setting a keyring's restriction.
And actions that are about managing a key's lifetime:
(4) Setting an expiry time.
(5) Revoking a key.
and (proposed) managing a key as part of a cache:
(6) Invalidating a key.
Managing a key's lifetime doesn't really have anything to do with
controlling access to that key.
Expiry time is awkward since it's more about the lifetime of the content
and so, in some ways goes better with WRITE permission. It can, however,
be set unconditionally by a process with an appropriate authorisation token
for instantiating a key, and can also be set by the key type driver when a
key is instantiated, so lumping it with the access-controlling actions is
probably okay.
As for SEARCH permission, that currently covers:
(1) Finding keys in a keyring tree during a search.
(2) Permitting keyrings to be joined.
(3) Invalidation.
But these don't really belong together either, since these actions really
need to be controlled separately.
Finally, there are number of special cases to do with granting the
administrator special rights to invalidate or clear keys that I would like
to handle with the ACL rather than key flags and special checks.
===============
WHAT IS CHANGED
===============
The SETATTR permission is split to create two new permissions:
(1) SET_SECURITY - which allows the key's owner, group and ACL to be
changed and a restriction to be placed on a keyring.
(2) REVOKE - which allows a key to be revoked.
The SEARCH permission is split to create:
(1) SEARCH - which allows a keyring to be search and a key to be found.
(2) JOIN - which allows a keyring to be joined as a session keyring.
(3) INVAL - which allows a key to be invalidated.
The WRITE permission is also split to create:
(1) WRITE - which allows a key's content to be altered and links to be
added, removed and replaced in a keyring.
(2) CLEAR - which allows a keyring to be cleared completely. This is
split out to make it possible to give just this to an administrator.
(3) REVOKE - see above.
Keys acquire ACLs which consist of a series of ACEs, and all that apply are
unioned together. An ACE specifies a subject, such as:
(*) Possessor - permitted to anyone who 'possesses' a key
(*) Owner - permitted to the key owner
(*) Group - permitted to the key group
(*) Everyone - permitted to everyone
Note that 'Other' has been replaced with 'Everyone' on the assumption that
you wouldn't grant a permit to 'Other' that you wouldn't also grant to
everyone else.
Further subjects may be made available by later patches.
The ACE also specifies a permissions mask. The set of permissions is now:
VIEW Can view the key metadata
READ Can read the key content
WRITE Can update/modify the key content
SEARCH Can find the key by searching/requesting
LINK Can make a link to the key
SET_SECURITY Can change owner, ACL, expiry
INVAL Can invalidate
REVOKE Can revoke
JOIN Can join this keyring
CLEAR Can clear this keyring
The KEYCTL_SETPERM function is then deprecated.
The KEYCTL_SET_TIMEOUT function then is permitted if SET_SECURITY is set,
or if the caller has a valid instantiation auth token.
The KEYCTL_INVALIDATE function then requires INVAL.
The KEYCTL_REVOKE function then requires REVOKE.
The KEYCTL_JOIN_SESSION_KEYRING function then requires JOIN to join an
existing keyring.
The JOIN permission is enabled by default for session keyrings and manually
created keyrings only.
======================
BACKWARD COMPATIBILITY
======================
To maintain backward compatibility, KEYCTL_SETPERM will translate the
permissions mask it is given into a new ACL for a key - unless
KEYCTL_SET_ACL has been called on that key, in which case an error will be
returned.
It will convert possessor, owner, group and other permissions into separate
ACEs, if each portion of the mask is non-zero.
SETATTR permission turns on all of INVAL, REVOKE and SET_SECURITY. WRITE
permission turns on WRITE, REVOKE and, if a keyring, CLEAR. JOIN is turned
on if a keyring is being altered.
The KEYCTL_DESCRIBE function translates the ACL back into a permissions
mask to return depending on possessor, owner, group and everyone ACEs.
It will make the following mappings:
(1) INVAL, JOIN -> SEARCH
(2) SET_SECURITY -> SETATTR
(3) REVOKE -> WRITE if SETATTR isn't already set
(4) CLEAR -> WRITE
Note that the value subsequently returned by KEYCTL_DESCRIBE may not match
the value set with KEYCTL_SETATTR.
=======
TESTING
=======
This passes the keyutils testsuite for all but a couple of tests:
(1) tests/keyctl/dh_compute/badargs: The first wrong-key-type test now
returns EOPNOTSUPP rather than ENOKEY as READ permission isn't removed
if the type doesn't have ->read(). You still can't actually read the
key.
(2) tests/keyctl/permitting/valid: The view-other-permissions test doesn't
work as Other has been replaced with Everyone in the ACL.
Signed-off-by: David Howells <dhowells(a)redhat.com>
b5b641deb8 keys: Fix request_key() lack of Link perm check on found key
b4295f00a3 KEYS: Replace uid/gid/perm permissions checking with an ACL
800e3be162 KEYS: Provide KEYCTL_GRANT_PERMISSION
+-------------------------------------------------------+------------+------------+------------+
| | b5b641deb8 | b4295f00a3 | 800e3be162 |
+-------------------------------------------------------+------------+------------+------------+
| boot_successes | 0 | 0 | 0 |
| boot_failures | 61 | 28 | 26 |
| WARNING:at_lib/debugobjects.c:#__debug_object_init | 61 | 28 | 26 |
| EIP:__debug_object_init | 61 | 28 | 26 |
| WARNING:possible_circular_locking_dependency_detected | 12 | | |
| EIP:lock_is_held_type | 1 | | |
| BUG:unable_to_handle_kernel | 0 | 27 | 26 |
| Oops:#[##] | 0 | 27 | 26 |
| EIP:key_alloc | 0 | 27 | 26 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 27 | 26 |
| INFO:task_blocked_for_more_than#seconds | 0 | 1 | |
| EIP:memcmp | 0 | 1 | |
| Kernel_panic-not_syncing:hung_task:blocked_tasks | 0 | 1 | |
+-------------------------------------------------------+------------+------------+------------+
[ 21.368911 ] Oops: 0002 [#1] SMP PTI
[ 21.369436 ] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G W 5.0.0-rc3-00019-gb4295f0 #363
[ 21.370777 ] EIP: key_alloc+0x1b3/0x375
[ 21.371333 ] Code: f0 89 73 68 66 89 83 8c 00 00 00 8b 47 04 66 89 83 8e 00 00 00 8b 45 e0 89 83 84 00 00 00 8b 45 08 89 83 88 00 00 00 8b 45 10 <f0> ff 00 0f 88 7d 31 82 00 8b 45 10 89 43 70 8b 45 18 89 83 bc 00
[ 21.374057 ] EAX: 1f0b0000 EBX: da6bb240 ECX: c21d2e94 EDX: 00000001
[ 21.374983 ] ESI: c21d3020 EDI: c21d2e40 EBP: c0127ed8 ESP: c0127eac
[ 21.375908 ] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068 EFLAGS: 00210246
[ 21.376905 ] CR0: 80050033 CR2: 1f0b0000 CR3: 024f4000 CR4: 001406b0
[ 21.377827 ] Call Trace:
[ 21.378200 ] keyring_alloc+0x27/0x5c
[ 21.378740 ] ? regulatory_init+0x8c/0x8c
[ 21.379323 ] regulatory_init_db+0x4e/0x16b
[ 21.379935 ] ? regulatory_init+0x8c/0x8c
[ 21.380524 ] do_one_initcall+0xd4/0x20d
[ 21.381095 ] ? rcu_read_lock_sched_held+0x4e/0x61
[ 21.381794 ] ? trace_initcall_level+0x7d/0xa7
[ 21.382437 ] kernel_init_freeable+0x183/0x20a
[ 21.383090 ] ? rest_init+0xb4/0xb4
[ 21.383605 ] kernel_init+0x8/0xda
[ 21.384101 ] ret_from_fork+0x1e/0x28
[ 21.384641 ] CR2: 000000001f0b0000
[ 21.385136 ] ---[ end trace 9c8a1029b8bac6ca ]---
[ 21.385826 ] EIP: key_alloc+0x1b3/0x375
[ 21.386384 ] Code: f0 89 73 68 66 89 83 8c 00 00 00 8b 47 04 66 89 83 8e 00 00 00 8b 45 e0 89 83 84 00 00 00 8b 45 08 89 83 88 00 00 00 8b 45 10 <f0> ff 00 0f 88 7d 31 82 00 8b 45 10 89 43 70 8b 45 18 89 83 bc 00
[ 21.389125 ] EAX: 1f0b0000 EBX: da6bb240 ECX: c21d2e94 EDX: 00000001
[ 21.390064 ] ESI: c21d3020 EDI: c21d2e40 EBP: c0127ed8 ESP: c24f91bc
[ 21.391006 ] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068 EFLAGS: 00210246
[ 21.392012 ] CR0: 80050033 CR2: 1f0b0000 CR3: 024f4000 CR4: 001406b0
[ 21.392937 ] Kernel panic - not syncing: Fatal exception
[ 21.393776 ] Kernel Offset: disabled
# HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start 776e6122444cc6f5bf650ca260d2826e10418f75 bfeffd155283772bbe78c6a05dec7c0128ee500c --
git bisect good 6d9c7ee1fe5d44dd6c8b7fee6803b2802c2e5489 # 04:22 G 11 0 0 0 Merge 'clk/clk-meson' into devel-catchup-201902210158
git bisect good 5633945a53db080aa0355faa038552bd3cd93739 # 05:00 G 11 0 0 0 Merge 'snitzer/dm-5.1' into devel-catchup-201902210158
git bisect good 332e87db42948908083d60e13382f80502bacb86 # 05:09 G 11 0 0 0 Merge 'omap/for-next' into devel-catchup-201902210158
git bisect bad 94aabc24301f5ba0c6eb0bb9a7be0987a7709e3e # 05:27 B 0 11 36 11 Merge 'dhowells-fs/keys-acl' into devel-catchup-201902210158
git bisect good fe6871fdaf3890cc4b1478ddac7e8db1c141ed03 # 05:41 G 11 0 0 0 Merge 'dax/xarray' into devel-catchup-201902210158
git bisect good fb7b3faf7ed487a35e27e2215b73e1ae8cbfbf5a # 06:32 G 10 0 10 21 keys: Garbage collect keys for which the domain has been removed
git bisect good eaa72b5b17c4995e7694b41b940a82d643c7efe2 # 07:19 G 10 0 10 19 keys: Make __key_link_begin() handle lockdep nesting
git bisect good b5b641deb8b113a27104373decd74ed4e8e3301f # 08:01 G 11 0 11 22 keys: Fix request_key() lack of Link perm check on found key
git bisect bad 800e3be162773c0361bb5d982b97a287c05221f8 # 08:20 B 0 10 34 10 KEYS: Provide KEYCTL_GRANT_PERMISSION
git bisect bad b4295f00a3c2b45eb97f110b0c994a5f80faf685 # 08:26 B 0 3 18 1 KEYS: Replace uid/gid/perm permissions checking with an ACL
# first bad commit: [b4295f00a3c2b45eb97f110b0c994a5f80faf685] KEYS: Replace uid/gid/perm permissions checking with an ACL
git bisect good b5b641deb8b113a27104373decd74ed4e8e3301f # 09:06 G 33 0 33 58 keys: Fix request_key() lack of Link perm check on found key
# extra tests with debug options
git bisect bad b4295f00a3c2b45eb97f110b0c994a5f80faf685 # 10:01 B 0 3 17 0 KEYS: Replace uid/gid/perm permissions checking with an ACL
# extra tests on HEAD of linux-devel/devel-catchup-201902210158
git bisect bad 776e6122444cc6f5bf650ca260d2826e10418f75 # 10:07 B 0 15 32 0 0day head guard for 'devel-catchup-201902210158'
# extra tests on tree/branch dhowells-fs/keys-acl
git bisect bad 800e3be162773c0361bb5d982b97a287c05221f8 # 10:12 B 0 26 40 0 KEYS: Provide KEYCTL_GRANT_PERMISSION
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
1 year, 11 months
5b7fddbab3 [ 3.925592] Mem-Info:
by kernel test robot
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
git://git.infradead.org/users/willy/linux-dax.git xarray-conv
commit 5b7fddbab3a7bdcfea0b8f59ad24f6e1edf7d568
Author: Matthew Wilcox <willy(a)infradead.org>
AuthorDate: Mon Feb 18 13:39:27 2019 -0500
Commit: Matthew Wilcox <willy(a)infradead.org>
CommitDate: Tue Feb 19 08:07:20 2019 -0500
i2c: Convert i2c_adapter_idr to XArray
Signed-off-by: Matthew Wilcox <willy(a)infradead.org>
81360c028d ib/bnxt: Remove mention of idr_alloc from comment
5b7fddbab3 i2c: Convert i2c_adapter_idr to XArray
df12a67b29 radix-tree, idr: Remove IDR
+---------------------------------------------------------+------------+------------+------------+
| | 81360c028d | 5b7fddbab3 | df12a67b29 |
+---------------------------------------------------------+------------+------------+------------+
| boot_successes | 0 | 0 | 0 |
| boot_failures | 112 | 44 | 13 |
| Mem-Info | 112 | 44 | 13 |
| WARNING:at_lib/debugobjects.c:#__debug_object_init | 112 | 44 | 13 |
| EIP:__debug_object_init | 112 | 44 | 13 |
| WARNING:at_lib/xarray.c:#__xa_alloc | 0 | 44 | 13 |
| EIP:__xa_alloc | 0 | 44 | 13 |
| WARNING:at_drivers/i2c/i2c-core-base.c:#i2c_add_adapter | 0 | 44 | 13 |
| EIP:i2c_add_adapter | 0 | 44 | 13 |
+---------------------------------------------------------+------------+------------+------------+
[ 3.908269] test_overflow: kmalloc_node detected saturation
[ 3.909111] test_overflow: kzalloc detected saturation
[ 3.909885] test_overflow: kzalloc_node detected saturation
[ 3.910660] swapper: vmalloc: allocation failure: 4294967295 bytes, mode:0x6000c0(GFP_KERNEL), nodemask=(null)
[ 3.911880] CPU: 0 PID: 1 Comm: swapper Not tainted 5.0.0-rc5-00236-g5b7fddb #353
[ 3.912783] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 3.913797] Call Trace:
[ 3.914109] ? dump_stack+0x16/0x18
[ 3.914543] ? warn_alloc+0x7f/0xe7
[ 3.914999] ? __vmalloc_node_range+0x198/0x1a5
[ 3.915601] ? __vmalloc_node+0x3d/0x44
[ 3.916146] ? test_overflow_allocation+0x335/0x928
[ 3.916825] ? __vmalloc_node_flags_caller+0x1f/0x24
[ 3.917507] ? test_overflow_allocation+0x335/0x928
[ 3.918191] ? kvmalloc_node+0x49/0x67
[ 3.918779] ? test_overflow_allocation+0x335/0x928
[ 3.919433] ? test_overflow_allocation+0x335/0x928
[ 3.920086] ? vprintk_emit+0x1b1/0x1bc
[ 3.920598] ? vprintk_default+0x12/0x14
[ 3.921145] ? test_module_init+0x17/0x3f
[ 3.921700] ? test_overflow_allocation+0x928/0x928
[ 3.922380] ? do_one_initcall+0x5b/0x10f
[ 3.922944] ? kernel_init_freeable+0xbc/0x15b
[ 3.923550] ? kernel_init_freeable+0xd4/0x15b
[ 3.924147] ? rest_init+0xf4/0xf4
[ 3.924603] ? kernel_init+0x8/0xcb
[ 3.925076] ? ret_from_fork+0x2e/0x38
[ 3.925592] Mem-Info:
[ 3.925910] active_anon:0 inactive_anon:0 isolated_anon:0
[ 3.925910] active_file:0 inactive_file:0 isolated_file:0
[ 3.925910] unevictable:20010 dirty:0 writeback:0 unstable:0
[ 3.925910] slab_reclaimable:1205 slab_unreclaimable:535
[ 3.925910] mapped:0 shmem:0 pagetables:0 bounce:0
[ 3.925910] free:100855 free_pcp:205 free_cma:0
[ 3.929942] Node 0 active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:80040kB isolated(anon):0kB isolated(file):0kB mapped:0kB dirty:0kB writeback:0kB shmem:0kB writeback_tmp:0kB unstable:0kB all_unreclaimable? no
[ 3.932582] Normal free:403420kB min:2732kB low:3412kB high:4092kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:80040kB writepending:0kB present:523768kB managed:491804kB mlocked:0kB kernel_stack:256kB pagetables:0kB bounce:0kB free_pcp:820kB local_pcp:820kB free_cma:0kB
[ 3.935895] lowmem_reserve[]: 0 0 0
[ 3.936327] Normal: 3*4kB (ME) 4*8kB (M) 1*16kB (M) 1*32kB (U) 2*64kB (ME) 2*128kB (UE) 4*256kB (M) 3*512kB (UM) 3*1024kB (ME) 6*2048kB (UME) 94*4096kB (M) = 403420kB
[ 3.938132] 20013 total pagecache pages
[ 3.938671] 0 pages in swap cache
[ 3.939108] Swap cache stats: add 0, delete 0, find 0/0
[ 3.939777] Free swap = 0kB
[ 3.940159] Total swap = 0kB
[ 3.940535] 130942 pages RAM
[ 3.940925] 0 pages HighMem/MovableOnly
[ 3.941450] 7991 pages reserved
[ 3.941876] 0 pages cma reserved
[ 3.942308] test_overflow: kvmalloc detected saturation
[ 3.943084] swapper: vmalloc: allocation failure: 4294967295 bytes, mode:0x6000c0(GFP_KERNEL), nodemask=(null)
[ 3.944395] CPU: 0 PID: 1 Comm: swapper Not tainted 5.0.0-rc5-00236-g5b7fddb #353
[ 3.945405] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 3.946539] Call Trace:
[ 3.946895] ? dump_stack+0x16/0x18
[ 3.947380] ? warn_alloc+0x7f/0xe7
[ 3.947854] ? __vmalloc_node_range+0x198/0x1a5
[ 3.948454] ? __vmalloc_node+0x3d/0x44
[ 3.949051] ? test_overflow_allocation+0x3dc/0x928
[ 3.949703] ? __vmalloc_node_flags_caller+0x1f/0x24
[ 3.950371] ? test_overflow_allocation+0x3dc/0x928
[ 3.951030] ? kvmalloc_node+0x49/0x67
[ 3.951535] ? test_overflow_allocation+0x3dc/0x928
[ 3.952193] ? test_overflow_allocation+0x3dc/0x928
[ 3.952848] ? vprintk_emit+0x1b1/0x1bc
[ 3.953362] ? vprintk_default+0x12/0x14
[ 3.953888] ? test_module_init+0x17/0x3f
[ 3.954407] ? test_overflow_allocation+0x928/0x928
[ 3.955034] ? do_one_initcall+0x5b/0x10f
[ 3.955547] ? kernel_init_freeable+0xbc/0x15b
[ 3.956128] ? kernel_init_freeable+0xd4/0x15b
[ 3.956675] ? rest_init+0xf4/0xf4
[ 3.957101] ? kernel_init+0x8/0xcb
[ 3.957532] ? ret_from_fork+0x2e/0x38
[ 3.957999] test_overflow: kvmalloc_node detected saturation
[ 3.958844] swapper: vmalloc: allocation failure: 4294967295 bytes, mode:0x6080c0(GFP_KERNEL|__GFP_ZERO), nodemask=(null)
[ 3.960175] CPU: 0 PID: 1 Comm: swapper Not tainted 5.0.0-rc5-00236-g5b7fddb #353
[ 3.961671] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 3.962668] Call Trace:
[ 3.962976] ? dump_stack+0x16/0x18
[ 3.963400] ? warn_alloc+0x7f/0xe7
[ 3.963829] ? __vmalloc_node_range+0x198/0x1a5
[ 3.964401] ? __vmalloc_node+0x3d/0x44
[ 3.964889] ? test_overflow_allocation+0x483/0x928
[ 3.965498] ? __vmalloc_node_flags_caller+0x1f/0x24
[ 3.966132] ? test_overflow_allocation+0x483/0x928
[ 3.966717] ? kvmalloc_node+0x49/0x67
[ 3.967208] ? test_overflow_allocation+0x483/0x928
[ 3.967843] ? test_overflow_allocation+0x483/0x928
[ 3.968473] ? vprintk_emit+0x1b1/0x1bc
[ 3.969067] ? test_module_init+0x17/0x3f
[ 3.969591] ? test_overflow_allocation+0x928/0x928
[ 3.970245] ? do_one_initcall+0x5b/0x10f
[ 3.970785] ? kernel_init_freeable+0xbc/0x15b
[ 3.971384] ? kernel_init_freeable+0xd4/0x15b
[ 3.971982] ? rest_init+0xf4/0xf4
[ 3.972436] ? kernel_init+0x8/0xcb
[ 3.972908] ? ret_from_fork+0x2e/0x38
[ 3.973393] test_overflow: kvzalloc detected saturation
[ 3.974148] swapper: vmalloc: allocation failure: 4294967295 bytes, mode:0x6080c0(GFP_KERNEL|__GFP_ZERO), nodemask=(null)
[ 3.975534] CPU: 0 PID: 1 Comm: swapper Not tainted 5.0.0-rc5-00236-g5b7fddb #353
[ 3.976513] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 3.977614] Call Trace:
[ 3.977957] ? dump_stack+0x16/0x18
[ 3.978423] ? warn_alloc+0x7f/0xe7
[ 3.978959] ? __vmalloc_node_range+0x198/0x1a5
[ 3.979545] ? __vmalloc_node+0x3d/0x44
[ 3.980030] ? test_overflow_allocation+0x52a/0x928
[ 3.980642] ? __vmalloc_node_flags_caller+0x1f/0x24
[ 3.981258] ? test_overflow_allocation+0x52a/0x928
[ 3.981863] ? kvmalloc_node+0x49/0x67
[ 3.982336] ? test_overflow_allocation+0x52a/0x928
[ 3.982921] ? test_overflow_allocation+0x52a/0x928
[ 3.983501] ? vprintk_emit+0x1b1/0x1bc
[ 3.983966] ? test_module_init+0x17/0x3f
[ 3.984473] ? test_overflow_allocation+0x928/0x928
[ 3.985097] ? do_one_initcall+0x5b/0x10f
[ 3.985594] ? kernel_init_freeable+0xbc/0x15b
[ 3.986165] ? kernel_init_freeable+0xd4/0x15b
[ 3.986694] ? rest_init+0xf4/0xf4
[ 3.987108] ? kernel_init+0x8/0xcb
[ 3.987527] ? ret_from_fork+0x2e/0x38
[ 3.987982] test_overflow: kvzalloc_node detected saturation
[ 3.998712] swapper: vmalloc: allocation failure: 4294967295 bytes, mode:0x6000c0(GFP_KERNEL), nodemask=(null)
[ 4.000045] CPU: 0 PID: 1 Comm: swapper Not tainted 5.0.0-rc5-00236-g5b7fddb #353
[ 4.001034] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 4.002133] Call Trace:
[ 4.002470] ? dump_stack+0x16/0x18
[ 4.002973] ? warn_alloc+0x7f/0xe7
[ 4.003461] ? __vmalloc_node_range+0x198/0x1a5
[ 4.004103] ? __vmalloc_node+0x3d/0x44
[ 4.004620] ? test_overflow_allocation+0x5b9/0x928
[ 4.005275] ? vmalloc+0x24/0x26
[ 4.005713] ? test_overflow_allocation+0x5b9/0x928
[ 4.006326] ? test_overflow_allocation+0x5b9/0x928
[ 4.006932] ? vprintk_emit+0x1b1/0x1bc
[ 4.007410] ? test_module_init+0x17/0x3f
[ 4.007920] ? test_overflow_allocation+0x928/0x928
[ 4.008553] ? do_one_initcall+0x5b/0x10f
[ 4.009168] ? kernel_init_freeable+0xbc/0x15b
[ 4.009741] ? kernel_init_freeable+0xd4/0x15b
[ 4.010324] ? rest_init+0xf4/0xf4
[ 4.010748] ? kernel_init+0x8/0xcb
[ 4.011190] ? ret_from_fork+0x2e/0x38
[ 4.011655] test_overflow: vmalloc detected saturation
[ 4.012312] swapper: vmalloc: allocation failure: 4294967295 bytes, mode:0x6000c0(GFP_KERNEL), nodemask=(null)
[ 4.013533] CPU: 0 PID: 1 Comm: swapper Not tainted 5.0.0-rc5-00236-g5b7fddb #353
[ 4.014449] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 4.015481] Call Trace:
[ 4.015800] ? dump_stack+0x16/0x18
[ 4.016237] ? warn_alloc+0x7f/0xe7
[ 4.016672] ? __vmalloc_node_range+0x198/0x1a5
[ 4.017236] ? __vmalloc_node+0x3d/0x44
[ 4.017712] ? test_overflow_allocation+0x651/0x928
[ 4.018319] ? vmalloc_node+0x23/0x25
[ 4.031059] ? test_overflow_allocation+0x651/0x928
[ 4.031664] ? test_overflow_allocation+0x651/0x928
[ 4.032276] ? vprintk_emit+0x1b1/0x1bc
[ 4.032754] ? test_module_init+0x17/0x3f
[ 4.033279] ? test_overflow_allocation+0x928/0x928
[ 4.033912] ? do_one_initcall+0x5b/0x10f
[ 4.034430] ? kernel_init_freeable+0xbc/0x15b
[ 4.035008] ? kernel_init_freeable+0xd4/0x15b
[ 4.035575] ? rest_init+0xf4/0xf4
[ 4.036010] ? kernel_init+0x8/0xcb
[ 4.036446] ? ret_from_fork+0x2e/0x38
[ 4.036920] test_overflow: vmalloc_node detected saturation
[ 4.037647] swapper: vmalloc: allocation failure: 4294967295 bytes, mode:0x6080c0(GFP_KERNEL|__GFP_ZERO), nodemask=(null)
[ 4.039062] CPU: 0 PID: 1 Comm: swapper Not tainted 5.0.0-rc5-00236-g5b7fddb #353
[ 4.039984] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 4.041061] Call Trace:
[ 4.041386] ? dump_stack+0x16/0x18
[ 4.041850] ? warn_alloc+0x7f/0xe7
[ 4.042287] ? __vmalloc_node_range+0x198/0x1a5
[ 4.042856] ? __vmalloc_node+0x3d/0x44
[ 4.043335] ? test_overflow_allocation+0x6e0/0x928
[ 4.043946] ? vzalloc+0x24/0x26
[ 4.044351] ? test_overflow_allocation+0x6e0/0x928
[ 4.044972] ? test_overflow_allocation+0x6e0/0x928
[ 4.045598] ? test_module_init+0x17/0x3f
[ 4.046121] ? test_overflow_allocation+0x928/0x928
[ 4.046752] ? do_one_initcall+0x5b/0x10f
[ 4.047258] ? kernel_init_freeable+0xbc/0x15b
[ 4.047815] ? kernel_init_freeable+0xd4/0x15b
[ 4.048366] ? rest_init+0xf4/0xf4
[ 4.061060] ? kernel_init+0x8/0xcb
[ 4.061500] ? ret_from_fork+0x2e/0x38
[ 4.061974] test_overflow: vzalloc detected saturation
[ 4.062643] swapper: vmalloc: allocation failure: 4294967295 bytes, mode:0x6080c0(GFP_KERNEL|__GFP_ZERO), nodemask=(null)
[ 4.064044] CPU: 0 PID: 1 Comm: swapper Not tainted 5.0.0-rc5-00236-g5b7fddb #353
[ 4.064997] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 4.066023] Call Trace:
[ 4.066336] ? dump_stack+0x16/0x18
[ 4.066773] ? warn_alloc+0x7f/0xe7
[ 4.067216] ? __vmalloc_node_range+0x198/0x1a5
[ 4.067778] ? __vmalloc_node+0x3d/0x44
[ 4.068263] ? test_overflow_allocation+0x778/0x928
[ 4.081154] ? vzalloc_node+0x23/0x25
[ 4.081618] ? test_overflow_allocation+0x778/0x928
[ 4.082264] ? test_overflow_allocation+0x778/0x928
[ 4.082901] ? test_module_init+0x17/0x3f
[ 4.083416] ? test_overflow_allocation+0x928/0x928
[ 4.084029] ? do_one_initcall+0x5b/0x10f
# HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start df12a67b29fc729f827d0c296c110b70d9f80240 8834f5600cf3c8db365e18a3d5cac2c2780c81e5 --
git bisect good 7b1dd18a15c2c4cedaae4ece19ecfa6c14151447 # 03:01 G 11 0 11 22 cxgb3: Convert qpidr to XArray
git bisect good a59f4e57a295e9cd0c6f264a5400a19dd1ac76fc # 03:31 G 10 0 10 10 arm_scmi: Convert tx_idr to XArray
git bisect bad 0551296a8286b608a259be03735c8332582cf695 # 03:58 B 0 1 24 9 i3c: Convert i3c_bus_idr to XArray
git bisect good 8cf6f81003d1cf11f6296493dadc79199c9e29c2 # 04:43 G 10 0 10 21 drm/amdgpu: Convert ctx_handles to XArray
git bisect good beb253e384d92f5c938443de94406b4b91063036 # 05:05 G 11 0 11 33 ac97: Convert ac97_adapter_idr to XArray
git bisect good c9b4da38947252ff7d78c56f41f8f4dab106a26f # 05:25 G 11 0 11 30 drm/i915: Convert vgpus_idr to XArray
git bisect good 7155162c11a40c7eb03625c0f3547281d5ac151d # 05:33 G 11 0 11 29 via: Convert object_idr to XArray
git bisect good 81360c028d0368083dd05db744804c69710c7cbe # 05:52 G 10 0 10 29 ib/bnxt: Remove mention of idr_alloc from comment
git bisect bad 5b7fddbab3a7bdcfea0b8f59ad24f6e1edf7d568 # 06:05 B 0 5 49 18 i2c: Convert i2c_adapter_idr to XArray
# first bad commit: [5b7fddbab3a7bdcfea0b8f59ad24f6e1edf7d568] i2c: Convert i2c_adapter_idr to XArray
git bisect good 81360c028d0368083dd05db744804c69710c7cbe # 06:16 G 33 0 33 66 ib/bnxt: Remove mention of idr_alloc from comment
# extra tests with debug options
git bisect bad 5b7fddbab3a7bdcfea0b8f59ad24f6e1edf7d568 # 06:23 B 0 8 22 0 i2c: Convert i2c_adapter_idr to XArray
# extra tests on HEAD of dax/xarray-conv
git bisect bad df12a67b29fc729f827d0c296c110b70d9f80240 # 06:23 B 0 13 30 0 radix-tree, idr: Remove IDR
# extra tests on tree/branch dax/xarray-conv
git bisect bad df12a67b29fc729f827d0c296c110b70d9f80240 # 06:24 B 0 13 30 0 radix-tree, idr: Remove IDR
# extra tests with first bad commit reverted
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
1 year, 11 months