[38.876443] Initializing watchdogs
[38.876546]   /dev/watchdog0
[38.895308] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:5927 Intel Kabylake (Gen9) Iris Plus Graphics 650
[38.900613] [001/161] (960s left) i915_module_load (load)
[39.240998] Starting subtest: load
[42.113545] Subtest load: SUCCESS (2.872s)
[42.149402] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[42.151608] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[42.157107] [002/161] (957s left) core_auth (basic-auth)
[42.254457] Starting subtest: basic-auth
[42.255920] Subtest basic-auth: SUCCESS (0.001s)
[42.338480] [003/161] (957s left) debugfs_test (read_all_entries)
[42.407825] Starting subtest: read_all_entries
[42.514680] Subtest read_all_entries: SUCCESS (0.106s)
[42.839700] [004/161] (956s left) debugfs_test (basic-hwmon)
[42.904996] Starting subtest: basic-hwmon
[42.906205] Subtest basic-hwmon: SKIP (0.000s)
[43.236878] [005/161] (956s left) debugfs_test (sysfs)
[43.300526] Starting subtest: sysfs
[43.366863] Subtest sysfs: SUCCESS (0.067s)
[43.688235] [006/161] (955s left) fbdev (eof)
[43.747022] Starting subtest: eof
[43.747848] Subtest eof: SUCCESS (0.001s)
[43.821966] [007/161] (955s left) fbdev (info)
[43.875946] Starting subtest: info
[43.876602] Subtest info: SUCCESS (0.000s)
[43.952190] [008/161] (955s left) fbdev (nullptr)
[44.012982] Starting subtest: nullptr
[44.013658] Subtest nullptr: SUCCESS (0.000s)
[44.084285] [009/161] (955s left) fbdev (read)
[44.147312] Starting subtest: read
[44.376168] Subtest read: SUCCESS (0.230s)
[44.451474] [010/161] (955s left) fbdev (write)
[44.512919] Starting subtest: write
[44.636848] Subtest write: SUCCESS (0.124s)
[44.701087] [011/161] (955s left) gem_basic (bad-close)
[44.758728] Starting subtest: bad-close
[44.759916] Subtest bad-close: SUCCESS (0.000s)
[44.838141] [012/161] (955s left) gem_basic (create-close)
[44.894343] Starting subtest: create-close
[44.895725] Subtest create-close: SUCCESS (0.000s)
[45.024978] [013/161] (954s left) gem_basic (create-fd-close)
[45.083770] Starting subtest: create-fd-close
[45.085056] Subtest create-fd-close: SUCCESS (0.000s)
[45.203146] [014/161] (954s left) gem_busy (busy@all-engines)
[45.279272] Starting subtest: busy
[45.279986] Starting dynamic subtest: all-engines
[45.343964] Dynamic subtest all-engines: SUCCESS (0.065s)
[45.344793] Subtest busy: SUCCESS (0.066s)
[45.756733] [015/161] (954s left) gem_close_race (basic-process)
[45.835957] Starting subtest: basic-process
[45.902449] Subtest basic-process: SUCCESS (0.068s)
[46.155227] [016/161] (953s left) gem_close_race (basic-threads)
[46.229938] Starting subtest: basic-threads
[47.412413] Subtest basic-threads: SUCCESS (1.193s)
[47.650931] [017/161] (952s left) gem_ctx_create (basic)
[47.721584] Starting subtest: basic
[47.722110] Subtest basic: SUCCESS (0.000s)
[47.943770] [018/161] (952s left) gem_ctx_create (basic-files)
[48.014334] Starting subtest: basic-files
[50.032938] Subtest basic-files: SUCCESS (2.032s)
[50.280642] [019/161] (949s left) gem_ctx_exec (basic)
[50.339298] Starting subtest: basic
[50.342735] Subtest basic: SUCCESS (0.003s)
[50.474929] [020/161] (949s left) gem_exec_basic (basic)
[50.541046] Starting subtest: basic
[50.542161] Starting dynamic subtest: rcs0-smem
[50.542857] Dynamic subtest rcs0-smem: SUCCESS (0.001s)
[50.550836] Starting dynamic subtest: bcs0-smem
[50.551369] Dynamic subtest bcs0-smem: SUCCESS (0.001s)
[50.558586] Starting dynamic subtest: vcs0-smem
[50.559225] Dynamic subtest vcs0-smem: SUCCESS (0.000s)
[50.566084] Starting dynamic subtest: vcs1-smem
[50.566579] Dynamic subtest vcs1-smem: SUCCESS (0.000s)
[50.573295] Starting dynamic subtest: vecs0-smem
[50.573790] Dynamic subtest vecs0-smem: SUCCESS (0.000s)
[50.574307] Subtest basic: SUCCESS (0.005s)
[50.794188] [021/161] (949s left) gem_exec_create (basic)
[50.866803] Starting subtest: basic
[50.867290] Starting dynamic subtest: smem
[52.913065] Dynamic subtest smem: SUCCESS (2.056s)
[52.913835] Subtest basic: SUCCESS (2.056s)
[53.147895] [022/161] (947s left) gem_exec_fence (basic-busy)
[53.388151] Starting subtest: basic-busy
[53.389193] Starting dynamic subtest: rcs0
[53.417018] Dynamic subtest rcs0: SUCCESS (0.029s)
[53.426170] Starting dynamic subtest: bcs0
[53.442081] Dynamic subtest bcs0: SUCCESS (0.025s)
[53.451278] Starting dynamic subtest: vcs0
[53.463899] Dynamic subtest vcs0: SUCCESS (0.022s)
[53.473452] Starting dynamic subtest: vcs1
[53.486974] Dynamic subtest vcs1: SUCCESS (0.023s)
[53.497488] Starting dynamic subtest: vecs0
[53.513010] Dynamic subtest vecs0: SUCCESS (0.026s)
[53.513743] Subtest basic-busy: SUCCESS (0.126s)
[54.268848] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[54.271068] [023/161] (946s left) gem_exec_fence (basic-wait)
[54.484560] Starting subtest: basic-wait
[54.485858] Starting dynamic subtest: rcs0
[54.512094] Dynamic subtest rcs0: SUCCESS (0.028s)
[54.527256] Starting dynamic subtest: bcs0
[54.541238] Dynamic subtest bcs0: SUCCESS (0.031s)
[54.555391] Starting dynamic subtest: vcs0
[54.573088] Dynamic subtest vcs0: SUCCESS (0.032s)
[54.583439] Starting dynamic subtest: vcs1
[54.598026] Dynamic subtest vcs1: SUCCESS (0.025s)
[54.608359] Starting dynamic subtest: vecs0
[54.619047] Dynamic subtest vecs0: SUCCESS (0.021s)
[54.619974] Subtest basic-wait: SUCCESS (0.138s)
[55.329420] [024/161] (945s left) gem_exec_fence (basic-await)
[55.540163] Starting subtest: basic-await
[55.541648] Starting dynamic subtest: rcs0
[55.654929] Dynamic subtest rcs0: SUCCESS (0.116s)
[55.663427] Starting dynamic subtest: bcs0
[55.758643] Dynamic subtest bcs0: SUCCESS (0.103s)
[55.767869] Starting dynamic subtest: vcs0
[55.866510] Dynamic subtest vcs0: SUCCESS (0.108s)
[55.874629] Starting dynamic subtest: vcs1
[55.970855] Dynamic subtest vcs1: SUCCESS (0.104s)
[55.980194] Starting dynamic subtest: vecs0
[56.088887] Dynamic subtest vecs0: SUCCESS (0.118s)
[56.089711] Subtest basic-await: SUCCESS (0.551s)
[56.819294] [025/161] (943s left) gem_exec_fence (nb-await)
[57.047236] Starting subtest: nb-await
[57.048466] Starting dynamic subtest: rcs0
[57.116673] Dynamic subtest rcs0: SUCCESS (0.070s)
[57.129928] Starting dynamic subtest: bcs0
[57.185064] Dynamic subtest bcs0: SUCCESS (0.066s)
[57.223392] Starting dynamic subtest: vcs0
[57.275850] Dynamic subtest vcs0: SUCCESS (0.091s)
[57.306691] Starting dynamic subtest: vcs1
[57.358490] Dynamic subtest vcs1: SUCCESS (0.082s)
[57.383209] Starting dynamic subtest: vecs0
[57.441454] Dynamic subtest vecs0: SUCCESS (0.078s)
[57.444107] Subtest nb-await: SUCCESS (0.392s)
[58.152302] [026/161] (942s left) gem_exec_gttfill (basic)
[58.224103] Starting subtest: basic
[66.628087] Subtest basic: SUCCESS (8.414s)
[66.825431] [027/161] (933s left) gem_exec_parallel (engines)
[66.960874] Starting subtest: engines
[66.964183] Starting dynamic subtest: basic
[69.345992] Dynamic subtest basic: SUCCESS (2.386s)
[69.355746] Starting dynamic subtest: contexts
[75.648294] Dynamic subtest contexts: SUCCESS (6.304s)
[75.657224] Starting dynamic subtest: fds
[81.547182] Dynamic subtest fds: SUCCESS (5.898s)
[81.556464] Starting dynamic subtest: userptr
[84.476018] Dynamic subtest userptr: SUCCESS (2.928s)
[84.476905] Subtest engines: SUCCESS (17.518s)
[84.727766] [028/161] (915s left) gem_exec_store (basic)
[84.801763] Starting subtest: basic
[84.867265] Subtest basic: SUCCESS (0.065s)
[85.114053] [029/161] (915s left) gem_flink_basic (bad-flink)
[85.170692] Starting subtest: bad-flink
[85.171745] Subtest bad-flink: SUCCESS (0.000s)
[85.268087] [030/161] (915s left) gem_flink_basic (bad-open)
[85.324557] Starting subtest: bad-open
[85.325165] Subtest bad-open: SUCCESS (0.000s)
[85.422179] [031/161] (915s left) gem_flink_basic (basic)
[85.481360] Starting subtest: basic
[85.482021] Subtest basic: SUCCESS (0.000s)
[85.594367] [032/161] (915s left) gem_flink_basic (double-flink)
[85.651925] Starting subtest: double-flink
[85.652673] Subtest double-flink: SUCCESS (0.000s)
[85.810052] [033/161] (915s left) gem_flink_basic (flink-lifetime)
[85.870177] Starting subtest: flink-lifetime
[85.870902] Subtest flink-lifetime: SUCCESS (0.001s)
[85.982269] [034/161] (915s left) gem_huc_copy (huc-copy)
[86.053346] Starting subtest: huc-copy
[86.054736] Subtest huc-copy: SKIP (0.000s)
[86.224571] [035/161] (914s left) gem_linear_blits (basic)
[86.393660] Starting subtest: basic
[86.403169] Subtest basic: SUCCESS (0.010s)
[86.595306] [036/161] (914s left) gem_mmap (basic)
[86.728679] Starting subtest: basic
[86.731568] Subtest basic: SUCCESS (0.000s)
[86.841084] [037/161] (914s left) gem_mmap_gtt (basic)
[86.897829] Starting subtest: basic
[86.898585] Subtest basic: SUCCESS (0.001s)
[86.974927] [038/161] (914s left) gem_render_linear_blits (basic)
[87.040612] Starting subtest: basic
[87.072844] Subtest basic: SUCCESS (0.033s)
[87.142689] [039/161] (914s left) gem_render_tiled_blits (basic)
[87.204895] Starting subtest: basic
[87.241930] Subtest basic: SUCCESS (0.037s)
[87.309513] [040/161] (914s left) gem_ringfill (basic-all)
[87.562167] Starting subtest: basic-all
[88.731493] Subtest basic-all: SUCCESS (1.170s)
[88.898242] [041/161] (912s left) gem_softpin (allocator-basic)
[88.962265] Starting subtest: allocator-basic
[89.081195] Subtest allocator-basic: SUCCESS (0.116s)
[89.205013] [042/161] (912s left) gem_softpin (allocator-basic-reserve)
[89.269241] Starting subtest: allocator-basic-reserve
[89.381037] Subtest allocator-basic-reserve: SUCCESS (0.112s)
[89.514898] [043/161] (912s left) gem_softpin (safe-alignment)
[89.579087] Starting subtest: safe-alignment
[89.593498] Subtest safe-alignment: SUCCESS (0.014s)
[89.781765] [044/161] (911s left) gem_sync (basic-all)
[89.855159] Starting subtest: basic-all
[92.007482] Subtest basic-all: SUCCESS (2.152s)
[92.270564] [045/161] (909s left) gem_sync (basic-each)
[92.343847] Starting subtest: basic-each
[94.535425] Subtest basic-each: SUCCESS (2.191s)
[94.830253] [046/161] (906s left) gem_tiled_blits (basic)
[94.959942] Starting subtest: basic
[94.984717] Subtest basic: SUCCESS (0.024s)
[95.184497] [047/161] (906s left) gem_tiled_fence_blits (basic)
[95.386238] Starting subtest: basic
[95.397400] Subtest basic: SUCCESS (0.011s)
[95.588248] [048/161] (906s left) gem_tiled_pread_basic
[95.922915] [049/161] (906s left) gem_wait (busy@all-engines)
[96.003641] Starting subtest: busy
[96.004256] Starting dynamic subtest: all-engines
[96.520162] Dynamic subtest all-engines: SUCCESS (0.516s)
[96.522197] Subtest busy: SUCCESS (0.517s)
[96.811827] [050/161] (905s left) gem_wait (wait@all-engines)
[96.890759] Starting subtest: wait
[96.891448] Starting dynamic subtest: all-engines
[97.926632] Dynamic subtest all-engines: SUCCESS (1.034s)
[97.929341] Subtest wait: SUCCESS (1.035s)
[98.251273] [051/161] (903s left) i915_getparams_basic (basic-eu-total)
[98.307780] Starting subtest: basic-eu-total
[98.309046] Subtest basic-eu-total: SUCCESS (0.000s)
[98.403989] [052/161] (903s left) i915_getparams_basic (basic-subslice-total)
[98.459452] Starting subtest: basic-subslice-total
[98.460757] Subtest basic-subslice-total: SUCCESS (0.000s)
[98.558515] [053/161] (903s left) i915_hangman (error-state-basic)
[98.630645] Starting subtest: error-state-basic
[98.638154] Subtest error-state-basic: SUCCESS (0.007s)
[98.733102] [054/161] (903s left) i915_pciid
[98.900241] [055/161] (903s left) kms_addfb_basic (addfb25-4-tiled)
[99.195169] Starting subtest: addfb25-4-tiled
[99.196301] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[99.357521] [056/161] (903s left) kms_addfb_basic (addfb25-bad-modifier)
[99.645626] Starting subtest: addfb25-bad-modifier
[99.646288] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[99.770740] [057/161] (902s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[100.057948] Starting subtest: addfb25-framebuffer-vs-set-tiling
[100.058828] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[100.198232] [058/161] (902s left) kms_addfb_basic (addfb25-modifier-no-flag)
[100.485538] Starting subtest: addfb25-modifier-no-flag
[100.486239] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[100.602977] [059/161] (901s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[100.893544] Starting subtest: addfb25-x-tiled-legacy
[100.894111] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[101.022531] [060/161] (901s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[101.305325] Starting subtest: addfb25-x-tiled-mismatch-legacy
[101.305963] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[101.457126] [061/161] (901s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[101.741879] Starting subtest: addfb25-yf-tiled-legacy
[101.742865] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[101.884935] [062/161] (900s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[102.172259] Starting subtest: addfb25-y-tiled-legacy
[102.173298] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[102.307785] [063/161] (900s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[102.593366] Starting subtest: addfb25-y-tiled-small-legacy
[102.593971] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s)
[102.723767] [064/161] (900s left) kms_addfb_basic (bad-pitch-0)
[102.783821] Starting subtest: bad-pitch-0
[102.784515] Subtest bad-pitch-0: SUCCESS (0.000s)
[103.158349] [065/161] (899s left) kms_addfb_basic (bad-pitch-1024)
[103.215683] Starting subtest: bad-pitch-1024
[103.216336] Subtest bad-pitch-1024: SUCCESS (0.000s)
[103.573362] [066/161] (899s left) kms_addfb_basic (bad-pitch-128)
[103.632357] Starting subtest: bad-pitch-128
[103.632992] Subtest bad-pitch-128: SUCCESS (0.000s)
[104.000317] [067/161] (899s left) kms_addfb_basic (bad-pitch-256)
[104.057419] Starting subtest: bad-pitch-256
[104.058108] Subtest bad-pitch-256: SUCCESS (0.000s)
[104.428192] [068/161] (898s left) kms_addfb_basic (bad-pitch-32)
[104.484690] Starting subtest: bad-pitch-32
[104.485299] Subtest bad-pitch-32: SUCCESS (0.000s)
[104.849124] [069/161] (898s left) kms_addfb_basic (bad-pitch-63)
[104.906803] Starting subtest: bad-pitch-63
[104.907404] Subtest bad-pitch-63: SUCCESS (0.000s)
[105.300797] [070/161] (898s left) kms_addfb_basic (bad-pitch-65536)
[105.359410] Starting subtest: bad-pitch-65536
[105.360243] Subtest bad-pitch-65536: SUCCESS (0.000s)
[105.735825] [071/161] (897s left) kms_addfb_basic (bad-pitch-999)
[105.793260] Starting subtest: bad-pitch-999
[105.793953] Subtest bad-pitch-999: SUCCESS (0.000s)
[106.162249] [072/161] (897s left) kms_addfb_basic (basic)
[106.219555] Starting subtest: basic
[106.220189] Subtest basic: SUCCESS (0.000s)
[106.582962] [073/161] (896s left) kms_addfb_basic (basic-x-tiled-legacy)
[106.639954] Starting subtest: basic-x-tiled-legacy
[106.640466] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[107.016907] [074/161] (896s left) kms_addfb_basic (basic-y-tiled-legacy)
[107.074265] Starting subtest: basic-y-tiled-legacy
[107.074897] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[107.459687] [075/161] (896s left) kms_addfb_basic (bo-too-small)
[107.747487] Starting subtest: bo-too-small
[107.748323] Subtest bo-too-small: SUCCESS (0.000s)
[107.879044] [076/161] (895s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[108.162447] Starting subtest: bo-too-small-due-to-tiling
[108.163136] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[108.290397] [077/161] (895s left) kms_addfb_basic (clobberred-modifier)
[108.346545] Starting subtest: clobberred-modifier
[108.347205] Subtest clobberred-modifier: SUCCESS (0.000s)
[108.726208] [078/161] (895s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[108.785857] Starting subtest: framebuffer-vs-set-tiling
[108.786555] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[109.139036] [079/161] (894s left) kms_addfb_basic (invalid-get-prop)
[109.196812] Starting subtest: invalid-get-prop
[109.197477] Subtest invalid-get-prop: SUCCESS (0.000s)
[109.579892] [080/161] (894s left) kms_addfb_basic (invalid-get-prop-any)
[109.639636] Starting subtest: invalid-get-prop-any
[109.640334] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[110.008235] [081/161] (894s left) kms_addfb_basic (invalid-set-prop)
[110.066081] Starting subtest: invalid-set-prop
[110.066585] Subtest invalid-set-prop: SUCCESS (0.000s)
[110.429240] [082/161] (893s left) kms_addfb_basic (invalid-set-prop-any)
[110.487738] Starting subtest: invalid-set-prop-any
[110.488344] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[110.869463] [083/161] (893s left) kms_addfb_basic (no-handle)
[110.926657] Starting subtest: no-handle
[110.927388] Subtest no-handle: SUCCESS (0.000s)
[111.294128] [084/161] (892s left) kms_addfb_basic (size-max)
[111.583610] Starting subtest: size-max
[111.584224] Subtest size-max: SUCCESS (0.000s)
[111.714250] [085/161] (892s left) kms_addfb_basic (small-bo)
[112.001834] Starting subtest: small-bo
[112.002469] Subtest small-bo: SUCCESS (0.000s)
[112.160153] [086/161] (892s left) kms_addfb_basic (tile-pitch-mismatch)
[112.221265] Starting subtest: tile-pitch-mismatch
[112.221945] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[112.593262] [087/161] (891s left) kms_addfb_basic (too-high)
[112.876628] Starting subtest: too-high
[112.878056] Subtest too-high: SUCCESS (0.001s)
[113.034923] [088/161] (891s left) kms_addfb_basic (too-wide)
[113.317671] Starting subtest: too-wide
[113.318350] Subtest too-wide: SUCCESS (0.000s)
[113.460548] [089/161] (891s left) kms_addfb_basic (unused-handle)
[113.518466] Starting subtest: unused-handle
[113.519187] Subtest unused-handle: SUCCESS (0.000s)
[113.912532] [090/161] (890s left) kms_addfb_basic (unused-modifier)
[113.971977] Starting subtest: unused-modifier
[113.972713] Subtest unused-modifier: SUCCESS (0.000s)
[114.345270] [091/161] (890s left) kms_addfb_basic (unused-offsets)
[114.403452] Starting subtest: unused-offsets
[114.404146] Subtest unused-offsets: SUCCESS (0.000s)
[114.780269] [092/161] (889s left) kms_addfb_basic (unused-pitches)
[114.838540] Starting subtest: unused-pitches
[114.839167] Subtest unused-pitches: SUCCESS (0.000s)
[115.218162] [093/161] (889s left) kms_busy (basic)
[115.524384] Starting subtest: basic
[115.525912] Starting dynamic subtest: flip
[116.193152] Dynamic subtest flip: SUCCESS (0.664s)
[116.208748] Starting dynamic subtest: modeset
[117.332950] Dynamic subtest modeset: SUCCESS (1.139s)
[117.333793] Subtest basic: SUCCESS (1.807s)
[117.664709] [094/161] (887s left) kms_prop_blob (basic)
[117.724382] Starting subtest: basic
[117.725239] Subtest basic: SUCCESS (0.000s)
[117.829224] [095/161] (887s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[118.117230] Starting subtest: basic-busy-flip-before-cursor-atomic
[118.118511] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[118.216245] [096/161] (886s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[118.502496] Starting subtest: basic-busy-flip-before-cursor-legacy
[118.503733] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[118.594959] [097/161] (886s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[118.876097] Starting subtest: basic-flip-after-cursor-atomic
[119.813815] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.936s)
[120.150697] [098/161] (885s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[120.439387] Starting subtest: basic-flip-after-cursor-legacy
[121.376108] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.935s)
[121.689306] [099/161] (883s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[121.980212] Starting subtest: basic-flip-after-cursor-varying-size
[122.916571] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.936s)
[123.230164] [100/161] (882s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[123.511318] Starting subtest: basic-flip-before-cursor-atomic
[124.459629] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.947s)
[124.801333] [101/161] (880s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[125.093751] Starting subtest: basic-flip-before-cursor-legacy
[126.041890] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.943s)
[126.405925] [102/161] (879s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[126.689137] Starting subtest: basic-flip-before-cursor-varying-size
[127.640112] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.946s)
[128.059501] [103/161] (877s left) kms_dsc (dsc-basic)
[128.347927] Subtest dsc-basic: SKIP (0.000s)
[128.401589] [104/161] (877s left) kms_flip (basic-flip-vs-dpms)
[128.691539] Starting subtest: basic-flip-vs-dpms
[128.692639] Starting dynamic subtest: A-DP1
[129.693310] Dynamic subtest A-DP1: SUCCESS (0.997s)
[129.717224] Starting dynamic subtest: B-DP1
[130.921234] Dynamic subtest B-DP1: SUCCESS (1.226s)
[130.949933] Starting dynamic subtest: C-DP1
[131.930195] Dynamic subtest C-DP1: SUCCESS (1.006s)
[131.933995] Subtest basic-flip-vs-dpms: SUCCESS (3.242s)
[132.334175] [105/161] (873s left) kms_flip (basic-flip-vs-modeset)
[132.622674] Starting subtest: basic-flip-vs-modeset
[132.624067] Starting dynamic subtest: A-DP1
[133.865584] Dynamic subtest A-DP1: SUCCESS (1.238s)
[133.882385] Starting dynamic subtest: B-DP1
[134.876973] Dynamic subtest B-DP1: SUCCESS (1.010s)
[134.891624] Starting dynamic subtest: C-DP1
[135.962979] Dynamic subtest C-DP1: SUCCESS (1.083s)
[135.967368] Subtest basic-flip-vs-modeset: SUCCESS (3.344s)
[136.342137] [106/161] (869s left) kms_flip (basic-flip-vs-wf_vblank)
[136.630862] Starting subtest: basic-flip-vs-wf_vblank
[136.632493] Starting dynamic subtest: A-DP1
[137.915384] Dynamic subtest A-DP1: SUCCESS (1.281s)
[137.929973] Starting dynamic subtest: B-DP1
[139.197530] Dynamic subtest B-DP1: SUCCESS (1.282s)
[139.216254] Starting dynamic subtest: C-DP1
[140.476474] Dynamic subtest C-DP1: SUCCESS (1.277s)
[140.478865] Subtest basic-flip-vs-wf_vblank: SUCCESS (3.848s)
[140.855573] [107/161] (865s left) kms_flip (basic-plain-flip)
[141.140717] Starting subtest: basic-plain-flip
[141.142239] Starting dynamic subtest: A-DP1
[142.147236] Dynamic subtest A-DP1: SUCCESS (1.005s)
[142.159973] Starting dynamic subtest: B-DP1
[143.143198] Dynamic subtest B-DP1: SUCCESS (0.994s)
[143.155911] Starting dynamic subtest: C-DP1
[144.124762] Dynamic subtest C-DP1: SUCCESS (0.978s)
[144.139473] Subtest basic-plain-flip: SUCCESS (2.993s)
[144.660748] [108/161] (861s left) kms_force_connector_basic (force-connector-state)
[144.911275] Starting subtest: force-connector-state
[145.701923] Subtest force-connector-state: SUCCESS (0.790s)
[146.514639] [109/161] (859s left) kms_force_connector_basic (force-edid)
[146.785579] Starting subtest: force-edid
[147.208964] Subtest force-edid: SUCCESS (0.422s)
[147.888910] [110/161] (858s left) kms_force_connector_basic (force-load-detect)
[148.144265] Starting subtest: force-load-detect
[148.146422] Subtest force-load-detect: SKIP (0.000s)
[148.594285] [111/161] (857s left) kms_force_connector_basic (prune-stale-modes)
[148.841243] Starting subtest: prune-stale-modes
[149.300992] Subtest prune-stale-modes: SUCCESS (0.453s)
[149.995464] [112/161] (856s left) kms_frontbuffer_tracking (basic)
[150.286664] Starting subtest: basic
[153.029997] Subtest basic: SUCCESS (2.738s)
[153.213451] [113/161] (853s left) kms_hdmi_inject (inject-audio)
[153.328818] Starting subtest: inject-audio
[153.855497] Subtest inject-audio: SUCCESS (0.519s)
[154.468640] [114/161] (851s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[154.759662] Starting subtest: compare-crc-sanitycheck-xr24
[154.760209] Starting dynamic subtest: pipe-A-DP-1
[155.245785] Dynamic subtest pipe-A-DP-1: SUCCESS (0.484s)
[155.262302] Starting dynamic subtest: pipe-B-DP-1
[155.819386] Dynamic subtest pipe-B-DP-1: SUCCESS (0.568s)
[155.842021] Starting dynamic subtest: pipe-C-DP-1
[156.415778] Dynamic subtest pipe-C-DP-1: SUCCESS (0.587s)
[156.417103] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.656s)
[156.781854] [115/161] (849s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[157.069704] Starting subtest: compare-crc-sanitycheck-nv12
[157.071735] Starting dynamic subtest: pipe-A-DP-1
[157.605455] Dynamic subtest pipe-A-DP-1: SUCCESS (0.531s)
[157.617016] Starting dynamic subtest: pipe-B-DP-1
[158.208914] Dynamic subtest pipe-B-DP-1: SUCCESS (0.601s)
[158.230358] Starting dynamic subtest: pipe-C-DP-1
[158.231726] Dynamic subtest pipe-C-DP-1: SKIP (0.001s)
[158.232421] Subtest compare-crc-sanitycheck-nv12: SUCCESS (1.147s)
[158.550995] [116/161] (848s left) kms_pipe_crc_basic (hang-read-crc)
[158.842204] Starting subtest: hang-read-crc
[158.843405] Starting dynamic subtest: pipe-A-DP-1
[160.184373] Dynamic subtest pipe-A-DP-1: SUCCESS (1.338s)
[160.226757] Starting dynamic subtest: pipe-B-DP-1
[161.627239] Dynamic subtest pipe-B-DP-1: SUCCESS (1.431s)
[161.647373] Starting dynamic subtest: pipe-C-DP-1
[163.081410] Dynamic subtest pipe-C-DP-1: SUCCESS (1.448s)
[163.082522] Subtest hang-read-crc: SUCCESS (4.240s)
[163.421707] [117/161] (843s left) kms_pipe_crc_basic (nonblocking-crc)
[163.711704] Starting subtest: nonblocking-crc
[163.713941] Starting dynamic subtest: pipe-A-DP-1
[164.414144] Dynamic subtest pipe-A-DP-1: SUCCESS (0.698s)
[164.426641] Starting dynamic subtest: pipe-B-DP-1
[165.188581] Dynamic subtest pipe-B-DP-1: SUCCESS (0.771s)
[165.206210] Starting dynamic subtest: pipe-C-DP-1
[165.972960] Dynamic subtest pipe-C-DP-1: SUCCESS (0.779s)
[165.973993] Subtest nonblocking-crc: SUCCESS (2.262s)
[166.312150] [118/161] (840s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[166.600202] Starting subtest: nonblocking-crc-frame-sequence
[166.602257] Starting dynamic subtest: pipe-A-DP-1
[167.296501] Dynamic subtest pipe-A-DP-1: SUCCESS (0.688s)
[167.310024] Starting dynamic subtest: pipe-B-DP-1
[167.976502] Dynamic subtest pipe-B-DP-1: SUCCESS (0.679s)
[167.987035] Starting dynamic subtest: pipe-C-DP-1
[168.759293] Dynamic subtest pipe-C-DP-1: SUCCESS (0.780s)
[168.760402] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.160s)
[169.091595] [119/161] (837s left) kms_pipe_crc_basic (read-crc)
[169.383845] Starting subtest: read-crc
[169.384817] Starting dynamic subtest: pipe-A-DP-1
[170.012286] Dynamic subtest pipe-A-DP-1: SUCCESS (0.625s)
[170.028873] Starting dynamic subtest: pipe-B-DP-1
[170.721270] Dynamic subtest pipe-B-DP-1: SUCCESS (0.704s)
[170.741530] Starting dynamic subtest: pipe-C-DP-1
[171.451499] Dynamic subtest pipe-C-DP-1: SUCCESS (0.723s)
[171.455182] Subtest read-crc: SUCCESS (2.071s)
[171.809758] [120/161] (835s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[172.099920] Starting subtest: read-crc-frame-sequence
[172.100630] Starting dynamic subtest: pipe-A-DP-1
[172.718899] Dynamic subtest pipe-A-DP-1: SUCCESS (0.616s)
[172.741757] Starting dynamic subtest: pipe-B-DP-1
[173.340827] Dynamic subtest pipe-B-DP-1: SUCCESS (0.618s)
[173.355673] Starting dynamic subtest: pipe-C-DP-1
[174.042197] Dynamic subtest pipe-C-DP-1: SUCCESS (0.698s)
[174.042950] Subtest read-crc-frame-sequence: SUCCESS (1.944s)
[174.372711] [121/161] (832s left) kms_pm_backlight (basic-brightness)
[174.666807] Subtest basic-brightness: SKIP (0.000s)
[174.728744] [122/161] (832s left) kms_pm_rpm (basic-pci-d3-state)
[176.474978] Starting subtest: basic-pci-d3-state
[177.094672] Subtest basic-pci-d3-state: SUCCESS (0.628s)
[177.482921] [123/161] (829s left) kms_pm_rpm (basic-rte)
[177.540295] Starting subtest: basic-rte
[179.878171] Subtest basic-rte: SUCCESS (2.335s)
[180.964680] [124/161] (826s left) kms_psr (psr-primary-page-flip)
[181.255283] Subtest psr-primary-page-flip: SKIP (0.000s)
[181.314744] [125/161] (826s left) kms_psr (psr-cursor-plane-move)
[181.598878] Subtest psr-cursor-plane-move: SKIP (0.000s)
[181.664904] [126/161] (825s left) kms_psr (psr-sprite-plane-onoff)
[181.952547] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[182.014608] [127/161] (825s left) kms_psr (psr-primary-mmap-gtt)
[182.302478] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[182.364477] [128/161] (825s left) kms_setmode (basic-clone-single-crtc)
[182.427144] Starting subtest: basic-clone-single-crtc
[182.429245] Subtest basic-clone-single-crtc: SKIP (0.000s)
[182.521157] [129/161] (825s left) i915_pm_rps (basic-api)
[182.588912] Starting subtest: basic-api
[182.591528] Subtest basic-api: SUCCESS (0.005s)
[182.714989] [130/161] (825s left) prime_self_import (basic-llseek-bad)
[182.773231] Starting subtest: basic-llseek-bad
[182.774441] Subtest basic-llseek-bad: SUCCESS (0.002s)
[182.935813] [131/161] (824s left) prime_self_import (basic-llseek-size)
[182.994822] Starting subtest: basic-llseek-size
[182.997967] Subtest basic-llseek-size: SUCCESS (0.004s)
[183.086454] [132/161] (824s left) prime_self_import (basic-with_fd_dup)
[183.141814] Starting subtest: basic-with_fd_dup
[183.145838] Subtest basic-with_fd_dup: SUCCESS (0.003s)
[183.269466] [133/161] (824s left) prime_self_import (basic-with_one_bo)
[183.326650] Starting subtest: basic-with_one_bo
[183.329992] Subtest basic-with_one_bo: SUCCESS (0.003s)
[183.414286] [134/161] (824s left) prime_self_import (basic-with_one_bo_two_files)
[183.471052] Starting subtest: basic-with_one_bo_two_files
[183.474657] Subtest basic-with_one_bo_two_files: SUCCESS (0.003s)
[183.600991] [135/161] (824s left) prime_self_import (basic-with_two_bos)
[183.658436] Starting subtest: basic-with_two_bos
[183.661629] Subtest basic-with_two_bos: SUCCESS (0.003s)
[183.781904] [136/161] (824s left) prime_vgem (basic-fence-flip)
[183.864881] Starting subtest: basic-fence-flip
[184.593743] Subtest basic-fence-flip: SUCCESS (0.725s)
[184.727894] [137/161] (823s left) prime_vgem (basic-fence-mmap)
[184.798315] Starting subtest: basic-fence-mmap
[184.826947] Subtest basic-fence-mmap: SUCCESS (0.029s)
[184.926175] [138/161] (823s left) prime_vgem (basic-fence-read)
[184.997930] Starting subtest: basic-fence-read
[185.054804] Subtest basic-fence-read: SUCCESS (0.057s)
[185.159948] [139/161] (823s left) prime_vgem (basic-gtt)
[185.268730] Starting subtest: basic-gtt
[185.289326] Subtest basic-gtt: SUCCESS (0.020s)
[185.423841] [140/161] (822s left) prime_vgem (basic-read)
[185.521570] Starting subtest: basic-read
[185.550305] Subtest basic-read: SUCCESS (0.032s)
[185.688115] [141/161] (822s left) prime_vgem (basic-write)
[185.773633] Starting subtest: basic-write
[185.803443] Subtest basic-write: SUCCESS (0.030s)
[185.902627] [142/161] (822s left) vgem_basic (setversion)
[185.976929] Starting subtest: setversion
[185.978756] Subtest setversion: SUCCESS (0.000s)
[186.052670] [143/161] (822s left) vgem_basic (create)
[186.125985] Starting subtest: create
[186.126672] Subtest create: SUCCESS (0.000s)
[186.210973] [144/161] (822s left) vgem_basic (debugfs)
[186.275739] Starting subtest: debugfs
[186.276384] Subtest debugfs: SUCCESS (0.001s)
[186.352818] [145/161] (822s left) vgem_basic (dmabuf-export)
[186.425958] Starting subtest: dmabuf-export
[186.427259] Subtest dmabuf-export: SUCCESS (0.002s)
[186.566515] [146/161] (822s left) vgem_basic (dmabuf-fence)
[186.625490] Starting subtest: dmabuf-fence
[186.626648] Subtest dmabuf-fence: SUCCESS (0.001s)
[186.701854] [147/161] (821s left) vgem_basic (dmabuf-fence-before)
[186.776893] Starting subtest: dmabuf-fence-before
[186.777788] Subtest dmabuf-fence-before: SUCCESS (0.000s)
[186.858648] [148/161] (821s left) vgem_basic (dmabuf-mmap)
[186.925787] Starting subtest: dmabuf-mmap
[186.935583] Subtest dmabuf-mmap: SUCCESS (0.011s)
[187.025383] [149/161] (821s left) vgem_basic (mmap)
[187.092448] Starting subtest: mmap
[187.100308] Subtest mmap: SUCCESS (0.009s)
[187.169714] [150/161] (821s left) vgem_basic (second-client)
[187.241946] Starting subtest: second-client
[187.275737] Subtest second-client: SUCCESS (0.032s)
[187.396763] [151/161] (821s left) vgem_basic (sysfs)
[187.459117] Starting subtest: sysfs
[187.459810] Subtest sysfs: SUCCESS (0.001s)
[187.566636] [152/161] (821s left) core_hotunplug (unbind-rebind)
[187.742965] Starting subtest: unbind-rebind
[192.518982] Subtest unbind-rebind: SUCCESS (4.778s)
[192.570423] [153/161] (816s left) vgem_basic (unload)
[192.631286] Starting subtest: unload
[221.779437] Subtest unload: SUCCESS (29.148s)
[221.913285] [154/161] (787s left) i915_module_load (reload)
[221.973556] Starting subtest: reload
[225.711854] Subtest reload: SUCCESS (3.740s)
[225.793200] [155/161] (783s left) gem_lmem_swapping (basic)
[225.879218] Subtest basic: SKIP (0.000s)
[225.936873] [156/161] (783s left) gem_lmem_swapping (parallel-random-engines)
[226.002332] Subtest parallel-random-engines: SKIP (0.000s)
[226.067906] [157/161] (783s left) gem_lmem_swapping (random-engines)
[226.132498] Subtest random-engines: SKIP (0.000s)
[226.184934] [158/161] (783s left) gem_lmem_swapping (verify-random)
[226.252148] Subtest verify-random: SKIP (0.000s)
[226.318565] [159/161] (783s left) i915_pm_rpm (module-reload)
[229.808464] Starting subtest: module-reload
[245.127786] Subtest module-reload: SUCCESS (15.320s)
[245.172924] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[245.174351] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[245.176674] [160/161] (764s left) i915_selftest (live)
[245.244529] Starting subtest: live
[245.245196] Starting dynamic subtest: sanitycheck
[247.714188] Dynamic subtest sanitycheck: SUCCESS (2.467s)
[247.726903] Starting dynamic subtest: uncore
[250.228430] Dynamic subtest uncore: SUCCESS (2.514s)
[250.239444] Starting dynamic subtest: workarounds
[253.034834] Dynamic subtest workarounds: SUCCESS (2.804s)
[253.056035] Starting dynamic subtest: gt_engines
[255.999269] Dynamic subtest gt_engines: SUCCESS (2.960s)
[256.010558] Starting dynamic subtest: gt_timelines
[268.232762] Dynamic subtest gt_timelines: SUCCESS (12.233s)
[268.246146] Starting dynamic subtest: gt_contexts
[270.784314] Dynamic subtest gt_contexts: SUCCESS (2.549s)
[270.795233] Starting dynamic subtest: gt_lrc
[276.392419] Dynamic subtest gt_lrc: SUCCESS (5.605s)
[276.410578] Starting dynamic subtest: gt_mocs
[278.935524] Dynamic subtest gt_mocs: SUCCESS (2.541s)
[278.949199] Starting dynamic subtest: gt_pm
[289.959723] Dynamic subtest gt_pm: SUCCESS (11.021s)
[289.973778] Starting dynamic subtest: gt_heartbeat
[292.453580] Dynamic subtest gt_heartbeat: SUCCESS (2.492s)
[292.468778] Starting dynamic subtest: gt_tlb
[296.025756] Dynamic subtest gt_tlb: SUCCESS (3.570s)
[296.040398] Starting dynamic subtest: requests
[305.906762] Dynamic subtest requests: SUCCESS (9.878s)
[305.919395] Starting dynamic subtest: migrate
[311.311501] Dynamic subtest migrate: SUCCESS (5.402s)
[311.321942] Starting dynamic subtest: active
[313.791633] Dynamic subtest active: SUCCESS (2.478s)
[313.803943] Starting dynamic subtest: objects
[316.927148] Dynamic subtest objects: SUCCESS (3.132s)
[316.935236] Starting dynamic subtest: mman
[322.846631] Dynamic subtest mman: SUCCESS (5.919s)
[322.862454] Starting dynamic subtest: dmabuf
[325.431743] Dynamic subtest dmabuf: SUCCESS (2.583s)
[325.449704] Starting dynamic subtest: vma
[327.933181] Dynamic subtest vma: SUCCESS (2.497s)
[327.941364] Starting dynamic subtest: coherency
[331.261639] Dynamic subtest coherency: SUCCESS (3.328s)
[331.275287] Starting dynamic subtest: gtt
[341.039731] Dynamic subtest gtt: SUCCESS (9.775s)
[341.053653] Starting dynamic subtest: gem
[344.049559] Dynamic subtest gem: SUCCESS (3.008s)
[344.060864] Starting dynamic subtest: evict
[348.112422] Dynamic subtest evict: SUCCESS (4.061s)
[348.130196] Starting dynamic subtest: hugepages
[356.541247] Dynamic subtest hugepages: SUCCESS (8.425s)
[356.558527] Starting dynamic subtest: gem_contexts
[374.726926] Dynamic subtest gem_contexts: SUCCESS (18.183s)
[374.741790] Starting dynamic subtest: client
[377.266168] Dynamic subtest client: SUCCESS (2.535s)
[377.276071] Starting dynamic subtest: gem_migrate
[379.761708] Dynamic subtest gem_migrate: SUCCESS (2.493s)
[379.772599] Starting dynamic subtest: reset
[382.481407] Dynamic subtest reset: SUCCESS (2.716s)
[382.496261] Starting dynamic subtest: memory_region
[384.932548] Dynamic subtest memory_region: SUCCESS (2.450s)
[384.943763] Starting dynamic subtest: hangcheck
[416.124967] Dynamic subtest hangcheck: SUCCESS (31.189s)
[416.140647] Starting dynamic subtest: execlists
[431.438552] Dynamic subtest execlists: SUCCESS (15.311s)
[431.458429] Starting dynamic subtest: ring_submission
[433.874367] Dynamic subtest ring_submission: SUCCESS (2.433s)
[433.883751] Starting dynamic subtest: perf
[436.500860] Dynamic subtest perf: SUCCESS (2.624s)
[436.519501] Starting dynamic subtest: slpc
[438.973604] Dynamic subtest slpc: SUCCESS (2.470s)
[438.985026] Starting dynamic subtest: guc
[441.427537] Dynamic subtest guc: SUCCESS (2.451s)
[441.442068] Starting dynamic subtest: guc_multi_lrc
[443.912591] Dynamic subtest guc_multi_lrc: SUCCESS (2.482s)
[443.922290] Starting dynamic subtest: guc_hang
[446.320326] Dynamic subtest guc_hang: SUCCESS (2.406s)
[446.332395] Starting dynamic subtest: late_gt_pm
[448.807848] Dynamic subtest late_gt_pm: SUCCESS (2.485s)
[448.810801] Subtest live: SUCCESS (203.558s)
[448.857305] [161/161] (560s left) dmabuf (all-tests)
[448.923101] Starting subtest: all-tests
[448.925893] Starting dynamic subtest: sanitycheck
[449.023755] Dynamic subtest sanitycheck: SUCCESS (0.098s)
[449.062424] Starting dynamic subtest: dma_fence
[449.246332] Dynamic subtest dma_fence: SUCCESS (0.215s)
[449.261260] Starting dynamic subtest: dma_fence_chain
[460.812166] Dynamic subtest dma_fence_chain: SUCCESS (11.560s)
[460.844475] Starting dynamic subtest: dma_fence_unwrap
[460.903568] Dynamic subtest dma_fence_unwrap: SUCCESS (0.086s)
[460.938208] Starting dynamic subtest: dma_resv
[461.003695] Dynamic subtest dma_resv: SUCCESS (0.093s)
[461.012688] Subtest all-tests: SUCCESS (12.083s)
[461.078742] Closing watchdogs
Done.