[53.980790] Initializing watchdogs
[53.980871]   /dev/watchdog0
[53.994190] [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]
[53.998317] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[54.000375] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[54.003798] [001/131] (960s left) gem_ringfill (engines-basic)
[59.927111] Starting subtest: engines-basic
[59.929590] Starting dynamic subtest: rcs0
[60.011096] Dynamic subtest rcs0: SUCCESS (0.085s)
[60.020840] Starting dynamic subtest: bcs0
[60.054981] Dynamic subtest bcs0: SUCCESS (0.044s)
[60.065034] Starting dynamic subtest: vcs0
[60.097373] Dynamic subtest vcs0: SUCCESS (0.042s)
[60.105799] Starting dynamic subtest: vcs1
[60.139068] Dynamic subtest vcs1: SUCCESS (0.042s)
[60.148244] Starting dynamic subtest: vecs0
[60.182576] Dynamic subtest vecs0: SUCCESS (0.042s)
[60.189313] Starting dynamic subtest: ccs0
[60.225079] Dynamic subtest ccs0: SUCCESS (0.044s)
[60.227688] Subtest engines-basic: SUCCESS (0.300s)
[60.297108] [FACT gem_ringfill (engines-basic)] new: kernel.kmod_is_loaded.vgem: true
[60.300327] [002/131] (954s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[60.426557] Starting subtest: basic-flip-before-cursor-varying-size
[61.386760] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.961s)
[62.188666] [003/131] (952s left) sysfs_timeslice_duration (duration)
[62.250260] Starting subtest: duration
[62.252584] Starting dynamic subtest: vecs0
[75.827057] Dynamic subtest vecs0: SUCCESS (13.575s)
[75.837577] Starting dynamic subtest: ccs0
[88.977036] Dynamic subtest ccs0: SUCCESS (13.151s)
[88.985808] Starting dynamic subtest: vcs0
[102.897643] Dynamic subtest vcs0: SUCCESS (13.918s)
[102.910666] Starting dynamic subtest: bcs0
[116.053317] Dynamic subtest bcs0: SUCCESS (13.156s)
[116.063957] Starting dynamic subtest: rcs0
[129.215122] Dynamic subtest rcs0: SUCCESS (13.161s)
[129.225476] Starting dynamic subtest: vcs1
[142.897725] Dynamic subtest vcs1: SUCCESS (13.680s)
[142.900404] Subtest duration: SUCCESS (80.646s)
[142.968326] [004/131] (871s left) gem_mmap_gtt (cpuset-basic-small-copy-xy)
[143.022900] Subtest cpuset-basic-small-copy-XY: SKIP (0.000s)
[143.089744] [005/131] (871s left) gem_create (hog-create)
[143.149402] Starting subtest: hog-create
[143.151923] Starting dynamic subtest: smem0
[176.962410] Dynamic subtest smem0: SUCCESS (33.809s)
[176.965035] Subtest hog-create: SUCCESS (33.809s)
[177.144564] [006/131] (837s left) gem_exec_balancer (individual)
[177.210670] Starting subtest: individual
[178.936231] Subtest individual: SUCCESS (1.724s)
[179.261330] [007/131] (835s left) kms_cursor_legacy (forked-bo)
[179.391281] Starting subtest: forked-bo
[179.393197] Starting dynamic subtest: pipe-A
[184.842684] Dynamic subtest pipe-A: SUCCESS (5.449s)
[184.853101] Starting dynamic subtest: pipe-B
[190.303293] Dynamic subtest pipe-B: SUCCESS (5.460s)
[190.315058] Starting dynamic subtest: pipe-C
[195.766470] Dynamic subtest pipe-C: SUCCESS (5.463s)
[195.777179] Starting dynamic subtest: pipe-D
[201.230073] Dynamic subtest pipe-D: SUCCESS (5.463s)
[201.240363] Starting dynamic subtest: all-pipes
[206.729610] Dynamic subtest all-pipes: SUCCESS (5.497s)
[206.731963] Subtest forked-bo: SUCCESS (27.333s)
[206.857991] [008/131] (807s left) kms_pm_dc (dc6-dpms)
[207.962113] Starting subtest: dc6-dpms
[210.052911] Subtest dc6-dpms: SUCCESS (2.090s)
[210.124535] [009/131] (804s left) syncobj_timeline (wait-all-for-submit-delayed-submit)
[210.169953] Starting subtest: wait-all-for-submit-delayed-submit
[210.273451] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.103s)
[210.344781] [010/131] (804s left) kms_async_flips (async-flip-with-page-flip-events-tiled-atomic)
[210.432134] Starting subtest: async-flip-with-page-flip-events-tiled-atomic
[210.812028] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[213.575238] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.763s)
[213.586306] Starting dynamic subtest: pipe-A-eDP-1-4
[215.872368] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.297s)
[215.879811] Starting dynamic subtest: pipe-A-eDP-1-4
[217.882646] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.010s)
[217.892881] Starting dynamic subtest: pipe-A-eDP-1-x
[220.183344] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.301s)
[220.560955] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[223.291692] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.736s)
[223.305651] Starting dynamic subtest: pipe-B-eDP-1-4
[225.600532] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.308s)
[225.611067] Starting dynamic subtest: pipe-B-eDP-1-4
[227.610438] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.010s)
[227.619499] Starting dynamic subtest: pipe-B-eDP-1-x
[229.912323] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.302s)
[230.286678] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[233.019792] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.739s)
[233.032796] Starting dynamic subtest: pipe-C-eDP-1-4
[235.306043] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.286s)
[235.316276] Starting dynamic subtest: pipe-C-eDP-1-4
[237.316530] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.010s)
[237.327196] Starting dynamic subtest: pipe-C-eDP-1-x
[239.617859] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.301s)
[239.977460] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[242.742989] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.769s)
[242.753215] Starting dynamic subtest: pipe-D-eDP-1-4
[245.018081] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.275s)
[245.028791] Starting dynamic subtest: pipe-D-eDP-1-4
[247.028374] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.010s)
[247.039216] Starting dynamic subtest: pipe-D-eDP-1-x
[249.307052] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.278s)
[249.309849] Subtest async-flip-with-page-flip-events-tiled-atomic: SUCCESS (38.872s)
[250.527527] [011/131] (764s left) kms_lease (possible-crtcs-filtering)
[250.627161] Starting subtest: possible-crtcs-filtering
[250.632601] Subtest possible-crtcs-filtering: SUCCESS (0.005s)
[250.750117] [012/131] (764s left) gem_userptr_blits (unsync-unmap-after-close)
[250.817394] Subtest unsync-unmap-after-close: SKIP (0.000s)
[250.938013] [013/131] (764s left) kms_cursor_legacy (flip-vs-cursor-toggle)
[251.061076] Starting subtest: flip-vs-cursor-toggle
[258.991923] Subtest flip-vs-cursor-toggle: SUCCESS (7.929s)
[259.846689] [014/131] (755s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0)
[259.954218] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0
[260.953355] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (0.999s)
[261.082777] [015/131] (753s left) kms_cursor_crc (cursor-offscreen-256x256)
[261.323255] Starting subtest: cursor-offscreen-256x256
[261.327919] Starting dynamic subtest: pipe-A-eDP-1
[263.313242] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.984s)
[263.336630] Starting dynamic subtest: pipe-D-eDP-1
[265.971798] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.638s)
[265.973804] Subtest cursor-offscreen-256x256: SUCCESS (4.644s)
[266.793336] [016/131] (748s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-cpu)
[266.891805] Starting subtest: fbcpsr-rgb101010-draw-mmap-cpu
[273.599642] Subtest fbcpsr-rgb101010-draw-mmap-cpu: SUCCESS (6.712s)
[273.708206] [017/131] (741s left) i915_drm_fdinfo (all-busy-check-all)
[273.745407] Starting subtest: all-busy-check-all
[273.752506] Subtest all-busy-check-all: SKIP (0.003s)
[273.806301] [018/131] (741s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[273.942579] Starting subtest: psr-1p-primscrn-spr-indfb-draw-render
[280.630376] Subtest psr-1p-primscrn-spr-indfb-draw-render: SUCCESS (6.692s)
[280.756651] [019/131] (734s left) gem_exec_reloc (basic-write-read-active)
[280.815290] Subtest basic-write-read-active: SKIP (0.000s)
[280.870533] [020/131] (734s left) kms_cursor_crc (cursor-rapid-movement-128x42)
[281.093953] Starting subtest: cursor-rapid-movement-128x42
[281.135560] Subtest cursor-rapid-movement-128x42: SKIP (0.037s)
[281.341271] [021/131] (733s left) kms_pm_rpm (modeset-lpsp-stress)
[283.081067] Starting subtest: modeset-lpsp-stress
[299.968644] Subtest modeset-lpsp-stress: SUCCESS (16.886s)
[300.054758] [022/131] (715s left) perf (invalid-oa-metric-set-id)
[300.177025] Starting subtest: invalid-oa-metric-set-id
[300.196253] Subtest invalid-oa-metric-set-id: SUCCESS (0.022s)
[300.233010] [023/131] (715s left) kms_pipe_stress (stress-xrgb8888-ytiled)
[300.321457] Starting subtest: stress-xrgb8888-ytiled
[300.326092] Subtest stress-xrgb8888-ytiled: SKIP (0.000s)
[300.391777] [024/131] (714s left) kms_vrr (negative-basic)
[300.476580] Starting subtest: negative-basic
[300.483211] Starting dynamic subtest: pipe-A-eDP-1
[305.941301] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.454s)
[306.219066] Subtest negative-basic: SUCCESS (5.738s)
[307.044092] [025/131] (708s left) kms_psr (psr2-cursor-blt)
[307.133161] Starting subtest: psr2-cursor-blt
[307.136050] Starting dynamic subtest: eDP-1
[308.817078] Dynamic subtest eDP-1: SUCCESS (1.683s)
[308.819367] Subtest psr2-cursor-blt: SUCCESS (1.684s)
[309.605483] [026/131] (705s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-pwrite)
[309.705271] Starting subtest: fbcpsr-rgb565-draw-pwrite
[316.636902] Subtest fbcpsr-rgb565-draw-pwrite: SUCCESS (6.934s)
[316.770654] [027/131] (698s left) kms_plane_scaling (planes-downscale-factor-0-5-unity-scaling)
[316.866957] Starting subtest: planes-downscale-factor-0-5-unity-scaling
[316.869210] Starting dynamic subtest: pipe-A
[316.877983] Dynamic subtest pipe-A: SKIP (0.005s)
[316.886100] Starting dynamic subtest: pipe-B
[316.894815] Dynamic subtest pipe-B: SKIP (0.018s)
[316.902257] Starting dynamic subtest: pipe-C
[316.915136] Dynamic subtest pipe-C: SKIP (0.018s)
[316.924600] Starting dynamic subtest: pipe-D
[316.936600] Dynamic subtest pipe-D: SKIP (0.022s)
[316.940991] Subtest planes-downscale-factor-0-5-unity-scaling: SKIP (0.065s)
[317.029372] [028/131] (698s left) gem_madvise (dontneed-before-exec)
[317.067067] Starting subtest: dontneed-before-exec
[317.081735] Subtest dontneed-before-exec: SKIP (0.014s)
[317.137882] [029/131] (698s left) syncobj_timeline (etime-multi-wait-for-submit-available-unsubmitted)
[317.194936] Starting subtest: etime-multi-wait-for-submit-available-unsubmitted
[317.294877] Subtest etime-multi-wait-for-submit-available-unsubmitted: SUCCESS (0.101s)
[317.369059] [030/131] (698s left) perf_pmu (most-busy-idle-check-all)
[317.412434] Starting subtest: most-busy-idle-check-all
[317.414748] Starting dynamic subtest: rcs0
[318.932902] Dynamic subtest rcs0: SUCCESS (1.519s)
[318.944752] Starting dynamic subtest: bcs0
[320.917041] Dynamic subtest bcs0: SUCCESS (1.982s)
[320.927987] Starting dynamic subtest: vcs0
[322.900271] Dynamic subtest vcs0: SUCCESS (1.982s)
[322.910423] Starting dynamic subtest: vcs1
[324.884095] Dynamic subtest vcs1: SUCCESS (1.982s)
[324.895256] Starting dynamic subtest: vecs0
[326.931840] Dynamic subtest vecs0: SUCCESS (2.046s)
[326.941939] Starting dynamic subtest: ccs0
[328.916608] Dynamic subtest ccs0: SUCCESS (1.983s)
[328.919648] Subtest most-busy-idle-check-all: SUCCESS (11.503s)
[329.051719] [031/131] (686s left) vgem_basic (debugfs)
[329.101332] Starting subtest: debugfs
[329.103193] Subtest debugfs: SUCCESS (0.000s)
[329.154467] [032/131] (686s left) kms_flip (wf_vblank-ts-check-interruptible)
[329.246078] Starting subtest: wf_vblank-ts-check-interruptible
[329.248289] Starting dynamic subtest: A-eDP1
[338.138871] Dynamic subtest A-eDP1: SUCCESS (8.891s)
[338.146464] Starting dynamic subtest: B-eDP1
[347.068516] Dynamic subtest B-eDP1: SUCCESS (8.927s)
[347.078980] Starting dynamic subtest: C-eDP1
[355.975292] Dynamic subtest C-eDP1: SUCCESS (8.906s)
[355.985396] Starting dynamic subtest: D-eDP1
[364.897356] Dynamic subtest D-eDP1: SUCCESS (8.921s)
[364.899978] Subtest wf_vblank-ts-check-interruptible: SUCCESS (35.653s)
[366.112367] [033/131] (649s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-onoff)
[366.218931] Starting subtest: fbc-2p-scndscrn-cur-indfb-onoff
[366.223201] Subtest fbc-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[366.311341] [034/131] (649s left) syncobj_wait (multi-wait-all-submitted-signaled)
[366.371354] Starting subtest: multi-wait-all-submitted-signaled
[366.480316] Subtest multi-wait-all-submitted-signaled: SUCCESS (0.109s)
[366.551934] [035/131] (649s left) kms_dp_linktrain_fallback (dp-fallback)
[366.641450] Starting subtest: dp-fallback
[366.648076] Subtest dp-fallback: SKIP (0.000s)
[366.709919] [036/131] (649s left) kms_cursor_legacy (cursor-vs-flip-toggle)
[366.825105] Starting subtest: cursor-vs-flip-toggle
[396.920693] Subtest cursor-vs-flip-toggle: SUCCESS (30.096s)
[397.731007] [037/131] (618s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-exceed-fully-sf)
[402.776054] Starting subtest: fbc-pr-cursor-plane-move-continuous-exceed-fully-sf
[402.780708] Subtest fbc-pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.002s)
[403.624608] [038/131] (612s left) kms_cursor_crc (cursor-random-32x10)
[403.783914] Starting subtest: cursor-random-32x10
[403.825620] Subtest cursor-random-32x10: SKIP (0.036s)
[404.094266] [039/131] (611s left) perf_pmu (busy-accuracy-2)
[404.133034] Starting subtest: busy-accuracy-2
[404.135322] Starting dynamic subtest: rcs0
[407.176663] Dynamic subtest rcs0: SUCCESS (3.042s)
[407.186799] Starting dynamic subtest: bcs0
[410.235850] Dynamic subtest bcs0: SUCCESS (3.058s)
[410.244228] Starting dynamic subtest: vcs0
[413.280290] Dynamic subtest vcs0: SUCCESS (3.043s)
[413.290806] Starting dynamic subtest: vcs1
[416.328647] Dynamic subtest vcs1: SUCCESS (3.046s)
[416.340000] Starting dynamic subtest: vecs0
[419.377607] Dynamic subtest vecs0: SUCCESS (3.048s)
[419.387895] Starting dynamic subtest: ccs0
[422.438641] Dynamic subtest ccs0: SUCCESS (3.059s)
[422.440986] Subtest busy-accuracy-2: SUCCESS (18.305s)
[422.568602] [040/131] (593s left) kms_frontbuffer_tracking (fbc-1p-pri-indfb-multidraw)
[422.669764] Starting subtest: fbc-1p-pri-indfb-multidraw
[435.718468] Subtest fbc-1p-pri-indfb-multidraw: SUCCESS (13.053s)
[436.899073] [041/131] (579s left) kms_big_fb (4-tiled-8bpp-rotate-270)
[437.007288] Starting subtest: 4-tiled-8bpp-rotate-270
[437.031430] Subtest 4-tiled-8bpp-rotate-270: SKIP (0.023s)
[437.168260] [042/131] (578s left) kms_ccs (crc-primary-basic-yf-tiled-ccs)
[437.265067] Starting subtest: crc-primary-basic-yf-tiled-ccs
[437.267272] Starting dynamic subtest: pipe-A-eDP-1
[437.290129] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[437.298316] Starting dynamic subtest: pipe-B-eDP-1
[437.319662] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[437.327694] Starting dynamic subtest: pipe-C-eDP-1
[437.349204] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[437.357106] Starting dynamic subtest: pipe-D-eDP-1
[437.379344] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[437.383520] Subtest crc-primary-basic-yf-tiled-ccs: SKIP (0.005s)
[439.427259] [043/131] (576s left) gem_softpin (noreloc)
[439.481514] Starting subtest: noreloc
[444.871765] Subtest noreloc: SUCCESS (5.388s)
[444.984361] [044/131] (571s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-render)
[445.083700] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-render
[445.087845] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[445.184983] [045/131] (570s left) i915_query (query-topology-garbage-items)
[445.243092] Starting subtest: query-topology-garbage-items
[445.245303] Subtest query-topology-garbage-items: SUCCESS (0.000s)
[445.339096] [046/131] (570s left) kms_flip (flip-vs-expired-vblank-interruptible)
[445.427989] Starting subtest: flip-vs-expired-vblank-interruptible
[445.430237] Starting dynamic subtest: A-eDP1
[454.127725] Dynamic subtest A-eDP1: SUCCESS (8.699s)
[454.135523] Starting dynamic subtest: B-eDP1
[462.761587] Dynamic subtest B-eDP1: SUCCESS (8.633s)
[462.769414] Starting dynamic subtest: C-eDP1
[471.379421] Dynamic subtest C-eDP1: SUCCESS (8.617s)
[471.389112] Starting dynamic subtest: D-eDP1
[480.004597] Dynamic subtest D-eDP1: SUCCESS (8.624s)
[480.007048] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (34.579s)
[481.166710] [047/131] (535s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-pgflip-blt)
[481.260829] Starting subtest: fbc-1p-primscrn-shrfb-pgflip-blt
[485.767776] Subtest fbc-1p-primscrn-shrfb-pgflip-blt: SUCCESS (4.512s)
[486.951659] [048/131] (529s left) gem_pxp (fail-invalid-protected-context)
[487.068799] Starting subtest: fail-invalid-protected-context
[487.071196] Subtest fail-invalid-protected-context: SUCCESS (0.000s)
[487.173900] [049/131] (529s left) syncobj_wait (invalid-signal-bad-pad)
[487.225068] Starting subtest: invalid-signal-bad-pad
[487.227380] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[487.340154] [050/131] (529s left) kms_psr (fbc-psr2-cursor-plane-onoff)
[487.436352] Starting subtest: fbc-psr2-cursor-plane-onoff
[487.438608] Starting dynamic subtest: eDP-1
[487.442744] Dynamic subtest eDP-1: SKIP (0.000s)
[487.446839] Subtest fbc-psr2-cursor-plane-onoff: SKIP (0.001s)
[487.512202] [051/131] (528s left) prime_busy (after-wait)
[487.562757] Starting subtest: after-wait
[487.568101] Starting dynamic subtest: rcs0
[487.601731] Dynamic subtest rcs0: SUCCESS (0.035s)
[487.612044] Starting dynamic subtest: bcs0
[487.668950] Dynamic subtest bcs0: SUCCESS (0.066s)
[487.676946] Starting dynamic subtest: vcs0
[487.708920] Dynamic subtest vcs0: SUCCESS (0.040s)
[487.718772] Starting dynamic subtest: vcs1
[487.751084] Dynamic subtest vcs1: SUCCESS (0.040s)
[487.759568] Starting dynamic subtest: vecs0
[487.792063] Dynamic subtest vecs0: SUCCESS (0.040s)
[487.800536] Starting dynamic subtest: ccs0
[487.831206] Dynamic subtest ccs0: SUCCESS (0.040s)
[487.939503] Subtest after-wait: SUCCESS (0.374s)
[488.010843] [052/131] (528s left) gem_exec_schedule (preempt-queue-chain)
[488.088083] Subtest preempt-queue-chain: SKIP (0.000s)
[488.376290] [053/131] (528s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[488.464468] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[488.466784] Starting dynamic subtest: pipe-A-eDP-1
[488.515658] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.051s)
[488.525079] Starting dynamic subtest: pipe-B-eDP-1
[488.568390] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.052s)
[488.576318] Starting dynamic subtest: pipe-C-eDP-1
[488.615994] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.047s)
[488.624404] Starting dynamic subtest: pipe-D-eDP-1
[488.664270] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.047s)
[488.666586] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SUCCESS (0.200s)
[490.792820] [054/131] (525s left) i915_pm_rpm (gem-execbuf)
[493.024434] Starting subtest: gem-execbuf
[493.027213] Starting dynamic subtest: smem0
[494.497836] Dynamic subtest smem0: SUCCESS (1.473s)
[494.500227] Subtest gem-execbuf: SUCCESS (1.474s)
[494.586356] [055/131] (521s left) gem_lmem_swapping (parallel-random)
[494.627401] Subtest parallel-random: SKIP (0.000s)
[494.671796] [056/131] (521s left) kms_psr (psr-sprite-mmap-gtt)
[494.768940] Starting subtest: psr-sprite-mmap-gtt
[494.771176] Starting dynamic subtest: eDP-1
[496.274947] Dynamic subtest eDP-1: SKIP (1.503s)
[496.279843] Subtest psr-sprite-mmap-gtt: SKIP (1.505s)
[496.430458] [057/131] (520s left) kms_invalid_mode (int-max-clock)
[496.525280] Starting subtest: int-max-clock
[496.527582] Starting dynamic subtest: pipe-A-eDP-1
[496.817732] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.291s)
[496.827877] Starting dynamic subtest: pipe-B-eDP-1
[496.829738] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[496.840179] Starting dynamic subtest: pipe-C-eDP-1
[496.844547] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[496.852102] Starting dynamic subtest: pipe-D-eDP-1
[496.854377] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[496.856631] Subtest int-max-clock: SUCCESS (0.296s)
[497.607978] [058/131] (519s left) perf_pmu (all-busy-idle-check-all)
[497.643880] Starting subtest: all-busy-idle-check-all
[498.899390] Subtest all-busy-idle-check-all: SUCCESS (1.254s)
[499.003724] [059/131] (517s left) gem_exec_schedule (pi-distinct-iova)
[499.174039] Starting subtest: pi-distinct-iova
[499.176489] Starting dynamic subtest: rcs0
[499.256125] Dynamic subtest rcs0: SUCCESS (0.086s)
[499.265745] Starting dynamic subtest: vcs0
[499.317972] Dynamic subtest vcs0: SUCCESS (0.059s)
[499.328401] Starting dynamic subtest: vcs1
[499.370255] Dynamic subtest vcs1: SUCCESS (0.051s)
[499.380368] Starting dynamic subtest: vecs0
[499.426066] Dynamic subtest vecs0: SUCCESS (0.055s)
[499.435894] Starting dynamic subtest: ccs0
[499.485099] Dynamic subtest ccs0: SUCCESS (0.059s)
[499.487841] Subtest pi-distinct-iova: SUCCESS (0.316s)
[500.996876] [060/131] (515s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[501.058844] Starting subtest: x-tiled-to-vebox-y-tiled
[501.063604] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[501.240549] [061/131] (515s left) kms_flip (flip-vs-dpms-on-nop)
[501.363275] Starting subtest: flip-vs-dpms-on-nop
[501.365519] Starting dynamic subtest: A-eDP1
[503.289139] Dynamic subtest A-eDP1: SUCCESS (1.924s)
[503.299846] Starting dynamic subtest: B-eDP1
[505.211111] Dynamic subtest B-eDP1: SUCCESS (1.921s)
[505.221136] Starting dynamic subtest: C-eDP1
[507.031650] Dynamic subtest C-eDP1: SUCCESS (1.819s)
[507.039702] Starting dynamic subtest: D-eDP1
[508.854927] Dynamic subtest D-eDP1: SUCCESS (1.822s)
[508.857443] Subtest flip-vs-dpms-on-nop: SUCCESS (7.495s)
[510.030418] [062/131] (506s left) gem_fenced_exec_thrash (too-many-fences)
[510.092734] Subtest too-many-fences: SKIP (0.000s)
[510.174212] [063/131] (506s left) gem_exec_fence (basic-wait)
[510.342640] Starting subtest: basic-wait
[510.345248] Starting dynamic subtest: rcs0
[510.449837] Dynamic subtest rcs0: SUCCESS (0.109s)
[510.461604] Starting dynamic subtest: bcs0
[510.512340] Dynamic subtest bcs0: SUCCESS (0.061s)
[510.522684] Starting dynamic subtest: vcs0
[510.579968] Dynamic subtest vcs0: SUCCESS (0.066s)
[510.590204] Starting dynamic subtest: vcs1
[510.642609] Dynamic subtest vcs1: SUCCESS (0.061s)
[510.653249] Starting dynamic subtest: vecs0
[510.706964] Dynamic subtest vecs0: SUCCESS (0.062s)
[510.717380] Starting dynamic subtest: ccs0
[510.769383] Dynamic subtest ccs0: SUCCESS (0.061s)
[510.771827] Subtest basic-wait: SUCCESS (0.430s)
[513.017833] [064/131] (503s left) gem_pread (exhaustion)
[513.083524] Subtest exhaustion: SKIP (0.000s)
[513.147713] [065/131] (503s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[513.249328] Starting subtest: bo-too-small-due-to-tiling
[513.254125] Subtest bo-too-small-due-to-tiling: SKIP (0.000s)
[513.362245] [066/131] (503s left) gem_exec_balancer (full)
[513.427353] Starting subtest: full
[517.713996] Subtest full: SUCCESS (4.287s)
[518.054750] [067/131] (498s left) gem_flink_race (flink_name)
[518.089094] Starting subtest: flink_name
[523.479515] Subtest flink_name: SUCCESS (5.387s)
[523.577849] [068/131] (493s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[523.686955] Starting subtest: fbc-rgb101010-draw-render
[528.246964] Subtest fbc-rgb101010-draw-render: SUCCESS (4.564s)
[529.401659] [069/131] (487s left) kms_hdr (bpc-switch-suspend)
[529.503154] Starting subtest: bpc-switch-suspend
[530.559140] Starting dynamic subtest: pipe-A-eDP-1
[550.173933] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.537s)
[550.176422] Subtest bpc-switch-suspend: SUCCESS (5.595s)
[550.282319] [070/131] (466s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted)
[550.348835] Starting subtest: multi-wait-for-submit-unsubmitted-submitted
[550.452117] Subtest multi-wait-for-submit-unsubmitted-submitted: SUCCESS (0.102s)
[550.529525] [071/131] (466s left) gem_exec_reloc (basic-gtt)
[550.590493] Subtest basic-gtt: SKIP (0.000s)
[550.655222] [072/131] (466s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[550.707460] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[550.710174] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[550.817064] [073/131] (466s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-20x20)
[550.919415] Starting subtest: planes-downscale-factor-0-75-upscale-20x20
[550.921776] Starting dynamic subtest: pipe-A
[550.992488] Dynamic subtest pipe-A: SUCCESS (0.073s)
[551.002295] Starting dynamic subtest: pipe-B
[552.151644] Dynamic subtest pipe-B: SUCCESS (1.158s)
[552.163214] Starting dynamic subtest: pipe-C
[553.254052] Dynamic subtest pipe-C: SUCCESS (1.101s)
[553.263623] Starting dynamic subtest: pipe-D
[554.384350] Dynamic subtest pipe-D: SUCCESS (1.129s)
[554.386799] Subtest planes-downscale-factor-0-75-upscale-20x20: SUCCESS (3.463s)
[555.540670] [074/131] (461s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-fullscreen)
[555.649249] Starting subtest: psr-2p-primscrn-spr-indfb-fullscreen
[555.654173] Subtest psr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[555.754171] [075/131] (461s left) gem_flink_race (flink_close)
[555.789652] Starting subtest: flink_close
[560.904373] Subtest flink_close: SUCCESS (5.112s)
[560.982664] [076/131] (456s left) gem_exec_whisper (basic-contexts-all)
[561.039690] Starting subtest: basic-contexts-all
[572.124682] Subtest basic-contexts-all: SUCCESS (11.078s)
[572.305027] [077/131] (445s left) gen9_exec_parse (basic-rejected)
[572.365600] Subtest basic-rejected: SKIP (0.000s)
[572.439210] [078/131] (445s left) gem_ctx_persistence (engines-mixed)
[572.495299] Starting subtest: engines-mixed
[572.498330] Starting dynamic subtest: rcs0
[572.593889] Dynamic subtest rcs0: SUCCESS (0.098s)
[572.603478] Starting dynamic subtest: bcs0
[572.653791] Dynamic subtest bcs0: SUCCESS (0.058s)
[572.664785] Starting dynamic subtest: vcs0
[572.715799] Dynamic subtest vcs0: SUCCESS (0.061s)
[572.726904] Starting dynamic subtest: vcs1
[572.758661] Dynamic subtest vcs1: SUCCESS (0.042s)
[572.767499] Starting dynamic subtest: vecs0
[572.813591] Dynamic subtest vecs0: SUCCESS (0.054s)
[572.822960] Starting dynamic subtest: ccs0
[572.875880] Dynamic subtest ccs0: SUCCESS (0.061s)
[572.878468] Subtest engines-mixed: SUCCESS (0.381s)
[572.956250] [079/131] (444s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[573.062274] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[573.066822] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[573.147407] [080/131] (444s left) gem_exec_params (cliprects_ptr-dirt)
[573.203356] Starting subtest: cliprects_ptr-dirt
[573.205826] Subtest cliprects_ptr-dirt: SUCCESS (0.000s)
[573.291444] [081/131] (444s left) kms_fbcon_fbt (psr-suspend)
[573.342042] Starting subtest: psr-suspend
[623.182610] Subtest psr-suspend: SUCCESS (19.748s)
[623.257390] [082/131] (394s left) kms_ccs (random-ccs-data-yf-tiled-ccs)
[623.346606] Starting subtest: random-ccs-data-yf-tiled-ccs
[623.348985] Starting dynamic subtest: pipe-A-eDP-1
[623.375822] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[623.386013] Starting dynamic subtest: pipe-B-eDP-1
[623.414471] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[623.429827] Starting dynamic subtest: pipe-C-eDP-1
[623.458485] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[623.469223] Starting dynamic subtest: pipe-D-eDP-1
[623.496443] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[623.500592] Subtest random-ccs-data-yf-tiled-ccs: SKIP (0.004s)
[625.552362] [083/131] (392s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180)
[625.647777] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180
[625.652837] Subtest y-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[625.765132] [084/131] (391s left) gem_userptr_blits (map-fixed-invalidate-overlap-busy)
[625.825406] Starting subtest: map-fixed-invalidate-overlap-busy
[625.829739] Subtest map-fixed-invalidate-overlap-busy: SKIP (0.000s)
[625.930289] [085/131] (391s left) gem_ctx_persistence (legacy-engines-mixed-process)
[625.984004] Starting subtest: legacy-engines-mixed-process
[625.986824] Starting dynamic subtest: render
[628.048442] Dynamic subtest render: SUCCESS (2.063s)
[628.059015] Starting dynamic subtest: bsd1
[630.035563] Dynamic subtest bsd1: SUCCESS (1.984s)
[630.047336] Starting dynamic subtest: bsd2
[632.088604] Dynamic subtest bsd2: SUCCESS (2.050s)
[632.100777] Starting dynamic subtest: blt
[634.056399] Dynamic subtest blt: SUCCESS (1.965s)
[634.068607] Starting dynamic subtest: vebox
[636.116365] Dynamic subtest vebox: SUCCESS (2.058s)
[636.118999] Subtest legacy-engines-mixed-process: SUCCESS (10.128s)
[636.195361] [086/131] (381s left) perf_pmu (interrupts-sync)
[636.238501] Starting subtest: interrupts-sync
[637.337284] Subtest interrupts-sync: SUCCESS (1.098s)
[637.488898] [087/131] (380s left) kms_psr (fbc-pr-cursor-blt)
[637.578546] Starting subtest: fbc-pr-cursor-blt
[637.583616] Subtest fbc-pr-cursor-blt: SKIP (0.000s)
[637.650325] [088/131] (380s left) kms_big_fb (y-tiled-32bpp-rotate-90)
[637.756000] Starting subtest: y-tiled-32bpp-rotate-90
[637.761047] Subtest y-tiled-32bpp-rotate-90: SKIP (0.000s)
[637.862826] [089/131] (380s left) kms_color (ctm-negative)
[637.960042] Starting subtest: ctm-negative
[637.962590] Starting dynamic subtest: pipe-A-eDP-1
[638.691540] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.728s)
[638.702661] Starting dynamic subtest: pipe-B-eDP-1
[640.030884] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.339s)
[640.041536] Starting dynamic subtest: pipe-C-eDP-1
[641.304790] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.274s)
[641.314704] Starting dynamic subtest: pipe-D-eDP-1
[642.655430] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.348s)
[642.657541] Subtest ctm-negative: SUCCESS (4.692s)
[643.467208] [090/131] (374s left) api_intel_bb (crc32)
[643.526631] Starting subtest: crc32
[643.528876] Starting dynamic subtest: rcs0-smem0
[643.829605] Dynamic subtest rcs0-smem0: SUCCESS (0.304s)
[643.839988] Starting dynamic subtest: bcs0-smem0
[644.160124] Dynamic subtest bcs0-smem0: SUCCESS (0.329s)
[644.171748] Starting dynamic subtest: vcs0-smem0
[644.521071] Dynamic subtest vcs0-smem0: SUCCESS (0.358s)
[644.534924] Starting dynamic subtest: vcs1-smem0
[644.867952] Dynamic subtest vcs1-smem0: SUCCESS (0.344s)
[644.879695] Starting dynamic subtest: vecs0-smem0
[645.217341] Dynamic subtest vecs0-smem0: SUCCESS (0.346s)
[645.230338] Starting dynamic subtest: ccs0-smem0
[645.534788] Dynamic subtest ccs0-smem0: SUCCESS (0.316s)
[645.537996] Subtest crc32: SUCCESS (2.010s)
[645.667188] [091/131] (372s left) gem_barrier_race (remote-request)
[645.720524] Starting subtest: remote-request
[645.722737] Starting dynamic subtest: rcs0
[650.890450] Dynamic subtest rcs0: SUCCESS (5.171s)
[650.892806] Subtest remote-request: SUCCESS (5.172s)
[651.011826] [092/131] (367s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[656.040460] Starting subtest: pr-cursor-plane-move-continuous-exceed-sf
[656.045491] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.002s)
[656.883767] [093/131] (361s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180)
[656.989962] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180
[656.995687] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[657.071900] [094/131] (361s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[657.163526] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[657.165925] Starting dynamic subtest: pipe-A-eDP-1
[657.413588] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.217s)
[657.424751] Starting dynamic subtest: pipe-B-eDP-1
[658.638527] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.222s)
[658.648284] Starting dynamic subtest: pipe-C-eDP-1
[659.856171] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.217s)
[659.865648] Starting dynamic subtest: pipe-D-eDP-1
[661.078775] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.216s)
[661.081617] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (3.876s)
[662.193072] [095/131] (355s left) kms_flip (2x-flip-vs-rmfb-interruptible)
[662.295608] Starting subtest: 2x-flip-vs-rmfb-interruptible
[662.305157] Subtest 2x-flip-vs-rmfb-interruptible: SKIP (0.010s)
[662.425823] [096/131] (355s left) kms_big_fb (x-tiled-64bpp-rotate-270)
[662.535980] Starting subtest: x-tiled-64bpp-rotate-270
[662.554499] Subtest x-tiled-64bpp-rotate-270: SKIP (0.018s)
[662.682304] [097/131] (355s left) kms_addfb_basic (too-high)
[662.787816] Starting subtest: too-high
[662.790536] Subtest too-high: SUCCESS (0.000s)
[662.896482] [098/131] (355s left) syncobj_timeline (multi-wait-for-submit-available-submitted)
[662.965107] Starting subtest: multi-wait-for-submit-available-submitted
[662.967651] Subtest multi-wait-for-submit-available-submitted: SUCCESS (0.000s)
[663.081331] [099/131] (355s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-render)
[663.188244] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-render
[667.653201] Subtest fbc-1p-primscrn-pri-indfb-draw-render: SUCCESS (4.467s)
[668.779270] [100/131] (349s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-upscaling)
[668.877610] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-upscaling
[668.880089] Starting dynamic subtest: pipe-A-default-mode
[669.163872] Dynamic subtest pipe-A-default-mode: SKIP (0.284s)
[669.169276] Subtest flip-64bpp-yftile-to-16bpp-yftile-upscaling: SKIP (0.285s)
[669.980799] [101/131] (348s left) api_intel_allocator (fork-simple-stress-signal)
[670.040660] Starting subtest: fork-simple-stress-signal
[675.443660] Subtest fork-simple-stress-signal: SUCCESS (5.402s)
[675.563335] [102/131] (342s left) gem_close (basic)
[675.626145] Starting subtest: basic
[675.629372] Subtest basic: SUCCESS (0.000s)
[675.746555] [103/131] (342s left) kms_ccs (bad-aux-stride-y-tiled-ccs)
[675.847668] Starting subtest: bad-aux-stride-y-tiled-ccs
[675.850136] Starting dynamic subtest: pipe-A-eDP-1
[675.877168] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[675.886666] Starting dynamic subtest: pipe-B-eDP-1
[675.910695] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[675.920500] Starting dynamic subtest: pipe-C-eDP-1
[675.945094] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[675.956196] Starting dynamic subtest: pipe-D-eDP-1
[675.981611] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[675.986382] Subtest bad-aux-stride-y-tiled-ccs: SKIP (0.004s)
[678.049230] [104/131] (340s left) kms_psr (fbc-psr-sprite-mmap-gtt)
[678.148018] Starting subtest: fbc-psr-sprite-mmap-gtt
[678.150355] Starting dynamic subtest: eDP-1
[678.154978] Dynamic subtest eDP-1: SKIP (0.000s)
[678.160002] Subtest fbc-psr-sprite-mmap-gtt: SKIP (0.001s)
[678.221706] [105/131] (340s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-downscaling)
[678.326848] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-downscaling
[678.329470] Starting dynamic subtest: pipe-A-default-mode
[678.611453] Dynamic subtest pipe-A-default-mode: SKIP (0.283s)
[678.616983] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-downscaling: SKIP (0.285s)
[679.470882] [106/131] (339s left) api_intel_bb (render-ccs)
[679.520679] Starting subtest: render-ccs
[679.872723] Subtest render-ccs: SUCCESS (0.351s)
[679.962848] [107/131] (338s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-pgflip-blt)
[680.066532] Starting subtest: psr-2p-scndscrn-indfb-pgflip-blt
[680.071628] Subtest psr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[680.175994] [108/131] (338s left) kms_psr (psr-primary-blt)
[680.260842] Starting subtest: psr-primary-blt
[680.263724] Starting dynamic subtest: eDP-1
[682.054913] Dynamic subtest eDP-1: SUCCESS (1.792s)
[682.057814] Subtest psr-primary-blt: SUCCESS (1.793s)
[682.911982] [109/131] (335s left) kms_plane (planar-pixel-format-settings)
[683.026052] Starting subtest: planar-pixel-format-settings
[683.055811] Subtest planar-pixel-format-settings: SUCCESS (0.028s)
[683.132665] [110/131] (335s left) kms_chamelium_hpd (dp-hpd-enable-disable-mode)
[683.522891] Subtest dp-hpd-enable-disable-mode: SKIP (0.000s)
[684.317676] [111/131] (334s left) gem_exec_whisper (basic-sync)
[684.383135] Starting subtest: basic-sync
[693.257015] Subtest basic-sync: SUCCESS (8.868s)
[693.445499] [112/131] (325s left) gem_exec_gttfill (multigpu-basic)
[693.621355] Starting subtest: multigpu-basic
[713.600993] Subtest multigpu-basic: SUCCESS (19.973s)
[713.654161] [113/131] (305s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[713.800611] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[713.804795] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[713.920590] [114/131] (305s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[713.982651] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[713.985150] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[714.095502] [115/131] (304s left) gem_busy (busy)
[714.156726] Starting subtest: busy
[714.159269] Starting dynamic subtest: all-engines
[714.228498] Dynamic subtest all-engines: SUCCESS (0.071s)
[714.240052] Starting dynamic subtest: rcs0
[716.097989] Dynamic subtest rcs0: SUCCESS (1.868s)
[716.118540] Starting dynamic subtest: bcs0
[716.163482] Dynamic subtest bcs0: SUCCESS (0.061s)
[716.183566] Starting dynamic subtest: vcs0
[716.202700] Dynamic subtest vcs0: SUCCESS (0.039s)
[716.222640] Starting dynamic subtest: vcs1
[718.080782] Dynamic subtest vcs1: SUCCESS (1.875s)
[718.101463] Starting dynamic subtest: vecs0
[720.065005] Dynamic subtest vecs0: SUCCESS (1.982s)
[720.078264] Starting dynamic subtest: ccs0
[722.048017] Dynamic subtest ccs0: SUCCESS (1.982s)
[722.050788] Subtest busy: SUCCESS (7.885s)
[722.402551] [116/131] (296s left) gem_exec_suspend (basic-s3)
[722.470132] Starting subtest: basic-S3
[722.472622] Starting dynamic subtest: smem
[743.039124] Dynamic subtest smem: SUCCESS (6.613s)
[743.041642] Subtest basic-S3: SUCCESS (6.615s)
[743.222692] [117/131] (275s left) i915_drm_fdinfo (virtual-busy)
[743.263675] Starting subtest: virtual-busy
[743.267876] Subtest virtual-busy: SKIP (0.000s)
[743.331469] [118/131] (275s left) syncobj_eventfd (binary-wait-before-signal)
[743.385970] Starting subtest: binary-wait-before-signal
[743.414599] Subtest binary-wait-before-signal: SUCCESS (0.031s)
[743.489147] [119/131] (275s left) gem_userptr_blits (nohangcheck)
[743.568295] Starting subtest: nohangcheck
[743.934368] Subtest nohangcheck: SUCCESS (0.362s)
[744.045660] [120/131] (275s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-fullscreen)
[744.146142] Starting subtest: psr-2p-scndscrn-spr-indfb-fullscreen
[744.150062] Subtest psr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[744.261186] [121/131] (274s left) kms_big_fb (x-tiled-64bpp-rotate-180)
[744.367341] Starting subtest: x-tiled-64bpp-rotate-180
[746.724736] Subtest x-tiled-64bpp-rotate-180: SUCCESS (2.361s)
[746.970490] [122/131] (272s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-pwrite)
[747.082008] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-pwrite
[756.359205] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-pwrite: SUCCESS (9.288s)
[756.481868] [123/131] (262s left) kms_chamelium_edid (hdmi-edid-change-during-suspend)
[756.860219] Subtest hdmi-edid-change-during-suspend: SKIP (0.000s)
[757.634372] [124/131] (261s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-render)
[757.731685] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-render
[764.961238] Subtest psr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.234s)
[765.086836] [125/131] (254s left) gem_softpin (noreloc-interruptible)
[765.162281] Starting subtest: noreloc-interruptible
[770.576241] Subtest noreloc-interruptible: SUCCESS (5.413s)
[770.718883] [126/131] (248s left) kms_universal_plane (cursor-fb-leak)
[770.808998] Starting subtest: cursor-fb-leak
[770.811254] Starting dynamic subtest: pipe-A-eDP-1
[771.136021] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.326s)
[771.147341] Starting dynamic subtest: pipe-B-eDP-1
[772.513578] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.377s)
[772.525655] Starting dynamic subtest: pipe-C-eDP-1
[773.825644] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.310s)
[773.836774] Starting dynamic subtest: pipe-D-eDP-1
[775.155197] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.327s)
[775.157979] Subtest cursor-fb-leak: SUCCESS (4.344s)
[776.331515] [127/131] (243s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-wc)
[776.436125] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-wc
[780.734718] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (4.302s)
[781.918326] [128/131] (237s left) i915_selftest (perf)
[783.967037] Starting subtest: perf
[783.970058] Starting dynamic subtest: engine_cs
[788.477646] Dynamic subtest engine_cs: SUCCESS (4.510s)
[788.492403] Starting dynamic subtest: request
[795.827706] Dynamic subtest request: SUCCESS (7.348s)
[795.837729] Starting dynamic subtest: migrate
[800.322380] Dynamic subtest migrate: SUCCESS (4.493s)
[800.337846] Starting dynamic subtest: region
[804.691291] Dynamic subtest region: SUCCESS (4.366s)
[804.694211] Subtest perf: SUCCESS (20.724s)
[804.723089] [FACT i915_selftest (perf)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[804.723767] [FACT i915_selftest (perf)] deleted: kernel.kmod_is_loaded.i915: true
[804.727039] [129/131] (214s left) gem_fence_thrash (bo-write-verify-y)
[804.769256] Starting subtest: bo-write-verify-y
[806.167226] Subtest bo-write-verify-y: SKIP (1.394s)
[806.225059] [FACT gem_fence_thrash (bo-write-verify-y)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[806.226937] [FACT gem_fence_thrash (bo-write-verify-y)] new: kernel.kmod_is_loaded.i915: true
[806.230686] [130/131] (213s left) gem_tiling_max_stride
[806.362249] [131/131] (213s left) perf (low-oa-exponent-permissions)
[806.474412] Starting subtest: low-oa-exponent-permissions
[806.518582] Subtest low-oa-exponent-permissions: SUCCESS (0.045s)
[806.561755] Closing watchdogs
results: parsing output: 0/ for test: gem_ringfill
results: parsing output: 1/ for test: kms_cursor_legacy
results: parsing output: 2/ for test: sysfs_timeslice_duration
results: parsing output: 3/ for test: gem_mmap_gtt
results: parsing output: 4/ for test: gem_create
results: parsing output: 5/ for test: gem_exec_balancer
results: parsing output: 6/ for test: kms_cursor_legacy
results: parsing output: 7/ for test: kms_pm_dc
results: parsing output: 8/ for test: syncobj_timeline
results: parsing output: 9/ for test: kms_async_flips
results: parsing output: 10/ for test: kms_lease
results: parsing output: 11/ for test: gem_userptr_blits
results: parsing output: 12/ for test: kms_cursor_legacy
results: parsing output: 13/ for test: kms_big_fb
results: parsing output: 14/ for test: kms_cursor_crc
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: i915_drm_fdinfo
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: gem_exec_reloc
results: parsing output: 19/ for test: kms_cursor_crc
results: parsing output: 20/ for test: kms_pm_rpm
results: parsing output: 21/ for test: perf
results: parsing output: 22/ for test: kms_pipe_stress
results: parsing output: 23/ for test: kms_vrr
results: parsing output: 24/ for test: kms_psr
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_plane_scaling
results: parsing output: 27/ for test: gem_madvise
results: parsing output: 28/ for test: syncobj_timeline
results: parsing output: 29/ for test: perf_pmu
results: parsing output: 30/ for test: vgem_basic
results: parsing output: 31/ for test: kms_flip
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: syncobj_wait
results: parsing output: 34/ for test: kms_dp_linktrain_fallback
results: parsing output: 35/ for test: kms_cursor_legacy
results: parsing output: 36/ for test: kms_psr2_sf
results: parsing output: 37/ for test: kms_cursor_crc
results: parsing output: 38/ for test: perf_pmu
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: kms_big_fb
results: parsing output: 41/ for test: kms_ccs
results: parsing output: 42/ for test: gem_softpin
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: i915_query
results: parsing output: 45/ for test: kms_flip
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: gem_pxp
results: parsing output: 48/ for test: syncobj_wait
results: parsing output: 49/ for test: kms_psr
results: parsing output: 50/ for test: prime_busy
results: parsing output: 51/ for test: gem_exec_schedule
results: parsing output: 52/ for test: kms_ccs
results: parsing output: 53/ for test: i915_pm_rpm
results: parsing output: 54/ for test: gem_lmem_swapping
results: parsing output: 55/ for test: kms_psr
results: parsing output: 56/ for test: kms_invalid_mode
results: parsing output: 57/ for test: perf_pmu
results: parsing output: 58/ for test: gem_exec_schedule
results: parsing output: 59/ for test: gem_render_copy
results: parsing output: 60/ for test: kms_flip
results: parsing output: 61/ for test: gem_fenced_exec_thrash
results: parsing output: 62/ for test: gem_exec_fence
results: parsing output: 63/ for test: gem_pread
results: parsing output: 64/ for test: kms_addfb_basic
results: parsing output: 65/ for test: gem_exec_balancer
results: parsing output: 66/ for test: gem_flink_race
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_hdr
results: parsing output: 69/ for test: syncobj_wait
results: parsing output: 70/ for test: gem_exec_reloc
results: parsing output: 71/ for test: syncobj_timeline
results: parsing output: 72/ for test: kms_plane_scaling
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: gem_flink_race
results: parsing output: 75/ for test: gem_exec_whisper
results: parsing output: 76/ for test: gen9_exec_parse
results: parsing output: 77/ for test: gem_ctx_persistence
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: gem_exec_params
results: parsing output: 80/ for test: kms_fbcon_fbt
results: parsing output: 81/ for test: kms_ccs
results: parsing output: 82/ for test: kms_big_fb
results: parsing output: 83/ for test: gem_userptr_blits
results: parsing output: 84/ for test: gem_ctx_persistence
results: parsing output: 85/ for test: perf_pmu
results: parsing output: 86/ for test: kms_psr
results: parsing output: 87/ for test: kms_big_fb
results: parsing output: 88/ for test: kms_color
results: parsing output: 89/ for test: api_intel_bb
results: parsing output: 90/ for test: gem_barrier_race
results: parsing output: 91/ for test: kms_psr2_sf
results: parsing output: 92/ for test: kms_big_fb
results: parsing output: 93/ for test: kms_ccs
results: parsing output: 94/ for test: kms_flip
results: parsing output: 95/ for test: kms_big_fb
results: parsing output: 96/ for test: kms_addfb_basic
results: parsing output: 97/ for test: syncobj_timeline
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: kms_flip_scaled_crc
results: parsing output: 100/ for test: api_intel_allocator
results: parsing output: 101/ for test: gem_close
results: parsing output: 102/ for test: kms_ccs
results: parsing output: 103/ for test: kms_psr
results: parsing output: 104/ for test: kms_flip_scaled_crc
results: parsing output: 105/ for test: api_intel_bb
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_psr
results: parsing output: 108/ for test: kms_plane
results: parsing output: 109/ for test: kms_chamelium_hpd
results: parsing output: 110/ for test: gem_exec_whisper
results: parsing output: 111/ for test: gem_exec_gttfill
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: syncobj_wait
results: parsing output: 114/ for test: gem_busy
results: parsing output: 115/ for test: gem_exec_suspend
results: parsing output: 116/ for test: i915_drm_fdinfo
results: parsing output: 117/ for test: syncobj_eventfd
results: parsing output: 118/ for test: gem_userptr_blits
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: kms_big_fb
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_chamelium_edid
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: gem_softpin
results: parsing output: 125/ for test: kms_universal_plane
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: i915_selftest
results: parsing output: 128/ for test: gem_fence_thrash
results: parsing output: 129/ for test: gem_tiling_max_stride
results: parsing output: 130/ for test: perf
Done.