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>] [day] [month] [year] [list]
Message-ID: <20160815043326.GF19354@yexl-desktop>
Date:	Mon, 15 Aug 2016 12:33:26 +0800
From:	kernel test robot <xiaolong.ye@...el.com>
To:	Jens Axboe <axboe@...com>
Cc:	LKML <linux-kernel@...r.kernel.org>, Jens Axboe <axboe@...com>,
	Jens Axboe <axboe@...nel.dk>, lkp@...org
Subject: [lkp] [writeback]  6792884a02: INFO: suspicious RCU usage. ]


FYI, we noticed the following commit:

https://git.kernel.org/pub/scm/linux/kernel/git/axboe/linux-block.git wb-buf-throttle
commit 6792884a02568aff55010b7f2382d1279c381beb ("writeback: throttle buffered writeback")

in testcase: boot

on test machine: 2 threads qemu-system-x86_64 -enable-kvm -cpu Haswell,+smep,+smap with 1G memory

caused below changes:


+------------------------------+------------+------------+
|                              | 36396533ad | 6792884a02 |
+------------------------------+------------+------------+
| boot_successes               | 14         | 0          |
| boot_failures                | 0          | 13         |
| INFO:suspicious_RCU_usage    | 0          | 13         |
| calltrace:disk_events_workfn | 0          | 6          |
| backtrace:disk_events_workfn | 0          | 7          |
+------------------------------+------------+------------+



[   16.394972] cdrom: Uniform CD-ROM driver Revision: 3.20
[   16.398939] 
[   16.399503] ===============================
[   16.400475] [ INFO: suspicious RCU usage. ]
[   16.401474] 4.8.0-rc1-00008-g6792884 #1 Not tainted
[   16.402636] -------------------------------
[   16.403611] include/linux/cgroup.h:435 suspicious rcu_dereference_check() usage!
[   16.405646] 
[   16.405646] other info that might help us debug this:
[   16.405646] 
[   16.407637] 
[   16.407637] rcu_scheduler_active = 1, debug_locks = 0
[   16.409227] 3 locks held by kworker/1:1/36:
[   16.410203]  #0:  ("events_freezable_power_efficient"){.+.+..}, at: [<ffffffff810fc9f8>] process_one_work+0x151/0x4ee
[   16.412928]  #1:  ((&(&ev->dwork)->work)){+.+...}, at: [<ffffffff810fc9f8>] process_one_work+0x151/0x4ee
[   16.415534]  #2:  (&(&q->__queue_lock)->rlock){-.....}, at: [<ffffffff81788fdd>] cfq_set_request+0x5e/0x2a3
[   16.418132] 
[   16.418132] stack backtrace:
[   16.419340] CPU: 1 PID: 36 Comm: kworker/1:1 Not tainted 4.8.0-rc1-00008-g6792884 #1
[   16.421111] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[   16.423085] Workqueue: events_freezable_power_ disk_events_workfn
[   16.424463]  0000000000000000 ffff88005568b8e8 ffffffff8178e201 ffff880055684940
[   16.426722]  0000000000000001 ffff88005568b918 ffffffff811233ed ffff880055684940
[   16.428896]  ffffffff84091180 0000000000000003 ffff88005efa2800 ffff88005568b938
[   16.431108] Call Trace:
[   16.431849]  [<ffffffff8178e201>] dump_stack+0x82/0xb8
[   16.433066]  [<ffffffff811233ed>] lockdep_rcu_suspicious+0xf7/0x100
[   16.434426]  [<ffffffff817868b4>] bio_blkcg+0x89/0x93
[   16.435609]  [<ffffffff817886b0>] check_blkcg_changed+0x58/0x1b8
[   16.436974]  [<ffffffff81125f22>] ? lock_acquire+0x125/0x1be
[   16.438246]  [<ffffffff81125f22>] ? lock_acquire+0x125/0x1be
[   16.439527]  [<ffffffff8112384e>] ? lock_acquired+0xfc/0x2f2
[   16.440842]  [<ffffffff81788fdd>] ? cfq_set_request+0x5e/0x2a3
[   16.442141]  [<ffffffff81789050>] cfq_set_request+0xd1/0x2a3
[   16.443427]  [<ffffffff82ef9383>] ? _raw_spin_unlock_irq+0x2c/0x3b
[   16.446981]  [<ffffffff811249cd>] ? trace_hardirqs_on_caller+0x17c/0x198
[   16.448376]  [<ffffffff811249f6>] ? trace_hardirqs_on+0xd/0xf
[   16.449625]  [<ffffffff82ef9388>] ? _raw_spin_unlock_irq+0x31/0x3b
[   16.450926]  [<ffffffff8176d278>] ? ioc_create_icq+0x15e/0x175
[   16.452178]  [<ffffffff817634fb>] elv_set_request+0x1f/0x24
[   16.453409]  [<ffffffff81767a6a>] get_request+0x38f/0x77f
[   16.454586]  [<ffffffff8111b513>] ? add_wait_queue_exclusive+0x44/0x44
[   16.455957]  [<ffffffff81767ebf>] blk_get_request+0x65/0xa8
[   16.457171]  [<ffffffff81c67cee>] ide_cd_queue_pc+0x76/0x19d
[   16.458388]  [<ffffffff81125319>] ? __lock_acquire+0x679/0xed1
[   16.459656]  [<ffffffff81c67ffd>] cdrom_check_status+0x51/0x53
[   16.460901]  [<ffffffff81c68c8d>] ide_cdrom_check_events_real+0x20/0x3f
[   16.462786]  [<ffffffff82578877>] cdrom_update_events+0x18/0x21
[   16.464061]  [<ffffffff82578892>] cdrom_check_events+0x12/0x1f
[   16.465806]  [<ffffffff81c66d26>] idecd_check_events+0x1c/0x1e
[   16.467501]  [<ffffffff81778208>] disk_check_events+0x47/0x103
[   16.469056]  [<ffffffff817782e0>] disk_events_workfn+0x1c/0x1e
[   16.470635]  [<ffffffff810fcb19>] process_one_work+0x272/0x4ee
[   16.472205]  [<ffffffff810fd266>] worker_thread+0x1eb/0x2c9
[   16.473479]  [<ffffffff810fd07b>] ? rescuer_thread+0x2b3/0x2b3
[   16.474795]  [<ffffffff81101df3>] kthread+0xc5/0xcd
[   16.475981]  [<ffffffff82ef9e2f>] ret_from_fork+0x1f/0x40
[   16.477233]  [<ffffffff81101d2e>] ? kthread_create_on_node+0x1b0/0x1b0
[   16.479239] Loading iSCSI transport class v2.0-870.
[   16.493146] rdac: device handler registered


