[69.430451] Initializing watchdogs
[69.430602]   /dev/watchdog0
[69.454638] [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]
[69.459379] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[69.461698] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[69.465594] [001/130] (960s left) kms_busy (basic)
[69.589863] Starting subtest: basic
[69.592227] Starting dynamic subtest: flip
[70.252710] Dynamic subtest flip: SUCCESS (0.660s)
[70.261864] Starting dynamic subtest: modeset
[73.919796] Dynamic subtest modeset: SUCCESS (3.666s)
[73.922137] Subtest basic: SUCCESS (4.327s)
[74.700170] [002/130] (955s left) gem_ctx_sseu (engines)
[74.767179] Subtest engines: SKIP (0.000s)
[74.846419] [003/130] (955s left) kms_ccs (crc-primary-basic-y-tiled-ccs)
[74.928075] Starting subtest: crc-primary-basic-y-tiled-ccs
[74.930518] Starting dynamic subtest: pipe-A-eDP-1
[74.954085] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[74.962873] Starting dynamic subtest: pipe-B-eDP-1
[74.985053] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[74.992297] Starting dynamic subtest: pipe-C-eDP-1
[75.014711] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[75.021926] Starting dynamic subtest: pipe-D-eDP-1
[75.044183] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[75.048589] Subtest crc-primary-basic-y-tiled-ccs: SKIP (0.004s)
[77.144211] [004/130] (952s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0)
[77.252506] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0
[78.330355] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.078s)
[78.506702] [005/130] (951s left) gem_exec_schedule (pi-shared-iova)
[78.694480] Starting subtest: pi-shared-iova
[78.696562] Starting dynamic subtest: rcs0
[78.773095] Dynamic subtest rcs0: SUCCESS (0.081s)
[78.784695] Starting dynamic subtest: vcs0
[78.826333] Dynamic subtest vcs0: SUCCESS (0.054s)
[78.835621] Starting dynamic subtest: vcs1
[78.879062] Dynamic subtest vcs1: SUCCESS (0.052s)
[78.889926] Starting dynamic subtest: vecs0
[78.930618] Dynamic subtest vecs0: SUCCESS (0.052s)
[78.940805] Starting dynamic subtest: ccs0
[78.989375] Dynamic subtest ccs0: SUCCESS (0.058s)
[78.992080] Subtest pi-shared-iova: SUCCESS (0.298s)
[80.013343] [006/130] (950s left) kms_draw_crc (fill-fb)
[80.126358] Starting subtest: fill-fb
[80.628761] Subtest fill-fb: SUCCESS (0.505s)
[80.742249] [007/130] (949s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-mmap-wc)
[80.847808] Starting subtest: psr-1p-offscren-pri-shrfb-draw-mmap-wc
[87.630434] Subtest psr-1p-offscren-pri-shrfb-draw-mmap-wc: SUCCESS (6.785s)
[87.743891] [008/130] (942s left) kms_plane_scaling (planes-downscale-factor-0-75-unity-scaling)
[87.848870] Starting subtest: planes-downscale-factor-0-75-unity-scaling
[87.851226] Starting dynamic subtest: pipe-A
[87.933750] Dynamic subtest pipe-A: SUCCESS (0.085s)
[87.946779] Starting dynamic subtest: pipe-B
[89.065849] Dynamic subtest pipe-B: SUCCESS (1.132s)
[89.074357] Starting dynamic subtest: pipe-C
[90.167757] Dynamic subtest pipe-C: SUCCESS (1.102s)
[90.177431] Starting dynamic subtest: pipe-D
[91.287545] Dynamic subtest pipe-D: SUCCESS (1.120s)
[91.289926] Subtest planes-downscale-factor-0-75-unity-scaling: SUCCESS (3.439s)
[92.432663] [009/130] (937s left) gem_userptr_blits (sync-unmap-cycles)
[92.496644] Starting subtest: sync-unmap-cycles
[97.878746] Subtest sync-unmap-cycles: SUCCESS (5.382s)
[98.013206] [010/130] (932s left) gem_userptr_blits (dmabuf-unsync)
[98.083582] Subtest dmabuf-unsync: SKIP (0.000s)
[98.192467] [011/130] (932s left) kms_sequence (queue-busy)
[98.283679] Starting subtest: queue-busy
[98.285713] Starting dynamic subtest: pipe-A-eDP-1
[100.242465] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.954s)
[100.251472] Starting dynamic subtest: pipe-B-eDP-1
[102.820353] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.578s)
[102.829420] Starting dynamic subtest: pipe-C-eDP-1
[105.432559] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.611s)
[105.441653] Starting dynamic subtest: pipe-D-eDP-1
[107.960352] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.529s)
[107.962820] Subtest queue-busy: SUCCESS (9.676s)
[108.837460] [012/130] (921s left) fbdev (read)
[108.875809] Starting subtest: read
[109.406893] Subtest read: SUCCESS (0.529s)
[109.466421] [013/130] (920s left) kms_async_flips (basic-modeset-with-all-modifiers-formats)
[109.566408] Starting subtest: basic-modeset-with-all-modifiers-formats
[109.939075] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB8888
[110.708750] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB8888: SUCCESS (0.770s)
[110.716297] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB2101010
[110.730601] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB2101010: SUCCESS (0.022s)
[110.738133] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB16161616F
[110.753361] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB16161616F: SUCCESS (0.022s)
[110.761670] Starting dynamic subtest: pipe-A-eDP-1-4-RGB565
[110.964359] Dynamic subtest pipe-A-eDP-1-4-RGB565: SUCCESS (0.211s)
[110.973609] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB8888
[110.986909] Dynamic subtest pipe-A-eDP-1-4-XRGB8888: SUCCESS (0.022s)
[111.000045] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB2101010
[111.008929] Dynamic subtest pipe-A-eDP-1-4-XRGB2101010: SUCCESS (0.022s)
[111.018329] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB16161616F
[111.030733] Dynamic subtest pipe-A-eDP-1-4-XRGB16161616F: SUCCESS (0.022s)
[111.038869] Starting dynamic subtest: pipe-A-eDP-1-4-YUYV
[111.053457] Dynamic subtest pipe-A-eDP-1-4-YUYV: SUCCESS (0.022s)
[111.066167] Starting dynamic subtest: pipe-A-eDP-1-4-Y210
[111.075632] Dynamic subtest pipe-A-eDP-1-4-Y210: SUCCESS (0.022s)
[111.085218] Starting dynamic subtest: pipe-A-eDP-1-4-XYUV8888
[111.097422] Dynamic subtest pipe-A-eDP-1-4-XYUV8888: SUCCESS (0.022s)
[111.105082] Starting dynamic subtest: pipe-A-eDP-1-4-XV30
[111.120057] Dynamic subtest pipe-A-eDP-1-4-XV30: SUCCESS (0.022s)
[111.127559] Starting dynamic subtest: pipe-A-eDP-1-4-XV36
[111.142266] Dynamic subtest pipe-A-eDP-1-4-XV36: SUCCESS (0.022s)
[111.151052] Starting dynamic subtest: pipe-A-eDP-1-x-RGB565
[111.353442] Dynamic subtest pipe-A-eDP-1-x-RGB565: SUCCESS (0.211s)
[111.362690] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB8888
[111.375429] Dynamic subtest pipe-A-eDP-1-x-XRGB8888: SUCCESS (0.022s)
[111.383076] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB2101010
[111.397419] Dynamic subtest pipe-A-eDP-1-x-XRGB2101010: SUCCESS (0.022s)
[111.404960] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB16161616F
[111.420083] Dynamic subtest pipe-A-eDP-1-x-XRGB16161616F: SUCCESS (0.022s)
[111.427791] Starting dynamic subtest: pipe-A-eDP-1-x-YUYV
[111.442368] Dynamic subtest pipe-A-eDP-1-x-YUYV: SUCCESS (0.022s)
[111.451632] Starting dynamic subtest: pipe-A-eDP-1-x-Y210
[111.464106] Dynamic subtest pipe-A-eDP-1-x-Y210: SUCCESS (0.022s)
[111.471510] Starting dynamic subtest: pipe-A-eDP-1-x-XYUV8888
[111.486484] Dynamic subtest pipe-A-eDP-1-x-XYUV8888: SUCCESS (0.022s)
[111.497850] Starting dynamic subtest: pipe-A-eDP-1-x-XV30
[111.508443] Dynamic subtest pipe-A-eDP-1-x-XV30: SUCCESS (0.022s)
[111.515812] Starting dynamic subtest: pipe-A-eDP-1-x-XV36
[111.530869] Dynamic subtest pipe-A-eDP-1-x-XV36: SUCCESS (0.022s)
[111.538095] Starting dynamic subtest: pipe-A-eDP-1-linear-RGB565
[111.742077] Dynamic subtest pipe-A-eDP-1-linear-RGB565: SUCCESS (0.211s)
[111.752993] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB8888
[111.764473] Dynamic subtest pipe-A-eDP-1-linear-XRGB8888: SUCCESS (0.022s)
[111.771960] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB2101010
[111.786760] Dynamic subtest pipe-A-eDP-1-linear-XRGB2101010: SUCCESS (0.022s)
[111.795787] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB16161616F
[111.808931] Dynamic subtest pipe-A-eDP-1-linear-XRGB16161616F: SUCCESS (0.022s)
[111.816111] Starting dynamic subtest: pipe-A-eDP-1-linear-YUYV
[111.831186] Dynamic subtest pipe-A-eDP-1-linear-YUYV: SUCCESS (0.022s)
[111.838354] Starting dynamic subtest: pipe-A-eDP-1-linear-Y210
[111.853740] Dynamic subtest pipe-A-eDP-1-linear-Y210: SUCCESS (0.022s)
[111.865828] Starting dynamic subtest: pipe-A-eDP-1-linear-XYUV8888
[111.875590] Dynamic subtest pipe-A-eDP-1-linear-XYUV8888: SUCCESS (0.022s)
[111.882750] Starting dynamic subtest: pipe-A-eDP-1-linear-XV30
[111.897754] Dynamic subtest pipe-A-eDP-1-linear-XV30: SUCCESS (0.022s)
[111.904965] Starting dynamic subtest: pipe-A-eDP-1-linear-XV36
[111.920082] Dynamic subtest pipe-A-eDP-1-linear-XV36: SUCCESS (0.022s)
[112.302054] Subtest basic-modeset-with-all-modifiers-formats: SUCCESS (2.736s)
[113.070735] [014/130] (917s left) kms_lease (lease-unleased-crtc)
[113.162156] Starting subtest: lease-unleased-crtc
[113.164756] Starting dynamic subtest: pipe-A-eDP-1
[113.453910] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.290s)
[113.463827] Starting dynamic subtest: pipe-B-eDP-1
[114.230559] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.775s)
[114.241359] Starting dynamic subtest: pipe-C-eDP-1
[114.243674] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.007s)
[114.251411] Starting dynamic subtest: pipe-D-eDP-1
[114.253253] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[114.255091] Subtest lease-unleased-crtc: SUCCESS (1.081s)
[114.574500] [015/130] (915s left) i915_pm_rpm (system-suspend)
[116.117104] Starting subtest: system-suspend
[134.144361] Subtest system-suspend: SUCCESS (2.997s)
[134.507057] [016/130] (896s left) kms_ccs (bad-aux-stride-y-tiled-ccs)
[134.634870] Starting subtest: bad-aux-stride-y-tiled-ccs
[134.637128] Starting dynamic subtest: pipe-A-eDP-1
[134.659557] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[134.668996] Starting dynamic subtest: pipe-B-eDP-1
[134.690509] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[134.698771] Starting dynamic subtest: pipe-C-eDP-1
[134.720626] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[134.728872] Starting dynamic subtest: pipe-D-eDP-1
[134.751262] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[134.755344] Subtest bad-aux-stride-y-tiled-ccs: SKIP (0.003s)
[136.754691] [017/130] (893s left) kms_flip (2x-plain-flip-interruptible)
[136.850648] Starting subtest: 2x-plain-flip-interruptible
[136.866255] Subtest 2x-plain-flip-interruptible: SKIP (0.013s)
[136.938571] [018/130] (893s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[137.048682] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[137.052900] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[137.137042] [019/130] (893s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-sf)
[142.199596] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-sf
[142.201556] Starting dynamic subtest: pipe-A-eDP-1
[143.001972] Dynamic subtest pipe-A-eDP-1: SKIP (0.800s)
[143.010676] Starting dynamic subtest: pipe-B-eDP-1
[144.119660] Dynamic subtest pipe-B-eDP-1: SKIP (1.114s)
[144.124006] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-sf: SKIP (1.918s)
[145.297809] [020/130] (885s left) gem_exec_fence (invalid-fence-array)
[145.754569] Starting subtest: invalid-fence-array
[145.760063] Subtest invalid-fence-array: SUCCESS (0.003s)
[147.009472] [FACT gem_exec_fence (invalid-fence-array)] new: kernel.kmod_is_loaded.vgem: true
[147.012779] [021/130] (883s left) gem_exec_schedule (pi-distinct-iova)
[147.201102] Starting subtest: pi-distinct-iova
[147.203064] Starting dynamic subtest: rcs0
[147.277973] Dynamic subtest rcs0: SUCCESS (0.078s)
[147.289813] Starting dynamic subtest: vcs0
[147.336583] Dynamic subtest vcs0: SUCCESS (0.058s)
[147.345739] Starting dynamic subtest: vcs1
[147.390265] Dynamic subtest vcs1: SUCCESS (0.053s)
[147.403572] Starting dynamic subtest: vecs0
[147.439192] Dynamic subtest vecs0: SUCCESS (0.049s)
[147.449629] Starting dynamic subtest: ccs0
[147.499833] Dynamic subtest ccs0: SUCCESS (0.060s)
[147.502175] Subtest pi-distinct-iova: SUCCESS (0.301s)
[149.050844] [022/130] (881s left) kms_psr2_sf (pr-overlay-plane-update-sf-dmg-area)
[154.037364] Starting subtest: pr-overlay-plane-update-sf-dmg-area
[154.042073] Subtest pr-overlay-plane-update-sf-dmg-area: SKIP (0.003s)
[154.881852] [023/130] (875s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[154.979984] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[154.982194] Starting dynamic subtest: pipe-A-eDP-1
[155.031523] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.051s)
[155.040874] Starting dynamic subtest: pipe-B-eDP-1
[155.081133] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.048s)
[155.091413] Starting dynamic subtest: pipe-C-eDP-1
[155.130736] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.049s)
[155.140109] Starting dynamic subtest: pipe-D-eDP-1
[155.180902] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.049s)
[155.183240] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SUCCESS (0.201s)
[157.427435] [024/130] (873s left) kms_vrr (negative-basic)
[157.526054] Starting subtest: negative-basic
[157.532819] Starting dynamic subtest: pipe-A-eDP-1
[162.989237] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.453s)
[163.266428] Subtest negative-basic: SUCCESS (5.737s)
[164.052679] [025/130] (866s left) kms_big_fb (4-tiled-16bpp-rotate-270)
[164.164054] Starting subtest: 4-tiled-16bpp-rotate-270
[164.183288] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.019s)
[164.330592] [026/130] (866s left) perf_pmu (most-busy-idle-check-all)
[164.368028] Starting subtest: most-busy-idle-check-all
[164.370342] Starting dynamic subtest: rcs0
[165.962692] Dynamic subtest rcs0: SUCCESS (1.592s)
[165.974291] Starting dynamic subtest: bcs0
[167.947045] Dynamic subtest bcs0: SUCCESS (1.983s)
[167.958402] Starting dynamic subtest: vcs0
[169.933118] Dynamic subtest vcs0: SUCCESS (1.983s)
[169.945216] Starting dynamic subtest: vcs1
[171.916176] Dynamic subtest vcs1: SUCCESS (1.982s)
[171.928467] Starting dynamic subtest: vecs0
[173.901513] Dynamic subtest vecs0: SUCCESS (1.983s)
[173.912367] Starting dynamic subtest: ccs0
[175.947661] Dynamic subtest ccs0: SUCCESS (2.044s)
[175.950418] Subtest most-busy-idle-check-all: SUCCESS (11.578s)
[176.058514] [027/130] (854s left) kms_pm_backlight (brightness-with-dpms)
[176.162234] Starting subtest: brightness-with-dpms
[176.227500] Starting dynamic subtest: eDP-1
[178.741769] Dynamic subtest eDP-1: SUCCESS (2.513s)
[178.744400] Subtest brightness-with-dpms: SUCCESS (2.579s)
[179.597026] [028/130] (851s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-submi
[179.653712] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-submitted
[179.758948] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-submitted: SUCCESS (0.105s)
[179.837242] [029/130] (851s left) gem_pxp (verify-pxp-key-change-after-suspend-resume)
[179.967089] Starting subtest: verify-pxp-key-change-after-suspend-resume
[187.042980] Subtest verify-pxp-key-change-after-suspend-resume: SUCCESS (7.078s)
[187.179979] [030/130] (843s left) kms_pipe_crc_basic (suspend-read-crc)
[187.297058] Starting subtest: suspend-read-crc
[187.299399] Starting dynamic subtest: pipe-A-eDP-1
[204.951592] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.308s)
[204.964759] Starting dynamic subtest: pipe-B-eDP-1
[222.957739] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.889s)
[222.967829] Starting dynamic subtest: pipe-C-eDP-1
[240.882316] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.675s)
[240.892435] Starting dynamic subtest: pipe-D-eDP-1
[258.905367] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.756s)
[258.907878] Subtest suspend-read-crc: SUCCESS (10.633s)
[259.740021] [031/130] (771s left) sw_sync (alloc_merge_fence)
[259.780757] Starting subtest: alloc_merge_fence
[259.782676] Subtest alloc_merge_fence: SUCCESS (0.000s)
[259.839444] [032/130] (771s left) gem_exec_balancer (individual)
[259.900851] Starting subtest: individual
[261.966950] Subtest individual: SUCCESS (2.064s)
[262.308451] [033/130] (768s left) gem_create (hog-create)
[262.372805] Starting subtest: hog-create
[262.375185] Starting dynamic subtest: smem0
[294.912156] Dynamic subtest smem0: SUCCESS (32.537s)
[294.914731] Subtest hog-create: SUCCESS (32.538s)
[295.098766] [034/130] (736s left) gem_exec_reloc (basic-cpu-wc)
[295.153960] Subtest basic-cpu-wc: SKIP (0.000s)
[295.238592] [035/130] (736s left) gem_ctx_sseu (invalid-args)
[295.301448] Subtest invalid-args: SKIP (0.000s)
[295.366423] [036/130] (735s left) kms_flip (flip-vs-fences-interruptible)
[295.462210] Starting subtest: flip-vs-fences-interruptible
[295.466803] Subtest flip-vs-fences-interruptible: SKIP (0.000s)
[295.579781] [037/130] (735s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite)
[295.677800] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite
[295.681944] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[295.788263] [038/130] (735s left) kms_prime (basic-crc-hybrid)
[295.872286] Subtest basic-crc-hybrid: SKIP (0.000s)
[295.955580] [039/130] (735s left) gem_softpin (allocator-fork)
[296.013915] Starting subtest: allocator-fork
[298.226711] Subtest allocator-fork: SUCCESS (2.210s)
[298.363700] [040/130] (733s left) sw_sync (sync_multi_producer_single_consumer)
[298.396323] Starting subtest: sync_multi_producer_single_consumer
[299.472293] Subtest sync_multi_producer_single_consumer: SUCCESS (1.075s)
[299.537622] [041/130] (731s left) core_auth (getclient-simple)
[299.572257] Starting subtest: getclient-simple
[299.597040] Subtest getclient-simple: SUCCESS (0.027s)
[299.714851] [042/130] (731s left) gem_ctx_create (basic)
[299.774360] Starting subtest: basic
[299.776802] Subtest basic: SUCCESS (0.000s)
[299.960163] [043/130] (731s left) gem_exec_reloc (basic-softpin)
[300.026919] Subtest basic-softpin: SKIP (0.000s)
[300.118502] [044/130] (731s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[300.222756] Starting subtest: psr-1p-primscrn-spr-indfb-draw-render
[306.919277] Subtest psr-1p-primscrn-spr-indfb-draw-render: SUCCESS (6.697s)
[307.086333] [045/130] (724s left) i915_drm_fdinfo (idle)
[307.129875] Starting subtest: idle
[307.132072] Starting dynamic subtest: rcs0
[307.704146] Dynamic subtest rcs0: SUCCESS (0.575s)
[307.716510] Starting dynamic subtest: bcs0
[308.211241] Dynamic subtest bcs0: SUCCESS (0.504s)
[308.223820] Starting dynamic subtest: vcs0
[308.716963] Dynamic subtest vcs0: SUCCESS (0.503s)
[308.729083] Starting dynamic subtest: vcs1
[309.222298] Dynamic subtest vcs1: SUCCESS (0.503s)
[309.234881] Starting dynamic subtest: vecs0
[309.732214] Dynamic subtest vecs0: SUCCESS (0.503s)
[309.743787] Starting dynamic subtest: ccs0
[310.234218] Dynamic subtest ccs0: SUCCESS (0.503s)
[310.237227] Subtest idle: SUCCESS (3.104s)
[310.311306] [046/130] (721s left) gem_exec_schedule (timeslicing)
[310.375647] Starting subtest: timeslicing
[310.377600] Starting dynamic subtest: rcs0
[310.389815] Dynamic subtest rcs0: SUCCESS (0.023s)
[310.400280] Starting dynamic subtest: bcs0
[310.412939] Dynamic subtest bcs0: SUCCESS (0.022s)
[310.422350] Starting dynamic subtest: vcs0
[310.436484] Dynamic subtest vcs0: SUCCESS (0.023s)
[310.446976] Starting dynamic subtest: vcs1
[310.459069] Dynamic subtest vcs1: SUCCESS (0.021s)
[310.471138] Starting dynamic subtest: vecs0
[310.479624] Dynamic subtest vecs0: SUCCESS (0.021s)
[310.488710] Starting dynamic subtest: ccs0
[310.503150] Dynamic subtest ccs0: SUCCESS (0.023s)
[310.505719] Subtest timeslicing: SUCCESS (0.137s)
[311.970709] [047/130] (719s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[312.080304] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-fullscreen
[312.084399] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[312.187415] [048/130] (719s left) kms_invalid_mode (zero-vdisplay)
[312.277218] Starting subtest: zero-vdisplay
[312.279514] Starting dynamic subtest: pipe-A-eDP-1
[312.561390] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.282s)
[312.571274] Starting dynamic subtest: pipe-B-eDP-1
[312.573256] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[312.582457] Starting dynamic subtest: pipe-C-eDP-1
[312.584779] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[312.593793] Starting dynamic subtest: pipe-D-eDP-1
[312.596102] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[312.598376] Subtest zero-vdisplay: SUCCESS (0.287s)
[313.347461] [049/130] (718s left) kms_dsc (dsc-with-bpc)
[313.444562] Starting subtest: dsc-with-bpc
[313.477095] Subtest dsc-with-bpc: SKIP (0.001s)
[313.520928] [050/130] (718s left) kms_ccs (crc-primary-suspend-y-tiled-gen12-rc-ccs-cc)
[313.620335] Starting subtest: crc-primary-suspend-y-tiled-gen12-rc-ccs-cc
[313.622627] Starting dynamic subtest: pipe-A-eDP-1
[313.631597] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[313.642220] Starting dynamic subtest: pipe-B-eDP-1
[313.650953] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[313.660457] Starting dynamic subtest: pipe-C-eDP-1
[313.668876] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[313.677856] Starting dynamic subtest: pipe-D-eDP-1
[313.686125] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[313.690301] Subtest crc-primary-suspend-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[315.795779] [051/130] (716s left) kms_psr (psr2-sprite-mmap-cpu)
[315.886392] Starting subtest: psr2-sprite-mmap-cpu
[315.888707] Starting dynamic subtest: eDP-1
[317.574562] Dynamic subtest eDP-1: SUCCESS (1.687s)
[317.576478] Subtest psr2-sprite-mmap-cpu: SUCCESS (1.688s)
[318.460395] [052/130] (713s left) kms_psr (pr-primary-blt)
[318.554839] Starting subtest: pr-primary-blt
[318.559423] Subtest pr-primary-blt: SKIP (0.000s)
[318.625751] [053/130] (713s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[318.729900] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[319.620604] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SUCCESS (0.892s)
[319.860333] [054/130] (712s left) kms_big_fb (y-tiled-32bpp-rotate-90)
[319.955623] Starting subtest: y-tiled-32bpp-rotate-90
[319.959964] Subtest y-tiled-32bpp-rotate-90: SKIP (0.000s)
[320.058975] [055/130] (712s left) gem_mmap_gtt (ptrace)
[320.118544] Subtest ptrace: SKIP (0.000s)
[320.188978] [056/130] (711s left) perf (invalid-create-userspace-config)
[320.303087] Starting subtest: invalid-create-userspace-config
[320.305339] Subtest invalid-create-userspace-config: SUCCESS (0.001s)
[320.344002] [057/130] (711s left) kms_cursor_crc (cursor-rapid-movement-32x32)
[320.492995] Starting subtest: cursor-rapid-movement-32x32
[320.537867] Subtest cursor-rapid-movement-32x32: SKIP (0.040s)
[320.819206] [058/130] (711s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-plflip-blt)
[320.923888] Starting subtest: fbcpsr-1p-primscrn-shrfb-plflip-blt
[328.378399] Subtest fbcpsr-1p-primscrn-shrfb-plflip-blt: SUCCESS (7.455s)
[328.525280] [059/130] (703s left) kms_cursor_legacy (single-bo)
[328.653872] Starting subtest: single-bo
[328.655793] Starting dynamic subtest: pipe-A
[334.035895] Dynamic subtest pipe-A: SUCCESS (5.379s)
[334.046362] Starting dynamic subtest: pipe-B
[339.420015] Dynamic subtest pipe-B: SUCCESS (5.381s)
[339.431593] Starting dynamic subtest: pipe-C
[344.802199] Dynamic subtest pipe-C: SUCCESS (5.379s)
[344.813400] Starting dynamic subtest: pipe-D
[350.184551] Dynamic subtest pipe-D: SUCCESS (5.380s)
[350.196815] Starting dynamic subtest: all-pipes
[355.573090] Dynamic subtest all-pipes: SUCCESS (5.386s)
[355.575546] Subtest single-bo: SUCCESS (26.917s)
[355.718081] [060/130] (676s left) kms_getfb (getfb-handle-zero)
[355.826567] Starting subtest: getfb-handle-zero
[355.828913] Subtest getfb-handle-zero: SUCCESS (0.000s)
[355.935923] [061/130] (676s left) kms_setmode (invalid-clone-exclusive-crtc)
[355.997915] Starting subtest: invalid-clone-exclusive-crtc
[356.004969] Subtest invalid-clone-exclusive-crtc: SKIP (0.001s)
[356.108311] [062/130] (676s left) syncobj_wait (single-wait-all-for-submit-submitted)
[356.164382] Starting subtest: single-wait-all-for-submit-submitted
[356.269439] Subtest single-wait-all-for-submit-submitted: SUCCESS (0.104s)
[356.346813] [063/130] (676s left) i915_suspend (fence-restore-untiled)
[356.402906] Starting subtest: fence-restore-untiled
[356.407614] Subtest fence-restore-untiled: SKIP (0.000s)
[356.518000] [064/130] (675s left) kms_fbcon_fbt (fbc-suspend)
[356.572652] Starting subtest: fbc-suspend
[404.416135] Subtest fbc-suspend: SUCCESS (18.595s)
[405.505386] [065/130] (627s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[405.906236] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[406.734587] [066/130] (625s left) vgem_basic (sysfs)
[406.783493] Starting subtest: sysfs
[406.786234] Subtest sysfs: SUCCESS (0.001s)
[406.839794] [067/130] (625s left) perf_pmu (all-busy-idle-check-all)
[406.882000] Starting subtest: all-busy-idle-check-all
[407.601628] Subtest all-busy-idle-check-all: FAIL (0.648s)
[408.250935] [068/130] (624s left) kms_rotation_crc (primary-y-tiled-reflect-x-270)
[408.345111] Starting subtest: primary-y-tiled-reflect-x-270
[408.349892] Subtest primary-y-tiled-reflect-x-270: SKIP (0.001s)
[408.419031] [069/130] (624s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[408.521013] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[408.533923] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.012s)
[408.612463] [070/130] (624s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[408.704106] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[408.706504] Starting dynamic subtest: pipe-A-default-mode
[408.992353] Dynamic subtest pipe-A-default-mode: SKIP (0.286s)
[408.997861] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.288s)
[409.777052] [071/130] (622s left) kms_ccs (random-ccs-data-y-tiled-ccs)
[409.877213] Starting subtest: random-ccs-data-y-tiled-ccs
[409.880060] Starting dynamic subtest: pipe-A-eDP-1
[409.908608] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[409.919689] Starting dynamic subtest: pipe-B-eDP-1
[409.949375] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[409.960226] Starting dynamic subtest: pipe-C-eDP-1
[409.987252] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[409.996927] Starting dynamic subtest: pipe-D-eDP-1
[410.026111] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[410.030536] Subtest random-ccs-data-y-tiled-ccs: SKIP (0.004s)
[412.033708] [072/130] (620s left) kms_flip (2x-blocking-absolute-wf_vblank-interruptible)
[412.142850] Starting subtest: 2x-blocking-absolute-wf_vblank-interruptible
[412.153374] Subtest 2x-blocking-absolute-wf_vblank-interruptible: SKIP (0.010s)
[412.267653] [073/130] (620s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[412.333488] Starting subtest: yf-tiled-to-vebox-yf-tiled
[412.338919] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[412.515976] [074/130] (620s left) gem_exec_create (forked)
[412.581189] Starting subtest: forked
[412.583658] Starting dynamic subtest: smem
[434.093902] Dynamic subtest smem: SUCCESS (21.504s)
[434.096827] Subtest forked: SUCCESS (21.506s)
[434.280764] [075/130] (598s left) gem_lmem_swapping (parallel-random-verify-ccs)
[434.330449] Subtest parallel-random-verify-ccs: SKIP (0.000s)
[434.376270] [076/130] (598s left) gem_partial_pwrite_pread (reads-display)
[434.437947] Subtest reads-display: SKIP (0.000s)
[434.505938] [077/130] (598s left) kms_content_protection (lic-type-0)
[434.687259] Starting subtest: lic-type-0
[438.070689] Subtest lic-type-0: SKIP (3.381s)
[439.241260] [078/130] (593s left) gen9_exec_parse (bb-start-far)
[439.300261] Subtest bb-start-far: SKIP (0.000s)
[439.380069] [079/130] (593s left) gem_create (create-ext-cpu-access-big)
[439.557128] Starting subtest: create-ext-cpu-access-big
[439.563023] Subtest create-ext-cpu-access-big: SKIP (0.001s)
[439.634978] [080/130] (593s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[439.759023] Starting subtest: cursorB-vs-flipA-toggle
[439.763831] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s)
[439.902881] [081/130] (593s left) kms_fb_coherency (memset-crc)
[440.002034] Starting subtest: memset-crc
[440.004444] Starting dynamic subtest: mmap-offset-wc
[440.387840] Dynamic subtest mmap-offset-wc: SUCCESS (0.388s)
[440.689328] Starting dynamic subtest: mmap-offset-uc
[446.784864] Dynamic subtest mmap-offset-uc: SUCCESS (6.105s)
[447.068379] Subtest memset-crc: SUCCESS (7.068s)
[447.889457] [082/130] (585s left) gem_exec_gttfill (engines)
[447.959464] Starting subtest: engines
[447.962346] Starting dynamic subtest: rcs0
[472.558670] Dynamic subtest rcs0: SUCCESS (24.594s)
[472.569809] Starting dynamic subtest: bcs0
[497.933718] Dynamic subtest bcs0: SUCCESS (25.369s)
[497.946107] Starting dynamic subtest: vcs0
[523.196683] Dynamic subtest vcs0: SUCCESS (25.264s)
[523.210097] Starting dynamic subtest: vcs1
[548.380843] Dynamic subtest vcs1: SUCCESS (25.179s)
[548.393967] Starting dynamic subtest: vecs0
[573.533092] Dynamic subtest vecs0: SUCCESS (25.148s)
[573.545641] Starting dynamic subtest: ccs0
[598.689257] Dynamic subtest ccs0: SUCCESS (25.151s)
[598.692437] Subtest engines: SUCCESS (150.714s)
[598.877324] [083/130] (434s left) gem_mmap_gtt (basic)
[598.975616] Subtest basic: SKIP (0.000s)
[599.042737] [084/130] (434s left) kms_cursor_crc (cursor-onscreen-128x42)
[599.284315] Starting subtest: cursor-onscreen-128x42
[599.328971] Subtest cursor-onscreen-128x42: SKIP (0.039s)
[599.556789] [085/130] (433s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[599.671218] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt
[599.676083] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[599.781874] [086/130] (433s left) kms_pm_dc (dc5-psr)
[600.890117] Starting subtest: dc5-psr
[603.779498] Subtest dc5-psr: SUCCESS (2.887s)
[604.603285] [087/130] (428s left) core_getstats
[604.763983] [088/130] (428s left) i915_pm_freq_api (freq-reset-multiple)
[604.819672] Starting subtest: freq-reset-multiple
[604.822613] Starting dynamic subtest: gt0
[614.566225] Dynamic subtest gt0: SUCCESS (9.744s)
[614.577275] Starting dynamic subtest: gt1
[623.005269] Dynamic subtest gt1: SUCCESS (8.432s)
[623.008445] Subtest freq-reset-multiple: SUCCESS (18.178s)
[623.083557] [089/130] (410s left) gem_ctx_persistence (engines-hang)
[623.145817] Starting subtest: engines-hang
[623.148677] Starting dynamic subtest: rcs0
[623.242931] Dynamic subtest rcs0: SUCCESS (0.096s)
[623.257102] Starting dynamic subtest: bcs0
[623.372840] Dynamic subtest bcs0: SUCCESS (0.128s)
[623.395194] Starting dynamic subtest: vcs0
[623.492815] Dynamic subtest vcs0: SUCCESS (0.118s)
[623.505265] Starting dynamic subtest: vcs1
[623.610676] Dynamic subtest vcs1: SUCCESS (0.116s)
[623.623017] Starting dynamic subtest: vecs0
[623.715738] Dynamic subtest vecs0: SUCCESS (0.103s)
[623.729494] Starting dynamic subtest: ccs0
[623.794178] Dynamic subtest ccs0: SUCCESS (0.077s)
[623.796555] Subtest engines-hang: SUCCESS (0.649s)
[623.944522] [090/130] (409s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[624.063782] Starting subtest: cursor-vs-flip-legacy
[653.476315] Subtest cursor-vs-flip-legacy: SUCCESS (29.412s)
[654.328515] [091/130] (379s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[654.431141] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[654.435432] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[654.537191] [092/130] (379s left) kms_panel_fitting (atomic-fastset)
[654.635913] Starting subtest: atomic-fastset
[654.637911] Starting dynamic subtest: pipe-A-eDP-1
[654.797757] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.163s)
[654.809455] Starting dynamic subtest: pipe-B-eDP-1
[656.088095] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.288s)
[656.100451] Starting dynamic subtest: pipe-C-eDP-1
[657.264339] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.174s)
[657.275135] Starting dynamic subtest: pipe-D-eDP-1
[658.394946] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.131s)
[658.397281] Subtest atomic-fastset: SUCCESS (3.762s)
[659.632985] [093/130] (374s left) gem_ctx_param (invalid-param-set)
[659.692898] Starting subtest: invalid-param-set
[659.695756] Subtest invalid-param-set: SUCCESS (0.000s)
[659.766289] [094/130] (373s left) kms_big_fb (yf-tiled-8bpp-rotate-0)
[659.876169] Starting subtest: yf-tiled-8bpp-rotate-0
[659.881123] Subtest yf-tiled-8bpp-rotate-0: SKIP (0.000s)
[659.979859] [095/130] (373s left) api_intel_bb (crc32)
[660.044385] Starting subtest: crc32
[660.046999] Starting dynamic subtest: rcs0-smem0
[660.343081] Dynamic subtest rcs0-smem0: SUCCESS (0.300s)
[660.357092] Starting dynamic subtest: bcs0-smem0
[660.646829] Dynamic subtest bcs0-smem0: SUCCESS (0.301s)
[660.661143] Starting dynamic subtest: vcs0-smem0
[660.977801] Dynamic subtest vcs0-smem0: SUCCESS (0.329s)
[660.991212] Starting dynamic subtest: vcs1-smem0
[661.296427] Dynamic subtest vcs1-smem0: SUCCESS (0.316s)
[661.310258] Starting dynamic subtest: vecs0-smem0
[661.621048] Dynamic subtest vecs0-smem0: SUCCESS (0.323s)
[661.634483] Starting dynamic subtest: ccs0-smem0
[661.939075] Dynamic subtest ccs0-smem0: SUCCESS (0.316s)
[661.941984] Subtest crc32: SUCCESS (1.897s)
[662.073378] [096/130] (371s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-wc)
[662.168027] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-wc
[665.801174] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (3.638s)
[667.015060] [097/130] (366s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-onoff)
[667.112419] Starting subtest: fbc-2p-scndscrn-cur-indfb-onoff
[667.117255] Subtest fbc-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[667.206850] [098/130] (366s left) syncobj_timeline (single-wait-all-available-signaled)
[667.270429] Starting subtest: single-wait-all-available-signaled
[667.273301] Subtest single-wait-all-available-signaled: SUCCESS (0.000s)
[667.393831] [099/130] (366s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-render)
[667.504979] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-render
[667.509706] Subtest psr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[667.631826] [100/130] (366s left) perf (global-sseu-config-invalid)
[667.733219] Starting subtest: global-sseu-config-invalid
[667.738785] Subtest global-sseu-config-invalid: SKIP (0.000s)
[667.786856] [101/130] (366s left) gem_sync (basic-many-each)
[667.865471] Starting subtest: basic-many-each
[670.159597] Subtest basic-many-each: SUCCESS (2.305s)
[670.346196] [102/130] (363s left) kms_psr (fbc-pr-dpms)
[670.450295] Starting subtest: fbc-pr-dpms
[670.456154] Subtest fbc-pr-dpms: SKIP (0.000s)
[670.522958] [103/130] (363s left) gem_userptr_blits (map-fixed-invalidate-busy)
[670.595125] Starting subtest: map-fixed-invalidate-busy
[670.600099] Subtest map-fixed-invalidate-busy: SKIP (0.000s)
[670.740705] [104/130] (363s left) kms_chamelium_color (ctm-green-to-red)
[670.840269] Subtest ctm-green-to-red: SKIP (0.000s)
[670.888768] [105/130] (363s left) gem_softpin (evict-snoop)
[670.951348] Starting subtest: evict-snoop
[670.956741] Subtest evict-snoop: SKIP (0.000s)
[671.088364] [106/130] (363s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-render)
[671.191153] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-render
[678.466465] Subtest psr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.280s)
[678.595216] [107/130] (355s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[678.694044] Starting subtest: x-tiled-64bpp-rotate-90
[678.713696] Subtest x-tiled-64bpp-rotate-90: SKIP (0.019s)
[678.840754] [108/130] (355s left) gem_exec_flush (basic-wb-ro-default)
[678.914281] Starting subtest: basic-wb-ro-default
[684.367484] Subtest basic-wb-ro-default: SUCCESS (5.455s)
[684.555626] [109/130] (349s left) gem_exec_whisper (basic-fds-all)
[684.629734] Starting subtest: basic-fds-all
[696.145667] Subtest basic-fds-all: SUCCESS (11.513s)
[696.329542] [110/130] (338s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-downscaling)
[696.437204] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-downscaling
[696.440110] Starting dynamic subtest: pipe-A-default-mode
[696.727245] Dynamic subtest pipe-A-default-mode: SKIP (0.286s)
[696.733154] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-downscaling: SKIP (0.287s)
[697.522551] [111/130] (336s left) gem_render_copy (y-tiled)
[697.586874] Starting subtest: y-tiled
[697.592321] Subtest y-tiled: SKIP (0.000s)
[697.770851] [112/130] (336s left) kms_psr (psr2-primary-page-flip)
[697.867147] Starting subtest: psr2-primary-page-flip
[697.869629] Starting dynamic subtest: eDP-1
[699.591545] Dynamic subtest eDP-1: SUCCESS (1.725s)
[699.594027] Subtest psr2-primary-page-flip: SUCCESS (1.725s)
[700.470581] [113/130] (334s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[700.532093] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[700.534383] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.000s)
[700.662046] [114/130] (333s left) syncobj_timeline (single-wait-all-for-submit-signaled)
[700.717290] Starting subtest: single-wait-all-for-submit-signaled
[700.719752] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[700.834837] [115/130] (333s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-move)
[700.939754] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-move
[708.236255] Subtest fbcpsr-1p-primscrn-cur-indfb-move: SUCCESS (7.299s)
[708.398969] [116/130] (326s left) gem_exec_params (dr1-dirt)
[708.452743] Starting subtest: DR1-dirt
[708.455121] Subtest DR1-dirt: SUCCESS (0.000s)
[708.554772] [117/130] (326s left) kms_plane_lowres (tiling-4)
[708.643731] Starting subtest: tiling-4
[708.646185] Starting dynamic subtest: pipe-A-eDP-1
[708.651062] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[708.943054] Starting dynamic subtest: pipe-B-eDP-1
[708.947949] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[708.961209] Starting dynamic subtest: pipe-C-eDP-1
[708.966029] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[708.976275] Starting dynamic subtest: pipe-D-eDP-1
[708.981080] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[708.986086] Subtest tiling-4: SKIP (0.292s)
[709.793576] [118/130] (324s left) gem_spin_batch (legacy-resubmit)
[709.865289] Starting subtest: legacy-resubmit
[709.868180] Starting dynamic subtest: default
[709.874064] Dynamic subtest default: SUCCESS (0.006s)
[709.886391] Starting dynamic subtest: render
[709.889106] Dynamic subtest render: SUCCESS (0.001s)
[709.899155] Starting dynamic subtest: bsd
[709.901348] Dynamic subtest bsd: SUCCESS (0.002s)
[709.911148] Starting dynamic subtest: bsd1
[709.913474] Dynamic subtest bsd1: SUCCESS (0.001s)
[709.923503] Starting dynamic subtest: bsd2
[709.925607] Dynamic subtest bsd2: SUCCESS (0.003s)
[709.935917] Starting dynamic subtest: blt
[709.937976] Dynamic subtest blt: SUCCESS (0.002s)
[709.948281] Starting dynamic subtest: vebox
[709.950704] Dynamic subtest vebox: SUCCESS (0.002s)
[709.952693] Subtest legacy-resubmit: SUCCESS (0.022s)
[710.117790] [119/130] (324s left) kms_color (ctm-0-25)
[710.217464] Starting subtest: ctm-0-25
[710.220435] Starting dynamic subtest: pipe-A-eDP-1
[713.817944] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.599s)
[713.830754] Starting dynamic subtest: pipe-B-eDP-1
[718.087426] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.267s)
[718.098453] Starting dynamic subtest: pipe-C-eDP-1
[722.047000] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.957s)
[722.057744] Starting dynamic subtest: pipe-D-eDP-1
[726.121388] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.075s)
[726.124146] Subtest ctm-0-25: SUCCESS (15.901s)
[726.975698] [120/130] (307s left) gem_mmap_gtt (basic-write-read)
[727.034221] Subtest basic-write-read: SKIP (0.000s)
[727.103150] [121/130] (307s left) syncobj_timeline (invalid-single-wait-unsubmitted)
[727.155325] Starting subtest: invalid-single-wait-unsubmitted
[727.157760] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[727.255952] [122/130] (307s left) kms_psr (fbc-psr-cursor-mmap-cpu)
[727.356349] Starting subtest: fbc-psr-cursor-mmap-cpu
[727.358974] Starting dynamic subtest: eDP-1
[727.364826] Dynamic subtest eDP-1: SKIP (0.000s)
[727.370052] Subtest fbc-psr-cursor-mmap-cpu: SKIP (0.001s)
[727.427660] [123/130] (307s left) kms_selftest (drm_format_helper)
[727.473794] Starting subtest: drm_format_helper
[727.507516] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8
[727.656131] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8: SUCCESS (0.147s)
[727.669049] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332
[727.773122] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332: SUCCESS (0.118s)
[727.785476] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565
[727.911863] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565: SUCCESS (0.138s)
[727.934076] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555
[728.045193] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555: SUCCESS (0.131s)
[728.061072] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555
[728.158293] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555: SUCCESS (0.112s)
[728.169468] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551
[728.279147] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551: SUCCESS (0.121s)
[728.292496] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888
[728.405321] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888: SUCCESS (0.123s)
[728.417356] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888
[728.531503] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888: SUCCESS (0.126s)
[728.542692] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888
[728.665749] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888: SUCCESS (0.134s)
[728.675813] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010
[728.800210] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010: SUCCESS (0.134s)
[728.812057] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010
[728.912335] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010: SUCCESS (0.111s)
[728.922942] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_mono
[729.044634] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_mono: SUCCESS (0.130s)
[729.056462] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_swab
[729.183769] Dynamic subtest drm_format_helper_test-drm_test_fb_swab: SUCCESS (0.138s)
[729.194620] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888
[729.323711] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888: SUCCESS (0.140s)
[729.334222] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888
[729.444577] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888: SUCCESS (0.122s)
[729.455611] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_clip_offset
[729.572868] Dynamic subtest drm_format_helper_test-drm_test_fb_clip_offset: SUCCESS (0.125s)
[729.583184] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_memcpy
[729.701846] Dynamic subtest drm_format_helper_test-drm_test_fb_memcpy: SUCCESS (0.128s)
[729.704471] Subtest drm_format_helper: SUCCESS (2.229s)
[729.874774] [124/130] (305s left) gem_wait (invalid-buf)
[729.938284] Starting subtest: invalid-buf
[729.940896] Subtest invalid-buf: SUCCESS (0.000s)
[730.174838] [125/130] (304s left) kms_cursor_legacy (flip-vs-cursor-crc-legacy)
[730.297160] Starting subtest: flip-vs-cursor-crc-legacy
[730.966263] Subtest flip-vs-cursor-crc-legacy: SUCCESS (0.671s)
[731.799696] [126/130] (303s left) kms_getfb (getfb2-into-addfb2)
[731.897164] Starting subtest: getfb2-into-addfb2
[731.899597] Subtest getfb2-into-addfb2: SUCCESS (0.000s)
[732.016935] [127/130] (303s left) gem_mmap_offset (open-flood)
[732.077179] Starting subtest: open-flood
[753.565788] Subtest open-flood: SUCCESS (21.489s)
[753.677811] [128/130] (281s left) prime_vgem (fence-wait)
[753.744754] Starting subtest: fence-wait
[753.746461] Starting dynamic subtest: rcs0
[754.809133] Dynamic subtest rcs0: SUCCESS (1.063s)
[754.822488] Starting dynamic subtest: bcs0
[755.876340] Dynamic subtest bcs0: SUCCESS (1.066s)
[755.890481] Starting dynamic subtest: vcs0
[756.925060] Dynamic subtest vcs0: SUCCESS (1.047s)
[756.938827] Starting dynamic subtest: vcs1
[757.968467] Dynamic subtest vcs1: SUCCESS (1.043s)
[757.982611] Starting dynamic subtest: vecs0
[759.012384] Dynamic subtest vecs0: SUCCESS (1.042s)
[759.026251] Starting dynamic subtest: ccs0
[760.052461] Dynamic subtest ccs0: SUCCESS (1.039s)
[760.055237] Subtest fence-wait: SUCCESS (6.306s)
[760.202706] [129/130] (275s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[760.305021] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[760.310076] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[760.416589] [130/130] (274s left) gem_vm_create (isolation)
[760.470316] Starting subtest: isolation
[760.479046] Subtest isolation: SUCCESS (0.008s)
[760.610856] 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.