[<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