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-prev] [day] [month] [year] [list]
Date:   Thu, 19 Apr 2018 11:13:15 +0800
From:   Fengguang Wu <fengguang.wu@...el.com>
To:     Paul Blakey <paulb@...lanox.com>
Cc:     linux-kernel@...r.kernel.org, lkp@...org,
        Thomas Graf <tgraf@...g.ch>,
        Daniel Borkmann <daniel@...earbox.net>,
        Florian Westphal <fw@...len.de>,
        Herbert Xu <herbert@...dor.apana.org.au>
Subject: 499ac3b60f ("test_rhashtable: add test case for rhltable with
 duplicate objects"): WARNING: suspicious RCU usage

Greetings,

0day kernel testing robot got the below dmesg and the first bad commit is

commit 499ac3b60f657dae82055fc81c7b01e6242ac9bc
Author:     Paul Blakey <paulb@...lanox.com>
AuthorDate: Sun Mar 4 17:29:49 2018 +0200
Commit:     David S. Miller <davem@...emloft.net>
CommitDate: Wed Mar 7 10:44:03 2018 -0500

     test_rhashtable: add test case for rhltable with duplicate objects
     
     Tries to insert duplicates in the middle of bucket's chain:
     bucket 1:  [[val 21 (tid=1)]] -> [[ val 1 (tid=2),  val 1 (tid=0) ]]
     
     Reuses tid to distinguish the elements insertion order.
     
     Signed-off-by: Paul Blakey <paulb@...lanox.com>
     Acked-by: Herbert Xu <herbert@...dor.apana.org.au>
     Signed-off-by: David S. Miller <davem@...emloft.net>

d3dcf8eb61  rhashtable: Fix rhlist duplicates insertion
499ac3b60f  test_rhashtable: add test case for rhltable with duplicate objects
+--------------------------------------------------------------------+------------+------------+
|                                                                    | d3dcf8eb61 | 499ac3b60f |
+--------------------------------------------------------------------+------------+------------+
| boot_successes                                                     | 41         | 0          |
| boot_failures                                                      | 0          | 21         |
| WARNING:suspicious_RCU_usage                                       | 0          | 21         |
| lib/test_rhashtable.c:#suspicious_rcu_dereference_protected()usage | 0          | 21         |
+--------------------------------------------------------------------+------------+------------+

[    7.248372] test if its possible to exceed max_size 8192: no, ok
[    7.249256] Average test time: 464870041
[    7.249746] test inserting duplicates
[    7.250234] 
[    7.250425] =============================
[    7.250900] WARNING: suspicious RCU usage
[    7.251392] 4.16.0-rc4-00130-g499ac3b #2 Not tainted
[    7.251971] -----------------------------
[    7.252469] lib/test_rhashtable.c:502 suspicious rcu_dereference_protected() usage!
[    7.253574] 
[    7.253574] other info that might help us debug this:
[    7.253574] 
[    7.254489] 
[    7.254489] rcu_scheduler_active = 2, debug_locks = 1
[    7.255236] no locks held by swapper/0/1.
[    7.255700] 
[    7.255700] stack backtrace:
[    7.256274] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.16.0-rc4-00130-g499ac3b #2
[    7.257148] Call Trace:
[    7.257478]  dump_stack+0xb7/0x107
[    7.257884]  print_ht+0x8f/0x35d
[    7.258286]  test_insert_dup+0x13b/0x1ce
[    7.258745]  ? ___siphash_aligned+0x2b/0x2b
[    7.259369]  ? jhash+0x1bb/0x1bb
[    7.259769]  ? my_hashfn+0x1a/0x1a
[    7.260199]  ? rhashtable_walk_peek+0x78/0x78
[    7.260217]  test_rht_init+0x6f8/0x1212
[    7.260217]  ? ___siphash_aligned+0x2b/0x2b
[    7.260217]  ? initcall_blacklisted+0xb0/0xd3
[    7.260217]  ? kstrtoll+0x9a/0xbc
[    7.260217]  ? kstrtos8+0x69/0x89
[    7.260217]  ? test_insert_dup+0x1ce/0x1ce
[    7.260217]  ? do_early_param+0xb9/0xb9
[    7.260217]  do_one_initcall+0xaa/0x17d
[    7.260217]  ? do_early_param+0xb9/0xb9
[    7.260217]  kernel_init_freeable+0x124/0x1d9
[    7.260217]  ? rest_init+0xc5/0xc5
[    7.260217]  kernel_init+0xb/0x147
[    7.260217]  ? rest_init+0xc5/0xc5
[    7.260217]  ret_from_fork+0x24/0x30
[    7.267845] 
[    7.268075] =============================
[    7.268564] WARNING: suspicious RCU usage
[    7.269031] 4.16.0-rc4-00130-g499ac3b #2 Not tainted
[    7.269622] -----------------------------
[    7.270084] lib/test_rhashtable.c:507 suspicious rcu_dereference_protected() usage!
[    7.271169] 
[    7.271169] other info that might help us debug this:
[    7.271169] 
[    7.272102] 
[    7.272102] rcu_scheduler_active = 2, debug_locks = 1
[    7.272851] no locks held by swapper/0/1.
[    7.273338] 
[    7.273338] stack backtrace:
[    7.273862] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.16.0-rc4-00130-g499ac3b #2
[    7.274723] Call Trace:
[    7.275024]  dump_stack+0xb7/0x107
[    7.275452]  print_ht+0xf4/0x35d
[    7.275856]  test_insert_dup+0x13b/0x1ce
[    7.276085]  ? ___siphash_aligned+0x2b/0x2b
[    7.276085]  ? jhash+0x1bb/0x1bb
[    7.276085]  ? my_hashfn+0x1a/0x1a
[    7.276085]  ? rhashtable_walk_peek+0x78/0x78
[    7.276085]  test_rht_init+0x6f8/0x1212
[    7.276085]  ? ___siphash_aligned+0x2b/0x2b
[    7.276085]  ? initcall_blacklisted+0xb0/0xd3
[    7.276085]  ? kstrtoll+0x9a/0xbc
[    7.276085]  ? kstrtos8+0x69/0x89
[    7.276085]  ? test_insert_dup+0x1ce/0x1ce
[    7.276085]  ? do_early_param+0xb9/0xb9
[    7.276085]  do_one_initcall+0xaa/0x17d
[    7.276085]  ? do_early_param+0xb9/0xb9
[    7.276085]  kernel_init_freeable+0x124/0x1d9
[    7.276085]  ? rest_init+0xc5/0xc5
[    7.276085]  kernel_init+0xb/0x147
[    7.276085]  ? rest_init+0xc5/0xc5
[    7.276085]  ret_from_fork+0x24/0x30
[    7.285387] 
[    7.285589] =============================
[    7.286070] WARNING: suspicious RCU usage
[    7.286550] 4.16.0-rc4-00130-g499ac3b #2 Not tainted
[    7.287121] -----------------------------
[    7.287590] lib/test_rhashtable.c:508 suspicious rcu_dereference_protected() usage!
[    7.288702] 
[    7.288702] other info that might help us debug this:
[    7.288702] 
[    7.289617] 
[    7.289617] rcu_scheduler_active = 2, debug_locks = 1
[    7.290374] no locks held by swapper/0/1.
[    7.290859] 
[    7.290859] stack backtrace:
[    7.291375] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.16.0-rc4-00130-g499ac3b #2
[    7.292239] Call Trace:
[    7.292556]  dump_stack+0xb7/0x107
[    7.292669]  print_ht+0x15e/0x35d
[    7.292669]  test_insert_dup+0x13b/0x1ce
[    7.292669]  ? ___siphash_aligned+0x2b/0x2b
[    7.292669]  ? jhash+0x1bb/0x1bb
[    7.292669]  ? my_hashfn+0x1a/0x1a
[    7.292669]  ? rhashtable_walk_peek+0x78/0x78
[    7.292669]  test_rht_init+0x6f8/0x1212
[    7.292669]  ? ___siphash_aligned+0x2b/0x2b
[    7.292669]  ? initcall_blacklisted+0xb0/0xd3
[    7.292669]  ? kstrtoll+0x9a/0xbc
[    7.292669]  ? kstrtos8+0x69/0x89
[    7.292669]  ? test_insert_dup+0x1ce/0x1ce
[    7.292669]  ? do_early_param+0xb9/0xb9
[    7.292669]  do_one_initcall+0xaa/0x17d
[    7.292669]  ? do_early_param+0xb9/0xb9
[    7.292669]  kernel_init_freeable+0x124/0x1d9
[    7.292669]  ? rest_init+0xc5/0xc5
[    7.292669]  kernel_init+0xb/0x147
[    7.292669]  ? rest_init+0xc5/0xc5
[    7.292669]  ret_from_fork+0x24/0x30
[    7.302814] 
[    7.303002] =============================
[    7.303480] WARNING: suspicious RCU usage
[    7.303942] 4.16.0-rc4-00130-g499ac3b #2 Not tainted
[    7.304550] -----------------------------
[    7.305015] lib/test_rhashtable.c:519 suspicious rcu_dereference_protected() usage!
[    7.306095] 
[    7.306095] other info that might help us debug this:
[    7.306095] 
[    7.306995] 
[    7.306995] rcu_scheduler_active = 2, debug_locks = 1
[    7.307755] no locks held by swapper/0/1.
[    7.308242] 
[    7.308242] stack backtrace:
[    7.308762] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.16.0-rc4-00130-g499ac3b #2
[    7.309626] Call Trace:
[    7.309945]  dump_stack+0xb7/0x107
[    7.310367]  print_ht+0x1f0/0x35d
[    7.310762]  test_insert_dup+0x13b/0x1ce
[    7.311232]  ? ___siphash_aligned+0x2b/0x2b
[    7.311851]  ? jhash+0x1bb/0x1bb
[    7.312228]  ? my_hashfn+0x1a/0x1a
[    7.312228]  ? rhashtable_walk_peek+0x78/0x78
[    7.312228]  test_rht_init+0x6f8/0x1212
[    7.312228]  ? ___siphash_aligned+0x2b/0x2b
[    7.312228]  ? initcall_blacklisted+0xb0/0xd3
[    7.312228]  ? kstrtoll+0x9a/0xbc
[    7.312228]  ? kstrtos8+0x69/0x89
[    7.312228]  ? test_insert_dup+0x1ce/0x1ce
[    7.312228]  ? do_early_param+0xb9/0xb9
[    7.312228]  do_one_initcall+0xaa/0x17d
[    7.312228]  ? do_early_param+0xb9/0xb9
[    7.312228]  kernel_init_freeable+0x124/0x1d9
[    7.312228]  ? rest_init+0xc5/0xc5
[    7.312228]  kernel_init+0xb/0x147
[    7.312228]  ? rest_init+0xc5/0xc5
[    7.312228]  ret_from_fork+0x24/0x30
[    7.320198] 
[    7.320408] =============================
[    7.320870] WARNING: suspicious RCU usage
[    7.321361] 4.16.0-rc4-00130-g499ac3b #2 Not tainted
[    7.321929] -----------------------------
[    7.322398] lib/test_rhashtable.c:529 suspicious rcu_dereference_protected() usage!
[    7.323469] 
[    7.323469] other info that might help us debug this:
[    7.323469] 
[    7.324404] 
[    7.324404] rcu_scheduler_active = 2, debug_locks = 1
[    7.325157] no locks held by swapper/0/1.
[    7.325631] 
[    7.325631] stack backtrace:
[    7.326158] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.16.0-rc4-00130-g499ac3b #2
[    7.326980] Call Trace:
[    7.327293]  dump_stack+0xb7/0x107
[    7.327707]  print_ht+0x2bc/0x35d
[    7.328129]  test_insert_dup+0x13b/0x1ce
[    7.328380]  ? ___siphash_aligned+0x2b/0x2b
[    7.328380]  ? jhash+0x1bb/0x1bb
[    7.328380]  ? my_hashfn+0x1a/0x1a
[    7.328380]  ? rhashtable_walk_peek+0x78/0x78
[    7.328380]  test_rht_init+0x6f8/0x1212
[    7.328380]  ? ___siphash_aligned+0x2b/0x2b
[    7.328380]  ? initcall_blacklisted+0xb0/0xd3
[    7.328380]  ? kstrtoll+0x9a/0xbc
[    7.328380]  ? kstrtos8+0x69/0x89
[    7.328380]  ? test_insert_dup+0x1ce/0x1ce
[    7.328380]  ? do_early_param+0xb9/0xb9
[    7.328380]  do_one_initcall+0xaa/0x17d
[    7.328380]  ? do_early_param+0xb9/0xb9
[    7.328380]  kernel_init_freeable+0x124/0x1d9
[    7.328380]  ? rest_init+0xc5/0xc5
[    7.328380]  kernel_init+0xb/0x147
[    7.328380]  ? rest_init+0xc5/0xc5
[    7.328380]  ret_from_fork+0x24/0x30
[    7.337480] 
[    7.337480] ---- ht: ----
[    7.337480] bucket[1] -> [[ val 1 (tid=0) ]] -> [[ val 21 (tid=1) ]]
[    7.337480] -------------
[    7.339010] 

                                                           # HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start 720b4957c76e21b64ebcfbe2db6628653457a132 661e50bc853209e41a5c14a290ca4decc43cbfd1 --
git bisect  bad 2105ee619597aa7c86e54374d7c18ca66d409546  # 05:17  B      0    11   24   0  Merge 'regmap/for-next' into devel-catchup-201803080312
git bisect  bad 4f9652fad49d3194174fd861d2ba2a008273380b  # 05:27  B      0     2   15   0  Merge 'hid/for-4.16/upstream-fixes' into devel-catchup-201803080312
git bisect  bad ee2902aa03dd3b02a8a7f01b4f8fa5b22e854d26  # 05:50  B      0     1   14   0  Merge 'jkirsher-net-queue/master' into devel-catchup-201803080312
git bisect good 08d0c47a51087fe73203e85d2455078bfeec2eeb  # 06:05  G     11     0    0   0  Merge 'linux-review/Leon-Romanovsky/RDMA-ucma-Limit-possible-option-size/20180308-012724' into devel-catchup-201803080312
git bisect good 70ccbfde7342e3d79f4fce05da08d1b6002d2492  # 06:19  G     11     0    1   1  Merge 'linux-review/Leon-Romanovsky/RDMA-ucma-Check-that-user-doesn-t-overflow-QP-state/20180308-011932' into devel-catchup-201803080312
git bisect good 7cb0d12201b8971cd91400c59e91743f760e3991  # 06:32  G     11     0    4   4  Merge 'linux-nvme/nvme-4.16-rc5' into devel-catchup-201803080312
git bisect good d69242bf203e3123ab722bdaa95e1bc42472d10b  # 06:46  G     11     0    1   1  Merge tag 'mac80211-for-davem-2018-03-02' of git://git.kernel.org/pub/scm/linux/kernel/git/jberg/mac80211
git bisect good b3bf5666a51068ad5ddd89a76ed877101ef3bc16  # 06:55  G     11     0    4   4  hv_netvsc: defer queue selection to VF
git bisect good 25b5cdfcce1b57971840505dfc78556bd12dea6d  # 07:07  G     11     0    1   1  dt-bindings: net: renesas-ravb: Make stream buffer optional
git bisect  bad 2695578b896aea472b2c0dcbe9d92daa71738484  # 07:16  B      0     4   17   0  net: usbnet: fix potential deadlock on 32bit hosts
git bisect  bad 803fafbe0cd522fa6b9e41ca3b96cfb2e2a2222d  # 07:34  B      0     1   14   0  fsl/fman: avoid sleeping in atomic context while adding an address
git bisect  bad 499ac3b60f657dae82055fc81c7b01e6242ac9bc  # 07:50  B      0    11   26   2  test_rhashtable: add test case for rhltable with duplicate objects
git bisect good d3dcf8eb615537526bd42ff27a081d46d337816e  # 07:59  G     10     0    1   1  rhashtable: Fix rhlist duplicates insertion
# first bad commit: [499ac3b60f657dae82055fc81c7b01e6242ac9bc] test_rhashtable: add test case for rhltable with duplicate objects
git bisect good d3dcf8eb615537526bd42ff27a081d46d337816e  # 08:02  G     32     0    3   4  rhashtable: Fix rhlist duplicates insertion
# extra tests with debug options
git bisect  bad 499ac3b60f657dae82055fc81c7b01e6242ac9bc  # 08:12  B      0     3   16   0  test_rhashtable: add test case for rhltable with duplicate objects
# extra tests on HEAD of linux-devel/devel-catchup-201803080312
git bisect  bad 720b4957c76e21b64ebcfbe2db6628653457a132  # 08:18  B      0    13   29   0  0day head guard for 'devel-catchup-201803080312'
# extra tests on tree/branch linux-devel/devel-catchup-201803080233
# extra tests with first bad commit reverted
git bisect good 7c17a6b5257f54bfeba9868af4a2fbbf0ae73c9f  # 15:37  G     11     0    0   0  Revert "test_rhashtable: add test case for rhltable with duplicate objects"

---
0-DAY kernel test infrastructure                Open Source Technology Center
https://lists.01.org/pipermail/lkp                          Intel Corporation

Download attachment "dmesg-yocto-lkp-hsw01-11:20180308074820:x86_64-randconfig-in0-03080359:4.16.0-rc4-00130-g499ac3b:2.gz" of type "application/gzip" (24247 bytes)

Download attachment "dmesg-yocto-lkp-hsw01-25:20180308080106:x86_64-randconfig-in0-03080359:4.16.0-rc4-00129-gd3dcf8e:1.gz" of type "application/gzip" (39654 bytes)

View attachment "reproduce-yocto-lkp-hsw01-11:20180308074820:x86_64-randconfig-in0-03080359:4.16.0-rc4-00130-g499ac3b:2" of type "text/plain" (916 bytes)

View attachment "config-4.16.0-rc4-00130-g499ac3b" of type "text/plain" (127291 bytes)

Powered by blists - more mailing lists

Powered by Openwall GNU/*/Linux Powered by OpenVZ