[LKP] [lkp-robot] [rhashtable] 60be2ebf32: INFO:suspicious_RCU_usage

FYI, we noticed the following commit: commit: 60be2ebf326aa90c88e9a967412557d832a1612e ("rhashtable: Add nested tables") url: https://github.com/0day-ci/linux/commits/Herbert-Xu/rhashtable-Handle-tab... in testcase: boot on test machine: qemu-system-x86_64 -enable-kvm -cpu host -smp 2 -m 2G caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace): +-------------------------------------------------+------------+------------+ | | 12f609851b | 60be2ebf32 | +-------------------------------------------------+------------+------------+ | boot_successes | 123 | 5 | | boot_failures | 9 | 8 | | BUG:kernel_reboot-without-warning_in_test_stage | 9 | | | INFO:suspicious_RCU_usage | 0 | 8 | +-------------------------------------------------+------------+------------+ [ 222.678280] [ INFO: suspicious RCU usage. ] [ 222.678280] [ INFO: suspicious RCU usage. ] [ 222.699410] 4.10.0-rc6-00112-g60be2eb #534 Not tainted [ 222.699410] 4.10.0-rc6-00112-g60be2eb #534 Not tainted [ 222.725264] ------------------------------- [ 222.725264] ------------------------------- [ 222.741887] lib/rhashtable.c:1125 suspicious rcu_dereference_check() usage! [ 222.741887] lib/rhashtable.c:1125 suspicious rcu_dereference_check() usage! [ 222.783537] [ 222.783537] other info that might help us debug this: [ 222.783537] [ 222.783537] [ 222.783537] other info that might help us debug this: [ 222.783537] [ 222.823615] [ 222.823615] rcu_scheduler_active = 2, debug_locks = 1 [ 222.823615] [ 222.823615] rcu_scheduler_active = 2, debug_locks = 1 [ 222.856672] 4 locks held by kworker/0:1/19: [ 222.856672] 4 locks held by kworker/0:1/19: [ 222.877765] #0: ("events"){.+.+.+}, at: [<ffffffff810e6bf5>] process_one_work+0x2ac/0x761 [ 222.877765] #0: ("events"){.+.+.+}, at: [<ffffffff810e6bf5>] process_one_work+0x2ac/0x761 [ 222.917921] #1: ((&ht->run_work)){+.+.+.}, at: [<ffffffff810e6c1f>] process_one_work+0x2d6/0x761 [ 222.917921] #1: ((&ht->run_work)){+.+.+.}, at: [<ffffffff810e6c1f>] process_one_work+0x2d6/0x761 [ 222.960763] #2: (&ht->mutex){+.+.+.}, at: [<ffffffff8145b13a>] rht_deferred_worker+0x22/0x26a [ 222.960763] #2: (&ht->mutex){+.+.+.}, at: [<ffffffff8145b13a>] rht_deferred_worker+0x22/0x26a [ 223.004378] #3: (&(&tbl->locks[i])->rlock){+.....}, at: [<ffffffff8145abce>] rhashtable_rehash_table+0xf6/0x640 [ 223.004378] #3: (&(&tbl->locks[i])->rlock){+.....}, at: [<ffffffff8145abce>] rhashtable_rehash_table+0xf6/0x640 [ 223.055769] [ 223.055769] stack backtrace: [ 223.055769] [ 223.055769] stack backtrace: [ 223.078034] CPU: 0 PID: 19 Comm: kworker/0:1 Not tainted 4.10.0-rc6-00112-g60be2eb #534 [ 223.078034] CPU: 0 PID: 19 Comm: kworker/0:1 Not tainted 4.10.0-rc6-00112-g60be2eb #534 [ 223.118613] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014 [ 223.118613] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014 [ 223.169614] Workqueue: events rht_deferred_worker [ 223.169614] Workqueue: events rht_deferred_worker [ 223.193302] Call Trace: [ 223.193302] Call Trace: [ 223.205882] dump_stack+0x19/0x1b [ 223.205882] dump_stack+0x19/0x1b [ 223.222639] lockdep_rcu_suspicious+0xdd/0xf4 [ 223.222639] lockdep_rcu_suspicious+0xdd/0xf4 [ 223.244610] rht_bucket_nested+0x107/0x10c [ 223.244610] rht_bucket_nested+0x107/0x10c [ 223.265382] rhashtable_rehash_table+0x373/0x640 [ 223.265382] rhashtable_rehash_table+0x373/0x640 [ 223.289295] rht_deferred_worker+0x112/0x26a [ 223.289295] rht_deferred_worker+0x112/0x26a [ 223.308573] process_one_work+0x3b7/0x761 [ 223.308573] process_one_work+0x3b7/0x761 [ 223.328696] ? process_one_work+0x2d6/0x761 [ 223.328696] ? process_one_work+0x2d6/0x761 [ 223.349210] worker_thread+0x2aa/0x6a5 [ 223.349210] worker_thread+0x2aa/0x6a5 [ 223.368199] kthread+0x107/0x13a [ 223.368199] kthread+0x107/0x13a [ 223.384633] ? process_one_work+0x761/0x761 [ 223.384633] ? process_one_work+0x761/0x761 [ 223.405721] ? __kthread_create_on_node+0x232/0x232 [ 223.405721] ? __kthread_create_on_node+0x232/0x232 [ 223.430350] ret_from_fork+0x31/0x40 [ 223.430350] ret_from_fork+0x31/0x40 [ 223.448309] [ 223.448309] [ 223.456052] =============================== [ 223.456052] =============================== [ 223.477118] [ INFO: suspicious RCU usage. ] [ 223.477118] [ INFO: suspicious RCU usage. ] [ 223.498249] 4.10.0-rc6-00112-g60be2eb #534 Not tainted [ 223.498249] 4.10.0-rc6-00112-g60be2eb #534 Not tainted [ 223.524590] ------------------------------- [ 223.524590] ------------------------------- [ 223.545889] lib/rhashtable.c:1130 suspicious rcu_dereference_check() usage! [ 223.545889] lib/rhashtable.c:1130 suspicious rcu_dereference_check() usage! [ 223.588207] [ 223.588207] other info that might help us debug this: [ 223.588207] [ 223.588207] [ 223.588207] other info that might help us debug this: [ 223.588207] [ 223.630761] [ 223.630761] rcu_scheduler_active = 2, debug_locks = 1 [ 223.630761] [ 223.630761] rcu_scheduler_active = 2, debug_locks = 1 [ 223.663675] 4 locks held by kworker/0:1/19: [ 223.663675] 4 locks held by kworker/0:1/19: [ 223.684903] #0: ("events"){.+.+.+}, at: [<ffffffff810e6bf5>] process_one_work+0x2ac/0x761 [ 223.684903] #0: ("events"){.+.+.+}, at: [<ffffffff810e6bf5>] process_one_work+0x2ac/0x761 [ 223.727266] #1: ((&ht->run_work)){+.+.+.}, at: [<ffffffff810e6c1f>] process_one_work+0x2d6/0x761 [ 223.727266] #1: ((&ht->run_work)){+.+.+.}, at: [<ffffffff810e6c1f>] process_one_work+0x2d6/0x761 [ 223.772273] #2: (&ht->mutex){+.+.+.}, at: [<ffffffff8145b13a>] rht_deferred_worker+0x22/0x26a [ 223.772273] #2: (&ht->mutex){+.+.+.}, at: [<ffffffff8145b13a>] rht_deferred_worker+0x22/0x26a [ 223.815952] #3: (&(&tbl->locks[i])->rlock){+.....}, at: [<ffffffff8145abce>] rhashtable_rehash_table+0xf6/0x640 [ 223.815952] #3: (&(&tbl->locks[i])->rlock){+.....}, at: [<ffffffff8145abce>] rhashtable_rehash_table+0xf6/0x640 [ 223.867261] [ 223.867261] stack backtrace: [ 223.867261] [ 223.867261] stack backtrace: [ 223.889319] CPU: 0 PID: 19 Comm: kworker/0:1 Not tainted 4.10.0-rc6-00112-g60be2eb #534 [ 223.889319] CPU: 0 PID: 19 Comm: kworker/0:1 Not tainted 4.10.0-rc6-00112-g60be2eb #534 [ 223.929997] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014 [ 223.929997] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014 [ 223.981005] Workqueue: events rht_deferred_worker [ 223.981005] Workqueue: events rht_deferred_worker [ 224.004663] Call Trace: [ 224.004663] Call Trace: [ 224.017225] dump_stack+0x19/0x1b [ 224.017225] dump_stack+0x19/0x1b [ 224.034147] lockdep_rcu_suspicious+0xdd/0xf4 [ 224.034147] lockdep_rcu_suspicious+0xdd/0xf4 [ 224.056211] rht_bucket_nested+0xb1/0x10c [ 224.056211] rht_bucket_nested+0xb1/0x10c [ 224.076497] rhashtable_rehash_table+0x373/0x640 [ 224.076497] rhashtable_rehash_table+0x373/0x640 [ 224.099931] rht_deferred_worker+0x112/0x26a [ 224.099931] rht_deferred_worker+0x112/0x26a [ 224.122059] process_one_work+0x3b7/0x761 [ 224.122059] process_one_work+0x3b7/0x761 [ 224.142702] ? process_one_work+0x2d6/0x761 [ 224.142702] ? process_one_work+0x2d6/0x761 [ 224.163871] worker_thread+0x2aa/0x6a5 [ 224.163871] worker_thread+0x2aa/0x6a5 [ 224.182764] kthread+0x107/0x13a [ 224.182764] kthread+0x107/0x13a [ 224.199164] ? process_one_work+0x761/0x761 [ 224.199164] ? process_one_work+0x761/0x761 [ 224.220351] ? __kthread_create_on_node+0x232/0x232 [ 224.220351] ? __kthread_create_on_node+0x232/0x232 [ 224.244855] ret_from_fork+0x31/0x40 [ 224.244855] ret_from_fork+0x31/0x40 [ 234.724349] Started 10 threads, 0 failed [ 234.724349] Started 10 threads, 0 failed [ 234.962237] test_printf: all 260 tests passed [ 234.962237] test_printf: all 260 tests passed [ 235.933585] test_bitmap: all 460506 tests passed [ 235.933585] test_bitmap: all 460506 tests passed [ 235.974966] test_uuid: all 18 tests passed [ 235.974966] test_uuid: all 18 tests passed [ 236.023025] glob: 64 self-tests passed, 0 failed [ 236.023025] glob: 64 self-tests passed, 0 failed [ 236.048527] rbtree testing [ 236.048527] rbtree testing [ 243.915063] -> 177860 cycles [ 243.915063] -> 177860 cycles [ 245.007005] augmented rbtree testing [ 245.007005] augmented rbtree testing [ 254.991361] -> 225745 cycles [ 254.991361] -> 225745 cycles [ 256.827942] gpio_it87: no device [ 256.827942] gpio_it87: no device [ 256.853015] gpio-mockup: probe of gpio-mockup failed with error -22 [ 256.853015] gpio-mockup: probe of gpio-mockup failed with error -22 [ 256.918377] cr_bllcd: INTEL CARILLO RANCH LPC not found. [ 256.918377] cr_bllcd: INTEL CARILLO RANCH LPC not found. [ 256.946920] cr_bllcd: Carillo Ranch Backlight Driver Initialized. [ 256.946920] cr_bllcd: Carillo Ranch Backlight Driver Initialized. [ 256.997383] vmlfb: initializing [ 256.997383] vmlfb: initializing [ 257.017758] no IO addresses supplied [ 257.017758] no IO addresses supplied [ 257.040767] hgafb: HGA card not detected. [ 257.040767] hgafb: HGA card not detected. [ 257.062684] hgafb: probe of hgafb.0 failed with error -22 [ 257.062684] hgafb: probe of hgafb.0 failed with error -22 [ 257.102876] uvesafb: failed to execute /sbin/v86d [ 257.102876] uvesafb: failed to execute /sbin/v86d [ 257.126137] uvesafb: make sure that the v86d helper is installed and executable [ 257.126137] uvesafb: make sure that the v86d helper is installed and executable To reproduce: git clone git://git.kernel.org/pub/scm/linux/kernel/git/wfg/lkp-tests.git cd lkp-tests bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email Thanks, Xiaolong