[98.646202] Initializing watchdogs
[98.647152]   /dev/watchdog0
[98.839421] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:46d3 Intel Alderlake_n (Gen12) 
[98.886113] [001/161] (960s left) i915_module_load (load)
[100.365287] Starting subtest: load
[110.324948] Subtest load: SUCCESS (9.952s)
[110.620316] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[110.640853] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[110.663954] [002/161] (949s left) core_auth (basic-auth)
[111.212215] Starting subtest: basic-auth
[111.227393] Subtest basic-auth: SUCCESS (0.008s)
[111.660745] [003/161] (948s left) debugfs_test (read_all_entries)
[112.095547] Starting subtest: read_all_entries
[113.629865] Subtest read_all_entries: SUCCESS (1.535s)
[114.100751] [004/161] (946s left) debugfs_test (basic-hwmon)
[114.515258] Starting subtest: basic-hwmon
[114.521124] Subtest basic-hwmon: SKIP (0.000s)
[114.971918] [005/161] (945s left) debugfs_test (sysfs)
[115.396272] Starting subtest: sysfs
[115.855817] Subtest sysfs: SUCCESS (0.461s)
[116.313435] [006/161] (944s left) fbdev (eof)
[116.733924] Starting subtest: eof
[116.737457] Subtest eof: SUCCESS (0.003s)
[117.077875] [007/161] (944s left) fbdev (info)
[117.441203] Starting subtest: info
[117.443953] Subtest info: SUCCESS (0.000s)
[117.794865] [008/161] (943s left) fbdev (nullptr)
[118.167219] Starting subtest: nullptr
[118.170508] Subtest nullptr: SUCCESS (0.001s)
[118.523818] [009/161] (943s left) fbdev (read)
[118.917506] Starting subtest: read
[122.744692] Subtest read: SUCCESS (3.796s)
[123.076184] [010/161] (939s left) fbdev (write)
[123.467614] Starting subtest: write
[125.458362] Subtest write: SUCCESS (1.984s)
[125.816236] [011/161] (936s left) gem_basic (bad-close)
[126.187501] Starting subtest: bad-close
[126.194633] Subtest bad-close: SUCCESS (0.000s)
[126.555950] [012/161] (936s left) gem_basic (create-close)
[126.939387] Starting subtest: create-close
[126.946386] Subtest create-close: SUCCESS (0.001s)
[127.319650] [013/161] (935s left) gem_basic (create-fd-close)
[127.696650] Starting subtest: create-fd-close
[127.702869] Subtest create-fd-close: SUCCESS (0.001s)
[128.097265] [014/161] (935s left) gem_busy (busy@all-engines)
[128.569200] Starting subtest: busy
[128.572205] Starting dynamic subtest: all-engines
[128.879782] Dynamic subtest all-engines: SUCCESS (0.314s)
[128.884452] Subtest busy: SUCCESS (0.316s)
[129.721897] [015/161] (934s left) gem_close_race (basic-process)
[130.199803] Starting subtest: basic-process
[130.352935] Subtest basic-process: SUCCESS (0.154s)
[130.856692] [016/161] (933s left) gem_close_race (basic-threads)
[131.344353] Starting subtest: basic-threads
[133.129842] Subtest basic-threads: SUCCESS (1.776s)
[133.633289] [017/161] (930s left) gem_ctx_create (basic)
[134.132358] Starting subtest: basic
[134.135939] Subtest basic: SUCCESS (0.000s)
[134.649482] [018/161] (930s left) gem_ctx_create (basic-files)
[135.112177] Starting subtest: basic-files
[137.215947] Subtest basic-files: SUCCESS (2.106s)
[137.765622] [019/161] (927s left) gem_ctx_exec (basic)
[138.157359] Starting subtest: basic
[138.176276] Subtest basic: SUCCESS (0.022s)
[138.574948] [020/161] (926s left) gem_exec_basic (basic)
[139.002462] Starting subtest: basic
[139.005729] Starting dynamic subtest: rcs0-smem
[139.013323] Dynamic subtest rcs0-smem: SUCCESS (0.009s)
[139.071208] Starting dynamic subtest: bcs0-smem
[139.074108] Dynamic subtest bcs0-smem: SUCCESS (0.004s)
[139.133427] Starting dynamic subtest: vcs0-smem
[139.136549] Dynamic subtest vcs0-smem: SUCCESS (0.004s)
[139.194221] Starting dynamic subtest: vecs0-smem
[139.197253] Dynamic subtest vecs0-smem: SUCCESS (0.005s)
[139.200295] Subtest basic: SUCCESS (0.033s)
[139.557874] [021/161] (926s left) gem_exec_create (basic)
[140.019869] Starting subtest: basic
[140.023091] Starting dynamic subtest: smem
[142.249336] Dynamic subtest smem: SUCCESS (2.229s)
[142.252749] Subtest basic: SUCCESS (2.231s)
[142.754740] [022/161] (923s left) gem_exec_fence (basic-busy)
[143.407154] Starting subtest: basic-busy
[143.410345] Starting dynamic subtest: rcs0
[143.492399] Dynamic subtest rcs0: SUCCESS (0.084s)
[143.551976] Starting dynamic subtest: bcs0
[143.558475] Dynamic subtest bcs0: SUCCESS (0.060s)
[143.617223] Starting dynamic subtest: vcs0
[143.623367] Dynamic subtest vcs0: SUCCESS (0.058s)
[143.681438] Starting dynamic subtest: vecs0
[143.687986] Dynamic subtest vecs0: SUCCESS (0.066s)
[143.690694] Subtest basic-busy: SUCCESS (0.275s)
[145.121704] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[145.142168] [023/161] (921s left) gem_exec_fence (basic-wait)
[145.803181] Starting subtest: basic-wait
[145.806480] Starting dynamic subtest: rcs0
[145.922270] Dynamic subtest rcs0: SUCCESS (0.118s)
[145.986132] Starting dynamic subtest: bcs0
[145.993482] Dynamic subtest bcs0: SUCCESS (0.069s)
[146.055503] Starting dynamic subtest: vcs0
[146.059135] Dynamic subtest vcs0: SUCCESS (0.062s)
[146.121077] Starting dynamic subtest: vecs0
[146.124185] Dynamic subtest vecs0: SUCCESS (0.062s)
[146.126954] Subtest basic-wait: SUCCESS (0.318s)
[147.384912] [024/161] (919s left) gem_exec_fence (basic-await)
[148.054678] Starting subtest: basic-await
[148.057719] Starting dynamic subtest: rcs0
[148.395394] Dynamic subtest rcs0: SUCCESS (0.343s)
[148.459583] Starting dynamic subtest: bcs0
[148.703103] Dynamic subtest bcs0: SUCCESS (0.304s)
[148.762932] Starting dynamic subtest: vcs0
[149.008603] Dynamic subtest vcs0: SUCCESS (0.301s)
[149.071575] Starting dynamic subtest: vecs0
[149.310106] Dynamic subtest vecs0: SUCCESS (0.302s)
[149.313933] Subtest basic-await: SUCCESS (1.260s)
[150.507525] [025/161] (916s left) gem_exec_fence (nb-await)
[151.182076] Starting subtest: nb-await
[151.185105] Starting dynamic subtest: rcs0
[151.304436] Dynamic subtest rcs0: SUCCESS (0.116s)
[151.364609] Starting dynamic subtest: bcs0
[151.386762] Dynamic subtest bcs0: SUCCESS (0.088s)
[151.448087] Starting dynamic subtest: vcs0
[151.476877] Dynamic subtest vcs0: SUCCESS (0.089s)
[151.541897] Starting dynamic subtest: vecs0
[151.567434] Dynamic subtest vecs0: SUCCESS (0.088s)
[151.570787] Subtest nb-await: SUCCESS (0.389s)
[152.855155] [026/161] (914s left) gem_exec_gttfill (basic)
[153.326774] Starting subtest: basic
[191.755612] Subtest basic: SUCCESS (38.429s)
[192.331756] [027/161] (875s left) gem_exec_parallel (engines)
[192.998536] Starting subtest: engines
[193.001408] Starting dynamic subtest: basic
[199.838757] Dynamic subtest basic: SUCCESS (6.851s)
[199.909291] Starting dynamic subtest: contexts
[210.928337] Dynamic subtest contexts: SUCCESS (11.081s)
[210.996328] Starting dynamic subtest: fds
[223.720176] Dynamic subtest fds: SUCCESS (12.789s)
[223.787749] Starting dynamic subtest: userptr
[229.804327] Dynamic subtest userptr: SUCCESS (6.089s)
[229.807213] Subtest engines: SUCCESS (36.819s)
[230.387393] [028/161] (837s left) gem_exec_store (basic)
[230.856187] Starting subtest: basic
[230.999759] Subtest basic: SUCCESS (0.146s)
[231.543777] [029/161] (837s left) gem_flink_basic (bad-flink)
[231.913569] Starting subtest: bad-flink
[231.916511] Subtest bad-flink: SUCCESS (0.000s)
[232.321682] [030/161] (836s left) gem_flink_basic (bad-open)
[232.696282] Starting subtest: bad-open
[232.699670] Subtest bad-open: SUCCESS (0.000s)
[233.092492] [031/161] (836s left) gem_flink_basic (basic)
[233.462459] Starting subtest: basic
[233.465430] Subtest basic: SUCCESS (0.001s)
[233.884378] [032/161] (835s left) gem_flink_basic (double-flink)
[234.258513] Starting subtest: double-flink
[234.261908] Subtest double-flink: SUCCESS (0.001s)
[234.672441] [033/161] (835s left) gem_flink_basic (flink-lifetime)
[235.045488] Starting subtest: flink-lifetime
[235.052843] Subtest flink-lifetime: SUCCESS (0.009s)
[235.463457] [034/161] (834s left) gem_huc_copy (huc-copy)
[235.882684] Starting subtest: huc-copy
[235.901331] Subtest huc-copy: SUCCESS (0.021s)
[236.356062] [035/161] (834s left) gem_linear_blits (basic)
[237.243536] Starting subtest: basic
[237.303098] Subtest basic: SUCCESS (0.058s)
[237.797811] [036/161] (833s left) gem_mmap (basic)
[238.365635] Starting subtest: basic
[238.379498] Subtest basic: SUCCESS (0.003s)
[238.788683] [037/161] (832s left) gem_mmap_gtt (basic)
[239.167690] Starting subtest: basic
[239.170791] Subtest basic: SUCCESS (0.005s)
[239.586517] [038/161] (832s left) gem_render_linear_blits (basic)
[239.995056] Starting subtest: basic
[240.219206] Subtest basic: SUCCESS (0.226s)
[240.633864] [039/161] (831s left) gem_render_tiled_blits (basic)
[241.035848] Starting subtest: basic
[241.333788] Subtest basic: SUCCESS (0.297s)
[241.737496] [040/161] (830s left) gem_ringfill (basic-all)
[242.637716] Starting subtest: basic-all
[244.107835] Subtest basic-all: SUCCESS (1.463s)
[244.519294] [041/161] (828s left) gem_softpin (allocator-basic)
[244.932880] Starting subtest: allocator-basic
[245.611304] Subtest allocator-basic: SUCCESS (0.673s)
[246.117841] [042/161] (827s left) gem_softpin (allocator-basic-reserve)
[246.521551] Starting subtest: allocator-basic-reserve
[247.202367] Subtest allocator-basic-reserve: SUCCESS (0.671s)
[247.696411] [043/161] (825s left) gem_softpin (safe-alignment)
[248.102216] Starting subtest: safe-alignment
[248.194101] Subtest safe-alignment: SUCCESS (0.090s)
[248.641234] [044/161] (825s left) gem_sync (basic-all)
[249.105178] Starting subtest: basic-all
[256.466788] Subtest basic-all: SUCCESS (7.365s)
[257.006265] [045/161] (817s left) gem_sync (basic-each)
[257.473525] Starting subtest: basic-each
[261.302072] Subtest basic-each: SUCCESS (3.832s)
[261.849264] [046/161] (812s left) gem_tiled_blits (basic)
[262.588824] Starting subtest: basic
[262.735213] Subtest basic: SUCCESS (0.138s)
[263.216354] [047/161] (811s left) gem_tiled_fence_blits (basic)
[264.233278] Starting subtest: basic
[264.299143] Subtest basic: SUCCESS (0.079s)
[264.816389] [048/161] (810s left) gem_tiled_pread_basic
[265.766416] [049/161] (810s left) gem_wait (busy@all-engines)
[266.291141] Starting subtest: busy
[266.294256] Starting dynamic subtest: all-engines
[266.891400] Dynamic subtest all-engines: SUCCESS (0.606s)
[266.894388] Subtest busy: SUCCESS (0.608s)
[267.549318] [050/161] (808s left) gem_wait (wait@all-engines)
[268.067250] Starting subtest: wait
[268.070576] Starting dynamic subtest: all-engines
[269.194133] Dynamic subtest all-engines: SUCCESS (1.125s)
[269.197362] Subtest wait: SUCCESS (1.127s)
[269.869492] [051/161] (806s left) i915_getparams_basic (basic-eu-total)
[270.233447] Starting subtest: basic-eu-total
[270.239390] Subtest basic-eu-total: SUCCESS (0.000s)
[270.651882] [052/161] (806s left) i915_getparams_basic (basic-subslice-total)
[271.025071] Starting subtest: basic-subslice-total
[271.030791] Subtest basic-subslice-total: SUCCESS (0.000s)
[271.431320] [053/161] (805s left) i915_hangman (error-state-basic)
[271.884330] Starting subtest: error-state-basic
[272.734221] Subtest error-state-basic: SUCCESS (0.846s)
[273.173831] [054/161] (804s left) i915_pciid
[273.951894] [055/161] (804s left) kms_addfb_basic (addfb25-4-tiled)
[274.476541] Starting subtest: addfb25-4-tiled
[274.479782] Subtest addfb25-4-tiled: SUCCESS (0.002s)
[274.903217] [056/161] (803s left) kms_addfb_basic (addfb25-bad-modifier)
[275.382704] Starting subtest: addfb25-bad-modifier
[275.385477] Subtest addfb25-bad-modifier: SUCCESS (0.002s)
[275.820701] [057/161] (802s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[276.297744] Starting subtest: addfb25-framebuffer-vs-set-tiling
[276.300829] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.002s)
[276.721686] [058/161] (802s left) kms_addfb_basic (addfb25-modifier-no-flag)
[277.201485] Starting subtest: addfb25-modifier-no-flag
[277.204666] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[277.632930] [059/161] (801s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[278.115334] Starting subtest: addfb25-x-tiled-legacy
[278.118712] Subtest addfb25-x-tiled-legacy: SUCCESS (0.001s)
[278.539688] [060/161] (801s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[279.019929] Starting subtest: addfb25-x-tiled-mismatch-legacy
[279.023182] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.002s)
[279.457452] [061/161] (800s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[279.937950] Starting subtest: addfb25-yf-tiled-legacy
[279.941738] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.002s)
[280.371345] [062/161] (800s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[280.855172] Starting subtest: addfb25-y-tiled-legacy
[280.858180] Subtest addfb25-y-tiled-legacy: SUCCESS (0.001s)
[281.274780] [063/161] (799s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[281.774902] Starting subtest: addfb25-y-tiled-small-legacy
[281.778507] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.002s)
[282.198302] [064/161] (799s left) kms_addfb_basic (bad-pitch-0)
[282.576238] Starting subtest: bad-pitch-0
[282.579187] Subtest bad-pitch-0: SUCCESS (0.000s)
[283.098391] [065/161] (798s left) kms_addfb_basic (bad-pitch-1024)
[283.483360] Starting subtest: bad-pitch-1024
[283.486390] Subtest bad-pitch-1024: SUCCESS (0.000s)
[284.023844] [066/161] (797s left) kms_addfb_basic (bad-pitch-128)
[284.403945] Starting subtest: bad-pitch-128
[284.407387] Subtest bad-pitch-128: SUCCESS (0.000s)
[284.924984] [067/161] (797s left) kms_addfb_basic (bad-pitch-256)
[285.310396] Starting subtest: bad-pitch-256
[285.313283] Subtest bad-pitch-256: SUCCESS (0.000s)
[285.830345] [068/161] (796s left) kms_addfb_basic (bad-pitch-32)
[286.209999] Starting subtest: bad-pitch-32
[286.212735] Subtest bad-pitch-32: SUCCESS (0.000s)
[286.726442] [069/161] (796s left) kms_addfb_basic (bad-pitch-63)
[287.104960] Starting subtest: bad-pitch-63
[287.108091] Subtest bad-pitch-63: SUCCESS (0.000s)
[287.630773] [070/161] (795s left) kms_addfb_basic (bad-pitch-65536)
[287.999418] Starting subtest: bad-pitch-65536
[288.003642] Subtest bad-pitch-65536: SUCCESS (0.002s)
[288.524604] [071/161] (795s left) kms_addfb_basic (bad-pitch-999)
[288.902890] Starting subtest: bad-pitch-999
[288.906312] Subtest bad-pitch-999: SUCCESS (0.000s)
[289.416479] [072/161] (794s left) kms_addfb_basic (basic)
[289.791333] Starting subtest: basic
[289.794317] Subtest basic: SUCCESS (0.000s)
[290.323068] [073/161] (794s left) kms_addfb_basic (basic-x-tiled-legacy)
[290.700718] Starting subtest: basic-x-tiled-legacy
[290.703740] Subtest basic-x-tiled-legacy: SUCCESS (0.001s)
[291.227866] [074/161] (793s left) kms_addfb_basic (basic-y-tiled-legacy)
[291.603929] Starting subtest: basic-y-tiled-legacy
[291.606865] Subtest basic-y-tiled-legacy: SUCCESS (0.002s)
[292.118339] [075/161] (793s left) kms_addfb_basic (bo-too-small)
[292.596470] Starting subtest: bo-too-small
[292.599456] Subtest bo-too-small: SUCCESS (0.002s)
[293.017689] [076/161] (792s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[293.497353] Starting subtest: bo-too-small-due-to-tiling
[293.500667] Subtest bo-too-small-due-to-tiling: SUCCESS (0.002s)
[293.914477] [077/161] (792s left) kms_addfb_basic (clobberred-modifier)
[294.290088] Starting subtest: clobberred-modifier
[294.293286] Subtest clobberred-modifier: SUCCESS (0.001s)
[294.815381] [078/161] (791s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[295.193108] Starting subtest: framebuffer-vs-set-tiling
[295.196141] Subtest framebuffer-vs-set-tiling: SUCCESS (0.001s)
[295.709159] [079/161] (790s left) kms_addfb_basic (invalid-get-prop)
[296.085095] Starting subtest: invalid-get-prop
[296.087747] Subtest invalid-get-prop: SUCCESS (0.000s)
[296.607905] [080/161] (790s left) kms_addfb_basic (invalid-get-prop-any)
[296.997685] Starting subtest: invalid-get-prop-any
[297.000789] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[297.512295] [081/161] (789s left) kms_addfb_basic (invalid-set-prop)
[297.883921] Starting subtest: invalid-set-prop
[297.886994] Subtest invalid-set-prop: SUCCESS (0.000s)
[298.416490] [082/161] (789s left) kms_addfb_basic (invalid-set-prop-any)
[298.793881] Starting subtest: invalid-set-prop-any
[298.797439] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[299.319839] [083/161] (788s left) kms_addfb_basic (no-handle)
[299.696741] Starting subtest: no-handle
[299.699612] Subtest no-handle: SUCCESS (0.000s)
[300.207102] [084/161] (788s left) kms_addfb_basic (size-max)
[300.682523] Starting subtest: size-max
[300.685682] Subtest size-max: SUCCESS (0.001s)
[301.110357] [085/161] (787s left) kms_addfb_basic (small-bo)
[301.590988] Starting subtest: small-bo
[301.594201] Subtest small-bo: SUCCESS (0.000s)
[302.010387] [086/161] (787s left) kms_addfb_basic (tile-pitch-mismatch)
[302.388822] Starting subtest: tile-pitch-mismatch
[302.392288] Subtest tile-pitch-mismatch: SUCCESS (0.002s)
[302.917350] [087/161] (786s left) kms_addfb_basic (too-high)
[303.395068] Starting subtest: too-high
[303.402307] Subtest too-high: SUCCESS (0.006s)
[303.821683] [088/161] (786s left) kms_addfb_basic (too-wide)
[304.304343] Starting subtest: too-wide
[304.307190] Subtest too-wide: SUCCESS (0.000s)
[304.717836] [089/161] (785s left) kms_addfb_basic (unused-handle)
[305.088641] Starting subtest: unused-handle
[305.092345] Subtest unused-handle: SUCCESS (0.000s)
[305.610440] [090/161] (785s left) kms_addfb_basic (unused-modifier)
[305.985351] Starting subtest: unused-modifier
[305.988190] Subtest unused-modifier: SUCCESS (0.000s)
[306.516869] [091/161] (784s left) kms_addfb_basic (unused-offsets)
[306.891610] Starting subtest: unused-offsets
[306.894948] Subtest unused-offsets: SUCCESS (0.000s)
[307.425342] [092/161] (783s left) kms_addfb_basic (unused-pitches)
[307.799820] Starting subtest: unused-pitches
[307.803355] Subtest unused-pitches: SUCCESS (0.000s)
[308.321664] [093/161] (783s left) kms_busy (basic)
[308.820657] Starting subtest: basic
[308.828367] Starting dynamic subtest: flip
[310.455527] Dynamic subtest flip: SUCCESS (1.624s)
[310.535393] Starting dynamic subtest: modeset
[315.480878] Dynamic subtest modeset: SUCCESS (5.023s)
[315.484607] Subtest basic: SUCCESS (6.658s)
[316.652516] [094/161] (775s left) kms_prop_blob (basic)
[317.025626] Starting subtest: basic
[317.028802] Subtest basic: SUCCESS (0.000s)
[317.428884] [095/161] (775s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[318.055860] Starting subtest: basic-busy-flip-before-cursor-atomic
[318.061585] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[318.597232] [096/161] (774s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[319.204646] Starting subtest: basic-busy-flip-before-cursor-legacy
[319.210250] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[319.733658] [097/161] (773s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[320.348593] Starting subtest: basic-flip-after-cursor-atomic
[321.295297] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.945s)
[322.494320] [098/161] (771s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[323.103589] Starting subtest: basic-flip-after-cursor-legacy
[324.037622] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.935s)
[325.276443] [099/161] (768s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[325.880172] Starting subtest: basic-flip-after-cursor-varying-size
[326.828320] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.949s)
[328.073812] [100/161] (766s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[328.678808] Starting subtest: basic-flip-before-cursor-atomic
[329.617717] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.936s)
[331.095357] [101/161] (763s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[331.707140] Starting subtest: basic-flip-before-cursor-legacy
[332.647208] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.938s)
[333.882988] [102/161] (761s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[334.489572] Starting subtest: basic-flip-before-cursor-varying-size
[335.432292] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.939s)
[336.666903] [103/161] (758s left) kms_dsc (dsc-basic)
[337.147564] Starting subtest: dsc-basic
[337.161399] Subtest dsc-basic: SKIP (0.003s)
[337.557556] [104/161] (758s left) kms_flip (basic-flip-vs-dpms)
[338.043378] Starting subtest: basic-flip-vs-dpms
[338.053842] Starting dynamic subtest: A-eDP1
[341.645301] Dynamic subtest A-eDP1: SUCCESS (3.591s)
[341.742254] Starting dynamic subtest: B-eDP1
[344.611040] Dynamic subtest B-eDP1: SUCCESS (2.955s)
[344.691146] Starting dynamic subtest: C-eDP1
[347.466498] Dynamic subtest C-eDP1: SUCCESS (2.853s)
[347.472912] Subtest basic-flip-vs-dpms: SUCCESS (9.432s)
[348.677120] [105/161] (747s left) kms_flip (basic-flip-vs-modeset)
[349.155766] Starting subtest: basic-flip-vs-modeset
[349.166080] Starting dynamic subtest: A-eDP1
[352.724141] Dynamic subtest A-eDP1: SUCCESS (3.555s)
[352.809698] Starting dynamic subtest: B-eDP1
[355.591702] Dynamic subtest B-eDP1: SUCCESS (2.860s)
[355.678084] Starting dynamic subtest: C-eDP1
[358.228738] Dynamic subtest C-eDP1: SUCCESS (2.631s)
[358.262248] Subtest basic-flip-vs-modeset: SUCCESS (9.103s)
[359.399321] [106/161] (737s left) kms_flip (basic-flip-vs-wf_vblank)
[359.883858] Starting subtest: basic-flip-vs-wf_vblank
[359.894718] Starting dynamic subtest: A-eDP1
[362.291311] Dynamic subtest A-eDP1: SUCCESS (2.395s)
[362.378220] Starting dynamic subtest: B-eDP1
[364.775926] Dynamic subtest B-eDP1: SUCCESS (2.477s)
[364.866731] Starting dynamic subtest: C-eDP1
[367.038816] Dynamic subtest C-eDP1: SUCCESS (2.257s)
[367.049205] Subtest basic-flip-vs-wf_vblank: SUCCESS (7.168s)
[369.193154] [107/161] (727s left) kms_flip (basic-plain-flip)
[369.677847] Starting subtest: basic-plain-flip
[369.687662] Starting dynamic subtest: A-eDP1
[371.988667] Dynamic subtest A-eDP1: SUCCESS (2.301s)
[372.074561] Starting dynamic subtest: B-eDP1
[374.356222] Dynamic subtest B-eDP1: SUCCESS (2.360s)
[374.442916] Starting dynamic subtest: C-eDP1
[376.507755] Dynamic subtest C-eDP1: SUCCESS (2.147s)
[376.518212] Subtest basic-plain-flip: SUCCESS (6.844s)
[378.463354] [108/161] (718s left) kms_force_connector_basic (force-connector-state)
[378.873559] Starting subtest: force-connector-state
[379.672094] Subtest force-connector-state: SUCCESS (0.793s)
[381.365582] [109/161] (716s left) kms_force_connector_basic (force-edid)
[381.770797] Starting subtest: force-edid
[381.870202] Subtest force-edid: SUCCESS (0.105s)
[382.440768] [110/161] (715s left) kms_force_connector_basic (force-load-detect)
[382.840814] Starting subtest: force-load-detect
[382.847451] Subtest force-load-detect: SKIP (0.000s)
[383.367448] [111/161] (715s left) kms_force_connector_basic (prune-stale-modes)
[383.772914] Starting subtest: prune-stale-modes
[383.881714] Subtest prune-stale-modes: SUCCESS (0.109s)
[384.470761] [112/161] (714s left) kms_frontbuffer_tracking (basic)
[384.985998] Starting subtest: basic
[398.789103] Subtest basic: SUCCESS (13.801s)
[399.261855] [113/161] (699s left) kms_hdmi_inject (inject-audio)
[400.070052] Starting subtest: inject-audio
[400.608327] Subtest inject-audio: SUCCESS (0.530s)
[401.704375] [114/161] (697s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[402.189610] Starting subtest: compare-crc-sanitycheck-xr24
[402.204226] Starting dynamic subtest: pipe-A-eDP-1
[403.506647] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.296s)
[403.594204] Starting dynamic subtest: pipe-B-eDP-1
[405.405333] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.892s)
[405.494322] Starting dynamic subtest: pipe-C-eDP-1
[407.200887] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.782s)
[407.204357] Subtest compare-crc-sanitycheck-xr24: SUCCESS (5.009s)
[408.412837] [115/161] (691s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[408.921510] Starting subtest: compare-crc-sanitycheck-nv12
[408.924736] Starting dynamic subtest: pipe-A-eDP-1
[410.590648] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.673s)
[410.679909] Starting dynamic subtest: pipe-B-eDP-1
[412.797905] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.194s)
[412.889668] Starting dynamic subtest: pipe-C-eDP-1
[414.911159] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.101s)
[414.914108] Subtest compare-crc-sanitycheck-nv12: SUCCESS (6.010s)
[416.068902] [116/161] (684s left) kms_pipe_crc_basic (hang-read-crc)
[416.582250] Starting subtest: hang-read-crc
[416.595472] Starting dynamic subtest: pipe-A-eDP-1
[420.693736] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.101s)
[420.787780] Starting dynamic subtest: pipe-B-eDP-1
[425.389564] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.680s)
[425.481483] Starting dynamic subtest: pipe-C-eDP-1
[430.075952] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.667s)
[430.079100] Subtest hang-read-crc: SUCCESS (13.492s)
[431.326112] [117/161] (669s left) kms_pipe_crc_basic (nonblocking-crc)
[431.842878] Starting subtest: nonblocking-crc
[431.852790] Starting dynamic subtest: pipe-A-eDP-1
[433.287804] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.433s)
[433.381076] Starting dynamic subtest: pipe-B-eDP-1
[435.411148] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.109s)
[435.510872] Starting dynamic subtest: pipe-C-eDP-1
[437.422325] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.991s)
[437.425308] Subtest nonblocking-crc: SUCCESS (5.580s)
[438.645776] [118/161] (662s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[439.139618] Starting subtest: nonblocking-crc-frame-sequence
[439.144661] Starting dynamic subtest: pipe-A-eDP-1
[440.579546] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.433s)
[440.672888] Starting dynamic subtest: pipe-B-eDP-1
[442.635231] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.037s)
[442.726325] Starting dynamic subtest: pipe-C-eDP-1
[444.671728] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.021s)
[444.674537] Subtest nonblocking-crc-frame-sequence: SUCCESS (5.539s)
[445.871971] [119/161] (655s left) kms_pipe_crc_basic (read-crc)
[446.353306] Starting subtest: read-crc
[446.370463] Starting dynamic subtest: pipe-A-eDP-1
[447.816910] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.452s)
[447.911200] Starting dynamic subtest: pipe-B-eDP-1
[450.044962] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.212s)
[450.143329] Starting dynamic subtest: pipe-C-eDP-1
[452.048991] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.980s)
[452.052076] Subtest read-crc: SUCCESS (5.693s)
[453.192882] [120/161] (648s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[453.677499] Starting subtest: read-crc-frame-sequence
[453.688806] Starting dynamic subtest: pipe-A-eDP-1
[455.108724] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.419s)
[455.206263] Starting dynamic subtest: pipe-B-eDP-1
[457.160971] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.033s)
[457.259213] Starting dynamic subtest: pipe-C-eDP-1
[459.177141] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.999s)
[459.179961] Subtest read-crc-frame-sequence: SUCCESS (5.501s)
[460.425221] [121/161] (641s left) kms_pm_backlight (basic-brightness)
[460.915647] Starting subtest: basic-brightness
[461.153218] Starting dynamic subtest: eDP-1
[461.843872] Dynamic subtest eDP-1: SUCCESS (0.695s)
[461.847151] Subtest basic-brightness: SUCCESS (0.927s)
[463.000214] [122/161] (639s left) kms_pm_rpm (basic-pci-d3-state)
[465.279209] Starting subtest: basic-pci-d3-state
[467.209238] Subtest basic-pci-d3-state: SUCCESS (1.922s)
[467.723204] [123/161] (635s left) kms_pm_rpm (basic-rte)
[468.095869] Starting subtest: basic-rte
[471.883958] Subtest basic-rte: SUCCESS (3.787s)
[474.481385] [124/161] (628s left) kms_psr (psr-primary-page-flip)
[474.967641] Starting subtest: psr-primary-page-flip
[474.978703] Starting dynamic subtest: eDP-1
[477.398968] Dynamic subtest eDP-1: SUCCESS (2.422s)
[477.401765] Subtest psr-primary-page-flip: SUCCESS (2.433s)
[478.603931] [125/161] (625s left) kms_psr (psr-cursor-plane-move)
[479.092112] Starting subtest: psr-cursor-plane-move
[479.102643] Starting dynamic subtest: eDP-1
[481.208434] Dynamic subtest eDP-1: SUCCESS (2.105s)
[481.211617] Subtest psr-cursor-plane-move: SUCCESS (2.117s)
[482.399140] [126/161] (621s left) kms_psr (psr-sprite-plane-onoff)
[482.886910] Starting subtest: psr-sprite-plane-onoff
[482.898327] Starting dynamic subtest: eDP-1
[485.302413] Dynamic subtest eDP-1: SUCCESS (2.402s)
[485.305715] Subtest psr-sprite-plane-onoff: SUCCESS (2.414s)
[486.523217] [127/161] (617s left) kms_psr (psr-primary-mmap-gtt)
[487.014889] Starting subtest: psr-primary-mmap-gtt
[487.017736] Starting dynamic subtest: eDP-1
[489.228967] Dynamic subtest eDP-1: SUCCESS (2.207s)
[489.232765] Subtest psr-primary-mmap-gtt: SUCCESS (2.220s)
[490.409704] [128/161] (614s left) kms_setmode (basic-clone-single-crtc)
[490.796858] Starting subtest: basic-clone-single-crtc
[490.805978] Subtest basic-clone-single-crtc: SKIP (0.001s)
[491.218273] [129/161] (614s left) i915_pm_rps (basic-api)
[491.655631] Starting subtest: basic-api
[491.662736] Subtest basic-api: SKIP (0.004s)
[492.102037] [130/161] (613s left) prime_self_import (basic-llseek-bad)
[492.473191] Starting subtest: basic-llseek-bad
[492.487089] Subtest basic-llseek-bad: SUCCESS (0.014s)
[492.917879] [131/161] (613s left) prime_self_import (basic-llseek-size)
[493.289067] Starting subtest: basic-llseek-size
[493.304500] Subtest basic-llseek-size: SUCCESS (0.022s)
[493.757218] [132/161] (612s left) prime_self_import (basic-with_fd_dup)
[494.126894] Starting subtest: basic-with_fd_dup
[494.142786] Subtest basic-with_fd_dup: SUCCESS (0.021s)
[494.576436] [133/161] (612s left) prime_self_import (basic-with_one_bo)
[494.945939] Starting subtest: basic-with_one_bo
[494.967986] Subtest basic-with_one_bo: SUCCESS (0.022s)
[495.413129] [134/161] (611s left) prime_self_import (basic-with_one_bo_two_files)
[495.779180] Starting subtest: basic-with_one_bo_two_files
[495.801068] Subtest basic-with_one_bo_two_files: SUCCESS (0.019s)
[496.237139] [135/161] (611s left) prime_self_import (basic-with_two_bos)
[496.614272] Starting subtest: basic-with_two_bos
[496.628333] Subtest basic-with_two_bos: SUCCESS (0.022s)
[497.062988] [136/161] (610s left) prime_vgem (basic-fence-flip)
[497.507222] Starting subtest: basic-fence-flip
[498.283586] Subtest basic-fence-flip: SUCCESS (0.779s)
[498.744480] [137/161] (609s left) prime_vgem (basic-fence-mmap)
[499.179204] Starting subtest: basic-fence-mmap
[499.379412] Subtest basic-fence-mmap: SUCCESS (0.197s)
[499.818467] [138/161] (608s left) prime_vgem (basic-fence-read)
[500.257540] Starting subtest: basic-fence-read
[500.309985] Subtest basic-fence-read: SKIP (0.057s)
[500.757606] [139/161] (608s left) prime_vgem (basic-gtt)
[501.205393] Starting subtest: basic-gtt
[501.286813] Subtest basic-gtt: SUCCESS (0.088s)
[501.733584] [140/161] (607s left) prime_vgem (basic-read)
[502.171205] Starting subtest: basic-read
[502.229710] Subtest basic-read: SKIP (0.055s)
[502.681518] [141/161] (607s left) prime_vgem (basic-write)
[503.116062] Starting subtest: basic-write
[503.175138] Subtest basic-write: SKIP (0.055s)
[503.634223] [142/161] (606s left) vgem_basic (setversion)
[504.039092] Starting subtest: setversion
[504.045099] Subtest setversion: SUCCESS (0.000s)
[504.457981] [143/161] (606s left) vgem_basic (create)
[504.858749] Starting subtest: create
[504.862300] Subtest create: SUCCESS (0.002s)
[505.265455] [144/161] (605s left) vgem_basic (debugfs)
[505.662830] Starting subtest: debugfs
[505.665932] Subtest debugfs: SUCCESS (0.003s)
[506.072272] [145/161] (605s left) vgem_basic (dmabuf-export)
[506.482329] Starting subtest: dmabuf-export
[506.501290] Subtest dmabuf-export: SUCCESS (0.023s)
[506.970826] [146/161] (604s left) vgem_basic (dmabuf-fence)
[507.374942] Starting subtest: dmabuf-fence
[507.380300] Subtest dmabuf-fence: SUCCESS (0.003s)
[507.789178] [147/161] (604s left) vgem_basic (dmabuf-fence-before)
[508.186760] Starting subtest: dmabuf-fence-before
[508.189983] Subtest dmabuf-fence-before: SUCCESS (0.005s)
[508.599995] [148/161] (603s left) vgem_basic (dmabuf-mmap)
[509.003351] Starting subtest: dmabuf-mmap
[509.094984] Subtest dmabuf-mmap: SUCCESS (0.097s)
[509.510496] [149/161] (603s left) vgem_basic (mmap)
[509.908922] Starting subtest: mmap
[509.971228] Subtest mmap: SUCCESS (0.067s)
[510.389681] [150/161] (602s left) vgem_basic (second-client)
[510.789451] Starting subtest: second-client
[510.823668] Subtest second-client: SUCCESS (0.038s)
[511.234795] [151/161] (602s left) vgem_basic (sysfs)
[511.637824] Starting subtest: sysfs
[511.640880] Subtest sysfs: SUCCESS (0.004s)
[512.047498] [152/161] (601s left) core_hotunplug (unbind-rebind)
[512.733562] Starting subtest: unbind-rebind
[521.545496] Subtest unbind-rebind: SUCCESS (8.810s)
[521.951621] [153/161] (592s left) vgem_basic (unload)
[522.335424] Starting subtest: unload
[552.035964] Subtest unload: SUCCESS (29.673s)
[552.598352] [154/161] (562s left) i915_module_load (reload)
[552.968369] Starting subtest: reload
[568.101161] Subtest reload: SUCCESS (15.130s)
[568.542264] [155/161] (546s left) gem_lmem_swapping (basic)
[569.103350] Subtest basic: SKIP (0.000s)
[569.518620] [156/161] (546s left) gem_lmem_swapping (parallel-random-engines)
[569.945530] Subtest parallel-random-engines: SKIP (0.000s)
[570.326691] [157/161] (545s left) gem_lmem_swapping (random-engines)
[570.748899] Subtest random-engines: SKIP (0.000s)
[571.133378] [158/161] (545s left) gem_lmem_swapping (verify-random)
[571.552153] Subtest verify-random: SKIP (0.000s)
[571.936260] [159/161] (544s left) i915_pm_rpm (module-reload)
[575.023365] Starting subtest: module-reload
[608.178178] Subtest module-reload: SUCCESS (33.143s)
[608.422364] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[608.436069] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[608.450485] [160/161] (508s left) i915_selftest (live)
[608.847658] Starting subtest: live
[608.858753] Starting dynamic subtest: sanitycheck
[619.262524] Dynamic subtest sanitycheck: SUCCESS (10.401s)
[619.335464] Starting dynamic subtest: uncore
[629.701210] Dynamic subtest uncore: SUCCESS (10.429s)
[629.767278] Starting dynamic subtest: workarounds
[642.664390] Dynamic subtest workarounds: SUCCESS (12.953s)
[642.753683] Starting dynamic subtest: gt_engines
[653.600239] Dynamic subtest gt_engines: SUCCESS (10.928s)
[653.665200] Starting dynamic subtest: gt_timelines
[698.248768] Dynamic subtest gt_timelines: SUCCESS (44.638s)
[698.314734] Starting dynamic subtest: gt_contexts
[708.705227] Dynamic subtest gt_contexts: SUCCESS (10.448s)
[708.768058] Starting dynamic subtest: gt_lrc
[722.880952] Dynamic subtest gt_lrc: SUCCESS (14.166s)
[722.950159] Starting dynamic subtest: gt_mocs
[736.456874] Dynamic subtest gt_mocs: SUCCESS (13.541s)
[736.522281] Starting dynamic subtest: gt_pm
[748.930614] Dynamic subtest gt_pm: SUCCESS (12.489s)
[748.995251] Starting dynamic subtest: gt_heartbeat
[759.352220] Dynamic subtest gt_heartbeat: SUCCESS (10.414s)
[759.415378] Starting dynamic subtest: gt_tlb
[770.896326] Dynamic subtest gt_tlb: SUCCESS (11.534s)
[770.963322] Starting dynamic subtest: requests
[791.223952] Dynamic subtest requests: SUCCESS (20.293s)
[791.286831] Starting dynamic subtest: migrate
[805.550243] Dynamic subtest migrate: SUCCESS (14.342s)
[805.615853] Starting dynamic subtest: active
[816.029244] Dynamic subtest active: SUCCESS (10.470s)
[816.092385] Starting dynamic subtest: objects
[830.727555] Dynamic subtest objects: SUCCESS (14.687s)
[830.793798] Starting dynamic subtest: mman
[852.397891] Dynamic subtest mman: SUCCESS (21.662s)
[852.474497] Starting dynamic subtest: dmabuf
[863.096401] Dynamic subtest dmabuf: SUCCESS (10.689s)
[863.165859] Starting dynamic subtest: vma
[873.430211] Dynamic subtest vma: SUCCESS (10.325s)
[873.498301] Starting dynamic subtest: coherency
[902.475246] Dynamic subtest coherency: SUCCESS (29.033s)
[902.548383] Starting dynamic subtest: gtt
[921.077912] Dynamic subtest gtt: SUCCESS (18.594s)
[921.144561] Starting dynamic subtest: gem
[932.670765] Dynamic subtest gem: SUCCESS (11.579s)
[932.737195] Starting dynamic subtest: evict
[951.577573] Dynamic subtest evict: SUCCESS (18.902s)
[951.643428] Starting dynamic subtest: hugepages
[968.929611] Dynamic subtest hugepages: SUCCESS (17.342s)
[968.995455] Starting dynamic subtest: gem_contexts
[1010.852268] Dynamic subtest gem_contexts: SUCCESS (41.908s)
[1010.923408] Starting dynamic subtest: client
[1021.382766] Dynamic subtest client: SUCCESS (10.521s)
[1021.451467] Starting dynamic subtest: gem_migrate
[1031.707123] Dynamic subtest gem_migrate: SUCCESS (10.315s)
[1031.777506] Starting dynamic subtest: reset
[1045.712431] Dynamic subtest reset: SUCCESS (13.996s)
[1045.783379] Starting dynamic subtest: memory_region
[1056.158532] Dynamic subtest memory_region: SUCCESS (10.437s)
[1056.224948] Starting dynamic subtest: hangcheck
[1087.118120] Dynamic subtest hangcheck: SUCCESS (30.946s)
[1087.184318] Starting dynamic subtest: execlists
[1097.527363] Dynamic subtest execlists: SUCCESS (10.402s)
[1097.621904] Starting dynamic subtest: ring_submission
[1107.864752] Dynamic subtest ring_submission: SUCCESS (10.327s)
[1107.936625] Starting dynamic subtest: perf
[1118.836412] Dynamic subtest perf: SUCCESS (10.962s)
[1118.902971] Starting dynamic subtest: slpc
[1131.023274] Dynamic subtest slpc: SUCCESS (12.178s)
[1131.089821] Starting dynamic subtest: guc
[1144.102697] Dynamic subtest guc: SUCCESS (13.070s)
[1144.166838] Starting dynamic subtest: guc_multi_lrc
[1154.529422] Dynamic subtest guc_multi_lrc: SUCCESS (10.418s)
[1154.596327] Starting dynamic subtest: guc_hang
[1166.114121] Dynamic subtest guc_hang: SUCCESS (11.574s)
[1166.181851] Starting dynamic subtest: late_gt_pm
[1176.639445] Dynamic subtest late_gt_pm: SUCCESS (10.518s)
[1176.642750] Subtest live: SUCCESS (567.761s)
[1176.765242] Overall timeout time exceeded, stopping.
[1176.931904] Closing watchdogs
Done.