[54.499377] Initializing watchdogs
[54.499427]   /dev/watchdog0
[54.513190] [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.517764] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[54.519964] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[54.523840] [001/131] (960s left) perf_pmu (most-busy-idle-check-all)
[54.577734] Starting subtest: most-busy-idle-check-all
[54.579959] Starting dynamic subtest: rcs0
[55.891533] Dynamic subtest rcs0: SUCCESS (1.339s)
[55.901286] Starting dynamic subtest: bcs0
[57.939772] Dynamic subtest bcs0: SUCCESS (2.081s)
[57.948622] Starting dynamic subtest: vcs0
[59.923977] Dynamic subtest vcs0: SUCCESS (2.008s)
[59.932655] Starting dynamic subtest: vcs1
[61.908059] Dynamic subtest vcs1: SUCCESS (2.003s)
[61.918342] Starting dynamic subtest: vecs0
[63.892303] Dynamic subtest vecs0: SUCCESS (1.998s)
[63.902652] Starting dynamic subtest: ccs0
[65.939476] Dynamic subtest ccs0: SUCCESS (2.058s)
[65.942234] Subtest most-busy-idle-check-all: SUCCESS (11.489s)
[66.039224] [002/131] (949s left) syncobj_wait (multi-wait-submitted)
[66.104912] Starting subtest: multi-wait-submitted
[66.216103] Subtest multi-wait-submitted: SUCCESS (0.109s)
[66.288583] [003/131] (948s left) gem_wait (write-wait)
[66.353584] Starting subtest: write-wait
[66.356064] Starting dynamic subtest: all-engines
[67.511904] Dynamic subtest all-engines: SUCCESS (1.163s)
[67.526792] Starting dynamic subtest: rcs0
[68.570661] Dynamic subtest rcs0: SUCCESS (1.062s)
[68.584255] Starting dynamic subtest: bcs0
[69.628400] Dynamic subtest bcs0: SUCCESS (1.061s)
[69.642892] Starting dynamic subtest: vcs0
[70.684286] Dynamic subtest vcs0: SUCCESS (1.058s)
[70.697635] Starting dynamic subtest: vcs1
[71.818910] Dynamic subtest vcs1: SUCCESS (1.136s)
[71.832790] Starting dynamic subtest: vecs0
[72.872413] Dynamic subtest vecs0: SUCCESS (1.056s)
[72.888433] Starting dynamic subtest: ccs0
[73.930348] Dynamic subtest ccs0: SUCCESS (1.059s)
[73.932805] Subtest write-wait: SUCCESS (7.599s)
[74.125710] [FACT gem_wait (write-wait)] new: kernel.kmod_is_loaded.vgem: true
[74.128838] [004/131] (941s left) kms_plane_lowres (tiling-4)
[74.235076] Starting subtest: tiling-4
[74.237406] Starting dynamic subtest: pipe-A-eDP-1
[74.241967] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[74.530960] Starting dynamic subtest: pipe-B-eDP-1
[74.535377] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[74.543939] Starting dynamic subtest: pipe-C-eDP-1
[74.548458] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[74.556232] Starting dynamic subtest: pipe-D-eDP-1
[74.560457] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[74.564673] Subtest tiling-4: SKIP (0.293s)
[75.324826] [005/131] (939s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)
[75.425190] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render
[82.115749] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.700s)
[82.252179] [006/131] (932s left) gem_exec_fence (basic-await)
[82.442077] Starting subtest: basic-await
[82.444611] Starting dynamic subtest: rcs0
[82.515592] Dynamic subtest rcs0: SUCCESS (0.074s)
[82.525615] Starting dynamic subtest: bcs0
[82.589807] Dynamic subtest bcs0: SUCCESS (0.074s)
[82.599689] Starting dynamic subtest: vcs0
[82.661876] Dynamic subtest vcs0: SUCCESS (0.071s)
[82.672006] Starting dynamic subtest: vcs1
[82.733929] Dynamic subtest vcs1: SUCCESS (0.072s)
[82.743615] Starting dynamic subtest: vecs0
[82.806322] Dynamic subtest vecs0: SUCCESS (0.072s)
[82.817661] Starting dynamic subtest: ccs0
[82.879449] Dynamic subtest ccs0: SUCCESS (0.073s)
[82.881679] Subtest basic-await: SUCCESS (0.438s)
[84.986779] [007/131] (930s left) syncobj_timeline (invalid-wait-illegal-handle)
[85.045940] Starting subtest: invalid-wait-illegal-handle
[85.048295] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[85.152951] [008/131] (930s left) gem_ctx_param (invalid-get-ringsize)
[85.210504] Starting subtest: invalid-get-ringsize
[85.212968] Subtest invalid-get-ringsize: SUCCESS (0.001s)
[85.310776] [009/131] (930s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[85.371546] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[85.373617] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[85.477788] [010/131] (929s left) kms_dsc (dsc-fractional-bpp)
[85.579568] Starting subtest: dsc-fractional-bpp
[85.592130] Subtest dsc-fractional-bpp: SKIP (0.000s)
[85.650377] [011/131] (929s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[85.718526] Starting subtest: yf-tiled-to-vebox-yf-tiled
[85.722876] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[85.900981] [012/131] (929s left) gem_exec_schedule (u-submit-late-slice)
[85.981622] Starting subtest: u-submit-late-slice
[85.983628] Starting dynamic subtest: rcs0
[86.006845] Dynamic subtest rcs0: SUCCESS (0.034s)
[86.017590] Starting dynamic subtest: bcs0
[86.035258] Dynamic subtest bcs0: SUCCESS (0.028s)
[86.043112] Starting dynamic subtest: vcs0
[86.062827] Dynamic subtest vcs0: SUCCESS (0.027s)
[86.071234] Starting dynamic subtest: vcs1
[86.091665] Dynamic subtest vcs1: SUCCESS (0.029s)
[86.099032] Starting dynamic subtest: vecs0
[86.119638] Dynamic subtest vecs0: SUCCESS (0.028s)
[86.129463] Starting dynamic subtest: ccs0
[86.147293] Dynamic subtest ccs0: SUCCESS (0.028s)
[86.149121] Subtest u-submit-late-slice: SUCCESS (0.175s)
[88.005296] [013/131] (927s left) kms_psr (psr-primary-mmap-cpu)
[88.101096] Starting subtest: psr-primary-mmap-cpu
[88.103403] Starting dynamic subtest: eDP-1
[89.849732] Dynamic subtest eDP-1: SUCCESS (1.749s)
[89.851648] Subtest psr-primary-mmap-cpu: SUCCESS (1.749s)
[90.728732] [014/131] (924s left) api_intel_bb (object-reloc-purge-cache)
[90.796639] Starting subtest: object-reloc-purge-cache
[90.800886] Subtest object-reloc-purge-cache: SKIP (0.002s)
[90.930734] [015/131] (924s left) gem_create (create-massive)
[90.987045] Starting subtest: create-massive
[90.989223] Subtest create-massive: SUCCESS (0.000s)
[91.164330] [016/131] (924s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0)
[91.268227] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0
[91.272464] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[91.366805] [017/131] (924s left) kms_ccs (bad-aux-stride-4-tiled-mtl-rc-ccs-cc)
[91.460776] Starting subtest: bad-aux-stride-4-tiled-mtl-rc-ccs-cc
[91.462977] Starting dynamic subtest: pipe-A-eDP-1
[91.482626] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[91.490662] Starting dynamic subtest: pipe-B-eDP-1
[91.523905] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[91.530973] Starting dynamic subtest: pipe-C-eDP-1
[91.549631] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[91.556812] Starting dynamic subtest: pipe-D-eDP-1
[91.577026] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[91.578905] Subtest bad-aux-stride-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.005s)
[93.708362] [018/131] (921s left) kms_big_fb (linear-8bpp-rotate-0)
[93.805280] Starting subtest: linear-8bpp-rotate-0
[94.959860] Subtest linear-8bpp-rotate-0: SUCCESS (1.156s)
[95.086516] [019/131] (920s left) kms_atomic (test-only)
[95.180294] Starting subtest: test-only
[95.224996] Starting dynamic subtest: pipe-A-eDP-1
[97.748754] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.521s)
[97.751077] Subtest test-only: SUCCESS (2.569s)
[98.616929] [020/131] (917s left) gem_mmap_offset (ptrace)
[98.677949] Starting subtest: ptrace
[98.680268] Starting dynamic subtest: smem0-wb
[98.692347] Dynamic subtest smem0-wb: SUCCESS (0.012s)
[98.702042] Starting dynamic subtest: smem0-wc
[98.709819] Dynamic subtest smem0-wc: SUCCESS (0.018s)
[98.717745] Starting dynamic subtest: smem0-uc
[98.726411] Dynamic subtest smem0-uc: SUCCESS (0.016s)
[98.728742] Subtest ptrace: SUCCESS (0.050s)
[98.846641] [021/131] (916s left) perf_pmu (busy-double-start)
[98.883221] Starting subtest: busy-double-start
[98.885452] Starting dynamic subtest: rcs0
[102.524349] Dynamic subtest rcs0: FAIL (3.588s)
[102.536589] Starting dynamic subtest: bcs0
[106.047327] Dynamic subtest bcs0: SUCCESS (3.575s)
[106.061224] Starting dynamic subtest: vcs0
[110.466253] Dynamic subtest vcs0: SUCCESS (4.418s)
[110.477373] Starting dynamic subtest: vcs1
[114.493870] Dynamic subtest vcs1: SUCCESS (4.027s)
[114.504332] Starting dynamic subtest: vecs0
[118.461993] Dynamic subtest vecs0: SUCCESS (3.967s)
[118.472638] Starting dynamic subtest: ccs0
[122.095905] Dynamic subtest ccs0: FAIL (3.564s)
[122.098314] Subtest busy-double-start: FAIL (23.143s)
[122.161051] [022/131] (893s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-fullscreen)
[122.263608] Starting subtest: fbc-1p-primscrn-spr-indfb-fullscreen
[126.223085] Subtest fbc-1p-primscrn-spr-indfb-fullscreen: SUCCESS (3.962s)
[127.403844] [023/131] (888s left) gem_exec_params (rsvd2-dirt)
[127.468448] Starting subtest: rsvd2-dirt
[127.472790] Subtest rsvd2-dirt: SKIP (0.000s)
[127.579998] [024/131] (888s left) api_intel_bb (object-noreloc-purge-cache-simple)
[127.636675] Starting subtest: object-noreloc-purge-cache-simple
[127.642316] Subtest object-noreloc-purge-cache-simple: SUCCESS (0.006s)
[127.741682] [025/131] (888s left) gem_userptr_blits (sync-unmap)
[127.814510] Starting subtest: sync-unmap
[127.826622] Subtest sync-unmap: SUCCESS (0.013s)
[127.967422] [026/131] (888s left) syncobj_timeline (device-signal-unordered)
[128.026881] Starting subtest: device-signal-unordered
[128.029185] Subtest device-signal-unordered: SUCCESS (0.001s)
[128.146257] [027/131] (887s left) gem_ctx_shared (q-smoketest)
[128.219523] Starting subtest: Q-smoketest
[128.221888] Starting dynamic subtest: rcs0
[133.769880] Dynamic subtest rcs0: SUCCESS (5.504s)
[133.777787] Starting dynamic subtest: bcs0
[139.260432] Dynamic subtest bcs0: SUCCESS (5.488s)
[139.269607] Starting dynamic subtest: vcs0
[144.752258] Dynamic subtest vcs0: SUCCESS (5.489s)
[144.761733] Starting dynamic subtest: vcs1
[150.228787] Dynamic subtest vcs1: SUCCESS (5.481s)
[150.237887] Starting dynamic subtest: vecs0
[155.713059] Dynamic subtest vecs0: SUCCESS (5.485s)
[155.725140] Starting dynamic subtest: ccs0
[161.210021] Dynamic subtest ccs0: SUCCESS (5.492s)
[161.212071] Subtest Q-smoketest: SUCCESS (32.942s)
[161.355906] [028/131] (854s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[161.458378] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[161.462877] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[161.546640] [029/131] (854s left) gem_userptr_blits (map-fixed-invalidate)
[161.628686] Starting subtest: map-fixed-invalidate
[161.632646] Subtest map-fixed-invalidate: SKIP (0.000s)
[161.756872] [030/131] (854s left) gem_mmap_gtt (big-bo)
[161.819074] Subtest big-bo: SKIP (0.000s)
[161.887838] [031/131] (854s left) sw_sync (sync_busy_fork)
[161.921676] Starting subtest: sync_busy_fork
[162.933869] Subtest sync_busy_fork: SUCCESS (1.011s)
[162.991442] [032/131] (853s left) gem_mmap_wc (coherency)
[163.044938] Subtest coherency: SKIP (0.000s)
[163.102478] [033/131] (853s left) kms_cursor_edge_walk (256x256-left-edge)
[163.192434] Starting subtest: 256x256-left-edge
[163.194685] Starting dynamic subtest: pipe-A-eDP-1
[166.657259] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.465s)
[166.665364] Starting dynamic subtest: pipe-D-eDP-1
[169.771701] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.113s)
[169.773583] Subtest 256x256-left-edge: SUCCESS (6.580s)
[170.590703] [034/131] (845s left) prime_vgem (coherency-blt)
[170.681048] Starting subtest: coherency-blt
[171.741568] Subtest coherency-blt: SUCCESS (1.059s)
[171.873610] [035/131] (844s left) gem_tiled_wc
[172.001907] [036/131] (844s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-pwrite)
[172.106692] Starting subtest: psr-1p-primscrn-cur-indfb-draw-pwrite
[178.839052] Subtest psr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.734s)
[178.937038] [037/131] (837s left) gem_exec_fence (syncobj-invalid-flags)
[179.377357] Starting subtest: syncobj-invalid-flags
[179.379837] Subtest syncobj-invalid-flags: SUCCESS (0.000s)
[180.949200] [038/131] (835s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[181.038177] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[181.040505] Starting dynamic subtest: pipe-A-default-mode
[181.326405] Dynamic subtest pipe-A-default-mode: SKIP (0.286s)
[181.331447] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SKIP (0.287s)
[182.148068] [039/131] (834s left) gem_mmap_gtt (hang-user)
[182.206905] Subtest hang-user: SKIP (0.000s)
[182.264813] [040/131] (834s left) kms_plane_scaling (plane-downscale-factor-0-75-with-pixel-format)
[182.357400] Starting subtest: plane-downscale-factor-0-75-with-pixel-format
[182.359748] Starting dynamic subtest: pipe-A
[183.517170] Dynamic subtest pipe-A: SUCCESS (1.163s)
[183.524907] Starting dynamic subtest: pipe-B
[185.744728] Dynamic subtest pipe-B: SUCCESS (2.227s)
[185.755102] Starting dynamic subtest: pipe-C
[186.945376] Dynamic subtest pipe-C: SUCCESS (1.200s)
[186.953561] Starting dynamic subtest: pipe-D
[188.175892] Dynamic subtest pipe-D: SUCCESS (1.230s)
[188.178249] Subtest plane-downscale-factor-0-75-with-pixel-format: SUCCESS (5.822s)
[189.364564] [041/131] (827s left) kms_chamelium_hpd (dp-hpd)
[189.767453] Subtest dp-hpd: SKIP (0.000s)
[190.583292] [042/131] (826s left) kms_cursor_crc (cursor-rapid-movement-128x42)
[190.798905] Starting subtest: cursor-rapid-movement-128x42
[190.844967] Subtest cursor-rapid-movement-128x42: SKIP (0.041s)
[191.054487] [043/131] (825s left) sw_sync (sync_multi_consumer_producer)
[191.087198] Starting subtest: sync_multi_consumer_producer
[191.369876] Subtest sync_multi_consumer_producer: SUCCESS (0.283s)
[191.428482] [044/131] (825s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[191.523427] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[191.527824] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[191.609167] [045/131] (825s left) kms_cursor_crc (cursor-offscreen-max-size)
[191.913663] Starting subtest: cursor-offscreen-max-size
[191.920415] Subtest cursor-offscreen-max-size: SKIP (0.000s)
[192.047222] [046/131] (824s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[192.146488] Starting subtest: yf-tiled-64bpp-rotate-90
[192.150677] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[192.232175] [047/131] (824s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs-cc)
[192.330233] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs-cc
[192.332457] Starting dynamic subtest: pipe-A-eDP-1
[192.363354] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[192.372770] Starting dynamic subtest: pipe-B-eDP-1
[192.392713] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[192.399866] Starting dynamic subtest: pipe-C-eDP-1
[192.417755] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[192.425922] Starting dynamic subtest: pipe-D-eDP-1
[192.443329] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[192.446863] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.005s)
[194.549792] [048/131] (822s left) i915_pm_sseu (full-enable)
[194.614078] Subtest full-enable: SKIP (0.000s)
[194.735956] [049/131] (822s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[194.822086] Starting subtest: bo-too-small-due-to-tiling
[194.826191] Subtest bo-too-small-due-to-tiling: SKIP (0.000s)
[194.925357] [050/131] (822s left) sysfs_defaults (readonly)
[194.978698] Starting subtest: readonly
[194.980941] Starting dynamic subtest: vecs0
[194.983304] Dynamic subtest vecs0: SUCCESS (0.000s)
[194.990982] Starting dynamic subtest: ccs0
[194.992824] Dynamic subtest ccs0: SUCCESS (0.000s)
[195.000149] Starting dynamic subtest: vcs0
[195.002027] Dynamic subtest vcs0: SUCCESS (0.000s)
[195.009196] Starting dynamic subtest: bcs0
[195.011066] Dynamic subtest bcs0: SUCCESS (0.000s)
[195.017990] Starting dynamic subtest: rcs0
[195.019806] Dynamic subtest rcs0: SUCCESS (0.000s)
[195.026857] Starting dynamic subtest: vcs1
[195.028774] Dynamic subtest vcs1: SUCCESS (0.000s)
[195.030658] Subtest readonly: SUCCESS (0.004s)
[195.093308] [051/131] (821s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[195.201780] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip
[195.206077] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[195.318384] [052/131] (821s left) kms_feature_discovery (psr1)
[195.402395] Starting subtest: psr1
[195.404311] Subtest psr1: SUCCESS (0.000s)
[195.476222] [053/131] (821s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-plflip-blt)
[195.574345] Starting subtest: psr-1p-primscrn-indfb-plflip-blt
[202.929048] Subtest psr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.357s)
[203.073915] [054/131] (814s left) gem_ctx_bad_destroy (invalid-ctx)
[203.120607] Starting subtest: invalid-ctx
[203.122763] Subtest invalid-ctx: SUCCESS (0.000s)
[203.191940] [055/131] (813s left) gem_linear_blits (basic)
[203.509946] Starting subtest: basic
[203.517550] Subtest basic: SUCCESS (0.011s)
[203.640909] [056/131] (813s left) gem_exec_reloc (basic-gtt-wc-noreloc)
[203.732585] Subtest basic-gtt-wc-noreloc: SKIP (0.000s)
[203.801844] [057/131] (813s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area)
[208.865798] Starting subtest: fbc-pr-overlay-primary-update-sf-dmg-area
[208.870435] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.003s)
[209.696387] [058/131] (807s left) kms_writeback (writeback-check-output-xrgb2101010)
[209.799240] Subtest writeback-check-output-XRGB2101010: SKIP (0.000s)
[209.841947] [059/131] (807s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[209.949963] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[210.729370] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.781s)
[210.888600] [060/131] (806s left) gem_ctx_param (non-root-set)
[210.938512] Starting subtest: non-root-set
[210.969507] Subtest non-root-set: SUCCESS (0.031s)
[211.062334] [061/131] (806s left) kms_psr (fbc-psr-primary-render)
[211.162886] Starting subtest: fbc-psr-primary-render
[211.165478] Starting dynamic subtest: eDP-1
[211.169961] Dynamic subtest eDP-1: SKIP (0.000s)
[211.174502] Subtest fbc-psr-primary-render: SKIP (0.001s)
[211.233876] [062/131] (806s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted)
[211.293282] Starting subtest: multi-wait-available-unsubmitted-submitted
[211.295491] Subtest multi-wait-available-unsubmitted-submitted: SUCCESS (0.000s)
[211.378407] [063/131] (806s left) kms_flip (nonexisting-fb)
[211.468308] Starting subtest: nonexisting-fb
[211.470461] Starting dynamic subtest: A-eDP1
[212.594234] Dynamic subtest A-eDP1: SUCCESS (1.128s)
[212.602489] Starting dynamic subtest: B-eDP1
[213.722726] Dynamic subtest B-eDP1: SUCCESS (1.127s)
[213.741112] Starting dynamic subtest: C-eDP1
[214.855550] Dynamic subtest C-eDP1: SUCCESS (1.130s)
[214.863816] Starting dynamic subtest: D-eDP1
[215.980652] Dynamic subtest D-eDP1: SUCCESS (1.127s)
[215.985809] Subtest nonexisting-fb: SUCCESS (4.520s)
[217.154406] [064/131] (800s left) gem_spin_batch (resubmit-new-all)
[217.218424] Starting subtest: resubmit-new-all
[217.220694] Starting dynamic subtest: rcs0
[217.233238] Dynamic subtest rcs0: SUCCESS (0.014s)
[217.241520] Starting dynamic subtest: bcs0
[217.245968] Dynamic subtest bcs0: SUCCESS (0.011s)
[217.253682] Starting dynamic subtest: vcs0
[217.256057] Dynamic subtest vcs0: SUCCESS (0.009s)
[217.264580] Starting dynamic subtest: vcs1
[217.266880] Dynamic subtest vcs1: SUCCESS (0.009s)
[217.275030] Starting dynamic subtest: vecs0
[217.277444] Dynamic subtest vecs0: SUCCESS (0.010s)
[217.289627] Starting dynamic subtest: ccs0
[217.291718] Dynamic subtest ccs0: SUCCESS (0.010s)
[217.293865] Subtest resubmit-new-all: SUCCESS (0.067s)
[218.939330] [065/131] (798s left) syncobj_timeline (etime-single-wait-all-for-submit-submitted)
[218.983440] Starting subtest: etime-single-wait-all-for-submit-submitted
[219.088382] Subtest etime-single-wait-all-for-submit-submitted: SUCCESS (0.104s)
[219.164917] [066/131] (798s left) gem_exec_whisper (basic-fds-forked)
[219.226852] Starting subtest: basic-fds-forked
[243.267582] Subtest basic-fds-forked: SUCCESS (24.038s)
[243.452601] [067/131] (774s left) drm_mm (drm_mm)
[243.498769] Starting subtest: drm_mm
[243.551867] Starting dynamic subtest: drm_test_mm_init
[243.676296] Dynamic subtest drm_test_mm_init: SUCCESS (0.121s)
[243.686393] Starting dynamic subtest: drm_test_mm_debug
[243.818863] Dynamic subtest drm_test_mm_debug: SUCCESS (0.143s)
[243.827739] Starting dynamic subtest: drm_test_mm_align32
[243.970894] Dynamic subtest drm_test_mm_align32: SUCCESS (0.152s)
[243.981238] Starting dynamic subtest: drm_test_mm_align64
[244.123752] Dynamic subtest drm_test_mm_align64: SUCCESS (0.153s)
[244.133605] Starting dynamic subtest: drm_test_mm_lowest
[244.243914] Dynamic subtest drm_test_mm_lowest: SUCCESS (0.119s)
[244.251753] Starting dynamic subtest: drm_test_mm_highest
[244.362540] Dynamic subtest drm_test_mm_highest: SUCCESS (0.119s)
[244.364476] Subtest drm_mm: SUCCESS (0.862s)
[244.564352] [068/131] (773s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render)
[244.668622] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render
[244.673084] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[244.777383] [069/131] (772s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-wc)
[244.869694] Starting subtest: fbcpsr-rgb565-draw-mmap-wc
[251.677654] Subtest fbcpsr-rgb565-draw-mmap-wc: SUCCESS (6.810s)
[251.813579] [070/131] (765s left) kms_psr2_su (page_flip-p010)
[251.882655] Subtest page_flip-P010: SKIP (0.000s)
[251.941126] [071/131] (765s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[252.037464] Starting subtest: fbc-rgb565-draw-blt
[256.360594] Subtest fbc-rgb565-draw-blt: SUCCESS (4.326s)
[257.482271] [072/131] (760s left) syncobj_timeline (single-wait-available-signaled)
[257.539076] Starting subtest: single-wait-available-signaled
[257.541073] Subtest single-wait-available-signaled: SUCCESS (0.000s)
[257.632641] [073/131] (760s left) perf (missing-sample-flags)
[257.761701] Starting subtest: missing-sample-flags
[257.764025] Subtest missing-sample-flags: SUCCESS (0.000s)
[257.803167] [074/131] (760s left) kms_rotation_crc (primary-y-tiled-reflect-x-270)
[257.902058] Starting subtest: primary-y-tiled-reflect-x-270
[257.905975] Subtest primary-y-tiled-reflect-x-270: SKIP (0.001s)
[257.972116] [075/131] (759s left) gem_lmem_swapping (random-engines)
[258.015077] Subtest random-engines: SKIP (0.000s)
[258.062789] [076/131] (759s left) i915_query (test-query-geometry-subslices)
[258.123748] Starting subtest: test-query-geometry-subslices
[258.125763] Subtest test-query-geometry-subslices: SUCCESS (0.000s)
[258.223750] [077/131] (759s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[258.322399] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[258.326691] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[258.438227] [078/131] (759s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs)
[258.529097] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs
[258.530919] Starting dynamic subtest: pipe-A-eDP-1
[275.524216] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.555s)
[275.535708] Starting dynamic subtest: pipe-B-eDP-1
[292.434935] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.402s)
[292.445645] Starting dynamic subtest: pipe-C-eDP-1
[309.382040] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.293s)
[309.393027] Starting dynamic subtest: pipe-D-eDP-1
[326.380039] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.245s)
[326.382346] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs: SUCCESS (8.500s)
[327.571305] [079/131] (690s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-mc-ccs)
[327.677899] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-mc-ccs
[327.680275] Starting dynamic subtest: pipe-A-eDP-1
[327.702544] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[327.712942] Starting dynamic subtest: pipe-B-eDP-1
[327.733535] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[327.741622] Starting dynamic subtest: pipe-C-eDP-1
[327.762624] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[327.770654] Starting dynamic subtest: pipe-D-eDP-1
[327.791517] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[327.795660] Subtest crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.006s)
[329.886149] [080/131] (688s left) syncobj_wait (multi-wait-all-submitted)
[329.950044] Starting subtest: multi-wait-all-submitted
[330.052535] Subtest multi-wait-all-submitted: SUCCESS (0.104s)
[330.129746] [081/131] (688s left) kms_content_protection (legacy)
[330.277823] Starting subtest: legacy
[334.058065] Subtest legacy: SKIP (3.844s)
[335.243780] [082/131] (682s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[335.352280] Starting subtest: plane-use-after-nonblocking-unbind
[335.354514] Starting dynamic subtest: pipe-A-eDP-1
[335.421360] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.071s)
[335.717139] Starting dynamic subtest: pipe-B-eDP-1
[336.543303] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.845s)
[336.837392] Subtest plane-use-after-nonblocking-unbind: SUCCESS (1.500s)
[337.685273] [083/131] (680s left) gem_create (create-ext-cpu-access-big)
[337.848057] Starting subtest: create-ext-cpu-access-big
[337.852929] Subtest create-ext-cpu-access-big: SKIP (0.001s)
[337.925949] [084/131] (680s left) gem_exec_balancer (busy)
[337.996454] Starting subtest: busy
[339.463501] Subtest busy: SUCCESS (1.477s)
[339.789873] [085/131] (678s left) kms_psr (fbc-psr2-sprite-render)
[339.882878] Starting subtest: fbc-psr2-sprite-render
[339.885176] Starting dynamic subtest: eDP-1
[339.889279] Dynamic subtest eDP-1: SKIP (0.000s)
[339.893397] Subtest fbc-psr2-sprite-render: SKIP (0.001s)
[339.957036] [086/131] (678s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-onoff)
[340.051960] Starting subtest: psr-2p-scndscrn-cur-indfb-onoff
[340.056646] Subtest psr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[340.162342] [087/131] (678s left) kms_flip (flip-vs-expired-vblank)
[340.254848] Starting subtest: flip-vs-expired-vblank
[340.257063] Starting dynamic subtest: A-eDP1
[348.960380] Dynamic subtest A-eDP1: SUCCESS (8.742s)
[348.969717] Starting dynamic subtest: B-eDP1
[357.663454] Dynamic subtest B-eDP1: SUCCESS (8.714s)
[357.675289] Starting dynamic subtest: C-eDP1
[366.386830] Dynamic subtest C-eDP1: SUCCESS (8.712s)
[366.398189] Starting dynamic subtest: D-eDP1
[375.021550] Dynamic subtest D-eDP1: SUCCESS (8.632s)
[375.027703] Subtest flip-vs-expired-vblank: SUCCESS (34.809s)
[376.196346] [088/131] (642s left) gem_userptr_blits (readonly-pwrite-unsync)
[376.272475] Subtest readonly-pwrite-unsync: SKIP (0.000s)
[376.418280] [089/131] (642s left) gem_pwrite (basic-self)
[376.478159] Subtest basic-self: SKIP (0.000s)
[376.538088] [090/131] (641s left) kms_lease (lease-again)
[376.638230] Starting subtest: lease-again
[376.640450] Starting dynamic subtest: pipe-A-eDP-1
[376.642727] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.002s)
[376.652514] Starting dynamic subtest: pipe-B-eDP-1
[376.654362] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[376.662911] Starting dynamic subtest: pipe-C-eDP-1
[376.664812] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[376.673514] Starting dynamic subtest: pipe-D-eDP-1
[376.675365] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[376.677189] Subtest lease-again: SUCCESS (0.013s)
[376.757985] [091/131] (641s left) syncobj_wait (invalid-wait-bad-flags)
[376.813464] Starting subtest: invalid-wait-bad-flags
[376.815556] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[376.931088] [092/131] (641s left) gem_exec_params (invalid-bsd2-flag-on-render)
[376.985193] Starting subtest: invalid-bsd2-flag-on-render
[376.987365] Subtest invalid-bsd2-flag-on-render: SUCCESS (0.000s)
[377.113530] [093/131] (641s left) gem_partial_pwrite_pread (write-snoop)
[377.168449] Subtest write-snoop: SKIP (0.000s)
[377.253520] [094/131] (641s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[377.339557] Starting subtest: nonblocking-crc-frame-sequence
[377.341365] Starting dynamic subtest: pipe-A-eDP-1
[378.068261] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.729s)
[378.080260] Starting dynamic subtest: pipe-B-eDP-1
[379.506993] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.436s)
[379.516549] Starting dynamic subtest: pipe-C-eDP-1
[380.896789] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.387s)
[380.906898] Starting dynamic subtest: pipe-D-eDP-1
[382.226170] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.327s)
[382.228413] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.886s)
[383.031183] [095/131] (635s left) kms_pm_dc (dc5-retention-flops)
[384.137315] Starting subtest: dc5-retention-flops
[384.141817] Subtest dc5-retention-flops: SKIP (0.000s)
[384.209867] [096/131] (634s left) kms_chamelium_frames (dp-crc-multiple)
[384.588209] Subtest dp-crc-multiple: SKIP (0.000s)
[385.440350] [097/131] (633s left) kms_flip (flip-vs-rmfb)
[385.531179] Starting subtest: flip-vs-rmfb
[385.533326] Starting dynamic subtest: A-eDP1
[394.161925] Dynamic subtest A-eDP1: SUCCESS (8.631s)
[394.172766] Starting dynamic subtest: B-eDP1
[402.791933] Dynamic subtest B-eDP1: SUCCESS (8.631s)
[402.801585] Starting dynamic subtest: C-eDP1
[411.444187] Dynamic subtest C-eDP1: SUCCESS (8.653s)
[411.456907] Starting dynamic subtest: D-eDP1
[420.179091] Dynamic subtest D-eDP1: SUCCESS (8.736s)
[420.183347] Subtest flip-vs-rmfb: SUCCESS (34.659s)
[421.424261] [098/131] (597s left) kms_panel_fitting (legacy)
[421.519326] Starting subtest: legacy
[421.521457] Starting dynamic subtest: pipe-A-eDP-1
[422.056516] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.539s)
[422.084355] Starting dynamic subtest: pipe-B-eDP-1
[423.600176] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.523s)
[423.629371] Starting dynamic subtest: pipe-C-eDP-1
[425.178083] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.554s)
[425.204673] Starting dynamic subtest: pipe-D-eDP-1
[426.699079] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.501s)
[426.701411] Subtest legacy: SUCCESS (5.180s)
[427.952836] [099/131] (590s left) kms_color (legacy-gamma)
[428.062231] Starting subtest: legacy-gamma
[428.064093] Starting dynamic subtest: pipe-A-eDP-1
[428.779977] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.711s)
[428.791201] Starting dynamic subtest: pipe-B-eDP-1
[430.129297] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.356s)
[430.138570] Starting dynamic subtest: pipe-C-eDP-1
[431.486554] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.355s)
[431.502330] Starting dynamic subtest: pipe-D-eDP-1
[432.829324] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.343s)
[432.831643] Subtest legacy-gamma: SUCCESS (4.768s)
[433.647287] [100/131] (585s left) kms_invalid_mode (bad-hsync-end)
[433.742309] Starting subtest: bad-hsync-end
[433.744589] Starting dynamic subtest: pipe-A-eDP-1
[434.028637] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.284s)
[434.037680] Starting dynamic subtest: pipe-B-eDP-1
[434.039574] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[434.047727] Starting dynamic subtest: pipe-C-eDP-1
[434.050066] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[434.059196] Starting dynamic subtest: pipe-D-eDP-1
[434.061424] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[434.063739] Subtest bad-hsync-end: SUCCESS (0.290s)
[434.885378] [101/131] (584s left) kms_scaling_modes (scaling-mode-center)
[434.992234] Starting subtest: scaling-mode-center
[434.994220] Starting dynamic subtest: pipe-A-eDP-1
[435.087963] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.097s)
[435.101523] Starting dynamic subtest: pipe-B-eDP-1
[436.297646] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.206s)
[436.308365] Starting dynamic subtest: pipe-C-eDP-1
[437.424027] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.125s)
[437.433349] Starting dynamic subtest: pipe-D-eDP-1
[438.563558] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.137s)
[438.565826] Subtest scaling-mode-center: SUCCESS (3.573s)
[439.739299] [102/131] (579s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions)
[439.866865] Starting subtest: cursorB-vs-flipA-atomic-transitions
[439.871450] Subtest cursorB-vs-flipA-atomic-transitions: SKIP (0.001s)
[440.011086] [103/131] (579s left) kms_ccs (missing-ccs-buffer-y-tiled-ccs)
[440.106015] Starting subtest: missing-ccs-buffer-y-tiled-ccs
[440.108282] Starting dynamic subtest: pipe-A-eDP-1
[440.131047] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[440.143024] Starting dynamic subtest: pipe-B-eDP-1
[440.164659] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[440.173201] Starting dynamic subtest: pipe-C-eDP-1
[440.195190] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[440.203346] Starting dynamic subtest: pipe-D-eDP-1
[440.224982] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[440.229312] Subtest missing-ccs-buffer-y-tiled-ccs: SKIP (0.004s)
[442.410011] [104/131] (576s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[442.509677] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-blt
[442.514090] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[442.622932] [105/131] (576s left) gem_exec_schedule (preempt-queue-contexts)
[442.700359] Subtest preempt-queue-contexts: SKIP (0.000s)
[442.985270] [106/131] (576s left) kms_getfb (getfb2-handle-protection)
[443.067204] Starting subtest: getfb2-handle-protection
[443.095805] Subtest getfb2-handle-protection: SUCCESS (0.029s)
[443.224952] [107/131] (575s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[443.321378] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[443.325378] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[443.432651] [108/131] (575s left) gem_exec_reloc (basic-wc-read)
[443.498273] Subtest basic-wc-read: SKIP (0.000s)
[443.588051] [109/131] (575s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area-big-fb)
[448.581174] Starting subtest: pr-primary-plane-update-sf-dmg-area-big-fb
[448.585406] Subtest pr-primary-plane-update-sf-dmg-area-big-fb: SKIP (0.002s)
[449.454863] [110/131] (569s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible)
[449.553526] Starting subtest: flip-vs-absolute-wf_vblank-interruptible
[449.555909] Starting dynamic subtest: A-eDP1
[458.503439] Dynamic subtest A-eDP1: SUCCESS (8.950s)
[458.513698] Starting dynamic subtest: B-eDP1
[467.429562] Dynamic subtest B-eDP1: SUCCESS (8.926s)
[467.439810] Starting dynamic subtest: C-eDP1
[476.351277] Dynamic subtest C-eDP1: SUCCESS (8.921s)
[476.363009] Starting dynamic subtest: D-eDP1
[485.273350] Dynamic subtest D-eDP1: SUCCESS (8.922s)
[485.275892] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (35.726s)
[486.510833] [111/131] (532s left) i915_pm_rc6_residency (media-rc6-accuracy)
[486.567589] Starting subtest: media-rc6-accuracy
[486.572211] Subtest media-rc6-accuracy: SKIP (0.000s)
[486.658063] [112/131] (532s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[486.752357] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[486.754701] Starting dynamic subtest: pipe-A
[487.090311] Dynamic subtest pipe-A: SUCCESS (0.338s)
[487.101890] Starting dynamic subtest: pipe-B
[488.510237] Dynamic subtest pipe-B: SUCCESS (1.420s)
[488.519345] Starting dynamic subtest: pipe-C
[489.950623] Dynamic subtest pipe-C: SUCCESS (1.440s)
[489.962388] Starting dynamic subtest: pipe-D
[491.381915] Dynamic subtest pipe-D: SUCCESS (1.431s)
[491.384205] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (4.631s)
[492.545189] [113/131] (526s left) gem_userptr_blits (unsync-unmap-after-close)
[492.624116] Subtest unsync-unmap-after-close: SKIP (0.000s)
[492.761689] [114/131] (526s left) gen9_exec_parse (unaligned-access)
[492.823232] Subtest unaligned-access: SKIP (0.000s)
[492.891948] [115/131] (526s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[492.999510] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[493.812626] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.815s)
[493.963520] [116/131] (525s left) kms_async_flips (crc)
[494.053848] Starting subtest: crc
[494.423745] Starting dynamic subtest: pipe-A-eDP-1
[497.366345] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.941s)
[497.754975] Starting dynamic subtest: pipe-B-eDP-1
[500.708532] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.967s)
[501.082517] Starting dynamic subtest: pipe-C-eDP-1
[504.000021] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.927s)
[504.376260] Starting dynamic subtest: pipe-D-eDP-1
[507.235854] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.872s)
[507.238265] Subtest crc: SUCCESS (13.186s)
[508.406800] [117/131] (511s left) perf (enable-disable)
[508.509524] Starting subtest: enable-disable
[508.511945] Starting dynamic subtest: 0-rcs0
[510.779519] Dynamic subtest 0-rcs0: SUCCESS (2.268s)
[510.789702] Starting dynamic subtest: 1-vecs0
[515.254512] Dynamic subtest 1-vecs0: SUCCESS (4.475s)
[515.256950] Subtest enable-disable: SUCCESS (6.745s)
[515.302588] [118/131] (504s left) perf (non-system-wide-paranoid)
[515.378193] Starting subtest: non-system-wide-paranoid
[515.399768] Subtest non-system-wide-paranoid: SUCCESS (0.022s)
[515.454665] [119/131] (504s left) kms_sequence (get-idle)
[515.546138] Starting subtest: get-idle
[515.548387] Starting dynamic subtest: pipe-A-eDP-1
[517.347538] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.800s)
[517.356884] Starting dynamic subtest: pipe-B-eDP-1
[519.774793] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.425s)
[519.784128] Starting dynamic subtest: pipe-C-eDP-1
[522.197363] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.424s)
[522.208280] Starting dynamic subtest: pipe-D-eDP-1
[524.628869] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.432s)
[524.631229] Subtest get-idle: SUCCESS (9.086s)
[525.440975] [120/131] (494s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[525.545524] Starting subtest: fbc-indfb-scaledprimary
[530.631604] Subtest fbc-indfb-scaledprimary: SUCCESS (5.090s)
[531.754006] [121/131] (488s left) kms_frontbuffer_tracking (fbc-1p-shrfb-fliptrack-mmap-gtt)
[531.858733] Starting subtest: fbc-1p-shrfb-fliptrack-mmap-gtt
[531.862869] Subtest fbc-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[531.982436] [122/131] (487s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-fullscreen)
[532.082717] Starting subtest: fbc-2p-primscrn-spr-indfb-fullscreen
[532.086834] Subtest fbc-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[532.200658] [123/131] (487s left) kms_pm_rpm (pm-tiling)
[533.734894] Starting subtest: pm-tiling
[533.740165] Subtest pm-tiling: SKIP (0.000s)
[534.494121] [124/131] (485s left) gem_exec_params (invalid-flag)
[534.547294] Starting subtest: invalid-flag
[534.549628] Subtest invalid-flag: SUCCESS (0.000s)
[534.666667] [125/131] (485s left) gem_exec_create (forked)
[534.733571] Starting subtest: forked
[534.735973] Starting dynamic subtest: smem
[556.496409] Dynamic subtest smem: SUCCESS (21.745s)
[556.500741] Subtest forked: SUCCESS (21.747s)
[556.664683] [126/131] (463s left) gem_softpin (allocator-basic-reserve)
[556.729167] Starting subtest: allocator-basic-reserve
[556.798064] Subtest allocator-basic-reserve: SUCCESS (0.069s)
[556.946696] [127/131] (463s left) gem_exec_balancer (invalid-bonds)
[557.000730] Starting subtest: invalid-bonds
[557.005282] Subtest invalid-bonds: SKIP (0.000s)
[557.305620] [128/131] (462s left) gem_exec_schedule (pi-common)
[557.483925] Starting subtest: pi-common
[557.485880] Starting dynamic subtest: rcs0
[557.572693] Dynamic subtest rcs0: SUCCESS (0.091s)
[557.586168] Starting dynamic subtest: bcs0
[557.659383] Dynamic subtest bcs0: SUCCESS (0.085s)
[557.679905] Starting dynamic subtest: vcs0
[557.743536] Dynamic subtest vcs0: SUCCESS (0.083s)
[557.755397] Starting dynamic subtest: vcs1
[557.824834] Dynamic subtest vcs1: SUCCESS (0.080s)
[557.841083] Starting dynamic subtest: vecs0
[557.902348] Dynamic subtest vecs0: SUCCESS (0.077s)
[557.918753] Starting dynamic subtest: ccs0
[557.979907] Dynamic subtest ccs0: SUCCESS (0.076s)
[557.982663] Subtest pi-common: SUCCESS (0.499s)
[558.213105] [129/131] (461s left) gem_exec_reloc (basic-cpu-read)
[558.269807] Subtest basic-cpu-read: SKIP (0.000s)
[558.364090] [130/131] (461s left) i915_drm_fdinfo (virtual-busy-idle)
[558.399567] Starting subtest: virtual-busy-idle
[558.403844] Subtest virtual-busy-idle: SKIP (0.000s)
[558.465542] [131/131] (461s left) kms_psr (pr-sprite-render)
[558.558857] Starting subtest: pr-sprite-render
[558.562948] Subtest pr-sprite-render: SKIP (0.000s)
[558.626803] 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.