[53.841095] Initializing watchdogs
[53.841168]   /dev/watchdog0
[53.855524] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[53.859914] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[53.862136] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[53.865552] [001/131] (960s left) kms_psr (psr-sprite-render)
[53.967420] Starting subtest: psr-sprite-render
[53.969841] Starting dynamic subtest: eDP-1
[55.768359] Dynamic subtest eDP-1: SUCCESS (1.775s)
[55.770803] Subtest psr-sprite-render: SUCCESS (1.775s)
[56.609591] [002/131] (957s left) gem_exec_reloc (basic-range-active)
[56.685861] Subtest basic-range-active: SKIP (0.000s)
[56.764400] [003/131] (957s left) kms_invalid_mode (zero-vdisplay)
[56.869407] Starting subtest: zero-vdisplay
[56.871659] Starting dynamic subtest: pipe-A-eDP-1
[57.159261] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.287s)
[57.168805] Starting dynamic subtest: pipe-B-eDP-1
[57.171067] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[57.179122] Starting dynamic subtest: pipe-C-eDP-1
[57.181336] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[57.188127] Starting dynamic subtest: pipe-D-eDP-1
[57.190081] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[57.191965] Subtest zero-vdisplay: SUCCESS (0.291s)
[57.941830] [004/131] (956s left) kms_getfb (getfb-handle-protection)
[58.038533] Starting subtest: getfb-handle-protection
[58.057322] Subtest getfb-handle-protection: SUCCESS (0.021s)
[58.170876] [005/131] (956s left) kms_sequence (get-forked-busy)
[58.265084] Starting subtest: get-forked-busy
[58.267403] Starting dynamic subtest: pipe-A-eDP-1
[60.208621] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.926s)
[60.216864] Starting dynamic subtest: pipe-B-eDP-1
[62.868899] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.645s)
[62.876795] Starting dynamic subtest: pipe-C-eDP-1
[65.485927] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.607s)
[65.493068] Starting dynamic subtest: pipe-D-eDP-1
[68.122419] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.630s)
[68.124717] Subtest get-forked-busy: SUCCESS (9.810s)
[68.902073] [006/131] (945s left) kms_flip (flip-vs-dpms-off-vs-modeset)
[68.993327] Starting subtest: flip-vs-dpms-off-vs-modeset
[68.995675] Starting dynamic subtest: A-eDP1
[72.626798] Dynamic subtest A-eDP1: SUCCESS (3.625s)
[72.633912] Starting dynamic subtest: B-eDP1
[75.799303] Dynamic subtest B-eDP1: SUCCESS (3.166s)
[75.806667] Starting dynamic subtest: C-eDP1
[78.873334] Dynamic subtest C-eDP1: SUCCESS (3.073s)
[78.882577] Starting dynamic subtest: D-eDP1
[81.965750] Dynamic subtest D-eDP1: SUCCESS (3.093s)
[81.968290] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (12.964s)
[82.749363] [007/131] (931s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs)
[82.841074] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs
[82.843351] Starting dynamic subtest: pipe-A-eDP-1
[82.865542] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[82.875921] Starting dynamic subtest: pipe-B-eDP-1
[82.898149] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[82.905493] Starting dynamic subtest: pipe-C-eDP-1
[82.927503] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[82.934867] Starting dynamic subtest: pipe-D-eDP-1
[82.957115] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[82.961216] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs: SKIP (0.002s)
[85.098800] [008/131] (929s left) gem_wait (wait)
[85.161154] Starting subtest: wait
[85.163627] Starting dynamic subtest: all-engines
[86.934934] Dynamic subtest all-engines: SUCCESS (1.771s)
[86.945439] Starting dynamic subtest: rcs0
[87.979963] Dynamic subtest rcs0: SUCCESS (1.046s)
[87.990120] Starting dynamic subtest: bcs0
[89.025638] Dynamic subtest bcs0: SUCCESS (1.045s)
[89.035296] Starting dynamic subtest: vcs0
[90.071293] Dynamic subtest vcs0: SUCCESS (1.045s)
[90.081637] Starting dynamic subtest: vcs1
[91.116330] Dynamic subtest vcs1: SUCCESS (1.044s)
[91.125220] Starting dynamic subtest: vecs0
[92.160359] Dynamic subtest vecs0: SUCCESS (1.044s)
[92.168684] Starting dynamic subtest: ccs0
[93.208034] Dynamic subtest ccs0: SUCCESS (1.047s)
[93.210555] Subtest wait: SUCCESS (8.045s)
[93.403528] [009/131] (921s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-sf)
[98.451112] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-sf
[98.453530] Starting dynamic subtest: pipe-A-eDP-1
[99.257102] Dynamic subtest pipe-A-eDP-1: SKIP (0.800s)
[99.265737] Starting dynamic subtest: pipe-B-eDP-1
[100.383083] Dynamic subtest pipe-B-eDP-1: SKIP (1.125s)
[100.387720] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-sf: SKIP (1.928s)
[101.555785] [010/131] (913s left) sw_sync (alloc_timeline)
[101.587319] Starting subtest: alloc_timeline
[101.589486] Subtest alloc_timeline: SUCCESS (0.000s)
[101.640812] [011/131] (913s left) kms_plane (plane-position-covered)
[101.738129] Starting subtest: plane-position-covered
[102.253302] Starting dynamic subtest: pipe-A-plane-1
[103.541080] Dynamic subtest pipe-A-plane-1: SUCCESS (1.284s)
[103.549912] Starting dynamic subtest: pipe-A-plane-2
[104.828273] Dynamic subtest pipe-A-plane-2: SUCCESS (1.287s)
[104.836996] Starting dynamic subtest: pipe-A-plane-3
[106.054569] Dynamic subtest pipe-A-plane-3: SUCCESS (1.226s)
[106.062538] Starting dynamic subtest: pipe-A-plane-4
[107.277847] Dynamic subtest pipe-A-plane-4: SUCCESS (1.225s)
[107.285160] Starting dynamic subtest: pipe-A-plane-5
[107.316116] Dynamic subtest pipe-A-plane-5: SUCCESS (0.037s)
[108.426641] Starting dynamic subtest: pipe-B-plane-1
[109.665498] Dynamic subtest pipe-B-plane-1: SUCCESS (1.245s)
[109.674210] Starting dynamic subtest: pipe-B-plane-2
[110.956316] Dynamic subtest pipe-B-plane-2: SUCCESS (1.290s)
[110.964729] Starting dynamic subtest: pipe-B-plane-3
[112.179288] Dynamic subtest pipe-B-plane-3: SUCCESS (1.225s)
[112.189740] Starting dynamic subtest: pipe-B-plane-4
[113.410012] Dynamic subtest pipe-B-plane-4: SUCCESS (1.228s)
[113.418379] Starting dynamic subtest: pipe-B-plane-5
[113.453932] Dynamic subtest pipe-B-plane-5: SUCCESS (0.042s)
[113.455949] Subtest plane-position-covered: SUCCESS (11.710s)
[114.206783] [012/131] (900s left) gem_exec_reloc (basic-range)
[114.270961] Subtest basic-range: SKIP (0.000s)
[114.333352] [013/131] (900s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic)
[114.456628] Starting subtest: flip-vs-cursor-busy-crc-atomic
[115.211170] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.752s)
[116.032129] [014/131] (898s left) syncobj_basic (bad-fd-to-handle)
[116.094039] Starting subtest: bad-fd-to-handle
[116.096391] Subtest bad-fd-to-handle: SUCCESS (0.000s)
[116.208470] [015/131] (898s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite)
[116.302344] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite
[116.306348] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[116.418856] [016/131] (898s left) kms_cursor_crc (cursor-rapid-movement-32x32)
[116.566728] Starting subtest: cursor-rapid-movement-32x32
[116.612678] Subtest cursor-rapid-movement-32x32: SKIP (0.041s)
[116.899481] [017/131] (898s left) gem_mmap_offset (mmap-boundaries)
[116.954090] Starting subtest: mmap-boundaries
[116.955972] Starting dynamic subtest: smem0
[118.050858] Dynamic subtest smem0: SUCCESS (1.098s)
[118.053550] Subtest mmap-boundaries: SUCCESS (1.098s)
[118.247670] [018/131] (896s left) sriov_basic (enable-vfs-bind-unbind-each-numvfs-all)
[118.291744] Starting subtest: enable-vfs-bind-unbind-each-numvfs-all
[118.368764] Subtest enable-vfs-bind-unbind-each-numvfs-all: FAIL (0.028s)
[118.401978] [019/131] (896s left) gem_spin_batch (resubmit-new-all)
[118.463974] Starting subtest: resubmit-new-all
[118.466348] Starting dynamic subtest: rcs0
[118.481050] Dynamic subtest rcs0: SUCCESS (0.017s)
[118.488335] Starting dynamic subtest: bcs0
[118.494996] Dynamic subtest bcs0: SUCCESS (0.014s)
[118.502213] Starting dynamic subtest: vcs0
[118.506713] Dynamic subtest vcs0: SUCCESS (0.009s)
[118.513362] Starting dynamic subtest: vcs1
[118.515242] Dynamic subtest vcs1: SUCCESS (0.010s)
[118.522158] Starting dynamic subtest: vecs0
[118.526831] Dynamic subtest vecs0: SUCCESS (0.009s)
[118.533523] Starting dynamic subtest: ccs0
[118.537809] Dynamic subtest ccs0: SUCCESS (0.012s)
[118.539669] Subtest resubmit-new-all: SUCCESS (0.073s)
[119.941315] [020/131] (895s left) kms_cursor_legacy (flip-vs-cursor-atomic)
[120.041959] Starting subtest: flip-vs-cursor-atomic
[127.172356] Subtest flip-vs-cursor-atomic: SUCCESS (7.131s)
[127.955700] [021/131] (887s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-blt)
[128.062308] Starting subtest: psr-2p-primscrn-spr-indfb-draw-blt
[128.066889] Subtest psr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[128.177264] [022/131] (886s left) gem_render_copy_redux (flink)
[128.245756] Starting subtest: flink
[129.214823] Subtest flink: SUCCESS (0.969s)
[129.362555] [023/131] (885s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-upscaling)
[129.466351] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-upscaling
[129.468490] Starting dynamic subtest: pipe-A-default-mode
[129.772606] Dynamic subtest pipe-A-default-mode: SKIP (0.301s)
[129.777181] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-upscaling: SKIP (0.302s)
[130.585321] [024/131] (884s left) gen9_exec_parse (bb-start-out)
[130.646475] Subtest bb-start-out: SKIP (0.000s)
[130.728861] [025/131] (884s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[130.834561] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt
[130.838638] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[130.929997] [026/131] (884s left) gem_exec_balancer (bonded-pair)
[130.990280] Starting subtest: bonded-pair
[130.994738] Subtest bonded-pair: SKIP (0.000s)
[131.298093] [027/131] (884s left) kms_flip (bo-too-big-interruptible)
[131.393244] Starting subtest: bo-too-big-interruptible
[131.395617] Starting dynamic subtest: A-eDP1
[133.186153] Dynamic subtest A-eDP1: SUCCESS (1.792s)
[133.196742] Starting dynamic subtest: B-eDP1
[134.902454] Dynamic subtest B-eDP1: SUCCESS (1.716s)
[134.911592] Starting dynamic subtest: C-eDP1
[136.640519] Dynamic subtest C-eDP1: SUCCESS (1.737s)
[136.649703] Starting dynamic subtest: D-eDP1
[138.357554] Dynamic subtest D-eDP1: SUCCESS (1.716s)
[138.360094] Subtest bo-too-big-interruptible: SUCCESS (6.967s)
[139.597921] [028/131] (875s left) gem_ctx_persistence (heartbeat-stop)
[139.658581] Starting subtest: heartbeat-stop
[139.663095] Subtest heartbeat-stop: SKIP (0.000s)
[139.769193] [029/131] (875s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[139.868862] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[139.873312] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[139.975165] [030/131] (875s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[140.063291] Subtest addfb25-x-tiled-legacy: SKIP (0.000s)
[140.168243] [031/131] (875s left) gem_lmem_swapping (basic)
[140.205241] Subtest basic: SKIP (0.000s)
[140.253747] [032/131] (875s left) gem_ccs (ctrl-surf-copy)
[140.313462] Starting subtest: ctrl-surf-copy
[140.318081] Subtest ctrl-surf-copy: SKIP (0.000s)
[140.414458] [033/131] (875s left) gem_exec_balancer (parallel-balancer)
[140.668357] Starting subtest: parallel-balancer
[141.538062] Subtest parallel-balancer: SUCCESS (0.870s)
[141.658526] [034/131] (873s left) kms_cursor_legacy (forked-move)
[141.753731] Starting subtest: forked-move
[141.755830] Starting dynamic subtest: pipe-A
[147.217429] Dynamic subtest pipe-A: SUCCESS (5.462s)
[147.228963] Starting dynamic subtest: pipe-B
[152.690189] Dynamic subtest pipe-B: SUCCESS (5.469s)
[152.701068] Starting dynamic subtest: pipe-C
[158.152578] Dynamic subtest pipe-C: SUCCESS (5.461s)
[158.162602] Starting dynamic subtest: pipe-D
[163.617685] Dynamic subtest pipe-D: SUCCESS (5.465s)
[163.627541] Starting dynamic subtest: all-pipes
[169.115140] Dynamic subtest all-pipes: SUCCESS (5.496s)
[169.117576] Subtest forked-move: SUCCESS (27.360s)
[169.180420] [035/131] (846s left) kms_addfb_basic (bad-pitch-256)
[169.225917] Starting subtest: bad-pitch-256
[169.227820] Subtest bad-pitch-256: SUCCESS (0.000s)
[169.379741] [036/131] (846s left) kms_cursor_legacy (cursora-vs-flipa-varying-size)
[169.482213] Starting subtest: cursorA-vs-flipA-varying-size
[180.633648] Subtest cursorA-vs-flipA-varying-size: SUCCESS (11.151s)
[181.420643] [037/131] (834s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-render)
[181.528063] Starting subtest: psr-1p-primscrn-cur-indfb-draw-render
[188.187202] Subtest psr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.662s)
[188.307941] [038/131] (827s left) api_intel_bb (object-noreloc-purge-cache-simple)
[188.370399] Starting subtest: object-noreloc-purge-cache-simple
[188.374962] Subtest object-noreloc-purge-cache-simple: SUCCESS (0.006s)
[188.457097] [039/131] (827s left) gem_exec_reloc (basic-concurrent16)
[188.505651] Subtest basic-concurrent16: SKIP (0.000s)
[188.583202] [040/131] (827s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-render)
[188.680930] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-render
[195.472765] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-render: SUCCESS (6.794s)
[195.627754] [041/131] (820s left) i915_getparams_basic (basic-subslice-total)
[195.689199] Starting subtest: basic-subslice-total
[195.693693] Subtest basic-subslice-total: SUCCESS (0.000s)
[195.780009] [042/131] (820s left) kms_big_fb (y-tiled-8bpp-rotate-0)
[195.884619] Starting subtest: y-tiled-8bpp-rotate-0
[195.888880] Subtest y-tiled-8bpp-rotate-0: SKIP (0.000s)
[196.006225] [043/131] (819s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[196.050261] Subtest heavy-verify-multi-ccs: SKIP (0.000s)
[196.089266] [044/131] (819s left) syncobj_wait (wait-delayed-signal)
[196.153086] Starting subtest: wait-delayed-signal
[196.257750] Subtest wait-delayed-signal: SUCCESS (0.103s)
[196.331856] [045/131] (819s left) kms_big_fb (linear-64bpp-rotate-90)
[196.435101] Starting subtest: linear-64bpp-rotate-90
[196.454161] Subtest linear-64bpp-rotate-90: SKIP (0.019s)
[196.567382] [046/131] (819s left) kms_plane_scaling (planes-downscale-factor-0-5-unity-scaling)
[196.657125] Starting subtest: planes-downscale-factor-0-5-unity-scaling
[196.659436] Starting dynamic subtest: pipe-A
[196.668871] Dynamic subtest pipe-A: SKIP (0.005s)
[196.678678] Starting dynamic subtest: pipe-B
[196.691932] Dynamic subtest pipe-B: SKIP (0.023s)
[196.700191] Starting dynamic subtest: pipe-C
[196.712330] Dynamic subtest pipe-C: SKIP (0.019s)
[196.719617] Starting dynamic subtest: pipe-D
[196.731627] Dynamic subtest pipe-D: SKIP (0.020s)
[196.736046] Subtest planes-downscale-factor-0-5-unity-scaling: SKIP (0.069s)
[196.845327] [047/131] (819s left) kms_psr (fbc-psr-no-drrs)
[196.947884] Starting subtest: fbc-psr-no-drrs
[196.950159] Starting dynamic subtest: eDP-1
[196.954653] Dynamic subtest eDP-1: SKIP (0.000s)
[196.959319] Subtest fbc-psr-no-drrs: SKIP (0.001s)
[197.017306] [048/131] (819s left) kms_plane_scaling (plane-upscale-factor-0-25-with-rotation)
[197.117828] Starting subtest: plane-upscale-factor-0-25-with-rotation
[197.120497] Starting dynamic subtest: pipe-A
[197.345255] Dynamic subtest pipe-A: SUCCESS (0.227s)
[197.352567] Starting dynamic subtest: pipe-B
[198.598986] Dynamic subtest pipe-B: SUCCESS (1.253s)
[198.607496] Starting dynamic subtest: pipe-C
[199.926798] Dynamic subtest pipe-C: SUCCESS (1.327s)
[199.934363] Starting dynamic subtest: pipe-D
[201.256250] Dynamic subtest pipe-D: SUCCESS (1.329s)
[201.258602] Subtest plane-upscale-factor-0-25-with-rotation: SUCCESS (4.138s)
[202.425213] [049/131] (813s left) kms_vblank (ts-continuation-modeset-hang)
[202.524330] Starting subtest: ts-continuation-modeset-hang
[202.526740] Starting dynamic subtest: pipe-A-eDP-1
[228.691703] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.162s)
[228.706587] Starting dynamic subtest: pipe-D-eDP-1
[253.650233] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.956s)
[253.652609] Subtest ts-continuation-modeset-hang: SUCCESS (51.122s)
[254.506833] [050/131] (761s left) syncobj_wait (wait-for-submit-snapshot)
[254.566120] Starting subtest: wait-for-submit-snapshot
[254.648585] Subtest wait-for-submit-snapshot: SUCCESS (0.082s)
[254.720888] [051/131] (761s left) kms_flip (flip-vs-rmfb)
[254.819138] Starting subtest: flip-vs-rmfb
[254.821382] Starting dynamic subtest: A-eDP1
[263.492751] Dynamic subtest A-eDP1: SUCCESS (8.673s)
[263.500985] Starting dynamic subtest: B-eDP1
[272.219922] Dynamic subtest B-eDP1: SUCCESS (8.726s)
[272.228218] Starting dynamic subtest: C-eDP1
[280.932275] Dynamic subtest C-eDP1: SUCCESS (8.711s)
[280.940072] Starting dynamic subtest: D-eDP1
[289.563930] Dynamic subtest D-eDP1: SUCCESS (8.630s)
[289.568386] Subtest flip-vs-rmfb: SUCCESS (34.749s)
[290.738824] [052/131] (725s left) i915_pm_freq_api (freq-suspend)
[290.792620] Starting subtest: freq-suspend
[290.795052] Starting dynamic subtest: gt0
[307.047116] Dynamic subtest gt0: SUCCESS (1.309s)
[307.067444] Starting dynamic subtest: gt1
[322.989641] Dynamic subtest gt1: SUCCESS (1.147s)
[323.006163] Subtest freq-suspend: SUCCESS (2.458s)
[324.070323] [053/131] (693s left) syncobj_timeline (reset-during-wait-for-submit)
[324.131004] Starting subtest: reset-during-wait-for-submit
[324.234563] Subtest reset-during-wait-for-submit: SUCCESS (0.102s)
[324.311882] [054/131] (693s left) kms_display_modes (extended-mode-basic)
[324.404090] Starting subtest: extended-mode-basic
[324.408832] Subtest extended-mode-basic: SKIP (0.000s)
[324.450815] [055/131] (692s left) kms_psr (psr2-cursor-mmap-gtt)
[324.552123] Starting subtest: psr2-cursor-mmap-gtt
[324.554530] Starting dynamic subtest: eDP-1
[326.313265] Dynamic subtest eDP-1: SUCCESS (1.732s)
[326.315623] Subtest psr2-cursor-mmap-gtt: SUCCESS (1.733s)
[327.107167] [056/131] (690s left) gem_workarounds (reset)
[327.165587] Starting subtest: reset
[327.325836] Subtest reset: SUCCESS (0.157s)
[327.442878] [057/131] (690s left) gem_readwrite (read-write)
[327.490984] Subtest read-write: SKIP (0.000s)
[327.551014] [058/131] (689s left) kms_async_flips (alternate-sync-async-flip)
[327.651854] Starting subtest: alternate-sync-async-flip
[328.029369] Starting dynamic subtest: pipe-A-eDP-1
[330.812234] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.757s)
[331.185064] Starting dynamic subtest: pipe-B-eDP-1
[333.955340] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.761s)
[334.335418] Starting dynamic subtest: pipe-C-eDP-1
[337.090372] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.750s)
[337.456507] Starting dynamic subtest: pipe-D-eDP-1
[340.224131] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.765s)
[340.226226] Subtest alternate-sync-async-flip: SUCCESS (12.494s)
[341.413098] [059/131] (676s left) gem_flink_basic (double-flink)
[341.473412] Starting subtest: double-flink
[341.475727] Subtest double-flink: SUCCESS (0.000s)
[341.582121] [060/131] (676s left) syncobj_timeline (invalid-transfer-non-existent-point)
[341.628178] Starting subtest: invalid-transfer-non-existent-point
[341.630026] Subtest invalid-transfer-non-existent-point: SUCCESS (0.000s)
[341.727907] [061/131] (675s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[341.842504] Starting subtest: basic-busy-flip-before-cursor-legacy
[341.846958] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[341.976102] [062/131] (675s left) syncobj_wait (reset-unsignaled)
[342.032164] Starting subtest: reset-unsignaled
[342.034590] Subtest reset-unsignaled: SUCCESS (0.000s)
[342.134182] [063/131] (675s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-blt)
[342.235948] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-blt
[348.927212] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (6.683s)
[349.093758] [064/131] (668s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-gtt)
[349.206155] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-gtt
[349.210444] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[349.321061] [065/131] (668s left) perf (low-oa-exponent-permissions)
[349.431834] Starting subtest: low-oa-exponent-permissions
[349.469593] Subtest low-oa-exponent-permissions: SUCCESS (0.040s)
[349.517204] [066/131] (668s left) gem_softpin (evict-prime)
[349.568146] Starting subtest: evict-prime
[349.570568] Starting dynamic subtest: rcs0
[350.752219] Dynamic subtest rcs0: SUCCESS (1.182s)
[350.764868] Starting dynamic subtest: bcs0
[351.902627] Dynamic subtest bcs0: SUCCESS (1.148s)
[351.914501] Starting dynamic subtest: vcs0
[353.697056] Dynamic subtest vcs0: SUCCESS (1.789s)
[353.709209] Starting dynamic subtest: vcs1
[355.673297] Dynamic subtest vcs1: SUCCESS (1.974s)
[355.686548] Starting dynamic subtest: vecs0
[357.661589] Dynamic subtest vecs0: SUCCESS (1.986s)
[357.674424] Starting dynamic subtest: ccs0
[358.819179] Dynamic subtest ccs0: SUCCESS (1.157s)
[358.831454] Starting dynamic subtest: all
[360.654220] Dynamic subtest all: SUCCESS (1.832s)
[360.656677] Subtest evict-prime: SUCCESS (11.082s)
[360.740642] [067/131] (657s left) kms_atomic_transition (modeset-transition)
[360.851090] Starting subtest: modeset-transition
[360.853300] Starting dynamic subtest: 1x-outputs
[371.305094] Dynamic subtest 1x-outputs: SUCCESS (10.451s)
[371.569248] Subtest modeset-transition: SUCCESS (10.716s)
[372.424722] [068/131] (645s left) gem_exec_schedule (preempt-user)
[372.502771] Starting subtest: preempt-user
[372.504592] Starting dynamic subtest: rcs0
[372.522856] Dynamic subtest rcs0: SUCCESS (0.027s)
[372.533708] Starting dynamic subtest: bcs0
[372.544537] Dynamic subtest bcs0: SUCCESS (0.020s)
[372.553694] Starting dynamic subtest: vcs0
[372.566742] Dynamic subtest vcs0: SUCCESS (0.022s)
[372.576075] Starting dynamic subtest: vcs1
[372.591833] Dynamic subtest vcs1: SUCCESS (0.024s)
[372.600654] Starting dynamic subtest: vecs0
[372.612476] Dynamic subtest vecs0: SUCCESS (0.020s)
[372.621496] Starting dynamic subtest: ccs0
[372.634070] Dynamic subtest ccs0: SUCCESS (0.021s)
[372.636417] Subtest preempt-user: SUCCESS (0.139s)
[373.720941] [069/131] (644s left) sysfs_preempt_timeout (timeout)
[373.778519] Starting subtest: timeout
[373.780723] Starting dynamic subtest: vecs0
[383.691789] Dynamic subtest vecs0: SUCCESS (9.906s)
[383.703720] Starting dynamic subtest: ccs0
[385.101883] Dynamic subtest ccs0: SUCCESS (1.409s)
[385.111391] Starting dynamic subtest: vcs0
[394.615388] Dynamic subtest vcs0: SUCCESS (9.507s)
[394.625514] Starting dynamic subtest: bcs0
[396.027523] Dynamic subtest bcs0: SUCCESS (1.409s)
[396.042533] Starting dynamic subtest: rcs0
[397.386525] Dynamic subtest rcs0: SUCCESS (1.360s)
[397.396387] Starting dynamic subtest: vcs1
[406.686718] Dynamic subtest vcs1: SUCCESS (9.292s)
[406.690256] Subtest timeout: SUCCESS (32.895s)
[406.764871] [070/131] (611s left) kms_psr (psr2-sprite-mmap-gtt)
[406.874100] Starting subtest: psr2-sprite-mmap-gtt
[406.876528] Starting dynamic subtest: eDP-1
[408.324193] Dynamic subtest eDP-1: SKIP (1.447s)
[408.328734] Subtest psr2-sprite-mmap-gtt: SKIP (1.449s)
[408.474275] [071/131] (609s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs)
[408.582105] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs
[408.584338] Starting dynamic subtest: pipe-A-eDP-1
[408.640998] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[408.651224] Starting dynamic subtest: pipe-B-eDP-1
[408.709536] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[408.718396] Starting dynamic subtest: pipe-C-eDP-1
[408.776184] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[408.785897] Starting dynamic subtest: pipe-D-eDP-1
[408.843386] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[408.848247] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs: SKIP (0.009s)
[410.723688] [072/131] (607s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled-ccs)
[410.781179] Starting subtest: yf-tiled-ccs-to-yf-tiled-ccs
[410.785992] Subtest yf-tiled-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[410.963231] [073/131] (607s left) gem_mmap_wc (write-gtt-read-wc)
[411.023033] Subtest write-gtt-read-wc: SKIP (0.000s)
[411.078656] [074/131] (607s left) gem_exec_fence (basic-busy-all)
[411.144249] Starting subtest: basic-busy-all
[411.240249] Subtest basic-busy-all: SUCCESS (0.097s)
[412.763088] [FACT gem_exec_fence (basic-busy-all)] new: kernel.kmod_is_loaded.vgem: true
[412.766446] [075/131] (605s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-wc)
[412.862199] Starting subtest: fbc-rgb101010-draw-mmap-wc
[417.427494] Subtest fbc-rgb101010-draw-mmap-wc: SUCCESS (4.567s)
[418.611305] [076/131] (599s left) kms_color (ctm-green-to-red)
[418.696991] Starting subtest: ctm-green-to-red
[418.699263] Starting dynamic subtest: pipe-A-eDP-1
[419.393010] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.695s)
[419.402895] Starting dynamic subtest: pipe-B-eDP-1
[420.796621] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.404s)
[420.807232] Starting dynamic subtest: pipe-C-eDP-1
[422.142316] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.345s)
[422.153560] Starting dynamic subtest: pipe-D-eDP-1
[423.465707] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.322s)
[423.468147] Subtest ctm-green-to-red: SUCCESS (4.769s)
[424.299537] [077/131] (593s left) gem_exec_nop (basic-sequential)
[424.382495] Starting subtest: basic-sequential
[439.153931] Subtest basic-sequential: SUCCESS (14.782s)
[439.382943] [078/131] (578s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-render)
[439.498320] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-render
[439.502638] Subtest fbc-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[439.623188] [079/131] (578s left) fbdev (unaligned-read)
[439.673120] Starting subtest: unaligned-read
[439.687944] Subtest unaligned-read: SUCCESS (0.015s)
[439.747844] [080/131] (578s left) gem_ctx_persistence (legacy-engines-persistence)
[439.802519] Starting subtest: legacy-engines-persistence
[439.804839] Starting dynamic subtest: render
[440.595718] Dynamic subtest render: SUCCESS (0.792s)
[440.607796] Starting dynamic subtest: bsd1
[441.418651] Dynamic subtest bsd1: SUCCESS (0.820s)
[441.430784] Starting dynamic subtest: bsd2
[442.182777] Dynamic subtest bsd2: SUCCESS (0.762s)
[442.194798] Starting dynamic subtest: blt
[442.967123] Dynamic subtest blt: SUCCESS (0.782s)
[442.979445] Starting dynamic subtest: vebox
[443.789659] Dynamic subtest vebox: SUCCESS (0.820s)
[443.794820] Subtest legacy-engines-persistence: SUCCESS (3.988s)
[443.870835] [081/131] (574s left) kms_cursor_crc (cursor-offscreen-32x32)
[444.028372] Starting subtest: cursor-offscreen-32x32
[444.075137] Subtest cursor-offscreen-32x32: SKIP (0.040s)
[444.374834] [082/131] (574s left) gem_exec_endless (dispatch)
[444.441731] Starting subtest: dispatch
[444.444214] Starting dynamic subtest: rcs0
[444.448570] Dynamic subtest rcs0: SUCCESS (0.005s)
[444.459985] Starting dynamic subtest: bcs0
[444.464446] Dynamic subtest bcs0: SUCCESS (0.004s)
[444.474363] Starting dynamic subtest: vcs0
[444.478736] Dynamic subtest vcs0: SUCCESS (0.004s)
[444.487477] Starting dynamic subtest: vcs1
[444.491499] Dynamic subtest vcs1: SUCCESS (0.005s)
[444.500273] Starting dynamic subtest: vecs0
[444.504359] Dynamic subtest vecs0: SUCCESS (0.005s)
[444.513481] Starting dynamic subtest: ccs0
[444.517893] Dynamic subtest ccs0: SUCCESS (0.006s)
[444.519774] Subtest dispatch: SUCCESS (0.033s)
[445.669262] [083/131] (572s left) kms_big_fb (x-tiled-8bpp-rotate-0)
[445.775177] Starting subtest: x-tiled-8bpp-rotate-0
[446.993973] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.218s)
[447.169597] [084/131] (571s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-wc)
[447.273129] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-wc
[453.749327] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.479s)
[453.861954] [085/131] (564s left) api_intel_bb (object-reloc-purge-cache)
[453.917987] Starting subtest: object-reloc-purge-cache
[453.922514] Subtest object-reloc-purge-cache: SKIP (0.003s)
[454.050204] [086/131] (564s left) kms_cursor_crc (cursor-offscreen-128x128)
[454.259482] Starting subtest: cursor-offscreen-128x128
[454.264281] Starting dynamic subtest: pipe-A-eDP-1
[456.248629] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.985s)
[456.277396] Starting dynamic subtest: pipe-D-eDP-1
[458.914839] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.645s)
[458.917235] Subtest cursor-offscreen-128x128: SUCCESS (4.654s)
[459.740080] [087/131] (558s left) kms_psr2_sf (pr-cursor-plane-update-sf)
[464.793060] Starting subtest: pr-cursor-plane-update-sf
[464.797971] Subtest pr-cursor-plane-update-sf: SKIP (0.002s)
[465.628774] [088/131] (553s left) kms_pm_rpm (cursor)
[467.774444] Starting subtest: cursor
[467.779133] Subtest cursor: SKIP (0.000s)
[468.142551] [089/131] (550s left) kms_plane_alpha_blend (constant-alpha-min)
[468.234202] Starting subtest: constant-alpha-min
[468.681290] Starting dynamic subtest: pipe-A-eDP-1
[469.391453] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.707s)
[469.401527] Starting dynamic subtest: pipe-D-eDP-1
[470.938861] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.547s)
[470.940814] Subtest constant-alpha-min: SUCCESS (2.703s)
[471.780167] [090/131] (547s left) kms_chamelium_color (ctm-negative)
[471.877993] Subtest ctm-negative: SKIP (0.000s)
[471.925274] [091/131] (546s left) prime_mmap_coherency (read)
[471.976902] Starting subtest: read
[477.358505] Subtest read: SUCCESS (5.379s)
[477.500194] [092/131] (541s left) kms_busy (extended-pageflip-hang-oldfb)
[477.603008] Starting subtest: extended-pageflip-hang-oldfb
[477.605305] Starting dynamic subtest: pipe-A
[478.077667] Dynamic subtest pipe-A: SUCCESS (0.473s)
[478.088025] Starting dynamic subtest: pipe-D
[479.198678] Dynamic subtest pipe-D: SUCCESS (1.118s)
[479.201090] Subtest extended-pageflip-hang-oldfb: SUCCESS (1.594s)
[480.033378] [093/131] (538s left) kms_addfb_basic (no-handle)
[480.093281] Starting subtest: no-handle
[480.095156] Subtest no-handle: SUCCESS (0.000s)
[480.254266] [094/131] (538s left) kms_lease (lease-unleased-connector)
[480.348893] Starting subtest: lease-unleased-connector
[480.351259] Starting dynamic subtest: pipe-A-eDP-1
[480.356791] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.007s)
[480.366392] Starting dynamic subtest: pipe-B-eDP-1
[480.368756] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.010s)
[480.378520] Starting dynamic subtest: pipe-C-eDP-1
[480.381124] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.007s)
[480.390208] Starting dynamic subtest: pipe-D-eDP-1
[480.392442] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.007s)
[480.394766] Subtest lease-unleased-connector: SUCCESS (0.035s)
[480.503366] [095/131] (538s left) kms_chamelium_color (ctm-0-25)
[480.609273] Subtest ctm-0-25: SKIP (0.000s)
[480.658171] [096/131] (538s left) kms_frontbuffer_tracking (psr-indfb-scaledprimary)
[480.758522] Starting subtest: psr-indfb-scaledprimary
[486.469956] Subtest psr-indfb-scaledprimary: SUCCESS (5.711s)
[486.596056] [097/131] (532s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling)
[486.708313] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling
[486.710562] Starting dynamic subtest: pipe-A-default-mode
[486.989017] Dynamic subtest pipe-A-default-mode: SKIP (0.286s)
[486.993651] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.287s)
[487.842826] [098/131] (531s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[487.949693] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[487.953964] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[488.030108] [099/131] (531s left) gen3_mixed_blits
[488.140445] [100/131] (531s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-move)
[488.247508] Starting subtest: fbc-2p-primscrn-spr-indfb-move
[488.251500] Subtest fbc-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[488.376738] [101/131] (530s left) kms_big_fb (linear-32bpp-rotate-0)
[488.483419] Starting subtest: linear-32bpp-rotate-0
[490.382285] Subtest linear-32bpp-rotate-0: SUCCESS (1.900s)
[490.575721] [102/131] (528s left) syncobj_wait (single-wait-for-submit-signaled)
[490.635190] Starting subtest: single-wait-for-submit-signaled
[490.637563] Subtest single-wait-for-submit-signaled: SUCCESS (0.000s)
[490.738432] [103/131] (528s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-wc)
[490.838775] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-wc
[490.843079] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[490.949955] [104/131] (528s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[491.057904] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[491.061889] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[491.169963] [105/131] (528s left) kms_psr (fbc-psr2-dpms)
[491.272019] Starting subtest: fbc-psr2-dpms
[491.274208] Starting dynamic subtest: eDP-1
[491.278331] Dynamic subtest eDP-1: SKIP (0.000s)
[491.282344] Subtest fbc-psr2-dpms: SKIP (0.002s)
[491.345703] [106/131] (528s left) gem_pxp (verify-pxp-stale-buf-optout-execution)
[491.462507] Starting subtest: verify-pxp-stale-buf-optout-execution
[492.519430] Subtest verify-pxp-stale-buf-optout-execution: SUCCESS (1.057s)
[492.661384] [107/131] (526s left) kms_flip (modeset-vs-vblank-race-interruptible)
[492.754365] Starting subtest: modeset-vs-vblank-race-interruptible
[492.756772] Starting dynamic subtest: A-eDP1
[497.441443] Dynamic subtest A-eDP1: SUCCESS (4.685s)
[497.451489] Starting dynamic subtest: B-eDP1
[502.127677] Dynamic subtest B-eDP1: SUCCESS (4.682s)
[502.139589] Starting dynamic subtest: C-eDP1
[506.622845] Dynamic subtest C-eDP1: SUCCESS (4.493s)
[506.633172] Starting dynamic subtest: D-eDP1
[511.131038] Dynamic subtest D-eDP1: SUCCESS (4.506s)
[511.133410] Subtest modeset-vs-vblank-race-interruptible: SUCCESS (18.376s)
[512.291267] [108/131] (507s left) gem_exec_fence (syncobj-timeline-unused-fence)
[512.843881] Starting subtest: syncobj-timeline-unused-fence
[512.849167] Subtest syncobj-timeline-unused-fence: SUCCESS (0.006s)
[514.704899] [109/131] (504s left) perf (global-sseu-config-invalid)
[514.781188] Starting subtest: global-sseu-config-invalid
[514.785861] Subtest global-sseu-config-invalid: SKIP (0.000s)
[514.826983] [110/131] (504s left) gem_mmap_gtt (cpuset-big-copy)
[514.882552] Subtest cpuset-big-copy: SKIP (0.000s)
[514.939965] [111/131] (504s left) perf_pmu (busy-idle-no-semaphores)
[514.980069] Starting subtest: busy-idle-no-semaphores
[514.982540] Starting dynamic subtest: rcs0
[515.657861] Dynamic subtest rcs0: SUCCESS (0.680s)
[515.670397] Starting dynamic subtest: bcs0
[516.313551] Dynamic subtest bcs0: SUCCESS (0.653s)
[516.326390] Starting dynamic subtest: vcs0
[516.966859] Dynamic subtest vcs0: SUCCESS (0.651s)
[516.978057] Starting dynamic subtest: vcs1
[518.243321] Dynamic subtest vcs1: SUCCESS (1.274s)
[518.254781] Starting dynamic subtest: vecs0
[520.229355] Dynamic subtest vecs0: SUCCESS (1.983s)
[520.240976] Starting dynamic subtest: ccs0
[522.277085] Dynamic subtest ccs0: SUCCESS (2.044s)
[522.280249] Subtest busy-idle-no-semaphores: SUCCESS (7.298s)
[522.418698] [112/131] (497s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[522.527689] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[529.494271] Subtest fbcpsr-rgb101010-draw-pwrite: SUCCESS (6.967s)
[529.604957] [113/131] (490s left) kms_flip (wf_vblank-ts-check)
[529.703036] Starting subtest: wf_vblank-ts-check
[529.705337] Starting dynamic subtest: A-eDP1
[538.645439] Dynamic subtest A-eDP1: SUCCESS (8.939s)
[538.657575] Starting dynamic subtest: B-eDP1
[547.568131] Dynamic subtest B-eDP1: SUCCESS (8.919s)
[547.580227] Starting dynamic subtest: C-eDP1
[556.480502] Dynamic subtest C-eDP1: SUCCESS (8.910s)
[556.492778] Starting dynamic subtest: D-eDP1
[565.394312] Dynamic subtest D-eDP1: SUCCESS (8.917s)
[565.397104] Subtest wf_vblank-ts-check: SUCCESS (35.694s)
[566.608180] [114/131] (453s left) kms_chamelium_hpd (dp-hpd-storm-disable)
[567.004309] Subtest dp-hpd-storm-disable: SKIP (0.000s)
[567.807396] [115/131] (452s left) syncobj_timeline (wait-all-complex)
[567.859068] Starting subtest: wait-all-complex
[568.072087] Subtest wait-all-complex: SUCCESS (0.213s)
[568.148263] [116/131] (451s left) gem_tiled_pread_pwrite
[568.286125] [117/131] (451s left) gem_workarounds (suspend-resume-context)
[568.347040] Starting subtest: suspend-resume-context
[584.041482] Subtest suspend-resume-context: SUCCESS (1.201s)
[584.178477] [118/131] (435s left) i915_pm_rps (fence-order)
[584.242660] Starting subtest: fence-order
[586.778864] Subtest fence-order: SUCCESS (2.490s)
[586.865302] [119/131] (433s left) gem_exec_reloc (basic-cpu-noreloc)
[586.932483] Subtest basic-cpu-noreloc: SKIP (0.000s)
[587.022549] [120/131] (433s left) gem_ctx_isolation (preservation-s3)
[587.093605] Starting subtest: preservation-S3
[587.096575] Starting dynamic subtest: rcs0
[603.089632] Dynamic subtest rcs0: SUCCESS (1.409s)
[603.099954] Starting dynamic subtest: bcs0
[619.071393] Dynamic subtest bcs0: SUCCESS (1.402s)
[619.081430] Starting dynamic subtest: vcs0
[635.070387] Dynamic subtest vcs0: SUCCESS (2.222s)
[635.083594] Starting dynamic subtest: vcs1
[653.383758] Dynamic subtest vcs1: SUCCESS (5.217s)
[653.399688] Starting dynamic subtest: vecs0
[671.141341] Dynamic subtest vecs0: SUCCESS (4.685s)
[671.154264] Starting dynamic subtest: ccs0
[689.082841] Dynamic subtest ccs0: SUCCESS (3.166s)
[689.086440] Subtest preservation-S3: SUCCESS (18.106s)
[689.333003] [121/131] (330s left) sriov_basic (enable-vfs-autoprobe-off)
[689.401298] Starting subtest: enable-vfs-autoprobe-off
[689.404104] Starting dynamic subtest: numvfs-1
[689.492559] Dynamic subtest numvfs-1: FAIL (0.005s)
[689.504702] Starting dynamic subtest: numvfs-2
[689.590581] Dynamic subtest numvfs-2: FAIL (0.004s)
[689.601339] Starting dynamic subtest: numvfs-3
[689.689150] Dynamic subtest numvfs-3: FAIL (0.003s)
[689.700358] Starting dynamic subtest: numvfs-4
[689.785678] Dynamic subtest numvfs-4: FAIL (0.003s)
[689.796373] Starting dynamic subtest: numvfs-5
[689.881370] Dynamic subtest numvfs-5: FAIL (0.003s)
[689.892602] Starting dynamic subtest: numvfs-6
[689.978121] Dynamic subtest numvfs-6: FAIL (0.004s)
[689.990413] Starting dynamic subtest: numvfs-7
[690.070979] Dynamic subtest numvfs-7: FAIL (0.003s)
[690.081116] Starting dynamic subtest: numvfs-random
[690.162262] Dynamic subtest numvfs-random: FAIL (0.003s)
[690.173184] Starting dynamic subtest: numvfs-all
[690.254859] Dynamic subtest numvfs-all: FAIL (0.588s)
[690.256849] Subtest enable-vfs-autoprobe-off: FAIL (0.623s)
[690.297496] [122/131] (329s left) kms_pm_rpm (pc8-residency)
[691.845043] Starting subtest: pc8-residency
[691.850753] Subtest pc8-residency: SKIP (0.000s)
[692.606401] [123/131] (327s left) syncobj_timeline (etime-single-wait-all-submitted)
[692.671705] Starting subtest: etime-single-wait-all-submitted
[692.772822] Subtest etime-single-wait-all-submitted: SUCCESS (0.100s)
[692.854398] [124/131] (327s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-pwrite)
[692.958228] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-pwrite
[692.963231] Subtest fbc-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[693.052613] [125/131] (327s left) kms_setmode (basic)
[693.099350] Starting subtest: basic
[693.438117] Starting dynamic subtest: pipe-A-eDP-1
[695.685024] Dynamic subtest pipe-A-eDP-1: FAIL (2.078s)
[695.894325] Starting dynamic subtest: pipe-B-eDP-1
[698.132024] Dynamic subtest pipe-B-eDP-1: FAIL (2.082s)
[698.291628] Subtest basic: FAIL (5.138s)
[699.137264] [126/131] (321s left) kms_flip (flip-vs-dpms-on-nop-interruptible)
[699.237162] Starting subtest: flip-vs-dpms-on-nop-interruptible
[699.239682] Starting dynamic subtest: A-eDP1
[701.126872] Dynamic subtest A-eDP1: SUCCESS (1.878s)
[701.138137] Starting dynamic subtest: B-eDP1
[702.960699] Dynamic subtest B-eDP1: SUCCESS (1.825s)
[702.974344] Starting dynamic subtest: C-eDP1
[704.777925] Dynamic subtest C-eDP1: SUCCESS (1.808s)
[704.789120] Starting dynamic subtest: D-eDP1
[706.609687] Dynamic subtest D-eDP1: SUCCESS (1.825s)
[706.616194] Subtest flip-vs-dpms-on-nop-interruptible: SUCCESS (7.347s)
[707.781843] [127/131] (312s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[707.879253] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[707.882049] Starting dynamic subtest: pipe-A-eDP-1
[708.778706] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.886s)
[708.789425] Starting dynamic subtest: pipe-B-eDP-1
[710.302020] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.520s)
[710.313043] Starting dynamic subtest: pipe-C-eDP-1
[711.793729] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.486s)
[711.805068] Starting dynamic subtest: pipe-D-eDP-1
[713.227576] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.434s)
[713.229937] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.330s)
[714.410323] [128/131] (305s left) kms_psr2_su (page_flip-xrgb8888)
[714.472185] Subtest page_flip-XRGB8888: SKIP (0.000s)
[714.541239] [129/131] (305s left) gem_exec_schedule (u-semaphore-resolve)
[714.624120] Starting subtest: u-semaphore-resolve
[714.647555] Subtest u-semaphore-resolve: SUCCESS (0.036s)
[715.012949] [130/131] (305s left) api_intel_allocator (fork-simple-once)
[715.081259] Starting subtest: fork-simple-once
[715.095096] Subtest fork-simple-once: SUCCESS (0.016s)
[715.215285] [131/131] (305s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[715.325759] Starting subtest: yf-tiled-64bpp-rotate-90
[715.330269] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[715.418724] Closing watchdogs
results: parsing output: 0/ for test: kms_psr
results: parsing output: 1/ for test: gem_exec_reloc
results: parsing output: 2/ for test: kms_invalid_mode
results: parsing output: 3/ for test: kms_getfb
results: parsing output: 4/ for test: kms_sequence
results: parsing output: 5/ for test: kms_flip
results: parsing output: 6/ for test: kms_ccs
results: parsing output: 7/ for test: gem_wait
results: parsing output: 8/ for test: kms_psr2_sf
results: parsing output: 9/ for test: sw_sync
results: parsing output: 10/ for test: kms_plane
results: parsing output: 11/ for test: gem_exec_reloc
results: parsing output: 12/ for test: kms_cursor_legacy
results: parsing output: 13/ for test: syncobj_basic
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: kms_cursor_crc
results: parsing output: 16/ for test: gem_mmap_offset
results: parsing output: 17/ for test: sriov_basic
results: parsing output: 18/ for test: gem_spin_batch
results: parsing output: 19/ for test: kms_cursor_legacy
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: gem_render_copy_redux
results: parsing output: 22/ for test: kms_flip_scaled_crc
results: parsing output: 23/ for test: gen9_exec_parse
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: gem_exec_balancer
results: parsing output: 26/ for test: kms_flip
results: parsing output: 27/ for test: gem_ctx_persistence
results: parsing output: 28/ for test: kms_big_fb
results: parsing output: 29/ for test: kms_addfb_basic
results: parsing output: 30/ for test: gem_lmem_swapping
results: parsing output: 31/ for test: gem_ccs
results: parsing output: 32/ for test: gem_exec_balancer
results: parsing output: 33/ for test: kms_cursor_legacy
results: parsing output: 34/ for test: kms_addfb_basic
results: parsing output: 35/ for test: kms_cursor_legacy
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: api_intel_bb
results: parsing output: 38/ for test: gem_exec_reloc
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: i915_getparams_basic
results: parsing output: 41/ for test: kms_big_fb
results: parsing output: 42/ for test: gem_lmem_swapping
results: parsing output: 43/ for test: syncobj_wait
results: parsing output: 44/ for test: kms_big_fb
results: parsing output: 45/ for test: kms_plane_scaling
results: parsing output: 46/ for test: kms_psr
results: parsing output: 47/ for test: kms_plane_scaling
results: parsing output: 48/ for test: kms_vblank
results: parsing output: 49/ for test: syncobj_wait
results: parsing output: 50/ for test: kms_flip
results: parsing output: 51/ for test: i915_pm_freq_api
results: parsing output: 52/ for test: syncobj_timeline
results: parsing output: 53/ for test: kms_display_modes
results: parsing output: 54/ for test: kms_psr
results: parsing output: 55/ for test: gem_workarounds
results: parsing output: 56/ for test: gem_readwrite
results: parsing output: 57/ for test: kms_async_flips
results: parsing output: 58/ for test: gem_flink_basic
results: parsing output: 59/ for test: syncobj_timeline
results: parsing output: 60/ for test: kms_cursor_legacy
results: parsing output: 61/ for test: syncobj_wait
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: perf
results: parsing output: 65/ for test: gem_softpin
results: parsing output: 66/ for test: kms_atomic_transition
results: parsing output: 67/ for test: gem_exec_schedule
results: parsing output: 68/ for test: sysfs_preempt_timeout
results: parsing output: 69/ for test: kms_psr
results: parsing output: 70/ for test: kms_ccs
results: parsing output: 71/ for test: gem_render_copy
results: parsing output: 72/ for test: gem_mmap_wc
results: parsing output: 73/ for test: gem_exec_fence
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_color
results: parsing output: 76/ for test: gem_exec_nop
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: fbdev
results: parsing output: 79/ for test: gem_ctx_persistence
results: parsing output: 80/ for test: kms_cursor_crc
results: parsing output: 81/ for test: gem_exec_endless
results: parsing output: 82/ for test: kms_big_fb
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: api_intel_bb
results: parsing output: 85/ for test: kms_cursor_crc
results: parsing output: 86/ for test: kms_psr2_sf
results: parsing output: 87/ for test: kms_pm_rpm
results: parsing output: 88/ for test: kms_plane_alpha_blend
results: parsing output: 89/ for test: kms_chamelium_color
results: parsing output: 90/ for test: prime_mmap_coherency
results: parsing output: 91/ for test: kms_busy
results: parsing output: 92/ for test: kms_addfb_basic
results: parsing output: 93/ for test: kms_lease
results: parsing output: 94/ for test: kms_chamelium_color
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_flip_scaled_crc
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: gen3_mixed_blits
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_big_fb
results: parsing output: 101/ for test: syncobj_wait
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_psr
results: parsing output: 105/ for test: gem_pxp
results: parsing output: 106/ for test: kms_flip
results: parsing output: 107/ for test: gem_exec_fence
results: parsing output: 108/ for test: perf
results: parsing output: 109/ for test: gem_mmap_gtt
results: parsing output: 110/ for test: perf_pmu
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_flip
results: parsing output: 113/ for test: kms_chamelium_hpd
results: parsing output: 114/ for test: syncobj_timeline
results: parsing output: 115/ for test: gem_tiled_pread_pwrite
results: parsing output: 116/ for test: gem_workarounds
results: parsing output: 117/ for test: i915_pm_rps
results: parsing output: 118/ for test: gem_exec_reloc
results: parsing output: 119/ for test: gem_ctx_isolation
results: parsing output: 120/ for test: sriov_basic
results: parsing output: 121/ for test: kms_pm_rpm
results: parsing output: 122/ for test: syncobj_timeline
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: kms_setmode
results: parsing output: 125/ for test: kms_flip
results: parsing output: 126/ for test: kms_ccs
results: parsing output: 127/ for test: kms_psr2_su
results: parsing output: 128/ for test: gem_exec_schedule
results: parsing output: 129/ for test: api_intel_allocator
results: parsing output: 130/ for test: kms_big_fb
Done.