Created attachment 138639 [details]
dmesg and kern-logs
CNL QA System is failing the following test:
igt@gem_shrink@pread
Software Version:
IGT-Version: 1.22-g0721161 (x86_64) (Linux:
4.16.0-rc6-drm-intel-qa-ww12-commit-4db112a+ x86_64)
Using 62 processes and 128MiB per process
Stack trace:
#0 [__igt_fail_assert+0x101]
#1 [gem_read+0x91]
#2 [<unknown>+0x91]
child 0 died with signal 9, Killed
Subtest pread: FAIL (179.205s)
Stderr:
(gem_shrink:10628) ioctl-wrappers-CRITICAL: Test assertion failure function
gem_read, file ioctl_wrappers.c:387:
(gem_shrink:10628) ioctl-wrappers-CRITICAL: Failed assertion: __gem_read(fd,
handle, offset, buf, length) =3D=3D 0
(gem_shrink:10628) ioctl-wrappers-CRITICAL: Last errno: 12, Cannot allocate
memory
(gem_shrink:10628) ioctl-wrappers-CRITICAL: error: -12 !=3D 0
Subtest pread failed.
No log.
Dmesg:
[ 260.304006] 1 and 0 pages still available in the bound and unbound GPU p=
age
lists.
[ 260.304085] kworker/u9:2 invoked oom-killer:
gfp_mask=3D0x14002c0(GFP_KERNEL|__GFP_NOWARN), nodemask=3D(null), order=3D0,
oom_score_adj=3D0
[ 260.304090] CPU: 2 PID: 348 Comm: kworker/u9:2 Not tainted
4.16.0-rc6-drm-intel-qa-ww12-commit-4db112a+ #1
[ 260.304091] Hardware name: Intel Corporation CannonLake Client
Platform/CannonLake Y LPDDR4 RVP, BIOS CNLSFWR1.R00.X124.B02.1802051422
02/05/2018
[ 260.304101] Workqueue: rb_allocator iwl_pcie_rx_allocator_work [iwlwifi]
[ 260.304102] Call Trace:
[ 260.304109] dump_stack+0x5c/0x85
[ 260.304112] dump_header+0x6b/0x27c
[ 260.304115] oom_kill_process+0x239/0x480
[ 260.304116] ? oom_badness+0xeb/0x160
[ 260.304118] out_of_memory+0x10f/0x480
[ 260.304120] __alloc_pages_slowpath+0xcdd/0xdb0
[ 260.304123] ? __update_load_avg_se.isra.38+0x1c1/0x1d0
[ 260.304126] __alloc_pages_nodemask+0x246/0x280
[ 260.304131] iwl_pcie_rx_alloc_page+0x25/0x90 [iwlwifi]
[ 260.304137] iwl_pcie_rx_allocator_work+0x128/0x370 [iwlwifi]
[ 260.304141] process_one_work+0x147/0x3c0
[ 260.304143] worker_thread+0x4a/0x440
[ 260.304145] kthread+0xf8/0x130
[ 260.304147] ? rescuer_thread+0x360/0x360
[ 260.304148] ? kthread_associate_blkcg+0x90/0x90
[ 260.304151] ret_from_fork+0x35/0x40
[ 260.304152] Mem-Info:
[ 260.304157] active_anon:6190 inactive_anon:1909516 isolated_anon:448
active_file:60 inactive_file:60 isolated_file:0
unevictable:0 dirty:0 writeback:0 unstable:0
slab_reclaimable:9750 slab_unreclaimable:25610
mapped:543 shmem:1910538 pagetables:3122 bounce:0
free:27808 free_pcp:90 free_cma:0
[ 260.304160] Node 0 active_anon:24760kB inactive_anon:7638064kB
active_file:240kB inactive_file:240kB unevictable:0kB isolated(anon):1792kB
isolated(file):0kB mapped:2172kB dirty:0kB writeback:0kB shmem:7642152kB
shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 0kB writeback_tmp:0kB
unstable:0kB all_unreclaimable? yes
[ 260.304161] Node 0 DMA free:15884kB min:132kB low:164kB high:196kB
active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB
unevictable:0kB writepending:0kB present:15992kB managed:15884kB mlocked:0kB
kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB
free_cma:0kB
[ 260.304165] lowmem_reserve[]: 0 747 7758 7758 7758
[ 260.304167] Node 0 DMA32 free:34540kB min:6500kB low:8124kB high:9748kB
active_anon:260kB inactive_anon:767444kB active_file:4kB inactive_file:0kB
unevictable:0kB writepending:0kB present:872780kB managed:807024kB mlocked:=
0kB
kernel_stack:16kB pagetables:4kB bounce:0kB free_pcp:0kB local_pcp:0kB
free_cma:0kB
[ 260.304171] lowmem_reserve[]: 0 0 7010 7010 7010
[ 260.304173] Node 0 Normal free:60808kB min:60944kB low:76180kB high:9141=
6kB
active_anon:24500kB inactive_anon:6870480kB active_file:236kB
inactive_file:240kB unevictable:0kB writepending:0kB present:7335936kB
managed:7182840kB mlocked:0kB kernel_stack:4224kB pagetables:12484kB bounce=
:0kB
free_pcp:360kB local_pcp:360kB free_cma:0kB
[ 260.304177] lowmem_reserve[]: 0 0 0 0 0
[ 260.304179] Node 0 DMA: 1*4kB (U) 1*8kB (U) 2*16kB (U) 1*32kB (U) 1*64kB=
(U)
1*128kB (U) 1*256kB (U) 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) =3D
15884kB
[ 260.304190] Node 0 DMA32: 15*4kB (UM) 22*8kB (UM) 24*16kB (UM) 26*32kB (=
UME)
21*64kB (UME) 16*128kB (UM) 18*256kB (UME) 17*512kB (UME) 4*1024kB (M) 6*20=
48kB
(M) 0*4096kB =3D 34540kB
[ 260.304200] Node 0 Normal: 568*4kB (UME) 299*8kB (UM) 149*16kB (UME)
123*32kB (UM) 187*64kB (UM) 128*128kB (UM) 54*256kB (UME) 3*512kB (UME)
0*1024kB 1*2048kB (E) 1*4096kB (E) =3D 60840kB
[ 260.304213] 1911494 total pagecache pages
[ 260.304215] 836 pages in swap cache
[ 260.304216] Swap cache stats: add 5093657, delete 5092313, find
110160/132281
[ 260.304217] Free swap =3D 7673432kB
[ 260.304217] Total swap =3D 8000508kB
[ 260.304218] 2056177 pages RAM
[ 260.304218] 0 pages HighMem/MovableOnly
[ 260.304219] 54740 pages reserved
[ 260.304219] 0 pages cma reserved
[ 260.304220] 0 pages hwpoisoned
[ 260.304381] Out of memory: Kill process 10614 (gem_shrink) score 999 or
sacrifice child
[ 260.304397] Killed process 10617 (gem_shrink) total-vm:51648kB,
anon-rss:396kB, file-rss:0kB, shmem-rss:0kB
[ 281.828011] 1 and 32768 pages still available in the bound and unbound G=
PU
page lists.
[ 281.828093] kworker/u9:2 invoked oom-killer:
gfp_mask=3D0x14002c0(GFP_KERNEL|__GFP_NOWARN), nodemask=3D(null), order=3D0,
oom_score_adj=3D0
[ 281.828097] CPU: 0 PID: 348 Comm: kworker/u9:2 Not tainted
4.16.0-rc6-drm-intel-qa-ww12-commit-4db112a+ #1
[ 281.828098] Hardware name: Intel Corporation CannonLake Client
Platform/CannonLake Y LPDDR4 RVP, BIOS CNLSFWR1.R00.X124.B02.1802051422
02/05/2018
[ 281.828110] Workqueue: rb_allocator iwl_pcie_rx_allocator_work [iwlwifi]
[ 281.828112] Call Trace:
[ 281.828118] dump_stack+0x5c/0x85
[ 281.828121] dump_header+0x6b/0x27c
[ 281.828124] oom_kill_process+0x239/0x480
[ 281.828126] ? oom_badness+0xeb/0x160
[ 281.828128] out_of_memory+0x10f/0x480
[ 281.828131] __alloc_pages_slowpath+0xcdd/0xdb0
[ 281.828134] ? __update_load_avg_se.isra.38+0x1c1/0x1d0
[ 281.828136] __alloc_pages_nodemask+0x246/0x280
[ 281.828143] iwl_pcie_rx_alloc_page+0x25/0x90 [iwlwifi]
[ 281.828150] iwl_pcie_rx_allocator_work+0x128/0x370 [iwlwifi]
[ 281.828153] process_one_work+0x147/0x3c0
[ 281.828156] worker_thread+0x4a/0x440
[ 281.828158] kthread+0xf8/0x130
[ 281.828160] ? rescuer_thread+0x360/0x360
[ 281.828162] ? kthread_associate_blkcg+0x90/0x90
[ 281.828164] ret_from_fork+0x35/0x40
[ 281.828166] Mem-Info:
[ 281.828171] active_anon:6176 inactive_anon:1909559 isolated_anon:416
active_file:60 inactive_file:60 isolated_file:0
unevictable:0 dirty:0 writeback:0 unstable:0
slab_reclaimable:9750 slab_unreclaimable:25610
mapped:543 shmem:1910538 pagetables:3095 bounce:0
free:27800 free_pcp:136 free_cma:0
[ 281.828174] Node 0 active_anon:24704kB inactive_anon:7638236kB
active_file:240kB inactive_file:240kB unevictable:0kB isolated(anon):1664kB
isolated(file):0kB mapped:2172kB dirty:0kB writeback:0kB shmem:7642152kB
shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 0kB writeback_tmp:0kB
unstable:0kB all_unreclaimable? yes
[ 281.828175] Node 0 DMA free:15884kB min:132kB low:164kB high:196kB
active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB
unevictable:0kB writepending:0kB present:15992kB managed:15884kB mlocked:0kB
kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB
free_cma:0kB
[ 281.828179] lowmem_reserve[]: 0 747 7758 7758 7758
[ 281.828183] Node 0 DMA32 free:34540kB min:6500kB low:8124kB high:9748kB
active_anon:260kB inactive_anon:767604kB active_file:0kB inactive_file:4kB
unevictable:0kB writepending:0kB present:872780kB managed:807024kB mlocked:=
0kB
kernel_stack:16kB pagetables:4kB bounce:0kB free_pcp:4kB local_pcp:0kB
free_cma:0kB
[ 281.828186] lowmem_reserve[]: 0 0 7010 7010 7010
[ 281.828189] Node 0 Normal free:60776kB min:60944kB low:76180kB high:9141=
6kB
active_anon:24444kB inactive_anon:6870424kB active_file:240kB
inactive_file:236kB unevictable:0kB writepending:0kB present:7335936kB
managed:7182840kB mlocked:0kB kernel_stack:4224kB pagetables:12376kB bounce=
:0kB
free_pcp:540kB local_pcp:0kB free_cma:0kB
[ 281.828194] lowmem_reserve[]: 0 0 0 0 0
[ 281.828196] Node 0 DMA: 1*4kB (U) 1*8kB (U) 2*16kB (U) 1*32kB (U) 1*64kB=
(U)
1*128kB (U) 1*256kB (U) 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) =3D
15884kB
[ 281.828208] Node 0 DMA32: 15*4kB (UM) 22*8kB (UM) 24*16kB (UM) 26*32kB (=
UME)
21*64kB (UME) 16*128kB (UM) 18*256kB (UME) 17*512kB (UME) 4*1024kB (M) 6*20=
48kB
(M) 0*4096kB =3D 34540kB
[ 281.828220] Node 0 Normal: 568*4kB (UME) 299*8kB (UM) 149*16kB (UME)
121*32kB (UM) 187*64kB (UM) 128*128kB (UM) 54*256kB (UME) 3*512kB (UME)
0*1024kB 1*2048kB (E) 1*4096kB (E) =3D 60776kB
[ 281.828235] 1911494 total pagecache pages
[ 281.828238] 836 pages in swap cache
[ 281.828239] Swap cache stats: add 5093657, delete 5092313, find
110160/132281
[ 281.828239] Free swap =3D 7673432kB
[ 281.828240] Total swap =3D 8000508kB
[ 281.828241] 2056177 pages RAM
[ 281.828241] 0 pages HighMem/MovableOnly
[ 281.828242] 54740 pages reserved
[ 281.828242] 0 pages cma reserved
[ 281.828243] 0 pages hwpoisoned
[ 281.828433] Out of memory: Kill process 10614 (gem_shrink) score 999 or
sacrifice child
[ 281.828453] Killed process 10618 (gem_shrink) total-vm:51648kB,
anon-rss:396kB, file-rss:0kB, shmem-rss:0kB
[ 297.168009] 1 and 65536 pages still available in the bound and unbound G=
PU
page lists.
[ 297.168089] irqbalance invoked oom-killer:
gfp_mask=3D0x14200ca(GFP_HIGHUSER_MOVABLE), nodemask=3D(null), order=3D0,
oom_score_adj=3D0
[ 297.168094] CPU: 0 PID: 756 Comm: irqbalance Not tainted
4.16.0-rc6-drm-intel-qa-ww12-commit-4db112a+ #1
[ 297.168095] Hardware name: Intel Corporation CannonLake Client
Platform/CannonLake Y LPDDR4 RVP, BIOS CNLSFWR1.R00.X124.B02.1802051422
02/05/2018
[ 297.168096] Call Trace:
[ 297.168104] dump_stack+0x5c/0x85
[ 297.168108] dump_header+0x6b/0x27c
[ 297.168111] oom_kill_process+0x239/0x480
[ 297.168113] ? oom_badness+0xeb/0x160
[ 297.168115] out_of_memory+0x10f/0x480
[ 297.168117] __alloc_pages_slowpath+0xcdd/0xdb0
[ 297.168121] __alloc_pages_nodemask+0x246/0x280
[ 297.168123] filemap_fault+0x2e9/0x650
[ 297.168125] ? filemap_map_pages+0x188/0x370
[ 297.168128] ext4_filemap_fault+0x2c/0x3b
[ 297.168131] __do_fault+0x1f/0xb6
[ 297.168133] __handle_mm_fault+0xbfc/0x1130
[ 297.168136] handle_mm_fault+0xfa/0x200
[ 297.168139] __do_page_fault+0x257/0x4f0
[ 297.168141] ? page_fault+0x2f/0x50
[ 297.168143] page_fault+0x45/0x50
[ 297.168144] RIP: 0002: (null)
[ 297.168146] RSP: 000a:00007fff6077fd38 EFLAGS: 7fff6077f9bc
[ 297.168147] Mem-Info:
[ 297.168152] active_anon:6162 inactive_anon:1909667 isolated_anon:320
active_file:60 inactive_file:60 isolated_file:0
unevictable:0 dirty:0 writeback:0 unstable:0
slab_reclaimable:9750 slab_unreclaimable:25610
mapped:543 shmem:1910538 pagetables:3068 bounce:0
free:27761 free_pcp:213 free_cma:0
[ 297.168155] Node 0 active_anon:24648kB inactive_anon:7638668kB
active_file:240kB inactive_file:240kB unevictable:0kB isolated(anon):1280kB
isolated(file):0kB mapped:2172kB dirty:0kB writeback:0kB shmem:7642152kB
shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 0kB writeback_tmp:0kB
unstable:0kB all_unreclaimable? yes
[ 297.168156] Node 0 DMA free:15884kB min:132kB low:164kB high:196kB
active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB
unevictable:0kB writepending:0kB present:15992kB managed:15884kB mlocked:0kB
kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB
free_cma:0kB
[ 297.168160] lowmem_reserve[]: 0 747 7758 7758 7758
[ 297.168164] Node 0 DMA32 free:34540kB min:6500kB low:8124kB high:9748kB
active_anon:260kB inactive_anon:767388kB active_file:4kB inactive_file:0kB
unevictable:0kB writepending:0kB present:872780kB managed:807024kB mlocked:=
0kB
kernel_stack:16kB pagetables:4kB bounce:0kB free_pcp:4kB local_pcp:0kB
free_cma:0kB
[ 297.168167] lowmem_reserve[]: 0 0 7010 7010 7010
[ 297.168170] Node 0 Normal free:60620kB min:60944kB low:76180kB high:9141=
6kB
active_anon:24388kB inactive_anon:6871068kB active_file:236kB
inactive_file:240kB unevictable:0kB writepending:0kB present:7335936kB
managed:7182840kB mlocked:0kB kernel_stack:4224kB pagetables:12268kB bounce=
:0kB
free_pcp:848kB local_pcp:308kB free_cma:0kB
[ 297.168174] lowmem_reserve[]: 0 0 0 0 0
[ 297.168177] Node 0 DMA: 1*4kB (U) 1*8kB (U) 2*16kB (U) 1*32kB (U) 1*64kB=
(U)
1*128kB (U) 1*256kB (U) 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) =3D
15884kB
[ 297.168189] Node 0 DMA32: 15*4kB (UM) 22*8kB (UM) 24*16kB (UM) 26*32kB (=
UME)
21*64kB (UME) 16*128kB (UM) 18*256kB (UME) 17*512kB (UME) 4*1024kB (M) 6*20=
48kB
(M) 0*4096kB =3D 34540kB
[ 297.168202] Node 0 Normal: 553*4kB (ME) 293*8kB (M) 148*16kB (UME) 120*3=
2kB
(UM) 187*64kB (UM) 128*128kB (UM) 54*256kB (UME) 3*512kB (UME) 0*1024kB
1*2048kB (E) 1*4096kB (E) =3D 60620kB
[ 297.168217] 1911494 total pagecache pages
[ 297.168219] 836 pages in swap cache
[ 297.168220] Swap cache stats: add 5093657, delete 5092313, find
110160/132281
[ 297.168221] Free swap =3D 7673432kB
[ 297.168222] Total swap =3D 8000508kB
[ 297.168222] 2056177 pages RAM
[ 297.168223] 0 pages HighMem/MovableOnly
[ 297.168223] 54740 pages reserved
[ 297.168224] 0 pages cma reserved
[ 297.168224] 0 pages hwpoisoned
[ 297.168409] Out of memory: Kill process 10614 (gem_shrink) score 999 or
sacrifice child
[ 297.168429] Killed process 10619 (gem_shrink) total-vm:51648kB,
anon-rss:396kB, file-rss:0kB, shmem-rss:0kB
[ 305.312012] 1 and 98304 pages still available in the bound and unbound G=
PU
page lists.
[ 305.312092] kworker/u9:2 invoked oom-killer:
gfp_mask=3D0x14002c0(GFP_KERNEL|__GFP_NOWARN), nodemask=3D(null), order=3D0,
oom_score_adj=3D0
[ 305.312098] CPU: 3 PID: 348 Comm: kworker/u9:2 Not tainted
4.16.0-rc6-drm-intel-qa-ww12-commit-4db112a+ #1
[ 305.312099] Hardware name: Intel Corporation CannonLake Client
Platform/CannonLake Y LPDDR4 RVP, BIOS CNLSFWR1.R00.X124.B02.1802051422
02/05/2018
[ 305.312111] Workqueue: rb_allocator iwl_pcie_rx_allocator_work [iwlwifi]
[ 305.312112] Call Trace:
[ 305.312120] dump_stack+0x5c/0x85
[ 305.312123] dump_header+0x6b/0x27c
[ 305.312126] oom_kill_process+0x239/0x480
[ 305.312128] ? oom_badness+0xeb/0x160
[ 305.312130] out_of_memory+0x10f/0x480
[ 305.312132] __alloc_pages_slowpath+0xcdd/0xdb0
[ 305.312135] ? __update_load_avg_se.isra.38+0x1c1/0x1d0
[ 305.312138] __alloc_pages_nodemask+0x246/0x280
[ 305.312145] iwl_pcie_rx_alloc_page+0x25/0x90 [iwlwifi]
[ 305.312151] iwl_pcie_rx_allocator_work+0x128/0x370 [iwlwifi]
[ 305.312155] process_one_work+0x147/0x3c0
[ 305.312157] worker_thread+0x4a/0x440
[ 305.312160] kthread+0xf8/0x130
[ 305.312162] ? rescuer_thread+0x360/0x360
[ 305.312164] ? kthread_associate_blkcg+0x90/0x90
[ 305.312166] ret_from_fork+0x35/0x40
[ 305.312168] Mem-Info:
[ 305.312173] active_anon:6148 inactive_anon:1909356 isolated_anon:608
active_file:60 inactive_file:60 isolated_file:0
unevictable:0 dirty:0 writeback:0 unstable:0
slab_reclaimable:9750 slab_unreclaimable:25610
mapped:543 shmem:1910538 pagetables:3041 bounce:0
free:27761 free_pcp:259 free_cma:0
[ 305.312177] Node 0 active_anon:24592kB inactive_anon:7637424kB
active_file:240kB inactive_file:240kB unevictable:0kB isolated(anon):2432kB
isolated(file):0kB mapped:2172kB dirty:0kB writeback:0kB shmem:7642152kB
shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 0kB writeback_tmp:0kB
unstable:0kB all_unreclaimable? yes
[ 305.312178] Node 0 DMA free:15884kB min:132kB low:164kB high:196kB
active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB
unevictable:0kB writepending:0kB present:15992kB managed:15884kB mlocked:0kB
kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB
free_cma:0kB
[ 305.312182] lowmem_reserve[]: 0 747 7758 7758 7758
[ 305.312185] Node 0 DMA32 free:34540kB min:6500kB low:8124kB high:9748kB
active_anon:260kB inactive_anon:767264kB active_file:4kB inactive_file:0kB
unevictable:0kB writepending:0kB present:872780kB managed:807024kB mlocked:=
0kB
kernel_stack:16kB pagetables:4kB bounce:0kB free_pcp:4kB local_pcp:0kB
free_cma:0kB
[ 305.312189] lowmem_reserve[]: 0 0 7010 7010 7010
[ 305.312192] Node 0 Normal free:60620kB min:60944kB low:76180kB high:9141=
6kB
active_anon:24332kB inactive_anon:6869952kB active_file:236kB
inactive_file:240kB unevictable:0kB writepending:0kB present:7335936kB
managed:7182840kB mlocked:0kB kernel_stack:4224kB pagetables:12160kB bounce=
:0kB
free_pcp:1032kB local_pcp:0kB free_cma:0kB
[ 305.312196] lowmem_reserve[]: 0 0 0 0 0
[ 305.312199] Node 0 DMA: 1*4kB (U) 1*8kB (U) 2*16kB (U) 1*32kB (U) 1*64kB=
(U)
1*128kB (U) 1*256kB (U) 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) =3D
15884kB
[ 305.312211] Node 0 DMA32: 15*4kB (UM) 22*8kB (UM) 24*16kB (UM) 26*32kB (=
UME)
21*64kB (UME) 16*128kB (UM) 18*256kB (UME) 17*512kB (UME) 4*1024kB (M) 6*20=
48kB
(M) 0*4096kB =3D 34540kB
[ 305.312223] Node 0 Normal: 553*4kB (ME) 293*8kB (M) 148*16kB (UME) 120*3=
2kB
(UM) 187*64kB (UM) 128*128kB (UM) 54*256kB (UME) 3*512kB (UME) 0*1024kB
1*2048kB (E) 1*4096kB (E) =3D 60620kB
[ 305.312238] 1911494 total pagecache pages
[ 305.312240] 836 pages in swap cache
[ 305.312241] Swap cache stats: add 5093657, delete 5092313, find
110160/132281
[ 305.312242] Free swap =3D 7673432kB
[ 305.312242] Total swap =3D 8000508kB
[ 305.312243] 2056177 pages RAM
[ 305.312243] 0 pages HighMem/MovableOnly
[ 305.312244] 54740 pages reserved
[ 305.312244] 0 pages cma reserved
[ 305.312245] 0 pages hwpoisoned
[ 305.312428] Out of memory: Kill process 10614 (gem_shrink) score 999 or
sacrifice child
[ 305.312446] Killed process 10620 (gem_shrink) total-vm:51648kB,
anon-rss:396kB, file-rss:0kB, shmem-rss:0kB
[ 306.956010] 1 and 131072 pages still available in the bound and unbound =
GPU
page lists.
[ 306.956090] wpa_supplicant invoked oom-killer:
gfp_mask=3D0x14200ca(GFP_HIGHUSER_MOVABLE), nodemask=3D(null), order=3D0,
oom_score_adj=3D0
[ 306.956096] CPU: 0 PID: 874 Comm: wpa_supplicant Not tainted
4.16.0-rc6-drm-intel-qa-ww12-commit-4db112a+ #1
[ 306.956097] Hardware name: Intel Corporation CannonLake Client
Platform/CannonLake Y LPDDR4 RVP, BIOS CNLSFWR1.R00.X124.B02.1802051422
02/05/2018
[ 306.956099] Call Trace:
[ 306.956106] dump_stack+0x5c/0x85
[ 306.956110] dump_header+0x6b/0x27c
[ 306.956112] oom_kill_process+0x239/0x480
[ 306.956115] ? oom_badness+0xeb/0x160
[ 306.956117] out_of_memory+0x10f/0x480
[ 306.956119] __alloc_pages_slowpath+0xcdd/0xdb0
[ 306.956124] ? compat_poll_select_copy_remaining+0x140/0x140
[ 306.956127] __alloc_pages_nodemask+0x246/0x280
[ 306.956129] filemap_fault+0x2e9/0x650
[ 306.956131] ? filemap_map_pages+0x188/0x370
[ 306.956134] ext4_filemap_fault+0x2c/0x3b
[ 306.956137] __do_fault+0x1f/0xb6
[ 306.956139] __handle_mm_fault+0xbfc/0x1130
[ 306.956142] handle_mm_fault+0xfa/0x200
[ 306.956145] __do_page_fault+0x257/0x4f0
[ 306.956147] ? page_fault+0x2f/0x50
[ 306.956149] page_fault+0x45/0x50
[ 306.956152] RIP: e91f96e0:0x5611e91f9800
[ 306.956153] RSP: 0000:00005611e921c740 EFLAGS: 5611e91f9770
[ 306.956155] Mem-Info:
[ 306.956160] active_anon:6134 inactive_anon:1909452 isolated_anon:480
active_file:45 inactive_file:75 isolated_file:0
unevictable:0 dirty:0 writeback:0 unstable:0
slab_reclaimable:9750 slab_unreclaimable:25610
mapped:543 shmem:1910538 pagetables:3014 bounce:0
free:27730 free_pcp:336 free_cma:0
[ 306.956164] Node 0 active_anon:24536kB inactive_anon:7637808kB
active_file:180kB inactive_file:300kB unevictable:0kB isolated(anon):1920kB
isolated(file):0kB mapped:2172kB dirty:0kB writeback:0kB shmem:7642152kB
shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 0kB writeback_tmp:0kB
unstable:0kB all_unreclaimable? yes
[ 306.956165] Node 0 DMA free:15884kB min:132kB low:164kB high:196kB
active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB
unevictable:0kB writepending:0kB present:15992kB managed:15884kB mlocked:0kB
kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB
free_cma:0kB
[ 306.956169] lowmem_reserve[]: 0 747 7758 7758 7758
[ 306.956173] Node 0 DMA32 free:34540kB min:6500kB low:8124kB high:9748kB
active_anon:260kB inactive_anon:767592kB active_file:0kB inactive_file:4kB
unevictable:0kB writepending:0kB present:872780kB managed:807024kB mlocked:=
0kB
kernel_stack:16kB pagetables:4kB bounce:0kB free_pcp:4kB local_pcp:0kB
free_cma:0kB
[ 306.956177] lowmem_reserve[]: 0 0 7010 7010 7010
[ 306.956180] Node 0 Normal free:60496kB min:60944kB low:76180kB high:9141=
6kB
active_anon:24276kB inactive_anon:6870252kB active_file:180kB
inactive_file:296kB unevictable:0kB writepending:0kB present:7335936kB
managed:7182840kB mlocked:0kB kernel_stack:4224kB pagetables:12052kB bounce=
:0kB
free_pcp:1340kB local_pcp:308kB free_cma:0kB
[ 306.956184] lowmem_reserve[]: 0 0 0 0 0
[ 306.956187] Node 0 DMA: 1*4kB (U) 1*8kB (U) 2*16kB (U) 1*32kB (U) 1*64kB=
(U)
1*128kB (U) 1*256kB (U) 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) =3D
15884kB
[ 306.956199] Node 0 DMA32: 15*4kB (UM) 22*8kB (UM) 24*16kB (UM) 26*32kB (=
UME)
21*64kB (UME) 16*128kB (UM) 18*256kB (UME) 17*512kB (UME) 4*1024kB (M) 6*20=
48kB
(M) 0*4096kB =3D 34540kB
[ 306.956212] Node 0 Normal: 554*4kB (UME) 293*8kB (M) 140*16kB (UME) 120*=
32kB
(UM) 187*64kB (UM) 128*128kB (UM) 54*256kB (UME) 3*512kB (UME) 0*1024kB
1*2048kB (E) 1*4096kB (E) =3D 60496kB
[ 306.956228] 1911494 total pagecache pages
[ 306.956230] 836 pages in swap cache
[ 306.956231] Swap cache stats: add 5093657, delete 5092313, find
110160/132283
[ 306.956232] Free swap =3D 7673432kB
[ 306.956233] Total swap =3D 8000508kB
[ 306.956233] 2056177 pages RAM
[ 306.956234] 0 pages HighMem/MovableOnly
[ 306.956234] 54740 pages reserved
[ 306.956235] 0 pages cma reserved
[ 306.956236] 0 pages hwpoisoned
[ 306.956424] Out of memory: Kill process 10614 (gem_shrink) score 999 or
sacrifice child
[ 306.956445] Killed process 10621 (gem_shrink) total-vm:51648kB,
anon-rss:396kB, file-rss:0kB, shmem-rss:0kB
[ 313.404009] 1 and 163840 pages still available in the bound and unbound =
GPU
page lists.
[ 316.388007] 1 and 163840 pages still available in the bound and unbound =
GPU
page lists.
[ 316.388084] systemd-timesyn invoked oom-killer:
gfp_mask=3D0x14200ca(GFP_HIGHUSER_MOVABLE), nodemask=3D(null), order=3D0,
oom_score_adj=3D0
[ 316.388089] CPU: 1 PID: 568 Comm: systemd-timesyn Not tainted
4.16.0-rc6-drm-intel-qa-ww12-commit-4db112a+ #1
[ 316.388090] Hardware name: Intel Corporation CannonLake Client
Platform/CannonLake Y LPDDR4 RVP, BIOS CNLSFWR1.R00.X124.B02.1802051422
02/05/2018
[ 316.388091] Call Trace:
[ 316.388098] dump_stack+0x5c/0x85
[ 316.388102] dump_header+0x6b/0x27c
[ 316.388104] oom_kill_process+0x239/0x480
[ 316.388106] ? oom_badness+0xeb/0x160
[ 316.388107] out_of_memory+0x10f/0x480
[ 316.388110] __alloc_pages_slowpath+0xcdd/0xdb0
[ 316.388113] __alloc_pages_nodemask+0x246/0x280
[ 316.388115] filemap_fault+0x2e9/0x650
[ 316.388117] ? filemap_map_pages+0x266/0x370
[ 316.388119] ext4_filemap_fault+0x2c/0x3b
[ 316.388122] __do_fault+0x1f/0xb6
[ 316.388124] __handle_mm_fault+0xbfc/0x1130
[ 316.388127] handle_mm_fault+0xfa/0x200
[ 316.388129] __do_page_fault+0x257/0x4f0
[ 316.388131] ? page_fault+0x2f/0x50
[ 316.388133] page_fault+0x45/0x50
[ 316.388134] RIP: ffffffff: (null)
[ 316.388135] RSP: 0004:0000000000000000 EFLAGS: 00000008
[ 316.388136] Mem-Info:
[ 316.388141] active_anon:6120 inactive_anon:1909431 isolated_anon:544
active_file:60 inactive_file:60 isolated_file:0
unevictable:0 dirty:0 writeback:0 unstable:0
slab_reclaimable:9750 slab_unreclaimable:25610
mapped:543 shmem:1910538 pagetables:2987 bounce:0
free:27722 free_pcp:390 free_cma:0
[ 316.388144] Node 0 active_anon:24480kB inactive_anon:7637724kB
active_file:240kB inactive_file:240kB unevictable:0kB isolated(anon):2176kB
isolated(file):0kB mapped:2172kB dirty:0kB writeback:0kB shmem:7642152kB
shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 0kB writeback_tmp:0kB
unstable:0kB all_unreclaimable? no
[ 316.388145] Node 0 DMA free:15884kB min:132kB low:164kB high:196kB
active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB
unevictable:0kB writepending:0kB present:15992kB managed:15884kB mlocked:0kB
kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB
free_cma:0kB
[ 316.388148] lowmem_reserve[]: 0 747 7758 7758 7758
[ 316.388151] Node 0 DMA32 free:34540kB min:6500kB low:8124kB high:9748kB
active_anon:260kB inactive_anon:767516kB active_file:4kB inactive_file:0kB
unevictable:0kB writepending:0kB present:872780kB managed:807024kB mlocked:=
0kB
kernel_stack:16kB pagetables:4kB bounce:0kB free_pcp:4kB local_pcp:0kB
free_cma:0kB
[ 316.388155] lowmem_reserve[]: 0 0 7010 7010 7010
[ 316.388157] Node 0 Normal free:60464kB min:60944kB low:76180kB high:9141=
6kB
active_anon:24220kB inactive_anon:6870224kB active_file:236kB
inactive_file:240kB unevictable:0kB writepending:0kB present:7335936kB
managed:7182840kB mlocked:0kB kernel_stack:4224kB pagetables:11944kB bounce=
:0kB
free_pcp:1556kB local_pcp:308kB free_cma:0kB
[ 316.388161] lowmem_reserve[]: 0 0 0 0 0
[ 316.388163] Node 0 DMA: 1*4kB (U) 1*8kB (U) 2*16kB (U) 1*32kB (U) 1*64kB=
(U)
1*128kB (U) 1*256kB (U) 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) =3D
15884kB
[ 316.388174] Node 0 DMA32: 15*4kB (UM) 22*8kB (UM) 24*16kB (UM) 26*32kB (=
UME)
21*64kB (UME) 16*128kB (UM) 18*256kB (UME) 17*512kB (UME) 4*1024kB (M) 6*20=
48kB
(M) 0*4096kB =3D 34540kB
[ 316.388184] Node 0 Normal: 554*4kB (UME) 293*8kB (M) 140*16kB (UME) 119*=
32kB
(UM) 187*64kB (UM) 128*128kB (UM) 54*256kB (UME) 3*512kB (UME) 0*1024kB
1*2048kB (E) 1*4096kB (E) =3D 60464kB
[ 316.388197] 1911494 total pagecache pages
[ 316.388199] 836 pages in swap cache
[ 316.388200] Swap cache stats: add 5093657, delete 5092313, find
110160/132283
[ 316.388200] Free swap =3D 7673432kB
[ 316.388201] Total swap =3D 8000508kB
[ 316.388201] 2056177 pages RAM
[ 316.388202] 0 pages HighMem/MovableOnly
[ 316.388202] 54740 pages reserved
[ 316.388203] 0 pages cma reserved
[ 316.388203] 0 pages hwpoisoned
[ 316.388362] Out of memory: Kill process 10614 (gem_shrink) score 999 or
sacrifice child
[ 316.388379] Killed process 10622 (gem_shrink) total-vm:51648kB,
anon-rss:396kB, file-rss:0kB, shmem-rss:0kB
[ 317.712003] 1 and 196608 pages still available in the bound and unbound =
GPU
page lists.
[ 317.712079] cron invoked oom-killer:
gfp_mask=3D0x14200ca(GFP_HIGHUSER_MOVABLE), nodemask=3D(null), order=3D0,
oom_score_adj=3D0
[ 317.712084] CPU: 1 PID: 681 Comm: cron Not tainted
4.16.0-rc6-drm-intel-qa-ww12-commit-4db112a+ #1
[ 317.712085] Hardware name: Intel Corporation CannonLake Client
Platform/CannonLake Y LPDDR4 RVP, BIOS CNLSFWR1.R00.X124.B02.1802051422
02/05/2018
[ 317.712086] Call Trace:
[ 317.712093] dump_stack+0x5c/0x85
[ 317.712097] dump_header+0x6b/0x27c
[ 317.712099] oom_kill_process+0x239/0x480
[ 317.712101] ? oom_badness+0xeb/0x160
[ 317.712103] out_of_memory+0x10f/0x480
[ 317.712106] __alloc_pages_slowpath+0xcdd/0xdb0
[ 317.712109] ? _cond_resched+0x16/0x40
[ 317.712112] __alloc_pages_nodemask+0x246/0x280
[ 317.712114] filemap_fault+0x2e9/0x650
[ 317.712116] ? filemap_map_pages+0x188/0x370
[ 317.712119] ext4_filemap_fault+0x2c/0x3b
[ 317.712122] __do_fault+0x1f/0xb6
[ 317.712125] __handle_mm_fault+0xbfc/0x1130
[ 317.712127] handle_mm_fault+0xfa/0x200
[ 317.712130] __do_page_fault+0x257/0x4f0
[ 317.712132] ? page_fault+0x2f/0x50
[ 317.712134] page_fault+0x45/0x50
[ 317.712135] RIP: f7ddd4e0: (null)
[ 317.712137] RSP: fffffe98:0000000000000004 EFLAGS: 00000000
[ 317.712138] Mem-Info:
[ 317.712143] active_anon:6106 inactive_anon:1909592 isolated_anon:416
active_file:60 inactive_file:60 isolated_file:0
unevictable:0 dirty:0 writeback:0 unstable:0
slab_reclaimable:9750 slab_unreclaimable:25610
mapped:543 shmem:1910538 pagetables:2960 bounce:0
free:27753 free_pcp:413 free_cma:0
[ 317.712147] Node 0 active_anon:24424kB inactive_anon:7638368kB
active_file:240kB inactive_file:240kB unevictable:0kB isolated(anon):1664kB
isolated(file):0kB mapped:2172kB dirty:0kB writeback:0kB shmem:7642152kB
shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 0kB writeback_tmp:0kB
unstable:0kB all_unreclaimable? no
[ 317.712148] Node 0 DMA free:15884kB min:132kB low:164kB high:196kB
active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB
unevictable:0kB writepending:0kB present:15992kB managed:15884kB mlocked:0kB
kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB
free_cma:0kB
[ 317.712152] lowmem_reserve[]: 0 747 7758 7758 7758
[ 317.712155] Node 0 DMA32 free:34540kB min:6500kB low:8124kB high:9748kB
active_anon:260kB inactive_anon:767544kB active_file:0kB inactive_file:4kB
unevictable:0kB writepending:0kB present:872780kB managed:807024kB mlocked:=
0kB
kernel_stack:16kB pagetables:4kB bounce:0kB free_pcp:4kB local_pcp:0kB
free_cma:0kB
[ 317.712159] lowmem_reserve[]: 0 0 7010 7010 7010
[ 317.712162] Node 0 Normal free:60588kB min:60944kB low:76180kB high:9141=
6kB
active_anon:24164kB inactive_anon:6870428kB active_file:240kB
inactive_file:236kB unevictable:0kB writepending:0kB present:7335936kB
managed:7182840kB mlocked:0kB kernel_stack:4112kB pagetables:11836kB bounce=
:0kB
free_pcp:1648kB local_pcp:524kB free_cma:0kB
[ 317.712166] lowmem_reserve[]: 0 0 0 0 0
[ 317.712168] Node 0 DMA: 1*4kB (U) 1*8kB (U) 2*16kB (U) 1*32kB (U) 1*64kB=
(U)
1*128kB (U) 1*256kB (U) 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) =3D
15884kB
[ 317.712180] Node 0 DMA32: 15*4kB (UM) 22*8kB (UM) 24*16kB (UM) 26*32kB (=
UME)
21*64kB (UME) 16*128kB (UM) 18*256kB (UME) 17*512kB (UME) 4*1024kB (M) 6*20=
48kB
(M) 0*4096kB =3D 34540kB
[ 317.712192] Node 0 Normal: 561*4kB (UME) 297*8kB (UME) 142*16kB (UME)
120*32kB (UME) 187*64kB (UM) 128*128kB (UM) 54*256kB (UME) 3*512kB (UME)
0*1024kB 1*2048kB (E) 1*4096kB (E) =3D 60588kB
[ 317.712207] 1911494 total pagecache pages
[ 317.712209] 836 pages in swap cache
[ 317.712211] Swap cache stats: add 5093657, delete 5092313, find
110160/132283
[ 317.712211] Free swap =3D 7673432kB
[ 317.712212] Total swap =3D 8000508kB
[ 317.712213] 2056177 pages RAM
[ 317.712213] 0 pages HighMem/MovableOnly
[ 317.712214] 54740 pages reserved
[ 317.712214] 0 pages cma reserved
[ 317.712215] 0 pages hwpoisoned
[ 317.712394] Out of memory: Kill process 10614 (gem_shrink) score 999 or
sacrifice child
[ 317.712411] Killed process 10623 (gem_shrink) total-vm:51648kB,
anon-rss:396kB, file-rss:0kB, shmem-rss:0kB
[ 343.932002] 1 and 196608 pages still available in the bound and unbound =
GPU
page lists.
[ 343.932078] gmain invoked oom-killer:
gfp_mask=3D0x14200ca(GFP_HIGHUSER_MOVABLE), nodemask=3D(null), order=3D0,
oom_score_adj=3D0
[ 343.932083] CPU: 1 PID: 805 Comm: gmain Not tainted
4.16.0-rc6-drm-intel-qa-ww12-commit-4db112a+ #1
[ 343.932084] Hardware name: Intel Corporation CannonLake Client
Platform/CannonLake Y LPDDR4 RVP, BIOS CNLSFWR1.R00.X124.B02.1802051422
02/05/2018
[ 343.932085] Call Trace:
[ 343.932091] dump_stack+0x5c/0x85
[ 343.932095] dump_header+0x6b/0x27c
[ 343.932097] oom_kill_process+0x239/0x480
[ 343.932099] ? oom_badness+0xeb/0x160
[ 343.932100] out_of_memory+0x10f/0x480
[ 343.932103] __alloc_pages_slowpath+0xcdd/0xdb0
[ 343.932106] __alloc_pages_nodemask+0x246/0x280
[ 343.932108] filemap_fault+0x2e9/0x650
[ 343.932110] ? filemap_map_pages+0x188/0x370
[ 343.932113] ext4_filemap_fault+0x2c/0x3b
[ 343.932115] __do_fault+0x1f/0xb6
[ 343.932117] __handle_mm_fault+0xbfc/0x1130
[ 343.932120] handle_mm_fault+0xfa/0x200
[ 343.932122] __do_page_fault+0x257/0x4f0
[ 343.932126] ? SyS_poll+0x6b/0x120
[ 343.932128] ? page_fault+0x2f/0x50
[ 343.932129] page_fault+0x45/0x50
[ 343.932132] RIP: 90873960:0x55a39089ddf0
[ 343.932133] RSP: 908fef20:000055a3908fef20 EFLAGS: 00000000
[ 343.932134] Mem-Info:
[ 343.932139] active_anon:6099 inactive_anon:1918269 isolated_anon:608
active_file:60 inactive_file:60 isolated_file:0
unevictable:0 dirty:4 writeback:0 unstable:0
slab_reclaimable:9750 slab_unreclaimable:25610
mapped:543 shmem:1919408 pagetables:2960 bounce:0
free:19290 free_pcp:29 free_cma:0
[ 343.932142] Node 0 active_anon:24396kB inactive_anon:7673076kB
active_file:240kB inactive_file:240kB unevictable:0kB isolated(anon):2432kB
isolated(file):0kB mapped:2172kB dirty:16kB writeback:0kB shmem:7677632kB
shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 0kB writeback_tmp:0kB
unstable:0kB all_unreclaimable? no
[ 343.932143] Node 0 DMA free:15884kB min:132kB low:164kB high:196kB
active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB
unevictable:0kB writepending:0kB present:15992kB managed:15884kB mlocked:0kB
kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB
free_cma:0kB
[ 343.932146] lowmem_reserve[]: 0 747 7758 7758 7758
[ 343.932149] Node 0 DMA32 free:31164kB min:6500kB low:8124kB high:9748kB
active_anon:288kB inactive_anon:770732kB active_file:0kB inactive_file:4kB
unevictable:0kB writepending:0kB present:872780kB managed:807024kB mlocked:=
0kB
kernel_stack:16kB pagetables:4kB bounce:0kB free_pcp:0kB local_pcp:0kB
free_cma:0kB
[ 343.932152] lowmem_reserve[]: 0 0 7010 7010 7010
[ 343.932155] Node 0 Normal free:30112kB min:60944kB low:76180kB high:9141=
6kB
active_anon:24108kB inactive_anon:6902044kB active_file:240kB
inactive_file:236kB unevictable:0kB writepending:16kB present:7335936kB
managed:7182840kB mlocked:0kB kernel_stack:4144kB pagetables:11836kB bounce=
:0kB
free_pcp:116kB local_pcp:0kB free_cma:0kB
[ 343.932158] lowmem_reserve[]: 0 0 0 0 0
[ 343.932161] Node 0 DMA: 1*4kB (U) 1*8kB (U) 2*16kB (U) 1*32kB (U) 1*64kB=
(U)
1*128kB (U) 1*256kB (U) 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) =3D
15884kB
[ 343.932171] Node 0 DMA32: 19*4kB (UME) 24*8kB (UME) 21*16kB (UM) 25*32kB
(UM) 21*64kB (UME) 16*128kB (UM) 17*256kB (UM) 17*512kB (UME) 5*1024kB (ME)
4*2048kB (M) 0*4096kB =3D 31164kB
[ 343.932182] Node 0 Normal: 370*4kB (UME) 197*8kB (UM) 108*16kB (UM) 80*3=
2kB
(UME) 110*64kB (UME) 70*128kB (ME) 26*256kB (ME) 0*512kB 0*1024kB 0*2048kB
0*4096kB =3D 30000kB
[ 343.932194] 1920367 total pagecache pages
[ 343.932196] 839 pages in swap cache
[ 343.932197] Swap cache stats: add 5093675, delete 5092328, find
110167/132300
[ 343.932197] Free swap =3D 7673432kB
[ 343.932198] Total swap =3D 8000508kB
[ 343.932198] 2056177 pages RAM
[ 343.932199] 0 pages HighMem/MovableOnly
[ 343.932199] 54740 pages reserved
[ 343.932200] 0 pages cma reserved
[ 343.932200] 0 pages hwpoisoned
[ 343.932354] Out of memory: Kill process 10614 (gem_shrink) score 999 or
sacrifice child
[ 343.932371] Killed process 10624 (gem_shrink) total-vm:51648kB,
anon-rss:396kB, file-rss:0kB, shmem-rss:0kB
[ 363.488021] INFO: task python:855 blocked for more than 120 seconds.
[ 363.488027] Not tainted 4.16.0-rc6-drm-intel-qa-ww12-commit-4db112=
a+
#1
[ 363.488030] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs&qu=
ot; disables this
message.
[ 363.488036] Call Trace:
[ 363.488045] ? __schedule+0x3d5/0x8c0
[ 363.488047] schedule+0x32/0x80
[ 363.488050] schedule_preempt_disabled+0xa/0x10
[ 363.488052] __mutex_lock.isra.2+0x2ae/0x4e0
[ 363.488086] ? i915_gem_shrink+0x3ff/0x500 [i915]
[ 363.488115] i915_gem_shrink+0x3ff/0x500 [i915]
[ 363.488120] ? wake_up_q+0x2d/0x60
[ 363.488149] ? i915_gem_shrinker_scan+0xb6/0x110 [i915]
[ 363.488177] i915_gem_shrinker_scan+0xb6/0x110 [i915]
[ 363.488181] shrink_slab.part.48+0x1c5/0x3f0
[ 363.488184] shrink_node+0x338/0x350
[ 363.488186] do_try_to_free_pages+0xeb/0x360
[ 363.488189] try_to_free_pages+0xf7/0x1d0
[ 363.488192] __alloc_pages_slowpath+0x3ad/0xdb0
[ 363.488194] ? pagecache_get_page+0x185/0x220
[ 363.488197] ? _cond_resched+0x16/0x40
[ 363.488199] ? dput+0x2f/0x1f0
[ 363.488202] __alloc_pages_nodemask+0x246/0x280
[ 363.488205] __do_page_cache_readahead+0x110/0x290
[ 363.488208] ? terminate_walk+0xe4/0x100
[ 363.488210] ? path_openat+0x3ce/0x1380
[ 363.488212] ? ondemand_readahead+0x171/0x2b0
[ 363.488214] ondemand_readahead+0x171/0x2b0
[ 363.488216] ? pagecache_get_page+0x27/0x220
[ 363.488218] generic_file_read_iter+0x71b/0x9b0
[ 363.488221] ? _copy_to_user+0x22/0x30
[ 363.488223] ? cp_new_stat+0x154/0x190
[ 363.488226] __vfs_read+0xfd/0x170
[ 363.488228] vfs_read+0x8e/0x130
[ 363.488230] SyS_read+0x52/0xc0
[ 363.488233] do_syscall_64+0x6e/0x120
[ 363.488235] entry_SYSCALL_64_after_hwframe+0x3d/0xa2
[ 363.488238] RIP: 0033:0x7fe763b09021
[ 363.488239] RSP: 002b:00007ffca6345258 EFLAGS: 00000246 ORIG_RAX:
0000000000000000
[ 363.488241] RAX: ffffffffffffffda RBX: 0000559f1ec4ada0 RCX:
00007fe763b09021
[ 363.488242] RDX: 0000000000001000 RSI: 0000559f1eca8260 RDI:
0000000000000007
[ 363.488243] RBP: 0000000000000d68 R08: 0000000000000000 R09:
0000559f1eca8260
[ 363.488244] R10: ffffffffffffffb0 R11: 0000000000000246 R12:
00007fe763ddc3e0
[ 363.488245] R13: 00007fe763ddb8a0 R14: 0000559f1ec9f174 R15:
00007fe763ddb8a0
[ 383.796008] 1 and 229376 pages still available in the bound and unbound =
GPU
page lists.
[ 383.796084] bash invoked oom-killer:
gfp_mask=3D0x15080c0(GFP_KERNEL_ACCOUNT|__GFP_ZERO), nodemask=3D(null), ord=
er=3D0,
oom_score_adj=3D0
[ 383.796090] CPU: 3 PID: 10741 Comm: bash Not tainted
4.16.0-rc6-drm-intel-qa-ww12-commit-4db112a+ #1
[ 383.796091] Hardware name: Intel Corporation CannonLake Client
Platform/CannonLake Y LPDDR4 RVP, BIOS CNLSFWR1.R00.X124.B02.1802051422
02/05/2018
[ 383.796092] Call Trace:
[ 383.796099] dump_stack+0x5c/0x85
[ 383.796102] dump_header+0x6b/0x27c
[ 383.796105] oom_kill_process+0x239/0x480
[ 383.796107] ? oom_badness+0xeb/0x160
[ 383.796109] out_of_memory+0x10f/0x480
[ 383.796112] __alloc_pages_slowpath+0xcdd/0xdb0
[ 383.796115] __alloc_pages_nodemask+0x246/0x280
[ 383.796118] pte_alloc_one+0x13/0x40
[ 383.796121] __handle_mm_fault+0xb2a/0x1130
[ 383.796124] handle_mm_fault+0xfa/0x200
[ 383.796126] __do_page_fault+0x257/0x4f0
[ 383.796128] ? page_fault+0x2f/0x50
[ 383.796130] page_fault+0x45/0x50
[ 383.796132] RIP: 4cd15e:0x2384801
[ 383.796134] RSP: 0001:00007ffd46762d80 EFLAGS: 7ffd46762e00
[ 383.796135] Mem-Info:
[ 383.796140] active_anon:6085 inactive_anon:1918280 isolated_anon:544
active_file:61 inactive_file:57 isolated_file:0
unevictable:0 dirty:0 writeback:0 unstable:0
slab_reclaimable:9750 slab_unreclaimable:25610
mapped:543 shmem:1919408 pagetables:2933 bounce:0
free:19312 free_pcp:20 free_cma:0
[ 383.796144] Node 0 active_anon:24340kB inactive_anon:7673120kB
active_file:244kB inactive_file:228kB unevictable:0kB isolated(anon):2176kB
isolated(file):0kB mapped:2172kB dirty:0kB writeback:0kB shmem:7677632kB
shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 0kB writeback_tmp:0kB
unstable:0kB all_unreclaimable? no
[ 383.796145] Node 0 DMA free:15884kB min:132kB low:164kB high:196kB
active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB
unevictable:0kB writepending:0kB present:15992kB managed:15884kB mlocked:0kB
kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB
free_cma:0kB
[ 383.796149] lowmem_reserve[]: 0 747 7758 7758 7758
[ 383.796152] Node 0 DMA32 free:31164kB min:6500kB low:8124kB high:9748kB
active_anon:288kB inactive_anon:770796kB active_file:4kB inactive_file:0kB
unevictable:0kB writepending:0kB present:872780kB managed:807024kB mlocked:=
0kB
kernel_stack:16kB pagetables:4kB bounce:0kB free_pcp:0kB local_pcp:0kB
free_cma:0kB
[ 383.796156] lowmem_reserve[]: 0 0 7010 7010 7010
[ 383.796159] Node 0 Normal free:30200kB min:60944kB low:76180kB high:9141=
6kB
active_anon:24052kB inactive_anon:6902332kB active_file:240kB
inactive_file:228kB unevictable:0kB writepending:0kB present:7335936kB
managed:7182840kB mlocked:0kB kernel_stack:4144kB pagetables:11728kB bounce=
:0kB
free_pcp:80kB local_pcp:0kB free_cma:0kB
[ 383.796162] lowmem_reserve[]: 0 0 0 0 0
[ 383.796165] Node 0 DMA: 1*4kB (U) 1*8kB (U) 2*16kB (U) 1*32kB (U) 1*64kB=
(U)
1*128kB (U) 1*256kB (U) 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) =3D
15884kB
[ 383.796177] Node 0 DMA32: 19*4kB (UME) 24*8kB (UME) 21*16kB (UM) 25*32kB
(UM) 21*64kB (UME) 16*128kB (UM) 17*256kB (UM) 17*512kB (UME) 5*1024kB (ME)
4*2048kB (M) 0*4096kB =3D 31164kB
[ 383.796189] Node 0 Normal: 393*4kB (UME) 213*8kB (UME) 108*16kB (UM) 80*=
32kB
(UME) 110*64kB (UME) 70*128kB (ME) 26*256kB (ME) 0*512kB 0*1024kB 0*2048kB
0*4096kB =3D 30220kB
[ 383.796203] 1920365 total pagecache pages
[ 383.796205] 839 pages in swap cache
[ 383.796206] Swap cache stats: add 5093675, delete 5092328, find
110167/132300
[ 383.796207] Free swap =3D 7673432kB
[ 383.796207] Total swap =3D 8000508kB
[ 383.796208] 2056177 pages RAM
[ 383.796208] 0 pages HighMem/MovableOnly
[ 383.796209] 54740 pages reserved
[ 383.796209] 0 pages cma reserved
[ 383.796210] 0 pages hwpoisoned
[ 383.796383] Out of memory: Kill process 10614 (gem_shrink) score 999 or
sacrifice child
[ 383.796400] Killed process 10625 (gem_shrink) total-vm:51648kB,
anon-rss:396kB, file-rss:0kB, shmem-rss:0kB
[ 387.872016] 1 and 262144 pages still available in the bound and unbound =
GPU
page lists.
[ 387.872096] in:imklog invoked oom-killer:
gfp_mask=3D0x14200ca(GFP_HIGHUSER_MOVABLE), nodemask=3D(null), order=3D0,
oom_score_adj=3D0
[ 387.872101] CPU: 1 PID: 697 Comm: in:imklog Not tainted
4.16.0-rc6-drm-intel-qa-ww12-commit-4db112a+ #1
[ 387.872102] Hardware name: Intel Corporation CannonLake Client
Platform/CannonLake Y LPDDR4 RVP, BIOS CNLSFWR1.R00.X124.B02.1802051422
02/05/2018
[ 387.872103] Call Trace:
[ 387.872110] dump_stack+0x5c/0x85
[ 387.872114] dump_header+0x6b/0x27c
[ 387.872116] oom_kill_process+0x239/0x480
[ 387.872118] ? oom_badness+0xeb/0x160
[ 387.872120] out_of_memory+0x10f/0x480
[ 387.872123] __alloc_pages_slowpath+0xcdd/0xdb0
[ 387.872127] __alloc_pages_nodemask+0x246/0x280
[ 387.872129] filemap_fault+0x2e9/0x650
[ 387.872131] ? filemap_map_pages+0x188/0x370
[ 387.872134] ext4_filemap_fault+0x2c/0x3b
[ 387.872137] __do_fault+0x1f/0xb6
[ 387.872140] __handle_mm_fault+0xbfc/0x1130
[ 387.872143] handle_mm_fault+0xfa/0x200
[ 387.872145] __do_page_fault+0x257/0x4f0
[ 387.872148] ? page_fault+0x2f/0x50
[ 387.872150] page_fault+0x45/0x50
[ 387.872152] RIP: 1fa0:0x1f9f
[ 387.872153] RSP: 0004:00007f5b8187cd90 EFLAGS: 00001fa0
[ 387.872155] Mem-Info:
[ 387.872160] active_anon:6071 inactive_anon:1918515 isolated_anon:288
active_file:61 inactive_file:57 isolated_file:0
unevictable:0 dirty:0 writeback:0 unstable:0
slab_reclaimable:9750 slab_unreclaimable:25610
mapped:543 shmem:1919408 pagetables:2906 bounce:0
free:19312 free_pcp:71 free_cma:0
[ 387.872163] Node 0 active_anon:24284kB inactive_anon:7674060kB
active_file:244kB inactive_file:228kB unevictable:0kB isolated(anon):1152kB
isolated(file):0kB mapped:2172kB dirty:0kB writeback:0kB shmem:7677632kB
shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 0kB writeback_tmp:0kB
unstable:0kB all_unreclaimable? no
[ 387.872164] Node 0 DMA free:15884kB min:132kB low:164kB high:196kB
active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB
unevictable:0kB writepending:0kB present:15992kB managed:15884kB mlocked:0kB
kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB
free_cma:0kB
[ 387.872168] lowmem_reserve[]: 0 747 7758 7758 7758
[ 387.872172] Node 0 DMA32 free:31164kB min:6500kB low:8124kB high:9748kB
active_anon:288kB inactive_anon:770860kB active_file:4kB inactive_file:0kB
unevictable:0kB writepending:0kB present:872780kB managed:807024kB mlocked:=
0kB
kernel_stack:16kB pagetables:4kB bounce:0kB free_pcp:0kB local_pcp:0kB
free_cma:0kB
[ 387.872176] lowmem_reserve[]: 0 0 7010 7010 7010
[ 387.872179] Node 0 Normal free:30200kB min:60944kB low:76180kB high:9141=
6kB
active_anon:23996kB inactive_anon:6902684kB active_file:240kB
inactive_file:228kB unevictable:0kB writepending:0kB present:7335936kB
managed:7182840kB mlocked:0kB kernel_stack:4144kB pagetables:11620kB bounce=
:0kB
free_pcp:284kB local_pcp:0kB free_cma:0kB
[ 387.872183] lowmem_reserve[]: 0 0 0 0 0
[ 387.872186] Node 0 DMA: 1*4kB (U) 1*8kB (U) 2*16kB (U) 1*32kB (U) 1*64kB=
(U)
1*128kB (U) 1*256kB (U) 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) =3D
15884kB
[ 387.872199] Node 0 DMA32: 19*4kB (UME) 24*8kB (UME) 21*16kB (UM) 25*32kB
(UM) 21*64kB (UME) 16*128kB (UM) 17*256kB (UM) 17*512kB (UME) 5*1024kB (ME)
4*2048kB (M) 0*4096kB =3D 31164kB
[ 387.872213] Node 0 Normal: 388*4kB (UME) 213*8kB (UME) 108*16kB (UM) 80*=
32kB
(UME) 110*64kB (UME) 70*128kB (ME) 26*256kB (ME) 0*512kB 0*1024kB 0*2048kB
0*4096kB =3D 30200kB
[ 387.872228] 1920365 total pagecache pages
[ 387.872230] 839 pages in swap cache
[ 387.872231] Swap cache stats: add 5093675, delete 5092328, find
110167/132300
[ 387.872231] Free swap =3D 7673432kB
[ 387.872232] Total swap =3D 8000508kB
[ 387.872233] 2056177 pages RAM
[ 387.872233] 0 pages HighMem/MovableOnly
[ 387.872234] 54740 pages reserved
[ 387.872234] 0 pages cma reserved
[ 387.872235] 0 pages hwpoisoned
[ 387.872428] Out of memory: Kill process 10614 (gem_shrink) score 999 or
sacrifice child
[ 387.872448] Killed process 10626 (gem_shrink) total-vm:51648kB,
anon-rss:396kB, file-rss:0kB, shmem-rss:0kB
[ 394.296014] gem_shrink: page allocation failure: order:0,
mode:0x14204d2(GFP_HIGHUSER|__GFP_RETRY_MAYFAIL|__GFP_RECLAIMABLE),
nodemask=3D(null)
[ 394.296021] CPU: 2 PID: 10628 Comm: gem_shrink Not tainted
4.16.0-rc6-drm-intel-qa-ww12-commit-4db112a+ #1
[ 394.296022] Hardware name: Intel Corporation CannonLake Client
Platform/CannonLake Y LPDDR4 RVP, BIOS CNLSFWR1.R00.X124.B02.1802051422
02/05/2018
[ 394.296023] Call Trace:
[ 394.296030] dump_stack+0x5c/0x85
[ 394.296034] warn_alloc+0x100/0x180
[ 394.296037] ? unreserve_highatomic_pageblock+0x111/0x180
[ 394.296039] __alloc_pages_slowpath+0xda9/0xdb0
[ 394.296042] __alloc_pages_nodemask+0x246/0x280
[ 394.296046] alloc_pages_vma+0x7c/0x1e0
[ 394.296048] shmem_alloc_page+0x78/0xc0
[ 394.296050] ? __radix_tree_create+0x168/0x1f0
[ 394.296053] ? __update_load_avg_se.isra.38+0x1c1/0x1d0
[ 394.296055] ? __update_load_avg_se.isra.38+0x1c1/0x1d0
[ 394.296058] shmem_alloc_and_acct_page+0x7a/0x1d0
[ 394.296060] shmem_getpage_gfp+0x194/0xcf0
[ 394.296063] ? __schedule+0x3dd/0x8c0
[ 394.296066] shmem_read_mapping_page_gfp+0x42/0x80
[ 394.296068] ? _cond_resched+0x2d/0x40
[ 394.296100] i915_gem_object_get_pages_gtt+0x1b2/0x520 [i915]
[ 394.296128] __i915_gem_object_get_pages+0x52/0x60 [i915]
[ 394.296156] i915_gem_pread_ioctl+0x266/0x5a0 [i915]
[ 394.296183] ? i915_gem_object_get_page+0x70/0x70 [i915]
[ 394.296186] drm_ioctl_kernel+0x67/0xb0
[ 394.296188] drm_ioctl+0x2d4/0x3c0
[ 394.296214] ? i915_gem_object_get_page+0x70/0x70 [i915]
[ 394.296216] ? set_next_entity+0x92/0x1b0
[ 394.296218] ? pick_next_task_fair+0x287/0x5d0
[ 394.296221] ? __switch_to+0x12f/0x460
[ 394.296224] do_vfs_ioctl+0xa2/0x610
[ 394.296228] ? __schedule+0x3dd/0x8c0
[ 394.296230] SyS_ioctl+0x74/0x80
[ 394.296233] ? exit_to_usermode_loop+0x61/0xc0
[ 394.296235] do_syscall_64+0x6e/0x120
[ 394.296237] entry_SYSCALL_64_after_hwframe+0x3d/0xa2
[ 394.296239] RIP: 0033:0x7fa69a483ef7
[ 394.296241] RSP: 002b:00007ffe21883a78 EFLAGS: 00000246 ORIG_RAX:
0000000000000010
[ 394.296243] RAX: ffffffffffffffda RBX: 0000000000008000 RCX:
00007fa69a483ef7
[ 394.296244] RDX: 00007ffe21883ab0 RSI: 000000004020645c RDI:
0000000000000004
[ 394.296245] RBP: 00007ffe21883ab0 R08: 0000000000000004 R09:
0000000000000043
[ 394.296246] R10: 0000000000000073 R11: 0000000000000246 R12:
000000004020645c
[ 394.296247] R13: 0000000000000004 R14: 0000000000000004 R15:
0000000008000000
[ 394.296248] Mem-Info:
[ 394.296254] active_anon:6057 inactive_anon:1918238 isolated_anon:608
active_file:57 inactive_file:61 isolated_file:0
unevictable:0 dirty:0 writeback:0 unstable:0
slab_reclaimable:9750 slab_unreclaimable:25582
mapped:543 shmem:1919408 pagetables:2879 bounce:0
free:19314 free_pcp:140 free_cma:0
[ 394.296257] Node 0 active_anon:24228kB inactive_anon:7672952kB
active_file:228kB inactive_file:244kB unevictable:0kB isolated(anon):2432kB
isolated(file):0kB mapped:2172kB dirty:0kB writeback:0kB shmem:7677632kB
shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 0kB writeback_tmp:0kB
unstable:0kB all_unreclaimable? no
[ 394.296258] Node 0 DMA free:15884kB min:132kB low:164kB high:196kB
active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB
unevictable:0kB writepending:0kB present:15992kB managed:15884kB mlocked:0kB
kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB
free_cma:0kB
[ 394.296262] lowmem_reserve[]: 0 747 7758 7758 7758
[ 394.296266] Node 0 DMA32 free:31164kB min:6500kB low:8124kB high:9748kB
active_anon:288kB inactive_anon:771032kB active_file:0kB inactive_file:4kB
unevictable:0kB writepending:0kB present:872780kB managed:807024kB mlocked:=
0kB
kernel_stack:16kB pagetables:4kB bounce:0kB free_pcp:0kB local_pcp:0kB
free_cma:0kB
[ 394.296270] lowmem_reserve[]: 0 0 7010 7010 7010
[ 394.296273] Node 0 Normal free:30208kB min:60944kB low:76180kB high:9141=
6kB
active_anon:23940kB inactive_anon:6901932kB active_file:228kB
inactive_file:240kB unevictable:0kB writepending:0kB present:7335936kB
managed:7182840kB mlocked:0kB kernel_stack:4144kB pagetables:11512kB bounce=
:0kB
free_pcp:560kB local_pcp:80kB free_cma:0kB
[ 394.296277] lowmem_reserve[]: 0 0 0 0 0
[ 394.296280] Node 0 DMA: 1*4kB (U) 1*8kB (U) 2*16kB (U) 1*32kB (U) 1*64kB=
(U)
1*128kB (U) 1*256kB (U) 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) =3D
15884kB
[ 394.296292] Node 0 DMA32: 19*4kB (UME) 24*8kB (UME) 21*16kB (UM) 25*32kB
(UM) 21*64kB (UME) 16*128kB (UM) 17*256kB (UM) 17*512kB (UME) 5*1024kB (ME)
4*2048kB (M) 0*4096kB =3D 31164kB
[ 394.296305] Node 0 Normal: 393*4kB (UME) 213*8kB (UME) 108*16kB (UM) 80*=
32kB
(UME) 110*64kB (UME) 70*128kB (ME) 26*256kB (ME) 0*512kB 0*1024kB 0*2048kB
0*4096kB =3D 30220kB
[ 394.296319] 1920365 total pagecache pages
[ 394.296321] 839 pages in swap cache
[ 394.296322] Swap cache stats: add 5093675, delete 5092328, find
110167/132300
[ 394.296323] Free swap =3D 7673432kB
[ 394.296324] Total swap =3D 8000508kB
[ 394.296324] 2056177 pages RAM
[ 394.296325] 0 pages HighMem/MovableOnly
[ 394.296325] 54740 pages reserved
[ 394.296326] 0 pages cma reserved
[ 394.296326] 0 pages hwpoisoned
[ 394.904700] systemd[1]: systemd-journald.service: Main process exited,
code=3Ddumped, status=3D6/ABRT
[ 394.905939] systemd[1]: systemd-journald.service: Unit entered failed st=
ate.
[ 394.907498] systemd[1]: systemd-journald.service: Failed with result
'watchdog'.
[ 394.958886] systemd-journald[10792]: File
/run/log/journal/f67bf632faf24157a4676ab8a79ac726/system.journal corrupted =
or
uncleanly shut down, renaming and replacing.