[57.447789] Initializing watchdogs
[57.447835]   /dev/watchdog0
[57.461622] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[57.465708] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.467930] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.471339] [001/132] (960s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[57.599789] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[57.603681] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[57.697750] [002/132] (960s left) syncobj_timeline (invalid-transfer-illegal-handle)
[57.755087] Starting subtest: invalid-transfer-illegal-handle
[57.757403] Subtest invalid-transfer-illegal-handle: SUCCESS (0.000s)
[57.844870] [003/132] (960s left) gem_exec_nop (basic-sequential)
[57.920625] Starting subtest: basic-sequential
[72.395692] Subtest basic-sequential: SUCCESS (14.485s)
[72.633942] [004/132] (945s left) gem_ctx_isolation (preservation)
[72.691088] Starting subtest: preservation
[72.693470] Starting dynamic subtest: rcs0
[72.849402] Dynamic subtest rcs0: SUCCESS (0.158s)
[72.857043] Starting dynamic subtest: bcs0
[73.025918] Dynamic subtest bcs0: SUCCESS (0.175s)
[73.035806] Starting dynamic subtest: vcs0
[73.194165] Dynamic subtest vcs0: SUCCESS (0.169s)
[73.204042] Starting dynamic subtest: vcs1
[75.024509] Dynamic subtest vcs1: SUCCESS (1.830s)
[75.035307] Starting dynamic subtest: vecs0
[76.997393] Dynamic subtest vecs0: SUCCESS (1.973s)
[77.006540] Starting dynamic subtest: ccs0
[79.073062] Dynamic subtest ccs0: SUCCESS (2.075s)
[79.075494] Subtest preservation: SUCCESS (6.382s)
[79.306102] [005/132] (938s left) gem_close_race (multigpu-basic-process)
[79.383050] Starting subtest: multigpu-basic-process
[79.387551] Subtest multigpu-basic-process: SKIP (0.000s)
[79.596394] [006/132] (938s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs)
[79.690313] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs
[79.692694] Starting dynamic subtest: pipe-A-eDP-1
[95.753245] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.564s)
[95.763043] Starting dynamic subtest: pipe-B-eDP-1
[112.736191] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.445s)
[112.745347] Starting dynamic subtest: pipe-C-eDP-1
[129.684280] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.411s)
[129.695205] Starting dynamic subtest: pipe-D-eDP-1
[146.692732] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.349s)
[146.694774] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs: SUCCESS (8.773s)
[147.848149] [007/132] (870s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted-signa
[147.919951] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled
[148.031419] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.109s)
[148.105727] [008/132] (870s left) kms_feature_discovery (dp-mst)
[148.190279] Starting subtest: dp-mst
[148.194704] Subtest dp-mst: SKIP (0.000s)
[148.257303] [009/132] (870s left) syncobj_timeline (wait-delayed-signal)
[148.304277] Starting subtest: wait-delayed-signal
[148.407792] Subtest wait-delayed-signal: SUCCESS (0.102s)
[148.494358] [010/132] (869s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-wc)
[148.586938] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-wc
[155.104519] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.517s)
[155.247582] [011/132] (863s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[155.346662] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[155.350599] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[155.422089] [012/132] (862s left) syncobj_basic (bad-handle-to-fd)
[155.480408] Starting subtest: bad-handle-to-fd
[155.482753] Subtest bad-handle-to-fd: SUCCESS (0.000s)
[155.578971] [013/132] (862s left) drm_read (empty-nonblock)
[155.707245] Starting subtest: empty-nonblock
[155.709700] Subtest empty-nonblock: SUCCESS (0.000s)
[155.833958] [014/132] (862s left) kms_psr (fbc-psr-no-drrs)
[155.922553] Starting subtest: fbc-psr-no-drrs
[155.924818] Starting dynamic subtest: eDP-1
[155.928941] Dynamic subtest eDP-1: SKIP (0.000s)
[155.933098] Subtest fbc-psr-no-drrs: SKIP (0.001s)
[155.994655] [015/132] (862s left) gem_exec_schedule (implicit-boths)
[156.060447] Starting subtest: implicit-boths
[156.062140] Starting dynamic subtest: rcs0
[156.169306] Dynamic subtest rcs0: SUCCESS (0.116s)
[156.180813] Starting dynamic subtest: vcs0
[156.258219] Dynamic subtest vcs0: SUCCESS (0.088s)
[156.269393] Starting dynamic subtest: vcs1
[156.338584] Dynamic subtest vcs1: SUCCESS (0.080s)
[156.348979] Starting dynamic subtest: vecs0
[156.418961] Dynamic subtest vecs0: SUCCESS (0.080s)
[156.429842] Starting dynamic subtest: ccs0
[156.496761] Dynamic subtest ccs0: SUCCESS (0.078s)
[156.499009] Subtest implicit-boths: SUCCESS (0.443s)
[157.204972] [016/132] (861s left) gem_ctx_persistence (legacy-engines-hostile)
[157.263720] Starting subtest: legacy-engines-hostile
[157.266163] Starting dynamic subtest: render
[157.384436] Dynamic subtest render: SUCCESS (0.120s)
[157.395482] Starting dynamic subtest: bsd1
[157.484210] Dynamic subtest bsd1: SUCCESS (0.098s)
[157.495283] Starting dynamic subtest: bsd2
[157.577856] Dynamic subtest bsd2: SUCCESS (0.091s)
[157.588248] Starting dynamic subtest: blt
[157.666295] Dynamic subtest blt: SUCCESS (0.087s)
[157.678000] Starting dynamic subtest: vebox
[157.762374] Dynamic subtest vebox: SUCCESS (0.094s)
[157.764918] Subtest legacy-engines-hostile: SUCCESS (0.499s)
[157.837287] [017/132] (860s left) kms_pm_rpm (fences)
[159.368121] Starting subtest: fences
[159.373139] Subtest fences: SKIP (0.000s)
[160.124069] [018/132] (858s left) gem_userptr_blits (unsync-unmap)
[160.196394] Subtest unsync-unmap: SKIP (0.000s)
[160.330628] [019/132] (858s left) gem_render_copy (y-tiled-ccs-to-yf-tiled)
[160.384856] Starting subtest: y-tiled-ccs-to-yf-tiled
[160.389664] Subtest y-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[160.570618] [020/132] (858s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[160.669738] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc
[167.218827] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.550s)
[167.341137] [021/132] (851s left) kms_psr (psr2-sprite-mmap-cpu)
[167.434739] Starting subtest: psr2-sprite-mmap-cpu
[167.437105] Starting dynamic subtest: eDP-1
[169.177769] Dynamic subtest eDP-1: SUCCESS (1.744s)
[169.180103] Subtest psr2-sprite-mmap-cpu: SUCCESS (1.745s)
[170.018157] [022/132] (848s left) kms_psr (pr-primary-blt)
[170.114521] Starting subtest: pr-primary-blt
[170.119089] Subtest pr-primary-blt: SKIP (0.000s)
[170.189201] [023/132] (848s left) kms_invalid_mode (clock-too-high)
[170.284966] Starting subtest: clock-too-high
[170.287377] Starting dynamic subtest: pipe-A-eDP-1
[170.291972] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[170.300813] Starting dynamic subtest: pipe-B-eDP-1
[170.304896] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[170.312732] Starting dynamic subtest: pipe-C-eDP-1
[170.317114] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[170.324636] Starting dynamic subtest: pipe-D-eDP-1
[170.329011] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[170.333454] Subtest clock-too-high: SKIP (0.003s)
[170.372444] [024/132] (848s left) gem_lmem_swapping (parallel-random-verify)
[170.416945] Subtest parallel-random-verify: SKIP (0.000s)
[170.457757] [025/132] (848s left) gem_linear_blits (normal)
[170.801043] Starting subtest: normal
[174.376910] Subtest normal: SUCCESS (3.574s)
[174.503194] [026/132] (844s left) gem_partial_pwrite_pread (reads-snoop)
[174.613464] Subtest reads-snoop: SKIP (0.000s)
[174.678446] [027/132] (844s left) kms_flip (bo-too-big)
[174.787322] Starting subtest: bo-too-big
[174.789521] Starting dynamic subtest: A-eDP1
[176.194648] Dynamic subtest A-eDP1: SUCCESS (1.406s)
[176.203224] Starting dynamic subtest: B-eDP1
[177.492138] Dynamic subtest B-eDP1: SUCCESS (1.295s)
[177.502904] Starting dynamic subtest: C-eDP1
[178.828302] Dynamic subtest C-eDP1: SUCCESS (1.334s)
[178.836463] Starting dynamic subtest: D-eDP1
[180.171929] Dynamic subtest D-eDP1: SUCCESS (1.341s)
[180.176468] Subtest bo-too-big: SUCCESS (5.387s)
[181.383225] [028/132] (837s left) i915_pm_rpm (sysfs-read)
[183.218092] Starting subtest: sysfs-read
[184.366108] Subtest sysfs-read: SUCCESS (1.147s)
[184.729866] [029/132] (834s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-cpu)
[184.841719] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-cpu
[184.846057] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[184.961110] [030/132] (834s left) gem_exec_capture (pi)
[185.023873] Starting subtest: pi
[185.026143] Starting dynamic subtest: rcs0
[189.014463] Dynamic subtest rcs0: SUCCESS (3.989s)
[189.025053] Starting dynamic subtest: bcs0
[192.890432] Dynamic subtest bcs0: SUCCESS (3.873s)
[192.903434] Starting dynamic subtest: vcs0
[196.743790] Dynamic subtest vcs0: SUCCESS (3.851s)
[196.755154] Starting dynamic subtest: vcs1
[200.660291] Dynamic subtest vcs1: SUCCESS (3.914s)
[200.671112] Starting dynamic subtest: vecs0
[204.496871] Dynamic subtest vecs0: SUCCESS (3.834s)
[204.508336] Starting dynamic subtest: ccs0
[208.357969] Dynamic subtest ccs0: SUCCESS (3.858s)
[208.361040] Subtest pi: SUCCESS (23.327s)
[208.436686] [031/132] (810s left) kms_cursor_legacy (2x-flip-vs-cursor-atomic)
[208.605656] Starting subtest: 2x-flip-vs-cursor-atomic
[208.610357] Subtest 2x-flip-vs-cursor-atomic: SKIP (0.001s)
[208.746364] [032/132] (810s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[208.851958] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[208.856468] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[208.954967] [033/132] (810s left) gem_exec_flush (basic-wb-ro-default)
[209.033106] Starting subtest: basic-wb-ro-default
[214.488804] Subtest basic-wb-ro-default: SUCCESS (5.455s)
[214.674733] [034/132] (804s left) gem_exec_schedule (preempt-contexts)
[214.770201] Starting subtest: preempt-contexts
[214.772591] Starting dynamic subtest: rcs0
[214.860492] Dynamic subtest rcs0: SUCCESS (0.090s)
[214.874708] Starting dynamic subtest: bcs0
[214.937685] Dynamic subtest bcs0: SUCCESS (0.077s)
[214.947899] Starting dynamic subtest: vcs0
[214.996020] Dynamic subtest vcs0: SUCCESS (0.058s)
[215.007874] Starting dynamic subtest: vcs1
[215.054618] Dynamic subtest vcs1: SUCCESS (0.058s)
[215.064515] Starting dynamic subtest: vecs0
[215.121533] Dynamic subtest vecs0: SUCCESS (0.065s)
[215.135811] Starting dynamic subtest: ccs0
[215.204851] Dynamic subtest ccs0: SUCCESS (0.083s)
[215.207433] Subtest preempt-contexts: SUCCESS (0.434s)
[216.216716] [035/132] (803s left) gem_create (create-invalid-size)
[216.279176] Starting subtest: create-invalid-size
[216.281439] Subtest create-invalid-size: SUCCESS (0.000s)
[216.462570] [036/132] (802s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-render)
[216.562317] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-render
[216.566418] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[216.674285] [037/132] (802s left) syncobj_wait (wait-any-complex)
[216.723618] Starting subtest: wait-any-complex
[216.788273] Subtest wait-any-complex: SUCCESS (0.065s)
[216.865583] [038/132] (802s left) gem_pxp (verify-pxp-stale-buf-optout-execution)
[216.987203] Starting subtest: verify-pxp-stale-buf-optout-execution
[218.045733] Subtest verify-pxp-stale-buf-optout-execution: SUCCESS (1.059s)
[218.187661] [039/132] (801s left) perf_pmu (frequency)
[218.228607] Starting subtest: frequency
[218.231043] Starting dynamic subtest: gt0
[220.393411] Dynamic subtest gt0: SUCCESS (2.157s)
[220.402542] Starting dynamic subtest: idle-gt0
[220.899869] Dynamic subtest idle-gt0: SUCCESS (0.505s)
[220.911715] Starting dynamic subtest: gt1
[225.130559] Dynamic subtest gt1: SUCCESS (4.226s)
[225.142268] Starting dynamic subtest: idle-gt1
[225.658846] Dynamic subtest idle-gt1: SUCCESS (0.529s)
[225.661612] Subtest frequency: SUCCESS (7.430s)
[225.768497] [040/132] (793s left) kms_content_protection (dp-mst-type-0-hdcp14)
[225.917069] Starting subtest: dp-mst-type-0-hdcp14
[225.921711] Subtest dp-mst-type-0-hdcp14: SKIP (0.000s)
[226.034559] [041/132] (793s left) syncobj_timeline (multi-wait-for-submit-available-submitted)
[226.082217] Starting subtest: multi-wait-for-submit-available-submitted
[226.084446] Subtest multi-wait-for-submit-available-submitted: SUCCESS (0.000s)
[226.179838] [042/132] (793s left) dmabuf_sync_file (import-multiple-read-only)
[226.289496] Starting subtest: import-multiple-read-only
[226.291824] Subtest import-multiple-read-only: SUCCESS (0.003s)
[226.341036] [FACT dmabuf_sync_file (import-multiple-read-only)] new: kernel.kmod_is_loaded.vgem: true
[226.344159] [043/132] (793s left) gem_mmap (basic)
[226.410451] Subtest basic: SKIP (0.000s)
[226.474533] [044/132] (793s left) gen7_exec_parse (load-register-reg)
[226.536220] Subtest load-register-reg: SKIP (0.000s)
[226.615703] [045/132] (793s left) gem_mmap_gtt (flink-race)
[226.668927] Subtest flink-race: SKIP (0.000s)
[226.726917] [046/132] (793s left) kms_atomic (test-only)
[226.815907] Starting subtest: test-only
[226.879893] Starting dynamic subtest: pipe-A-eDP-1
[229.443292] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.559s)
[229.445254] Subtest test-only: SUCCESS (2.626s)
[230.314871] [047/132] (789s left) gem_blits (basic)
[230.386369] Starting subtest: basic
[231.802070] Subtest basic: SUCCESS (1.420s)
[231.914880] [048/132] (787s left) kms_frontbuffer_tracking (psr-slowdraw)
[232.001520] Starting subtest: psr-slowdraw
[257.235362] Subtest psr-slowdraw: SUCCESS (25.240s)
[257.359730] [049/132] (762s left) prime_self_import (basic-with_fd_dup)
[257.402769] Starting subtest: basic-with_fd_dup
[257.412669] Subtest basic-with_fd_dup: SUCCESS (0.011s)
[257.502720] [050/132] (762s left) api_intel_bb (crc32)
[257.567824] Starting subtest: crc32
[257.570202] Starting dynamic subtest: rcs0-smem0
[257.854877] Dynamic subtest rcs0-smem0: SUCCESS (0.287s)
[257.867427] Starting dynamic subtest: bcs0-smem0
[258.202092] Dynamic subtest bcs0-smem0: SUCCESS (0.346s)
[258.214371] Starting dynamic subtest: vcs0-smem0
[258.558926] Dynamic subtest vcs0-smem0: SUCCESS (0.355s)
[258.571488] Starting dynamic subtest: vcs1-smem0
[258.899835] Dynamic subtest vcs1-smem0: SUCCESS (0.339s)
[258.913771] Starting dynamic subtest: vecs0-smem0
[259.211883] Dynamic subtest vecs0-smem0: SUCCESS (0.309s)
[259.228476] Starting dynamic subtest: ccs0-smem0
[259.551172] Dynamic subtest ccs0-smem0: SUCCESS (0.337s)
[259.554193] Subtest crc32: SUCCESS (1.985s)
[259.677167] [051/132] (760s left) kms_big_fb (linear-32bpp-rotate-270)
[259.777201] Starting subtest: linear-32bpp-rotate-270
[259.797835] Subtest linear-32bpp-rotate-270: SKIP (0.020s)
[259.902520] [052/132] (760s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping)
[259.995602] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping
[259.999832] Subtest pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping: SKIP (0.000s)
[260.065915] [053/132] (759s left) gem_exec_whisper (basic-queues-priority-all)
[260.139551] Starting subtest: basic-queues-priority-all
[280.478168] Subtest basic-queues-priority-all: SUCCESS (20.337s)
[280.664711] [054/132] (739s left) kms_psr (psr-primary-render)
[280.770376] Starting subtest: psr-primary-render
[280.772737] Starting dynamic subtest: eDP-1
[282.518987] Dynamic subtest eDP-1: SUCCESS (1.748s)
[282.521243] Subtest psr-primary-render: SUCCESS (1.749s)
[283.381906] [055/132] (736s left) kms_addfb_basic (master-rmfb)
[283.437050] Starting subtest: master-rmfb
[283.439058] Subtest master-rmfb: SUCCESS (0.001s)
[283.569758] [056/132] (736s left) gem_exec_reloc (basic-cpu-gtt-active)
[283.632713] Subtest basic-cpu-gtt-active: SKIP (0.000s)
[283.708460] [057/132] (736s left) gem_mmap (pf-nonblock)
[283.769606] Subtest pf-nonblock: SKIP (0.000s)
[283.834724] [058/132] (736s left) kms_psr (fbc-pr-primary-blt)
[283.923572] Starting subtest: fbc-pr-primary-blt
[283.928000] Subtest fbc-pr-primary-blt: SKIP (0.000s)
[283.995499] [059/132] (736s left) kms_cursor_edge_walk (64x64-top-bottom)
[284.096138] Starting subtest: 64x64-top-bottom
[284.098448] Starting dynamic subtest: pipe-A-eDP-1
[287.569918] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.473s)
[287.580421] Starting dynamic subtest: pipe-D-eDP-1
[290.681531] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.110s)
[290.683849] Subtest 64x64-top-bottom: SUCCESS (6.585s)
[291.485613] [060/132] (728s left) perf (gen12-group-exclusive-stream-ctx-handle)
[291.631910] Starting subtest: gen12-group-exclusive-stream-ctx-handle
[291.656002] Subtest gen12-group-exclusive-stream-ctx-handle: SUCCESS (0.025s)
[291.705459] [061/132] (728s left) kms_cursor_crc (cursor-random-512x512)
[293.349369] Starting subtest: cursor-random-512x512
[293.354477] Subtest cursor-random-512x512: SKIP (0.001s)
[293.523149] [062/132] (726s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-render)
[293.620247] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-render
[293.624434] Subtest fbc-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[293.731233] [063/132] (726s left) gem_ctx_shared (single-timeline)
[293.789149] Starting subtest: single-timeline
[293.808899] Subtest single-timeline: SUCCESS (0.014s)
[294.051367] [064/132] (726s left) kms_big_fb (y-tiled-16bpp-rotate-0)
[294.149534] Starting subtest: y-tiled-16bpp-rotate-0
[294.153959] Subtest y-tiled-16bpp-rotate-0: SKIP (0.000s)
[294.245603] [065/132] (726s left) kms_psr2_sf (fbc-pr-primary-plane-update-sf-dmg-area)
[294.340834] Starting subtest: fbc-pr-primary-plane-update-sf-dmg-area
[294.345287] Subtest fbc-pr-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[294.402141] [066/132] (726s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[294.494115] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[301.265664] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SUCCESS (6.774s)
[301.458065] [067/132] (719s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[301.543286] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[301.547756] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[302.643110] [068/132] (718s left) i915_pm_rps (thresholds-idle)
[302.698631] Starting subtest: thresholds-idle
[302.703133] Subtest thresholds-idle: SKIP (0.001s)
[302.799775] [069/132] (717s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[302.902500] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[302.906472] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[303.006685] [070/132] (717s left) kms_ccs (bad-rotation-90-4-tiled-mtl-mc-ccs)
[303.088512] Starting subtest: bad-rotation-90-4-tiled-mtl-mc-ccs
[303.090646] Starting dynamic subtest: pipe-A-eDP-1
[303.504738] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.417s)
[303.516459] Starting dynamic subtest: pipe-B-eDP-1
[303.536688] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.003s)
[303.545703] Starting dynamic subtest: pipe-C-eDP-1
[303.564745] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.003s)
[303.573466] Starting dynamic subtest: pipe-D-eDP-1
[303.590114] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[303.591907] Subtest bad-rotation-90-4-tiled-mtl-mc-ccs: SUCCESS (0.432s)
[305.811809] [071/132] (715s left) gem_flink_basic (bad-flink)
[305.867374] Starting subtest: bad-flink
[305.869519] Subtest bad-flink: SUCCESS (0.000s)
[305.962634] [072/132] (714s left) i915_query (query-topology-coherent-slice-mask)
[306.023258] Starting subtest: query-topology-coherent-slice-mask
[306.027639] Subtest query-topology-coherent-slice-mask: SKIP (0.000s)
[306.123618] [073/132] (714s left) kms_cursor_legacy (single-bo)
[306.238187] Starting subtest: single-bo
[306.240075] Starting dynamic subtest: pipe-A
[311.619910] Dynamic subtest pipe-A: SUCCESS (5.379s)
[311.631638] Starting dynamic subtest: pipe-B
[317.003019] Dynamic subtest pipe-B: SUCCESS (5.379s)
[317.014245] Starting dynamic subtest: pipe-C
[322.386739] Dynamic subtest pipe-C: SUCCESS (5.379s)
[322.397908] Starting dynamic subtest: pipe-D
[327.766833] Dynamic subtest pipe-D: SUCCESS (5.379s)
[327.778473] Starting dynamic subtest: all-pipes
[333.157428] Dynamic subtest all-pipes: SUCCESS (5.386s)
[333.160151] Subtest single-bo: SUCCESS (26.915s)
[333.317743] [074/132] (687s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-wc)
[333.420254] Starting subtest: psr-rgb101010-draw-mmap-wc
[340.510329] Subtest psr-rgb101010-draw-mmap-wc: SUCCESS (7.092s)
[340.612902] [075/132] (680s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[340.732986] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[340.736937] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[340.826965] [076/132] (680s left) kms_color_pipeline (plane-lut1d-ctm3x4-lut1d)
[340.923667] Starting subtest: plane-lut1d-ctm3x4-lut1d
[341.047457] Starting dynamic subtest: pipe-A-plane-0
[341.390558] Dynamic subtest pipe-A-plane-0: FAIL (0.171s)
[341.400349] Starting dynamic subtest: pipe-A-plane-1
[341.600179] Dynamic subtest pipe-A-plane-1: FAIL (0.192s)
[341.610309] Starting dynamic subtest: pipe-A-plane-2
[341.808286] Dynamic subtest pipe-A-plane-2: FAIL (0.166s)
[342.768500] Starting dynamic subtest: pipe-B-plane-0
[343.189449] Dynamic subtest pipe-B-plane-0: FAIL (0.141s)
[343.203227] Starting dynamic subtest: pipe-B-plane-1
[343.507129] Dynamic subtest pipe-B-plane-1: FAIL (0.166s)
[343.517602] Starting dynamic subtest: pipe-B-plane-2
[343.824312] Dynamic subtest pipe-B-plane-2: FAIL (0.593s)
[344.866580] Starting dynamic subtest: pipe-C-plane-0
[345.393150] Dynamic subtest pipe-C-plane-0: FAIL (0.151s)
[345.404749] Starting dynamic subtest: pipe-C-plane-1
[345.815060] Dynamic subtest pipe-C-plane-1: FAIL (0.605s)
[345.824329] Starting dynamic subtest: pipe-C-plane-2
[346.186236] Dynamic subtest pipe-C-plane-2: FAIL (0.148s)
[346.981455] Starting dynamic subtest: pipe-D-plane-0
[347.624625] Dynamic subtest pipe-D-plane-0: FAIL (0.163s)
[347.635950] Starting dynamic subtest: pipe-D-plane-1
[348.102613] Dynamic subtest pipe-D-plane-1: FAIL (0.608s)
[348.113114] Starting dynamic subtest: pipe-D-plane-2
[348.557799] Dynamic subtest pipe-D-plane-2: FAIL (0.549s)
[348.592555] Subtest plane-lut1d-ctm3x4-lut1d: FAIL (7.668s)
[349.444427] [077/132] (671s left) gem_close (basic)
[349.527185] Starting subtest: basic
[349.529340] Subtest basic: SUCCESS (0.000s)
[349.633915] [078/132] (671s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-downscaling)
[349.727180] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-downscaling
[349.731368] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-downscaling: SKIP (0.000s)
[349.797998] [079/132] (671s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[349.896381] Starting subtest: yf-tiled-64bpp-rotate-90
[349.900747] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[350.001320] [080/132] (671s left) gem_partial_pwrite_pread (writes-after-reads)
[350.067420] Subtest writes-after-reads: SKIP (0.000s)
[350.135588] [081/132] (671s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible)
[350.243440] Starting subtest: flip-vs-absolute-wf_vblank-interruptible
[350.245735] Starting dynamic subtest: A-eDP1
[359.190471] Dynamic subtest A-eDP1: SUCCESS (8.945s)
[359.201740] Starting dynamic subtest: B-eDP1
[368.117507] Dynamic subtest B-eDP1: SUCCESS (8.923s)
[368.134262] Starting dynamic subtest: C-eDP1
[377.036717] Dynamic subtest C-eDP1: SUCCESS (8.917s)
[377.049335] Starting dynamic subtest: D-eDP1
[385.956850] Dynamic subtest D-eDP1: SUCCESS (8.918s)
[385.963056] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (35.718s)
[387.180535] [082/132] (634s left) kms_pipe_crc_basic (read-crc)
[387.293789] Starting subtest: read-crc
[387.296209] Starting dynamic subtest: pipe-A-eDP-1
[387.998846] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.703s)
[388.008866] Starting dynamic subtest: pipe-B-eDP-1
[389.332329] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.332s)
[389.342349] Starting dynamic subtest: pipe-C-eDP-1
[390.625227] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.290s)
[390.635693] Starting dynamic subtest: pipe-D-eDP-1
[391.956286] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.332s)
[391.958744] Subtest read-crc: SUCCESS (4.664s)
[392.786344] [083/132] (628s left) kms_plane (pixel-format-4-tiled-mtl-mc-ccs-modifier)
[392.879712] Starting subtest: pixel-format-4-tiled-mtl-mc-ccs-modifier
[392.905434] Starting dynamic subtest: pipe-A-plane-0
[393.327343] Dynamic subtest pipe-A-plane-0: SUCCESS (0.422s)
[393.336803] Starting dynamic subtest: pipe-A-plane-3
[393.593972] Dynamic subtest pipe-A-plane-3: SUCCESS (0.264s)
[393.604645] Starting dynamic subtest: pipe-A-plane-5
[393.608987] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[394.676069] Starting dynamic subtest: pipe-B-plane-0
[395.101515] Dynamic subtest pipe-B-plane-0: SUCCESS (0.433s)
[395.112899] Starting dynamic subtest: pipe-B-plane-3
[395.358000] Dynamic subtest pipe-B-plane-3: SUCCESS (0.253s)
[395.372946] Starting dynamic subtest: pipe-B-plane-5
[395.381998] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[395.670119] Subtest pixel-format-4-tiled-mtl-mc-ccs-modifier: SUCCESS (2.791s)
[396.565963] [084/132] (624s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc)
[396.654425] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc
[396.656547] Starting dynamic subtest: pipe-A-eDP-1
[396.676989] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[396.689685] Starting dynamic subtest: pipe-B-eDP-1
[396.709103] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[396.718019] Starting dynamic subtest: pipe-C-eDP-1
[396.736503] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[396.745316] Starting dynamic subtest: pipe-D-eDP-1
[396.764157] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[396.766356] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.007s)
[398.825642] [085/132] (622s left) kms_chamelium_edid (dp-mode-timings)
[399.223063] Subtest dp-mode-timings: SKIP (0.000s)
[400.031790] [086/132] (621s left) kms_cursor_legacy (cursora-vs-flipb-varying-size)
[400.152673] Starting subtest: cursorA-vs-flipB-varying-size
[400.157029] Subtest cursorA-vs-flipB-varying-size: SKIP (0.001s)
[400.298784] [087/132] (621s left) kms_vblank (query-forked)
[400.391146] Starting subtest: query-forked
[400.393333] Starting dynamic subtest: pipe-A-eDP-1
[402.212120] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.823s)
[402.227065] Starting dynamic subtest: pipe-D-eDP-1
[404.746657] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.529s)
[404.748923] Subtest query-forked: SUCCESS (4.356s)
[405.607180] [088/132] (615s left) kms_chamelium_color (ctm-max)
[405.704803] Subtest ctm-max: SKIP (0.000s)
[405.753140] [089/132] (615s left) kms_lease (lease-unleased-connector)
[405.839304] Starting subtest: lease-unleased-connector
[405.841506] Starting dynamic subtest: pipe-A-eDP-1
[405.846954] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.006s)
[405.857702] Starting dynamic subtest: pipe-B-eDP-1
[405.859790] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.006s)
[405.869955] Starting dynamic subtest: pipe-C-eDP-1
[405.872143] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.007s)
[405.881116] Starting dynamic subtest: pipe-D-eDP-1
[405.882987] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.007s)
[405.884816] Subtest lease-unleased-connector: SUCCESS (0.032s)
[405.973609] [090/132] (615s left) kms_rotation_crc (sprite-rotation-90-pos-100-0)
[406.084128] Starting subtest: sprite-rotation-90-pos-100-0
[406.088481] Subtest sprite-rotation-90-pos-100-0: SKIP (0.001s)
[406.171324] [091/132] (615s left) gen7_exec_parse (basic-allocation)
[406.219459] Subtest basic-allocation: SKIP (0.000s)
[406.283903] [092/132] (615s left) kms_vblank (wait-forked-busy)
[406.382243] Starting subtest: wait-forked-busy
[406.384531] Starting dynamic subtest: pipe-A-eDP-1
[408.258151] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.875s)
[408.272194] Starting dynamic subtest: pipe-D-eDP-1
[410.878773] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.618s)
[410.880984] Subtest wait-forked-busy: SUCCESS (4.497s)
[411.684991] [093/132] (610s left) gem_softpin (allocator-fork)
[411.751910] Starting subtest: allocator-fork
[413.959018] Subtest allocator-fork: SUCCESS (2.205s)
[414.085577] [094/132] (607s left) gem_wait (invalid-flags)
[414.133863] Starting subtest: invalid-flags
[414.136238] Subtest invalid-flags: SUCCESS (0.000s)
[414.332756] [095/132] (607s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[414.437866] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[414.442390] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.000s)
[414.522330] [096/132] (607s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-render)
[414.615040] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-render
[414.619021] Subtest psr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[414.706715] [097/132] (607s left) kms_big_fb (x-tiled-8bpp-rotate-90)
[414.810201] Starting subtest: x-tiled-8bpp-rotate-90
[414.831894] Subtest x-tiled-8bpp-rotate-90: SKIP (0.021s)
[414.942247] [098/132] (607s left) gem_exec_balancer (full-pulse)
[414.994287] Starting subtest: full-pulse
[419.962446] Subtest full-pulse: SUCCESS (4.968s)
[420.298840] [099/132] (601s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions)
[420.426763] Starting subtest: cursorA-vs-flipA-atomic-transitions
[431.582293] Subtest cursorA-vs-flipA-atomic-transitions: SUCCESS (11.155s)
[432.399457] [100/132] (589s left) kms_pm_rpm (pc8-residency)
[434.253614] Starting subtest: pc8-residency
[434.258517] Subtest pc8-residency: SKIP (0.000s)
[434.657203] [101/132] (587s left) gem_exec_schedule (u-independent)
[434.740327] Starting subtest: u-independent
[434.742672] Starting dynamic subtest: rcs0
[436.100306] Dynamic subtest rcs0: SUCCESS (1.370s)
[436.113787] Starting dynamic subtest: vcs0
[438.154143] Dynamic subtest vcs0: SUCCESS (2.051s)
[438.166677] Starting dynamic subtest: vcs1
[440.137327] Dynamic subtest vcs1: SUCCESS (1.981s)
[440.147438] Starting dynamic subtest: vecs0
[442.124167] Dynamic subtest vecs0: SUCCESS (1.983s)
[442.137073] Starting dynamic subtest: ccs0
[444.163731] Dynamic subtest ccs0: SUCCESS (2.037s)
[444.166493] Subtest u-independent: SUCCESS (9.435s)
[444.468932] [102/132] (577s left) gem_wait (invalid-buf)
[444.526191] Starting subtest: invalid-buf
[444.528428] Subtest invalid-buf: SUCCESS (0.000s)
[444.746004] [103/132] (577s left) i915_hangman (detector)
[444.812313] Starting subtest: detector
[444.814566] Starting dynamic subtest: rcs0
[456.097959] Dynamic subtest rcs0: SUCCESS (11.285s)
[456.110222] Starting dynamic subtest: bcs0
[468.065843] Dynamic subtest bcs0: SUCCESS (11.964s)
[468.078589] Starting dynamic subtest: vcs0
[480.034466] Dynamic subtest vcs0: SUCCESS (11.965s)
[480.047290] Starting dynamic subtest: vcs1
[490.016941] Dynamic subtest vcs1: SUCCESS (9.979s)
[490.030251] Starting dynamic subtest: vecs0
[500.065766] Dynamic subtest vecs0: SUCCESS (10.044s)
[500.078323] Starting dynamic subtest: ccs0
[512.099451] Dynamic subtest ccs0: SUCCESS (12.029s)
[512.102378] Subtest detector: SUCCESS (67.283s)
[512.225261] [104/132] (509s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-wc)
[512.323598] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-wc
[512.327528] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[512.405940] [105/132] (509s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[512.502697] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip
[513.604156] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (1.102s)
[513.784370] [106/132] (508s left) kms_cursor_legacy (flip-vs-cursor-legacy)
[513.903895] Starting subtest: flip-vs-cursor-legacy
[520.937632] Subtest flip-vs-cursor-legacy: SUCCESS (7.032s)
[521.757826] [107/132] (500s left) sw_sync (alloc_fence)
[521.809500] Starting subtest: alloc_fence
[521.811712] Subtest alloc_fence: SUCCESS (0.000s)
[521.893953] [108/132] (500s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[521.981972] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[521.986217] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.000s)
[522.056001] [109/132] (500s left) syncobj_wait (wait-all-for-submit-complex)
[522.103795] Starting subtest: wait-all-for-submit-complex
[522.317869] Subtest wait-all-for-submit-complex: SUCCESS (0.213s)
[522.398499] [110/132] (500s left) kms_properties (invalid-properties-legacy)
[522.496621] Starting subtest: invalid-properties-legacy
[522.598241] Subtest invalid-properties-legacy: SUCCESS (0.103s)
[522.671348] [111/132] (499s left) kms_psr (fbc-psr2-dpms)
[522.763244] Starting subtest: fbc-psr2-dpms
[522.765483] Starting dynamic subtest: eDP-1
[522.769543] Dynamic subtest eDP-1: SKIP (0.000s)
[522.773555] Subtest fbc-psr2-dpms: SKIP (0.001s)
[522.835498] [112/132] (499s left) kms_plane_scaling (planes-downscale-factor-0-75)
[522.926848] Starting subtest: planes-downscale-factor-0-75
[522.929197] Starting dynamic subtest: pipe-A
[522.946981] Dynamic subtest pipe-A: SKIP (0.017s)
[522.964089] Starting dynamic subtest: pipe-B
[522.978480] Dynamic subtest pipe-B: SKIP (0.031s)
[522.987851] Starting dynamic subtest: pipe-C
[523.009799] Dynamic subtest pipe-C: SKIP (0.030s)
[523.018949] Starting dynamic subtest: pipe-D
[523.041031] Dynamic subtest pipe-D: SKIP (0.030s)
[523.044975] Subtest planes-downscale-factor-0-75: SKIP (0.111s)
[523.128761] [113/132] (499s left) kms_psr2_sf (fbc-psr2-primary-plane-update-sf-dmg-area)
[523.232642] Starting subtest: fbc-psr2-primary-plane-update-sf-dmg-area
[523.234895] Starting dynamic subtest: pipe-A-eDP-1
[523.318223] Dynamic subtest pipe-A-eDP-1: SKIP (0.089s)
[523.328797] Starting dynamic subtest: pipe-B-eDP-1
[524.432640] Dynamic subtest pipe-B-eDP-1: SKIP (1.111s)
[524.436680] Subtest fbc-psr2-primary-plane-update-sf-dmg-area: SKIP (1.203s)
[525.640161] [114/132] (496s left) syncobj_timeline (invalid-multi-wait-unsubmitted)
[525.706998] Starting subtest: invalid-multi-wait-unsubmitted
[525.709275] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[525.805670] [115/132] (496s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-onoff)
[525.893246] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-onoff
[532.957968] Subtest fbcpsr-1p-primscrn-cur-indfb-onoff: SUCCESS (7.067s)
[533.103356] [116/132] (489s left) kms_chamelium_frames (vga-frame-dump)
[533.494816] Subtest vga-frame-dump: SKIP (0.000s)
[534.275279] [117/132] (488s left) i915_drm_fdinfo (all-busy-idle-check-all)
[534.317124] Starting subtest: all-busy-idle-check-all
[534.321913] Subtest all-busy-idle-check-all: SKIP (0.003s)
[534.395274] [118/132] (488s left) vgem_basic (debugfs)
[534.445040] Starting subtest: debugfs
[534.447288] Subtest debugfs: SUCCESS (0.000s)
[534.519858] [119/132] (488s left) syncobj_timeline (single-wait-for-submit-available-signaled)
[534.589673] Starting subtest: single-wait-for-submit-available-signaled
[534.592065] Subtest single-wait-for-submit-available-signaled: SUCCESS (0.000s)
[534.690510] [120/132] (488s left) kms_hdr (bpc-switch-dpms)
[534.770321] Starting subtest: bpc-switch-dpms
[535.859205] Starting dynamic subtest: pipe-A-eDP-1
[540.393770] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.534s)
[540.396060] Subtest bpc-switch-dpms: SUCCESS (5.626s)
[540.510898] [121/132] (482s left) kms_setmode (clone-exclusive-crtc)
[540.570551] Starting subtest: clone-exclusive-crtc
[540.577298] Subtest clone-exclusive-crtc: SKIP (0.000s)
[540.694077] [122/132] (482s left) sriov_basic (bind-unbind-vf)
[540.747235] Starting subtest: bind-unbind-vf
[540.749568] Starting dynamic subtest: vf-1
[540.823125] Dynamic subtest vf-1: FAIL (0.004s)
[540.834483] Starting dynamic subtest: vf-2
[540.906651] Dynamic subtest vf-2: FAIL (0.003s)
[540.917682] Starting dynamic subtest: vf-3
[540.992944] Dynamic subtest vf-3: FAIL (0.003s)
[541.003924] Starting dynamic subtest: vf-4
[541.076791] Dynamic subtest vf-4: FAIL (0.003s)
[541.088004] Starting dynamic subtest: vf-5
[541.161158] Dynamic subtest vf-5: FAIL (0.004s)
[541.171904] Starting dynamic subtest: vf-6
[541.244910] Dynamic subtest vf-6: FAIL (0.004s)
[541.255474] Starting dynamic subtest: vf-7
[541.328080] Dynamic subtest vf-7: FAIL (0.003s)
[541.339184] Starting dynamic subtest: vf-random
[541.407179] Dynamic subtest vf-random: FAIL (0.004s)
[541.417526] Starting dynamic subtest: vf-last
[541.476905] Dynamic subtest vf-last: FAIL (0.519s)
[541.478588] Subtest bind-unbind-vf: FAIL (0.555s)
[541.516166] [123/132] (481s left) perf_pmu (enable-race)
[541.560642] Starting subtest: enable-race
[541.562449] Starting dynamic subtest: rcs0
[554.322499] Dynamic subtest rcs0: SUCCESS (12.761s)
[554.332393] Starting dynamic subtest: bcs0
[567.069560] Dynamic subtest bcs0: SUCCESS (12.743s)
[567.081817] Starting dynamic subtest: vcs0
[583.107919] Dynamic subtest vcs0: SUCCESS (16.032s)
[583.120659] Starting dynamic subtest: vcs1
[599.107251] Dynamic subtest vcs1: SUCCESS (15.994s)
[599.119930] Starting dynamic subtest: vecs0
[615.108128] Dynamic subtest vecs0: SUCCESS (15.996s)
[615.120720] Starting dynamic subtest: ccs0
[627.872648] Dynamic subtest ccs0: SUCCESS (12.762s)
[627.875168] Subtest enable-race: SUCCESS (86.303s)
[627.982354] [124/132] (395s left) kms_cursor_crc (cursor-dpms)
[628.112348] Starting subtest: cursor-dpms
[628.114674] Starting dynamic subtest: pipe-A-eDP-1
[631.114957] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.001s)
[631.125909] Starting dynamic subtest: pipe-D-eDP-1
[634.695247] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.577s)
[634.697085] Subtest cursor-dpms: SUCCESS (6.581s)
[635.549567] [125/132] (387s left) gem_eio (wait-10ms)
[635.787056] Starting subtest: wait-10ms
[636.134352] Subtest wait-10ms: SUCCESS (0.347s)
[636.275741] [126/132] (386s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[636.379578] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc
[636.383555] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[636.495237] [127/132] (386s left) gem_exec_whisper (basic-contexts-priority)
[636.563485] Starting subtest: basic-contexts-priority
[660.691132] Subtest basic-contexts-priority: SUCCESS (24.123s)
[660.891508] [128/132] (362s left) kms_plane (plane-position-covered)
[661.001297] Starting subtest: plane-position-covered
[661.505896] Starting dynamic subtest: pipe-A-plane-1
[662.827685] Dynamic subtest pipe-A-plane-1: SUCCESS (1.324s)
[662.839827] Starting dynamic subtest: pipe-A-plane-2
[664.138080] Dynamic subtest pipe-A-plane-2: SUCCESS (1.309s)
[664.147548] Starting dynamic subtest: pipe-A-plane-3
[665.362950] Dynamic subtest pipe-A-plane-3: SUCCESS (1.224s)
[665.375173] Starting dynamic subtest: pipe-A-plane-4
[666.605925] Dynamic subtest pipe-A-plane-4: SUCCESS (1.240s)
[666.617390] Starting dynamic subtest: pipe-A-plane-5
[666.630909] Dynamic subtest pipe-A-plane-5: SUCCESS (0.026s)
[667.760060] Starting dynamic subtest: pipe-B-plane-1
[669.083276] Dynamic subtest pipe-B-plane-1: SUCCESS (1.330s)
[669.095467] Starting dynamic subtest: pipe-B-plane-2
[670.437379] Dynamic subtest pipe-B-plane-2: SUCCESS (1.354s)
[670.447615] Starting dynamic subtest: pipe-B-plane-3
[671.743348] Dynamic subtest pipe-B-plane-3: SUCCESS (1.304s)
[671.754668] Starting dynamic subtest: pipe-B-plane-4
[673.082232] Dynamic subtest pipe-B-plane-4: SUCCESS (1.340s)
[673.093025] Starting dynamic subtest: pipe-B-plane-5
[673.110555] Dynamic subtest pipe-B-plane-5: SUCCESS (0.026s)
[673.112819] Subtest plane-position-covered: SUCCESS (12.110s)
[673.953273] [129/132] (349s left) gem_sync (basic-store-all)
[674.043747] Starting subtest: basic-store-all
[678.233577] Subtest basic-store-all: SUCCESS (4.192s)
[678.428634] [130/132] (344s left) api_intel_bb (simple-bb-ctx)
[678.496312] Starting subtest: simple-bb-ctx
[678.513461] Subtest simple-bb-ctx: SUCCESS (0.018s)
[678.634022] [131/132] (344s left) kms_big_fb (4-tiled-16bpp-rotate-90)
[678.742551] Starting subtest: 4-tiled-16bpp-rotate-90
[678.762129] Subtest 4-tiled-16bpp-rotate-90: SKIP (0.019s)
[678.872950] [132/132] (344s left) gem_exec_fence (syncobj-timeline-export)
[679.429196] Starting subtest: syncobj-timeline-export
[679.435066] Subtest syncobj-timeline-export: SUCCESS (0.007s)
[681.165764] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: syncobj_timeline
results: parsing output: 2/ for test: gem_exec_nop
results: parsing output: 3/ for test: gem_ctx_isolation
results: parsing output: 4/ for test: gem_close_race
results: parsing output: 5/ for test: kms_ccs
results: parsing output: 6/ for test: syncobj_timeline
results: parsing output: 7/ for test: kms_feature_discovery
results: parsing output: 8/ for test: syncobj_timeline
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: kms_big_fb
results: parsing output: 11/ for test: syncobj_basic
results: parsing output: 12/ for test: drm_read
results: parsing output: 13/ for test: kms_psr
results: parsing output: 14/ for test: gem_exec_schedule
results: parsing output: 15/ for test: gem_ctx_persistence
results: parsing output: 16/ for test: kms_pm_rpm
results: parsing output: 17/ for test: gem_userptr_blits
results: parsing output: 18/ for test: gem_render_copy
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: kms_psr
results: parsing output: 21/ for test: kms_psr
results: parsing output: 22/ for test: kms_invalid_mode
results: parsing output: 23/ for test: gem_lmem_swapping
results: parsing output: 24/ for test: gem_linear_blits
results: parsing output: 25/ for test: gem_partial_pwrite_pread
results: parsing output: 26/ for test: kms_flip
results: parsing output: 27/ for test: i915_pm_rpm
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: gem_exec_capture
results: parsing output: 30/ for test: kms_cursor_legacy
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: gem_exec_flush
results: parsing output: 33/ for test: gem_exec_schedule
results: parsing output: 34/ for test: gem_create
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: syncobj_wait
results: parsing output: 37/ for test: gem_pxp
results: parsing output: 38/ for test: perf_pmu
results: parsing output: 39/ for test: kms_content_protection
results: parsing output: 40/ for test: syncobj_timeline
results: parsing output: 41/ for test: dmabuf_sync_file
results: parsing output: 42/ for test: gem_mmap
results: parsing output: 43/ for test: gen7_exec_parse
results: parsing output: 44/ for test: gem_mmap_gtt
results: parsing output: 45/ for test: kms_atomic
results: parsing output: 46/ for test: gem_blits
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: prime_self_import
results: parsing output: 49/ for test: api_intel_bb
results: parsing output: 50/ for test: kms_big_fb
results: parsing output: 51/ for test: kms_plane
results: parsing output: 52/ for test: gem_exec_whisper
results: parsing output: 53/ for test: kms_psr
results: parsing output: 54/ for test: kms_addfb_basic
results: parsing output: 55/ for test: gem_exec_reloc
results: parsing output: 56/ for test: gem_mmap
results: parsing output: 57/ for test: kms_psr
results: parsing output: 58/ for test: kms_cursor_edge_walk
results: parsing output: 59/ for test: perf
results: parsing output: 60/ for test: kms_cursor_crc
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: gem_ctx_shared
results: parsing output: 63/ for test: kms_big_fb
results: parsing output: 64/ for test: kms_psr2_sf
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: kms_ccs
results: parsing output: 67/ for test: i915_pm_rps
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: kms_ccs
results: parsing output: 70/ for test: gem_flink_basic
results: parsing output: 71/ for test: i915_query
results: parsing output: 72/ for test: kms_cursor_legacy
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_color_pipeline
results: parsing output: 76/ for test: gem_close
results: parsing output: 77/ for test: kms_flip_scaled_crc
results: parsing output: 78/ for test: kms_big_fb
results: parsing output: 79/ for test: gem_partial_pwrite_pread
results: parsing output: 80/ for test: kms_flip
results: parsing output: 81/ for test: kms_pipe_crc_basic
results: parsing output: 82/ for test: kms_plane
results: parsing output: 83/ for test: kms_ccs
results: parsing output: 84/ for test: kms_chamelium_edid
results: parsing output: 85/ for test: kms_cursor_legacy
results: parsing output: 86/ for test: kms_vblank
results: parsing output: 87/ for test: kms_chamelium_color
results: parsing output: 88/ for test: kms_lease
results: parsing output: 89/ for test: kms_rotation_crc
results: parsing output: 90/ for test: gen7_exec_parse
results: parsing output: 91/ for test: kms_vblank
results: parsing output: 92/ for test: gem_softpin
results: parsing output: 93/ for test: gem_wait
results: parsing output: 94/ for test: kms_flip_scaled_crc
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_big_fb
results: parsing output: 97/ for test: gem_exec_balancer
results: parsing output: 98/ for test: kms_cursor_legacy
results: parsing output: 99/ for test: kms_pm_rpm
results: parsing output: 100/ for test: gem_exec_schedule
results: parsing output: 101/ for test: gem_wait
results: parsing output: 102/ for test: i915_hangman
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_big_fb
results: parsing output: 105/ for test: kms_cursor_legacy
results: parsing output: 106/ for test: sw_sync
results: parsing output: 107/ for test: kms_flip_scaled_crc
results: parsing output: 108/ for test: syncobj_wait
results: parsing output: 109/ for test: kms_properties
results: parsing output: 110/ for test: kms_psr
results: parsing output: 111/ for test: kms_plane_scaling
results: parsing output: 112/ for test: kms_psr2_sf
results: parsing output: 113/ for test: syncobj_timeline
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: kms_chamelium_frames
results: parsing output: 116/ for test: i915_drm_fdinfo
results: parsing output: 117/ for test: vgem_basic
results: parsing output: 118/ for test: syncobj_timeline
results: parsing output: 119/ for test: kms_hdr
results: parsing output: 120/ for test: kms_setmode
results: parsing output: 121/ for test: sriov_basic
results: parsing output: 122/ for test: perf_pmu
results: parsing output: 123/ for test: kms_cursor_crc
results: parsing output: 124/ for test: gem_eio
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: gem_exec_whisper
results: parsing output: 127/ for test: kms_plane
results: parsing output: 128/ for test: gem_sync
results: parsing output: 129/ for test: api_intel_bb
results: parsing output: 130/ for test: kms_big_fb
results: parsing output: 131/ for test: gem_exec_fence
Done.