[108.273654] Initializing watchdogs
[108.274426]   /dev/watchdog0
[108.444244] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:46d3 Intel Alderlake_n (Gen12) 
[108.490574] [001/161] (960s left) i915_module_load (load)
[109.681442] Starting subtest: load
[121.034577] Subtest load: SUCCESS (11.366s)
[121.398307] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[121.414663] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[121.435794] [002/161] (947s left) core_auth (basic-auth)
[122.042128] Starting subtest: basic-auth
[122.053971] Subtest basic-auth: SUCCESS (0.010s)
[122.599786] [003/161] (947s left) debugfs_test (read_all_entries)
[123.045131] Starting subtest: read_all_entries
[124.548039] Subtest read_all_entries: SUCCESS (1.503s)
[125.092676] [004/161] (945s left) debugfs_test (basic-hwmon)
[125.515219] Starting subtest: basic-hwmon
[125.520654] Subtest basic-hwmon: SKIP (0.000s)
[126.046792] [005/161] (944s left) debugfs_test (sysfs)
[126.473912] Starting subtest: sysfs
[126.948716] Subtest sysfs: SUCCESS (0.476s)
[127.492945] [006/161] (943s left) fbdev (eof)
[127.884330] Starting subtest: eof
[127.892645] Subtest eof: SUCCESS (0.004s)
[128.327216] [007/161] (942s left) fbdev (info)
[128.701548] Starting subtest: info
[128.704613] Subtest info: SUCCESS (0.000s)
[129.175525] [008/161] (942s left) fbdev (nullptr)
[129.581819] Starting subtest: nullptr
[129.584903] Subtest nullptr: SUCCESS (0.000s)
[130.022285] [009/161] (941s left) fbdev (read)
[130.422791] Starting subtest: read
[144.083000] Subtest read: SUCCESS (13.650s)
[144.533308] [010/161] (927s left) fbdev (write)
[144.937734] Starting subtest: write
[152.066932] Subtest write: SUCCESS (7.122s)
[152.536474] [011/161] (919s left) gem_basic (bad-close)
[152.922936] Starting subtest: bad-close
[152.929176] Subtest bad-close: SUCCESS (0.000s)
[153.353239] [012/161] (919s left) gem_basic (create-close)
[153.736323] Starting subtest: create-close
[153.742365] Subtest create-close: SUCCESS (0.001s)
[154.189598] [013/161] (918s left) gem_basic (create-fd-close)
[154.570617] Starting subtest: create-fd-close
[154.577265] Subtest create-fd-close: SUCCESS (0.001s)
[155.015367] [014/161] (918s left) gem_busy (busy@all-engines)
[155.501845] Starting subtest: busy
[155.505251] Starting dynamic subtest: all-engines
[155.830130] Dynamic subtest all-engines: SUCCESS (0.331s)
[155.833685] Subtest busy: SUCCESS (0.334s)
[156.721303] [015/161] (917s left) gem_close_race (basic-process)
[157.213279] Starting subtest: basic-process
[157.369747] Subtest basic-process: SUCCESS (0.157s)
[157.940997] [016/161] (916s left) gem_close_race (basic-threads)
[158.430970] Starting subtest: basic-threads
[159.899254] Subtest basic-threads: SUCCESS (1.473s)
[160.475596] [017/161] (914s left) gem_ctx_create (basic)
[160.948256] Starting subtest: basic
[160.951363] Subtest basic: SUCCESS (0.000s)
[161.525550] [018/161] (913s left) gem_ctx_create (basic-files)
[162.000747] Starting subtest: basic-files
[164.121490] Subtest basic-files: SUCCESS (2.124s)
[164.741752] [019/161] (910s left) gem_ctx_exec (basic)
[165.138225] Starting subtest: basic
[165.162096] Subtest basic: SUCCESS (0.024s)
[165.646206] [020/161] (909s left) gem_exec_basic (basic)
[166.087433] Starting subtest: basic
[166.090631] Starting dynamic subtest: rcs0-smem
[166.097752] Dynamic subtest rcs0-smem: SUCCESS (0.009s)
[166.156572] Starting dynamic subtest: bcs0-smem
[166.159602] Dynamic subtest bcs0-smem: SUCCESS (0.004s)
[166.216205] Starting dynamic subtest: vcs0-smem
[166.219130] Dynamic subtest vcs0-smem: SUCCESS (0.003s)
[166.280115] Starting dynamic subtest: vecs0-smem
[166.283218] Dynamic subtest vecs0-smem: SUCCESS (0.004s)
[166.285935] Subtest basic: SUCCESS (0.029s)
[166.697483] [021/161] (909s left) gem_exec_create (basic)
[167.177738] Starting subtest: basic
[167.180895] Starting dynamic subtest: smem
[169.409311] Dynamic subtest smem: SUCCESS (2.234s)
[169.413558] Subtest basic: SUCCESS (2.236s)
[169.987586] [022/161] (906s left) gem_exec_fence (basic-busy)
[170.675401] Starting subtest: basic-busy
[170.678656] Starting dynamic subtest: rcs0
[170.749149] Dynamic subtest rcs0: SUCCESS (0.076s)
[170.817732] Starting dynamic subtest: bcs0
[170.823831] Dynamic subtest bcs0: SUCCESS (0.068s)
[170.882791] Starting dynamic subtest: vcs0
[170.889942] Dynamic subtest vcs0: SUCCESS (0.064s)
[170.952545] Starting dynamic subtest: vecs0
[170.961418] Dynamic subtest vecs0: SUCCESS (0.063s)
[170.964158] Subtest basic-busy: SUCCESS (0.279s)
[172.454203] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[172.477188] [023/161] (904s left) gem_exec_fence (basic-wait)
[173.155628] Starting subtest: basic-wait
[173.158905] Starting dynamic subtest: rcs0
[173.256319] Dynamic subtest rcs0: SUCCESS (0.100s)
[173.317225] Starting dynamic subtest: bcs0
[173.320914] Dynamic subtest bcs0: SUCCESS (0.063s)
[173.382988] Starting dynamic subtest: vcs0
[173.386181] Dynamic subtest vcs0: SUCCESS (0.058s)
[173.455169] Starting dynamic subtest: vecs0
[173.458184] Dynamic subtest vecs0: SUCCESS (0.060s)
[173.461169] Subtest basic-wait: SUCCESS (0.289s)
[174.755450] [024/161] (902s left) gem_exec_fence (basic-await)
[175.434281] Starting subtest: basic-await
[175.438451] Starting dynamic subtest: rcs0
[175.782257] Dynamic subtest rcs0: SUCCESS (0.347s)
[175.842814] Starting dynamic subtest: bcs0
[176.099215] Dynamic subtest bcs0: SUCCESS (0.315s)
[176.162434] Starting dynamic subtest: vcs0
[176.412996] Dynamic subtest vcs0: SUCCESS (0.310s)
[176.473379] Starting dynamic subtest: vecs0
[176.738375] Dynamic subtest vecs0: SUCCESS (0.322s)
[176.741956] Subtest basic-await: SUCCESS (1.303s)
[178.071442] [025/161] (899s left) gem_exec_fence (nb-await)
[178.754828] Starting subtest: nb-await
[178.758140] Starting dynamic subtest: rcs0
[178.864382] Dynamic subtest rcs0: SUCCESS (0.111s)
[178.926217] Starting dynamic subtest: bcs0
[178.954164] Dynamic subtest bcs0: SUCCESS (0.088s)
[179.015647] Starting dynamic subtest: vcs0
[179.044696] Dynamic subtest vcs0: SUCCESS (0.088s)
[179.106213] Starting dynamic subtest: vecs0
[179.133817] Dynamic subtest vecs0: SUCCESS (0.087s)
[179.137221] Subtest nb-await: SUCCESS (0.382s)
[180.405607] [026/161] (897s left) gem_exec_gttfill (basic)
[180.882391] Starting subtest: basic
[219.415448] Subtest basic: SUCCESS (38.497s)
[220.022062] [027/161] (858s left) gem_exec_parallel (engines)
[220.686126] Starting subtest: engines
[220.689152] Starting dynamic subtest: basic
[227.453641] Dynamic subtest basic: SUCCESS (6.765s)
[227.520271] Starting dynamic subtest: contexts
[237.746339] Dynamic subtest contexts: SUCCESS (10.281s)
[237.813103] Starting dynamic subtest: fds
[250.598909] Dynamic subtest fds: SUCCESS (12.850s)
[250.667882] Starting dynamic subtest: userptr
[256.411331] Dynamic subtest userptr: SUCCESS (5.819s)
[256.414295] Subtest engines: SUCCESS (35.725s)
[257.068633] [028/161] (821s left) gem_exec_store (basic)
[257.544822] Starting subtest: basic
[257.691145] Subtest basic: SUCCESS (0.150s)
[258.302804] [029/161] (820s left) gem_flink_basic (bad-flink)
[258.682339] Starting subtest: bad-flink
[258.685716] Subtest bad-flink: SUCCESS (0.000s)
[259.148575] [030/161] (820s left) gem_flink_basic (bad-open)
[259.528861] Starting subtest: bad-open
[259.532717] Subtest bad-open: SUCCESS (0.000s)
[259.995577] [031/161] (819s left) gem_flink_basic (basic)
[260.378387] Starting subtest: basic
[260.381616] Subtest basic: SUCCESS (0.001s)
[260.863976] [032/161] (819s left) gem_flink_basic (double-flink)
[261.246655] Starting subtest: double-flink
[261.249710] Subtest double-flink: SUCCESS (0.001s)
[261.732745] [033/161] (818s left) gem_flink_basic (flink-lifetime)
[262.115711] Starting subtest: flink-lifetime
[262.126928] Subtest flink-lifetime: SUCCESS (0.010s)
[262.625412] [034/161] (818s left) gem_huc_copy (huc-copy)
[263.053307] Starting subtest: huc-copy
[263.072727] Subtest huc-copy: SUCCESS (0.020s)
[263.588464] [035/161] (817s left) gem_linear_blits (basic)
[264.669863] Starting subtest: basic
[264.706949] Subtest basic: SUCCESS (0.049s)
[265.288418] [036/161] (816s left) gem_mmap (basic)
[265.875304] Starting subtest: basic
[265.889728] Subtest basic: SUCCESS (0.003s)
[266.370360] [037/161] (815s left) gem_mmap_gtt (basic)
[266.756561] Starting subtest: basic
[266.759468] Subtest basic: SUCCESS (0.005s)
[267.247897] [038/161] (815s left) gem_render_linear_blits (basic)
[267.697331] Starting subtest: basic
[267.930614] Subtest basic: SUCCESS (0.232s)
[268.409435] [039/161] (814s left) gem_render_tiled_blits (basic)
[268.819408] Starting subtest: basic
[269.124149] Subtest basic: SUCCESS (0.304s)
[269.601163] [040/161] (813s left) gem_ringfill (basic-all)
[270.490798] Starting subtest: basic-all
[271.962079] Subtest basic-all: SUCCESS (1.464s)
[272.435600] [041/161] (811s left) gem_softpin (allocator-basic)
[272.853226] Starting subtest: allocator-basic
[273.543822] Subtest allocator-basic: SUCCESS (0.685s)
[274.094093] [042/161] (809s left) gem_softpin (allocator-basic-reserve)
[274.509074] Starting subtest: allocator-basic-reserve
[275.204108] Subtest allocator-basic-reserve: SUCCESS (0.689s)
[275.774935] [043/161] (808s left) gem_softpin (safe-alignment)
[276.195490] Starting subtest: safe-alignment
[276.278777] Subtest safe-alignment: SUCCESS (0.083s)
[276.783387] [044/161] (807s left) gem_sync (basic-all)
[277.265367] Starting subtest: basic-all
[282.525109] Subtest basic-all: SUCCESS (5.260s)
[283.138942] [045/161] (801s left) gem_sync (basic-each)
[283.611237] Starting subtest: basic-each
[287.219794] Subtest basic-each: SUCCESS (3.608s)
[287.834914] [046/161] (797s left) gem_tiled_blits (basic)
[288.799133] Starting subtest: basic
[288.910447] Subtest basic: SUCCESS (0.124s)
[289.460307] [047/161] (796s left) gem_tiled_fence_blits (basic)
[290.647875] Starting subtest: basic
[290.717402] Subtest basic: SUCCESS (0.077s)
[291.298225] [048/161] (794s left) gem_tiled_pread_basic
[292.334532] [049/161] (794s left) gem_wait (busy@all-engines)
[292.883105] Starting subtest: busy
[292.886117] Starting dynamic subtest: all-engines
[293.468828] Dynamic subtest all-engines: SUCCESS (0.596s)
[293.472290] Subtest busy: SUCCESS (0.598s)
[294.223505] [050/161] (792s left) gem_wait (wait@all-engines)
[294.754738] Starting subtest: wait
[294.758267] Starting dynamic subtest: all-engines
[295.857180] Dynamic subtest all-engines: SUCCESS (1.113s)
[295.860358] Subtest wait: SUCCESS (1.115s)
[296.595650] [051/161] (790s left) i915_getparams_basic (basic-eu-total)
[296.969798] Starting subtest: basic-eu-total
[296.975696] Subtest basic-eu-total: SUCCESS (0.000s)
[297.452839] [052/161] (790s left) i915_getparams_basic (basic-subslice-total)
[297.834638] Starting subtest: basic-subslice-total
[297.840682] Subtest basic-subslice-total: SUCCESS (0.000s)
[298.307447] [053/161] (789s left) i915_hangman (error-state-basic)
[298.775926] Starting subtest: error-state-basic
[299.630519] Subtest error-state-basic: SUCCESS (0.855s)
[300.134207] [054/161] (788s left) i915_pciid
[301.000294] [055/161] (787s left) kms_addfb_basic (addfb25-4-tiled)
[301.521412] Starting subtest: addfb25-4-tiled
[301.524552] Subtest addfb25-4-tiled: SUCCESS (0.002s)
[302.024501] [056/161] (787s left) kms_addfb_basic (addfb25-bad-modifier)
[302.517634] Starting subtest: addfb25-bad-modifier
[302.521705] Subtest addfb25-bad-modifier: SUCCESS (0.002s)
[303.026670] [057/161] (786s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[303.517709] Starting subtest: addfb25-framebuffer-vs-set-tiling
[303.521348] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.002s)
[304.008376] [058/161] (786s left) kms_addfb_basic (addfb25-modifier-no-flag)
[304.495610] Starting subtest: addfb25-modifier-no-flag
[304.498432] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[304.995994] [059/161] (785s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[305.487763] Starting subtest: addfb25-x-tiled-legacy
[305.491321] Subtest addfb25-x-tiled-legacy: SUCCESS (0.001s)
[305.966228] [060/161] (784s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[306.457966] Starting subtest: addfb25-x-tiled-mismatch-legacy
[306.461745] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.002s)
[306.956992] [061/161] (784s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[307.464504] Starting subtest: addfb25-yf-tiled-legacy
[307.467937] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.002s)
[308.002375] [062/161] (783s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[308.618860] Starting subtest: addfb25-y-tiled-legacy
[308.624990] Subtest addfb25-y-tiled-legacy: SUCCESS (0.001s)
[309.276133] [063/161] (782s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[309.930381] Starting subtest: addfb25-y-tiled-small-legacy
[309.936390] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.003s)
[310.460755] [064/161] (782s left) kms_addfb_basic (bad-pitch-0)
[310.875722] Starting subtest: bad-pitch-0
[310.878460] Subtest bad-pitch-0: SUCCESS (0.000s)
[311.523048] [065/161] (781s left) kms_addfb_basic (bad-pitch-1024)
[311.936550] Starting subtest: bad-pitch-1024
[311.941703] Subtest bad-pitch-1024: SUCCESS (0.000s)
[312.734551] [066/161] (780s left) kms_addfb_basic (bad-pitch-128)
[313.125126] Starting subtest: bad-pitch-128
[313.131840] Subtest bad-pitch-128: SUCCESS (0.000s)
[313.715798] [067/161] (780s left) kms_addfb_basic (bad-pitch-256)
[314.105579] Starting subtest: bad-pitch-256
[314.108712] Subtest bad-pitch-256: SUCCESS (0.000s)
[314.697437] [068/161] (779s left) kms_addfb_basic (bad-pitch-32)
[315.087655] Starting subtest: bad-pitch-32
[315.091065] Subtest bad-pitch-32: SUCCESS (0.000s)
[315.671840] [069/161] (778s left) kms_addfb_basic (bad-pitch-63)
[316.061136] Starting subtest: bad-pitch-63
[316.064156] Subtest bad-pitch-63: SUCCESS (0.000s)
[316.655724] [070/161] (778s left) kms_addfb_basic (bad-pitch-65536)
[317.046675] Starting subtest: bad-pitch-65536
[317.049886] Subtest bad-pitch-65536: SUCCESS (0.002s)
[317.654146] [071/161] (777s left) kms_addfb_basic (bad-pitch-999)
[318.035715] Starting subtest: bad-pitch-999
[318.038951] Subtest bad-pitch-999: SUCCESS (0.000s)
[318.639751] [072/161] (777s left) kms_addfb_basic (basic)
[319.024422] Starting subtest: basic
[319.027333] Subtest basic: SUCCESS (0.000s)
[319.615732] [073/161] (776s left) kms_addfb_basic (basic-x-tiled-legacy)
[320.004709] Starting subtest: basic-x-tiled-legacy
[320.007722] Subtest basic-x-tiled-legacy: SUCCESS (0.001s)
[320.593499] [074/161] (775s left) kms_addfb_basic (basic-y-tiled-legacy)
[320.982176] Starting subtest: basic-y-tiled-legacy
[320.985406] Subtest basic-y-tiled-legacy: SUCCESS (0.002s)
[321.577955] [075/161] (775s left) kms_addfb_basic (bo-too-small)
[322.065488] Starting subtest: bo-too-small
[322.068799] Subtest bo-too-small: SUCCESS (0.002s)
[322.561900] [076/161] (774s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[323.048194] Starting subtest: bo-too-small-due-to-tiling
[323.051225] Subtest bo-too-small-due-to-tiling: SUCCESS (0.002s)
[323.542925] [077/161] (774s left) kms_addfb_basic (clobberred-modifier)
[323.921730] Starting subtest: clobberred-modifier
[323.925776] Subtest clobberred-modifier: SUCCESS (0.001s)
[324.526688] [078/161] (773s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[324.913809] Starting subtest: framebuffer-vs-set-tiling
[324.916719] Subtest framebuffer-vs-set-tiling: SUCCESS (0.001s)
[325.513544] [079/161] (772s left) kms_addfb_basic (invalid-get-prop)
[325.895935] Starting subtest: invalid-get-prop
[325.898792] Subtest invalid-get-prop: SUCCESS (0.000s)
[326.512614] [080/161] (772s left) kms_addfb_basic (invalid-get-prop-any)
[326.896551] Starting subtest: invalid-get-prop-any
[326.900258] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[327.514969] [081/161] (771s left) kms_addfb_basic (invalid-set-prop)
[327.895599] Starting subtest: invalid-set-prop
[327.898411] Subtest invalid-set-prop: SUCCESS (0.000s)
[328.496919] [082/161] (771s left) kms_addfb_basic (invalid-set-prop-any)
[328.880648] Starting subtest: invalid-set-prop-any
[328.883543] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[329.494621] [083/161] (770s left) kms_addfb_basic (no-handle)
[329.880754] Starting subtest: no-handle
[329.884684] Subtest no-handle: SUCCESS (0.000s)
[330.476621] [084/161] (769s left) kms_addfb_basic (size-max)
[330.973137] Starting subtest: size-max
[330.976124] Subtest size-max: SUCCESS (0.001s)
[331.477334] [085/161] (769s left) kms_addfb_basic (small-bo)
[331.966682] Starting subtest: small-bo
[331.969417] Subtest small-bo: SUCCESS (0.000s)
[332.476473] [086/161] (768s left) kms_addfb_basic (tile-pitch-mismatch)
[332.866586] Starting subtest: tile-pitch-mismatch
[332.869487] Subtest tile-pitch-mismatch: SUCCESS (0.002s)
[333.472878] [087/161] (768s left) kms_addfb_basic (too-high)
[333.965328] Starting subtest: too-high
[333.973577] Subtest too-high: SUCCESS (0.006s)
[334.464695] [088/161] (767s left) kms_addfb_basic (too-wide)
[334.959772] Starting subtest: too-wide
[334.963839] Subtest too-wide: SUCCESS (0.000s)
[335.443355] [089/161] (766s left) kms_addfb_basic (unused-handle)
[335.833716] Starting subtest: unused-handle
[335.837221] Subtest unused-handle: SUCCESS (0.000s)
[336.426396] [090/161] (766s left) kms_addfb_basic (unused-modifier)
[336.815772] Starting subtest: unused-modifier
[336.818602] Subtest unused-modifier: SUCCESS (0.000s)
[337.413422] [091/161] (765s left) kms_addfb_basic (unused-offsets)
[337.798054] Starting subtest: unused-offsets
[337.800829] Subtest unused-offsets: SUCCESS (0.000s)
[338.399746] [092/161] (765s left) kms_addfb_basic (unused-pitches)
[338.787067] Starting subtest: unused-pitches
[338.790392] Subtest unused-pitches: SUCCESS (0.000s)
[339.375438] [093/161] (764s left) kms_busy (basic)
[339.893118] Starting subtest: basic
[339.898486] Starting dynamic subtest: flip
[342.240083] Dynamic subtest flip: SUCCESS (2.336s)
[342.332172] Starting dynamic subtest: modeset
[348.040450] Dynamic subtest modeset: SUCCESS (5.803s)
[348.043347] Subtest basic: SUCCESS (8.150s)
[349.371827] [094/161] (754s left) kms_prop_blob (basic)
[349.758088] Starting subtest: basic
[349.760961] Subtest basic: SUCCESS (0.000s)
[350.238602] [095/161] (754s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[350.880077] Starting subtest: basic-busy-flip-before-cursor-atomic
[350.887194] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[351.494964] [096/161] (753s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[352.123833] Starting subtest: basic-busy-flip-before-cursor-legacy
[352.129332] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[352.739854] [097/161] (752s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[353.361729] Starting subtest: basic-flip-after-cursor-atomic
[354.985353] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.622s)
[356.263780] [098/161] (749s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[356.894677] Starting subtest: basic-flip-after-cursor-legacy
[358.513260] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.620s)
[359.803411] [099/161] (746s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[360.439074] Starting subtest: basic-flip-after-cursor-varying-size
[362.820251] Subtest basic-flip-after-cursor-varying-size: SUCCESS (2.374s)
[363.894411] [100/161] (742s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[364.520890] Starting subtest: basic-flip-before-cursor-atomic
[366.138881] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.617s)
[367.426365] [101/161] (739s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[368.055379] Starting subtest: basic-flip-before-cursor-legacy
[369.669645] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.612s)
[370.958944] [102/161] (736s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[371.604783] Starting subtest: basic-flip-before-cursor-varying-size
[374.077250] Subtest basic-flip-before-cursor-varying-size: SUCCESS (2.466s)
[375.125483] [103/161] (732s left) kms_dsc (dsc-basic)
[375.617939] Starting subtest: dsc-basic
[375.632655] Subtest dsc-basic: SKIP (0.003s)
[376.097679] [104/161] (732s left) kms_flip (basic-flip-vs-dpms)
[376.586899] Starting subtest: basic-flip-vs-dpms
[376.597611] Starting dynamic subtest: A-eDP1
[380.472447] Dynamic subtest A-eDP1: SUCCESS (3.873s)
[380.550052] Starting dynamic subtest: B-eDP1
[383.743049] Dynamic subtest B-eDP1: SUCCESS (3.262s)
[383.823395] Starting dynamic subtest: C-eDP1
[386.904891] Dynamic subtest C-eDP1: SUCCESS (3.162s)
[386.914688] Subtest basic-flip-vs-dpms: SUCCESS (10.329s)
[388.212112] [105/161] (720s left) kms_flip (basic-flip-vs-modeset)
[388.713985] Starting subtest: basic-flip-vs-modeset
[388.725578] Starting dynamic subtest: A-eDP1
[392.554062] Dynamic subtest A-eDP1: SUCCESS (3.829s)
[392.637720] Starting dynamic subtest: B-eDP1
[396.190172] Dynamic subtest B-eDP1: SUCCESS (3.627s)
[396.269626] Starting dynamic subtest: C-eDP1
[400.522791] Dynamic subtest C-eDP1: SUCCESS (4.327s)
[400.529503] Subtest basic-flip-vs-modeset: SUCCESS (11.818s)
[401.622077] [106/161] (707s left) kms_flip (basic-flip-vs-wf_vblank)
[402.137406] Starting subtest: basic-flip-vs-wf_vblank
[402.146542] Starting dynamic subtest: A-eDP1
[405.183636] Dynamic subtest A-eDP1: SUCCESS (3.039s)
[405.266390] Starting dynamic subtest: B-eDP1
[408.206729] Dynamic subtest B-eDP1: SUCCESS (3.016s)
[408.294221] Starting dynamic subtest: C-eDP1
[411.605717] Dynamic subtest C-eDP1: SUCCESS (3.393s)
[411.616486] Subtest basic-flip-vs-wf_vblank: SUCCESS (9.486s)
[413.679589] [107/161] (695s left) kms_flip (basic-plain-flip)
[414.175364] Starting subtest: basic-plain-flip
[414.187870] Starting dynamic subtest: A-eDP1
[416.949709] Dynamic subtest A-eDP1: SUCCESS (2.762s)
[417.033864] Starting dynamic subtest: B-eDP1
[419.755088] Dynamic subtest B-eDP1: SUCCESS (2.799s)
[419.840121] Starting dynamic subtest: C-eDP1
[422.418795] Dynamic subtest C-eDP1: SUCCESS (2.657s)
[422.431255] Subtest basic-plain-flip: SUCCESS (8.256s)
[424.671389] [108/161] (685s left) kms_force_connector_basic (force-connector-state)
[425.083414] Starting subtest: force-connector-state
[425.941363] Subtest force-connector-state: SUCCESS (0.857s)
[427.680156] [109/161] (682s left) kms_force_connector_basic (force-edid)
[428.087229] Starting subtest: force-edid
[428.192205] Subtest force-edid: SUCCESS (0.104s)
[428.856088] [110/161] (681s left) kms_force_connector_basic (force-load-detect)
[429.274799] Starting subtest: force-load-detect
[429.282304] Subtest force-load-detect: SKIP (0.000s)
[429.893245] [111/161] (681s left) kms_force_connector_basic (prune-stale-modes)
[430.308786] Starting subtest: prune-stale-modes
[430.412798] Subtest prune-stale-modes: SUCCESS (0.106s)
[431.080585] [112/161] (680s left) kms_frontbuffer_tracking (basic)
[431.604211] Starting subtest: basic
[458.996124] Subtest basic: SUCCESS (27.393s)
[459.602228] [113/161] (652s left) kms_hdmi_inject (inject-audio)
[460.425247] Starting subtest: inject-audio
[460.972102] Subtest inject-audio: SUCCESS (0.540s)
[462.087287] [114/161] (650s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[462.625302] Starting subtest: compare-crc-sanitycheck-xr24
[462.634755] Starting dynamic subtest: pipe-A-eDP-1
[464.418667] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.782s)
[464.507154] Starting dynamic subtest: pipe-B-eDP-1
[466.659266] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.226s)
[466.745113] Starting dynamic subtest: pipe-C-eDP-1
[468.927277] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.257s)
[468.929961] Subtest compare-crc-sanitycheck-xr24: SUCCESS (6.305s)
[470.168772] [115/161] (642s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[470.661956] Starting subtest: compare-crc-sanitycheck-nv12
[470.671765] Starting dynamic subtest: pipe-A-eDP-1
[473.633687] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.956s)
[473.721077] Starting dynamic subtest: pipe-B-eDP-1
[477.057989] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.411s)
[477.163082] Starting dynamic subtest: pipe-C-eDP-1
[480.361196] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.288s)
[480.363863] Subtest compare-crc-sanitycheck-nv12: SUCCESS (9.698s)
[481.650928] [116/161] (631s left) kms_pipe_crc_basic (hang-read-crc)
[482.145851] Starting subtest: hang-read-crc
[482.156887] Starting dynamic subtest: pipe-A-eDP-1
[487.324524] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.164s)
[487.420224] Starting dynamic subtest: pipe-B-eDP-1
[493.007518] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.671s)
[493.099397] Starting dynamic subtest: pipe-C-eDP-1
[498.412855] Dynamic subtest pipe-C-eDP-1: SUCCESS (5.389s)
[498.415629] Subtest hang-read-crc: SUCCESS (16.269s)
[499.701125] [117/161] (613s left) kms_pipe_crc_basic (nonblocking-crc)
[500.203187] Starting subtest: nonblocking-crc
[500.213351] Starting dynamic subtest: pipe-A-eDP-1
[502.294077] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.077s)
[502.383725] Starting dynamic subtest: pipe-B-eDP-1
[504.824768] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.515s)
[504.919387] Starting dynamic subtest: pipe-C-eDP-1
[507.243127] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.399s)
[507.248147] Subtest nonblocking-crc: SUCCESS (7.038s)
[508.547572] [118/161] (605s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[509.043197] Starting subtest: nonblocking-crc-frame-sequence
[509.054758] Starting dynamic subtest: pipe-A-eDP-1
[511.140207] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.079s)
[511.235183] Starting dynamic subtest: pipe-B-eDP-1
[513.670649] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.515s)
[513.766395] Starting dynamic subtest: pipe-C-eDP-1
[516.097515] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.409s)
[516.100785] Subtest nonblocking-crc-frame-sequence: SUCCESS (7.052s)
[517.400513] [119/161] (596s left) kms_pipe_crc_basic (read-crc)
[517.903283] Starting subtest: read-crc
[517.908435] Starting dynamic subtest: pipe-A-eDP-1
[519.929358] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.018s)
[520.021214] Starting dynamic subtest: pipe-B-eDP-1
[522.415923] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.465s)
[522.516411] Starting dynamic subtest: pipe-C-eDP-1
[524.797529] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.368s)
[524.800893] Subtest read-crc: SUCCESS (6.902s)
[526.042856] [120/161] (588s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[526.541148] Starting subtest: read-crc-frame-sequence
[526.554151] Starting dynamic subtest: pipe-A-eDP-1
[528.569989] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.020s)
[528.660766] Starting dynamic subtest: pipe-B-eDP-1
[531.052099] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.467s)
[531.145157] Starting dynamic subtest: pipe-C-eDP-1
[533.438184] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.363s)
[533.441216] Subtest read-crc-frame-sequence: SUCCESS (6.901s)
[534.676264] [121/161] (580s left) kms_pm_backlight (basic-brightness)
[535.179898] Starting subtest: basic-brightness
[535.674299] Starting dynamic subtest: eDP-1
[536.150501] Dynamic subtest eDP-1: SUCCESS (0.485s)
[536.153606] Subtest basic-brightness: SUCCESS (0.970s)
[537.503982] [122/161] (577s left) kms_pm_rpm (basic-pci-d3-state)
[540.010238] Starting subtest: basic-pci-d3-state
[541.979722] Subtest basic-pci-d3-state: SUCCESS (1.967s)
[542.562201] [123/161] (573s left) kms_pm_rpm (basic-rte)
[542.942134] Starting subtest: basic-rte
[546.978438] Subtest basic-rte: SUCCESS (4.028s)
[549.325694] [124/161] (566s left) kms_psr (psr-primary-page-flip)
[549.825130] Starting subtest: psr-primary-page-flip
[549.836362] Starting dynamic subtest: eDP-1
[552.648106] Dynamic subtest eDP-1: SUCCESS (2.811s)
[552.652239] Subtest psr-primary-page-flip: SUCCESS (2.823s)
[553.916865] [125/161] (562s left) kms_psr (psr-cursor-plane-move)
[554.423300] Starting subtest: psr-cursor-plane-move
[554.426442] Starting dynamic subtest: eDP-1
[556.919183] Dynamic subtest eDP-1: SUCCESS (2.493s)
[556.921999] Subtest psr-cursor-plane-move: SUCCESS (2.505s)
[558.138854] [126/161] (558s left) kms_psr (psr-sprite-plane-onoff)
[558.635095] Starting subtest: psr-sprite-plane-onoff
[558.645972] Starting dynamic subtest: eDP-1
[561.393685] Dynamic subtest eDP-1: SUCCESS (2.745s)
[561.400437] Subtest psr-sprite-plane-onoff: SUCCESS (2.757s)
[562.689534] [127/161] (554s left) kms_psr (psr-primary-mmap-gtt)
[563.187665] Starting subtest: psr-primary-mmap-gtt
[563.198898] Starting dynamic subtest: eDP-1
[565.956464] Dynamic subtest eDP-1: SUCCESS (2.756s)
[565.960983] Subtest psr-primary-mmap-gtt: SUCCESS (2.768s)
[567.250900] [128/161] (550s left) kms_setmode (basic-clone-single-crtc)
[567.661359] Starting subtest: basic-clone-single-crtc
[567.670556] Subtest basic-clone-single-crtc: SKIP (0.001s)
[568.157462] [129/161] (550s left) i915_pm_rps (basic-api)
[568.583253] Starting subtest: basic-api
[568.589312] Subtest basic-api: SKIP (0.004s)
[569.095170] [130/161] (549s left) prime_self_import (basic-llseek-bad)
[569.484105] Starting subtest: basic-llseek-bad
[569.490133] Subtest basic-llseek-bad: SUCCESS (0.014s)
[570.001168] [131/161] (549s left) prime_self_import (basic-llseek-size)
[570.378882] Starting subtest: basic-llseek-size
[570.401243] Subtest basic-llseek-size: SUCCESS (0.022s)
[570.913156] [132/161] (548s left) prime_self_import (basic-with_fd_dup)
[571.284438] Starting subtest: basic-with_fd_dup
[571.307309] Subtest basic-with_fd_dup: SUCCESS (0.023s)
[571.814255] [133/161] (548s left) prime_self_import (basic-with_one_bo)
[572.189123] Starting subtest: basic-with_one_bo
[572.215794] Subtest basic-with_one_bo: SUCCESS (0.027s)
[572.732570] [134/161] (547s left) prime_self_import (basic-with_one_bo_two_files)
[573.114074] Starting subtest: basic-with_one_bo_two_files
[573.128694] Subtest basic-with_one_bo_two_files: SUCCESS (0.018s)
[573.640478] [135/161] (546s left) prime_self_import (basic-with_two_bos)
[574.024895] Starting subtest: basic-with_two_bos
[574.041859] Subtest basic-with_two_bos: SUCCESS (0.024s)
[574.557291] [136/161] (546s left) prime_vgem (basic-fence-flip)
[575.025782] Starting subtest: basic-fence-flip
[576.484744] Subtest basic-fence-flip: SUCCESS (1.466s)
[577.113102] [137/161] (544s left) prime_vgem (basic-fence-mmap)
[577.570715] Starting subtest: basic-fence-mmap
[577.778993] Subtest basic-fence-mmap: SUCCESS (0.205s)
[578.285737] [138/161] (543s left) prime_vgem (basic-fence-read)
[578.732103] Starting subtest: basic-fence-read
[578.792411] Subtest basic-fence-read: SKIP (0.058s)
[579.326321] [139/161] (542s left) prime_vgem (basic-gtt)
[579.782698] Starting subtest: basic-gtt
[579.866960] Subtest basic-gtt: SUCCESS (0.091s)
[580.376155] [140/161] (542s left) prime_vgem (basic-read)
[580.839056] Starting subtest: basic-read
[580.891074] Subtest basic-read: SKIP (0.057s)
[581.417340] [141/161] (541s left) prime_vgem (basic-write)
[581.870141] Starting subtest: basic-write
[581.928979] Subtest basic-write: SKIP (0.056s)
[582.471382] [142/161] (540s left) vgem_basic (setversion)
[582.888946] Starting subtest: setversion
[582.900908] Subtest setversion: SUCCESS (0.000s)
[583.363997] [143/161] (540s left) vgem_basic (create)
[583.775781] Starting subtest: create
[583.783956] Subtest create: SUCCESS (0.002s)
[584.262217] [144/161] (540s left) vgem_basic (debugfs)
[584.677762] Starting subtest: debugfs
[584.681358] Subtest debugfs: SUCCESS (0.003s)
[585.168211] [145/161] (539s left) vgem_basic (dmabuf-export)
[585.578666] Starting subtest: dmabuf-export
[585.598295] Subtest dmabuf-export: SUCCESS (0.018s)
[586.080234] [146/161] (539s left) vgem_basic (dmabuf-fence)
[586.489322] Starting subtest: dmabuf-fence
[586.492535] Subtest dmabuf-fence: SUCCESS (0.003s)
[586.977631] [147/161] (538s left) vgem_basic (dmabuf-fence-before)
[587.388960] Starting subtest: dmabuf-fence-before
[587.392289] Subtest dmabuf-fence-before: SUCCESS (0.003s)
[587.870254] [148/161] (538s left) vgem_basic (dmabuf-mmap)
[588.289520] Starting subtest: dmabuf-mmap
[588.384907] Subtest dmabuf-mmap: SUCCESS (0.100s)
[588.854250] [149/161] (537s left) vgem_basic (mmap)
[589.267684] Starting subtest: mmap
[589.328908] Subtest mmap: SUCCESS (0.065s)
[589.798536] [150/161] (536s left) vgem_basic (second-client)
[590.213933] Starting subtest: second-client
[590.263393] Subtest second-client: SUCCESS (0.046s)
[590.725646] [151/161] (536s left) vgem_basic (sysfs)
[591.135877] Starting subtest: sysfs
[591.139406] Subtest sysfs: SUCCESS (0.004s)
[591.614283] [152/161] (535s left) core_hotunplug (unbind-rebind)
[592.324345] Starting subtest: unbind-rebind
[601.491158] Subtest unbind-rebind: SUCCESS (9.171s)
[601.902206] [153/161] (525s left) vgem_basic (unload)
[602.287277] Starting subtest: unload
[632.051669] Subtest unload: SUCCESS (29.728s)
[632.693269] [154/161] (495s left) i915_module_load (reload)
[633.067986] Starting subtest: reload
[647.783373] Subtest reload: SUCCESS (14.714s)
[648.348837] [155/161] (480s left) gem_lmem_swapping (basic)
[648.943780] Subtest basic: SKIP (0.000s)
[649.395248] [156/161] (479s left) gem_lmem_swapping (parallel-random-engines)
[649.817845] Subtest parallel-random-engines: SKIP (0.000s)
[650.262286] [157/161] (479s left) gem_lmem_swapping (random-engines)
[650.681066] Subtest random-engines: SKIP (0.000s)
[651.122194] [158/161] (478s left) gem_lmem_swapping (verify-random)
[651.539319] Subtest verify-random: SKIP (0.000s)
[651.983455] [159/161] (478s left) i915_pm_rpm (module-reload)
[655.395337] Starting subtest: module-reload
[689.162358] Subtest module-reload: SUCCESS (33.757s)
[689.421401] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[689.434903] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[689.458095] [160/161] (441s left) i915_selftest (live)
[689.870636] Starting subtest: live
[689.881242] Starting dynamic subtest: sanitycheck
[700.339704] Dynamic subtest sanitycheck: SUCCESS (10.452s)
[700.404800] Starting dynamic subtest: uncore
[710.757203] Dynamic subtest uncore: SUCCESS (10.411s)
[710.825083] Starting dynamic subtest: workarounds
[723.842339] Dynamic subtest workarounds: SUCCESS (13.072s)
[723.909282] Starting dynamic subtest: gt_engines
[736.640627] Dynamic subtest gt_engines: SUCCESS (12.766s)
[736.704649] Starting dynamic subtest: gt_timelines
[781.731203] Dynamic subtest gt_timelines: SUCCESS (45.104s)
[781.796710] Starting dynamic subtest: gt_contexts
[792.230612] Dynamic subtest gt_contexts: SUCCESS (10.490s)
[792.301161] Starting dynamic subtest: gt_lrc
[806.236192] Dynamic subtest gt_lrc: SUCCESS (13.995s)
[806.300137] Starting dynamic subtest: gt_mocs
[817.901505] Dynamic subtest gt_mocs: SUCCESS (11.657s)
[817.967432] Starting dynamic subtest: gt_pm
[830.493838] Dynamic subtest gt_pm: SUCCESS (12.583s)
[830.558218] Starting dynamic subtest: gt_heartbeat
[841.022537] Dynamic subtest gt_heartbeat: SUCCESS (10.520s)
[841.087190] Starting dynamic subtest: gt_tlb
[852.600886] Dynamic subtest gt_tlb: SUCCESS (11.569s)
[852.674797] Starting dynamic subtest: requests
[873.018203] Dynamic subtest requests: SUCCESS (20.378s)
[873.079864] Starting dynamic subtest: migrate
[887.555269] Dynamic subtest migrate: SUCCESS (14.553s)
[887.622479] Starting dynamic subtest: active
[898.147578] Dynamic subtest active: SUCCESS (10.575s)
[898.217490] Starting dynamic subtest: objects
[912.761348] Dynamic subtest objects: SUCCESS (14.608s)
[912.827257] Starting dynamic subtest: mman
[933.853315] Dynamic subtest mman: SUCCESS (21.083s)
[933.915682] Starting dynamic subtest: dmabuf
[944.822876] Dynamic subtest dmabuf: SUCCESS (10.964s)
[944.912635] Starting dynamic subtest: vma
[955.390903] Dynamic subtest vma: SUCCESS (10.554s)
[955.459813] Starting dynamic subtest: coherency
[984.804767] Dynamic subtest coherency: SUCCESS (29.405s)
[984.869809] Starting dynamic subtest: gtt
[1003.517554] Dynamic subtest gtt: SUCCESS (18.713s)
[1003.603827] Starting dynamic subtest: gem
[1015.203430] Dynamic subtest gem: SUCCESS (11.664s)
[1015.270244] Starting dynamic subtest: evict
[1033.672931] Dynamic subtest evict: SUCCESS (18.453s)
[1033.745463] Starting dynamic subtest: hugepages
[1050.861572] Dynamic subtest hugepages: SUCCESS (17.181s)
[1050.926389] Starting dynamic subtest: gem_contexts
[1092.067220] Dynamic subtest gem_contexts: SUCCESS (41.203s)
[1092.141360] Starting dynamic subtest: client
[1102.766851] Dynamic subtest client: SUCCESS (10.679s)
[1102.829287] Starting dynamic subtest: gem_migrate
[1113.170212] Dynamic subtest gem_migrate: SUCCESS (10.394s)
[1113.235445] Starting dynamic subtest: reset
[1119.807239] 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 [1164] via Terminated, terminating children
[1124.378412] Aborting: Kernel badly tainted (0x240, 0x200) (check dmesg for details):
	TAINT_WARN: WARN_ON has happened.

[1124.547577] [FACT i915_selftest (live)] new: kernel.is_tainted.taint_warn: true
[1124.550730] [FACT i915_selftest (live)] new: kernel.kmod_is_loaded.i915: true
[1124.562637] Closing watchdogs
Done.