Bug 1421375 - page allocation stalls for 19290ms
Summary: page allocation stalls for 19290ms
Status: NEW
Alias: None
Product: Fedora
Classification: Fedora
Component: kernel
Version: rawhide
Hardware: Unspecified
OS: Unspecified
unspecified
unspecified
Target Milestone: ---
Assignee: Kernel Maintainer List
QA Contact: Fedora Extras Quality Assurance
URL:
Whiteboard:
Keywords:
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2017-02-11 17:49 UTC by Mikhail
Modified: 2019-01-09 12:54 UTC (History)
10 users (show)

(edit)
Clone Of:
(edit)
Last Closed:


Attachments (Terms of Use)
dmesg (130.24 KB, text/plain)
2017-02-11 17:49 UTC, Mikhail
no flags Details
dmesg (121.26 KB, text/plain)
2017-04-15 10:36 UTC, Mikhail
no flags Details
dmesg (125.39 KB, text/plain)
2017-07-20 15:14 UTC, Mikhail
no flags Details

Description Mikhail 2017-02-11 17:49:28 UTC
Created attachment 1249373 [details]
dmesg

Description of problem:

[30917.055898] pulseaudio: page allocation stalls for 19290ms, order:0, mode:0x24000c0(GFP_KERNEL)
[30917.055904] CPU: 4 PID: 1836 Comm: pulseaudio Not tainted 4.9.9-200.fc25.x86_64+debug #1
[30917.055905] Hardware name: Gigabyte Technology Co., Ltd. Z87M-D3H/Z87M-D3H, BIOS F11 08/12/2014
[30917.055906]  ffffab694d60b798 ffffffffbd476d33 ffffffffbdc6fb98 0000000000000001
[30917.055910]  ffffab694d60b820 ffffffffbd20deaa 024000c000000000 ffffffffbdc6fb98
[30917.055913]  ffffab694d60b7c0 ffff931400000010 ffffab694d60b830 ffffab694d60b7e0
[30917.055915] Call Trace:
[30917.055919]  [<ffffffffbd476d33>] dump_stack+0x86/0xc3
[30917.055922]  [<ffffffffbd20deaa>] warn_alloc+0x13a/0x170
[30917.055924]  [<ffffffffbd20e9cb>] __alloc_pages_slowpath+0xa7b/0xd40
[30917.055926]  [<ffffffffbd20ef4f>] ? __alloc_pages_nodemask+0x2bf/0x3c0
[30917.055928]  [<ffffffffbd20ef4f>] __alloc_pages_nodemask+0x2bf/0x3c0
[30917.055931]  [<ffffffffbd26d121>] alloc_pages_current+0xa1/0x1f0
[30917.055932]  [<ffffffffbd2093c4>] ? __get_free_pages+0x14/0x40
[30917.055933]  [<ffffffffbd2093c4>] __get_free_pages+0x14/0x40
[30917.055935]  [<ffffffffbd2c4249>] __pollwait+0x99/0xe0
[30917.055938]  [<ffffffffbd891419>] unix_poll+0x29/0xc0
[30917.055940]  [<ffffffffbd790930>] sock_poll+0x80/0xa0
[30917.055942]  [<ffffffffbd2c5b68>] do_sys_poll+0x278/0x560
[30917.055944]  [<ffffffffbd2c41b0>] ? poll_initwait+0x50/0x50
[30917.055946]  [<ffffffffbd2c4590>] ? poll_select_copy_remaining+0x170/0x170
[30917.055947]  [<ffffffffbd2c4590>] ? poll_select_copy_remaining+0x170/0x170
[30917.055949]  [<ffffffffbd2c4590>] ? poll_select_copy_remaining+0x170/0x170
[30917.055951]  [<ffffffffbd2c4590>] ? poll_select_copy_remaining+0x170/0x170
[30917.055952]  [<ffffffffbd2c4590>] ? poll_select_copy_remaining+0x170/0x170
[30917.055954]  [<ffffffffbd2c4590>] ? poll_select_copy_remaining+0x170/0x170
[30917.055955]  [<ffffffffbd2c4590>] ? poll_select_copy_remaining+0x170/0x170
[30917.055957]  [<ffffffffbd2c4590>] ? poll_select_copy_remaining+0x170/0x170
[30917.055959]  [<ffffffffbd2c4590>] ? poll_select_copy_remaining+0x170/0x170
[30917.055960]  [<ffffffffbd2c6195>] SyS_ppoll+0x195/0x1c0
[30917.055963]  [<ffffffffbd00301a>] ? trace_hardirqs_on_thunk+0x1a/0x1c
[30917.055966]  [<ffffffffbd918cc1>] entry_SYSCALL_64_fastpath+0x1f/0xc2
[30917.055968] Mem-Info:
[30917.055973] active_anon:6951783 inactive_anon:532293 isolated_anon:0
                active_file:45816 inactive_file:30180 isolated_file:0
                unevictable:1740 dirty:12 writeback:0 unstable:0
                slab_reclaimable:64266 slab_unreclaimable:84279
                mapped:462712 shmem:555041 pagetables:87418 bounce:0
                free:49289 free_pcp:0 free_cma:0
