[102.398473] Initializing watchdogs
[102.399138]   /dev/watchdog0
[102.568435] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:46d3 Intel Alderlake_n (Gen12) 
[102.613664] [001/161] (960s left) i915_module_load (load)
[103.818349] Starting subtest: load
[115.022122] Subtest load: SUCCESS (11.218s)
[115.425254] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[115.440269] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[115.460632] [002/161] (948s left) core_auth (basic-auth)
[116.007174] Starting subtest: basic-auth
[116.016596] Subtest basic-auth: SUCCESS (0.011s)
[116.528962] [003/161] (947s left) debugfs_test (read_all_entries)
[116.974660] Starting subtest: read_all_entries
[118.475203] Subtest read_all_entries: SUCCESS (1.498s)
[119.012616] [004/161] (945s left) debugfs_test (basic-hwmon)
[119.451139] Starting subtest: basic-hwmon
[119.457208] Subtest basic-hwmon: SKIP (0.000s)
[119.966077] [005/161] (944s left) debugfs_test (sysfs)
[120.406831] Starting subtest: sysfs
[120.887796] Subtest sysfs: SUCCESS (0.487s)
[121.402559] [006/161] (943s left) fbdev (eof)
[121.806664] Starting subtest: eof
[121.809951] Subtest eof: SUCCESS (0.004s)
[122.242770] [007/161] (942s left) fbdev (info)
[122.622544] Starting subtest: info
[122.625282] Subtest info: SUCCESS (0.000s)
[123.077171] [008/161] (942s left) fbdev (nullptr)
[123.462156] Starting subtest: nullptr
[123.465325] Subtest nullptr: SUCCESS (0.001s)
[123.890669] [009/161] (942s left) fbdev (read)
[124.288782] Starting subtest: read
[137.967488] Subtest read: SUCCESS (13.647s)
[138.381326] [010/161] (927s left) fbdev (write)
[138.782450] Starting subtest: write
[145.901218] Subtest write: SUCCESS (7.109s)
[146.364615] [011/161] (920s left) gem_basic (bad-close)
[146.754740] Starting subtest: bad-close
[146.760769] Subtest bad-close: SUCCESS (0.000s)
[147.170869] [012/161] (919s left) gem_basic (create-close)
[147.558553] Starting subtest: create-close
[147.564226] Subtest create-close: SUCCESS (0.001s)
[147.982540] [013/161] (919s left) gem_basic (create-fd-close)
[148.369642] Starting subtest: create-fd-close
[148.377361] Subtest create-fd-close: SUCCESS (0.001s)
[148.799987] [014/161] (918s left) gem_busy (busy@all-engines)
[149.282722] Starting subtest: busy
[149.285927] Starting dynamic subtest: all-engines
[149.597461] Dynamic subtest all-engines: SUCCESS (0.319s)
[149.600730] Subtest busy: SUCCESS (0.321s)
[150.493601] [015/161] (917s left) gem_close_race (basic-process)
[150.975735] Starting subtest: basic-process
[151.134820] Subtest basic-process: SUCCESS (0.163s)
[151.691758] [016/161] (916s left) gem_close_race (basic-threads)
[152.176540] Starting subtest: basic-threads
[153.600888] Subtest basic-threads: SUCCESS (1.425s)
[154.159453] [017/161] (914s left) gem_ctx_create (basic)
[154.633920] Starting subtest: basic
[154.636743] Subtest basic: SUCCESS (0.000s)
[155.194663] [018/161] (913s left) gem_ctx_create (basic-files)
[155.664079] Starting subtest: basic-files
[157.788622] Subtest basic-files: SUCCESS (2.124s)
[158.477630] [019/161] (910s left) gem_ctx_exec (basic)
[158.879893] Starting subtest: basic
[158.900158] Subtest basic: SUCCESS (0.023s)
[159.346957] [020/161] (910s left) gem_exec_basic (basic)
[159.786410] Starting subtest: basic
[159.789748] Starting dynamic subtest: rcs0-smem
[159.799753] Dynamic subtest rcs0-smem: SUCCESS (0.011s)
[159.857581] Starting dynamic subtest: bcs0-smem
[159.860959] Dynamic subtest bcs0-smem: SUCCESS (0.004s)
[159.916762] Starting dynamic subtest: vcs0-smem
[159.919936] Dynamic subtest vcs0-smem: SUCCESS (0.004s)
[159.982391] Starting dynamic subtest: vecs0-smem
[159.985482] Dynamic subtest vecs0-smem: SUCCESS (0.005s)
[159.988433] Subtest basic: SUCCESS (0.034s)
[160.392388] [021/161] (909s left) gem_exec_create (basic)
[160.865380] Starting subtest: basic
[160.868525] Starting dynamic subtest: smem
[163.096785] Dynamic subtest smem: SUCCESS (2.232s)
[163.100192] Subtest basic: SUCCESS (2.234s)
[163.661472] [022/161] (906s left) gem_exec_fence (basic-busy)
[164.338097] Starting subtest: basic-busy
[164.341300] Starting dynamic subtest: rcs0
[164.460278] Dynamic subtest rcs0: SUCCESS (0.120s)
[164.521533] Starting dynamic subtest: bcs0
[164.527636] Dynamic subtest bcs0: SUCCESS (0.061s)
[164.586460] Starting dynamic subtest: vcs0
[164.592364] Dynamic subtest vcs0: SUCCESS (0.059s)
[164.654172] Starting dynamic subtest: vecs0
[164.661900] Dynamic subtest vecs0: SUCCESS (0.071s)
[164.664793] Subtest basic-busy: SUCCESS (0.318s)
[166.165850] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[166.188074] [023/161] (904s left) gem_exec_fence (basic-wait)
[166.874508] Starting subtest: basic-wait
[166.877544] Starting dynamic subtest: rcs0
[166.947891] Dynamic subtest rcs0: SUCCESS (0.076s)
[167.014428] Starting dynamic subtest: bcs0
[167.017718] Dynamic subtest bcs0: SUCCESS (0.062s)
[167.077172] Starting dynamic subtest: vcs0
[167.080399] Dynamic subtest vcs0: SUCCESS (0.064s)
[167.142829] Starting dynamic subtest: vecs0
[167.151734] Dynamic subtest vecs0: SUCCESS (0.071s)
[167.154437] Subtest basic-wait: SUCCESS (0.280s)
[168.462636] [024/161] (902s left) gem_exec_fence (basic-await)
[169.148445] Starting subtest: basic-await
[169.151722] Starting dynamic subtest: rcs0
[169.500341] Dynamic subtest rcs0: SUCCESS (0.350s)
[169.563094] Starting dynamic subtest: bcs0
[169.817440] Dynamic subtest bcs0: SUCCESS (0.314s)
[169.881329] Starting dynamic subtest: vcs0
[170.134126] Dynamic subtest vcs0: SUCCESS (0.316s)
[170.194230] Starting dynamic subtest: vecs0
[170.445109] Dynamic subtest vecs0: SUCCESS (0.309s)
[170.447864] Subtest basic-await: SUCCESS (1.298s)
[171.704224] [025/161] (899s left) gem_exec_fence (nb-await)
[172.382261] Starting subtest: nb-await
[172.385794] Starting dynamic subtest: rcs0
[172.496237] Dynamic subtest rcs0: SUCCESS (0.115s)
[172.556950] Starting dynamic subtest: bcs0
[172.585872] Dynamic subtest bcs0: SUCCESS (0.089s)
[172.648705] Starting dynamic subtest: vcs0
[172.681247] Dynamic subtest vcs0: SUCCESS (0.090s)
[172.741629] Starting dynamic subtest: vecs0
[172.769915] Dynamic subtest vecs0: SUCCESS (0.089s)
[172.772791] Subtest nb-await: SUCCESS (0.392s)
[174.085192] [026/161] (897s left) gem_exec_gttfill (basic)
[174.559492] Starting subtest: basic
[212.821328] Subtest basic: SUCCESS (38.251s)
[213.449881] [027/161] (858s left) gem_exec_parallel (engines)
[214.114335] Starting subtest: engines
[214.118962] Starting dynamic subtest: basic
[220.668263] Dynamic subtest basic: SUCCESS (6.553s)
[220.732319] Starting dynamic subtest: contexts
[231.129966] Dynamic subtest contexts: SUCCESS (10.451s)
[231.197177] Starting dynamic subtest: fds
[244.543778] Dynamic subtest fds: SUCCESS (13.411s)
[244.612311] Starting dynamic subtest: userptr
[250.233554] Dynamic subtest userptr: SUCCESS (5.697s)
[250.236655] Subtest engines: SUCCESS (36.120s)
[250.863282] [028/161] (821s left) gem_exec_store (basic)
[251.342306] Starting subtest: basic
[251.480239] Subtest basic: SUCCESS (0.138s)
[252.076914] [029/161] (820s left) gem_flink_basic (bad-flink)
[252.451379] Starting subtest: bad-flink
[252.454729] Subtest bad-flink: SUCCESS (0.000s)
[252.907507] [030/161] (820s left) gem_flink_basic (bad-open)
[253.288966] Starting subtest: bad-open
[253.293322] Subtest bad-open: SUCCESS (0.000s)
[253.746612] [031/161] (819s left) gem_flink_basic (basic)
[254.135844] Starting subtest: basic
[254.138916] Subtest basic: SUCCESS (0.001s)
[254.620733] [032/161] (819s left) gem_flink_basic (double-flink)
[255.001826] Starting subtest: double-flink
[255.005777] Subtest double-flink: SUCCESS (0.001s)
[255.493428] [033/161] (818s left) gem_flink_basic (flink-lifetime)
[255.876493] Starting subtest: flink-lifetime
[255.887291] Subtest flink-lifetime: SUCCESS (0.009s)
[256.359087] [034/161] (818s left) gem_huc_copy (huc-copy)
[256.827260] Starting subtest: huc-copy
[256.850917] Subtest huc-copy: SUCCESS (0.024s)
[257.390343] [035/161] (817s left) gem_linear_blits (basic)
[258.875767] Starting subtest: basic
[258.933544] Subtest basic: SUCCESS (0.066s)
[259.485627] [036/161] (816s left) gem_mmap (basic)
[260.064805] Starting subtest: basic
[260.078604] Subtest basic: SUCCESS (0.004s)
[260.549319] [037/161] (815s left) gem_mmap_gtt (basic)
[260.940567] Starting subtest: basic
[260.943671] Subtest basic: SUCCESS (0.006s)
[261.408968] [038/161] (814s left) gem_render_linear_blits (basic)
[261.820466] Starting subtest: basic
[262.050844] Subtest basic: SUCCESS (0.229s)
[262.514284] [039/161] (814s left) gem_render_tiled_blits (basic)
[262.930684] Starting subtest: basic
[263.198064] Subtest basic: SUCCESS (0.269s)
[263.662518] [040/161] (813s left) gem_ringfill (basic-all)
[264.559597] Starting subtest: basic-all
[266.037847] Subtest basic-all: SUCCESS (1.472s)
[266.501098] [041/161] (811s left) gem_softpin (allocator-basic)
[266.915215] Starting subtest: allocator-basic
[267.620497] Subtest allocator-basic: SUCCESS (0.695s)
[268.176441] [042/161] (809s left) gem_softpin (allocator-basic-reserve)
[268.594900] Starting subtest: allocator-basic-reserve
[269.303138] Subtest allocator-basic-reserve: SUCCESS (0.698s)
[269.866454] [043/161] (808s left) gem_softpin (safe-alignment)
[270.309752] Starting subtest: safe-alignment
[270.418224] Subtest safe-alignment: SUCCESS (0.105s)
[270.907260] [044/161] (807s left) gem_sync (basic-all)
[271.383133] Starting subtest: basic-all
[276.643198] Subtest basic-all: SUCCESS (5.260s)
[277.277504] [045/161] (801s left) gem_sync (basic-each)
[277.780547] Starting subtest: basic-each
[281.417231] Subtest basic-each: SUCCESS (3.641s)
[282.028458] [046/161] (797s left) gem_tiled_blits (basic)
[282.970856] Starting subtest: basic
[283.096306] Subtest basic: SUCCESS (0.124s)
[283.629799] [047/161] (796s left) gem_tiled_fence_blits (basic)
[284.803997] Starting subtest: basic
[284.868221] Subtest basic: SUCCESS (0.077s)
[285.487959] [048/161] (794s left) gem_tiled_pread_basic
[286.515268] [049/161] (794s left) gem_wait (busy@all-engines)
[287.068820] Starting subtest: busy
[287.072097] Starting dynamic subtest: all-engines
[287.656194] Dynamic subtest all-engines: SUCCESS (0.597s)
[287.662565] Subtest busy: SUCCESS (0.599s)
[288.396910] [050/161] (792s left) gem_wait (wait@all-engines)
[288.928800] Starting subtest: wait
[288.932089] Starting dynamic subtest: all-engines
[290.028734] Dynamic subtest all-engines: SUCCESS (1.108s)
[290.032207] Subtest wait: SUCCESS (1.110s)
[290.762922] [051/161] (790s left) i915_getparams_basic (basic-eu-total)
[291.138783] Starting subtest: basic-eu-total
[291.144378] Subtest basic-eu-total: SUCCESS (0.000s)
[291.597622] [052/161] (790s left) i915_getparams_basic (basic-subslice-total)
[291.979346] Starting subtest: basic-subslice-total
[291.986296] Subtest basic-subslice-total: SUCCESS (0.000s)
[292.441548] [053/161] (789s left) i915_hangman (error-state-basic)
[292.905229] Starting subtest: error-state-basic
[293.763556] Subtest error-state-basic: SUCCESS (0.855s)
[294.267875] [054/161] (788s left) i915_pciid
[295.111142] [055/161] (787s left) kms_addfb_basic (addfb25-4-tiled)
[295.639094] Starting subtest: addfb25-4-tiled
[295.641786] Subtest addfb25-4-tiled: SUCCESS (0.002s)
[296.127488] [056/161] (787s left) kms_addfb_basic (addfb25-bad-modifier)
[296.613632] Starting subtest: addfb25-bad-modifier
[296.617632] Subtest addfb25-bad-modifier: SUCCESS (0.002s)
[297.100227] [057/161] (786s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[297.591526] Starting subtest: addfb25-framebuffer-vs-set-tiling
[297.595257] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.002s)
[298.078470] [058/161] (786s left) kms_addfb_basic (addfb25-modifier-no-flag)
[298.570694] Starting subtest: addfb25-modifier-no-flag
[298.574693] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[299.061464] [059/161] (785s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[299.548783] Starting subtest: addfb25-x-tiled-legacy
[299.551942] Subtest addfb25-x-tiled-legacy: SUCCESS (0.001s)
[300.042893] [060/161] (784s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[300.556754] Starting subtest: addfb25-x-tiled-mismatch-legacy
[300.560518] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.002s)
[301.041951] [061/161] (784s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[301.534089] Starting subtest: addfb25-yf-tiled-legacy
[301.537411] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.002s)
[302.028911] [062/161] (783s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[302.517273] Starting subtest: addfb25-y-tiled-legacy
[302.520900] Subtest addfb25-y-tiled-legacy: SUCCESS (0.001s)
[302.994160] [063/161] (783s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[303.485151] Starting subtest: addfb25-y-tiled-small-legacy
[303.487791] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.002s)
[303.968943] [064/161] (782s left) kms_addfb_basic (bad-pitch-0)
[304.351978] Starting subtest: bad-pitch-0
[304.355452] Subtest bad-pitch-0: SUCCESS (0.000s)
[304.933365] [065/161] (781s left) kms_addfb_basic (bad-pitch-1024)
[305.316242] Starting subtest: bad-pitch-1024
[305.319244] Subtest bad-pitch-1024: SUCCESS (0.000s)
[305.901817] [066/161] (781s left) kms_addfb_basic (bad-pitch-128)
[306.291687] Starting subtest: bad-pitch-128
[306.294761] Subtest bad-pitch-128: SUCCESS (0.000s)
[306.886513] [067/161] (780s left) kms_addfb_basic (bad-pitch-256)
[307.271686] Starting subtest: bad-pitch-256
[307.274811] Subtest bad-pitch-256: SUCCESS (0.000s)
[307.854993] [068/161] (780s left) kms_addfb_basic (bad-pitch-32)
[308.241993] Starting subtest: bad-pitch-32
[308.244887] Subtest bad-pitch-32: SUCCESS (0.000s)
[308.824404] [069/161] (779s left) kms_addfb_basic (bad-pitch-63)
[309.214778] Starting subtest: bad-pitch-63
[309.217587] Subtest bad-pitch-63: SUCCESS (0.000s)
[309.794581] [070/161] (778s left) kms_addfb_basic (bad-pitch-65536)
[310.181934] Starting subtest: bad-pitch-65536
[310.185330] Subtest bad-pitch-65536: SUCCESS (0.002s)
[310.775803] [071/161] (778s left) kms_addfb_basic (bad-pitch-999)
[311.160135] Starting subtest: bad-pitch-999
[311.163403] Subtest bad-pitch-999: SUCCESS (0.000s)
[311.752706] [072/161] (777s left) kms_addfb_basic (basic)
[312.140746] Starting subtest: basic
[312.144490] Subtest basic: SUCCESS (0.000s)
[312.726478] [073/161] (777s left) kms_addfb_basic (basic-x-tiled-legacy)
[313.127928] Starting subtest: basic-x-tiled-legacy
[313.131457] Subtest basic-x-tiled-legacy: SUCCESS (0.001s)
[313.718873] [074/161] (776s left) kms_addfb_basic (basic-y-tiled-legacy)
[314.111768] Starting subtest: basic-y-tiled-legacy
[314.114576] Subtest basic-y-tiled-legacy: SUCCESS (0.002s)
[314.692686] [075/161] (775s left) kms_addfb_basic (bo-too-small)
[315.216286] Starting subtest: bo-too-small
[315.222077] Subtest bo-too-small: SUCCESS (0.002s)
[315.736635] [076/161] (775s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[316.230839] Starting subtest: bo-too-small-due-to-tiling
[316.235074] Subtest bo-too-small-due-to-tiling: SUCCESS (0.002s)
[316.717157] [077/161] (774s left) kms_addfb_basic (clobberred-modifier)
[317.102837] Starting subtest: clobberred-modifier
[317.105683] Subtest clobberred-modifier: SUCCESS (0.001s)
[317.683252] [078/161] (774s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[318.069878] Starting subtest: framebuffer-vs-set-tiling
[318.073526] Subtest framebuffer-vs-set-tiling: SUCCESS (0.001s)
[318.643941] [079/161] (773s left) kms_addfb_basic (invalid-get-prop)
[319.026396] Starting subtest: invalid-get-prop
[319.029737] Subtest invalid-get-prop: SUCCESS (0.000s)
[319.603087] [080/161] (772s left) kms_addfb_basic (invalid-get-prop-any)
[319.989742] Starting subtest: invalid-get-prop-any
[319.992576] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[320.571893] [081/161] (772s left) kms_addfb_basic (invalid-set-prop)
[320.961648] Starting subtest: invalid-set-prop
[320.964804] Subtest invalid-set-prop: SUCCESS (0.000s)
[321.550687] [082/161] (771s left) kms_addfb_basic (invalid-set-prop-any)
[321.933924] Starting subtest: invalid-set-prop-any
[321.937762] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[322.530782] [083/161] (771s left) kms_addfb_basic (no-handle)
[322.914750] Starting subtest: no-handle
[322.917950] Subtest no-handle: SUCCESS (0.000s)
[323.500995] [084/161] (770s left) kms_addfb_basic (size-max)
[323.991766] Starting subtest: size-max
[323.995301] Subtest size-max: SUCCESS (0.001s)
[324.469540] [085/161] (770s left) kms_addfb_basic (small-bo)
[324.956112] Starting subtest: small-bo
[324.959132] Subtest small-bo: SUCCESS (0.000s)
[325.438804] [086/161] (769s left) kms_addfb_basic (tile-pitch-mismatch)
[325.829625] Starting subtest: tile-pitch-mismatch
[325.832707] Subtest tile-pitch-mismatch: SUCCESS (0.002s)
[326.412403] [087/161] (768s left) kms_addfb_basic (too-high)
[326.907836] Starting subtest: too-high
[326.915114] Subtest too-high: SUCCESS (0.006s)
[327.398428] [088/161] (768s left) kms_addfb_basic (too-wide)
[327.886557] Starting subtest: too-wide
[327.889569] Subtest too-wide: SUCCESS (0.000s)
[328.366582] [089/161] (767s left) kms_addfb_basic (unused-handle)
[328.749317] Starting subtest: unused-handle
[328.752605] Subtest unused-handle: SUCCESS (0.000s)
[329.328510] [090/161] (767s left) kms_addfb_basic (unused-modifier)
[329.716723] Starting subtest: unused-modifier
[329.719775] Subtest unused-modifier: SUCCESS (0.000s)
[330.312147] [091/161] (766s left) kms_addfb_basic (unused-offsets)
[330.702545] Starting subtest: unused-offsets
[330.705814] Subtest unused-offsets: SUCCESS (0.000s)
[331.293908] [092/161] (765s left) kms_addfb_basic (unused-pitches)
[331.682553] Starting subtest: unused-pitches
[331.685356] Subtest unused-pitches: SUCCESS (0.000s)
[332.271839] [093/161] (765s left) kms_busy (basic)
[332.787228] Starting subtest: basic
[332.794386] Starting dynamic subtest: flip
[335.116883] Dynamic subtest flip: SUCCESS (2.322s)
[335.198418] Starting dynamic subtest: modeset
[340.326421] Dynamic subtest modeset: SUCCESS (5.203s)
[340.333160] Subtest basic: SUCCESS (7.537s)
[341.635489] [094/161] (756s left) kms_prop_blob (basic)
[342.020667] Starting subtest: basic
[342.023620] Subtest basic: SUCCESS (0.000s)
[342.496319] [095/161] (755s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[343.134497] Starting subtest: basic-busy-flip-before-cursor-atomic
[343.140473] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[343.735481] [096/161] (755s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[344.353083] Starting subtest: basic-busy-flip-before-cursor-legacy
[344.358879] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[344.958610] [097/161] (754s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[345.610426] Starting subtest: basic-flip-after-cursor-atomic
[347.237796] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.628s)
[348.544660] [098/161] (751s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[349.172351] Starting subtest: basic-flip-after-cursor-legacy
[350.783718] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.608s)
[352.069559] [099/161] (747s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[352.699804] Starting subtest: basic-flip-after-cursor-varying-size
[355.105845] Subtest basic-flip-after-cursor-varying-size: SUCCESS (2.398s)
[356.111876] [100/161] (744s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[356.744869] Starting subtest: basic-flip-before-cursor-atomic
[358.350588] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.606s)
[359.645225] [101/161] (741s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[360.295149] Starting subtest: basic-flip-before-cursor-legacy
[361.912779] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.620s)
[363.178916] [102/161] (737s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[363.803728] Starting subtest: basic-flip-before-cursor-varying-size
[366.204503] Subtest basic-flip-before-cursor-varying-size: SUCCESS (2.393s)
[367.246567] [103/161] (734s left) kms_dsc (dsc-basic)
[367.737999] Starting subtest: dsc-basic
[367.751341] Subtest dsc-basic: SKIP (0.003s)
[368.205993] [104/161] (733s left) kms_flip (basic-flip-vs-dpms)
[368.698797] Starting subtest: basic-flip-vs-dpms
[368.709876] Starting dynamic subtest: A-eDP1
[372.592981] Dynamic subtest A-eDP1: SUCCESS (3.883s)
[372.672210] Starting dynamic subtest: B-eDP1
[376.725860] Dynamic subtest B-eDP1: SUCCESS (4.127s)
[376.808466] Starting dynamic subtest: C-eDP1
[379.964381] Dynamic subtest C-eDP1: SUCCESS (3.232s)
[379.975317] Subtest basic-flip-vs-dpms: SUCCESS (11.274s)
[381.252771] [105/161] (721s left) kms_flip (basic-flip-vs-modeset)
[381.745623] Starting subtest: basic-flip-vs-modeset
[381.762315] Starting dynamic subtest: A-eDP1
[385.559598] Dynamic subtest A-eDP1: SUCCESS (3.800s)
[385.643845] Starting dynamic subtest: B-eDP1
[388.685555] Dynamic subtest B-eDP1: SUCCESS (3.117s)
[388.765317] Starting dynamic subtest: C-eDP1
[393.040923] Dynamic subtest C-eDP1: SUCCESS (4.352s)
[393.049597] Subtest basic-flip-vs-modeset: SUCCESS (11.304s)
[394.125519] [106/161] (708s left) kms_flip (basic-flip-vs-wf_vblank)
[394.619737] Starting subtest: basic-flip-vs-wf_vblank
[394.631385] Starting dynamic subtest: A-eDP1
[397.720810] Dynamic subtest A-eDP1: SUCCESS (3.089s)
[397.805094] Starting dynamic subtest: B-eDP1
[400.809514] Dynamic subtest B-eDP1: SUCCESS (3.083s)
[400.894698] Starting dynamic subtest: C-eDP1
[403.803894] Dynamic subtest C-eDP1: SUCCESS (2.988s)
[403.813993] Subtest basic-flip-vs-wf_vblank: SUCCESS (9.197s)
[406.081189] [107/161] (696s left) kms_flip (basic-plain-flip)
[406.576757] Starting subtest: basic-plain-flip
[406.588371] Starting dynamic subtest: A-eDP1
[409.353770] Dynamic subtest A-eDP1: SUCCESS (2.765s)
[409.438770] Starting dynamic subtest: B-eDP1
[412.145326] Dynamic subtest B-eDP1: SUCCESS (2.785s)
[412.230304] Starting dynamic subtest: C-eDP1
[414.828907] Dynamic subtest C-eDP1: SUCCESS (2.676s)
[414.838752] Subtest basic-plain-flip: SUCCESS (8.265s)
[417.063350] [108/161] (686s left) kms_force_connector_basic (force-connector-state)
[417.497662] Starting subtest: force-connector-state
[418.349894] Subtest force-connector-state: SUCCESS (0.853s)
[420.094270] [109/161] (683s left) kms_force_connector_basic (force-edid)
[420.541266] Starting subtest: force-edid
[420.654606] Subtest force-edid: SUCCESS (0.105s)
[421.295231] [110/161] (682s left) kms_force_connector_basic (force-load-detect)
[421.708452] Starting subtest: force-load-detect
[421.715764] Subtest force-load-detect: SKIP (0.000s)
[422.300240] [111/161] (682s left) kms_force_connector_basic (prune-stale-modes)
[422.716250] Starting subtest: prune-stale-modes
[422.845142] Subtest prune-stale-modes: SUCCESS (0.123s)
[423.485235] [112/161] (681s left) kms_frontbuffer_tracking (basic)
[424.015894] Starting subtest: basic
[451.276047] Subtest basic: SUCCESS (27.263s)
[451.891173] [113/161] (653s left) kms_hdmi_inject (inject-audio)
[452.710463] Starting subtest: inject-audio
[453.247645] Subtest inject-audio: SUCCESS (0.537s)
[454.383485] [114/161] (651s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[454.883727] Starting subtest: compare-crc-sanitycheck-xr24
[454.889833] Starting dynamic subtest: pipe-A-eDP-1
[456.674564] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.783s)
[456.766736] Starting dynamic subtest: pipe-B-eDP-1
[458.897133] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.206s)
[458.995830] Starting dynamic subtest: pipe-C-eDP-1
[461.914909] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.004s)
[461.917753] Subtest compare-crc-sanitycheck-xr24: SUCCESS (7.035s)
[463.002389] [115/161] (643s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[463.497421] Starting subtest: compare-crc-sanitycheck-nv12
[463.502922] Starting dynamic subtest: pipe-A-eDP-1
[466.480632] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.973s)
[466.570094] Starting dynamic subtest: pipe-B-eDP-1
[469.865386] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.373s)
[469.956698] Starting dynamic subtest: pipe-C-eDP-1
[473.123967] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.244s)
[473.126860] Subtest compare-crc-sanitycheck-nv12: SUCCESS (9.633s)
[474.401975] [116/161] (632s left) kms_pipe_crc_basic (hang-read-crc)
[474.900729] Starting subtest: hang-read-crc
[474.908333] Starting dynamic subtest: pipe-A-eDP-1
[480.062750] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.136s)
[480.151330] Starting dynamic subtest: pipe-B-eDP-1
[486.449842] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.378s)
[486.538130] Starting dynamic subtest: pipe-C-eDP-1
[491.802467] Dynamic subtest pipe-C-eDP-1: SUCCESS (5.344s)
[491.819355] Subtest hang-read-crc: SUCCESS (16.907s)
[493.068629] [117/161] (613s left) kms_pipe_crc_basic (nonblocking-crc)
[493.574329] Starting subtest: nonblocking-crc
[493.579402] Starting dynamic subtest: pipe-A-eDP-1
[495.640696] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.058s)
[495.739599] Starting dynamic subtest: pipe-B-eDP-1
[498.151366] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.492s)
[498.240652] Starting dynamic subtest: pipe-C-eDP-1
[500.554477] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.389s)
[500.557749] Subtest nonblocking-crc: SUCCESS (6.986s)
[501.852119] [118/161] (605s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[502.346459] Starting subtest: nonblocking-crc-frame-sequence
[502.357775] Starting dynamic subtest: pipe-A-eDP-1
[504.439630] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.082s)
[504.538831] Starting dynamic subtest: pipe-B-eDP-1
[507.733072] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.272s)
[507.827309] Starting dynamic subtest: pipe-C-eDP-1
[510.948093] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.198s)
[510.951118] Subtest nonblocking-crc-frame-sequence: SUCCESS (8.600s)
[511.955112] [119/161] (595s left) kms_pipe_crc_basic (read-crc)
[512.452259] Starting subtest: read-crc
[512.464278] Starting dynamic subtest: pipe-A-eDP-1
[514.472899] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.014s)
[514.567546] Starting dynamic subtest: pipe-B-eDP-1
[516.963678] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.473s)
[517.057546] Starting dynamic subtest: pipe-C-eDP-1
[519.300357] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.322s)
[519.311484] Subtest read-crc: SUCCESS (6.859s)
[520.581959] [120/161] (587s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[521.091126] Starting subtest: read-crc-frame-sequence
[521.096073] Starting dynamic subtest: pipe-A-eDP-1
[523.117440] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.017s)
[523.208636] Starting dynamic subtest: pipe-B-eDP-1
[525.599425] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.469s)
[525.694753] Starting dynamic subtest: pipe-C-eDP-1
[528.724560] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.102s)
[528.727518] Subtest read-crc-frame-sequence: SUCCESS (7.639s)
[529.730697] [121/161] (578s left) kms_pm_backlight (basic-brightness)
[530.251713] Starting subtest: basic-brightness
[530.741839] Starting dynamic subtest: eDP-1
[531.209928] Dynamic subtest eDP-1: SUCCESS (0.477s)
[531.213561] Subtest basic-brightness: SUCCESS (0.966s)
[532.436074] [122/161] (576s left) kms_pm_rpm (basic-pci-d3-state)
[534.956605] Starting subtest: basic-pci-d3-state
[536.914761] Subtest basic-pci-d3-state: SUCCESS (1.958s)
[537.475960] [123/161] (571s left) kms_pm_rpm (basic-rte)
[537.855231] Starting subtest: basic-rte
[541.794394] Subtest basic-rte: SUCCESS (3.930s)
[544.124782] [124/161] (565s left) kms_psr (psr-primary-page-flip)
[544.621159] Starting subtest: psr-primary-page-flip
[544.631471] Starting dynamic subtest: eDP-1
[547.420997] Dynamic subtest eDP-1: SUCCESS (2.793s)
[547.431092] Subtest psr-primary-page-flip: SUCCESS (2.805s)
[548.710122] [125/161] (561s left) kms_psr (psr-cursor-plane-move)
[549.210972] Starting subtest: psr-cursor-plane-move
[549.221708] Starting dynamic subtest: eDP-1
[552.571723] Dynamic subtest eDP-1: SUCCESS (3.348s)
[552.574590] Subtest psr-cursor-plane-move: SUCCESS (3.360s)
[553.588284] [126/161] (557s left) kms_psr (psr-sprite-plane-onoff)
[554.087694] Starting subtest: psr-sprite-plane-onoff
[554.098594] Starting dynamic subtest: eDP-1
[556.824457] Dynamic subtest eDP-1: SUCCESS (2.724s)
[556.827430] Subtest psr-sprite-plane-onoff: SUCCESS (2.736s)
[558.133036] [127/161] (552s left) kms_psr (psr-primary-mmap-gtt)
[558.626869] Starting subtest: psr-primary-mmap-gtt
[558.637485] Starting dynamic subtest: eDP-1
[561.395528] Dynamic subtest eDP-1: SUCCESS (2.760s)
[561.406931] Subtest psr-primary-mmap-gtt: SUCCESS (2.773s)
[562.674218] [128/161] (548s left) kms_setmode (basic-clone-single-crtc)
[563.073501] Starting subtest: basic-clone-single-crtc
[563.081616] Subtest basic-clone-single-crtc: SKIP (0.001s)
[563.565334] [129/161] (548s left) i915_pm_rps (basic-api)
[563.989421] Starting subtest: basic-api
[563.995603] Subtest basic-api: SKIP (0.004s)
[564.493712] [130/161] (547s left) prime_self_import (basic-llseek-bad)
[564.864570] Starting subtest: basic-llseek-bad
[564.880407] Subtest basic-llseek-bad: SUCCESS (0.014s)
[565.382258] [131/161] (547s left) prime_self_import (basic-llseek-size)
[565.766605] Starting subtest: basic-llseek-size
[565.781555] Subtest basic-llseek-size: SUCCESS (0.022s)
[566.283891] [132/161] (546s left) prime_self_import (basic-with_fd_dup)
[566.661971] Starting subtest: basic-with_fd_dup
[566.684859] Subtest basic-with_fd_dup: SUCCESS (0.021s)
[567.182844] [133/161] (546s left) prime_self_import (basic-with_one_bo)
[567.557380] Starting subtest: basic-with_one_bo
[567.579368] Subtest basic-with_one_bo: SUCCESS (0.022s)
[568.087531] [134/161] (545s left) prime_self_import (basic-with_one_bo_two_files)
[568.460158] Starting subtest: basic-with_one_bo_two_files
[568.481714] Subtest basic-with_one_bo_two_files: SUCCESS (0.019s)
[568.986159] [135/161] (545s left) prime_self_import (basic-with_two_bos)
[569.365266] Starting subtest: basic-with_two_bos
[569.379655] Subtest basic-with_two_bos: SUCCESS (0.021s)
[569.879280] [136/161] (544s left) prime_vgem (basic-fence-flip)
[570.369293] Starting subtest: basic-fence-flip
[571.851258] Subtest basic-fence-flip: SUCCESS (1.482s)
[572.421193] [137/161] (542s left) prime_vgem (basic-fence-mmap)
[572.871148] Starting subtest: basic-fence-mmap
[573.070764] Subtest basic-fence-mmap: SUCCESS (0.196s)
[573.569641] [138/161] (541s left) prime_vgem (basic-fence-read)
[574.013295] Starting subtest: basic-fence-read
[574.072674] Subtest basic-fence-read: SKIP (0.057s)
[574.589512] [139/161] (541s left) prime_vgem (basic-gtt)
[575.042584] Starting subtest: basic-gtt
[575.132157] Subtest basic-gtt: SUCCESS (0.089s)
[575.641798] [140/161] (540s left) prime_vgem (basic-read)
[576.098184] Starting subtest: basic-read
[576.156086] Subtest basic-read: SKIP (0.055s)
[576.669236] [141/161] (539s left) prime_vgem (basic-write)
[577.127766] Starting subtest: basic-write
[577.178887] Subtest basic-write: SKIP (0.055s)
[577.694848] [142/161] (539s left) vgem_basic (setversion)
[578.108232] Starting subtest: setversion
[578.114868] Subtest setversion: SUCCESS (0.000s)
[578.567346] [143/161] (538s left) vgem_basic (create)
[578.989723] Starting subtest: create
[578.992866] Subtest create: SUCCESS (0.002s)
[579.459438] [144/161] (538s left) vgem_basic (debugfs)
[579.871314] Starting subtest: debugfs
[579.874356] Subtest debugfs: SUCCESS (0.003s)
[580.340394] [145/161] (537s left) vgem_basic (dmabuf-export)
[580.754513] Starting subtest: dmabuf-export
[580.774705] Subtest dmabuf-export: SUCCESS (0.015s)
[581.262187] [146/161] (537s left) vgem_basic (dmabuf-fence)
[581.677888] Starting subtest: dmabuf-fence
[581.681263] Subtest dmabuf-fence: SUCCESS (0.003s)
[582.157766] [147/161] (536s left) vgem_basic (dmabuf-fence-before)
[582.576528] Starting subtest: dmabuf-fence-before
[582.584125] Subtest dmabuf-fence-before: SUCCESS (0.003s)
[583.049641] [148/161] (536s left) vgem_basic (dmabuf-mmap)
[583.467862] Starting subtest: dmabuf-mmap
[583.561640] Subtest dmabuf-mmap: SUCCESS (0.098s)
[584.020899] [149/161] (535s left) vgem_basic (mmap)
[584.435520] Starting subtest: mmap
[584.496328] Subtest mmap: SUCCESS (0.065s)
[584.960684] [150/161] (535s left) vgem_basic (second-client)
[585.392693] Starting subtest: second-client
[585.441510] Subtest second-client: SUCCESS (0.044s)
[585.906223] [151/161] (534s left) vgem_basic (sysfs)
[586.322359] Starting subtest: sysfs
[586.325706] Subtest sysfs: SUCCESS (0.004s)
[586.796380] [152/161] (534s left) core_hotunplug (unbind-rebind)
[587.499509] Starting subtest: unbind-rebind
[596.645061] Subtest unbind-rebind: SUCCESS (9.145s)
[597.073467] [153/161] (524s left) vgem_basic (unload)
[597.466897] Starting subtest: unload
[627.224210] Subtest unload: SUCCESS (29.729s)
[627.862266] [154/161] (494s left) i915_module_load (reload)
[628.247478] Starting subtest: reload
[642.897466] Subtest reload: SUCCESS (14.622s)
[643.389336] [155/161] (479s left) gem_lmem_swapping (basic)
[643.984360] Subtest basic: SKIP (0.000s)
[644.461290] [156/161] (478s left) gem_lmem_swapping (parallel-random-engines)
[644.886692] Subtest parallel-random-engines: SKIP (0.000s)
[645.339515] [157/161] (477s left) gem_lmem_swapping (random-engines)
[645.770588] Subtest random-engines: SKIP (0.000s)
[646.206580] [158/161] (477s left) gem_lmem_swapping (verify-random)
[646.634085] Subtest verify-random: SKIP (0.000s)
[647.058062] [159/161] (476s left) i915_pm_rpm (module-reload)
[650.450361] Starting subtest: module-reload
[684.254205] Subtest module-reload: SUCCESS (33.798s)
[684.514264] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[684.526960] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[684.549546] [160/161] (439s left) i915_selftest (live)
[684.960416] Starting subtest: live
[684.971424] Starting dynamic subtest: sanitycheck
[695.476118] Dynamic subtest sanitycheck: SUCCESS (10.502s)
[695.537598] Starting dynamic subtest: uncore
[705.861442] Dynamic subtest uncore: SUCCESS (10.375s)
[705.933229] Starting dynamic subtest: workarounds
[719.019280] Dynamic subtest workarounds: SUCCESS (13.149s)
[719.086989] Starting dynamic subtest: gt_engines
[732.063635] Dynamic subtest gt_engines: SUCCESS (13.009s)
[732.131579] Starting dynamic subtest: gt_timelines
[776.816545] Dynamic subtest gt_timelines: SUCCESS (44.768s)
[776.882934] Starting dynamic subtest: gt_contexts
[787.395948] Dynamic subtest gt_contexts: SUCCESS (10.570s)
[787.461075] Starting dynamic subtest: gt_lrc
[801.605418] Dynamic subtest gt_lrc: SUCCESS (14.199s)
[801.670315] Starting dynamic subtest: gt_mocs
[813.232789] Dynamic subtest gt_mocs: SUCCESS (11.619s)
[813.300360] Starting dynamic subtest: gt_pm
[825.863485] Dynamic subtest gt_pm: SUCCESS (12.621s)
[825.933065] Starting dynamic subtest: gt_heartbeat
[836.384779] Dynamic subtest gt_heartbeat: SUCCESS (10.506s)
[836.452816] Starting dynamic subtest: gt_tlb
[848.013827] Dynamic subtest gt_tlb: SUCCESS (11.622s)
[848.078773] Starting dynamic subtest: requests
[868.868823] Dynamic subtest requests: SUCCESS (20.821s)
[868.933651] Starting dynamic subtest: migrate
[883.417332] Dynamic subtest migrate: SUCCESS (14.564s)
[883.485619] Starting dynamic subtest: active
[893.908248] Dynamic subtest active: SUCCESS (10.471s)
[893.997763] Starting dynamic subtest: objects
[909.138469] Dynamic subtest objects: SUCCESS (15.166s)
[909.206315] Starting dynamic subtest: mman
[931.717369] Dynamic subtest mman: SUCCESS (22.574s)
[931.800399] Starting dynamic subtest: dmabuf
[942.760530] Dynamic subtest dmabuf: SUCCESS (11.032s)
[942.840678] Starting dynamic subtest: vma
[954.434200] Dynamic subtest vma: SUCCESS (11.634s)
[954.531611] Starting dynamic subtest: coherency
[982.846234] Dynamic subtest coherency: SUCCESS (28.408s)
[982.920232] Starting dynamic subtest: gtt
[1001.784285] Dynamic subtest gtt: SUCCESS (18.930s)
[1001.849282] Starting dynamic subtest: gem
[1013.563339] Dynamic subtest gem: SUCCESS (11.770s)
[1013.629246] Starting dynamic subtest: evict
[1032.774590] Dynamic subtest evict: SUCCESS (19.200s)
[1032.844682] Starting dynamic subtest: hugepages
[1050.630473] Dynamic subtest hugepages: SUCCESS (17.846s)
[1050.710337] Starting dynamic subtest: gem_contexts
[1092.860225] Dynamic subtest gem_contexts: SUCCESS (42.220s)
[1092.924856] Starting dynamic subtest: client
[1103.584409] Dynamic subtest client: SUCCESS (10.714s)
[1103.650748] Starting dynamic subtest: gem_migrate
[1113.502712] 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 [1171] via Terminated, terminating children
[1116.497675] Closing watchdogs
Done.