[<prev] [next>] [thread-next>] [day] [month] [year] [list]
Message-ID: <20220312155919.GD1189@xsang-OptiPlex-9020>
Date: Sat, 12 Mar 2022 23:59:19 +0800
From: kernel test robot <oliver.sang@...el.com>
To: "Paul E. McKenney" <paulmck@...nel.org>
Cc: lkp@...ts.01.org, lkp@...el.com,
LKML <linux-kernel@...r.kernel.org>
Subject: [fixup! EXP srcu] 7bf8d644f8:
WARNING:at_kernel/rcu/srcutree.c:#process_srcu
Greeting,
FYI, we noticed the following commit (built with gcc-9):
commit: 7bf8d644f869260f63eaaedebabdd2054f59ecc4 ("fixup! EXP srcu: Check for too many zero-delay srcu_reschedule() calls")
https://github.com/ammarfaizi2/linux-block paulmck/linux-rcu/dev.2022.03.01a
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 16G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
If you fix the issue, kindly add following tag
Reported-by: kernel test robot <oliver.sang@...el.com>
[ 27.088275][ T42] WARNING: CPU: 1 PID: 42 at kernel/rcu/srcutree.c:1555 process_srcu (kernel/rcu/srcutree.c:1555 (discriminator 3))
[ 27.089252][ T42] Modules linked in: libata(+) serio_raw i2c_piix4 parport_pc(+) parport ip_tables
[ 27.090253][ T42] CPU: 1 PID: 42 Comm: kworker/1:1 Not tainted 5.17.0-rc1-00191-g7bf8d644f869 #1
[ 27.091212][ T42] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014
[ 27.092176][ T42] Workqueue: rcu_gp process_srcu
[ 27.092718][ T42] RIP: 0010:process_srcu (kernel/rcu/srcutree.c:1555 (discriminator 3))
[ 27.093653][ T42] Code: 6b f8 48 b8 00 00 00 00 00 fc ff df 48 c1 ea 03 80 3c 02 00 0f 85 91 02 00 00 48 8b 43 f8 31 f6 48 83 f8 32 0f 86 02 fe ff ff <0f> 0b e9 fb fd ff ff 4c 8d b3 58 ff ff ff 48 b8 00 00 00 00 00 fc
All code
========
0: 6b f8 48 imul $0x48,%eax,%edi
3: b8 00 00 00 00 mov $0x0,%eax
8: 00 fc add %bh,%ah
a: ff (bad)
b: df 48 c1 fisttps -0x3f(%rax)
e: ea (bad)
f: 03 80 3c 02 00 0f add 0xf00023c(%rax),%eax
15: 85 91 02 00 00 48 test %edx,0x48000002(%rcx)
1b: 8b 43 f8 mov -0x8(%rbx),%eax
1e: 31 f6 xor %esi,%esi
20: 48 83 f8 32 cmp $0x32,%rax
24: 0f 86 02 fe ff ff jbe 0xfffffffffffffe2c
2a:* 0f 0b ud2 <-- trapping instruction
2c: e9 fb fd ff ff jmpq 0xfffffffffffffe2c
31: 4c 8d b3 58 ff ff ff lea -0xa8(%rbx),%r14
38: 48 rex.W
39: b8 00 00 00 00 mov $0x0,%eax
3e: 00 fc add %bh,%ah
Code starting with the faulting instruction
===========================================
0: 0f 0b ud2
2: e9 fb fd ff ff jmpq 0xfffffffffffffe02
7: 4c 8d b3 58 ff ff ff lea -0xa8(%rbx),%r14
e: 48 rex.W
f: b8 00 00 00 00 mov $0x0,%eax
14: 00 fc add %bh,%ah
[ 27.095572][ T42] RSP: 0018:ffffc90000187dc0 EFLAGS: 00010202
[ 27.096261][ T42] RAX: 0000000000000033 RBX: ffffffffa068dfc0 RCX: ffffffff9dd83100
[ 27.097146][ T42] RDX: 1ffffffff40d1bf7 RSI: 0000000000000000 RDI: ffffffffa068df30
[ 27.098030][ T42] RBP: ffffffffa068dfb8 R08: 0000000000000001 R09: fffff52000030fae
[ 27.098890][ T42] R10: ffffc90000187d6f R11: fffff52000030fad R12: ffffffffa068dea0
[ 27.099740][ T42] R13: 0000000000000033 R14: ffff88839d651418 R15: ffff88839d73bf05
[ 27.100590][ T42] FS: 0000000000000000(0000) GS:ffff88839d700000(0000) knlGS:0000000000000000
[ 27.101533][ T42] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 27.102210][ T42] CR2: 00007f9796b58e21 CR3: 000000015b4a8000 CR4: 00000000000406e0
[ 27.103063][ T42] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[ 27.103906][ T42] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[ 27.104753][ T42] Call Trace:
[ 27.105166][ T42] <TASK>
[ 27.105569][ T42] process_one_work (arch/x86/include/asm/jump_label.h:27 include/linux/jump_label.h:212 include/trace/events/workqueue.h:108 kernel/workqueue.c:2312)
[ 27.106123][ T42] worker_thread (include/linux/list.h:292 kernel/workqueue.c:2455)
[ 27.106626][ T42] ? __kthread_parkme (arch/x86/include/asm/bitops.h:207 (discriminator 4) include/asm-generic/bitops/instrumented-non-atomic.h:135 (discriminator 4) kernel/kthread.c:271 (discriminator 4))
[ 27.107199][ T42] ? schedule (arch/x86/include/asm/bitops.h:207 (discriminator 1) include/asm-generic/bitops/instrumented-non-atomic.h:135 (discriminator 1) include/linux/thread_info.h:118 (discriminator 1) include/linux/sched.h:2132 (discriminator 1) kernel/sched/core.c:6371 (discriminator 1))
[ 27.107696][ T42] ? process_one_work (kernel/workqueue.c:2397)
[ 27.108282][ T42] ? process_one_work (kernel/workqueue.c:2397)
[ 27.108855][ T42] kthread (kernel/kthread.c:377)
[ 27.109331][ T42] ? kthread_complete_and_exit (kernel/kthread.c:332)
[ 27.109923][ T42] ret_from_fork (arch/x86/entry/entry_64.S:301)
[ 27.110421][ T42] </TASK>
[ 27.110802][ T42] ---[ end trace 0000000000000000 ]---
[ OK ] Listening on D-Bus System Message Bus Socket.
[ OK ] Reached target Sockets.
[ OK ] Reached target Basic System.
Starting LSB: OpenIPMI Driver init script...
Starting Login Service...
[ 27.155875][ T198] libata version 3.00 loaded.
[ OK ] Started Regular background program processing daemon.
[ 27.267833][ T190] ACPI: bus type drm_connector registered
Starting System Logging Service...
[ 27.305186][ T198] ata_piix 0000:00:01.1: version 2.13
[ OK ] Started Daily apt download activities.
[ OK ] Started Daily apt upgrade and clean activities.
[ 27.318846][ T198] scsi host0: ata_piix
[ OK ] Reached target Timers.
[ OK ] Started D-Bus System Message Bus.
[ 27.338455][ T198] scsi host1: ata_piix
[ 27.339842][ T198] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc040 irq 14
[ 27.340920][ T198] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc048 irq 15
[ OK ] Started Helper to synchronize boot up for ifupdown.
[ 27.410826][ T191] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer
[ 27.502464][ T223] ata2: found unknown device (class 0)
[ 27.503902][ T223] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
[ 27.523776][ T168] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5
Starting Raise network interfaces...
[ 27.663689][ T235] IPMI message handler: version 39.2
Starting LSB: Load kernel image with kexec...
[ OK ] Started Login Service.
[ 27.689420][ T235] ipmi device interface
[ OK ] Started LSB: Load kernel image with kexec.
[ OK ] Started Raise network interfaces.
[ OK ] Reached target Network.
[ 27.776957][ T190] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console
[ 27.795582][ T190] Console: switching to colour dummy device 80x25
Starting /etc/rc.local Compatibility...
[ 27.810246][ T256] ipmi_si: IPMI System Interface driver
[ 27.814584][ T256] ipmi_si: Unable to find any System Interface(s)
Starting LKP bootstrap...
[ 27.825386][ T190] [drm] Found bochs VGA, ID 0xb0c0.
[ 27.825838][ T190] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebf0000.
[ 27.721831] rc.local[257]: PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/lkp/lkp/src/bin
Starting Permit User Sessions...
[ 27.858471][ T183] ppdev: user-space parallel port driver
[ 27.771982] rc.local[257]: /lkp/lkp/src/bin/lkp-setup-rootfs: 78: /lkp/lkp/src/bin/lkp-setup-rootfs: cannot create /sys/devices/system/cpu/microcode/reload: Directory nonexistent
[ 27.930434][ T190] [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:02.0 on minor 0
Starting OpenBSD Secure Shell server...
[ OK ] Started /etc/rc.local Compatibility.
[ 28.047199][ T190] fbcon: bochs-drmdrmfb (fb0) is primary device
[ 28.067849][ T190] Console: switching to colour frame buffer device 128x48
[ 28.085384][ T190] bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device
[ OK ] Started Permit User Sessions.
[ OK ] Started Getty on tty1.
[ OK ] Reached target Login Prompts.
[FAILED] Failed to start LSB: OpenIPMI Driver init script.
See 'systemctl status openipmi.service' for details.
[ 28.164355][ T196] scsi 1:0:0:0: Attached scsi generic sg0 type 5
[ 28.214927][ T196] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
[ 28.215719][ T196] cdrom: Uniform CD-ROM driver Revision: 3.20
[ 28.238808][ T196] sr 1:0:0:0: Attached scsi CD-ROM sr0
LKP: ttyS0: 265: Kernel tests: Boot OK!
LKP: ttyS0: 265: HOSTNAME vm-snb-157, MAC 52:54:00:12:34:56, kernel 5.17.0-rc1-00191-g7bf8d644f869 1
LKP: ttyS0: 265: /lkp/lkp/src/bin/run-lkp /lkp/jobs/scheduled/vm-snb-157/boot-1-debian-10.4-x86_64-20200603.cgz-7bf8d644f869260f63eaaedebabdd2054f59ecc4-20220310-128355-1v2pw5f-8.yaml
[ 29.609090][ C1] random: crng init done
[ 29.609649][ C1] random: 7 urandom warning(s) missed due to ratelimiting
[ OK ] Started OpenBSD Secure Shell server.
[ 41.804947][ T280] LKP: stdout: 265: Kernel tests: Boot OK!
[ 41.804970][ T280]
LKP: ttyS0: 265: LKP: rebooting forcely
[ 43.635670][ T265] sysrq: Emergency Sync
[ 43.636317][ T52] Emergency Sync complete
[ 43.636892][ T265] sysrq: Resetting
Kboot worker: lkp-worker57
Elapsed time: 60
kvm=(
qemu-system-x86_64
-enable-kvm
-cpu SandyBridge
-kernel $kernel
-initrd initrd-vm-snb-157.cgz
-m 16384
-smp 2
-device e1000,netdev=net0
-netdev user,id=net0,hostfwd=tcp::32032-:22
-boot order=nc
-no-reboot
-watchdog i6300esb
-watchdog-action debug
-rtc base=localtime
-serial stdio
-display none
-monitor null
To reproduce:
# build kernel
cd linux
cp config-5.17.0-rc1-00191-g7bf8d644f869 .config
make HOSTCC=gcc-9 CC=gcc-9 ARCH=x86_64 olddefconfig prepare modules_prepare bzImage modules
make HOSTCC=gcc-9 CC=gcc-9 ARCH=x86_64 INSTALL_MOD_PATH=<mod-install-dir> modules_install
cd <mod-install-dir>
find lib/ | cpio -o -H newc --quiet | gzip > modules.cgz
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> -m modules.cgz job-script # job-script is attached in this email
# if come across any failure that blocks the test,
# please remove ~/.lkp and /lkp dir to run from a clean state.
---
0-DAY CI Kernel Test Service
https://lists.01.org/hyperkitty/list/lkp@lists.01.org
Thanks,
Oliver Sang
View attachment "config-5.17.0-rc1-00191-g7bf8d644f869" of type "text/plain" (165695 bytes)
View attachment "job-script" of type "text/plain" (4872 bytes)
Download attachment "dmesg.xz" of type "application/x-xz" (14504 bytes)
Powered by blists - more mailing lists