[56.088376] Initializing watchdogs
[56.088465]   /dev/watchdog0
[56.102865] [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]
[56.107174] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.109617] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.112807] [001/130] (960s left) kms_busy (basic)
[56.235083] Starting subtest: basic
[56.237355] Starting dynamic subtest: flip
[56.889033] Dynamic subtest flip: SUCCESS (0.664s)
[56.897143] Starting dynamic subtest: modeset
[60.226212] Dynamic subtest modeset: SUCCESS (3.388s)
[60.228638] Subtest basic: SUCCESS (4.054s)
[60.998169] [002/130] (955s left) gem_ctx_sseu (engines)
[61.053610] Subtest engines: SKIP (0.000s)
[61.121687] [003/130] (955s left) kms_ccs (crc-primary-basic-y-tiled-ccs)
[61.215916] Starting subtest: crc-primary-basic-y-tiled-ccs
[61.218307] Starting dynamic subtest: pipe-A-eDP-1
[61.241721] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[61.251302] Starting dynamic subtest: pipe-B-eDP-1
[61.272995] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[61.280637] Starting dynamic subtest: pipe-C-eDP-1
[61.308071] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[61.316804] Starting dynamic subtest: pipe-D-eDP-1
[61.338745] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[61.343414] Subtest crc-primary-basic-y-tiled-ccs: SKIP (0.004s)
[63.420149] [004/130] (953s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0)
[63.532476] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0
[64.604352] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.079s)
[64.791465] [005/130] (951s left) gem_exec_schedule (pi-shared-iova)
[64.973190] Starting subtest: pi-shared-iova
[64.975493] Starting dynamic subtest: rcs0
[65.053413] Dynamic subtest rcs0: SUCCESS (0.083s)
[65.065083] Starting dynamic subtest: vcs0
[65.111035] Dynamic subtest vcs0: SUCCESS (0.058s)
[65.121617] Starting dynamic subtest: vcs1
[65.167987] Dynamic subtest vcs1: SUCCESS (0.057s)
[65.178940] Starting dynamic subtest: vecs0
[65.224777] Dynamic subtest vecs0: SUCCESS (0.057s)
[65.234461] Starting dynamic subtest: ccs0
[65.280354] Dynamic subtest ccs0: SUCCESS (0.056s)
[65.282764] Subtest pi-shared-iova: SUCCESS (0.312s)
[66.997221] [006/130] (949s left) kms_draw_crc (fill-fb)
[67.091305] Starting subtest: fill-fb
[67.547586] Subtest fill-fb: SUCCESS (0.461s)
[67.649214] [007/130] (949s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-mmap-wc)
[67.755152] Starting subtest: psr-1p-offscren-pri-shrfb-draw-mmap-wc
[74.348446] Subtest psr-1p-offscren-pri-shrfb-draw-mmap-wc: SUCCESS (6.615s)
[74.484211] [008/130] (942s left) kms_plane_scaling (planes-downscale-factor-0-75-unity-scaling)
[74.581082] Starting subtest: planes-downscale-factor-0-75-unity-scaling
[74.583563] Starting dynamic subtest: pipe-A
[74.664388] Dynamic subtest pipe-A: SUCCESS (0.083s)
[74.674320] Starting dynamic subtest: pipe-B
[75.792968] Dynamic subtest pipe-B: SUCCESS (1.130s)
[75.802698] Starting dynamic subtest: pipe-C
[76.889513] Dynamic subtest pipe-C: SUCCESS (1.098s)
[76.899107] Starting dynamic subtest: pipe-D
[78.008833] Dynamic subtest pipe-D: SUCCESS (1.121s)
[78.011123] Subtest planes-downscale-factor-0-75-unity-scaling: SUCCESS (3.433s)
[79.157555] [009/130] (937s left) gem_userptr_blits (sync-unmap-cycles)
[79.221501] Starting subtest: sync-unmap-cycles
[84.588909] Subtest sync-unmap-cycles: SUCCESS (5.374s)
[84.736246] [010/130] (932s left) gem_userptr_blits (dmabuf-unsync)
[84.798344] Subtest dmabuf-unsync: SKIP (0.000s)
[84.929740] [011/130] (932s left) kms_sequence (queue-busy)
[85.024631] Starting subtest: queue-busy
[85.026676] Starting dynamic subtest: pipe-A-eDP-1
[86.959504] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.932s)
[86.968856] Starting dynamic subtest: pipe-B-eDP-1
[89.541501] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.580s)
[89.550725] Starting dynamic subtest: pipe-C-eDP-1
[92.142620] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.597s)
[92.152192] Starting dynamic subtest: pipe-D-eDP-1
[94.671881] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.531s)
[94.673724] Subtest queue-busy: SUCCESS (9.643s)
[95.454194] [012/130] (921s left) fbdev (read)
[95.495034] Starting subtest: read
[96.165583] Subtest read: SUCCESS (0.669s)
[96.225812] [013/130] (920s left) kms_async_flips (basic-modeset-with-all-modifiers-formats)
[96.326123] Starting subtest: basic-modeset-with-all-modifiers-formats
[96.695401] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB8888
[97.423726] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB8888: SUCCESS (0.728s)
[97.432846] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB2101010
[97.447945] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB2101010: SUCCESS (0.023s)
[97.458906] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB16161616F
[97.468152] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB16161616F: SUCCESS (0.021s)
[97.478547] Starting dynamic subtest: pipe-A-eDP-1-4-RGB565
[97.668163] Dynamic subtest pipe-A-eDP-1-4-RGB565: SUCCESS (0.200s)
[97.675925] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB8888
[97.690333] Dynamic subtest pipe-A-eDP-1-4-XRGB8888: SUCCESS (0.022s)
[97.697977] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB2101010
[97.712527] Dynamic subtest pipe-A-eDP-1-4-XRGB2101010: SUCCESS (0.022s)
[97.720021] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB16161616F
[97.734831] Dynamic subtest pipe-A-eDP-1-4-XRGB16161616F: SUCCESS (0.022s)
[97.742423] Starting dynamic subtest: pipe-A-eDP-1-4-YUYV
[97.757122] Dynamic subtest pipe-A-eDP-1-4-YUYV: SUCCESS (0.022s)
[97.765515] Starting dynamic subtest: pipe-A-eDP-1-4-Y210
[97.779282] Dynamic subtest pipe-A-eDP-1-4-Y210: SUCCESS (0.022s)
[97.787828] Starting dynamic subtest: pipe-A-eDP-1-4-XYUV8888
[97.801456] Dynamic subtest pipe-A-eDP-1-4-XYUV8888: SUCCESS (0.022s)
[97.809177] Starting dynamic subtest: pipe-A-eDP-1-4-XV30
[97.823786] Dynamic subtest pipe-A-eDP-1-4-XV30: SUCCESS (0.022s)
[97.831493] Starting dynamic subtest: pipe-A-eDP-1-4-XV36
[97.847337] Dynamic subtest pipe-A-eDP-1-4-XV36: SUCCESS (0.022s)
[97.855098] Starting dynamic subtest: pipe-A-eDP-1-x-RGB565
[98.046163] Dynamic subtest pipe-A-eDP-1-x-RGB565: SUCCESS (0.200s)
[98.056949] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB8888
[98.068259] Dynamic subtest pipe-A-eDP-1-x-XRGB8888: SUCCESS (0.022s)
[98.076101] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB2101010
[98.090421] Dynamic subtest pipe-A-eDP-1-x-XRGB2101010: SUCCESS (0.022s)
[98.098276] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB16161616F
[98.112682] Dynamic subtest pipe-A-eDP-1-x-XRGB16161616F: SUCCESS (0.022s)
[98.120272] Starting dynamic subtest: pipe-A-eDP-1-x-YUYV
[98.134592] Dynamic subtest pipe-A-eDP-1-x-YUYV: SUCCESS (0.022s)
[98.141964] Starting dynamic subtest: pipe-A-eDP-1-x-Y210
[98.157111] Dynamic subtest pipe-A-eDP-1-x-Y210: SUCCESS (0.022s)
[98.164243] Starting dynamic subtest: pipe-A-eDP-1-x-XYUV8888
[98.179322] Dynamic subtest pipe-A-eDP-1-x-XYUV8888: SUCCESS (0.022s)
[98.186749] Starting dynamic subtest: pipe-A-eDP-1-x-XV30
[98.201555] Dynamic subtest pipe-A-eDP-1-x-XV30: SUCCESS (0.022s)
[98.208901] Starting dynamic subtest: pipe-A-eDP-1-x-XV36
[98.223861] Dynamic subtest pipe-A-eDP-1-x-XV36: SUCCESS (0.022s)
[98.231317] Starting dynamic subtest: pipe-A-eDP-1-linear-RGB565
[98.446029] Dynamic subtest pipe-A-eDP-1-linear-RGB565: SUCCESS (0.222s)
[98.453870] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB8888
[98.468245] Dynamic subtest pipe-A-eDP-1-linear-XRGB8888: SUCCESS (0.022s)
[98.475502] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB2101010
[98.490544] Dynamic subtest pipe-A-eDP-1-linear-XRGB2101010: SUCCESS (0.022s)
[98.497748] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB16161616F
[98.512822] Dynamic subtest pipe-A-eDP-1-linear-XRGB16161616F: SUCCESS (0.022s)
[98.520098] Starting dynamic subtest: pipe-A-eDP-1-linear-YUYV
[98.535247] Dynamic subtest pipe-A-eDP-1-linear-YUYV: SUCCESS (0.022s)
[98.544573] Starting dynamic subtest: pipe-A-eDP-1-linear-Y210
[98.557098] Dynamic subtest pipe-A-eDP-1-linear-Y210: SUCCESS (0.022s)
[98.564917] Starting dynamic subtest: pipe-A-eDP-1-linear-XYUV8888
[98.579404] Dynamic subtest pipe-A-eDP-1-linear-XYUV8888: SUCCESS (0.022s)
[98.586578] Starting dynamic subtest: pipe-A-eDP-1-linear-XV30
[98.603939] Dynamic subtest pipe-A-eDP-1-linear-XV30: SUCCESS (0.022s)
[98.613613] Starting dynamic subtest: pipe-A-eDP-1-linear-XV36
[98.623897] Dynamic subtest pipe-A-eDP-1-linear-XV36: SUCCESS (0.021s)
[99.015713] Subtest basic-modeset-with-all-modifiers-formats: SUCCESS (2.691s)
[99.820909] [014/130] (917s left) kms_lease (lease-unleased-crtc)
[99.911945] Starting subtest: lease-unleased-crtc
[99.914118] Starting dynamic subtest: pipe-A-eDP-1
[100.204366] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.290s)
[100.214064] Starting dynamic subtest: pipe-B-eDP-1
[100.950280] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.745s)
[100.960994] Starting dynamic subtest: pipe-C-eDP-1
[100.963277] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.004s)
[100.970488] Starting dynamic subtest: pipe-D-eDP-1
[100.972478] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[100.974404] Subtest lease-unleased-crtc: SUCCESS (1.048s)
[101.299258] [015/130] (915s left) i915_pm_rpm (system-suspend)
[103.147944] Starting subtest: system-suspend
[121.492256] Subtest system-suspend: SUCCESS (3.010s)
[121.843527] [016/130] (895s left) kms_ccs (bad-aux-stride-y-tiled-ccs)
[121.942054] Starting subtest: bad-aux-stride-y-tiled-ccs
[121.955957] Starting dynamic subtest: pipe-A-eDP-1
[121.977843] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[121.987421] Starting dynamic subtest: pipe-B-eDP-1
[122.007266] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[122.014937] Starting dynamic subtest: pipe-C-eDP-1
[122.035436] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[122.043118] Starting dynamic subtest: pipe-D-eDP-1
[122.064353] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[122.068574] Subtest bad-aux-stride-y-tiled-ccs: SKIP (0.003s)
[124.118166] [017/130] (893s left) kms_flip (2x-plain-flip-interruptible)
[124.226763] Starting subtest: 2x-plain-flip-interruptible
[124.238055] Subtest 2x-plain-flip-interruptible: SKIP (0.011s)
[124.355860] [018/130] (892s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[124.450711] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[124.454817] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[124.544805] [019/130] (892s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-sf)
[129.476088] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-sf
[129.478240] Starting dynamic subtest: pipe-A-eDP-1
[130.261048] Dynamic subtest pipe-A-eDP-1: SKIP (0.792s)
[130.272483] Starting dynamic subtest: pipe-B-eDP-1
[131.377479] Dynamic subtest pipe-B-eDP-1: SKIP (1.128s)
[131.382173] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-sf: SKIP (1.924s)
[132.543286] [020/130] (884s left) gem_exec_fence (invalid-fence-array)
[133.004424] Starting subtest: invalid-fence-array
[133.006840] Subtest invalid-fence-array: SUCCESS (0.002s)
[134.269797] [FACT gem_exec_fence (invalid-fence-array)] new: kernel.kmod_is_loaded.vgem: true
[134.272974] [021/130] (883s left) gem_exec_schedule (pi-distinct-iova)
[134.462609] Starting subtest: pi-distinct-iova
[134.464970] Starting dynamic subtest: rcs0
[134.537832] Dynamic subtest rcs0: SUCCESS (0.078s)
[134.551306] Starting dynamic subtest: vcs0
[134.592923] Dynamic subtest vcs0: SUCCESS (0.056s)
[134.601955] Starting dynamic subtest: vcs1
[134.648142] Dynamic subtest vcs1: SUCCESS (0.054s)
[134.660945] Starting dynamic subtest: vecs0
[134.697905] Dynamic subtest vecs0: SUCCESS (0.051s)
[134.710220] Starting dynamic subtest: ccs0
[134.756236] Dynamic subtest ccs0: SUCCESS (0.058s)
[134.758833] Subtest pi-distinct-iova: SUCCESS (0.298s)
[136.366030] [022/130] (881s left) kms_psr2_sf (pr-overlay-plane-update-sf-dmg-area)
[141.344877] Starting subtest: pr-overlay-plane-update-sf-dmg-area
[141.349660] Subtest pr-overlay-plane-update-sf-dmg-area: SKIP (0.002s)
[142.192142] [023/130] (875s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[142.283492] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[142.285860] Starting dynamic subtest: pipe-A-eDP-1
[142.334009] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.050s)
[142.344382] Starting dynamic subtest: pipe-B-eDP-1
[142.384638] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.050s)
[142.394038] Starting dynamic subtest: pipe-C-eDP-1
[142.435559] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.050s)
[142.444970] Starting dynamic subtest: pipe-D-eDP-1
[142.485595] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.049s)
[142.487860] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SUCCESS (0.203s)
[144.736340] [024/130] (872s left) kms_vrr (negative-basic)
[144.822284] Starting subtest: negative-basic
[144.829065] Starting dynamic subtest: pipe-A-eDP-1
[150.274154] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.453s)
[150.551708] Subtest negative-basic: SUCCESS (5.737s)
[151.351768] [025/130] (866s left) kms_big_fb (4-tiled-16bpp-rotate-270)
[151.464698] Starting subtest: 4-tiled-16bpp-rotate-270
[151.484441] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.019s)
[151.585467] [026/130] (865s left) perf_pmu (most-busy-idle-check-all)
[151.625575] Starting subtest: most-busy-idle-check-all
[151.627486] Starting dynamic subtest: rcs0
[153.287735] Dynamic subtest rcs0: SUCCESS (1.661s)
[153.300641] Starting dynamic subtest: bcs0
[155.272574] Dynamic subtest bcs0: SUCCESS (1.982s)
[155.284938] Starting dynamic subtest: vcs0
[157.255765] Dynamic subtest vcs0: SUCCESS (1.981s)
[157.268103] Starting dynamic subtest: vcs1
[159.240685] Dynamic subtest vcs1: SUCCESS (1.983s)
[159.251494] Starting dynamic subtest: vecs0
[161.226020] Dynamic subtest vecs0: SUCCESS (1.983s)
[161.238162] Starting dynamic subtest: ccs0
[163.272651] Dynamic subtest ccs0: SUCCESS (2.045s)
[163.275715] Subtest most-busy-idle-check-all: SUCCESS (11.645s)
[163.395238] [027/130] (854s left) kms_pm_backlight (brightness-with-dpms)
[163.495036] Starting subtest: brightness-with-dpms
[163.558256] Starting dynamic subtest: eDP-1
[165.982335] Dynamic subtest eDP-1: SUCCESS (2.423s)
[165.985171] Subtest brightness-with-dpms: SUCCESS (2.489s)
[166.861949] [028/130] (850s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-submi
[166.922384] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-submitted
[167.029249] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-submitted: SUCCESS (0.105s)
[167.103453] [029/130] (850s left) gem_pxp (verify-pxp-key-change-after-suspend-resume)
[167.221709] Starting subtest: verify-pxp-key-change-after-suspend-resume
[174.271286] Subtest verify-pxp-key-change-after-suspend-resume: SUCCESS (7.052s)
[174.397550] [030/130] (843s left) kms_pipe_crc_basic (suspend-read-crc)
[174.496085] Starting subtest: suspend-read-crc
[174.498401] Starting dynamic subtest: pipe-A-eDP-1
[192.276291] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.254s)
[192.291424] Starting dynamic subtest: pipe-B-eDP-1
[210.316430] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.968s)
[210.325999] Starting dynamic subtest: pipe-C-eDP-1
[228.236310] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.786s)
[228.246481] Starting dynamic subtest: pipe-D-eDP-1
[246.228549] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.739s)
[246.230863] Subtest suspend-read-crc: SUCCESS (10.752s)
[247.055230] [031/130] (770s left) sw_sync (alloc_merge_fence)
[247.119228] Starting subtest: alloc_merge_fence
[247.126753] Subtest alloc_merge_fence: SUCCESS (0.000s)
[247.192611] [032/130] (770s left) gem_exec_balancer (individual)
[247.267346] Starting subtest: individual
[248.543665] Subtest individual: SUCCESS (1.323s)
[248.885616] [033/130] (769s left) gem_create (hog-create)
[248.939527] Starting subtest: hog-create
[248.941758] Starting dynamic subtest: smem0
[282.540094] Dynamic subtest smem0: SUCCESS (33.766s)
[282.543106] Subtest hog-create: SUCCESS (33.767s)
[282.726593] [034/130] (735s left) gem_exec_reloc (basic-cpu-wc)
[282.792851] Subtest basic-cpu-wc: SKIP (0.000s)
[282.880867] [035/130] (735s left) gem_ctx_sseu (invalid-args)
[282.954797] Subtest invalid-args: SKIP (0.000s)
[283.044069] [036/130] (734s left) kms_flip (flip-vs-fences-interruptible)
[283.148070] Starting subtest: flip-vs-fences-interruptible
[283.152651] Subtest flip-vs-fences-interruptible: SKIP (0.000s)
[283.258870] [037/130] (734s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite)
[283.366121] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite
[283.370986] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[283.461187] [038/130] (734s left) kms_prime (basic-crc-hybrid)
[283.545146] Subtest basic-crc-hybrid: SKIP (0.000s)
[283.628326] [039/130] (734s left) gem_softpin (allocator-fork)
[283.673714] Starting subtest: allocator-fork
[285.885975] Subtest allocator-fork: SUCCESS (2.212s)
[286.008184] [040/130] (732s left) sw_sync (sync_multi_producer_single_consumer)
[286.038492] Starting subtest: sync_multi_producer_single_consumer
[287.113008] Subtest sync_multi_producer_single_consumer: SUCCESS (1.075s)
[287.176438] [041/130] (731s left) core_auth (getclient-simple)
[287.216912] Starting subtest: getclient-simple
[287.238344] Subtest getclient-simple: SUCCESS (0.027s)
[287.346004] [042/130] (730s left) gem_ctx_create (basic)
[287.408706] Starting subtest: basic
[287.411645] Subtest basic: SUCCESS (0.000s)
[287.597552] [043/130] (730s left) gem_exec_reloc (basic-softpin)
[287.656993] Subtest basic-softpin: SKIP (0.000s)
[287.732756] [044/130] (730s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[287.833970] Starting subtest: psr-1p-primscrn-spr-indfb-draw-render
[294.543388] Subtest psr-1p-primscrn-spr-indfb-draw-render: SUCCESS (6.716s)
[294.680122] [045/130] (723s left) i915_drm_fdinfo (idle)
[294.719657] Starting subtest: idle
[294.722256] Starting dynamic subtest: rcs0
[295.287556] Dynamic subtest rcs0: SUCCESS (0.569s)
[295.303393] Starting dynamic subtest: bcs0
[295.794782] Dynamic subtest bcs0: SUCCESS (0.504s)
[295.811830] Starting dynamic subtest: vcs0
[296.301499] Dynamic subtest vcs0: SUCCESS (0.504s)
[296.314115] Starting dynamic subtest: vcs1
[296.807491] Dynamic subtest vcs1: SUCCESS (0.504s)
[296.819621] Starting dynamic subtest: vecs0
[297.312576] Dynamic subtest vecs0: SUCCESS (0.503s)
[297.324668] Starting dynamic subtest: ccs0
[297.817118] Dynamic subtest ccs0: SUCCESS (0.503s)
[297.820108] Subtest idle: SUCCESS (3.100s)
[297.892431] [046/130] (720s left) gem_exec_schedule (timeslicing)
[297.968057] Starting subtest: timeslicing
[297.970344] Starting dynamic subtest: rcs0
[297.981653] Dynamic subtest rcs0: SUCCESS (0.026s)
[297.991790] Starting dynamic subtest: bcs0
[298.007113] Dynamic subtest bcs0: SUCCESS (0.024s)
[298.016205] Starting dynamic subtest: vcs0
[298.030523] Dynamic subtest vcs0: SUCCESS (0.023s)
[298.040083] Starting dynamic subtest: vcs1
[298.056590] Dynamic subtest vcs1: SUCCESS (0.025s)
[298.065818] Starting dynamic subtest: vecs0
[298.081621] Dynamic subtest vecs0: SUCCESS (0.024s)
[298.091108] Starting dynamic subtest: ccs0
[298.109365] Dynamic subtest ccs0: SUCCESS (0.026s)
[298.112072] Subtest timeslicing: SUCCESS (0.154s)
[299.569802] [047/130] (718s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[299.670717] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-fullscreen
[299.675765] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[299.764353] [048/130] (718s left) kms_invalid_mode (zero-vdisplay)
[299.866623] Starting subtest: zero-vdisplay
[299.869223] Starting dynamic subtest: pipe-A-eDP-1
[300.152352] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.287s)
[300.163103] Starting dynamic subtest: pipe-B-eDP-1
[300.165092] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[300.174355] Starting dynamic subtest: pipe-C-eDP-1
[300.176738] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[300.186708] Starting dynamic subtest: pipe-D-eDP-1
[300.189066] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[300.191739] Subtest zero-vdisplay: SUCCESS (0.292s)
[301.017657] [049/130] (717s left) kms_dsc (dsc-with-bpc)
[301.105055] Starting subtest: dsc-with-bpc
[301.143733] Subtest dsc-with-bpc: SKIP (0.001s)
[301.183021] [050/130] (717s left) kms_ccs (crc-primary-suspend-y-tiled-gen12-rc-ccs-cc)
[301.271874] Starting subtest: crc-primary-suspend-y-tiled-gen12-rc-ccs-cc
[301.274639] Starting dynamic subtest: pipe-A-eDP-1
[301.285262] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[301.295932] Starting dynamic subtest: pipe-B-eDP-1
[301.305845] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[301.315349] Starting dynamic subtest: pipe-C-eDP-1
[301.325722] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[301.335684] Starting dynamic subtest: pipe-D-eDP-1
[301.345969] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[301.350852] Subtest crc-primary-suspend-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[303.470903] [051/130] (715s left) kms_psr (psr2-sprite-mmap-cpu)
[303.571850] Starting subtest: psr2-sprite-mmap-cpu
[303.574765] Starting dynamic subtest: eDP-1
[305.267373] Dynamic subtest eDP-1: SUCCESS (1.695s)
[305.270792] Subtest psr2-sprite-mmap-cpu: SUCCESS (1.697s)
[306.127540] [052/130] (712s left) kms_psr (pr-primary-blt)
[306.226111] Starting subtest: pr-primary-blt
[306.231892] Subtest pr-primary-blt: SKIP (0.000s)
[306.301367] [053/130] (712s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[306.406078] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[307.262540] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SUCCESS (0.859s)
[307.500414] [054/130] (711s left) kms_big_fb (y-tiled-32bpp-rotate-90)
[307.611970] Starting subtest: y-tiled-32bpp-rotate-90
[307.617368] Subtest y-tiled-32bpp-rotate-90: SKIP (0.000s)
[307.728623] [055/130] (711s left) gem_mmap_gtt (ptrace)
[307.796270] Subtest ptrace: SKIP (0.000s)
[307.866866] [056/130] (710s left) perf (invalid-create-userspace-config)
[307.987006] Starting subtest: invalid-create-userspace-config
[307.989724] Subtest invalid-create-userspace-config: SUCCESS (0.001s)
[308.033886] [057/130] (710s left) kms_cursor_crc (cursor-rapid-movement-32x32)
[308.186975] Starting subtest: cursor-rapid-movement-32x32
[308.232805] Subtest cursor-rapid-movement-32x32: SKIP (0.039s)
[308.509526] [058/130] (710s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-plflip-blt)
[308.605111] Starting subtest: fbcpsr-1p-primscrn-shrfb-plflip-blt
[316.069941] Subtest fbcpsr-1p-primscrn-shrfb-plflip-blt: SUCCESS (7.472s)
[316.219154] [059/130] (702s left) kms_cursor_legacy (single-bo)
[316.339967] Starting subtest: single-bo
[316.342542] Starting dynamic subtest: pipe-A
[321.719841] Dynamic subtest pipe-A: SUCCESS (5.380s)
[321.731508] Starting dynamic subtest: pipe-B
[327.109296] Dynamic subtest pipe-B: SUCCESS (5.383s)
[327.120897] Starting dynamic subtest: pipe-C
[332.494453] Dynamic subtest pipe-C: SUCCESS (5.380s)
[332.504760] Starting dynamic subtest: pipe-D
[337.887898] Dynamic subtest pipe-D: SUCCESS (5.380s)
[337.900861] Starting dynamic subtest: all-pipes
[343.268829] Dynamic subtest all-pipes: SUCCESS (5.387s)
[343.271404] Subtest single-bo: SUCCESS (26.920s)
[343.416424] [060/130] (675s left) kms_getfb (getfb-handle-zero)
[343.518973] Starting subtest: getfb-handle-zero
[343.521806] Subtest getfb-handle-zero: SUCCESS (0.000s)
[343.645428] [061/130] (675s left) kms_setmode (invalid-clone-exclusive-crtc)
[343.706145] Starting subtest: invalid-clone-exclusive-crtc
[343.713344] Subtest invalid-clone-exclusive-crtc: SKIP (0.000s)
[343.821593] [062/130] (675s left) syncobj_wait (single-wait-all-for-submit-submitted)
[343.872232] Starting subtest: single-wait-all-for-submit-submitted
[343.973763] Subtest single-wait-all-for-submit-submitted: SUCCESS (0.103s)
[344.053023] [063/130] (675s left) i915_suspend (fence-restore-untiled)
[344.112957] Starting subtest: fence-restore-untiled
[344.118621] Subtest fence-restore-untiled: SKIP (0.000s)
[344.212610] [064/130] (674s left) kms_fbcon_fbt (fbc-suspend)
[344.276568] Starting subtest: fbc-suspend
[393.792683] Subtest fbc-suspend: SUCCESS (19.063s)
[394.900370] [065/130] (624s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[395.287437] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[396.095275] [066/130] (623s left) vgem_basic (sysfs)
[396.131667] Starting subtest: sysfs
[396.133848] Subtest sysfs: SUCCESS (0.000s)
[396.185999] [067/130] (623s left) perf_pmu (all-busy-idle-check-all)
[396.228325] Starting subtest: all-busy-idle-check-all
[396.930177] Subtest all-busy-idle-check-all: FAIL (0.654s)
[398.375579] [068/130] (620s left) kms_rotation_crc (primary-y-tiled-reflect-x-270)
[398.471042] Starting subtest: primary-y-tiled-reflect-x-270
[398.475440] Subtest primary-y-tiled-reflect-x-270: SKIP (0.001s)
[398.544913] [069/130] (620s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[398.647811] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[398.661114] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.011s)
[398.734149] [070/130] (620s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[398.825927] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[398.828270] Starting dynamic subtest: pipe-A-default-mode
[399.125566] Dynamic subtest pipe-A-default-mode: SKIP (0.296s)
[399.130313] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.297s)
[399.900744] [071/130] (619s left) kms_ccs (random-ccs-data-y-tiled-ccs)
[399.995770] Starting subtest: random-ccs-data-y-tiled-ccs
[399.998087] Starting dynamic subtest: pipe-A-eDP-1
[400.023710] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[400.034405] Starting dynamic subtest: pipe-B-eDP-1
[400.058550] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[400.067975] Starting dynamic subtest: pipe-C-eDP-1
[400.091808] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[400.101017] Starting dynamic subtest: pipe-D-eDP-1
[400.125321] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[400.129321] Subtest random-ccs-data-y-tiled-ccs: SKIP (0.004s)
[402.224286] [072/130] (617s left) kms_flip (2x-blocking-absolute-wf_vblank-interruptible)
[402.330825] Starting subtest: 2x-blocking-absolute-wf_vblank-interruptible
[402.343878] Subtest 2x-blocking-absolute-wf_vblank-interruptible: SKIP (0.011s)
[402.448657] [073/130] (617s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[402.501921] Starting subtest: yf-tiled-to-vebox-yf-tiled
[402.505921] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[402.685699] [074/130] (616s left) gem_exec_create (forked)
[402.751346] Starting subtest: forked
[402.753857] Starting dynamic subtest: smem
[424.277927] Dynamic subtest smem: SUCCESS (21.571s)
[424.280644] Subtest forked: SUCCESS (21.573s)
[424.465685] [075/130] (595s left) gem_lmem_swapping (parallel-random-verify-ccs)
[424.519121] Subtest parallel-random-verify-ccs: SKIP (0.000s)
[424.566898] [076/130] (595s left) gem_partial_pwrite_pread (reads-display)
[424.621309] Subtest reads-display: SKIP (0.000s)
[424.709308] [077/130] (595s left) kms_content_protection (lic-type-0)
[424.890414] Starting subtest: lic-type-0
[428.305142] Subtest lic-type-0: SKIP (3.410s)
[429.475287] [078/130] (590s left) gen9_exec_parse (bb-start-far)
[429.531559] Subtest bb-start-far: SKIP (0.000s)
[429.597656] [079/130] (590s left) gem_create (create-ext-cpu-access-big)
[429.767654] Starting subtest: create-ext-cpu-access-big
[429.772684] Subtest create-ext-cpu-access-big: SKIP (0.001s)
[429.844822] [080/130] (589s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[429.970429] Starting subtest: cursorB-vs-flipA-toggle
[429.974872] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s)
[430.113174] [081/130] (589s left) kms_fb_coherency (memset-crc)
[430.205924] Starting subtest: memset-crc
[430.207822] Starting dynamic subtest: mmap-offset-wc
[430.591434] Dynamic subtest mmap-offset-wc: SUCCESS (0.387s)
[430.879936] Starting dynamic subtest: mmap-offset-uc
[436.987442] Dynamic subtest mmap-offset-uc: SUCCESS (6.114s)
[437.283161] Subtest memset-crc: SUCCESS (7.073s)
[438.121835] [082/130] (581s left) gem_exec_gttfill (engines)
[438.179341] Starting subtest: engines
[438.181761] Starting dynamic subtest: rcs0
[462.781167] Dynamic subtest rcs0: SUCCESS (24.602s)
[462.791432] Starting dynamic subtest: bcs0
[488.050784] Dynamic subtest bcs0: SUCCESS (25.272s)
[488.063145] Starting dynamic subtest: vcs0
[513.261938] Dynamic subtest vcs0: SUCCESS (25.203s)
[513.271832] Starting dynamic subtest: vcs1
[538.420898] Dynamic subtest vcs1: SUCCESS (25.157s)
[538.433361] Starting dynamic subtest: vecs0
[563.640854] Dynamic subtest vecs0: SUCCESS (25.221s)
[563.652279] Starting dynamic subtest: ccs0
[588.889788] Dynamic subtest ccs0: SUCCESS (25.252s)
[588.892167] Subtest engines: SUCCESS (150.719s)
[589.080544] [083/130] (430s left) gem_mmap_gtt (basic)
[589.186273] Subtest basic: SKIP (0.000s)
[589.255965] [084/130] (430s left) kms_cursor_crc (cursor-onscreen-128x42)
[589.501816] Starting subtest: cursor-onscreen-128x42
[589.549088] Subtest cursor-onscreen-128x42: SKIP (0.041s)
[589.775054] [085/130] (430s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[589.884340] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt
[589.888814] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[590.006192] [086/130] (430s left) kms_pm_dc (dc5-psr)
[591.116715] Starting subtest: dc5-psr
[594.001286] Subtest dc5-psr: SUCCESS (2.884s)
[594.826878] [087/130] (425s left) core_getstats
[595.002807] [088/130] (425s left) i915_pm_freq_api (freq-reset-multiple)
[595.062484] Starting subtest: freq-reset-multiple
[595.064770] Starting dynamic subtest: gt0
[604.784262] Dynamic subtest gt0: SUCCESS (9.721s)
[604.794066] Starting dynamic subtest: gt1
[613.197837] Dynamic subtest gt1: SUCCESS (8.415s)
[613.200816] Subtest freq-reset-multiple: SUCCESS (18.137s)
[613.289321] [089/130] (406s left) gem_ctx_persistence (engines-hang)
[613.364032] Starting subtest: engines-hang
[613.366459] Starting dynamic subtest: rcs0
[613.465517] Dynamic subtest rcs0: SUCCESS (0.101s)
[613.478808] Starting dynamic subtest: bcs0
[613.585266] Dynamic subtest bcs0: SUCCESS (0.118s)
[613.598167] Starting dynamic subtest: vcs0
[613.703706] Dynamic subtest vcs0: SUCCESS (0.116s)
[613.715418] Starting dynamic subtest: vcs1
[613.801314] Dynamic subtest vcs1: SUCCESS (0.096s)
[613.814168] Starting dynamic subtest: vecs0
[613.881262] Dynamic subtest vecs0: SUCCESS (0.078s)
[613.892138] Starting dynamic subtest: ccs0
[613.992363] Dynamic subtest ccs0: SUCCESS (0.109s)
[613.994855] Subtest engines-hang: SUCCESS (0.629s)
[614.112141] [090/130] (406s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[614.236037] Starting subtest: cursor-vs-flip-legacy
[643.613288] Subtest cursor-vs-flip-legacy: SUCCESS (29.367s)
[644.468993] [091/130] (375s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[644.579173] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[644.583443] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[644.697693] [092/130] (375s left) kms_panel_fitting (atomic-fastset)
[644.792968] Starting subtest: atomic-fastset
[644.795385] Starting dynamic subtest: pipe-A-eDP-1
[644.958455] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.165s)
[644.970879] Starting dynamic subtest: pipe-B-eDP-1
[646.191689] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.232s)
[646.203928] Starting dynamic subtest: pipe-C-eDP-1
[647.381896] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.188s)
[647.392884] Starting dynamic subtest: pipe-D-eDP-1
[648.595039] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.213s)
[648.597506] Subtest atomic-fastset: SUCCESS (3.801s)
[649.832005] [093/130] (370s left) gem_ctx_param (invalid-param-set)
[649.898483] Starting subtest: invalid-param-set
[649.901003] Subtest invalid-param-set: SUCCESS (0.000s)
[649.979669] [094/130] (370s left) kms_big_fb (yf-tiled-8bpp-rotate-0)
[650.086879] Starting subtest: yf-tiled-8bpp-rotate-0
[650.090807] Subtest yf-tiled-8bpp-rotate-0: SKIP (0.000s)
[650.189746] [095/130] (370s left) api_intel_bb (crc32)
[650.250180] Starting subtest: crc32
[650.252142] Starting dynamic subtest: rcs0-smem0
[650.538055] Dynamic subtest rcs0-smem0: SUCCESS (0.288s)
[650.551274] Starting dynamic subtest: bcs0-smem0
[650.848813] Dynamic subtest bcs0-smem0: SUCCESS (0.308s)
[650.862403] Starting dynamic subtest: vcs0-smem0
[651.183345] Dynamic subtest vcs0-smem0: SUCCESS (0.333s)
[651.196841] Starting dynamic subtest: vcs1-smem0
[651.511323] Dynamic subtest vcs1-smem0: SUCCESS (0.325s)
[651.525061] Starting dynamic subtest: vecs0-smem0
[651.801635] Dynamic subtest vecs0-smem0: SUCCESS (0.288s)
[651.815021] Starting dynamic subtest: ccs0-smem0
[652.117720] Dynamic subtest ccs0-smem0: SUCCESS (0.314s)
[652.120803] Subtest crc32: SUCCESS (1.869s)
[652.269089] [096/130] (368s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-wc)
[652.368960] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-wc
[655.997586] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (3.632s)
[657.204732] [097/130] (363s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-onoff)
[657.312229] Starting subtest: fbc-2p-scndscrn-cur-indfb-onoff
[657.316561] Subtest fbc-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[657.428237] [098/130] (363s left) syncobj_timeline (single-wait-all-available-signaled)
[657.493427] Starting subtest: single-wait-all-available-signaled
[657.495760] Subtest single-wait-all-available-signaled: SUCCESS (0.000s)
[657.611263] [099/130] (363s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-render)
[657.697430] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-render
[657.701872] Subtest psr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[657.809242] [100/130] (362s left) perf (global-sseu-config-invalid)
[657.907375] Starting subtest: global-sseu-config-invalid
[657.912090] Subtest global-sseu-config-invalid: SKIP (0.000s)
[657.952158] [101/130] (362s left) gem_sync (basic-many-each)
[658.023243] Starting subtest: basic-many-each
[660.325666] Subtest basic-many-each: SUCCESS (2.313s)
[660.507797] [102/130] (360s left) kms_psr (fbc-pr-dpms)
[660.610272] Starting subtest: fbc-pr-dpms
[660.614519] Subtest fbc-pr-dpms: SKIP (0.000s)
[660.684276] [103/130] (360s left) gem_userptr_blits (map-fixed-invalidate-busy)
[660.757665] Starting subtest: map-fixed-invalidate-busy
[660.762352] Subtest map-fixed-invalidate-busy: SKIP (0.000s)
[660.878669] [104/130] (360s left) kms_chamelium_color (ctm-green-to-red)
[660.967923] Subtest ctm-green-to-red: SKIP (0.000s)
[661.017079] [105/130] (359s left) gem_softpin (evict-snoop)
[661.082698] Starting subtest: evict-snoop
[661.087509] Subtest evict-snoop: SKIP (0.000s)
[661.197548] [106/130] (359s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-render)
[661.301209] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-render
[668.543305] Subtest psr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.243s)
[668.690039] [107/130] (352s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[668.793598] Starting subtest: x-tiled-64bpp-rotate-90
[668.812850] Subtest x-tiled-64bpp-rotate-90: SKIP (0.019s)
[668.935867] [108/130] (352s left) gem_exec_flush (basic-wb-ro-default)
[668.993513] Starting subtest: basic-wb-ro-default
[674.445489] Subtest basic-wb-ro-default: SUCCESS (5.453s)
[674.632134] [109/130] (346s left) gem_exec_whisper (basic-fds-all)
[674.687720] Starting subtest: basic-fds-all
[686.955654] Subtest basic-fds-all: SUCCESS (12.264s)
[687.140316] [110/130] (333s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-downscaling)
[687.243766] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-downscaling
[687.246007] Starting dynamic subtest: pipe-A-default-mode
[687.532887] Dynamic subtest pipe-A-default-mode: SKIP (0.285s)
[687.537742] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-downscaling: SKIP (0.286s)
[688.327522] [111/130] (332s left) gem_render_copy (y-tiled)
[688.395400] Starting subtest: y-tiled
[688.400361] Subtest y-tiled: SKIP (0.000s)
[688.582471] [112/130] (332s left) kms_psr (psr2-primary-page-flip)
[688.684897] Starting subtest: psr2-primary-page-flip
[688.687080] Starting dynamic subtest: eDP-1
[690.404398] Dynamic subtest eDP-1: SUCCESS (1.720s)
[690.406704] Subtest psr2-primary-page-flip: SUCCESS (1.722s)
[691.274550] [113/130] (329s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[691.335198] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[691.337590] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.000s)
[691.449862] [114/130] (329s left) syncobj_timeline (single-wait-all-for-submit-signaled)
[691.508118] Starting subtest: single-wait-all-for-submit-signaled
[691.510579] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[691.637883] [115/130] (329s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-move)
[691.738326] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-move
[699.011671] Subtest fbcpsr-1p-primscrn-cur-indfb-move: SUCCESS (7.276s)
[699.179742] [116/130] (322s left) gem_exec_params (dr1-dirt)
[699.238100] Starting subtest: DR1-dirt
[699.240448] Subtest DR1-dirt: SUCCESS (0.000s)
[699.352325] [117/130] (322s left) kms_plane_lowres (tiling-4)
[699.452896] Starting subtest: tiling-4
[699.455033] Starting dynamic subtest: pipe-A-eDP-1
[699.459435] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[699.751210] Starting dynamic subtest: pipe-B-eDP-1
[699.755690] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[699.767861] Starting dynamic subtest: pipe-C-eDP-1
[699.772404] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[699.782307] Starting dynamic subtest: pipe-D-eDP-1
[699.786671] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[699.790851] Subtest tiling-4: SKIP (0.294s)
[700.589702] [118/130] (320s left) gem_spin_batch (legacy-resubmit)
[700.647773] Starting subtest: legacy-resubmit
[700.650292] Starting dynamic subtest: default
[700.655536] Dynamic subtest default: SUCCESS (0.007s)
[700.667863] Starting dynamic subtest: render
[700.670195] Dynamic subtest render: SUCCESS (0.001s)
[700.681551] Starting dynamic subtest: bsd
[700.683847] Dynamic subtest bsd: SUCCESS (0.002s)
[700.694000] Starting dynamic subtest: bsd1
[700.696353] Dynamic subtest bsd1: SUCCESS (0.002s)
[700.706239] Starting dynamic subtest: bsd2
[700.708546] Dynamic subtest bsd2: SUCCESS (0.001s)
[700.718273] Starting dynamic subtest: blt
[700.720656] Dynamic subtest blt: SUCCESS (0.002s)
[700.730779] Starting dynamic subtest: vebox
[700.733018] Dynamic subtest vebox: SUCCESS (0.002s)
[700.735232] Subtest legacy-resubmit: SUCCESS (0.022s)
[700.897273] [119/130] (320s left) kms_color (ctm-0-25)
[700.989942] Starting subtest: ctm-0-25
[700.992480] Starting dynamic subtest: pipe-A-eDP-1
[704.468787] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.477s)
[704.481408] Starting dynamic subtest: pipe-B-eDP-1
[708.676169] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.207s)
[708.687502] Starting dynamic subtest: pipe-C-eDP-1
[712.667081] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.992s)
[712.678230] Starting dynamic subtest: pipe-D-eDP-1
[716.652821] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.983s)
[716.654772] Subtest ctm-0-25: SUCCESS (15.661s)
[717.472797] [120/130] (304s left) gem_mmap_gtt (basic-write-read)
[717.529797] Subtest basic-write-read: SKIP (0.000s)
[717.603059] [121/130] (304s left) syncobj_timeline (invalid-single-wait-unsubmitted)
[717.659324] Starting subtest: invalid-single-wait-unsubmitted
[717.661937] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[717.772790] [122/130] (303s left) kms_psr (fbc-psr-cursor-mmap-cpu)
[717.877708] Starting subtest: fbc-psr-cursor-mmap-cpu
[717.879958] Starting dynamic subtest: eDP-1
[717.884449] Dynamic subtest eDP-1: SKIP (0.000s)
[717.889024] Subtest fbc-psr-cursor-mmap-cpu: SKIP (0.001s)
[717.946288] [123/130] (303s left) kms_selftest (drm_format_helper)
[717.995865] Starting subtest: drm_format_helper
[718.037284] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8
[718.189106] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8: SUCCESS (0.152s)
[718.199802] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332
[718.277834] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332: SUCCESS (0.086s)
[718.287725] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565
[718.408862] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565: SUCCESS (0.129s)
[718.419104] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555
[718.535147] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555: SUCCESS (0.128s)
[718.545221] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555
[718.681788] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555: SUCCESS (0.144s)
[718.692228] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551
[718.819767] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551: SUCCESS (0.138s)
[718.829948] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888
[718.923750] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888: SUCCESS (0.105s)
[718.933944] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888
[719.051533] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888: SUCCESS (0.127s)
[719.061723] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888
[719.182896] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888: SUCCESS (0.129s)
[719.194005] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010
[719.319919] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010: SUCCESS (0.137s)
[719.330451] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010
[719.473570] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010: SUCCESS (0.153s)
[719.484999] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_mono
[719.618538] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_mono: SUCCESS (0.142s)
[719.628402] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_swab
[719.767407] Dynamic subtest drm_format_helper_test-drm_test_fb_swab: SUCCESS (0.150s)
[719.777528] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888
[719.906899] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888: SUCCESS (0.138s)
[719.916668] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888
[720.040896] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888: SUCCESS (0.133s)
[720.051046] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_clip_offset
[720.162638] Dynamic subtest drm_format_helper_test-drm_test_fb_clip_offset: SUCCESS (0.122s)
[720.172806] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_memcpy
[720.285520] Dynamic subtest drm_format_helper_test-drm_test_fb_memcpy: SUCCESS (0.122s)
[720.287933] Subtest drm_format_helper: SUCCESS (2.289s)
[720.475675] [124/130] (301s left) gem_wait (invalid-buf)
[720.531864] Starting subtest: invalid-buf
[720.534454] Subtest invalid-buf: SUCCESS (0.000s)
[720.755978] [125/130] (301s left) kms_cursor_legacy (flip-vs-cursor-crc-legacy)
[720.878097] Starting subtest: flip-vs-cursor-crc-legacy
[721.538664] Subtest flip-vs-cursor-crc-legacy: SUCCESS (0.658s)
[722.403377] [126/130] (299s left) kms_getfb (getfb2-into-addfb2)
[722.495048] Starting subtest: getfb2-into-addfb2
[722.497307] Subtest getfb2-into-addfb2: SUCCESS (0.000s)
[722.588224] [127/130] (299s left) gem_mmap_offset (open-flood)
[722.643618] Starting subtest: open-flood
[744.129462] Subtest open-flood: SUCCESS (21.490s)
[744.261705] [128/130] (277s left) prime_vgem (fence-wait)
[744.345591] Starting subtest: fence-wait
[744.347651] Starting dynamic subtest: rcs0
[745.418780] Dynamic subtest rcs0: SUCCESS (1.072s)
[745.431037] Starting dynamic subtest: bcs0
[746.510299] Dynamic subtest bcs0: SUCCESS (1.091s)
[746.521744] Starting dynamic subtest: vcs0
[747.553397] Dynamic subtest vcs0: SUCCESS (1.042s)
[747.566783] Starting dynamic subtest: vcs1
[748.595328] Dynamic subtest vcs1: SUCCESS (1.041s)
[748.608243] Starting dynamic subtest: vecs0
[749.641151] Dynamic subtest vecs0: SUCCESS (1.044s)
[749.653907] Starting dynamic subtest: ccs0
[750.680508] Dynamic subtest ccs0: SUCCESS (1.036s)
[750.683403] Subtest fence-wait: SUCCESS (6.334s)
[750.812124] [129/130] (271s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[750.925858] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[750.930185] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[751.023225] [130/130] (270s left) gem_vm_create (isolation)
[751.070549] Starting subtest: isolation
[751.079453] Subtest isolation: SUCCESS (0.009s)
[751.200334] 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.