[30917.055976] Node 0 active_anon:27807132kB inactive_anon:2129172kB active_file:183264kB inactive_file:120720kB unevictable:6960kB isolated(anon):0kB isolated(file):0kB mapped:1850848kB dirty:48kB writeback:0kB shmem:0kB shmem_thp: 0kB shmem_pmdmapped: 3108864kB anon_thp: 2220164kB writeback_tmp:0kB unstable:0kB pages_scanned:0 all_unreclaimable? no
[30917.055977] Node 0 DMA free:15268kB min:32kB low:44kB high:56kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:15988kB managed:15896kB mlocked:0kB slab_reclaimable:0kB slab_unreclaimable:628kB kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB
[30917.055981] lowmem_reserve[]: 0 2415 31042 31042 31042
[30917.055985] Node 0 DMA32 free:119748kB min:5252kB low:7724kB high:10196kB active_anon:2215000kB inactive_anon:38288kB active_file:19072kB inactive_file:2780kB unevictable:16kB writepending:12kB present:2556492kB managed:2490504kB mlocked:16kB slab_reclaimable:8000kB slab_unreclaimable:14924kB kernel_stack:5676kB pagetables:29716kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB
[30917.055989] lowmem_reserve[]: 0 0 28626 28626 28626
[30917.055992] Node 0 Normal free:62140kB min:62292kB low:91604kB high:120916kB active_anon:25592132kB inactive_anon:2090884kB active_file:164192kB inactive_file:117640kB unevictable:6944kB writepending:0kB present:29874176kB managed:29319088kB mlocked:6944kB slab_reclaimable:249064kB slab_unreclaimable:321564kB kernel_stack:50548kB pagetables:319956kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB
[30917.055996] lowmem_reserve[]: 0 0 0 0 0
[30917.055999] Node 0 DMA: 1*4kB (U) 0*8kB 0*16kB 1*32kB (U) 0*64kB 1*128kB (U) 1*256kB (U) 1*512kB (U) 0*1024kB 1*2048kB (M) 3*4096kB (M) = 15268kB
[30917.056013] Node 0 DMA32: 129*4kB (UME) 1609*8kB (UME) 996*16kB (UME) 900*32kB (UME) 493*64kB (UME) 134*128kB (UME) 43*256kB (UME) 2*512kB (E) 1*1024kB (E) 0*2048kB 0*4096kB = 119884kB
[30917.056026] Node 0 Normal: 8429*4kB (UMEH) 3264*8kB (UMEH) 142*16kB (UMH) 14*32kB (H) 7*64kB (H) 3*128kB (H) 1*256kB (H) 0*512kB 0*1024kB 0*2048kB 0*4096kB = 63636kB
[30917.056039] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB
[30917.056040] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB
[30917.056041] 721969 total pagecache pages
[30917.056042] 91240 pages in swap cache
[30917.056043] Swap cache stats: add 2335461, delete 2244221, find 382649/549754
[30917.056044] Free swap  = 57139792kB
[30917.056044] Total swap = 62494716kB
[30917.056076] 8111664 pages RAM
[30917.056076] 0 pages HighMem/MovableOnly
[30917.056077] 155292 pages reserved
[30917.056077] 0 pages cma reserved
[30917.056078] 0 pages hwpoisoned

