[117.639775] Initializing watchdogs
[117.640576]   /dev/watchdog0
[117.801739] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:46d3 Intel Alderlake_n (Gen12) 
[117.839909] [001/161] (960s left) i915_module_load (load)
[119.010560] Starting subtest: load
[130.129288] Subtest load: SUCCESS (11.108s)
[130.469600] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[130.488366] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[130.510514] [002/161] (948s left) core_auth (basic-auth)
[131.204442] Starting subtest: basic-auth
[131.208336] Subtest basic-auth: SUCCESS (0.012s)
[131.656984] [003/161] (947s left) debugfs_test (read_all_entries)
[132.086248] Starting subtest: read_all_entries
[133.570042] Subtest read_all_entries: SUCCESS (1.485s)
[134.089431] [004/161] (945s left) debugfs_test (basic-hwmon)
[134.500270] Starting subtest: basic-hwmon
[134.505863] Subtest basic-hwmon: SKIP (0.000s)
[135.062584] [005/161] (944s left) debugfs_test (sysfs)
[135.465219] Starting subtest: sysfs
[135.952297] Subtest sysfs: SUCCESS (0.485s)
[136.475382] [006/161] (943s left) fbdev (eof)
[136.869915] Starting subtest: eof
[136.873252] Subtest eof: SUCCESS (0.006s)
[137.293723] [007/161] (943s left) fbdev (info)
[137.656943] Starting subtest: info
[137.660200] Subtest info: SUCCESS (0.000s)
[138.092662] [008/161] (942s left) fbdev (nullptr)
[138.469783] Starting subtest: nullptr
[138.473217] Subtest nullptr: SUCCESS (0.000s)
[138.899856] [009/161] (942s left) fbdev (read)
[139.288693] Starting subtest: read
[153.021406] Subtest read: SUCCESS (13.700s)
[153.428376] [010/161] (928s left) fbdev (write)
[153.809627] Starting subtest: write
[160.925973] Subtest write: SUCCESS (7.110s)
[161.379423] [011/161] (920s left) gem_basic (bad-close)
[161.757335] Starting subtest: bad-close
[161.762813] Subtest bad-close: SUCCESS (0.000s)
[162.168338] [012/161] (919s left) gem_basic (create-close)
[162.539788] Starting subtest: create-close
[162.545666] Subtest create-close: SUCCESS (0.001s)
[162.970251] [013/161] (919s left) gem_basic (create-fd-close)
[163.349748] Starting subtest: create-fd-close
[163.356213] Subtest create-fd-close: SUCCESS (0.001s)
[163.775623] [014/161] (918s left) gem_busy (busy@all-engines)
[164.249884] Starting subtest: busy
[164.253260] Starting dynamic subtest: all-engines
[164.563282] Dynamic subtest all-engines: SUCCESS (0.314s)
[164.566436] Subtest busy: SUCCESS (0.317s)
[165.465869] [015/161] (917s left) gem_close_race (basic-process)
[165.940261] Starting subtest: basic-process
[166.095934] Subtest basic-process: SUCCESS (0.156s)
[166.657660] [016/161] (916s left) gem_close_race (basic-threads)
[167.132469] Starting subtest: basic-threads
[168.563931] Subtest basic-threads: SUCCESS (1.433s)
[169.119071] [017/161] (914s left) gem_ctx_create (basic)
[169.578806] Starting subtest: basic
[169.582082] Subtest basic: SUCCESS (0.000s)
[170.125732] [018/161] (913s left) gem_ctx_create (basic-files)
[170.584571] Starting subtest: basic-files
[172.693995] Subtest basic-files: SUCCESS (2.112s)
[173.387889] [019/161] (911s left) gem_ctx_exec (basic)
[173.776945] Starting subtest: basic
[173.790845] Subtest basic: SUCCESS (0.017s)
[174.235736] [020/161] (910s left) gem_exec_basic (basic)
[174.665727] Starting subtest: basic
[174.668733] Starting dynamic subtest: rcs0-smem
[174.676615] Dynamic subtest rcs0-smem: SUCCESS (0.009s)
[174.734813] Starting dynamic subtest: bcs0-smem
[174.737840] Dynamic subtest bcs0-smem: SUCCESS (0.004s)
[174.794815] Starting dynamic subtest: vcs0-smem
[174.797942] Dynamic subtest vcs0-smem: SUCCESS (0.006s)
[174.856438] Starting dynamic subtest: vecs0-smem
[174.859685] Dynamic subtest vecs0-smem: SUCCESS (0.005s)
[174.863415] Subtest basic: SUCCESS (0.033s)
[175.272793] [021/161] (909s left) gem_exec_create (basic)
[175.731808] Starting subtest: basic
[175.735058] Starting dynamic subtest: smem
[177.938085] Dynamic subtest smem: SUCCESS (2.206s)
[177.941397] Subtest basic: SUCCESS (2.208s)
[178.491530] [022/161] (906s left) gem_exec_fence (basic-busy)
[179.155862] Starting subtest: basic-busy
[179.159096] Starting dynamic subtest: rcs0
[179.239274] Dynamic subtest rcs0: SUCCESS (0.085s)
[179.300400] Starting dynamic subtest: bcs0
[179.306866] Dynamic subtest bcs0: SUCCESS (0.063s)
[179.370349] Starting dynamic subtest: vcs0
[179.376919] Dynamic subtest vcs0: SUCCESS (0.064s)
[179.437170] Starting dynamic subtest: vecs0
[179.445717] Dynamic subtest vecs0: SUCCESS (0.066s)
[179.448653] Subtest basic-busy: SUCCESS (0.286s)
[180.943082] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[180.964462] [023/161] (904s left) gem_exec_fence (basic-wait)
[181.624181] Starting subtest: basic-wait
[181.627370] Starting dynamic subtest: rcs0
[181.707085] Dynamic subtest rcs0: SUCCESS (0.082s)
[181.767550] Starting dynamic subtest: bcs0
[181.770661] Dynamic subtest bcs0: SUCCESS (0.060s)
[181.834681] Starting dynamic subtest: vcs0
[181.838056] Dynamic subtest vcs0: SUCCESS (0.058s)
[181.898886] Starting dynamic subtest: vecs0
[181.901986] Dynamic subtest vecs0: SUCCESS (0.065s)
[181.905313] Subtest basic-wait: SUCCESS (0.273s)
[183.177467] [024/161] (902s left) gem_exec_fence (basic-await)
[183.848774] Starting subtest: basic-await
[183.852188] Starting dynamic subtest: rcs0
[184.183206] Dynamic subtest rcs0: SUCCESS (0.337s)
[184.249821] Starting dynamic subtest: bcs0
[184.501817] Dynamic subtest bcs0: SUCCESS (0.313s)
[184.567780] Starting dynamic subtest: vcs0
[184.808110] Dynamic subtest vcs0: SUCCESS (0.305s)
[184.871411] Starting dynamic subtest: vecs0
[185.118493] Dynamic subtest vecs0: SUCCESS (0.309s)
[185.121784] Subtest basic-await: SUCCESS (1.274s)
[186.393494] [025/161] (900s left) gem_exec_fence (nb-await)
[187.058510] Starting subtest: nb-await
[187.061610] Starting dynamic subtest: rcs0
[187.168332] Dynamic subtest rcs0: SUCCESS (0.112s)
[187.230717] Starting dynamic subtest: bcs0
[187.257934] Dynamic subtest bcs0: SUCCESS (0.088s)
[187.319843] Starting dynamic subtest: vcs0
[187.348321] Dynamic subtest vcs0: SUCCESS (0.086s)
[187.408952] Starting dynamic subtest: vecs0
[187.434772] Dynamic subtest vecs0: SUCCESS (0.086s)
[187.438136] Subtest nb-await: SUCCESS (0.381s)
[188.700275] [026/161] (898s left) gem_exec_gttfill (basic)
[189.164675] Starting subtest: basic
[227.158074] Subtest basic: SUCCESS (37.983s)
[227.787686] [027/161] (859s left) gem_exec_parallel (engines)
[228.411907] Starting subtest: engines
[228.418158] Starting dynamic subtest: basic
[235.067873] Dynamic subtest basic: SUCCESS (6.656s)
[235.134243] Starting dynamic subtest: contexts
[246.593369] Dynamic subtest contexts: SUCCESS (11.513s)
[246.665490] Starting dynamic subtest: fds
[258.563123] Dynamic subtest fds: SUCCESS (11.967s)
[258.632322] Starting dynamic subtest: userptr
[264.374217] Dynamic subtest userptr: SUCCESS (5.818s)
[264.377478] Subtest engines: SUCCESS (35.965s)
[265.013410] [028/161] (822s left) gem_exec_store (basic)
[265.478835] Starting subtest: basic
[265.613523] Subtest basic: SUCCESS (0.135s)
[266.207205] [029/161] (821s left) gem_flink_basic (bad-flink)
[266.575942] Starting subtest: bad-flink
[266.579220] Subtest bad-flink: SUCCESS (0.000s)
[267.025929] [030/161] (821s left) gem_flink_basic (bad-open)
[267.406726] Starting subtest: bad-open
[267.409698] Subtest bad-open: SUCCESS (0.000s)
[267.852214] [031/161] (820s left) gem_flink_basic (basic)
[268.228853] Starting subtest: basic
[268.232136] Subtest basic: SUCCESS (0.001s)
[268.702397] [032/161] (820s left) gem_flink_basic (double-flink)
[269.075450] Starting subtest: double-flink
[269.078427] Subtest double-flink: SUCCESS (0.000s)
[269.538602] [033/161] (819s left) gem_flink_basic (flink-lifetime)
[269.933779] Starting subtest: flink-lifetime
[269.945338] Subtest flink-lifetime: SUCCESS (0.013s)
[270.410394] [034/161] (819s left) gem_huc_copy (huc-copy)
[270.821415] Starting subtest: huc-copy
[270.842644] Subtest huc-copy: SUCCESS (0.021s)
[271.358721] [035/161] (818s left) gem_linear_blits (basic)
[272.384865] Starting subtest: basic
[272.435193] Subtest basic: SUCCESS (0.059s)
[272.996539] [036/161] (817s left) gem_mmap (basic)
[273.563137] Starting subtest: basic
[273.577425] Subtest basic: SUCCESS (0.003s)
[274.036903] [037/161] (817s left) gem_mmap_gtt (basic)
[274.415490] Starting subtest: basic
[274.418456] Subtest basic: SUCCESS (0.005s)
[274.882813] [038/161] (816s left) gem_render_linear_blits (basic)
[275.284741] Starting subtest: basic
[275.526746] Subtest basic: SUCCESS (0.240s)
[275.999472] [039/161] (815s left) gem_render_tiled_blits (basic)
[276.402333] Starting subtest: basic
[276.681238] Subtest basic: SUCCESS (0.278s)
[277.149444] [040/161] (815s left) gem_ringfill (basic-all)
[278.026634] Starting subtest: basic-all
[279.496910] Subtest basic-all: SUCCESS (1.463s)
[280.010619] [041/161] (812s left) gem_softpin (allocator-basic)
[280.419689] Starting subtest: allocator-basic
[281.096737] Subtest allocator-basic: SUCCESS (0.670s)
[281.633812] [042/161] (811s left) gem_softpin (allocator-basic-reserve)
[282.044729] Starting subtest: allocator-basic-reserve
[282.728511] Subtest allocator-basic-reserve: SUCCESS (0.674s)
[283.289635] [043/161] (810s left) gem_softpin (safe-alignment)
[283.700899] Starting subtest: safe-alignment
[283.779554] Subtest safe-alignment: SUCCESS (0.080s)
[284.276508] [044/161] (809s left) gem_sync (basic-all)
[284.759097] Starting subtest: basic-all
[289.931323] Subtest basic-all: SUCCESS (5.178s)
[290.559664] [045/161] (803s left) gem_sync (basic-each)
[291.028227] Starting subtest: basic-each
[294.591716] Subtest basic-each: SUCCESS (3.562s)
[295.189431] [046/161] (799s left) gem_tiled_blits (basic)
[296.101991] Starting subtest: basic
[296.236527] Subtest basic: SUCCESS (0.127s)
[296.769985] [047/161] (798s left) gem_tiled_fence_blits (basic)
[297.976061] Starting subtest: basic
[298.039830] Subtest basic: SUCCESS (0.077s)
[298.618162] [048/161] (796s left) gem_tiled_pread_basic
[299.623438] [049/161] (795s left) gem_wait (busy@all-engines)
[300.183987] Starting subtest: busy
[300.187393] Starting dynamic subtest: all-engines
[300.772388] Dynamic subtest all-engines: SUCCESS (0.595s)
[300.775653] Subtest busy: SUCCESS (0.597s)
[301.499407] [050/161] (794s left) gem_wait (wait@all-engines)
[302.015969] Starting subtest: wait
[302.018972] Starting dynamic subtest: all-engines
[303.111355] Dynamic subtest all-engines: SUCCESS (1.106s)
[303.114681] Subtest wait: SUCCESS (1.108s)
[303.838317] [051/161] (792s left) i915_getparams_basic (basic-eu-total)
[304.207303] Starting subtest: basic-eu-total
[304.213940] Subtest basic-eu-total: SUCCESS (0.000s)
[304.662518] [052/161] (792s left) i915_getparams_basic (basic-subslice-total)
[305.031599] Starting subtest: basic-subslice-total
[305.038662] Subtest basic-subslice-total: SUCCESS (0.000s)
[305.486068] [053/161] (791s left) i915_hangman (error-state-basic)
[305.939062] Starting subtest: error-state-basic
[306.792968] Subtest error-state-basic: SUCCESS (0.851s)
[307.286749] [054/161] (790s left) i915_pciid
[308.117566] [055/161] (789s left) kms_addfb_basic (addfb25-4-tiled)
[308.627406] Starting subtest: addfb25-4-tiled
[308.631256] Subtest addfb25-4-tiled: SUCCESS (0.002s)
[309.109194] [056/161] (789s left) kms_addfb_basic (addfb25-bad-modifier)
[309.590939] Starting subtest: addfb25-bad-modifier
[309.594577] Subtest addfb25-bad-modifier: SUCCESS (0.002s)
[310.060685] [057/161] (788s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[310.545541] Starting subtest: addfb25-framebuffer-vs-set-tiling
[310.549300] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.002s)
[311.016881] [058/161] (787s left) kms_addfb_basic (addfb25-modifier-no-flag)
[311.495038] Starting subtest: addfb25-modifier-no-flag
[311.498157] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[311.976768] [059/161] (787s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[312.455115] Starting subtest: addfb25-x-tiled-legacy
[312.458330] Subtest addfb25-x-tiled-legacy: SUCCESS (0.001s)
[312.930824] [060/161] (786s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[313.417111] Starting subtest: addfb25-x-tiled-mismatch-legacy
[313.420618] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.002s)
[313.898307] [061/161] (786s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[314.382559] Starting subtest: addfb25-yf-tiled-legacy
[314.386048] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.002s)
[314.873088] [062/161] (785s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[315.376835] Starting subtest: addfb25-y-tiled-legacy
[315.380360] Subtest addfb25-y-tiled-legacy: SUCCESS (0.001s)
[315.853866] [063/161] (785s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[316.335175] Starting subtest: addfb25-y-tiled-small-legacy
[316.338897] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.002s)
[316.817439] [064/161] (784s left) kms_addfb_basic (bad-pitch-0)
[317.198460] Starting subtest: bad-pitch-0
[317.201555] Subtest bad-pitch-0: SUCCESS (0.000s)
[317.769399] [065/161] (783s left) kms_addfb_basic (bad-pitch-1024)
[318.149447] Starting subtest: bad-pitch-1024
[318.153591] Subtest bad-pitch-1024: SUCCESS (0.000s)
[318.723941] [066/161] (783s left) kms_addfb_basic (bad-pitch-128)
[319.101705] Starting subtest: bad-pitch-128
[319.105492] Subtest bad-pitch-128: SUCCESS (0.000s)
[319.682851] [067/161] (782s left) kms_addfb_basic (bad-pitch-256)
[320.062379] Starting subtest: bad-pitch-256
[320.065641] Subtest bad-pitch-256: SUCCESS (0.000s)
[320.643361] [068/161] (782s left) kms_addfb_basic (bad-pitch-32)
[321.024341] Starting subtest: bad-pitch-32
[321.027192] Subtest bad-pitch-32: SUCCESS (0.000s)
[321.603442] [069/161] (781s left) kms_addfb_basic (bad-pitch-63)
[321.987449] Starting subtest: bad-pitch-63
[321.990955] Subtest bad-pitch-63: SUCCESS (0.000s)
[322.555825] [070/161] (780s left) kms_addfb_basic (bad-pitch-65536)
[322.940459] Starting subtest: bad-pitch-65536
[322.943606] Subtest bad-pitch-65536: SUCCESS (0.002s)
[323.530868] [071/161] (780s left) kms_addfb_basic (bad-pitch-999)
[323.908124] Starting subtest: bad-pitch-999
[323.911740] Subtest bad-pitch-999: SUCCESS (0.000s)
[324.484931] [072/161] (779s left) kms_addfb_basic (basic)
[324.869998] Starting subtest: basic
[324.873764] Subtest basic: SUCCESS (0.000s)
[325.445113] [073/161] (779s left) kms_addfb_basic (basic-x-tiled-legacy)
[325.821610] Starting subtest: basic-x-tiled-legacy
[325.825545] Subtest basic-x-tiled-legacy: SUCCESS (0.001s)
[326.403950] [074/161] (778s left) kms_addfb_basic (basic-y-tiled-legacy)
[326.790158] Starting subtest: basic-y-tiled-legacy
[326.793266] Subtest basic-y-tiled-legacy: SUCCESS (0.002s)
[327.362141] [075/161] (778s left) kms_addfb_basic (bo-too-small)
[327.841864] Starting subtest: bo-too-small
[327.845794] Subtest bo-too-small: SUCCESS (0.002s)
[328.299666] [076/161] (777s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[328.784953] Starting subtest: bo-too-small-due-to-tiling
[328.788545] Subtest bo-too-small-due-to-tiling: SUCCESS (0.002s)
[329.263383] [077/161] (776s left) kms_addfb_basic (clobberred-modifier)
[329.637618] Starting subtest: clobberred-modifier
[329.641606] Subtest clobberred-modifier: SUCCESS (0.001s)
[330.284808] [078/161] (776s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[330.667741] Starting subtest: framebuffer-vs-set-tiling
[330.670921] Subtest framebuffer-vs-set-tiling: SUCCESS (0.001s)
[331.237848] [079/161] (775s left) kms_addfb_basic (invalid-get-prop)
[331.619491] Starting subtest: invalid-get-prop
[331.622872] Subtest invalid-get-prop: SUCCESS (0.000s)
[332.215712] [080/161] (775s left) kms_addfb_basic (invalid-get-prop-any)
[332.603742] Starting subtest: invalid-get-prop-any
[332.606991] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[333.188939] [081/161] (774s left) kms_addfb_basic (invalid-set-prop)
[333.562529] Starting subtest: invalid-set-prop
[333.565827] Subtest invalid-set-prop: SUCCESS (0.000s)
[334.145583] [082/161] (773s left) kms_addfb_basic (invalid-set-prop-any)
[334.517260] Starting subtest: invalid-set-prop-any
[334.520377] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[335.107428] [083/161] (773s left) kms_addfb_basic (no-handle)
[335.486609] Starting subtest: no-handle
[335.489858] Subtest no-handle: SUCCESS (0.000s)
[336.070632] [084/161] (772s left) kms_addfb_basic (size-max)
[336.552416] Starting subtest: size-max
[336.556174] Subtest size-max: SUCCESS (0.001s)
[337.026398] [085/161] (772s left) kms_addfb_basic (small-bo)
[337.510302] Starting subtest: small-bo
[337.513657] Subtest small-bo: SUCCESS (0.000s)
[337.976281] [086/161] (771s left) kms_addfb_basic (tile-pitch-mismatch)
[338.357595] Starting subtest: tile-pitch-mismatch
[338.360889] Subtest tile-pitch-mismatch: SUCCESS (0.002s)
[338.938497] [087/161] (771s left) kms_addfb_basic (too-high)
[339.422932] Starting subtest: too-high
[339.426515] Subtest too-high: SUCCESS (0.006s)
[339.917802] [088/161] (770s left) kms_addfb_basic (too-wide)
[340.400329] Starting subtest: too-wide
[340.403743] Subtest too-wide: SUCCESS (0.000s)
[340.868927] [089/161] (769s left) kms_addfb_basic (unused-handle)
[341.244644] Starting subtest: unused-handle
[341.248220] Subtest unused-handle: SUCCESS (0.000s)
[341.830283] [090/161] (769s left) kms_addfb_basic (unused-modifier)
[342.209822] Starting subtest: unused-modifier
[342.213048] Subtest unused-modifier: SUCCESS (0.000s)
[342.788765] [091/161] (768s left) kms_addfb_basic (unused-offsets)
[343.163391] Starting subtest: unused-offsets
[343.167734] Subtest unused-offsets: SUCCESS (0.000s)
[343.739529] [092/161] (768s left) kms_addfb_basic (unused-pitches)
[344.114897] Starting subtest: unused-pitches
[344.118375] Subtest unused-pitches: SUCCESS (0.000s)
[344.692644] [093/161] (767s left) kms_busy (basic)
[345.223818] Starting subtest: basic
[345.231801] Starting dynamic subtest: flip
[347.506297] Dynamic subtest flip: SUCCESS (2.272s)
[347.587802] Starting dynamic subtest: modeset
[352.702659] Dynamic subtest modeset: SUCCESS (5.194s)
[352.705537] Subtest basic: SUCCESS (7.477s)
[354.015690] [094/161] (758s left) kms_prop_blob (basic)
[354.391497] Starting subtest: basic
[354.394869] Subtest basic: SUCCESS (0.000s)
[354.860566] [095/161] (758s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[355.497273] Starting subtest: basic-busy-flip-before-cursor-atomic
[355.503092] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[356.084545] [096/161] (757s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[356.695505] Starting subtest: basic-busy-flip-before-cursor-legacy
[356.700878] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[357.295188] [097/161] (756s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[357.907613] Starting subtest: basic-flip-after-cursor-atomic
[359.535329] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.625s)
[360.804635] [098/161] (753s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[361.424375] Starting subtest: basic-flip-after-cursor-legacy
[363.840473] Subtest basic-flip-after-cursor-legacy: SUCCESS (2.406s)
[364.832505] [099/161] (749s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[365.449174] Starting subtest: basic-flip-after-cursor-varying-size
[367.058540] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.610s)
[368.356458] [100/161] (746s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[368.973366] Starting subtest: basic-flip-before-cursor-atomic
[370.584179] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.608s)
[371.890274] [101/161] (743s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[372.504148] Starting subtest: basic-flip-before-cursor-legacy
[374.112169] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.603s)
[375.449447] [102/161] (740s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[376.068930] Starting subtest: basic-flip-before-cursor-varying-size
[377.676496] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.608s)
[378.963737] [103/161] (737s left) kms_dsc (dsc-basic)
[379.448853] Starting subtest: dsc-basic
[379.464410] Subtest dsc-basic: SKIP (0.003s)
[379.910435] [104/161] (736s left) kms_flip (basic-flip-vs-dpms)
[380.393340] Starting subtest: basic-flip-vs-dpms
[380.405914] Starting dynamic subtest: A-eDP1
[385.117527] Dynamic subtest A-eDP1: SUCCESS (4.711s)
[385.196653] Starting dynamic subtest: B-eDP1
[388.352831] Dynamic subtest B-eDP1: SUCCESS (3.229s)
[388.438800] Starting dynamic subtest: C-eDP1
[391.745507] Dynamic subtest C-eDP1: SUCCESS (3.386s)
[391.752911] Subtest basic-flip-vs-dpms: SUCCESS (11.358s)
[393.028805] [105/161] (723s left) kms_flip (basic-flip-vs-modeset)
[393.511930] Starting subtest: basic-flip-vs-modeset
[393.527167] Starting dynamic subtest: A-eDP1
[397.347969] Dynamic subtest A-eDP1: SUCCESS (3.823s)
[397.428395] Starting dynamic subtest: B-eDP1
[400.470404] Dynamic subtest B-eDP1: SUCCESS (3.114s)
[400.556733] Starting dynamic subtest: C-eDP1
[404.225433] Dynamic subtest C-eDP1: SUCCESS (3.752s)
[404.233257] Subtest basic-flip-vs-modeset: SUCCESS (10.724s)
[405.308747] [106/161] (711s left) kms_flip (basic-flip-vs-wf_vblank)
[405.794610] Starting subtest: basic-flip-vs-wf_vblank
[405.804188] Starting dynamic subtest: A-eDP1
[408.887315] Dynamic subtest A-eDP1: SUCCESS (3.083s)
[408.971688] Starting dynamic subtest: B-eDP1
[412.410208] Dynamic subtest B-eDP1: SUCCESS (3.516s)
[412.498940] Starting dynamic subtest: C-eDP1
[415.377180] Dynamic subtest C-eDP1: SUCCESS (2.960s)
[415.389435] Subtest basic-flip-vs-wf_vblank: SUCCESS (9.598s)
[417.420433] [107/161] (700s left) kms_flip (basic-plain-flip)
[417.901510] Starting subtest: basic-plain-flip
[417.913274] Starting dynamic subtest: A-eDP1
[420.733758] Dynamic subtest A-eDP1: SUCCESS (2.820s)
[420.819982] Starting dynamic subtest: B-eDP1
[424.041196] Dynamic subtest B-eDP1: SUCCESS (3.302s)
[424.126472] Starting dynamic subtest: C-eDP1
[426.799615] Dynamic subtest C-eDP1: SUCCESS (2.752s)
[426.811104] Subtest basic-plain-flip: SUCCESS (8.912s)
[428.798861] [108/161] (689s left) kms_force_connector_basic (force-connector-state)
[429.208251] Starting subtest: force-connector-state
[430.057130] Subtest force-connector-state: SUCCESS (0.852s)
[431.708649] [109/161] (686s left) kms_force_connector_basic (force-edid)
[432.115222] Starting subtest: force-edid
[432.215598] Subtest force-edid: SUCCESS (0.106s)
[432.874665] [110/161] (685s left) kms_force_connector_basic (force-load-detect)
[433.269924] Starting subtest: force-load-detect
[433.276957] Subtest force-load-detect: SKIP (0.000s)
[433.849316] [111/161] (685s left) kms_force_connector_basic (prune-stale-modes)
[434.252183] Starting subtest: prune-stale-modes
[434.352654] Subtest prune-stale-modes: SUCCESS (0.104s)
[435.017493] [112/161] (684s left) kms_frontbuffer_tracking (basic)
[435.534540] Starting subtest: basic
[463.242747] Subtest basic: SUCCESS (27.707s)
[463.828192] [113/161] (656s left) kms_hdmi_inject (inject-audio)
[464.657212] Starting subtest: inject-audio
[465.324299] Subtest inject-audio: SUCCESS (0.658s)
[466.449130] [114/161] (653s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[466.942359] Starting subtest: compare-crc-sanitycheck-xr24
[466.954654] Starting dynamic subtest: pipe-A-eDP-1
[468.710350] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.757s)
[468.799274] Starting dynamic subtest: pipe-B-eDP-1
[471.020715] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.296s)
[471.110906] Starting dynamic subtest: pipe-C-eDP-1
[473.200134] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.170s)
[473.203288] Subtest compare-crc-sanitycheck-xr24: SUCCESS (6.262s)
[474.421521] [115/161] (646s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[474.912399] Starting subtest: compare-crc-sanitycheck-nv12
[474.918417] Starting dynamic subtest: pipe-A-eDP-1
[477.876867] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.952s)
[477.967494] Starting dynamic subtest: pipe-B-eDP-1
[481.254286] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.365s)
[481.348860] Starting dynamic subtest: pipe-C-eDP-1
[484.520573] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.250s)
[484.524221] Subtest compare-crc-sanitycheck-nv12: SUCCESS (9.608s)
[485.798176] [116/161] (635s left) kms_pipe_crc_basic (hang-read-crc)
[486.289615] Starting subtest: hang-read-crc
[486.293652] Starting dynamic subtest: pipe-A-eDP-1
[491.498733] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.202s)
[491.590438] Starting dynamic subtest: pipe-B-eDP-1
[497.917829] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.405s)
[498.009408] Starting dynamic subtest: pipe-C-eDP-1
[504.163537] Dynamic subtest pipe-C-eDP-1: SUCCESS (6.233s)
[504.167128] Subtest hang-read-crc: SUCCESS (17.883s)
[505.145515] [117/161] (616s left) kms_pipe_crc_basic (nonblocking-crc)
[505.640354] Starting subtest: nonblocking-crc
[505.652162] Starting dynamic subtest: pipe-A-eDP-1
[507.703315] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.054s)
[507.799370] Starting dynamic subtest: pipe-B-eDP-1
[510.217894] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.501s)
[510.311853] Starting dynamic subtest: pipe-C-eDP-1
[513.441494] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.207s)
[513.445097] Subtest nonblocking-crc: SUCCESS (7.808s)
[514.423628] [118/161] (607s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[514.916773] Starting subtest: nonblocking-crc-frame-sequence
[514.927788] Starting dynamic subtest: pipe-A-eDP-1
[516.963341] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.035s)
[517.062333] Starting dynamic subtest: pipe-B-eDP-1
[519.489864] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.507s)
[519.581856] Starting dynamic subtest: pipe-C-eDP-1
[521.897789] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.392s)
[521.900745] Subtest nonblocking-crc-frame-sequence: SUCCESS (6.982s)
[523.202090] [119/161] (599s left) kms_pipe_crc_basic (read-crc)
[523.685317] Starting subtest: read-crc
[523.696764] Starting dynamic subtest: pipe-A-eDP-1
[526.459107] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.758s)
[526.548645] Starting dynamic subtest: pipe-B-eDP-1
[529.638271] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.161s)
[529.728753] Starting dynamic subtest: pipe-C-eDP-1
[532.742426] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.086s)
[532.745929] Subtest read-crc: SUCCESS (9.054s)
[533.727633] [120/161] (588s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[534.211052] Starting subtest: read-crc-frame-sequence
[534.221812] Starting dynamic subtest: pipe-A-eDP-1
[536.229346] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.004s)
[536.329425] Starting dynamic subtest: pipe-B-eDP-1
[538.662285] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.417s)
[538.755241] Starting dynamic subtest: pipe-C-eDP-1
[541.056819] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.379s)
[541.060191] Subtest read-crc-frame-sequence: SUCCESS (6.848s)
[542.263186] [121/161] (580s left) kms_pm_backlight (basic-brightness)
[542.754966] Starting subtest: basic-brightness
[543.251518] Starting dynamic subtest: eDP-1
[543.733516] Dynamic subtest eDP-1: SUCCESS (0.489s)
[543.737409] Subtest basic-brightness: SUCCESS (0.976s)
[544.967092] [122/161] (578s left) kms_pm_rpm (basic-pci-d3-state)
[547.514590] Starting subtest: basic-pci-d3-state
[549.523114] Subtest basic-pci-d3-state: SUCCESS (2.008s)
[550.163305] [123/161] (573s left) kms_pm_rpm (basic-rte)
[550.539907] Starting subtest: basic-rte
[554.564731] Subtest basic-rte: SUCCESS (4.025s)
[557.328892] [124/161] (566s left) kms_psr (psr-primary-page-flip)
[557.816383] Starting subtest: psr-primary-page-flip
[557.820187] Starting dynamic subtest: eDP-1
[560.600383] Dynamic subtest eDP-1: SUCCESS (2.780s)
[560.612846] Subtest psr-primary-page-flip: SUCCESS (2.792s)
[561.897420] [125/161] (562s left) kms_psr (psr-cursor-plane-move)
[562.384274] Starting subtest: psr-cursor-plane-move
[562.395517] Starting dynamic subtest: eDP-1
[564.939308] Dynamic subtest eDP-1: SUCCESS (2.542s)
[564.942958] Subtest psr-cursor-plane-move: SUCCESS (2.554s)
[566.130886] [126/161] (558s left) kms_psr (psr-sprite-plane-onoff)
[566.628869] Starting subtest: psr-sprite-plane-onoff
[566.632232] Starting dynamic subtest: eDP-1
[569.268755] Dynamic subtest eDP-1: SUCCESS (2.633s)
[569.274153] Subtest psr-sprite-plane-onoff: SUCCESS (2.645s)
[570.567944] [127/161] (554s left) kms_psr (psr-primary-mmap-gtt)
[571.064822] Starting subtest: psr-primary-mmap-gtt
[571.068113] Starting dynamic subtest: eDP-1
[573.844435] Dynamic subtest eDP-1: SUCCESS (2.774s)
[573.847547] Subtest psr-primary-mmap-gtt: SUCCESS (2.786s)
[575.107365] [128/161] (550s left) kms_setmode (basic-clone-single-crtc)
[575.494489] Starting subtest: basic-clone-single-crtc
[575.503666] Subtest basic-clone-single-crtc: SKIP (0.001s)
[575.966439] [129/161] (550s left) i915_pm_rps (basic-api)
[576.384306] Starting subtest: basic-api
[576.390441] Subtest basic-api: SKIP (0.004s)
[576.882309] [130/161] (549s left) prime_self_import (basic-llseek-bad)
[577.246904] Starting subtest: basic-llseek-bad
[577.261810] Subtest basic-llseek-bad: SUCCESS (0.014s)
[577.742867] [131/161] (549s left) prime_self_import (basic-llseek-size)
[578.110385] Starting subtest: basic-llseek-size
[578.131244] Subtest basic-llseek-size: SUCCESS (0.021s)
[578.626323] [132/161] (548s left) prime_self_import (basic-with_fd_dup)
[578.987261] Starting subtest: basic-with_fd_dup
[579.010435] Subtest basic-with_fd_dup: SUCCESS (0.021s)
[579.500220] [133/161] (548s left) prime_self_import (basic-with_one_bo)
[579.875409] Starting subtest: basic-with_one_bo
[579.891212] Subtest basic-with_one_bo: SUCCESS (0.022s)
[580.380935] [134/161] (547s left) prime_self_import (basic-with_one_bo_two_files)
[580.757877] Starting subtest: basic-with_one_bo_two_files
[580.772398] Subtest basic-with_one_bo_two_files: SUCCESS (0.018s)
[581.257242] [135/161] (547s left) prime_self_import (basic-with_two_bos)
[581.622482] Starting subtest: basic-with_two_bos
[581.642977] Subtest basic-with_two_bos: SUCCESS (0.021s)
[582.142231] [136/161] (546s left) prime_vgem (basic-fence-flip)
[582.584844] Starting subtest: basic-fence-flip
[584.026066] Subtest basic-fence-flip: SUCCESS (1.441s)
[584.579133] [137/161] (544s left) prime_vgem (basic-fence-mmap)
[585.046416] Starting subtest: basic-fence-mmap
[585.251544] Subtest basic-fence-mmap: SUCCESS (0.195s)
[585.726490] [138/161] (543s left) prime_vgem (basic-fence-read)
[586.162775] Starting subtest: basic-fence-read
[586.222119] Subtest basic-fence-read: SKIP (0.057s)
[586.718202] [139/161] (543s left) prime_vgem (basic-gtt)
[587.155636] Starting subtest: basic-gtt
[587.244532] Subtest basic-gtt: SUCCESS (0.089s)
[587.744163] [140/161] (542s left) prime_vgem (basic-read)
[588.186676] Starting subtest: basic-read
[588.245176] Subtest basic-read: SKIP (0.055s)
[588.752458] [141/161] (542s left) prime_vgem (basic-write)
[589.194766] Starting subtest: basic-write
[589.253253] Subtest basic-write: SKIP (0.056s)
[589.761094] [142/161] (541s left) vgem_basic (setversion)
[590.169361] Starting subtest: setversion
[590.175890] Subtest setversion: SUCCESS (0.000s)
[590.612293] [143/161] (541s left) vgem_basic (create)
[591.015581] Starting subtest: create
[591.022955] Subtest create: SUCCESS (0.002s)
[591.478351] [144/161] (540s left) vgem_basic (debugfs)
[591.877789] Starting subtest: debugfs
[591.881586] Subtest debugfs: SUCCESS (0.002s)
[592.345093] [145/161] (540s left) vgem_basic (dmabuf-export)
[592.750170] Starting subtest: dmabuf-export
[592.764960] Subtest dmabuf-export: SUCCESS (0.018s)
[593.232164] [146/161] (539s left) vgem_basic (dmabuf-fence)
[593.632603] Starting subtest: dmabuf-fence
[593.636121] Subtest dmabuf-fence: SUCCESS (0.003s)
[594.095977] [147/161] (539s left) vgem_basic (dmabuf-fence-before)
[594.497890] Starting subtest: dmabuf-fence-before
[594.501313] Subtest dmabuf-fence-before: SUCCESS (0.003s)
[594.958399] [148/161] (538s left) vgem_basic (dmabuf-mmap)
[595.362588] Starting subtest: dmabuf-mmap
[595.455814] Subtest dmabuf-mmap: SUCCESS (0.100s)
[595.910444] [149/161] (538s left) vgem_basic (mmap)
[596.310857] Starting subtest: mmap
[596.370166] Subtest mmap: SUCCESS (0.064s)
[596.825248] [150/161] (537s left) vgem_basic (second-client)
[597.228622] Starting subtest: second-client
[597.278800] Subtest second-client: SUCCESS (0.047s)
[597.734197] [151/161] (537s left) vgem_basic (sysfs)
[598.141263] Starting subtest: sysfs
[598.144815] Subtest sysfs: SUCCESS (0.004s)
[598.604510] [152/161] (536s left) core_hotunplug (unbind-rebind)
[599.293443] Starting subtest: unbind-rebind
[608.335180] Subtest unbind-rebind: SUCCESS (9.036s)
[608.728204] [153/161] (526s left) vgem_basic (unload)
[609.101403] Starting subtest: unload
[638.915357] Subtest unload: SUCCESS (29.804s)
[639.567890] [154/161] (496s left) i915_module_load (reload)
[639.946927] Starting subtest: reload
[654.694375] Subtest reload: SUCCESS (14.746s)
[655.213279] [155/161] (481s left) gem_lmem_swapping (basic)
[655.739113] Subtest basic: SKIP (0.000s)
[656.189320] [156/161] (480s left) gem_lmem_swapping (parallel-random-engines)
[656.606990] Subtest parallel-random-engines: SKIP (0.000s)
[657.031974] [157/161] (480s left) gem_lmem_swapping (random-engines)
[657.448347] Subtest random-engines: SKIP (0.000s)
[657.870504] [158/161] (479s left) gem_lmem_swapping (verify-random)
[658.284931] Subtest verify-random: SKIP (0.000s)
[658.714753] [159/161] (479s left) i915_pm_rpm (module-reload)
[662.130685] Starting subtest: module-reload
[695.615940] Subtest module-reload: SUCCESS (33.476s)
[695.869085] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[695.882396] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[695.908523] [160/161] (442s left) i915_selftest (live)
[696.314655] Starting subtest: live
[696.318250] Starting dynamic subtest: sanitycheck
[706.737885] Dynamic subtest sanitycheck: SUCCESS (10.415s)
[706.805795] Starting dynamic subtest: uncore
[717.110852] Dynamic subtest uncore: SUCCESS (10.363s)
[717.177133] Starting dynamic subtest: workarounds
[733.441718] Dynamic subtest workarounds: SUCCESS (16.318s)
[733.510055] Starting dynamic subtest: gt_engines
[744.519445] Dynamic subtest gt_engines: SUCCESS (11.071s)
[744.584978] Starting dynamic subtest: gt_timelines
[789.059215] Dynamic subtest gt_timelines: SUCCESS (44.530s)
[789.125460] Starting dynamic subtest: gt_contexts
[799.630689] Dynamic subtest gt_contexts: SUCCESS (10.562s)
[799.700614] Starting dynamic subtest: gt_lrc
[813.694557] Dynamic subtest gt_lrc: SUCCESS (14.054s)
[813.763129] Starting dynamic subtest: gt_mocs
[825.288424] Dynamic subtest gt_mocs: SUCCESS (11.579s)
[825.361717] Starting dynamic subtest: gt_pm
[837.746722] Dynamic subtest gt_pm: SUCCESS (12.451s)
[837.817453] Starting dynamic subtest: gt_heartbeat
[848.242877] Dynamic subtest gt_heartbeat: SUCCESS (10.487s)
[848.308283] Starting dynamic subtest: gt_tlb
[859.813276] Dynamic subtest gt_tlb: SUCCESS (11.561s)
[859.879567] Starting dynamic subtest: requests
[880.309279] Dynamic subtest requests: SUCCESS (20.462s)
[880.373764] Starting dynamic subtest: migrate
[894.648795] Dynamic subtest migrate: SUCCESS (14.353s)
[894.717567] Starting dynamic subtest: active
[905.139597] Dynamic subtest active: SUCCESS (10.483s)
[905.205422] Starting dynamic subtest: objects
[919.702617] Dynamic subtest objects: SUCCESS (14.554s)
[919.771153] Starting dynamic subtest: mman
[941.271349] Dynamic subtest mman: SUCCESS (21.569s)
[941.353629] Starting dynamic subtest: dmabuf
[952.221644] Dynamic subtest dmabuf: SUCCESS (10.930s)
[952.285396] Starting dynamic subtest: vma
[962.762213] Dynamic subtest vma: SUCCESS (10.532s)
[962.828413] Starting dynamic subtest: coherency
[991.944622] Dynamic subtest coherency: SUCCESS (29.171s)
[992.010648] Starting dynamic subtest: gtt
[1010.554632] Dynamic subtest gtt: SUCCESS (18.602s)
[1010.639664] Starting dynamic subtest: gem
[1022.165646] Dynamic subtest gem: SUCCESS (11.611s)
[1022.240749] Starting dynamic subtest: evict
[1040.576111] Dynamic subtest evict: SUCCESS (18.386s)
[1040.646813] Starting dynamic subtest: hugepages
[1058.133155] Dynamic subtest hugepages: SUCCESS (17.547s)
[1058.220725] Starting dynamic subtest: gem_contexts
[1099.952813] Dynamic subtest gem_contexts: SUCCESS (41.811s)
[1100.025996] Starting dynamic subtest: client
[1110.526503] Dynamic subtest client: SUCCESS (10.545s)
[1110.613329] Starting dynamic subtest: gem_migrate
[1120.971993] Dynamic subtest gem_migrate: SUCCESS (10.428s)
[1121.041245] Starting dynamic subtest: reset
[1135.058747] Dynamic subtest reset: SUCCESS (14.076s)
[1135.132651] Starting dynamic subtest: memory_region
[1145.472506] Dynamic subtest memory_region: SUCCESS (10.407s)
[1145.536400] Starting dynamic subtest: hangcheck
[1176.852301] Dynamic subtest hangcheck: SUCCESS (31.369s)
[1176.918446] Starting dynamic subtest: execlists
[1187.288884] Dynamic subtest execlists: SUCCESS (10.436s)
[1187.368206] Starting dynamic subtest: ring_submission
[1197.617797] Dynamic subtest ring_submission: SUCCESS (10.311s)
[1197.689659] Starting dynamic subtest: perf
[1208.555922] Dynamic subtest perf: SUCCESS (10.938s)
[1208.633578] Starting dynamic subtest: slpc
[1220.840209] Dynamic subtest slpc: SUCCESS (12.260s)
[1220.911900] Starting dynamic subtest: guc
[1233.819193] Dynamic subtest guc: SUCCESS (12.969s)
[1233.888620] Starting dynamic subtest: guc_multi_lrc
[1244.239756] Dynamic subtest guc_multi_lrc: SUCCESS (10.415s)
[1244.307303] Starting dynamic subtest: guc_hang
[1256.021666] Dynamic subtest guc_hang: SUCCESS (11.773s)
[1256.088612] Starting dynamic subtest: late_gt_pm
[1266.589924] Dynamic subtest late_gt_pm: SUCCESS (10.558s)
[1266.593507] Subtest live: SUCCESS (570.255s)
[1266.709221] Overall timeout time exceeded, stopping.
[1266.887989] Closing watchdogs
Done.