[54.589219] Initializing watchdogs
[54.589329]   /dev/watchdog0
[54.604032] [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.608441] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[54.610855] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[54.614424] [001/131] (960s left) perf_pmu (most-busy-idle-check-all)
[54.670425] Starting subtest: most-busy-idle-check-all
[54.672740] Starting dynamic subtest: rcs0
[55.891969] Dynamic subtest rcs0: SUCCESS (1.219s)
[55.902376] Starting dynamic subtest: bcs0
[57.942073] Dynamic subtest bcs0: SUCCESS (2.050s)
[57.953034] Starting dynamic subtest: vcs0
[59.924830] Dynamic subtest vcs0: SUCCESS (1.982s)
[59.935318] Starting dynamic subtest: vcs1
[61.908081] Dynamic subtest vcs1: SUCCESS (1.983s)
[61.918861] Starting dynamic subtest: vecs0
[63.893170] Dynamic subtest vecs0: SUCCESS (1.984s)
[63.902620] Starting dynamic subtest: ccs0
[65.940672] Dynamic subtest ccs0: SUCCESS (2.048s)
[65.943775] Subtest most-busy-idle-check-all: SUCCESS (11.268s)
[66.039057] [002/131] (949s left) syncobj_wait (multi-wait-submitted)
[66.107329] Starting subtest: multi-wait-submitted
[66.216632] Subtest multi-wait-submitted: SUCCESS (0.107s)
[66.289470] [003/131] (948s left) gem_wait (write-wait)
[66.358724] Starting subtest: write-wait
[66.361025] Starting dynamic subtest: all-engines
[67.508658] Dynamic subtest all-engines: SUCCESS (1.150s)
[67.524159] Starting dynamic subtest: rcs0
[68.565229] Dynamic subtest rcs0: SUCCESS (1.056s)
[68.579853] Starting dynamic subtest: bcs0
[69.622265] Dynamic subtest bcs0: SUCCESS (1.056s)
[69.636583] Starting dynamic subtest: vcs0
[70.674316] Dynamic subtest vcs0: SUCCESS (1.052s)
[70.688081] Starting dynamic subtest: vcs1
[71.737781] Dynamic subtest vcs1: SUCCESS (1.063s)
[71.752581] Starting dynamic subtest: vecs0
[72.793152] Dynamic subtest vecs0: SUCCESS (1.055s)
[72.807864] Starting dynamic subtest: ccs0
[73.850138] Dynamic subtest ccs0: SUCCESS (1.056s)
[73.853040] Subtest write-wait: SUCCESS (7.492s)
[74.041012] [FACT gem_wait (write-wait)] new: kernel.kmod_is_loaded.vgem: true
[74.043938] [004/131] (941s left) kms_plane_lowres (tiling-4)
[74.139969] Starting subtest: tiling-4
[74.142328] Starting dynamic subtest: pipe-A-eDP-1
[74.146773] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[74.431613] Starting dynamic subtest: pipe-B-eDP-1
[74.436378] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[74.444395] Starting dynamic subtest: pipe-C-eDP-1
[74.449449] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[74.456808] Starting dynamic subtest: pipe-D-eDP-1
[74.461180] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[74.465507] Subtest tiling-4: SKIP (0.290s)
[75.243186] [005/131] (940s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)
[75.346720] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render
[82.057612] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.713s)
[82.200216] [006/131] (933s left) gem_exec_fence (basic-await)
[82.382132] Starting subtest: basic-await
[82.384497] Starting dynamic subtest: rcs0
[82.457642] Dynamic subtest rcs0: SUCCESS (0.076s)
[82.465667] Starting dynamic subtest: bcs0
[82.538752] Dynamic subtest bcs0: SUCCESS (0.080s)
[82.549082] Starting dynamic subtest: vcs0
[82.613177] Dynamic subtest vcs0: SUCCESS (0.073s)
[82.623832] Starting dynamic subtest: vcs1
[82.685697] Dynamic subtest vcs1: SUCCESS (0.073s)
[82.696088] Starting dynamic subtest: vecs0
[82.756773] Dynamic subtest vecs0: SUCCESS (0.071s)
[82.767167] Starting dynamic subtest: ccs0
[82.828910] Dynamic subtest ccs0: SUCCESS (0.072s)
[82.831390] Subtest basic-await: SUCCESS (0.447s)
[84.981412] [007/131] (930s left) syncobj_timeline (invalid-wait-illegal-handle)
[85.038738] Starting subtest: invalid-wait-illegal-handle
[85.040852] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[85.155624] [008/131] (930s left) gem_ctx_param (invalid-get-ringsize)
[85.198581] Starting subtest: invalid-get-ringsize
[85.200871] Subtest invalid-get-ringsize: SUCCESS (0.002s)
[85.317193] [009/131] (930s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[85.370340] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[85.372354] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[85.497142] [010/131] (929s left) kms_dsc (dsc-fractional-bpp)
[85.601283] Starting subtest: dsc-fractional-bpp
[85.615146] Subtest dsc-fractional-bpp: SKIP (0.000s)
[85.680812] [011/131] (929s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[85.743368] Starting subtest: yf-tiled-to-vebox-yf-tiled
[85.748288] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[85.925735] [012/131] (929s left) gem_exec_schedule (u-submit-late-slice)
[85.994159] Starting subtest: u-submit-late-slice
[85.996392] Starting dynamic subtest: rcs0
[86.019556] Dynamic subtest rcs0: SUCCESS (0.033s)
[86.029878] Starting dynamic subtest: bcs0
[86.047915] Dynamic subtest bcs0: SUCCESS (0.028s)
[86.056630] Starting dynamic subtest: vcs0
[86.079483] Dynamic subtest vcs0: SUCCESS (0.031s)
[86.089375] Starting dynamic subtest: vcs1
[86.112287] Dynamic subtest vcs1: SUCCESS (0.033s)
[86.120529] Starting dynamic subtest: vecs0
[86.137815] Dynamic subtest vecs0: SUCCESS (0.025s)
[86.145295] Starting dynamic subtest: ccs0
[86.165154] Dynamic subtest ccs0: SUCCESS (0.027s)
[86.166995] Subtest u-submit-late-slice: SUCCESS (0.179s)
[87.970233] [013/131] (927s left) kms_psr (psr-primary-mmap-cpu)
[88.054114] Starting subtest: psr-primary-mmap-cpu
[88.056429] Starting dynamic subtest: eDP-1
[89.780488] Dynamic subtest eDP-1: SUCCESS (1.722s)
[89.782642] Subtest psr-primary-mmap-cpu: SUCCESS (1.723s)
[90.643840] [014/131] (924s left) api_intel_bb (object-reloc-purge-cache)
[90.702871] Starting subtest: object-reloc-purge-cache
[90.707302] Subtest object-reloc-purge-cache: SKIP (0.003s)
[90.790636] [015/131] (924s left) gem_create (create-massive)
[90.842669] Starting subtest: create-massive
[90.844544] Subtest create-massive: SUCCESS (0.000s)
[91.020770] [016/131] (924s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0)
[91.126860] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0
[91.131026] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[91.228262] [017/131] (924s left) kms_ccs (bad-aux-stride-4-tiled-mtl-rc-ccs-cc)
[91.319020] Starting subtest: bad-aux-stride-4-tiled-mtl-rc-ccs-cc
[91.321339] Starting dynamic subtest: pipe-A-eDP-1
[91.341934] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[91.350594] Starting dynamic subtest: pipe-B-eDP-1
[91.370144] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[91.377673] Starting dynamic subtest: pipe-C-eDP-1
[91.397104] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[91.404449] Starting dynamic subtest: pipe-D-eDP-1
[91.424154] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[91.426044] Subtest bad-aux-stride-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.006s)
[93.607438] [018/131] (922s left) kms_big_fb (linear-8bpp-rotate-0)
[93.723254] Starting subtest: linear-8bpp-rotate-0
[94.847357] Subtest linear-8bpp-rotate-0: SUCCESS (1.124s)
[94.973284] [019/131] (920s left) kms_atomic (test-only)
[95.078884] Starting subtest: test-only
[95.131448] Starting dynamic subtest: pipe-A-eDP-1
[97.632445] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.501s)
[97.634874] Subtest test-only: SUCCESS (2.551s)
[98.495626] [020/131] (917s left) gem_mmap_offset (ptrace)
[98.549076] Starting subtest: ptrace
[98.551467] Starting dynamic subtest: smem0-wb
[98.564312] Dynamic subtest smem0-wb: SUCCESS (0.014s)
[98.574390] Starting dynamic subtest: smem0-wc
[98.584334] Dynamic subtest smem0-wc: SUCCESS (0.020s)
[98.591886] Starting dynamic subtest: smem0-uc
[98.604343] Dynamic subtest smem0-uc: SUCCESS (0.020s)
[98.606686] Subtest ptrace: SUCCESS (0.058s)
[98.743107] [021/131] (917s left) perf_pmu (busy-double-start)
[98.785338] Starting subtest: busy-double-start
[98.787498] Starting dynamic subtest: rcs0
[102.429256] Dynamic subtest rcs0: FAIL (3.591s)
[102.438724] Starting dynamic subtest: bcs0
[105.988352] Dynamic subtest bcs0: SUCCESS (3.610s)
[105.998580] Starting dynamic subtest: vcs0
[110.425797] Dynamic subtest vcs0: SUCCESS (4.437s)
[110.436206] Starting dynamic subtest: vcs1
[114.471789] Dynamic subtest vcs1: SUCCESS (4.045s)
[114.481351] Starting dynamic subtest: vecs0
[118.443777] Dynamic subtest vecs0: SUCCESS (3.971s)
[118.454021] Starting dynamic subtest: ccs0
[122.081639] Dynamic subtest ccs0: FAIL (3.575s)
[122.083990] Subtest busy-double-start: FAIL (23.231s)
[122.140211] [022/131] (893s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-fullscreen)
[122.251790] Starting subtest: fbc-1p-primscrn-spr-indfb-fullscreen
[126.263060] Subtest fbc-1p-primscrn-spr-indfb-fullscreen: SUCCESS (4.015s)
[127.403250] [023/131] (888s left) gem_exec_params (rsvd2-dirt)
[127.462581] Starting subtest: rsvd2-dirt
[127.467260] Subtest rsvd2-dirt: SKIP (0.000s)
[127.594623] [024/131] (888s left) api_intel_bb (object-noreloc-purge-cache-simple)
[127.648191] Starting subtest: object-noreloc-purge-cache-simple
[127.653173] Subtest object-noreloc-purge-cache-simple: SUCCESS (0.006s)
[127.774419] [025/131] (888s left) gem_userptr_blits (sync-unmap)
[127.844780] Starting subtest: sync-unmap
[127.855734] Subtest sync-unmap: SUCCESS (0.012s)
[128.007576] [026/131] (888s left) syncobj_timeline (device-signal-unordered)
[128.057976] Starting subtest: device-signal-unordered
[128.060316] Subtest device-signal-unordered: SUCCESS (0.001s)
[128.156112] [027/131] (887s left) gem_ctx_shared (q-smoketest)
[128.228559] Starting subtest: Q-smoketest
[128.230955] Starting dynamic subtest: rcs0
[133.782568] Dynamic subtest rcs0: SUCCESS (5.507s)
[133.792949] Starting dynamic subtest: bcs0
[139.283039] Dynamic subtest bcs0: SUCCESS (5.501s)
[139.291364] Starting dynamic subtest: vcs0
[144.757264] Dynamic subtest vcs0: SUCCESS (5.474s)
[144.765574] Starting dynamic subtest: vcs1
[150.229229] Dynamic subtest vcs1: SUCCESS (5.469s)
[150.238204] Starting dynamic subtest: vecs0
[155.690736] Dynamic subtest vecs0: SUCCESS (5.469s)
[155.699431] Starting dynamic subtest: ccs0
[161.188443] Dynamic subtest ccs0: SUCCESS (5.495s)
[161.190751] Subtest Q-smoketest: SUCCESS (32.915s)
[161.326278] [028/131] (854s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[161.424538] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[161.428762] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[161.511981] [029/131] (854s left) gem_userptr_blits (map-fixed-invalidate)
[161.580112] Starting subtest: map-fixed-invalidate
[161.584092] Subtest map-fixed-invalidate: SKIP (0.000s)
[161.719040] [030/131] (854s left) gem_mmap_gtt (big-bo)
[161.767169] Subtest big-bo: SKIP (0.000s)
[161.828678] [031/131] (854s left) sw_sync (sync_busy_fork)
[161.859152] Starting subtest: sync_busy_fork
[162.871795] Subtest sync_busy_fork: SUCCESS (1.012s)
[162.931133] [032/131] (853s left) gem_mmap_wc (coherency)
[162.989234] Subtest coherency: SKIP (0.000s)
[163.055190] [033/131] (853s left) kms_cursor_edge_walk (256x256-left-edge)
[163.146086] Starting subtest: 256x256-left-edge
[163.148691] Starting dynamic subtest: pipe-A-eDP-1
[166.647612] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.499s)
[166.660675] Starting dynamic subtest: pipe-D-eDP-1
[169.763517] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.118s)
[169.765802] Subtest 256x256-left-edge: SUCCESS (6.619s)
[170.565085] [034/131] (845s left) prime_vgem (coherency-blt)
[170.631305] Starting subtest: coherency-blt
[171.711058] Subtest coherency-blt: SUCCESS (1.080s)
[171.854067] [035/131] (844s left) gem_tiled_wc
[171.976760] [036/131] (844s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-pwrite)
[172.086490] Starting subtest: psr-1p-primscrn-cur-indfb-draw-pwrite
[178.779727] Subtest psr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.699s)
[178.924123] [037/131] (837s left) gem_exec_fence (syncobj-invalid-flags)
[179.370154] Starting subtest: syncobj-invalid-flags
[179.372516] Subtest syncobj-invalid-flags: SUCCESS (0.000s)
[180.975357] [038/131] (835s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[181.076081] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[181.078386] Starting dynamic subtest: pipe-A-default-mode
[181.369198] Dynamic subtest pipe-A-default-mode: SKIP (0.287s)
[181.373982] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SKIP (0.288s)
[182.205619] [039/131] (834s left) gem_mmap_gtt (hang-user)
[182.265122] Subtest hang-user: SKIP (0.000s)
[182.319520] [040/131] (834s left) kms_plane_scaling (plane-downscale-factor-0-75-with-pixel-format)
[182.402987] Starting subtest: plane-downscale-factor-0-75-with-pixel-format
[182.405314] Starting dynamic subtest: pipe-A
[183.576672] Dynamic subtest pipe-A: SUCCESS (1.174s)
[183.586618] Starting dynamic subtest: pipe-B
[185.798931] Dynamic subtest pipe-B: SUCCESS (2.222s)
[185.806960] Starting dynamic subtest: pipe-C
[187.004504] Dynamic subtest pipe-C: SUCCESS (1.205s)
[187.013316] Starting dynamic subtest: pipe-D
[188.159280] Dynamic subtest pipe-D: SUCCESS (1.155s)
[188.161649] Subtest plane-downscale-factor-0-75-with-pixel-format: SUCCESS (5.757s)
[189.357371] [041/131] (827s left) kms_chamelium_hpd (dp-hpd)
[189.749783] Subtest dp-hpd: SKIP (0.000s)
[190.575333] [042/131] (826s left) kms_cursor_crc (cursor-rapid-movement-128x42)
[190.798365] Starting subtest: cursor-rapid-movement-128x42
[190.847524] Subtest cursor-rapid-movement-128x42: SKIP (0.045s)
[191.055804] [043/131] (825s left) sw_sync (sync_multi_consumer_producer)
[191.093986] Starting subtest: sync_multi_consumer_producer
[191.371247] Subtest sync_multi_consumer_producer: SUCCESS (0.279s)
[191.429462] [044/131] (825s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[191.529578] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[191.533825] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[191.628291] [045/131] (825s left) kms_cursor_crc (cursor-offscreen-max-size)
[191.931345] Starting subtest: cursor-offscreen-max-size
[191.938425] Subtest cursor-offscreen-max-size: SKIP (0.000s)
[192.077671] [046/131] (824s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[192.176330] Starting subtest: yf-tiled-64bpp-rotate-90
[192.180402] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[192.287406] [047/131] (824s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs-cc)
[192.387102] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs-cc
[192.388996] Starting dynamic subtest: pipe-A-eDP-1
[192.408564] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[192.416585] Starting dynamic subtest: pipe-B-eDP-1
[192.434336] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[192.441488] Starting dynamic subtest: pipe-C-eDP-1
[192.459254] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[192.466305] Starting dynamic subtest: pipe-D-eDP-1
[192.485368] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[192.489163] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.005s)
[194.654395] [048/131] (822s left) i915_pm_sseu (full-enable)
[194.722956] Subtest full-enable: SKIP (0.000s)
[194.846306] [049/131] (822s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[194.943868] Starting subtest: bo-too-small-due-to-tiling
[194.948533] Subtest bo-too-small-due-to-tiling: SKIP (0.000s)
[195.027333] [050/131] (822s left) sysfs_defaults (readonly)
[195.090530] Starting subtest: readonly
[195.092693] Starting dynamic subtest: vecs0
[195.094969] Dynamic subtest vecs0: SUCCESS (0.000s)
[195.102575] Starting dynamic subtest: ccs0
[195.104465] Dynamic subtest ccs0: SUCCESS (0.000s)
[195.111696] Starting dynamic subtest: vcs0
[195.113609] Dynamic subtest vcs0: SUCCESS (0.000s)
[195.121444] Starting dynamic subtest: bcs0
[195.123632] Dynamic subtest bcs0: SUCCESS (0.000s)
[195.133089] Starting dynamic subtest: rcs0
[195.134958] Dynamic subtest rcs0: SUCCESS (0.000s)
[195.145579] Starting dynamic subtest: vcs1
[195.147898] Dynamic subtest vcs1: SUCCESS (0.000s)
[195.150271] Subtest readonly: SUCCESS (0.004s)
[195.202777] [051/131] (821s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[195.308603] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip
[195.312746] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[195.409221] [052/131] (821s left) kms_feature_discovery (psr1)
[195.506245] Starting subtest: psr1
[195.508141] Subtest psr1: SUCCESS (0.000s)
[195.576777] [053/131] (821s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-plflip-blt)
[195.676495] Starting subtest: psr-1p-primscrn-indfb-plflip-blt
[203.075922] Subtest psr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.402s)
[203.198690] [054/131] (813s left) gem_ctx_bad_destroy (invalid-ctx)
[203.244209] Starting subtest: invalid-ctx
[203.246615] Subtest invalid-ctx: SUCCESS (0.000s)
[203.319105] [055/131] (813s left) gem_linear_blits (basic)
[203.597456] Starting subtest: basic
[203.610781] Subtest basic: SUCCESS (0.014s)
[203.763816] [056/131] (813s left) gem_exec_reloc (basic-gtt-wc-noreloc)
[203.868067] Subtest basic-gtt-wc-noreloc: SKIP (0.000s)
[203.949415] [057/131] (813s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area)
[209.000869] Starting subtest: fbc-pr-overlay-primary-update-sf-dmg-area
[209.005513] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.002s)
[209.842047] [058/131] (807s left) kms_writeback (writeback-check-output-xrgb2101010)
[209.941842] Subtest writeback-check-output-XRGB2101010: SKIP (0.000s)
[209.987396] [059/131] (807s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[210.098168] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[210.890354] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.795s)
[211.060446] [060/131] (806s left) gem_ctx_param (non-root-set)
[211.109697] Starting subtest: non-root-set
[211.138645] Subtest non-root-set: SUCCESS (0.029s)
[211.227300] [061/131] (806s left) kms_psr (fbc-psr-primary-render)
[211.332245] Starting subtest: fbc-psr-primary-render
[211.334656] Starting dynamic subtest: eDP-1
[211.339358] Dynamic subtest eDP-1: SKIP (0.000s)
[211.344103] Subtest fbc-psr-primary-render: SKIP (0.001s)
[211.401428] [062/131] (806s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted)
[211.466178] Starting subtest: multi-wait-available-unsubmitted-submitted
[211.468650] Subtest multi-wait-available-unsubmitted-submitted: SUCCESS (0.000s)
[211.578648] [063/131] (805s left) kms_flip (nonexisting-fb)
[211.679920] Starting subtest: nonexisting-fb
[211.682236] Starting dynamic subtest: A-eDP1
[212.824693] Dynamic subtest A-eDP1: SUCCESS (1.144s)
[212.833167] Starting dynamic subtest: B-eDP1
[213.964971] Dynamic subtest B-eDP1: SUCCESS (1.140s)
[213.973480] Starting dynamic subtest: C-eDP1
[215.104819] Dynamic subtest C-eDP1: SUCCESS (1.139s)
[215.114226] Starting dynamic subtest: D-eDP1
[216.241840] Dynamic subtest D-eDP1: SUCCESS (1.135s)
[216.244077] Subtest nonexisting-fb: SUCCESS (4.566s)
[217.415422] [064/131] (800s left) gem_spin_batch (resubmit-new-all)
[217.467635] Starting subtest: resubmit-new-all
[217.469873] Starting dynamic subtest: rcs0
[217.482075] Dynamic subtest rcs0: SUCCESS (0.014s)
[217.492183] Starting dynamic subtest: bcs0
[217.494386] Dynamic subtest bcs0: SUCCESS (0.011s)
[217.502249] Starting dynamic subtest: vcs0
[217.504617] Dynamic subtest vcs0: SUCCESS (0.009s)
[217.513630] Starting dynamic subtest: vcs1
[217.515548] Dynamic subtest vcs1: SUCCESS (0.009s)
[217.523436] Starting dynamic subtest: vecs0
[217.525545] Dynamic subtest vecs0: SUCCESS (0.009s)
[217.533626] Starting dynamic subtest: ccs0
[217.535536] Dynamic subtest ccs0: SUCCESS (0.009s)
[217.537443] Subtest resubmit-new-all: SUCCESS (0.063s)
[218.936874] [065/131] (798s left) syncobj_timeline (etime-single-wait-all-for-submit-submitted)
[218.983839] Starting subtest: etime-single-wait-all-for-submit-submitted
[219.087810] Subtest etime-single-wait-all-for-submit-submitted: SUCCESS (0.103s)
[219.161790] [066/131] (798s left) gem_exec_whisper (basic-fds-forked)
[219.219821] Starting subtest: basic-fds-forked
[243.188663] Subtest basic-fds-forked: SUCCESS (23.967s)
[243.368490] [067/131] (774s left) drm_mm (drm_mm)
[243.402688] Starting subtest: drm_mm
[243.453872] Starting dynamic subtest: drm_test_mm_init
[243.573820] Dynamic subtest drm_test_mm_init: SUCCESS (0.118s)
[243.583241] Starting dynamic subtest: drm_test_mm_debug
[243.704981] Dynamic subtest drm_test_mm_debug: SUCCESS (0.130s)
[243.715031] Starting dynamic subtest: drm_test_mm_align32
[243.855644] Dynamic subtest drm_test_mm_align32: SUCCESS (0.151s)
[243.865548] Starting dynamic subtest: drm_test_mm_align64
[244.010018] Dynamic subtest drm_test_mm_align64: SUCCESS (0.155s)
[244.019663] Starting dynamic subtest: drm_test_mm_lowest
[244.100252] Dynamic subtest drm_test_mm_lowest: SUCCESS (0.090s)
[244.108336] Starting dynamic subtest: drm_test_mm_highest
[244.246973] Dynamic subtest drm_test_mm_highest: SUCCESS (0.143s)
[244.248979] Subtest drm_mm: SUCCESS (0.841s)
[244.446333] [068/131] (773s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render)
[244.555501] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render
[244.559735] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[244.658787] [069/131] (773s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-wc)
[244.759961] Starting subtest: fbcpsr-rgb565-draw-mmap-wc
[251.426293] Subtest fbcpsr-rgb565-draw-mmap-wc: SUCCESS (6.669s)
[251.546744] [070/131] (766s left) kms_psr2_su (page_flip-p010)
[251.608145] Subtest page_flip-P010: SKIP (0.000s)
[251.669103] [071/131] (766s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[251.777455] Starting subtest: fbc-rgb565-draw-blt
[255.973694] Subtest fbc-rgb565-draw-blt: SUCCESS (4.200s)
[257.124416] [072/131] (760s left) syncobj_timeline (single-wait-available-signaled)
[257.169421] Starting subtest: single-wait-available-signaled
[257.171656] Subtest single-wait-available-signaled: SUCCESS (0.000s)
[257.288438] [073/131] (760s left) perf (missing-sample-flags)
[257.422608] Starting subtest: missing-sample-flags
[257.424801] Subtest missing-sample-flags: SUCCESS (0.000s)
[257.461250] [074/131] (760s left) kms_rotation_crc (primary-y-tiled-reflect-x-270)
[257.561278] Starting subtest: primary-y-tiled-reflect-x-270
[257.565785] Subtest primary-y-tiled-reflect-x-270: SKIP (0.001s)
[257.634150] [075/131] (760s left) gem_lmem_swapping (random-engines)
[257.676116] Subtest random-engines: SKIP (0.000s)
[257.723395] [076/131] (760s left) i915_query (test-query-geometry-subslices)
[257.783263] Starting subtest: test-query-geometry-subslices
[257.785530] Subtest test-query-geometry-subslices: SUCCESS (0.000s)
[257.903065] [077/131] (760s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[258.004176] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[258.008141] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[258.105174] [078/131] (760s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs)
[258.200995] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs
[258.203063] Starting dynamic subtest: pipe-A-eDP-1
[274.465869] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.506s)
[274.475471] Starting dynamic subtest: pipe-B-eDP-1
[291.354687] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.332s)
[291.364599] Starting dynamic subtest: pipe-C-eDP-1
[308.316092] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.298s)
[308.325476] Starting dynamic subtest: pipe-D-eDP-1
[325.309427] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.235s)
[325.311691] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs: SUCCESS (8.376s)
[326.522790] [079/131] (691s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-mc-ccs)
[326.622773] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-mc-ccs
[326.625167] Starting dynamic subtest: pipe-A-eDP-1
[326.648701] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[326.660878] Starting dynamic subtest: pipe-B-eDP-1
[326.682226] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[326.690824] Starting dynamic subtest: pipe-C-eDP-1
[326.712897] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[326.723928] Starting dynamic subtest: pipe-D-eDP-1
[326.747412] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[326.751809] Subtest crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.005s)
[328.809260] [080/131] (689s left) syncobj_wait (multi-wait-all-submitted)
[328.874912] Starting subtest: multi-wait-all-submitted
[328.982919] Subtest multi-wait-all-submitted: SUCCESS (0.105s)
[329.059299] [081/131] (689s left) kms_content_protection (legacy)
[329.209457] Starting subtest: legacy
[333.037968] Subtest legacy: SKIP (3.818s)
[334.245268] [082/131] (684s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[334.355107] Starting subtest: plane-use-after-nonblocking-unbind
[334.357470] Starting dynamic subtest: pipe-A-eDP-1
[334.421379] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.069s)
[334.723912] Starting dynamic subtest: pipe-B-eDP-1
[335.544669] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.829s)
[335.832555] Subtest plane-use-after-nonblocking-unbind: SUCCESS (1.478s)
[336.697437] [083/131] (681s left) gem_create (create-ext-cpu-access-big)
[336.866857] Starting subtest: create-ext-cpu-access-big
[336.871199] Subtest create-ext-cpu-access-big: SKIP (0.001s)
[336.944361] [084/131] (681s left) gem_exec_balancer (busy)
[337.013857] Starting subtest: busy
[338.614772] Subtest busy: SUCCESS (1.599s)
[338.947341] [085/131] (679s left) kms_psr (fbc-psr2-sprite-render)
[339.053777] Starting subtest: fbc-psr2-sprite-render
[339.056040] Starting dynamic subtest: eDP-1
[339.060495] Dynamic subtest eDP-1: SKIP (0.000s)
[339.065027] Subtest fbc-psr2-sprite-render: SKIP (0.001s)
[339.135373] [086/131] (679s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-onoff)
[339.235623] Starting subtest: psr-2p-scndscrn-cur-indfb-onoff
[339.240051] Subtest psr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[339.329570] [087/131] (679s left) kms_flip (flip-vs-expired-vblank)
[339.434113] Starting subtest: flip-vs-expired-vblank
[339.436494] Starting dynamic subtest: A-eDP1
[348.105612] Dynamic subtest A-eDP1: SUCCESS (8.667s)
[348.117762] Starting dynamic subtest: B-eDP1
[356.742564] Dynamic subtest B-eDP1: SUCCESS (8.632s)
[356.754216] Starting dynamic subtest: C-eDP1
[365.372092] Dynamic subtest C-eDP1: SUCCESS (8.622s)
[365.386483] Starting dynamic subtest: D-eDP1
[374.007280] Dynamic subtest D-eDP1: SUCCESS (8.631s)
[374.009728] Subtest flip-vs-expired-vblank: SUCCESS (34.561s)
[375.177954] [088/131] (643s left) gem_userptr_blits (readonly-pwrite-unsync)
[375.258264] Subtest readonly-pwrite-unsync: SKIP (0.000s)
[375.406388] [089/131] (643s left) gem_pwrite (basic-self)
[375.468277] Subtest basic-self: SKIP (0.000s)
[375.545472] [090/131] (643s left) kms_lease (lease-again)
[375.636066] Starting subtest: lease-again
[375.638269] Starting dynamic subtest: pipe-A-eDP-1
[375.640647] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.003s)
[375.650499] Starting dynamic subtest: pipe-B-eDP-1
[375.652723] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[375.662191] Starting dynamic subtest: pipe-C-eDP-1
[375.664410] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[375.673058] Starting dynamic subtest: pipe-D-eDP-1
[375.675081] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[375.677102] Subtest lease-again: SUCCESS (0.013s)
[375.758019] [091/131] (642s left) syncobj_wait (invalid-wait-bad-flags)
[375.819699] Starting subtest: invalid-wait-bad-flags
[375.822140] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[375.936774] [092/131] (642s left) gem_exec_params (invalid-bsd2-flag-on-render)
[376.012046] Starting subtest: invalid-bsd2-flag-on-render
[376.014509] Subtest invalid-bsd2-flag-on-render: SUCCESS (0.000s)
[376.163988] [093/131] (642s left) gem_partial_pwrite_pread (write-snoop)
[376.227839] Subtest write-snoop: SKIP (0.000s)
[376.315408] [094/131] (642s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[376.400839] Starting subtest: nonblocking-crc-frame-sequence
[376.402934] Starting dynamic subtest: pipe-A-eDP-1
[377.137114] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.734s)
[377.146686] Starting dynamic subtest: pipe-B-eDP-1
[378.562142] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.423s)
[378.571922] Starting dynamic subtest: pipe-C-eDP-1
[379.964079] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.400s)
[379.974275] Starting dynamic subtest: pipe-D-eDP-1
[381.306245] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.340s)
[381.308599] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.902s)
[382.106479] [095/131] (636s left) kms_pm_dc (dc5-retention-flops)
[383.213881] Starting subtest: dc5-retention-flops
[383.218243] Subtest dc5-retention-flops: SKIP (0.000s)
[383.285802] [096/131] (635s left) kms_chamelium_frames (dp-crc-multiple)
[383.692934] Subtest dp-crc-multiple: SKIP (0.000s)
[384.527943] [097/131] (634s left) kms_flip (flip-vs-rmfb)
[384.635103] Starting subtest: flip-vs-rmfb
[384.637466] Starting dynamic subtest: A-eDP1
[393.342195] Dynamic subtest A-eDP1: SUCCESS (8.703s)
[393.353797] Starting dynamic subtest: B-eDP1
[401.983119] Dynamic subtest B-eDP1: SUCCESS (8.636s)
[401.992447] Starting dynamic subtest: C-eDP1
[410.634607] Dynamic subtest C-eDP1: SUCCESS (8.652s)
[410.643849] Starting dynamic subtest: D-eDP1
[419.277243] Dynamic subtest D-eDP1: SUCCESS (8.644s)
[419.282156] Subtest flip-vs-rmfb: SUCCESS (34.642s)
[420.520760] [098/131] (598s left) kms_panel_fitting (legacy)
[420.623142] Starting subtest: legacy
[420.625484] Starting dynamic subtest: pipe-A-eDP-1
[421.167375] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.544s)
[421.195656] Starting dynamic subtest: pipe-B-eDP-1
[422.802587] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.614s)
[422.829933] Starting dynamic subtest: pipe-C-eDP-1
[424.391934] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.568s)
[424.418072] Starting dynamic subtest: pipe-D-eDP-1
[425.905750] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.494s)
[425.907950] Subtest legacy: SUCCESS (5.282s)
[427.130561] [099/131] (591s left) kms_color (legacy-gamma)
[427.236162] Starting subtest: legacy-gamma
[427.238511] Starting dynamic subtest: pipe-A-eDP-1
[427.947925] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.711s)
[427.956963] Starting dynamic subtest: pipe-B-eDP-1
[429.327148] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.375s)
[429.337586] Starting dynamic subtest: pipe-C-eDP-1
[430.690149] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.363s)
[430.701833] Starting dynamic subtest: pipe-D-eDP-1
[432.037154] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.345s)
[432.038997] Subtest legacy-gamma: SUCCESS (4.798s)
[432.862255] [100/131] (586s left) kms_invalid_mode (bad-hsync-end)
[432.966227] Starting subtest: bad-hsync-end
[432.968619] Starting dynamic subtest: pipe-A-eDP-1
[433.254402] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.289s)
[433.264209] Starting dynamic subtest: pipe-B-eDP-1
[433.266444] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[433.275066] Starting dynamic subtest: pipe-C-eDP-1
[433.277270] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[433.285983] Starting dynamic subtest: pipe-D-eDP-1
[433.287920] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[433.289783] Subtest bad-hsync-end: SUCCESS (0.295s)
[434.093525] [101/131] (585s left) kms_scaling_modes (scaling-mode-center)
[434.191927] Starting subtest: scaling-mode-center
[434.194098] Starting dynamic subtest: pipe-A-eDP-1
[434.290167] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.097s)
[434.299207] Starting dynamic subtest: pipe-B-eDP-1
[435.426355] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.135s)
[435.436822] Starting dynamic subtest: pipe-C-eDP-1
[436.533343] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.106s)
[436.543684] Starting dynamic subtest: pipe-D-eDP-1
[437.659738] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.125s)
[437.662169] Subtest scaling-mode-center: SUCCESS (3.468s)
[438.819888] [102/131] (580s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions)
[438.952703] Starting subtest: cursorB-vs-flipA-atomic-transitions
[438.957320] Subtest cursorB-vs-flipA-atomic-transitions: SKIP (0.002s)
[439.096831] [103/131] (580s left) kms_ccs (missing-ccs-buffer-y-tiled-ccs)
[439.200131] Starting subtest: missing-ccs-buffer-y-tiled-ccs
[439.202441] Starting dynamic subtest: pipe-A-eDP-1
[439.225488] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[439.241854] Starting dynamic subtest: pipe-B-eDP-1
[439.263529] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[439.272716] Starting dynamic subtest: pipe-C-eDP-1
[439.294347] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[439.302948] Starting dynamic subtest: pipe-D-eDP-1
[439.324781] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[439.329321] Subtest missing-ccs-buffer-y-tiled-ccs: SKIP (0.004s)
[441.491008] [104/131] (577s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[441.599138] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-blt
[441.603071] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[441.711787] [105/131] (577s left) gem_exec_schedule (preempt-queue-contexts)
[441.780127] Subtest preempt-queue-contexts: SKIP (0.000s)
[442.062420] [106/131] (577s left) kms_getfb (getfb2-handle-protection)
[442.174136] Starting subtest: getfb2-handle-protection
[442.196738] Subtest getfb2-handle-protection: SUCCESS (0.024s)
[442.331236] [107/131] (576s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[442.436392] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[442.440439] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[442.541463] [108/131] (576s left) gem_exec_reloc (basic-wc-read)
[442.600715] Subtest basic-wc-read: SKIP (0.000s)
[442.676762] [109/131] (576s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area-big-fb)
[447.646469] Starting subtest: pr-primary-plane-update-sf-dmg-area-big-fb
[447.651204] Subtest pr-primary-plane-update-sf-dmg-area-big-fb: SKIP (0.003s)
[448.515240] [110/131] (570s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible)
[448.618238] Starting subtest: flip-vs-absolute-wf_vblank-interruptible
[448.620601] Starting dynamic subtest: A-eDP1
[457.561615] Dynamic subtest A-eDP1: SUCCESS (8.942s)
[457.572448] Starting dynamic subtest: B-eDP1
[466.486512] Dynamic subtest B-eDP1: SUCCESS (8.921s)
[466.495489] Starting dynamic subtest: C-eDP1
[475.419605] Dynamic subtest C-eDP1: SUCCESS (8.929s)
[475.431522] Starting dynamic subtest: D-eDP1
[484.361183] Dynamic subtest D-eDP1: SUCCESS (8.936s)
[484.363639] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (35.736s)
[485.593844] [111/131] (533s left) i915_pm_rc6_residency (media-rc6-accuracy)
[485.650542] Starting subtest: media-rc6-accuracy
[485.655068] Subtest media-rc6-accuracy: SKIP (0.000s)
[485.764052] [112/131] (533s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[485.857028] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[485.859297] Starting dynamic subtest: pipe-A
[486.195208] Dynamic subtest pipe-A: SUCCESS (0.338s)
[486.205108] Starting dynamic subtest: pipe-B
[487.594463] Dynamic subtest pipe-B: SUCCESS (1.398s)
[487.605101] Starting dynamic subtest: pipe-C
[489.034339] Dynamic subtest pipe-C: SUCCESS (1.437s)
[489.049732] Starting dynamic subtest: pipe-D
[490.467013] Dynamic subtest pipe-D: SUCCESS (1.432s)
[490.469485] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (4.609s)
[491.641858] [113/131] (527s left) gem_userptr_blits (unsync-unmap-after-close)
[491.716951] Subtest unsync-unmap-after-close: SKIP (0.000s)
[491.845764] [114/131] (527s left) gen9_exec_parse (unaligned-access)
[491.910404] Subtest unaligned-access: SKIP (0.000s)
[492.004123] [115/131] (527s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[492.103193] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[492.927324] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.826s)
[493.109893] [116/131] (526s left) kms_async_flips (crc)
[493.216557] Starting subtest: crc
[493.595616] Starting dynamic subtest: pipe-A-eDP-1
[496.472035] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.875s)
[496.853596] Starting dynamic subtest: pipe-B-eDP-1
[499.807898] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.964s)
[500.183507] Starting dynamic subtest: pipe-C-eDP-1
[503.105378] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.932s)
[503.473453] Starting dynamic subtest: pipe-D-eDP-1
[506.330710] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.866s)
[506.333064] Subtest crc: SUCCESS (13.110s)
[507.502537] [117/131] (512s left) perf (enable-disable)
[507.582313] Starting subtest: enable-disable
[507.584732] Starting dynamic subtest: 0-rcs0
[509.852916] Dynamic subtest 0-rcs0: SUCCESS (2.268s)
[509.862781] Starting dynamic subtest: 1-vecs0
[514.332211] Dynamic subtest 1-vecs0: SUCCESS (4.478s)
[514.334684] Subtest enable-disable: SUCCESS (6.747s)
[514.382937] [118/131] (505s left) perf (non-system-wide-paranoid)
[514.456294] Starting subtest: non-system-wide-paranoid
[514.482513] Subtest non-system-wide-paranoid: SUCCESS (0.026s)
[514.524392] [119/131] (505s left) kms_sequence (get-idle)
[514.614724] Starting subtest: get-idle
[514.617116] Starting dynamic subtest: pipe-A-eDP-1
[516.421754] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.805s)
[516.431338] Starting dynamic subtest: pipe-B-eDP-1
[518.872159] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.448s)
[518.884426] Starting dynamic subtest: pipe-C-eDP-1
[521.301616] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.426s)
[521.312028] Starting dynamic subtest: pipe-D-eDP-1
[523.717138] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.413s)
[523.719024] Subtest get-idle: SUCCESS (9.097s)
[524.534286] [120/131] (495s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[524.639848] Starting subtest: fbc-indfb-scaledprimary
[529.737622] Subtest fbc-indfb-scaledprimary: SUCCESS (5.101s)
[530.889822] [121/131] (489s left) kms_frontbuffer_tracking (fbc-1p-shrfb-fliptrack-mmap-gtt)
[531.005533] Starting subtest: fbc-1p-shrfb-fliptrack-mmap-gtt
[531.010017] Subtest fbc-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[531.120229] [122/131] (488s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-fullscreen)
[531.220536] Starting subtest: fbc-2p-primscrn-spr-indfb-fullscreen
[531.224653] Subtest fbc-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[531.312110] [123/131] (488s left) kms_pm_rpm (pm-tiling)
[532.845401] Starting subtest: pm-tiling
[532.856268] Subtest pm-tiling: SKIP (0.000s)
[533.577614] [124/131] (486s left) gem_exec_params (invalid-flag)
[533.645669] Starting subtest: invalid-flag
[533.647935] Subtest invalid-flag: SUCCESS (0.000s)
[533.765561] [125/131] (486s left) gem_exec_create (forked)
[533.828545] Starting subtest: forked
[533.830861] Starting dynamic subtest: smem
[555.626723] Dynamic subtest smem: SUCCESS (21.803s)
[555.629197] Subtest forked: SUCCESS (21.804s)
[555.810218] [126/131] (464s left) gem_softpin (allocator-basic-reserve)
[555.872875] Starting subtest: allocator-basic-reserve
[555.944938] Subtest allocator-basic-reserve: SUCCESS (0.071s)
[556.111003] [127/131] (464s left) gem_exec_balancer (invalid-bonds)
[556.177599] Starting subtest: invalid-bonds
[556.181992] Subtest invalid-bonds: SKIP (0.000s)
[556.523187] [128/131] (463s left) gem_exec_schedule (pi-common)
[556.705799] Starting subtest: pi-common
[556.708821] Starting dynamic subtest: rcs0
[556.792838] Dynamic subtest rcs0: SUCCESS (0.089s)
[556.804231] Starting dynamic subtest: bcs0
[556.885455] Dynamic subtest bcs0: SUCCESS (0.092s)
[556.895947] Starting dynamic subtest: vcs0
[556.967436] Dynamic subtest vcs0: SUCCESS (0.081s)
[556.982370] Starting dynamic subtest: vcs1
[557.048588] Dynamic subtest vcs1: SUCCESS (0.080s)
[557.063891] Starting dynamic subtest: vecs0
[557.128777] Dynamic subtest vecs0: SUCCESS (0.079s)
[557.143159] Starting dynamic subtest: ccs0
[557.208816] Dynamic subtest ccs0: SUCCESS (0.078s)
[557.211493] Subtest pi-common: SUCCESS (0.506s)
[557.453565] [129/131] (462s left) gem_exec_reloc (basic-cpu-read)
[557.514160] Subtest basic-cpu-read: SKIP (0.000s)
[557.590024] [130/131] (462s left) i915_drm_fdinfo (virtual-busy-idle)
[557.628298] Starting subtest: virtual-busy-idle
[557.632745] Subtest virtual-busy-idle: SKIP (0.000s)
[557.691827] [131/131] (462s left) kms_psr (pr-sprite-render)
[557.795687] Starting subtest: pr-sprite-render
[557.800054] Subtest pr-sprite-render: SKIP (0.000s)
[557.865084] Closing watchdogs
results: parsing output: 0/ for test: perf_pmu
results: parsing output: 1/ for test: syncobj_wait
results: parsing output: 2/ for test: gem_wait
results: parsing output: 3/ for test: kms_plane_lowres
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: gem_exec_fence
results: parsing output: 6/ for test: syncobj_timeline
results: parsing output: 7/ for test: gem_ctx_param
results: parsing output: 8/ for test: syncobj_wait
results: parsing output: 9/ for test: kms_dsc
results: parsing output: 10/ for test: gem_render_copy
results: parsing output: 11/ for test: gem_exec_schedule
results: parsing output: 12/ for test: kms_psr
results: parsing output: 13/ for test: api_intel_bb
results: parsing output: 14/ for test: gem_create
results: parsing output: 15/ for test: kms_big_fb
results: parsing output: 16/ for test: kms_ccs
results: parsing output: 17/ for test: kms_big_fb
results: parsing output: 18/ for test: kms_atomic
results: parsing output: 19/ for test: gem_mmap_offset
results: parsing output: 20/ for test: perf_pmu
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: gem_exec_params
results: parsing output: 23/ for test: api_intel_bb
results: parsing output: 24/ for test: gem_userptr_blits
results: parsing output: 25/ for test: syncobj_timeline
results: parsing output: 26/ for test: gem_ctx_shared
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: gem_userptr_blits
results: parsing output: 29/ for test: gem_mmap_gtt
results: parsing output: 30/ for test: sw_sync
results: parsing output: 31/ for test: gem_mmap_wc
results: parsing output: 32/ for test: kms_cursor_edge_walk
results: parsing output: 33/ for test: prime_vgem
results: parsing output: 34/ for test: gem_tiled_wc
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: gem_exec_fence
results: parsing output: 37/ for test: kms_flip_scaled_crc
results: parsing output: 38/ for test: gem_mmap_gtt
results: parsing output: 39/ for test: kms_plane_scaling
results: parsing output: 40/ for test: kms_chamelium_hpd
results: parsing output: 41/ for test: kms_cursor_crc
results: parsing output: 42/ for test: sw_sync
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: kms_cursor_crc
results: parsing output: 45/ for test: kms_big_fb
results: parsing output: 46/ for test: kms_ccs
results: parsing output: 47/ for test: i915_pm_sseu
results: parsing output: 48/ for test: kms_addfb_basic
results: parsing output: 49/ for test: sysfs_defaults
results: parsing output: 50/ for test: kms_big_fb
results: parsing output: 51/ for test: kms_feature_discovery
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: gem_ctx_bad_destroy
results: parsing output: 54/ for test: gem_linear_blits
results: parsing output: 55/ for test: gem_exec_reloc
results: parsing output: 56/ for test: kms_psr2_sf
results: parsing output: 57/ for test: kms_writeback
results: parsing output: 58/ for test: kms_big_fb
results: parsing output: 59/ for test: gem_ctx_param
results: parsing output: 60/ for test: kms_psr
results: parsing output: 61/ for test: syncobj_timeline
results: parsing output: 62/ for test: kms_flip
results: parsing output: 63/ for test: gem_spin_batch
results: parsing output: 64/ for test: syncobj_timeline
results: parsing output: 65/ for test: gem_exec_whisper
results: parsing output: 66/ for test: drm_mm
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: kms_psr2_su
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: syncobj_timeline
results: parsing output: 72/ for test: perf
results: parsing output: 73/ for test: kms_rotation_crc
results: parsing output: 74/ for test: gem_lmem_swapping
results: parsing output: 75/ for test: i915_query
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_ccs
results: parsing output: 78/ for test: kms_ccs
results: parsing output: 79/ for test: syncobj_wait
results: parsing output: 80/ for test: kms_content_protection
results: parsing output: 81/ for test: kms_atomic_transition
results: parsing output: 82/ for test: gem_create
results: parsing output: 83/ for test: gem_exec_balancer
results: parsing output: 84/ for test: kms_psr
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: kms_flip
results: parsing output: 87/ for test: gem_userptr_blits
results: parsing output: 88/ for test: gem_pwrite
results: parsing output: 89/ for test: kms_lease
results: parsing output: 90/ for test: syncobj_wait
results: parsing output: 91/ for test: gem_exec_params
results: parsing output: 92/ for test: gem_partial_pwrite_pread
results: parsing output: 93/ for test: kms_pipe_crc_basic
results: parsing output: 94/ for test: kms_pm_dc
results: parsing output: 95/ for test: kms_chamelium_frames
results: parsing output: 96/ for test: kms_flip
results: parsing output: 97/ for test: kms_panel_fitting
results: parsing output: 98/ for test: kms_color
results: parsing output: 99/ for test: kms_invalid_mode
results: parsing output: 100/ for test: kms_scaling_modes
results: parsing output: 101/ for test: kms_cursor_legacy
results: parsing output: 102/ for test: kms_ccs
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: gem_exec_schedule
results: parsing output: 105/ for test: kms_getfb
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: gem_exec_reloc
results: parsing output: 108/ for test: kms_psr2_sf
results: parsing output: 109/ for test: kms_flip
results: parsing output: 110/ for test: i915_pm_rc6_residency
results: parsing output: 111/ for test: kms_plane_scaling
results: parsing output: 112/ for test: gem_userptr_blits
results: parsing output: 113/ for test: gen9_exec_parse
results: parsing output: 114/ for test: kms_big_fb
results: parsing output: 115/ for test: kms_async_flips
results: parsing output: 116/ for test: perf
results: parsing output: 117/ for test: perf
results: parsing output: 118/ for test: kms_sequence
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_pm_rpm
results: parsing output: 123/ for test: gem_exec_params
results: parsing output: 124/ for test: gem_exec_create
results: parsing output: 125/ for test: gem_softpin
results: parsing output: 126/ for test: gem_exec_balancer
results: parsing output: 127/ for test: gem_exec_schedule
results: parsing output: 128/ for test: gem_exec_reloc
results: parsing output: 129/ for test: i915_drm_fdinfo
results: parsing output: 130/ for test: kms_psr
Done.