[42.466991] Initializing watchdogs
[42.467251]   /dev/watchdog0
[42.499745] [001/165] (960s left) i915_module_load (load)
[42.729966] Starting subtest: load
[45.256810] Subtest load: SUCCESS (2.524s)
[45.375171] [002/165] (957s left) core_auth (basic-auth)
[45.661327] Starting subtest: basic-auth
[45.683114] Subtest basic-auth: SUCCESS (0.004s)
[45.766655] [003/165] (957s left) debugfs_test (read_all_entries)
[46.007034] Starting subtest: read_all_entries
[46.419994] Subtest read_all_entries: SUCCESS (0.402s)
[46.603457] [004/165] (956s left) debugfs_test (basic-hwmon)
[46.818950] Starting subtest: basic-hwmon
[46.861206] Subtest basic-hwmon: SKIP (0.000s)
[47.044150] [005/165] (956s left) fbdev (eof)
[47.262560] Starting subtest: eof
[47.283543] Subtest eof: SUCCESS (0.002s)
[47.390382] [006/165] (956s left) fbdev (info)
[47.635195] Starting subtest: info
[47.663640] Subtest info: SUCCESS (0.000s)
[47.793604] [007/165] (955s left) fbdev (nullptr)
[48.031882] Starting subtest: nullptr
[48.057467] Subtest nullptr: SUCCESS (0.000s)
[48.191485] [008/165] (955s left) fbdev (read)
[48.394485] Starting subtest: read
[49.858926] Subtest read: SUCCESS (1.475s)
[49.994851] [009/165] (953s left) fbdev (write)
[50.218055] Starting subtest: write
[50.969690] Subtest write: SUCCESS (0.761s)
[51.079240] [010/165] (952s left) gem_basic (bad-close)
[51.309663] Starting subtest: bad-close
[51.353424] Subtest bad-close: SUCCESS (0.000s)
[51.439533] [011/165] (952s left) gem_basic (create-close)
[51.669547] Starting subtest: create-close
[51.713152] Subtest create-close: SUCCESS (0.000s)
[51.805989] [012/165] (952s left) gem_basic (create-fd-close)
[52.037409] Starting subtest: create-fd-close
[52.080543] Subtest create-fd-close: SUCCESS (0.000s)
[52.167556] [013/165] (951s left) gem_busy (busy@all-engines)
[52.409552] Starting subtest: busy
[52.429233] Starting dynamic subtest: all-engines
[52.493577] Dynamic subtest all-engines: SUCCESS (0.091s)
[52.516204] Subtest busy: SUCCESS (0.091s)
[52.902378] [014/165] (951s left) gem_close_race (basic-process)
[53.144402] Starting subtest: basic-process
[53.238903] Subtest basic-process: SUCCESS (0.097s)
[53.459434] [015/165] (950s left) gem_close_race (basic-threads)
[53.712372] Starting subtest: basic-threads
[54.913652] Subtest basic-threads: SUCCESS (1.222s)
[55.139646] [016/165] (949s left) gem_ctx_create (basic)
[55.381609] Starting subtest: basic
[55.404422] Subtest basic: SUCCESS (0.000s)
[55.592792] [017/165] (948s left) gem_ctx_create (basic-files)
[55.824247] Starting subtest: basic-files
[57.855306] Subtest basic-files: SUCCESS (2.037s)
[58.104867] [018/165] (946s left) gem_ctx_exec (basic)
[58.323906] Starting subtest: basic
[58.347086] Subtest basic: SUCCESS (0.007s)
[58.435350] [019/165] (946s left) gem_exec_basic (basic)
[58.669659] Starting subtest: basic
[58.689746] Starting dynamic subtest: rcs0-smem
[58.712466] Dynamic subtest rcs0-smem: SUCCESS (0.004s)
[58.747160] Starting dynamic subtest: bcs0-smem
[58.767797] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[58.802047] Starting dynamic subtest: vcs0-smem
[58.824737] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[58.860223] Starting dynamic subtest: vecs0-smem
[58.880409] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[58.900440] Subtest basic: SUCCESS (0.010s)
[58.990106] [020/165] (945s left) gem_exec_create (basic)
[59.231074] Starting subtest: basic
[59.252806] Starting dynamic subtest: smem
[61.307954] Dynamic subtest smem: SUCCESS (2.074s)
[61.328692] Subtest basic: SUCCESS (2.074s)
[61.523921] [021/165] (943s left) gem_exec_fence (basic-busy)
[61.882698] Starting subtest: basic-busy
[61.903377] Starting dynamic subtest: rcs0
[61.947171] Dynamic subtest rcs0: SUCCESS (0.031s)
[61.981694] Starting dynamic subtest: bcs0
[62.024354] Dynamic subtest bcs0: SUCCESS (0.025s)
[62.060911] Starting dynamic subtest: vcs0
[62.103480] Dynamic subtest vcs0: SUCCESS (0.023s)
[62.140766] Starting dynamic subtest: vecs0
[62.182128] Dynamic subtest vecs0: SUCCESS (0.024s)
[62.203654] Subtest basic-busy: SUCCESS (0.104s)
[62.774277] [022/165] (942s left) gem_exec_fence (basic-wait)
[63.125815] Starting subtest: basic-wait
[63.145622] Starting dynamic subtest: rcs0
[63.168035] Dynamic subtest rcs0: SUCCESS (0.029s)
[63.205171] Starting dynamic subtest: bcs0
[63.224701] Dynamic subtest bcs0: SUCCESS (0.027s)
[63.258729] Starting dynamic subtest: vcs0
[63.281935] Dynamic subtest vcs0: SUCCESS (0.023s)
[63.317298] Starting dynamic subtest: vecs0
[63.337632] Dynamic subtest vecs0: SUCCESS (0.026s)
[63.356938] Subtest basic-wait: SUCCESS (0.107s)
[63.939215] [023/165] (940s left) gem_exec_fence (basic-await)
[64.295149] Starting subtest: basic-await
[64.316480] Starting dynamic subtest: rcs0
[64.437869] Dynamic subtest rcs0: SUCCESS (0.142s)
[64.476412] Starting dynamic subtest: bcs0
[64.570264] Dynamic subtest bcs0: SUCCESS (0.135s)
[64.610453] Starting dynamic subtest: vcs0
[64.706044] Dynamic subtest vcs0: SUCCESS (0.135s)
[64.743144] Starting dynamic subtest: vecs0
[64.843113] Dynamic subtest vecs0: SUCCESS (0.133s)
[64.864118] Subtest basic-await: SUCCESS (0.547s)
[65.541912] [024/165] (939s left) gem_exec_fence (nb-await)
[65.893934] Starting subtest: nb-await
[65.915165] Starting dynamic subtest: rcs0
[65.973934] Dynamic subtest rcs0: SUCCESS (0.077s)
[66.010515] Starting dynamic subtest: bcs0
[66.053710] Dynamic subtest bcs0: SUCCESS (0.070s)
[66.091324] Starting dynamic subtest: vcs0
[66.133322] Dynamic subtest vcs0: SUCCESS (0.070s)
[66.172384] Starting dynamic subtest: vecs0
[66.194684] Dynamic subtest vecs0: SUCCESS (0.070s)
[66.215855] Subtest nb-await: SUCCESS (0.289s)
[66.873879] [025/165] (938s left) gem_exec_gttfill (basic)
[67.110354] Starting subtest: basic
[88.952859] Subtest basic: SUCCESS (21.848s)
[89.180228] [026/165] (915s left) gem_exec_parallel (engines)
[89.583188] Starting subtest: engines
[89.602716] Starting dynamic subtest: basic
[93.136947] Dynamic subtest basic: SUCCESS (3.550s)
[93.173066] Starting dynamic subtest: contexts
[98.095665] Dynamic subtest contexts: SUCCESS (4.806s)
[98.136326] Starting dynamic subtest: fds
[104.668053] Dynamic subtest fds: SUCCESS (6.558s)
[104.705258] Starting dynamic subtest: userptr
[108.291719] Dynamic subtest userptr: SUCCESS (3.622s)
[108.312504] Subtest engines: SUCCESS (18.539s)
[108.423816] [027/165] (896s left) gem_exec_store (basic)
[108.729584] Starting subtest: basic
[108.751973] Subtest basic: SUCCESS (0.072s)
[108.957097] [028/165] (896s left) gem_flink_basic (bad-flink)
[109.187541] Starting subtest: bad-flink
[109.208456] Subtest bad-flink: SUCCESS (0.000s)
[109.302676] [029/165] (896s left) gem_flink_basic (bad-open)
[109.534747] Starting subtest: bad-open
[109.557731] Subtest bad-open: SUCCESS (0.000s)
[109.649294] [030/165] (895s left) gem_flink_basic (basic)
[109.886651] Starting subtest: basic
[109.907285] Subtest basic: SUCCESS (0.000s)
[110.001060] [031/165] (895s left) gem_flink_basic (double-flink)
[110.227968] Starting subtest: double-flink
[110.250067] Subtest double-flink: SUCCESS (0.000s)
[110.339703] [032/165] (895s left) gem_flink_basic (flink-lifetime)
[110.566366] Starting subtest: flink-lifetime
[110.586158] Subtest flink-lifetime: SUCCESS (0.003s)
[110.678300] [033/165] (895s left) gem_huc_copy (huc-copy)
[110.906885] Starting subtest: huc-copy
[110.928598] Subtest huc-copy: SUCCESS (0.006s)
[111.022598] [034/165] (894s left) gem_linear_blits (basic)
[111.414946] Starting subtest: basic
[111.437106] Subtest basic: SUCCESS (0.019s)
[111.603487] [035/165] (894s left) gem_mmap (basic)
[111.991950] Starting subtest: basic
[112.101322] Subtest basic: SUCCESS (0.001s)
[112.191531] [036/165] (893s left) gem_mmap_gtt (basic)
[112.436166] Starting subtest: basic
[112.456904] Subtest basic: SUCCESS (0.001s)
[112.549997] [037/165] (893s left) gem_render_linear_blits (basic)
[112.763621] Starting subtest: basic
[112.867094] Subtest basic: SUCCESS (0.086s)
[112.967599] [038/165] (893s left) gem_render_tiled_blits (basic)
[113.208930] Starting subtest: basic
[113.334774] Subtest basic: SUCCESS (0.117s)
[113.429968] [039/165] (892s left) gem_ringfill (basic-all)
[113.941825] Starting subtest: basic-all
[115.105775] Subtest basic-all: SUCCESS (1.199s)
[115.258896] [040/165] (891s left) gem_softpin (allocator-basic)
[115.496202] Starting subtest: allocator-basic
[115.715945] Subtest allocator-basic: SUCCESS (0.242s)
[115.861743] [041/165] (890s left) gem_softpin (allocator-basic-reserve)
[116.090824] Starting subtest: allocator-basic-reserve
[116.308292] Subtest allocator-basic-reserve: SUCCESS (0.240s)
[116.459780] [042/165] (890s left) gem_softpin (safe-alignment)
[116.674492] Starting subtest: safe-alignment
[116.694362] Subtest safe-alignment: SUCCESS (0.032s)
[116.820436] [043/165] (889s left) gem_sync (basic-all)
[117.232226] Starting subtest: basic-all
[120.188624] Subtest basic-all: SUCCESS (2.962s)
[120.279868] [044/165] (886s left) gem_sync (basic-each)
[120.689907] Starting subtest: basic-each
[122.850122] Subtest basic-each: SUCCESS (2.335s)
[123.076435] [045/165] (883s left) gem_tiled_blits (basic)
[123.448347] Starting subtest: basic
[123.492429] Subtest basic: SUCCESS (0.046s)
[123.679118] [046/165] (883s left) gem_tiled_fence_blits (basic)
[124.231481] Starting subtest: basic
[124.273261] Subtest basic: SUCCESS (0.042s)
[124.463502] [047/165] (882s left) gem_tiled_pread_basic
[125.208642] [048/165] (881s left) gem_wait (busy@all-engines)
[125.473201] Starting subtest: busy
[125.494026] Starting dynamic subtest: all-engines
[126.003154] Dynamic subtest all-engines: SUCCESS (0.534s)
[126.024104] Subtest busy: SUCCESS (0.534s)
[126.260455] [049/165] (880s left) gem_wait (wait@all-engines)
[126.498579] Starting subtest: wait
[126.521530] Starting dynamic subtest: all-engines
[127.530653] Dynamic subtest all-engines: SUCCESS (1.042s)
[127.553451] Subtest wait: SUCCESS (1.043s)
[127.792885] [050/165] (879s left) i915_getparams_basic (basic-eu-total)
[128.027433] Starting subtest: basic-eu-total
[128.071140] Subtest basic-eu-total: SUCCESS (0.000s)
[128.163782] [051/165] (879s left) i915_getparams_basic (basic-subslice-total)
[128.391074] Starting subtest: basic-subslice-total
[128.434948] Subtest basic-subslice-total: SUCCESS (0.000s)
[128.525830] [052/165] (878s left) i915_hangman (error-state-basic)
[128.765050] Starting subtest: error-state-basic
[129.037392] Subtest error-state-basic: SUCCESS (0.288s)
[129.157568] [053/165] (878s left) i915_pciid
[129.503747] [054/165] (878s left) kms_addfb_basic (addfb25-4-tiled)
[129.888699] Starting subtest: addfb25-4-tiled
[129.911938] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[130.022993] [055/165] (877s left) kms_addfb_basic (addfb25-bad-modifier)
[130.272415] Starting subtest: addfb25-bad-modifier
[130.295780] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[130.470082] [056/165] (877s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[130.722305] Starting subtest: addfb25-framebuffer-vs-set-tiling
[130.746797] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[130.919263] [057/165] (876s left) kms_addfb_basic (addfb25-modifier-no-flag)
[131.177276] Starting subtest: addfb25-modifier-no-flag
[131.202603] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[131.362097] [058/165] (876s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[131.590110] Starting subtest: addfb25-x-tiled-legacy
[131.612258] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[131.790785] [059/165] (876s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[132.020050] Starting subtest: addfb25-x-tiled-mismatch-legacy
[132.041314] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.001s)
[132.224573] [060/165] (875s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[132.540749] Starting subtest: addfb25-yf-tiled-legacy
[132.561484] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[132.654733] [061/165] (875s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[132.969482] Starting subtest: addfb25-y-tiled-legacy
[132.992053] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[133.084078] [062/165] (875s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[133.404220] Starting subtest: addfb25-y-tiled-small-legacy
[133.424281] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.001s)
[133.520368] [063/165] (874s left) kms_addfb_basic (bad-pitch-0)
[133.745756] Starting subtest: bad-pitch-0
[133.767728] Subtest bad-pitch-0: SUCCESS (0.000s)
[133.959908] [064/165] (874s left) kms_addfb_basic (bad-pitch-1024)
[134.187890] Starting subtest: bad-pitch-1024
[134.207693] Subtest bad-pitch-1024: SUCCESS (0.000s)
[134.391971] [065/165] (874s left) kms_addfb_basic (bad-pitch-128)
[134.619844] Starting subtest: bad-pitch-128
[134.639324] Subtest bad-pitch-128: SUCCESS (0.000s)
[134.811294] [066/165] (873s left) kms_addfb_basic (bad-pitch-256)
[135.037726] Starting subtest: bad-pitch-256
[135.060337] Subtest bad-pitch-256: SUCCESS (0.000s)
[135.242356] [067/165] (873s left) kms_addfb_basic (bad-pitch-32)
[135.469996] Starting subtest: bad-pitch-32
[135.490400] Subtest bad-pitch-32: SUCCESS (0.000s)
[135.658283] [068/165] (873s left) kms_addfb_basic (bad-pitch-63)
[135.886198] Starting subtest: bad-pitch-63
[135.905382] Subtest bad-pitch-63: SUCCESS (0.000s)
[136.091352] [069/165] (872s left) kms_addfb_basic (bad-pitch-65536)
[136.329022] Starting subtest: bad-pitch-65536
[136.349695] Subtest bad-pitch-65536: SUCCESS (0.001s)
[136.524610] [070/165] (872s left) kms_addfb_basic (bad-pitch-999)
[136.732544] Starting subtest: bad-pitch-999
[136.753544] Subtest bad-pitch-999: SUCCESS (0.000s)
[136.925527] [071/165] (872s left) kms_addfb_basic (basic)
[137.154154] Starting subtest: basic
[137.176063] Subtest basic: SUCCESS (0.000s)
[137.355554] [072/165] (871s left) kms_addfb_basic (basic-x-tiled-legacy)
[137.586717] Starting subtest: basic-x-tiled-legacy
[137.607201] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[137.774058] [073/165] (871s left) kms_addfb_basic (basic-y-tiled-legacy)
[138.006905] Starting subtest: basic-y-tiled-legacy
[138.026619] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[138.210677] [074/165] (871s left) kms_addfb_basic (bo-too-small)
[138.545738] Starting subtest: bo-too-small
[138.570344] Subtest bo-too-small: SUCCESS (0.001s)
[138.680979] [075/165] (870s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[139.023664] Starting subtest: bo-too-small-due-to-tiling
[139.048530] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[139.161404] [076/165] (870s left) kms_addfb_basic (clobberred-modifier)
[139.414396] Starting subtest: clobberred-modifier
[139.443272] Subtest clobberred-modifier: SUCCESS (0.000s)
[139.591221] [077/165] (870s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[139.821690] Starting subtest: framebuffer-vs-set-tiling
[139.843730] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[140.023806] [078/165] (869s left) kms_addfb_basic (invalid-get-prop)
[140.251336] Starting subtest: invalid-get-prop
[140.270754] Subtest invalid-get-prop: SUCCESS (0.000s)
[140.442414] [079/165] (869s left) kms_addfb_basic (invalid-get-prop-any)
[140.671959] Starting subtest: invalid-get-prop-any
[140.691315] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[140.859563] [080/165] (869s left) kms_addfb_basic (invalid-set-prop)
[141.088291] Starting subtest: invalid-set-prop
[141.110273] Subtest invalid-set-prop: SUCCESS (0.000s)
[141.273501] [081/165] (868s left) kms_addfb_basic (invalid-set-prop-any)
[141.502374] Starting subtest: invalid-set-prop-any
[141.523665] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[141.706278] [082/165] (868s left) kms_addfb_basic (no-handle)
[141.934944] Starting subtest: no-handle
[141.954736] Subtest no-handle: SUCCESS (0.000s)
[142.127422] [083/165] (868s left) kms_addfb_basic (size-max)
[142.442579] Starting subtest: size-max
[142.465300] Subtest size-max: SUCCESS (0.000s)
[142.557772] [084/165] (867s left) kms_addfb_basic (small-bo)
[142.872333] Starting subtest: small-bo
[142.891707] Subtest small-bo: SUCCESS (0.000s)
[142.985398] [085/165] (867s left) kms_addfb_basic (tile-pitch-mismatch)
[143.210920] Starting subtest: tile-pitch-mismatch
[143.232938] Subtest tile-pitch-mismatch: SUCCESS (0.001s)
[143.406558] [086/165] (867s left) kms_addfb_basic (too-high)
[143.721062] Starting subtest: too-high
[143.741990] Subtest too-high: SUCCESS (0.002s)
[143.842687] [087/165] (866s left) kms_addfb_basic (too-wide)
[144.139791] Starting subtest: too-wide
[144.159747] Subtest too-wide: SUCCESS (0.000s)
[144.261319] [088/165] (866s left) kms_addfb_basic (unused-handle)
[144.487678] Starting subtest: unused-handle
[144.509717] Subtest unused-handle: SUCCESS (0.000s)
[144.671730] [089/165] (866s left) kms_addfb_basic (unused-modifier)
[144.902265] Starting subtest: unused-modifier
[144.921649] Subtest unused-modifier: SUCCESS (0.000s)
[145.109299] [090/165] (865s left) kms_addfb_basic (unused-offsets)
[145.336090] Starting subtest: unused-offsets
[145.355562] Subtest unused-offsets: SUCCESS (0.000s)
[145.543377] [091/165] (865s left) kms_addfb_basic (unused-pitches)
[145.769394] Starting subtest: unused-pitches
[145.792149] Subtest unused-pitches: SUCCESS (0.000s)
[145.975086] [092/165] (864s left) kms_busy (basic)
[146.300640] Starting subtest: basic
[146.322892] Starting dynamic subtest: flip
[147.288307] Dynamic subtest flip: SUCCESS (0.962s)
[147.344480] Starting dynamic subtest: modeset
[148.659570] Dynamic subtest modeset: SUCCESS (1.397s)
[148.681935] Subtest basic: SUCCESS (2.363s)
[149.051532] [093/165] (861s left) kms_prop_blob (basic)
[149.300341] Starting subtest: basic
[149.320460] Subtest basic: SUCCESS (0.000s)
[149.414602] [094/165] (861s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[149.744451] Starting subtest: basic-busy-flip-before-cursor-atomic
[149.787062] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[149.882729] [095/165] (861s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[150.199712] Starting subtest: basic-busy-flip-before-cursor-legacy
[150.244085] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[150.335309] [096/165] (860s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[150.650369] Starting subtest: basic-flip-after-cursor-atomic
[151.710042] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.063s)
[152.055529] [097/165] (859s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[152.376120] Starting subtest: basic-flip-after-cursor-legacy
[153.413439] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.056s)
[153.776136] [098/165] (857s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[154.101486] Starting subtest: basic-flip-after-cursor-varying-size
[155.153220] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.069s)
[155.518721] [099/165] (856s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[155.816511] Starting subtest: basic-flip-before-cursor-atomic
[156.882260] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.058s)
[157.245941] [100/165] (854s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[157.561491] Starting subtest: basic-flip-before-cursor-legacy
[158.605899] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.054s)
[158.993115] [101/165] (852s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[159.319660] Starting subtest: basic-flip-before-cursor-varying-size
[160.377328] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.065s)
[160.757379] [102/165] (851s left) kms_dsc (dsc-basic)
[161.079255] Subtest dsc-basic: SKIP (0.000s)
[161.175137] [103/165] (850s left) kms_flip (basic-flip-vs-dpms)
[161.501225] Starting subtest: basic-flip-vs-dpms
[161.522388] Starting dynamic subtest: A-DP1
[162.729279] Dynamic subtest A-DP1: SUCCESS (1.245s)
[162.767734] Starting dynamic subtest: B-DP1
[163.853125] Dynamic subtest B-DP1: SUCCESS (1.128s)
[163.891836] Starting dynamic subtest: C-DP1
[164.995886] Dynamic subtest C-DP1: SUCCESS (1.149s)
[165.018813] Subtest basic-flip-vs-dpms: SUCCESS (3.531s)
[165.406950] [104/165] (846s left) kms_flip (basic-flip-vs-modeset)
[165.728793] Starting subtest: basic-flip-vs-modeset
[165.750613] Starting dynamic subtest: A-DP1
[167.022623] Dynamic subtest A-DP1: SUCCESS (1.304s)
[167.060495] Starting dynamic subtest: B-DP1
[168.153118] Dynamic subtest B-DP1: SUCCESS (1.144s)
[168.195442] Starting dynamic subtest: C-DP1
[169.271218] Dynamic subtest C-DP1: SUCCESS (1.119s)
[169.291988] Subtest basic-flip-vs-modeset: SUCCESS (3.577s)
[169.662281] [105/165] (842s left) kms_flip (basic-flip-vs-wf_vblank)
[169.981984] Starting subtest: basic-flip-vs-wf_vblank
[170.004043] Starting dynamic subtest: A-DP1
[171.410095] Dynamic subtest A-DP1: SUCCESS (1.448s)
[171.449417] Starting dynamic subtest: B-DP1
[172.810118] Dynamic subtest B-DP1: SUCCESS (1.395s)
[172.850641] Starting dynamic subtest: C-DP1
[174.199217] Dynamic subtest C-DP1: SUCCESS (1.391s)
[174.221164] Subtest basic-flip-vs-wf_vblank: SUCCESS (4.246s)
[174.656339] [106/165] (837s left) kms_flip (basic-plain-flip)
[174.972396] Starting subtest: basic-plain-flip
[174.992128] Starting dynamic subtest: A-DP1
[176.144966] Dynamic subtest A-DP1: SUCCESS (1.175s)
[176.188565] Starting dynamic subtest: B-DP1
[177.242560] Dynamic subtest B-DP1: SUCCESS (1.107s)
[177.291684] Starting dynamic subtest: C-DP1
[178.346240] Dynamic subtest C-DP1: SUCCESS (1.113s)
[178.368163] Subtest basic-plain-flip: SUCCESS (3.406s)
[178.815537] [107/165] (833s left) kms_force_connector_basic (force-connector-state)
[179.079355] Subtest force-connector-state: SKIP (0.000s)
[179.176570] [108/165] (833s left) kms_force_connector_basic (force-edid)
[179.432177] Subtest force-edid: SKIP (0.000s)
[179.525475] [109/165] (832s left) kms_force_connector_basic (force-load-detect)
[179.773939] Subtest force-load-detect: SKIP (0.000s)
[179.870919] [110/165] (832s left) kms_force_connector_basic (prune-stale-modes)
[180.102513] Subtest prune-stale-modes: SKIP (0.000s)
[180.197141] [111/165] (832s left) kms_frontbuffer_tracking (basic)
[180.589548] Starting subtest: basic
[183.609396] Subtest basic: SUCCESS (3.093s)
[183.757496] [112/165] (829s left) kms_hdmi_inject (inject-audio)
[184.012294] Subtest inject-audio: SKIP (0.000s)
[184.106479] [113/165] (828s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[184.434934] Starting subtest: compare-crc-sanitycheck-xr24
[184.455691] Starting dynamic subtest: pipe-A-DP-1
[184.869066] Dynamic subtest pipe-A-DP-1: SUCCESS (0.434s)
[184.911692] Starting dynamic subtest: pipe-B-DP-1
[185.462330] Dynamic subtest pipe-B-DP-1: SUCCESS (0.605s)
[185.509794] Starting dynamic subtest: pipe-C-DP-1
[186.104906] Dynamic subtest pipe-C-DP-1: SUCCESS (0.618s)
[186.127090] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.672s)
[186.455245] [114/165] (826s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[186.754058] Starting subtest: compare-crc-sanitycheck-nv12
[186.773814] Starting dynamic subtest: pipe-A-DP-1
[186.817634] Dynamic subtest pipe-A-DP-1: SKIP (0.001s)
[186.856361] Starting dynamic subtest: pipe-B-DP-1
[186.896274] Dynamic subtest pipe-B-DP-1: SKIP (0.001s)
[186.937127] Starting dynamic subtest: pipe-C-DP-1
[186.989299] Dynamic subtest pipe-C-DP-1: SKIP (0.001s)
[187.048041] Subtest compare-crc-sanitycheck-nv12: SKIP (0.017s)
[187.161149] [115/165] (825s left) kms_pipe_crc_basic (hang-read-crc)
[187.471532] Starting subtest: hang-read-crc
[187.496205] Starting dynamic subtest: pipe-A-DP-1
[188.939509] Dynamic subtest pipe-A-DP-1: SUCCESS (1.470s)
[188.993867] Starting dynamic subtest: pipe-B-DP-1
[190.565897] Dynamic subtest pipe-B-DP-1: SUCCESS (1.624s)
[190.622861] Starting dynamic subtest: pipe-C-DP-1
[192.213890] Dynamic subtest pipe-C-DP-1: SUCCESS (1.624s)
[192.236742] Subtest hang-read-crc: SUCCESS (4.731s)
[192.561925] [116/165] (820s left) kms_pipe_crc_basic (nonblocking-crc)
[192.861594] Starting subtest: nonblocking-crc
[192.881363] Starting dynamic subtest: pipe-A-DP-1
[193.451530] Dynamic subtest pipe-A-DP-1: SUCCESS (0.568s)
[193.492652] Starting dynamic subtest: pipe-B-DP-1
[194.222857] Dynamic subtest pipe-B-DP-1: SUCCESS (0.764s)
[194.261694] Starting dynamic subtest: pipe-C-DP-1
[194.972025] Dynamic subtest pipe-C-DP-1: SUCCESS (0.750s)
[194.993586] Subtest nonblocking-crc: SUCCESS (2.095s)
[195.316986] [117/165] (817s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[195.637215] Starting subtest: nonblocking-crc-frame-sequence
[195.659549] Starting dynamic subtest: pipe-A-DP-1
[196.230116] Dynamic subtest pipe-A-DP-1: SUCCESS (0.586s)
[196.275849] Starting dynamic subtest: pipe-B-DP-1
[196.991226] Dynamic subtest pipe-B-DP-1: SUCCESS (0.758s)
[197.037468] Starting dynamic subtest: pipe-C-DP-1
[197.724244] Dynamic subtest pipe-C-DP-1: SUCCESS (0.752s)
[197.747507] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.109s)
[198.084862] [118/165] (815s left) kms_pipe_crc_basic (read-crc)
[198.381425] Starting subtest: read-crc
[198.402189] Starting dynamic subtest: pipe-A-DP-1
[198.899259] Dynamic subtest pipe-A-DP-1: SUCCESS (0.507s)
[198.952702] Starting dynamic subtest: pipe-B-DP-1
[199.592584] Dynamic subtest pipe-B-DP-1: SUCCESS (0.695s)
[199.643589] Starting dynamic subtest: pipe-C-DP-1
[200.282100] Dynamic subtest pipe-C-DP-1: SUCCESS (0.685s)
[200.305262] Subtest read-crc: SUCCESS (1.901s)
[200.645325] [119/165] (812s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[200.940283] Starting subtest: read-crc-frame-sequence
[200.960608] Starting dynamic subtest: pipe-A-DP-1
[201.475379] Dynamic subtest pipe-A-DP-1: SUCCESS (0.506s)
[201.528336] Starting dynamic subtest: pipe-B-DP-1
[202.180810] Dynamic subtest pipe-B-DP-1: SUCCESS (0.696s)
[202.220834] Starting dynamic subtest: pipe-C-DP-1
[202.847508] Dynamic subtest pipe-C-DP-1: SUCCESS (0.672s)
[202.869743] Subtest read-crc-frame-sequence: SUCCESS (1.889s)
[203.194810] [120/165] (810s left) kms_pm_backlight (basic-brightness)
[203.545748] Subtest basic-brightness: SKIP (0.000s)
[203.641304] [121/165] (809s left) kms_pm_rpm (basic-pci-d3-state)
[205.518878] Starting subtest: basic-pci-d3-state
[206.193735] Subtest basic-pci-d3-state: SUCCESS (0.656s)
[206.400518] [122/165] (807s left) kms_pm_rpm (basic-rte)
[206.609997] Starting subtest: basic-rte
[208.770184] Subtest basic-rte: SUCCESS (2.167s)
[209.723380] [123/165] (804s left) kms_psr (primary_page_flip)
[209.981842] Subtest primary_page_flip: SKIP (0.000s)
[210.078227] [124/165] (803s left) kms_psr (cursor_plane_move)
[210.312267] Subtest cursor_plane_move: SKIP (0.000s)
[210.409576] [125/165] (803s left) kms_psr (sprite_plane_onoff)
[210.664469] Subtest sprite_plane_onoff: SKIP (0.000s)
[210.762484] [126/165] (803s left) kms_psr (primary_mmap_gtt)
[211.016924] Subtest primary_mmap_gtt: SKIP (0.000s)
[211.114453] [127/165] (803s left) kms_setmode (basic-clone-single-crtc)
[211.358861] Starting subtest: basic-clone-single-crtc
[211.423437] Subtest basic-clone-single-crtc: SKIP (0.000s)
[211.519986] [128/165] (802s left) i915_pm_rps (basic-api)
[211.760321] Starting subtest: basic-api
[211.782157] Subtest basic-api: SUCCESS (0.007s)
[211.903351] [129/165] (802s left) prime_self_import (basic-llseek-bad)
[212.121343] Starting subtest: basic-llseek-bad
[212.161961] Subtest basic-llseek-bad: SUCCESS (0.004s)
[212.264246] [130/165] (802s left) prime_self_import (basic-llseek-size)
[212.473842] Starting subtest: basic-llseek-size
[212.517207] Subtest basic-llseek-size: SUCCESS (0.006s)
[212.615944] [131/165] (801s left) prime_self_import (basic-with_fd_dup)
[212.826517] Starting subtest: basic-with_fd_dup
[212.869046] Subtest basic-with_fd_dup: SUCCESS (0.007s)
[212.969657] [132/165] (801s left) prime_self_import (basic-with_one_bo)
[213.178314] Starting subtest: basic-with_one_bo
[213.221137] Subtest basic-with_one_bo: SUCCESS (0.007s)
[213.319541] [133/165] (801s left) prime_self_import (basic-with_one_bo_two_files)
[213.518977] Starting subtest: basic-with_one_bo_two_files
[213.559629] Subtest basic-with_one_bo_two_files: SUCCESS (0.005s)
[213.658347] [134/165] (801s left) prime_self_import (basic-with_two_bos)
[213.874744] Starting subtest: basic-with_two_bos
[213.916532] Subtest basic-with_two_bos: SUCCESS (0.006s)
[214.013702] [135/165] (800s left) prime_vgem (basic-fence-flip)
[214.295238] Starting subtest: basic-fence-flip
[214.858146] Subtest basic-fence-flip: SUCCESS (0.603s)
[214.988579] [136/165] (800s left) prime_vgem (basic-fence-mmap)
[215.237693] Starting subtest: basic-fence-mmap
[215.282732] Subtest basic-fence-mmap: SUCCESS (0.072s)
[215.433445] [137/165] (799s left) prime_vgem (basic-fence-read)
[215.721258] Starting subtest: basic-fence-read
[215.786469] Subtest basic-fence-read: SUCCESS (0.109s)
[215.930868] [138/165] (799s left) prime_vgem (basic-gtt)
[216.218205] Starting subtest: basic-gtt
[216.246086] Subtest basic-gtt: SUCCESS (0.035s)
[216.377313] [139/165] (799s left) prime_vgem (basic-read)
[216.626681] Starting subtest: basic-read
[216.671677] Subtest basic-read: SUCCESS (0.053s)
[216.790722] [140/165] (798s left) prime_vgem (basic-write)
[217.026997] Starting subtest: basic-write
[217.073533] Subtest basic-write: SUCCESS (0.065s)
[217.200849] [141/165] (798s left) vgem_basic (setversion)
[217.440337] Starting subtest: setversion
[217.483454] Subtest setversion: SUCCESS (0.000s)
[217.581888] [142/165] (798s left) vgem_basic (create)
[217.813546] Starting subtest: create
[217.836644] Subtest create: SUCCESS (0.001s)
[217.932858] [143/165] (797s left) vgem_basic (debugfs)
[218.144892] Starting subtest: debugfs
[218.166567] Subtest debugfs: SUCCESS (0.001s)
[218.261585] [144/165] (797s left) vgem_basic (dmabuf-export)
[218.491751] Starting subtest: dmabuf-export
[218.534763] Subtest dmabuf-export: SUCCESS (0.005s)
[218.631181] [145/165] (797s left) vgem_basic (dmabuf-fence)
[218.861514] Starting subtest: dmabuf-fence
[218.882359] Subtest dmabuf-fence: SUCCESS (0.002s)
[218.982776] [146/165] (797s left) vgem_basic (dmabuf-fence-before)
[219.195405] Starting subtest: dmabuf-fence-before
[219.218252] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[219.313661] [147/165] (796s left) vgem_basic (dmabuf-mmap)
[219.541677] Starting subtest: dmabuf-mmap
[219.562272] Subtest dmabuf-mmap: SUCCESS (0.041s)
[219.662643] [148/165] (796s left) vgem_basic (mmap)
[219.894774] Starting subtest: mmap
[219.916994] Subtest mmap: SUCCESS (0.026s)
[220.012515] [149/165] (796s left) vgem_basic (second-client)
[220.242069] Starting subtest: second-client
[220.284717] Subtest second-client: SUCCESS (0.032s)
[220.378567] [150/165] (796s left) vgem_basic (sysfs)
[220.614760] Starting subtest: sysfs
[220.635446] Subtest sysfs: SUCCESS (0.001s)
[220.734649] [151/165] (795s left) core_hotunplug (unbind-rebind)
[221.067979] Starting subtest: unbind-rebind
[224.918677] Subtest unbind-rebind: SUCCESS (3.853s)
[225.016323] [152/165] (791s left) vgem_basic (unload)
[225.232332] Starting subtest: unload
[254.520439] Subtest unload: SUCCESS (29.048s)
[254.630408] [153/165] (762s left) i915_module_load (reload)
[254.851542] Starting subtest: reload
[258.651581] Subtest reload: SUCCESS (3.790s)
[258.792905] [154/165] (758s left) gem_lmem_swapping (basic)
[259.051749] Subtest basic: SKIP (0.000s)
[259.149991] [155/165] (757s left) gem_lmem_swapping (parallel-random-engines)
[259.382604] Subtest parallel-random-engines: SKIP (0.000s)
[259.477473] [156/165] (757s left) gem_lmem_swapping (random-engines)
[259.730792] Subtest random-engines: SKIP (0.000s)
[259.825914] [157/165] (757s left) gem_lmem_swapping (verify-random)
[260.079222] Subtest verify-random: SKIP (0.000s)
[260.174319] [158/165] (757s left) i915_pm_rpm (module-reload)
[262.464122] Starting subtest: module-reload
[271.670776] Subtest module-reload: SUCCESS (9.208s)
[271.764080] [159/165] (745s left) i915_selftest (live)
[272.002238] Starting subtest: live
[272.024237] Starting dynamic subtest: sanitycheck
[273.639172] Dynamic subtest sanitycheck: SUCCESS (1.633s)
[273.675208] Starting dynamic subtest: uncore
[275.136814] Dynamic subtest uncore: SUCCESS (1.494s)
[275.173273] Starting dynamic subtest: workarounds
[277.035498] Dynamic subtest workarounds: SUCCESS (1.895s)
[277.072617] Starting dynamic subtest: gt_engines
[279.141476] Dynamic subtest gt_engines: SUCCESS (2.103s)
[279.188093] Starting dynamic subtest: gt_timelines
[301.670829] Dynamic subtest gt_timelines: SUCCESS (22.525s)
[301.705767] Starting dynamic subtest: gt_contexts
[303.506339] Dynamic subtest gt_contexts: SUCCESS (1.832s)
[303.542940] Starting dynamic subtest: gt_lrc
[307.805978] Dynamic subtest gt_lrc: SUCCESS (4.288s)
[307.847782] Starting dynamic subtest: gt_mocs
[309.483842] Dynamic subtest gt_mocs: SUCCESS (1.680s)
[309.539874] Starting dynamic subtest: gt_pm
[318.611115] Dynamic subtest gt_pm: SUCCESS (9.127s)
[318.656596] Starting dynamic subtest: gt_heartbeat
[334.799494] Dynamic subtest gt_heartbeat: FAIL (10.313s)
[334.866681] Starting dynamic subtest: gt_tlb
[334.888908] Dynamic subtest gt_tlb: SUCCESS (2.390s)
[334.954621] Starting dynamic subtest: requests
[339.313085] Dynamic subtest requests: SUCCESS (7.989s)
[339.369497] Starting dynamic subtest: migrate
[344.263356] Dynamic subtest migrate: SUCCESS (4.948s)
[344.301395] Starting dynamic subtest: active
[346.044100] Dynamic subtest active: SUCCESS (1.777s)
[346.079311] Starting dynamic subtest: objects
[350.483549] Dynamic subtest objects: SUCCESS (4.436s)
[350.529453] Starting dynamic subtest: mman
[359.832846] Dynamic subtest mman: SUCCESS (9.345s)
[359.881204] Starting dynamic subtest: dmabuf
[361.603224] Dynamic subtest dmabuf: SUCCESS (1.770s)
[361.641435] Starting dynamic subtest: vma
[363.180294] Dynamic subtest vma: SUCCESS (1.571s)
[363.223864] Starting dynamic subtest: coherency
[367.679347] Dynamic subtest coherency: SUCCESS (4.490s)
[367.719802] Starting dynamic subtest: gtt
[376.977094] Dynamic subtest gtt: SUCCESS (9.303s)
[377.011977] Starting dynamic subtest: gem
[379.865634] Dynamic subtest gem: SUCCESS (2.882s)
[379.914226] Starting dynamic subtest: evict
[386.219172] Dynamic subtest evict: SUCCESS (6.353s)
[386.268803] Starting dynamic subtest: hugepages
[394.376306] Dynamic subtest hugepages: SUCCESS (8.152s)
[394.413304] Starting dynamic subtest: gem_contexts
[419.378406] Dynamic subtest gem_contexts: SUCCESS (24.829s)
[419.415654] Starting dynamic subtest: client
[420.781817] Dynamic subtest client: SUCCESS (1.572s)
[420.828984] Starting dynamic subtest: gem_migrate
[422.562279] Dynamic subtest gem_migrate: SUCCESS (1.774s)
[422.599670] Starting dynamic subtest: reset
[435.936716] Dynamic subtest reset: SUCCESS (13.374s)
[435.991716] Starting dynamic subtest: memory_region
[437.677395] Dynamic subtest memory_region: SUCCESS (1.736s)
[437.713237] Starting dynamic subtest: hangcheck
[462.436234] Dynamic subtest hangcheck: SUCCESS (24.758s)
[462.474516] Starting dynamic subtest: execlists
[474.897274] Dynamic subtest execlists: SUCCESS (12.456s)
[474.935983] Starting dynamic subtest: ring_submission
[476.610195] Dynamic subtest ring_submission: SUCCESS (1.707s)
[476.658392] Starting dynamic subtest: perf
[478.258128] Dynamic subtest perf: SUCCESS (1.647s)
[478.293610] Starting dynamic subtest: slpc
[479.968467] Dynamic subtest slpc: SUCCESS (1.704s)
[480.005546] Starting dynamic subtest: guc
[481.485131] Dynamic subtest guc: SUCCESS (1.507s)
[481.527856] Starting dynamic subtest: guc_multi_lrc
[483.154653] Dynamic subtest guc_multi_lrc: SUCCESS (1.667s)
[483.199908] Starting dynamic subtest: guc_hang
[484.683982] Dynamic subtest guc_hang: SUCCESS (1.529s)
[484.719778] Starting dynamic subtest: late_gt_pm
[486.304468] Dynamic subtest late_gt_pm: SUCCESS (1.620s)
[486.324313] Subtest live: FAIL (214.302s)
[486.417092] [160/165] (531s left) dmabuf (all-tests)
[486.631827] Starting subtest: all-tests
[486.654353] Starting dynamic subtest: sanitycheck
[486.707073] Dynamic subtest sanitycheck: SUCCESS (0.079s)
[486.744766] Starting dynamic subtest: dma_fence
[486.895860] Dynamic subtest dma_fence: SUCCESS (0.183s)
[486.933920] Starting dynamic subtest: dma_fence_chain
[510.509574] Dynamic subtest dma_fence_chain: SUCCESS (23.610s)
[510.551068] Starting dynamic subtest: dma_fence_unwrap
[510.594893] Dynamic subtest dma_fence_unwrap: SUCCESS (0.077s)
[510.637591] Starting dynamic subtest: dma_resv
[510.680403] Dynamic subtest dma_resv: SUCCESS (0.079s)
[510.703432] Subtest all-tests: SUCCESS (24.044s)
[510.794482] [161/165] (506s left) i915_suspend (basic-s2idle-without-i915)
[511.015419] Starting subtest: basic-s2idle-without-i915
[531.431941] Subtest basic-s2idle-without-i915: SUCCESS (5.422s)
[536.115618] [162/165] (500s left) i915_suspend (basic-s3-without-i915)
[536.327153] Starting subtest: basic-s3-without-i915
[555.514596] Subtest basic-s3-without-i915: SUCCESS (3.761s)
[558.915122] [163/165] (496s left) gem_exec_suspend (basic-s0)
[559.184193] Starting subtest: basic-S0
[559.205386] Starting dynamic subtest: smem
[580.996277] Dynamic subtest smem: SUCCESS (5.550s)
[581.017473] Subtest basic-S0: SUCCESS (5.552s)
[586.384649] [164/165] (490s left) gem_exec_suspend (basic-s3)
[586.616078] Starting subtest: basic-S3
[586.638132] Starting dynamic subtest: smem
[607.432387] Dynamic subtest smem: SUCCESS (5.906s)
[607.453613] Subtest basic-S3: SUCCESS (5.909s)
[609.815616] [165/165] (484s left) kms_pipe_crc_basic (suspend-read-crc)
[610.140309] Starting subtest: suspend-read-crc
[610.161968] Starting dynamic subtest: pipe-A-DP-1
[628.184887] Dynamic subtest pipe-A-DP-1: SUCCESS (2.499s)
[628.254733] Starting dynamic subtest: pipe-B-DP-1
[647.164185] Dynamic subtest pipe-B-DP-1: SUCCESS (2.394s)
[647.209382] Starting dynamic subtest: pipe-C-DP-1
[665.206096] Dynamic subtest pipe-C-DP-1: SUCCESS (2.582s)
[665.236274] Subtest suspend-read-crc: SUCCESS (7.490s)
[669.632022] Closing watchdogs
Done.