[LTP] [stable-rc 5.4] thp04: page allocation failure: order:0, mode:0x400dc0(GFP_KERNEL_ACCOUNT|__GFP_ZERO), nodemask=(null)

Naresh Kamboju naresh.kamboju@linaro.org
Tue Apr 20 10:00:10 CEST 2021


The following kernel crash reported on arm64 hikey device running stable-rc
 5.4.114-rc1 kernel and testing LTP thp04 test case.

Reported-by: Naresh Kamboju <naresh.kamboju@linaro.org>

I have tried to reproduce this crash but was not successful after
multiple attempts.
Could not bisect this problem because it is not reproducible.

step to reproduce:
-------------------------
 - Boot 5.4.114-rc1 kernel on arm64 hikey device.
 - run ltp cve test case
 - cd /opt/ltp
 - ./runltp -f cve
 -  ./runltp -s thp04

Test crash log:
--------------------
../../../../include/tst_fuzzy_sync.h:507: TINFO: Minimum sampling period ended
../../../../include/tst_fuzzy_sync.h:331: TINFO: loop = 1024, delay_bias = 0
../../../../include/tst_fuzzy_sync.h:320: TINFO: start_a - start_b: {
avg =   -43ns, avg_dev =   104ns, dev_ratio = 2.44 }
../../../../include/tst_fuzzy_sync.h:320: TINFO: end_a - start_a  : {
avg = 1373611ns, avg_dev = 90431ns, dev_ratio = 0.07 }
../../../../include/tst_fuzzy_sync.h:320: TINFO: end_b - start_b  : {
avg = 968943ns, avg_dev = 11299ns, dev_ratio = 0.01 }
../../../../include/tst_fuzzy_sync.h:320: TINFO: end_a - end_b    : {
avg = 404625ns, avg_dev = 80082ns, dev_ratio = 0.20 }
../../../../include/tst_fuzzy_sync.h:320: TINFO: spins            : {
avg = 38993  , avg_dev =  7743  , dev_ratio = 0.20 }
[ 1303.074490] wlcore: down
[ 1303.081180] thp04: page allocation failure: order:0,
mode:0x400dc0(GFP_KERNEL_ACCOUNT|__GFP_ZERO), nodemask=(null)
[ 1303.081189] Unable to handle kernel paging request at virtual
address 0000000000001540
[ 1303.081191] ,cpuset=/,mems_allowed=0
[ 1303.081302] Unable to handle kernel paging request at virtual
address 0000000000001540
[ 1303.081313] Mem abort info:
[ 1303.081322]   ESR = 0x96000004
[ 1303.081331]   EC = 0x25: DABT (current EL), IL = 32 bits
[ 1303.081340]   SET = 0, FnV = 0
[ 1303.081348]   EA = 0, S1PTW = 0
[ 1303.081355] Data abort info:
[ 1303.081363]   ISV = 0, ISS = 0x00000004
[ 1303.081371]   CM = 0, WnR = 0
[ 1303.081393] user pgtable: 4k pages, 48-bit VAs, pgdp=0000000074f30000
[ 1303.081402] [0000000000001540] pgd=0000000000000000
[ 1303.081417] Internal error: Oops: 96000004 [#1] PREEMPT SMP
[ 1303.081424] Modules linked in: algif_hash wl18xx wlcore mac80211
cfg80211 hci_uart snd_soc_hdmi_codec btbcm snd_soc_audio_graph_card
adv7511 snd_soc_simple_card_utils crct10dif_ce wlcore_sdio bluetooth
cec kirin_drm drm_kms_helper dw_drm_dsi rfkill drm fuse
[ 1303.081483] CPU: 0 PID: 210 Comm: kworker/0:2 Not tainted 5.4.114-rc1 #1
[ 1303.081487] Hardware name: HiKey Development Board (DT)
[ 1303.081506] Workqueue: events vmstat_shepherd
[ 1303.081514] pstate: a0000005 (NzCv daif -PAN -UAO)
[ 1303.081523] pc : next_online_pgdat+0x24/0x78
[ 1303.081530] lr : next_zone+0x40/0x50
[ 1303.081534] sp : ffff8000129f3d10
[ 1303.081537] x29: ffff8000129f3d10 x28: ffff800011fe7000
[ 1303.081544] x27: ffff000077af1b30 x26: ffff000077af1ae0
[ 1303.081551] x25: 0000000000000000 x24: ffff000077b4cd38
[ 1303.081557] x23: ffff800011fe9000 x22: ffff800011fe9920
[ 1303.081564] x21: 0000000000000003 x20: ffff000077b531d8
[ 1303.081570] x19: 0000000000000000 x18: 0000000000000000
[ 1303.081577] x17: 0000000000000000 x16: 0000000000000000
[ 1303.081583] x15: 0000000000000000 x14: 0000000000000000
[ 1303.081589] x13: 0000000000000000 x12: 0000000000000000
[ 1303.081596] x11: 0000000000000000 x10: 0000000000000040
[ 1303.081603] x9 : 0000002a141f0a9e x8 : 0000000000000002
[ 1303.081609] x7 : ffff000077b30c10 x6 : ffff800011fea000
[ 1303.081616] x5 : ffff800011fea968 x4 : ffff000077b53226
[ 1303.081623] x3 : 0000000000000000 x2 : 000000000000000c
[ 1303.081629] x1 : 0000000000000004 x0 : ffff800011fea000
[ 1303.081637] Call trace:
[ 1303.081645]  next_online_pgdat+0x24/0x78
[ 1303.081654]  next_zone+0x40/0x50
[ 1303.081661]  need_update+0x7c/0xb0
[ 1303.081667]  vmstat_shepherd+0x7c/0x100
[ 1303.081676]  process_one_work+0x1c4/0x480
[ 1303.081682]  worker_thread+0x54/0x430
[ 1303.081690]  kthread+0x11c/0x150
[ 1303.081700]  ret_from_fork+0x10/0x1c
[ 1303.081710] Code: aa1e03e0 d503201f d2800081 9000eb20 (b9554262)
[ 1303.081718] ---[ end trace cd32e7e2c7ee8919 ]---
[ 1303.081820] Unable to handle kernel paging request at virtual
address 0000000000001540
[ 1303.081830] Mem abort info:
[ 1303.081838]   ESR = 0x96000004
[ 1303.081849]   EC = 0x25: DABT (current EL), IL = 32 bits
[ 1303.081858]   SET = 0, FnV = 0
[ 1303.081866]   EA = 0, S1PTW = 0
[ 1303.081873] Data abort info:
[ 1303.081881]   ISV = 0, ISS = 0x00000004
[ 1303.081889]   CM = 0, WnR = 0
[ 1303.081899] user pgtable: 4k pages, 48-bit VAs, pgdp=0000000001b98000
[ 1303.081907] [0000000000001540] pgd=0000000000000000
[ 1303.081921] Internal error: Oops: 96000004 [#2] PREEMPT SMP
[ 1303.081926] Modules linked in: algif_hash wl18xx wlcore mac80211
cfg80211 hci_uart snd_soc_hdmi_codec btbcm snd_soc_audio_graph_card
adv7511 snd_soc_simple_card_utils crct10dif_ce wlcore_sdio bluetooth
cec kirin_drm drm_kms_helper dw_drm_dsi rfkill drm fuse
[ 1303.081967] CPU: 2 PID: 51 Comm: kworker/2:1 Tainted: G      D
     5.4.114-rc1 #1
[ 1303.081971] Hardware name: HiKey Development Board (DT)
[ 1303.081982] Workqueue: mm_percpu_wq vmstat_update
[ 1303.081989] pstate: a0000005 (NzCv daif -PAN -UAO)
[ 1303.081997] pc : next_online_pgdat+0x24/0x78
[ 1303.082004] lr : next_zone+0x40/0x50
[ 1303.082007] sp : ffff8000124d3be0
[ 1303.082012] x29: ffff8000124d3be0 x28: 0000000000000000
[ 1303.082019] x27: 0000000000000006 x26: ffff000074e00000
[ 1303.082026] x25: 0000000000000000 x24: ffff8000119f0218
[ 1303.082033] x23: ffff8000119f01d8 x22: 0000000000000000
[ 1303.082043] x21: ffff8000124d3cc0 x20: ffff8000124d3cd8
[ 1303.082050] x19: 0000000000000000 x18: 0000000000000000
[ 1303.082057] x17: 0000000000000000 x16: 0000000000000000
[ 1303.082064] x15: 0000000000000000 x14: 0000000000000000
[ 1303.082070] x13: 0000000000000000 x12: 0000000000000000
[ 1303.082077] x11: 0000000000000000 x10: 0000000000000000
[ 1303.082084] x9 : 0000000000000000 x8 : ffff000077bdbe20
[ 1303.082090] x7 : ffff8000119f0218 x6 : ffff8000119f0226
[ 1303.082097] x5 : 0000000000000000 x4 : 0000000000000000
[ 1303.082104] x3 : ffff8000119f01d8 x2 : ffff800066144000
[ 1303.082111] x1 : 0000000000000004 x0 : ffff800011fea000
[ 1303.082117] Call trace:
[ 1303.082125]  next_online_pgdat+0x24/0x78
[ 1303.082131]  next_zone+0x40/0x50
[ 1303.082139]  refresh_cpu_vm_stats+0xbc/0x3f8
[ 1303.082146]  vmstat_update+0x1c/0x88
[ 1303.082153]  process_one_work+0x1c4/0x480
[ 1303.082158]  worker_thread+0x54/0x430
[ 1303.082165]  kthread+0x11c/0x150
[ 1303.082172]  ret_from_fork+0x10/0x1c
[ 1303.082181] Code: aa1e03e0 d503201f d2800081 9000eb20 (b9554262)
[ 1303.082187] ---[ end trace cd32e7e2c7ee891a ]---
[ 1303.085351] SLUB: Unable to allocate memory on node -1,
gfp=0x400cc0(GFP_KERNEL_ACCOUNT)
[ 1303.085362]   cache: skbuff_head_cache, object size: 216, buffer
size: 256, default order: 1, min order: 0
[ 1303.085372]   node 0: slabs: 53, objs: 1696, free: 0
[ 1303.085502] SLUB: Unable to allocate memory on node -1,
gfp=0x400cc0(GFP_KERNEL_ACCOUNT)
[ 1303.085510]   cache: skbuff_head_cache, object size: 216, buffer
size: 256, default order: 1, min order: 0
[ 1303.085517]   node 0: slabs: 53, objs: 1696, free: 0
[ 1303.085618] SLUB: Unable to allocate memory on node -1,
gfp=0x400cc0(GFP_KERNEL_ACCOUNT)
[ 1303.085626]   cache: skbuff_head_cache, object size: 216, buffer
size: 256, default order: 1, min order: 0
[ 1303.085633]   node 0: slabs: 53, objs: 1696, free: 0
[ 1303.085713] SLUB: Unable to allocate memory on node -1,
gfp=0x400cc0(GFP_KERNEL_ACCOUNT)
[ 1303.085721]   cache: skbuff_head_cache, object size: 216, buffer
size: 256, default order: 1, min order: 0
[ 1303.085728]   node 0: slabs: 53, objs: 1696, free: 0
[ 1303.085822] SLUB: Unable to allocate memory on node -1,
gfp=0x400cc0(GFP_KERNEL_ACCOUNT)
[ 1303.085829]   cache: skbuff_head_cache, object size: 216, buffer
size: 256, default order: 1, min order: 0
[ 1303.085836]   node 0: slabs: 53, objs: 1696, free: 0
[ 1303.085916] SLUB: Unable to allocate memory on node -1,
gfp=0x400cc0(GFP_KERNEL_ACCOUNT)
[ 1303.085923]   cache: skbuff_head_cache, object size: 216, buffer
size: 256, default order: 1, min order: 0
[ 1303.085931]   node 0: slabs: 53, objs: 1696, free: 0
[ 1303.086023] SLUB: Unable to allocate memory on node -1,
gfp=0x400cc0(GFP_KERNEL_ACCOUNT)
[ 1303.086030]   cache: skbuff_head_cache, object size: 216, buffer
size: 256, default order: 1, min order: 0
[ 1303.086037]   node 0: slabs: 53, objs: 1696, free: 0
[ 1303.086117] SLUB: Unable to allocate memory on node -1,
gfp=0x400cc0(GFP_KERNEL_ACCOUNT)
[ 1303.086124]   cache: skbuff_head_cache, object size: 216, buffer
size: 256, default order: 1, min order: 0
[ 1303.086131]   node 0: slabs: 53, objs: 1696, free: 0
[ 1303.086223] SLUB: Unable to allocate memory on node -1,
gfp=0x400cc0(GFP_KERNEL_ACCOUNT)
[ 1303.086231]   cache: skbuff_head_cache, object size: 216, buffer
size: 256, default order: 1, min order: 0
[ 1303.086238]   node 0: slabs: 53, objs: 1696, free: 0
[ 1303.086317] SLUB: Unable to allocate memory on node -1,
gfp=0x400cc0(GFP_KERNEL_ACCOUNT)
[ 1303.086324]   cache: skbuff_head_cache, object size: 216, buffer
size: 256, default order: 1, min order: 0
[ 1303.086331]   node 0: slabs: 53, objs: 1696, free: 0
[ 1303.091806] Mem abort info:
[ 1303.091810]   ESR = 0x96000006
[ 1303.091815]   EC = 0x25: DABT (current EL), IL = 32 bits
[ 1303.091819]   SET = 0, FnV = 0
[ 1303.091823]   EA = 0, S1PTW = 0
[ 1303.091826] Data abort info:
[ 1303.091830]   ISV = 0, ISS = 0x00000006
[ 1303.091834]   CM = 0, WnR = 0
[ 1303.091840] user pgtable: 4k pages, 48-bit VAs, pgdp=000000006ab4d000
[ 1303.091844] [0000000000001540] pgd=000000006dded003,
pud=000000006a870003, pmd=0000000000000000
[ 1303.091858] Internal error: Oops: 96000006 [#3] PREEMPT SMP
[ 1303.092766] socket: no more sockets
[ 1303.092883] socket: no more sockets
[ 1303.092913] socket: no more sockets
[ 1303.092942] socket: no more sockets
[ 1303.092971] socket: no more sockets
[ 1303.092999] socket: no more sockets
[ 1303.093027] socket: no more sockets
[ 1303.093056] socket: no more sockets
[ 1303.093078] systemd-journald[246]: Failed to open system journal:
Cannot allocate memory
[ 1303.093085] socket: no more sockets
[ 1303.093113] socket: no more sockets
[ 1303.093246] systemd-journald[246]: Failed to open system journal:
Cannot allocate memory
[ 1303.093331] systemd-journald[246]: Failed to open system journal:
Cannot allocate memory
[ 1303.093488] NetworkManager invoked oom-killer: gfp_mask=0x0(),
order=0, oom_score_adj=0
[ 1303.093505] CPU: 4 PID: 354 Comm: NetworkManager Tainted: G      D
         5.4.114-rc1 #1
[ 1303.093510] Hardware name: HiKey Development Board (DT)
[ 1303.093516] Call trace:
[ 1303.093530]  dump_backtrace+0x0/0x178
[ 1303.093533] systemd-journald[246]: Failed to open system journal:
Cannot allocate memory
[ 1303.093538]  show_stack+0x28/0x38
[ 1303.093548]  dump_stack+0xe0/0x160
[ 1303.093559]  dump_header+0x4c/0x1f8
[ 1303.093568]  oom_kill_process+0x1cc/0x1d0
[ 1303.093576]  out_of_memory+0x178/0x4f0
[ 1303.093584]  pagefault_out_of_memory+0x8c/0x298
[ 1303.093593]  do_page_fault+0x484/0x4c8
[ 1303.093602]  do_translation_fault+0xb4/0xd0
[ 1303.093609]  do_mem_abort+0x54/0xb0
[ 1303.093616]  el0_da+0x1c/0x20
[ 1303.093620] systemd-journald[246]: Failed to open system journal:
Cannot allocate memory
[ 1303.093625] Mem-Info:
[ 1303.093648] Unable to handle kernel paging request at virtual
address 0000000000001540
[ 1303.093656] Mem abort info:
[ 1303.093664]   ESR = 0x96000006
[ 1303.093673]   EC = 0x25: DABT (current EL), IL = 32 bits
[ 1303.093681]   SET = 0, FnV = 0
[ 1303.093689]   EA = 0, S1PTW = 0
[ 1303.093697] Data abort info:
[ 1303.093705]   ISV = 0, ISS = 0x00000006
[ 1303.093713]   CM = 0, WnR = 0
[ 1303.093722] user pgtable: 4k pages, 48-bit VAs, pgdp=0000000072aa1000
[ 1303.093730] [0000000000001540] pgd=0000000074f43003,
pud=000000006f64c003, pmd=0000000000000000
[ 1303.093776] systemd-journald[246]: Failed to open system journal:
Cannot allocate memory
[ 1303.093859] systemd-journald[246]: Failed to open system journal:
Cannot allocate memory
[ 1303.094013] systemd-journald[246]: Failed to open system journal:
Cannot allocate memory
[ 1303.094095] systemd-journald[246]: Failed to open system journal:
Cannot allocate memory
[ 1303.094248] systemd-journald[246]: Failed to open system journal:
Cannot allocate memory
[ 1303.100042] CPU: 1 PID: 18028 Comm: thp04 Tainted: G      D
  5.4.114-rc1 #1
[ 1303.103634] Modules linked in: algif_hash wl18xx wlcore mac80211
cfg80211 hci_uart snd_soc_hdmi_codec btbcm snd_soc_audio_graph_card
adv7511 snd_soc_simple_card_utils crct10dif_ce wlcore_sdio bluetooth
cec kirin_drm drm_kms_helper dw_drm_dsi rfkill drm fuse
[ 1303.111730] Hardware name: HiKey Development Board (DT)
[ 1303.114592] CPU: 6 PID: 0 Comm: swapper/6 Tainted: G      D
  5.4.114-rc1 #1
[ 1303.117710] Call trace:
[ 1303.123139] Hardware name: HiKey Development Board (DT)
[ 1303.126270]  dump_backtrace+0x0/0x178
[ 1303.129474] pstate: a0000085 (NzCv daIf -PAN -UAO)
[ 1303.132417]  show_stack+0x28/0x38
[ 1303.136341] pc : next_online_pgdat+0x24/0x78
[ 1303.139369]  dump_stack+0xe0/0x160
[ 1303.145953] lr : next_zone+0x40/0x50
[ 1303.150939]  warn_alloc+0x100/0x170
[ 1303.156632] sp : ffff80001238bd20
[ 1303.179898]  __alloc_pages_slowpath+0xbc4/0xbf0
[ 1303.186741] x29: ffff80001238bd20 x28: 0000000000000000
[ 1303.192082]  __alloc_pages_nodemask+0x2ec/0x360
[ 1303.196535] x27: 0000000000000006 x26: ffff0000389e8000
[ 1303.201434]  alloc_pages_current+0x90/0x100
[ 1303.205791] x25: 0000000000000000 x24: ffff8000119f0218
[ 1303.209450]  do_huge_pmd_anonymous_page+0x3b0/0x818
[ 1303.212830] x23: ffff8000119f01d8 x22: 0000000000000000
[ 1303.218261]  __handle_mm_fault+0x83c/0x10a8
[ 1303.223684] x21: ffff80001238be00 x20: ffff80001238be18
[ 1303.229113]  handle_mm_fault+0x110/0x1f8
[ 1303.234536] x19: 0000000000000000 x18: 0000000000000000
[ 1303.239968]  do_page_fault+0x14c/0x4c8
[ 1303.245390] x17: 0000000000000000 x16: 0000000000000000
[ 1303.250821]  do_translation_fault+0xb4/0xd0
[ 1303.256245] x15: 0000000000000000 x14: 0000000000000000
[ 1303.261673]  do_mem_abort+0x54/0xb0
[ 1303.267097] x13: 0000000000000000 x12: 0000000000000000
[ 1303.272526]  el0_da+0x1c/0x20
[ 1303.277951] x11: 0000000000000000 x10: 0000000000000000
[ 1303.291214] Mem-Info:
[ 1303.304193] x9 : 0000000000000000 x8 : ffff000077bdbe20
[ 1303.304201] x7 : ffff8000119f0218 x6 : ffff8000119f0226
[ 1303.304208] x5 : 0000000000000000 x4 : ffff8000661c0000
[ 1303.304215] x3 : ffff800011feaff0 x2 : ffff8000661c0000
[ 1303.317358] Unable to handle kernel paging request at virtual
address 0000000000001540
[ 1303.327384] x1 : 0000000000000004 x0 : ffff800011fea000
[ 1303.327393] Call trace:
[ 1303.327406]  next_online_pgdat+0x24/0x78
[ 1303.327412]  next_zone+0x40/0x50
[ 1303.327420]  refresh_cpu_vm_stats+0xbc/0x3f8
[ 1303.327427]  quiet_vmstat+0x64/0x488
[ 1303.327441]  tick_nohz_idle_stop_tick+0xc4/0x2f0
[ 1303.338935] Mem abort info:
[ 1303.349349]  do_idle+0x1e0/0x2a0
[ 1303.349358]  cpu_startup_entry+0x30/0x90
[ 1303.349367]  secondary_start_kernel+0x16c/0x198
[ 1303.349380] Code: aa1e03e0 d503201f d2800081 9000eb20 (b9554262)
[ 1303.349392] ---[ end trace cd32e7e2c7ee891b ]---

Full test log link,
https://qa-reports.linaro.org/lkft/linux-stable-rc-linux-5.4.y/build/v5.4.113-74-gc509b45704fd/testrun/4393633/suite/linux-log-parser/test/check-kernel-oops-2551234/log
and
https://lkft.validation.linaro.org/scheduler/job/2551234#L10291

metadata:
  git branch: linux-5.4.y
  git repo: https://gitlab.com/Linaro/lkft/mirrors/stable/linux-stable-rc
  git commit: c509b45704fd663fc59405e98d29d7f06eaae4b5
  git describe: v5.4.113-74-gc509b45704fd
  make_kernelversion: 5.4.114-rc1
  kernel-config: https://builds.tuxbuild.com/1rOEzHLegEbudzQQdiifqEuFVur/config

--
Linaro LKFT
https://lkft.linaro.org


More information about the ltp mailing list