On Thu, 12 Aug 2021 23:30:29 +0200 Vlastimil Babka <vbabka@xxxxxxx> wrote: > On 8/12/21 11:24 PM, Clark Williams wrote: > > On Thu, 12 Aug 2021 22:45:19 +0200 > > Vlastimil Babka <vbabka@xxxxxxx> wrote: > > > >> On 8/12/21 10:18 PM, Clark Williams wrote: > >>> On Tue, 10 Aug 2021 18:37:31 +0200 > >>> Sebastian Andrzej Siewior <bigeasy@xxxxxxxxxxxxx> wrote: > >>> > >>> Sebastian, et al, > >>> > >>> Got the following panic running v5.14-rc5-rt8: > >>> > >>> Aug 13 06:35:05 oberon kernel: page:000000009ac5dd73 refcount:0 mapcount:0 mapping:0000000000000000 index:0x0 pfn:0x1ab3db > >>> Aug 13 06:35:05 oberon kernel: flags: 0x17ffffc0000000(node=0|zone=2|lastcpupid=0x1fffff) > >>> Aug 13 06:35:05 oberon kernel: raw: 0017ffffc0000000 ffffee1286aceb88 ffffee1287b66288 0000000000000000 > >>> Aug 13 06:35:05 oberon kernel: raw: 0000000000000000 0000000000100000 00000000ffffffff 0000000000000000 > >>> Aug 13 06:35:05 oberon kernel: page dumped because: VM_BUG_ON_PAGE(!PageSlab(page)) > >>> Aug 13 06:35:05 oberon kernel: ------------[ cut here ]------------ > >>> Aug 13 06:35:05 oberon kernel: kernel BUG at include/linux/page-flags.h:814! > >>> Aug 13 06:35:05 oberon kernel: invalid opcode: 0000 [#1] PREEMPT_RT SMP PTI > >>> Aug 13 06:35:05 oberon kernel: CPU: 3 PID: 12345 Comm: hackbench Not tainted 5.14.0-rc5-rt8+ #12 > >>> Aug 13 06:35:05 oberon kernel: Hardware name: /NUC5i7RYB, BIOS RYBDWi35.86A.0359.2016.0906.1028 09/06/2016 > >>> Aug 13 06:35:05 oberon kernel: RIP: 0010:___slab_alloc+0x340/0x940 > >> > >> Are you able to translate this RIP via addr2line? > > > > $ addr2line -e /data/o/linux-5.14.y-rt/vmlinux ___slab_alloc+0x340/0x940 > > <snip>/arch/x86/include/asm/processor.h:440 > > Hm that's not much useful, I'd need the line in mm/slub.c > does ./scripts/faddr2line give better output? Why, yes it does! :) $ ./scripts/faddr2line /data/o/linux-5.14.y-rt/vmlinux ___slab_alloc+0x340/0x940 ___slab_alloc+0x340/0x940: PageSlabPfmemalloc at include/linux/page-flags.h:814 (inlined by) pfmemalloc_match at mm/slub.c:2772 (inlined by) ___slab_alloc at mm/slub.c:2874 > > >> > >>> Aug 13 06:35:05 oberon kernel: Code: c6 48 0f a3 05 b1 7b 57 03 72 99 c7 85 78 ff ff ff ff ff ff ff 48 8b 7d 88 e9 8d fd ff ff 48 c7 c6 50 5a 7c b0 e> > >>> Aug 13 06:35:05 oberon kernel: RSP: 0018:ffffba1c4a8b7ab0 EFLAGS: 00010293 > >>> Aug 13 06:35:05 oberon kernel: RAX: 0000000000000000 RBX: 0000000000000002 RCX: ffff9bb765118000 > >>> Aug 13 06:35:05 oberon kernel: RDX: 0000000000000000 RSI: ffffffffaf426050 RDI: 00000000ffffffff > >>> Aug 13 06:35:05 oberon kernel: RBP: ffffba1c4a8b7b70 R08: 0000000000000000 R09: 0000000000000000 > >>> Aug 13 06:35:05 oberon kernel: R10: 0000000000000000 R11: 0000000000000000 R12: ffff9bb7410d3600 > >>> Aug 13 06:35:05 oberon kernel: R13: 0000000000400cc0 R14: 00000000001f7770 R15: ffff9bbe76df7770 > >>> Aug 13 06:35:05 oberon kernel: FS: 00007f474b1be740(0000) GS:ffff9bbe76c00000(0000) knlGS:0000000000000000 > >>> Aug 13 06:35:05 oberon kernel: CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 > >>> Aug 13 06:35:05 oberon kernel: CR2: 00007f60c04bdaf8 CR3: 0000000124f3a003 CR4: 00000000003706e0 > >>> Aug 13 06:35:05 oberon kernel: Call Trace: > >>> Aug 13 06:35:05 oberon kernel: ? __alloc_skb+0x1db/0x270 > >>> Aug 13 06:35:05 oberon kernel: ? __alloc_skb+0x1db/0x270 > >>> Aug 13 06:35:05 oberon kernel: ? kmem_cache_alloc_node+0xa4/0x2b0 > >>> Aug 13 06:35:05 oberon kernel: kmem_cache_alloc_node+0xa4/0x2b0 > >>> Aug 13 06:35:05 oberon kernel: __alloc_skb+0x1db/0x270 > >>> Aug 13 06:35:05 oberon kernel: alloc_skb_with_frags+0x64/0x250 > >>> Aug 13 06:35:05 oberon kernel: sock_alloc_send_pskb+0x260/0x2b0 > >>> Aug 13 06:35:05 oberon kernel: ? bpf_lsm_socket_getpeersec_dgram+0xa/0x10 > >>> Aug 13 06:35:05 oberon kernel: unix_stream_sendmsg+0x27c/0x550 > >>> Aug 13 06:35:05 oberon kernel: ? unix_seqpacket_recvmsg+0x60/0x60 > >>> Aug 13 06:35:05 oberon kernel: sock_sendmsg+0xbd/0xd0 > >>> Aug 13 06:35:05 oberon kernel: sock_write_iter+0xb9/0x120 > >>> Aug 13 06:35:05 oberon kernel: new_sync_write+0x175/0x200 > >>> Aug 13 06:35:05 oberon kernel: vfs_write+0x3c4/0x510 > >>> Aug 13 06:35:05 oberon kernel: ksys_write+0xc9/0x110 > >>> Aug 13 06:35:05 oberon kernel: do_syscall_64+0x3b/0x90 > >>> Aug 13 06:35:05 oberon kernel: entry_SYSCALL_64_after_hwframe+0x44/0xae > >> > >> While SLUB RT rewrite is obvious suspect, could be also a boring slab > >> misuse (use-after-free etc), wouldn't be the first related to skb's... > >> If this reproduces well, could you try booting with slub_debug boot > >> param. Could catch the culprit sooner (but also hide the bug, > >> unfortunately). > > > > Without 'slub_debug' it panic's consistently. > > > > Adding 'slub_debug' to the boot command line causes odd behavior where load and measurement tasks > > run by 'rteval' crash, but (so far) no kernel panic. > > Huh. And nothing in dmesg about corrupted slabs with slub_debug? No, nothing slab related in the log. Typical gnome-shell complaints and a lockdep complaint about MAX_LOCKDEP_ENTRIES too low, but nothing really wierd. > > >>> Aug 13 06:35:05 oberon kernel: RIP: 0033:0x7f474b3a2877 > >>> Aug 13 06:35:05 oberon kernel: Code: 75 05 48 83 c4 58 c3 e8 37 4e ff ff 0f 1f 80 00 00 00 00 f3 0f 1e fa 64 8b 04 25 18 00 00 00 85 c0 75 10 b8 01 0> > >>> Aug 13 06:35:05 oberon kernel: RSP: 002b:00007ffe5e71e7a8 EFLAGS: 00000246 ORIG_RAX: 0000000000000001 > >>> Aug 13 06:35:05 oberon kernel: RAX: ffffffffffffffda RBX: 00000000000003e8 RCX: 00007f474b3a2877 > >>> Aug 13 06:35:05 oberon kernel: RDX: 00000000000003e8 RSI: 00007ffe5e71e7b0 RDI: 0000000000000010 > >>> Aug 13 06:35:05 oberon kernel: RBP: 00007ffe5e71ebf0 R08: 00007ffe5e71e700 R09: 0000000000000000 > >>> Aug 13 06:35:05 oberon kernel: R10: 0000000000000008 R11: 0000000000000246 R12: 00007ffe5e71e7b0 > >>> Aug 13 06:35:05 oberon kernel: R13: 0000000000000008 R14: 0000560b46008210 R15: 0000000000000000 > >>> Aug 13 06:35:05 oberon kernel: Modules linked in: uinput rfcomm snd_seq_dummy snd_hrtimer xt_CHECKSUM xt_MASQUERADE xt_conntrack ipt_REJECT nf_nat_tf> > >>> Aug 13 06:35:05 oberon kernel: snd_intel_dspcfg snd_hda_codec mei_hdcp snd_hda_core snd_hwdep cfg80211 snd_seq iTCO_wdt snd_seq_device intel_pmc_bxt> > >>> Aug 13 06:35:05 oberon kernel: ---[ end trace 0000000000000002 ]--- > >>> Aug 13 06:35:05 oberon kernel: RIP: 0010:___slab_alloc+0x340/0x940 > >>> Aug 13 06:35:05 oberon kernel: Code: c6 48 0f a3 05 b1 7b 57 03 72 99 c7 85 78 ff ff ff ff ff ff ff 48 8b 7d 88 e9 8d fd ff ff 48 c7 c6 50 5a 7c b0 e> > >>> Aug 13 06:35:05 oberon kernel: RSP: 0018:ffffba1c4a8b7ab0 EFLAGS: 00010293 > >>> Aug 13 06:35:05 oberon kernel: RAX: 0000000000000000 RBX: 0000000000000002 RCX: ffff9bb765118000 > >>> Aug 13 06:35:05 oberon kernel: RDX: 0000000000000000 RSI: ffffffffaf426050 RDI: 00000000ffffffff > >>> Aug 13 06:35:05 oberon kernel: RBP: ffffba1c4a8b7b70 R08: 0000000000000000 R09: 0000000000000000 > >>> Aug 13 06:35:05 oberon kernel: R10: 0000000000000000 R11: 0000000000000000 R12: ffff9bb7410d3600 > >>> Aug 13 06:35:05 oberon kernel: R13: 0000000000400cc0 R14: 00000000001f7770 R15: ffff9bbe76df7770 > >>> Aug 13 06:35:05 oberon kernel: FS: 00007f474b1be740(0000) GS:ffff9bbe76c00000(0000) knlGS:0000000000000000 > >>> Aug 13 06:35:05 oberon kernel: CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 > >>> Aug 13 06:35:05 oberon kernel: CR2: 00007f60c1d901a0 CR3: 0000000124f3a003 CR4: 00000000003706e0 > >>> Aug 13 06:35:05 oberon kernel: Kernel panic - not syncing: > >>> > >>> Config is attached. > >>> > >>> I was running the rteval script that kicks off parallel kernel builds and hackbench runs as loads and runs cyclictest with a thread on each core: > >>> > >>> $ sudo rteval --duration=10m > >>> > >>> Clark > >>> > >> > > > > > -- The United States Coast Guard Ruining Natural Selection since 1790
