[56.926432] Initializing watchdogs
[56.926477]   /dev/watchdog0
[56.943503] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[56.948096] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.950637] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.954439] [001/133] (960s left) gem_bad_reloc (negative-reloc-bltcopy)
[57.033776] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[57.101072] [002/133] (960s left) gem_ctx_persistence (engines-hostile-preempt)
[57.162995] Starting subtest: engines-hostile-preempt
[57.165543] Starting dynamic subtest: rcs0
[57.244909] Dynamic subtest rcs0: SUCCESS (0.081s)
[57.258118] Starting dynamic subtest: bcs0
[57.269283] Dynamic subtest bcs0: SUCCESS (0.024s)
[57.276252] Starting dynamic subtest: vcs0
[58.896618] Dynamic subtest vcs0: SUCCESS (1.624s)
[58.905482] Starting dynamic subtest: vcs1
[60.859917] Dynamic subtest vcs1: SUCCESS (1.961s)
[60.869262] Starting dynamic subtest: vecs0
[62.930587] Dynamic subtest vecs0: SUCCESS (2.069s)
[62.938925] Starting dynamic subtest: ccs0
[62.990702] Dynamic subtest ccs0: SUCCESS (0.061s)
[62.993673] Subtest engines-hostile-preempt: SUCCESS (5.825s)
[63.072080] [003/133] (954s left) kms_setmode (basic)
[63.128986] Starting subtest: basic
[63.480741] Starting dynamic subtest: pipe-A-eDP-1
[65.535985] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.053s)
[65.892097] Starting dynamic subtest: pipe-B-eDP-1
[67.974208] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.084s)
[68.289169] Subtest basic: SUCCESS (5.156s)
[69.116129] [004/133] (948s left) kms_lease (lease-get)
[69.206322] Starting subtest: lease-get
[69.208750] Starting dynamic subtest: pipe-A-eDP-1
[69.211111] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[69.220377] Starting dynamic subtest: pipe-B-eDP-1
[69.222508] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[69.229386] Starting dynamic subtest: pipe-C-eDP-1
[69.231562] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[69.238438] Starting dynamic subtest: pipe-D-eDP-1
[69.240560] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[69.242631] Subtest lease-get: SUCCESS (0.007s)
[69.312738] [005/133] (948s left) kms_atomic_interruptible (legacy-dpms)
[69.415098] Starting subtest: legacy-dpms
[69.417518] Starting dynamic subtest: pipe-A-eDP-1
[76.856163] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.438s)
[76.858064] Subtest legacy-dpms: SUCCESS (7.439s)
[77.695322] [006/133] (939s left) kms_plane_scaling (plane-upscale-factor-0-25-with-pixel-format)
[77.779701] Starting subtest: plane-upscale-factor-0-25-with-pixel-format
[77.782069] Starting dynamic subtest: pipe-A
[78.826863] Dynamic subtest pipe-A: SUCCESS (1.049s)
[78.833882] Starting dynamic subtest: pipe-B
[81.002571] Dynamic subtest pipe-B: SUCCESS (2.174s)
[81.011498] Starting dynamic subtest: pipe-C
[82.195450] Dynamic subtest pipe-C: SUCCESS (1.192s)
[82.205827] Starting dynamic subtest: pipe-D
[83.348474] Dynamic subtest pipe-D: SUCCESS (1.153s)
[83.350838] Subtest plane-upscale-factor-0-25-with-pixel-format: SUCCESS (5.570s)
[84.535403] [007/133] (933s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[84.644501] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu
[91.180866] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (6.539s)
[91.300820] [008/133] (926s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs)
[91.410404] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs
[91.412638] Starting dynamic subtest: pipe-A-eDP-1
[91.434536] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[91.443572] Starting dynamic subtest: pipe-B-eDP-1
[91.465127] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[91.472188] Starting dynamic subtest: pipe-C-eDP-1
[91.493601] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[91.500796] Starting dynamic subtest: pipe-D-eDP-1
[91.525125] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[91.529461] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs: SKIP (0.005s)
[93.711841] [009/133] (924s left) gem_fence_thrash (bo-write-verify-threaded-x)
[93.747623] Starting subtest: bo-write-verify-threaded-x
[93.763554] Subtest bo-write-verify-threaded-x: SKIP (0.011s)
[93.829100] [010/133] (923s left) kms_chamelium_hpd (dp-hpd-after-suspend)
[94.218680] Subtest dp-hpd-after-suspend: SKIP (0.000s)
[95.033100] [011/133] (922s left) gem_exec_schedule (submit-golden-slice)
[95.104608] Starting subtest: submit-golden-slice
[95.106892] Starting dynamic subtest: rcs0
[95.132034] Dynamic subtest rcs0: SUCCESS (0.035s)
[95.144932] Starting dynamic subtest: bcs0
[95.166874] Dynamic subtest bcs0: SUCCESS (0.035s)
[95.177252] Starting dynamic subtest: vcs0
[95.200810] Dynamic subtest vcs0: SUCCESS (0.033s)
[95.209676] Starting dynamic subtest: vcs1
[95.230850] Dynamic subtest vcs1: SUCCESS (0.030s)
[95.240473] Starting dynamic subtest: vecs0
[95.267487] Dynamic subtest vecs0: SUCCESS (0.032s)
[95.284384] Starting dynamic subtest: ccs0
[95.298167] Dynamic subtest ccs0: SUCCESS (0.035s)
[95.302393] Subtest submit-golden-slice: SUCCESS (0.202s)
[96.994138] [012/133] (920s left) gem_exec_params (invalid-ring2)
[97.062086] Starting subtest: invalid-ring2
[97.064327] Subtest invalid-ring2: SUCCESS (0.000s)
[97.169650] [013/133] (920s left) gem_pread (self)
[97.226851] Subtest self: SKIP (0.000s)
[97.278604] [014/133] (920s left) kms_big_fb (x-tiled-addfb)
[97.367850] Starting subtest: x-tiled-addfb
[97.369815] Subtest x-tiled-addfb: SUCCESS (0.000s)
[97.473765] [015/133] (920s left) gem_softpin (32b-excludes-last-page)
[97.539510] Starting subtest: 32b-excludes-last-page
[97.544314] Subtest 32b-excludes-last-page: SUCCESS (0.004s)
[97.660140] [016/133] (920s left) kms_hdr (static-toggle-dpms)
[97.759930] Starting subtest: static-toggle-dpms
[97.766561] Subtest static-toggle-dpms: SKIP (0.000s)
[97.834262] [017/133] (920s left) kms_frontbuffer_tracking (psr-1p-pri-indfb-multidraw)
[97.927779] Starting subtest: psr-1p-pri-indfb-multidraw
[120.417543] Subtest psr-1p-pri-indfb-multidraw: SUCCESS (22.493s)
[120.546455] [018/133] (897s left) gem_basic (create-fd-close)
[120.618509] Starting subtest: create-fd-close
[120.622131] Subtest create-fd-close: SUCCESS (0.000s)
[120.717145] [019/133] (897s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-render)
[120.812343] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-render
[120.815841] Subtest psr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[120.913984] [020/133] (897s left) kms_color_pipeline (plane-lut1d-ctm3x4)
[120.998463] Starting subtest: plane-lut1d-ctm3x4
[121.145499] Starting dynamic subtest: pipe-A-plane-0
[121.289380] Dynamic subtest pipe-A-plane-0: SUCCESS (0.144s)
[121.296934] Starting dynamic subtest: pipe-A-plane-1
[121.422849] Dynamic subtest pipe-A-plane-1: SUCCESS (0.133s)
[121.430397] Starting dynamic subtest: pipe-A-plane-2
[121.556128] Dynamic subtest pipe-A-plane-2: SUCCESS (0.133s)
[122.757372] Starting dynamic subtest: pipe-B-plane-0
[122.906524] Dynamic subtest pipe-B-plane-0: SUCCESS (0.155s)
[122.915477] Starting dynamic subtest: pipe-B-plane-1
[123.040425] Dynamic subtest pipe-B-plane-1: SUCCESS (0.133s)
[123.048646] Starting dynamic subtest: pipe-B-plane-2
[123.184935] Dynamic subtest pipe-B-plane-2: SUCCESS (0.144s)
[124.409361] Starting dynamic subtest: pipe-C-plane-0
[124.549634] Dynamic subtest pipe-C-plane-0: SUCCESS (0.144s)
[124.557794] Starting dynamic subtest: pipe-C-plane-1
[124.680781] Dynamic subtest pipe-C-plane-1: SUCCESS (0.132s)
[124.688842] Starting dynamic subtest: pipe-C-plane-2
[124.835732] Dynamic subtest pipe-C-plane-2: SUCCESS (0.155s)
[126.034543] Starting dynamic subtest: pipe-D-plane-0
[126.195070] Dynamic subtest pipe-D-plane-0: SUCCESS (0.166s)
[126.203091] Starting dynamic subtest: pipe-D-plane-1
[126.339253] Dynamic subtest pipe-D-plane-1: SUCCESS (0.144s)
[126.347219] Starting dynamic subtest: pipe-D-plane-2
[126.495448] Dynamic subtest pipe-D-plane-2: SUCCESS (0.155s)
[126.788537] Subtest plane-lut1d-ctm3x4: SUCCESS (5.791s)
[127.613439] [021/133] (890s left) gem_ccs (ctrl-surf-copy)
[127.672264] Starting subtest: ctrl-surf-copy
[127.677043] Subtest ctrl-surf-copy: SKIP (0.000s)
[127.780595] [022/133] (890s left) gem_exec_parallel (engines)
[127.836811] Starting subtest: engines
[127.839220] Starting dynamic subtest: basic
[129.877408] Dynamic subtest basic: SUCCESS (2.038s)
[129.888190] Starting dynamic subtest: contexts
[134.217347] Dynamic subtest contexts: SUCCESS (4.336s)
[134.228435] Starting dynamic subtest: fds
[138.034966] Dynamic subtest fds: SUCCESS (3.815s)
[138.045969] Starting dynamic subtest: userptr
[139.862246] Dynamic subtest userptr: SUCCESS (1.825s)
[139.865054] Subtest engines: SUCCESS (12.024s)
[140.066142] [023/133] (878s left) gem_exec_balancer (sequential)
[140.137040] Starting subtest: sequential
[143.218766] Subtest sequential: SUCCESS (3.081s)
[143.550662] [024/133] (874s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[143.658220] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[143.662752] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[143.769346] [025/133] (874s left) gem_fence_thrash (bo-write-verify-x)
[143.803435] Starting subtest: bo-write-verify-x
[143.821980] Subtest bo-write-verify-x: SKIP (0.020s)
[143.888264] [026/133] (874s left) kms_pipe_crc_basic (hang-read-crc)
[143.977238] Starting subtest: hang-read-crc
[143.979114] Starting dynamic subtest: pipe-A-eDP-1
[146.072561] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.096s)
[146.082257] Starting dynamic subtest: pipe-B-eDP-1
[148.855069] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.779s)
[148.863862] Starting dynamic subtest: pipe-C-eDP-1
[151.481718] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.626s)
[151.491191] Starting dynamic subtest: pipe-D-eDP-1
[154.142284] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.658s)
[154.144924] Subtest hang-read-crc: SUCCESS (10.165s)
[154.975012] [027/133] (863s left) kms_psr2_sf (fbc-psr2-plane-move-sf-dmg-area)
[155.090428] Starting subtest: fbc-psr2-plane-move-sf-dmg-area
[155.092756] Starting dynamic subtest: pipe-A-eDP-1
[155.174245] Dynamic subtest pipe-A-eDP-1: SKIP (0.088s)
[155.183227] Starting dynamic subtest: pipe-B-eDP-1
[156.355090] Dynamic subtest pipe-B-eDP-1: SKIP (1.179s)
[156.359693] Subtest fbc-psr2-plane-move-sf-dmg-area: SKIP (1.270s)
[157.559781] [028/133] (860s left) gem_compute (compute-square)
[157.625737] Starting subtest: compute-square
[157.636954] Subtest compute-square: SUCCESS (0.011s)
[157.791416] [029/133] (860s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-render)
[157.899792] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-render
[162.036310] Subtest fbc-1p-offscreen-pri-shrfb-draw-render: SUCCESS (4.140s)
[163.192253] [030/133] (855s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-gtt)
[163.296045] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-gtt
[163.300070] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[163.403938] [031/133] (855s left) kms_dsc (dsc-with-bpc-formats)
[163.491199] Starting subtest: dsc-with-bpc-formats
[163.601019] Subtest dsc-with-bpc-formats: SKIP (0.003s)
[163.638507] [032/133] (855s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-upscaling)
[163.733301] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-upscaling
[163.737311] Subtest flip-64bpp-yftile-to-16bpp-yftile-upscaling: SKIP (0.000s)
[163.831370] [033/133] (854s left) kms_flip (wf_vblank-ts-check-interruptible)
[163.933051] Starting subtest: wf_vblank-ts-check-interruptible
[163.935311] Starting dynamic subtest: A-eDP1
[172.823200] Dynamic subtest A-eDP1: SUCCESS (8.890s)
[172.831946] Starting dynamic subtest: B-eDP1
[181.751932] Dynamic subtest B-eDP1: SUCCESS (8.928s)
[181.760098] Starting dynamic subtest: C-eDP1
[190.672253] Dynamic subtest C-eDP1: SUCCESS (8.918s)
[190.682018] Starting dynamic subtest: D-eDP1
[199.601242] Dynamic subtest D-eDP1: SUCCESS (8.926s)
[199.605942] Subtest wf_vblank-ts-check-interruptible: SUCCESS (35.673s)
[200.828920] [034/133] (817s left) gem_eio (unwedge-stress)
[201.068832] Starting subtest: unwedge-stress
[201.071049] Starting dynamic subtest: render
[206.040058] Dynamic subtest render: SUCCESS (4.968s)
[206.054998] Starting dynamic subtest: bsd1
[226.373658] Dynamic subtest bsd1: SUCCESS (20.332s)
[226.388936] Starting dynamic subtest: bsd2
[248.400761] Dynamic subtest bsd2: SUCCESS (22.025s)
[248.416517] Starting dynamic subtest: blt
[248.420693] Dynamic subtest blt: SKIP (0.000s)
[248.428636] Starting dynamic subtest: vebox
[268.413232] Dynamic subtest vebox: SUCCESS (20.011s)
[268.415589] Subtest unwedge-stress: SUCCESS (67.341s)
[269.878103] [FACT gem_eio (unwedge-stress)] new: kernel.kmod_is_loaded.vgem: true
[269.882181] [035/133] (748s left) gem_close (basic)
[269.941622] Starting subtest: basic
[269.944084] Subtest basic: SUCCESS (0.000s)
[270.062998] [036/133] (748s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[270.155671] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[270.846062] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SUCCESS (0.692s)
[271.005090] [037/133] (747s left) i915_query (query-regions-unallocated)
[271.062083] Starting subtest: query-regions-unallocated
[271.063973] Subtest query-regions-unallocated: SUCCESS (0.000s)
[271.173799] [038/133] (747s left) i915_drm_fdinfo (virtual-busy-hang)
[271.209111] Starting subtest: virtual-busy-hang
[271.213614] Subtest virtual-busy-hang: SKIP (0.000s)
[271.276594] [039/133] (747s left) gem_ctx_persistence (legacy-engines-queued)
[271.335939] Starting subtest: legacy-engines-queued
[271.338334] Starting dynamic subtest: render
[271.398493] Dynamic subtest render: SUCCESS (0.062s)
[271.413431] Starting dynamic subtest: bsd1
[272.882584] Dynamic subtest bsd1: SUCCESS (1.480s)
[272.894429] Starting dynamic subtest: bsd2
[274.867033] Dynamic subtest bsd2: SUCCESS (1.981s)
[274.878453] Starting dynamic subtest: blt
[274.920729] Dynamic subtest blt: SUCCESS (0.052s)
[274.932586] Starting dynamic subtest: vebox
[276.851436] Dynamic subtest vebox: SUCCESS (1.927s)
[276.854335] Subtest legacy-engines-queued: SUCCESS (5.515s)
[276.930855] [040/133] (742s left) kms_debugfs (display-on-read-all)
[277.028931] Starting subtest: display-on-read-all
[277.194929] Subtest display-on-read-all: SUCCESS (0.167s)
[277.320639] [041/133] (741s left) dumb_buffer (create-valid-dumb)
[277.375983] Starting subtest: create-valid-dumb
[277.378156] Subtest create-valid-dumb: SUCCESS (0.000s)
[277.467562] [042/133] (741s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-move)
[277.555213] Starting subtest: fbc-2p-primscrn-spr-indfb-move
[277.559176] Subtest fbc-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[277.647408] [043/133] (741s left) kms_big_fb (4-tiled-16bpp-rotate-0)
[277.741290] Starting subtest: 4-tiled-16bpp-rotate-0
[278.965600] Subtest 4-tiled-16bpp-rotate-0: SUCCESS (1.224s)
[279.074253] [044/133] (740s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-wc)
[279.173488] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-wc
[285.674951] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (6.505s)
[285.812113] [045/133] (733s left) perf_pmu (busy-check-all)
[285.858714] Starting subtest: busy-check-all
[285.860712] Starting dynamic subtest: rcs0
[286.417510] Dynamic subtest rcs0: SUCCESS (0.559s)
[286.428859] Starting dynamic subtest: bcs0
[286.968718] Dynamic subtest bcs0: SUCCESS (0.550s)
[286.980496] Starting dynamic subtest: vcs0
[288.916607] Dynamic subtest vcs0: SUCCESS (1.944s)
[288.928906] Starting dynamic subtest: vcs1
[290.901342] Dynamic subtest vcs1: SUCCESS (1.981s)
[290.913086] Starting dynamic subtest: vecs0
[292.884620] Dynamic subtest vecs0: SUCCESS (1.981s)
[292.895938] Starting dynamic subtest: ccs0
[293.438263] Dynamic subtest ccs0: SUCCESS (0.553s)
[293.440723] Subtest busy-check-all: SUCCESS (7.582s)
[293.555116] [046/133] (725s left) gem_lmem_swapping (heavy-random)
[293.603692] Subtest heavy-random: SKIP (0.000s)
[293.642401] [047/133] (725s left) kms_cursor_legacy (cursora-vs-flipb-toggle)
[293.778486] Starting subtest: cursorA-vs-flipB-toggle
[293.783091] Subtest cursorA-vs-flipB-toggle: SKIP (0.001s)
[293.921685] [048/133] (725s left) api_intel_allocator (reserve-simple)
[293.994063] Starting subtest: reserve-simple
[293.996395] Subtest reserve-simple: SUCCESS (0.002s)
[294.115702] [049/133] (725s left) perf (mi-rpc)
[294.249701] Starting subtest: mi-rpc
[294.253764] Subtest mi-rpc: SKIP (0.000s)
[294.293899] [050/133] (725s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[294.389063] Starting subtest: bad-pixel-format-y-tiled-ccs
[294.391304] Starting dynamic subtest: pipe-A-eDP-1
[294.412867] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[294.421710] Starting dynamic subtest: pipe-B-eDP-1
[294.438531] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[294.446170] Starting dynamic subtest: pipe-C-eDP-1
[294.463251] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[294.471022] Starting dynamic subtest: pipe-D-eDP-1
[294.488280] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[294.491680] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.004s)
[296.707491] [051/133] (722s left) kms_big_fb (yf-tiled-64bpp-rotate-180)
[296.805484] Starting subtest: yf-tiled-64bpp-rotate-180
[296.809833] Subtest yf-tiled-64bpp-rotate-180: SKIP (0.000s)
[296.897550] [052/133] (722s left) gem_ctx_param (get-priority-new-ctx)
[296.955535] Starting subtest: get-priority-new-ctx
[296.957912] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[297.075312] [053/133] (722s left) vgem_slow (nohang)
[297.116591] Starting subtest: nohang
[307.314940] Subtest nohang: SUCCESS (10.198s)
[307.396576] [054/133] (712s left) gem_exec_balancer (full-pulse)
[307.456537] Starting subtest: full-pulse
[311.801599] Subtest full-pulse: SUCCESS (4.345s)
[312.111223] [055/133] (707s left) gem_ctx_bad_destroy (double-destroy)
[312.174998] Starting subtest: double-destroy
[312.177459] Subtest double-destroy: SUCCESS (0.000s)
[312.265849] [056/133] (707s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-render)
[312.368623] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-render
[316.052031] Subtest fbc-1p-primscrn-spr-indfb-draw-render: SUCCESS (3.687s)
[317.232900] [057/133] (702s left) vgem_basic (second-client)
[317.285105] Starting subtest: second-client
[317.304054] Subtest second-client: SUCCESS (0.022s)
[317.369524] [058/133] (702s left) kms_psr (pr-primary-render)
[317.468075] Starting subtest: pr-primary-render
[317.472604] Subtest pr-primary-render: SKIP (0.000s)
[317.546877] [059/133] (702s left) kms_vblank (ts-continuation-modeset-hang)
[317.630757] Starting subtest: ts-continuation-modeset-hang
[317.632732] Starting dynamic subtest: pipe-A-eDP-1
[343.699647] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.066s)
[343.715355] Starting dynamic subtest: pipe-D-eDP-1
[368.650053] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.948s)
[368.652408] Subtest ts-continuation-modeset-hang: SUCCESS (51.019s)
[369.458342] [060/133] (650s left) kms_psr_stress_test (flip-primary-invalidate-overlay)
[369.554336] Starting subtest: flip-primary-invalidate-overlay
[381.251298] Subtest flip-primary-invalidate-overlay: SUCCESS (11.699s)
[381.407392] [061/133] (638s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-wc)
[381.508066] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-wc
[381.511497] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[381.625714] [062/133] (638s left) gem_pxp (verify-pxp-stale-ctx-execution)
[381.749517] Starting subtest: verify-pxp-stale-ctx-execution
[382.812862] Subtest verify-pxp-stale-ctx-execution: SUCCESS (1.061s)
[382.942775] [063/133] (637s left) gen9_exec_parse (secure-batches)
[383.001134] Subtest secure-batches: SKIP (0.000s)
[383.062414] [064/133] (636s left) perf (per-context-mode-unprivileged)
[383.153596] Starting subtest: per-context-mode-unprivileged
[383.158081] Subtest per-context-mode-unprivileged: SKIP (0.000s)
[383.193790] [065/133] (636s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[383.292505] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[383.296786] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.000s)
[383.362976] [066/133] (636s left) gem_mmap (bad-offset)
[383.420477] Subtest bad-offset: SKIP (0.000s)
[383.474907] [067/133] (636s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[383.565554] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[390.583697] Subtest fbcpsr-rgb101010-draw-pwrite: SUCCESS (7.021s)
[390.695857] [068/133] (629s left) kms_big_fb (y-tiled-32bpp-rotate-270)
[390.804169] Starting subtest: y-tiled-32bpp-rotate-270
[390.808123] Subtest y-tiled-32bpp-rotate-270: SKIP (0.000s)
[390.903115] [069/133] (629s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-pgflip-blt)
[390.998724] Starting subtest: psr-2p-primscrn-indfb-pgflip-blt
[391.002785] Subtest psr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[391.127638] [070/133] (629s left) kms_plane (pixel-format-yf-tiled-modifier-source-clamping)
[391.225979] Starting subtest: pixel-format-yf-tiled-modifier-source-clamping
[391.230052] Subtest pixel-format-yf-tiled-modifier-source-clamping: SKIP (0.000s)
[391.303571] [071/133] (628s left) sysfs_preempt_timeout (timeout)
[391.354623] Starting subtest: timeout
[391.356810] Starting dynamic subtest: vecs0
[400.917488] Dynamic subtest vecs0: SUCCESS (9.558s)
[400.928994] Starting dynamic subtest: ccs0
[402.218214] Dynamic subtest ccs0: SUCCESS (1.300s)
[402.227858] Starting dynamic subtest: vcs0
[411.919226] Dynamic subtest vcs0: SUCCESS (9.695s)
[411.931844] Starting dynamic subtest: bcs0
[413.153268] Dynamic subtest bcs0: SUCCESS (1.235s)
[413.163736] Starting dynamic subtest: rcs0
[414.366397] Dynamic subtest rcs0: SUCCESS (1.210s)
[414.377952] Starting dynamic subtest: vcs1
[423.889067] Dynamic subtest vcs1: SUCCESS (9.519s)
[423.891870] Subtest timeout: SUCCESS (32.531s)
[423.964951] [072/133] (596s left) i915_pm_rpm (gem-evict-pwrite)
[425.992778] Starting subtest: gem-evict-pwrite
[425.997399] Subtest gem-evict-pwrite: SKIP (0.000s)
[426.390356] [073/133] (593s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[426.491388] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[426.495433] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[426.628459] [074/133] (593s left) syncobj_basic (bad-pad-handle-to-fd)
[426.696902] Starting subtest: bad-pad-handle-to-fd
[426.699292] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[426.790340] [075/133] (593s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted-signa
[426.847690] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted-signaled
[426.849923] Subtest multi-wait-for-submit-available-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[426.939605] [076/133] (593s left) syncobj_timeline (single-wait-available-submitted)
[426.996992] Starting subtest: single-wait-available-submitted
[426.999365] Subtest single-wait-available-submitted: SUCCESS (0.000s)
[427.136056] [077/133] (593s left) gem_vm_create (create-ext)
[427.204620] Starting subtest: create-ext
[427.209627] Subtest create-ext: SUCCESS (0.004s)
[427.339778] [078/133] (593s left) kms_ccs (random-ccs-data-4-tiled-dg2-mc-ccs)
[427.435574] Starting subtest: random-ccs-data-4-tiled-dg2-mc-ccs
[427.437459] Starting dynamic subtest: pipe-A-eDP-1
[427.458693] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[427.469181] Starting dynamic subtest: pipe-B-eDP-1
[427.488930] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[427.497060] Starting dynamic subtest: pipe-C-eDP-1
[427.516552] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[427.524727] Starting dynamic subtest: pipe-D-eDP-1
[427.544221] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[427.547770] Subtest random-ccs-data-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[429.644987] [079/133] (591s left) gem_exec_params (no-vebox)
[429.700671] Starting subtest: no-vebox
[429.703075] Subtest no-vebox: SUCCESS (0.002s)
[430.995131] [080/133] (589s left) kms_flip (2x-flip-vs-fences-interruptible)
[431.085153] Starting subtest: 2x-flip-vs-fences-interruptible
[431.089175] Subtest 2x-flip-vs-fences-interruptible: SKIP (0.000s)
[431.167118] [081/133] (589s left) kms_async_flips (async-flip-with-page-flip-events-tiled)
[431.250427] Starting subtest: async-flip-with-page-flip-events-tiled
[431.625033] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[434.405070] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.778s)
[434.416645] Starting dynamic subtest: pipe-A-eDP-1-4
[436.579595] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.173s)
[436.591726] Starting dynamic subtest: pipe-A-eDP-1-4
[438.590265] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.009s)
[438.603680] Starting dynamic subtest: pipe-A-eDP-1-x
[440.791302] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.199s)
[441.163335] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[443.935318] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.778s)
[443.944824] Starting dynamic subtest: pipe-B-eDP-1-4
[446.121429] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.184s)
[446.131072] Starting dynamic subtest: pipe-B-eDP-1-4
[448.131901] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.009s)
[448.142072] Starting dynamic subtest: pipe-B-eDP-1-x
[450.321942] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.189s)
[450.705844] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[453.464871] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.766s)
[453.474845] Starting dynamic subtest: pipe-C-eDP-1-4
[455.628427] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.162s)
[455.637929] Starting dynamic subtest: pipe-C-eDP-1-4
[457.638822] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s)
[457.648956] Starting dynamic subtest: pipe-C-eDP-1-x
[459.839944] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.200s)
[460.214078] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[462.997674] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.789s)
[463.007630] Starting dynamic subtest: pipe-D-eDP-1-4
[465.170072] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.173s)
[465.181917] Starting dynamic subtest: pipe-D-eDP-1-4
[467.180849] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.009s)
[467.191430] Starting dynamic subtest: pipe-D-eDP-1-x
[469.370752] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.189s)
[469.373437] Subtest async-flip-with-page-flip-events-tiled: SUCCESS (38.120s)
[470.585373] [082/133] (550s left) kms_big_fb (4-tiled-64bpp-rotate-90)
[470.691055] Starting subtest: 4-tiled-64bpp-rotate-90
[470.711076] Subtest 4-tiled-64bpp-rotate-90: SKIP (0.019s)
[470.829188] [083/133] (550s left) kms_psr (psr2-cursor-render)
[470.935402] Starting subtest: psr2-cursor-render
[470.937589] Starting dynamic subtest: eDP-1
[472.654545] Dynamic subtest eDP-1: SUCCESS (1.719s)
[472.656671] Subtest psr2-cursor-render: SUCCESS (1.720s)
[473.472843] [084/133] (547s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs)
[473.586682] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs
[473.589070] Starting dynamic subtest: pipe-A-eDP-1
[474.299780] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.704s)
[474.309700] Starting dynamic subtest: pipe-B-eDP-1
[476.078950] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.778s)
[476.088906] Starting dynamic subtest: pipe-C-eDP-1
[477.827908] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.748s)
[477.836569] Starting dynamic subtest: pipe-D-eDP-1
[479.640274] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.810s)
[479.642651] Subtest random-ccs-data-4-tiled-mtl-rc-ccs: SUCCESS (6.045s)
[480.785939] [085/133] (540s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size)
[480.893274] Starting subtest: basic-busy-flip-before-cursor-varying-size
[480.897716] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s)
[481.039803] [086/133] (539s left) syncobj_timeline (invalid-wait-illegal-handle)
[481.088991] Starting subtest: invalid-wait-illegal-handle
[481.090925] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[481.180559] [087/133] (539s left) gem_reg_read (bad-register)
[481.237170] Starting subtest: bad-register
[481.239373] Subtest bad-register: SUCCESS (0.000s)
[481.330433] [088/133] (539s left) kms_big_fb (linear-16bpp-rotate-90)
[481.421079] Starting subtest: linear-16bpp-rotate-90
[481.440296] Subtest linear-16bpp-rotate-90: SKIP (0.019s)
[481.567389] [089/133] (539s left) kms_pipe_b_c_ivb (pipe-b-dpms-off-modeset-pipe-c)
[481.625276] Subtest pipe-B-dpms-off-modeset-pipe-C: SKIP (0.000s)
[481.691856] [090/133] (539s left) gem_mmap_gtt (medium-copy-xy)
[481.747448] Subtest medium-copy-XY: SKIP (0.000s)
[481.810729] [091/133] (539s left) kms_plane_scaling (plane-downscale-factor-0-75-with-pixel-format)
[481.911104] Starting subtest: plane-downscale-factor-0-75-with-pixel-format
[481.912880] Starting dynamic subtest: pipe-A
[483.095097] Dynamic subtest pipe-A: SUCCESS (1.185s)
[483.105082] Starting dynamic subtest: pipe-B
[485.321602] Dynamic subtest pipe-B: SUCCESS (2.225s)
[485.330426] Starting dynamic subtest: pipe-C
[486.484777] Dynamic subtest pipe-C: SUCCESS (1.162s)
[486.494518] Starting dynamic subtest: pipe-D
[487.633886] Dynamic subtest pipe-D: SUCCESS (1.148s)
[487.636063] Subtest plane-downscale-factor-0-75-with-pixel-format: SUCCESS (5.724s)
[488.829636] [092/133] (532s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic)
[488.947120] Starting subtest: flip-vs-cursor-busy-crc-atomic
[489.735684] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.789s)
[490.563768] [093/133] (530s left) gem_spin_batch (resubmit-all)
[490.629125] Starting subtest: resubmit-all
[490.631571] Starting dynamic subtest: rcs0
[490.640285] Dynamic subtest rcs0: SUCCESS (0.011s)
[490.652653] Starting dynamic subtest: bcs0
[490.654935] Dynamic subtest bcs0: SUCCESS (0.002s)
[490.664103] Starting dynamic subtest: vcs0
[490.666091] Dynamic subtest vcs0: SUCCESS (0.002s)
[490.674819] Starting dynamic subtest: vcs1
[490.676835] Dynamic subtest vcs1: SUCCESS (0.002s)
[490.685576] Starting dynamic subtest: vecs0
[490.687567] Dynamic subtest vecs0: SUCCESS (0.001s)
[490.696252] Starting dynamic subtest: ccs0
[490.698197] Dynamic subtest ccs0: SUCCESS (0.003s)
[490.700124] Subtest resubmit-all: SUCCESS (0.026s)
[491.985340] [094/133] (529s left) syncobj_basic (bad-pad-fd-to-handle)
[492.044669] Starting subtest: bad-pad-fd-to-handle
[492.046666] Subtest bad-pad-fd-to-handle: SUCCESS (0.000s)
[492.126811] [095/133] (529s left) kms_psr (fbc-pr-cursor-plane-onoff)
[492.211233] Starting subtest: fbc-pr-cursor-plane-onoff
[492.215636] Subtest fbc-pr-cursor-plane-onoff: SKIP (0.000s)
[492.283907] [096/133] (529s left) gem_exec_fence (syncobj-timeline-repeat)
[492.850980] Starting subtest: syncobj-timeline-repeat
[493.084859] Subtest syncobj-timeline-repeat: SUCCESS (0.237s)
[493.988260] [097/133] (527s left) kms_frontbuffer_tracking (fbc-1p-rte)
[494.091564] Starting subtest: fbc-1p-rte
[499.516100] Subtest fbc-1p-rte: SUCCESS (5.428s)
[500.645718] [098/133] (520s left) vgem_basic (debugfs)
[500.687898] Starting subtest: debugfs
[500.690204] Subtest debugfs: SUCCESS (0.000s)
[500.746572] [099/133] (520s left) perf (low-oa-exponent-permissions)
[500.841727] Starting subtest: low-oa-exponent-permissions
[500.879661] Subtest low-oa-exponent-permissions: SUCCESS (0.038s)
[500.919815] [100/133] (520s left) gem_softpin (softpin)
[500.970117] Starting subtest: softpin
[533.257105] Subtest softpin: SUCCESS (32.281s)
[533.379320] [101/133] (488s left) i915_getparams_basic (basic-subslice-total)
[533.440180] Starting subtest: basic-subslice-total
[533.444691] Subtest basic-subslice-total: SUCCESS (0.000s)
[533.539287] [102/133] (488s left) kms_cursor_crc (cursor-offscreen-512x170)
[533.841161] Starting subtest: cursor-offscreen-512x170
[533.845651] Subtest cursor-offscreen-512x170: SKIP (0.001s)
[534.003200] [103/133] (487s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[534.062591] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[534.064842] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.000s)
[534.178426] [104/133] (487s left) kms_vblank (crtc-id)
[534.273041] Starting subtest: crtc-id
[534.275033] Starting dynamic subtest: pipe-A-eDP-1
[534.724512] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.451s)
[534.739857] Starting dynamic subtest: pipe-D-eDP-1
[535.859753] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.133s)
[535.861900] Subtest crtc-id: SUCCESS (1.587s)
[536.705248] [105/133] (485s left) kms_content_protection (dp-mst-type-0)
[536.847346] Starting subtest: dp-mst-type-0
[536.851747] Subtest dp-mst-type-0: SKIP (0.000s)
[536.954507] [106/133] (484s left) perf_pmu (busy-start)
[536.995121] Starting subtest: busy-start
[536.997302] Starting dynamic subtest: rcs0
[539.544412] Dynamic subtest rcs0: SUCCESS (2.550s)
[539.556095] Starting dynamic subtest: bcs0
[542.097331] Dynamic subtest bcs0: SUCCESS (2.550s)
[542.109809] Starting dynamic subtest: vcs0
[545.875992] Dynamic subtest vcs0: SUCCESS (3.775s)
[545.888675] Starting dynamic subtest: vcs1
[549.908407] Dynamic subtest vcs1: SUCCESS (4.029s)
[549.920753] Starting dynamic subtest: vecs0
[553.940066] Dynamic subtest vecs0: SUCCESS (4.029s)
[553.952176] Starting dynamic subtest: ccs0
[556.491709] Dynamic subtest ccs0: SUCCESS (2.550s)
[556.494328] Subtest busy-start: SUCCESS (19.498s)
[556.594304] [107/133] (465s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-msflip-blt)
[556.705929] Starting subtest: fbcpsr-1p-primscrn-indfb-msflip-blt
[563.977609] Subtest fbcpsr-1p-primscrn-indfb-msflip-blt: SUCCESS (7.273s)
[564.105625] [108/133] (457s left) gem_exec_schedule (in-order)
[564.175953] Starting subtest: in-order
[564.178213] Starting dynamic subtest: rcs0
[564.271798] Dynamic subtest rcs0: SUCCESS (0.101s)
[564.281633] Starting dynamic subtest: vcs0
[564.358921] Dynamic subtest vcs0: SUCCESS (0.086s)
[564.371657] Starting dynamic subtest: vcs1
[564.442871] Dynamic subtest vcs1: SUCCESS (0.083s)
[564.463198] Starting dynamic subtest: vecs0
[564.530960] Dynamic subtest vecs0: SUCCESS (0.087s)
[564.545574] Starting dynamic subtest: ccs0
[564.627533] Dynamic subtest ccs0: SUCCESS (0.093s)
[564.630615] Subtest in-order: SUCCESS (0.457s)
[565.996557] [109/133] (455s left) sriov_basic (enable-vfs-bind-unbind-each)
[566.053026] Starting subtest: enable-vfs-bind-unbind-each
[566.055413] Starting dynamic subtest: numvfs-1
[566.135430] Dynamic subtest numvfs-1: FAIL (0.033s)
[566.146527] Starting dynamic subtest: numvfs-2
[566.217581] Dynamic subtest numvfs-2: FAIL (0.003s)
[566.227898] Starting dynamic subtest: numvfs-3
[566.296474] Dynamic subtest numvfs-3: FAIL (0.003s)
[566.305563] Starting dynamic subtest: numvfs-4
[566.374428] Dynamic subtest numvfs-4: FAIL (0.003s)
[566.383820] Starting dynamic subtest: numvfs-5
[566.451917] Dynamic subtest numvfs-5: FAIL (0.003s)
[566.461685] Starting dynamic subtest: numvfs-6
[566.535815] Dynamic subtest numvfs-6: FAIL (0.004s)
[566.546549] Starting dynamic subtest: numvfs-7
[566.618308] Dynamic subtest numvfs-7: FAIL (0.003s)
[566.628223] Starting dynamic subtest: numvfs-random
[566.701317] Dynamic subtest numvfs-random: FAIL (0.003s)
[566.703433] Subtest enable-vfs-bind-unbind-each: FAIL (0.062s)
[566.743564] [110/133] (455s left) kms_psr (fbc-psr2-cursor-render)
[566.832680] Starting subtest: fbc-psr2-cursor-render
[566.835062] Starting dynamic subtest: eDP-1
[566.839077] Dynamic subtest eDP-1: SKIP (0.000s)
[566.843483] Subtest fbc-psr2-cursor-render: SKIP (0.001s)
[566.902659] [111/133] (455s left) syncobj_basic (bad-fd-to-handle)
[566.953943] Starting subtest: bad-fd-to-handle
[566.955616] Subtest bad-fd-to-handle: SUCCESS (0.000s)
[567.046681] [112/133] (455s left) syncobj_eventfd (invalid-illegal-handle)
[567.103925] Starting subtest: invalid-illegal-handle
[567.105649] Subtest invalid-illegal-handle: SUCCESS (0.000s)
[567.196822] [113/133] (454s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[567.296953] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[567.300282] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[567.390891] [114/133] (454s left) kms_frontbuffer_tracking (fbcpsr-1p-shrfb-fliptrack-mmap-gtt)
[567.500257] Starting subtest: fbcpsr-1p-shrfb-fliptrack-mmap-gtt
[567.503670] Subtest fbcpsr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[567.616723] [115/133] (454s left) kms_vblank (invalid)
[567.707393] Starting subtest: invalid
[567.709126] Starting dynamic subtest: pipe-A-eDP-1
[568.125172] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.418s)
[568.127448] Subtest invalid: SUCCESS (0.419s)
[568.950880] [116/133] (453s left) kms_cursor_crc (cursor-rapid-movement-512x512)
[569.238977] Starting subtest: cursor-rapid-movement-512x512
[569.243792] Subtest cursor-rapid-movement-512x512: SKIP (0.001s)
[569.404093] [117/133] (452s left) kms_getfb (getfb-handle-protection)
[569.508101] Starting subtest: getfb-handle-protection
[569.530223] Subtest getfb-handle-protection: SUCCESS (0.023s)
[569.636930] [118/133] (452s left) gem_exec_params (invalid-bsd1-flag-on-vebox)
[569.701832] Starting subtest: invalid-bsd1-flag-on-vebox
[569.704232] Subtest invalid-bsd1-flag-on-vebox: SUCCESS (0.001s)
[569.805407] [119/133] (452s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[569.900911] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[569.904617] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[570.006513] [120/133] (452s left) kms_psr (fbc-psr-cursor-mmap-gtt)
[570.097462] Starting subtest: fbc-psr-cursor-mmap-gtt
[570.099477] Starting dynamic subtest: eDP-1
[570.103086] Dynamic subtest eDP-1: SKIP (0.000s)
[570.106737] Subtest fbc-psr-cursor-mmap-gtt: SKIP (0.001s)
[570.170545] [121/133] (452s left) kms_pm_rpm (legacy-planes-dpms)
[572.104476] Starting subtest: legacy-planes-dpms
[572.107279] Starting dynamic subtest: plane-65
[580.463809] Dynamic subtest plane-65: SUCCESS (8.358s)
[580.476482] Starting dynamic subtest: plane-95
[589.052516] Dynamic subtest plane-95: SUCCESS (8.585s)
[589.065875] Starting dynamic subtest: plane-125
[597.668169] Dynamic subtest plane-125: SUCCESS (8.613s)
[597.679791] Starting dynamic subtest: plane-135
[606.300445] Dynamic subtest plane-135: SUCCESS (8.629s)
[606.302949] Subtest legacy-planes-dpms: SUCCESS (34.197s)
[606.727546] [122/133] (415s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[606.856026] Starting subtest: cursorA-vs-flipA-legacy
[617.973958] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.117s)
[618.816899] [123/133] (403s left) gem_exec_alignment (many)
[618.875236] Starting subtest: many
[641.434333] Subtest many: SUCCESS (22.556s)
[643.907721] [124/133] (378s left) perf (enable-disable)
[644.073762] Starting subtest: enable-disable
[644.076212] Starting dynamic subtest: 0-rcs0
[646.351059] Dynamic subtest 0-rcs0: SUCCESS (2.276s)
[646.360898] Starting dynamic subtest: 1-vcs1
[650.821153] Dynamic subtest 1-vcs1: SUCCESS (4.469s)
[650.823822] Subtest enable-disable: SUCCESS (6.746s)
[650.873744] [125/133] (371s left) gem_softpin (noreloc)
[650.938346] Starting subtest: noreloc
[656.360479] Subtest noreloc: SUCCESS (5.420s)
[656.485130] [126/133] (366s left) kms_plane (pixel-format-4-tiled-bmg-ccs-modifier)
[656.583185] Starting subtest: pixel-format-4-tiled-bmg-ccs-modifier
[656.587335] Subtest pixel-format-4-tiled-bmg-ccs-modifier: SKIP (0.000s)
[656.700493] [127/133] (366s left) kms_prop_blob (invalid-set-prop-any)
[656.769638] Starting subtest: invalid-set-prop-any
[656.771938] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[656.870604] [128/133] (365s left) gem_exec_whisper (basic-contexts-forked)
[656.948315] Starting subtest: basic-contexts-forked
[681.331761] Subtest basic-contexts-forked: SUCCESS (24.379s)
[681.511154] [129/133] (341s left) kms_ccs (crc-primary-basic-y-tiled-gen12-mc-ccs)
[681.616063] Starting subtest: crc-primary-basic-y-tiled-gen12-mc-ccs
[681.618283] Starting dynamic subtest: pipe-A-eDP-1
[681.640883] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[681.651251] Starting dynamic subtest: pipe-B-eDP-1
[681.672730] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[681.682949] Starting dynamic subtest: pipe-C-eDP-1
[681.704307] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[681.713458] Starting dynamic subtest: pipe-D-eDP-1
[681.735757] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[681.739822] Subtest crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.006s)
[683.765293] [130/133] (339s left) gem_mmap_gtt (zero-extend)
[683.818578] Subtest zero-extend: SKIP (0.000s)
[683.878930] [131/133] (339s left) kms_pm_dc (dc6-dpms)
[684.980075] Starting subtest: dc6-dpms
[687.045849] Subtest dc6-dpms: SUCCESS (2.065s)
[687.124474] [132/133] (335s left) kms_chamelium_color (degamma)
[687.227643] Subtest degamma: SKIP (0.000s)
[687.272769] [133/133] (335s left) api_intel_bb (intel-bb-blit-none)
[687.332624] Starting subtest: intel-bb-blit-none
[691.655041] Subtest intel-bb-blit-none: SUCCESS (4.323s)
[691.772632] Closing watchdogs
results: parsing output: 0/ for test: gem_bad_reloc
results: parsing output: 1/ for test: gem_ctx_persistence
results: parsing output: 2/ for test: kms_setmode
results: parsing output: 3/ for test: kms_lease
results: parsing output: 4/ for test: kms_atomic_interruptible
results: parsing output: 5/ for test: kms_plane_scaling
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: kms_ccs
results: parsing output: 8/ for test: gem_fence_thrash
results: parsing output: 9/ for test: kms_chamelium_hpd
results: parsing output: 10/ for test: gem_exec_schedule
results: parsing output: 11/ for test: gem_exec_params
results: parsing output: 12/ for test: gem_pread
results: parsing output: 13/ for test: kms_big_fb
results: parsing output: 14/ for test: gem_softpin
results: parsing output: 15/ for test: kms_hdr
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: gem_basic
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_color_pipeline
results: parsing output: 20/ for test: gem_ccs
results: parsing output: 21/ for test: gem_exec_parallel
results: parsing output: 22/ for test: gem_exec_balancer
results: parsing output: 23/ for test: kms_big_fb
results: parsing output: 24/ for test: gem_fence_thrash
results: parsing output: 25/ for test: kms_pipe_crc_basic
results: parsing output: 26/ for test: kms_psr2_sf
results: parsing output: 27/ for test: gem_compute
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_dsc
results: parsing output: 31/ for test: kms_flip_scaled_crc
results: parsing output: 32/ for test: kms_flip
results: parsing output: 33/ for test: gem_eio
results: parsing output: 34/ for test: gem_close
results: parsing output: 35/ for test: kms_big_fb
results: parsing output: 36/ for test: i915_query
results: parsing output: 37/ for test: i915_drm_fdinfo
results: parsing output: 38/ for test: gem_ctx_persistence
results: parsing output: 39/ for test: kms_debugfs
results: parsing output: 40/ for test: dumb_buffer
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_big_fb
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: perf_pmu
results: parsing output: 45/ for test: gem_lmem_swapping
results: parsing output: 46/ for test: kms_cursor_legacy
results: parsing output: 47/ for test: api_intel_allocator
results: parsing output: 48/ for test: perf
results: parsing output: 49/ for test: kms_ccs
results: parsing output: 50/ for test: kms_big_fb
results: parsing output: 51/ for test: gem_ctx_param
results: parsing output: 52/ for test: vgem_slow
results: parsing output: 53/ for test: gem_exec_balancer
results: parsing output: 54/ for test: gem_ctx_bad_destroy
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: vgem_basic
results: parsing output: 57/ for test: kms_psr
results: parsing output: 58/ for test: kms_vblank
results: parsing output: 59/ for test: kms_psr_stress_test
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: gem_pxp
results: parsing output: 62/ for test: gen9_exec_parse
results: parsing output: 63/ for test: perf
results: parsing output: 64/ for test: kms_flip_scaled_crc
results: parsing output: 65/ for test: gem_mmap
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_big_fb
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: kms_plane
results: parsing output: 70/ for test: sysfs_preempt_timeout
results: parsing output: 71/ for test: i915_pm_rpm
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: syncobj_basic
results: parsing output: 74/ for test: syncobj_timeline
results: parsing output: 75/ for test: syncobj_timeline
results: parsing output: 76/ for test: gem_vm_create
results: parsing output: 77/ for test: kms_ccs
results: parsing output: 78/ for test: gem_exec_params
results: parsing output: 79/ for test: kms_flip
results: parsing output: 80/ for test: kms_async_flips
results: parsing output: 81/ for test: kms_big_fb
results: parsing output: 82/ for test: kms_psr
results: parsing output: 83/ for test: kms_ccs
results: parsing output: 84/ for test: kms_cursor_legacy
results: parsing output: 85/ for test: syncobj_timeline
results: parsing output: 86/ for test: gem_reg_read
results: parsing output: 87/ for test: kms_big_fb
results: parsing output: 88/ for test: kms_pipe_b_c_ivb
results: parsing output: 89/ for test: gem_mmap_gtt
results: parsing output: 90/ for test: kms_plane_scaling
results: parsing output: 91/ for test: kms_cursor_legacy
results: parsing output: 92/ for test: gem_spin_batch
results: parsing output: 93/ for test: syncobj_basic
results: parsing output: 94/ for test: kms_psr
results: parsing output: 95/ for test: gem_exec_fence
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: vgem_basic
results: parsing output: 98/ for test: perf
results: parsing output: 99/ for test: gem_softpin
results: parsing output: 100/ for test: i915_getparams_basic
results: parsing output: 101/ for test: kms_cursor_crc
results: parsing output: 102/ for test: syncobj_timeline
results: parsing output: 103/ for test: kms_vblank
results: parsing output: 104/ for test: kms_content_protection
results: parsing output: 105/ for test: perf_pmu
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: gem_exec_schedule
results: parsing output: 108/ for test: sriov_basic
results: parsing output: 109/ for test: kms_psr
results: parsing output: 110/ for test: syncobj_basic
results: parsing output: 111/ for test: syncobj_eventfd
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: kms_vblank
results: parsing output: 115/ for test: kms_cursor_crc
results: parsing output: 116/ for test: kms_getfb
results: parsing output: 117/ for test: gem_exec_params
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_psr
results: parsing output: 120/ for test: kms_pm_rpm
results: parsing output: 121/ for test: kms_cursor_legacy
results: parsing output: 122/ for test: gem_exec_alignment
results: parsing output: 123/ for test: perf
results: parsing output: 124/ for test: gem_softpin
results: parsing output: 125/ for test: kms_plane
results: parsing output: 126/ for test: kms_prop_blob
results: parsing output: 127/ for test: gem_exec_whisper
results: parsing output: 128/ for test: kms_ccs
results: parsing output: 129/ for test: gem_mmap_gtt
results: parsing output: 130/ for test: kms_pm_dc
results: parsing output: 131/ for test: kms_chamelium_color
results: parsing output: 132/ for test: api_intel_bb
Done.