FYI, we noticed the following commit:
commit: d79433e97cd250e7f6c824907b46e95c01c87eba ("xfs: create tracepoints for online
repair")
https://git.kernel.org/cgit/linux/kernel/git/djwong/xfs-linux.git djwong-devel
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -cpu host -smp 2 -m 1G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+----------------------------------------------------------------------------------+------------+------------+
| |
9b505b38f0 | d79433e97c |
+----------------------------------------------------------------------------------+------------+------------+
| boot_successes | 2
| 4 |
| boot_failures | 50
| 48 |
| invoked_oom-killer:gfp_mask=0x | 50
| 48 |
| Mem-Info | 50
| 48 |
| Out_of_memory:Kill_process | 2
| |
| Kernel_panic-not_syncing:Out_of_memory_and_no_killable_processes | 48
| 48 |
| page_allocation_failure:order:#,mode:#(GFP_KERNEL|__GFP_NORETRY),nodemask=(null) | 0
| 20 |
| WARNING:at_kernel/trace/ring_buffer.c:#trace_rb_cpu_prepare | 0
| 20 |
+----------------------------------------------------------------------------------+------------+------------+
[ 12.923390] AVX or AES-NI instructions are not detected.
[ 12.924190] CPU feature 'AVX registers' is not supported.
[ 12.925000] AVX2 or AES-NI instructions are not detected.
[ 12.927563] Kprobe smoke test: started
[ 12.947734] Kprobe smoke test: passed successfully
[ 13.106363] swapper/0: page allocation failure: order:0,
mode:0x14010c0(GFP_KERNEL|__GFP_NORETRY), nodemask=(null)
[ 13.107808] CPU: 1 PID: 1 Comm: swapper/0 Not tainted 4.12.0-00067-gd79433e #1
[ 13.108717] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS
1.9.3-20161025_171302-gandalf 04/01/2014
[ 13.110000] Call Trace:
[ 13.110334] dump_stack+0x99/0xce
[ 13.110784] warn_alloc+0xe0/0x15d
[ 13.111274] __alloc_pages_slowpath+0x900/0xa1f
[ 13.111869] ? ____cache_alloc+0x1e0/0x8ac
[ 13.112400] ? get_page_from_freelist+0x14a/0x65f
[ 13.113012] ? check_irq_off+0xe/0x17
[ 13.113490] ? ____cache_alloc+0x1c/0x8ac
[ 13.114021] __alloc_pages_nodemask+0x102/0x1a4
[ 13.114607] __alloc_pages_node+0x14/0x16
[ 13.115270] __rb_allocate_pages+0x68/0xfb
[ 13.115894] rb_allocate_cpu_buffer+0x212/0x29b
[ 13.116476] trace_rb_cpu_prepare+0x89/0xcc
[ 13.117029] ? ring_buffer_record_is_on+0x11/0x11
[ 13.117640] cpuhp_invoke_callback+0xb1/0x17a
[ 13.118236] ? __mutex_unlock_slowpath+0x3a/0x201
[ 13.118852] cpuhp_issue_call+0xb3/0xca
[ 13.119351] __cpuhp_state_add_instance+0xae/0x100
[ 13.119991] __ring_buffer_alloc+0x134/0x19c
[ 13.120547] ? ftrace_init_tracefs_toplevel+0x11/0x11
[ 13.121206] ring_buffer_benchmark_init+0x1e/0x13e
[ 13.121875] ? ftrace_init_tracefs_toplevel+0x11/0x11
[ 13.122522] do_one_initcall+0x8f/0x134
[ 13.123085] kernel_init_freeable+0x13a/0x1bb
[ 13.123695] ? rest_init+0xc2/0xc2
[ 13.124155] kernel_init+0xe/0xf0
[ 13.124653] ret_from_fork+0x2a/0x40
[ 13.125153] Mem-Info:
[ 13.125467] active_anon:0 inactive_anon:0 isolated_anon:0
[ 13.125467] active_file:0 inactive_file:0 isolated_file:0
[ 13.125467] unevictable:134434 dirty:0 writeback:0 unstable:0
[ 13.125467] slab_reclaimable:51893 slab_unreclaimable:2444
[ 13.125467] mapped:0 shmem:0 pagetables:0 bounce:0
[ 13.125467] free:9934 free_pcp:196 free_cma:0
[ 13.129492] Node 0 active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB
unevictable:537736kB isolated(anon):0kB isolated(file):0kB mapped:0kB dirty:0kB
writeback:0kB shmem:0kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 0kB
writeback_tmp:0kB unstable:0kB all_unreclaimable? no
[ 13.132841] DMA32 free:39736kB min:39784kB low:49728kB high:59672kB active_anon:0kB
inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:537736kB writepending:0kB
present:1048056kB managed:801072kB mlocked:0kB slab_reclaimable:207572kB
slab_unreclaimable:9776kB kernel_stack:512kB pagetables:0kB bounce:0kB free_pcp:784kB
local_pcp:232kB free_cma:0kB
[ 13.138398] lowmem_reserve[]: 0 0 0
[ 13.139214] DMA32: 4*4kB (UM) 1*8kB (U) 7*16kB (ME) 5*32kB (UME) 4*64kB (ME) 2*128kB
(UM) 2*256kB (ME) 3*512kB (ME) 4*1024kB (UM) 2*2048kB (UM) 7*4096kB (M) = 39720kB
[ 13.141429] 134458 total pagecache pages
[ 13.142009] 262014 pages RAM
[ 13.142388] 0 pages HighMem/MovableOnly
[ 13.142902] 61746 pages reserved
[ 13.143355] 0 pages cma reserved
[ 13.144299] failed to allocate ring buffer on CPU 0
[ 13.145013] ------------[ cut here ]------------
[ 13.145606] WARNING: CPU: 1 PID: 1 at kernel/trace/ring_buffer.c:4687
trace_rb_cpu_prepare+0xaa/0xcc
[ 13.147012] Modules linked in:
[ 13.147410] CPU: 1 PID: 1 Comm: swapper/0 Not tainted 4.12.0-00067-gd79433e #1
[ 13.148328] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS
1.9.3-20161025_171302-gandalf 04/01/2014
[ 13.149604] task: ffff88000031c040 task.stack: ffffc90000008000
[ 13.150359] RIP: 0010:trace_rb_cpu_prepare+0xaa/0xcc
[ 13.151081] RSP: 0000:ffffc9000000bd48 EFLAGS: 00010296
[ 13.151790] RAX: 0000000000000027 RBX: ffff8800058e6ec8 RCX: 00000000e272cf33
[ 13.152748] RDX: 0000000000000003 RSI: 0000000000000003 RDI: 0000000000000286
[ 13.153733] RBP: ffffc9000000bd78 R08: 00000000e272cf33 R09: 0000000000000004
[ 13.154694] R10: ffffc9000000bb90 R11: ffffffff82ad2bed R12: 0000000000000000
[ 13.155653] R13: 00000000000000f6 R14: ffff8800058e6e10 R15: 0000000000000000
[ 13.156591] FS: 0000000000000000(0000) GS:ffff880034a00000(0000)
knlGS:0000000000000000
[ 13.157625] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 13.158435] CR2: ffffc90000094000 CR3: 0000000001c11000 CR4: 00000000000006a0
[ 13.159354] Call Trace:
[ 13.159689] ? ring_buffer_record_is_on+0x11/0x11
[ 13.160383] cpuhp_invoke_callback+0xb1/0x17a
[ 13.160954] ? __mutex_unlock_slowpath+0x3a/0x201
[ 13.161609] cpuhp_issue_call+0xb3/0xca
[ 13.162118] __cpuhp_state_add_instance+0xae/0x100
[ 13.162739] __ring_buffer_alloc+0x134/0x19c
[ 13.163372] ? ftrace_init_tracefs_toplevel+0x11/0x11
[ 13.164038] ring_buffer_benchmark_init+0x1e/0x13e
[ 13.164671] ? ftrace_init_tracefs_toplevel+0x11/0x11
[ 13.165312] do_one_initcall+0x8f/0x134
[ 13.165820] kernel_init_freeable+0x13a/0x1bb
[ 13.166378] ? rest_init+0xc2/0xc2
[ 13.166830] kernel_init+0xe/0xf0
[ 13.167259] ret_from_fork+0x2a/0x40
[ 13.167818] Code: ee 48 89 4d d0 e8 0c d9 ff ff 48 8b 4d d0 48 89 01 48 8b 43 f8 4a 83
3c f8 00 75 18 44 89 e6 48 c7 c7 b0 d1 a9 81 e8 a8 b7 03 00 <0f> ff b8 f4 ff ff ff
eb 0d 4c 89 f6 44 89 e7 e8 7b c6 ff ff 31
[ 13.170389] ---[ end trace f8526cd82d8a6f15 ]---
[ 13.172442] Initialise system trusted keyrings
[ 13.173089] Key type blacklist registered
[ 13.174051] workingset: timestamp_bits=62 max_order=18 bucket_order=0
[ 13.176570] romfs: ROMFS MTD (C) 2007 Red Hat, Inc.
[ 13.177263] qnx6: QNX6 filesystem 1.0.0 registered.
[ 13.178024] JFS: nTxBlock = 6258, nTxLock = 50067
[ 13.179368] swapper/0 invoked oom-killer:
gfp_mask=0x14002c2(GFP_KERNEL|__GFP_HIGHMEM|__GFP_NOWARN), nodemask=(null), order=0,
oom_score_adj=0
[ 13.181012] CPU: 1 PID: 1 Comm: swapper/0 Tainted: G W
4.12.0-00067-gd79433e #1
[ 13.182218] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS
1.9.3-20161025_171302-gandalf 04/01/2014
[ 13.183532] Call Trace:
[ 13.183873] dump_stack+0x99/0xce
[ 13.184407] dump_header+0x8f/0x1fb
[ 13.185240] out_of_memory+0x28d/0x2de
[ 13.186009] __alloc_pages_slowpath+0x84d/0xa1f
[ 13.186652] ? sched_clock_cpu+0x15/0xac
[ 13.187173] __alloc_pages_nodemask+0x102/0x1a4
[ 13.187772] __alloc_pages_node+0x1c/0x1e
[ 13.188430] __vmalloc_node_range+0x183/0x1e7
[ 13.189194] __vmalloc_node+0x2e/0x30
[ 13.189980] ? txInit+0x12f/0x33a
[ 13.190692] vmalloc+0x2a/0x2c
[ 13.191342] txInit+0x12f/0x33a
[ 13.192019] ? add_element+0x3a/0xb2
[ 13.192789] ? ovl_init+0x17/0x17
[ 13.193511] ? ovl_init+0x17/0x17
[ 13.194238] init_jfs_fs+0x56/0x1d4
[ 13.194992] ? ovl_init+0x17/0x17
[ 13.195714] do_one_initcall+0x8f/0x134
[ 13.196548] kernel_init_freeable+0x13a/0x1bb
[ 13.197488] ? rest_init+0xc2/0xc2
[ 13.198225] kernel_init+0xe/0xf0
[ 13.198954] ret_from_fork+0x2a/0x40
[ 13.199771] Mem-Info:
[ 13.200271] active_anon:0 inactive_anon:0 isolated_anon:0
[ 13.200271] active_file:0 inactive_file:12 isolated_file:0
[ 13.200271] unevictable:134434 dirty:0 writeback:0 unstable:0
[ 13.200271] slab_reclaimable:51883 slab_unreclaimable:2483
[ 13.200271] mapped:0 shmem:0 pagetables:0 bounce:0
[ 13.200271] free:9928 free_pcp:191 free_cma:0
[ 13.206780] Node 0 active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:48kB
unevictable:537736kB isolated(anon):0kB isolated(file):0kB mapped:0kB dirty:0kB
writeback:0kB shmem:0kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 0kB
writeback_tmp:0kB unstable:0kB all_unreclaimable? yes
[ 13.212135] DMA32 free:39712kB min:39784kB low:49728kB high:59672kB active_anon:0kB
inactive_anon:0kB active_file:0kB inactive_file:48kB unevictable:537736kB writepending:0kB
present:1048056kB managed:801072kB mlocked:0kB slab_reclaimable:207532kB
slab_unreclaimable:9932kB kernel_stack:532kB pagetables:0kB bounce:0kB free_pcp:764kB
local_pcp:212kB free_cma:0kB
[ 13.218709] lowmem_reserve[]: 0 0 0
[ 13.219473] DMA32: 4*4kB (ME) 0*8kB 7*16kB (UM) 5*32kB (UME) 4*64kB (ME) 2*128kB (UM)
2*256kB (ME) 3*512kB (ME) 4*1024kB (UM) 2*2048kB (UM) 7*4096kB (M) = 39712kB
[ 13.222542] 134458 total pagecache pages
[ 13.223400] 262014 pages RAM
[ 13.224032] 0 pages HighMem/MovableOnly
[ 13.224849] 61746 pages reserved
[ 13.225527] 0 pages cma reserved
[ 13.226225] [ pid ] uid tgid total_vm rss nr_ptes nr_pmds swapents
oom_score_adj name
[ 13.228017] Kernel panic - not syncing: Out of memory and no killable processes...
[ 13.228017]
[ 13.229924] CPU: 1 PID: 1 Comm: swapper/0 Tainted: G W
4.12.0-00067-gd79433e #1
[ 13.231684] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS
1.9.3-20161025_171302-gandalf 04/01/2014
[ 13.233821] Call Trace:
[ 13.234356] dump_stack+0x99/0xce
[ 13.235086] panic+0xea/0x23c
[ 13.235745] ? dump_header+0x1ec/0x1fb
[ 13.236696] out_of_memory+0x299/0x2de
[ 13.237490] __alloc_pages_slowpath+0x84d/0xa1f
[ 13.238446] ? sched_clock_cpu+0x15/0xac
[ 13.239304] __alloc_pages_nodemask+0x102/0x1a4
[ 13.240301] __alloc_pages_node+0x1c/0x1e
[ 13.241384] __vmalloc_node_range+0x183/0x1e7
[ 13.242317] __vmalloc_node+0x2e/0x30
[ 13.243106] ? txInit+0x12f/0x33a
[ 13.243826] vmalloc+0x2a/0x2c
[ 13.244491] txInit+0x12f/0x33a
[ 13.245167] ? add_element+0x3a/0xb2
[ 13.245946] ? ovl_init+0x17/0x17
[ 13.246678] ? ovl_init+0x17/0x17
[ 13.247382] init_jfs_fs+0x56/0x1d4
[ 13.248134] ? ovl_init+0x17/0x17
[ 13.248856] do_one_initcall+0x8f/0x134
[ 13.249700] kernel_init_freeable+0x13a/0x1bb
[ 13.250611] ? rest_init+0xc2/0xc2
[ 13.251389] kernel_init+0xe/0xf0
[ 13.252105] ret_from_fork+0x2a/0x40
[ 13.253046] Kernel Offset: disabled
Elapsed time: 20
initrds=(
To reproduce:
git clone
https://github.com/01org/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this
email
Thanks,
Xiaolong