[65.153202] Initializing watchdogs
[65.153245]   /dev/watchdog0
[65.167879] [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]
[65.172563] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[65.174940] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[65.178349] [001/156] (960s left) gem_exec_schedule (fairslice-all)
[65.273096] Starting subtest: fairslice-all
[67.361650] Subtest fairslice-all: SUCCESS (2.105s)
[67.675255] [002/156] (958s left) gem_tiled_wc
[67.780464] [003/156] (957s left) kms_frontbuffer_tracking (fbchdr-rgb565-draw-mmap-cpu)
[67.888102] Starting subtest: fbchdr-rgb565-draw-mmap-cpu
[67.892491] Subtest fbchdr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[67.977632] [004/156] (957s left) kms_frontbuffer_tracking (fbcpsrhdr-tiling-4)
[68.074419] Starting subtest: fbcpsrhdr-tiling-4
[68.078822] Subtest fbcpsrhdr-tiling-4: SKIP (0.000s)
[68.179988] [005/156] (957s left) prime_self_import (basic-llseek-size)
[68.214855] Starting subtest: basic-llseek-size
[68.227230] Subtest basic-llseek-size: SUCCESS (0.012s)
[68.310771] [006/156] (957s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-wc)
[68.404443] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-wc
[73.116690] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (4.739s)
[74.323582] [007/156] (951s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[74.447834] Starting subtest: basic-flip-after-cursor-varying-size
[75.395645] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.948s)
[76.232755] [008/156] (949s left) kms_dirtyfb (default-dirtyfb-ioctl)
[76.319455] Starting subtest: default-dirtyfb-ioctl
[76.321454] Starting dynamic subtest: A-eDP-1
[77.893556] Dynamic subtest A-eDP-1: SUCCESS (1.578s)
[77.896070] Subtest default-dirtyfb-ioctl: SUCCESS (1.578s)
[78.760654] [009/156] (947s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-blt)
[78.869237] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-blt
[85.612955] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (6.757s)
[85.742439] [010/156] (940s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted)
[85.799854] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[85.802298] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.001s)
[85.900013] [011/156] (940s left) api_intel_allocator (fork-simple-stress)
[85.946579] Starting subtest: fork-simple-stress
[91.345529] Subtest fork-simple-stress: SUCCESS (5.396s)
[91.465177] [012/156] (934s left) gem_exec_flush (basic-uc-set-default)
[91.533909] Starting subtest: basic-uc-set-default
[97.003415] Subtest basic-uc-set-default: SUCCESS (5.468s)
[97.187828] [013/156] (928s left) kms_psr2_sf (fbc-psr2-primary-plane-update-sf-dmg-area)
[97.293881] Starting subtest: fbc-psr2-primary-plane-update-sf-dmg-area
[97.296405] Starting dynamic subtest: pipe-A-eDP-1
[97.390242] Dynamic subtest pipe-A-eDP-1: SKIP (0.099s)
[97.400221] Starting dynamic subtest: pipe-B-eDP-1
[98.573550] Dynamic subtest pipe-B-eDP-1: SKIP (1.181s)
[98.577686] Subtest fbc-psr2-primary-plane-update-sf-dmg-area: SKIP (1.282s)
[99.755924] [014/156] (926s left) kms_psr (psr2-cursor-plane-move)
[99.847679] Starting subtest: psr2-cursor-plane-move
[99.850114] Starting dynamic subtest: eDP-1
[101.538024] Dynamic subtest eDP-1: SUCCESS (1.690s)
[101.540392] Subtest psr2-cursor-plane-move: SUCCESS (1.691s)
[102.408415] [015/156] (923s left) syncobj_timeline (wait-for-submit-complex)
[102.462754] Starting subtest: wait-for-submit-complex
[102.525224] Subtest wait-for-submit-complex: SUCCESS (0.062s)
[102.597339] [016/156] (923s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-move)
[102.693813] Starting subtest: hdr-2p-scndscrn-cur-indfb-move
[102.697831] Subtest hdr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[102.775357] [017/156] (923s left) kms_pm_backlight (fade)
[102.859885] Starting subtest: fade
[102.909909] Starting dynamic subtest: eDP-1
[105.907511] Dynamic subtest eDP-1: SUCCESS (2.997s)
[105.909964] Subtest fade: SUCCESS (3.047s)
[106.738631] [018/156] (919s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt)
[106.839354] Starting subtest: psr-2p-primscrn-indfb-msflip-blt
[106.843698] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[106.930837] [019/156] (919s left) gem_caching (reads)
[106.983986] Subtest reads: SKIP (0.000s)
[107.058886] [020/156] (919s left) kms_flip_event_leak (basic)
[107.154683] Starting subtest: basic
[107.156805] Starting dynamic subtest: pipe-A-eDP-1
[107.575700] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.422s)
[107.584722] Starting dynamic subtest: pipe-B-eDP-1
[108.665900] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.087s)
[108.674948] Starting dynamic subtest: pipe-C-eDP-1
[109.787131] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.120s)
[109.797359] Starting dynamic subtest: pipe-D-eDP-1
[110.916407] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.129s)
[110.918875] Subtest basic: SUCCESS (3.762s)
[111.775290] [021/156] (914s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs)
[111.862378] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs
[111.864838] Starting dynamic subtest: pipe-A-eDP-1
[111.887458] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[111.896860] Starting dynamic subtest: pipe-B-eDP-1
[111.917134] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[111.925070] Starting dynamic subtest: pipe-C-eDP-1
[111.945805] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[111.953421] Starting dynamic subtest: pipe-D-eDP-1
[111.975753] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[111.980284] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs: SKIP (0.006s)
[114.139292] [022/156] (912s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-sf)
[114.248753] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-sf
[114.252876] Subtest fbc-pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[114.316393] [023/156] (912s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-move)
[114.424543] Starting subtest: psrhdr-2p-primscrn-cur-indfb-move
[114.428870] Subtest psrhdr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[114.503126] [024/156] (912s left) kms_joiner (invalid-modeset-ultra-joiner)
[114.593997] Starting subtest: invalid-modeset-ultra-joiner
[114.598344] Subtest invalid-modeset-ultra-joiner: SKIP (0.000s)
[114.674930] [025/156] (911s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-move)
[114.779849] Starting subtest: hdr-1p-primscrn-cur-indfb-move
[114.783780] Subtest hdr-1p-primscrn-cur-indfb-move: SKIP (0.000s)
[114.882228] [026/156] (911s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-onoff)
[114.982336] Starting subtest: fbc-1p-primscrn-cur-indfb-onoff
[118.871473] Subtest fbc-1p-primscrn-cur-indfb-onoff: SUCCESS (3.892s)
[120.047190] [027/156] (906s left) kms_frontbuffer_tracking (psr-slowdraw)
[120.142548] Starting subtest: psr-slowdraw
[145.376490] Subtest psr-slowdraw: SUCCESS (25.237s)
[145.508466] [028/156] (881s left) syncobj_timeline (invalid-single-wait-unsubmitted)
[145.568086] Starting subtest: invalid-single-wait-unsubmitted
[145.570045] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[145.656893] [029/156] (881s left) perf (polling)
[145.780738] Starting subtest: polling
[145.782626] Starting dynamic subtest: 0-rcs0
[155.808122] Dynamic subtest 0-rcs0: SUCCESS (10.026s)
[155.817098] Starting dynamic subtest: 1-vecs0
[165.833497] Dynamic subtest 1-vecs0: SUCCESS (10.025s)
[165.836096] Subtest polling: SUCCESS (20.052s)
[165.878704] [030/156] (860s left) kms_flip (2x-flip-vs-absolute-wf_vblank-interruptible)
[165.972264] Starting subtest: 2x-flip-vs-absolute-wf_vblank-interruptible
[165.983575] Subtest 2x-flip-vs-absolute-wf_vblank-interruptible: SKIP (0.010s)
[166.056444] [031/156] (860s left) kms_cursor_legacy (cursora-vs-flipa-varying-size)
[166.167465] Starting subtest: cursorA-vs-flipA-varying-size
[177.300502] Subtest cursorA-vs-flipA-varying-size: SUCCESS (11.130s)
[178.103396] [032/156] (848s left) gem_lmem_swapping (heavy-verify-random)
[178.146335] Subtest heavy-verify-random: SKIP (0.000s)
[178.189479] [033/156] (848s left) prime_vgem (wait)
[178.320112] Starting subtest: wait
[178.322275] Starting dynamic subtest: rcs0
[178.353487] Dynamic subtest rcs0: SUCCESS (0.036s)
[178.362258] Starting dynamic subtest: bcs0
[178.410204] Dynamic subtest bcs0: SUCCESS (0.056s)
[178.418303] Starting dynamic subtest: vcs0
[178.457133] Dynamic subtest vcs0: SUCCESS (0.046s)
[178.465340] Starting dynamic subtest: vcs1
[178.501208] Dynamic subtest vcs1: SUCCESS (0.043s)
[178.509301] Starting dynamic subtest: vecs0
[178.549633] Dynamic subtest vecs0: SUCCESS (0.047s)
[178.557640] Starting dynamic subtest: ccs0
[178.594525] Dynamic subtest ccs0: SUCCESS (0.044s)
[178.596842] Subtest wait: SUCCESS (0.277s)
[178.716892] [FACT prime_vgem (wait)] new: kernel.kmod_is_loaded.vgem: true
[178.720167] [034/156] (848s left) kms_plane_alpha_blend (alpha-basic)
[178.806360] Starting subtest: alpha-basic
[179.090280] Starting dynamic subtest: pipe-A-eDP-1
[181.020902] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.927s)
[181.046443] Starting dynamic subtest: pipe-D-eDP-1
[183.702517] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.662s)
[183.704981] Subtest alpha-basic: SUCCESS (4.897s)
[184.569414] [035/156] (842s left) i915_drm_fdinfo (context-close-stress)
[184.608760] Starting subtest: context-close-stress
[195.354689] Subtest context-close-stress: SUCCESS (10.744s)
[195.446580] [036/156] (831s left) gem_eio (throttle)
[195.799576] Starting subtest: throttle
[196.139545] Subtest throttle: SUCCESS (0.339s)
[196.259908] [037/156] (830s left) perf_pmu (enable-race)
[196.299202] Starting subtest: enable-race
[196.301388] Starting dynamic subtest: rcs0
[209.063478] Dynamic subtest rcs0: SUCCESS (12.765s)
[209.074262] Starting dynamic subtest: bcs0
[221.802022] Dynamic subtest bcs0: SUCCESS (12.737s)
[221.812644] Starting dynamic subtest: vcs0
[237.316608] Dynamic subtest vcs0: SUCCESS (15.510s)
[237.325847] Starting dynamic subtest: vcs1
[253.251376] Dynamic subtest vcs1: SUCCESS (15.933s)
[253.263667] Starting dynamic subtest: vecs0
[269.316549] Dynamic subtest vecs0: SUCCESS (16.061s)
[269.326079] Starting dynamic subtest: ccs0
[282.063775] Dynamic subtest ccs0: SUCCESS (12.745s)
[282.066909] Subtest enable-race: SUCCESS (85.763s)
[282.182668] [038/156] (744s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-blt)
[282.282763] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-blt
[282.286851] Subtest psr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[282.373864] [039/156] (744s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-signa
[282.430497] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-signaled
[282.535376] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-signaled: SUCCESS (0.105s)
[282.609235] [040/156] (744s left) gem_eio (wait-wedge-10ms)
[282.819807] Starting subtest: wait-wedge-10ms
[284.371683] Subtest wait-wedge-10ms: SUCCESS (1.550s)
[284.497970] [041/156] (742s left) kms_big_fb (yf-tiled-8bpp-rotate-90)
[284.591830] Starting subtest: yf-tiled-8bpp-rotate-90
[284.596151] Subtest yf-tiled-8bpp-rotate-90: SKIP (0.000s)
[284.688700] [042/156] (742s left) gem_softpin (allocator-basic-reserve)
[284.740567] Starting subtest: allocator-basic-reserve
[284.802445] Subtest allocator-basic-reserve: SUCCESS (0.062s)
[284.926971] [043/156] (742s left) gem_exec_balancer (bonded-pair)
[284.992851] Starting subtest: bonded-pair
[284.997509] Subtest bonded-pair: SKIP (0.000s)
[285.303696] [044/156] (742s left) kms_chamelium_audio (dp-audio)
[285.683179] Subtest dp-audio: SKIP (0.000s)
[286.470102] [045/156] (740s left) kms_cursor_crc (cursor-rapid-movement-256x85)
[286.732352] Starting subtest: cursor-rapid-movement-256x85
[286.774588] Subtest cursor-rapid-movement-256x85: SKIP (0.040s)
[286.956585] [046/156] (740s left) i915_suspend (basic-s2idle-without-i915)
[286.990990] Starting subtest: basic-s2idle-without-i915
[306.659076] Subtest basic-s2idle-without-i915: SUCCESS (4.186s)
[306.761160] [047/156] (720s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted)
[306.830134] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted
[306.832030] Subtest multi-wait-for-submit-available-unsubmitted-submitted: SUCCESS (0.000s)
[306.937357] [048/156] (720s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-render)
[307.031949] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-render
[307.035823] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-render: SKIP (0.000s)
[307.126226] [049/156] (720s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[307.211927] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[307.215771] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[307.304601] [050/156] (720s left) gem_mmap (pf-nonblock)
[307.371403] Subtest pf-nonblock: SKIP (0.000s)
[307.426515] [051/156] (720s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[307.523659] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[307.526975] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[307.619708] [052/156] (720s left) gem_basic (multigpu-create-close)
[307.668734] Starting subtest: multigpu-create-close
[307.672198] Subtest multigpu-create-close: SKIP (0.000s)
[307.761779] [053/156] (719s left) kms_frontbuffer_tracking (fbchdr-2p-shrfb-fliptrack-mmap-gtt)
[307.857581] Starting subtest: fbchdr-2p-shrfb-fliptrack-mmap-gtt
[307.860873] Subtest fbchdr-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[307.946983] [054/156] (719s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-move)
[308.044666] Starting subtest: fbc-1p-primscrn-spr-indfb-move
[312.155529] Subtest fbc-1p-primscrn-spr-indfb-move: SUCCESS (4.234s)
[313.289215] [055/156] (714s left) gem_exec_params (no-vebox)
[313.342707] Starting subtest: no-vebox
[313.347166] Subtest no-vebox: SUCCESS (0.003s)
[314.911731] [056/156] (712s left) gem_exec_schedule (smoketest-all)
[314.986331] Starting subtest: smoketest-all
[347.242022] Subtest smoketest-all: SUCCESS (32.320s)
[348.897453] [057/156] (678s left) kms_properties (connector-properties-legacy)
[348.992427] Starting subtest: connector-properties-legacy
[348.994417] Starting dynamic subtest: pipe-A-eDP-1
[356.942925] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.951s)
[356.952177] Starting dynamic subtest: pipe-None-DP-1
[356.956602] Dynamic subtest pipe-None-DP-1: SUCCESS (0.001s)
[356.964992] Starting dynamic subtest: pipe-None-HDMI-A-1
[356.969049] Dynamic subtest pipe-None-HDMI-A-1: SUCCESS (0.001s)
[356.977796] Starting dynamic subtest: pipe-None-DP-2
[356.982046] Dynamic subtest pipe-None-DP-2: SUCCESS (0.001s)
[356.990612] Starting dynamic subtest: pipe-None-HDMI-A-2
[356.995016] Dynamic subtest pipe-None-HDMI-A-2: SUCCESS (0.001s)
[357.006900] Starting dynamic subtest: pipe-None-DP-3
[357.011190] Dynamic subtest pipe-None-DP-3: SUCCESS (0.001s)
[357.020848] Starting dynamic subtest: pipe-None-DP-4
[357.025127] Dynamic subtest pipe-None-DP-4: SUCCESS (0.001s)
[357.027263] Subtest connector-properties-legacy: SUCCESS (7.962s)
[357.812820] [058/156] (670s left) gem_userptr_blits (set-cache-level)
[357.882675] Starting subtest: set-cache-level
[357.887009] Subtest set-cache-level: SKIP (0.000s)
[358.031290] [059/156] (669s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-upscaling)
[358.129873] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-upscaling
[358.132205] Starting dynamic subtest: pipe-A-default-mode
[360.856528] Dynamic subtest pipe-A-default-mode: SUCCESS (2.727s)
[360.858794] Subtest flip-64bpp-xtile-to-32bpp-xtile-upscaling: SUCCESS (2.729s)
[361.723687] [060/156] (666s left) gem_set_tiling_vs_blt (tiled-to-tiled)
[361.780398] Subtest tiled-to-tiled: SKIP (0.000s)
[361.855108] [061/156] (666s left) kms_color_pipeline (plane-lut1d-ctm3x4)
[361.940509] Starting subtest: plane-lut1d-ctm3x4
[362.064307] Starting dynamic subtest: pipe-A-plane-0
[362.241759] Dynamic subtest pipe-A-plane-0: SUCCESS (0.177s)
[362.251055] Starting dynamic subtest: pipe-A-plane-1
[362.386240] Dynamic subtest pipe-A-plane-1: SUCCESS (0.144s)
[362.396126] Starting dynamic subtest: pipe-A-plane-2
[362.519473] Dynamic subtest pipe-A-plane-2: SUCCESS (0.133s)
[363.695332] Starting dynamic subtest: pipe-B-plane-0
[363.864754] Dynamic subtest pipe-B-plane-0: SUCCESS (0.177s)
[363.874925] Starting dynamic subtest: pipe-B-plane-1
[363.998187] Dynamic subtest pipe-B-plane-1: SUCCESS (0.133s)
[364.007920] Starting dynamic subtest: pipe-B-plane-2
[364.142630] Dynamic subtest pipe-B-plane-2: SUCCESS (0.144s)
[365.335654] Starting dynamic subtest: pipe-C-plane-0
[365.461230] Dynamic subtest pipe-C-plane-0: SUCCESS (0.133s)
[365.471585] Starting dynamic subtest: pipe-C-plane-1
[365.606428] Dynamic subtest pipe-C-plane-1: SUCCESS (0.144s)
[365.619203] Starting dynamic subtest: pipe-C-plane-2
[365.750257] Dynamic subtest pipe-C-plane-2: SUCCESS (0.144s)
[366.960205] Starting dynamic subtest: pipe-D-plane-0
[367.098336] Dynamic subtest pipe-D-plane-0: SUCCESS (0.144s)
[367.108760] Starting dynamic subtest: pipe-D-plane-1
[367.262695] Dynamic subtest pipe-D-plane-1: SUCCESS (0.166s)
[367.272708] Starting dynamic subtest: pipe-D-plane-2
[367.407050] Dynamic subtest pipe-D-plane-2: SUCCESS (0.143s)
[367.703960] Subtest plane-lut1d-ctm3x4: SUCCESS (5.763s)
[368.526180] [062/156] (659s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-plflip-blt)
[368.641170] Starting subtest: fbc-1p-primscrn-shrfb-plflip-blt
[373.265650] Subtest fbc-1p-primscrn-shrfb-plflip-blt: SUCCESS (4.629s)
[374.431655] [063/156] (653s left) gem_userptr_blits (sync-unmap-after-close)
[374.504808] Starting subtest: sync-unmap-after-close
[374.515362] Subtest sync-unmap-after-close: SUCCESS (0.013s)
[374.636860] [064/156] (653s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-onoff)
[374.738288] Starting subtest: fbc-1p-primscrn-spr-indfb-onoff
[378.669874] Subtest fbc-1p-primscrn-spr-indfb-onoff: SUCCESS (3.938s)
[379.833482] [065/156] (648s left) gem_exec_schedule (thriceslice)
[379.902796] Starting subtest: thriceslice
[379.904787] Starting dynamic subtest: rcs0
[379.926141] Dynamic subtest rcs0: SUCCESS (0.032s)
[379.938200] Starting dynamic subtest: bcs0
[379.955597] Dynamic subtest bcs0: SUCCESS (0.029s)
[379.968295] Starting dynamic subtest: vcs0
[379.985538] Dynamic subtest vcs0: SUCCESS (0.029s)
[379.995026] Starting dynamic subtest: vcs1
[380.015524] Dynamic subtest vcs1: SUCCESS (0.028s)
[380.025036] Starting dynamic subtest: vecs0
[380.043873] Dynamic subtest vecs0: SUCCESS (0.027s)
[380.057373] Starting dynamic subtest: ccs0
[380.072804] Dynamic subtest ccs0: SUCCESS (0.029s)
[380.075374] Subtest thriceslice: SUCCESS (0.179s)
[380.374245] [066/156] (647s left) gem_render_copy_redux (interruptible)
[380.433664] Starting subtest: interruptible
[380.694270] Subtest interruptible: SUCCESS (0.261s)
[380.820337] [067/156] (647s left) i915_pm_rps (fence-order)
[380.872079] Starting subtest: fence-order
[383.964437] Subtest fence-order: SUCCESS (3.090s)
[384.082990] [068/156] (644s left) kms_content_protection (legacy-hdcp14)
[384.226057] Starting subtest: legacy-hdcp14
[384.230693] Subtest legacy-hdcp14: SKIP (0.000s)
[384.350612] [069/156] (643s left) gem_tiled_blits (basic)
[384.411425] Subtest basic: SKIP (0.000s)
[384.487541] [070/156] (643s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-shrfb-plflip-blt)
[384.588988] Starting subtest: psrhdr-2p-primscrn-shrfb-plflip-blt
[384.593146] Subtest psrhdr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[384.688621] [071/156] (643s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[384.778555] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-cpu
[391.831362] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (7.060s)
[391.932159] [072/156] (636s left) kms_cursor_crc (cursor-offscreen-64x21)
[392.123688] Starting subtest: cursor-offscreen-64x21
[392.178794] Subtest cursor-offscreen-64x21: SKIP (0.049s)
[392.409381] [073/156] (636s left) kms_psr (fbc-psr-sprite-plane-onoff)
[392.494224] Starting subtest: fbc-psr-sprite-plane-onoff
[392.496553] Starting dynamic subtest: eDP-1
[392.500962] Dynamic subtest eDP-1: SKIP (0.000s)
[392.505369] Subtest fbc-psr-sprite-plane-onoff: SKIP (0.001s)
[392.569160] [074/156] (635s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-20x20)
[392.657644] Starting subtest: planes-downscale-factor-0-5-upscale-20x20
[392.659778] Starting dynamic subtest: pipe-A
[392.678435] Dynamic subtest pipe-A: SKIP (0.017s)
[392.688917] Starting dynamic subtest: pipe-B
[392.710432] Dynamic subtest pipe-B: SKIP (0.031s)
[392.720593] Starting dynamic subtest: pipe-C
[392.740477] Dynamic subtest pipe-C: SKIP (0.030s)
[392.753213] Starting dynamic subtest: pipe-D
[392.770029] Dynamic subtest pipe-D: SKIP (0.030s)
[392.773166] Subtest planes-downscale-factor-0-5-upscale-20x20: SKIP (0.110s)
[392.880280] [075/156] (635s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-rc-ccs)
[392.972071] Starting subtest: missing-ccs-buffer-y-tiled-gen12-rc-ccs
[392.973879] Starting dynamic subtest: pipe-A-eDP-1
[392.992818] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[393.004217] Starting dynamic subtest: pipe-B-eDP-1
[393.022177] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[393.031835] Starting dynamic subtest: pipe-C-eDP-1
[393.050007] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[393.060094] Starting dynamic subtest: pipe-D-eDP-1
[393.078467] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[393.081848] Subtest missing-ccs-buffer-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[395.257516] [076/156] (633s left) core_setmaster (master-drop-set-shared-fd)
[395.355049] Starting subtest: master-drop-set-shared-fd
[395.362927] Subtest master-drop-set-shared-fd: SUCCESS (0.010s)
[395.401054] [077/156] (633s left) syncobj_wait (wait-all-interrupted)
[395.452433] Starting subtest: wait-all-interrupted
[395.553020] Subtest wait-all-interrupted: SUCCESS (0.102s)
[395.633211] [078/156] (633s left) kms_plane (pixel-format-y-tiled-gen12-mc-ccs-modifier)
[395.732593] Starting subtest: pixel-format-y-tiled-gen12-mc-ccs-modifier
[395.737103] Subtest pixel-format-y-tiled-gen12-mc-ccs-modifier: SKIP (0.000s)
[395.804469] [079/156] (632s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-cur-indfb-draw-render)
[395.924134] Starting subtest: fbchdr-2p-scndscrn-cur-indfb-draw-render
[395.928288] Subtest fbchdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[396.027187] [080/156] (632s left) perf_pmu (rc6)
[396.066633] Starting subtest: rc6
[396.068813] Starting dynamic subtest: gt0
[402.441281] Dynamic subtest gt0: SUCCESS (6.375s)
[402.454562] Starting dynamic subtest: runtime-pm-gt0
[410.169925] Dynamic subtest runtime-pm-gt0: SUCCESS (7.724s)
[410.183369] Starting dynamic subtest: runtime-pm-long-gt0
[422.653324] Dynamic subtest runtime-pm-long-gt0: SUCCESS (12.478s)
[422.666646] Starting dynamic subtest: other-idle-gt0
[428.998510] Dynamic subtest other-idle-gt0: SUCCESS (6.342s)
[429.009448] Starting dynamic subtest: gt1
[435.340680] Dynamic subtest gt1: SUCCESS (6.339s)
[435.356056] Starting dynamic subtest: runtime-pm-gt1
[442.778818] Dynamic subtest runtime-pm-gt1: SUCCESS (7.436s)
[442.791220] Starting dynamic subtest: runtime-pm-long-gt1
[455.285560] Dynamic subtest runtime-pm-long-gt1: SUCCESS (12.490s)
[455.296364] Starting dynamic subtest: other-idle-gt1
[461.613703] Dynamic subtest other-idle-gt1: SUCCESS (6.342s)
[461.616836] Subtest rc6: SUCCESS (65.544s)
[462.789290] [081/156] (566s left) kms_flip (2x-flip-vs-panning-vs-hang)
[462.885847] Starting subtest: 2x-flip-vs-panning-vs-hang
[462.890439] Subtest 2x-flip-vs-panning-vs-hang: SKIP (0.002s)
[462.960135] [082/156] (565s left) gen9_exec_parse (allowed-single)
[463.016035] Subtest allowed-single: SKIP (0.000s)
[463.089801] [083/156] (565s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite)
[463.187160] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite
[463.190709] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[463.265814] [084/156] (565s left) kms_feature_discovery (psr2)
[463.355377] Starting subtest: psr2
[463.357410] Subtest psr2: SUCCESS (0.000s)
[463.406963] [085/156] (565s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[463.517853] Starting subtest: cursorB-vs-flipB-legacy
[463.521937] Subtest cursorB-vs-flipB-legacy: SKIP (0.001s)
[463.640352] [086/156] (565s left) kms_frontbuffer_tracking (hdr-rgb565-draw-mmap-cpu)
[463.741343] Starting subtest: hdr-rgb565-draw-mmap-cpu
[463.744818] Subtest hdr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[463.819645] [087/156] (565s left) gem_exec_flush (basic-uc-rw-default)
[463.883432] Starting subtest: basic-uc-rw-default
[469.334848] Subtest basic-uc-rw-default: SUCCESS (5.452s)
[469.497854] [088/156] (559s left) gem_cs_tlb (engines)
[469.555478] Starting subtest: engines
[469.557965] Starting dynamic subtest: rcs0
[471.713996] Dynamic subtest rcs0: SUCCESS (2.154s)
[471.728379] Starting dynamic subtest: bcs0
[473.866493] Dynamic subtest bcs0: SUCCESS (2.152s)
[473.883419] Starting dynamic subtest: vcs0
[476.019827] Dynamic subtest vcs0: SUCCESS (2.152s)
[476.032823] Starting dynamic subtest: vcs1
[478.173142] Dynamic subtest vcs1: SUCCESS (2.152s)
[478.186132] Starting dynamic subtest: vecs0
[480.326274] Dynamic subtest vecs0: SUCCESS (2.153s)
[480.338817] Starting dynamic subtest: ccs0
[482.480371] Dynamic subtest ccs0: SUCCESS (2.153s)
[482.482914] Subtest engines: SUCCESS (12.923s)
[482.573063] [089/156] (546s left) gem_exec_fence (syncobj-timeline-invalid-wait)
[483.169683] Starting subtest: syncobj-timeline-invalid-wait
[483.176667] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.005s)
[484.791020] [090/156] (544s left) i915_module_load (reload-no-display)
[484.826957] Starting subtest: reload-no-display
[490.756770] Subtest reload-no-display: SUCCESS (5.935s)
[490.786727] [FACT i915_module_load (reload-no-display)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[490.787427] [FACT i915_module_load (reload-no-display)] deleted: kernel.kmod_is_loaded.i915: true
[490.790753] [091/156] (538s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[492.277645] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[492.282204] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[492.375727] [FACT kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[492.377006] [FACT kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)] new: kernel.kmod_is_loaded.i915: true
[492.381019] [092/156] (536s left) kms_cursor_legacy (forked-bo)
[492.507631] Starting subtest: forked-bo
[492.509940] Starting dynamic subtest: pipe-A
[497.963983] Dynamic subtest pipe-A: SUCCESS (5.454s)
[497.981534] Starting dynamic subtest: pipe-B
[503.441440] Dynamic subtest pipe-B: SUCCESS (5.476s)
[503.456989] Starting dynamic subtest: pipe-C
[508.911175] Dynamic subtest pipe-C: SUCCESS (5.468s)
[508.922598] Starting dynamic subtest: pipe-D
[514.383318] Dynamic subtest pipe-D: SUCCESS (5.471s)
[514.395941] Starting dynamic subtest: all-pipes
[519.893695] Dynamic subtest all-pipes: SUCCESS (5.509s)
[519.896042] Subtest forked-bo: SUCCESS (27.392s)
[520.026787] [093/156] (509s left) perf_pmu (busy-hang)
[520.072935] Starting subtest: busy-hang
[520.075211] Starting dynamic subtest: rcs0
[521.273571] Dynamic subtest rcs0: SUCCESS (1.203s)
[521.286347] Starting dynamic subtest: bcs0
[522.434932] Dynamic subtest bcs0: SUCCESS (1.160s)
[522.449711] Starting dynamic subtest: vcs0
[524.297280] Dynamic subtest vcs0: SUCCESS (1.857s)
[524.306771] Starting dynamic subtest: vcs1
[526.273397] Dynamic subtest vcs1: SUCCESS (1.974s)
[526.285046] Starting dynamic subtest: vecs0
[528.317247] Dynamic subtest vecs0: SUCCESS (2.045s)
[528.328937] Starting dynamic subtest: ccs0
[529.472282] Dynamic subtest ccs0: SUCCESS (1.153s)
[529.474881] Subtest busy-hang: SUCCESS (9.404s)
[529.559093] [094/156] (499s left) kms_hdr (bpc-switch-suspend)
[529.654826] Starting subtest: bpc-switch-suspend
[529.657154] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010
[551.330620] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SUCCESS (6.668s)
[551.341978] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F
[572.308335] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SUCCESS (5.753s)
[572.310647] Subtest bpc-switch-suspend: SUCCESS (12.424s)
[572.410225] [095/156] (456s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[572.500943] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[572.503128] Starting dynamic subtest: pipe-A-eDP-1
[573.296685] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.818s)
[573.306710] Starting dynamic subtest: pipe-B-eDP-1
[574.871696] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.619s)
[574.884240] Starting dynamic subtest: pipe-C-eDP-1
[576.377442] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.542s)
[576.387757] Starting dynamic subtest: pipe-D-eDP-1
[577.870581] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.521s)
[577.872960] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.504s)
[579.030185] [096/156] (450s left) syncobj_wait (invalid-multi-wait-unsubmitted)
[579.082315] Starting subtest: invalid-multi-wait-unsubmitted
[579.084635] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[579.175317] [097/156] (450s left) gem_exec_balancer (smoke)
[579.244889] Starting subtest: smoke
[601.694209] Subtest smoke: SUCCESS (22.565s)
[602.601192] [098/156] (426s left) gem_exec_schedule (preempt-engines)
[602.668604] Starting subtest: preempt-engines
[602.671052] Starting dynamic subtest: rcs0
[604.992536] Dynamic subtest rcs0: SUCCESS (2.333s)
[605.003081] Starting dynamic subtest: bcs0
[607.346845] Dynamic subtest bcs0: SUCCESS (2.350s)
[607.357394] Starting dynamic subtest: vcs0
[609.616489] Dynamic subtest vcs0: SUCCESS (2.267s)
[609.631379] Starting dynamic subtest: vcs1
[611.893033] Dynamic subtest vcs1: SUCCESS (2.273s)
[611.903357] Starting dynamic subtest: vecs0
[614.164616] Dynamic subtest vecs0: SUCCESS (2.269s)
[614.175413] Starting dynamic subtest: ccs0
[616.448602] Dynamic subtest ccs0: SUCCESS (2.281s)
[616.450942] Subtest preempt-engines: SUCCESS (13.787s)
[616.774918] [099/156] (412s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[616.872599] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[616.876920] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[616.943123] [100/156] (412s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[617.006333] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[617.010854] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[617.169627] [101/156] (412s left) gem_ctx_persistence (processes)
[617.219150] Starting subtest: processes
[619.240089] Subtest processes: SUCCESS (2.021s)
[619.295958] [102/156] (410s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-mmap-wc)
[619.406260] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-mmap-wc
[619.410713] Subtest fbchdr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[619.480189] [103/156] (410s left) kms_chamelium_frames (dp-crc-fast)
[619.859770] Subtest dp-crc-fast: SKIP (0.000s)
[620.653430] [104/156] (409s left) kms_psr (fbc-pr-sprite-mmap-gtt)
[620.746340] Starting subtest: fbc-pr-sprite-mmap-gtt
[620.750617] Subtest fbc-pr-sprite-mmap-gtt: SKIP (0.000s)
[620.799453] [105/156] (409s left) gem_mmap_offset (bad-flags)
[620.852889] Starting subtest: bad-flags
[620.855228] Subtest bad-flags: SUCCESS (0.000s)
[620.935719] [106/156] (408s left) kms_getfb (getfb-handle-valid)
[621.024913] Starting subtest: getfb-handle-valid
[621.027200] Subtest getfb-handle-valid: SUCCESS (0.000s)
[621.105049] [107/156] (408s left) gem_mmap_gtt (cpuset-big-copy-xy)
[621.162087] Subtest cpuset-big-copy-XY: SKIP (0.000s)
[621.229978] [108/156] (408s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[621.333556] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-cpu
[621.337537] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[621.403588] [109/156] (408s left) core_getclient (basic)
[621.452734] Starting subtest: basic
[621.455020] Subtest basic: SUCCESS (0.000s)
[621.528834] [110/156] (408s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[621.619376] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[621.622876] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[621.699267] [111/156] (408s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-blt)
[621.804228] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-blt
[621.807878] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[621.895962] [112/156] (408s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[621.992013] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu
[629.118117] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (7.132s)
[629.230280] [113/156] (400s left) kms_sequence (queue-busy)
[629.316744] Starting subtest: queue-busy
[629.319086] Starting dynamic subtest: pipe-A-eDP-1
[631.240023] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.924s)
[631.250880] Starting dynamic subtest: pipe-B-eDP-1
[633.880349] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.638s)
[633.891758] Starting dynamic subtest: pipe-C-eDP-1
[636.495471] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.617s)
[636.506189] Starting dynamic subtest: pipe-D-eDP-1
[639.130438] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.632s)
[639.132947] Subtest queue-busy: SUCCESS (9.816s)
[639.995423] [114/156] (390s left) gem_eio (in-flight-internal-10ms)
[640.202371] Starting subtest: in-flight-internal-10ms
[641.257227] Subtest in-flight-internal-10ms: SUCCESS (1.055s)
[641.374347] [115/156] (388s left) kms_flip (2x-flip-vs-expired-vblank-interruptible)
[641.473274] Starting subtest: 2x-flip-vs-expired-vblank-interruptible
[641.483892] Subtest 2x-flip-vs-expired-vblank-interruptible: SKIP (0.010s)
[641.570843] [116/156] (388s left) kms_async_flips (crc)
[641.669566] Starting subtest: crc
[642.052339] Starting dynamic subtest: pipe-A-eDP-1
[644.935951] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.882s)
[645.329845] Starting dynamic subtest: pipe-B-eDP-1
[648.299181] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.981s)
[648.672721] Starting dynamic subtest: pipe-C-eDP-1
[651.573007] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.911s)
[651.939814] Starting dynamic subtest: pipe-D-eDP-1
[654.818263] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.890s)
[654.820580] Subtest crc: SUCCESS (13.154s)
[655.950374] [117/156] (374s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[656.047598] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[656.051905] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[656.106573] [118/156] (374s left) kms_big_fb (x-tiled-32bpp-rotate-180)
[656.219730] Starting subtest: x-tiled-32bpp-rotate-180
[657.553069] Subtest x-tiled-32bpp-rotate-180: SUCCESS (1.334s)
[657.666195] [119/156] (372s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[657.762381] Starting subtest: psrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[657.765768] Subtest psrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[657.828436] [120/156] (372s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[657.926698] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip
[658.908400] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (0.981s)
[659.021960] [121/156] (371s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-shrfb-draw-pwrite)
[659.126742] Starting subtest: fbchdr-1p-primscrn-pri-shrfb-draw-pwrite
[659.130792] Subtest fbchdr-1p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[659.203427] [122/156] (371s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-indfb-draw-blt)
[659.301299] Starting subtest: fbcpsrhdr-1p-primscrn-pri-indfb-draw-blt
[659.304625] Subtest fbcpsrhdr-1p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[659.374660] [123/156] (371s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-wc)
[659.475109] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-wc
[659.478422] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[659.560063] [124/156] (371s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[659.653440] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[659.657002] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[659.739143] [125/156] (370s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[659.830407] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[659.833826] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[659.899412] [126/156] (370s left) gem_pxp (protected-encrypted-src-copy-not-readible)
[660.018380] Starting subtest: protected-encrypted-src-copy-not-readible
[660.129470] Subtest protected-encrypted-src-copy-not-readible: SUCCESS (0.112s)
[660.220721] [127/156] (370s left) kms_psr (psr-cursor-render)
[660.304617] Starting subtest: psr-cursor-render
[660.307006] Starting dynamic subtest: eDP-1
[662.058439] Dynamic subtest eDP-1: SUCCESS (1.755s)
[662.060787] Subtest psr-cursor-render: SUCCESS (1.756s)
[662.906236] [128/156] (367s left) kms_psr (fbc-psr2-primary-render)
[662.994257] Starting subtest: fbc-psr2-primary-render
[662.996577] Starting dynamic subtest: eDP-1
[663.000726] Dynamic subtest eDP-1: SKIP (0.000s)
[663.004775] Subtest fbc-psr2-primary-render: SKIP (0.001s)
[663.044113] [129/156] (367s left) gem_ctx_persistence (engines-hostile-preempt)
[663.104306] Starting subtest: engines-hostile-preempt
[663.106610] Starting dynamic subtest: rcs0
[663.208306] Dynamic subtest rcs0: SUCCESS (0.103s)
[663.221066] Starting dynamic subtest: bcs0
[663.283558] Dynamic subtest bcs0: SUCCESS (0.073s)
[663.296719] Starting dynamic subtest: vcs0
[665.183621] Dynamic subtest vcs0: SUCCESS (1.897s)
[665.197310] Starting dynamic subtest: vcs1
[667.226433] Dynamic subtest vcs1: SUCCESS (2.041s)
[667.239609] Starting dynamic subtest: vecs0
[669.192455] Dynamic subtest vecs0: SUCCESS (1.962s)
[669.208988] Starting dynamic subtest: ccs0
[669.261212] Dynamic subtest ccs0: SUCCESS (0.068s)
[669.263742] Subtest engines-hostile-preempt: SUCCESS (6.158s)
[669.317004] [130/156] (361s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[669.414271] Starting subtest: psrhdr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[669.418737] Subtest psrhdr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[669.509944] [131/156] (361s left) kms_plane_multiple (2x-tiling-x)
[669.610423] Starting subtest: 2x-tiling-x
[669.614591] Subtest 2x-tiling-x: SKIP (0.000s)
[669.660320] [132/156] (361s left) sysfs_heartbeat_interval (invalid)
[669.742464] Starting subtest: invalid
[669.744916] Starting dynamic subtest: vecs0
[669.747261] Dynamic subtest vecs0: SUCCESS (0.000s)
[669.759605] Starting dynamic subtest: ccs0
[669.761802] Dynamic subtest ccs0: SUCCESS (0.000s)
[669.771688] Starting dynamic subtest: vcs0
[669.773850] Dynamic subtest vcs0: SUCCESS (0.000s)
[669.783819] Starting dynamic subtest: bcs0
[669.786143] Dynamic subtest bcs0: SUCCESS (0.000s)
[669.796027] Starting dynamic subtest: rcs0
[669.798229] Dynamic subtest rcs0: SUCCESS (0.000s)
[669.808156] Starting dynamic subtest: vcs1
[669.810364] Dynamic subtest vcs1: SUCCESS (0.000s)
[669.812635] Subtest invalid: SUCCESS (0.006s)
[669.851518] [133/156] (361s left) gem_exec_reloc (basic-cpu-read-active)
[669.904214] Subtest basic-cpu-read-active: SKIP (0.000s)
[669.982359] [134/156] (361s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[670.082134] Starting subtest: hdr-2p-primscrn-pri-shrfb-draw-mmap-wc
[670.086421] Subtest hdr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[670.166439] [135/156] (360s left) kms_chamelium_hpd (vga-hpd-enable-disable-mode)
[670.543166] Subtest vga-hpd-enable-disable-mode: SKIP (0.000s)
[671.332634] [136/156] (359s left) gem_mmap_wc (set-cache-level)
[671.399358] Subtest set-cache-level: SKIP (0.000s)
[671.462616] [137/156] (359s left) kms_pm_rpm (universal-planes-dpms)
[673.388805] Starting subtest: universal-planes-dpms
[673.391262] Starting dynamic subtest: plane-35
[681.788091] Dynamic subtest plane-35: SUCCESS (8.398s)
[681.809266] Starting dynamic subtest: plane-65
[690.114844] Dynamic subtest plane-65: SUCCESS (8.321s)
[690.127802] Starting dynamic subtest: plane-95
[698.457607] Dynamic subtest plane-95: SUCCESS (8.340s)
[698.470654] Starting dynamic subtest: plane-125
[706.789550] Dynamic subtest plane-125: SUCCESS (8.329s)
[706.803193] Starting dynamic subtest: plane-135
[715.130056] Dynamic subtest plane-135: SUCCESS (8.338s)
[715.145502] Starting dynamic subtest: plane-145
[723.445347] Dynamic subtest plane-145: SUCCESS (8.313s)
[723.448740] Subtest universal-planes-dpms: SUCCESS (50.059s)
[723.818618] [138/156] (307s left) gem_exec_alignment (many)
[723.874087] Starting subtest: many
[747.009888] Subtest many: SUCCESS (23.139s)
[749.339358] [139/156] (281s left) gem_exec_params (invalid-bsd1-flag-on-render)
[749.444409] Starting subtest: invalid-bsd1-flag-on-render
[749.446646] Subtest invalid-bsd1-flag-on-render: SUCCESS (0.000s)
[749.527743] [140/156] (281s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[749.641559] Starting subtest: hdr-1p-primscrn-spr-indfb-draw-mmap-wc
[749.645657] Subtest hdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[749.722227] [141/156] (281s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[749.831573] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[749.835931] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[749.907883] [142/156] (281s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[749.997261] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[750.001708] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[750.080430] [143/156] (281s left) kms_atomic (plane-overlay-legacy)
[750.181378] Starting subtest: plane-overlay-legacy
[750.183571] Starting dynamic subtest: pipe-A-eDP-1
[750.605411] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.422s)
[750.607260] Subtest plane-overlay-legacy: SUCCESS (0.424s)
[751.431759] [144/156] (280s left) gem_eio (wait-1us)
[751.650395] Starting subtest: wait-1us
[751.975658] Subtest wait-1us: SUCCESS (0.326s)
[752.068865] [145/156] (279s left) perf (short-reads)
[752.182006] Starting subtest: short-reads
[752.197320] Subtest short-reads: SUCCESS (0.016s)
[752.250246] [146/156] (279s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[752.331881] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[752.334216] Starting dynamic subtest: pipe-A-eDP-1
[754.434235] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.102s)
[754.446171] Starting dynamic subtest: pipe-B-eDP-1
[756.768783] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.333s)
[756.780595] Starting dynamic subtest: pipe-C-eDP-1
[758.875697] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.108s)
[758.887172] Starting dynamic subtest: pipe-D-eDP-1
[760.932623] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.056s)
[760.934953] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.603s)
[762.158743] [147/156] (269s left) kms_lease (lease-unleased-connector)
[762.253715] Starting subtest: lease-unleased-connector
[762.256005] Starting dynamic subtest: pipe-A-eDP-1
[762.267371] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.012s)
[762.280045] Starting dynamic subtest: pipe-B-eDP-1
[762.282440] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.006s)
[762.293593] Starting dynamic subtest: pipe-C-eDP-1
[762.295561] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.006s)
[762.305995] Starting dynamic subtest: pipe-D-eDP-1
[762.307972] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.006s)
[762.309942] Subtest lease-unleased-connector: SUCCESS (0.035s)
[762.365343] [148/156] (269s left) kms_big_fb (linear-addfb)
[762.471769] Starting subtest: linear-addfb
[762.473648] Subtest linear-addfb: SUCCESS (0.000s)
[762.535426] [149/156] (269s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt)
[762.637753] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt
[762.642325] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[762.719338] [150/156] (268s left) kms_pm_dc (dc5-dpms-negative)
[763.828438] Starting subtest: dc5-dpms-negative
[763.833145] Subtest dc5-dpms-negative: SKIP (0.000s)
[763.878878] [151/156] (267s left) gem_exec_store (dword)
[763.946281] Starting subtest: dword
[763.948697] Starting dynamic subtest: rcs0
[764.034354] Dynamic subtest rcs0: SUCCESS (0.088s)
[764.047623] Starting dynamic subtest: bcs0
[764.075830] Dynamic subtest bcs0: SUCCESS (0.040s)
[764.087217] Starting dynamic subtest: vcs0
[764.117862] Dynamic subtest vcs0: SUCCESS (0.040s)
[764.129380] Starting dynamic subtest: vcs1
[764.159761] Dynamic subtest vcs1: SUCCESS (0.040s)
[764.174938] Starting dynamic subtest: vecs0
[764.204043] Dynamic subtest vecs0: SUCCESS (0.041s)
[764.215830] Starting dynamic subtest: ccs0
[764.251480] Dynamic subtest ccs0: SUCCESS (0.046s)
[764.253867] Subtest dword: SUCCESS (0.307s)
[764.414585] [152/156] (267s left) kms_vblank (wait-forked)
[764.518391] Starting subtest: wait-forked
[764.520673] Starting dynamic subtest: pipe-A-eDP-1
[766.330454] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.812s)
[766.349015] Starting dynamic subtest: pipe-D-eDP-1
[768.876996] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.545s)
[768.879208] Subtest wait-forked: SUCCESS (4.360s)
[769.722016] [153/156] (262s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180)
[769.818301] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180
[770.906757] Subtest x-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (1.091s)
[771.011353] [154/156] (260s left) kms_psr (psr-sprite-mmap-gtt)
[771.098994] Starting subtest: psr-sprite-mmap-gtt
[771.100983] Starting dynamic subtest: eDP-1
[772.541208] Dynamic subtest eDP-1: SKIP (1.439s)
[772.546046] Subtest psr-sprite-mmap-gtt: SKIP (1.441s)
[772.687517] [155/156] (259s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions)
[772.805231] Starting subtest: cursorA-vs-flipB-atomic-transitions
[772.809967] Subtest cursorA-vs-flipB-atomic-transitions: SKIP (0.001s)
[772.933703] [156/156] (259s left) gem_ctx_param (set-priority-not-supported)
[773.000225] Starting subtest: set-priority-not-supported
[773.004807] Subtest set-priority-not-supported: SKIP (0.000s)
[773.073122] Closing watchdogs
results: parsing output: 0/ for test: gem_exec_schedule
results: parsing output: 1/ for test: gem_tiled_wc
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: prime_self_import
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_cursor_legacy
results: parsing output: 7/ for test: kms_dirtyfb
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: syncobj_timeline
results: parsing output: 10/ for test: api_intel_allocator
results: parsing output: 11/ for test: gem_exec_flush
results: parsing output: 12/ for test: kms_psr2_sf
results: parsing output: 13/ for test: kms_psr
results: parsing output: 14/ for test: syncobj_timeline
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: kms_pm_backlight
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: gem_caching
results: parsing output: 19/ for test: kms_flip_event_leak
results: parsing output: 20/ for test: kms_ccs
results: parsing output: 21/ for test: kms_psr2_sf
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: kms_joiner
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: syncobj_timeline
results: parsing output: 28/ for test: perf
results: parsing output: 29/ for test: kms_flip
results: parsing output: 30/ for test: kms_cursor_legacy
results: parsing output: 31/ for test: gem_lmem_swapping
results: parsing output: 32/ for test: prime_vgem
results: parsing output: 33/ for test: kms_plane_alpha_blend
results: parsing output: 34/ for test: i915_drm_fdinfo
results: parsing output: 35/ for test: gem_eio
results: parsing output: 36/ for test: perf_pmu
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: syncobj_timeline
results: parsing output: 39/ for test: gem_eio
results: parsing output: 40/ for test: kms_big_fb
results: parsing output: 41/ for test: gem_softpin
results: parsing output: 42/ for test: gem_exec_balancer
results: parsing output: 43/ for test: kms_chamelium_audio
results: parsing output: 44/ for test: kms_cursor_crc
results: parsing output: 45/ for test: i915_suspend
results: parsing output: 46/ for test: syncobj_timeline
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: gem_mmap
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: gem_basic
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: gem_exec_params
results: parsing output: 55/ for test: gem_exec_schedule
results: parsing output: 56/ for test: kms_properties
results: parsing output: 57/ for test: gem_userptr_blits
results: parsing output: 58/ for test: kms_flip_scaled_crc
results: parsing output: 59/ for test: gem_set_tiling_vs_blt
results: parsing output: 60/ for test: kms_color_pipeline
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: gem_userptr_blits
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: gem_exec_schedule
results: parsing output: 65/ for test: gem_render_copy_redux
results: parsing output: 66/ for test: i915_pm_rps
results: parsing output: 67/ for test: kms_content_protection
results: parsing output: 68/ for test: gem_tiled_blits
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_cursor_crc
results: parsing output: 72/ for test: kms_psr
results: parsing output: 73/ for test: kms_plane_scaling
results: parsing output: 74/ for test: kms_ccs
results: parsing output: 75/ for test: core_setmaster
results: parsing output: 76/ for test: syncobj_wait
results: parsing output: 77/ for test: kms_plane
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: perf_pmu
results: parsing output: 80/ for test: kms_flip
results: parsing output: 81/ for test: gen9_exec_parse
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: kms_feature_discovery
results: parsing output: 84/ for test: kms_cursor_legacy
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: gem_exec_flush
results: parsing output: 87/ for test: gem_cs_tlb
results: parsing output: 88/ for test: gem_exec_fence
results: parsing output: 89/ for test: i915_module_load
results: parsing output: 90/ for test: kms_big_fb
results: parsing output: 91/ for test: kms_cursor_legacy
results: parsing output: 92/ for test: perf_pmu
results: parsing output: 93/ for test: kms_hdr
results: parsing output: 94/ for test: kms_ccs
results: parsing output: 95/ for test: syncobj_wait
results: parsing output: 96/ for test: gem_exec_balancer
results: parsing output: 97/ for test: gem_exec_schedule
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: gem_render_copy
results: parsing output: 100/ for test: gem_ctx_persistence
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_chamelium_frames
results: parsing output: 103/ for test: kms_psr
results: parsing output: 104/ for test: gem_mmap_offset
results: parsing output: 105/ for test: kms_getfb
results: parsing output: 106/ for test: gem_mmap_gtt
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: core_getclient
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_sequence
results: parsing output: 113/ for test: gem_eio
results: parsing output: 114/ for test: kms_flip
results: parsing output: 115/ for test: kms_async_flips
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: kms_big_fb
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_big_fb
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: gem_pxp
results: parsing output: 126/ for test: kms_psr
results: parsing output: 127/ for test: kms_psr
results: parsing output: 128/ for test: gem_ctx_persistence
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: kms_plane_multiple
results: parsing output: 131/ for test: sysfs_heartbeat_interval
results: parsing output: 132/ for test: gem_exec_reloc
results: parsing output: 133/ for test: kms_frontbuffer_tracking
results: parsing output: 134/ for test: kms_chamelium_hpd
results: parsing output: 135/ for test: gem_mmap_wc
results: parsing output: 136/ for test: kms_pm_rpm
results: parsing output: 137/ for test: gem_exec_alignment
results: parsing output: 138/ for test: gem_exec_params
results: parsing output: 139/ for test: kms_frontbuffer_tracking
results: parsing output: 140/ for test: kms_big_fb
results: parsing output: 141/ for test: kms_frontbuffer_tracking
results: parsing output: 142/ for test: kms_atomic
results: parsing output: 143/ for test: gem_eio
results: parsing output: 144/ for test: perf
results: parsing output: 145/ for test: kms_ccs
results: parsing output: 146/ for test: kms_lease
results: parsing output: 147/ for test: kms_big_fb
results: parsing output: 148/ for test: kms_frontbuffer_tracking
results: parsing output: 149/ for test: kms_pm_dc
results: parsing output: 150/ for test: gem_exec_store
results: parsing output: 151/ for test: kms_vblank
results: parsing output: 152/ for test: kms_big_fb
results: parsing output: 153/ for test: kms_psr
results: parsing output: 154/ for test: kms_cursor_legacy
results: parsing output: 155/ for test: gem_ctx_param
Done.