[101.358712] Initializing watchdogs
[101.359407]   /dev/watchdog0
[101.523542] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:46d3 Intel Alderlake_n (Gen12) 
[101.562894] [001/161] (960s left) i915_module_load (load)
[102.738550] Starting subtest: load
[114.082479] Subtest load: SUCCESS (11.348s)
[114.414640] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[114.429486] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[114.451308] [002/161] (947s left) core_auth (basic-auth)
[115.013273] Starting subtest: basic-auth
[115.048579] Subtest basic-auth: SUCCESS (0.014s)
[115.532468] [003/161] (947s left) debugfs_test (read_all_entries)
[115.973178] Starting subtest: read_all_entries
[117.438249] Subtest read_all_entries: SUCCESS (1.462s)
[117.968820] [004/161] (945s left) debugfs_test (basic-hwmon)
[118.385379] Starting subtest: basic-hwmon
[118.391544] Subtest basic-hwmon: SKIP (0.001s)
[118.899664] [005/161] (944s left) debugfs_test (sysfs)
[119.307212] Starting subtest: sysfs
[119.784384] Subtest sysfs: SUCCESS (0.477s)
[120.315673] [006/161] (943s left) fbdev (eof)
[120.739291] Starting subtest: eof
[120.746070] Subtest eof: SUCCESS (0.005s)
[121.168211] [007/161] (942s left) fbdev (info)
[121.533352] Starting subtest: info
[121.536440] Subtest info: SUCCESS (0.000s)
[121.969297] [008/161] (942s left) fbdev (nullptr)
[122.341788] Starting subtest: nullptr
[122.344949] Subtest nullptr: SUCCESS (0.000s)
[122.769315] [009/161] (942s left) fbdev (read)
[123.160655] Starting subtest: read
[136.801643] Subtest read: SUCCESS (13.634s)
[137.231965] [010/161] (927s left) fbdev (write)
[137.618611] Starting subtest: write
[144.741329] Subtest write: SUCCESS (7.117s)
[145.193663] [011/161] (920s left) gem_basic (bad-close)
[145.568480] Starting subtest: bad-close
[145.573966] Subtest bad-close: SUCCESS (0.000s)
[145.981440] [012/161] (919s left) gem_basic (create-close)
[146.355885] Starting subtest: create-close
[146.362799] Subtest create-close: SUCCESS (0.001s)
[146.789432] [013/161] (919s left) gem_basic (create-fd-close)
[147.165229] Starting subtest: create-fd-close
[147.177756] Subtest create-fd-close: SUCCESS (0.001s)
[147.598360] [014/161] (918s left) gem_busy (busy@all-engines)
[148.070881] Starting subtest: busy
[148.074371] Starting dynamic subtest: all-engines
[148.384498] Dynamic subtest all-engines: SUCCESS (0.312s)
[148.387629] Subtest busy: SUCCESS (0.314s)
[149.266376] [015/161] (917s left) gem_close_race (basic-process)
[149.738836] Starting subtest: basic-process
[149.910879] Subtest basic-process: SUCCESS (0.173s)
[150.482641] [016/161] (916s left) gem_close_race (basic-threads)
[150.960761] Starting subtest: basic-threads
[152.515892] Subtest basic-threads: SUCCESS (1.556s)
[153.065593] [017/161] (914s left) gem_ctx_create (basic)
[153.524243] Starting subtest: basic
[153.527251] Subtest basic: SUCCESS (0.000s)
[154.077570] [018/161] (913s left) gem_ctx_create (basic-files)
[154.541696] Starting subtest: basic-files
[156.656822] Subtest basic-files: SUCCESS (2.118s)
[157.327591] [019/161] (910s left) gem_ctx_exec (basic)
[157.734558] Starting subtest: basic
[157.751075] Subtest basic: SUCCESS (0.018s)
[158.201583] [020/161] (910s left) gem_exec_basic (basic)
[158.648851] Starting subtest: basic
[158.653086] Starting dynamic subtest: rcs0-smem
[158.662944] Dynamic subtest rcs0-smem: SUCCESS (0.009s)
[158.731523] Starting dynamic subtest: bcs0-smem
[158.734867] Dynamic subtest bcs0-smem: SUCCESS (0.003s)
[158.795627] Starting dynamic subtest: vcs0-smem
[158.798898] Dynamic subtest vcs0-smem: SUCCESS (0.004s)
[158.868262] Starting dynamic subtest: vecs0-smem
[158.872810] Dynamic subtest vecs0-smem: SUCCESS (0.004s)
[158.878096] Subtest basic: SUCCESS (0.029s)
[159.253577] [021/161] (909s left) gem_exec_create (basic)
[159.744622] Starting subtest: basic
[159.747919] Starting dynamic subtest: smem
[161.949538] Dynamic subtest smem: SUCCESS (2.204s)
[161.952705] Subtest basic: SUCCESS (2.207s)
[162.505580] [022/161] (906s left) gem_exec_fence (basic-busy)
[163.170073] Starting subtest: basic-busy
[163.173397] Starting dynamic subtest: rcs0
[163.253684] Dynamic subtest rcs0: SUCCESS (0.085s)
[163.322561] Starting dynamic subtest: bcs0
[163.329293] Dynamic subtest bcs0: SUCCESS (0.069s)
[163.390731] Starting dynamic subtest: vcs0
[163.396969] Dynamic subtest vcs0: SUCCESS (0.064s)
[163.458221] Starting dynamic subtest: vecs0
[163.464671] Dynamic subtest vecs0: SUCCESS (0.062s)
[163.467549] Subtest basic-busy: SUCCESS (0.288s)
[164.934936] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[164.954913] [023/161] (904s left) gem_exec_fence (basic-wait)
[165.642795] Starting subtest: basic-wait
[165.646217] Starting dynamic subtest: rcs0
[165.719221] Dynamic subtest rcs0: SUCCESS (0.078s)
[165.781504] Starting dynamic subtest: bcs0
[165.789814] Dynamic subtest bcs0: SUCCESS (0.067s)
[165.853386] Starting dynamic subtest: vcs0
[165.857150] Dynamic subtest vcs0: SUCCESS (0.061s)
[165.921283] Starting dynamic subtest: vecs0
[165.924677] Dynamic subtest vecs0: SUCCESS (0.069s)
[165.927591] Subtest basic-wait: SUCCESS (0.283s)
[167.212543] [024/161] (902s left) gem_exec_fence (basic-await)
[167.883649] Starting subtest: basic-await
[167.886909] Starting dynamic subtest: rcs0
[168.219827] Dynamic subtest rcs0: SUCCESS (0.338s)
[168.286230] Starting dynamic subtest: bcs0
[168.528410] Dynamic subtest bcs0: SUCCESS (0.305s)
[168.592760] Starting dynamic subtest: vcs0
[168.832708] Dynamic subtest vcs0: SUCCESS (0.302s)
[168.901231] Starting dynamic subtest: vecs0
[169.138662] Dynamic subtest vecs0: SUCCESS (0.304s)
[169.141920] Subtest basic-await: SUCCESS (1.258s)
[170.395375] [025/161] (899s left) gem_exec_fence (nb-await)
[171.055254] Starting subtest: nb-await
[171.058960] Starting dynamic subtest: rcs0
[171.184546] Dynamic subtest rcs0: SUCCESS (0.119s)
[171.246816] Starting dynamic subtest: bcs0
[171.270448] Dynamic subtest bcs0: SUCCESS (0.092s)
[171.333354] Starting dynamic subtest: vcs0
[171.361403] Dynamic subtest vcs0: SUCCESS (0.089s)
[171.427991] Starting dynamic subtest: vecs0
[171.452597] Dynamic subtest vecs0: SUCCESS (0.089s)
[171.455537] Subtest nb-await: SUCCESS (0.399s)
[172.739183] [026/161] (897s left) gem_exec_gttfill (basic)
[173.208183] Starting subtest: basic
[211.128200] Subtest basic: SUCCESS (37.913s)
[211.743107] [027/161] (859s left) gem_exec_parallel (engines)
[212.377078] Starting subtest: engines
[212.381138] Starting dynamic subtest: basic
[219.232457] Dynamic subtest basic: SUCCESS (6.851s)
[219.309484] Starting dynamic subtest: contexts
[230.649604] Dynamic subtest contexts: SUCCESS (11.402s)
[230.719834] Starting dynamic subtest: fds
[243.034363] Dynamic subtest fds: SUCCESS (12.383s)
[243.108865] Starting dynamic subtest: userptr
[248.664797] Dynamic subtest userptr: SUCCESS (5.633s)
[248.667765] Subtest engines: SUCCESS (36.284s)
[249.289866] [028/161] (822s left) gem_exec_store (basic)
[249.761311] Starting subtest: basic
[249.895587] Subtest basic: SUCCESS (0.135s)
[250.493752] [029/161] (821s left) gem_flink_basic (bad-flink)
[250.861522] Starting subtest: bad-flink
[250.864540] Subtest bad-flink: SUCCESS (0.000s)
[251.323392] [030/161] (820s left) gem_flink_basic (bad-open)
[251.698306] Starting subtest: bad-open
[251.701195] Subtest bad-open: SUCCESS (0.000s)
[252.147371] [031/161] (820s left) gem_flink_basic (basic)
[252.526267] Starting subtest: basic
[252.528975] Subtest basic: SUCCESS (0.001s)
[252.998605] [032/161] (819s left) gem_flink_basic (double-flink)
[253.369905] Starting subtest: double-flink
[253.372794] Subtest double-flink: SUCCESS (0.001s)
[253.841372] [033/161] (819s left) gem_flink_basic (flink-lifetime)
[254.218298] Starting subtest: flink-lifetime
[254.224714] Subtest flink-lifetime: SUCCESS (0.009s)
[254.703392] [034/161] (818s left) gem_huc_copy (huc-copy)
[255.110862] Starting subtest: huc-copy
[255.132993] Subtest huc-copy: SUCCESS (0.021s)
[255.659092] [035/161] (818s left) gem_linear_blits (basic)
[256.689109] Starting subtest: basic
[256.741386] Subtest basic: SUCCESS (0.060s)
[257.301353] [036/161] (817s left) gem_mmap (basic)
[257.877331] Starting subtest: basic
[257.890490] Subtest basic: SUCCESS (0.003s)
[258.353858] [037/161] (816s left) gem_mmap_gtt (basic)
[258.725656] Starting subtest: basic
[258.728616] Subtest basic: SUCCESS (0.005s)
[259.189564] [038/161] (815s left) gem_render_linear_blits (basic)
[259.608257] Starting subtest: basic
[259.821604] Subtest basic: SUCCESS (0.213s)
[260.304060] [039/161] (815s left) gem_render_tiled_blits (basic)
[260.719105] Starting subtest: basic
[261.004355] Subtest basic: SUCCESS (0.286s)
[261.468886] [040/161] (814s left) gem_ringfill (basic-all)
[262.385449] Starting subtest: basic-all
[263.870355] Subtest basic-all: SUCCESS (1.475s)
[264.369043] [041/161] (811s left) gem_softpin (allocator-basic)
[264.805096] Starting subtest: allocator-basic
[265.524498] Subtest allocator-basic: SUCCESS (0.706s)
[266.164427] [042/161] (810s left) gem_softpin (allocator-basic-reserve)
[266.594797] Starting subtest: allocator-basic-reserve
[267.288258] Subtest allocator-basic-reserve: SUCCESS (0.691s)
[267.918747] [043/161] (809s left) gem_softpin (safe-alignment)
[268.343798] Starting subtest: safe-alignment
[268.415528] Subtest safe-alignment: SUCCESS (0.073s)
[268.947674] [044/161] (808s left) gem_sync (basic-all)
[269.436307] Starting subtest: basic-all
[274.266734] Subtest basic-all: SUCCESS (4.829s)
[274.864634] [045/161] (802s left) gem_sync (basic-each)
[275.338482] Starting subtest: basic-each
[278.916358] Subtest basic-each: SUCCESS (3.579s)
[279.509746] [046/161] (798s left) gem_tiled_blits (basic)
[280.589144] Starting subtest: basic
[280.712911] Subtest basic: SUCCESS (0.127s)
[281.244866] [047/161] (797s left) gem_tiled_fence_blits (basic)
[282.430878] Starting subtest: basic
[282.498740] Subtest basic: SUCCESS (0.076s)
[283.079403] [048/161] (795s left) gem_tiled_pread_basic
[284.066413] [049/161] (795s left) gem_wait (busy@all-engines)
[284.595496] Starting subtest: busy
[284.598742] Starting dynamic subtest: all-engines
[285.182760] Dynamic subtest all-engines: SUCCESS (0.592s)
[285.186539] Subtest busy: SUCCESS (0.594s)
[285.959437] [050/161] (793s left) gem_wait (wait@all-engines)
[286.486935] Starting subtest: wait
[286.490248] Starting dynamic subtest: all-engines
[287.582867] Dynamic subtest all-engines: SUCCESS (1.106s)
[287.586045] Subtest wait: SUCCESS (1.108s)
[288.313620] [051/161] (791s left) i915_getparams_basic (basic-eu-total)
[288.680861] Starting subtest: basic-eu-total
[288.686420] Subtest basic-eu-total: SUCCESS (0.000s)
[289.135553] [052/161] (791s left) i915_getparams_basic (basic-subslice-total)
[289.505715] Starting subtest: basic-subslice-total
[289.511649] Subtest basic-subslice-total: SUCCESS (0.000s)
[289.965703] [053/161] (790s left) i915_hangman (error-state-basic)
[290.424900] Starting subtest: error-state-basic
[291.246214] Subtest error-state-basic: SUCCESS (0.822s)
[291.752150] [054/161] (789s left) i915_pciid
[292.585915] [055/161] (789s left) kms_addfb_basic (addfb25-4-tiled)
[293.096937] Starting subtest: addfb25-4-tiled
[293.100331] Subtest addfb25-4-tiled: SUCCESS (0.002s)
[293.567365] [056/161] (788s left) kms_addfb_basic (addfb25-bad-modifier)
[294.050351] Starting subtest: addfb25-bad-modifier
[294.054640] Subtest addfb25-bad-modifier: SUCCESS (0.002s)
[294.540646] [057/161] (787s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[295.021939] Starting subtest: addfb25-framebuffer-vs-set-tiling
[295.025492] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.002s)
[295.511799] [058/161] (787s left) kms_addfb_basic (addfb25-modifier-no-flag)
[295.990151] Starting subtest: addfb25-modifier-no-flag
[295.993158] Subtest addfb25-modifier-no-flag: SUCCESS (0.001s)
[296.473080] [059/161] (786s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[296.954364] Starting subtest: addfb25-x-tiled-legacy
[296.957452] Subtest addfb25-x-tiled-legacy: SUCCESS (0.001s)
[297.423465] [060/161] (786s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[297.901345] Starting subtest: addfb25-x-tiled-mismatch-legacy
[297.904422] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.002s)
[298.381842] [061/161] (785s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[298.865875] Starting subtest: addfb25-yf-tiled-legacy
[298.869548] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.002s)
[299.346938] [062/161] (784s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[299.831083] Starting subtest: addfb25-y-tiled-legacy
[299.834586] Subtest addfb25-y-tiled-legacy: SUCCESS (0.001s)
[300.322604] [063/161] (784s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[300.897219] Starting subtest: addfb25-y-tiled-small-legacy
[300.900192] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.002s)
[301.432745] [064/161] (783s left) kms_addfb_basic (bad-pitch-0)
[301.888165] Starting subtest: bad-pitch-0
[301.893525] Subtest bad-pitch-0: SUCCESS (0.000s)
[302.549354] [065/161] (783s left) kms_addfb_basic (bad-pitch-1024)
[302.935731] Starting subtest: bad-pitch-1024
[302.938679] Subtest bad-pitch-1024: SUCCESS (0.000s)
[303.515931] [066/161] (782s left) kms_addfb_basic (bad-pitch-128)
[303.894822] Starting subtest: bad-pitch-128
[303.897836] Subtest bad-pitch-128: SUCCESS (0.000s)
[304.487701] [067/161] (781s left) kms_addfb_basic (bad-pitch-256)
[304.863316] Starting subtest: bad-pitch-256
[304.866372] Subtest bad-pitch-256: SUCCESS (0.000s)
[305.428697] [068/161] (781s left) kms_addfb_basic (bad-pitch-32)
[305.805201] Starting subtest: bad-pitch-32
[305.808234] Subtest bad-pitch-32: SUCCESS (0.000s)
[306.385843] [069/161] (780s left) kms_addfb_basic (bad-pitch-63)
[306.759893] Starting subtest: bad-pitch-63
[306.763830] Subtest bad-pitch-63: SUCCESS (0.000s)
[307.332909] [070/161] (780s left) kms_addfb_basic (bad-pitch-65536)
[307.710575] Starting subtest: bad-pitch-65536
[307.713419] Subtest bad-pitch-65536: SUCCESS (0.002s)
[308.279437] [071/161] (779s left) kms_addfb_basic (bad-pitch-999)
[308.659089] Starting subtest: bad-pitch-999
[308.662344] Subtest bad-pitch-999: SUCCESS (0.000s)
[309.220353] [072/161] (778s left) kms_addfb_basic (basic)
[309.593349] Starting subtest: basic
[309.596397] Subtest basic: SUCCESS (0.000s)
[310.165707] [073/161] (778s left) kms_addfb_basic (basic-x-tiled-legacy)
[310.543952] Starting subtest: basic-x-tiled-legacy
[310.547449] Subtest basic-x-tiled-legacy: SUCCESS (0.001s)
[311.124367] [074/161] (777s left) kms_addfb_basic (basic-y-tiled-legacy)
[311.501960] Starting subtest: basic-y-tiled-legacy
[311.504976] Subtest basic-y-tiled-legacy: SUCCESS (0.002s)
[312.094361] [075/161] (777s left) kms_addfb_basic (bo-too-small)
[312.576814] Starting subtest: bo-too-small
[312.580408] Subtest bo-too-small: SUCCESS (0.002s)
[313.058583] [076/161] (776s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[313.537632] Starting subtest: bo-too-small-due-to-tiling
[313.540604] Subtest bo-too-small-due-to-tiling: SUCCESS (0.002s)
[314.012226] [077/161] (776s left) kms_addfb_basic (clobberred-modifier)
[314.388739] Starting subtest: clobberred-modifier
[314.391388] Subtest clobberred-modifier: SUCCESS (0.001s)
[314.962449] [078/161] (775s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[315.357051] Starting subtest: framebuffer-vs-set-tiling
[315.360294] Subtest framebuffer-vs-set-tiling: SUCCESS (0.002s)
[315.998526] [079/161] (774s left) kms_addfb_basic (invalid-get-prop)
[316.377614] Starting subtest: invalid-get-prop
[316.380456] Subtest invalid-get-prop: SUCCESS (0.000s)
[316.945449] [080/161] (774s left) kms_addfb_basic (invalid-get-prop-any)
[317.319730] Starting subtest: invalid-get-prop-any
[317.322924] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[317.912428] [081/161] (773s left) kms_addfb_basic (invalid-set-prop)
[318.285451] Starting subtest: invalid-set-prop
[318.288963] Subtest invalid-set-prop: SUCCESS (0.000s)
[318.862310] [082/161] (773s left) kms_addfb_basic (invalid-set-prop-any)
[319.244708] Starting subtest: invalid-set-prop-any
[319.248148] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[319.810786] [083/161] (772s left) kms_addfb_basic (no-handle)
[320.199479] Starting subtest: no-handle
[320.203047] Subtest no-handle: SUCCESS (0.000s)
[320.783948] [084/161] (771s left) kms_addfb_basic (size-max)
[321.264269] Starting subtest: size-max
[321.267098] Subtest size-max: SUCCESS (0.001s)
[321.752842] [085/161] (771s left) kms_addfb_basic (small-bo)
[322.235774] Starting subtest: small-bo
[322.239628] Subtest small-bo: SUCCESS (0.000s)
[322.711416] [086/161] (770s left) kms_addfb_basic (tile-pitch-mismatch)
[323.090690] Starting subtest: tile-pitch-mismatch
[323.094541] Subtest tile-pitch-mismatch: SUCCESS (0.002s)
[323.665092] [087/161] (770s left) kms_addfb_basic (too-high)
[324.143623] Starting subtest: too-high
[324.167162] Subtest too-high: SUCCESS (0.006s)
[324.623817] [088/161] (769s left) kms_addfb_basic (too-wide)
[325.106487] Starting subtest: too-wide
[325.109989] Subtest too-wide: SUCCESS (0.000s)
[325.588888] [089/161] (769s left) kms_addfb_basic (unused-handle)
[325.965738] Starting subtest: unused-handle
[325.968970] Subtest unused-handle: SUCCESS (0.000s)
[326.543552] [090/161] (768s left) kms_addfb_basic (unused-modifier)
[326.915617] Starting subtest: unused-modifier
[326.919898] Subtest unused-modifier: SUCCESS (0.000s)
[327.501867] [091/161] (767s left) kms_addfb_basic (unused-offsets)
[327.880429] Starting subtest: unused-offsets
[327.883346] Subtest unused-offsets: SUCCESS (0.000s)
[328.469790] [092/161] (767s left) kms_addfb_basic (unused-pitches)
[328.845555] Starting subtest: unused-pitches
[328.848654] Subtest unused-pitches: SUCCESS (0.000s)
[329.424303] [093/161] (766s left) kms_busy (basic)
[329.933431] Starting subtest: basic
[329.938651] Starting dynamic subtest: flip
[332.277347] Dynamic subtest flip: SUCCESS (2.336s)
[332.364302] Starting dynamic subtest: modeset
[337.474793] Dynamic subtest modeset: SUCCESS (5.195s)
[337.477605] Subtest basic: SUCCESS (7.542s)
[338.806481] [094/161] (757s left) kms_prop_blob (basic)
[339.178641] Starting subtest: basic
[339.182302] Subtest basic: SUCCESS (0.000s)
[339.634158] [095/161] (757s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[340.253532] Starting subtest: basic-busy-flip-before-cursor-atomic
[340.259707] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[340.845205] [096/161] (756s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[341.453440] Starting subtest: basic-busy-flip-before-cursor-legacy
[341.459154] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[342.051822] [097/161] (755s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[342.660684] Starting subtest: basic-flip-after-cursor-atomic
[344.279811] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.619s)
[345.620974] [098/161] (752s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[346.237339] Starting subtest: basic-flip-after-cursor-legacy
[347.834829] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.595s)
[349.117685] [099/161] (749s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[349.730331] Starting subtest: basic-flip-after-cursor-varying-size
[352.170124] Subtest basic-flip-after-cursor-varying-size: SUCCESS (2.433s)
[353.194364] [100/161] (745s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[353.805933] Starting subtest: basic-flip-before-cursor-atomic
[356.228473] Subtest basic-flip-before-cursor-atomic: SUCCESS (2.415s)
[357.291785] [101/161] (741s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[357.906055] Starting subtest: basic-flip-before-cursor-legacy
[360.313818] Subtest basic-flip-before-cursor-legacy: SUCCESS (2.398s)
[361.300392] [102/161] (738s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[361.917717] Starting subtest: basic-flip-before-cursor-varying-size
[363.523423] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.608s)
[364.832930] [103/161] (735s left) kms_dsc (dsc-basic)
[365.311588] Starting subtest: dsc-basic
[365.326399] Subtest dsc-basic: SKIP (0.003s)
[365.781575] [104/161] (734s left) kms_flip (basic-flip-vs-dpms)
[366.262497] Starting subtest: basic-flip-vs-dpms
[366.274297] Starting dynamic subtest: A-eDP1
[370.687161] Dynamic subtest A-eDP1: SUCCESS (4.410s)
[370.775211] Starting dynamic subtest: B-eDP1
[373.975288] Dynamic subtest B-eDP1: SUCCESS (3.286s)
[374.065305] Starting dynamic subtest: C-eDP1
[377.404353] Dynamic subtest C-eDP1: SUCCESS (3.420s)
[377.411457] Subtest basic-flip-vs-dpms: SUCCESS (11.148s)
[378.686455] [105/161] (722s left) kms_flip (basic-flip-vs-modeset)
[379.168414] Starting subtest: basic-flip-vs-modeset
[379.178901] Starting dynamic subtest: A-eDP1
[383.021154] Dynamic subtest A-eDP1: SUCCESS (3.839s)
[383.105652] Starting dynamic subtest: B-eDP1
[386.122550] Dynamic subtest B-eDP1: SUCCESS (3.094s)
[386.210111] Starting dynamic subtest: C-eDP1
[389.881667] Dynamic subtest C-eDP1: SUCCESS (3.756s)
[389.889638] Subtest basic-flip-vs-modeset: SUCCESS (10.724s)
[390.976129] [106/161] (710s left) kms_flip (basic-flip-vs-wf_vblank)
[391.453534] Starting subtest: basic-flip-vs-wf_vblank
[391.465763] Starting dynamic subtest: A-eDP1
[394.578719] Dynamic subtest A-eDP1: SUCCESS (3.113s)
[394.668537] Starting dynamic subtest: B-eDP1
[397.591980] Dynamic subtest B-eDP1: SUCCESS (3.007s)
[397.680505] Starting dynamic subtest: C-eDP1
[400.584786] Dynamic subtest C-eDP1: SUCCESS (2.987s)
[400.595778] Subtest basic-flip-vs-wf_vblank: SUCCESS (9.144s)
[402.863809] [107/161] (698s left) kms_flip (basic-plain-flip)
[403.344915] Starting subtest: basic-plain-flip
[403.356494] Starting dynamic subtest: A-eDP1
[406.171211] Dynamic subtest A-eDP1: SUCCESS (2.814s)
[406.259467] Starting dynamic subtest: B-eDP1
[409.457062] Dynamic subtest B-eDP1: SUCCESS (3.280s)
[409.545937] Starting dynamic subtest: C-eDP1
[412.242183] Dynamic subtest C-eDP1: SUCCESS (2.778s)
[412.251656] Subtest basic-plain-flip: SUCCESS (8.910s)
[414.240491] [108/161] (687s left) kms_force_connector_basic (force-connector-state)
[414.640215] Starting subtest: force-connector-state
[415.481235] Subtest force-connector-state: SUCCESS (0.843s)
[417.145898] [109/161] (685s left) kms_force_connector_basic (force-edid)
[417.541689] Starting subtest: force-edid
[417.657453] Subtest force-edid: SUCCESS (0.109s)
[418.298086] [110/161] (684s left) kms_force_connector_basic (force-load-detect)
[418.700762] Starting subtest: force-load-detect
[418.707055] Subtest force-load-detect: SKIP (0.000s)
[419.282908] [111/161] (683s left) kms_force_connector_basic (prune-stale-modes)
[419.688523] Starting subtest: prune-stale-modes
[419.798980] Subtest prune-stale-modes: SUCCESS (0.113s)
[420.506123] [112/161] (682s left) kms_frontbuffer_tracking (basic)
[421.028980] Starting subtest: basic
[448.097193] Subtest basic: SUCCESS (27.065s)
[448.695388] [113/161] (655s left) kms_hdmi_inject (inject-audio)
[449.511860] Starting subtest: inject-audio
[450.075211] Subtest inject-audio: SUCCESS (0.556s)
[451.531824] [114/161] (652s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[452.008459] Starting subtest: compare-crc-sanitycheck-xr24
[452.018832] Starting dynamic subtest: pipe-A-eDP-1
[453.805416] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.784s)
[453.907233] Starting dynamic subtest: pipe-B-eDP-1
[456.020217] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.193s)
[456.113214] Starting dynamic subtest: pipe-C-eDP-1
[458.198086] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.163s)
[458.200965] Subtest compare-crc-sanitycheck-xr24: SUCCESS (6.186s)
[459.425454] [115/161] (645s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[459.907726] Starting subtest: compare-crc-sanitycheck-nv12
[459.915750] Starting dynamic subtest: pipe-A-eDP-1
[462.874681] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.956s)
[462.974654] Starting dynamic subtest: pipe-B-eDP-1
[467.090967] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.204s)
[467.188218] Starting dynamic subtest: pipe-C-eDP-1
[470.339285] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.231s)
[470.342204] Subtest compare-crc-sanitycheck-nv12: SUCCESS (10.436s)
[471.613360] [116/161] (633s left) kms_pipe_crc_basic (hang-read-crc)
[472.095865] Starting subtest: hang-read-crc
[472.105581] Starting dynamic subtest: pipe-A-eDP-1
[477.226481] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.114s)
[477.317821] Starting dynamic subtest: pipe-B-eDP-1
[482.846127] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.605s)
[482.938324] Starting dynamic subtest: pipe-C-eDP-1
[488.292732] Dynamic subtest pipe-C-eDP-1: SUCCESS (5.436s)
[488.295636] Subtest hang-read-crc: SUCCESS (16.200s)
[489.580364] [117/161] (615s left) kms_pipe_crc_basic (nonblocking-crc)
[490.069372] Starting subtest: nonblocking-crc
[490.074430] Starting dynamic subtest: pipe-A-eDP-1
[492.136300] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.062s)
[492.241207] Starting dynamic subtest: pipe-B-eDP-1
[495.455786] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.292s)
[495.555210] Starting dynamic subtest: pipe-C-eDP-1
[497.841239] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.378s)
[497.844095] Subtest nonblocking-crc: SUCCESS (7.778s)
[499.138722] [118/161] (606s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[499.621908] Starting subtest: nonblocking-crc-frame-sequence
[499.630886] Starting dynamic subtest: pipe-A-eDP-1
[501.700071] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.069s)
[501.800190] Starting dynamic subtest: pipe-B-eDP-1
[504.208718] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.492s)
[504.308314] Starting dynamic subtest: pipe-C-eDP-1
[506.613124] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.385s)
[506.616110] Subtest nonblocking-crc-frame-sequence: SUCCESS (6.994s)
[507.912398] [119/161] (598s left) kms_pipe_crc_basic (read-crc)
[508.396311] Starting subtest: read-crc
[508.407450] Starting dynamic subtest: pipe-A-eDP-1
[510.460310] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.030s)
[510.559326] Starting dynamic subtest: pipe-B-eDP-1
[512.906385] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.445s)
[513.004185] Starting dynamic subtest: pipe-C-eDP-1
[515.242531] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.321s)
[515.245421] Subtest read-crc: SUCCESS (6.845s)
[516.505603] [120/161] (590s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[516.989837] Starting subtest: read-crc-frame-sequence
[517.001753] Starting dynamic subtest: pipe-A-eDP-1
[519.770191] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.764s)
[519.871112] Starting dynamic subtest: pipe-B-eDP-1
[522.234219] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.445s)
[522.330810] Starting dynamic subtest: pipe-C-eDP-1
[524.572208] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.321s)
[524.575133] Subtest read-crc-frame-sequence: SUCCESS (7.580s)
[525.845273] [121/161] (581s left) kms_pm_backlight (basic-brightness)
[526.335416] Starting subtest: basic-brightness
[526.826876] Starting dynamic subtest: eDP-1
[527.305404] Dynamic subtest eDP-1: SUCCESS (0.488s)
[527.309174] Subtest basic-brightness: SUCCESS (0.975s)
[528.529131] [122/161] (578s left) kms_pm_rpm (basic-pci-d3-state)
[531.032314] Starting subtest: basic-pci-d3-state
[532.983272] Subtest basic-pci-d3-state: SUCCESS (1.953s)
[533.624261] [123/161] (574s left) kms_pm_rpm (basic-rte)
[533.990325] Starting subtest: basic-rte
[538.006525] Subtest basic-rte: SUCCESS (4.015s)
[540.793442] [124/161] (567s left) kms_psr (psr-primary-page-flip)
[541.279200] Starting subtest: psr-primary-page-flip
[541.290223] Starting dynamic subtest: eDP-1
[544.053758] Dynamic subtest eDP-1: SUCCESS (2.763s)
[544.057141] Subtest psr-primary-page-flip: SUCCESS (2.775s)
[545.342999] [125/161] (563s left) kms_psr (psr-cursor-plane-move)
[545.820925] Starting subtest: psr-cursor-plane-move
[545.831585] Starting dynamic subtest: eDP-1
[548.390407] Dynamic subtest eDP-1: SUCCESS (2.558s)
[548.393668] Subtest psr-cursor-plane-move: SUCCESS (2.570s)
[549.691346] [126/161] (559s left) kms_psr (psr-sprite-plane-onoff)
[550.175073] Starting subtest: psr-sprite-plane-onoff
[550.185523] Starting dynamic subtest: eDP-1
[552.850744] Dynamic subtest eDP-1: SUCCESS (2.661s)
[552.856689] Subtest psr-sprite-plane-onoff: SUCCESS (2.673s)
[554.145076] [127/161] (555s left) kms_psr (psr-primary-mmap-gtt)
[554.637599] Starting subtest: psr-primary-mmap-gtt
[554.640682] Starting dynamic subtest: eDP-1
[557.419915] Dynamic subtest eDP-1: SUCCESS (2.777s)
[557.423089] Subtest psr-primary-mmap-gtt: SUCCESS (2.789s)
[558.688407] [128/161] (551s left) kms_setmode (basic-clone-single-crtc)
[559.070271] Starting subtest: basic-clone-single-crtc
[559.078541] Subtest basic-clone-single-crtc: SKIP (0.001s)
[559.549904] [129/161] (550s left) i915_pm_rps (basic-api)
[559.967126] Starting subtest: basic-api
[559.972979] Subtest basic-api: SKIP (0.004s)
[560.478100] [130/161] (550s left) prime_self_import (basic-llseek-bad)
[560.840561] Starting subtest: basic-llseek-bad
[560.854702] Subtest basic-llseek-bad: SUCCESS (0.014s)
[561.351205] [131/161] (549s left) prime_self_import (basic-llseek-size)
[561.720875] Starting subtest: basic-llseek-size
[561.735543] Subtest basic-llseek-size: SUCCESS (0.022s)
[562.226980] [132/161] (549s left) prime_self_import (basic-with_fd_dup)
[562.586928] Starting subtest: basic-with_fd_dup
[562.608991] Subtest basic-with_fd_dup: SUCCESS (0.021s)
[563.102912] [133/161] (548s left) prime_self_import (basic-with_one_bo)
[563.459500] Starting subtest: basic-with_one_bo
[563.481326] Subtest basic-with_one_bo: SUCCESS (0.021s)
[563.983501] [134/161] (548s left) prime_self_import (basic-with_one_bo_two_files)
[564.356727] Starting subtest: basic-with_one_bo_two_files
[564.372179] Subtest basic-with_one_bo_two_files: SUCCESS (0.018s)
[564.862380] [135/161] (547s left) prime_self_import (basic-with_two_bos)
[565.229820] Starting subtest: basic-with_two_bos
[565.244271] Subtest basic-with_two_bos: SUCCESS (0.021s)
[565.730858] [136/161] (547s left) prime_vgem (basic-fence-flip)
[566.176362] Starting subtest: basic-fence-flip
[567.617887] Subtest basic-fence-flip: SUCCESS (1.448s)
[568.183722] [137/161] (545s left) prime_vgem (basic-fence-mmap)
[568.624679] Starting subtest: basic-fence-mmap
[568.833669] Subtest basic-fence-mmap: SUCCESS (0.200s)
[569.319258] [138/161] (544s left) prime_vgem (basic-fence-read)
[569.759522] Starting subtest: basic-fence-read
[569.812751] Subtest basic-fence-read: SKIP (0.057s)
[570.326386] [139/161] (543s left) prime_vgem (basic-gtt)
[570.797681] Starting subtest: basic-gtt
[570.881733] Subtest basic-gtt: SUCCESS (0.090s)
[571.374895] [140/161] (543s left) prime_vgem (basic-read)
[571.822258] Starting subtest: basic-read
[571.873846] Subtest basic-read: SKIP (0.055s)
[572.389435] [141/161] (542s left) prime_vgem (basic-write)
[572.830955] Starting subtest: basic-write
[572.887826] Subtest basic-write: SKIP (0.054s)
[573.389567] [142/161] (541s left) vgem_basic (setversion)
[573.792974] Starting subtest: setversion
[573.803917] Subtest setversion: SUCCESS (0.000s)
[574.243568] [143/161] (541s left) vgem_basic (create)
[574.647695] Starting subtest: create
[574.650791] Subtest create: SUCCESS (0.002s)
[575.117135] [144/161] (540s left) vgem_basic (debugfs)
[575.519561] Starting subtest: debugfs
[575.522740] Subtest debugfs: SUCCESS (0.002s)
[575.992191] [145/161] (540s left) vgem_basic (dmabuf-export)
[576.390590] Starting subtest: dmabuf-export
[576.407458] Subtest dmabuf-export: SUCCESS (0.017s)
[576.893427] [146/161] (539s left) vgem_basic (dmabuf-fence)
[577.294886] Starting subtest: dmabuf-fence
[577.298770] Subtest dmabuf-fence: SUCCESS (0.003s)
[577.759563] [147/161] (539s left) vgem_basic (dmabuf-fence-before)
[578.158853] Starting subtest: dmabuf-fence-before
[578.163246] Subtest dmabuf-fence-before: SUCCESS (0.003s)
[578.627516] [148/161] (539s left) vgem_basic (dmabuf-mmap)
[579.027826] Starting subtest: dmabuf-mmap
[579.120114] Subtest dmabuf-mmap: SUCCESS (0.098s)
[579.584606] [149/161] (538s left) vgem_basic (mmap)
[579.986450] Starting subtest: mmap
[580.046749] Subtest mmap: SUCCESS (0.064s)
[580.507122] [150/161] (537s left) vgem_basic (second-client)
[580.910563] Starting subtest: second-client
[580.961678] Subtest second-client: SUCCESS (0.047s)
[581.408527] [151/161] (537s left) vgem_basic (sysfs)
[581.807878] Starting subtest: sysfs
[581.811322] Subtest sysfs: SUCCESS (0.004s)
[582.274400] [152/161] (536s left) core_hotunplug (unbind-rebind)
[582.965720] Starting subtest: unbind-rebind
[592.280195] Subtest unbind-rebind: SUCCESS (9.315s)
[592.686056] [153/161] (526s left) vgem_basic (unload)
[593.066195] Starting subtest: unload
[622.829368] Subtest unload: SUCCESS (29.752s)
[623.483945] [154/161] (496s left) i915_module_load (reload)
[623.846836] Starting subtest: reload
[638.613285] Subtest reload: SUCCESS (14.752s)
[639.098207] [155/161] (481s left) gem_lmem_swapping (basic)
[639.671412] Subtest basic: SKIP (0.000s)
[640.161845] [156/161] (480s left) gem_lmem_swapping (parallel-random-engines)
[640.580760] Subtest parallel-random-engines: SKIP (0.000s)
[641.009405] [157/161] (480s left) gem_lmem_swapping (random-engines)
[641.424228] Subtest random-engines: SKIP (0.000s)
[641.845905] [158/161] (479s left) gem_lmem_swapping (verify-random)
[642.261288] Subtest verify-random: SKIP (0.000s)
[642.689320] [159/161] (479s left) i915_pm_rpm (module-reload)
[649.262992] Starting subtest: module-reload
[682.992483] Subtest module-reload: SUCCESS (33.734s)
[683.263142] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[683.275706] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[683.301832] [160/161] (439s left) i915_selftest (live)
[683.703227] Starting subtest: live
[683.713644] Starting dynamic subtest: sanitycheck
[694.273664] Dynamic subtest sanitycheck: SUCCESS (10.556s)
[694.338953] Starting dynamic subtest: uncore
[704.739389] Dynamic subtest uncore: SUCCESS (10.455s)
[704.809241] Starting dynamic subtest: workarounds
[717.725556] Dynamic subtest workarounds: SUCCESS (12.974s)
[717.798310] Starting dynamic subtest: gt_engines
[730.427946] Dynamic subtest gt_engines: SUCCESS (12.665s)
[730.560608] Starting dynamic subtest: gt_timelines
[774.967394] Dynamic subtest gt_timelines: SUCCESS (44.556s)
[775.039103] Starting dynamic subtest: gt_contexts
[785.376446] Dynamic subtest gt_contexts: SUCCESS (10.400s)
[785.448189] Starting dynamic subtest: gt_lrc
[799.571144] Dynamic subtest gt_lrc: SUCCESS (14.186s)
[799.640122] Starting dynamic subtest: gt_mocs
[811.222385] Dynamic subtest gt_mocs: SUCCESS (11.639s)
[811.298374] Starting dynamic subtest: gt_pm
[823.741374] Dynamic subtest gt_pm: SUCCESS (12.509s)
[823.812993] Starting dynamic subtest: gt_heartbeat
[834.282815] Dynamic subtest gt_heartbeat: SUCCESS (10.533s)
[834.353500] Starting dynamic subtest: gt_tlb
[846.021277] Dynamic subtest gt_tlb: SUCCESS (11.731s)
[846.092533] Starting dynamic subtest: requests
[866.624180] Dynamic subtest requests: SUCCESS (20.568s)
[866.690238] Starting dynamic subtest: migrate
[881.175597] Dynamic subtest migrate: SUCCESS (14.568s)
[881.241928] Starting dynamic subtest: active
[891.797558] Dynamic subtest active: SUCCESS (10.613s)
[891.866561] Starting dynamic subtest: objects
[906.402496] Dynamic subtest objects: SUCCESS (14.591s)
[906.478947] Starting dynamic subtest: mman
[928.078184] Dynamic subtest mman: SUCCESS (21.668s)
[928.145627] Starting dynamic subtest: dmabuf
[938.804637] Dynamic subtest dmabuf: SUCCESS (10.708s)
[938.891697] Starting dynamic subtest: vma
[949.998627] Dynamic subtest vma: SUCCESS (11.180s)
[950.078165] Starting dynamic subtest: coherency
[978.964200] Dynamic subtest coherency: SUCCESS (28.949s)
[979.037269] Starting dynamic subtest: gtt
[997.557271] Dynamic subtest gtt: SUCCESS (18.571s)
[997.639346] Starting dynamic subtest: gem
[1009.241864] Dynamic subtest gem: SUCCESS (11.675s)
[1009.314568] Starting dynamic subtest: evict
[1027.653940] Dynamic subtest evict: SUCCESS (18.403s)
[1027.724742] Starting dynamic subtest: hugepages
[1045.029571] Dynamic subtest hugepages: SUCCESS (17.366s)
[1045.106639] Starting dynamic subtest: gem_contexts
[1085.605496] Dynamic subtest gem_contexts: SUCCESS (40.565s)
[1085.675663] Starting dynamic subtest: client
[1096.177335] Dynamic subtest client: SUCCESS (10.546s)
[1096.291454] Starting dynamic subtest: gem_migrate
[1106.697768] Dynamic subtest gem_migrate: SUCCESS (10.479s)
[1106.766248] Starting dynamic subtest: reset
[1120.777842] Dynamic subtest reset: SUCCESS (14.070s)
[1120.861886] Starting dynamic subtest: memory_region
[1131.226789] Dynamic subtest memory_region: SUCCESS (10.431s)
[1131.299839] Starting dynamic subtest: hangcheck
[1162.525859] Dynamic subtest hangcheck: SUCCESS (31.289s)
[1162.599364] Starting dynamic subtest: execlists
[1172.977214] Dynamic subtest execlists: SUCCESS (10.442s)
[1173.048466] Starting dynamic subtest: ring_submission
[1183.374798] Dynamic subtest ring_submission: SUCCESS (10.395s)
[1183.455441] Starting dynamic subtest: perf
[1194.480397] Dynamic subtest perf: SUCCESS (11.090s)
[1194.561296] Starting dynamic subtest: slpc
[1206.879352] Dynamic subtest slpc: SUCCESS (12.390s)
[1206.952307] Starting dynamic subtest: guc
[1219.983187] Dynamic subtest guc: SUCCESS (13.094s)
[1220.058334] Starting dynamic subtest: guc_multi_lrc
[1230.468579] Dynamic subtest guc_multi_lrc: SUCCESS (10.473s)
[1230.563103] Starting dynamic subtest: guc_hang
[1242.375814] Dynamic subtest guc_hang: SUCCESS (11.885s)
[1242.443382] Starting dynamic subtest: late_gt_pm
[1253.002708] Dynamic subtest late_gt_pm: SUCCESS (10.618s)
[1253.005996] Subtest live: SUCCESS (569.274s)
[1253.125129] Overall timeout time exceeded, stopping.
[1253.286360] Closing watchdogs
Done.