[57.132201] Initializing watchdogs
[57.132248]   /dev/watchdog0
[57.145703] [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.149887] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.151970] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.155719] [001/131] (960s left) perf_pmu (most-busy-idle-check-all)
[57.207736] Starting subtest: most-busy-idle-check-all
[57.209988] Starting dynamic subtest: rcs0
[58.900684] Dynamic subtest rcs0: SUCCESS (1.691s)
[58.909455] Starting dynamic subtest: bcs0
[60.883991] Dynamic subtest bcs0: SUCCESS (1.983s)
[60.893913] Starting dynamic subtest: vcs0
[62.866688] Dynamic subtest vcs0: SUCCESS (1.982s)
[62.876335] Starting dynamic subtest: vcs1
[64.915721] Dynamic subtest vcs1: SUCCESS (2.048s)
[64.925447] Starting dynamic subtest: vecs0
[66.900770] Dynamic subtest vecs0: SUCCESS (1.984s)
[66.910673] Starting dynamic subtest: ccs0
[68.884846] Dynamic subtest ccs0: SUCCESS (1.984s)
[68.887761] Subtest most-busy-idle-check-all: SUCCESS (11.676s)
[68.985299] [002/131] (948s left) syncobj_wait (multi-wait-submitted)
[69.042935] Starting subtest: multi-wait-submitted
[69.152317] Subtest multi-wait-submitted: SUCCESS (0.107s)
[69.224539] [003/131] (948s left) gem_wait (write-wait)
[69.285731] Starting subtest: write-wait
[69.288048] Starting dynamic subtest: all-engines
[70.456292] Dynamic subtest all-engines: SUCCESS (1.170s)
[70.470274] Starting dynamic subtest: rcs0
[71.610457] Dynamic subtest rcs0: SUCCESS (1.153s)
[71.623923] Starting dynamic subtest: bcs0
[72.668563] Dynamic subtest bcs0: SUCCESS (1.059s)
[72.682726] Starting dynamic subtest: vcs0
[73.724074] Dynamic subtest vcs0: SUCCESS (1.054s)
[73.738293] Starting dynamic subtest: vcs1
[74.776103] Dynamic subtest vcs1: SUCCESS (1.052s)
[74.790379] Starting dynamic subtest: vecs0
[75.829698] Dynamic subtest vecs0: SUCCESS (1.053s)
[75.843154] Starting dynamic subtest: ccs0
[76.886929] Dynamic subtest ccs0: SUCCESS (1.057s)
[76.889673] Subtest write-wait: SUCCESS (7.601s)
[77.082417] [FACT gem_wait (write-wait)] new: kernel.kmod_is_loaded.vgem: true
[77.085231] [004/131] (940s left) kms_plane_lowres (tiling-4)
[77.184474] Starting subtest: tiling-4
[77.186638] Starting dynamic subtest: pipe-A-eDP-1
[77.191190] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[77.488600] Starting dynamic subtest: pipe-B-eDP-1
[77.493199] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[77.502079] Starting dynamic subtest: pipe-C-eDP-1
[77.506408] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[77.513532] Starting dynamic subtest: pipe-D-eDP-1
[77.517709] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[77.521986] Subtest tiling-4: SKIP (0.300s)
[78.289020] [005/131] (939s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)
[78.391137] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render
[85.069849] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.681s)
[85.191462] [006/131] (932s left) gem_exec_fence (basic-await)
[85.366528] Starting subtest: basic-await
[85.368907] Starting dynamic subtest: rcs0
[85.439798] Dynamic subtest rcs0: SUCCESS (0.075s)
[85.449806] Starting dynamic subtest: bcs0
[85.513425] Dynamic subtest bcs0: SUCCESS (0.072s)
[85.523212] Starting dynamic subtest: vcs0
[85.585196] Dynamic subtest vcs0: SUCCESS (0.072s)
[85.595324] Starting dynamic subtest: vcs1
[85.655038] Dynamic subtest vcs1: SUCCESS (0.070s)
[85.664740] Starting dynamic subtest: vecs0
[85.725881] Dynamic subtest vecs0: SUCCESS (0.070s)
[85.736487] Starting dynamic subtest: ccs0
[85.796733] Dynamic subtest ccs0: SUCCESS (0.071s)
[85.799128] Subtest basic-await: SUCCESS (0.431s)
[87.976795] [007/131] (929s left) syncobj_timeline (invalid-wait-illegal-handle)
[88.033797] Starting subtest: invalid-wait-illegal-handle
[88.036148] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[88.148935] [008/131] (929s left) gem_ctx_param (invalid-get-ringsize)
[88.205516] Starting subtest: invalid-get-ringsize
[88.207889] Subtest invalid-get-ringsize: SUCCESS (0.002s)
[88.336326] [009/131] (929s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[88.387053] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[88.389423] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[88.487757] [010/131] (929s left) kms_dsc (dsc-fractional-bpp)
[88.585581] Starting subtest: dsc-fractional-bpp
[88.598385] Subtest dsc-fractional-bpp: SKIP (0.000s)
[88.657186] [011/131] (929s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[88.720379] Starting subtest: yf-tiled-to-vebox-yf-tiled
[88.724457] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[88.897958] [012/131] (929s left) gem_exec_schedule (u-submit-late-slice)
[88.974243] Starting subtest: u-submit-late-slice
[88.976233] Starting dynamic subtest: rcs0
[89.002802] Dynamic subtest rcs0: SUCCESS (0.034s)
[89.013454] Starting dynamic subtest: bcs0
[89.029023] Dynamic subtest bcs0: SUCCESS (0.026s)
[89.036526] Starting dynamic subtest: vcs0
[89.060705] Dynamic subtest vcs0: SUCCESS (0.032s)
[89.068553] Starting dynamic subtest: vcs1
[89.088847] Dynamic subtest vcs1: SUCCESS (0.028s)
[89.098991] Starting dynamic subtest: vecs0
[89.114624] Dynamic subtest vecs0: SUCCESS (0.026s)
[89.122039] Starting dynamic subtest: ccs0
[89.141684] Dynamic subtest ccs0: SUCCESS (0.027s)
[89.144044] Subtest u-submit-late-slice: SUCCESS (0.173s)
[91.007346] [013/131] (927s left) kms_psr (psr-primary-mmap-cpu)
[91.107054] Starting subtest: psr-primary-mmap-cpu
[91.109326] Starting dynamic subtest: eDP-1
[92.795049] Dynamic subtest eDP-1: SUCCESS (1.687s)
[92.796963] Subtest psr-primary-mmap-cpu: SUCCESS (1.688s)
[93.653737] [014/131] (924s left) api_intel_bb (object-reloc-purge-cache)
[93.710126] Starting subtest: object-reloc-purge-cache
[93.714867] Subtest object-reloc-purge-cache: SKIP (0.003s)
[93.844667] [015/131] (924s left) gem_create (create-massive)
[93.902968] Starting subtest: create-massive
[93.905364] Subtest create-massive: SUCCESS (0.000s)
[94.085892] [016/131] (924s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0)
[94.203401] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0
[94.207751] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[94.302786] [017/131] (923s left) kms_ccs (bad-aux-stride-4-tiled-mtl-rc-ccs-cc)
[94.394041] Starting subtest: bad-aux-stride-4-tiled-mtl-rc-ccs-cc
[94.396448] Starting dynamic subtest: pipe-A-eDP-1
[94.417147] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[94.426887] Starting dynamic subtest: pipe-B-eDP-1
[94.445865] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[94.453465] Starting dynamic subtest: pipe-C-eDP-1
[94.473770] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[94.482253] Starting dynamic subtest: pipe-D-eDP-1
[94.501967] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[94.504200] Subtest bad-aux-stride-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.006s)
[96.537354] [018/131] (921s left) kms_big_fb (linear-8bpp-rotate-0)
[96.642440] Starting subtest: linear-8bpp-rotate-0
[97.856202] Subtest linear-8bpp-rotate-0: SUCCESS (1.215s)
[98.003663] [019/131] (920s left) kms_atomic (test-only)
[98.087612] Starting subtest: test-only
[98.138594] Starting dynamic subtest: pipe-A-eDP-1
[100.684066] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.544s)
[100.686264] Subtest test-only: SUCCESS (2.594s)
[101.551219] [020/131] (916s left) gem_mmap_offset (ptrace)
[101.607832] Starting subtest: ptrace
[101.612312] Starting dynamic subtest: smem0-wb
[101.628512] Dynamic subtest smem0-wb: SUCCESS (0.018s)
[101.638321] Starting dynamic subtest: smem0-wc
[101.646889] Dynamic subtest smem0-wc: SUCCESS (0.018s)
[101.654228] Starting dynamic subtest: smem0-uc
[101.664249] Dynamic subtest smem0-uc: SUCCESS (0.017s)
[101.666430] Subtest ptrace: SUCCESS (0.057s)
[101.759188] [021/131] (916s left) perf_pmu (busy-double-start)
[101.793978] Starting subtest: busy-double-start
[101.796337] Starting dynamic subtest: rcs0
[105.437276] Dynamic subtest rcs0: FAIL (3.591s)
[105.446536] Starting dynamic subtest: bcs0
[108.988881] Dynamic subtest bcs0: SUCCESS (3.604s)
[108.998496] Starting dynamic subtest: vcs0
[113.462678] Dynamic subtest vcs0: SUCCESS (4.473s)
[113.472973] Starting dynamic subtest: vcs1
[117.426834] Dynamic subtest vcs1: SUCCESS (3.964s)
[117.437242] Starting dynamic subtest: vecs0
[121.469905] Dynamic subtest vecs0: SUCCESS (4.043s)
[121.480108] Starting dynamic subtest: ccs0
[125.103557] Dynamic subtest ccs0: FAIL (3.563s)
[125.105519] Subtest busy-double-start: FAIL (23.239s)
[125.163928] [022/131] (893s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-fullscreen)
[125.275579] Starting subtest: fbc-1p-primscrn-spr-indfb-fullscreen
[129.183919] Subtest fbc-1p-primscrn-spr-indfb-fullscreen: SUCCESS (3.912s)
[130.350345] [023/131] (888s left) gem_exec_params (rsvd2-dirt)
[130.411395] Starting subtest: rsvd2-dirt
[130.415950] Subtest rsvd2-dirt: SKIP (0.000s)
[130.535691] [024/131] (888s left) api_intel_bb (object-noreloc-purge-cache-simple)
[130.589704] Starting subtest: object-noreloc-purge-cache-simple
[130.595357] Subtest object-noreloc-purge-cache-simple: SUCCESS (0.007s)
[130.721594] [025/131] (887s left) gem_userptr_blits (sync-unmap)
[130.786170] Starting subtest: sync-unmap
[130.797815] Subtest sync-unmap: SUCCESS (0.014s)
[130.932201] [026/131] (887s left) syncobj_timeline (device-signal-unordered)
[130.990062] Starting subtest: device-signal-unordered
[130.992061] Subtest device-signal-unordered: SUCCESS (0.001s)
[131.111681] [027/131] (887s left) gem_ctx_shared (q-smoketest)
[131.179760] Starting subtest: Q-smoketest
[131.182165] Starting dynamic subtest: rcs0
[136.740242] Dynamic subtest rcs0: SUCCESS (5.511s)
[136.748350] Starting dynamic subtest: bcs0
[142.219324] Dynamic subtest bcs0: SUCCESS (5.480s)
[142.232483] Starting dynamic subtest: vcs0
[147.695238] Dynamic subtest vcs0: SUCCESS (5.478s)
[147.708017] Starting dynamic subtest: vcs1
[153.175619] Dynamic subtest vcs1: SUCCESS (5.478s)
[153.183733] Starting dynamic subtest: vecs0
[158.665442] Dynamic subtest vecs0: SUCCESS (5.491s)
[158.673922] Starting dynamic subtest: ccs0
[164.180768] Dynamic subtest ccs0: SUCCESS (5.511s)
[164.182723] Subtest Q-smoketest: SUCCESS (32.951s)
[164.314544] [028/131] (854s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[164.410203] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[164.414563] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[164.494630] [029/131] (854s left) gem_userptr_blits (map-fixed-invalidate)
[164.565801] Starting subtest: map-fixed-invalidate
[164.570131] Subtest map-fixed-invalidate: SKIP (0.000s)
[164.693895] [030/131] (854s left) gem_mmap_gtt (big-bo)
[164.753707] Subtest big-bo: SKIP (0.000s)
[164.809789] [031/131] (854s left) sw_sync (sync_busy_fork)
[164.843127] Starting subtest: sync_busy_fork
[165.855657] Subtest sync_busy_fork: SUCCESS (1.013s)
[165.917671] [032/131] (852s left) gem_mmap_wc (coherency)
[165.964595] Subtest coherency: SKIP (0.000s)
[166.027749] [033/131] (852s left) kms_cursor_edge_walk (256x256-left-edge)
[166.115124] Starting subtest: 256x256-left-edge
[166.117475] Starting dynamic subtest: pipe-A-eDP-1
[169.591703] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.475s)
[169.601036] Starting dynamic subtest: pipe-D-eDP-1
[172.722573] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.131s)
[172.724854] Subtest 256x256-left-edge: SUCCESS (6.607s)
[173.518257] [034/131] (845s left) prime_vgem (coherency-blt)
[173.603490] Starting subtest: coherency-blt
[174.664611] Subtest coherency-blt: SUCCESS (1.058s)
[174.789618] [035/131] (844s left) gem_tiled_wc
[174.902988] [036/131] (844s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-pwrite)
[174.993611] Starting subtest: psr-1p-primscrn-cur-indfb-draw-pwrite
[181.723205] Subtest psr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.734s)
[181.821890] [037/131] (837s left) gem_exec_fence (syncobj-invalid-flags)
[182.256687] Starting subtest: syncobj-invalid-flags
[182.259170] Subtest syncobj-invalid-flags: SUCCESS (0.000s)
[183.956826] [038/131] (835s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[184.045320] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[184.047736] Starting dynamic subtest: pipe-A-default-mode
[184.334471] Dynamic subtest pipe-A-default-mode: SKIP (0.284s)
[184.339205] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SKIP (0.285s)
[185.136435] [039/131] (834s left) gem_mmap_gtt (hang-user)
[185.199267] Subtest hang-user: SKIP (0.000s)
[185.255276] [040/131] (833s left) kms_plane_scaling (plane-downscale-factor-0-75-with-pixel-format)
[185.354800] Starting subtest: plane-downscale-factor-0-75-with-pixel-format
[185.357024] Starting dynamic subtest: pipe-A
[186.505120] Dynamic subtest pipe-A: SUCCESS (1.152s)
[186.513434] Starting dynamic subtest: pipe-B
[188.706255] Dynamic subtest pipe-B: SUCCESS (2.201s)
[188.714208] Starting dynamic subtest: pipe-C
[189.861932] Dynamic subtest pipe-C: SUCCESS (1.155s)
[189.869586] Starting dynamic subtest: pipe-D
[191.083175] Dynamic subtest pipe-D: SUCCESS (1.221s)
[191.085482] Subtest plane-downscale-factor-0-75-with-pixel-format: SUCCESS (5.730s)
[192.282916] [041/131] (826s left) kms_chamelium_hpd (dp-hpd)
[192.676084] Subtest dp-hpd: SKIP (0.000s)
[193.490790] [042/131] (825s left) kms_cursor_crc (cursor-rapid-movement-128x42)
[193.716829] Starting subtest: cursor-rapid-movement-128x42
[193.759831] Subtest cursor-rapid-movement-128x42: SKIP (0.038s)
[193.970131] [043/131] (825s left) sw_sync (sync_multi_consumer_producer)
[194.003413] Starting subtest: sync_multi_consumer_producer
[194.291181] Subtest sync_multi_consumer_producer: SUCCESS (0.289s)
[194.346934] [044/131] (824s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[194.437221] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[194.441169] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[194.539943] [045/131] (824s left) kms_cursor_crc (cursor-offscreen-max-size)
[194.834468] Starting subtest: cursor-offscreen-max-size
[194.841066] Subtest cursor-offscreen-max-size: SKIP (0.000s)
[194.959286] [046/131] (824s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[195.055627] Starting subtest: yf-tiled-64bpp-rotate-90
[195.059814] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[195.140561] [047/131] (824s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs-cc)
[195.232933] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs-cc
[195.235212] Starting dynamic subtest: pipe-A-eDP-1
[195.257820] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[195.265950] Starting dynamic subtest: pipe-B-eDP-1
[195.287731] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[195.295585] Starting dynamic subtest: pipe-C-eDP-1
[195.315783] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[195.322829] Starting dynamic subtest: pipe-D-eDP-1
[195.341644] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[195.344973] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[197.477335] [048/131] (822s left) i915_pm_sseu (full-enable)
[197.537860] Subtest full-enable: SKIP (0.000s)
[197.630771] [049/131] (821s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[197.728770] Starting subtest: bo-too-small-due-to-tiling
[197.733151] Subtest bo-too-small-due-to-tiling: SKIP (0.000s)
[197.835051] [050/131] (821s left) sysfs_defaults (readonly)
[197.897386] Starting subtest: readonly
[197.899712] Starting dynamic subtest: vecs0
[197.902034] Dynamic subtest vecs0: SUCCESS (0.000s)
[197.910128] Starting dynamic subtest: ccs0
[197.912279] Dynamic subtest ccs0: SUCCESS (0.000s)
[197.919965] Starting dynamic subtest: vcs0
[197.922330] Dynamic subtest vcs0: SUCCESS (0.000s)
[197.935668] Starting dynamic subtest: bcs0
[197.937898] Dynamic subtest bcs0: SUCCESS (0.000s)
[197.945481] Starting dynamic subtest: rcs0
[197.947732] Dynamic subtest rcs0: SUCCESS (0.000s)
[197.955267] Starting dynamic subtest: vcs1
[197.957499] Dynamic subtest vcs1: SUCCESS (0.000s)
[197.959787] Subtest readonly: SUCCESS (0.004s)
[198.014996] [051/131] (821s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[198.118302] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip
[198.122600] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[198.220345] [052/131] (821s left) kms_feature_discovery (psr1)
[198.321929] Starting subtest: psr1
[198.324225] Subtest psr1: SUCCESS (0.000s)
[198.391468] [053/131] (821s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-plflip-blt)
[198.493906] Starting subtest: psr-1p-primscrn-indfb-plflip-blt
[205.870815] Subtest psr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.379s)
[206.016300] [054/131] (813s left) gem_ctx_bad_destroy (invalid-ctx)
[206.077873] Starting subtest: invalid-ctx
[206.080583] Subtest invalid-ctx: SUCCESS (0.000s)
[206.153514] [055/131] (813s left) gem_linear_blits (basic)
[206.431545] Starting subtest: basic
[206.442777] Subtest basic: SUCCESS (0.011s)
[206.595484] [056/131] (813s left) gem_exec_reloc (basic-gtt-wc-noreloc)
[206.688186] Subtest basic-gtt-wc-noreloc: SKIP (0.000s)
[206.748744] [057/131] (813s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area)
[211.795802] Starting subtest: fbc-pr-overlay-primary-update-sf-dmg-area
[211.800340] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.002s)
[212.628598] [058/131] (807s left) kms_writeback (writeback-check-output-xrgb2101010)
[212.715595] Subtest writeback-check-output-XRGB2101010: SKIP (0.000s)
[212.759794] [059/131] (807s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[212.868924] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[213.637311] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.770s)
[213.819725] [060/131] (806s left) gem_ctx_param (non-root-set)
[213.870854] Starting subtest: non-root-set
[213.899504] Subtest non-root-set: SUCCESS (0.029s)
[213.990405] [061/131] (805s left) kms_psr (fbc-psr-primary-render)
[214.080272] Starting subtest: fbc-psr-primary-render
[214.082204] Starting dynamic subtest: eDP-1
[214.086257] Dynamic subtest eDP-1: SKIP (0.000s)
[214.090329] Subtest fbc-psr-primary-render: SKIP (0.001s)
[214.153366] [062/131] (805s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted)
[214.219444] Starting subtest: multi-wait-available-unsubmitted-submitted
[214.221873] Subtest multi-wait-available-unsubmitted-submitted: SUCCESS (0.000s)
[214.332637] [063/131] (805s left) kms_flip (nonexisting-fb)
[214.424129] Starting subtest: nonexisting-fb
[214.426359] Starting dynamic subtest: A-eDP1
[215.620601] Dynamic subtest A-eDP1: SUCCESS (1.199s)
[215.629087] Starting dynamic subtest: B-eDP1
[216.756279] Dynamic subtest B-eDP1: SUCCESS (1.135s)
[216.764571] Starting dynamic subtest: C-eDP1
[217.884078] Dynamic subtest C-eDP1: SUCCESS (1.126s)
[217.892397] Starting dynamic subtest: D-eDP1
[219.014687] Dynamic subtest D-eDP1: SUCCESS (1.130s)
[219.016996] Subtest nonexisting-fb: SUCCESS (4.596s)
[220.185932] [064/131] (799s left) gem_spin_batch (resubmit-new-all)
[220.249766] Starting subtest: resubmit-new-all
[220.252233] Starting dynamic subtest: rcs0
[220.264715] Dynamic subtest rcs0: SUCCESS (0.014s)
[220.274391] Starting dynamic subtest: bcs0
[220.276660] Dynamic subtest bcs0: SUCCESS (0.011s)
[220.285864] Starting dynamic subtest: vcs0
[220.288295] Dynamic subtest vcs0: SUCCESS (0.009s)
[220.299113] Starting dynamic subtest: vcs1
[220.300888] Dynamic subtest vcs1: SUCCESS (0.009s)
[220.308537] Starting dynamic subtest: vecs0
[220.310746] Dynamic subtest vecs0: SUCCESS (0.009s)
[220.318219] Starting dynamic subtest: ccs0
[220.320073] Dynamic subtest ccs0: SUCCESS (0.009s)
[220.321934] Subtest resubmit-new-all: SUCCESS (0.063s)
[221.949866] [065/131] (798s left) syncobj_timeline (etime-single-wait-all-for-submit-submitted)
[222.008572] Starting subtest: etime-single-wait-all-for-submit-submitted
[222.112435] Subtest etime-single-wait-all-for-submit-submitted: SUCCESS (0.104s)
[222.189701] [066/131] (797s left) gem_exec_whisper (basic-fds-forked)
[222.250529] Starting subtest: basic-fds-forked
[246.490827] Subtest basic-fds-forked: SUCCESS (24.235s)
[246.683661] [067/131] (773s left) drm_mm (drm_mm)
[246.716471] Starting subtest: drm_mm
[246.757213] Starting dynamic subtest: drm_test_mm_init
[246.933537] Dynamic subtest drm_test_mm_init: SUCCESS (0.175s)
[246.940904] Starting dynamic subtest: drm_test_mm_debug
[247.088373] Dynamic subtest drm_test_mm_debug: SUCCESS (0.154s)
[247.095617] Starting dynamic subtest: drm_test_mm_align32
[247.231329] Dynamic subtest drm_test_mm_align32: SUCCESS (0.143s)
[247.238636] Starting dynamic subtest: drm_test_mm_align64
[247.361761] Dynamic subtest drm_test_mm_align64: SUCCESS (0.129s)
[247.370623] Starting dynamic subtest: drm_test_mm_lowest
[247.507922] Dynamic subtest drm_test_mm_lowest: SUCCESS (0.144s)
[247.515682] Starting dynamic subtest: drm_test_mm_highest
[247.649946] Dynamic subtest drm_test_mm_highest: SUCCESS (0.142s)
[247.651979] Subtest drm_mm: SUCCESS (0.932s)
[247.809613] [068/131] (772s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render)
[247.917437] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render
[247.921596] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[248.004684] [069/131] (772s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-wc)
[248.099532] Starting subtest: fbcpsr-rgb565-draw-mmap-wc
[254.891116] Subtest fbcpsr-rgb565-draw-mmap-wc: SUCCESS (6.795s)
[255.035112] [070/131] (765s left) kms_psr2_su (page_flip-p010)
[255.094846] Subtest page_flip-P010: SKIP (0.000s)
[255.153478] [071/131] (765s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[255.245427] Starting subtest: fbc-rgb565-draw-blt
[259.427845] Subtest fbc-rgb565-draw-blt: SUCCESS (4.186s)
[260.613996] [072/131] (759s left) syncobj_timeline (single-wait-available-signaled)
[260.675043] Starting subtest: single-wait-available-signaled
[260.677038] Subtest single-wait-available-signaled: SUCCESS (0.000s)
[260.766775] [073/131] (759s left) perf (missing-sample-flags)
[260.899062] Starting subtest: missing-sample-flags
[260.901338] Subtest missing-sample-flags: SUCCESS (0.000s)
[260.941781] [074/131] (759s left) kms_rotation_crc (primary-y-tiled-reflect-x-270)
[261.026756] Starting subtest: primary-y-tiled-reflect-x-270
[261.031226] Subtest primary-y-tiled-reflect-x-270: SKIP (0.001s)
[261.095997] [075/131] (759s left) gem_lmem_swapping (random-engines)
[261.136164] Subtest random-engines: SKIP (0.000s)
[261.184860] [076/131] (759s left) i915_query (test-query-geometry-subslices)
[261.245610] Starting subtest: test-query-geometry-subslices
[261.247811] Subtest test-query-geometry-subslices: SUCCESS (0.000s)
[261.356215] [077/131] (759s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[261.459113] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[261.463315] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[261.565846] [078/131] (759s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs)
[261.655669] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs
[261.657804] Starting dynamic subtest: pipe-A-eDP-1
[277.820106] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.497s)
[277.829486] Starting dynamic subtest: pipe-B-eDP-1
[294.723304] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.345s)
[294.733543] Starting dynamic subtest: pipe-C-eDP-1
[311.698072] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.271s)
[311.707302] Starting dynamic subtest: pipe-D-eDP-1
[328.704645] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.236s)
[328.706863] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs: SUCCESS (8.355s)
[329.902025] [079/131] (690s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-mc-ccs)
[329.995639] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-mc-ccs
[329.997876] Starting dynamic subtest: pipe-A-eDP-1
[330.020819] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[330.031661] Starting dynamic subtest: pipe-B-eDP-1
[330.052828] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[330.061204] Starting dynamic subtest: pipe-C-eDP-1
[330.083641] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[330.092905] Starting dynamic subtest: pipe-D-eDP-1
[330.113749] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[330.118081] Subtest crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.005s)
[332.274129] [080/131] (688s left) syncobj_wait (multi-wait-all-submitted)
[332.332313] Starting subtest: multi-wait-all-submitted
[332.438620] Subtest multi-wait-all-submitted: SUCCESS (0.106s)
[332.517169] [081/131] (688s left) kms_content_protection (legacy)
[332.663453] Starting subtest: legacy
[336.503277] Subtest legacy: SKIP (3.833s)
[337.712512] [082/131] (683s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[337.809206] Starting subtest: plane-use-after-nonblocking-unbind
[337.811336] Starting dynamic subtest: pipe-A-eDP-1
[337.877757] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.069s)
[338.185648] Starting dynamic subtest: pipe-B-eDP-1
[339.010909] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.832s)
[339.308740] Subtest plane-use-after-nonblocking-unbind: SUCCESS (1.498s)
[340.171211] [083/131] (680s left) gem_create (create-ext-cpu-access-big)
[340.339985] Starting subtest: create-ext-cpu-access-big
[340.344767] Subtest create-ext-cpu-access-big: SKIP (0.001s)
[340.414959] [084/131] (680s left) gem_exec_balancer (busy)
[340.484609] Starting subtest: busy
[342.630898] Subtest busy: SUCCESS (2.147s)
[342.970698] [085/131] (677s left) kms_psr (fbc-psr2-sprite-render)
[343.075883] Starting subtest: fbc-psr2-sprite-render
[343.077722] Starting dynamic subtest: eDP-1
[343.081737] Dynamic subtest eDP-1: SKIP (0.000s)
[343.085765] Subtest fbc-psr2-sprite-render: SKIP (0.001s)
[343.149227] [086/131] (677s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-onoff)
[343.255241] Starting subtest: psr-2p-scndscrn-cur-indfb-onoff
[343.259540] Subtest psr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[343.347663] [087/131] (677s left) kms_flip (flip-vs-expired-vblank)
[343.442958] Starting subtest: flip-vs-expired-vblank
[343.444897] Starting dynamic subtest: A-eDP1
[352.170314] Dynamic subtest A-eDP1: SUCCESS (8.727s)
[352.182098] Starting dynamic subtest: B-eDP1
[360.805264] Dynamic subtest B-eDP1: SUCCESS (8.630s)
[360.820221] Starting dynamic subtest: C-eDP1
[369.436034] Dynamic subtest C-eDP1: SUCCESS (8.626s)
[369.446346] Starting dynamic subtest: D-eDP1
[378.072175] Dynamic subtest D-eDP1: SUCCESS (8.631s)
[378.077260] Subtest flip-vs-expired-vblank: SUCCESS (34.626s)
[379.241416] [088/131] (641s left) gem_userptr_blits (readonly-pwrite-unsync)
[379.317164] Subtest readonly-pwrite-unsync: SKIP (0.000s)
[379.458065] [089/131] (641s left) gem_pwrite (basic-self)
[379.517637] Subtest basic-self: SKIP (0.000s)
[379.576067] [090/131] (641s left) kms_lease (lease-again)
[379.672652] Starting subtest: lease-again
[379.675151] Starting dynamic subtest: pipe-A-eDP-1
[379.677462] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.003s)
[379.688939] Starting dynamic subtest: pipe-B-eDP-1
[379.691026] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[379.699543] Starting dynamic subtest: pipe-C-eDP-1
[379.701634] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[379.710154] Starting dynamic subtest: pipe-D-eDP-1
[379.712244] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[379.714293] Subtest lease-again: SUCCESS (0.012s)
[379.783114] [091/131] (641s left) syncobj_wait (invalid-wait-bad-flags)
[379.840759] Starting subtest: invalid-wait-bad-flags
[379.843037] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[379.940160] [092/131] (641s left) gem_exec_params (invalid-bsd2-flag-on-render)
[380.013190] Starting subtest: invalid-bsd2-flag-on-render
[380.015626] Subtest invalid-bsd2-flag-on-render: SUCCESS (0.000s)
[380.125706] [093/131] (641s left) gem_partial_pwrite_pread (write-snoop)
[380.182106] Subtest write-snoop: SKIP (0.000s)
[380.268098] [094/131] (641s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[380.363242] Starting subtest: nonblocking-crc-frame-sequence
[380.364790] Starting dynamic subtest: pipe-A-eDP-1
[381.097548] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.732s)
[381.106990] Starting dynamic subtest: pipe-B-eDP-1
[382.470207] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.372s)
[382.480648] Starting dynamic subtest: pipe-C-eDP-1
[383.857892] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.386s)
[383.866711] Starting dynamic subtest: pipe-D-eDP-1
[385.189431] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.329s)
[385.191810] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.825s)
[385.995459] [095/131] (635s left) kms_pm_dc (dc5-retention-flops)
[387.091232] Starting subtest: dc5-retention-flops
[387.095347] Subtest dc5-retention-flops: SKIP (0.000s)
[387.162405] [096/131] (634s left) kms_chamelium_frames (dp-crc-multiple)
[387.558797] Subtest dp-crc-multiple: SKIP (0.000s)
[388.422648] [097/131] (632s left) kms_flip (flip-vs-rmfb)
[388.517632] Starting subtest: flip-vs-rmfb
[388.519898] Starting dynamic subtest: A-eDP1
[397.236684] Dynamic subtest A-eDP1: SUCCESS (8.715s)
[397.246503] Starting dynamic subtest: B-eDP1
[405.867716] Dynamic subtest B-eDP1: SUCCESS (8.626s)
[405.879462] Starting dynamic subtest: C-eDP1
[414.500746] Dynamic subtest C-eDP1: SUCCESS (8.628s)
[414.510901] Starting dynamic subtest: D-eDP1
[423.146251] Dynamic subtest D-eDP1: SUCCESS (8.640s)
[423.150911] Subtest flip-vs-rmfb: SUCCESS (34.618s)
[424.329732] [098/131] (597s left) kms_panel_fitting (legacy)
[424.421657] Starting subtest: legacy
[424.423906] Starting dynamic subtest: pipe-A-eDP-1
[424.980325] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.558s)
[425.007853] Starting dynamic subtest: pipe-B-eDP-1
[426.505980] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.506s)
[426.535695] Starting dynamic subtest: pipe-C-eDP-1
[428.092557] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.564s)
[428.118741] Starting dynamic subtest: pipe-D-eDP-1
[429.612704] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.500s)
[429.615064] Subtest legacy: SUCCESS (5.189s)
[430.871137] [099/131] (590s left) kms_color (legacy-gamma)
[430.975236] Starting subtest: legacy-gamma
[430.977386] Starting dynamic subtest: pipe-A-eDP-1
[431.696372] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.721s)
[431.707098] Starting dynamic subtest: pipe-B-eDP-1
[433.061800] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.364s)
[433.070991] Starting dynamic subtest: pipe-C-eDP-1
[434.469214] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.406s)
[434.480392] Starting dynamic subtest: pipe-D-eDP-1
[435.783778] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.310s)
[435.785948] Subtest legacy-gamma: SUCCESS (4.805s)
[436.615927] [100/131] (584s left) kms_invalid_mode (bad-hsync-end)
[436.720473] Starting subtest: bad-hsync-end
[436.722758] Starting dynamic subtest: pipe-A-eDP-1
[437.001285] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.284s)
[437.011302] Starting dynamic subtest: pipe-B-eDP-1
[437.013521] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[437.022195] Starting dynamic subtest: pipe-C-eDP-1
[437.024558] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[437.033581] Starting dynamic subtest: pipe-D-eDP-1
[437.035536] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[437.037504] Subtest bad-hsync-end: SUCCESS (0.289s)
[437.850806] [101/131] (583s left) kms_scaling_modes (scaling-mode-center)
[437.953713] Starting subtest: scaling-mode-center
[437.958410] Starting dynamic subtest: pipe-A-eDP-1
[438.054368] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.102s)
[438.063446] Starting dynamic subtest: pipe-B-eDP-1
[439.182688] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.126s)
[439.194025] Starting dynamic subtest: pipe-C-eDP-1
[440.286812] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.102s)
[440.297118] Starting dynamic subtest: pipe-D-eDP-1
[441.419305] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.131s)
[441.421443] Subtest scaling-mode-center: SUCCESS (3.467s)
[442.586072] [102/131] (579s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions)
[442.700265] Starting subtest: cursorB-vs-flipA-atomic-transitions
[442.705107] Subtest cursorB-vs-flipA-atomic-transitions: SKIP (0.001s)
[442.844100] [103/131] (578s left) kms_ccs (missing-ccs-buffer-y-tiled-ccs)
[442.936322] Starting subtest: missing-ccs-buffer-y-tiled-ccs
[442.938628] Starting dynamic subtest: pipe-A-eDP-1
[442.961243] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[442.971890] Starting dynamic subtest: pipe-B-eDP-1
[442.993545] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[443.001954] Starting dynamic subtest: pipe-C-eDP-1
[443.023767] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[443.031921] Starting dynamic subtest: pipe-D-eDP-1
[443.054316] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[443.058391] Subtest missing-ccs-buffer-y-tiled-ccs: SKIP (0.004s)
[445.157909] [104/131] (576s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[445.255674] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-blt
[445.259984] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[445.347667] [105/131] (576s left) gem_exec_schedule (preempt-queue-contexts)
[445.426689] Subtest preempt-queue-contexts: SKIP (0.000s)
[445.705212] [106/131] (576s left) kms_getfb (getfb2-handle-protection)
[445.809494] Starting subtest: getfb2-handle-protection
[445.835851] Subtest getfb2-handle-protection: SUCCESS (0.027s)
[445.979170] [107/131] (575s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[446.073105] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[446.077474] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[446.193986] [108/131] (575s left) gem_exec_reloc (basic-wc-read)
[446.259746] Subtest basic-wc-read: SKIP (0.000s)
[446.352116] [109/131] (575s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area-big-fb)
[451.306568] Starting subtest: pr-primary-plane-update-sf-dmg-area-big-fb
[451.311646] Subtest pr-primary-plane-update-sf-dmg-area-big-fb: SKIP (0.002s)
[452.172629] [110/131] (569s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible)
[452.275668] Starting subtest: flip-vs-absolute-wf_vblank-interruptible
[452.277921] Starting dynamic subtest: A-eDP1
[461.234518] Dynamic subtest A-eDP1: SUCCESS (8.954s)
[461.244613] Starting dynamic subtest: B-eDP1
[470.162563] Dynamic subtest B-eDP1: SUCCESS (8.922s)
[470.174150] Starting dynamic subtest: C-eDP1
[479.097898] Dynamic subtest C-eDP1: SUCCESS (8.930s)
[479.106815] Starting dynamic subtest: D-eDP1
[487.953489] Dynamic subtest D-eDP1: SUCCESS (8.851s)
[487.958152] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (35.667s)
[489.178573] [111/131] (532s left) i915_pm_rc6_residency (media-rc6-accuracy)
[489.229446] Starting subtest: media-rc6-accuracy
[489.233693] Subtest media-rc6-accuracy: SKIP (0.000s)
[489.347023] [112/131] (532s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[489.437024] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[489.439307] Starting dynamic subtest: pipe-A
[489.772755] Dynamic subtest pipe-A: SUCCESS (0.338s)
[489.783803] Starting dynamic subtest: pipe-B
[491.165065] Dynamic subtest pipe-B: SUCCESS (1.390s)
[491.174481] Starting dynamic subtest: pipe-C
[492.596199] Dynamic subtest pipe-C: SUCCESS (1.430s)
[492.605671] Starting dynamic subtest: pipe-D
[494.034702] Dynamic subtest pipe-D: SUCCESS (1.436s)
[494.036934] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (4.599s)
[495.208472] [113/131] (526s left) gem_userptr_blits (unsync-unmap-after-close)
[495.286330] Subtest unsync-unmap-after-close: SKIP (0.000s)
[495.414387] [114/131] (526s left) gen9_exec_parse (unaligned-access)
[495.475733] Subtest unaligned-access: SKIP (0.000s)
[495.537412] [115/131] (526s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[495.643021] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[496.411533] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.770s)
[496.604472] [116/131] (525s left) kms_async_flips (crc)
[496.700493] Starting subtest: crc
[497.070945] Starting dynamic subtest: pipe-A-eDP-1
[500.028130] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.955s)
[500.409685] Starting dynamic subtest: pipe-B-eDP-1
[503.338995] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.939s)
[503.707482] Starting dynamic subtest: pipe-C-eDP-1
[506.556913] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.856s)
[506.919072] Starting dynamic subtest: pipe-D-eDP-1
[509.783466] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.873s)
[509.785826] Subtest crc: SUCCESS (13.079s)
[510.950131] [117/131] (511s left) perf (enable-disable)
[511.026471] Starting subtest: enable-disable
[511.028854] Starting dynamic subtest: 0-rcs0
[513.276627] Dynamic subtest 0-rcs0: SUCCESS (2.247s)
[513.286621] Starting dynamic subtest: 1-vcs1
[517.724381] Dynamic subtest 1-vcs1: SUCCESS (4.445s)
[517.726660] Subtest enable-disable: SUCCESS (6.693s)
[517.762417] [118/131] (504s left) perf (non-system-wide-paranoid)
[517.835865] Starting subtest: non-system-wide-paranoid
[517.857287] Subtest non-system-wide-paranoid: SUCCESS (0.024s)
[517.903809] [119/131] (504s left) kms_sequence (get-idle)
[517.991615] Starting subtest: get-idle
[517.993937] Starting dynamic subtest: pipe-A-eDP-1
[519.789955] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.798s)
[519.800255] Starting dynamic subtest: pipe-B-eDP-1
[522.216623] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.425s)
[522.226702] Starting dynamic subtest: pipe-C-eDP-1
[524.640608] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.421s)
[524.650672] Starting dynamic subtest: pipe-D-eDP-1
[527.080717] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.436s)
[527.083027] Subtest get-idle: SUCCESS (9.085s)
[527.891201] [120/131] (494s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[528.007673] Starting subtest: fbc-indfb-scaledprimary
[533.127628] Subtest fbc-indfb-scaledprimary: SUCCESS (5.127s)
[534.304905] [121/131] (488s left) kms_frontbuffer_tracking (fbc-1p-shrfb-fliptrack-mmap-gtt)
[534.411037] Starting subtest: fbc-1p-shrfb-fliptrack-mmap-gtt
[534.415409] Subtest fbc-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[534.521849] [122/131] (487s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-fullscreen)
[534.611482] Starting subtest: fbc-2p-primscrn-spr-indfb-fullscreen
[534.615577] Subtest fbc-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[534.702547] [123/131] (487s left) kms_pm_rpm (pm-tiling)
[536.733240] Starting subtest: pm-tiling
[536.738144] Subtest pm-tiling: SKIP (0.000s)
[537.106771] [124/131] (485s left) gem_exec_params (invalid-flag)
[537.154949] Starting subtest: invalid-flag
[537.157210] Subtest invalid-flag: SUCCESS (0.000s)
[537.281029] [125/131] (485s left) gem_exec_create (forked)
[537.335433] Starting subtest: forked
[537.337710] Starting dynamic subtest: smem
[558.546903] Dynamic subtest smem: SUCCESS (21.250s)
[558.549809] Subtest forked: SUCCESS (21.252s)
[558.727427] [126/131] (463s left) gem_softpin (allocator-basic-reserve)
[558.802914] Starting subtest: allocator-basic-reserve
[558.872683] Subtest allocator-basic-reserve: SUCCESS (0.070s)
[559.002015] [127/131] (463s left) gem_exec_balancer (invalid-bonds)
[559.065880] Starting subtest: invalid-bonds
[559.070611] Subtest invalid-bonds: SKIP (0.000s)
[559.368051] [128/131] (463s left) gem_exec_schedule (pi-common)
[559.545580] Starting subtest: pi-common
[559.547732] Starting dynamic subtest: rcs0
[559.630361] Dynamic subtest rcs0: SUCCESS (0.088s)
[559.642342] Starting dynamic subtest: bcs0
[559.716921] Dynamic subtest bcs0: SUCCESS (0.086s)
[559.729193] Starting dynamic subtest: vcs0
[559.800311] Dynamic subtest vcs0: SUCCESS (0.083s)
[559.816486] Starting dynamic subtest: vcs1
[559.880046] Dynamic subtest vcs1: SUCCESS (0.079s)
[559.897542] Starting dynamic subtest: vecs0
[559.959020] Dynamic subtest vecs0: SUCCESS (0.078s)
[559.975787] Starting dynamic subtest: ccs0
[560.037438] Dynamic subtest ccs0: SUCCESS (0.078s)
[560.039971] Subtest pi-common: SUCCESS (0.497s)
[560.289923] [129/131] (462s left) gem_exec_reloc (basic-cpu-read)
[560.346947] Subtest basic-cpu-read: SKIP (0.000s)
[560.427249] [130/131] (462s left) i915_drm_fdinfo (virtual-busy-idle)
[560.465494] Starting subtest: virtual-busy-idle
[560.469966] Subtest virtual-busy-idle: SKIP (0.000s)
[560.531548] [131/131] (462s left) kms_psr (pr-sprite-render)
[560.635364] Starting subtest: pr-sprite-render
[560.639425] Subtest pr-sprite-render: SKIP (0.000s)
[560.705099] Closing watchdogs
results: parsing output: 0/ for test: perf_pmu
results: parsing output: 1/ for test: syncobj_wait
results: parsing output: 2/ for test: gem_wait
results: parsing output: 3/ for test: kms_plane_lowres
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: gem_exec_fence
results: parsing output: 6/ for test: syncobj_timeline
results: parsing output: 7/ for test: gem_ctx_param
results: parsing output: 8/ for test: syncobj_wait
results: parsing output: 9/ for test: kms_dsc
results: parsing output: 10/ for test: gem_render_copy
results: parsing output: 11/ for test: gem_exec_schedule
results: parsing output: 12/ for test: kms_psr
results: parsing output: 13/ for test: api_intel_bb
results: parsing output: 14/ for test: gem_create
results: parsing output: 15/ for test: kms_big_fb
results: parsing output: 16/ for test: kms_ccs
results: parsing output: 17/ for test: kms_big_fb
results: parsing output: 18/ for test: kms_atomic
results: parsing output: 19/ for test: gem_mmap_offset
results: parsing output: 20/ for test: perf_pmu
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: gem_exec_params
results: parsing output: 23/ for test: api_intel_bb
results: parsing output: 24/ for test: gem_userptr_blits
results: parsing output: 25/ for test: syncobj_timeline
results: parsing output: 26/ for test: gem_ctx_shared
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: gem_userptr_blits
results: parsing output: 29/ for test: gem_mmap_gtt
results: parsing output: 30/ for test: sw_sync
results: parsing output: 31/ for test: gem_mmap_wc
results: parsing output: 32/ for test: kms_cursor_edge_walk
results: parsing output: 33/ for test: prime_vgem
results: parsing output: 34/ for test: gem_tiled_wc
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: gem_exec_fence
results: parsing output: 37/ for test: kms_flip_scaled_crc
results: parsing output: 38/ for test: gem_mmap_gtt
results: parsing output: 39/ for test: kms_plane_scaling
results: parsing output: 40/ for test: kms_chamelium_hpd
results: parsing output: 41/ for test: kms_cursor_crc
results: parsing output: 42/ for test: sw_sync
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: kms_cursor_crc
results: parsing output: 45/ for test: kms_big_fb
results: parsing output: 46/ for test: kms_ccs
results: parsing output: 47/ for test: i915_pm_sseu
results: parsing output: 48/ for test: kms_addfb_basic
results: parsing output: 49/ for test: sysfs_defaults
results: parsing output: 50/ for test: kms_big_fb
results: parsing output: 51/ for test: kms_feature_discovery
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: gem_ctx_bad_destroy
results: parsing output: 54/ for test: gem_linear_blits
results: parsing output: 55/ for test: gem_exec_reloc
results: parsing output: 56/ for test: kms_psr2_sf
results: parsing output: 57/ for test: kms_writeback
results: parsing output: 58/ for test: kms_big_fb
results: parsing output: 59/ for test: gem_ctx_param
results: parsing output: 60/ for test: kms_psr
results: parsing output: 61/ for test: syncobj_timeline
results: parsing output: 62/ for test: kms_flip
results: parsing output: 63/ for test: gem_spin_batch
results: parsing output: 64/ for test: syncobj_timeline
results: parsing output: 65/ for test: gem_exec_whisper
results: parsing output: 66/ for test: drm_mm
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: kms_psr2_su
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: syncobj_timeline
results: parsing output: 72/ for test: perf
results: parsing output: 73/ for test: kms_rotation_crc
results: parsing output: 74/ for test: gem_lmem_swapping
results: parsing output: 75/ for test: i915_query
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_ccs
results: parsing output: 78/ for test: kms_ccs
results: parsing output: 79/ for test: syncobj_wait
results: parsing output: 80/ for test: kms_content_protection
results: parsing output: 81/ for test: kms_atomic_transition
results: parsing output: 82/ for test: gem_create
results: parsing output: 83/ for test: gem_exec_balancer
results: parsing output: 84/ for test: kms_psr
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: kms_flip
results: parsing output: 87/ for test: gem_userptr_blits
results: parsing output: 88/ for test: gem_pwrite
results: parsing output: 89/ for test: kms_lease
results: parsing output: 90/ for test: syncobj_wait
results: parsing output: 91/ for test: gem_exec_params
results: parsing output: 92/ for test: gem_partial_pwrite_pread
results: parsing output: 93/ for test: kms_pipe_crc_basic
results: parsing output: 94/ for test: kms_pm_dc
results: parsing output: 95/ for test: kms_chamelium_frames
results: parsing output: 96/ for test: kms_flip
results: parsing output: 97/ for test: kms_panel_fitting
results: parsing output: 98/ for test: kms_color
results: parsing output: 99/ for test: kms_invalid_mode
results: parsing output: 100/ for test: kms_scaling_modes
results: parsing output: 101/ for test: kms_cursor_legacy
results: parsing output: 102/ for test: kms_ccs
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: gem_exec_schedule
results: parsing output: 105/ for test: kms_getfb
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: gem_exec_reloc
results: parsing output: 108/ for test: kms_psr2_sf
results: parsing output: 109/ for test: kms_flip
results: parsing output: 110/ for test: i915_pm_rc6_residency
results: parsing output: 111/ for test: kms_plane_scaling
results: parsing output: 112/ for test: gem_userptr_blits
results: parsing output: 113/ for test: gen9_exec_parse
results: parsing output: 114/ for test: kms_big_fb
results: parsing output: 115/ for test: kms_async_flips
results: parsing output: 116/ for test: perf
results: parsing output: 117/ for test: perf
results: parsing output: 118/ for test: kms_sequence
results: parsing output: 119/ for test: kms_frontbuffer_tracking
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_pm_rpm
results: parsing output: 123/ for test: gem_exec_params
results: parsing output: 124/ for test: gem_exec_create
results: parsing output: 125/ for test: gem_softpin
results: parsing output: 126/ for test: gem_exec_balancer
results: parsing output: 127/ for test: gem_exec_schedule
results: parsing output: 128/ for test: gem_exec_reloc
results: parsing output: 129/ for test: i915_drm_fdinfo
results: parsing output: 130/ for test: kms_psr
Done.