[55.763912] Initializing watchdogs
[55.763955]   /dev/watchdog0
[55.777022] [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]
[55.781083] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[55.783196] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[55.786373] [001/133] (960s left) kms_ccs (crc-primary-basic-4-tiled-lnl-ccs)
[55.908823] Starting subtest: crc-primary-basic-4-tiled-lnl-ccs
[55.913455] Subtest crc-primary-basic-4-tiled-lnl-ccs: SKIP (0.000s)
[57.053783] [002/133] (959s left) api_intel_bb (intel-bb-blit-none)
[57.104746] Starting subtest: intel-bb-blit-none
[61.282440] Subtest intel-bb-blit-none: SUCCESS (4.241s)
[61.429804] [003/133] (954s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-cpu)
[61.542947] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-cpu
[65.128882] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (3.621s)
[66.283336] [004/133] (950s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[66.402328] Starting subtest: basic-flip-before-cursor-legacy
[67.343343] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.945s)
[68.168563] [005/133] (948s left) kms_psr (psr-primary-blt)
[68.255321] Starting subtest: psr-primary-blt
[68.257737] Starting dynamic subtest: eDP-1
[69.944249] Dynamic subtest eDP-1: SUCCESS (1.697s)
[69.946579] Subtest psr-primary-blt: SUCCESS (1.698s)
[70.822159] [006/133] (945s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[70.949187] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt
[70.953706] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[71.232345] [007/133] (945s left) kms_cursor_legacy (short-flip-before-cursor-toggle)
[71.418148] Starting subtest: short-flip-before-cursor-toggle
[72.343568] Subtest short-flip-before-cursor-toggle: SUCCESS (0.927s)
[73.163101] [008/133] (943s left) kms_chamelium_hpd (dp-hpd-storm-disable)
[73.538850] Subtest dp-hpd-storm-disable: SKIP (0.000s)
[74.340008] [009/133] (942s left) gem_exec_params (invalid-bsd2-flag-on-vebox)
[74.400554] Starting subtest: invalid-bsd2-flag-on-vebox
[74.402867] Subtest invalid-bsd2-flag-on-vebox: SUCCESS (0.000s)
[74.536802] [010/133] (942s left) perf_pmu (busy-idle-no-semaphores)
[74.578833] Starting subtest: busy-idle-no-semaphores
[74.581500] Starting dynamic subtest: rcs0
[75.268366] Dynamic subtest rcs0: SUCCESS (0.691s)
[75.278840] Starting dynamic subtest: bcs0
[75.919073] Dynamic subtest bcs0: SUCCESS (0.651s)
[75.929390] Starting dynamic subtest: vcs0
[76.571075] Dynamic subtest vcs0: SUCCESS (0.652s)
[76.580989] Starting dynamic subtest: vcs1
[78.527059] Dynamic subtest vcs1: SUCCESS (1.958s)
[78.537461] Starting dynamic subtest: vecs0
[80.510084] Dynamic subtest vecs0: SUCCESS (1.984s)
[80.520105] Starting dynamic subtest: ccs0
[82.495280] Dynamic subtest ccs0: SUCCESS (1.985s)
[82.497964] Subtest busy-idle-no-semaphores: SUCCESS (7.926s)
[82.620679] [011/133] (934s left) api_intel_allocator (standalone)
[82.674054] Starting subtest: standalone
[82.692845] Subtest standalone: SUCCESS (0.021s)
[82.839734] [012/133] (933s left) perf_pmu (multi-client)
[82.878067] Starting subtest: multi-client
[82.880369] Starting dynamic subtest: rcs0
[83.957088] Dynamic subtest rcs0: SUCCESS (1.079s)
[83.966294] Starting dynamic subtest: bcs0
[85.009432] Dynamic subtest bcs0: SUCCESS (1.050s)
[85.022311] Starting dynamic subtest: vcs0
[86.061424] Dynamic subtest vcs0: SUCCESS (1.051s)
[86.071649] Starting dynamic subtest: vcs1
[87.948312] Dynamic subtest vcs1: SUCCESS (1.886s)
[87.956926] Starting dynamic subtest: vecs0
[89.931994] Dynamic subtest vecs0: SUCCESS (1.983s)
[89.941903] Starting dynamic subtest: ccs0
[91.917894] Dynamic subtest ccs0: SUCCESS (1.983s)
[91.921041] Subtest multi-client: SUCCESS (9.038s)
[92.053263] [013/133] (924s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-wc)
[92.149618] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-wc
[99.127915] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (6.980s)
[99.260071] [014/133] (917s left) perf (invalid-open-flags)
[99.389374] Starting subtest: invalid-open-flags
[99.391703] Subtest invalid-open-flags: SUCCESS (0.000s)
[99.428445] [015/133] (917s left) gem_pxp (create-regular-buffer)
[99.556902] Starting subtest: create-regular-buffer
[99.559601] Subtest create-regular-buffer: SUCCESS (0.000s)
[99.701813] [016/133] (917s left) i915_hangman (gt-error-state-capture)
[99.777367] Starting subtest: gt-error-state-capture
[99.779704] Starting dynamic subtest: rcs0
[110.901506] Dynamic subtest rcs0: SUCCESS (11.122s)
[110.912043] Starting dynamic subtest: bcs0
[122.868649] Dynamic subtest bcs0: SUCCESS (11.965s)
[122.879946] Starting dynamic subtest: vcs0
[134.834452] Dynamic subtest vcs0: SUCCESS (11.964s)
[134.842651] Starting dynamic subtest: vcs1
[145.844039] Dynamic subtest vcs1: SUCCESS (11.008s)
[145.857742] Starting dynamic subtest: vecs0
[157.812677] Dynamic subtest vecs0: SUCCESS (11.966s)
[157.822889] Starting dynamic subtest: ccs0
[168.884627] Dynamic subtest ccs0: SUCCESS (11.070s)
[168.887624] Subtest gt-error-state-capture: SUCCESS (69.102s)
[168.972690] [017/133] (847s left) kms_flip (flip-vs-modeset-vs-hang)
[169.072292] Starting subtest: flip-vs-modeset-vs-hang
[169.074576] Starting dynamic subtest: A-eDP1
[220.407614] Dynamic subtest A-eDP1: SUCCESS (51.322s)
[220.415407] Starting dynamic subtest: D-eDP1
[270.390776] Dynamic subtest D-eDP1: SUCCESS (49.986s)
[270.392993] Subtest flip-vs-modeset-vs-hang: SUCCESS (101.312s)
[270.795752] [018/133] (746s left) kms_big_fb (x-tiled-addfb-size-overflow)
[270.899067] Starting subtest: x-tiled-addfb-size-overflow
[270.901275] Subtest x-tiled-addfb-size-overflow: SUCCESS (0.000s)
[271.027027] [019/133] (745s left) kms_psr (pr-sprite-plane-onoff)
[271.131229] Starting subtest: pr-sprite-plane-onoff
[271.135349] Subtest pr-sprite-plane-onoff: SKIP (0.000s)
[271.223492] [020/133] (745s left) kms_cursor_crc (cursor-offscreen-64x64)
[271.407646] Starting subtest: cursor-offscreen-64x64
[271.412409] Starting dynamic subtest: pipe-A-eDP-1
[273.336433] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.923s)
[273.365622] Starting dynamic subtest: pipe-D-eDP-1
[275.909257] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.550s)
[275.911675] Subtest cursor-offscreen-64x64: SUCCESS (4.499s)
[276.781478] [021/133] (740s left) syncobj_timeline (etime-single-wait-for-submit-unsubmitted)
[276.833035] Starting subtest: etime-single-wait-for-submit-unsubmitted
[276.937914] Subtest etime-single-wait-for-submit-unsubmitted: SUCCESS (0.104s)
[277.031088] [022/133] (740s left) gem_ctx_shared (single-timeline)
[277.097148] Starting subtest: single-timeline
[277.115755] Subtest single-timeline: SUCCESS (0.013s)
[277.375204] [023/133] (739s left) kms_addfb_basic (bad-pitch-256)
[277.424723] Starting subtest: bad-pitch-256
[277.426640] Subtest bad-pitch-256: SUCCESS (0.000s)
[277.596718] [024/133] (739s left) gem_exec_schedule (semaphore-noskip)
[277.658941] Starting subtest: semaphore-noskip
[278.086516] Subtest semaphore-noskip: SUCCESS (0.433s)
[278.446688] [025/133] (738s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions-varying-size)
[278.554108] Starting subtest: flip-vs-cursor-atomic-transitions-varying-size
[287.380663] Subtest flip-vs-cursor-atomic-transitions-varying-size: SUCCESS (8.824s)
[288.204861] [026/133] (729s left) kms_big_fb (4-tiled-addfb)
[288.310746] Starting subtest: 4-tiled-addfb
[288.312677] Subtest 4-tiled-addfb: SUCCESS (0.000s)
[288.434470] [027/133] (728s left) kms_cursor_crc (cursor-offscreen-32x32)
[288.569920] Starting subtest: cursor-offscreen-32x32
[288.615374] Subtest cursor-offscreen-32x32: SKIP (0.041s)
[288.936324] [028/133] (728s left) kms_vblank (ts-continuation-idle-hang)
[289.026051] Starting subtest: ts-continuation-idle-hang
[289.028395] Starting dynamic subtest: pipe-A-eDP-1
[315.663094] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.635s)
[315.676298] Starting dynamic subtest: pipe-D-eDP-1
[330.651840] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.984s)
[330.654281] Subtest ts-continuation-idle-hang: SUCCESS (41.623s)
[331.461225] [029/133] (685s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-cpu)
[331.564916] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-cpu
[331.569208] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[331.677276] [030/133] (685s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-pwrite)
[331.780538] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-pwrite
[339.279483] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (7.502s)
[339.424662] [031/133] (678s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-yf-tiled)
[339.497395] Starting subtest: y-tiled-mc-ccs-to-vebox-yf-tiled
[339.501625] Subtest y-tiled-mc-ccs-to-vebox-yf-tiled: SKIP (0.000s)
[339.700411] [032/133] (677s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-msflip-blt)
[339.795887] Starting subtest: fbc-1p-primscrn-shrfb-msflip-blt
[344.202649] Subtest fbc-1p-primscrn-shrfb-msflip-blt: SUCCESS (4.411s)
[345.368291] [033/133] (672s left) gem_exec_reloc (basic-cpu-read)
[345.422822] Subtest basic-cpu-read: SKIP (0.000s)
[345.494345] [034/133] (672s left) gem_exec_balancer (hang)
[345.743928] Starting subtest: hang
[347.360661] Subtest hang: SUCCESS (1.618s)
[347.512413] [035/133] (670s left) i915_suspend (debugfs-reader)
[347.568026] Starting subtest: debugfs-reader
[365.609268] Subtest debugfs-reader: SUCCESS (3.301s)
[365.690864] [036/133] (651s left) syncobj_wait (multi-wait-for-submit-submitted-signaled)
[365.763251] Starting subtest: multi-wait-for-submit-submitted-signaled
[365.765481] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.000s)
[365.858685] [037/133] (651s left) kms_colorop (plane-xr30-xr30-ctm_3x4_bt709_dec_enc)
[366.001151] Subtest plane-XR30-XR30-ctm_3x4_bt709_dec_enc: SKIP (0.000s)
[366.073407] [038/133] (651s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled)
[366.130799] Starting subtest: yf-tiled-ccs-to-yf-tiled
[366.134921] Subtest yf-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[366.318663] [039/133] (651s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs)
[366.404211] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs
[366.406499] Starting dynamic subtest: pipe-A-eDP-1
[382.749637] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.392s)
[382.758279] Starting dynamic subtest: pipe-B-eDP-1
[399.759751] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.373s)
[399.769481] Starting dynamic subtest: pipe-C-eDP-1
[416.694767] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.303s)
[416.710012] Starting dynamic subtest: pipe-D-eDP-1
[433.689275] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.269s)
[433.691423] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs: SUCCESS (8.341s)
[434.869924] [040/133] (582s left) kms_psr (fbc-psr2-primary-blt)
[434.967866] Starting subtest: fbc-psr2-primary-blt
[434.970075] Starting dynamic subtest: eDP-1
[434.974882] Dynamic subtest eDP-1: SKIP (0.000s)
[434.979411] Subtest fbc-psr2-primary-blt: SKIP (0.001s)
[435.054380] [041/133] (582s left) kms_psr2_sf (fbc-psr2-overlay-primary-update-sf-dmg-area)
[440.068571] Starting subtest: fbc-psr2-overlay-primary-update-sf-dmg-area
[440.071151] Starting dynamic subtest: pipe-A-eDP-1
[440.850665] Dynamic subtest pipe-A-eDP-1: SKIP (0.786s)
[440.859995] Starting dynamic subtest: pipe-B-eDP-1
[442.079927] Dynamic subtest pipe-B-eDP-1: SKIP (1.236s)
[442.085041] Subtest fbc-psr2-overlay-primary-update-sf-dmg-area: SKIP (2.026s)
[443.265136] [042/133] (574s left) sw_sync (sync_multi_timeline_wait)
[443.299019] Starting subtest: sync_multi_timeline_wait
[443.301361] Subtest sync_multi_timeline_wait: SUCCESS (0.000s)
[443.375404] [043/133] (574s left) kms_ccs (random-ccs-data-y-tiled-gen12-rc-ccs-cc)
[443.461150] Starting subtest: random-ccs-data-y-tiled-gen12-rc-ccs-cc
[443.463961] Starting dynamic subtest: pipe-A-eDP-1
[443.491520] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[443.501084] Starting dynamic subtest: pipe-B-eDP-1
[443.529507] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[443.538983] Starting dynamic subtest: pipe-C-eDP-1
[443.567143] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[443.576994] Starting dynamic subtest: pipe-D-eDP-1
[443.605745] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[443.610616] Subtest random-ccs-data-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[445.653600] [044/133] (572s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[445.766244] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip
[445.771235] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[445.897426] [045/133] (572s left) gem_lmem_swapping (verify)
[445.939552] Subtest verify: SKIP (0.000s)
[445.980252] [046/133] (572s left) kms_psr (pr-sprite-mmap-gtt)
[446.077943] Starting subtest: pr-sprite-mmap-gtt
[446.082721] Subtest pr-sprite-mmap-gtt: SKIP (0.000s)
[446.164124] [047/133] (571s left) kms_ccs (random-ccs-data-4-tiled-dg2-mc-ccs)
[446.250561] Starting subtest: random-ccs-data-4-tiled-dg2-mc-ccs
[446.253250] Starting dynamic subtest: pipe-A-eDP-1
[446.282251] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[446.292063] Starting dynamic subtest: pipe-B-eDP-1
[446.319854] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[446.328915] Starting dynamic subtest: pipe-C-eDP-1
[446.357009] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[446.366156] Starting dynamic subtest: pipe-D-eDP-1
[446.395389] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[446.400252] Subtest random-ccs-data-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[448.489086] [048/133] (569s left) kms_color (invalid-ctm-matrix-sizes)
[448.584165] Starting subtest: invalid-ctm-matrix-sizes
[448.586871] Starting dynamic subtest: pipe-A
[448.589512] Dynamic subtest pipe-A: SUCCESS (0.002s)
[448.599122] Starting dynamic subtest: pipe-B
[448.601476] Dynamic subtest pipe-B: SUCCESS (0.002s)
[448.610251] Starting dynamic subtest: pipe-C
[448.613092] Dynamic subtest pipe-C: SUCCESS (0.003s)
[448.621875] Starting dynamic subtest: pipe-D
[448.624097] Dynamic subtest pipe-D: SUCCESS (0.003s)
[448.626594] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.013s)
[448.675970] [049/133] (569s left) prime_mmap (test_reprime)
[448.733092] Starting subtest: test_reprime
[448.735944] Starting dynamic subtest: test_reprime-smem
[448.758989] Dynamic subtest test_reprime-smem: SUCCESS (0.029s)
[448.762030] Subtest test_reprime: SUCCESS (0.029s)
[448.886764] [050/133] (569s left) kms_flip (dpms-vs-vblank-race)
[448.983831] Starting subtest: dpms-vs-vblank-race
[448.986237] Starting dynamic subtest: A-eDP1
[453.950578] Dynamic subtest A-eDP1: SUCCESS (4.975s)
[453.959701] Starting dynamic subtest: B-eDP1
[458.551879] Dynamic subtest B-eDP1: SUCCESS (4.611s)
[458.564498] Starting dynamic subtest: C-eDP1
[463.154042] Dynamic subtest C-eDP1: SUCCESS (4.606s)
[463.163825] Starting dynamic subtest: D-eDP1
[464.322200] Dynamic subtest D-eDP1: FAIL (1.020s)
[464.324872] Subtest dpms-vs-vblank-race: FAIL (15.219s)
[465.320672] [051/133] (552s left) kms_chamelium_edid (hdmi-edid-read)
[465.721762] Subtest hdmi-edid-read: SKIP (0.000s)
[466.535481] [052/133] (551s left) gem_mmap_offset (clear)
[466.585911] Starting subtest: clear
[466.589408] Starting dynamic subtest: smem0
[490.214756] Dynamic subtest smem0: SUCCESS (23.754s)
[490.217260] Subtest clear: SUCCESS (23.756s)
[490.313394] [053/133] (527s left) kms_async_flips (async-flip-with-page-flip-events-tiled-atomic)
[490.473678] Starting subtest: async-flip-with-page-flip-events-tiled-atomic
[490.848014] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[492.299366] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: FAIL (0.775s)
[492.310196] Starting dynamic subtest: pipe-A-eDP-1-4
[492.763015] Dynamic subtest pipe-A-eDP-1-4: FAIL (0.733s)
[492.774167] Starting dynamic subtest: pipe-A-eDP-1-4
[492.969510] Dynamic subtest pipe-A-eDP-1-4: FAIL (0.016s)
[492.979192] Starting dynamic subtest: pipe-A-eDP-1-x
[493.353871] Dynamic subtest pipe-A-eDP-1-x: FAIL (0.666s)
[493.417612] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[494.942388] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: FAIL (1.290s)
[494.953136] Starting dynamic subtest: pipe-B-eDP-1-4
[495.421045] Dynamic subtest pipe-B-eDP-1-4: FAIL (0.201s)
[495.431969] Starting dynamic subtest: pipe-B-eDP-1-4
[495.615659] Dynamic subtest pipe-B-eDP-1-4: FAIL (0.544s)
[495.630174] Starting dynamic subtest: pipe-B-eDP-1-x
[496.052729] Dynamic subtest pipe-B-eDP-1-x: FAIL (0.186s)
[496.063271] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[498.808386] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.755s)
[498.821436] Starting dynamic subtest: pipe-C-eDP-1-4
[500.984708] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.174s)
[500.998753] Starting dynamic subtest: pipe-C-eDP-1-4
[502.943674] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s)
[502.953564] Starting dynamic subtest: pipe-C-eDP-1-x
[505.185839] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.189s)
[505.505127] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[508.340024] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.790s)
[508.352965] Starting dynamic subtest: pipe-D-eDP-1-4
[510.525017] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.184s)
[510.536728] Starting dynamic subtest: pipe-D-eDP-1-4
[512.485312] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.010s)
[512.495163] Starting dynamic subtest: pipe-D-eDP-1-x
[514.727542] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.189s)
[514.730151] Subtest async-flip-with-page-flip-events-tiled-atomic: FAIL (24.201s)
[515.854875] [054/133] (502s left) kms_cursor_crc (cursor-tearing-position-change)
[515.970195] Starting subtest: cursor-tearing-position-change
[515.972915] Starting dynamic subtest: pipe-A-eDP-1
[516.567569] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.593s)
[516.578072] Starting dynamic subtest: pipe-D-eDP-1
[517.807603] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.240s)
[517.810173] Subtest cursor-tearing-position-change: SUCCESS (1.836s)
[518.629740] [055/133] (499s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-onoff)
[518.738057] Starting subtest: psr-1p-primscrn-spr-indfb-onoff
[525.849559] Subtest psr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.115s)
[525.975955] [056/133] (492s left) gem_mmap_gtt (basic-write-read)
[526.035179] Subtest basic-write-read: SKIP (0.000s)
[526.092079] [057/133] (492s left) kms_draw_crc (fill-fb)
[526.175123] Starting subtest: fill-fb
[526.592990] Subtest fill-fb: SUCCESS (0.420s)
[526.674877] [058/133] (491s left) i915_pm_rc6_residency (rc6-idle)
[526.734471] Starting subtest: rc6-idle
[526.768243] Starting dynamic subtest: gt0-rcs0
[551.044101] Dynamic subtest gt0-rcs0: SUCCESS (24.283s)
[551.056122] Starting dynamic subtest: gt0-bcs0
[575.317750] Dynamic subtest gt0-bcs0: SUCCESS (24.269s)
[575.328018] Starting dynamic subtest: gt0-ccs0
[599.601127] Dynamic subtest gt0-ccs0: SUCCESS (24.279s)
[599.613019] Starting dynamic subtest: gt1-vcs0
[605.773467] Dynamic subtest gt1-vcs0: SUCCESS (6.169s)
[605.785912] Starting dynamic subtest: gt1-vecs0
[611.997295] Dynamic subtest gt1-vecs0: SUCCESS (6.169s)
[612.000261] Subtest rc6-idle: SUCCESS (85.217s)
[612.039514] [059/133] (406s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[612.150108] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[612.152987] Starting dynamic subtest: pipe-A-eDP-1
[612.224439] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.076s)
[612.550995] Starting dynamic subtest: pipe-B-eDP-1
[613.372775] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.833s)
[613.658584] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (1.511s)
[614.503913] [060/133] (404s left) gem_ppgtt (flink-and-close-vma-leak)
[614.568819] Starting subtest: flink-and-close-vma-leak
[614.611841] Subtest flink-and-close-vma-leak: SUCCESS (0.043s)
[614.710543] [061/133] (403s left) syncobj_basic (test-valid-cycle)
[614.774132] Starting subtest: test-valid-cycle
[614.777253] Subtest test-valid-cycle: SUCCESS (0.000s)
[614.851880] [062/133] (403s left) gem_userptr_blits (dmabuf-unsync)
[614.915687] Subtest dmabuf-unsync: SKIP (0.000s)
[615.034461] [063/133] (403s left) gem_ctx_engines (execute-one)
[615.096912] Starting subtest: execute-one
[617.387116] Subtest execute-one: SUCCESS (2.291s)
[617.888998] [064/133] (400s left) device_reset (unbind-reset-rebind)
[617.935075] Starting subtest: unbind-reset-rebind
[620.703296] Subtest unbind-reset-rebind: SUCCESS (2.767s)
[620.750287] [065/133] (398s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[620.848026] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[620.852499] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[620.933440] [066/133] (397s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-render)
[621.029833] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-render
[628.153264] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.129s)
[628.280919] [067/133] (390s left) kms_atomic_transition (modeset-transition-nonblocking)
[628.383717] Starting subtest: modeset-transition-nonblocking
[628.386073] Starting dynamic subtest: 1x-outputs
[639.424303] Dynamic subtest 1x-outputs: SUCCESS (11.045s)
[639.427632] Subtest modeset-transition-nonblocking: SUCCESS (11.046s)
[640.255516] [068/133] (378s left) prime_vgem (sync)
[640.361769] Starting subtest: sync
[640.364250] Starting dynamic subtest: rcs0
[640.412054] Dynamic subtest rcs0: SUCCESS (0.050s)
[640.422253] Starting dynamic subtest: bcs0
[640.475580] Dynamic subtest bcs0: SUCCESS (0.063s)
[640.485750] Starting dynamic subtest: vcs0
[640.523949] Dynamic subtest vcs0: SUCCESS (0.047s)
[640.533886] Starting dynamic subtest: vcs1
[640.566926] Dynamic subtest vcs1: SUCCESS (0.042s)
[640.576659] Starting dynamic subtest: vecs0
[640.613869] Dynamic subtest vecs0: SUCCESS (0.046s)
[640.623723] Starting dynamic subtest: ccs0
[640.660246] Dynamic subtest ccs0: SUCCESS (0.046s)
[640.662912] Subtest sync: SUCCESS (0.299s)
[640.777611] [FACT prime_vgem (sync)] new: kernel.kmod_is_loaded.vgem: true
[640.781497] [069/133] (378s left) kms_feature_discovery (display-2x)
[640.880062] Starting subtest: display-2x
[640.884921] Subtest display-2x: SKIP (0.000s)
[640.931277] [070/133] (378s left) kms_big_fb (linear-64bpp-rotate-270)
[641.030857] Starting subtest: linear-64bpp-rotate-270
[641.050852] Subtest linear-64bpp-rotate-270: SKIP (0.019s)
[641.147822] [071/133] (377s left) gem_pread (snoop)
[641.211029] Subtest snoop: SKIP (0.000s)
[641.283677] [072/133] (377s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-sf)
[646.254917] Starting subtest: psr2-overlay-plane-move-continuous-exceed-sf
[646.257302] Starting dynamic subtest: pipe-A-eDP-1
[658.288069] Dynamic subtest pipe-A-eDP-1: SUCCESS (12.034s)
[658.299555] Starting dynamic subtest: pipe-B-eDP-1
[670.307037] Dynamic subtest pipe-B-eDP-1: SUCCESS (12.018s)
[670.309539] Subtest psr2-overlay-plane-move-continuous-exceed-sf: SUCCESS (24.055s)
[671.133597] [073/133] (347s left) gen9_exec_parse (secure-batches)
[671.184523] Subtest secure-batches: SKIP (0.000s)
[671.255992] [074/133] (347s left) gem_ctx_isolation (preservation-s3)
[671.317889] Starting subtest: preservation-S3
[671.320913] Starting dynamic subtest: rcs0
[687.639798] Dynamic subtest rcs0: SUCCESS (1.299s)
[687.650723] Starting dynamic subtest: bcs0
[703.601869] Dynamic subtest bcs0: SUCCESS (1.226s)
[703.614004] Starting dynamic subtest: vcs0
[719.594923] Dynamic subtest vcs0: SUCCESS (3.099s)
[719.608262] Starting dynamic subtest: vcs1
[736.605707] Dynamic subtest vcs1: SUCCESS (4.181s)
[736.617270] Starting dynamic subtest: vecs0
[754.652972] Dynamic subtest vecs0: SUCCESS (5.224s)
[754.664012] Starting dynamic subtest: ccs0
[772.591591] Dynamic subtest ccs0: SUCCESS (3.242s)
[772.596068] Subtest preservation-S3: SUCCESS (18.277s)
[772.808313] [075/133] (246s left) gem_exec_params (readonly)
[772.869861] Starting subtest: readonly
[772.874909] Subtest readonly: SUCCESS (0.003s)
[772.970644] [076/133] (246s left) kms_pipe_stress (stress-xrgb8888-untiled)
[773.058804] Starting subtest: stress-xrgb8888-untiled
[778.806221] Subtest stress-xrgb8888-untiled: SUCCESS (5.894s)
[778.856607] [077/133] (240s left) kms_frontbuffer_tracking (fbcpsr-tiling-y)
[778.957833] Starting subtest: fbcpsr-tiling-y
[778.962406] Subtest fbcpsr-tiling-y: SKIP (0.000s)
[779.054782] [078/133] (240s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[779.177482] Starting subtest: basic-flip-before-cursor-varying-size
[780.089929] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.928s)
[780.874393] [079/133] (238s left) kms_pipe_b_c_ivb (disable-pipe-b-enable-pipe-c)
[780.936022] Subtest disable-pipe-B-enable-pipe-C: SKIP (0.000s)
[781.002514] [080/133] (238s left) gem_exec_schedule (implicit-read-write)
[781.079067] Starting subtest: implicit-read-write
[781.081267] Starting dynamic subtest: rcs0
[781.180698] Dynamic subtest rcs0: SUCCESS (0.115s)
[781.192532] Starting dynamic subtest: vcs0
[781.257875] Dynamic subtest vcs0: SUCCESS (0.077s)
[781.270740] Starting dynamic subtest: vcs1
[781.336848] Dynamic subtest vcs1: SUCCESS (0.079s)
[781.350142] Starting dynamic subtest: vecs0
[781.412035] Dynamic subtest vecs0: SUCCESS (0.074s)
[781.425586] Starting dynamic subtest: ccs0
[781.488908] Dynamic subtest ccs0: SUCCESS (0.077s)
[781.492056] Subtest implicit-read-write: SUCCESS (0.427s)
[782.449897] [081/133] (236s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-blt)
[782.547856] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-blt
[786.681359] Subtest fbc-1p-offscreen-pri-indfb-draw-blt: SUCCESS (4.172s)
[787.811829] [082/133] (231s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-onoff)
[787.914353] Starting subtest: fbc-2p-scndscrn-cur-indfb-onoff
[787.919889] Subtest fbc-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[788.011699] [083/133] (231s left) kms_psr2_sf (fbc-pr-overlay-plane-update-continuous-sf)
[792.995654] Starting subtest: fbc-pr-overlay-plane-update-continuous-sf
[793.001013] Subtest fbc-pr-overlay-plane-update-continuous-sf: SKIP (0.002s)
[793.821728] [084/133] (225s left) kms_frontbuffer_tracking (fbcpsr-indfb-scaledprimary)
[793.923945] Starting subtest: fbcpsr-indfb-scaledprimary
[799.707975] Subtest fbcpsr-indfb-scaledprimary: SUCCESS (5.797s)
[799.847435] [085/133] (219s left) kms_writeback (writeback-invalid-parameters)
[799.945848] Subtest writeback-invalid-parameters: SKIP (0.000s)
[799.993415] [086/133] (219s left) kms_colorop (plane-xr24-xr24-pq_125_eotf-pq_125_inv_eotf)
[800.130255] Subtest plane-XR24-XR24-pq_125_eotf-pq_125_inv_eotf: SKIP (0.000s)
[800.183488] [087/133] (219s left) syncobj_wait (multi-wait-all-submitted)
[800.237431] Starting subtest: multi-wait-all-submitted
[800.345179] Subtest multi-wait-all-submitted: SUCCESS (0.109s)
[800.401424] [088/133] (219s left) gem_exec_capture (capture-invisible)
[800.465363] Starting subtest: capture-invisible
[800.468047] Starting dynamic subtest: smem0
[800.473143] Dynamic subtest smem0: SKIP (0.000s)
[800.477751] Subtest capture-invisible: SKIP (0.001s)
[800.586537] [089/133] (219s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[800.694034] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[800.698914] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[800.789949] [090/133] (219s left) kms_pm_rpm (modeset-non-lpsp)
[802.515897] Starting subtest: modeset-non-lpsp
[803.661332] Subtest modeset-non-lpsp: SKIP (1.146s)
[804.009214] [091/133] (215s left) syncobj_timeline (etime-multi-wait-for-submit-available-unsubmitted)
[804.066282] Starting subtest: etime-multi-wait-for-submit-available-unsubmitted
[804.167837] Subtest etime-multi-wait-for-submit-available-unsubmitted: SUCCESS (0.101s)
[804.226593] [092/133] (215s left) kms_flip (2x-wf_vblank-ts-check)
[804.329312] Starting subtest: 2x-wf_vblank-ts-check
[804.341348] Subtest 2x-wf_vblank-ts-check: SKIP (0.011s)
[804.447299] [093/133] (215s left) kms_plane_scaling (planes-downscale-factor-0-75)
[804.545630] Starting subtest: planes-downscale-factor-0-75
[804.548180] Starting dynamic subtest: pipe-A
[804.569039] Dynamic subtest pipe-A: SKIP (0.019s)
[804.579974] Starting dynamic subtest: pipe-B
[804.603942] Dynamic subtest pipe-B: SKIP (0.032s)
[804.614675] Starting dynamic subtest: pipe-C
[804.634869] Dynamic subtest pipe-C: SKIP (0.030s)
[804.645232] Starting dynamic subtest: pipe-D
[804.665565] Dynamic subtest pipe-D: SKIP (0.030s)
[804.669846] Subtest planes-downscale-factor-0-75: SKIP (0.116s)
[804.760313] [094/133] (215s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-plflip-blt)
[804.866494] Starting subtest: fbcpsr-2p-scndscrn-indfb-plflip-blt
[804.871363] Subtest fbcpsr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[804.956462] [095/133] (215s left) gem_softpin (allocator-fork)
[805.022935] Starting subtest: allocator-fork
[807.232915] Subtest allocator-fork: SUCCESS (2.207s)
[807.345246] [096/133] (212s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-upscaling)
[807.444919] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-upscaling
[807.447777] Starting dynamic subtest: pipe-A-default-mode
[807.731863] Dynamic subtest pipe-A-default-mode: SKIP (0.285s)
[807.737581] Subtest flip-32bpp-ytile-to-64bpp-ytile-upscaling: SKIP (0.286s)
[808.527063] [097/133] (211s left) gem_exec_reloc (basic-cpu-gtt-active)
[808.600194] Subtest basic-cpu-gtt-active: SKIP (0.000s)
[808.672594] [098/133] (211s left) kms_draw_crc (draw-method-render)
[808.771205] Starting subtest: draw-method-render
[808.773923] Starting dynamic subtest: xrgb8888-untiled
[808.990891] Dynamic subtest xrgb8888-untiled: SUCCESS (0.222s)
[809.003625] Starting dynamic subtest: rgb565-untiled
[809.213642] Dynamic subtest rgb565-untiled: SUCCESS (0.222s)
[809.225837] Starting dynamic subtest: xrgb2101010-untiled
[809.424806] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.210s)
[809.435070] Starting dynamic subtest: xbgr16161616f-untiled
[809.646708] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.220s)
[809.657360] Starting dynamic subtest: xrgb8888-xtiled
[809.781575] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.133s)
[809.797403] Starting dynamic subtest: rgb565-xtiled
[809.891862] Dynamic subtest rgb565-xtiled: SUCCESS (0.110s)
[809.903457] Starting dynamic subtest: xrgb2101010-xtiled
[810.002856] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.109s)
[810.012818] Starting dynamic subtest: xbgr16161616f-xtiled
[810.124590] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.121s)
[810.135816] Starting dynamic subtest: xrgb8888-4tiled
[810.247306] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.121s)
[810.259915] Starting dynamic subtest: rgb565-4tiled
[810.358518] Dynamic subtest rgb565-4tiled: SUCCESS (0.110s)
[810.369451] Starting dynamic subtest: xrgb2101010-4tiled
[810.482047] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.120s)
[810.493397] Starting dynamic subtest: xbgr16161616f-4tiled
[810.602875] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.121s)
[810.605615] Subtest draw-method-render: SUCCESS (1.834s)
[810.711384] [099/133] (209s left) syncobj_timeline (invalid-transfer-non-existent-point)
[810.779828] Starting subtest: invalid-transfer-non-existent-point
[810.782766] Subtest invalid-transfer-non-existent-point: SUCCESS (0.000s)
[810.873330] [100/133] (209s left) gem_tiled_swapping (non-threaded)
[810.932896] Subtest non-threaded: SKIP (0.000s)
[811.004336] [101/133] (209s left) kms_cursor_crc (cursor-sliding-64x64)
[811.177424] Starting subtest: cursor-sliding-64x64
[811.181799] Starting dynamic subtest: pipe-A-eDP-1
[814.437078] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.243s)
[814.467839] Starting dynamic subtest: pipe-D-eDP-1
[818.326491] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.868s)
[818.329026] Subtest cursor-sliding-64x64: SUCCESS (7.144s)
[819.192188] [102/133] (201s left) syncobj_timeline (reset-signaled)
[819.255832] Starting subtest: reset-signaled
[819.258568] Subtest reset-signaled: SUCCESS (0.000s)
[819.352565] [103/133] (201s left) kms_joiner (basic-force-ultra-joiner)
[819.456539] Subtest basic-force-ultra-joiner: SKIP (0.000s)
[819.498395] [104/133] (200s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[819.591590] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[820.238327] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.648s)
[820.402491] [105/133] (200s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[820.505822] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[820.510793] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[820.597779] [106/133] (199s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible)
[820.709620] Starting subtest: flip-vs-absolute-wf_vblank-interruptible
[820.712048] Starting dynamic subtest: A-eDP1
[829.649716] Dynamic subtest A-eDP1: SUCCESS (8.940s)
[829.663329] Starting dynamic subtest: B-eDP1
[838.587596] Dynamic subtest B-eDP1: SUCCESS (8.937s)
[838.599856] Starting dynamic subtest: C-eDP1
[847.555981] Dynamic subtest C-eDP1: SUCCESS (8.922s)
[847.566958] Starting dynamic subtest: D-eDP1
[856.475051] Dynamic subtest D-eDP1: SUCCESS (8.921s)
[856.477720] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (35.732s)
[857.641055] [107/133] (162s left) kms_psr (fbc-psr-cursor-mmap-gtt)
[857.738311] Starting subtest: fbc-psr-cursor-mmap-gtt
[857.740639] Starting dynamic subtest: eDP-1
[857.745682] Dynamic subtest eDP-1: SKIP (0.000s)
[857.750939] Subtest fbc-psr-cursor-mmap-gtt: SKIP (0.001s)
[857.790727] [108/133] (162s left) gem_lmem_swapping (heavy-verify-random)
[857.834791] Subtest heavy-verify-random: SKIP (0.000s)
[857.876502] [109/133] (162s left) sysfs_heartbeat_interval (precise)
[857.929463] Starting subtest: precise
[857.931926] Starting dynamic subtest: vecs0
[865.400824] Dynamic subtest vecs0: SUCCESS (7.472s)
[865.413685] Starting dynamic subtest: ccs0
[866.619616] Dynamic subtest ccs0: SUCCESS (1.217s)
[866.630080] Starting dynamic subtest: vcs0
[874.359077] Dynamic subtest vcs0: SUCCESS (7.733s)
[874.374634] Starting dynamic subtest: bcs0
[875.536271] Dynamic subtest bcs0: SUCCESS (1.177s)
[875.550262] Starting dynamic subtest: rcs0
[876.670315] Dynamic subtest rcs0: SUCCESS (1.129s)
[876.687167] Starting dynamic subtest: vcs1
[884.396172] Dynamic subtest vcs1: SUCCESS (7.724s)
[884.399042] Subtest precise: SUCCESS (26.464s)
[884.456588] [110/133] (136s left) kms_hdr (static-toggle)
[884.545273] Starting subtest: static-toggle
[884.552680] Subtest static-toggle: SKIP (0.000s)
[884.595671] [111/133] (136s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[884.694312] Starting subtest: compare-crc-sanitycheck-nv12
[884.697434] Starting dynamic subtest: pipe-A-eDP-1
[885.334587] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.639s)
[885.346305] Starting dynamic subtest: pipe-B-eDP-1
[886.639008] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.302s)
[886.651071] Starting dynamic subtest: pipe-C-eDP-1
[887.826644] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.187s)
[887.838269] Starting dynamic subtest: pipe-D-eDP-1
[889.055888] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.228s)
[889.058331] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.362s)
[889.878347] [112/133] (130s left) gem_ctx_persistence (engines-queued)
[889.929954] Starting subtest: engines-queued
[889.932774] Starting dynamic subtest: rcs0
[890.025855] Dynamic subtest rcs0: SUCCESS (0.095s)
[890.040273] Starting dynamic subtest: bcs0
[890.091225] Dynamic subtest bcs0: SUCCESS (0.062s)
[890.102863] Starting dynamic subtest: vcs0
[891.324339] Dynamic subtest vcs0: SUCCESS (1.229s)
[891.338376] Starting dynamic subtest: vcs1
[893.308336] Dynamic subtest vcs1: SUCCESS (1.982s)
[893.324534] Starting dynamic subtest: vecs0
[895.291377] Dynamic subtest vecs0: SUCCESS (1.979s)
[895.304591] Starting dynamic subtest: ccs0
[895.485565] Dynamic subtest ccs0: SUCCESS (0.192s)
[895.488394] Subtest engines-queued: SUCCESS (5.554s)
[895.600781] [113/133] (125s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[895.708669] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[895.713717] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[895.800808] [114/133] (125s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-plflip-blt)
[895.912315] Starting subtest: fbcpsr-2p-primscrn-indfb-plflip-blt
[895.917664] Subtest fbcpsr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[896.003392] [115/133] (124s left) kms_sequence (queue-busy)
[896.103723] Starting subtest: queue-busy
[896.106398] Starting dynamic subtest: pipe-A-eDP-1
[898.025235] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.921s)
[898.037774] Starting dynamic subtest: pipe-B-eDP-1
[900.578717] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.552s)
[900.591095] Starting dynamic subtest: pipe-C-eDP-1
[903.191927] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.612s)
[903.204739] Starting dynamic subtest: pipe-D-eDP-1
[905.811443] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.619s)
[905.814018] Subtest queue-busy: SUCCESS (9.711s)
[906.596109] [116/133] (114s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[906.693595] Starting subtest: plane-upscale-20x20-with-rotation
[906.696149] Starting dynamic subtest: pipe-A
[906.918373] Dynamic subtest pipe-A: SUCCESS (0.227s)
[906.929376] Starting dynamic subtest: pipe-B
[908.200831] Dynamic subtest pipe-B: SUCCESS (1.282s)
[908.210902] Starting dynamic subtest: pipe-C
[909.523959] Dynamic subtest pipe-C: SUCCESS (1.322s)
[909.535277] Starting dynamic subtest: pipe-D
[910.857611] Dynamic subtest pipe-D: SUCCESS (1.333s)
[910.860355] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.167s)
[912.038903] [117/133] (108s left) kms_big_fb (y-tiled-8bpp-rotate-0)
[912.142977] Starting subtest: y-tiled-8bpp-rotate-0
[912.148790] Subtest y-tiled-8bpp-rotate-0: SKIP (0.000s)
[912.231804] [118/133] (108s left) kms_plane (planar-pixel-format-settings)
[912.335102] Starting subtest: planar-pixel-format-settings
[912.376270] Subtest planar-pixel-format-settings: SUCCESS (0.041s)
[912.445287] [119/133] (108s left) kms_atomic_interruptible (legacy-dpms)
[912.530513] Starting subtest: legacy-dpms
[912.532746] Starting dynamic subtest: pipe-A-eDP-1
[919.973970] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.442s)
[919.976492] Subtest legacy-dpms: SUCCESS (7.443s)
[920.781614] [120/133] (100s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[920.881848] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-gtt
[920.887050] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[920.938366] [121/133] (100s left) gem_wait (busy)
[921.003390] Starting subtest: busy
[921.005973] Starting dynamic subtest: all-engines
[922.332955] Dynamic subtest all-engines: SUCCESS (1.328s)
[922.346362] Starting dynamic subtest: rcs0
[922.873365] Dynamic subtest rcs0: SUCCESS (0.539s)
[922.887644] Starting dynamic subtest: bcs0
[923.412092] Dynamic subtest bcs0: SUCCESS (0.538s)
[923.424544] Starting dynamic subtest: vcs0
[925.341414] Dynamic subtest vcs0: SUCCESS (1.927s)
[925.355335] Starting dynamic subtest: vcs1
[927.324896] Dynamic subtest vcs1: SUCCESS (1.981s)
[927.336927] Starting dynamic subtest: vecs0
[929.372226] Dynamic subtest vecs0: SUCCESS (2.045s)
[929.385485] Starting dynamic subtest: ccs0
[929.916669] Dynamic subtest ccs0: SUCCESS (0.543s)
[929.919282] Subtest busy: SUCCESS (8.915s)
[930.094537] [122/133] ( 91s left) kms_ccs (bad-pixel-format-4-tiled-dg2-mc-ccs)
[930.200493] Starting subtest: bad-pixel-format-4-tiled-dg2-mc-ccs
[930.202871] Starting dynamic subtest: pipe-A-eDP-1
[930.226018] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[930.236764] Starting dynamic subtest: pipe-B-eDP-1
[930.259487] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[930.269880] Starting dynamic subtest: pipe-C-eDP-1
[930.295276] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[930.305679] Starting dynamic subtest: pipe-D-eDP-1
[930.330967] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[930.335718] Subtest bad-pixel-format-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[932.443724] [123/133] ( 88s left) i915_drm_fdinfo (busy-idle-check-all)
[932.482887] Starting subtest: busy-idle-check-all
[932.485965] Starting dynamic subtest: rcs0
[932.491556] Dynamic subtest rcs0: SKIP (0.005s)
[932.502205] Starting dynamic subtest: bcs0
[932.507854] Dynamic subtest bcs0: SKIP (0.000s)
[932.518051] Starting dynamic subtest: vcs0
[932.523509] Dynamic subtest vcs0: SKIP (0.000s)
[932.533679] Starting dynamic subtest: vcs1
[932.539045] Dynamic subtest vcs1: SKIP (0.000s)
[932.549051] Starting dynamic subtest: vecs0
[932.554730] Dynamic subtest vecs0: SKIP (0.000s)
[932.565199] Starting dynamic subtest: ccs0
[932.570811] Dynamic subtest ccs0: SKIP (0.000s)
[932.576154] Subtest busy-idle-check-all: SKIP (0.011s)
[932.616288] [124/133] ( 88s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions)
[932.760919] Starting subtest: cursorB-vs-flipA-atomic-transitions
[932.766137] Subtest cursorB-vs-flipA-atomic-transitions: SKIP (0.001s)
[932.884119] [125/133] ( 88s left) gem_exec_whisper (basic-fds-forked)
[932.942893] Starting subtest: basic-fds-forked
[957.153842] Subtest basic-fds-forked: SUCCESS (24.215s)
[957.319604] [126/133] ( 64s left) fbdev (pan)
[957.354583] Starting subtest: pan
[957.389336] Subtest pan: SUCCESS (0.035s)
[957.444234] [127/133] ( 63s left) vgem_basic (second-client)
[957.498545] Starting subtest: second-client
[957.521088] Subtest second-client: SUCCESS (0.022s)
[957.561016] [128/133] ( 63s left) sw_sync (sync_multi_consumer)
[957.603733] Starting subtest: sync_multi_consumer
[958.408726] Subtest sync_multi_consumer: SUCCESS (0.808s)
[958.449402] [129/133] ( 63s left) kms_flip (basic-flip-vs-wf_vblank)
[958.550804] Starting subtest: basic-flip-vs-wf_vblank
[958.553759] Starting dynamic subtest: A-eDP1
[960.493421] Dynamic subtest A-eDP1: SUCCESS (1.943s)
[960.510970] Starting dynamic subtest: B-eDP1
[962.429579] Dynamic subtest B-eDP1: SUCCESS (1.934s)
[962.448523] Starting dynamic subtest: C-eDP1
[964.344443] Dynamic subtest C-eDP1: SUCCESS (1.913s)
[964.358088] Starting dynamic subtest: D-eDP1
[966.272505] Dynamic subtest D-eDP1: SUCCESS (1.928s)
[966.275040] Subtest basic-flip-vs-wf_vblank: SUCCESS (7.728s)
[967.485273] [130/133] ( 54s left) gem_mmap_wc (write-cpu-read-wc-unflushed)
[967.552060] Subtest write-cpu-read-wc-unflushed: SKIP (0.000s)
[967.615793] [131/133] ( 53s left) gem_exec_fence (keep-in-fence)
[967.790385] Starting subtest: keep-in-fence
[967.792564] Starting dynamic subtest: rcs0
[967.867586] Dynamic subtest rcs0: SUCCESS (0.078s)
[967.879323] Starting dynamic subtest: bcs0
[967.924021] Dynamic subtest bcs0: SUCCESS (0.055s)
[967.937823] Starting dynamic subtest: vcs0
[969.373700] Dynamic subtest vcs0: SUCCESS (1.446s)
[969.386690] Starting dynamic subtest: vcs1
[971.357082] Dynamic subtest vcs1: SUCCESS (1.982s)
[971.371323] Starting dynamic subtest: vecs0
[973.341063] Dynamic subtest vecs0: SUCCESS (1.981s)
[973.353967] Starting dynamic subtest: ccs0
[973.571782] Dynamic subtest ccs0: SUCCESS (0.229s)
[973.574816] Subtest keep-in-fence: SUCCESS (5.787s)
[975.381999] [132/133] ( 46s left) kms_getfb (getfb-handle-closed)
[975.481664] Starting subtest: getfb-handle-closed
[975.483979] Subtest getfb-handle-closed: SUCCESS (0.000s)
[975.553501] [133/133] ( 46s left) gem_flink_basic (basic)
[975.603083] Starting subtest: basic
[975.605564] Subtest basic: SUCCESS (0.000s)
[975.700618] Closing watchdogs
results: parsing output: 0/ for test: kms_ccs
results: parsing output: 1/ for test: api_intel_bb
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_cursor_legacy
results: parsing output: 4/ for test: kms_psr
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_cursor_legacy
results: parsing output: 7/ for test: kms_chamelium_hpd
results: parsing output: 8/ for test: gem_exec_params
results: parsing output: 9/ for test: perf_pmu
results: parsing output: 10/ for test: api_intel_allocator
results: parsing output: 11/ for test: perf_pmu
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: perf
results: parsing output: 14/ for test: gem_pxp
results: parsing output: 15/ for test: i915_hangman
results: parsing output: 16/ for test: kms_flip
results: parsing output: 17/ for test: kms_big_fb
results: parsing output: 18/ for test: kms_psr
results: parsing output: 19/ for test: kms_cursor_crc
results: parsing output: 20/ for test: syncobj_timeline
results: parsing output: 21/ for test: gem_ctx_shared
results: parsing output: 22/ for test: kms_addfb_basic
results: parsing output: 23/ for test: gem_exec_schedule
results: parsing output: 24/ for test: kms_cursor_legacy
results: parsing output: 25/ for test: kms_big_fb
results: parsing output: 26/ for test: kms_cursor_crc
results: parsing output: 27/ for test: kms_vblank
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: gem_render_copy
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: gem_exec_reloc
results: parsing output: 33/ for test: gem_exec_balancer
results: parsing output: 34/ for test: i915_suspend
results: parsing output: 35/ for test: syncobj_wait
results: parsing output: 36/ for test: kms_colorop
results: parsing output: 37/ for test: gem_render_copy
results: parsing output: 38/ for test: kms_ccs
results: parsing output: 39/ for test: kms_psr
results: parsing output: 40/ for test: kms_psr2_sf
results: parsing output: 41/ for test: sw_sync
results: parsing output: 42/ for test: kms_ccs
results: parsing output: 43/ for test: kms_big_fb
results: parsing output: 44/ for test: gem_lmem_swapping
results: parsing output: 45/ for test: kms_psr
results: parsing output: 46/ for test: kms_ccs
results: parsing output: 47/ for test: kms_color
results: parsing output: 48/ for test: prime_mmap
results: parsing output: 49/ for test: kms_flip
results: parsing output: 50/ for test: kms_chamelium_edid
results: parsing output: 51/ for test: gem_mmap_offset
results: parsing output: 52/ for test: kms_async_flips
results: parsing output: 53/ for test: kms_cursor_crc
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: gem_mmap_gtt
results: parsing output: 56/ for test: kms_draw_crc
results: parsing output: 57/ for test: i915_pm_rc6_residency
results: parsing output: 58/ for test: kms_atomic_transition
results: parsing output: 59/ for test: gem_ppgtt
results: parsing output: 60/ for test: syncobj_basic
results: parsing output: 61/ for test: gem_userptr_blits
results: parsing output: 62/ for test: gem_ctx_engines
results: parsing output: 63/ for test: device_reset
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: kms_atomic_transition
results: parsing output: 67/ for test: prime_vgem
results: parsing output: 68/ for test: kms_feature_discovery
results: parsing output: 69/ for test: kms_big_fb
results: parsing output: 70/ for test: gem_pread
results: parsing output: 71/ for test: kms_psr2_sf
results: parsing output: 72/ for test: gen9_exec_parse
results: parsing output: 73/ for test: gem_ctx_isolation
results: parsing output: 74/ for test: gem_exec_params
results: parsing output: 75/ for test: kms_pipe_stress
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_cursor_legacy
results: parsing output: 78/ for test: kms_pipe_b_c_ivb
results: parsing output: 79/ for test: gem_exec_schedule
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_psr2_sf
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: kms_writeback
results: parsing output: 85/ for test: kms_colorop
results: parsing output: 86/ for test: syncobj_wait
results: parsing output: 87/ for test: gem_exec_capture
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_pm_rpm
results: parsing output: 90/ for test: syncobj_timeline
results: parsing output: 91/ for test: kms_flip
results: parsing output: 92/ for test: kms_plane_scaling
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: gem_softpin
results: parsing output: 95/ for test: kms_flip_scaled_crc
results: parsing output: 96/ for test: gem_exec_reloc
results: parsing output: 97/ for test: kms_draw_crc
results: parsing output: 98/ for test: syncobj_timeline
results: parsing output: 99/ for test: gem_tiled_swapping
results: parsing output: 100/ for test: kms_cursor_crc
results: parsing output: 101/ for test: syncobj_timeline
results: parsing output: 102/ for test: kms_joiner
results: parsing output: 103/ for test: kms_big_fb
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: kms_flip
results: parsing output: 106/ for test: kms_psr
results: parsing output: 107/ for test: gem_lmem_swapping
results: parsing output: 108/ for test: sysfs_heartbeat_interval
results: parsing output: 109/ for test: kms_hdr
results: parsing output: 110/ for test: kms_pipe_crc_basic
results: parsing output: 111/ for test: gem_ctx_persistence
results: parsing output: 112/ for test: kms_big_fb
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: kms_sequence
results: parsing output: 115/ for test: kms_plane_scaling
results: parsing output: 116/ for test: kms_big_fb
results: parsing output: 117/ for test: kms_plane
results: parsing output: 118/ for test: kms_atomic_interruptible
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: gem_wait
results: parsing output: 121/ for test: kms_ccs
results: parsing output: 122/ for test: i915_drm_fdinfo
results: parsing output: 123/ for test: kms_cursor_legacy
results: parsing output: 124/ for test: gem_exec_whisper
results: parsing output: 125/ for test: fbdev
results: parsing output: 126/ for test: vgem_basic
results: parsing output: 127/ for test: sw_sync
results: parsing output: 128/ for test: kms_flip
results: parsing output: 129/ for test: gem_mmap_wc
results: parsing output: 130/ for test: gem_exec_fence
results: parsing output: 131/ for test: kms_getfb
results: parsing output: 132/ for test: gem_flink_basic
Done.