[57.398487] Initializing watchdogs
[57.398586]   /dev/watchdog0
[57.415014] [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.419146] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.421387] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.424537] [001/130] (960s left) kms_busy (basic)
[57.552152] Starting subtest: basic
[57.554565] Starting dynamic subtest: flip
[58.227485] Dynamic subtest flip: SUCCESS (0.673s)
[58.235786] Starting dynamic subtest: modeset
[61.620855] Dynamic subtest modeset: SUCCESS (3.393s)
[61.623513] Subtest basic: SUCCESS (4.067s)
[62.404688] [002/130] (955s left) gem_ctx_sseu (engines)
[62.460304] Subtest engines: SKIP (0.000s)
[62.520415] [003/130] (955s left) kms_ccs (crc-primary-basic-y-tiled-ccs)
[62.611903] Starting subtest: crc-primary-basic-y-tiled-ccs
[62.614237] Starting dynamic subtest: pipe-A-eDP-1
[62.636459] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[62.645436] Starting dynamic subtest: pipe-B-eDP-1
[62.666987] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[62.674430] Starting dynamic subtest: pipe-C-eDP-1
[62.695962] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[62.703869] Starting dynamic subtest: pipe-D-eDP-1
[62.727123] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[62.731354] Subtest crc-primary-basic-y-tiled-ccs: SKIP (0.004s)
[64.771946] [004/130] (953s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0)
[64.880945] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0
[65.994748] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.114s)
[66.166381] [005/130] (951s left) gem_exec_schedule (pi-shared-iova)
[66.356021] Starting subtest: pi-shared-iova
[66.358420] Starting dynamic subtest: rcs0
[66.428565] Dynamic subtest rcs0: SUCCESS (0.075s)
[66.438303] Starting dynamic subtest: vcs0
[66.481826] Dynamic subtest vcs0: SUCCESS (0.054s)
[66.491879] Starting dynamic subtest: vcs1
[66.536958] Dynamic subtest vcs1: SUCCESS (0.055s)
[66.547777] Starting dynamic subtest: vecs0
[66.585193] Dynamic subtest vecs0: SUCCESS (0.048s)
[66.595831] Starting dynamic subtest: ccs0
[66.641582] Dynamic subtest ccs0: SUCCESS (0.056s)
[66.644074] Subtest pi-shared-iova: SUCCESS (0.289s)
[68.039948] [006/130] (950s left) kms_draw_crc (fill-fb)
[68.145040] Starting subtest: fill-fb
[68.610395] Subtest fill-fb: SUCCESS (0.472s)
[68.721231] [007/130] (949s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-mmap-wc)
[68.831672] Starting subtest: psr-1p-offscren-pri-shrfb-draw-mmap-wc
[75.801755] Subtest psr-1p-offscren-pri-shrfb-draw-mmap-wc: SUCCESS (6.972s)
[75.935145] [008/130] (942s left) kms_plane_scaling (planes-downscale-factor-0-75-unity-scaling)
[76.041969] Starting subtest: planes-downscale-factor-0-75-unity-scaling
[76.044382] Starting dynamic subtest: pipe-A
[76.128490] Dynamic subtest pipe-A: SUCCESS (0.086s)
[76.136738] Starting dynamic subtest: pipe-B
[77.293418] Dynamic subtest pipe-B: SUCCESS (1.165s)
[77.302951] Starting dynamic subtest: pipe-C
[78.391419] Dynamic subtest pipe-C: SUCCESS (1.098s)
[78.399756] Starting dynamic subtest: pipe-D
[79.515506] Dynamic subtest pipe-D: SUCCESS (1.124s)
[79.517863] Subtest planes-downscale-factor-0-75-unity-scaling: SUCCESS (3.474s)
[80.668455] [009/130] (937s left) gem_userptr_blits (sync-unmap-cycles)
[80.738837] Starting subtest: sync-unmap-cycles
[86.121065] Subtest sync-unmap-cycles: SUCCESS (5.381s)
[86.278236] [010/130] (931s left) gem_userptr_blits (dmabuf-unsync)
[86.348865] Subtest dmabuf-unsync: SKIP (0.000s)
[86.499199] [011/130] (931s left) kms_sequence (queue-busy)
[86.594672] Starting subtest: queue-busy
[86.596744] Starting dynamic subtest: pipe-A-eDP-1
[88.535616] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.936s)
[88.543071] Starting dynamic subtest: pipe-B-eDP-1
[91.153614] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.620s)
[91.163430] Starting dynamic subtest: pipe-C-eDP-1
[93.769065] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.613s)
[93.777084] Starting dynamic subtest: pipe-D-eDP-1
[96.297307] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.527s)
[96.299191] Subtest queue-busy: SUCCESS (9.699s)
[97.166786] [012/130] (921s left) fbdev (read)
[97.213039] Starting subtest: read
[97.740082] Subtest read: SUCCESS (0.527s)
[97.801393] [013/130] (920s left) kms_async_flips (basic-modeset-with-all-modifiers-formats)
[97.897896] Starting subtest: basic-modeset-with-all-modifiers-formats
[98.283310] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB8888
[99.060158] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB8888: SUCCESS (0.778s)
[99.067921] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB2101010
[99.082201] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB2101010: SUCCESS (0.021s)
[99.089720] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB16161616F
[99.106098] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB16161616F: SUCCESS (0.022s)
[99.113078] Starting dynamic subtest: pipe-A-eDP-1-4-RGB565
[99.304524] Dynamic subtest pipe-A-eDP-1-4-RGB565: SUCCESS (0.200s)
[99.313350] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB8888
[99.326725] Dynamic subtest pipe-A-eDP-1-4-XRGB8888: SUCCESS (0.022s)
[99.334102] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB2101010
[99.348586] Dynamic subtest pipe-A-eDP-1-4-XRGB2101010: SUCCESS (0.022s)
[99.355775] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB16161616F
[99.371183] Dynamic subtest pipe-A-eDP-1-4-XRGB16161616F: SUCCESS (0.022s)
[99.378228] Starting dynamic subtest: pipe-A-eDP-1-4-YUYV
[99.393448] Dynamic subtest pipe-A-eDP-1-4-YUYV: SUCCESS (0.022s)
[99.400497] Starting dynamic subtest: pipe-A-eDP-1-4-Y210
[99.415524] Dynamic subtest pipe-A-eDP-1-4-Y210: SUCCESS (0.022s)
[99.422761] Starting dynamic subtest: pipe-A-eDP-1-4-XYUV8888
[99.438742] Dynamic subtest pipe-A-eDP-1-4-XYUV8888: SUCCESS (0.023s)
[99.451114] Starting dynamic subtest: pipe-A-eDP-1-4-XV30
[99.459855] Dynamic subtest pipe-A-eDP-1-4-XV30: SUCCESS (0.021s)
[99.468865] Starting dynamic subtest: pipe-A-eDP-1-4-XV36
[99.482308] Dynamic subtest pipe-A-eDP-1-4-XV36: SUCCESS (0.022s)
[99.489518] Starting dynamic subtest: pipe-A-eDP-1-x-RGB565
[99.693446] Dynamic subtest pipe-A-eDP-1-x-RGB565: SUCCESS (0.211s)
[99.700974] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB8888
[99.715407] Dynamic subtest pipe-A-eDP-1-x-XRGB8888: SUCCESS (0.022s)
[99.723899] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB2101010
[99.737994] Dynamic subtest pipe-A-eDP-1-x-XRGB2101010: SUCCESS (0.022s)
[99.745075] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB16161616F
[99.760260] Dynamic subtest pipe-A-eDP-1-x-XRGB16161616F: SUCCESS (0.022s)
[99.769085] Starting dynamic subtest: pipe-A-eDP-1-x-YUYV
[99.782412] Dynamic subtest pipe-A-eDP-1-x-YUYV: SUCCESS (0.022s)
[99.789369] Starting dynamic subtest: pipe-A-eDP-1-x-Y210
[99.804146] Dynamic subtest pipe-A-eDP-1-x-Y210: SUCCESS (0.022s)
[99.813318] Starting dynamic subtest: pipe-A-eDP-1-x-XYUV8888
[99.826525] Dynamic subtest pipe-A-eDP-1-x-XYUV8888: SUCCESS (0.022s)
[99.835534] Starting dynamic subtest: pipe-A-eDP-1-x-XV30
[99.848664] Dynamic subtest pipe-A-eDP-1-x-XV30: SUCCESS (0.022s)
[99.855526] Starting dynamic subtest: pipe-A-eDP-1-x-XV36
[99.870853] Dynamic subtest pipe-A-eDP-1-x-XV36: SUCCESS (0.022s)
[99.878145] Starting dynamic subtest: pipe-A-eDP-1-linear-RGB565
[100.093246] Dynamic subtest pipe-A-eDP-1-linear-RGB565: SUCCESS (0.222s)
[100.100851] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB8888
[100.115617] Dynamic subtest pipe-A-eDP-1-linear-XRGB8888: SUCCESS (0.022s)
[100.122935] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB2101010
[100.137667] Dynamic subtest pipe-A-eDP-1-linear-XRGB2101010: SUCCESS (0.022s)
[100.145079] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB16161616F
[100.160069] Dynamic subtest pipe-A-eDP-1-linear-XRGB16161616F: SUCCESS (0.022s)
[100.167020] Starting dynamic subtest: pipe-A-eDP-1-linear-YUYV
[100.182301] Dynamic subtest pipe-A-eDP-1-linear-YUYV: SUCCESS (0.022s)
[100.189232] Starting dynamic subtest: pipe-A-eDP-1-linear-Y210
[100.204774] Dynamic subtest pipe-A-eDP-1-linear-Y210: SUCCESS (0.022s)
[100.216762] Starting dynamic subtest: pipe-A-eDP-1-linear-XYUV8888
[100.226793] Dynamic subtest pipe-A-eDP-1-linear-XYUV8888: SUCCESS (0.022s)
[100.234115] Starting dynamic subtest: pipe-A-eDP-1-linear-XV30
[100.248927] Dynamic subtest pipe-A-eDP-1-linear-XV30: SUCCESS (0.022s)
[100.255782] Starting dynamic subtest: pipe-A-eDP-1-linear-XV36
[100.270913] Dynamic subtest pipe-A-eDP-1-linear-XV36: SUCCESS (0.022s)
[100.654942] Subtest basic-modeset-with-all-modifiers-formats: SUCCESS (2.757s)
[101.416459] [014/130] (916s left) kms_lease (lease-unleased-crtc)
[101.510056] Starting subtest: lease-unleased-crtc
[101.512463] Starting dynamic subtest: pipe-A-eDP-1
[101.810990] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.299s)
[101.823088] Starting dynamic subtest: pipe-B-eDP-1
[102.574481] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.761s)
[102.585082] Starting dynamic subtest: pipe-C-eDP-1
[102.587202] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.005s)
[102.594320] Starting dynamic subtest: pipe-D-eDP-1
[102.596533] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[102.598744] Subtest lease-unleased-crtc: SUCCESS (1.073s)
[102.927063] [015/130] (915s left) i915_pm_rpm (system-suspend)
[105.092245] Starting subtest: system-suspend
[122.750441] Subtest system-suspend: SUCCESS (2.992s)
[123.110333] [016/130] (895s left) kms_ccs (bad-aux-stride-y-tiled-ccs)
[123.219426] Starting subtest: bad-aux-stride-y-tiled-ccs
[123.221603] Starting dynamic subtest: pipe-A-eDP-1
[123.244187] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[123.254697] Starting dynamic subtest: pipe-B-eDP-1
[123.275646] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[123.284018] Starting dynamic subtest: pipe-C-eDP-1
[123.304971] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[123.313049] Starting dynamic subtest: pipe-D-eDP-1
[123.335270] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[123.339417] Subtest bad-aux-stride-y-tiled-ccs: SKIP (0.003s)
[125.351086] [017/130] (893s left) kms_flip (2x-plain-flip-interruptible)
[125.443203] Starting subtest: 2x-plain-flip-interruptible
[125.454662] Subtest 2x-plain-flip-interruptible: SKIP (0.011s)
[125.568316] [018/130] (892s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[125.671573] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[125.675808] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[125.778650] [019/130] (892s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-sf)
[130.822312] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-sf
[130.824595] Starting dynamic subtest: pipe-A-eDP-1
[131.600011] Dynamic subtest pipe-A-eDP-1: SKIP (0.776s)
[131.608932] Starting dynamic subtest: pipe-B-eDP-1
[132.724261] Dynamic subtest pipe-B-eDP-1: SKIP (1.122s)
[132.728485] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-sf: SKIP (1.901s)
[133.908501] [020/130] (884s left) gem_exec_fence (invalid-fence-array)
[134.368061] Starting subtest: invalid-fence-array
[134.373312] Subtest invalid-fence-array: SUCCESS (0.004s)
[135.601061] [FACT gem_exec_fence (invalid-fence-array)] new: kernel.kmod_is_loaded.vgem: true
[135.604264] [021/130] (883s left) gem_exec_schedule (pi-distinct-iova)
[135.783518] Starting subtest: pi-distinct-iova
[135.785952] Starting dynamic subtest: rcs0
[135.861465] Dynamic subtest rcs0: SUCCESS (0.080s)
[135.870096] Starting dynamic subtest: vcs0
[135.923140] Dynamic subtest vcs0: SUCCESS (0.061s)
[135.932685] Starting dynamic subtest: vcs1
[135.979306] Dynamic subtest vcs1: SUCCESS (0.056s)
[135.988259] Starting dynamic subtest: vecs0
[136.038741] Dynamic subtest vecs0: SUCCESS (0.058s)
[136.051042] Starting dynamic subtest: ccs0
[136.099639] Dynamic subtest ccs0: SUCCESS (0.061s)
[136.101993] Subtest pi-distinct-iova: SUCCESS (0.320s)
[137.667270] [022/130] (881s left) kms_psr2_sf (pr-overlay-plane-update-sf-dmg-area)
[142.748936] Starting subtest: pr-overlay-plane-update-sf-dmg-area
[142.753633] Subtest pr-overlay-plane-update-sf-dmg-area: SKIP (0.003s)
[143.601965] [023/130] (875s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[143.696467] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[143.698616] Starting dynamic subtest: pipe-A-eDP-1
[143.748359] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.051s)
[143.758782] Starting dynamic subtest: pipe-B-eDP-1
[143.799034] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.050s)
[143.808566] Starting dynamic subtest: pipe-C-eDP-1
[143.850811] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.051s)
[143.859998] Starting dynamic subtest: pipe-D-eDP-1
[143.901193] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.050s)
[143.903853] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SUCCESS (0.205s)
[146.063372] [024/130] (872s left) kms_vrr (negative-basic)
[146.153360] Starting subtest: negative-basic
[146.160530] Starting dynamic subtest: pipe-A-eDP-1
[151.615171] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.453s)
[151.890879] Subtest negative-basic: SUCCESS (5.737s)
[152.687281] [025/130] (866s left) kms_big_fb (4-tiled-16bpp-rotate-270)
[152.792747] Starting subtest: 4-tiled-16bpp-rotate-270
[152.811494] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.018s)
[152.936918] [026/130] (865s left) perf_pmu (most-busy-idle-check-all)
[152.975432] Starting subtest: most-busy-idle-check-all
[152.977688] Starting dynamic subtest: rcs0
[154.591334] Dynamic subtest rcs0: SUCCESS (1.615s)
[154.601481] Starting dynamic subtest: bcs0
[156.575038] Dynamic subtest bcs0: SUCCESS (1.982s)
[156.585729] Starting dynamic subtest: vcs0
[158.559040] Dynamic subtest vcs0: SUCCESS (1.982s)
[158.570786] Starting dynamic subtest: vcs1
[160.605688] Dynamic subtest vcs1: SUCCESS (2.045s)
[160.618311] Starting dynamic subtest: vecs0
[162.590460] Dynamic subtest vecs0: SUCCESS (1.982s)
[162.600496] Starting dynamic subtest: ccs0
[164.574208] Dynamic subtest ccs0: SUCCESS (1.982s)
[164.577362] Subtest most-busy-idle-check-all: SUCCESS (11.599s)
[164.692522] [027/130] (854s left) kms_pm_backlight (brightness-with-dpms)
[164.800915] Starting subtest: brightness-with-dpms
[164.862764] Starting dynamic subtest: eDP-1
[167.363817] Dynamic subtest eDP-1: SUCCESS (2.501s)
[167.366284] Subtest brightness-with-dpms: SUCCESS (2.565s)
[168.224752] [028/130] (850s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-submi
[168.279083] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-submitted
[168.386692] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-submitted: SUCCESS (0.105s)
[168.463673] [029/130] (850s left) gem_pxp (verify-pxp-key-change-after-suspend-resume)
[168.587478] Starting subtest: verify-pxp-key-change-after-suspend-resume
[175.663949] Subtest verify-pxp-key-change-after-suspend-resume: SUCCESS (7.078s)
[175.803049] [030/130] (843s left) kms_pipe_crc_basic (suspend-read-crc)
[175.903548] Starting subtest: suspend-read-crc
[175.905889] Starting dynamic subtest: pipe-A-eDP-1
[193.550968] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.260s)
[193.561004] Starting dynamic subtest: pipe-B-eDP-1
[211.590113] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.912s)
[211.599506] Starting dynamic subtest: pipe-C-eDP-1
[229.514606] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.777s)
[229.524043] Starting dynamic subtest: pipe-D-eDP-1
[247.499767] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.726s)
[247.501920] Subtest suspend-read-crc: SUCCESS (10.680s)
[248.342727] [031/130] (770s left) sw_sync (alloc_merge_fence)
[248.383314] Starting subtest: alloc_merge_fence
[248.385830] Subtest alloc_merge_fence: SUCCESS (0.000s)
[248.443921] [032/130] (770s left) gem_exec_balancer (individual)
[248.501791] Starting subtest: individual
[250.541975] Subtest individual: SUCCESS (2.039s)
[250.883748] [033/130] (768s left) gem_create (hog-create)
[250.935641] Starting subtest: hog-create
[250.937942] Starting dynamic subtest: smem0
[284.428177] Dynamic subtest smem0: SUCCESS (33.497s)
[284.431000] Subtest hog-create: SUCCESS (33.498s)
[284.613948] [034/130] (734s left) gem_exec_reloc (basic-cpu-wc)
[284.671185] Subtest basic-cpu-wc: SKIP (0.000s)
[284.764278] [035/130] (734s left) gem_ctx_sseu (invalid-args)
[284.818400] Subtest invalid-args: SKIP (0.000s)
[284.881685] [036/130] (734s left) kms_flip (flip-vs-fences-interruptible)
[284.977056] Starting subtest: flip-vs-fences-interruptible
[284.981916] Subtest flip-vs-fences-interruptible: SKIP (0.000s)
[285.114588] [037/130] (734s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite)
[285.214513] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite
[285.219063] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[285.304152] [038/130] (734s left) kms_prime (basic-crc-hybrid)
[285.387251] Subtest basic-crc-hybrid: SKIP (0.000s)
[285.473111] [039/130] (733s left) gem_softpin (allocator-fork)
[285.535538] Starting subtest: allocator-fork
[287.746311] Subtest allocator-fork: SUCCESS (2.210s)
[287.874188] [040/130] (731s left) sw_sync (sync_multi_producer_single_consumer)
[287.908203] Starting subtest: sync_multi_producer_single_consumer
[288.981362] Subtest sync_multi_producer_single_consumer: SUCCESS (1.075s)
[289.044739] [041/130] (730s left) core_auth (getclient-simple)
[289.079764] Starting subtest: getclient-simple
[289.108255] Subtest getclient-simple: SUCCESS (0.029s)
[289.219057] [042/130] (730s left) gem_ctx_create (basic)
[289.282305] Starting subtest: basic
[289.285305] Subtest basic: SUCCESS (0.000s)
[289.464260] [043/130] (730s left) gem_exec_reloc (basic-softpin)
[289.532159] Subtest basic-softpin: SKIP (0.000s)
[289.615098] [044/130] (730s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[289.727779] Starting subtest: psr-1p-primscrn-spr-indfb-draw-render
[296.450833] Subtest psr-1p-primscrn-spr-indfb-draw-render: SUCCESS (6.728s)
[296.573708] [045/130] (723s left) i915_drm_fdinfo (idle)
[296.611305] Starting subtest: idle
[296.613580] Starting dynamic subtest: rcs0
[297.172935] Dynamic subtest rcs0: SUCCESS (0.563s)
[297.184942] Starting dynamic subtest: bcs0
[297.678927] Dynamic subtest bcs0: SUCCESS (0.503s)
[297.691470] Starting dynamic subtest: vcs0
[298.184538] Dynamic subtest vcs0: SUCCESS (0.503s)
[298.193847] Starting dynamic subtest: vcs1
[298.695083] Dynamic subtest vcs1: SUCCESS (0.503s)
[298.706503] Starting dynamic subtest: vecs0
[299.195570] Dynamic subtest vecs0: SUCCESS (0.503s)
[299.207569] Starting dynamic subtest: ccs0
[299.701229] Dynamic subtest ccs0: SUCCESS (0.504s)
[299.703887] Subtest idle: SUCCESS (3.092s)
[299.778113] [046/130] (719s left) gem_exec_schedule (timeslicing)
[299.847567] Starting subtest: timeslicing
[299.850297] Starting dynamic subtest: rcs0
[299.864318] Dynamic subtest rcs0: SUCCESS (0.026s)
[299.875127] Starting dynamic subtest: bcs0
[299.888527] Dynamic subtest bcs0: SUCCESS (0.024s)
[299.898014] Starting dynamic subtest: vcs0
[299.912235] Dynamic subtest vcs0: SUCCESS (0.023s)
[299.921696] Starting dynamic subtest: vcs1
[299.935861] Dynamic subtest vcs1: SUCCESS (0.023s)
[299.947161] Starting dynamic subtest: vecs0
[299.957343] Dynamic subtest vecs0: SUCCESS (0.021s)
[299.966835] Starting dynamic subtest: ccs0
[299.983364] Dynamic subtest ccs0: SUCCESS (0.025s)
[299.986126] Subtest timeslicing: SUCCESS (0.146s)
[301.502976] [047/130] (718s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[301.599542] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-fullscreen
[301.604281] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[301.718752] [048/130] (718s left) kms_invalid_mode (zero-vdisplay)
[301.807594] Starting subtest: zero-vdisplay
[301.810267] Starting dynamic subtest: pipe-A-eDP-1
[302.092558] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.284s)
[302.102139] Starting dynamic subtest: pipe-B-eDP-1
[302.104466] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[302.113342] Starting dynamic subtest: pipe-C-eDP-1
[302.115932] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[302.125160] Starting dynamic subtest: pipe-D-eDP-1
[302.127771] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[302.130175] Subtest zero-vdisplay: SUCCESS (0.290s)
[302.952183] [049/130] (716s left) kms_dsc (dsc-with-bpc)
[303.041991] Starting subtest: dsc-with-bpc
[303.076280] Subtest dsc-with-bpc: SKIP (0.001s)
[303.115295] [050/130] (716s left) kms_ccs (crc-primary-suspend-y-tiled-gen12-rc-ccs-cc)
[303.199045] Starting subtest: crc-primary-suspend-y-tiled-gen12-rc-ccs-cc
[303.201358] Starting dynamic subtest: pipe-A-eDP-1
[303.210881] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[303.220516] Starting dynamic subtest: pipe-B-eDP-1
[303.230090] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[303.239340] Starting dynamic subtest: pipe-C-eDP-1
[303.249526] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[303.258530] Starting dynamic subtest: pipe-D-eDP-1
[303.268568] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[303.273555] Subtest crc-primary-suspend-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[305.413748] [051/130] (714s left) kms_psr (psr2-sprite-mmap-cpu)
[305.510225] Starting subtest: psr2-sprite-mmap-cpu
[305.512689] Starting dynamic subtest: eDP-1
[307.187005] Dynamic subtest eDP-1: SUCCESS (1.678s)
[307.189542] Subtest psr2-sprite-mmap-cpu: SUCCESS (1.679s)
[308.062529] [052/130] (711s left) kms_psr (pr-primary-blt)
[308.168867] Starting subtest: pr-primary-blt
[308.173193] Subtest pr-primary-blt: SKIP (0.000s)
[308.241905] [053/130] (711s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[308.338403] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[309.217475] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SUCCESS (0.881s)
[309.467768] [054/130] (710s left) kms_big_fb (y-tiled-32bpp-rotate-90)
[309.574689] Starting subtest: y-tiled-32bpp-rotate-90
[309.579149] Subtest y-tiled-32bpp-rotate-90: SKIP (0.000s)
[309.670056] [055/130] (710s left) gem_mmap_gtt (ptrace)
[309.725165] Subtest ptrace: SKIP (0.000s)
[309.785929] [056/130] (710s left) perf (invalid-create-userspace-config)
[309.899346] Starting subtest: invalid-create-userspace-config
[309.901692] Subtest invalid-create-userspace-config: SUCCESS (0.001s)
[309.941205] [057/130] (710s left) kms_cursor_crc (cursor-rapid-movement-32x32)
[310.082218] Starting subtest: cursor-rapid-movement-32x32
[310.125370] Subtest cursor-rapid-movement-32x32: SKIP (0.038s)
[310.398079] [058/130] (709s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-plflip-blt)
[310.498463] Starting subtest: fbcpsr-1p-primscrn-shrfb-plflip-blt
[317.959899] Subtest fbcpsr-1p-primscrn-shrfb-plflip-blt: SUCCESS (7.464s)
[318.116175] [059/130] (702s left) kms_cursor_legacy (single-bo)
[318.238537] Starting subtest: single-bo
[318.240778] Starting dynamic subtest: pipe-A
[323.618425] Dynamic subtest pipe-A: SUCCESS (5.379s)
[323.630107] Starting dynamic subtest: pipe-B
[329.001469] Dynamic subtest pipe-B: SUCCESS (5.381s)
[329.011064] Starting dynamic subtest: pipe-C
[334.383158] Dynamic subtest pipe-C: SUCCESS (5.380s)
[334.392790] Starting dynamic subtest: pipe-D
[339.764823] Dynamic subtest pipe-D: SUCCESS (5.379s)
[339.776790] Starting dynamic subtest: all-pipes
[345.152289] Dynamic subtest all-pipes: SUCCESS (5.386s)
[345.155030] Subtest single-bo: SUCCESS (26.917s)
[345.297071] [060/130] (674s left) kms_getfb (getfb-handle-zero)
[345.386018] Starting subtest: getfb-handle-zero
[345.388289] Subtest getfb-handle-zero: SUCCESS (0.000s)
[345.499425] [061/130] (674s left) kms_setmode (invalid-clone-exclusive-crtc)
[345.558091] Starting subtest: invalid-clone-exclusive-crtc
[345.565232] Subtest invalid-clone-exclusive-crtc: SKIP (0.000s)
[345.655247] [062/130] (674s left) syncobj_wait (single-wait-all-for-submit-submitted)
[345.704835] Starting subtest: single-wait-all-for-submit-submitted
[345.807586] Subtest single-wait-all-for-submit-submitted: SUCCESS (0.103s)
[345.883540] [063/130] (674s left) i915_suspend (fence-restore-untiled)
[345.934279] Starting subtest: fence-restore-untiled
[345.939712] Subtest fence-restore-untiled: SKIP (0.000s)
[346.048703] [064/130] (674s left) kms_fbcon_fbt (fbc-suspend)
[346.109848] Starting subtest: fbc-suspend
[394.032515] Subtest fbc-suspend: SUCCESS (18.676s)
[395.117892] [065/130] (625s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[395.519263] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[396.340987] [066/130] (624s left) vgem_basic (sysfs)
[396.379263] Starting subtest: sysfs
[396.382161] Subtest sysfs: SUCCESS (0.000s)
[396.433720] [067/130] (624s left) perf_pmu (all-busy-idle-check-all)
[396.472887] Starting subtest: all-busy-idle-check-all
[397.182257] Subtest all-busy-idle-check-all: FAIL (0.649s)
[397.790159] [068/130] (622s left) kms_rotation_crc (primary-y-tiled-reflect-x-270)
[397.885393] Starting subtest: primary-y-tiled-reflect-x-270
[397.890510] Subtest primary-y-tiled-reflect-x-270: SKIP (0.001s)
[397.961118] [069/130] (622s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[398.053304] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[398.064560] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.011s)
[398.147805] [070/130] (622s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[398.243073] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[398.245298] Starting dynamic subtest: pipe-A-default-mode
[398.532166] Dynamic subtest pipe-A-default-mode: SKIP (0.285s)
[398.537628] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.286s)
[399.378241] [071/130] (621s left) kms_ccs (random-ccs-data-y-tiled-ccs)
[399.470250] Starting subtest: random-ccs-data-y-tiled-ccs
[399.472844] Starting dynamic subtest: pipe-A-eDP-1
[399.499951] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[399.510468] Starting dynamic subtest: pipe-B-eDP-1
[399.538959] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[399.549295] Starting dynamic subtest: pipe-C-eDP-1
[399.573981] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[399.583745] Starting dynamic subtest: pipe-D-eDP-1
[399.607826] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[399.611950] Subtest random-ccs-data-y-tiled-ccs: SKIP (0.004s)
[401.635376] [072/130] (619s left) kms_flip (2x-blocking-absolute-wf_vblank-interruptible)
[401.728034] Starting subtest: 2x-blocking-absolute-wf_vblank-interruptible
[401.739087] Subtest 2x-blocking-absolute-wf_vblank-interruptible: SKIP (0.011s)
[401.812939] [073/130] (619s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[401.877426] Starting subtest: yf-tiled-to-vebox-yf-tiled
[401.883627] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[402.062805] [074/130] (618s left) gem_exec_create (forked)
[402.122508] Starting subtest: forked
[402.125437] Starting dynamic subtest: smem
[423.702367] Dynamic subtest smem: SUCCESS (21.580s)
[423.705422] Subtest forked: SUCCESS (21.582s)
[423.884842] [075/130] (597s left) gem_lmem_swapping (parallel-random-verify-ccs)
[423.938140] Subtest parallel-random-verify-ccs: SKIP (0.000s)
[423.985716] [076/130] (596s left) gem_partial_pwrite_pread (reads-display)
[424.038236] Subtest reads-display: SKIP (0.000s)
[424.117126] [077/130] (596s left) kms_content_protection (lic-type-0)
[424.292268] Starting subtest: lic-type-0
[427.659561] Subtest lic-type-0: SKIP (3.365s)
[428.825295] [078/130] (592s left) gen9_exec_parse (bb-start-far)
[428.887399] Subtest bb-start-far: SKIP (0.000s)
[428.960100] [079/130] (592s left) gem_create (create-ext-cpu-access-big)
[429.131432] Starting subtest: create-ext-cpu-access-big
[429.137385] Subtest create-ext-cpu-access-big: SKIP (0.001s)
[429.205636] [080/130] (591s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[429.326260] Starting subtest: cursorB-vs-flipA-toggle
[429.331308] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s)
[429.470744] [081/130] (591s left) kms_fb_coherency (memset-crc)
[429.582908] Starting subtest: memset-crc
[429.584807] Starting dynamic subtest: mmap-offset-wc
[429.990895] Dynamic subtest mmap-offset-wc: SUCCESS (0.410s)
[430.298771] Starting dynamic subtest: mmap-offset-uc
[436.453251] Dynamic subtest mmap-offset-uc: SUCCESS (6.165s)
[436.735891] Subtest memset-crc: SUCCESS (7.152s)
[437.577281] [082/130] (583s left) gem_exec_gttfill (engines)
[437.642602] Starting subtest: engines
[437.645482] Starting dynamic subtest: rcs0
[462.335173] Dynamic subtest rcs0: SUCCESS (24.694s)
[462.345110] Starting dynamic subtest: bcs0
[487.477460] Dynamic subtest bcs0: SUCCESS (25.142s)
[487.489884] Starting dynamic subtest: vcs0
[512.812216] Dynamic subtest vcs0: SUCCESS (25.329s)
[512.823159] Starting dynamic subtest: vcs1
[537.990982] Dynamic subtest vcs1: SUCCESS (25.176s)
[538.002094] Starting dynamic subtest: vecs0
[563.254184] Dynamic subtest vecs0: SUCCESS (25.243s)
[563.266993] Starting dynamic subtest: ccs0
[588.377192] Dynamic subtest ccs0: SUCCESS (25.141s)
[588.380125] Subtest engines: SUCCESS (150.738s)
[588.569845] [083/130] (432s left) gem_mmap_gtt (basic)
[588.673210] Subtest basic: SKIP (0.000s)
[588.742949] [084/130] (432s left) kms_cursor_crc (cursor-onscreen-128x42)
[588.974393] Starting subtest: cursor-onscreen-128x42
[589.021888] Subtest cursor-onscreen-128x42: SKIP (0.041s)
[589.224425] [085/130] (432s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[589.328356] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt
[589.333544] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[589.443520] [086/130] (431s left) kms_pm_dc (dc5-psr)
[590.558433] Starting subtest: dc5-psr
[593.534366] Subtest dc5-psr: SUCCESS (2.975s)
[594.374676] [087/130] (427s left) core_getstats
[594.552166] [088/130] (426s left) i915_pm_freq_api (freq-reset-multiple)
[594.609724] Starting subtest: freq-reset-multiple
[594.612263] Starting dynamic subtest: gt0
[604.393391] Dynamic subtest gt0: SUCCESS (9.780s)
[604.404657] Starting dynamic subtest: gt1
[612.828965] Dynamic subtest gt1: SUCCESS (8.436s)
[612.831780] Subtest freq-reset-multiple: SUCCESS (18.218s)
[612.909615] [089/130] (408s left) gem_ctx_persistence (engines-hang)
[612.968463] Starting subtest: engines-hang
[612.971109] Starting dynamic subtest: rcs0
[613.054966] Dynamic subtest rcs0: SUCCESS (0.086s)
[613.066985] Starting dynamic subtest: bcs0
[613.158882] Dynamic subtest bcs0: SUCCESS (0.102s)
[613.171416] Starting dynamic subtest: vcs0
[613.268589] Dynamic subtest vcs0: SUCCESS (0.108s)
[613.281206] Starting dynamic subtest: vcs1
[613.384834] Dynamic subtest vcs1: SUCCESS (0.115s)
[613.397598] Starting dynamic subtest: vecs0
[613.479914] Dynamic subtest vecs0: SUCCESS (0.093s)
[613.492218] Starting dynamic subtest: ccs0
[613.588941] Dynamic subtest ccs0: SUCCESS (0.108s)
[613.591770] Subtest engines-hang: SUCCESS (0.621s)
[613.674394] [090/130] (407s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[613.803242] Starting subtest: cursor-vs-flip-legacy
[643.196782] Subtest cursor-vs-flip-legacy: SUCCESS (29.393s)
[643.990940] [091/130] (377s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[644.098410] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[644.104339] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[644.215654] [092/130] (377s left) kms_panel_fitting (atomic-fastset)
[644.314192] Starting subtest: atomic-fastset
[644.316815] Starting dynamic subtest: pipe-A-eDP-1
[644.503851] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.189s)
[644.515569] Starting dynamic subtest: pipe-B-eDP-1
[645.744319] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.239s)
[645.755721] Starting dynamic subtest: pipe-C-eDP-1
[646.923607] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.178s)
[646.933359] Starting dynamic subtest: pipe-D-eDP-1
[648.147501] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.221s)
[648.150022] Subtest atomic-fastset: SUCCESS (3.831s)
[649.295909] [093/130] (372s left) gem_ctx_param (invalid-param-set)
[649.349593] Starting subtest: invalid-param-set
[649.352577] Subtest invalid-param-set: SUCCESS (0.000s)
[649.433043] [094/130] (372s left) kms_big_fb (yf-tiled-8bpp-rotate-0)
[649.544244] Starting subtest: yf-tiled-8bpp-rotate-0
[649.549287] Subtest yf-tiled-8bpp-rotate-0: SKIP (0.000s)
[649.658931] [095/130] (372s left) api_intel_bb (crc32)
[649.722283] Starting subtest: crc32
[649.724542] Starting dynamic subtest: rcs0-smem0
[650.025863] Dynamic subtest rcs0-smem0: SUCCESS (0.305s)
[650.037044] Starting dynamic subtest: bcs0-smem0
[650.329803] Dynamic subtest bcs0-smem0: SUCCESS (0.301s)
[650.342604] Starting dynamic subtest: vcs0-smem0
[650.673839] Dynamic subtest vcs0-smem0: SUCCESS (0.342s)
[650.686545] Starting dynamic subtest: vcs1-smem0
[651.005249] Dynamic subtest vcs1-smem0: SUCCESS (0.329s)
[651.017373] Starting dynamic subtest: vecs0-smem0
[651.339036] Dynamic subtest vecs0-smem0: SUCCESS (0.332s)
[651.351289] Starting dynamic subtest: ccs0-smem0
[651.678835] Dynamic subtest ccs0-smem0: SUCCESS (0.337s)
[651.681881] Subtest crc32: SUCCESS (1.959s)
[651.830794] [096/130] (369s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-wc)
[651.931380] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-wc
[655.605056] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (3.678s)
[656.783963] [097/130] (365s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-onoff)
[656.886356] Starting subtest: fbc-2p-scndscrn-cur-indfb-onoff
[656.891459] Subtest fbc-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[656.979381] [098/130] (364s left) syncobj_timeline (single-wait-all-available-signaled)
[657.041138] Starting subtest: single-wait-all-available-signaled
[657.044310] Subtest single-wait-all-available-signaled: SUCCESS (0.000s)
[657.152438] [099/130] (364s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-render)
[657.250467] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-render
[657.255309] Subtest psr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[657.353976] [100/130] (364s left) perf (global-sseu-config-invalid)
[657.461613] Starting subtest: global-sseu-config-invalid
[657.466796] Subtest global-sseu-config-invalid: SKIP (0.000s)
[657.510353] [101/130] (364s left) gem_sync (basic-many-each)
[657.590706] Starting subtest: basic-many-each
[659.893078] Subtest basic-many-each: SUCCESS (2.314s)
[660.082789] [102/130] (361s left) kms_psr (fbc-pr-dpms)
[660.170518] Starting subtest: fbc-pr-dpms
[660.175850] Subtest fbc-pr-dpms: SKIP (0.000s)
[660.243960] [103/130] (361s left) gem_userptr_blits (map-fixed-invalidate-busy)
[660.312218] Starting subtest: map-fixed-invalidate-busy
[660.316960] Subtest map-fixed-invalidate-busy: SKIP (0.000s)
[660.450386] [104/130] (361s left) kms_chamelium_color (ctm-green-to-red)
[660.542438] Subtest ctm-green-to-red: SKIP (0.000s)
[660.590857] [105/130] (361s left) gem_softpin (evict-snoop)
[660.651578] Starting subtest: evict-snoop
[660.657057] Subtest evict-snoop: SKIP (0.000s)
[660.778500] [106/130] (361s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-render)
[660.882439] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-render
[668.165035] Subtest psr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.286s)
[668.278791] [107/130] (353s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[668.384143] Starting subtest: x-tiled-64bpp-rotate-90
[668.402608] Subtest x-tiled-64bpp-rotate-90: SKIP (0.018s)
[668.522366] [108/130] (353s left) gem_exec_flush (basic-wb-ro-default)
[668.578727] Starting subtest: basic-wb-ro-default
[674.027871] Subtest basic-wb-ro-default: SUCCESS (5.449s)
[674.210612] [109/130] (348s left) gem_exec_whisper (basic-fds-all)
[674.276605] Starting subtest: basic-fds-all
[685.887717] Subtest basic-fds-all: SUCCESS (11.608s)
[686.084903] [110/130] (336s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-downscaling)
[686.181366] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-downscaling
[686.184136] Starting dynamic subtest: pipe-A-default-mode
[686.469385] Dynamic subtest pipe-A-default-mode: SKIP (0.284s)
[686.474316] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-downscaling: SKIP (0.285s)
[687.288994] [111/130] (335s left) gem_render_copy (y-tiled)
[687.346319] Starting subtest: y-tiled
[687.351091] Subtest y-tiled: SKIP (0.000s)
[687.528698] [112/130] (334s left) kms_psr (psr2-primary-page-flip)
[687.621308] Starting subtest: psr2-primary-page-flip
[687.623846] Starting dynamic subtest: eDP-1
[689.375939] Dynamic subtest eDP-1: SUCCESS (1.756s)
[689.378187] Subtest psr2-primary-page-flip: SUCCESS (1.757s)
[690.242385] [113/130] (332s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[690.296308] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[690.298498] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.000s)
[690.383824] [114/130] (332s left) syncobj_timeline (single-wait-all-for-submit-signaled)
[690.436788] Starting subtest: single-wait-all-for-submit-signaled
[690.439053] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[690.553460] [115/130] (332s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-move)
[690.649460] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-move
[697.855083] Subtest fbcpsr-1p-primscrn-cur-indfb-move: SUCCESS (7.211s)
[698.024952] [116/130] (324s left) gem_exec_params (dr1-dirt)
[698.084206] Starting subtest: DR1-dirt
[698.086773] Subtest DR1-dirt: SUCCESS (0.000s)
[698.178853] [117/130] (324s left) kms_plane_lowres (tiling-4)
[698.286573] Starting subtest: tiling-4
[698.289334] Starting dynamic subtest: pipe-A-eDP-1
[698.294577] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[698.594121] Starting dynamic subtest: pipe-B-eDP-1
[698.599319] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[698.611200] Starting dynamic subtest: pipe-C-eDP-1
[698.616262] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[698.625732] Starting dynamic subtest: pipe-D-eDP-1
[698.630329] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[698.635332] Subtest tiling-4: SKIP (0.295s)
[699.366890] [118/130] (323s left) gem_spin_batch (legacy-resubmit)
[699.437201] Starting subtest: legacy-resubmit
[699.439611] Starting dynamic subtest: default
[699.445521] Dynamic subtest default: SUCCESS (0.007s)
[699.456272] Starting dynamic subtest: render
[699.459025] Dynamic subtest render: SUCCESS (0.001s)
[699.469612] Starting dynamic subtest: bsd
[699.471805] Dynamic subtest bsd: SUCCESS (0.003s)
[699.481395] Starting dynamic subtest: bsd1
[699.483838] Dynamic subtest bsd1: SUCCESS (0.001s)
[699.493408] Starting dynamic subtest: bsd2
[699.495604] Dynamic subtest bsd2: SUCCESS (0.002s)
[699.505178] Starting dynamic subtest: blt
[699.507252] Dynamic subtest blt: SUCCESS (0.002s)
[699.516594] Starting dynamic subtest: vebox
[699.518980] Dynamic subtest vebox: SUCCESS (0.003s)
[699.520998] Subtest legacy-resubmit: SUCCESS (0.027s)
[699.709144] [119/130] (323s left) kms_color (ctm-0-25)
[699.808354] Starting subtest: ctm-0-25
[699.811106] Starting dynamic subtest: pipe-A-eDP-1
[703.351680] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.541s)
[703.361615] Starting dynamic subtest: pipe-B-eDP-1
[707.544282] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.192s)
[707.559574] Starting dynamic subtest: pipe-C-eDP-1
[711.523120] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.975s)
[711.536601] Starting dynamic subtest: pipe-D-eDP-1
[715.503995] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.982s)
[715.506435] Subtest ctm-0-25: SUCCESS (15.695s)
[716.348921] [120/130] (306s left) gem_mmap_gtt (basic-write-read)
[716.413900] Subtest basic-write-read: SKIP (0.000s)
[716.485076] [121/130] (306s left) syncobj_timeline (invalid-single-wait-unsubmitted)
[716.535055] Starting subtest: invalid-single-wait-unsubmitted
[716.537334] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[716.646425] [122/130] (306s left) kms_psr (fbc-psr-cursor-mmap-cpu)
[716.748469] Starting subtest: fbc-psr-cursor-mmap-cpu
[716.750882] Starting dynamic subtest: eDP-1
[716.755827] Dynamic subtest eDP-1: SKIP (0.000s)
[716.760742] Subtest fbc-psr-cursor-mmap-cpu: SKIP (0.001s)
[716.819753] [123/130] (306s left) kms_selftest (drm_format_helper)
[716.860208] Starting subtest: drm_format_helper
[716.905204] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8
[717.034158] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8: SUCCESS (0.131s)
[717.046358] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332
[717.153275] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332: SUCCESS (0.118s)
[717.164443] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565
[717.263973] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565: SUCCESS (0.109s)
[717.273942] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555
[717.404374] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555: SUCCESS (0.136s)
[717.416046] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555
[717.518886] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555: SUCCESS (0.115s)
[717.534480] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551
[717.641463] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551: SUCCESS (0.122s)
[717.652508] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888
[717.755538] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888: SUCCESS (0.117s)
[717.766896] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888
[717.870235] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888: SUCCESS (0.114s)
[717.882127] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888
[717.972110] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888: SUCCESS (0.098s)
[717.983624] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010
[718.095016] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010: SUCCESS (0.125s)
[718.106057] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010
[718.223067] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010: SUCCESS (0.128s)
[718.233257] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_mono
[718.337094] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_mono: SUCCESS (0.111s)
[718.348468] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_swab
[718.472357] Dynamic subtest drm_format_helper_test-drm_test_fb_swab: SUCCESS (0.134s)
[718.483088] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888
[718.575376] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888: SUCCESS (0.104s)
[718.585354] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888
[718.703979] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888: SUCCESS (0.128s)
[718.716859] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_clip_offset
[718.827998] Dynamic subtest drm_format_helper_test-drm_test_fb_clip_offset: SUCCESS (0.120s)
[718.840398] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_memcpy
[718.961450] Dynamic subtest drm_format_helper_test-drm_test_fb_memcpy: SUCCESS (0.135s)
[718.963571] Subtest drm_format_helper: SUCCESS (2.101s)
[719.171519] [124/130] (303s left) gem_wait (invalid-buf)
[719.227565] Starting subtest: invalid-buf
[719.230594] Subtest invalid-buf: SUCCESS (0.000s)
[719.466563] [125/130] (303s left) kms_cursor_legacy (flip-vs-cursor-crc-legacy)
[719.594970] Starting subtest: flip-vs-cursor-crc-legacy
[720.239356] Subtest flip-vs-cursor-crc-legacy: SUCCESS (0.647s)
[721.075354] [126/130] (301s left) kms_getfb (getfb2-into-addfb2)
[721.181267] Starting subtest: getfb2-into-addfb2
[721.183701] Subtest getfb2-into-addfb2: SUCCESS (0.000s)
[721.297317] [127/130] (301s left) gem_mmap_offset (open-flood)
[721.356301] Starting subtest: open-flood
[742.840014] Subtest open-flood: SUCCESS (21.488s)
[742.946892] [128/130] (280s left) prime_vgem (fence-wait)
[743.034181] Starting subtest: fence-wait
[743.036016] Starting dynamic subtest: rcs0
[744.090418] Dynamic subtest rcs0: SUCCESS (1.054s)
[744.103174] Starting dynamic subtest: bcs0
[745.169355] Dynamic subtest bcs0: SUCCESS (1.078s)
[745.183277] Starting dynamic subtest: vcs0
[746.213618] Dynamic subtest vcs0: SUCCESS (1.043s)
[746.227189] Starting dynamic subtest: vcs1
[747.254758] Dynamic subtest vcs1: SUCCESS (1.041s)
[747.268497] Starting dynamic subtest: vecs0
[748.299000] Dynamic subtest vecs0: SUCCESS (1.043s)
[748.312621] Starting dynamic subtest: ccs0
[749.769328] Dynamic subtest ccs0: SUCCESS (1.468s)
[749.772262] Subtest fence-wait: SUCCESS (6.734s)
[749.888718] [129/130] (273s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[749.989053] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[749.993160] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[750.086401] [130/130] (273s left) gem_vm_create (isolation)
[750.142508] Starting subtest: isolation
[750.152229] Subtest isolation: SUCCESS (0.010s)
[750.275328] Closing watchdogs
results: parsing output: 0/ for test: kms_busy
results: parsing output: 1/ for test: gem_ctx_sseu
results: parsing output: 2/ for test: kms_ccs
results: parsing output: 3/ for test: kms_big_fb
results: parsing output: 4/ for test: gem_exec_schedule
results: parsing output: 5/ for test: kms_draw_crc
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: kms_plane_scaling
results: parsing output: 8/ for test: gem_userptr_blits
results: parsing output: 9/ for test: gem_userptr_blits
results: parsing output: 10/ for test: kms_sequence
results: parsing output: 11/ for test: fbdev
results: parsing output: 12/ for test: kms_async_flips
results: parsing output: 13/ for test: kms_lease
results: parsing output: 14/ for test: i915_pm_rpm
results: parsing output: 15/ for test: kms_ccs
results: parsing output: 16/ for test: kms_flip
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: kms_psr2_sf
results: parsing output: 19/ for test: gem_exec_fence
results: parsing output: 20/ for test: gem_exec_schedule
results: parsing output: 21/ for test: kms_psr2_sf
results: parsing output: 22/ for test: kms_ccs
results: parsing output: 23/ for test: kms_vrr
results: parsing output: 24/ for test: kms_big_fb
results: parsing output: 25/ for test: perf_pmu
results: parsing output: 26/ for test: kms_pm_backlight
results: parsing output: 27/ for test: syncobj_timeline
results: parsing output: 28/ for test: gem_pxp
results: parsing output: 29/ for test: kms_pipe_crc_basic
results: parsing output: 30/ for test: sw_sync
results: parsing output: 31/ for test: gem_exec_balancer
results: parsing output: 32/ for test: gem_create
results: parsing output: 33/ for test: gem_exec_reloc
results: parsing output: 34/ for test: gem_ctx_sseu
results: parsing output: 35/ for test: kms_flip
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: kms_prime
results: parsing output: 38/ for test: gem_softpin
results: parsing output: 39/ for test: sw_sync
results: parsing output: 40/ for test: core_auth
results: parsing output: 41/ for test: gem_ctx_create
results: parsing output: 42/ for test: gem_exec_reloc
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: i915_drm_fdinfo
results: parsing output: 45/ for test: gem_exec_schedule
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_invalid_mode
results: parsing output: 48/ for test: kms_dsc
results: parsing output: 49/ for test: kms_ccs
results: parsing output: 50/ for test: kms_psr
results: parsing output: 51/ for test: kms_psr
results: parsing output: 52/ for test: kms_big_fb
results: parsing output: 53/ for test: kms_big_fb
results: parsing output: 54/ for test: gem_mmap_gtt
results: parsing output: 55/ for test: perf
results: parsing output: 56/ for test: kms_cursor_crc
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_cursor_legacy
results: parsing output: 59/ for test: kms_getfb
results: parsing output: 60/ for test: kms_setmode
results: parsing output: 61/ for test: syncobj_wait
results: parsing output: 62/ for test: i915_suspend
results: parsing output: 63/ for test: kms_fbcon_fbt
results: parsing output: 64/ for test: kms_chamelium_hpd
results: parsing output: 65/ for test: vgem_basic
results: parsing output: 66/ for test: perf_pmu
results: parsing output: 67/ for test: kms_rotation_crc
results: parsing output: 68/ for test: kms_flip
results: parsing output: 69/ for test: kms_flip_scaled_crc
results: parsing output: 70/ for test: kms_ccs
results: parsing output: 71/ for test: kms_flip
results: parsing output: 72/ for test: gem_render_copy
results: parsing output: 73/ for test: gem_exec_create
results: parsing output: 74/ for test: gem_lmem_swapping
results: parsing output: 75/ for test: gem_partial_pwrite_pread
results: parsing output: 76/ for test: kms_content_protection
results: parsing output: 77/ for test: gen9_exec_parse
results: parsing output: 78/ for test: gem_create
results: parsing output: 79/ for test: kms_cursor_legacy
results: parsing output: 80/ for test: kms_fb_coherency
results: parsing output: 81/ for test: gem_exec_gttfill
results: parsing output: 82/ for test: gem_mmap_gtt
results: parsing output: 83/ for test: kms_cursor_crc
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_pm_dc
results: parsing output: 86/ for test: core_getstats
results: parsing output: 87/ for test: i915_pm_freq_api
results: parsing output: 88/ for test: gem_ctx_persistence
results: parsing output: 89/ for test: kms_cursor_legacy
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_panel_fitting
results: parsing output: 92/ for test: gem_ctx_param
results: parsing output: 93/ for test: kms_big_fb
results: parsing output: 94/ for test: api_intel_bb
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: syncobj_timeline
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: perf
results: parsing output: 100/ for test: gem_sync
results: parsing output: 101/ for test: kms_psr
results: parsing output: 102/ for test: gem_userptr_blits
results: parsing output: 103/ for test: kms_chamelium_color
results: parsing output: 104/ for test: gem_softpin
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_big_fb
results: parsing output: 107/ for test: gem_exec_flush
results: parsing output: 108/ for test: gem_exec_whisper
results: parsing output: 109/ for test: kms_flip_scaled_crc
results: parsing output: 110/ for test: gem_render_copy
results: parsing output: 111/ for test: kms_psr
results: parsing output: 112/ for test: syncobj_timeline
results: parsing output: 113/ for test: syncobj_timeline
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: gem_exec_params
results: parsing output: 116/ for test: kms_plane_lowres
results: parsing output: 117/ for test: gem_spin_batch
results: parsing output: 118/ for test: kms_color
results: parsing output: 119/ for test: gem_mmap_gtt
results: parsing output: 120/ for test: syncobj_timeline
results: parsing output: 121/ for test: kms_psr
results: parsing output: 122/ for test: kms_selftest
results: parsing output: 123/ for test: gem_wait
results: parsing output: 124/ for test: kms_cursor_legacy
results: parsing output: 125/ for test: kms_getfb
results: parsing output: 126/ for test: gem_mmap_offset
results: parsing output: 127/ for test: prime_vgem
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: gem_vm_create
Done.