[90.544401] Initializing watchdogs
[90.544458]   /dev/watchdog0
[90.559159] [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]
[90.563726] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[90.566120] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[90.569456] [001/133] (960s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-wc)
[90.709789] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-wc
[94.770069] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (4.064s)
[95.953224] [002/133] (955s left) gem_flink_basic (bad-open)
[96.009987] Starting subtest: bad-open
[96.012192] Subtest bad-open: SUCCESS (0.000s)
[96.095097] [003/133] (955s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-move)
[96.197049] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-move
[96.201471] Subtest fbcpsr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[96.292897] [004/133] (954s left) gem_ppgtt (shrink-vs-evict-pinned)
[96.361646] Starting subtest: shrink-vs-evict-pinned
[101.870355] Subtest shrink-vs-evict-pinned: SUCCESS (5.507s)
[101.956041] [005/133] (949s left) gem_exec_schedule (implicit-boths)
[102.026347] Starting subtest: implicit-boths
[102.028363] Starting dynamic subtest: rcs0
[102.204944] Dynamic subtest rcs0: SUCCESS (0.184s)
[102.217337] Starting dynamic subtest: vcs0
[102.319056] Dynamic subtest vcs0: SUCCESS (0.114s)
[102.326860] Starting dynamic subtest: vcs1
[102.432529] Dynamic subtest vcs1: SUCCESS (0.113s)
[102.442912] Starting dynamic subtest: vecs0
[102.538057] Dynamic subtest vecs0: SUCCESS (0.105s)
[102.549322] Starting dynamic subtest: ccs0
[102.661959] Dynamic subtest ccs0: SUCCESS (0.123s)
[102.665038] Subtest implicit-boths: SUCCESS (0.641s)
[103.995075] [006/133] (947s left) vgem_slow (nohang)
[104.066558] Starting subtest: nohang
[114.292575] Subtest nohang: SUCCESS (10.224s)
[114.365955] [FACT vgem_slow (nohang)] new: kernel.kmod_is_loaded.vgem: true
[114.370263] [007/133] (936s left) gem_exec_schedule (u-submit-late-slice)
[114.442668] Starting subtest: u-submit-late-slice
[114.445110] Starting dynamic subtest: rcs0
[114.485996] Dynamic subtest rcs0: SUCCESS (0.050s)
[114.497360] Starting dynamic subtest: bcs0
[114.537185] Dynamic subtest bcs0: SUCCESS (0.051s)
[114.548525] Starting dynamic subtest: vcs0
[114.582739] Dynamic subtest vcs0: SUCCESS (0.045s)
[114.593749] Starting dynamic subtest: vcs1
[114.627156] Dynamic subtest vcs1: SUCCESS (0.045s)
[114.638358] Starting dynamic subtest: vecs0
[114.672134] Dynamic subtest vecs0: SUCCESS (0.044s)
[114.683516] Starting dynamic subtest: ccs0
[114.720649] Dynamic subtest ccs0: SUCCESS (0.048s)
[114.723220] Subtest u-submit-late-slice: SUCCESS (0.284s)
[116.024817] [008/133] (935s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-rc-ccs)
[116.120502] Starting subtest: missing-ccs-buffer-y-tiled-gen12-rc-ccs
[116.122659] Starting dynamic subtest: pipe-A-eDP-1
[116.143987] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[116.152387] Starting dynamic subtest: pipe-B-eDP-1
[116.173320] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[116.180673] Starting dynamic subtest: pipe-C-eDP-1
[116.201149] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[116.208181] Starting dynamic subtest: pipe-D-eDP-1
[116.229600] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[116.234067] Subtest missing-ccs-buffer-y-tiled-gen12-rc-ccs: SKIP (0.003s)
[118.424762] [009/133] (932s left) kms_cursor_legacy (torture-move)
[118.543467] Starting subtest: torture-move
[118.545355] Starting dynamic subtest: pipe-A
[124.356245] Dynamic subtest pipe-A: SUCCESS (5.811s)
[124.370079] Starting dynamic subtest: pipe-B
[131.202947] Dynamic subtest pipe-B: SUCCESS (6.846s)
[131.220909] Starting dynamic subtest: pipe-C
[136.996853] Dynamic subtest pipe-C: SUCCESS (5.794s)
[137.011591] Starting dynamic subtest: pipe-D
[144.454449] Dynamic subtest pipe-D: SUCCESS (7.457s)
[144.464546] Starting dynamic subtest: all-pipes
[151.009037] Dynamic subtest all-pipes: SUCCESS (6.553s)
[151.011846] Subtest torture-move: SUCCESS (32.465s)
[151.166607] [010/133] (900s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[151.282187] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-cpu
[151.286607] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[151.382184] [011/133] (900s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-wc)
[151.488597] Starting subtest: psr-rgb101010-draw-mmap-wc
[158.611912] Subtest psr-rgb101010-draw-mmap-wc: SUCCESS (7.126s)
[158.726670] [012/133] (892s left) i915_drm_fdinfo (virtual-busy-idle)
[158.766721] Starting subtest: virtual-busy-idle
[158.771319] Subtest virtual-busy-idle: SKIP (0.000s)
[158.838642] [013/133] (892s left) kms_vblank (ts-continuation-modeset)
[158.932869] Starting subtest: ts-continuation-modeset
[158.935336] Starting dynamic subtest: pipe-A-eDP-1
[160.402278] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.468s)
[160.415814] Starting dynamic subtest: pipe-D-eDP-1
[162.565033] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.159s)
[162.567354] Subtest ts-continuation-modeset: SUCCESS (3.630s)
[163.368672] [014/133] (888s left) i915_pm_rps (thresholds-idle)
[163.431359] Starting subtest: thresholds-idle
[163.436032] Subtest thresholds-idle: SKIP (0.001s)
[163.523215] [015/133] (888s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs-cc)
[163.615834] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs-cc
[163.618173] Starting dynamic subtest: pipe-A-eDP-1
[163.640848] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[163.651651] Starting dynamic subtest: pipe-B-eDP-1
[163.673535] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[163.681061] Starting dynamic subtest: pipe-C-eDP-1
[163.702146] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[163.709959] Starting dynamic subtest: pipe-D-eDP-1
[163.731332] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[163.735243] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs-cc: SKIP (0.003s)
[165.929258] [016/133] (885s left) gem_ctx_bad_destroy (invalid-default-ctx)
[165.990700] Starting subtest: invalid-default-ctx
[165.993169] Subtest invalid-default-ctx: SUCCESS (0.000s)
[166.070985] [017/133] (885s left) gem_fence_thrash (bo-write-verify-threaded-y)
[166.102922] Starting subtest: bo-write-verify-threaded-y
[166.132933] Subtest bo-write-verify-threaded-y: SKIP (0.025s)
[166.202065] [018/133] (885s left) kms_frontbuffer_tracking (fbcpsr-farfromfence-mmap-gtt)
[166.311146] Starting subtest: fbcpsr-farfromfence-mmap-gtt
[166.315443] Subtest fbcpsr-farfromfence-mmap-gtt: SKIP (0.000s)
[166.421565] [019/133] (885s left) kms_color_pipeline (plane-lut1d-lut1d)
[166.527455] Starting subtest: plane-lut1d-lut1d
[166.684978] Starting dynamic subtest: pipe-A-plane-0
[167.013286] Dynamic subtest pipe-A-plane-0: FAIL (0.188s)
[167.020897] Starting dynamic subtest: pipe-A-plane-1
[167.222059] Dynamic subtest pipe-A-plane-1: FAIL (0.167s)
[167.230374] Starting dynamic subtest: pipe-A-plane-2
[167.438198] Dynamic subtest pipe-A-plane-2: FAIL (0.186s)
[168.464765] Starting dynamic subtest: pipe-B-plane-0
[168.926745] Dynamic subtest pipe-B-plane-0: FAIL (0.198s)
[168.936927] Starting dynamic subtest: pipe-B-plane-1
[169.247433] Dynamic subtest pipe-B-plane-1: FAIL (0.199s)
[169.257245] Starting dynamic subtest: pipe-B-plane-2
[169.545257] Dynamic subtest pipe-B-plane-2: FAIL (0.189s)
[170.248230] Starting dynamic subtest: pipe-C-plane-0
[170.769140] Dynamic subtest pipe-C-plane-0: FAIL (0.152s)
[170.778538] Starting dynamic subtest: pipe-C-plane-1
[171.173819] Dynamic subtest pipe-C-plane-1: FAIL (0.635s)
[171.183860] Starting dynamic subtest: pipe-C-plane-2
[171.577073] Dynamic subtest pipe-C-plane-2: FAIL (0.172s)
[172.377132] Starting dynamic subtest: pipe-D-plane-0
[172.978264] Dynamic subtest pipe-D-plane-0: FAIL (0.141s)
[172.990723] Starting dynamic subtest: pipe-D-plane-1
[173.461699] Dynamic subtest pipe-D-plane-1: FAIL (0.626s)
[173.470207] Starting dynamic subtest: pipe-D-plane-2
[173.910577] Dynamic subtest pipe-D-plane-2: FAIL (0.571s)
[174.007786] Subtest plane-lut1d-lut1d: FAIL (7.479s)
[174.862200] [020/133] (876s left) gem_ctx_shared (create-shared-gtt)
[174.934297] Starting subtest: create-shared-gtt
[177.084315] Subtest create-shared-gtt: SUCCESS (2.151s)
[177.443850] [021/133] (874s left) gem_exec_create (basic)
[177.515441] Starting subtest: basic
[177.517808] Starting dynamic subtest: smem
[180.888441] Dynamic subtest smem: SUCCESS (3.370s)
[180.891124] Subtest basic: SUCCESS (3.372s)
[181.070062] [022/133] (870s left) syncobj_timeline (single-wait-signaled)
[181.120781] Starting subtest: single-wait-signaled
[181.123088] Subtest single-wait-signaled: SUCCESS (0.000s)
[181.215775] [023/133] (870s left) kms_chamelium_audio (dp-audio)
[181.597948] Subtest dp-audio: SKIP (0.000s)
[182.452917] [024/133] (869s left) gem_pwrite_snooped
[182.576717] [025/133] (869s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[182.677812] Starting subtest: fbc-rgb101010-draw-render
[187.280787] Subtest fbc-rgb101010-draw-render: SUCCESS (4.607s)
[188.463751] [026/133] (863s left) perf (gen12-invalid-class-instance)
[188.601141] Starting subtest: gen12-invalid-class-instance
[188.611972] Subtest gen12-invalid-class-instance: SUCCESS (0.010s)
[188.652985] [027/133] (863s left) kms_psr (fbc-psr-primary-page-flip)
[188.758057] Starting subtest: fbc-psr-primary-page-flip
[188.760359] Starting dynamic subtest: eDP-1
[188.764742] Dynamic subtest eDP-1: SKIP (0.000s)
[188.769266] Subtest fbc-psr-primary-page-flip: SKIP (0.001s)
[188.829636] [028/133] (863s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[188.932290] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip
[188.936318] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[189.034636] [029/133] (863s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-fullscreen)
[189.132472] Starting subtest: fbc-2p-primscrn-spr-indfb-fullscreen
[189.137085] Subtest fbc-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[189.222686] [030/133] (863s left) gem_exec_params (sol-reset-invalid)
[189.277486] Starting subtest: sol-reset-invalid
[189.282450] Subtest sol-reset-invalid: SUCCESS (0.003s)
[190.993161] [031/133] (861s left) gem_mmap_gtt (zero-extend)
[191.050648] Subtest zero-extend: SKIP (0.000s)
[191.109633] [032/133] (861s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0)
[191.214087] Starting subtest: linear-max-hw-stride-64bpp-rotate-0
[192.258385] Subtest linear-max-hw-stride-64bpp-rotate-0: SUCCESS (1.045s)
[192.368640] [033/133] (859s left) kms_rotation_crc (primary-x-tiled-reflect-x-0)
[192.458765] Starting subtest: primary-x-tiled-reflect-x-0
[199.336379] Subtest primary-x-tiled-reflect-x-0: SUCCESS (6.879s)
[200.503732] [034/133] (851s left) kms_big_fb (linear-32bpp-rotate-0)
[200.613417] Starting subtest: linear-32bpp-rotate-0
[202.126037] Subtest linear-32bpp-rotate-0: SUCCESS (1.512s)
[202.257475] [035/133] (850s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[202.344217] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[202.345945] Starting dynamic subtest: pipe-A-default-mode
[205.317462] Dynamic subtest pipe-A-default-mode: SUCCESS (2.971s)
[205.319887] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SUCCESS (2.972s)
[206.147695] [036/133] (846s left) kms_ccs (crc-primary-basic-yf-tiled-ccs)
[206.235946] Starting subtest: crc-primary-basic-yf-tiled-ccs
[206.238279] Starting dynamic subtest: pipe-A-eDP-1
[206.261929] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[206.271281] Starting dynamic subtest: pipe-B-eDP-1
[206.293125] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[206.300807] Starting dynamic subtest: pipe-C-eDP-1
[206.322531] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[206.330331] Starting dynamic subtest: pipe-D-eDP-1
[206.352126] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[206.356195] Subtest crc-primary-basic-yf-tiled-ccs: SKIP (0.005s)
[208.509322] [037/133] (844s left) kms_plane (pixel-format-x-tiled-modifier)
[208.608248] Starting subtest: pixel-format-x-tiled-modifier
[208.634423] Starting dynamic subtest: pipe-A-plane-0
[209.267375] Dynamic subtest pipe-A-plane-0: SUCCESS (0.633s)
[209.276113] Starting dynamic subtest: pipe-A-plane-3
[209.701907] Dynamic subtest pipe-A-plane-3: SUCCESS (0.433s)
[209.709866] Starting dynamic subtest: pipe-A-plane-5
[209.713430] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[210.794549] Starting dynamic subtest: pipe-B-plane-0
[211.408470] Dynamic subtest pipe-B-plane-0: SUCCESS (0.622s)
[211.417274] Starting dynamic subtest: pipe-B-plane-3
[211.830328] Dynamic subtest pipe-B-plane-3: SUCCESS (0.422s)
[211.838472] Starting dynamic subtest: pipe-B-plane-5
[211.841614] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[212.156077] Subtest pixel-format-x-tiled-modifier: SUCCESS (3.549s)
[212.998323] [038/133] (839s left) kms_psr (psr2-primary-render)
[213.084113] Starting subtest: psr2-primary-render
[213.086394] Starting dynamic subtest: eDP-1
[214.788494] Dynamic subtest eDP-1: SUCCESS (1.705s)
[214.790900] Subtest psr2-primary-render: SUCCESS (1.706s)
[215.675474] [039/133] (836s left) kms_cursor_legacy (cursor-vs-flip-atomic)
[215.791469] Starting subtest: cursor-vs-flip-atomic
[245.196277] Subtest cursor-vs-flip-atomic: SUCCESS (29.403s)
[245.989088] [040/133] (806s left) gem_ctx_freq (sysfs)
[246.059299] Starting subtest: sysfs
[246.061591] Starting dynamic subtest: gt0
[251.564153] Dynamic subtest gt0: SUCCESS (5.504s)
[251.574468] Starting dynamic subtest: gt1
[292.885994] Dynamic subtest gt1: SUCCESS (41.319s)
[292.888704] Subtest sysfs: SUCCESS (46.826s)
[292.998767] [041/133] (759s left) gem_lmem_swapping (heavy-verify-multi)
[293.040654] Subtest heavy-verify-multi: SKIP (0.000s)
[293.083520] [042/133] (759s left) kms_plane_multiple (tiling-none)
[293.175859] Starting subtest: tiling-none
[293.178105] Starting dynamic subtest: pipe-A-eDP-1
[296.839425] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.662s)
[296.848831] Starting dynamic subtest: pipe-B-eDP-1
[301.353781] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.513s)
[301.363195] Starting dynamic subtest: pipe-C-eDP-1
[305.685637] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.333s)
[305.697672] Starting dynamic subtest: pipe-D-eDP-1
[310.039254] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.351s)
[310.041514] Subtest tiling-none: SUCCESS (16.863s)
[310.840361] [043/133] (741s left) gem_partial_pwrite_pread (reads)
[310.899194] Subtest reads: SKIP (0.000s)
[310.981334] [044/133] (741s left) kms_ccs (crc-primary-suspend-4-tiled-lnl-ccs)
[311.067968] Starting subtest: crc-primary-suspend-4-tiled-lnl-ccs
[311.072678] Subtest crc-primary-suspend-4-tiled-lnl-ccs: SKIP (0.000s)
[312.174377] [045/133] (740s left) core_getclient (basic)
[312.240197] Starting subtest: basic
[312.242163] Subtest basic: SUCCESS (0.000s)
[312.330376] [046/133] (740s left) gem_eio (create)
[312.544176] Starting subtest: create
[312.856435] Subtest create: SUCCESS (0.312s)
[312.997412] [047/133] (739s left) gem_exec_schedule (smoketest)
[313.181492] Starting subtest: smoketest
[313.184585] Starting dynamic subtest: rcs0
[318.724045] Dynamic subtest rcs0: SUCCESS (5.489s)
[318.735550] Starting dynamic subtest: bcs0
[324.248397] Dynamic subtest bcs0: SUCCESS (5.528s)
[324.264976] Starting dynamic subtest: vcs0
[329.746112] Dynamic subtest vcs0: SUCCESS (5.490s)
[329.757059] Starting dynamic subtest: vcs1
[335.244721] Dynamic subtest vcs1: SUCCESS (5.501s)
[335.261732] Starting dynamic subtest: vecs0
[340.736916] Dynamic subtest vecs0: SUCCESS (5.489s)
[340.748761] Starting dynamic subtest: ccs0
[346.274221] Dynamic subtest ccs0: SUCCESS (5.533s)
[346.276472] Subtest smoketest: SUCCESS (33.043s)
[346.418220] [048/133] (706s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[346.550441] Starting subtest: basic-flip-after-cursor-legacy
[347.482258] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.932s)
[348.297454] [049/133] (704s left) kms_cursor_edge_walk (64x64-top-edge)
[348.399458] Starting subtest: 64x64-top-edge
[348.401844] Starting dynamic subtest: pipe-A-eDP-1
[351.862928] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.461s)
[351.872548] Starting dynamic subtest: pipe-D-eDP-1
[354.996178] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.133s)
[354.998449] Subtest 64x64-top-edge: SUCCESS (6.598s)
[355.790682] [050/133] (697s left) syncobj_eventfd (binary-wait-before-signal)
[355.842205] Starting subtest: binary-wait-before-signal
[355.873176] Subtest binary-wait-before-signal: SUCCESS (0.031s)
[355.961062] [051/133] (697s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[356.023573] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[356.025781] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[356.117535] [052/133] (697s left) core_auth (basic-auth)
[356.175599] Starting subtest: basic-auth
[356.177778] Subtest basic-auth: SUCCESS (0.001s)
[356.278471] [053/133] (696s left) kms_flip (flip-vs-panning)
[356.372886] Starting subtest: flip-vs-panning
[356.375306] Starting dynamic subtest: A-eDP1
[365.108285] Dynamic subtest A-eDP1: SUCCESS (8.735s)
[365.121552] Starting dynamic subtest: B-eDP1
[373.839227] Dynamic subtest B-eDP1: SUCCESS (8.728s)
[373.853283] Starting dynamic subtest: C-eDP1
[382.548056] Dynamic subtest C-eDP1: SUCCESS (8.707s)
[382.558181] Starting dynamic subtest: D-eDP1
[391.290307] Dynamic subtest D-eDP1: SUCCESS (8.741s)
[391.294849] Subtest flip-vs-panning: SUCCESS (34.923s)
[392.446483] [054/133] (660s left) kms_cursor_legacy (2x-nonblocking-modeset-vs-cursor-atomic)
[392.564266] Starting subtest: 2x-nonblocking-modeset-vs-cursor-atomic
[392.568618] Subtest 2x-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[392.709962] [055/133] (660s left) gem_cs_tlb (engines)
[392.761226] Starting subtest: engines
[392.763574] Starting dynamic subtest: rcs0
[394.917401] Dynamic subtest rcs0: SUCCESS (2.152s)
[394.928163] Starting dynamic subtest: bcs0
[397.070522] Dynamic subtest bcs0: SUCCESS (2.151s)
[397.079997] Starting dynamic subtest: vcs0
[399.223487] Dynamic subtest vcs0: SUCCESS (2.153s)
[399.232662] Starting dynamic subtest: vcs1
[401.377193] Dynamic subtest vcs1: SUCCESS (2.152s)
[401.388188] Starting dynamic subtest: vecs0
[403.530985] Dynamic subtest vecs0: SUCCESS (2.152s)
[403.542645] Starting dynamic subtest: ccs0
[405.685739] Dynamic subtest ccs0: SUCCESS (2.154s)
[405.688157] Subtest engines: SUCCESS (12.922s)
[405.804146] [056/133] (647s left) gem_vm_create (isolation)
[405.872381] Starting subtest: isolation
[405.892503] Subtest isolation: SUCCESS (0.020s)
[406.003519] [057/133] (647s left) kms_frontbuffer_tracking (psr-1p-pri-indfb-multidraw)
[406.118148] Starting subtest: psr-1p-pri-indfb-multidraw
[429.207288] Subtest psr-1p-pri-indfb-multidraw: SUCCESS (23.093s)
[429.330945] [058/133] (624s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-wc)
[429.436707] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-wc
[429.440218] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[429.536552] [059/133] (623s left) kms_content_protection (dp-mst-type-0-suspend-resume)
[429.685579] Starting subtest: dp-mst-type-0-suspend-resume
[429.689664] Subtest dp-mst-type-0-suspend-resume: SKIP (0.000s)
[429.830371] [060/133] (623s left) gen9_exec_parse (bb-start-param)
[429.892117] Subtest bb-start-param: SKIP (0.000s)
[429.958554] [061/133] (623s left) kms_flip (flip-vs-dpms-off-vs-modeset)
[430.050320] Starting subtest: flip-vs-dpms-off-vs-modeset
[430.052093] Starting dynamic subtest: A-eDP1
[433.697359] Dynamic subtest A-eDP1: SUCCESS (3.646s)
[433.706781] Starting dynamic subtest: B-eDP1
[436.909984] Dynamic subtest B-eDP1: SUCCESS (3.211s)
[436.919911] Starting dynamic subtest: C-eDP1
[440.026104] Dynamic subtest C-eDP1: SUCCESS (3.116s)
[440.035502] Starting dynamic subtest: D-eDP1
[443.140887] Dynamic subtest D-eDP1: SUCCESS (3.114s)
[443.143259] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (13.095s)
[443.939577] [062/133] (609s left) kms_psr (fbc-psr2-cursor-mmap-gtt)
[444.035932] Starting subtest: fbc-psr2-cursor-mmap-gtt
[444.038185] Starting dynamic subtest: eDP-1
[444.042402] Dynamic subtest eDP-1: SKIP (0.000s)
[444.046679] Subtest fbc-psr2-cursor-mmap-gtt: SKIP (0.001s)
[444.121623] [063/133] (609s left) core_getversion (all-cards)
[444.169768] Starting subtest: all-cards
[444.177234] Subtest all-cards: SUCCESS (0.012s)
[444.220466] [064/133] (609s left) gem_ringfill (legacy-basic)
[449.882599] Starting subtest: legacy-basic
[449.885241] Starting dynamic subtest: default
[449.948961] Dynamic subtest default: SUCCESS (0.065s)
[449.960169] Starting dynamic subtest: render
[449.990698] Dynamic subtest render: SUCCESS (0.040s)
[450.002484] Starting dynamic subtest: bsd
[450.033305] Dynamic subtest bsd: SUCCESS (0.040s)
[450.042926] Starting dynamic subtest: bsd1
[450.073902] Dynamic subtest bsd1: SUCCESS (0.038s)
[450.086062] Starting dynamic subtest: bsd2
[450.117898] Dynamic subtest bsd2: SUCCESS (0.041s)
[450.129821] Starting dynamic subtest: blt
[450.162751] Dynamic subtest blt: SUCCESS (0.042s)
[450.175084] Starting dynamic subtest: vebox
[450.205122] Dynamic subtest vebox: SUCCESS (0.040s)
[450.207453] Subtest legacy-basic: SUCCESS (0.325s)
[450.297939] [065/133] (603s left) gem_media_fill (media-fill)
[450.353409] Subtest media-fill: SKIP (0.000s)
[450.468706] [066/133] (603s left) perf_pmu (render-node-busy)
[450.543123] Starting subtest: render-node-busy
[450.545608] Starting dynamic subtest: rcs0
[451.117808] Dynamic subtest rcs0: SUCCESS (0.574s)
[451.129200] Starting dynamic subtest: bcs0
[451.697780] Dynamic subtest bcs0: SUCCESS (0.578s)
[451.709720] Starting dynamic subtest: vcs0
[452.886897] Dynamic subtest vcs0: SUCCESS (1.186s)
[452.898639] Starting dynamic subtest: vcs1
[454.935641] Dynamic subtest vcs1: SUCCESS (2.045s)
[454.947384] Starting dynamic subtest: vecs0
[456.918658] Dynamic subtest vecs0: SUCCESS (1.981s)
[456.930497] Starting dynamic subtest: ccs0
[457.485767] Dynamic subtest ccs0: SUCCESS (0.565s)
[457.488401] Subtest render-node-busy: SUCCESS (6.943s)
[457.565129] [067/133] (596s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-plflip-blt)
[457.673553] Starting subtest: fbc-2p-primscrn-indfb-plflip-blt
[457.677768] Subtest fbc-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[457.772782] [068/133] (596s left) gem_partial_pwrite_pread (write-display)
[457.840843] Subtest write-display: SKIP (0.000s)
[457.903198] [069/133] (595s left) gem_userptr_blits (dmabuf-sync)
[457.970171] Starting subtest: dmabuf-sync
[457.974726] Subtest dmabuf-sync: SKIP (0.002s)
[458.135049] [070/133] (595s left) i915_pm_rps (basic-api)
[458.191578] Starting subtest: basic-api
[458.195712] Subtest basic-api: SKIP (0.000s)
[458.316053] [071/133] (595s left) syncobj_basic (bad-handle-to-fd)
[458.367952] Starting subtest: bad-handle-to-fd
[458.369896] Subtest bad-handle-to-fd: SUCCESS (0.000s)
[458.466215] [072/133] (595s left) sysfs_preempt_timeout (idempotent)
[458.521216] Starting subtest: idempotent
[458.523077] Starting dynamic subtest: vecs0
[458.525042] Dynamic subtest vecs0: SUCCESS (0.000s)
[458.534132] Starting dynamic subtest: ccs0
[458.535673] Dynamic subtest ccs0: SUCCESS (0.000s)
[458.543832] Starting dynamic subtest: vcs0
[458.545485] Dynamic subtest vcs0: SUCCESS (0.000s)
[458.553354] Starting dynamic subtest: bcs0
[458.554955] Dynamic subtest bcs0: SUCCESS (0.000s)
[458.563196] Starting dynamic subtest: rcs0
[458.564718] Dynamic subtest rcs0: SUCCESS (0.000s)
[458.573232] Starting dynamic subtest: vcs1
[458.575022] Dynamic subtest vcs1: SUCCESS (0.000s)
[458.576730] Subtest idempotent: SUCCESS (0.007s)
[458.629082] [073/133] (595s left) gem_pxp (reject-modify-context-protection-off-2)
[458.751453] Starting subtest: reject-modify-context-protection-off-2
[458.753774] Subtest reject-modify-context-protection-off-2: SUCCESS (0.002s)
[458.854148] [074/133] (595s left) gem_exec_reloc (basic-wc-gtt-noreloc)
[458.910122] Subtest basic-wc-gtt-noreloc: SKIP (0.000s)
[458.982536] [075/133] (595s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted-submitted)
[459.033031] Starting subtest: etime-multi-wait-for-submit-unsubmitted-submitted
[459.139056] Subtest etime-multi-wait-for-submit-unsubmitted-submitted: SUCCESS (0.104s)
[459.217027] [076/133] (594s left) syncobj_basic (bad-flags-handle-to-fd)
[459.272181] Starting subtest: bad-flags-handle-to-fd
[459.274452] Subtest bad-flags-handle-to-fd: SUCCESS (0.000s)
[459.373064] [077/133] (594s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-pwrite)
[459.480301] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-pwrite
[459.484586] Subtest psr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[459.578042] [078/133] (594s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[459.685747] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[460.731575] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.045s)
[460.861903] [079/133] (593s left) gem_exec_balancer (sliced)
[460.932052] Starting subtest: sliced
[460.935989] Subtest sliced: SKIP (0.000s)
[461.232520] [080/133] (593s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[461.341756] Starting subtest: fbc-psr2-cursor-plane-update-sf
[461.343905] Starting dynamic subtest: pipe-A-eDP-1
[461.417199] Dynamic subtest pipe-A-eDP-1: SKIP (0.078s)
[461.429022] Starting dynamic subtest: pipe-B-eDP-1
[462.512623] Dynamic subtest pipe-B-eDP-1: SKIP (1.093s)
[462.516861] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.174s)
[463.706639] [081/133] (590s left) syncobj_timeline (multi-wait-for-submit-signaled)
[463.770636] Starting subtest: multi-wait-for-submit-signaled
[463.773109] Subtest multi-wait-for-submit-signaled: SUCCESS (0.000s)
[463.872449] [082/133] (590s left) syncobj_wait (multi-wait-signaled)
[463.917656] Starting subtest: multi-wait-signaled
[463.919914] Subtest multi-wait-signaled: SUCCESS (0.000s)
[464.022447] [083/133] (590s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[464.121685] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[471.119720] Subtest fbcpsr-rgb101010-draw-pwrite: SUCCESS (7.000s)
[471.222127] [084/133] (583s left) kms_big_fb (yf-tiled-32bpp-rotate-90)
[471.319235] Starting subtest: yf-tiled-32bpp-rotate-90
[471.323446] Subtest yf-tiled-32bpp-rotate-90: SKIP (0.000s)
[471.422717] [085/133] (583s left) kms_big_fb (y-tiled-8bpp-rotate-270)
[471.529181] Starting subtest: y-tiled-8bpp-rotate-270
[471.533156] Subtest y-tiled-8bpp-rotate-270: SKIP (0.000s)
[471.629218] [086/133] (582s left) gem_exec_reloc (basic-write-read-noreloc)
[471.697773] Subtest basic-write-read-noreloc: SKIP (0.000s)
[471.765653] [087/133] (582s left) kms_dsc (dsc-with-output-formats)
[471.864760] Starting subtest: dsc-with-output-formats
[471.887240] Subtest dsc-with-output-formats: SKIP (0.000s)
[471.938947] [088/133] (582s left) kms_chamelium_color (gamma)
[472.033188] Subtest gamma: SKIP (0.000s)
[472.082505] [089/133] (582s left) kms_psr (psr-suspend)
[472.174538] Starting subtest: psr-suspend
[472.176417] Starting dynamic subtest: eDP-1
[489.932187] Dynamic subtest eDP-1: SUCCESS (3.058s)
[489.934107] Subtest psr-suspend: SUCCESS (3.059s)
[490.793401] [090/133] (563s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[490.911485] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc
[497.980698] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.074s)
[498.123726] [091/133] (556s left) kms_vblank (accuracy-idle)
[498.231492] Starting subtest: accuracy-idle
[498.233752] Starting dynamic subtest: pipe-A-eDP-1
[499.987982] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.756s)
[500.002639] Starting dynamic subtest: pipe-D-eDP-1
[502.427561] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.435s)
[502.429880] Subtest accuracy-idle: SUCCESS (4.195s)
[503.256539] [092/133] (551s left) kms_async_flips (async-flip-suspend-resume)
[503.370552] Starting subtest: async-flip-suspend-resume
[503.753464] Starting dynamic subtest: pipe-A-eDP-1
[521.482151] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.908s)
[521.862623] Starting dynamic subtest: pipe-B-eDP-1
[539.520448] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.926s)
[539.893677] Starting dynamic subtest: pipe-C-eDP-1
[557.500356] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.857s)
[557.861432] Starting dynamic subtest: pipe-D-eDP-1
[575.515632] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.904s)
[575.518468] Subtest async-flip-suspend-resume: SUCCESS (13.091s)
[576.710063] [093/133] (478s left) kms_psr (psr2-cursor-blt)
[576.811852] Starting subtest: psr2-cursor-blt
[576.814278] Starting dynamic subtest: eDP-1
[578.502323] Dynamic subtest eDP-1: SUCCESS (1.688s)
[578.505024] Subtest psr2-cursor-blt: SUCCESS (1.690s)
[579.310179] [094/133] (475s left) kms_plane (plane-position-covered)
[579.402637] Starting subtest: plane-position-covered
[579.931857] Starting dynamic subtest: pipe-A-plane-1
[581.197834] Dynamic subtest pipe-A-plane-1: SUCCESS (1.266s)
[581.208304] Starting dynamic subtest: pipe-A-plane-2
[582.522288] Dynamic subtest pipe-A-plane-2: SUCCESS (1.320s)
[582.532989] Starting dynamic subtest: pipe-A-plane-3
[583.844820] Dynamic subtest pipe-A-plane-3: SUCCESS (1.323s)
[583.857669] Starting dynamic subtest: pipe-A-plane-4
[585.098282] Dynamic subtest pipe-A-plane-4: SUCCESS (1.249s)
[585.110136] Starting dynamic subtest: pipe-A-plane-5
[585.127221] Dynamic subtest pipe-A-plane-5: SUCCESS (0.028s)
[586.354624] Starting dynamic subtest: pipe-B-plane-1
[587.659316] Dynamic subtest pipe-B-plane-1: SUCCESS (1.310s)
[587.670436] Starting dynamic subtest: pipe-B-plane-2
[588.896253] Dynamic subtest pipe-B-plane-2: SUCCESS (1.236s)
[588.907097] Starting dynamic subtest: pipe-B-plane-3
[590.143541] Dynamic subtest pipe-B-plane-3: SUCCESS (1.249s)
[590.155148] Starting dynamic subtest: pipe-B-plane-4
[591.407881] Dynamic subtest pipe-B-plane-4: SUCCESS (1.260s)
[591.418360] Starting dynamic subtest: pipe-B-plane-5
[591.434211] Dynamic subtest pipe-B-plane-5: SUCCESS (0.025s)
[591.437167] Subtest plane-position-covered: SUCCESS (12.021s)
[592.289109] [095/133] (462s left) kms_joiner (basic-force-big-joiner)
[592.393349] Starting subtest: basic-force-big-joiner
[592.398723] Subtest basic-force-big-joiner: SKIP (0.000s)
[592.464968] [096/133] (462s left) kms_cursor_legacy (2x-cursor-vs-flip-atomic)
[592.584895] Starting subtest: 2x-cursor-vs-flip-atomic
[592.589668] Subtest 2x-cursor-vs-flip-atomic: SKIP (0.001s)
[592.730732] [097/133] (462s left) kms_chamelium_hpd (vga-hpd-enable-disable-mode)
[593.117403] Subtest vga-hpd-enable-disable-mode: SKIP (0.000s)
[593.895818] [098/133] (461s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-render)
[594.004943] Starting subtest: psr-1p-offscreen-pri-indfb-draw-render
[600.956774] Subtest psr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.953s)
[601.104458] [099/133] (454s left) kms_pipe_stress (stress-xrgb8888-4tiled)
[601.203953] Starting subtest: stress-xrgb8888-4tiled
[607.154751] Subtest stress-xrgb8888-4tiled: SUCCESS (5.948s)
[607.205529] [100/133] (447s left) kms_pm_dc (dc6-psr)
[608.309786] Starting subtest: dc6-psr
[611.192756] Subtest dc6-psr: SUCCESS (2.881s)
[611.999856] [101/133] (443s left) kms_cursor_crc (cursor-alpha-transparent)
[612.090079] Starting subtest: cursor-alpha-transparent
[612.092194] Starting dynamic subtest: pipe-A-eDP-1
[612.712102] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.621s)
[612.722167] Starting dynamic subtest: pipe-D-eDP-1
[613.978930] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.265s)
[613.981879] Subtest cursor-alpha-transparent: SUCCESS (1.888s)
[614.850994] [102/133] (440s left) i915_drm_fdinfo (virtual-busy-hang)
[614.900604] Starting subtest: virtual-busy-hang
[614.906079] Subtest virtual-busy-hang: SKIP (0.000s)
[614.977133] [103/133] (440s left) kms_psr2_su (page_flip-xrgb8888)
[615.041143] Subtest page_flip-XRGB8888: SKIP (0.000s)
[615.107024] [104/133] (440s left) kms_force_connector_basic (force-connector-state)
[615.190278] Starting subtest: force-connector-state
[615.805326] Subtest force-connector-state: SUCCESS (0.615s)
[616.744274] [105/133] (438s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-downscaling)
[616.852300] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-downscaling
[616.857258] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-downscaling: SKIP (0.000s)
[616.927118] [106/133] (438s left) gem_tiled_blits (basic)
[616.990578] Subtest basic: SKIP (0.000s)
[617.060276] [107/133] (438s left) gem_userptr_blits (access-control)
[617.138264] Starting subtest: access-control
[617.143309] Subtest access-control: SKIP (0.000s)
[617.265882] [108/133] (438s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[617.362144] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[617.367305] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[617.465858] [109/133] (438s left) gem_exec_fence (syncobj-timeline-chain-engines)
[618.021959] Starting subtest: syncobj-timeline-chain-engines
[618.137694] Subtest syncobj-timeline-chain-engines: SUCCESS (0.116s)
[619.707136] [110/133] (435s left) gem_ccs (block-copy-uncompressed)
[619.771113] Starting subtest: block-copy-uncompressed
[619.774562] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem
[619.796294] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.024s)
[619.806551] Starting dynamic subtest: xmajor-uncompressed-compfmt0-smem-smem
[619.814906] Dynamic subtest xmajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.017s)
[619.824847] Starting dynamic subtest: tile4-uncompressed-compfmt0-smem-smem
[619.831284] Dynamic subtest tile4-uncompressed-compfmt0-smem-smem: SUCCESS (0.016s)
[619.841287] Starting dynamic subtest: tile64-uncompressed-compfmt0-smem-smem
[619.848444] Dynamic subtest tile64-uncompressed-compfmt0-smem-smem: SUCCESS (0.016s)
[619.851123] Subtest block-copy-uncompressed: SUCCESS (0.077s)
[619.968000] [111/133] (435s left) kms_big_fb (4-tiled-64bpp-rotate-270)
[620.078362] Starting subtest: 4-tiled-64bpp-rotate-270
[620.097850] Subtest 4-tiled-64bpp-rotate-270: SKIP (0.019s)
[620.213270] [112/133] (435s left) gem_exec_schedule (preemptive-hang)
[620.396560] Starting subtest: preemptive-hang
[620.399466] Starting dynamic subtest: rcs0
[629.774185] Dynamic subtest rcs0: SUCCESS (9.376s)
[629.785609] Starting dynamic subtest: bcs0
[638.793134] Dynamic subtest bcs0: SUCCESS (9.012s)
[638.804881] Starting dynamic subtest: vcs0
[647.757010] Dynamic subtest vcs0: SUCCESS (8.957s)
[647.770030] Starting dynamic subtest: vcs1
[656.776296] Dynamic subtest vcs1: SUCCESS (9.013s)
[656.788833] Starting dynamic subtest: vecs0
[665.734460] Dynamic subtest vecs0: SUCCESS (8.952s)
[665.747439] Starting dynamic subtest: ccs0
[674.830453] Dynamic subtest ccs0: SUCCESS (9.097s)
[674.833329] Subtest preemptive-hang: SUCCESS (54.417s)
[675.066737] [113/133] (380s left) kms_pm_rpm (universal-planes)
[676.803728] Starting subtest: universal-planes
[676.806567] Starting dynamic subtest: plane-34
[685.086632] Dynamic subtest plane-34: SUCCESS (8.291s)
[685.100964] Starting dynamic subtest: plane-64
[693.396564] Dynamic subtest plane-64: SUCCESS (8.311s)
[693.408472] Starting dynamic subtest: plane-94
[701.724946] Dynamic subtest plane-94: SUCCESS (8.328s)
[701.737329] Starting dynamic subtest: plane-124
[710.035093] Dynamic subtest plane-124: SUCCESS (8.308s)
[710.048303] Starting dynamic subtest: plane-134
[718.350145] Dynamic subtest plane-134: SUCCESS (8.313s)
[718.362965] Starting dynamic subtest: plane-144
[726.664566] Dynamic subtest plane-144: SUCCESS (8.310s)
[726.667607] Subtest universal-planes: SUCCESS (49.880s)
[727.047405] [114/133] (328s left) gem_spin_batch (spin-all-new)
[727.109853] Starting subtest: spin-all-new
[727.123972] Subtest spin-all-new: SUCCESS (0.017s)
[728.661804] [115/133] (327s left) gem_exec_reloc (basic-gtt-read-active)
[728.715106] Subtest basic-gtt-read-active: SKIP (0.000s)
[728.782844] [116/133] (327s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[728.841156] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[728.843774] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[728.946159] [117/133] (327s left) kms_cursor_legacy (2x-long-flip-vs-cursor-atomic)
[729.071531] Starting subtest: 2x-long-flip-vs-cursor-atomic
[729.076458] Subtest 2x-long-flip-vs-cursor-atomic: SKIP (0.001s)
[729.262403] [118/133] (326s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-fullscreen)
[729.360897] Starting subtest: fbc-2p-scndscrn-spr-indfb-fullscreen
[729.366001] Subtest fbc-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[729.449963] [119/133] (326s left) perf_pmu (semaphore-busy)
[729.494918] Starting subtest: semaphore-busy
[729.498071] Starting dynamic subtest: rcs0
[731.133289] Dynamic subtest rcs0: SUCCESS (1.635s)
[731.146441] Starting dynamic subtest: bcs0
[732.768597] Dynamic subtest bcs0: SUCCESS (1.632s)
[732.779920] Starting dynamic subtest: vcs0
[737.181205] Dynamic subtest vcs0: SUCCESS (4.410s)
[737.193308] Starting dynamic subtest: vcs1
[743.132958] Dynamic subtest vcs1: SUCCESS (5.948s)
[743.146521] Starting dynamic subtest: vecs0
[749.148177] Dynamic subtest vecs0: SUCCESS (6.011s)
[749.161881] Starting dynamic subtest: ccs0
[752.224116] Dynamic subtest ccs0: SUCCESS (3.072s)
[752.227325] Subtest semaphore-busy: SUCCESS (22.724s)
[752.339041] [120/133] (303s left) kms_psr2_sf (fbc-pr-overlay-plane-update-sf-dmg-area)
[752.444263] Starting subtest: fbc-pr-overlay-plane-update-sf-dmg-area
[752.450105] Subtest fbc-pr-overlay-plane-update-sf-dmg-area: SKIP (0.000s)
[752.512094] [121/133] (303s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-blt)
[752.605863] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-blt
[752.610683] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[752.695577] [122/133] (303s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-fullscreen)
[752.798053] Starting subtest: psr-2p-primscrn-spr-indfb-fullscreen
[752.802974] Subtest psr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[752.890113] [123/133] (303s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted)
[752.953272] Starting subtest: multi-wait-all-for-submit-available-submitted
[752.956196] Subtest multi-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[753.048026] [124/133] (303s left) api_intel_allocator (two-level-inception)
[753.106204] Starting subtest: two-level-inception
[758.509893] Subtest two-level-inception: SUCCESS (5.404s)
[758.638606] [125/133] (297s left) kms_async_flips (test-time-stamp)
[758.745699] Starting subtest: test-time-stamp
[759.134972] Starting dynamic subtest: pipe-A-eDP-1
[759.944848] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.808s)
[760.314895] Starting dynamic subtest: pipe-B-eDP-1
[761.146143] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.837s)
[761.512980] Starting dynamic subtest: pipe-C-eDP-1
[762.352294] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.844s)
[762.726684] Starting dynamic subtest: pipe-D-eDP-1
[763.546598] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.825s)
[763.549169] Subtest test-time-stamp: SUCCESS (4.800s)
[764.792635] [126/133] (291s left) drm_read (empty-nonblock)
[764.946163] Starting subtest: empty-nonblock
[764.949062] Subtest empty-nonblock: SUCCESS (0.000s)
[765.068822] [127/133] (291s left) kms_lease (lease-again)
[765.160010] Starting subtest: lease-again
[765.162683] Starting dynamic subtest: pipe-A-eDP-1
[765.164880] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.003s)
[765.175337] Starting dynamic subtest: pipe-B-eDP-1
[765.177975] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[765.188005] Starting dynamic subtest: pipe-C-eDP-1
[765.190256] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[765.199971] Starting dynamic subtest: pipe-D-eDP-1
[765.202293] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[765.204721] Subtest lease-again: SUCCESS (0.015s)
[765.281908] [128/133] (291s left) gem_exec_balancer (indices)
[765.350135] Starting subtest: indices
[768.575297] Subtest indices: SUCCESS (3.222s)
[769.025925] [129/133] (287s left) kms_cursor_crc (cursor-rapid-movement-256x85)
[769.288445] Starting subtest: cursor-rapid-movement-256x85
[769.339887] Subtest cursor-rapid-movement-256x85: SKIP (0.043s)
[769.539175] [130/133] (287s left) gem_basic (create-fd-close)
[769.591694] Starting subtest: create-fd-close
[769.596920] Subtest create-fd-close: SUCCESS (0.000s)
[769.684814] [131/133] (287s left) core_auth (many-magics)
[769.731858] Starting subtest: many-magics
[770.516299] Subtest many-magics: SUCCESS (0.786s)
[770.728753] [132/133] (286s left) kms_flip (2x-absolute-wf_vblank-interruptible)
[770.828180] Starting subtest: 2x-absolute-wf_vblank-interruptible
[770.843800] Subtest 2x-absolute-wf_vblank-interruptible: SKIP (0.014s)
[770.924143] [133/133] (285s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[771.015326] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[771.018105] Starting dynamic subtest: pipe-A
[771.352121] Dynamic subtest pipe-A: SUCCESS (0.338s)
[771.362191] Starting dynamic subtest: pipe-B
[772.743152] Dynamic subtest pipe-B: SUCCESS (1.390s)
[772.754028] Starting dynamic subtest: pipe-C
[774.169287] Dynamic subtest pipe-C: SUCCESS (1.425s)
[774.179151] Starting dynamic subtest: pipe-D
[775.540311] Dynamic subtest pipe-D: SUCCESS (1.369s)
[775.542862] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (4.526s)
[776.672747] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: gem_flink_basic
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: gem_ppgtt
results: parsing output: 4/ for test: gem_exec_schedule
results: parsing output: 5/ for test: vgem_slow
results: parsing output: 6/ for test: gem_exec_schedule
results: parsing output: 7/ for test: kms_ccs
results: parsing output: 8/ for test: kms_cursor_legacy
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: i915_drm_fdinfo
results: parsing output: 12/ for test: kms_vblank
results: parsing output: 13/ for test: i915_pm_rps
results: parsing output: 14/ for test: kms_ccs
results: parsing output: 15/ for test: gem_ctx_bad_destroy
results: parsing output: 16/ for test: gem_fence_thrash
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: kms_color_pipeline
results: parsing output: 19/ for test: gem_ctx_shared
results: parsing output: 20/ for test: gem_exec_create
results: parsing output: 21/ for test: syncobj_timeline
results: parsing output: 22/ for test: kms_chamelium_audio
results: parsing output: 23/ for test: gem_pwrite_snooped
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: perf
results: parsing output: 26/ for test: kms_psr
results: parsing output: 27/ for test: kms_big_fb
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: gem_exec_params
results: parsing output: 30/ for test: gem_mmap_gtt
results: parsing output: 31/ for test: kms_big_fb
results: parsing output: 32/ for test: kms_rotation_crc
results: parsing output: 33/ for test: kms_big_fb
results: parsing output: 34/ for test: kms_flip_scaled_crc
results: parsing output: 35/ for test: kms_ccs
results: parsing output: 36/ for test: kms_plane
results: parsing output: 37/ for test: kms_psr
results: parsing output: 38/ for test: kms_cursor_legacy
results: parsing output: 39/ for test: gem_ctx_freq
results: parsing output: 40/ for test: gem_lmem_swapping
results: parsing output: 41/ for test: kms_plane_multiple
results: parsing output: 42/ for test: gem_partial_pwrite_pread
results: parsing output: 43/ for test: kms_ccs
results: parsing output: 44/ for test: core_getclient
results: parsing output: 45/ for test: gem_eio
results: parsing output: 46/ for test: gem_exec_schedule
results: parsing output: 47/ for test: kms_cursor_legacy
results: parsing output: 48/ for test: kms_cursor_edge_walk
results: parsing output: 49/ for test: syncobj_eventfd
results: parsing output: 50/ for test: syncobj_wait
results: parsing output: 51/ for test: core_auth
results: parsing output: 52/ for test: kms_flip
results: parsing output: 53/ for test: kms_cursor_legacy
results: parsing output: 54/ for test: gem_cs_tlb
results: parsing output: 55/ for test: gem_vm_create
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_content_protection
results: parsing output: 59/ for test: gen9_exec_parse
results: parsing output: 60/ for test: kms_flip
results: parsing output: 61/ for test: kms_psr
results: parsing output: 62/ for test: core_getversion
results: parsing output: 63/ for test: gem_ringfill
results: parsing output: 64/ for test: gem_media_fill
results: parsing output: 65/ for test: perf_pmu
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: gem_partial_pwrite_pread
results: parsing output: 68/ for test: gem_userptr_blits
results: parsing output: 69/ for test: i915_pm_rps
results: parsing output: 70/ for test: syncobj_basic
results: parsing output: 71/ for test: sysfs_preempt_timeout
results: parsing output: 72/ for test: gem_pxp
results: parsing output: 73/ for test: gem_exec_reloc
results: parsing output: 74/ for test: syncobj_timeline
results: parsing output: 75/ for test: syncobj_basic
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_big_fb
results: parsing output: 78/ for test: gem_exec_balancer
results: parsing output: 79/ for test: kms_psr2_sf
results: parsing output: 80/ for test: syncobj_timeline
results: parsing output: 81/ for test: syncobj_wait
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: kms_big_fb
results: parsing output: 84/ for test: kms_big_fb
results: parsing output: 85/ for test: gem_exec_reloc
results: parsing output: 86/ for test: kms_dsc
results: parsing output: 87/ for test: kms_chamelium_color
results: parsing output: 88/ for test: kms_psr
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_vblank
results: parsing output: 91/ for test: kms_async_flips
results: parsing output: 92/ for test: kms_psr
results: parsing output: 93/ for test: kms_plane
results: parsing output: 94/ for test: kms_joiner
results: parsing output: 95/ for test: kms_cursor_legacy
results: parsing output: 96/ for test: kms_chamelium_hpd
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_pipe_stress
results: parsing output: 99/ for test: kms_pm_dc
results: parsing output: 100/ for test: kms_cursor_crc
results: parsing output: 101/ for test: i915_drm_fdinfo
results: parsing output: 102/ for test: kms_psr2_su
results: parsing output: 103/ for test: kms_force_connector_basic
results: parsing output: 104/ for test: kms_flip_scaled_crc
results: parsing output: 105/ for test: gem_tiled_blits
results: parsing output: 106/ for test: gem_userptr_blits
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: gem_exec_fence
results: parsing output: 109/ for test: gem_ccs
results: parsing output: 110/ for test: kms_big_fb
results: parsing output: 111/ for test: gem_exec_schedule
results: parsing output: 112/ for test: kms_pm_rpm
results: parsing output: 113/ for test: gem_spin_batch
results: parsing output: 114/ for test: gem_exec_reloc
results: parsing output: 115/ for test: syncobj_wait
results: parsing output: 116/ for test: kms_cursor_legacy
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: perf_pmu
results: parsing output: 119/ for test: kms_psr2_sf
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: syncobj_timeline
results: parsing output: 123/ for test: api_intel_allocator
results: parsing output: 124/ for test: kms_async_flips
results: parsing output: 125/ for test: drm_read
results: parsing output: 126/ for test: kms_lease
results: parsing output: 127/ for test: gem_exec_balancer
results: parsing output: 128/ for test: kms_cursor_crc
results: parsing output: 129/ for test: gem_basic
results: parsing output: 130/ for test: core_auth
results: parsing output: 131/ for test: kms_flip
results: parsing output: 132/ for test: kms_plane_scaling
Done.