[57.195937] Initializing watchdogs
[57.195984]   /dev/watchdog0
[57.211157] [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.215976] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.218425] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.222156] [001/133] (960s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[57.341450] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-fullscreen
[57.345687] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[57.427989] [002/133] (960s left) kms_flip (2x-blocking-absolute-wf_vblank-interruptible)
[57.520204] Starting subtest: 2x-blocking-absolute-wf_vblank-interruptible
[57.532225] Subtest 2x-blocking-absolute-wf_vblank-interruptible: SKIP (0.011s)
[57.605367] [003/133] (960s left) kms_cursor_legacy (short-flip-after-cursor-toggle)
[57.735939] Starting subtest: short-flip-after-cursor-toggle
[58.724066] Subtest short-flip-after-cursor-toggle: SUCCESS (0.984s)
[59.576517] [004/133] (958s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[59.697235] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[60.500758] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.804s)
[60.660360] [005/133] (957s left) kms_flip (2x-flip-vs-blocking-wf-vblank)
[60.747038] Starting subtest: 2x-flip-vs-blocking-wf-vblank
[60.759448] Subtest 2x-flip-vs-blocking-wf-vblank: SKIP (0.012s)
[60.846500] [006/133] (957s left) gem_ctx_persistence (hostile)
[60.915063] Starting subtest: hostile
[71.080527] Subtest hostile: SUCCESS (10.155s)
[71.228120] [007/133] (946s left) core_auth (getclient-simple)
[71.267092] Starting subtest: getclient-simple
[71.292494] Subtest getclient-simple: SUCCESS (0.025s)
[71.418531] [008/133] (946s left) vgem_basic (bad-handle)
[71.496104] Starting subtest: bad-handle
[71.498298] Subtest bad-handle: SUCCESS (0.000s)
[71.549187] [FACT vgem_basic (bad-handle)] new: kernel.kmod_is_loaded.vgem: true
[71.552392] [009/133] (946s left) gem_ctx_bad_destroy (invalid-ctx)
[71.609264] Starting subtest: invalid-ctx
[71.612181] Subtest invalid-ctx: SUCCESS (0.000s)
[71.700647] [010/133] (946s left) syncobj_wait (wait-all-interrupted)
[71.755175] Starting subtest: wait-all-interrupted
[71.856203] Subtest wait-all-interrupted: SUCCESS (0.102s)
[71.938435] [011/133] (946s left) kms_flip (basic-flip-vs-wf_vblank)
[72.036055] Starting subtest: basic-flip-vs-wf_vblank
[72.038487] Starting dynamic subtest: A-eDP1
[73.917978] Dynamic subtest A-eDP1: SUCCESS (1.881s)
[73.926549] Starting dynamic subtest: B-eDP1
[75.855630] Dynamic subtest B-eDP1: SUCCESS (1.934s)
[75.865680] Starting dynamic subtest: C-eDP1
[77.777820] Dynamic subtest C-eDP1: SUCCESS (1.923s)
[77.787290] Starting dynamic subtest: D-eDP1
[79.708650] Dynamic subtest D-eDP1: SUCCESS (1.930s)
[79.711112] Subtest basic-flip-vs-wf_vblank: SUCCESS (7.675s)
[80.940704] [012/133] (937s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[80.994072] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[80.998802] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[81.175109] [013/133] (937s left) gem_mmap_gtt (basic-read-write-distinct)
[81.233995] Subtest basic-read-write-distinct: SKIP (0.000s)
[81.294054] [014/133] (936s left) gem_fenced_exec_thrash (too-many-fences)
[81.347305] Subtest too-many-fences: SKIP (0.000s)
[81.411620] [015/133] (936s left) gem_spin_batch (spin-each)
[81.473322] Starting subtest: spin-each
[84.738610] Subtest spin-each: SUCCESS (3.258s)
[84.974856] [016/133] (933s left) kms_plane (plane-position-covered)
[85.084923] Starting subtest: plane-position-covered
[85.599687] Starting dynamic subtest: pipe-A-plane-1
[86.906621] Dynamic subtest pipe-A-plane-1: SUCCESS (1.310s)
[86.916532] Starting dynamic subtest: pipe-A-plane-2
[88.148590] Dynamic subtest pipe-A-plane-2: SUCCESS (1.241s)
[88.158090] Starting dynamic subtest: pipe-A-plane-3
[89.489368] Dynamic subtest pipe-A-plane-3: SUCCESS (1.341s)
[89.498508] Starting dynamic subtest: pipe-A-plane-4
[90.800280] Dynamic subtest pipe-A-plane-4: SUCCESS (1.310s)
[90.809706] Starting dynamic subtest: pipe-A-plane-5
[90.825132] Dynamic subtest pipe-A-plane-5: SUCCESS (0.024s)
[91.964082] Starting dynamic subtest: pipe-B-plane-1
[93.283203] Dynamic subtest pipe-B-plane-1: SUCCESS (1.326s)
[93.291426] Starting dynamic subtest: pipe-B-plane-2
[94.591968] Dynamic subtest pipe-B-plane-2: SUCCESS (1.306s)
[94.601226] Starting dynamic subtest: pipe-B-plane-3
[95.854070] Dynamic subtest pipe-B-plane-3: SUCCESS (1.263s)
[95.865036] Starting dynamic subtest: pipe-B-plane-4
[97.156079] Dynamic subtest pipe-B-plane-4: SUCCESS (1.303s)
[97.165733] Starting dynamic subtest: pipe-B-plane-5
[97.182230] Dynamic subtest pipe-B-plane-5: SUCCESS (0.024s)
[97.184552] Subtest plane-position-covered: SUCCESS (12.097s)
[97.963534] [017/133] (920s left) kms_vblank (query-busy-hang)
[98.067038] Starting subtest: query-busy-hang
[98.069307] Starting dynamic subtest: pipe-A-eDP-1
[125.657561] Dynamic subtest pipe-A-eDP-1: SUCCESS (27.589s)
[125.671446] Starting dynamic subtest: pipe-D-eDP-1
[150.679477] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.021s)
[150.681805] Subtest query-busy-hang: SUCCESS (52.613s)
[151.509260] [018/133] (866s left) syncobj_timeline (multi-wait-all-signaled)
[151.574255] Starting subtest: multi-wait-all-signaled
[151.576576] Subtest multi-wait-all-signaled: SUCCESS (0.001s)
[151.681102] [019/133] (866s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[151.728875] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[151.836109] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.107s)
[151.913384] [020/133] (866s left) kms_vblank (wait-forked-busy)
[151.999401] Starting subtest: wait-forked-busy
[152.001665] Starting dynamic subtest: pipe-A-eDP-1
[153.883841] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.885s)
[153.900358] Starting dynamic subtest: pipe-D-eDP-1
[156.468576] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.580s)
[156.470806] Subtest wait-forked-busy: SUCCESS (4.469s)
[157.286449] [021/133] (861s left) perf_pmu (semaphore-wait-idle)
[157.324911] Starting subtest: semaphore-wait-idle
[157.327177] Starting dynamic subtest: rcs0
[157.837764] Dynamic subtest rcs0: SUCCESS (0.513s)
[157.848409] Starting dynamic subtest: bcs0
[158.351319] Dynamic subtest bcs0: SUCCESS (0.511s)
[158.360473] Starting dynamic subtest: vcs0
[158.863957] Dynamic subtest vcs0: SUCCESS (0.511s)
[158.873559] Starting dynamic subtest: vcs1
[159.377335] Dynamic subtest vcs1: SUCCESS (0.511s)
[159.388635] Starting dynamic subtest: vecs0
[159.891216] Dynamic subtest vecs0: SUCCESS (0.512s)
[159.899843] Starting dynamic subtest: ccs0
[160.405935] Dynamic subtest ccs0: SUCCESS (0.513s)
[160.408992] Subtest semaphore-wait-idle: SUCCESS (3.081s)
[162.036045] [022/133] (856s left) kms_addfb_basic (unused-handle)
[162.093928] Starting subtest: unused-handle
[162.095869] Subtest unused-handle: SUCCESS (0.000s)
[162.234472] [023/133] (856s left) gen9_exec_parse (batch-zero-length)
[162.289525] Subtest batch-zero-length: SKIP (0.000s)
[162.359980] [024/133] (856s left) gem_eio (throttle)
[162.549763] Starting subtest: throttle
[162.799125] Subtest throttle: SUCCESS (0.249s)
[162.951836] [025/133] (855s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-cpu)
[163.055374] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-cpu
[163.058845] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[163.137610] [026/133] (855s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-render)
[163.241139] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-render
[167.505815] Subtest fbc-1p-offscreen-pri-shrfb-draw-render: SUCCESS (4.266s)
[168.703648] [027/133] (850s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs-cc)
[168.803770] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs-cc
[168.806018] Starting dynamic subtest: pipe-A-eDP-1
[169.563215] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.751s)
[169.575737] Starting dynamic subtest: pipe-B-eDP-1
[171.405619] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.840s)
[171.416493] Starting dynamic subtest: pipe-C-eDP-1
[173.232340] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.826s)
[173.242415] Starting dynamic subtest: pipe-D-eDP-1
[175.061723] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.828s)
[175.063673] Subtest random-ccs-data-4-tiled-mtl-rc-ccs-cc: SUCCESS (6.250s)
[176.196887] [028/133] (842s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[176.300305] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[176.312863] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.011s)
[176.426605] [029/133] (842s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[176.520256] Starting subtest: compare-crc-sanitycheck-xr24
[176.522497] Starting dynamic subtest: pipe-A-eDP-1
[177.152506] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.633s)
[177.162687] Starting dynamic subtest: pipe-B-eDP-1
[178.466776] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.311s)
[178.477645] Starting dynamic subtest: pipe-C-eDP-1
[179.738655] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.271s)
[179.747058] Starting dynamic subtest: pipe-D-eDP-1
[180.965464] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.227s)
[180.967893] Subtest compare-crc-sanitycheck-xr24: SUCCESS (4.446s)
[181.804304] [030/133] (837s left) dumb_buffer (create-clear)
[181.861294] Starting subtest: create-clear
[215.928024] Subtest create-clear: SUCCESS (34.062s)
[216.030906] [031/133] (802s left) kms_psr (fbc-psr2-sprite-mmap-gtt)
[216.129738] Starting subtest: fbc-psr2-sprite-mmap-gtt
[216.132046] Starting dynamic subtest: eDP-1
[216.136333] Dynamic subtest eDP-1: SKIP (0.000s)
[216.140685] Subtest fbc-psr2-sprite-mmap-gtt: SKIP (0.001s)
[216.216286] [032/133] (802s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset-interruptible)
[216.316335] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset-interruptible
[216.327631] Subtest 2x-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.011s)
[216.408933] [033/133] (802s left) gem_exec_schedule (pi-ringfull)
[216.591150] Starting subtest: pi-ringfull
[216.593616] Starting dynamic subtest: rcs0
[216.620365] Dynamic subtest rcs0: SUCCESS (0.032s)
[216.636654] Starting dynamic subtest: bcs0
[216.651524] Dynamic subtest bcs0: SUCCESS (0.031s)
[216.663344] Starting dynamic subtest: vcs0
[216.681285] Dynamic subtest vcs0: SUCCESS (0.029s)
[216.692861] Starting dynamic subtest: vcs1
[216.709962] Dynamic subtest vcs1: SUCCESS (0.028s)
[216.722669] Starting dynamic subtest: vecs0
[216.737558] Dynamic subtest vecs0: SUCCESS (0.027s)
[216.750443] Starting dynamic subtest: ccs0
[216.764046] Dynamic subtest ccs0: SUCCESS (0.026s)
[216.766238] Subtest pi-ringfull: SUCCESS (0.176s)
[216.999206] [034/133] (802s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite)
[217.092068] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite
[217.096057] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[217.186812] [035/133] (801s left) kms_cursor_edge_walk (64x64-right-edge)
[217.276085] Starting subtest: 64x64-right-edge
[217.278383] Starting dynamic subtest: pipe-A-eDP-1
[220.774696] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.499s)
[220.783831] Starting dynamic subtest: pipe-D-eDP-1
[223.888261] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.111s)
[223.890139] Subtest 64x64-right-edge: SUCCESS (6.612s)
[224.697228] [036/133] (794s left) gem_mmap_wc (bad-size)
[224.760030] Subtest bad-size: SKIP (0.000s)
[224.822447] [037/133] (794s left) api_intel_allocator (gem-pool)
[224.874215] Starting subtest: gem-pool
[224.880654] Subtest gem-pool: SUCCESS (0.009s)
[224.995031] [038/133] (794s left) kms_psr2_sf (pr-plane-move-sf-dmg-area)
[225.091758] Starting subtest: pr-plane-move-sf-dmg-area
[225.096172] Subtest pr-plane-move-sf-dmg-area: SKIP (0.000s)
[225.163403] [039/133] (794s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-render)
[225.260589] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-render
[229.737179] Subtest fbc-1p-primscrn-pri-indfb-draw-render: SUCCESS (4.479s)
[230.891170] [040/133] (788s left) kms_frontbuffer_tracking (psr-rgb101010-draw-blt)
[230.995571] Starting subtest: psr-rgb101010-draw-blt
[238.371492] Subtest psr-rgb101010-draw-blt: SUCCESS (7.380s)
[238.496390] [041/133] (780s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc)
[238.588166] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc
[245.694206] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (7.109s)
[245.833690] [042/133] (773s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-20x20)
[245.931218] Starting subtest: planes-downscale-factor-0-75-upscale-20x20
[245.933596] Starting dynamic subtest: pipe-A
[245.993892] Dynamic subtest pipe-A: SUCCESS (0.065s)
[246.005271] Starting dynamic subtest: pipe-B
[247.107630] Dynamic subtest pipe-B: SUCCESS (1.113s)
[247.117785] Starting dynamic subtest: pipe-C
[248.215127] Dynamic subtest pipe-C: SUCCESS (1.106s)
[248.224153] Starting dynamic subtest: pipe-D
[249.354885] Dynamic subtest pipe-D: SUCCESS (1.139s)
[249.357221] Subtest planes-downscale-factor-0-75-upscale-20x20: SUCCESS (3.427s)
[250.517300] [043/133] (768s left) gem_gpgpu_fill (basic)
[250.581309] Starting subtest: basic
[250.583639] Starting dynamic subtest: smem
[250.592049] Dynamic subtest smem: SUCCESS (0.010s)
[250.594707] Subtest basic: SUCCESS (0.011s)
[250.735804] [044/133] (768s left) kms_atomic_interruptible (universal-setplane-primary)
[250.844881] Starting subtest: universal-setplane-primary
[250.847044] Starting dynamic subtest: pipe-A-eDP-1
[258.265815] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.419s)
[258.268099] Subtest universal-setplane-primary: SUCCESS (7.420s)
[259.086393] [045/133] (760s left) gem_exec_fence (long-history)
[259.503693] Starting subtest: long-history
[266.625519] Subtest long-history: SUCCESS (7.122s)
[267.167117] [046/133] (752s left) kms_rotation_crc (primary-y-tiled-reflect-x-90)
[267.260194] Starting subtest: primary-y-tiled-reflect-x-90
[267.264524] Subtest primary-y-tiled-reflect-x-90: SKIP (0.001s)
[267.331432] [047/133] (752s left) gem_close_race (multigpu-basic-process)
[267.399497] Starting subtest: multigpu-basic-process
[267.403801] Subtest multigpu-basic-process: SKIP (0.000s)
[267.636155] [048/133] (752s left) kms_draw_crc (draw-method-pwrite)
[267.728095] Starting subtest: draw-method-pwrite
[267.730046] Starting dynamic subtest: xrgb8888-untiled
[268.327282] Dynamic subtest xrgb8888-untiled: SUCCESS (0.601s)
[268.335859] Starting dynamic subtest: rgb565-untiled
[268.762035] Dynamic subtest rgb565-untiled: SUCCESS (0.433s)
[268.770712] Starting dynamic subtest: xrgb2101010-untiled
[269.316129] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.555s)
[269.325200] Starting dynamic subtest: xbgr16161616f-untiled
[270.094271] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.777s)
[270.103323] Starting dynamic subtest: xrgb8888-xtiled
[271.894471] Dynamic subtest xrgb8888-xtiled: SUCCESS (1.799s)
[271.903476] Starting dynamic subtest: rgb565-xtiled
[273.050077] Dynamic subtest rgb565-xtiled: SUCCESS (1.155s)
[273.059333] Starting dynamic subtest: xrgb2101010-xtiled
[274.850530] Dynamic subtest xrgb2101010-xtiled: SUCCESS (1.798s)
[274.859631] Starting dynamic subtest: xbgr16161616f-xtiled
[278.194254] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (3.344s)
[278.203395] Starting dynamic subtest: xrgb8888-4tiled
[279.306888] Dynamic subtest xrgb8888-4tiled: SUCCESS (1.109s)
[279.316056] Starting dynamic subtest: rgb565-4tiled
[279.861511] Dynamic subtest rgb565-4tiled: SUCCESS (0.555s)
[279.870594] Starting dynamic subtest: xrgb2101010-4tiled
[280.972298] Dynamic subtest xrgb2101010-4tiled: SUCCESS (1.110s)
[280.984322] Starting dynamic subtest: xbgr16161616f-4tiled
[284.028588] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (3.054s)
[284.030859] Subtest draw-method-pwrite: SUCCESS (16.302s)
[284.123594] [049/133] (735s left) gem_exec_schedule (fairslice-all)
[284.205901] Starting subtest: fairslice-all
[286.257644] Subtest fairslice-all: SUCCESS (2.057s)
[286.548946] [050/133] (733s left) gem_ctx_param (invalid-param-get)
[286.601865] Starting subtest: invalid-param-get
[286.604175] Subtest invalid-param-get: SUCCESS (0.001s)
[286.686369] [051/133] (733s left) kms_pm_rpm (modeset-lpsp-stress-no-wait)
[288.224862] Starting subtest: modeset-lpsp-stress-no-wait
[298.250662] Subtest modeset-lpsp-stress-no-wait: SUCCESS (10.030s)
[298.337800] [052/133] (721s left) i915_drm_fdinfo (virtual-idle)
[298.387049] Starting subtest: virtual-idle
[301.445362] Subtest virtual-idle: SUCCESS (3.057s)
[301.533050] [053/133] (718s left) gem_userptr_blits (set-cache-level)
[301.599080] Starting subtest: set-cache-level
[301.603757] Subtest set-cache-level: SKIP (0.000s)
[301.739600] [054/133] (718s left) kms_ccs (missing-ccs-buffer-yf-tiled-ccs)
[301.832783] Starting subtest: missing-ccs-buffer-yf-tiled-ccs
[301.834932] Starting dynamic subtest: pipe-A-eDP-1
[301.856677] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[301.866416] Starting dynamic subtest: pipe-B-eDP-1
[301.887144] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[301.895367] Starting dynamic subtest: pipe-C-eDP-1
[301.916090] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[301.924669] Starting dynamic subtest: pipe-D-eDP-1
[301.947182] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[301.951199] Subtest missing-ccs-buffer-yf-tiled-ccs: SKIP (0.005s)
[304.070038] [055/133] (715s left) gem_eio (wait-10ms)
[304.306725] Starting subtest: wait-10ms
[304.667770] Subtest wait-10ms: SUCCESS (0.361s)
[304.818959] [056/133] (715s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-pwrite)
[304.921199] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-pwrite
[314.240225] Subtest psr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.321s)
[314.367900] [057/133] (705s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[314.465802] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[315.421875] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (0.956s)
[315.559133] [058/133] (704s left) perf (i915-ref-count)
[315.625299] Starting subtest: i915-ref-count
[315.708038] Subtest i915-ref-count: SUCCESS (0.081s)
[315.764904] [059/133] (704s left) gem_tiled_blits (interruptible)
[315.829697] Subtest interruptible: SKIP (0.000s)
[315.901434] [060/133] (704s left) syncobj_timeline (wait-all-snapshot)
[315.960802] Starting subtest: wait-all-snapshot
[316.042413] Subtest wait-all-snapshot: SUCCESS (0.082s)
[316.119986] [061/133] (704s left) perf_pmu (busy-double-start)
[316.165768] Starting subtest: busy-double-start
[316.167526] Starting dynamic subtest: rcs0
[319.811593] Dynamic subtest rcs0: FAIL (3.593s)
[319.823629] Starting dynamic subtest: bcs0
[323.328473] Dynamic subtest bcs0: SUCCESS (3.569s)
[323.340137] Starting dynamic subtest: vcs0
[327.471888] Dynamic subtest vcs0: SUCCESS (4.141s)
[327.483653] Starting dynamic subtest: vcs1
[331.413887] Dynamic subtest vcs1: SUCCESS (3.940s)
[331.425266] Starting dynamic subtest: vecs0
[335.445077] Dynamic subtest vecs0: SUCCESS (4.029s)
[335.456851] Starting dynamic subtest: ccs0
[339.084410] Dynamic subtest ccs0: FAIL (3.565s)
[339.086489] Subtest busy-double-start: FAIL (22.848s)
[339.128750] [062/133] (681s left) kms_lease (lease-unleased-crtc)
[339.226719] Starting subtest: lease-unleased-crtc
[339.229513] Starting dynamic subtest: pipe-A-eDP-1
[339.514398] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.290s)
[339.532363] Starting dynamic subtest: pipe-B-eDP-1
[340.234771] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.717s)
[340.255879] Starting dynamic subtest: pipe-C-eDP-1
[340.258579] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.007s)
[340.276923] Starting dynamic subtest: pipe-D-eDP-1
[340.279828] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.006s)
[340.282385] Subtest lease-unleased-crtc: SUCCESS (1.031s)
[340.629703] [063/133] (679s left) kms_ccs (bad-pixel-format-y-tiled-gen12-mc-ccs)
[340.728135] Starting subtest: bad-pixel-format-y-tiled-gen12-mc-ccs
[340.730315] Starting dynamic subtest: pipe-A-eDP-1
[340.752754] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[340.763465] Starting dynamic subtest: pipe-B-eDP-1
[340.785186] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[340.793877] Starting dynamic subtest: pipe-C-eDP-1
[340.815225] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[340.823601] Starting dynamic subtest: pipe-D-eDP-1
[340.845401] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[340.849451] Subtest bad-pixel-format-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[342.989278] [064/133] (677s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-render)
[343.098803] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-render
[349.933315] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-render: SUCCESS (6.833s)
[350.097097] [065/133] (670s left) kms_plane (pixel-format-4-tiled-modifier-source-clamping)
[350.202179] Starting subtest: pixel-format-4-tiled-modifier-source-clamping
[350.228376] Starting dynamic subtest: pipe-A-plane-0
[350.895303] Dynamic subtest pipe-A-plane-0: SUCCESS (0.666s)
[350.906832] Starting dynamic subtest: pipe-A-plane-3
[351.349980] Dynamic subtest pipe-A-plane-3: SUCCESS (0.453s)
[352.417506] Starting dynamic subtest: pipe-B-plane-0
[353.076744] Dynamic subtest pipe-B-plane-0: SUCCESS (0.666s)
[353.088460] Starting dynamic subtest: pipe-B-plane-3
[353.509521] Dynamic subtest pipe-B-plane-3: SUCCESS (0.431s)
[353.829326] Subtest pixel-format-4-tiled-modifier-source-clamping: SUCCESS (3.629s)
[354.703818] [066/133] (665s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[354.791254] Starting subtest: plane-use-after-nonblocking-unbind
[354.793461] Starting dynamic subtest: pipe-A-eDP-1
[354.851060] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.061s)
[355.150498] Starting dynamic subtest: pipe-B-eDP-1
[355.921448] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.777s)
[356.223398] Subtest plane-use-after-nonblocking-unbind: SUCCESS (1.431s)
[357.073523] [067/133] (663s left) gem_tiled_fence_blits (basic)
[357.136866] Subtest basic: SKIP (0.000s)
[357.200526] [068/133] (663s left) syncobj_timeline (invalid-multi-wait-unsubmitted)
[357.254413] Starting subtest: invalid-multi-wait-unsubmitted
[357.256718] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[357.380164] [069/133] (663s left) i915_query (test-query-geometry-subslices)
[357.439552] Starting subtest: test-query-geometry-subslices
[357.441917] Subtest test-query-geometry-subslices: SUCCESS (0.000s)
[357.540812] [070/133] (663s left) kms_cursor_legacy (forked-move)
[357.667538] Starting subtest: forked-move
[357.669697] Starting dynamic subtest: pipe-A
[363.122849] Dynamic subtest pipe-A: SUCCESS (5.453s)
[363.134128] Starting dynamic subtest: pipe-B
[368.597763] Dynamic subtest pipe-B: SUCCESS (5.471s)
[368.608884] Starting dynamic subtest: pipe-C
[374.075628] Dynamic subtest pipe-C: SUCCESS (5.475s)
[374.089278] Starting dynamic subtest: pipe-D
[379.559073] Dynamic subtest pipe-D: SUCCESS (5.480s)
[379.570534] Starting dynamic subtest: all-pipes
[385.058432] Dynamic subtest all-pipes: SUCCESS (5.497s)
[385.061033] Subtest forked-move: SUCCESS (27.390s)
[385.207084] [071/133] (635s left) kms_busy (extended-pageflip-hang-newfb)
[385.313410] Starting subtest: extended-pageflip-hang-newfb
[385.315746] Starting dynamic subtest: pipe-A
[411.693640] Dynamic subtest pipe-A: SUCCESS (26.378s)
[411.712672] Starting dynamic subtest: pipe-D
[436.711788] Dynamic subtest pipe-D: SUCCESS (25.018s)
[436.714109] Subtest extended-pageflip-hang-newfb: SUCCESS (51.401s)
[437.544793] [072/133] (583s left) gem_tiling_max_stride
[437.665375] [073/133] (583s left) kms_flip (2x-flip-vs-wf_vblank)
[437.754375] Starting subtest: 2x-flip-vs-wf_vblank
[437.766046] Subtest 2x-flip-vs-wf_vblank: SKIP (0.011s)
[437.855503] [074/133] (582s left) vgem_basic (dmabuf-export)
[437.908942] Starting subtest: dmabuf-export
[437.946131] Subtest dmabuf-export: SUCCESS (0.039s)
[438.047651] [075/133] (582s left) kms_psr (psr-cursor-mmap-cpu)
[438.146857] Starting subtest: psr-cursor-mmap-cpu
[438.149104] Starting dynamic subtest: eDP-1
[439.827646] Dynamic subtest eDP-1: SUCCESS (1.681s)
[439.829894] Subtest psr-cursor-mmap-cpu: SUCCESS (1.682s)
[440.690893] [076/133] (580s left) gem_mmap_gtt (basic-small-bo-tiledx)
[440.763049] Subtest basic-small-bo-tiledX: SKIP (0.000s)
[440.829464] [077/133] (580s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[440.924055] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc
[440.928358] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[441.038375] [078/133] (579s left) kms_rotation_crc (primary-rotation-90)
[441.128846] Starting subtest: primary-rotation-90
[441.133226] Subtest primary-rotation-90: SKIP (0.001s)
[441.202596] [079/133] (579s left) gem_pxp (reject-modify-context-protection-on)
[441.332522] Starting subtest: reject-modify-context-protection-on
[441.335096] Subtest reject-modify-context-protection-on: SUCCESS (0.002s)
[441.462143] [080/133] (579s left) kms_psr (fbc-pr-primary-page-flip)
[441.553207] Starting subtest: fbc-pr-primary-page-flip
[441.557737] Subtest fbc-pr-primary-page-flip: SKIP (0.000s)
[441.626083] [081/133] (579s left) perf_pmu (busy-idle)
[441.673959] Starting subtest: busy-idle
[441.676206] Starting dynamic subtest: rcs0
[442.364024] Dynamic subtest rcs0: SUCCESS (0.692s)
[442.376034] Starting dynamic subtest: bcs0
[443.026020] Dynamic subtest bcs0: SUCCESS (0.660s)
[443.037740] Starting dynamic subtest: vcs0
[444.887798] Dynamic subtest vcs0: SUCCESS (1.858s)
[444.899931] Starting dynamic subtest: vcs1
[446.936413] Dynamic subtest vcs1: SUCCESS (2.046s)
[446.948978] Starting dynamic subtest: vecs0
[448.920422] Dynamic subtest vecs0: SUCCESS (1.981s)
[448.933294] Starting dynamic subtest: ccs0
[449.599843] Dynamic subtest ccs0: SUCCESS (0.678s)
[449.602748] Subtest busy-idle: SUCCESS (7.929s)
[449.711127] [082/133] (571s left) gem_exec_params (no-blt)
[449.759623] Starting subtest: no-blt
[449.765683] Subtest no-blt: SUCCESS (0.005s)
[449.858254] [083/133] (571s left) gem_ctx_shared (exec-single-timeline)
[449.913860] Starting subtest: exec-single-timeline
[449.916207] Starting dynamic subtest: rcs0
[450.997012] Dynamic subtest rcs0: SUCCESS (1.081s)
[451.014678] Starting dynamic subtest: bcs0
[452.084334] Dynamic subtest bcs0: SUCCESS (1.084s)
[452.099263] Starting dynamic subtest: vcs0
[453.174194] Dynamic subtest vcs0: SUCCESS (1.085s)
[453.196124] Starting dynamic subtest: vcs1
[454.260791] Dynamic subtest vcs1: SUCCESS (1.084s)
[454.273569] Starting dynamic subtest: vecs0
[455.350536] Dynamic subtest vecs0: SUCCESS (1.085s)
[455.363431] Starting dynamic subtest: ccs0
[456.437622] Dynamic subtest ccs0: SUCCESS (1.083s)
[456.440681] Subtest exec-single-timeline: SUCCESS (6.523s)
[456.632640] [084/133] (564s left) intel_hwmon (hwmon-write)
[456.690869] Subtest hwmon-write: SKIP (0.000s)
[456.775245] [085/133] (564s left) gem_exec_balancer (parallel-out-fence)
[457.035531] Starting subtest: parallel-out-fence
[458.282807] Subtest parallel-out-fence: SUCCESS (1.246s)
[458.364834] [086/133] (562s left) i915_drm_fdinfo (memory-info-purgeable)
[458.410214] Starting subtest: memory-info-purgeable
[458.412600] Starting dynamic subtest: smem0
[461.294732] Dynamic subtest smem0: SUCCESS (2.885s)
[461.297215] Subtest memory-info-purgeable: SUCCESS (2.886s)
[461.372588] [087/133] (559s left) gem_basic (multigpu-create-close)
[461.432426] Starting subtest: multigpu-create-close
[461.436244] Subtest multigpu-create-close: SKIP (0.000s)
[461.543958] [088/133] (559s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render)
[461.635828] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render
[461.639484] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[461.728343] [089/133] (559s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[462.047482] Starting subtest: cursor-rapid-movement-max-size
[462.053411] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[462.168782] [090/133] (559s left) perf_pmu (all-busy-check-all)
[462.209854] Starting subtest: all-busy-check-all
[463.896755] Subtest all-busy-check-all: SUCCESS (1.687s)
[464.015805] [091/133] (557s left) gem_mmap_gtt (basic-small-bo-tiledy)
[464.071607] Subtest basic-small-bo-tiledY: SKIP (0.000s)
[464.135218] [092/133] (557s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[464.236298] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[467.911885] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (3.678s)
[469.042768] [093/133] (552s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render)
[469.144673] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render
[469.148694] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[469.238092] [094/133] (552s left) kms_properties (connector-properties-atomic)
[469.336380] Starting subtest: connector-properties-atomic
[469.338725] Starting dynamic subtest: pipe-A-eDP-1
[477.248860] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.908s)
[477.259084] Starting dynamic subtest: pipe-None-DP-1
[477.263609] Dynamic subtest pipe-None-DP-1: SUCCESS (0.001s)
[477.271951] Starting dynamic subtest: pipe-None-HDMI-A-1
[477.276295] Dynamic subtest pipe-None-HDMI-A-1: SUCCESS (0.001s)
[477.284648] Starting dynamic subtest: pipe-None-DP-2
[477.290547] Dynamic subtest pipe-None-DP-2: SUCCESS (0.001s)
[477.300444] Starting dynamic subtest: pipe-None-HDMI-A-2
[477.305808] Dynamic subtest pipe-None-HDMI-A-2: SUCCESS (0.001s)
[477.317071] Starting dynamic subtest: pipe-None-DP-3
[477.321634] Dynamic subtest pipe-None-DP-3: SUCCESS (0.001s)
[477.330501] Starting dynamic subtest: pipe-None-DP-4
[477.334869] Dynamic subtest pipe-None-DP-4: SUCCESS (0.001s)
[477.337081] Subtest connector-properties-atomic: SUCCESS (7.920s)
[478.115863] [095/133] (543s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc)
[478.226783] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc
[478.229067] Starting dynamic subtest: pipe-A-eDP-1
[478.251721] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[478.261211] Starting dynamic subtest: pipe-B-eDP-1
[478.282454] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[478.290987] Starting dynamic subtest: pipe-C-eDP-1
[478.313516] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[478.322030] Starting dynamic subtest: pipe-D-eDP-1
[478.344713] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[478.348822] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc: SKIP (0.006s)
[480.497390] [096/133] (541s left) kms_color (ctm-negative)
[480.600154] Starting subtest: ctm-negative
[480.602266] Starting dynamic subtest: pipe-A-eDP-1
[481.315311] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.714s)
[481.325044] Starting dynamic subtest: pipe-B-eDP-1
[482.703500] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.388s)
[482.713216] Starting dynamic subtest: pipe-C-eDP-1
[484.079917] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.374s)
[484.089216] Starting dynamic subtest: pipe-D-eDP-1
[485.415718] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.337s)
[485.418106] Subtest ctm-negative: SUCCESS (4.818s)
[486.208343] [097/133] (535s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-upscaling)
[486.307766] Starting subtest: flip-32bpp-linear-to-64bpp-linear-upscaling
[486.310155] Starting dynamic subtest: pipe-A-default-mode
[489.035608] Dynamic subtest pipe-A-default-mode: SUCCESS (2.728s)
[489.037960] Subtest flip-32bpp-linear-to-64bpp-linear-upscaling: SUCCESS (2.730s)
[489.906178] [098/133] (531s left) syncobj_eventfd (binary-wait)
[489.968986] Starting subtest: binary-wait
[489.978414] Subtest binary-wait: SUCCESS (0.011s)
[490.053834] [099/133] (531s left) kms_big_fb (y-tiled-16bpp-rotate-0)
[490.174608] Starting subtest: y-tiled-16bpp-rotate-0
[490.178685] Subtest y-tiled-16bpp-rotate-0: SKIP (0.000s)
[490.283555] [100/133] (531s left) i915_pm_rpm (system-suspend-devices)
[492.113263] Starting subtest: system-suspend-devices
[499.971286] Subtest system-suspend-devices: SUCCESS (7.861s)
[500.377646] [101/133] (521s left) gem_userptr_blits (process-exit)
[500.452699] Starting subtest: process-exit
[500.460473] Subtest process-exit: SUCCESS (0.010s)
[500.593235] [102/133] (521s left) kms_chamelium_edid (hdmi-edid-change-during-suspend)
[500.989997] Subtest hdmi-edid-change-during-suspend: SKIP (0.000s)
[501.764895] [103/133] (520s left) kms_rotation_crc (multiplane-rotation)
[501.859693] Starting subtest: multiplane-rotation
[519.241675] Subtest multiplane-rotation: SUCCESS (17.382s)
[519.371445] [104/133] (502s left) kms_psr2_sf (psr2-overlay-plane-update-sf-dmg-area)
[519.468072] Starting subtest: psr2-overlay-plane-update-sf-dmg-area
[519.469926] Starting dynamic subtest: pipe-A-eDP-1
[525.351916] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.886s)
[525.362103] Starting dynamic subtest: pipe-B-eDP-1
[531.982177] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.626s)
[531.984653] Subtest psr2-overlay-plane-update-sf-dmg-area: SUCCESS (12.516s)
[532.861285] [105/133] (489s left) gem_exec_schedule (smoketest)
[533.046184] Starting subtest: smoketest
[533.049056] Starting dynamic subtest: rcs0
[538.587109] Dynamic subtest rcs0: SUCCESS (5.498s)
[538.598886] Starting dynamic subtest: bcs0
[544.095652] Dynamic subtest bcs0: SUCCESS (5.506s)
[544.107616] Starting dynamic subtest: vcs0
[549.591631] Dynamic subtest vcs0: SUCCESS (5.491s)
[549.608482] Starting dynamic subtest: vcs1
[555.084698] Dynamic subtest vcs1: SUCCESS (5.493s)
[555.096212] Starting dynamic subtest: vecs0
[560.583792] Dynamic subtest vecs0: SUCCESS (5.500s)
[560.594649] Starting dynamic subtest: ccs0
[566.076469] Dynamic subtest ccs0: SUCCESS (5.486s)
[566.078697] Subtest smoketest: SUCCESS (32.981s)
[566.216837] [106/133] (455s left) gem_ctx_param (invalid-get-ringsize)
[566.287868] Starting subtest: invalid-get-ringsize
[566.290319] Subtest invalid-get-ringsize: SUCCESS (0.001s)
[566.402323] [107/133] (455s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc)
[566.500529] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc
[566.502898] Starting dynamic subtest: pipe-A-eDP-1
[566.525428] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[566.536873] Starting dynamic subtest: pipe-B-eDP-1
[566.557902] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[566.566995] Starting dynamic subtest: pipe-C-eDP-1
[566.588035] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[566.597087] Starting dynamic subtest: pipe-D-eDP-1
[566.619989] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[566.624099] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc: SKIP (0.005s)
[568.822045] [108/133] (453s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[568.919058] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[569.598909] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.681s)
[569.799720] [109/133] (452s left) kms_psr (pr-primary-render)
[569.900069] Starting subtest: pr-primary-render
[569.904241] Subtest pr-primary-render: SKIP (0.000s)
[569.986844] [110/133] (452s left) kms_prop_blob (invalid-set-prop-any)
[570.045332] Starting subtest: invalid-set-prop-any
[570.047266] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[570.155206] [111/133] (452s left) kms_async_flips (test-time-stamp-atomic)
[570.249030] Starting subtest: test-time-stamp-atomic
[570.632426] Starting dynamic subtest: pipe-A-eDP-1
[571.432450] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.798s)
[571.810928] Starting dynamic subtest: pipe-B-eDP-1
[572.557221] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.752s)
[572.939094] Starting dynamic subtest: pipe-C-eDP-1
[573.698692] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.765s)
[574.080265] Starting dynamic subtest: pipe-D-eDP-1
[574.839761] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.765s)
[574.842302] Subtest test-time-stamp-atomic: SUCCESS (4.591s)
[576.008624] [112/133] (446s left) kms_flip (basic-flip-vs-dpms)
[576.123252] Starting subtest: basic-flip-vs-dpms
[576.125531] Starting dynamic subtest: A-eDP1
[578.685288] Dynamic subtest A-eDP1: SUCCESS (2.563s)
[578.695205] Starting dynamic subtest: B-eDP1
[580.845434] Dynamic subtest B-eDP1: SUCCESS (2.158s)
[580.855794] Starting dynamic subtest: C-eDP1
[582.922183] Dynamic subtest C-eDP1: SUCCESS (2.075s)
[582.932625] Starting dynamic subtest: D-eDP1
[585.005750] Dynamic subtest D-eDP1: SUCCESS (2.080s)
[585.008055] Subtest basic-flip-vs-dpms: SUCCESS (8.887s)
[585.804910] [113/133] (436s left) kms_pm_backlight (brightness-with-dpms)
[585.902484] Starting subtest: brightness-with-dpms
[585.950199] Starting dynamic subtest: eDP-1
[588.659386] Dynamic subtest eDP-1: SUCCESS (2.708s)
[588.661702] Subtest brightness-with-dpms: SUCCESS (2.760s)
[589.531787] [114/133] (432s left) kms_big_fb (x-tiled-8bpp-rotate-0)
[589.650986] Starting subtest: x-tiled-8bpp-rotate-0
[590.867583] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.216s)
[590.993809] [115/133] (431s left) gem_ctx_param (invalid-ctx-set)
[591.047937] Starting subtest: invalid-ctx-set
[591.050115] Subtest invalid-ctx-set: SUCCESS (0.001s)
[591.164836] [116/133] (431s left) kms_content_protection (dp-mst-type-1)
[591.310635] Starting subtest: dp-mst-type-1
[591.314675] Subtest dp-mst-type-1: SKIP (0.000s)
[591.440391] [117/133] (431s left) gem_mmap_gtt (cpuset-big-copy)
[591.494392] Subtest cpuset-big-copy: SKIP (0.000s)
[591.551850] [118/133] (431s left) gem_mmap_offset (close-race)
[591.612255] Starting subtest: close-race
[611.716897] Subtest close-race: SUCCESS (20.104s)
[611.836196] [119/133] (410s left) kms_chamelium_hpd (dp-hpd-fast)
[612.231259] Subtest dp-hpd-fast: SKIP (0.000s)
[613.046392] [120/133] (409s left) kms_invalid_mode (bad-vtotal)
[613.139351] Starting subtest: bad-vtotal
[613.141742] Starting dynamic subtest: pipe-A-eDP-1
[613.426203] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.286s)
[613.436421] Starting dynamic subtest: pipe-B-eDP-1
[613.438332] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[613.447638] Starting dynamic subtest: pipe-C-eDP-1
[613.450026] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[613.459554] Starting dynamic subtest: pipe-D-eDP-1
[613.461933] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[613.464286] Subtest bad-vtotal: SUCCESS (0.292s)
[614.299245] [121/133] (408s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-pwrite)
[614.399520] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-pwrite
[614.403924] Subtest psr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[614.492346] [122/133] (408s left) kms_plane_lowres (tiling-yf)
[614.593981] Starting subtest: tiling-yf
[614.598417] Subtest tiling-yf: SKIP (0.000s)
[614.671821] [123/133] (408s left) gem_exec_flush (basic-wb-ro-default)
[614.738294] Starting subtest: basic-wb-ro-default
[620.215906] Subtest basic-wb-ro-default: SUCCESS (5.480s)
[620.417486] [124/133] (402s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[620.517120] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[620.521557] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[620.622564] [125/133] (402s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-downscaling)
[620.722778] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-downscaling
[620.725058] Starting dynamic subtest: pipe-A-default-mode
[621.847834] Dynamic subtest pipe-A-default-mode: SKIP (1.121s)
[621.852111] Subtest flip-64bpp-4tile-to-16bpp-4tile-downscaling: SKIP (1.123s)
[622.017417] [126/133] (400s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen)
[622.118208] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-fullscreen
[628.805017] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (6.690s)
[628.942696] [127/133] (394s left) gem_exec_store (pages)
[629.007049] Starting subtest: pages
[629.009352] Starting dynamic subtest: rcs0
[629.102920] Dynamic subtest rcs0: SUCCESS (0.096s)
[629.117871] Starting dynamic subtest: bcs0
[629.179329] Dynamic subtest bcs0: SUCCESS (0.075s)
[629.192422] Starting dynamic subtest: vcs0
[629.259178] Dynamic subtest vcs0: SUCCESS (0.078s)
[629.269542] Starting dynamic subtest: vcs1
[629.323480] Dynamic subtest vcs1: SUCCESS (0.062s)
[629.335079] Starting dynamic subtest: vecs0
[629.401029] Dynamic subtest vecs0: SUCCESS (0.077s)
[629.414028] Starting dynamic subtest: ccs0
[629.474660] Dynamic subtest ccs0: SUCCESS (0.072s)
[629.477064] Subtest pages: SUCCESS (0.469s)
[629.659960] [128/133] (393s left) gem_exec_create (basic)
[629.720390] Starting subtest: basic
[629.722624] Starting dynamic subtest: smem
[631.895333] Dynamic subtest smem: SUCCESS (2.173s)
[631.897991] Subtest basic: SUCCESS (2.175s)
[632.081697] [129/133] (391s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0)
[632.190612] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0
[632.194981] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[632.288709] [130/133] (390s left) gem_exec_balancer (bonded-dual)
[632.350796] Starting subtest: bonded-dual
[632.355099] Subtest bonded-dual: SKIP (0.000s)
[632.655805] [131/133] (390s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-render)
[632.758978] Starting subtest: psr-1p-primscrn-cur-indfb-draw-render
[639.545348] Subtest psr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.789s)
[639.649744] [132/133] (383s left) kms_cursor_crc (cursor-offscreen-32x10)
[639.838807] Starting subtest: cursor-offscreen-32x10
[639.883817] Subtest cursor-offscreen-32x10: SKIP (0.043s)
[640.138098] [133/133] (383s left) gem_exec_reloc (basic-wc)
[640.193826] Subtest basic-wc: SKIP (0.000s)
[640.268421] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_flip
results: parsing output: 2/ for test: kms_cursor_legacy
results: parsing output: 3/ for test: kms_big_fb
results: parsing output: 4/ for test: kms_flip
results: parsing output: 5/ for test: gem_ctx_persistence
results: parsing output: 6/ for test: core_auth
results: parsing output: 7/ for test: vgem_basic
results: parsing output: 8/ for test: gem_ctx_bad_destroy
results: parsing output: 9/ for test: syncobj_wait
results: parsing output: 10/ for test: kms_flip
results: parsing output: 11/ for test: gem_render_copy
results: parsing output: 12/ for test: gem_mmap_gtt
results: parsing output: 13/ for test: gem_fenced_exec_thrash
results: parsing output: 14/ for test: gem_spin_batch
results: parsing output: 15/ for test: kms_plane
results: parsing output: 16/ for test: kms_vblank
results: parsing output: 17/ for test: syncobj_timeline
results: parsing output: 18/ for test: syncobj_timeline
results: parsing output: 19/ for test: kms_vblank
results: parsing output: 20/ for test: perf_pmu
results: parsing output: 21/ for test: kms_addfb_basic
results: parsing output: 22/ for test: gen9_exec_parse
results: parsing output: 23/ for test: gem_eio
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_ccs
results: parsing output: 27/ for test: kms_flip
results: parsing output: 28/ for test: kms_pipe_crc_basic
results: parsing output: 29/ for test: dumb_buffer
results: parsing output: 30/ for test: kms_psr
results: parsing output: 31/ for test: kms_flip
results: parsing output: 32/ for test: gem_exec_schedule
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: kms_cursor_edge_walk
results: parsing output: 35/ for test: gem_mmap_wc
results: parsing output: 36/ for test: api_intel_allocator
results: parsing output: 37/ for test: kms_psr2_sf
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: kms_plane_scaling
results: parsing output: 42/ for test: gem_gpgpu_fill
results: parsing output: 43/ for test: kms_atomic_interruptible
results: parsing output: 44/ for test: gem_exec_fence
results: parsing output: 45/ for test: kms_rotation_crc
results: parsing output: 46/ for test: gem_close_race
results: parsing output: 47/ for test: kms_draw_crc
results: parsing output: 48/ for test: gem_exec_schedule
results: parsing output: 49/ for test: gem_ctx_param
results: parsing output: 50/ for test: kms_pm_rpm
results: parsing output: 51/ for test: i915_drm_fdinfo
results: parsing output: 52/ for test: gem_userptr_blits
results: parsing output: 53/ for test: kms_ccs
results: parsing output: 54/ for test: gem_eio
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: kms_big_fb
results: parsing output: 57/ for test: perf
results: parsing output: 58/ for test: gem_tiled_blits
results: parsing output: 59/ for test: syncobj_timeline
results: parsing output: 60/ for test: perf_pmu
results: parsing output: 61/ for test: kms_lease
results: parsing output: 62/ for test: kms_ccs
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: kms_plane
results: parsing output: 65/ for test: kms_atomic_transition
results: parsing output: 66/ for test: gem_tiled_fence_blits
results: parsing output: 67/ for test: syncobj_timeline
results: parsing output: 68/ for test: i915_query
results: parsing output: 69/ for test: kms_cursor_legacy
results: parsing output: 70/ for test: kms_busy
results: parsing output: 71/ for test: gem_tiling_max_stride
results: parsing output: 72/ for test: kms_flip
results: parsing output: 73/ for test: vgem_basic
results: parsing output: 74/ for test: kms_psr
results: parsing output: 75/ for test: gem_mmap_gtt
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_rotation_crc
results: parsing output: 78/ for test: gem_pxp
results: parsing output: 79/ for test: kms_psr
results: parsing output: 80/ for test: perf_pmu
results: parsing output: 81/ for test: gem_exec_params
results: parsing output: 82/ for test: gem_ctx_shared
results: parsing output: 83/ for test: intel_hwmon
results: parsing output: 84/ for test: gem_exec_balancer
results: parsing output: 85/ for test: i915_drm_fdinfo
results: parsing output: 86/ for test: gem_basic
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: kms_cursor_crc
results: parsing output: 89/ for test: perf_pmu
results: parsing output: 90/ for test: gem_mmap_gtt
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_properties
results: parsing output: 94/ for test: kms_ccs
results: parsing output: 95/ for test: kms_color
results: parsing output: 96/ for test: kms_flip_scaled_crc
results: parsing output: 97/ for test: syncobj_eventfd
results: parsing output: 98/ for test: kms_big_fb
results: parsing output: 99/ for test: i915_pm_rpm
results: parsing output: 100/ for test: gem_userptr_blits
results: parsing output: 101/ for test: kms_chamelium_edid
results: parsing output: 102/ for test: kms_rotation_crc
results: parsing output: 103/ for test: kms_psr2_sf
results: parsing output: 104/ for test: gem_exec_schedule
results: parsing output: 105/ for test: gem_ctx_param
results: parsing output: 106/ for test: kms_ccs
results: parsing output: 107/ for test: kms_big_fb
results: parsing output: 108/ for test: kms_psr
results: parsing output: 109/ for test: kms_prop_blob
results: parsing output: 110/ for test: kms_async_flips
results: parsing output: 111/ for test: kms_flip
results: parsing output: 112/ for test: kms_pm_backlight
results: parsing output: 113/ for test: kms_big_fb
results: parsing output: 114/ for test: gem_ctx_param
results: parsing output: 115/ for test: kms_content_protection
results: parsing output: 116/ for test: gem_mmap_gtt
results: parsing output: 117/ for test: gem_mmap_offset
results: parsing output: 118/ for test: kms_chamelium_hpd
results: parsing output: 119/ for test: kms_invalid_mode
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_plane_lowres
results: parsing output: 122/ for test: gem_exec_flush
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: kms_flip_scaled_crc
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: gem_exec_store
results: parsing output: 127/ for test: gem_exec_create
results: parsing output: 128/ for test: kms_big_fb
results: parsing output: 129/ for test: gem_exec_balancer
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_cursor_crc
results: parsing output: 132/ for test: gem_exec_reloc
Done.