[57.452776] Initializing watchdogs
[57.452897]   /dev/watchdog0
[57.477400] [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.481556] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.483670] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.487154] [001/132] (960s left) kms_cursor_crc (cursor-random-128x42)
[57.734087] Starting subtest: cursor-random-128x42
[57.787529] Subtest cursor-random-128x42: SKIP (0.046s)
[58.029412] [002/132] (959s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-cpu)
[58.141914] Starting subtest: fbcpsr-rgb565-draw-mmap-cpu
[64.974096] Subtest fbcpsr-rgb565-draw-mmap-cpu: SUCCESS (6.816s)
[65.121067] [003/132] (952s left) gem_exec_schedule (preemptive-hang)
[65.317038] Starting subtest: preemptive-hang
[65.319133] Starting dynamic subtest: rcs0
[75.011072] Dynamic subtest rcs0: SUCCESS (9.688s)
[75.018583] Starting dynamic subtest: bcs0
[84.029030] Dynamic subtest bcs0: SUCCESS (9.014s)
[84.038722] Starting dynamic subtest: vcs0
[93.055518] Dynamic subtest vcs0: SUCCESS (9.028s)
[93.062753] Starting dynamic subtest: vcs1
[102.019707] Dynamic subtest vcs1: SUCCESS (8.957s)
[102.027019] Starting dynamic subtest: vecs0
[111.044155] Dynamic subtest vecs0: SUCCESS (9.022s)
[111.052704] Starting dynamic subtest: ccs0
[120.066133] Dynamic subtest ccs0: SUCCESS (9.023s)
[120.068511] Subtest preemptive-hang: SUCCESS (54.736s)
[120.352778] [004/132] (897s left) kms_psr (psr2-primary-mmap-gtt)
[120.449323] Starting subtest: psr2-primary-mmap-gtt
[120.451727] Starting dynamic subtest: eDP-1
[121.917337] Dynamic subtest eDP-1: SKIP (1.464s)
[121.922215] Subtest psr2-primary-mmap-gtt: SKIP (1.466s)
[122.067510] [005/132] (896s left) kms_cursor_legacy (2x-long-flip-vs-cursor-legacy)
[122.192158] Starting subtest: 2x-long-flip-vs-cursor-legacy
[122.196872] Subtest 2x-long-flip-vs-cursor-legacy: SKIP (0.001s)
[122.358182] [006/132] (895s left) syncobj_timeline (etime-multi-wait-all-submitted-signaled)
[122.405132] Starting subtest: etime-multi-wait-all-submitted-signaled
[122.514231] Subtest etime-multi-wait-all-submitted-signaled: SUCCESS (0.108s)
[122.611486] [007/132] (895s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen)
[122.713496] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-fullscreen
[129.289221] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (6.579s)
[129.444073] [008/132] (888s left) perf_pmu (all-busy-check-all)
[129.490195] Starting subtest: all-busy-check-all
[130.838884] Subtest all-busy-check-all: SUCCESS (1.347s)
[130.969477] [009/132] (887s left) gem_exec_balancer (parallel-out-fence)
[131.230267] Starting subtest: parallel-out-fence
[132.258028] Subtest parallel-out-fence: SUCCESS (1.028s)
[132.407684] [010/132] (885s left) gem_bad_reloc (negative-reloc-bltcopy)
[132.476721] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[132.567078] [011/132] (885s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[132.667329] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[132.669509] Starting dynamic subtest: pipe-A-eDP-1
[133.572906] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.897s)
[133.582203] Starting dynamic subtest: pipe-B-eDP-1
[135.246891] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.670s)
[135.262693] Starting dynamic subtest: pipe-C-eDP-1
[136.717890] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.471s)
[136.733873] Starting dynamic subtest: pipe-D-eDP-1
[138.267358] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.550s)
[138.269563] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.591s)
[139.458765] [012/132] (878s left) gem_ctx_freq (sysfs)
[139.527102] Starting subtest: sysfs
[139.529561] Starting dynamic subtest: gt0
[145.072910] Dynamic subtest gt0: SUCCESS (5.545s)
[145.082959] Starting dynamic subtest: gt1
[186.901469] Dynamic subtest gt1: SUCCESS (41.813s)
[186.904388] Subtest sysfs: SUCCESS (47.359s)
[187.055724] [013/132] (831s left) kms_vblank (wait-idle-hang)
[187.163982] Starting subtest: wait-idle-hang
[187.166301] Starting dynamic subtest: pipe-A-eDP-1
[213.718861] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.549s)
[213.733754] Starting dynamic subtest: pipe-D-eDP-1
[228.688562] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.968s)
[228.690988] Subtest wait-idle-hang: SUCCESS (41.521s)
[229.528218] [014/132] (788s left) kms_ccs (random-ccs-data-y-tiled-ccs)
[229.630379] Starting subtest: random-ccs-data-y-tiled-ccs
[229.632715] Starting dynamic subtest: pipe-A-eDP-1
[229.658423] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[229.668438] Starting dynamic subtest: pipe-B-eDP-1
[229.691986] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[229.699799] Starting dynamic subtest: pipe-C-eDP-1
[229.723740] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[229.731595] Starting dynamic subtest: pipe-D-eDP-1
[229.755809] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[229.760220] Subtest random-ccs-data-y-tiled-ccs: SKIP (0.003s)
[231.891297] [015/132] (786s left) syncobj_wait (invalid-signal-zero-handles)
[231.962318] Starting subtest: invalid-signal-zero-handles
[231.964746] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[232.098569] [016/132] (786s left) i915_drm_fdinfo (busy)
[232.136561] Starting subtest: busy
[232.138643] Starting dynamic subtest: rcs0
[232.162051] Dynamic subtest rcs0: SKIP (0.024s)
[232.172548] Starting dynamic subtest: bcs0
[232.176892] Dynamic subtest bcs0: SKIP (0.000s)
[232.184672] Starting dynamic subtest: vcs0
[232.189104] Dynamic subtest vcs0: SKIP (0.000s)
[232.196481] Starting dynamic subtest: vcs1
[232.200575] Dynamic subtest vcs1: SKIP (0.000s)
[232.208115] Starting dynamic subtest: vecs0
[232.212140] Dynamic subtest vecs0: SKIP (0.000s)
[232.219613] Starting dynamic subtest: ccs0
[232.223711] Dynamic subtest ccs0: SKIP (0.000s)
[232.227786] Subtest busy: SKIP (0.029s)
[232.263044] [017/132] (786s left) gem_ctx_param (non-root-set)
[232.311740] Starting subtest: non-root-set
[232.333291] Subtest non-root-set: SUCCESS (0.022s)
[232.444343] [018/132] (786s left) gem_partial_pwrite_pread (reads-display)
[232.504293] Subtest reads-display: SKIP (0.000s)
[232.569326] [019/132] (786s left) kms_flip (2x-flip-vs-expired-vblank-interruptible)
[232.673839] Starting subtest: 2x-flip-vs-expired-vblank-interruptible
[232.683973] Subtest 2x-flip-vs-expired-vblank-interruptible: SKIP (0.010s)
[232.780787] [020/132] (785s left) fbdev (write)
[232.832697] Starting subtest: write
[233.189532] Subtest write: SUCCESS (0.356s)
[233.272462] [021/132] (785s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-pwrite)
[233.377863] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-pwrite
[240.523276] Subtest fbc-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (7.148s)
[241.681246] [022/132] (777s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-render)
[241.781366] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-render
[241.785806] Subtest psr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[241.919592] [023/132] (776s left) gem_exec_reloc (basic-cpu)
[241.984169] Subtest basic-cpu: SKIP (0.000s)
[242.060466] [024/132] (776s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-onoff)
[242.180758] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-onoff
[242.185075] Subtest fbcpsr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[242.304422] [025/132] (776s left) syncobj_wait (invalid-reset-bad-pad)
[242.365858] Starting subtest: invalid-reset-bad-pad
[242.367767] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[242.491230] [026/132] (776s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc)
[242.579203] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc
[242.581051] Starting dynamic subtest: pipe-A-eDP-1
[242.599853] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[242.607973] Starting dynamic subtest: pipe-B-eDP-1
[242.625673] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[242.633251] Starting dynamic subtest: pipe-C-eDP-1
[242.650359] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[242.657912] Starting dynamic subtest: pipe-D-eDP-1
[242.675677] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[242.679169] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc: SKIP (0.005s)
[244.836070] [027/132] (774s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[244.938368] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[245.706971] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.770s)
[245.912489] [028/132] (773s left) kms_lease (lease-invalid-crtc)
[246.000712] Starting subtest: lease-invalid-crtc
[246.003077] Starting dynamic subtest: pipe-A-eDP-1
[246.005180] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[246.017203] Starting dynamic subtest: pipe-B-eDP-1
[246.019467] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[246.029835] Starting dynamic subtest: pipe-C-eDP-1
[246.031687] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[246.039193] Starting dynamic subtest: pipe-D-eDP-1
[246.041072] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[246.042932] Subtest lease-invalid-crtc: SUCCESS (0.003s)
[246.103045] [029/132] (772s left) gem_create (create-ext-placement-all)
[246.278153] Starting subtest: create-ext-placement-all
[246.280255] Subtest create-ext-placement-all: SUCCESS (0.000s)
[246.402943] [030/132] (772s left) kms_properties (invalid-properties-legacy)
[246.499250] Starting subtest: invalid-properties-legacy
[246.600760] Subtest invalid-properties-legacy: SUCCESS (0.102s)
[246.692265] [031/132] (772s left) kms_rotation_crc (primary-yf-tiled-reflect-x-270)
[246.777236] Starting subtest: primary-yf-tiled-reflect-x-270
[246.781114] Subtest primary-yf-tiled-reflect-x-270: SKIP (0.001s)
[246.872270] [032/132] (772s left) gem_pread (snoop)
[246.927040] Subtest snoop: SKIP (0.000s)
[246.993865] [033/132] (772s left) kms_big_fb (y-tiled-16bpp-rotate-180)
[247.100426] Starting subtest: y-tiled-16bpp-rotate-180
[247.104437] Subtest y-tiled-16bpp-rotate-180: SKIP (0.000s)
[247.224685] [034/132] (772s left) gem_render_copy (x-tiled-to-vebox-yf-tiled)
[247.300707] Starting subtest: x-tiled-to-vebox-yf-tiled
[247.305449] Subtest x-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[247.511811] [035/132] (771s left) gem_set_tiling_vs_gtt
[247.621454] [036/132] (771s left) kms_psr (fbc-psr-cursor-blt)
[247.709465] Starting subtest: fbc-psr-cursor-blt
[247.711371] Starting dynamic subtest: eDP-1
[247.715465] Dynamic subtest eDP-1: SKIP (0.000s)
[247.719557] Subtest fbc-psr-cursor-blt: SKIP (0.001s)
[247.803278] [037/132] (771s left) perf_pmu (gt-awake)
[247.852506] Starting subtest: gt-awake
[256.919397] Subtest gt-awake: SUCCESS (9.067s)
[257.043118] [038/132] (762s left) kms_cursor_legacy (flip-vs-cursor-atomic)
[257.171197] Starting subtest: flip-vs-cursor-atomic
[264.170918] Subtest flip-vs-cursor-atomic: SUCCESS (7.000s)
[265.071840] [039/132] (754s left) kms_big_fb (x-tiled-addfb-size-overflow)
[265.178194] Starting subtest: x-tiled-addfb-size-overflow
[265.180074] Subtest x-tiled-addfb-size-overflow: SUCCESS (0.000s)
[265.307918] [040/132] (754s left) gem_eio (wait-10ms)
[265.503493] Starting subtest: wait-10ms
[265.783295] Subtest wait-10ms: SUCCESS (0.279s)
[265.920116] [041/132] (753s left) kms_big_fb (4-tiled-16bpp-rotate-0)
[266.031706] Starting subtest: 4-tiled-16bpp-rotate-0
[267.265319] Subtest 4-tiled-16bpp-rotate-0: SUCCESS (1.233s)
[267.426297] [042/132] (752s left) syncobj_timeline (reset-multiple-signaled)
[267.483125] Starting subtest: reset-multiple-signaled
[267.485078] Subtest reset-multiple-signaled: SUCCESS (0.002s)
[267.610831] [043/132] (751s left) i915_pm_rc6_residency (rc6-fence)
[267.662156] Starting subtest: rc6-fence
[267.691747] Starting dynamic subtest: gt0
[280.032910] Dynamic subtest gt0: SUCCESS (12.325s)
[280.043865] Starting dynamic subtest: gt1
[295.830627] Dynamic subtest gt1: SUCCESS (15.808s)
[295.833629] Subtest rc6-fence: SUCCESS (28.168s)
[295.931885] [044/132] (723s left) gem_exec_fence (invalid-fence-array)
[296.406986] Starting subtest: invalid-fence-array
[296.412382] Subtest invalid-fence-array: SUCCESS (0.003s)
[297.989031] [FACT gem_exec_fence (invalid-fence-array)] new: kernel.kmod_is_loaded.vgem: true
[297.992335] [045/132] (721s left) perf (polling-parameterized)
[298.135447] Starting subtest: polling-parameterized
[318.208119] Subtest polling-parameterized: SUCCESS (20.020s)
[318.247442] [046/132] (701s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)
[318.350391] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render
[325.073934] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.727s)
[325.242645] [047/132] (694s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[325.344135] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[326.001561] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.659s)
[326.167925] [048/132] (693s left) prime_mmap_kms (buffer-sharing)
[326.253280] Starting subtest: buffer-sharing
[326.687868] Subtest buffer-sharing: SUCCESS (0.433s)
[327.522208] [049/132] (692s left) gem_mmap_wc (bad-size)
[327.594604] Subtest bad-size: SKIP (0.000s)
[327.667718] [050/132] (692s left) kms_flip (dpms-vs-vblank-race-interruptible)
[327.772139] Starting subtest: dpms-vs-vblank-race-interruptible
[327.774341] Starting dynamic subtest: A-eDP1
[332.679835] Dynamic subtest A-eDP1: SUCCESS (4.904s)
[332.689750] Starting dynamic subtest: B-eDP1
[337.300686] Dynamic subtest B-eDP1: SUCCESS (4.620s)
[337.309873] Starting dynamic subtest: C-eDP1
[341.908369] Dynamic subtest C-eDP1: SUCCESS (4.606s)
[341.918096] Starting dynamic subtest: D-eDP1
[346.538036] Dynamic subtest D-eDP1: SUCCESS (4.630s)
[346.540511] Subtest dpms-vs-vblank-race-interruptible: SUCCESS (18.767s)
[347.439113] [051/132] (672s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[347.550241] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[348.452870] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.903s)
[348.717938] [052/132] (671s left) i915_query (engine-info)
[348.784491] Starting subtest: engine-info
[348.789534] Subtest engine-info: SUCCESS (0.005s)
[348.950610] [053/132] (671s left) kms_atomic_transition (plane-all-modeset-transition-internal-panels)
[349.044104] Starting subtest: plane-all-modeset-transition-internal-panels
[349.045996] Starting dynamic subtest: pipe-A-eDP-1
[350.356541] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.312s)
[350.640816] Starting dynamic subtest: pipe-B-eDP-1
[351.680659] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.047s)
[351.953336] Subtest plane-all-modeset-transition-internal-panels: SUCCESS (2.911s)
[352.816558] [054/132] (667s left) gem_reg_read (timestamp-monotonic)
[352.878981] Starting subtest: timestamp-monotonic
[357.518344] Subtest timestamp-monotonic: SUCCESS (4.588s)
[357.566217] [055/132] (662s left) sw_sync (alloc_fence)
[357.605852] Starting subtest: alloc_fence
[357.608121] Subtest alloc_fence: SUCCESS (0.000s)
[357.690733] [056/132] (662s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[357.786687] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[357.788808] Starting dynamic subtest: pipe-A-eDP-1
[357.845182] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[357.853538] Starting dynamic subtest: pipe-B-eDP-1
[357.910372] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[357.921075] Starting dynamic subtest: pipe-C-eDP-1
[357.978742] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[357.989216] Starting dynamic subtest: pipe-D-eDP-1
[358.048253] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[358.052808] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.008s)
[359.991672] [057/132] (660s left) gem_mmap_gtt (basic-small-bo)
[360.044067] Subtest basic-small-bo: SKIP (0.000s)
[360.112139] [058/132] (660s left) template (b)
[360.173293] Starting subtest: B
[360.175503] Subtest B: SUCCESS (0.000s)
[360.294297] [059/132] (659s left) kms_pm_backlight (fade)
[360.386755] Starting subtest: fade
[360.436895] Starting dynamic subtest: eDP-1
[363.124903] Dynamic subtest eDP-1: SUCCESS (2.687s)
[363.127219] Subtest fade: SUCCESS (2.739s)
[364.015993] [060/132] (656s left) gem_exec_reloc (basic-wc-read)
[364.087734] Subtest basic-wc-read: SKIP (0.000s)
[364.171892] [061/132] (656s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[364.286594] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-wc
[370.904269] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.618s)
[371.076149] [062/132] (649s left) kms_color_pipeline (plane-ctm3x4-lut1d)
[371.184886] Starting subtest: plane-ctm3x4-lut1d
[371.200022] Starting dynamic subtest: pipe-A-eDP-1
[371.898231] Dynamic subtest pipe-A-eDP-1: FAIL (0.313s)
[372.589701] Starting dynamic subtest: pipe-B-eDP-1
[373.387231] Dynamic subtest pipe-B-eDP-1: FAIL (0.283s)
[373.916709] Starting dynamic subtest: pipe-C-eDP-1
[374.745656] Dynamic subtest pipe-C-eDP-1: FAIL (0.246s)
[375.254034] Starting dynamic subtest: pipe-D-eDP-1
[376.087617] Dynamic subtest pipe-D-eDP-1: FAIL (0.235s)
[376.089497] Subtest plane-ctm3x4-lut1d: FAIL (4.587s)
[376.655256] [063/132] (643s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-upscaling)
[376.738319] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-upscaling
[376.740600] Starting dynamic subtest: pipe-A-default-mode
[379.490500] Dynamic subtest pipe-A-default-mode: SUCCESS (2.753s)
[379.492795] Subtest flip-64bpp-xtile-to-32bpp-xtile-upscaling: SUCCESS (2.755s)
[380.346490] [064/132] (640s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[380.450496] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[380.454437] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[380.567046] [065/132] (639s left) kms_pm_rpm (dpms-mode-unset-lpsp)
[382.093063] Starting subtest: dpms-mode-unset-lpsp
[386.125657] Subtest dpms-mode-unset-lpsp: SUCCESS (4.035s)
[386.520564] [066/132] (633s left) gem_lmem_swapping (verify)
[386.561034] Subtest verify: SKIP (0.000s)
[386.606120] [067/132] (633s left) syncobj_timeline (etime-single-wait-for-submit-submitted)
[386.653644] Starting subtest: etime-single-wait-for-submit-submitted
[386.762484] Subtest etime-single-wait-for-submit-submitted: SUCCESS (0.108s)
[386.866833] [068/132] (633s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf)
[391.852740] Starting subtest: fbc-psr2-overlay-plane-update-continuous-sf
[391.854916] Starting dynamic subtest: pipe-A-eDP-1
[392.650910] Dynamic subtest pipe-A-eDP-1: SKIP (0.794s)
[392.660574] Starting dynamic subtest: pipe-B-eDP-1
[393.772657] Dynamic subtest pipe-B-eDP-1: SKIP (1.120s)
[393.776778] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (1.918s)
[394.978742] [069/132] (625s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs-cc)
[395.081419] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs-cc
[395.083576] Starting dynamic subtest: pipe-A-eDP-1
[395.102705] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[395.116639] Starting dynamic subtest: pipe-B-eDP-1
[395.135734] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[395.147984] Starting dynamic subtest: pipe-C-eDP-1
[395.166970] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[395.176087] Starting dynamic subtest: pipe-D-eDP-1
[395.194724] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[395.196595] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.010s)
[397.347906] [070/132] (623s left) api_intel_bb (render-ccs)
[397.410975] Starting subtest: render-ccs
[397.758094] Subtest render-ccs: SUCCESS (0.346s)
[397.873168] [071/132] (622s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-fully-sf)
[402.805325] Starting subtest: psr2-overlay-plane-move-continuous-exceed-fully-sf
[402.807654] Starting dynamic subtest: pipe-A-eDP-1
[419.987868] Dynamic subtest pipe-A-eDP-1: SUCCESS (17.181s)
[419.996851] Starting dynamic subtest: pipe-B-eDP-1
[437.129823] Dynamic subtest pipe-B-eDP-1: SUCCESS (17.137s)
[437.132138] Subtest psr2-overlay-plane-move-continuous-exceed-fully-sf: SUCCESS (34.324s)
[437.953660] [072/132] (582s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-pgflip-blt)
[438.050354] Starting subtest: fbc-1p-primscrn-shrfb-pgflip-blt
[442.496985] Subtest fbc-1p-primscrn-shrfb-pgflip-blt: SUCCESS (4.450s)
[443.663230] [073/132] (577s left) kms_selftest (drm_dp_mst_helper)
[443.702744] Starting subtest: drm_dp_mst_helper
[443.726109] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_mode
[443.853400] Dynamic subtest drm_test_dp_mst_calc_pbn_mode: SUCCESS (0.128s)
[443.862996] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_div
[444.009179] Dynamic subtest drm_test_dp_mst_calc_pbn_div: SUCCESS (0.155s)
[444.019575] Starting dynamic subtest: drm_test_dp_mst_sideband_msg_req_decode
[444.141452] Dynamic subtest drm_test_dp_mst_sideband_msg_req_decode: SUCCESS (0.131s)
[444.143690] Subtest drm_dp_mst_helper: SUCCESS (0.440s)
[444.357299] [074/132] (576s left) kms_psr (fbc-pr-no-drrs)
[444.448045] Starting subtest: fbc-pr-no-drrs
[444.452610] Subtest fbc-pr-no-drrs: SKIP (0.000s)
[444.547601] [075/132] (576s left) gem_exec_balancer (fairslice)
[444.611165] Starting subtest: fairslice
[447.465396] Subtest fairslice: SUCCESS (2.851s)
[447.839943] [076/132] (573s left) gem_set_tiling_vs_blt (tiled-to-tiled)
[447.902378] Subtest tiled-to-tiled: SKIP (0.000s)
[447.971345] [077/132] (573s left) kms_atomic_transition (plane-all-modeset-transition-fencing)
[448.077778] Starting subtest: plane-all-modeset-transition-fencing
[448.082156] Subtest plane-all-modeset-transition-fencing: SKIP (0.000s)
[448.176293] [078/132] (572s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[448.241102] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[448.243064] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[448.380286] [079/132] (572s left) kms_vblank (query-busy-hang)
[448.478975] Starting subtest: query-busy-hang
[448.480811] Starting dynamic subtest: pipe-A-eDP-1
[474.635168] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.155s)
[474.649676] Starting dynamic subtest: pipe-D-eDP-1
[489.687822] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.047s)
[489.690318] Subtest query-busy-hang: SUCCESS (41.206s)
[490.498391] [080/132] (530s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[490.592934] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[494.927937] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.338s)
[496.144630] [081/132] (525s left) syncobj_timeline (wait-all-delayed-signal)
[496.211825] Starting subtest: wait-all-delayed-signal
[496.315762] Subtest wait-all-delayed-signal: SUCCESS (0.102s)
[496.421263] [082/132] (524s left) kms_flip (flip-vs-absolute-wf_vblank)
[496.523095] Starting subtest: flip-vs-absolute-wf_vblank
[496.525546] Starting dynamic subtest: A-eDP1
[505.440245] Dynamic subtest A-eDP1: SUCCESS (8.919s)
[505.452105] Starting dynamic subtest: B-eDP1
[514.366367] Dynamic subtest B-eDP1: SUCCESS (8.923s)
[514.378978] Starting dynamic subtest: C-eDP1
[523.339171] Dynamic subtest C-eDP1: SUCCESS (8.924s)
[523.348260] Starting dynamic subtest: D-eDP1
[532.273056] Dynamic subtest D-eDP1: SUCCESS (8.930s)
[532.275166] Subtest flip-vs-absolute-wf_vblank: SUCCESS (35.706s)
[533.477601] [083/132] (487s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions)
[533.604542] Starting subtest: short-flip-before-cursor-atomic-transitions
[534.562533] Subtest short-flip-before-cursor-atomic-transitions: SUCCESS (0.955s)
[535.415775] [084/132] (485s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[535.477047] Starting subtest: yf-tiled-to-vebox-yf-tiled
[535.481577] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[535.685872] [085/132] (485s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[535.776142] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[535.780696] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[535.904702] [086/132] (485s left) kms_plane_scaling (plane-downscale-factor-0-75-with-modifiers)
[536.007022] Starting subtest: plane-downscale-factor-0-75-with-modifiers
[536.009255] Starting dynamic subtest: pipe-A
[536.412442] Dynamic subtest pipe-A: SUCCESS (0.408s)
[536.421833] Starting dynamic subtest: pipe-B
[537.828918] Dynamic subtest pipe-B: SUCCESS (1.414s)
[537.839282] Starting dynamic subtest: pipe-C
[539.227819] Dynamic subtest pipe-C: SUCCESS (1.398s)
[539.237238] Starting dynamic subtest: pipe-D
[540.739597] Dynamic subtest pipe-D: SUCCESS (1.511s)
[540.741728] Subtest plane-downscale-factor-0-75-with-modifiers: SUCCESS (4.737s)
[541.939902] [087/132] (479s left) api_intel_allocator (open-vm)
[541.996659] Starting subtest: open-vm
[541.999102] Subtest open-vm: SUCCESS (0.003s)
[542.158259] [088/132] (479s left) gem_exec_whisper (basic-contexts-priority-all)
[542.233959] Starting subtest: basic-contexts-priority-all
[566.850734] Subtest basic-contexts-priority-all: SUCCESS (24.565s)
[567.005889] [089/132] (454s left) kms_cursor_crc (cursor-tearing-framebuffer-change)
[567.115095] Starting subtest: cursor-tearing-framebuffer-change
[567.117283] Starting dynamic subtest: pipe-A-eDP-1
[567.689622] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.574s)
[567.699837] Starting dynamic subtest: pipe-D-eDP-1
[568.930440] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.238s)
[568.932672] Subtest cursor-tearing-framebuffer-change: SUCCESS (1.814s)
[569.789367] [090/132] (451s left) kms_psr (psr2-sprite-mmap-cpu)
[569.886979] Starting subtest: psr2-sprite-mmap-cpu
[569.889275] Starting dynamic subtest: eDP-1
[571.576339] Dynamic subtest eDP-1: SUCCESS (1.690s)
[571.578777] Subtest psr2-sprite-mmap-cpu: SUCCESS (1.691s)
[572.474135] [091/132] (449s left) gem_tiled_wc
[572.602656] [092/132] (449s left) vgem_basic (second-client)
[572.648800] Starting subtest: second-client
[572.670699] Subtest second-client: SUCCESS (0.022s)
[572.753507] [093/132] (448s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-onoff)
[572.854315] Starting subtest: fbc-1p-primscrn-spr-indfb-onoff
[576.849486] Subtest fbc-1p-primscrn-spr-indfb-onoff: SUCCESS (3.998s)
[578.060402] [094/132] (443s left) prime_vgem (shrink)
[578.139799] Starting subtest: shrink
[578.170568] Subtest shrink: SUCCESS (0.032s)
[578.310888] [095/132] (443s left) sysfs_preempt_timeout (invalid)
[578.367995] Starting subtest: invalid
[578.370455] Starting dynamic subtest: vecs0
[578.372718] Dynamic subtest vecs0: SUCCESS (0.001s)
[578.382979] Starting dynamic subtest: ccs0
[578.384757] Dynamic subtest ccs0: SUCCESS (0.000s)
[578.393597] Starting dynamic subtest: vcs0
[578.395612] Dynamic subtest vcs0: SUCCESS (0.000s)
[578.406178] Starting dynamic subtest: bcs0
[578.408089] Dynamic subtest bcs0: SUCCESS (0.000s)
[578.417220] Starting dynamic subtest: rcs0
[578.419014] Dynamic subtest rcs0: SUCCESS (0.000s)
[578.427599] Starting dynamic subtest: vcs1
[578.429667] Dynamic subtest vcs1: SUCCESS (0.000s)
[578.431795] Subtest invalid: SUCCESS (0.008s)
[578.506522] [096/132] (443s left) gem_ctx_persistence (heartbeat-stop)
[578.575659] Starting subtest: heartbeat-stop
[578.580619] Subtest heartbeat-stop: SKIP (0.000s)
[578.716414] [097/132] (443s left) kms_joiner (basic-force-big-joiner)
[578.818666] Starting subtest: basic-force-big-joiner
[578.821037] Starting dynamic subtest: single
[582.157586] Dynamic subtest single: SUCCESS (3.345s)
[582.159997] Subtest basic-force-big-joiner: SUCCESS (3.347s)
[583.363979] [098/132] (438s left) syncobj_timeline (multi-wait-available-submitted-signaled)
[583.423074] Starting subtest: multi-wait-available-submitted-signaled
[583.425516] Subtest multi-wait-available-submitted-signaled: SUCCESS (0.000s)
[583.543358] [099/132] (438s left) kms_cursor_legacy (cursora-vs-flipb-legacy)
[583.654366] Starting subtest: cursorA-vs-flipB-legacy
[583.659085] Subtest cursorA-vs-flipB-legacy: SKIP (0.001s)
[583.819089] [100/132] (438s left) gem_userptr_blits (probe)
[583.902462] Starting subtest: probe
[583.974785] Subtest probe: SUCCESS (0.072s)
[584.124763] [101/132] (437s left) kms_properties (colorop-properties-atomic)
[584.208927] Starting subtest: colorop-properties-atomic
[584.211226] Starting dynamic subtest: pipe-A-eDP-1
[584.565714] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.357s)
[584.575656] Starting dynamic subtest: pipe-B-eDP-1
[585.609082] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.041s)
[585.618085] Starting dynamic subtest: pipe-C-eDP-1
[586.646619] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.035s)
[586.656995] Starting dynamic subtest: pipe-D-eDP-1
[587.674693] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.027s)
[587.676592] Subtest colorop-properties-atomic: SUCCESS (3.466s)
[588.524832] [102/132] (433s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[588.627081] Starting subtest: addfb25-y-tiled-legacy
[588.628921] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[588.745903] [103/132] (433s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-downscaling)
[588.851744] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-downscaling
[588.853891] Starting dynamic subtest: pipe-A-default-mode
[589.140124] Dynamic subtest pipe-A-default-mode: SKIP (0.285s)
[589.144556] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-downscaling: SKIP (0.287s)
[590.010620] [104/132] (432s left) kms_plane_multiple (tiling-y)
[590.101122] Starting subtest: tiling-y
[590.105766] Subtest tiling-y: SKIP (0.000s)
[590.193094] [105/132] (432s left) gem_softpin (evict-prime)
[590.246881] Starting subtest: evict-prime
[590.248816] Starting dynamic subtest: rcs0
[591.435939] Dynamic subtest rcs0: SUCCESS (1.191s)
[591.448146] Starting dynamic subtest: bcs0
[592.583039] Dynamic subtest bcs0: SUCCESS (1.145s)
[592.595189] Starting dynamic subtest: vcs0
[593.910438] Dynamic subtest vcs0: SUCCESS (1.324s)
[593.923492] Starting dynamic subtest: vcs1
[595.936919] Dynamic subtest vcs1: SUCCESS (2.024s)
[595.948918] Starting dynamic subtest: vecs0
[597.877308] Dynamic subtest vecs0: SUCCESS (1.938s)
[597.889644] Starting dynamic subtest: ccs0
[599.037920] Dynamic subtest ccs0: SUCCESS (1.160s)
[599.050347] Starting dynamic subtest: all
[600.856909] Dynamic subtest all: SUCCESS (1.792s)
[600.861384] Subtest evict-prime: SUCCESS (10.589s)
[601.004082] [106/132] (421s left) gem_mmap_offset (close-race)
[601.061495] Starting subtest: close-race
[621.215109] Subtest close-race: SUCCESS (20.103s)
[621.342728] [107/132] (400s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-render)
[621.454443] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-render
[621.458807] Subtest psr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[621.589531] [108/132] (400s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-pwrite)
[621.685063] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-pwrite
[628.766521] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (7.083s)
[628.938763] [109/132] (393s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-pwrite)
[629.048330] Starting subtest: psr-1p-primscrn-pri-indfb-draw-pwrite
[636.511422] Subtest psr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (7.465s)
[636.659773] [110/132] (385s left) kms_chamelium_frames (dp-frame-dump)
[637.063164] Subtest dp-frame-dump: SKIP (0.000s)
[637.826818] [111/132] (384s left) kms_content_protection (dp-mst-lic-type-0)
[637.973055] Starting subtest: dp-mst-lic-type-0
[637.977703] Subtest dp-mst-lic-type-0: SKIP (0.000s)
[638.132267] [112/132] (384s left) gen9_exec_parse (bb-chained)
[638.199060] Subtest bb-chained: SKIP (0.000s)
[638.285170] [113/132] (384s left) gem_lmem_swapping (heavy-verify-random)
[638.322863] Subtest heavy-verify-random: SKIP (0.000s)
[638.364874] [114/132] (384s left) kms_pipe_crc_basic (suspend-read-crc)
[638.454961] Starting subtest: suspend-read-crc
[638.457232] Starting dynamic subtest: pipe-A-eDP-1
[655.959766] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.663s)
[655.973399] Starting dynamic subtest: pipe-B-eDP-1
[673.956926] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.098s)
[673.969727] Starting dynamic subtest: pipe-C-eDP-1
[691.915787] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.031s)
[691.926392] Starting dynamic subtest: pipe-D-eDP-1
[709.953621] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.075s)
[709.955851] Subtest suspend-read-crc: SUCCESS (11.873s)
[710.825182] [115/132] (311s left) gem_exec_schedule (submit-late-slice)
[710.899004] Starting subtest: submit-late-slice
[710.901062] Starting dynamic subtest: rcs0
[710.924061] Dynamic subtest rcs0: SUCCESS (0.032s)
[710.935327] Starting dynamic subtest: bcs0
[710.952065] Dynamic subtest bcs0: SUCCESS (0.026s)
[710.965393] Starting dynamic subtest: vcs0
[710.980340] Dynamic subtest vcs0: SUCCESS (0.027s)
[710.990809] Starting dynamic subtest: vcs1
[711.010537] Dynamic subtest vcs1: SUCCESS (0.029s)
[711.020683] Starting dynamic subtest: vecs0
[711.038817] Dynamic subtest vecs0: SUCCESS (0.027s)
[711.049788] Starting dynamic subtest: ccs0
[711.071204] Dynamic subtest ccs0: SUCCESS (0.031s)
[711.073349] Subtest submit-late-slice: SUCCESS (0.178s)
[712.483377] [116/132] (310s left) kms_cursor_crc (cursor-sliding-256x256)
[712.721786] Starting subtest: cursor-sliding-256x256
[712.726506] Starting dynamic subtest: pipe-A-eDP-1
[716.041648] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.280s)
[716.071633] Starting dynamic subtest: pipe-D-eDP-1
[719.998553] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.905s)
[720.000710] Subtest cursor-sliding-256x256: SUCCESS (7.211s)
[720.873344] [117/132] (301s left) kms_dp_aux_dev
[721.251992] [118/132] (301s left) gem_mmap_wc (write-cpu-read-wc-unflushed)
[721.320778] Subtest write-cpu-read-wc-unflushed: SKIP (0.000s)
[721.391459] [119/132] (301s left) kms_feature_discovery (display-1x)
[721.486677] Starting subtest: display-1x
[721.488823] Subtest display-1x: SUCCESS (0.000s)
[721.588143] [120/132] (301s left) kms_psr (fbc-psr-sprite-mmap-cpu)
[721.674835] Starting subtest: fbc-psr-sprite-mmap-cpu
[721.677094] Starting dynamic subtest: eDP-1
[721.681143] Dynamic subtest eDP-1: SKIP (0.000s)
[721.685189] Subtest fbc-psr-sprite-mmap-cpu: SKIP (0.001s)
[721.776181] [121/132] (301s left) gem_exec_params (secure-non-root)
[721.838506] Starting subtest: secure-non-root
[721.842943] Subtest secure-non-root: SKIP (0.000s)
[721.997574] [122/132] (300s left) gem_exec_alignment (many)
[722.060863] Starting subtest: many
[745.402793] Subtest many: SUCCESS (23.307s)
[747.160354] [123/132] (275s left) kms_chamelium_hpd (vga-hpd-without-ddc)
[747.596792] Subtest vga-hpd-without-ddc: SKIP (0.000s)
[748.386848] [124/132] (274s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render)
[748.475826] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render
[748.479838] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[748.592012] [125/132] (274s left) gem_mmap (basic-small-bo)
[748.653434] Subtest basic-small-bo: SKIP (0.000s)
[748.737969] [126/132] (274s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[748.830176] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[748.834153] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[748.971734] [127/132] (274s left) dumb_buffer (invalid-bpp)
[749.037935] Starting subtest: invalid-bpp
[749.040201] Subtest invalid-bpp: SUCCESS (0.000s)
[749.172602] [128/132] (274s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[749.281162] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[749.285383] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[749.396879] [129/132] (273s left) i915_getparams_basic (basic-eu-total)
[749.464094] Starting subtest: basic-eu-total
[749.467379] Subtest basic-eu-total: SUCCESS (0.000s)
[749.583818] [130/132] (273s left) gem_exec_parallel (userptr)
[749.651804] Starting subtest: userptr
[749.653784] Starting dynamic subtest: rcs0
[751.094708] Dynamic subtest rcs0: SUCCESS (1.444s)
[751.105288] Starting dynamic subtest: bcs0
[752.595554] Dynamic subtest bcs0: SUCCESS (1.499s)
[752.606775] Starting dynamic subtest: vcs0
[754.431624] Dynamic subtest vcs0: SUCCESS (1.832s)
[754.445014] Starting dynamic subtest: vcs1
[756.309969] Dynamic subtest vcs1: SUCCESS (1.875s)
[756.321690] Starting dynamic subtest: vecs0
[758.358471] Dynamic subtest vecs0: SUCCESS (2.045s)
[758.376100] Starting dynamic subtest: ccs0
[759.890344] Dynamic subtest ccs0: SUCCESS (1.531s)
[759.892717] Subtest userptr: SUCCESS (10.240s)
[760.108625] [131/132] (263s left) kms_flip (2x-plain-flip-ts-check)
[760.206782] Starting subtest: 2x-plain-flip-ts-check
[760.216384] Subtest 2x-plain-flip-ts-check: SKIP (0.011s)
[760.320992] [132/132] (263s left) kms_busy (extended-modeset-hang-oldfb)
[760.423158] Starting subtest: extended-modeset-hang-oldfb
[760.425486] Starting dynamic subtest: pipe-A
[772.350212] Dynamic subtest pipe-A: SUCCESS (11.921s)
[772.360897] Starting dynamic subtest: pipe-D
[784.935709] Dynamic subtest pipe-D: SUCCESS (12.578s)
[784.938077] Subtest extended-modeset-hang-oldfb: SUCCESS (24.505s)
[785.831122] Closing watchdogs
results: parsing output: 0/ for test: kms_cursor_crc
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: gem_exec_schedule
results: parsing output: 3/ for test: kms_psr
results: parsing output: 4/ for test: kms_cursor_legacy
results: parsing output: 5/ for test: syncobj_timeline
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: perf_pmu
results: parsing output: 8/ for test: gem_exec_balancer
results: parsing output: 9/ for test: gem_bad_reloc
results: parsing output: 10/ for test: kms_ccs
results: parsing output: 11/ for test: gem_ctx_freq
results: parsing output: 12/ for test: kms_vblank
results: parsing output: 13/ for test: kms_ccs
results: parsing output: 14/ for test: syncobj_wait
results: parsing output: 15/ for test: i915_drm_fdinfo
results: parsing output: 16/ for test: gem_ctx_param
results: parsing output: 17/ for test: gem_partial_pwrite_pread
results: parsing output: 18/ for test: kms_flip
results: parsing output: 19/ for test: fbdev
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: gem_exec_reloc
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: syncobj_wait
results: parsing output: 25/ for test: kms_ccs
results: parsing output: 26/ for test: kms_big_fb
results: parsing output: 27/ for test: kms_lease
results: parsing output: 28/ for test: gem_create
results: parsing output: 29/ for test: kms_properties
results: parsing output: 30/ for test: kms_rotation_crc
results: parsing output: 31/ for test: gem_pread
results: parsing output: 32/ for test: kms_big_fb
results: parsing output: 33/ for test: gem_render_copy
results: parsing output: 34/ for test: gem_set_tiling_vs_gtt
results: parsing output: 35/ for test: kms_psr
results: parsing output: 36/ for test: perf_pmu
results: parsing output: 37/ for test: kms_cursor_legacy
results: parsing output: 38/ for test: kms_big_fb
results: parsing output: 39/ for test: gem_eio
results: parsing output: 40/ for test: kms_big_fb
results: parsing output: 41/ for test: syncobj_timeline
results: parsing output: 42/ for test: i915_pm_rc6_residency
results: parsing output: 43/ for test: gem_exec_fence
results: parsing output: 44/ for test: perf
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: kms_big_fb
results: parsing output: 47/ for test: prime_mmap_kms
results: parsing output: 48/ for test: gem_mmap_wc
results: parsing output: 49/ for test: kms_flip
results: parsing output: 50/ for test: kms_big_fb
results: parsing output: 51/ for test: i915_query
results: parsing output: 52/ for test: kms_atomic_transition
results: parsing output: 53/ for test: gem_reg_read
results: parsing output: 54/ for test: sw_sync
results: parsing output: 55/ for test: kms_ccs
results: parsing output: 56/ for test: gem_mmap_gtt
results: parsing output: 57/ for test: template
results: parsing output: 58/ for test: kms_pm_backlight
results: parsing output: 59/ for test: gem_exec_reloc
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_color_pipeline
results: parsing output: 62/ for test: kms_flip_scaled_crc
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: kms_pm_rpm
results: parsing output: 65/ for test: gem_lmem_swapping
results: parsing output: 66/ for test: syncobj_timeline
results: parsing output: 67/ for test: kms_psr2_sf
results: parsing output: 68/ for test: kms_ccs
results: parsing output: 69/ for test: api_intel_bb
results: parsing output: 70/ for test: kms_psr2_sf
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: kms_selftest
results: parsing output: 73/ for test: kms_psr
results: parsing output: 74/ for test: gem_exec_balancer
results: parsing output: 75/ for test: gem_set_tiling_vs_blt
results: parsing output: 76/ for test: kms_atomic_transition
results: parsing output: 77/ for test: syncobj_wait
results: parsing output: 78/ for test: kms_vblank
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: syncobj_timeline
results: parsing output: 81/ for test: kms_flip
results: parsing output: 82/ for test: kms_cursor_legacy
results: parsing output: 83/ for test: gem_render_copy
results: parsing output: 84/ for test: kms_big_fb
results: parsing output: 85/ for test: kms_plane_scaling
results: parsing output: 86/ for test: api_intel_allocator
results: parsing output: 87/ for test: gem_exec_whisper
results: parsing output: 88/ for test: kms_cursor_crc
results: parsing output: 89/ for test: kms_psr
results: parsing output: 90/ for test: gem_tiled_wc
results: parsing output: 91/ for test: vgem_basic
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: prime_vgem
results: parsing output: 94/ for test: sysfs_preempt_timeout
results: parsing output: 95/ for test: gem_ctx_persistence
results: parsing output: 96/ for test: kms_joiner
results: parsing output: 97/ for test: syncobj_timeline
results: parsing output: 98/ for test: kms_cursor_legacy
results: parsing output: 99/ for test: gem_userptr_blits
results: parsing output: 100/ for test: kms_properties
results: parsing output: 101/ for test: kms_addfb_basic
results: parsing output: 102/ for test: kms_flip_scaled_crc
results: parsing output: 103/ for test: kms_plane_multiple
results: parsing output: 104/ for test: gem_softpin
results: parsing output: 105/ for test: gem_mmap_offset
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_chamelium_frames
results: parsing output: 110/ for test: kms_content_protection
results: parsing output: 111/ for test: gen9_exec_parse
results: parsing output: 112/ for test: gem_lmem_swapping
results: parsing output: 113/ for test: kms_pipe_crc_basic
results: parsing output: 114/ for test: gem_exec_schedule
results: parsing output: 115/ for test: kms_cursor_crc
results: parsing output: 116/ for test: kms_dp_aux_dev
results: parsing output: 117/ for test: gem_mmap_wc
results: parsing output: 118/ for test: kms_feature_discovery
results: parsing output: 119/ for test: kms_psr
results: parsing output: 120/ for test: gem_exec_params
results: parsing output: 121/ for test: gem_exec_alignment
results: parsing output: 122/ for test: kms_chamelium_hpd
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: gem_mmap
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: dumb_buffer
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: i915_getparams_basic
results: parsing output: 129/ for test: gem_exec_parallel
results: parsing output: 130/ for test: kms_flip
results: parsing output: 131/ for test: kms_busy
Done.