[38.424893] Initializing watchdogs
[38.425189]   /dev/watchdog0
[38.459240] [001/165] (960s left) i915_module_load (load)
[38.709418] Starting subtest: load
[41.240736] Subtest load: SUCCESS (2.550s)
[41.341785] [002/165] (957s left) core_auth (basic-auth)
[41.630200] Starting subtest: basic-auth
[41.654326] Subtest basic-auth: SUCCESS (0.005s)
[41.741460] [003/165] (957s left) debugfs_test (read_all_entries)
[41.998130] Starting subtest: read_all_entries
[42.396499] Subtest read_all_entries: SUCCESS (0.400s)
[42.595040] [004/165] (956s left) debugfs_test (basic-hwmon)
[42.825939] Starting subtest: basic-hwmon
[42.868557] Subtest basic-hwmon: SKIP (0.000s)
[43.017203] [005/165] (956s left) fbdev (eof)
[43.244088] Starting subtest: eof
[43.266387] Subtest eof: SUCCESS (0.003s)
[43.373709] [006/165] (955s left) fbdev (info)
[43.583692] Starting subtest: info
[43.603859] Subtest info: SUCCESS (0.000s)
[43.722940] [007/165] (955s left) fbdev (nullptr)
[43.935044] Starting subtest: nullptr
[43.957267] Subtest nullptr: SUCCESS (0.000s)
[44.064537] [008/165] (955s left) fbdev (read)
[44.254254] Starting subtest: read
[45.724346] Subtest read: SUCCESS (1.485s)
[45.832320] [009/165] (953s left) fbdev (write)
[46.041793] Starting subtest: write
[46.804625] Subtest write: SUCCESS (0.764s)
[46.912411] [010/165] (952s left) gem_basic (bad-close)
[47.143663] Starting subtest: bad-close
[47.187637] Subtest bad-close: SUCCESS (0.000s)
[47.276096] [011/165] (952s left) gem_basic (create-close)
[47.504568] Starting subtest: create-close
[47.548059] Subtest create-close: SUCCESS (0.000s)
[47.634177] [012/165] (952s left) gem_basic (create-fd-close)
[47.862168] Starting subtest: create-fd-close
[47.911644] Subtest create-fd-close: SUCCESS (0.000s)
[47.999607] [013/165] (951s left) gem_busy (busy@all-engines)
[48.243325] Starting subtest: busy
[48.266199] Starting dynamic subtest: all-engines
[48.343765] Dynamic subtest all-engines: SUCCESS (0.109s)
[48.366581] Subtest busy: SUCCESS (0.109s)
[48.753309] [014/165] (951s left) gem_close_race (basic-process)
[49.014170] Starting subtest: basic-process
[49.103186] Subtest basic-process: SUCCESS (0.108s)
[49.326846] [015/165] (950s left) gem_close_race (basic-threads)
[49.559330] Starting subtest: basic-threads
[50.770616] Subtest basic-threads: SUCCESS (1.214s)
[51.004617] [016/165] (949s left) gem_ctx_create (basic)
[51.244868] Starting subtest: basic
[51.266924] Subtest basic: SUCCESS (0.000s)
[51.454898] [017/165] (948s left) gem_ctx_create (basic-files)
[51.683553] Starting subtest: basic-files
[53.719939] Subtest basic-files: SUCCESS (2.041s)
[53.960700] [018/165] (946s left) gem_ctx_exec (basic)
[54.223978] Starting subtest: basic
[54.244301] Subtest basic: SUCCESS (0.012s)
[54.363457] [019/165] (946s left) gem_exec_basic (basic)
[54.641321] Starting subtest: basic
[54.663199] Starting dynamic subtest: rcs0-smem
[54.683653] Dynamic subtest rcs0-smem: SUCCESS (0.005s)
[54.723868] Starting dynamic subtest: bcs0-smem
[54.748402] Dynamic subtest bcs0-smem: SUCCESS (0.003s)
[54.787446] Starting dynamic subtest: vcs0-smem
[54.809120] Dynamic subtest vcs0-smem: SUCCESS (0.003s)
[54.851644] Starting dynamic subtest: vecs0-smem
[54.874352] Dynamic subtest vecs0-smem: SUCCESS (0.002s)
[54.896546] Subtest basic: SUCCESS (0.017s)
[54.987275] [020/165] (945s left) gem_exec_create (basic)
[55.242973] Starting subtest: basic
[55.263107] Starting dynamic subtest: smem
[57.310206] Dynamic subtest smem: SUCCESS (2.071s)
[57.333056] Subtest basic: SUCCESS (2.072s)
[57.524829] [021/165] (943s left) gem_exec_fence (basic-busy)
[57.901860] Starting subtest: basic-busy
[57.925304] Starting dynamic subtest: rcs0
[57.969882] Dynamic subtest rcs0: SUCCESS (0.038s)
[58.006660] Starting dynamic subtest: bcs0
[58.051113] Dynamic subtest bcs0: SUCCESS (0.027s)
[58.084424] Starting dynamic subtest: vcs0
[58.128822] Dynamic subtest vcs0: SUCCESS (0.017s)
[58.163601] Starting dynamic subtest: vecs0
[58.203901] Dynamic subtest vecs0: SUCCESS (0.017s)
[58.226473] Subtest basic-busy: SUCCESS (0.099s)
[58.803764] [022/165] (941s left) gem_exec_fence (basic-wait)
[59.162350] Starting subtest: basic-wait
[59.184848] Starting dynamic subtest: rcs0
[59.206159] Dynamic subtest rcs0: SUCCESS (0.025s)
[59.244528] Starting dynamic subtest: bcs0
[59.264163] Dynamic subtest bcs0: SUCCESS (0.029s)
[59.302997] Starting dynamic subtest: vcs0
[59.325174] Dynamic subtest vcs0: SUCCESS (0.036s)
[59.358752] Starting dynamic subtest: vecs0
[59.378621] Dynamic subtest vecs0: SUCCESS (0.021s)
[59.400947] Subtest basic-wait: SUCCESS (0.114s)
[59.990549] [023/165] (940s left) gem_exec_fence (basic-await)
[60.346981] Starting subtest: basic-await
[60.366985] Starting dynamic subtest: rcs0
[60.488087] Dynamic subtest rcs0: SUCCESS (0.139s)
[60.525129] Starting dynamic subtest: bcs0
[60.623055] Dynamic subtest bcs0: SUCCESS (0.133s)
[60.663575] Starting dynamic subtest: vcs0
[60.756911] Dynamic subtest vcs0: SUCCESS (0.136s)
[60.797752] Starting dynamic subtest: vecs0
[60.895661] Dynamic subtest vecs0: SUCCESS (0.136s)
[60.916624] Subtest basic-await: SUCCESS (0.548s)
[61.601890] [024/165] (939s left) gem_exec_fence (nb-await)
[61.958465] Starting subtest: nb-await
[61.980242] Starting dynamic subtest: rcs0
[62.030374] Dynamic subtest rcs0: SUCCESS (0.074s)
[62.067988] Starting dynamic subtest: bcs0
[62.112530] Dynamic subtest bcs0: SUCCESS (0.070s)
[62.148736] Starting dynamic subtest: vcs0
[62.193809] Dynamic subtest vcs0: SUCCESS (0.071s)
[62.230378] Starting dynamic subtest: vecs0
[62.250515] Dynamic subtest vecs0: SUCCESS (0.070s)
[62.273912] Subtest nb-await: SUCCESS (0.288s)
[62.936670] [025/165] (938s left) gem_exec_gttfill (basic)
[63.183523] Starting subtest: basic
[85.277964] Subtest basic: SUCCESS (21.927s)
[85.375204] [026/165] (915s left) gem_exec_parallel (engines)
[85.773055] Starting subtest: engines
[85.792671] Starting dynamic subtest: basic
[89.588902] Dynamic subtest basic: SUCCESS (3.644s)
[89.625259] Starting dynamic subtest: contexts
[94.505954] Dynamic subtest contexts: SUCCESS (4.935s)
[94.545035] Starting dynamic subtest: fds
[101.071026] Dynamic subtest fds: SUCCESS (6.565s)
[101.107899] Starting dynamic subtest: userptr
[104.786488] Dynamic subtest userptr: SUCCESS (3.696s)
[104.806579] Subtest engines: SUCCESS (18.842s)
[104.912951] [027/165] (896s left) gem_exec_store (basic)
[105.223099] Starting subtest: basic
[105.246364] Subtest basic: SUCCESS (0.063s)
[105.445595] [028/165] (895s left) gem_flink_basic (bad-flink)
[105.677960] Starting subtest: bad-flink
[105.697678] Subtest bad-flink: SUCCESS (0.000s)
[105.788622] [029/165] (895s left) gem_flink_basic (bad-open)
[106.015179] Starting subtest: bad-open
[106.038072] Subtest bad-open: SUCCESS (0.000s)
[106.127343] [030/165] (895s left) gem_flink_basic (basic)
[106.360978] Starting subtest: basic
[106.382273] Subtest basic: SUCCESS (0.000s)
[106.474730] [031/165] (895s left) gem_flink_basic (double-flink)
[106.826611] Starting subtest: double-flink
[106.852089] Subtest double-flink: SUCCESS (0.000s)
[106.969432] [032/165] (894s left) gem_flink_basic (flink-lifetime)
[107.216775] Starting subtest: flink-lifetime
[107.241878] Subtest flink-lifetime: SUCCESS (0.003s)
[107.347291] [033/165] (894s left) gem_huc_copy (huc-copy)
[107.596954] Starting subtest: huc-copy
[107.620711] Subtest huc-copy: SUCCESS (0.006s)
[107.728241] [034/165] (894s left) gem_linear_blits (basic)
[108.124836] Starting subtest: basic
[108.147124] Subtest basic: SUCCESS (0.018s)
[108.316650] [035/165] (893s left) gem_mmap (basic)
[108.720060] Starting subtest: basic
[108.827426] Subtest basic: SUCCESS (0.001s)
[108.921087] [036/165] (893s left) gem_mmap_gtt (basic)
[109.164112] Starting subtest: basic
[109.186618] Subtest basic: SUCCESS (0.002s)
[109.278267] [037/165] (892s left) gem_render_linear_blits (basic)
[109.506804] Starting subtest: basic
[109.611109] Subtest basic: SUCCESS (0.095s)
[109.703895] [038/165] (892s left) gem_render_tiled_blits (basic)
[109.945861] Starting subtest: basic
[110.072971] Subtest basic: SUCCESS (0.117s)
[110.166189] [039/165] (892s left) gem_ringfill (basic-all)
[110.675166] Starting subtest: basic-all
[111.848076] Subtest basic-all: SUCCESS (1.198s)
[111.993711] [040/165] (890s left) gem_softpin (allocator-basic)
[112.230612] Starting subtest: allocator-basic
[112.481747] Subtest allocator-basic: SUCCESS (0.274s)
[112.626979] [041/165] (889s left) gem_softpin (allocator-basic-reserve)
[112.839422] Starting subtest: allocator-basic-reserve
[113.064568] Subtest allocator-basic-reserve: SUCCESS (0.249s)
[113.207775] [042/165] (889s left) gem_softpin (safe-alignment)
[113.437896] Starting subtest: safe-alignment
[113.458143] Subtest safe-alignment: SUCCESS (0.032s)
[113.589109] [043/165] (889s left) gem_sync (basic-all)
[113.997987] Starting subtest: basic-all
[115.918462] Subtest basic-all: SUCCESS (2.083s)
[116.127347] [044/165] (886s left) gem_sync (basic-each)
[116.540768] Starting subtest: basic-each
[118.632610] Subtest basic-each: SUCCESS (2.270s)
[118.859184] [045/165] (883s left) gem_tiled_blits (basic)
[119.240970] Starting subtest: basic
[119.289241] Subtest basic: SUCCESS (0.050s)
[119.479646] [046/165] (883s left) gem_tiled_fence_blits (basic)
[120.035954] Starting subtest: basic
[120.076908] Subtest basic: SUCCESS (0.040s)
[120.263412] [047/165] (882s left) gem_tiled_pread_basic
[121.006248] [048/165] (882s left) gem_wait (busy@all-engines)
[121.251906] Starting subtest: busy
[121.272792] Starting dynamic subtest: all-engines
[121.793318] Dynamic subtest all-engines: SUCCESS (0.527s)
[121.814483] Subtest busy: SUCCESS (0.527s)
[122.080148] [049/165] (881s left) gem_wait (wait@all-engines)
[122.356321] Starting subtest: wait
[122.389086] Starting dynamic subtest: all-engines
[123.402037] Dynamic subtest all-engines: SUCCESS (1.048s)
[123.428946] Subtest wait: SUCCESS (1.048s)
[123.662661] [050/165] (879s left) i915_getparams_basic (basic-eu-total)
[123.898789] Starting subtest: basic-eu-total
[123.945291] Subtest basic-eu-total: SUCCESS (0.000s)
[124.038797] [051/165] (879s left) i915_getparams_basic (basic-subslice-total)
[124.270881] Starting subtest: basic-subslice-total
[124.315581] Subtest basic-subslice-total: SUCCESS (0.000s)
[124.408379] [052/165] (878s left) i915_hangman (error-state-basic)
[124.647798] Starting subtest: error-state-basic
[124.941098] Subtest error-state-basic: SUCCESS (0.289s)
[125.047459] [053/165] (878s left) i915_pciid
[125.406286] [054/165] (878s left) kms_addfb_basic (addfb25-4-tiled)
[125.802374] Starting subtest: addfb25-4-tiled
[125.824789] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[125.917335] [055/165] (877s left) kms_addfb_basic (addfb25-bad-modifier)
[126.154454] Starting subtest: addfb25-bad-modifier
[126.174548] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[126.343065] [056/165] (877s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[126.579131] Starting subtest: addfb25-framebuffer-vs-set-tiling
[126.601764] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[126.778599] [057/165] (877s left) kms_addfb_basic (addfb25-modifier-no-flag)
[127.014024] Starting subtest: addfb25-modifier-no-flag
[127.036882] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[127.211346] [058/165] (876s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[127.445043] Starting subtest: addfb25-x-tiled-legacy
[127.467905] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[127.644916] [059/165] (876s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[127.878322] Starting subtest: addfb25-x-tiled-mismatch-legacy
[127.901045] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.001s)
[128.075157] [060/165] (876s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[128.399547] Starting subtest: addfb25-yf-tiled-legacy
[128.421754] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[128.521295] [061/165] (875s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[128.825635] Starting subtest: addfb25-y-tiled-legacy
[128.845907] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[128.962567] [062/165] (875s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[129.272310] Starting subtest: addfb25-y-tiled-small-legacy
[129.294570] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.001s)
[129.394177] [063/165] (874s left) kms_addfb_basic (bad-pitch-0)
[129.624723] Starting subtest: bad-pitch-0
[129.645490] Subtest bad-pitch-0: SUCCESS (0.000s)
[129.826968] [064/165] (874s left) kms_addfb_basic (bad-pitch-1024)
[130.064025] Starting subtest: bad-pitch-1024
[130.086262] Subtest bad-pitch-1024: SUCCESS (0.000s)
[130.256706] [065/165] (874s left) kms_addfb_basic (bad-pitch-128)
[130.490823] Starting subtest: bad-pitch-128
[130.511232] Subtest bad-pitch-128: SUCCESS (0.000s)
[130.675945] [066/165] (873s left) kms_addfb_basic (bad-pitch-256)
[130.907766] Starting subtest: bad-pitch-256
[130.929792] Subtest bad-pitch-256: SUCCESS (0.000s)
[131.091682] [067/165] (873s left) kms_addfb_basic (bad-pitch-32)
[131.326376] Starting subtest: bad-pitch-32
[131.346939] Subtest bad-pitch-32: SUCCESS (0.000s)
[131.527446] [068/165] (873s left) kms_addfb_basic (bad-pitch-63)
[131.796166] Starting subtest: bad-pitch-63
[131.822319] Subtest bad-pitch-63: SUCCESS (0.000s)
[131.979563] [069/165] (872s left) kms_addfb_basic (bad-pitch-65536)
[132.237689] Starting subtest: bad-pitch-65536
[132.270656] Subtest bad-pitch-65536: SUCCESS (0.001s)
[132.447953] [070/165] (872s left) kms_addfb_basic (bad-pitch-999)
[132.712128] Starting subtest: bad-pitch-999
[132.737086] Subtest bad-pitch-999: SUCCESS (0.000s)
[132.918543] [071/165] (872s left) kms_addfb_basic (basic)
[133.186684] Starting subtest: basic
[133.220672] Subtest basic: SUCCESS (0.000s)
[133.375826] [072/165] (871s left) kms_addfb_basic (basic-x-tiled-legacy)
[133.649710] Starting subtest: basic-x-tiled-legacy
[133.676428] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[133.832081] [073/165] (871s left) kms_addfb_basic (basic-y-tiled-legacy)
[134.143997] Starting subtest: basic-y-tiled-legacy
[134.170647] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[134.293616] [074/165] (871s left) kms_addfb_basic (bo-too-small)
[134.610170] Starting subtest: bo-too-small
[134.639514] Subtest bo-too-small: SUCCESS (0.001s)
[134.760771] [075/165] (870s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[135.091878] Starting subtest: bo-too-small-due-to-tiling
[135.119568] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[135.237279] [076/165] (870s left) kms_addfb_basic (clobberred-modifier)
[135.488564] Starting subtest: clobberred-modifier
[135.509898] Subtest clobberred-modifier: SUCCESS (0.000s)
[135.673579] [077/165] (870s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[135.905441] Starting subtest: framebuffer-vs-set-tiling
[135.925462] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[136.108601] [078/165] (869s left) kms_addfb_basic (invalid-get-prop)
[136.341521] Starting subtest: invalid-get-prop
[136.364107] Subtest invalid-get-prop: SUCCESS (0.000s)
[136.541463] [079/165] (869s left) kms_addfb_basic (invalid-get-prop-any)
[136.779909] Starting subtest: invalid-get-prop-any
[136.801417] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[136.974984] [080/165] (869s left) kms_addfb_basic (invalid-set-prop)
[137.208792] Starting subtest: invalid-set-prop
[137.229094] Subtest invalid-set-prop: SUCCESS (0.000s)
[137.415348] [081/165] (868s left) kms_addfb_basic (invalid-set-prop-any)
[137.648726] Starting subtest: invalid-set-prop-any
[137.670974] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[137.840486] [082/165] (868s left) kms_addfb_basic (no-handle)
[138.085701] Starting subtest: no-handle
[138.106841] Subtest no-handle: SUCCESS (0.000s)
[138.275146] [083/165] (867s left) kms_addfb_basic (size-max)
[138.602050] Starting subtest: size-max
[138.623127] Subtest size-max: SUCCESS (0.000s)
[138.716891] [084/165] (867s left) kms_addfb_basic (small-bo)
[139.043663] Starting subtest: small-bo
[139.064581] Subtest small-bo: SUCCESS (0.000s)
[139.158668] [085/165] (867s left) kms_addfb_basic (tile-pitch-mismatch)
[139.392412] Starting subtest: tile-pitch-mismatch
[139.413706] Subtest tile-pitch-mismatch: SUCCESS (0.001s)
[139.592356] [086/165] (866s left) kms_addfb_basic (too-high)
[139.899119] Starting subtest: too-high
[139.921867] Subtest too-high: SUCCESS (0.002s)
[140.024767] [087/165] (866s left) kms_addfb_basic (too-wide)
[140.330976] Starting subtest: too-wide
[140.352216] Subtest too-wide: SUCCESS (0.000s)
[140.477042] [088/165] (866s left) kms_addfb_basic (unused-handle)
[140.743463] Starting subtest: unused-handle
[140.770593] Subtest unused-handle: SUCCESS (0.000s)
[140.925526] [089/165] (865s left) kms_addfb_basic (unused-modifier)
[141.192595] Starting subtest: unused-modifier
[141.222404] Subtest unused-modifier: SUCCESS (0.000s)
[141.397534] [090/165] (865s left) kms_addfb_basic (unused-offsets)
[141.668032] Starting subtest: unused-offsets
[141.690516] Subtest unused-offsets: SUCCESS (0.000s)
[141.842404] [091/165] (865s left) kms_addfb_basic (unused-pitches)
[142.078281] Starting subtest: unused-pitches
[142.100986] Subtest unused-pitches: SUCCESS (0.000s)
[142.274937] [092/165] (864s left) kms_busy (basic)
[142.618154] Starting subtest: basic
[142.638832] Starting dynamic subtest: flip
[143.596639] Dynamic subtest flip: SUCCESS (0.975s)
[143.660834] Starting dynamic subtest: modeset
[144.978663] Dynamic subtest modeset: SUCCESS (1.400s)
[145.002211] Subtest basic: SUCCESS (2.378s)
[145.373394] [093/165] (861s left) kms_prop_blob (basic)
[145.622203] Starting subtest: basic
[145.642923] Subtest basic: SUCCESS (0.000s)
[145.739581] [094/165] (861s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[146.051375] Starting subtest: basic-busy-flip-before-cursor-atomic
[146.095417] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[146.189373] [095/165] (861s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[146.521871] Starting subtest: basic-busy-flip-before-cursor-legacy
[146.563500] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[146.660864] [096/165] (860s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[146.982667] Starting subtest: basic-flip-after-cursor-atomic
[148.017959] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.055s)
[148.381845] [097/165] (859s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[148.710033] Starting subtest: basic-flip-after-cursor-legacy
[149.754682] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.067s)
[150.130344] [098/165] (857s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[150.440954] Starting subtest: basic-flip-after-cursor-varying-size
[151.520234] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.069s)
[151.885733] [099/165] (855s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[152.222729] Starting subtest: basic-flip-before-cursor-atomic
[153.261508] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.053s)
[153.634477] [100/165] (854s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[153.939043] Starting subtest: basic-flip-before-cursor-legacy
[154.988972] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.051s)
[155.353505] [101/165] (852s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[155.652481] Starting subtest: basic-flip-before-cursor-varying-size
[156.745933] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.072s)
[157.097847] [102/165] (850s left) kms_dsc (dsc-basic)
[157.435544] Subtest dsc-basic: SKIP (0.000s)
[157.532560] [103/165] (850s left) kms_flip (basic-flip-vs-dpms)
[157.868019] Starting subtest: basic-flip-vs-dpms
[157.888113] Starting dynamic subtest: A-DP1
[159.061297] Dynamic subtest A-DP1: SUCCESS (1.223s)
[159.109705] Starting dynamic subtest: B-DP1
[160.187974] Dynamic subtest B-DP1: SUCCESS (1.119s)
[160.228201] Starting dynamic subtest: C-DP1
[161.364948] Dynamic subtest C-DP1: SUCCESS (1.157s)
[161.393192] Subtest basic-flip-vs-dpms: SUCCESS (3.509s)
[161.758653] [104/165] (846s left) kms_flip (basic-flip-vs-modeset)
[162.066520] Starting subtest: basic-flip-vs-modeset
[162.089310] Starting dynamic subtest: A-DP1
[163.352691] Dynamic subtest A-DP1: SUCCESS (1.285s)
[163.392598] Starting dynamic subtest: B-DP1
[164.494986] Dynamic subtest B-DP1: SUCCESS (1.136s)
[164.535390] Starting dynamic subtest: C-DP1
[165.617673] Dynamic subtest C-DP1: SUCCESS (1.143s)
[165.639271] Subtest basic-flip-vs-modeset: SUCCESS (3.574s)
[166.020946] [105/165] (842s left) kms_flip (basic-flip-vs-wf_vblank)
[166.328378] Starting subtest: basic-flip-vs-wf_vblank
[166.350468] Starting dynamic subtest: A-DP1
[167.781611] Dynamic subtest A-DP1: SUCCESS (1.457s)
[167.821516] Starting dynamic subtest: B-DP1
[169.183807] Dynamic subtest B-DP1: SUCCESS (1.392s)
[169.231369] Starting dynamic subtest: C-DP1
[170.584667] Dynamic subtest C-DP1: SUCCESS (1.393s)
[170.612235] Subtest basic-flip-vs-wf_vblank: SUCCESS (4.252s)
[171.040159] [106/165] (837s left) kms_flip (basic-plain-flip)
[171.374787] Starting subtest: basic-plain-flip
[171.396771] Starting dynamic subtest: A-DP1
[172.523442] Dynamic subtest A-DP1: SUCCESS (1.179s)
[172.565190] Starting dynamic subtest: B-DP1
[173.643122] Dynamic subtest B-DP1: SUCCESS (1.111s)
[173.684729] Starting dynamic subtest: C-DP1
[174.747805] Dynamic subtest C-DP1: SUCCESS (1.103s)
[174.768838] Subtest basic-plain-flip: SUCCESS (3.404s)
[175.205142] [107/165] (833s left) kms_force_connector_basic (force-connector-state)
[175.475417] Subtest force-connector-state: SKIP (0.000s)
[175.574361] [108/165] (832s left) kms_force_connector_basic (force-edid)
[175.830391] Subtest force-edid: SKIP (0.000s)
[175.927472] [109/165] (832s left) kms_force_connector_basic (force-load-detect)
[176.187555] Subtest force-load-detect: SKIP (0.000s)
[176.287983] [110/165] (832s left) kms_force_connector_basic (prune-stale-modes)
[176.548579] Subtest prune-stale-modes: SKIP (0.000s)
[176.646729] [111/165] (832s left) kms_frontbuffer_tracking (basic)
[177.056874] Starting subtest: basic
[180.054515] Subtest basic: SUCCESS (3.075s)
[180.200909] [112/165] (828s left) kms_hdmi_inject (inject-audio)
[180.467956] Subtest inject-audio: SKIP (0.000s)
[180.563574] [113/165] (828s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[180.876402] Starting subtest: compare-crc-sanitycheck-xr24
[180.897688] Starting dynamic subtest: pipe-A-DP-1
[181.330478] Dynamic subtest pipe-A-DP-1: SUCCESS (0.431s)
[181.386506] Starting dynamic subtest: pipe-B-DP-1
[181.947353] Dynamic subtest pipe-B-DP-1: SUCCESS (0.605s)
[182.006084] Starting dynamic subtest: pipe-C-DP-1
[182.544965] Dynamic subtest pipe-C-DP-1: SUCCESS (0.615s)
[182.568233] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.664s)
[182.916992] [114/165] (826s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[183.219360] Starting subtest: compare-crc-sanitycheck-nv12
[183.239697] Starting dynamic subtest: pipe-A-DP-1
[183.284281] Dynamic subtest pipe-A-DP-1: SKIP (0.001s)
[183.324459] Starting dynamic subtest: pipe-B-DP-1
[183.375825] Dynamic subtest pipe-B-DP-1: SKIP (0.001s)
[183.417565] Starting dynamic subtest: pipe-C-DP-1
[183.461293] Dynamic subtest pipe-C-DP-1: SKIP (0.001s)
[183.507454] Subtest compare-crc-sanitycheck-nv12: SKIP (0.016s)
[183.606262] [115/165] (825s left) kms_pipe_crc_basic (hang-read-crc)
[183.914425] Starting subtest: hang-read-crc
[183.935301] Starting dynamic subtest: pipe-A-DP-1
[185.398190] Dynamic subtest pipe-A-DP-1: SUCCESS (1.475s)
[185.442416] Starting dynamic subtest: pipe-B-DP-1
[187.011440] Dynamic subtest pipe-B-DP-1: SUCCESS (1.624s)
[187.062443] Starting dynamic subtest: pipe-C-DP-1
[188.682363] Dynamic subtest pipe-C-DP-1: SUCCESS (1.644s)
[188.704351] Subtest hang-read-crc: SUCCESS (4.756s)
[189.026294] [116/165] (820s left) kms_pipe_crc_basic (nonblocking-crc)
[189.333395] Starting subtest: nonblocking-crc
[189.355590] Starting dynamic subtest: pipe-A-DP-1
[189.907414] Dynamic subtest pipe-A-DP-1: SUCCESS (0.572s)
[189.948092] Starting dynamic subtest: pipe-B-DP-1
[190.644559] Dynamic subtest pipe-B-DP-1: SUCCESS (0.729s)
[190.693422] Starting dynamic subtest: pipe-C-DP-1
[191.451300] Dynamic subtest pipe-C-DP-1: SUCCESS (0.751s)
[191.481365] Subtest nonblocking-crc: SUCCESS (2.067s)
[191.781065] [117/165] (817s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[192.098076] Starting subtest: nonblocking-crc-frame-sequence
[192.125859] Starting dynamic subtest: pipe-A-DP-1
[192.702816] Dynamic subtest pipe-A-DP-1: SUCCESS (0.585s)
[192.757037] Starting dynamic subtest: pipe-B-DP-1
[193.443717] Dynamic subtest pipe-B-DP-1: SUCCESS (0.759s)
[193.493936] Starting dynamic subtest: pipe-C-DP-1
[194.210202] Dynamic subtest pipe-C-DP-1: SUCCESS (0.753s)
[194.231163] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.111s)
[194.543359] [118/165] (815s left) kms_pipe_crc_basic (read-crc)
[194.857064] Starting subtest: read-crc
[194.879929] Starting dynamic subtest: pipe-A-DP-1
[195.360574] Dynamic subtest pipe-A-DP-1: SUCCESS (0.500s)
[195.412202] Starting dynamic subtest: pipe-B-DP-1
[196.058621] Dynamic subtest pipe-B-DP-1: SUCCESS (0.694s)
[196.104434] Starting dynamic subtest: pipe-C-DP-1
[196.762456] Dynamic subtest pipe-C-DP-1: SUCCESS (0.701s)
[196.782289] Subtest read-crc: SUCCESS (1.910s)
[197.123892] [119/165] (812s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[197.437027] Starting subtest: read-crc-frame-sequence
[197.458159] Starting dynamic subtest: pipe-A-DP-1
[197.930773] Dynamic subtest pipe-A-DP-1: SUCCESS (0.500s)
[197.971380] Starting dynamic subtest: pipe-B-DP-1
[198.630255] Dynamic subtest pipe-B-DP-1: SUCCESS (0.694s)
[198.672963] Starting dynamic subtest: pipe-C-DP-1
[199.343053] Dynamic subtest pipe-C-DP-1: SUCCESS (0.702s)
[199.373151] Subtest read-crc-frame-sequence: SUCCESS (1.909s)
[199.726704] [120/165] (810s left) kms_pm_backlight (basic-brightness)
[200.084677] Subtest basic-brightness: SKIP (0.000s)
[200.207288] [121/165] (809s left) kms_pm_rpm (basic-pci-d3-state)
[202.119715] Starting subtest: basic-pci-d3-state
[202.834900] Subtest basic-pci-d3-state: SUCCESS (0.698s)
[203.032289] [122/165] (806s left) kms_pm_rpm (basic-rte)
[203.245418] Starting subtest: basic-rte
[205.627060] Subtest basic-rte: SUCCESS (2.387s)
[206.597585] [123/165] (803s left) kms_psr (primary_page_flip)
[206.863845] Subtest primary_page_flip: SKIP (0.000s)
[206.966066] [124/165] (803s left) kms_psr (cursor_plane_move)
[207.203721] Subtest cursor_plane_move: SKIP (0.000s)
[207.304177] [125/165] (802s left) kms_psr (sprite_plane_onoff)
[207.563241] Subtest sprite_plane_onoff: SKIP (0.000s)
[207.659766] [126/165] (802s left) kms_psr (primary_mmap_gtt)
[207.920458] Subtest primary_mmap_gtt: SKIP (0.000s)
[208.029864] [127/165] (802s left) kms_setmode (basic-clone-single-crtc)
[208.274100] Starting subtest: basic-clone-single-crtc
[208.340245] Subtest basic-clone-single-crtc: SKIP (0.000s)
[208.439996] [128/165] (802s left) i915_pm_rps (basic-api)
[208.685445] Starting subtest: basic-api
[208.708985] Subtest basic-api: SUCCESS (0.007s)
[208.830231] [129/165] (801s left) prime_self_import (basic-llseek-bad)
[209.052123] Starting subtest: basic-llseek-bad
[209.093103] Subtest basic-llseek-bad: SUCCESS (0.004s)
[209.192948] [130/165] (801s left) prime_self_import (basic-llseek-size)
[209.389370] Starting subtest: basic-llseek-size
[209.431859] Subtest basic-llseek-size: SUCCESS (0.006s)
[209.555251] [131/165] (801s left) prime_self_import (basic-with_fd_dup)
[209.778212] Starting subtest: basic-with_fd_dup
[209.826283] Subtest basic-with_fd_dup: SUCCESS (0.006s)
[209.937787] [132/165] (801s left) prime_self_import (basic-with_one_bo)
[210.163441] Starting subtest: basic-with_one_bo
[210.214342] Subtest basic-with_one_bo: SUCCESS (0.007s)
[210.331488] [133/165] (800s left) prime_self_import (basic-with_one_bo_two_files)
[210.559764] Starting subtest: basic-with_one_bo_two_files
[210.609451] Subtest basic-with_one_bo_two_files: SUCCESS (0.005s)
[210.718091] [134/165] (800s left) prime_self_import (basic-with_two_bos)
[210.946296] Starting subtest: basic-with_two_bos
[210.994987] Subtest basic-with_two_bos: SUCCESS (0.006s)
[211.098586] [135/165] (800s left) prime_vgem (basic-fence-flip)
[211.390107] Starting subtest: basic-fence-flip
[211.936177] Subtest basic-fence-flip: SUCCESS (0.586s)
[212.065086] [136/165] (799s left) prime_vgem (basic-fence-mmap)
[212.317186] Starting subtest: basic-fence-mmap
[212.363668] Subtest basic-fence-mmap: SUCCESS (0.072s)
[212.490339] [137/165] (799s left) prime_vgem (basic-fence-read)
[212.748926] Starting subtest: basic-fence-read
[212.815621] Subtest basic-fence-read: SUCCESS (0.111s)
[212.945652] [138/165] (798s left) prime_vgem (basic-gtt)
[213.184803] Starting subtest: basic-gtt
[213.205886] Subtest basic-gtt: SUCCESS (0.035s)
[213.326384] [139/165] (798s left) prime_vgem (basic-read)
[213.589211] Starting subtest: basic-read
[213.632081] Subtest basic-read: SUCCESS (0.054s)
[213.760310] [140/165] (798s left) prime_vgem (basic-write)
[213.998837] Starting subtest: basic-write
[214.019623] Subtest basic-write: SUCCESS (0.066s)
[214.149113] [141/165] (797s left) vgem_basic (setversion)
[214.395281] Starting subtest: setversion
[214.439840] Subtest setversion: SUCCESS (0.000s)
[214.540613] [142/165] (797s left) vgem_basic (create)
[214.805314] Starting subtest: create
[214.826980] Subtest create: SUCCESS (0.000s)
[214.924338] [143/165] (797s left) vgem_basic (debugfs)
[215.184666] Starting subtest: debugfs
[215.212152] Subtest debugfs: SUCCESS (0.001s)
[215.309373] [144/165] (796s left) vgem_basic (dmabuf-export)
[215.547691] Starting subtest: dmabuf-export
[215.593148] Subtest dmabuf-export: SUCCESS (0.004s)
[215.691837] [145/165] (796s left) vgem_basic (dmabuf-fence)
[215.930891] Starting subtest: dmabuf-fence
[215.953541] Subtest dmabuf-fence: SUCCESS (0.002s)
[216.053622] [146/165] (796s left) vgem_basic (dmabuf-fence-before)
[216.272599] Starting subtest: dmabuf-fence-before
[216.296092] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[216.391453] [147/165] (796s left) vgem_basic (dmabuf-mmap)
[216.629148] Starting subtest: dmabuf-mmap
[216.650543] Subtest dmabuf-mmap: SUCCESS (0.043s)
[216.751665] [148/165] (795s left) vgem_basic (mmap)
[216.991059] Starting subtest: mmap
[217.013887] Subtest mmap: SUCCESS (0.027s)
[217.111323] [149/165] (795s left) vgem_basic (second-client)
[217.348497] Starting subtest: second-client
[217.394618] Subtest second-client: SUCCESS (0.032s)
[217.491613] [150/165] (795s left) vgem_basic (sysfs)
[217.726873] Starting subtest: sysfs
[217.747527] Subtest sysfs: SUCCESS (0.001s)
[217.847114] [151/165] (795s left) core_hotunplug (unbind-rebind)
[218.162032] Starting subtest: unbind-rebind
[222.330764] Subtest unbind-rebind: SUCCESS (4.164s)
[222.431826] [152/165] (790s left) vgem_basic (unload)
[222.632406] Starting subtest: unload
[251.863572] Subtest unload: SUCCESS (29.054s)
[251.960635] [153/165] (761s left) i915_module_load (reload)
[252.174643] Starting subtest: reload
[255.965188] Subtest reload: SUCCESS (3.803s)
[256.119231] [154/165] (757s left) gem_lmem_swapping (basic)
[256.407580] Subtest basic: SKIP (0.000s)
[256.503163] [155/165] (756s left) gem_lmem_swapping (parallel-random-engines)
[256.738883] Subtest parallel-random-engines: SKIP (0.000s)
[256.836814] [156/165] (756s left) gem_lmem_swapping (random-engines)
[257.096374] Subtest random-engines: SKIP (0.000s)
[257.192844] [157/165] (756s left) gem_lmem_swapping (verify-random)
[257.444065] Subtest verify-random: SKIP (0.000s)
[257.540183] [158/165] (756s left) i915_pm_rpm (module-reload)
[259.781081] Starting subtest: module-reload
[269.224491] Subtest module-reload: SUCCESS (9.456s)
[269.318593] [159/165] (744s left) i915_selftest (live)
[269.564485] Starting subtest: live
[269.585056] Starting dynamic subtest: sanitycheck
[271.037118] Dynamic subtest sanitycheck: SUCCESS (1.489s)
[271.071881] Starting dynamic subtest: uncore
[272.508385] Dynamic subtest uncore: SUCCESS (1.469s)
[272.556389] Starting dynamic subtest: workarounds
[274.420842] Dynamic subtest workarounds: SUCCESS (1.909s)
[274.456875] Starting dynamic subtest: gt_engines
[276.570807] Dynamic subtest gt_engines: SUCCESS (2.146s)
[276.606790] Starting dynamic subtest: gt_timelines
[299.380846] Dynamic subtest gt_timelines: SUCCESS (22.804s)
[299.417698] Starting dynamic subtest: gt_contexts
[301.032535] Dynamic subtest gt_contexts: SUCCESS (1.651s)
[301.071151] Starting dynamic subtest: gt_lrc
[305.375426] Dynamic subtest gt_lrc: SUCCESS (4.338s)
[305.413503] Starting dynamic subtest: gt_mocs
[307.078948] Dynamic subtest gt_mocs: SUCCESS (1.701s)
[307.133747] Starting dynamic subtest: gt_pm
[315.292885] Dynamic subtest gt_pm: SUCCESS (8.209s)
[315.330359] Starting dynamic subtest: gt_heartbeat
[331.060280] Dynamic subtest gt_heartbeat: FAIL (10.790s)
[331.099409] Starting dynamic subtest: gt_tlb
[331.123175] Dynamic subtest gt_tlb: SUCCESS (2.325s)
[331.165217] Starting dynamic subtest: requests
[336.609468] Dynamic subtest requests: SUCCESS (8.192s)
[336.644333] Starting dynamic subtest: migrate
[341.497821] Dynamic subtest migrate: SUCCESS (4.883s)
[341.542905] Starting dynamic subtest: active
[343.193404] Dynamic subtest active: SUCCESS (1.691s)
[343.229574] Starting dynamic subtest: objects
[347.657932] Dynamic subtest objects: SUCCESS (4.463s)
[347.695327] Starting dynamic subtest: mman
[356.820563] Dynamic subtest mman: SUCCESS (9.158s)
[356.857501] Starting dynamic subtest: dmabuf
[358.519045] Dynamic subtest dmabuf: SUCCESS (1.697s)
[358.566099] Starting dynamic subtest: vma
[360.052614] Dynamic subtest vma: SUCCESS (1.521s)
[360.093673] Starting dynamic subtest: coherency
[364.339875] Dynamic subtest coherency: SUCCESS (4.283s)
[364.394347] Starting dynamic subtest: gtt
[373.831822] Dynamic subtest gtt: SUCCESS (9.494s)
[373.880110] Starting dynamic subtest: gem
[376.783432] Dynamic subtest gem: SUCCESS (2.949s)
[376.818632] Starting dynamic subtest: evict
[382.904117] Dynamic subtest evict: SUCCESS (6.119s)
[382.941081] Starting dynamic subtest: hugepages
[391.104156] Dynamic subtest hugepages: SUCCESS (8.195s)
[391.140929] Starting dynamic subtest: gem_contexts
[416.261220] Dynamic subtest gem_contexts: SUCCESS (24.966s)
[416.301766] Starting dynamic subtest: client
[417.759938] Dynamic subtest client: SUCCESS (1.673s)
[417.800524] Starting dynamic subtest: gem_migrate
[419.436290] Dynamic subtest gem_migrate: SUCCESS (1.677s)
[419.477819] Starting dynamic subtest: reset
[432.943430] Dynamic subtest reset: SUCCESS (13.504s)
[432.986242] Starting dynamic subtest: memory_region
[434.415656] Dynamic subtest memory_region: SUCCESS (1.473s)
[434.453961] Starting dynamic subtest: hangcheck
[459.282471] Dynamic subtest hangcheck: SUCCESS (24.859s)
[459.332314] Starting dynamic subtest: execlists
[471.756234] Dynamic subtest execlists: SUCCESS (12.473s)
[471.803933] Starting dynamic subtest: ring_submission
[473.453079] Dynamic subtest ring_submission: SUCCESS (1.693s)
[473.488849] Starting dynamic subtest: perf
[475.059406] Dynamic subtest perf: SUCCESS (1.600s)
[475.106680] Starting dynamic subtest: slpc
[476.593450] Dynamic subtest slpc: SUCCESS (1.530s)
[476.629951] Starting dynamic subtest: guc
[478.118048] Dynamic subtest guc: SUCCESS (1.520s)
[478.154695] Starting dynamic subtest: guc_multi_lrc
[479.581415] Dynamic subtest guc_multi_lrc: SUCCESS (1.456s)
[479.624076] Starting dynamic subtest: guc_hang
[481.090683] Dynamic subtest guc_hang: SUCCESS (1.509s)
[481.128642] Starting dynamic subtest: late_gt_pm
[482.649890] Dynamic subtest late_gt_pm: SUCCESS (1.556s)
[482.670239] Subtest live: FAIL (213.106s)
[482.762225] [160/165] (531s left) dmabuf (all-tests)
[482.966359] Starting subtest: all-tests
[482.986092] Starting dynamic subtest: sanitycheck
[483.049527] Dynamic subtest sanitycheck: SUCCESS (0.075s)
[483.087246] Starting dynamic subtest: dma_fence
[483.227402] Dynamic subtest dma_fence: SUCCESS (0.181s)
[483.266082] Starting dynamic subtest: dma_fence_chain
[506.464582] Dynamic subtest dma_fence_chain: SUCCESS (23.230s)
[506.504610] Starting dynamic subtest: dma_fence_unwrap
[506.561330] Dynamic subtest dma_fence_unwrap: SUCCESS (0.095s)
[506.603043] Starting dynamic subtest: dma_resv
[506.645636] Dynamic subtest dma_resv: SUCCESS (0.075s)
[506.669302] Subtest all-tests: SUCCESS (23.672s)
[506.761669] [161/165] (507s left) i915_suspend (basic-s2idle-without-i915)
[506.982458] Starting subtest: basic-s2idle-without-i915
[526.743299] Subtest basic-s2idle-without-i915: SUCCESS (4.766s)
[530.446830] [162/165] (501s left) i915_suspend (basic-s3-without-i915)
[530.665687] Starting subtest: basic-s3-without-i915
[549.638040] Subtest basic-s3-without-i915: SUCCESS (3.307s)
[553.387631] [163/165] (498s left) gem_exec_suspend (basic-s0)
[553.667261] Starting subtest: basic-S0
[553.689107] Starting dynamic subtest: smem
[575.451963] Dynamic subtest smem: SUCCESS (5.768s)
[575.475003] Subtest basic-S0: SUCCESS (5.771s)
[578.845359] [164/165] (491s left) gem_exec_suspend (basic-s3)
[579.080011] Starting subtest: basic-S3
[579.099375] Starting dynamic subtest: smem
[599.946715] Dynamic subtest smem: SUCCESS (5.773s)
[599.966836] Subtest basic-S3: SUCCESS (5.775s)
[602.333494] [165/165] (485s left) kms_pipe_crc_basic (suspend-read-crc)
[602.662749] Starting subtest: suspend-read-crc
[602.683802] Starting dynamic subtest: pipe-A-DP-1
[620.731979] Dynamic subtest pipe-A-DP-1: SUCCESS (2.490s)
[620.803100] Starting dynamic subtest: pipe-B-DP-1
[638.709897] Dynamic subtest pipe-B-DP-1: SUCCESS (2.581s)
[638.758188] Starting dynamic subtest: pipe-C-DP-1
[656.688845] Dynamic subtest pipe-C-DP-1: SUCCESS (2.408s)
[656.711062] Subtest suspend-read-crc: SUCCESS (7.494s)
[661.140973] Closing watchdogs
Done.