[56.881025] Initializing watchdogs
[56.881073]   /dev/watchdog0
[56.895277] [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]
[56.899638] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.901704] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.905522] [001/133] (960s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[57.032654] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[57.871402] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.848s)
[58.041119] [002/133] (959s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0-hflip)
[58.139472] Starting subtest: linear-max-hw-stride-32bpp-rotate-0-hflip
[58.143661] Subtest linear-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[58.230818] [003/133] (959s left) kms_3d (basic)
[58.286907] Starting subtest: basic
[60.222905] Subtest basic: SUCCESS (1.951s)
[60.700820] [004/133] (956s left) gem_ctx_param (invalid-ctx-get)
[60.752609] Starting subtest: invalid-ctx-get
[60.755068] Subtest invalid-ctx-get: SUCCESS (0.000s)
[60.836785] [005/133] (956s left) syncobj_basic (create-signaled)
[60.882649] Starting subtest: create-signaled
[60.884636] Subtest create-signaled: SUCCESS (0.000s)
[60.972554] [006/133] (956s left) perf_pmu (most-busy-idle-check-all)
[61.011075] Starting subtest: most-busy-idle-check-all
[61.013328] Starting dynamic subtest: rcs0
[62.936016] Dynamic subtest rcs0: SUCCESS (1.932s)
[62.946042] Starting dynamic subtest: bcs0
[64.857091] Dynamic subtest bcs0: SUCCESS (1.925s)
[64.872786] Starting dynamic subtest: vcs0
[66.839097] Dynamic subtest vcs0: SUCCESS (1.987s)
[66.849787] Starting dynamic subtest: vcs1
[67.926076] Dynamic subtest vcs1: SUCCESS (1.088s)
[67.936763] Starting dynamic subtest: vecs0
[69.910876] Dynamic subtest vecs0: SUCCESS (1.987s)
[69.921487] Starting dynamic subtest: ccs0
[71.895856] Dynamic subtest ccs0: SUCCESS (1.987s)
[71.898816] Subtest most-busy-idle-check-all: SUCCESS (10.913s)
[71.988208] [007/133] (945s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[72.102079] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[72.106568] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[72.195165] [008/133] (945s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-factor-0-25)
[72.290460] Starting subtest: planes-downscale-factor-0-75-upscale-factor-0-25
[72.292824] Starting dynamic subtest: pipe-A
[72.352665] Dynamic subtest pipe-A: SUCCESS (0.065s)
[72.360740] Starting dynamic subtest: pipe-B
[73.520023] Dynamic subtest pipe-B: SUCCESS (1.168s)
[73.528340] Starting dynamic subtest: pipe-C
[74.623511] Dynamic subtest pipe-C: SUCCESS (1.104s)
[74.631100] Starting dynamic subtest: pipe-D
[75.805511] Dynamic subtest pipe-D: SUCCESS (1.182s)
[75.807876] Subtest planes-downscale-factor-0-75-upscale-factor-0-25: SUCCESS (3.521s)
[76.928149] [009/133] (940s left) syncobj_timeline (single-wait-all-signaled)
[76.985776] Starting subtest: single-wait-all-signaled
[76.988415] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[77.074235] [010/133] (940s left) gem_eio (in-flight-1us)
[77.270123] Starting subtest: in-flight-1us
[85.282961] Subtest in-flight-1us: SUCCESS (8.011s)
[85.430882] [FACT gem_eio (in-flight-1us)] new: kernel.kmod_is_loaded.vgem: true
[85.434069] [011/133] (932s left) sw_sync (sync_busy_fork_unixsocket)
[85.479623] Starting subtest: sync_busy_fork_unixsocket
[85.483882] Subtest sync_busy_fork_unixsocket: SUCCESS (0.006s)
[85.544692] [012/133] (932s left) kms_cursor_crc (cursor-offscreen-256x85)
[85.805050] Starting subtest: cursor-offscreen-256x85
[85.850748] Subtest cursor-offscreen-256x85: SKIP (0.040s)
[86.031718] [013/133] (931s left) tools_test (tools_test)
[86.084827] Starting subtest: tools_test
[86.138335] Subtest tools_test: SUCCESS (0.067s)
[86.197977] [014/133] (931s left) gen9_exec_parse (allowed-single)
[86.247363] Subtest allowed-single: SKIP (0.000s)
[86.334178] [015/133] (931s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[86.420701] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt
[86.423992] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[86.512898] [016/133] (931s left) i915_drm_fdinfo (virtual-busy-all)
[86.555834] Starting subtest: virtual-busy-all
[86.559697] Subtest virtual-busy-all: SKIP (0.000s)
[86.616723] [017/133] (931s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-gtt)
[86.717767] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-gtt
[86.721080] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[86.807163] [018/133] (931s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[86.907467] Starting subtest: pipe-fbc-rte
[86.913440] Starting dynamic subtest: pipe-B-eDP-1
[92.336350] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.434s)
[92.344055] Subtest pipe-fbc-rte: SUCCESS (5.436s)
[94.628670] [019/133] (923s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-mc-ccs)
[94.729097] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-mc-ccs
[94.731478] Starting dynamic subtest: pipe-A-eDP-1
[94.791183] Dynamic subtest pipe-A-eDP-1: SKIP (0.002s)
[94.801795] Starting dynamic subtest: pipe-B-eDP-1
[94.859974] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[94.869790] Starting dynamic subtest: pipe-C-eDP-1
[94.926821] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[94.937991] Starting dynamic subtest: pipe-D-eDP-1
[94.995030] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[94.999572] Subtest crc-sprite-planes-basic-4-tiled-dg2-mc-ccs: SKIP (0.008s)
[97.040209] [020/133] (921s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[97.153727] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu
[103.560638] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (6.411s)
[103.699389] [021/133] (914s left) kms_pipe_b_c_ivb (pipe-b-double-modeset-then-modeset-pipe-c)
[103.761646] Subtest pipe-B-double-modeset-then-modeset-pipe-C: SKIP (0.000s)
[103.822104] [022/133] (914s left) kms_flip (flip-vs-suspend-interruptible)
[103.916774] Starting subtest: flip-vs-suspend-interruptible
[103.919096] Starting dynamic subtest: A-eDP1
[137.258779] Dynamic subtest A-eDP1: SUCCESS (3.717s)
[137.269200] Starting dynamic subtest: B-eDP1
[186.259849] Dynamic subtest B-eDP1: SUCCESS (4.568s)
[186.270722] Starting dynamic subtest: C-eDP1
[235.258767] Dynamic subtest C-eDP1: SUCCESS (4.577s)
[235.272309] Starting dynamic subtest: D-eDP1
[284.256652] Dynamic subtest D-eDP1: SUCCESS (4.533s)
[284.259121] Subtest flip-vs-suspend-interruptible: SUCCESS (17.402s)
[285.435011] [023/133] (732s left) kms_setmode (invalid-clone-exclusive-crtc)
[285.498043] Starting subtest: invalid-clone-exclusive-crtc
[285.504750] Subtest invalid-clone-exclusive-crtc: SKIP (0.000s)
[285.588768] [024/133] (732s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-pgflip-blt)
[285.680037] Starting subtest: fbcpsr-2p-scndscrn-indfb-pgflip-blt
[285.684202] Subtest fbcpsr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[285.785814] [025/133] (732s left) kms_psr (fbc-psr2-primary-render)
[285.886314] Starting subtest: fbc-psr2-primary-render
[285.888181] Starting dynamic subtest: eDP-1
[285.892281] Dynamic subtest eDP-1: SKIP (0.000s)
[285.896532] Subtest fbc-psr2-primary-render: SKIP (0.001s)
[285.954612] [026/133] (732s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[286.053375] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[286.057603] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[286.161943] [027/133] (732s left) kms_pm_rpm (pm-tiling)
[288.384654] Starting subtest: pm-tiling
[288.389200] Subtest pm-tiling: SKIP (0.000s)
[288.753635] [028/133] (729s left) gem_lmem_swapping (basic)
[288.791851] Subtest basic: SKIP (0.000s)
[288.840783] [029/133] (729s left) sw_sync (sync_random_merge)
[288.875174] Starting subtest: sync_random_merge
[288.918930] Subtest sync_random_merge: SUCCESS (0.046s)
[288.979988] [030/133] (729s left) kms_psr (fbc-psr-primary-mmap-cpu)
[289.064810] Starting subtest: fbc-psr-primary-mmap-cpu
[289.067209] Starting dynamic subtest: eDP-1
[289.071602] Dynamic subtest eDP-1: SKIP (0.000s)
[289.075948] Subtest fbc-psr-primary-mmap-cpu: SKIP (0.001s)
[289.136772] [031/133] (729s left) kms_frontbuffer_tracking (psr-farfromfence-mmap-gtt)
[289.229505] Starting subtest: psr-farfromfence-mmap-gtt
[289.233791] Subtest psr-farfromfence-mmap-gtt: SKIP (0.000s)
[289.343395] [032/133] (729s left) gem_mmap_gtt (hang)
[289.398776] Subtest hang: SKIP (0.000s)
[289.471281] [033/133] (729s left) kms_big_fb (4-tiled-32bpp-rotate-0)
[289.570891] Starting subtest: 4-tiled-32bpp-rotate-0
[290.916662] Subtest 4-tiled-32bpp-rotate-0: SUCCESS (1.369s)
[291.065744] [034/133] (727s left) gem_lmem_swapping (smem-oom)
[291.106752] Subtest smem-oom: SKIP (0.000s)
[291.157622] [035/133] (727s left) kms_psr (psr-cursor-render)
[291.251146] Starting subtest: psr-cursor-render
[291.252954] Starting dynamic subtest: eDP-1
[292.941134] Dynamic subtest eDP-1: SUCCESS (1.710s)
[292.943020] Subtest psr-cursor-render: SUCCESS (1.711s)
[293.771687] [036/133] (725s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-cpu)
[293.872293] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-cpu
[298.072170] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (4.236s)
[299.242064] [037/133] (719s left) sw_sync (timeline_closed)
[299.278384] Starting subtest: timeline_closed
[299.280802] Subtest timeline_closed: SUCCESS (0.000s)
[299.338757] [038/133] (719s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-upscaling)
[299.429108] Starting subtest: flip-64bpp-linear-to-16bpp-linear-upscaling
[299.431358] Starting dynamic subtest: pipe-A-default-mode
[302.084298] Dynamic subtest pipe-A-default-mode: SUCCESS (2.666s)
[302.086623] Subtest flip-64bpp-linear-to-16bpp-linear-upscaling: SUCCESS (2.668s)
[302.911006] [039/133] (716s left) syncobj_timeline (signal)
[302.965924] Starting subtest: signal
[302.968298] Subtest signal: SUCCESS (0.000s)
[303.047895] [040/133] (715s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[303.150648] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[303.154583] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[303.259851] [041/133] (715s left) gem_pxp (reject-modify-context-protection-off-2)
[303.376668] Starting subtest: reject-modify-context-protection-off-2
[303.379405] Subtest reject-modify-context-protection-off-2: SUCCESS (0.003s)
[303.484212] [042/133] (715s left) vgem_basic (mmap)
[303.532164] Starting subtest: mmap
[303.541502] Subtest mmap: SUCCESS (0.011s)
[303.607311] [043/133] (715s left) gem_exec_schedule (fairslice-all)
[303.675038] Starting subtest: fairslice-all
[305.732234] Subtest fairslice-all: SUCCESS (2.068s)
[307.216287] [044/133] (711s left) api_intel_allocator (default-alignment)
[307.277345] Starting subtest: default-alignment
[307.283722] Subtest default-alignment: SUCCESS (0.008s)
[307.411758] [045/133] (711s left) gem_exec_capture (userptr)
[307.485429] Starting subtest: userptr
[308.324556] Subtest userptr: SUCCESS (0.840s)
[308.451406] [046/133] (710s left) kms_big_fb (yf-tiled-addfb-size-offset-overflow)
[308.569127] Starting subtest: yf-tiled-addfb-size-offset-overflow
[308.573669] Subtest yf-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[308.667850] [047/133] (710s left) prime_busy (before-wait)
[308.732514] Starting subtest: before-wait
[308.738024] Starting dynamic subtest: rcs0
[308.768373] Dynamic subtest rcs0: SUCCESS (0.032s)
[308.778306] Starting dynamic subtest: bcs0
[308.808163] Dynamic subtest bcs0: SUCCESS (0.039s)
[308.817202] Starting dynamic subtest: vcs0
[308.848070] Dynamic subtest vcs0: SUCCESS (0.039s)
[308.859706] Starting dynamic subtest: vcs1
[308.887024] Dynamic subtest vcs1: SUCCESS (0.038s)
[308.897124] Starting dynamic subtest: vecs0
[308.927071] Dynamic subtest vecs0: SUCCESS (0.040s)
[308.937912] Starting dynamic subtest: ccs0
[308.967694] Dynamic subtest ccs0: SUCCESS (0.040s)
[309.077301] Subtest before-wait: SUCCESS (0.343s)
[309.153242] [048/133] (710s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[309.259616] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[313.613187] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.363s)
[314.814198] [049/133] (704s left) kms_async_flips (invalid-async-flip-atomic)
[314.905597] Starting subtest: invalid-async-flip-atomic
[315.276363] Starting dynamic subtest: pipe-A-eDP-1
[316.073535] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.797s)
[316.432397] Starting dynamic subtest: pipe-B-eDP-1
[317.284548] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.859s)
[317.647561] Starting dynamic subtest: pipe-C-eDP-1
[318.463147] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.820s)
[318.818709] Starting dynamic subtest: pipe-D-eDP-1
[319.668215] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.860s)
[319.670617] Subtest invalid-async-flip-atomic: SUCCESS (4.763s)
[320.890246] [050/133] (698s left) kms_cursor_crc (cursor-rapid-movement-128x128)
[321.103568] Starting subtest: cursor-rapid-movement-128x128
[321.108316] Starting dynamic subtest: pipe-A-eDP-1
[321.639498] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.531s)
[321.665923] Starting dynamic subtest: pipe-D-eDP-1
[322.859562] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.198s)
[322.861768] Subtest cursor-rapid-movement-128x128: SUCCESS (1.753s)
[323.694081] [051/133] (695s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-pwrite)
[323.801629] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-pwrite
[331.367173] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (7.569s)
[331.503954] [052/133] (687s left) kms_plane (pixel-format-x-tiled-modifier)
[331.601148] Starting subtest: pixel-format-x-tiled-modifier
[331.627500] Starting dynamic subtest: pipe-A-plane-0
[332.249336] Dynamic subtest pipe-A-plane-0: SUCCESS (0.622s)
[332.261644] Starting dynamic subtest: pipe-A-plane-3
[332.682797] Dynamic subtest pipe-A-plane-3: SUCCESS (0.431s)
[332.694060] Starting dynamic subtest: pipe-A-plane-5
[332.697821] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[333.764921] Starting dynamic subtest: pipe-B-plane-0
[334.434799] Dynamic subtest pipe-B-plane-0: SUCCESS (0.678s)
[334.445123] Starting dynamic subtest: pipe-B-plane-3
[334.845698] Dynamic subtest pipe-B-plane-3: SUCCESS (0.410s)
[334.855604] Starting dynamic subtest: pipe-B-plane-5
[334.859134] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[335.167824] Subtest pixel-format-x-tiled-modifier: SUCCESS (3.565s)
[335.992184] [053/133] (683s left) kms_ccs (bad-aux-stride-4-tiled-mtl-rc-ccs)
[336.089005] Starting subtest: bad-aux-stride-4-tiled-mtl-rc-ccs
[336.091191] Starting dynamic subtest: pipe-A-eDP-1
[336.111123] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[336.124248] Starting dynamic subtest: pipe-B-eDP-1
[336.142941] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[336.151413] Starting dynamic subtest: pipe-C-eDP-1
[336.170103] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[336.178969] Starting dynamic subtest: pipe-D-eDP-1
[336.197890] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[336.199832] Subtest bad-aux-stride-4-tiled-mtl-rc-ccs: SUCCESS (0.007s)
[338.289372] [054/133] (681s left) kms_flip (basic-plain-flip)
[338.405945] Starting subtest: basic-plain-flip
[338.410835] Starting dynamic subtest: A-eDP1
[340.065255] Dynamic subtest A-eDP1: SUCCESS (1.657s)
[340.074795] Starting dynamic subtest: B-eDP1
[341.751495] Dynamic subtest B-eDP1: SUCCESS (1.685s)
[341.765013] Starting dynamic subtest: C-eDP1
[343.451800] Dynamic subtest C-eDP1: SUCCESS (1.697s)
[343.477843] Starting dynamic subtest: D-eDP1
[345.158031] Dynamic subtest D-eDP1: SUCCESS (1.703s)
[345.163877] Subtest basic-plain-flip: SUCCESS (6.761s)
[346.396465] [055/133] (673s left) kms_chamelium_hpd (dp-hpd-for-each-pipe)
[346.812932] Subtest dp-hpd-for-each-pipe: SKIP (0.000s)
[347.672230] [056/133] (671s left) gem_ctx_persistence (engines-queued)
[347.730259] Starting subtest: engines-queued
[347.732619] Starting dynamic subtest: rcs0
[347.789339] Dynamic subtest rcs0: SUCCESS (0.059s)
[347.801512] Starting dynamic subtest: bcs0
[347.816461] Dynamic subtest bcs0: SUCCESS (0.026s)
[347.827536] Starting dynamic subtest: vcs0
[349.207320] Dynamic subtest vcs0: SUCCESS (1.388s)
[349.219963] Starting dynamic subtest: vcs1
[351.189081] Dynamic subtest vcs1: SUCCESS (1.978s)
[351.201593] Starting dynamic subtest: vecs0
[353.172328] Dynamic subtest vecs0: SUCCESS (1.981s)
[353.187254] Starting dynamic subtest: ccs0
[353.355946] Dynamic subtest ccs0: SUCCESS (0.182s)
[353.358376] Subtest engines-queued: SUCCESS (5.627s)
[353.482831] [057/133] (666s left) gem_exec_whisper (basic-fds-priority-all)
[353.549512] Starting subtest: basic-fds-priority-all
[377.260197] Subtest basic-fds-priority-all: SUCCESS (23.713s)
[377.445053] [058/133] (642s left) gem_exec_balancer (noheartbeat)
[377.709681] Starting subtest: noheartbeat
[377.714821] Subtest noheartbeat: SKIP (0.000s)
[377.828638] [059/133] (641s left) i915_pm_rpm (system-suspend-execbuf)
[379.343851] Starting subtest: system-suspend-execbuf
[438.197722] Subtest system-suspend-execbuf: SUCCESS (43.296s)
[438.580145] [060/133] (581s left) kms_ccs (ccs-on-another-bo-y-tiled-ccs)
[438.678593] Starting subtest: ccs-on-another-bo-y-tiled-ccs
[438.681470] Starting dynamic subtest: pipe-A-eDP-1
[438.707320] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[438.717798] Starting dynamic subtest: pipe-B-eDP-1
[438.744616] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[438.755116] Starting dynamic subtest: pipe-C-eDP-1
[438.781151] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[438.790366] Starting dynamic subtest: pipe-D-eDP-1
[438.816350] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[438.821951] Subtest ccs-on-another-bo-y-tiled-ccs: SKIP (0.005s)
[440.890348] [061/133] (578s left) kms_vblank (wait-busy-hang)
[440.988887] Starting subtest: wait-busy-hang
[440.991157] Starting dynamic subtest: pipe-A-eDP-1
[467.801778] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.814s)
[467.819416] Starting dynamic subtest: pipe-D-eDP-1
[492.823025] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.028s)
[492.825758] Subtest wait-busy-hang: SUCCESS (51.846s)
[493.653971] [062/133] (526s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[493.766527] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[493.770681] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[493.868325] [063/133] (526s left) kms_pm_dc (dc3co-vpb-simulation)
[494.966557] Starting subtest: dc3co-vpb-simulation
[503.970644] Subtest dc3co-vpb-simulation: SKIP (8.997s)
[504.102115] [064/133] (515s left) gem_exec_params (sol-reset-not-gen7)
[504.163741] Starting subtest: sol-reset-not-gen7
[504.169610] Subtest sol-reset-not-gen7: SUCCESS (0.003s)
[504.276892] [065/133] (515s left) i915_hangman (engine-error-state-capture)
[504.346890] Starting subtest: engine-error-state-capture
[504.349475] Starting dynamic subtest: rcs0
[509.997359] Dynamic subtest rcs0: SUCCESS (5.648s)
[510.012723] Starting dynamic subtest: bcs0
[516.012946] Dynamic subtest bcs0: SUCCESS (6.012s)
[516.025803] Starting dynamic subtest: vcs0
[521.965567] Dynamic subtest vcs0: SUCCESS (5.949s)
[521.978232] Starting dynamic subtest: vcs1
[526.956991] Dynamic subtest vcs1: SUCCESS (4.988s)
[526.972710] Starting dynamic subtest: vecs0
[532.972881] Dynamic subtest vecs0: SUCCESS (6.013s)
[532.986115] Starting dynamic subtest: ccs0
[538.988949] Dynamic subtest ccs0: SUCCESS (6.014s)
[538.991731] Subtest engine-error-state-capture: SUCCESS (34.640s)
[539.121858] [066/133] (480s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[539.237633] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[539.242691] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[539.332969] [067/133] (480s left) gem_exec_reloc (basic-cpu-wc-active)
[539.394287] Subtest basic-cpu-wc-active: SKIP (0.000s)
[539.454567] [068/133] (480s left) syncobj_basic (bad-flags-handle-to-fd)
[539.517760] Starting subtest: bad-flags-handle-to-fd
[539.520765] Subtest bad-flags-handle-to-fd: SUCCESS (0.000s)
[539.607638] [069/133] (480s left) gem_mmap_offset (bad-extensions)
[539.662749] Starting subtest: bad-extensions
[539.665676] Subtest bad-extensions: SUCCESS (0.000s)
[539.778560] [070/133] (480s left) kms_properties (invalid-properties-atomic)
[539.876513] Starting subtest: invalid-properties-atomic
[540.176543] Subtest invalid-properties-atomic: SUCCESS (0.302s)
[540.254321] [071/133] (480s left) kms_flip (plain-flip-ts-check)
[540.347046] Starting subtest: plain-flip-ts-check
[540.349263] Starting dynamic subtest: A-eDP1
[549.237754] Dynamic subtest A-eDP1: SUCCESS (8.894s)
[549.251350] Starting dynamic subtest: B-eDP1
[558.133130] Dynamic subtest B-eDP1: SUCCESS (8.895s)
[558.144454] Starting dynamic subtest: C-eDP1
[567.015721] Dynamic subtest C-eDP1: SUCCESS (8.881s)
[567.026148] Starting dynamic subtest: D-eDP1
[575.913481] Dynamic subtest D-eDP1: SUCCESS (8.899s)
[575.918579] Subtest plain-flip-ts-check: SUCCESS (35.581s)
[577.073739] [072/133] (443s left) kms_rotation_crc (primary-rotation-180)
[577.167461] Starting subtest: primary-rotation-180
[584.122317] Subtest primary-rotation-180: SUCCESS (6.957s)
[585.351193] [073/133] (435s left) i915_pm_rps (thresholds-park)
[585.416069] Starting subtest: thresholds-park
[585.421749] Subtest thresholds-park: SKIP (0.001s)
[585.547602] [074/133] (434s left) gem_create (create-ext-cpu-access-big)
[585.707511] Starting subtest: create-ext-cpu-access-big
[585.713558] Subtest create-ext-cpu-access-big: SKIP (0.001s)
[585.792109] [075/133] (434s left) kms_psr (fbc-pr-cursor-render)
[585.883582] Starting subtest: fbc-pr-cursor-render
[585.888588] Subtest fbc-pr-cursor-render: SKIP (0.000s)
[585.957278] [076/133] (434s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-sf)
[586.053882] Starting subtest: fbc-psr2-cursor-plane-move-continuous-sf
[586.056597] Starting dynamic subtest: pipe-A-eDP-1
[586.127517] Dynamic subtest pipe-A-eDP-1: SKIP (0.077s)
[586.138242] Starting dynamic subtest: pipe-B-eDP-1
[587.295406] Dynamic subtest pipe-B-eDP-1: SKIP (1.165s)
[587.300585] Subtest fbc-psr2-cursor-plane-move-continuous-sf: SKIP (1.245s)
[588.555209] [077/133] (432s left) syncobj_timeline (32bits-limit)
[588.608534] Starting subtest: 32bits-limit
[588.611365] Subtest 32bits-limit: SUCCESS (0.001s)
[588.718944] [078/133] (431s left) gem_render_copy (yf-tiled-ccs-to-y-tiled-ccs)
[588.779446] Starting subtest: yf-tiled-ccs-to-y-tiled-ccs
[588.784752] Subtest yf-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[588.964127] [079/133] (431s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions)
[589.085623] Starting subtest: cursor-vs-flip-atomic-transitions
[619.015968] Subtest cursor-vs-flip-atomic-transitions: SUCCESS (29.937s)
[619.852090] [080/133] (400s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[619.936039] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[619.938100] Starting dynamic subtest: pipe-A
[620.306758] Dynamic subtest pipe-A: SUCCESS (0.373s)
[620.316572] Starting dynamic subtest: pipe-B
[621.734457] Dynamic subtest pipe-B: SUCCESS (1.426s)
[621.744232] Starting dynamic subtest: pipe-C
[623.117803] Dynamic subtest pipe-C: SUCCESS (1.382s)
[623.131527] Starting dynamic subtest: pipe-D
[624.511930] Dynamic subtest pipe-D: SUCCESS (1.391s)
[624.514774] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.578s)
[625.647967] [081/133] (395s left) kms_frontbuffer_tracking (psr-rgb565-draw-blt)
[625.747584] Starting subtest: psr-rgb565-draw-blt
[632.655940] Subtest psr-rgb565-draw-blt: SUCCESS (6.902s)
[632.833347] [082/133] (388s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy)
[632.986080] Starting subtest: fbcpsr-modesetfrombusy
[638.965348] Subtest fbcpsr-modesetfrombusy: SUCCESS (6.012s)
[639.084060] [083/133] (381s left) kms_big_fb (linear-32bpp-rotate-180)
[639.187766] Starting subtest: linear-32bpp-rotate-180
[640.757039] Subtest linear-32bpp-rotate-180: SUCCESS (1.567s)
[640.906114] [084/133] (380s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs)
[641.001927] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs
[641.003988] Starting dynamic subtest: pipe-A-eDP-1
[641.023727] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[641.035494] Starting dynamic subtest: pipe-B-eDP-1
[641.057372] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[641.066581] Starting dynamic subtest: pipe-C-eDP-1
[641.088432] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[641.097439] Starting dynamic subtest: pipe-D-eDP-1
[641.119487] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[641.123606] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SKIP (0.006s)
[643.246147] [085/133] (377s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[643.303746] Subtest writes-after-reads: SKIP (0.000s)
[643.381948] [086/133] (377s left) gem_ctx_shared (q-in-order)
[643.445207] Starting subtest: Q-in-order
[643.447260] Starting dynamic subtest: rcs0
[643.569950] Dynamic subtest rcs0: SUCCESS (0.125s)
[643.582614] Starting dynamic subtest: vcs0
[643.680121] Dynamic subtest vcs0: SUCCESS (0.110s)
[643.692045] Starting dynamic subtest: vcs1
[643.771347] Dynamic subtest vcs1: SUCCESS (0.091s)
[643.788594] Starting dynamic subtest: vecs0
[643.853247] Dynamic subtest vecs0: SUCCESS (0.081s)
[643.875099] Starting dynamic subtest: ccs0
[643.939329] Dynamic subtest ccs0: SUCCESS (0.085s)
[643.941855] Subtest Q-in-order: SUCCESS (0.498s)
[645.169414] [087/133] (375s left) gem_exec_capture (capture-invisible)
[645.225854] Starting subtest: capture-invisible
[645.228519] Starting dynamic subtest: smem0
[645.232942] Dynamic subtest smem0: SKIP (0.000s)
[645.237326] Subtest capture-invisible: SKIP (0.001s)
[645.321886] [088/133] (375s left) kms_joiner (basic-force-big-joiner)
[645.417229] Starting subtest: basic-force-big-joiner
[645.422344] Subtest basic-force-big-joiner: SKIP (0.000s)
[645.484436] [089/133] (375s left) gem_exec_params (batch-first)
[645.551511] Starting subtest: batch-first
[645.556586] Subtest batch-first: SUCCESS (0.007s)
[645.674287] [090/133] (375s left) kms_psr (psr-primary-page-flip)
[645.770558] Starting subtest: psr-primary-page-flip
[645.773439] Starting dynamic subtest: eDP-1
[647.518796] Dynamic subtest eDP-1: SUCCESS (1.749s)
[647.521588] Subtest psr-primary-page-flip: SUCCESS (1.750s)
[648.344625] [091/133] (372s left) syncobj_timeline (etime-multi-wait-submitted)
[648.396723] Starting subtest: etime-multi-wait-submitted
[648.497556] Subtest etime-multi-wait-submitted: SUCCESS (0.103s)
[648.590272] [092/133] (372s left) kms_chamelium_frames (hdmi-crc-fast)
[648.982998] Subtest hdmi-crc-fast: SKIP (0.000s)
[649.833762] [093/133] (371s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-plflip-blt)
[649.944475] Starting subtest: psr-2p-primscrn-shrfb-plflip-blt
[649.949673] Subtest psr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[650.024715] [094/133] (371s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-wc)
[650.136475] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-wc
[656.567119] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (6.434s)
[656.696719] [095/133] (364s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-onoff)
[656.787776] Starting subtest: psr-2p-primscrn-spr-indfb-onoff
[656.792177] Subtest psr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[656.881464] [096/133] (364s left) perf (buffer-fill)
[656.993545] Starting subtest: buffer-fill
[656.996306] Starting dynamic subtest: 0-rcs0
[659.237287] Dynamic subtest 0-rcs0: SUCCESS (2.243s)
[659.248092] Starting dynamic subtest: 1-vcs0
[663.683353] Dynamic subtest 1-vcs0: SUCCESS (4.444s)
[663.686098] Subtest buffer-fill: SUCCESS (6.690s)
[663.734871] [097/133] (357s left) kms_cursor_legacy (forked-bo)
[663.866532] Starting subtest: forked-bo
[663.869105] Starting dynamic subtest: pipe-A
[669.313822] Dynamic subtest pipe-A: SUCCESS (5.444s)
[669.332130] Starting dynamic subtest: pipe-B
[674.786274] Dynamic subtest pipe-B: SUCCESS (5.470s)
[674.800943] Starting dynamic subtest: pipe-C
[680.259294] Dynamic subtest pipe-C: SUCCESS (5.472s)
[680.272857] Starting dynamic subtest: pipe-D
[685.728342] Dynamic subtest pipe-D: SUCCESS (5.466s)
[685.741434] Starting dynamic subtest: all-pipes
[691.214253] Dynamic subtest all-pipes: SUCCESS (5.484s)
[691.217274] Subtest forked-bo: SUCCESS (27.347s)
[691.364911] [098/133] (330s left) perf (gen12-mi-rpc)
[691.476070] Starting subtest: gen12-mi-rpc
[691.478556] Starting dynamic subtest: rcs0
[691.496384] Dynamic subtest rcs0: SUCCESS (0.023s)
[691.498905] Subtest gen12-mi-rpc: SUCCESS (0.024s)
[691.540998] [099/133] (330s left) gem_spin_batch (legacy-resubmit)
[691.642673] Starting subtest: legacy-resubmit
[691.645804] Starting dynamic subtest: default
[691.652374] Dynamic subtest default: SUCCESS (0.009s)
[691.664509] Starting dynamic subtest: render
[691.668677] Dynamic subtest render: SUCCESS (0.003s)
[691.678976] Starting dynamic subtest: bsd
[691.681227] Dynamic subtest bsd: SUCCESS (0.004s)
[691.690423] Starting dynamic subtest: bsd1
[691.692915] Dynamic subtest bsd1: SUCCESS (0.003s)
[691.702334] Starting dynamic subtest: bsd2
[691.704598] Dynamic subtest bsd2: SUCCESS (0.001s)
[691.713889] Starting dynamic subtest: blt
[691.716189] Dynamic subtest blt: SUCCESS (0.003s)
[691.725475] Starting dynamic subtest: vebox
[691.727772] Dynamic subtest vebox: SUCCESS (0.004s)
[691.730183] Subtest legacy-resubmit: SUCCESS (0.035s)
[691.915913] [100/133] (329s left) kms_flip (2x-absolute-wf_vblank-interruptible)
[692.030869] Starting subtest: 2x-absolute-wf_vblank-interruptible
[692.042531] Subtest 2x-absolute-wf_vblank-interruptible: SKIP (0.011s)
[692.124836] [101/133] (329s left) kms_flip (2x-flip-vs-panning-vs-hang)
[692.215197] Starting subtest: 2x-flip-vs-panning-vs-hang
[692.225773] Subtest 2x-flip-vs-panning-vs-hang: SKIP (0.010s)
[692.306246] [102/133] (329s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-sf)
[692.402016] Starting subtest: pr-overlay-plane-move-continuous-exceed-sf
[692.406060] Subtest pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[692.487305] [103/133] (329s left) kms_cursor_edge_walk (64x64-left-edge)
[692.571629] Starting subtest: 64x64-left-edge
[692.573712] Starting dynamic subtest: pipe-A-eDP-1
[696.071645] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.498s)
[696.082099] Starting dynamic subtest: pipe-D-eDP-1
[699.264588] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.194s)
[699.267725] Subtest 64x64-left-edge: SUCCESS (6.695s)
[700.135547] [104/133] (321s left) kms_cdclk (mode-transition-all-outputs)
[700.240540] Starting subtest: mode-transition-all-outputs
[700.530718] Subtest mode-transition-all-outputs: SKIP (0.287s)
[701.351484] [105/133] (320s left) gem_vm_create (invalid-destroy)
[701.411654] Starting subtest: invalid-destroy
[701.414537] Subtest invalid-destroy: SUCCESS (0.002s)
[701.530301] [106/133] (320s left) kms_plane_alpha_blend (constant-alpha-max)
[701.632368] Starting subtest: constant-alpha-max
[701.920807] Starting dynamic subtest: pipe-A-eDP-1
[703.030837] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.109s)
[703.042926] Starting dynamic subtest: pipe-D-eDP-1
[704.651352] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.619s)
[704.653824] Subtest constant-alpha-max: SUCCESS (3.022s)
[705.537249] [107/133] (316s left) kms_plane_cursor (primary)
[705.628029] Starting subtest: primary
[705.633420] Starting dynamic subtest: pipe-A-eDP-1-size-64
[708.207376] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.579s)
[708.231276] Starting dynamic subtest: pipe-A-eDP-1-size-128
[710.796368] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.572s)
[710.824680] Starting dynamic subtest: pipe-A-eDP-1-size-256
[713.386621] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.572s)
[713.710744] Starting dynamic subtest: pipe-B-eDP-1-size-64
[717.030143] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.337s)
[717.057581] Starting dynamic subtest: pipe-B-eDP-1-size-128
[719.631763] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.583s)
[719.656373] Starting dynamic subtest: pipe-B-eDP-1-size-256
[722.187532] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.539s)
[722.516756] Starting dynamic subtest: pipe-C-eDP-1-size-64
[725.409582] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.905s)
[725.436386] Starting dynamic subtest: pipe-C-eDP-1-size-128
[727.587616] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.158s)
[727.613931] Starting dynamic subtest: pipe-C-eDP-1-size-256
[729.775788] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.169s)
[730.094005] Starting dynamic subtest: pipe-D-eDP-1-size-64
[733.081952] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.999s)
[733.120851] Starting dynamic subtest: pipe-D-eDP-1-size-128
[735.369822] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.269s)
[735.396473] Starting dynamic subtest: pipe-D-eDP-1-size-256
[737.591181] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.202s)
[737.903711] Subtest primary: SUCCESS (32.278s)
[738.722618] [108/133] (283s left) gem_readwrite (read-bad-handle)
[738.779358] Subtest read-bad-handle: SKIP (0.000s)
[738.835128] [109/133] (283s left) kms_content_protection (uevent-hdcp14)
[738.979018] Starting subtest: uevent-hdcp14
[738.984475] Subtest uevent-hdcp14: SKIP (0.000s)
[739.088128] [110/133] (282s left) kms_psr (pr-cursor-blt)
[739.175234] Starting subtest: pr-cursor-blt
[739.180123] Subtest pr-cursor-blt: SKIP (0.000s)
[739.254634] [111/133] (282s left) gem_exec_schedule (preempt)
[739.317286] Starting subtest: preempt
[739.319551] Starting dynamic subtest: rcs0
[739.336204] Dynamic subtest rcs0: SUCCESS (0.029s)
[739.346272] Starting dynamic subtest: bcs0
[739.356451] Dynamic subtest bcs0: SUCCESS (0.021s)
[739.366499] Starting dynamic subtest: vcs0
[739.384836] Dynamic subtest vcs0: SUCCESS (0.028s)
[739.394747] Starting dynamic subtest: vcs1
[739.406429] Dynamic subtest vcs1: SUCCESS (0.021s)
[739.419833] Starting dynamic subtest: vecs0
[739.427229] Dynamic subtest vecs0: SUCCESS (0.020s)
[739.437573] Starting dynamic subtest: ccs0
[739.451516] Dynamic subtest ccs0: SUCCESS (0.024s)
[739.454063] Subtest preempt: SUCCESS (0.146s)
[741.088739] [112/133] (281s left) gem_exec_schedule (preempt-self)
[741.162459] Starting subtest: preempt-self
[741.165155] Starting dynamic subtest: rcs0
[741.176704] Dynamic subtest rcs0: SUCCESS (0.025s)
[741.186403] Starting dynamic subtest: bcs0
[741.199254] Dynamic subtest bcs0: SUCCESS (0.022s)
[741.213262] Starting dynamic subtest: vcs0
[741.222080] Dynamic subtest vcs0: SUCCESS (0.021s)
[741.234149] Starting dynamic subtest: vcs1
[741.247921] Dynamic subtest vcs1: SUCCESS (0.024s)
[741.264053] Starting dynamic subtest: vecs0
[741.270210] Dynamic subtest vecs0: SUCCESS (0.020s)
[741.280830] Starting dynamic subtest: ccs0
[741.289981] Dynamic subtest ccs0: SUCCESS (0.019s)
[741.292735] Subtest preempt-self: SUCCESS (0.139s)
[743.158334] [113/133] (279s left) syncobj_wait (invalid-reset-one-illegal-handle)
[743.223629] Starting subtest: invalid-reset-one-illegal-handle
[743.226556] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.000s)
[743.306729] [114/133] (278s left) sysfs_defaults (readonly)
[743.371747] Starting subtest: readonly
[743.374623] Starting dynamic subtest: vecs0
[743.377526] Dynamic subtest vecs0: SUCCESS (0.000s)
[743.389346] Starting dynamic subtest: ccs0
[743.391504] Dynamic subtest ccs0: SUCCESS (0.000s)
[743.401190] Starting dynamic subtest: vcs0
[743.403758] Dynamic subtest vcs0: SUCCESS (0.000s)
[743.413407] Starting dynamic subtest: bcs0
[743.416048] Dynamic subtest bcs0: SUCCESS (0.000s)
[743.425622] Starting dynamic subtest: rcs0
[743.428442] Dynamic subtest rcs0: SUCCESS (0.000s)
[743.441561] Starting dynamic subtest: vcs1
[743.444264] Dynamic subtest vcs1: SUCCESS (0.000s)
[743.446734] Subtest readonly: SUCCESS (0.005s)
[743.533219] [115/133] (278s left) device_reset (unbind-cold-reset-rebind)
[743.593452] Starting subtest: unbind-cold-reset-rebind
[743.598585] Subtest unbind-cold-reset-rebind: SKIP (0.000s)
[743.680072] [116/133] (278s left) gem_ctx_persistence (legacy-engines-hostile-preempt)
[743.744669] Starting subtest: legacy-engines-hostile-preempt
[743.747279] Starting dynamic subtest: render
[743.838846] Dynamic subtest render: SUCCESS (0.096s)
[743.851739] Starting dynamic subtest: bsd1
[745.024432] Dynamic subtest bsd1: SUCCESS (1.181s)
[745.037622] Starting dynamic subtest: bsd2
[747.017404] Dynamic subtest bsd2: SUCCESS (1.990s)
[747.034879] Starting dynamic subtest: blt
[747.064989] Dynamic subtest blt: SUCCESS (0.045s)
[747.074730] Starting dynamic subtest: vebox
[748.997392] Dynamic subtest vebox: SUCCESS (1.929s)
[749.000387] Subtest legacy-engines-hostile-preempt: SUCCESS (5.256s)
[749.079242] [117/133] (273s left) gem_ccs (block-copy-compressed)
[749.152261] Starting subtest: block-copy-compressed
[749.157679] Subtest block-copy-compressed: SKIP (0.000s)
[749.255581] [118/133] (273s left) kms_lease (lease-get)
[749.349754] Starting subtest: lease-get
[749.352445] Starting dynamic subtest: pipe-A-eDP-1
[749.354878] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[749.364993] Starting dynamic subtest: pipe-B-eDP-1
[749.367543] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[749.377611] Starting dynamic subtest: pipe-C-eDP-1
[749.380474] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[749.390240] Starting dynamic subtest: pipe-D-eDP-1
[749.392722] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[749.395408] Subtest lease-get: SUCCESS (0.012s)
[749.472023] [119/133] (272s left) api_intel_bb (delta-check)
[749.529521] Starting subtest: delta-check
[749.533449] Subtest delta-check: SUCCESS (0.006s)
[749.657335] [120/133] (272s left) syncobj_timeline (invalid-transfer-non-existent-point)
[749.711655] Starting subtest: invalid-transfer-non-existent-point
[749.714550] Subtest invalid-transfer-non-existent-point: SUCCESS (0.000s)
[749.813927] [121/133] (272s left) gem_exec_fence (syncobj-backward-timeline-chain-engines)
[750.375191] Starting subtest: syncobj-backward-timeline-chain-engines
[750.495496] Subtest syncobj-backward-timeline-chain-engines: SUCCESS (0.120s)
[752.110806] [122/133] (270s left) gem_softpin (evict-active)
[752.171102] Starting subtest: evict-active
[752.184230] Subtest evict-active: SUCCESS (0.013s)
[752.307846] [123/133] (270s left) gem_mmap_gtt (basic-small-copy-odd)
[752.370330] Subtest basic-small-copy-odd: SKIP (0.000s)
[752.434849] [124/133] (270s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs)
[752.537746] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs
[752.540057] Starting dynamic subtest: pipe-A-eDP-1
[752.565065] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[752.576739] Starting dynamic subtest: pipe-B-eDP-1
[752.602522] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[752.613420] Starting dynamic subtest: pipe-C-eDP-1
[752.638631] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[752.648550] Starting dynamic subtest: pipe-D-eDP-1
[752.675004] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[752.679657] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs: SKIP (0.004s)
[754.823440] [125/133] (267s left) kms_draw_crc (draw-method-mmap-wc)
[754.923313] Starting subtest: draw-method-mmap-wc
[754.925547] Starting dynamic subtest: xrgb8888-untiled
[755.144622] Dynamic subtest xrgb8888-untiled: SUCCESS (0.222s)
[755.155357] Starting dynamic subtest: rgb565-untiled
[755.366767] Dynamic subtest rgb565-untiled: SUCCESS (0.222s)
[755.377472] Starting dynamic subtest: xrgb2101010-untiled
[755.566737] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.199s)
[755.577378] Starting dynamic subtest: xbgr16161616f-untiled
[755.777393] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.210s)
[755.791576] Starting dynamic subtest: xrgb8888-xtiled
[755.900020] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.121s)
[755.910077] Starting dynamic subtest: rgb565-xtiled
[756.033214] Dynamic subtest rgb565-xtiled: SUCCESS (0.132s)
[756.043190] Starting dynamic subtest: xrgb2101010-xtiled
[756.154772] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.121s)
[756.164181] Starting dynamic subtest: xbgr16161616f-xtiled
[756.288257] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s)
[756.305908] Starting dynamic subtest: xrgb8888-4tiled
[756.455098] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.166s)
[756.464551] Starting dynamic subtest: rgb565-4tiled
[756.600337] Dynamic subtest rgb565-4tiled: SUCCESS (0.142s)
[756.609751] Starting dynamic subtest: xrgb2101010-4tiled
[756.744313] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.143s)
[756.754680] Starting dynamic subtest: xbgr16161616f-4tiled
[756.922004] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.177s)
[756.924423] Subtest draw-method-mmap-wc: SUCCESS (2.001s)
[757.019066] [126/133] (265s left) kms_color_pipeline (plane-lut1d)
[757.115636] Starting subtest: plane-lut1d
[757.240515] Starting dynamic subtest: pipe-A-plane-0
[757.569316] Dynamic subtest pipe-A-plane-0: FAIL (0.163s)
[757.578936] Starting dynamic subtest: pipe-A-plane-1
[757.767394] Dynamic subtest pipe-A-plane-1: FAIL (0.178s)
[757.777556] Starting dynamic subtest: pipe-A-plane-2
[758.005133] Dynamic subtest pipe-A-plane-2: FAIL (0.154s)
[758.977058] Starting dynamic subtest: pipe-B-plane-0
[759.442555] Dynamic subtest pipe-B-plane-0: FAIL (0.152s)
[759.455200] Starting dynamic subtest: pipe-B-plane-1
[759.816739] Dynamic subtest pipe-B-plane-1: FAIL (0.155s)
[759.831733] Starting dynamic subtest: pipe-B-plane-2
[760.176428] Dynamic subtest pipe-B-plane-2: FAIL (0.729s)
[761.196193] Starting dynamic subtest: pipe-C-plane-0
[761.751732] Dynamic subtest pipe-C-plane-0: FAIL (0.140s)
[761.763201] Starting dynamic subtest: pipe-C-plane-1
[762.206593] Dynamic subtest pipe-C-plane-1: FAIL (0.143s)
[762.216875] Starting dynamic subtest: pipe-C-plane-2
[762.631257] Dynamic subtest pipe-C-plane-2: FAIL (0.146s)
[763.396989] Starting dynamic subtest: pipe-D-plane-0
[764.074871] Dynamic subtest pipe-D-plane-0: FAIL (0.162s)
[764.086377] Starting dynamic subtest: pipe-D-plane-1
[764.569993] Dynamic subtest pipe-D-plane-1: FAIL (0.699s)
[764.580985] Starting dynamic subtest: pipe-D-plane-2
[765.079501] Dynamic subtest pipe-D-plane-2: FAIL (0.620s)
[765.166134] Subtest plane-lut1d: FAIL (8.049s)
[766.031762] [127/133] (256s left) perf (invalid-create-userspace-config)
[766.127537] Starting subtest: invalid-create-userspace-config
[766.129830] Subtest invalid-create-userspace-config: SUCCESS (0.001s)
[766.173545] [128/133] (256s left) gem_exec_reloc (basic-gtt-cpu)
[766.239403] Subtest basic-gtt-cpu: SKIP (0.000s)
[766.318423] [129/133] (256s left) kms_cursor_legacy (cursora-vs-flipa-atomic)
[766.445789] Starting subtest: cursorA-vs-flipA-atomic
[777.573461] Subtest cursorA-vs-flipA-atomic: SUCCESS (11.127s)
[778.426681] [130/133] (244s left) gem_pread (exhaustion)
[778.478243] Subtest exhaustion: SKIP (0.000s)
[778.535286] [131/133] (244s left) kms_tiled_display (basic-test-pattern-with-chamelium)
[778.649553] Subtest basic-test-pattern-with-chamelium: SKIP (0.000s)
[778.701329] [132/133] (244s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[778.803951] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[778.808568] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[778.903626] [133/133] (244s left) kms_flip (dpms-vs-vblank-race)
[778.995560] Starting subtest: dpms-vs-vblank-race
[778.998395] Starting dynamic subtest: A-eDP1
[782.871301] Dynamic subtest A-eDP1: SUCCESS (3.875s)
[782.882407] Starting dynamic subtest: B-eDP1
[786.364875] Dynamic subtest B-eDP1: SUCCESS (3.497s)
[786.376746] Starting dynamic subtest: C-eDP1
[790.959527] Dynamic subtest C-eDP1: SUCCESS (4.593s)
[790.971646] Starting dynamic subtest: D-eDP1
[794.478585] Dynamic subtest D-eDP1: SUCCESS (3.517s)
[794.481443] Subtest dpms-vs-vblank-race: SUCCESS (15.491s)
[795.313917] Closing watchdogs
results: parsing output: 0/ for test: kms_big_fb
results: parsing output: 1/ for test: kms_big_fb
results: parsing output: 2/ for test: kms_3d
results: parsing output: 3/ for test: gem_ctx_param
results: parsing output: 4/ for test: syncobj_basic
results: parsing output: 5/ for test: perf_pmu
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: kms_plane_scaling
results: parsing output: 8/ for test: syncobj_timeline
results: parsing output: 9/ for test: gem_eio
results: parsing output: 10/ for test: sw_sync
results: parsing output: 11/ for test: kms_cursor_crc
results: parsing output: 12/ for test: tools_test
results: parsing output: 13/ for test: gen9_exec_parse
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: i915_drm_fdinfo
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: kms_ccs
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: kms_pipe_b_c_ivb
results: parsing output: 21/ for test: kms_flip
results: parsing output: 22/ for test: kms_setmode
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: kms_psr
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_pm_rpm
results: parsing output: 27/ for test: gem_lmem_swapping
results: parsing output: 28/ for test: sw_sync
results: parsing output: 29/ for test: kms_psr
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: gem_mmap_gtt
results: parsing output: 32/ for test: kms_big_fb
results: parsing output: 33/ for test: gem_lmem_swapping
results: parsing output: 34/ for test: kms_psr
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: sw_sync
results: parsing output: 37/ for test: kms_flip_scaled_crc
results: parsing output: 38/ for test: syncobj_timeline
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: gem_pxp
results: parsing output: 41/ for test: vgem_basic
results: parsing output: 42/ for test: gem_exec_schedule
results: parsing output: 43/ for test: api_intel_allocator
results: parsing output: 44/ for test: gem_exec_capture
results: parsing output: 45/ for test: kms_big_fb
results: parsing output: 46/ for test: prime_busy
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_async_flips
results: parsing output: 49/ for test: kms_cursor_crc
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: kms_plane
results: parsing output: 52/ for test: kms_ccs
results: parsing output: 53/ for test: kms_flip
results: parsing output: 54/ for test: kms_chamelium_hpd
results: parsing output: 55/ for test: gem_ctx_persistence
results: parsing output: 56/ for test: gem_exec_whisper
results: parsing output: 57/ for test: gem_exec_balancer
results: parsing output: 58/ for test: i915_pm_rpm
results: parsing output: 59/ for test: kms_ccs
results: parsing output: 60/ for test: kms_vblank
results: parsing output: 61/ for test: kms_big_fb
results: parsing output: 62/ for test: kms_pm_dc
results: parsing output: 63/ for test: gem_exec_params
results: parsing output: 64/ for test: i915_hangman
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: gem_exec_reloc
results: parsing output: 67/ for test: syncobj_basic
results: parsing output: 68/ for test: gem_mmap_offset
results: parsing output: 69/ for test: kms_properties
results: parsing output: 70/ for test: kms_flip
results: parsing output: 71/ for test: kms_rotation_crc
results: parsing output: 72/ for test: i915_pm_rps
results: parsing output: 73/ for test: gem_create
results: parsing output: 74/ for test: kms_psr
results: parsing output: 75/ for test: kms_psr2_sf
results: parsing output: 76/ for test: syncobj_timeline
results: parsing output: 77/ for test: gem_render_copy
results: parsing output: 78/ for test: kms_cursor_legacy
results: parsing output: 79/ for test: kms_plane_scaling
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_big_fb
results: parsing output: 83/ for test: kms_ccs
results: parsing output: 84/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 85/ for test: gem_ctx_shared
results: parsing output: 86/ for test: gem_exec_capture
results: parsing output: 87/ for test: kms_joiner
results: parsing output: 88/ for test: gem_exec_params
results: parsing output: 89/ for test: kms_psr
results: parsing output: 90/ for test: syncobj_timeline
results: parsing output: 91/ for test: kms_chamelium_frames
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: perf
results: parsing output: 96/ for test: kms_cursor_legacy
results: parsing output: 97/ for test: perf
results: parsing output: 98/ for test: gem_spin_batch
results: parsing output: 99/ for test: kms_flip
results: parsing output: 100/ for test: kms_flip
results: parsing output: 101/ for test: kms_psr2_sf
results: parsing output: 102/ for test: kms_cursor_edge_walk
results: parsing output: 103/ for test: kms_cdclk
results: parsing output: 104/ for test: gem_vm_create
results: parsing output: 105/ for test: kms_plane_alpha_blend
results: parsing output: 106/ for test: kms_plane_cursor
results: parsing output: 107/ for test: gem_readwrite
results: parsing output: 108/ for test: kms_content_protection
results: parsing output: 109/ for test: kms_psr
results: parsing output: 110/ for test: gem_exec_schedule
results: parsing output: 111/ for test: gem_exec_schedule
results: parsing output: 112/ for test: syncobj_wait
results: parsing output: 113/ for test: sysfs_defaults
results: parsing output: 114/ for test: device_reset
results: parsing output: 115/ for test: gem_ctx_persistence
results: parsing output: 116/ for test: gem_ccs
results: parsing output: 117/ for test: kms_lease
results: parsing output: 118/ for test: api_intel_bb
results: parsing output: 119/ for test: syncobj_timeline
results: parsing output: 120/ for test: gem_exec_fence
results: parsing output: 121/ for test: gem_softpin
results: parsing output: 122/ for test: gem_mmap_gtt
results: parsing output: 123/ for test: kms_ccs
results: parsing output: 124/ for test: kms_draw_crc
results: parsing output: 125/ for test: kms_color_pipeline
results: parsing output: 126/ for test: perf
results: parsing output: 127/ for test: gem_exec_reloc
results: parsing output: 128/ for test: kms_cursor_legacy
results: parsing output: 129/ for test: gem_pread
results: parsing output: 130/ for test: kms_tiled_display
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: kms_flip
Done.