[<prev] [next>] [thread-next>] [day] [month] [year] [list]
Message-ID: <20180823023839.GA13343@shao2-debian>
Date: Thu, 23 Aug 2018 10:38:39 +0800
From: kernel test robot <rong.a.chen@...el.com>
To: Joel Fernandes <joel@...lfernandes.org>
Cc: Steven Rostedt <rostedt@...dmis.org>,
Peter Zijlstra <peterz@...radead.org>,
Namhyung Kim <namhyung@...nel.org>,
LKML <linux-kernel@...r.kernel.org>, lkp@...org
Subject: [lkp-robot] [tracing] c3bc8fd637:
WARNING:at_kernel/rcu/tree.c:#rcu_irq_enter
FYI, we noticed the following commit (built with gcc-6):
commit: c3bc8fd637a9623f5c507bd18f9677effbddf584 ("tracing: Centralize preemptirq tracepoints and unify their usage")
https://git.kernel.org/cgit/linux/kernel/git/rostedt/linux-trace.git ftrace/core
in testcase: trinity
with following parameters:
runtime: 300s
test-description: Trinity is a linux system call fuzz tester.
test-url: http://codemonkey.org.uk/projects/trinity/
on test machine: qemu-system-x86_64 -enable-kvm -cpu host -smp 2 -m 4G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+------------------------------------------------------------------+------------+------------+
| | e6753f23d9 | c3bc8fd637 |
+------------------------------------------------------------------+------------+------------+
| boot_successes | 19 | 4 |
| boot_failures | 2 | 15 |
| invoked_oom-killer:gfp_mask=0x | 2 | 3 |
| Mem-Info | 2 | 3 |
| Out_of_memory:Kill_process | 1 | 3 |
| RIP:copy_user_generic_string | 1 | 1 |
| RIP:__clear_user | 1 | |
| Kernel_panic-not_syncing:Out_of_memory_and_no_killable_processes | 1 | 1 |
| WARNING:at_kernel/rcu/tree.c:#rcu_irq_enter | 0 | 12 |
| RIP:rcu_irq_enter | 0 | 12 |
| RIP:arch_local_irq_restore | 0 | 12 |
| WARNING:at_kernel/events/core.c:#perf_event_task_tick | 0 | 12 |
| RIP:perf_event_task_tick | 0 | 12 |
| WARNING:at_kernel/rcu/tree.c:#rcu_irq_exit | 0 | 11 |
| RIP:rcu_irq_exit | 0 | 11 |
| RIP:check_poison_obj | 0 | 1 |
| RIP:get_page_from_freelist | 0 | 1 |
| RIP:locks_remove_posix | 0 | 1 |
+------------------------------------------------------------------+------------+------------+
[ 11.209512] WARNING: CPU: 0 PID: 24 at kernel/rcu/tree.c:999 rcu_irq_enter+0x55/0x77
[ 11.209512] CPU: 0 PID: 24 Comm: kworker/0:1 Tainted: G T 4.18.0-rc6-00023-gc3bc8fd #1
[ 11.209512] Workqueue: events test_mutex_work
[ 11.209512] RIP: 0010:rcu_irq_enter+0x55/0x77
[ 11.209512] Code: 00 00 00 75 27 83 b8 d8 0a 00 00 00 74 1e 80 3d 48 e3 f0 01 00 75 15 48 c7 c7 1a 1a 8b 82 c6 05 38 e3 f0 01 01 e8 fc f5 f9 ff <0f> 0b 48 83 7b 08 00 75 13 65 48 8b 04 25 c0 4e 01 00 c7 80 40 19
[ 11.209512] RSP: 0000:ffff88013f403fc8 EFLAGS: 00010082
[ 11.209512] RAX: 0000000000000000 RBX: ffff88013f419c60 RCX: ffff88013f403e44
[ 11.209512] RDX: 0000000000000003 RSI: 0000000000000003 RDI: 0000000000000046
[ 11.209512] RBP: ffff88013f403fe8 R08: 0000000000000001 R09: 0000000000000001
[ 11.209512] R10: 0000000000000000 R11: ffffffff82c98b38 R12: 0000000000000000
[ 11.209512] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[ 11.209512] FS: 0000000000000000(0000) GS:ffff88013f400000(0000) knlGS:0000000000000000
[ 11.209512] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 11.209512] CR2: 0000000000000000 CR3: 0000000002c57001 CR4: 00000000001606f0
[ 11.209512] Call Trace:
[ 11.209512] <IRQ>
[ 11.209512] irq_enter+0xa/0x60
[ 11.209512] scheduler_ipi+0x4b/0x8a
[ 11.209512] reschedule_interrupt+0xf/0x20
[ 11.209512] </IRQ>
[ 11.209512] RIP: 0010:arch_local_irq_restore+0x2/0x8
[ 11.209512] Code: 15 48 c7 c6 19 8c 8c 82 48 c7 c7 ca 58 8b 82 e8 da 0b fc ff 0f 0b 48 c7 43 58 00 00 00 00 5b c3 9c 58 0f 1f 44 00 00 c3 57 9d <0f> 1f 44 00 00 c3 e8 eb ff ff ff 48 89 c2 fa 66 0f 1f 44 00 00 48
[ 11.209512] RSP: 0000:ffffc900000cbe60 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff02
[ 11.209512] RAX: ffff88013ead8100 RBX: ffff88013ea0aa80 RCX: 0000000000000000
[ 11.209512] RDX: 0000000000000001 RSI: ffffffff83e00190 RDI: 0000000000000246
[ 11.209512] RBP: ffffc900000cbed0 R08: ffffffff83e00190 R09: ffffffff8323b3ec
[ 11.209512] R10: ffffc900000cbdf8 R11: 0000000000000002 R12: ffff88013f4217c0
[ 11.209512] R13: ffff88013f424600 R14: 0000000000000000 R15: 0000000000000000
[ 11.209512] process_one_work+0x1ff/0x4a9
[ 11.209512] ? process_one_work+0x199/0x4a9
[ 11.209512] ? process_scheduled_works+0x2c/0x2c
[ 11.209512] worker_thread+0x1b2/0x261
[ 11.209512] ? process_scheduled_works+0x2c/0x2c
[ 11.209512] kthread+0xf5/0xfa
[ 11.209512] ? queuing_blocked+0x32/0x32
[ 11.209512] ret_from_fork+0x3a/0x50
[ 11.209512] irq event stamp: 71539
[ 11.209512] hardirqs last enabled at (71539): [<0000000000000000>] (null)
[ 11.209512] hardirqs last disabled at (71538): [<0000000000000000>] (null)
[ 11.209512] softirqs last enabled at (71496): [<ffffffff8220030d>] __do_softirq+0x30d/0x38f
[ 11.209512] softirqs last disabled at (71489): [<ffffffff810daae3>] irq_exit+0x63/0xd4
[ 11.209512] _warn_unseeded_randomness: 1 callbacks suppressed
[ 11.209512] random: get_random_bytes called from init_oops_id+0x22/0x31 with crng_init=0
[ 11.209512] ---[ end trace ad4d30e5b1ea3d93 ]---
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
lkp
View attachment "config-4.18.0-rc6-00023-gc3bc8fd" of type "text/plain" (122751 bytes)
View attachment "job-script" of type "text/plain" (3968 bytes)
Download attachment "dmesg.xz" of type "application/x-xz" (20936 bytes)
View attachment "trinity" of type "text/plain" (6305 bytes)
Powered by blists - more mailing lists