Bug 103842

Summary:	[CI] igt@drv_selftest@mock_gtt - dmesg-warn - drv_selftest: page allocation failure
Product:	DRI	Reporter:	Marta Löfstedt <marta.lofstedt>
Component:	DRM/Intel	Assignee:	Intel GFX Bugs mailing list <intel-gfx-bugs>
Status:	CLOSED FIXED	QA Contact:	Intel GFX Bugs mailing list <intel-gfx-bugs>
Severity:	normal
Priority:	medium	CC:	intel-gfx-bugs
Version:	DRI git
Hardware:	Other
OS:	All
Whiteboard:	ReadyForDev
i915 platform:	BXT	i915 features:	GEM/Other

Description Marta Löfstedt 2017-11-22 07:17:13 UTC

https://intel-gfx-ci.01.org/tree/drm-tip/IGT_3997/shard-apl3/igt@drv_selftest@mock_gtt.html

<7>[ 4966.885163] [IGT] drv_selftest: starting subtest mock_gtt
<4>[ 4966.947867] Setting dangerous option mock_selftests - tainting kernel
<6>[ 4966.972855] i915: Performing mock selftests with st_random_seed=0x2c54a11c st_timeout=1000
<4>[ 4966.983711] drv_selftest: page allocation failure: order:9, mode:0x14040c0(GFP_KERNEL|__GFP_COMP), nodemask=(null)
<4>[ 4966.983750] CPU: 2 PID: 4214 Comm: drv_selftest Tainted: G     U          4.14.0-CI-CI_DRM_3368+ #1
<4>[ 4966.983768] Hardware name:                  /NUC6CAYB, BIOS AYAPLCEL.86A.0040.2017.0619.1722 06/19/2017
<4>[ 4966.983786] Call Trace:
<4>[ 4966.983800]  dump_stack+0x5f/0x86
<4>[ 4966.983812]  warn_alloc+0xdb/0x170
<4>[ 4966.983825]  __alloc_pages_nodemask+0xf62/0x12a0
<4>[ 4966.983837]  ? ___slab_alloc.constprop.29+0x157/0x3d0
<4>[ 4966.983850]  ? init_object+0x7b/0x90
<4>[ 4966.983863]  ? lock_acquire+0xaf/0x200
<4>[ 4966.983945]  ? mock_context+0x25/0x120 [i915]
<4>[ 4966.983959]  kmalloc_order+0x24/0x70
<4>[ 4966.983970]  kmalloc_order_trace+0x1d/0x170
<4>[ 4966.984033]  ? shrink_hole+0x3f0/0x3f0 [i915]
<4>[ 4966.984102]  i915_random_order+0x1f/0x50 [i915]
<4>[ 4966.984168]  drunk_hole+0xea/0x4a0 [i915]
<4>[ 4966.984234]  ? shrink_hole+0x3f0/0x3f0 [i915]
<4>[ 4966.984297]  ? shrink_hole+0x3f0/0x3f0 [i915]
<4>[ 4966.984360]  exercise_mock+0x51/0x80 [i915]
<4>[ 4966.984428]  __i915_subtests+0x34/0xc0 [i915]
<4>[ 4966.984494]  i915_gem_gtt_mock_selftests+0x3f/0x70 [i915]
<4>[ 4966.984564]  __run_selftests+0x167/0x1c0 [i915]
<4>[ 4966.984576]  ? 0xffffffffa05cc000
<4>[ 4966.984641]  i915_mock_selftests+0x2c/0x60 [i915]
<4>[ 4966.984700]  i915_init+0x7/0x6f [i915]
<4>[ 4966.984711]  ? 0xffffffffa05cc000
<4>[ 4966.984721]  do_one_initcall+0x3e/0x160
<4>[ 4966.984733]  ? kmem_cache_alloc_trace+0x1ec/0x2b0
<4>[ 4966.984746]  do_init_module+0x5b/0x1fa
<4>[ 4966.984757]  load_module+0x2374/0x2dc0
<4>[ 4966.984768]  ? show_coresize+0x30/0x30
<4>[ 4966.984779]  ? vfs_read+0x12b/0x150
<4>[ 4966.984792]  ? SyS_finit_module+0xaa/0xe0
<4>[ 4966.984803]  SyS_finit_module+0xaa/0xe0
<4>[ 4966.984818]  entry_SYSCALL_64_fastpath+0x1c/0xb1
<4>[ 4966.984829] RIP: 0033:0x7fd2da0ad9f9
<4>[ 4966.984838] RSP: 002b:00007fff4234a158 EFLAGS: 00000202 ORIG_RAX: 0000000000000139
<4>[ 4966.984856] RAX: ffffffffffffffda RBX: 0000000000000005 RCX: 00007fd2da0ad9f9
<4>[ 4966.984870] RDX: 0000000000000000 RSI: 00005560a72b2e10 RDI: 0000000000000005
<4>[ 4966.984884] RBP: 00007fff42349160 R08: 0000000000000000 R09: 0000000000000000
<4>[ 4966.984898] R10: 0000000000000005 R11: 0000000000000202 R12: 00005560a72aaab0
<4>[ 4966.984912] R13: 00007fff42349140 R14: 0000000000000005 R15: 0000000000000000
<4>[ 4966.985369] Mem-Info:
<4>[ 4966.985421] active_anon:45339 inactive_anon:47129 isolated_anon:0
                   active_file:32241 inactive_file:18278 isolated_file:0
                   unevictable:0 dirty:5 writeback:16 unstable:0
                   slab_reclaimable:9548 slab_unreclaimable:25016
                   mapped:11815 shmem:37781 pagetables:1392 bounce:0
                   free:1805647 free_pcp:63 free_cma:0
