[51.742178] Initializing watchdogs
[51.742222]   /dev/watchdog0
[51.755866] [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]
[51.760215] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[51.762767] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[51.766361] [001/133] (960s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[51.896082] Starting subtest: fbc-rgb101010-draw-render
[56.543074] Subtest fbc-rgb101010-draw-render: SUCCESS (4.649s)
[57.736752] [002/133] (954s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[57.791738] Starting subtest: y-tiled-to-vebox-x-tiled
[57.795713] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[57.980840] [003/133] (954s left) gem_exec_gttfill (basic)
[58.043916] Starting subtest: basic
[60.899476] Subtest basic: SUCCESS (2.853s)
[61.094833] [004/133] (951s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-pwrite)
[61.252548] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-pwrite
[65.707852] Subtest fbc-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (4.457s)
[66.848051] [005/133] (945s left) kms_pm_rpm (dpms-non-lpsp)
[69.048210] Starting subtest: dpms-non-lpsp
[69.053555] Subtest dpms-non-lpsp: SKIP (0.001s)
[69.442932] [006/133] (943s left) gem_exec_schedule (submit-golden-slice)
[69.535502] Starting subtest: submit-golden-slice
[69.537587] Starting dynamic subtest: rcs0
[69.559518] Dynamic subtest rcs0: SUCCESS (0.031s)
[69.568743] Starting dynamic subtest: bcs0
[69.585243] Dynamic subtest bcs0: SUCCESS (0.025s)
[69.595149] Starting dynamic subtest: vcs0
[69.612144] Dynamic subtest vcs0: SUCCESS (0.027s)
[69.619997] Starting dynamic subtest: vcs1
[69.640100] Dynamic subtest vcs1: SUCCESS (0.028s)
[69.647505] Starting dynamic subtest: vecs0
[69.666788] Dynamic subtest vecs0: SUCCESS (0.026s)
[69.674650] Starting dynamic subtest: ccs0
[69.695450] Dynamic subtest ccs0: SUCCESS (0.028s)
[69.697742] Subtest submit-golden-slice: SUCCESS (0.167s)
[71.041393] [007/133] (941s left) syncobj_timeline (reset-during-wait-for-submit)
[71.101923] Starting subtest: reset-during-wait-for-submit
[71.205582] Subtest reset-during-wait-for-submit: SUCCESS (0.103s)
[71.290064] [008/133] (941s left) kms_properties (connector-properties-legacy)
[71.384390] Starting subtest: connector-properties-legacy
[71.386833] Starting dynamic subtest: pipe-A-eDP-1
[79.171133] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.785s)
[79.181537] Starting dynamic subtest: pipe-None-DP-1
[79.185720] Dynamic subtest pipe-None-DP-1: SUCCESS (0.001s)
[79.192324] Starting dynamic subtest: pipe-None-HDMI-A-1
[79.196981] Dynamic subtest pipe-None-HDMI-A-1: SUCCESS (0.001s)
[79.203613] Starting dynamic subtest: pipe-None-DP-2
[79.207707] Dynamic subtest pipe-None-DP-2: SUCCESS (0.001s)
[79.214419] Starting dynamic subtest: pipe-None-HDMI-A-2
[79.218425] Dynamic subtest pipe-None-HDMI-A-2: SUCCESS (0.001s)
[79.225076] Starting dynamic subtest: pipe-None-DP-3
[79.229161] Dynamic subtest pipe-None-DP-3: SUCCESS (0.001s)
[79.235803] Starting dynamic subtest: pipe-None-DP-4
[79.240482] Dynamic subtest pipe-None-DP-4: SUCCESS (0.001s)
[79.242867] Subtest connector-properties-legacy: SUCCESS (7.793s)
[80.044106] [009/133] (932s left) perf (i915-ref-count)
[80.140157] Starting subtest: i915-ref-count
[80.198470] Subtest i915-ref-count: SUCCESS (0.060s)
[80.255856] [010/133] (932s left) api_intel_allocator (gem-pool)
[80.302618] Starting subtest: gem-pool
[80.310120] Subtest gem-pool: SUCCESS (0.009s)
[80.460532] [011/133] (932s left) kms_frontbuffer_tracking (fbc-stridechange)
[80.565754] Starting subtest: fbc-stridechange
[83.885764] Subtest fbc-stridechange: SUCCESS (3.322s)
[85.072897] [012/133] (927s left) kms_addfb_basic (bad-pitch-1024)
[85.132024] Starting subtest: bad-pitch-1024
[85.134452] Subtest bad-pitch-1024: SUCCESS (0.000s)
[85.293221] [013/133] (927s left) kms_ccs (bad-aux-stride-y-tiled-ccs)
[85.393511] Starting subtest: bad-aux-stride-y-tiled-ccs
[85.395694] Starting dynamic subtest: pipe-A-eDP-1
[85.418536] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[85.428146] Starting dynamic subtest: pipe-B-eDP-1
[85.449707] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[85.456956] Starting dynamic subtest: pipe-C-eDP-1
[85.478235] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[85.485720] Starting dynamic subtest: pipe-D-eDP-1
[85.508335] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[85.512680] Subtest bad-aux-stride-y-tiled-ccs: SKIP (0.007s)
[87.698493] [014/133] (925s left) gem_workarounds (suspend-resume-fd)
[87.775133] Starting subtest: suspend-resume-fd
[103.928145] Subtest suspend-resume-fd: SUCCESS (1.303s)
[105.096627] [015/133] (908s left) gem_ctx_persistence (legacy-engines-mixed)
[105.168084] Starting subtest: legacy-engines-mixed
[105.170523] Starting dynamic subtest: render
[105.291962] Dynamic subtest render: SUCCESS (0.123s)
[105.303103] Starting dynamic subtest: bsd1
[105.349800] Dynamic subtest bsd1: SUCCESS (0.055s)
[105.360531] Starting dynamic subtest: bsd2
[105.418039] Dynamic subtest bsd2: SUCCESS (0.066s)
[105.429662] Starting dynamic subtest: blt
[105.488021] Dynamic subtest blt: SUCCESS (0.068s)
[105.498669] Starting dynamic subtest: vebox
[105.576970] Dynamic subtest vebox: SUCCESS (0.087s)
[105.579720] Subtest legacy-engines-mixed: SUCCESS (0.408s)
[105.665286] [016/133] (908s left) gem_exec_schedule (out-order)
[105.736843] Starting subtest: out-order
[105.738822] Starting dynamic subtest: rcs0
[105.820066] Dynamic subtest rcs0: SUCCESS (0.091s)
[105.831453] Starting dynamic subtest: vcs0
[105.893863] Dynamic subtest vcs0: SUCCESS (0.073s)
[105.905073] Starting dynamic subtest: vcs1
[105.971322] Dynamic subtest vcs1: SUCCESS (0.077s)
[105.982909] Starting dynamic subtest: vecs0
[106.046044] Dynamic subtest vecs0: SUCCESS (0.074s)
[106.057449] Starting dynamic subtest: ccs0
[106.127522] Dynamic subtest ccs0: SUCCESS (0.081s)
[106.129676] Subtest out-order: SUCCESS (0.398s)
[107.866627] [017/133] (906s left) gem_exec_fence (parallel)
[108.062666] Starting subtest: parallel
[108.065070] Starting dynamic subtest: rcs0
[110.219642] Dynamic subtest rcs0: SUCCESS (2.153s)
[110.230277] Starting dynamic subtest: bcs0
[112.375078] Dynamic subtest bcs0: SUCCESS (2.151s)
[112.385617] Starting dynamic subtest: vcs0
[114.533062] Dynamic subtest vcs0: SUCCESS (2.154s)
[114.544669] Starting dynamic subtest: vcs1
[116.687256] Dynamic subtest vcs1: SUCCESS (2.151s)
[116.697216] Starting dynamic subtest: vecs0
[118.842961] Dynamic subtest vecs0: SUCCESS (2.153s)
[118.853897] Starting dynamic subtest: ccs0
[120.996886] Dynamic subtest ccs0: SUCCESS (2.152s)
[120.998881] Subtest parallel: SUCCESS (12.916s)
[121.883133] [FACT gem_exec_fence (parallel)] new: kernel.kmod_is_loaded.vgem: true
[121.886204] [018/133] (892s left) sysfs_heartbeat_interval (idempotent)
[121.954398] Starting subtest: idempotent
[121.956731] Starting dynamic subtest: vecs0
[121.959156] Dynamic subtest vecs0: SUCCESS (0.001s)
[121.968961] Starting dynamic subtest: ccs0
[121.970880] Dynamic subtest ccs0: SUCCESS (0.002s)
[121.978469] Starting dynamic subtest: vcs0
[121.980356] Dynamic subtest vcs0: SUCCESS (0.000s)
[121.987980] Starting dynamic subtest: bcs0
[121.989887] Dynamic subtest bcs0: SUCCESS (0.002s)
[121.997468] Starting dynamic subtest: rcs0
[121.999370] Dynamic subtest rcs0: SUCCESS (0.001s)
[122.006954] Starting dynamic subtest: vcs1
[122.008861] Dynamic subtest vcs1: SUCCESS (0.000s)
[122.010758] Subtest idempotent: SUCCESS (0.009s)
[122.107512] [019/133] (891s left) gem_caching (read-writes)
[122.181619] Subtest read-writes: SKIP (0.000s)
[122.267204] [020/133] (891s left) syncobj_wait (reset-multiple-signaled)
[122.314932] Starting subtest: reset-multiple-signaled
[122.316916] Subtest reset-multiple-signaled: SUCCESS (0.000s)
[122.443168] [021/133] (891s left) gem_mmap_offset (partial-remap)
[122.505969] Starting subtest: partial-remap
[122.508286] Starting dynamic subtest: smem0
[122.517077] Dynamic subtest smem0: SUCCESS (0.005s)
[122.519053] Subtest partial-remap: SUCCESS (0.005s)
[122.626231] [022/133] (891s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted)
[122.693659] Starting subtest: etime-multi-wait-for-submit-unsubmitted
[122.796230] Subtest etime-multi-wait-for-submit-unsubmitted: SUCCESS (0.102s)
[122.883489] [023/133] (891s left) gem_spin_batch (spin-each)
[122.947424] Starting subtest: spin-each
[126.315053] Subtest spin-each: SUCCESS (3.365s)
[126.861492] [024/133] (887s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[126.963254] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[126.967288] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[127.086619] [025/133] (887s left) i915_pm_rpm (debugfs-read)
[128.913378] Starting subtest: debugfs-read
[130.084145] Subtest debugfs-read: SUCCESS (1.166s)
[130.472722] [026/133] (883s left) perf (blocking-parameterized)
[130.566793] Starting subtest: blocking-parameterized
[150.584166] Subtest blocking-parameterized: SUCCESS (20.017s)
[150.631503] [027/133] (863s left) gem_exec_flush (basic-batch-kernel-default-cmd)
[150.700196] Starting subtest: basic-batch-kernel-default-cmd
[150.704525] Subtest basic-batch-kernel-default-cmd: SKIP (0.000s)
[150.907858] [028/133] (863s left) syncobj_wait (invalid-reset-zero-handles)
[150.968972] Starting subtest: invalid-reset-zero-handles
[150.971379] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[151.082714] [029/133] (863s left) kms_flip (flip-vs-blocking-wf-vblank)
[151.185903] Starting subtest: flip-vs-blocking-wf-vblank
[151.188165] Starting dynamic subtest: A-eDP1
[160.101939] Dynamic subtest A-eDP1: SUCCESS (8.917s)
[160.113293] Starting dynamic subtest: B-eDP1
[169.038041] Dynamic subtest B-eDP1: SUCCESS (8.934s)
[169.050162] Starting dynamic subtest: C-eDP1
[177.965182] Dynamic subtest C-eDP1: SUCCESS (8.924s)
[177.975922] Starting dynamic subtest: D-eDP1
[186.902345] Dynamic subtest D-eDP1: SUCCESS (8.932s)
[186.906453] Subtest flip-vs-blocking-wf-vblank: SUCCESS (35.715s)
[188.150608] [030/133] (826s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[188.253311] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu
[194.777617] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (6.527s)
[194.924603] [031/133] (819s left) gem_exec_balancer (bonded-semaphore)
[194.993770] Starting subtest: bonded-semaphore
[194.998079] Subtest bonded-semaphore: SKIP (0.000s)
[195.309114] [032/133] (819s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[195.411225] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[195.415170] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[195.540986] [033/133] (818s left) kms_invalid_mode (int-max-clock)
[195.640055] Starting subtest: int-max-clock
[195.642332] Starting dynamic subtest: pipe-A-eDP-1
[195.923338] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.283s)
[195.932142] Starting dynamic subtest: pipe-B-eDP-1
[195.934297] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[195.942298] Starting dynamic subtest: pipe-C-eDP-1
[195.944487] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[195.952104] Starting dynamic subtest: pipe-D-eDP-1
[195.953915] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[195.955753] Subtest int-max-clock: SUCCESS (0.288s)
[196.814895] [034/133] (817s left) kms_big_fb (4-tiled-16bpp-rotate-0)
[196.921812] Starting subtest: 4-tiled-16bpp-rotate-0
[198.244719] Subtest 4-tiled-16bpp-rotate-0: SUCCESS (1.323s)
[198.410579] [035/133] (816s left) kms_addfb_basic (bad-pitch-999)
[198.469221] Starting subtest: bad-pitch-999
[198.470818] Subtest bad-pitch-999: SUCCESS (0.000s)
[198.613837] [036/133] (815s left) gen7_exec_parse (cmd-crossing-page)
[198.682317] Subtest cmd-crossing-page: SKIP (0.000s)
[198.766051] [037/133] (815s left) kms_async_flips (crc)
[198.856370] Starting subtest: crc
[199.226609] Starting dynamic subtest: pipe-A-eDP-1
[202.114500] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.888s)
[202.493066] Starting dynamic subtest: pipe-B-eDP-1
[205.443854] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.962s)
[205.846692] Starting dynamic subtest: pipe-C-eDP-1
[208.746133] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.912s)
[209.106264] Starting dynamic subtest: pipe-D-eDP-1
[211.973948] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.876s)
[211.976147] Subtest crc: SUCCESS (13.122s)
[213.155895] [038/133] (801s left) syncobj_wait (single-wait-all-for-submit-submitted)
[213.220775] Starting subtest: single-wait-all-for-submit-submitted
[213.322836] Subtest single-wait-all-for-submit-submitted: SUCCESS (0.103s)
[213.411909] [039/133] (801s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[213.505286] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[213.509556] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[213.626763] [040/133] (801s left) kms_rotation_crc (primary-yf-tiled-reflect-x-0)
[213.719779] Starting subtest: primary-yf-tiled-reflect-x-0
[213.779034] Subtest primary-yf-tiled-reflect-x-0: SKIP (0.059s)
[213.896684] [041/133] (800s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[214.001291] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[214.005258] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[214.122571] [042/133] (800s left) kms_legacy_colorkey (basic)
[214.220146] Starting subtest: basic
[214.222037] Starting dynamic subtest: pipe-A
[214.264533] Dynamic subtest pipe-A: SUCCESS (0.045s)
[214.275597] Starting dynamic subtest: pipe-B
[214.277845] Dynamic subtest pipe-B: SUCCESS (0.001s)
[214.286030] Starting dynamic subtest: pipe-C
[214.288481] Dynamic subtest pipe-C: SUCCESS (0.000s)
[214.296700] Starting dynamic subtest: pipe-D
[214.298888] Dynamic subtest pipe-D: SUCCESS (0.000s)
[214.301187] Subtest basic: SUCCESS (0.050s)
[214.358802] [043/133] (800s left) kms_plane_alpha_blend (alpha-7efc)
[214.456404] Starting subtest: alpha-7efc
[214.752249] Starting dynamic subtest: pipe-A-eDP-1
[215.363392] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.608s)
[215.390201] Starting dynamic subtest: pipe-D-eDP-1
[216.665686] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.283s)
[216.668074] Subtest alpha-7efc: SUCCESS (2.209s)
[217.540655] [044/133] (797s left) gem_mmap (big-bo)
[217.601628] Subtest big-bo: SKIP (0.000s)
[217.659136] [045/133] (797s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[217.766565] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt
[217.770997] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[217.904350] [046/133] (797s left) gem_exec_schedule (u-submit-golden-slice)
[217.986936] Starting subtest: u-submit-golden-slice
[217.989235] Starting dynamic subtest: rcs0
[218.008631] Dynamic subtest rcs0: SUCCESS (0.030s)
[218.019872] Starting dynamic subtest: bcs0
[218.037276] Dynamic subtest bcs0: SUCCESS (0.028s)
[218.045727] Starting dynamic subtest: vcs0
[218.067091] Dynamic subtest vcs0: SUCCESS (0.029s)
[218.075558] Starting dynamic subtest: vcs1
[218.097826] Dynamic subtest vcs1: SUCCESS (0.030s)
[218.106831] Starting dynamic subtest: vecs0
[218.124040] Dynamic subtest vecs0: SUCCESS (0.026s)
[218.132489] Starting dynamic subtest: ccs0
[218.153433] Dynamic subtest ccs0: SUCCESS (0.029s)
[218.155651] Subtest u-submit-golden-slice: SUCCESS (0.176s)
[219.835990] [047/133] (795s left) gem_exec_async (forked-writes)
[219.908101] Starting subtest: forked-writes
[219.910578] Starting dynamic subtest: rcs0
[219.934670] Dynamic subtest rcs0: SUCCESS (0.026s)
[219.950589] Starting dynamic subtest: bcs0
[219.958419] Dynamic subtest bcs0: SUCCESS (0.023s)
[219.970971] Starting dynamic subtest: vcs0
[219.980183] Dynamic subtest vcs0: SUCCESS (0.021s)
[219.989273] Starting dynamic subtest: vcs1
[220.000624] Dynamic subtest vcs1: SUCCESS (0.020s)
[220.009699] Starting dynamic subtest: vecs0
[220.023203] Dynamic subtest vecs0: SUCCESS (0.022s)
[220.032942] Starting dynamic subtest: ccs0
[220.044285] Dynamic subtest ccs0: SUCCESS (0.020s)
[220.046470] Subtest forked-writes: SUCCESS (0.137s)
[220.315310] [048/133] (794s left) core_auth (many-magics)
[220.389482] Starting subtest: many-magics
[221.108431] Subtest many-magics: SUCCESS (0.721s)
[221.307085] [049/133] (793s left) kms_color (deep-color)
[221.416411] Starting subtest: deep-color
[222.490857] Starting dynamic subtest: pipe-A-eDP-1-gamma
[223.197956] Dynamic subtest pipe-A-eDP-1-gamma: SUCCESS (0.708s)
[223.207199] Starting dynamic subtest: pipe-A-eDP-1-degamma
[224.700913] Dynamic subtest pipe-A-eDP-1-degamma: SUCCESS (1.503s)
[224.710310] Starting dynamic subtest: pipe-A-eDP-1-ctm
[226.091591] Dynamic subtest pipe-A-eDP-1-ctm: SUCCESS (1.389s)
[226.886476] Starting dynamic subtest: pipe-B-eDP-1-gamma
[227.592869] Dynamic subtest pipe-B-eDP-1-gamma: SUCCESS (0.713s)
[227.602598] Starting dynamic subtest: pipe-B-eDP-1-degamma
[229.033587] Dynamic subtest pipe-B-eDP-1-degamma: SUCCESS (1.442s)
[229.046922] Starting dynamic subtest: pipe-B-eDP-1-ctm
[230.459188] Dynamic subtest pipe-B-eDP-1-ctm: SUCCESS (1.425s)
[231.266761] Starting dynamic subtest: pipe-C-eDP-1-gamma
[231.941289] Dynamic subtest pipe-C-eDP-1-gamma: SUCCESS (0.683s)
[231.950227] Starting dynamic subtest: pipe-C-eDP-1-degamma
[233.307058] Dynamic subtest pipe-C-eDP-1-degamma: SUCCESS (1.365s)
[233.315316] Starting dynamic subtest: pipe-C-eDP-1-ctm
[234.703189] Dynamic subtest pipe-C-eDP-1-ctm: SUCCESS (1.396s)
[235.445980] Starting dynamic subtest: pipe-D-eDP-1-gamma
[236.117206] Dynamic subtest pipe-D-eDP-1-gamma: SUCCESS (0.681s)
[236.126526] Starting dynamic subtest: pipe-D-eDP-1-degamma
[237.466432] Dynamic subtest pipe-D-eDP-1-degamma: SUCCESS (1.347s)
[237.476041] Starting dynamic subtest: pipe-D-eDP-1-ctm
[238.826827] Dynamic subtest pipe-D-eDP-1-ctm: SUCCESS (1.360s)
[238.829289] Subtest deep-color: SUCCESS (17.410s)
[239.700904] [050/133] (775s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[239.802100] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[239.806394] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[239.921065] [051/133] (775s left) gem_exec_fence (syncobj-timeline-unused-fence)
[240.471894] Starting subtest: syncobj-timeline-unused-fence
[240.478059] Subtest syncobj-timeline-unused-fence: SUCCESS (0.006s)
[241.881885] [052/133] (773s left) kms_psr (fbc-psr-cursor-plane-move)
[241.989056] Starting subtest: fbc-psr-cursor-plane-move
[241.991157] Starting dynamic subtest: eDP-1
[241.995081] Dynamic subtest eDP-1: SKIP (0.000s)
[241.999152] Subtest fbc-psr-cursor-plane-move: SKIP (0.001s)
[242.069397] [053/133] (773s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[242.165979] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[242.167958] Starting dynamic subtest: pipe-A
[242.513880] Dynamic subtest pipe-A: SUCCESS (0.351s)
[242.522306] Starting dynamic subtest: pipe-B
[243.928795] Dynamic subtest pipe-B: SUCCESS (1.414s)
[243.938696] Starting dynamic subtest: pipe-C
[245.312412] Dynamic subtest pipe-C: SUCCESS (1.382s)
[245.324940] Starting dynamic subtest: pipe-D
[246.743743] Dynamic subtest pipe-D: SUCCESS (1.430s)
[246.746045] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.581s)
[247.921134] [054/133] (767s left) kms_flip (2x-flip-vs-suspend)
[248.015199] Starting subtest: 2x-flip-vs-suspend
[248.023687] Subtest 2x-flip-vs-suspend: SKIP (0.010s)
[248.155315] [055/133] (767s left) gem_exec_capture (pi)
[248.235730] Starting subtest: pi
[248.238528] Starting dynamic subtest: rcs0
[252.443558] Dynamic subtest rcs0: SUCCESS (4.207s)
[252.455934] Starting dynamic subtest: bcs0
[256.444269] Dynamic subtest bcs0: SUCCESS (3.998s)
[256.453722] Starting dynamic subtest: vcs0
[260.470069] Dynamic subtest vcs0: SUCCESS (4.022s)
[260.481757] Starting dynamic subtest: vcs1
[264.469229] Dynamic subtest vcs1: SUCCESS (3.996s)
[264.480538] Starting dynamic subtest: vecs0
[268.573811] Dynamic subtest vecs0: SUCCESS (4.102s)
[268.585164] Starting dynamic subtest: ccs0
[272.648575] Dynamic subtest ccs0: SUCCESS (4.072s)
[272.651042] Subtest pi: SUCCESS (24.410s)
[272.741269] [056/133] (742s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping)
[272.897789] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping
[272.925711] Starting dynamic subtest: pipe-A-plane-0
[273.203220] Dynamic subtest pipe-A-plane-0: SUCCESS (0.278s)
[273.212474] Starting dynamic subtest: pipe-A-plane-3
[273.436630] Dynamic subtest pipe-A-plane-3: SUCCESS (0.233s)
[274.519825] Starting dynamic subtest: pipe-B-plane-0
[274.799840] Dynamic subtest pipe-B-plane-0: SUCCESS (0.289s)
[274.808853] Starting dynamic subtest: pipe-B-plane-3
[275.032190] Dynamic subtest pipe-B-plane-3: SUCCESS (0.233s)
[275.338988] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping: SUCCESS (2.442s)
[276.191843] [057/133] (739s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-upscaling)
[276.280054] Starting subtest: flip-64bpp-linear-to-32bpp-linear-upscaling
[276.282391] Starting dynamic subtest: pipe-A-default-mode
[279.037174] Dynamic subtest pipe-A-default-mode: SUCCESS (2.757s)
[279.039526] Subtest flip-64bpp-linear-to-32bpp-linear-upscaling: SUCCESS (2.759s)
[279.905439] [058/133] (735s left) i915_query (query-regions-garbage-items)
[279.962941] Starting subtest: query-regions-garbage-items
[279.965293] Subtest query-regions-garbage-items: SUCCESS (0.000s)
[280.071337] [059/133] (735s left) kms_big_fb (x-tiled-8bpp-rotate-180)
[280.183248] Starting subtest: x-tiled-8bpp-rotate-180
[281.385642] Subtest x-tiled-8bpp-rotate-180: SUCCESS (1.202s)
[281.542935] [060/133] (734s left) kms_content_protection (srm)
[281.691595] Starting subtest: srm
[281.698114] Subtest srm: SKIP (0.003s)
[281.866621] [061/133] (733s left) gem_ctx_persistence (engines-cleanup)
[281.923300] Starting subtest: engines-cleanup
[281.925350] Starting dynamic subtest: rcs0
[282.006089] Dynamic subtest rcs0: SUCCESS (0.082s)
[282.020349] Starting dynamic subtest: bcs0
[282.077287] Dynamic subtest bcs0: SUCCESS (0.069s)
[282.088199] Starting dynamic subtest: vcs0
[282.128112] Dynamic subtest vcs0: SUCCESS (0.049s)
[282.138729] Starting dynamic subtest: vcs1
[282.194678] Dynamic subtest vcs1: SUCCESS (0.064s)
[282.205831] Starting dynamic subtest: vecs0
[282.267529] Dynamic subtest vecs0: SUCCESS (0.071s)
[282.278383] Starting dynamic subtest: ccs0
[282.323321] Dynamic subtest ccs0: SUCCESS (0.054s)
[282.325461] Subtest engines-cleanup: SUCCESS (0.401s)
[282.413635] [062/133] (733s left) gem_exec_flush (basic-batch-kernel-default-wb)
[282.479976] Starting subtest: basic-batch-kernel-default-wb
[289.852108] Subtest basic-batch-kernel-default-wb: SUCCESS (7.370s)
[290.048751] [063/133] (725s left) kms_big_fb (4-tiled-8bpp-rotate-0)
[290.153205] Starting subtest: 4-tiled-8bpp-rotate-0
[291.278767] Subtest 4-tiled-8bpp-rotate-0: SUCCESS (1.127s)
[291.445734] [064/133] (724s left) gem_mmap_gtt (close-race)
[291.516114] Subtest close-race: SKIP (0.000s)
[291.579264] [065/133] (724s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[291.685862] Starting subtest: pr-cursor-plane-move-continuous-exceed-sf
[291.689302] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[291.764417] [066/133] (724s left) kms_flip (2x-flip-vs-expired-vblank)
[291.862819] Starting subtest: 2x-flip-vs-expired-vblank
[291.872068] Subtest 2x-flip-vs-expired-vblank: SKIP (0.010s)
[292.002138] [067/133] (723s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset)
[292.094621] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset
[292.096673] Starting dynamic subtest: A-eDP1
[295.716648] Dynamic subtest A-eDP1: SUCCESS (3.618s)
[295.727862] Starting dynamic subtest: B-eDP1
[298.924713] Dynamic subtest B-eDP1: SUCCESS (3.204s)
[298.935233] Starting dynamic subtest: C-eDP1
[302.042391] Dynamic subtest C-eDP1: SUCCESS (3.117s)
[302.052799] Starting dynamic subtest: D-eDP1
[305.169773] Dynamic subtest D-eDP1: SUCCESS (3.126s)
[305.172071] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (13.073s)
[305.985643] [068/133] (709s left) gem_softpin (softpin)
[306.046737] Starting subtest: softpin
[338.427946] Subtest softpin: SUCCESS (32.374s)
[338.579070] [069/133] (677s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[338.697223] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[338.701555] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[338.835117] [070/133] (677s left) gem_exec_params (invalid-ring2)
[338.900115] Starting subtest: invalid-ring2
[338.902039] Subtest invalid-ring2: SUCCESS (0.000s)
[339.010600] [071/133] (677s left) kms_dsc (dsc-fractional-bpp-with-bpc)
[339.106055] Starting subtest: dsc-fractional-bpp-with-bpc
[339.136638] Subtest dsc-fractional-bpp-with-bpc: SKIP (0.001s)
[339.192386] [072/133] (676s left) kms_cursor_crc (cursor-rapid-movement-128x42)
[339.425826] Starting subtest: cursor-rapid-movement-128x42
[339.469660] Subtest cursor-rapid-movement-128x42: SKIP (0.042s)
[339.690888] [073/133] (676s left) i915_hangman (gt-error-state-capture)
[339.767092] Starting subtest: gt-error-state-capture
[339.769047] Starting dynamic subtest: rcs0
[351.734399] Dynamic subtest rcs0: SUCCESS (11.969s)
[351.747078] Starting dynamic subtest: bcs0
[363.702672] Dynamic subtest bcs0: SUCCESS (11.967s)
[363.715059] Starting dynamic subtest: vcs0
[375.669975] Dynamic subtest vcs0: SUCCESS (11.965s)
[375.682622] Starting dynamic subtest: vcs1
[386.678363] Dynamic subtest vcs1: SUCCESS (11.006s)
[386.690748] Starting dynamic subtest: vecs0
[397.687078] Dynamic subtest vecs0: SUCCESS (11.006s)
[397.698958] Starting dynamic subtest: ccs0
[409.718535] Dynamic subtest ccs0: SUCCESS (12.028s)
[409.721389] Subtest gt-error-state-capture: SUCCESS (69.957s)
[409.812070] [074/133] (606s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[409.918849] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[409.923258] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.000s)
[410.018858] [075/133] (606s left) kms_selftest (drm_format)
[410.068923] Starting subtest: drm_format
[410.110627] Starting dynamic subtest: drm_test_format_block_width_invalid
[410.257239] Dynamic subtest drm_test_format_block_width_invalid: SUCCESS (0.146s)
[410.268078] Starting dynamic subtest: drm_test_format_block_width_one_plane
[410.383061] Dynamic subtest drm_test_format_block_width_one_plane: SUCCESS (0.123s)
[410.393259] Starting dynamic subtest: drm_test_format_block_width_two_plane
[410.535083] Dynamic subtest drm_test_format_block_width_two_plane: SUCCESS (0.152s)
[410.546044] Starting dynamic subtest: drm_test_format_block_width_three_plane
[410.668068] Dynamic subtest drm_test_format_block_width_three_plane: SUCCESS (0.132s)
[410.678855] Starting dynamic subtest: drm_test_format_block_width_tiled
[410.809014] Dynamic subtest drm_test_format_block_width_tiled: SUCCESS (0.138s)
[410.819605] Starting dynamic subtest: drm_test_format_block_height_invalid
[410.941984] Dynamic subtest drm_test_format_block_height_invalid: SUCCESS (0.132s)
[410.951834] Starting dynamic subtest: drm_test_format_block_height_one_plane
[411.080690] Dynamic subtest drm_test_format_block_height_one_plane: SUCCESS (0.136s)
[411.091060] Starting dynamic subtest: drm_test_format_block_height_two_plane
[411.233867] Dynamic subtest drm_test_format_block_height_two_plane: SUCCESS (0.152s)
[411.242830] Starting dynamic subtest: drm_test_format_block_height_three_plane
[411.382497] Dynamic subtest drm_test_format_block_height_three_plane: SUCCESS (0.148s)
[411.391056] Starting dynamic subtest: drm_test_format_block_height_tiled
[411.498108] Dynamic subtest drm_test_format_block_height_tiled: SUCCESS (0.114s)
[411.508306] Starting dynamic subtest: drm_test_format_min_pitch_invalid
[411.642327] Dynamic subtest drm_test_format_min_pitch_invalid: SUCCESS (0.141s)
[411.652893] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_8bpp
[411.793669] Dynamic subtest drm_test_format_min_pitch_one_plane_8bpp: SUCCESS (0.151s)
[411.803977] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_16bpp
[411.915407] Dynamic subtest drm_test_format_min_pitch_one_plane_16bpp: SUCCESS (0.121s)
[411.923962] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_24bpp
[412.052068] Dynamic subtest drm_test_format_min_pitch_one_plane_24bpp: SUCCESS (0.135s)
[412.062128] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_32bpp
[412.168738] Dynamic subtest drm_test_format_min_pitch_one_plane_32bpp: SUCCESS (0.115s)
[412.177134] Starting dynamic subtest: drm_test_format_min_pitch_two_plane
[412.329428] Dynamic subtest drm_test_format_min_pitch_two_plane: SUCCESS (0.160s)
[412.338202] Starting dynamic subtest: drm_test_format_min_pitch_three_plane_8bpp
[412.477841] Dynamic subtest drm_test_format_min_pitch_three_plane_8bpp: SUCCESS (0.148s)
[412.487460] Starting dynamic subtest: drm_test_format_min_pitch_tiled
[412.601053] Dynamic subtest drm_test_format_min_pitch_tiled: SUCCESS (0.122s)
[412.603348] Subtest drm_format: SUCCESS (2.530s)
[412.815400] [076/133] (603s left) syncobj_timeline (invalid-signal-illegal-handle)
[412.880029] Starting subtest: invalid-signal-illegal-handle
[412.882302] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[412.994494] [077/133] (603s left) gem_ctx_param (invalid-ctx-get)
[413.050098] Starting subtest: invalid-ctx-get
[413.052343] Subtest invalid-ctx-get: SUCCESS (0.000s)
[413.143828] [078/133] (603s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[413.251481] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[419.818691] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.569s)
[419.981303] [079/133] (596s left) prime_self_import (basic-with_fd_dup)
[420.022747] Starting subtest: basic-with_fd_dup
[420.045757] Subtest basic-with_fd_dup: SUCCESS (0.022s)
[420.195074] [080/133] (596s left) i915_drm_fdinfo (busy-idle-check-all)
[420.235708] Starting subtest: busy-idle-check-all
[420.238029] Starting dynamic subtest: rcs0
[420.243748] Dynamic subtest rcs0: SKIP (0.004s)
[420.260678] Starting dynamic subtest: bcs0
[420.265065] Dynamic subtest bcs0: SKIP (0.000s)
[420.276159] Starting dynamic subtest: vcs0
[420.280388] Dynamic subtest vcs0: SKIP (0.000s)
[420.289082] Starting dynamic subtest: vcs1
[420.293326] Dynamic subtest vcs1: SKIP (0.000s)
[420.302241] Starting dynamic subtest: vecs0
[420.306600] Dynamic subtest vecs0: SKIP (0.000s)
[420.315242] Starting dynamic subtest: ccs0
[420.319603] Dynamic subtest ccs0: SKIP (0.000s)
[420.324000] Subtest busy-idle-check-all: SKIP (0.010s)
[420.360479] [081/133] (596s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc)
[420.515060] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc
[420.517268] Starting dynamic subtest: pipe-A-eDP-1
[420.540104] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[420.550465] Starting dynamic subtest: pipe-B-eDP-1
[420.572260] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[420.581284] Starting dynamic subtest: pipe-C-eDP-1
[420.602186] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[420.611087] Starting dynamic subtest: pipe-D-eDP-1
[420.632057] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[420.635929] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc: SKIP (0.006s)
[422.755436] [082/133] (593s left) gem_userptr_blits (map-fixed-invalidate-overlap-busy)
[422.816662] Starting subtest: map-fixed-invalidate-overlap-busy
[422.821053] Subtest map-fixed-invalidate-overlap-busy: SKIP (0.000s)
[422.969158] [083/133] (593s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[423.068674] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[423.073102] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[423.185267] [084/133] (593s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-move)
[423.290537] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-move
[423.294761] Subtest fbcpsr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[423.405741] [085/133] (593s left) gem_create (create-valid-nonaligned)
[423.461049] Starting subtest: create-valid-nonaligned
[423.462929] Subtest create-valid-nonaligned: SUCCESS (0.000s)
[423.655912] [086/133] (592s left) kms_vblank (wait-forked-busy)
[423.753543] Starting subtest: wait-forked-busy
[423.758404] Starting dynamic subtest: pipe-A-eDP-1
[425.641303] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.884s)
[425.655757] Starting dynamic subtest: pipe-D-eDP-1
[428.201781] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.558s)
[428.204594] Subtest wait-forked-busy: SUCCESS (4.448s)
[429.025188] [087/133] (587s left) api_intel_bb (delta-check)
[429.091651] Starting subtest: delta-check
[429.096707] Subtest delta-check: SUCCESS (0.006s)
[429.256970] [088/133] (587s left) kms_chamelium_frames (dp-crc-fast)
[429.642781] Subtest dp-crc-fast: SKIP (0.000s)
[430.410432] [089/133] (586s left) kms_frontbuffer_tracking (psr-farfromfence-mmap-gtt)
[430.506590] Starting subtest: psr-farfromfence-mmap-gtt
[430.511055] Subtest psr-farfromfence-mmap-gtt: SKIP (0.000s)
[430.631891] [090/133] (586s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-blt)
[430.741122] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-blt
[435.151985] Subtest fbc-1p-primscrn-pri-indfb-draw-blt: SUCCESS (4.413s)
[436.327841] [091/133] (580s left) kms_cursor_legacy (2x-cursor-vs-flip-legacy)
[436.454320] Starting subtest: 2x-cursor-vs-flip-legacy
[436.458860] Subtest 2x-cursor-vs-flip-legacy: SKIP (0.001s)
[436.619979] [092/133] (580s left) gem_pxp (reject-modify-context-protection-off-2)
[436.750576] Starting subtest: reject-modify-context-protection-off-2
[436.752841] Subtest reject-modify-context-protection-off-2: SUCCESS (0.002s)
[436.878385] [093/133] (580s left) syncobj_timeline (single-wait-available-submitted)
[436.930293] Starting subtest: single-wait-available-submitted
[436.932190] Subtest single-wait-available-submitted: SUCCESS (0.000s)
[437.050263] [094/133] (579s left) kms_cursor_legacy (torture-bo)
[437.161221] Starting subtest: torture-bo
[437.163384] Starting dynamic subtest: pipe-A
[442.913658] Dynamic subtest pipe-A: SUCCESS (5.748s)
[442.926739] Starting dynamic subtest: pipe-B
[449.386642] Dynamic subtest pipe-B: SUCCESS (6.472s)
[449.396380] Starting dynamic subtest: pipe-C
[456.289199] Dynamic subtest pipe-C: SUCCESS (6.900s)
[456.301716] Starting dynamic subtest: pipe-D
[462.703185] Dynamic subtest pipe-D: SUCCESS (6.411s)
[462.713825] Starting dynamic subtest: all-pipes
[469.154737] Dynamic subtest all-pipes: SUCCESS (6.448s)
[469.156919] Subtest torture-bo: SUCCESS (31.987s)
[469.318899] [095/133] (547s left) perf_pmu (init-sema)
[469.367700] Starting subtest: init-sema
[469.370040] Starting dynamic subtest: rcs0
[469.372283] Dynamic subtest rcs0: SUCCESS (0.002s)
[469.387269] Starting dynamic subtest: bcs0
[469.389662] Dynamic subtest bcs0: SUCCESS (0.000s)
[469.401387] Starting dynamic subtest: vcs0
[469.403597] Dynamic subtest vcs0: SUCCESS (0.000s)
[469.413301] Starting dynamic subtest: vcs1
[469.415357] Dynamic subtest vcs1: SUCCESS (0.000s)
[469.426705] Starting dynamic subtest: vecs0
[469.428806] Dynamic subtest vecs0: SUCCESS (0.000s)
[469.437448] Starting dynamic subtest: ccs0
[469.439556] Dynamic subtest ccs0: SUCCESS (0.000s)
[469.441621] Subtest init-sema: SUCCESS (0.008s)
[469.479909] [096/133] (547s left) kms_pm_rpm (system-suspend-idle)
[471.008787] Starting subtest: system-suspend-idle
[490.465125] Subtest system-suspend-idle: SUCCESS (4.616s)
[490.858888] [097/133] (526s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-wc)
[490.967894] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-wc
[497.484299] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (6.506s)
[497.623672] [098/133] (519s left) kms_ccs (random-ccs-data-yf-tiled-ccs)
[497.732984] Starting subtest: random-ccs-data-yf-tiled-ccs
[497.747399] Starting dynamic subtest: pipe-A-eDP-1
[497.771960] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[497.782787] Starting dynamic subtest: pipe-B-eDP-1
[497.805824] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[497.814936] Starting dynamic subtest: pipe-C-eDP-1
[497.838555] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[497.847774] Starting dynamic subtest: pipe-D-eDP-1
[497.871778] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[497.875827] Subtest random-ccs-data-yf-tiled-ccs: SKIP (0.005s)
[499.987954] [099/133] (517s left) kms_draw_crc (fill-fb)
[500.093544] Starting subtest: fill-fb
[500.512564] Subtest fill-fb: SUCCESS (0.420s)
[500.645242] [100/133] (516s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset-interruptible)
[500.756858] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset-interruptible
[500.767338] Subtest 2x-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.010s)
[500.909223] [101/133] (516s left) kms_psr (psr2-suspend)
[501.013438] Starting subtest: psr2-suspend
[501.015483] Starting dynamic subtest: eDP-1
[518.324079] Dynamic subtest eDP-1: SUCCESS (2.922s)
[518.326457] Subtest psr2-suspend: SUCCESS (2.923s)
[519.225265] [102/133] (498s left) kms_frontbuffer_tracking (psr-1p-pri-indfb-multidraw)
[519.343873] Starting subtest: psr-1p-pri-indfb-multidraw
[541.776624] Subtest psr-1p-pri-indfb-multidraw: SUCCESS (22.398s)
[541.948253] [103/133] (475s left) kms_cursor_edge_walk (256x256-right-edge)
[542.051638] Starting subtest: 256x256-right-edge
[542.053456] Starting dynamic subtest: pipe-A-eDP-1
[545.524870] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.470s)
[545.538998] Starting dynamic subtest: pipe-D-eDP-1
[548.659182] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.130s)
[548.661622] Subtest 256x256-right-edge: SUCCESS (6.604s)
[549.487046] [104/133] (467s left) gem_ccs (block-copy-compressed)
[549.552640] Starting subtest: block-copy-compressed
[549.556687] Subtest block-copy-compressed: SKIP (0.000s)
[549.668615] [105/133] (467s left) gem_exec_reloc (basic-wc-cpu-active)
[549.730200] Subtest basic-wc-cpu-active: SKIP (0.000s)
[549.826352] [106/133] (467s left) perf (invalid-remove-userspace-config)
[549.961872] Starting subtest: invalid-remove-userspace-config
[549.973846] Subtest invalid-remove-userspace-config: SUCCESS (0.012s)
[550.020137] [107/133] (467s left) gem_lmem_swapping (massive)
[550.061741] Subtest massive: SKIP (0.000s)
[550.108837] [108/133] (467s left) kms_psr (fbc-psr2-cursor-plane-onoff)
[550.205166] Starting subtest: fbc-psr2-cursor-plane-onoff
[550.207271] Starting dynamic subtest: eDP-1
[550.211194] Dynamic subtest eDP-1: SKIP (0.000s)
[550.215088] Subtest fbc-psr2-cursor-plane-onoff: SKIP (0.001s)
[550.290995] [109/133] (467s left) gem_ctx_exec (basic-invalid-context)
[550.356164] Starting subtest: basic-invalid-context
[550.360774] Subtest basic-invalid-context: SUCCESS (0.005s)
[550.491770] [110/133] (467s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-mc-ccs)
[550.573091] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-mc-ccs
[550.575003] Starting dynamic subtest: pipe-A-eDP-1
[550.623627] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[550.634459] Starting dynamic subtest: pipe-B-eDP-1
[550.680517] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[550.691041] Starting dynamic subtest: pipe-C-eDP-1
[550.738926] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[550.749356] Starting dynamic subtest: pipe-D-eDP-1
[550.796091] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[550.799842] Subtest crc-sprite-planes-basic-y-tiled-gen12-mc-ccs: SKIP (0.008s)
[552.772422] [111/133] (464s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[552.874029] Starting subtest: yf-tiled-16bpp-rotate-90
[552.878515] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[553.010158] [112/133] (464s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier-source-clamping)
[553.110032] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier-source-clamping
[553.122838] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[553.194798] [113/133] (464s left) kms_big_fb (linear-8bpp-rotate-90)
[553.293254] Starting subtest: linear-8bpp-rotate-90
[553.315933] Subtest linear-8bpp-rotate-90: SKIP (0.022s)
[553.476659] [114/133] (464s left) kms_chamelium_hpd (dp-hpd-storm)
[553.861612] Subtest dp-hpd-storm: SKIP (0.000s)
[554.647286] [115/133] (463s left) syncobj_timeline (32bits-limit)
[554.699902] Starting subtest: 32bits-limit
[554.702089] Subtest 32bits-limit: SUCCESS (0.001s)
[554.827174] [116/133] (463s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[554.923198] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-gtt
[554.927573] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[555.063213] [117/133] (462s left) perf_pmu (interrupts)
[555.105830] Starting subtest: interrupts
[556.200437] Subtest interrupts: SUCCESS (1.094s)
[556.331641] [118/133] (461s left) kms_psr (psr-primary-page-flip)
[556.441028] Starting subtest: psr-primary-page-flip
[556.443322] Starting dynamic subtest: eDP-1
[558.167477] Dynamic subtest eDP-1: SUCCESS (1.727s)
[558.169427] Subtest psr-primary-page-flip: SUCCESS (1.728s)
[559.037463] [119/133] (458s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs)
[559.129432] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs
[559.131821] Starting dynamic subtest: pipe-A-eDP-1
[559.930148] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.791s)
[559.941091] Starting dynamic subtest: pipe-B-eDP-1
[561.485510] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.552s)
[561.497558] Starting dynamic subtest: pipe-C-eDP-1
[562.946474] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.462s)
[562.959313] Starting dynamic subtest: pipe-D-eDP-1
[564.398417] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.449s)
[564.400687] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs: SUCCESS (5.260s)
[565.607383] [120/133] (452s left) prime_mmap (test_correct_cpu_write)
[565.664619] Starting subtest: test_correct_cpu_write
[565.666935] Starting dynamic subtest: test_correct_cpu_write-smem
[565.669120] Dynamic subtest test_correct_cpu_write-smem: SUCCESS (0.000s)
[565.671017] Subtest test_correct_cpu_write: SUCCESS (0.002s)
[565.804555] [121/133] (452s left) gem_exec_reloc (basic-range-active)
[565.865799] Subtest basic-range-active: SKIP (0.000s)
[565.960650] [122/133] (452s left) kms_psr2_sf (fbc-pr-overlay-plane-update-continuous-sf)
[566.059232] Starting subtest: fbc-pr-overlay-plane-update-continuous-sf
[566.063304] Subtest fbc-pr-overlay-plane-update-continuous-sf: SKIP (0.000s)
[566.158715] [123/133] (452s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[566.285655] Starting subtest: cursorB-vs-flipB-legacy
[566.290080] Subtest cursorB-vs-flipB-legacy: SKIP (0.001s)
[566.459254] [124/133] (451s left) kms_flip (flip-vs-dpms-off-vs-modeset)
[566.559265] Starting subtest: flip-vs-dpms-off-vs-modeset
[566.561275] Starting dynamic subtest: A-eDP1
[570.115382] Dynamic subtest A-eDP1: SUCCESS (3.551s)
[570.126207] Starting dynamic subtest: B-eDP1
[573.297487] Dynamic subtest B-eDP1: SUCCESS (3.184s)
[573.311077] Starting dynamic subtest: C-eDP1
[576.415882] Dynamic subtest C-eDP1: SUCCESS (3.118s)
[576.426877] Starting dynamic subtest: D-eDP1
[579.525616] Dynamic subtest D-eDP1: SUCCESS (3.104s)
[579.527707] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (12.966s)
[580.336869] [125/133] (437s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[580.437701] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[580.441996] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[580.536391] [126/133] (437s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[580.628884] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[580.631176] Starting dynamic subtest: pipe-A
[580.655862] Dynamic subtest pipe-A: SKIP (0.022s)
[580.668429] Starting dynamic subtest: pipe-B
[580.691682] Dynamic subtest pipe-B: SKIP (0.032s)
[580.701862] Starting dynamic subtest: pipe-C
[580.719653] Dynamic subtest pipe-C: SKIP (0.029s)
[580.729425] Starting dynamic subtest: pipe-D
[580.750447] Dynamic subtest pipe-D: SKIP (0.029s)
[580.754646] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SKIP (0.117s)
[580.873558] [127/133] (437s left) kms_cursor_crc (cursor-onscreen-64x21)
[581.091969] Starting subtest: cursor-onscreen-64x21
[581.136624] Subtest cursor-onscreen-64x21: SKIP (0.039s)
[581.389067] [128/133] (437s left) core_setmaster (master-drop-set-user)
[581.476838] Starting subtest: master-drop-set-user
[581.491058] Subtest master-drop-set-user: SUCCESS (0.014s)
[581.527753] [129/133] (436s left) prime_vgem (fence-wait)
[581.605624] Starting subtest: fence-wait
[581.607395] Starting dynamic subtest: rcs0
[582.654760] Dynamic subtest rcs0: SUCCESS (1.049s)
[582.667999] Starting dynamic subtest: bcs0
[583.717319] Dynamic subtest bcs0: SUCCESS (1.061s)
[583.730006] Starting dynamic subtest: vcs0
[584.766056] Dynamic subtest vcs0: SUCCESS (1.047s)
[584.778911] Starting dynamic subtest: vcs1
[585.809448] Dynamic subtest vcs1: SUCCESS (1.041s)
[585.822384] Starting dynamic subtest: vecs0
[586.860763] Dynamic subtest vecs0: SUCCESS (1.038s)
[586.874139] Starting dynamic subtest: ccs0
[587.893107] Dynamic subtest ccs0: SUCCESS (1.041s)
[587.895811] Subtest fence-wait: SUCCESS (6.287s)
[588.087512] [130/133] (430s left) gem_mmap_wc (write-wc-read-gtt)
[588.155921] Subtest write-wc-read-gtt: SKIP (0.000s)
[588.226062] [131/133] (430s left) kms_psr (pr-cursor-blt)
[588.324247] Starting subtest: pr-cursor-blt
[588.328731] Subtest pr-cursor-blt: SKIP (0.000s)
[588.414111] [132/133] (430s left) gen7_exec_parse (basic-allowed)
[588.478204] Subtest basic-allowed: SKIP (0.000s)
[588.564379] [133/133] (430s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-sf)
[588.676961] Starting subtest: psr2-overlay-plane-move-continuous-exceed-sf
[588.679287] Starting dynamic subtest: pipe-A-eDP-1
[599.980031] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.304s)
[599.990713] Starting dynamic subtest: pipe-B-eDP-1
[611.974826] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.989s)
[611.977109] Subtest psr2-overlay-plane-move-continuous-exceed-sf: SUCCESS (23.296s)
[612.829326] 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.