[59.804851] Initializing watchdogs
[59.804905]   /dev/watchdog0
[59.820386] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[59.825174] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.827439] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.830738] [001/133] (960s left) i915_drm_fdinfo (busy-idle)
[59.875818] Starting subtest: busy-idle
[59.876754] Starting dynamic subtest: rcs0
[59.911289] Dynamic subtest rcs0: SKIP (0.034s)
[59.918842] Starting dynamic subtest: bcs0
[59.920565] Dynamic subtest bcs0: SKIP (0.001s)
[59.925789] Starting dynamic subtest: vcs0
[59.927615] Dynamic subtest vcs0: SKIP (0.000s)
[59.934142] Starting dynamic subtest: vcs1
[59.935814] Dynamic subtest vcs1: SKIP (0.000s)
[59.941690] Starting dynamic subtest: vecs0
[59.943364] Dynamic subtest vecs0: SKIP (0.000s)
[59.948491] Starting dynamic subtest: ccs0
[59.950341] Dynamic subtest ccs0: SKIP (0.000s)
[59.952039] Subtest busy-idle: SKIP (0.038s)
[59.977689] [002/133] (960s left) kms_hdr (static-toggle-dpms)
[60.078137] Starting subtest: static-toggle-dpms
[60.080706] Subtest static-toggle-dpms: SKIP (0.000s)
[60.146637] [003/133] (960s left) gem_ctx_param (basic-default)
[60.195378] Starting subtest: basic-default
[60.196381] Subtest basic-default: SUCCESS (0.000s)
[60.252538] [004/133] (960s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[60.348795] Starting subtest: psr-rgb565-draw-mmap-wc
[67.116883] Subtest psr-rgb565-draw-mmap-wc: SUCCESS (6.774s)
[67.528590] [005/133] (952s left) gem_render_copy (yf-tiled-ccs-to-linear)
[67.689247] Starting subtest: yf-tiled-ccs-to-linear
[67.691114] Subtest yf-tiled-ccs-to-linear: SKIP (0.000s)
[67.886692] [006/133] (952s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-render)
[68.133060] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-render
[75.071486] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-render: SUCCESS (6.943s)
[75.213064] [007/133] (945s left) i915_pm_rps (min-max-config-loaded)
[75.273137] Starting subtest: min-max-config-loaded
[75.274928] Subtest min-max-config-loaded: SKIP (0.001s)
[75.375082] [008/133] (945s left) kms_frontbuffer_tracking (fbcpsr-shrfb-scaledprimary)
[75.467461] Starting subtest: fbcpsr-shrfb-scaledprimary
[81.221969] Subtest fbcpsr-shrfb-scaledprimary: SUCCESS (5.758s)
[81.337913] [009/133] (939s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-onoff)
[81.448863] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-onoff
[88.408757] Subtest fbcpsr-1p-primscrn-cur-indfb-onoff: SUCCESS (6.962s)
[88.536417] [010/133] (932s left) kms_rotation_crc (cursor-rotation-180)
[88.623940] Starting subtest: cursor-rotation-180
[90.165734] Subtest cursor-rotation-180: SUCCESS (1.542s)
[91.354828] [011/133] (929s left) syncobj_wait (reset-multiple-signaled)
[91.402241] Starting subtest: reset-multiple-signaled
[91.403207] Subtest reset-multiple-signaled: SUCCESS (0.000s)
[91.491093] [012/133] (929s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[91.548248] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[91.549220] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.001s)
[91.648752] [013/133] (929s left) kms_psr (psr2-basic)
[91.739364] Starting subtest: psr2-basic
[91.740296] Starting dynamic subtest: eDP-1
[93.319219] Dynamic subtest eDP-1: SUCCESS (1.579s)
[93.320193] Subtest psr2-basic: SUCCESS (1.580s)
[94.108060] [014/133] (926s left) kms_vblank (ts-continuation-modeset)
[94.189333] Starting subtest: ts-continuation-modeset
[94.190219] Starting dynamic subtest: pipe-A-eDP-1
[95.674751] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.485s)
[95.682752] Starting dynamic subtest: pipe-D-eDP-1
[97.852606] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.175s)
[97.853566] Subtest ts-continuation-modeset: SUCCESS (3.663s)
[98.647687] [015/133] (922s left) syncobj_timeline (wait-for-submit-snapshot)
[98.694440] Starting subtest: wait-for-submit-snapshot
[98.776829] Subtest wait-for-submit-snapshot: SUCCESS (0.082s)
[98.857019] [016/133] (921s left) gem_mmap_gtt (cpuset-medium-copy-odd)
[98.901109] Subtest cpuset-medium-copy-odd: SKIP (0.000s)
[98.950854] [017/133] (921s left) gem_ctx_param (invalid-get-ringsize)
[99.007402] Starting subtest: invalid-get-ringsize
[99.008360] Subtest invalid-get-ringsize: SUCCESS (0.001s)
[99.100123] [018/133] (921s left) gem_tiled_fence_blits (normal)
[99.161550] Subtest normal: SKIP (0.000s)
[99.218452] [019/133] (921s left) gem_linear_blits (interruptible)
[99.497363] Starting subtest: interruptible
[103.596771] Subtest interruptible: SUCCESS (4.092s)
[103.772281] [020/133] (917s left) kms_big_fb (x-tiled-64bpp-rotate-270)
[103.918197] Starting subtest: x-tiled-64bpp-rotate-270
[103.937526] Subtest x-tiled-64bpp-rotate-270: SKIP (0.019s)
[104.032157] [021/133] (916s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-modifiers)
[104.117126] Starting subtest: plane-scaler-with-clipping-clamping-modifiers
[104.118184] Starting dynamic subtest: pipe-A
[104.457271] Dynamic subtest pipe-A: SUCCESS (0.340s)
[104.466334] Starting dynamic subtest: pipe-B
[105.883073] Dynamic subtest pipe-B: SUCCESS (1.425s)
[105.889916] Starting dynamic subtest: pipe-C
[107.316118] Dynamic subtest pipe-C: SUCCESS (1.432s)
[107.325965] Starting dynamic subtest: pipe-D
[108.702134] Dynamic subtest pipe-D: SUCCESS (1.385s)
[108.703067] Subtest plane-scaler-with-clipping-clamping-modifiers: SUCCESS (4.586s)
[109.863613] [022/133] (911s left) kms_pm_rpm (system-suspend-modeset)
[112.019460] Starting subtest: system-suspend-modeset
[132.361590] Subtest system-suspend-modeset: SUCCESS (5.057s)
[137.800767] [023/133] (888s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted-signaled)
[137.859006] Starting subtest: multi-wait-all-for-submit-available-submitted-signaled
[137.859850] Subtest multi-wait-all-for-submit-available-submitted-signaled: SUCCESS (0.000s)
[137.968028] [024/133] (888s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[138.053638] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[138.055394] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.000s)
[138.115335] [025/133] (888s left) gem_ctx_param (non-root-set)
[138.161587] Starting subtest: non-root-set
[138.186169] Subtest non-root-set: SUCCESS (0.025s)
[138.264994] [026/133] (887s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[138.358100] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-cpu
[138.359814] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[138.441572] [027/133] (887s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[138.539892] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[139.350153] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (0.814s)
[139.492611] [028/133] (886s left) gem_ctx_persistence (file)
[139.557652] Starting subtest: file
[139.670680] Subtest file: SUCCESS (0.114s)
[139.776325] [029/133] (886s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-downscaling)
[139.860506] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-downscaling
[139.861398] Starting dynamic subtest: pipe-A-default-mode
[140.968532] Dynamic subtest pipe-A-default-mode: SKIP (1.112s)
[140.970224] Subtest flip-32bpp-xtile-to-64bpp-xtile-downscaling: SKIP (1.113s)
[141.111776] [030/133] (885s left) kms_plane_scaling (plane-upscale-factor-0-25-with-pixel-format)
[141.202444] Starting subtest: plane-upscale-factor-0-25-with-pixel-format
[141.203462] Starting dynamic subtest: pipe-A
[142.258278] Dynamic subtest pipe-A: SUCCESS (1.061s)
[142.266761] Starting dynamic subtest: pipe-B
[144.332517] Dynamic subtest pipe-B: SUCCESS (2.079s)
[144.341689] Starting dynamic subtest: pipe-C
[145.531597] Dynamic subtest pipe-C: SUCCESS (1.201s)
[145.539111] Starting dynamic subtest: pipe-D
[146.686190] Dynamic subtest pipe-D: SUCCESS (1.156s)
[146.687465] Subtest plane-upscale-factor-0-25-with-pixel-format: SUCCESS (5.502s)
[147.877731] [031/133] (878s left) kms_flip (flip-vs-dpms-off-vs-modeset-interruptible)
[148.000049] Starting subtest: flip-vs-dpms-off-vs-modeset-interruptible
[148.001282] Starting dynamic subtest: A-eDP1
[151.592734] Dynamic subtest A-eDP1: SUCCESS (3.597s)
[151.603664] Starting dynamic subtest: B-eDP1
[154.808820] Dynamic subtest B-eDP1: SUCCESS (3.217s)
[154.816475] Starting dynamic subtest: C-eDP1
[157.987842] Dynamic subtest C-eDP1: SUCCESS (3.182s)
[157.995403] Starting dynamic subtest: D-eDP1
[161.082046] Dynamic subtest D-eDP1: SUCCESS (3.096s)
[161.084181] Subtest flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (13.099s)
[161.885341] [032/133] (864s left) gem_exec_parallel (contexts)
[161.936418] Starting subtest: contexts
[161.937429] Starting dynamic subtest: rcs0
[164.833948] Dynamic subtest rcs0: SUCCESS (2.889s)
[164.841450] Starting dynamic subtest: bcs0
[168.460925] Dynamic subtest bcs0: SUCCESS (3.628s)
[168.468367] Starting dynamic subtest: vcs0
[172.699810] Dynamic subtest vcs0: SUCCESS (4.238s)
[172.707677] Starting dynamic subtest: vcs1
[176.652488] Dynamic subtest vcs1: SUCCESS (3.952s)
[176.662369] Starting dynamic subtest: vecs0
[180.680948] Dynamic subtest vecs0: SUCCESS (4.028s)
[180.688631] Starting dynamic subtest: ccs0
[184.212847] Dynamic subtest ccs0: SUCCESS (3.532s)
[184.214328] Subtest contexts: SUCCESS (22.280s)
[184.388031] [033/133] (842s left) gem_exec_alignment (many)
[184.450391] Starting subtest: many
[209.527912] Subtest many: SUCCESS (25.043s)
[211.177610] [034/133] (815s left) kms_psr (fbc-pr-no-drrs)
[211.314930] Starting subtest: fbc-pr-no-drrs
[211.316918] Subtest fbc-pr-no-drrs: SKIP (0.000s)
[211.391548] [035/133] (815s left) kms_vrr (flip-suspend)
[211.486212] Starting subtest: flip-suspend
[211.488885] Subtest flip-suspend: SKIP (0.000s)
[211.558728] [036/133] (814s left) i915_module_load (reload)
[211.587517] Starting subtest: reload
[216.375263] Subtest reload: SUCCESS (4.792s)
[216.405456] [037/133] (810s left) gem_vm_create (invalid-destroy)
[218.167809] Starting subtest: invalid-destroy
[218.169191] Subtest invalid-destroy: SUCCESS (0.002s)
[218.261794] [038/133] (808s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs)
[218.355643] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs
[218.356520] Starting dynamic subtest: pipe-A-eDP-1
[218.359993] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[218.367025] Starting dynamic subtest: pipe-B-eDP-1
[218.370454] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[218.376873] Starting dynamic subtest: pipe-C-eDP-1
[218.380336] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[218.386633] Starting dynamic subtest: pipe-D-eDP-1
[218.389901] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[218.391499] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[220.571599] [039/133] (806s left) kms_dsc (dsc-with-bpc-formats)
[220.668619] Starting subtest: dsc-with-bpc-formats
[220.707775] Subtest dsc-with-bpc-formats: SKIP (0.003s)
[220.750095] [040/133] (805s left) kms_flip (dpms-off-confusion)
[220.831822] Starting subtest: dpms-off-confusion
[220.834006] Starting dynamic subtest: A-eDP1
[229.611857] Dynamic subtest A-eDP1: SUCCESS (8.757s)
[229.619688] Starting dynamic subtest: B-eDP1
[238.341894] Dynamic subtest B-eDP1: SUCCESS (8.710s)
[238.351058] Starting dynamic subtest: C-eDP1
[246.945987] Dynamic subtest C-eDP1: SUCCESS (8.614s)
[246.955667] Starting dynamic subtest: D-eDP1
[255.637884] Dynamic subtest D-eDP1: SUCCESS (8.646s)
[255.638867] Subtest dpms-off-confusion: SUCCESS (34.741s)
[256.771078] [041/133] (769s left) gem_exec_schedule (submit-early-slice)
[256.844581] Starting subtest: submit-early-slice
[256.845484] Starting dynamic subtest: rcs0
[256.887851] Dynamic subtest rcs0: SUCCESS (0.043s)
[256.895129] Starting dynamic subtest: bcs0
[256.924659] Dynamic subtest bcs0: SUCCESS (0.035s)
[256.934684] Starting dynamic subtest: vcs0
[256.963170] Dynamic subtest vcs0: SUCCESS (0.038s)
[256.974755] Starting dynamic subtest: vcs1
[257.005228] Dynamic subtest vcs1: SUCCESS (0.041s)
[257.016921] Starting dynamic subtest: vecs0
[257.043183] Dynamic subtest vecs0: SUCCESS (0.037s)
[257.052302] Starting dynamic subtest: ccs0
[257.077748] Dynamic subtest ccs0: SUCCESS (0.034s)
[257.078675] Subtest submit-early-slice: SUCCESS (0.234s)
[258.257031] [042/133] (768s left) gem_exec_schedule (noreorder)
[258.436965] Starting subtest: noreorder
[258.438409] Starting dynamic subtest: rcs0
[258.491827] Dynamic subtest rcs0: SUCCESS (0.062s)
[258.502906] Starting dynamic subtest: vcs0
[260.174941] Dynamic subtest vcs0: SUCCESS (1.672s)
[260.182349] Starting dynamic subtest: vcs1
[262.226868] Dynamic subtest vcs1: SUCCESS (2.049s)
[262.235770] Starting dynamic subtest: vecs0
[264.185755] Dynamic subtest vecs0: SUCCESS (1.956s)
[264.194263] Starting dynamic subtest: ccs0
[264.237510] Dynamic subtest ccs0: SUCCESS (0.058s)
[264.238789] Subtest noreorder: SUCCESS (5.807s)
[264.418341] [043/133] (762s left) gem_close (many-handles-one-vma)
[264.480698] Starting subtest: many-handles-one-vma
[264.523843] Subtest many-handles-one-vma: SUCCESS (0.044s)
[264.636861] [044/133] (762s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-gtt)
[264.727866] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-gtt
[264.729635] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[264.817300] [045/133] (762s left) kms_plane_alpha_blend (alpha-basic)
[264.916606] Starting subtest: alpha-basic
[265.250423] Starting dynamic subtest: pipe-A-eDP-1
[267.154188] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.903s)
[267.179530] Starting dynamic subtest: pipe-D-eDP-1
[269.830274] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.657s)
[269.831090] Subtest alpha-basic: SUCCESS (4.913s)
[270.634080] [046/133] (756s left) kms_psr (psr2-cursor-plane-move)
[270.720640] Starting subtest: psr2-cursor-plane-move
[270.721558] Starting dynamic subtest: eDP-1
[272.410645] Dynamic subtest eDP-1: SUCCESS (1.688s)
[272.411539] Subtest psr2-cursor-plane-move: SUCCESS (1.689s)
[273.191071] [047/133] (753s left) kms_big_fb (y-tiled-64bpp-rotate-270)
[273.304909] Starting subtest: y-tiled-64bpp-rotate-270
[273.306709] Subtest y-tiled-64bpp-rotate-270: SKIP (0.000s)
[273.407680] [048/133] (753s left) dmabuf_sync_file (export-multiwait)
[273.486746] Starting subtest: export-multiwait
[273.487691] Subtest export-multiwait: SUCCESS (0.001s)
[273.552818] [FACT dmabuf_sync_file (export-multiwait)] new: kernel.kmod_is_loaded.vgem: true
[273.555327] [049/133] (753s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[273.657401] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc
[273.659006] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[273.756783] [050/133] (753s left) gem_mmap_gtt (basic-small-copy)
[273.804955] Subtest basic-small-copy: SKIP (0.000s)
[273.854017] [051/133] (753s left) gem_exec_fence (syncobj-backward-timeline-chain-engines)
[274.386539] Starting subtest: syncobj-backward-timeline-chain-engines
[274.480171] Subtest syncobj-backward-timeline-chain-engines: SUCCESS (0.101s)
[276.252773] [052/133] (750s left) gem_userptr_blits (process-exit-busy)
[276.311907] Starting subtest: process-exit-busy
[276.329044] Subtest process-exit-busy: SUCCESS (0.017s)
[276.445407] [053/133] (750s left) kms_big_fb (linear-64bpp-rotate-90)
[276.540075] Starting subtest: linear-64bpp-rotate-90
[276.558522] Subtest linear-64bpp-rotate-90: SKIP (0.018s)
[276.670990] [054/133] (750s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[276.765213] Starting subtest: extended-modeset-hang-oldfb-with-reset
[276.766002] Starting dynamic subtest: pipe-A
[288.686388] Dynamic subtest pipe-A: SUCCESS (11.920s)
[288.696716] Starting dynamic subtest: pipe-D
[300.956005] Dynamic subtest pipe-D: SUCCESS (12.266s)
[300.957445] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (24.190s)
[301.769016] [055/133] (725s left) gem_readwrite (new-obj)
[301.823467] Subtest new-obj: SKIP (0.000s)
[301.879463] [056/133] (725s left) gem_exec_whisper (basic-forked-all)
[301.936759] Starting subtest: basic-forked-all
[315.216112] Subtest basic-forked-all: SUCCESS (13.278s)
[315.402005] [057/133] (711s left) gem_mmap_offset (mmap-boundaries)
[315.458328] Starting subtest: mmap-boundaries
[315.459425] Starting dynamic subtest: smem0
[315.752717] Dynamic subtest smem0: SUCCESS (0.285s)
[315.754091] Subtest mmap-boundaries: SUCCESS (0.286s)
[315.869920] [058/133] (711s left) kms_plane (pixel-format-4-tiled-modifier-source-clamping)
[315.956124] Starting subtest: pixel-format-4-tiled-modifier-source-clamping
[315.984641] Starting dynamic subtest: pipe-A-plane-0
[316.639613] Dynamic subtest pipe-A-plane-0: SUCCESS (0.655s)
[316.648176] Starting dynamic subtest: pipe-A-plane-3
[317.095203] Dynamic subtest pipe-A-plane-3: SUCCESS (0.455s)
[318.246744] Starting dynamic subtest: pipe-B-plane-0
[318.915706] Dynamic subtest pipe-B-plane-0: SUCCESS (0.677s)
[318.924217] Starting dynamic subtest: pipe-B-plane-3
[319.349051] Dynamic subtest pipe-B-plane-3: SUCCESS (0.432s)
[319.656832] Subtest pixel-format-4-tiled-modifier-source-clamping: SUCCESS (3.700s)
[320.516485] [059/133] (706s left) api_intel_allocator (reopen)
[320.567665] Starting subtest: reopen
[320.571469] Subtest reopen: SUCCESS (0.004s)
[320.681898] [060/133] (706s left) gem_mmap (pf-nonblock)
[320.726162] Subtest pf-nonblock: SKIP (0.000s)
[320.790540] [061/133] (706s left) syncobj_timeline (wait-any-snapshot)
[320.837420] Starting subtest: wait-any-snapshot
[320.919398] Subtest wait-any-snapshot: SUCCESS (0.081s)
[320.991520] [062/133] (706s left) i915_query (query-garbage)
[321.038126] Starting subtest: query-garbage
[321.038965] Subtest query-garbage: SUCCESS (0.000s)
[321.150568] [063/133] (706s left) syncobj_timeline (multi-wait-all-for-submit-signaled)
[321.217795] Starting subtest: multi-wait-all-for-submit-signaled
[321.218749] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.001s)
[321.288801] [064/133] (706s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[321.339637] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[321.341354] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[321.514324] [065/133] (706s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[321.614757] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[321.627067] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.011s)
[321.702794] [066/133] (705s left) gem_create (create-valid-nonaligned)
[321.753742] Starting subtest: create-valid-nonaligned
[321.754917] Subtest create-valid-nonaligned: SUCCESS (0.000s)
[321.937965] [067/133] (705s left) gem_barrier_race (remote-request)
[322.000173] Starting subtest: remote-request
[322.001155] Starting dynamic subtest: rcs0
[327.173949] Dynamic subtest rcs0: SUCCESS (5.173s)
[327.179124] Subtest remote-request: SUCCESS (5.177s)
[327.307823] [068/133] (700s left) kms_prop_blob (blob-prop-core)
[327.353091] Starting subtest: blob-prop-core
[327.353928] Subtest blob-prop-core: SUCCESS (0.000s)
[327.452026] [069/133] (700s left) kms_frontbuffer_tracking (fbcpsr-suspend)
[327.546023] Starting subtest: fbcpsr-suspend
[368.501811] Subtest fbcpsr-suspend: SUCCESS (10.975s)
[373.635391] [070/133] (659s left) gem_mmap_gtt (basic-read)
[373.700654] Subtest basic-read: SKIP (0.000s)
[373.751367] [071/133] (659s left) kms_flip (2x-blocking-wf_vblank)
[373.840765] Starting subtest: 2x-blocking-wf_vblank
[373.851977] Subtest 2x-blocking-wf_vblank: SKIP (0.011s)
[373.961051] [072/133] (658s left) kms_psr (psr-sprite-plane-move)
[374.059613] Starting subtest: psr-sprite-plane-move
[374.060508] Starting dynamic subtest: eDP-1
[375.825370] Dynamic subtest eDP-1: SUCCESS (1.772s)
[375.826287] Subtest psr-sprite-plane-move: SUCCESS (1.773s)
[376.659019] [073/133] (656s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt)
[376.747703] Starting subtest: psr-2p-primscrn-indfb-msflip-blt
[376.749397] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[376.845062] [074/133] (656s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted-signa
[376.901457] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled
[377.007303] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.105s)
[377.080903] [075/133] (655s left) kms_psr (fbc-psr2-cursor-mmap-gtt)
[377.169416] Starting subtest: fbc-psr2-cursor-mmap-gtt
[377.170220] Starting dynamic subtest: eDP-1
[377.171863] Dynamic subtest eDP-1: SKIP (0.000s)
[377.173662] Subtest fbc-psr2-cursor-mmap-gtt: SKIP (0.001s)
[377.252410] [076/133] (655s left) kms_getfb (getfb-handle-protection)
[377.345395] Starting subtest: getfb-handle-protection
[377.363968] Subtest getfb-handle-protection: SUCCESS (0.019s)
[377.476253] [077/133] (655s left) kms_addfb_basic (bad-pitch-128)
[377.530474] Starting subtest: bad-pitch-128
[377.531363] Subtest bad-pitch-128: SUCCESS (0.000s)
[377.665309] [078/133] (655s left) gem_partial_pwrite_pread (writes-after-reads-display)
[377.720065] Subtest writes-after-reads-display: SKIP (0.000s)
[377.782900] [079/133] (655s left) kms_content_protection (atomic)
[377.913610] Starting subtest: atomic
[377.915698] Subtest atomic: SKIP (0.000s)
[378.035699] [080/133] (655s left) kms_cursor_edge_walk (256x256-left-edge)
[378.120795] Starting subtest: 256x256-left-edge
[378.121707] Starting dynamic subtest: pipe-A-eDP-1
[381.616625] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.503s)
[381.625183] Starting dynamic subtest: pipe-D-eDP-1
[384.733598] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.120s)
[384.734450] Subtest 256x256-left-edge: SUCCESS (6.626s)
[385.540736] [081/133] (647s left) kms_pm_rpm (cursor)
[387.394414] Starting subtest: cursor
[387.396652] Subtest cursor: SKIP (0.000s)
[387.792129] [082/133] (645s left) kms_atomic (plane-invalid-params-fence)
[387.890877] Starting subtest: plane-invalid-params-fence
[387.891954] Starting dynamic subtest: pipe-A-eDP-1
[388.249622] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.359s)
[388.250484] Subtest plane-invalid-params-fence: SUCCESS (0.361s)
[389.107744] [083/133] (644s left) kms_async_flips (crc)
[389.192354] Starting subtest: crc
[389.577021] Starting dynamic subtest: pipe-A-eDP-1
[392.540602] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.967s)
[392.922799] Starting dynamic subtest: pipe-B-eDP-1
[395.865486] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.953s)
[396.239098] Starting dynamic subtest: pipe-C-eDP-1
[399.164878] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.936s)
[399.527203] Starting dynamic subtest: pipe-D-eDP-1
[402.397680] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.880s)
[402.398743] Subtest crc: SUCCESS (13.216s)
[403.559407] [084/133] (629s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-render)
[403.650499] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-render
[403.652684] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[403.749318] [085/133] (629s left) kms_plane_cursor (viewport)
[403.832474] Starting subtest: viewport
[403.834247] Starting dynamic subtest: pipe-A-eDP-1-size-64
[406.239813] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.408s)
[406.301925] Starting dynamic subtest: pipe-A-eDP-1-size-128
[408.677550] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.382s)
[408.737657] Starting dynamic subtest: pipe-A-eDP-1-size-256
[411.145321] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.416s)
[411.491616] Starting dynamic subtest: pipe-B-eDP-1-size-64
[414.578119] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.094s)
[414.641942] Starting dynamic subtest: pipe-B-eDP-1-size-128
[417.035605] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.404s)
[417.098227] Starting dynamic subtest: pipe-B-eDP-1-size-256
[419.471232] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.382s)
[419.816177] Starting dynamic subtest: pipe-C-eDP-1-size-64
[422.508693] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.701s)
[422.547691] Starting dynamic subtest: pipe-C-eDP-1-size-128
[424.575687] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.036s)
[424.614719] Starting dynamic subtest: pipe-C-eDP-1-size-256
[426.643927] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.038s)
[426.969791] Starting dynamic subtest: pipe-D-eDP-1-size-64
[429.708063] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.748s)
[429.747530] Starting dynamic subtest: pipe-D-eDP-1-size-128
[431.785531] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.047s)
[431.825174] Starting dynamic subtest: pipe-D-eDP-1-size-256
[433.829486] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.013s)
[434.145907] Subtest viewport: SUCCESS (30.310s)
[434.972127] [086/133] (598s left) i915_pm_rps (thresholds-idle)
[435.024689] Starting subtest: thresholds-idle
[435.026865] Subtest thresholds-idle: SKIP (0.001s)
[435.126066] [087/133] (598s left) kms_addfb_basic (bo-too-small)
[435.206246] Starting subtest: bo-too-small
[435.207102] Subtest bo-too-small: SUCCESS (0.000s)
[435.293704] [088/133] (598s left) gem_exec_whisper (basic-queues-forked)
[435.353647] Starting subtest: basic-queues-forked
[459.432341] Subtest basic-queues-forked: SUCCESS (24.037s)
[459.568594] [089/133] (573s left) perf (invalid-oa-format-id)
[459.671372] Starting subtest: invalid-oa-format-id
[459.682001] Subtest invalid-oa-format-id: SUCCESS (0.011s)
[459.721610] [090/133] (573s left) kms_psr2_sf (psr2-cursor-plane-update-sf)
[459.831818] Starting subtest: psr2-cursor-plane-update-sf
[459.832651] Starting dynamic subtest: pipe-A-eDP-1
[460.424330] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.593s)
[460.434535] Starting dynamic subtest: pipe-B-eDP-1
[461.754837] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.329s)
[461.755789] Subtest psr2-cursor-plane-update-sf: SUCCESS (1.925s)
[462.621052] [091/133] (570s left) gem_exec_reloc (basic-cpu-gtt)
[462.681632] Subtest basic-cpu-gtt: SKIP (0.000s)
[462.752187] [092/133] (570s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-mc-ccs)
[462.847414] Starting subtest: missing-ccs-buffer-y-tiled-gen12-mc-ccs
[462.848314] Starting dynamic subtest: pipe-A-eDP-1
[462.857025] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[462.865809] Starting dynamic subtest: pipe-B-eDP-1
[462.874438] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[462.882852] Starting dynamic subtest: pipe-C-eDP-1
[462.890985] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[462.899267] Starting dynamic subtest: pipe-D-eDP-1
[462.907591] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[462.909198] Subtest missing-ccs-buffer-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[464.991924] [093/133] (568s left) gem_close_race (multigpu-basic-process)
[465.059884] Starting subtest: multigpu-basic-process
[465.061522] Subtest multigpu-basic-process: SKIP (0.000s)
[465.264273] [094/133] (568s left) gen9_exec_parse (batch-invalid-length)
[465.311566] Subtest batch-invalid-length: SKIP (0.000s)
[465.377123] [095/133] (568s left) gem_exec_fence (syncobj-unused-fence)
[465.823857] Starting subtest: syncobj-unused-fence
[465.829122] Subtest syncobj-unused-fence: SUCCESS (0.005s)
[467.271200] [096/133] (566s left) gem_exec_nop (basic-sequential)
[467.347534] Starting subtest: basic-sequential
[481.614844] Subtest basic-sequential: SUCCESS (14.264s)
[481.859783] [097/133] (551s left) kms_frontbuffer_tracking (fbc-2p-shrfb-fliptrack-mmap-gtt)
[481.961837] Starting subtest: fbc-2p-shrfb-fliptrack-mmap-gtt
[481.963623] Subtest fbc-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[482.063404] [098/133] (551s left) kms_big_fb (4-tiled-64bpp-rotate-90)
[482.156758] Starting subtest: 4-tiled-64bpp-rotate-90
[482.176314] Subtest 4-tiled-64bpp-rotate-90: SKIP (0.019s)
[482.300364] [099/133] (551s left) kms_pipe_b_c_ivb (from-pipe-c-to-b-with-3-lanes)
[482.349788] Subtest from-pipe-C-to-B-with-3-lanes: SKIP (0.000s)
[482.405279] [100/133] (551s left) kms_fence_pin_leak
[482.560738] [101/133] (551s left) prime_busy (hang-wait)
[482.606428] Starting subtest: hang-wait
[482.607451] Starting dynamic subtest: rcs0
[508.618810] Dynamic subtest rcs0: SUCCESS (26.005s)
[508.629732] Starting dynamic subtest: bcs0
[515.772364] Dynamic subtest bcs0: SUCCESS (7.154s)
[515.781043] Starting dynamic subtest: vcs0
[522.811805] Dynamic subtest vcs0: SUCCESS (7.040s)
[522.822871] Starting dynamic subtest: vcs1
[529.788617] Dynamic subtest vcs1: SUCCESS (6.974s)
[529.798625] Starting dynamic subtest: vecs0
[536.761864] Dynamic subtest vecs0: SUCCESS (6.971s)
[536.770528] Starting dynamic subtest: ccs0
[550.663318] Dynamic subtest ccs0: SUCCESS (13.905s)
[550.666438] Subtest hang-wait: SUCCESS (68.061s)
[550.800718] [102/133] (483s left) kms_chamelium_hpd (hdmi-hpd-for-each-pipe)
[551.184509] Subtest hdmi-hpd-for-each-pipe: SKIP (0.000s)
[551.992679] [103/133] (481s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-onoff)
[552.084642] Starting subtest: psr-2p-primscrn-spr-indfb-onoff
[552.086501] Subtest psr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[552.188390] [104/133] (481s left) gem_unref_active_buffers
[552.364229] [105/133] (481s left) kms_rmfb (rmfb-ioctl)
[552.451851] Starting subtest: rmfb-ioctl
[552.453019] Starting dynamic subtest: pipe-A-eDP-1
[552.556470] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.106s)
[552.566848] Starting dynamic subtest: pipe-B-eDP-1
[553.765395] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.207s)
[553.779172] Starting dynamic subtest: pipe-C-eDP-1
[554.891212] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.124s)
[554.902230] Starting dynamic subtest: pipe-D-eDP-1
[556.018578] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.126s)
[556.019851] Subtest rmfb-ioctl: SUCCESS (3.571s)
[557.149366] [106/133] (476s left) gem_exec_reloc (basic-cpu-read-noreloc)
[557.216878] Subtest basic-cpu-read-noreloc: SKIP (0.000s)
[557.286317] [107/133] (476s left) kms_cursor_crc (cursor-dpms)
[557.405312] Starting subtest: cursor-dpms
[557.406150] Starting dynamic subtest: pipe-A-eDP-1
[560.429551] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.024s)
[560.445152] Starting dynamic subtest: pipe-D-eDP-1
[564.009468] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.578s)
[564.010391] Subtest cursor-dpms: SUCCESS (6.605s)
[564.849709] [108/133] (469s left) kms_debugfs (display-off-read-all)
[564.938045] Starting subtest: display-off-read-all
[565.234569] Subtest display-off-read-all: SUCCESS (0.297s)
[566.113043] [109/133] (467s left) perf_pmu (most-busy-idle-check-all)
[566.158303] Starting subtest: most-busy-idle-check-all
[566.159222] Starting dynamic subtest: rcs0
[568.179847] Dynamic subtest rcs0: SUCCESS (2.012s)
[568.191976] Starting dynamic subtest: bcs0
[570.163810] Dynamic subtest bcs0: SUCCESS (1.981s)
[570.175453] Starting dynamic subtest: vcs0
[572.210737] Dynamic subtest vcs0: SUCCESS (2.044s)
[572.222198] Starting dynamic subtest: vcs1
[574.195292] Dynamic subtest vcs1: SUCCESS (1.982s)
[574.207125] Starting dynamic subtest: vecs0
[576.179273] Dynamic subtest vecs0: SUCCESS (1.981s)
[576.190758] Starting dynamic subtest: ccs0
[578.165798] Dynamic subtest ccs0: SUCCESS (1.983s)
[578.167175] Subtest most-busy-idle-check-all: SUCCESS (12.003s)
[578.266654] [110/133] (455s left) gem_exec_balancer (waits)
[578.324937] Starting subtest: waits
[584.919129] Subtest waits: SUCCESS (6.586s)
[585.230548] [111/133] (448s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[585.329166] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[585.330044] Starting dynamic subtest: pipe-A-eDP-1
[587.414686] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.086s)
[587.424905] Starting dynamic subtest: pipe-B-eDP-1
[589.531782] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.116s)
[589.542032] Starting dynamic subtest: pipe-C-eDP-1
[591.566303] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.034s)
[591.576476] Starting dynamic subtest: pipe-D-eDP-1
[593.623004] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.056s)
[593.623901] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.297s)
[594.843996] [112/133] (439s left) perf (invalid-oa-metric-set-id)
[594.928010] Starting subtest: invalid-oa-metric-set-id
[594.937373] Subtest invalid-oa-metric-set-id: SUCCESS (0.009s)
[594.981246] [113/133] (439s left) kms_fbcon_fbt (fbc)
[595.024428] Starting subtest: fbc
[601.410982] Subtest fbc: SUCCESS (6.374s)
[602.473552] [114/133] (431s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-msflip-blt)
[602.567739] Starting subtest: fbc-2p-scndscrn-indfb-msflip-blt
[602.569547] Subtest fbc-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[602.666931] [115/133] (431s left) kms_lease (lease-again)
[602.756918] Starting subtest: lease-again
[602.757841] Starting dynamic subtest: pipe-A-eDP-1
[602.760115] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.002s)
[602.769034] Starting dynamic subtest: pipe-B-eDP-1
[602.769864] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[602.778639] Starting dynamic subtest: pipe-C-eDP-1
[602.779445] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[602.788011] Starting dynamic subtest: pipe-D-eDP-1
[602.788822] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[602.789610] Subtest lease-again: SUCCESS (0.013s)
[602.878748] [116/133] (431s left) kms_chamelium_color (ctm-max)
[602.971837] Subtest ctm-max: SKIP (0.000s)
[603.012865] [117/133] (431s left) gem_softpin (zero)
[603.070386] Starting subtest: zero
[603.075167] Subtest zero: SUCCESS (0.005s)
[603.187980] [118/133] (431s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[603.288542] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[603.290334] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[603.389378] [119/133] (431s left) perf (missing-sample-flags)
[603.476416] Starting subtest: missing-sample-flags
[603.477449] Subtest missing-sample-flags: SUCCESS (0.000s)
[603.520172] [120/133] (430s left) core_auth (getclient-simple)
[603.551849] Starting subtest: getclient-simple
[603.571176] Subtest getclient-simple: SUCCESS (0.020s)
[603.676227] [121/133] (430s left) kms_color (degamma)
[603.764578] Starting subtest: degamma
[603.765502] Starting dynamic subtest: pipe-A-eDP-1
[604.450437] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.686s)
[604.459881] Starting dynamic subtest: pipe-B-eDP-1
[605.904967] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.453s)
[605.913888] Starting dynamic subtest: pipe-C-eDP-1
[607.297555] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.391s)
[607.306395] Starting dynamic subtest: pipe-D-eDP-1
[608.618608] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.319s)
[608.619528] Subtest degamma: SUCCESS (4.853s)
[609.420992] [122/133] (425s left) kms_frontbuffer_tracking (fbcpsr-2p-pri-indfb-multidraw)
[609.513256] Starting subtest: fbcpsr-2p-pri-indfb-multidraw
[609.514789] Subtest fbcpsr-2p-pri-indfb-multidraw: SKIP (0.000s)
[609.601338] [123/133] (425s left) kms_ccs (bad-rotation-90-4-tiled-bmg-ccs)
[609.682197] Starting subtest: bad-rotation-90-4-tiled-bmg-ccs
[609.683778] Subtest bad-rotation-90-4-tiled-bmg-ccs: SKIP (0.000s)
[610.878319] [124/133] (423s left) gem_ctx_create (basic-files)
[610.940789] Starting subtest: basic-files
[612.951160] Subtest basic-files: SUCCESS (2.010s)
[614.302215] [125/133] (420s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[614.530174] Starting subtest: cursor-rapid-movement-256x256
[614.534507] Starting dynamic subtest: pipe-A-eDP-1
[615.065189] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.530s)
[615.093167] Starting dynamic subtest: pipe-D-eDP-1
[616.261813] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.177s)
[616.262654] Subtest cursor-rapid-movement-256x256: SUCCESS (1.732s)
[617.116053] [126/133] (417s left) kms_chamelium_frames (dp-frame-dump)
[617.499098] Subtest dp-frame-dump: SKIP (0.000s)
[618.319881] [127/133] (416s left) gem_mmap_gtt (medium-copy)
[618.380626] Subtest medium-copy: SKIP (0.000s)
[618.437450] [128/133] (416s left) gem_ctx_param (invalid-param-set)
[618.484632] Starting subtest: invalid-param-set
[618.485769] Subtest invalid-param-set: SUCCESS (0.000s)
[618.560407] [129/133] (416s left) kms_psr (fbc-psr2-sprite-plane-move)
[618.647657] Starting subtest: fbc-psr2-sprite-plane-move
[618.648550] Starting dynamic subtest: eDP-1
[618.658210] Dynamic subtest eDP-1: SKIP (0.000s)
[618.659940] Subtest fbc-psr2-sprite-plane-move: SKIP (0.001s)
[618.729287] [130/133] (416s left) gem_exec_balancer (full-pulse)
[618.781749] Starting subtest: full-pulse
[623.117318] Subtest full-pulse: SUCCESS (4.334s)
[623.446667] [131/133] (411s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-gtt)
[623.544437] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-gtt
[623.546150] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[623.651309] [132/133] (411s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[623.765242] Starting subtest: flip-vs-cursor-crc-atomic
[624.418423] Subtest flip-vs-cursor-crc-atomic: SUCCESS (0.652s)
[625.291274] [133/133] (409s left) kms_vblank (invalid)
[625.371553] Starting subtest: invalid
[625.372459] Starting dynamic subtest: pipe-A-eDP-1
[625.772005] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.400s)
[625.772991] Subtest invalid: SUCCESS (0.401s)
[626.605441] Closing watchdogs
results: parsing output: 0/ for test: i915_drm_fdinfo
results: parsing output: 1/ for test: kms_hdr
results: parsing output: 2/ for test: gem_ctx_param
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: gem_render_copy
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: i915_pm_rps
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: kms_rotation_crc
results: parsing output: 10/ for test: syncobj_wait
results: parsing output: 11/ for test: syncobj_timeline
results: parsing output: 12/ for test: kms_psr
results: parsing output: 13/ for test: kms_vblank
results: parsing output: 14/ for test: syncobj_timeline
results: parsing output: 15/ for test: gem_mmap_gtt
results: parsing output: 16/ for test: gem_ctx_param
results: parsing output: 17/ for test: gem_tiled_fence_blits
results: parsing output: 18/ for test: gem_linear_blits
results: parsing output: 19/ for test: kms_big_fb
results: parsing output: 20/ for test: kms_plane_scaling
results: parsing output: 21/ for test: kms_pm_rpm
results: parsing output: 22/ for test: syncobj_timeline
results: parsing output: 23/ for test: kms_flip_scaled_crc
results: parsing output: 24/ for test: gem_ctx_param
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_big_fb
results: parsing output: 27/ for test: gem_ctx_persistence
results: parsing output: 28/ for test: kms_flip_scaled_crc
results: parsing output: 29/ for test: kms_plane_scaling
results: parsing output: 30/ for test: kms_flip
results: parsing output: 31/ for test: gem_exec_parallel
results: parsing output: 32/ for test: gem_exec_alignment
results: parsing output: 33/ for test: kms_psr
results: parsing output: 34/ for test: kms_vrr
results: parsing output: 35/ for test: i915_module_load
results: parsing output: 36/ for test: gem_vm_create
results: parsing output: 37/ for test: kms_ccs
results: parsing output: 38/ for test: kms_dsc
results: parsing output: 39/ for test: kms_flip
results: parsing output: 40/ for test: gem_exec_schedule
results: parsing output: 41/ for test: gem_exec_schedule
results: parsing output: 42/ for test: gem_close
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: kms_plane_alpha_blend
results: parsing output: 45/ for test: kms_psr
results: parsing output: 46/ for test: kms_big_fb
results: parsing output: 47/ for test: dmabuf_sync_file
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: gem_mmap_gtt
results: parsing output: 50/ for test: gem_exec_fence
results: parsing output: 51/ for test: gem_userptr_blits
results: parsing output: 52/ for test: kms_big_fb
results: parsing output: 53/ for test: kms_busy
results: parsing output: 54/ for test: gem_readwrite
results: parsing output: 55/ for test: gem_exec_whisper
results: parsing output: 56/ for test: gem_mmap_offset
results: parsing output: 57/ for test: kms_plane
results: parsing output: 58/ for test: api_intel_allocator
results: parsing output: 59/ for test: gem_mmap
results: parsing output: 60/ for test: syncobj_timeline
results: parsing output: 61/ for test: i915_query
results: parsing output: 62/ for test: syncobj_timeline
results: parsing output: 63/ for test: gem_render_copy
results: parsing output: 64/ for test: kms_flip
results: parsing output: 65/ for test: gem_create
results: parsing output: 66/ for test: gem_barrier_race
results: parsing output: 67/ for test: kms_prop_blob
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: gem_mmap_gtt
results: parsing output: 70/ for test: kms_flip
results: parsing output: 71/ for test: kms_psr
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: syncobj_timeline
results: parsing output: 74/ for test: kms_psr
results: parsing output: 75/ for test: kms_getfb
results: parsing output: 76/ for test: kms_addfb_basic
results: parsing output: 77/ for test: gem_partial_pwrite_pread
results: parsing output: 78/ for test: kms_content_protection
results: parsing output: 79/ for test: kms_cursor_edge_walk
results: parsing output: 80/ for test: kms_pm_rpm
results: parsing output: 81/ for test: kms_atomic
results: parsing output: 82/ for test: kms_async_flips
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: kms_plane_cursor
results: parsing output: 85/ for test: i915_pm_rps
results: parsing output: 86/ for test: kms_addfb_basic
results: parsing output: 87/ for test: gem_exec_whisper
results: parsing output: 88/ for test: perf
results: parsing output: 89/ for test: kms_psr2_sf
results: parsing output: 90/ for test: gem_exec_reloc
results: parsing output: 91/ for test: kms_ccs
results: parsing output: 92/ for test: gem_close_race
results: parsing output: 93/ for test: gen9_exec_parse
results: parsing output: 94/ for test: gem_exec_fence
results: parsing output: 95/ for test: gem_exec_nop
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_big_fb
results: parsing output: 98/ for test: kms_pipe_b_c_ivb
results: parsing output: 99/ for test: kms_fence_pin_leak
results: parsing output: 100/ for test: prime_busy
results: parsing output: 101/ for test: kms_chamelium_hpd
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: gem_unref_active_buffers
results: parsing output: 104/ for test: kms_rmfb
results: parsing output: 105/ for test: gem_exec_reloc
results: parsing output: 106/ for test: kms_cursor_crc
results: parsing output: 107/ for test: kms_debugfs
results: parsing output: 108/ for test: perf_pmu
results: parsing output: 109/ for test: gem_exec_balancer
results: parsing output: 110/ for test: kms_ccs
results: parsing output: 111/ for test: perf
results: parsing output: 112/ for test: kms_fbcon_fbt
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: kms_lease
results: parsing output: 115/ for test: kms_chamelium_color
results: parsing output: 116/ for test: gem_softpin
results: parsing output: 117/ for test: kms_big_fb
results: parsing output: 118/ for test: perf
results: parsing output: 119/ for test: core_auth
results: parsing output: 120/ for test: kms_color
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_ccs
results: parsing output: 123/ for test: gem_ctx_create
results: parsing output: 124/ for test: kms_cursor_crc
results: parsing output: 125/ for test: kms_chamelium_frames
results: parsing output: 126/ for test: gem_mmap_gtt
results: parsing output: 127/ for test: gem_ctx_param
results: parsing output: 128/ for test: kms_psr
results: parsing output: 129/ for test: gem_exec_balancer
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_cursor_legacy
results: parsing output: 132/ for test: kms_vblank
Done.