<4>[ 4966.985484] Node 0 active_anon:181356kB inactive_anon:188516kB active_file:128964kB inactive_file:73112kB unevictable:0kB isolated(anon):0kB isolated(file):0kB mapped:47260kB dirty:20kB writeback:64kB shmem:151124kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 49152kB writeback_tmp:0kB unstable:0kB all_unreclaimable? no
<4>[ 4966.985535] DMA free:15896kB min:132kB low:164kB high:196kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:15984kB managed:15896kB mlocked:0kB kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB
<4>[ 4966.985579] lowmem_reserve[]: 0 1775 7777 7777
<4>[ 4966.985598] DMA32 free:1794584kB min:15392kB low:19240kB high:23088kB active_anon:18432kB inactive_anon:4096kB active_file:96kB inactive_file:0kB unevictable:0kB writepending:0kB present:1926488kB managed:1820688kB mlocked:0kB kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB
<4>[ 4966.985644] lowmem_reserve[]: 0 0 6001 6001
<4>[ 4966.985664] Normal free:5412108kB min:52052kB low:65064kB high:78076kB active_anon:162752kB inactive_anon:184420kB active_file:128708kB inactive_file:72992kB unevictable:0kB writepending:0kB present:6291456kB managed:6145996kB mlocked:0kB kernel_stack:3264kB pagetables:5568kB bounce:0kB free_pcp:252kB local_pcp:0kB free_cma:0kB
<4>[ 4966.985714] lowmem_reserve[]: 0 0 0 0
<4>[ 4966.985729] DMA: 2*4kB (U) 2*8kB (U) 2*16kB (U) 3*32kB (U) 2*64kB (U) 0*128kB 1*256kB (U) 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) = 15896kB
<4>[ 4966.985784] DMA32: 876*4kB (UM) 883*8kB (UM) 885*16kB (UM) 882*32kB (UM) 883*64kB (UM) 881*128kB (UM) 878*256kB (UM) 878*512kB (UM) 877*1024kB (UM) 0*2048kB 0*4096kB = 1794584kB
<4>[ 4966.985843] Normal: 3118*4kB (UME) 4328*8kB (UME) 2269*16kB (UME) 3220*32kB (UME) 3100*64kB (UME) 2514*128kB (UE) 2592*256kB (UME) 2725*512kB (UME) 2585*1024kB (UME) 0*2048kB 0*4096kB = 5412424kB
<6>[ 4966.985906] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB
<4>[ 4966.985923] 91512 total pagecache pages
<4>[ 4966.985933] 3230 pages in swap cache
<4>[ 4966.985942] Swap cache stats: add 177358, delete 174130, find 89300/121520
<4>[ 4966.985956] Free swap  = 1845936kB
<4>[ 4966.985964] Total swap = 2097148kB
<4>[ 4966.985973] 2058482 pages RAM
<4>[ 4966.985981] 0 pages HighMem/MovableOnly
<4>[ 4966.985989] 62837 pages reserved
<5>[ 4967.975396] drunk_hole timed out after 211567/262144
<5>[ 4968.976395] walk_hole timed out at 34ff8000
<5>[ 4970.182055] pot_hole timed out after 47/63
<5>[ 4971.883941] fill_hole timed out (npages=17592186044416, prime=2)
<7>[ 4973.219919] [IGT] drv_selftest: exiting, ret=0

Comment 1 Chris Wilson 2017-11-22 14:41:30 UTC

commit c65c8b0f7a1f90e84c273e111fc9391c50c3c482
Author: Chris Wilson <chris@chris-wilson.co.uk>
Date:   Wed Nov 22 12:06:00 2017 +0000

    drm/i915/selftests: Use NOWARN for large allocations
    
    We may try to do a large kmalloc for the permutation array, falling back
    to a smaller array/test if the first allocation fails. Since we are
    intentionally trying a large allocation which may fail, pass __GFP_NOWARN.
    
    Bugzilla: https://bugs.freedesktop.org/show_bug.cgi?id=103842
    Signed-off-by: Chris Wilson <chris@chris-wilson.co.uk>
    Cc: Matthew Auld <matthew.auld@intel.com>
    Link: https://patchwork.freedesktop.org/patch/msgid/20171122120600.27025-1-chris@chris-wilson.co.uk
    Reviewed-by: Matthew Auld <matthew.auld@intel.com>

Comment 2 Marta Löfstedt 2017-11-23 08:35:53 UTC

Fix integtrated to CI_DRM_3373, however the issue hasn't been seen for quite a while. Closing

Use of freedesktop.org services, including Bugzilla, is subject to our Code of Conduct. How we collect and use information is described in our Privacy Policy.