[87.294050] Initializing watchdogs
[87.294140]   /dev/watchdog0
[87.311504] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:46d3 Intel Alderlake_n (Gen12) 
[87.316113] [001/161] (960s left) i915_module_load (load)
[87.546663] Starting subtest: load
[88.957754] Subtest load: SUCCESS (1.413s)
[89.001053] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[89.002869] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[89.006457] [002/161] (958s left) core_auth (basic-auth)
[89.108510] Starting subtest: basic-auth
[89.109759] Subtest basic-auth: SUCCESS (0.002s)
[89.188704] [003/161] (958s left) debugfs_test (read_all_entries)
[89.281990] Starting subtest: read_all_entries
[89.502508] Subtest read_all_entries: SUCCESS (0.224s)
[89.589671] [004/161] (958s left) debugfs_test (basic-hwmon)
[89.636616] Starting subtest: basic-hwmon
[89.638191] Subtest basic-hwmon: SKIP (0.000s)
[89.738905] [005/161] (958s left) debugfs_test (sysfs)
[89.786508] Starting subtest: sysfs
[89.852026] Subtest sysfs: SUCCESS (0.065s)
[89.949077] [006/161] (958s left) fbdev (eof)
[90.006609] Starting subtest: eof
[90.007942] Subtest eof: SUCCESS (0.000s)
[90.063571] [007/161] (958s left) fbdev (info)
[90.105724] Starting subtest: info
[90.106538] Subtest info: SUCCESS (0.000s)
[90.178787] [008/161] (957s left) fbdev (nullptr)
[90.230445] Starting subtest: nullptr
[90.231717] Subtest nullptr: SUCCESS (0.000s)
[90.290669] [009/161] (957s left) fbdev (read)
[90.346743] Starting subtest: read
[92.232786] Subtest read: SUCCESS (1.879s)
[92.287874] [010/161] (955s left) fbdev (write)
[92.345690] Starting subtest: write
[93.308809] Subtest write: SUCCESS (0.956s)
[93.374629] [011/161] (954s left) gem_basic (bad-close)
[93.419031] Starting subtest: bad-close
[93.420646] Subtest bad-close: SUCCESS (0.000s)
[93.494198] [012/161] (954s left) gem_basic (create-close)
[93.535863] Starting subtest: create-close
[93.537655] Subtest create-close: SUCCESS (0.000s)
[93.635996] [013/161] (954s left) gem_basic (create-fd-close)
[93.680671] Starting subtest: create-fd-close
[93.682341] Subtest create-fd-close: SUCCESS (0.000s)
[93.808602] [014/161] (954s left) gem_busy (busy@all-engines)
[93.875602] Starting subtest: busy
[93.876467] Starting dynamic subtest: all-engines
[93.930276] Dynamic subtest all-engines: SUCCESS (0.055s)
[93.931381] Subtest busy: SUCCESS (0.055s)
[94.284814] [015/161] (954s left) gem_close_race (basic-process)
[94.345161] Starting subtest: basic-process
[94.390482] Subtest basic-process: SUCCESS (0.045s)
[94.578225] [016/161] (953s left) gem_close_race (basic-threads)
[94.637151] Starting subtest: basic-threads
[95.874629] Subtest basic-threads: SUCCESS (1.238s)
[96.064307] [017/161] (952s left) gem_ctx_create (basic)
[96.119880] Starting subtest: basic
[96.120776] Subtest basic: SUCCESS (0.000s)
[96.312968] [018/161] (952s left) gem_ctx_create (basic-files)
[96.367234] Starting subtest: basic-files
[98.382791] Subtest basic-files: SUCCESS (2.017s)
[98.585036] [019/161] (949s left) gem_ctx_exec (basic)
[98.630904] Starting subtest: basic
[98.635304] Subtest basic: SUCCESS (0.005s)
[98.740587] [020/161] (949s left) gem_exec_basic (basic)
[98.794516] Starting subtest: basic
[98.795311] Starting dynamic subtest: rcs0-smem
[98.796908] Dynamic subtest rcs0-smem: SUCCESS (0.002s)
[98.804862] Starting dynamic subtest: bcs0-smem
[98.805584] Dynamic subtest bcs0-smem: SUCCESS (0.001s)
[98.812309] Starting dynamic subtest: vcs0-smem
[98.813014] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[98.819531] Starting dynamic subtest: vecs0-smem
[98.820231] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[98.820935] Subtest basic: SUCCESS (0.007s)
[98.994679] [021/161] (949s left) gem_exec_create (basic)
[99.051399] Starting subtest: basic
[99.052369] Starting dynamic subtest: smem
[101.158563] Dynamic subtest smem: SUCCESS (2.108s)
[101.159924] Subtest basic: SUCCESS (2.108s)
[101.350748] [022/161] (947s left) gem_exec_fence (basic-busy)
[101.531242] Starting subtest: basic-busy
[101.532277] Starting dynamic subtest: rcs0
[101.622576] Dynamic subtest rcs0: SUCCESS (0.090s)
[101.637925] Starting dynamic subtest: bcs0
[101.671835] Dynamic subtest bcs0: SUCCESS (0.048s)
[101.686489] Starting dynamic subtest: vcs0
[101.720877] Dynamic subtest vcs0: SUCCESS (0.048s)
[101.735986] Starting dynamic subtest: vecs0
[101.772627] Dynamic subtest vecs0: SUCCESS (0.051s)
[101.774083] Subtest basic-busy: SUCCESS (0.241s)
[102.355535] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[102.357378] [023/161] (946s left) gem_exec_fence (basic-wait)
[102.537062] Starting subtest: basic-wait
[102.538233] Starting dynamic subtest: rcs0
[102.620638] Dynamic subtest rcs0: SUCCESS (0.084s)
[102.635059] Starting dynamic subtest: bcs0
[102.670731] Dynamic subtest bcs0: SUCCESS (0.049s)
[102.684950] Starting dynamic subtest: vcs0
[102.722684] Dynamic subtest vcs0: SUCCESS (0.051s)
[102.736992] Starting dynamic subtest: vecs0
[102.776835] Dynamic subtest vecs0: SUCCESS (0.053s)
[102.778172] Subtest basic-wait: SUCCESS (0.241s)
[103.303143] [024/161] (945s left) gem_exec_fence (basic-await)
[103.480310] Starting subtest: basic-await
[103.481462] Starting dynamic subtest: rcs0
[103.571036] Dynamic subtest rcs0: SUCCESS (0.091s)
[103.581504] Starting dynamic subtest: bcs0
[103.659324] Dynamic subtest bcs0: SUCCESS (0.089s)
[103.676252] Starting dynamic subtest: vcs0
[103.753899] Dynamic subtest vcs0: SUCCESS (0.094s)
[103.771863] Starting dynamic subtest: vecs0
[103.849879] Dynamic subtest vecs0: SUCCESS (0.094s)
[103.851548] Subtest basic-await: SUCCESS (0.370s)
[104.369428] [025/161] (944s left) gem_exec_fence (nb-await)
[104.547705] Starting subtest: nb-await
[104.548842] Starting dynamic subtest: rcs0
[104.615665] Dynamic subtest rcs0: SUCCESS (0.068s)
[104.627092] Starting dynamic subtest: bcs0
[104.676965] Dynamic subtest bcs0: SUCCESS (0.061s)
[104.695248] Starting dynamic subtest: vcs0
[104.742776] Dynamic subtest vcs0: SUCCESS (0.065s)
[104.764456] Starting dynamic subtest: vecs0
[104.810774] Dynamic subtest vecs0: SUCCESS (0.067s)
[104.811548] Subtest nb-await: SUCCESS (0.264s)
[105.339491] [026/161] (943s left) gem_exec_gttfill (basic)
[105.397218] Starting subtest: basic
[112.361645] Subtest basic: SUCCESS (6.958s)
[112.558528] [027/161] (936s left) gem_exec_parallel (engines)
[112.682218] Starting subtest: engines
[112.683186] Starting dynamic subtest: basic
[115.616868] Dynamic subtest basic: SUCCESS (2.935s)
[115.633018] Starting dynamic subtest: contexts
[119.291701] Dynamic subtest contexts: SUCCESS (3.666s)
[119.305836] Starting dynamic subtest: fds
[123.431270] Dynamic subtest fds: SUCCESS (4.140s)
[123.449378] Starting dynamic subtest: userptr
[125.999957] Dynamic subtest userptr: SUCCESS (2.577s)
[126.001389] Subtest engines: SUCCESS (13.321s)
[126.208785] [028/161] (922s left) gem_exec_store (basic)
[126.280382] Starting subtest: basic
[126.360177] Subtest basic: SUCCESS (0.080s)
[126.561011] [029/161] (922s left) gem_flink_basic (bad-flink)
[126.611611] Starting subtest: bad-flink
[126.612384] Subtest bad-flink: SUCCESS (0.000s)
[126.692481] [030/161] (922s left) gem_flink_basic (bad-open)
[126.742510] Starting subtest: bad-open
[126.743330] Subtest bad-open: SUCCESS (0.000s)
[126.826144] [031/161] (922s left) gem_flink_basic (basic)
[126.877653] Starting subtest: basic
[126.878463] Subtest basic: SUCCESS (0.000s)
[126.983409] [032/161] (922s left) gem_flink_basic (double-flink)
[127.032225] Starting subtest: double-flink
[127.033200] Subtest double-flink: SUCCESS (0.000s)
[127.136663] [033/161] (922s left) gem_flink_basic (flink-lifetime)
[127.185361] Starting subtest: flink-lifetime
[127.186222] Subtest flink-lifetime: SUCCESS (0.001s)
[127.293618] [034/161] (921s left) gem_huc_copy (huc-copy)
[127.351374] Starting subtest: huc-copy
[127.357788] Subtest huc-copy: SUCCESS (0.006s)
[127.501937] [035/161] (921s left) gem_linear_blits (basic)
[127.633302] Starting subtest: basic
[127.645194] Subtest basic: SUCCESS (0.012s)
[127.790254] [036/161] (921s left) gem_mmap (basic)
[127.884078] Starting subtest: basic
[127.887892] Subtest basic: SUCCESS (0.000s)
[127.982520] [037/161] (921s left) gem_mmap_gtt (basic)
[128.031654] Starting subtest: basic
[128.032435] Subtest basic: SUCCESS (0.001s)
[128.115953] [038/161] (921s left) gem_render_linear_blits (basic)
[128.171282] Starting subtest: basic
[128.229626] Subtest basic: SUCCESS (0.058s)
[128.329835] [039/161] (921s left) gem_render_tiled_blits (basic)
[128.384865] Starting subtest: basic
[128.452244] Subtest basic: SUCCESS (0.067s)
[128.552527] [040/161] (921s left) gem_ringfill (basic-all)
[128.860856] Starting subtest: basic-all
[130.027296] Subtest basic-all: SUCCESS (1.158s)
[130.159545] [041/161] (919s left) gem_softpin (allocator-basic)
[130.226181] Starting subtest: allocator-basic
[130.342361] Subtest allocator-basic: SUCCESS (0.108s)
[130.462963] [042/161] (919s left) gem_softpin (allocator-basic-reserve)
[130.522917] Starting subtest: allocator-basic-reserve
[130.626461] Subtest allocator-basic-reserve: SUCCESS (0.103s)
[130.776321] [043/161] (918s left) gem_softpin (safe-alignment)
[130.837733] Starting subtest: safe-alignment
[130.864110] Subtest safe-alignment: SUCCESS (0.026s)
[130.987328] [044/161] (918s left) gem_sync (basic-all)
[131.056669] Starting subtest: basic-all
[134.291247] Subtest basic-all: SUCCESS (3.234s)
[134.486185] [045/161] (915s left) gem_sync (basic-each)
[134.547124] Starting subtest: basic-each
[137.261613] Subtest basic-each: SUCCESS (2.714s)
[137.455459] [046/161] (912s left) gem_tiled_blits (basic)
[137.557430] Starting subtest: basic
[137.583759] Subtest basic: SUCCESS (0.027s)
[137.732448] [047/161] (912s left) gem_tiled_fence_blits (basic)
[137.897597] Starting subtest: basic
[137.921180] Subtest basic: SUCCESS (0.024s)
[138.063475] [048/161] (911s left) gem_tiled_pread_basic
[138.230743] [049/161] (911s left) gem_wait (busy@all-engines)
[138.301193] Starting subtest: busy
[138.302075] Starting dynamic subtest: all-engines
[138.860640] Dynamic subtest all-engines: SUCCESS (0.558s)
[138.862065] Subtest busy: SUCCESS (0.559s)
[139.084763] [050/161] (911s left) gem_wait (wait@all-engines)
[139.157087] Starting subtest: wait
[139.157985] Starting dynamic subtest: all-engines
[140.236783] Dynamic subtest all-engines: SUCCESS (1.078s)
[140.238274] Subtest wait: SUCCESS (1.079s)
[140.450659] [051/161] (909s left) i915_getparams_basic (basic-eu-total)
[140.494726] Starting subtest: basic-eu-total
[140.496457] Subtest basic-eu-total: SUCCESS (0.000s)
[140.588678] [052/161] (909s left) i915_getparams_basic (basic-subslice-total)
[140.630916] Starting subtest: basic-subslice-total
[140.633538] Subtest basic-subslice-total: SUCCESS (0.000s)
[140.710855] [053/161] (909s left) i915_hangman (error-state-basic)
[140.768956] Starting subtest: error-state-basic
[140.889655] Subtest error-state-basic: SUCCESS (0.121s)
[140.970985] [054/161] (909s left) i915_pciid
[141.093636] [055/161] (909s left) kms_addfb_basic (addfb25-4-tiled)
[141.173500] Starting subtest: addfb25-4-tiled
[141.174610] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[141.278518] [056/161] (909s left) kms_addfb_basic (addfb25-bad-modifier)
[141.346347] Starting subtest: addfb25-bad-modifier
[141.347329] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[141.444622] [057/161] (908s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[141.514394] Starting subtest: addfb25-framebuffer-vs-set-tiling
[141.515183] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.001s)
[141.610920] [058/161] (908s left) kms_addfb_basic (addfb25-modifier-no-flag)
[141.682136] Starting subtest: addfb25-modifier-no-flag
[141.682980] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[141.779684] [059/161] (908s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[141.851144] Starting subtest: addfb25-x-tiled-legacy
[141.851914] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[141.947733] [060/161] (908s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[142.018861] Starting subtest: addfb25-x-tiled-mismatch-legacy
[142.019746] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[142.121450] [061/161] (908s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[142.195076] Starting subtest: addfb25-yf-tiled-legacy
[142.195999] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[142.298830] [062/161] (908s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[142.372907] Starting subtest: addfb25-y-tiled-legacy
[142.373740] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[142.473893] [063/161] (908s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[142.550367] Starting subtest: addfb25-y-tiled-small-legacy
[142.551192] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s)
[142.656708] [064/161] (908s left) kms_addfb_basic (bad-pitch-0)
[142.708870] Starting subtest: bad-pitch-0
[142.710174] Subtest bad-pitch-0: SUCCESS (0.000s)
[142.840163] [065/161] (907s left) kms_addfb_basic (bad-pitch-1024)
[142.888752] Starting subtest: bad-pitch-1024
[142.889561] Subtest bad-pitch-1024: SUCCESS (0.000s)
[143.025144] [066/161] (907s left) kms_addfb_basic (bad-pitch-128)
[143.075820] Starting subtest: bad-pitch-128
[143.076615] Subtest bad-pitch-128: SUCCESS (0.000s)
[143.208950] [067/161] (907s left) kms_addfb_basic (bad-pitch-256)
[143.258877] Starting subtest: bad-pitch-256
[143.259870] Subtest bad-pitch-256: SUCCESS (0.000s)
[143.389626] [068/161] (907s left) kms_addfb_basic (bad-pitch-32)
[143.440792] Starting subtest: bad-pitch-32
[143.441736] Subtest bad-pitch-32: SUCCESS (0.000s)
[143.574948] [069/161] (907s left) kms_addfb_basic (bad-pitch-63)
[143.625553] Starting subtest: bad-pitch-63
[143.626312] Subtest bad-pitch-63: SUCCESS (0.000s)
[143.749931] [070/161] (907s left) kms_addfb_basic (bad-pitch-65536)
[143.799691] Starting subtest: bad-pitch-65536
[143.800478] Subtest bad-pitch-65536: SUCCESS (0.000s)
[143.931828] [071/161] (907s left) kms_addfb_basic (bad-pitch-999)
[143.982381] Starting subtest: bad-pitch-999
[143.983167] Subtest bad-pitch-999: SUCCESS (0.000s)
[144.108585] [072/161] (907s left) kms_addfb_basic (basic)
[144.157860] Starting subtest: basic
[144.158689] Subtest basic: SUCCESS (0.000s)
[144.290785] [073/161] (906s left) kms_addfb_basic (basic-x-tiled-legacy)
[144.341902] Starting subtest: basic-x-tiled-legacy
[144.342690] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[144.465164] [074/161] (906s left) kms_addfb_basic (basic-y-tiled-legacy)
[144.515937] Starting subtest: basic-y-tiled-legacy
[144.516791] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[144.649627] [075/161] (906s left) kms_addfb_basic (bo-too-small)
[144.727236] Starting subtest: bo-too-small
[144.728169] Subtest bo-too-small: SUCCESS (0.000s)
[144.824522] [076/161] (906s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[144.904213] Starting subtest: bo-too-small-due-to-tiling
[144.905154] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[145.023807] [077/161] (906s left) kms_addfb_basic (clobberred-modifier)
[145.072116] Starting subtest: clobberred-modifier
[145.072916] Subtest clobberred-modifier: SUCCESS (0.000s)
[145.198902] [078/161] (906s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[145.250812] Starting subtest: framebuffer-vs-set-tiling
[145.251676] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[145.382762] [079/161] (906s left) kms_addfb_basic (invalid-get-prop)
[145.434818] Starting subtest: invalid-get-prop
[145.435584] Subtest invalid-get-prop: SUCCESS (0.000s)
[145.565796] [080/161] (905s left) kms_addfb_basic (invalid-get-prop-any)
[145.615131] Starting subtest: invalid-get-prop-any
[145.615939] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[145.741977] [081/161] (905s left) kms_addfb_basic (invalid-set-prop)
[145.792918] Starting subtest: invalid-set-prop
[145.793741] Subtest invalid-set-prop: SUCCESS (0.000s)
[145.931831] [082/161] (905s left) kms_addfb_basic (invalid-set-prop-any)
[145.982596] Starting subtest: invalid-set-prop-any
[145.983405] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[146.116998] [083/161] (905s left) kms_addfb_basic (no-handle)
[146.166828] Starting subtest: no-handle
[146.167678] Subtest no-handle: SUCCESS (0.000s)
[146.298367] [084/161] (905s left) kms_addfb_basic (size-max)
[146.374799] Starting subtest: size-max
[146.375573] Subtest size-max: SUCCESS (0.000s)
[146.480981] [085/161] (905s left) kms_addfb_basic (small-bo)
[146.556296] Starting subtest: small-bo
[146.557175] Subtest small-bo: SUCCESS (0.000s)
[146.659487] [086/161] (905s left) kms_addfb_basic (tile-pitch-mismatch)
[146.710169] Starting subtest: tile-pitch-mismatch
[146.710945] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[146.832169] [087/161] (905s left) kms_addfb_basic (too-high)
[146.910167] Starting subtest: too-high
[146.911113] Subtest too-high: SUCCESS (0.001s)
[147.017691] [088/161] (904s left) kms_addfb_basic (too-wide)
[147.092831] Starting subtest: too-wide
[147.093645] Subtest too-wide: SUCCESS (0.000s)
[147.192738] [089/161] (904s left) kms_addfb_basic (unused-handle)
[147.242848] Starting subtest: unused-handle
[147.243676] Subtest unused-handle: SUCCESS (0.000s)
[147.374717] [090/161] (904s left) kms_addfb_basic (unused-modifier)
[147.424913] Starting subtest: unused-modifier
[147.425787] Subtest unused-modifier: SUCCESS (0.000s)
[147.549125] [091/161] (904s left) kms_addfb_basic (unused-offsets)
[147.601500] Starting subtest: unused-offsets
[147.602436] Subtest unused-offsets: SUCCESS (0.000s)
[147.724689] [092/161] (904s left) kms_addfb_basic (unused-pitches)
[147.779596] Starting subtest: unused-pitches
[147.780368] Subtest unused-pitches: SUCCESS (0.000s)
[147.901456] [093/161] (904s left) kms_busy (basic)
[147.985149] Starting subtest: basic
[147.986097] Starting dynamic subtest: flip
[148.676405] Dynamic subtest flip: SUCCESS (0.689s)
[148.697825] Starting dynamic subtest: modeset
[152.462769] Dynamic subtest modeset: SUCCESS (3.786s)
[152.463916] Subtest basic: SUCCESS (4.478s)
[153.300873] [094/161] (898s left) kms_prop_blob (basic)
[153.351350] Starting subtest: basic
[153.352140] Subtest basic: SUCCESS (0.000s)
[153.420427] [095/161] (898s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[153.518409] Starting subtest: basic-busy-flip-before-cursor-atomic
[153.519945] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[153.617597] [096/161] (898s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[153.713374] Starting subtest: basic-busy-flip-before-cursor-legacy
[153.714776] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[153.809073] [097/161] (898s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[153.905932] Starting subtest: basic-flip-after-cursor-atomic
[154.631524] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.723s)
[155.536147] [098/161] (896s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[155.635395] Starting subtest: basic-flip-after-cursor-legacy
[156.357641] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.720s)
[157.247384] [099/161] (895s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[157.347161] Starting subtest: basic-flip-after-cursor-varying-size
[158.071985] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.724s)
[158.954416] [100/161] (893s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[159.055572] Starting subtest: basic-flip-before-cursor-atomic
[159.786988] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.730s)
[160.668383] [101/161] (891s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[160.765212] Starting subtest: basic-flip-before-cursor-legacy
[161.500026] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.733s)
[162.381511] [102/161] (890s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[162.483177] Starting subtest: basic-flip-before-cursor-varying-size
[163.209401] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.724s)
[164.090122] [103/161] (888s left) kms_dsc (dsc-basic)
[164.165111] Starting subtest: dsc-basic
[164.168743] Subtest dsc-basic: SKIP (0.001s)
[164.241412] [104/161] (888s left) kms_flip (basic-flip-vs-dpms)
[164.309837] Starting subtest: basic-flip-vs-dpms
[164.312037] Starting dynamic subtest: A-eDP1
[167.036514] Dynamic subtest A-eDP1: SUCCESS (2.724s)
[167.058283] Starting dynamic subtest: B-eDP1
[169.312884] Dynamic subtest B-eDP1: SUCCESS (2.275s)
[169.335166] Starting dynamic subtest: C-eDP1
[171.683865] Dynamic subtest C-eDP1: SUCCESS (2.369s)
[171.686517] Subtest basic-flip-vs-dpms: SUCCESS (7.375s)
[172.512612] [105/161] (880s left) kms_flip (basic-flip-vs-modeset)
[172.585666] Starting subtest: basic-flip-vs-modeset
[172.587932] Starting dynamic subtest: A-eDP1
[175.242864] Dynamic subtest A-eDP1: SUCCESS (2.654s)
[175.263738] Starting dynamic subtest: B-eDP1
[177.513099] Dynamic subtest B-eDP1: SUCCESS (2.269s)
[177.531021] Starting dynamic subtest: C-eDP1
[179.663835] Dynamic subtest C-eDP1: SUCCESS (2.150s)
[179.664647] Subtest basic-flip-vs-modeset: SUCCESS (7.079s)
[180.500469] [106/161] (872s left) kms_flip (basic-flip-vs-wf_vblank)
[180.575178] Starting subtest: basic-flip-vs-wf_vblank
[180.576205] Starting dynamic subtest: A-eDP1
[182.551673] Dynamic subtest A-eDP1: SUCCESS (1.975s)
[182.575573] Starting dynamic subtest: B-eDP1
[184.573736] Dynamic subtest B-eDP1: SUCCESS (2.020s)
[184.597805] Starting dynamic subtest: C-eDP1
[186.602639] Dynamic subtest C-eDP1: SUCCESS (2.022s)
[186.603839] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.025s)
[187.879263] [107/161] (864s left) kms_flip (basic-plain-flip)
[187.956168] Starting subtest: basic-plain-flip
[187.957115] Starting dynamic subtest: A-eDP1
[189.790927] Dynamic subtest A-eDP1: SUCCESS (1.833s)
[189.812780] Starting dynamic subtest: B-eDP1
[191.601527] Dynamic subtest B-eDP1: SUCCESS (1.809s)
[191.622517] Starting dynamic subtest: C-eDP1
[193.410444] Dynamic subtest C-eDP1: SUCCESS (1.804s)
[193.411465] Subtest basic-plain-flip: SUCCESS (5.453s)
[194.622589] [108/161] (858s left) kms_force_connector_basic (force-connector-state)
[194.686223] Starting subtest: force-connector-state
[195.112267] Subtest force-connector-state: SUCCESS (0.426s)
[196.232941] [109/161] (856s left) kms_force_connector_basic (force-edid)
[196.289303] Starting subtest: force-edid
[196.327345] Subtest force-edid: SUCCESS (0.038s)
[196.460934] [110/161] (856s left) kms_force_connector_basic (force-load-detect)
[196.520231] Starting subtest: force-load-detect
[196.521930] Subtest force-load-detect: SKIP (0.000s)
[196.626877] [111/161] (856s left) kms_force_connector_basic (prune-stale-modes)
[196.684235] Starting subtest: prune-stale-modes
[196.716970] Subtest prune-stale-modes: SUCCESS (0.033s)
[196.839187] [112/161] (856s left) kms_frontbuffer_tracking (basic)
[196.913867] Starting subtest: basic
[200.202401] Subtest basic: SUCCESS (3.289s)
[200.332724] [113/161] (852s left) kms_hdmi_inject (inject-audio)
[200.679173] Starting subtest: inject-audio
[200.843035] Subtest inject-audio: SUCCESS (0.166s)
[201.612195] [114/161] (851s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[201.683819] Starting subtest: compare-crc-sanitycheck-xr24
[201.684660] Starting dynamic subtest: pipe-A-eDP-1
[202.295224] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.609s)
[202.317772] Starting dynamic subtest: pipe-B-eDP-1
[203.666178] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.368s)
[203.688089] Starting dynamic subtest: pipe-C-eDP-1
[205.025091] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.356s)
[205.029486] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.342s)
[205.880385] [115/161] (847s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[205.953788] Starting subtest: compare-crc-sanitycheck-nv12
[205.954720] Starting dynamic subtest: pipe-A-eDP-1
[206.633342] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.677s)
[206.653751] Starting dynamic subtest: pipe-B-eDP-1
[207.967215] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.326s)
[207.984440] Starting dynamic subtest: pipe-C-eDP-1
[209.327407] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.359s)
[209.328698] Subtest compare-crc-sanitycheck-nv12: SUCCESS (3.372s)
[210.171954] [116/161] (843s left) kms_pipe_crc_basic (hang-read-crc)
[210.245875] Starting subtest: hang-read-crc
[210.246732] Starting dynamic subtest: pipe-A-eDP-1
[212.328249] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.081s)
[212.344615] Starting dynamic subtest: pipe-B-eDP-1
[215.137720] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.807s)
[215.151956] Starting dynamic subtest: pipe-C-eDP-1
[217.891646] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.750s)
[217.892710] Subtest hang-read-crc: SUCCESS (7.644s)
[218.767202] [117/161] (834s left) kms_pipe_crc_basic (nonblocking-crc)
[218.840428] Starting subtest: nonblocking-crc
[218.841602] Starting dynamic subtest: pipe-A-eDP-1
[219.564024] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.721s)
[219.578295] Starting dynamic subtest: pipe-B-eDP-1
[221.043194] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.477s)
[221.061852] Starting dynamic subtest: pipe-C-eDP-1
[222.394473] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.347s)
[222.395303] Subtest nonblocking-crc: SUCCESS (3.555s)
[223.269831] [118/161] (830s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[223.344312] Starting subtest: nonblocking-crc-frame-sequence
[223.345091] Starting dynamic subtest: pipe-A-eDP-1
[224.054983] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.710s)
[224.072942] Starting dynamic subtest: pipe-B-eDP-1
[225.551316] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.494s)
[225.566752] Starting dynamic subtest: pipe-C-eDP-1
[226.913479] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.360s)
[226.914248] Subtest nonblocking-crc-frame-sequence: SUCCESS (3.570s)
[227.779790] [119/161] (825s left) kms_pipe_crc_basic (read-crc)
[227.853830] Starting subtest: read-crc
[227.854705] Starting dynamic subtest: pipe-A-eDP-1
[228.537318] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.681s)
[228.556158] Starting dynamic subtest: pipe-B-eDP-1
[229.923867] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.384s)
[229.939540] Starting dynamic subtest: pipe-C-eDP-1
[231.291497] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.364s)
[231.292663] Subtest read-crc: SUCCESS (3.438s)
[232.174043] [120/161] (821s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[232.243451] Starting subtest: read-crc-frame-sequence
[232.244239] Starting dynamic subtest: pipe-A-eDP-1
[232.941814] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.695s)
[232.960568] Starting dynamic subtest: pipe-B-eDP-1
[234.332699] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.387s)
[234.346472] Starting dynamic subtest: pipe-C-eDP-1
[235.675609] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.339s)
[235.676866] Subtest read-crc-frame-sequence: SUCCESS (3.431s)
[236.568803] [121/161] (816s left) kms_pm_backlight (basic-brightness)
[236.645448] Starting subtest: basic-brightness
[236.681997] Starting dynamic subtest: eDP-1
[237.210795] Dynamic subtest eDP-1: SUCCESS (0.528s)
[237.211650] Subtest basic-brightness: SUCCESS (0.565s)
[238.070615] [122/161] (815s left) kms_pm_rpm (basic-pci-d3-state)
[239.582761] Starting subtest: basic-pci-d3-state
[241.070792] Subtest basic-pci-d3-state: SUCCESS (1.489s)
[241.195491] [123/161] (812s left) kms_pm_rpm (basic-rte)
[241.238996] Starting subtest: basic-rte
[244.174223] Subtest basic-rte: SUCCESS (2.934s)
[245.676659] [124/161] (807s left) kms_psr (psr-primary-page-flip)
[245.748490] Starting subtest: psr-primary-page-flip
[245.749279] Starting dynamic subtest: eDP-1
[247.502818] Dynamic subtest eDP-1: SUCCESS (1.752s)
[247.504203] Subtest psr-primary-page-flip: SUCCESS (1.754s)
[248.368594] [125/161] (805s left) kms_psr (psr-cursor-plane-move)
[248.443366] Starting subtest: psr-cursor-plane-move
[248.444346] Starting dynamic subtest: eDP-1
[250.190573] Dynamic subtest eDP-1: SUCCESS (1.746s)
[250.191785] Subtest psr-cursor-plane-move: SUCCESS (1.748s)
[251.060333] [126/161] (802s left) kms_psr (psr-sprite-plane-onoff)
[251.135850] Starting subtest: psr-sprite-plane-onoff
[251.136732] Starting dynamic subtest: eDP-1
[252.910894] Dynamic subtest eDP-1: SUCCESS (1.774s)
[252.912202] Subtest psr-sprite-plane-onoff: SUCCESS (1.776s)
[253.836383] [127/161] (799s left) kms_psr (psr-primary-mmap-gtt)
[253.914395] Starting subtest: psr-primary-mmap-gtt
[253.915357] Starting dynamic subtest: eDP-1
[255.654858] Dynamic subtest eDP-1: SUCCESS (1.739s)
[255.656510] Subtest psr-primary-mmap-gtt: SUCCESS (1.741s)
[256.529277] [128/161] (797s left) kms_setmode (basic-clone-single-crtc)
[256.578516] Starting subtest: basic-clone-single-crtc
[256.580793] Subtest basic-clone-single-crtc: SKIP (0.000s)
[256.678373] [129/161] (797s left) i915_pm_rps (basic-api)
[256.727297] Starting subtest: basic-api
[256.728877] Subtest basic-api: SKIP (0.000s)
[256.805195] [130/161] (797s left) prime_self_import (basic-llseek-bad)
[256.848082] Starting subtest: basic-llseek-bad
[256.849647] Subtest basic-llseek-bad: SUCCESS (0.003s)
[256.941625] [131/161] (797s left) prime_self_import (basic-llseek-size)
[256.986309] Starting subtest: basic-llseek-size
[256.988564] Subtest basic-llseek-size: SUCCESS (0.003s)
[257.084380] [132/161] (796s left) prime_self_import (basic-with_fd_dup)
[257.127326] Starting subtest: basic-with_fd_dup
[257.130938] Subtest basic-with_fd_dup: SUCCESS (0.005s)
[257.223790] [133/161] (796s left) prime_self_import (basic-with_one_bo)
[257.266363] Starting subtest: basic-with_one_bo
[257.269565] Subtest basic-with_one_bo: SUCCESS (0.003s)
[257.347494] [134/161] (796s left) prime_self_import (basic-with_one_bo_two_files)
[257.389856] Starting subtest: basic-with_one_bo_two_files
[257.392543] Subtest basic-with_one_bo_two_files: SUCCESS (0.002s)
[257.490493] [135/161] (796s left) prime_self_import (basic-with_two_bos)
[257.533729] Starting subtest: basic-with_two_bos
[257.535318] Subtest basic-with_two_bos: SUCCESS (0.003s)
[257.648475] [136/161] (796s left) prime_vgem (basic-fence-flip)
[257.721516] Starting subtest: basic-fence-flip
[257.982445] Subtest basic-fence-flip: SUCCESS (0.261s)
[258.083292] [137/161] (796s left) prime_vgem (basic-fence-mmap)
[258.156183] Starting subtest: basic-fence-mmap
[258.182738] Subtest basic-fence-mmap: SUCCESS (0.029s)
[258.280842] [138/161] (796s left) prime_vgem (basic-fence-read)
[258.352723] Starting subtest: basic-fence-read
[258.362228] Subtest basic-fence-read: SKIP (0.009s)
[258.453961] [139/161] (795s left) prime_vgem (basic-gtt)
[258.534690] Starting subtest: basic-gtt
[258.551215] Subtest basic-gtt: SUCCESS (0.017s)
[258.667942] [140/161] (795s left) prime_vgem (basic-read)
[258.741283] Starting subtest: basic-read
[258.751700] Subtest basic-read: SKIP (0.010s)
[258.843587] [141/161] (795s left) prime_vgem (basic-write)
[258.912764] Starting subtest: basic-write
[258.922804] Subtest basic-write: SKIP (0.009s)
[259.020869] [142/161] (795s left) vgem_basic (setversion)
[259.074286] Starting subtest: setversion
[259.076171] Subtest setversion: SUCCESS (0.000s)
[259.140083] [143/161] (795s left) vgem_basic (create)
[259.192122] Starting subtest: create
[259.192902] Subtest create: SUCCESS (0.000s)
[259.256236] [144/161] (795s left) vgem_basic (debugfs)
[259.308269] Starting subtest: debugfs
[259.309335] Subtest debugfs: SUCCESS (0.000s)
[259.372315] [145/161] (795s left) vgem_basic (dmabuf-export)
[259.426074] Starting subtest: dmabuf-export
[259.440953] Subtest dmabuf-export: SUCCESS (0.014s)
[259.539799] [146/161] (795s left) vgem_basic (dmabuf-fence)
[259.591393] Starting subtest: dmabuf-fence
[259.592334] Subtest dmabuf-fence: SUCCESS (0.001s)
[259.655148] [147/161] (795s left) vgem_basic (dmabuf-fence-before)
[259.707611] Starting subtest: dmabuf-fence-before
[259.708619] Subtest dmabuf-fence-before: SUCCESS (0.000s)
[259.772515] [148/161] (794s left) vgem_basic (dmabuf-mmap)
[259.825646] Starting subtest: dmabuf-mmap
[259.840685] Subtest dmabuf-mmap: SUCCESS (0.016s)
[259.900146] [149/161] (794s left) vgem_basic (mmap)
[259.955160] Starting subtest: mmap
[259.966647] Subtest mmap: SUCCESS (0.014s)
[260.029215] [150/161] (794s left) vgem_basic (second-client)
[260.080345] Starting subtest: second-client
[260.097467] Subtest second-client: SUCCESS (0.016s)
[260.162517] [151/161] (794s left) vgem_basic (sysfs)
[260.215597] Starting subtest: sysfs
[260.216417] Subtest sysfs: SUCCESS (0.001s)
[260.278725] [152/161] (794s left) core_hotunplug (unbind-rebind)
[260.377877] Starting subtest: unbind-rebind
[264.875358] Subtest unbind-rebind: SUCCESS (4.498s)
[264.920620] [153/161] (790s left) vgem_basic (unload)
[264.964140] Starting subtest: unload
[294.031151] Subtest unload: SUCCESS (29.064s)
[294.152403] [154/161] (760s left) i915_module_load (reload)
[294.200410] Starting subtest: reload
[298.417144] Subtest reload: SUCCESS (4.217s)
[298.463949] [155/161] (756s left) gem_lmem_swapping (basic)
[298.518246] Subtest basic: SKIP (0.000s)
[298.568752] [156/161] (756s left) gem_lmem_swapping (parallel-random-engines)
[298.624972] Subtest parallel-random-engines: SKIP (0.000s)
[298.703092] [157/161] (756s left) gem_lmem_swapping (random-engines)
[298.765146] Subtest random-engines: SKIP (0.000s)
[298.815327] [158/161] (756s left) gem_lmem_swapping (verify-random)
[298.866013] Subtest verify-random: SKIP (0.000s)
[298.909444] [159/161] (756s left) i915_pm_rpm (module-reload)
[301.145518] Starting subtest: module-reload
[314.543805] Subtest module-reload: SUCCESS (13.389s)
[314.578613] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[314.579904] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[314.584861] [160/161] (740s left) i915_selftest (live)
[314.632062] Starting subtest: live
[314.632917] Starting dynamic subtest: sanitycheck
[316.873030] Dynamic subtest sanitycheck: SUCCESS (2.231s)
[316.884014] Starting dynamic subtest: uncore
[319.104084] Dynamic subtest uncore: SUCCESS (2.227s)
[319.115118] Starting dynamic subtest: workarounds
[322.099832] Dynamic subtest workarounds: SUCCESS (2.992s)
[322.109889] Starting dynamic subtest: gt_engines
[324.821110] Dynamic subtest gt_engines: SUCCESS (2.717s)
[324.833939] Starting dynamic subtest: gt_timelines
[335.091597] Dynamic subtest gt_timelines: SUCCESS (10.267s)
[335.102687] Starting dynamic subtest: gt_contexts
[337.335031] Dynamic subtest gt_contexts: SUCCESS (2.240s)
[337.347950] Starting dynamic subtest: gt_lrc
[341.931032] Dynamic subtest gt_lrc: SUCCESS (4.592s)
[341.942494] Starting dynamic subtest: gt_mocs
[344.702464] Dynamic subtest gt_mocs: SUCCESS (2.768s)
[344.710700] Starting dynamic subtest: gt_pm
[349.336041] Dynamic subtest gt_pm: SUCCESS (4.629s)
[349.346767] Starting dynamic subtest: gt_heartbeat
[351.585928] Dynamic subtest gt_heartbeat: SUCCESS (2.246s)
[351.596761] Starting dynamic subtest: gt_tlb
[354.932914] Dynamic subtest gt_tlb: SUCCESS (3.343s)
[354.944494] Starting dynamic subtest: requests
[363.491436] Dynamic subtest requests: SUCCESS (8.555s)
[363.504655] Starting dynamic subtest: migrate
[368.366781] Dynamic subtest migrate: SUCCESS (4.872s)
[368.378880] Starting dynamic subtest: active
[370.658799] Dynamic subtest active: SUCCESS (2.289s)
[370.671743] Starting dynamic subtest: objects
[373.513104] Dynamic subtest objects: SUCCESS (2.851s)
[373.524388] Starting dynamic subtest: mman
[379.133884] Dynamic subtest mman: SUCCESS (5.617s)
[379.146393] Starting dynamic subtest: dmabuf
[381.621342] Dynamic subtest dmabuf: SUCCESS (2.492s)
[381.636828] Starting dynamic subtest: vma
[384.068105] Dynamic subtest vma: SUCCESS (2.435s)
[384.079110] Starting dynamic subtest: coherency
[388.644888] Dynamic subtest coherency: SUCCESS (4.573s)
[388.655588] Starting dynamic subtest: gtt
[398.118062] Dynamic subtest gtt: SUCCESS (9.469s)
[398.130788] Starting dynamic subtest: gem
[400.721923] Dynamic subtest gem: SUCCESS (2.600s)
[400.734722] Starting dynamic subtest: evict
[404.558498] Dynamic subtest evict: SUCCESS (3.833s)
[404.569331] Starting dynamic subtest: hugepages
[413.047261] Dynamic subtest hugepages: SUCCESS (8.485s)
[413.057921] Starting dynamic subtest: gem_contexts
[427.874098] Dynamic subtest gem_contexts: SUCCESS (14.823s)
[427.884926] Starting dynamic subtest: client
[430.352415] Dynamic subtest client: SUCCESS (2.474s)
[430.364236] Starting dynamic subtest: gem_migrate
[432.780146] Dynamic subtest gem_migrate: SUCCESS (2.424s)
[432.793982] Starting dynamic subtest: reset
[437.596548] Dynamic subtest reset: SUCCESS (4.813s)
[437.609076] Starting dynamic subtest: memory_region
[440.024077] Dynamic subtest memory_region: SUCCESS (2.424s)
[440.034483] Starting dynamic subtest: hangcheck
[456.669306] Dynamic subtest hangcheck: SUCCESS (16.641s)
[456.681861] Starting dynamic subtest: execlists
[459.050974] Dynamic subtest execlists: SUCCESS (2.378s)
[459.061952] Starting dynamic subtest: ring_submission
[461.369091] Dynamic subtest ring_submission: SUCCESS (2.314s)
[461.379958] Starting dynamic subtest: perf
[463.853767] Dynamic subtest perf: SUCCESS (2.481s)
[463.863420] Starting dynamic subtest: slpc
[470.693815] Dynamic subtest slpc: SUCCESS (6.836s)
[470.705625] Starting dynamic subtest: guc
[473.804899] Dynamic subtest guc: SUCCESS (3.108s)
[473.815713] Starting dynamic subtest: guc_multi_lrc
[476.029892] Dynamic subtest guc_multi_lrc: SUCCESS (2.222s)
[476.042546] Starting dynamic subtest: guc_hang
[478.972049] Dynamic subtest guc_hang: SUCCESS (2.939s)
[478.984811] Starting dynamic subtest: late_gt_pm
[481.253852] Dynamic subtest late_gt_pm: SUCCESS (2.278s)
[481.255359] Subtest live: SUCCESS (166.609s)
[481.294386] [161/161] (574s left) dmabuf (all-tests)
[481.340427] Starting subtest: all-tests
[481.341412] Starting dynamic subtest: sanitycheck
[481.420204] Dynamic subtest sanitycheck: SUCCESS (0.079s)
[481.442596] Starting dynamic subtest: dma_fence
[481.670064] Dynamic subtest dma_fence: SUCCESS (0.238s)
[481.685129] Starting dynamic subtest: dma_fence_chain
[492.117172] Dynamic subtest dma_fence_chain: SUCCESS (10.443s)
[492.127126] Starting dynamic subtest: dma_fence_unwrap
[492.229537] Dynamic subtest dma_fence_unwrap: SUCCESS (0.118s)
[492.254981] Starting dynamic subtest: dma_resv
[492.320336] Dynamic subtest dma_resv: SUCCESS (0.088s)
[492.322106] Subtest all-tests: SUCCESS (10.981s)
[492.368159] Closing watchdogs
Done.