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  PHC 
Open Source and information security mailing list archives
 
Hash Suite: Windows password security audit tool. GUI, reports in PDF.
[<prev] [next>] [day] [month] [year] [list]
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