lists.openwall.net   lists  /  announce  owl-users  owl-dev  john-users  john-dev  passwdqc-users  yescrypt  popa3d-users  /  oss-security  kernel-hardening  musl  sabotage  tlsify  passwords  /  crypt-dev  xvendor  /  Bugtraq  Full-Disclosure  linux-kernel  linux-netdev  linux-ext4  linux-hardening  linux-cve-announce  PHC 
Open Source and information security mailing list archives
 
Hash Suite: Windows password security audit tool. GUI, reports in PDF.
[<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

Powered by Openwall GNU/*/Linux Powered by OpenVZ