Comment 1 Justin M. Forbes 2017-04-11 14:57:16 UTC
*********** MASS BUG UPDATE **************

We apologize for the inconvenience.  There is a large number of bugs to go through and several of them have gone stale.  Due to this, we are doing a mass bug update across all of the Fedora 25 kernel bugs.

Fedora 25 has now been rebased to 4.10.9-200.fc25.  Please test this kernel update (or newer) and let us know if you issue has been resolved or if it is still present with the newer kernel.

If you have moved on to Fedora 26, and are still experiencing this issue, please change the version to Fedora 26.

If you experience different issues, please open a new bug report for those.

Comment 2 Mikhail 2017-04-15 10:34:09 UTC
Still also happens with kernel 4.11.0-0.rc6 on Fedora 26


[59756.058579] chrome: page allocation stalls for 11683ms, order:0, mode:0x17080c0(GFP_KERNEL_ACCOUNT|__GFP_ZERO|__GFP_NOTRACK), nodemask=(null)
[59756.058630] chrome cpuset=/ mems_allowed=0
[59756.058834] CPU: 2 PID: 5506 Comm: chrome Not tainted 4.11.0-0.rc6.git0.1.local.fc26.x86_64+debug #1
[59756.058837] Hardware name: Gigabyte Technology Co., Ltd. Z87M-D3H/Z87M-D3H, BIOS F11 08/12/2014
[59756.058839] Call Trace:
[59756.058845]  dump_stack+0x8e/0xd1
[59756.058851]  warn_alloc+0x114/0x1c0
[59756.058865]  __alloc_pages_slowpath+0xa9d/0x1120
[59756.058892]  __alloc_pages_nodemask+0x33a/0x3b0
[59756.058903]  alloc_pages_current+0x8f/0x1c0
[59756.058912]  pte_alloc_one+0x17/0x80
[59756.058916]  __pte_alloc+0x1e/0x120
[59756.058921]  __handle_mm_fault+0xe30/0x1160
[59756.058926]  ? sched_clock+0x9/0x10
[59756.058929]  ? sched_clock+0x9/0x10
[59756.058943]  handle_mm_fault+0x153/0x380
[59756.058950]  __do_page_fault+0x26e/0x510
[59756.058960]  do_page_fault+0x30/0x80
[59756.058967]  page_fault+0x28/0x30
[59756.058970] RIP: 0033:0x55ffb717bfeb
[59756.058972] RSP: 002b:00007ffd68df1b40 EFLAGS: 00010202
[59756.058976] RAX: 00002a1c9551ec88 RBX: 000008c3e8c01000 RCX: 0000000000004468
[59756.058978] RDX: 0000000000000003 RSI: 0000000000012000 RDI: 000008c3e8c01000
[59756.058980] RBP: 0000122729dfc4a0 R08: 00000000ffffffff R09: 0000000000000000
[59756.058983] R10: 0000000000000022 R11: 0000000000000246 R12: 00002a1c9345c000
[59756.058985] R13: 0000000000002223 R14: 0000000000011120 R15: 0000000000000205
[59756.059029] Mem-Info:
[59756.059034] active_anon:6860953 inactive_anon:483139 isolated_anon:18
                active_file:51933 inactive_file:60012 isolated_file:0
                unevictable:1608 dirty:6010 writeback:105 unstable:0
                slab_reclaimable:84435 slab_unreclaimable:98499
                mapped:280676 shmem:383979 pagetables:123791 bounce:0
                free:49177 free_pcp:272 free_cma:0
