[40.973992] Initializing watchdogs
[40.974298]   /dev/watchdog0
[41.006296] [001/165] (960s left) i915_module_load (load)
[41.275763] Starting subtest: load
[43.801177] Subtest load: SUCCESS (2.541s)
[43.905883] [002/165] (957s left) core_auth (basic-auth)
[44.196710] Starting subtest: basic-auth
[44.215901] Subtest basic-auth: SUCCESS (0.004s)
[44.323116] [003/165] (957s left) debugfs_test (read_all_entries)
[44.565788] Starting subtest: read_all_entries
[44.969871] Subtest read_all_entries: SUCCESS (0.403s)
[45.160373] [004/165] (956s left) debugfs_test (basic-hwmon)
[45.389986] Starting subtest: basic-hwmon
[45.431670] Subtest basic-hwmon: SKIP (0.000s)
[45.578510] [005/165] (956s left) fbdev (eof)
[45.813473] Starting subtest: eof
[45.833992] Subtest eof: SUCCESS (0.002s)
[45.951225] [006/165] (955s left) fbdev (info)
[46.163447] Starting subtest: info
[46.185570] Subtest info: SUCCESS (0.000s)
[46.304696] [007/165] (955s left) fbdev (nullptr)
[46.517886] Starting subtest: nullptr
[46.542402] Subtest nullptr: SUCCESS (0.000s)
[46.662489] [008/165] (955s left) fbdev (read)
[46.878665] Starting subtest: read
[48.337573] Subtest read: SUCCESS (1.473s)
[48.458633] [009/165] (953s left) fbdev (write)
[48.663113] Starting subtest: write
[49.410507] Subtest write: SUCCESS (0.763s)
[49.533104] [010/165] (952s left) gem_basic (bad-close)
[49.786719] Starting subtest: bad-close
[49.832244] Subtest bad-close: SUCCESS (0.000s)
[49.926404] [011/165] (952s left) gem_basic (create-close)
[50.181042] Starting subtest: create-close
[50.220732] Subtest create-close: SUCCESS (0.000s)
[50.310541] [012/165] (952s left) gem_basic (create-fd-close)
[50.539165] Starting subtest: create-fd-close
[50.580471] Subtest create-fd-close: SUCCESS (0.000s)
[50.669267] [013/165] (951s left) gem_busy (busy@all-engines)
[50.929221] Starting subtest: busy
[50.953195] Starting dynamic subtest: all-engines
[50.995077] Dynamic subtest all-engines: SUCCESS (0.090s)
[51.017514] Subtest busy: SUCCESS (0.091s)
[51.391259] [014/165] (951s left) gem_close_race (basic-process)
[51.629800] Starting subtest: basic-process
[51.735760] Subtest basic-process: SUCCESS (0.106s)
[51.960723] [015/165] (950s left) gem_close_race (basic-threads)
[52.191249] Starting subtest: basic-threads
[53.394893] Subtest basic-threads: SUCCESS (1.204s)
[53.628662] [016/165] (949s left) gem_ctx_create (basic)
[53.888384] Starting subtest: basic
[53.911221] Subtest basic: SUCCESS (0.000s)
[54.077131] [017/165] (948s left) gem_ctx_create (basic-files)
[54.306368] Starting subtest: basic-files
[56.342232] Subtest basic-files: SUCCESS (2.039s)
[56.604152] [018/165] (946s left) gem_ctx_exec (basic)
[56.925257] Starting subtest: basic
[56.950596] Subtest basic: SUCCESS (0.008s)
[57.052600] [019/165] (946s left) gem_exec_basic (basic)
[57.338239] Starting subtest: basic
[57.368444] Starting dynamic subtest: rcs0-smem
[57.400867] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[57.436175] Starting dynamic subtest: bcs0-smem
[57.456682] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[57.493156] Starting dynamic subtest: vcs0-smem
[57.515832] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[57.549860] Starting dynamic subtest: vecs0-smem
[57.571236] Dynamic subtest vecs0-smem: SUCCESS (0.002s)
[57.594680] Subtest basic: SUCCESS (0.012s)
[57.684081] [020/165] (945s left) gem_exec_create (basic)
[57.923901] Starting subtest: basic
[57.944204] Starting dynamic subtest: smem
[59.992184] Dynamic subtest smem: SUCCESS (2.063s)
[60.012416] Subtest basic: SUCCESS (2.064s)
[60.194209] [021/165] (943s left) gem_exec_fence (basic-busy)
[60.557615] Starting subtest: basic-busy
[60.578112] Starting dynamic subtest: rcs0
[60.620099] Dynamic subtest rcs0: SUCCESS (0.042s)
[60.658601] Starting dynamic subtest: bcs0
[60.698866] Dynamic subtest bcs0: SUCCESS (0.033s)
[60.735910] Starting dynamic subtest: vcs0
[60.778479] Dynamic subtest vcs0: SUCCESS (0.022s)
[60.811340] Starting dynamic subtest: vecs0
[60.859962] Dynamic subtest vecs0: SUCCESS (0.029s)
[60.880172] Subtest basic-busy: SUCCESS (0.126s)
[61.513098] [022/165] (941s left) gem_exec_fence (basic-wait)
[61.865504] Starting subtest: basic-wait
[61.884892] Starting dynamic subtest: rcs0
[61.907686] Dynamic subtest rcs0: SUCCESS (0.026s)
[61.945248] Starting dynamic subtest: bcs0
[61.965241] Dynamic subtest bcs0: SUCCESS (0.026s)
[61.999307] Starting dynamic subtest: vcs0
[62.021885] Dynamic subtest vcs0: SUCCESS (0.028s)
[62.058340] Starting dynamic subtest: vecs0
[62.078639] Dynamic subtest vecs0: SUCCESS (0.035s)
[62.100309] Subtest basic-wait: SUCCESS (0.116s)
[62.679445] [023/165] (940s left) gem_exec_fence (basic-await)
[63.008423] Starting subtest: basic-await
[63.027924] Starting dynamic subtest: rcs0
[63.148550] Dynamic subtest rcs0: SUCCESS (0.138s)
[63.186934] Starting dynamic subtest: bcs0
[63.280978] Dynamic subtest bcs0: SUCCESS (0.130s)
[63.322988] Starting dynamic subtest: vcs0
[63.410275] Dynamic subtest vcs0: SUCCESS (0.131s)
[63.451100] Starting dynamic subtest: vecs0
[63.543775] Dynamic subtest vecs0: SUCCESS (0.132s)
[63.564425] Subtest basic-await: SUCCESS (0.535s)
[64.243796] [024/165] (939s left) gem_exec_fence (nb-await)
[64.594984] Starting subtest: nb-await
[64.618133] Starting dynamic subtest: rcs0
[64.668824] Dynamic subtest rcs0: SUCCESS (0.073s)
[64.705924] Starting dynamic subtest: bcs0
[64.752425] Dynamic subtest bcs0: SUCCESS (0.071s)
[64.789115] Starting dynamic subtest: vcs0
[64.833805] Dynamic subtest vcs0: SUCCESS (0.071s)
[64.872841] Starting dynamic subtest: vecs0
[64.893671] Dynamic subtest vecs0: SUCCESS (0.070s)
[64.914420] Subtest nb-await: SUCCESS (0.288s)
[65.574597] [025/165] (938s left) gem_exec_gttfill (basic)
[65.806905] Starting subtest: basic
[87.573067] Subtest basic: SUCCESS (21.626s)
[87.669376] [026/165] (916s left) gem_exec_parallel (engines)
[88.086788] Starting subtest: engines
[88.106332] Starting dynamic subtest: basic
[91.810772] Dynamic subtest basic: SUCCESS (3.577s)
[91.846709] Starting dynamic subtest: contexts
[96.442727] Dynamic subtest contexts: SUCCESS (4.654s)
[96.479307] Starting dynamic subtest: fds
[103.108422] Dynamic subtest fds: SUCCESS (6.663s)
[103.149191] Starting dynamic subtest: userptr
[106.780644] Dynamic subtest userptr: SUCCESS (3.643s)
[106.800995] Subtest engines: SUCCESS (18.539s)
[106.911579] [027/165] (896s left) gem_exec_store (basic)
[107.222787] Starting subtest: basic
[107.243313] Subtest basic: SUCCESS (0.070s)
[107.448410] [028/165] (896s left) gem_flink_basic (bad-flink)
[107.698392] Starting subtest: bad-flink
[107.720734] Subtest bad-flink: SUCCESS (0.000s)
[107.810726] [029/165] (896s left) gem_flink_basic (bad-open)
[108.056376] Starting subtest: bad-open
[108.079216] Subtest bad-open: SUCCESS (0.000s)
[108.168277] [030/165] (895s left) gem_flink_basic (basic)
[108.417908] Starting subtest: basic
[108.440605] Subtest basic: SUCCESS (0.000s)
[108.530263] [031/165] (895s left) gem_flink_basic (double-flink)
[108.758653] Starting subtest: double-flink
[108.779060] Subtest double-flink: SUCCESS (0.000s)
[108.874324] [032/165] (895s left) gem_flink_basic (flink-lifetime)
[109.122654] Starting subtest: flink-lifetime
[109.142651] Subtest flink-lifetime: SUCCESS (0.003s)
[109.234791] [033/165] (895s left) gem_huc_copy (huc-copy)
[109.467459] Starting subtest: huc-copy
[109.490122] Subtest huc-copy: SUCCESS (0.006s)
[109.579805] [034/165] (894s left) gem_linear_blits (basic)
[109.967368] Starting subtest: basic
[110.011342] Subtest basic: SUCCESS (0.026s)
[110.205081] [035/165] (894s left) gem_mmap (basic)
[110.625642] Starting subtest: basic
[110.736821] Subtest basic: SUCCESS (0.001s)
[110.839752] [036/165] (893s left) gem_mmap_gtt (basic)
[111.091174] Starting subtest: basic
[111.115231] Subtest basic: SUCCESS (0.001s)
[111.206334] [037/165] (893s left) gem_render_linear_blits (basic)
[111.434959] Starting subtest: basic
[111.539273] Subtest basic: SUCCESS (0.082s)
[111.630777] [038/165] (893s left) gem_render_tiled_blits (basic)
[111.868372] Starting subtest: basic
[111.995401] Subtest basic: SUCCESS (0.114s)
[112.090250] [039/165] (892s left) gem_ringfill (basic-all)
[112.589622] Starting subtest: basic-all
[113.766216] Subtest basic-all: SUCCESS (1.195s)
[113.914767] [040/165] (891s left) gem_softpin (allocator-basic)
[114.166720] Starting subtest: allocator-basic
[114.361940] Subtest allocator-basic: SUCCESS (0.238s)
[114.511917] [041/165] (890s left) gem_softpin (allocator-basic-reserve)
[114.742275] Starting subtest: allocator-basic-reserve
[114.958507] Subtest allocator-basic-reserve: SUCCESS (0.242s)
[115.110971] [042/165] (890s left) gem_softpin (safe-alignment)
[115.362764] Starting subtest: safe-alignment
[115.383149] Subtest safe-alignment: SUCCESS (0.031s)
[115.497698] [043/165] (889s left) gem_sync (basic-all)
[115.931947] Starting subtest: basic-all
[117.803100] Subtest basic-all: SUCCESS (2.065s)
[118.029230] [044/165] (887s left) gem_sync (basic-each)
[118.438385] Starting subtest: basic-each
[120.608813] Subtest basic-each: SUCCESS (2.361s)
[120.829526] [045/165] (884s left) gem_tiled_blits (basic)
[121.200166] Starting subtest: basic
[121.246930] Subtest basic: SUCCESS (0.049s)
[121.452989] [046/165] (884s left) gem_tiled_fence_blits (basic)
[121.993372] Starting subtest: basic
[122.036559] Subtest basic: SUCCESS (0.041s)
[122.239961] [047/165] (883s left) gem_tiled_pread_basic
[122.996811] [048/165] (882s left) gem_wait (busy@all-engines)
[123.252580] Starting subtest: busy
[123.274301] Starting dynamic subtest: all-engines
[123.777584] Dynamic subtest all-engines: SUCCESS (0.526s)
[123.800817] Subtest busy: SUCCESS (0.527s)
[124.035393] [049/165] (881s left) gem_wait (wait@all-engines)
[124.287153] Starting subtest: wait
[124.308063] Starting dynamic subtest: all-engines
[125.323544] Dynamic subtest all-engines: SUCCESS (1.038s)
[125.343993] Subtest wait: SUCCESS (1.038s)
[125.583121] [050/165] (880s left) i915_getparams_basic (basic-eu-total)
[125.818177] Starting subtest: basic-eu-total
[125.862752] Subtest basic-eu-total: SUCCESS (0.000s)
[125.958252] [051/165] (879s left) i915_getparams_basic (basic-subslice-total)
[126.188543] Starting subtest: basic-subslice-total
[126.233917] Subtest basic-subslice-total: SUCCESS (0.000s)
[126.326664] [052/165] (879s left) i915_hangman (error-state-basic)
[126.578943] Starting subtest: error-state-basic
[126.840872] Subtest error-state-basic: SUCCESS (0.288s)
[126.965400] [053/165] (879s left) i915_pciid
[127.333174] [054/165] (878s left) kms_addfb_basic (addfb25-4-tiled)
[127.737315] Starting subtest: addfb25-4-tiled
[127.757536] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[127.853088] [055/165] (878s left) kms_addfb_basic (addfb25-bad-modifier)
[128.080353] Starting subtest: addfb25-bad-modifier
[128.102213] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[128.280207] [056/165] (878s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[128.507401] Starting subtest: addfb25-framebuffer-vs-set-tiling
[128.526800] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[128.698930] [057/165] (877s left) kms_addfb_basic (addfb25-modifier-no-flag)
[128.926690] Starting subtest: addfb25-modifier-no-flag
[128.947639] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[129.131605] [058/165] (877s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[129.358227] Starting subtest: addfb25-x-tiled-legacy
[129.379962] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[129.543719] [059/165] (877s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[129.794455] Starting subtest: addfb25-x-tiled-mismatch-legacy
[129.813788] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.001s)
[129.979457] [060/165] (876s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[130.297176] Starting subtest: addfb25-yf-tiled-legacy
[130.317096] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[130.414495] [061/165] (876s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[130.736457] Starting subtest: addfb25-y-tiled-legacy
[130.758713] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[130.852416] [062/165] (875s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[131.168948] Starting subtest: addfb25-y-tiled-small-legacy
[131.189486] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.001s)
[131.283236] [063/165] (875s left) kms_addfb_basic (bad-pitch-0)
[131.511658] Starting subtest: bad-pitch-0
[131.533222] Subtest bad-pitch-0: SUCCESS (0.000s)
[131.711114] [064/165] (875s left) kms_addfb_basic (bad-pitch-1024)
[131.944330] Starting subtest: bad-pitch-1024
[131.963681] Subtest bad-pitch-1024: SUCCESS (0.000s)
[132.130184] [065/165] (874s left) kms_addfb_basic (bad-pitch-128)
[132.381751] Starting subtest: bad-pitch-128
[132.405058] Subtest bad-pitch-128: SUCCESS (0.000s)
[132.548826] [066/165] (874s left) kms_addfb_basic (bad-pitch-256)
[132.777191] Starting subtest: bad-pitch-256
[132.799113] Subtest bad-pitch-256: SUCCESS (0.000s)
[132.979761] [067/165] (874s left) kms_addfb_basic (bad-pitch-32)
[133.207836] Starting subtest: bad-pitch-32
[133.229208] Subtest bad-pitch-32: SUCCESS (0.000s)
[133.395393] [068/165] (873s left) kms_addfb_basic (bad-pitch-63)
[133.620963] Starting subtest: bad-pitch-63
[133.640303] Subtest bad-pitch-63: SUCCESS (0.000s)
[133.815907] [069/165] (873s left) kms_addfb_basic (bad-pitch-65536)
[134.053620] Starting subtest: bad-pitch-65536
[134.076417] Subtest bad-pitch-65536: SUCCESS (0.001s)
[134.232698] [070/165] (873s left) kms_addfb_basic (bad-pitch-999)
[134.459697] Starting subtest: bad-pitch-999
[134.482339] Subtest bad-pitch-999: SUCCESS (0.000s)
[134.662252] [071/165] (872s left) kms_addfb_basic (basic)
[134.888164] Starting subtest: basic
[134.912043] Subtest basic: SUCCESS (0.000s)
[135.096370] [072/165] (872s left) kms_addfb_basic (basic-x-tiled-legacy)
[135.384231] Starting subtest: basic-x-tiled-legacy
[135.412514] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[135.562320] [073/165] (872s left) kms_addfb_basic (basic-y-tiled-legacy)
[135.830404] Starting subtest: basic-y-tiled-legacy
[135.857452] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[136.013241] [074/165] (871s left) kms_addfb_basic (bo-too-small)
[136.333741] Starting subtest: bo-too-small
[136.353368] Subtest bo-too-small: SUCCESS (0.001s)
[136.448227] [075/165] (871s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[136.767100] Starting subtest: bo-too-small-due-to-tiling
[136.789445] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[136.879941] [076/165] (871s left) kms_addfb_basic (clobberred-modifier)
[137.110238] Starting subtest: clobberred-modifier
[137.131152] Subtest clobberred-modifier: SUCCESS (0.000s)
[137.315143] [077/165] (870s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[137.547326] Starting subtest: framebuffer-vs-set-tiling
[137.569183] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[137.727602] [078/165] (870s left) kms_addfb_basic (invalid-get-prop)
[137.957128] Starting subtest: invalid-get-prop
[137.977519] Subtest invalid-get-prop: SUCCESS (0.000s)
[138.149418] [079/165] (870s left) kms_addfb_basic (invalid-get-prop-any)
[138.396438] Starting subtest: invalid-get-prop-any
[138.418776] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[138.582419] [080/165] (869s left) kms_addfb_basic (invalid-set-prop)
[138.808208] Starting subtest: invalid-set-prop
[138.830220] Subtest invalid-set-prop: SUCCESS (0.000s)
[138.994580] [081/165] (869s left) kms_addfb_basic (invalid-set-prop-any)
[139.223228] Starting subtest: invalid-set-prop-any
[139.243193] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[139.393460] [082/165] (869s left) kms_addfb_basic (no-handle)
[139.623129] Starting subtest: no-handle
[139.642816] Subtest no-handle: SUCCESS (0.000s)
[139.814603] [083/165] (868s left) kms_addfb_basic (size-max)
[140.131777] Starting subtest: size-max
[140.153793] Subtest size-max: SUCCESS (0.000s)
[140.244513] [084/165] (868s left) kms_addfb_basic (small-bo)
[140.556252] Starting subtest: small-bo
[140.576027] Subtest small-bo: SUCCESS (0.000s)
[140.669530] [085/165] (868s left) kms_addfb_basic (tile-pitch-mismatch)
[140.898180] Starting subtest: tile-pitch-mismatch
[140.919895] Subtest tile-pitch-mismatch: SUCCESS (0.001s)
[141.093840] [086/165] (867s left) kms_addfb_basic (too-high)
[141.387901] Starting subtest: too-high
[141.407831] Subtest too-high: SUCCESS (0.002s)
[141.515194] [087/165] (867s left) kms_addfb_basic (too-wide)
[141.827482] Starting subtest: too-wide
[141.850054] Subtest too-wide: SUCCESS (0.000s)
[141.942879] [088/165] (867s left) kms_addfb_basic (unused-handle)
[142.188701] Starting subtest: unused-handle
[142.210596] Subtest unused-handle: SUCCESS (0.000s)
[142.365055] [089/165] (866s left) kms_addfb_basic (unused-modifier)
[142.589863] Starting subtest: unused-modifier
[142.611889] Subtest unused-modifier: SUCCESS (0.000s)
[142.801850] [090/165] (866s left) kms_addfb_basic (unused-offsets)
[143.030984] Starting subtest: unused-offsets
[143.053679] Subtest unused-offsets: SUCCESS (0.000s)
[143.238549] [091/165] (866s left) kms_addfb_basic (unused-pitches)
[143.491334] Starting subtest: unused-pitches
[143.516186] Subtest unused-pitches: SUCCESS (0.000s)
[143.678675] [092/165] (865s left) kms_busy (basic)
[144.011560] Starting subtest: basic
[144.033458] Starting dynamic subtest: flip
[145.076928] Dynamic subtest flip: SUCCESS (1.050s)
[145.118133] Starting dynamic subtest: modeset
[146.454677] Dynamic subtest modeset: SUCCESS (1.391s)
[146.475859] Subtest basic: SUCCESS (2.444s)
[146.848648] [093/165] (862s left) kms_prop_blob (basic)
[147.089290] Starting subtest: basic
[147.112376] Subtest basic: SUCCESS (0.000s)
[147.212312] [094/165] (862s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[147.521456] Starting subtest: basic-busy-flip-before-cursor-atomic
[147.561250] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[147.656256] [095/165] (862s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[147.974378] Starting subtest: basic-busy-flip-before-cursor-legacy
[148.016177] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[148.109801] [096/165] (861s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[148.406324] Starting subtest: basic-flip-after-cursor-atomic
[149.476703] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.051s)
[149.822740] [097/165] (860s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[150.117102] Starting subtest: basic-flip-after-cursor-legacy
[151.175512] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.058s)
[151.546527] [098/165] (858s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[151.864363] Starting subtest: basic-flip-after-cursor-varying-size
[152.927576] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.076s)
[153.294297] [099/165] (856s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[153.619114] Starting subtest: basic-flip-before-cursor-atomic
[154.654171] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.057s)
[155.025933] [100/165] (855s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[155.341885] Starting subtest: basic-flip-before-cursor-legacy
[156.386596] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.049s)
[156.756325] [101/165] (853s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[157.055303] Starting subtest: basic-flip-before-cursor-varying-size
[158.127346] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.058s)
[158.490610] [102/165] (851s left) kms_dsc (dsc-basic)
[158.813423] Subtest dsc-basic: SKIP (0.000s)
[158.908316] [103/165] (851s left) kms_flip (basic-flip-vs-dpms)
[159.252181] Starting subtest: basic-flip-vs-dpms
[159.271407] Starting dynamic subtest: A-DP1
[160.459607] Dynamic subtest A-DP1: SUCCESS (1.237s)
[160.499925] Starting dynamic subtest: B-DP1
[161.596318] Dynamic subtest B-DP1: SUCCESS (1.138s)
[161.637198] Starting dynamic subtest: C-DP1
[162.722789] Dynamic subtest C-DP1: SUCCESS (1.120s)
[162.742634] Subtest basic-flip-vs-dpms: SUCCESS (3.505s)
[163.162472] [104/165] (847s left) kms_flip (basic-flip-vs-modeset)
[163.511974] Starting subtest: basic-flip-vs-modeset
[163.541277] Starting dynamic subtest: A-DP1
[164.781577] Dynamic subtest A-DP1: SUCCESS (1.288s)
[164.819863] Starting dynamic subtest: B-DP1
[165.915908] Dynamic subtest B-DP1: SUCCESS (1.139s)
[165.956276] Starting dynamic subtest: C-DP1
[167.072400] Dynamic subtest C-DP1: SUCCESS (1.146s)
[167.092225] Subtest basic-flip-vs-modeset: SUCCESS (3.582s)
[167.454226] [105/165] (843s left) kms_flip (basic-flip-vs-wf_vblank)
[167.796241] Starting subtest: basic-flip-vs-wf_vblank
[167.816197] Starting dynamic subtest: A-DP1
[169.220423] Dynamic subtest A-DP1: SUCCESS (1.452s)
[169.258793] Starting dynamic subtest: B-DP1
[170.609652] Dynamic subtest B-DP1: SUCCESS (1.390s)
[170.647899] Starting dynamic subtest: C-DP1
[172.006479] Dynamic subtest C-DP1: SUCCESS (1.393s)
[172.028293] Subtest basic-flip-vs-wf_vblank: SUCCESS (4.245s)
[172.472619] [106/165] (838s left) kms_flip (basic-plain-flip)
[172.831898] Starting subtest: basic-plain-flip
[172.858633] Starting dynamic subtest: A-DP1
[173.991519] Dynamic subtest A-DP1: SUCCESS (1.187s)
[174.041298] Starting dynamic subtest: B-DP1
[175.104032] Dynamic subtest B-DP1: SUCCESS (1.110s)
[175.152094] Starting dynamic subtest: C-DP1
[176.215423] Dynamic subtest C-DP1: SUCCESS (1.109s)
[176.241734] Subtest basic-plain-flip: SUCCESS (3.416s)
[176.668806] [107/165] (834s left) kms_force_connector_basic (force-connector-state)
[176.956252] Subtest force-connector-state: SKIP (0.000s)
[177.051415] [108/165] (833s left) kms_force_connector_basic (force-edid)
[177.306730] Subtest force-edid: SKIP (0.000s)
[177.403547] [109/165] (833s left) kms_force_connector_basic (force-load-detect)
[177.679994] Subtest force-load-detect: SKIP (0.000s)
[177.775093] [110/165] (833s left) kms_force_connector_basic (prune-stale-modes)
[178.054821] Subtest prune-stale-modes: SKIP (0.000s)
[178.149885] [111/165] (833s left) kms_frontbuffer_tracking (basic)
[178.555246] Starting subtest: basic
[181.535243] Subtest basic: SUCCESS (3.051s)
[181.665734] [112/165] (829s left) kms_hdmi_inject (inject-audio)
[181.957194] Subtest inject-audio: SKIP (0.000s)
[182.053792] [113/165] (829s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[182.355353] Starting subtest: compare-crc-sanitycheck-xr24
[182.376226] Starting dynamic subtest: pipe-A-DP-1
[182.790951] Dynamic subtest pipe-A-DP-1: SUCCESS (0.424s)
[182.839352] Starting dynamic subtest: pipe-B-DP-1
[183.403481] Dynamic subtest pipe-B-DP-1: SUCCESS (0.609s)
[183.443099] Starting dynamic subtest: pipe-C-DP-1
[184.029762] Dynamic subtest pipe-C-DP-1: SUCCESS (0.611s)
[184.056044] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.656s)
[184.397128] [114/165] (827s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[184.706043] Starting subtest: compare-crc-sanitycheck-nv12
[184.727254] Starting dynamic subtest: pipe-A-DP-1
[184.769835] Dynamic subtest pipe-A-DP-1: SKIP (0.001s)
[184.811455] Starting dynamic subtest: pipe-B-DP-1
[184.855147] Dynamic subtest pipe-B-DP-1: SKIP (0.001s)
[184.896879] Starting dynamic subtest: pipe-C-DP-1
[184.942709] Dynamic subtest pipe-C-DP-1: SKIP (0.001s)
[184.986374] Subtest compare-crc-sanitycheck-nv12: SKIP (0.017s)
[185.085593] [115/165] (826s left) kms_pipe_crc_basic (hang-read-crc)
[185.386186] Starting subtest: hang-read-crc
[185.409736] Starting dynamic subtest: pipe-A-DP-1
[186.878944] Dynamic subtest pipe-A-DP-1: SUCCESS (1.472s)
[186.921243] Starting dynamic subtest: pipe-B-DP-1
[188.495401] Dynamic subtest pipe-B-DP-1: SUCCESS (1.627s)
[188.536425] Starting dynamic subtest: pipe-C-DP-1
[190.155943] Dynamic subtest pipe-C-DP-1: SUCCESS (1.660s)
[190.178982] Subtest hang-read-crc: SUCCESS (4.772s)
[190.529320] [116/165] (821s left) kms_pipe_crc_basic (nonblocking-crc)
[190.833334] Starting subtest: nonblocking-crc
[190.856586] Starting dynamic subtest: pipe-A-DP-1
[191.412391] Dynamic subtest pipe-A-DP-1: SUCCESS (0.574s)
[191.468351] Starting dynamic subtest: pipe-B-DP-1
[192.159068] Dynamic subtest pipe-B-DP-1: SUCCESS (0.745s)
[192.201050] Starting dynamic subtest: pipe-C-DP-1
[192.960292] Dynamic subtest pipe-C-DP-1: SUCCESS (0.770s)
[192.989388] Subtest nonblocking-crc: SUCCESS (2.103s)
[193.328622] [117/165] (818s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[193.984590] Starting subtest: nonblocking-crc-frame-sequence
[194.014181] Starting dynamic subtest: pipe-A-DP-1
[194.269656] Dynamic subtest pipe-A-DP-1: SUCCESS (0.582s)
[194.327508] Starting dynamic subtest: pipe-B-DP-1
[195.014748] Dynamic subtest pipe-B-DP-1: SUCCESS (0.748s)
[195.058924] Starting dynamic subtest: pipe-C-DP-1
[195.751605] Dynamic subtest pipe-C-DP-1: SUCCESS (0.754s)
[195.774162] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.099s)
[196.113572] [118/165] (815s left) kms_pipe_crc_basic (read-crc)
[196.417132] Starting subtest: read-crc
[196.437753] Starting dynamic subtest: pipe-A-DP-1
[196.913905] Dynamic subtest pipe-A-DP-1: SUCCESS (0.501s)
[196.973073] Starting dynamic subtest: pipe-B-DP-1
[197.616861] Dynamic subtest pipe-B-DP-1: SUCCESS (0.678s)
[197.659211] Starting dynamic subtest: pipe-C-DP-1
[198.293369] Dynamic subtest pipe-C-DP-1: SUCCESS (0.686s)
[198.314851] Subtest read-crc: SUCCESS (1.879s)
[198.653993] [119/165] (813s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[198.957375] Starting subtest: read-crc-frame-sequence
[198.978917] Starting dynamic subtest: pipe-A-DP-1
[199.498428] Dynamic subtest pipe-A-DP-1: SUCCESS (0.519s)
[199.540670] Starting dynamic subtest: pipe-B-DP-1
[200.188474] Dynamic subtest pipe-B-DP-1: SUCCESS (0.684s)
[200.231075] Starting dynamic subtest: pipe-C-DP-1
[200.885988] Dynamic subtest pipe-C-DP-1: SUCCESS (0.691s)
[200.908202] Subtest read-crc-frame-sequence: SUCCESS (1.909s)
[201.232953] [120/165] (810s left) kms_pm_backlight (basic-brightness)
[201.561617] Subtest basic-brightness: SKIP (0.000s)
[201.660605] [121/165] (810s left) kms_pm_rpm (basic-pci-d3-state)
[203.543346] Starting subtest: basic-pci-d3-state
[204.183951] Subtest basic-pci-d3-state: SUCCESS (0.627s)
[204.404511] [122/165] (807s left) kms_pm_rpm (basic-rte)
[204.638917] Starting subtest: basic-rte
[206.797267] Subtest basic-rte: SUCCESS (2.187s)
[207.705797] [123/165] (804s left) kms_psr (psr-primary-page-flip)
[208.034712] Subtest psr-primary-page-flip: SKIP (0.000s)
[208.137800] [124/165] (804s left) kms_psr (psr-cursor-plane-move)
[208.465991] Subtest psr-cursor-plane-move: SKIP (0.000s)
[208.566552] [125/165] (803s left) kms_psr (psr-sprite-plane-onoff)
[208.891689] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[208.990331] [126/165] (803s left) kms_psr (psr-primary-mmap-gtt)
[209.316938] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[209.414225] [127/165] (803s left) kms_setmode (basic-clone-single-crtc)
[209.658728] Starting subtest: basic-clone-single-crtc
[209.727263] Subtest basic-clone-single-crtc: SKIP (0.000s)
[209.824984] [128/165] (802s left) i915_pm_rps (basic-api)
[210.089608] Starting subtest: basic-api
[210.112618] Subtest basic-api: SUCCESS (0.008s)
[210.231552] [129/165] (802s left) prime_self_import (basic-llseek-bad)
[210.474083] Starting subtest: basic-llseek-bad
[210.518995] Subtest basic-llseek-bad: SUCCESS (0.005s)
[210.617809] [130/165] (802s left) prime_self_import (basic-llseek-size)
[210.855314] Starting subtest: basic-llseek-size
[210.898877] Subtest basic-llseek-size: SUCCESS (0.006s)
[210.997443] [131/165] (802s left) prime_self_import (basic-with_fd_dup)
[211.231313] Starting subtest: basic-with_fd_dup
[211.272870] Subtest basic-with_fd_dup: SUCCESS (0.006s)
[211.370392] [132/165] (801s left) prime_self_import (basic-with_one_bo)
[211.584710] Starting subtest: basic-with_one_bo
[211.627743] Subtest basic-with_one_bo: SUCCESS (0.007s)
[211.725567] [133/165] (801s left) prime_self_import (basic-with_one_bo_two_files)
[211.954889] Starting subtest: basic-with_one_bo_two_files
[211.998625] Subtest basic-with_one_bo_two_files: SUCCESS (0.006s)
[212.093556] [134/165] (801s left) prime_self_import (basic-with_two_bos)
[212.355176] Starting subtest: basic-with_two_bos
[212.417886] Subtest basic-with_two_bos: SUCCESS (0.006s)
[212.538669] [135/165] (801s left) prime_vgem (basic-fence-flip)
[212.814559] Starting subtest: basic-fence-flip
[213.386133] Subtest basic-fence-flip: SUCCESS (0.613s)
[213.513698] [136/165] (800s left) prime_vgem (basic-fence-mmap)
[213.794667] Starting subtest: basic-fence-mmap
[213.816772] Subtest basic-fence-mmap: SUCCESS (0.071s)
[213.934694] [137/165] (799s left) prime_vgem (basic-fence-read)
[214.210206] Starting subtest: basic-fence-read
[214.267253] Subtest basic-fence-read: SUCCESS (0.109s)
[214.387664] [138/165] (799s left) prime_vgem (basic-gtt)
[214.662811] Starting subtest: basic-gtt
[214.686568] Subtest basic-gtt: SUCCESS (0.034s)
[214.808095] [139/165] (799s left) prime_vgem (basic-read)
[215.083910] Starting subtest: basic-read
[215.106540] Subtest basic-read: SUCCESS (0.053s)
[215.235323] [140/165] (798s left) prime_vgem (basic-write)
[215.514127] Starting subtest: basic-write
[215.534872] Subtest basic-write: SUCCESS (0.065s)
[215.657632] [141/165] (798s left) vgem_basic (setversion)
[215.924249] Starting subtest: setversion
[215.968240] Subtest setversion: SUCCESS (0.000s)
[216.065522] [142/165] (798s left) vgem_basic (create)
[216.303463] Starting subtest: create
[216.324780] Subtest create: SUCCESS (0.000s)
[216.422722] [143/165] (797s left) vgem_basic (debugfs)
[216.659191] Starting subtest: debugfs
[216.682824] Subtest debugfs: SUCCESS (0.001s)
[216.787682] [144/165] (797s left) vgem_basic (dmabuf-export)
[217.038674] Starting subtest: dmabuf-export
[217.087050] Subtest dmabuf-export: SUCCESS (0.004s)
[217.184146] [145/165] (797s left) vgem_basic (dmabuf-fence)
[217.421461] Starting subtest: dmabuf-fence
[217.443231] Subtest dmabuf-fence: SUCCESS (0.002s)
[217.540291] [146/165] (797s left) vgem_basic (dmabuf-fence-before)
[217.795140] Starting subtest: dmabuf-fence-before
[217.815508] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[217.913119] [147/165] (796s left) vgem_basic (dmabuf-mmap)
[218.144083] Starting subtest: dmabuf-mmap
[218.166467] Subtest dmabuf-mmap: SUCCESS (0.042s)
[218.263155] [148/165] (796s left) vgem_basic (mmap)
[218.518918] Starting subtest: mmap
[218.540987] Subtest mmap: SUCCESS (0.026s)
[218.644489] [149/165] (796s left) vgem_basic (second-client)
[218.883545] Starting subtest: second-client
[218.928631] Subtest second-client: SUCCESS (0.033s)
[219.026672] [150/165] (796s left) vgem_basic (sysfs)
[219.279845] Starting subtest: sysfs
[219.301838] Subtest sysfs: SUCCESS (0.001s)
[219.398740] [151/165] (795s left) core_hotunplug (unbind-rebind)
[219.711250] Starting subtest: unbind-rebind
[223.573484] Subtest unbind-rebind: SUCCESS (3.873s)
[223.673580] [152/165] (791s left) vgem_basic (unload)
[223.916778] Starting subtest: unload
[253.121182] Subtest unload: SUCCESS (29.054s)
[253.221197] [153/165] (762s left) i915_module_load (reload)
[253.458967] Starting subtest: reload
[257.179502] Subtest reload: SUCCESS (3.752s)
[257.298247] [154/165] (758s left) gem_lmem_swapping (basic)
[257.586809] Subtest basic: SKIP (0.000s)
[257.681593] [155/165] (758s left) gem_lmem_swapping (parallel-random-engines)
[257.953279] Subtest parallel-random-engines: SKIP (0.000s)
[258.046766] [156/165] (757s left) gem_lmem_swapping (random-engines)
[258.319544] Subtest random-engines: SKIP (0.000s)
[258.412557] [157/165] (757s left) gem_lmem_swapping (verify-random)
[258.673448] Subtest verify-random: SKIP (0.000s)
[258.768238] [158/165] (757s left) i915_pm_rpm (module-reload)
[261.000284] Starting subtest: module-reload
[270.697447] Subtest module-reload: SUCCESS (9.700s)
[270.788553] [159/165] (745s left) i915_selftest (live)
[271.029641] Starting subtest: live
[271.049896] Starting dynamic subtest: sanitycheck
[272.627414] Dynamic subtest sanitycheck: SUCCESS (1.594s)
[272.672831] Starting dynamic subtest: uncore
[274.089538] Dynamic subtest uncore: SUCCESS (1.464s)
[274.125736] Starting dynamic subtest: workarounds
[276.035088] Dynamic subtest workarounds: SUCCESS (1.944s)
[276.070152] Starting dynamic subtest: gt_engines
[278.215866] Dynamic subtest gt_engines: SUCCESS (2.177s)
[278.250800] Starting dynamic subtest: gt_timelines
[300.919897] Dynamic subtest gt_timelines: SUCCESS (22.700s)
[300.965626] Starting dynamic subtest: gt_contexts
[302.731891] Dynamic subtest gt_contexts: SUCCESS (1.800s)
[302.785856] Starting dynamic subtest: gt_lrc
[307.251500] Dynamic subtest gt_lrc: SUCCESS (4.521s)
[307.298698] Starting dynamic subtest: gt_mocs
[309.080399] Dynamic subtest gt_mocs: SUCCESS (1.828s)
[309.128597] Starting dynamic subtest: gt_pm
[317.955069] Dynamic subtest gt_pm: SUCCESS (8.868s)
[318.003286] Starting dynamic subtest: gt_heartbeat
[333.087937] Dynamic subtest gt_heartbeat: FAIL (10.402s)
[333.135259] Starting dynamic subtest: gt_tlb
[333.161394] Dynamic subtest gt_tlb: SUCCESS (2.436s)
[333.200426] Starting dynamic subtest: requests
[338.865384] Dynamic subtest requests: SUCCESS (8.062s)
[338.913121] Starting dynamic subtest: migrate
[343.929248] Dynamic subtest migrate: SUCCESS (5.060s)
[343.966642] Starting dynamic subtest: active
[345.443628] Dynamic subtest active: SUCCESS (1.510s)
[345.490930] Starting dynamic subtest: objects
[350.196039] Dynamic subtest objects: SUCCESS (4.751s)
[350.233893] Starting dynamic subtest: mman
[359.443177] Dynamic subtest mman: SUCCESS (9.241s)
[359.478429] Starting dynamic subtest: dmabuf
[361.101833] Dynamic subtest dmabuf: SUCCESS (1.657s)
[361.136743] Starting dynamic subtest: vma
[362.619703] Dynamic subtest vma: SUCCESS (1.514s)
[362.654476] Starting dynamic subtest: coherency
[366.830049] Dynamic subtest coherency: SUCCESS (4.202s)
[366.872411] Starting dynamic subtest: gtt
[376.144444] Dynamic subtest gtt: SUCCESS (9.314s)
[376.190596] Starting dynamic subtest: gem
[378.930372] Dynamic subtest gem: SUCCESS (2.782s)
[378.968453] Starting dynamic subtest: evict
[384.948954] Dynamic subtest evict: SUCCESS (6.016s)
[384.986773] Starting dynamic subtest: hugepages
[393.095977] Dynamic subtest hugepages: SUCCESS (8.143s)
[393.144724] Starting dynamic subtest: gem_contexts
[418.167090] Dynamic subtest gem_contexts: SUCCESS (24.881s)
[418.211112] Starting dynamic subtest: client
[419.673741] Dynamic subtest client: SUCCESS (1.684s)
[419.716913] Starting dynamic subtest: gem_migrate
[421.163117] Dynamic subtest gem_migrate: SUCCESS (1.492s)
[421.198554] Starting dynamic subtest: reset
[434.630987] Dynamic subtest reset: SUCCESS (13.463s)
[434.678907] Starting dynamic subtest: memory_region
[436.149862] Dynamic subtest memory_region: SUCCESS (1.516s)
[436.197241] Starting dynamic subtest: hangcheck
[461.046826] Dynamic subtest hangcheck: SUCCESS (24.892s)
[461.084392] Starting dynamic subtest: execlists
[473.333675] Dynamic subtest execlists: SUCCESS (12.285s)
[473.369204] Starting dynamic subtest: ring_submission
[474.813864] Dynamic subtest ring_submission: SUCCESS (1.478s)
[474.862482] Starting dynamic subtest: perf
[476.534937] Dynamic subtest perf: SUCCESS (1.715s)
[476.570919] Starting dynamic subtest: slpc
[478.076138] Dynamic subtest slpc: SUCCESS (1.537s)
[478.123615] Starting dynamic subtest: guc
[479.598366] Dynamic subtest guc: SUCCESS (1.514s)
[479.635960] Starting dynamic subtest: guc_multi_lrc
[481.136779] Dynamic subtest guc_multi_lrc: SUCCESS (1.533s)
[481.172775] Starting dynamic subtest: guc_hang
[482.749415] Dynamic subtest guc_hang: SUCCESS (1.611s)
[482.791666] Starting dynamic subtest: late_gt_pm
[484.332290] Dynamic subtest late_gt_pm: SUCCESS (1.583s)
[484.354920] Subtest live: FAIL (213.309s)
[484.445779] [160/165] (531s left) dmabuf (all-tests)
[484.667443] Starting subtest: all-tests
[484.686851] Starting dynamic subtest: sanitycheck
[484.750890] Dynamic subtest sanitycheck: SUCCESS (0.081s)
[484.786266] Starting dynamic subtest: dma_fence
[484.950512] Dynamic subtest dma_fence: SUCCESS (0.199s)
[484.986296] Starting dynamic subtest: dma_fence_chain
[508.281215] Dynamic subtest dma_fence_chain: SUCCESS (23.326s)
[508.323960] Starting dynamic subtest: dma_fence_unwrap
[508.367965] Dynamic subtest dma_fence_unwrap: SUCCESS (0.063s)
[508.404239] Starting dynamic subtest: dma_resv
[508.426673] Dynamic subtest dma_resv: SUCCESS (0.070s)
[508.449254] Subtest all-tests: SUCCESS (23.754s)
[508.544391] [161/165] (507s left) i915_suspend (basic-s2idle-without-i915)
[508.763853] Starting subtest: basic-s2idle-without-i915
[529.763767] Subtest basic-s2idle-without-i915: SUCCESS (5.008s)
[533.120217] [162/165] (502s left) i915_suspend (basic-s3-without-i915)
[533.337475] Starting subtest: basic-s3-without-i915
[551.444239] Subtest basic-s3-without-i915: SUCCESS (3.691s)
[555.129261] [163/165] (498s left) gem_exec_suspend (basic-s0)
[555.402546] Starting subtest: basic-S0
[555.425003] Starting dynamic subtest: smem
[576.159680] Dynamic subtest smem: SUCCESS (5.366s)
[576.179696] Subtest basic-S0: SUCCESS (5.369s)
[582.556334] [164/165] (492s left) gem_exec_suspend (basic-s3)
[582.806662] Starting subtest: basic-S3
[582.828831] Starting dynamic subtest: smem
[603.698705] Dynamic subtest smem: SUCCESS (5.926s)
[603.719688] Subtest basic-S3: SUCCESS (5.928s)
[606.079909] [165/165] (486s left) kms_pipe_crc_basic (suspend-read-crc)
[606.401351] Starting subtest: suspend-read-crc
[606.422210] Starting dynamic subtest: pipe-A-DP-1
[624.413255] Dynamic subtest pipe-A-DP-1: SUCCESS (2.502s)
[624.458882] Starting dynamic subtest: pipe-B-DP-1
[642.380535] Dynamic subtest pipe-B-DP-1: SUCCESS (2.585s)
[642.426300] Starting dynamic subtest: pipe-C-DP-1
[660.385703] Dynamic subtest pipe-C-DP-1: SUCCESS (2.432s)
[660.406113] Subtest suspend-read-crc: SUCCESS (7.534s)
[664.843286] Closing watchdogs
Done.