2021-09-02 09:58:05

by kernel test robot

[permalink] [raw]
Subject: [mm] 730633f0b7: invoked_oom-killer:gfp_mask=0x



Greeting,

FYI, we noticed the following commit (built with gcc-9):

commit: 730633f0b7f951726e87f912a6323641f674ae34 ("mm: Protect operations adding pages to page cache with invalidate_lock")
https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git master


in testcase: rcutorture
version:
with following parameters:

runtime: 300s
test: default
torture_type: srcu

test-description: rcutorture is rcutorture kernel module load/unload test.
test-url: https://www.kernel.org/doc/Documentation/RCU/torture.txt


on test machine: qemu-system-i386 -enable-kvm -cpu SandyBridge -smp 2 -m 4G

caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):


+----------------------------------------------------------------------+------------+------------+
| | c625b4cc57 | 730633f0b7 |
+----------------------------------------------------------------------+------------+------------+
| boot_successes | 35 | 0 |
| invoked_oom-killer:gfp_mask=0x | 0 | 32 |
| Mem-Info | 0 | 32 |
| Out_of_memory_and_no_killable_processes | 0 | 32 |
| Kernel_panic-not_syncing:System_is_deadlocked_on_memory | 0 | 32 |
+----------------------------------------------------------------------+------------+------------+


If you fix the issue, kindly add following tag
Reported-by: kernel test robot <[email protected]>


