[57.374445] Initializing watchdogs
[57.374490]   /dev/watchdog0
[57.388635] [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.393028] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.395246] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.398712] [001/133] (960s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[57.526435] Starting subtest: fbc-rgb101010-draw-render
[62.153963] Subtest fbc-rgb101010-draw-render: SUCCESS (4.630s)
[63.342602] [002/133] (954s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[63.413138] Starting subtest: y-tiled-to-vebox-x-tiled
[63.417360] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[63.602654] [003/133] (954s left) gem_exec_gttfill (basic)
[63.660944] Starting subtest: basic
[66.203870] Subtest basic: SUCCESS (2.542s)
[66.393924] [004/133] (951s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-pwrite)
[66.551045] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-pwrite
[70.883485] Subtest fbc-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (4.335s)
[72.012679] [005/133] (946s left) kms_pm_rpm (dpms-non-lpsp)
[74.070148] Starting subtest: dpms-non-lpsp
[74.075051] Subtest dpms-non-lpsp: SKIP (0.001s)
[74.457018] [006/133] (943s left) gem_exec_schedule (submit-golden-slice)
[74.548479] Starting subtest: submit-golden-slice
[74.550825] Starting dynamic subtest: rcs0
[74.575673] Dynamic subtest rcs0: SUCCESS (0.032s)
[74.584262] Starting dynamic subtest: bcs0
[74.604151] Dynamic subtest bcs0: SUCCESS (0.028s)
[74.612059] Starting dynamic subtest: vcs0
[74.633588] Dynamic subtest vcs0: SUCCESS (0.029s)
[74.641179] Starting dynamic subtest: vcs1
[74.664515] Dynamic subtest vcs1: SUCCESS (0.031s)
[74.674739] Starting dynamic subtest: vecs0
[74.690106] Dynamic subtest vecs0: SUCCESS (0.025s)
[74.697760] Starting dynamic subtest: ccs0
[74.720297] Dynamic subtest ccs0: SUCCESS (0.030s)
[74.722504] Subtest submit-golden-slice: SUCCESS (0.177s)
[76.035680] [007/133] (942s left) syncobj_timeline (reset-during-wait-for-submit)
[76.107911] Starting subtest: reset-during-wait-for-submit
[76.210833] Subtest reset-during-wait-for-submit: SUCCESS (0.103s)
[76.301564] [008/133] (941s left) kms_properties (connector-properties-legacy)
[76.407374] Starting subtest: connector-properties-legacy
[76.409800] Starting dynamic subtest: pipe-A-eDP-1
[84.238249] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.832s)
[84.247761] Starting dynamic subtest: pipe-None-DP-1
[84.251887] Dynamic subtest pipe-None-DP-1: SUCCESS (0.001s)
[84.259090] Starting dynamic subtest: pipe-None-HDMI-A-1
[84.263341] Dynamic subtest pipe-None-HDMI-A-1: SUCCESS (0.001s)
[84.270607] Starting dynamic subtest: pipe-None-DP-2
[84.274736] Dynamic subtest pipe-None-DP-2: SUCCESS (0.001s)
[84.281827] Starting dynamic subtest: pipe-None-HDMI-A-2
[84.285972] Dynamic subtest pipe-None-HDMI-A-2: SUCCESS (0.001s)
[84.294203] Starting dynamic subtest: pipe-None-DP-3
[84.298163] Dynamic subtest pipe-None-DP-3: SUCCESS (0.001s)
[84.304889] Starting dynamic subtest: pipe-None-DP-4
[84.309357] Dynamic subtest pipe-None-DP-4: SUCCESS (0.001s)
[84.311673] Subtest connector-properties-legacy: SUCCESS (7.841s)
[85.117698] [009/133] (933s left) perf (i915-ref-count)
[85.201634] Starting subtest: i915-ref-count
[85.261617] Subtest i915-ref-count: SUCCESS (0.058s)
[85.315872] [010/133] (932s left) api_intel_allocator (gem-pool)
[85.376664] Starting subtest: gem-pool
[85.381639] Subtest gem-pool: SUCCESS (0.007s)
[85.551332] [011/133] (932s left) kms_frontbuffer_tracking (fbc-stridechange)
[85.656357] Starting subtest: fbc-stridechange
[88.959822] Subtest fbc-stridechange: SUCCESS (3.307s)
[90.107557] [012/133] (928s left) kms_addfb_basic (bad-pitch-1024)
[90.156734] Starting subtest: bad-pitch-1024
[90.158816] Subtest bad-pitch-1024: SUCCESS (0.000s)
[90.321786] [013/133] (928s left) kms_ccs (bad-aux-stride-y-tiled-ccs)
[90.422517] Starting subtest: bad-aux-stride-y-tiled-ccs
[90.440876] Starting dynamic subtest: pipe-A-eDP-1
[90.463865] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[90.475857] Starting dynamic subtest: pipe-B-eDP-1
[90.496329] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[90.503501] Starting dynamic subtest: pipe-C-eDP-1
[90.524134] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[90.531729] Starting dynamic subtest: pipe-D-eDP-1
[90.552669] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[90.556732] Subtest bad-aux-stride-y-tiled-ccs: SKIP (0.003s)
[92.664443] [014/133] (925s left) gem_workarounds (suspend-resume-fd)
[92.743755] Starting subtest: suspend-resume-fd
[108.467539] Subtest suspend-resume-fd: SUCCESS (1.306s)
[108.633169] [015/133] (909s left) gem_ctx_persistence (legacy-engines-mixed)
[108.710835] Starting subtest: legacy-engines-mixed
[108.713237] Starting dynamic subtest: render
[108.837373] Dynamic subtest render: SUCCESS (0.126s)
[108.848643] Starting dynamic subtest: bsd1
[108.897737] Dynamic subtest bsd1: SUCCESS (0.058s)
[108.908359] Starting dynamic subtest: bsd2
[108.957460] Dynamic subtest bsd2: SUCCESS (0.058s)
[108.968240] Starting dynamic subtest: blt
[109.038522] Dynamic subtest blt: SUCCESS (0.080s)
[109.049781] Starting dynamic subtest: vebox
[109.123560] Dynamic subtest vebox: SUCCESS (0.083s)
[109.126372] Subtest legacy-engines-mixed: SUCCESS (0.413s)
[109.213427] [016/133] (909s left) gem_exec_schedule (out-order)
[109.279625] Starting subtest: out-order
[109.281655] Starting dynamic subtest: rcs0
[109.368363] Dynamic subtest rcs0: SUCCESS (0.095s)
[109.381135] Starting dynamic subtest: vcs0
[109.448129] Dynamic subtest vcs0: SUCCESS (0.081s)
[109.459273] Starting dynamic subtest: vcs1
[109.529136] Dynamic subtest vcs1: SUCCESS (0.080s)
[109.540146] Starting dynamic subtest: vecs0
[109.609391] Dynamic subtest vecs0: SUCCESS (0.081s)
[109.620126] Starting dynamic subtest: ccs0
[109.699406] Dynamic subtest ccs0: SUCCESS (0.089s)
[109.701593] Subtest out-order: SUCCESS (0.428s)
[111.477726] [017/133] (907s left) gem_exec_fence (parallel)
[111.655032] Starting subtest: parallel
[111.657545] Starting dynamic subtest: rcs0
[113.803143] Dynamic subtest rcs0: SUCCESS (2.149s)
[113.814388] Starting dynamic subtest: bcs0
[115.956603] Dynamic subtest bcs0: SUCCESS (2.153s)
[115.967758] Starting dynamic subtest: vcs0
[118.108060] Dynamic subtest vcs0: SUCCESS (2.151s)
[118.118809] Starting dynamic subtest: vcs1
[120.265707] Dynamic subtest vcs1: SUCCESS (2.157s)
[120.280785] Starting dynamic subtest: vecs0
[122.416196] Dynamic subtest vecs0: SUCCESS (2.150s)
[122.425034] Starting dynamic subtest: ccs0
[124.565065] Dynamic subtest ccs0: SUCCESS (2.148s)
[124.567241] Subtest parallel: SUCCESS (12.911s)
[125.475854] [FACT gem_exec_fence (parallel)] new: kernel.kmod_is_loaded.vgem: true
[125.479145] [018/133] (893s left) sysfs_heartbeat_interval (idempotent)
[125.545827] Starting subtest: idempotent
[125.548209] Starting dynamic subtest: vecs0
[125.550526] Dynamic subtest vecs0: SUCCESS (0.001s)
[125.562063] Starting dynamic subtest: ccs0
[125.564237] Dynamic subtest ccs0: SUCCESS (0.002s)
[125.573623] Starting dynamic subtest: vcs0
[125.575728] Dynamic subtest vcs0: SUCCESS (0.000s)
[125.584144] Starting dynamic subtest: bcs0
[125.586017] Dynamic subtest bcs0: SUCCESS (0.005s)
[125.594682] Starting dynamic subtest: rcs0
[125.596771] Dynamic subtest rcs0: SUCCESS (0.003s)
[125.605166] Starting dynamic subtest: vcs1
[125.607321] Dynamic subtest vcs1: SUCCESS (0.001s)
[125.609478] Subtest idempotent: SUCCESS (0.017s)
[125.662845] [019/133] (892s left) gem_caching (read-writes)
[125.732625] Subtest read-writes: SKIP (0.000s)
[125.788618] [020/133] (892s left) syncobj_wait (reset-multiple-signaled)
[125.850845] Starting subtest: reset-multiple-signaled
[125.853174] Subtest reset-multiple-signaled: SUCCESS (0.000s)
[125.992965] [021/133] (892s left) gem_mmap_offset (partial-remap)
[126.055301] Starting subtest: partial-remap
[126.057137] Starting dynamic subtest: smem0
[126.065817] Dynamic subtest smem0: SUCCESS (0.005s)
[126.067719] Subtest partial-remap: SUCCESS (0.005s)
[126.181487] [022/133] (892s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted)
[126.237400] Starting subtest: etime-multi-wait-for-submit-unsubmitted
[126.343307] Subtest etime-multi-wait-for-submit-unsubmitted: SUCCESS (0.106s)
[126.434133] [023/133] (892s left) gem_spin_batch (spin-each)
[126.497711] Starting subtest: spin-each
[129.869731] Subtest spin-each: SUCCESS (3.371s)
[130.406784] [024/133] (888s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[130.520863] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[130.525010] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[130.647545] [025/133] (888s left) i915_pm_rpm (debugfs-read)
[132.483775] Starting subtest: debugfs-read
[133.641679] Subtest debugfs-read: SUCCESS (1.157s)
[134.044874] [026/133] (884s left) perf (blocking-parameterized)
[134.142144] Starting subtest: blocking-parameterized
[154.160480] Subtest blocking-parameterized: SUCCESS (20.016s)
[154.209179] [027/133] (864s left) gem_exec_flush (basic-batch-kernel-default-cmd)
[154.272031] Starting subtest: basic-batch-kernel-default-cmd
[154.276256] Subtest basic-batch-kernel-default-cmd: SKIP (0.000s)
[154.469671] [028/133] (864s left) syncobj_wait (invalid-reset-zero-handles)
[154.526698] Starting subtest: invalid-reset-zero-handles
[154.529482] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[154.640328] [029/133] (864s left) kms_flip (flip-vs-blocking-wf-vblank)
[154.747430] Starting subtest: flip-vs-blocking-wf-vblank
[154.749861] Starting dynamic subtest: A-eDP1
[163.645826] Dynamic subtest A-eDP1: SUCCESS (8.897s)
[163.657604] Starting dynamic subtest: B-eDP1
[172.580109] Dynamic subtest B-eDP1: SUCCESS (8.933s)
[172.588624] Starting dynamic subtest: C-eDP1
[181.493597] Dynamic subtest C-eDP1: SUCCESS (8.911s)
[181.504452] Starting dynamic subtest: D-eDP1
[190.430632] Dynamic subtest D-eDP1: SUCCESS (8.935s)
[190.432988] Subtest flip-vs-blocking-wf-vblank: SUCCESS (35.686s)
[191.661437] [030/133] (827s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[191.760352] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu
[198.326118] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (6.568s)
[198.483978] [031/133] (820s left) gem_exec_balancer (bonded-semaphore)
[198.556611] Starting subtest: bonded-semaphore
[198.561297] Subtest bonded-semaphore: SKIP (0.000s)
[198.917797] [032/133] (820s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[199.024177] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[199.028587] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[199.147349] [033/133] (820s left) kms_invalid_mode (int-max-clock)
[199.249343] Starting subtest: int-max-clock
[199.251461] Starting dynamic subtest: pipe-A-eDP-1
[199.530758] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.283s)
[199.540870] Starting dynamic subtest: pipe-B-eDP-1
[199.543030] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[199.551703] Starting dynamic subtest: pipe-C-eDP-1
[199.553951] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[199.562612] Starting dynamic subtest: pipe-D-eDP-1
[199.564685] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[199.566750] Subtest int-max-clock: SUCCESS (0.288s)
[200.341737] [034/133] (818s left) kms_big_fb (4-tiled-16bpp-rotate-0)
[200.444449] Starting subtest: 4-tiled-16bpp-rotate-0
[201.777748] Subtest 4-tiled-16bpp-rotate-0: SUCCESS (1.334s)
[201.932583] [035/133] (817s left) kms_addfb_basic (bad-pitch-999)
[201.991884] Starting subtest: bad-pitch-999
[201.993496] Subtest bad-pitch-999: SUCCESS (0.000s)
[202.170298] [036/133] (817s left) gen7_exec_parse (cmd-crossing-page)
[202.248287] Subtest cmd-crossing-page: SKIP (0.000s)
[202.330949] [037/133] (816s left) kms_async_flips (crc)
[202.427532] Starting subtest: crc
[202.799410] Starting dynamic subtest: pipe-A-eDP-1
[205.732528] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.931s)
[206.109625] Starting dynamic subtest: pipe-B-eDP-1
[208.968911] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.867s)
[209.355014] Starting dynamic subtest: pipe-C-eDP-1
[212.282928] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.937s)
[212.643661] Starting dynamic subtest: pipe-D-eDP-1
[215.533748] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.898s)
[215.536036] Subtest crc: SUCCESS (13.106s)
[216.708669] [038/133] (802s left) syncobj_wait (single-wait-all-for-submit-submitted)
[216.776381] Starting subtest: single-wait-all-for-submit-submitted
[216.878475] Subtest single-wait-all-for-submit-submitted: SUCCESS (0.102s)
[216.967928] [039/133] (802s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[217.074935] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[217.079241] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[217.209927] [040/133] (802s left) kms_rotation_crc (primary-yf-tiled-reflect-x-0)
[217.318537] Starting subtest: primary-yf-tiled-reflect-x-0
[217.377885] Subtest primary-yf-tiled-reflect-x-0: SKIP (0.059s)
[217.492447] [041/133] (801s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[217.596602] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[217.601023] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[217.737733] [042/133] (801s left) kms_legacy_colorkey (basic)
[217.838563] Starting subtest: basic
[217.840368] Starting dynamic subtest: pipe-A
[217.882963] Dynamic subtest pipe-A: SUCCESS (0.045s)
[217.894843] Starting dynamic subtest: pipe-B
[217.896613] Dynamic subtest pipe-B: SUCCESS (0.001s)
[217.905024] Starting dynamic subtest: pipe-C
[217.907036] Dynamic subtest pipe-C: SUCCESS (0.000s)
[217.916784] Starting dynamic subtest: pipe-D
[217.918287] Dynamic subtest pipe-D: SUCCESS (0.000s)
[217.919847] Subtest basic: SUCCESS (0.050s)
[217.963729] [043/133] (801s left) kms_plane_alpha_blend (alpha-7efc)
[218.070644] Starting subtest: alpha-7efc
[218.346322] Starting dynamic subtest: pipe-A-eDP-1
[218.959362] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.611s)
[218.987553] Starting dynamic subtest: pipe-D-eDP-1
[220.287290] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.307s)
[220.289706] Subtest alpha-7efc: SUCCESS (2.218s)
[221.156787] [044/133] (798s left) gem_mmap (big-bo)
[221.223707] Subtest big-bo: SKIP (0.000s)
[221.295805] [045/133] (798s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[221.403862] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt
[221.408086] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[221.523342] [046/133] (798s left) gem_exec_schedule (u-submit-golden-slice)
[221.600368] Starting subtest: u-submit-golden-slice
[221.602669] Starting dynamic subtest: rcs0
[221.626514] Dynamic subtest rcs0: SUCCESS (0.033s)
[221.638176] Starting dynamic subtest: bcs0
[221.654926] Dynamic subtest bcs0: SUCCESS (0.028s)
[221.664532] Starting dynamic subtest: vcs0
[221.688101] Dynamic subtest vcs0: SUCCESS (0.032s)
[221.699403] Starting dynamic subtest: vcs1
[221.718177] Dynamic subtest vcs1: SUCCESS (0.030s)
[221.727790] Starting dynamic subtest: vecs0
[221.750231] Dynamic subtest vecs0: SUCCESS (0.031s)
[221.759245] Starting dynamic subtest: ccs0
[221.780869] Dynamic subtest ccs0: SUCCESS (0.030s)
[221.783149] Subtest u-submit-golden-slice: SUCCESS (0.188s)
[223.463531] [047/133] (796s left) gem_exec_async (forked-writes)
[223.531182] Starting subtest: forked-writes
[223.533514] Starting dynamic subtest: rcs0
[223.557610] Dynamic subtest rcs0: SUCCESS (0.026s)
[223.566942] Starting dynamic subtest: bcs0
[223.579061] Dynamic subtest bcs0: SUCCESS (0.021s)
[223.588674] Starting dynamic subtest: vcs0
[223.601714] Dynamic subtest vcs0: SUCCESS (0.022s)
[223.610801] Starting dynamic subtest: vcs1
[223.622645] Dynamic subtest vcs1: SUCCESS (0.020s)
[223.631964] Starting dynamic subtest: vecs0
[223.644960] Dynamic subtest vecs0: SUCCESS (0.022s)
[223.655084] Starting dynamic subtest: ccs0
[223.666029] Dynamic subtest ccs0: SUCCESS (0.020s)
[223.668314] Subtest forked-writes: SUCCESS (0.135s)
[223.912938] [048/133] (795s left) core_auth (many-magics)
[223.974753] Starting subtest: many-magics
[224.743634] Subtest many-magics: SUCCESS (0.769s)
[224.989651] [049/133] (794s left) kms_color (deep-color)
[225.102866] Starting subtest: deep-color
[226.210400] Starting dynamic subtest: pipe-A-eDP-1-gamma
[226.943356] Dynamic subtest pipe-A-eDP-1-gamma: SUCCESS (0.735s)
[226.953553] Starting dynamic subtest: pipe-A-eDP-1-degamma
[228.446437] Dynamic subtest pipe-A-eDP-1-degamma: SUCCESS (1.499s)
[228.456250] Starting dynamic subtest: pipe-A-eDP-1-ctm
[229.966757] Dynamic subtest pipe-A-eDP-1-ctm: SUCCESS (1.521s)
[230.721774] Starting dynamic subtest: pipe-B-eDP-1-gamma
[231.451439] Dynamic subtest pipe-B-eDP-1-gamma: SUCCESS (0.739s)
[231.463334] Starting dynamic subtest: pipe-B-eDP-1-degamma
[232.948894] Dynamic subtest pipe-B-eDP-1-degamma: SUCCESS (1.495s)
[232.958566] Starting dynamic subtest: pipe-B-eDP-1-ctm
[234.331272] Dynamic subtest pipe-B-eDP-1-ctm: SUCCESS (1.382s)
[235.117747] Starting dynamic subtest: pipe-C-eDP-1-gamma
[235.768692] Dynamic subtest pipe-C-eDP-1-gamma: SUCCESS (0.655s)
[235.779062] Starting dynamic subtest: pipe-C-eDP-1-degamma
[237.178055] Dynamic subtest pipe-C-eDP-1-degamma: SUCCESS (1.412s)
[237.188778] Starting dynamic subtest: pipe-C-eDP-1-ctm
[238.611127] Dynamic subtest pipe-C-eDP-1-ctm: SUCCESS (1.430s)
[239.423452] Starting dynamic subtest: pipe-D-eDP-1-gamma
[240.100408] Dynamic subtest pipe-D-eDP-1-gamma: SUCCESS (0.692s)
[240.115437] Starting dynamic subtest: pipe-D-eDP-1-degamma
[241.474041] Dynamic subtest pipe-D-eDP-1-degamma: SUCCESS (1.375s)
[241.483448] Starting dynamic subtest: pipe-D-eDP-1-ctm
[242.921492] Dynamic subtest pipe-D-eDP-1-ctm: SUCCESS (1.447s)
[242.923970] Subtest deep-color: SUCCESS (17.819s)
[243.780591] [050/133] (776s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[243.880812] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[243.884800] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[244.003116] [051/133] (775s left) gem_exec_fence (syncobj-timeline-unused-fence)
[244.577375] Starting subtest: syncobj-timeline-unused-fence
[244.583603] Subtest syncobj-timeline-unused-fence: SUCCESS (0.006s)
[246.449607] [052/133] (773s left) kms_psr (fbc-psr-cursor-plane-move)
[246.544628] Starting subtest: fbc-psr-cursor-plane-move
[246.546790] Starting dynamic subtest: eDP-1
[246.550742] Dynamic subtest eDP-1: SKIP (0.000s)
[246.554780] Subtest fbc-psr-cursor-plane-move: SKIP (0.001s)
[246.630037] [053/133] (773s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[246.723631] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[246.725483] Starting dynamic subtest: pipe-A
[247.061054] Dynamic subtest pipe-A: SUCCESS (0.340s)
[247.070933] Starting dynamic subtest: pipe-B
[248.462380] Dynamic subtest pipe-B: SUCCESS (1.400s)
[248.471763] Starting dynamic subtest: pipe-C
[249.880430] Dynamic subtest pipe-C: SUCCESS (1.417s)
[249.890661] Starting dynamic subtest: pipe-D
[251.312009] Dynamic subtest pipe-D: SUCCESS (1.430s)
[251.314276] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.591s)
[252.505465] [054/133] (767s left) kms_flip (2x-flip-vs-suspend)
[252.604864] Starting subtest: 2x-flip-vs-suspend
[252.616537] Subtest 2x-flip-vs-suspend: SKIP (0.011s)
[252.759046] [055/133] (767s left) gem_exec_capture (pi)
[252.815416] Starting subtest: pi
[252.817709] Starting dynamic subtest: rcs0
[257.138081] Dynamic subtest rcs0: SUCCESS (4.321s)
[257.150137] Starting dynamic subtest: bcs0
[261.280787] Dynamic subtest bcs0: SUCCESS (4.141s)
[261.294955] Starting dynamic subtest: vcs0
[265.455679] Dynamic subtest vcs0: SUCCESS (4.171s)
[265.467375] Starting dynamic subtest: vcs1
[269.603880] Dynamic subtest vcs1: SUCCESS (4.147s)
[269.614836] Starting dynamic subtest: vecs0
[273.737953] Dynamic subtest vecs0: SUCCESS (4.132s)
[273.747403] Starting dynamic subtest: ccs0
[277.916325] Dynamic subtest ccs0: SUCCESS (4.176s)
[277.919153] Subtest pi: SUCCESS (25.102s)
[278.015868] [056/133] (742s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping)
[278.167621] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping
[278.194183] Starting dynamic subtest: pipe-A-plane-0
[278.482670] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[278.492775] Starting dynamic subtest: pipe-A-plane-3
[278.738974] Dynamic subtest pipe-A-plane-3: SUCCESS (0.255s)
[279.875680] Starting dynamic subtest: pipe-B-plane-0
[280.170545] Dynamic subtest pipe-B-plane-0: SUCCESS (0.300s)
[280.184763] Starting dynamic subtest: pipe-B-plane-3
[280.401514] Dynamic subtest pipe-B-plane-3: SUCCESS (0.232s)
[280.711501] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping: SUCCESS (2.543s)
[281.566033] [057/133] (738s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-upscaling)
[281.670341] Starting subtest: flip-64bpp-linear-to-32bpp-linear-upscaling
[281.672718] Starting dynamic subtest: pipe-A-default-mode
[284.407292] Dynamic subtest pipe-A-default-mode: SUCCESS (2.739s)
[284.409647] Subtest flip-64bpp-linear-to-32bpp-linear-upscaling: SUCCESS (2.741s)
[285.248939] [058/133] (734s left) i915_query (query-regions-garbage-items)
[285.312401] Starting subtest: query-regions-garbage-items
[285.314255] Subtest query-regions-garbage-items: SUCCESS (0.000s)
[285.432557] [059/133] (734s left) kms_big_fb (x-tiled-8bpp-rotate-180)
[285.538514] Starting subtest: x-tiled-8bpp-rotate-180
[286.774266] Subtest x-tiled-8bpp-rotate-180: SUCCESS (1.235s)
[286.927926] [060/133] (733s left) kms_content_protection (srm)
[287.074110] Starting subtest: srm
[287.080411] Subtest srm: SKIP (0.003s)
[287.254088] [061/133] (733s left) gem_ctx_persistence (engines-cleanup)
[287.320774] Starting subtest: engines-cleanup
[287.322680] Starting dynamic subtest: rcs0
[287.427284] Dynamic subtest rcs0: SUCCESS (0.105s)
[287.438804] Starting dynamic subtest: bcs0
[287.492023] Dynamic subtest bcs0: SUCCESS (0.063s)
[287.503499] Starting dynamic subtest: vcs0
[287.572135] Dynamic subtest vcs0: SUCCESS (0.078s)
[287.583068] Starting dynamic subtest: vcs1
[287.598446] Dynamic subtest vcs1: SUCCESS (0.025s)
[287.607009] Starting dynamic subtest: vecs0
[287.703222] Dynamic subtest vecs0: SUCCESS (0.103s)
[287.717328] Starting dynamic subtest: ccs0
[287.768189] Dynamic subtest ccs0: SUCCESS (0.064s)
[287.770920] Subtest engines-cleanup: SUCCESS (0.447s)
[287.871407] [062/133] (732s left) gem_exec_flush (basic-batch-kernel-default-wb)
[287.941752] Starting subtest: basic-batch-kernel-default-wb
[294.134236] Subtest basic-batch-kernel-default-wb: SUCCESS (6.192s)
[294.333727] [063/133] (726s left) kms_big_fb (4-tiled-8bpp-rotate-0)
[294.431771] Starting subtest: 4-tiled-8bpp-rotate-0
[295.612354] Subtest 4-tiled-8bpp-rotate-0: SUCCESS (1.182s)
[295.749661] [064/133] (724s left) gem_mmap_gtt (close-race)
[295.807076] Subtest close-race: SKIP (0.000s)
[295.873632] [065/133] (724s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[295.982367] Starting subtest: pr-cursor-plane-move-continuous-exceed-sf
[295.985852] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[296.075245] [066/133] (724s left) kms_flip (2x-flip-vs-expired-vblank)
[296.172609] Starting subtest: 2x-flip-vs-expired-vblank
[296.182091] Subtest 2x-flip-vs-expired-vblank: SKIP (0.011s)
[296.274377] [067/133] (724s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset)
[296.380630] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset
[296.385095] Starting dynamic subtest: A-eDP1
[299.964454] Dynamic subtest A-eDP1: SUCCESS (3.578s)
[299.974905] Starting dynamic subtest: B-eDP1
[303.153377] Dynamic subtest B-eDP1: SUCCESS (3.186s)
[303.163029] Starting dynamic subtest: C-eDP1
[306.266749] Dynamic subtest C-eDP1: SUCCESS (3.113s)
[306.277086] Starting dynamic subtest: D-eDP1
[309.394364] Dynamic subtest D-eDP1: SUCCESS (3.128s)
[309.399408] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (13.016s)
[310.197372] [068/133] (710s left) gem_softpin (softpin)
[310.258874] Starting subtest: softpin
[342.536648] Subtest softpin: SUCCESS (32.271s)
[342.695853] [069/133] (677s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[342.822988] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[342.827071] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[342.946039] [070/133] (677s left) gem_exec_params (invalid-ring2)
[343.019825] Starting subtest: invalid-ring2
[343.022198] Subtest invalid-ring2: SUCCESS (0.000s)
[343.151649] [071/133] (677s left) kms_dsc (dsc-fractional-bpp-with-bpc)
[343.259336] Starting subtest: dsc-fractional-bpp-with-bpc
[343.289976] Subtest dsc-fractional-bpp-with-bpc: SKIP (0.001s)
[343.343577] [072/133] (677s left) kms_cursor_crc (cursor-rapid-movement-128x42)
[343.586309] Starting subtest: cursor-rapid-movement-128x42
[343.638628] Subtest cursor-rapid-movement-128x42: SKIP (0.047s)
[343.864022] [073/133] (676s left) i915_hangman (gt-error-state-capture)
[343.941643] Starting subtest: gt-error-state-capture
[343.943177] Starting dynamic subtest: rcs0
[356.233971] Dynamic subtest rcs0: SUCCESS (12.289s)
[356.249379] Starting dynamic subtest: bcs0
[367.304866] Dynamic subtest bcs0: SUCCESS (11.066s)
[367.316973] Starting dynamic subtest: vcs0
[379.274154] Dynamic subtest vcs0: SUCCESS (11.965s)
[379.286070] Starting dynamic subtest: vcs1
[391.242170] Dynamic subtest vcs1: SUCCESS (11.964s)
[391.254698] Starting dynamic subtest: vecs0
[401.225499] Dynamic subtest vecs0: SUCCESS (9.979s)
[401.237666] Starting dynamic subtest: ccs0
[411.274425] Dynamic subtest ccs0: SUCCESS (10.044s)
[411.277440] Subtest gt-error-state-capture: SUCCESS (67.325s)
[411.421922] [074/133] (609s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[411.520684] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[411.525133] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.000s)
[411.606352] [075/133] (609s left) kms_selftest (drm_format)
[411.662427] Starting subtest: drm_format
[411.720777] Starting dynamic subtest: drm_test_format_block_width_invalid
[411.837923] Dynamic subtest drm_test_format_block_width_invalid: SUCCESS (0.115s)
[411.847303] Starting dynamic subtest: drm_test_format_block_width_one_plane
[411.945540] Dynamic subtest drm_test_format_block_width_one_plane: SUCCESS (0.107s)
[411.954728] Starting dynamic subtest: drm_test_format_block_width_two_plane
[412.079555] Dynamic subtest drm_test_format_block_width_two_plane: SUCCESS (0.131s)
[412.089002] Starting dynamic subtest: drm_test_format_block_width_three_plane
[412.219120] Dynamic subtest drm_test_format_block_width_three_plane: SUCCESS (0.139s)
[412.234274] Starting dynamic subtest: drm_test_format_block_width_tiled
[412.322100] Dynamic subtest drm_test_format_block_width_tiled: SUCCESS (0.102s)
[412.336854] Starting dynamic subtest: drm_test_format_block_height_invalid
[412.433638] Dynamic subtest drm_test_format_block_height_invalid: SUCCESS (0.111s)
[412.442602] Starting dynamic subtest: drm_test_format_block_height_one_plane
[412.544447] Dynamic subtest drm_test_format_block_height_one_plane: SUCCESS (0.110s)
[412.553825] Starting dynamic subtest: drm_test_format_block_height_two_plane
[412.658394] Dynamic subtest drm_test_format_block_height_two_plane: SUCCESS (0.113s)
[412.667696] Starting dynamic subtest: drm_test_format_block_height_three_plane
[412.763197] Dynamic subtest drm_test_format_block_height_three_plane: SUCCESS (0.104s)
[412.772130] Starting dynamic subtest: drm_test_format_block_height_tiled
[412.878500] Dynamic subtest drm_test_format_block_height_tiled: SUCCESS (0.113s)
[412.888140] Starting dynamic subtest: drm_test_format_min_pitch_invalid
[412.981751] Dynamic subtest drm_test_format_min_pitch_invalid: SUCCESS (0.103s)
[412.990925] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_8bpp
[413.094971] Dynamic subtest drm_test_format_min_pitch_one_plane_8bpp: SUCCESS (0.113s)
[413.103995] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_16bpp
[413.206763] Dynamic subtest drm_test_format_min_pitch_one_plane_16bpp: SUCCESS (0.110s)
[413.215838] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_24bpp
[413.320152] Dynamic subtest drm_test_format_min_pitch_one_plane_24bpp: SUCCESS (0.114s)
[413.329535] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_32bpp
[413.446279] Dynamic subtest drm_test_format_min_pitch_one_plane_32bpp: SUCCESS (0.122s)
[413.455718] Starting dynamic subtest: drm_test_format_min_pitch_two_plane
[413.562357] Dynamic subtest drm_test_format_min_pitch_two_plane: SUCCESS (0.115s)
[413.571822] Starting dynamic subtest: drm_test_format_min_pitch_three_plane_8bpp
[413.699684] Dynamic subtest drm_test_format_min_pitch_three_plane_8bpp: SUCCESS (0.137s)
[413.709188] Starting dynamic subtest: drm_test_format_min_pitch_tiled
[413.841424] Dynamic subtest drm_test_format_min_pitch_tiled: SUCCESS (0.141s)
[413.843721] Subtest drm_format: SUCCESS (2.177s)
[414.063981] [076/133] (606s left) syncobj_timeline (invalid-signal-illegal-handle)
[414.116954] Starting subtest: invalid-signal-illegal-handle
[414.119368] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[414.232345] [077/133] (606s left) gem_ctx_param (invalid-ctx-get)
[414.296769] Starting subtest: invalid-ctx-get
[414.298672] Subtest invalid-ctx-get: SUCCESS (0.000s)
[414.381239] [078/133] (606s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[414.492871] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[421.218251] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.726s)
[421.384448] [079/133] (599s left) prime_self_import (basic-with_fd_dup)
[421.425742] Starting subtest: basic-with_fd_dup
[421.446313] Subtest basic-with_fd_dup: SUCCESS (0.023s)
[421.579034] [080/133] (599s left) i915_drm_fdinfo (busy-idle-check-all)
[421.616812] Starting subtest: busy-idle-check-all
[421.619016] Starting dynamic subtest: rcs0
[421.623457] Dynamic subtest rcs0: SKIP (0.004s)
[421.633455] Starting dynamic subtest: bcs0
[421.637748] Dynamic subtest bcs0: SKIP (0.000s)
[421.646946] Starting dynamic subtest: vcs0
[421.651014] Dynamic subtest vcs0: SKIP (0.000s)
[421.660190] Starting dynamic subtest: vcs1
[421.664628] Dynamic subtest vcs1: SKIP (0.000s)
[421.673714] Starting dynamic subtest: vecs0
[421.677901] Dynamic subtest vecs0: SKIP (0.000s)
[421.687062] Starting dynamic subtest: ccs0
[421.691402] Dynamic subtest ccs0: SKIP (0.000s)
[421.695735] Subtest busy-idle-check-all: SKIP (0.010s)
[421.733803] [081/133] (599s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc)
[421.879395] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc
[421.881648] Starting dynamic subtest: pipe-A-eDP-1
[421.904259] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[421.915899] Starting dynamic subtest: pipe-B-eDP-1
[421.937821] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[421.947337] Starting dynamic subtest: pipe-C-eDP-1
[421.968677] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[421.978219] Starting dynamic subtest: pipe-D-eDP-1
[421.999494] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[422.003348] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc: SKIP (0.006s)
[424.194203] [082/133] (596s left) gem_userptr_blits (map-fixed-invalidate-overlap-busy)
[424.266474] Starting subtest: map-fixed-invalidate-overlap-busy
[424.270886] Subtest map-fixed-invalidate-overlap-busy: SKIP (0.000s)
[424.415507] [083/133] (596s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[424.519336] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[424.523822] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[424.632937] [084/133] (596s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-move)
[424.730841] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-move
[424.735072] Subtest fbcpsr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[424.844852] [085/133] (596s left) gem_create (create-valid-nonaligned)
[424.910000] Starting subtest: create-valid-nonaligned
[424.911945] Subtest create-valid-nonaligned: SUCCESS (0.000s)
[425.109060] [086/133] (596s left) kms_vblank (wait-forked-busy)
[425.201222] Starting subtest: wait-forked-busy
[425.203358] Starting dynamic subtest: pipe-A-eDP-1
[427.080024] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.878s)
[427.095243] Starting dynamic subtest: pipe-D-eDP-1
[429.653442] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.570s)
[429.655627] Subtest wait-forked-busy: SUCCESS (4.451s)
[430.474984] [087/133] (590s left) api_intel_bb (delta-check)
[430.543918] Starting subtest: delta-check
[430.549479] Subtest delta-check: SUCCESS (0.006s)
[430.694635] [088/133] (590s left) kms_chamelium_frames (dp-crc-fast)
[431.090030] Subtest dp-crc-fast: SKIP (0.000s)
[431.864729] [089/133] (589s left) kms_frontbuffer_tracking (psr-farfromfence-mmap-gtt)
[431.976947] Starting subtest: psr-farfromfence-mmap-gtt
[431.981221] Subtest psr-farfromfence-mmap-gtt: SKIP (0.000s)
[432.105717] [090/133] (589s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-blt)
[432.204446] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-blt
[436.727942] Subtest fbc-1p-primscrn-pri-indfb-draw-blt: SUCCESS (4.529s)
[437.913254] [091/133] (583s left) kms_cursor_legacy (2x-cursor-vs-flip-legacy)
[438.036883] Starting subtest: 2x-cursor-vs-flip-legacy
[438.041684] Subtest 2x-cursor-vs-flip-legacy: SKIP (0.001s)
[438.194954] [092/133] (583s left) gem_pxp (reject-modify-context-protection-off-2)
[438.323015] Starting subtest: reject-modify-context-protection-off-2
[438.324878] Subtest reject-modify-context-protection-off-2: SUCCESS (0.001s)
[438.454580] [093/133] (583s left) syncobj_timeline (single-wait-available-submitted)
[438.510649] Starting subtest: single-wait-available-submitted
[438.512843] Subtest single-wait-available-submitted: SUCCESS (0.000s)
[438.611604] [094/133] (583s left) kms_cursor_legacy (torture-bo)
[438.727347] Starting subtest: torture-bo
[438.729637] Starting dynamic subtest: pipe-A
[444.931254] Dynamic subtest pipe-A: SUCCESS (6.199s)
[444.944611] Starting dynamic subtest: pipe-B
[451.525180] Dynamic subtest pipe-B: SUCCESS (6.593s)
[451.537261] Starting dynamic subtest: pipe-C
[459.199748] Dynamic subtest pipe-C: SUCCESS (7.673s)
[459.211299] Starting dynamic subtest: pipe-D
[467.634208] Dynamic subtest pipe-D: SUCCESS (8.430s)
[467.647092] Starting dynamic subtest: all-pipes
[474.663560] Dynamic subtest all-pipes: SUCCESS (7.027s)
[474.666155] Subtest torture-bo: SUCCESS (35.932s)
[474.830977] [095/133] (546s left) perf_pmu (init-sema)
[474.875371] Starting subtest: init-sema
[474.877827] Starting dynamic subtest: rcs0
[474.880202] Dynamic subtest rcs0: SUCCESS (0.002s)
[474.891876] Starting dynamic subtest: bcs0
[474.894029] Dynamic subtest bcs0: SUCCESS (0.000s)
[474.903500] Starting dynamic subtest: vcs0
[474.905704] Dynamic subtest vcs0: SUCCESS (0.001s)
[474.914859] Starting dynamic subtest: vcs1
[474.917062] Dynamic subtest vcs1: SUCCESS (0.001s)
[474.926165] Starting dynamic subtest: vecs0
[474.928052] Dynamic subtest vecs0: SUCCESS (0.001s)
[474.937657] Starting dynamic subtest: ccs0
[474.939858] Dynamic subtest ccs0: SUCCESS (0.001s)
[474.942045] Subtest init-sema: SUCCESS (0.009s)
[474.990749] [096/133] (546s left) kms_pm_rpm (system-suspend-idle)
[476.522559] Starting subtest: system-suspend-idle
[495.995152] Subtest system-suspend-idle: SUCCESS (4.619s)
[496.395152] [097/133] (525s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-wc)
[496.509543] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-wc
[503.062117] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (6.549s)
[503.176838] [098/133] (518s left) kms_ccs (random-ccs-data-yf-tiled-ccs)
[503.274026] Starting subtest: random-ccs-data-yf-tiled-ccs
[503.276235] Starting dynamic subtest: pipe-A-eDP-1
[503.300448] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[503.312273] Starting dynamic subtest: pipe-B-eDP-1
[503.336037] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[503.346233] Starting dynamic subtest: pipe-C-eDP-1
[503.369723] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[503.380524] Starting dynamic subtest: pipe-D-eDP-1
[503.404650] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[503.409039] Subtest random-ccs-data-yf-tiled-ccs: SKIP (0.005s)
[505.506222] [099/133] (516s left) kms_draw_crc (fill-fb)
[505.610411] Starting subtest: fill-fb
[506.073727] Subtest fill-fb: SUCCESS (0.464s)
[506.202192] [100/133] (515s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset-interruptible)
[506.306279] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset-interruptible
[506.318785] Subtest 2x-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.011s)
[506.407716] [101/133] (515s left) kms_psr (psr2-suspend)
[506.502842] Starting subtest: psr2-suspend
[506.504680] Starting dynamic subtest: eDP-1
[523.883244] Dynamic subtest eDP-1: SUCCESS (2.843s)
[523.885557] Subtest psr2-suspend: SUCCESS (2.844s)
[524.780278] [102/133] (497s left) kms_frontbuffer_tracking (psr-1p-pri-indfb-multidraw)
[524.888315] Starting subtest: psr-1p-pri-indfb-multidraw
[547.649452] Subtest psr-1p-pri-indfb-multidraw: SUCCESS (22.745s)
[547.812756] [103/133] (474s left) kms_cursor_edge_walk (256x256-right-edge)
[547.924105] Starting subtest: 256x256-right-edge
[547.926231] Starting dynamic subtest: pipe-A-eDP-1
[551.369131] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.443s)
[551.379352] Starting dynamic subtest: pipe-D-eDP-1
[554.486777] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.114s)
[554.488910] Subtest 256x256-right-edge: SUCCESS (6.560s)
[555.305614] [104/133] (466s left) gem_ccs (block-copy-compressed)
[555.367403] Starting subtest: block-copy-compressed
[555.371609] Subtest block-copy-compressed: SKIP (0.000s)
[555.502453] [105/133] (466s left) gem_exec_reloc (basic-wc-cpu-active)
[555.579799] Subtest basic-wc-cpu-active: SKIP (0.000s)
[555.660848] [106/133] (466s left) perf (invalid-remove-userspace-config)
[555.791098] Starting subtest: invalid-remove-userspace-config
[555.802779] Subtest invalid-remove-userspace-config: SUCCESS (0.010s)
[555.850902] [107/133] (466s left) gem_lmem_swapping (massive)
[555.892234] Subtest massive: SKIP (0.000s)
[555.940867] [108/133] (466s left) kms_psr (fbc-psr2-cursor-plane-onoff)
[556.050203] Starting subtest: fbc-psr2-cursor-plane-onoff
[556.052437] Starting dynamic subtest: eDP-1
[556.056528] Dynamic subtest eDP-1: SKIP (0.000s)
[556.060560] Subtest fbc-psr2-cursor-plane-onoff: SKIP (0.001s)
[556.137558] [109/133] (466s left) gem_ctx_exec (basic-invalid-context)
[556.202252] Starting subtest: basic-invalid-context
[556.208880] Subtest basic-invalid-context: SUCCESS (0.009s)
[556.353440] [110/133] (465s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-mc-ccs)
[556.445887] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-mc-ccs
[556.447739] Starting dynamic subtest: pipe-A-eDP-1
[556.496016] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[556.510283] Starting dynamic subtest: pipe-B-eDP-1
[556.558348] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[556.569343] Starting dynamic subtest: pipe-C-eDP-1
[556.616047] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[556.625979] Starting dynamic subtest: pipe-D-eDP-1
[556.673171] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[556.676697] Subtest crc-sprite-planes-basic-y-tiled-gen12-mc-ccs: SKIP (0.009s)
[558.776170] [111/133] (463s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[558.885237] Starting subtest: yf-tiled-16bpp-rotate-90
[558.889466] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[559.009573] [112/133] (463s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier-source-clamping)
[559.106790] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier-source-clamping
[559.119834] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[559.190952] [113/133] (463s left) kms_big_fb (linear-8bpp-rotate-90)
[559.295078] Starting subtest: linear-8bpp-rotate-90
[559.315282] Subtest linear-8bpp-rotate-90: SKIP (0.020s)
[559.455041] [114/133] (463s left) kms_chamelium_hpd (dp-hpd-storm)
[559.854196] Subtest dp-hpd-storm: SKIP (0.000s)
[560.674794] [115/133] (461s left) syncobj_timeline (32bits-limit)
[560.743788] Starting subtest: 32bits-limit
[560.746090] Subtest 32bits-limit: SUCCESS (0.001s)
[560.863564] [116/133] (461s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[560.962427] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-gtt
[560.966627] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[561.097727] [117/133] (461s left) perf_pmu (interrupts)
[561.147047] Starting subtest: interrupts
[562.254770] Subtest interrupts: SUCCESS (1.106s)
[562.388992] [118/133] (460s left) kms_psr (psr-primary-page-flip)
[562.485618] Starting subtest: psr-primary-page-flip
[562.487942] Starting dynamic subtest: eDP-1
[564.213138] Dynamic subtest eDP-1: SUCCESS (1.728s)
[564.215435] Subtest psr-primary-page-flip: SUCCESS (1.730s)
[565.073623] [119/133] (457s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs)
[565.179851] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs
[565.181718] Starting dynamic subtest: pipe-A-eDP-1
[566.010979] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.825s)
[566.021511] Starting dynamic subtest: pipe-B-eDP-1
[567.601035] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.585s)
[567.612755] Starting dynamic subtest: pipe-C-eDP-1
[569.069182] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.465s)
[569.081623] Starting dynamic subtest: pipe-D-eDP-1
[570.600240] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.532s)
[570.602409] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs: SUCCESS (5.412s)
[571.792609] [120/133] (450s left) prime_mmap (test_correct_cpu_write)
[571.854780] Starting subtest: test_correct_cpu_write
[571.857186] Starting dynamic subtest: test_correct_cpu_write-smem
[571.859508] Dynamic subtest test_correct_cpu_write-smem: SUCCESS (0.000s)
[571.861807] Subtest test_correct_cpu_write: SUCCESS (0.001s)
[571.986771] [121/133] (450s left) gem_exec_reloc (basic-range-active)
[572.053349] Subtest basic-range-active: SKIP (0.000s)
[572.150722] [122/133] (450s left) kms_psr2_sf (fbc-pr-overlay-plane-update-continuous-sf)
[572.252682] Starting subtest: fbc-pr-overlay-plane-update-continuous-sf
[572.256627] Subtest fbc-pr-overlay-plane-update-continuous-sf: SKIP (0.000s)
[572.334110] [123/133] (450s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[572.458888] Starting subtest: cursorB-vs-flipB-legacy
[572.463456] Subtest cursorB-vs-flipB-legacy: SKIP (0.001s)
[572.620567] [124/133] (450s left) kms_flip (flip-vs-dpms-off-vs-modeset)
[572.715648] Starting subtest: flip-vs-dpms-off-vs-modeset
[572.717665] Starting dynamic subtest: A-eDP1
[576.308766] Dynamic subtest A-eDP1: SUCCESS (3.589s)
[576.319605] Starting dynamic subtest: B-eDP1
[579.505307] Dynamic subtest B-eDP1: SUCCESS (3.196s)
[579.516279] Starting dynamic subtest: C-eDP1
[582.601612] Dynamic subtest C-eDP1: SUCCESS (3.094s)
[582.612454] Starting dynamic subtest: D-eDP1
[585.737583] Dynamic subtest D-eDP1: SUCCESS (3.131s)
[585.739481] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (13.018s)
[586.548489] [125/133] (436s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[586.650613] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[586.654964] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[586.775983] [126/133] (436s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[586.880461] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[586.882794] Starting dynamic subtest: pipe-A
[586.908140] Dynamic subtest pipe-A: SKIP (0.024s)
[586.918781] Starting dynamic subtest: pipe-B
[586.976428] Dynamic subtest pipe-B: SKIP (0.063s)
[586.986643] Starting dynamic subtest: pipe-C
[587.042716] Dynamic subtest pipe-C: SKIP (0.065s)
[587.052699] Starting dynamic subtest: pipe-D
[587.086775] Dynamic subtest pipe-D: SKIP (0.042s)
[587.091092] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SKIP (0.204s)
[587.214703] [127/133] (435s left) kms_cursor_crc (cursor-onscreen-64x21)
[587.407511] Starting subtest: cursor-onscreen-64x21
[587.451308] Subtest cursor-onscreen-64x21: SKIP (0.041s)
[587.720430] [128/133] (435s left) core_setmaster (master-drop-set-user)
[587.805524] Starting subtest: master-drop-set-user
[587.819722] Subtest master-drop-set-user: SUCCESS (0.014s)
[587.860070] [129/133] (435s left) prime_vgem (fence-wait)
[587.949235] Starting subtest: fence-wait
[587.951872] Starting dynamic subtest: rcs0
[589.004771] Dynamic subtest rcs0: SUCCESS (1.052s)
[589.017097] Starting dynamic subtest: bcs0
[590.086967] Dynamic subtest bcs0: SUCCESS (1.079s)
[590.100104] Starting dynamic subtest: vcs0
[591.135654] Dynamic subtest vcs0: SUCCESS (1.047s)
[591.147411] Starting dynamic subtest: vcs1
[592.181520] Dynamic subtest vcs1: SUCCESS (1.043s)
[592.194700] Starting dynamic subtest: vecs0
[593.223126] Dynamic subtest vecs0: SUCCESS (1.038s)
[593.236065] Starting dynamic subtest: ccs0
[594.735013] Dynamic subtest ccs0: SUCCESS (1.509s)
[594.738065] Subtest fence-wait: SUCCESS (6.782s)
[594.918595] [130/133] (428s left) gem_mmap_wc (write-wc-read-gtt)
[594.988763] Subtest write-wc-read-gtt: SKIP (0.000s)
[595.068631] [131/133] (428s left) kms_psr (pr-cursor-blt)
[595.167768] Starting subtest: pr-cursor-blt
[595.171683] Subtest pr-cursor-blt: SKIP (0.000s)
[595.255172] [132/133] (428s left) gen7_exec_parse (basic-allowed)
[595.319282] Subtest basic-allowed: SKIP (0.000s)
[595.415567] [133/133] (427s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-sf)
[595.513437] Starting subtest: psr2-overlay-plane-move-continuous-exceed-sf
[595.515695] Starting dynamic subtest: pipe-A-eDP-1
[606.812427] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.296s)
[606.823798] Starting dynamic subtest: pipe-B-eDP-1
[618.863889] Dynamic subtest pipe-B-eDP-1: SUCCESS (12.046s)
[618.866316] Subtest psr2-overlay-plane-move-continuous-exceed-sf: SUCCESS (23.345s)
[619.714987] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: gem_render_copy
results: parsing output: 2/ for test: gem_exec_gttfill
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_pm_rpm
results: parsing output: 5/ for test: gem_exec_schedule
results: parsing output: 6/ for test: syncobj_timeline
results: parsing output: 7/ for test: kms_properties
results: parsing output: 8/ for test: perf
results: parsing output: 9/ for test: api_intel_allocator
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_addfb_basic
results: parsing output: 12/ for test: kms_ccs
results: parsing output: 13/ for test: gem_workarounds
results: parsing output: 14/ for test: gem_ctx_persistence
results: parsing output: 15/ for test: gem_exec_schedule
results: parsing output: 16/ for test: gem_exec_fence
results: parsing output: 17/ for test: sysfs_heartbeat_interval
results: parsing output: 18/ for test: gem_caching
results: parsing output: 19/ for test: syncobj_wait
results: parsing output: 20/ for test: gem_mmap_offset
results: parsing output: 21/ for test: syncobj_timeline
results: parsing output: 22/ for test: gem_spin_batch
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: i915_pm_rpm
results: parsing output: 25/ for test: perf
results: parsing output: 26/ for test: gem_exec_flush
results: parsing output: 27/ for test: syncobj_wait
results: parsing output: 28/ for test: kms_flip
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: gem_exec_balancer
results: parsing output: 31/ for test: kms_big_fb
results: parsing output: 32/ for test: kms_invalid_mode
results: parsing output: 33/ for test: kms_big_fb
results: parsing output: 34/ for test: kms_addfb_basic
results: parsing output: 35/ for test: gen7_exec_parse
results: parsing output: 36/ for test: kms_async_flips
results: parsing output: 37/ for test: syncobj_wait
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: kms_rotation_crc
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: kms_legacy_colorkey
results: parsing output: 42/ for test: kms_plane_alpha_blend
results: parsing output: 43/ for test: gem_mmap
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: gem_exec_schedule
results: parsing output: 46/ for test: gem_exec_async
results: parsing output: 47/ for test: core_auth
results: parsing output: 48/ for test: kms_color
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: gem_exec_fence
results: parsing output: 51/ for test: kms_psr
results: parsing output: 52/ for test: kms_plane_scaling
results: parsing output: 53/ for test: kms_flip
results: parsing output: 54/ for test: gem_exec_capture
results: parsing output: 55/ for test: kms_plane
results: parsing output: 56/ for test: kms_flip_scaled_crc
results: parsing output: 57/ for test: i915_query
results: parsing output: 58/ for test: kms_big_fb
results: parsing output: 59/ for test: kms_content_protection
results: parsing output: 60/ for test: gem_ctx_persistence
results: parsing output: 61/ for test: gem_exec_flush
results: parsing output: 62/ for test: kms_big_fb
results: parsing output: 63/ for test: gem_mmap_gtt
results: parsing output: 64/ for test: kms_psr2_sf
results: parsing output: 65/ for test: kms_flip
results: parsing output: 66/ for test: kms_flip
results: parsing output: 67/ for test: gem_softpin
results: parsing output: 68/ for test: kms_big_fb
results: parsing output: 69/ for test: gem_exec_params
results: parsing output: 70/ for test: kms_dsc
results: parsing output: 71/ for test: kms_cursor_crc
results: parsing output: 72/ for test: i915_hangman
results: parsing output: 73/ for test: kms_flip_scaled_crc
results: parsing output: 74/ for test: kms_selftest
results: parsing output: 75/ for test: syncobj_timeline
results: parsing output: 76/ for test: gem_ctx_param
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: prime_self_import
results: parsing output: 79/ for test: i915_drm_fdinfo
results: parsing output: 80/ for test: kms_ccs
results: parsing output: 81/ for test: gem_userptr_blits
results: parsing output: 82/ for test: kms_big_fb
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: gem_create
results: parsing output: 85/ for test: kms_vblank
results: parsing output: 86/ for test: api_intel_bb
results: parsing output: 87/ for test: kms_chamelium_frames
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_cursor_legacy
results: parsing output: 91/ for test: gem_pxp
results: parsing output: 92/ for test: syncobj_timeline
results: parsing output: 93/ for test: kms_cursor_legacy
results: parsing output: 94/ for test: perf_pmu
results: parsing output: 95/ for test: kms_pm_rpm
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_ccs
results: parsing output: 98/ for test: kms_draw_crc
results: parsing output: 99/ for test: kms_flip
results: parsing output: 100/ for test: kms_psr
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_cursor_edge_walk
results: parsing output: 103/ for test: gem_ccs
results: parsing output: 104/ for test: gem_exec_reloc
results: parsing output: 105/ for test: perf
results: parsing output: 106/ for test: gem_lmem_swapping
results: parsing output: 107/ for test: kms_psr
results: parsing output: 108/ for test: gem_ctx_exec
results: parsing output: 109/ for test: kms_ccs
results: parsing output: 110/ for test: kms_big_fb
results: parsing output: 111/ for test: kms_plane
results: parsing output: 112/ for test: kms_big_fb
results: parsing output: 113/ for test: kms_chamelium_hpd
results: parsing output: 114/ for test: syncobj_timeline
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: perf_pmu
results: parsing output: 117/ for test: kms_psr
results: parsing output: 118/ for test: kms_ccs
results: parsing output: 119/ for test: prime_mmap
results: parsing output: 120/ for test: gem_exec_reloc
results: parsing output: 121/ for test: kms_psr2_sf
results: parsing output: 122/ for test: kms_cursor_legacy
results: parsing output: 123/ for test: kms_flip
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_plane_scaling
results: parsing output: 126/ for test: kms_cursor_crc
results: parsing output: 127/ for test: core_setmaster
results: parsing output: 128/ for test: prime_vgem
results: parsing output: 129/ for test: gem_mmap_wc
results: parsing output: 130/ for test: kms_psr
results: parsing output: 131/ for test: gen7_exec_parse
results: parsing output: 132/ for test: kms_psr2_sf
Done.