[89.549380] Initializing watchdogs
[89.549983]   /dev/watchdog0
[89.717183] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:46d3 Intel Alderlake_n (Gen12) 
[89.765104] [001/161] (960s left) i915_module_load (load)
[90.973856] Starting subtest: load
[103.387348] Subtest load: SUCCESS (12.386s)
[103.759994] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[103.776802] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[103.797713] [002/161] (946s left) core_auth (basic-auth)
[104.360092] Starting subtest: basic-auth
[104.368662] Subtest basic-auth: SUCCESS (0.016s)
[104.924732] [003/161] (946s left) debugfs_test (read_all_entries)
[105.375513] Starting subtest: read_all_entries
[106.910334] Subtest read_all_entries: SUCCESS (1.531s)
[107.490591] [004/161] (943s left) debugfs_test (basic-hwmon)
[107.934245] Starting subtest: basic-hwmon
[107.940192] Subtest basic-hwmon: SKIP (0.002s)
[108.515517] [005/161] (943s left) debugfs_test (sysfs)
[108.939583] Starting subtest: sysfs
[109.423945] Subtest sysfs: SUCCESS (0.484s)
[109.955290] [006/161] (942s left) fbdev (eof)
[110.367774] Starting subtest: eof
[110.371113] Subtest eof: SUCCESS (0.004s)
[110.810981] [007/161] (941s left) fbdev (info)
[111.189784] Starting subtest: info
[111.192712] Subtest info: SUCCESS (0.000s)
[111.657825] [008/161] (941s left) fbdev (nullptr)
[112.054193] Starting subtest: nullptr
[112.057218] Subtest nullptr: SUCCESS (0.002s)
[112.504585] [009/161] (940s left) fbdev (read)
[112.913996] Starting subtest: read
[126.604826] Subtest read: SUCCESS (13.657s)
[127.032493] [010/161] (926s left) fbdev (write)
[127.434789] Starting subtest: write
[134.579773] Subtest write: SUCCESS (7.137s)
[135.056903] [011/161] (918s left) gem_basic (bad-close)
[135.443540] Starting subtest: bad-close
[135.449164] Subtest bad-close: SUCCESS (0.000s)
[135.880470] [012/161] (918s left) gem_basic (create-close)
[136.269370] Starting subtest: create-close
[136.275372] Subtest create-close: SUCCESS (0.001s)
[136.727216] [013/161] (917s left) gem_basic (create-fd-close)
[137.120203] Starting subtest: create-fd-close
[137.126140] Subtest create-fd-close: SUCCESS (0.001s)
[137.562916] [014/161] (917s left) gem_busy (busy@all-engines)
[138.049196] Starting subtest: busy
[138.051910] Starting dynamic subtest: all-engines
[138.361924] Dynamic subtest all-engines: SUCCESS (0.316s)
[138.365383] Subtest busy: SUCCESS (0.318s)
[139.249774] [015/161] (915s left) gem_close_race (basic-process)
[139.745342] Starting subtest: basic-process
[139.908888] Subtest basic-process: SUCCESS (0.166s)
[140.480494] [016/161] (915s left) gem_close_race (basic-threads)
[140.970993] Starting subtest: basic-threads
[142.478165] Subtest basic-threads: SUCCESS (1.508s)
[143.050902] [017/161] (912s left) gem_ctx_create (basic)
[143.548458] Starting subtest: basic
[143.551767] Subtest basic: SUCCESS (0.000s)
[144.135758] [018/161] (912s left) gem_ctx_create (basic-files)
[144.635347] Starting subtest: basic-files
[146.764813] Subtest basic-files: SUCCESS (2.129s)
[147.495807] [019/161] (909s left) gem_ctx_exec (basic)
[147.914337] Starting subtest: basic
[147.933162] Subtest basic: SUCCESS (0.022s)
[148.462081] [020/161] (908s left) gem_exec_basic (basic)
[148.925670] Starting subtest: basic
[148.928759] Starting dynamic subtest: rcs0-smem
[148.937246] Dynamic subtest rcs0-smem: SUCCESS (0.009s)
[148.995996] Starting dynamic subtest: bcs0-smem
[148.999129] Dynamic subtest bcs0-smem: SUCCESS (0.004s)
[149.055522] Starting dynamic subtest: vcs0-smem
[149.058695] Dynamic subtest vcs0-smem: SUCCESS (0.003s)
[149.118368] Starting dynamic subtest: vecs0-smem
[149.122413] Dynamic subtest vecs0-smem: SUCCESS (0.003s)
[149.127067] Subtest basic: SUCCESS (0.033s)
[149.633269] [021/161] (907s left) gem_exec_create (basic)
[150.128923] Starting subtest: basic
[150.132234] Starting dynamic subtest: smem
[152.366878] Dynamic subtest smem: SUCCESS (2.241s)
[152.369911] Subtest basic: SUCCESS (2.244s)
[152.947378] [022/161] (904s left) gem_exec_fence (basic-busy)
[153.652387] Starting subtest: basic-busy
[153.655695] Starting dynamic subtest: rcs0
[153.741908] Dynamic subtest rcs0: SUCCESS (0.085s)
[153.811287] Starting dynamic subtest: bcs0
[153.817851] Dynamic subtest bcs0: SUCCESS (0.067s)
[153.878974] Starting dynamic subtest: vcs0
[153.885359] Dynamic subtest vcs0: SUCCESS (0.068s)
[153.945555] Starting dynamic subtest: vecs0
[153.951207] Dynamic subtest vecs0: SUCCESS (0.057s)
[153.955799] Subtest basic-busy: SUCCESS (0.286s)
[155.423445] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[155.443761] [023/161] (902s left) gem_exec_fence (basic-wait)
[156.127568] Starting subtest: basic-wait
[156.130927] Starting dynamic subtest: rcs0
[156.203121] Dynamic subtest rcs0: SUCCESS (0.078s)
[156.266346] Starting dynamic subtest: bcs0
[156.269272] Dynamic subtest bcs0: SUCCESS (0.062s)
[156.331980] Starting dynamic subtest: vcs0
[156.335313] Dynamic subtest vcs0: SUCCESS (0.057s)
[156.400689] Starting dynamic subtest: vecs0
[156.403956] Dynamic subtest vecs0: SUCCESS (0.074s)
[156.406936] Subtest basic-wait: SUCCESS (0.278s)
[157.711174] [024/161] (900s left) gem_exec_fence (basic-await)
[158.390404] Starting subtest: basic-await
[158.393922] Starting dynamic subtest: rcs0
[158.746279] Dynamic subtest rcs0: SUCCESS (0.356s)
[158.808204] Starting dynamic subtest: bcs0
[159.067996] Dynamic subtest bcs0: SUCCESS (0.320s)
[159.130111] Starting dynamic subtest: vcs0
[159.381423] Dynamic subtest vcs0: SUCCESS (0.309s)
[159.443673] Starting dynamic subtest: vecs0
[159.693345] Dynamic subtest vecs0: SUCCESS (0.312s)
[159.697388] Subtest basic-await: SUCCESS (1.307s)
[161.017970] [025/161] (897s left) gem_exec_fence (nb-await)
[161.701476] Starting subtest: nb-await
[161.704583] Starting dynamic subtest: rcs0
[161.813799] Dynamic subtest rcs0: SUCCESS (0.114s)
[161.877967] Starting dynamic subtest: bcs0
[161.904729] Dynamic subtest bcs0: SUCCESS (0.090s)
[161.968253] Starting dynamic subtest: vcs0
[161.994764] Dynamic subtest vcs0: SUCCESS (0.088s)
[162.061371] Starting dynamic subtest: vecs0
[162.085335] Dynamic subtest vecs0: SUCCESS (0.088s)
[162.089460] Subtest nb-await: SUCCESS (0.388s)
[163.382703] [026/161] (895s left) gem_exec_gttfill (basic)
[163.853446] Starting subtest: basic
[202.483454] Subtest basic: SUCCESS (38.594s)
[203.082717] [027/161] (856s left) gem_exec_parallel (engines)
[203.756092] Starting subtest: engines
[203.761093] Starting dynamic subtest: basic
[210.690657] Dynamic subtest basic: SUCCESS (6.935s)
[210.761503] Starting dynamic subtest: contexts
[221.679061] Dynamic subtest contexts: SUCCESS (10.980s)
[221.749433] Starting dynamic subtest: fds
[235.282137] Dynamic subtest fds: SUCCESS (13.600s)
[235.359515] Starting dynamic subtest: userptr
[241.134308] Dynamic subtest userptr: SUCCESS (5.860s)
[241.137578] Subtest engines: SUCCESS (37.383s)
[241.809523] [028/161] (818s left) gem_exec_store (basic)
[242.287378] Starting subtest: basic
[242.454739] Subtest basic: SUCCESS (0.170s)
[243.064749] [029/161] (817s left) gem_flink_basic (bad-flink)
[243.441871] Starting subtest: bad-flink
[243.445691] Subtest bad-flink: SUCCESS (0.000s)
[243.915409] [030/161] (816s left) gem_flink_basic (bad-open)
[244.291385] Starting subtest: bad-open
[244.294765] Subtest bad-open: SUCCESS (0.000s)
[244.765869] [031/161] (816s left) gem_flink_basic (basic)
[245.142951] Starting subtest: basic
[245.146215] Subtest basic: SUCCESS (0.001s)
[245.630514] [032/161] (815s left) gem_flink_basic (double-flink)
[246.008794] Starting subtest: double-flink
[246.011917] Subtest double-flink: SUCCESS (0.000s)
[246.501760] [033/161] (815s left) gem_flink_basic (flink-lifetime)
[246.884586] Starting subtest: flink-lifetime
[246.895437] Subtest flink-lifetime: SUCCESS (0.010s)
[247.376827] [034/161] (814s left) gem_huc_copy (huc-copy)
[247.796885] Starting subtest: huc-copy
[247.814145] Subtest huc-copy: SUCCESS (0.021s)
[248.330727] [035/161] (814s left) gem_linear_blits (basic)
[249.419508] Starting subtest: basic
[249.470785] Subtest basic: SUCCESS (0.064s)
[250.061361] [036/161] (813s left) gem_mmap (basic)
[250.693095] Starting subtest: basic
[250.709295] Subtest basic: SUCCESS (0.004s)
[251.181361] [037/161] (812s left) gem_mmap_gtt (basic)
[251.564631] Starting subtest: basic
[251.567592] Subtest basic: SUCCESS (0.005s)
[252.053056] [038/161] (811s left) gem_render_linear_blits (basic)
[252.469486] Starting subtest: basic
[252.680113] Subtest basic: SUCCESS (0.213s)
[253.171000] [039/161] (811s left) gem_render_tiled_blits (basic)
[253.583460] Starting subtest: basic
[253.870482] Subtest basic: SUCCESS (0.290s)
[254.359844] [040/161] (810s left) gem_ringfill (basic-all)
[255.248755] Starting subtest: basic-all
[256.748635] Subtest basic-all: SUCCESS (1.489s)
[257.279365] [041/161] (807s left) gem_softpin (allocator-basic)
[257.692606] Starting subtest: allocator-basic
[258.400242] Subtest allocator-basic: SUCCESS (0.699s)
[258.981393] [042/161] (806s left) gem_softpin (allocator-basic-reserve)
[259.391204] Starting subtest: allocator-basic-reserve
[260.104817] Subtest allocator-basic-reserve: SUCCESS (0.704s)
[260.666877] [043/161] (805s left) gem_softpin (safe-alignment)
[261.082215] Starting subtest: safe-alignment
[261.169402] Subtest safe-alignment: SUCCESS (0.087s)
[261.694753] [044/161] (804s left) gem_sync (basic-all)
[262.176659] Starting subtest: basic-all
[267.830813] Subtest basic-all: SUCCESS (5.656s)
[268.450494] [045/161] (798s left) gem_sync (basic-each)
[268.921242] Starting subtest: basic-each
[272.524714] Subtest basic-each: SUCCESS (3.602s)
[273.138417] [046/161] (793s left) gem_tiled_blits (basic)
[274.131667] Starting subtest: basic
[274.265191] Subtest basic: SUCCESS (0.134s)
[274.810915] [047/161] (792s left) gem_tiled_fence_blits (basic)
[276.024633] Starting subtest: basic
[276.095236] Subtest basic: SUCCESS (0.080s)
[276.676139] [048/161] (791s left) gem_tiled_pread_basic
[277.711980] [049/161] (790s left) gem_wait (busy@all-engines)
[278.254204] Starting subtest: busy
[278.256973] Starting dynamic subtest: all-engines
[278.853394] Dynamic subtest all-engines: SUCCESS (0.610s)
[278.856608] Subtest busy: SUCCESS (0.612s)
[279.601596] [050/161] (788s left) gem_wait (wait@all-engines)
[280.129201] Starting subtest: wait
[280.132305] Starting dynamic subtest: all-engines
[281.240101] Dynamic subtest all-engines: SUCCESS (1.115s)
[281.244173] Subtest wait: SUCCESS (1.117s)
[281.986478] [051/161] (786s left) i915_getparams_basic (basic-eu-total)
[282.363738] Starting subtest: basic-eu-total
[282.369723] Subtest basic-eu-total: SUCCESS (0.000s)
[282.833207] [052/161] (786s left) i915_getparams_basic (basic-subslice-total)
[283.214463] Starting subtest: basic-subslice-total
[283.220254] Subtest basic-subslice-total: SUCCESS (0.000s)
[283.685877] [053/161] (785s left) i915_hangman (error-state-basic)
[284.138752] Starting subtest: error-state-basic
[284.970605] Subtest error-state-basic: SUCCESS (0.831s)
[285.490930] [054/161] (784s left) i915_pciid
[286.348268] [055/161] (784s left) kms_addfb_basic (addfb25-4-tiled)
[286.872550] Starting subtest: addfb25-4-tiled
[286.875769] Subtest addfb25-4-tiled: SUCCESS (0.002s)
[287.358822] [056/161] (783s left) kms_addfb_basic (addfb25-bad-modifier)
[287.842499] Starting subtest: addfb25-bad-modifier
[287.846486] Subtest addfb25-bad-modifier: SUCCESS (0.002s)
[288.335715] [057/161] (782s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[288.827332] Starting subtest: addfb25-framebuffer-vs-set-tiling
[288.830619] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.002s)
[289.323855] [058/161] (782s left) kms_addfb_basic (addfb25-modifier-no-flag)
[289.808813] Starting subtest: addfb25-modifier-no-flag
[289.811735] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[290.298580] [059/161] (781s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[290.787409] Starting subtest: addfb25-x-tiled-legacy
[290.790562] Subtest addfb25-x-tiled-legacy: SUCCESS (0.001s)
[291.285308] [060/161] (781s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[291.774984] Starting subtest: addfb25-x-tiled-mismatch-legacy
[291.778309] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.002s)
[292.270400] [061/161] (780s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[292.760568] Starting subtest: addfb25-yf-tiled-legacy
[292.763872] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.002s)
[293.252426] [062/161] (779s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[293.742497] Starting subtest: addfb25-y-tiled-legacy
[293.745647] Subtest addfb25-y-tiled-legacy: SUCCESS (0.001s)
[294.237507] [063/161] (779s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[294.726523] Starting subtest: addfb25-y-tiled-small-legacy
[294.729756] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.002s)
[295.231837] [064/161] (778s left) kms_addfb_basic (bad-pitch-0)
[295.631450] Starting subtest: bad-pitch-0
[295.638942] Subtest bad-pitch-0: SUCCESS (0.000s)
[296.230905] [065/161] (777s left) kms_addfb_basic (bad-pitch-1024)
[296.610750] Starting subtest: bad-pitch-1024
[296.613835] Subtest bad-pitch-1024: SUCCESS (0.000s)
[297.207342] [066/161] (777s left) kms_addfb_basic (bad-pitch-128)
[297.590924] Starting subtest: bad-pitch-128
[297.594316] Subtest bad-pitch-128: SUCCESS (0.000s)
[298.186295] [067/161] (776s left) kms_addfb_basic (bad-pitch-256)
[298.572305] Starting subtest: bad-pitch-256
[298.575163] Subtest bad-pitch-256: SUCCESS (0.000s)
[299.167402] [068/161] (776s left) kms_addfb_basic (bad-pitch-32)
[299.549912] Starting subtest: bad-pitch-32
[299.553458] Subtest bad-pitch-32: SUCCESS (0.000s)
[300.159445] [069/161] (775s left) kms_addfb_basic (bad-pitch-63)
[300.542529] Starting subtest: bad-pitch-63
[300.545732] Subtest bad-pitch-63: SUCCESS (0.000s)
[301.149869] [070/161] (775s left) kms_addfb_basic (bad-pitch-65536)
[301.535663] Starting subtest: bad-pitch-65536
[301.539437] Subtest bad-pitch-65536: SUCCESS (0.002s)
[302.130694] [071/161] (774s left) kms_addfb_basic (bad-pitch-999)
[302.512391] Starting subtest: bad-pitch-999
[302.515417] Subtest bad-pitch-999: SUCCESS (0.000s)
[303.111438] [072/161] (773s left) kms_addfb_basic (basic)
[303.490431] Starting subtest: basic
[303.493306] Subtest basic: SUCCESS (0.000s)
[304.100505] [073/161] (773s left) kms_addfb_basic (basic-x-tiled-legacy)
[304.487419] Starting subtest: basic-x-tiled-legacy
[304.491051] Subtest basic-x-tiled-legacy: SUCCESS (0.001s)
[305.094652] [074/161] (772s left) kms_addfb_basic (basic-y-tiled-legacy)
[305.478707] Starting subtest: basic-y-tiled-legacy
[305.481958] Subtest basic-y-tiled-legacy: SUCCESS (0.002s)
[306.077390] [075/161] (772s left) kms_addfb_basic (bo-too-small)
[306.559543] Starting subtest: bo-too-small
[306.562740] Subtest bo-too-small: SUCCESS (0.002s)
[307.062665] [076/161] (771s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[307.547828] Starting subtest: bo-too-small-due-to-tiling
[307.551547] Subtest bo-too-small-due-to-tiling: SUCCESS (0.002s)
[308.049740] [077/161] (770s left) kms_addfb_basic (clobberred-modifier)
[308.432389] Starting subtest: clobberred-modifier
[308.434999] Subtest clobberred-modifier: SUCCESS (0.001s)
[309.043326] [078/161] (770s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[309.429637] Starting subtest: framebuffer-vs-set-tiling
[309.432540] Subtest framebuffer-vs-set-tiling: SUCCESS (0.001s)
[310.024171] [079/161] (769s left) kms_addfb_basic (invalid-get-prop)
[310.420891] Starting subtest: invalid-get-prop
[310.425901] Subtest invalid-get-prop: SUCCESS (0.000s)
[311.042587] [080/161] (768s left) kms_addfb_basic (invalid-get-prop-any)
[311.428377] Starting subtest: invalid-get-prop-any
[311.431579] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[312.025887] [081/161] (768s left) kms_addfb_basic (invalid-set-prop)
[312.410769] Starting subtest: invalid-set-prop
[312.413906] Subtest invalid-set-prop: SUCCESS (0.000s)
[313.009292] [082/161] (767s left) kms_addfb_basic (invalid-set-prop-any)
[313.392854] Starting subtest: invalid-set-prop-any
[313.396311] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[313.998935] [083/161] (767s left) kms_addfb_basic (no-handle)
[314.376771] Starting subtest: no-handle
[314.379957] Subtest no-handle: SUCCESS (0.000s)
[314.984553] [084/161] (766s left) kms_addfb_basic (size-max)
[315.472335] Starting subtest: size-max
[315.475987] Subtest size-max: SUCCESS (0.001s)
[315.974756] [085/161] (765s left) kms_addfb_basic (small-bo)
[316.462281] Starting subtest: small-bo
[316.465152] Subtest small-bo: SUCCESS (0.000s)
[316.958571] [086/161] (765s left) kms_addfb_basic (tile-pitch-mismatch)
[317.333835] Starting subtest: tile-pitch-mismatch
[317.337478] Subtest tile-pitch-mismatch: SUCCESS (0.003s)
[317.941337] [087/161] (764s left) kms_addfb_basic (too-high)
[318.426372] Starting subtest: too-high
[318.433790] Subtest too-high: SUCCESS (0.006s)
[318.931124] [088/161] (764s left) kms_addfb_basic (too-wide)
[319.420621] Starting subtest: too-wide
[319.424218] Subtest too-wide: SUCCESS (0.000s)
[319.908491] [089/161] (763s left) kms_addfb_basic (unused-handle)
[320.289985] Starting subtest: unused-handle
[320.293211] Subtest unused-handle: SUCCESS (0.000s)
[320.890663] [090/161] (763s left) kms_addfb_basic (unused-modifier)
[321.271388] Starting subtest: unused-modifier
[321.274445] Subtest unused-modifier: SUCCESS (0.000s)
[321.886066] [091/161] (762s left) kms_addfb_basic (unused-offsets)
[322.266937] Starting subtest: unused-offsets
[322.270173] Subtest unused-offsets: SUCCESS (0.000s)
[322.874876] [092/161] (761s left) kms_addfb_basic (unused-pitches)
[323.257069] Starting subtest: unused-pitches
[323.260203] Subtest unused-pitches: SUCCESS (0.000s)
[323.861419] [093/161] (761s left) kms_busy (basic)
[324.372990] Starting subtest: basic
[324.380153] Starting dynamic subtest: flip
[326.732579] Dynamic subtest flip: SUCCESS (2.346s)
[326.816373] Starting dynamic subtest: modeset
[331.940997] Dynamic subtest modeset: SUCCESS (5.208s)
[331.944779] Subtest basic: SUCCESS (7.565s)
[333.293685] [094/161] (752s left) kms_prop_blob (basic)
[333.675889] Starting subtest: basic
[333.679297] Subtest basic: SUCCESS (0.000s)
[334.155897] [095/161] (751s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[334.789728] Starting subtest: basic-busy-flip-before-cursor-atomic
[334.795685] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[335.395859] [096/161] (750s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[336.061285] Starting subtest: basic-busy-flip-before-cursor-legacy
[336.067260] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[336.673729] [097/161] (749s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[337.294863] Starting subtest: basic-flip-after-cursor-atomic
[338.918735] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.622s)
[340.186699] [098/161] (746s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[340.850551] Starting subtest: basic-flip-after-cursor-legacy
[343.303393] Subtest basic-flip-after-cursor-legacy: SUCCESS (2.446s)
[344.313286] [099/161] (743s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[344.948775] Starting subtest: basic-flip-after-cursor-varying-size
[346.570598] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.624s)
[347.837960] [100/161] (739s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[348.461918] Starting subtest: basic-flip-before-cursor-atomic
[350.077689] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.614s)
[351.365550] [101/161] (736s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[351.989630] Starting subtest: basic-flip-before-cursor-legacy
[354.378939] Subtest basic-flip-before-cursor-legacy: SUCCESS (2.380s)
[355.415463] [102/161] (733s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[356.057633] Starting subtest: basic-flip-before-cursor-varying-size
[357.673783] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.619s)
[358.937923] [103/161] (730s left) kms_dsc (dsc-basic)
[359.432789] Starting subtest: dsc-basic
[359.446957] Subtest dsc-basic: SKIP (0.003s)
[359.915170] [104/161] (729s left) kms_flip (basic-flip-vs-dpms)
[360.400585] Starting subtest: basic-flip-vs-dpms
[360.411432] Starting dynamic subtest: A-eDP1
[364.275115] Dynamic subtest A-eDP1: SUCCESS (3.863s)
[364.357174] Starting dynamic subtest: B-eDP1
[368.036703] Dynamic subtest B-eDP1: SUCCESS (3.755s)
[368.130821] Starting dynamic subtest: C-eDP1
[371.318679] Dynamic subtest C-eDP1: SUCCESS (3.272s)
[371.325656] Subtest basic-flip-vs-dpms: SUCCESS (10.927s)
[372.612975] [105/161] (717s left) kms_flip (basic-flip-vs-modeset)
[373.104445] Starting subtest: basic-flip-vs-modeset
[373.115731] Starting dynamic subtest: A-eDP1
[377.430697] Dynamic subtest A-eDP1: SUCCESS (4.312s)
[377.514075] Starting dynamic subtest: B-eDP1
[380.451767] Dynamic subtest B-eDP1: SUCCESS (3.018s)
[380.544089] Starting dynamic subtest: C-eDP1
[383.561615] Dynamic subtest C-eDP1: SUCCESS (3.102s)
[383.569701] Subtest basic-flip-vs-modeset: SUCCESS (10.468s)
[384.802583] [106/161] (705s left) kms_flip (basic-flip-vs-wf_vblank)
[385.292246] Starting subtest: basic-flip-vs-wf_vblank
[385.308108] Starting dynamic subtest: A-eDP1
[388.384172] Dynamic subtest A-eDP1: SUCCESS (3.079s)
[388.472136] Starting dynamic subtest: B-eDP1
[391.402530] Dynamic subtest B-eDP1: SUCCESS (3.012s)
[391.492987] Starting dynamic subtest: C-eDP1
[394.396800] Dynamic subtest C-eDP1: SUCCESS (2.988s)
[394.407285] Subtest basic-flip-vs-wf_vblank: SUCCESS (9.117s)
[396.460313] [107/161] (694s left) kms_flip (basic-plain-flip)
[396.952780] Starting subtest: basic-plain-flip
[396.963766] Starting dynamic subtest: A-eDP1
[399.723613] Dynamic subtest A-eDP1: SUCCESS (2.759s)
[399.814867] Starting dynamic subtest: B-eDP1
[403.037849] Dynamic subtest B-eDP1: SUCCESS (3.308s)
[403.124601] Starting dynamic subtest: C-eDP1
[406.313712] Dynamic subtest C-eDP1: SUCCESS (3.270s)
[406.329183] Subtest basic-plain-flip: SUCCESS (9.375s)
[408.356110] [108/161] (682s left) kms_force_connector_basic (force-connector-state)
[408.768361] Starting subtest: force-connector-state
[409.633991] Subtest force-connector-state: SUCCESS (0.857s)
[411.368090] [109/161] (680s left) kms_force_connector_basic (force-edid)
[411.771658] Starting subtest: force-edid
[411.881418] Subtest force-edid: SUCCESS (0.104s)
[412.562832] [110/161] (679s left) kms_force_connector_basic (force-load-detect)
[412.965175] Starting subtest: force-load-detect
[412.971581] Subtest force-load-detect: SKIP (0.000s)
[413.562457] [111/161] (678s left) kms_force_connector_basic (prune-stale-modes)
[413.973154] Starting subtest: prune-stale-modes
[414.093587] Subtest prune-stale-modes: SUCCESS (0.124s)
[414.766629] [112/161] (677s left) kms_frontbuffer_tracking (basic)
[415.302653] Starting subtest: basic
[442.730910] Subtest basic: SUCCESS (27.438s)
[443.335491] [113/161] (649s left) kms_hdmi_inject (inject-audio)
[444.158893] Starting subtest: inject-audio
[444.708286] Subtest inject-audio: SUCCESS (0.542s)
[445.875208] [114/161] (647s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[446.364761] Starting subtest: compare-crc-sanitycheck-xr24
[446.376783] Starting dynamic subtest: pipe-A-eDP-1
[448.921665] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.545s)
[449.010070] Starting dynamic subtest: pipe-B-eDP-1
[451.218240] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.286s)
[451.310108] Starting dynamic subtest: pipe-C-eDP-1
[453.491183] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.261s)
[453.501949] Subtest compare-crc-sanitycheck-xr24: SUCCESS (7.133s)
[454.735663] [115/161] (639s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[455.235859] Starting subtest: compare-crc-sanitycheck-nv12
[455.245841] Starting dynamic subtest: pipe-A-eDP-1
[458.219189] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.972s)
[458.318925] Starting dynamic subtest: pipe-B-eDP-1
[461.697548] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.462s)
[461.791196] Starting dynamic subtest: pipe-C-eDP-1
[465.017981] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.303s)
[465.020989] Subtest compare-crc-sanitycheck-nv12: SUCCESS (9.781s)
[466.293544] [116/161] (627s left) kms_pipe_crc_basic (hang-read-crc)
[466.791499] Starting subtest: hang-read-crc
[466.799101] Starting dynamic subtest: pipe-A-eDP-1
[472.023548] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.219s)
[472.119219] Starting dynamic subtest: pipe-B-eDP-1
[477.714427] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.680s)
[477.812998] Starting dynamic subtest: pipe-C-eDP-1
[483.916865] Dynamic subtest pipe-C-eDP-1: SUCCESS (6.185s)
[483.920293] Subtest hang-read-crc: SUCCESS (17.128s)
[485.196609] [117/161] (609s left) kms_pipe_crc_basic (nonblocking-crc)
[485.702465] Starting subtest: nonblocking-crc
[485.712522] Starting dynamic subtest: pipe-A-eDP-1
[487.789903] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.076s)
[487.885631] Starting dynamic subtest: pipe-B-eDP-1
[490.325956] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.509s)
[490.416316] Starting dynamic subtest: pipe-C-eDP-1
[493.563533] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.227s)
[493.566615] Subtest nonblocking-crc: SUCCESS (7.860s)
[494.584495] [118/161] (600s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[495.087438] Starting subtest: nonblocking-crc-frame-sequence
[495.092745] Starting dynamic subtest: pipe-A-eDP-1
[497.167875] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.074s)
[497.264961] Starting dynamic subtest: pipe-B-eDP-1
[499.703791] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.518s)
[499.798405] Starting dynamic subtest: pipe-C-eDP-1
[502.931606] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.208s)
[502.934704] Subtest nonblocking-crc-frame-sequence: SUCCESS (7.848s)
[503.943454] [119/161] (591s left) kms_pipe_crc_basic (read-crc)
[504.445045] Starting subtest: read-crc
[504.456312] Starting dynamic subtest: pipe-A-eDP-1
[506.517336] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.061s)
[506.614311] Starting dynamic subtest: pipe-B-eDP-1
[508.997945] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.461s)
[509.093087] Starting dynamic subtest: pipe-C-eDP-1
[511.342921] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.327s)
[511.346126] Subtest read-crc: SUCCESS (6.898s)
[512.626798] [120/161] (583s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[513.120798] Starting subtest: read-crc-frame-sequence
[513.132543] Starting dynamic subtest: pipe-A-eDP-1
[515.170497] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.034s)
[515.269156] Starting dynamic subtest: pipe-B-eDP-1
[517.640547] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.452s)
[517.734244] Starting dynamic subtest: pipe-C-eDP-1
[519.984507] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.327s)
[519.987699] Subtest read-crc-frame-sequence: SUCCESS (6.865s)
[521.275507] [121/161] (574s left) kms_pm_backlight (basic-brightness)
[521.783643] Starting subtest: basic-brightness
[522.284451] Starting dynamic subtest: eDP-1
[522.755295] Dynamic subtest eDP-1: SUCCESS (0.491s)
[522.768788] Subtest basic-brightness: SUCCESS (0.983s)
[524.068362] [122/161] (572s left) kms_pm_rpm (basic-pci-d3-state)
[526.590228] Starting subtest: basic-pci-d3-state
[528.553150] Subtest basic-pci-d3-state: SUCCESS (1.958s)
[529.132325] [123/161] (567s left) kms_pm_rpm (basic-rte)
[529.516619] Starting subtest: basic-rte
[533.488423] Subtest basic-rte: SUCCESS (3.969s)
[536.275164] [124/161] (561s left) kms_psr (psr-primary-page-flip)
[536.775631] Starting subtest: psr-primary-page-flip
[536.778830] Starting dynamic subtest: eDP-1
[539.521944] Dynamic subtest eDP-1: SUCCESS (2.743s)
[539.524878] Subtest psr-primary-page-flip: SUCCESS (2.755s)
[540.833298] [125/161] (556s left) kms_psr (psr-cursor-plane-move)
[541.335885] Starting subtest: psr-cursor-plane-move
[541.339504] Starting dynamic subtest: eDP-1
[543.861372] Dynamic subtest eDP-1: SUCCESS (2.518s)
[543.864378] Subtest psr-cursor-plane-move: SUCCESS (2.530s)
[545.177153] [126/161] (553s left) kms_psr (psr-sprite-plane-onoff)
[545.682123] Starting subtest: psr-sprite-plane-onoff
[545.692688] Starting dynamic subtest: eDP-1
[548.425284] Dynamic subtest eDP-1: SUCCESS (2.729s)
[548.428181] Subtest psr-sprite-plane-onoff: SUCCESS (2.741s)
[549.726670] [127/161] (548s left) kms_psr (psr-primary-mmap-gtt)
[550.230645] Starting subtest: psr-primary-mmap-gtt
[550.233469] Starting dynamic subtest: eDP-1
[553.092884] Dynamic subtest eDP-1: SUCCESS (2.856s)
[553.096652] Subtest psr-primary-mmap-gtt: SUCCESS (2.868s)
[554.376315] [128/161] (544s left) kms_setmode (basic-clone-single-crtc)
[554.768249] Starting subtest: basic-clone-single-crtc
[554.776939] Subtest basic-clone-single-crtc: SKIP (0.001s)
[555.261122] [129/161] (544s left) i915_pm_rps (basic-api)
[555.695291] Starting subtest: basic-api
[555.701994] Subtest basic-api: SKIP (0.003s)
[556.208643] [130/161] (543s left) prime_self_import (basic-llseek-bad)
[556.583704] Starting subtest: basic-llseek-bad
[556.590341] Subtest basic-llseek-bad: SUCCESS (0.014s)
[557.107833] [131/161] (543s left) prime_self_import (basic-llseek-size)
[557.484186] Starting subtest: basic-llseek-size
[557.498885] Subtest basic-llseek-size: SUCCESS (0.022s)
[558.012532] [132/161] (542s left) prime_self_import (basic-with_fd_dup)
[558.389157] Starting subtest: basic-with_fd_dup
[558.411073] Subtest basic-with_fd_dup: SUCCESS (0.021s)
[558.921598] [133/161] (542s left) prime_self_import (basic-with_one_bo)
[559.297833] Starting subtest: basic-with_one_bo
[559.322880] Subtest basic-with_one_bo: SUCCESS (0.025s)
[559.846051] [134/161] (541s left) prime_self_import (basic-with_one_bo_two_files)
[560.224179] Starting subtest: basic-with_one_bo_two_files
[560.239436] Subtest basic-with_one_bo_two_files: SUCCESS (0.019s)
[560.748917] [135/161] (541s left) prime_self_import (basic-with_two_bos)
[561.117261] Starting subtest: basic-with_two_bos
[561.139225] Subtest basic-with_two_bos: SUCCESS (0.022s)
[561.655538] [136/161] (540s left) prime_vgem (basic-fence-flip)
[562.112504] Starting subtest: basic-fence-flip
[563.581079] Subtest basic-fence-flip: SUCCESS (1.469s)
[564.176551] [137/161] (538s left) prime_vgem (basic-fence-mmap)
[564.630831] Starting subtest: basic-fence-mmap
[564.831965] Subtest basic-fence-mmap: SUCCESS (0.198s)
[565.344402] [138/161] (537s left) prime_vgem (basic-fence-read)
[565.804975] Starting subtest: basic-fence-read
[565.866821] Subtest basic-fence-read: SKIP (0.059s)
[566.397180] [139/161] (537s left) prime_vgem (basic-gtt)
[566.857086] Starting subtest: basic-gtt
[566.940272] Subtest basic-gtt: SUCCESS (0.090s)
[567.454502] [140/161] (536s left) prime_vgem (basic-read)
[567.909400] Starting subtest: basic-read
[567.967201] Subtest basic-read: SKIP (0.055s)
[568.492125] [141/161] (535s left) prime_vgem (basic-write)
[568.951728] Starting subtest: basic-write
[569.003688] Subtest basic-write: SKIP (0.056s)
[569.537377] [142/161] (535s left) vgem_basic (setversion)
[569.950751] Starting subtest: setversion
[569.960576] Subtest setversion: SUCCESS (0.000s)
[570.433395] [143/161] (534s left) vgem_basic (create)
[570.844671] Starting subtest: create
[570.847963] Subtest create: SUCCESS (0.002s)
[571.327058] [144/161] (534s left) vgem_basic (debugfs)
[571.739198] Starting subtest: debugfs
[571.742638] Subtest debugfs: SUCCESS (0.002s)
[572.230306] [145/161] (533s left) vgem_basic (dmabuf-export)
[572.648056] Starting subtest: dmabuf-export
[572.663578] Subtest dmabuf-export: SUCCESS (0.020s)
[573.167756] [146/161] (533s left) vgem_basic (dmabuf-fence)
[573.581723] Starting subtest: dmabuf-fence
[573.586597] Subtest dmabuf-fence: SUCCESS (0.003s)
[574.065109] [147/161] (532s left) vgem_basic (dmabuf-fence-before)
[574.478316] Starting subtest: dmabuf-fence-before
[574.481541] Subtest dmabuf-fence-before: SUCCESS (0.003s)
[574.968148] [148/161] (532s left) vgem_basic (dmabuf-mmap)
[575.383202] Starting subtest: dmabuf-mmap
[575.477221] Subtest dmabuf-mmap: SUCCESS (0.099s)
[575.950123] [149/161] (531s left) vgem_basic (mmap)
[576.359062] Starting subtest: mmap
[576.424539] Subtest mmap: SUCCESS (0.071s)
[576.907473] [150/161] (530s left) vgem_basic (second-client)
[577.321733] Starting subtest: second-client
[577.364954] Subtest second-client: SUCCESS (0.047s)
[577.838433] [151/161] (530s left) vgem_basic (sysfs)
[578.245981] Starting subtest: sysfs
[578.254985] Subtest sysfs: SUCCESS (0.003s)
[578.729929] [152/161] (529s left) core_hotunplug (unbind-rebind)
[579.432648] Starting subtest: unbind-rebind
[588.689159] Subtest unbind-rebind: SUCCESS (9.257s)
[589.108132] [153/161] (519s left) vgem_basic (unload)
[589.493470] Starting subtest: unload
[619.248212] Subtest unload: SUCCESS (29.745s)
[619.933237] [154/161] (489s left) i915_module_load (reload)
[620.310810] Starting subtest: reload
[635.040379] Subtest reload: SUCCESS (14.726s)
[635.564758] [155/161] (474s left) gem_lmem_swapping (basic)
[636.160548] Subtest basic: SKIP (0.000s)
[636.623215] [156/161] (473s left) gem_lmem_swapping (parallel-random-engines)
[637.056107] Subtest parallel-random-engines: SKIP (0.000s)
[637.503637] [157/161] (473s left) gem_lmem_swapping (random-engines)
[637.931574] Subtest random-engines: SKIP (0.000s)
[638.386497] [158/161] (472s left) gem_lmem_swapping (verify-random)
[638.812590] Subtest verify-random: SKIP (0.000s)
[639.259050] [159/161] (472s left) i915_pm_rpm (module-reload)
[643.174539] Starting subtest: module-reload
[678.491589] Subtest module-reload: SUCCESS (35.315s)
[678.752703] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[678.765943] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[678.788124] [160/161] (433s left) i915_selftest (live)
[679.198105] Starting subtest: live
[679.209109] Starting dynamic subtest: sanitycheck
[689.794331] Dynamic subtest sanitycheck: SUCCESS (10.581s)
[689.861492] Starting dynamic subtest: uncore
[700.207356] Dynamic subtest uncore: SUCCESS (10.404s)
[700.276422] Starting dynamic subtest: workarounds
[713.320781] Dynamic subtest workarounds: SUCCESS (13.102s)
[713.391893] Starting dynamic subtest: gt_engines
[724.370451] Dynamic subtest gt_engines: SUCCESS (11.043s)
[724.439665] Starting dynamic subtest: gt_timelines
[770.388338] Dynamic subtest gt_timelines: SUCCESS (46.008s)
[770.454408] Starting dynamic subtest: gt_contexts
[780.896605] Dynamic subtest gt_contexts: SUCCESS (10.498s)
[780.968356] Starting dynamic subtest: gt_lrc
[794.957204] Dynamic subtest gt_lrc: SUCCESS (14.052s)
[795.023820] Starting dynamic subtest: gt_mocs
[806.577744] Dynamic subtest gt_mocs: SUCCESS (11.608s)
[806.648361] Starting dynamic subtest: gt_pm
[819.206440] Dynamic subtest gt_pm: SUCCESS (12.623s)
[819.296477] Starting dynamic subtest: gt_heartbeat
[829.793615] Dynamic subtest gt_heartbeat: SUCCESS (10.570s)
[829.861342] Starting dynamic subtest: gt_tlb
[841.427139] Dynamic subtest gt_tlb: SUCCESS (11.620s)
[841.493275] Starting dynamic subtest: requests
[862.304452] Dynamic subtest requests: SUCCESS (20.830s)
[862.371734] Starting dynamic subtest: migrate
[877.001877] Dynamic subtest migrate: SUCCESS (14.716s)
[877.070146] Starting dynamic subtest: active
[887.530618] Dynamic subtest active: SUCCESS (10.520s)
[887.598761] Starting dynamic subtest: objects
[902.203235] Dynamic subtest objects: SUCCESS (14.663s)
[902.271710] Starting dynamic subtest: mman
[923.007337] Dynamic subtest mman: SUCCESS (20.795s)
[923.078188] Starting dynamic subtest: dmabuf
[933.713688] Dynamic subtest dmabuf: SUCCESS (10.697s)
[933.782326] Starting dynamic subtest: vma
[944.423454] Dynamic subtest vma: SUCCESS (10.707s)
[944.518717] Starting dynamic subtest: coherency
[976.077821] Dynamic subtest coherency: SUCCESS (31.634s)
[976.144088] Starting dynamic subtest: gtt
[994.921840] Dynamic subtest gtt: SUCCESS (18.834s)
[995.017271] Starting dynamic subtest: gem
[1006.598615] Dynamic subtest gem: SUCCESS (11.666s)
[1006.665969] Starting dynamic subtest: evict
[1025.289808] Dynamic subtest evict: SUCCESS (18.682s)
[1025.357265] Starting dynamic subtest: hugepages
[1042.810663] Dynamic subtest hugepages: SUCCESS (17.512s)
[1042.879822] Starting dynamic subtest: gem_contexts
[1084.362366] Dynamic subtest gem_contexts: SUCCESS (41.542s)
[1084.426243] Starting dynamic subtest: client
[1095.131599] Dynamic subtest client: SUCCESS (10.757s)
[1095.198704] Starting dynamic subtest: gem_migrate
[1102.584782] Abort requested by sudo IGT_PING_HOSTNAME=10.211.124.1 IGT_CI_META_TEST=yes IGT_REBOOT_ON_FATAL_ERROR=yes LD_LIBRARY_PATH=/opt/igt/lib:/op [1162] via Terminated, terminating children
[1106.790226] Aborting: Kernel badly tainted (0x240, 0x200) (check dmesg for details):
	TAINT_WARN: WARN_ON has happened.

[1106.965409] [FACT i915_selftest (live)] new: kernel.is_tainted.taint_warn: true
[1106.968427] [FACT i915_selftest (live)] new: kernel.kmod_is_loaded.i915: true
[1106.980376] Closing watchdogs
Done.