[57.076691] Initializing watchdogs
[57.076838]   /dev/watchdog0
[57.093110] [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.098073] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.100406] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.104090] [001/155] (960s left) gem_exec_whisper (basic-contexts)
[57.183692] Starting subtest: basic-contexts
[65.248896] Subtest basic-contexts: SUCCESS (7.932s)
[65.433206] [002/155] (952s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted)
[65.482933] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted
[65.588671] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.104s)
[65.681350] [003/155] (952s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled-ccs)
[65.731812] Starting subtest: yf-tiled-ccs-to-yf-tiled-ccs
[65.736569] Subtest yf-tiled-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[65.937199] [004/155] (951s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-upscaling)
[66.035280] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-upscaling
[66.037673] Starting dynamic subtest: pipe-A-default-mode
[68.822958] Dynamic subtest pipe-A-default-mode: SUCCESS (2.769s)
[68.825447] Subtest flip-64bpp-4tile-to-32bpp-4tile-upscaling: SUCCESS (2.770s)
[69.663378] [005/155] (948s left) syncobj_timeline (multi-wait-signaled)
[69.717632] Starting subtest: multi-wait-signaled
[69.719833] Subtest multi-wait-signaled: SUCCESS (0.000s)
[69.833703] [006/155] (947s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted)
[69.884076] Starting subtest: multi-wait-all-for-submit-available-submitted
[69.886491] Subtest multi-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[69.997873] [007/155] (947s left) kms_color (degamma)
[70.084770] Starting subtest: degamma
[70.087176] Starting dynamic subtest: pipe-A-eDP-1
[70.827033] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.737s)
[70.836936] Starting dynamic subtest: pipe-B-eDP-1
[72.292628] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.462s)
[72.302034] Starting dynamic subtest: pipe-C-eDP-1
[73.701303] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.403s)
[73.709593] Starting dynamic subtest: pipe-D-eDP-1
[75.093753] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.390s)
[75.096162] Subtest degamma: SUCCESS (4.993s)
[75.911887] [008/155] (941s left) kms_frontbuffer_tracking (fbchdr-tiling-4)
[76.025318] Starting subtest: fbchdr-tiling-4
[76.029424] Subtest fbchdr-tiling-4: SKIP (0.000s)
[76.112112] [009/155] (941s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[76.197943] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[76.200422] Starting dynamic subtest: pipe-A
[76.218053] Dynamic subtest pipe-A: SKIP (0.016s)
[76.227889] Starting dynamic subtest: pipe-B
[76.248138] Dynamic subtest pipe-B: SKIP (0.028s)
[76.260191] Starting dynamic subtest: pipe-C
[76.275081] Dynamic subtest pipe-C: SKIP (0.029s)
[76.283920] Starting dynamic subtest: pipe-D
[76.304764] Dynamic subtest pipe-D: SKIP (0.029s)
[76.308413] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.103s)
[76.400520] [010/155] (941s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-cur-indfb-move)
[76.513626] Starting subtest: psrhdr-1p-primscrn-cur-indfb-move
[76.517756] Subtest psrhdr-1p-primscrn-cur-indfb-move: SKIP (0.000s)
[76.607587] [011/155] (941s left) vgem_basic (dmabuf-fence)
[76.700553] Starting subtest: dmabuf-fence
[76.702540] Subtest dmabuf-fence: SUCCESS (0.001s)
[76.755285] [FACT vgem_basic (dmabuf-fence)] new: kernel.kmod_is_loaded.vgem: true
[76.758396] [012/155] (941s left) gem_mmap (short-mmap)
[76.810971] Subtest short-mmap: SKIP (0.000s)
[76.874085] [013/155] (941s left) syncobj_wait (wait-delayed-signal)
[76.919880] Starting subtest: wait-delayed-signal
[77.025271] Subtest wait-delayed-signal: SUCCESS (0.104s)
[77.128155] [014/155] (941s left) kms_pm_rpm (modeset-lpsp-stress)
[78.678368] Starting subtest: modeset-lpsp-stress
[94.084644] Subtest modeset-lpsp-stress: SUCCESS (15.394s)
[94.206775] [015/155] (923s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180-hflip)
[94.316321] Starting subtest: linear-max-hw-stride-64bpp-rotate-180-hflip
[94.320665] Subtest linear-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[94.412609] [016/155] (923s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-indfb-msflip-blt)
[94.520769] Starting subtest: fbcpsrhdr-2p-scndscrn-indfb-msflip-blt
[94.525237] Subtest fbcpsrhdr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[94.613549] [017/155] (923s left) core_setmaster (master-drop-set-user)
[94.699724] Starting subtest: master-drop-set-user
[94.714112] Subtest master-drop-set-user: SUCCESS (0.015s)
[94.751220] [018/155] (923s left) gem_ctx_isolation (preservation-s3)
[94.820490] Starting subtest: preservation-S3
[94.822726] Starting dynamic subtest: rcs0
[111.061119] Dynamic subtest rcs0: SUCCESS (1.493s)
[111.072348] Starting dynamic subtest: bcs0
[127.035433] Dynamic subtest bcs0: SUCCESS (1.314s)
[127.053012] Starting dynamic subtest: vcs0
[143.063401] Dynamic subtest vcs0: SUCCESS (2.635s)
[143.075592] Starting dynamic subtest: vcs1
[161.043718] Dynamic subtest vcs1: SUCCESS (4.894s)
[161.054172] Starting dynamic subtest: vecs0
[179.031608] Dynamic subtest vecs0: SUCCESS (4.853s)
[179.044264] Starting dynamic subtest: ccs0
[197.051062] Dynamic subtest ccs0: SUCCESS (3.061s)
[197.053663] Subtest preservation-S3: SUCCESS (18.252s)
[197.274062] [019/155] (821s left) kms_joiner (basic-force-big-joiner)
[197.377934] Starting subtest: basic-force-big-joiner
[197.382990] Subtest basic-force-big-joiner: SKIP (0.000s)
[197.445318] [020/155] (820s left) kms_psr (psr2-basic)
[197.534700] Starting subtest: psr2-basic
[197.537655] Starting dynamic subtest: eDP-1
[199.189455] Dynamic subtest eDP-1: SUCCESS (1.631s)
[199.192435] Subtest psr2-basic: SUCCESS (1.632s)
[200.098410] [021/155] (818s left) kms_cursor_crc (cursor-random-64x64)
[200.296618] Starting subtest: cursor-random-64x64
[200.302801] Starting dynamic subtest: pipe-A-eDP-1
[203.711392] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.379s)
[203.740681] Starting dynamic subtest: pipe-D-eDP-1
[207.777941] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.018s)
[207.780589] Subtest cursor-random-64x64: SUCCESS (7.424s)
[208.620335] [022/155] (809s left) i915_pm_rpm (gem-execbuf-stress-pc8)
[210.150894] Starting subtest: gem-execbuf-stress-pc8
[210.191977] Subtest gem-execbuf-stress-pc8: SKIP (0.040s)
[210.866794] [023/155] (807s left) kms_busy (extended-modeset-hang-newfb-with-reset)
[210.966306] Starting subtest: extended-modeset-hang-newfb-with-reset
[210.968759] Starting dynamic subtest: pipe-A
[224.980776] Dynamic subtest pipe-A: SUCCESS (13.988s)
[224.995057] Starting dynamic subtest: pipe-D
[240.840781] Dynamic subtest pipe-D: SUCCESS (15.712s)
[240.843028] Subtest extended-modeset-hang-newfb-with-reset: SUCCESS (29.707s)
[241.649081] [024/155] (776s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-shrfb-draw-blt)
[241.746460] Starting subtest: fbchdr-2p-scndscrn-pri-shrfb-draw-blt
[241.752309] Subtest fbchdr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[241.844196] [025/155] (776s left) gem_ccs (block-copy-compressed)
[241.897674] Starting subtest: block-copy-compressed
[241.902644] Subtest block-copy-compressed: SKIP (0.000s)
[241.996063] [026/155] (776s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-pgflip-blt)
[242.102204] Starting subtest: psr-2p-primscrn-shrfb-pgflip-blt
[242.107439] Subtest psr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[242.181208] [027/155] (776s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-pri-indfb-multidraw)
[242.279418] Starting subtest: fbcpsrhdr-2p-pri-indfb-multidraw
[242.284673] Subtest fbcpsrhdr-2p-pri-indfb-multidraw: SKIP (0.000s)
[242.382502] [028/155] (776s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-indfb-draw-blt)
[242.478784] Starting subtest: fbcpsrhdr-1p-primscrn-pri-indfb-draw-blt
[242.484203] Subtest fbcpsrhdr-1p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[242.577291] [029/155] (776s left) gem_exec_reloc (basic-gtt-wc-noreloc)
[242.628757] Subtest basic-gtt-wc-noreloc: SKIP (0.000s)
[242.696672] [030/155] (776s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc)
[242.800192] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc
[249.579027] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (6.765s)
[249.719114] [031/155] (769s left) kms_chamelium_hpd (common-hpd-after-suspend)
[250.098129] Subtest common-hpd-after-suspend: SKIP (0.000s)
[250.944403] [032/155] (767s left) gem_exec_fence (syncobj-unused-fence)
[251.376791] Starting subtest: syncobj-unused-fence
[251.382924] Subtest syncobj-unused-fence: SUCCESS (0.006s)
[252.889330] [033/155] (766s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[252.983796] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[252.988803] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[253.081392] [034/155] (765s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[253.186766] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[253.192457] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[253.306651] [035/155] (765s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-y-tiled)
[253.368691] Starting subtest: yf-tiled-mc-ccs-to-vebox-y-tiled
[253.374623] Subtest yf-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[253.553754] [036/155] (765s left) gem_userptr_blits (process-exit-busy)
[253.615565] Starting subtest: process-exit-busy
[253.624554] Subtest process-exit-busy: SUCCESS (0.015s)
[253.747696] [037/155] (765s left) kms_frontbuffer_tracking (fbchdr-2p-indfb-fliptrack-mmap-gtt)
[253.847614] Starting subtest: fbchdr-2p-indfb-fliptrack-mmap-gtt
[253.852435] Subtest fbchdr-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[253.975586] [038/155] (765s left) gem_tiling_max_stride
[254.091245] [039/155] (765s left) kms_cursor_legacy (cursora-vs-flipa-varying-size)
[254.202276] Starting subtest: cursorA-vs-flipA-varying-size
[265.332585] Subtest cursorA-vs-flipA-varying-size: SUCCESS (11.125s)
[266.197955] [040/155] (753s left) kms_frontbuffer_tracking (hdr-1p-primscrn-shrfb-pgflip-blt)
[266.296479] Starting subtest: hdr-1p-primscrn-shrfb-pgflip-blt
[266.301683] Subtest hdr-1p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[266.391525] [041/155] (752s left) gem_mmap_offset (perf)
[266.446163] Starting subtest: perf
[266.448326] Starting dynamic subtest: smem0
[266.811907] Dynamic subtest smem0: SUCCESS (0.362s)
[266.814665] Subtest perf: SUCCESS (0.364s)
[266.919239] [042/155] (752s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[267.020790] Starting subtest: fbcpsrhdr-1p-offscreen-pri-indfb-draw-mmap-gtt
[267.025789] Subtest fbcpsrhdr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[267.113980] [043/155] (752s left) gem_sync (basic-all)
[267.184772] Starting subtest: basic-all
[269.802786] Subtest basic-all: SUCCESS (2.630s)
[269.990701] [044/155] (749s left) gem_linear_blits (interruptible)
[270.396443] Starting subtest: interruptible
[276.381221] Subtest interruptible: SUCCESS (5.986s)
[276.523095] [045/155] (742s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[276.669483] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[276.672087] Starting dynamic subtest: pipe-A-eDP-1
[276.701231] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[276.712823] Starting dynamic subtest: pipe-B-eDP-1
[276.741761] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[276.752554] Starting dynamic subtest: pipe-C-eDP-1
[276.781517] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[276.793955] Starting dynamic subtest: pipe-D-eDP-1
[276.822898] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[276.827778] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[278.957235] [046/155] (740s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[279.064467] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-mmap-cpu
[279.069437] Subtest fbchdr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[279.153294] [047/155] (740s left) kms_cursor_crc (cursor-onscreen-64x21)
[279.360597] Starting subtest: cursor-onscreen-64x21
[279.411414] Subtest cursor-onscreen-64x21: SKIP (0.045s)
[279.636033] [048/155] (739s left) syncobj_timeline (host-signal-points)
[279.699277] Starting subtest: host-signal-points
[279.702001] Subtest host-signal-points: SUCCESS (0.004s)
[279.775443] [049/155] (739s left) kms_addfb_basic (unused-handle)
[279.824704] Starting subtest: unused-handle
[279.827506] Subtest unused-handle: SUCCESS (0.000s)
[279.966166] [050/155] (739s left) gem_ppgtt (shrink-vs-evict-any)
[280.025630] Starting subtest: shrink-vs-evict-any
[285.858201] Subtest shrink-vs-evict-any: SUCCESS (5.779s)
[285.898857] [051/155] (733s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-onoff)
[286.005447] Starting subtest: hdr-2p-scndscrn-cur-indfb-onoff
[286.010616] Subtest hdr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[286.102064] [052/155] (733s left) gem_create (hog-create)
[286.158623] Starting subtest: hog-create
[286.161639] Starting dynamic subtest: smem0
[319.766459] Dynamic subtest smem0: SUCCESS (33.594s)
[319.769662] Subtest hog-create: SUCCESS (33.595s)
[319.953469] [053/155] (699s left) gem_exec_fence (basic-busy)
[320.130666] Starting subtest: basic-busy
[320.133656] Starting dynamic subtest: rcs0
[320.219668] Dynamic subtest rcs0: SUCCESS (0.091s)
[320.233436] Starting dynamic subtest: bcs0
[320.282669] Dynamic subtest bcs0: SUCCESS (0.060s)
[320.300390] Starting dynamic subtest: vcs0
[320.350183] Dynamic subtest vcs0: SUCCESS (0.064s)
[320.363979] Starting dynamic subtest: vcs1
[320.408140] Dynamic subtest vcs1: SUCCESS (0.056s)
[320.420794] Starting dynamic subtest: vecs0
[320.474378] Dynamic subtest vecs0: SUCCESS (0.062s)
[320.487776] Starting dynamic subtest: ccs0
[320.539435] Dynamic subtest ccs0: SUCCESS (0.065s)
[320.542457] Subtest basic-busy: SUCCESS (0.413s)
[321.866713] [054/155] (697s left) kms_psr (fbc-psr-sprite-mmap-cpu)
[321.960772] Starting subtest: fbc-psr-sprite-mmap-cpu
[321.963534] Starting dynamic subtest: eDP-1
[321.968812] Dynamic subtest eDP-1: SKIP (0.000s)
[321.973574] Subtest fbc-psr-sprite-mmap-cpu: SKIP (0.001s)
[322.033895] [055/155] (697s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-render)
[322.121779] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-render
[322.127220] Subtest fbchdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[322.206538] [056/155] (697s left) kms_frontbuffer_tracking (psrhdr-rgb101010-draw-pwrite)
[322.301721] Starting subtest: psrhdr-rgb101010-draw-pwrite
[322.306018] Subtest psrhdr-rgb101010-draw-pwrite: SKIP (0.000s)
[322.389480] [057/155] (697s left) drm_read (empty-block)
[322.561850] Starting subtest: empty-block
[323.563039] Subtest empty-block: SUCCESS (1.000s)
[323.687003] [058/155] (696s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[323.797569] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[323.802777] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[323.911205] [059/155] (696s left) kms_prop_blob (blob-multiple)
[323.964436] Starting subtest: blob-multiple
[323.967362] Subtest blob-multiple: SUCCESS (0.002s)
[324.067273] [060/155] (696s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[324.160907] Starting subtest: psr-rgb565-draw-mmap-wc
[331.026632] Subtest psr-rgb565-draw-mmap-wc: SUCCESS (6.873s)
[331.140619] [061/155] (688s left) kms_content_protection (dp-mst-type-0-hdcp14)
[331.289734] Starting subtest: dp-mst-type-0-hdcp14
[331.294955] Subtest dp-mst-type-0-hdcp14: SKIP (0.000s)
[331.401966] [062/155] (688s left) i915_hangman (gt-error-state-capture)
[331.469997] Starting subtest: gt-error-state-capture
[331.472038] Starting dynamic subtest: rcs0
[342.832711] Dynamic subtest rcs0: SUCCESS (11.364s)
[342.847030] Starting dynamic subtest: bcs0
[354.801492] Dynamic subtest bcs0: SUCCESS (11.965s)
[354.815041] Starting dynamic subtest: vcs0
[365.807241] Dynamic subtest vcs0: SUCCESS (11.003s)
[365.820845] Starting dynamic subtest: vcs1
[377.777180] Dynamic subtest vcs1: SUCCESS (11.964s)
[377.790624] Starting dynamic subtest: vecs0
[389.744400] Dynamic subtest vecs0: SUCCESS (11.962s)
[389.758772] Starting dynamic subtest: ccs0
[400.816410] Dynamic subtest ccs0: SUCCESS (11.066s)
[400.819777] Subtest gt-error-state-capture: SUCCESS (69.343s)
[400.936622] [063/155] (619s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-blt)
[401.040678] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-blt
[401.046680] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[401.119791] [064/155] (619s left) kms_flip (2x-flip-vs-dpms)
[401.215379] Starting subtest: 2x-flip-vs-dpms
[401.226610] Subtest 2x-flip-vs-dpms: SKIP (0.010s)
[401.304037] [065/155] (618s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[401.399538] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-gtt
[401.404780] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[401.526559] [066/155] (618s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0)
[401.629806] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0
[402.606744] Subtest x-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (0.979s)
[402.731620] [067/155] (617s left) kms_feature_discovery (display-1x)
[402.823255] Starting subtest: display-1x
[402.825669] Subtest display-1x: SUCCESS (0.000s)
[402.896903] [068/155] (617s left) gen9_exec_parse (cmd-crossing-page)
[402.948559] Subtest cmd-crossing-page: SKIP (0.000s)
[403.036195] [069/155] (617s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[403.134028] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc
[403.138427] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[403.230013] [070/155] (617s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[403.343485] Starting subtest: basic-flip-before-cursor-varying-size
[404.274825] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.928s)
[405.118975] [071/155] (615s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-gtt)
[405.223561] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-gtt
[405.228412] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[405.324637] [072/155] (615s left) gem_exec_params (dr4-dirt)
[405.370260] Starting subtest: DR4-dirt
[405.372772] Subtest DR4-dirt: SUCCESS (0.000s)
[405.488505] [073/155] (615s left) kms_getfb (getfb-reject-nv12)
[405.590563] Starting subtest: getfb-reject-nv12
[405.593418] Subtest getfb-reject-nv12: SUCCESS (0.001s)
[405.685288] [074/155] (615s left) vgem_basic (second-client)
[405.728562] Starting subtest: second-client
[405.747053] Subtest second-client: SUCCESS (0.022s)
[405.804788] [075/155] (614s left) kms_rotation_crc (cursor-rotation-180)
[405.898534] Starting subtest: cursor-rotation-180
[407.402684] Subtest cursor-rotation-180: SUCCESS (1.504s)
[408.583566] [076/155] (612s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move)
[408.682357] Starting subtest: hdr-2p-primscrn-spr-indfb-move
[408.687744] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[408.774522] [077/155] (612s left) kms_vblank (query-forked-hang)
[408.869102] Starting subtest: query-forked-hang
[408.871647] Starting dynamic subtest: pipe-A-eDP-1
[435.604908] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.729s)
[435.621787] Starting dynamic subtest: pipe-D-eDP-1
[450.588397] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.976s)
[450.591048] Subtest query-forked-hang: SUCCESS (41.709s)
[451.458788] [078/155] (569s left) syncobj_wait (invalid-signal-illegal-handle)
[451.521936] Starting subtest: invalid-signal-illegal-handle
[451.524672] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[451.627525] [079/155] (569s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[451.728219] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-hflip
[452.751489] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (1.023s)
[452.866322] [080/155] (568s left) kms_vblank (ts-continuation-suspend)
[452.958095] Starting subtest: ts-continuation-suspend
[452.959888] Starting dynamic subtest: pipe-A-eDP-1
[469.295260] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.538s)
[469.312964] Starting dynamic subtest: pipe-D-eDP-1
[486.311989] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.181s)
[486.314775] Subtest ts-continuation-suspend: SUCCESS (3.723s)
[487.158819] [081/155] (533s left) i915_drm_fdinfo (all-busy-check-all)
[487.208399] Starting subtest: all-busy-check-all
[487.214377] Subtest all-busy-check-all: SKIP (0.004s)
[487.300263] [082/155] (533s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-msflip-blt)
[487.417231] Starting subtest: hdr-1p-primscrn-indfb-msflip-blt
[487.422297] Subtest hdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[487.514182] [083/155] (533s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[487.616343] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[494.282131] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.522s)
[494.400577] [084/155] (526s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[494.506285] Starting subtest: hdr-2p-scndscrn-spr-indfb-draw-mmap-wc
[494.511662] Subtest hdr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[494.598181] [085/155] (526s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[494.701409] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[494.706325] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[494.804261] [086/155] (526s left) sysfs_timeslice_duration (duration)
[494.856179] Starting subtest: duration
[494.858653] Starting dynamic subtest: vecs0
[509.282516] Dynamic subtest vecs0: SUCCESS (14.336s)
[509.299231] Starting dynamic subtest: ccs0
[522.442013] Dynamic subtest ccs0: SUCCESS (13.148s)
[522.455711] Starting dynamic subtest: vcs0
[536.289485] Dynamic subtest vcs0: SUCCESS (13.844s)
[536.305671] Starting dynamic subtest: bcs0
[549.456477] Dynamic subtest bcs0: SUCCESS (13.153s)
[549.470158] Starting dynamic subtest: rcs0
[562.603465] Dynamic subtest rcs0: SUCCESS (13.148s)
[562.616050] Starting dynamic subtest: vcs1
[576.288814] Dynamic subtest vcs1: SUCCESS (13.676s)
[576.291677] Subtest duration: SUCCESS (81.322s)
[576.373165] [087/155] (444s left) i915_query (engine-info)
[576.434659] Starting subtest: engine-info
[576.436943] Subtest engine-info: SUCCESS (0.004s)
[576.582982] [088/155] (444s left) syncobj_timeline (single-wait-all-for-submit-available-signaled)
[576.640750] Starting subtest: single-wait-all-for-submit-available-signaled
[576.643314] Subtest single-wait-all-for-submit-available-signaled: SUCCESS (0.000s)
[576.738167] [089/155] (444s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[576.841199] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[576.843857] Starting dynamic subtest: pipe-A
[576.862390] Dynamic subtest pipe-A: SKIP (0.017s)
[576.873017] Starting dynamic subtest: pipe-B
[576.892226] Dynamic subtest pipe-B: SKIP (0.030s)
[576.902676] Starting dynamic subtest: pipe-C
[576.921234] Dynamic subtest pipe-C: SKIP (0.029s)
[576.931859] Starting dynamic subtest: pipe-D
[576.952549] Dynamic subtest pipe-D: SKIP (0.029s)
[576.957751] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SKIP (0.108s)
[577.046684] [090/155] (444s left) kms_flip (flip-vs-blocking-wf-vblank)
[577.139080] Starting subtest: flip-vs-blocking-wf-vblank
[577.141576] Starting dynamic subtest: A-eDP1
[586.099097] Dynamic subtest A-eDP1: SUCCESS (8.978s)
[586.111126] Starting dynamic subtest: B-eDP1
[595.011178] Dynamic subtest B-eDP1: SUCCESS (8.935s)
[595.022064] Starting dynamic subtest: C-eDP1
[603.962110] Dynamic subtest C-eDP1: SUCCESS (8.916s)
[603.974381] Starting dynamic subtest: D-eDP1
[612.883316] Dynamic subtest D-eDP1: SUCCESS (8.926s)
[612.886319] Subtest flip-vs-blocking-wf-vblank: SUCCESS (35.764s)
[614.060011] [091/155] (407s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-pwrite)
[614.156738] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-pwrite
[614.161395] Subtest fbc-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[614.249135] [092/155] (407s left) gem_exec_whisper (basic-sync)
[614.318722] Starting subtest: basic-sync
[622.096507] Subtest basic-sync: SUCCESS (7.756s)
[622.259112] [093/155] (399s left) gem_exec_params (cliprects_ptr-dirt)
[622.313205] Starting subtest: cliprects_ptr-dirt
[622.316361] Subtest cliprects_ptr-dirt: SUCCESS (0.000s)
[622.416275] [094/155] (399s left) kms_big_fb (y-tiled-8bpp-rotate-90)
[622.514281] Starting subtest: y-tiled-8bpp-rotate-90
[622.519453] Subtest y-tiled-8bpp-rotate-90: SKIP (0.000s)
[622.611393] [095/155] (399s left) gem_userptr_blits (huge-split)
[622.683689] Starting subtest: huge-split
[622.694251] Subtest huge-split: SUCCESS (0.011s)
[622.805808] [096/155] (398s left) kms_lease (lease-invalid-plane)
[622.900399] Starting subtest: lease-invalid-plane
[622.903365] Starting dynamic subtest: pipe-A-eDP-1
[622.906323] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[622.918138] Starting dynamic subtest: pipe-B-eDP-1
[622.920476] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[622.931494] Starting dynamic subtest: pipe-C-eDP-1
[622.934332] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[622.947312] Starting dynamic subtest: pipe-D-eDP-1
[622.950334] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[622.953148] Subtest lease-invalid-plane: SUCCESS (0.004s)
[622.993526] [097/155] (398s left) gem_softpin (allocator-nopin-reserve)
[623.046287] Starting subtest: allocator-nopin-reserve
[623.139764] Subtest allocator-nopin-reserve: SUCCESS (0.098s)
[623.264704] [098/155] (398s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[623.357305] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt
[623.361755] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[623.456599] [099/155] (398s left) perf_pmu (busy)
[623.497851] Starting subtest: busy
[623.499775] Starting dynamic subtest: rcs0
[624.080095] Dynamic subtest rcs0: SUCCESS (0.583s)
[624.094288] Starting dynamic subtest: bcs0
[624.643469] Dynamic subtest bcs0: SUCCESS (0.561s)
[624.656548] Starting dynamic subtest: vcs0
[626.371975] Dynamic subtest vcs0: SUCCESS (1.725s)
[626.385136] Starting dynamic subtest: vcs1
[628.356934] Dynamic subtest vcs1: SUCCESS (1.983s)
[628.370764] Starting dynamic subtest: vecs0
[630.403452] Dynamic subtest vecs0: SUCCESS (2.044s)
[630.415678] Starting dynamic subtest: ccs0
[630.971854] Dynamic subtest ccs0: SUCCESS (0.566s)
[630.974668] Subtest busy: SUCCESS (7.480s)
[631.079998] [100/155] (390s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-shrfb-pgflip-blt)
[631.186875] Starting subtest: fbcpsrhdr-2p-primscrn-shrfb-pgflip-blt
[631.191667] Subtest fbcpsrhdr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[631.299547] [101/155] (390s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-blt)
[631.412935] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-blt
[631.417452] Subtest fbc-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[631.508343] [102/155] (390s left) gem_exec_fence (syncobj-timeline-multiple-ext-nodes)
[632.051202] Starting subtest: syncobj-timeline-multiple-ext-nodes
[632.057751] Subtest syncobj-timeline-multiple-ext-nodes: SUCCESS (0.005s)
[633.491275] [103/155] (388s left) api_intel_allocator (fork-simple-once)
[633.559478] Starting subtest: fork-simple-once
[633.573033] Subtest fork-simple-once: SUCCESS (0.015s)
[633.687366] [104/155] (388s left) kms_flip (2x-flip-vs-suspend)
[633.785257] Starting subtest: 2x-flip-vs-suspend
[633.789978] Subtest 2x-flip-vs-suspend: SKIP (0.002s)
[633.886857] [105/155] (388s left) gem_exec_schedule (preempt-other)
[633.969049] Starting subtest: preempt-other
[633.971489] Starting dynamic subtest: rcs0
[634.028065] Dynamic subtest rcs0: SUCCESS (0.069s)
[634.046532] Starting dynamic subtest: bcs0
[634.095086] Dynamic subtest bcs0: SUCCESS (0.067s)
[634.109260] Starting dynamic subtest: vcs0
[634.163954] Dynamic subtest vcs0: SUCCESS (0.068s)
[634.177279] Starting dynamic subtest: vcs1
[634.240449] Dynamic subtest vcs1: SUCCESS (0.076s)
[634.257364] Starting dynamic subtest: vecs0
[634.309898] Dynamic subtest vecs0: SUCCESS (0.069s)
[634.322179] Starting dynamic subtest: ccs0
[634.375597] Dynamic subtest ccs0: SUCCESS (0.065s)
[634.378374] Subtest preempt-other: SUCCESS (0.418s)
[635.488054] [106/155] (386s left) kms_psr (fbc-pr-sprite-mmap-gtt)
[635.579319] Starting subtest: fbc-pr-sprite-mmap-gtt
[635.585179] Subtest fbc-pr-sprite-mmap-gtt: SKIP (0.000s)
[635.670175] [107/155] (386s left) core_getstats (basic)
[635.718301] Starting subtest: basic
[635.721384] Subtest basic: SUCCESS (0.000s)
[635.809869] [108/155] (386s left) kms_cursor_legacy (forked-bo)
[635.933166] Starting subtest: forked-bo
[635.936002] Starting dynamic subtest: pipe-A
[641.448831] Dynamic subtest pipe-A: SUCCESS (5.462s)
[641.459952] Starting dynamic subtest: pipe-B
[646.940225] Dynamic subtest pipe-B: SUCCESS (5.484s)
[646.952576] Starting dynamic subtest: pipe-C
[652.452638] Dynamic subtest pipe-C: SUCCESS (5.493s)
[652.465724] Starting dynamic subtest: pipe-D
[657.954007] Dynamic subtest pipe-D: SUCCESS (5.485s)
[657.970435] Starting dynamic subtest: all-pipes
[663.478682] Dynamic subtest all-pipes: SUCCESS (5.512s)
[663.481396] Subtest forked-bo: SUCCESS (27.448s)
[663.572639] [109/155] (358s left) gem_ctx_persistence (heartbeat-many)
[663.653443] Starting subtest: heartbeat-many
[663.659169] Subtest heartbeat-many: SKIP (0.000s)
[663.758285] [110/155] (358s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-blt)
[663.864326] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-blt
[670.798242] Subtest psr-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (6.930s)
[670.943416] [111/155] (351s left) kms_rotation_crc (multiplane-rotation-cropping-top)
[671.043395] Starting subtest: multiplane-rotation-cropping-top
[688.570086] Subtest multiplane-rotation-cropping-top: SUCCESS (17.515s)
[688.677746] [112/155] (333s left) gem_exec_reloc (basic-wc-read-noreloc)
[688.748358] Subtest basic-wc-read-noreloc: SKIP (0.000s)
[688.821016] [113/155] (333s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[688.915943] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[688.920810] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[689.005449] [114/155] (333s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[689.093449] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[689.096049] Starting dynamic subtest: pipe-A-eDP-1
[689.895246] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.793s)
[689.906457] Starting dynamic subtest: pipe-B-eDP-1
[691.426911] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.530s)
[691.437865] Starting dynamic subtest: pipe-C-eDP-1
[692.935770] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.505s)
[692.949213] Starting dynamic subtest: pipe-D-eDP-1
[694.485512] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.548s)
[694.488308] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.381s)
[695.654933] [115/155] (326s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs)
[695.748838] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs
[695.751273] Starting dynamic subtest: pipe-A-eDP-1
[695.773781] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[695.785373] Starting dynamic subtest: pipe-B-eDP-1
[695.810844] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[695.821284] Starting dynamic subtest: pipe-C-eDP-1
[695.846308] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[695.857450] Starting dynamic subtest: pipe-D-eDP-1
[695.884074] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[695.889027] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[698.027993] [116/155] (324s left) kms_frontbuffer_tracking (fbcpsrhdr-stridechange)
[698.124490] Starting subtest: fbcpsrhdr-stridechange
[698.130116] Subtest fbcpsrhdr-stridechange: SKIP (0.000s)
[698.222233] [117/155] (324s left) kms_cursor_legacy (single-move)
[698.336923] Starting subtest: single-move
[698.339389] Starting dynamic subtest: pipe-A
[703.773616] Dynamic subtest pipe-A: SUCCESS (5.378s)
[703.785973] Starting dynamic subtest: pipe-B
[709.158017] Dynamic subtest pipe-B: SUCCESS (5.380s)
[709.170473] Starting dynamic subtest: pipe-C
[714.540264] Dynamic subtest pipe-C: SUCCESS (5.379s)
[714.553263] Starting dynamic subtest: pipe-D
[719.919192] Dynamic subtest pipe-D: SUCCESS (5.379s)
[719.931699] Starting dynamic subtest: all-pipes
[725.307338] Dynamic subtest all-pipes: SUCCESS (5.386s)
[725.309775] Subtest single-move: SUCCESS (26.915s)
[725.414017] [118/155] (297s left) perf_pmu (init-sema)
[725.456200] Starting subtest: init-sema
[725.458823] Starting dynamic subtest: rcs0
[725.461392] Dynamic subtest rcs0: SUCCESS (0.002s)
[725.473315] Starting dynamic subtest: bcs0
[725.475867] Dynamic subtest bcs0: SUCCESS (0.000s)
[725.486507] Starting dynamic subtest: vcs0
[725.488976] Dynamic subtest vcs0: SUCCESS (0.001s)
[725.499454] Starting dynamic subtest: vcs1
[725.502327] Dynamic subtest vcs1: SUCCESS (0.001s)
[725.513728] Starting dynamic subtest: vecs0
[725.515975] Dynamic subtest vecs0: SUCCESS (0.001s)
[725.526979] Starting dynamic subtest: ccs0
[725.529324] Dynamic subtest ccs0: SUCCESS (0.001s)
[725.531845] Subtest init-sema: SUCCESS (0.008s)
[725.580864] [119/155] (297s left) kms_frontbuffer_tracking (psr-slowdraw)
[725.677002] Starting subtest: psr-slowdraw
[750.956331] Subtest psr-slowdraw: SUCCESS (25.284s)
[751.091788] [120/155] (271s left) gem_exec_flush (basic-wb-pro-default)
[751.160503] Starting subtest: basic-wb-pro-default
[756.657285] Subtest basic-wb-pro-default: SUCCESS (5.459s)
[756.816009] [121/155] (265s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[756.936168] Starting subtest: psrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt
[756.941474] Subtest psrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[757.021600] [122/155] (265s left) kms_psr (psr-cursor-mmap-cpu)
[757.119412] Starting subtest: psr-cursor-mmap-cpu
[757.121814] Starting dynamic subtest: eDP-1
[758.839063] Dynamic subtest eDP-1: SUCCESS (1.720s)
[758.841757] Subtest psr-cursor-mmap-cpu: SUCCESS (1.721s)
[759.705865] [123/155] (263s left) gem_exec_parallel (basic)
[759.767495] Starting subtest: basic
[759.770413] Starting dynamic subtest: rcs0
[761.608282] Dynamic subtest rcs0: SUCCESS (1.840s)
[761.622406] Starting dynamic subtest: bcs0
[763.339399] Dynamic subtest bcs0: SUCCESS (1.728s)
[763.350938] Starting dynamic subtest: vcs0
[765.315128] Dynamic subtest vcs0: SUCCESS (1.973s)
[765.326503] Starting dynamic subtest: vcs1
[767.363498] Dynamic subtest vcs1: SUCCESS (2.046s)
[767.377061] Starting dynamic subtest: vecs0
[769.348144] Dynamic subtest vecs0: SUCCESS (1.981s)
[769.359782] Starting dynamic subtest: ccs0
[771.220215] Dynamic subtest ccs0: SUCCESS (1.870s)
[771.223157] Subtest basic: SUCCESS (11.452s)
[771.423237] [124/155] (251s left) gem_mmap_wc (fault-concurrent)
[771.486035] Subtest fault-concurrent: SKIP (0.000s)
[771.552005] [125/155] (251s left) kms_cursor_legacy (cursora-vs-flipa-atomic)
[771.679241] Starting subtest: cursorA-vs-flipA-atomic
[782.798832] Subtest cursorA-vs-flipA-atomic: SUCCESS (11.119s)
[783.637002] [126/155] (239s left) syncobj_wait (wait-for-submit-delayed-submit)
[783.694809] Starting subtest: wait-for-submit-delayed-submit
[783.798077] Subtest wait-for-submit-delayed-submit: SUCCESS (0.103s)
[783.893066] [127/155] (239s left) kms_ccs (bad-pixel-format-4-tiled-dg2-mc-ccs)
[783.987275] Starting subtest: bad-pixel-format-4-tiled-dg2-mc-ccs
[783.990150] Starting dynamic subtest: pipe-A-eDP-1
[784.014836] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[784.028510] Starting dynamic subtest: pipe-B-eDP-1
[784.055397] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[784.067477] Starting dynamic subtest: pipe-C-eDP-1
[784.093806] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[784.104483] Starting dynamic subtest: pipe-D-eDP-1
[784.131350] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[784.136203] Subtest bad-pixel-format-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[786.307263] [128/155] (236s left) kms_cdclk (mode-transition)
[786.399297] Starting subtest: mode-transition
[786.402213] Starting dynamic subtest: pipe-A-eDP-1
[786.696967] Dynamic subtest pipe-A-eDP-1: SKIP (0.293s)
[786.707901] Starting dynamic subtest: pipe-B-eDP-1
[786.712463] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[786.729196] Starting dynamic subtest: pipe-C-eDP-1
[786.734027] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[786.744398] Starting dynamic subtest: pipe-D-eDP-1
[786.749285] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[786.754403] Subtest mode-transition: SKIP (0.300s)
[787.546009] [129/155] (235s left) kms_chamelium_frames (dp-crc-fast)
[787.933965] Subtest dp-crc-fast: SKIP (0.000s)
[788.764251] [130/155] (234s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-shrfb-plflip-blt)
[788.864046] Starting subtest: psrhdr-2p-primscrn-shrfb-plflip-blt
[788.869145] Subtest psrhdr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[788.963185] [131/155] (234s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-cc-modifier)
[789.054491] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-cc-modifier
[789.059497] Subtest pixel-format-4-tiled-dg2-rc-ccs-cc-modifier: SKIP (0.000s)
[789.129062] [132/155] (234s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-upscaling)
[789.224479] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-upscaling
[789.229583] Subtest flip-64bpp-ytile-to-16bpp-ytile-upscaling: SKIP (0.000s)
[789.296450] [133/155] (234s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[789.391701] Starting subtest: addfb25-y-tiled-legacy
[789.393907] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[789.480546] [134/155] (233s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb101010-draw-blt)
[789.583248] Starting subtest: fbcpsrhdr-rgb101010-draw-blt
[789.588076] Subtest fbcpsrhdr-rgb101010-draw-blt: SKIP (0.000s)
[789.684856] [135/155] (233s left) kms_psr2_sf (pr-overlay-plane-update-continuous-sf)
[789.781820] Starting subtest: pr-overlay-plane-update-continuous-sf
[789.786029] Subtest pr-overlay-plane-update-continuous-sf: SKIP (0.000s)
[789.855751] [136/155] (233s left) gem_exec_balancer (fairslice)
[789.916074] Starting subtest: fairslice
[792.857468] Subtest fairslice: SUCCESS (2.939s)
[793.426523] [137/155] (230s left) gem_basic (create-fd-close)
[793.481169] Starting subtest: create-fd-close
[793.486235] Subtest create-fd-close: SUCCESS (0.000s)
[793.571081] [138/155] (229s left) kms_setmode (basic)
[793.625022] Starting subtest: basic
[793.945135] Starting dynamic subtest: pipe-A-eDP-1
[796.007071] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.057s)
[796.350531] Starting dynamic subtest: pipe-B-eDP-1
[798.440088] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.095s)
[798.747455] Subtest basic: SUCCESS (5.121s)
[799.578120] [139/155] (223s left) syncobj_eventfd (binary-wait-signaled)
[799.630231] Starting subtest: binary-wait-signaled
[799.632971] Subtest binary-wait-signaled: SUCCESS (0.000s)
[799.728026] [140/155] (223s left) syncobj_wait (invalid-signal-bad-pad)
[799.775138] Starting subtest: invalid-signal-bad-pad
[799.778057] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[799.866976] [141/155] (223s left) gem_exec_params (invalid-bsd-ring)
[799.926011] Starting subtest: invalid-bsd-ring
[799.928382] Subtest invalid-bsd-ring: SUCCESS (0.000s)
[800.027576] [142/155] (223s left) kms_flip (busy-flip)
[800.111130] Starting subtest: busy-flip
[800.113476] Starting dynamic subtest: A-eDP1
[801.796253] Dynamic subtest A-eDP1: SUCCESS (1.686s)
[801.807517] Starting dynamic subtest: B-eDP1
[803.525454] Dynamic subtest B-eDP1: SUCCESS (1.728s)
[803.540077] Starting dynamic subtest: C-eDP1
[805.260474] Dynamic subtest C-eDP1: SUCCESS (1.732s)
[805.272831] Starting dynamic subtest: D-eDP1
[806.915024] Dynamic subtest D-eDP1: SUCCESS (1.652s)
[806.917764] Subtest busy-flip: SUCCESS (6.808s)
[808.083868] [143/155] (215s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[808.336249] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[809.273012] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.937s)
[809.427328] [144/155] (214s left) gem_exec_schedule (thriceslice)
[809.495491] Starting subtest: thriceslice
[809.498349] Starting dynamic subtest: rcs0
[809.512691] Dynamic subtest rcs0: SUCCESS (0.029s)
[809.527385] Starting dynamic subtest: bcs0
[809.541969] Dynamic subtest bcs0: SUCCESS (0.029s)
[809.557238] Starting dynamic subtest: vcs0
[809.573707] Dynamic subtest vcs0: SUCCESS (0.031s)
[809.587167] Starting dynamic subtest: vcs1
[809.605329] Dynamic subtest vcs1: SUCCESS (0.031s)
[809.619490] Starting dynamic subtest: vecs0
[809.658854] Dynamic subtest vecs0: SUCCESS (0.052s)
[809.670442] Starting dynamic subtest: ccs0
[809.715469] Dynamic subtest ccs0: SUCCESS (0.054s)
[809.718452] Subtest thriceslice: SUCCESS (0.234s)
[811.435628] [145/155] (212s left) gem_ctx_shared (single-timeline)
[811.501262] Starting subtest: single-timeline
[811.527131] Subtest single-timeline: SUCCESS (0.016s)
[811.755402] [146/155] (212s left) kms_psr (fbc-psr2-suspend)
[811.850334] Starting subtest: fbc-psr2-suspend
[811.852871] Starting dynamic subtest: eDP-1
[811.858322] Dynamic subtest eDP-1: SKIP (0.000s)
[811.863541] Subtest fbc-psr2-suspend: SKIP (0.001s)
[811.924384] [147/155] (211s left) kms_atomic_transition (modeset-transition-nonblocking)
[812.017231] Starting subtest: modeset-transition-nonblocking
[812.019508] Starting dynamic subtest: 1x-outputs
[823.107834] Dynamic subtest 1x-outputs: SUCCESS (11.089s)
[823.110455] Subtest modeset-transition-nonblocking: SUCCESS (11.090s)
[823.976334] [148/155] (199s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-sf-dmg-area)
[824.075021] Starting subtest: fbc-psr2-overlay-plane-update-sf-dmg-area
[824.077612] Starting dynamic subtest: pipe-A-eDP-1
[824.171084] Dynamic subtest pipe-A-eDP-1: SKIP (0.100s)
[824.182964] Starting dynamic subtest: pipe-B-eDP-1
[825.351945] Dynamic subtest pipe-B-eDP-1: SKIP (1.177s)
[825.357324] Subtest fbc-psr2-overlay-plane-update-sf-dmg-area: SKIP (1.280s)
[826.538846] [149/155] (197s left) gem_unfence_active_buffers
[826.711392] [150/155] (197s left) kms_cursor_legacy (short-flip-after-cursor-toggle)
[826.827212] Starting subtest: short-flip-after-cursor-toggle
[827.746228] Subtest short-flip-after-cursor-toggle: SUCCESS (0.920s)
[828.574370] [151/155] (195s left) kms_pipe_crc_basic (suspend-read-crc)
[828.679176] Starting subtest: suspend-read-crc
[828.681942] Starting dynamic subtest: pipe-A-eDP-1
[845.902016] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.485s)
[845.916289] Starting dynamic subtest: pipe-B-eDP-1
[863.909577] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.241s)
[863.922208] Starting dynamic subtest: pipe-C-eDP-1
[881.864148] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.103s)
[881.876160] Starting dynamic subtest: pipe-D-eDP-1
[899.896139] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.109s)
[899.898826] Subtest suspend-read-crc: SUCCESS (11.944s)
[900.741529] [152/155] (123s left) kms_cursor_legacy (2x-flip-vs-cursor-legacy)
[900.875500] Starting subtest: 2x-flip-vs-cursor-legacy
[900.880649] Subtest 2x-flip-vs-cursor-legacy: SKIP (0.001s)
[901.023744] [153/155] (123s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping)
[901.118116] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping
[901.143580] Starting dynamic subtest: pipe-A-plane-0
[901.443153] Dynamic subtest pipe-A-plane-0: SUCCESS (0.300s)
[901.456645] Starting dynamic subtest: pipe-A-plane-3
[901.676194] Dynamic subtest pipe-A-plane-3: SUCCESS (0.231s)
[902.814616] Starting dynamic subtest: pipe-B-plane-0
[903.116492] Dynamic subtest pipe-B-plane-0: SUCCESS (0.311s)
[903.128063] Starting dynamic subtest: pipe-B-plane-3
[903.348448] Dynamic subtest pipe-B-plane-3: SUCCESS (0.233s)
[903.663935] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping: SUCCESS (2.546s)
[904.504155] [154/155] (119s left) gem_mmap_gtt (bad-object)
[904.569143] Subtest bad-object: SKIP (0.000s)
[904.631155] [155/155] (119s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-indfb-pgflip-blt)
[904.731922] Starting subtest: fbchdr-1p-primscrn-indfb-pgflip-blt
[904.736007] Subtest fbchdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[904.846218] Closing watchdogs
results: parsing output: 0/ for test: gem_exec_whisper
results: parsing output: 1/ for test: syncobj_timeline
results: parsing output: 2/ for test: gem_render_copy
results: parsing output: 3/ for test: kms_flip_scaled_crc
results: parsing output: 4/ for test: syncobj_timeline
results: parsing output: 5/ for test: syncobj_timeline
results: parsing output: 6/ for test: kms_color
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: kms_plane_scaling
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: vgem_basic
results: parsing output: 11/ for test: gem_mmap
results: parsing output: 12/ for test: syncobj_wait
results: parsing output: 13/ for test: kms_pm_rpm
results: parsing output: 14/ for test: kms_big_fb
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: core_setmaster
results: parsing output: 17/ for test: gem_ctx_isolation
results: parsing output: 18/ for test: kms_joiner
results: parsing output: 19/ for test: kms_psr
results: parsing output: 20/ for test: kms_cursor_crc
results: parsing output: 21/ for test: i915_pm_rpm
results: parsing output: 22/ for test: kms_busy
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: gem_ccs
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: gem_exec_reloc
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_chamelium_hpd
results: parsing output: 31/ for test: gem_exec_fence
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: gem_render_copy
results: parsing output: 35/ for test: gem_userptr_blits
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: gem_tiling_max_stride
results: parsing output: 38/ for test: kms_cursor_legacy
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: gem_mmap_offset
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: gem_sync
results: parsing output: 43/ for test: gem_linear_blits
results: parsing output: 44/ for test: kms_ccs
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: kms_cursor_crc
results: parsing output: 47/ for test: syncobj_timeline
results: parsing output: 48/ for test: kms_addfb_basic
results: parsing output: 49/ for test: gem_ppgtt
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: gem_create
results: parsing output: 52/ for test: gem_exec_fence
results: parsing output: 53/ for test: kms_psr
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: drm_read
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_prop_blob
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: kms_content_protection
results: parsing output: 61/ for test: i915_hangman
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_flip
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: kms_big_fb
results: parsing output: 66/ for test: kms_feature_discovery
results: parsing output: 67/ for test: gen9_exec_parse
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: kms_cursor_legacy
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: gem_exec_params
results: parsing output: 72/ for test: kms_getfb
results: parsing output: 73/ for test: vgem_basic
results: parsing output: 74/ for test: kms_rotation_crc
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: kms_vblank
results: parsing output: 77/ for test: syncobj_wait
results: parsing output: 78/ for test: kms_big_fb
results: parsing output: 79/ for test: kms_vblank
results: parsing output: 80/ for test: i915_drm_fdinfo
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: sysfs_timeslice_duration
results: parsing output: 86/ for test: i915_query
results: parsing output: 87/ for test: syncobj_timeline
results: parsing output: 88/ for test: kms_plane_scaling
results: parsing output: 89/ for test: kms_flip
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: gem_exec_whisper
results: parsing output: 92/ for test: gem_exec_params
results: parsing output: 93/ for test: kms_big_fb
results: parsing output: 94/ for test: gem_userptr_blits
results: parsing output: 95/ for test: kms_lease
results: parsing output: 96/ for test: gem_softpin
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: perf_pmu
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: gem_exec_fence
results: parsing output: 102/ for test: api_intel_allocator
results: parsing output: 103/ for test: kms_flip
results: parsing output: 104/ for test: gem_exec_schedule
results: parsing output: 105/ for test: kms_psr
results: parsing output: 106/ for test: core_getstats
results: parsing output: 107/ for test: kms_cursor_legacy
results: parsing output: 108/ for test: gem_ctx_persistence
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: kms_rotation_crc
results: parsing output: 111/ for test: gem_exec_reloc
results: parsing output: 112/ for test: kms_big_fb
results: parsing output: 113/ for test: kms_ccs
results: parsing output: 114/ for test: kms_ccs
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_cursor_legacy
results: parsing output: 117/ for test: perf_pmu
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: gem_exec_flush
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_psr
results: parsing output: 122/ for test: gem_exec_parallel
results: parsing output: 123/ for test: gem_mmap_wc
results: parsing output: 124/ for test: kms_cursor_legacy
results: parsing output: 125/ for test: syncobj_wait
results: parsing output: 126/ for test: kms_ccs
results: parsing output: 127/ for test: kms_cdclk
results: parsing output: 128/ for test: kms_chamelium_frames
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: kms_plane
results: parsing output: 131/ for test: kms_flip_scaled_crc
results: parsing output: 132/ for test: kms_addfb_basic
results: parsing output: 133/ for test: kms_frontbuffer_tracking
results: parsing output: 134/ for test: kms_psr2_sf
results: parsing output: 135/ for test: gem_exec_balancer
results: parsing output: 136/ for test: gem_basic
results: parsing output: 137/ for test: kms_setmode
results: parsing output: 138/ for test: syncobj_eventfd
results: parsing output: 139/ for test: syncobj_wait
results: parsing output: 140/ for test: gem_exec_params
results: parsing output: 141/ for test: kms_flip
results: parsing output: 142/ for test: kms_big_fb
results: parsing output: 143/ for test: gem_exec_schedule
results: parsing output: 144/ for test: gem_ctx_shared
results: parsing output: 145/ for test: kms_psr
results: parsing output: 146/ for test: kms_atomic_transition
results: parsing output: 147/ for test: kms_psr2_sf
results: parsing output: 148/ for test: gem_unfence_active_buffers
results: parsing output: 149/ for test: kms_cursor_legacy
results: parsing output: 150/ for test: kms_pipe_crc_basic
results: parsing output: 151/ for test: kms_cursor_legacy
results: parsing output: 152/ for test: kms_plane
results: parsing output: 153/ for test: gem_mmap_gtt
results: parsing output: 154/ for test: kms_frontbuffer_tracking
Done.