[57.214894] Initializing watchdogs
[57.214942]   /dev/watchdog0
[57.229921] [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.234554] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.236927] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.240326] [001/155] (960s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[57.369431] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[57.371719] Starting dynamic subtest: pipe-A-eDP-1
[57.651858] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.251s)
[57.661130] Starting dynamic subtest: pipe-B-eDP-1
[58.917610] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.265s)
[58.925788] Starting dynamic subtest: pipe-C-eDP-1
[60.208132] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.291s)
[60.217337] Starting dynamic subtest: pipe-D-eDP-1
[61.452344] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.244s)
[61.454252] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (4.054s)
[62.655685] [002/155] (955s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-blt)
[62.757588] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-blt
[62.761838] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[62.874442] [003/155] (954s left) drm_buddy (drm_buddy)
[62.913723] Starting subtest: drm_buddy
[62.918164] Subtest drm_buddy: SKIP (0.001s)
[62.969702] [004/155] (954s left) kms_ccs (bad-rotation-90-4-tiled-bmg-ccs)
[63.057721] Starting subtest: bad-rotation-90-4-tiled-bmg-ccs
[63.062326] Subtest bad-rotation-90-4-tiled-bmg-ccs: SKIP (0.000s)
[64.196621] [005/155] (953s left) kms_big_fb (yf-tiled-32bpp-rotate-0)
[64.321881] Starting subtest: yf-tiled-32bpp-rotate-0
[64.326238] Subtest yf-tiled-32bpp-rotate-0: SKIP (0.000s)
[64.421944] [006/155] (953s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[64.536513] Starting subtest: psrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[64.541122] Subtest psrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[64.635792] [007/155] (953s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[64.741613] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu
[64.745995] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[64.839551] [008/155] (953s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[64.947602] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[64.952059] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[65.047441] [009/155] (952s left) gem_exec_fence (syncobj-backward-timeline-chain-engines)
[65.607110] Starting subtest: syncobj-backward-timeline-chain-engines
[65.713633] Subtest syncobj-backward-timeline-chain-engines: SUCCESS (0.107s)
[67.322841] [FACT gem_exec_fence (syncobj-backward-timeline-chain-engines)] new: kernel.kmod_is_loaded.vgem: true
[67.325862] [010/155] (950s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-render)
[67.424740] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-render
[67.429174] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[67.509204] [011/155] (950s left) kms_plane_alpha_blend (constant-alpha-min)
[67.602047] Starting subtest: constant-alpha-min
[67.913566] Starting dynamic subtest: pipe-A-eDP-1
[68.633077] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.721s)
[68.643995] Starting dynamic subtest: pipe-D-eDP-1
[70.161245] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.527s)
[70.163456] Subtest constant-alpha-min: SUCCESS (2.561s)
[71.041833] [012/155] (947s left) vgem_basic (create)
[71.083658] Starting subtest: create
[71.085785] Subtest create: SUCCESS (0.000s)
[71.147245] [013/155] (947s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[71.252878] Starting subtest: fbcpsrhdr-1p-offscreen-pri-shrfb-draw-mmap-wc
[71.257275] Subtest fbcpsrhdr-1p-offscreen-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[71.340817] [014/155] (946s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-move)
[71.448268] Starting subtest: hdr-2p-scndscrn-cur-indfb-move
[71.452547] Subtest hdr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[71.538289] [015/155] (946s left) gem_ccs (block-copy-compressed)
[71.594720] Starting subtest: block-copy-compressed
[71.598852] Subtest block-copy-compressed: SKIP (0.000s)
[71.710014] [016/155] (946s left) kms_big_fb (4-tiled-16bpp-rotate-90)
[71.826721] Starting subtest: 4-tiled-16bpp-rotate-90
[71.846185] Subtest 4-tiled-16bpp-rotate-90: SKIP (0.019s)
[71.963619] [017/155] (946s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf)
[72.052831] Starting subtest: fbc-psr2-overlay-plane-update-continuous-sf
[72.054842] Starting dynamic subtest: pipe-A-eDP-1
[72.150081] Dynamic subtest pipe-A-eDP-1: SKIP (0.101s)
[72.159694] Starting dynamic subtest: pipe-B-eDP-1
[73.315654] Dynamic subtest pipe-B-eDP-1: SKIP (1.164s)
[73.320389] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (1.266s)
[74.605965] [018/155] (943s left) kms_frontbuffer_tracking (psrhdr-rgb101010-draw-pwrite)
[74.701687] Starting subtest: psrhdr-rgb101010-draw-pwrite
[74.706177] Subtest psrhdr-rgb101010-draw-pwrite: SKIP (0.000s)
[74.806521] [019/155] (943s left) gem_exec_parallel (fds)
[74.865524] Starting subtest: fds
[74.867910] Starting dynamic subtest: rcs0
[77.256547] Dynamic subtest rcs0: SUCCESS (2.390s)
[77.269184] Starting dynamic subtest: bcs0
[80.645521] Dynamic subtest bcs0: SUCCESS (3.387s)
[80.653597] Starting dynamic subtest: vcs0
[84.548996] Dynamic subtest vcs0: SUCCESS (3.902s)
[84.558774] Starting dynamic subtest: vcs1
[88.621310] Dynamic subtest vcs1: SUCCESS (4.072s)
[88.630085] Starting dynamic subtest: vecs0
[92.583567] Dynamic subtest vecs0: SUCCESS (3.961s)
[92.592834] Starting dynamic subtest: ccs0
[96.048553] Dynamic subtest ccs0: SUCCESS (3.464s)
[96.051338] Subtest fds: SUCCESS (21.181s)
[96.253098] [020/155] (922s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[96.363840] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[96.368073] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[96.464252] [021/155] (922s left) gem_render_copy (yf-tiled-ccs-to-linear)
[96.524421] Starting subtest: yf-tiled-ccs-to-linear
[96.528961] Subtest yf-tiled-ccs-to-linear: SKIP (0.000s)
[96.708532] [022/155] (921s left) kms_cursor_crc (cursor-offscreen-512x170)
[97.000020] Starting subtest: cursor-offscreen-512x170
[97.004616] Subtest cursor-offscreen-512x170: SKIP (0.001s)
[97.150878] [023/155] (921s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[97.245172] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt
[97.249569] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[97.342933] [024/155] (921s left) kms_cursor_legacy (cursor-vs-flip-varying-size)
[97.467322] Starting subtest: cursor-vs-flip-varying-size
[128.899701] Subtest cursor-vs-flip-varying-size: SUCCESS (31.431s)
[129.727730] [025/155] (888s left) kms_cursor_crc (cursor-random-256x85)
[129.999945] Starting subtest: cursor-random-256x85
[130.045066] Subtest cursor-random-256x85: SKIP (0.043s)
[130.225999] [026/155] (888s left) perf (per-context-mode-unprivileged)
[130.345368] Starting subtest: per-context-mode-unprivileged
[130.349574] Subtest per-context-mode-unprivileged: SKIP (0.000s)
[130.388121] [027/155] (888s left) kms_big_fb (x-tiled-16bpp-rotate-270)
[130.487375] Starting subtest: x-tiled-16bpp-rotate-270
[130.506972] Subtest x-tiled-16bpp-rotate-270: SKIP (0.019s)
[130.620056] [028/155] (888s left) syncobj_timeline (invalid-wait-bad-flags)
[130.669528] Starting subtest: invalid-wait-bad-flags
[130.671596] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[130.775933] [029/155] (888s left) gem_linear_blits (interruptible)
[131.139724] Starting subtest: interruptible
[135.132305] Subtest interruptible: SUCCESS (3.992s)
[135.258102] [030/155] (883s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0)
[135.399121] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0
[135.403509] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[135.516961] [031/155] (883s left) i915_pm_sseu (full-enable)
[135.574977] Subtest full-enable: SKIP (0.000s)
[135.677218] [032/155] (883s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats)
[136.085917] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s)
[136.950109] [033/155] (882s left) gem_exec_whisper (basic-contexts-forked)
[137.004999] Starting subtest: basic-contexts-forked
[161.698102] Subtest basic-contexts-forked: SUCCESS (24.691s)
[161.885825] [034/155] (857s left) kms_psr (psr2-primary-page-flip)
[161.978879] Starting subtest: psr2-primary-page-flip
[161.981736] Starting dynamic subtest: eDP-1
[163.761392] Dynamic subtest eDP-1: SUCCESS (1.782s)
[163.763202] Subtest psr2-primary-page-flip: SUCCESS (1.782s)
[164.627901] [035/155] (854s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation)
[164.719358] Starting subtest: plane-scaler-unity-scaling-with-rotation
[164.721302] Starting dynamic subtest: pipe-A
[164.946790] Dynamic subtest pipe-A: SUCCESS (0.231s)
[164.957949] Starting dynamic subtest: pipe-B
[166.240571] Dynamic subtest pipe-B: SUCCESS (1.293s)
[166.250629] Starting dynamic subtest: pipe-C
[167.549463] Dynamic subtest pipe-C: SUCCESS (1.308s)
[167.558646] Starting dynamic subtest: pipe-D
[168.880288] Dynamic subtest pipe-D: SUCCESS (1.330s)
[168.882694] Subtest plane-scaler-unity-scaling-with-rotation: SUCCESS (4.164s)
[170.058356] [036/155] (849s left) sysfs_heartbeat_interval (mixed)
[170.112215] Starting subtest: mixed
[170.114550] Starting dynamic subtest: vecs0
[175.371144] Dynamic subtest vecs0: SUCCESS (5.259s)
[175.382078] Starting dynamic subtest: ccs0
[180.562509] Dynamic subtest ccs0: SUCCESS (5.190s)
[180.571617] Starting dynamic subtest: vcs0
[185.753372] Dynamic subtest vcs0: SUCCESS (5.190s)
[185.764454] Starting dynamic subtest: bcs0
[190.950702] Dynamic subtest bcs0: SUCCESS (5.196s)
[190.961128] Starting dynamic subtest: rcs0
[196.095563] Dynamic subtest rcs0: SUCCESS (5.144s)
[196.111079] Starting dynamic subtest: vcs1
[201.335965] Dynamic subtest vcs1: SUCCESS (5.240s)
[201.338740] Subtest mixed: SUCCESS (31.224s)
[201.413693] [037/155] (817s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[201.540893] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[208.296952] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.760s)
[208.442741] [038/155] (810s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[208.551309] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[208.555871] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[208.657049] [039/155] (810s left) kms_atomic_transition (modeset-transition-nonblocking)
[208.748023] Starting subtest: modeset-transition-nonblocking
[208.750147] Starting dynamic subtest: 1x-outputs
[220.157318] Dynamic subtest 1x-outputs: SUCCESS (11.411s)
[220.160266] Subtest modeset-transition-nonblocking: SUCCESS (11.413s)
[221.014414] [040/155] (798s left) i915_module_load (reload)
[221.050040] Starting subtest: reload
[227.376871] Subtest reload: SUCCESS (6.330s)
[227.417353] [041/155] (791s left) kms_psr (fbc-psr-sprite-blt)
[229.335766] Starting subtest: fbc-psr-sprite-blt
[229.338059] Starting dynamic subtest: eDP-1
[229.342468] Dynamic subtest eDP-1: SKIP (0.000s)
[229.346767] Subtest fbc-psr-sprite-blt: SKIP (0.001s)
[229.421849] [042/155] (789s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions-varying-s
[229.554209] Starting subtest: short-busy-flip-before-cursor-atomic-transitions-varying-size
[229.558622] Subtest short-busy-flip-before-cursor-atomic-transitions-varying-size: SKIP (0.000s)
[229.710237] [043/155] (789s left) gem_exec_reloc (basic-cpu-read)
[229.775317] Subtest basic-cpu-read: SKIP (0.000s)
[229.866040] [044/155] (789s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-move)
[229.974804] Starting subtest: fbc-2p-scndscrn-cur-indfb-move
[229.979069] Subtest fbc-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[230.057342] [045/155] (789s left) gen9_exec_parse (cmd-crossing-page)
[230.107349] Subtest cmd-crossing-page: SKIP (0.000s)
[230.175862] [046/155] (789s left) kms_chamelium_hpd (dp-hpd-for-each-pipe)
[230.561078] Subtest dp-hpd-for-each-pipe: SKIP (0.000s)
[231.408973] [047/155] (788s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-fullscreen)
[231.509968] Starting subtest: fbchdr-1p-primscrn-spr-indfb-fullscreen
[231.514445] Subtest fbchdr-1p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[231.627362] [048/155] (788s left) kms_psr (psr2-basic)
[231.730273] Starting subtest: psr2-basic
[231.732651] Starting dynamic subtest: eDP-1
[234.120440] Dynamic subtest eDP-1: SUCCESS (2.391s)
[234.122996] Subtest psr2-basic: SUCCESS (2.392s)
[234.508804] [049/155] (785s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[234.615901] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[243.523027] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.913s)
[243.635649] [050/155] (776s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-wc)
[243.748952] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-wc
[243.753235] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[243.869250] [051/155] (775s left) kms_flip (2x-plain-flip-ts-check-interruptible)
[243.957225] Starting subtest: 2x-plain-flip-ts-check-interruptible
[243.970624] Subtest 2x-plain-flip-ts-check-interruptible: SKIP (0.012s)
[244.047521] [052/155] (775s left) kms_pm_rpm (dpms-lpsp)
[245.583499] Starting subtest: dpms-lpsp
[246.282113] Subtest dpms-lpsp: SUCCESS (0.701s)
[246.402514] [053/155] (773s left) kms_flip (flip-vs-rmfb-interruptible)
[246.500860] Starting subtest: flip-vs-rmfb-interruptible
[246.505879] Starting dynamic subtest: A-eDP1
[255.123043] Dynamic subtest A-eDP1: SUCCESS (8.618s)
[255.132330] Starting dynamic subtest: B-eDP1
[263.752938] Dynamic subtest B-eDP1: SUCCESS (8.631s)
[263.763705] Starting dynamic subtest: C-eDP1
[272.375575] Dynamic subtest C-eDP1: SUCCESS (8.621s)
[272.385078] Starting dynamic subtest: D-eDP1
[281.005622] Dynamic subtest D-eDP1: SUCCESS (8.628s)
[281.010084] Subtest flip-vs-rmfb-interruptible: SUCCESS (34.507s)
[282.193275] [054/155] (737s left) gem_mmap_gtt (cpuset-big-copy-odd)
[282.258823] Subtest cpuset-big-copy-odd: SKIP (0.000s)
[282.316111] [055/155] (737s left) api_intel_allocator (standalone)
[282.375411] Starting subtest: standalone
[282.389849] Subtest standalone: SUCCESS (0.017s)
[282.506905] [056/155] (737s left) gem_exec_reloc (basic-concurrent0)
[282.567058] Subtest basic-concurrent0: SKIP (0.000s)
[282.633572] [057/155] (737s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-move)
[282.728823] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-move
[282.733227] Subtest fbchdr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[282.830381] [058/155] (737s left) kms_addfb_basic (bad-pitch-32)
[282.891208] Starting subtest: bad-pitch-32
[282.893442] Subtest bad-pitch-32: SUCCESS (0.000s)
[283.029568] [059/155] (737s left) gem_ctx_param (invalid-get-ringsize)
[283.088733] Starting subtest: invalid-get-ringsize
[283.091949] Subtest invalid-get-ringsize: SUCCESS (0.001s)
[283.197459] [060/155] (736s left) kms_rotation_crc (primary-yf-tiled-reflect-x-270)
[283.283234] Starting subtest: primary-yf-tiled-reflect-x-270
[283.286937] Subtest primary-yf-tiled-reflect-x-270: SKIP (0.001s)
[283.368047] [061/155] (736s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-blt)
[283.466431] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-blt
[287.680667] Subtest fbc-1p-offscreen-pri-indfb-draw-blt: SUCCESS (4.217s)
[288.915624] [062/155] (731s left) kms_big_fb (y-tiled-addfb-size-overflow)
[289.017326] Starting subtest: y-tiled-addfb-size-overflow
[289.021809] Subtest y-tiled-addfb-size-overflow: SKIP (0.000s)
[289.112654] [063/155] (731s left) gem_mmap_gtt (basic-wc)
[289.168134] Subtest basic-wc: SKIP (0.000s)
[289.232921] [064/155] (731s left) kms_plane (planar-pixel-format-settings)
[289.318394] Starting subtest: planar-pixel-format-settings
[289.387605] Subtest planar-pixel-format-settings: SUCCESS (0.070s)
[289.490737] [065/155] (730s left) gem_spin_batch (spin-all-new)
[289.549560] Starting subtest: spin-all-new
[289.560896] Subtest spin-all-new: SUCCESS (0.014s)
[291.308478] [066/155] (729s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt)
[291.412139] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt
[291.416312] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[291.514408] [067/155] (728s left) gem_exec_schedule (u-fairslice-all)
[291.576829] Starting subtest: u-fairslice-all
[293.647176] Subtest u-fairslice-all: SUCCESS (2.065s)
[295.328179] [068/155] (725s left) gem_mmap_gtt (basic-short)
[295.387746] Subtest basic-short: SKIP (0.000s)
[295.449462] [069/155] (725s left) gem_gpgpu_fill (basic)
[295.507850] Starting subtest: basic
[295.510373] Starting dynamic subtest: smem
[295.518228] Dynamic subtest smem: SUCCESS (0.012s)
[295.520650] Subtest basic: SUCCESS (0.012s)
[295.666521] [070/155] (724s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-mmap-wc)
[295.767661] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-mmap-wc
[295.772087] Subtest hdr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[295.871650] [071/155] (724s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-wc)
[295.971770] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-wc
[295.976210] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[296.067905] [072/155] (724s left) perf_pmu (multi-client)
[296.107842] Starting subtest: multi-client
[296.110345] Starting dynamic subtest: rcs0
[297.165563] Dynamic subtest rcs0: SUCCESS (1.055s)
[297.177938] Starting dynamic subtest: bcs0
[298.217561] Dynamic subtest bcs0: SUCCESS (1.048s)
[298.229878] Starting dynamic subtest: vcs0
[299.269725] Dynamic subtest vcs0: SUCCESS (1.048s)
[299.282851] Starting dynamic subtest: vcs1
[301.314337] Dynamic subtest vcs1: SUCCESS (2.042s)
[301.325849] Starting dynamic subtest: vecs0
[303.294446] Dynamic subtest vecs0: SUCCESS (1.977s)
[303.305929] Starting dynamic subtest: ccs0
[305.342328] Dynamic subtest ccs0: SUCCESS (2.045s)
[305.345270] Subtest multi-client: SUCCESS (9.234s)
[305.477465] [073/155] (715s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions)
[305.603410] Starting subtest: cursorA-vs-flipB-atomic-transitions
[305.607945] Subtest cursorA-vs-flipB-atomic-transitions: SKIP (0.001s)
[305.749339] [074/155] (714s left) gem_ctx_persistence (legacy-engines-mixed-process)
[305.805475] Starting subtest: legacy-engines-mixed-process
[305.807962] Starting dynamic subtest: render
[307.343170] Dynamic subtest render: SUCCESS (1.537s)
[307.354260] Starting dynamic subtest: bsd1
[309.300433] Dynamic subtest bsd1: SUCCESS (1.956s)
[309.310123] Starting dynamic subtest: bsd2
[311.311731] Dynamic subtest bsd2: SUCCESS (2.009s)
[311.323080] Starting dynamic subtest: blt
[313.289126] Dynamic subtest blt: SUCCESS (1.975s)
[313.300163] Starting dynamic subtest: vebox
[315.283704] Dynamic subtest vebox: SUCCESS (1.993s)
[315.286160] Subtest legacy-engines-mixed-process: SUCCESS (9.478s)
[315.366553] [075/155] (705s left) kms_atomic_transition (plane-all-modeset-transition-fencing-internal-panels)
[315.462700] Starting subtest: plane-all-modeset-transition-fencing-internal-panels
[315.464784] Starting dynamic subtest: pipe-A-eDP-1
[316.823987] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.360s)
[317.102996] Starting dynamic subtest: pipe-B-eDP-1
[318.149891] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.058s)
[318.426726] Subtest plane-all-modeset-transition-fencing-internal-panels: SUCCESS (2.964s)
[319.272395] [076/155] (701s left) kms_properties (get_properties-sanity-non-atomic)
[319.362969] Starting subtest: get_properties-sanity-non-atomic
[319.365173] Subtest get_properties-sanity-non-atomic: SUCCESS (0.002s)
[319.437406] [077/155] (701s left) gem_exec_reloc (basic-gtt)
[319.493815] Subtest basic-gtt: SKIP (0.000s)
[319.565962] [078/155] (701s left) kms_content_protection (dp-mst-type-1)
[319.691572] Starting subtest: dp-mst-type-1
[319.696078] Subtest dp-mst-type-1: SKIP (0.000s)
[319.804076] [079/155] (701s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted)
[319.862242] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted
[319.864586] Subtest multi-wait-for-submit-available-unsubmitted-submitted: SUCCESS (0.000s)
[319.965218] [080/155] (701s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-pwrite)
[320.062710] Starting subtest: psr-1p-offscreen-pri-indfb-draw-pwrite
[327.252478] Subtest psr-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (7.193s)
[327.368607] [081/155] (693s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-move)
[327.472601] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-move
[327.476709] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-move: SKIP (0.000s)
[327.572506] [082/155] (693s left) gem_watchdog (default-physical)
[327.639608] Starting subtest: default-physical
[329.395317] Subtest default-physical: SUCCESS (1.768s)
[329.500541] [083/155] (691s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-draw-blt)
[329.608609] Starting subtest: hdr-1p-primscrn-spr-indfb-draw-blt
[329.612784] Subtest hdr-1p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[329.729285] [084/155] (691s left) i915_drm_fdinfo (busy-hang)
[329.775431] Starting subtest: busy-hang
[329.777701] Starting dynamic subtest: rcs0
[329.806034] Dynamic subtest rcs0: SKIP (0.028s)
[329.818049] Starting dynamic subtest: bcs0
[329.822617] Dynamic subtest bcs0: SKIP (0.001s)
[329.832718] Starting dynamic subtest: vcs0
[329.837618] Dynamic subtest vcs0: SKIP (0.000s)
[329.846629] Starting dynamic subtest: vcs1
[329.850795] Dynamic subtest vcs1: SKIP (0.000s)
[329.859988] Starting dynamic subtest: vecs0
[329.864199] Dynamic subtest vecs0: SKIP (0.000s)
[329.873183] Starting dynamic subtest: ccs0
[329.877209] Dynamic subtest ccs0: SKIP (0.000s)
[329.881329] Subtest busy-hang: SKIP (0.035s)
[329.918567] [085/155] (691s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[330.017934] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[337.431840] Subtest psr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.419s)
[337.560165] [086/155] (683s left) gem_pwrite (basic-self)
[337.618115] Subtest basic-self: SKIP (0.000s)
[337.684230] [087/155] (683s left) kms_ccs (bad-rotation-90-y-tiled-gen12-rc-ccs-cc)
[337.787399] Starting subtest: bad-rotation-90-y-tiled-gen12-rc-ccs-cc
[337.789738] Starting dynamic subtest: pipe-A-eDP-1
[337.812426] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[337.822953] Starting dynamic subtest: pipe-B-eDP-1
[337.845074] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[337.854411] Starting dynamic subtest: pipe-C-eDP-1
[337.876175] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[337.884778] Starting dynamic subtest: pipe-D-eDP-1
[337.907305] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[337.911734] Subtest bad-rotation-90-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[340.047314] [088/155] (681s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-cur-indfb-move)
[340.151947] Starting subtest: fbchdr-2p-scndscrn-cur-indfb-move
[340.156479] Subtest fbchdr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[340.255885] [089/155] (681s left) gem_exec_reloc (basic-cpu-gtt-active)
[340.317900] Subtest basic-cpu-gtt-active: SKIP (0.000s)
[340.393942] [090/155] (681s left) kms_plane_multiple (tiling-x)
[340.481424] Starting subtest: tiling-x
[340.483381] Starting dynamic subtest: pipe-A-eDP-1
[344.195850] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.715s)
[344.205900] Starting dynamic subtest: pipe-B-eDP-1
[348.817908] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.618s)
[348.828214] Starting dynamic subtest: pipe-C-eDP-1
[353.364609] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.548s)
[353.375680] Starting dynamic subtest: pipe-D-eDP-1
[357.804070] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.436s)
[357.806304] Subtest tiling-x: SUCCESS (17.322s)
[358.626763] [091/155] (662s left) perf (mi-rpc)
[358.776044] Starting subtest: mi-rpc
[358.780363] Subtest mi-rpc: SKIP (0.000s)
[358.827012] [092/155] (662s left) kms_big_fb (linear-8bpp-rotate-270)
[358.921461] Starting subtest: linear-8bpp-rotate-270
[358.941946] Subtest linear-8bpp-rotate-270: SKIP (0.020s)
[359.056890] [093/155] (662s left) syncobj_eventfd (invalid-illegal-eventfd)
[359.109845] Starting subtest: invalid-illegal-eventfd
[359.112094] Subtest invalid-illegal-eventfd: SUCCESS (0.000s)
[359.206656] [094/155] (662s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-upscaling)
[359.294895] Starting subtest: flip-64bpp-linear-to-16bpp-linear-upscaling
[359.297092] Starting dynamic subtest: pipe-A-default-mode
[362.032080] Dynamic subtest pipe-A-default-mode: SUCCESS (2.737s)
[362.034383] Subtest flip-64bpp-linear-to-16bpp-linear-upscaling: SUCCESS (2.738s)
[362.896517] [095/155] (658s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-fully-sf)
[362.995033] Starting subtest: pr-overlay-plane-move-continuous-exceed-fully-sf
[362.999256] Subtest pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[363.070903] [096/155] (658s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[363.166728] Starting subtest: psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[363.170872] Subtest psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[363.265098] [097/155] (658s left) kms_color_pipeline (plane-lut1d-post-ctm3x4)
[363.351480] Starting subtest: plane-lut1d-post-ctm3x4
[363.508334] Starting dynamic subtest: pipe-A-plane-0
[363.674679] Dynamic subtest pipe-A-plane-0: SUCCESS (0.166s)
[363.684949] Starting dynamic subtest: pipe-A-plane-1
[363.841366] Dynamic subtest pipe-A-plane-1: SUCCESS (0.166s)
[363.853396] Starting dynamic subtest: pipe-A-plane-2
[363.999123] Dynamic subtest pipe-A-plane-2: SUCCESS (0.155s)
[365.206672] Starting dynamic subtest: pipe-B-plane-0
[365.365407] Dynamic subtest pipe-B-plane-0: SUCCESS (0.166s)
[365.377097] Starting dynamic subtest: pipe-B-plane-1
[365.554322] Dynamic subtest pipe-B-plane-1: SUCCESS (0.188s)
[365.564504] Starting dynamic subtest: pipe-B-plane-2
[365.721216] Dynamic subtest pipe-B-plane-2: SUCCESS (0.166s)
[366.918142] Starting dynamic subtest: pipe-C-plane-0
[367.076747] Dynamic subtest pipe-C-plane-0: SUCCESS (0.166s)
[367.089106] Starting dynamic subtest: pipe-C-plane-1
[367.232136] Dynamic subtest pipe-C-plane-1: SUCCESS (0.155s)
[367.242137] Starting dynamic subtest: pipe-C-plane-2
[367.376810] Dynamic subtest pipe-C-plane-2: SUCCESS (0.144s)
[368.582630] Starting dynamic subtest: pipe-D-plane-0
[368.729285] Dynamic subtest pipe-D-plane-0: SUCCESS (0.154s)
[368.739175] Starting dynamic subtest: pipe-D-plane-1
[368.885126] Dynamic subtest pipe-D-plane-1: SUCCESS (0.155s)
[368.895088] Starting dynamic subtest: pipe-D-plane-2
[369.040463] Dynamic subtest pipe-D-plane-2: SUCCESS (0.155s)
[369.327685] Subtest plane-lut1d-post-ctm3x4: SUCCESS (5.976s)
[370.233642] [098/155] (651s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-fullscreen)
[370.336890] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-fullscreen
[370.341251] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[370.443399] [099/155] (651s left) kms_flip (flip-vs-expired-vblank-interruptible)
[370.537613] Starting subtest: flip-vs-expired-vblank-interruptible
[370.539739] Starting dynamic subtest: A-eDP1
[379.216330] Dynamic subtest A-eDP1: SUCCESS (8.677s)
[379.228516] Starting dynamic subtest: B-eDP1
[387.853838] Dynamic subtest B-eDP1: SUCCESS (8.635s)
[387.866451] Starting dynamic subtest: C-eDP1
[396.462888] Dynamic subtest C-eDP1: SUCCESS (8.607s)
[396.472483] Starting dynamic subtest: D-eDP1
[405.082968] Dynamic subtest D-eDP1: SUCCESS (8.618s)
[405.085543] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (34.547s)
[406.249664] [100/155] (615s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[406.351289] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[406.355468] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[406.445660] [101/155] (615s left) gem_workarounds (basic-read-fd)
[406.507727] Starting subtest: basic-read-fd
[406.519623] Subtest basic-read-fd: SUCCESS (0.012s)
[406.656942] [102/155] (615s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[406.718608] Starting subtest: single-wait-all-for-submit-available-submitted
[406.721163] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[406.809838] [103/155] (615s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[406.911215] Starting subtest: addfb25-y-tiled-small-legacy
[406.915224] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[407.018676] [104/155] (614s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-render)
[407.132976] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-render
[407.137201] Subtest fbchdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[407.240605] [105/155] (614s left) gem_mmap_offset (perf)
[407.291752] Starting subtest: perf
[407.293405] Starting dynamic subtest: smem0
[407.659201] Dynamic subtest smem0: SUCCESS (0.366s)
[407.661547] Subtest perf: SUCCESS (0.367s)
[407.771604] [106/155] (614s left) kms_chamelium_color (ctm-0-75)
[407.867567] Subtest ctm-0-75: SKIP (0.000s)
[407.915009] [107/155] (614s left) gem_softpin (32b-excludes-last-page)
[407.967414] Starting subtest: 32b-excludes-last-page
[407.972434] Subtest 32b-excludes-last-page: SUCCESS (0.003s)
[408.123028] [108/155] (614s left) perf (gen12-group-exclusive-stream-sample-oa)
[408.211992] Starting subtest: gen12-group-exclusive-stream-sample-oa
[408.238937] Subtest gen12-group-exclusive-stream-sample-oa: SUCCESS (0.025s)
[408.283710] [109/155] (613s left) kms_busy (extended-modeset-hang-newfb)
[408.381119] Starting subtest: extended-modeset-hang-newfb
[408.383324] Starting dynamic subtest: pipe-A
[443.243806] Dynamic subtest pipe-A: SUCCESS (34.861s)
[443.254598] Starting dynamic subtest: pipe-D
[466.208875] Dynamic subtest pipe-D: SUCCESS (22.961s)
[466.211132] Subtest extended-modeset-hang-newfb: SUCCESS (57.826s)
[467.104114] [110/155] (555s left) gem_ctx_shared (q-promotion)
[467.164832] Starting subtest: Q-promotion
[467.167349] Starting dynamic subtest: rcs0
[467.287191] Dynamic subtest rcs0: SUCCESS (0.120s)
[467.306073] Starting dynamic subtest: vcs0
[467.391821] Dynamic subtest vcs0: SUCCESS (0.104s)
[467.411116] Starting dynamic subtest: vcs1
[467.481594] Dynamic subtest vcs1: SUCCESS (0.088s)
[467.500781] Starting dynamic subtest: vecs0
[467.569110] Dynamic subtest vecs0: SUCCESS (0.087s)
[467.585206] Starting dynamic subtest: ccs0
[467.667024] Dynamic subtest ccs0: SUCCESS (0.095s)
[467.669950] Subtest Q-promotion: SUCCESS (0.502s)
[469.405609] [111/155] (552s left) kms_universal_plane (disable-primary-vs-flip)
[469.496612] Starting subtest: disable-primary-vs-flip
[469.498541] Starting dynamic subtest: pipe-A-eDP-1
[471.098206] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.600s)
[471.113700] Starting dynamic subtest: pipe-B-eDP-1
[473.347483] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.250s)
[473.358468] Starting dynamic subtest: pipe-C-eDP-1
[475.595588] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.247s)
[475.606864] Starting dynamic subtest: pipe-D-eDP-1
[477.869658] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.272s)
[477.871991] Subtest disable-primary-vs-flip: SUCCESS (8.374s)
[478.774424] [112/155] (543s left) kms_cursor_edge_walk (256x256-left-edge)
[478.871402] Starting subtest: 256x256-left-edge
[478.873747] Starting dynamic subtest: pipe-A-eDP-1
[482.320651] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.450s)
[482.332136] Starting dynamic subtest: pipe-D-eDP-1
[485.458887] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.135s)
[485.461264] Subtest 256x256-left-edge: SUCCESS (6.588s)
[486.262459] [113/155] (536s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-factor-0-25)
[486.360324] Starting subtest: planes-downscale-factor-0-75-upscale-factor-0-25
[486.362589] Starting dynamic subtest: pipe-A
[486.422395] Dynamic subtest pipe-A: SUCCESS (0.065s)
[486.432366] Starting dynamic subtest: pipe-B
[487.582427] Dynamic subtest pipe-B: SUCCESS (1.158s)
[487.594735] Starting dynamic subtest: pipe-C
[488.690868] Dynamic subtest pipe-C: SUCCESS (1.108s)
[488.700898] Starting dynamic subtest: pipe-D
[489.841601] Dynamic subtest pipe-D: SUCCESS (1.150s)
[489.843853] Subtest planes-downscale-factor-0-75-upscale-factor-0-25: SUCCESS (3.484s)
[490.997442] [114/155] (531s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-upscaling)
[491.098127] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-upscaling
[491.100439] Starting dynamic subtest: pipe-A-default-mode
[493.881999] Dynamic subtest pipe-A-default-mode: SUCCESS (2.783s)
[493.884114] Subtest flip-32bpp-4tile-to-64bpp-4tile-upscaling: SUCCESS (2.784s)
[494.711417] [115/155] (527s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[494.811237] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[494.815394] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[494.926498] [116/155] (527s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc)
[495.014191] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc
[495.016492] Starting dynamic subtest: pipe-A-eDP-1
[495.039491] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[495.050629] Starting dynamic subtest: pipe-B-eDP-1
[495.071252] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[495.080789] Starting dynamic subtest: pipe-C-eDP-1
[495.101733] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[495.111410] Starting dynamic subtest: pipe-D-eDP-1
[495.134367] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[495.138811] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc: SKIP (0.006s)
[497.284856] [117/155] (525s left) core_auth (getclient-simple)
[497.321444] Starting subtest: getclient-simple
[497.352939] Subtest getclient-simple: SUCCESS (0.031s)
[497.448804] [118/155] (525s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-cur-indfb-onoff)
[497.543538] Starting subtest: fbchdr-1p-primscrn-cur-indfb-onoff
[497.547619] Subtest fbchdr-1p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[497.658417] [119/155] (525s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-blt)
[497.754148] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-blt
[497.758591] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[497.861137] [120/155] (524s left) syncobj_timeline (etime-single-wait-for-submit-submitted)
[497.913206] Starting subtest: etime-single-wait-for-submit-submitted
[498.018450] Subtest etime-single-wait-for-submit-submitted: SUCCESS (0.105s)
[498.097934] [121/155] (524s left) kms_vblank (ts-continuation-modeset)
[498.196105] Starting subtest: ts-continuation-modeset
[498.198113] Starting dynamic subtest: pipe-A-eDP-1
[499.736854] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.539s)
[499.753654] Starting dynamic subtest: pipe-D-eDP-1
[501.904764] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.165s)
[501.907024] Subtest ts-continuation-modeset: SUCCESS (3.708s)
[502.718464] [122/155] (520s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-blt)
[502.827171] Starting subtest: fbcpsr-rgb101010-draw-blt
[507.602691] Subtest fbcpsr-rgb101010-draw-blt: SUCCESS (4.779s)
[507.744491] [123/155] (515s left) prime_mmap (test_dup)
[507.810047] Starting subtest: test_dup
[507.812092] Starting dynamic subtest: test_dup-smem
[507.840767] Dynamic subtest test_dup-smem: SUCCESS (0.033s)
[507.843461] Subtest test_dup: SUCCESS (0.034s)
[507.916488] [124/155] (514s left) gem_exec_flush (basic-batch-kernel-default-wb)
[507.976624] Starting subtest: basic-batch-kernel-default-wb
[515.195828] Subtest basic-batch-kernel-default-wb: SUCCESS (7.220s)
[515.387356] [125/155] (507s left) kms_flip (2x-modeset-vs-vblank-race)
[515.478125] Starting subtest: 2x-modeset-vs-vblank-race
[515.487116] Subtest 2x-modeset-vs-vblank-race: SKIP (0.011s)
[515.579912] [126/155] (507s left) i915_pm_rpm (gem-evict-pwrite)
[517.518183] Starting subtest: gem-evict-pwrite
[517.523045] Subtest gem-evict-pwrite: SKIP (0.000s)
[517.910378] [127/155] (505s left) kms_invalid_mode (bad-hsync-start)
[518.014460] Starting subtest: bad-hsync-start
[518.016764] Starting dynamic subtest: pipe-A-eDP-1
[518.313680] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.301s)
[518.323647] Starting dynamic subtest: pipe-B-eDP-1
[518.325531] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[518.334890] Starting dynamic subtest: pipe-C-eDP-1
[518.337182] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[518.346582] Starting dynamic subtest: pipe-D-eDP-1
[518.348775] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[518.351028] Subtest bad-hsync-start: SUCCESS (0.307s)
[519.138753] [128/155] (503s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[519.246677] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[519.250958] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[519.368590] [129/155] (503s left) gem_exec_balancer (parallel-out-fence)
[519.644739] Starting subtest: parallel-out-fence
[520.925771] Subtest parallel-out-fence: SUCCESS (1.284s)
[521.041104] [130/155] (502s left) kms_psr (fbc-psr2-sprite-plane-move)
[521.130237] Starting subtest: fbc-psr2-sprite-plane-move
[521.132454] Starting dynamic subtest: eDP-1
[521.136500] Dynamic subtest eDP-1: SKIP (0.000s)
[521.140616] Subtest fbc-psr2-sprite-plane-move: SKIP (0.001s)
[521.203989] [131/155] (501s left) kms_fb_coherency (memset-crc)
[521.296330] Starting subtest: memset-crc
[521.298533] Starting dynamic subtest: mmap-offset-wc
[521.709911] Dynamic subtest mmap-offset-wc: SUCCESS (0.412s)
[522.017127] Starting dynamic subtest: mmap-offset-uc
[528.158619] Dynamic subtest mmap-offset-uc: SUCCESS (6.150s)
[528.453755] Subtest memset-crc: SUCCESS (7.157s)
[529.308312] [132/155] (493s left) gem_workarounds (reset-context)
[529.372207] Starting subtest: reset-context
[529.560650] Subtest reset-context: SUCCESS (0.189s)
[529.685599] [133/155] (493s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite)
[529.773803] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite
[529.778174] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[529.869871] [134/155] (493s left) gem_ctx_exec (basic-nohangcheck)
[529.942824] Starting subtest: basic-nohangcheck
[530.187350] Subtest basic-nohangcheck: SUCCESS (0.245s)
[530.317732] [135/155] (493s left) kms_pm_rpm (modeset-non-lpsp-stress)
[532.449511] Starting subtest: modeset-non-lpsp-stress
[533.604748] Subtest modeset-non-lpsp-stress: SKIP (1.153s)
[534.005572] [136/155] (489s left) gem_exec_flush (basic-wb-ro-default)
[534.077251] Starting subtest: basic-wb-ro-default
[539.533479] Subtest basic-wb-ro-default: SUCCESS (5.458s)
[539.726373] [137/155] (483s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-fullscreen)
[539.828830] Starting subtest: psr-1p-primscrn-spr-indfb-fullscreen
[546.560323] Subtest psr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (6.735s)
[546.697372] [138/155] (476s left) gem_mmap_wc (coherency)
[546.759078] Subtest coherency: SKIP (0.000s)
[546.820063] [139/155] (476s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[546.928899] Starting subtest: hdr-1p-offscreen-pri-indfb-draw-mmap-gtt
[546.933281] Subtest hdr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[547.021622] [140/155] (476s left) prime_vgem (basic-blt)
[547.108064] Starting subtest: basic-blt
[547.142665] Subtest basic-blt: SUCCESS (0.034s)
[547.259764] [141/155] (476s left) kms_psr2_sf (psr2-plane-move-sf-dmg-area)
[547.356990] Starting subtest: psr2-plane-move-sf-dmg-area
[547.359355] Starting dynamic subtest: pipe-A-eDP-1
[551.874570] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.516s)
[551.884859] Starting dynamic subtest: pipe-B-eDP-1
[557.200649] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.326s)
[557.202862] Subtest psr2-plane-move-sf-dmg-area: SUCCESS (9.844s)
[558.064510] [142/155] (465s left) kms_cursor_legacy (2x-cursor-vs-flip-legacy)
[558.201620] Starting subtest: 2x-cursor-vs-flip-legacy
[558.206355] Subtest 2x-cursor-vs-flip-legacy: SKIP (0.001s)
[558.350540] [143/155] (465s left) gem_exec_schedule (implicit-write-read)
[558.420065] Starting subtest: implicit-write-read
[558.422106] Starting dynamic subtest: rcs0
[558.515787] Dynamic subtest rcs0: SUCCESS (0.105s)
[558.530202] Starting dynamic subtest: vcs0
[558.621111] Dynamic subtest vcs0: SUCCESS (0.104s)
[558.633253] Starting dynamic subtest: vcs1
[558.707994] Dynamic subtest vcs1: SUCCESS (0.086s)
[558.721344] Starting dynamic subtest: vecs0
[558.795961] Dynamic subtest vecs0: SUCCESS (0.087s)
[558.809039] Starting dynamic subtest: ccs0
[558.889688] Dynamic subtest ccs0: SUCCESS (0.093s)
[558.892205] Subtest implicit-write-read: SUCCESS (0.480s)
[560.439113] [144/155] (463s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions)
[560.562243] Starting subtest: flip-vs-cursor-atomic-transitions
[568.590661] Subtest flip-vs-cursor-atomic-transitions: SUCCESS (8.026s)
[569.402426] [145/155] (454s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-move)
[569.511785] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-move
[569.516056] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-move: SKIP (0.000s)
[569.612723] [146/155] (454s left) kms_rotation_crc (multiplane-rotation-cropping-top)
[569.703636] Starting subtest: multiplane-rotation-cropping-top
[587.218514] Subtest multiplane-rotation-cropping-top: SUCCESS (17.516s)
[587.323353] [147/155] (436s left) kms_joiner (invalid-modeset-ultra-joiner)
[587.432232] Starting subtest: invalid-modeset-ultra-joiner
[587.435796] Subtest invalid-modeset-ultra-joiner: SKIP (0.000s)
[587.498928] [148/155] (436s left) kms_lease (lease-invalid-plane)
[587.591479] Starting subtest: lease-invalid-plane
[587.593525] Starting dynamic subtest: pipe-A-eDP-1
[587.595528] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[587.606558] Starting dynamic subtest: pipe-B-eDP-1
[587.608471] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[587.618584] Starting dynamic subtest: pipe-C-eDP-1
[587.620300] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[587.632717] Starting dynamic subtest: pipe-D-eDP-1
[587.634576] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[587.636451] Subtest lease-invalid-plane: SUCCESS (0.004s)
[587.675737] [149/155] (436s left) gem_exec_balancer (fairslice)
[587.732843] Starting subtest: fairslice
[590.679399] Subtest fairslice: SUCCESS (2.944s)
[591.041838] [150/155] (432s left) gem_readwrite (read-bad-handle)
[591.098071] Subtest read-bad-handle: SKIP (0.000s)
[591.163277] [151/155] (432s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[591.268629] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-mmap-gtt
[591.272789] Subtest hdr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[591.374242] [152/155] (432s left) gem_madvise (dontneed-after-mmap)
[591.413182] Starting subtest: dontneed-after-mmap
[591.444705] Subtest dontneed-after-mmap: SUCCESS (0.033s)
[591.561304] [153/155] (432s left) sw_sync (sync_merge_invalid)
[591.597156] Starting subtest: sync_merge_invalid
[591.608475] Subtest sync_merge_invalid: SUCCESS (0.016s)
[591.691092] [154/155] (432s left) kms_frontbuffer_tracking (hdr-rgb101010-draw-pwrite)
[591.787642] Starting subtest: hdr-rgb101010-draw-pwrite
[591.791649] Subtest hdr-rgb101010-draw-pwrite: SKIP (0.000s)
[591.895858] [155/155] (432s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-render)
[592.004294] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-render
[592.007994] Subtest fbc-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[592.103359] Closing watchdogs
results: parsing output: 0/ for test: kms_ccs
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: drm_buddy
results: parsing output: 3/ for test: kms_ccs
results: parsing output: 4/ for test: kms_big_fb
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: gem_exec_fence
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: kms_plane_alpha_blend
results: parsing output: 11/ for test: vgem_basic
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: gem_ccs
results: parsing output: 15/ for test: kms_big_fb
results: parsing output: 16/ for test: kms_psr2_sf
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: gem_exec_parallel
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: gem_render_copy
results: parsing output: 21/ for test: kms_cursor_crc
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: kms_cursor_legacy
results: parsing output: 24/ for test: kms_cursor_crc
results: parsing output: 25/ for test: perf
results: parsing output: 26/ for test: kms_big_fb
results: parsing output: 27/ for test: syncobj_timeline
results: parsing output: 28/ for test: gem_linear_blits
results: parsing output: 29/ for test: kms_big_fb
results: parsing output: 30/ for test: i915_pm_sseu
results: parsing output: 31/ for test: kms_chamelium_frames
results: parsing output: 32/ for test: gem_exec_whisper
results: parsing output: 33/ for test: kms_psr
results: parsing output: 34/ for test: kms_plane_scaling
results: parsing output: 35/ for test: sysfs_heartbeat_interval
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: kms_atomic_transition
results: parsing output: 39/ for test: i915_module_load
results: parsing output: 40/ for test: kms_psr
results: parsing output: 41/ for test: kms_cursor_legacy
results: parsing output: 42/ for test: gem_exec_reloc
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: gen9_exec_parse
results: parsing output: 45/ for test: kms_chamelium_hpd
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_psr
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: kms_flip
results: parsing output: 51/ for test: kms_pm_rpm
results: parsing output: 52/ for test: kms_flip
results: parsing output: 53/ for test: gem_mmap_gtt
results: parsing output: 54/ for test: api_intel_allocator
results: parsing output: 55/ for test: gem_exec_reloc
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_addfb_basic
results: parsing output: 58/ for test: gem_ctx_param
results: parsing output: 59/ for test: kms_rotation_crc
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_big_fb
results: parsing output: 62/ for test: gem_mmap_gtt
results: parsing output: 63/ for test: kms_plane
results: parsing output: 64/ for test: gem_spin_batch
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: gem_exec_schedule
results: parsing output: 67/ for test: gem_mmap_gtt
results: parsing output: 68/ for test: gem_gpgpu_fill
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: perf_pmu
results: parsing output: 72/ for test: kms_cursor_legacy
results: parsing output: 73/ for test: gem_ctx_persistence
results: parsing output: 74/ for test: kms_atomic_transition
results: parsing output: 75/ for test: kms_properties
results: parsing output: 76/ for test: gem_exec_reloc
results: parsing output: 77/ for test: kms_content_protection
results: parsing output: 78/ for test: syncobj_timeline
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: gem_watchdog
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: i915_drm_fdinfo
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: gem_pwrite
results: parsing output: 86/ for test: kms_ccs
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: gem_exec_reloc
results: parsing output: 89/ for test: kms_plane_multiple
results: parsing output: 90/ for test: perf
results: parsing output: 91/ for test: kms_big_fb
results: parsing output: 92/ for test: syncobj_eventfd
results: parsing output: 93/ for test: kms_flip_scaled_crc
results: parsing output: 94/ for test: kms_psr2_sf
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_color_pipeline
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_flip
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: gem_workarounds
results: parsing output: 101/ for test: syncobj_timeline
results: parsing output: 102/ for test: kms_addfb_basic
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: gem_mmap_offset
results: parsing output: 105/ for test: kms_chamelium_color
results: parsing output: 106/ for test: gem_softpin
results: parsing output: 107/ for test: perf
results: parsing output: 108/ for test: kms_busy
results: parsing output: 109/ for test: gem_ctx_shared
results: parsing output: 110/ for test: kms_universal_plane
results: parsing output: 111/ for test: kms_cursor_edge_walk
results: parsing output: 112/ for test: kms_plane_scaling
results: parsing output: 113/ for test: kms_flip_scaled_crc
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: kms_ccs
results: parsing output: 116/ for test: core_auth
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: syncobj_timeline
results: parsing output: 120/ for test: kms_vblank
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: prime_mmap
results: parsing output: 123/ for test: gem_exec_flush
results: parsing output: 124/ for test: kms_flip
results: parsing output: 125/ for test: i915_pm_rpm
results: parsing output: 126/ for test: kms_invalid_mode
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: gem_exec_balancer
results: parsing output: 129/ for test: kms_psr
results: parsing output: 130/ for test: kms_fb_coherency
results: parsing output: 131/ for test: gem_workarounds
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: gem_ctx_exec
results: parsing output: 134/ for test: kms_pm_rpm
results: parsing output: 135/ for test: gem_exec_flush
results: parsing output: 136/ for test: kms_frontbuffer_tracking
results: parsing output: 137/ for test: gem_mmap_wc
results: parsing output: 138/ for test: kms_frontbuffer_tracking
results: parsing output: 139/ for test: prime_vgem
results: parsing output: 140/ for test: kms_psr2_sf
results: parsing output: 141/ for test: kms_cursor_legacy
results: parsing output: 142/ for test: gem_exec_schedule
results: parsing output: 143/ for test: kms_cursor_legacy
results: parsing output: 144/ for test: kms_frontbuffer_tracking
results: parsing output: 145/ for test: kms_rotation_crc
results: parsing output: 146/ for test: kms_joiner
results: parsing output: 147/ for test: kms_lease
results: parsing output: 148/ for test: gem_exec_balancer
results: parsing output: 149/ for test: gem_readwrite
results: parsing output: 150/ for test: kms_frontbuffer_tracking
results: parsing output: 151/ for test: gem_madvise
results: parsing output: 152/ for test: sw_sync
results: parsing output: 153/ for test: kms_frontbuffer_tracking
results: parsing output: 154/ for test: kms_frontbuffer_tracking
Done.