[42.265104] Initializing watchdogs
[42.265253]   /dev/watchdog0
[42.275511] [001/165] (960s left) i915_module_load (load)
[42.375186] Starting subtest: load
[44.940284] Subtest load: SUCCESS (2.565s)
[44.965128] [002/165] (957s left) core_auth (basic-auth)
[45.062974] Starting subtest: basic-auth
[45.064232] Subtest basic-auth: SUCCESS (0.002s)
[45.141341] [003/165] (957s left) debugfs_test (read_all_entries)
[45.240190] Starting subtest: read_all_entries
[45.311196] Subtest read_all_entries: SUCCESS (0.070s)
[45.426119] [004/165] (957s left) debugfs_test (basic-hwmon)
[45.521413] Starting subtest: basic-hwmon
[45.523378] Subtest basic-hwmon: SKIP (0.000s)
[45.622222] [005/165] (957s left) fbdev (eof)
[45.727146] Starting subtest: eof
[45.728497] Subtest eof: SUCCESS (0.001s)
[45.801020] [006/165] (957s left) fbdev (info)
[45.907824] Starting subtest: info
[45.908992] Subtest info: SUCCESS (0.000s)
[45.988987] [007/165] (956s left) fbdev (nullptr)
[46.092766] Starting subtest: nullptr
[46.094244] Subtest nullptr: SUCCESS (0.000s)
[46.160602] [008/165] (956s left) fbdev (read)
[46.258651] Starting subtest: read
[53.323089] Subtest read: SUCCESS (7.053s)
[53.383335] [009/165] (949s left) fbdev (write)
[53.482399] Starting subtest: write
[57.256992] Subtest write: SUCCESS (3.740s)
[57.305860] [010/165] (945s left) gem_basic (bad-close)
[57.399418] Starting subtest: bad-close
[57.401763] Subtest bad-close: SUCCESS (0.000s)
[57.494435] [011/165] (945s left) gem_basic (create-close)
[57.583281] Starting subtest: create-close
[57.585303] Subtest create-close: SUCCESS (0.000s)
[57.707390] [012/165] (945s left) gem_basic (create-fd-close)
[57.798630] Starting subtest: create-fd-close
[57.800807] Subtest create-fd-close: SUCCESS (0.000s)
[57.907889] [013/165] (945s left) gem_busy (busy@all-engines)
[58.015457] Starting subtest: busy
[58.016631] Starting dynamic subtest: all-engines
[58.074138] Dynamic subtest all-engines: SUCCESS (0.058s)
[58.075408] Subtest busy: SUCCESS (0.059s)
[58.479211] [014/165] (944s left) gem_close_race (basic-process)
[58.593553] Starting subtest: basic-process
[58.665915] Subtest basic-process: SUCCESS (0.074s)
[58.877550] [015/165] (944s left) gem_close_race (basic-threads)
[58.988157] Starting subtest: basic-threads
[60.160478] Subtest basic-threads: SUCCESS (1.172s)
[60.405029] [016/165] (942s left) gem_ctx_create (basic)
[60.512160] Starting subtest: basic
[60.513255] Subtest basic: SUCCESS (0.000s)
[60.712708] [017/165] (942s left) gem_ctx_create (basic-files)
[60.822247] Starting subtest: basic-files
[62.848229] Subtest basic-files: SUCCESS (2.026s)
[63.111726] [018/165] (940s left) gem_ctx_exec (basic)
[63.205071] Starting subtest: basic
[63.210468] Subtest basic: SUCCESS (0.005s)
[63.331378] [019/165] (939s left) gem_exec_basic (basic)
[63.431945] Starting subtest: basic
[63.433193] Starting dynamic subtest: rcs0-smem
[63.434255] Dynamic subtest rcs0-smem: SUCCESS (0.002s)
[63.445538] Starting dynamic subtest: bcs0-smem
[63.446689] Dynamic subtest bcs0-smem: SUCCESS (0.001s)
[63.457286] Starting dynamic subtest: vcs0-smem
[63.458542] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[63.469085] Starting dynamic subtest: vecs0-smem
[63.470458] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[63.471769] Subtest basic: SUCCESS (0.006s)
[63.647217] [020/165] (939s left) gem_exec_create (basic)
[63.753027] Starting subtest: basic
[63.754221] Starting dynamic subtest: smem
[65.816380] Dynamic subtest smem: SUCCESS (2.063s)
[65.817504] Subtest basic: SUCCESS (2.064s)
[66.065457] [021/165] (937s left) gem_exec_fence (basic-busy)
[66.306486] Starting subtest: basic-busy
[66.307617] Starting dynamic subtest: rcs0
[66.328812] Dynamic subtest rcs0: SUCCESS (0.030s)
[66.342703] Starting dynamic subtest: bcs0
[66.345202] Dynamic subtest bcs0: SUCCESS (0.014s)
[66.358355] Starting dynamic subtest: vcs0
[66.365667] Dynamic subtest vcs0: SUCCESS (0.023s)
[66.378829] Starting dynamic subtest: vecs0
[66.384726] Dynamic subtest vecs0: SUCCESS (0.016s)
[66.385818] Subtest basic-busy: SUCCESS (0.084s)
[66.949989] [022/165] (936s left) gem_exec_fence (basic-wait)
[67.213049] Starting subtest: basic-wait
[67.214532] Starting dynamic subtest: rcs0
[67.228321] Dynamic subtest rcs0: SUCCESS (0.025s)
[67.240397] Starting dynamic subtest: bcs0
[67.248351] Dynamic subtest bcs0: SUCCESS (0.020s)
[67.260362] Starting dynamic subtest: vcs0
[67.269962] Dynamic subtest vcs0: SUCCESS (0.021s)
[67.281570] Starting dynamic subtest: vecs0
[67.299460] Dynamic subtest vecs0: SUCCESS (0.029s)
[67.300738] Subtest basic-wait: SUCCESS (0.096s)
[67.836558] [023/165] (935s left) gem_exec_fence (basic-await)
[68.088411] Starting subtest: basic-await
[68.089552] Starting dynamic subtest: rcs0
[68.180610] Dynamic subtest rcs0: SUCCESS (0.098s)
[68.205610] Starting dynamic subtest: bcs0
[68.292297] Dynamic subtest bcs0: SUCCESS (0.111s)
[68.319279] Starting dynamic subtest: vcs0
[68.430944] Dynamic subtest vcs0: SUCCESS (0.131s)
[68.457488] Starting dynamic subtest: vecs0
[68.555427] Dynamic subtest vecs0: SUCCESS (0.131s)
[68.557469] Subtest basic-await: SUCCESS (0.474s)
[69.122336] [024/165] (934s left) gem_exec_fence (nb-await)
[69.378214] Starting subtest: nb-await
[69.391194] Starting dynamic subtest: rcs0
[69.433510] Dynamic subtest rcs0: SUCCESS (0.065s)
[69.451540] Starting dynamic subtest: bcs0
[69.501343] Dynamic subtest bcs0: SUCCESS (0.068s)
[69.522080] Starting dynamic subtest: vcs0
[69.573214] Dynamic subtest vcs0: SUCCESS (0.071s)
[69.602296] Starting dynamic subtest: vecs0
[69.647344] Dynamic subtest vecs0: SUCCESS (0.074s)
[69.649542] Subtest nb-await: SUCCESS (0.281s)
[70.205215] [025/165] (933s left) gem_exec_gttfill (basic)
[70.312399] Starting subtest: basic
[76.861345] Subtest basic: SUCCESS (6.540s)
[77.044160] [026/165] (926s left) gem_exec_parallel (engines)
[77.221293] Starting subtest: engines
[77.222658] Starting dynamic subtest: basic
[79.663536] Dynamic subtest basic: SUCCESS (2.421s)
[79.680440] Starting dynamic subtest: contexts
[86.523365] Dynamic subtest contexts: SUCCESS (6.873s)
[86.537608] Starting dynamic subtest: fds
[94.997261] Dynamic subtest fds: SUCCESS (8.482s)
[95.028631] Starting dynamic subtest: userptr
[97.394376] Dynamic subtest userptr: SUCCESS (2.372s)
[97.396266] Subtest engines: SUCCESS (20.149s)
[97.608385] [027/165] (905s left) gem_exec_store (basic)
[97.716833] Starting subtest: basic
[97.789649] Subtest basic: SUCCESS (0.072s)
[98.005356] [028/165] (905s left) gem_flink_basic (bad-flink)
[98.093849] Starting subtest: bad-flink
[98.095157] Subtest bad-flink: SUCCESS (0.000s)
[98.186901] [029/165] (905s left) gem_flink_basic (bad-open)
[98.274017] Starting subtest: bad-open
[98.275268] Subtest bad-open: SUCCESS (0.000s)
[98.369875] [030/165] (905s left) gem_flink_basic (basic)
[98.460185] Starting subtest: basic
[98.461259] Subtest basic: SUCCESS (0.000s)
[98.570951] [031/165] (905s left) gem_flink_basic (double-flink)
[98.660754] Starting subtest: double-flink
[98.661975] Subtest double-flink: SUCCESS (0.000s)
[98.769547] [032/165] (904s left) gem_flink_basic (flink-lifetime)
[98.858402] Starting subtest: flink-lifetime
[98.860832] Subtest flink-lifetime: SUCCESS (0.002s)
[98.971688] [033/165] (904s left) gem_huc_copy (huc-copy)
[99.069199] Starting subtest: huc-copy
[99.071357] Subtest huc-copy: SKIP (0.000s)
[99.188137] [034/165] (904s left) gem_linear_blits (basic)
[99.447595] Starting subtest: basic
[99.459276] Subtest basic: SUCCESS (0.012s)
[99.609325] [035/165] (904s left) gem_mmap (basic)
[99.769488] Starting subtest: basic
[99.774586] Subtest basic: SUCCESS (0.001s)
[99.889261] [036/165] (903s left) gem_mmap_gtt (basic)
[99.978092] Starting subtest: basic
[99.979230] Subtest basic: SUCCESS (0.001s)
[100.064852] [037/165] (903s left) gem_render_linear_blits (basic)
[100.161366] Starting subtest: basic
[100.210972] Subtest basic: SUCCESS (0.050s)
[100.281574] [038/165] (903s left) gem_render_tiled_blits (basic)
[100.377568] Starting subtest: basic
[100.437091] Subtest basic: SUCCESS (0.059s)
[100.498378] [039/165] (903s left) gem_ringfill (basic-all)
[100.714484] Starting subtest: basic-all
[101.949517] Subtest basic-all: SUCCESS (1.226s)
[102.030310] [040/165] (901s left) gem_softpin (allocator-basic)
[102.127891] Starting subtest: allocator-basic
[102.297282] Subtest allocator-basic: SUCCESS (0.161s)
[102.426219] [041/165] (901s left) gem_softpin (allocator-basic-reserve)
[102.521184] Starting subtest: allocator-basic-reserve
[102.693304] Subtest allocator-basic-reserve: SUCCESS (0.163s)
[102.806912] [042/165] (901s left) gem_softpin (safe-alignment)
[102.904938] Starting subtest: safe-alignment
[102.922700] Subtest safe-alignment: SUCCESS (0.018s)
[103.059545] [043/165] (900s left) gem_sync (basic-all)
[103.165783] Starting subtest: basic-all
[105.436208] Subtest basic-all: SUCCESS (2.257s)
[105.627847] [044/165] (898s left) gem_sync (basic-each)
[105.733637] Starting subtest: basic-each
[107.982492] Subtest basic-each: SUCCESS (2.249s)
[108.224561] [045/165] (895s left) gem_tiled_blits (basic)
[108.461438] Starting subtest: basic
[108.491587] Subtest basic: SUCCESS (0.029s)
[108.634572] [046/165] (895s left) gem_tiled_fence_blits (basic)
[108.957370] Starting subtest: basic
[108.984835] Subtest basic: SUCCESS (0.028s)
[109.133600] [047/165] (894s left) gem_tiled_pread_basic
[109.631753] [048/165] (894s left) gem_wait (busy@all-engines)
[109.748467] Starting subtest: busy
[109.749675] Starting dynamic subtest: all-engines
[110.279505] Dynamic subtest all-engines: SUCCESS (0.530s)
[110.280524] Subtest busy: SUCCESS (0.530s)
[110.534191] [049/165] (893s left) gem_wait (wait@all-engines)
[110.650089] Starting subtest: wait
[110.651543] Starting dynamic subtest: all-engines
[111.693114] Dynamic subtest all-engines: SUCCESS (1.042s)
[111.694929] Subtest wait: SUCCESS (1.043s)
[111.935707] [050/165] (892s left) i915_getparams_basic (basic-eu-total)
[112.028275] Starting subtest: basic-eu-total
[112.030698] Subtest basic-eu-total: SUCCESS (0.000s)
[112.118329] [051/165] (892s left) i915_getparams_basic (basic-subslice-total)
[112.209143] Starting subtest: basic-subslice-total
[112.211465] Subtest basic-subslice-total: SUCCESS (0.000s)
[112.302299] [052/165] (891s left) i915_hangman (error-state-basic)
[112.407200] Starting subtest: error-state-basic
[112.417828] Subtest error-state-basic: SUCCESS (0.010s)
[112.517189] [053/165] (891s left) i915_pciid
[112.701388] [054/165] (891s left) kms_addfb_basic (addfb25-4-tiled)
[112.830935] Starting subtest: addfb25-4-tiled
[112.832122] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[112.936513] [055/165] (891s left) kms_addfb_basic (addfb25-bad-modifier)
[113.027519] Starting subtest: addfb25-bad-modifier
[113.028696] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[113.154126] [056/165] (891s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[113.247319] Starting subtest: addfb25-framebuffer-vs-set-tiling
[113.248361] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[113.387765] [057/165] (890s left) kms_addfb_basic (addfb25-modifier-no-flag)
[113.477201] Starting subtest: addfb25-modifier-no-flag
[113.478352] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[113.602483] [058/165] (890s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[113.694112] Starting subtest: addfb25-x-tiled-legacy
[113.695228] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[113.838653] [059/165] (890s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[113.930698] Starting subtest: addfb25-x-tiled-mismatch-legacy
[113.931850] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[114.071193] [060/165] (890s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[114.191262] Starting subtest: addfb25-yf-tiled-legacy
[114.192472] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[114.282204] [061/165] (890s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[114.402027] Starting subtest: addfb25-y-tiled-legacy
[114.403112] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[114.504633] [062/165] (889s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[114.624065] Starting subtest: addfb25-y-tiled-small-legacy
[114.625167] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s)
[114.737542] [063/165] (889s left) kms_addfb_basic (bad-pitch-0)
[114.827747] Starting subtest: bad-pitch-0
[114.828993] Subtest bad-pitch-0: SUCCESS (0.000s)
[114.955492] [064/165] (889s left) kms_addfb_basic (bad-pitch-1024)
[115.045633] Starting subtest: bad-pitch-1024
[115.046744] Subtest bad-pitch-1024: SUCCESS (0.000s)
[115.172124] [065/165] (889s left) kms_addfb_basic (bad-pitch-128)
[115.262546] Starting subtest: bad-pitch-128
[115.263647] Subtest bad-pitch-128: SUCCESS (0.000s)
[115.383447] [066/165] (889s left) kms_addfb_basic (bad-pitch-256)
[115.471412] Starting subtest: bad-pitch-256
[115.472595] Subtest bad-pitch-256: SUCCESS (0.000s)
[115.606352] [067/165] (889s left) kms_addfb_basic (bad-pitch-32)
[115.696104] Starting subtest: bad-pitch-32
[115.697197] Subtest bad-pitch-32: SUCCESS (0.000s)
[115.822232] [068/165] (888s left) kms_addfb_basic (bad-pitch-63)
[115.913392] Starting subtest: bad-pitch-63
[115.914484] Subtest bad-pitch-63: SUCCESS (0.000s)
[116.037300] [069/165] (888s left) kms_addfb_basic (bad-pitch-65536)
[116.130206] Starting subtest: bad-pitch-65536
[116.131247] Subtest bad-pitch-65536: SUCCESS (0.000s)
[116.261602] [070/165] (888s left) kms_addfb_basic (bad-pitch-999)
[116.351851] Starting subtest: bad-pitch-999
[116.352988] Subtest bad-pitch-999: SUCCESS (0.000s)
[116.489292] [071/165] (888s left) kms_addfb_basic (basic)
[116.579329] Starting subtest: basic
[116.580477] Subtest basic: SUCCESS (0.000s)
[116.724196] [072/165] (888s left) kms_addfb_basic (basic-x-tiled-legacy)
[116.815650] Starting subtest: basic-x-tiled-legacy
[116.816804] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[116.957565] [073/165] (887s left) kms_addfb_basic (basic-y-tiled-legacy)
[117.047519] Starting subtest: basic-y-tiled-legacy
[117.048722] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[117.172778] [074/165] (887s left) kms_addfb_basic (bo-too-small)
[117.293926] Starting subtest: bo-too-small
[117.295189] Subtest bo-too-small: SUCCESS (0.000s)
[117.390306] [075/165] (887s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[117.509994] Starting subtest: bo-too-small-due-to-tiling
[117.511214] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[117.606675] [076/165] (887s left) kms_addfb_basic (clobberred-modifier)
[117.697586] Starting subtest: clobberred-modifier
[117.698689] Subtest clobberred-modifier: SUCCESS (0.000s)
[117.827835] [077/165] (887s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[117.918691] Starting subtest: framebuffer-vs-set-tiling
[117.919862] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[118.058551] [078/165] (886s left) kms_addfb_basic (invalid-get-prop)
[118.148397] Starting subtest: invalid-get-prop
[118.149485] Subtest invalid-get-prop: SUCCESS (0.000s)
[118.275209] [079/165] (886s left) kms_addfb_basic (invalid-get-prop-any)
[118.366650] Starting subtest: invalid-get-prop-any
[118.368115] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[118.508609] [080/165] (886s left) kms_addfb_basic (invalid-set-prop)
[118.598352] Starting subtest: invalid-set-prop
[118.599516] Subtest invalid-set-prop: SUCCESS (0.000s)
[118.729107] [081/165] (886s left) kms_addfb_basic (invalid-set-prop-any)
[118.821501] Starting subtest: invalid-set-prop-any
[118.822524] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[118.961319] [082/165] (886s left) kms_addfb_basic (no-handle)
[119.050500] Starting subtest: no-handle
[119.051579] Subtest no-handle: SUCCESS (0.000s)
[119.175650] [083/165] (885s left) kms_addfb_basic (size-max)
[119.296666] Starting subtest: size-max
[119.297784] Subtest size-max: SUCCESS (0.000s)
[119.409894] [084/165] (885s left) kms_addfb_basic (small-bo)
[119.528235] Starting subtest: small-bo
[119.529357] Subtest small-bo: SUCCESS (0.000s)
[119.628316] [085/165] (885s left) kms_addfb_basic (tile-pitch-mismatch)
[119.720122] Starting subtest: tile-pitch-mismatch
[119.721153] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[119.867360] [086/165] (885s left) kms_addfb_basic (too-high)
[119.986157] Starting subtest: too-high
[119.987392] Subtest too-high: SUCCESS (0.001s)
[120.106067] [087/165] (885s left) kms_addfb_basic (too-wide)
[120.224452] Starting subtest: too-wide
[120.225549] Subtest too-wide: SUCCESS (0.000s)
[120.322169] [088/165] (884s left) kms_addfb_basic (unused-handle)
[120.411234] Starting subtest: unused-handle
[120.412361] Subtest unused-handle: SUCCESS (0.000s)
[120.545039] [089/165] (884s left) kms_addfb_basic (unused-modifier)
[120.632276] Starting subtest: unused-modifier
[120.633366] Subtest unused-modifier: SUCCESS (0.000s)
[120.760162] [090/165] (884s left) kms_addfb_basic (unused-offsets)
[120.851840] Starting subtest: unused-offsets
[120.852925] Subtest unused-offsets: SUCCESS (0.000s)
[120.995087] [091/165] (884s left) kms_addfb_basic (unused-pitches)
[121.085734] Starting subtest: unused-pitches
[121.086867] Subtest unused-pitches: SUCCESS (0.000s)
[121.230209] [092/165] (884s left) kms_busy (basic)
[121.352990] Starting subtest: basic
[121.355271] Starting dynamic subtest: flip
[122.364079] Dynamic subtest flip: SUCCESS (1.005s)
[122.379190] Starting dynamic subtest: modeset
[126.271153] Dynamic subtest modeset: SUCCESS (3.910s)
[126.272267] Subtest basic: SUCCESS (4.918s)
[127.225622] [093/165] (878s left) kms_prop_blob (basic)
[127.318419] Starting subtest: basic
[127.319629] Subtest basic: SUCCESS (0.000s)
[127.407626] [094/165] (877s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[127.527582] Starting subtest: basic-busy-flip-before-cursor-atomic
[127.529687] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[127.601752] [095/165] (877s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[127.721885] Starting subtest: basic-busy-flip-before-cursor-legacy
[127.724068] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[127.805943] [096/165] (877s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[127.924339] Starting subtest: basic-flip-after-cursor-atomic
[129.186235] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.262s)
[130.102565] [097/165] (875s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[130.221687] Starting subtest: basic-flip-after-cursor-legacy
[131.531062] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.310s)
[132.475159] [098/165] (873s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[132.594326] Starting subtest: basic-flip-after-cursor-varying-size
[133.903694] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.310s)
[134.840984] [099/165] (870s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[134.959752] Starting subtest: basic-flip-before-cursor-atomic
[136.237527] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.278s)
[137.209187] [100/165] (868s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[137.325139] Starting subtest: basic-flip-before-cursor-legacy
[138.635094] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.310s)
[139.575255] [101/165] (866s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[139.694037] Starting subtest: basic-flip-before-cursor-varying-size
[141.007083] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.312s)
[141.943895] [102/165] (863s left) kms_dsc (dsc-basic)
[142.065150] Starting subtest: dsc-basic
[142.067341] Subtest dsc-basic: SKIP (0.001s)
[142.137781] [103/165] (863s left) kms_flip (basic-flip-vs-dpms)
[142.236880] Starting subtest: basic-flip-vs-dpms
[142.241454] Starting dynamic subtest: A-eDP1
[145.155845] Dynamic subtest A-eDP1: SUCCESS (2.915s)
[145.188804] Starting dynamic subtest: B-eDP1
[147.582265] Dynamic subtest B-eDP1: SUCCESS (2.424s)
[147.601415] Starting dynamic subtest: C-eDP1
[150.001083] Dynamic subtest C-eDP1: SUCCESS (2.417s)
[150.006332] Subtest basic-flip-vs-dpms: SUCCESS (7.767s)
[150.915293] [104/165] (854s left) kms_flip (basic-flip-vs-modeset)
[151.014728] Starting subtest: basic-flip-vs-modeset
[151.018125] Starting dynamic subtest: A-eDP1
[153.897455] Dynamic subtest A-eDP1: SUCCESS (2.879s)
[153.921611] Starting dynamic subtest: B-eDP1
[156.268231] Dynamic subtest B-eDP1: SUCCESS (2.369s)
[156.284784] Starting dynamic subtest: C-eDP1
[158.641026] Dynamic subtest C-eDP1: SUCCESS (2.369s)
[158.642203] Subtest basic-flip-vs-modeset: SUCCESS (7.626s)
[159.612656] [105/165] (846s left) kms_flip (basic-flip-vs-wf_vblank)
[159.713138] Starting subtest: basic-flip-vs-wf_vblank
[159.716567] Starting dynamic subtest: A-eDP1
[162.071928] Dynamic subtest A-eDP1: SUCCESS (2.353s)
[162.091683] Starting dynamic subtest: B-eDP1
[164.360103] Dynamic subtest B-eDP1: SUCCESS (2.284s)
[164.378923] Starting dynamic subtest: C-eDP1
[166.596905] Dynamic subtest C-eDP1: SUCCESS (2.238s)
[166.601804] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.889s)
[167.930025] [106/165] (837s left) kms_flip (basic-plain-flip)
[168.030517] Starting subtest: basic-plain-flip
[168.033291] Starting dynamic subtest: A-eDP1
[170.106263] Dynamic subtest A-eDP1: SUCCESS (2.072s)
[170.127620] Starting dynamic subtest: B-eDP1
[172.071982] Dynamic subtest B-eDP1: SUCCESS (1.964s)
[172.090598] Starting dynamic subtest: C-eDP1
[174.056442] Dynamic subtest C-eDP1: SUCCESS (1.982s)
[174.063756] Subtest basic-plain-flip: SUCCESS (6.033s)
[175.352459] [107/165] (830s left) kms_force_connector_basic (force-connector-state)
[175.450166] Starting subtest: force-connector-state
[176.044185] Subtest force-connector-state: SUCCESS (0.595s)
[177.088380] [108/165] (828s left) kms_force_connector_basic (force-edid)
[177.183444] Starting subtest: force-edid
[177.445035] Subtest force-edid: SUCCESS (0.262s)
[177.590062] [109/165] (828s left) kms_force_connector_basic (force-load-detect)
[177.684217] Starting subtest: force-load-detect
[177.686633] Subtest force-load-detect: SKIP (0.000s)
[177.801390] [110/165] (828s left) kms_force_connector_basic (prune-stale-modes)
[177.896058] Starting subtest: prune-stale-modes
[178.215377] Subtest prune-stale-modes: SUCCESS (0.318s)
[178.357206] [111/165] (827s left) kms_frontbuffer_tracking (basic)
[178.486053] Starting subtest: basic
[181.853390] Subtest basic: SUCCESS (3.368s)
[181.995757] [112/165] (823s left) kms_hdmi_inject (inject-audio)
[182.408375] Starting subtest: inject-audio
[182.736130] Subtest inject-audio: SUCCESS (0.326s)
[183.667389] [113/165] (822s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[183.787718] Starting subtest: compare-crc-sanitycheck-xr24
[183.790414] Starting dynamic subtest: pipe-A-eDP-1
[184.480028] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.687s)
[184.499178] Starting dynamic subtest: pipe-B-eDP-1
[185.868588] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.386s)
[185.892996] Starting dynamic subtest: pipe-C-eDP-1
[187.260879] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.388s)
[187.262143] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.473s)
[188.176663] [114/165] (817s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[188.296252] Starting subtest: compare-crc-sanitycheck-nv12
[188.299154] Starting dynamic subtest: pipe-A-eDP-1
[189.289873] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.990s)
[189.309147] Starting dynamic subtest: pipe-B-eDP-1
[190.688509] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.392s)
[190.727231] Starting dynamic subtest: pipe-C-eDP-1
[192.078987] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.386s)
[192.080170] Subtest compare-crc-sanitycheck-nv12: SUCCESS (3.782s)
[193.015915] [115/165] (813s left) kms_pipe_crc_basic (hang-read-crc)
[193.137069] Starting subtest: hang-read-crc
[193.138294] Starting dynamic subtest: pipe-A-eDP-1
[195.562709] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.422s)
[195.587024] Starting dynamic subtest: pipe-B-eDP-1
[198.670419] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.102s)
[198.688919] Starting dynamic subtest: pipe-C-eDP-1
[201.662271] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.988s)
[201.663388] Subtest hang-read-crc: SUCCESS (8.526s)
[202.614451] [116/165] (803s left) kms_pipe_crc_basic (nonblocking-crc)
[202.733873] Starting subtest: nonblocking-crc
[202.736569] Starting dynamic subtest: pipe-A-eDP-1
[203.613461] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.873s)
[203.629712] Starting dynamic subtest: pipe-B-eDP-1
[205.182409] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.567s)
[205.212474] Starting dynamic subtest: pipe-C-eDP-1
[206.732161] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.542s)
[206.733936] Subtest nonblocking-crc: SUCCESS (3.997s)
[207.672616] [117/165] (798s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[207.789960] Starting subtest: nonblocking-crc-frame-sequence
[207.792873] Starting dynamic subtest: pipe-A-eDP-1
[208.672468] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.877s)
[208.710120] Starting dynamic subtest: pipe-B-eDP-1
[210.236135] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.555s)
[210.258504] Starting dynamic subtest: pipe-C-eDP-1
[211.793300] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.553s)
[211.795147] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.005s)
[212.729800] [118/165] (793s left) kms_pipe_crc_basic (read-crc)
[212.850148] Starting subtest: read-crc
[212.853059] Starting dynamic subtest: pipe-A-eDP-1
[213.677877] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.822s)
[213.706367] Starting dynamic subtest: pipe-B-eDP-1
[215.239710] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.556s)
[215.258762] Starting dynamic subtest: pipe-C-eDP-1
[216.742765] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.497s)
[216.744605] Subtest read-crc: SUCCESS (3.891s)
[217.664979] [119/165] (788s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[217.785645] Starting subtest: read-crc-frame-sequence
[217.788755] Starting dynamic subtest: pipe-A-eDP-1
[218.611185] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.822s)
[218.643948] Starting dynamic subtest: pipe-B-eDP-1
[220.105321] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.485s)
[220.142739] Starting dynamic subtest: pipe-C-eDP-1
[221.613153] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.501s)
[221.614713] Subtest read-crc-frame-sequence: SUCCESS (3.828s)
[222.582145] [120/165] (783s left) kms_pm_backlight (basic-brightness)
[222.703642] Starting subtest: basic-brightness
[222.810595] Starting dynamic subtest: eDP-1
[223.395736] Dynamic subtest eDP-1: SUCCESS (0.585s)
[223.399023] Subtest basic-brightness: SUCCESS (0.694s)
[224.311165] [121/165] (781s left) kms_psr (primary_page_flip)
[224.432483] Starting subtest: primary_page_flip
[228.265397] Subtest primary_page_flip: SUCCESS (3.832s)
[229.253954] [122/165] (776s left) kms_psr (cursor_plane_move)
[229.376366] Starting subtest: cursor_plane_move
[233.174427] Subtest cursor_plane_move: SUCCESS (3.797s)
[234.103041] [123/165] (772s left) kms_psr (sprite_plane_onoff)
[234.225517] Starting subtest: sprite_plane_onoff
[238.133387] Subtest sprite_plane_onoff: SUCCESS (3.905s)
[239.098260] [124/165] (767s left) kms_psr (primary_mmap_gtt)
[239.221439] Starting subtest: primary_mmap_gtt
[243.066375] Subtest primary_mmap_gtt: SUCCESS (3.844s)
[243.985441] [125/165] (762s left) kms_setmode (basic-clone-single-crtc)
[244.079677] Starting subtest: basic-clone-single-crtc
[244.083053] Subtest basic-clone-single-crtc: SKIP (0.000s)
[244.169460] [126/165] (762s left) i915_pm_rpm (basic-pci-d3-state)
[246.005198] Starting subtest: basic-pci-d3-state
[250.382362] Subtest basic-pci-d3-state: SUCCESS (4.377s)
[250.589374] [127/165] (755s left) i915_pm_rpm (basic-rte)
[250.678983] Starting subtest: basic-rte
[260.623970] Subtest basic-rte: SUCCESS (9.945s)
[262.124149] [128/165] (744s left) i915_pm_rps (basic-api)
[262.225073] Starting subtest: basic-api
[262.229048] Subtest basic-api: SUCCESS (0.004s)
[262.342488] [129/165] (744s left) prime_self_import (basic-llseek-bad)
[262.430905] Starting subtest: basic-llseek-bad
[262.435441] Subtest basic-llseek-bad: SUCCESS (0.003s)
[262.541159] [130/165] (743s left) prime_self_import (basic-llseek-size)
[262.629442] Starting subtest: basic-llseek-size
[262.633881] Subtest basic-llseek-size: SUCCESS (0.004s)
[262.741119] [131/165] (743s left) prime_self_import (basic-with_fd_dup)
[262.828900] Starting subtest: basic-with_fd_dup
[262.833499] Subtest basic-with_fd_dup: SUCCESS (0.004s)
[262.957415] [132/165] (743s left) prime_self_import (basic-with_one_bo)
[263.045620] Starting subtest: basic-with_one_bo
[263.050355] Subtest basic-with_one_bo: SUCCESS (0.005s)
[263.137737] [133/165] (743s left) prime_self_import (basic-with_one_bo_two_files)
[263.223061] Starting subtest: basic-with_one_bo_two_files
[263.227476] Subtest basic-with_one_bo_two_files: SUCCESS (0.004s)
[263.324254] [134/165] (743s left) prime_self_import (basic-with_two_bos)
[263.412698] Starting subtest: basic-with_two_bos
[263.417542] Subtest basic-with_two_bos: SUCCESS (0.005s)
[263.525357] [135/165] (743s left) prime_vgem (basic-fence-flip)
[263.641193] Starting subtest: basic-fence-flip
[264.181872] Subtest basic-fence-flip: SUCCESS (0.541s)
[264.310571] [136/165] (742s left) prime_vgem (basic-fence-mmap)
[264.434662] Starting subtest: basic-fence-mmap
[264.485546] Subtest basic-fence-mmap: SUCCESS (0.051s)
[264.576100] [137/165] (742s left) prime_vgem (basic-fence-read)
[264.698759] Starting subtest: basic-fence-read
[264.776556] Subtest basic-fence-read: SUCCESS (0.077s)
[264.878597] [138/165] (741s left) prime_vgem (basic-gtt)
[265.001603] Starting subtest: basic-gtt
[265.027286] Subtest basic-gtt: SUCCESS (0.025s)
[265.144508] [139/165] (741s left) prime_vgem (basic-read)
[265.267333] Starting subtest: basic-read
[265.305590] Subtest basic-read: SUCCESS (0.038s)
[265.427597] [140/165] (741s left) prime_vgem (basic-write)
[265.552883] Starting subtest: basic-write
[265.597738] Subtest basic-write: SUCCESS (0.045s)
[265.694556] [141/165] (741s left) vgem_basic (setversion)
[265.802286] Starting subtest: setversion
[265.804520] Subtest setversion: SUCCESS (0.000s)
[265.878595] [142/165] (740s left) vgem_basic (create)
[265.985415] Starting subtest: create
[265.986779] Subtest create: SUCCESS (0.000s)
[266.059930] [143/165] (740s left) vgem_basic (debugfs)
[266.169349] Starting subtest: debugfs
[266.170509] Subtest debugfs: SUCCESS (0.001s)
[266.244873] [144/165] (740s left) vgem_basic (dmabuf-export)
[266.352606] Starting subtest: dmabuf-export
[266.366493] Subtest dmabuf-export: SUCCESS (0.012s)
[266.461849] [145/165] (740s left) vgem_basic (dmabuf-fence)
[266.552300] Starting subtest: dmabuf-fence
[266.553665] Subtest dmabuf-fence: SUCCESS (0.001s)
[266.631232] [146/165] (740s left) vgem_basic (dmabuf-fence-before)
[266.735720] Starting subtest: dmabuf-fence-before
[266.736941] Subtest dmabuf-fence-before: SUCCESS (0.000s)
[266.811515] [147/165] (740s left) vgem_basic (dmabuf-mmap)
[266.919378] Starting subtest: dmabuf-mmap
[266.945079] Subtest dmabuf-mmap: SUCCESS (0.026s)
[267.027217] [148/165] (740s left) vgem_basic (mmap)
[267.136226] Starting subtest: mmap
[267.155483] Subtest mmap: SUCCESS (0.017s)
[267.225567] [149/165] (739s left) vgem_basic (second-client)
[267.336822] Starting subtest: second-client
[267.372078] Subtest second-client: SUCCESS (0.033s)
[267.446049] [150/165] (739s left) vgem_basic (sysfs)
[267.553234] Starting subtest: sysfs
[267.554467] Subtest sysfs: SUCCESS (0.001s)
[267.627648] [151/165] (739s left) core_hotunplug (unbind-rebind)
[267.770129] Starting subtest: unbind-rebind
[272.255402] Subtest unbind-rebind: SUCCESS (4.485s)
[272.291746] [152/165] (734s left) vgem_basic (unload)
[272.386193] Starting subtest: unload
[301.433507] Subtest unload: SUCCESS (29.012s)
[301.528408] [153/165] (705s left) i915_module_load (reload)
[301.616742] Starting subtest: reload
[304.972620] Subtest reload: SUCCESS (3.355s)
[305.001684] [154/165] (702s left) gem_lmem_swapping (basic)
[305.113140] Subtest basic: SKIP (0.000s)
[305.167394] [155/165] (702s left) gem_lmem_swapping (parallel-random-engines)
[305.265266] Subtest parallel-random-engines: SKIP (0.000s)
[305.306955] [156/165] (701s left) gem_lmem_swapping (random-engines)
[305.404210] Subtest random-engines: SKIP (0.000s)
[305.457276] [157/165] (701s left) gem_lmem_swapping (verify-random)
[305.553533] Subtest verify-random: SKIP (0.000s)
[305.607623] [158/165] (701s left) i915_pm_rpm (module-reload)
[318.066311] Starting subtest: module-reload
[352.536762] Subtest module-reload: SUCCESS (34.461s)
[352.563176] [159/165] (654s left) i915_selftest (live)
[352.667946] Starting subtest: live
[352.669290] Starting dynamic subtest: sanitycheck
[354.525389] Dynamic subtest sanitycheck: SUCCESS (1.848s)
[354.540526] Starting dynamic subtest: uncore
[356.384390] Dynamic subtest uncore: SUCCESS (1.855s)
[356.399119] Starting dynamic subtest: workarounds
[358.560624] Dynamic subtest workarounds: SUCCESS (2.172s)
[358.575512] Starting dynamic subtest: gt_engines
[360.856531] Dynamic subtest gt_engines: SUCCESS (2.292s)
[360.871438] Starting dynamic subtest: gt_timelines
[373.780678] Dynamic subtest gt_timelines: SUCCESS (12.920s)
[373.795317] Starting dynamic subtest: gt_contexts
[375.771910] Dynamic subtest gt_contexts: SUCCESS (1.987s)
[375.786898] Starting dynamic subtest: gt_lrc
[380.187395] Dynamic subtest gt_lrc: SUCCESS (4.412s)
[380.202523] Starting dynamic subtest: gt_mocs
[382.171294] Dynamic subtest gt_mocs: SUCCESS (1.981s)
[382.186120] Starting dynamic subtest: gt_pm
[387.247998] Dynamic subtest gt_pm: SUCCESS (5.073s)
[387.263401] Starting dynamic subtest: gt_heartbeat
[389.201852] Dynamic subtest gt_heartbeat: SUCCESS (1.951s)
[389.217218] Starting dynamic subtest: gt_tlb
[392.331464] Dynamic subtest gt_tlb: SUCCESS (3.127s)
[392.346788] Starting dynamic subtest: requests
[399.990756] Dynamic subtest requests: SUCCESS (7.656s)
[400.006156] Starting dynamic subtest: migrate
[404.615462] Dynamic subtest migrate: SUCCESS (4.621s)
[404.630559] Starting dynamic subtest: active
[406.486144] Dynamic subtest active: SUCCESS (1.867s)
[406.501560] Starting dynamic subtest: objects
[409.854262] Dynamic subtest objects: SUCCESS (3.365s)
[409.869507] Starting dynamic subtest: mman
[416.799577] Dynamic subtest mman: SUCCESS (6.942s)
[416.814752] Starting dynamic subtest: dmabuf
[418.737748] Dynamic subtest dmabuf: SUCCESS (1.934s)
[418.753020] Starting dynamic subtest: vma
[420.658434] Dynamic subtest vma: SUCCESS (1.917s)
[420.673572] Starting dynamic subtest: coherency
[425.170275] Dynamic subtest coherency: SUCCESS (4.508s)
[425.185750] Starting dynamic subtest: gtt
[434.702541] Dynamic subtest gtt: SUCCESS (9.529s)
[434.717566] Starting dynamic subtest: gem
[436.710437] Dynamic subtest gem: SUCCESS (2.004s)
[436.725611] Starting dynamic subtest: evict
[440.595423] Dynamic subtest evict: SUCCESS (3.881s)
[440.610579] Starting dynamic subtest: hugepages
[448.385610] Dynamic subtest hugepages: SUCCESS (7.786s)
[448.401097] Starting dynamic subtest: gem_contexts
[463.771183] Dynamic subtest gem_contexts: SUCCESS (15.382s)
[463.787290] Starting dynamic subtest: client
[465.638375] Dynamic subtest client: SUCCESS (1.864s)
[465.654312] Starting dynamic subtest: gem_migrate
[467.498735] Dynamic subtest gem_migrate: SUCCESS (1.857s)
[467.514599] Starting dynamic subtest: reset
[472.932633] Dynamic subtest reset: SUCCESS (5.430s)
[472.948377] Starting dynamic subtest: memory_region
[474.830516] Dynamic subtest memory_region: SUCCESS (1.895s)
[474.846108] Starting dynamic subtest: hangcheck
[499.296563] Dynamic subtest hangcheck: SUCCESS (24.462s)
[499.312383] Starting dynamic subtest: execlists
[509.457646] Dynamic subtest execlists: SUCCESS (10.157s)
[509.473132] Starting dynamic subtest: ring_submission
[511.340418] Dynamic subtest ring_submission: SUCCESS (1.879s)
[511.356031] Starting dynamic subtest: perf
[513.308729] Dynamic subtest perf: SUCCESS (1.964s)
[513.325019] Starting dynamic subtest: slpc
[515.147487] Dynamic subtest slpc: SUCCESS (1.835s)
[515.163555] Starting dynamic subtest: guc
[516.999402] Dynamic subtest guc: SUCCESS (1.849s)
[517.015669] Starting dynamic subtest: guc_multi_lrc
[518.880830] Dynamic subtest guc_multi_lrc: SUCCESS (1.878s)
[518.896787] Starting dynamic subtest: guc_hang
[520.731529] Dynamic subtest guc_hang: SUCCESS (1.847s)
[520.747151] Starting dynamic subtest: late_gt_pm
[522.638267] Dynamic subtest late_gt_pm: SUCCESS (1.903s)
[522.639449] Subtest live: SUCCESS (169.963s)
[522.664685] [160/165] (484s left) dmabuf (all-tests)
[522.762614] Starting subtest: all-tests
[522.763834] Starting dynamic subtest: sanitycheck
[522.834910] Dynamic subtest sanitycheck: SUCCESS (0.070s)
[522.854522] Starting dynamic subtest: dma_fence
[523.022694] Dynamic subtest dma_fence: SUCCESS (0.184s)
[523.042281] Starting dynamic subtest: dma_fence_chain
[541.298937] Dynamic subtest dma_fence_chain: SUCCESS (18.264s)
[541.312304] Starting dynamic subtest: dma_fence_unwrap
[541.367614] Dynamic subtest dma_fence_unwrap: SUCCESS (0.073s)
[541.388443] Starting dynamic subtest: dma_resv
[541.443653] Dynamic subtest dma_resv: SUCCESS (0.072s)
[541.446065] Subtest all-tests: SUCCESS (18.682s)
[541.478368] [161/165] (465s left) i915_suspend (basic-s2idle-without-i915)
[541.566201] Starting subtest: basic-s2idle-without-i915
[564.666310] Subtest basic-s2idle-without-i915: SUCCESS (8.275s)
[565.065978] [162/165] (457s left) i915_suspend (basic-s3-without-i915)
[565.155653] Starting subtest: basic-s3-without-i915
[587.792704] Subtest basic-s3-without-i915: SUCCESS (6.866s)
[588.156042] [163/165] (449s left) gem_exec_suspend (basic-s0)
[588.274654] Starting subtest: basic-S0
[588.275811] Starting dynamic subtest: smem
[607.886276] Dynamic subtest smem: SUCCESS (3.705s)
[607.887540] Subtest basic-S0: SUCCESS (3.706s)
[608.095460] [164/165] (446s left) gem_exec_suspend (basic-s3)
[608.204013] Starting subtest: basic-S3
[608.205180] Starting dynamic subtest: smem
[626.898070] Dynamic subtest smem: SUCCESS (3.625s)
[626.899399] Subtest basic-S3: SUCCESS (3.627s)
[627.116779] [165/165] (442s left) kms_pipe_crc_basic (suspend-read-crc)
[627.239616] Starting subtest: suspend-read-crc
[627.242626] Starting dynamic subtest: pipe-A-eDP-1
[644.868794] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.532s)
[644.910268] Starting dynamic subtest: pipe-B-eDP-1
[663.833916] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.162s)
[663.862472] Starting dynamic subtest: pipe-C-eDP-1
[681.845867] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.128s)
[681.848112] Subtest suspend-read-crc: SUCCESS (8.843s)
[682.713407] Closing watchdogs
Done.