[122.526429] Initializing watchdogs
[122.527414]   /dev/watchdog0
[122.688516] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:46d3 Intel Alderlake_n (Gen12) 
[122.727565] [001/161] (960s left) i915_module_load (load)
[123.872446] Starting subtest: load
[135.114103] Subtest load: SUCCESS (11.238s)
[135.502778] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[135.517750] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[135.538252] [002/161] (948s left) core_auth (basic-auth)
[136.118734] Starting subtest: basic-auth
[136.124962] Subtest basic-auth: SUCCESS (0.008s)
[136.592705] [003/161] (947s left) debugfs_test (read_all_entries)
[137.037364] Starting subtest: read_all_entries
[138.528821] Subtest read_all_entries: SUCCESS (1.492s)
[139.066731] [004/161] (945s left) debugfs_test (basic-hwmon)
[139.474097] Starting subtest: basic-hwmon
[139.480124] Subtest basic-hwmon: SKIP (0.000s)
[139.975809] [005/161] (944s left) debugfs_test (sysfs)
[140.396042] Starting subtest: sysfs
[140.866068] Subtest sysfs: SUCCESS (0.471s)
[141.377478] [006/161] (943s left) fbdev (eof)
[141.826844] Starting subtest: eof
[141.831084] Subtest eof: SUCCESS (0.006s)
[142.248711] [007/161] (943s left) fbdev (info)
[142.608993] Starting subtest: info
[142.612363] Subtest info: SUCCESS (0.000s)
[143.052763] [008/161] (942s left) fbdev (nullptr)
[143.428287] Starting subtest: nullptr
[143.431220] Subtest nullptr: SUCCESS (0.000s)
[143.854479] [009/161] (942s left) fbdev (read)
[144.229742] Starting subtest: read
[157.925215] Subtest read: SUCCESS (13.660s)
[158.327845] [010/161] (927s left) fbdev (write)
[158.703819] Starting subtest: write
[165.831793] Subtest write: SUCCESS (7.121s)
[166.283724] [011/161] (920s left) gem_basic (bad-close)
[166.652785] Starting subtest: bad-close
[166.658641] Subtest bad-close: SUCCESS (0.000s)
[167.065698] [012/161] (919s left) gem_basic (create-close)
[167.431124] Starting subtest: create-close
[167.438154] Subtest create-close: SUCCESS (0.001s)
[167.860595] [013/161] (919s left) gem_basic (create-fd-close)
[168.231613] Starting subtest: create-fd-close
[168.237508] Subtest create-fd-close: SUCCESS (0.001s)
[168.656185] [014/161] (918s left) gem_busy (busy@all-engines)
[169.120210] Starting subtest: busy
[169.123401] Starting dynamic subtest: all-engines
[169.478341] Dynamic subtest all-engines: SUCCESS (0.348s)
[169.481901] Subtest busy: SUCCESS (0.350s)
[170.329178] [015/161] (917s left) gem_close_race (basic-process)
[170.799274] Starting subtest: basic-process
[170.964888] Subtest basic-process: SUCCESS (0.165s)
[171.514390] [016/161] (916s left) gem_close_race (basic-threads)
[171.985749] Starting subtest: basic-threads
[173.438096] Subtest basic-threads: SUCCESS (1.453s)
[173.985528] [017/161] (914s left) gem_ctx_create (basic)
[174.447070] Starting subtest: basic
[174.451597] Subtest basic: SUCCESS (0.000s)
[174.999477] [018/161] (913s left) gem_ctx_create (basic-files)
[175.454204] Starting subtest: basic-files
[177.573808] Subtest basic-files: SUCCESS (2.118s)
[178.233709] [019/161] (911s left) gem_ctx_exec (basic)
[178.622694] Starting subtest: basic
[178.643243] Subtest basic: SUCCESS (0.022s)
[179.071676] [020/161] (910s left) gem_exec_basic (basic)
[179.492599] Starting subtest: basic
[179.495710] Starting dynamic subtest: rcs0-smem
[179.504312] Dynamic subtest rcs0-smem: SUCCESS (0.009s)
[179.563000] Starting dynamic subtest: bcs0-smem
[179.565981] Dynamic subtest bcs0-smem: SUCCESS (0.003s)
[179.624647] Starting dynamic subtest: vcs0-smem
[179.627774] Dynamic subtest vcs0-smem: SUCCESS (0.003s)
[179.690763] Starting dynamic subtest: vecs0-smem
[179.693698] Dynamic subtest vecs0-smem: SUCCESS (0.003s)
[179.696870] Subtest basic: SUCCESS (0.028s)
[180.132112] [021/161] (909s left) gem_exec_create (basic)
[180.587998] Starting subtest: basic
[180.591100] Starting dynamic subtest: smem
[182.803626] Dynamic subtest smem: SUCCESS (2.216s)
[182.806914] Subtest basic: SUCCESS (2.218s)
[183.360631] [022/161] (906s left) gem_exec_fence (basic-busy)
[184.021051] Starting subtest: basic-busy
[184.024400] Starting dynamic subtest: rcs0
[184.120870] Dynamic subtest rcs0: SUCCESS (0.102s)
[184.181190] Starting dynamic subtest: bcs0
[184.187649] Dynamic subtest bcs0: SUCCESS (0.065s)
[184.248158] Starting dynamic subtest: vcs0
[184.258706] Dynamic subtest vcs0: SUCCESS (0.065s)
[184.321647] Starting dynamic subtest: vecs0
[184.327545] Dynamic subtest vecs0: SUCCESS (0.064s)
[184.330640] Subtest basic-busy: SUCCESS (0.304s)
[185.787497] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[185.807888] [023/161] (904s left) gem_exec_fence (basic-wait)
[186.472757] Starting subtest: basic-wait
[186.476408] Starting dynamic subtest: rcs0
[186.567224] Dynamic subtest rcs0: SUCCESS (0.094s)
[186.628581] Starting dynamic subtest: bcs0
[186.631935] Dynamic subtest bcs0: SUCCESS (0.063s)
[186.694445] Starting dynamic subtest: vcs0
[186.698185] Dynamic subtest vcs0: SUCCESS (0.060s)
[186.761870] Starting dynamic subtest: vecs0
[186.764804] Dynamic subtest vecs0: SUCCESS (0.063s)
[186.767900] Subtest basic-wait: SUCCESS (0.288s)
[188.071704] [024/161] (902s left) gem_exec_fence (basic-await)
[188.740375] Starting subtest: basic-await
[188.743718] Starting dynamic subtest: rcs0
[189.073474] Dynamic subtest rcs0: SUCCESS (0.334s)
[189.139676] Starting dynamic subtest: bcs0
[189.382794] Dynamic subtest bcs0: SUCCESS (0.305s)
[189.450327] Starting dynamic subtest: vcs0
[189.687828] Dynamic subtest vcs0: SUCCESS (0.305s)
[189.751198] Starting dynamic subtest: vecs0
[189.994961] Dynamic subtest vecs0: SUCCESS (0.306s)
[189.998289] Subtest basic-await: SUCCESS (1.260s)
[191.302617] [025/161] (900s left) gem_exec_fence (nb-await)
[191.958956] Starting subtest: nb-await
[191.962314] Starting dynamic subtest: rcs0
[192.070771] Dynamic subtest rcs0: SUCCESS (0.114s)
[192.132416] Starting dynamic subtest: bcs0
[192.164954] Dynamic subtest bcs0: SUCCESS (0.093s)
[192.228425] Starting dynamic subtest: vcs0
[192.254911] Dynamic subtest vcs0: SUCCESS (0.088s)
[192.318255] Starting dynamic subtest: vecs0
[192.343087] Dynamic subtest vecs0: SUCCESS (0.086s)
[192.346158] Subtest nb-await: SUCCESS (0.388s)
[193.622830] [026/161] (898s left) gem_exec_gttfill (basic)
[194.085334] Starting subtest: basic
[231.981163] Subtest basic: SUCCESS (37.860s)
[232.582848] [027/161] (859s left) gem_exec_parallel (engines)
[233.221306] Starting subtest: engines
[233.224489] Starting dynamic subtest: basic
[240.047576] Dynamic subtest basic: SUCCESS (6.827s)
[240.115338] Starting dynamic subtest: contexts
[250.810974] Dynamic subtest contexts: SUCCESS (10.749s)
[250.890421] Starting dynamic subtest: fds
[263.101540] Dynamic subtest fds: SUCCESS (12.291s)
[263.172404] Starting dynamic subtest: userptr
[268.899212] Dynamic subtest userptr: SUCCESS (5.805s)
[268.902856] Subtest engines: SUCCESS (35.681s)
[269.543478] [028/161] (822s left) gem_exec_store (basic)
[270.009988] Starting subtest: basic
[270.173733] Subtest basic: SUCCESS (0.165s)
[270.771455] [029/161] (822s left) gem_flink_basic (bad-flink)
[271.137758] Starting subtest: bad-flink
[271.141608] Subtest bad-flink: SUCCESS (0.000s)
[271.589404] [030/161] (821s left) gem_flink_basic (bad-open)
[271.956863] Starting subtest: bad-open
[271.960768] Subtest bad-open: SUCCESS (0.000s)
[272.408363] [031/161] (821s left) gem_flink_basic (basic)
[272.780068] Starting subtest: basic
[272.783758] Subtest basic: SUCCESS (0.001s)
[273.257215] [032/161] (820s left) gem_flink_basic (double-flink)
[273.627385] Starting subtest: double-flink
[273.631126] Subtest double-flink: SUCCESS (0.001s)
[274.097405] [033/161] (820s left) gem_flink_basic (flink-lifetime)
[274.472161] Starting subtest: flink-lifetime
[274.480648] Subtest flink-lifetime: SUCCESS (0.010s)
[274.950417] [034/161] (819s left) gem_huc_copy (huc-copy)
[275.361950] Starting subtest: huc-copy
[275.383689] Subtest huc-copy: SUCCESS (0.020s)
[275.870779] [035/161] (819s left) gem_linear_blits (basic)
[276.909816] Starting subtest: basic
[276.972737] Subtest basic: SUCCESS (0.062s)
[277.538809] [036/161] (817s left) gem_mmap (basic)
[278.105991] Starting subtest: basic
[278.120079] Subtest basic: SUCCESS (0.003s)
[278.575300] [037/161] (817s left) gem_mmap_gtt (basic)
[278.948884] Starting subtest: basic
[278.952314] Subtest basic: SUCCESS (0.005s)
[279.409641] [038/161] (816s left) gem_render_linear_blits (basic)
[279.809808] Starting subtest: basic
[280.048518] Subtest basic: SUCCESS (0.237s)
[280.504512] [039/161] (816s left) gem_render_tiled_blits (basic)
[280.934135] Starting subtest: basic
[281.223745] Subtest basic: SUCCESS (0.288s)
[281.675123] [040/161] (815s left) gem_ringfill (basic-all)
[282.556302] Starting subtest: basic-all
[284.004707] Subtest basic-all: SUCCESS (1.442s)
[284.459401] [041/161] (812s left) gem_softpin (allocator-basic)
[284.860894] Starting subtest: allocator-basic
[285.540912] Subtest allocator-basic: SUCCESS (0.671s)
[286.073982] [042/161] (811s left) gem_softpin (allocator-basic-reserve)
[286.478792] Starting subtest: allocator-basic-reserve
[287.165943] Subtest allocator-basic-reserve: SUCCESS (0.681s)
[287.717470] [043/161] (810s left) gem_softpin (safe-alignment)
[288.123711] Starting subtest: safe-alignment
[288.204079] Subtest safe-alignment: SUCCESS (0.080s)
[288.707702] [044/161] (809s left) gem_sync (basic-all)
[289.168273] Starting subtest: basic-all
[294.417195] Subtest basic-all: SUCCESS (5.249s)
[295.014487] [045/161] (803s left) gem_sync (basic-each)
[295.476345] Starting subtest: basic-each
[299.096834] Subtest basic-each: SUCCESS (3.620s)
[299.697549] [046/161] (799s left) gem_tiled_blits (basic)
[300.644311] Starting subtest: basic
[300.777057] Subtest basic: SUCCESS (0.136s)
[301.307863] [047/161] (798s left) gem_tiled_fence_blits (basic)
[302.509372] Starting subtest: basic
[302.572949] Subtest basic: SUCCESS (0.076s)
[303.129332] [048/161] (796s left) gem_tiled_pread_basic
[304.116918] [049/161] (796s left) gem_wait (busy@all-engines)
[304.644813] Starting subtest: busy
[304.647777] Starting dynamic subtest: all-engines
[305.229244] Dynamic subtest all-engines: SUCCESS (0.595s)
[305.232167] Subtest busy: SUCCESS (0.597s)
[305.955438] [050/161] (794s left) gem_wait (wait@all-engines)
[306.469730] Starting subtest: wait
[306.472707] Starting dynamic subtest: all-engines
[307.575914] Dynamic subtest all-engines: SUCCESS (1.117s)
[307.579346] Subtest wait: SUCCESS (1.119s)
[308.300205] [051/161] (792s left) i915_getparams_basic (basic-eu-total)
[308.670339] Starting subtest: basic-eu-total
[308.676707] Subtest basic-eu-total: SUCCESS (0.000s)
[309.129700] [052/161] (792s left) i915_getparams_basic (basic-subslice-total)
[309.499154] Starting subtest: basic-subslice-total
[309.505579] Subtest basic-subslice-total: SUCCESS (0.000s)
[309.956591] [053/161] (791s left) i915_hangman (error-state-basic)
[310.409380] Starting subtest: error-state-basic
[311.255292] Subtest error-state-basic: SUCCESS (0.844s)
[311.753223] [054/161] (790s left) i915_pciid
[312.577249] [055/161] (790s left) kms_addfb_basic (addfb25-4-tiled)
[313.087801] Starting subtest: addfb25-4-tiled
[313.091618] Subtest addfb25-4-tiled: SUCCESS (0.002s)
[313.558821] [056/161] (789s left) kms_addfb_basic (addfb25-bad-modifier)
[314.040350] Starting subtest: addfb25-bad-modifier
[314.043498] Subtest addfb25-bad-modifier: SUCCESS (0.002s)
[314.515399] [057/161] (788s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[314.996576] Starting subtest: addfb25-framebuffer-vs-set-tiling
[314.999711] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.002s)
[315.472597] [058/161] (788s left) kms_addfb_basic (addfb25-modifier-no-flag)
[315.951519] Starting subtest: addfb25-modifier-no-flag
[315.954677] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[316.423487] [059/161] (787s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[316.903493] Starting subtest: addfb25-x-tiled-legacy
[316.906813] Subtest addfb25-x-tiled-legacy: SUCCESS (0.001s)
[317.379426] [060/161] (787s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[317.861952] Starting subtest: addfb25-x-tiled-mismatch-legacy
[317.865909] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.002s)
[318.338782] [061/161] (786s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[318.819476] Starting subtest: addfb25-yf-tiled-legacy
[318.823165] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.002s)
[319.301202] [062/161] (785s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[319.780309] Starting subtest: addfb25-y-tiled-legacy
[319.783302] Subtest addfb25-y-tiled-legacy: SUCCESS (0.001s)
[320.248301] [063/161] (785s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[320.728408] Starting subtest: addfb25-y-tiled-small-legacy
[320.731967] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.002s)
[321.224554] [064/161] (784s left) kms_addfb_basic (bad-pitch-0)
[321.604677] Starting subtest: bad-pitch-0
[321.607665] Subtest bad-pitch-0: SUCCESS (0.000s)
[322.182464] [065/161] (784s left) kms_addfb_basic (bad-pitch-1024)
[322.554940] Starting subtest: bad-pitch-1024
[322.557773] Subtest bad-pitch-1024: SUCCESS (0.000s)
[323.123872] [066/161] (783s left) kms_addfb_basic (bad-pitch-128)
[323.496753] Starting subtest: bad-pitch-128
[323.499892] Subtest bad-pitch-128: SUCCESS (0.000s)
[324.072837] [067/161] (783s left) kms_addfb_basic (bad-pitch-256)
[324.446433] Starting subtest: bad-pitch-256
[324.449858] Subtest bad-pitch-256: SUCCESS (0.000s)
[325.020368] [068/161] (782s left) kms_addfb_basic (bad-pitch-32)
[325.390708] Starting subtest: bad-pitch-32
[325.394198] Subtest bad-pitch-32: SUCCESS (0.000s)
[326.008890] [069/161] (781s left) kms_addfb_basic (bad-pitch-63)
[326.384500] Starting subtest: bad-pitch-63
[326.387615] Subtest bad-pitch-63: SUCCESS (0.000s)
[326.971493] [070/161] (781s left) kms_addfb_basic (bad-pitch-65536)
[327.344943] Starting subtest: bad-pitch-65536
[327.348271] Subtest bad-pitch-65536: SUCCESS (0.002s)
[327.928902] [071/161] (780s left) kms_addfb_basic (bad-pitch-999)
[328.302214] Starting subtest: bad-pitch-999
[328.305238] Subtest bad-pitch-999: SUCCESS (0.000s)
[328.881464] [072/161] (780s left) kms_addfb_basic (basic)
[329.256873] Starting subtest: basic
[329.259805] Subtest basic: SUCCESS (0.000s)
[329.835765] [073/161] (779s left) kms_addfb_basic (basic-x-tiled-legacy)
[330.214472] Starting subtest: basic-x-tiled-legacy
[330.217325] Subtest basic-x-tiled-legacy: SUCCESS (0.001s)
[330.779715] [074/161] (779s left) kms_addfb_basic (basic-y-tiled-legacy)
[331.161268] Starting subtest: basic-y-tiled-legacy
[331.163983] Subtest basic-y-tiled-legacy: SUCCESS (0.002s)
[331.731443] [075/161] (778s left) kms_addfb_basic (bo-too-small)
[332.207838] Starting subtest: bo-too-small
[332.211350] Subtest bo-too-small: SUCCESS (0.002s)
[332.682496] [076/161] (777s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[333.164578] Starting subtest: bo-too-small-due-to-tiling
[333.167638] Subtest bo-too-small-due-to-tiling: SUCCESS (0.002s)
[333.645586] [077/161] (777s left) kms_addfb_basic (clobberred-modifier)
[334.020175] Starting subtest: clobberred-modifier
[334.023149] Subtest clobberred-modifier: SUCCESS (0.001s)
[334.596116] [078/161] (776s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[334.975362] Starting subtest: framebuffer-vs-set-tiling
[334.978483] Subtest framebuffer-vs-set-tiling: SUCCESS (0.001s)
[335.555341] [079/161] (776s left) kms_addfb_basic (invalid-get-prop)
[335.929625] Starting subtest: invalid-get-prop
[335.932784] Subtest invalid-get-prop: SUCCESS (0.000s)
[336.510829] [080/161] (775s left) kms_addfb_basic (invalid-get-prop-any)
[336.901275] Starting subtest: invalid-get-prop-any
[336.904369] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[337.483074] [081/161] (774s left) kms_addfb_basic (invalid-set-prop)
[337.865966] Starting subtest: invalid-set-prop
[337.868975] Subtest invalid-set-prop: SUCCESS (0.000s)
[338.445305] [082/161] (774s left) kms_addfb_basic (invalid-set-prop-any)
[338.821842] Starting subtest: invalid-set-prop-any
[338.825140] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[339.390660] [083/161] (773s left) kms_addfb_basic (no-handle)
[339.767843] Starting subtest: no-handle
[339.770881] Subtest no-handle: SUCCESS (0.000s)
[340.351698] [084/161] (773s left) kms_addfb_basic (size-max)
[340.877759] Starting subtest: size-max
[340.883621] Subtest size-max: SUCCESS (0.001s)
[341.353643] [085/161] (772s left) kms_addfb_basic (small-bo)
[341.827995] Starting subtest: small-bo
[341.831408] Subtest small-bo: SUCCESS (0.000s)
[342.306899] [086/161] (772s left) kms_addfb_basic (tile-pitch-mismatch)
[342.680395] Starting subtest: tile-pitch-mismatch
[342.683871] Subtest tile-pitch-mismatch: SUCCESS (0.002s)
[343.265780] [087/161] (771s left) kms_addfb_basic (too-high)
[343.744342] Starting subtest: too-high
[343.751942] Subtest too-high: SUCCESS (0.006s)
[344.229163] [088/161] (770s left) kms_addfb_basic (too-wide)
[344.708593] Starting subtest: too-wide
[344.711585] Subtest too-wide: SUCCESS (0.000s)
[345.193678] [089/161] (770s left) kms_addfb_basic (unused-handle)
[345.566509] Starting subtest: unused-handle
[345.569717] Subtest unused-handle: SUCCESS (0.000s)
[346.143557] [090/161] (769s left) kms_addfb_basic (unused-modifier)
[346.511078] Starting subtest: unused-modifier
[346.514155] Subtest unused-modifier: SUCCESS (0.000s)
[347.079586] [091/161] (769s left) kms_addfb_basic (unused-offsets)
[347.456268] Starting subtest: unused-offsets
[347.459225] Subtest unused-offsets: SUCCESS (0.000s)
[348.029678] [092/161] (768s left) kms_addfb_basic (unused-pitches)
[348.401588] Starting subtest: unused-pitches
[348.404458] Subtest unused-pitches: SUCCESS (0.000s)
[348.983409] [093/161] (768s left) kms_busy (basic)
[349.489391] Starting subtest: basic
[349.493472] Starting dynamic subtest: flip
[351.789690] Dynamic subtest flip: SUCCESS (2.289s)
[351.874281] Starting dynamic subtest: modeset
[356.985739] Dynamic subtest modeset: SUCCESS (5.198s)
[356.988798] Subtest basic: SUCCESS (7.498s)
[358.296831] [094/161] (759s left) kms_prop_blob (basic)
[358.676117] Starting subtest: basic
[358.679350] Subtest basic: SUCCESS (0.000s)
[359.133965] [095/161] (758s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[359.762179] Starting subtest: basic-busy-flip-before-cursor-atomic
[359.767374] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[360.357475] [096/161] (757s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[360.969796] Starting subtest: basic-busy-flip-before-cursor-legacy
[360.975078] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[361.569210] [097/161] (756s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[362.182677] Starting subtest: basic-flip-after-cursor-atomic
[363.799725] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.615s)
[365.100397] [098/161] (753s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[365.715771] Starting subtest: basic-flip-after-cursor-legacy
[367.323199] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.605s)
[368.626350] [099/161] (750s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[369.237169] Starting subtest: basic-flip-after-cursor-varying-size
[371.671673] Subtest basic-flip-after-cursor-varying-size: SUCCESS (2.423s)
[372.691426] [100/161] (747s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[373.303721] Starting subtest: basic-flip-before-cursor-atomic
[375.724217] Subtest basic-flip-before-cursor-atomic: SUCCESS (2.410s)
[376.741086] [101/161] (743s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[377.353840] Starting subtest: basic-flip-before-cursor-legacy
[378.962999] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.607s)
[380.272443] [102/161] (740s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[380.884758] Starting subtest: basic-flip-before-cursor-varying-size
[382.495733] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.609s)
[383.796113] [103/161] (737s left) kms_dsc (dsc-basic)
[384.277696] Starting subtest: dsc-basic
[384.292067] Subtest dsc-basic: SKIP (0.003s)
[384.743928] [104/161] (736s left) kms_flip (basic-flip-vs-dpms)
[385.223145] Starting subtest: basic-flip-vs-dpms
[385.233874] Starting dynamic subtest: A-eDP1
[389.142171] Dynamic subtest A-eDP1: SUCCESS (3.906s)
[389.224208] Starting dynamic subtest: B-eDP1
[392.445173] Dynamic subtest B-eDP1: SUCCESS (3.296s)
[392.526626] Starting dynamic subtest: C-eDP1
[395.698868] Dynamic subtest C-eDP1: SUCCESS (3.251s)
[395.705717] Subtest basic-flip-vs-dpms: SUCCESS (10.486s)
[396.982203] [105/161] (724s left) kms_flip (basic-flip-vs-modeset)
[397.467876] Starting subtest: basic-flip-vs-modeset
[397.478983] Starting dynamic subtest: A-eDP1
[401.347303] Dynamic subtest A-eDP1: SUCCESS (3.866s)
[401.434555] Starting dynamic subtest: B-eDP1
[404.454917] Dynamic subtest B-eDP1: SUCCESS (3.096s)
[404.536277] Starting dynamic subtest: C-eDP1
[407.567962] Dynamic subtest C-eDP1: SUCCESS (3.110s)
[407.575765] Subtest basic-flip-vs-modeset: SUCCESS (10.110s)
[408.789191] [106/161] (713s left) kms_flip (basic-flip-vs-wf_vblank)
[409.271292] Starting subtest: basic-flip-vs-wf_vblank
[409.282179] Starting dynamic subtest: A-eDP1
[412.270356] Dynamic subtest A-eDP1: SUCCESS (2.988s)
[412.359094] Starting dynamic subtest: B-eDP1
[415.683827] Dynamic subtest B-eDP1: SUCCESS (3.406s)
[415.780346] Starting dynamic subtest: C-eDP1
[418.672201] Dynamic subtest C-eDP1: SUCCESS (2.979s)
[418.680984] Subtest basic-flip-vs-wf_vblank: SUCCESS (9.413s)
[420.937541] [107/161] (701s left) kms_flip (basic-plain-flip)
[421.422122] Starting subtest: basic-plain-flip
[421.435114] Starting dynamic subtest: A-eDP1
[424.220933] Dynamic subtest A-eDP1: SUCCESS (2.786s)
[424.309371] Starting dynamic subtest: B-eDP1
[426.916180] Dynamic subtest B-eDP1: SUCCESS (2.689s)
[427.002223] Starting dynamic subtest: C-eDP1
[429.694454] Dynamic subtest C-eDP1: SUCCESS (2.771s)
[429.705176] Subtest basic-plain-flip: SUCCESS (8.285s)
[431.926548] [108/161] (690s left) kms_force_connector_basic (force-connector-state)
[432.335895] Starting subtest: force-connector-state
[433.200943] Subtest force-connector-state: SUCCESS (0.867s)
[434.945784] [109/161] (688s left) kms_force_connector_basic (force-edid)
[435.357222] Starting subtest: force-edid
[435.470408] Subtest force-edid: SUCCESS (0.108s)
[436.107835] [110/161] (687s left) kms_force_connector_basic (force-load-detect)
[436.515455] Starting subtest: force-load-detect
[436.521625] Subtest force-load-detect: SKIP (0.000s)
[437.115158] [111/161] (686s left) kms_force_connector_basic (prune-stale-modes)
[437.521759] Starting subtest: prune-stale-modes
[437.637298] Subtest prune-stale-modes: SUCCESS (0.110s)
[438.285468] [112/161] (686s left) kms_frontbuffer_tracking (basic)
[438.798999] Starting subtest: basic
[465.795054] Subtest basic: SUCCESS (26.996s)
[466.400614] [113/161] (658s left) kms_hdmi_inject (inject-audio)
[467.218474] Starting subtest: inject-audio
[467.769394] Subtest inject-audio: SUCCESS (0.539s)
[468.887198] [114/161] (656s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[469.378310] Starting subtest: compare-crc-sanitycheck-xr24
[469.385879] Starting dynamic subtest: pipe-A-eDP-1
[471.142372] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.754s)
[471.235238] Starting dynamic subtest: pipe-B-eDP-1
[474.124245] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.966s)
[474.217215] Starting dynamic subtest: pipe-C-eDP-1
[476.306417] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.166s)
[476.309183] Subtest compare-crc-sanitycheck-xr24: SUCCESS (6.926s)
[477.605986] [115/161] (647s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[478.091541] Starting subtest: compare-crc-sanitycheck-nv12
[478.097794] Starting dynamic subtest: pipe-A-eDP-1
[481.058145] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.956s)
[481.154356] Starting dynamic subtest: pipe-B-eDP-1
[484.443723] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.373s)
[484.541858] Starting dynamic subtest: pipe-C-eDP-1
[487.701924] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.225s)
[487.704696] Subtest compare-crc-sanitycheck-nv12: SUCCESS (9.613s)
[488.973060] [116/161] (636s left) kms_pipe_crc_basic (hang-read-crc)
[489.462364] Starting subtest: hang-read-crc
[489.477965] Starting dynamic subtest: pipe-A-eDP-1
[495.330076] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.854s)
[495.422586] Starting dynamic subtest: pipe-B-eDP-1
[500.905320] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.562s)
[500.998888] Starting dynamic subtest: pipe-C-eDP-1
[506.298991] Dynamic subtest pipe-C-eDP-1: SUCCESS (5.375s)
[506.301973] Subtest hang-read-crc: SUCCESS (16.835s)
[507.533470] [117/161] (618s left) kms_pipe_crc_basic (nonblocking-crc)
[508.027695] Starting subtest: nonblocking-crc
[508.039241] Starting dynamic subtest: pipe-A-eDP-1
[510.090254] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.051s)
[510.186214] Starting dynamic subtest: pipe-B-eDP-1
[512.592821] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.480s)
[512.695136] Starting dynamic subtest: pipe-C-eDP-1
[514.990760] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.390s)
[515.002137] Subtest nonblocking-crc: SUCCESS (6.967s)
[516.289704] [118/161] (610s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[516.783806] Starting subtest: nonblocking-crc-frame-sequence
[516.794232] Starting dynamic subtest: pipe-A-eDP-1
[518.849650] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.055s)
[518.946896] Starting dynamic subtest: pipe-B-eDP-1
[521.378324] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.512s)
[521.479170] Starting dynamic subtest: pipe-C-eDP-1
[523.788474] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.390s)
[523.791463] Subtest nonblocking-crc-frame-sequence: SUCCESS (7.005s)
[525.093905] [119/161] (601s left) kms_pipe_crc_basic (read-crc)
[525.580145] Starting subtest: read-crc
[525.590790] Starting dynamic subtest: pipe-A-eDP-1
[528.355176] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.758s)
[528.451136] Starting dynamic subtest: pipe-B-eDP-1
[531.531627] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.161s)
[531.629862] Starting dynamic subtest: pipe-C-eDP-1
[533.851150] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.308s)
[533.862428] Subtest read-crc: SUCCESS (8.276s)
[535.119431] [120/161] (592s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[535.617289] Starting subtest: read-crc-frame-sequence
[535.633114] Starting dynamic subtest: pipe-A-eDP-1
[537.643465] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.020s)
[537.744539] Starting dynamic subtest: pipe-B-eDP-1
[540.112725] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.449s)
[540.210322] Starting dynamic subtest: pipe-C-eDP-1
[543.226198] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.097s)
[543.229437] Subtest read-crc-frame-sequence: SUCCESS (7.616s)
[544.223504] [121/161] (583s left) kms_pm_backlight (basic-brightness)
[544.716409] Starting subtest: basic-brightness
[545.200844] Starting dynamic subtest: eDP-1
[545.682325] Dynamic subtest eDP-1: SUCCESS (0.488s)
[545.686373] Subtest basic-brightness: SUCCESS (0.966s)
[546.915121] [122/161] (581s left) kms_pm_rpm (basic-pci-d3-state)
[549.421173] Starting subtest: basic-pci-d3-state
[551.477498] Subtest basic-pci-d3-state: SUCCESS (2.058s)
[552.036290] [123/161] (576s left) kms_pm_rpm (basic-rte)
[552.399438] Starting subtest: basic-rte
[556.328826] Subtest basic-rte: SUCCESS (3.927s)
[558.496243] [124/161] (570s left) kms_psr (psr-primary-page-flip)
[558.981520] Starting subtest: psr-primary-page-flip
[558.993912] Starting dynamic subtest: eDP-1
[561.725671] Dynamic subtest eDP-1: SUCCESS (2.734s)
[561.728376] Subtest psr-primary-page-flip: SUCCESS (2.746s)
[563.017344] [125/161] (566s left) kms_psr (psr-cursor-plane-move)
[563.506166] Starting subtest: psr-cursor-plane-move
[563.517098] Starting dynamic subtest: eDP-1
[566.063900] Dynamic subtest eDP-1: SUCCESS (2.548s)
[566.066691] Subtest psr-cursor-plane-move: SUCCESS (2.560s)
[567.362128] [126/161] (562s left) kms_psr (psr-sprite-plane-onoff)
[567.847715] Starting subtest: psr-sprite-plane-onoff
[567.858135] Starting dynamic subtest: eDP-1
[570.509360] Dynamic subtest eDP-1: SUCCESS (2.653s)
[570.520337] Subtest psr-sprite-plane-onoff: SUCCESS (2.665s)
[571.798423] [127/161] (558s left) kms_psr (psr-primary-mmap-gtt)
[572.283887] Starting subtest: psr-primary-mmap-gtt
[572.294861] Starting dynamic subtest: eDP-1
[575.069737] Dynamic subtest eDP-1: SUCCESS (2.776s)
[575.072607] Subtest psr-primary-mmap-gtt: SUCCESS (2.787s)
[576.336592] [128/161] (554s left) kms_setmode (basic-clone-single-crtc)
[576.723712] Starting subtest: basic-clone-single-crtc
[576.732892] Subtest basic-clone-single-crtc: SKIP (0.001s)
[577.198274] [129/161] (553s left) i915_pm_rps (basic-api)
[577.622731] Starting subtest: basic-api
[577.628749] Subtest basic-api: SKIP (0.004s)
[578.122232] [130/161] (553s left) prime_self_import (basic-llseek-bad)
[578.494207] Starting subtest: basic-llseek-bad
[578.508775] Subtest basic-llseek-bad: SUCCESS (0.014s)
[579.009910] [131/161] (552s left) prime_self_import (basic-llseek-size)
[579.374312] Starting subtest: basic-llseek-size
[579.396445] Subtest basic-llseek-size: SUCCESS (0.022s)
[579.884948] [132/161] (552s left) prime_self_import (basic-with_fd_dup)
[580.255954] Starting subtest: basic-with_fd_dup
[580.278967] Subtest basic-with_fd_dup: SUCCESS (0.021s)
[580.789276] [133/161] (551s left) prime_self_import (basic-with_one_bo)
[581.165725] Starting subtest: basic-with_one_bo
[581.192176] Subtest basic-with_one_bo: SUCCESS (0.027s)
[581.693988] [134/161] (551s left) prime_self_import (basic-with_one_bo_two_files)
[582.054780] Starting subtest: basic-with_one_bo_two_files
[582.077370] Subtest basic-with_one_bo_two_files: SUCCESS (0.018s)
[582.568956] [135/161] (550s left) prime_self_import (basic-with_two_bos)
[582.932655] Starting subtest: basic-with_two_bos
[582.954509] Subtest basic-with_two_bos: SUCCESS (0.022s)
[583.454263] [136/161] (550s left) prime_vgem (basic-fence-flip)
[583.911352] Starting subtest: basic-fence-flip
[585.353673] Subtest basic-fence-flip: SUCCESS (1.448s)
[585.948987] [137/161] (548s left) prime_vgem (basic-fence-mmap)
[586.394800] Starting subtest: basic-fence-mmap
[586.590836] Subtest basic-fence-mmap: SUCCESS (0.194s)
[587.069627] [138/161] (547s left) prime_vgem (basic-fence-read)
[587.503935] Starting subtest: basic-fence-read
[587.561375] Subtest basic-fence-read: SKIP (0.055s)
[588.071471] [139/161] (546s left) prime_vgem (basic-gtt)
[588.517494] Starting subtest: basic-gtt
[588.600454] Subtest basic-gtt: SUCCESS (0.089s)
[589.095690] [140/161] (546s left) prime_vgem (basic-read)
[589.538988] Starting subtest: basic-read
[589.597937] Subtest basic-read: SKIP (0.056s)
[590.102390] [141/161] (545s left) prime_vgem (basic-write)
[590.552065] Starting subtest: basic-write
[590.611296] Subtest basic-write: SKIP (0.056s)
[591.114419] [142/161] (545s left) vgem_basic (setversion)
[591.523511] Starting subtest: setversion
[591.534268] Subtest setversion: SUCCESS (0.000s)
[591.970908] [143/161] (544s left) vgem_basic (create)
[592.377328] Starting subtest: create
[592.380548] Subtest create: SUCCESS (0.002s)
[592.844518] [144/161] (544s left) vgem_basic (debugfs)
[593.250570] Starting subtest: debugfs
[593.253624] Subtest debugfs: SUCCESS (0.003s)
[593.715427] [145/161] (543s left) vgem_basic (dmabuf-export)
[594.124920] Starting subtest: dmabuf-export
[594.141390] Subtest dmabuf-export: SUCCESS (0.017s)
[594.621991] [146/161] (543s left) vgem_basic (dmabuf-fence)
[595.027690] Starting subtest: dmabuf-fence
[595.037123] Subtest dmabuf-fence: SUCCESS (0.003s)
[595.490601] [147/161] (542s left) vgem_basic (dmabuf-fence-before)
[595.903475] Starting subtest: dmabuf-fence-before
[595.906637] Subtest dmabuf-fence-before: SUCCESS (0.003s)
[596.371561] [148/161] (542s left) vgem_basic (dmabuf-mmap)
[596.774512] Starting subtest: dmabuf-mmap
[596.870613] Subtest dmabuf-mmap: SUCCESS (0.101s)
[597.323598] [149/161] (541s left) vgem_basic (mmap)
[597.730127] Starting subtest: mmap
[597.791815] Subtest mmap: SUCCESS (0.065s)
[598.246463] [150/161] (541s left) vgem_basic (second-client)
[598.652944] Starting subtest: second-client
[598.697070] Subtest second-client: SUCCESS (0.046s)
[599.158977] [151/161] (540s left) vgem_basic (sysfs)
[599.559871] Starting subtest: sysfs
[599.563232] Subtest sysfs: SUCCESS (0.004s)
[600.027424] [152/161] (540s left) core_hotunplug (unbind-rebind)
[600.718209] Starting subtest: unbind-rebind
[609.656590] Subtest unbind-rebind: SUCCESS (8.932s)
[610.063625] [153/161] (530s left) vgem_basic (unload)
[610.436874] Starting subtest: unload
[640.124297] Subtest unload: SUCCESS (29.685s)
[640.798792] [154/161] (500s left) i915_module_load (reload)
[641.185596] Starting subtest: reload
[655.761091] Subtest reload: SUCCESS (14.563s)
[656.570460] [155/161] (485s left) gem_lmem_swapping (basic)
[657.140411] Subtest basic: SKIP (0.000s)
[657.567651] [156/161] (484s left) gem_lmem_swapping (parallel-random-engines)
[657.997623] Subtest parallel-random-engines: SKIP (0.000s)
[658.431854] [157/161] (484s left) gem_lmem_swapping (random-engines)
[658.837210] Subtest random-engines: SKIP (0.000s)
[659.269059] [158/161] (483s left) gem_lmem_swapping (verify-random)
[659.689930] Subtest verify-random: SKIP (0.000s)
[660.115285] [159/161] (483s left) i915_pm_rpm (module-reload)
[663.495971] Starting subtest: module-reload
[697.249271] Subtest module-reload: SUCCESS (33.749s)
[697.508256] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[697.520611] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[697.547437] [160/161] (446s left) i915_selftest (live)
[697.952701] Starting subtest: live
[697.955920] Starting dynamic subtest: sanitycheck
[708.350284] Dynamic subtest sanitycheck: SUCCESS (10.391s)
[708.416142] Starting dynamic subtest: uncore
[718.849393] Dynamic subtest uncore: SUCCESS (10.490s)
[718.915770] Starting dynamic subtest: workarounds
[735.182804] Dynamic subtest workarounds: SUCCESS (16.324s)
[735.249127] Starting dynamic subtest: gt_engines
[746.105491] Dynamic subtest gt_engines: SUCCESS (10.907s)
[746.195064] Starting dynamic subtest: gt_timelines
[790.477929] Dynamic subtest gt_timelines: SUCCESS (44.362s)
[790.548333] Starting dynamic subtest: gt_contexts
[801.031110] Dynamic subtest gt_contexts: SUCCESS (10.552s)
[801.104506] Starting dynamic subtest: gt_lrc
[815.023080] Dynamic subtest gt_lrc: SUCCESS (13.974s)
[815.094967] Starting dynamic subtest: gt_mocs
[826.693371] Dynamic subtest gt_mocs: SUCCESS (11.671s)
[826.772303] Starting dynamic subtest: gt_pm
[839.198580] Dynamic subtest gt_pm: SUCCESS (12.480s)
[839.269851] Starting dynamic subtest: gt_heartbeat
[849.816074] Dynamic subtest gt_heartbeat: SUCCESS (10.598s)
[849.882305] Starting dynamic subtest: gt_tlb
[861.408888] Dynamic subtest gt_tlb: SUCCESS (11.585s)
[861.473810] Starting dynamic subtest: requests
[882.016802] Dynamic subtest requests: SUCCESS (20.574s)
[882.116893] Starting dynamic subtest: migrate
[896.554857] Dynamic subtest migrate: SUCCESS (14.551s)
[896.621852] Starting dynamic subtest: active
[907.132444] Dynamic subtest active: SUCCESS (10.569s)
[907.202245] Starting dynamic subtest: objects
[922.822273] Dynamic subtest objects: SUCCESS (15.681s)
[922.891608] Starting dynamic subtest: mman
[944.795225] Dynamic subtest mman: SUCCESS (21.968s)
[944.944312] Starting dynamic subtest: dmabuf
[955.623381] Dynamic subtest dmabuf: SUCCESS (10.803s)
[955.696257] Starting dynamic subtest: vma
[966.027295] Dynamic subtest vma: SUCCESS (10.393s)
[966.091418] Starting dynamic subtest: coherency
[995.087737] Dynamic subtest coherency: SUCCESS (29.051s)
[995.157588] Starting dynamic subtest: gtt
[1013.703282] Dynamic subtest gtt: SUCCESS (18.606s)
[1013.767218] Starting dynamic subtest: gem
[1025.366578] Dynamic subtest gem: SUCCESS (11.654s)
[1025.430396] Starting dynamic subtest: evict
[1043.677399] Dynamic subtest evict: SUCCESS (18.302s)
[1043.741211] Starting dynamic subtest: hugepages
[1061.140476] Dynamic subtest hugepages: SUCCESS (17.453s)
[1061.209874] Starting dynamic subtest: gem_contexts
[1101.687364] Dynamic subtest gem_contexts: SUCCESS (40.533s)
[1101.753421] Starting dynamic subtest: client
[1112.272927] Dynamic subtest client: SUCCESS (10.577s)
[1112.340236] Starting dynamic subtest: gem_migrate
[1122.694354] Dynamic subtest gem_migrate: SUCCESS (10.412s)
[1122.763148] Starting dynamic subtest: reset
[1136.671444] Dynamic subtest reset: SUCCESS (13.960s)
[1136.739250] Starting dynamic subtest: memory_region
[1147.072234] Dynamic subtest memory_region: SUCCESS (10.396s)
[1147.140159] Starting dynamic subtest: hangcheck
[1178.001261] Dynamic subtest hangcheck: SUCCESS (30.919s)
[1178.068867] Starting dynamic subtest: execlists
[1188.435247] Dynamic subtest execlists: SUCCESS (10.425s)
[1188.499764] Starting dynamic subtest: ring_submission
[1198.891420] Dynamic subtest ring_submission: SUCCESS (10.447s)
[1198.961463] Starting dynamic subtest: perf
[1209.880919] Dynamic subtest perf: SUCCESS (10.980s)
[1209.948709] Starting dynamic subtest: slpc
[1222.010283] Dynamic subtest slpc: SUCCESS (12.130s)
[1222.086982] Starting dynamic subtest: guc
[1235.072878] Dynamic subtest guc: SUCCESS (13.044s)
[1235.137683] Starting dynamic subtest: guc_multi_lrc
[1245.417796] Dynamic subtest guc_multi_lrc: SUCCESS (10.335s)
[1245.487783] Starting dynamic subtest: guc_hang
[1257.005068] Dynamic subtest guc_hang: SUCCESS (11.588s)
[1257.094428] Starting dynamic subtest: late_gt_pm
[1267.624362] Dynamic subtest late_gt_pm: SUCCESS (10.588s)
[1267.627613] Subtest live: SUCCESS (569.653s)
[1267.743284] Overall timeout time exceeded, stopping.
[1267.909156] Closing watchdogs
Done.