[91.041960] Initializing watchdogs
[91.042075]   /dev/watchdog0
[91.058552] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:46d3 Intel Alderlake_n (Gen12) 
[91.062630] [001/161] (960s left) i915_module_load (load)
[91.207098] Starting subtest: load
[92.627404] Subtest load: SUCCESS (1.416s)
[92.681969] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[92.684166] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[92.688533] [002/161] (958s left) core_auth (basic-auth)
[92.881077] Starting subtest: basic-auth
[92.883579] Subtest basic-auth: SUCCESS (0.002s)
[92.980172] [003/161] (958s left) debugfs_test (read_all_entries)
[93.039582] Starting subtest: read_all_entries
[93.244922] Subtest read_all_entries: SUCCESS (0.204s)
[93.338695] [004/161] (958s left) debugfs_test (basic-hwmon)
[93.387073] Starting subtest: basic-hwmon
[93.388681] Subtest basic-hwmon: SKIP (0.000s)
[93.491613] [005/161] (958s left) debugfs_test (sysfs)
[93.538984] Starting subtest: sysfs
[93.604063] Subtest sysfs: SUCCESS (0.065s)
[93.702292] [006/161] (958s left) fbdev (eof)
[93.758364] Starting subtest: eof
[93.759182] Subtest eof: SUCCESS (0.000s)
[93.813984] [007/161] (958s left) fbdev (info)
[93.857424] Starting subtest: info
[93.858188] Subtest info: SUCCESS (0.000s)
[93.935511] [008/161] (957s left) fbdev (nullptr)
[93.991380] Starting subtest: nullptr
[93.992590] Subtest nullptr: SUCCESS (0.000s)
[94.044440] [009/161] (957s left) fbdev (read)
[94.100545] Starting subtest: read
[95.988450] Subtest read: SUCCESS (1.878s)
[96.036234] [010/161] (955s left) fbdev (write)
[96.093385] Starting subtest: write
[97.071604] Subtest write: SUCCESS (0.969s)
[97.129099] [011/161] (954s left) gem_basic (bad-close)
[97.173986] Starting subtest: bad-close
[97.175515] Subtest bad-close: SUCCESS (0.000s)
[97.245374] [012/161] (954s left) gem_basic (create-close)
[97.289418] Starting subtest: create-close
[97.290981] Subtest create-close: SUCCESS (0.000s)
[97.381380] [013/161] (954s left) gem_basic (create-fd-close)
[97.430839] Starting subtest: create-fd-close
[97.432397] Subtest create-fd-close: SUCCESS (0.000s)
[97.520303] [014/161] (954s left) gem_busy (busy@all-engines)
[97.580809] Starting subtest: busy
[97.581667] Starting dynamic subtest: all-engines
[97.635484] Dynamic subtest all-engines: SUCCESS (0.055s)
[97.636969] Subtest busy: SUCCESS (0.055s)
[98.002304] [015/161] (954s left) gem_close_race (basic-process)
[98.063420] Starting subtest: basic-process
[98.110645] Subtest basic-process: SUCCESS (0.046s)
[98.301542] [016/161] (953s left) gem_close_race (basic-threads)
[98.364397] Starting subtest: basic-threads
[99.629572] Subtest basic-threads: SUCCESS (1.264s)
[99.818899] [017/161] (952s left) gem_ctx_create (basic)
[99.878271] Starting subtest: basic
[99.879166] Subtest basic: SUCCESS (0.000s)
[100.071702] [018/161] (952s left) gem_ctx_create (basic-files)
[100.125077] Starting subtest: basic-files
[102.145717] Subtest basic-files: SUCCESS (2.020s)
[102.346204] [019/161] (949s left) gem_ctx_exec (basic)
[102.394423] Starting subtest: basic
[102.401153] Subtest basic: SUCCESS (0.007s)
[102.512971] [020/161] (949s left) gem_exec_basic (basic)
[102.565377] Starting subtest: basic
[102.566240] Starting dynamic subtest: rcs0-smem
[102.568021] Dynamic subtest rcs0-smem: SUCCESS (0.002s)
[102.577380] Starting dynamic subtest: bcs0-smem
[102.578095] Dynamic subtest bcs0-smem: SUCCESS (0.001s)
[102.584447] Starting dynamic subtest: vcs0-smem
[102.585157] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[102.591472] Starting dynamic subtest: vecs0-smem
[102.592186] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[102.592885] Subtest basic: SUCCESS (0.008s)
[102.760713] [021/161] (949s left) gem_exec_create (basic)
[102.819153] Starting subtest: basic
[102.819991] Starting dynamic subtest: smem
[104.925954] Dynamic subtest smem: SUCCESS (2.105s)
[104.927429] Subtest basic: SUCCESS (2.106s)
[105.124137] [022/161] (947s left) gem_exec_fence (basic-busy)
[105.306349] Starting subtest: basic-busy
[105.307554] Starting dynamic subtest: rcs0
[105.384709] Dynamic subtest rcs0: SUCCESS (0.077s)
[105.399034] Starting dynamic subtest: bcs0
[105.437831] Dynamic subtest bcs0: SUCCESS (0.052s)
[105.452760] Starting dynamic subtest: vcs0
[105.492960] Dynamic subtest vcs0: SUCCESS (0.052s)
[105.505833] Starting dynamic subtest: vecs0
[105.542600] Dynamic subtest vecs0: SUCCESS (0.051s)
[105.543951] Subtest basic-busy: SUCCESS (0.236s)
[106.106249] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[106.110640] [023/161] (946s left) gem_exec_fence (basic-wait)
[106.288793] Starting subtest: basic-wait
[106.289928] Starting dynamic subtest: rcs0
[106.344960] Dynamic subtest rcs0: SUCCESS (0.056s)
[106.359907] Starting dynamic subtest: bcs0
[106.396974] Dynamic subtest bcs0: SUCCESS (0.051s)
[106.411869] Starting dynamic subtest: vcs0
[106.451657] Dynamic subtest vcs0: SUCCESS (0.054s)
[106.467401] Starting dynamic subtest: vecs0
[106.507381] Dynamic subtest vecs0: SUCCESS (0.052s)
[106.508790] Subtest basic-wait: SUCCESS (0.217s)
[107.005027] [024/161] (945s left) gem_exec_fence (basic-await)
[107.183370] Starting subtest: basic-await
[107.184627] Starting dynamic subtest: rcs0
[107.274758] Dynamic subtest rcs0: SUCCESS (0.091s)
[107.289775] Starting dynamic subtest: bcs0
[107.368794] Dynamic subtest bcs0: SUCCESS (0.093s)
[107.387612] Starting dynamic subtest: vcs0
[107.463227] Dynamic subtest vcs0: SUCCESS (0.093s)
[107.479895] Starting dynamic subtest: vecs0
[107.558270] Dynamic subtest vecs0: SUCCESS (0.094s)
[107.560000] Subtest basic-await: SUCCESS (0.374s)
[108.076922] [025/161] (944s left) gem_exec_fence (nb-await)
[108.252992] Starting subtest: nb-await
[108.254152] Starting dynamic subtest: rcs0
[108.319826] Dynamic subtest rcs0: SUCCESS (0.066s)
[108.340520] Starting dynamic subtest: bcs0
[108.387198] Dynamic subtest bcs0: SUCCESS (0.066s)
[108.394985] Starting dynamic subtest: vcs0
[108.447847] Dynamic subtest vcs0: SUCCESS (0.060s)
[108.470087] Starting dynamic subtest: vecs0
[108.513838] Dynamic subtest vecs0: SUCCESS (0.065s)
[108.515718] Subtest nb-await: SUCCESS (0.260s)
[109.016537] [026/161] (943s left) gem_exec_gttfill (basic)
[109.075412] Starting subtest: basic
[116.504459] Subtest basic: SUCCESS (7.419s)
[116.703648] [027/161] (936s left) gem_exec_parallel (engines)
[116.838859] Starting subtest: engines
[116.839823] Starting dynamic subtest: basic
[119.493805] Dynamic subtest basic: SUCCESS (2.653s)
[119.509042] Starting dynamic subtest: contexts
[123.805095] Dynamic subtest contexts: SUCCESS (4.301s)
[123.820697] Starting dynamic subtest: fds
[128.833924] Dynamic subtest fds: SUCCESS (5.027s)
[128.851980] Starting dynamic subtest: userptr
[131.420930] Dynamic subtest userptr: SUCCESS (2.594s)
[131.422347] Subtest engines: SUCCESS (14.579s)
[131.631735] [028/161] (921s left) gem_exec_store (basic)
[131.709229] Starting subtest: basic
[131.815363] Subtest basic: SUCCESS (0.106s)
[132.033117] [029/161] (920s left) gem_flink_basic (bad-flink)
[132.084387] Starting subtest: bad-flink
[132.085176] Subtest bad-flink: SUCCESS (0.000s)
[132.166231] [030/161] (920s left) gem_flink_basic (bad-open)
[132.216722] Starting subtest: bad-open
[132.217557] Subtest bad-open: SUCCESS (0.000s)
[132.302501] [031/161] (920s left) gem_flink_basic (basic)
[132.353480] Starting subtest: basic
[132.354328] Subtest basic: SUCCESS (0.000s)
[132.455737] [032/161] (920s left) gem_flink_basic (double-flink)
[132.508271] Starting subtest: double-flink
[132.509068] Subtest double-flink: SUCCESS (0.000s)
[132.615046] [033/161] (920s left) gem_flink_basic (flink-lifetime)
[132.665307] Starting subtest: flink-lifetime
[132.667120] Subtest flink-lifetime: SUCCESS (0.001s)
[132.769682] [034/161] (920s left) gem_huc_copy (huc-copy)
[132.828100] Starting subtest: huc-copy
[132.836678] Subtest huc-copy: SUCCESS (0.007s)
[132.962972] [035/161] (920s left) gem_linear_blits (basic)
[133.100390] Starting subtest: basic
[133.111903] Subtest basic: SUCCESS (0.011s)
[133.247013] [036/161] (919s left) gem_mmap (basic)
[133.347710] Starting subtest: basic
[133.351487] Subtest basic: SUCCESS (0.001s)
[133.453714] [037/161] (919s left) gem_mmap_gtt (basic)
[133.506948] Starting subtest: basic
[133.507729] Subtest basic: SUCCESS (0.001s)
[133.592948] [038/161] (919s left) gem_render_linear_blits (basic)
[133.649200] Starting subtest: basic
[133.706984] Subtest basic: SUCCESS (0.058s)
[133.807726] [039/161] (919s left) gem_render_tiled_blits (basic)
[133.872332] Starting subtest: basic
[133.940601] Subtest basic: SUCCESS (0.068s)
[134.044398] [040/161] (919s left) gem_ringfill (basic-all)
[134.347028] Starting subtest: basic-all
[135.503857] Subtest basic-all: SUCCESS (1.149s)
[135.637975] [041/161] (917s left) gem_softpin (allocator-basic)
[135.706867] Starting subtest: allocator-basic
[135.829886] Subtest allocator-basic: SUCCESS (0.115s)
[135.961710] [042/161] (917s left) gem_softpin (allocator-basic-reserve)
[136.025390] Starting subtest: allocator-basic-reserve
[136.143821] Subtest allocator-basic-reserve: SUCCESS (0.110s)
[136.289971] [043/161] (917s left) gem_softpin (safe-alignment)
[136.354230] Starting subtest: safe-alignment
[136.374773] Subtest safe-alignment: SUCCESS (0.020s)
[136.503285] [044/161] (917s left) gem_sync (basic-all)
[136.576876] Starting subtest: basic-all
[139.823692] Subtest basic-all: SUCCESS (3.246s)
[140.020839] [045/161] (913s left) gem_sync (basic-each)
[140.082613] Starting subtest: basic-each
[142.742043] Subtest basic-each: SUCCESS (2.658s)
[142.945690] [046/161] (910s left) gem_tiled_blits (basic)
[143.052358] Starting subtest: basic
[143.076177] Subtest basic: SUCCESS (0.024s)
[143.234244] [047/161] (910s left) gem_tiled_fence_blits (basic)
[143.407203] Starting subtest: basic
[143.428504] Subtest basic: SUCCESS (0.022s)
[143.578012] [048/161] (910s left) gem_tiled_pread_basic
[143.734416] [049/161] (910s left) gem_wait (busy@all-engines)
[143.807558] Starting subtest: busy
[143.808427] Starting dynamic subtest: all-engines
[144.385073] Dynamic subtest all-engines: SUCCESS (0.576s)
[144.386860] Subtest busy: SUCCESS (0.576s)
[144.606311] [050/161] (909s left) gem_wait (wait@all-engines)
[144.678975] Starting subtest: wait
[144.679873] Starting dynamic subtest: all-engines
[145.768335] Dynamic subtest all-engines: SUCCESS (1.088s)
[145.769872] Subtest wait: SUCCESS (1.089s)
[145.988167] [051/161] (907s left) i915_getparams_basic (basic-eu-total)
[146.035093] Starting subtest: basic-eu-total
[146.037031] Subtest basic-eu-total: SUCCESS (0.000s)
[146.113250] [052/161] (907s left) i915_getparams_basic (basic-subslice-total)
[146.157334] Starting subtest: basic-subslice-total
[146.158868] Subtest basic-subslice-total: SUCCESS (0.000s)
[146.239437] [053/161] (907s left) i915_hangman (error-state-basic)
[146.298070] Starting subtest: error-state-basic
[146.420515] Subtest error-state-basic: SUCCESS (0.122s)
[146.501889] [054/161] (907s left) i915_pciid
[146.619670] [055/161] (907s left) kms_addfb_basic (addfb25-4-tiled)
[146.702052] Starting subtest: addfb25-4-tiled
[146.702875] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[146.797606] [056/161] (907s left) kms_addfb_basic (addfb25-bad-modifier)
[146.864803] Starting subtest: addfb25-bad-modifier
[146.865569] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[146.965460] [057/161] (907s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[147.035876] Starting subtest: addfb25-framebuffer-vs-set-tiling
[147.036725] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[147.141743] [058/161] (907s left) kms_addfb_basic (addfb25-modifier-no-flag)
[147.215261] Starting subtest: addfb25-modifier-no-flag
[147.216036] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[147.325911] [059/161] (907s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[147.397956] Starting subtest: addfb25-x-tiled-legacy
[147.398748] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[147.491581] [060/161] (906s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[147.566173] Starting subtest: addfb25-x-tiled-mismatch-legacy
[147.567056] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[147.668828] [061/161] (906s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[147.744129] Starting subtest: addfb25-yf-tiled-legacy
[147.745061] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[147.844433] [062/161] (906s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[147.920938] Starting subtest: addfb25-y-tiled-legacy
[147.921779] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[148.018126] [063/161] (906s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[148.097129] Starting subtest: addfb25-y-tiled-small-legacy
[148.098056] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s)
[148.193896] [064/161] (906s left) kms_addfb_basic (bad-pitch-0)
[148.246072] Starting subtest: bad-pitch-0
[148.246947] Subtest bad-pitch-0: SUCCESS (0.000s)
[148.377014] [065/161] (906s left) kms_addfb_basic (bad-pitch-1024)
[148.429124] Starting subtest: bad-pitch-1024
[148.429996] Subtest bad-pitch-1024: SUCCESS (0.000s)
[148.560066] [066/161] (906s left) kms_addfb_basic (bad-pitch-128)
[148.613227] Starting subtest: bad-pitch-128
[148.614015] Subtest bad-pitch-128: SUCCESS (0.000s)
[148.745662] [067/161] (906s left) kms_addfb_basic (bad-pitch-256)
[148.796906] Starting subtest: bad-pitch-256
[148.797701] Subtest bad-pitch-256: SUCCESS (0.000s)
[148.937154] [068/161] (905s left) kms_addfb_basic (bad-pitch-32)
[148.988991] Starting subtest: bad-pitch-32
[148.990045] Subtest bad-pitch-32: SUCCESS (0.000s)
[149.119613] [069/161] (905s left) kms_addfb_basic (bad-pitch-63)
[149.172954] Starting subtest: bad-pitch-63
[149.173880] Subtest bad-pitch-63: SUCCESS (0.000s)
[149.301071] [070/161] (905s left) kms_addfb_basic (bad-pitch-65536)
[149.353967] Starting subtest: bad-pitch-65536
[149.355299] Subtest bad-pitch-65536: SUCCESS (0.000s)
[149.487592] [071/161] (905s left) kms_addfb_basic (bad-pitch-999)
[149.541201] Starting subtest: bad-pitch-999
[149.541970] Subtest bad-pitch-999: SUCCESS (0.000s)
[149.666503] [072/161] (905s left) kms_addfb_basic (basic)
[149.720271] Starting subtest: basic
[149.721094] Subtest basic: SUCCESS (0.000s)
[149.853804] [073/161] (905s left) kms_addfb_basic (basic-x-tiled-legacy)
[149.907154] Starting subtest: basic-x-tiled-legacy
[149.907937] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[150.037217] [074/161] (905s left) kms_addfb_basic (basic-y-tiled-legacy)
[150.093061] Starting subtest: basic-y-tiled-legacy
[150.093831] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[150.228923] [075/161] (904s left) kms_addfb_basic (bo-too-small)
[150.310252] Starting subtest: bo-too-small
[150.311286] Subtest bo-too-small: SUCCESS (0.000s)
[150.428458] [076/161] (904s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[150.507802] Starting subtest: bo-too-small-due-to-tiling
[150.508590] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[150.610769] [077/161] (904s left) kms_addfb_basic (clobberred-modifier)
[150.665060] Starting subtest: clobberred-modifier
[150.665942] Subtest clobberred-modifier: SUCCESS (0.000s)
[150.786685] [078/161] (904s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[150.842613] Starting subtest: framebuffer-vs-set-tiling
[150.843430] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[150.971291] [079/161] (904s left) kms_addfb_basic (invalid-get-prop)
[151.027530] Starting subtest: invalid-get-prop
[151.028360] Subtest invalid-get-prop: SUCCESS (0.000s)
[151.162879] [080/161] (904s left) kms_addfb_basic (invalid-get-prop-any)
[151.215851] Starting subtest: invalid-get-prop-any
[151.216820] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[151.345700] [081/161] (904s left) kms_addfb_basic (invalid-set-prop)
[151.400174] Starting subtest: invalid-set-prop
[151.400973] Subtest invalid-set-prop: SUCCESS (0.000s)
[151.538361] [082/161] (903s left) kms_addfb_basic (invalid-set-prop-any)
[151.592767] Starting subtest: invalid-set-prop-any
[151.593595] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[151.718885] [083/161] (903s left) kms_addfb_basic (no-handle)
[151.773646] Starting subtest: no-handle
[151.774610] Subtest no-handle: SUCCESS (0.000s)
[151.905513] [084/161] (903s left) kms_addfb_basic (size-max)
[151.989472] Starting subtest: size-max
[151.990392] Subtest size-max: SUCCESS (0.000s)
[152.104551] [085/161] (903s left) kms_addfb_basic (small-bo)
[152.186449] Starting subtest: small-bo
[152.187394] Subtest small-bo: SUCCESS (0.000s)
[152.302806] [086/161] (903s left) kms_addfb_basic (tile-pitch-mismatch)
[152.356229] Starting subtest: tile-pitch-mismatch
[152.356998] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[152.488175] [087/161] (903s left) kms_addfb_basic (too-high)
[152.567835] Starting subtest: too-high
[152.568658] Subtest too-high: SUCCESS (0.001s)
[152.679759] [088/161] (903s left) kms_addfb_basic (too-wide)
[152.756824] Starting subtest: too-wide
[152.757611] Subtest too-wide: SUCCESS (0.000s)
[152.869585] [089/161] (903s left) kms_addfb_basic (unused-handle)
[152.921233] Starting subtest: unused-handle
[152.922014] Subtest unused-handle: SUCCESS (0.000s)
[153.053923] [090/161] (902s left) kms_addfb_basic (unused-modifier)
[153.107661] Starting subtest: unused-modifier
[153.108462] Subtest unused-modifier: SUCCESS (0.000s)
[153.237763] [091/161] (902s left) kms_addfb_basic (unused-offsets)
[153.291480] Starting subtest: unused-offsets
[153.292379] Subtest unused-offsets: SUCCESS (0.000s)
[153.428868] [092/161] (902s left) kms_addfb_basic (unused-pitches)
[153.497548] Starting subtest: unused-pitches
[153.498792] Subtest unused-pitches: SUCCESS (0.000s)
[153.638176] [093/161] (902s left) kms_busy (basic)
[153.721305] Starting subtest: basic
[153.722343] Starting dynamic subtest: flip
[154.386838] Dynamic subtest flip: SUCCESS (0.664s)
[154.407971] Starting dynamic subtest: modeset
[158.172232] Dynamic subtest modeset: SUCCESS (3.786s)
[158.173094] Subtest basic: SUCCESS (4.452s)
[159.006403] [094/161] (897s left) kms_prop_blob (basic)
[159.056186] Starting subtest: basic
[159.056994] Subtest basic: SUCCESS (0.000s)
[159.133627] [095/161] (897s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[159.233423] Starting subtest: basic-busy-flip-before-cursor-atomic
[159.234990] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[159.334495] [096/161] (896s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[159.431362] Starting subtest: basic-busy-flip-before-cursor-legacy
[159.432972] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[159.532604] [097/161] (896s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[159.623888] Starting subtest: basic-flip-after-cursor-atomic
[160.357444] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.732s)
[161.243705] [098/161] (895s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[161.335530] Starting subtest: basic-flip-after-cursor-legacy
[162.068469] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.732s)
[162.958343] [099/161] (893s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[163.049404] Starting subtest: basic-flip-after-cursor-varying-size
[163.782664] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.730s)
[164.670787] [100/161] (891s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[164.768220] Starting subtest: basic-flip-before-cursor-atomic
[165.502673] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.731s)
[166.385226] [101/161] (890s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[166.485946] Starting subtest: basic-flip-before-cursor-legacy
[167.208936] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.721s)
[168.087827] [102/161] (888s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[168.187468] Starting subtest: basic-flip-before-cursor-varying-size
[168.910756] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.721s)
[169.796507] [103/161] (886s left) kms_dsc (dsc-basic)
[169.873080] Starting subtest: dsc-basic
[169.876977] Subtest dsc-basic: SKIP (0.001s)
[169.949282] [104/161] (886s left) kms_flip (basic-flip-vs-dpms)
[170.018339] Starting subtest: basic-flip-vs-dpms
[170.020104] Starting dynamic subtest: A-eDP1
[172.721443] Dynamic subtest A-eDP1: SUCCESS (2.701s)
[172.743802] Starting dynamic subtest: B-eDP1
[174.989808] Dynamic subtest B-eDP1: SUCCESS (2.267s)
[175.004640] Starting dynamic subtest: C-eDP1
[177.258223] Dynamic subtest C-eDP1: SUCCESS (2.268s)
[177.258991] Subtest basic-flip-vs-dpms: SUCCESS (7.241s)
[178.085660] [105/161] (878s left) kms_flip (basic-flip-vs-modeset)
[178.161918] Starting subtest: basic-flip-vs-modeset
[178.164121] Starting dynamic subtest: A-eDP1
[180.804787] Dynamic subtest A-eDP1: SUCCESS (2.641s)
[180.826937] Starting dynamic subtest: B-eDP1
[183.070237] Dynamic subtest B-eDP1: SUCCESS (2.259s)
[183.089584] Starting dynamic subtest: C-eDP1
[185.321921] Dynamic subtest C-eDP1: SUCCESS (2.254s)
[185.323516] Subtest basic-flip-vs-modeset: SUCCESS (7.162s)
[186.163373] [106/161] (870s left) kms_flip (basic-flip-vs-wf_vblank)
[186.240191] Starting subtest: basic-flip-vs-wf_vblank
[186.241805] Starting dynamic subtest: A-eDP1
[188.216762] Dynamic subtest A-eDP1: SUCCESS (1.975s)
[188.240157] Starting dynamic subtest: B-eDP1
[190.248519] Dynamic subtest B-eDP1: SUCCESS (2.030s)
[190.270751] Starting dynamic subtest: C-eDP1
[192.272985] Dynamic subtest C-eDP1: SUCCESS (2.019s)
[192.274207] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.031s)
[193.546669] [107/161] (863s left) kms_flip (basic-plain-flip)
[193.622088] Starting subtest: basic-plain-flip
[193.623319] Starting dynamic subtest: A-eDP1
[195.458849] Dynamic subtest A-eDP1: SUCCESS (1.835s)
[195.479740] Starting dynamic subtest: B-eDP1
[197.281706] Dynamic subtest B-eDP1: SUCCESS (1.821s)
[197.302886] Starting dynamic subtest: C-eDP1
[199.188677] Dynamic subtest C-eDP1: SUCCESS (1.905s)
[199.191732] Subtest basic-plain-flip: SUCCESS (5.571s)
[200.388270] [108/161] (856s left) kms_force_connector_basic (force-connector-state)
[200.450368] Starting subtest: force-connector-state
[200.888351] Subtest force-connector-state: SUCCESS (0.438s)
[202.007798] [109/161] (854s left) kms_force_connector_basic (force-edid)
[202.066241] Starting subtest: force-edid
[202.097924] Subtest force-edid: SUCCESS (0.032s)
[202.221885] [110/161] (854s left) kms_force_connector_basic (force-load-detect)
[202.281379] Starting subtest: force-load-detect
[202.283360] Subtest force-load-detect: SKIP (0.000s)
[202.396133] [111/161] (854s left) kms_force_connector_basic (prune-stale-modes)
[202.457916] Starting subtest: prune-stale-modes
[202.503908] Subtest prune-stale-modes: SUCCESS (0.044s)
[202.637299] [112/161] (854s left) kms_frontbuffer_tracking (basic)
[202.714379] Starting subtest: basic
[206.098478] Subtest basic: SUCCESS (3.384s)
[206.207110] [113/161] (850s left) kms_hdmi_inject (inject-audio)
[206.555172] Starting subtest: inject-audio
[206.724963] Subtest inject-audio: SUCCESS (0.170s)
[207.510321] [114/161] (849s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[207.582762] Starting subtest: compare-crc-sanitycheck-xr24
[207.583924] Starting dynamic subtest: pipe-A-eDP-1
[208.202447] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.616s)
[208.225183] Starting dynamic subtest: pipe-B-eDP-1
[209.565406] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.361s)
[209.589033] Starting dynamic subtest: pipe-C-eDP-1
[210.909688] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.341s)
[210.910541] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.328s)
[211.774121] [115/161] (845s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[211.851918] Starting subtest: compare-crc-sanitycheck-nv12
[211.852885] Starting dynamic subtest: pipe-A-eDP-1
[212.525274] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.671s)
[212.546249] Starting dynamic subtest: pipe-B-eDP-1
[213.852974] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.325s)
[213.864505] Starting dynamic subtest: pipe-C-eDP-1
[215.215282] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.360s)
[215.216332] Subtest compare-crc-sanitycheck-nv12: SUCCESS (3.364s)
[216.059968] [116/161] (841s left) kms_pipe_crc_basic (hang-read-crc)
[216.133686] Starting subtest: hang-read-crc
[216.134622] Starting dynamic subtest: pipe-A-eDP-1
[218.216953] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.082s)
[218.233821] Starting dynamic subtest: pipe-B-eDP-1
[220.993078] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.774s)
[221.008475] Starting dynamic subtest: pipe-C-eDP-1
[223.654120] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.660s)
[223.654901] Subtest hang-read-crc: SUCCESS (7.522s)
[224.538745] [117/161] (832s left) kms_pipe_crc_basic (nonblocking-crc)
[224.612963] Starting subtest: nonblocking-crc
[224.613879] Starting dynamic subtest: pipe-A-eDP-1
[225.331883] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.717s)
[225.346579] Starting dynamic subtest: pipe-B-eDP-1
[226.827008] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.494s)
[226.843085] Starting dynamic subtest: pipe-C-eDP-1
[228.309635] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.481s)
[228.310471] Subtest nonblocking-crc: SUCCESS (3.698s)
[229.154633] [118/161] (828s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[229.229942] Starting subtest: nonblocking-crc-frame-sequence
[229.231083] Starting dynamic subtest: pipe-A-eDP-1
[229.939047] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.708s)
[229.956051] Starting dynamic subtest: pipe-B-eDP-1
[231.441526] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.500s)
[231.461528] Starting dynamic subtest: pipe-C-eDP-1
[232.907156] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.461s)
[232.908466] Subtest nonblocking-crc-frame-sequence: SUCCESS (3.678s)
[233.764907] [119/161] (823s left) kms_pipe_crc_basic (read-crc)
[233.841207] Starting subtest: read-crc
[233.842422] Starting dynamic subtest: pipe-A-eDP-1
[234.533303] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.690s)
[234.552182] Starting dynamic subtest: pipe-B-eDP-1
[235.903701] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.366s)
[235.924741] Starting dynamic subtest: pipe-C-eDP-1
[237.278357] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.369s)
[237.279511] Subtest read-crc: SUCCESS (3.438s)
[238.156774] [120/161] (819s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[238.233225] Starting subtest: read-crc-frame-sequence
[238.234509] Starting dynamic subtest: pipe-A-eDP-1
[238.928707] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.694s)
[238.944645] Starting dynamic subtest: pipe-B-eDP-1
[240.311303] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.381s)
[240.326997] Starting dynamic subtest: pipe-C-eDP-1
[241.661415] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.347s)
[241.662198] Subtest read-crc-frame-sequence: SUCCESS (3.429s)
[242.559132] [121/161] (814s left) kms_pm_backlight (basic-brightness)
[242.636223] Starting subtest: basic-brightness
[242.673531] Starting dynamic subtest: eDP-1
[243.203845] Dynamic subtest eDP-1: SUCCESS (0.531s)
[243.205257] Subtest basic-brightness: SUCCESS (0.568s)
[244.058703] [122/161] (813s left) kms_pm_rpm (basic-pci-d3-state)
[245.570956] Starting subtest: basic-pci-d3-state
[247.031135] Subtest basic-pci-d3-state: SUCCESS (1.459s)
[247.155592] [123/161] (810s left) kms_pm_rpm (basic-rte)
[247.202301] Starting subtest: basic-rte
[250.136732] Subtest basic-rte: SUCCESS (2.932s)
[251.641469] [124/161] (805s left) kms_psr (psr-primary-page-flip)
[251.720843] Starting subtest: psr-primary-page-flip
[251.721729] Starting dynamic subtest: eDP-1
[253.460963] Dynamic subtest eDP-1: SUCCESS (1.738s)
[253.462632] Subtest psr-primary-page-flip: SUCCESS (1.740s)
[254.333601] [125/161] (803s left) kms_psr (psr-cursor-plane-move)
[254.412496] Starting subtest: psr-cursor-plane-move
[254.413318] Starting dynamic subtest: eDP-1
[256.151522] Dynamic subtest eDP-1: SUCCESS (1.738s)
[256.152907] Subtest psr-cursor-plane-move: SUCCESS (1.740s)
[257.022901] [126/161] (800s left) kms_psr (psr-sprite-plane-onoff)
[257.100355] Starting subtest: psr-sprite-plane-onoff
[257.101392] Starting dynamic subtest: eDP-1
[258.884654] Dynamic subtest eDP-1: SUCCESS (1.783s)
[258.886190] Subtest psr-sprite-plane-onoff: SUCCESS (1.785s)
[259.813556] [127/161] (797s left) kms_psr (psr-primary-mmap-gtt)
[259.883249] Starting subtest: psr-primary-mmap-gtt
[259.884032] Starting dynamic subtest: eDP-1
[261.630573] Dynamic subtest eDP-1: SUCCESS (1.743s)
[261.632156] Subtest psr-primary-mmap-gtt: SUCCESS (1.745s)
[262.496977] [128/161] (795s left) kms_setmode (basic-clone-single-crtc)
[262.550723] Starting subtest: basic-clone-single-crtc
[262.553125] Subtest basic-clone-single-crtc: SKIP (0.000s)
[262.622194] [129/161] (795s left) i915_pm_rps (basic-api)
[262.671324] Starting subtest: basic-api
[262.672952] Subtest basic-api: SKIP (0.000s)
[262.751510] [130/161] (795s left) prime_self_import (basic-llseek-bad)
[262.794181] Starting subtest: basic-llseek-bad
[262.797505] Subtest basic-llseek-bad: SUCCESS (0.003s)
[262.899959] [131/161] (794s left) prime_self_import (basic-llseek-size)
[262.942518] Starting subtest: basic-llseek-size
[262.946872] Subtest basic-llseek-size: SUCCESS (0.004s)
[263.046997] [132/161] (794s left) prime_self_import (basic-with_fd_dup)
[263.091876] Starting subtest: basic-with_fd_dup
[263.095443] Subtest basic-with_fd_dup: SUCCESS (0.003s)
[263.196726] [133/161] (794s left) prime_self_import (basic-with_one_bo)
[263.240522] Starting subtest: basic-with_one_bo
[263.244054] Subtest basic-with_one_bo: SUCCESS (0.003s)
[263.320442] [134/161] (794s left) prime_self_import (basic-with_one_bo_two_files)
[263.365068] Starting subtest: basic-with_one_bo_two_files
[263.366826] Subtest basic-with_one_bo_two_files: SUCCESS (0.002s)
[263.465193] [135/161] (794s left) prime_self_import (basic-with_two_bos)
[263.508472] Starting subtest: basic-with_two_bos
[263.510863] Subtest basic-with_two_bos: SUCCESS (0.003s)
[263.614405] [136/161] (794s left) prime_vgem (basic-fence-flip)
[263.685231] Starting subtest: basic-fence-flip
[263.945267] Subtest basic-fence-flip: SUCCESS (0.260s)
[264.056965] [137/161] (794s left) prime_vgem (basic-fence-mmap)
[264.126575] Starting subtest: basic-fence-mmap
[264.154852] Subtest basic-fence-mmap: SUCCESS (0.028s)
[264.259884] [138/161] (793s left) prime_vgem (basic-fence-read)
[264.333878] Starting subtest: basic-fence-read
[264.344058] Subtest basic-fence-read: SKIP (0.010s)
[264.448504] [139/161] (793s left) prime_vgem (basic-gtt)
[264.519145] Starting subtest: basic-gtt
[264.533604] Subtest basic-gtt: SUCCESS (0.015s)
[264.635914] [140/161] (793s left) prime_vgem (basic-read)
[264.695708] Starting subtest: basic-read
[264.704897] Subtest basic-read: SKIP (0.009s)
[264.803211] [141/161] (793s left) prime_vgem (basic-write)
[264.873662] Starting subtest: basic-write
[264.884205] Subtest basic-write: SKIP (0.010s)
[264.988121] [142/161] (793s left) vgem_basic (setversion)
[265.041724] Starting subtest: setversion
[265.043616] Subtest setversion: SUCCESS (0.000s)
[265.104543] [143/161] (793s left) vgem_basic (create)
[265.158239] Starting subtest: create
[265.159408] Subtest create: SUCCESS (0.001s)
[265.221426] [144/161] (793s left) vgem_basic (debugfs)
[265.275276] Starting subtest: debugfs
[265.276332] Subtest debugfs: SUCCESS (0.000s)
[265.339875] [145/161] (793s left) vgem_basic (dmabuf-export)
[265.392362] Starting subtest: dmabuf-export
[265.414452] Subtest dmabuf-export: SUCCESS (0.019s)
[265.508370] [146/161] (793s left) vgem_basic (dmabuf-fence)
[265.562803] Starting subtest: dmabuf-fence
[265.564120] Subtest dmabuf-fence: SUCCESS (0.001s)
[265.622277] [147/161] (793s left) vgem_basic (dmabuf-fence-before)
[265.674751] Starting subtest: dmabuf-fence-before
[265.675795] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[265.736122] [148/161] (792s left) vgem_basic (dmabuf-mmap)
[265.788886] Starting subtest: dmabuf-mmap
[265.803310] Subtest dmabuf-mmap: SUCCESS (0.015s)
[265.864970] [149/161] (792s left) vgem_basic (mmap)
[265.919160] Starting subtest: mmap
[265.928872] Subtest mmap: SUCCESS (0.011s)
[265.987113] [150/161] (792s left) vgem_basic (second-client)
[266.039538] Starting subtest: second-client
[266.057161] Subtest second-client: SUCCESS (0.016s)
[266.122413] [151/161] (792s left) vgem_basic (sysfs)
[266.175398] Starting subtest: sysfs
[266.176286] Subtest sysfs: SUCCESS (0.001s)
[266.234605] [152/161] (792s left) core_hotunplug (unbind-rebind)
[266.327661] Starting subtest: unbind-rebind
[271.565173] Subtest unbind-rebind: SUCCESS (5.237s)
[271.610794] [153/161] (787s left) vgem_basic (unload)
[271.656340] Starting subtest: unload
[300.766356] Subtest unload: SUCCESS (29.075s)
[300.845992] [154/161] (758s left) i915_module_load (reload)
[300.895244] Starting subtest: reload
[305.070380] Subtest reload: SUCCESS (4.175s)
[305.121067] [155/161] (753s left) gem_lmem_swapping (basic)
[305.179628] Subtest basic: SKIP (0.000s)
[305.231321] [156/161] (753s left) gem_lmem_swapping (parallel-random-engines)
[305.288918] Subtest parallel-random-engines: SKIP (0.000s)
[305.384621] [157/161] (753s left) gem_lmem_swapping (random-engines)
[305.444077] Subtest random-engines: SKIP (0.000s)
[305.498242] [158/161] (753s left) gem_lmem_swapping (verify-random)
[305.546374] Subtest verify-random: SKIP (0.000s)
[305.606339] [159/161] (753s left) i915_pm_rpm (module-reload)
[307.815966] Starting subtest: module-reload
[323.772921] Subtest module-reload: SUCCESS (15.946s)
[323.806657] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[323.807948] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[323.812172] [160/161] (735s left) i915_selftest (live)
[323.864294] Starting subtest: live
[323.865183] Starting dynamic subtest: sanitycheck
[326.074082] Dynamic subtest sanitycheck: SUCCESS (2.199s)
[326.084644] Starting dynamic subtest: uncore
[328.361820] Dynamic subtest uncore: SUCCESS (2.283s)
[328.376444] Starting dynamic subtest: workarounds
[331.331976] Dynamic subtest workarounds: SUCCESS (2.967s)
[331.342867] Starting dynamic subtest: gt_engines
[334.005984] Dynamic subtest gt_engines: SUCCESS (2.670s)
[334.017761] Starting dynamic subtest: gt_timelines
[344.304693] Dynamic subtest gt_timelines: SUCCESS (10.294s)
[344.314954] Starting dynamic subtest: gt_contexts
[346.630096] Dynamic subtest gt_contexts: SUCCESS (2.321s)
[346.640513] Starting dynamic subtest: gt_lrc
[351.159963] Dynamic subtest gt_lrc: SUCCESS (4.526s)
[351.170757] Starting dynamic subtest: gt_mocs
[354.071075] Dynamic subtest gt_mocs: SUCCESS (2.907s)
[354.083233] Starting dynamic subtest: gt_pm
[358.683054] Dynamic subtest gt_pm: SUCCESS (4.608s)
[358.694872] Starting dynamic subtest: gt_heartbeat
[360.910095] Dynamic subtest gt_heartbeat: SUCCESS (2.223s)
[360.920949] Starting dynamic subtest: gt_tlb
[364.298880] Dynamic subtest gt_tlb: SUCCESS (3.385s)
[364.312067] Starting dynamic subtest: requests
[372.815335] Dynamic subtest requests: SUCCESS (8.512s)
[372.826780] Starting dynamic subtest: migrate
[377.816799] Dynamic subtest migrate: SUCCESS (4.998s)
[377.827236] Starting dynamic subtest: active
[380.008024] Dynamic subtest active: SUCCESS (2.187s)
[380.018207] Starting dynamic subtest: objects
[382.901130] Dynamic subtest objects: SUCCESS (2.889s)
[382.911946] Starting dynamic subtest: mman
[388.600070] Dynamic subtest mman: SUCCESS (5.695s)
[388.611312] Starting dynamic subtest: dmabuf
[391.079086] Dynamic subtest dmabuf: SUCCESS (2.475s)
[391.091805] Starting dynamic subtest: vma
[393.495061] Dynamic subtest vma: SUCCESS (2.412s)
[393.505505] Starting dynamic subtest: coherency
[398.062891] Dynamic subtest coherency: SUCCESS (4.564s)
[398.073521] Starting dynamic subtest: gtt
[407.787135] Dynamic subtest gtt: SUCCESS (9.720s)
[407.800231] Starting dynamic subtest: gem
[410.367909] Dynamic subtest gem: SUCCESS (2.577s)
[410.379110] Starting dynamic subtest: evict
[414.319670] Dynamic subtest evict: SUCCESS (3.948s)
[414.329988] Starting dynamic subtest: hugepages
[422.729983] Dynamic subtest hugepages: SUCCESS (8.406s)
[422.740862] Starting dynamic subtest: gem_contexts
[437.670055] Dynamic subtest gem_contexts: SUCCESS (14.936s)
[437.678996] Starting dynamic subtest: client
[440.160586] Dynamic subtest client: SUCCESS (2.486s)
[440.172425] Starting dynamic subtest: gem_migrate
[442.544998] Dynamic subtest gem_migrate: SUCCESS (2.381s)
[442.556970] Starting dynamic subtest: reset
[447.659694] Dynamic subtest reset: SUCCESS (5.118s)
[447.667859] Starting dynamic subtest: memory_region
[450.083517] Dynamic subtest memory_region: SUCCESS (2.412s)
[450.095989] Starting dynamic subtest: hangcheck
[467.103406] Dynamic subtest hangcheck: SUCCESS (17.016s)
[467.114950] Starting dynamic subtest: execlists
[469.542100] Dynamic subtest execlists: SUCCESS (2.435s)
[469.554672] Starting dynamic subtest: ring_submission
[471.824945] Dynamic subtest ring_submission: SUCCESS (2.279s)
[471.835097] Starting dynamic subtest: perf
[474.330114] Dynamic subtest perf: SUCCESS (2.501s)
[474.340296] Starting dynamic subtest: slpc
[481.237811] Dynamic subtest slpc: SUCCESS (6.904s)
[481.248121] Starting dynamic subtest: guc
[484.342990] Dynamic subtest guc: SUCCESS (3.101s)
[484.353638] Starting dynamic subtest: guc_multi_lrc
[486.590988] Dynamic subtest guc_multi_lrc: SUCCESS (2.244s)
[486.622506] Starting dynamic subtest: guc_hang
[489.529955] Dynamic subtest guc_hang: SUCCESS (2.935s)
[489.540606] Starting dynamic subtest: late_gt_pm
[491.778672] Dynamic subtest late_gt_pm: SUCCESS (2.245s)
[491.779975] Subtest live: SUCCESS (167.896s)
[491.817410] [161/161] (567s left) dmabuf (all-tests)
[491.866190] Starting subtest: all-tests
[491.867726] Starting dynamic subtest: sanitycheck
[491.948203] Dynamic subtest sanitycheck: SUCCESS (0.081s)
[491.971163] Starting dynamic subtest: dma_fence
[492.199057] Dynamic subtest dma_fence: SUCCESS (0.238s)
[492.210040] Starting dynamic subtest: dma_fence_chain
[502.688060] Dynamic subtest dma_fence_chain: SUCCESS (10.485s)
[502.708963] Starting dynamic subtest: dma_fence_unwrap
[502.777329] Dynamic subtest dma_fence_unwrap: SUCCESS (0.094s)
[502.803440] Starting dynamic subtest: dma_resv
[502.878528] Dynamic subtest dma_resv: SUCCESS (0.097s)
[502.880204] Subtest all-tests: SUCCESS (11.013s)
[502.928735] Closing watchdogs
Done.