Linux next-20220412 i386 LTP mm test case caused following kernel crash
however, test runs to complete successfully.
tst_test.c:1422: TINFO: Timeout per run is disabled
mem.c:708: TINFO: set overcommit_memory to 2
mem.c:708: TINFO: set min_free_kbytes to 41716
memfree is 2916072 kB before eatup mem
memfree is 1585764 kB after eatup mem
mem.c:708: TINFO: set min_free_kbytes to 83432
memfree is 2915168 kB before eatup mem
memfree is 1583424 kB after eatup mem
mem.c:708: TINFO: set min_free_kbytes to 61704
memfree is 2909960 kB before eatup mem
memfree is 1583480 kB after eatup mem
mem.c:708: TINFO: set overcommit_memory to 0
mem.c:708: TINFO: set min_free_kbytes to 41716
[ 951.727784] systemd invoked oom-killer:
gfp_mask=0x140cca(GFP_HIGHUSER_MOVABLE|__GFP_COMP), order=0,
oom_score_adj=0
[ 951.730905] CPU: 1 PID: 1 Comm: systemd Tainted: G W
5.18.0-rc2-next-20220412 #1
[ 951.732870] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996),
BIOS 1.12.0-1 04/01/2014
[ 951.734537] Call Trace:
[ 951.735061] dump_stack_lvl+0x44/0x57
[ 951.735904] dump_stack+0xd/0x10
[ 951.736572] dump_header+0x45/0x1d4
[ 951.737417] ? ___ratelimit+0x73/0x110
[ 951.738641] ? preempt_count_sub+0xc1/0x110
[ 951.739338] ? oom_badness+0x125/0x1b0
[ 951.739963] oom_kill_process.cold+0x9/0xe
[ 951.740675] out_of_memory+0xe1/0x2c0
[ 951.741447] __alloc_pages+0xd02/0xdf0
[ 951.742104] ? finish_task_switch+0xa4/0x2c0
[ 951.742806] __folio_alloc+0x22/0x50
[ 951.743360] __filemap_get_folio+0x1a3/0x340
[ 951.744050] filemap_fault+0x176/0x880
[ 951.744609] ? next_uptodate_page+0x240/0x240
[ 951.745412] __do_fault+0x32/0xa0
[ 951.745920] handle_mm_fault+0x974/0xe80
[ 951.746676] do_user_addr_fault+0x17b/0x530
[ 951.747531] ? irqentry_enter+0x45/0x50
[ 951.748368] exc_page_fault+0x4d/0x160
[ 951.749170] ? do_fast_syscall_32+0x32/0x70
[ 951.750063] ? paravirt_BUG+0x10/0x10
[ 951.750725] handle_exception+0x133/0x133
[ 951.751397] EIP: 0xb7e2091f
[ 951.751875] Code: Unable to access opcode bytes at RIP 0xb7e208f5.
[ 951.752877] EAX: 00000000 EBX: b7f8c2e4 ECX: bfcf2464 EDX: 00000000
[ 951.754163] ESI: bfcf2464 EDI: 0000002c EBP: bfcf2798 ESP: bfcf2450
[ 951.755463] DS: 007b ES: 007b FS: 0000 GS: 0033 SS: 007b EFLAGS: 00010207
[ 951.756880] ? paravirt_BUG+0x10/0x10
[ 951.757927] Mem-Info:
[ 951.758557] active_anon:61 inactive_anon:732341 isolated_anon:0
[ 951.758557] active_file:71 inactive_file:14 isolated_file:0
[ 951.758557] unevictable:0 dirty:25 writeback:10
[ 951.758557] slab_reclaimable:2308 slab_unreclaimable:3155
[ 951.758557] mapped:344 shmem:2200 pagetables:898 bounce:0
[ 951.758557] kernel_misc_reclaimable:0
[ 951.758557] free:31053 free_pcp:333 free_cma:0
[ 951.787248] Node 0 active_anon:244kB inactive_anon:2929364kB
active_file:16kB inactive_file:116kB unevictable:0kB
isolated(anon):0kB isolated(file):0kB mapped:1376kB dirty:100kB
writeback:40kB shmem:8800kB shmem_thp: 0kB shmem_pmdmapped: 0kB
anon_thp: 0kB writeback_tmp:0kB kernel_stack:1016kB pagetables:3592kB
all_unreclaimable? no
[ 951.791525] DMA free:12732kB boost:0kB min:760kB low:948kB
high:1136kB reserved_highatomic:0KB active_anon:0kB
inactive_anon:2628kB active_file:0kB inactive_file:0kB unevictable:0kB
writepending:0kB present:15992kB managed:15360kB mlocked:0kB
bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB
[ 951.795238] lowmem_reserve[]: 0 805 2997 2997
[ 951.795896] Normal free:111020kB boost:0kB min:40952kB low:51188kB
high:61424kB reserved_highatomic:0KB active_anon:0kB
inactive_anon:684420kB active_file:0kB inactive_file:372kB
unevictable:0kB writepending:0kB present:884728kB managed:825372kB
mlocked:0kB bounce:0kB free_pcp:560kB local_pcp:8kB free_cma:0kB
[ 951.799843] lowmem_reserve[]: 0 0 17535 17535
[ 951.800479] HighMem free:460kB boost:0kB min:512kB low:28352kB
high:56192kB reserved_highatomic:0KB active_anon:244kB
inactive_anon:2242168kB active_file:16kB inactive_file:288kB
unevictable:0kB writepending:140kB present:2244480kB managed:2244480kB
mlocked:0kB bounce:0kB free_pcp:912kB local_pcp:560kB free_cma:0kB
[ 951.804447] lowmem_reserve[]: 0 0 0 0
[ 951.805010] DMA: 1*4kB (M) 1*8kB (M) 1*16kB (M) 1*32kB (M) 0*64kB
1*128kB (M) 1*256kB (M) 0*512kB 2*1024kB (UM) 1*2048kB (U) 2*4096kB
(M) = 12732kB
[ 951.807652] Normal: 925*4kB (UE) 807*8kB (UE) 586*16kB (UME)
391*32kB (UE) 227*64kB (UME) 129*128kB (UME) 30*256kB (UE) 14*512kB
(UE) 6*1024kB (UME) 1*2048kB (E) 6*4096kB (M) = 110700kB
[ 951.810641] HighMem: 37*4kB (M) 13*8kB (M) 9*16kB (M) 0*32kB 1*64kB
(M) 1*128kB (M) 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 588kB
[ 951.812794] Node 0 hugepages_total=0 hugepages_free=0
hugepages_surp=0 hugepages_size=4096kB
[ 951.814031] 2313 total pagecache pages
[ 951.814588] 0 pages in swap cache
[ 951.815098] Swap cache stats: add 0, delete 0, find 0/0
[ 951.815867] Free swap = 0kB
[ 951.816298] Total swap = 0kB
[ 951.816729] 786300 pages RAM
[ 951.817228] 561120 pages HighMem/MovableOnly
[ 951.817873] 14997 pages reserved
[ 951.818348] Tasks state (memory values in pages):
[ 951.819043] [ pid ] uid tgid total_vm rss pgtables_bytes
swapents oom_score_adj name
[ 951.820291] [ 204] 0 204 3450 354 28672
0 0 systemd-journal
[ 951.821628] [ 241] 0 241 3283 124 20480
0 -1000 systemd-udevd
[ 951.822922] [ 256] 993 256 1569 59 20480
0 0 systemd-network
[ 951.824232] [ 265] 992 265 1729 77 20480
0 0 systemd-resolve
[ 951.825591] [ 269] 0 269 811 63 16384
0 0 crond
[ 951.826788] [ 272] 0 272 594 16 16384
0 0 acpid
[ 951.827972] [ 273] 0 273 1468 84 20480
0 0 systemd-logind
[ 951.829278] [ 274] 995 274 1246 115 24576
0 0 avahi-daemon
[ 951.830565] [ 276] 0 276 921 17 16384
0 0 syslogd
[ 951.831775] [ 277] 996 277 1173 109 20480
0 -900 dbus-daemon
[ 951.833062] [ 280] 995 280 1182 58 20480
0 0 avahi-daemon
[ 951.834812] [ 288] 0 288 14090 365 53248
0 0 NetworkManager
[ 951.836476] [ 289] 0 289 922 14 16384
0 0 klogd
[ 951.837686] [ 293] 0 293 2069 790 24576
0 0 haveged
[ 951.838904] [ 326] 0 326 947 94 16384
0 0 login
[ 951.840095] [ 327] 0 327 666 26 16384
0 0 agetty
[ 951.841315] [ 332] 65534 332 729 32 16384
0 0 dnsmasq
[ 951.842524] [ 337] 998 337 19521 745 69632
0 0 polkitd
[ 951.843735] [ 338] 0 338 8698 317 36864
0 0 dhclient
[ 951.844960] [ 353] 0 353 1892 100 20480
0 0 systemd
[ 951.846197] [ 354] 0 354 2317 295 20480
0 0 (sd-pam)
[ 951.847417] [ 358] 0 358 971 72 16384
0 0 sh
[ 951.848580] [ 373] 0 373 903 39 16384
0 0 lava-test-runne
[ 951.849911] [ 1087] 0 1087 903 25 16384
0 0 lava-test-shell
[ 951.851232] [ 1088] 0 1088 903 25 16384
0 0 sh
[ 951.852382] [ 1090] 0 1090 969 113 16384
0 0 ltp.sh
[ 951.853821] [ 1109] 0 1109 969 116 16384
0 0 ltp.sh
[ 951.855456] [ 1110] 0 1110 969 115 16384
0 0 ltp.sh
[ 951.856922] [ 1111] 0 1111 969 115 16384
0 0 ltp.sh
[ 951.858149] [ 1112] 0 1112 1002 148 16384
0 0 runltp
[ 951.859347] [ 1113] 0 1113 592 11 16384
0 0 tee
[ 951.860505] [ 1180] 0 1180 590 25 16384
0 0 ltp-pan
[ 951.861999] [ 20965] 0 20965 673 22 16384
0 -1000 min_free_kbytes
[ 951.863792] [ 20966] 0 20966 673 27 16384
0 0 min_free_kbytes
[ 951.865275] [ 20972] 0 20972 726433 725743 2912256
0 0 min_free_kbytes
[ 951.866586] oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/,task=min_free_kbytes,pid=20972,uid=0
[ 951.868579] Out of memory: Killed process 20972 (min_free_kbytes)
total-vm:2905732kB, anon-rss:2902972kB, file-rss:0kB, shmem-rss:0kB,
UID:0 pgtables:2844kB oom_score_adj:0
[ 951.985382] __swap_info_get: Bad swap file entry 6c0373b6
[ 951.986163] BUG: Bad page map in process oom_reaper pte:06e76c3e
pmd:0583f067
[ 951.987098] addr:77da7000 vm_flags:00100073 anon_vma:c4e22c40
mapping:00000000 index:77da7
[ 951.988163] file:(null) fault:0x0 mmap:0x0 readpage:0x0
[ 951.988850] CPU: 2 PID: 36 Comm: oom_reaper Tainted: G W
5.18.0-rc2-next-20220412 #1
[ 951.990034] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996),
BIOS 1.12.0-1 04/01/2014
[ 951.991101] Call Trace:
[ 951.991457] dump_stack_lvl+0x44/0x57
[ 951.991942] dump_stack+0xd/0x10
[ 951.992371] print_bad_pte.cold+0x82/0xd9
[ 951.992901] ? free_swap_and_cache+0x2e/0xe0
[ 951.993475] unmap_page_range+0x6b5/0x790
[ 951.994005] __oom_reap_task_mm+0xc0/0x140
[ 951.994539] ? _raw_spin_lock_irqsave+0x28/0x70
[ 951.995128] ? trace_preempt_on+0x8/0xe0
[ 951.995645] oom_reaper+0x28c/0x3d0
[ 951.996103] ? __kthread_parkme+0x37/0x80
[ 951.996626] ? trace_preempt_on+0x29/0xe0
[ 951.997205] ? __kthread_parkme+0x37/0x80
[ 951.997723] ? nsec_high+0xa0/0xa0
[ 951.998174] kthread+0xe0/0x110
[ 951.998588] ? __oom_reap_task_mm+0x140/0x140
[ 951.999154] ? kthread_complete_and_exit+0x20/0x20
[ 951.999771] ret_from_fork+0x1c/0x28
[ 952.000258] Disabling lock debugging due to kernel taint
[ 952.000951] __swap_info_get: Bad swap file entry 6c0373b7
[ 952.001779] BUG: Bad page map in process oom_reaper pte:06e76e3e
pmd:0583f067
[ 952.002827] addr:77da8000 vm_flags:00100073 anon_vma:c4e22c40
mapping:00000000 index:77da8
[ 952.004010] file:(null) fault:0x0 mmap:0x0 readpage:0x0
[ 952.004764] CPU: 2 PID: 36 Comm: oom_reaper Tainted: G B W
5.18.0-rc2-next-20220412 #1
[ 952.006060] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996),
BIOS 1.12.0-1 04/01/2014
[ 952.007248] Call Trace:
[ 952.007611] dump_stack_lvl+0x44/0x57
[ 952.008142] dump_stack+0xd/0x10
[ 952.008614] print_bad_pte.cold+0x82/0xd9
[ 952.009225] ? free_swap_and_cache+0x2e/0xe0
[ 952.009848] unmap_page_range+0x6b5/0x790
[ 952.010432] __oom_reap_task_mm+0xc0/0x140
[ 952.011219] ? _raw_spin_lock_irqsave+0x28/0x70
[ 952.011863] ? trace_preempt_on+0x8/0xe0
[ 952.012470] oom_reaper+0x28c/0x3d0
[ 952.013048] ? __kthread_parkme+0x37/0x80
[ 952.013628] ? trace_preempt_on+0x29/0xe0
[ 952.014202] ? __kthread_parkme+0x37/0x80
[ 952.014774] ? nsec_high+0xa0/0xa0
[ 952.015265] kthread+0xe0/0x110
[ 952.015716] ? __oom_reap_task_mm+0x140/0x140
[ 952.016336] ? kthread_complete_and_exit+0x20/0x20
[ 952.017035] ret_from_fork+0x1c/0x28
[ 952.017547] __swap_info_get: Bad swap file entry 6c0373b8
[ 952.018368] BUG: Bad page map in process oom_reaper pte:06e7703e
pmd:0583f067
Reported-by: Linux Kernel Functional Testing <lkft@xxxxxxxxxx>
--
Linaro LKFT
https://lkft.linaro.org
