[57.456645] Initializing watchdogs
[57.456691]   /dev/watchdog0
[57.469885] [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]
[57.473995] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.475976] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.478184] [001/131] (960s left) perf (buffer-fill)
[57.674716] Starting subtest: buffer-fill
[57.675588] Starting dynamic subtest: 0-rcs0
[59.988187] Dynamic subtest 0-rcs0: SUCCESS (2.307s)
[59.993800] Starting dynamic subtest: 1-vcs1
[64.459874] Dynamic subtest 1-vcs1: SUCCESS (4.466s)
[64.461352] Subtest buffer-fill: SUCCESS (6.774s)
[64.494219] [002/131] (953s left) gem_userptr_blits (nohangcheck)
[64.558867] Starting subtest: nohangcheck
[64.909278] Subtest nohangcheck: SUCCESS (0.349s)
[65.044346] [003/131] (952s left) syncobj_wait (reset-unsignaled)
[65.090850] Starting subtest: reset-unsignaled
[65.091855] Subtest reset-unsignaled: SUCCESS (0.000s)
[65.198590] [004/131] (952s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-fullscreen)
[65.296684] Starting subtest: psr-2p-primscrn-spr-indfb-fullscreen
[65.298447] Subtest psr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[65.376577] [005/131] (952s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-render)
[65.467840] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-render
[70.285247] Subtest fbcpsr-1p-offscren-pri-indfb-draw-render: SUCCESS (4.811s)
[70.421037] [006/131] (947s left) prime_self_import (basic-with_fd_dup)
[70.451597] Starting subtest: basic-with_fd_dup
[70.485907] Subtest basic-with_fd_dup: SUCCESS (0.036s)
[70.580389] [007/131] (947s left) gem_ctx_persistence (hostile)
[70.635091] Starting subtest: hostile
[80.159970] Subtest hostile: SUCCESS (9.514s)
[80.297780] [008/131] (937s left) gem_exec_fence (syncobj-timeline-chain-engines)
[80.854052] Starting subtest: syncobj-timeline-chain-engines
[80.997511] Subtest syncobj-timeline-chain-engines: SUCCESS (0.144s)
[82.018680] [FACT gem_exec_fence (syncobj-timeline-chain-engines)] new: kernel.kmod_is_loaded.vgem: true
[82.020720] [009/131] (936s left) kms_big_fb (4-tiled-64bpp-rotate-0)
[82.133379] Starting subtest: 4-tiled-64bpp-rotate-0
[84.389378] Subtest 4-tiled-64bpp-rotate-0: SUCCESS (2.256s)
[84.677673] [010/131] (933s left) kms_plane_scaling (plane-downscale-factor-0-5-with-rotation)
[84.764599] Starting subtest: plane-downscale-factor-0-5-with-rotation
[84.765626] Starting dynamic subtest: pipe-A
[84.771228] Dynamic subtest pipe-A: SKIP (0.004s)
[84.777770] Starting dynamic subtest: pipe-B
[84.789647] Dynamic subtest pipe-B: SKIP (0.019s)
[84.795416] Starting dynamic subtest: pipe-C
[84.809285] Dynamic subtest pipe-C: SKIP (0.019s)
[84.814933] Starting dynamic subtest: pipe-D
[84.828222] Dynamic subtest pipe-D: SKIP (0.019s)
[84.829884] Subtest plane-downscale-factor-0-5-with-rotation: SKIP (0.062s)
[84.940231] [011/131] (933s left) kms_ccs (bad-rotation-90-y-tiled-gen12-rc-ccs-cc)
[85.047648] Starting subtest: bad-rotation-90-y-tiled-gen12-rc-ccs-cc
[85.048605] Starting dynamic subtest: pipe-A-eDP-1
[85.057314] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[85.062939] Starting dynamic subtest: pipe-B-eDP-1
[85.071731] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[85.077302] Starting dynamic subtest: pipe-C-eDP-1
[85.085769] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[85.091323] Starting dynamic subtest: pipe-D-eDP-1
[85.099883] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[85.101590] Subtest bad-rotation-90-y-tiled-gen12-rc-ccs-cc: SKIP (0.002s)
[87.196672] [012/131] (931s left) gem_render_copy (mixed-tiled-to-yf-tiled-ccs)
[87.257836] Starting subtest: mixed-tiled-to-yf-tiled-ccs
[87.259863] Subtest mixed-tiled-to-yf-tiled-ccs: SKIP (0.000s)
[87.430042] [013/131] (930s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[87.530552] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[87.532386] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[87.618608] [014/131] (930s left) gem_exec_reloc (basic-active)
[87.679905] Subtest basic-active: SKIP (0.000s)
[87.758206] [015/131] (930s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[87.863819] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[87.865632] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[87.955522] [016/131] (930s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions-varying-size)
[88.070265] Starting subtest: short-flip-before-cursor-atomic-transitions-varying-size
[89.054120] Subtest short-flip-before-cursor-atomic-transitions-varying-size: SUCCESS (0.983s)
[89.920199] [017/131] (928s left) prime_vgem (coherency-blt)
[90.007117] Starting subtest: coherency-blt
[91.037495] Subtest coherency-blt: SUCCESS (1.028s)
[91.178689] [018/131] (927s left) sw_sync (sync_merge_invalid)
[91.208541] Starting subtest: sync_merge_invalid
[91.237736] Subtest sync_merge_invalid: SUCCESS (0.029s)
[91.297853] [019/131] (927s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-mmap-wc)
[91.392994] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-mmap-wc
[95.905988] Subtest fbcpsr-1p-offscren-pri-indfb-draw-mmap-wc: SUCCESS (4.510s)
[96.038963] [020/131] (922s left) kms_vblank (query-forked-busy)
[96.122580] Starting subtest: query-forked-busy
[96.123534] Starting dynamic subtest: pipe-A-eDP-1
[98.009966] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.886s)
[98.019200] Starting dynamic subtest: pipe-D-eDP-1
[100.537654] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.524s)
[100.538667] Subtest query-forked-busy: SUCCESS (4.414s)
[101.322027] [021/131] (917s left) kms_chamelium_hpd (common-hpd-after-suspend)
[101.693805] Subtest common-hpd-after-suspend: SKIP (0.000s)
[102.524111] [022/131] (916s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc)
[102.619732] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc
[102.620910] Starting dynamic subtest: pipe-A-eDP-1
[102.632706] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[102.639127] Starting dynamic subtest: pipe-B-eDP-1
[102.648738] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[102.654513] Starting dynamic subtest: pipe-C-eDP-1
[102.663026] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[102.668577] Starting dynamic subtest: pipe-D-eDP-1
[102.677380] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[102.678985] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[104.797685] [023/131] (913s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-pwrite)
[104.898116] Starting subtest: psr-1p-offscren-pri-indfb-draw-pwrite
[109.856834] Subtest psr-1p-offscren-pri-indfb-draw-pwrite: SUCCESS (4.957s)
[109.959515] [024/131] (908s left) kms_psr (pr-cursor-mmap-gtt)
[110.058901] Starting subtest: pr-cursor-mmap-gtt
[110.060645] Subtest pr-cursor-mmap-gtt: SKIP (0.000s)
[110.123775] [025/131] (908s left) gem_madvise (dontneed-before-exec)
[110.155165] Starting subtest: dontneed-before-exec
[110.180248] Subtest dontneed-before-exec: SKIP (0.024s)
[110.249142] [026/131] (908s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[110.305727] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[110.306798] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[110.413707] [027/131] (908s left) gem_exec_params (secure-non-root)
[110.464119] Starting subtest: secure-non-root
[110.466049] Subtest secure-non-root: SKIP (0.000s)
[110.553811] [028/131] (908s left) kms_psr (fbc-psr-cursor-mmap-cpu)
[110.638129] Starting subtest: fbc-psr-cursor-mmap-cpu
[110.639079] Starting dynamic subtest: eDP-1
[110.640875] Dynamic subtest eDP-1: SKIP (0.000s)
[110.642685] Subtest fbc-psr-cursor-mmap-cpu: SKIP (0.000s)
[110.700656] [029/131] (908s left) kms_frontbuffer_tracking (psr-indfb-scaledprimary)
[110.795609] Starting subtest: psr-indfb-scaledprimary
[114.483156] Subtest psr-indfb-scaledprimary: SUCCESS (3.687s)
[114.601724] [030/131] (904s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-pwrite)
[114.695147] Starting subtest: fbc-1p-offscren-pri-indfb-draw-pwrite
[118.706858] Subtest fbc-1p-offscren-pri-indfb-draw-pwrite: SUCCESS (4.012s)
[118.811745] [031/131] (900s left) syncobj_timeline (single-wait-all-available-signaled)
[118.865980] Starting subtest: single-wait-all-available-signaled
[118.867130] Subtest single-wait-all-available-signaled: SUCCESS (0.000s)
[118.969216] [032/131] (899s left) sw_sync (sync_expired_merge)
[119.004100] Starting subtest: sync_expired_merge
[120.079560] Subtest sync_expired_merge: SUCCESS (1.074s)
[120.132258] [033/131] (898s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-render)
[120.230074] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-render
[120.231949] Subtest psr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[120.329952] [034/131] (898s left) gem_exec_reloc (basic-gtt-wc-active)
[120.392411] Subtest basic-gtt-wc-active: SKIP (0.000s)
[120.464689] [035/131] (898s left) api_intel_allocator (fork-simple-once)
[120.529390] Starting subtest: fork-simple-once
[120.545468] Subtest fork-simple-once: SUCCESS (0.017s)
[120.657410] [036/131] (898s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-sf)
[125.623227] Starting subtest: psr2-cursor-plane-move-continuous-sf
[125.624295] Starting dynamic subtest: pipe-A-eDP-1
[136.723838] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.098s)
[136.729970] Starting dynamic subtest: pipe-B-eDP-1
[147.752267] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.027s)
[147.753198] Subtest psr2-cursor-plane-move-continuous-sf: SUCCESS (22.128s)
[148.532514] [037/131] (870s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[148.633948] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt
[148.635677] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[148.745975] [038/131] (870s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[148.804517] Starting subtest: y-tiled-to-vebox-y-tiled
[148.806127] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s)
[148.975513] [039/131] (870s left) kms_chamelium_hpd (vga-hpd-after-suspend)
[149.349859] Subtest vga-hpd-after-suspend: SKIP (0.000s)
[150.136441] [040/131] (869s left) kms_big_fb (yf-tiled-64bpp-rotate-0)
[150.243865] Starting subtest: yf-tiled-64bpp-rotate-0
[150.245717] Subtest yf-tiled-64bpp-rotate-0: SKIP (0.000s)
[150.338516] [041/131] (868s left) kms_atomic_interruptible (atomic-setmode)
[150.424068] Starting subtest: atomic-setmode
[150.429790] Starting dynamic subtest: pipe-A-eDP-1
[157.285455] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.860s)
[157.286317] Subtest atomic-setmode: SUCCESS (6.861s)
[158.068782] [042/131] (861s left) kms_selftest (drm_dp_mst_helper)
[158.102095] Starting subtest: drm_dp_mst_helper
[158.120664] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_mode
[158.280260] Dynamic subtest drm_test_dp_mst_calc_pbn_mode: SUCCESS (0.159s)
[158.287811] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_div
[158.421294] Dynamic subtest drm_test_dp_mst_calc_pbn_div: SUCCESS (0.142s)
[158.427538] Starting dynamic subtest: drm_test_dp_mst_sideband_msg_req_decode
[158.560359] Dynamic subtest drm_test_dp_mst_sideband_msg_req_decode: SUCCESS (0.139s)
[158.561182] Subtest drm_dp_mst_helper: SUCCESS (0.459s)
[158.726606] [043/131] (860s left) gem_exec_reloc (basic-cpu)
[158.786108] Subtest basic-cpu: SKIP (0.000s)
[158.842699] [044/131] (860s left) kms_hdr (bpc-switch-dpms)
[158.949711] Starting subtest: bpc-switch-dpms
[160.041272] Starting dynamic subtest: pipe-A-eDP-1
[164.435687] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.395s)
[164.436667] Subtest bpc-switch-dpms: SUCCESS (5.486s)
[164.563417] [045/131] (854s left) kms_flip (2x-dpms-vs-vblank-race)
[164.653538] Starting subtest: 2x-dpms-vs-vblank-race
[164.665063] Subtest 2x-dpms-vs-vblank-race: SKIP (0.011s)
[164.749300] [046/131] (854s left) gem_mmap_gtt (cpuset-big-copy-odd)
[164.803890] Subtest cpuset-big-copy-odd: SKIP (0.000s)
[164.853050] [047/131] (854s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0)
[164.950772] Starting subtest: linear-max-hw-stride-32bpp-rotate-0
[166.027760] Subtest linear-max-hw-stride-32bpp-rotate-0: SUCCESS (1.077s)
[166.153960] [048/131] (853s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-msflip-blt)
[166.263546] Starting subtest: fbc-2p-scndscrn-shrfb-msflip-blt
[166.265255] Subtest fbc-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[166.353661] [049/131] (853s left) kms_big_fb (y-tiled-32bpp-rotate-180)
[166.455187] Starting subtest: y-tiled-32bpp-rotate-180
[166.457063] Subtest y-tiled-32bpp-rotate-180: SKIP (0.000s)
[166.552281] [050/131] (852s left) kms_cursor_crc (cursor-sliding-64x64)
[166.716552] Starting subtest: cursor-sliding-64x64
[166.719953] Starting dynamic subtest: pipe-A-eDP-1
[170.248691] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.529s)
[170.274352] Starting dynamic subtest: pipe-D-eDP-1
[174.487129] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.216s)
[174.488098] Subtest cursor-sliding-64x64: SUCCESS (7.769s)
[175.336137] [051/131] (844s left) perf_pmu (enable-race)
[175.374947] Starting subtest: enable-race
[175.375864] Starting dynamic subtest: rcs0
[188.169432] Dynamic subtest rcs0: SUCCESS (12.793s)
[188.177935] Starting dynamic subtest: bcs0
[200.919908] Dynamic subtest bcs0: SUCCESS (12.750s)
[200.926405] Starting dynamic subtest: vcs0
[216.943994] Dynamic subtest vcs0: SUCCESS (16.020s)
[216.951489] Starting dynamic subtest: vcs1
[232.880098] Dynamic subtest vcs1: SUCCESS (15.934s)
[232.888843] Starting dynamic subtest: vecs0
[248.881095] Dynamic subtest vecs0: SUCCESS (15.999s)
[248.888774] Starting dynamic subtest: ccs0
[261.643425] Dynamic subtest ccs0: SUCCESS (12.763s)
[261.644889] Subtest enable-race: SUCCESS (86.267s)
[261.727816] [052/131] (757s left) kms_flip (2x-plain-flip)
[261.827606] Starting subtest: 2x-plain-flip
[261.838957] Subtest 2x-plain-flip: SKIP (0.011s)
[261.920312] [053/131] (757s left) core_auth (many-magics)
[261.973811] Starting subtest: many-magics
[262.751723] Subtest many-magics: SUCCESS (0.779s)
[262.957554] [054/131] (756s left) gem_lmem_swapping (verify)
[262.994463] Subtest verify: SKIP (0.000s)
[263.031535] [055/131] (756s left) kms_cursor_crc (cursor-random-512x170)
[263.300174] Starting subtest: cursor-random-512x170
[263.302224] Subtest cursor-random-512x170: SKIP (0.001s)
[263.460451] [056/131] (756s left) kms_feature_discovery (display-4x)
[263.551299] Starting subtest: display-4x
[263.553029] Subtest display-4x: SKIP (0.000s)
[263.613693] [057/131] (756s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-downscaling)
[263.713894] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-downscaling
[263.714741] Starting dynamic subtest: pipe-A-default-mode
[263.998311] Dynamic subtest pipe-A-default-mode: SKIP (0.282s)
[264.000124] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-downscaling: SKIP (0.283s)
[264.848458] [058/131] (754s left) gem_mmap_wc (close)
[264.902085] Subtest close: SKIP (0.000s)
[264.960778] [059/131] (754s left) gem_exec_schedule (reorder-wide)
[265.141273] Subtest reorder-wide: SKIP (0.000s)
[265.312741] [060/131] (754s left) i915_pm_rps (reset)
[265.366233] Starting subtest: reset
[265.396572] Subtest reset: FAIL (0.009s)
[265.434099] [061/131] (754s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[265.482706] Starting subtest: etime-multi-wait-all-for-submit-submitted
[265.588486] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.103s)
[265.654858] [062/131] (754s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[265.751084] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[269.327595] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (3.577s)
[269.457142] [063/131] (750s left) gem_pxp (reject-modify-context-protection-off-3)
[269.574086] Starting subtest: reject-modify-context-protection-off-3
[269.575188] Subtest reject-modify-context-protection-off-3: SUCCESS (0.002s)
[269.690280] [064/131] (750s left) gem_fenced_exec_thrash (no-spare-fences-busy-interruptible)
[269.748576] Subtest no-spare-fences-busy-interruptible: SKIP (0.000s)
[269.808497] [065/131] (750s left) kms_rotation_crc (bad-tiling)
[269.900893] Starting subtest: bad-tiling
[269.903047] Subtest bad-tiling: SKIP (0.001s)
[269.969653] [066/131] (750s left) gem_ctx_shared (create-shared-gtt)
[270.032270] Starting subtest: create-shared-gtt
[272.185207] Subtest create-shared-gtt: SUCCESS (2.151s)
[273.265244] [067/131] (746s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-pwrite)
[273.367399] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-pwrite
[279.585340] Subtest fbc-1p-offscren-pri-shrfb-draw-pwrite: SUCCESS (6.219s)
[279.714564] [068/131] (740s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[279.833272] Starting subtest: basic-flip-after-cursor-varying-size
[280.750047] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.916s)
[281.630282] [069/131] (738s left) kms_addfb_basic (unused-offsets)
[281.690516] Starting subtest: unused-offsets
[281.691700] Subtest unused-offsets: SUCCESS (0.000s)
[281.833680] [070/131] (738s left) core_setmaster (master-drop-set-shared-fd)
[283.920321] Starting subtest: master-drop-set-shared-fd
[283.942761] Subtest master-drop-set-shared-fd: SUCCESS (0.022s)
[283.971718] [FACT core_setmaster (master-drop-set-shared-fd)] new: kernel.kmod_is_loaded.amdgpu: true
[283.972506] [FACT core_setmaster (master-drop-set-shared-fd)] new: kernel.kmod_is_loaded.xe: true
[283.974221] [071/131] (736s left) kms_cursor_crc (cursor-offscreen-256x85)
[284.194667] Starting subtest: cursor-offscreen-256x85
[284.236276] Subtest cursor-offscreen-256x85: SKIP (0.039s)
[284.410434] [072/131] (735s left) kms_vrr (seamless-rr-switch-vrr)
[284.505660] Starting subtest: seamless-rr-switch-vrr
[284.508214] Subtest seamless-rr-switch-vrr: SKIP (0.000s)
[284.574431] [073/131] (735s left) kms_draw_crc (draw-method-mmap-cpu)
[284.673531] Starting subtest: draw-method-mmap-cpu
[284.674465] Starting dynamic subtest: xrgb8888-untiled
[284.879270] Dynamic subtest xrgb8888-untiled: SUCCESS (0.207s)
[284.887476] Starting dynamic subtest: rgb565-untiled
[285.079528] Dynamic subtest rgb565-untiled: SUCCESS (0.200s)
[285.088448] Starting dynamic subtest: xrgb2101010-untiled
[285.301836] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.222s)
[285.310075] Starting dynamic subtest: xbgr16161616f-untiled
[285.523961] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.221s)
[285.529961] Starting dynamic subtest: xrgb8888-xtiled
[285.646236] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.122s)
[285.652651] Starting dynamic subtest: rgb565-xtiled
[285.757567] Dynamic subtest rgb565-xtiled: SUCCESS (0.111s)
[285.765514] Starting dynamic subtest: xrgb2101010-xtiled
[285.879522] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.120s)
[285.886343] Starting dynamic subtest: xbgr16161616f-xtiled
[286.013238] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s)
[286.021417] Starting dynamic subtest: xrgb8888-4tiled
[286.146127] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.133s)
[286.155212] Starting dynamic subtest: rgb565-4tiled
[286.279471] Dynamic subtest rgb565-4tiled: SUCCESS (0.132s)
[286.285468] Starting dynamic subtest: xrgb2101010-4tiled
[286.401750] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.122s)
[286.407989] Starting dynamic subtest: xbgr16161616f-4tiled
[286.546187] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.144s)
[286.547134] Subtest draw-method-mmap-cpu: SUCCESS (1.874s)
[286.642499] [074/131] (733s left) dumb_buffer (create-valid-dumb)
[286.695622] Starting subtest: create-valid-dumb
[286.696788] Subtest create-valid-dumb: SUCCESS (0.000s)
[286.793121] [075/131] (733s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[291.793269] Starting subtest: pr-cursor-plane-move-continuous-exceed-sf
[291.797188] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.003s)
[292.629227] [076/131] (727s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-plflip-blt)
[292.736359] Starting subtest: psr-1p-primscrn-shrfb-plflip-blt
[298.109269] Subtest psr-1p-primscrn-shrfb-plflip-blt: SUCCESS (5.375s)
[298.227840] [077/131] (722s left) gem_pipe_control_store_loop (reused-buffer)
[298.290889] Starting subtest: reused-buffer
[300.445795] Subtest reused-buffer: SUCCESS (2.153s)
[300.562084] [078/131] (719s left) gem_exec_balancer (nop)
[300.615222] Starting subtest: nop
[347.209741] Subtest nop: SUCCESS (46.594s)
[347.496541] [079/131] (672s left) kms_psr (psr2-sprite-mmap-cpu)
[347.605133] Starting subtest: psr2-sprite-mmap-cpu
[347.606077] Starting dynamic subtest: eDP-1
[349.357505] Dynamic subtest eDP-1: SUCCESS (1.752s)
[349.358529] Subtest psr2-sprite-mmap-cpu: SUCCESS (1.753s)
[350.221118] [080/131] (670s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs-cc)
[350.333264] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs-cc
[350.334474] Starting dynamic subtest: pipe-A-eDP-1
[351.105515] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.768s)
[351.113131] Starting dynamic subtest: pipe-B-eDP-1
[352.870909] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.764s)
[352.877082] Starting dynamic subtest: pipe-C-eDP-1
[354.707984] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.836s)
[354.716171] Starting dynamic subtest: pipe-D-eDP-1
[356.546405] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.838s)
[356.547278] Subtest random-ccs-data-4-tiled-mtl-rc-ccs-cc: SUCCESS (6.210s)
[357.741742] [081/131] (662s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-wc)
[357.838919] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-wc
[360.440914] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (2.604s)
[360.594754] [082/131] (659s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[360.690490] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[360.691441] Starting dynamic subtest: pipe-A-eDP-1
[360.713607] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[360.720406] Starting dynamic subtest: pipe-B-eDP-1
[360.741933] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[360.747976] Starting dynamic subtest: pipe-C-eDP-1
[360.769431] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[360.775375] Starting dynamic subtest: pipe-D-eDP-1
[360.798046] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[360.799796] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.008s)
[362.824538] [083/131] (657s left) gem_exec_async (concurrent-writes)
[362.881318] Starting subtest: concurrent-writes
[362.882442] Starting dynamic subtest: rcs0
[362.896894] Dynamic subtest rcs0: SUCCESS (0.015s)
[362.903718] Starting dynamic subtest: bcs0
[362.904752] Dynamic subtest bcs0: SUCCESS (0.007s)
[362.911511] Starting dynamic subtest: vcs0
[362.912684] Dynamic subtest vcs0: SUCCESS (0.006s)
[362.918477] Starting dynamic subtest: vcs1
[362.919294] Dynamic subtest vcs1: SUCCESS (0.008s)
[362.925177] Starting dynamic subtest: vecs0
[362.928071] Dynamic subtest vecs0: SUCCESS (0.008s)
[362.934082] Starting dynamic subtest: ccs0
[362.934949] Dynamic subtest ccs0: SUCCESS (0.005s)
[362.935833] Subtest concurrent-writes: SUCCESS (0.053s)
[363.175559] [084/131] (657s left) gem_exec_reloc (basic-cpu-gtt)
[363.236634] Subtest basic-cpu-gtt: SKIP (0.000s)
[363.318808] [085/131] (657s left) kms_big_fb (x-tiled-8bpp-rotate-90)
[363.419668] Starting subtest: x-tiled-8bpp-rotate-90
[363.440041] Subtest x-tiled-8bpp-rotate-90: SKIP (0.020s)
[363.569241] [086/131] (657s left) kms_addfb_basic (master-rmfb)
[363.625919] Starting subtest: master-rmfb
[363.626821] Subtest master-rmfb: SUCCESS (0.001s)
[363.744099] [087/131] (656s left) gem_ctx_engines (none)
[363.800309] Starting subtest: none
[363.803912] Subtest none: SUCCESS (0.002s)
[363.982155] [088/131] (656s left) sysfs_heartbeat_interval (precise)
[364.042318] Starting subtest: precise
[364.043414] Starting dynamic subtest: vecs0
[371.948066] Dynamic subtest vecs0: SUCCESS (7.903s)
[371.957090] Starting dynamic subtest: ccs0
[373.113457] Dynamic subtest ccs0: SUCCESS (1.162s)
[373.121565] Starting dynamic subtest: vcs0
[380.925457] Dynamic subtest vcs0: SUCCESS (7.812s)
[380.936567] Starting dynamic subtest: bcs0
[382.050471] Dynamic subtest bcs0: SUCCESS (1.122s)
[382.057781] Starting dynamic subtest: rcs0
[383.238097] Dynamic subtest rcs0: SUCCESS (1.188s)
[383.249453] Starting dynamic subtest: vcs1
[390.982904] Dynamic subtest vcs1: SUCCESS (7.742s)
[390.984297] Subtest precise: SUCCESS (26.941s)
[391.051243] [089/131] (629s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[391.146116] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[391.157971] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.011s)
[391.229100] [090/131] (629s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-wc)
[391.321050] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-wc
[391.323094] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[391.429261] [091/131] (629s left) api_intel_allocator (reloc-allocator)
[391.489268] Starting subtest: reloc-allocator
[391.490316] Starting dynamic subtest: basic
[391.491423] Dynamic subtest basic: SUCCESS (0.003s)
[391.499441] Starting dynamic subtest: parallel-one
[391.500322] Dynamic subtest parallel-one: SUCCESS (0.008s)
[391.506608] Starting dynamic subtest: print
[391.507581] Dynamic subtest print: SUCCESS (0.000s)
[391.513817] Starting dynamic subtest: reuse
[391.514653] Dynamic subtest reuse: SUCCESS (0.000s)
[391.520486] Starting dynamic subtest: fork-reopen-allocator
[391.521425] Dynamic subtest fork-reopen-allocator: SUCCESS (0.011s)
[391.522240] Subtest reloc-allocator: SUCCESS (0.025s)
[391.629062] [092/131] (629s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-render)
[391.724866] Starting subtest: fbc-1p-offscren-pri-indfb-draw-render
[394.941182] Subtest fbc-1p-offscren-pri-indfb-draw-render: SUCCESS (3.217s)
[395.068335] [093/131] (625s left) kms_lease (cursor-implicit-plane)
[395.161852] Starting subtest: cursor-implicit-plane
[395.162816] Starting dynamic subtest: pipe-A-eDP-1
[395.679343] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.517s)
[395.687185] Starting dynamic subtest: pipe-B-eDP-1
[396.764474] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.083s)
[396.772151] Starting dynamic subtest: pipe-C-eDP-1
[397.874387] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.108s)
[397.881085] Starting dynamic subtest: pipe-D-eDP-1
[398.998178] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.122s)
[398.999138] Subtest cursor-implicit-plane: SUCCESS (3.836s)
[399.849744] [094/131] (621s left) kms_chamelium_edid (dp-edid-read)
[400.225590] Subtest dp-edid-read: SKIP (0.000s)
[401.046824] [095/131] (619s left) kms_psr (fbc-pr-sprite-plane-move)
[401.144109] Starting subtest: fbc-pr-sprite-plane-move
[401.145847] Subtest fbc-pr-sprite-plane-move: SKIP (0.000s)
[401.211723] [096/131] (619s left) kms_plane_scaling (plane-downscale-factor-0-75-with-pixel-format)
[401.305030] Starting subtest: plane-downscale-factor-0-75-with-pixel-format
[401.306037] Starting dynamic subtest: pipe-A
[402.860985] Dynamic subtest pipe-A: SUCCESS (1.556s)
[402.867338] Starting dynamic subtest: pipe-B
[405.521966] Dynamic subtest pipe-B: SUCCESS (2.660s)
[405.528859] Starting dynamic subtest: pipe-C
[406.678821] Dynamic subtest pipe-C: SUCCESS (1.156s)
[406.686861] Starting dynamic subtest: pipe-D
[407.899699] Dynamic subtest pipe-D: SUCCESS (1.219s)
[407.900833] Subtest plane-downscale-factor-0-75-with-pixel-format: SUCCESS (6.595s)
[409.075148] [097/131] (611s left) kms_pm_dc (dc5-dpms-negative)
[410.180859] Starting subtest: dc5-dpms-negative
[410.182864] Subtest dc5-dpms-negative: SKIP (0.000s)
[410.247656] [098/131] (610s left) gen9_exec_parse (bb-chained)
[410.308541] Subtest bb-chained: SKIP (0.000s)
[410.378079] [099/131] (610s left) gem_exec_params (secure-non-master)
[410.431277] Starting subtest: secure-non-master
[410.434437] Subtest secure-non-master: SKIP (0.000s)
[410.533161] [100/131] (610s left) syncobj_timeline (invalid-signal-illegal-handle)
[410.589191] Starting subtest: invalid-signal-illegal-handle
[410.590220] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[410.679528] [101/131] (610s left) gen9_exec_parse (allowed-all)
[410.742421] Subtest allowed-all: SKIP (0.000s)
[410.794445] [102/131] (610s left) gem_exec_reloc (basic-write-read-active)
[410.852972] Subtest basic-write-read-active: SKIP (0.000s)
[410.924938] [103/131] (610s left) kms_pipe_crc_basic (suspend-read-crc)
[411.020398] Starting subtest: suspend-read-crc
[411.021366] Starting dynamic subtest: pipe-A-eDP-1
[428.487161] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.543s)
[428.496413] Starting dynamic subtest: pipe-B-eDP-1
[448.795128] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.338s)
[448.804757] Starting dynamic subtest: pipe-C-eDP-1
[467.467620] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.028s)
[467.478364] Starting dynamic subtest: pipe-D-eDP-1
[487.723879] Dynamic subtest pipe-D-eDP-1: SUCCESS (5.251s)
[487.724955] Subtest suspend-read-crc: SUCCESS (16.166s)
[496.605916] [104/131] (593s left) gem_exec_fence (keep-in-fence)
[496.800257] Starting subtest: keep-in-fence
[496.801878] Starting dynamic subtest: rcs0
[496.872908] Dynamic subtest rcs0: SUCCESS (0.072s)
[496.884994] Starting dynamic subtest: bcs0
[496.930389] Dynamic subtest bcs0: SUCCESS (0.056s)
[496.941535] Starting dynamic subtest: vcs0
[498.417369] Dynamic subtest vcs0: SUCCESS (1.475s)
[498.424964] Starting dynamic subtest: vcs1
[500.401521] Dynamic subtest vcs1: SUCCESS (1.974s)
[500.409800] Starting dynamic subtest: vecs0
[502.386506] Dynamic subtest vecs0: SUCCESS (1.976s)
[502.396879] Starting dynamic subtest: ccs0
[502.628303] Dynamic subtest ccs0: SUCCESS (0.241s)
[502.630134] Subtest keep-in-fence: SUCCESS (5.805s)
[504.531942] [105/131] (585s left) kms_prop_blob (invalid-get-prop)
[504.589625] Starting subtest: invalid-get-prop
[504.590828] Subtest invalid-get-prop: SUCCESS (0.000s)
[504.669293] [106/131] (585s left) kms_flip (absolute-wf_vblank-interruptible)
[504.774478] Starting subtest: absolute-wf_vblank-interruptible
[504.776735] Starting dynamic subtest: A-eDP1
[513.494144] Dynamic subtest A-eDP1: SUCCESS (8.704s)
[513.503677] Starting dynamic subtest: B-eDP1
[522.223663] Dynamic subtest B-eDP1: SUCCESS (8.720s)
[522.237688] Starting dynamic subtest: C-eDP1
[530.932024] Dynamic subtest C-eDP1: SUCCESS (8.707s)
[530.940840] Starting dynamic subtest: D-eDP1
[539.650129] Dynamic subtest D-eDP1: SUCCESS (8.720s)
[539.653134] Subtest absolute-wf_vblank-interruptible: SUCCESS (34.861s)
[540.850205] [107/131] (549s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling)
[540.945431] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling
[540.946453] Starting dynamic subtest: pipe-A-default-mode
[541.230774] Dynamic subtest pipe-A-default-mode: SKIP (0.283s)
[541.232713] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.284s)
[542.074845] [108/131] (547s left) kms_flip (2x-blocking-wf_vblank)
[542.169667] Starting subtest: 2x-blocking-wf_vblank
[542.181028] Subtest 2x-blocking-wf_vblank: SKIP (0.011s)
[542.253965] [109/131] (547s left) gem_exec_whisper (basic-queues-all)
[542.313500] Starting subtest: basic-queues-all
[552.765177] Subtest basic-queues-all: SUCCESS (10.448s)
[552.944009] [110/131] (537s left) kms_cursor_edge_walk (64x64-top-edge)
[553.045456] Starting subtest: 64x64-top-edge
[553.046446] Starting dynamic subtest: pipe-A-eDP-1
[556.538816] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.491s)
[556.547999] Starting dynamic subtest: pipe-D-eDP-1
[559.656288] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.116s)
[559.657396] Subtest 64x64-top-edge: SUCCESS (6.610s)
[560.464065] [111/131] (529s left) kms_psr (psr-sprite-plane-onoff)
[560.561630] Starting subtest: psr-sprite-plane-onoff
[560.562509] Starting dynamic subtest: eDP-1
[562.294628] Dynamic subtest eDP-1: SUCCESS (1.733s)
[562.295716] Subtest psr-sprite-plane-onoff: SUCCESS (1.734s)
[563.111015] [112/131] (526s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[563.210196] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip
[563.212231] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[563.304153] [113/131] (526s left) perf_pmu (semaphore-wait)
[563.339722] Starting subtest: semaphore-wait
[563.340549] Starting dynamic subtest: rcs0
[563.856792] Dynamic subtest rcs0: SUCCESS (0.513s)
[563.866859] Starting dynamic subtest: bcs0
[564.370010] Dynamic subtest bcs0: SUCCESS (0.510s)
[564.378286] Starting dynamic subtest: vcs0
[564.881800] Dynamic subtest vcs0: SUCCESS (0.510s)
[564.895529] Starting dynamic subtest: vcs1
[565.394710] Dynamic subtest vcs1: SUCCESS (0.510s)
[565.404141] Starting dynamic subtest: vecs0
[565.931271] Dynamic subtest vecs0: SUCCESS (0.509s)
[565.941652] Starting dynamic subtest: ccs0
[566.417612] Dynamic subtest ccs0: SUCCESS (0.510s)
[566.419192] Subtest semaphore-wait: SUCCESS (3.075s)
[566.525508] [114/131] (523s left) kms_legacy_colorkey (basic)
[566.619178] Starting subtest: basic
[566.620102] Starting dynamic subtest: pipe-A
[566.665007] Dynamic subtest pipe-A: SUCCESS (0.045s)
[566.674819] Starting dynamic subtest: pipe-B
[566.676361] Dynamic subtest pipe-B: SUCCESS (0.000s)
[566.684897] Starting dynamic subtest: pipe-C
[566.685974] Dynamic subtest pipe-C: SUCCESS (0.000s)
[566.693324] Starting dynamic subtest: pipe-D
[566.694343] Dynamic subtest pipe-D: SUCCESS (0.000s)
[566.695293] Subtest basic: SUCCESS (0.049s)
[566.727317] [115/131] (523s left) gem_busy (busy)
[566.788475] Starting subtest: busy
[566.789486] Starting dynamic subtest: all-engines
[566.873350] Dynamic subtest all-engines: SUCCESS (0.084s)
[566.882060] Starting dynamic subtest: rcs0
[568.377589] Dynamic subtest rcs0: SUCCESS (1.502s)
[568.391218] Starting dynamic subtest: bcs0
[568.450199] Dynamic subtest bcs0: SUCCESS (0.071s)
[568.457569] Starting dynamic subtest: vcs0
[568.490477] Dynamic subtest vcs0: SUCCESS (0.038s)
[568.497669] Starting dynamic subtest: vcs1
[570.419490] Dynamic subtest vcs1: SUCCESS (1.926s)
[570.429275] Starting dynamic subtest: vecs0
[572.406517] Dynamic subtest vecs0: SUCCESS (1.983s)
[572.416321] Starting dynamic subtest: ccs0
[574.452921] Dynamic subtest ccs0: SUCCESS (2.046s)
[574.454139] Subtest busy: SUCCESS (7.660s)
[574.800648] [116/131] (515s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-gtt)
[574.909252] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-gtt
[574.911120] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[575.023298] [117/131] (515s left) syncobj_timeline (wait-zero-handles)
[575.085971] Starting subtest: wait-zero-handles
[575.086988] Subtest wait-zero-handles: SUCCESS (0.000s)
[575.197575] [118/131] (515s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[575.248590] Starting subtest: etime-single-wait-all-available-unsubmitted
[575.357089] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.106s)
[575.428818] [119/131] (514s left) gem_mmap_gtt (basic-write-cpu-read-gtt)
[575.481761] Subtest basic-write-cpu-read-gtt: SKIP (0.000s)
[575.546213] [120/131] (514s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[575.653289] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[576.500484] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.848s)
[576.708652] [121/131] (513s left) i915_module_load (resize-bar)
[576.744078] Starting subtest: resize-bar
[576.751016] Subtest resize-bar: SKIP (0.006s)
[576.782206] [122/131] (513s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[576.878263] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[576.880209] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[576.984438] [123/131] (513s left) gem_mmap_offset (bad-object)
[577.032625] Starting subtest: bad-object
[577.033680] Subtest bad-object: SUCCESS (0.001s)
[577.109486] [124/131] (513s left) gem_mmap_wc (write-read-distinct)
[577.164544] Subtest write-read-distinct: SKIP (0.000s)
[577.222799] [125/131] (513s left) gem_exec_store (pages)
[577.280194] Starting subtest: pages
[577.281320] Starting dynamic subtest: rcs0
[577.412672] Dynamic subtest rcs0: SUCCESS (0.131s)
[577.423109] Starting dynamic subtest: bcs0
[577.498511] Dynamic subtest bcs0: SUCCESS (0.085s)
[577.509584] Starting dynamic subtest: vcs0
[577.579456] Dynamic subtest vcs0: SUCCESS (0.079s)
[577.586796] Starting dynamic subtest: vcs1
[577.651101] Dynamic subtest vcs1: SUCCESS (0.071s)
[577.658328] Starting dynamic subtest: vecs0
[577.722137] Dynamic subtest vecs0: SUCCESS (0.070s)
[577.729522] Starting dynamic subtest: ccs0
[577.792322] Dynamic subtest ccs0: SUCCESS (0.069s)
[577.793777] Subtest pages: SUCCESS (0.512s)
[577.970748] [126/131] (512s left) kms_pm_rpm (basic-pci-d3-state)
[579.620600] Starting subtest: basic-pci-d3-state
[581.065275] Subtest basic-pci-d3-state: SUCCESS (1.448s)
[581.147783] [127/131] (509s left) kms_rotation_crc (bad-pixel-format)
[581.247683] Starting subtest: bad-pixel-format
[581.249883] Subtest bad-pixel-format: SKIP (0.001s)
[581.318884] [128/131] (509s left) syncobj_wait (multi-wait-all-for-submit-submitted-signaled)
[581.380771] Starting subtest: multi-wait-all-for-submit-submitted-signaled
[581.483298] Subtest multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.101s)
[581.554972] [129/131] (509s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs)
[581.659326] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs
[581.660194] Starting dynamic subtest: pipe-A-eDP-1
[581.667992] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[581.675751] Starting dynamic subtest: pipe-B-eDP-1
[581.683777] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[581.691320] Starting dynamic subtest: pipe-C-eDP-1
[581.699057] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[581.706179] Starting dynamic subtest: pipe-D-eDP-1
[581.713843] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[581.714663] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs: SUCCESS (0.005s)
[583.771845] [130/131] (506s left) i915_suspend (sysfs-reader)
[583.817840] Starting subtest: sysfs-reader
[601.585256] Subtest sysfs-reader: SUCCESS (3.104s)
[601.634651] [131/131] (503s left) dmabuf_sync_file (export-wait-after-attach)
[601.678849] Starting subtest: export-wait-after-attach
[601.680340] Subtest export-wait-after-attach: SUCCESS (0.001s)
[601.728170] Closing watchdogs
results: parsing output: 0/ for test: perf
results: parsing output: 1/ for test: gem_userptr_blits
results: parsing output: 2/ for test: syncobj_wait
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: prime_self_import
results: parsing output: 6/ for test: gem_ctx_persistence
results: parsing output: 7/ for test: gem_exec_fence
results: parsing output: 8/ for test: kms_big_fb
results: parsing output: 9/ for test: kms_plane_scaling
results: parsing output: 10/ for test: kms_ccs
results: parsing output: 11/ for test: gem_render_copy
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: gem_exec_reloc
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: kms_cursor_legacy
results: parsing output: 16/ for test: prime_vgem
results: parsing output: 17/ for test: sw_sync
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_vblank
results: parsing output: 20/ for test: kms_chamelium_hpd
results: parsing output: 21/ for test: kms_ccs
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: kms_psr
results: parsing output: 24/ for test: gem_madvise
results: parsing output: 25/ for test: syncobj_wait
results: parsing output: 26/ for test: gem_exec_params
results: parsing output: 27/ for test: kms_psr
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: syncobj_timeline
results: parsing output: 31/ for test: sw_sync
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: gem_exec_reloc
results: parsing output: 34/ for test: api_intel_allocator
results: parsing output: 35/ for test: kms_psr2_sf
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: gem_render_copy
results: parsing output: 38/ for test: kms_chamelium_hpd
results: parsing output: 39/ for test: kms_big_fb
results: parsing output: 40/ for test: kms_atomic_interruptible
results: parsing output: 41/ for test: kms_selftest
results: parsing output: 42/ for test: gem_exec_reloc
results: parsing output: 43/ for test: kms_hdr
results: parsing output: 44/ for test: kms_flip
results: parsing output: 45/ for test: gem_mmap_gtt
results: parsing output: 46/ for test: kms_big_fb
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_big_fb
results: parsing output: 49/ for test: kms_cursor_crc
results: parsing output: 50/ for test: perf_pmu
results: parsing output: 51/ for test: kms_flip
results: parsing output: 52/ for test: core_auth
results: parsing output: 53/ for test: gem_lmem_swapping
results: parsing output: 54/ for test: kms_cursor_crc
results: parsing output: 55/ for test: kms_feature_discovery
results: parsing output: 56/ for test: kms_flip_scaled_crc
results: parsing output: 57/ for test: gem_mmap_wc
results: parsing output: 58/ for test: gem_exec_schedule
results: parsing output: 59/ for test: i915_pm_rps
results: parsing output: 60/ for test: syncobj_timeline
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: gem_pxp
results: parsing output: 63/ for test: gem_fenced_exec_thrash
results: parsing output: 64/ for test: kms_rotation_crc
results: parsing output: 65/ for test: gem_ctx_shared
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_cursor_legacy
results: parsing output: 68/ for test: kms_addfb_basic
results: parsing output: 69/ for test: core_setmaster
results: parsing output: 70/ for test: kms_cursor_crc
results: parsing output: 71/ for test: kms_vrr
results: parsing output: 72/ for test: kms_draw_crc
results: parsing output: 73/ for test: dumb_buffer
results: parsing output: 74/ for test: kms_psr2_sf
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: gem_pipe_control_store_loop
results: parsing output: 77/ for test: gem_exec_balancer
results: parsing output: 78/ for test: kms_psr
results: parsing output: 79/ for test: kms_ccs
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: kms_ccs
results: parsing output: 82/ for test: gem_exec_async
results: parsing output: 83/ for test: gem_exec_reloc
results: parsing output: 84/ for test: kms_big_fb
results: parsing output: 85/ for test: kms_addfb_basic
results: parsing output: 86/ for test: gem_ctx_engines
results: parsing output: 87/ for test: sysfs_heartbeat_interval
results: parsing output: 88/ for test: kms_flip
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: api_intel_allocator
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_lease
results: parsing output: 93/ for test: kms_chamelium_edid
results: parsing output: 94/ for test: kms_psr
results: parsing output: 95/ for test: kms_plane_scaling
results: parsing output: 96/ for test: kms_pm_dc
results: parsing output: 97/ for test: gen9_exec_parse
results: parsing output: 98/ for test: gem_exec_params
results: parsing output: 99/ for test: syncobj_timeline
results: parsing output: 100/ for test: gen9_exec_parse
results: parsing output: 101/ for test: gem_exec_reloc
results: parsing output: 102/ for test: kms_pipe_crc_basic
results: parsing output: 103/ for test: gem_exec_fence
results: parsing output: 104/ for test: kms_prop_blob
results: parsing output: 105/ for test: kms_flip
results: parsing output: 106/ for test: kms_flip_scaled_crc
results: parsing output: 107/ for test: kms_flip
results: parsing output: 108/ for test: gem_exec_whisper
results: parsing output: 109/ for test: kms_cursor_edge_walk
results: parsing output: 110/ for test: kms_psr
results: parsing output: 111/ for test: kms_big_fb
results: parsing output: 112/ for test: perf_pmu
results: parsing output: 113/ for test: kms_legacy_colorkey
results: parsing output: 114/ for test: gem_busy
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: syncobj_timeline
results: parsing output: 117/ for test: syncobj_timeline
results: parsing output: 118/ for test: gem_mmap_gtt
results: parsing output: 119/ for test: kms_big_fb
results: parsing output: 120/ for test: i915_module_load
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: gem_mmap_offset
results: parsing output: 123/ for test: gem_mmap_wc
results: parsing output: 124/ for test: gem_exec_store
results: parsing output: 125/ for test: kms_pm_rpm
results: parsing output: 126/ for test: kms_rotation_crc
results: parsing output: 127/ for test: syncobj_wait
results: parsing output: 128/ for test: kms_ccs
results: parsing output: 129/ for test: i915_suspend
results: parsing output: 130/ for test: dmabuf_sync_file
Done.