[59756.059040] Node 0 active_anon:27443812kB inactive_anon:1932556kB active_file:207732kB inactive_file:240048kB unevictable:6432kB isolated(anon):72kB isolated(file):0kB mapped:1122704kB dirty:24040kB writeback:420kB shmem:1535916kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 1888256kB writeback_tmp:0kB unstable:0kB pages_scanned:324 all_unreclaimable? no
[59756.059042] Node 0 DMA free:15664kB min:32kB low:44kB high:56kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:15988kB managed:15896kB mlocked:0kB slab_reclaimable:0kB slab_unreclaimable:232kB kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB
[59756.059048] lowmem_reserve[]: 0 2405 31030 31030 31030
[59756.059061] Node 0 DMA32 free:119420kB min:5236kB low:7696kB high:10156kB active_anon:2088200kB inactive_anon:158412kB active_file:15884kB inactive_file:20388kB unevictable:0kB writepending:1048kB present:2548836kB managed:2482848kB mlocked:0kB slab_reclaimable:21028kB slab_unreclaimable:18844kB kernel_stack:912kB pagetables:19852kB bounce:0kB free_pcp:4kB local_pcp:0kB free_cma:0kB
[59756.059068] lowmem_reserve[]: 0 0 28624 28624 28624
[59756.059080] Node 0 Normal free:61624kB min:62312kB low:91620kB high:120928kB active_anon:25355556kB inactive_anon:1774380kB active_file:190972kB inactive_file:220176kB unevictable:6432kB writepending:23948kB present:29874176kB managed:29317280kB mlocked:6432kB slab_reclaimable:316712kB slab_unreclaimable:374920kB kernel_stack:56384kB pagetables:475312kB bounce:0kB free_pcp:1084kB local_pcp:8kB free_cma:0kB
[59756.059086] lowmem_reserve[]: 0 0 0 0 0
[59756.059099] Node 0 DMA: 0*4kB 0*8kB 1*16kB (U) 1*32kB (U) 0*64kB 2*128kB (U) 0*256kB 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) = 15664kB
[59756.059139] Node 0 DMA32: 816*4kB (UME) 7481*8kB (UMEH) 1613*16kB (UMEH) 278*32kB (UMEH) 176*64kB (UMEH) 82*128kB (UMEH) 1*256kB (H) 0*512kB 0*1024kB 0*2048kB 0*4096kB = 119832kB
[59756.059180] Node 0 Normal: 5407*4kB (UME) 4585*8kB (UME) 192*16kB (UME) 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 61380kB
[59756.059214] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB
[59756.059216] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB
[59756.059218] 1540716 total pagecache pages
[59756.059229] 1044780 pages in swap cache
[59756.059231] Swap cache stats: add 57685593, delete 56638542, find 27315019/42985977
[59756.059233] Free swap  = 36481748kB
[59756.059235] Total swap = 62494716kB
[59756.059284] 8109750 pages RAM
[59756.059286] 0 pages HighMem/MovableOnly
[59756.059288] 155744 pages reserved
[59756.059290] 0 pages cma reserved
[59756.059292] 0 pages hwpoisoned
[59756.059439] chrome: page allocation stalls for 11680ms, order:0, mode:0x14280ca(GFP_HIGHUSER_MOVABLE|__GFP_ZERO), nodemask=(null)
[59756.059449] chrome cpuset=/ mems_allowed=0
[59756.059456] CPU: 2 PID: 6805 Comm: chrome Not tainted 4.11.0-0.rc6.git0.1.local.fc26.x86_64+debug #1
[59756.059458] Hardware name: Gigabyte Technology Co., Ltd. Z87M-D3H/Z87M-D3H, BIOS F11 08/12/2014
[59756.059460] Call Trace:
[59756.059465]  dump_stack+0x8e/0xd1
[59756.059469]  warn_alloc+0x114/0x1c0
[59756.059483]  __alloc_pages_slowpath+0xa9d/0x1120
[59756.059509]  __alloc_pages_nodemask+0x33a/0x3b0
[59756.059519]  alloc_pages_vma+0xa7/0x330
[59756.059529]  __handle_mm_fault+0x80d/0x1160
[59756.059532]  ? sched_clock+0x9/0x10
[59756.059535]  ? sched_clock+0x9/0x10
[59756.059548]  handle_mm_fault+0x153/0x380
[59756.059554]  __do_page_fault+0x26e/0x510
[59756.059564]  do_page_fault+0x30/0x80
[59756.059570]  page_fault+0x28/0x30
[59756.059573] RIP: 0033:0x24b449c75798
[59756.059575] RSP: 002b:00007ffd68df22b0 EFLAGS: 00010202
[59756.059579] RAX: 0000000000000004 RBX: 00000b1a81261001 RCX: 000026f2358c2abd
[59756.059581] RDX: 0000000000000000 RSI: 0000028f99b02781 RDI: 0000000400000000
[59756.059583] RBP: 00007ffd68df22f8 R08: 0000000000000000 R09: 00000b1a81261000
[59756.059585] R10: 000000000000004a R11: 00000b1a81261020 R12: 0000000d00000000
[59756.059587] R13: 00003f4938f700c8 R14: 0000000000000003 R15: 00007ffd68df22b8