[ 38.907151][ T213] systemd-journal invoked oom-killer: gfp_mask=0xcd0(GFP_KERNEL|__GFP_RECLAIMABLE), order=0, oom_score_adj=0
[ 38.907656][ T213] CPU: 0 PID: 213 Comm: systemd-journal Not tainted 5.14.0-rc1-g730633f0b7f9 #1 e682208cff06eef16286612743ecaf55e9ae1132
[ 38.908187][ T213] Call Trace:
[ 38.908323][ T213] dump_stack_lvl+0x19/0x1b
[ 38.908518][ T213] dump_stack+0xd/0x10
[ 38.908701][ T213] dump_header+0x3f/0x239
[ 38.908889][ T213] ? ___ratelimit+0x6e/0x100
[ 38.909080][ T213] oom_kill_process.cold+0x9/0xe
[ 38.909284][ T213] out_of_memory+0xee/0x300
[ 38.909471][ T213] __alloc_pages_slowpath+0xa80/0xc00
[ 38.909748][ T213] __alloc_pages+0x284/0x300
[ 38.909950][ T213] allocate_slab+0x473/0x6c0
[ 38.910141][ T213] ___slab_alloc+0x257/0x900
[ 38.910378][ T213] ? __d_alloc+0x1d/0x200
[ 38.910565][ T213] ? kvm_sched_clock_read+0x13/0x40
[ 38.910780][ T213] __slab_alloc+0x50/0x80
[ 38.911009][ T213] ? trace_hardirqs_off+0xc/0xc0
[ 38.911212][ T213] ? __slab_alloc+0x50/0x80
[ 38.911441][ T213] ? __d_alloc+0x1d/0x200
[ 38.911627][ T213] kmem_cache_alloc+0x556/0x680
[ 38.911839][ T213] ? __d_alloc+0x1d/0x200
[ 38.912018][ T213] __d_alloc+0x1d/0x200
[ 38.912190][ T213] d_alloc+0x13/0x80
[ 38.912351][ T213] d_alloc_parallel+0x45/0x680
[ 38.912556][ T213] ? lockdep_init_map_type+0x4a/0x240
[ 38.912781][ T213] ? __init_waitqueue_head+0x35/0x80
[ 38.913007][ T213] __lookup_slow+0x64/0x140
[ 38.913195][ T213] walk_component+0x112/0x180
[ 38.913389][ T213] path_lookupat+0x5f/0x140
[ 38.913606][ T213] filename_lookup+0xad/0x1c0
[ 38.913803][ T213] ? __might_fault+0x68/0x80
[ 38.914001][ T213] ? strncpy_from_user+0x21/0x180
[ 38.914208][ T213] user_path_at_empty+0x26/0x40
[ 38.914411][ T213] do_faccessat+0x7a/0x240
[ 38.914600][ T213] __ia32_sys_access+0x14/0x40
[ 38.914799][ T213] do_int80_syscall_32+0x52/0x80
[ 38.915009][ T213] ? syscall_exit_to_user_mode+0x41/0x80
[ 38.915241][ T213] ? do_int80_syscall_32+0x5c/0x80
[ 38.915451][ T213] ? __task_pid_nr_ns+0x6d/0x140
[ 38.915660][ T213] ? kvm_sched_clock_read+0x13/0x40
[ 38.915886][ T213] ? sched_clock+0x8/0x40
[ 38.916065][ T213] ? sched_clock_cpu+0x18/0x100
[ 38.916265][ T213] ? __task_pid_nr_ns+0x6d/0x140
[ 38.916467][ T213] ? syscall_exit_to_user_mode+0x41/0x80
[ 38.916706][ T213] ? do_int80_syscall_32+0x5c/0x80
[ 38.916925][ T213] ? irqentry_exit_to_user_mode+0x2d/0x40
[ 38.917161][ T213] ? irqentry_exit+0x5d/0xc0
[ 38.917350][ T213] ? exc_page_fault+0x62/0x240
[ 38.917553][ T213] entry_INT80_32+0x109/0x109
[ 38.917746][ T213] EIP: 0xb7ee9a02
[ 38.917907][ T213] Code: 95 01 00 05 25 36 02 00 83 ec 14 8d 80 e8 99 ff ff 50 6a 02 e8 1f ff 00 00 c7 04 24 7f 00 00 00 e8 7e 87 01 00 66 90 90 cd 80 <c3> 8d b6 00 00 00 00 8d bc 27 00 00 00 00 8b 1c 24 c3 8d b6 00 00
[ 38.918715][ T213] EAX: ffffffda EBX: 00470b00 ECX: 00000000 EDX: 00477c4c
[ 38.919013][ T213] ESI: bfe88290 EDI: 00000000 EBP: bfe859a8 ESP: bfe85958
[ 38.919301][ T213] DS: 007b ES: 007b FS: 0000 GS: 0033 SS: 007b EFLAGS: 00000292
[ 38.919617][ T213] Mem-Info:
[ 38.919745][ T213] active_anon:9 inactive_anon:2835 isolated_anon:0
[ 38.919745][ T213] active_file:0 inactive_file:0 isolated_file:0
[ 38.919745][ T213] unevictable:88336 dirty:0 writeback:0
[ 38.919745][ T213] slab_reclaimable:8364 slab_unreclaimable:1802
[ 38.919745][ T213] mapped:1924 shmem:2825 pagetables:22 bounce:0
[ 38.919745][ T213] free:481332 free_pcp:6127 free_cma:0
[ 38.921221][ T213] Node 0 active_anon:36kB inactive_anon:11340kB active_file:0kB inactive_file:0kB unevictable:353344kB isolated(anon):0kB isolated(file):0kB mapped:7696kB dirty:0kB writeback:0kB shmem:11300kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 0kB writeback_tmp:0kB kernel_stack:584kB pagetables:88kB all_unreclaimable? yes
[ 38.922442][ T213] Normal free:38240kB min:34196kB low:42744kB high:51292kB reserved_highatomic:4096KB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:15992kB writepending:0kB present:896624kB managed:805236kB mlocked:0kB bounce:0kB free_pcp:15936kB local_pcp:15936kB free_cma:0kB
[ 38.923574][ T213] lowmem_reserve[]: 0 17567 17567
[ 38.923783][ T213] HighMem free:1887088kB min:512kB low:28412kB high:56312kB reserved_highatomic:0KB active_anon:36kB inactive_anon:11340kB active_file:0kB inactive_file:0kB unevictable:337352kB writepending:0kB present:2248584kB managed:2248584kB mlocked:0kB bounce:0kB free_pcp:8572kB local_pcp:8572kB free_cma:0kB
[ 38.924942][ T213] lowmem_reserve[]: 0 0 0
[ 38.925124][ T213] Normal: 4*4kB (M) 2*8kB (ME) 2*16kB (UM) 5*32kB (UM) 2*64kB (ME) 2*128kB (M) 5*256kB (UME) 5*512kB (UME) 1*1024kB (U) 6*2048kB (UMEH) 5*4096kB (M) = 38240kB
[ 38.925799][ T213] HighMem: 0*4kB 0*8kB 1*16kB (U) 1*32kB (U) 1*64kB (M) 0*128kB 1*256kB (U) 1*512kB (M) 0*1024kB 1*2048kB (U) 460*4096kB (M) = 1887088kB
[ 38.926401][ T213] 90393 total pagecache pages
[ 38.926600][ T213] 786302 pages RAM
[ 38.926754][ T213] 562146 pages HighMem/MovableOnly
[ 38.926970][ T213] 22847 pages reserved
[ 38.927136][ T213] 0 pages cma reserved
[ 38.927302][ T213] Tasks state (memory values in pages):
[ 38.927528][ T213] [ pid ] uid tgid total_vm rss pgtables_bytes swapents oom_score_adj name
[ 38.927932][ T213] [ 213] 0 213 3790 1201 20480 0 0 systemd-journal
[ 38.928355][ T213] [ 224] 0 224 3186 822 20480 0 -1000 systemd-udevd
[ 38.928759][ T213] [ 225] 0 225 1359 1037 12288 0 0 udevadm
[ 38.929142][ T213] [ 241] 0 241 565 363 12288 0 0 ifup
[ 38.929510][ T213] oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),global_oom,task_memcg=/,task=systemd-journal,pid=213,uid=0
[ 38.930043][ T213] Out of memory: Killed process 213 (systemd-journal) total-vm:15160kB, anon-rss:340kB, file-rss:3560kB, shmem-rss:904kB, UID:0 pgtables:20kB oom_score_adj:0



To reproduce:

# build kernel
cd linux
cp config-5.14.0-rc1-g730633f0b7f9 .config
make HOSTCC=gcc-9 CC=gcc-9 ARCH=i386 olddefconfig prepare modules_prepare bzImage

git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email



---
0DAY/LKP+ Test Infrastructure Open Source Technology Center
https://lists.01.org/hyperkitty/list/[email protected] Intel Corporation

Thanks,
Oliver Sang


Attachments:
(No filename) (8.35 kB)
config-5.14.0-rc1-g730633f0b7f9 (150.74 kB)
job-script (4.63 kB)
dmesg.xz (21.46 kB)
Download all attachments