[42.086837] Initializing watchdogs
[42.086989]   /dev/watchdog0
[42.097638] [001/165] (960s left) i915_module_load (load)
[42.200233] Starting subtest: load
[44.683531] Subtest load: SUCCESS (2.482s)
[44.710182] [002/165] (957s left) core_auth (basic-auth)
[44.811761] Starting subtest: basic-auth
[44.814705] Subtest basic-auth: SUCCESS (0.002s)
[44.906599] [003/165] (957s left) debugfs_test (read_all_entries)
[45.009078] Starting subtest: read_all_entries
[45.085460] Subtest read_all_entries: SUCCESS (0.076s)
[45.203489] [004/165] (957s left) debugfs_test (basic-hwmon)
[45.298996] Starting subtest: basic-hwmon
[45.301213] Subtest basic-hwmon: SKIP (0.000s)
[45.397656] [005/165] (957s left) fbdev (eof)
[45.500270] Starting subtest: eof
[45.501459] Subtest eof: SUCCESS (0.001s)
[45.569543] [006/165] (957s left) fbdev (info)
[45.678402] Starting subtest: info
[45.679723] Subtest info: SUCCESS (0.000s)
[45.748346] [007/165] (957s left) fbdev (nullptr)
[45.850073] Starting subtest: nullptr
[45.851273] Subtest nullptr: SUCCESS (0.000s)
[45.921665] [008/165] (956s left) fbdev (read)
[46.016770] Starting subtest: read
[53.108123] Subtest read: SUCCESS (7.056s)
[53.141213] [009/165] (949s left) fbdev (write)
[53.241312] Starting subtest: write
[56.991814] Subtest write: SUCCESS (3.741s)
[57.062037] [010/165] (945s left) gem_basic (bad-close)
[57.153558] Starting subtest: bad-close
[57.155662] Subtest bad-close: SUCCESS (0.000s)
[57.228833] [011/165] (945s left) gem_basic (create-close)
[57.317365] Starting subtest: create-close
[57.319429] Subtest create-close: SUCCESS (0.000s)
[57.432865] [012/165] (945s left) gem_basic (create-fd-close)
[57.522760] Starting subtest: create-fd-close
[57.524802] Subtest create-fd-close: SUCCESS (0.000s)
[57.614311] [013/165] (945s left) gem_busy (busy@all-engines)
[57.723210] Starting subtest: busy
[57.724399] Starting dynamic subtest: all-engines
[57.797698] Dynamic subtest all-engines: SUCCESS (0.075s)
[57.798745] Subtest busy: SUCCESS (0.075s)
[58.202823] [014/165] (944s left) gem_close_race (basic-process)
[58.315558] Starting subtest: basic-process
[58.393063] Subtest basic-process: SUCCESS (0.079s)
[58.604791] [015/165] (944s left) gem_close_race (basic-threads)
[58.718683] Starting subtest: basic-threads
[59.882342] Subtest basic-threads: SUCCESS (1.165s)
[60.127151] [016/165] (942s left) gem_ctx_create (basic)
[60.233471] Starting subtest: basic
[60.234601] Subtest basic: SUCCESS (0.000s)
[60.437165] [017/165] (942s left) gem_ctx_create (basic-files)
[60.544598] Starting subtest: basic-files
[62.568484] Subtest basic-files: SUCCESS (2.025s)
[62.819603] [018/165] (940s left) gem_ctx_exec (basic)
[62.912464] Starting subtest: basic
[62.916867] Subtest basic: SUCCESS (0.004s)
[63.038866] [019/165] (940s left) gem_exec_basic (basic)
[63.138797] Starting subtest: basic
[63.139978] Starting dynamic subtest: rcs0-smem
[63.141125] Dynamic subtest rcs0-smem: SUCCESS (0.002s)
[63.152422] Starting dynamic subtest: bcs0-smem
[63.153569] Dynamic subtest bcs0-smem: SUCCESS (0.001s)
[63.164040] Starting dynamic subtest: vcs0-smem
[63.165295] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[63.175721] Starting dynamic subtest: vecs0-smem
[63.176906] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[63.178095] Subtest basic: SUCCESS (0.005s)
[63.357184] [020/165] (939s left) gem_exec_create (basic)
[63.464222] Starting subtest: basic
[63.465551] Starting dynamic subtest: smem
[65.500390] Dynamic subtest smem: SUCCESS (2.035s)
[65.501463] Subtest basic: SUCCESS (2.036s)
[65.740451] [021/165] (937s left) gem_exec_fence (basic-busy)
[66.003349] Starting subtest: basic-busy
[66.004487] Starting dynamic subtest: rcs0
[66.027907] Dynamic subtest rcs0: SUCCESS (0.034s)
[66.041166] Starting dynamic subtest: bcs0
[66.052529] Dynamic subtest bcs0: SUCCESS (0.025s)
[66.063804] Starting dynamic subtest: vcs0
[66.084202] Dynamic subtest vcs0: SUCCESS (0.031s)
[66.099300] Starting dynamic subtest: vecs0
[66.112414] Dynamic subtest vecs0: SUCCESS (0.028s)
[66.113462] Subtest basic-busy: SUCCESS (0.119s)
[66.727517] [022/165] (936s left) gem_exec_fence (basic-wait)
[66.983778] Starting subtest: basic-wait
[66.985152] Starting dynamic subtest: rcs0
[67.014903] Dynamic subtest rcs0: SUCCESS (0.039s)
[67.030306] Starting dynamic subtest: bcs0
[67.052769] Dynamic subtest bcs0: SUCCESS (0.038s)
[67.065235] Starting dynamic subtest: vcs0
[67.089091] Dynamic subtest vcs0: SUCCESS (0.036s)
[67.101599] Starting dynamic subtest: vecs0
[67.120035] Dynamic subtest vecs0: SUCCESS (0.030s)
[67.121438] Subtest basic-wait: SUCCESS (0.145s)
[67.661960] [023/165] (935s left) gem_exec_fence (basic-await)
[67.916655] Starting subtest: basic-await
[67.917856] Starting dynamic subtest: rcs0
[68.009907] Dynamic subtest rcs0: SUCCESS (0.100s)
[68.034375] Starting dynamic subtest: bcs0
[68.123047] Dynamic subtest bcs0: SUCCESS (0.112s)
[68.150283] Starting dynamic subtest: vcs0
[68.263985] Dynamic subtest vcs0: SUCCESS (0.131s)
[68.290441] Starting dynamic subtest: vecs0
[68.388334] Dynamic subtest vecs0: SUCCESS (0.131s)
[68.390279] Subtest basic-await: SUCCESS (0.479s)
[68.980508] [024/165] (934s left) gem_exec_fence (nb-await)
[69.236033] Starting subtest: nb-await
[69.237258] Starting dynamic subtest: rcs0
[69.295404] Dynamic subtest rcs0: SUCCESS (0.067s)
[69.313298] Starting dynamic subtest: bcs0
[69.363689] Dynamic subtest bcs0: SUCCESS (0.068s)
[69.377555] Starting dynamic subtest: vcs0
[69.427311] Dynamic subtest vcs0: SUCCESS (0.063s)
[69.455643] Starting dynamic subtest: vecs0
[69.500853] Dynamic subtest vecs0: SUCCESS (0.073s)
[69.502905] Subtest nb-await: SUCCESS (0.273s)
[70.048168] [025/165] (933s left) gem_exec_gttfill (basic)
[70.155550] Starting subtest: basic
[76.332587] Subtest basic: SUCCESS (6.176s)
[76.520815] [026/165] (926s left) gem_exec_parallel (engines)
[76.691778] Starting subtest: engines
[76.693021] Starting dynamic subtest: basic
[79.098379] Dynamic subtest basic: SUCCESS (2.396s)
[79.112023] Starting dynamic subtest: contexts
[86.380215] Dynamic subtest contexts: SUCCESS (7.285s)
[86.394355] Starting dynamic subtest: fds
[95.303193] Dynamic subtest fds: SUCCESS (8.931s)
[95.322559] Starting dynamic subtest: userptr
[97.967072] Dynamic subtest userptr: SUCCESS (2.638s)
[97.968863] Subtest engines: SUCCESS (21.251s)
[98.157317] [027/165] (905s left) gem_exec_store (basic)
[98.263305] Starting subtest: basic
[98.330642] Subtest basic: SUCCESS (0.067s)
[98.548264] [028/165] (904s left) gem_flink_basic (bad-flink)
[98.638652] Starting subtest: bad-flink
[98.639936] Subtest bad-flink: SUCCESS (0.000s)
[98.728501] [029/165] (904s left) gem_flink_basic (bad-open)
[98.817415] Starting subtest: bad-open
[98.818529] Subtest bad-open: SUCCESS (0.000s)
[98.912648] [030/165] (904s left) gem_flink_basic (basic)
[99.002831] Starting subtest: basic
[99.004084] Subtest basic: SUCCESS (0.000s)
[99.112880] [031/165] (904s left) gem_flink_basic (double-flink)
[99.202268] Starting subtest: double-flink
[99.203594] Subtest double-flink: SUCCESS (0.000s)
[99.313317] [032/165] (904s left) gem_flink_basic (flink-lifetime)
[99.402606] Starting subtest: flink-lifetime
[99.405258] Subtest flink-lifetime: SUCCESS (0.002s)
[99.514633] [033/165] (903s left) gem_huc_copy (huc-copy)
[99.612228] Starting subtest: huc-copy
[99.614671] Subtest huc-copy: SKIP (0.000s)
[99.750490] [034/165] (903s left) gem_linear_blits (basic)
[99.996695] Starting subtest: basic
[100.009212] Subtest basic: SUCCESS (0.012s)
[100.153059] [035/165] (903s left) gem_mmap (basic)
[100.311629] Starting subtest: basic
[100.316534] Subtest basic: SUCCESS (0.001s)
[100.413901] [036/165] (903s left) gem_mmap_gtt (basic)
[100.504360] Starting subtest: basic
[100.505486] Subtest basic: SUCCESS (0.001s)
[100.589698] [037/165] (903s left) gem_render_linear_blits (basic)
[100.685969] Starting subtest: basic
[100.736301] Subtest basic: SUCCESS (0.050s)
[100.789764] [038/165] (902s left) gem_render_tiled_blits (basic)
[100.885435] Starting subtest: basic
[100.944223] Subtest basic: SUCCESS (0.058s)
[101.008236] [039/165] (902s left) gem_ringfill (basic-all)
[101.247208] Starting subtest: basic-all
[102.496378] Subtest basic-all: SUCCESS (1.240s)
[102.590985] [040/165] (901s left) gem_softpin (allocator-basic)
[102.688193] Starting subtest: allocator-basic
[102.857366] Subtest allocator-basic: SUCCESS (0.160s)
[102.967320] [041/165] (900s left) gem_softpin (allocator-basic-reserve)
[103.061170] Starting subtest: allocator-basic-reserve
[103.231972] Subtest allocator-basic-reserve: SUCCESS (0.162s)
[103.351106] [042/165] (900s left) gem_softpin (safe-alignment)
[103.448679] Starting subtest: safe-alignment
[103.467239] Subtest safe-alignment: SUCCESS (0.018s)
[103.603606] [043/165] (900s left) gem_sync (basic-all)
[103.712485] Starting subtest: basic-all
[106.008057] Subtest basic-all: SUCCESS (2.281s)
[106.205026] [044/165] (897s left) gem_sync (basic-each)
[106.309179] Starting subtest: basic-each
[108.586709] Subtest basic-each: SUCCESS (2.277s)
[108.816184] [045/165] (895s left) gem_tiled_blits (basic)
[109.047267] Starting subtest: basic
[109.076549] Subtest basic: SUCCESS (0.029s)
[109.210317] [046/165] (894s left) gem_tiled_fence_blits (basic)
[109.517998] Starting subtest: basic
[109.545550] Subtest basic: SUCCESS (0.028s)
[109.692310] [047/165] (894s left) gem_tiled_pread_basic
[110.209170] [048/165] (893s left) gem_wait (busy@all-engines)
[110.326440] Starting subtest: busy
[110.327612] Starting dynamic subtest: all-engines
[110.857656] Dynamic subtest all-engines: SUCCESS (0.531s)
[110.858795] Subtest busy: SUCCESS (0.531s)
[111.109729] [049/165] (892s left) gem_wait (wait@all-engines)
[111.225722] Starting subtest: wait
[111.226959] Starting dynamic subtest: all-engines
[112.262751] Dynamic subtest all-engines: SUCCESS (1.037s)
[112.264266] Subtest wait: SUCCESS (1.037s)
[112.510864] [050/165] (891s left) i915_getparams_basic (basic-eu-total)
[112.602203] Starting subtest: basic-eu-total
[112.604568] Subtest basic-eu-total: SUCCESS (0.000s)
[112.693021] [051/165] (891s left) i915_getparams_basic (basic-subslice-total)
[112.782513] Starting subtest: basic-subslice-total
[112.784609] Subtest basic-subslice-total: SUCCESS (0.000s)
[112.875991] [052/165] (891s left) i915_hangman (error-state-basic)
[112.978761] Starting subtest: error-state-basic
[112.992335] Subtest error-state-basic: SUCCESS (0.013s)
[113.093289] [053/165] (890s left) i915_pciid
[113.276793] [054/165] (890s left) kms_addfb_basic (addfb25-4-tiled)
[113.405103] Starting subtest: addfb25-4-tiled
[113.406193] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[113.512085] [055/165] (890s left) kms_addfb_basic (addfb25-bad-modifier)
[113.604171] Starting subtest: addfb25-bad-modifier
[113.605347] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[113.729697] [056/165] (890s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[113.820753] Starting subtest: addfb25-framebuffer-vs-set-tiling
[113.821910] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[113.944886] [057/165] (890s left) kms_addfb_basic (addfb25-modifier-no-flag)
[114.035143] Starting subtest: addfb25-modifier-no-flag
[114.036228] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[114.163784] [058/165] (890s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[114.256066] Starting subtest: addfb25-x-tiled-legacy
[114.257135] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[114.395364] [059/165] (889s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[114.486952] Starting subtest: addfb25-x-tiled-mismatch-legacy
[114.488094] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[114.612449] [060/165] (889s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[114.734202] Starting subtest: addfb25-yf-tiled-legacy
[114.735318] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[114.829611] [061/165] (889s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[114.947115] Starting subtest: addfb25-y-tiled-legacy
[114.948247] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[115.046812] [062/165] (889s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[115.166311] Starting subtest: addfb25-y-tiled-small-legacy
[115.167443] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s)
[115.280286] [063/165] (889s left) kms_addfb_basic (bad-pitch-0)
[115.370776] Starting subtest: bad-pitch-0
[115.372053] Subtest bad-pitch-0: SUCCESS (0.000s)
[115.491818] [064/165] (888s left) kms_addfb_basic (bad-pitch-1024)
[115.583187] Starting subtest: bad-pitch-1024
[115.584308] Subtest bad-pitch-1024: SUCCESS (0.000s)
[115.713766] [065/165] (888s left) kms_addfb_basic (bad-pitch-128)
[115.804555] Starting subtest: bad-pitch-128
[115.805588] Subtest bad-pitch-128: SUCCESS (0.000s)
[115.948071] [066/165] (888s left) kms_addfb_basic (bad-pitch-256)
[116.039473] Starting subtest: bad-pitch-256
[116.040545] Subtest bad-pitch-256: SUCCESS (0.000s)
[116.181324] [067/165] (888s left) kms_addfb_basic (bad-pitch-32)
[116.272480] Starting subtest: bad-pitch-32
[116.273553] Subtest bad-pitch-32: SUCCESS (0.000s)
[116.395885] [068/165] (888s left) kms_addfb_basic (bad-pitch-63)
[116.486900] Starting subtest: bad-pitch-63
[116.487948] Subtest bad-pitch-63: SUCCESS (0.000s)
[116.615613] [069/165] (887s left) kms_addfb_basic (bad-pitch-65536)
[116.706800] Starting subtest: bad-pitch-65536
[116.707920] Subtest bad-pitch-65536: SUCCESS (0.001s)
[116.826322] [070/165] (887s left) kms_addfb_basic (bad-pitch-999)
[116.917137] Starting subtest: bad-pitch-999
[116.918209] Subtest bad-pitch-999: SUCCESS (0.000s)
[117.047227] [071/165] (887s left) kms_addfb_basic (basic)
[117.137532] Starting subtest: basic
[117.138552] Subtest basic: SUCCESS (0.000s)
[117.283921] [072/165] (887s left) kms_addfb_basic (basic-x-tiled-legacy)
[117.374277] Starting subtest: basic-x-tiled-legacy
[117.375322] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[117.516330] [073/165] (887s left) kms_addfb_basic (basic-y-tiled-legacy)
[117.608946] Starting subtest: basic-y-tiled-legacy
[117.610065] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[117.732572] [074/165] (886s left) kms_addfb_basic (bo-too-small)
[117.853781] Starting subtest: bo-too-small
[117.855041] Subtest bo-too-small: SUCCESS (0.000s)
[117.949215] [075/165] (886s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[118.069644] Starting subtest: bo-too-small-due-to-tiling
[118.070954] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[118.161552] [076/165] (886s left) kms_addfb_basic (clobberred-modifier)
[118.251650] Starting subtest: clobberred-modifier
[118.252755] Subtest clobberred-modifier: SUCCESS (0.000s)
[118.362152] [077/165] (886s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[118.452666] Starting subtest: framebuffer-vs-set-tiling
[118.453784] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[118.583649] [078/165] (886s left) kms_addfb_basic (invalid-get-prop)
[118.673261] Starting subtest: invalid-get-prop
[118.674368] Subtest invalid-get-prop: SUCCESS (0.000s)
[118.800881] [079/165] (885s left) kms_addfb_basic (invalid-get-prop-any)
[118.889752] Starting subtest: invalid-get-prop-any
[118.891083] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[119.017485] [080/165] (885s left) kms_addfb_basic (invalid-set-prop)
[119.107938] Starting subtest: invalid-set-prop
[119.109097] Subtest invalid-set-prop: SUCCESS (0.000s)
[119.236833] [081/165] (885s left) kms_addfb_basic (invalid-set-prop-any)
[119.328501] Starting subtest: invalid-set-prop-any
[119.329696] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[119.468891] [082/165] (885s left) kms_addfb_basic (no-handle)
[119.558226] Starting subtest: no-handle
[119.559332] Subtest no-handle: SUCCESS (0.000s)
[119.684370] [083/165] (885s left) kms_addfb_basic (size-max)
[119.804107] Starting subtest: size-max
[119.805268] Subtest size-max: SUCCESS (0.000s)
[119.900544] [084/165] (885s left) kms_addfb_basic (small-bo)
[120.019554] Starting subtest: small-bo
[120.020689] Subtest small-bo: SUCCESS (0.000s)
[120.118525] [085/165] (884s left) kms_addfb_basic (tile-pitch-mismatch)
[120.210381] Starting subtest: tile-pitch-mismatch
[120.211430] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[120.363636] [086/165] (884s left) kms_addfb_basic (too-high)
[120.483498] Starting subtest: too-high
[120.484673] Subtest too-high: SUCCESS (0.001s)
[120.582206] [087/165] (884s left) kms_addfb_basic (too-wide)
[120.703060] Starting subtest: too-wide
[120.704171] Subtest too-wide: SUCCESS (0.000s)
[120.803515] [088/165] (884s left) kms_addfb_basic (unused-handle)
[120.893543] Starting subtest: unused-handle
[120.894637] Subtest unused-handle: SUCCESS (0.000s)
[121.048092] [089/165] (884s left) kms_addfb_basic (unused-modifier)
[121.138492] Starting subtest: unused-modifier
[121.139609] Subtest unused-modifier: SUCCESS (0.000s)
[121.268952] [090/165] (883s left) kms_addfb_basic (unused-offsets)
[121.359521] Starting subtest: unused-offsets
[121.360617] Subtest unused-offsets: SUCCESS (0.000s)
[121.482341] [091/165] (883s left) kms_addfb_basic (unused-pitches)
[121.573238] Starting subtest: unused-pitches
[121.574363] Subtest unused-pitches: SUCCESS (0.000s)
[121.704179] [092/165] (883s left) kms_busy (basic)
[121.831061] Starting subtest: basic
[121.833372] Starting dynamic subtest: flip
[122.836361] Dynamic subtest flip: SUCCESS (1.003s)
[122.853174] Starting dynamic subtest: modeset
[126.652733] Dynamic subtest modeset: SUCCESS (3.816s)
[126.653956] Subtest basic: SUCCESS (4.822s)
[127.607774] [093/165] (877s left) kms_prop_blob (basic)
[127.703750] Starting subtest: basic
[127.705118] Subtest basic: SUCCESS (0.000s)
[127.787279] [094/165] (877s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[127.907479] Starting subtest: basic-busy-flip-before-cursor-atomic
[127.909583] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[127.983600] [095/165] (877s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[128.102985] Starting subtest: basic-busy-flip-before-cursor-legacy
[128.105138] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[128.183925] [096/165] (877s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[128.302073] Starting subtest: basic-flip-after-cursor-atomic
[129.602016] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.300s)
[130.550121] [097/165] (874s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[130.671222] Starting subtest: basic-flip-after-cursor-legacy
[131.977465] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.307s)
[132.916145] [098/165] (872s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[133.036900] Starting subtest: basic-flip-after-cursor-varying-size
[134.311472] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.275s)
[135.283904] [099/165] (870s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[135.401352] Starting subtest: basic-flip-before-cursor-atomic
[136.711637] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.310s)
[137.655411] [100/165] (867s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[137.774870] Starting subtest: basic-flip-before-cursor-legacy
[139.086320] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.312s)
[140.025101] [101/165] (865s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[140.145270] Starting subtest: basic-flip-before-cursor-varying-size
[141.451604] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.307s)
[142.390217] [102/165] (863s left) kms_dsc (dsc-basic)
[142.512206] Starting subtest: dsc-basic
[142.514326] Subtest dsc-basic: SKIP (0.001s)
[142.589600] [103/165] (862s left) kms_flip (basic-flip-vs-dpms)
[142.689155] Starting subtest: basic-flip-vs-dpms
[142.692271] Starting dynamic subtest: A-eDP1
[145.598879] Dynamic subtest A-eDP1: SUCCESS (2.906s)
[145.619244] Starting dynamic subtest: B-eDP1
[148.018086] Dynamic subtest B-eDP1: SUCCESS (2.417s)
[148.036043] Starting dynamic subtest: C-eDP1
[150.449812] Dynamic subtest C-eDP1: SUCCESS (2.432s)
[150.452525] Subtest basic-flip-vs-dpms: SUCCESS (7.764s)
[151.360859] [104/165] (854s left) kms_flip (basic-flip-vs-modeset)
[151.457429] Starting subtest: basic-flip-vs-modeset
[151.459760] Starting dynamic subtest: A-eDP1
[154.286223] Dynamic subtest A-eDP1: SUCCESS (2.821s)
[154.301216] Starting dynamic subtest: B-eDP1
[156.657411] Dynamic subtest B-eDP1: SUCCESS (2.371s)
[156.672761] Starting dynamic subtest: C-eDP1
[159.018682] Dynamic subtest C-eDP1: SUCCESS (2.364s)
[159.019915] Subtest basic-flip-vs-modeset: SUCCESS (7.564s)
[159.994042] [105/165] (845s left) kms_flip (basic-flip-vs-wf_vblank)
[160.094148] Starting subtest: basic-flip-vs-wf_vblank
[160.097374] Starting dynamic subtest: A-eDP1
[162.453113] Dynamic subtest A-eDP1: SUCCESS (2.354s)
[162.471433] Starting dynamic subtest: B-eDP1
[164.740821] Dynamic subtest B-eDP1: SUCCESS (2.286s)
[164.760264] Starting dynamic subtest: C-eDP1
[166.983727] Dynamic subtest C-eDP1: SUCCESS (2.240s)
[166.989758] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.896s)
[168.311455] [106/165] (837s left) kms_flip (basic-plain-flip)
[168.409335] Starting subtest: basic-plain-flip
[168.412659] Starting dynamic subtest: A-eDP1
[170.490530] Dynamic subtest A-eDP1: SUCCESS (2.077s)
[170.513575] Starting dynamic subtest: B-eDP1
[172.457336] Dynamic subtest B-eDP1: SUCCESS (1.964s)
[172.477529] Starting dynamic subtest: C-eDP1
[174.441740] Dynamic subtest C-eDP1: SUCCESS (1.982s)
[174.447336] Subtest basic-plain-flip: SUCCESS (6.038s)
[175.734693] [107/165] (829s left) kms_force_connector_basic (force-connector-state)
[175.832053] Starting subtest: force-connector-state
[176.425816] Subtest force-connector-state: SUCCESS (0.592s)
[177.482799] [108/165] (828s left) kms_force_connector_basic (force-edid)
[177.574681] Starting subtest: force-edid
[177.837789] Subtest force-edid: SUCCESS (0.261s)
[177.984076] [109/165] (827s left) kms_force_connector_basic (force-load-detect)
[178.079704] Starting subtest: force-load-detect
[178.081824] Subtest force-load-detect: SKIP (0.000s)
[178.193888] [110/165] (827s left) kms_force_connector_basic (prune-stale-modes)
[178.289642] Starting subtest: prune-stale-modes
[178.609836] Subtest prune-stale-modes: SUCCESS (0.318s)
[178.749773] [111/165] (826s left) kms_frontbuffer_tracking (basic)
[178.878432] Starting subtest: basic
[182.229714] Subtest basic: SUCCESS (3.352s)
[182.370810] [112/165] (823s left) kms_hdmi_inject (inject-audio)
[182.786941] Starting subtest: inject-audio
[183.115094] Subtest inject-audio: SUCCESS (0.326s)
[183.720823] [113/165] (822s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[183.842865] Starting subtest: compare-crc-sanitycheck-xr24
[183.844156] Starting dynamic subtest: pipe-A-eDP-1
[184.515189] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.671s)
[184.535411] Starting dynamic subtest: pipe-B-eDP-1
[185.941328] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.422s)
[185.966187] Starting dynamic subtest: pipe-C-eDP-1
[187.320404] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.374s)
[187.321840] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.478s)
[188.280530] [114/165] (817s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[188.402176] Starting subtest: compare-crc-sanitycheck-nv12
[188.403238] Starting dynamic subtest: pipe-A-eDP-1
[189.340876] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.937s)
[189.360786] Starting dynamic subtest: pipe-B-eDP-1
[190.740709] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.394s)
[190.774097] Starting dynamic subtest: pipe-C-eDP-1
[192.118126] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.371s)
[192.119245] Subtest compare-crc-sanitycheck-nv12: SUCCESS (3.716s)
[193.080324] [115/165] (812s left) kms_pipe_crc_basic (hang-read-crc)
[193.201232] Starting subtest: hang-read-crc
[193.204015] Starting dynamic subtest: pipe-A-eDP-1
[195.564870] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.358s)
[195.590022] Starting dynamic subtest: pipe-B-eDP-1
[198.667473] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.097s)
[198.686095] Starting dynamic subtest: pipe-C-eDP-1
[201.677647] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.006s)
[201.678994] Subtest hang-read-crc: SUCCESS (8.475s)
[202.616532] [116/165] (803s left) kms_pipe_crc_basic (nonblocking-crc)
[202.737614] Starting subtest: nonblocking-crc
[202.740457] Starting dynamic subtest: pipe-A-eDP-1
[203.634207] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.892s)
[203.668937] Starting dynamic subtest: pipe-B-eDP-1
[205.219026] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.575s)
[205.249096] Starting dynamic subtest: pipe-C-eDP-1
[206.785455] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.552s)
[206.795444] Subtest nonblocking-crc: SUCCESS (4.039s)
[207.733705] [117/165] (798s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[207.854196] Starting subtest: nonblocking-crc-frame-sequence
[207.856965] Starting dynamic subtest: pipe-A-eDP-1
[208.733754] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.875s)
[208.755531] Starting dynamic subtest: pipe-B-eDP-1
[210.301351] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.562s)
[210.324359] Starting dynamic subtest: pipe-C-eDP-1
[211.845196] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.539s)
[211.846427] Subtest nonblocking-crc-frame-sequence: SUCCESS (3.991s)
[212.793421] [118/165] (793s left) kms_pipe_crc_basic (read-crc)
[212.914388] Starting subtest: read-crc
[212.917219] Starting dynamic subtest: pipe-A-eDP-1
[213.740943] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.823s)
[213.772063] Starting dynamic subtest: pipe-B-eDP-1
[215.264328] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.515s)
[215.283208] Starting dynamic subtest: pipe-C-eDP-1
[216.765255] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.498s)
[216.766804] Subtest read-crc: SUCCESS (3.851s)
[217.733051] [119/165] (788s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[217.854488] Starting subtest: read-crc-frame-sequence
[217.857417] Starting dynamic subtest: pipe-A-eDP-1
[218.681520] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.823s)
[218.700680] Starting dynamic subtest: pipe-B-eDP-1
[220.236647] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.551s)
[220.257344] Starting dynamic subtest: pipe-C-eDP-1
[221.733489] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.491s)
[221.734970] Subtest read-crc-frame-sequence: SUCCESS (3.879s)
[222.662607] [120/165] (783s left) kms_pm_backlight (basic-brightness)
[222.784661] Starting subtest: basic-brightness
[222.898443] Starting dynamic subtest: eDP-1
[223.476671] Dynamic subtest eDP-1: SUCCESS (0.585s)
[223.478073] Subtest basic-brightness: SUCCESS (0.691s)
[224.439918] [121/165] (781s left) kms_psr (primary_page_flip)
[224.563342] Starting subtest: primary_page_flip
[228.384032] Subtest primary_page_flip: SUCCESS (3.815s)
[229.303390] [122/165] (776s left) kms_psr (cursor_plane_move)
[229.427482] Starting subtest: cursor_plane_move
[233.210920] Subtest cursor_plane_move: SUCCESS (3.782s)
[234.168237] [123/165] (771s left) kms_psr (sprite_plane_onoff)
[234.290451] Starting subtest: sprite_plane_onoff
[238.162564] Subtest sprite_plane_onoff: SUCCESS (3.871s)
[239.096120] [124/165] (767s left) kms_psr (primary_mmap_gtt)
[239.218368] Starting subtest: primary_mmap_gtt
[243.046682] Subtest primary_mmap_gtt: SUCCESS (3.827s)
[244.027541] [125/165] (762s left) kms_setmode (basic-clone-single-crtc)
[244.119327] Starting subtest: basic-clone-single-crtc
[244.122503] Subtest basic-clone-single-crtc: SKIP (0.000s)
[244.210550] [126/165] (761s left) i915_pm_rpm (basic-pci-d3-state)
[246.044626] Starting subtest: basic-pci-d3-state
[250.443658] Subtest basic-pci-d3-state: SUCCESS (4.399s)
[250.650232] [127/165] (755s left) i915_pm_rpm (basic-rte)
[250.739778] Starting subtest: basic-rte
[256.847790] Subtest basic-rte: SUCCESS (6.107s)
[258.332402] [128/165] (747s left) i915_pm_rps (basic-api)
[258.431838] Starting subtest: basic-api
[258.435951] Subtest basic-api: SUCCESS (0.004s)
[258.534590] [129/165] (747s left) prime_self_import (basic-llseek-bad)
[258.622852] Starting subtest: basic-llseek-bad
[258.627931] Subtest basic-llseek-bad: SUCCESS (0.003s)
[258.734534] [130/165] (747s left) prime_self_import (basic-llseek-size)
[258.823967] Starting subtest: basic-llseek-size
[258.828348] Subtest basic-llseek-size: SUCCESS (0.004s)
[258.936119] [131/165] (747s left) prime_self_import (basic-with_fd_dup)
[259.021621] Starting subtest: basic-with_fd_dup
[259.026265] Subtest basic-with_fd_dup: SUCCESS (0.004s)
[259.135912] [132/165] (747s left) prime_self_import (basic-with_one_bo)
[259.224082] Starting subtest: basic-with_one_bo
[259.228895] Subtest basic-with_one_bo: SUCCESS (0.005s)
[259.314799] [133/165] (747s left) prime_self_import (basic-with_one_bo_two_files)
[259.402502] Starting subtest: basic-with_one_bo_two_files
[259.407142] Subtest basic-with_one_bo_two_files: SUCCESS (0.004s)
[259.519942] [134/165] (746s left) prime_self_import (basic-with_two_bos)
[259.607421] Starting subtest: basic-with_two_bos
[259.611908] Subtest basic-with_two_bos: SUCCESS (0.004s)
[259.721022] [135/165] (746s left) prime_vgem (basic-fence-flip)
[259.834789] Starting subtest: basic-fence-flip
[260.375981] Subtest basic-fence-flip: SUCCESS (0.541s)
[260.505508] [136/165] (745s left) prime_vgem (basic-fence-mmap)
[260.638568] Starting subtest: basic-fence-mmap
[260.688811] Subtest basic-fence-mmap: SUCCESS (0.050s)
[260.787612] [137/165] (745s left) prime_vgem (basic-fence-read)
[260.903474] Starting subtest: basic-fence-read
[260.979866] Subtest basic-fence-read: SUCCESS (0.077s)
[261.089919] [138/165] (745s left) prime_vgem (basic-gtt)
[261.214488] Starting subtest: basic-gtt
[261.238576] Subtest basic-gtt: SUCCESS (0.025s)
[261.352867] [139/165] (745s left) prime_vgem (basic-read)
[261.469466] Starting subtest: basic-read
[261.507519] Subtest basic-read: SUCCESS (0.038s)
[261.635617] [140/165] (744s left) prime_vgem (basic-write)
[261.736414] Starting subtest: basic-write
[261.780678] Subtest basic-write: SUCCESS (0.044s)
[261.870908] [141/165] (744s left) vgem_basic (setversion)
[261.962331] Starting subtest: setversion
[261.964442] Subtest setversion: SUCCESS (0.000s)
[262.037365] [142/165] (744s left) vgem_basic (create)
[262.146698] Starting subtest: create
[262.148106] Subtest create: SUCCESS (0.000s)
[262.221770] [143/165] (744s left) vgem_basic (debugfs)
[262.329517] Starting subtest: debugfs
[262.330725] Subtest debugfs: SUCCESS (0.001s)
[262.403837] [144/165] (744s left) vgem_basic (dmabuf-export)
[262.496387] Starting subtest: dmabuf-export
[262.500883] Subtest dmabuf-export: SUCCESS (0.003s)
[262.606111] [145/165] (744s left) vgem_basic (dmabuf-fence)
[262.713481] Starting subtest: dmabuf-fence
[262.714801] Subtest dmabuf-fence: SUCCESS (0.001s)
[262.791053] [146/165] (744s left) vgem_basic (dmabuf-fence-before)
[262.900158] Starting subtest: dmabuf-fence-before
[262.901614] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[262.972394] [147/165] (743s left) vgem_basic (dmabuf-mmap)
[263.080041] Starting subtest: dmabuf-mmap
[263.105902] Subtest dmabuf-mmap: SUCCESS (0.026s)
[263.187536] [148/165] (743s left) vgem_basic (mmap)
[263.297303] Starting subtest: mmap
[263.314234] Subtest mmap: SUCCESS (0.017s)
[263.386278] [149/165] (743s left) vgem_basic (second-client)
[263.497438] Starting subtest: second-client
[263.533277] Subtest second-client: SUCCESS (0.034s)
[263.606253] [150/165] (743s left) vgem_basic (sysfs)
[263.714144] Starting subtest: sysfs
[263.715347] Subtest sysfs: SUCCESS (0.001s)
[263.788203] [151/165] (743s left) core_hotunplug (unbind-rebind)
[263.930749] Starting subtest: unbind-rebind
[268.423953] Subtest unbind-rebind: SUCCESS (4.493s)
[268.461538] [152/165] (738s left) vgem_basic (unload)
[268.554576] Starting subtest: unload
[297.591015] Subtest unload: SUCCESS (29.001s)
[297.699405] [153/165] (709s left) i915_module_load (reload)
[297.794168] Starting subtest: reload
[301.158078] Subtest reload: SUCCESS (3.364s)
[301.189823] [154/165] (705s left) gem_lmem_swapping (basic)
[301.298087] Subtest basic: SKIP (0.000s)
[301.345319] [155/165] (705s left) gem_lmem_swapping (parallel-random-engines)
[301.442694] Subtest parallel-random-engines: SKIP (0.000s)
[301.492916] [156/165] (705s left) gem_lmem_swapping (random-engines)
[301.589904] Subtest random-engines: SKIP (0.000s)
[301.644113] [157/165] (705s left) gem_lmem_swapping (verify-random)
[301.740222] Subtest verify-random: SKIP (0.000s)
[301.793488] [158/165] (705s left) i915_pm_rpm (module-reload)
[314.237578] Starting subtest: module-reload
[348.594610] Subtest module-reload: SUCCESS (34.348s)
[348.617823] [159/165] (658s left) i915_selftest (live)
[348.723526] Starting subtest: live
[348.724861] Starting dynamic subtest: sanitycheck
[350.563545] Dynamic subtest sanitycheck: SUCCESS (1.830s)
[350.578511] Starting dynamic subtest: uncore
[352.461491] Dynamic subtest uncore: SUCCESS (1.894s)
[352.476311] Starting dynamic subtest: workarounds
[354.606495] Dynamic subtest workarounds: SUCCESS (2.141s)
[354.621409] Starting dynamic subtest: gt_engines
[356.939418] Dynamic subtest gt_engines: SUCCESS (2.329s)
[356.954186] Starting dynamic subtest: gt_timelines
[369.825598] Dynamic subtest gt_timelines: SUCCESS (12.882s)
[369.840407] Starting dynamic subtest: gt_contexts
[371.808434] Dynamic subtest gt_contexts: SUCCESS (1.979s)
[371.823135] Starting dynamic subtest: gt_lrc
[376.211478] Dynamic subtest gt_lrc: SUCCESS (4.399s)
[376.226754] Starting dynamic subtest: gt_mocs
[378.200766] Dynamic subtest gt_mocs: SUCCESS (1.986s)
[378.215411] Starting dynamic subtest: gt_pm
[383.340507] Dynamic subtest gt_pm: SUCCESS (5.136s)
[383.355638] Starting dynamic subtest: gt_heartbeat
[385.321419] Dynamic subtest gt_heartbeat: SUCCESS (1.977s)
[385.336790] Starting dynamic subtest: gt_tlb
[388.435105] Dynamic subtest gt_tlb: SUCCESS (3.110s)
[388.451948] Starting dynamic subtest: requests
[396.052793] Dynamic subtest requests: SUCCESS (7.614s)
[396.067966] Starting dynamic subtest: migrate
[400.673566] Dynamic subtest migrate: SUCCESS (4.617s)
[400.688714] Starting dynamic subtest: active
[402.579393] Dynamic subtest active: SUCCESS (1.902s)
[402.594535] Starting dynamic subtest: objects
[406.082814] Dynamic subtest objects: SUCCESS (3.499s)
[406.098466] Starting dynamic subtest: mman
[413.032384] Dynamic subtest mman: SUCCESS (6.946s)
[413.047556] Starting dynamic subtest: dmabuf
[414.897957] Dynamic subtest dmabuf: SUCCESS (1.863s)
[414.913730] Starting dynamic subtest: vma
[416.822430] Dynamic subtest vma: SUCCESS (1.919s)
[416.837461] Starting dynamic subtest: coherency
[421.390378] Dynamic subtest coherency: SUCCESS (4.564s)
[421.405720] Starting dynamic subtest: gtt
[430.879586] Dynamic subtest gtt: SUCCESS (9.486s)
[430.894638] Starting dynamic subtest: gem
[432.860911] Dynamic subtest gem: SUCCESS (1.977s)
[432.876976] Starting dynamic subtest: evict
[436.695411] Dynamic subtest evict: SUCCESS (3.831s)
[436.710951] Starting dynamic subtest: hugepages
[444.490879] Dynamic subtest hugepages: SUCCESS (7.792s)
[444.506362] Starting dynamic subtest: gem_contexts
[459.943421] Dynamic subtest gem_contexts: SUCCESS (15.449s)
[459.959307] Starting dynamic subtest: client
[461.860384] Dynamic subtest client: SUCCESS (1.914s)
[461.876111] Starting dynamic subtest: gem_migrate
[463.756042] Dynamic subtest gem_migrate: SUCCESS (1.892s)
[463.771990] Starting dynamic subtest: reset
[469.171460] Dynamic subtest reset: SUCCESS (5.412s)
[469.187352] Starting dynamic subtest: memory_region
[471.072522] Dynamic subtest memory_region: SUCCESS (1.898s)
[471.088296] Starting dynamic subtest: hangcheck
[495.574457] Dynamic subtest hangcheck: SUCCESS (24.498s)
[495.590330] Starting dynamic subtest: execlists
[505.728586] Dynamic subtest execlists: SUCCESS (10.151s)
[505.744673] Starting dynamic subtest: ring_submission
[507.609828] Dynamic subtest ring_submission: SUCCESS (1.878s)
[507.625790] Starting dynamic subtest: perf
[509.602528] Dynamic subtest perf: SUCCESS (1.989s)
[509.618173] Starting dynamic subtest: slpc
[511.443420] Dynamic subtest slpc: SUCCESS (1.837s)
[511.459155] Starting dynamic subtest: guc
[513.300433] Dynamic subtest guc: SUCCESS (1.854s)
[513.316096] Starting dynamic subtest: guc_multi_lrc
[515.161539] Dynamic subtest guc_multi_lrc: SUCCESS (1.858s)
[515.177303] Starting dynamic subtest: guc_hang
[517.003470] Dynamic subtest guc_hang: SUCCESS (1.838s)
[517.019572] Starting dynamic subtest: late_gt_pm
[518.877377] Dynamic subtest late_gt_pm: SUCCESS (1.871s)
[518.878650] Subtest live: SUCCESS (170.147s)
[518.903874] [160/165] (488s left) dmabuf (all-tests)
[519.001952] Starting subtest: all-tests
[519.003572] Starting dynamic subtest: sanitycheck
[519.066744] Dynamic subtest sanitycheck: SUCCESS (0.063s)
[519.085944] Starting dynamic subtest: dma_fence
[519.255985] Dynamic subtest dma_fence: SUCCESS (0.185s)
[519.275279] Starting dynamic subtest: dma_fence_chain
[537.745220] Dynamic subtest dma_fence_chain: SUCCESS (18.477s)
[537.758852] Starting dynamic subtest: dma_fence_unwrap
[537.819881] Dynamic subtest dma_fence_unwrap: SUCCESS (0.079s)
[537.840372] Starting dynamic subtest: dma_resv
[537.896904] Dynamic subtest dma_resv: SUCCESS (0.073s)
[537.899207] Subtest all-tests: SUCCESS (18.896s)
[537.938516] [161/165] (469s left) i915_suspend (basic-s2idle-without-i915)
[538.029113] Starting subtest: basic-s2idle-without-i915
[561.671930] Subtest basic-s2idle-without-i915: SUCCESS (8.190s)
[562.057605] [162/165] (460s left) i915_suspend (basic-s3-without-i915)
[562.149277] Starting subtest: basic-s3-without-i915
[585.735507] Subtest basic-s3-without-i915: SUCCESS (6.841s)
[586.073424] [163/165] (453s left) gem_exec_suspend (basic-s0)
[586.191588] Starting subtest: basic-S0
[586.192775] Starting dynamic subtest: smem
[605.883676] Dynamic subtest smem: SUCCESS (3.816s)
[605.884945] Subtest basic-S0: SUCCESS (3.818s)
[606.101676] [164/165] (449s left) gem_exec_suspend (basic-s3)
[606.210654] Starting subtest: basic-S3
[606.211778] Starting dynamic subtest: smem
[624.897854] Dynamic subtest smem: SUCCESS (3.668s)
[624.899152] Subtest basic-S3: SUCCESS (3.670s)
[625.103941] [165/165] (445s left) kms_pipe_crc_basic (suspend-read-crc)
[625.226392] Starting subtest: suspend-read-crc
[625.229404] Starting dynamic subtest: pipe-A-eDP-1
[642.870550] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.503s)
[642.896617] Starting dynamic subtest: pipe-B-eDP-1
[661.816266] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.214s)
[661.843332] Starting dynamic subtest: pipe-C-eDP-1
[679.820917] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.097s)
[679.822976] Subtest suspend-read-crc: SUCCESS (8.832s)
[680.701500] Closing watchdogs
Done.