Comment 3 Mikhail 2017-04-15 10:36 UTC
Created attachment 1271780 [details]
dmesg

Comment 4 Mikhail 2017-07-20 15:11:30 UTC
[ 3146.319639] Compositor: page allocation stalls for 19167ms, order:0, mode:0x14200ca(GFP_HIGHUSER_MOVABLE), nodemask=(null)
[ 3146.319687] Compositor cpuset=/ mems_allowed=0
[ 3146.319934] CPU: 4 PID: 13148 Comm: Compositor Not tainted 4.13.0-0.rc1.git1.1.fc27.x86_64 #1
[ 3146.319936] Hardware name: Gigabyte Technology Co., Ltd. Z87M-D3H/Z87M-D3H, BIOS F11 08/12/2014
[ 3146.319937] Call Trace:
[ 3146.319942]  dump_stack+0x8e/0xd6
[ 3146.319946]  warn_alloc+0x114/0x1c0
[ 3146.319954]  __alloc_pages_slowpath+0x906/0x10f0
[ 3146.319961]  ? _raw_spin_unlock_irq+0x2c/0x40
[ 3146.319965]  ? finish_task_switch+0x6a/0x260
[ 3146.319975]  __alloc_pages_nodemask+0x351/0x3e0
[ 3146.319982]  alloc_pages_vma+0x88/0x200
[ 3146.319986]  __read_swap_cache_async+0x168/0x270
[ 3146.319991]  read_swap_cache_async+0x2b/0x60
[ 3146.319995]  swapin_readahead+0x125/0x250
[ 3146.320004]  do_swap_page+0x268/0x880
[ 3146.320006]  ? do_swap_page+0x268/0x880
[ 3146.320009]  ? do_wp_page+0x169/0x590
[ 3146.320013]  __handle_mm_fault+0x725/0x1080
[ 3146.320021]  handle_mm_fault+0x178/0x350
[ 3146.320026]  __do_page_fault+0x26e/0x510
[ 3146.320031]  do_page_fault+0x30/0x80
[ 3146.320034]  page_fault+0x28/0x30
[ 3146.320037] RIP: 0033:0x5d55255a35
[ 3146.320038] RSP: 002b:00007f4807520a10 EFLAGS: 00010283
[ 3146.320040] RAX: 000000000000000c RBX: 0000000002eaa92a RCX: 0000000000000088
[ 3146.320041] RDX: 0000000000000000 RSI: 0000000000000004 RDI: 0000000000000040
[ 3146.320043] RBP: 000005f4dd6c58c0 R08: 0000000000000000 R09: 0000000000000000
[ 3146.320044] R10: 00007f48214c3600 R11: 0000000000000001 R12: 0000000000000040
[ 3146.320045] R13: 00007f4807521058 R14: 000005f4dc338598 R15: 000005f4dcc12000
[ 3146.320122] Mem-Info:
[ 3146.320125] active_anon:6895350 inactive_anon:539187 isolated_anon:895
                active_file:36814 inactive_file:24998 isolated_file:0
                unevictable:653 dirty:140 writeback:0 unstable:0
                slab_reclaimable:653 slab_unreclaimable:163
                mapped:188853 shmem:343570 pagetables:102354 bounce:0
                free:49597 free_pcp:146 free_cma:0
