linux-kernel - [test_rhashtable] WARNING: CPU: 0 PID: 1 at lib/debugobjects.c:366 __debug_object

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] [thread-next>] [day] [month] [year] [list]

Message-ID: <20180419030602.e6prycb2gw3agagn@wfg-t540p.sh.intel.com>
Date:   Thu, 19 Apr 2018 11:06:02 +0800
From:   Fengguang Wu <fengguang.wu@...el.com>
To:     Phil Sutter <phil@....cc>
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: [test_rhashtable] WARNING: CPU: 0 PID: 1 at lib/debugobjects.c:366
 __debug_object_init+0x273/0x320

Hello,

FYI this happens in mainline kernel 4.17.0-rc1.
It at least dates back to v4.16 .

It occurs in 2 out of 2 boots.

[  121.028717]   Duration of test: 9447209905 ns
[  122.205613] test if its possible to exceed max_size 8192: no, ok
[  122.256212] Average test time: 10330673927
[  122.258500] test inserting duplicates
[  122.260166] ODEBUG: object is on stack, but not annotated
[  122.261095] WARNING: CPU: 0 PID: 1 at lib/debugobjects.c:366 __debug_object_init+0x273/0x320:
						debug_object_is_on_stack at include/linux/sched/task_stack.h:85
						 (inlined by) __debug_object_init at lib/debugobjects.c:392
[  122.261095] CPU: 0 PID: 1 Comm: swapper Tainted: G                T 4.17.0-rc1 #9
[  122.261095] EIP: __debug_object_init+0x273/0x320:
						debug_object_is_on_stack at include/linux/sched/task_stack.h:85
						 (inlined by) __debug_object_init at lib/debugobjects.c:392
[  122.261095] EFLAGS: 00200002 CPU: 0
[  122.261095] EAX: 41e68f0e EBX: 4011bd60 ECX: 00000001 EDX: 9810de96
[  122.261095] ESI: 41fa8e40 EDI: 5701e6a8 EBP: 4011bcdc ESP: 4011bcb8
[  122.261095]  DS: 007b ES: 007b FS: 0000 GS: 00e0 SS: 0068
[  122.261095] CR0: 80050033 CR2: 00000000 CR3: 021b4000 CR4: 00000690
[  122.261095] Call Trace:
[  122.261095]  ? debug_object_init+0x13/0x16:
						debug_object_init at lib/debugobjects.c:430
[  122.261095]  ? __init_work+0x18/0x1a:
						__init_work at kernel/workqueue.c:505
[  122.261095]  ? rhashtable_init+0x186/0x1be:
						rhashtable_init at lib/rhashtable.c:1038
[  122.261095]  ? rhltable_init+0x11/0x1e:
						rhltable_init at lib/rhashtable.c:1062
[  122.261095]  ? test_insert_dup+0x47/0x239:
						test_insert_dup at lib/test_rhashtable.c:547
[  122.261095]  ? ___siphash_aligned+0x11/0x11
[  122.261095]  ? jhash+0x11d/0x11d:
						jhash2 at include/linux/jhash.h:117
						
						jhash+0x11d/0x11d:
						jhash2 at include/linux/jhash.h:117
						
						jhash+0x11d/0x11d:
						my_hashfn at lib/test_rhashtable.c:83
						
						jhash+0x11d/0x11d:
						jhash2 at include/linux/jhash.h:117
						
						jhash+0x11d/0x11d:
						fl_set_masked_key at net/sched/cls_flower.c:127
						
						jhash+0x11d/0x11d:
						jhash2 at include/linux/jhash.h:117
						
						jhash+0x11d/0x11d:
						inet_frag_destroy_rcu at net/ipv4/inet_fragment.c:115
[  122.261095]  ? my_hashfn+0x14/0x14:
						my_cmpfn at lib/test_rhashtable.c:90
[  122.261095]  ? test_rht_init+0x7b8/0x14a8:
						test_insert_duplicates_run at lib/test_rhashtable.c:593 (discriminator 260)
						 (inlined by) test_rht_init at lib/test_rhashtable.c:748 (discriminator 260)
[  122.261095]  ? sched_clock_local+0xe/0x13b
[  122.261095]  ? ___siphash_aligned+0x11/0x11
[  122.261095]  ? check_chain_key+0x9a/0xef:
						check_chain_key at kernel/locking/lockdep.c:2450 (discriminator 2)
[  122.261095]  ? __lock_acquire+0x487/0x4f8
[  122.261095]  ? lock_release+0x15f/0x178:
						lock_release at kernel/locking/lockdep.c:3943
[  122.261095]  ? add_device_randomness+0x74/0x8c:
						add_device_randomness at drivers/char/random.c:994
[  122.261095]  ? test_insert_dup+0x239/0x239:
						test_rht_init at lib/test_rhashtable.c:693
[  122.261095]  ? do_one_initcall+0x8c/0x1d6:
						do_one_initcall at init/main.c:883
[  122.261095]  ? do_early_param+0xf1/0xf1:
						repair_env_string at init/main.c:250
[  122.261095]  ? kernel_init_freeable+0x11b/0x1fc:
						do_initcall_level at init/main.c:950
						 (inlined by) do_initcalls at init/main.c:959
						 (inlined by) do_basic_setup at init/main.c:977
						 (inlined by) kernel_init_freeable at init/main.c:1127
[  122.261095]  ? rest_init+0xbb/0xbb:
						kernel_init at init/main.c:1050
[  122.261095]  ? kernel_init+0x8/0xd0:
						kernel_init at init/main.c:1055
[  122.261095]  ? ret_from_fork+0x19/0x30:
						ret_from_fork at arch/x86/entry/entry_32.S:311
[  122.261095] Code: 7d 42 83 f8 04 7f 26 8b 15 f0 43 fa 41 8b 92 74 02 00 00 39 d3 0f 82 98 00 00 00 81 c2 00 20 00 00 39 d3 0f 83 8a 00 00 00 eb 6a <0f> 0b 8b 47 08 83 f8 03 74 12 72 07 83 f8 04 74 33 eb 3d c7 47
[  122.261095] ---[ end trace ddc80bc4cbbff90f ]---
[  122.323276]

Attached the full dmesg, kconfig and reproduce scripts.

Thanks,
Fengguang

View attachment "dmesg-vm-lkp-os-yocto-ia32-11:20180418132718:i386-randconfig-c0-04180923:4.17.0-rc1:9" of type "text/plain" (32624 bytes)

View attachment ".config" of type "text/plain" (123815 bytes)

View attachment "job-script" of type "text/plain" (3966 bytes)

View attachment "reproduce-vm-lkp-os-yocto-ia32-11:20180418132718:i386-randconfig-c0-04180923:4.17.0-rc1:9" of type "text/plain" (1883 bytes)