[56.629725] Initializing watchdogs [56.629882] /dev/watchdog0 [56.645053] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics] [56.649527] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [56.652052] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [56.655802] [001/156] (960s left) kms_flip (flip-vs-suspend) [56.772340] Starting subtest: flip-vs-suspend [56.774718] Starting dynamic subtest: A-eDP1 [202.002819] Dynamic subtest A-eDP1: SUCCESS (11.479s) [202.005019] Subtest flip-vs-suspend: SUCCESS (11.481s) [202.165360] [002/156] (815s left) gem_exec_whisper (basic-contexts-priority) [202.238483] Starting subtest: basic-contexts-priority [227.203994] Subtest basic-contexts-priority: SUCCESS (24.664s) [227.394848] [003/156] (789s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-submi [227.459438] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-submitted [227.563391] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-submitted: SUCCESS (0.103s) [227.651269] [004/156] (789s left) kms_pm_rpm (dpms-non-lpsp) [229.194712] Starting subtest: dpms-non-lpsp [229.199694] Subtest dpms-non-lpsp: SKIP (0.001s) [229.898510] [005/156] (787s left) sw_sync (sync_multi_consumer_producer) [229.933762] Starting subtest: sync_multi_consumer_producer [230.230323] Subtest sync_multi_consumer_producer: SUCCESS (0.296s) [230.288328] [006/156] (787s left) gem_ctx_persistence (legacy-engines-cleanup) [230.339342] Starting subtest: legacy-engines-cleanup [230.341580] Starting dynamic subtest: render [230.413040] Dynamic subtest render: SUCCESS (0.074s) [230.426559] Starting dynamic subtest: bsd1 [230.457169] Dynamic subtest bsd1: SUCCESS (0.042s) [230.467202] Starting dynamic subtest: bsd2 [230.493845] Dynamic subtest bsd2: SUCCESS (0.036s) [230.503395] Starting dynamic subtest: blt [230.541227] Dynamic subtest blt: SUCCESS (0.046s) [230.551753] Starting dynamic subtest: vebox [230.580300] Dynamic subtest vebox: SUCCESS (0.038s) [230.582810] Subtest legacy-engines-cleanup: SUCCESS (0.241s) [230.666964] [007/156] (786s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff) [230.771392] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff [230.776135] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s) [230.846947] [008/156] (786s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc) [230.944986] Starting subtest: missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc [230.947350] Starting dynamic subtest: pipe-A-eDP-1 [230.970046] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [230.981002] Starting dynamic subtest: pipe-B-eDP-1 [231.003527] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [231.014355] Starting dynamic subtest: pipe-C-eDP-1 [231.036192] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [231.047470] Starting dynamic subtest: pipe-D-eDP-1 [231.069779] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [231.073915] Subtest missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc: SKIP (0.002s) [233.267811] [009/156] (784s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats) [233.663550] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s) [234.490473] [010/156] (783s left) kms_pipe_b_c_ivb (from-pipe-c-to-b-with-3-lanes) [234.558777] Subtest from-pipe-C-to-B-with-3-lanes: SKIP (0.000s) [234.623412] [011/156] (782s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-gtt) [234.712406] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-gtt [234.716588] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s) [234.788633] [012/156] (782s left) gem_softpin (safe-alignment) [234.855397] Starting subtest: safe-alignment [234.876360] Subtest safe-alignment: SUCCESS (0.024s) [236.941326] [013/156] (780s left) kms_cursor_crc (cursor-onscreen-128x128) [237.140390] Starting subtest: cursor-onscreen-128x128 [237.142713] Starting dynamic subtest: pipe-A-eDP-1 [239.250793] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.107s) [239.280043] Starting dynamic subtest: pipe-D-eDP-1 [242.124623] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.853s) [242.127043] Subtest cursor-onscreen-128x128: SUCCESS (4.984s) [242.995631] [014/156] (774s left) kms_dp_linktrain_fallback (dp-fallback) [243.086996] Starting subtest: dp-fallback [243.093646] Subtest dp-fallback: SKIP (0.000s) [243.154198] [015/156] (774s left) gem_ctx_shared (exec-shared-gtt) [243.210126] Starting subtest: exec-shared-gtt [243.212252] Starting dynamic subtest: rcs0 [243.217399] Dynamic subtest rcs0: SUCCESS (0.007s) [243.226309] Starting dynamic subtest: bcs0 [243.228418] Dynamic subtest bcs0: SUCCESS (0.009s) [243.238075] Starting dynamic subtest: vcs0 [243.240394] Dynamic subtest vcs0: SUCCESS (0.008s) [243.248919] Starting dynamic subtest: vcs1 [243.250776] Dynamic subtest vcs1: SUCCESS (0.007s) [243.259808] Starting dynamic subtest: vecs0 [243.261977] Dynamic subtest vecs0: SUCCESS (0.005s) [243.270352] Starting dynamic subtest: ccs0 [243.272528] Dynamic subtest ccs0: SUCCESS (0.005s) [243.274768] Subtest exec-shared-gtt: SUCCESS (0.043s) [243.449390] [016/156] (774s left) kms_cursor_legacy (flip-vs-cursor-crc-legacy) [243.573885] Starting subtest: flip-vs-cursor-crc-legacy [244.254839] Subtest flip-vs-cursor-crc-legacy: SUCCESS (0.680s) [245.119083] [017/156] (772s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip) [245.224080] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip [246.318955] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip: FAIL (0.893s) [246.362030] [018/156] (771s left) syncobj_basic (illegal-fd-to-handle) [246.411950] Starting subtest: illegal-fd-to-handle [246.414288] Subtest illegal-fd-to-handle: SUCCESS (0.000s) [246.507941] [019/156] (771s left) kms_flip (nonexisting-fb) [246.593396] Starting subtest: nonexisting-fb [246.595218] Starting dynamic subtest: A-eDP1 [247.820386] Dynamic subtest A-eDP1: SUCCESS (1.224s) [247.831788] Starting dynamic subtest: B-eDP1 [248.946959] Dynamic subtest B-eDP1: SUCCESS (1.125s) [248.956406] Starting dynamic subtest: C-eDP1 [250.075942] Dynamic subtest C-eDP1: SUCCESS (1.128s) [250.087968] Starting dynamic subtest: D-eDP1 [251.206210] Dynamic subtest D-eDP1: SUCCESS (1.129s) [251.208447] Subtest nonexisting-fb: SUCCESS (4.614s) [252.389595] [020/156] (765s left) gem_pxp (regular-baseline-src-copy-readible) [252.508606] Starting subtest: regular-baseline-src-copy-readible [252.571773] Subtest regular-baseline-src-copy-readible: SUCCESS (0.066s) [252.714678] [021/156] (765s left) kms_vrr (seamless-rr-switch-drrs) [252.804613] Starting subtest: seamless-rr-switch-drrs [252.810878] Subtest seamless-rr-switch-drrs: SKIP (0.000s) [252.888075] [022/156] (765s left) kms_flip (2x-plain-flip) [252.975686] Starting subtest: 2x-plain-flip [252.983893] Subtest 2x-plain-flip: SKIP (0.010s) [253.063970] [023/156] (764s left) kms_fence_pin_leak [253.236813] [024/156] (764s left) kms_fb_coherency (memset-crc) [253.336669] Starting subtest: memset-crc [253.338371] Starting dynamic subtest: mmap-offset-wc [253.698270] Dynamic subtest mmap-offset-wc: SUCCESS (0.362s) [254.003214] Starting dynamic subtest: mmap-offset-uc [260.103654] Dynamic subtest mmap-offset-uc: SUCCESS (6.107s) [260.393828] Subtest memset-crc: SUCCESS (7.055s) [261.258597] [025/156] (756s left) kms_ccs (bad-rotation-90-yf-tiled-ccs) [261.350029] Starting subtest: bad-rotation-90-yf-tiled-ccs [261.352241] Starting dynamic subtest: pipe-A-eDP-1 [261.374303] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [261.385977] Starting dynamic subtest: pipe-B-eDP-1 [261.406814] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [261.415406] Starting dynamic subtest: pipe-C-eDP-1 [261.435788] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [261.444180] Starting dynamic subtest: pipe-D-eDP-1 [261.465301] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [261.469228] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.003s) [263.616176] [026/156] (754s left) gen7_exec_parse (bitmasks) [263.683926] Subtest bitmasks: SKIP (0.000s) [263.755251] [027/156] (754s left) syncobj_wait (multi-wait-all-signaled) [263.810589] Starting subtest: multi-wait-all-signaled [263.812997] Subtest multi-wait-all-signaled: SUCCESS (0.000s) [263.918329] [028/156] (754s left) kms_getfb (getfb-handle-valid) [263.999741] Starting subtest: getfb-handle-valid [264.002047] Subtest getfb-handle-valid: SUCCESS (0.000s) [264.114924] [029/156] (754s left) perf (oa-exponents) [264.238269] Starting subtest: oa-exponents [264.240223] Starting dynamic subtest: 0-rcs0 [266.034791] Dynamic subtest 0-rcs0: SUCCESS (1.796s) [266.044197] Starting dynamic subtest: 1-vcs1 [267.863152] Dynamic subtest 1-vcs1: SUCCESS (1.827s) [267.865640] Subtest oa-exponents: SUCCESS (3.624s) [267.908675] [030/156] (750s left) gem_exec_fence (concurrent) [268.081445] Starting subtest: concurrent [268.085860] Subtest concurrent: SKIP (0.000s) [270.007432] [FACT gem_exec_fence (concurrent)] new: kernel.kmod_is_loaded.vgem: true [270.011348] [031/156] (748s left) gem_exec_params (rel-constants-invalid-ring) [270.064976] Starting subtest: rel-constants-invalid-ring [270.067421] Subtest rel-constants-invalid-ring: SUCCESS (0.000s) [270.177532] [032/156] (748s left) kms_plane (pixel-format-x-tiled-modifier) [270.265847] Starting subtest: pixel-format-x-tiled-modifier [270.292306] Starting dynamic subtest: pipe-A-plane-0 [270.681070] Dynamic subtest pipe-A-plane-0: SUCCESS (0.389s) [270.691796] Starting dynamic subtest: pipe-A-plane-3 [271.025482] Dynamic subtest pipe-A-plane-3: SUCCESS (0.344s) [271.034210] Starting dynamic subtest: pipe-A-plane-5 [271.038102] Dynamic subtest pipe-A-plane-5: SKIP (0.000s) [272.114061] Starting dynamic subtest: pipe-B-plane-0 [272.504942] Dynamic subtest pipe-B-plane-0: SUCCESS (0.400s) [272.516403] Starting dynamic subtest: pipe-B-plane-3 [272.816621] Dynamic subtest pipe-B-plane-3: SUCCESS (0.311s) [272.826624] Starting dynamic subtest: pipe-B-plane-5 [272.831255] Dynamic subtest pipe-B-plane-5: SKIP (0.000s) [273.126369] Subtest pixel-format-x-tiled-modifier: SUCCESS (2.858s) [273.977778] [033/156] (744s left) gem_ctx_persistence (engines-queued) [274.034353] Starting subtest: engines-queued [274.036344] Starting dynamic subtest: rcs0 [274.130992] Dynamic subtest rcs0: SUCCESS (0.097s) [274.141907] Starting dynamic subtest: bcs0 [274.165766] Dynamic subtest bcs0: SUCCESS (0.033s) [274.174596] Starting dynamic subtest: vcs0 [275.849780] Dynamic subtest vcs0: SUCCESS (1.680s) [275.860513] Starting dynamic subtest: vcs1 [277.893520] Dynamic subtest vcs1: SUCCESS (2.041s) [277.906277] Starting dynamic subtest: vecs0 [279.878321] Dynamic subtest vecs0: SUCCESS (1.982s) [279.890682] Starting dynamic subtest: ccs0 [280.063214] Dynamic subtest ccs0: SUCCESS (0.184s) [280.065555] Subtest engines-queued: SUCCESS (6.028s) [280.194491] [034/156] (738s left) i915_drm_fdinfo (context-close-stress) [280.231468] Starting subtest: context-close-stress [290.981397] Subtest context-close-stress: SUCCESS (10.745s) [291.112438] [035/156] (727s left) kms_psr (psr-cursor-plane-onoff) [291.327684] Starting subtest: psr-cursor-plane-onoff [291.330026] Starting dynamic subtest: eDP-1 [293.082169] Dynamic subtest eDP-1: SUCCESS (1.752s) [293.084196] Subtest psr-cursor-plane-onoff: SUCCESS (1.753s) [293.946310] [036/156] (724s left) kms_ccs (crc-primary-basic-4-tiled-dg2-mc-ccs) [294.038804] Starting subtest: crc-primary-basic-4-tiled-dg2-mc-ccs [294.041023] Starting dynamic subtest: pipe-A-eDP-1 [294.064580] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [294.076721] Starting dynamic subtest: pipe-B-eDP-1 [294.098116] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [294.106971] Starting dynamic subtest: pipe-C-eDP-1 [294.128924] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [294.137926] Starting dynamic subtest: pipe-D-eDP-1 [294.159912] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [294.164415] Subtest crc-primary-basic-4-tiled-dg2-mc-ccs: SKIP (0.005s) [296.313871] [037/156] (722s left) syncobj_timeline (single-wait-for-submit-available-signaled) [296.377111] Starting subtest: single-wait-for-submit-available-signaled [296.379128] Subtest single-wait-for-submit-available-signaled: SUCCESS (0.000s) [296.493279] [038/156] (722s left) gem_exec_balancer (full) [296.545382] Starting subtest: full [300.685949] Subtest full: SUCCESS (4.141s) [300.994287] [039/156] (717s left) kms_psr (psr2-cursor-plane-onoff) [301.088483] Starting subtest: psr2-cursor-plane-onoff [301.090753] Starting dynamic subtest: eDP-1 [302.793406] Dynamic subtest eDP-1: SUCCESS (1.704s) [302.795691] Subtest psr2-cursor-plane-onoff: SUCCESS (1.705s) [303.673385] [040/156] (715s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-downscaling) [303.773163] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-downscaling [303.775442] Starting dynamic subtest: pipe-A-default-mode [304.926325] Dynamic subtest pipe-A-default-mode: SKIP (1.153s) [304.930067] Subtest flip-64bpp-xtile-to-16bpp-xtile-downscaling: SKIP (1.154s) [305.075300] [041/156] (713s left) gem_exec_fence (basic-wait-all) [305.138367] Starting subtest: basic-wait-all [305.235281] Subtest basic-wait-all: SUCCESS (0.098s) [307.003456] [042/156] (711s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-indfb-pgflip-blt) [307.106220] Starting subtest: fbchdr-1p-primscrn-indfb-pgflip-blt [307.110387] Subtest fbchdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s) [307.199940] [043/156] (711s left) gem_wait (wait) [307.254408] Starting subtest: wait [307.256780] Starting dynamic subtest: all-engines [308.902999] Dynamic subtest all-engines: SUCCESS (1.647s) [308.915373] Starting dynamic subtest: rcs0 [309.949120] Dynamic subtest rcs0: SUCCESS (1.045s) [309.959918] Starting dynamic subtest: bcs0 [310.994877] Dynamic subtest bcs0: SUCCESS (1.045s) [311.006228] Starting dynamic subtest: vcs0 [312.040079] Dynamic subtest vcs0: SUCCESS (1.043s) [312.051860] Starting dynamic subtest: vcs1 [313.085241] Dynamic subtest vcs1: SUCCESS (1.043s) [313.095789] Starting dynamic subtest: vecs0 [314.129634] Dynamic subtest vecs0: SUCCESS (1.043s) [314.140916] Starting dynamic subtest: ccs0 [315.175480] Dynamic subtest ccs0: SUCCESS (1.044s) [315.178083] Subtest wait: SUCCESS (7.923s) [315.384058] [044/156] (703s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt) [315.478027] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt [315.482071] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s) [315.568638] [045/156] (703s left) kms_frontbuffer_tracking (fbcpsr-tiling-y) [315.667778] Starting subtest: fbcpsr-tiling-y [315.671772] Subtest fbcpsr-tiling-y: SKIP (0.000s) [315.759987] [046/156] (703s left) gem_readwrite (read-bad-handle) [315.819098] Subtest read-bad-handle: SKIP (0.000s) [315.882508] [047/156] (703s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip) [315.978970] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip [315.983591] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s) [316.089858] [048/156] (702s left) kms_universal_plane (cursor-fb-leak) [316.193561] Starting subtest: cursor-fb-leak [316.195904] Starting dynamic subtest: pipe-A-eDP-1 [316.513691] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.323s) [316.526779] Starting dynamic subtest: pipe-B-eDP-1 [317.932794] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.418s) [317.945908] Starting dynamic subtest: pipe-C-eDP-1 [319.338284] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.403s) [319.352647] Starting dynamic subtest: pipe-D-eDP-1 [320.658505] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.319s) [320.660887] Subtest cursor-fb-leak: SUCCESS (4.469s) [321.834978] [049/156] (697s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-shrfb-draw-render) [321.929917] Starting subtest: hdr-1p-offscreen-pri-shrfb-draw-render [321.933867] Subtest hdr-1p-offscreen-pri-shrfb-draw-render: SKIP (0.000s) [322.010611] [050/156] (697s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-render) [322.103461] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-render [322.107543] Subtest fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s) [322.198832] [051/156] (696s left) kms_frontbuffer_tracking (fbc-2p-indfb-fliptrack-mmap-gtt) [322.290044] Starting subtest: fbc-2p-indfb-fliptrack-mmap-gtt [322.293989] Subtest fbc-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s) [322.398302] [052/156] (696s left) i915_pm_rps (reset) [322.457159] Starting subtest: reset [330.884559] Subtest reset: SUCCESS (8.426s) [330.995088] [053/156] (688s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-plflip-blt) [331.093798] Starting subtest: fbc-2p-scndscrn-indfb-plflip-blt [331.097923] Subtest fbc-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s) [331.197253] [054/156] (688s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-draw-render) [331.290891] Starting subtest: hdr-1p-primscrn-spr-indfb-draw-render [331.295037] Subtest hdr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s) [331.397810] [055/156] (687s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-indfb-draw-mmap-wc) [331.503272] Starting subtest: fbcpsrhdr-1p-primscrn-pri-indfb-draw-mmap-wc [331.507503] Subtest fbcpsrhdr-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s) [331.594956] [056/156] (687s left) kms_flip (bo-too-big) [331.684802] Starting subtest: bo-too-big [331.687080] Starting dynamic subtest: A-eDP1 [332.976062] Dynamic subtest A-eDP1: SUCCESS (1.293s) [332.986756] Starting dynamic subtest: B-eDP1 [334.309030] Dynamic subtest B-eDP1: SUCCESS (1.332s) [334.320664] Starting dynamic subtest: C-eDP1 [335.650640] Dynamic subtest C-eDP1: SUCCESS (1.340s) [335.661052] Starting dynamic subtest: D-eDP1 [337.080307] Dynamic subtest D-eDP1: SUCCESS (1.429s) [337.086217] Subtest bo-too-big: SUCCESS (5.405s) [338.282219] [057/156] (681s left) kms_cursor_legacy (2x-flip-vs-cursor-legacy) [338.386688] Starting subtest: 2x-flip-vs-cursor-legacy [338.391374] Subtest 2x-flip-vs-cursor-legacy: SKIP (0.001s) [338.533608] [058/156] (680s left) api_intel_allocator (default-alignment) [338.583757] Starting subtest: default-alignment [338.586614] Subtest default-alignment: SUCCESS (0.003s) [338.707706] [059/156] (680s left) gem_mmap_gtt (medium-copy) [338.760998] Subtest medium-copy: SKIP (0.000s) [338.830397] [060/156] (680s left) kms_plane_scaling (2x-scaler-multi-pipe) [338.922625] Starting subtest: 2x-scaler-multi-pipe [338.927177] Subtest 2x-scaler-multi-pipe: SKIP (0.001s) [339.022884] [061/156] (680s left) gem_lmem_swapping (heavy-verify-random-ccs) [339.064134] Subtest heavy-verify-random-ccs: SKIP (0.000s) [339.109125] [062/156] (680s left) syncobj_timeline (invalid-query-illegal-handle) [339.158227] Starting subtest: invalid-query-illegal-handle [339.160442] Subtest invalid-query-illegal-handle: SUCCESS (0.000s) [339.258879] [063/156] (680s left) kms_psr (fbc-psr2-primary-mmap-gtt) [339.355987] Starting subtest: fbc-psr2-primary-mmap-gtt [339.357818] Starting dynamic subtest: eDP-1 [339.361063] Dynamic subtest eDP-1: SKIP (0.000s) [339.364375] Subtest fbc-psr2-primary-mmap-gtt: SKIP (0.001s) [339.427568] [064/156] (680s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-wc) [339.524110] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-wc [339.527794] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s) [339.619755] [065/156] (680s left) gem_pxp (dmabuf-shared-protected-dst-is-context-refcounted) [339.682255] Starting subtest: dmabuf-shared-protected-dst-is-context-refcounted [339.816409] Subtest dmabuf-shared-protected-dst-is-context-refcounted: SUCCESS (0.134s) [339.944444] [066/156] (679s left) perf (invalid-remove-userspace-config) [340.033268] Starting subtest: invalid-remove-userspace-config [340.043963] Subtest invalid-remove-userspace-config: SUCCESS (0.011s) [340.086576] [067/156] (679s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-onoff) [340.188907] Starting subtest: psr-2p-scndscrn-spr-indfb-onoff [340.192527] Subtest psr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s) [340.282838] [068/156] (679s left) kms_flip (flip-vs-fences-interruptible) [340.368114] Starting subtest: flip-vs-fences-interruptible [340.371848] Subtest flip-vs-fences-interruptible: SKIP (0.000s) [340.478440] [069/156] (679s left) syncobj_timeline (single-wait-all-available-signaled) [340.538840] Starting subtest: single-wait-all-available-signaled [340.540832] Subtest single-wait-all-available-signaled: SUCCESS (0.000s) [340.623104] [070/156] (679s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu) [340.713487] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu [347.756842] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (7.046s) [347.870917] [071/156] (672s left) kms_atomic_transition (plane-all-transition-nonblocking-fencing) [347.960797] Starting subtest: plane-all-transition-nonblocking-fencing [347.962923] Starting dynamic subtest: pipe-A-eDP-1 [350.454241] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.495s) [350.762303] Starting dynamic subtest: pipe-B-eDP-1 [353.969046] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.217s) [354.279094] Subtest plane-all-transition-nonblocking-fencing: SUCCESS (6.318s) [355.114832] [072/156] (664s left) api_intel_bb (offset-control) [355.168383] Starting subtest: offset-control [355.172864] Subtest offset-control: SUCCESS (0.007s) [355.301618] [073/156] (664s left) kms_ccs (bad-aux-stride-4-tiled-mtl-mc-ccs) [355.407024] Starting subtest: bad-aux-stride-4-tiled-mtl-mc-ccs [355.408942] Starting dynamic subtest: pipe-A-eDP-1 [355.463809] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.057s) [355.473541] Starting dynamic subtest: pipe-B-eDP-1 [355.523144] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.058s) [355.534254] Starting dynamic subtest: pipe-C-eDP-1 [355.582184] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.058s) [355.592319] Starting dynamic subtest: pipe-D-eDP-1 [355.641046] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.057s) [355.643417] Subtest bad-aux-stride-4-tiled-mtl-mc-ccs: SUCCESS (0.236s) [357.955184] [074/156] (662s left) kms_chamelium_color (ctm-limited-range) [358.057081] Subtest ctm-limited-range: SKIP (0.000s) [358.105537] [075/156] (661s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions) [358.225968] Starting subtest: cursorA-vs-flipA-atomic-transitions [369.390786] Subtest cursorA-vs-flipA-atomic-transitions: SUCCESS (11.163s) [370.205547] [076/156] (649s left) syncobj_wait (multi-wait-all-submitted-signaled) [370.270777] Starting subtest: multi-wait-all-submitted-signaled [370.379775] Subtest multi-wait-all-submitted-signaled: SUCCESS (0.108s) [370.459272] [077/156] (649s left) syncobj_eventfd (invalid-bad-pad) [370.510840] Starting subtest: invalid-bad-pad [370.512911] Subtest invalid-bad-pad: SUCCESS (0.000s) [370.613770] [078/156] (649s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-cur-indfb-draw-render) [370.707533] Starting subtest: fbcpsrhdr-2p-primscrn-cur-indfb-draw-render [370.711995] Subtest fbcpsrhdr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s) [370.799127] [079/156] (649s left) kms_content_protection (atomic-hdcp14) [370.933918] Starting subtest: atomic-hdcp14 [370.938514] Subtest atomic-hdcp14: SKIP (0.000s) [371.071169] [080/156] (649s left) kms_chamelium_hpd (vga-hpd-for-each-pipe) [371.461609] Subtest vga-hpd-for-each-pipe: SKIP (0.000s) [372.328811] [081/156] (648s left) gem_media_vme [372.438656] [082/156] (647s left) perf (polling-parameterized) [372.527775] Starting subtest: polling-parameterized [392.549079] Subtest polling-parameterized: SUCCESS (20.014s) [392.597017] [083/156] (627s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc) [392.704003] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc [392.708022] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [392.802943] [084/156] (627s left) kms_force_connector_basic (force-edid) [392.871002] Starting subtest: force-edid [393.235826] Subtest force-edid: SUCCESS (0.367s) [393.395055] [085/156] (627s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-render) [393.492869] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-render [393.496890] Subtest psr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s) [393.595868] [086/156] (626s left) kms_plane_multiple (2x-tiling-yf) [393.690518] Starting subtest: 2x-tiling-yf [393.694309] Subtest 2x-tiling-yf: SKIP (0.000s) [393.766152] [087/156] (626s left) kms_color (ctm-signed) [393.864105] Starting subtest: ctm-signed [393.865906] Starting dynamic subtest: pipe-A-eDP-1 [396.026037] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.159s) [396.036054] Starting dynamic subtest: pipe-B-eDP-1 [398.818180] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.792s) [398.828493] Starting dynamic subtest: pipe-C-eDP-1 [401.587474] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.766s) [401.597133] Starting dynamic subtest: pipe-D-eDP-1 [404.393594] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.806s) [404.396131] Subtest ctm-signed: SUCCESS (10.527s) [405.285596] [088/156] (615s left) kms_psr (fbc-psr-sprite-plane-move) [405.384887] Starting subtest: fbc-psr-sprite-plane-move [405.387173] Starting dynamic subtest: eDP-1 [405.391551] Dynamic subtest eDP-1: SKIP (0.000s) [405.395992] Subtest fbc-psr-sprite-plane-move: SKIP (0.001s) [405.455471] [089/156] (615s left) kms_flip (busy-flip) [405.542035] Starting subtest: busy-flip [405.544237] Starting dynamic subtest: A-eDP1 [407.248167] Dynamic subtest A-eDP1: SUCCESS (1.707s) [407.259256] Starting dynamic subtest: B-eDP1 [408.956005] Dynamic subtest B-eDP1: SUCCESS (1.706s) [408.966311] Starting dynamic subtest: C-eDP1 [410.641292] Dynamic subtest C-eDP1: SUCCESS (1.684s) [410.652581] Starting dynamic subtest: D-eDP1 [412.338038] Dynamic subtest D-eDP1: SUCCESS (1.695s) [412.340348] Subtest busy-flip: SUCCESS (6.800s) [413.579241] [090/156] (607s left) gem_exec_params (rsvd2-dirt) [413.640763] Starting subtest: rsvd2-dirt [413.645097] Subtest rsvd2-dirt: SKIP (0.000s) [413.749493] [091/156] (607s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb101010-draw-mmap-wc) [413.839278] Starting subtest: fbcpsrhdr-rgb101010-draw-mmap-wc [413.843501] Subtest fbcpsrhdr-rgb101010-draw-mmap-wc: SKIP (0.000s) [413.950103] [092/156] (606s left) i915_pciid [414.112143] [093/156] (606s left) kms_invalid_mode (bad-vsync-start) [414.209769] Starting subtest: bad-vsync-start [414.211910] Starting dynamic subtest: pipe-A-eDP-1 [414.511541] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.302s) [414.523320] Starting dynamic subtest: pipe-B-eDP-1 [414.525216] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s) [414.534878] Starting dynamic subtest: pipe-C-eDP-1 [414.536987] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [414.546315] Starting dynamic subtest: pipe-D-eDP-1 [414.548536] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [414.550700] Subtest bad-vsync-start: SUCCESS (0.307s) [415.370666] [094/156] (605s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-indfb-draw-pwrite) [415.460368] Starting subtest: psrhdr-2p-primscrn-pri-indfb-draw-pwrite [415.464610] Subtest psrhdr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s) [415.564020] [095/156] (605s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt) [415.659504] Starting subtest: psrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt [415.663921] Subtest psrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [415.740888] [096/156] (605s left) sw_sync (sync_busy_fork_unixsocket) [415.776688] Starting subtest: sync_busy_fork_unixsocket [415.784657] Subtest sync_busy_fork_unixsocket: SUCCESS (0.010s) [415.839855] [097/156] (605s left) gem_sync (basic-all) [415.913365] Starting subtest: basic-all [419.880933] Subtest basic-all: SUCCESS (3.975s) [420.064281] [098/156] (600s left) kms_frontbuffer_tracking (fbchdr-rgb565-draw-blt) [420.172411] Starting subtest: fbchdr-rgb565-draw-blt [420.176811] Subtest fbchdr-rgb565-draw-blt: SKIP (0.000s) [420.268077] [099/156] (600s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-mmap-wc) [420.373919] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-mmap-wc [420.377800] Subtest hdr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s) [420.474781] [100/156] (600s left) gem_fenced_exec_thrash (no-spare-fences) [420.528430] Subtest no-spare-fences: SKIP (0.000s) [420.608601] [101/156] (600s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-blt) [420.698669] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-blt [420.703138] Subtest fbchdr-1p-primscrn-spr-indfb-draw-blt: SKIP (0.000s) [420.803172] [102/156] (600s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-indfb-draw-render) [420.898017] Starting subtest: hdr-2p-primscrn-pri-indfb-draw-render [420.902112] Subtest hdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s) [420.996011] [103/156] (600s left) kms_big_fb (4-tiled-32bpp-rotate-90) [421.097847] Starting subtest: 4-tiled-32bpp-rotate-90 [421.117530] Subtest 4-tiled-32bpp-rotate-90: SKIP (0.019s) [421.255899] [104/156] (600s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-pwrite) [421.356811] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-pwrite [421.360210] Subtest fbc-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s) [421.450555] [105/156] (599s left) gem_exec_schedule (preempt-other-chain) [421.515607] Starting subtest: preempt-other-chain [421.517213] Starting dynamic subtest: rcs0 [421.591409] Dynamic subtest rcs0: SUCCESS (0.079s) [421.602638] Starting dynamic subtest: bcs0 [421.669590] Dynamic subtest bcs0: SUCCESS (0.077s) [421.683659] Starting dynamic subtest: vcs0 [421.752785] Dynamic subtest vcs0: SUCCESS (0.083s) [421.762310] Starting dynamic subtest: vcs1 [421.832378] Dynamic subtest vcs1: SUCCESS (0.078s) [421.845810] Starting dynamic subtest: vecs0 [421.910456] Dynamic subtest vecs0: SUCCESS (0.077s) [421.925460] Starting dynamic subtest: ccs0 [421.997171] Dynamic subtest ccs0: SUCCESS (0.086s) [421.999204] Subtest preempt-other-chain: SUCCESS (0.486s) [422.989455] [106/156] (598s left) kms_plane_scaling (plane-downscale-factor-0-75-with-rotation) [423.099689] Starting subtest: plane-downscale-factor-0-75-with-rotation [423.102774] Starting dynamic subtest: pipe-A [423.340388] Dynamic subtest pipe-A: SUCCESS (0.242s) [423.351677] Starting dynamic subtest: pipe-B [424.649375] Dynamic subtest pipe-B: SUCCESS (1.307s) [424.658758] Starting dynamic subtest: pipe-C [425.925080] Dynamic subtest pipe-C: SUCCESS (1.275s) [425.936003] Starting dynamic subtest: pipe-D [427.247905] Dynamic subtest pipe-D: SUCCESS (1.322s) [427.250073] Subtest plane-downscale-factor-0-75-with-rotation: SUCCESS (4.151s) [428.475560] [107/156] (592s left) gem_partial_pwrite_pread (reads-snoop) [428.529488] Subtest reads-snoop: SKIP (0.000s) [428.583648] [108/156] (592s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-mmap-cpu) [428.682917] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-mmap-cpu [428.687041] Subtest fbchdr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [428.774538] [109/156] (592s left) sysfs_heartbeat_interval (idempotent) [428.831107] Starting subtest: idempotent [428.833310] Starting dynamic subtest: vecs0 [428.835563] Dynamic subtest vecs0: SUCCESS (0.001s) [428.845052] Starting dynamic subtest: ccs0 [428.846944] Dynamic subtest ccs0: SUCCESS (0.001s) [428.856615] Starting dynamic subtest: vcs0 [428.858843] Dynamic subtest vcs0: SUCCESS (0.000s) [428.868184] Starting dynamic subtest: bcs0 [428.870090] Dynamic subtest bcs0: SUCCESS (0.001s) [428.879020] Starting dynamic subtest: rcs0 [428.880895] Dynamic subtest rcs0: SUCCESS (0.000s) [428.889515] Starting dynamic subtest: vcs1 [428.891387] Dynamic subtest vcs1: SUCCESS (0.000s) [428.893319] Subtest idempotent: SUCCESS (0.010s) [428.932430] [110/156] (592s left) kms_plane_scaling (planes-upscale-factor-0-25) [429.016851] Starting subtest: planes-upscale-factor-0-25 [429.019010] Starting dynamic subtest: pipe-A [429.077527] Dynamic subtest pipe-A: SUCCESS (0.062s) [429.088324] Starting dynamic subtest: pipe-B [430.277786] Dynamic subtest pipe-B: SUCCESS (1.200s) [430.289702] Starting dynamic subtest: pipe-C [431.440416] Dynamic subtest pipe-C: SUCCESS (1.162s) [431.450803] Starting dynamic subtest: pipe-D [432.545988] Dynamic subtest pipe-D: SUCCESS (1.104s) [432.548342] Subtest planes-upscale-factor-0-25: SUCCESS (3.533s) [433.690497] [111/156] (587s left) kms_multipipe_modeset (basic-max-pipe-crc-check) [433.786697] Starting subtest: basic-max-pipe-crc-check [433.790938] Subtest basic-max-pipe-crc-check: SKIP (0.000s) [433.832837] [112/156] (587s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-draw-pwrite) [433.939923] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-draw-pwrite [433.944453] Subtest fbchdr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s) [434.070481] [113/156] (587s left) gem_exec_reloc (basic-gtt-cpu) [434.138037] Subtest basic-gtt-cpu: SKIP (0.000s) [434.209073] [114/156] (587s left) gem_fence_thrash (bo-write-verify-threaded-x) [434.240401] Starting subtest: bo-write-verify-threaded-x [434.263375] Subtest bo-write-verify-threaded-x: SKIP (0.019s) [434.331906] [115/156] (587s left) gem_exec_fence (syncobj-timeline-chain-engines) [434.886451] Starting subtest: syncobj-timeline-chain-engines [435.010737] Subtest syncobj-timeline-chain-engines: SUCCESS (0.126s) [435.997423] [116/156] (585s left) dmabuf_sync_file (export-before-signal) [436.048830] Starting subtest: export-before-signal [436.050865] Subtest export-before-signal: SUCCESS (0.001s) [436.110532] [117/156] (585s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-shrfb-msflip-blt) [436.200639] Starting subtest: fbcpsrhdr-2p-primscrn-shrfb-msflip-blt [436.204738] Subtest fbcpsrhdr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s) [436.300187] [118/156] (585s left) kms_hdr (static-toggle-dpms) [436.389540] Starting subtest: static-toggle-dpms [436.391960] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010 [436.396237] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SKIP (0.000s) [436.405810] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F [436.409839] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SKIP (0.000s) [436.413893] Subtest static-toggle-dpms: SKIP (0.003s) [436.500523] [119/156] (585s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt) [436.603422] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt [436.607983] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s) [436.714681] [120/156] (585s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-indfb-draw-mmap-cpu) [436.805316] Starting subtest: fbcpsrhdr-1p-offscreen-pri-indfb-draw-mmap-cpu [436.809323] Subtest fbcpsrhdr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [436.905162] [121/156] (585s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-blt) [436.998612] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-blt [437.002551] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s) [437.099200] [122/156] (584s left) gem_exec_schedule (timeslicing) [437.163885] Starting subtest: timeslicing [437.165800] Starting dynamic subtest: rcs0 [437.177424] Dynamic subtest rcs0: SUCCESS (0.022s) [437.187359] Starting dynamic subtest: bcs0 [437.197645] Dynamic subtest bcs0: SUCCESS (0.019s) [437.206603] Starting dynamic subtest: vcs0 [437.217801] Dynamic subtest vcs0: SUCCESS (0.020s) [437.227054] Starting dynamic subtest: vcs1 [437.237770] Dynamic subtest vcs1: SUCCESS (0.019s) [437.246838] Starting dynamic subtest: vecs0 [437.257719] Dynamic subtest vecs0: SUCCESS (0.019s) [437.266949] Starting dynamic subtest: ccs0 [437.279455] Dynamic subtest ccs0: SUCCESS (0.021s) [437.281431] Subtest timeslicing: SUCCESS (0.125s) [438.943490] [123/156] (583s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu) [439.049468] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu [439.053430] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [439.147898] [124/156] (583s left) gem_mmap_offset (bad-extensions) [439.195344] Starting subtest: bad-extensions [439.197292] Subtest bad-extensions: SUCCESS (0.000s) [439.288192] [125/156] (582s left) kms_psr (fbc-pr-basic) [439.370659] Starting subtest: fbc-pr-basic [439.374897] Subtest fbc-pr-basic: SKIP (0.000s) [439.442288] [126/156] (582s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-shrfb-plflip-blt) [439.545365] Starting subtest: fbchdr-2p-primscrn-shrfb-plflip-blt [439.549526] Subtest fbchdr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s) [439.647981] [127/156] (582s left) kms_pm_rpm (system-suspend-idle) [441.166197] Starting subtest: system-suspend-idle [460.224448] Subtest system-suspend-idle: SUCCESS (4.231s) [460.642489] [128/156] (561s left) kms_psr2_sf (fbc-psr2-overlay-primary-update-sf-dmg-area) [460.743103] Starting subtest: fbc-psr2-overlay-primary-update-sf-dmg-area [460.745331] Starting dynamic subtest: pipe-A-eDP-1 [460.879809] Dynamic subtest pipe-A-eDP-1: SKIP (0.133s) [460.890712] Starting dynamic subtest: pipe-B-eDP-1 [462.092329] Dynamic subtest pipe-B-eDP-1: SKIP (1.175s) [462.097261] Subtest fbc-psr2-overlay-primary-update-sf-dmg-area: SKIP (1.311s) [463.373049] [129/156] (559s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-render) [463.471605] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-render [470.313168] Subtest psr-1p-offscreen-pri-shrfb-draw-render: SUCCESS (6.747s) [470.464434] [130/156] (551s left) kms_cursor_crc (cursor-suspend) [470.593245] Starting subtest: cursor-suspend [470.595611] Starting dynamic subtest: pipe-A-eDP-1 [503.449188] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.116s) [503.460038] Starting dynamic subtest: pipe-D-eDP-1 [537.501329] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.802s) [537.504066] Subtest cursor-suspend: SUCCESS (6.921s) [538.393046] [131/156] (484s left) kms_plane_lowres (tiling-4) [538.498878] Starting subtest: tiling-4 [538.501443] Starting dynamic subtest: pipe-A-eDP-1 [538.506442] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [538.806692] Starting dynamic subtest: pipe-B-eDP-1 [538.812156] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [538.822850] Starting dynamic subtest: pipe-C-eDP-1 [538.828609] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [538.839147] Starting dynamic subtest: pipe-D-eDP-1 [538.844992] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [538.850939] Subtest tiling-4: SKIP (0.299s) [539.627991] [132/156] (482s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-spr-indfb-draw-render) [539.728088] Starting subtest: hdr-2p-scndscrn-spr-indfb-draw-render [539.733142] Subtest hdr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s) [539.824674] [133/156] (482s left) syncobj_timeline (etime-single-wait-all-for-submit-submitted) [539.884957] Starting subtest: etime-single-wait-all-for-submit-submitted [539.986313] Subtest etime-single-wait-all-for-submit-submitted: SUCCESS (0.099s) [540.070194] [134/156] (482s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs-cc) [540.160007] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs-cc [540.162369] Starting dynamic subtest: pipe-A-eDP-1 [556.209189] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.333s) [556.220261] Starting dynamic subtest: pipe-B-eDP-1 [573.195300] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.341s) [573.209100] Starting dynamic subtest: pipe-C-eDP-1 [590.183682] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.389s) [590.195022] Starting dynamic subtest: pipe-D-eDP-1 [607.153085] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.342s) [607.155957] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs-cc: SUCCESS (8.411s) [608.368421] [135/156] (414s left) kms_vblank (ts-continuation-modeset) [608.474064] Starting subtest: ts-continuation-modeset [608.476662] Starting dynamic subtest: pipe-A-eDP-1 [609.942974] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.426s) [609.958773] Starting dynamic subtest: pipe-D-eDP-1 [612.199930] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.202s) [612.203036] Subtest ts-continuation-modeset: SUCCESS (3.632s) [613.098973] [136/156] (409s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [613.189977] Starting subtest: addfb25-yf-tiled-legacy [613.192440] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [613.291499] [137/156] (409s left) kms_big_fb (x-tiled-8bpp-rotate-270) [613.391945] Starting subtest: x-tiled-8bpp-rotate-270 [613.413926] Subtest x-tiled-8bpp-rotate-270: SKIP (0.021s) [613.522844] [138/156] (409s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip) [613.614061] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip [613.618804] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s) [613.719144] [139/156] (409s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-gtt) [613.821719] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-gtt [613.826872] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s) [613.916830] [140/156] (408s left) kms_scaling_modes (scaling-mode-full-aspect) [614.000429] Starting subtest: scaling-mode-full-aspect [614.002669] Starting dynamic subtest: pipe-A-eDP-1 [614.117008] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.116s) [614.126880] Starting dynamic subtest: pipe-B-eDP-1 [615.318241] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.180s) [615.329401] Starting dynamic subtest: pipe-C-eDP-1 [616.514990] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.183s) [616.525566] Starting dynamic subtest: pipe-D-eDP-1 [617.722797] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.194s) [617.725518] Subtest scaling-mode-full-aspect: SUCCESS (3.678s) [618.873050] [141/156] (404s left) gem_eio (wait-1us) [619.101922] Starting subtest: wait-1us [619.451973] Subtest wait-1us: SUCCESS (0.347s) [619.571908] [142/156] (403s left) i915_pm_sseu (full-enable) [619.623896] Subtest full-enable: SKIP (0.000s) [619.736741] [143/156] (403s left) syncobj_basic (bad-pad-fd-to-handle) [619.784934] Starting subtest: bad-pad-fd-to-handle [619.787509] Subtest bad-pad-fd-to-handle: SUCCESS (0.000s) [619.889689] [144/156] (403s left) gem_flink_basic (basic) [619.943131] Starting subtest: basic [619.945737] Subtest basic: SUCCESS (0.000s) [620.058307] [145/156] (403s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-y-tiled) [620.117447] Starting subtest: yf-tiled-mc-ccs-to-vebox-y-tiled [620.121890] Subtest yf-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s) [620.296064] [146/156] (402s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-blt) [620.395929] Starting subtest: psr-1p-primscrn-cur-indfb-draw-blt [627.166007] Subtest psr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (6.745s) [627.302796] [147/156] (395s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-pwrite) [627.407903] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-pwrite [627.412997] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s) [627.497194] [148/156] (395s left) kms_big_fb (yf-tiled-32bpp-rotate-0) [627.593578] Starting subtest: yf-tiled-32bpp-rotate-0 [627.598938] Subtest yf-tiled-32bpp-rotate-0: SKIP (0.000s) [627.703119] [149/156] (395s left) drm_read (short-buffer-nonblock) [627.847860] Starting subtest: short-buffer-nonblock [627.850663] Subtest short-buffer-nonblock: SUCCESS (0.000s) [627.965001] [150/156] (395s left) kms_psr2_sf (fbc-pr-cursor-plane-update-sf) [628.060443] Starting subtest: fbc-pr-cursor-plane-update-sf [628.065425] Subtest fbc-pr-cursor-plane-update-sf: SKIP (0.000s) [628.129560] [151/156] (395s left) gem_mmap_gtt (basic-write) [628.180734] Subtest basic-write: SKIP (0.000s) [628.283293] [152/156] (395s left) kms_cursor_crc (cursor-rapid-movement-512x170) [628.593786] Starting subtest: cursor-rapid-movement-512x170 [628.597831] Subtest cursor-rapid-movement-512x170: SKIP (0.001s) [628.746540] [153/156] (394s left) gem_create (create-ext-cpu-access-sanity-check) [628.913413] Starting subtest: create-ext-cpu-access-sanity-check [628.919009] Subtest create-ext-cpu-access-sanity-check: SKIP (0.001s) [628.988842] [154/156] (394s left) kms_atomic_transition (modeset-transition-nonblocking) [629.085983] Starting subtest: modeset-transition-nonblocking [629.088987] Starting dynamic subtest: 1x-outputs [640.187404] Dynamic subtest 1x-outputs: SUCCESS (11.088s) [640.190037] Subtest modeset-transition-nonblocking: SUCCESS (11.090s) [641.029550] [155/156] (382s left) gem_bad_reloc (negative-reloc-lut) [641.095595] Subtest negative-reloc-lut: SKIP (0.000s) [641.164184] [156/156] (382s left) gem_exec_gttfill (engines) [641.225023] Starting subtest: engines [641.227927] Starting dynamic subtest: rcs0 [664.111654] Dynamic subtest rcs0: SUCCESS (22.880s) [664.123134] Starting dynamic subtest: bcs0 [687.387135] Dynamic subtest bcs0: SUCCESS (23.264s) [687.398086] Starting dynamic subtest: vcs0 [710.717922] Dynamic subtest vcs0: SUCCESS (23.324s) [710.733159] Starting dynamic subtest: vcs1 [733.983915] Dynamic subtest vcs1: SUCCESS (23.264s) [733.995137] Starting dynamic subtest: vecs0 [757.261889] Dynamic subtest vecs0: SUCCESS (23.271s) [757.272935] Starting dynamic subtest: ccs0 [780.584451] Dynamic subtest ccs0: SUCCESS (23.315s) [780.587141] Subtest engines: SUCCESS (139.329s) [780.786214] Closing watchdogs results: parsing output: 0/ for test: kms_flip results: parsing output: 1/ for test: gem_exec_whisper results: parsing output: 2/ for test: syncobj_timeline results: parsing output: 3/ for test: kms_pm_rpm results: parsing output: 4/ for test: sw_sync results: parsing output: 5/ for test: gem_ctx_persistence results: parsing output: 6/ for test: kms_frontbuffer_tracking results: parsing output: 7/ for test: kms_ccs results: parsing output: 8/ for test: kms_chamelium_frames results: parsing output: 9/ for test: kms_pipe_b_c_ivb results: parsing output: 10/ for test: kms_frontbuffer_tracking results: parsing output: 11/ for test: gem_softpin results: parsing output: 12/ for test: kms_cursor_crc results: parsing output: 13/ for test: kms_dp_linktrain_fallback results: parsing output: 14/ for test: gem_ctx_shared results: parsing output: 15/ for test: kms_cursor_legacy results: parsing output: 16/ for test: kms_big_fb results: parsing output: 17/ for test: syncobj_basic results: parsing output: 18/ for test: kms_flip results: parsing output: 19/ for test: gem_pxp results: parsing output: 20/ for test: kms_vrr results: parsing output: 21/ for test: kms_flip results: parsing output: 22/ for test: kms_fence_pin_leak results: parsing output: 23/ for test: kms_fb_coherency results: parsing output: 24/ for test: kms_ccs results: parsing output: 25/ for test: gen7_exec_parse results: parsing output: 26/ for test: syncobj_wait results: parsing output: 27/ for test: kms_getfb results: parsing output: 28/ for test: perf results: parsing output: 29/ for test: gem_exec_fence results: parsing output: 30/ for test: gem_exec_params results: parsing output: 31/ for test: kms_plane results: parsing output: 32/ for test: gem_ctx_persistence results: parsing output: 33/ for test: i915_drm_fdinfo results: parsing output: 34/ for test: kms_psr results: parsing output: 35/ for test: kms_ccs results: parsing output: 36/ for test: syncobj_timeline results: parsing output: 37/ for test: gem_exec_balancer results: parsing output: 38/ for test: kms_psr results: parsing output: 39/ for test: kms_flip_scaled_crc results: parsing output: 40/ for test: gem_exec_fence results: parsing output: 41/ for test: kms_frontbuffer_tracking results: parsing output: 42/ for test: gem_wait results: parsing output: 43/ for test: kms_frontbuffer_tracking results: parsing output: 44/ for test: kms_frontbuffer_tracking results: parsing output: 45/ for test: gem_readwrite results: parsing output: 46/ for test: kms_big_fb results: parsing output: 47/ for test: kms_universal_plane 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_frontbuffer_tracking results: parsing output: 51/ for test: i915_pm_rps results: parsing output: 52/ for test: kms_frontbuffer_tracking results: parsing output: 53/ for test: kms_frontbuffer_tracking results: parsing output: 54/ for test: kms_frontbuffer_tracking results: parsing output: 55/ for test: kms_flip results: parsing output: 56/ for test: kms_cursor_legacy results: parsing output: 57/ for test: api_intel_allocator results: parsing output: 58/ for test: gem_mmap_gtt results: parsing output: 59/ for test: kms_plane_scaling results: parsing output: 60/ for test: gem_lmem_swapping results: parsing output: 61/ for test: syncobj_timeline results: parsing output: 62/ for test: kms_psr results: parsing output: 63/ for test: kms_frontbuffer_tracking results: parsing output: 64/ for test: gem_pxp results: parsing output: 65/ for test: perf results: parsing output: 66/ for test: kms_frontbuffer_tracking results: parsing output: 67/ for test: kms_flip results: parsing output: 68/ for test: syncobj_timeline results: parsing output: 69/ for test: kms_frontbuffer_tracking results: parsing output: 70/ for test: kms_atomic_transition results: parsing output: 71/ for test: api_intel_bb results: parsing output: 72/ for test: kms_ccs results: parsing output: 73/ for test: kms_chamelium_color results: parsing output: 74/ for test: kms_cursor_legacy results: parsing output: 75/ for test: syncobj_wait results: parsing output: 76/ for test: syncobj_eventfd results: parsing output: 77/ for test: kms_frontbuffer_tracking results: parsing output: 78/ for test: kms_content_protection results: parsing output: 79/ for test: kms_chamelium_hpd results: parsing output: 80/ for test: gem_media_vme results: parsing output: 81/ for test: perf results: parsing output: 82/ for test: kms_frontbuffer_tracking results: parsing output: 83/ for test: kms_force_connector_basic results: parsing output: 84/ for test: kms_frontbuffer_tracking results: parsing output: 85/ for test: kms_plane_multiple results: parsing output: 86/ for test: kms_color results: parsing output: 87/ for test: kms_psr results: parsing output: 88/ for test: kms_flip results: parsing output: 89/ for test: gem_exec_params results: parsing output: 90/ for test: kms_frontbuffer_tracking results: parsing output: 91/ for test: i915_pciid results: parsing output: 92/ for test: kms_invalid_mode results: parsing output: 93/ for test: kms_frontbuffer_tracking results: parsing output: 94/ for test: kms_frontbuffer_tracking results: parsing output: 95/ for test: sw_sync results: parsing output: 96/ for test: gem_sync results: parsing output: 97/ for test: kms_frontbuffer_tracking results: parsing output: 98/ for test: kms_frontbuffer_tracking results: parsing output: 99/ for test: gem_fenced_exec_thrash results: parsing output: 100/ for test: kms_frontbuffer_tracking results: parsing output: 101/ for test: kms_frontbuffer_tracking results: parsing output: 102/ for test: kms_big_fb results: parsing output: 103/ for test: kms_frontbuffer_tracking results: parsing output: 104/ for test: gem_exec_schedule results: parsing output: 105/ for test: kms_plane_scaling results: parsing output: 106/ for test: gem_partial_pwrite_pread results: parsing output: 107/ for test: kms_frontbuffer_tracking results: parsing output: 108/ for test: sysfs_heartbeat_interval results: parsing output: 109/ for test: kms_plane_scaling results: parsing output: 110/ for test: kms_multipipe_modeset results: parsing output: 111/ for test: kms_frontbuffer_tracking results: parsing output: 112/ for test: gem_exec_reloc results: parsing output: 113/ for test: gem_fence_thrash results: parsing output: 114/ for test: gem_exec_fence results: parsing output: 115/ for test: dmabuf_sync_file results: parsing output: 116/ for test: kms_frontbuffer_tracking results: parsing output: 117/ for test: kms_hdr results: parsing output: 118/ for test: kms_frontbuffer_tracking results: parsing output: 119/ for test: kms_frontbuffer_tracking results: parsing output: 120/ for test: kms_frontbuffer_tracking results: parsing output: 121/ for test: gem_exec_schedule results: parsing output: 122/ for test: kms_frontbuffer_tracking results: parsing output: 123/ for test: gem_mmap_offset results: parsing output: 124/ for test: kms_psr results: parsing output: 125/ for test: kms_frontbuffer_tracking results: parsing output: 126/ for test: kms_pm_rpm results: parsing output: 127/ for test: kms_psr2_sf results: parsing output: 128/ for test: kms_frontbuffer_tracking results: parsing output: 129/ for test: kms_cursor_crc results: parsing output: 130/ for test: kms_plane_lowres results: parsing output: 131/ for test: kms_frontbuffer_tracking results: parsing output: 132/ for test: syncobj_timeline results: parsing output: 133/ for test: kms_ccs results: parsing output: 134/ for test: kms_vblank results: parsing output: 135/ for test: kms_addfb_basic results: parsing output: 136/ for test: kms_big_fb results: parsing output: 137/ for test: kms_big_fb results: parsing output: 138/ for test: kms_frontbuffer_tracking results: parsing output: 139/ for test: kms_scaling_modes results: parsing output: 140/ for test: gem_eio results: parsing output: 141/ for test: i915_pm_sseu results: parsing output: 142/ for test: syncobj_basic results: parsing output: 143/ for test: gem_flink_basic results: parsing output: 144/ for test: gem_render_copy results: parsing output: 145/ for test: kms_frontbuffer_tracking results: parsing output: 146/ for test: kms_frontbuffer_tracking results: parsing output: 147/ for test: kms_big_fb results: parsing output: 148/ for test: drm_read results: parsing output: 149/ for test: kms_psr2_sf results: parsing output: 150/ for test: gem_mmap_gtt results: parsing output: 151/ for test: kms_cursor_crc results: parsing output: 152/ for test: gem_create results: parsing output: 153/ for test: kms_atomic_transition results: parsing output: 154/ for test: gem_bad_reloc results: parsing output: 155/ for test: gem_exec_gttfill Done.