[195.493536] Initializing watchdogs
[195.494569]   /dev/watchdog0
[195.569188] [001/165] (960s left) i915_module_load (load)
[196.403673] Starting subtest: load
[206.256184] Subtest load: SUCCESS (9.711s)
[206.767707] [002/165] (949s left) core_auth (basic-auth)
[207.909574] Starting subtest: basic-auth
[207.920974] Subtest basic-auth: SUCCESS (0.007s)
[208.410334] [003/165] (948s left) debugfs_test (read_all_entries)
[209.231386] Starting subtest: read_all_entries
[209.821773] Subtest read_all_entries: SUCCESS (0.590s)
[210.813339] [004/165] (946s left) debugfs_test (basic-hwmon)
[211.567644] Starting subtest: basic-hwmon
[211.582740] Subtest basic-hwmon: SKIP (0.001s)
[212.565303] [005/165] (944s left) fbdev (eof)
[213.269756] Starting subtest: eof
[213.277728] Subtest eof: SUCCESS (0.007s)
[213.524805] [006/165] (944s left) fbdev (info)
[214.149773] Starting subtest: info
[214.157142] Subtest info: SUCCESS (0.000s)
[214.416162] [007/165] (943s left) fbdev (nullptr)
[215.040133] Starting subtest: nullptr
[215.047738] Subtest nullptr: SUCCESS (0.000s)
[215.263842] [008/165] (942s left) fbdev (read)
[215.914668] Starting subtest: read
[218.387778] Subtest read: SUCCESS (2.418s)
[218.552139] [009/165] (939s left) fbdev (write)
[219.195623] Starting subtest: write
[220.505883] Subtest write: SUCCESS (1.258s)
[220.695668] [010/165] (937s left) gem_basic (bad-close)
[221.354247] Starting subtest: bad-close
[221.371951] Subtest bad-close: SUCCESS (0.000s)
[221.584687] [011/165] (936s left) gem_basic (create-close)
[222.205901] Starting subtest: create-close
[222.222627] Subtest create-close: SUCCESS (0.001s)
[222.445214] [012/165] (935s left) gem_basic (create-fd-close)
[223.061627] Starting subtest: create-fd-close
[223.077180] Subtest create-fd-close: SUCCESS (0.002s)
[223.305393] [013/165] (935s left) gem_busy (busy@all-engines)
[224.165410] Starting subtest: busy
[224.172327] Starting dynamic subtest: all-engines
[224.612871] Dynamic subtest all-engines: SUCCESS (0.455s)
[224.620247] Subtest busy: SUCCESS (0.457s)
[225.314835] [014/165] (933s left) gem_close_race (basic-process)
[226.233741] Starting subtest: basic-process
[226.568774] Subtest basic-process: SUCCESS (0.365s)
[226.929564] [015/165] (931s left) gem_close_race (basic-threads)
[227.740818] Starting subtest: basic-threads
[229.356415] Subtest basic-threads: SUCCESS (1.677s)
[229.888636] [016/165] (929s left) gem_ctx_create (basic)
[231.048175] Starting subtest: basic
[231.059234] Subtest basic: SUCCESS (0.000s)
[231.690350] [017/165] (927s left) gem_ctx_create (basic-files)
[232.801576] Starting subtest: basic-files
[234.993153] Subtest basic-files: SUCCESS (2.214s)
[235.399781] [018/165] (923s left) gem_ctx_exec (basic)
[236.114632] Starting subtest: basic
[236.148768] Subtest basic: SUCCESS (0.033s)
[236.477534] [019/165] (923s left) gem_exec_basic (basic)
[237.270500] Starting subtest: basic
[237.282812] Starting dynamic subtest: rcs0-smem
[237.290862] Dynamic subtest rcs0-smem: SUCCESS (0.019s)
[237.359144] Starting dynamic subtest: bcs0-smem
[237.365753] Dynamic subtest bcs0-smem: SUCCESS (0.012s)
[237.423887] Starting dynamic subtest: vcs0-smem
[237.429820] Dynamic subtest vcs0-smem: SUCCESS (0.005s)
[237.488765] Starting dynamic subtest: vecs0-smem
[237.500316] Dynamic subtest vecs0-smem: SUCCESS (0.004s)
[237.509234] Subtest basic: SUCCESS (0.050s)
[237.856781] [020/165] (921s left) gem_exec_create (basic)
[238.763733] Starting subtest: basic
[238.770734] Starting dynamic subtest: smem
[240.944733] Dynamic subtest smem: SUCCESS (2.234s)
[240.955694] Subtest basic: SUCCESS (2.237s)
[241.456382] [021/165] (918s left) gem_exec_fence (basic-busy)
[242.574497] Starting subtest: basic-busy
[242.582886] Starting dynamic subtest: rcs0
[242.598559] Dynamic subtest rcs0: SUCCESS (0.069s)
[242.668969] Starting dynamic subtest: bcs0
[242.687959] Dynamic subtest bcs0: SUCCESS (0.069s)
[242.816245] Starting dynamic subtest: vcs0
[242.829794] Dynamic subtest vcs0: SUCCESS (0.056s)
[242.892199] Starting dynamic subtest: vecs0
[242.905442] Dynamic subtest vecs0: SUCCESS (0.040s)
[242.912451] Subtest basic-busy: SUCCESS (0.245s)
[245.036098] [022/165] (915s left) gem_exec_fence (basic-wait)
[246.053137] Starting subtest: basic-wait
[246.060285] Starting dynamic subtest: rcs0
[246.067547] Dynamic subtest rcs0: SUCCESS (0.065s)
[246.196846] Starting dynamic subtest: bcs0
[246.205086] Dynamic subtest bcs0: SUCCESS (0.060s)
[246.261841] Starting dynamic subtest: vcs0
[246.269182] Dynamic subtest vcs0: SUCCESS (0.046s)
[246.336924] Starting dynamic subtest: vecs0
[246.343793] Dynamic subtest vecs0: SUCCESS (0.041s)
[246.351128] Subtest basic-wait: SUCCESS (0.219s)
[248.096198] [023/165] (912s left) gem_exec_fence (basic-await)
[249.197352] Starting subtest: basic-await
[249.205721] Starting dynamic subtest: rcs0
[249.653590] Dynamic subtest rcs0: SUCCESS (0.460s)
[249.753694] Starting dynamic subtest: bcs0
[250.117161] Dynamic subtest bcs0: SUCCESS (0.462s)
[250.180389] Starting dynamic subtest: vcs0
[250.556167] Dynamic subtest vcs0: SUCCESS (0.458s)
[250.616181] Starting dynamic subtest: vecs0
[251.012626] Dynamic subtest vecs0: SUCCESS (0.457s)
[251.022315] Subtest basic-await: SUCCESS (1.847s)
[252.798455] [024/165] (907s left) gem_exec_fence (nb-await)
[253.827766] Starting subtest: nb-await
[253.834662] Starting dynamic subtest: rcs0
[253.991205] Dynamic subtest rcs0: SUCCESS (0.161s)
[254.051705] Starting dynamic subtest: bcs0
[254.076182] Dynamic subtest bcs0: SUCCESS (0.133s)
[254.142651] Starting dynamic subtest: vcs0
[254.194393] Dynamic subtest vcs0: SUCCESS (0.118s)
[254.257781] Starting dynamic subtest: vecs0
[254.318605] Dynamic subtest vecs0: SUCCESS (0.122s)
[254.324933] Subtest nb-await: SUCCESS (0.544s)
[256.124206] [025/165] (904s left) gem_exec_gttfill (basic)
[256.960821] Starting subtest: basic
[262.369689] Subtest basic: SKIP (5.337s)
[262.867385] [026/165] (898s left) gem_exec_parallel (engines)
[264.171764] Starting subtest: engines
[264.179697] Starting dynamic subtest: basic
[275.905661] Dynamic subtest basic: SUCCESS (11.752s)
[276.042919] Starting dynamic subtest: contexts
[296.899435] Dynamic subtest contexts: SUCCESS (20.973s)
[297.017903] Starting dynamic subtest: fds
[329.316227] Dynamic subtest fds: SUCCESS (32.465s)
[329.437277] Starting dynamic subtest: userptr
[341.856850] Dynamic subtest userptr: SUCCESS (12.501s)
[341.864164] Subtest engines: SUCCESS (77.697s)
[342.408093] [027/165] (818s left) gem_exec_store (basic)
[343.321190] Starting subtest: basic
[343.472355] Subtest basic: SUCCESS (0.163s)
[343.881277] [028/165] (817s left) gem_flink_basic (bad-flink)
[344.549955] Starting subtest: bad-flink
[344.558998] Subtest bad-flink: SUCCESS (0.000s)
[344.848249] [029/165] (816s left) gem_flink_basic (bad-open)
[345.502445] Starting subtest: bad-open
[345.514640] Subtest bad-open: SUCCESS (0.000s)
[345.784611] [030/165] (816s left) gem_flink_basic (basic)
[346.411774] Starting subtest: basic
[346.423558] Subtest basic: SUCCESS (0.001s)
[346.714669] [031/165] (815s left) gem_flink_basic (double-flink)
[347.320669] Starting subtest: double-flink
[347.327702] Subtest double-flink: SUCCESS (0.001s)
[347.617383] [032/165] (814s left) gem_flink_basic (flink-lifetime)
[348.230900] Starting subtest: flink-lifetime
[348.241968] Subtest flink-lifetime: SUCCESS (0.011s)
[348.530101] [033/165] (814s left) gem_huc_copy (huc-copy)
[349.233582] Starting subtest: huc-copy
[349.249185] Subtest huc-copy: SKIP (0.000s)
[349.540178] [034/165] (813s left) gem_linear_blits (basic)
[351.355277] Subtest basic: SKIP (0.000s)
[351.789469] [035/165] (811s left) gem_mmap (basic)
[353.086643] Starting subtest: basic
[353.129514] Subtest basic: SUCCESS (0.003s)
[353.443599] [036/165] (809s left) gem_mmap_gtt (basic)
[354.220624] Starting subtest: basic
[354.227879] Subtest basic: SUCCESS (0.007s)
[354.594645] [037/165] (808s left) gem_render_linear_blits (basic)
[355.374796] Starting subtest: basic
[355.649196] Subtest basic: SUCCESS (0.271s)
[355.939285] [038/165] (807s left) gem_render_tiled_blits (basic)
[356.707929] Starting subtest: basic
[357.025839] Subtest basic: SUCCESS (0.311s)
[357.347481] [039/165] (806s left) gem_ringfill (basic-all)
[359.168455] Starting subtest: basic-all
[360.979727] Subtest basic-all: SUCCESS (1.760s)
[361.240431] [040/165] (802s left) gem_softpin (allocator-basic)
[361.981909] Starting subtest: allocator-basic
[363.195290] Subtest allocator-basic: SUCCESS (1.157s)
[363.550575] [041/165] (800s left) gem_softpin (allocator-basic-reserve)
[364.214128] Starting subtest: allocator-basic-reserve
[365.386887] Subtest allocator-basic-reserve: SUCCESS (1.099s)
[365.773825] [042/165] (798s left) gem_softpin (safe-alignment)
[366.441676] Starting subtest: safe-alignment
[366.605244] Subtest safe-alignment: SUCCESS (0.117s)
[366.867153] [043/165] (797s left) gem_sync (basic-all)
[367.721869] Starting subtest: basic-all
[372.496726] Subtest basic-all: SUCCESS (4.774s)
[373.034183] [044/165] (791s left) gem_sync (basic-each)
[373.805342] Starting subtest: basic-each
[377.586713] Subtest basic-each: SUCCESS (3.796s)
[378.132489] [045/165] (787s left) gem_tiled_blits (basic)
[379.901683] Subtest basic: SKIP (0.000s)
[380.174308] [046/165] (785s left) gem_tiled_fence_blits (basic)
[382.537685] Starting subtest: basic
[382.561940] Subtest basic: SUCCESS (0.114s)
[383.062373] [047/165] (782s left) gem_tiled_pread_basic
[385.486252] [048/165] (780s left) gem_wait (busy@all-engines)
[386.405524] Starting subtest: busy
[386.411688] Starting dynamic subtest: all-engines
[387.016696] Dynamic subtest all-engines: SUCCESS (0.631s)
[387.022944] Subtest busy: SUCCESS (0.634s)
[387.795261] [049/165] (778s left) gem_wait (wait@all-engines)
[388.630924] Starting subtest: wait
[388.639375] Starting dynamic subtest: all-engines
[389.720729] Dynamic subtest all-engines: SUCCESS (1.114s)
[389.729186] Subtest wait: SUCCESS (1.118s)
[390.379296] [050/165] (775s left) i915_getparams_basic (basic-eu-total)
[391.059823] Starting subtest: basic-eu-total
[391.075881] Subtest basic-eu-total: SUCCESS (0.000s)
[391.446377] [051/165] (775s left) i915_getparams_basic (basic-subslice-total)
[392.083133] Starting subtest: basic-subslice-total
[392.098711] Subtest basic-subslice-total: SUCCESS (0.000s)
[392.396841] [052/165] (774s left) i915_hangman (error-state-basic)
[393.234918] Starting subtest: error-state-basic
[393.298542] Subtest error-state-basic: SUCCESS (0.052s)
[393.647984] [053/165] (773s left) i915_pciid
[394.636484] [054/165] (772s left) kms_addfb_basic (addfb25-4-tiled)
[396.309922] Starting subtest: addfb25-4-tiled
[396.318591] Subtest addfb25-4-tiled: SUCCESS (0.002s)
[396.644220] [055/165] (770s left) kms_addfb_basic (addfb25-bad-modifier)
[397.276981] Starting subtest: addfb25-bad-modifier
[397.283804] Subtest addfb25-bad-modifier: SUCCESS (0.002s)
[398.294527] [056/165] (769s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[398.941410] Starting subtest: addfb25-framebuffer-vs-set-tiling
[398.948936] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.002s)
[400.042479] [057/165] (767s left) kms_addfb_basic (addfb25-modifier-no-flag)
[400.726822] Starting subtest: addfb25-modifier-no-flag
[400.737467] Subtest addfb25-modifier-no-flag: SUCCESS (0.001s)
[401.744179] [058/165] (766s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[402.415925] Starting subtest: addfb25-x-tiled-legacy
[402.424169] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[403.427892] [059/165] (765s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[404.082215] Starting subtest: addfb25-x-tiled-mismatch-legacy
[404.089807] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.002s)
[405.072345] [060/165] (763s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[406.370683] Starting subtest: addfb25-yf-tiled-legacy
[406.380197] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.001s)
[406.764355] [061/165] (762s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[408.064300] Starting subtest: addfb25-y-tiled-legacy
[408.076859] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[408.439422] [062/165] (760s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[409.740762] Starting subtest: addfb25-y-tiled-small-legacy
[409.750993] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.003s)
[410.120642] [063/165] (759s left) kms_addfb_basic (bad-pitch-0)
[410.761728] Starting subtest: bad-pitch-0
[410.769934] Subtest bad-pitch-0: SUCCESS (0.000s)
[411.776336] [064/165] (758s left) kms_addfb_basic (bad-pitch-1024)
[412.447641] Starting subtest: bad-pitch-1024
[412.454549] Subtest bad-pitch-1024: SUCCESS (0.000s)
[413.510309] [065/165] (756s left) kms_addfb_basic (bad-pitch-128)
[414.200811] Starting subtest: bad-pitch-128
[414.208403] Subtest bad-pitch-128: SUCCESS (0.000s)
[415.228600] [066/165] (755s left) kms_addfb_basic (bad-pitch-256)
[415.916734] Starting subtest: bad-pitch-256
[415.925692] Subtest bad-pitch-256: SUCCESS (0.000s)
[416.939254] [067/165] (753s left) kms_addfb_basic (bad-pitch-32)
[417.600670] Starting subtest: bad-pitch-32
[417.608866] Subtest bad-pitch-32: SUCCESS (0.000s)
[418.615646] [068/165] (752s left) kms_addfb_basic (bad-pitch-63)
[419.298792] Starting subtest: bad-pitch-63
[419.310906] Subtest bad-pitch-63: SUCCESS (0.000s)
[420.338172] [069/165] (751s left) kms_addfb_basic (bad-pitch-65536)
[421.008083] Starting subtest: bad-pitch-65536
[421.015915] Subtest bad-pitch-65536: SUCCESS (0.002s)
[422.000669] [070/165] (749s left) kms_addfb_basic (bad-pitch-999)
[422.669746] Starting subtest: bad-pitch-999
[422.677682] Subtest bad-pitch-999: SUCCESS (0.000s)
[423.640778] [071/165] (748s left) kms_addfb_basic (basic)
[424.305903] Starting subtest: basic
[424.312878] Subtest basic: SUCCESS (0.001s)
[425.362495] [072/165] (746s left) kms_addfb_basic (basic-x-tiled-legacy)
[426.028689] Starting subtest: basic-x-tiled-legacy
[426.035871] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[427.030306] [073/165] (745s left) kms_addfb_basic (basic-y-tiled-legacy)
[427.696282] Starting subtest: basic-y-tiled-legacy
[427.704699] Subtest basic-y-tiled-legacy: SUCCESS (0.003s)
[428.692325] [074/165] (744s left) kms_addfb_basic (bo-too-small)
[430.013326] Starting subtest: bo-too-small
[430.022802] Subtest bo-too-small: SUCCESS (0.002s)
[430.380798] [075/165] (742s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[431.673661] Starting subtest: bo-too-small-due-to-tiling
[431.682312] Subtest bo-too-small-due-to-tiling: SUCCESS (0.002s)
[432.050213] [076/165] (741s left) kms_addfb_basic (clobberred-modifier)
[432.711697] Starting subtest: clobberred-modifier
[432.719433] Subtest clobberred-modifier: SUCCESS (0.001s)
[433.687359] [077/165] (739s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[434.346255] Starting subtest: framebuffer-vs-set-tiling
[434.352861] Subtest framebuffer-vs-set-tiling: SUCCESS (0.001s)
[435.363378] [078/165] (738s left) kms_addfb_basic (invalid-get-prop)
[435.983644] Starting subtest: invalid-get-prop
[435.992601] Subtest invalid-get-prop: SUCCESS (0.000s)
[436.920491] [079/165] (737s left) kms_addfb_basic (invalid-get-prop-any)
[437.556246] Starting subtest: invalid-get-prop-any
[437.563569] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[438.620350] [080/165] (735s left) kms_addfb_basic (invalid-set-prop)
[439.307538] Starting subtest: invalid-set-prop
[439.314181] Subtest invalid-set-prop: SUCCESS (0.000s)
[440.291415] [081/165] (734s left) kms_addfb_basic (invalid-set-prop-any)
[440.970640] Starting subtest: invalid-set-prop-any
[440.978193] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[441.988556] [082/165] (732s left) kms_addfb_basic (no-handle)
[442.656912] Starting subtest: no-handle
[442.665334] Subtest no-handle: SUCCESS (0.000s)
[443.666237] [083/165] (731s left) kms_addfb_basic (size-max)
[445.003781] Starting subtest: size-max
[445.013857] Subtest size-max: SUCCESS (0.001s)
[445.372444] [084/165] (730s left) kms_addfb_basic (small-bo)
[446.669761] Starting subtest: small-bo
[446.677676] Subtest small-bo: SUCCESS (0.000s)
[447.073870] [085/165] (728s left) kms_addfb_basic (tile-pitch-mismatch)
[447.785468] Starting subtest: tile-pitch-mismatch
[447.791515] Subtest tile-pitch-mismatch: SUCCESS (0.003s)
[448.830205] [086/165] (727s left) kms_addfb_basic (too-high)
[450.122762] Starting subtest: too-high
[450.148187] Subtest too-high: SUCCESS (0.007s)
[450.492421] [087/165] (725s left) kms_addfb_basic (too-wide)
[451.819776] Starting subtest: too-wide
[451.838908] Subtest too-wide: SUCCESS (0.001s)
[452.210307] [088/165] (724s left) kms_addfb_basic (unused-handle)
[452.870925] Starting subtest: unused-handle
[452.878794] Subtest unused-handle: SUCCESS (0.000s)
[453.888604] [089/165] (723s left) kms_addfb_basic (unused-modifier)
[454.539952] Starting subtest: unused-modifier
[454.547956] Subtest unused-modifier: SUCCESS (0.002s)
[455.554796] [090/165] (721s left) kms_addfb_basic (unused-offsets)
[456.233632] Starting subtest: unused-offsets
[456.241865] Subtest unused-offsets: SUCCESS (0.000s)
[457.223595] [091/165] (720s left) kms_addfb_basic (unused-pitches)
[457.904860] Starting subtest: unused-pitches
[457.912982] Subtest unused-pitches: SUCCESS (0.000s)
[458.879386] [092/165] (718s left) kms_busy (basic)
[460.309876] Starting subtest: basic
[460.333290] Starting dynamic subtest: flip
[463.485794] Dynamic subtest flip: SUCCESS (3.188s)
[463.657291] Starting dynamic subtest: modeset
[468.339625] Dynamic subtest modeset: SUCCESS (4.851s)
[468.348806] Subtest basic: SUCCESS (8.053s)
[469.552424] [093/165] (708s left) kms_prop_blob (basic)
[470.342979] Starting subtest: basic
[470.351918] Subtest basic: SUCCESS (0.000s)
[470.675523] [094/165] (707s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[472.006154] Starting subtest: basic-busy-flip-before-cursor-atomic
[472.021279] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.001s)
[472.374864] [095/165] (706s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[473.640333] Starting subtest: basic-busy-flip-before-cursor-legacy
[473.659787] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[474.005196] [096/165] (704s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[475.302812] Starting subtest: basic-flip-after-cursor-atomic
[477.065873] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.765s)
[478.238857] [097/165] (700s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[479.519868] Starting subtest: basic-flip-after-cursor-legacy
[481.340167] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.840s)
[482.579392] [098/165] (696s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[483.910241] Starting subtest: basic-flip-after-cursor-varying-size
[485.691454] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.782s)
[486.853968] [099/165] (692s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[488.129979] Starting subtest: basic-flip-before-cursor-atomic
[489.898206] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.770s)
[491.092782] [100/165] (688s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[492.402735] Starting subtest: basic-flip-before-cursor-legacy
[494.251256] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.849s)
[495.421504] [101/165] (684s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[496.723744] Starting subtest: basic-flip-before-cursor-varying-size
[498.554312] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.813s)
[499.703236] [102/165] (680s left) kms_dsc (dsc-basic)
[501.046139] Subtest dsc-basic: SKIP (0.000s)
[501.328420] [103/165] (679s left) kms_flip (basic-flip-vs-dpms)
[502.340645] Starting subtest: basic-flip-vs-dpms
[502.381482] Starting dynamic subtest: A-eDP1
[506.012400] Dynamic subtest A-eDP1: SUCCESS (3.618s)
[506.166760] Starting dynamic subtest: B-eDP1
[508.816167] Dynamic subtest B-eDP1: SUCCESS (2.794s)
[508.969185] Starting dynamic subtest: C-eDP1
[511.560893] Dynamic subtest C-eDP1: SUCCESS (2.741s)
[511.605684] Subtest basic-flip-vs-dpms: SUCCESS (9.269s)
[512.757527] [104/165] (668s left) kms_flip (basic-flip-vs-modeset)
[513.698107] Starting subtest: basic-flip-vs-modeset
[513.738550] Starting dynamic subtest: A-eDP1
[517.332317] Dynamic subtest A-eDP1: SUCCESS (3.587s)
[517.487935] Starting dynamic subtest: B-eDP1
[519.960135] Dynamic subtest B-eDP1: SUCCESS (2.618s)
[520.121611] Starting dynamic subtest: C-eDP1
[522.617796] Dynamic subtest C-eDP1: SUCCESS (2.644s)
[522.661919] Subtest basic-flip-vs-modeset: SUCCESS (8.976s)
[523.812871] [105/165] (657s left) kms_flip (basic-flip-vs-wf_vblank)
[524.890360] Starting subtest: basic-flip-vs-wf_vblank
[524.898650] Starting dynamic subtest: A-eDP1
[527.744839] Dynamic subtest A-eDP1: SUCCESS (2.811s)
[527.864635] Starting dynamic subtest: B-eDP1
[530.293672] Dynamic subtest B-eDP1: SUCCESS (2.538s)
[530.417510] Starting dynamic subtest: C-eDP1
[532.892857] Dynamic subtest C-eDP1: SUCCESS (2.592s)
[532.915759] Subtest basic-flip-vs-wf_vblank: SUCCESS (8.067s)
[534.963645] [106/165] (646s left) kms_flip (basic-plain-flip)
[535.890443] Starting subtest: basic-plain-flip
[535.933935] Starting dynamic subtest: A-eDP1
[538.570299] Dynamic subtest A-eDP1: SUCCESS (2.598s)
[538.686658] Starting dynamic subtest: B-eDP1
[540.877356] Dynamic subtest B-eDP1: SUCCESS (2.282s)
[541.000123] Starting dynamic subtest: C-eDP1
[543.202967] Dynamic subtest C-eDP1: SUCCESS (2.313s)
[543.221415] Subtest basic-plain-flip: SUCCESS (7.329s)
[545.203694] [107/165] (636s left) kms_force_connector_basic (force-connector-state)
[546.503859] Starting subtest: force-connector-state
[548.572846] Subtest force-connector-state: SUCCESS (2.059s)
[551.105709] [108/165] (630s left) kms_force_connector_basic (force-edid)
[552.350873] Starting subtest: force-edid
[552.895451] Subtest force-edid: SUCCESS (0.546s)
[554.699960] [109/165] (627s left) kms_force_connector_basic (force-load-detect)
[555.923305] Starting subtest: force-load-detect
[555.943564] Subtest force-load-detect: SKIP (0.000s)
[557.448318] [110/165] (625s left) kms_force_connector_basic (prune-stale-modes)
[558.683150] Starting subtest: prune-stale-modes
[559.265142] Subtest prune-stale-modes: SUCCESS (0.565s)
[561.036827] [111/165] (621s left) kms_frontbuffer_tracking (basic)
[562.448816] Starting subtest: basic
[571.298246] Subtest basic: SUCCESS (8.851s)
[571.717826] [112/165] (611s left) kms_hdmi_inject (inject-audio)
[572.908785] Starting subtest: inject-audio
[573.935663] Subtest inject-audio: SUCCESS (1.012s)
[575.246135] [113/165] (608s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[576.644197] Starting subtest: compare-crc-sanitycheck-xr24
[576.657696] Starting dynamic subtest: pipe-A-eDP-1
[577.890582] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.224s)
[578.145584] Starting dynamic subtest: pipe-B-eDP-1
[579.740788] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.829s)
[579.910255] Starting dynamic subtest: pipe-C-eDP-1
[581.567853] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.784s)
[581.574509] Subtest compare-crc-sanitycheck-xr24: SUCCESS (4.916s)
[582.729098] [114/165] (600s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[584.017900] Starting subtest: compare-crc-sanitycheck-nv12
[584.027424] Starting dynamic subtest: pipe-A-eDP-1
[585.854897] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.830s)
[586.024929] Starting dynamic subtest: pipe-B-eDP-1
[588.272249] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.360s)
[588.443771] Starting dynamic subtest: pipe-C-eDP-1
[588.459851] Dynamic subtest pipe-C-eDP-1: SKIP (0.007s)
[588.468933] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.279s)
[589.469563] [115/165] (594s left) kms_pipe_crc_basic (hang-read-crc)
[590.771379] Starting subtest: hang-read-crc
[590.796962] Starting dynamic subtest: pipe-A-eDP-1
[594.139572] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.346s)
[594.348771] Starting dynamic subtest: pipe-B-eDP-1
[598.188449] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.026s)
[598.385316] Starting dynamic subtest: pipe-C-eDP-1
[602.225540] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.014s)
[602.236338] Subtest hang-read-crc: SUCCESS (11.464s)
[603.473771] [116/165] (580s left) kms_pipe_crc_basic (nonblocking-crc)
[604.831814] Starting subtest: nonblocking-crc
[604.850732] Starting dynamic subtest: pipe-A-eDP-1
[606.223157] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.348s)
[606.406447] Starting dynamic subtest: pipe-B-eDP-1
[608.273847] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.027s)
[608.447468] Starting dynamic subtest: pipe-C-eDP-1
[610.317687] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.037s)
[610.333837] Subtest nonblocking-crc: SUCCESS (5.489s)
[611.552875] [117/165] (573s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[612.904551] Starting subtest: nonblocking-crc-frame-sequence
[612.912813] Starting dynamic subtest: pipe-A-eDP-1
[614.290605] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.364s)
[614.465623] Starting dynamic subtest: pipe-B-eDP-1
[616.351357] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.041s)
[616.543463] Starting dynamic subtest: pipe-C-eDP-1
[618.433724] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.051s)
[618.440813] Subtest nonblocking-crc-frame-sequence: SUCCESS (5.535s)
[619.653695] [118/165] (565s left) kms_pipe_crc_basic (read-crc)
[621.053491] Starting subtest: read-crc
[621.066325] Starting dynamic subtest: pipe-A-eDP-1
[622.375191] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.301s)
[622.541789] Starting dynamic subtest: pipe-B-eDP-1
[624.338516] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.941s)
[624.543535] Starting dynamic subtest: pipe-C-eDP-1
[626.311257] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.954s)
[626.336235] Subtest read-crc: SUCCESS (5.288s)
[627.530993] [119/165] (557s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[628.879334] Starting subtest: read-crc-frame-sequence
[628.890797] Starting dynamic subtest: pipe-A-eDP-1
[630.161199] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.281s)
[630.367741] Starting dynamic subtest: pipe-B-eDP-1
[632.187910] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.976s)
[632.413570] Starting dynamic subtest: pipe-C-eDP-1
[634.207815] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.980s)
[634.216490] Subtest read-crc-frame-sequence: SUCCESS (5.318s)
[635.387987] [120/165] (550s left) kms_pm_backlight (basic-brightness)
[636.888419] Starting subtest: basic-brightness
[637.452490] Starting dynamic subtest: eDP-1
[638.224412] Dynamic subtest eDP-1: SUCCESS (0.854s)
[638.235207] Subtest basic-brightness: SUCCESS (1.330s)
[639.428570] [121/165] (546s left) kms_pm_rpm (basic-pci-d3-state)
[643.174982] Starting subtest: basic-pci-d3-state
[647.693797] Subtest basic-pci-d3-state: SUCCESS (4.507s)
[648.413373] [122/165] (537s left) kms_pm_rpm (basic-rte)
[649.107549] Starting subtest: basic-rte
[656.652774] Subtest basic-rte: SUCCESS (7.533s)
[662.727642] [123/165] (523s left) kms_psr (primary_page_flip)
[664.209805] Starting subtest: primary_page_flip
[666.520399] Subtest primary_page_flip: SUCCESS (2.309s)
[667.759459] [124/165] (518s left) kms_psr (cursor_plane_move)
[669.091741] Starting subtest: cursor_plane_move
[671.423931] Subtest cursor_plane_move: SUCCESS (2.310s)
[672.612654] [125/165] (514s left) kms_psr (sprite_plane_onoff)
[673.972387] Starting subtest: sprite_plane_onoff
[676.387761] Subtest sprite_plane_onoff: SUCCESS (2.402s)
[677.577616] [126/165] (509s left) kms_psr (primary_mmap_gtt)
[678.935877] Starting subtest: primary_mmap_gtt
[681.334540] Subtest primary_mmap_gtt: SUCCESS (2.391s)
[682.543583] [127/165] (504s left) kms_setmode (basic-clone-single-crtc)
[683.323184] Starting subtest: basic-clone-single-crtc
[683.346861] Subtest basic-clone-single-crtc: SKIP (0.007s)
[683.715441] [128/165] (504s left) i915_pm_rps (basic-api)
[684.533579] Starting subtest: basic-api
[684.574657] Subtest basic-api: SUCCESS (0.032s)
[684.921926] [129/165] (503s left) prime_self_import (basic-llseek-bad)
[685.657304] Starting subtest: basic-llseek-bad
[685.673662] Subtest basic-llseek-bad: SUCCESS (0.018s)
[685.993413] [130/165] (502s left) prime_self_import (basic-llseek-size)
[686.621266] Starting subtest: basic-llseek-size
[686.637161] Subtest basic-llseek-size: SUCCESS (0.029s)
[686.959980] [131/165] (501s left) prime_self_import (basic-with_fd_dup)
[687.599853] Starting subtest: basic-with_fd_dup
[687.637722] Subtest basic-with_fd_dup: SUCCESS (0.026s)
[687.977877] [132/165] (500s left) prime_self_import (basic-with_one_bo)
[688.655599] Starting subtest: basic-with_one_bo
[688.669894] Subtest basic-with_one_bo: SUCCESS (0.027s)
[689.121525] [133/165] (499s left) prime_self_import (basic-with_one_bo_two_files)
[689.782561] Starting subtest: basic-with_one_bo_two_files
[689.797372] Subtest basic-with_one_bo_two_files: SUCCESS (0.023s)
[690.127640] [134/165] (499s left) prime_self_import (basic-with_two_bos)
[690.742555] Starting subtest: basic-with_two_bos
[690.779650] Subtest basic-with_two_bos: SUCCESS (0.027s)
[691.084825] [135/165] (498s left) prime_vgem (basic-fence-flip)
[691.947153] Starting subtest: basic-fence-flip
[693.703961] Subtest basic-fence-flip: SUCCESS (1.718s)
[694.202829] [136/165] (495s left) prime_vgem (basic-fence-mmap)
[694.901743] Starting subtest: basic-fence-mmap
[695.231425] Subtest basic-fence-mmap: SUCCESS (0.273s)
[695.551902] [137/165] (494s left) prime_vgem (basic-fence-read)
[696.249336] Starting subtest: basic-fence-read
[696.736820] Subtest basic-fence-read: SUCCESS (0.432s)
[697.065508] [138/165] (493s left) prime_vgem (basic-gtt)
[697.747086] Starting subtest: basic-gtt
[697.921610] Subtest basic-gtt: SUCCESS (0.130s)
[698.286199] [139/165] (492s left) prime_vgem (basic-read)
[699.021461] Starting subtest: basic-read
[699.305472] Subtest basic-read: SUCCESS (0.223s)
[699.582399] [140/165] (491s left) prime_vgem (basic-write)
[700.292106] Starting subtest: basic-write
[700.579139] Subtest basic-write: SUCCESS (0.232s)
[700.894285] [141/165] (490s left) vgem_basic (setversion)
[701.646955] Starting subtest: setversion
[701.666715] Subtest setversion: SUCCESS (0.000s)
[701.962398] [142/165] (489s left) vgem_basic (create)
[702.635424] Starting subtest: create
[702.644187] Subtest create: SUCCESS (0.003s)
[702.951085] [143/165] (488s left) vgem_basic (debugfs)
[703.610476] Starting subtest: debugfs
[703.619246] Subtest debugfs: SUCCESS (0.003s)
[703.926222] [144/165] (487s left) vgem_basic (dmabuf-export)
[704.607406] Starting subtest: dmabuf-export
[704.631734] Subtest dmabuf-export: SUCCESS (0.019s)
[704.974749] [145/165] (486s left) vgem_basic (dmabuf-fence)
[705.670085] Starting subtest: dmabuf-fence
[705.677432] Subtest dmabuf-fence: SUCCESS (0.004s)
[706.004816] [146/165] (486s left) vgem_basic (dmabuf-fence-before)
[706.714294] Starting subtest: dmabuf-fence-before
[706.734768] Subtest dmabuf-fence-before: SUCCESS (0.004s)
[707.029356] [147/165] (485s left) vgem_basic (dmabuf-mmap)
[707.690729] Starting subtest: dmabuf-mmap
[707.887783] Subtest dmabuf-mmap: SUCCESS (0.137s)
[708.137159] [148/165] (484s left) vgem_basic (mmap)
[708.799945] Starting subtest: mmap
[708.951530] Subtest mmap: SUCCESS (0.097s)
[709.187895] [149/165] (483s left) vgem_basic (second-client)
[709.868865] Starting subtest: second-client
[709.943375] Subtest second-client: SUCCESS (0.059s)
[710.235458] [150/165] (482s left) vgem_basic (sysfs)
[710.886566] Starting subtest: sysfs
[710.894900] Subtest sysfs: SUCCESS (0.007s)
[711.244910] [151/165] (482s left) core_hotunplug (unbind-rebind)
[712.354884] Starting subtest: unbind-rebind
[720.952551] Subtest unbind-rebind: SUCCESS (8.604s)
[721.380162] [152/165] (472s left) vgem_basic (unload)
[722.095147] Starting subtest: unload
[752.032932] Subtest unload: SUCCESS (29.876s)
[752.590685] [153/165] (441s left) i915_module_load (reload)
[753.256908] Starting subtest: reload
[764.809731] Subtest reload: SUCCESS (11.410s)
[765.157544] [154/165] (429s left) gem_lmem_swapping (basic)
[766.432774] Subtest basic: SKIP (0.000s)
[766.735072] [155/165] (427s left) gem_lmem_swapping (parallel-random-engines)
[767.419933] Subtest parallel-random-engines: SKIP (0.000s)
[767.719766] [156/165] (427s left) gem_lmem_swapping (random-engines)
[768.427672] Subtest random-engines: SKIP (0.000s)
[768.754541] [157/165] (426s left) gem_lmem_swapping (verify-random)
[769.615777] Subtest verify-random: SKIP (0.000s)
[769.862881] [158/165] (425s left) i915_pm_rpm (module-reload)
[774.259939] Starting subtest: module-reload
[799.702975] Subtest module-reload: SUCCESS (25.419s)
[799.881974] [159/165] (395s left) i915_selftest (live)
[800.686835] Starting subtest: live
[800.696105] Starting dynamic subtest: sanitycheck
[806.289335] Dynamic subtest sanitycheck: SUCCESS (5.557s)
[806.348461] Starting dynamic subtest: uncore
[811.688636] Dynamic subtest uncore: SUCCESS (5.385s)
[811.744450] Starting dynamic subtest: workarounds
[818.279164] Dynamic subtest workarounds: SUCCESS (6.562s)
[818.335779] Starting dynamic subtest: gt_engines
[824.169790] Dynamic subtest gt_engines: SUCCESS (5.867s)
[824.226485] Starting dynamic subtest: gt_timelines
[883.817539] Dynamic subtest gt_timelines: SUCCESS (59.643s)
[883.882870] Starting dynamic subtest: gt_contexts
[889.589342] Dynamic subtest gt_contexts: SUCCESS (5.768s)
[889.647908] Starting dynamic subtest: gt_lrc
[899.806656] Dynamic subtest gt_lrc: SUCCESS (10.190s)
[899.868918] Starting dynamic subtest: gt_mocs
[905.822204] Dynamic subtest gt_mocs: SUCCESS (6.001s)
[905.879778] Starting dynamic subtest: gt_pm
[916.091714] Dynamic subtest gt_pm: FAIL (8.664s)
[916.148478] Starting dynamic subtest: gt_heartbeat
[920.366789] Dynamic subtest gt_heartbeat: SUCCESS (5.857s)
[920.483312] Starting dynamic subtest: gt_tlb
[927.456809] Dynamic subtest gt_tlb: SUCCESS (7.077s)
[927.513804] Starting dynamic subtest: requests
[939.770751] Dynamic subtest requests: SUCCESS (12.298s)
[939.827927] Starting dynamic subtest: migrate
[950.452115] Dynamic subtest migrate: SUCCESS (10.663s)
[950.508456] Starting dynamic subtest: active
[956.060594] Dynamic subtest active: SUCCESS (5.588s)
[956.120606] Starting dynamic subtest: objects
[971.169404] Dynamic subtest objects: SUCCESS (15.103s)
[971.226115] Starting dynamic subtest: mman
[992.909464] Dynamic subtest mman: SUCCESS (21.724s)
[992.966688] Starting dynamic subtest: dmabuf
[998.460265] Dynamic subtest dmabuf: SUCCESS (5.539s)
[998.518866] Starting dynamic subtest: vma
[1004.002320] Dynamic subtest vma: SUCCESS (5.524s)
[1004.061283] Starting dynamic subtest: coherency
[1018.839496] Dynamic subtest coherency: SUCCESS (14.824s)
[1018.898565] Starting dynamic subtest: gtt
[1032.801448] Dynamic subtest gtt: SUCCESS (13.983s)
[1032.860245] Starting dynamic subtest: gem
[1039.953929] Dynamic subtest gem: SUCCESS (7.112s)
[1040.012605] Starting dynamic subtest: evict
[1057.906564] Dynamic subtest evict: SUCCESS (17.938s)
[1057.973492] Starting dynamic subtest: hugepages
[1070.115385] Dynamic subtest hugepages: SUCCESS (12.236s)
[1070.195983] Starting dynamic subtest: gem_contexts
[1120.774158] Dynamic subtest gem_contexts: SUCCESS (50.593s)
[1120.834116] Starting dynamic subtest: client
[1126.586781] Dynamic subtest client: SUCCESS (5.801s)
[1126.702818] Starting dynamic subtest: gem_migrate
[1132.001764] Dynamic subtest gem_migrate: SUCCESS (5.403s)
[1132.063874] Starting dynamic subtest: reset
[1142.143837] Dynamic subtest reset: SUCCESS (10.131s)
[1142.205853] Starting dynamic subtest: memory_region
[1147.577113] Dynamic subtest memory_region: SUCCESS (5.418s)
[1147.637776] Starting dynamic subtest: hangcheck
[1176.479152] Dynamic subtest hangcheck: SUCCESS (28.880s)
[1176.537886] Starting dynamic subtest: execlists
[1198.752168] Dynamic subtest execlists: SUCCESS (22.265s)
[1198.813720] Starting dynamic subtest: ring_submission
[1204.190478] Dynamic subtest ring_submission: SUCCESS (5.469s)
[1204.268404] Starting dynamic subtest: perf
[1210.086708] Dynamic subtest perf: SUCCESS (5.828s)
[1210.146305] Starting dynamic subtest: slpc
[1215.518933] Dynamic subtest slpc: SUCCESS (5.411s)
[1215.577811] Starting dynamic subtest: guc
[1220.959676] Dynamic subtest guc: SUCCESS (5.434s)
[1221.018636] Starting dynamic subtest: guc_multi_lrc
[1226.421642] Dynamic subtest guc_multi_lrc: SUCCESS (5.416s)
[1226.480168] Starting dynamic subtest: guc_hang
[1231.883838] Dynamic subtest guc_hang: SUCCESS (5.472s)
[1231.944946] Starting dynamic subtest: late_gt_pm
[1237.511767] Dynamic subtest late_gt_pm: SUCCESS (5.621s)
[1237.518845] Subtest live: FAIL (436.793s)
[1237.635878] Overall timeout time exceeded, stopping.
[1237.638167] Closing watchdogs
Done.