Re: 5.13.14 page allocation failure

From: Udo van den Heuvel
Date: Fri Sep 10 2021 - 01:52:37 EST


Again:

Sep 9 15:06:03 surfplank2 rtkit-daemon[296496]: Successfully made thread 311799 of process 311302 (/usr/lib64/firefox/firefox) owned by '500' RT at priority 10.
Sep 9 15:12:41 knalp3 kernel: [120876.114705] warn_alloc: 2 callbacks suppressed
Sep 9 15:12:41 knalp3 kernel: [120876.114708] Web Content: page allocation failure: order:7, mode:0x40a20(GFP_ATOMIC|__GFP_COMP), nodemask=(null)
Sep 9 15:12:41 knalp3 kernel: [120876.114715] CPU: 12 PID: 312097 Comm: Web Content Not tainted 5.13.14 #5
Sep 9 15:12:41 knalp3 kernel: [120876.114717] Hardware name: Gigabyte Technology Co., Ltd. X570 AORUS PRO/X570 AORUS PRO, BIOS F34 07/08/2021
Sep 9 15:12:41 knalp3 kernel: [120876.114718] Call Trace:
Sep 9 15:12:41 knalp3 kernel: [120876.114721] dump_stack+0x64/0x7c
Sep 9 15:12:41 knalp3 kernel: [120876.114725] warn_alloc.cold+0x6f/0xd3
Sep 9 15:12:41 knalp3 kernel: [120876.114728] __alloc_pages_slowpath.constprop.0+0x6c3/0xa80
Sep 9 15:12:41 knalp3 kernel: [120876.114731] ? get_page_from_freelist+0x223/0x9d0
Sep 9 15:12:41 knalp3 kernel: [120876.114733] __alloc_pages+0x169/0x1d0
Sep 9 15:12:41 knalp3 kernel: [120876.114735] cache_alloc_refill+0x58f/0x810
Sep 9 15:12:41 knalp3 kernel: [120876.114737] ? _raw_spin_unlock+0xd/0x20
Sep 9 15:12:41 knalp3 kernel: [120876.114740] kmem_cache_alloc+0x109/0x120
Sep 9 15:12:41 knalp3 kernel: [120876.114742] p_dump_task_f+0x19/0xf0 [p_lkrg]
Sep 9 15:12:41 knalp3 kernel: [120876.114749] p_wake_up_new_task_entry+0x6b/0xe0 [p_lkrg]
Sep 9 15:12:41 knalp3 kernel: [120876.114753] pre_handler_kretprobe+0x8a/0x160
Sep 9 15:12:41 knalp3 kernel: [120876.114756] opt_pre_handler+0x4a/0x80
Sep 9 15:12:41 knalp3 kernel: [120876.114758] optimized_callback+0xbe/0xf0
Sep 9 15:12:41 knalp3 kernel: [120876.114761] 0xffffffffc0076294
Sep 9 15:12:41 knalp3 kernel: [120876.114763] ? wake_up_new_task+0x1/0x280
Sep 9 15:12:41 knalp3 kernel: [120876.114765] ? kernel_clone+0xd6/0x380
Sep 9 15:12:41 knalp3 kernel: [120876.114768] ? __do_sys_clone+0x60/0x80
Sep 9 15:12:41 knalp3 kernel: [120876.114769] ? do_syscall_64+0x61/0x80
Sep 9 15:12:41 knalp3 kernel: [120876.114771] ? exit_to_user_mode_prepare+0x19/0xf0
Sep 9 15:12:41 knalp3 kernel: [120876.114773] ? entry_SYSCALL_64_after_hwframe+0x44/0xae
Sep 9 15:12:41 knalp3 kernel: [120876.114775] Mem-Info:
Sep 9 15:12:41 knalp3 kernel: [120876.114776] active_anon:2937 inactive_anon:1070473 isolated_anon:0
Sep 9 15:12:41 knalp3 kernel: [120876.114776] active_file:2166685 inactive_file:4129380 isolated_file:0
Sep 9 15:12:41 knalp3 kernel: [120876.114776] unevictable:6793 dirty:773 writeback:0
Sep 9 15:12:41 knalp3 kernel: [120876.114776] slab_reclaimable:58285 slab_unreclaimable:200826
Sep 9 15:12:41 knalp3 kernel: [120876.114776] mapped:263480 shmem:109170 pagetables:10726 bounce:0
Sep 9 15:12:41 knalp3 kernel: [120876.114776] free:116342 free_pcp:7303 free_cma:0
Sep 9 15:12:41 knalp3 kernel: [120876.114779] Node 0 active_anon:11748kB inactive_anon:4281892kB active_file:8666740kB inactive_file:16517520kB unevictable:27172kB isolated(anon):0kB isolated(file):0kB mapped:1053920kB dirty:3092kB writeback:0kB shmem:436680kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 13352
96kB writeback_tmp:0kB kernel_stack:26276kB pagetables:42904kB all_unreclaimable? no
Sep 9 15:12:41 knalp3 kernel: [120876.114782] DMA free:11264kB min:32kB low:44kB high:56kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:15984kB managed:15360kB mlocked:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB
Sep 9 15:12:41 knalp3 kernel: [120876.114785] lowmem_reserve[]: 0 2357 31506 31506
Sep 9 15:12:41 knalp3 kernel: [120876.114787] DMA32 free:118884kB min:5052kB low:7464kB high:9876kB reserved_highatomic:26624KB active_anon:4144kB inactive_anon:713188kB active_file:581608kB inactive_file:865364kB unevictable:0kB writepending:208kB present:2521312kB managed:2455776kB mlocked:0kB bounce:0kB free
_pcp:15980kB local_pcp:1344kB free_cma:0kB
Sep 9 15:12:41 knalp3 kernel: [120876.114790] lowmem_reserve[]: 0 0 29148 29148
Sep 9 15:12:41 knalp3 kernel: [120876.114791] Normal free:335220kB min:64544kB low:94388kB high:124232kB reserved_highatomic:0KB active_anon:7604kB inactive_anon:3569388kB active_file:8085132kB inactive_file:15652668kB unevictable:27172kB writepending:2884kB present:30379008kB managed:29854648kB mlocked:24700kB
bounce:0kB free_pcp:13080kB local_pcp:688kB free_cma:0kB
Sep 9 15:12:41 knalp3 kernel: [120876.114794] lowmem_reserve[]: 0 0 0 0
Sep 9 15:12:41 knalp3 kernel: [120876.114795] DMA: 0*4kB 0*8kB 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 1*1024kB (U) 1*2048kB (M) 2*4096kB (M) = 11264kB
Sep 9 15:12:41 knalp3 kernel: [120876.114801] DMA32: 2599*4kB (UM) 4207*8kB (UM) 781*16kB (M) 144*32kB (M) 114*64kB (UM) 38*128kB (UM) 20*256kB (UM) 15*512kB (UM) 2*1024kB (M) 5*2048kB (UM) 5*4096kB (M) = 118884kB
Sep 9 15:12:41 knalp3 kernel: [120876.114807] Normal: 2676*4kB (UME) 1619*8kB (UME) 5501*16kB (UME) 2818*32kB (UME) 867*64kB (UME) 455*128kB (UME) 78*256kB (UM) 0*512kB 0*1024kB 0*2048kB 0*4096kB = 335544kB
Sep 9 15:12:41 knalp3 kernel: [120876.114813] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB
Sep 9 15:12:41 knalp3 kernel: [120876.114814] 6409485 total pagecache pages
Sep 9 15:12:41 knalp3 kernel: [120876.114815] 2 pages in swap cache
Sep 9 15:12:41 knalp3 kernel: [120876.114816] Swap cache stats: add 211, delete 209, find 61/84
Sep 9 15:12:41 knalp3 kernel: [120876.114817] Free swap = 18873080kB
Sep 9 15:12:41 knalp3 kernel: [120876.114818] Total swap = 18874360kB
Sep 9 15:12:41 knalp3 kernel: [120876.114819] 8229076 pages RAM
Sep 9 15:12:41 knalp3 kernel: [120876.114819] 0 pages HighMem/MovableOnly
Sep 9 15:12:41 knalp3 kernel: [120876.114820] 147630 pages reserved
Sep 9 15:12:41 knalp3 kernel: [120876.114820] [p_lkrg] p_alloc_ed_pids() returned NULL for pid 312164 :(
Sep 9 15:12:41 knalp3 kernel: [120876.114822] [p_lkrg] <Exploit Detection> Error[-1] when trying to add process[312164 |Web Content] for tracking!
Sep 9 15:12:41 knalp3 kernel: [120876.184547] [p_lkrg] p_alloc_ed_pids() returned NULL for pid 312165 :(
Sep 9 15:12:41 knalp3 kernel: [120876.184551] [p_lkrg] <Exploit Detection> Error[-1] when trying to add process[312165 |Socket Thread] for tracking!
Sep 9 15:12:41 knalp3 kernel: warn_alloc: 2 callbacks suppressed



Udo


On 08-09-2021 17:17, Udo van den Heuvel wrote:
Hello,

Ever since I recently migrated from AMD Ryzen 3400g to 5700g and went from 16GB to 32GB we get these:

Sep  8 16:40:44 knalp3 kernel: chrome: page allocation failure: order:7, mode:0x40a20(GFP_ATOMIC|__GFP_COMP), nodemask=(null)
Sep  8 16:40:44 knalp3 kernel: CPU: 0 PID: 156918 Comm: chrome Not tainted 5.13.14 #5
Sep  8 16:40:44 knalp3 kernel: Hardware name: Gigabyte Technology Co., Ltd. X570 AORUS PRO/X570 AORUS PRO, BIOS F34 07/08/2021
Sep  8 16:40:44 knalp3 kernel: Call Trace:
Sep  8 16:40:44 knalp3 kernel: dump_stack+0x64/0x7c
Sep  8 16:40:44 knalp3 kernel: warn_alloc.cold+0x6f/0xd3
Sep  8 16:40:44 knalp3 kernel: __alloc_pages_slowpath.constprop.0+0x6c3/0xa80
Sep  8 16:40:44 knalp3 kernel: ? get_page_from_freelist+0x223/0x9d0
Sep  8 16:40:44 knalp3 kernel: __alloc_pages+0x169/0x1d0
Sep  8 16:40:44 knalp3 kernel: cache_alloc_refill+0x58f/0x810
Sep  8 16:40:44 knalp3 kernel: ? _raw_spin_unlock+0xd/0x20
Sep  8 16:40:44 knalp3 kernel: kmem_cache_alloc+0x109/0x120
Sep  8 16:40:44 knalp3 kernel: p_dump_task_f+0x19/0xf0 [p_lkrg]
Sep  8 16:40:44 knalp3 kernel: p_wake_up_new_task_entry+0x6b/0xe0 [p_lkrg]
Sep  8 16:40:44 knalp3 kernel: pre_handler_kretprobe+0x8a/0x160
Sep  8 16:40:44 knalp3 kernel: opt_pre_handler+0x4a/0x80
Sep  8 16:40:44 knalp3 kernel: optimized_callback+0xbe/0xf0
Sep  8 16:40:44 knalp3 kernel: 0xffffffffc0076294
Sep  8 16:40:44 knalp3 kernel: ? wake_up_new_task+0x1/0x280
Sep  8 16:40:44 knalp3 kernel: ? kernel_clone+0xd6/0x380
Sep  8 16:40:44 knalp3 kernel: ? __do_sys_clone+0x60/0x80
Sep  8 16:40:44 knalp3 kernel: ? do_syscall_64+0x61/0x80
Sep  8 16:40:44 knalp3 kernel: ? entry_SYSCALL_64_after_hwframe+0x44/0xae
Sep  8 16:40:44 knalp3 kernel: Mem-Info:
Sep  8 16:40:44 knalp3 kernel: active_anon:5244 inactive_anon:1494070 isolated_anon:0#012 active_file:1098885 inactive_file:4703460 isolated_file:0#012 unevictable:639 dirty:948 writeback:0#012 slab_reclaimable:74606 slab_unreclaimable:245109#012 mapped:347626 shmem:160084 pagetables:16000 bounce:0#012 free:98085 free_pcp:4475 free_cma:0
Sep  8 16:40:44 knalp3 kernel: Node 0 active_anon:20976kB inactive_anon:5976280kB active_file:4395540kB inactive_file:18813840kB unevictable:2556kB isolated(anon):0kB isolated(file):0kB mapped:1390504kB dirty:3792kB writeback:0kB shmem:640336kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 1681408kB writeback_tmp:0kB kernel_stack:31376kB pagetables:64000kB all_unreclaimable? no
Sep  8 16:40:44 knalp3 kernel: DMA free:11264kB min:32kB low:44kB high:56kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:15984kB managed:15360kB mlocked:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB
Sep  8 16:40:44 knalp3 kernel: lowmem_reserve[]: 0 2357 31506 31506
Sep  8 16:40:44 knalp3 kernel: DMA32 free:118968kB min:5052kB low:7464kB high:9876kB reserved_highatomic:16384KB active_anon:124kB inactive_anon:772432kB active_file:166476kB inactive_file:1282188kB unevictable:16kB writepending:368kB present:2521312kB managed:2455776kB mlocked:16kB bounce:0kB free_pcp:3528kB local_pcp:0kB free_cma:0kB
Sep  8 16:40:44 knalp3 kernel: lowmem_reserve[]: 0 0 29148 29148
Sep  8 16:40:44 knalp3 kernel: Normal free:262612kB min:64544kB low:94388kB high:124232kB reserved_highatomic:0KB active_anon:20488kB inactive_anon:5202876kB active_file:4228640kB inactive_file:17532192kB unevictable:2540kB writepending:3424kB present:30379008kB managed:29854648kB mlocked:68kB bounce:0kB free_pcp:14248kB local_pcp:64kB free_cma:0kB
Sep  8 16:40:44 knalp3 kernel: lowmem_reserve[]: 0 0 0 0
Sep  8 16:40:44 knalp3 kernel: DMA: 0*4kB 0*8kB 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 1*1024kB (U) 1*2048kB (M) 2*4096kB (M) = 11264kB
Sep  8 16:40:44 knalp3 kernel: DMA32: 494*4kB (UM) 132*8kB (UM) 194*16kB (M) 710*32kB (M) 224*64kB (UM) 24*128kB (UM) 18*256kB (UM) 13*512kB (M) 6*1024kB (M) 9*2048kB (M) 9*4096kB (M) = 118968kB
Sep  8 16:40:44 knalp3 kernel: Normal: 570*4kB (UME) 2546*8kB (UME) 2503*16kB (UME) 1391*32kB (UME) 848*64kB (UME) 549*128kB (UME) 114*256kB (U) 1*512kB (M) 0*1024kB 0*2048kB 0*4096kB = 261448kB
Sep  8 16:40:44 knalp3 kernel: Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB
Sep  8 16:40:44 knalp3 kernel: 5963328 total pagecache pages
Sep  8 16:40:44 knalp3 kernel: 0 pages in swap cache
Sep  8 16:40:44 knalp3 kernel: Swap cache stats: add 170, delete 169, find 61/83
Sep  8 16:40:44 knalp3 kernel: Free swap  = 18873848kB
Sep  8 16:40:44 knalp3 kernel: Total swap = 18874360kB
Sep  8 16:40:44 knalp3 kernel: 8229076 pages RAM
Sep  8 16:40:44 knalp3 kernel: 0 pages HighMem/MovableOnly
Sep  8 16:40:44 knalp3 kernel: 147630 pages reserved
Sep  8 16:40:44 knalp3 kernel: [p_lkrg] p_alloc_ed_pids() returned NULL for pid 156929 :(

This did not happen (unless there really was an OOM) when using 16GB of RAM.

The RAM is from AMD's HCL for AMD Ryzen 3000+. The RAM was tested with memtest86 with no errors. (only a night, but still)

How can I find out what is going on?
Did I misconfigure my kernel?
Do I make to configure something to use the 'immense' amount of 32GB with no issues?

Please let me know.


Kind regards,
Udo