| 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
| ||
|
Date: Mon, 6 Oct 2014 13:20:27 +0800
From: Fengguang Wu <fengguang.wu@...el.com>
To: Amir Vadai <amirv@...lanox.com>
Cc: Dave Hansen <dave.hansen@...el.com>,
Jenkins Builder <jenkins@...lanox.com>,
LKML <linux-kernel@...r.kernel.org>, lkp@...org
Subject: [fentry] WARNING: CPU: 0 PID: 1 at
kernel/trace/trace_functions_graph.c:224
ftrace_return_to_handler+0xd2/0x1a8()
FYI, we noticed the below changes on
git://flatbed.openfabrics.org/~amirv/linux.git for-netdev
commit de89e9380c82b0da22a6609f973dd6e9d67656e3 ("regression: Disable FENTRY")
+-----------------------------------------------------------------------------+------------+------------+
| | d068b02cfd | de89e9380c |
+-----------------------------------------------------------------------------+------------+------------+
| boot_successes | 0 | 0 |
| boot_failures | 10 | 10 |
| BUG:sleeping_function_called_from_invalid_context_at_kernel/locking/mutex.c | 7 | |
| backtrace:do_vfs_ioctl | 7 | |
| backtrace:SyS_ioctl | 7 | |
| Out_of_memory:Kill_process | 3 | |
| Kernel_panic-not_syncing:Out_of_memory_and_no_killable_processes | 2 | |
| backtrace:do_execve | 2 | |
| backtrace:SyS_execve | 2 | |
| backtrace:do_fork | 3 | |
| backtrace:SyS_clone | 3 | |
| backtrace:do_sys_open | 1 | |
| backtrace:SyS_open | 1 | |
| backtrace:pgd_alloc | 1 | |
| backtrace:mm_init | 1 | |
| backtrace:SYSC_reboot | 1 | |
| backtrace:SyS_reboot | 1 | |
| WARNING:at_kernel/trace/trace_functions_graph.c:ftrace_return_to_handler() | 0 | 10 |
| backtrace:register_tracer | 0 | 10 |
| backtrace:init_graph_trace | 0 | 10 |
| backtrace:kernel_init_freeable | 0 | 10 |
+-----------------------------------------------------------------------------+------------+------------+
[ 10.644181] PASSED
[ 10.644938] Testing tracer function_graph:
[ 11.136016] ------------[ cut here ]------------
[ 11.140000] WARNING: CPU: 0 PID: 1 at kernel/trace/trace_functions_graph.c:224 ftrace_return_to_handler+0xd2/0x1a8()
[ 11.140000] Bad frame pointer: expected ffff880023603eb8, received ffff880023603ef0
[ 11.140000] from func __calc_delta return to ffffffff810e981c
[ 11.140000] Modules linked in:
[ 11.140000] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 3.17.0-rc7-01516-g8585242 #920
[ 11.140000] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011
[ 11.140000] 0000000000000000 ffff880023603db8 ffffffff82d0d960 ffff880023603e00
[ 11.140000] ffff880023603df0 ffffffff810c3476 ffffffff81164abb ffffffff82d05448
[ 11.140000] ffff8800237d6238 ffff8800237d6180 0000000000000000 ffff880023603e58
[ 11.140000] Call Trace:
[ 11.140000] <IRQ> [<ffffffff82d0d960>] dump_stack+0x4d/0x66
[ 11.140000] [<ffffffff810c3476>] warn_slowpath_common+0x7f/0x98
[ 11.140000] [<ffffffff81164abb>] ? ftrace_return_to_handler+0xd2/0x1a8
[ 11.140000] [<ffffffff82d05448>] ? set_tsk_thread_flag+0x13/0x13
[ 11.140000] [<ffffffff810c34d7>] warn_slowpath_fmt+0x48/0x50
[ 11.140000] [<ffffffff810e9461>] ? __calc_delta+0x15/0xae
[ 11.140000] [<ffffffff81164abb>] ftrace_return_to_handler+0xd2/0x1a8
[ 11.140000] [<ffffffff810e981c>] ? sched_slice+0x74/0x87
[ 11.140000] [<ffffffff82d3485e>] ? ftrace_graph_caller+0x7e/0x7e
[ 11.140000] [<ffffffff810e981c>] sched_slice+0x74/0x87
[ 11.140000] [<ffffffff810e981c>] ? sched_slice+0x74/0x87
[ 11.140000] [<ffffffff82d34873>] return_to_handler+0x15/0x32
[ 11.140000] [<ffffffff82d3485e>] ? ftrace_graph_caller+0x7e/0x7e
[ 11.140000] [<ffffffff810ebe79>] task_tick_fair+0x88/0x109
[ 11.140000] [<ffffffff8107a7e3>] ? ftrace_write+0x23/0x4c
[ 11.140000] [<ffffffff82d3485e>] ftrace_graph_caller+0x7e/0x7e
[ 11.140000] [<ffffffff810e54be>] scheduler_tick+0x57/0x89
[ 11.140000] [<ffffffff82d3485e>] ftrace_graph_caller+0x7e/0x7e
[ 11.140000] [<ffffffff811195c8>] update_process_times+0x56/0x65
[ 11.140000] [<ffffffff82d3485e>] ftrace_graph_caller+0x7e/0x7e
[ 11.140000] [<ffffffff811240dd>] tick_periodic+0xa1/0xad
[ 11.140000] [<ffffffff82d3485e>] ftrace_graph_caller+0x7e/0x7e
[ 11.140000] [<ffffffff8112427d>] tick_handle_periodic+0x26/0x60
[ 11.140000] [<ffffffff81089300>] ? __virt_addr_valid+0x66/0x8d
[ 11.140000] [<ffffffff82d3485e>] ftrace_graph_caller+0x7e/0x7e
[ 11.140000] [<ffffffff8107556a>] local_apic_timer_interrupt+0x54/0x57
[ 11.140000] [<ffffffff82d3485e>] ftrace_graph_caller+0x7e/0x7e
[ 11.140000] [<ffffffff82d3501d>] smp_apic_timer_interrupt+0x2b/0x3c
[ 11.140000] [<ffffffff82d33082>] apic_timer_interrupt+0x72/0x80
[ 11.140000] <EOI> [<ffffffff81080ac3>] ? pvclock_clocksource_read+0x76/0xa0
[ 11.140000] [<ffffffff81164d97>] ? trace_graph_entry+0x175/0x18b
[ 11.140000] [<ffffffff8108937b>] ? __phys_addr_symbol+0x4/0x24
[ 11.140000] [<ffffffff8107ff56>] kvm_clock_read+0x27/0x31
[ 11.140000] [<ffffffff81051ebc>] paravirt_sched_clock+0x9/0xd
[ 11.140000] [<ffffffff81052506>] sched_clock+0x9/0xb
[ 11.140000] [<ffffffff8114fda6>] trace_clock_local+0x11/0x1b
[ 11.140000] [<ffffffff81164916>] ftrace_push_return_trace+0x76/0x149
[ 11.140000] [<ffffffff8107a7e3>] ? ftrace_write+0x23/0x4c
[ 11.140000] [<ffffffff8108937b>] ? __phys_addr_symbol+0x4/0x24
[ 11.140000] [<ffffffff8107af30>] prepare_ftrace_return+0xa2/0xb5
[ 11.140000] [<ffffffff8108937b>] ? __phys_addr_symbol+0x4/0x24
[ 11.140000] [<ffffffff82d34833>] ftrace_graph_caller+0x53/0x7e
[ 11.140000] [<ffffffff810f9873>] ? trace_hardirqs_on_caller+0x18f/0x1ab
[ 11.140000] [<ffffffff81164f4a>] ? trace_graph_return+0x8e/0x97
[ 11.140000] [<ffffffff8107a7e3>] ? ftrace_write+0x23/0x4c
[ 11.140000] [<ffffffff811420f6>] ? audit_find_rule+0x12/0x108
[ 11.140000] [<ffffffff811420f1>] ? audit_find_rule+0xd/0x108
[ 11.140000] [<ffffffff81089380>] ? __phys_addr_symbol+0x9/0x24
[ 11.140000] [<ffffffff81089380>] ? __phys_addr_symbol+0x9/0x24
[ 11.140000] [<ffffffff82d3485e>] ftrace_graph_caller+0x7e/0x7e
[ 11.140000] [<ffffffff8107aca9>] ftrace_replace_code+0x1d6/0x331
[ 11.140000] [<ffffffff81164ebc>] ? trace_graph_function+0x78/0x78
[ 11.140000] [<ffffffff811525f1>] ftrace_modify_all_code+0x41/0xca
[ 11.140000] [<ffffffff8107ae19>] arch_ftrace_update_code+0x15/0x1e
[ 11.140000] [<ffffffff81152b2c>] ftrace_run_update_code+0x2b/0x1b2
[ 11.140000] [<ffffffff81164ebc>] ? trace_graph_function+0x78/0x78
[ 11.140000] [<ffffffff81152ce5>] ftrace_startup_enable+0x32/0x34
[ 11.140000] [<ffffffff81152e25>] ftrace_startup+0x13e/0x148
[ 11.140000] [<ffffffff8115443b>] register_ftrace_graph+0x235/0x252
[ 11.140000] [<ffffffff8115f982>] ? ftrace_dump+0x241/0x241
[ 11.140000] [<ffffffff8441060b>] trace_selftest_startup_function_graph+0x57/0xe3
[ 11.140000] [<ffffffff8115eeac>] register_tracer+0x165/0x288
[ 11.140000] [<ffffffff84410c87>] ? init_graph_debugfs+0x2f/0x2f
[ 11.140000] [<ffffffff84410ced>] init_graph_trace+0x66/0x68
[ 11.140000] [<ffffffff8100216d>] do_one_initcall+0xee/0x17e
[ 11.140000] [<ffffffff810db300>] ? parse_args+0xfc/0x2b8
[ 11.140000] [<ffffffff843e80bf>] kernel_init_freeable+0x1e2/0x26f
[ 11.140000] [<ffffffff82cfcab5>] ? rest_init+0xc9/0xc9
[ 11.140000] [<ffffffff82cfcac3>] kernel_init+0xe/0xdf
[ 11.140000] [<ffffffff82d3203c>] ret_from_fork+0x7c/0xb0
[ 11.140000] [<ffffffff82cfcab5>] ? rest_init+0xc9/0xc9
[ 11.140000] ---[ end trace 94c1e7868964d6b1 ]---
[ 11.140000] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G W 3.17.0-rc7-01516-g8585242 #920
The "BUG: sleeping function called" in the parent commit "disappeared"
in commit de89e93 because it happens in much later time:
[ 322.056221] smpboot: CPU 1 is now offline
[ 322.081569] bnx2fc: CPU 1 offline: Remove Rx thread
[ 322.088300] CPU 1 offline: Remove Rx thread
mount.nfs: Connection timed out
run-parts: /etc/kernel-tests/99-trinity exited with return code 32
[ 362.720433] BUG: sleeping function called from invalid context at kernel/locking/mutex.c:583
[ 362.733241] in_atomic(): 1, irqs_disabled(): 0, pid: 6357, name: reboot
[ 362.734867] 2 locks held by reboot/6357:
[ 362.735995] #0: (rtnl_mutex){+.+.+.}, at: [<ffffffff829d5b5d>] rtnl_lock+0x17/0x19
[ 362.738773] #1: (rcu_read_lock){......}, at: [<ffffffff810dca0e>] rcu_lock_acquire+0x0/0x20
[ 362.741686] CPU: 0 PID: 6357 Comm: reboot Not tainted 3.17.0-rc6-01531-gd068b02 #1333
[ 362.750958] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011
[ 362.752463] 0000000000000000 ffff88001858fa30 ffffffff82d0d3bf 00000000000018d5
[ 362.754976] ffff88001858fa50 ffffffff810e1e35 ffffffff83fc43c0 0000000000000000
[ 362.757523] ffff88001858fad0 ffffffff82d2e0f7 000000000052001f 0000000000000001
[ 362.760103] Call Trace:
[ 362.782376] [<ffffffff82d0d3bf>] dump_stack+0x4d/0x66
[ 362.793968] [<ffffffff810e1e35>] __might_sleep+0x125/0x12a
[ 362.795422] [<ffffffff82d2e0f7>] mutex_lock_nested+0x3e/0x3ec
[ 362.796958] [<ffffffff81d60f4e>] cxgbi_device_find_by_netdev+0x63/0x102
[ 362.798562] [<ffffffff81d60f4e>] ? cxgbi_device_find_by_netdev+0x63/0x102
[ 362.800309] [<ffffffff81d68648>] cxgbi_inet6addr_handler+0x3c/0x83
[ 362.801817] [<ffffffff810dc923>] notifier_call_chain+0x6d/0x93
[ 362.803274] [<ffffffff810dcdc7>] __atomic_notifier_call_chain+0x4c/0x79
[ 362.804991] [<ffffffff810dce08>] atomic_notifier_call_chain+0x14/0x16
[ 362.806572] [<ffffffff82b239b6>] inet6addr_notifier_call_chain+0x1b/0x1d
[ 362.808348] [<ffffffff82aefb5c>] addrconf_ifdown+0x28d/0x321
[ 362.809759] [<ffffffff82af0d7e>] addrconf_notify+0x717/0x7d5
[ 362.811192] [<ffffffff810c6ce5>] ? __local_bh_enable_ip+0xaf/0xb4
[ 362.812797] [<ffffffff82d30fe6>] ? _raw_spin_unlock_bh+0x35/0x38
[ 362.815980] [<ffffffff82af80bb>] ? fib6_run_gc+0xc4/0xcb
[ 362.817453] [<ffffffff810dc923>] notifier_call_chain+0x6d/0x93
[ 362.818944] [<ffffffff810dc923>] ? notifier_call_chain+0x6d/0x93
[ 362.820545] [<ffffffff810dc96d>] raw_notifier_call_chain+0x14/0x16
[ 362.822095] [<ffffffff829c52bb>] call_netdevice_notifiers_info+0x52/0x59
[ 362.823725] [<ffffffff829c52d5>] call_netdevice_notifiers+0x13/0x15
[ 362.825368] [<ffffffff829cc19e>] __dev_notify_flags+0x53/0x81
[ 362.826838] [<ffffffff829cc814>] dev_change_flags+0x4e/0x59
[ 362.828374] [<ffffffff82aa877a>] devinet_ioctl+0x267/0x566
[ 362.829731] [<ffffffff82aa91f7>] inet_ioctl+0x86/0xa2
[ 362.831085] [<ffffffff82aa91f7>] ? inet_ioctl+0x86/0xa2
[ 362.832482] [<ffffffff829b25cc>] sock_do_ioctl+0x25/0x42
[ 362.833941] [<ffffffff829b2ad8>] sock_ioctl+0x213/0x21f
[ 362.835346] [<ffffffff811e532f>] vfs_ioctl+0x18/0x34
[ 362.836692] [<ffffffff811e5b9a>] do_vfs_ioctl+0x39a/0x442
[ 362.838237] [<ffffffff811e5c99>] SyS_ioctl+0x57/0x79
[ 362.839545] [<ffffffff82d31d50>] tracesys+0xdd/0xe2
[ 364.099570] Unregister pv shared memory for cpu 0
[ 364.137326] reboot: Restarting system
[ 364.138391] reboot: machine restart
Thanks,
Fengguang
View attachment ".dmesg" of type "text/plain" (47985 bytes)
_______________________________________________
LKP mailing list
LKP@...ux.intel.com
Powered by blists - more mailing lists