[57.596528] Initializing watchdogs
[57.596664]   /dev/watchdog0
[57.612288] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[57.616998] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.619554] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.623668] [001/133] (960s left) perf_pmu (frequency)
[57.691972] Starting subtest: frequency
[57.694776] Starting dynamic subtest: gt0
[59.875641] Dynamic subtest gt0: SUCCESS (2.161s)
[59.886338] Starting dynamic subtest: idle-gt0
[60.382722] Dynamic subtest idle-gt0: SUCCESS (0.504s)
[60.391651] Starting dynamic subtest: gt1
[64.894442] Dynamic subtest gt1: SUCCESS (4.490s)
[64.901333] Starting dynamic subtest: idle-gt1
[65.432000] Dynamic subtest idle-gt1: SUCCESS (0.535s)
[65.434699] Subtest frequency: SUCCESS (7.702s)
[65.540301] [002/133] (952s left) kms_frontbuffer_tracking (psr-farfromfence-mmap-gtt)
[65.647321] Starting subtest: psr-farfromfence-mmap-gtt
[65.651331] Subtest psr-farfromfence-mmap-gtt: SKIP (0.000s)
[65.738151] [003/133] (952s left) gem_exec_store (cachelines)
[65.797992] Starting subtest: cachelines
[65.800373] Starting dynamic subtest: rcs0
[65.886245] Dynamic subtest rcs0: SUCCESS (0.090s)
[65.895517] Starting dynamic subtest: bcs0
[65.960554] Dynamic subtest bcs0: SUCCESS (0.072s)
[65.969809] Starting dynamic subtest: vcs0
[66.009601] Dynamic subtest vcs0: SUCCESS (0.048s)
[66.018254] Starting dynamic subtest: vcs1
[66.063977] Dynamic subtest vcs1: SUCCESS (0.053s)
[66.073080] Starting dynamic subtest: vecs0
[66.113777] Dynamic subtest vecs0: SUCCESS (0.049s)
[66.122208] Starting dynamic subtest: ccs0
[66.168751] Dynamic subtest ccs0: SUCCESS (0.054s)
[66.171730] Subtest cachelines: SUCCESS (0.373s)
[66.345328] [004/133] (951s left) gem_exec_basic (basic)
[66.397619] Starting subtest: basic
[66.399868] Starting dynamic subtest: rcs0-smem
[66.402183] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[66.411568] Starting dynamic subtest: bcs0-smem
[66.413462] Dynamic subtest bcs0-smem: SUCCESS (0.001s)
[66.420051] Starting dynamic subtest: vcs0-smem
[66.421927] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[66.428419] Starting dynamic subtest: vcs1-smem
[66.430304] Dynamic subtest vcs1-smem: SUCCESS (0.001s)
[66.436974] Starting dynamic subtest: vecs0-smem
[66.438904] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[66.445393] Starting dynamic subtest: ccs0-smem
[66.447424] Dynamic subtest ccs0-smem: SUCCESS (0.001s)
[66.449778] Subtest basic: SUCCESS (0.012s)
[67.960692] [005/133] (950s left) gem_wait (write-busy)
[68.061718] Starting subtest: write-busy
[68.064120] Starting dynamic subtest: all-engines
[68.695884] Dynamic subtest all-engines: SUCCESS (0.626s)
[68.703850] Starting dynamic subtest: rcs0
[69.238066] Dynamic subtest rcs0: SUCCESS (0.547s)
[69.251348] Starting dynamic subtest: bcs0
[69.788409] Dynamic subtest bcs0: SUCCESS (0.549s)
[69.801999] Starting dynamic subtest: vcs0
[70.933269] Dynamic subtest vcs0: SUCCESS (1.141s)
[70.945893] Starting dynamic subtest: vcs1
[72.917557] Dynamic subtest vcs1: SUCCESS (1.981s)
[72.931311] Starting dynamic subtest: vecs0
[74.901037] Dynamic subtest vecs0: SUCCESS (1.980s)
[74.913944] Starting dynamic subtest: ccs0
[75.450549] Dynamic subtest ccs0: SUCCESS (0.550s)
[75.453523] Subtest write-busy: SUCCESS (7.379s)
[75.661717] [FACT gem_wait (write-busy)] new: kernel.kmod_is_loaded.vgem: true
[75.665415] [006/133] (942s left) kms_dither (fb-8bpc-vs-panel-6bpc)
[75.760703] Starting subtest: fb-8bpc-vs-panel-6bpc
[75.762978] Starting dynamic subtest: pipe-A-eDP-1
[77.179410] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.419s)
[77.181697] Subtest fb-8bpc-vs-panel-6bpc: SUCCESS (1.420s)
[77.982626] [007/133] (940s left) kms_color_pipeline (plane-lut1d)
[78.074952] Starting subtest: plane-lut1d
[78.213109] Starting dynamic subtest: pipe-A-plane-0
[78.553769] Dynamic subtest pipe-A-plane-0: FAIL (0.175s)
[78.563168] Starting dynamic subtest: pipe-A-plane-1
[78.780148] Dynamic subtest pipe-A-plane-1: FAIL (0.165s)
[78.789127] Starting dynamic subtest: pipe-A-plane-2
[79.011706] Dynamic subtest pipe-A-plane-2: FAIL (0.166s)
[79.976410] Starting dynamic subtest: pipe-B-plane-0
[80.414247] Dynamic subtest pipe-B-plane-0: FAIL (0.163s)
[80.423351] Starting dynamic subtest: pipe-B-plane-1
[80.728304] Dynamic subtest pipe-B-plane-1: FAIL (0.167s)
[80.740838] Starting dynamic subtest: pipe-B-plane-2
[81.031209] Dynamic subtest pipe-B-plane-2: FAIL (0.166s)
[81.704092] Starting dynamic subtest: pipe-C-plane-0
[82.174540] Dynamic subtest pipe-C-plane-0: FAIL (0.129s)
[82.183130] Starting dynamic subtest: pipe-C-plane-1
[82.576412] Dynamic subtest pipe-C-plane-1: FAIL (0.133s)
[82.585664] Starting dynamic subtest: pipe-C-plane-2
[82.930281] Dynamic subtest pipe-C-plane-2: FAIL (0.627s)
[83.844770] Starting dynamic subtest: pipe-D-plane-0
[84.425754] Dynamic subtest pipe-D-plane-0: FAIL (0.140s)
[84.435084] Starting dynamic subtest: pipe-D-plane-1
[84.870160] Dynamic subtest pipe-D-plane-1: FAIL (0.608s)
[84.877662] Starting dynamic subtest: pipe-D-plane-2
[85.284514] Dynamic subtest pipe-D-plane-2: FAIL (0.540s)
[85.431255] Subtest plane-lut1d: FAIL (7.353s)
[86.233422] [008/133] (932s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[86.343670] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[87.168600] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.826s)
[87.341572] [009/133] (931s left) kms_frontbuffer_tracking (psr-modesetfrombusy)
[87.443269] Starting subtest: psr-modesetfrombusy
[93.619780] Subtest psr-modesetfrombusy: SUCCESS (6.179s)
[93.748533] [010/133] (924s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-blt)
[93.836821] Starting subtest: psr-1p-primscrn-pri-indfb-draw-blt
[101.027116] Subtest psr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.190s)
[101.152523] [011/133] (917s left) kms_lease (lease-invalid-connector)
[101.238852] Starting subtest: lease-invalid-connector
[101.241067] Starting dynamic subtest: pipe-A-eDP-1
[101.243479] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[101.251721] Starting dynamic subtest: pipe-B-eDP-1
[101.253840] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[101.261107] Starting dynamic subtest: pipe-C-eDP-1
[101.262939] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[101.270353] Starting dynamic subtest: pipe-D-eDP-1
[101.272537] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[101.274691] Subtest lease-invalid-connector: SUCCESS (0.004s)
[101.314990] [012/133] (917s left) kms_flip (2x-plain-flip-fb-recreate)
[101.421745] Starting subtest: 2x-plain-flip-fb-recreate
[101.429883] Subtest 2x-plain-flip-fb-recreate: SKIP (0.011s)
[101.512637] [013/133] (917s left) gen9_exec_parse (unaligned-access)
[101.583423] Subtest unaligned-access: SKIP (0.000s)
[101.638300] [014/133] (917s left) kms_plane_multiple (tiling-yf)
[101.738312] Starting subtest: tiling-yf
[101.742841] Subtest tiling-yf: SKIP (0.000s)
[101.831185] [015/133] (916s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset)
[101.916315] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset
[101.918653] Starting dynamic subtest: A-eDP1
[105.503062] Dynamic subtest A-eDP1: SUCCESS (3.585s)
[105.512672] Starting dynamic subtest: B-eDP1
[108.699332] Dynamic subtest B-eDP1: SUCCESS (3.195s)
[108.708540] Starting dynamic subtest: C-eDP1
[111.900433] Dynamic subtest C-eDP1: SUCCESS (3.201s)
[111.909670] Starting dynamic subtest: D-eDP1
[115.017662] Dynamic subtest D-eDP1: SUCCESS (3.115s)
[115.020068] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (13.102s)
[115.811852] [016/133] (902s left) gem_exec_schedule (semaphore-noskip)
[115.888407] Starting subtest: semaphore-noskip
[116.288740] Subtest semaphore-noskip: SUCCESS (0.407s)
[116.616286] [017/133] (902s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-render)
[116.708496] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-render
[120.840631] Subtest fbc-1p-offscreen-pri-indfb-draw-render: SUCCESS (4.133s)
[122.028867] [018/133] (896s left) kms_psr (psr-cursor-mmap-cpu)
[122.128153] Starting subtest: psr-cursor-mmap-cpu
[122.130506] Starting dynamic subtest: eDP-1
[123.816673] Dynamic subtest eDP-1: SUCCESS (1.689s)
[123.818965] Subtest psr-cursor-mmap-cpu: SUCCESS (1.690s)
[124.673603] [019/133] (894s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size)
[124.795375] Starting subtest: basic-busy-flip-before-cursor-varying-size
[124.799904] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s)
[124.950037] [020/133] (893s left) perf (unprivileged-single-ctx-counters)
[125.057241] Starting subtest: unprivileged-single-ctx-counters
[125.061425] Subtest unprivileged-single-ctx-counters: SKIP (0.000s)
[125.114739] [021/133] (893s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[125.216522] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip
[125.220824] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[125.304501] [022/133] (893s left) syncobj_wait (wait-all-delayed-signal)
[125.358074] Starting subtest: wait-all-delayed-signal
[125.460044] Subtest wait-all-delayed-signal: SUCCESS (0.102s)
[125.593090] [023/133] (893s left) sw_sync (sync_busy_fork)
[125.629649] Starting subtest: sync_busy_fork
[126.641469] Subtest sync_busy_fork: SUCCESS (1.012s)
[126.702227] [024/133] (892s left) kms_big_fb (4-tiled-16bpp-rotate-0)
[126.812789] Starting subtest: 4-tiled-16bpp-rotate-0
[128.156353] Subtest 4-tiled-16bpp-rotate-0: SUCCESS (1.344s)
[128.279350] [025/133] (890s left) gem_pxp (hw-rejects-pxp-context)
[128.413420] Starting subtest: hw-rejects-pxp-context
[128.417796] Subtest hw-rejects-pxp-context: SKIP (0.000s)
[128.515960] [026/133] (890s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-sf)
[128.611072] Starting subtest: pr-overlay-plane-move-continuous-exceed-sf
[128.614378] Subtest pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[128.676425] [027/133] (890s left) kms_psr (pr-sprite-plane-move)
[128.773069] Starting subtest: pr-sprite-plane-move
[128.776857] Subtest pr-sprite-plane-move: SKIP (0.000s)
[128.842551] [028/133] (890s left) kms_plane_alpha_blend (coverage-vs-premult-vs-constant)
[128.932099] Starting subtest: coverage-vs-premult-vs-constant
[129.210657] Starting dynamic subtest: pipe-A-eDP-1
[129.792703] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.579s)
[129.801527] Starting dynamic subtest: pipe-D-eDP-1
[131.085199] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.293s)
[131.087577] Subtest coverage-vs-premult-vs-constant: SUCCESS (2.153s)
[131.947543] [029/133] (887s left) sw_sync (sync_multi_timeline_wait)
[131.985666] Starting subtest: sync_multi_timeline_wait
[131.988088] Subtest sync_multi_timeline_wait: SUCCESS (0.000s)
[132.044804] [030/133] (887s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-onoff)
[132.138866] Starting subtest: fbc-1p-primscrn-spr-indfb-onoff
[136.106634] Subtest fbc-1p-primscrn-spr-indfb-onoff: SUCCESS (3.971s)
[137.248574] [031/133] (882s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[137.349294] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[137.353198] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[137.447543] [032/133] (881s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation)
[137.541066] Starting subtest: plane-scaler-unity-scaling-with-rotation
[137.543045] Starting dynamic subtest: pipe-A
[137.767240] Dynamic subtest pipe-A: SUCCESS (0.228s)
[137.775153] Starting dynamic subtest: pipe-B
[139.031048] Dynamic subtest pipe-B: SUCCESS (1.263s)
[139.038809] Starting dynamic subtest: pipe-C
[140.368329] Dynamic subtest pipe-C: SUCCESS (1.336s)
[140.381088] Starting dynamic subtest: pipe-D
[141.697144] Dynamic subtest pipe-D: SUCCESS (1.328s)
[141.699426] Subtest plane-scaler-unity-scaling-with-rotation: SUCCESS (4.159s)
[142.873893] [033/133] (876s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-move)
[142.981321] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-move
[142.985304] Subtest fbcpsr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[143.073597] [034/133] (876s left) api_intel_bb (crc32)
[143.127991] Starting subtest: crc32
[143.130257] Starting dynamic subtest: rcs0-smem0
[143.443221] Dynamic subtest rcs0-smem0: SUCCESS (0.314s)
[143.454907] Starting dynamic subtest: bcs0-smem0
[143.791062] Dynamic subtest bcs0-smem0: SUCCESS (0.345s)
[143.801882] Starting dynamic subtest: vcs0-smem0
[144.150478] Dynamic subtest vcs0-smem0: SUCCESS (0.357s)
[144.161273] Starting dynamic subtest: vcs1-smem0
[144.550097] Dynamic subtest vcs1-smem0: SUCCESS (0.396s)
[144.560278] Starting dynamic subtest: vecs0-smem0
[144.860017] Dynamic subtest vecs0-smem0: SUCCESS (0.306s)
[144.870224] Starting dynamic subtest: ccs0-smem0
[145.172232] Dynamic subtest ccs0-smem0: SUCCESS (0.309s)
[145.175051] Subtest crc32: SUCCESS (2.046s)
[145.290274] [035/133] (874s left) gem_unref_active_buffers
[145.501232] [036/133] (874s left) gem_exec_reloc (basic-cpu-read-active)
[145.556712] Subtest basic-cpu-read-active: SKIP (0.000s)
[145.622873] [037/133] (873s left) gem_close_race (basic-process)
[145.692256] Starting subtest: basic-process
[145.748982] Subtest basic-process: SUCCESS (0.057s)
[145.934810] [038/133] (873s left) kms_vblank (ts-continuation-modeset-rpm)
[146.032969] Starting subtest: ts-continuation-modeset-rpm
[146.034970] Starting dynamic subtest: pipe-A-eDP-1
[148.560629] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.527s)
[148.574080] Starting dynamic subtest: pipe-D-eDP-1
[150.815895] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.255s)
[150.818194] Subtest ts-continuation-modeset-rpm: SUCCESS (4.785s)
[151.591985] [039/133] (868s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions-varying-size)
[151.733488] Starting subtest: cursorA-vs-flipA-atomic-transitions-varying-size
[162.889591] Subtest cursorA-vs-flipA-atomic-transitions-varying-size: SUCCESS (11.155s)
[163.733896] [040/133] (855s left) gem_sync (basic-store-all)
[163.817830] Starting subtest: basic-store-all
[166.947293] Subtest basic-store-all: SUCCESS (3.142s)
[167.130801] [041/133] (852s left) kms_plane (pixel-format-linear-modifier)
[167.214179] Starting subtest: pixel-format-linear-modifier
[167.240727] Starting dynamic subtest: pipe-A-plane-0
[170.806339] Dynamic subtest pipe-A-plane-0: SUCCESS (3.566s)
[170.817998] Starting dynamic subtest: pipe-A-plane-3
[172.495414] Dynamic subtest pipe-A-plane-3: SUCCESS (1.688s)
[172.505078] Starting dynamic subtest: pipe-A-plane-5
[172.706723] Dynamic subtest pipe-A-plane-5: SUCCESS (0.210s)
[173.824666] Starting dynamic subtest: pipe-B-plane-0
[177.373453] Dynamic subtest pipe-B-plane-0: SUCCESS (3.555s)
[177.383981] Starting dynamic subtest: pipe-B-plane-3
[179.164348] Dynamic subtest pipe-B-plane-3: SUCCESS (1.787s)
[179.172498] Starting dynamic subtest: pipe-B-plane-5
[179.384830] Dynamic subtest pipe-B-plane-5: SUCCESS (0.220s)
[179.694084] Subtest pixel-format-linear-modifier: SUCCESS (12.481s)
[180.488902] [042/133] (839s left) kms_flip (wf_vblank-ts-check)
[180.586831] Starting subtest: wf_vblank-ts-check
[180.589264] Starting dynamic subtest: A-eDP1
[189.536468] Dynamic subtest A-eDP1: SUCCESS (8.952s)
[189.545100] Starting dynamic subtest: B-eDP1
[198.467915] Dynamic subtest B-eDP1: SUCCESS (8.930s)
[198.478126] Starting dynamic subtest: C-eDP1
[207.395803] Dynamic subtest C-eDP1: SUCCESS (8.924s)
[207.403492] Starting dynamic subtest: D-eDP1
[216.330664] Dynamic subtest D-eDP1: SUCCESS (8.930s)
[216.335207] Subtest wf_vblank-ts-check: SUCCESS (35.749s)
[217.542952] [043/133] (802s left) i915_selftest (perf)
[220.848219] Starting subtest: perf
[220.850917] Starting dynamic subtest: engine_cs
[225.193883] Dynamic subtest engine_cs: SUCCESS (4.345s)
[225.201985] Starting dynamic subtest: request
[232.382331] Dynamic subtest request: SUCCESS (7.186s)
[232.390546] Starting dynamic subtest: migrate
[236.686679] Dynamic subtest migrate: SUCCESS (4.302s)
[236.693284] Starting dynamic subtest: region
[242.272097] Dynamic subtest region: SUCCESS (5.582s)
[242.274907] Subtest perf: SUCCESS (21.424s)
[242.302272] [FACT i915_selftest (perf)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[242.302976] [FACT i915_selftest (perf)] deleted: kernel.kmod_is_loaded.i915: true
[242.306148] [044/133] (777s left) sysfs_heartbeat_interval (precise)
[243.831997] Starting subtest: precise
[243.834499] Starting dynamic subtest: vecs0
[251.923060] Dynamic subtest vecs0: SUCCESS (8.087s)
[251.933447] Starting dynamic subtest: ccs0
[254.751479] Dynamic subtest ccs0: SUCCESS (2.828s)
[254.761928] Starting dynamic subtest: vcs0
[261.914696] Dynamic subtest vcs0: SUCCESS (7.158s)
[261.924676] Starting dynamic subtest: bcs0
[262.922148] Dynamic subtest bcs0: SUCCESS (1.008s)
[262.933292] Starting dynamic subtest: rcs0
[263.962643] Dynamic subtest rcs0: SUCCESS (1.041s)
[263.970385] Starting dynamic subtest: vcs1
[271.888271] Dynamic subtest vcs1: SUCCESS (7.924s)
[271.890822] Subtest precise: SUCCESS (28.056s)
[271.939347] [FACT sysfs_heartbeat_interval (precise)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[271.940626] [FACT sysfs_heartbeat_interval (precise)] new: kernel.kmod_is_loaded.i915: true
[271.944655] [045/133] (747s left) kms_psr (fbc-pr-primary-mmap-gtt)
[272.044942] Starting subtest: fbc-pr-primary-mmap-gtt
[272.049177] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[272.115291] [046/133] (747s left) core_setmaster (master-drop-set-root)
[272.186817] Starting subtest: master-drop-set-root
[272.211986] Subtest master-drop-set-root: SUCCESS (0.025s)
[272.257213] [047/133] (747s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[272.361570] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[272.365811] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[272.455323] [048/133] (747s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[272.522665] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[272.624603] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.102s)
[272.701880] [049/133] (747s left) i915_hangman (engine-engine-hang)
[272.775411] Starting subtest: engine-engine-hang
[272.777601] Starting dynamic subtest: rcs0
[273.843486] Dynamic subtest rcs0: SUCCESS (1.066s)
[273.854530] Starting dynamic subtest: bcs0
[275.891039] Dynamic subtest bcs0: SUCCESS (2.045s)
[275.901758] Starting dynamic subtest: vcs0
[277.876265] Dynamic subtest vcs0: SUCCESS (1.982s)
[277.887225] Starting dynamic subtest: vcs1
[279.860489] Dynamic subtest vcs1: SUCCESS (1.982s)
[279.873074] Starting dynamic subtest: vecs0
[281.842496] Dynamic subtest vecs0: SUCCESS (1.979s)
[281.852129] Starting dynamic subtest: ccs0
[283.891438] Dynamic subtest ccs0: SUCCESS (2.046s)
[283.894090] Subtest engine-engine-hang: SUCCESS (11.117s)
[283.998637] [050/133] (736s left) kms_chamelium_color (ctm-limited-range)
[284.082412] Subtest ctm-limited-range: SKIP (0.000s)
[284.128462] [051/133] (736s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-onoff)
[284.224405] Starting subtest: psr-2p-primscrn-spr-indfb-onoff
[284.228357] Subtest psr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[284.326925] [052/133] (735s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-wc)
[284.424634] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-wc
[284.428734] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[284.508600] [053/133] (735s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-pgflip-blt)
[284.610531] Starting subtest: fbcpsr-1p-primscrn-indfb-pgflip-blt
[291.933088] Subtest fbcpsr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.325s)
[292.057088] [054/133] (728s left) gem_create (create-size-update)
[292.120724] Starting subtest: create-size-update
[292.122597] Subtest create-size-update: SUCCESS (0.000s)
[292.332701] [055/133] (727s left) i915_pm_rpm (gem-execbuf)
[294.074689] Starting subtest: gem-execbuf
[294.077063] Starting dynamic subtest: smem0
[295.976739] Dynamic subtest smem0: SUCCESS (1.905s)
[295.979063] Subtest gem-execbuf: SUCCESS (1.906s)
[296.073453] [056/133] (724s left) gem_eio (suspend)
[296.271751] Starting subtest: suspend
[309.251661] Subtest suspend: SUCCESS (12.978s)
[309.392031] [057/133] (710s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs)
[309.496190] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs
[309.498349] Starting dynamic subtest: pipe-A-eDP-1
[309.522934] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[309.534054] Starting dynamic subtest: pipe-B-eDP-1
[309.557403] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[309.565345] Starting dynamic subtest: pipe-C-eDP-1
[309.589169] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[309.597288] Starting dynamic subtest: pipe-D-eDP-1
[309.621894] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[309.626042] Subtest random-ccs-data-4-tiled-dg2-rc-ccs: SKIP (0.004s)
[311.804432] [058/133] (708s left) kms_cursor_crc (cursor-onscreen-512x512)
[312.111986] Starting subtest: cursor-onscreen-512x512
[312.116325] Subtest cursor-onscreen-512x512: SKIP (0.001s)
[312.274407] [059/133] (708s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[312.356927] Starting subtest: plane-upscale-20x20-with-rotation
[312.359248] Starting dynamic subtest: pipe-A
[312.582173] Dynamic subtest pipe-A: SUCCESS (0.227s)
[312.591006] Starting dynamic subtest: pipe-B
[313.872492] Dynamic subtest pipe-B: SUCCESS (1.289s)
[313.881893] Starting dynamic subtest: pipe-C
[315.195879] Dynamic subtest pipe-C: SUCCESS (1.322s)
[315.205023] Starting dynamic subtest: pipe-D
[316.530621] Dynamic subtest pipe-D: SUCCESS (1.334s)
[316.532942] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.176s)
[317.716812] [060/133] (702s left) gem_exec_fence (invalid-fence-array)
[318.137057] Starting subtest: invalid-fence-array
[318.139697] Subtest invalid-fence-array: SUCCESS (0.002s)
[318.980756] [061/133] (701s left) kms_pm_rpm (basic-rte)
[319.018106] Starting subtest: basic-rte
[322.593497] Subtest basic-rte: SUCCESS (3.574s)
[324.393843] [062/133] (696s left) perf_pmu (busy-idle-no-semaphores)
[324.435500] Starting subtest: busy-idle-no-semaphores
[324.437700] Starting dynamic subtest: rcs0
[325.101915] Dynamic subtest rcs0: SUCCESS (0.667s)
[325.113366] Starting dynamic subtest: bcs0
[325.751590] Dynamic subtest bcs0: SUCCESS (0.647s)
[325.763645] Starting dynamic subtest: vcs0
[326.404571] Dynamic subtest vcs0: SUCCESS (0.650s)
[326.416558] Starting dynamic subtest: vcs1
[327.550272] Dynamic subtest vcs1: SUCCESS (1.142s)
[327.562185] Starting dynamic subtest: vecs0
[329.534352] Dynamic subtest vecs0: SUCCESS (1.981s)
[329.546713] Starting dynamic subtest: ccs0
[331.519728] Dynamic subtest ccs0: SUCCESS (1.983s)
[331.522594] Subtest busy-idle-no-semaphores: SUCCESS (7.086s)
[331.642301] [063/133] (688s left) syncobj_timeline (reset-during-wait-for-submit)
[331.709039] Starting subtest: reset-during-wait-for-submit
[331.812869] Subtest reset-during-wait-for-submit: SUCCESS (0.103s)
[331.894694] [064/133] (688s left) kms_rotation_crc (primary-y-tiled-reflect-x-180)
[331.990516] Starting subtest: primary-y-tiled-reflect-x-180
[332.050040] Subtest primary-y-tiled-reflect-x-180: SKIP (0.060s)
[332.157055] [065/133] (688s left) kms_prop_blob (blob-prop-lifetime)
[332.216402] Starting subtest: blob-prop-lifetime
[332.218750] Subtest blob-prop-lifetime: SUCCESS (0.001s)
[332.332080] [066/133] (688s left) kms_frontbuffer_tracking (fbc-suspend)
[332.421994] Starting subtest: fbc-suspend
[370.800364] Subtest fbc-suspend: SUCCESS (7.431s)
[372.015548] [067/133] (648s left) prime_mmap (test_correct_cpu_write)
[372.094698] Starting subtest: test_correct_cpu_write
[372.097070] Starting dynamic subtest: test_correct_cpu_write-smem
[372.099076] Dynamic subtest test_correct_cpu_write-smem: SUCCESS (0.000s)
[372.101089] Subtest test_correct_cpu_write: SUCCESS (0.001s)
[372.218394] [068/133] (648s left) kms_big_fb (y-tiled-addfb-size-overflow)
[372.309865] Starting subtest: y-tiled-addfb-size-overflow
[372.314342] Subtest y-tiled-addfb-size-overflow: SKIP (0.000s)
[372.410904] [069/133] (648s left) gem_exec_params (sol-reset-invalid)
[372.472534] Starting subtest: sol-reset-invalid
[372.477854] Subtest sol-reset-invalid: SUCCESS (0.003s)
[373.856898] [070/133] (647s left) gem_ctx_persistence (legacy-engines-hang)
[373.914112] Starting subtest: legacy-engines-hang
[373.916340] Starting dynamic subtest: render
[373.973970] Dynamic subtest render: SUCCESS (0.061s)
[373.986227] Starting dynamic subtest: bsd1
[374.065005] Dynamic subtest bsd1: SUCCESS (0.087s)
[374.082235] Starting dynamic subtest: bsd2
[374.164061] Dynamic subtest bsd2: SUCCESS (0.094s)
[374.176615] Starting dynamic subtest: blt
[374.254759] Dynamic subtest blt: SUCCESS (0.087s)
[374.269407] Starting dynamic subtest: vebox
[374.358263] Dynamic subtest vebox: SUCCESS (0.097s)
[374.361142] Subtest legacy-engines-hang: SUCCESS (0.441s)
[374.438724] [071/133] (646s left) gem_lmem_swapping (parallel-random-verify)
[374.485486] Subtest parallel-random-verify: SKIP (0.000s)
[374.531932] [072/133] (646s left) gem_ctx_bad_destroy (invalid-default-ctx)
[374.590050] Starting subtest: invalid-default-ctx
[374.592463] Subtest invalid-default-ctx: SUCCESS (0.000s)
[374.668211] [073/133] (646s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[374.769827] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-cpu
[374.775703] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[374.874699] [074/133] (646s left) gem_exec_schedule (preempt-other-chain)
[374.939435] Starting subtest: preempt-other-chain
[374.941316] Starting dynamic subtest: rcs0
[375.019365] Dynamic subtest rcs0: SUCCESS (0.083s)
[375.032154] Starting dynamic subtest: bcs0
[375.100078] Dynamic subtest bcs0: SUCCESS (0.079s)
[375.113180] Starting dynamic subtest: vcs0
[375.178106] Dynamic subtest vcs0: SUCCESS (0.076s)
[375.192523] Starting dynamic subtest: vcs1
[375.260682] Dynamic subtest vcs1: SUCCESS (0.080s)
[375.271700] Starting dynamic subtest: vecs0
[375.337009] Dynamic subtest vecs0: SUCCESS (0.074s)
[375.355689] Starting dynamic subtest: ccs0
[375.416063] Dynamic subtest ccs0: SUCCESS (0.077s)
[375.418842] Subtest preempt-other-chain: SUCCESS (0.475s)
[376.890853] [075/133] (644s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[376.995273] Starting subtest: extended-pageflip-modeset-hang-oldfb
[376.997460] Starting dynamic subtest: pipe-A
[384.937575] Dynamic subtest pipe-A: SUCCESS (7.889s)
[384.948104] Starting dynamic subtest: pipe-D
[393.543646] Dynamic subtest pipe-D: SUCCESS (8.582s)
[393.545921] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.477s)
[394.404911] [076/133] (626s left) gem_mmap_offset (isolation)
[394.463648] Starting subtest: isolation
[394.481441] Subtest isolation: SUCCESS (0.015s)
[394.585418] [077/133] (626s left) kms_dsc (dsc-with-formats)
[394.673105] Starting subtest: dsc-with-formats
[394.712606] Subtest dsc-with-formats: SKIP (0.001s)
[394.757384] [078/133] (626s left) kms_big_fb (x-tiled-16bpp-rotate-270)
[394.853090] Starting subtest: x-tiled-16bpp-rotate-270
[394.872731] Subtest x-tiled-16bpp-rotate-270: SKIP (0.019s)
[395.002961] [079/133] (626s left) kms_psr (fbc-psr-cursor-plane-move)
[395.089317] Starting subtest: fbc-psr-cursor-plane-move
[395.091600] Starting dynamic subtest: eDP-1
[395.096031] Dynamic subtest eDP-1: SKIP (0.000s)
[395.100370] Subtest fbc-psr-cursor-plane-move: SKIP (0.001s)
[395.161711] [080/133] (626s left) kms_concurrent (multi-plane-atomic-lowres)
[395.264173] Starting subtest: multi-plane-atomic-lowres
[395.266379] Starting dynamic subtest: pipe-A-eDP-1
[395.753741] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.488s)
[395.764196] Starting dynamic subtest: pipe-B-eDP-1
[397.048125] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.290s)
[397.058933] Starting dynamic subtest: pipe-C-eDP-1
[398.264242] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.214s)
[398.274285] Starting dynamic subtest: pipe-D-eDP-1
[399.510528] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.245s)
[399.512997] Subtest multi-plane-atomic-lowres: SUCCESS (4.242s)
[400.332644] [081/133] (621s left) syncobj_wait (wait-all-for-submit-snapshot)
[400.392578] Starting subtest: wait-all-for-submit-snapshot
[400.473073] Subtest wait-all-for-submit-snapshot: SUCCESS (0.082s)
[400.549200] [082/133] (620s left) kms_ccs (bad-rotation-90-y-tiled-gen12-mc-ccs)
[400.636058] Starting subtest: bad-rotation-90-y-tiled-gen12-mc-ccs
[400.638263] Starting dynamic subtest: pipe-A-eDP-1
[400.660826] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[400.672436] Starting dynamic subtest: pipe-B-eDP-1
[400.693834] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[400.703058] Starting dynamic subtest: pipe-C-eDP-1
[400.724439] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[400.735083] Starting dynamic subtest: pipe-D-eDP-1
[400.756999] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[400.761324] Subtest bad-rotation-90-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[402.906394] [083/133] (618s left) syncobj_timeline (invalid-transfer-illegal-handle)
[402.958467] Starting subtest: invalid-transfer-illegal-handle
[402.960436] Subtest invalid-transfer-illegal-handle: SUCCESS (0.000s)
[403.074139] [084/133] (618s left) kms_ccs (bad-rotation-90-4-tiled-dg2-mc-ccs)
[403.163169] Starting subtest: bad-rotation-90-4-tiled-dg2-mc-ccs
[403.165428] Starting dynamic subtest: pipe-A-eDP-1
[403.188077] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[403.199511] Starting dynamic subtest: pipe-B-eDP-1
[403.218056] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[403.226768] Starting dynamic subtest: pipe-C-eDP-1
[403.246337] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[403.254804] Starting dynamic subtest: pipe-D-eDP-1
[403.276215] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[403.280589] Subtest bad-rotation-90-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[405.428606] [085/133] (616s left) gem_pwrite (basic-self)
[405.498998] Subtest basic-self: SKIP (0.000s)
[405.563180] [086/133] (616s left) api_intel_bb (intel-bb-blit-y)
[405.615568] Starting subtest: intel-bb-blit-y
[409.011359] Subtest intel-bb-blit-y: SUCCESS (3.397s)
[409.158425] [087/133] (612s left) gem_ccs (block-multicopy-inplace)
[409.217603] Starting subtest: block-multicopy-inplace
[409.221797] Subtest block-multicopy-inplace: SKIP (0.000s)
[409.324310] [088/133] (612s left) kms_pm_rpm (package-g7)
[410.950791] Starting subtest: package-g7
[410.955706] Subtest package-g7: SKIP (0.000s)
[411.581477] [089/133] (610s left) prime_mmap_coherency (ioctl-errors)
[411.651700] Starting subtest: ioctl-errors
[425.660549] Subtest ioctl-errors: SUCCESS (14.007s)
[425.918415] [090/133] (595s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[426.087277] Starting subtest: cursor-vs-flip-legacy
[455.437145] Subtest cursor-vs-flip-legacy: SUCCESS (29.339s)
[456.301306] [091/133] (565s left) kms_universal_plane (cursor-fb-leak)
[456.401991] Starting subtest: cursor-fb-leak
[456.404193] Starting dynamic subtest: pipe-A-eDP-1
[456.737791] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.337s)
[456.753073] Starting dynamic subtest: pipe-B-eDP-1
[458.103677] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.361s)
[458.120708] Starting dynamic subtest: pipe-C-eDP-1
[459.445784] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.339s)
[459.475200] Starting dynamic subtest: pipe-D-eDP-1
[460.779632] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.331s)
[460.782066] Subtest cursor-fb-leak: SUCCESS (4.379s)
[461.948512] [092/133] (559s left) syncobj_basic (bad-destroy-pad)
[462.008233] Starting subtest: bad-destroy-pad
[462.010497] Subtest bad-destroy-pad: SUCCESS (0.000s)
[462.089781] [093/133] (559s left) kms_chamelium_hpd (dp-hpd-fast)
[462.469750] Subtest dp-hpd-fast: SKIP (0.000s)
[463.249990] [094/133] (558s left) prime_mmap_coherency (read)
[463.316232] Starting subtest: read
[468.688459] Subtest read: SUCCESS (5.372s)
[468.802502] [095/133] (553s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite)
[468.909625] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite
[468.913807] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[469.033149] [096/133] (553s left) gem_render_copy (yf-tiled-ccs-to-y-tiled)
[469.096348] Starting subtest: yf-tiled-ccs-to-y-tiled
[469.100876] Subtest yf-tiled-ccs-to-y-tiled: SKIP (0.000s)
[469.292410] [097/133] (552s left) kms_cursor_crc (cursor-rapid-movement-128x128)
[469.500776] Starting subtest: cursor-rapid-movement-128x128
[469.506700] Starting dynamic subtest: pipe-A-eDP-1
[470.046872] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.541s)
[470.076341] Starting dynamic subtest: pipe-D-eDP-1
[471.271528] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.201s)
[471.273743] Subtest cursor-rapid-movement-128x128: SUCCESS (1.769s)
[472.141639] [098/133] (550s left) kms_psr (fbc-pr-primary-page-flip)
[472.238416] Starting subtest: fbc-pr-primary-page-flip
[472.242484] Subtest fbc-pr-primary-page-flip: SKIP (0.000s)
[472.307372] [099/133] (549s left) gem_ctx_exec (basic-norecovery)
[472.367627] Starting subtest: basic-norecovery
[472.698553] Subtest basic-norecovery: SUCCESS (0.332s)
[472.816399] [100/133] (549s left) gen3_mixed_blits
[472.943153] [101/133] (549s left) gem_exec_whisper (basic-queues-all)
[473.010619] Starting subtest: basic-queues-all
[484.125529] Subtest basic-queues-all: SUCCESS (11.113s)
[484.309002] [102/133] (538s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[484.375654] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[484.481808] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.104s)
[484.575728] [103/133] (537s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180)
[484.680206] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180
[485.637765] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (0.957s)
[485.775959] [104/133] (536s left) gem_tiled_blits (normal)
[485.837926] Subtest normal: SKIP (0.000s)
[485.903145] [105/133] (536s left) perf_pmu (busy-accuracy-50)
[485.943393] Starting subtest: busy-accuracy-50
[485.945371] Starting dynamic subtest: rcs0
[487.479090] Dynamic subtest rcs0: SUCCESS (1.532s)
[487.491591] Starting dynamic subtest: bcs0
[489.021649] Dynamic subtest bcs0: SUCCESS (1.540s)
[489.034626] Starting dynamic subtest: vcs0
[490.566746] Dynamic subtest vcs0: SUCCESS (1.542s)
[490.576916] Starting dynamic subtest: vcs1
[492.110413] Dynamic subtest vcs1: SUCCESS (1.543s)
[492.120611] Starting dynamic subtest: vecs0
[493.651768] Dynamic subtest vecs0: SUCCESS (1.539s)
[493.662679] Starting dynamic subtest: ccs0
[495.199776] Dynamic subtest ccs0: SUCCESS (1.545s)
[495.202214] Subtest busy-accuracy-50: SUCCESS (9.254s)
[495.313674] [106/133] (527s left) kms_psr (psr-sprite-plane-move)
[495.405090] Starting subtest: psr-sprite-plane-move
[495.407413] Starting dynamic subtest: eDP-1
[497.166841] Dynamic subtest eDP-1: SUCCESS (1.759s)
[497.168868] Subtest psr-sprite-plane-move: SUCCESS (1.760s)
[497.983147] [107/133] (524s left) syncobj_timeline (wait-all-complex)
[498.045488] Starting subtest: wait-all-complex
[498.259453] Subtest wait-all-complex: SUCCESS (0.214s)
[498.336914] [108/133] (524s left) gem_exec_params (cliprects-invalid)
[498.403833] Starting subtest: cliprects-invalid
[498.406122] Subtest cliprects-invalid: SUCCESS (0.000s)
[498.512813] [109/133] (524s left) kms_chamelium_frames (hdmi-crc-single)
[498.883381] Subtest hdmi-crc-single: SKIP (0.000s)
[499.688847] [110/133] (522s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-pgflip-blt)
[499.792877] Starting subtest: fbc-1p-primscrn-indfb-pgflip-blt
[504.278462] Subtest fbc-1p-primscrn-indfb-pgflip-blt: SUCCESS (4.489s)
[505.429507] [111/133] (517s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[505.526453] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[505.530812] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.000s)
[505.602631] [112/133] (517s left) gem_ctx_bad_destroy (invalid-pad)
[505.657843] Starting subtest: invalid-pad
[505.660181] Subtest invalid-pad: SUCCESS (0.000s)
[505.731762] [113/133] (517s left) syncobj_basic (illegal-fd-to-handle)
[505.789919] Starting subtest: illegal-fd-to-handle
[505.792186] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[505.895528] [114/133] (516s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc)
[505.983132] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc
[505.985366] Starting dynamic subtest: pipe-A-eDP-1
[506.970076] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.980s)
[506.980635] Starting dynamic subtest: pipe-B-eDP-1
[508.595914] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.622s)
[508.607092] Starting dynamic subtest: pipe-C-eDP-1
[510.193052] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.596s)
[510.203840] Starting dynamic subtest: pipe-D-eDP-1
[511.702711] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.508s)
[511.704885] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.712s)
[512.948378] [115/133] (509s left) kms_invalid_mode (bad-hsync-start)
[513.046205] Starting subtest: bad-hsync-start
[513.048413] Starting dynamic subtest: pipe-A-eDP-1
[513.338742] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.288s)
[513.349010] Starting dynamic subtest: pipe-B-eDP-1
[513.351183] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[513.361203] Starting dynamic subtest: pipe-C-eDP-1
[513.363359] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[513.372720] Starting dynamic subtest: pipe-D-eDP-1
[513.374912] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[513.377259] Subtest bad-hsync-start: SUCCESS (0.294s)
[514.164384] [116/133] (508s left) syncobj_wait (wait-all-complex)
[514.220070] Starting subtest: wait-all-complex
[514.435535] Subtest wait-all-complex: SUCCESS (0.215s)
[514.526524] [117/133] (508s left) gem_flink_basic (double-flink)
[514.583663] Starting subtest: double-flink
[514.586180] Subtest double-flink: SUCCESS (0.000s)
[514.687097] [118/133] (508s left) gem_exec_balancer (bonded-sync)
[514.750092] Starting subtest: bonded-sync
[514.754526] Subtest bonded-sync: SKIP (0.000s)
[515.041656] [119/133] (508s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-plflip-blt)
[515.148989] Starting subtest: fbcpsr-2p-scndscrn-indfb-plflip-blt
[515.153543] Subtest fbcpsr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[515.234679] [120/133] (507s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[515.339806] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt
[515.344272] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[515.444256] [121/133] (507s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc)
[515.537963] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc
[519.839068] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (4.303s)
[520.978492] [122/133] (502s left) kms_async_flips (async-flip-with-page-flip-events-tiled)
[521.091103] Starting subtest: async-flip-with-page-flip-events-tiled
[521.469614] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[524.231219] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.761s)
[524.245367] Starting dynamic subtest: pipe-A-eDP-1-4
[526.395855] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.162s)
[526.408093] Starting dynamic subtest: pipe-A-eDP-1-4
[528.431418] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.008s)
[528.444796] Starting dynamic subtest: pipe-A-eDP-1-x
[530.608764] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.202s)
[531.007198] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[533.768709] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.769s)
[533.779318] Starting dynamic subtest: pipe-B-eDP-1-4
[535.966245] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.196s)
[535.977441] Starting dynamic subtest: pipe-B-eDP-1-4
[537.975666] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.008s)
[537.986535] Starting dynamic subtest: pipe-B-eDP-1-x
[540.167240] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.189s)
[540.532818] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[543.297935] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.772s)
[543.309253] Starting dynamic subtest: pipe-C-eDP-1-4
[545.484548] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.185s)
[545.495339] Starting dynamic subtest: pipe-C-eDP-1-4
[547.495162] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s)
[547.505258] Starting dynamic subtest: pipe-C-eDP-1-x
[549.697563] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.201s)
[550.078319] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[552.834075] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.762s)
[552.852771] Starting dynamic subtest: pipe-D-eDP-1-4
[555.009397] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.173s)
[555.019533] Starting dynamic subtest: pipe-D-eDP-1-4
[557.020041] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.009s)
[557.035462] Starting dynamic subtest: pipe-D-eDP-1-x
[559.266092] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.244s)
[559.268943] Subtest async-flip-with-page-flip-events-tiled: SUCCESS (38.167s)
[560.440263] [123/133] (462s left) gem_exec_params (mmapped)
[560.515658] Starting subtest: mmapped
[560.518157] Subtest mmapped: SUCCESS (0.003s)
[560.627096] [124/133] (462s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[560.735213] Starting subtest: basic-flip-before-cursor-legacy
[561.665319] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.928s)
[562.465920] [125/133] (460s left) gem_mmap_gtt (big-copy-odd)
[562.518745] Subtest big-copy-odd: SKIP (0.000s)
[562.578427] [126/133] (460s left) gem_exec_alignment (forked)
[562.635257] Starting subtest: forked
[599.737707] Subtest forked: SUCCESS (37.095s)
[602.007480] [127/133] (421s left) syncobj_basic (test-valid-cycle)
[602.089142] Starting subtest: test-valid-cycle
[602.091129] Subtest test-valid-cycle: SUCCESS (0.000s)
[602.172381] [128/133] (421s left) prime_busy (after)
[602.232224] Starting subtest: after
[602.238424] Starting dynamic subtest: rcs0
[602.265854] Dynamic subtest rcs0: SUCCESS (0.027s)
[602.277866] Starting dynamic subtest: bcs0
[602.307070] Dynamic subtest bcs0: SUCCESS (0.041s)
[602.317237] Starting dynamic subtest: vcs0
[602.349731] Dynamic subtest vcs0: SUCCESS (0.042s)
[602.359147] Starting dynamic subtest: vcs1
[602.391807] Dynamic subtest vcs1: SUCCESS (0.041s)
[602.401862] Starting dynamic subtest: vecs0
[602.433716] Dynamic subtest vecs0: SUCCESS (0.041s)
[602.443742] Starting dynamic subtest: ccs0
[602.476655] Dynamic subtest ccs0: SUCCESS (0.042s)
[602.584306] Subtest after: SUCCESS (0.351s)
[602.661988] [129/133] (420s left) gem_softpin (full)
[602.719318] Starting subtest: full
[602.728068] Subtest full: SUCCESS (0.009s)
[602.852980] [130/133] (420s left) perf (invalid-remove-userspace-config)
[603.007300] Starting subtest: invalid-remove-userspace-config
[603.018935] Subtest invalid-remove-userspace-config: SUCCESS (0.010s)
[603.062851] [131/133] (420s left) gen7_exec_parse (cmd-crossing-page)
[603.122917] Subtest cmd-crossing-page: SKIP (0.000s)
[603.195284] [132/133] (420s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[603.297029] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[603.301444] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.000s)
[603.384048] [133/133] (420s left) kms_big_fb (linear-8bpp-rotate-0)
[603.494663] Starting subtest: linear-8bpp-rotate-0
[604.809269] Subtest linear-8bpp-rotate-0: SUCCESS (1.315s)
[604.937608] Closing watchdogs
results: parsing output: 0/ for test: perf_pmu
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: gem_exec_store
results: parsing output: 3/ for test: gem_exec_basic
results: parsing output: 4/ for test: gem_wait
results: parsing output: 5/ for test: kms_dither
results: parsing output: 6/ for test: kms_color_pipeline
results: parsing output: 7/ for test: kms_big_fb
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: kms_lease
results: parsing output: 11/ for test: kms_flip
results: parsing output: 12/ for test: gen9_exec_parse
results: parsing output: 13/ for test: kms_plane_multiple
results: parsing output: 14/ for test: kms_flip
results: parsing output: 15/ for test: gem_exec_schedule
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: kms_psr
results: parsing output: 18/ for test: kms_cursor_legacy
results: parsing output: 19/ for test: perf
results: parsing output: 20/ for test: kms_big_fb
results: parsing output: 21/ for test: syncobj_wait
results: parsing output: 22/ for test: sw_sync
results: parsing output: 23/ for test: kms_big_fb
results: parsing output: 24/ for test: gem_pxp
results: parsing output: 25/ for test: kms_psr2_sf
results: parsing output: 26/ for test: kms_psr
results: parsing output: 27/ for test: kms_plane_alpha_blend
results: parsing output: 28/ for test: sw_sync
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: kms_plane_scaling
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: api_intel_bb
results: parsing output: 34/ for test: gem_unref_active_buffers
results: parsing output: 35/ for test: gem_exec_reloc
results: parsing output: 36/ for test: gem_close_race
results: parsing output: 37/ for test: kms_vblank
results: parsing output: 38/ for test: kms_cursor_legacy
results: parsing output: 39/ for test: gem_sync
results: parsing output: 40/ for test: kms_plane
results: parsing output: 41/ for test: kms_flip
results: parsing output: 42/ for test: i915_selftest
results: parsing output: 43/ for test: sysfs_heartbeat_interval
results: parsing output: 44/ for test: kms_psr
results: parsing output: 45/ for test: core_setmaster
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: syncobj_timeline
results: parsing output: 48/ for test: i915_hangman
results: parsing output: 49/ for test: kms_chamelium_color
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: gem_create
results: parsing output: 54/ for test: i915_pm_rpm
results: parsing output: 55/ for test: gem_eio
results: parsing output: 56/ for test: kms_ccs
results: parsing output: 57/ for test: kms_cursor_crc
results: parsing output: 58/ for test: kms_plane_scaling
results: parsing output: 59/ for test: gem_exec_fence
results: parsing output: 60/ for test: kms_pm_rpm
results: parsing output: 61/ for test: perf_pmu
results: parsing output: 62/ for test: syncobj_timeline
results: parsing output: 63/ for test: kms_rotation_crc
results: parsing output: 64/ for test: kms_prop_blob
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: prime_mmap
results: parsing output: 67/ for test: kms_big_fb
results: parsing output: 68/ for test: gem_exec_params
results: parsing output: 69/ for test: gem_ctx_persistence
results: parsing output: 70/ for test: gem_lmem_swapping
results: parsing output: 71/ for test: gem_ctx_bad_destroy
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: gem_exec_schedule
results: parsing output: 74/ for test: kms_busy
results: parsing output: 75/ for test: gem_mmap_offset
results: parsing output: 76/ for test: kms_dsc
results: parsing output: 77/ for test: kms_big_fb
results: parsing output: 78/ for test: kms_psr
results: parsing output: 79/ for test: kms_concurrent
results: parsing output: 80/ for test: syncobj_wait
results: parsing output: 81/ for test: kms_ccs
results: parsing output: 82/ for test: syncobj_timeline
results: parsing output: 83/ for test: kms_ccs
results: parsing output: 84/ for test: gem_pwrite
results: parsing output: 85/ for test: api_intel_bb
results: parsing output: 86/ for test: gem_ccs
results: parsing output: 87/ for test: kms_pm_rpm
results: parsing output: 88/ for test: prime_mmap_coherency
results: parsing output: 89/ for test: kms_cursor_legacy
results: parsing output: 90/ for test: kms_universal_plane
results: parsing output: 91/ for test: syncobj_basic
results: parsing output: 92/ for test: kms_chamelium_hpd
results: parsing output: 93/ for test: prime_mmap_coherency
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: gem_render_copy
results: parsing output: 96/ for test: kms_cursor_crc
results: parsing output: 97/ for test: kms_psr
results: parsing output: 98/ for test: gem_ctx_exec
results: parsing output: 99/ for test: gen3_mixed_blits
results: parsing output: 100/ for test: gem_exec_whisper
results: parsing output: 101/ for test: syncobj_timeline
results: parsing output: 102/ for test: kms_big_fb
results: parsing output: 103/ for test: gem_tiled_blits
results: parsing output: 104/ for test: perf_pmu
results: parsing output: 105/ for test: kms_psr
results: parsing output: 106/ for test: syncobj_timeline
results: parsing output: 107/ for test: gem_exec_params
results: parsing output: 108/ for test: kms_chamelium_frames
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: kms_flip_scaled_crc
results: parsing output: 111/ for test: gem_ctx_bad_destroy
results: parsing output: 112/ for test: syncobj_basic
results: parsing output: 113/ for test: kms_ccs
results: parsing output: 114/ for test: kms_invalid_mode
results: parsing output: 115/ for test: syncobj_wait
results: parsing output: 116/ for test: gem_flink_basic
results: parsing output: 117/ for test: gem_exec_balancer
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_async_flips
results: parsing output: 122/ for test: gem_exec_params
results: parsing output: 123/ for test: kms_cursor_legacy
results: parsing output: 124/ for test: gem_mmap_gtt
results: parsing output: 125/ for test: gem_exec_alignment
results: parsing output: 126/ for test: syncobj_basic
results: parsing output: 127/ for test: prime_busy
results: parsing output: 128/ for test: gem_softpin
results: parsing output: 129/ for test: perf
results: parsing output: 130/ for test: gen7_exec_parse
results: parsing output: 131/ for test: kms_flip_scaled_crc
results: parsing output: 132/ for test: kms_big_fb
Done.