Call Trace

Questions related to general functionality
Post Reply
andlui9
Posts: 37
Joined: 20 Jan 2017, 11:46

Call Trace

Post by andlui9 »

Hi guys!

I'm having a difficulty with accel on debian 8 (jessie), after compiling and starting the service, a call trace happens instantly, I am using the commit 54f225b10ddd13ad4a7e3e5359fdbd2bf927d130, which I believe is the most recent, I leave below some data of the problem.

Feb 13 16:49:28 bras-1 kernel: [ 280.842976] PPP generic driver version 2.4.2
Feb 13 16:49:28 bras-1 kernel: [ 280.852925] NET: Registered protocol family 24
Feb 13 16:49:29 bras-1 kernel: [ 281.936806] IPv6: ADDRCONF(NETDEV_CHANGE): ifb0: link becomes ready
Feb 13 16:49:50 bras-1 kernel: [ 302.964900] INFO: rcu_sched self-detected stall on CPU { 2} (t=5250 jiffies g=1678 c=1677 q=461)
Feb 13 16:49:50 bras-1 kernel: [ 302.965321] sending NMI to all CPUs:
Feb 13 16:49:50 bras-1 kernel: [ 302.965429] NMI backtrace for cpu 0
Feb 13 16:49:50 bras-1 kernel: [ 302.965521] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G O 3.16.0-5-amd64 #1 Debian 3.16.51-3+deb8u1
Feb 13 16:49:50 bras-1 kernel: [ 302.965624] Hardware name: Dell Inc. PowerEdge T320/0N1DKP, BIOS 2.1.2 01/20/2014
Feb 13 16:49:50 bras-1 kernel: [ 302.965721] task: ffffffff8181d460 ti: ffffffff81800000 task.ti: ffffffff81800000
Feb 13 16:49:50 bras-1 kernel: [ 302.965818] RIP: 0010:[<ffffffff81327e92>] [<ffffffff81327e92>] intel_idle+0xc2/0x140
Feb 13 16:49:50 bras-1 kernel: [ 302.965999] RSP: 0018:ffffffff81803ea8 EFLAGS: 00000046
Feb 13 16:49:50 bras-1 kernel: [ 302.966091] RAX: 0000000000000020 RBX: 0000000000000008 RCX: 0000000000000001
Feb 13 16:49:50 bras-1 kernel: [ 302.966188] RDX: 0000000000000000 RSI: ffffffff81803fd8 RDI: 0000000000000000
Feb 13 16:49:50 bras-1 kernel: [ 302.966284] RBP: 0000000000000004 R08: 00000000ffffffff R09: 0000000000000645
Feb 13 16:49:50 bras-1 kernel: [ 302.966402] R10: 0000000000007a23 R11: 0000000000000008 R12: 0000000000000020
Feb 13 16:49:50 bras-1 kernel: [ 302.966520] R13: 0000000000000003 R14: 0000000000000004 R15: ffffffff818a3ad8
Feb 13 16:49:50 bras-1 kernel: [ 302.966638] FS: 0000000000000000(0000) GS:ffff88042f000000(0000) knlGS:0000000000000000
Feb 13 16:49:50 bras-1 kernel: [ 302.966779] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
Feb 13 16:49:50 bras-1 kernel: [ 302.966894] CR2: 00007f3f11a41000 CR3: 0000000001814000 CR4: 0000000000160770
Feb 13 16:49:50 bras-1 kernel: [ 302.967012] Stack:
Feb 13 16:49:50 bras-1 kernel: [ 302.967117] 000000008101db75 ffffe8ffff800020 ffffffff81803f00 ffffffff818a3960
Feb 13 16:49:50 bras-1 kernel: [ 302.967662] 000000467b8516fd ffffffff813ee850 ffffffff818e8520 ffffffff81800000
Feb 13 16:49:50 bras-1 kernel: [ 302.968116] ffffffff818a3960 ffffffff81800000 0000000000000004 ffffe8ffff800020
Feb 13 16:49:50 bras-1 kernel: [ 302.968572] Call Trace:
Feb 13 16:49:50 bras-1 kernel: [ 302.968681] [<ffffffff813ee850>] ? cpuidle_enter_state+0x40/0xc0
Feb 13 16:49:50 bras-1 kernel: [ 302.968799] [<ffffffff810ac9a8>] ? cpu_startup_entry+0x328/0x470
Feb 13 16:49:50 bras-1 kernel: [ 302.968917] [<ffffffff8190a07b>] ? start_kernel+0x49c/0x4a7
Feb 13 16:49:50 bras-1 kernel: [ 302.969032] [<ffffffff81909a04>] ? set_init_arg+0x4e/0x4e
Feb 13 16:49:50 bras-1 kernel: [ 302.969147] [<ffffffff81909120>] ? early_idt_handler_array+0x120/0x120
Feb 13 16:49:50 bras-1 kernel: [ 302.969265] [<ffffffff8190971f>] ? x86_64_start_kernel+0x14d/0x15c
Feb 13 16:49:50 bras-1 kernel: [ 302.969380] Code: 31 d2 65 48 8b 34 25 20 44 00 00 48 89 d1 48 8d 86 38 c0 ff ff 0f 01 c8 48 8b 86 38 c0 ff ff a8 08 75 08 b1 01 4c 89 e0 0f 01 c9 <65> 48 8b 0c 25 20 44 00 00 f0 80 a1 3a c0 ff ff df 0f ae f0 48
Feb 13 16:49:50 bras-1 kernel: [ 302.974693] NMI backtrace for cpu 1
Feb 13 16:49:50 bras-1 kernel: [ 302.974698] INFO: NMI handler (arch_trigger_all_cpu_backtrace_handler) took too long to run: 9.263 msecs
Feb 13 16:49:50 bras-1 kernel: [ 302.974950] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G O 3.16.0-5-amd64 #1 Debian 3.16.51-3+deb8u1
Feb 13 16:49:50 bras-1 kernel: [ 302.975095] Hardware name: Dell Inc. PowerEdge T320/0N1DKP, BIOS 2.1.2 01/20/2014
Feb 13 16:49:50 bras-1 kernel: [ 302.975236] task: ffff88041e80aa30 ti: ffff88041e814000 task.ti: ffff88041e814000
Feb 13 16:49:50 bras-1 kernel: [ 302.975375] RIP: 0010:[<ffffffff81327e92>] [<ffffffff81327e92>] intel_idle+0xc2/0x140
Feb 13 16:49:50 bras-1 kernel: [ 302.975597] RSP: 0018:ffff88041e817e78 EFLAGS: 00000046
Feb 13 16:49:50 bras-1 kernel: [ 302.975710] RAX: 0000000000000020 RBX: 0000000000000008 RCX: 0000000000000001
Feb 13 16:49:50 bras-1 kernel: [ 302.975828] RDX: 0000000000000000 RSI: ffff88041e817fd8 RDI: 0000000000000001
Feb 13 16:49:50 bras-1 kernel: [ 302.975946] RBP: 0000000000000004 R08: 0000000000005d54 R09: 0000000000000018
Feb 13 16:49:50 bras-1 kernel: [ 302.976064] R10: 000000000000d881 R11: 0000000000000006 R12: 0000000000000020
Feb 13 16:49:50 bras-1 kernel: [ 302.976181] R13: 0000000000000003 R14: 0000000000000004 R15: ffffffff818a3ad8
Feb 13 16:49:50 bras-1 kernel: [ 302.976300] FS: 0000000000000000(0000) GS:ffff88042f020000(0000) knlGS:0000000000000000
Feb 13 16:49:50 bras-1 kernel: [ 302.976441] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
Feb 13 16:49:50 bras-1 kernel: [ 302.976556] CR2: 00007f3f0f50a148 CR3: 0000000001814000 CR4: 0000000000160770
Feb 13 16:49:50 bras-1 kernel: [ 302.976673] Stack:
Feb 13 16:49:50 bras-1 kernel: [ 302.976779] 000000018101db75 ffffe8ffff820020
Feb 13 16:49:50 bras-1 kernel: [ 302.976912] INFO: rcu_sched detected stalls on CPUs/tasks: { 2} (detected by 3, t=5252 jiffies, g=1678, c=1677, q=464)
Feb 13 16:49:50 bras-1 kernel: [ 302.977431] ffff88041e817ed0 ffffffff818a3960
Feb 13 16:49:50 bras-1 kernel: [ 302.977801] 000000467b47cf25 ffffffff813ee850 ffffffff818e8520 ffff88041e814000
Feb 13 16:49:50 bras-1 kernel: [ 302.978256] ffffffff818a3960 ffff88041e814000 0000000000000004 ffffe8ffff820020
Feb 13 16:49:50 bras-1 kernel: [ 302.978712] Call Trace:
Feb 13 16:49:50 bras-1 kernel: [ 302.978820] [<ffffffff813ee850>] ? cpuidle_enter_state+0x40/0xc0
Feb 13 16:49:50 bras-1 kernel: [ 302.978936] [<ffffffff810ac9a8>] ? cpu_startup_entry+0x328/0x470
Feb 13 16:49:50 bras-1 kernel: [ 302.979053] [<ffffffff8104595f>] ? start_secondary+0x20f/0x2d0
Feb 13 16:49:50 bras-1 kernel: [ 302.979167] Code: 31 d2 65 48 8b 34 25 20 44 00 00 48 89 d1 48 8d 86 38 c0 ff ff 0f 01 c8 48 8b 86 38 c0 ff ff a8 08 75 08 b1 01 4c 89 e0 0f 01 c9 <65> 48 8b 0c 25 20 44 00 00 f0 80 a1 3a c0 ff ff df 0f ae f0 48
Feb 13 16:49:50 bras-1 kernel: [ 302.984528] NMI backtrace for cpu 2
Feb 13 16:49:50 bras-1 kernel: [ 302.984532] INFO: NMI handler (arch_trigger_all_cpu_backtrace_handler) took too long to run: 18.997 msecs
Feb 13 16:49:50 bras-1 kernel: [ 302.984782] CPU: 2 PID: 1511 Comm: accel-pppd Tainted: G O 3.16.0-5-amd64 #1 Debian 3.16.51-3+deb8u1
Feb 13 16:49:50 bras-1 kernel: [ 302.984927] Hardware name: Dell Inc. PowerEdge T320/0N1DKP, BIOS 2.1.2 01/20/2014
Feb 13 16:49:50 bras-1 kernel: [ 302.985068] task: ffff8800c8ccf490 ti: ffff88041998c000 task.ti: ffff88041998c000
Feb 13 16:49:50 bras-1 kernel: [ 302.985207] RIP: 0010:[<ffffffff8101db32>] [<ffffffff8101db32>] native_read_tsc+0x2/0x10
Feb 13 16:49:50 bras-1 kernel: [ 302.985431] RSP: 0018:ffff88042f043dd8 EFLAGS: 00000046
Feb 13 16:49:50 bras-1 kernel: [ 302.985544] RAX: 0000000001a3ecb8 RBX: 0000000001a3ec5c RCX: 000000000124f030
Feb 13 16:49:50 bras-1 kernel: [ 302.985662] RDX: 0000000000000316 RSI: 0000000000000200 RDI: 000000000003a1ea
Feb 13 16:49:50 bras-1 kernel: [ 302.985779] RBP: 000000000003a1ea R08: ffffffff818e8000 R09: 00000000000004cc
Feb 13 16:49:50 bras-1 kernel: [ 302.985897] R10: 0000000000000000 R11: ffff88042f043b96 R12: 0000000000000002
Feb 13 16:49:50 bras-1 kernel: [ 302.986015] R13: 0000000000000096 R14: 0000000000000002 R15: 0000000000000006
Feb 13 16:49:50 bras-1 kernel: [ 302.986133] FS: 00007f295fdd3880(0000) GS:ffff88042f040000(0000) knlGS:0000000000000000
Feb 13 16:49:50 bras-1 kernel: [ 302.986275] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
Feb 13 16:49:50 bras-1 kernel: [ 302.986389] CR2: 00007fcde73e1d4c CR3: 000000041db16000 CR4: 0000000000160770
Feb 13 16:49:50 bras-1 kernel: [ 302.986507] Stack:
Feb 13 16:49:50 bras-1 kernel: [ 302.986612] ffffffff812c0852 00000000000003e9 0000000000001000 0000000000000400
Feb 13 16:49:50 bras-1 kernel: [ 302.987066] ffffffff810479f2 000000000000d0ea ffffffff818e8000 ffffffff81049489
Feb 13 16:49:50 bras-1 kernel: [ 302.987521] 0000000000000003 ffff88042f0502a0 ffffffff81858940 0000000000000002
Feb 13 16:49:50 bras-1 kernel: [ 302.987975] Call Trace:
Feb 13 16:49:50 bras-1 kernel: [ 302.988082] <IRQ>
Feb 13 16:49:50 bras-1 kernel: [ 302.988165] [<ffffffff812c0852>] ? delay_tsc+0x42/0x70
Feb 13 16:49:50 bras-1 kernel: [ 302.988461] [<ffffffff810479f2>] ? native_safe_apic_wait_icr_idle+0x22/0x50
Feb 13 16:49:50 bras-1 kernel: [ 302.988579] [<ffffffff81049489>] ? default_send_IPI_mask_sequence_phys+0xd9/0xe0
Feb 13 16:49:50 bras-1 kernel: [ 302.988720] [<ffffffff810496e3>] ? arch_trigger_all_cpu_backtrace+0xc3/0x140
Feb 13 16:49:50 bras-1 kernel: [ 302.988841] [<ffffffff810ca46a>] ? rcu_check_callbacks+0x42a/0x670
Feb 13 16:49:50 bras-1 kernel: [ 302.988958] [<ffffffff810cbfb5>] ? timekeeping_update.constprop.9+0x35/0x70
Feb 13 16:49:50 bras-1 kernel: [ 302.989076] [<ffffffff810d4e50>] ? tick_sched_handle.isra.16+0x60/0x60
Feb 13 16:49:50 bras-1 kernel: [ 302.989196] [<ffffffff81078120>] ? update_process_times+0x40/0x70
Feb 13 16:49:50 bras-1 kernel: [ 302.989312] [<ffffffff810d4e10>] ? tick_sched_handle.isra.16+0x20/0x60
Feb 13 16:49:50 bras-1 kernel: [ 302.989429] [<ffffffff810d4e8c>] ? tick_sched_timer+0x3c/0x60
Feb 13 16:49:50 bras-1 kernel: [ 302.989545] [<ffffffff8108eb67>] ? __run_hrtimer+0x67/0x210
Feb 13 16:49:50 bras-1 kernel: [ 302.989660] [<ffffffff8108ef69>] ? hrtimer_interrupt+0xe9/0x220
Feb 13 16:49:50 bras-1 kernel: [ 302.989776] [<ffffffff81528e6b>] ? smp_apic_timer_interrupt+0x3b/0x50
Feb 13 16:49:50 bras-1 kernel: [ 302.989895] [<ffffffff81526c61>] ? apic_timer_interrupt+0x81/0x90
Feb 13 16:49:50 bras-1 kernel: [ 302.990009] <EOI>
Feb 13 16:49:50 bras-1 kernel: [ 302.990094] [<ffffffff81525744>] ? _raw_spin_lock_bh+0x34/0x40
Feb 13 16:49:50 bras-1 kernel: [ 302.990394] [<ffffffffa03e3b95>] ? fq_dump_stats+0x35/0x140 [sch_fq]
Feb 13 16:49:50 bras-1 kernel: [ 302.990512] [<ffffffff812db92c>] ? __nla_put+0xc/0x20
Feb 13 16:49:50 bras-1 kernel: [ 302.990626] [<ffffffff812db968>] ? nla_put+0x28/0x40
Feb 13 16:49:50 bras-1 kernel: [ 302.990741] [<ffffffff81427645>] ? gnet_stats_start_copy_compat+0xb5/0x160
Feb 13 16:49:50 bras-1 kernel: [ 302.990860] [<ffffffff8145507a>] ? tc_fill_qdisc+0x20a/0x2c0
Feb 13 16:49:50 bras-1 kernel: [ 302.990975] [<ffffffff814554e8>] ? qdisc_notify+0x118/0x140
Feb 13 16:49:50 bras-1 kernel: [ 302.991090] [<ffffffff81455538>] ? notify_and_destroy+0x28/0x40
Feb 13 16:49:50 bras-1 kernel: [ 302.991205] [<ffffffff814556ca>] ? qdisc_graft+0x17a/0x2d0
Feb 13 16:49:50 bras-1 kernel: [ 302.991320] [<ffffffff81456968>] ? tc_modify_qdisc+0x4a8/0x560
Dmitry
Администратор
Posts: 954
Joined: 09 Oct 2014, 10:06

Re: Call Trace

Post by Dmitry »

3.16 is buggy, try 3.18
Post Reply