[ 3146.320128] Node 0 active_anon:27581400kB inactive_anon:2156748kB active_file:147256kB inactive_file:99992kB unevictable:2612kB isolated(anon):3580kB isolated(file):0kB mapped:755412kB dirty:560kB writeback:0kB shmem:1374280kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 1619968kB writeback_tmp:0kB unstable:0kB all_unreclaimable? no
[ 3146.320130] Node 0 DMA free:15864kB min:32kB low:44kB high:56kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:15988kB managed:15896kB mlocked:0kB kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB
[ 3146.320133] lowmem_reserve[]: 0 2372 30978 30978 30978
[ 3146.320141] Node 0 DMA32 free:119684kB min:5172kB low:7600kB high:10028kB active_anon:2091820kB inactive_anon:141704kB active_file:4708kB inactive_file:6996kB unevictable:0kB writepending:0kB present:2514388kB managed:2448676kB mlocked:0kB kernel_stack:1176kB pagetables:16468kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB
[ 3146.320144] lowmem_reserve[]: 0 0 28606 28606 28606
[ 3146.320152] Node 0 Normal free:62840kB min:62376kB low:91668kB high:120960kB active_anon:25489108kB inactive_anon:2014652kB active_file:142712kB inactive_file:93096kB unevictable:2612kB writepending:736kB present:29874176kB managed:29298604kB mlocked:2612kB kernel_stack:52200kB pagetables:392948kB bounce:0kB free_pcp:584kB local_pcp:0kB free_cma:0kB
[ 3146.320155] lowmem_reserve[]: 0 0 0 0 0
[ 3146.320162] Node 0 DMA: 2*4kB (U) 2*8kB (U) 0*16kB 1*32kB (U) 3*64kB (U) 2*128kB (U) 0*256kB 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) = 15864kB
[ 3146.320187] Node 0 DMA32: 533*4kB (UME) 1233*8kB (UME) 718*16kB (UME) 365*32kB (UME) 559*64kB (UME) 175*128kB (UME) 56*256kB (UME) 14*512kB (ME) 5*1024kB (UE) 0*2048kB 0*4096kB = 119964kB
[ 3146.320212] Node 0 Normal: 1053*4kB (UME) 360*8kB (UME) 560*16kB (UME) 537*32kB (UME) 147*64kB (ME) 24*128kB (M) 15*256kB (M) 11*512kB (M) 6*1024kB (M) 1*2048kB (M) 0*4096kB = 63380kB
[ 3146.320239] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB
[ 3146.320241] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB
[ 3146.320242] 1197990 total pagecache pages
[ 3146.320251] 792504 pages in swap cache
[ 3146.320252] Swap cache stats: add 10176068, delete 9384014, find 3315053/5091678
[ 3146.320253] Free swap  = 46907644kB
[ 3146.320254] Total swap = 62494716kB
[ 3146.320326] 8101138 pages RAM
[ 3146.320327] 0 pages HighMem/MovableOnly
[ 3146.320346] 160344 pages reserved
[ 3146.320348] 0 pages cma reserved
[ 3146.320349] 0 pages hwpoisoned

Comment 5 Mikhail 2017-07-20 15:14 UTC
Created attachment 1301803 [details]
dmesg

Comment 6 Vedran Miletić 2018-01-31 03:08:25 UTC
Can confirm on 4.14.13 on Fedora 27, when system is under high load (e.g. kernel compile), I get:

