[lkp-robot] [perf] c4c23fbd59: perf-sanity-tests.'import_perf'_in_python.fail
by kernel test robot
FYI, we noticed the following commit (built with gcc-6):
commit: c4c23fbd5921fe530ec6a6dcf6e772a8a2d113a1 ("perf: change strncpy+truncation to strlcpy")
https://github.com/0day-ci/linux UPDATE-20180714-184856/Dominique-Martinet/coccinelle-change-strncpy-truncation-to-strlcpy/20180714-082532
in testcase: perf-sanity-tests
with following parameters:
perf_compiler: gcc
on test machine: qemu-system-x86_64 -enable-kvm -cpu kvm64,+ssse3 -smp 2 -m 8G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
2018-07-14 23:39:08 sudo /usr/src/linux-perf-x86_64-randconfig-r0-07141954-c4c23fbd5921fe530ec6a6dcf6e772a8a2d113a1/tools/perf/perf test 18
18: 'import perf' in python : FAILED!
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,
Xiaolong
2 years, 6 months
[lkp-robot] [debugobjects] 3f96d20faf: WARNING:at_kernel/locking/lockdep.c:#register_lock_class
by kernel test robot
FYI, we noticed the following commit (built with gcc-4.9):
commit: 3f96d20fafb19e6dd869362ace53662b06e6f6c1 ("[PATCH] debugobjects: Disable lockdep tracking of debugobjects internal locks")
url: https://github.com/0day-ci/linux/commits/Waiman-Long/debugobjects-Disable...
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-i386 -enable-kvm -m 256M
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+----------------------------------------------------------+------------+------------+
| | 3951dbf232 | 3f96d20faf |
+----------------------------------------------------------+------------+------------+
| boot_successes | 0 | 0 |
| boot_failures | 15 | 8 |
| WARNING:at_mm/page_alloc.c:#__alloc_pages_nodemask | 15 | 8 |
| EIP:__alloc_pages_nodemask | 15 | 8 |
| Mem-Info | 15 | 8 |
| WARNING:at_kernel/locking/lockdep.c:#register_lock_class | 0 | 8 |
| EIP:register_lock_class | 0 | 8 |
+----------------------------------------------------------+------------+------------+
[ 0.003333] WARNING: CPU: 0 PID: 0 at kernel/locking/lockdep.c:704 register_lock_class+0x3f0/0x4e0
[ 0.003333] Modules linked in:
[ 0.003333] CPU: 0 PID: 0 Comm: swapper Not tainted 4.18.0-rc4-00144-g3f96d20 #1
[ 0.003333] EIP: register_lock_class+0x3f0/0x4e0
[ 0.003333] Code: 25 ff ff ff 68 a3 14 a7 81 68 6b fb a4 81 e8 47 48 fc ff 0f 0b 8b 15 4c a3 45 82 58 59 e9 0d ff ff ff 90 8d b4 26 00 00 00 00 <0f> 0b 8b 15 4c a3 45 82 e9 f8 fe ff ff 8d 76 00 8d 54 24 18 89 f8
[ 0.003333] EAX: 81a8242d EBX: 8219b130 ECX: 81d920c0 EDX: 00000000
[ 0.003333] ESI: 00000000 EDI: 81bb4530 EBP: 00000000 ESP: 81b61e38
[ 0.003333] DS: 007b ES: 007b FS: 0000 GS: 0000 SS: 0068 EFLAGS: 00210012
[ 0.003333] CR0: 80050033 CR2: ffbff000 CR3: 01d86000 CR4: 000006b0
[ 0.003333] Call Trace:
[ 0.003333] ? __lock_acquire+0x65/0x1a90
[ 0.003333] ? pcpu_alloc+0x495/0x810
[ 0.003333] ? lock_release+0x126/0x290
[ 0.003333] ? lock_acquire+0xa2/0x190
[ 0.003333] ? __debug_object_init+0x255/0x450
[ 0.003333] ? _raw_spin_lock+0x1f/0x30
[ 0.003333] ? __debug_object_init+0x255/0x450
[ 0.003333] ? __debug_object_init+0x255/0x450
[ 0.003333] ? debug_mutex_init+0x21/0x30
[ 0.003333] ? alloc_desc+0x83/0x120
[ 0.003333] ? early_irq_init+0x14d/0x18b
[ 0.003333] ? start_kernel+0x3fa/0x60c
[ 0.003333] ? startup_32_smp+0x164/0x170
[ 0.003333] irq event stamp: 0
[ 0.003333] hardirqs last enabled at (0): [<00000000>] (null)
[ 0.003333] hardirqs last disabled at (0): [<00000000>] (null)
[ 0.003333] softirqs last enabled at (0): [<00000000>] (null)
[ 0.003333] softirqs last disabled at (0): [<00000000>] (null)
[ 0.003333] random: get_random_bytes called from init_oops_id+0x37/0x40 with crng_init=0
[ 0.003333] ---[ end trace 2ecea7c3f5e2e513 ]---
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,
Xiaolong
2 years, 6 months
[lkp-robot] 9cf57731b6 [ 8.051016] WARNING: CPU: 1 PID: 58 at lib/list_debug.c:28 __list_add_valid
by kernel test robot
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git master
commit 9cf57731b63e37ed995b46690adc604891a9a28f
Author: Peter Zijlstra <peterz(a)infradead.org>
AuthorDate: Thu Jun 7 10:52:03 2018 +0200
Commit: Ingo Molnar <mingo(a)kernel.org>
CommitDate: Tue Jul 3 09:20:43 2018 +0200
watchdog/softlockup: Replace "watchdog/%u" threads with cpu_stop_work
Oleg suggested to replace the "watchdog/%u" threads with
cpu_stop_work. That removes one thread per CPU while at the same time
fixes softlockup vs SCHED_DEADLINE.
But more importantly, it does away with the single
smpboot_update_cpumask_percpu_thread() user, which allows
cleanups/shrinkage of the smpboot interface.
Suggested-by: Oleg Nesterov <oleg(a)redhat.com>
Signed-off-by: Peter Zijlstra (Intel) <peterz(a)infradead.org>
Cc: Linus Torvalds <torvalds(a)linux-foundation.org>
Cc: Peter Zijlstra <peterz(a)infradead.org>
Cc: Thomas Gleixner <tglx(a)linutronix.de>
Cc: linux-kernel(a)vger.kernel.org
Signed-off-by: Ingo Molnar <mingo(a)kernel.org>
4520843dfa Merge branch 'sched/urgent' into sched/core, to pick up fixes
9cf57731b6 watchdog/softlockup: Replace "watchdog/%u" threads with cpu_stop_work
d00d6d9a33 Add linux-next specific files for 20180709
+-----------------------------------------------------+------------+------------+---------------+
| | 4520843dfa | 9cf57731b6 | next-20180709 |
+-----------------------------------------------------+------------+------------+---------------+
| boot_successes | 51 | 13 | 16 |
| boot_failures | 0 | 20 | 26 |
| WARNING:at_lib/list_debug.c:#__list_add_valid | 0 | 20 | 26 |
| RIP:__list_add_valid | 0 | 20 | 26 |
| RIP:ring_buffer_consume | 0 | 19 | 20 |
| WARNING:at_lib/list_debug.c:#__list_del_entry_valid | 0 | 18 | 21 |
| RIP:__list_del_entry_valid | 0 | 20 | 26 |
| RIP:_raw_spin_unlock_irq | 0 | 18 | 21 |
| RIP:active_load_balance_cpu_stop | 0 | 18 | 21 |
| RIP:cpu_stopper_thread | 0 | 14 | 16 |
| INFO:rcu_sched_self-detected_stall_on_CPU | 0 | 16 | 19 |
| RIP:ftrace_likely_update | 0 | 11 | 17 |
| RIP:_raw_spin_lock_irq | 0 | 9 | 17 |
| INFO:task_blocked_for_more_than#seconds | 0 | 10 | 5 |
| Kernel_panic-not_syncing:hung_task:blocked_tasks | 0 | 10 | 5 |
| BUG:unable_to_handle_kernel | 0 | 2 | 7 |
| Oops:#[##] | 0 | 2 | 7 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 2 | 7 |
| Initiating_system_reboot | 0 | 1 | |
| INFO:rcu_sched_detected_stalls_on_CPUs/tasks | 0 | 6 | 1 |
| RIP:sched_clock_local | 0 | 2 | |
| RIP:__lock_acquire | 0 | 3 | 1 |
| RIP:match_held_lock | 0 | 1 | |
| RIP:lock_unpin_lock | 0 | 1 | |
| RIP:sched_clock_cpu | 0 | 1 | |
| RIP:lock_acquired | 0 | 3 | |
| RIP:queued_spin_lock_slowpath | 0 | 3 | |
| RIP:trace_hardirqs_on_caller | 0 | 3 | |
| RIP:trace_hardirqs_off_caller | 0 | 2 | |
| RIP:pvclock_clocksource_read | 0 | 2 | 1 |
| RIP:lock_pin_lock | 0 | 1 | |
| RIP:check_flags | 0 | 3 | |
| RIP:cpumask_next | 0 | 2 | |
| RIP:debug_lockdep_rcu_enabled | 0 | 1 | |
| RIP:lock_acquire | 0 | 2 | |
| RIP:ring_buffer_consumer_thread | 0 | 1 | 2 |
| RIP:do_raw_spin_trylock | 0 | 1 | |
| RIP:mark_held_locks | 0 | 1 | 1 |
| RIP:kvm_clock_read | 0 | 1 | |
| RIP:check_chain_key | 0 | 1 | |
| RIP:lock_release | 0 | 1 | 3 |
| RIP:time_hardirqs_on | 0 | 1 | |
| RIP:rb_next | 0 | 0 | 2 |
| RIP:separate_adjacent_colors | 0 | 0 | 3 |
| RIP:rb_erase | 0 | 0 | 1 |
| RIP:drm_mm_insert_node_in_range | 0 | 0 | 2 |
| RIP:rb_prev | 0 | 0 | 1 |
| calltrace:interrupt_entry | 0 | 0 | 1 |
| calltrace:irq_exit | 0 | 0 | 1 |
+-----------------------------------------------------+------------+------------+---------------+
[ 3.216087] xz_dec_test: Create a device node with 'mknod xz_dec_test c 249 0' and write .xz files to it.
[ 3.937469] tsc: Refined TSC clocksource calibration: 2693.509 MHz
[ 3.938827] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x26d34aa491a, max_idle_ns: 440795256568 ns
[ 8.051016] ------------[ cut here ]------------
[ 8.051016] list_add corruption. prev->next should be next (ffff88001d7dc998), but was 0000000000000000. (prev=ffff88001d7dc9e0).
[ 8.051016] WARNING: CPU: 1 PID: 58 at lib/list_debug.c:28 __list_add_valid+0xc8/0x15c
[ 8.051016] CPU: 1 PID: 58 Comm: rb_consumer Not tainted 4.18.0-rc3-00013-g9cf5773 #2
[ 8.051016] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 8.051016] RIP: 0010:__list_add_valid+0xc8/0x15c
[ 8.051016] Code: 00 00 00 be 01 00 00 00 48 c7 c7 d8 5e f0 82 e8 bf a0 b4 ff 48 8b 55 00 48 89 e9 48 89 de 48 c7 c7 bf 4f 71 82 e8 7c 73 a5 ff <0f> 0b b9 01 00 00 00 31 d2 be 01 00 00 00 48 c7 c7 a8 5e f0 82 e8
[ 8.051016] RSP: 0000:ffff88001d603df8 EFLAGS: 00010086
[ 8.051016] RAX: 0000000000000075 RBX: ffff88001d7dc998 RCX: 0000000000000000
[ 8.051016] RDX: 0000000000000000 RSI: ffffffff8115691b RDI: ffffffff8115691b
[ 8.051016] RBP: ffff88001d7dc9e0 R08: ffff88001ff50cc8 R09: 000000001592d373
[ 8.051016] R10: 0000000000000001 R11: ffffffff843995c8 R12: 0000000000000000
[ 8.051016] R13: ffff88001d7dc9e0 R14: ffff88001d603e58 R15: 0000000000000000
[ 8.051016] FS: 0000000000000000(0000) GS:ffff88001d600000(0000) knlGS:0000000000000000
[ 8.051016] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 8.051016] CR2: 00000000ffffffff CR3: 0000000002a69000 CR4: 00000000000006a0
[ 8.051016] Call Trace:
[ 8.051016] <IRQ>
[ 8.051016] __cpu_stop_queue_work+0x2c/0x53
[ 8.051016] cpu_stop_queue_work+0x60/0x93
[ 8.051016] watchdog_timer_fn+0x66/0x227
[ 8.051016] ? __touch_watchdog+0x17/0x17
[ 8.051016] __run_hrtimer+0x34e/0x62b
[ 8.051016] __hrtimer_run_queues+0x7c/0x88
[ 8.051016] hrtimer_run_queues+0x99/0xcb
[ 8.051016] run_local_timers+0x1a/0x44
[ 8.051016] update_process_times+0x21/0x4a
[ 8.051016] tick_nohz_handler+0x150/0x1aa
[ 8.051016] smp_apic_timer_interrupt+0x1b4/0x3ba
[ 8.051016] apic_timer_interrupt+0xf/0x20
[ 8.051016] </IRQ>
[ 8.051016] RIP: 0010:ring_buffer_consume+0x176/0x1a7
[ 8.051016] Code: e6 b2 c0 00 48 8b 04 24 0f ba e0 09 72 11 48 89 c7 57 9d 0f 1f 44 00 00 e8 d9 f2 f7 ff eb 10 e8 f6 f1 f7 ff 48 8b 3c 24 57 9d <0f> 1f 44 00 00 65 ff 0d a0 fc e4 7e 48 85 ed 74 0e 8a 45 00 83 e0
[ 8.051016] RSP: 0000:ffff88001ff57e50 EFLAGS: 00000207 ORIG_RAX: ffffffffffffff13
[ 8.051016] RAX: ffff88001ff50000 RBX: ffff88001ffb49c8 RCX: 0000000000000000
[ 8.051016] RDX: 0000000000000000 RSI: 0000000000000001 RDI: 0000000000000207
[ 8.051016] RBP: ffff88001e9c4920 R08: ffff88001ff50cc8 R09: 00000000a9142808
[ 8.051016] R10: 0000000000000001 R11: 0000000000000001 R12: 0000000000000000
[ 8.051016] R13: ffff88001ffb49d8 R14: 0000000000000001 R15: ffff88001ffb47c0
[ 8.051016] ring_buffer_consumer_thread+0x102/0x5a2
[ 8.051016] ? ring_buffer_producer_thread+0x5a0/0x5a0
[ 8.051016] kthread+0x145/0x14d
[ 8.051016] ? kthread_flush_work_fn+0xe/0xe
[ 8.051016] ret_from_fork+0x3a/0x50
[ 8.051016] irq event stamp: 4742136
[ 8.051016] hardirqs last enabled at (4742135): [<ffffffff811c521e>] ring_buffer_consume+0x170/0x1a7
[ 8.051016] hardirqs last disabled at (4742136): [<ffffffff81e0090c>] interrupt_entry+0xcc/0x100
[ 8.051016] softirqs last enabled at (4741900): [<ffffffff820004e8>] __do_softirq+0x4e8/0x606
[ 8.051016] softirqs last disabled at (4741893): [<ffffffff810ec87b>] irq_exit+0x63/0x155
[ 8.051016] random: get_random_bytes called from init_oops_id+0x22/0x31 with crng_init=0
[ 8.051016] ---[ end trace e4fc51e8772f52df ]---
[ 12.054018] ------------[ cut here ]------------
[ 12.054018] ------------[ cut here ]------------
[ 12.054018] list_add corruption. prev->next should be next (ffff88001d7dc998), but was 0000000000000000. (prev=ffff88001d7dc9e0).
[ 12.054018] WARNING: CPU: 1 PID: 58 at lib/list_debug.c:28 __list_add_valid+0xc8/0x15c
[ 12.054018] CPU: 1 PID: 58 Comm: rb_consumer Tainted: G W 4.18.0-rc3-00013-g9cf5773 #2
[ 12.054018] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 12.054018] RIP: 0010:__list_add_valid+0xc8/0x15c
[ 12.054018] Code: 00 00 00 be 01 00 00 00 48 c7 c7 d8 5e f0 82 e8 bf a0 b4 ff 48 8b 55 00 48 89 e9 48 89 de 48 c7 c7 bf 4f 71 82 e8 7c 73 a5 ff <0f> 0b b9 01 00 00 00 31 d2 be 01 00 00 00 48 c7 c7 a8 5e f0 82 e8
[ 12.054018] RSP: 0000:ffff88001d603df8 EFLAGS: 00010086
[ 12.054018] RAX: 0000000000000075 RBX: ffff88001d7dc998 RCX: 0000000000000000
[ 12.054018] RDX: 0000000000000000 RSI: ffffffff8115691b RDI: ffffffff8115691b
[ 12.054018] RBP: ffff88001d7dc9e0 R08: ffff88001ff50cc8 R09: 000000001592d373
[ 12.054018] R10: 0000000000000001 R11: ffffffff843995c8 R12: 0000000000000000
[ 12.054018] R13: ffff88001d7dc9e0 R14: ffff88001d603e58 R15: 0000000000000000
[ 12.054018] FS: 0000000000000000(0000) GS:ffff88001d600000(0000) knlGS:0000000000000000
[ 12.054018] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 12.054018] CR2: 00000000ffffffff CR3: 0000000002a69000 CR4: 00000000000006a0
[ 12.054018] Call Trace:
[ 12.054018] <IRQ>
[ 12.054018] __cpu_stop_queue_work+0x2c/0x53
[ 12.054018] cpu_stop_queue_work+0x60/0x93
[ 12.054018] watchdog_timer_fn+0x66/0x227
[ 12.054018] ? __touch_watchdog+0x17/0x17
[ 12.054018] __run_hrtimer+0x34e/0x62b
[ 12.054018] __hrtimer_run_queues+0x7c/0x88
[ 12.054018] hrtimer_run_queues+0x99/0xcb
[ 12.054018] run_local_timers+0x1a/0x44
[ 12.054018] update_process_times+0x21/0x4a
[ 12.054018] tick_nohz_handler+0x150/0x1aa
[ 12.054018] smp_apic_timer_interrupt+0x1b4/0x3ba
[ 12.054018] apic_timer_interrupt+0xf/0x20
[ 12.054018] </IRQ>
[ 12.054018] RIP: 0010:ring_buffer_consume+0x176/0x1a7
[ 12.054018] Code: e6 b2 c0 00 48 8b 04 24 0f ba e0 09 72 11 48 89 c7 57 9d 0f 1f 44 00 00 e8 d9 f2 f7 ff eb 10 e8 f6 f1 f7 ff 48 8b 3c 24 57 9d <0f> 1f 44 00 00 65 ff 0d a0 fc e4 7e 48 85 ed 74 0e 8a 45 00 83 e0
[ 12.054018] RSP: 0000:ffff88001ff57e50 EFLAGS: 00000207 ORIG_RAX: ffffffffffffff13
[ 12.054018] RAX: ffff88001ff50000 RBX: ffff88001ffb4cd0 RCX: 0000000000000000
[ 12.054018] RDX: 0000000000000000 RSI: 0000000000000001 RDI: 0000000000000207
[ 12.054018] RBP: 0000000000000000 R08: ffff88001ff50cc8 R09: 00000000a9142808
[ 12.054018] R10: 0000000000000001 R11: 0000000000000001 R12: 0000000000000001
[ 12.054018] R13: ffff88001ffb4ce0 R14: 0000000000000001 R15: ffff88001ffb47c0
[ 12.054018] ring_buffer_consumer_thread+0x102/0x5a2
[ 12.054018] ? ring_buffer_producer_thread+0x5a0/0x5a0
[ 12.054018] kthread+0x145/0x14d
[ 12.054018] ? kthread_flush_work_fn+0xe/0xe
[ 12.054018] ret_from_fork+0x3a/0x50
[ 12.054018] irq event stamp: 10738208
[ 12.054018] hardirqs last enabled at (10738207): [<ffffffff811c521e>] ring_buffer_consume+0x170/0x1a7
[ 12.054018] hardirqs last disabled at (10738208): [<ffffffff81e0090c>] interrupt_entry+0xcc/0x100
[ 12.054018] softirqs last enabled at (4742146): [<ffffffff820004e8>] __do_softirq+0x4e8/0x606
[ 12.054018] softirqs last disabled at (4742137): [<ffffffff810ec87b>] irq_exit+0x63/0x155
[ 12.054018] ---[ end trace e4fc51e8772f52e0 ]---
[ 13.113255] ------------[ cut here ]------------
[ 13.113255] ------------[ cut here ]------------
[ 13.114012] list_del corruption. next->prev should be ffff88001d7e2558, but was 0000000000000000
[ 13.114012] WARNING: CPU: 1 PID: 16 at lib/list_debug.c:56 __list_del_entry_valid+0x19d/0x1c0
[ 13.114012] CPU: 1 PID: 16 Comm: migration/1 Tainted: G W 4.18.0-rc3-00013-g9cf5773 #2
[ 13.114012] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 13.114012] RIP: 0010:__list_del_entry_valid+0x19d/0x1c0
[ 13.114012] Code: 01 00 00 00 31 d2 be 01 00 00 00 48 c7 c7 08 5c f0 82 e8 8b 9e b4 ff 48 8b 55 08 48 89 de 48 c7 c7 ea 50 71 82 e8 4b 71 a5 ff <0f> 0b b9 01 00 00 00 31 d2 be 01 00 00 00 48 c7 c7 d8 5b f0 82 e9
[ 13.114012] RSP: 0000:ffff88000ff33e80 EFLAGS: 00010086
[ 13.114012] RAX: 0000000000000054 RBX: ffff88001d7e2558 RCX: 0000000000000000
[ 13.114012] RDX: 0000000000000000 RSI: ffffffff8115691b RDI: ffffffff8115691b
[ 13.114012] RBP: ffff88001d7dc9e0 R08: ffff88000ff2ccc8 R09: 000000001592d373
[ 13.114012] R10: 0000000000000001 R11: ffffffff843995c7 R12: 0000000000000000
[ 13.114012] R13: ffff88001d7e2558 R14: 0000000000000001 R15: ffffffff8110e1a8
[ 13.114012] FS: 0000000000000000(0000) GS:ffff88001d600000(0000) knlGS:0000000000000000
[ 13.114012] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 13.114012] CR2: 00000000ffffffff CR3: 0000000002a69000 CR4: 00000000000006a0
[ 13.114012] Call Trace:
[ 13.114012] cpu_stopper_thread+0x47/0x15e
[ 13.114012] ? sort_range+0x1c/0x1c
[ 13.114012] smpboot_thread_fn+0x320/0x339
[ 13.114012] kthread+0x145/0x14d
[ 13.114012] ? kthread_flush_work_fn+0xe/0xe
[ 13.114012] ret_from_fork+0x3a/0x50
[ 13.114012] irq event stamp: 162
[ 13.114012] hardirqs last enabled at (161): [<ffffffff81dd05c2>] _raw_spin_unlock_irqrestore+0x44/0x58
[ 13.114012] hardirqs last disabled at (162): [<ffffffff81dd02d8>] _raw_spin_lock_irq+0x16/0x6d
[ 13.114012] softirqs last enabled at (138): [<ffffffff820004e8>] __do_softirq+0x4e8/0x606
[ 13.114012] softirqs last disabled at (101): [<ffffffff810ec87b>] irq_exit+0x63/0x155
[ 13.114012] ---[ end trace e4fc51e8772f52e1 ]---
[ 16.054013] ------------[ cut here ]------------
[ 16.054013] ------------[ cut here ]------------
[ 16.054013] list_add corruption. prev->next should be next (ffff88001d7dc998), but was 0000000000000000. (prev=ffff88001d7dc9e0).
[ 16.054013] WARNING: CPU: 1 PID: 16 at lib/list_debug.c:28 __list_add_valid+0xc8/0x15c
[ 16.054013] CPU: 1 PID: 16 Comm: migration/1 Tainted: G W 4.18.0-rc3-00013-g9cf5773 #2
[ 16.054013] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 16.054013] RIP: 0010:__list_add_valid+0xc8/0x15c
[ 16.054013] Code: 00 00 00 be 01 00 00 00 48 c7 c7 d8 5e f0 82 e8 bf a0 b4 ff 48 8b 55 00 48 89 e9 48 89 de 48 c7 c7 bf 4f 71 82 e8 7c 73 a5 ff <0f> 0b b9 01 00 00 00 31 d2 be 01 00 00 00 48 c7 c7 a8 5e f0 82 e8
[ 16.054013] RSP: 0000:ffff88001d603df8 EFLAGS: 00010086
[ 16.054013] RAX: 0000000000000075 RBX: ffff88001d7dc998 RCX: 0000000000000000
[ 16.054013] RDX: 0000000000000000 RSI: ffffffff8115691b RDI: ffffffff8115691b
[ 16.054013] RBP: ffff88001d7dc9e0 R08: ffff88000ff2ccc8 R09: 000000001592d373
[ 16.054013] R10: 0000000000000001 R11: ffffffff843995c8 R12: 0000000000000000
[ 16.054013] R13: ffff88001d7dc9e0 R14: ffff88001d603e58 R15: 0000000000000000
[ 16.054013] FS: 0000000000000000(0000) GS:ffff88001d600000(0000) knlGS:0000000000000000
[ 16.054013] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 16.054013] CR2: 00000000ffffffff CR3: 0000000002a69000 CR4: 00000000000006a0
[ 16.054013] Call Trace:
[ 16.054013] <IRQ>
[ 16.054013] __cpu_stop_queue_work+0x2c/0x53
[ 16.054013] cpu_stop_queue_work+0x60/0x93
[ 16.054013] watchdog_timer_fn+0x66/0x227
[ 16.054013] ? __touch_watchdog+0x17/0x17
[ 16.054013] __run_hrtimer+0x34e/0x62b
[ 16.054013] __hrtimer_run_queues+0x7c/0x88
[ 16.054013] hrtimer_run_queues+0x99/0xcb
[ 16.054013] run_local_timers+0x1a/0x44
[ 16.054013] update_process_times+0x21/0x4a
[ 16.054013] tick_nohz_handler+0x150/0x1aa
[ 16.054013] smp_apic_timer_interrupt+0x1b4/0x3ba
[ 16.054013] apic_timer_interrupt+0xf/0x20
[ 16.054013] </IRQ>
[ 16.054013] RIP: 0010:_raw_spin_unlock_irq+0x30/0x39
[ 16.054013] Code: 48 8b 54 24 08 48 89 fb 48 8d 7f 18 be 01 00 00 00 e8 a7 60 37 ff 48 89 df e8 9e a0 37 ff e8 a6 3e 37 ff fb 66 0f 1f 44 00 00 <65> ff 0d 54 49 24 7e 5b c3 0f 1f 44 00 00 55 53 48 89 fd 48 8b 54
[ 16.054013] RSP: 0000:ffff88000ff33e98 EFLAGS: 00000202 ORIG_RAX: ffffffffffffff13
[ 16.054013] RAX: ffff88000ff2c000 RBX: ffff88001d7dc948 RCX: 0000000000000000
[ 16.054013] RDX: 0000000000000000 RSI: 0000000000000001 RDI: ffff88000ff2c000
[ 16.054013] RBP: ffff88001d7dc940 R08: ffff88000ff2ccc8 R09: 000000001592d373
[ 16.054013] R10: 0000000000000001 R11: ffffffff843995c7 R12: ffff88001d7dc948
[ 16.054013] R13: ffffffff8111ee31 R14: ffff88001d7e1b80 R15: 0000000000000000
[ 16.054013] ? attach_task+0xb7/0xb7
[ 16.054013] ? _raw_spin_unlock_irq+0x29/0x39
[ 16.054013] cpu_stopper_thread+0x68/0x15e
[ 16.054013] ? sort_range+0x1c/0x1c
[ 16.054013] smpboot_thread_fn+0x320/0x339
[ 16.054013] kthread+0x145/0x14d
[ 16.054013] ? kthread_flush_work_fn+0xe/0xe
[ 16.054013] ret_from_fork+0x3a/0x50
[ 16.054013] irq event stamp: 8468098
[ 16.054013] hardirqs last enabled at (8468097): [<ffffffff81dd056e>] _raw_spin_unlock_irq+0x29/0x39
[ 16.054013] hardirqs last disabled at (8468098): [<ffffffff81e0090c>] interrupt_entry+0xcc/0x100
[ 16.054013] softirqs last enabled at (138): [<ffffffff820004e8>] __do_softirq+0x4e8/0x606
[ 16.054013] softirqs last disabled at (101): [<ffffffff810ec87b>] irq_exit+0x63/0x155
[ 16.054013] ---[ end trace e4fc51e8772f52e2 ]---
[ 20.054014] ------------[ cut here ]------------
[ 20.054014] ------------[ cut here ]------------
[ 20.054061] list_add corruption. prev->next should be next (ffff88001d7dc998), but was 0000000000000000. (prev=ffff88001d7dc9e0).
[ 20.054061] WARNING: CPU: 1 PID: 16 at lib/list_debug.c:28 __list_add_valid+0xc8/0x15c
[ 20.054061] CPU: 1 PID: 16 Comm: migration/1 Tainted: G W 4.18.0-rc3-00013-g9cf5773 #2
[ 20.054061] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 20.054061] RIP: 0010:__list_add_valid+0xc8/0x15c
[ 20.054061] Code: 00 00 00 be 01 00 00 00 48 c7 c7 d8 5e f0 82 e8 bf a0 b4 ff 48 8b 55 00 48 89 e9 48 89 de 48 c7 c7 bf 4f 71 82 e8 7c 73 a5 ff <0f> 0b b9 01 00 00 00 31 d2 be 01 00 00 00 48 c7 c7 a8 5e f0 82 e8
[ 20.054061] RSP: 0000:ffff88001d603df8 EFLAGS: 00010086
[ 20.054061] RAX: 0000000000000075 RBX: ffff88001d7dc998 RCX: 0000000000000000
[ 20.054061] RDX: 0000000000000000 RSI: ffffffff8115691b RDI: ffffffff8115691b
[ 20.054061] RBP: ffff88001d7dc9e0 R08: ffff88000ff2ccc8 R09: 000000001592d373
[ 20.054061] R10: 0000000000000001 R11: ffffffff843995c8 R12: 0000000000000000
[ 20.054061] R13: ffff88001d7dc9e0 R14: ffff88001d603e58 R15: 0000000000000000
[ 20.054061] FS: 0000000000000000(0000) GS:ffff88001d600000(0000) knlGS:0000000000000000
[ 20.054061] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
# HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start 3fae197885ecb17fc07c970349b8e77fa7a7e75c 021c91791a5e7e85c567452f1be3e4c2c6cb6063 --
git bisect bad 80bcaed152203eb8e2eba66a02f56af0628f9ca1 # 01:59 B 0 2 15 0 Merge 'linux-review/Gustavo-A-R-Silva/HID-hiddev-fix-potential-Spectre-v1/20180630-061205' into devel-hourly-2018070810
git bisect good 9b2b9fd79b12951e0bf8fe27c91a955b3515e8ec # 02:15 G 11 0 0 0 Merge 'linux-review/Sebastian-Andrzej-Siewior/cgroup-use-irqsave-in-cgroup_rstat_flush_locked/20180704-035956' into devel-hourly-2018070810
git bisect bad de6968e0b0ed0141af8175e112c2a5e575c6da30 # 02:25 B 0 1 14 0 Merge 'linux-review/Heiner-Kallweit/net-phy-fix-flag-masking-in-__set_phy_supported/20180704-061809' into devel-hourly-2018070810
git bisect good 90a1e8d8ef9ba14e7527ab6cef523001d551ac14 # 02:40 G 11 0 0 0 Merge 'clk/clk-pxa-32k-pll' into devel-hourly-2018070810
git bisect bad fea5b54b89b8913ae11d3d5923222e0cefe3730f # 02:55 B 0 1 14 0 Merge 'linux-review/Alexey-Kardashevskiy/powerpc-powernv-ioda2-Add-256M-IOMMU-page-size-to-the-default-POWER8-case/20180702-164447' into devel-hourly-2018070810
git bisect good 5fd0cb4f149ca65988821163f6d0240b36579a7a # 03:21 G 10 0 0 0 Merge 'linux-review/Jaegeuk-Kim/f2fs-do-checkpoint-in-kill_sb/20180707-092222' into devel-hourly-2018070810
git bisect good aa7ec2bac0df6c57c4fdd444f986ef977acc8db2 # 03:31 G 11 0 0 0 Merge 'linux-review/Michel-D-nzer/dma-buf-Move-BUG_ON-from-_add_shared_fence-to-_add_shared_inplace/20180705-015352' into devel-hourly-2018070810
git bisect good 6eb3fc78cb1f4bf42bd6c5cf0d84252afedd3ac3 # 03:46 G 10 0 0 0 Merge 'stffrdhrn/travis' into devel-hourly-2018070810
git bisect bad 7796e39daffda3c3446a79ff5f31e54877ef66c7 # 04:01 B 3 3 3 3 Merge 'linux-review/Sabrina-Dubroca/net-ipv6-fix-addrconf_sysctl_addr_gen_mode/20180707-035426' into devel-hourly-2018070810
git bisect bad 030357fee21c049b83eff95f310ee05c64a7b48e # 04:17 B 0 1 14 0 Merge 'linux-review/Jann-Horn/x86-mtrr-don-t-copy-out-of-bounds-data-in-mtrr_write/20180707-062726' into devel-hourly-2018070810
git bisect bad 94bc69ad4959eb4815c3a18660e1a87892d3bdca # 04:27 B 0 1 14 0 Merge branch 'x86/asm'
git bisect good 632b59017a6f749809204bd0b998d698d5a8db56 # 04:44 G 16 0 0 0 Merge branch 'locking/core'
git bisect bad f83ee19be4272564ad592ef90145db7295229490 # 05:11 B 0 1 15 0 kthread: Simplify kthread_park() completion
git bisect good 1cef1150ef40ec52f507436a14230cbc2623299c # 05:26 G 16 0 0 0 kthread, sched/core: Fix kthread_parkme() (again...)
git bisect good 8f894bf47dc9e8b77166125a084a7217693a28cd # 05:44 G 16 0 0 0 sched/debug: Use match_string() helper instead of open-coded logic
git bisect good 4520843dfa34417eb1e2061f60d2345d9ca614e1 # 06:06 G 15 0 0 0 Merge branch 'sched/urgent' into sched/core, to pick up fixes
git bisect bad 167a88677b05d6a810f23b871cfb2b5db1808e60 # 06:19 B 0 1 14 0 smpboot: Remove cpumask from the API
git bisect bad 9cf57731b63e37ed995b46690adc604891a9a28f # 06:30 B 0 2 15 0 watchdog/softlockup: Replace "watchdog/%u" threads with cpu_stop_work
# first bad commit: [9cf57731b63e37ed995b46690adc604891a9a28f] watchdog/softlockup: Replace "watchdog/%u" threads with cpu_stop_work
git bisect good 4520843dfa34417eb1e2061f60d2345d9ca614e1 # 06:36 G 47 0 0 0 Merge branch 'sched/urgent' into sched/core, to pick up fixes
# extra tests with debug options
git bisect bad 9cf57731b63e37ed995b46690adc604891a9a28f # 06:52 B 0 3 16 0 watchdog/softlockup: Replace "watchdog/%u" threads with cpu_stop_work
# extra tests on HEAD of linux-devel/devel-hourly-2018070810
git bisect bad 3fae197885ecb17fc07c970349b8e77fa7a7e75c # 06:52 B 3 356 0 8 0day head guard for 'devel-hourly-2018070810'
# extra tests on tree/branch linux-next/master
git bisect bad d00d6d9a339d613f812e26c59d6b5983faa1af24 # 07:18 B 0 1 14 0 Add linux-next specific files for 20180709
# extra tests with first bad commit reverted
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
2 years, 6 months
[lkp-robot] a951deb502 [ 27.825477] BUG: unable to handle kernel NULL pointer dereference at 00000060
by kernel test robot
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
https://git.kernel.org/pub/scm/linux/kernel/git/luto/linux.git vfs/suid
commit a951deb502bfd6c54b657248a351ed01e563aa99
Author: Andy Lutomirski <luto(a)kernel.org>
AuthorDate: Fri Jul 6 12:50:37 2018 -0700
Commit: Andy Lutomirski <luto(a)kernel.org>
CommitDate: Wed Jul 11 22:44:11 2018 -0700
fs: Pass struct file into should_remove_suid(), etc
This makes no semantic change by itself. It will let us reference
f_cred in should_remove_suid() in a future patch.
Signed-off-by: Andy Lutomirski <luto(a)kernel.org>
b4d0562137 Merge branch 'for-linus' of git://git.kernel.org/pub/scm/linux/kernel/git/s390/linux
a951deb502 fs: Pass struct file into should_remove_suid(), etc
9948027eb2 fs: Kill setuid and setgid when writing regardless of capabilities
+------------------------------------------+------------+------------+------------+
| | b4d0562137 | a951deb502 | 9948027eb2 |
+------------------------------------------+------------+------------+------------+
| boot_successes | 37 | 10 | 1 |
| boot_failures | 7 | 17 | 13 |
| BUG:workqueue_lockup-pool | 6 | | |
| Mem-Info | 1 | | |
| BUG:unable_to_handle_kernel | 0 | 8 | 5 |
| Oops:#[##] | 0 | 17 | 13 |
| EIP:file_needs_remove_privs | 0 | 17 | 10 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 17 | 12 |
| EIP:cap_inode_need_killpriv | 0 | 0 | 3 |
+------------------------------------------+------------+------------+------------+
[ 27.557690] init: tty2 main process ended, respawning
[ 27.571025] init: tty3 main process (418) terminated with status 1
[ 27.582079] init: tty3 main process ended, respawning
[ 27.596183] init: tty6 main process (422) terminated with status 1
[ 27.597179] init: tty6 main process ended, respawning
[ 27.825477] BUG: unable to handle kernel NULL pointer dereference at 00000060
[ 27.826519] *pde = 00000000
[ 27.826944] Oops: 0000 [#1] PREEMPT SMP DEBUG_PAGEALLOC
[ 27.827680] CPU: 1 PID: 452 Comm: trinity-c0 Tainted: G T 4.18.0-rc3-00190-ga951deb5 #164
[ 27.829082] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 27.830325] EIP: file_needs_remove_privs+0x0/0x60
[ 27.831036] Code: ff 84 c0 75 ce 81 e3 00 f0 00 00 81 fb 00 80 00 00 75 c0 89 f0 8b 5d f8 8b 75 fc 89 ec 5d c3 8d 74 26 00 8d bc 27 00 00 00 00 <8b> 50 60 f6 82 a5 01 00 00 10 75 34 55 89 e5 83 ec 08 89 5d f8 89
[ 27.833715] EAX: 00000000 EBX: 00000000 ECX: 3e5a6688 EDX: 00000008
[ 27.834604] ESI: 8dc80840 EDI: 00000000 EBP: 8fcd5f08 ESP: 8fcd5eb8
[ 27.835528] DS: 007b ES: 007b FS: 00d8 GS: 0033 SS: 0068 EFLAGS: 00010246
[ 27.836519] CR0: 80050033 CR2: 00000060 CR3: 18266000 CR4: 00000690
[ 27.837436] Call Trace:
[ 27.837815] ? do_truncate+0x40/0xa0
[ 27.838350] ? generic_permission+0x1c6/0x1f0
[ 27.838971] ? inode_permission+0xc7/0xf0
[ 27.839535] vfs_truncate+0x14a/0x260
[ 27.840062] do_sys_truncate+0x5f/0x90
[ 27.840593] sys_truncate64+0x11/0x20
[ 27.841121] do_int80_syscall_32+0x6e/0x110
[ 27.841743] entry_INT80_32+0x2a/0x2a
[ 27.842294] EIP: 0x6ff301b2
[ 27.842714] Code: 89 c2 31 c0 89 d7 f3 aa 8b 44 24 1c 89 30 c6 40 04 00 83 c4 2c 89 f0 5b 5e 5f 5d c3 90 90 90 90 90 90 90 90 90 90 90 90 cd 80 <c3> 8d b6 00 00 00 00 8d bc 27 00 00 00 00 8b 1c 24 c3 8d b6 00 00
[ 27.846502] EAX: ffffffda EBX: 09f27668 ECX: 000018b7 EDX: 3e5a6688
[ 27.847382] ESI: ffffffb9 EDI: 80000009 EBP: 00000000 ESP: 778ec928
[ 27.848297] DS: 007b ES: 007b FS: 0000 GS: 0033 SS: 007b EFLAGS: 00000246
[ 27.849312] CR2: 0000000000000060
[ 27.856451] ---[ end trace 0ff1ca23a86960c5 ]---
[ 27.857195] EIP: file_needs_remove_privs+0x0/0x60
# HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start 9948027eb271623f46240d063b0204341a506f83 b4d0562137c9035becad5fc0588f6163aed916db --
git bisect bad a951deb502bfd6c54b657248a351ed01e563aa99 # 10:59 B 0 3 20 3 fs: Pass struct file into should_remove_suid(), etc
# first bad commit: [a951deb502bfd6c54b657248a351ed01e563aa99] fs: Pass struct file into should_remove_suid(), etc
git bisect good b4d0562137c9035becad5fc0588f6163aed916db # 11:10 G 33 0 13 16 Merge branch 'for-linus' of git://git.kernel.org/pub/scm/linux/kernel/git/s390/linux
# extra tests on HEAD of luto/vfs/suid
git bisect bad 9948027eb271623f46240d063b0204341a506f83 # 11:10 B 1 11 0 3 fs: Kill setuid and setgid when writing regardless of capabilities
# extra tests on tree/branch luto/vfs/suid
git bisect bad 9948027eb271623f46240d063b0204341a506f83 # 14:11 B 1 11 0 3 fs: Kill setuid and setgid when writing regardless of capabilities
# extra tests with first bad commit reverted
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
2 years, 6 months
[lkp-robot] [xarray] f0b90e702f: BUG:soft_lockup-CPU##stuck_for#s
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: f0b90e702fe74fa575b7382ec3474d341098d5b1 ("xarray: Add XArray unconditional store operations")
https://git.kernel.org/cgit/linux/kernel/git/next/linux-next.git master
in testcase: boot
on test machine: qemu-system-i386 -enable-kvm -cpu Haswell,+smep,+smap -m 360M
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+------------------------------------------------+------------+------------+
| | 3d730c4294 | f0b90e702f |
+------------------------------------------------+------------+------------+
| boot_successes | 0 | 0 |
| boot_failures | 14 | 25 |
| WARNING:at_mm/slab_common.c:#kmalloc_slab | 14 | 25 |
| EIP:kmalloc_slab | 14 | 25 |
| Mem-Info | 14 | 25 |
| INFO:trying_to_register_non-static_key | 14 | 25 |
| BUG:unable_to_handle_kernel | 14 | |
| Oops:#[##] | 14 | |
| EIP:__pci_epf_register_driver | 14 | |
| Kernel_panic-not_syncing:Fatal_exception | 14 | |
| BUG:soft_lockup-CPU##stuck_for#s | 0 | 25 |
| EIP:xa_entry | 0 | 5 |
| Kernel_panic-not_syncing:softlockup:hung_tasks | 0 | 25 |
| EIP:xa_is_node | 0 | 8 |
| EIP:xas_load | 0 | 2 |
| EIP:debug_lockdep_rcu_enabled | 0 | 1 |
| EIP:xa_load | 0 | 3 |
| EIP:xas_descend | 0 | 2 |
| EIP:xa_head | 0 | 1 |
| EIP:xas_start | 0 | 3 |
+------------------------------------------------+------------+------------+
[ 44.036666] watchdog: BUG: soft lockup - CPU#0 stuck for 23s! [swapper/0:1]
[ 44.036666] irq event stamp: 1072387
[ 44.036666] hardirqs last enabled at (1072387): [<4106ebde>] console_unlock+0x3f3/0x42d
[ 44.036666] hardirqs last disabled at (1072386): [<4106e84f>] console_unlock+0x64/0x42d
[ 44.036666] softirqs last enabled at (1072364): [<417ecbeb>] __do_softirq+0x183/0x1b3
[ 44.036666] softirqs last disabled at (1072357): [<41007967>] do_softirq_own_stack+0x1d/0x23
[ 44.036666] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G W 4.18.0-rc3-00012-gf0b90e7 #169
[ 44.036666] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 44.036666] EIP: xa_is_node+0x0/0x1a
[ 44.036666] Code: 89 73 08 89 7b 0c eb 0b 39 43 14 72 0c 8b 75 ec 8b 7d f0 89 73 10 89 7b 14 8d 4d ec 89 d8 e8 88 fe ff ff 5a 59 5b 5e 5f 5d c3 <89> c2 55 83 e2 03 83 fa 02 89 e5 0f 94 c2 3d 00 10 00 00 0f 97 c0
[ 44.036666] EAX: 4c93caf2 EBX: 5442fec0 ECX: 4c93caf2 EDX: 00000001
[ 44.036666] ESI: 00000000 EDI: 00000000 EBP: 5442feb4 ESP: 5442feac
[ 44.036666] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068 EFLAGS: 00200293
[ 44.036666] CR0: 80050033 CR2: 00000000 CR3: 01d27000 CR4: 000406b0
[ 44.036666] Call Trace:
[ 44.036666] ? xas_load+0x26/0x2f
[ 44.036666] ? xa_load+0x35/0x52
[ 44.036666] ? xarray_checks+0x8c2/0x984
[ 44.036666] ? check_xa_tag_1+0x308/0x308
[ 44.036666] ? do_one_initcall+0x6a/0x13c
[ 44.036666] ? parse_args+0xd9/0x1e3
[ 44.036666] ? kernel_init_freeable+0xe1/0x172
[ 44.036666] ? rest_init+0xaf/0xaf
[ 44.036666] ? kernel_init+0x8/0xd0
[ 44.036666] ? ret_from_fork+0x19/0x24
[ 44.036666] Kernel panic - not syncing: softlockup: hung tasks
[ 44.036666] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G W L 4.18.0-rc3-00012-gf0b90e7 #169
[ 44.036666] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 44.036666] Call Trace:
[ 44.036666] ? dump_stack+0x79/0xab
[ 44.036666] ? panic+0x99/0x1d8
[ 44.036666] ? watchdog_timer_fn+0x1ac/0x1d3
[ 44.036666] ? __hrtimer_run_queues+0xa0/0x114
[ 44.036666] ? watchdog+0x16/0x16
[ 44.036666] ? hrtimer_run_queues+0xd2/0xe5
[ 44.036666] ? run_local_timers+0x15/0x39
[ 44.036666] ? update_process_times+0x18/0x39
[ 44.036666] ? tick_nohz_handler+0xba/0xfb
[ 44.036666] ? smp_apic_timer_interrupt+0x54/0x67
[ 44.036666] ? apic_timer_interrupt+0x41/0x48
[ 44.036666] ? siphash_2u64+0x54f/0x7de
[ 44.036666] ? minmax_running_min+0x6f/0x6f
[ 44.036666] ? xas_load+0x26/0x2f
[ 44.036666] ? xa_load+0x35/0x52
[ 44.036666] ? xarray_checks+0x8c2/0x984
[ 44.036666] ? check_xa_tag_1+0x308/0x308
[ 44.036666] ? do_one_initcall+0x6a/0x13c
[ 44.036666] ? parse_args+0xd9/0x1e3
[ 44.036666] ? kernel_init_freeable+0xe1/0x172
[ 44.036666] ? rest_init+0xaf/0xaf
[ 44.036666] ? kernel_init+0x8/0xd0
[ 44.036666] ? ret_from_fork+0x19/0x24
[ 44.036666] Kernel Offset: disabled
Elapsed time: 50
#!/bin/bash
# To reproduce,
# 1) save job-script and this script (both are attached in 0day report email)
# 2) run this script with your compiled kernel and optional env $INSTALL_MOD_PATH
kernel=$1
initrds=(
/osimage/quantal/quantal-core-i386.cgz
/lkp/lkp/lkp-i386.cgz
)
HTTP_PREFIX=https://download.01.org/0day-ci/lkp-qemu
wget --timestamping "${initrds[@]/#/$HTTP_PREFIX}"
{
cat "${initrds[@]//*\//}"
[[ $INSTALL_MOD_PATH ]] && (
cd "$INSTALL_MOD_PATH"
find lib | cpio -o -H newc --quiet | gzip
)
echo job-script | cpio -o -H newc --quiet | gzip
} > initrd.img
kvm=(
qemu-system-i386
-enable-kvm
-cpu Haswell,+smep,+smap
-kernel $kernel
-initrd initrd.img
-m 360
-smp 1
-device e1000,netdev=net0
-netdev user,id=net0
-boot order=nc
-no-reboot
-watchdog i6300esb
-watchdog-action debug
-rtc base=localtime
-serial stdio
-display none
-monitor null
)
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,
Xiaolong
2 years, 6 months
[lkp-robot] 0a8f358b50: BUG:KASAN:null-ptr-deref_in_d
by kernel test robot
FYI, we noticed the following commit (built with gcc-6):
commit: 0a8f358b50be2e336119060ec4f9411f5b8cac28 ("[PATCH v13 1/2] Reorganize the oom report in dump_header")
url: https://github.com/0day-ci/linux/commits/ufo19890607-gmail-com/Reorganize...
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -smp 2 -m 512M
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+------------------------------------------------------------------+------------+------------+
| | 092150a25c | 0a8f358b50 |
+------------------------------------------------------------------+------------+------------+
| boot_successes | 1 | 2 |
| boot_failures | 73 | 74 |
| invoked_oom-killer:gfp_mask=0x | 73 | 74 |
| Mem-Info | 73 | 74 |
| Out_of_memory:Kill_process | 71 | 74 |
| RIP:copy_user_generic_string | 25 | 31 |
| RIP:__clear_user | 1 | 1 |
| Kernel_panic-not_syncing:Out_of_memory_and_no_killable_processes | 7 | |
| RIP:__put_user_4 | 1 | |
| BUG:KASAN:null-ptr-deref_in_d | 0 | 9 |
| BUG:unable_to_handle_kernel | 0 | 9 |
| Oops:#[##] | 0 | 9 |
| RIP:dump_header | 0 | 9 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 9 |
| RIP:do_signal | 0 | 1 |
+------------------------------------------------------------------+------------+------------+
[ 54.798530] BUG: KASAN: null-ptr-deref in dump_header+0x128/0x619
[ 54.798536] Read of size 8 at addr 0000000000000590 by task init/1
[ 54.798541]
[ 54.798549] CPU: 0 PID: 1 Comm: init Not tainted 4.18.0-rc4-00008-g0a8f358 #1
[ 54.798555] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 54.798560] Call Trace:
[ 54.798569] kasan_report+0x225/0x255
[ 54.798578] dump_header+0x128/0x619
[ 54.798587] ? dump_header+0xa8/0x619
[ 54.798596] ? oom_unkillable_task+0x2a/0x48
[ 54.798605] out_of_memory+0x3e4/0x470
[ 54.798614] ? oom_killer_disable+0x190/0x190
[ 54.798622] ? mutex_trylock+0xf3/0x101
[ 54.798632] __alloc_pages_nodemask+0x9cc/0xbda
[ 54.798642] ? gfp_pfmemalloc_allowed+0xae/0xae
[ 54.798651] ? pvclock_clocksource_read+0xbc/0x14b
[ 54.798660] ? check_chain_key+0x140/0x197
[ 54.798669] ? lock_downgrade+0x2a0/0x2a0
[ 54.798677] ? chrdev_open+0x72/0x283
[ 54.798691] kmalloc_order+0x1e/0x5f
[ 54.798701] devkmsg_open+0x44/0x14c
[ 54.798710] chrdev_open+0x246/0x283
[ 54.798718] ? cdev_put+0x2a/0x2a
[ 54.798727] ? cdev_put+0x2a/0x2a
[ 54.798736] do_dentry_open+0x33b/0x54c
[ 54.798745] path_openat+0x9bc/0xc01
[ 54.798754] ? lookup_open+0x85d/0x85d
[ 54.798762] ? kmem_cache_alloc+0x14f/0x170
[ 54.798771] ? do_sys_open+0x159/0x24d
[ 54.798779] ? do_syscall_64+0x271/0x367
[ 54.798788] ? entry_SYSCALL_64_after_hwframe+0x44/0xa9
[ 54.798797] ? do_raw_spin_unlock+0xb2/0xbd
[ 54.798806] ? _raw_spin_unlock+0x1f/0x27
[ 54.798814] ? deactivate_slab+0x59f/0x5ee
[ 54.798823] do_filp_open+0xae/0x127
[ 54.798832] ? path_openat+0xc01/0xc01
[ 54.798841] ? lock_downgrade+0x2a0/0x2a0
[ 54.798849] ? __alloc_fd+0x2f/0x1f8
[ 54.798858] ? _raw_spin_unlock+0x1f/0x27
[ 54.798866] ? __alloc_fd+0x1e6/0x1f8
[ 54.798876] ? do_sys_open+0x189/0x24d
[ 54.798884] do_sys_open+0x189/0x24d
[ 54.798894] ? filp_open+0x3a/0x3a
[ 54.798903] ? do_raw_spin_unlock+0xb2/0xbd
[ 54.798912] ? mem_cgroup_handle_over_high+0x1b/0x111
[ 54.798920] do_syscall_64+0x271/0x367
[ 54.798929] ? syscall_return_slowpath+0xa1/0xa1
[ 54.798937] ? mm_fault_error+0x164/0x164
[ 54.798944] ? schedule+0xd3/0xdf
[ 54.798952] ? exit_to_usermode_loop+0x98/0xb4
[ 54.798962] entry_SYSCALL_64_after_hwframe+0x44/0xa9
[ 54.798969] RIP: 0033:0x7fbbe80b7eb0
[ 54.798974] Code: 48 8b 15 93 0f 2d 00 f7 d8 64 89 02 48 83 c8 ff c3 90 90 90 90 90 90 90 90 90 83 3d 2d 73 2d 00 00 75 10 b8 02 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 31 c3 48 83 ec 08 e8 2e b3 01 00 48 89 04 24
[ 54.799283] RSP: 002b:00007ffdb38380e8 EFLAGS: 00000246 ORIG_RAX: 0000000000000002
[ 54.799295] RAX: ffffffffffffffda RBX: 000055e46b2736f0 RCX: 00007fbbe80b7eb0
[ 54.799302] RDX: 00000000000001b6 RSI: 0000000000000241 RDI: 000055e469bef620
[ 54.799308] RBP: 00007ffdb3838140 R08: 0000000000000000 R09: 000055e469bef61f
[ 54.799315] R10: 00007ffdb3837eb8 R11: 0000000000000246 R12: 000055e469bef61e
[ 54.799322] R13: 000055e469bef620 R14: 0000000000000004 R15: 000055e46b282290
[ 54.799328] ==================================================================
[ 54.799333] Disabling lock debugging due to kernel taint
[ 54.799340] BUG: unable to handle kernel NULL pointer dereference at 0000000000000590
[ 54.799343] PGD 0 P4D 0
[ 54.799351] Oops: 0000 [#1] KASAN PTI
[ 54.799357] CPU: 0 PID: 1 Comm: init Tainted: G B 4.18.0-rc4-00008-g0a8f358 #1
[ 54.799360] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 54.799366] RIP: 0010:dump_header+0x128/0x619
[ 54.799368] Code: 2e 3b bc 45 31 c9 45 31 c0 b9 02 00 00 00 31 d2 31 f6 48 c7 c7 c0 1b 89 bd e8 fd 83 f5 ff 49 8d bf 90 05 00 00 e8 36 df 05 00 <4d> 8b a7 90 05 00 00 49 8d 7c 24 04 e8 22 de 05 00 45 8b 64 24 04
[ 54.799493] RSP: 0000:ffff8800170976c8 EFLAGS: 00010256
[ 54.799499] RAX: 0000000000000296 RBX: ffff8800170978f8 RCX: ffffffffbc30f263
[ 54.799503] RDX: ffffed0002e12eba RSI: 0000000000000003 RDI: 0000000000000296
[ 54.799507] RBP: ffff880017097728 R08: dffffc0000000000 R09: 0000000000000000
[ 54.799510] R10: ffffed0002e12ea2 R11: 0000000000000001 R12: 0000000000000000
[ 54.799514] R13: ffff880017097900 R14: 0000000000000000 R15: 0000000000000000
[ 54.799519] FS: 00007fbbe9035700(0000) GS:ffffffffbd674000(0000) knlGS:0000000000000000
[ 54.799523] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 54.799527] CR2: 0000000000000590 CR3: 000000001ec2a000 CR4: 00000000000006b0
[ 54.799532] Call Trace:
[ 54.799538] ? dump_header+0xa8/0x619
[ 54.799544] ? oom_unkillable_task+0x2a/0x48
[ 54.799550] out_of_memory+0x3e4/0x470
[ 54.799556] ? oom_killer_disable+0x190/0x190
[ 54.799561] ? mutex_trylock+0xf3/0x101
[ 54.799568] __alloc_pages_nodemask+0x9cc/0xbda
[ 54.799575] ? gfp_pfmemalloc_allowed+0xae/0xae
[ 54.799580] ? pvclock_clocksource_read+0xbc/0x14b
[ 54.799586] ? check_chain_key+0x140/0x197
[ 54.799593] ? lock_downgrade+0x2a0/0x2a0
[ 54.799598] ? chrdev_open+0x72/0x283
[ 54.799603] kmalloc_order+0x1e/0x5f
[ 54.799609] devkmsg_open+0x44/0x14c
[ 54.799615] chrdev_open+0x246/0x283
[ 54.799621] ? cdev_put+0x2a/0x2a
[ 54.799627] ? cdev_put+0x2a/0x2a
[ 54.799633] do_dentry_open+0x33b/0x54c
[ 54.799639] path_openat+0x9bc/0xc01
[ 54.799645] ? lookup_open+0x85d/0x85d
[ 54.799650] ? kmem_cache_alloc+0x14f/0x170
[ 54.799656] ? do_sys_open+0x159/0x24d
[ 54.799661] ? do_syscall_64+0x271/0x367
[ 54.799667] ? entry_SYSCALL_64_after_hwframe+0x44/0xa9
[ 54.799673] ? do_raw_spin_unlock+0xb2/0xbd
[ 54.799679] ? _raw_spin_unlock+0x1f/0x27
[ 54.799684] ? deactivate_slab+0x59f/0x5ee
[ 54.799696] do_filp_open+0xae/0x127
[ 54.799701] ? path_openat+0xc01/0xc01
[ 54.799707] ? lock_downgrade+0x2a0/0x2a0
[ 54.799713] ? __alloc_fd+0x2f/0x1f8
[ 54.799719] ? _raw_spin_unlock+0x1f/0x27
[ 54.799724] ? __alloc_fd+0x1e6/0x1f8
[ 54.799730] ? do_sys_open+0x189/0x24d
[ 54.799736] do_sys_open+0x189/0x24d
[ 54.799742] ? filp_open+0x3a/0x3a
[ 54.799748] ? do_raw_spin_unlock+0xb2/0xbd
[ 54.799755] ? mem_cgroup_handle_over_high+0x1b/0x111
[ 54.799760] do_syscall_64+0x271/0x367
[ 54.799765] ? syscall_return_slowpath+0xa1/0xa1
[ 54.799771] ? mm_fault_error+0x164/0x164
[ 54.799775] ? schedule+0xd3/0xdf
[ 54.799781] ? exit_to_usermode_loop+0x98/0xb4
[ 54.799787] entry_SYSCALL_64_after_hwframe+0x44/0xa9
[ 54.799791] RIP: 0033:0x7fbbe80b7eb0
[ 54.799793] Code: 48 8b 15 93 0f 2d 00 f7 d8 64 89 02 48 83 c8 ff c3 90 90 90 90 90 90 90 90 90 83 3d 2d 73 2d 00 00 75 10 b8 02 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 31 c3 48 83 ec 08 e8 2e b3 01 00 48 89 04 24
[ 54.799918] RSP: 002b:00007ffdb38380e8 EFLAGS: 00000246 ORIG_RAX: 0000000000000002
[ 54.799924] RAX: ffffffffffffffda RBX: 000055e46b2736f0 RCX: 00007fbbe80b7eb0
[ 54.799928] RDX: 00000000000001b6 RSI: 0000000000000241 RDI: 000055e469bef620
[ 54.799931] RBP: 00007ffdb3838140 R08: 0000000000000000 R09: 000055e469bef61f
[ 54.799935] R10: 00007ffdb3837eb8 R11: 0000000000000246 R12: 000055e469bef61e
[ 54.799939] R13: 000055e469bef620 R14: 0000000000000004 R15: 000055e46b282290
[ 54.799942] Modules linked in:
[ 54.799947] CR2: 0000000000000590
[ 54.799954] ---[ end trace 0420af0cc87dd67b ]---
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,
Xiaolong
2 years, 6 months
[lkp-robot] 66d38dea23 [ 15.598409] WARNING: CPU: 0 PID: 408 at kernel/locking/lockdep.c:3826 check_flags
by kernel test robot
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
https://github.com/0day-ci/linux/commits/Joel-Fernandes/Centralize-and-un...
commit 66d38dea23d84646e36b9ddcda9a64f032b051a1
Author: Joel Fernandes (Google) <joel(a)joelfernandes.org>
AuthorDate: Thu Jun 28 11:21:47 2018 -0700
Commit: 0day robot <lkp(a)intel.com>
CommitDate: Fri Jun 29 04:26:15 2018 +0800
tracing: Centralize preemptirq tracepoints and unify their usage
This patch detaches the preemptirq tracepoints from the tracers and
keeps it separate.
Advantages:
* Lockdep and irqsoff event can now run in parallel since they no longer
have their own calls.
* This unifies the usecase of adding hooks to an irqsoff and irqson
event, and a preemptoff and preempton event.
3 users of the events exist:
- Lockdep
- irqsoff and preemptoff tracers
- irqs and preempt trace events
The unification cleans up several ifdefs and makes the code in preempt
tracer and irqsoff tracers simpler. It gets rid of all the horrific
ifdeferry around PROVE_LOCKING and makes configuration of the different
users of the tracepoints more easy and understandable. It also gets rid
of the time_* function calls from the lockdep hooks used to call into
the preemptirq tracer which is not needed anymore. The negative delta in
lines of code in this patch is quite large too.
In the patch we introduce a new CONFIG option PREEMPTIRQ_TRACEPOINTS
as a single point for registering probes onto the tracepoints. With
this,
the web of config options for preempt/irq toggle tracepoints and its
users becomes:
PREEMPT_TRACER PREEMPTIRQ_EVENTS IRQSOFF_TRACER PROVE_LOCKING
| | \ | |
\ (selects) / \ \ (selects) /
TRACE_PREEMPT_TOGGLE ----> TRACE_IRQFLAGS
\ /
\ (depends on) /
PREEMPTIRQ_TRACEPOINTS
One note, I have to check for lockdep recursion in the code that calls
the trace events API and bail out if we're in lockdep recursion
protection to prevent something like the following case: a spin_lock is
taken. Then lockdep_acquired is called. That does a raw_local_irq_save
and then sets lockdep_recursion, and then calls __lockdep_acquired. In
this function, a call to get_lock_stats happens which calls
preempt_disable, which calls trace IRQS off somewhere which enters my
tracepoint code and sets the tracing_irq_cpu flag to prevent recursion.
This flag is then never cleared causing lockdep paths to never be
entered and thus causing splats and other bad things.
Other than the performance tests mentioned in the previous patch, I also
ran the locking API test suite. I verified that all tests cases are
passing.
I also injected issues by not registering lockdep probes onto the
tracepoints and I see failures to confirm that the probes are indeed
working.
This series + lockdep probes not registered (just to inject errors):
[ 0.000000] hard-irqs-on + irq-safe-A/21: ok | ok | ok |
[ 0.000000] soft-irqs-on + irq-safe-A/21: ok | ok | ok |
[ 0.000000] sirq-safe-A => hirqs-on/12:FAILED|FAILED| ok |
[ 0.000000] sirq-safe-A => hirqs-on/21:FAILED|FAILED| ok |
[ 0.000000] hard-safe-A + irqs-on/12:FAILED|FAILED| ok |
[ 0.000000] soft-safe-A + irqs-on/12:FAILED|FAILED| ok |
[ 0.000000] hard-safe-A + irqs-on/21:FAILED|FAILED| ok |
[ 0.000000] soft-safe-A + irqs-on/21:FAILED|FAILED| ok |
[ 0.000000] hard-safe-A + unsafe-B #1/123: ok | ok | ok |
[ 0.000000] soft-safe-A + unsafe-B #1/123: ok | ok | ok |
With this series + lockdep probes registered, all locking tests pass:
[ 0.000000] hard-irqs-on + irq-safe-A/21: ok | ok | ok |
[ 0.000000] soft-irqs-on + irq-safe-A/21: ok | ok | ok |
[ 0.000000] sirq-safe-A => hirqs-on/12: ok | ok | ok |
[ 0.000000] sirq-safe-A => hirqs-on/21: ok | ok | ok |
[ 0.000000] hard-safe-A + irqs-on/12: ok | ok | ok |
[ 0.000000] soft-safe-A + irqs-on/12: ok | ok | ok |
[ 0.000000] hard-safe-A + irqs-on/21: ok | ok | ok |
[ 0.000000] soft-safe-A + irqs-on/21: ok | ok | ok |
[ 0.000000] hard-safe-A + unsafe-B #1/123: ok | ok | ok |
[ 0.000000] soft-safe-A + unsafe-B #1/123: ok | ok | ok |
Reviewed-by: Namhyung Kim <namhyung(a)kernel.org>
Signed-off-by: Joel Fernandes (Google) <joel(a)joelfernandes.org>
02b8172ab8 tracepoint: Make rcuidle tracepoint callers use SRCU
66d38dea23 tracing: Centralize preemptirq tracepoints and unify their usage
ec96d36953 kselftests: Add tests for the preemptoff and irqsoff tracers
+--------------------------------------------------+------------+------------+------------+
| | 02b8172ab8 | 66d38dea23 | ec96d36953 |
+--------------------------------------------------+------------+------------+------------+
| boot_successes | 27 | 7 | 6 |
| boot_failures | 2 | 19 | 13 |
| invoked_oom-killer:gfp_mask=0x | 2 | 0 | 1 |
| Mem-Info | 2 | 2 | 5 |
| Out_of_memory:Kill_process | 2 | 0 | 2 |
| WARNING:at_kernel/locking/lockdep.c:#check_flags | 0 | 19 | 11 |
| EIP:check_flags | 0 | 19 | 11 |
| BUG:workqueue_lockup-pool | 0 | 1 | |
+--------------------------------------------------+------------+------------+------------+
Starting udev
[ 14.371368] udevd (175) used greatest stack depth: 6448 bytes left
[ 14.562071] udevadm (178) used greatest stack depth: 6360 bytes left
[ 15.596283] ------------[ cut here ]------------
[ 15.598395] DEBUG_LOCKS_WARN_ON(current->hardirqs_enabled)
[ 15.598409] WARNING: CPU: 0 PID: 408 at kernel/locking/lockdep.c:3826 check_flags+0x1b2/0x1c0
[ 15.599938] CPU: 0 PID: 408 Comm: mount Tainted: G S T 4.18.0-rc2-00093-g66d38de #2
[ 15.600743] EIP: check_flags+0x1b2/0x1c0
[ 15.601177] Code: 24 04 c7 04 24 37 8d 36 b2 e8 9a 26 fc ff 0f 0b e9 ea fe ff ff b8 82 a9 37 b2 89 44 24 04 c7 04 24 37 8d 36 b2 e8 7e 26 fc ff <0f> 0b e9 58 ff ff ff 8d b4 26 00 00 00 00 8b 15 0c 39 73 b2 8b 0d
[ 15.602982] EAX: 0000002e EBX: b327048c ECX: b1045be0 EDX: 00000000
[ 15.603548] ESI: b327048c EDI: 00000000 EBP: 00000000 ESP: b7499dbc
[ 15.604129] DS: 007b ES: 007b FS: 0000 GS: 00e0 SS: 0068 EFLAGS: 00010086
[ 15.604742] CR0: 80050033 CR2: a7fce000 CR3: 07c64000 CR4: 001406d0
[ 15.605316] Call Trace:
[ 15.605552] ? lock_acquire+0x121/0x220
[ 15.605916] ? ntfs_fill_super+0x1fd/0x333f
[ 15.606303] ? unregister_shrinker+0x50/0x60
[ 15.606697] ? _raw_spin_lock_irqsave+0x77/0xd0
[ 15.607122] ? __debug_check_no_obj_freed+0x72/0x1a0
[ 15.607581] ? __debug_check_no_obj_freed+0x72/0x1a0
[ 15.608043] ? unregister_shrinker+0x50/0x60
[ 15.608441] ? kfree+0xc5/0x1e0
[ 15.608736] ? unregister_shrinker+0x50/0x60
[ 15.609135] ? deactivate_locked_super+0x3f/0x80
[ 15.609558] ? mount_bdev+0x1ae/0x1c0
[ 15.609904] ? ntfs_mount+0x1e/0x30
[ 15.610231] ? ntfs_put_super+0x6f0/0x6f0
[ 15.610601] ? mount_fs+0x3e/0x180
[ 15.610987] ? __init_waitqueue_head+0x3c/0x60
[ 15.611397] ? lock_mount+0xf0/0xf0
[ 15.611723] ? alloc_vfsmnt+0x132/0x1a0
[ 15.612085] ? vfs_kern_mount+0x55/0x180
[ 15.612448] ? _raw_read_unlock+0x20/0x30
[ 15.612827] ? do_mount+0x1bf/0xc60
[ 15.613152] ? memdup_user+0x36/0x70
[ 15.613485] ? ksys_mount+0x7e/0xe0
[ 15.613968] ? do_int80_syscall_32+0x88/0x3c0
[ 15.614626] ? entry_INT80_32+0x36/0x36
[ 15.614992] irq event stamp: 7685
[ 15.615299] hardirqs last enabled at (7685): [<00000000>] (null)
[ 15.615873] hardirqs last disabled at (7684): [<00000000>] (null)
[ 15.616441] softirqs last enabled at (7542): [<b1fee7ee>] __do_softirq+0x35e/0x401
[ 15.617138] softirqs last disabled at (7525): [<b101dfe6>] do_softirq_own_stack+0x26/0x40
[ 15.617880] ---[ end trace a588cc6ebe8cb7a7 ]---
[ 15.618303] possible reason: unannotated irqs-off.
# HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start 9edd8c0fbaf2eedee5a4bdca9614ac9e4001f04e 021c91791a5e7e85c567452f1be3e4c2c6cb6063 --
git bisect good 6b439cd1bce83b1dcd667834065d07176ce63225 # 11:14 G 10 0 6 6 Merge 'linux-review/Jakub-Kicinski/nfp-flower-updates-and-netconsole/20180630-082222' into devel-hourly-2018070705
git bisect bad 8ad84b5ba11a59c25bd85977aab5a9cfd0745f5e # 11:25 B 0 5 18 0 Merge 'linux-review/Wei-Yongjun/hinic-reset-irq-affinity-before-freeing-irq/20180627-175000' into devel-hourly-2018070705
git bisect good 4de869ad26884f60d5cba2334c9d2fa20115a017 # 11:42 G 11 0 9 9 Merge 'linux-review/Antoine-Tenart/crypto-inside-secure-EIP197d-support/20180629-030050' into devel-hourly-2018070705
git bisect bad 1a7371a238480d2431e1f85ed3b4910db339564d # 11:56 B 0 5 18 0 Merge 'linux-review/Gustavo-A-R-Silva/bnx2x-Mark-expected-switch-fall-throughs/20180628-093414' into devel-hourly-2018070705
git bisect bad 079678c5cbe6d4e43c41e486380f17e59e05a2a5 # 12:13 B 0 1 14 0 Merge 'linux-review/Andrew-Jones/arm64-acpi-reenumerate-topology-ids/20180629-000826' into devel-hourly-2018070705
git bisect bad d83308ef0d71077c3ea37ba4f623e178ce122429 # 12:45 B 0 9 22 0 Merge 'linux-review/Colin-King/ocfs2-make-several-functions-and-variables-static-and-some-const/20180629-002109' into devel-hourly-2018070705
git bisect good c897f855045bcbf0eca6ddc331b11fd4c3b1d5d4 # 12:56 G 11 0 8 8 Merge 'linux-review/Charles-Keepax/mfd-arizona-Don-t-use-regmap_read_poll_timeout/20180629-023626' into devel-hourly-2018070705
git bisect good d5d24de470249244ed6ad9907938c0943f1e5a4b # 13:23 G 11 0 7 7 Merge 'linux-review/Brad-Love/em28xx-Fix-dual-transport-stream-operation/20180629-032508' into devel-hourly-2018070705
git bisect bad f491e7d2b7c2a778ddfc1b10b15e013a805093a3 # 13:40 B 0 7 20 0 Merge 'linux-review/Joel-Fernandes/Centralize-and-unify-usage-of-preempt-irq-tracepoints/20180629-042612' into devel-hourly-2018070705
git bisect good 02b8172ab8291a617c3a6fcbc1fcf36a089d177a # 14:01 G 11 0 3 3 tracepoint: Make rcuidle tracepoint callers use SRCU
git bisect bad a2c5dd341c772bd3a2055ad7b714398c00303510 # 14:13 B 0 6 19 0 lib: Add module to simulate atomic sections for testing preemptoff tracers
git bisect bad 66d38dea23d84646e36b9ddcda9a64f032b051a1 # 14:26 B 0 3 18 2 tracing: Centralize preemptirq tracepoints and unify their usage
# first bad commit: [66d38dea23d84646e36b9ddcda9a64f032b051a1] tracing: Centralize preemptirq tracepoints and unify their usage
git bisect good 02b8172ab8291a617c3a6fcbc1fcf36a089d177a # 14:33 G 32 0 7 12 tracepoint: Make rcuidle tracepoint callers use SRCU
# extra tests with debug options
git bisect bad 66d38dea23d84646e36b9ddcda9a64f032b051a1 # 14:47 B 0 10 23 0 tracing: Centralize preemptirq tracepoints and unify their usage
# extra tests on HEAD of linux-devel/devel-hourly-2018070705
git bisect bad 9edd8c0fbaf2eedee5a4bdca9614ac9e4001f04e # 14:47 B 0 13 32 2 0day head guard for 'devel-hourly-2018070705'
# extra tests on tree/branch linux-review/Joel-Fernandes/Centralize-and-unify-usage-of-preempt-irq-tracepoints/20180629-042612
git bisect bad ec96d36953721afefa91773600322d19450a92b1 # 15:10 B 0 4 17 0 kselftests: Add tests for the preemptoff and irqsoff tracers
# extra tests with first bad commit reverted
git bisect good 73460072b9cc8c681c01edbfe655ed59232059c0 # 15:32 G 11 0 1 1 Revert "tracing: Centralize preemptirq tracepoints and unify their usage"
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
2 years, 6 months
[lkp-robot] 72c22bc279 [ 0.000000] kernel BUG at arch/x86/mm/pageattr.c:482!
by kernel test robot
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
https://github.com/0day-ci/linux/commits/Thomas-Gleixner/x86-kvmclock-Rem...
commit 72c22bc279b50febce8896561981d1f642ba7076
Author: Thomas Gleixner <tglx(a)linutronix.de>
AuthorDate: Fri Jul 6 18:13:10 2018 +0200
Commit: 0day robot <lkp(a)intel.com>
CommitDate: Sat Jul 7 05:28:27 2018 +0800
x86/kvmclock: Decrapify kvm_register_clock()
The return value is pointless because the wrmsr cannot fail if
KVM_FEATURE_CLOCKSOURCE or KVM_FEATURE_CLOCKSOURCE2 are set.
kvm_register_clock() is only called locally so wants to be static.
Signed-off-by: Thomas Gleixner <tglx(a)linutronix.de>
Cc: Paolo Bonzini <pbonzini(a)redhat.com>
Cc: Radim Krcmar <rkrcmar(a)redhat.com>
Cc: Peter Zijlstra <peterz(a)infradead.org>
Cc: Juergen Gross <jgross(a)suse.com>
Cc: Pavel Tatashin <pasha.tatashin(a)oracle.com>
Cc: steven.sistare(a)oracle.com
Cc: daniel.m.jordan(a)oracle.com
Cc: x86(a)kernel.org
Cc: kvm(a)vger.kernel.org
d8a50d4b96 x86/kvmclock: Remove page size requirement from wall_clock
72c22bc279 x86/kvmclock: Decrapify kvm_register_clock()
20cb1d5383 x86/kvmclock: Switch kvmclock data to a PER_CPU variable
+--------------------------------------+------------+------------+------------+
| | d8a50d4b96 | 72c22bc279 | 20cb1d5383 |
+--------------------------------------+------------+------------+------------+
| boot_successes | 78 | 0 | 42 |
| boot_failures | 0 | 37 | |
| kernel_BUG_at_arch/x86/mm/pageattr.c | 0 | 37 | |
| PANIC:early_exception | 0 | 37 | |
| EIP:slow_virt_to_phys | 0 | 37 | |
+--------------------------------------+------------+------------+------------+
[ 0.000000] bootconsole [earlyser0] enabled
[ 0.000000] Notice: NX (Execute Disable) protection cannot be enabled: non-PAE kernel!
[ 0.000000] Hypervisor detected: KVM
[ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[ 0.000000] ------------[ cut here ]------------
[ 0.000000] kernel BUG at arch/x86/mm/pageattr.c:482!
PANIC: early exception 0x06 IP 60:41036950 error 0 cr2 0x0
[ 0.000000] CPU: 0 PID: 0 Comm: swapper Not tainted 4.18.0-rc2-00040-g72c22bc #134
[ 0.000000] EIP: slow_virt_to_phys+0x18/0x56
[ 0.000000] Code: c1 e9 16 8d 04 88 89 d1 89 da e8 77 ff ff ff 5b 5d c3 e8 a7 a3 ff ff 55 89 e5 53 89 c3 51 8d 55 f8 e8 c8 ff ff ff 85 c0 75 02 <0f> 0b 8b 55 f8 8b 00 83 fa 02 74 0c 83 fa 03 75 17 e8 ec f6 ff ff
[ 0.000000] EAX: 00000000 EBX: 00000000 ECX: 41753ef4 EDX: 00000000
[ 0.000000] ESI: 416a3f72 EDI: 0000000c EBP: 41753efc ESP: 41753ef4
[ 0.000000] DS: 007b ES: 007b FS: 0000 GS: 0000 SS: 0068 EFLAGS: 00210046
[ 0.000000] CR0: 80050033 CR2: 00000000 CR3: 01a03000 CR4: 00040600
[ 0.000000] Call Trace:
[ 0.000000] ? kvm_register_clock+0x17/0x46
[ 0.000000] ? kvmclock_init+0x7d/0x19b
[ 0.000000] ? init_hypervisor_platform+0x7d/0x85
[ 0.000000] ? setup_arch+0x3af/0xa0d
[ 0.000000] ? start_kernel+0x55/0x3fb
[ 0.000000] ? i386_start_kernel+0x99/0x9d
[ 0.000000] ? startup_32_smp+0x15f/0x164
Linux version 4.18.0-rc2-00040-g72c22bc #134
Command line: root=/dev/ram0 hung_task_panic=1 debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 drbd.minor_count=8 systemd.log_level=err ignore_loglevel console=tty0 earlyprintk=ttyS0,115200 console=ttyS0,115200 vga=normal rw link=/kbuild-tests/run-queue/openwrt-lkp-hsw01/i386-randconfig-s1-201826/linux-devel:devel-spot-201807070817:72c22bc279b50febce8896561981d1f642ba7076:bisect-linux-28/.vmlinuz-72c22bc279b50febce8896561981d1f642ba7076-20180707110921-11:openwrt-lkp-hsw01-103 branch=linux-devel/devel-spot-201807070817 BOOT_IMAGE=/pkg/linux/i386-randconfig-s1-201826/gcc-6/72c22bc279b50febce8896561981d1f642ba7076/vmlinuz-4.18.0-rc2-00040-g72c22bc drbd.minor_count=8 rcuperf.shutdown=0
# HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start 6b1cc80df7abaa2dd52ddd638a6014d87b3c2df1 021c91791a5e7e85c567452f1be3e4c2c6cb6063 --
git bisect good cd93266459d094173dea1e78b9fe8a56dd0adb43 # 10:18 G 11 0 11 33 Merge 'linux-review/Leonard-Crestez/soc-imx-gpc-Turn-PU-domain-on-off-in-sleep-on-6qp/20180707-061122' into devel-spot-201807070817
git bisect bad 8819ebf7a79ad04d43f648a3239f3dd38de2e3d7 # 12:52 B 0 1 49 22 Merge 'linux-review/Fabio-Estevam/mmc-sdhci-esdhc-imx-Switch-to-SPDX-identifier/20180707-052941' into devel-spot-201807070817
git bisect good 9d721f3dfac486dc09cc0365c52e665b1cd99668 # 12:59 G 11 0 11 44 Merge 'linux-review/Ivan-Khoronzhuk/allow-PTP-224-0-0-107-to-be-timestamped/20180707-055715' into devel-spot-201807070817
git bisect good 6ce5fe2862b4eb66cbe78178d1dd082eb82c2acf # 13:00 G 11 0 11 26 Merge 'linux-review/Tim-Collier/staging-wlan-ng-fix-coding-style-issues/20180707-054559' into devel-spot-201807070817
git bisect bad 53406dbe6ebe197a79c01f478ce9486c5a621f2e # 13:00 B 0 35 81 0 Merge 'linux-review/Thomas-Gleixner/x86-kvmclock-Remove-memblock-dependency-and-further-cleanups/20180707-052825' into devel-spot-201807070817
git bisect good d8a50d4b96f8efd35ce7e67efcaefb39bc2e0a0b # 13:08 G 10 0 1 5 x86/kvmclock: Remove page size requirement from wall_clock
git bisect bad 72c22bc279b50febce8896561981d1f642ba7076 # 13:09 B 0 37 91 0 x86/kvmclock: Decrapify kvm_register_clock()
# first bad commit: [72c22bc279b50febce8896561981d1f642ba7076] x86/kvmclock: Decrapify kvm_register_clock()
git bisect good d8a50d4b96f8efd35ce7e67efcaefb39bc2e0a0b # 13:17 G 32 0 6 11 x86/kvmclock: Remove page size requirement from wall_clock
# extra tests on HEAD of linux-devel/devel-spot-201807070817
git bisect bad 46f014d2e5716b9d17327f6fb68d1594412c662d # 13:17 B 0 27 44 0 0day head guard for 'devel-spot-201807070817'
# extra tests on tree/branch linux-review/Thomas-Gleixner/x86-kvmclock-Remove-memblock-dependency-and-further-cleanups/20180707-052825
git bisect good 20cb1d5383cd99b35ef9b4c76ee97b0527814a77 # 13:37 G 10 0 1 7 x86/kvmclock: Switch kvmclock data to a PER_CPU variable
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
2 years, 6 months
[lkp-robot] [kprobes/x86] 80006dbee6: will-it-scale.per_process_ops +2.6% improvement
by kernel test robot
Greeting,
We noticed a +2.6% improvement of will-it-scale.per_process_ops due to commit:
commit: 80006dbee674f9fa7c20a799d208657a18b5dabf ("kprobes/x86: Remove jprobe implementation")
https://git.kernel.org/cgit/linux/kernel/git/next/linux-next.git master
in testcase: will-it-scale
on test machine: 160 threads Intel(R) Xeon(R) CPU E7-8890 v4 @ 2.20GHz with 256G memory
with following parameters:
test: sched_yield
cpufreq_governor: performance
test-description: Will It Scale takes a testcase and runs it from 1 through to n parallel copies to see if the testcase will scale. It builds both a process and threads based test in order to see any differences between the two.
test-url: https://github.com/antonblanchard/will-it-scale
Details are as below:
-------------------------------------------------------------------------------------------------->
=========================================================================================
tbox_group/testcase/rootfs/kconfig/compiler/test/cpufreq_governor:
lkp-bdw-ex2/will-it-scale/debian-x86_64-2016-08-31.cgz/x86_64-rhel-7.2/gcc-7/sched_yield/performance
commit:
5a6cf77f5e35e7af35d36a1e7dc21a42f6412e4f
80006dbee674f9fa7c20a799d208657a18b5dabf
5a6cf77f5e35e7af 80006dbee674f9fa7c20a799d2
---------------- --------------------------
%stddev %change %stddev
\ | \
2317441 +2.6% 2378544 will-it-scale.per_process_ops
2312968 +2.7% 2375459 will-it-scale.per_thread_ops
0.65 -0.3% 0.64 will-it-scale.scalability
310.23 +0.1% 310.40 will-it-scale.time.elapsed_time
310.23 +0.1% 310.40 will-it-scale.time.elapsed_time.max
12652 ± 33% +755.1% 108195 ±150% will-it-scale.time.involuntary_context_switches
9875 -0.2% 9859 will-it-scale.time.maximum_resident_set_size
28704 +0.1% 28738 will-it-scale.time.minor_page_faults
4096 +0.0% 4096 will-it-scale.time.page_size
3411 -0.1% 3410 will-it-scale.time.percent_of_cpu_this_job_got
8104 -0.8% 8035 will-it-scale.time.system_time
2481 +2.8% 2550 will-it-scale.time.user_time
1109 ± 3% +2.4% 1135 ± 4% will-it-scale.time.voluntary_context_switches
2.036e+09 +2.7% 2.09e+09 will-it-scale.workload
1200408 -0.3% 1196470 interrupts.CAL:Function_call_interrupts
33.25 ± 4% +5.3% 35.02 ± 6% boot-time.boot
19.29 ± 8% +9.9% 21.20 ± 8% boot-time.dhcp
6449 -1.0% 6381 boot-time.idle
22.74 ± 7% +7.7% 24.50 ± 8% boot-time.kernel_boot
64.25 +0.0 64.27 mpstat.cpu.idle%
0.17 ± 4% -0.0 0.17 ± 9% mpstat.cpu.soft%
27.20 -0.2 26.96 mpstat.cpu.sys%
8.38 +0.2 8.59 mpstat.cpu.usr%
23893 ± 6% +9.6% 26179 ± 8% softirqs.NET_RX
7240677 ± 6% +8.3% 7844993 ± 10% softirqs.RCU
5044013 ± 4% +0.1% 5049813 ± 2% softirqs.SCHED
29708405 +2.2% 30376480 softirqs.TIMER
1366288 -0.0% 1366033 vmstat.memory.cache
2.616e+08 +0.0% 2.616e+08 vmstat.memory.free
0.00 -100.0% 0.00 vmstat.procs.b
67.00 +0.0% 67.00 vmstat.procs.r
1645 ± 2% +24.5% 2049 ± 29% vmstat.system.cs
198691 +0.0% 198790 vmstat.system.in
3425724 ± 2% +5.3% 3606570 ± 11% cpuidle.C1.time
124814 ± 2% +3.0% 128603 ± 4% cpuidle.C1.usage
4177906 ± 14% -2.0% 4095503 ± 16% cpuidle.C1E.time
49521 ± 10% +0.0% 49537 ± 9% cpuidle.C1E.usage
will-it-scale.per_process_ops
2.42e+06 +-+--------------------------------------------------------------+
| |
2.4e+06 +-OO O |
O O |
| O O O O O O OO O |
2.38e+06 +-+ O O O O O OO O O OO O OO O O OO O
| O O O O OO |
2.36e+06 +-+ |
| |
2.34e+06 +-+ |
| |
| |
2.32e+06 +-+ .+.++.+.+.++.|
| +. .++.+.+.++ |
2.3e+06 +-+--------------------------------------------------------------+
will-it-scale.workload
2.11e+09 +-+--------------------------------------------------------------+
| |
2.1e+09 O-OO O O O O OO O OO O |
2.09e+09 +-+ O O OO O O O OO O
| O O O O O O OO O |
2.08e+09 +-+ O O O O O |
2.07e+09 +-+ O O |
| |
2.06e+09 +-+ |
2.05e+09 +-+ |
| |
2.04e+09 +-+ .+ .+ .|
2.03e+09 +-+ .+.+.++.+ +.+.+ + |
|.++.+.+.++. +.+.+.++.+.++.+.+.++.+.++.+.++ |
2.02e+09 +-+--------------------------------------------------------------+
[*] bisect-good sample
[O] bisect-bad sample
Disclaimer:
Results have been estimated based on internal Intel analysis and are provided
for informational purposes only. Any difference in system hardware or software
design or configuration may affect actual performance.
Thanks,
Xiaolong
2 years, 6 months