[59.797349] Initializing watchdogs
[59.797416]   /dev/watchdog0
[59.810367] [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]
[59.814338] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.816570] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.819870] [001/131] (960s left) syncobj_wait (reset-unsignaled)
[59.936062] Starting subtest: reset-unsignaled
[59.938448] Subtest reset-unsignaled: SUCCESS (0.000s)
[60.018020] [002/131] (960s left) kms_vblank (accuracy-idle)
[60.118034] Starting subtest: accuracy-idle
[60.120275] Starting dynamic subtest: pipe-A-eDP-1
[61.893865] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.798s)
[61.906060] Starting dynamic subtest: pipe-D-eDP-1
[64.295284] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.419s)
[64.297456] Subtest accuracy-idle: SUCCESS (4.220s)
[65.091016] [003/131] (955s left) kms_vrr (flip-basic)
[65.186992] Starting subtest: flip-basic
[65.193670] Subtest flip-basic: SKIP (0.000s)
[65.248220] [004/131] (955s left) kms_pm_rpm (dpms-mode-unset-lpsp)
[67.108847] Starting subtest: dpms-mode-unset-lpsp
[71.137799] Subtest dpms-mode-unset-lpsp: SUCCESS (4.046s)
[71.515387] [005/131] (948s left) kms_cursor_crc (cursor-offscreen-32x32)
[71.845629] Starting subtest: cursor-offscreen-32x32
[71.885645] Subtest cursor-offscreen-32x32: SKIP (0.035s)
[72.229798] [006/131] (948s left) gem_exec_balancer (bonded-semaphore)
[72.306370] Starting subtest: bonded-semaphore
[72.310892] Subtest bonded-semaphore: SKIP (0.000s)
[72.590182] [007/131] (947s left) gem_exec_reloc (basic-gtt-wc-active)
[72.652077] Subtest basic-gtt-wc-active: SKIP (0.000s)
[72.725031] [008/131] (947s left) kms_ccs (crc-primary-rotation-180-4-tiled-bmg-ccs)
[72.823937] Starting subtest: crc-primary-rotation-180-4-tiled-bmg-ccs
[72.828592] Subtest crc-primary-rotation-180-4-tiled-bmg-ccs: SKIP (0.000s)
[73.897218] [009/131] (946s left) syncobj_timeline (single-wait-all-available-signaled)
[73.952389] Starting subtest: single-wait-all-available-signaled
[73.954834] Subtest single-wait-all-available-signaled: SUCCESS (0.000s)
[74.043906] [010/131] (946s left) kms_plane (pixel-format)
[74.142884] Starting subtest: pixel-format
[74.178824] Starting dynamic subtest: pipe-A-plane-0
[78.782481] Dynamic subtest pipe-A-plane-0: SUCCESS (4.611s)
[78.790532] Starting dynamic subtest: pipe-A-plane-3
[80.936087] Dynamic subtest pipe-A-plane-3: SUCCESS (2.155s)
[80.944895] Starting dynamic subtest: pipe-A-plane-5
[81.091202] Dynamic subtest pipe-A-plane-5: SUCCESS (0.155s)
[82.170446] Starting dynamic subtest: pipe-B-plane-0
[86.837482] Dynamic subtest pipe-B-plane-0: SUCCESS (4.678s)
[86.844742] Starting dynamic subtest: pipe-B-plane-3
[89.070949] Dynamic subtest pipe-B-plane-3: SUCCESS (2.233s)
[89.078480] Starting dynamic subtest: pipe-B-plane-5
[89.226934] Dynamic subtest pipe-B-plane-5: SUCCESS (0.155s)
[89.533306] Subtest pixel-format: SUCCESS (15.399s)
[90.334190] [011/131] (930s left) gem_exec_flush (basic-uc-rw-default)
[90.410151] Starting subtest: basic-uc-rw-default
[95.884079] Subtest basic-uc-rw-default: SUCCESS (5.475s)
[96.060064] [012/131] (924s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[96.168225] Starting subtest: nonblocking-crc-frame-sequence
[96.170694] Starting dynamic subtest: pipe-A-eDP-1
[96.919692] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.747s)
[96.927756] Starting dynamic subtest: pipe-B-eDP-1
[98.332091] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.413s)
[98.339949] Starting dynamic subtest: pipe-C-eDP-1
[99.709804] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.377s)
[99.717895] Starting dynamic subtest: pipe-D-eDP-1
[101.054596] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.342s)
[101.056992] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.883s)
[101.837274] [013/131] (918s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-wc)
[101.944496] Starting subtest: fbcpsr-rgb565-draw-mmap-wc
[106.581633] Subtest fbcpsr-rgb565-draw-mmap-wc: SUCCESS (4.637s)
[106.695722] [014/131] (913s left) kms_big_fb (x-tiled-8bpp-rotate-180)
[106.807071] Starting subtest: x-tiled-8bpp-rotate-180
[108.029058] Subtest x-tiled-8bpp-rotate-180: SUCCESS (1.224s)
[108.189618] [015/131] (912s left) kms_psr (fbc-pr-sprite-mmap-cpu)
[108.277880] Starting subtest: fbc-pr-sprite-mmap-cpu
[108.281745] Subtest fbc-pr-sprite-mmap-cpu: SKIP (0.000s)
[108.343073] [016/131] (912s left) kms_properties (crtc-properties-atomic)
[108.431735] Starting subtest: crtc-properties-atomic
[108.433781] Starting dynamic subtest: pipe-A-eDP-1
[108.878462] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.445s)
[108.886216] Starting dynamic subtest: pipe-B-eDP-1
[110.035545] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.155s)
[110.042982] Starting dynamic subtest: pipe-C-eDP-1
[111.139529] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.105s)
[111.147464] Starting dynamic subtest: pipe-D-eDP-1
[112.268282] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.126s)
[112.270650] Subtest crtc-properties-atomic: SUCCESS (3.835s)
[113.069765] [017/131] (907s left) gem_mmap_gtt (basic)
[113.134133] Subtest basic: SKIP (0.000s)
[113.190982] [018/131] (907s left) kms_cursor_edge_walk (256x256-left-edge)
[113.291095] Starting subtest: 256x256-left-edge
[113.293518] Starting dynamic subtest: pipe-A-eDP-1
[116.726865] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.431s)
[116.734669] Starting dynamic subtest: pipe-D-eDP-1
[119.824427] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.099s)
[119.826797] Subtest 256x256-left-edge: SUCCESS (6.532s)
[120.643153] [019/131] (900s left) kms_big_fb (x-tiled-64bpp-rotate-270)
[120.746254] Starting subtest: x-tiled-64bpp-rotate-270
[120.766123] Subtest x-tiled-64bpp-rotate-270: SKIP (0.020s)
[120.885429] [020/131] (899s left) kms_hdr (invalid-metadata-sizes)
[120.985137] Starting subtest: invalid-metadata-sizes
[120.991891] Subtest invalid-metadata-sizes: SKIP (0.000s)
[121.051656] [021/131] (899s left) gem_mmap_wc (bad-offset)
[121.116137] Subtest bad-offset: SKIP (0.000s)
[121.164597] [022/131] (899s left) kms_chamelium_color (ctm-green-to-red)
[121.260296] Subtest ctm-green-to-red: SKIP (0.000s)
[121.297717] [023/131] (899s left) kms_lease (lease-unleased-connector)
[121.396064] Starting subtest: lease-unleased-connector
[121.398424] Starting dynamic subtest: pipe-A-eDP-1
[121.408979] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.011s)
[121.416580] Starting dynamic subtest: pipe-B-eDP-1
[121.421522] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.011s)
[121.429176] Starting dynamic subtest: pipe-C-eDP-1
[121.431300] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.011s)
[121.438801] Starting dynamic subtest: pipe-D-eDP-1
[121.444170] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.011s)
[121.446461] Subtest lease-unleased-connector: SUCCESS (0.047s)
[121.545335] [024/131] (899s left) gem_flink_basic (bad-open)
[121.608788] Starting subtest: bad-open
[121.610569] Subtest bad-open: SUCCESS (0.000s)
[121.714369] [025/131] (899s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-pgflip-blt)
[121.831236] Starting subtest: fbcpsr-2p-scndscrn-shrfb-pgflip-blt
[121.834914] Subtest fbcpsr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[121.938680] [026/131] (899s left) kms_flip (wf_vblank-ts-check)
[122.030275] Starting subtest: wf_vblank-ts-check
[122.032316] Starting dynamic subtest: A-eDP1
[130.992554] Dynamic subtest A-eDP1: SUCCESS (8.960s)
[131.001395] Starting dynamic subtest: B-eDP1
[139.918071] Dynamic subtest B-eDP1: SUCCESS (8.924s)
[139.926857] Starting dynamic subtest: C-eDP1
[148.840019] Dynamic subtest C-eDP1: SUCCESS (8.920s)
[148.847694] Starting dynamic subtest: D-eDP1
[157.764276] Dynamic subtest D-eDP1: SUCCESS (8.920s)
[157.766580] Subtest wf_vblank-ts-check: SUCCESS (35.732s)
[158.978285] [027/131] (862s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0)
[159.085558] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0
[159.090072] Subtest y-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[159.192884] [028/131] (861s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-msflip-blt)
[159.303382] Starting subtest: fbcpsr-1p-primscrn-shrfb-msflip-blt
[164.549691] Subtest fbcpsr-1p-primscrn-shrfb-msflip-blt: SUCCESS (5.248s)
[164.697516] [029/131] (856s left) kms_invalid_mode (bad-hsync-start)
[164.806302] Starting subtest: bad-hsync-start
[164.808649] Starting dynamic subtest: pipe-A-eDP-1
[165.086327] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.280s)
[165.094683] Starting dynamic subtest: pipe-B-eDP-1
[165.096645] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[165.103069] Starting dynamic subtest: pipe-C-eDP-1
[165.105090] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[165.111816] Starting dynamic subtest: pipe-D-eDP-1
[165.114085] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[165.116420] Subtest bad-hsync-start: SUCCESS (0.285s)
[165.942058] [030/131] (855s left) kms_flip (basic-flip-vs-wf_vblank)
[166.040438] Starting subtest: basic-flip-vs-wf_vblank
[166.042825] Starting dynamic subtest: A-eDP1
[167.992771] Dynamic subtest A-eDP1: SUCCESS (1.951s)
[168.001778] Starting dynamic subtest: B-eDP1
[169.917263] Dynamic subtest B-eDP1: SUCCESS (1.923s)
[169.924361] Starting dynamic subtest: C-eDP1
[171.836728] Dynamic subtest C-eDP1: SUCCESS (1.918s)
[171.847607] Starting dynamic subtest: D-eDP1
[173.767145] Dynamic subtest D-eDP1: SUCCESS (1.929s)
[173.769650] Subtest basic-flip-vs-wf_vblank: SUCCESS (7.730s)
[174.983007] [031/131] (846s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-render)
[175.084657] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-render
[175.089170] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[175.183225] [032/131] (846s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs)
[175.280102] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs
[175.282321] Starting dynamic subtest: pipe-A-eDP-1
[192.245594] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.478s)
[192.254122] Starting dynamic subtest: pipe-B-eDP-1
[209.107894] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.341s)
[209.115243] Starting dynamic subtest: pipe-C-eDP-1
[226.080164] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.301s)
[226.088230] Starting dynamic subtest: pipe-D-eDP-1
[243.088699] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.251s)
[243.091160] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs: SUCCESS (8.376s)
[244.247042] [033/131] (836s left) gem_userptr_blits (invalid-mmap-offset-unsync)
[244.317427] Subtest invalid-mmap-offset-unsync: SKIP (0.000s)
[244.440027] [034/131] (836s left) perf (sysctl-defaults)
[244.501796] Starting subtest: sysctl-defaults
[244.504498] Subtest sysctl-defaults: SUCCESS (0.000s)
[244.577345] [035/131] (836s left) kms_properties (connector-properties-atomic)
[244.675789] Starting subtest: connector-properties-atomic
[244.677919] Starting dynamic subtest: pipe-A-eDP-1
[252.359114] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.835s)
[252.367990] Starting dynamic subtest: pipe-None-DP-1
[252.372645] Dynamic subtest pipe-None-DP-1: SUCCESS (0.001s)
[252.379568] Starting dynamic subtest: pipe-None-HDMI-A-1
[252.383762] Dynamic subtest pipe-None-HDMI-A-1: SUCCESS (0.001s)
[252.391209] Starting dynamic subtest: pipe-None-DP-2
[252.395779] Dynamic subtest pipe-None-DP-2: SUCCESS (0.001s)
[252.402978] Starting dynamic subtest: pipe-None-HDMI-A-2
[252.407557] Dynamic subtest pipe-None-HDMI-A-2: SUCCESS (0.001s)
[252.414788] Starting dynamic subtest: pipe-None-DP-3
[252.419371] Dynamic subtest pipe-None-DP-3: SUCCESS (0.001s)
[252.426495] Starting dynamic subtest: pipe-None-DP-4
[252.431163] Dynamic subtest pipe-None-DP-4: SUCCESS (0.001s)
[252.433469] Subtest connector-properties-atomic: SUCCESS (7.848s)
[253.206179] [036/131] (827s left) kms_psr (pr-basic)
[253.307046] Starting subtest: pr-basic
[253.311093] Subtest pr-basic: SKIP (0.000s)
[253.369847] [037/131] (827s left) kms_big_fb (4-tiled-8bpp-rotate-90)
[253.472544] Starting subtest: 4-tiled-8bpp-rotate-90
[253.493581] Subtest 4-tiled-8bpp-rotate-90: SKIP (0.021s)
[253.611149] [038/131] (827s left) sysfs_timeslice_duration (duration)
[253.668549] Starting subtest: duration
[253.670922] Starting dynamic subtest: vecs0
[267.468155] Dynamic subtest vecs0: SUCCESS (13.862s)
[267.477814] Starting dynamic subtest: ccs0
[280.626264] Dynamic subtest ccs0: SUCCESS (13.150s)
[280.636998] Starting dynamic subtest: vcs0
[294.476526] Dynamic subtest vcs0: SUCCESS (13.831s)
[294.487962] Starting dynamic subtest: bcs0
[307.635084] Dynamic subtest bcs0: SUCCESS (13.153s)
[307.645822] Starting dynamic subtest: rcs0
[320.795458] Dynamic subtest rcs0: SUCCESS (13.157s)
[320.805747] Starting dynamic subtest: vcs1
[334.476917] Dynamic subtest vcs1: SUCCESS (13.679s)
[334.480067] Subtest duration: SUCCESS (80.847s)
[334.545124] [039/131] (746s left) gem_exec_flush (basic-batch-kernel-default-uc)
[334.617737] Starting subtest: basic-batch-kernel-default-uc
[340.591534] Subtest basic-batch-kernel-default-uc: SUCCESS (5.973s)
[340.772123] [040/131] (739s left) kms_chamelium_frames (hdmi-crc-fast)
[341.166992] Subtest hdmi-crc-fast: SKIP (0.000s)
[341.954468] [041/131] (738s left) kms_dsc (dsc-with-output-formats-with-bpc)
[342.057126] Starting subtest: dsc-with-output-formats-with-bpc
[342.116033] Subtest dsc-with-output-formats-with-bpc: SKIP (0.001s)
[342.146453] [042/131] (738s left) perf (gen8-unprivileged-single-ctx-counters)
[342.239463] Starting subtest: gen8-unprivileged-single-ctx-counters
[342.243681] Subtest gen8-unprivileged-single-ctx-counters: SKIP (0.000s)
[342.271992] [043/131] (738s left) gem_ccs (block-copy-compressed)
[342.335954] Starting subtest: block-copy-compressed
[342.340067] Subtest block-copy-compressed: SKIP (0.000s)
[342.426541] [044/131] (738s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-msflip-blt)
[342.526802] Starting subtest: fbc-1p-primscrn-indfb-msflip-blt
[346.038562] Subtest fbc-1p-primscrn-indfb-msflip-blt: SUCCESS (3.513s)
[346.178361] [045/131] (734s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-downscaling)
[346.282554] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-downscaling
[346.284616] Starting dynamic subtest: pipe-A-default-mode
[347.450656] Dynamic subtest pipe-A-default-mode: SKIP (1.163s)
[347.455577] Subtest flip-64bpp-4tile-to-32bpp-4tile-downscaling: SKIP (1.164s)
[347.597625] [046/131] (733s left) gem_exec_fence (syncobj-timeline-multiple-ext-nodes)
[348.166964] Starting subtest: syncobj-timeline-multiple-ext-nodes
[348.169592] Subtest syncobj-timeline-multiple-ext-nodes: SUCCESS (0.003s)
[349.694752] [FACT gem_exec_fence (syncobj-timeline-multiple-ext-nodes)] new: kernel.kmod_is_loaded.vgem: true
[349.698034] [047/131] (731s left) gem_ctx_engines (invalid-engines)
[349.769106] Starting subtest: invalid-engines
[349.777255] Subtest invalid-engines: SUCCESS (0.008s)
[349.982468] [048/131] (730s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[350.105970] Starting subtest: cursorA-vs-flipA-legacy
[361.261515] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.152s)
[362.052474] [049/131] (718s left) syncobj_wait (wait-zero-handles)
[362.103853] Starting subtest: wait-zero-handles
[362.106278] Subtest wait-zero-handles: SUCCESS (0.000s)
[362.208937] [050/131] (718s left) kms_addfb_basic (no-handle)
[362.263917] Starting subtest: no-handle
[362.266342] Subtest no-handle: SUCCESS (0.000s)
[362.399860] [051/131] (718s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-modifiers)
[362.494592] Starting subtest: plane-scaler-with-clipping-clamping-modifiers
[362.497331] Starting dynamic subtest: pipe-A
[362.998842] Dynamic subtest pipe-A: SUCCESS (0.505s)
[363.007260] Starting dynamic subtest: pipe-B
[364.618945] Dynamic subtest pipe-B: SUCCESS (1.619s)
[364.627797] Starting dynamic subtest: pipe-C
[366.235201] Dynamic subtest pipe-C: SUCCESS (1.616s)
[366.247456] Starting dynamic subtest: pipe-D
[367.754684] Dynamic subtest pipe-D: SUCCESS (1.519s)
[367.757137] Subtest plane-scaler-with-clipping-clamping-modifiers: SUCCESS (5.260s)
[368.956361] [052/131] (712s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-downscaling)
[369.058267] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-downscaling
[369.060550] Starting dynamic subtest: pipe-A-default-mode
[370.217413] Dynamic subtest pipe-A-default-mode: SKIP (1.154s)
[370.222342] Subtest flip-64bpp-xtile-to-32bpp-xtile-downscaling: SKIP (1.156s)
[370.349726] [053/131] (710s left) kms_atomic_interruptible (atomic-setmode)
[370.456596] Starting subtest: atomic-setmode
[370.458841] Starting dynamic subtest: pipe-A-eDP-1
[377.321061] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.863s)
[377.323115] Subtest atomic-setmode: SUCCESS (6.864s)
[378.105344] [054/131] (703s left) gem_exec_params (invalid-bsd2-flag-on-render)
[378.177192] Starting subtest: invalid-bsd2-flag-on-render
[378.179728] Subtest invalid-bsd2-flag-on-render: SUCCESS (0.000s)
[378.289570] [055/131] (702s left) gem_ppgtt (shrink-vs-evict-any)
[378.355597] Starting subtest: shrink-vs-evict-any
[383.567473] Subtest shrink-vs-evict-any: SUCCESS (5.210s)
[383.632970] [056/131] (697s left) gem_exec_schedule (thriceslice)
[383.708943] Starting subtest: thriceslice
[383.711196] Starting dynamic subtest: rcs0
[383.758209] Dynamic subtest rcs0: SUCCESS (0.058s)
[383.772905] Starting dynamic subtest: bcs0
[383.817353] Dynamic subtest bcs0: SUCCESS (0.057s)
[383.831048] Starting dynamic subtest: vcs0
[383.851753] Dynamic subtest vcs0: SUCCESS (0.033s)
[383.862475] Starting dynamic subtest: vcs1
[383.904462] Dynamic subtest vcs1: SUCCESS (0.052s)
[383.915173] Starting dynamic subtest: vecs0
[383.937967] Dynamic subtest vecs0: SUCCESS (0.032s)
[383.946250] Starting dynamic subtest: ccs0
[383.970423] Dynamic subtest ccs0: SUCCESS (0.032s)
[383.972779] Subtest thriceslice: SUCCESS (0.271s)
[385.616524] [057/131] (695s left) drm_fdinfo (basics)
[385.659083] Starting subtest: basics
[385.661344] Subtest basics: SUCCESS (0.000s)
[385.725067] [058/131] (695s left) gem_exec_balancer (parallel-contexts)
[385.984317] Starting subtest: parallel-contexts
[389.438672] Subtest parallel-contexts: SUCCESS (3.454s)
[389.752979] [059/131] (691s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[389.856120] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[389.860232] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[389.954531] [060/131] (691s left) kms_big_fb (y-tiled-16bpp-rotate-0)
[390.059199] Starting subtest: y-tiled-16bpp-rotate-0
[390.063282] Subtest y-tiled-16bpp-rotate-0: SKIP (0.000s)
[390.150298] [061/131] (691s left) gem_softpin (noreloc-s3)
[390.210511] Starting subtest: noreloc-S3
[411.074258] Subtest noreloc-S3: SUCCESS (5.390s)
[411.187428] [062/131] (685s left) kms_prop_blob (blob-prop-lifetime)
[411.238292] Starting subtest: blob-prop-lifetime
[411.240565] Subtest blob-prop-lifetime: SUCCESS (0.001s)
[411.328592] [063/131] (685s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted-signaled)
[411.398250] Starting subtest: etime-multi-wait-all-for-submit-submitted-signaled
[411.503921] Subtest etime-multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.110s)
[411.576062] [064/131] (685s left) kms_flip (flip-vs-expired-vblank)
[411.675046] Starting subtest: flip-vs-expired-vblank
[411.677260] Starting dynamic subtest: A-eDP1
[420.332327] Dynamic subtest A-eDP1: SUCCESS (8.773s)
[420.341545] Starting dynamic subtest: B-eDP1
[429.048549] Dynamic subtest B-eDP1: SUCCESS (8.753s)
[429.059191] Starting dynamic subtest: C-eDP1
[437.731835] Dynamic subtest C-eDP1: SUCCESS (8.697s)
[437.741098] Starting dynamic subtest: D-eDP1
[446.436391] Dynamic subtest D-eDP1: SUCCESS (8.703s)
[446.441457] Subtest flip-vs-expired-vblank: SUCCESS (34.938s)
[447.695178] [065/131] (649s left) kms_psr (psr-sprite-mmap-gtt)
[447.800568] Starting subtest: psr-sprite-mmap-gtt
[447.802974] Starting dynamic subtest: eDP-1
[449.228614] Dynamic subtest eDP-1: SKIP (1.422s)
[449.233674] Subtest psr-sprite-mmap-gtt: SKIP (1.424s)
[449.368347] [066/131] (647s left) gem_ctx_persistence (engines-mixed-process)
[449.434443] Starting subtest: engines-mixed-process
[449.436629] Starting dynamic subtest: rcs0
[451.173878] Dynamic subtest rcs0: SUCCESS (1.738s)
[451.184502] Starting dynamic subtest: bcs0
[453.220979] Dynamic subtest bcs0: SUCCESS (2.045s)
[453.232503] Starting dynamic subtest: vcs0
[455.142153] Dynamic subtest vcs0: SUCCESS (1.919s)
[455.151586] Starting dynamic subtest: vcs1
[457.168392] Dynamic subtest vcs1: SUCCESS (2.024s)
[457.177704] Starting dynamic subtest: vecs0
[459.162362] Dynamic subtest vecs0: SUCCESS (1.993s)
[459.173775] Starting dynamic subtest: ccs0
[461.214715] Dynamic subtest ccs0: SUCCESS (2.047s)
[461.217307] Subtest engines-mixed-process: SUCCESS (11.775s)
[461.288454] [067/131] (635s left) kms_big_fb (yf-tiled-64bpp-rotate-180)
[461.396636] Starting subtest: yf-tiled-64bpp-rotate-180
[461.400710] Subtest yf-tiled-64bpp-rotate-180: SKIP (0.000s)
[461.510336] [068/131] (635s left) kms_atomic (plane-cursor-legacy)
[461.606344] Starting subtest: plane-cursor-legacy
[461.608664] Starting dynamic subtest: pipe-A-eDP-1
[461.983508] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.374s)
[461.985800] Subtest plane-cursor-legacy: SUCCESS (0.376s)
[462.849954] [069/131] (634s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0)
[462.946687] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0
[463.925876] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (0.979s)
[464.088787] [070/131] (632s left) kms_cursor_crc (cursor-size-change)
[464.184526] Starting subtest: cursor-size-change
[464.186911] Starting dynamic subtest: pipe-A-eDP-1
[464.994276] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.808s)
[465.003749] Starting dynamic subtest: pipe-D-eDP-1
[466.424479] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.428s)
[466.426955] Subtest cursor-size-change: SUCCESS (2.239s)
[467.297763] [071/131] (629s left) gem_exec_schedule (preempt)
[467.376827] Starting subtest: preempt
[467.379045] Starting dynamic subtest: rcs0
[467.395462] Dynamic subtest rcs0: SUCCESS (0.023s)
[467.405933] Starting dynamic subtest: bcs0
[467.412576] Dynamic subtest bcs0: SUCCESS (0.017s)
[467.421129] Starting dynamic subtest: vcs0
[467.431039] Dynamic subtest vcs0: SUCCESS (0.018s)
[467.439541] Starting dynamic subtest: vcs1
[467.457208] Dynamic subtest vcs1: SUCCESS (0.025s)
[467.467187] Starting dynamic subtest: vecs0
[467.473972] Dynamic subtest vecs0: SUCCESS (0.016s)
[467.482267] Starting dynamic subtest: ccs0
[467.493436] Dynamic subtest ccs0: SUCCESS (0.019s)
[467.495691] Subtest preempt: SUCCESS (0.122s)
[469.302257] [072/131] (627s left) kms_color (legacy-gamma)
[469.399337] Starting subtest: legacy-gamma
[469.401592] Starting dynamic subtest: pipe-A-eDP-1
[470.120775] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.718s)
[470.130696] Starting dynamic subtest: pipe-B-eDP-1
[471.481118] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.362s)
[471.491694] Starting dynamic subtest: pipe-C-eDP-1
[472.854641] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.374s)
[472.869787] Starting dynamic subtest: pipe-D-eDP-1
[474.195089] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.339s)
[474.197459] Subtest legacy-gamma: SUCCESS (4.796s)
[475.001166] [073/131] (622s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-gtt)
[475.107193] Starting subtest: fbc-rgb565-draw-mmap-gtt
[475.111686] Subtest fbc-rgb565-draw-mmap-gtt: SKIP (0.000s)
[475.191701] [074/131] (621s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-onoff)
[475.307926] Starting subtest: psr-1p-primscrn-spr-indfb-onoff
[480.163573] Subtest psr-1p-primscrn-spr-indfb-onoff: SUCCESS (4.859s)
[480.314822] [075/131] (616s left) gem_eio (banned)
[480.566988] Starting subtest: banned
[481.205660] Subtest banned: SUCCESS (0.640s)
[481.366723] [076/131] (615s left) kms_psr (fbc-psr2-primary-mmap-gtt)
[481.468382] Starting subtest: fbc-psr2-primary-mmap-gtt
[481.470046] Starting dynamic subtest: eDP-1
[481.473531] Dynamic subtest eDP-1: SKIP (0.000s)
[481.477132] Subtest fbc-psr2-primary-mmap-gtt: SKIP (0.001s)
[481.538909] [077/131] (615s left) gem_exec_params (sol-reset-invalid)
[481.590397] Starting subtest: sol-reset-invalid
[481.595314] Subtest sol-reset-invalid: SUCCESS (0.004s)
[483.239700] [078/131] (614s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[483.334596] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[486.845186] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (3.515s)
[486.988095] [079/131] (610s left) kms_pipe_b_c_ivb (from-pipe-c-to-b-with-3-lanes)
[487.048340] Subtest from-pipe-C-to-B-with-3-lanes: SKIP (0.000s)
[487.109392] [080/131] (610s left) kms_cursor_crc (cursor-rapid-movement-32x10)
[487.258702] Starting subtest: cursor-rapid-movement-32x10
[487.298908] Subtest cursor-rapid-movement-32x10: SKIP (0.035s)
[487.565479] [081/131] (609s left) kms_plane_scaling (plane-upscale-factor-0-25-with-pixel-format)
[487.659070] Starting subtest: plane-upscale-factor-0-25-with-pixel-format
[487.661126] Starting dynamic subtest: pipe-A
[488.708484] Dynamic subtest pipe-A: SUCCESS (1.050s)
[488.716869] Starting dynamic subtest: pipe-B
[490.766401] Dynamic subtest pipe-B: SUCCESS (2.058s)
[490.774557] Starting dynamic subtest: pipe-C
[491.966576] Dynamic subtest pipe-C: SUCCESS (1.199s)
[491.975013] Starting dynamic subtest: pipe-D
[493.096904] Dynamic subtest pipe-D: SUCCESS (1.130s)
[493.099237] Subtest plane-upscale-factor-0-25-with-pixel-format: SUCCESS (5.440s)
[494.277083] [082/131] (603s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-cpu)
[494.396945] Starting subtest: fbc-rgb101010-draw-mmap-cpu
[497.928928] Subtest fbc-rgb101010-draw-mmap-cpu: SUCCESS (3.537s)
[498.027324] [083/131] (599s left) perf (whitelisted-registers-userspace-config)
[498.107037] Starting subtest: whitelisted-registers-userspace-config
[498.109397] Subtest whitelisted-registers-userspace-config: SUCCESS (0.001s)
[498.146868] [084/131] (599s left) i915_pm_rps (thresholds-idle)
[498.208179] Starting subtest: thresholds-idle
[498.212889] Subtest thresholds-idle: SKIP (0.000s)
[498.279076] [085/131] (599s left) i915_query (query-topology-garbage-items)
[498.327182] Starting subtest: query-topology-garbage-items
[498.329341] Subtest query-topology-garbage-items: SUCCESS (0.000s)
[498.445886] [086/131] (599s left) gem_exec_gttfill (basic)
[498.510415] Starting subtest: basic
[503.432591] Subtest basic: SUCCESS (4.922s)
[503.616100] [087/131] (593s left) fbdev (unaligned-read)
[503.698676] Starting subtest: unaligned-read
[503.715603] Subtest unaligned-read: SUCCESS (0.017s)
[503.765942] [088/131] (593s left) gem_exec_whisper (basic-queues)
[503.837934] Starting subtest: basic-queues
[511.690867] Subtest basic-queues: SUCCESS (7.846s)
[511.868020] [089/131] (585s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs)
[511.973341] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs
[511.975882] Starting dynamic subtest: pipe-A-eDP-1
[511.998985] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[512.010818] Starting dynamic subtest: pipe-B-eDP-1
[512.031545] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[512.042878] Starting dynamic subtest: pipe-C-eDP-1
[512.063151] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[512.073453] Starting dynamic subtest: pipe-D-eDP-1
[512.094326] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[512.096208] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs: SUCCESS (0.007s)
[514.156677] [090/131] (583s left) gem_ctx_persistence (legacy-engines-persistence)
[514.208666] Starting subtest: legacy-engines-persistence
[514.211082] Starting dynamic subtest: render
[515.051511] Dynamic subtest render: SUCCESS (0.842s)
[515.063880] Starting dynamic subtest: bsd1
[515.873653] Dynamic subtest bsd1: SUCCESS (0.819s)
[515.891066] Starting dynamic subtest: bsd2
[516.634250] Dynamic subtest bsd2: SUCCESS (0.758s)
[516.646452] Starting dynamic subtest: blt
[517.368449] Dynamic subtest blt: SUCCESS (0.732s)
[517.378952] Starting dynamic subtest: vebox
[518.165850] Dynamic subtest vebox: SUCCESS (0.794s)
[518.168500] Subtest legacy-engines-persistence: SUCCESS (3.956s)
[518.244202] [091/131] (579s left) kms_feature_discovery (display-2x)
[518.359235] Starting subtest: display-2x
[518.363245] Subtest display-2x: SKIP (0.000s)
[518.428584] [092/131] (579s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-onoff)
[518.541552] Starting subtest: psr-1p-primscrn-cur-indfb-onoff
[523.316742] Subtest psr-1p-primscrn-cur-indfb-onoff: SUCCESS (4.776s)
[523.434058] [093/131] (574s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[523.495393] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[523.497760] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.000s)
[523.604100] [094/131] (574s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-msflip-blt)
[523.716072] Starting subtest: psr-2p-scndscrn-shrfb-msflip-blt
[523.720317] Subtest psr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[523.796506] [095/131] (574s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted)
[523.868486] Starting subtest: multi-wait-available-unsubmitted-submitted
[523.870734] Subtest multi-wait-available-unsubmitted-submitted: SUCCESS (0.000s)
[523.963971] [096/131] (573s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions-varying-size)
[524.082453] Starting subtest: cursorA-vs-flipA-atomic-transitions-varying-size
[535.307298] Subtest cursorA-vs-flipA-atomic-transitions-varying-size: SUCCESS (11.221s)
[536.129169] [097/131] (561s left) kms_rotation_crc (exhaust-fences)
[536.229039] Starting subtest: exhaust-fences
[536.233513] Subtest exhaust-fences: SKIP (0.000s)
[536.302557] [098/131] (561s left) gen9_exec_parse (bb-large)
[536.356995] Subtest bb-large: SKIP (0.000s)
[536.417128] [099/131] (561s left) perf (per-context-mode-unprivileged)
[536.516456] Starting subtest: per-context-mode-unprivileged
[536.521061] Subtest per-context-mode-unprivileged: SKIP (0.000s)
[536.553070] [100/131] (561s left) gem_exec_params (larger-than-life-batch)
[536.612342] Starting subtest: larger-than-life-batch
[540.025957] Subtest larger-than-life-batch: SUCCESS (3.412s)
[541.243684] [101/131] (556s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-sf)
[546.276131] Starting subtest: fbc-psr2-cursor-plane-move-continuous-sf
[546.278614] Starting dynamic subtest: pipe-A-eDP-1
[547.077498] Dynamic subtest pipe-A-eDP-1: SKIP (0.797s)
[547.088888] Starting dynamic subtest: pipe-B-eDP-1
[548.206399] Dynamic subtest pipe-B-eDP-1: SKIP (1.129s)
[548.210681] Subtest fbc-psr2-cursor-plane-move-continuous-sf: SKIP (1.929s)
[549.380725] [102/131] (548s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-plflip-blt)
[549.485907] Starting subtest: fbcpsr-1p-primscrn-indfb-plflip-blt
[554.777505] Subtest fbcpsr-1p-primscrn-indfb-plflip-blt: SUCCESS (5.294s)
[554.908686] [103/131] (543s left) gem_exec_capture (capture)
[554.976167] Starting subtest: capture
[554.978543] Starting dynamic subtest: rcs0-smem
[555.815372] Dynamic subtest rcs0-smem: SUCCESS (0.839s)
[555.826333] Starting dynamic subtest: bcs0-smem
[557.145053] Dynamic subtest bcs0-smem: SUCCESS (1.327s)
[557.161138] Starting dynamic subtest: vcs0-smem
[557.960128] Dynamic subtest vcs0-smem: SUCCESS (0.813s)
[557.977060] Starting dynamic subtest: vcs1-smem
[558.769977] Dynamic subtest vcs1-smem: SUCCESS (0.814s)
[558.781028] Starting dynamic subtest: vecs0-smem
[560.175749] Dynamic subtest vecs0-smem: FAIL (1.343s)
[560.186333] Starting dynamic subtest: ccs0-smem
[561.440137] Dynamic subtest ccs0-smem: SUCCESS (1.326s)
[561.442480] Subtest capture: FAIL (6.466s)
[561.591092] [104/131] (536s left) kms_chamelium_audio (dp-audio-edid)
[561.988579] Subtest dp-audio-edid: SKIP (0.000s)
[562.827503] [105/131] (535s left) gem_vm_create (isolation)
[562.900921] Starting subtest: isolation
[562.909398] Subtest isolation: SUCCESS (0.008s)
[563.026702] [106/131] (535s left) syncobj_wait (reset-multiple-signaled)
[563.100691] Starting subtest: reset-multiple-signaled
[563.103184] Subtest reset-multiple-signaled: SUCCESS (0.000s)
[563.217134] [107/131] (535s left) gem_softpin (evict-prime)
[563.301096] Starting subtest: evict-prime
[563.303444] Starting dynamic subtest: rcs0
[564.469189] Dynamic subtest rcs0: SUCCESS (1.171s)
[564.481806] Starting dynamic subtest: bcs0
[565.617545] Dynamic subtest bcs0: SUCCESS (1.147s)
[565.631191] Starting dynamic subtest: vcs0
[567.140740] Dynamic subtest vcs0: SUCCESS (1.520s)
[567.153027] Starting dynamic subtest: vcs1
[569.132717] Dynamic subtest vcs1: SUCCESS (1.990s)
[569.145554] Starting dynamic subtest: vecs0
[571.181242] Dynamic subtest vecs0: SUCCESS (2.047s)
[571.198958] Starting dynamic subtest: ccs0
[572.336566] Dynamic subtest ccs0: SUCCESS (1.155s)
[572.348165] Starting dynamic subtest: all
[574.139693] Dynamic subtest all: SUCCESS (1.800s)
[574.142343] Subtest evict-prime: SUCCESS (10.843s)
[574.291170] [108/131] (524s left) gem_flink_basic (basic)
[574.359868] Starting subtest: basic
[574.362330] Subtest basic: SUCCESS (0.000s)
[574.474750] [109/131] (523s left) gem_lmem_swapping (verify)
[574.517992] Subtest verify: SKIP (0.000s)
[574.560057] [110/131] (523s left) prime_busy (after-wait)
[574.618598] Starting subtest: after-wait
[574.624192] Starting dynamic subtest: rcs0
[574.680777] Dynamic subtest rcs0: SUCCESS (0.058s)
[574.692027] Starting dynamic subtest: bcs0
[574.754456] Dynamic subtest bcs0: SUCCESS (0.073s)
[574.765066] Starting dynamic subtest: vcs0
[574.796598] Dynamic subtest vcs0: SUCCESS (0.042s)
[574.806002] Starting dynamic subtest: vcs1
[574.837897] Dynamic subtest vcs1: SUCCESS (0.041s)
[574.848519] Starting dynamic subtest: vecs0
[574.878709] Dynamic subtest vecs0: SUCCESS (0.041s)
[574.887624] Starting dynamic subtest: ccs0
[574.921263] Dynamic subtest ccs0: SUCCESS (0.042s)
[575.031570] Subtest after-wait: SUCCESS (0.411s)
[575.104488] [111/131] (523s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs)
[575.204072] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs
[575.206437] Starting dynamic subtest: pipe-A-eDP-1
[575.228846] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[575.241049] Starting dynamic subtest: pipe-B-eDP-1
[575.263244] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[575.273663] Starting dynamic subtest: pipe-C-eDP-1
[575.294902] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[575.303785] Starting dynamic subtest: pipe-D-eDP-1
[575.325879] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[575.330145] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[577.396387] [112/131] (521s left) kms_cursor_crc (cursor-onscreen-256x256)
[577.628713] Starting subtest: cursor-onscreen-256x256
[577.633604] Starting dynamic subtest: pipe-A-eDP-1
[580.014076] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.379s)
[580.040539] Starting dynamic subtest: pipe-D-eDP-1
[582.960389] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.929s)
[582.962715] Subtest cursor-onscreen-256x256: SUCCESS (5.330s)
[583.745801] [113/131] (514s left) gem_pxp (verify-pxp-stale-buf-optout-execution)
[583.865344] Starting subtest: verify-pxp-stale-buf-optout-execution
[584.923968] Subtest verify-pxp-stale-buf-optout-execution: SUCCESS (1.059s)
[585.062525] [114/131] (513s left) perf (invalid-oa-format-id)
[585.156666] Starting subtest: invalid-oa-format-id
[585.176157] Subtest invalid-oa-format-id: SUCCESS (0.018s)
[585.217079] [115/131] (513s left) api_intel_bb (misplaced-blitter)
[585.277025] Starting subtest: misplaced-blitter
[585.284294] Subtest misplaced-blitter: SUCCESS (0.007s)
[585.423793] [116/131] (513s left) gem_exec_reloc (basic-wc-noreloc)
[585.485126] Subtest basic-wc-noreloc: SKIP (0.000s)
[585.540101] [117/131] (513s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-gtt)
[585.636307] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-gtt
[585.640306] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[585.737829] [118/131] (512s left) api_intel_allocator (simple-allocator)
[585.788064] Starting subtest: simple-allocator
[585.790365] Starting dynamic subtest: basic
[585.792646] Dynamic subtest basic: SUCCESS (0.003s)
[585.802034] Starting dynamic subtest: parallel-one
[585.975555] Dynamic subtest parallel-one: SUCCESS (0.185s)
[585.986650] Starting dynamic subtest: print
[585.989007] Dynamic subtest print: SUCCESS (0.000s)
[585.998419] Starting dynamic subtest: reuse
[586.000645] Dynamic subtest reuse: SUCCESS (0.000s)
[586.009459] Starting dynamic subtest: reserve
[586.011696] Dynamic subtest reserve: SUCCESS (0.000s)
[586.020379] Starting dynamic subtest: fork-reopen-allocator
[586.022541] Dynamic subtest fork-reopen-allocator: SUCCESS (0.016s)
[586.024818] Subtest simple-allocator: SUCCESS (0.207s)
[586.124151] [119/131] (512s left) gem_mmap_gtt (cpuset-basic-small-copy-xy)
[586.184408] Subtest cpuset-basic-small-copy-XY: SKIP (0.000s)
[586.244147] [120/131] (512s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[586.341338] Starting subtest: primary-4-tiled-reflect-x-180
[593.351443] Subtest primary-4-tiled-reflect-x-180: SUCCESS (7.012s)
[594.580413] [121/131] (504s left) i915_hangman (engine-error-state-capture)
[594.648293] Starting subtest: engine-error-state-capture
[594.650788] Starting dynamic subtest: rcs0
[598.125891] Dynamic subtest rcs0: SUCCESS (3.476s)
[598.139170] Starting dynamic subtest: bcs0
[604.140472] Dynamic subtest bcs0: SUCCESS (6.014s)
[604.153622] Starting dynamic subtest: vcs0
[610.092285] Dynamic subtest vcs0: SUCCESS (5.951s)
[610.104269] Starting dynamic subtest: vcs1
[616.108628] Dynamic subtest vcs1: SUCCESS (6.015s)
[616.119871] Starting dynamic subtest: vecs0
[622.057932] Dynamic subtest vecs0: SUCCESS (5.950s)
[622.069934] Starting dynamic subtest: ccs0
[627.117479] Dynamic subtest ccs0: SUCCESS (5.057s)
[627.120705] Subtest engine-error-state-capture: SUCCESS (32.476s)
[627.235388] [122/131] (471s left) syncobj_eventfd (binary-wait-before-signal)
[627.293935] Starting subtest: binary-wait-before-signal
[627.322479] Subtest binary-wait-before-signal: SUCCESS (0.031s)
[627.396319] [123/131] (471s left) kms_frontbuffer_tracking (psr-rgb565-draw-blt)
[627.508237] Starting subtest: psr-rgb565-draw-blt
[632.418176] Subtest psr-rgb565-draw-blt: SUCCESS (4.916s)
[632.540364] [124/131] (466s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-legacy)
[632.662881] Starting subtest: flip-vs-cursor-busy-crc-legacy
[633.416826] Subtest flip-vs-cursor-busy-crc-legacy: SUCCESS (0.753s)
[634.241317] [125/131] (464s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area-big-fb)
[639.711104] Starting subtest: psr2-primary-plane-update-sf-dmg-area-big-fb
[639.713633] Starting dynamic subtest: pipe-B-eDP-1
[651.027826] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.312s)
[651.030041] Subtest psr2-primary-plane-update-sf-dmg-area-big-fb: SUCCESS (11.314s)
[651.861057] [126/131] (447s left) prime_mmap (test_correct)
[651.929992] Starting subtest: test_correct
[651.932080] Starting dynamic subtest: test_correct-smem
[651.961935] Dynamic subtest test_correct-smem: SUCCESS (0.034s)
[651.964469] Subtest test_correct: SUCCESS (0.035s)
[652.056978] [127/131] (446s left) perf_pmu (busy-accuracy-98)
[652.096136] Starting subtest: busy-accuracy-98
[652.098041] Starting dynamic subtest: rcs0
[655.139117] Dynamic subtest rcs0: SUCCESS (3.040s)
[655.155781] Starting dynamic subtest: bcs0
[658.196254] Dynamic subtest bcs0: SUCCESS (3.055s)
[658.208075] Starting dynamic subtest: vcs0
[661.239002] Dynamic subtest vcs0: SUCCESS (3.041s)
[661.250496] Starting dynamic subtest: vcs1
[664.283576] Dynamic subtest vcs1: SUCCESS (3.042s)
[664.294614] Starting dynamic subtest: vecs0
[667.326996] Dynamic subtest vecs0: SUCCESS (3.042s)
[667.338281] Starting dynamic subtest: ccs0
[670.390165] Dynamic subtest ccs0: SUCCESS (3.060s)
[670.392956] Subtest busy-accuracy-98: SUCCESS (18.290s)
[670.506239] [128/131] (428s left) syncobj_wait (single-wait-all-signaled)
[670.572103] Starting subtest: single-wait-all-signaled
[670.574455] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[670.678598] [129/131] (428s left) kms_flip (dpms-off-confusion)
[670.783476] Starting subtest: dpms-off-confusion
[670.785771] Starting dynamic subtest: A-eDP1
[679.468003] Dynamic subtest A-eDP1: SUCCESS (8.683s)
[679.477504] Starting dynamic subtest: B-eDP1
[688.196117] Dynamic subtest B-eDP1: SUCCESS (8.726s)
[688.212499] Starting dynamic subtest: C-eDP1
[696.906972] Dynamic subtest C-eDP1: SUCCESS (8.709s)
[696.917167] Starting dynamic subtest: D-eDP1
[705.636695] Dynamic subtest D-eDP1: SUCCESS (8.729s)
[705.641833] Subtest dpms-off-confusion: SUCCESS (34.857s)
[706.797228] [130/131] (392s left) kms_psr (psr2-cursor-render)
[706.892442] Starting subtest: psr2-cursor-render
[706.894684] Starting dynamic subtest: eDP-1
[709.202239] Dynamic subtest eDP-1: FAIL (1.711s)
[709.204325] Subtest psr2-cursor-render: FAIL (1.712s)
[709.239040] [131/131] (389s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[709.347895] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[709.352266] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[709.470818] Closing watchdogs
Done.