FYI, raw QEMU command line is:

	qemu-system-x86_64 -enable-kvm -cpu Haswell,+smep,+smap -kernel /pkg/linux/x86_64-allyesdebian/gcc-6/6792884a02568aff55010b7f2382d1279c381beb/vmlinuz-4.8.0-rc1-00008-g6792884 -append 'ip=::::vm-kbuild-1G-11::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-kbuild-1G-11/boot-1-debian-x86_64-2015-02-07.cgz-6792884a02568aff55010b7f2382d1279c381beb-20160812-33734-ztodsg-0.yaml ARCH=x86_64 kconfig=x86_64-allyesdebian branch=linux-devel/devel-catchup-201608120516 commit=6792884a02568aff55010b7f2382d1279c381beb BOOT_IMAGE=/pkg/linux/x86_64-allyesdebian/gcc-6/6792884a02568aff55010b7f2382d1279c381beb/vmlinuz-4.8.0-rc1-00008-g6792884 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-kbuild-1G/debian-x86_64-2015-02-07.cgz/x86_64-allyesdebian/gcc-6/6792884a02568aff55010b7f2382d1279c381beb/0 LKP_SERVER=inn debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 systemd.log_level=err ignore_loglevel earlyprintk=ttyS0,115200 console=ttyS0,115200 console=tty0 vga=normal rw'  -initrd /fs/sdf1/initrd-vm-kbuild-1G-11 -m 1536 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0,hostfwd=tcp::23010-:22 -boot order=nc -no-reboot -watchdog i6300esb -watchdog-action debug -rtc base=localtime -device virtio-scsi-pci,id=scsi0 -drive file=/fs/sdf1/disk0-vm-kbuild-1G-11,if=none,id=hd0,media=disk,aio=native,cache=none -device scsi-hd,bus=scsi0.0,drive=hd0,scsi-id=1,lun=0 -drive file=/fs/sdf1/disk1-vm-kbuild-1G-11,if=none,id=hd1,media=disk,aio=native,cache=none -device scsi-hd,bus=scsi0.0,drive=hd1,scsi-id=1,lun=1 -drive file=/fs/sdf1/disk2-vm-kbuild-1G-11,if=none,id=hd2,media=disk,aio=native,cache=none -device scsi-hd,bus=scsi0.0,drive=hd2,scsi-id=1,lun=2 -drive file=/fs/sdf1/disk3-vm-kbuild-1G-11,if=none,id=hd3,media=disk,aio=native,cache=none -device scsi-hd,bus=scsi0.0,drive=hd3,scsi-id=1,lun=3 -drive file=/fs/sdf1/disk4-vm-kbuild-1G-11,if=none,id=hd4,media=disk,aio=native,cache=none -device scsi-hd,bus=scsi0.0,drive=hd4,scsi-id=1,lun=4 -pidfile /dev/shm/kboot/pid-vm-kbuild-1G-11 -serial file:/dev/shm/kboot/serial-vm-kbuild-1G-11 -daemonize -display none -monitor null 





Thanks,
Xiaolong

View attachment "config-4.8.0-rc1-00008-g6792884" of type "text/plain" (154263 bytes)

Download attachment "dmesg.xz" of type "application/octet-stream" (31788 bytes)

Powered by blists - more mailing lists

Powered by Openwall GNU/*/Linux Powered by OpenVZ