[104.907169] Initializing watchdogs
[104.907714]   /dev/watchdog0
[105.070681] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:46d3 Intel Alderlake_n (Gen12) 
[105.108398] [001/161] (960s left) i915_module_load (load)
[106.283372] Starting subtest: load
[117.531481] Subtest load: SUCCESS (11.257s)
[117.877105] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[117.892949] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[117.936911] [002/161] (948s left) core_auth (basic-auth)
[118.518328] Starting subtest: basic-auth
[118.524632] Subtest basic-auth: SUCCESS (0.008s)
[119.045087] [003/161] (947s left) debugfs_test (read_all_entries)
[119.490961] Starting subtest: read_all_entries
[120.969047] Subtest read_all_entries: SUCCESS (1.479s)
[121.490605] [004/161] (945s left) debugfs_test (basic-hwmon)
[121.911790] Starting subtest: basic-hwmon
[121.917894] Subtest basic-hwmon: SKIP (0.000s)
[122.431246] [005/161] (944s left) debugfs_test (sysfs)
[122.834275] Starting subtest: sysfs
[123.298897] Subtest sysfs: SUCCESS (0.462s)
[123.813188] [006/161] (943s left) fbdev (eof)
[124.212298] Starting subtest: eof
[124.220223] Subtest eof: SUCCESS (0.009s)
[124.671614] [007/161] (943s left) fbdev (info)
[125.039814] Starting subtest: info
[125.043256] Subtest info: SUCCESS (0.000s)
[125.488521] [008/161] (942s left) fbdev (nullptr)
[125.863495] Starting subtest: nullptr
[125.866574] Subtest nullptr: SUCCESS (0.000s)
[126.292493] [009/161] (942s left) fbdev (read)
[126.677355] Starting subtest: read
[140.358229] Subtest read: SUCCESS (13.646s)
[140.763212] [010/161] (927s left) fbdev (write)
[141.135595] Starting subtest: write
[148.263460] Subtest write: SUCCESS (7.121s)
[148.720668] [011/161] (920s left) gem_basic (bad-close)
[149.097621] Starting subtest: bad-close
[149.103675] Subtest bad-close: SUCCESS (0.000s)
[149.510831] [012/161] (919s left) gem_basic (create-close)
[149.891104] Starting subtest: create-close
[149.896519] Subtest create-close: SUCCESS (0.001s)
[150.282608] [013/161] (919s left) gem_basic (create-fd-close)
[150.660195] Starting subtest: create-fd-close
[150.666213] Subtest create-fd-close: SUCCESS (0.001s)
[151.086290] [014/161] (918s left) gem_busy (busy@all-engines)
[151.560759] Starting subtest: busy
[151.563977] Starting dynamic subtest: all-engines
[151.874966] Dynamic subtest all-engines: SUCCESS (0.316s)
[151.878356] Subtest busy: SUCCESS (0.318s)
[152.740589] [015/161] (917s left) gem_close_race (basic-process)
[153.218221] Starting subtest: basic-process
[153.371925] Subtest basic-process: SUCCESS (0.154s)
[153.926619] [016/161] (916s left) gem_close_race (basic-threads)
[154.428632] Starting subtest: basic-threads
[155.899882] Subtest basic-threads: SUCCESS (1.474s)
[156.450498] [017/161] (914s left) gem_ctx_create (basic)
[156.913673] Starting subtest: basic
[156.916887] Subtest basic: SUCCESS (0.000s)
[157.470296] [018/161] (913s left) gem_ctx_create (basic-files)
[157.928626] Starting subtest: basic-files
[160.089074] Subtest basic-files: SUCCESS (2.159s)
[160.686337] [019/161] (911s left) gem_ctx_exec (basic)
[161.070722] Starting subtest: basic
[161.092419] Subtest basic: SUCCESS (0.021s)
[161.552903] [020/161] (910s left) gem_exec_basic (basic)
[161.982222] Starting subtest: basic
[161.984995] Starting dynamic subtest: rcs0-smem
[161.994862] Dynamic subtest rcs0-smem: SUCCESS (0.009s)
[162.053640] Starting dynamic subtest: bcs0-smem
[162.056744] Dynamic subtest bcs0-smem: SUCCESS (0.004s)
[162.114318] Starting dynamic subtest: vcs0-smem
[162.117308] Dynamic subtest vcs0-smem: SUCCESS (0.004s)
[162.177566] Starting dynamic subtest: vecs0-smem
[162.180732] Dynamic subtest vecs0-smem: SUCCESS (0.004s)
[162.184499] Subtest basic: SUCCESS (0.030s)
[162.596606] [021/161] (909s left) gem_exec_create (basic)
[163.047251] Starting subtest: basic
[163.051455] Starting dynamic subtest: smem
[165.282171] Dynamic subtest smem: SUCCESS (2.235s)
[165.285402] Subtest basic: SUCCESS (2.237s)
[165.835735] [022/161] (906s left) gem_exec_fence (basic-busy)
[166.499394] Starting subtest: basic-busy
[166.502574] Starting dynamic subtest: rcs0
[166.608228] Dynamic subtest rcs0: SUCCESS (0.111s)
[166.671268] Starting dynamic subtest: bcs0
[166.677806] Dynamic subtest bcs0: SUCCESS (0.060s)
[166.737294] Starting dynamic subtest: vcs0
[166.743379] Dynamic subtest vcs0: SUCCESS (0.060s)
[166.806146] Starting dynamic subtest: vecs0
[166.814205] Dynamic subtest vecs0: SUCCESS (0.073s)
[166.817230] Subtest basic-busy: SUCCESS (0.311s)
[168.296423] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[168.318452] [023/161] (904s left) gem_exec_fence (basic-wait)
[168.985847] Starting subtest: basic-wait
[168.989581] Starting dynamic subtest: rcs0
[169.100866] Dynamic subtest rcs0: SUCCESS (0.117s)
[169.165120] Starting dynamic subtest: bcs0
[169.168356] Dynamic subtest bcs0: SUCCESS (0.066s)
[169.234537] Starting dynamic subtest: vcs0
[169.239204] Dynamic subtest vcs0: SUCCESS (0.063s)
[169.302254] Starting dynamic subtest: vecs0
[169.305222] Dynamic subtest vecs0: SUCCESS (0.066s)
[169.308311] Subtest basic-wait: SUCCESS (0.320s)
[170.637840] [024/161] (902s left) gem_exec_fence (basic-await)
[171.300831] Starting subtest: basic-await
[171.304218] Starting dynamic subtest: rcs0
[171.644832] Dynamic subtest rcs0: SUCCESS (0.344s)
[171.711921] Starting dynamic subtest: bcs0
[171.945722] Dynamic subtest bcs0: SUCCESS (0.302s)
[172.009415] Starting dynamic subtest: vcs0
[172.249316] Dynamic subtest vcs0: SUCCESS (0.301s)
[172.311219] Starting dynamic subtest: vecs0
[172.555739] Dynamic subtest vecs0: SUCCESS (0.303s)
[172.559329] Subtest basic-await: SUCCESS (1.258s)
[173.841680] [025/161] (899s left) gem_exec_fence (nb-await)
[174.501898] Starting subtest: nb-await
[174.505139] Starting dynamic subtest: rcs0
[174.609090] Dynamic subtest rcs0: SUCCESS (0.111s)
[174.673243] Starting dynamic subtest: bcs0
[174.699599] Dynamic subtest bcs0: SUCCESS (0.088s)
[174.761472] Starting dynamic subtest: vcs0
[174.790619] Dynamic subtest vcs0: SUCCESS (0.089s)
[174.854913] Starting dynamic subtest: vecs0
[174.881432] Dynamic subtest vecs0: SUCCESS (0.089s)
[174.884681] Subtest nb-await: SUCCESS (0.385s)
[176.152730] [026/161] (898s left) gem_exec_gttfill (basic)
[176.615808] Starting subtest: basic
[215.113314] Subtest basic: SUCCESS (38.484s)
[215.731057] [027/161] (858s left) gem_exec_parallel (engines)
[216.365322] Starting subtest: engines
[216.368095] Starting dynamic subtest: basic
[222.892592] Dynamic subtest basic: SUCCESS (6.514s)
[222.957843] Starting dynamic subtest: contexts
[233.075800] Dynamic subtest contexts: SUCCESS (10.185s)
[233.143628] Starting dynamic subtest: fds
[245.853841] Dynamic subtest fds: SUCCESS (12.776s)
[245.924271] Starting dynamic subtest: userptr
[252.033550] Dynamic subtest userptr: SUCCESS (6.186s)
[252.036481] Subtest engines: SUCCESS (35.668s)
[252.656165] [028/161] (822s left) gem_exec_store (basic)
[253.120100] Starting subtest: basic
[253.282084] Subtest basic: SUCCESS (0.164s)
[253.874861] [029/161] (821s left) gem_flink_basic (bad-flink)
[254.238514] Starting subtest: bad-flink
[254.242168] Subtest bad-flink: SUCCESS (0.000s)
[254.690570] [030/161] (821s left) gem_flink_basic (bad-open)
[255.059552] Starting subtest: bad-open
[255.063368] Subtest bad-open: SUCCESS (0.000s)
[255.510496] [031/161] (820s left) gem_flink_basic (basic)
[255.883430] Starting subtest: basic
[255.886416] Subtest basic: SUCCESS (0.001s)
[256.353286] [032/161] (820s left) gem_flink_basic (double-flink)
[256.725707] Starting subtest: double-flink
[256.729534] Subtest double-flink: SUCCESS (0.001s)
[257.187351] [033/161] (819s left) gem_flink_basic (flink-lifetime)
[257.568370] Starting subtest: flink-lifetime
[257.575482] Subtest flink-lifetime: SUCCESS (0.010s)
[258.052342] [034/161] (819s left) gem_huc_copy (huc-copy)
[258.456846] Starting subtest: huc-copy
[258.477140] Subtest huc-copy: SUCCESS (0.020s)
[258.966201] [035/161] (818s left) gem_linear_blits (basic)
[260.085414] Starting subtest: basic
[260.139178] Subtest basic: SUCCESS (0.063s)
[260.711332] [036/161] (817s left) gem_mmap (basic)
[261.276922] Starting subtest: basic
[261.291563] Subtest basic: SUCCESS (0.003s)
[261.758865] [037/161] (816s left) gem_mmap_gtt (basic)
[262.133334] Starting subtest: basic
[262.137266] Subtest basic: SUCCESS (0.005s)
[262.607630] [038/161] (816s left) gem_render_linear_blits (basic)
[263.011277] Starting subtest: basic
[263.229133] Subtest basic: SUCCESS (0.215s)
[263.681485] [039/161] (815s left) gem_render_tiled_blits (basic)
[264.082273] Starting subtest: basic
[264.357131] Subtest basic: SUCCESS (0.275s)
[264.812454] [040/161] (814s left) gem_ringfill (basic-all)
[265.687541] Starting subtest: basic-all
[267.191789] Subtest basic-all: SUCCESS (1.498s)
[267.654766] [041/161] (812s left) gem_softpin (allocator-basic)
[268.062995] Starting subtest: allocator-basic
[268.753946] Subtest allocator-basic: SUCCESS (0.685s)
[269.311509] [042/161] (810s left) gem_softpin (allocator-basic-reserve)
[269.717904] Starting subtest: allocator-basic-reserve
[270.412867] Subtest allocator-basic-reserve: SUCCESS (0.686s)
[270.949589] [043/161] (809s left) gem_softpin (safe-alignment)
[271.361769] Starting subtest: safe-alignment
[271.442332] Subtest safe-alignment: SUCCESS (0.083s)
[271.938847] [044/161] (808s left) gem_sync (basic-all)
[272.406238] Starting subtest: basic-all
[277.642114] Subtest basic-all: SUCCESS (5.235s)
[278.236664] [045/161] (803s left) gem_sync (basic-each)
[278.702874] Starting subtest: basic-each
[282.261418] Subtest basic-each: SUCCESS (3.559s)
[282.858370] [046/161] (798s left) gem_tiled_blits (basic)
[283.780847] Starting subtest: basic
[283.907345] Subtest basic: SUCCESS (0.127s)
[284.433615] [047/161] (797s left) gem_tiled_fence_blits (basic)
[285.582486] Starting subtest: basic
[285.645740] Subtest basic: SUCCESS (0.076s)
[286.213887] [048/161] (796s left) gem_tiled_pread_basic
[287.212698] [049/161] (795s left) gem_wait (busy@all-engines)
[287.739576] Starting subtest: busy
[287.742664] Starting dynamic subtest: all-engines
[288.324886] Dynamic subtest all-engines: SUCCESS (0.597s)
[288.328230] Subtest busy: SUCCESS (0.599s)
[289.054836] [050/161] (794s left) gem_wait (wait@all-engines)
[289.605131] Starting subtest: wait
[289.608215] Starting dynamic subtest: all-engines
[290.721886] Dynamic subtest all-engines: SUCCESS (1.120s)
[290.725293] Subtest wait: SUCCESS (1.122s)
[291.447082] [051/161] (792s left) i915_getparams_basic (basic-eu-total)
[291.811412] Starting subtest: basic-eu-total
[291.817810] Subtest basic-eu-total: SUCCESS (0.000s)
[292.261626] [052/161] (791s left) i915_getparams_basic (basic-subslice-total)
[292.632878] Starting subtest: basic-subslice-total
[292.639238] Subtest basic-subslice-total: SUCCESS (0.000s)
[293.088337] [053/161] (791s left) i915_hangman (error-state-basic)
[293.538305] Starting subtest: error-state-basic
[294.386945] Subtest error-state-basic: SUCCESS (0.847s)
[294.876789] [054/161] (789s left) i915_pciid
[295.700392] [055/161] (789s left) kms_addfb_basic (addfb25-4-tiled)
[296.204755] Starting subtest: addfb25-4-tiled
[296.207462] Subtest addfb25-4-tiled: SUCCESS (0.002s)
[296.685459] [056/161] (788s left) kms_addfb_basic (addfb25-bad-modifier)
[297.165489] Starting subtest: addfb25-bad-modifier
[297.168891] Subtest addfb25-bad-modifier: SUCCESS (0.002s)
[297.641487] [057/161] (788s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[298.119827] Starting subtest: addfb25-framebuffer-vs-set-tiling
[298.122799] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.002s)
[298.593921] [058/161] (787s left) kms_addfb_basic (addfb25-modifier-no-flag)
[299.071679] Starting subtest: addfb25-modifier-no-flag
[299.075057] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[299.546365] [059/161] (787s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[300.030770] Starting subtest: addfb25-x-tiled-legacy
[300.034363] Subtest addfb25-x-tiled-legacy: SUCCESS (0.001s)
[300.508198] [060/161] (786s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[300.993660] Starting subtest: addfb25-x-tiled-mismatch-legacy
[300.997067] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.002s)
[301.463666] [061/161] (785s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[301.945826] Starting subtest: addfb25-yf-tiled-legacy
[301.948851] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.002s)
[302.433816] [062/161] (785s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[302.914908] Starting subtest: addfb25-y-tiled-legacy
[302.918692] Subtest addfb25-y-tiled-legacy: SUCCESS (0.001s)
[303.391199] [063/161] (784s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[303.873108] Starting subtest: addfb25-y-tiled-small-legacy
[303.876627] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.002s)
[304.354912] [064/161] (784s left) kms_addfb_basic (bad-pitch-0)
[304.754473] Starting subtest: bad-pitch-0
[304.757354] Subtest bad-pitch-0: SUCCESS (0.000s)
[305.325845] [065/161] (783s left) kms_addfb_basic (bad-pitch-1024)
[305.701555] Starting subtest: bad-pitch-1024
[305.704603] Subtest bad-pitch-1024: SUCCESS (0.000s)
[306.274328] [066/161] (782s left) kms_addfb_basic (bad-pitch-128)
[306.650707] Starting subtest: bad-pitch-128
[306.653722] Subtest bad-pitch-128: SUCCESS (0.000s)
[307.238158] [067/161] (782s left) kms_addfb_basic (bad-pitch-256)
[307.613426] Starting subtest: bad-pitch-256
[307.616345] Subtest bad-pitch-256: SUCCESS (0.000s)
[308.171494] [068/161] (781s left) kms_addfb_basic (bad-pitch-32)
[308.545769] Starting subtest: bad-pitch-32
[308.548887] Subtest bad-pitch-32: SUCCESS (0.000s)
[309.115177] [069/161] (781s left) kms_addfb_basic (bad-pitch-63)
[309.491731] Starting subtest: bad-pitch-63
[309.494667] Subtest bad-pitch-63: SUCCESS (0.000s)
[310.074397] [070/161] (780s left) kms_addfb_basic (bad-pitch-65536)
[310.449485] Starting subtest: bad-pitch-65536
[310.452958] Subtest bad-pitch-65536: SUCCESS (0.002s)
[311.022228] [071/161] (780s left) kms_addfb_basic (bad-pitch-999)
[311.400090] Starting subtest: bad-pitch-999
[311.403505] Subtest bad-pitch-999: SUCCESS (0.000s)
[311.973166] [072/161] (779s left) kms_addfb_basic (basic)
[312.350764] Starting subtest: basic
[312.353810] Subtest basic: SUCCESS (0.000s)
[312.910523] [073/161] (778s left) kms_addfb_basic (basic-x-tiled-legacy)
[313.292561] Starting subtest: basic-x-tiled-legacy
[313.295528] Subtest basic-x-tiled-legacy: SUCCESS (0.001s)
[313.862710] [074/161] (778s left) kms_addfb_basic (basic-y-tiled-legacy)
[314.242137] Starting subtest: basic-y-tiled-legacy
[314.245358] Subtest basic-y-tiled-legacy: SUCCESS (0.002s)
[314.817879] [075/161] (777s left) kms_addfb_basic (bo-too-small)
[315.289878] Starting subtest: bo-too-small
[315.293301] Subtest bo-too-small: SUCCESS (0.002s)
[315.764922] [076/161] (777s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[316.237825] Starting subtest: bo-too-small-due-to-tiling
[316.241207] Subtest bo-too-small-due-to-tiling: SUCCESS (0.002s)
[316.717650] [077/161] (776s left) kms_addfb_basic (clobberred-modifier)
[317.090934] Starting subtest: clobberred-modifier
[317.093795] Subtest clobberred-modifier: SUCCESS (0.001s)
[317.660547] [078/161] (776s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[318.036293] Starting subtest: framebuffer-vs-set-tiling
[318.039394] Subtest framebuffer-vs-set-tiling: SUCCESS (0.001s)
[318.610097] [079/161] (775s left) kms_addfb_basic (invalid-get-prop)
[318.980146] Starting subtest: invalid-get-prop
[318.982865] Subtest invalid-get-prop: SUCCESS (0.000s)
[319.575525] [080/161] (774s left) kms_addfb_basic (invalid-get-prop-any)
[319.949252] Starting subtest: invalid-get-prop-any
[319.952439] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[320.529256] [081/161] (774s left) kms_addfb_basic (invalid-set-prop)
[320.902872] Starting subtest: invalid-set-prop
[320.905920] Subtest invalid-set-prop: SUCCESS (0.000s)
[321.475543] [082/161] (773s left) kms_addfb_basic (invalid-set-prop-any)
[321.850385] Starting subtest: invalid-set-prop-any
[321.854310] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[322.425462] [083/161] (773s left) kms_addfb_basic (no-handle)
[322.796979] Starting subtest: no-handle
[322.800791] Subtest no-handle: SUCCESS (0.000s)
[323.376393] [084/161] (772s left) kms_addfb_basic (size-max)
[323.858835] Starting subtest: size-max
[323.861895] Subtest size-max: SUCCESS (0.001s)
[324.320832] [085/161] (772s left) kms_addfb_basic (small-bo)
[324.800631] Starting subtest: small-bo
[324.803779] Subtest small-bo: SUCCESS (0.000s)
[325.266426] [086/161] (771s left) kms_addfb_basic (tile-pitch-mismatch)
[325.644852] Starting subtest: tile-pitch-mismatch
[325.648350] Subtest tile-pitch-mismatch: SUCCESS (0.002s)
[326.228187] [087/161] (770s left) kms_addfb_basic (too-high)
[326.702980] Starting subtest: too-high
[326.706311] Subtest too-high: SUCCESS (0.006s)
[327.178267] [088/161] (770s left) kms_addfb_basic (too-wide)
[327.659122] Starting subtest: too-wide
[327.662337] Subtest too-wide: SUCCESS (0.000s)
[328.125670] [089/161] (769s left) kms_addfb_basic (unused-handle)
[328.503293] Starting subtest: unused-handle
[328.506403] Subtest unused-handle: SUCCESS (0.000s)
[329.082482] [090/161] (769s left) kms_addfb_basic (unused-modifier)
[329.461236] Starting subtest: unused-modifier
[329.464339] Subtest unused-modifier: SUCCESS (0.000s)
[330.032398] [091/161] (768s left) kms_addfb_basic (unused-offsets)
[330.403635] Starting subtest: unused-offsets
[330.407398] Subtest unused-offsets: SUCCESS (0.000s)
[330.980633] [092/161] (767s left) kms_addfb_basic (unused-pitches)
[331.357069] Starting subtest: unused-pitches
[331.360167] Subtest unused-pitches: SUCCESS (0.000s)
[331.935460] [093/161] (767s left) kms_busy (basic)
[332.446503] Starting subtest: basic
[332.452761] Starting dynamic subtest: flip
[334.761927] Dynamic subtest flip: SUCCESS (2.310s)
[334.846498] Starting dynamic subtest: modeset
[339.959984] Dynamic subtest modeset: SUCCESS (5.194s)
[339.963156] Subtest basic: SUCCESS (7.515s)
[341.286991] [094/161] (758s left) kms_prop_blob (basic)
[341.658801] Starting subtest: basic
[341.661783] Subtest basic: SUCCESS (0.000s)
[342.115790] [095/161] (758s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[342.733174] Starting subtest: basic-busy-flip-before-cursor-atomic
[342.738887] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[343.326351] [096/161] (757s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[343.931322] Starting subtest: basic-busy-flip-before-cursor-legacy
[343.937247] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[344.528876] [097/161] (756s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[345.137833] Starting subtest: basic-flip-after-cursor-atomic
[346.760663] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.621s)
[348.068247] [098/161] (753s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[348.685285] Starting subtest: basic-flip-after-cursor-legacy
[350.288084] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.604s)
[351.588880] [099/161] (750s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[352.202885] Starting subtest: basic-flip-after-cursor-varying-size
[353.819226] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.614s)
[355.128221] [100/161] (746s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[355.735204] Starting subtest: basic-flip-before-cursor-atomic
[357.344730] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.606s)
[358.655557] [101/161] (743s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[359.274539] Starting subtest: basic-flip-before-cursor-legacy
[361.690390] Subtest basic-flip-before-cursor-legacy: SUCCESS (2.410s)
[362.660446] [102/161] (740s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[363.281599] Starting subtest: basic-flip-before-cursor-varying-size
[364.894350] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.611s)
[366.198568] [103/161] (736s left) kms_dsc (dsc-basic)
[366.680050] Starting subtest: dsc-basic
[366.693520] Subtest dsc-basic: SKIP (0.003s)
[367.142082] [104/161] (736s left) kms_flip (basic-flip-vs-dpms)
[367.624152] Starting subtest: basic-flip-vs-dpms
[367.634803] Starting dynamic subtest: A-eDP1
[371.560139] Dynamic subtest A-eDP1: SUCCESS (3.925s)
[371.643137] Starting dynamic subtest: B-eDP1
[374.862117] Dynamic subtest B-eDP1: SUCCESS (3.293s)
[374.942178] Starting dynamic subtest: C-eDP1
[378.596915] Dynamic subtest C-eDP1: SUCCESS (3.732s)
[378.603100] Subtest basic-flip-vs-dpms: SUCCESS (10.981s)
[379.904099] [105/161] (724s left) kms_flip (basic-flip-vs-modeset)
[380.386397] Starting subtest: basic-flip-vs-modeset
[380.397785] Starting dynamic subtest: A-eDP1
[384.717335] Dynamic subtest A-eDP1: SUCCESS (4.319s)
[384.806798] Starting dynamic subtest: B-eDP1
[388.312593] Dynamic subtest B-eDP1: SUCCESS (3.587s)
[388.392698] Starting dynamic subtest: C-eDP1
[391.432266] Dynamic subtest C-eDP1: SUCCESS (3.115s)
[391.439793] Subtest basic-flip-vs-modeset: SUCCESS (11.056s)
[392.647893] [106/161] (711s left) kms_flip (basic-flip-vs-wf_vblank)
[393.129687] Starting subtest: basic-flip-vs-wf_vblank
[393.143177] Starting dynamic subtest: A-eDP1
[396.236605] Dynamic subtest A-eDP1: SUCCESS (3.093s)
[396.325887] Starting dynamic subtest: B-eDP1
[399.241715] Dynamic subtest B-eDP1: SUCCESS (2.999s)
[399.326220] Starting dynamic subtest: C-eDP1
[402.231327] Dynamic subtest C-eDP1: SUCCESS (2.984s)
[402.242056] Subtest basic-flip-vs-wf_vblank: SUCCESS (9.112s)
[404.274697] [107/161] (700s left) kms_flip (basic-plain-flip)
[404.757733] Starting subtest: basic-plain-flip
[404.770433] Starting dynamic subtest: A-eDP1
[407.563806] Dynamic subtest A-eDP1: SUCCESS (2.793s)
[407.648318] Starting dynamic subtest: B-eDP1
[410.865153] Dynamic subtest B-eDP1: SUCCESS (3.295s)
[410.951067] Starting dynamic subtest: C-eDP1
[413.556457] Dynamic subtest C-eDP1: SUCCESS (2.685s)
[413.566712] Subtest basic-plain-flip: SUCCESS (8.810s)
[415.751731] [108/161] (689s left) kms_force_connector_basic (force-connector-state)
[416.160252] Starting subtest: force-connector-state
[416.987683] Subtest force-connector-state: SUCCESS (0.830s)
[418.670397] [109/161] (686s left) kms_force_connector_basic (force-edid)
[419.072255] Starting subtest: force-edid
[419.171968] Subtest force-edid: SUCCESS (0.102s)
[419.799929] [110/161] (686s left) kms_force_connector_basic (force-load-detect)
[420.202687] Starting subtest: force-load-detect
[420.208703] Subtest force-load-detect: SKIP (0.000s)
[420.784404] [111/161] (685s left) kms_force_connector_basic (prune-stale-modes)
[421.185880] Starting subtest: prune-stale-modes
[421.292620] Subtest prune-stale-modes: SUCCESS (0.109s)
[421.947587] [112/161] (684s left) kms_frontbuffer_tracking (basic)
[422.470680] Starting subtest: basic
[449.967820] Subtest basic: SUCCESS (27.500s)
[450.554295] [113/161] (656s left) kms_hdmi_inject (inject-audio)
[451.366125] Starting subtest: inject-audio
[451.909189] Subtest inject-audio: SUCCESS (0.537s)
[452.991566] [114/161] (654s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[453.480977] Starting subtest: compare-crc-sanitycheck-xr24
[453.486202] Starting dynamic subtest: pipe-A-eDP-1
[455.324219] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.838s)
[455.437460] Starting dynamic subtest: pipe-B-eDP-1
[457.623168] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.279s)
[457.722741] Starting dynamic subtest: pipe-C-eDP-1
[460.535317] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.901s)
[460.538275] Subtest compare-crc-sanitycheck-xr24: SUCCESS (7.060s)
[461.608613] [115/161] (646s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[462.093314] Starting subtest: compare-crc-sanitycheck-nv12
[462.100769] Starting dynamic subtest: pipe-A-eDP-1
[465.060176] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.954s)
[465.153101] Starting dynamic subtest: pipe-B-eDP-1
[468.469489] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.395s)
[468.558242] Starting dynamic subtest: pipe-C-eDP-1
[471.761937] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.282s)
[471.764800] Subtest compare-crc-sanitycheck-nv12: SUCCESS (9.673s)
[473.025985] [116/161] (635s left) kms_pipe_crc_basic (hang-read-crc)
[473.513366] Starting subtest: hang-read-crc
[473.517837] Starting dynamic subtest: pipe-A-eDP-1
[478.651964] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.134s)
[478.745543] Starting dynamic subtest: pipe-B-eDP-1
[484.283357] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.615s)
[484.381617] Starting dynamic subtest: pipe-C-eDP-1
[490.449464] Dynamic subtest pipe-C-eDP-1: SUCCESS (6.149s)
[490.452438] Subtest hang-read-crc: SUCCESS (16.942s)
[491.710597] [117/161] (616s left) kms_pipe_crc_basic (nonblocking-crc)
[492.201969] Starting subtest: nonblocking-crc
[492.206374] Starting dynamic subtest: pipe-A-eDP-1
[494.248516] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.037s)
[494.349111] Starting dynamic subtest: pipe-B-eDP-1
[496.770125] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.510s)
[496.863518] Starting dynamic subtest: pipe-C-eDP-1
[499.176594] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.390s)
[499.179749] Subtest nonblocking-crc: SUCCESS (6.983s)
[500.475777] [118/161] (608s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[500.979579] Starting subtest: nonblocking-crc-frame-sequence
[500.984862] Starting dynamic subtest: pipe-A-eDP-1
[503.051212] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.063s)
[503.148227] Starting dynamic subtest: pipe-B-eDP-1
[505.560935] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.495s)
[505.658799] Starting dynamic subtest: pipe-C-eDP-1
[507.957464] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.378s)
[507.960613] Subtest nonblocking-crc-frame-sequence: SUCCESS (6.983s)
[509.258974] [119/161] (600s left) kms_pipe_crc_basic (read-crc)
[509.748227] Starting subtest: read-crc
[509.753345] Starting dynamic subtest: pipe-A-eDP-1
[512.520984] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.763s)
[512.617392] Starting dynamic subtest: pipe-B-eDP-1
[514.971715] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.434s)
[515.062781] Starting dynamic subtest: pipe-C-eDP-1
[518.097852] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.110s)
[518.100974] Subtest read-crc: SUCCESS (8.355s)
[519.086890] [120/161] (590s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[519.578282] Starting subtest: read-crc-frame-sequence
[519.590318] Starting dynamic subtest: pipe-A-eDP-1
[521.608186] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.023s)
[521.706861] Starting dynamic subtest: pipe-B-eDP-1
[524.043484] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.416s)
[524.133664] Starting dynamic subtest: pipe-C-eDP-1
[526.382872] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.322s)
[526.386420] Subtest read-crc-frame-sequence: SUCCESS (6.810s)
[527.643621] [121/161] (582s left) kms_pm_backlight (basic-brightness)
[528.132191] Starting subtest: basic-brightness
[528.617507] Starting dynamic subtest: eDP-1
[529.110725] Dynamic subtest eDP-1: SUCCESS (0.503s)
[529.113589] Subtest basic-brightness: SUCCESS (0.981s)
[530.353920] [122/161] (580s left) kms_pm_rpm (basic-pci-d3-state)
[532.868504] Starting subtest: basic-pci-d3-state
[534.820435] Subtest basic-pci-d3-state: SUCCESS (1.954s)
[535.456328] [123/161] (575s left) kms_pm_rpm (basic-rte)
[535.829865] Starting subtest: basic-rte
[539.763125] Subtest basic-rte: SUCCESS (3.936s)
[542.119444] [124/161] (569s left) kms_psr (psr-primary-page-flip)
[542.605327] Starting subtest: psr-primary-page-flip
[542.615814] Starting dynamic subtest: eDP-1
[545.395858] Dynamic subtest eDP-1: SUCCESS (2.780s)
[545.398742] Subtest psr-primary-page-flip: SUCCESS (2.792s)
[546.682940] [125/161] (564s left) kms_psr (psr-cursor-plane-move)
[547.162767] Starting subtest: psr-cursor-plane-move
[547.172897] Starting dynamic subtest: eDP-1
[549.713078] Dynamic subtest eDP-1: SUCCESS (2.539s)
[549.715700] Subtest psr-cursor-plane-move: SUCCESS (2.550s)
[551.012984] [126/161] (561s left) kms_psr (psr-sprite-plane-onoff)
[551.501638] Starting subtest: psr-sprite-plane-onoff
[551.504635] Starting dynamic subtest: eDP-1
[554.141664] Dynamic subtest eDP-1: SUCCESS (2.635s)
[554.145321] Subtest psr-sprite-plane-onoff: SUCCESS (2.647s)
[555.345233] [127/161] (557s left) kms_psr (psr-primary-mmap-gtt)
[555.833358] Starting subtest: psr-primary-mmap-gtt
[555.843688] Starting dynamic subtest: eDP-1
[558.614621] Dynamic subtest eDP-1: SUCCESS (2.769s)
[558.617355] Subtest psr-primary-mmap-gtt: SUCCESS (2.780s)
[559.901058] [128/161] (552s left) kms_setmode (basic-clone-single-crtc)
[560.288115] Starting subtest: basic-clone-single-crtc
[560.297751] Subtest basic-clone-single-crtc: SKIP (0.001s)
[560.761280] [129/161] (552s left) i915_pm_rps (basic-api)
[561.178629] Starting subtest: basic-api
[561.193098] Subtest basic-api: SKIP (0.005s)
[561.678062] [130/161] (551s left) prime_self_import (basic-llseek-bad)
[562.045650] Starting subtest: basic-llseek-bad
[562.052398] Subtest basic-llseek-bad: SUCCESS (0.014s)
[562.540652] [131/161] (551s left) prime_self_import (basic-llseek-size)
[562.905926] Starting subtest: basic-llseek-size
[562.928391] Subtest basic-llseek-size: SUCCESS (0.022s)
[563.407422] [132/161] (550s left) prime_self_import (basic-with_fd_dup)
[563.770938] Starting subtest: basic-with_fd_dup
[563.792370] Subtest basic-with_fd_dup: SUCCESS (0.021s)
[564.272926] [133/161] (550s left) prime_self_import (basic-with_one_bo)
[564.642145] Starting subtest: basic-with_one_bo
[564.655908] Subtest basic-with_one_bo: SUCCESS (0.022s)
[565.160180] [134/161] (549s left) prime_self_import (basic-with_one_bo_two_files)
[565.527543] Starting subtest: basic-with_one_bo_two_files
[565.550925] Subtest basic-with_one_bo_two_files: SUCCESS (0.019s)
[566.028691] [135/161] (549s left) prime_self_import (basic-with_two_bos)
[566.401276] Starting subtest: basic-with_two_bos
[566.415966] Subtest basic-with_two_bos: SUCCESS (0.021s)
[566.917994] [136/161] (549s left) prime_vgem (basic-fence-flip)
[567.373240] Starting subtest: basic-fence-flip
[568.816247] Subtest basic-fence-flip: SUCCESS (1.449s)
[569.422622] [137/161] (546s left) prime_vgem (basic-fence-mmap)
[569.866328] Starting subtest: basic-fence-mmap
[570.065689] Subtest basic-fence-mmap: SUCCESS (0.197s)
[570.550121] [138/161] (546s left) prime_vgem (basic-fence-read)
[570.987621] Starting subtest: basic-fence-read
[571.039697] Subtest basic-fence-read: SKIP (0.056s)
[571.533915] [139/161] (545s left) prime_vgem (basic-gtt)
[571.987162] Starting subtest: basic-gtt
[572.068901] Subtest basic-gtt: SUCCESS (0.088s)
[572.560932] [140/161] (544s left) prime_vgem (basic-read)
[573.006744] Starting subtest: basic-read
[573.057664] Subtest basic-read: SKIP (0.055s)
[573.575236] [141/161] (544s left) prime_vgem (basic-write)
[574.021076] Starting subtest: basic-write
[574.077768] Subtest basic-write: SKIP (0.054s)
[574.620968] [142/161] (543s left) vgem_basic (setversion)
[575.036495] Starting subtest: setversion
[575.042574] Subtest setversion: SUCCESS (0.000s)
[575.487184] [143/161] (543s left) vgem_basic (create)
[575.897709] Starting subtest: create
[575.907120] Subtest create: SUCCESS (0.002s)
[576.341339] [144/161] (542s left) vgem_basic (debugfs)
[576.747214] Starting subtest: debugfs
[576.750283] Subtest debugfs: SUCCESS (0.002s)
[577.213578] [145/161] (542s left) vgem_basic (dmabuf-export)
[577.632815] Starting subtest: dmabuf-export
[577.650619] Subtest dmabuf-export: SUCCESS (0.018s)
[578.129330] [146/161] (541s left) vgem_basic (dmabuf-fence)
[578.537174] Starting subtest: dmabuf-fence
[578.544934] Subtest dmabuf-fence: SUCCESS (0.003s)
[579.002289] [147/161] (541s left) vgem_basic (dmabuf-fence-before)
[579.408492] Starting subtest: dmabuf-fence-before
[579.411992] Subtest dmabuf-fence-before: SUCCESS (0.003s)
[579.878294] [148/161] (540s left) vgem_basic (dmabuf-mmap)
[580.281890] Starting subtest: dmabuf-mmap
[580.374795] Subtest dmabuf-mmap: SUCCESS (0.099s)
[580.825414] [149/161] (540s left) vgem_basic (mmap)
[581.227853] Starting subtest: mmap
[581.293762] Subtest mmap: SUCCESS (0.069s)
[581.761122] [150/161] (539s left) vgem_basic (second-client)
[582.242081] Starting subtest: second-client
[582.293204] Subtest second-client: SUCCESS (0.047s)
[582.743086] [151/161] (539s left) vgem_basic (sysfs)
[583.139614] Starting subtest: sysfs
[583.142832] Subtest sysfs: SUCCESS (0.004s)
[583.604455] [152/161] (538s left) core_hotunplug (unbind-rebind)
[584.293816] Starting subtest: unbind-rebind
[593.232248] Subtest unbind-rebind: SUCCESS (8.938s)
[593.637451] [153/161] (529s left) vgem_basic (unload)
[594.012650] Starting subtest: unload
[623.730177] Subtest unload: SUCCESS (29.683s)
[624.355841] [154/161] (498s left) i915_module_load (reload)
[624.727871] Starting subtest: reload
[639.311708] Subtest reload: SUCCESS (14.579s)
[639.828418] [155/161] (483s left) gem_lmem_swapping (basic)
[640.452395] Subtest basic: SKIP (0.000s)
[640.913868] [156/161] (483s left) gem_lmem_swapping (parallel-random-engines)
[641.321959] Subtest parallel-random-engines: SKIP (0.000s)
[641.753482] [157/161] (482s left) gem_lmem_swapping (random-engines)
[642.164261] Subtest random-engines: SKIP (0.000s)
[642.596163] [158/161] (482s left) gem_lmem_swapping (verify-random)
[643.007590] Subtest verify-random: SKIP (0.000s)
[643.429599] [159/161] (481s left) i915_pm_rpm (module-reload)
[646.834496] Starting subtest: module-reload
[680.529736] Subtest module-reload: SUCCESS (33.691s)
[680.805199] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[680.818200] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[680.843275] [160/161] (444s left) i915_selftest (live)
[681.232701] Starting subtest: live
[681.243534] Starting dynamic subtest: sanitycheck
[691.600189] Dynamic subtest sanitycheck: SUCCESS (10.350s)
[691.664231] Starting dynamic subtest: uncore
[702.016207] Dynamic subtest uncore: SUCCESS (10.410s)
[702.082633] Starting dynamic subtest: workarounds
[714.990781] Dynamic subtest workarounds: SUCCESS (12.965s)
[715.059314] Starting dynamic subtest: gt_engines
[726.020793] Dynamic subtest gt_engines: SUCCESS (11.029s)
[726.096301] Starting dynamic subtest: gt_timelines
[770.161387] Dynamic subtest gt_timelines: SUCCESS (44.129s)
[770.254388] Starting dynamic subtest: gt_contexts
[780.712223] Dynamic subtest gt_contexts: SUCCESS (10.530s)
[780.778830] Starting dynamic subtest: gt_lrc
[794.726210] Dynamic subtest gt_lrc: SUCCESS (14.005s)
[794.792290] Starting dynamic subtest: gt_mocs
[806.293192] Dynamic subtest gt_mocs: SUCCESS (11.558s)
[806.363136] Starting dynamic subtest: gt_pm
[818.906219] Dynamic subtest gt_pm: SUCCESS (12.604s)
[818.971624] Starting dynamic subtest: gt_heartbeat
[829.334228] Dynamic subtest gt_heartbeat: SUCCESS (10.416s)
[829.422431] Starting dynamic subtest: gt_tlb
[840.974105] Dynamic subtest gt_tlb: SUCCESS (11.632s)
[841.043406] Starting dynamic subtest: requests
[861.413202] Dynamic subtest requests: SUCCESS (20.428s)
[861.483821] Starting dynamic subtest: migrate
[875.794079] Dynamic subtest migrate: SUCCESS (14.371s)
[875.884479] Starting dynamic subtest: active
[886.313344] Dynamic subtest active: SUCCESS (10.512s)
[886.376242] Starting dynamic subtest: objects
[901.062356] Dynamic subtest objects: SUCCESS (14.740s)
[901.129238] Starting dynamic subtest: mman
[922.816280] Dynamic subtest mman: SUCCESS (21.751s)
[922.896255] Starting dynamic subtest: dmabuf
[933.800611] Dynamic subtest dmabuf: SUCCESS (10.968s)
[933.869506] Starting dynamic subtest: vma
[944.822451] Dynamic subtest vma: SUCCESS (11.008s)
[944.895795] Starting dynamic subtest: coherency
[973.868916] Dynamic subtest coherency: SUCCESS (29.041s)
[973.945430] Starting dynamic subtest: gtt
[992.413797] Dynamic subtest gtt: SUCCESS (18.535s)
[992.486633] Starting dynamic subtest: gem
[1004.159452] Dynamic subtest gem: SUCCESS (11.737s)
[1004.224574] Starting dynamic subtest: evict
[1022.481613] Dynamic subtest evict: SUCCESS (18.313s)
[1022.549983] Starting dynamic subtest: hugepages
[1039.607919] Dynamic subtest hugepages: SUCCESS (17.117s)
[1039.679551] Starting dynamic subtest: gem_contexts
[1079.983591] Dynamic subtest gem_contexts: SUCCESS (40.374s)
[1080.063681] Starting dynamic subtest: client
[1090.706370] Dynamic subtest client: SUCCESS (10.702s)
[1090.775279] Starting dynamic subtest: gem_migrate
[1101.157373] Dynamic subtest gem_migrate: SUCCESS (10.442s)
[1101.228164] Starting dynamic subtest: reset
[1115.180315] Dynamic subtest reset: SUCCESS (14.005s)
[1115.250069] Starting dynamic subtest: memory_region
[1125.611471] Dynamic subtest memory_region: SUCCESS (10.417s)
[1125.684143] Starting dynamic subtest: hangcheck
[1156.664721] Dynamic subtest hangcheck: SUCCESS (31.042s)
[1156.737677] Starting dynamic subtest: execlists
[1167.146364] Dynamic subtest execlists: SUCCESS (10.474s)
[1167.215266] Starting dynamic subtest: ring_submission
[1177.766922] Dynamic subtest ring_submission: SUCCESS (10.610s)
[1177.832870] Starting dynamic subtest: perf
[1188.709613] Dynamic subtest perf: SUCCESS (10.935s)
[1188.780359] Starting dynamic subtest: slpc
[1200.983922] Dynamic subtest slpc: SUCCESS (12.257s)
[1201.054401] Starting dynamic subtest: guc
[1214.257767] Dynamic subtest guc: SUCCESS (13.268s)
[1214.323260] Starting dynamic subtest: guc_multi_lrc
[1224.692384] Dynamic subtest guc_multi_lrc: SUCCESS (10.417s)
[1224.764271] Starting dynamic subtest: guc_hang
[1236.480233] Dynamic subtest guc_hang: SUCCESS (11.780s)
[1236.549055] Starting dynamic subtest: late_gt_pm
[1247.043323] Dynamic subtest late_gt_pm: SUCCESS (10.552s)
[1247.046328] Subtest live: SUCCESS (565.784s)
[1247.162099] Overall timeout time exceeded, stopping.
[1247.322202] Closing watchdogs
Done.