[63.781948] Initializing watchdogs [63.781989] /dev/watchdog0 [63.795670] [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] [63.799985] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [63.802352] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [63.806315] [001/131] (960s left) kms_color (ctm-0-50) [63.923824] Starting subtest: ctm-0-50 [63.926089] Starting dynamic subtest: pipe-A-eDP-1 [67.501904] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.569s) [67.514299] Starting dynamic subtest: pipe-B-eDP-1 [71.812959] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.303s) [71.821658] Starting dynamic subtest: pipe-C-eDP-1 [75.846547] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.028s) [75.854530] Starting dynamic subtest: pipe-D-eDP-1 [79.844971] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.995s) [79.847368] Subtest ctm-0-50: SUCCESS (15.896s) [80.676369] [002/131] (943s left) kms_prop_blob (invalid-get-prop-any) [80.748239] Starting subtest: invalid-get-prop-any [80.750732] Subtest invalid-get-prop-any: SUCCESS (0.000s) [80.849177] [003/131] (943s left) kms_invalid_mode (overflow-vrefresh) [80.944704] Starting subtest: overflow-vrefresh [80.947093] Starting dynamic subtest: pipe-A-eDP-1 [81.231591] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.288s) [81.241809] Starting dynamic subtest: pipe-B-eDP-1 [81.243806] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s) [81.250688] Starting dynamic subtest: pipe-C-eDP-1 [81.252868] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [81.260220] Starting dynamic subtest: pipe-D-eDP-1 [81.262434] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [81.264816] Subtest overflow-vrefresh: SUCCESS (0.292s) [82.114584] [004/131] (942s left) gem_lmem_swapping (smem-oom) [82.159063] Subtest smem-oom: SKIP (0.000s) [82.200763] [005/131] (942s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-gtt) [82.296180] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-gtt [82.300594] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s) [82.410238] [006/131] (942s left) gem_softpin (evict-snoop-interruptible) [82.471715] Starting subtest: evict-snoop-interruptible [82.475995] Subtest evict-snoop-interruptible: SKIP (0.000s) [82.584024] [007/131] (941s left) kms_plane (pixel-format-yf-tiled-modifier) [82.668998] Starting subtest: pixel-format-yf-tiled-modifier [82.673345] Subtest pixel-format-yf-tiled-modifier: SKIP (0.000s) [82.739807] [008/131] (941s left) gem_flink_basic (flink-lifetime) [82.794714] Starting subtest: flink-lifetime [82.796630] Subtest flink-lifetime: SUCCESS (0.003s) [82.910617] [009/131] (941s left) kms_cursor_crc (cursor-offscreen-32x10) [83.069673] Starting subtest: cursor-offscreen-32x10 [83.118170] Subtest cursor-offscreen-32x10: SKIP (0.045s) [83.367252] [010/131] (941s left) kms_psr (fbc-psr-sprite-mmap-cpu) [83.471645] Starting subtest: fbc-psr-sprite-mmap-cpu [83.473523] Starting dynamic subtest: eDP-1 [83.477307] Dynamic subtest eDP-1: SKIP (0.000s) [83.480944] Subtest fbc-psr-sprite-mmap-cpu: SKIP (0.000s) [83.538852] [011/131] (941s left) prime_mmap (test_errors) [83.587310] Starting subtest: test_errors [83.589207] Starting dynamic subtest: test_errors-smem [83.681181] Dynamic subtest test_errors-smem: SUCCESS (0.094s) [83.683464] Subtest test_errors: SUCCESS (0.095s) [83.776510] [012/131] (940s left) perf (unprivileged-single-ctx-counters) [83.899067] Starting subtest: unprivileged-single-ctx-counters [83.902742] Subtest unprivileged-single-ctx-counters: SKIP (0.000s) [83.942371] [013/131] (940s left) kms_content_protection (dp-mst-type-0-hdcp14) [84.083322] Starting subtest: dp-mst-type-0-hdcp14 [84.087311] Subtest dp-mst-type-0-hdcp14: SKIP (0.000s) [84.202303] [014/131] (940s left) kms_addfb_basic (unused-offsets) [84.263242] Starting subtest: unused-offsets [84.264968] Subtest unused-offsets: SUCCESS (0.000s) [84.422873] [015/131] (940s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen) [84.515620] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-fullscreen [91.079637] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (6.564s) [91.223262] [016/131] (933s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt) [91.319426] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt [98.582537] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.263s) [98.704337] [017/131] (926s left) kms_big_fb (y-tiled-8bpp-rotate-0) [98.800098] Starting subtest: y-tiled-8bpp-rotate-0 [98.804225] Subtest y-tiled-8bpp-rotate-0: SKIP (0.000s) [98.913948] [018/131] (926s left) gem_close_race (multigpu-basic-threads) [98.983962] Starting subtest: multigpu-basic-threads [98.988084] Subtest multigpu-basic-threads: SKIP (0.000s) [99.197966] [019/131] (925s left) i915_query (query-topology-coherent-slice-mask) [99.253141] Starting subtest: query-topology-coherent-slice-mask [99.258061] Subtest query-topology-coherent-slice-mask: SKIP (0.000s) [99.339461] [020/131] (925s left) gem_render_copy (y-tiled-to-vebox-x-tiled) [99.391475] Starting subtest: y-tiled-to-vebox-x-tiled [99.395935] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s) [99.572481] [021/131] (925s left) kms_cursor_crc (cursor-rapid-movement-32x10) [99.734291] Starting subtest: cursor-rapid-movement-32x10 [99.781715] Subtest cursor-rapid-movement-32x10: SKIP (0.042s) [100.043848] [022/131] (925s left) kms_properties (invalid-properties-legacy) [100.137740] Starting subtest: invalid-properties-legacy [100.238058] Subtest invalid-properties-legacy: SUCCESS (0.101s) [100.320377] [023/131] (924s left) kms_psr (pr-sprite-plane-move) [100.418395] Starting subtest: pr-sprite-plane-move [100.422822] Subtest pr-sprite-plane-move: SKIP (0.000s) [100.489094] [024/131] (924s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-render) [100.598181] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-render [104.273799] Subtest fbc-1p-primscrn-cur-indfb-draw-render: SUCCESS (3.677s) [105.469713] [025/131] (919s left) syncobj_timeline (etime-single-wait-for-submit-available-unsubmitted) [105.530072] Starting subtest: etime-single-wait-for-submit-available-unsubmitted [105.630766] Subtest etime-single-wait-for-submit-available-unsubmitted: SUCCESS (0.102s) [105.702869] [026/131] (919s left) kms_big_fb (linear-16bpp-rotate-0) [105.802225] Starting subtest: linear-16bpp-rotate-0 [107.258310] Subtest linear-16bpp-rotate-0: SUCCESS (1.457s) [107.387327] [027/131] (917s left) gem_pxp (protected-raw-src-copy-not-readible) [107.508375] Starting subtest: protected-raw-src-copy-not-readible [107.567377] Subtest protected-raw-src-copy-not-readible: SUCCESS (0.061s) [107.674595] [028/131] (917s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt) [107.763754] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt [107.767171] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [107.854501] [029/131] (917s left) gem_exec_schedule (reorder-wide) [108.029667] Subtest reorder-wide: SKIP (0.000s) [108.216477] [030/131] (917s left) gem_exec_fence (nb-await) [108.395644] Starting subtest: nb-await [108.398088] Starting dynamic subtest: rcs0 [108.463800] Dynamic subtest rcs0: SUCCESS (0.067s) [108.473959] Starting dynamic subtest: bcs0 [108.521950] Dynamic subtest bcs0: SUCCESS (0.057s) [108.531773] Starting dynamic subtest: vcs0 [108.582261] Dynamic subtest vcs0: SUCCESS (0.058s) [108.591759] Starting dynamic subtest: vcs1 [108.640745] Dynamic subtest vcs1: SUCCESS (0.057s) [108.649596] Starting dynamic subtest: vecs0 [108.700054] Dynamic subtest vecs0: SUCCESS (0.058s) [108.710135] Starting dynamic subtest: ccs0 [108.758658] Dynamic subtest ccs0: SUCCESS (0.057s) [108.761307] Subtest nb-await: SUCCESS (0.363s) [111.009940] [FACT gem_exec_fence (nb-await)] new: kernel.kmod_is_loaded.vgem: true [111.013130] [031/131] (914s left) kms_plane_multiple (2x-tiling-4) [111.099636] Starting subtest: 2x-tiling-4 [111.103602] Subtest 2x-tiling-4: SKIP (0.000s) [111.169497] [032/131] (914s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions-varying-size) [111.314282] Starting subtest: cursorA-vs-flipA-atomic-transitions-varying-size [122.504855] Subtest cursorA-vs-flipA-atomic-transitions-varying-size: SUCCESS (11.188s) [123.300071] [033/131] (902s left) gem_ctx_shared (disjoint-timelines) [123.359978] Starting subtest: disjoint-timelines [123.377527] Subtest disjoint-timelines: SUCCESS (0.017s) [123.605121] [034/131] (901s left) perf (enable-disable) [123.696026] Starting subtest: enable-disable [123.698572] Starting dynamic subtest: 0-rcs0 [125.952866] Dynamic subtest 0-rcs0: SUCCESS (2.255s) [125.960921] Starting dynamic subtest: 1-vcs1 [130.377306] Dynamic subtest 1-vcs1: SUCCESS (4.423s) [130.379787] Subtest enable-disable: SUCCESS (6.680s) [130.419737] [035/131] (895s left) gem_tiled_partial_pwrite_pread (reads) [130.478192] Subtest reads: SKIP (0.000s) [130.535133] [036/131] (895s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip) [130.636138] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip [131.636380] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (1.001s) [131.773525] [037/131] (893s left) kms_plane_scaling (plane-downscale-factor-0-75-with-modifiers) [131.874513] Starting subtest: plane-downscale-factor-0-75-with-modifiers [131.876375] Starting dynamic subtest: pipe-A [132.258576] Dynamic subtest pipe-A: SUCCESS (0.386s) [132.265710] Starting dynamic subtest: pipe-B [133.703080] Dynamic subtest pipe-B: SUCCESS (1.442s) [133.711481] Starting dynamic subtest: pipe-C [135.128157] Dynamic subtest pipe-C: SUCCESS (1.424s) [135.136515] Starting dynamic subtest: pipe-D [136.603113] Dynamic subtest pipe-D: SUCCESS (1.474s) [136.605514] Subtest plane-downscale-factor-0-75-with-modifiers: SUCCESS (4.730s) [137.736179] [038/131] (888s left) gem_watchdog (default-virtual) [137.808429] Starting subtest: default-virtual [144.082576] Subtest default-virtual: SUCCESS (6.287s) [144.177102] [039/131] (881s left) kms_ccs (missing-ccs-buffer-yf-tiled-ccs) [144.283246] Starting subtest: missing-ccs-buffer-yf-tiled-ccs [144.285492] Starting dynamic subtest: pipe-A-eDP-1 [144.307743] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [144.316798] Starting dynamic subtest: pipe-B-eDP-1 [144.337684] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [144.344932] Starting dynamic subtest: pipe-C-eDP-1 [144.366298] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [144.373229] Starting dynamic subtest: pipe-D-eDP-1 [144.394714] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [144.399041] Subtest missing-ccs-buffer-yf-tiled-ccs: SKIP (0.004s) [146.596481] [040/131] (879s left) kms_flip (modeset-vs-vblank-race-interruptible) [146.708158] Starting subtest: modeset-vs-vblank-race-interruptible [146.710409] Starting dynamic subtest: A-eDP1 [151.386468] Dynamic subtest A-eDP1: SUCCESS (4.676s) [151.399647] Starting dynamic subtest: B-eDP1 [156.006768] Dynamic subtest B-eDP1: SUCCESS (4.617s) [156.016270] Starting dynamic subtest: C-eDP1 [160.615217] Dynamic subtest C-eDP1: SUCCESS (4.606s) [160.626092] Starting dynamic subtest: D-eDP1 [165.153435] Dynamic subtest D-eDP1: SUCCESS (4.535s) [165.158424] Subtest modeset-vs-vblank-race-interruptible: SUCCESS (18.449s) [166.318333] [041/131] (859s left) sysfs_preempt_timeout (idempotent) [166.379531] Starting subtest: idempotent [166.381815] Starting dynamic subtest: vecs0 [166.384054] Dynamic subtest vecs0: SUCCESS (0.000s) [166.392825] Starting dynamic subtest: ccs0 [166.395035] Dynamic subtest ccs0: SUCCESS (0.000s) [166.401765] Starting dynamic subtest: vcs0 [166.403666] Dynamic subtest vcs0: SUCCESS (0.000s) [166.410553] Starting dynamic subtest: bcs0 [166.412415] Dynamic subtest bcs0: SUCCESS (0.000s) [166.419522] Starting dynamic subtest: rcs0 [166.421752] Dynamic subtest rcs0: SUCCESS (0.000s) [166.428624] Starting dynamic subtest: vcs1 [166.430808] Dynamic subtest vcs1: SUCCESS (0.000s) [166.432976] Subtest idempotent: SUCCESS (0.007s) [166.477887] [042/131] (859s left) gem_eio (in-flight-internal-immediate) [166.656918] Starting subtest: in-flight-internal-immediate [167.954831] Subtest in-flight-internal-immediate: SUCCESS (1.296s) [168.095469] [043/131] (857s left) api_intel_bb (object-reloc-purge-cache) [168.145873] Starting subtest: object-reloc-purge-cache [168.149884] Subtest object-reloc-purge-cache: SKIP (0.003s) [168.265371] [044/131] (857s left) kms_big_fb (4-tiled-16bpp-rotate-90) [168.359816] Starting subtest: 4-tiled-16bpp-rotate-90 [168.379309] Subtest 4-tiled-16bpp-rotate-90: SKIP (0.018s) [168.491645] [045/131] (857s left) kms_atomic_transition (plane-primary-toggle-with-vblank-wait) [168.588032] Starting subtest: plane-primary-toggle-with-vblank-wait [168.590249] Starting dynamic subtest: pipe-A-eDP-1 [168.760678] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.174s) [169.069550] Starting dynamic subtest: pipe-B-eDP-1 [169.930419] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.869s) [170.229829] Subtest plane-primary-toggle-with-vblank-wait: SUCCESS (1.643s) [171.084992] [046/131] (854s left) kms_hdr (invalid-metadata-sizes) [171.177359] Starting subtest: invalid-metadata-sizes [171.183639] Subtest invalid-metadata-sizes: SKIP (0.000s) [171.277878] [047/131] (854s left) kms_pm_dc (dc5-retention-flops) [172.391972] Starting subtest: dc5-retention-flops [172.396539] Subtest dc5-retention-flops: SKIP (0.000s) [172.461693] [048/131] (853s left) core_setmaster (master-drop-set-shared-fd) [172.552268] Starting subtest: master-drop-set-shared-fd [172.571478] Subtest master-drop-set-shared-fd: SUCCESS (0.021s) [172.606669] [049/131] (853s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions-varying-size) [172.723990] Starting subtest: short-flip-after-cursor-atomic-transitions-varying-size [173.691246] Subtest short-flip-after-cursor-atomic-transitions-varying-size: SUCCESS (0.968s) [174.526219] [050/131] (851s left) kms_frontbuffer_tracking (psr-modesetfrombusy) [174.629592] Starting subtest: psr-modesetfrombusy [180.787959] Subtest psr-modesetfrombusy: SUCCESS (6.162s) [180.913031] [051/131] (845s left) kms_draw_crc (draw-method-render) [181.014075] Starting subtest: draw-method-render [181.015873] Starting dynamic subtest: xrgb8888-untiled [181.267817] Dynamic subtest xrgb8888-untiled: SUCCESS (0.254s) [181.275982] Starting dynamic subtest: rgb565-untiled [181.478782] Dynamic subtest rgb565-untiled: SUCCESS (0.210s) [181.490610] Starting dynamic subtest: xrgb2101010-untiled [181.700967] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.221s) [181.708762] Starting dynamic subtest: xbgr16161616f-untiled [181.945659] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.244s) [181.955652] Starting dynamic subtest: xrgb8888-xtiled [182.090268] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.143s) [182.099975] Starting dynamic subtest: rgb565-xtiled [182.246060] Dynamic subtest rgb565-xtiled: SUCCESS (0.153s) [182.256450] Starting dynamic subtest: xrgb2101010-xtiled [182.368287] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.121s) [182.376366] Starting dynamic subtest: xbgr16161616f-xtiled [182.501080] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.132s) [182.508576] Starting dynamic subtest: xrgb8888-4tiled [182.623277] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.121s) [182.631367] Starting dynamic subtest: rgb565-4tiled [182.745770] Dynamic subtest rgb565-4tiled: SUCCESS (0.122s) [182.755663] Starting dynamic subtest: xrgb2101010-4tiled [182.868326] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.120s) [182.884474] Starting dynamic subtest: xbgr16161616f-4tiled [183.001168] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.130s) [183.002960] Subtest draw-method-render: SUCCESS (1.989s) [183.123875] [052/131] (843s left) gem_exec_params (larger-than-life-batch) [183.174730] Starting subtest: larger-than-life-batch [185.564208] Subtest larger-than-life-batch: SUCCESS (2.388s) [185.994485] [053/131] (840s left) kms_psr (fbc-pr-cursor-mmap-gtt) [186.134885] Starting subtest: fbc-pr-cursor-mmap-gtt [186.139228] Subtest fbc-pr-cursor-mmap-gtt: SKIP (0.000s) [186.205606] [054/131] (840s left) kms_lease (lease-again) [186.293085] Starting subtest: lease-again [186.295323] Starting dynamic subtest: pipe-A-eDP-1 [186.297440] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.002s) [186.309324] Starting dynamic subtest: pipe-B-eDP-1 [186.311702] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s) [186.322496] Starting dynamic subtest: pipe-C-eDP-1 [186.324602] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s) [186.332550] Starting dynamic subtest: pipe-D-eDP-1 [186.334614] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s) [186.336656] Subtest lease-again: SUCCESS (0.012s) [186.397938] [055/131] (840s left) gem_ctx_persistence (hostile) [186.454097] Starting subtest: hostile [196.591922] Subtest hostile: SUCCESS (10.132s) [196.715253] [056/131] (829s left) kms_big_fb (yf-tiled-8bpp-rotate-180) [196.829516] Starting subtest: yf-tiled-8bpp-rotate-180 [196.833878] Subtest yf-tiled-8bpp-rotate-180: SKIP (0.000s) [196.932359] [057/131] (829s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-blt) [197.027580] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-blt [197.031685] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s) [197.132413] [058/131] (829s left) syncobj_timeline (wait-all-snapshot) [197.194364] Starting subtest: wait-all-snapshot [197.276738] Subtest wait-all-snapshot: SUCCESS (0.082s) [197.353462] [059/131] (829s left) gem_exec_schedule (independent) [197.420607] Starting subtest: independent [197.422600] Starting dynamic subtest: rcs0 [198.933453] Dynamic subtest rcs0: SUCCESS (1.523s) [198.946581] Starting dynamic subtest: vcs0 [200.928461] Dynamic subtest vcs0: SUCCESS (1.992s) [200.944581] Starting dynamic subtest: vcs1 [202.911926] Dynamic subtest vcs1: SUCCESS (1.980s) [202.927091] Starting dynamic subtest: vecs0 [204.893836] Dynamic subtest vecs0: SUCCESS (1.979s) [204.904408] Starting dynamic subtest: ccs0 [206.932866] Dynamic subtest ccs0: SUCCESS (2.036s) [206.935857] Subtest independent: SUCCESS (9.523s) [207.244870] [060/131] (819s left) kms_flip (wf_vblank-ts-check) [207.343051] Starting subtest: wf_vblank-ts-check [207.345400] Starting dynamic subtest: A-eDP1 [216.325760] Dynamic subtest A-eDP1: SUCCESS (8.984s) [216.338240] Starting dynamic subtest: B-eDP1 [225.260526] Dynamic subtest B-eDP1: SUCCESS (8.933s) [225.270203] Starting dynamic subtest: C-eDP1 [234.179932] Dynamic subtest C-eDP1: SUCCESS (8.917s) [234.187962] Starting dynamic subtest: D-eDP1 [243.114049] Dynamic subtest D-eDP1: SUCCESS (8.932s) [243.118743] Subtest wf_vblank-ts-check: SUCCESS (35.777s) [244.334369] [061/131] (782s left) gem_spin_batch (spin-all) [244.411502] Starting subtest: spin-all [244.428063] Subtest spin-all: SUCCESS (0.015s) [245.961040] [062/131] (780s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-factor-0-25) [246.053332] Starting subtest: planes-downscale-factor-0-5-upscale-factor-0-25 [246.055681] Starting dynamic subtest: pipe-A [246.078603] Dynamic subtest pipe-A: SKIP (0.018s) [246.087220] Starting dynamic subtest: pipe-B [246.112323] Dynamic subtest pipe-B: SKIP (0.034s) [246.120401] Starting dynamic subtest: pipe-C [246.142735] Dynamic subtest pipe-C: SKIP (0.031s) [246.150902] Starting dynamic subtest: pipe-D [246.175681] Dynamic subtest pipe-D: SKIP (0.031s) [246.179810] Subtest planes-downscale-factor-0-5-upscale-factor-0-25: SKIP (0.116s) [246.280700] [063/131] (780s left) i915_pm_rpm (debugfs-forcewake-user) [248.007558] Starting subtest: debugfs-forcewake-user [259.396334] Subtest debugfs-forcewake-user: SUCCESS (11.388s) [259.775671] [064/131] (767s left) gem_mmap_offset (bad-extensions) [259.831040] Starting subtest: bad-extensions [259.833360] Subtest bad-extensions: SUCCESS (0.000s) [259.914667] [065/131] (766s left) gem_exec_flush (basic-uc-prw-default) [259.989448] Starting subtest: basic-uc-prw-default [265.450376] Subtest basic-uc-prw-default: SUCCESS (5.463s) [265.660107] [066/131] (761s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted) [265.716445] Starting subtest: etime-multi-wait-all-available-unsubmitted [265.825088] Subtest etime-multi-wait-all-available-unsubmitted: SUCCESS (0.108s) [265.919388] [067/131] (761s left) kms_prime (basic-modeset-hybrid) [266.005915] Subtest basic-modeset-hybrid: SKIP (0.000s) [266.095550] [068/131] (760s left) prime_self_import (basic-llseek-size) [266.134110] Starting subtest: basic-llseek-size [266.152446] Subtest basic-llseek-size: SUCCESS (0.020s) [266.248223] [069/131] (760s left) kms_force_connector_basic (prune-stale-modes) [266.322021] Starting subtest: prune-stale-modes [266.767664] Subtest prune-stale-modes: SUCCESS (0.445s) [266.915143] [070/131] (760s left) gem_exec_params (cliprects_ptr-dirt) [266.976536] Starting subtest: cliprects_ptr-dirt [266.978214] Subtest cliprects_ptr-dirt: SUCCESS (0.000s) [267.091812] [071/131] (760s left) gem_exec_big (single) [267.157642] Starting subtest: single [270.462838] Subtest single: FAIL (3.246s) [270.510878] [072/131] (756s left) gem_exec_reloc (basic-gtt-cpu-noreloc) [270.597958] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s) [270.691916] [073/131] (756s left) gem_pwrite (basic-random) [270.747911] Subtest basic-random: SKIP (0.000s) [270.818346] [074/131] (756s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-downscaling) [270.926019] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-downscaling [270.930358] Subtest flip-64bpp-yftile-to-32bpp-yftile-downscaling: SKIP (0.000s) [270.998399] [075/131] (756s left) gem_partial_pwrite_pread (write-display) [271.058773] Subtest write-display: SKIP (0.000s) [271.135801] [076/131] (756s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-plflip-blt) [271.229090] Starting subtest: psr-2p-primscrn-indfb-plflip-blt [271.233294] Subtest psr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s) [271.311885] [077/131] (756s left) kms_chamelium_color (ctm-blue-to-red) [271.406076] Subtest ctm-blue-to-red: SKIP (0.000s) [271.453209] [078/131] (755s left) i915_drm_fdinfo (virtual-busy) [271.493741] Starting subtest: virtual-busy [271.497369] Subtest virtual-busy: SKIP (0.000s) [271.563293] [079/131] (755s left) perf_pmu (render-node-busy-idle) [271.649952] Starting subtest: render-node-busy-idle [271.651902] Starting dynamic subtest: rcs0 [272.335770] Dynamic subtest rcs0: SUCCESS (0.687s) [272.347102] Starting dynamic subtest: bcs0 [272.998838] Dynamic subtest bcs0: SUCCESS (0.661s) [273.011072] Starting dynamic subtest: vcs0 [274.900978] Dynamic subtest vcs0: SUCCESS (1.898s) [274.913160] Starting dynamic subtest: vcs1 [276.884795] Dynamic subtest vcs1: SUCCESS (1.981s) [276.897825] Starting dynamic subtest: vecs0 [278.934396] Dynamic subtest vecs0: SUCCESS (2.047s) [278.945851] Starting dynamic subtest: ccs0 [279.598291] Dynamic subtest ccs0: SUCCESS (0.663s) [279.600727] Subtest render-node-busy-idle: SUCCESS (7.952s) [279.690242] [080/131] (747s left) kms_pipe_b_c_ivb (pipe-b-dpms-off-modeset-pipe-c) [279.753339] Subtest pipe-B-dpms-off-modeset-pipe-C: SKIP (0.000s) [279.811687] [081/131] (747s left) kms_ccs (bad-pixel-format-4-tiled-dg2-mc-ccs) [279.909814] Starting subtest: bad-pixel-format-4-tiled-dg2-mc-ccs [279.911980] Starting dynamic subtest: pipe-A-eDP-1 [279.933502] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [279.942770] Starting dynamic subtest: pipe-B-eDP-1 [279.964148] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [279.972646] Starting dynamic subtest: pipe-C-eDP-1 [279.993592] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [280.002060] Starting dynamic subtest: pipe-D-eDP-1 [280.024154] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [280.028168] Subtest bad-pixel-format-4-tiled-dg2-mc-ccs: SKIP (0.004s) [282.191642] [082/131] (745s left) kms_cursor_edge_walk (64x64-top-edge) [282.282280] Starting subtest: 64x64-top-edge [282.284584] Starting dynamic subtest: pipe-A-eDP-1 [285.730340] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.449s) [285.739779] Starting dynamic subtest: pipe-D-eDP-1 [288.903411] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.169s) [288.905587] Subtest 64x64-top-edge: SUCCESS (6.621s) [289.715926] [083/131] (737s left) kms_vblank (ts-continuation-dpms-suspend) [289.811048] Starting subtest: ts-continuation-dpms-suspend [289.813138] Starting dynamic subtest: pipe-A-eDP-1 [306.555444] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.034s) [306.571525] Starting dynamic subtest: pipe-D-eDP-1 [324.568554] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.474s) [324.571040] Subtest ts-continuation-dpms-suspend: SUCCESS (4.511s) [325.417526] [084/131] (702s left) gem_exec_parallel (contexts) [325.491517] Starting subtest: contexts [325.494044] Starting dynamic subtest: rcs0 [328.560023] Dynamic subtest rcs0: SUCCESS (2.983s) [328.572896] Starting dynamic subtest: bcs0 [332.378472] Dynamic subtest bcs0: SUCCESS (3.749s) [332.391264] Starting dynamic subtest: vcs0 [336.521002] Dynamic subtest vcs0: SUCCESS (4.096s) [336.530712] Starting dynamic subtest: vcs1 [340.549797] Dynamic subtest vcs1: SUCCESS (3.999s) [340.561627] Starting dynamic subtest: vecs0 [344.527429] Dynamic subtest vecs0: SUCCESS (3.961s) [344.546588] Starting dynamic subtest: ccs0 [348.215688] Dynamic subtest ccs0: SUCCESS (3.676s) [348.218446] Subtest contexts: SUCCESS (22.480s) [348.413762] [085/131] (679s left) kms_feature_discovery (psr2) [348.521879] Starting subtest: psr2 [348.524189] Subtest psr2: SUCCESS (0.000s) [348.593440] [086/131] (679s left) api_intel_allocator (reopen) [348.641998] Starting subtest: reopen [348.644338] Subtest reopen: SUCCESS (0.004s) [348.766154] [087/131] (679s left) kms_flip (absolute-wf_vblank) [348.855699] Starting subtest: absolute-wf_vblank [348.858045] Starting dynamic subtest: A-eDP1 [357.608882] Dynamic subtest A-eDP1: SUCCESS (8.744s) [357.621210] Starting dynamic subtest: B-eDP1 [366.304021] Dynamic subtest B-eDP1: SUCCESS (8.693s) [366.317470] Starting dynamic subtest: C-eDP1 [374.987850] Dynamic subtest C-eDP1: SUCCESS (8.682s) [374.997585] Starting dynamic subtest: D-eDP1 [383.687626] Dynamic subtest D-eDP1: SUCCESS (8.692s) [383.690075] Subtest absolute-wf_vblank: SUCCESS (34.824s) [384.869312] [088/131] (642s left) prime_busy (before) [384.939516] Starting subtest: before [384.941997] Starting dynamic subtest: rcs0 [384.978589] Dynamic subtest rcs0: SUCCESS (0.039s) [384.990201] Starting dynamic subtest: bcs0 [385.026292] Dynamic subtest bcs0: SUCCESS (0.047s) [385.036316] Starting dynamic subtest: vcs0 [385.067777] Dynamic subtest vcs0: SUCCESS (0.041s) [385.077395] Starting dynamic subtest: vcs1 [385.107815] Dynamic subtest vcs1: SUCCESS (0.039s) [385.117301] Starting dynamic subtest: vecs0 [385.148208] Dynamic subtest vecs0: SUCCESS (0.040s) [385.157892] Starting dynamic subtest: ccs0 [385.189018] Dynamic subtest ccs0: SUCCESS (0.040s) [385.298430] Subtest before: SUCCESS (0.360s) [385.377103] [089/131] (642s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-cpu) [385.475032] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-cpu [389.344400] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (3.872s) [390.499290] [090/131] (637s left) syncobj_timeline (invalid-signal-illegal-handle) [390.554420] Starting subtest: invalid-signal-illegal-handle [390.557159] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s) [390.647035] [091/131] (637s left) gen9_exec_parse (batch-invalid-length) [390.702645] Subtest batch-invalid-length: SKIP (0.000s) [390.782500] [092/131] (637s left) gem_ctx_persistence (engines-queued) [390.832913] Starting subtest: engines-queued [390.835329] Starting dynamic subtest: rcs0 [390.894665] Dynamic subtest rcs0: SUCCESS (0.061s) [390.905447] Starting dynamic subtest: bcs0 [390.918478] Dynamic subtest bcs0: SUCCESS (0.023s) [390.928164] Starting dynamic subtest: vcs0 [392.060317] Dynamic subtest vcs0: SUCCESS (1.138s) [392.073594] Starting dynamic subtest: vcs1 [394.044371] Dynamic subtest vcs1: SUCCESS (1.980s) [394.057992] Starting dynamic subtest: vecs0 [396.028325] Dynamic subtest vecs0: SUCCESS (1.980s) [396.042740] Starting dynamic subtest: ccs0 [396.209293] Dynamic subtest ccs0: SUCCESS (0.179s) [396.211758] Subtest engines-queued: SUCCESS (5.375s) [396.348418] [093/131] (631s left) kms_busy (extended-modeset-hang-oldfb) [396.446838] Starting subtest: extended-modeset-hang-oldfb [396.449207] Starting dynamic subtest: pipe-A [408.184705] Dynamic subtest pipe-A: SUCCESS (11.732s) [408.197916] Starting dynamic subtest: pipe-D [420.724771] Dynamic subtest pipe-D: SUCCESS (12.536s) [420.727337] Subtest extended-modeset-hang-oldfb: SUCCESS (24.272s) [421.591593] [094/131] (606s left) gem_exec_whisper (basic-contexts-priority) [421.664012] Starting subtest: basic-contexts-priority [446.545640] Subtest basic-contexts-priority: SUCCESS (24.882s) [446.730765] [095/131] (581s left) kms_pipe_stress (stress-xrgb8888-yftiled) [446.841884] Starting subtest: stress-xrgb8888-yftiled [446.846196] Subtest stress-xrgb8888-yftiled: SKIP (0.000s) [446.917781] [096/131] (581s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-exceed-sf) [447.008685] Starting subtest: psr2-cursor-plane-move-continuous-exceed-sf [447.010990] Starting dynamic subtest: pipe-A-eDP-1 [457.716995] Dynamic subtest pipe-A-eDP-1: SUCCESS (10.709s) [457.729600] Starting dynamic subtest: pipe-B-eDP-1 [469.152025] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.430s) [469.154028] Subtest psr2-cursor-plane-move-continuous-exceed-sf: SUCCESS (22.143s) [469.983080] [097/131] (558s left) gem_eio (execbuf) [470.200209] Starting subtest: execbuf [470.493998] Subtest execbuf: SUCCESS (0.295s) [470.609325] [098/131] (557s left) kms_rotation_crc (multiplane-rotation) [470.693391] Starting subtest: multiplane-rotation [488.154147] Subtest multiplane-rotation: SUCCESS (17.460s) [488.282383] [099/131] (540s left) kms_selftest (drm_damage_helper) [488.322631] Starting subtest: drm_damage_helper [488.398457] Starting dynamic subtest: drm_test_damage_iter_no_damage [488.508440] Dynamic subtest drm_test_damage_iter_no_damage: SUCCESS (0.109s) [488.519726] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src [488.587820] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src: SUCCESS (0.079s) [488.598685] Starting dynamic subtest: drm_test_damage_iter_no_damage_src_moved [488.679127] Dynamic subtest drm_test_damage_iter_no_damage_src_moved: SUCCESS (0.091s) [488.692641] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src_moved [488.772193] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src_moved: SUCCESS (0.091s) [488.783047] Starting dynamic subtest: drm_test_damage_iter_no_damage_not_visible [488.859998] Dynamic subtest drm_test_damage_iter_no_damage_not_visible: SUCCESS (0.087s) [488.869259] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_crtc [488.960001] Dynamic subtest drm_test_damage_iter_no_damage_no_crtc: SUCCESS (0.100s) [488.969165] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_fb [489.055333] Dynamic subtest drm_test_damage_iter_no_damage_no_fb: SUCCESS (0.094s) [489.064401] Starting dynamic subtest: drm_test_damage_iter_simple_damage [489.164748] Dynamic subtest drm_test_damage_iter_simple_damage: SUCCESS (0.108s) [489.174008] Starting dynamic subtest: drm_test_damage_iter_single_damage [489.265318] Dynamic subtest drm_test_damage_iter_single_damage: SUCCESS (0.101s) [489.274877] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_src [489.363741] Dynamic subtest drm_test_damage_iter_single_damage_intersect_src: SUCCESS (0.095s) [489.372826] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_src [489.458184] Dynamic subtest drm_test_damage_iter_single_damage_outside_src: SUCCESS (0.094s) [489.469039] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src [489.554787] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src: SUCCESS (0.097s) [489.563792] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_fractional_src [489.654032] Dynamic subtest drm_test_damage_iter_single_damage_intersect_fractional_src: SUCCESS (0.097s) [489.664239] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_fractional_src [489.771080] Dynamic subtest drm_test_damage_iter_single_damage_outside_fractional_src: SUCCESS (0.114s) [489.782058] Starting dynamic subtest: drm_test_damage_iter_single_damage_src_moved [489.858182] Dynamic subtest drm_test_damage_iter_single_damage_src_moved: SUCCESS (0.087s) [489.869031] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src_moved [489.952742] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src_moved: SUCCESS (0.094s) [489.962826] Starting dynamic subtest: drm_test_damage_iter_damage [490.049043] Dynamic subtest drm_test_damage_iter_damage: SUCCESS (0.096s) [490.059188] Starting dynamic subtest: drm_test_damage_iter_damage_one_intersect [490.145372] Dynamic subtest drm_test_damage_iter_damage_one_intersect: SUCCESS (0.094s) [490.155750] Starting dynamic subtest: drm_test_damage_iter_damage_one_outside [490.243653] Dynamic subtest drm_test_damage_iter_damage_one_outside: SUCCESS (0.097s) [490.253224] Starting dynamic subtest: drm_test_damage_iter_damage_src_moved [490.349477] Dynamic subtest drm_test_damage_iter_damage_src_moved: SUCCESS (0.105s) [490.359713] Starting dynamic subtest: drm_test_damage_iter_damage_not_visible [490.451213] Dynamic subtest drm_test_damage_iter_damage_not_visible: SUCCESS (0.101s) [490.453475] Subtest drm_damage_helper: SUCCESS (2.126s) [490.613166] [100/131] (537s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [490.750709] Starting subtest: basic-flip-after-cursor-legacy [491.679879] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.929s) [492.491864] [101/131] (535s left) kms_big_fb (x-tiled-8bpp-rotate-0) [492.587656] Starting subtest: x-tiled-8bpp-rotate-0 [493.838366] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.250s) [493.937796] [102/131] (534s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-render) [494.044072] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-render [494.047666] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s) [494.134197] [103/131] (534s left) kms_ccs (crc-primary-basic-y-tiled-gen12-rc-ccs) [494.238080] Starting subtest: crc-primary-basic-y-tiled-gen12-rc-ccs [494.239838] Starting dynamic subtest: pipe-A-eDP-1 [494.258173] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s) [494.267738] Starting dynamic subtest: pipe-B-eDP-1 [494.285768] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s) [494.295091] Starting dynamic subtest: pipe-C-eDP-1 [494.312246] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s) [494.321200] Starting dynamic subtest: pipe-D-eDP-1 [494.338728] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [494.342207] Subtest crc-primary-basic-y-tiled-gen12-rc-ccs: SKIP (0.015s) [496.473714] [104/131] (532s left) kms_async_flips (alternate-sync-async-flip) [496.561689] Starting subtest: alternate-sync-async-flip [496.937927] Starting dynamic subtest: pipe-A-eDP-1 [499.736072] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.799s) [500.123681] Starting dynamic subtest: pipe-B-eDP-1 [502.934458] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.818s) [503.321107] Starting dynamic subtest: pipe-C-eDP-1 [506.142233] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.829s) [506.509738] Starting dynamic subtest: pipe-D-eDP-1 [509.332336] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.832s) [509.334419] Subtest alternate-sync-async-flip: SUCCESS (12.770s) [510.489084] [105/131] (518s left) kms_psr (fbc-pr-suspend) [510.584357] Starting subtest: fbc-pr-suspend [510.588898] Subtest fbc-pr-suspend: SKIP (0.000s) [510.663593] [106/131] (517s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt) [510.776740] Starting subtest: fbcpsr-rgb565-draw-blt [517.656875] Subtest fbcpsr-rgb565-draw-blt: SUCCESS (6.882s) [517.782147] [107/131] (510s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-blt) [517.886272] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-blt [517.890342] Subtest fbc-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s) [517.984759] [108/131] (510s left) perf_pmu (invalid-init) [518.030123] Starting subtest: invalid-init [518.032364] Subtest invalid-init: SUCCESS (0.000s) [518.133368] [109/131] (510s left) gem_exec_balancer (nohangcheck) [518.391833] Starting subtest: nohangcheck [519.277041] Subtest nohangcheck: SUCCESS (0.884s) [519.424303] [110/131] (509s left) gem_lmem_swapping (parallel-multi) [519.462316] Subtest parallel-multi: SKIP (0.000s) [519.513842] [111/131] (509s left) kms_rotation_crc (sprite-rotation-90) [519.606720] Starting subtest: sprite-rotation-90 [519.611367] Subtest sprite-rotation-90: SKIP (0.001s) [519.676270] [112/131] (509s left) syncobj_timeline (multi-wait-for-submit-submitted-signaled) [519.737165] Starting subtest: multi-wait-for-submit-submitted-signaled [519.739541] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.001s) [519.834466] [113/131] (509s left) perf (mi-rpc) [519.935489] Starting subtest: mi-rpc [519.941599] Subtest mi-rpc: SKIP (0.000s) [519.980310] [114/131] (508s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite) [520.078743] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite [529.333621] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.254s) [529.486495] [115/131] (499s left) kms_chamelium_hpd (dp-hpd-fast) [529.866776] Subtest dp-hpd-fast: SKIP (0.000s) [530.661328] [116/131] (498s left) kms_chamelium_frames (dp-crc-single) [531.051739] Subtest dp-crc-single: SKIP (0.000s) [531.854493] [117/131] (497s left) kms_psr (psr2-cursor-mmap-gtt) [531.951187] Starting subtest: psr2-cursor-mmap-gtt [531.953511] Starting dynamic subtest: eDP-1 [533.613938] Dynamic subtest eDP-1: SUCCESS (1.662s) [533.616242] Subtest psr2-cursor-mmap-gtt: SUCCESS (1.663s) [534.476687] [118/131] (494s left) gem_basic (multigpu-create-close) [534.532538] Starting subtest: multigpu-create-close [534.536759] Subtest multigpu-create-close: SKIP (0.000s) [534.646277] [119/131] (494s left) gem_exec_flush (basic-wb-ro-default) [534.702379] Starting subtest: basic-wb-ro-default [540.173487] Subtest basic-wb-ro-default: SUCCESS (5.471s) [540.375701] [120/131] (488s left) gem_exec_params (invalid-bsd1-flag-on-render) [540.433158] Starting subtest: invalid-bsd1-flag-on-render [540.435499] Subtest invalid-bsd1-flag-on-render: SUCCESS (0.000s) [540.532923] [121/131] (488s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render) [540.628324] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render [540.632647] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s) [540.736420] [122/131] (488s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-cpu) [540.829508] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-cpu [547.325246] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (6.497s) [547.459590] [123/131] (481s left) perf_pmu (busy-check-all) [547.511408] Starting subtest: busy-check-all [547.513776] Starting dynamic subtest: rcs0 [548.060384] Dynamic subtest rcs0: SUCCESS (0.551s) [548.072891] Starting dynamic subtest: bcs0 [548.610603] Dynamic subtest bcs0: SUCCESS (0.548s) [548.623009] Starting dynamic subtest: vcs0 [550.044401] Dynamic subtest vcs0: SUCCESS (1.431s) [550.057747] Starting dynamic subtest: vcs1 [552.029385] Dynamic subtest vcs1: SUCCESS (1.982s) [552.042212] Starting dynamic subtest: vecs0 [554.077679] Dynamic subtest vecs0: SUCCESS (2.045s) [554.096031] Starting dynamic subtest: ccs0 [554.632270] Dynamic subtest ccs0: SUCCESS (0.554s) [554.634791] Subtest busy-check-all: SUCCESS (7.126s) [554.733521] [124/131] (474s left) kms_dp_link_training (uhbr-mst) [554.836970] Starting subtest: uhbr-mst [554.845733] Subtest uhbr-mst: SKIP (0.000s) [554.910647] [125/131] (474s left) gem_eio (in-flight-immediate) [555.116805] Starting subtest: in-flight-immediate [563.548357] Subtest in-flight-immediate: SUCCESS (8.432s) [563.670455] [126/131] (465s left) i915_drm_fdinfo (virtual-busy-idle-all) [563.715725] Starting subtest: virtual-busy-idle-all [563.720246] Subtest virtual-busy-idle-all: SKIP (0.000s) [563.787991] [127/131] (465s left) kms_async_flips (test-time-stamp) [563.880434] Starting subtest: test-time-stamp [564.257000] Starting dynamic subtest: pipe-A-eDP-1 [565.043522] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.785s) [565.428731] Starting dynamic subtest: pipe-B-eDP-1 [566.250488] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.827s) [566.629139] Starting dynamic subtest: pipe-C-eDP-1 [567.441936] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.819s) [567.810815] Starting dynamic subtest: pipe-D-eDP-1 [568.652896] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.854s) [568.655406] Subtest test-time-stamp: SUCCESS (4.773s) [569.879526] [128/131] (459s left) gem_mmap_wc (write) [569.932235] Subtest write: SKIP (0.000s) [569.988148] [129/131] (459s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs) [570.078856] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs [570.081001] Starting dynamic subtest: pipe-A-eDP-1 [570.102783] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [570.115641] Starting dynamic subtest: pipe-B-eDP-1 [570.136603] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [570.146604] Starting dynamic subtest: pipe-C-eDP-1 [570.167761] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [570.177463] Starting dynamic subtest: pipe-D-eDP-1 [570.200353] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [570.204555] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs: SKIP (0.006s) [572.367381] [130/131] (457s left) i915_power (sanity) [572.432703] Starting subtest: sanity [576.480004] Subtest sanity: SUCCESS (4.041s) [578.100229] [131/131] (451s left) kms_flip (2x-flip-vs-wf_vblank) [578.206528] Starting subtest: 2x-flip-vs-wf_vblank [578.217378] Subtest 2x-flip-vs-wf_vblank: SKIP (0.010s) [578.331884] Closing watchdogs results: parsing output: 0/ for test: kms_color results: parsing output: 1/ for test: kms_prop_blob results: parsing output: 2/ for test: kms_invalid_mode results: parsing output: 3/ for test: gem_lmem_swapping results: parsing output: 4/ for test: kms_frontbuffer_tracking results: parsing output: 5/ for test: gem_softpin results: parsing output: 6/ for test: kms_plane results: parsing output: 7/ for test: gem_flink_basic results: parsing output: 8/ for test: kms_cursor_crc results: parsing output: 9/ for test: kms_psr results: parsing output: 10/ for test: prime_mmap results: parsing output: 11/ for test: perf results: parsing output: 12/ for test: kms_content_protection results: parsing output: 13/ for test: kms_addfb_basic results: parsing output: 14/ for test: kms_frontbuffer_tracking results: parsing output: 15/ for test: kms_frontbuffer_tracking results: parsing output: 16/ for test: kms_big_fb results: parsing output: 17/ for test: gem_close_race results: parsing output: 18/ for test: i915_query results: parsing output: 19/ for test: gem_render_copy results: parsing output: 20/ for test: kms_cursor_crc results: parsing output: 21/ for test: kms_properties results: parsing output: 22/ for test: kms_psr results: parsing output: 23/ for test: kms_frontbuffer_tracking results: parsing output: 24/ for test: syncobj_timeline results: parsing output: 25/ for test: kms_big_fb results: parsing output: 26/ for test: gem_pxp results: parsing output: 27/ for test: kms_frontbuffer_tracking results: parsing output: 28/ for test: gem_exec_schedule results: parsing output: 29/ for test: gem_exec_fence results: parsing output: 30/ for test: kms_plane_multiple results: parsing output: 31/ for test: kms_cursor_legacy results: parsing output: 32/ for test: gem_ctx_shared results: parsing output: 33/ for test: perf results: parsing output: 34/ for test: gem_tiled_partial_pwrite_pread results: parsing output: 35/ for test: kms_big_fb results: parsing output: 36/ for test: kms_plane_scaling results: parsing output: 37/ for test: gem_watchdog results: parsing output: 38/ for test: kms_ccs results: parsing output: 39/ for test: kms_flip results: parsing output: 40/ for test: sysfs_preempt_timeout results: parsing output: 41/ for test: gem_eio results: parsing output: 42/ for test: api_intel_bb results: parsing output: 43/ for test: kms_big_fb results: parsing output: 44/ for test: kms_atomic_transition results: parsing output: 45/ for test: kms_hdr results: parsing output: 46/ for test: kms_pm_dc results: parsing output: 47/ for test: core_setmaster results: parsing output: 48/ for test: kms_cursor_legacy results: parsing output: 49/ for test: kms_frontbuffer_tracking results: parsing output: 50/ for test: kms_draw_crc results: parsing output: 51/ for test: gem_exec_params results: parsing output: 52/ for test: kms_psr results: parsing output: 53/ for test: kms_lease results: parsing output: 54/ for test: gem_ctx_persistence results: parsing output: 55/ for test: kms_big_fb results: parsing output: 56/ for test: kms_frontbuffer_tracking results: parsing output: 57/ for test: syncobj_timeline results: parsing output: 58/ for test: gem_exec_schedule results: parsing output: 59/ for test: kms_flip results: parsing output: 60/ for test: gem_spin_batch results: parsing output: 61/ for test: kms_plane_scaling results: parsing output: 62/ for test: i915_pm_rpm results: parsing output: 63/ for test: gem_mmap_offset results: parsing output: 64/ for test: gem_exec_flush results: parsing output: 65/ for test: syncobj_timeline results: parsing output: 66/ for test: kms_prime results: parsing output: 67/ for test: prime_self_import results: parsing output: 68/ for test: kms_force_connector_basic results: parsing output: 69/ for test: gem_exec_params results: parsing output: 70/ for test: gem_exec_big results: parsing output: 71/ for test: gem_exec_reloc results: parsing output: 72/ for test: gem_pwrite results: parsing output: 73/ for test: kms_flip_scaled_crc results: parsing output: 74/ for test: gem_partial_pwrite_pread results: parsing output: 75/ for test: kms_frontbuffer_tracking results: parsing output: 76/ for test: kms_chamelium_color results: parsing output: 77/ for test: i915_drm_fdinfo results: parsing output: 78/ for test: perf_pmu results: parsing output: 79/ for test: kms_pipe_b_c_ivb results: parsing output: 80/ for test: kms_ccs results: parsing output: 81/ for test: kms_cursor_edge_walk results: parsing output: 82/ for test: kms_vblank results: parsing output: 83/ for test: gem_exec_parallel results: parsing output: 84/ for test: kms_feature_discovery results: parsing output: 85/ for test: api_intel_allocator results: parsing output: 86/ for test: kms_flip results: parsing output: 87/ for test: prime_busy results: parsing output: 88/ for test: kms_frontbuffer_tracking results: parsing output: 89/ for test: syncobj_timeline results: parsing output: 90/ for test: gen9_exec_parse results: parsing output: 91/ for test: gem_ctx_persistence results: parsing output: 92/ for test: kms_busy results: parsing output: 93/ for test: gem_exec_whisper results: parsing output: 94/ for test: kms_pipe_stress results: parsing output: 95/ for test: kms_psr2_sf results: parsing output: 96/ for test: gem_eio results: parsing output: 97/ for test: kms_rotation_crc results: parsing output: 98/ for test: kms_selftest results: parsing output: 99/ for test: kms_cursor_legacy results: parsing output: 100/ for test: kms_big_fb results: parsing output: 101/ for test: kms_frontbuffer_tracking results: parsing output: 102/ for test: kms_ccs results: parsing output: 103/ for test: kms_async_flips results: parsing output: 104/ for test: kms_psr results: parsing output: 105/ for test: kms_frontbuffer_tracking results: parsing output: 106/ for test: kms_frontbuffer_tracking results: parsing output: 107/ for test: perf_pmu results: parsing output: 108/ for test: gem_exec_balancer results: parsing output: 109/ for test: gem_lmem_swapping results: parsing output: 110/ for test: kms_rotation_crc results: parsing output: 111/ for test: syncobj_timeline results: parsing output: 112/ for test: perf results: parsing output: 113/ for test: kms_frontbuffer_tracking results: parsing output: 114/ for test: kms_chamelium_hpd results: parsing output: 115/ for test: kms_chamelium_frames results: parsing output: 116/ for test: kms_psr results: parsing output: 117/ for test: gem_basic results: parsing output: 118/ for test: gem_exec_flush results: parsing output: 119/ for test: gem_exec_params results: parsing output: 120/ for test: kms_frontbuffer_tracking results: parsing output: 121/ for test: kms_frontbuffer_tracking results: parsing output: 122/ for test: perf_pmu results: parsing output: 123/ for test: kms_dp_link_training results: parsing output: 124/ for test: gem_eio results: parsing output: 125/ for test: i915_drm_fdinfo results: parsing output: 126/ for test: kms_async_flips results: parsing output: 127/ for test: gem_mmap_wc results: parsing output: 128/ for test: kms_ccs results: parsing output: 129/ for test: i915_power results: parsing output: 130/ for test: kms_flip Done.