[1307794.925220] Adding 3617788k swap on /dev/sda2.  Priority:-2 extents:1 across:3617788k FS
[1693102.255453] glances: page allocation stalls for 12005ms, order:0, mode:0x14000c0(GFP_KERNEL), nodemask=(null)
[1693102.255497] glances cpuset=/ mems_allowed=0
[1693102.255530] CPU: 1 PID: 25104 Comm: glances Tainted: G      D         4.14.13-300.fc27.x86_64 #1
[1693102.255560] Hardware name: System manufacturer System Product Name/P5QL-EM, BIOS 0802    05/12/2010
[1693102.255591] Call Trace:
[1693102.255625]  dump_stack+0x63/0x8b
[1693102.255655]  warn_alloc+0x114/0x1c0
[1693102.255684]  __alloc_pages_slowpath+0x907/0xf00
[1693102.255714]  ? kmem_cache_alloc+0x163/0x1c0
[1693102.255743]  ? __d_alloc+0x27/0x1d0
[1693102.255771]  __alloc_pages_nodemask+0x28b/0x2b0
[1693102.255802]  alloc_pages_current+0x6a/0xe0
[1693102.255832]  __get_free_pages+0x14/0x40
[1693102.255865]  inode_doinit_with_dentry+0x232/0x580
[1693102.255895]  selinux_d_instantiate+0x1c/0x20
[1693102.255945]  security_d_instantiate+0x32/0x50
[1693102.255992]  d_add+0x28/0x170
[1693102.256046]  proc_lookup_de+0x9f/0xb0
[1693102.256093]  proc_lookup+0x1b/0x20
[1693102.256140]  proc_root_lookup+0x2f/0x40
[1693102.256188]  path_openat+0xc91/0x15c0
[1693102.256237]  do_filp_open+0x9b/0x110
[1693102.256289]  ? __check_object_size+0xaf/0x1b0
[1693102.256337]  do_sys_open+0x1ba/0x250
[1693102.256345]  ? do_sys_open+0x1ba/0x250
[1693102.256345]  SyS_openat+0x14/0x20
[1693102.256345]  entry_SYSCALL_64_fastpath+0x20/0x83
[1693102.256345] RIP: 0033:0x7f3868736080
[1693102.256345] RSP: 002b:00007ffda00970b0 EFLAGS: 00000293 ORIG_RAX: 0000000000000101
[1693102.256345] RAX: ffffffffffffffda RBX: 0000561382d61498 RCX: 00007f3868736080
[1693102.256345] RDX: 0000000000080000 RSI: 00007f384aef89b0 RDI: ffffffffffffff9c
[1693102.256345] RBP: 00007f385d394ae0 R08: 0000000000000000 R09: 00007f3868ffcdf1
[1693102.256345] R10: 0000000000000000 R11: 0000000000000293 R12: 00007f385bca1048
[1693102.256345] R13: 00007f38690b0480 R14: 00007f385d3dd9d8 R15: 0000000000000000
[1693102.257029] Mem-Info:
[1693102.257083] active_anon:20821 inactive_anon:34241 isolated_anon:0
                  active_file:195508 inactive_file:668580 isolated_file:0
                  unevictable:0 dirty:1851 writeback:0 unstable:0
                  slab_reclaimable:30854 slab_unreclaimable:14985
                  mapped:12004 shmem:342 pagetables:5586 bounce:0
                  free:21073 free_pcp:0 free_cma:0
