[72.579863] Initializing watchdogs
[72.579913]   /dev/watchdog0
[72.611810] [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]
[72.623702] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[72.630135] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[72.634364] [001/156] (960s left) sysfs_heartbeat_interval (idempotent)
[72.923096] Starting subtest: idempotent
[72.925643] Starting dynamic subtest: vecs0
[72.928322] Dynamic subtest vecs0: SUCCESS (0.002s)
[72.941447] Starting dynamic subtest: ccs0
[72.943965] Dynamic subtest ccs0: SUCCESS (0.004s)
[72.951660] Starting dynamic subtest: vcs0
[72.953653] Dynamic subtest vcs0: SUCCESS (0.001s)
[72.962671] Starting dynamic subtest: bcs0
[72.965030] Dynamic subtest bcs0: SUCCESS (0.003s)
[72.974755] Starting dynamic subtest: rcs0
[72.976873] Dynamic subtest rcs0: SUCCESS (0.002s)
[72.983876] Starting dynamic subtest: vcs1
[72.985861] Dynamic subtest vcs1: SUCCESS (0.001s)
[72.987861] Subtest idempotent: SUCCESS (0.015s)
[73.069322] [002/156] (960s left) gem_mmap_gtt (ptrace)
[73.155482] Subtest ptrace: SKIP (0.000s)
[73.250408] [003/156] (960s left) gem_create (create-ext-placement-all)
[73.417618] Starting subtest: create-ext-placement-all
[73.420122] Subtest create-ext-placement-all: SUCCESS (0.000s)
[73.574197] [004/156] (959s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-factor-0-25)
[73.667195] Starting subtest: planes-downscale-factor-0-5-upscale-factor-0-25
[73.669926] Starting dynamic subtest: pipe-A
[73.689944] Dynamic subtest pipe-A: SKIP (0.018s)
[73.698605] Starting dynamic subtest: pipe-B
[73.721171] Dynamic subtest pipe-B: SKIP (0.033s)
[73.731602] Starting dynamic subtest: pipe-C
[73.754335] Dynamic subtest pipe-C: SKIP (0.033s)
[73.761798] Starting dynamic subtest: pipe-D
[73.789373] Dynamic subtest pipe-D: SKIP (0.033s)
[73.793091] Subtest planes-downscale-factor-0-5-upscale-factor-0-25: SKIP (0.118s)
[73.898986] [005/156] (959s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-plflip-blt)
[74.008965] Starting subtest: fbcpsr-2p-scndscrn-shrfb-plflip-blt
[74.014033] Subtest fbcpsr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[74.096533] [006/156] (959s left) kms_async_flips (test-cursor-atomic)
[74.195086] Starting subtest: test-cursor-atomic
[74.578381] Starting dynamic subtest: pipe-A-eDP-1
[75.340671] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.762s)
[75.707897] Starting dynamic subtest: pipe-B-eDP-1
[76.474083] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.774s)
[76.862236] Starting dynamic subtest: pipe-C-eDP-1
[77.616200] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.762s)
[77.979529] Starting dynamic subtest: pipe-D-eDP-1
[78.743081] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.770s)
[78.745495] Subtest test-cursor-atomic: SUCCESS (4.549s)
[79.928808] [007/156] (953s left) gem_create (create-invalid-size)
[79.986131] Starting subtest: create-invalid-size
[79.988508] Subtest create-invalid-size: SUCCESS (0.000s)
[80.164751] [008/156] (953s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-spr-indfb-draw-blt)
[80.271681] Starting subtest: hdr-2p-scndscrn-spr-indfb-draw-blt
[80.275816] Subtest hdr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[80.387612] [009/156] (953s left) kms_cursor_crc (cursor-random-32x32)
[80.536127] Starting subtest: cursor-random-32x32
[80.580979] Subtest cursor-random-32x32: SKIP (0.040s)
[80.854334] [010/156] (952s left) gem_exec_capture (pi)
[80.924878] Starting subtest: pi
[80.928046] Starting dynamic subtest: rcs0
[85.172756] Dynamic subtest rcs0: SUCCESS (4.247s)
[85.181609] Starting dynamic subtest: bcs0
[89.151275] Dynamic subtest bcs0: SUCCESS (3.977s)
[89.162480] Starting dynamic subtest: vcs0
[93.101629] Dynamic subtest vcs0: SUCCESS (3.950s)
[93.112542] Starting dynamic subtest: vcs1
[97.075396] Dynamic subtest vcs1: SUCCESS (3.974s)
[97.084101] Starting dynamic subtest: vecs0
[101.047953] Dynamic subtest vecs0: SUCCESS (3.971s)
[101.059540] Starting dynamic subtest: ccs0
[105.041842] Dynamic subtest ccs0: SUCCESS (3.993s)
[105.044269] Subtest pi: SUCCESS (24.115s)
[105.120878] [011/156] (928s left) kms_setmode (basic)
[105.232897] Starting subtest: basic
[105.601778] Starting dynamic subtest: pipe-A-eDP-1
[107.637330] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.031s)
[107.979053] Starting dynamic subtest: pipe-B-eDP-1
[110.058779] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.083s)
[110.367137] Subtest basic: SUCCESS (5.131s)
[111.201428] [012/156] (922s left) kms_async_flips (async-flip-with-page-flip-events-linear)
[111.310937] Starting subtest: async-flip-with-page-flip-events-linear
[111.683071] Starting dynamic subtest: pipe-A-eDP-1
[114.539695] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.801s)
[114.854498] Starting dynamic subtest: pipe-B-eDP-1
[117.664810] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.766s)
[117.977975] Starting dynamic subtest: pipe-C-eDP-1
[120.797705] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.775s)
[121.105667] Starting dynamic subtest: pipe-D-eDP-1
[123.920585] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.773s)
[123.923030] Subtest async-flip-with-page-flip-events-linear: SUCCESS (12.558s)
[125.052323] [013/156] (908s left) gem_ctx_bad_destroy (invalid-ctx)
[125.114515] Starting subtest: invalid-ctx
[125.116787] Subtest invalid-ctx: SUCCESS (0.000s)
[125.197116] [014/156] (908s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats)
[125.584517] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s)
[126.371556] [015/156] (907s left) kms_cursor_crc (cursor-onscreen-128x42)
[126.594467] Starting subtest: cursor-onscreen-128x42
[126.638610] Subtest cursor-onscreen-128x42: SKIP (0.040s)
[126.836761] [016/156] (906s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[126.939497] Starting subtest: fbcpsrhdr-1p-offscreen-pri-indfb-draw-mmap-gtt
[126.944027] Subtest fbcpsrhdr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[127.044690] [017/156] (906s left) kms_vblank (ts-continuation-idle-hang)
[127.132902] Starting subtest: ts-continuation-idle-hang
[127.134838] Starting dynamic subtest: pipe-A-eDP-1
[154.055821] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.921s)
[154.071236] Starting dynamic subtest: pipe-D-eDP-1
[169.019019] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.962s)
[169.021694] Subtest ts-continuation-idle-hang: SUCCESS (41.886s)
[169.853579] [018/156] (863s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[169.914398] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[169.916800] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[170.010212] [019/156] (863s left) kms_dsc (dsc-with-bpc-formats)
[170.100718] Starting subtest: dsc-with-bpc-formats
[170.215291] Subtest dsc-with-bpc-formats: SKIP (0.003s)
[170.251564] [020/156] (863s left) kms_psr (fbc-psr-cursor-mmap-cpu)
[170.351172] Starting subtest: fbc-psr-cursor-mmap-cpu
[170.353407] Starting dynamic subtest: eDP-1
[170.357439] Dynamic subtest eDP-1: SKIP (0.000s)
[170.361559] Subtest fbc-psr-cursor-mmap-cpu: SKIP (0.000s)
[170.431839] [021/156] (863s left) gem_exec_whisper (basic-fds-forked-all)
[170.497608] Starting subtest: basic-fds-forked-all
[190.553649] Subtest basic-fds-forked-all: SUCCESS (20.053s)
[190.737103] [022/156] (843s left) kms_big_fb (4-tiled-32bpp-rotate-90)
[190.863978] Starting subtest: 4-tiled-32bpp-rotate-90
[190.883633] Subtest 4-tiled-32bpp-rotate-90: SKIP (0.019s)
[191.018868] [023/156] (843s left) kms_psr (fbc-psr2-sprite-render)
[191.112170] Starting subtest: fbc-psr2-sprite-render
[191.114125] Starting dynamic subtest: eDP-1
[191.118246] Dynamic subtest eDP-1: SKIP (0.000s)
[191.122381] Subtest fbc-psr2-sprite-render: SKIP (0.000s)
[191.191984] [024/156] (842s left) gem_exec_schedule (semaphore-power)
[191.377070] Subtest semaphore-power: SKIP (0.000s)
[191.548643] [025/156] (842s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[191.644749] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-cpu
[191.649109] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[191.745923] [026/156] (842s left) gem_mmap_gtt (basic-write-read-distinct)
[191.804205] Subtest basic-write-read-distinct: SKIP (0.000s)
[191.867601] [027/156] (842s left) kms_ccs (crc-primary-basic-y-tiled-gen12-mc-ccs)
[191.970854] Starting subtest: crc-primary-basic-y-tiled-gen12-mc-ccs
[191.973183] Starting dynamic subtest: pipe-A-eDP-1
[191.996473] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[192.007079] Starting dynamic subtest: pipe-B-eDP-1
[192.029747] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[192.038247] Starting dynamic subtest: pipe-C-eDP-1
[192.060075] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[192.068737] Starting dynamic subtest: pipe-D-eDP-1
[192.091602] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[192.095948] Subtest crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[194.221431] [028/156] (839s left) kms_ccs (random-ccs-data-4-tiled-lnl-ccs)
[194.319769] Starting subtest: random-ccs-data-4-tiled-lnl-ccs
[194.324281] Subtest random-ccs-data-4-tiled-lnl-ccs: SKIP (0.000s)
[195.450904] [029/156] (838s left) i915_query (query-regions-sanity-check)
[195.502483] Starting subtest: query-regions-sanity-check
[195.512156] Subtest query-regions-sanity-check: SUCCESS (0.011s)
[195.598775] [030/156] (838s left) kms_psr (psr2-cursor-mmap-cpu)
[195.682444] Starting subtest: psr2-cursor-mmap-cpu
[195.684783] Starting dynamic subtest: eDP-1
[197.432632] Dynamic subtest eDP-1: SUCCESS (1.752s)
[197.435070] Subtest psr2-cursor-mmap-cpu: SUCCESS (1.753s)
[198.330034] [031/156] (836s left) gem_exec_reloc (basic-wc-read)
[198.393931] Subtest basic-wc-read: SKIP (0.000s)
[198.464353] [032/156] (835s left) gem_exec_fence (syncobj-backward-timeline-chain-engines)
[199.121215] Starting subtest: syncobj-backward-timeline-chain-engines
[199.240421] Subtest syncobj-backward-timeline-chain-engines: SUCCESS (0.119s)
[200.341811] [FACT gem_exec_fence (syncobj-backward-timeline-chain-engines)] new: kernel.kmod_is_loaded.vgem: true
[200.345056] [033/156] (834s left) i915_drm_fdinfo (virtual-busy-idle-all)
[200.389412] Starting subtest: virtual-busy-idle-all
[200.394187] Subtest virtual-busy-idle-all: SKIP (0.000s)
[200.462437] [034/156] (833s left) gem_exec_capture (userptr)
[200.522429] Starting subtest: userptr
[201.356754] Subtest userptr: SUCCESS (0.832s)
[201.498250] [035/156] (833s left) gem_pread (self)
[201.554547] Subtest self: SKIP (0.000s)
[201.611880] [036/156] (832s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-blt)
[201.708429] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-blt
[201.712522] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[201.803967] [037/156] (832s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180)
[201.900080] Starting subtest: linear-max-hw-stride-64bpp-rotate-180
[202.877579] Subtest linear-max-hw-stride-64bpp-rotate-180: SUCCESS (0.978s)
[203.012812] [038/156] (831s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-fullscreen)
[203.119236] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-fullscreen
[203.123364] Subtest psrhdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[203.222867] [039/156] (831s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[203.328270] Starting subtest: fbchdr-1p-primscrn-pri-indfb-draw-mmap-cpu
[203.331869] Subtest fbchdr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[203.428094] [040/156] (831s left) kms_chamelium_hpd (dp-hpd-after-suspend)
[203.818403] Subtest dp-hpd-after-suspend: SKIP (0.000s)
[204.636697] [041/156] (830s left) kms_atomic_transition (plane-all-modeset-transition-fencing)
[204.735949] Starting subtest: plane-all-modeset-transition-fencing
[204.740569] Subtest plane-all-modeset-transition-fencing: SKIP (0.000s)
[204.807068] [042/156] (829s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[204.914072] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[204.918248] Subtest fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[205.001976] [043/156] (829s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-msflip-blt)
[205.100268] Starting subtest: fbcpsr-2p-primscrn-indfb-msflip-blt
[205.104589] Subtest fbcpsr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[205.192134] [044/156] (829s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[205.293158] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt
[205.297304] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[205.390737] [045/156] (829s left) kms_sequence (get-forked)
[205.497223] Starting subtest: get-forked
[205.499591] Starting dynamic subtest: pipe-A-eDP-1
[207.373059] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.877s)
[207.383753] Starting dynamic subtest: pipe-B-eDP-1
[209.898066] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.522s)
[209.908159] Starting dynamic subtest: pipe-C-eDP-1
[212.418066] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.519s)
[212.429736] Starting dynamic subtest: pipe-D-eDP-1
[214.971745] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.552s)
[214.974241] Subtest get-forked: SUCCESS (9.474s)
[215.797964] [046/156] (819s left) syncobj_timeline (etime-multi-wait-all-submitted-signaled)
[215.853147] Starting subtest: etime-multi-wait-all-submitted-signaled
[215.959059] Subtest etime-multi-wait-all-submitted-signaled: SUCCESS (0.105s)
[216.051975] [047/156] (818s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-draw-pwrite)
[216.146765] Starting subtest: hdr-1p-primscrn-spr-indfb-draw-pwrite
[216.151155] Subtest hdr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[216.244502] [048/156] (818s left) gem_partial_pwrite_pread (write)
[216.299630] Subtest write: SKIP (0.000s)
[216.383796] [049/156] (818s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-indfb-plflip-blt)
[216.488417] Starting subtest: psrhdr-2p-scndscrn-indfb-plflip-blt
[216.492505] Subtest psrhdr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[216.580411] [050/156] (818s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[216.670196] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[216.674280] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.000s)
[216.739901] [051/156] (818s left) gem_partial_pwrite_pread (reads-uncached)
[216.811164] Subtest reads-uncached: SKIP (0.000s)
[216.869367] [052/156] (818s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-shrfb-plflip-blt)
[216.963614] Starting subtest: psrhdr-2p-primscrn-shrfb-plflip-blt
[216.968113] Subtest psrhdr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[217.061844] [053/156] (818s left) gem_render_copy (yf-tiled-to-vebox-y-tiled)
[217.121795] Starting subtest: yf-tiled-to-vebox-y-tiled
[217.125360] Subtest yf-tiled-to-vebox-y-tiled: SKIP (0.000s)
[217.303881] [054/156] (817s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[217.397397] Starting subtest: fbcpsrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[217.401557] Subtest fbcpsrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[217.497017] [055/156] (817s left) perf (invalid-open-flags)
[217.628118] Starting subtest: invalid-open-flags
[217.630517] Subtest invalid-open-flags: SUCCESS (0.000s)
[217.674561] [056/156] (817s left) gem_mmap_gtt (big-bo-tiledy)
[217.736409] Subtest big-bo-tiledY: SKIP (0.000s)
[217.792284] [057/156] (817s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-render)
[217.901245] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-render
[217.905840] Subtest hdr-1p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[217.998845] [058/156] (817s left) kms_invalid_mode (bad-vtotal)
[218.092131] Starting subtest: bad-vtotal
[218.094433] Starting dynamic subtest: pipe-A-eDP-1
[218.386062] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.296s)
[218.396623] Starting dynamic subtest: pipe-B-eDP-1
[218.398522] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[218.407766] Starting dynamic subtest: pipe-C-eDP-1
[218.409955] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[218.419236] Starting dynamic subtest: pipe-D-eDP-1
[218.421871] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[218.424605] Subtest bad-vtotal: SUCCESS (0.301s)
[219.237131] [059/156] (816s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[219.340502] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip
[219.344889] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[219.447167] [060/156] (816s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[219.543139] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[219.547396] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[219.648965] [061/156] (815s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[219.751983] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[225.045486] Subtest fbcpsr-rgb101010-draw-pwrite: SUCCESS (5.299s)
[225.161299] [062/156] (810s left) kms_getfb (getfb-reject-nv12)
[225.256104] Starting subtest: getfb-reject-nv12
[225.258358] Subtest getfb-reject-nv12: SUCCESS (0.001s)
[225.375335] [063/156] (810s left) kms_frontbuffer_tracking (hdr-shrfb-scaledprimary)
[225.473452] Starting subtest: hdr-shrfb-scaledprimary
[225.477727] Subtest hdr-shrfb-scaledprimary: SKIP (0.000s)
[225.579191] [064/156] (810s left) kms_lease (lease-get)
[225.664993] Starting subtest: lease-get
[225.667343] Starting dynamic subtest: pipe-A-eDP-1
[225.669626] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[225.679984] Starting dynamic subtest: pipe-B-eDP-1
[225.682191] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[225.691425] Starting dynamic subtest: pipe-C-eDP-1
[225.693643] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[225.702277] Starting dynamic subtest: pipe-D-eDP-1
[225.704464] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[225.706692] Subtest lease-get: SUCCESS (0.008s)
[225.787986] [065/156] (809s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-cur-indfb-draw-render)
[225.890977] Starting subtest: psrhdr-1p-primscrn-cur-indfb-draw-render
[225.895435] Subtest psrhdr-1p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[225.979089] [066/156] (809s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[226.072095] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[226.074267] Starting dynamic subtest: A-eDP1
[229.668302] Dynamic subtest A-eDP1: SUCCESS (3.591s)
[229.678440] Starting dynamic subtest: B-eDP1
[232.885269] Dynamic subtest B-eDP1: SUCCESS (3.217s)
[232.894892] Starting dynamic subtest: C-eDP1
[236.090100] Dynamic subtest C-eDP1: SUCCESS (3.203s)
[236.100090] Starting dynamic subtest: D-eDP1
[239.296754] Dynamic subtest D-eDP1: SUCCESS (3.205s)
[239.299383] Subtest single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (13.224s)
[240.102699] [067/156] (795s left) kms_frontbuffer_tracking (psr-indfb-scaledprimary)
[240.213023] Starting subtest: psr-indfb-scaledprimary
[245.946373] Subtest psr-indfb-scaledprimary: SUCCESS (5.738s)
[246.080931] [068/156] (789s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move)
[246.175279] Starting subtest: psr-2p-primscrn-cur-indfb-move
[246.179924] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[246.262788] [069/156] (789s left) gem_userptr_blits (probe)
[246.335983] Starting subtest: probe
[246.414771] Subtest probe: SUCCESS (0.079s)
[246.536349] [070/156] (789s left) prime_vgem (wait)
[246.611063] Starting subtest: wait
[246.613328] Starting dynamic subtest: rcs0
[246.653684] Dynamic subtest rcs0: SUCCESS (0.043s)
[246.662986] Starting dynamic subtest: bcs0
[246.705927] Dynamic subtest bcs0: SUCCESS (0.051s)
[246.715238] Starting dynamic subtest: vcs0
[246.752211] Dynamic subtest vcs0: SUCCESS (0.045s)
[246.761502] Starting dynamic subtest: vcs1
[246.797072] Dynamic subtest vcs1: SUCCESS (0.044s)
[246.806303] Starting dynamic subtest: vecs0
[246.840903] Dynamic subtest vecs0: SUCCESS (0.043s)
[246.850083] Starting dynamic subtest: ccs0
[246.888848] Dynamic subtest ccs0: SUCCESS (0.047s)
[246.890950] Subtest wait: SUCCESS (0.279s)
[247.027159] [071/156] (788s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[247.122409] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-wc
[247.125922] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[247.213145] [072/156] (788s left) kms_flip (blocking-absolute-wf_vblank)
[247.303725] Starting subtest: blocking-absolute-wf_vblank
[247.305540] Starting dynamic subtest: A-eDP1
[256.129866] Dynamic subtest A-eDP1: SUCCESS (8.790s)
[256.145269] Starting dynamic subtest: B-eDP1
[264.862219] Dynamic subtest B-eDP1: SUCCESS (8.728s)
[264.873938] Starting dynamic subtest: C-eDP1
[273.596590] Dynamic subtest C-eDP1: SUCCESS (8.722s)
[273.610524] Starting dynamic subtest: D-eDP1
[282.332986] Dynamic subtest D-eDP1: SUCCESS (8.728s)
[282.335596] Subtest blocking-absolute-wf_vblank: SUCCESS (34.985s)
[283.500807] [073/156] (752s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[283.586110] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs
[283.587972] Starting dynamic subtest: pipe-A-eDP-1
[283.608390] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.021s)
[283.619809] Starting dynamic subtest: pipe-B-eDP-1
[283.638909] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.021s)
[283.648170] Starting dynamic subtest: pipe-C-eDP-1
[283.667910] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.020s)
[283.678475] Starting dynamic subtest: pipe-D-eDP-1
[283.698390] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.020s)
[283.700603] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SUCCESS (0.086s)
[285.959691] [074/156] (750s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[286.077953] Starting subtest: short-busy-flip-before-cursor-toggle
[286.082645] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[286.220835] [075/156] (749s left) perf_pmu (all-busy-check-all)
[286.267831] Starting subtest: all-busy-check-all
[288.323367] Subtest all-busy-check-all: SUCCESS (2.055s)
[288.450986] [076/156] (747s left) kms_ccs (bad-pixel-format-yf-tiled-ccs)
[288.539864] Starting subtest: bad-pixel-format-yf-tiled-ccs
[288.542217] Starting dynamic subtest: pipe-A-eDP-1
[288.565357] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[288.576728] Starting dynamic subtest: pipe-B-eDP-1
[288.604088] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[288.614413] Starting dynamic subtest: pipe-C-eDP-1
[288.635574] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[288.645420] Starting dynamic subtest: pipe-D-eDP-1
[288.667680] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[288.671776] Subtest bad-pixel-format-yf-tiled-ccs: SKIP (0.004s)
[290.820799] [077/156] (745s left) gem_set_tiling_vs_blt (tiled-to-tiled)
[290.880062] Subtest tiled-to-tiled: SKIP (0.000s)
[290.955428] [078/156] (745s left) kms_flip (2x-dpms-vs-vblank-race)
[291.051614] Starting subtest: 2x-dpms-vs-vblank-race
[291.062410] Subtest 2x-dpms-vs-vblank-race: SKIP (0.010s)
[291.156119] [079/156] (745s left) kms_content_protection (mei-interface)
[291.303416] Starting subtest: mei-interface
[291.307805] Subtest mei-interface: SKIP (0.001s)
[291.432456] [080/156] (744s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-cpu)
[291.522425] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-cpu
[291.526723] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[291.631267] [081/156] (744s left) perf (invalid-create-userspace-config)
[291.719325] Starting subtest: invalid-create-userspace-config
[291.721733] Subtest invalid-create-userspace-config: SUCCESS (0.001s)
[291.760802] [082/156] (744s left) perf_pmu (busy-idle)
[291.799094] Starting subtest: busy-idle
[291.800981] Starting dynamic subtest: rcs0
[292.494079] Dynamic subtest rcs0: SUCCESS (0.695s)
[292.506264] Starting dynamic subtest: bcs0
[293.166614] Dynamic subtest bcs0: SUCCESS (0.671s)
[293.180277] Starting dynamic subtest: vcs0
[294.275295] Dynamic subtest vcs0: SUCCESS (1.104s)
[294.288553] Starting dynamic subtest: vcs1
[296.321864] Dynamic subtest vcs1: SUCCESS (2.044s)
[296.333045] Starting dynamic subtest: vecs0
[298.307101] Dynamic subtest vecs0: SUCCESS (1.983s)
[298.319293] Starting dynamic subtest: ccs0
[298.973510] Dynamic subtest ccs0: SUCCESS (0.665s)
[298.976252] Subtest busy-idle: SUCCESS (7.176s)
[299.100536] [083/156] (737s left) kms_big_fb (y-tiled-16bpp-rotate-180)
[299.198488] Starting subtest: y-tiled-16bpp-rotate-180
[299.202641] Subtest y-tiled-16bpp-rotate-180: SKIP (0.000s)
[299.307805] [084/156] (737s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-indfb-msflip-blt)
[299.407128] Starting subtest: fbchdr-2p-scndscrn-indfb-msflip-blt
[299.411307] Subtest fbchdr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[299.498205] [085/156] (737s left) gem_ctx_persistence (engines-hang)
[299.563659] Starting subtest: engines-hang
[299.566138] Starting dynamic subtest: rcs0
[299.646351] Dynamic subtest rcs0: SUCCESS (0.082s)
[299.657650] Starting dynamic subtest: bcs0
[299.753734] Dynamic subtest bcs0: SUCCESS (0.106s)
[299.765201] Starting dynamic subtest: vcs0
[299.843033] Dynamic subtest vcs0: SUCCESS (0.087s)
[299.854788] Starting dynamic subtest: vcs1
[299.935674] Dynamic subtest vcs1: SUCCESS (0.091s)
[299.948053] Starting dynamic subtest: vecs0
[300.041308] Dynamic subtest vecs0: SUCCESS (0.104s)
[300.055065] Starting dynamic subtest: ccs0
[300.115184] Dynamic subtest ccs0: SUCCESS (0.072s)
[300.117395] Subtest engines-hang: SUCCESS (0.553s)
[300.245067] [086/156] (736s left) perf_pmu (semaphore-busy)
[300.281191] Starting subtest: semaphore-busy
[300.282721] Starting dynamic subtest: rcs0
[301.916763] Dynamic subtest rcs0: SUCCESS (1.632s)
[301.928277] Starting dynamic subtest: bcs0
[303.554862] Dynamic subtest bcs0: SUCCESS (1.635s)
[303.565665] Starting dynamic subtest: vcs0
[307.829322] Dynamic subtest vcs0: SUCCESS (4.219s)
[307.841170] Starting dynamic subtest: vcs1
[313.789121] Dynamic subtest vcs1: SUCCESS (6.009s)
[313.800199] Starting dynamic subtest: vecs0
[319.802785] Dynamic subtest vecs0: SUCCESS (6.012s)
[319.812315] Starting dynamic subtest: ccs0
[322.880520] Dynamic subtest ccs0: SUCCESS (3.075s)
[322.883253] Subtest semaphore-busy: SUCCESS (22.598s)
[322.993378] [087/156] (713s left) kms_flip (flip-vs-panning-interruptible)
[323.104836] Starting subtest: flip-vs-panning-interruptible
[323.107149] Starting dynamic subtest: A-eDP1
[331.844969] Dynamic subtest A-eDP1: SUCCESS (8.739s)
[331.857041] Starting dynamic subtest: B-eDP1
[340.570882] Dynamic subtest B-eDP1: SUCCESS (8.723s)
[340.582463] Starting dynamic subtest: C-eDP1
[349.289917] Dynamic subtest C-eDP1: SUCCESS (8.718s)
[349.299883] Starting dynamic subtest: D-eDP1
[358.020707] Dynamic subtest D-eDP1: SUCCESS (8.729s)
[358.025265] Subtest flip-vs-panning-interruptible: SUCCESS (34.921s)
[359.202991] [088/156] (677s left) i915_pm_rpm (debugfs-forcewake-user)
[361.442410] Starting subtest: debugfs-forcewake-user
[372.829054] Subtest debugfs-forcewake-user: SUCCESS (11.389s)
[373.229548] [089/156] (663s left) gem_mmap (bad-offset)
[373.300638] Subtest bad-offset: SKIP (0.000s)
[373.364391] [090/156] (663s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[373.454033] Starting subtest: hdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[373.458658] Subtest hdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[373.569089] [091/156] (663s left) kms_plane (pixel-format-yf-tiled-modifier-source-clamping)
[373.664622] Starting subtest: pixel-format-yf-tiled-modifier-source-clamping
[373.668595] Subtest pixel-format-yf-tiled-modifier-source-clamping: SKIP (0.000s)
[373.736451] [092/156] (663s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled)
[373.796246] Starting subtest: yf-tiled-ccs-to-yf-tiled
[373.800646] Subtest yf-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[373.987789] [093/156] (662s left) gem_exec_balancer (individual)
[374.052327] Starting subtest: individual
[375.280054] Subtest individual: SUCCESS (1.227s)
[375.609042] [094/156] (661s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-move)
[375.707135] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-move
[375.711203] Subtest fbchdr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[375.798456] [095/156] (661s left) kms_frontbuffer_tracking (fbcpsrhdr-indfb-scaledprimary)
[375.906588] Starting subtest: fbcpsrhdr-indfb-scaledprimary
[375.911063] Subtest fbcpsrhdr-indfb-scaledprimary: SKIP (0.000s)
[376.025664] [096/156] (660s left) kms_psr2_sf (pr-cursor-plane-move-continuous-sf)
[376.120545] Starting subtest: pr-cursor-plane-move-continuous-sf
[376.124766] Subtest pr-cursor-plane-move-continuous-sf: SKIP (0.000s)
[376.227403] [097/156] (660s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[376.331673] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[376.335946] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[376.428367] [098/156] (660s left) kms_cursor_crc (cursor-suspend)
[376.554460] Starting subtest: cursor-suspend
[376.556510] Starting dynamic subtest: pipe-A-eDP-1
[409.568760] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.279s)
[409.580230] Starting dynamic subtest: pipe-D-eDP-1
[443.513670] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.622s)
[443.516101] Subtest cursor-suspend: SUCCESS (6.904s)
[444.428029] [099/156] (592s left) i915_drm_fdinfo (virtual-busy-hang-all)
[444.479164] Starting subtest: virtual-busy-hang-all
[444.483399] Subtest virtual-busy-hang-all: SKIP (0.000s)
[444.555698] [100/156] (592s left) gem_userptr_blits (readonly-pwrite-unsync)
[444.618078] Subtest readonly-pwrite-unsync: SKIP (0.000s)
[444.751079] [101/156] (592s left) gen9_exec_parse (bb-start-far)
[444.822036] Subtest bb-start-far: SKIP (0.000s)
[444.887156] [102/156] (592s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-sf)
[444.987358] Starting subtest: fbc-pr-overlay-plane-move-continuous-sf
[444.991887] Subtest fbc-pr-overlay-plane-move-continuous-sf: SKIP (0.000s)
[445.061330] [103/156] (592s left) kms_prime (basic-crc-vgem)
[445.143652] Starting subtest: basic-crc-vgem
[445.145792] Starting dynamic subtest: second-to-first
[446.107663] Dynamic subtest second-to-first: SUCCESS (0.961s)
[446.109681] Subtest basic-crc-vgem: SUCCESS (0.961s)
[446.208067] [104/156] (591s left) device_reset (unbind-reset-rebind)
[446.257595] Starting subtest: unbind-reset-rebind
[452.550395] Subtest unbind-reset-rebind: SUCCESS (6.261s)
[452.599466] [105/156] (584s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[452.684141] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[452.758270] [106/156] (584s left) syncobj_timeline (wait-all-complex)
[452.823338] Starting subtest: wait-all-complex
[453.037779] Subtest wait-all-complex: SUCCESS (0.214s)
[453.113217] [107/156] (584s left) api_intel_allocator (standalone)
[453.178880] Starting subtest: standalone
[453.200553] Subtest standalone: SUCCESS (0.021s)
[453.322662] [108/156] (584s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier)
[453.421221] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier
[453.425283] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s)
[453.508472] [109/156] (584s left) kms_setmode (invalid-clone-single-crtc)
[453.560841] Starting subtest: invalid-clone-single-crtc
[453.567198] Subtest invalid-clone-single-crtc: SKIP (0.000s)
[453.681292] [110/156] (583s left) kms_psr_stress_test (invalidate-primary-flip-overlay)
[453.774109] Starting subtest: invalidate-primary-flip-overlay
[464.499450] Subtest invalidate-primary-flip-overlay: SUCCESS (10.708s)
[464.624585] [111/156] (573s left) kms_big_fb (linear-64bpp-rotate-90)
[464.724366] Starting subtest: linear-64bpp-rotate-90
[464.744695] Subtest linear-64bpp-rotate-90: SKIP (0.020s)
[464.853004] [112/156] (572s left) kms_ccs (bad-aux-stride-4-tiled-mtl-rc-ccs-cc)
[464.950147] Starting subtest: bad-aux-stride-4-tiled-mtl-rc-ccs-cc
[464.952029] Starting dynamic subtest: pipe-A-eDP-1
[464.969663] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[464.982522] Starting dynamic subtest: pipe-B-eDP-1
[464.997894] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[465.007814] Starting dynamic subtest: pipe-C-eDP-1
[465.023256] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[465.033152] Starting dynamic subtest: pipe-D-eDP-1
[465.048835] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[465.050393] Subtest bad-aux-stride-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.007s)
[467.252290] [113/156] (570s left) gem_exec_params (invalid-bsd1-flag-on-vebox)
[467.318707] Starting subtest: invalid-bsd1-flag-on-vebox
[467.321054] Subtest invalid-bsd1-flag-on-vebox: SUCCESS (0.000s)
[467.418388] [114/156] (570s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[467.536507] Starting subtest: basic-flip-after-cursor-legacy
[468.449460] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.912s)
[469.285962] [115/156] (568s left) kms_addfb_basic (bad-pitch-999)
[469.350931] Starting subtest: bad-pitch-999
[469.353548] Subtest bad-pitch-999: SUCCESS (0.000s)
[469.489190] [116/156] (568s left) syncobj_timeline (invalid-signal-one-illegal-handle)
[469.548143] Starting subtest: invalid-signal-one-illegal-handle
[469.550392] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[469.678226] [117/156] (568s left) perf (invalid-oa-exponent)
[469.802342] Starting subtest: invalid-oa-exponent
[469.816911] Subtest invalid-oa-exponent: SUCCESS (0.014s)
[469.863523] [118/156] (568s left) fbdev (eof)
[469.918839] Starting subtest: eof
[469.921150] Subtest eof: SUCCESS (0.000s)
[469.992016] [119/156] (568s left) gem_softpin (allocator-basic-reserve)
[470.048671] Starting subtest: allocator-basic-reserve
[470.127451] Subtest allocator-basic-reserve: SUCCESS (0.078s)
[470.253545] [120/156] (567s left) sw_sync (sync_expired_merge)
[470.287739] Starting subtest: sync_expired_merge
[471.363607] Subtest sync_expired_merge: SUCCESS (1.074s)
[471.427663] [121/156] (566s left) kms_pm_lpsp (screens-disabled)
[472.523312] Starting subtest: screens-disabled
[472.527606] Subtest screens-disabled: SKIP (0.000s)
[472.593782] [122/156] (565s left) api_intel_bb (destroy-bb)
[472.650667] Starting subtest: destroy-bb
[472.658282] Subtest destroy-bb: SUCCESS (0.008s)
[472.789394] [123/156] (565s left) gem_exec_flush (basic-wb-rw-default)
[472.839772] Starting subtest: basic-wb-rw-default
[478.326277] Subtest basic-wb-rw-default: SUCCESS (5.456s)
[478.487082] [124/156] (559s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[478.593765] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[485.636619] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (7.046s)
[485.774206] [125/156] (552s left) gem_exec_schedule (preempt-other-chain)
[485.850691] Starting subtest: preempt-other-chain
[485.852883] Starting dynamic subtest: rcs0
[485.930486] Dynamic subtest rcs0: SUCCESS (0.084s)
[485.941870] Starting dynamic subtest: bcs0
[486.013250] Dynamic subtest bcs0: SUCCESS (0.081s)
[486.025288] Starting dynamic subtest: vcs0
[486.098573] Dynamic subtest vcs0: SUCCESS (0.084s)
[486.120280] Starting dynamic subtest: vcs1
[486.182266] Dynamic subtest vcs1: SUCCESS (0.084s)
[486.197078] Starting dynamic subtest: vecs0
[486.264216] Dynamic subtest vecs0: SUCCESS (0.081s)
[486.283568] Starting dynamic subtest: ccs0
[486.342650] Dynamic subtest ccs0: SUCCESS (0.077s)
[486.344973] Subtest preempt-other-chain: SUCCESS (0.497s)
[487.405503] [126/156] (550s left) kms_pm_backlight (basic-brightness)
[487.507154] Starting subtest: basic-brightness
[487.559783] Starting dynamic subtest: eDP-1
[488.059608] Dynamic subtest eDP-1: SUCCESS (0.499s)
[488.061949] Subtest basic-brightness: SUCCESS (0.554s)
[488.939596] [127/156] (549s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-cur-indfb-move)
[489.037156] Starting subtest: fbchdr-2p-primscrn-cur-indfb-move
[489.041555] Subtest fbchdr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[489.138495] [128/156] (549s left) kms_frontbuffer_tracking (fbc-2p-shrfb-fliptrack-mmap-gtt)
[489.236659] Starting subtest: fbc-2p-shrfb-fliptrack-mmap-gtt
[489.240883] Subtest fbc-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[489.337902] [129/156] (549s left) kms_color (ctm-max)
[489.429840] Starting subtest: ctm-max
[489.431922] Starting dynamic subtest: pipe-A-eDP-1
[490.142997] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.715s)
[490.157671] Starting dynamic subtest: pipe-B-eDP-1
[491.538349] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.394s)
[491.552393] Starting dynamic subtest: pipe-C-eDP-1
[492.920706] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.381s)
[492.932611] Starting dynamic subtest: pipe-D-eDP-1
[494.258748] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.336s)
[494.260953] Subtest ctm-max: SUCCESS (4.830s)
[495.078780] [130/156] (543s left) gem_fence_thrash (bo-write-verify-y)
[495.112645] Starting subtest: bo-write-verify-y
[495.131591] Subtest bo-write-verify-y: SKIP (0.019s)
[495.195820] [131/156] (543s left) kms_psr2_su (frontbuffer-xrgb8888)
[495.259171] Subtest frontbuffer-XRGB8888: SKIP (0.000s)
[495.322994] [132/156] (543s left) gem_ctx_shared (q-in-order)
[495.381135] Starting subtest: Q-in-order
[495.383542] Starting dynamic subtest: rcs0
[495.510435] Dynamic subtest rcs0: SUCCESS (0.127s)
[495.528600] Starting dynamic subtest: vcs0
[495.595621] Dynamic subtest vcs0: SUCCESS (0.085s)
[495.613072] Starting dynamic subtest: vcs1
[495.679476] Dynamic subtest vcs1: SUCCESS (0.083s)
[495.698808] Starting dynamic subtest: vecs0
[495.757598] Dynamic subtest vecs0: SUCCESS (0.075s)
[495.776692] Starting dynamic subtest: ccs0
[495.833425] Dynamic subtest ccs0: SUCCESS (0.075s)
[495.836139] Subtest Q-in-order: SUCCESS (0.452s)
[497.435805] [133/156] (541s left) kms_frontbuffer_tracking (fbcpsrhdr-tiling-4)
[497.536533] Starting subtest: fbcpsrhdr-tiling-4
[497.541122] Subtest fbcpsrhdr-tiling-4: SKIP (0.000s)
[497.620962] [134/156] (541s left) gem_lmem_swapping (parallel-random)
[497.665681] Subtest parallel-random: SKIP (0.000s)
[497.706205] [135/156] (541s left) kms_psr (psr2-no-drrs)
[497.797346] Starting subtest: psr2-no-drrs
[497.799579] Starting dynamic subtest: eDP-1
[499.436696] Dynamic subtest eDP-1: SUCCESS (1.639s)
[499.438612] Subtest psr2-no-drrs: SUCCESS (1.640s)
[500.335467] [136/156] (538s left) gem_tiled_partial_pwrite_pread (reads)
[500.393327] Subtest reads: SKIP (0.000s)
[500.464015] [137/156] (538s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-mc-ccs)
[500.552071] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-mc-ccs
[500.553937] Starting dynamic subtest: pipe-A-eDP-1
[502.674981] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.125s)
[502.686275] Starting dynamic subtest: pipe-B-eDP-1
[504.940209] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.263s)
[504.953329] Starting dynamic subtest: pipe-C-eDP-1
[507.025133] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.083s)
[507.036448] Starting dynamic subtest: pipe-D-eDP-1
[509.094852] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.067s)
[509.097146] Subtest crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SUCCESS (8.543s)
[510.242157] [138/156] (528s left) syncobj_timeline (invalid-query-bad-pad)
[510.301011] Starting subtest: invalid-query-bad-pad
[510.303370] Subtest invalid-query-bad-pad: SUCCESS (0.000s)
[510.402102] [139/156] (528s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-mmap-wc)
[510.500192] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-mmap-wc
[510.504060] Subtest hdr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[510.594882] [140/156] (528s left) gem_ctx_persistence (idempotent)
[510.653134] Starting subtest: idempotent
[510.655972] Subtest idempotent: SUCCESS (0.000s)
[510.779039] [141/156] (528s left) perf (create-destroy-userspace-config)
[510.875689] Starting subtest: create-destroy-userspace-config
[510.899559] Subtest create-destroy-userspace-config: SUCCESS (0.021s)
[510.943280] [142/156] (528s left) syncobj_wait (single-wait-all-for-submit-submitted)
[510.996878] Starting subtest: single-wait-all-for-submit-submitted
[511.103153] Subtest single-wait-all-for-submit-submitted: SUCCESS (0.106s)
[511.181942] [143/156] (527s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-onoff)
[511.283700] Starting subtest: fbc-2p-scndscrn-cur-indfb-onoff
[511.288170] Subtest fbc-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[511.386284] [144/156] (527s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-wc)
[511.480591] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-wc
[518.509894] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (7.032s)
[518.634275] [145/156] (520s left) perf (oa-formats)
[518.731002] Starting subtest: oa-formats
[518.733399] Starting dynamic subtest: 0-rcs0
[518.744145] Dynamic subtest 0-rcs0: SUCCESS (0.013s)
[518.755520] Starting dynamic subtest: 1-vcs0
[518.766155] Dynamic subtest 1-vcs0: SUCCESS (0.021s)
[518.768403] Subtest oa-formats: SUCCESS (0.036s)
[518.810136] [146/156] (520s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[518.914026] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[518.918208] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[519.014060] [147/156] (520s left) gem_exec_schedule (fairslice-all)
[519.086297] Starting subtest: fairslice-all
[521.142165] Subtest fairslice-all: SUCCESS (2.058s)
[522.371364] [148/156] (516s left) kms_big_fb (yf-tiled-32bpp-rotate-90)
[522.480700] Starting subtest: yf-tiled-32bpp-rotate-90
[522.485147] Subtest yf-tiled-32bpp-rotate-90: SKIP (0.000s)
[522.583246] [149/156] (516s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-wc)
[522.686360] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-wc
[522.690597] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[522.792715] [150/156] (516s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-wc)
[522.901572] Starting subtest: fbc-rgb101010-draw-mmap-wc
[525.348327] Subtest fbc-rgb101010-draw-mmap-wc: SUCCESS (2.450s)
[526.494383] [151/156] (512s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-fullscreen)
[526.592202] Starting subtest: fbc-2p-primscrn-spr-indfb-fullscreen
[526.596413] Subtest fbc-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[526.668072] [152/156] (512s left) vgem_basic (mmap)
[526.710282] Starting subtest: mmap
[526.718080] Subtest mmap: SUCCESS (0.009s)
[526.778404] [153/156] (512s left) gem_eio (wait-wedge-1us)
[526.999723] Starting subtest: wait-wedge-1us
[528.390877] Subtest wait-wedge-1us: SUCCESS (1.390s)
[528.517471] [154/156] (511s left) kms_pipe_crc_basic (suspend-read-crc)
[528.608332] Starting subtest: suspend-read-crc
[528.610702] Starting dynamic subtest: pipe-A-eDP-1
[545.972189] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.480s)
[545.986191] Starting dynamic subtest: pipe-B-eDP-1
[563.997759] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.088s)
[564.010658] Starting dynamic subtest: pipe-C-eDP-1
[581.986232] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.055s)
[581.998058] Starting dynamic subtest: pipe-D-eDP-1
[599.967087] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.050s)
[599.970406] Subtest suspend-read-crc: SUCCESS (11.680s)
[600.824075] [155/156] (438s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-shrfb-plflip-blt)
[600.934817] Starting subtest: fbcpsrhdr-2p-primscrn-shrfb-plflip-blt
[600.940097] Subtest fbcpsrhdr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[601.035633] [156/156] (438s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-upscaling)
[601.124637] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-upscaling
[601.129796] Subtest flip-64bpp-yftile-to-32bpp-yftile-upscaling: SKIP (0.000s)
[601.193991] Closing watchdogs
results: parsing output: 0/ for test: sysfs_heartbeat_interval
results: parsing output: 1/ for test: gem_mmap_gtt
results: parsing output: 2/ for test: gem_create
results: parsing output: 3/ for test: kms_plane_scaling
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: kms_async_flips
results: parsing output: 6/ for test: gem_create
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: kms_cursor_crc
results: parsing output: 9/ for test: gem_exec_capture
results: parsing output: 10/ for test: kms_setmode
results: parsing output: 11/ for test: kms_async_flips
results: parsing output: 12/ for test: gem_ctx_bad_destroy
results: parsing output: 13/ for test: kms_chamelium_frames
results: parsing output: 14/ for test: kms_cursor_crc
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: kms_vblank
results: parsing output: 17/ for test: syncobj_timeline
results: parsing output: 18/ for test: kms_dsc
results: parsing output: 19/ for test: kms_psr
results: parsing output: 20/ for test: gem_exec_whisper
results: parsing output: 21/ for test: kms_big_fb
results: parsing output: 22/ for test: kms_psr
results: parsing output: 23/ for test: gem_exec_schedule
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: gem_mmap_gtt
results: parsing output: 26/ for test: kms_ccs
results: parsing output: 27/ for test: kms_ccs
results: parsing output: 28/ for test: i915_query
results: parsing output: 29/ for test: kms_psr
results: parsing output: 30/ for test: gem_exec_reloc
results: parsing output: 31/ for test: gem_exec_fence
results: parsing output: 32/ for test: i915_drm_fdinfo
results: parsing output: 33/ for test: gem_exec_capture
results: parsing output: 34/ for test: gem_pread
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: kms_big_fb
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: kms_chamelium_hpd
results: parsing output: 40/ for test: kms_atomic_transition
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: kms_sequence
results: parsing output: 45/ for test: syncobj_timeline
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: gem_partial_pwrite_pread
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_flip_scaled_crc
results: parsing output: 50/ for test: gem_partial_pwrite_pread
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: gem_render_copy
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: perf
results: parsing output: 55/ for test: gem_mmap_gtt
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_invalid_mode
results: parsing output: 58/ for test: kms_big_fb
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_getfb
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_lease
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: kms_flip
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: gem_userptr_blits
results: parsing output: 69/ for test: prime_vgem
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_flip
results: parsing output: 72/ for test: kms_ccs
results: parsing output: 73/ for test: kms_cursor_legacy
results: parsing output: 74/ for test: perf_pmu
results: parsing output: 75/ for test: kms_ccs
results: parsing output: 76/ for test: gem_set_tiling_vs_blt
results: parsing output: 77/ for test: kms_flip
results: parsing output: 78/ for test: kms_content_protection
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: perf
results: parsing output: 81/ for test: perf_pmu
results: parsing output: 82/ for test: kms_big_fb
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: gem_ctx_persistence
results: parsing output: 85/ for test: perf_pmu
results: parsing output: 86/ for test: kms_flip
results: parsing output: 87/ for test: i915_pm_rpm
results: parsing output: 88/ for test: gem_mmap
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_plane
results: parsing output: 91/ for test: gem_render_copy
results: parsing output: 92/ for test: gem_exec_balancer
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: kms_psr2_sf
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_cursor_crc
results: parsing output: 98/ for test: i915_drm_fdinfo
results: parsing output: 99/ for test: gem_userptr_blits
results: parsing output: 100/ for test: gen9_exec_parse
results: parsing output: 101/ for test: kms_psr2_sf
results: parsing output: 102/ for test: kms_prime
results: parsing output: 103/ for test: device_reset
results: parsing output: 104/ for test: kms_pipe_b_c_ivb
results: parsing output: 105/ for test: syncobj_timeline
results: parsing output: 106/ for test: api_intel_allocator
results: parsing output: 107/ for test: kms_plane
results: parsing output: 108/ for test: kms_setmode
results: parsing output: 109/ for test: kms_psr_stress_test
results: parsing output: 110/ for test: kms_big_fb
results: parsing output: 111/ for test: kms_ccs
results: parsing output: 112/ for test: gem_exec_params
results: parsing output: 113/ for test: kms_cursor_legacy
results: parsing output: 114/ for test: kms_addfb_basic
results: parsing output: 115/ for test: syncobj_timeline
results: parsing output: 116/ for test: perf
results: parsing output: 117/ for test: fbdev
results: parsing output: 118/ for test: gem_softpin
results: parsing output: 119/ for test: sw_sync
results: parsing output: 120/ for test: kms_pm_lpsp
results: parsing output: 121/ for test: api_intel_bb
results: parsing output: 122/ for test: gem_exec_flush
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: gem_exec_schedule
results: parsing output: 125/ for test: kms_pm_backlight
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_color
results: parsing output: 129/ for test: gem_fence_thrash
results: parsing output: 130/ for test: kms_psr2_su
results: parsing output: 131/ for test: gem_ctx_shared
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: gem_lmem_swapping
results: parsing output: 134/ for test: kms_psr
results: parsing output: 135/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 136/ for test: kms_ccs
results: parsing output: 137/ for test: syncobj_timeline
results: parsing output: 138/ for test: kms_frontbuffer_tracking
results: parsing output: 139/ for test: gem_ctx_persistence
results: parsing output: 140/ for test: perf
results: parsing output: 141/ for test: syncobj_wait
results: parsing output: 142/ for test: kms_frontbuffer_tracking
results: parsing output: 143/ for test: kms_frontbuffer_tracking
results: parsing output: 144/ for test: perf
results: parsing output: 145/ for test: kms_frontbuffer_tracking
results: parsing output: 146/ for test: gem_exec_schedule
results: parsing output: 147/ for test: kms_big_fb
results: parsing output: 148/ for test: kms_frontbuffer_tracking
results: parsing output: 149/ for test: kms_frontbuffer_tracking
results: parsing output: 150/ for test: kms_frontbuffer_tracking
results: parsing output: 151/ for test: vgem_basic
results: parsing output: 152/ for test: gem_eio
results: parsing output: 153/ for test: kms_pipe_crc_basic
results: parsing output: 154/ for test: kms_frontbuffer_tracking
results: parsing output: 155/ for test: kms_flip_scaled_crc
Done.