[44.572354] Initializing watchdogs
[44.573452]   /dev/watchdog0
[44.645992] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:5a85 Intel Broxton (Gen9) HD Graphics 500
[44.697689] [001/161] (960s left) i915_module_load (load)
[45.300081] Starting subtest: load
[49.573867] Subtest load: SUCCESS (4.276s)
[49.730227] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[49.734717] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[49.759786] [002/161] (955s left) core_auth (basic-auth)
[50.055396] Starting subtest: basic-auth
[50.077424] Subtest basic-auth: SUCCESS (0.002s)
[50.227760] [003/161] (955s left) debugfs_test (read_all_entries)
[50.493967] Starting subtest: read_all_entries
[50.648078] Subtest read_all_entries: SUCCESS (0.163s)
[50.938457] [004/161] (954s left) debugfs_test (basic-hwmon)
[51.195806] Starting subtest: basic-hwmon
[51.247701] Subtest basic-hwmon: SKIP (0.000s)
[51.490461] [005/161] (954s left) debugfs_test (sysfs)
[51.782036] Starting subtest: sysfs
[51.890931] Subtest sysfs: SUCCESS (0.166s)
[52.182273] [006/161] (953s left) fbdev (eof)
[52.425828] Starting subtest: eof
[52.446097] Subtest eof: SUCCESS (0.001s)
[52.603659] [007/161] (953s left) fbdev (info)
[52.852507] Starting subtest: info
[52.875420] Subtest info: SUCCESS (0.000s)
[53.030464] [008/161] (953s left) fbdev (nullptr)
[53.257931] Starting subtest: nullptr
[53.278389] Subtest nullptr: SUCCESS (0.000s)
[53.437722] [009/161] (953s left) fbdev (read)
[53.689244] Starting subtest: read
[55.154247] Subtest read: SUCCESS (1.495s)
[55.318772] [010/161] (951s left) fbdev (write)
[55.555910] Starting subtest: write
[56.311190] Subtest write: SUCCESS (0.776s)
[56.482575] [011/161] (950s left) gem_basic (bad-close)
[56.756181] Starting subtest: bad-close
[56.801683] Subtest bad-close: SUCCESS (0.000s)
[56.958801] [012/161] (950s left) gem_basic (create-close)
[57.234964] Starting subtest: create-close
[57.279562] Subtest create-close: SUCCESS (0.000s)
[57.437724] [013/161] (949s left) gem_basic (create-fd-close)
[57.711099] Starting subtest: create-fd-close
[57.756988] Subtest create-fd-close: SUCCESS (0.000s)
[57.913258] [014/161] (949s left) gem_busy (busy@all-engines)
[58.193040] Starting subtest: busy
[58.215945] Starting dynamic subtest: all-engines
[58.295447] Dynamic subtest all-engines: SUCCESS (0.116s)
[58.316440] Subtest busy: SUCCESS (0.117s)
[58.778656] [015/161] (948s left) gem_close_race (basic-process)
[59.059137] Starting subtest: basic-process
[59.182458] Subtest basic-process: SUCCESS (0.123s)
[59.481709] [016/161] (948s left) gem_close_race (basic-threads)
[59.733902] Starting subtest: basic-threads
[61.000978] Subtest basic-threads: SUCCESS (1.237s)
[61.296276] [017/161] (946s left) gem_ctx_create (basic)
[61.569176] Starting subtest: basic
[61.593164] Subtest basic: SUCCESS (0.000s)
[61.862636] [018/161] (946s left) gem_ctx_create (basic-files)
[62.124921] Starting subtest: basic-files
[64.176059] Subtest basic-files: SUCCESS (2.007s)
[64.465773] [019/161] (943s left) gem_ctx_exec (basic)
[64.691985] Starting subtest: basic
[64.715263] Subtest basic: SUCCESS (0.010s)
[64.879943] [020/161] (943s left) gem_exec_basic (basic)
[65.121701] Starting subtest: basic
[65.142144] Starting dynamic subtest: rcs0-smem
[65.164113] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[65.206766] Starting dynamic subtest: bcs0-smem
[65.227768] Dynamic subtest bcs0-smem: SUCCESS (0.003s)
[65.279196] Starting dynamic subtest: vcs0-smem
[65.301851] Dynamic subtest vcs0-smem: SUCCESS (0.002s)
[65.352589] Starting dynamic subtest: vecs0-smem
[65.374163] Dynamic subtest vecs0-smem: SUCCESS (0.002s)
[65.394736] Subtest basic: SUCCESS (0.013s)
[65.550097] [021/161] (943s left) gem_exec_create (basic)
[65.819498] Starting subtest: basic
[65.840471] Starting dynamic subtest: smem
[67.886909] Dynamic subtest smem: SUCCESS (2.046s)
[67.907223] Subtest basic: SUCCESS (2.047s)
[68.281553] [022/161] (940s left) gem_exec_fence (basic-busy)
[68.815787] Starting subtest: basic-busy
[68.837285] Starting dynamic subtest: rcs0
[68.881324] Dynamic subtest rcs0: SUCCESS (0.040s)
[68.922813] Starting dynamic subtest: bcs0
[68.967464] Dynamic subtest bcs0: SUCCESS (0.033s)
[69.010570] Starting dynamic subtest: vcs0
[69.053686] Dynamic subtest vcs0: SUCCESS (0.046s)
[69.097991] Starting dynamic subtest: vecs0
[69.142319] Dynamic subtest vecs0: SUCCESS (0.031s)
[69.164059] Subtest basic-busy: SUCCESS (0.155s)
[70.139321] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[70.164554] [023/161] (938s left) gem_exec_fence (basic-wait)
[70.624491] Starting subtest: basic-wait
[70.644480] Starting dynamic subtest: rcs0
[70.665721] Dynamic subtest rcs0: SUCCESS (0.040s)
[70.711165] Starting dynamic subtest: bcs0
[70.732700] Dynamic subtest bcs0: SUCCESS (0.034s)
[70.777624] Starting dynamic subtest: vcs0
[70.798781] Dynamic subtest vcs0: SUCCESS (0.028s)
[70.843682] Starting dynamic subtest: vecs0
[70.864639] Dynamic subtest vecs0: SUCCESS (0.027s)
[70.884785] Subtest basic-wait: SUCCESS (0.133s)
[71.591647] [024/161] (937s left) gem_exec_fence (basic-await)
[71.979619] Starting subtest: basic-await
[72.000875] Starting dynamic subtest: rcs0
[72.151874] Dynamic subtest rcs0: SUCCESS (0.172s)
[72.199453] Starting dynamic subtest: bcs0
[72.322231] Dynamic subtest bcs0: SUCCESS (0.169s)
[72.366330] Starting dynamic subtest: vcs0
[72.490362] Dynamic subtest vcs0: SUCCESS (0.165s)
[72.532937] Starting dynamic subtest: vecs0
[72.663596] Dynamic subtest vecs0: SUCCESS (0.169s)
[72.685055] Subtest basic-await: SUCCESS (0.679s)
[73.487253] [025/161] (935s left) gem_exec_fence (nb-await)
[73.855931] Starting subtest: nb-await
[73.878339] Starting dynamic subtest: rcs0
[73.933931] Dynamic subtest rcs0: SUCCESS (0.078s)
[73.981957] Starting dynamic subtest: bcs0
[74.034411] Dynamic subtest bcs0: SUCCESS (0.096s)
[74.081447] Starting dynamic subtest: vcs0
[74.127916] Dynamic subtest vcs0: SUCCESS (0.072s)
[74.173515] Starting dynamic subtest: vecs0
[74.194089] Dynamic subtest vecs0: SUCCESS (0.073s)
[74.216357] Subtest nb-await: SUCCESS (0.323s)
[75.008911] [026/161] (934s left) gem_exec_gttfill (basic)
[75.280374] Starting subtest: basic
[105.645252] Subtest basic: SUCCESS (30.341s)
[105.969294] [027/161] (903s left) gem_exec_parallel (engines)
[106.373059] Starting subtest: engines
[106.392640] Starting dynamic subtest: basic
[111.590246] Dynamic subtest basic: SUCCESS (5.049s)
[111.638528] Starting dynamic subtest: contexts
[117.547999] Dynamic subtest contexts: SUCCESS (6.129s)
[117.594698] Starting dynamic subtest: fds
[123.235182] Dynamic subtest fds: SUCCESS (5.640s)
[123.285125] Starting dynamic subtest: userptr
[127.480692] Dynamic subtest userptr: SUCCESS (4.117s)
[127.502645] Subtest engines: SUCCESS (20.938s)
[127.675314] [028/161] (882s left) gem_exec_store (basic)
[127.989854] Starting subtest: basic
[128.010766] Subtest basic: SUCCESS (0.081s)
[128.310521] [029/161] (881s left) gem_flink_basic (bad-flink)
[128.570213] Starting subtest: bad-flink
[128.593737] Subtest bad-flink: SUCCESS (0.000s)
[128.761213] [030/161] (881s left) gem_flink_basic (bad-open)
[129.021794] Starting subtest: bad-open
[129.041797] Subtest bad-open: SUCCESS (0.000s)
[129.209762] [031/161] (881s left) gem_flink_basic (basic)
[129.471718] Starting subtest: basic
[129.491832] Subtest basic: SUCCESS (0.000s)
[129.659679] [032/161] (881s left) gem_flink_basic (double-flink)
[129.920470] Starting subtest: double-flink
[129.943178] Subtest double-flink: SUCCESS (0.000s)
[130.111477] [033/161] (880s left) gem_flink_basic (flink-lifetime)
[130.378289] Starting subtest: flink-lifetime
[130.401591] Subtest flink-lifetime: SUCCESS (0.003s)
[130.569825] [034/161] (880s left) gem_huc_copy (huc-copy)
[130.835341] Starting subtest: huc-copy
[130.878732] Subtest huc-copy: SKIP (0.000s)
[131.045174] [035/161] (880s left) gem_linear_blits (basic)
[131.455924] Starting subtest: basic
[131.501332] Subtest basic: SUCCESS (0.029s)
[131.771626] [036/161] (879s left) gem_mmap (basic)
[132.206585] Starting subtest: basic
[132.312813] Subtest basic: SUCCESS (0.001s)
[132.487999] [037/161] (879s left) gem_mmap_gtt (basic)
[132.755727] Starting subtest: basic
[132.778158] Subtest basic: SUCCESS (0.002s)
[132.943753] [038/161] (878s left) gem_render_linear_blits (basic)
[133.190048] Starting subtest: basic
[133.294220] Subtest basic: SUCCESS (0.098s)
[133.468157] [039/161] (878s left) gem_render_tiled_blits (basic)
[133.711245] Starting subtest: basic
[133.838949] Subtest basic: SUCCESS (0.135s)
[134.024981] [040/161] (878s left) gem_ringfill (basic-all)
[134.575129] Starting subtest: basic-all
[135.769297] Subtest basic-all: SUCCESS (1.224s)
[135.986544] [041/161] (876s left) gem_softpin (allocator-basic)
[136.253774] Starting subtest: allocator-basic
[136.489423] Subtest allocator-basic: SUCCESS (0.276s)
[136.704722] [042/161] (875s left) gem_softpin (allocator-basic-reserve)
[136.962460] Starting subtest: allocator-basic-reserve
[137.191268] Subtest allocator-basic-reserve: SUCCESS (0.276s)
[137.433666] [043/161] (875s left) gem_softpin (safe-alignment)
[137.695361] Starting subtest: safe-alignment
[137.722132] Subtest safe-alignment: SUCCESS (0.035s)
[137.938855] [044/161] (874s left) gem_sync (basic-all)
[138.361512] Starting subtest: basic-all
[140.652452] Subtest basic-all: SUCCESS (2.299s)
[140.819448] [045/161] (872s left) gem_sync (basic-each)
[141.231645] Starting subtest: basic-each
[143.359485] Subtest basic-each: SUCCESS (2.304s)
[143.665577] [046/161] (869s left) gem_tiled_blits (basic)
[144.009488] Starting subtest: basic
[144.069449] Subtest basic: SUCCESS (0.060s)
[144.354084] [047/161] (869s left) gem_tiled_fence_blits (basic)
[144.882503] Starting subtest: basic
[144.930385] Subtest basic: SUCCESS (0.045s)
[145.210374] [048/161] (868s left) gem_tiled_pread_basic
[146.082736] [049/161] (867s left) gem_wait (busy@all-engines)
[146.336870] Starting subtest: busy
[146.360338] Starting dynamic subtest: all-engines
[146.880819] Dynamic subtest all-engines: SUCCESS (0.534s)
[146.903804] Subtest busy: SUCCESS (0.535s)
[147.223762] [050/161] (866s left) gem_wait (wait@all-engines)
[147.487586] Starting subtest: wait
[147.508845] Starting dynamic subtest: all-engines
[148.540205] Dynamic subtest all-engines: SUCCESS (1.050s)
[148.560961] Subtest wait: SUCCESS (1.051s)
[148.873115] [051/161] (865s left) i915_getparams_basic (basic-eu-total)
[149.133133] Starting subtest: basic-eu-total
[149.177032] Subtest basic-eu-total: SUCCESS (0.000s)
[149.344080] [052/161] (864s left) i915_getparams_basic (basic-subslice-total)
[149.587812] Starting subtest: basic-subslice-total
[149.631778] Subtest basic-subslice-total: SUCCESS (0.000s)
[149.806682] [053/161] (864s left) i915_hangman (error-state-basic)
[150.056049] Starting subtest: error-state-basic
[150.076899] Subtest error-state-basic: SUCCESS (0.021s)
[150.277185] [054/161] (864s left) i915_pciid
[150.733996] [055/161] (864s left) kms_addfb_basic (addfb25-4-tiled)
[151.166173] Starting subtest: addfb25-4-tiled
[151.192375] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[151.382371] [056/161] (863s left) kms_addfb_basic (addfb25-bad-modifier)
[151.729321] Starting subtest: addfb25-bad-modifier
[151.750177] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[151.942311] [057/161] (863s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[152.288911] Starting subtest: addfb25-framebuffer-vs-set-tiling
[152.309367] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[152.497378] [058/161] (862s left) kms_addfb_basic (addfb25-modifier-no-flag)
[152.831924] Starting subtest: addfb25-modifier-no-flag
[152.855245] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[153.069878] [059/161] (862s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[153.420960] Starting subtest: addfb25-x-tiled-legacy
[153.442672] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[153.607825] [060/161] (862s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[153.965055] Starting subtest: addfb25-x-tiled-mismatch-legacy
[153.987117] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.001s)
[154.162494] [061/161] (861s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[154.509088] Starting subtest: addfb25-yf-tiled-legacy
[154.528369] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[154.728657] [062/161] (861s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[155.073599] Starting subtest: addfb25-y-tiled-legacy
[155.095936] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[155.262941] [063/161] (860s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[155.616854] Starting subtest: addfb25-y-tiled-small-legacy
[155.639544] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.001s)
[155.807458] [064/161] (860s left) kms_addfb_basic (bad-pitch-0)
[156.073667] Starting subtest: bad-pitch-0
[156.095623] Subtest bad-pitch-0: SUCCESS (0.000s)
[156.373755] [065/161] (860s left) kms_addfb_basic (bad-pitch-1024)
[156.615317] Starting subtest: bad-pitch-1024
[156.635083] Subtest bad-pitch-1024: SUCCESS (0.000s)
[156.929404] [066/161] (859s left) kms_addfb_basic (bad-pitch-128)
[157.172412] Starting subtest: bad-pitch-128
[157.193995] Subtest bad-pitch-128: SUCCESS (0.000s)
[157.510602] [067/161] (859s left) kms_addfb_basic (bad-pitch-256)
[157.747074] Starting subtest: bad-pitch-256
[157.769546] Subtest bad-pitch-256: SUCCESS (0.000s)
[158.060089] [068/161] (858s left) kms_addfb_basic (bad-pitch-32)
[158.318183] Starting subtest: bad-pitch-32
[158.347042] Subtest bad-pitch-32: SUCCESS (0.000s)
[158.591508] [069/161] (858s left) kms_addfb_basic (bad-pitch-63)
[158.833754] Starting subtest: bad-pitch-63
[158.855099] Subtest bad-pitch-63: SUCCESS (0.000s)
[159.146997] [070/161] (858s left) kms_addfb_basic (bad-pitch-65536)
[159.385537] Starting subtest: bad-pitch-65536
[159.406929] Subtest bad-pitch-65536: SUCCESS (0.001s)
[159.674816] [071/161] (857s left) kms_addfb_basic (bad-pitch-999)
[159.905974] Starting subtest: bad-pitch-999
[159.925333] Subtest bad-pitch-999: SUCCESS (0.000s)
[160.209115] [072/161] (857s left) kms_addfb_basic (basic)
[160.467877] Starting subtest: basic
[160.487222] Subtest basic: SUCCESS (0.000s)
[160.760633] [073/161] (857s left) kms_addfb_basic (basic-x-tiled-legacy)
[160.999765] Starting subtest: basic-x-tiled-legacy
[161.023842] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[161.308486] [074/161] (856s left) kms_addfb_basic (basic-y-tiled-legacy)
[161.545883] Starting subtest: basic-y-tiled-legacy
[161.568237] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[161.886555] [075/161] (856s left) kms_addfb_basic (bo-too-small)
[162.242658] Starting subtest: bo-too-small
[162.270101] Subtest bo-too-small: SUCCESS (0.001s)
[162.457804] [076/161] (855s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[162.831720] Starting subtest: bo-too-small-due-to-tiling
[162.858917] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[163.030712] [077/161] (855s left) kms_addfb_basic (clobberred-modifier)
[163.323458] Starting subtest: clobberred-modifier
[163.346172] Subtest clobberred-modifier: SUCCESS (0.001s)
[163.640734] [078/161] (855s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[163.877282] Starting subtest: framebuffer-vs-set-tiling
[163.896669] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[164.170938] [079/161] (854s left) kms_addfb_basic (invalid-get-prop)
[164.428078] Starting subtest: invalid-get-prop
[164.450055] Subtest invalid-get-prop: SUCCESS (0.000s)
[164.713948] [080/161] (854s left) kms_addfb_basic (invalid-get-prop-any)
[164.972101] Starting subtest: invalid-get-prop-any
[164.994297] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[165.258467] [081/161] (853s left) kms_addfb_basic (invalid-set-prop)
[165.520330] Starting subtest: invalid-set-prop
[165.542587] Subtest invalid-set-prop: SUCCESS (0.000s)
[165.804356] [082/161] (853s left) kms_addfb_basic (invalid-set-prop-any)
[166.064481] Starting subtest: invalid-set-prop-any
[166.084096] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[166.349695] [083/161] (853s left) kms_addfb_basic (no-handle)
[166.613254] Starting subtest: no-handle
[166.632453] Subtest no-handle: SUCCESS (0.000s)
[166.892403] [084/161] (852s left) kms_addfb_basic (size-max)
[167.247065] Starting subtest: size-max
[167.268540] Subtest size-max: SUCCESS (0.000s)
[167.448616] [085/161] (852s left) kms_addfb_basic (small-bo)
[167.798747] Starting subtest: small-bo
[167.821742] Subtest small-bo: SUCCESS (0.000s)
[168.001217] [086/161] (852s left) kms_addfb_basic (tile-pitch-mismatch)
[168.252610] Starting subtest: tile-pitch-mismatch
[168.273623] Subtest tile-pitch-mismatch: SUCCESS (0.001s)
[168.530169] [087/161] (851s left) kms_addfb_basic (too-high)
[168.876551] Starting subtest: too-high
[168.895936] Subtest too-high: SUCCESS (0.002s)
[169.074752] [088/161] (851s left) kms_addfb_basic (too-wide)
[169.426456] Starting subtest: too-wide
[169.446915] Subtest too-wide: SUCCESS (0.000s)
[169.625740] [089/161] (850s left) kms_addfb_basic (unused-handle)
[169.885601] Starting subtest: unused-handle
[169.907160] Subtest unused-handle: SUCCESS (0.000s)
[170.162871] [090/161] (850s left) kms_addfb_basic (unused-modifier)
[170.403190] Starting subtest: unused-modifier
[170.422561] Subtest unused-modifier: SUCCESS (0.000s)
[170.693565] [091/161] (850s left) kms_addfb_basic (unused-offsets)
[170.931840] Starting subtest: unused-offsets
[170.954242] Subtest unused-offsets: SUCCESS (0.000s)
[171.240851] [092/161] (849s left) kms_addfb_basic (unused-pitches)
[171.529234] Starting subtest: unused-pitches
[171.555068] Subtest unused-pitches: SUCCESS (0.000s)
[171.798101] [093/161] (849s left) kms_busy (basic)
[172.163561] Starting subtest: basic
[172.184747] Starting dynamic subtest: flip
[172.985483] Dynamic subtest flip: SUCCESS (0.806s)
[173.040083] Starting dynamic subtest: modeset
[174.445154] Dynamic subtest modeset: SUCCESS (1.464s)
[174.467831] Subtest basic: SUCCESS (2.274s)
[174.950227] [094/161] (846s left) kms_prop_blob (basic)
[175.201116] Starting subtest: basic
[175.224186] Subtest basic: SUCCESS (0.000s)
[175.398299] [095/161] (846s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[175.762308] Starting subtest: basic-busy-flip-before-cursor-atomic
[175.810725] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[175.977992] [096/161] (845s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[176.315789] Starting subtest: basic-busy-flip-before-cursor-legacy
[176.358877] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[176.554942] [097/161] (845s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[176.931115] Starting subtest: basic-flip-after-cursor-atomic
[177.907708] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.994s)
[178.347730] [098/161] (843s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[178.679973] Starting subtest: basic-flip-after-cursor-legacy
[179.650399] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.985s)
[180.133506] [099/161] (842s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[180.487160] Starting subtest: basic-flip-after-cursor-varying-size
[181.461414] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.993s)
[181.907706] [100/161] (840s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[182.263340] Starting subtest: basic-flip-before-cursor-atomic
[183.241357] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.992s)
[183.702689] [101/161] (838s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[184.030356] Starting subtest: basic-flip-before-cursor-legacy
[185.023385] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.999s)
[185.466521] [102/161] (837s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[185.813936] Starting subtest: basic-flip-before-cursor-varying-size
[186.791412] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.992s)
[187.260662] [103/161] (835s left) kms_dsc (dsc-basic)
[187.650759] Subtest dsc-basic: SKIP (0.000s)
[187.820358] [104/161] (835s left) kms_flip (basic-flip-vs-dpms)
[188.185098] Starting subtest: basic-flip-vs-dpms
[188.204434] Starting dynamic subtest: A-DP1
[189.454783] Dynamic subtest A-DP1: SUCCESS (1.264s)
[189.504416] Starting dynamic subtest: B-DP1
[190.616364] Dynamic subtest B-DP1: SUCCESS (1.173s)
[190.663476] Starting dynamic subtest: C-DP1
[191.800671] Dynamic subtest C-DP1: SUCCESS (1.172s)
[191.874094] Subtest basic-flip-vs-dpms: SUCCESS (3.620s)
[192.310608] [105/161] (831s left) kms_flip (basic-flip-vs-modeset)
[192.711408] Starting subtest: basic-flip-vs-modeset
[192.739390] Starting dynamic subtest: A-DP1
[193.956605] Dynamic subtest A-DP1: SUCCESS (1.277s)
[194.005291] Starting dynamic subtest: B-DP1
[195.117621] Dynamic subtest B-DP1: SUCCESS (1.165s)
[195.164292] Starting dynamic subtest: C-DP1
[196.294191] Dynamic subtest C-DP1: SUCCESS (1.165s)
[196.315374] Subtest basic-flip-vs-modeset: SUCCESS (3.618s)
[196.746496] [106/161] (826s left) kms_flip (basic-flip-vs-wf_vblank)
[197.124962] Starting subtest: basic-flip-vs-wf_vblank
[197.145260] Starting dynamic subtest: A-DP1
[198.539656] Dynamic subtest A-DP1: SUCCESS (1.445s)
[198.587813] Starting dynamic subtest: B-DP1
[199.963308] Dynamic subtest B-DP1: SUCCESS (1.414s)
[200.010467] Starting dynamic subtest: C-DP1
[201.387040] Dynamic subtest C-DP1: SUCCESS (1.420s)
[201.407859] Subtest basic-flip-vs-wf_vblank: SUCCESS (4.291s)
[201.928704] [107/161] (821s left) kms_flip (basic-plain-flip)
[202.336838] Starting subtest: basic-plain-flip
[202.359447] Starting dynamic subtest: A-DP1
[203.467969] Dynamic subtest A-DP1: SUCCESS (1.172s)
[203.516396] Starting dynamic subtest: B-DP1
[204.608142] Dynamic subtest B-DP1: SUCCESS (1.135s)
[204.654818] Starting dynamic subtest: C-DP1
[205.747287] Dynamic subtest C-DP1: SUCCESS (1.136s)
[205.767636] Subtest basic-plain-flip: SUCCESS (3.455s)
[206.291582] [108/161] (817s left) kms_force_connector_basic (force-connector-state)
[206.579801] Subtest force-connector-state: SKIP (0.000s)
[206.754431] [109/161] (817s left) kms_force_connector_basic (force-edid)
[207.047055] Subtest force-edid: SKIP (0.000s)
[207.222509] [110/161] (817s left) kms_force_connector_basic (force-load-detect)
[207.490146] Subtest force-load-detect: SKIP (0.000s)
[207.670638] [111/161] (816s left) kms_force_connector_basic (prune-stale-modes)
[207.956336] Subtest prune-stale-modes: SKIP (0.000s)
[208.138533] [112/161] (816s left) kms_frontbuffer_tracking (basic)
[208.557157] Starting subtest: basic
[212.287373] Subtest basic: SUCCESS (3.794s)
[212.504287] [113/161] (812s left) kms_hdmi_inject (inject-audio)
[212.796849] Subtest inject-audio: SKIP (0.000s)
[212.986797] [114/161] (812s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[213.347051] Starting subtest: compare-crc-sanitycheck-xr24
[213.369411] Starting dynamic subtest: pipe-A-DP-1
[213.826085] Dynamic subtest pipe-A-DP-1: SUCCESS (0.474s)
[213.873403] Starting dynamic subtest: pipe-B-DP-1
[214.451192] Dynamic subtest pipe-B-DP-1: SUCCESS (0.625s)
[214.502576] Starting dynamic subtest: pipe-C-DP-1
[215.097853] Dynamic subtest pipe-C-DP-1: SUCCESS (0.639s)
[215.121499] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.753s)
[215.539147] [115/161] (809s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[215.898742] Starting subtest: compare-crc-sanitycheck-nv12
[215.918739] Starting dynamic subtest: pipe-A-DP-1
[215.964248] Dynamic subtest pipe-A-DP-1: SKIP (0.001s)
[216.032093] Starting dynamic subtest: pipe-B-DP-1
[216.076215] Dynamic subtest pipe-B-DP-1: SKIP (0.001s)
[216.152670] Starting dynamic subtest: pipe-C-DP-1
[216.193952] Dynamic subtest pipe-C-DP-1: SKIP (0.001s)
[216.239629] Subtest compare-crc-sanitycheck-nv12: SKIP (0.017s)
[216.416937] [116/161] (808s left) kms_pipe_crc_basic (hang-read-crc)
[216.766156] Starting subtest: hang-read-crc
[216.788175] Starting dynamic subtest: pipe-A-DP-1
[218.069596] Dynamic subtest pipe-A-DP-1: SUCCESS (1.300s)
[218.127317] Starting dynamic subtest: pipe-B-DP-1
[219.525917] Dynamic subtest pipe-B-DP-1: SUCCESS (1.465s)
[219.578720] Starting dynamic subtest: pipe-C-DP-1
[221.036308] Dynamic subtest pipe-C-DP-1: SUCCESS (1.491s)
[221.058489] Subtest hang-read-crc: SUCCESS (4.271s)
[221.462823] [117/161] (804s left) kms_pipe_crc_basic (nonblocking-crc)
[221.818160] Starting subtest: nonblocking-crc
[221.837986] Starting dynamic subtest: pipe-A-DP-1
[222.472399] Dynamic subtest pipe-A-DP-1: SUCCESS (0.641s)
[222.520442] Starting dynamic subtest: pipe-B-DP-1
[223.278078] Dynamic subtest pipe-B-DP-1: SUCCESS (0.788s)
[223.332225] Starting dynamic subtest: pipe-C-DP-1
[224.084978] Dynamic subtest pipe-C-DP-1: SUCCESS (0.811s)
[224.105096] Subtest nonblocking-crc: SUCCESS (2.255s)
[224.506981] [118/161] (801s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[224.870387] Starting subtest: nonblocking-crc-frame-sequence
[224.893372] Starting dynamic subtest: pipe-A-DP-1
[225.502584] Dynamic subtest pipe-A-DP-1: SUCCESS (0.628s)
[225.550369] Starting dynamic subtest: pipe-B-DP-1
[226.314980] Dynamic subtest pipe-B-DP-1: SUCCESS (0.808s)
[226.365521] Starting dynamic subtest: pipe-C-DP-1
[227.107201] Dynamic subtest pipe-C-DP-1: SUCCESS (0.796s)
[227.128918] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.248s)
[227.529220] [119/161] (798s left) kms_pipe_crc_basic (read-crc)
[227.890460] Starting subtest: read-crc
[227.910603] Starting dynamic subtest: pipe-A-DP-1
[228.480070] Dynamic subtest pipe-A-DP-1: SUCCESS (0.582s)
[228.531666] Starting dynamic subtest: pipe-B-DP-1
[229.200640] Dynamic subtest pipe-B-DP-1: SUCCESS (0.719s)
[229.277365] Starting dynamic subtest: pipe-C-DP-1
[229.937032] Dynamic subtest pipe-C-DP-1: SUCCESS (0.729s)
[229.956827] Subtest read-crc: SUCCESS (2.046s)
[230.354556] [120/161] (795s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[230.713893] Starting subtest: read-crc-frame-sequence
[230.735453] Starting dynamic subtest: pipe-A-DP-1
[231.303381] Dynamic subtest pipe-A-DP-1: SUCCESS (0.579s)
[231.353375] Starting dynamic subtest: pipe-B-DP-1
[232.038796] Dynamic subtest pipe-B-DP-1: SUCCESS (0.738s)
[232.090248] Starting dynamic subtest: pipe-C-DP-1
[232.805540] Dynamic subtest pipe-C-DP-1: SUCCESS (0.750s)
[232.829121] Subtest read-crc-frame-sequence: SUCCESS (2.084s)
[233.226903] [121/161] (792s left) kms_pm_backlight (basic-brightness)
[233.601646] Subtest basic-brightness: SKIP (0.000s)
[233.774768] [122/161] (792s left) kms_pm_rpm (basic-pci-d3-state)
[235.609905] Starting subtest: basic-pci-d3-state
[236.293370] Subtest basic-pci-d3-state: SUCCESS (0.678s)
[236.615726] [123/161] (789s left) kms_pm_rpm (basic-rte)
[236.856815] Starting subtest: basic-rte
[239.067697] Subtest basic-rte: SUCCESS (2.231s)
[240.047903] [124/161] (786s left) kms_psr (psr-primary-page-flip)
[240.430807] Subtest psr-primary-page-flip: SKIP (0.000s)
[240.604589] [125/161] (786s left) kms_psr (psr-cursor-plane-move)
[240.991503] Subtest psr-cursor-plane-move: SKIP (0.000s)
[241.161946] [126/161] (785s left) kms_psr (psr-sprite-plane-onoff)
[241.515770] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[241.693966] [127/161] (785s left) kms_psr (psr-primary-mmap-gtt)
[242.092341] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[242.281602] [128/161] (785s left) kms_setmode (basic-clone-single-crtc)
[242.547550] Starting subtest: basic-clone-single-crtc
[242.611638] Subtest basic-clone-single-crtc: SKIP (0.000s)
[242.786637] [129/161] (784s left) i915_pm_rps (basic-api)
[243.055779] Starting subtest: basic-api
[243.078053] Subtest basic-api: SUCCESS (0.010s)
[243.278809] [130/161] (784s left) prime_self_import (basic-llseek-bad)
[243.530812] Starting subtest: basic-llseek-bad
[243.583043] Subtest basic-llseek-bad: SUCCESS (0.005s)
[243.764650] [131/161] (784s left) prime_self_import (basic-llseek-size)
[243.984730] Starting subtest: basic-llseek-size
[244.025940] Subtest basic-llseek-size: SUCCESS (0.008s)
[244.202530] [132/161] (784s left) prime_self_import (basic-with_fd_dup)
[244.426782] Starting subtest: basic-with_fd_dup
[244.470419] Subtest basic-with_fd_dup: SUCCESS (0.008s)
[244.652805] [133/161] (783s left) prime_self_import (basic-with_one_bo)
[244.895109] Starting subtest: basic-with_one_bo
[244.940620] Subtest basic-with_one_bo: SUCCESS (0.008s)
[245.111791] [134/161] (783s left) prime_self_import (basic-with_one_bo_two_files)
[245.355118] Starting subtest: basic-with_one_bo_two_files
[245.398799] Subtest basic-with_one_bo_two_files: SUCCESS (0.006s)
[245.574606] [135/161] (783s left) prime_self_import (basic-with_two_bos)
[245.815119] Starting subtest: basic-with_two_bos
[245.859512] Subtest basic-with_two_bos: SUCCESS (0.008s)
[246.043985] [136/161] (782s left) prime_vgem (basic-fence-flip)
[246.339663] Starting subtest: basic-fence-flip
[246.992094] Subtest basic-fence-flip: SUCCESS (0.680s)
[247.238511] [137/161] (781s left) prime_vgem (basic-fence-mmap)
[247.527079] Starting subtest: basic-fence-mmap
[247.571511] Subtest basic-fence-mmap: SUCCESS (0.078s)
[247.772305] [138/161] (781s left) prime_vgem (basic-fence-read)
[248.034861] Starting subtest: basic-fence-read
[248.124611] Subtest basic-fence-read: SUCCESS (0.145s)
[248.328520] [139/161] (781s left) prime_vgem (basic-gtt)
[248.618257] Starting subtest: basic-gtt
[248.638858] Subtest basic-gtt: SUCCESS (0.032s)
[248.844979] [140/161] (780s left) prime_vgem (basic-read)
[249.136148] Starting subtest: basic-read
[249.156808] Subtest basic-read: SUCCESS (0.067s)
[249.384525] [141/161] (780s left) prime_vgem (basic-write)
[249.647868] Starting subtest: basic-write
[249.699380] Subtest basic-write: SUCCESS (0.093s)
[249.921233] [142/161] (780s left) vgem_basic (setversion)
[250.200531] Starting subtest: setversion
[250.242049] Subtest setversion: SUCCESS (0.000s)
[250.419628] [143/161] (779s left) vgem_basic (create)
[250.663940] Starting subtest: create
[250.686111] Subtest create: SUCCESS (0.001s)
[250.860203] [144/161] (779s left) vgem_basic (debugfs)
[251.112268] Starting subtest: debugfs
[251.134685] Subtest debugfs: SUCCESS (0.001s)
[251.314558] [145/161] (779s left) vgem_basic (dmabuf-export)
[251.560462] Starting subtest: dmabuf-export
[251.601942] Subtest dmabuf-export: SUCCESS (0.006s)
[251.777989] [146/161] (779s left) vgem_basic (dmabuf-fence)
[252.022670] Starting subtest: dmabuf-fence
[252.044955] Subtest dmabuf-fence: SUCCESS (0.001s)
[252.217958] [147/161] (778s left) vgem_basic (dmabuf-fence-before)
[252.461523] Starting subtest: dmabuf-fence-before
[252.482478] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[252.691196] [148/161] (778s left) vgem_basic (dmabuf-mmap)
[252.984189] Starting subtest: dmabuf-mmap
[253.008547] Subtest dmabuf-mmap: SUCCESS (0.037s)
[253.198522] [149/161] (778s left) vgem_basic (mmap)
[253.494383] Starting subtest: mmap
[253.519878] Subtest mmap: SUCCESS (0.024s)
[253.710125] [150/161] (777s left) vgem_basic (second-client)
[253.981403] Starting subtest: second-client
[254.040446] Subtest second-client: SUCCESS (0.032s)
[254.231332] [151/161] (777s left) vgem_basic (sysfs)
[254.510363] Starting subtest: sysfs
[254.538978] Subtest sysfs: SUCCESS (0.001s)
[254.712727] [152/161] (777s left) core_hotunplug (unbind-rebind)
[255.079837] Starting subtest: unbind-rebind
[259.764778] Subtest unbind-rebind: SUCCESS (4.684s)
[259.944494] [153/161] (772s left) vgem_basic (unload)
[260.170904] Starting subtest: unload
[289.520433] Subtest unload: SUCCESS (29.194s)
[289.694782] [154/161] (742s left) i915_module_load (reload)
[289.949947] Starting subtest: reload
[296.035586] Subtest reload: SUCCESS (6.114s)
[296.313942] [155/161] (736s left) gem_lmem_swapping (basic)
[296.633445] Subtest basic: SKIP (0.000s)
[296.805195] [156/161] (736s left) gem_lmem_swapping (parallel-random-engines)
[297.079066] Subtest parallel-random-engines: SKIP (0.000s)
[297.259129] [157/161] (735s left) gem_lmem_swapping (random-engines)
[297.545346] Subtest random-engines: SKIP (0.000s)
[297.745548] [158/161] (735s left) gem_lmem_swapping (verify-random)
[298.069190] Subtest verify-random: SKIP (0.000s)
[298.260655] [159/161] (735s left) i915_pm_rpm (module-reload)
[300.434320] Starting subtest: module-reload
[315.138593] Subtest module-reload: SUCCESS (14.706s)
[315.261342] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[315.265461] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[315.292385] [160/161] (718s left) i915_selftest (live)
[315.539900] Starting subtest: live
[315.562654] Starting dynamic subtest: sanitycheck
[319.391148] Dynamic subtest sanitycheck: SUCCESS (3.850s)
[319.432111] Starting dynamic subtest: uncore
[323.140355] Dynamic subtest uncore: SUCCESS (3.737s)
[323.188390] Starting dynamic subtest: workarounds
[327.448298] Dynamic subtest workarounds: SUCCESS (4.310s)
[327.492209] Starting dynamic subtest: gt_engines
[331.731089] Dynamic subtest gt_engines: SUCCESS (4.277s)
[331.772507] Starting dynamic subtest: gt_timelines
[356.246774] Dynamic subtest gt_timelines: SUCCESS (24.510s)
[356.305044] Starting dynamic subtest: gt_contexts
[360.219525] Dynamic subtest gt_contexts: SUCCESS (3.969s)
[360.264452] Starting dynamic subtest: gt_lrc
[367.075359] Dynamic subtest gt_lrc: SUCCESS (6.851s)
[367.116664] Starting dynamic subtest: gt_mocs
[370.993959] Dynamic subtest gt_mocs: SUCCESS (3.914s)
[371.050621] Starting dynamic subtest: gt_pm
[383.980826] Dynamic subtest gt_pm: SUCCESS (12.981s)
[384.023181] Starting dynamic subtest: gt_heartbeat
[387.808777] Dynamic subtest gt_heartbeat: SUCCESS (3.820s)
[387.857951] Starting dynamic subtest: gt_tlb
[392.372873] Dynamic subtest gt_tlb: SUCCESS (4.559s)
[392.415275] Starting dynamic subtest: requests
[402.605065] Dynamic subtest requests: SUCCESS (10.228s)
[402.646630] Starting dynamic subtest: migrate
[409.919595] Dynamic subtest migrate: SUCCESS (7.309s)
[409.977732] Starting dynamic subtest: active
[413.969544] Dynamic subtest active: SUCCESS (4.046s)
[414.029960] Starting dynamic subtest: objects
[420.371487] Dynamic subtest objects: SUCCESS (6.396s)
[420.430594] Starting dynamic subtest: mman
[430.397874] Dynamic subtest mman: SUCCESS (10.024s)
[430.458081] Starting dynamic subtest: dmabuf
[434.350156] Dynamic subtest dmabuf: SUCCESS (3.944s)
[434.393186] Starting dynamic subtest: vma
[438.186990] Dynamic subtest vma: SUCCESS (3.833s)
[438.229897] Starting dynamic subtest: coherency
[445.681310] Dynamic subtest coherency: SUCCESS (7.490s)
[445.722890] Starting dynamic subtest: gtt
[457.409285] Dynamic subtest gtt: SUCCESS (11.722s)
[457.467725] Starting dynamic subtest: gem
[463.012619] Dynamic subtest gem: SUCCESS (5.599s)
[463.055622] Starting dynamic subtest: evict
[472.085897] Dynamic subtest evict: SUCCESS (9.068s)
[472.168845] Starting dynamic subtest: hugepages
[483.004660] Dynamic subtest hugepages: SUCCESS (10.914s)
[483.050686] Starting dynamic subtest: gem_contexts
[509.464647] Dynamic subtest gem_contexts: SUCCESS (26.454s)
[509.506687] Starting dynamic subtest: client
[513.469370] Dynamic subtest client: SUCCESS (4.005s)
[513.529404] Starting dynamic subtest: gem_migrate
[517.482385] Dynamic subtest gem_migrate: SUCCESS (4.005s)
[517.527074] Starting dynamic subtest: reset
[531.834789] Dynamic subtest reset: SUCCESS (14.350s)
[531.882339] Starting dynamic subtest: memory_region
[535.812342] Dynamic subtest memory_region: SUCCESS (3.970s)
[535.854197] Starting dynamic subtest: hangcheck
[562.926103] Dynamic subtest hangcheck: SUCCESS (27.111s)
[562.984779] Starting dynamic subtest: execlists
[577.657792] Dynamic subtest execlists: SUCCESS (14.729s)
[577.702410] Starting dynamic subtest: ring_submission
[581.559335] Dynamic subtest ring_submission: SUCCESS (3.896s)
[581.600964] Starting dynamic subtest: perf
[585.522647] Dynamic subtest perf: SUCCESS (3.955s)
[585.571675] Starting dynamic subtest: slpc
[589.509444] Dynamic subtest slpc: SUCCESS (3.980s)
[589.557886] Starting dynamic subtest: guc
[593.505405] Dynamic subtest guc: SUCCESS (3.993s)
[593.550661] Starting dynamic subtest: guc_multi_lrc
[597.497121] Dynamic subtest guc_multi_lrc: SUCCESS (3.991s)
[597.538639] Starting dynamic subtest: guc_hang
[601.479510] Dynamic subtest guc_hang: SUCCESS (3.976s)
[601.538326] Starting dynamic subtest: late_gt_pm
[605.468063] Dynamic subtest late_gt_pm: SUCCESS (3.984s)
[605.491138] Subtest live: SUCCESS (289.920s)
[605.646747] [161/161] (428s left) dmabuf (all-tests)
[605.906190] Starting subtest: all-tests
[605.926752] Starting dynamic subtest: sanitycheck
[606.006103] Dynamic subtest sanitycheck: SUCCESS (0.095s)
[606.051512] Starting dynamic subtest: dma_fence
[606.250176] Dynamic subtest dma_fence: SUCCESS (0.241s)
[606.293871] Starting dynamic subtest: dma_fence_chain
[625.474661] Dynamic subtest dma_fence_chain: SUCCESS (19.222s)
[625.523842] Starting dynamic subtest: dma_fence_unwrap
[625.574428] Dynamic subtest dma_fence_unwrap: SUCCESS (0.084s)
[625.630045] Starting dynamic subtest: dma_resv
[625.678540] Dynamic subtest dma_resv: SUCCESS (0.097s)
[625.699398] Subtest all-tests: SUCCESS (19.761s)
[625.826689] Closing watchdogs
Done.