c3c0a0c6cf [ 71.844514] WARNING: CPU: 0 PID: 64 at mm/page_counter.c:62 page_counter_cancel
by kernel test robot
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
https://github.com/rgushchin/linux.git bpf_memcg_acc
commit c3c0a0c6cf58efdb76d6c527712f436602064688
Author: Roman Gushchin <guro(a)fb.com>
AuthorDate: Thu May 16 19:30:33 2019 -0700
Commit: Roman Gushchin <guro(a)fb.com>
CommitDate: Thu May 16 20:06:13 2019 -0700
bpf: memcg-based memory accounting for bpf maps
Signed-off-by: Roman Gushchin <guro(a)fb.com>
1ecf7718b8 bpf: track memcg for map memory allocations
c3c0a0c6cf bpf: memcg-based memory accounting for bpf maps
07e5faf187 bpf: memcg-based memory accounting for dynamic maps memory charges
+-----------------------------------------------------------+------------+------------+------------+
| | 1ecf7718b8 | c3c0a0c6cf | 07e5faf187 |
+-----------------------------------------------------------+------------+------------+------------+
| boot_successes | 58 | 5 | 11 |
| boot_failures | 1 | 8 | 10 |
| WARNING:at_kernel/locking/lockdep.c:#lockdep_register_key | 1 | | |
| EIP:lockdep_register_key | 1 | | |
| WARNING:at_mm/page_counter.c:#page_counter_cancel | 0 | 6 | 10 |
| EIP:page_counter_cancel | 0 | 6 | 10 |
| Mem-Info | 0 | 2 | |
+-----------------------------------------------------------+------------+------------+------------+
If you fix the issue, kindly add following tag
Reported-by: kernel test robot <lkp(a)intel.com>
[ 70.612864] random: get_random_u32 called from arch_align_stack+0x28/0x3f with crng_init=1
[ 71.707450] _warn_unseeded_randomness: 65 callbacks suppressed
[ 71.707461] random: get_random_u32 called from arch_rnd+0x27/0x38 with crng_init=1
[ 71.710741] random: get_random_u32 called from load_elf_binary+0x45b/0x1457 with crng_init=1
[ 71.712854] random: get_random_u32 called from arch_align_stack+0x28/0x3f with crng_init=1
[ 71.844514] WARNING: CPU: 0 PID: 64 at mm/page_counter.c:62 page_counter_cancel+0x37/0x59
[ 71.846793] Modules linked in:
[ 71.846850] CPU: 0 PID: 64 Comm: kworker/0:1 Not tainted 5.1.0-10752-gc3c0a0c #58
[ 71.846850] Workqueue: events bpf_map_free_deferred
[ 71.846850] EIP: page_counter_cancel+0x37/0x59
[ 71.846850] Code: cb 29 d3 89 da 89 de e8 31 ff ff ff c1 ee 1f 6a 00 31 c9 89 f2 b8 90 48 fe 57 e8 ce 89 f2 ff 85 db 5a 79 0a ff 05 88 4c 0f 58 <0f> 0b eb 06 ff 05 84 4c 0f 58 6a 00 89 f2 b8 78 48 fe 57 31 c9 e8
[ 71.846850] EAX: 57fe4890 EBX: ffffffd2 ECX: 00000000 EDX: 00000000
[ 71.846850] ESI: 00000001 EDI: 40087800 EBP: 40179e90 ESP: 40179e88
[ 71.846850] DS: 007b ES: 007b FS: 0000 GS: 0000 SS: 0068 EFLAGS: 00010002
[ 71.846850] CR0: 80050033 CR2: 37e8604b CR3: 1c156000 CR4: 000006d0
[ 71.846850] DR0: 361f5000 DR1: 00000000 DR2: 00000000 DR3: 00000000
[ 71.846850] DR6: ffff0ff0 DR7: 00000600
[ 71.846850] Call Trace:
[ 71.846850] ? page_counter_uncharge+0x16/0x1f
[ 71.846850] ? drain_stock+0x28/0x160
[ 71.846850] ? refill_stock+0x4f/0x7e
[ 71.846850] ? mem_cgroup_uncharge_bpf+0x106/0x10e
[ 71.846850] ? bpf_map_charge_release+0x22/0x153
[ 71.846850] ? bpf_map_free_deferred+0x31/0x33
[ 71.846850] ? process_one_work+0x3c5/0x6c1
[ 71.846850] ? worker_thread+0x3ca/0x545
[ 71.846850] ? kthread+0x131/0x146
[ 71.846850] ? rescuer_thread+0x39c/0x39c
[ 71.846850] ? kthread_create_on_node+0x1a/0x1a
[ 71.846850] ? ret_from_fork+0x19/0x24
[ 71.846850] irq event stamp: 10950
[ 71.846850] hardirqs last enabled at (10949): [<56bf2210>] mem_cgroup_uncharge_bpf+0xf8/0x10e
[ 71.846850] hardirqs last disabled at (10950): [<56be9a8b>] refill_stock+0x12/0x7e
[ 71.846850] softirqs last enabled at (8634): [<56ab8469>] srcu_drive_gp+0xc2/0x11e
[ 71.846850] softirqs last disabled at (8630): [<56ab8469>] srcu_drive_gp+0xc2/0x11e
[ 71.846850] ---[ end trace ed693796e9c0266d ]---
Sending all processes the KILL signal...
# HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start c3c0a0c6cf58efdb76d6c527712f436602064688 35c99ffa20edd3c24be352d28a63cd3a23121282 --
git bisect good cc71b1333207372c4cb4b6ae88993a819aca1c9e # 10:02 G 15 0 3 3 bpf: rework memlock-based memory accounting for maps
git bisect good 045085645bdfb6feb8b68ee4e1d6a1b09b9bdae8 # 10:22 G 15 0 0 0 mm: memcontrol: introduce MEMCG_BPF and memory.stat::bpf
git bisect good d5390f1083753d12033190a65ad768e97b56295e # 23:12 G 16 0 1 17 mm: memcontrol: bpf charging API
git bisect good 1ecf7718b8a98abb9cdd2da7026f35c64edf8d49 # 23:30 G 16 0 3 3 bpf: track memcg for map memory allocations
# first bad commit: [c3c0a0c6cf58efdb76d6c527712f436602064688] bpf: memcg-based memory accounting for bpf maps
git bisect good 1ecf7718b8a98abb9cdd2da7026f35c64edf8d49 # 23:34 G 46 0 2 5 bpf: track memcg for map memory allocations
# extra tests with debug options
git bisect bad c3c0a0c6cf58efdb76d6c527712f436602064688 # 23:56 B 4 11 2 2 bpf: memcg-based memory accounting for bpf maps
# extra tests on HEAD of rgushchin/bpf_memcg_acc
git bisect bad c3c0a0c6cf58efdb76d6c527712f436602064688 # 23:56 B 5 6 0 3 bpf: memcg-based memory accounting for bpf maps
# extra tests on tree/branch rgushchin/bpf_memcg_acc
git bisect bad 07e5faf187cb317033b5ba5eb36dfe04f001022c # 00:33 B 5 2 1 1 bpf: memcg-based memory accounting for dynamic maps memory charges
# extra tests with first bad commit reverted
git bisect good 241c59c86d874b0d742b98d02047c4856d8f094b # 01:03 G 17 0 5 5 Revert "bpf: memcg-based memory accounting for bpf maps"
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
1 year, 5 months
[IMA] d4f7b87e55: WARNING:suspicious_RCU_usage
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: d4f7b87e55fb41849e9da958718134d2a34d3355 ("[PATCH V3 6/6] IMA: Allow profiles to define the desired IMA template")
url: https://github.com/0day-ci/linux/commits/Matthew-Garrett/IMA-Support-aski...
base: https://git.kernel.org/cgit/linux/kernel/git/zohar/linux-integrity.git next-integrity
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-i386 -enable-kvm -cpu SandyBridge -smp 2 -m 2G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+------------------------------------------------------------------------------------+------------+------------+
| | b0b4536f16 | d4f7b87e55 |
+------------------------------------------------------------------------------------+------------+------------+
| boot_successes | 6 | 0 |
| boot_failures | 0 | 4 |
| WARNING:suspicious_RCU_usage | 0 | 4 |
| include/linux/rcupdate.h:#Illegal_context_switch_in_RCU_read-side_critical_section | 0 | 4 |
| BUG:sleeping_function_called_from_invalid_context_at_mm/slab.h | 0 | 4 |
+------------------------------------------------------------------------------------+------------+------------+
If you fix the issue, kindly add following tag
Reported-by: kernel test robot <lkp(a)intel.com>
[ 361.266989] WARNING: suspicious RCU usage
[ 361.274571] 5.1.0-rc2-00031-gd4f7b87 #1 Tainted: G T
[ 361.285514] -----------------------------
[ 361.293501] include/linux/rcupdate.h:268 Illegal context switch in RCU read-side critical section!
[ 361.312409]
[ 361.312409] other info that might help us debug this:
[ 361.312409]
[ 361.322783]
[ 361.322783] rcu_scheduler_active = 2, debug_locks = 1
[ 361.332527] 1 lock held by swapper/1:
[ 361.338036] #0: d45d28b0 (rcu_read_lock){....}, at: lookup_template_desc+0x5/0x86
[ 361.349181]
[ 361.349181] stack backtrace:
[ 361.354855] CPU: 0 PID: 1 Comm: swapper Tainted: G T 5.1.0-rc2-00031-gd4f7b87 #1
[ 361.366652] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 361.377484] Call Trace:
[ 361.382585] dump_stack+0x16/0x18
[ 361.388906] lockdep_rcu_suspicious+0x8f/0x9a
[ 361.395500] rcu_preempt_sleep_check+0x3e/0x40
[ 361.402590] ___might_sleep+0x16/0x19b
[ 361.408184] __might_sleep+0x69/0x70
[ 361.414046] ? __fs_reclaim_release+0x1c/0x1e
[ 361.421146] slab_pre_alloc_hook+0x34/0x52
[ 361.429702] __kmalloc+0x4e/0xce
[ 361.433419] ? template_desc_init_fields+0x135/0x16e
[ 361.440154] template_desc_init_fields+0x135/0x16e
[ 361.445803] ? kvm_sched_clock_read+0x9/0x18
[ 361.450404] ? lookup_template_desc+0x5/0x86
[ 361.455865] template_desc_init_fields+0x1d/0x20
[ 361.461323] lookup_template_desc+0x5d/0x86
[ 361.468040] ima_template_desc_current+0x20/0x32
[ 361.473911] hash_setup+0x18/0x109
[ 361.477425] ? hash_setup+0x109/0x109
[ 361.482667] init_ima+0x22/0xde
[ 361.487190] ? hash_setup+0x109/0x109
[ 361.491590] do_one_initcall+0x12e/0x312
[ 361.496145] ? rcu_read_lock_sched_held+0x47/0x4f
[ 361.500046] ? trace_initcall_level+0x5a/0x83
[ 361.503716] kernel_init_freeable+0x156/0x227
[ 361.507287] ? rest_init+0x96/0x96
[ 361.510157] kernel_init+0xd/0xd5
[ 361.513147] ret_from_fork+0x2e/0x38
[ 361.516143] BUG: sleeping function called from invalid context at mm/slab.h:417
[ 361.522899] in_atomic(): 1, irqs_disabled(): 0, pid: 1, name: swapper
[ 361.528723] 1 lock held by swapper/1:
[ 361.531230] #0: d45d28b0 (rcu_read_lock){....}, at: lookup_template_desc+0x5/0x86
[ 361.538251] CPU: 0 PID: 1 Comm: swapper Tainted: G T 5.1.0-rc2-00031-gd4f7b87 #1
[ 361.547940] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 361.557169] Call Trace:
[ 361.560239] dump_stack+0x16/0x18
[ 361.565114] ___might_sleep+0x187/0x19b
[ 361.571819] __might_sleep+0x69/0x70
[ 361.578949] ? __fs_reclaim_release+0x1c/0x1e
[ 361.583895] slab_pre_alloc_hook+0x34/0x52
[ 361.588664] __kmalloc+0x4e/0xce
[ 361.592271] ? template_desc_init_fields+0x135/0x16e
[ 361.598572] template_desc_init_fields+0x135/0x16e
[ 361.604810] ? kvm_sched_clock_read+0x9/0x18
[ 361.609842] ? lookup_template_desc+0x5/0x86
[ 361.614891] template_desc_init_fields+0x1d/0x20
[ 361.623218] lookup_template_desc+0x5d/0x86
[ 361.630492] ima_template_desc_current+0x20/0x32
[ 361.637302] hash_setup+0x18/0x109
[ 361.642723] ? hash_setup+0x109/0x109
[ 361.646881] init_ima+0x22/0xde
[ 361.650262] ? hash_setup+0x109/0x109
[ 361.653602] do_one_initcall+0x12e/0x312
[ 361.657291] ? rcu_read_lock_sched_held+0x47/0x4f
[ 361.662532] ? trace_initcall_level+0x5a/0x83
[ 361.666753] kernel_init_freeable+0x156/0x227
[ 361.671714] ? rest_init+0x96/0x96
[ 361.675634] kernel_init+0xd/0xd5
[ 361.679397] ret_from_fork+0x2e/0x38
[ 361.688284] ima: No TPM chip found, activating TPM-bypass!
[ 361.696993] ima: Allocated hash algorithm: sha1
[ 361.704162] No architecture policies found
[ 361.772839] e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX
[ 361.817671] 8021q: adding VLAN 0 to HW filter on device eth0
[ 361.829581] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 361.859124] Sending DHCP requests ., OK
[ 361.885160] IP-Config: Got DHCP answer from 10.0.2.2, my address is 10.0.2.15
[ 361.899013] IP-Config: Complete:
[ 361.904753] device=eth0, hwaddr=52:54:00:12:34:56, ipaddr=10.0.2.15, mask=255.255.255.0, gw=10.0.2.2
[ 361.927790] host=vm-snb-yocto-i386-317, domain=, nis-domain=(none)
[ 361.942511] bootserver=10.0.2.2, rootserver=10.0.2.2, rootpath=
[ 361.942528] nameserver0=10.0.2.3
[ 361.976726] Bluetooth: Starting self testing
[ 361.983847] Bluetooth: Finished self testing
[ 362.102727] Freeing unused kernel image memory: 1292K
[ 362.120228] Write protecting kernel text and read-only data: 17360k
[ 362.133838] Run /init as init process
[ 362.186986] mount (143) used greatest stack depth: 6968 bytes left
[ 362.390143] cat (159) used greatest stack depth: 6800 bytes left
PATH=/sbin:/usr/sbin:/bin:/usr/bin:/lkp/lkp/src/bin
/ #
[ 363.611091] process 224 (wget) attempted a POSIX timer syscall while CONFIG_POSIX_TIMERS is not set
[ 363.677034] Kernel tests: Boot OK!
[ 363.677034] /lkp/lkp/src/bin/run-lkp
[ 363.677034] RESULT_ROOT=/result/trinity/300s/vm-snb-yocto-i386/yocto-tiny-i386-2016-04-22.cgz/i386-randconfig-sb0-05181754/gcc-7/d4f7b87e55fb41849e9da958718134d2a34d3355/3
[ 363.677034] job=/lkp/jobs/scheduled/vm-snb-yocto-i386-317/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-d4f7b87e55fb4184-20190519-6838-84cphy-1.yaml
[ 363.677034] run-job /lkp/jobs/scheduled/vm-snb-yocto-i386-317/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-d4f7b87e55fb4184-20190519-6838-84cphy-1.yaml
[ 363.677034] /usr/bin/wget -q http://inn:80/~lkp/cgi-bin/lkp-jobfile-append-var?job_file=/lkp/jobs/sche... -O /dev/null
[ 366.803023] /lkp/lkp/src/monitors/heartbeat: line 9: vmstat: not found
[ 366.803023] /lkp/lkp/src/monitors/heartbeat: exec: line 23: vmstat: not found
[ 366.816173] target ucode:
[ 368.829982] Seeding trinity based on i386-randconfig-sb0-05181754
[ 368.829982] 2019-05-18 22:25:36 trinity -q -q -l off -s 1047496 -x get_robust_list -x remap_file_pages -N 999999999 -X
[ 368.829982] Trinity v1.9-1-g865d3fc2 Dave Jones <davej(a)codemonkey.org.uk>
[ 368.829982] shm:0xb7689000-0xc3e35e14 (4 pages)
[ 368.829982] [main] Marking syscall get_robust_list (312) as to be disabled.
[ 368.829982] [main] Marking syscall remap_file_pages (257) as to be disabled.
[ 368.829982] Error getting nobody pwent (No such file or directory)
Elapsed time: 310
qemu-img create -f qcow2 disk-vm-snb-yocto-i386-317-0 256G
qemu-img create -f qcow2 disk-vm-snb-yocto-i386-317-1 256G
kvm=(
qemu-system-i386
-enable-kvm
-cpu SandyBridge
-kernel $kernel
-initrd initrd-vm-snb-yocto-i386-317
-m 2048
-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-yocto-i386-317-0,media=disk,if=virtio
-drive file=disk-vm-snb-yocto-i386-317-1,media=disk,if=virtio
-serial stdio
-display none
-monitor null
)
append=(
ip=::::vm-snb-yocto-i386-317::dhcp
root=/dev/ram0
To reproduce:
# build kernel
cd linux
cp config-5.1.0-rc2-00031-gd4f7b87 .config
make HOSTCC=gcc-7 CC=gcc-7 ARCH=i386 olddefconfig
make HOSTCC=gcc-7 CC=gcc-7 ARCH=i386 prepare
make HOSTCC=gcc-7 CC=gcc-7 ARCH=i386 modules_prepare
make HOSTCC=gcc-7 CC=gcc-7 ARCH=i386 SHELL=/bin/bash
make HOSTCC=gcc-7 CC=gcc-7 ARCH=i386 bzImage
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, 5 months
[mac80211] 3df7894e9b: WARNING:at_net/mac80211/rate.c:#__rate_control_send_low[mac80211]
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: 3df7894e9b83877826acdfb12291b058f8b71c97 ("[PATCH 2/2] mac80211: use STA info in rate_control_send_low()")
url: https://github.com/0day-ci/linux/commits/Johannes-Berg/mac80211-call-rate...
base: https://git.kernel.org/cgit/linux/kernel/git/jberg/mac80211.git master
in testcase: hwsim
with following parameters:
group: hwsim-10
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):
+-------------------------------------------------------------------+------------+------------+
| | 676e759c98 | 3df7894e9b |
+-------------------------------------------------------------------+------------+------------+
| boot_successes | 10 | 13 |
| boot_failures | 0 | 6 |
| WARNING:at_net/mac80211/rate.c:#__rate_control_send_low[mac80211] | 0 | 6 |
| RIP:__rate_control_send_low[mac80211] | 0 | 6 |
+-------------------------------------------------------------------+------------+------------+
If you fix the issue, kindly add following tag
Reported-by: kernel test robot <lkp(a)intel.com>
[ 904.330858] WARNING: CPU: 1 PID: 4858 at net/mac80211/rate.c:362 __rate_control_send_low+0xf0/0x100 [mac80211]
[ 904.334146] Modules linked in: bridge stp llc veth cmac ccm arc4 mac80211_hwsim mac80211 cfg80211 rfkill crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel sr_mod cdrom sg ata_generic bochs_drm pata_acpi ttm drm_kms_helper ppdev snd_pcm aesni_intel syscopyarea sysfillrect sysimgblt fb_sys_fops crypto_simd ata_piix snd_timer libata cryptd snd drm soundcore glue_helper pcspkr joydev serio_raw i2c_piix4 parport_pc parport floppy ip_tables
[ 904.340913] CPU: 1 PID: 4858 Comm: wpa_supplicant Not tainted 5.1.0-10245-g3df7894 #1
[ 904.342376] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 904.343940] RIP: 0010:__rate_control_send_low+0xf0/0x100 [mac80211]
[ 904.345264] Code: c3 4d 85 e4 c6 05 b7 46 0a 00 01 b8 ff ff ff ff 74 07 8b 46 10 41 8b 04 84 44 89 c2 89 c6 48 c7 c7 d0 e6 68 c0 e8 b0 63 67 ea <0f> 0b eb 96 31 c0 eb 8d 0f 1f 84 00 00 00 00 00 66 66 66 66 90 41
[ 904.348616] RSP: 0018:ffffba5340b53810 EFLAGS: 00010286
[ 904.349860] RAX: 0000000000000000 RBX: ffff989f67348328 RCX: 0000000000000000
[ 904.351304] RDX: ffff98a03fd26d40 RSI: ffff98a03fd17778 RDI: ffff98a03fd17778
[ 904.352754] RBP: ffff989f8888c7a0 R08: 000000000000b4e4 R09: 0000000000aaaaaa
[ 904.354212] R10: 0000000000000000 R11: ffff98a01c7ab780 R12: ffff989f84945898
[ 904.355677] R13: ffff989f8888d648 R14: ffff989f8887d8d0 R15: ffff989f84945898
[ 904.357119] FS: 00007f33257dfe40(0000) GS:ffff98a03fd00000(0000) knlGS:0000000000000000
[ 904.358693] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 904.360022] CR2: 00000000004216d0 CR3: 000000008895c000 CR4: 00000000000406e0
[ 904.361515] Call Trace:
[ 904.362522] rate_control_send_low+0x41/0x110 [mac80211]
[ 904.363825] rate_control_get_rate+0x7c/0x150 [mac80211]
[ 904.365133] ieee80211_tx_h_rate_ctrl+0x195/0x3d0 [mac80211]
[ 904.366484] invoke_tx_handlers_early+0x2a0/0x5f0 [mac80211]
[ 904.367808] ieee80211_tx+0x82/0x130 [mac80211]
[ 904.369014] __ieee80211_tx_skb_tid_band+0x58/0x70 [mac80211]
[ 904.370346] ieee80211_mgmt_tx+0x36a/0x580 [mac80211]
[ 904.371596] ? skb_partial_csum_set+0xb0/0xb0
[ 904.372779] cfg80211_mlme_mgmt_tx+0x10e/0x300 [cfg80211]
[ 904.374079] nl80211_tx_mgmt+0x246/0x3b0 [cfg80211]
[ 904.375321] ? __nla_validate_parse+0x57/0x8a0
[ 904.376509] genl_family_rcv_msg+0x203/0x400
[ 904.378183] ? get_page_from_freelist+0x15c7/0x15e0
[ 904.379423] genl_rcv_msg+0x47/0x90
[ 904.380522] ? __kmalloc_node_track_caller+0x59/0x2a0
[ 904.381780] ? genl_family_rcv_msg+0x400/0x400
[ 904.383645] netlink_rcv_skb+0x4a/0x110
[ 904.384759] genl_rcv+0x24/0x40
[ 904.385798] netlink_unicast+0x193/0x230
[ 904.386913] netlink_sendmsg+0x2c1/0x3c0
[ 904.388017] sock_sendmsg+0x4c/0x50
[ 904.389070] ___sys_sendmsg+0x289/0x300
[ 904.390158] ? __generic_file_write_iter+0x192/0x1c0
[ 904.391363] ? generic_file_write_iter+0xb8/0x150
[ 904.392540] ? new_sync_write+0x12d/0x1d0
[ 904.393631] ? __sys_sendmsg+0x5e/0xa0
[ 904.394693] __sys_sendmsg+0x5e/0xa0
[ 904.395722] do_syscall_64+0x5b/0x1d0
[ 904.396736] entry_SYSCALL_64_after_hwframe+0x44/0xa9
[ 904.397880] RIP: 0033:0x7f33239b1dc7
[ 904.398845] Code: d8 64 89 02 48 c7 c0 ff ff ff ff eb cd 66 0f 1f 44 00 00 8b 05 4a 49 2b 00 85 c0 75 2e 48 63 ff 48 63 d2 b8 2e 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 01 c3 48 8b 15 a1 f0 2a 00 f7 d8 64 89 02 48
[ 904.402016] RSP: 002b:00007ffe4dc102e8 EFLAGS: 00000246 ORIG_RAX: 000000000000002e
[ 904.403400] RAX: ffffffffffffffda RBX: 0000558ed2830b00 RCX: 00007f33239b1dc7
[ 904.404746] RDX: 0000000000000000 RSI: 00007ffe4dc10370 RDI: 0000000000000007
[ 904.406079] RBP: 0000558ed2830a10 R08: 0000000000000004 R09: 00000000000000f0
[ 904.407400] R10: 00007ffe4dc1044c R11: 0000000000000246 R12: 0000558ed37d0910
[ 904.408707] R13: 00007ffe4dc10370 R14: 00007ffe4dc104a8 R15: 00007ffe4dc1044c
[ 904.410047] ---[ end trace 2b49fe831975940d ]---
To reproduce:
# build kernel
cd linux
cp config-5.1.0-10245-g3df7894 .config
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 olddefconfig
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 prepare
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 modules_prepare
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 SHELL=/bin/bash
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 bzImage
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, 5 months
fcbd06ff70 ("keys: Cache result of request_key*() temporarily .."): BUG: kernel reboot-without-warning in boot stage
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 afs-next
commit fcbd06ff703a11e7b771010ede92177ce406491c
Author: David Howells <dhowells(a)redhat.com>
AuthorDate: Wed May 15 15:54:06 2019 +0100
Commit: David Howells <dhowells(a)redhat.com>
CommitDate: Wed May 15 16:08:55 2019 +0100
keys: Cache result of request_key*() temporarily in task_struct
If a filesystem uses keys to hold authentication tokens, then it needs a
token for each VFS operation that might perform an authentication check -
either by passing it to the server, or using to perform a check based on
authentication data cached locally.
For open files this isn't a problem, since the key should be cached in the
file struct since it represents the subject performing operations on that
file descriptor.
During pathwalk, however, there isn't anywhere to cache the key, except
perhaps in the nameidata struct - but that isn't exposed to the
filesystems. Further, a pathwalk can incur a lot of operations, calling
one or more of the following, for instance:
->lookup()
->permission()
->d_revalidate()
->d_automount()
->get_acl()
->getxattr()
on each dentry/inode it encounters - and each one may need to call
request_key(). And then, at the end of pathwalk, it will call the actual
operation:
->mkdir()
->mknod()
->getattr()
->open()
...
which may need to go and get the token again.
However, it is very likely that all of the operations on a single
dentry/inode - and quite possibly a sequence of them - will all want to use
the same authentication token, which suggests that caching it would be a
good idea.
To this end:
(1) Make it so that a positive result of request_key() and co. that didn't
require upcalling to userspace is cached temporarily in task_struct.
(2) The cache is 1 deep, so a new result displaces the old one.
(3) The key is released by exit and by notify-resume.
(4) The cache is cleared in a newly forked process.
Signed-off-by: David Howells <dhowells(a)redhat.com>
279301e23b afs: Support RCU pathwalk
fcbd06ff70 keys: Cache result of request_key*() temporarily in task_struct
+-------------------------------------------------+------------+------------+
| | 279301e23b | fcbd06ff70 |
+-------------------------------------------------+------------+------------+
| boot_successes | 47 | 0 |
| boot_failures | 1 | 16 |
| BUG:soft_lockup-CPU##stuck_for#s | 1 | |
| RIP:_raw_spin_unlock_irqrestore | 1 | |
| Kernel_panic-not_syncing:softlockup:hung_tasks | 1 | |
| BUG:kernel_reboot-without-warning_in_boot_stage | 0 | 16 |
+-------------------------------------------------+------------+------------+
If you fix the issue, kindly add following tag
Reported-by: kernel test robot <lkp(a)intel.com>
[ 0.522324] ACPI: Early table checksum verification disabled
[ 0.533773] ACPI: RSDP 0x00000000000F6870 000014 (v00 BOCHS )
[ 0.548005] ACPI: RSDT 0x000000001FFE15C9 000030 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001)
[ 0.563969] ACPI: FACP 0x000000001FFE149D 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001)
[ 0.579681] ACPI: DSDT 0x000000001FFE0040 00145D (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001)
BUG: kernel reboot-without-warning in boot stage
# HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start fcbd06ff703a11e7b771010ede92177ce406491c 80f232121b69cc69a31ccb2b38c1665d770b0710 --
git bisect good 51a8f00b122709a1e18050c8b906b1ae391166c9 # 09:12 G 10 0 3 3 afs: Fix double inc of vnode->cb_break
git bisect good 3f33c85c0f065b3a36489f60dd0ff2e9f2d49ec7 # 09:38 G 11 0 2 2 afs: Make vnode->cb_interest RCU safe
git bisect good a97498e28bdbf6b3a05e3b8970e09ffe024a7264 # 09:57 G 10 0 2 2 afs: Fix application of the results of a inline bulk status fetch
git bisect good c2025bd0bd948893650455b7b2b45d7e36b58408 # 10:10 G 10 0 1 1 afs: Provide an RCU-capable key lookup
git bisect good 279301e23b8444ce1f1ea0c94b0d3428a9a10ec4 # 10:27 G 11 0 3 4 afs: Support RCU pathwalk
# first bad commit: [fcbd06ff703a11e7b771010ede92177ce406491c] keys: Cache result of request_key*() temporarily in task_struct
git bisect good 279301e23b8444ce1f1ea0c94b0d3428a9a10ec4 # 23:12 G 30 0 5 9 afs: Support RCU pathwalk
# extra tests on HEAD of dhowells-fs/afs-next
git bisect bad fcbd06ff703a11e7b771010ede92177ce406491c # 23:12 B 0 16 48 0 keys: Cache result of request_key*() temporarily in task_struct
# extra tests on tree/branch dhowells-fs/afs-next
git bisect bad fcbd06ff703a11e7b771010ede92177ce406491c # 23:17 B 0 16 48 0 keys: Cache result of request_key*() temporarily in task_struct
# extra tests with first bad commit reverted
git bisect good 88b215f57280bb88b175f8cfc8dd3e09ea54dc55 # 23:34 G 10 0 0 0 Revert "keys: Cache result of request_key*() temporarily in task_struct"
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
1 year, 5 months
[sched] b0f464c9c8: WARNING:at_kernel/sched/sched.h:#pick_next_task_fair
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: b0f464c9c862c0c912ac3fe1edf8a157517157d0 ("sched: Add task_struct pointer to sched_class::set_curr_task")
https://github.com/digitalocean/linux-coresched coresched
in testcase: locktorture
with following parameters:
runtime: 300s
test: cpuhotplug
test-description: This torture test consists of creating a number of kernel threads which acquire the lock and hold it for specific amount of time, thus simulating different critical region behaviors.
test-url: https://www.kernel.org/doc/Documentation/locking/locktorture.txt
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):
+------------------------------------------------------+------------+------------+
| | 4f53939e5f | b0f464c9c8 |
+------------------------------------------------------+------------+------------+
| boot_successes | 5 | 10 |
| boot_failures | 1 | 16 |
| BUG:kernel_reboot-without-warning_in_test_stage | 1 | |
| WARNING:at_kernel/sched/sched.h:#pick_next_task_fair | 0 | 16 |
| RIP:pick_next_task_fair | 0 | 16 |
| WARNING:at_kernel/sched/sched.h:#sched_cpu_dying | 0 | 16 |
| RIP:sched_cpu_dying | 0 | 16 |
+------------------------------------------------------+------------+------------+
If you fix the issue, kindly add following tag
Reported-by: kernel test robot <lkp(a)intel.com>
[ 276.263169] WARNING: CPU: 0 PID: 11 at kernel/sched/sched.h:1719 pick_next_task_fair+0x754/0x780
[ 276.265858] Modules linked in: locktorture torture ppdev snd_pcm crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel snd_timer snd soundcore bochs_drm pcspkr joydev ttm serio_raw drm_kms_helper drm ata_generic pata_acpi i2c_piix4 parport_pc floppy parport qemu_fw_cfg
[ 276.272254] CPU: 0 PID: 11 Comm: migration/0 Not tainted 5.1.0-10881-gb0f464c #1
[ 276.274218] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 276.276415] RIP: 0010:pick_next_task_fair+0x754/0x780
[ 276.277773] Code: 48 0f af 85 18 01 00 00 48 83 c1 01 48 f7 f1 48 89 83 10 0a 00 00 e9 6a fa ff ff bf 02 00 00 00 e8 e1 33 ff ff e9 08 fa ff ff <0f> 0b e9 1d fb ff ff 80 3d e7 50 61 01 00 0f 85 30 fc ff ff e8 83
[ 276.282716] RSP: 0018:ffffbab3c0377d08 EFLAGS: 00010006
[ 276.284174] RAX: ffffffff90011dc0 RBX: ffffa0316be2aec0 RCX: ffffffff9064d0c0
[ 276.286075] RDX: ffffbab3c0377d90 RSI: 0000000000000001 RDI: ffffa0316be2aec0
[ 276.287968] RBP: ffffbab3c0377dd0 R08: 000000405290c768 R09: 0000000000000005
[ 276.289876] R10: ffffbab3c0377d48 R11: 000000000000138e R12: ffffffff9064d0c0
[ 276.291768] R13: ffffffff90011fc0 R14: ffffbab3c0377d90 R15: 0000000000000000
[ 276.293658] FS: 0000000000000000(0000) GS:ffffa0316be00000(0000) knlGS:0000000000000000
[ 276.295803] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 276.297325] CR2: 00005608c15f8d60 CR3: 000000007efe0000 CR4: 00000000000006f0
[ 276.299238] Call Trace:
[ 276.301855] sched_cpu_dying+0x29a/0x3c0
[ 276.302925] ? sched_cpu_starting+0xd0/0xd0
[ 276.304034] cpuhp_invoke_callback+0x86/0x5d0
[ 276.305221] ? cpu_disable_common+0x1cf/0x1f0
[ 276.306375] take_cpu_down+0x60/0xa0
[ 276.307330] multi_cpu_stop+0x68/0xe0
[ 276.308308] ? cpu_stopper_thread+0x100/0x100
[ 276.309469] cpu_stopper_thread+0x94/0x100
[ 276.310591] ? smpboot_thread_fn+0x2f/0x1e0
[ 276.311725] ? smpboot_thread_fn+0x74/0x1e0
[ 276.312843] ? smpboot_thread_fn+0x14e/0x1e0
[ 276.313987] smpboot_thread_fn+0x149/0x1e0
[ 276.315077] ? sort_range+0x20/0x20
[ 276.316034] kthread+0x11e/0x140
[ 276.316903] ? kthread_park+0x90/0x90
[ 276.317889] ret_from_fork+0x35/0x40
[ 276.318851] ---[ end trace 4938a64101275eb2 ]---
To reproduce:
# build kernel
cd linux
cp config-5.1.0-10881-gb0f464c .config
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 olddefconfig
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 prepare
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 modules_prepare
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 SHELL=/bin/bash
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 bzImage
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
find lib/ | cpio -o -H newc --quiet | gzip > modules.cgz
bin/lkp qemu -k <bzImage> -m modules.cgz job-script # job-script is attached in this email
Thanks,
lkp
1 year, 5 months
[sched] b0f464c9c8: WARNING:at_kernel/sched/sched.h:#pick_next_task_fair
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: b0f464c9c862c0c912ac3fe1edf8a157517157d0 ("sched: Add task_struct pointer to sched_class::set_curr_task")
https://github.com/digitalocean/linux-coresched coresched
in testcase: locktorture
with following parameters:
runtime: 300s
test: cpuhotplug
test-description: This torture test consists of creating a number of kernel threads which acquire the lock and hold it for specific amount of time, thus simulating different critical region behaviors.
test-url: https://www.kernel.org/doc/Documentation/locking/locktorture.txt
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):
+------------------------------------------------------+------------+------------+
| | 4f53939e5f | b0f464c9c8 |
+------------------------------------------------------+------------+------------+
| boot_successes | 5 | 10 |
| boot_failures | 1 | 16 |
| BUG:kernel_reboot-without-warning_in_test_stage | 1 | |
| WARNING:at_kernel/sched/sched.h:#pick_next_task_fair | 0 | 16 |
| RIP:pick_next_task_fair | 0 | 16 |
| WARNING:at_kernel/sched/sched.h:#sched_cpu_dying | 0 | 16 |
| RIP:sched_cpu_dying | 0 | 16 |
+------------------------------------------------------+------------+------------+
If you fix the issue, kindly add following tag
Reported-by: kernel test robot <lkp(a)intel.com>
[ 276.263169] WARNING: CPU: 0 PID: 11 at kernel/sched/sched.h:1719 pick_next_task_fair+0x754/0x780
[ 276.265858] Modules linked in: locktorture torture ppdev snd_pcm crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel snd_timer snd soundcore bochs_drm pcspkr joydev ttm serio_raw drm_kms_helper drm ata_generic pata_acpi i2c_piix4 parport_pc floppy parport qemu_fw_cfg
[ 276.272254] CPU: 0 PID: 11 Comm: migration/0 Not tainted 5.1.0-10881-gb0f464c #1
[ 276.274218] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 276.276415] RIP: 0010:pick_next_task_fair+0x754/0x780
[ 276.277773] Code: 48 0f af 85 18 01 00 00 48 83 c1 01 48 f7 f1 48 89 83 10 0a 00 00 e9 6a fa ff ff bf 02 00 00 00 e8 e1 33 ff ff e9 08 fa ff ff <0f> 0b e9 1d fb ff ff 80 3d e7 50 61 01 00 0f 85 30 fc ff ff e8 83
[ 276.282716] RSP: 0018:ffffbab3c0377d08 EFLAGS: 00010006
[ 276.284174] RAX: ffffffff90011dc0 RBX: ffffa0316be2aec0 RCX: ffffffff9064d0c0
[ 276.286075] RDX: ffffbab3c0377d90 RSI: 0000000000000001 RDI: ffffa0316be2aec0
[ 276.287968] RBP: ffffbab3c0377dd0 R08: 000000405290c768 R09: 0000000000000005
[ 276.289876] R10: ffffbab3c0377d48 R11: 000000000000138e R12: ffffffff9064d0c0
[ 276.291768] R13: ffffffff90011fc0 R14: ffffbab3c0377d90 R15: 0000000000000000
[ 276.293658] FS: 0000000000000000(0000) GS:ffffa0316be00000(0000) knlGS:0000000000000000
[ 276.295803] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 276.297325] CR2: 00005608c15f8d60 CR3: 000000007efe0000 CR4: 00000000000006f0
[ 276.299238] Call Trace:
[ 276.301855] sched_cpu_dying+0x29a/0x3c0
[ 276.302925] ? sched_cpu_starting+0xd0/0xd0
[ 276.304034] cpuhp_invoke_callback+0x86/0x5d0
[ 276.305221] ? cpu_disable_common+0x1cf/0x1f0
[ 276.306375] take_cpu_down+0x60/0xa0
[ 276.307330] multi_cpu_stop+0x68/0xe0
[ 276.308308] ? cpu_stopper_thread+0x100/0x100
[ 276.309469] cpu_stopper_thread+0x94/0x100
[ 276.310591] ? smpboot_thread_fn+0x2f/0x1e0
[ 276.311725] ? smpboot_thread_fn+0x74/0x1e0
[ 276.312843] ? smpboot_thread_fn+0x14e/0x1e0
[ 276.313987] smpboot_thread_fn+0x149/0x1e0
[ 276.315077] ? sort_range+0x20/0x20
[ 276.316034] kthread+0x11e/0x140
[ 276.316903] ? kthread_park+0x90/0x90
[ 276.317889] ret_from_fork+0x35/0x40
[ 276.318851] ---[ end trace 4938a64101275eb2 ]---
To reproduce:
# build kernel
cd linux
cp config-5.1.0-10881-gb0f464c .config
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 olddefconfig
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 prepare
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 modules_prepare
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 SHELL=/bin/bash
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 bzImage
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
find lib/ | cpio -o -H newc --quiet | gzip > modules.cgz
bin/lkp qemu -k <bzImage> -m modules.cgz job-script # job-script is attached in this email
Thanks,
lkp
1 year, 5 months
[sched] b0f464c9c8: WARNING:at_kernel/sched/sched.h:#pick_next_task_fair
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: b0f464c9c862c0c912ac3fe1edf8a157517157d0 ("sched: Add task_struct pointer to sched_class::set_curr_task")
https://github.com/digitalocean/linux-coresched coresched
in testcase: locktorture
with following parameters:
runtime: 300s
test: cpuhotplug
test-description: This torture test consists of creating a number of kernel threads which acquire the lock and hold it for specific amount of time, thus simulating different critical region behaviors.
test-url: https://www.kernel.org/doc/Documentation/locking/locktorture.txt
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):
+------------------------------------------------------+------------+------------+
| | 4f53939e5f | b0f464c9c8 |
+------------------------------------------------------+------------+------------+
| boot_successes | 5 | 10 |
| boot_failures | 1 | 16 |
| BUG:kernel_reboot-without-warning_in_test_stage | 1 | |
| WARNING:at_kernel/sched/sched.h:#pick_next_task_fair | 0 | 16 |
| RIP:pick_next_task_fair | 0 | 16 |
| WARNING:at_kernel/sched/sched.h:#sched_cpu_dying | 0 | 16 |
| RIP:sched_cpu_dying | 0 | 16 |
+------------------------------------------------------+------------+------------+
If you fix the issue, kindly add following tag
Reported-by: kernel test robot <lkp(a)intel.com>
[ 276.263169] WARNING: CPU: 0 PID: 11 at kernel/sched/sched.h:1719 pick_next_task_fair+0x754/0x780
[ 276.265858] Modules linked in: locktorture torture ppdev snd_pcm crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel snd_timer snd soundcore bochs_drm pcspkr joydev ttm serio_raw drm_kms_helper drm ata_generic pata_acpi i2c_piix4 parport_pc floppy parport qemu_fw_cfg
[ 276.272254] CPU: 0 PID: 11 Comm: migration/0 Not tainted 5.1.0-10881-gb0f464c #1
[ 276.274218] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 276.276415] RIP: 0010:pick_next_task_fair+0x754/0x780
[ 276.277773] Code: 48 0f af 85 18 01 00 00 48 83 c1 01 48 f7 f1 48 89 83 10 0a 00 00 e9 6a fa ff ff bf 02 00 00 00 e8 e1 33 ff ff e9 08 fa ff ff <0f> 0b e9 1d fb ff ff 80 3d e7 50 61 01 00 0f 85 30 fc ff ff e8 83
[ 276.282716] RSP: 0018:ffffbab3c0377d08 EFLAGS: 00010006
[ 276.284174] RAX: ffffffff90011dc0 RBX: ffffa0316be2aec0 RCX: ffffffff9064d0c0
[ 276.286075] RDX: ffffbab3c0377d90 RSI: 0000000000000001 RDI: ffffa0316be2aec0
[ 276.287968] RBP: ffffbab3c0377dd0 R08: 000000405290c768 R09: 0000000000000005
[ 276.289876] R10: ffffbab3c0377d48 R11: 000000000000138e R12: ffffffff9064d0c0
[ 276.291768] R13: ffffffff90011fc0 R14: ffffbab3c0377d90 R15: 0000000000000000
[ 276.293658] FS: 0000000000000000(0000) GS:ffffa0316be00000(0000) knlGS:0000000000000000
[ 276.295803] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 276.297325] CR2: 00005608c15f8d60 CR3: 000000007efe0000 CR4: 00000000000006f0
[ 276.299238] Call Trace:
[ 276.301855] sched_cpu_dying+0x29a/0x3c0
[ 276.302925] ? sched_cpu_starting+0xd0/0xd0
[ 276.304034] cpuhp_invoke_callback+0x86/0x5d0
[ 276.305221] ? cpu_disable_common+0x1cf/0x1f0
[ 276.306375] take_cpu_down+0x60/0xa0
[ 276.307330] multi_cpu_stop+0x68/0xe0
[ 276.308308] ? cpu_stopper_thread+0x100/0x100
[ 276.309469] cpu_stopper_thread+0x94/0x100
[ 276.310591] ? smpboot_thread_fn+0x2f/0x1e0
[ 276.311725] ? smpboot_thread_fn+0x74/0x1e0
[ 276.312843] ? smpboot_thread_fn+0x14e/0x1e0
[ 276.313987] smpboot_thread_fn+0x149/0x1e0
[ 276.315077] ? sort_range+0x20/0x20
[ 276.316034] kthread+0x11e/0x140
[ 276.316903] ? kthread_park+0x90/0x90
[ 276.317889] ret_from_fork+0x35/0x40
[ 276.318851] ---[ end trace 4938a64101275eb2 ]---
To reproduce:
# build kernel
cd linux
cp config-5.1.0-10881-gb0f464c .config
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 olddefconfig
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 prepare
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 modules_prepare
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 SHELL=/bin/bash
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 bzImage
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
find lib/ | cpio -o -H newc --quiet | gzip > modules.cgz
bin/lkp qemu -k <bzImage> -m modules.cgz job-script # job-script is attached in this email
Thanks,
lkp
1 year, 5 months
[sched] b0f464c9c8: WARNING:at_kernel/sched/sched.h:#pick_next_task_fair
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: b0f464c9c862c0c912ac3fe1edf8a157517157d0 ("sched: Add task_struct pointer to sched_class::set_curr_task")
https://github.com/digitalocean/linux-coresched coresched
in testcase: locktorture
with following parameters:
runtime: 300s
test: cpuhotplug
test-description: This torture test consists of creating a number of kernel threads which acquire the lock and hold it for specific amount of time, thus simulating different critical region behaviors.
test-url: https://www.kernel.org/doc/Documentation/locking/locktorture.txt
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):
+------------------------------------------------------+------------+------------+
| | 4f53939e5f | b0f464c9c8 |
+------------------------------------------------------+------------+------------+
| boot_successes | 5 | 10 |
| boot_failures | 1 | 16 |
| BUG:kernel_reboot-without-warning_in_test_stage | 1 | |
| WARNING:at_kernel/sched/sched.h:#pick_next_task_fair | 0 | 16 |
| RIP:pick_next_task_fair | 0 | 16 |
| WARNING:at_kernel/sched/sched.h:#sched_cpu_dying | 0 | 16 |
| RIP:sched_cpu_dying | 0 | 16 |
+------------------------------------------------------+------------+------------+
If you fix the issue, kindly add following tag
Reported-by: kernel test robot <lkp(a)intel.com>
[ 276.263169] WARNING: CPU: 0 PID: 11 at kernel/sched/sched.h:1719 pick_next_task_fair+0x754/0x780
[ 276.265858] Modules linked in: locktorture torture ppdev snd_pcm crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel snd_timer snd soundcore bochs_drm pcspkr joydev ttm serio_raw drm_kms_helper drm ata_generic pata_acpi i2c_piix4 parport_pc floppy parport qemu_fw_cfg
[ 276.272254] CPU: 0 PID: 11 Comm: migration/0 Not tainted 5.1.0-10881-gb0f464c #1
[ 276.274218] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 276.276415] RIP: 0010:pick_next_task_fair+0x754/0x780
[ 276.277773] Code: 48 0f af 85 18 01 00 00 48 83 c1 01 48 f7 f1 48 89 83 10 0a 00 00 e9 6a fa ff ff bf 02 00 00 00 e8 e1 33 ff ff e9 08 fa ff ff <0f> 0b e9 1d fb ff ff 80 3d e7 50 61 01 00 0f 85 30 fc ff ff e8 83
[ 276.282716] RSP: 0018:ffffbab3c0377d08 EFLAGS: 00010006
[ 276.284174] RAX: ffffffff90011dc0 RBX: ffffa0316be2aec0 RCX: ffffffff9064d0c0
[ 276.286075] RDX: ffffbab3c0377d90 RSI: 0000000000000001 RDI: ffffa0316be2aec0
[ 276.287968] RBP: ffffbab3c0377dd0 R08: 000000405290c768 R09: 0000000000000005
[ 276.289876] R10: ffffbab3c0377d48 R11: 000000000000138e R12: ffffffff9064d0c0
[ 276.291768] R13: ffffffff90011fc0 R14: ffffbab3c0377d90 R15: 0000000000000000
[ 276.293658] FS: 0000000000000000(0000) GS:ffffa0316be00000(0000) knlGS:0000000000000000
[ 276.295803] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 276.297325] CR2: 00005608c15f8d60 CR3: 000000007efe0000 CR4: 00000000000006f0
[ 276.299238] Call Trace:
[ 276.301855] sched_cpu_dying+0x29a/0x3c0
[ 276.302925] ? sched_cpu_starting+0xd0/0xd0
[ 276.304034] cpuhp_invoke_callback+0x86/0x5d0
[ 276.305221] ? cpu_disable_common+0x1cf/0x1f0
[ 276.306375] take_cpu_down+0x60/0xa0
[ 276.307330] multi_cpu_stop+0x68/0xe0
[ 276.308308] ? cpu_stopper_thread+0x100/0x100
[ 276.309469] cpu_stopper_thread+0x94/0x100
[ 276.310591] ? smpboot_thread_fn+0x2f/0x1e0
[ 276.311725] ? smpboot_thread_fn+0x74/0x1e0
[ 276.312843] ? smpboot_thread_fn+0x14e/0x1e0
[ 276.313987] smpboot_thread_fn+0x149/0x1e0
[ 276.315077] ? sort_range+0x20/0x20
[ 276.316034] kthread+0x11e/0x140
[ 276.316903] ? kthread_park+0x90/0x90
[ 276.317889] ret_from_fork+0x35/0x40
[ 276.318851] ---[ end trace 4938a64101275eb2 ]---
To reproduce:
# build kernel
cd linux
cp config-5.1.0-10881-gb0f464c .config
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 olddefconfig
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 prepare
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 modules_prepare
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 SHELL=/bin/bash
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 bzImage
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
find lib/ | cpio -o -H newc --quiet | gzip > modules.cgz
bin/lkp qemu -k <bzImage> -m modules.cgz job-script # job-script is attached in this email
Thanks,
lkp
1 year, 5 months
[sched] b0f464c9c8: WARNING:at_kernel/sched/sched.h:#pick_next_task_fair
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: b0f464c9c862c0c912ac3fe1edf8a157517157d0 ("sched: Add task_struct pointer to sched_class::set_curr_task")
https://github.com/digitalocean/linux-coresched coresched
in testcase: locktorture
with following parameters:
runtime: 300s
test: cpuhotplug
test-description: This torture test consists of creating a number of kernel threads which acquire the lock and hold it for specific amount of time, thus simulating different critical region behaviors.
test-url: https://www.kernel.org/doc/Documentation/locking/locktorture.txt
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):
+------------------------------------------------------+------------+------------+
| | 4f53939e5f | b0f464c9c8 |
+------------------------------------------------------+------------+------------+
| boot_successes | 5 | 0 |
| boot_failures | 1 | 8 |
| BUG:kernel_reboot-without-warning_in_test_stage | 1 | |
| WARNING:at_kernel/sched/sched.h:#pick_next_task_fair | 0 | 8 |
| RIP:pick_next_task_fair | 0 | 8 |
| WARNING:at_kernel/sched/sched.h:#sched_cpu_dying | 0 | 8 |
| RIP:sched_cpu_dying | 0 | 8 |
+------------------------------------------------------+------------+------------+
If you fix the issue, kindly add following tag
Reported-by: kernel test robot <lkp(a)intel.com>
[ 276.263169] WARNING: CPU: 0 PID: 11 at kernel/sched/sched.h:1719 pick_next_task_fair+0x754/0x780
[ 276.265858] Modules linked in: locktorture torture ppdev snd_pcm crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel snd_timer snd soundcore bochs_drm pcspkr joydev ttm serio_raw drm_kms_helper drm ata_generic pata_acpi i2c_piix4 parport_pc floppy parport qemu_fw_cfg
[ 276.272254] CPU: 0 PID: 11 Comm: migration/0 Not tainted 5.1.0-10881-gb0f464c #1
[ 276.274218] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 276.276415] RIP: 0010:pick_next_task_fair+0x754/0x780
[ 276.277773] Code: 48 0f af 85 18 01 00 00 48 83 c1 01 48 f7 f1 48 89 83 10 0a 00 00 e9 6a fa ff ff bf 02 00 00 00 e8 e1 33 ff ff e9 08 fa ff ff <0f> 0b e9 1d fb ff ff 80 3d e7 50 61 01 00 0f 85 30 fc ff ff e8 83
[ 276.282716] RSP: 0018:ffffbab3c0377d08 EFLAGS: 00010006
[ 276.284174] RAX: ffffffff90011dc0 RBX: ffffa0316be2aec0 RCX: ffffffff9064d0c0
[ 276.286075] RDX: ffffbab3c0377d90 RSI: 0000000000000001 RDI: ffffa0316be2aec0
[ 276.287968] RBP: ffffbab3c0377dd0 R08: 000000405290c768 R09: 0000000000000005
[ 276.289876] R10: ffffbab3c0377d48 R11: 000000000000138e R12: ffffffff9064d0c0
[ 276.291768] R13: ffffffff90011fc0 R14: ffffbab3c0377d90 R15: 0000000000000000
[ 276.293658] FS: 0000000000000000(0000) GS:ffffa0316be00000(0000) knlGS:0000000000000000
[ 276.295803] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 276.297325] CR2: 00005608c15f8d60 CR3: 000000007efe0000 CR4: 00000000000006f0
[ 276.299238] Call Trace:
[ 276.301855] sched_cpu_dying+0x29a/0x3c0
[ 276.302925] ? sched_cpu_starting+0xd0/0xd0
[ 276.304034] cpuhp_invoke_callback+0x86/0x5d0
[ 276.305221] ? cpu_disable_common+0x1cf/0x1f0
[ 276.306375] take_cpu_down+0x60/0xa0
[ 276.307330] multi_cpu_stop+0x68/0xe0
[ 276.308308] ? cpu_stopper_thread+0x100/0x100
[ 276.309469] cpu_stopper_thread+0x94/0x100
[ 276.310591] ? smpboot_thread_fn+0x2f/0x1e0
[ 276.311725] ? smpboot_thread_fn+0x74/0x1e0
[ 276.312843] ? smpboot_thread_fn+0x14e/0x1e0
[ 276.313987] smpboot_thread_fn+0x149/0x1e0
[ 276.315077] ? sort_range+0x20/0x20
[ 276.316034] kthread+0x11e/0x140
[ 276.316903] ? kthread_park+0x90/0x90
[ 276.317889] ret_from_fork+0x35/0x40
[ 276.318851] ---[ end trace 4938a64101275eb2 ]---
To reproduce:
# build kernel
cd linux
cp config-5.1.0-10881-gb0f464c .config
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 olddefconfig
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 prepare
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 modules_prepare
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 SHELL=/bin/bash
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 bzImage
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
find lib/ | cpio -o -H newc --quiet | gzip > modules.cgz
bin/lkp qemu -k <bzImage> -m modules.cgz job-script # job-script is attached in this email
Thanks,
lkp
1 year, 5 months
[sched] b0f464c9c8: WARNING:at_kernel/sched/sched.h:#pick_next_task_fair
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: b0f464c9c862c0c912ac3fe1edf8a157517157d0 ("sched: Add task_struct pointer to sched_class::set_curr_task")
https://github.com/digitalocean/linux-coresched coresched
in testcase: locktorture
with following parameters:
runtime: 300s
test: cpuhotplug
test-description: This torture test consists of creating a number of kernel threads which acquire the lock and hold it for specific amount of time, thus simulating different critical region behaviors.
test-url: https://www.kernel.org/doc/Documentation/locking/locktorture.txt
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):
+------------------------------------------------------+------------+------------+
| | 4f53939e5f | b0f464c9c8 |
+------------------------------------------------------+------------+------------+
| boot_successes | 5 | 0 |
| boot_failures | 1 | 8 |
| BUG:kernel_reboot-without-warning_in_test_stage | 1 | |
| WARNING:at_kernel/sched/sched.h:#pick_next_task_fair | 0 | 8 |
| RIP:pick_next_task_fair | 0 | 8 |
| WARNING:at_kernel/sched/sched.h:#sched_cpu_dying | 0 | 8 |
| RIP:sched_cpu_dying | 0 | 8 |
+------------------------------------------------------+------------+------------+
If you fix the issue, kindly add following tag
Reported-by: kernel test robot <lkp(a)intel.com>
[ 276.263169] WARNING: CPU: 0 PID: 11 at kernel/sched/sched.h:1719 pick_next_task_fair+0x754/0x780
[ 276.265858] Modules linked in: locktorture torture ppdev snd_pcm crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel snd_timer snd soundcore bochs_drm pcspkr joydev ttm serio_raw drm_kms_helper drm ata_generic pata_acpi i2c_piix4 parport_pc floppy parport qemu_fw_cfg
[ 276.272254] CPU: 0 PID: 11 Comm: migration/0 Not tainted 5.1.0-10881-gb0f464c #1
[ 276.274218] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 276.276415] RIP: 0010:pick_next_task_fair+0x754/0x780
[ 276.277773] Code: 48 0f af 85 18 01 00 00 48 83 c1 01 48 f7 f1 48 89 83 10 0a 00 00 e9 6a fa ff ff bf 02 00 00 00 e8 e1 33 ff ff e9 08 fa ff ff <0f> 0b e9 1d fb ff ff 80 3d e7 50 61 01 00 0f 85 30 fc ff ff e8 83
[ 276.282716] RSP: 0018:ffffbab3c0377d08 EFLAGS: 00010006
[ 276.284174] RAX: ffffffff90011dc0 RBX: ffffa0316be2aec0 RCX: ffffffff9064d0c0
[ 276.286075] RDX: ffffbab3c0377d90 RSI: 0000000000000001 RDI: ffffa0316be2aec0
[ 276.287968] RBP: ffffbab3c0377dd0 R08: 000000405290c768 R09: 0000000000000005
[ 276.289876] R10: ffffbab3c0377d48 R11: 000000000000138e R12: ffffffff9064d0c0
[ 276.291768] R13: ffffffff90011fc0 R14: ffffbab3c0377d90 R15: 0000000000000000
[ 276.293658] FS: 0000000000000000(0000) GS:ffffa0316be00000(0000) knlGS:0000000000000000
[ 276.295803] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 276.297325] CR2: 00005608c15f8d60 CR3: 000000007efe0000 CR4: 00000000000006f0
[ 276.299238] Call Trace:
[ 276.301855] sched_cpu_dying+0x29a/0x3c0
[ 276.302925] ? sched_cpu_starting+0xd0/0xd0
[ 276.304034] cpuhp_invoke_callback+0x86/0x5d0
[ 276.305221] ? cpu_disable_common+0x1cf/0x1f0
[ 276.306375] take_cpu_down+0x60/0xa0
[ 276.307330] multi_cpu_stop+0x68/0xe0
[ 276.308308] ? cpu_stopper_thread+0x100/0x100
[ 276.309469] cpu_stopper_thread+0x94/0x100
[ 276.310591] ? smpboot_thread_fn+0x2f/0x1e0
[ 276.311725] ? smpboot_thread_fn+0x74/0x1e0
[ 276.312843] ? smpboot_thread_fn+0x14e/0x1e0
[ 276.313987] smpboot_thread_fn+0x149/0x1e0
[ 276.315077] ? sort_range+0x20/0x20
[ 276.316034] kthread+0x11e/0x140
[ 276.316903] ? kthread_park+0x90/0x90
[ 276.317889] ret_from_fork+0x35/0x40
[ 276.318851] ---[ end trace 4938a64101275eb2 ]---
To reproduce:
# build kernel
cd linux
cp config-5.1.0-10881-gb0f464c .config
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 olddefconfig
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 prepare
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 modules_prepare
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 SHELL=/bin/bash
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 bzImage
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
find lib/ | cpio -o -H newc --quiet | gzip > modules.cgz
bin/lkp qemu -k <bzImage> -m modules.cgz job-script # job-script is attached in this email
Thanks,
lkp
1 year, 5 months