[<prev] [next>] [day] [month] [year] [list]
Message-ID: <CAA85sZt-CamVe24=i=rtoU+oN5twF8sj4imCKR=Ode0ezM0Grg@mail.gmail.com>
Date: Sat, 29 Jul 2023 21:06:14 +0200
From: Ian Kumlien <ian.kumlien@...il.com>
To: Linux Kernel Network Developers <netdev@...r.kernel.org>,
intel-wired-lan <intel-wired-lan@...ts.osuosl.org>
Subject: [6.4.7] pfifo_fast bug with ixgbe?
Had played with sysctl, forgot to change back to fq - so was running
with pfifo_fast and this happened :)
[94875.282334] ------------[ cut here ]------------
[94875.282347] NETDEV WATCHDOG: eno1 (ixgbe): transmit queue 6 timed out 8232 ms
[94875.282424] WARNING: CPU: 2 PID: 0 at net/sched/sch_generic.c:525
dev_watchdog (net/sched/sch_generic.c:525 (discriminator 3))
[94875.282442] Modules linked in: chaoskey
[94875.282454] CPU: 2 PID: 0 Comm: swapper/2 Not tainted 6.4.7 #383
[94875.282464] Hardware name: Supermicro Super Server/A2SDi-12C-HLN4F,
BIOS 1.7a 10/13/2022
[94875.282469] RIP: 0010:dev_watchdog (net/sched/sch_generic.c:525
(discriminator 3))
[94875.282480] Code: ff ff ff 48 89 df c6 05 6c e1 f9 00 01 e8 d0 27
fa ff 45 89 f8 44 89 f1 48 89 de 48 89 c2 48 c7 c7 20 7d 0c 9d e8 48
9a 2a ff <0f> 0b e9 2a ff ff ff 90 55 53 48 89 fb 48 8b 6f 18 0f 1f 44
00 00
All code
========
0: ff (bad)
1: ff (bad)
2: ff 48 89 decl -0x77(%rax)
5: df c6 ffreep %st(6)
7: 05 6c e1 f9 00 add $0xf9e16c,%eax
c: 01 e8 add %ebp,%eax
e: d0 27 shlb (%rdi)
10: fa cli
11: ff 45 89 incl -0x77(%rbp)
14: f8 clc
15: 44 89 f1 mov %r14d,%ecx
18: 48 89 de mov %rbx,%rsi
1b: 48 89 c2 mov %rax,%rdx
1e: 48 c7 c7 20 7d 0c 9d mov $0xffffffff9d0c7d20,%rdi
25: e8 48 9a 2a ff call 0xffffffffff2a9a72
2a:* 0f 0b ud2 <-- trapping instruction
2c: e9 2a ff ff ff jmp 0xffffffffffffff5b
31: 90 nop
32: 55 push %rbp
33: 53 push %rbx
34: 48 89 fb mov %rdi,%rbx
37: 48 8b 6f 18 mov 0x18(%rdi),%rbp
3b: 0f 1f 44 00 00 nopl 0x0(%rax,%rax,1)
Code starting with the faulting instruction
===========================================
0: 0f 0b ud2
2: e9 2a ff ff ff jmp 0xffffffffffffff31
7: 90 nop
8: 55 push %rbp
9: 53 push %rbx
a: 48 89 fb mov %rdi,%rbx
d: 48 8b 6f 18 mov 0x18(%rdi),%rbp
11: 0f 1f 44 00 00 nopl 0x0(%rax,%rax,1)
[94875.282488] RSP: 0018:ffffac3480150ea8 EFLAGS: 00010282
[94875.282498] RAX: 0000000000000000 RBX: ffff8c7cc5aac000 RCX: 0000000000000027
[94875.282504] RDX: ffff8c802fa9b408 RSI: 0000000000000001 RDI: ffff8c802fa9b400
[94875.282510] RBP: ffff8c7cc5aac488 R08: 0000000000000000 R09: ffffac3480150d48
[94875.282516] R10: 0000000000000003 R11: ffffffff9d347ec8 R12: ffff8c7cc5c08780
[94875.282522] R13: ffff8c7cc5aac3dc R14: 0000000000000006 R15: 0000000000002028
[94875.282528] FS: 0000000000000000(0000) GS:ffff8c802fa80000(0000)
knlGS:0000000000000000
[94875.282536] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[94875.282542] CR2: 00007fd2f57b9a94 CR3: 0000000103fda000 CR4: 00000000003526e0
[94875.282548] Call Trace:
[94875.282556] <IRQ>
[94875.282561] ? dev_watchdog (net/sched/sch_generic.c:525 (discriminator 3))
[94875.282569] ? __warn (kernel/panic.c:673)
[94875.282579] ? dev_watchdog (net/sched/sch_generic.c:525 (discriminator 3))
[94875.282587] ? report_bug (lib/bug.c:180 lib/bug.c:219)
[94875.282597] ? handle_bug (arch/x86/kernel/traps.c:324)
[94875.282606] ? exc_invalid_op (arch/x86/kernel/traps.c:345 (discriminator 1))
[94875.282615] ? asm_exc_invalid_op (./arch/x86/include/asm/idtentry.h:568)
[94875.282628] ? dev_watchdog (net/sched/sch_generic.c:525 (discriminator 3))
[94875.282635] ? dev_watchdog (net/sched/sch_generic.c:525 (discriminator 3))
[94875.282642] ? pfifo_fast_reset (net/sched/sch_generic.c:496)
[94875.282649] ? pfifo_fast_reset (net/sched/sch_generic.c:496)
[94875.282656] call_timer_fn (./arch/x86/include/asm/jump_label.h:27
./include/linux/jump_label.h:207 ./include/trace/events/timer.h:127
kernel/time/timer.c:1701)
[94875.282669] __run_timers.part.0 (kernel/time/timer.c:1752
kernel/time/timer.c:2022)
[94875.282682] ? __hrtimer_run_queues (./include/linux/seqlock.h:612
kernel/time/hrtimer.c:1711 kernel/time/hrtimer.c:1749)
[94875.282690] ? recalibrate_cpu_khz (./arch/x86/include/asm/msr.h:215
arch/x86/kernel/tsc.c:1110)
[94875.282699] ? ktime_get (kernel/time/timekeeping.c:292
(discriminator 3) kernel/time/timekeeping.c:388 (discriminator 3)
kernel/time/timekeeping.c:848 (discriminator 3))
[94875.282709] run_timer_softirq (kernel/time/timer.c:2037)
[94875.282720] __do_softirq (./arch/x86/include/asm/jump_label.h:27
./include/linux/jump_label.h:207 ./include/trace/events/irq.h:142
kernel/softirq.c:572)
[94875.282732] irq_exit_rcu (kernel/softirq.c:445 kernel/softirq.c:650
kernel/softirq.c:662)
[94875.282743] sysvec_apic_timer_interrupt
(arch/x86/kernel/apic/apic.c:1106 (discriminator 14))
[94875.282755] </IRQ>
[94875.282759] <TASK>
[94875.282763] asm_sysvec_apic_timer_interrupt
(./arch/x86/include/asm/idtentry.h:645)
[94875.282775] RIP: 0010:cpuidle_enter_state (drivers/cpuidle/cpuidle.c:291)
[94875.282787] Code: 00 e8 e2 01 fd fe e8 7d fa ff ff 8b 53 04 49 89
c5 0f 1f 44 00 00 31 ff e8 5b 71 fc fe 45 84 ff 0f 85 60 02 00 00 fb
45 85 f6 <0f> 88 8e 01 00 00 49 63 ce 4c 8b 14 24 48 8d 04 49 48 8d 14
81 48
All code
========
0: 00 e8 add %ch,%al
2: e2 01 loop 0x5
4: fd std
5: fe (bad)
6: e8 7d fa ff ff call 0xfffffffffffffa88
b: 8b 53 04 mov 0x4(%rbx),%edx
e: 49 89 c5 mov %rax,%r13
11: 0f 1f 44 00 00 nopl 0x0(%rax,%rax,1)
16: 31 ff xor %edi,%edi
18: e8 5b 71 fc fe call 0xfffffffffefc7178
1d: 45 84 ff test %r15b,%r15b
20: 0f 85 60 02 00 00 jne 0x286
26: fb sti
27: 45 85 f6 test %r14d,%r14d
2a:* 0f 88 8e 01 00 00 js 0x1be <-- trapping instruction
30: 49 63 ce movslq %r14d,%rcx
33: 4c 8b 14 24 mov (%rsp),%r10
37: 48 8d 04 49 lea (%rcx,%rcx,2),%rax
3b: 48 8d 14 81 lea (%rcx,%rax,4),%rdx
3f: 48 rex.W
Code starting with the faulting instruction
===========================================
0: 0f 88 8e 01 00 00 js 0x194
6: 49 63 ce movslq %r14d,%rcx
9: 4c 8b 14 24 mov (%rsp),%r10
d: 48 8d 04 49 lea (%rcx,%rcx,2),%rax
11: 48 8d 14 81 lea (%rcx,%rax,4),%rdx
15: 48 rex.W
[94875.282794] RSP: 0018:ffffac34800a7e98 EFLAGS: 00000202
[94875.282803] RAX: ffff8c802faabb80 RBX: ffff8c802fab6600 RCX: 0000000000000000
[94875.282809] RDX: 0000000000000002 RSI: fffffff7a29adb93 RDI: 0000000000000000
[94875.282814] RBP: 0000000000000002 R08: 0000000000000000 R09: 0000000040000000
[94875.282820] R10: 0000000000000018 R11: 00000000000003d8 R12: ffffffff9d415480
[94875.282825] R13: 00005649df803d70 R14: 0000000000000002 R15: 0000000000000000
[94875.282835] ? cpuidle_enter_state (drivers/cpuidle/cpuidle.c:285)
[94875.282848] cpuidle_enter (drivers/cpuidle/cpuidle.c:390)
[94875.282857] do_idle (kernel/sched/idle.c:219 kernel/sched/idle.c:282)
[94875.282868] cpu_startup_entry (kernel/sched/idle.c:378 (discriminator 1))
[94875.282878] start_secondary (arch/x86/kernel/smpboot.c:288)
[94875.282887] secondary_startup_64_no_verify (arch/x86/kernel/head_64.S:370)
[94875.282900] </TASK>
[94875.282904] ---[ end trace 0000000000000000 ]---
Which in turn ended up with:
[94875.282948] ixgbe 0000:06:00.0 eno1: Reset adapter
[94884.121100] rcu: INFO: rcu_preempt self-detected stall on CPU
[94884.126957] rcu: 8-...!: (20999 ticks this GP)
idle=1774/1/0x4000000000000000 softirq=987317/987317 fqs=625
[94884.136888] rcu: (t=21015 jiffies g=2224957 q=302 ncpus=12)
[94884.142640] rcu: rcu_preempt kthread timer wakeup didn't happen for
18517 jiffies! g2224957 f0x0 RCU_GP_WAIT_FQS(5) ->state=0x402
[94884.154385] rcu: Possible timer handling issue on cpu=4
timer-softirq=784094
[94884.161611] rcu: rcu_preempt kthread starved for 18540 jiffies!
g2224957 f0x0 RCU_GP_WAIT_FQS(5) ->state=0x402 ->cpu=4
[94884.172399] rcu: Unless rcu_preempt kthread gets sufficient CPU
time, OOM is now expected behavior.
[94884.181621] rcu: RCU grace-period kthread stack dump:
[94884.186909] rcu: Stack dump where RCU GP kthread last ran:
[94947.192496] rcu: INFO: rcu_preempt self-detected stall on CPU
[94947.198353] rcu: 8-....: (84001 ticks this GP)
idle=1774/1/0x4000000000000000 softirq=987317/987317 fqs=16377
[94947.208456] rcu: (t=84088 jiffies g=2224957 q=828 ncpus=12)
Eventually the watchdog kicked in and rebooted the machine
Powered by blists - more mailing lists