[1693102.257251] Node 0 active_anon:83284kB inactive_anon:136964kB active_file:782032kB inactive_file:2674320kB unevictable:0kB isolated(anon):0kB isolated(file):0kB mapped:48016kB dirty:7404kB writeback:0kB shmem:1368kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 0kB writeback_tmp:0kB unstable:0kB all_unreclaimable? no
[1693102.257388] Node 0 DMA free:15860kB min:264kB low:328kB high:392kB active_anon:36kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:15988kB managed:15904kB mlocked:0kB kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB
[1693102.257514] lowmem_reserve[]: 0 3458 3904 3904 3904
[1693102.257564] Node 0 DMA32 free:61128kB min:59612kB low:74512kB high:89412kB active_anon:73388kB inactive_anon:108212kB active_file:733420kB inactive_file:2432008kB unevictable:0kB writepending:6580kB present:3653120kB managed:3568436kB mlocked:0kB kernel_stack:6160kB pagetables:18748kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB
[1693102.257703] lowmem_reserve[]: 0 0 446 446 446
[1693102.257755] Node 0 Normal free:7304kB min:7700kB low:9624kB high:11548kB active_anon:9860kB inactive_anon:28752kB active_file:48612kB inactive_file:241808kB unevictable:0kB writepending:824kB present:524288kB managed:457384kB mlocked:0kB kernel_stack:1920kB pagetables:3596kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB
[1693102.257905] lowmem_reserve[]: 0 0 0 0 0
[1693102.257954] Node 0 DMA: 1*4kB (M) 2*8kB (MH) 2*16kB (MH) 0*32kB 3*64kB (MH) 2*128kB (MH) 2*256kB (MH) 1*512kB (M) 2*1024kB (MH) 0*2048kB 3*4096kB (UME) = 15860kB
[1693102.258071] Node 0 DMA32: 2493*4kB (UME) 2336*8kB (UME) 905*16kB (UME) 333*32kB (UME) 109*64kB (UME) 4*128kB (UE) 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 61284kB
[1693102.258172] Node 0 Normal: 324*4kB (UMH) 364*8kB (UMEH) 139*16kB (UEH) 25*32kB (UMEH) 2*64kB (H) 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 7360kB
[1693102.258278] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB
[1693102.258346] 865805 total pagecache pages
[1693102.258397] 1374 pages in swap cache
[1693102.258444] Swap cache stats: add 925626, delete 924253, find 3968351/4141050
[1693102.258514] Free swap  = 3413024kB
[1693102.258561] Total swap = 3617788kB
[1693102.258607] 1048349 pages RAM
[1693102.258653] 0 pages HighMem/MovableOnly
[1693102.258700] 37918 pages reserved
[1693102.258752] 0 pages cma reserved
[1693102.258803] 0 pages hwpoisoned
[1693102.259031] as: page allocation stalls for 13027ms, order:0, mode:0x14280ca(GFP_HIGHUSER_MOVABLE|__GFP_ZERO), nodemask=(null)
[1693102.259113] as cpuset=/ mems_allowed=0
[1693102.259167] CPU: 0 PID: 20332 Comm: as Tainted: G      D         4.14.13-300.fc27.x86_64 #1
[1693102.259242] Hardware name: System manufacturer System Product Name/P5QL-EM, BIOS 0802    05/12/2010
[1693102.259312] Call Trace:
[1693102.259373]  dump_stack+0x63/0x8b
[1693102.259428]  warn_alloc+0x114/0x1c0
[1693102.259479]  __alloc_pages_slowpath+0x907/0xf00
[1693102.259531]  __alloc_pages_nodemask+0x28b/0x2b0
[1693102.259583]  alloc_pages_vma+0x88/0x200
[1693102.259630]  __handle_mm_fault+0x9b9/0x12f0
[1693102.259678]  handle_mm_fault+0xb1/0x200
[1693102.259726]  __do_page_fault+0x257/0x4d0
[1693102.259772]  ? __schedule+0x241/0x860
[1693102.259817]  do_page_fault+0x38/0x120
[1693102.259864]  ? page_fault+0x36/0x60
[1693102.259915]  page_fault+0x4c/0x60
[1693102.259966] RIP: 0033:0x7f924fde6de9
[1693102.260005] RSP: 002b:00007ffc0247c3e0 EFLAGS: 00010202
[1693102.260005] RAX: 000000000000c521 RBX: 00007f9250136c20 RCX: 00005595120ffaf0
[1693102.260005] RDX: 0000000000000ff1 RSI: 0000000000000000 RDI: 0000559512100ae0
[1693102.260005] RBP: 0000000000000ff0 R08: 0000000000000000 R09: 00007f9250136c80
[1693102.260005] R10: ffffffffffffffb0 R11: 00000000000000fd R12: 0000000000000fe0
[1693102.260005] R13: 0000000000000062 R14: 00007f9250136c80 R15: 0000000000000031


Note You need to log in before you can comment on or make changes to this bug.