[54.904282] Initializing watchdogs
[54.904329]   /dev/watchdog0
[54.917798] [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]
[54.921965] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[54.924332] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[54.927670] [001/130] (960s left) kms_busy (basic)
[55.061086] Starting subtest: basic
[55.063341] Starting dynamic subtest: flip
[55.765944] Dynamic subtest flip: SUCCESS (0.697s)
[55.775804] Starting dynamic subtest: modeset
[59.171942] Dynamic subtest modeset: SUCCESS (3.379s)
[59.174501] Subtest basic: SUCCESS (4.077s)
[59.964048] [002/130] (955s left) gem_ctx_sseu (engines)
[60.040837] Subtest engines: SKIP (0.000s)
[60.114423] [003/130] (955s left) kms_ccs (crc-primary-basic-y-tiled-ccs)
[60.213003] Starting subtest: crc-primary-basic-y-tiled-ccs
[60.215484] Starting dynamic subtest: pipe-A-eDP-1
[60.239695] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[60.251698] Starting dynamic subtest: pipe-B-eDP-1
[60.273656] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[60.280089] Starting dynamic subtest: pipe-C-eDP-1
[60.301627] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[60.308237] Starting dynamic subtest: pipe-D-eDP-1
[60.331050] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[60.335799] Subtest crc-primary-basic-y-tiled-ccs: SKIP (0.004s)
[62.427557] [004/130] (953s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0)
[62.526185] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0
[63.585485] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.056s)
[63.770949] [005/130] (951s left) gem_exec_schedule (pi-shared-iova)
[63.961668] Starting subtest: pi-shared-iova
[63.963970] Starting dynamic subtest: rcs0
[64.038732] Dynamic subtest rcs0: SUCCESS (0.080s)
[64.050791] Starting dynamic subtest: vcs0
[64.093120] Dynamic subtest vcs0: SUCCESS (0.054s)
[64.103744] Starting dynamic subtest: vcs1
[64.143823] Dynamic subtest vcs1: SUCCESS (0.051s)
[64.156046] Starting dynamic subtest: vecs0
[64.192171] Dynamic subtest vecs0: SUCCESS (0.048s)
[64.203114] Starting dynamic subtest: ccs0
[64.251050] Dynamic subtest ccs0: SUCCESS (0.058s)
[64.254261] Subtest pi-shared-iova: SUCCESS (0.292s)
[65.984736] [006/130] (949s left) kms_draw_crc (fill-fb)
[66.092238] Starting subtest: fill-fb
[66.539906] Subtest fill-fb: SUCCESS (0.450s)
[66.651020] [007/130] (948s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-mmap-wc)
[66.754401] Starting subtest: psr-1p-offscren-pri-shrfb-draw-mmap-wc
[73.322426] Subtest psr-1p-offscren-pri-shrfb-draw-mmap-wc: SUCCESS (6.560s)
[73.454146] [008/130] (942s left) kms_plane_scaling (planes-downscale-factor-0-75-unity-scaling)
[73.553068] Starting subtest: planes-downscale-factor-0-75-unity-scaling
[73.555477] Starting dynamic subtest: pipe-A
[73.638856] Dynamic subtest pipe-A: SUCCESS (0.086s)
[73.645893] Starting dynamic subtest: pipe-B
[74.765646] Dynamic subtest pipe-B: SUCCESS (1.125s)
[74.778479] Starting dynamic subtest: pipe-C
[75.871798] Dynamic subtest pipe-C: SUCCESS (1.105s)
[75.881068] Starting dynamic subtest: pipe-D
[76.998827] Dynamic subtest pipe-D: SUCCESS (1.126s)
[77.002342] Subtest planes-downscale-factor-0-75-unity-scaling: SUCCESS (3.443s)
[78.146932] [009/130] (937s left) gem_userptr_blits (sync-unmap-cycles)
[78.214879] Starting subtest: sync-unmap-cycles
[83.596199] Subtest sync-unmap-cycles: SUCCESS (5.381s)
[83.733155] [010/130] (932s left) gem_userptr_blits (dmabuf-unsync)
[83.798968] Subtest dmabuf-unsync: SKIP (0.000s)
[83.938749] [011/130] (931s left) kms_sequence (queue-busy)
[84.039068] Starting subtest: queue-busy
[84.041210] Starting dynamic subtest: pipe-A-eDP-1
[85.996780] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.953s)
[86.004713] Starting dynamic subtest: pipe-B-eDP-1
[88.564113] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.568s)
[88.573791] Starting dynamic subtest: pipe-C-eDP-1
[91.150832] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.583s)
[91.159874] Starting dynamic subtest: pipe-D-eDP-1
[93.684108] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.532s)
[93.686371] Subtest queue-busy: SUCCESS (9.641s)
[94.551247] [012/130] (921s left) fbdev (read)
[94.597366] Starting subtest: read
[95.127543] Subtest read: SUCCESS (0.528s)
[95.185585] [013/130] (920s left) kms_async_flips (basic-modeset-with-all-modifiers-formats)
[95.270383] Starting subtest: basic-modeset-with-all-modifiers-formats
[95.644365] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB8888
[96.427503] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB8888: SUCCESS (0.786s)
[96.435213] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB2101010
[96.451052] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB2101010: SUCCESS (0.022s)
[96.463236] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB16161616F
[96.472284] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB16161616F: SUCCESS (0.022s)
[96.479731] Starting dynamic subtest: pipe-A-eDP-1-4-RGB565
[96.683375] Dynamic subtest pipe-A-eDP-1-4-RGB565: SUCCESS (0.211s)
[96.691538] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB8888
[96.705681] Dynamic subtest pipe-A-eDP-1-4-XRGB8888: SUCCESS (0.022s)
[96.712770] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB2101010
[96.727687] Dynamic subtest pipe-A-eDP-1-4-XRGB2101010: SUCCESS (0.022s)
[96.736927] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB16161616F
[96.750106] Dynamic subtest pipe-A-eDP-1-4-XRGB16161616F: SUCCESS (0.022s)
[96.757312] Starting dynamic subtest: pipe-A-eDP-1-4-YUYV
[96.772654] Dynamic subtest pipe-A-eDP-1-4-YUYV: SUCCESS (0.022s)
[96.781108] Starting dynamic subtest: pipe-A-eDP-1-4-Y210
[96.794406] Dynamic subtest pipe-A-eDP-1-4-Y210: SUCCESS (0.022s)
[96.801595] Starting dynamic subtest: pipe-A-eDP-1-4-XYUV8888
[96.816412] Dynamic subtest pipe-A-eDP-1-4-XYUV8888: SUCCESS (0.022s)
[96.823647] Starting dynamic subtest: pipe-A-eDP-1-4-XV30
[96.839092] Dynamic subtest pipe-A-eDP-1-4-XV30: SUCCESS (0.022s)
[96.846313] Starting dynamic subtest: pipe-A-eDP-1-4-XV36
[96.862488] Dynamic subtest pipe-A-eDP-1-4-XV36: SUCCESS (0.022s)
[96.869664] Starting dynamic subtest: pipe-A-eDP-1-x-RGB565
[97.062399] Dynamic subtest pipe-A-eDP-1-x-RGB565: SUCCESS (0.200s)
[97.073861] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB8888
[97.083415] Dynamic subtest pipe-A-eDP-1-x-XRGB8888: SUCCESS (0.022s)
[97.091963] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB2101010
[97.105707] Dynamic subtest pipe-A-eDP-1-x-XRGB2101010: SUCCESS (0.022s)
[97.114856] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB16161616F
[97.127746] Dynamic subtest pipe-A-eDP-1-x-XRGB16161616F: SUCCESS (0.022s)
[97.134679] Starting dynamic subtest: pipe-A-eDP-1-x-YUYV
[97.150160] Dynamic subtest pipe-A-eDP-1-x-YUYV: SUCCESS (0.022s)
[97.157322] Starting dynamic subtest: pipe-A-eDP-1-x-Y210
[97.172427] Dynamic subtest pipe-A-eDP-1-x-Y210: SUCCESS (0.022s)
[97.183494] Starting dynamic subtest: pipe-A-eDP-1-x-XYUV8888
[97.194534] Dynamic subtest pipe-A-eDP-1-x-XYUV8888: SUCCESS (0.022s)
[97.202076] Starting dynamic subtest: pipe-A-eDP-1-x-XV30
[97.216708] Dynamic subtest pipe-A-eDP-1-x-XV30: SUCCESS (0.022s)
[97.223147] Starting dynamic subtest: pipe-A-eDP-1-x-XV36
[97.239374] Dynamic subtest pipe-A-eDP-1-x-XV36: SUCCESS (0.022s)
[97.245751] Starting dynamic subtest: pipe-A-eDP-1-linear-RGB565
[97.461283] Dynamic subtest pipe-A-eDP-1-linear-RGB565: SUCCESS (0.222s)
[97.469806] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB8888
[97.483503] Dynamic subtest pipe-A-eDP-1-linear-XRGB8888: SUCCESS (0.022s)
[97.490623] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB2101010
[97.505557] Dynamic subtest pipe-A-eDP-1-linear-XRGB2101010: SUCCESS (0.022s)
[97.512292] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB16161616F
[97.527897] Dynamic subtest pipe-A-eDP-1-linear-XRGB16161616F: SUCCESS (0.022s)
[97.534440] Starting dynamic subtest: pipe-A-eDP-1-linear-YUYV
[97.550204] Dynamic subtest pipe-A-eDP-1-linear-YUYV: SUCCESS (0.022s)
[97.557282] Starting dynamic subtest: pipe-A-eDP-1-linear-Y210
[97.572475] Dynamic subtest pipe-A-eDP-1-linear-Y210: SUCCESS (0.022s)
[97.581639] Starting dynamic subtest: pipe-A-eDP-1-linear-XYUV8888
[97.594694] Dynamic subtest pipe-A-eDP-1-linear-XYUV8888: SUCCESS (0.022s)
[97.601851] Starting dynamic subtest: pipe-A-eDP-1-linear-XV30
[97.616624] Dynamic subtest pipe-A-eDP-1-linear-XV30: SUCCESS (0.022s)
[97.624503] Starting dynamic subtest: pipe-A-eDP-1-linear-XV36
[97.639145] Dynamic subtest pipe-A-eDP-1-linear-XV36: SUCCESS (0.022s)
[98.030255] Subtest basic-modeset-with-all-modifiers-formats: SUCCESS (2.760s)
[98.806685] [014/130] (917s left) kms_lease (lease-unleased-crtc)
[98.900038] Starting subtest: lease-unleased-crtc
[98.902409] Starting dynamic subtest: pipe-A-eDP-1
[99.191286] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.288s)
[99.199140] Starting dynamic subtest: pipe-B-eDP-1
[99.969828] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.777s)
[99.980024] Starting dynamic subtest: pipe-C-eDP-1
[99.981885] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.004s)
[99.988815] Starting dynamic subtest: pipe-D-eDP-1
[99.990678] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[99.992545] Subtest lease-unleased-crtc: SUCCESS (1.077s)
[100.331906] [015/130] (915s left) i915_pm_rpm (system-suspend)
[102.087095] Starting subtest: system-suspend
[120.500160] Subtest system-suspend: SUCCESS (3.001s)
[120.866254] [016/130] (895s left) kms_ccs (bad-aux-stride-y-tiled-ccs)
[120.982779] Starting subtest: bad-aux-stride-y-tiled-ccs
[120.985127] Starting dynamic subtest: pipe-A-eDP-1
[121.008618] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[121.021123] Starting dynamic subtest: pipe-B-eDP-1
[121.049318] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[121.057739] Starting dynamic subtest: pipe-C-eDP-1
[121.079217] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[121.087311] Starting dynamic subtest: pipe-D-eDP-1
[121.108884] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[121.112857] Subtest bad-aux-stride-y-tiled-ccs: SKIP (0.003s)
[123.127079] [017/130] (892s left) kms_flip (2x-plain-flip-interruptible)
[123.226730] Starting subtest: 2x-plain-flip-interruptible
[123.239906] Subtest 2x-plain-flip-interruptible: SKIP (0.010s)
[123.319363] [018/130] (892s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[123.414462] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[123.418903] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[123.519486] [019/130] (892s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-sf)
[128.533526] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-sf
[128.536122] Starting dynamic subtest: pipe-A-eDP-1
[129.324480] Dynamic subtest pipe-A-eDP-1: SKIP (0.784s)
[129.335550] Starting dynamic subtest: pipe-B-eDP-1
[130.447766] Dynamic subtest pipe-B-eDP-1: SKIP (1.115s)
[130.452045] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-sf: SKIP (1.902s)
[131.632669] [020/130] (884s left) gem_exec_fence (invalid-fence-array)
[132.084495] Starting subtest: invalid-fence-array
[132.087411] Subtest invalid-fence-array: SUCCESS (0.003s)
[133.379828] [FACT gem_exec_fence (invalid-fence-array)] new: kernel.kmod_is_loaded.vgem: true
[133.383373] [021/130] (882s left) gem_exec_schedule (pi-distinct-iova)
[133.566327] Starting subtest: pi-distinct-iova
[133.568283] Starting dynamic subtest: rcs0
[133.641870] Dynamic subtest rcs0: SUCCESS (0.078s)
[133.652982] Starting dynamic subtest: vcs0
[133.698951] Dynamic subtest vcs0: SUCCESS (0.056s)
[133.709151] Starting dynamic subtest: vcs1
[133.754418] Dynamic subtest vcs1: SUCCESS (0.055s)
[133.767944] Starting dynamic subtest: vecs0
[133.805507] Dynamic subtest vecs0: SUCCESS (0.051s)
[133.813783] Starting dynamic subtest: ccs0
[133.872142] Dynamic subtest ccs0: SUCCESS (0.065s)
[133.874721] Subtest pi-distinct-iova: SUCCESS (0.307s)
[135.376614] [022/130] (880s left) kms_psr2_sf (pr-overlay-plane-update-sf-dmg-area)
[140.434441] Starting subtest: pr-overlay-plane-update-sf-dmg-area
[140.439100] Subtest pr-overlay-plane-update-sf-dmg-area: SKIP (0.002s)
[141.269654] [023/130] (874s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[141.353798] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[141.356029] Starting dynamic subtest: pipe-A-eDP-1
[141.404306] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.050s)
[141.414566] Starting dynamic subtest: pipe-B-eDP-1
[141.453154] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.048s)
[141.462962] Starting dynamic subtest: pipe-C-eDP-1
[141.502386] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.049s)
[141.512551] Starting dynamic subtest: pipe-D-eDP-1
[141.554344] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.050s)
[141.556590] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SUCCESS (0.200s)
[143.724059] [024/130] (872s left) kms_vrr (negative-basic)
[143.820147] Starting subtest: negative-basic
[143.826887] Starting dynamic subtest: pipe-A-eDP-1
[149.275903] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.444s)
[149.557952] Subtest negative-basic: SUCCESS (5.730s)
[150.355524] [025/130] (865s left) kms_big_fb (4-tiled-16bpp-rotate-270)
[150.474107] Starting subtest: 4-tiled-16bpp-rotate-270
[150.493035] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.019s)
[150.627590] [026/130] (865s left) perf_pmu (most-busy-idle-check-all)
[150.669501] Starting subtest: most-busy-idle-check-all
[150.671707] Starting dynamic subtest: rcs0
[152.330079] Dynamic subtest rcs0: SUCCESS (1.658s)
[152.341632] Starting dynamic subtest: bcs0
[154.310251] Dynamic subtest bcs0: SUCCESS (1.978s)
[154.324067] Starting dynamic subtest: vcs0
[156.293973] Dynamic subtest vcs0: SUCCESS (1.981s)
[156.304748] Starting dynamic subtest: vcs1
[158.278341] Dynamic subtest vcs1: SUCCESS (1.982s)
[158.291004] Starting dynamic subtest: vecs0
[160.326037] Dynamic subtest vecs0: SUCCESS (2.045s)
[160.337981] Starting dynamic subtest: ccs0
[162.309470] Dynamic subtest ccs0: SUCCESS (1.981s)
[162.312376] Subtest most-busy-idle-check-all: SUCCESS (11.635s)
[162.424646] [027/130] (853s left) kms_pm_backlight (brightness-with-dpms)
[162.525737] Starting subtest: brightness-with-dpms
[162.590271] Starting dynamic subtest: eDP-1
[165.073952] Dynamic subtest eDP-1: SUCCESS (2.485s)
[165.076390] Subtest brightness-with-dpms: SUCCESS (2.549s)
[165.961331] [028/130] (850s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-submi
[166.015556] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-submitted
[166.121380] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-submitted: SUCCESS (0.105s)
[166.216061] [029/130] (850s left) gem_pxp (verify-pxp-key-change-after-suspend-resume)
[166.339625] Starting subtest: verify-pxp-key-change-after-suspend-resume
[173.415887] Subtest verify-pxp-key-change-after-suspend-resume: SUCCESS (7.076s)
[173.549223] [030/130] (843s left) kms_pipe_crc_basic (suspend-read-crc)
[173.650763] Starting subtest: suspend-read-crc
[173.653008] Starting dynamic subtest: pipe-A-eDP-1
[191.258731] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.225s)
[191.268519] Starting dynamic subtest: pipe-B-eDP-1
[209.344828] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.842s)
[209.355603] Starting dynamic subtest: pipe-C-eDP-1
[227.246237] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.724s)
[227.256036] Starting dynamic subtest: pipe-D-eDP-1
[245.234380] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.746s)
[245.236894] Subtest suspend-read-crc: SUCCESS (10.542s)
[246.115313] [031/130] (770s left) sw_sync (alloc_merge_fence)
[246.152390] Starting subtest: alloc_merge_fence
[246.154415] Subtest alloc_merge_fence: SUCCESS (0.000s)
[246.212220] [032/130] (770s left) gem_exec_balancer (individual)
[246.274122] Starting subtest: individual
[248.206580] Subtest individual: SUCCESS (1.902s)
[248.536726] [033/130] (768s left) gem_create (hog-create)
[248.592253] Starting subtest: hog-create
[248.594728] Starting dynamic subtest: smem0
[282.183249] Dynamic subtest smem0: SUCCESS (33.493s)
[282.185989] Subtest hog-create: SUCCESS (33.495s)
[282.363685] [034/130] (734s left) gem_exec_reloc (basic-cpu-wc)
[282.425522] Subtest basic-cpu-wc: SKIP (0.000s)
[282.515523] [035/130] (734s left) gem_ctx_sseu (invalid-args)
[282.583617] Subtest invalid-args: SKIP (0.000s)
[282.659056] [036/130] (734s left) kms_flip (flip-vs-fences-interruptible)
[282.766855] Starting subtest: flip-vs-fences-interruptible
[282.771439] Subtest flip-vs-fences-interruptible: SKIP (0.000s)
[282.887497] [037/130] (733s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite)
[282.981240] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite
[282.985260] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[283.096317] [038/130] (733s left) kms_prime (basic-crc-hybrid)
[283.181786] Subtest basic-crc-hybrid: SKIP (0.000s)
[283.261690] [039/130] (733s left) gem_softpin (allocator-fork)
[283.324327] Starting subtest: allocator-fork
[285.535178] Subtest allocator-fork: SUCCESS (2.210s)
[285.661576] [040/130] (731s left) sw_sync (sync_multi_producer_single_consumer)
[285.691058] Starting subtest: sync_multi_producer_single_consumer
[286.766282] Subtest sync_multi_producer_single_consumer: SUCCESS (1.075s)
[286.826863] [041/130] (730s left) core_auth (getclient-simple)
[286.859282] Starting subtest: getclient-simple
[286.892237] Subtest getclient-simple: SUCCESS (0.030s)
[287.003510] [042/130] (730s left) gem_ctx_create (basic)
[287.062821] Starting subtest: basic
[287.065131] Subtest basic: SUCCESS (0.000s)
[287.246973] [043/130] (729s left) gem_exec_reloc (basic-softpin)
[287.313745] Subtest basic-softpin: SKIP (0.000s)
[287.406430] [044/130] (729s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[287.512327] Starting subtest: psr-1p-primscrn-spr-indfb-draw-render
[294.186208] Subtest psr-1p-primscrn-spr-indfb-draw-render: SUCCESS (6.677s)
[294.334296] [045/130] (722s left) i915_drm_fdinfo (idle)
[294.381660] Starting subtest: idle
[294.383878] Starting dynamic subtest: rcs0
[294.955928] Dynamic subtest rcs0: SUCCESS (0.576s)
[294.968309] Starting dynamic subtest: bcs0
[295.463352] Dynamic subtest bcs0: SUCCESS (0.504s)
[295.475024] Starting dynamic subtest: vcs0
[295.968467] Dynamic subtest vcs0: SUCCESS (0.503s)
[295.980194] Starting dynamic subtest: vcs1
[296.474193] Dynamic subtest vcs1: SUCCESS (0.503s)
[296.485860] Starting dynamic subtest: vecs0
[296.979464] Dynamic subtest vecs0: SUCCESS (0.503s)
[296.991896] Starting dynamic subtest: ccs0
[297.485195] Dynamic subtest ccs0: SUCCESS (0.504s)
[297.487926] Subtest idle: SUCCESS (3.105s)
[297.566903] [046/130] (719s left) gem_exec_schedule (timeslicing)
[297.638402] Starting subtest: timeslicing
[297.640833] Starting dynamic subtest: rcs0
[297.657325] Dynamic subtest rcs0: SUCCESS (0.026s)
[297.668999] Starting dynamic subtest: bcs0
[297.681348] Dynamic subtest bcs0: SUCCESS (0.023s)
[297.692499] Starting dynamic subtest: vcs0
[297.704657] Dynamic subtest vcs0: SUCCESS (0.022s)
[297.716539] Starting dynamic subtest: vcs1
[297.726253] Dynamic subtest vcs1: SUCCESS (0.022s)
[297.735169] Starting dynamic subtest: vecs0
[297.747480] Dynamic subtest vecs0: SUCCESS (0.021s)
[297.760006] Starting dynamic subtest: ccs0
[297.772798] Dynamic subtest ccs0: SUCCESS (0.025s)
[297.775215] Subtest timeslicing: SUCCESS (0.142s)
[299.278621] [047/130] (717s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[299.388149] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-fullscreen
[299.392390] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[299.479268] [048/130] (717s left) kms_invalid_mode (zero-vdisplay)
[299.576026] Starting subtest: zero-vdisplay
[299.578172] Starting dynamic subtest: pipe-A-eDP-1
[299.857339] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.281s)
[299.867243] Starting dynamic subtest: pipe-B-eDP-1
[299.869153] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[299.878612] Starting dynamic subtest: pipe-C-eDP-1
[299.880980] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[299.889525] Starting dynamic subtest: pipe-D-eDP-1
[299.891879] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[299.894019] Subtest zero-vdisplay: SUCCESS (0.286s)
[300.656890] [049/130] (716s left) kms_dsc (dsc-with-bpc)
[300.749698] Starting subtest: dsc-with-bpc
[300.780558] Subtest dsc-with-bpc: SKIP (0.001s)
[300.826214] [050/130] (716s left) kms_ccs (crc-primary-suspend-y-tiled-gen12-rc-ccs-cc)
[300.916000] Starting subtest: crc-primary-suspend-y-tiled-gen12-rc-ccs-cc
[300.918164] Starting dynamic subtest: pipe-A-eDP-1
[300.927049] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[300.939191] Starting dynamic subtest: pipe-B-eDP-1
[300.948303] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[300.957759] Starting dynamic subtest: pipe-C-eDP-1
[300.966097] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[300.974345] Starting dynamic subtest: pipe-D-eDP-1
[300.982820] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[300.987050] Subtest crc-primary-suspend-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[303.220893] [051/130] (714s left) kms_psr (psr2-sprite-mmap-cpu)
[303.329826] Starting subtest: psr2-sprite-mmap-cpu
[303.332110] Starting dynamic subtest: eDP-1
[305.102447] Dynamic subtest eDP-1: SUCCESS (1.771s)
[305.104695] Subtest psr2-sprite-mmap-cpu: SUCCESS (1.772s)
[305.961904] [052/130] (711s left) kms_psr (pr-primary-blt)
[306.063215] Starting subtest: pr-primary-blt
[306.067453] Subtest pr-primary-blt: SKIP (0.000s)
[306.135109] [053/130] (711s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[306.239967] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[307.097252] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SUCCESS (0.859s)
[307.289080] [054/130] (710s left) kms_big_fb (y-tiled-32bpp-rotate-90)
[307.400888] Starting subtest: y-tiled-32bpp-rotate-90
[307.405442] Subtest y-tiled-32bpp-rotate-90: SKIP (0.000s)
[307.497703] [055/130] (710s left) gem_mmap_gtt (ptrace)
[307.566602] Subtest ptrace: SKIP (0.000s)
[307.634941] [056/130] (709s left) perf (invalid-create-userspace-config)
[307.744805] Starting subtest: invalid-create-userspace-config
[307.747102] Subtest invalid-create-userspace-config: SUCCESS (0.001s)
[307.788974] [057/130] (709s left) kms_cursor_crc (cursor-rapid-movement-32x32)
[307.935567] Starting subtest: cursor-rapid-movement-32x32
[307.981293] Subtest cursor-rapid-movement-32x32: SKIP (0.039s)
[308.255121] [058/130] (709s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-plflip-blt)
[308.349087] Starting subtest: fbcpsr-1p-primscrn-shrfb-plflip-blt
[315.735415] Subtest fbcpsr-1p-primscrn-shrfb-plflip-blt: SUCCESS (7.388s)
[315.893861] [059/130] (701s left) kms_cursor_legacy (single-bo)
[316.022014] Starting subtest: single-bo
[316.024034] Starting dynamic subtest: pipe-A
[321.455104] Dynamic subtest pipe-A: SUCCESS (5.379s)
[321.466206] Starting dynamic subtest: pipe-B
[326.836565] Dynamic subtest pipe-B: SUCCESS (5.379s)
[326.847965] Starting dynamic subtest: pipe-C
[332.217201] Dynamic subtest pipe-C: SUCCESS (5.377s)
[332.227655] Starting dynamic subtest: pipe-D
[337.600362] Dynamic subtest pipe-D: SUCCESS (5.379s)
[337.611382] Starting dynamic subtest: all-pipes
[342.989453] Dynamic subtest all-pipes: SUCCESS (5.386s)
[342.991828] Subtest single-bo: SUCCESS (26.910s)
[343.081742] [060/130] (674s left) kms_getfb (getfb-handle-zero)
[343.179832] Starting subtest: getfb-handle-zero
[343.182286] Subtest getfb-handle-zero: SUCCESS (0.000s)
[343.295358] [061/130] (674s left) kms_setmode (invalid-clone-exclusive-crtc)
[343.340352] Starting subtest: invalid-clone-exclusive-crtc
[343.346561] Subtest invalid-clone-exclusive-crtc: SKIP (0.000s)
[343.430247] [062/130] (674s left) syncobj_wait (single-wait-all-for-submit-submitted)
[343.485472] Starting subtest: single-wait-all-for-submit-submitted
[343.589390] Subtest single-wait-all-for-submit-submitted: SUCCESS (0.104s)
[343.665386] [063/130] (674s left) i915_suspend (fence-restore-untiled)
[343.722204] Starting subtest: fence-restore-untiled
[343.727044] Subtest fence-restore-untiled: SKIP (0.000s)
[343.827302] [064/130] (674s left) kms_fbcon_fbt (fbc-suspend)
[343.890165] Starting subtest: fbc-suspend
[391.968746] Subtest fbc-suspend: SUCCESS (18.707s)
[393.098077] [065/130] (624s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[393.494007] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[394.328280] [066/130] (623s left) vgem_basic (sysfs)
[394.377871] Starting subtest: sysfs
[394.379706] Subtest sysfs: SUCCESS (0.000s)
[394.434933] [067/130] (623s left) perf_pmu (all-busy-idle-check-all)
[394.478449] Starting subtest: all-busy-idle-check-all
[395.188331] Subtest all-busy-idle-check-all: FAIL (0.647s)
[396.476822] [068/130] (621s left) kms_rotation_crc (primary-y-tiled-reflect-x-270)
[396.572055] Starting subtest: primary-y-tiled-reflect-x-270
[396.576613] Subtest primary-y-tiled-reflect-x-270: SKIP (0.001s)
[396.646741] [069/130] (621s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[396.735023] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[396.747937] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.012s)
[396.830301] [070/130] (621s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[396.923972] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[396.926313] Starting dynamic subtest: pipe-A-default-mode
[397.214877] Dynamic subtest pipe-A-default-mode: SKIP (0.288s)
[397.219596] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.289s)
[397.994038] [071/130] (620s left) kms_ccs (random-ccs-data-y-tiled-ccs)
[398.091977] Starting subtest: random-ccs-data-y-tiled-ccs
[398.094335] Starting dynamic subtest: pipe-A-eDP-1
[398.120531] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[398.131821] Starting dynamic subtest: pipe-B-eDP-1
[398.156398] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[398.165570] Starting dynamic subtest: pipe-C-eDP-1
[398.189613] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[398.197954] Starting dynamic subtest: pipe-D-eDP-1
[398.222106] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[398.226596] Subtest random-ccs-data-y-tiled-ccs: SKIP (0.004s)
[400.250181] [072/130] (617s left) kms_flip (2x-blocking-absolute-wf_vblank-interruptible)
[400.341948] Starting subtest: 2x-blocking-absolute-wf_vblank-interruptible
[400.352427] Subtest 2x-blocking-absolute-wf_vblank-interruptible: SKIP (0.010s)
[400.474156] [073/130] (617s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[400.524285] Starting subtest: yf-tiled-to-vebox-yf-tiled
[400.528445] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[400.708494] [074/130] (617s left) gem_exec_create (forked)
[400.768529] Starting subtest: forked
[400.771402] Starting dynamic subtest: smem
[422.383164] Dynamic subtest smem: SUCCESS (21.582s)
[422.385981] Subtest forked: SUCCESS (21.584s)
[422.572249] [075/130] (595s left) gem_lmem_swapping (parallel-random-verify-ccs)
[422.613149] Subtest parallel-random-verify-ccs: SKIP (0.000s)
[422.657658] [076/130] (595s left) gem_partial_pwrite_pread (reads-display)
[422.723132] Subtest reads-display: SKIP (0.000s)
[422.809668] [077/130] (595s left) kms_content_protection (lic-type-0)
[422.973838] Starting subtest: lic-type-0
[426.310272] Subtest lic-type-0: SKIP (3.335s)
[427.476953] [078/130] (591s left) gen9_exec_parse (bb-start-far)
[427.541551] Subtest bb-start-far: SKIP (0.000s)
[427.626222] [079/130] (590s left) gem_create (create-ext-cpu-access-big)
[427.795191] Starting subtest: create-ext-cpu-access-big
[427.800013] Subtest create-ext-cpu-access-big: SKIP (0.001s)
[427.872186] [080/130] (590s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[428.007096] Starting subtest: cursorB-vs-flipA-toggle
[428.012354] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s)
[428.150604] [081/130] (590s left) kms_fb_coherency (memset-crc)
[428.250221] Starting subtest: memset-crc
[428.252147] Starting dynamic subtest: mmap-offset-wc
[428.623144] Dynamic subtest mmap-offset-wc: SUCCESS (0.375s)
[428.924105] Starting dynamic subtest: mmap-offset-uc
[434.941243] Dynamic subtest mmap-offset-uc: SUCCESS (6.024s)
[435.220029] Subtest memset-crc: SUCCESS (6.971s)
[436.019163] [082/130] (582s left) gem_exec_gttfill (engines)
[436.078623] Starting subtest: engines
[436.081246] Starting dynamic subtest: rcs0
[460.541511] Dynamic subtest rcs0: SUCCESS (24.454s)
[460.554739] Starting dynamic subtest: bcs0
[485.785637] Dynamic subtest bcs0: SUCCESS (25.234s)
[485.797878] Starting dynamic subtest: vcs0
[510.962384] Dynamic subtest vcs0: SUCCESS (25.182s)
[510.974805] Starting dynamic subtest: vcs1
[536.073344] Dynamic subtest vcs1: SUCCESS (25.108s)
[536.084173] Starting dynamic subtest: vecs0
[561.226113] Dynamic subtest vecs0: SUCCESS (25.147s)
[561.239000] Starting dynamic subtest: ccs0
[586.435181] Dynamic subtest ccs0: SUCCESS (25.202s)
[586.437644] Subtest engines: SUCCESS (150.337s)
[586.614818] [083/130] (432s left) gem_mmap_gtt (basic)
[586.712123] Subtest basic: SKIP (0.000s)
[586.785962] [084/130] (431s left) kms_cursor_crc (cursor-onscreen-128x42)
[587.022016] Starting subtest: cursor-onscreen-128x42
[587.065049] Subtest cursor-onscreen-128x42: SKIP (0.038s)
[587.276239] [085/130] (431s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[587.385583] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt
[587.390075] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[587.477380] [086/130] (431s left) kms_pm_dc (dc5-psr)
[588.588399] Starting subtest: dc5-psr
[591.467442] Subtest dc5-psr: SUCCESS (2.875s)
[592.250151] [087/130] (426s left) core_getstats
[592.424495] [088/130] (426s left) i915_pm_freq_api (freq-reset-multiple)
[592.486975] Starting subtest: freq-reset-multiple
[592.489284] Starting dynamic subtest: gt0
[602.135985] Dynamic subtest gt0: SUCCESS (9.643s)
[602.147100] Starting dynamic subtest: gt1
[610.438949] Dynamic subtest gt1: SUCCESS (8.300s)
[610.441829] Subtest freq-reset-multiple: SUCCESS (17.945s)
[610.518047] [089/130] (408s left) gem_ctx_persistence (engines-hang)
[610.582101] Starting subtest: engines-hang
[610.584287] Starting dynamic subtest: rcs0
[610.682542] Dynamic subtest rcs0: SUCCESS (0.100s)
[610.695423] Starting dynamic subtest: bcs0
[610.786488] Dynamic subtest bcs0: SUCCESS (0.102s)
[610.798179] Starting dynamic subtest: vcs0
[610.920791] Dynamic subtest vcs0: SUCCESS (0.132s)
[610.932212] Starting dynamic subtest: vcs1
[611.022464] Dynamic subtest vcs1: SUCCESS (0.101s)
[611.035401] Starting dynamic subtest: vecs0
[611.135722] Dynamic subtest vecs0: SUCCESS (0.111s)
[611.147795] Starting dynamic subtest: ccs0
[611.269088] Dynamic subtest ccs0: SUCCESS (0.131s)
[611.271671] Subtest engines-hang: SUCCESS (0.687s)
[611.351693] [090/130] (407s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[611.484275] Starting subtest: cursor-vs-flip-legacy
[640.772428] Subtest cursor-vs-flip-legacy: SUCCESS (29.291s)
[641.563301] [091/130] (377s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[641.672066] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[641.676404] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[641.769151] [092/130] (377s left) kms_panel_fitting (atomic-fastset)
[641.868073] Starting subtest: atomic-fastset
[641.870484] Starting dynamic subtest: pipe-A-eDP-1
[641.999595] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.129s)
[642.009833] Starting dynamic subtest: pipe-B-eDP-1
[643.222696] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.225s)
[643.233084] Starting dynamic subtest: pipe-C-eDP-1
[644.397015] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.172s)
[644.406916] Starting dynamic subtest: pipe-D-eDP-1
[645.617854] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.220s)
[645.620082] Subtest atomic-fastset: SUCCESS (3.749s)
[646.763100] [093/130] (372s left) gem_ctx_param (invalid-param-set)
[646.811482] Starting subtest: invalid-param-set
[646.814002] Subtest invalid-param-set: SUCCESS (0.000s)
[646.886718] [094/130] (372s left) kms_big_fb (yf-tiled-8bpp-rotate-0)
[646.992280] Starting subtest: yf-tiled-8bpp-rotate-0
[646.996432] Subtest yf-tiled-8bpp-rotate-0: SKIP (0.000s)
[647.081160] [095/130] (372s left) api_intel_bb (crc32)
[647.151366] Starting subtest: crc32
[647.153825] Starting dynamic subtest: rcs0-smem0
[647.428836] Dynamic subtest rcs0-smem0: SUCCESS (0.276s)
[647.442451] Starting dynamic subtest: bcs0-smem0
[647.764280] Dynamic subtest bcs0-smem0: SUCCESS (0.334s)
[647.781237] Starting dynamic subtest: vcs0-smem0
[648.092016] Dynamic subtest vcs0-smem0: SUCCESS (0.326s)
[648.106962] Starting dynamic subtest: vcs1-smem0
[648.416294] Dynamic subtest vcs1-smem0: SUCCESS (0.321s)
[648.429563] Starting dynamic subtest: vecs0-smem0
[648.722370] Dynamic subtest vecs0-smem0: SUCCESS (0.304s)
[648.736953] Starting dynamic subtest: ccs0-smem0
[649.039917] Dynamic subtest ccs0-smem0: SUCCESS (0.315s)
[649.042824] Subtest crc32: SUCCESS (1.889s)
[649.187604] [096/130] (370s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-wc)
[649.290783] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-wc
[652.889660] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (3.604s)
[654.043681] [097/130] (365s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-onoff)
[654.148518] Starting subtest: fbc-2p-scndscrn-cur-indfb-onoff
[654.152997] Subtest fbc-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[654.240874] [098/130] (365s left) syncobj_timeline (single-wait-all-available-signaled)
[654.293833] Starting subtest: single-wait-all-available-signaled
[654.296309] Subtest single-wait-all-available-signaled: SUCCESS (0.000s)
[654.401635] [099/130] (364s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-render)
[654.505264] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-render
[654.509317] Subtest psr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[654.618458] [100/130] (364s left) perf (global-sseu-config-invalid)
[654.715627] Starting subtest: global-sseu-config-invalid
[654.720132] Subtest global-sseu-config-invalid: SKIP (0.000s)
[654.763887] [101/130] (364s left) gem_sync (basic-many-each)
[654.847039] Starting subtest: basic-many-each
[657.099240] Subtest basic-many-each: SUCCESS (2.263s)
[657.284808] [102/130] (362s left) kms_psr (fbc-pr-dpms)
[657.390504] Starting subtest: fbc-pr-dpms
[657.394527] Subtest fbc-pr-dpms: SKIP (0.000s)
[657.474846] [103/130] (362s left) gem_userptr_blits (map-fixed-invalidate-busy)
[657.545389] Starting subtest: map-fixed-invalidate-busy
[657.549541] Subtest map-fixed-invalidate-busy: SKIP (0.000s)
[657.691212] [104/130] (361s left) kms_chamelium_color (ctm-green-to-red)
[657.775928] Subtest ctm-green-to-red: SKIP (0.000s)
[657.822187] [105/130] (361s left) gem_softpin (evict-snoop)
[657.891193] Starting subtest: evict-snoop
[657.895770] Subtest evict-snoop: SKIP (0.000s)
[658.028514] [106/130] (361s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-render)
[658.126378] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-render
[665.382098] Subtest psr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.259s)
[665.531595] [107/130] (354s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[665.629996] Starting subtest: x-tiled-64bpp-rotate-90
[665.649457] Subtest x-tiled-64bpp-rotate-90: SKIP (0.019s)
[665.766004] [108/130] (353s left) gem_exec_flush (basic-wb-ro-default)
[665.821444] Starting subtest: basic-wb-ro-default
[671.308402] Subtest basic-wb-ro-default: SUCCESS (5.453s)
[671.476481] [109/130] (348s left) gem_exec_whisper (basic-fds-all)
[671.550804] Starting subtest: basic-fds-all
[684.016124] Subtest basic-fds-all: SUCCESS (12.461s)
[684.203639] [110/130] (335s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-downscaling)
[684.314063] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-downscaling
[684.316405] Starting dynamic subtest: pipe-A-default-mode
[684.604411] Dynamic subtest pipe-A-default-mode: SKIP (0.284s)
[684.608870] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-downscaling: SKIP (0.286s)
[685.449767] [111/130] (334s left) gem_render_copy (y-tiled)
[685.509563] Starting subtest: y-tiled
[685.514318] Subtest y-tiled: SKIP (0.000s)
[685.696043] [112/130] (334s left) kms_psr (psr2-primary-page-flip)
[685.785048] Starting subtest: psr2-primary-page-flip
[685.787320] Starting dynamic subtest: eDP-1
[687.531955] Dynamic subtest eDP-1: SUCCESS (1.743s)
[687.534240] Subtest psr2-primary-page-flip: SUCCESS (1.744s)
[688.402065] [113/130] (331s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[688.453747] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[688.455855] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.000s)
[688.549402] [114/130] (331s left) syncobj_timeline (single-wait-all-for-submit-signaled)
[688.611606] Starting subtest: single-wait-all-for-submit-signaled
[688.613911] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[688.718973] [115/130] (331s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-move)
[688.814829] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-move
[696.038873] Subtest fbcpsr-1p-primscrn-cur-indfb-move: SUCCESS (7.227s)
[696.194686] [116/130] (323s left) gem_exec_params (dr1-dirt)
[696.261260] Starting subtest: DR1-dirt
[696.263652] Subtest DR1-dirt: SUCCESS (0.000s)
[696.366878] [117/130] (323s left) kms_plane_lowres (tiling-4)
[696.458946] Starting subtest: tiling-4
[696.461354] Starting dynamic subtest: pipe-A-eDP-1
[696.466077] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[696.753165] Starting dynamic subtest: pipe-B-eDP-1
[696.757830] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[696.767781] Starting dynamic subtest: pipe-C-eDP-1
[696.773195] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[696.785308] Starting dynamic subtest: pipe-D-eDP-1
[696.789606] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[696.793949] Subtest tiling-4: SKIP (0.290s)
[697.531277] [118/130] (322s left) gem_spin_batch (legacy-resubmit)
[697.588941] Starting subtest: legacy-resubmit
[697.591276] Starting dynamic subtest: default
[697.595660] Dynamic subtest default: SUCCESS (0.006s)
[697.606052] Starting dynamic subtest: render
[697.608334] Dynamic subtest render: SUCCESS (0.001s)
[697.618507] Starting dynamic subtest: bsd
[697.620451] Dynamic subtest bsd: SUCCESS (0.004s)
[697.629725] Starting dynamic subtest: bsd1
[697.631746] Dynamic subtest bsd1: SUCCESS (0.004s)
[697.640817] Starting dynamic subtest: bsd2
[697.642775] Dynamic subtest bsd2: SUCCESS (0.001s)
[697.651900] Starting dynamic subtest: blt
[697.653860] Dynamic subtest blt: SUCCESS (0.003s)
[697.663037] Starting dynamic subtest: vebox
[697.664966] Dynamic subtest vebox: SUCCESS (0.003s)
[697.666883] Subtest legacy-resubmit: SUCCESS (0.028s)
[697.850129] [119/130] (322s left) kms_color (ctm-0-25)
[697.937036] Starting subtest: ctm-0-25
[697.939378] Starting dynamic subtest: pipe-A-eDP-1
[701.408431] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.469s)
[701.421603] Starting dynamic subtest: pipe-B-eDP-1
[705.586749] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.179s)
[705.598615] Starting dynamic subtest: pipe-C-eDP-1
[709.597058] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.010s)
[709.609050] Starting dynamic subtest: pipe-D-eDP-1
[713.600305] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.001s)
[713.602744] Subtest ctm-0-25: SUCCESS (15.661s)
[714.422027] [120/130] (305s left) gem_mmap_gtt (basic-write-read)
[714.482519] Subtest basic-write-read: SKIP (0.000s)
[714.566409] [121/130] (305s left) syncobj_timeline (invalid-single-wait-unsubmitted)
[714.626043] Starting subtest: invalid-single-wait-unsubmitted
[714.628397] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[714.741622] [122/130] (305s left) kms_psr (fbc-psr-cursor-mmap-cpu)
[714.837836] Starting subtest: fbc-psr-cursor-mmap-cpu
[714.840123] Starting dynamic subtest: eDP-1
[714.844310] Dynamic subtest eDP-1: SKIP (0.000s)
[714.848550] Subtest fbc-psr-cursor-mmap-cpu: SKIP (0.001s)
[714.911976] [123/130] (305s left) kms_selftest (drm_format_helper)
[714.953796] Starting subtest: drm_format_helper
[714.986248] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8
[715.163770] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8: SUCCESS (0.175s)
[715.173599] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332
[715.295976] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332: SUCCESS (0.132s)
[715.307496] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565
[715.425813] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565: SUCCESS (0.129s)
[715.435981] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555
[715.502618] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555: SUCCESS (0.077s)
[715.514686] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555
[715.613122] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555: SUCCESS (0.111s)
[715.623846] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551
[715.767076] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551: SUCCESS (0.151s)
[715.776978] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888
[715.858585] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888: SUCCESS (0.091s)
[715.869803] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888
[715.995598] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888: SUCCESS (0.136s)
[716.004750] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888
[716.128924] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888: SUCCESS (0.132s)
[716.139907] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010
[716.255983] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010: SUCCESS (0.127s)
[716.265485] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010
[716.379570] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010: SUCCESS (0.124s)
[716.389080] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_mono
[716.505942] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_mono: SUCCESS (0.125s)
[716.515702] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_swab
[716.638060] Dynamic subtest drm_format_helper_test-drm_test_fb_swab: SUCCESS (0.130s)
[716.649892] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888
[716.774196] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888: SUCCESS (0.135s)
[716.785964] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888
[716.883787] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888: SUCCESS (0.110s)
[716.895666] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_clip_offset
[717.016946] Dynamic subtest drm_format_helper_test-drm_test_fb_clip_offset: SUCCESS (0.131s)
[717.026662] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_memcpy
[717.143604] Dynamic subtest drm_format_helper_test-drm_test_fb_memcpy: SUCCESS (0.125s)
[717.145518] Subtest drm_format_helper: SUCCESS (2.188s)
[717.335236] [124/130] (303s left) gem_wait (invalid-buf)
[717.392191] Starting subtest: invalid-buf
[717.394522] Subtest invalid-buf: SUCCESS (0.000s)
[717.631700] [125/130] (302s left) kms_cursor_legacy (flip-vs-cursor-crc-legacy)
[717.749826] Starting subtest: flip-vs-cursor-crc-legacy
[718.372539] Subtest flip-vs-cursor-crc-legacy: SUCCESS (0.624s)
[719.242262] [126/130] (301s left) kms_getfb (getfb2-into-addfb2)
[719.349114] Starting subtest: getfb2-into-addfb2
[719.351472] Subtest getfb2-into-addfb2: SUCCESS (0.000s)
[719.449853] [127/130] (301s left) gem_mmap_offset (open-flood)
[719.495484] Starting subtest: open-flood
[740.986472] Subtest open-flood: SUCCESS (21.489s)
[741.118710] [128/130] (279s left) prime_vgem (fence-wait)
[741.204362] Starting subtest: fence-wait
[741.205973] Starting dynamic subtest: rcs0
[742.270497] Dynamic subtest rcs0: SUCCESS (1.065s)
[742.283542] Starting dynamic subtest: bcs0
[743.345434] Dynamic subtest bcs0: SUCCESS (1.074s)
[743.358432] Starting dynamic subtest: vcs0
[744.389355] Dynamic subtest vcs0: SUCCESS (1.043s)
[744.402414] Starting dynamic subtest: vcs1
[745.433223] Dynamic subtest vcs1: SUCCESS (1.042s)
[745.446161] Starting dynamic subtest: vecs0
[746.476556] Dynamic subtest vecs0: SUCCESS (1.042s)
[746.489803] Starting dynamic subtest: ccs0
[747.520805] Dynamic subtest ccs0: SUCCESS (1.041s)
[747.523265] Subtest fence-wait: SUCCESS (6.313s)
[747.646649] [129/130] (272s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[747.749864] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[747.754533] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[747.869322] [130/130] (272s left) gem_vm_create (isolation)
[747.934550] Starting subtest: isolation
[747.943126] Subtest isolation: SUCCESS (0.009s)
[748.071200] 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.