[57.749373] Initializing watchdogs
[57.749420]   /dev/watchdog0
[57.763348] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[57.767790] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.769993] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.773348] [001/133] (960s left) kms_rotation_crc (bad-pixel-format)
[57.894280] Starting subtest: bad-pixel-format
[57.898511] Subtest bad-pixel-format: SKIP (0.001s)
[57.989228] [002/133] (960s left) vgem_basic (debugfs)
[58.052742] Starting subtest: debugfs
[58.054646] Subtest debugfs: SUCCESS (0.000s)
[58.127704] [FACT vgem_basic (debugfs)] new: kernel.kmod_is_loaded.vgem: true
[58.130766] [003/133] (960s left) gem_pxp (create-valid-protected-context)
[58.261506] Starting subtest: create-valid-protected-context
[58.263840] Subtest create-valid-protected-context: SUCCESS (0.002s)
[58.410852] [004/133] (959s left) kms_frontbuffer_tracking (psr-1p-rte)
[58.505987] Starting subtest: psr-1p-rte
[63.891989] Subtest psr-1p-rte: SUCCESS (5.426s)
[64.070915] [005/133] (954s left) syncobj_basic (bad-flags-handle-to-fd)
[64.136786] Starting subtest: bad-flags-handle-to-fd
[64.139069] Subtest bad-flags-handle-to-fd: SUCCESS (0.000s)
[64.251829] [006/133] (954s left) gen9_exec_parse (bb-secure)
[64.321086] Subtest bb-secure: SKIP (0.000s)
[64.386743] [007/133] (954s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible)
[64.480828] Starting subtest: flip-vs-absolute-wf_vblank-interruptible
[64.483492] Starting dynamic subtest: A-eDP1
[73.424427] Dynamic subtest A-eDP1: SUCCESS (8.965s)
[73.436311] Starting dynamic subtest: B-eDP1
[82.352361] Dynamic subtest B-eDP1: SUCCESS (8.930s)
[82.361397] Starting dynamic subtest: C-eDP1
[91.276486] Dynamic subtest C-eDP1: SUCCESS (8.921s)
[91.287277] Starting dynamic subtest: D-eDP1
[100.199709] Dynamic subtest D-eDP1: SUCCESS (8.921s)
[100.205283] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (35.746s)
[101.451730] [008/133] (917s left) kms_psr (psr-primary-render)
[101.550103] Starting subtest: psr-primary-render
[101.552561] Starting dynamic subtest: eDP-1
[103.333044] Dynamic subtest eDP-1: SUCCESS (1.783s)
[103.335360] Subtest psr-primary-render: SUCCESS (1.783s)
[104.216893] [009/133] (914s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[104.306324] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[104.310958] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[105.441619] [010/133] (913s left) kms_content_protection (lic-type-0)
[105.602905] Starting subtest: lic-type-0
[105.607363] Subtest lic-type-0: SKIP (0.000s)
[105.775358] [011/133] (912s left) kms_psr (psr2-cursor-plane-onoff)
[105.869560] Starting subtest: psr2-cursor-plane-onoff
[105.871834] Starting dynamic subtest: eDP-1
[107.605356] Dynamic subtest eDP-1: SUCCESS (1.737s)
[107.607691] Subtest psr2-cursor-plane-onoff: SUCCESS (1.737s)
[108.427028] [012/133] (910s left) syncobj_wait (reset-multiple-signaled)
[108.494708] Starting subtest: reset-multiple-signaled
[108.497066] Subtest reset-multiple-signaled: SUCCESS (0.000s)
[108.626687] [013/133] (910s left) gem_mmap_gtt (hang-busy)
[108.693611] Subtest hang-busy: SKIP (0.000s)
[108.763462] [014/133] (910s left) syncobj_timeline (invalid-transfer-non-existent-point)
[108.824848] Starting subtest: invalid-transfer-non-existent-point
[108.827106] Subtest invalid-transfer-non-existent-point: SUCCESS (0.000s)
[108.959021] [015/133] (909s left) kms_pm_rpm (modeset-pc8-residency-stress)
[111.081581] Starting subtest: modeset-pc8-residency-stress
[111.086899] Subtest modeset-pc8-residency-stress: SKIP (0.000s)
[111.468607] [016/133] (907s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[111.594437] Starting subtest: short-busy-flip-before-cursor-toggle
[111.598988] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[111.761869] [017/133] (907s left) gem_spin_batch (resubmit)
[111.830676] Starting subtest: resubmit
[111.832609] Starting dynamic subtest: rcs0
[111.834582] Dynamic subtest rcs0: SUCCESS (0.006s)
[111.843101] Starting dynamic subtest: bcs0
[111.845418] Dynamic subtest bcs0: SUCCESS (0.002s)
[111.857726] Starting dynamic subtest: vcs0
[111.859730] Dynamic subtest vcs0: SUCCESS (0.002s)
[111.869643] Starting dynamic subtest: vcs1
[111.871586] Dynamic subtest vcs1: SUCCESS (0.003s)
[111.879612] Starting dynamic subtest: vecs0
[111.881484] Dynamic subtest vecs0: SUCCESS (0.002s)
[111.888523] Starting dynamic subtest: ccs0
[111.890436] Dynamic subtest ccs0: SUCCESS (0.002s)
[111.892328] Subtest resubmit: SUCCESS (0.019s)
[112.124078] [018/133] (906s left) vgem_slow (nohang)
[112.176748] Starting subtest: nohang
[122.482454] Subtest nohang: SUCCESS (10.304s)
[122.571369] [019/133] (896s left) kms_getfb (getfb-reject-nv12)
[122.670595] Starting subtest: getfb-reject-nv12
[122.672778] Subtest getfb-reject-nv12: SUCCESS (0.001s)
[122.798122] [020/133] (896s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[122.900617] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[122.904703] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[123.027880] [021/133] (896s left) gem_exec_fence (basic-wait-all)
[123.101590] Starting subtest: basic-wait-all
[123.202238] Subtest basic-wait-all: SUCCESS (0.101s)
[124.990812] [022/133] (894s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-mc-ccs)
[125.096309] Starting subtest: crc-primary-suspend-4-tiled-mtl-mc-ccs
[125.098543] Starting dynamic subtest: pipe-A-eDP-1
[158.235831] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.023s)
[158.244892] Starting dynamic subtest: pipe-B-eDP-1
[191.170836] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.695s)
[191.179675] Starting dynamic subtest: pipe-C-eDP-1
[224.145691] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.748s)
[224.155797] Starting dynamic subtest: pipe-D-eDP-1
[257.151852] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.704s)
[257.154808] Subtest crc-primary-suspend-4-tiled-mtl-mc-ccs: SUCCESS (14.174s)
[258.347948] [023/133] (760s left) gem_exec_params (invalid-ring)
[258.404690] Starting subtest: invalid-ring
[258.407270] Subtest invalid-ring: SUCCESS (0.000s)
[258.535161] [024/133] (760s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-blt)
[258.635710] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-blt
[262.658191] Subtest fbc-1p-offscreen-pri-indfb-draw-blt: SUCCESS (4.143s)
[263.821367] [025/133] (755s left) kms_feature_discovery (display-1x)
[263.923755] Starting subtest: display-1x
[263.925935] Subtest display-1x: SUCCESS (0.000s)
[264.019061] [026/133] (755s left) gem_request_retire (retire-vma-not-inactive)
[264.084573] Starting subtest: retire-vma-not-inactive
[264.143878] Subtest retire-vma-not-inactive: SUCCESS (0.058s)
[264.295130] [027/133] (755s left) sysfs_preempt_timeout (timeout)
[264.367770] Starting subtest: timeout
[264.370432] Starting dynamic subtest: vecs0
[274.262588] Dynamic subtest vecs0: SUCCESS (9.991s)
[274.274901] Starting dynamic subtest: ccs0
[275.604136] Dynamic subtest ccs0: SUCCESS (1.347s)
[275.616004] Starting dynamic subtest: vcs0
[285.265743] Dynamic subtest vcs0: SUCCESS (9.687s)
[285.277153] Starting dynamic subtest: bcs0
[286.648944] Dynamic subtest bcs0: SUCCESS (1.382s)
[286.660753] Starting dynamic subtest: rcs0
[287.886186] Dynamic subtest rcs0: SUCCESS (1.235s)
[287.897992] Starting dynamic subtest: vcs1
[297.255109] Dynamic subtest vcs1: SUCCESS (9.357s)
[297.258050] Subtest timeout: SUCCESS (33.008s)
[297.354013] [028/133] (721s left) i915_drm_fdinfo (idle)
[297.392146] Starting subtest: idle
[297.394872] Starting dynamic subtest: rcs0
[297.982048] Dynamic subtest rcs0: SUCCESS (0.591s)
[297.993540] Starting dynamic subtest: bcs0
[298.488030] Dynamic subtest bcs0: SUCCESS (0.503s)
[298.500021] Starting dynamic subtest: vcs0
[298.992277] Dynamic subtest vcs0: SUCCESS (0.503s)
[299.001722] Starting dynamic subtest: vcs1
[299.497524] Dynamic subtest vcs1: SUCCESS (0.503s)
[299.509642] Starting dynamic subtest: vecs0
[300.002868] Dynamic subtest vecs0: SUCCESS (0.503s)
[300.013584] Starting dynamic subtest: ccs0
[300.507753] Dynamic subtest ccs0: SUCCESS (0.502s)
[300.510689] Subtest idle: SUCCESS (3.114s)
[300.611088] [029/133] (718s left) kms_big_fb (4-tiled-addfb-size-overflow)
[300.730747] Starting subtest: 4-tiled-addfb-size-overflow
[300.733532] Subtest 4-tiled-addfb-size-overflow: SUCCESS (0.000s)
[300.909327] [030/133] (718s left) kms_plane_alpha_blend (constant-alpha-min)
[301.013907] Starting subtest: constant-alpha-min
[301.303989] Starting dynamic subtest: pipe-A-eDP-1
[302.035523] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.729s)
[302.046145] Starting dynamic subtest: pipe-D-eDP-1
[303.553279] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.518s)
[303.556058] Subtest constant-alpha-min: SUCCESS (2.539s)
[304.457124] [031/133] (715s left) gem_exec_alignment (many)
[304.526718] Starting subtest: many
[326.672729] Subtest many: SUCCESS (22.143s)
[329.213643] [032/133] (690s left) sw_sync (sync_busy)
[329.306445] Starting subtest: sync_busy
[329.309197] Subtest sync_busy: SUCCESS (0.002s)
[329.404250] [033/133] (690s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-upscaling)
[329.515938] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-upscaling
[329.518511] Starting dynamic subtest: pipe-A-default-mode
[332.244783] Dynamic subtest pipe-A-default-mode: SUCCESS (2.730s)
[332.247771] Subtest flip-32bpp-4tile-to-64bpp-4tile-upscaling: SUCCESS (2.731s)
[333.141312] [034/133] (686s left) kms_color_pipeline (plane-lut1d-pre-ctm3x4)
[333.234835] Starting subtest: plane-lut1d-pre-ctm3x4
[333.247351] Starting dynamic subtest: pipe-A-eDP-1
[334.040361] Dynamic subtest pipe-A-eDP-1: FAIL (0.337s)
[334.691753] Starting dynamic subtest: pipe-B-eDP-1
[335.612686] Dynamic subtest pipe-B-eDP-1: FAIL (0.335s)
[336.125816] Starting dynamic subtest: pipe-C-eDP-1
[337.083858] Dynamic subtest pipe-C-eDP-1: FAIL (0.279s)
[337.463272] Starting dynamic subtest: pipe-D-eDP-1
[338.438706] Dynamic subtest pipe-D-eDP-1: FAIL (0.301s)
[338.441022] Subtest plane-lut1d-pre-ctm3x4: FAIL (4.801s)
[338.866612] [035/133] (680s left) i915_query (test-query-geometry-subslices)
[338.929903] Starting subtest: test-query-geometry-subslices
[338.932573] Subtest test-query-geometry-subslices: SUCCESS (0.000s)
[339.049276] [036/133] (680s left) kms_addfb_basic (bad-pitch-128)
[339.114911] Starting subtest: bad-pitch-128
[339.117809] Subtest bad-pitch-128: SUCCESS (0.000s)
[339.287698] [037/133] (680s left) kms_psr (pr-cursor-blt)
[339.388498] Starting subtest: pr-cursor-blt
[339.392728] Subtest pr-cursor-blt: SKIP (0.000s)
[339.482684] [038/133] (680s left) syncobj_basic (bad-pad-fd-to-handle)
[339.536213] Starting subtest: bad-pad-fd-to-handle
[339.538812] Subtest bad-pad-fd-to-handle: SUCCESS (0.000s)
[339.682211] [039/133] (680s left) gem_ctx_persistence (legacy-engines-hang)
[339.757810] Starting subtest: legacy-engines-hang
[339.760550] Starting dynamic subtest: render
[339.913268] Dynamic subtest render: SUCCESS (0.159s)
[339.926978] Starting dynamic subtest: bsd1
[340.023907] Dynamic subtest bsd1: SUCCESS (0.107s)
[340.036019] Starting dynamic subtest: bsd2
[340.139763] Dynamic subtest bsd2: SUCCESS (0.113s)
[340.152685] Starting dynamic subtest: blt
[340.261802] Dynamic subtest blt: SUCCESS (0.119s)
[340.274057] Starting dynamic subtest: vebox
[340.376438] Dynamic subtest vebox: SUCCESS (0.111s)
[340.379014] Subtest legacy-engines-hang: SUCCESS (0.623s)
[340.480821] [040/133] (679s left) kms_pipe_crc_basic (read-crc)
[340.579987] Starting subtest: read-crc
[340.581950] Starting dynamic subtest: pipe-A-eDP-1
[341.286015] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.704s)
[341.295629] Starting dynamic subtest: pipe-B-eDP-1
[342.625476] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.337s)
[342.635050] Starting dynamic subtest: pipe-C-eDP-1
[344.022343] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.398s)
[344.032331] Starting dynamic subtest: pipe-D-eDP-1
[345.341733] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.318s)
[345.344599] Subtest read-crc: SUCCESS (4.762s)
[346.186227] [041/133] (673s left) kms_cursor_legacy (short-flip-before-cursor-toggle)
[346.322721] Starting subtest: short-flip-before-cursor-toggle
[347.258029] Subtest short-flip-before-cursor-toggle: SUCCESS (0.937s)
[348.119174] [042/133] (671s left) gem_partial_pwrite_pread (writes-after-reads)
[348.184624] Subtest writes-after-reads: SKIP (0.000s)
[348.266954] [043/133] (671s left) gem_mmap_gtt (big-bo-tiledy)
[348.332210] Subtest big-bo-tiledY: SKIP (0.000s)
[348.399309] [044/133] (671s left) gem_exec_reloc (basic-gtt-wc)
[348.469825] Subtest basic-gtt-wc: SKIP (0.000s)
[348.525181] [045/133] (671s left) gem_pread (display)
[348.584562] Subtest display: SKIP (0.000s)
[348.638715] [046/133] (671s left) kms_chamelium_hpd (hdmi-hpd-storm)
[349.020284] Subtest hdmi-hpd-storm: SKIP (0.000s)
[349.805507] [047/133] (670s left) gem_exec_schedule (fairslice)
[349.879008] Starting subtest: fairslice
[349.881860] Starting dynamic subtest: rcs0
[351.891039] Dynamic subtest rcs0: SUCCESS (2.021s)
[351.901625] Starting dynamic subtest: bcs0
[353.913816] Dynamic subtest bcs0: SUCCESS (2.021s)
[353.925436] Starting dynamic subtest: vcs0
[355.935950] Dynamic subtest vcs0: SUCCESS (2.018s)
[355.947197] Starting dynamic subtest: vcs1
[357.954476] Dynamic subtest vcs1: SUCCESS (2.018s)
[357.965644] Starting dynamic subtest: vecs0
[359.974397] Dynamic subtest vecs0: SUCCESS (2.019s)
[359.984835] Starting dynamic subtest: ccs0
[361.995580] Dynamic subtest ccs0: SUCCESS (2.021s)
[361.998053] Subtest fairslice: SUCCESS (12.123s)
[362.318860] [048/133] (657s left) kms_vrr (flip-basic)
[362.417039] Starting subtest: flip-basic
[362.424849] Subtest flip-basic: SKIP (0.000s)
[362.514698] [049/133] (657s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs)
[362.615408] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs
[362.617881] Starting dynamic subtest: pipe-A-eDP-1
[362.641668] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[362.651454] Starting dynamic subtest: pipe-B-eDP-1
[362.674867] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[362.684300] Starting dynamic subtest: pipe-C-eDP-1
[362.709669] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[362.718820] Starting dynamic subtest: pipe-D-eDP-1
[362.743905] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[362.749040] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs: SKIP (0.006s)
[364.895370] [050/133] (655s left) gem_pxp (protected-encrypted-src-copy-not-readible)
[365.003651] Starting subtest: protected-encrypted-src-copy-not-readible
[365.116927] Subtest protected-encrypted-src-copy-not-readible: SUCCESS (0.114s)
[365.264517] [051/133] (655s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[365.363356] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[365.368588] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[365.499239] [052/133] (654s left) gem_exec_whisper (basic-contexts-forked)
[365.568076] Starting subtest: basic-contexts-forked
[390.670322] Subtest basic-contexts-forked: SUCCESS (25.095s)
[390.881334] [053/133] (629s left) gem_exec_fence (syncobj-timeline-chain-engines)
[391.501714] Starting subtest: syncobj-timeline-chain-engines
[391.677661] Subtest syncobj-timeline-chain-engines: SUCCESS (0.179s)
[393.363871] [054/133] (627s left) kms_rotation_crc (primary-yf-tiled-reflect-x-0)
[393.462889] Starting subtest: primary-yf-tiled-reflect-x-0
[393.522842] Subtest primary-yf-tiled-reflect-x-0: SKIP (0.059s)
[393.648084] [055/133] (626s left) syncobj_timeline (wait-all-for-submit-complex)
[393.700050] Starting subtest: wait-all-for-submit-complex
[393.916316] Subtest wait-all-for-submit-complex: SUCCESS (0.216s)
[394.017735] [056/133] (626s left) gem_exec_flush (basic-uc-prw-default)
[394.078995] Starting subtest: basic-uc-prw-default
[399.542620] Subtest basic-uc-prw-default: SUCCESS (5.464s)
[399.756448] [057/133] (620s left) kms_psr (fbc-psr2-primary-page-flip)
[399.871133] Starting subtest: fbc-psr2-primary-page-flip
[399.873653] Starting dynamic subtest: eDP-1
[399.878438] Dynamic subtest eDP-1: SKIP (0.000s)
[399.882873] Subtest fbc-psr2-primary-page-flip: SKIP (0.001s)
[399.969390] [058/133] (620s left) kms_vblank (ts-continuation-idle-hang)
[400.067158] Starting subtest: ts-continuation-idle-hang
[400.069619] Starting dynamic subtest: pipe-A-eDP-1
[427.034575] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.971s)
[427.049624] Starting dynamic subtest: pipe-D-eDP-1
[441.989839] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.955s)
[441.992891] Subtest ts-continuation-idle-hang: SUCCESS (41.931s)
[442.845810] [059/133] (577s left) kms_big_fb (x-tiled-16bpp-rotate-180)
[442.970867] Starting subtest: x-tiled-16bpp-rotate-180
[444.240028] Subtest x-tiled-16bpp-rotate-180: SUCCESS (1.271s)
[444.408824] [060/133] (576s left) kms_psr (fbc-pr-cursor-plane-onoff)
[444.514372] Starting subtest: fbc-pr-cursor-plane-onoff
[444.518718] Subtest fbc-pr-cursor-plane-onoff: SKIP (0.000s)
[444.608313] [061/133] (576s left) kms_async_flips (crc-atomic)
[444.702001] Starting subtest: crc-atomic
[445.086643] Starting dynamic subtest: pipe-A-eDP-1
[448.036300] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.951s)
[448.422631] Starting dynamic subtest: pipe-B-eDP-1
[451.290059] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.883s)
[451.665330] Starting dynamic subtest: pipe-C-eDP-1
[454.593725] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.939s)
[454.971652] Starting dynamic subtest: pipe-D-eDP-1
[457.827778] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.866s)
[457.830443] Subtest crc-atomic: SUCCESS (13.130s)
[459.012897] [062/133] (561s left) syncobj_wait (invalid-signal-illegal-handle)
[459.076625] Starting subtest: invalid-signal-illegal-handle
[459.078928] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[459.216984] [063/133] (561s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-render)
[459.319429] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-render
[459.324741] Subtest fbc-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[459.437169] [064/133] (561s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[459.527509] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[460.406918] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.881s)
[460.603766] [065/133] (560s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[460.700718] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc
[467.270046] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.573s)
[467.431752] [066/133] (553s left) dmabuf (all-tests)
[467.476785] Starting subtest: all-tests
[467.479522] Starting dynamic subtest: sanitycheck
[467.618753] Dynamic subtest sanitycheck: SUCCESS (0.143s)
[467.632005] Starting dynamic subtest: dma_fence
[467.892783] Dynamic subtest dma_fence: SUCCESS (0.271s)
[467.906957] Starting dynamic subtest: dma_fence_chain
[476.388995] Dynamic subtest dma_fence_chain: SUCCESS (8.495s)
[476.403043] Starting dynamic subtest: dma_fence_unwrap
[476.542362] Dynamic subtest dma_fence_unwrap: SUCCESS (0.152s)
[476.556651] Starting dynamic subtest: dma_resv
[476.672903] Dynamic subtest dma_resv: SUCCESS (0.128s)
[476.675702] Subtest all-tests: SUCCESS (9.201s)
[476.772269] [067/133] (544s left) perf_pmu (rc6-all-gts)
[476.817571] Starting subtest: rc6-all-gts
[483.178226] Subtest rc6-all-gts: SUCCESS (6.360s)
[484.157292] [068/133] (536s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-blt)
[484.266504] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-blt
[484.270911] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[484.359865] [069/133] (536s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-sf)
[489.379633] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-sf
[489.384944] Subtest fbc-pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.002s)
[490.266803] [070/133] (530s left) kms_cursor_crc (cursor-sliding-64x64)
[490.474630] Starting subtest: cursor-sliding-64x64
[490.477548] Starting dynamic subtest: pipe-A-eDP-1
[493.713856] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.233s)
[493.741525] Starting dynamic subtest: pipe-D-eDP-1
[497.630161] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.894s)
[497.632715] Subtest cursor-sliding-64x64: SUCCESS (7.153s)
[498.506148] [071/133] (522s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-submi
[498.569190] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-submitted
[498.674587] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-submitted: SUCCESS (0.104s)
[498.776682] [072/133] (522s left) kms_psr2_sf (psr2-plane-move-sf-dmg-area)
[503.780651] Starting subtest: psr2-plane-move-sf-dmg-area
[503.783313] Starting dynamic subtest: pipe-A-eDP-1
[509.095735] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.310s)
[509.107039] Starting dynamic subtest: pipe-B-eDP-1
[514.404029] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.308s)
[514.406956] Subtest psr2-plane-move-sf-dmg-area: SUCCESS (10.622s)
[515.299520] [073/133] (506s left) kms_atomic_transition (plane-all-transition-nonblocking-fencing)
[515.407014] Starting subtest: plane-all-transition-nonblocking-fencing
[515.409781] Starting dynamic subtest: pipe-A-eDP-1
[517.903634] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.499s)
[518.228266] Starting dynamic subtest: pipe-B-eDP-1
[521.422273] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.209s)
[521.720907] Subtest plane-all-transition-nonblocking-fencing: SUCCESS (6.313s)
[522.582514] [074/133] (498s left) kms_busy (basic-hang)
[522.686673] Starting subtest: basic-hang
[522.689013] Starting dynamic subtest: flip-pipe-A
[523.391158] Dynamic subtest flip-pipe-A: SUCCESS (0.704s)
[523.401988] Starting dynamic subtest: modeset-pipe-A
[526.773810] Dynamic subtest modeset-pipe-A: SUCCESS (3.382s)
[526.785153] Starting dynamic subtest: flip-pipe-D
[528.001402] Dynamic subtest flip-pipe-D: SUCCESS (1.222s)
[528.012245] Starting dynamic subtest: modeset-pipe-D
[531.388043] Dynamic subtest modeset-pipe-D: SUCCESS (3.389s)
[531.390872] Subtest basic-hang: SUCCESS (8.704s)
[532.218601] [075/133] (489s left) kms_plane_scaling (2x-scaler-multi-pipe)
[532.322666] Starting subtest: 2x-scaler-multi-pipe
[532.328135] Subtest 2x-scaler-multi-pipe: SKIP (0.001s)
[532.450086] [076/133] (489s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt)
[532.545817] Starting subtest: psr-rgb565-draw-mmap-gtt
[532.550823] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[532.667194] [077/133] (488s left) i915_pm_rpm (system-suspend-devices)
[534.396569] Starting subtest: system-suspend-devices
[542.069415] Subtest system-suspend-devices: SUCCESS (7.676s)
[542.472364] [078/133] (479s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[542.584754] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt
[542.589769] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[542.711557] [079/133] (478s left) dumb_buffer (map-invalid-size)
[542.771047] Starting subtest: map-invalid-size
[542.773218] Subtest map-invalid-size: SUCCESS (0.000s)
[542.903077] [080/133] (478s left) kms_rotation_crc (primary-rotation-270)
[543.005938] Starting subtest: primary-rotation-270
[543.010770] Subtest primary-rotation-270: SKIP (0.001s)
[543.106423] [081/133] (478s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[543.196031] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt
[543.200890] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[543.316653] [082/133] (478s left) i915_pm_freq_api (freq-reset)
[543.378515] Starting subtest: freq-reset
[543.380847] Starting dynamic subtest: gt0
[543.582879] Dynamic subtest gt0: SUCCESS (0.202s)
[543.592878] Starting dynamic subtest: gt1
[543.756348] Dynamic subtest gt1: SUCCESS (0.173s)
[543.759046] Subtest freq-reset: SUCCESS (0.377s)
[543.855490] [083/133] (477s left) syncobj_wait (wait-all-complex)
[543.916751] Starting subtest: wait-all-complex
[544.130181] Subtest wait-all-complex: SUCCESS (0.215s)
[544.230367] [084/133] (477s left) kms_async_flips (basic-modeset-with-all-modifiers-formats)
[544.337758] Starting subtest: basic-modeset-with-all-modifiers-formats
[544.712661] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB8888
[545.519915] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB8888: SUCCESS (0.808s)
[545.530783] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB2101010
[545.542018] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB2101010: SUCCESS (0.021s)
[545.552001] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB16161616F
[545.563942] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB16161616F: SUCCESS (0.021s)
[545.574265] Starting dynamic subtest: pipe-A-eDP-1-4-RGB565
[545.731425] Dynamic subtest pipe-A-eDP-1-4-RGB565: SUCCESS (0.166s)
[545.742591] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB8888
[545.753331] Dynamic subtest pipe-A-eDP-1-4-XRGB8888: SUCCESS (0.021s)
[545.763774] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB2101010
[545.775284] Dynamic subtest pipe-A-eDP-1-4-XRGB2101010: SUCCESS (0.021s)
[545.785642] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB16161616F
[545.797646] Dynamic subtest pipe-A-eDP-1-4-XRGB16161616F: SUCCESS (0.022s)
[545.807650] Starting dynamic subtest: pipe-A-eDP-1-4-YUYV
[545.819936] Dynamic subtest pipe-A-eDP-1-4-YUYV: SUCCESS (0.022s)
[545.830185] Starting dynamic subtest: pipe-A-eDP-1-4-Y210
[545.842217] Dynamic subtest pipe-A-eDP-1-4-Y210: SUCCESS (0.022s)
[545.852499] Starting dynamic subtest: pipe-A-eDP-1-4-XYUV8888
[545.863985] Dynamic subtest pipe-A-eDP-1-4-XYUV8888: SUCCESS (0.021s)
[545.873993] Starting dynamic subtest: pipe-A-eDP-1-4-XV30
[545.886475] Dynamic subtest pipe-A-eDP-1-4-XV30: SUCCESS (0.022s)
[545.896664] Starting dynamic subtest: pipe-A-eDP-1-4-XV36
[545.908823] Dynamic subtest pipe-A-eDP-1-4-XV36: SUCCESS (0.022s)
[545.919670] Starting dynamic subtest: pipe-A-eDP-1-x-RGB565
[546.075549] Dynamic subtest pipe-A-eDP-1-x-RGB565: SUCCESS (0.166s)
[546.086146] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB8888
[546.098047] Dynamic subtest pipe-A-eDP-1-x-XRGB8888: SUCCESS (0.022s)
[546.108186] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB2101010
[546.120346] Dynamic subtest pipe-A-eDP-1-x-XRGB2101010: SUCCESS (0.022s)
[546.130756] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB16161616F
[546.142671] Dynamic subtest pipe-A-eDP-1-x-XRGB16161616F: SUCCESS (0.021s)
[546.153046] Starting dynamic subtest: pipe-A-eDP-1-x-YUYV
[546.164616] Dynamic subtest pipe-A-eDP-1-x-YUYV: SUCCESS (0.021s)
[546.175692] Starting dynamic subtest: pipe-A-eDP-1-x-Y210
[546.186906] Dynamic subtest pipe-A-eDP-1-x-Y210: SUCCESS (0.022s)
[546.196802] Starting dynamic subtest: pipe-A-eDP-1-x-XYUV8888
[546.209689] Dynamic subtest pipe-A-eDP-1-x-XYUV8888: SUCCESS (0.022s)
[546.226903] Starting dynamic subtest: pipe-A-eDP-1-x-XV30
[546.233805] Dynamic subtest pipe-A-eDP-1-x-XV30: SUCCESS (0.020s)
[546.244939] Starting dynamic subtest: pipe-A-eDP-1-x-XV36
[546.253807] Dynamic subtest pipe-A-eDP-1-x-XV36: SUCCESS (0.022s)
[546.264630] Starting dynamic subtest: pipe-A-eDP-1-linear-RGB565
[546.420601] Dynamic subtest pipe-A-eDP-1-linear-RGB565: SUCCESS (0.165s)
[546.431815] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB8888
[546.442770] Dynamic subtest pipe-A-eDP-1-linear-XRGB8888: SUCCESS (0.021s)
[546.452987] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB2101010
[546.465145] Dynamic subtest pipe-A-eDP-1-linear-XRGB2101010: SUCCESS (0.022s)
[546.475996] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB16161616F
[546.487453] Dynamic subtest pipe-A-eDP-1-linear-XRGB16161616F: SUCCESS (0.022s)
[546.497713] Starting dynamic subtest: pipe-A-eDP-1-linear-YUYV
[546.509166] Dynamic subtest pipe-A-eDP-1-linear-YUYV: SUCCESS (0.021s)
[546.519517] Starting dynamic subtest: pipe-A-eDP-1-linear-Y210
[546.531354] Dynamic subtest pipe-A-eDP-1-linear-Y210: SUCCESS (0.021s)
[546.541300] Starting dynamic subtest: pipe-A-eDP-1-linear-XYUV8888
[546.553600] Dynamic subtest pipe-A-eDP-1-linear-XYUV8888: SUCCESS (0.022s)
[546.563425] Starting dynamic subtest: pipe-A-eDP-1-linear-XV30
[546.575757] Dynamic subtest pipe-A-eDP-1-linear-XV30: SUCCESS (0.022s)
[546.585634] Starting dynamic subtest: pipe-A-eDP-1-linear-XV36
[546.598419] Dynamic subtest pipe-A-eDP-1-linear-XV36: SUCCESS (0.021s)
[546.983495] Subtest basic-modeset-with-all-modifiers-formats: SUCCESS (2.648s)
[547.826509] [085/133] (474s left) api_intel_bb (intel-bb-blit-y)
[547.891030] Starting subtest: intel-bb-blit-y
[551.403724] Subtest intel-bb-blit-y: SUCCESS (3.514s)
[551.570165] [086/133] (470s left) gem_basic (create-close)
[551.627017] Starting subtest: create-close
[551.632290] Subtest create-close: SUCCESS (0.000s)
[551.770505] [087/133] (470s left) api_intel_allocator (reopen)
[551.835884] Starting subtest: reopen
[551.841432] Subtest reopen: SUCCESS (0.008s)
[552.008354] [088/133] (469s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[552.132518] Starting subtest: basic-flip-before-cursor-legacy
[553.045882] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.914s)
[553.955816] [089/133] (468s left) gem_render_copy (y-tiled-to-vebox-yf-tiled)
[554.019819] Starting subtest: y-tiled-to-vebox-yf-tiled
[554.024679] Subtest y-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[554.232915] [090/133] (467s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-plflip-blt)
[554.330479] Starting subtest: psr-1p-primscrn-indfb-plflip-blt
[561.725056] Subtest psr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.397s)
[561.898376] [091/133] (460s left) gem_exec_flush (basic-wb-ro-before-default)
[561.972370] Starting subtest: basic-wb-ro-before-default
[567.436024] Subtest basic-wb-ro-before-default: SUCCESS (5.466s)
[567.653491] [092/133] (454s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[567.756405] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[567.761708] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[567.873343] [093/133] (454s left) kms_big_fb (yf-tiled-16bpp-rotate-270)
[567.981954] Starting subtest: yf-tiled-16bpp-rotate-270
[567.987459] Subtest yf-tiled-16bpp-rotate-270: SKIP (0.000s)
[568.118587] [094/133] (454s left) gem_exec_schedule (preempt-other)
[568.197781] Starting subtest: preempt-other
[568.200505] Starting dynamic subtest: rcs0
[568.257729] Dynamic subtest rcs0: SUCCESS (0.071s)
[568.268647] Starting dynamic subtest: bcs0
[568.326429] Dynamic subtest bcs0: SUCCESS (0.068s)
[568.338989] Starting dynamic subtest: vcs0
[568.388885] Dynamic subtest vcs0: SUCCESS (0.062s)
[568.401699] Starting dynamic subtest: vcs1
[568.453008] Dynamic subtest vcs1: SUCCESS (0.063s)
[568.467747] Starting dynamic subtest: vecs0
[568.518404] Dynamic subtest vecs0: SUCCESS (0.065s)
[568.529993] Starting dynamic subtest: ccs0
[568.583709] Dynamic subtest ccs0: SUCCESS (0.066s)
[568.585829] Subtest preempt-other: SUCCESS (0.398s)
[570.378645] [095/133] (451s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-signaled)
[570.440009] Starting subtest: multi-wait-for-submit-available-unsubmitted-signaled
[570.442922] Subtest multi-wait-for-submit-available-unsubmitted-signaled: SUCCESS (0.000s)
[570.580238] [096/133] (451s left) gem_exec_schedule (u-fairslice-all)
[570.658773] Starting subtest: u-fairslice-all
[572.744531] Subtest u-fairslice-all: SUCCESS (2.095s)
[574.254829] [097/133] (448s left) kms_invalid_mode (int-max-clock)
[574.356768] Starting subtest: int-max-clock
[574.359488] Starting dynamic subtest: pipe-A-eDP-1
[574.638504] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.285s)
[574.650314] Starting dynamic subtest: pipe-B-eDP-1
[574.652769] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[574.663254] Starting dynamic subtest: pipe-C-eDP-1
[574.665863] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[574.676373] Starting dynamic subtest: pipe-D-eDP-1
[574.678884] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[574.681458] Subtest int-max-clock: SUCCESS (0.291s)
[575.506702] [098/133] (446s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[575.570820] Subtest cpuset-basic-small-copy-odd: SKIP (0.000s)
[575.648020] [099/133] (446s left) gem_mmap_wc (write-read-distinct)
[575.726582] Subtest write-read-distinct: SKIP (0.000s)
[575.796800] [100/133] (446s left) kms_cursor_crc (cursor-rapid-movement-128x42)
[576.028997] Starting subtest: cursor-rapid-movement-128x42
[576.076737] Subtest cursor-rapid-movement-128x42: SKIP (0.042s)
[576.326448] [101/133] (446s left) kms_scaling_modes (scaling-mode-center)
[576.413864] Starting subtest: scaling-mode-center
[576.416298] Starting dynamic subtest: pipe-A-eDP-1
[576.509198] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.096s)
[576.519829] Starting dynamic subtest: pipe-B-eDP-1
[577.715169] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.204s)
[577.734894] Starting dynamic subtest: pipe-C-eDP-1
[578.822258] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.104s)
[578.834543] Starting dynamic subtest: pipe-D-eDP-1
[579.949374] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.126s)
[579.952029] Subtest scaling-mode-center: SUCCESS (3.538s)
[581.137804] [102/133] (441s left) gem_eio (in-flight-contexts-immediate)
[581.387524] Starting subtest: in-flight-contexts-immediate
[595.233054] Subtest in-flight-contexts-immediate: SUCCESS (13.847s)
[595.437677] [103/133] (427s left) gem_partial_pwrite_pread (write-snoop)
[595.503988] Subtest write-snoop: SKIP (0.000s)
[595.569593] [104/133] (427s left) syncobj_wait (single-wait-for-submit-unsubmitted)
[595.633873] Starting subtest: single-wait-for-submit-unsubmitted
[595.738990] Subtest single-wait-for-submit-unsubmitted: SUCCESS (0.104s)
[595.838602] [105/133] (426s left) gem_mmap_wc (fault-concurrent)
[595.932918] Subtest fault-concurrent: SKIP (0.000s)
[595.999757] [106/133] (426s left) kms_pm_dc (dc9-dpms)
[597.100942] Starting subtest: dc9-dpms
[598.515057] Subtest dc9-dpms: SUCCESS (1.413s)
[598.603678] [107/133] (424s left) kms_frontbuffer_tracking (fbc-rgb565-draw-pwrite)
[598.708955] Starting subtest: fbc-rgb565-draw-pwrite
[603.263354] Subtest fbc-rgb565-draw-pwrite: SUCCESS (4.558s)
[604.396134] [108/133] (418s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions-varying-size)
[604.526669] Starting subtest: short-flip-before-cursor-atomic-transitions-varying-size
[605.483554] Subtest short-flip-before-cursor-atomic-transitions-varying-size: SUCCESS (0.958s)
[606.318356] [109/133] (416s left) gem_flink_basic (double-flink)
[606.376389] Starting subtest: double-flink
[606.379008] Subtest double-flink: SUCCESS (0.000s)
[606.513206] [110/133] (416s left) gem_ctx_exec (basic-close-race)
[606.577691] Starting subtest: basic-close-race
[612.342832] Subtest basic-close-race: SUCCESS (5.765s)
[612.512409] [111/133] (410s left) kms_ccs (bad-pixel-format-yf-tiled-ccs)
[612.623441] Starting subtest: bad-pixel-format-yf-tiled-ccs
[612.625757] Starting dynamic subtest: pipe-A-eDP-1
[612.648743] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[612.659444] Starting dynamic subtest: pipe-B-eDP-1
[612.682141] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[612.692013] Starting dynamic subtest: pipe-C-eDP-1
[612.714340] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[612.724788] Starting dynamic subtest: pipe-D-eDP-1
[612.752409] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[612.757202] Subtest bad-pixel-format-yf-tiled-ccs: SKIP (0.004s)
[614.793199] [112/133] (408s left) kms_cursor_crc (cursor-onscreen-64x64)
[614.996785] Starting subtest: cursor-onscreen-64x64
[614.999794] Starting dynamic subtest: pipe-A-eDP-1
[617.143906] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.145s)
[617.174861] Starting dynamic subtest: pipe-D-eDP-1
[620.009522] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.843s)
[620.011923] Subtest cursor-onscreen-64x64: SUCCESS (5.014s)
[620.867098] [113/133] (402s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-downscaling)
[620.972385] Starting subtest: flip-64bpp-linear-to-32bpp-linear-downscaling
[620.974696] Starting dynamic subtest: pipe-A-default-mode
[622.102022] Dynamic subtest pipe-A-default-mode: SKIP (1.128s)
[622.107394] Subtest flip-64bpp-linear-to-32bpp-linear-downscaling: SKIP (1.130s)
[622.297382] [114/133] (400s left) gem_wait (wait)
[622.366707] Starting subtest: wait
[622.369307] Starting dynamic subtest: all-engines
[624.270577] Dynamic subtest all-engines: SUCCESS (1.902s)
[624.285731] Starting dynamic subtest: rcs0
[625.321947] Dynamic subtest rcs0: SUCCESS (1.050s)
[625.336377] Starting dynamic subtest: bcs0
[626.373023] Dynamic subtest bcs0: SUCCESS (1.049s)
[626.387050] Starting dynamic subtest: vcs0
[627.421186] Dynamic subtest vcs0: SUCCESS (1.046s)
[627.435044] Starting dynamic subtest: vcs1
[628.469327] Dynamic subtest vcs1: SUCCESS (1.046s)
[628.483179] Starting dynamic subtest: vecs0
[629.519175] Dynamic subtest vecs0: SUCCESS (1.048s)
[629.532644] Starting dynamic subtest: ccs0
[630.572016] Dynamic subtest ccs0: SUCCESS (1.051s)
[630.575003] Subtest wait: SUCCESS (8.207s)
[630.810441] [115/133] (392s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-onoff)
[630.913757] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-onoff
[630.919010] Subtest fbcpsr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[631.047783] [116/133] (392s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[631.155025] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[631.160397] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[631.288393] [117/133] (391s left) sriov_basic (enable-vfs-autoprobe-off)
[631.348019] Starting subtest: enable-vfs-autoprobe-off
[631.350888] Starting dynamic subtest: numvfs-1
[631.438934] Dynamic subtest numvfs-1: FAIL (0.006s)
[631.450050] Starting dynamic subtest: numvfs-2
[631.535615] Dynamic subtest numvfs-2: FAIL (0.004s)
[631.547983] Starting dynamic subtest: numvfs-3
[631.635415] Dynamic subtest numvfs-3: FAIL (0.003s)
[631.646503] Starting dynamic subtest: numvfs-4
[631.732802] Dynamic subtest numvfs-4: FAIL (0.003s)
[631.744841] Starting dynamic subtest: numvfs-5
[631.819713] Dynamic subtest numvfs-5: FAIL (0.004s)
[631.830989] Starting dynamic subtest: numvfs-6
[631.906346] Dynamic subtest numvfs-6: FAIL (0.004s)
[631.917684] Starting dynamic subtest: numvfs-7
[631.994691] Dynamic subtest numvfs-7: FAIL (0.003s)
[632.005676] Starting dynamic subtest: numvfs-random
[632.080275] Dynamic subtest numvfs-random: FAIL (0.004s)
[632.092027] Starting dynamic subtest: numvfs-all
[632.164646] Dynamic subtest numvfs-all: FAIL (0.577s)
[632.166813] Subtest enable-vfs-autoprobe-off: FAIL (0.616s)
[632.206231] [118/133] (391s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf)
[637.247801] Starting subtest: fbc-psr2-overlay-plane-update-continuous-sf
[637.250698] Starting dynamic subtest: pipe-A-eDP-1
[638.034570] Dynamic subtest pipe-A-eDP-1: SKIP (0.785s)
[638.045134] Starting dynamic subtest: pipe-B-eDP-1
[639.174518] Dynamic subtest pipe-B-eDP-1: SKIP (1.138s)
[639.178890] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (1.926s)
[640.388292] [119/133] (382s left) kms_plane (pixel-format-source-clamping)
[640.497574] Starting subtest: pixel-format-source-clamping
[640.522795] Starting dynamic subtest: pipe-A-plane-0
[645.600642] Dynamic subtest pipe-A-plane-0: SUCCESS (5.078s)
[645.611552] Starting dynamic subtest: pipe-A-plane-3
[648.045534] Dynamic subtest pipe-A-plane-3: SUCCESS (2.442s)
[648.056306] Starting dynamic subtest: pipe-A-plane-5
[648.058676] Dynamic subtest pipe-A-plane-5: SUCCESS (0.000s)
[649.157054] Starting dynamic subtest: pipe-B-plane-0
[654.669646] Dynamic subtest pipe-B-plane-0: SUCCESS (5.522s)
[654.682672] Starting dynamic subtest: pipe-B-plane-3
[657.247023] Dynamic subtest pipe-B-plane-3: SUCCESS (2.577s)
[657.257426] Starting dynamic subtest: pipe-B-plane-5
[657.259860] Dynamic subtest pipe-B-plane-5: SUCCESS (0.000s)
[657.555821] Subtest pixel-format-source-clamping: SUCCESS (17.061s)
[658.443219] [120/133] (364s left) gem_ctx_exec (basic-nohangcheck)
[658.518802] Starting subtest: basic-nohangcheck
[658.767151] Subtest basic-nohangcheck: SUCCESS (0.251s)
[658.931915] [121/133] (364s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-move)
[659.045408] Starting subtest: psr-2p-scndscrn-cur-indfb-move
[659.050593] Subtest psr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[659.175849] [122/133] (364s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-msflip-blt)
[659.272303] Starting subtest: fbcpsr-1p-primscrn-indfb-msflip-blt
[666.591801] Subtest fbcpsr-1p-primscrn-indfb-msflip-blt: SUCCESS (7.321s)
[666.794886] [123/133] (356s left) kms_big_fb (y-tiled-16bpp-rotate-270)
[666.901028] Starting subtest: y-tiled-16bpp-rotate-270
[666.906242] Subtest y-tiled-16bpp-rotate-270: SKIP (0.000s)
[667.034490] [124/133] (356s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-render)
[667.142140] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-render
[674.011621] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.873s)
[674.185709] [125/133] (349s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[674.282914] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt
[674.287455] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[674.413810] [126/133] (349s left) gem_mmap_gtt (basic-write-read)
[674.475616] Subtest basic-write-read: SKIP (0.000s)
[674.535099] [127/133] (349s left) kms_chamelium_frames (dp-crc-single)
[674.917126] Subtest dp-crc-single: SKIP (0.000s)
[675.824545] [128/133] (347s left) gem_exec_balancer (full-late)
[675.905892] Starting subtest: full-late
[681.096841] Subtest full-late: SUCCESS (5.191s)
[681.452331] [129/133] (342s left) kms_plane_scaling (plane-upscale-20x20-with-modifiers)
[681.553741] Starting subtest: plane-upscale-20x20-with-modifiers
[681.556426] Starting dynamic subtest: pipe-A
[681.888881] Dynamic subtest pipe-A: SUCCESS (0.338s)
[681.900593] Starting dynamic subtest: pipe-B
[683.296029] Dynamic subtest pipe-B: SUCCESS (1.407s)
[683.306607] Starting dynamic subtest: pipe-C
[684.738769] Dynamic subtest pipe-C: SUCCESS (1.442s)
[684.749942] Starting dynamic subtest: pipe-D
[686.156744] Dynamic subtest pipe-D: SUCCESS (1.417s)
[686.159625] Subtest plane-upscale-20x20-with-modifiers: SUCCESS (4.608s)
[687.349317] [130/133] (336s left) gem_lmem_swapping (heavy-multi)
[687.397042] Subtest heavy-multi: SKIP (0.000s)
[687.444800] [131/133] (336s left) perf_pmu (enable-race)
[687.484430] Starting subtest: enable-race
[687.487073] Starting dynamic subtest: rcs0
[700.274006] Dynamic subtest rcs0: SUCCESS (12.792s)
[700.287310] Starting dynamic subtest: bcs0
[713.020950] Dynamic subtest bcs0: SUCCESS (12.746s)
[713.032291] Starting dynamic subtest: vcs0
[728.270414] Dynamic subtest vcs0: SUCCESS (15.247s)
[728.283355] Starting dynamic subtest: vcs1
[744.272209] Dynamic subtest vcs1: SUCCESS (16.001s)
[744.286201] Starting dynamic subtest: vecs0
[760.206696] Dynamic subtest vecs0: SUCCESS (15.933s)
[760.228357] Starting dynamic subtest: ccs0
[772.960013] Dynamic subtest ccs0: SUCCESS (12.752s)
[772.962902] Subtest enable-race: SUCCESS (85.485s)
[773.099793] [132/133] (250s left) kms_chamelium_audio (hdmi-audio-edid)
[773.496990] Subtest hdmi-audio-edid: SKIP (0.000s)
[774.309757] [133/133] (249s left) gem_exec_basic (basic)
[774.383012] Starting subtest: basic
[774.386088] Starting dynamic subtest: rcs0-smem
[774.388713] Dynamic subtest rcs0-smem: SUCCESS (0.004s)
[774.408237] Starting dynamic subtest: bcs0-smem
[774.410836] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[774.422807] Starting dynamic subtest: vcs0-smem
[774.425017] Dynamic subtest vcs0-smem: SUCCESS (0.003s)
[774.434992] Starting dynamic subtest: vcs1-smem
[774.437521] Dynamic subtest vcs1-smem: SUCCESS (0.002s)
[774.447908] Starting dynamic subtest: vecs0-smem
[774.450436] Dynamic subtest vecs0-smem: SUCCESS (0.003s)
[774.460805] Starting dynamic subtest: ccs0-smem
[774.463008] Dynamic subtest ccs0-smem: SUCCESS (0.002s)
[774.465458] Subtest basic: SUCCESS (0.021s)
[776.266416] Closing watchdogs
results: parsing output: 0/ for test: kms_rotation_crc
results: parsing output: 1/ for test: vgem_basic
results: parsing output: 2/ for test: gem_pxp
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: syncobj_basic
results: parsing output: 5/ for test: gen9_exec_parse
results: parsing output: 6/ for test: kms_flip
results: parsing output: 7/ for test: kms_psr
results: parsing output: 8/ for test: kms_ccs
results: parsing output: 9/ for test: kms_content_protection
results: parsing output: 10/ for test: kms_psr
results: parsing output: 11/ for test: syncobj_wait
results: parsing output: 12/ for test: gem_mmap_gtt
results: parsing output: 13/ for test: syncobj_timeline
results: parsing output: 14/ for test: kms_pm_rpm
results: parsing output: 15/ for test: kms_cursor_legacy
results: parsing output: 16/ for test: gem_spin_batch
results: parsing output: 17/ for test: vgem_slow
results: parsing output: 18/ for test: kms_getfb
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: gem_exec_fence
results: parsing output: 21/ for test: kms_ccs
results: parsing output: 22/ for test: gem_exec_params
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: kms_feature_discovery
results: parsing output: 25/ for test: gem_request_retire
results: parsing output: 26/ for test: sysfs_preempt_timeout
results: parsing output: 27/ for test: i915_drm_fdinfo
results: parsing output: 28/ for test: kms_big_fb
results: parsing output: 29/ for test: kms_plane_alpha_blend
results: parsing output: 30/ for test: gem_exec_alignment
results: parsing output: 31/ for test: sw_sync
results: parsing output: 32/ for test: kms_flip_scaled_crc
results: parsing output: 33/ for test: kms_color_pipeline
results: parsing output: 34/ for test: i915_query
results: parsing output: 35/ for test: kms_addfb_basic
results: parsing output: 36/ for test: kms_psr
results: parsing output: 37/ for test: syncobj_basic
results: parsing output: 38/ for test: gem_ctx_persistence
results: parsing output: 39/ for test: kms_pipe_crc_basic
results: parsing output: 40/ for test: kms_cursor_legacy
results: parsing output: 41/ for test: gem_partial_pwrite_pread
results: parsing output: 42/ for test: gem_mmap_gtt
results: parsing output: 43/ for test: gem_exec_reloc
results: parsing output: 44/ for test: gem_pread
results: parsing output: 45/ for test: kms_chamelium_hpd
results: parsing output: 46/ for test: gem_exec_schedule
results: parsing output: 47/ for test: kms_vrr
results: parsing output: 48/ for test: kms_ccs
results: parsing output: 49/ for test: gem_pxp
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: gem_exec_whisper
results: parsing output: 52/ for test: gem_exec_fence
results: parsing output: 53/ for test: kms_rotation_crc
results: parsing output: 54/ for test: syncobj_timeline
results: parsing output: 55/ for test: gem_exec_flush
results: parsing output: 56/ for test: kms_psr
results: parsing output: 57/ for test: kms_vblank
results: parsing output: 58/ for test: kms_big_fb
results: parsing output: 59/ for test: kms_psr
results: parsing output: 60/ for test: kms_async_flips
results: parsing output: 61/ for test: syncobj_wait
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_big_fb
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: dmabuf
results: parsing output: 66/ for test: perf_pmu
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_psr2_sf
results: parsing output: 69/ for test: kms_cursor_crc
results: parsing output: 70/ for test: syncobj_timeline
results: parsing output: 71/ for test: kms_psr2_sf
results: parsing output: 72/ for test: kms_atomic_transition
results: parsing output: 73/ for test: kms_busy
results: parsing output: 74/ for test: kms_plane_scaling
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: i915_pm_rpm
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: dumb_buffer
results: parsing output: 79/ for test: kms_rotation_crc
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: i915_pm_freq_api
results: parsing output: 82/ for test: syncobj_wait
results: parsing output: 83/ for test: kms_async_flips
results: parsing output: 84/ for test: api_intel_bb
results: parsing output: 85/ for test: gem_basic
results: parsing output: 86/ for test: api_intel_allocator
results: parsing output: 87/ for test: kms_cursor_legacy
results: parsing output: 88/ for test: gem_render_copy
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: gem_exec_flush
results: parsing output: 91/ for test: kms_big_fb
results: parsing output: 92/ for test: kms_big_fb
results: parsing output: 93/ for test: gem_exec_schedule
results: parsing output: 94/ for test: syncobj_timeline
results: parsing output: 95/ for test: gem_exec_schedule
results: parsing output: 96/ for test: kms_invalid_mode
results: parsing output: 97/ for test: gem_mmap_gtt
results: parsing output: 98/ for test: gem_mmap_wc
results: parsing output: 99/ for test: kms_cursor_crc
results: parsing output: 100/ for test: kms_scaling_modes
results: parsing output: 101/ for test: gem_eio
results: parsing output: 102/ for test: gem_partial_pwrite_pread
results: parsing output: 103/ for test: syncobj_wait
results: parsing output: 104/ for test: gem_mmap_wc
results: parsing output: 105/ for test: kms_pm_dc
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_cursor_legacy
results: parsing output: 108/ for test: gem_flink_basic
results: parsing output: 109/ for test: gem_ctx_exec
results: parsing output: 110/ for test: kms_ccs
results: parsing output: 111/ for test: kms_cursor_crc
results: parsing output: 112/ for test: kms_flip_scaled_crc
results: parsing output: 113/ for test: gem_wait
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: sriov_basic
results: parsing output: 117/ for test: kms_psr2_sf
results: parsing output: 118/ for test: kms_plane
results: parsing output: 119/ for test: gem_ctx_exec
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_big_fb
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: gem_mmap_gtt
results: parsing output: 126/ for test: kms_chamelium_frames
results: parsing output: 127/ for test: gem_exec_balancer
results: parsing output: 128/ for test: kms_plane_scaling
results: parsing output: 129/ for test: gem_lmem_swapping
results: parsing output: 130/ for test: perf_pmu
results: parsing output: 131/ for test: kms_chamelium_audio
results: parsing output: 132/ for test: gem_exec_basic
Done.