[38.812666] Initializing watchdogs
[38.812725]   /dev/watchdog0
[38.819964] [001/130] (960s left) kms_flip (dpms-vs-vblank-race-interruptible)
[39.069069] Starting subtest: dpms-vs-vblank-race-interruptible
[39.074258] Starting dynamic subtest: A-DP4
[42.166188] Dynamic subtest A-DP4: SUCCESS (3.092s)
[42.176022] Starting dynamic subtest: B-DP4
[45.315833] Dynamic subtest B-DP4: SUCCESS (3.114s)
[45.322330] Starting dynamic subtest: C-DP4
[48.439965] Dynamic subtest C-DP4: SUCCESS (3.060s)
[48.448291] Starting dynamic subtest: D-DP4
[51.579245] Dynamic subtest D-DP4: SUCCESS (3.096s)
[51.580065] Subtest dpms-vs-vblank-race-interruptible: SUCCESS (12.369s)
[51.799670] [002/130] (947s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-blt)
[52.070475] Starting subtest: psr-2p-primscrn-spr-indfb-draw-blt
[52.072065] Subtest psr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[52.153805] [003/130] (947s left) prime_vgem (fence-read-hang)
[52.359052] Subtest fence-read-hang: SKIP (0.000s)
[52.496068] [004/130] (947s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-sf)
[52.738560] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[52.765738] [005/130] (946s left) gem_exec_alignment (pi)
[52.848511] Starting subtest: pi
[84.822395] Subtest pi: SUCCESS (31.902s)
[85.483268] [006/130] (914s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[85.774231] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[85.775929] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[85.870945] [007/130] (913s left) sysfs_timeslice_duration (timeout)
[85.986163] Starting subtest: timeout
[85.987051] Starting dynamic subtest: vecs0
[87.768110] Dynamic subtest vecs0: SUCCESS (1.780s)
[87.774855] Starting dynamic subtest: ccs0
[89.549140] Dynamic subtest ccs0: SUCCESS (1.780s)
[89.556555] Starting dynamic subtest: vcs0
[91.230032] Dynamic subtest vcs0: SUCCESS (1.680s)
[91.237677] Starting dynamic subtest: bcs0
[92.306933] Dynamic subtest bcs0: SUCCESS (1.076s)
[92.313631] Starting dynamic subtest: vecs1
[94.037162] Dynamic subtest vecs1: SUCCESS (1.729s)
[94.044385] Starting dynamic subtest: rcs0
[95.702111] Dynamic subtest rcs0: SUCCESS (1.664s)
[95.709266] Starting dynamic subtest: vcs1
[97.353986] Dynamic subtest vcs1: SUCCESS (1.651s)
[97.354852] Subtest timeout: SUCCESS (11.365s)
[97.449392] [008/130] (902s left) gem_exec_fence (submit)
[97.696934] Starting subtest: submit
[97.699189] Subtest submit: SKIP (0.000s)
[98.419923] [009/130] (901s left) kms_getfb (getfb-handle-valid)
[98.502684] Starting subtest: getfb-handle-valid
[98.503902] Subtest getfb-handle-valid: SUCCESS (0.000s)
[98.616374] [010/130] (901s left) kms_plane_scaling (planes-upscale-20x20)
[98.865441] Starting subtest: planes-upscale-20x20
[98.866238] Starting dynamic subtest: pipe-A
[98.999298] Dynamic subtest pipe-A: SUCCESS (0.133s)
[99.014329] Starting dynamic subtest: pipe-B
[99.239927] Dynamic subtest pipe-B: SUCCESS (0.240s)
[99.248413] Starting dynamic subtest: pipe-C
[99.447500] Dynamic subtest pipe-C: SUCCESS (0.207s)
[99.458323] Starting dynamic subtest: pipe-D
[99.672269] Dynamic subtest pipe-D: SUCCESS (0.224s)
[99.673267] Subtest planes-upscale-20x20: SUCCESS (0.807s)
[99.989797] [011/130] (899s left) api_intel_allocator (open-vm)
[100.084881] Starting subtest: open-vm
[100.106537] Subtest open-vm: SUCCESS (0.022s)
[100.215132] [012/130] (899s left) gem_render_tiled_blits (basic)
[100.297639] Subtest basic: SKIP (0.000s)
[100.341617] [013/130] (899s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[100.576283] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu
[100.577851] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[100.648263] [014/130] (899s left) gem_render_copy (yf-tiled-to-vebox-x-tiled)
[100.743858] Starting subtest: yf-tiled-to-vebox-x-tiled
[100.745458] Subtest yf-tiled-to-vebox-x-tiled: SKIP (0.000s)
[100.946734] [015/130] (898s left) kms_psr (psr2-primary-render)
[101.200065] Subtest psr2-primary-render: SKIP (0.000s)
[101.223759] [016/130] (898s left) gem_exec_await (wide-contexts)
[101.446334] Starting subtest: wide-contexts
[123.330310] Subtest wide-contexts: SUCCESS (21.882s)
[123.528743] [017/130] (876s left) kms_flip (plain-flip-ts-check)
[123.806149] Starting subtest: plain-flip-ts-check
[123.809776] Starting dynamic subtest: A-DP4
[131.963598] Dynamic subtest A-DP4: SUCCESS (8.152s)
[131.996487] Starting dynamic subtest: B-DP4
[140.032661] Dynamic subtest B-DP4: SUCCESS (8.066s)
[140.040713] Starting dynamic subtest: C-DP4
[148.089980] Dynamic subtest C-DP4: SUCCESS (8.056s)
[148.100441] Starting dynamic subtest: D-DP4
[156.144984] Dynamic subtest D-DP4: SUCCESS (8.053s)
[156.145830] Subtest plain-flip-ts-check: SUCCESS (32.340s)
[156.481158] [018/130] (843s left) kms_ccs (crc-primary-rotation-180-4-tiled-bmg-ccs)
[156.719867] Starting subtest: crc-primary-rotation-180-4-tiled-bmg-ccs
[156.721369] Subtest crc-primary-rotation-180-4-tiled-bmg-ccs: SKIP (0.000s)
[156.979624] [019/130] (843s left) kms_big_fb (yf-tiled-64bpp-rotate-180)
[157.229371] Starting subtest: yf-tiled-64bpp-rotate-180
[157.230925] Subtest yf-tiled-64bpp-rotate-180: SKIP (0.000s)
[157.356766] [020/130] (842s left) gem_exec_params (no-blt)
[157.454995] Starting subtest: no-blt
[157.456986] Subtest no-blt: SUCCESS (0.001s)
[157.603986] [021/130] (842s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[157.839962] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[158.458293] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SUCCESS (0.618s)
[158.657616] [022/130] (841s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-msflip-blt)
[158.913465] Starting subtest: fbcpsr-1p-primscrn-shrfb-msflip-blt
[158.915031] Subtest fbcpsr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[158.994687] [023/130] (841s left) gem_exec_reloc (basic-wc-cpu-active)
[159.103523] Subtest basic-wc-cpu-active: SKIP (0.000s)
[159.165642] [024/130] (841s left) gem_exec_params (rel-constants-invalid-ring)
[159.267167] Starting subtest: rel-constants-invalid-ring
[159.267962] Subtest rel-constants-invalid-ring: SUCCESS (0.000s)
[159.399212] [025/130] (840s left) syncobj_eventfd (timeline-wait-signaled)
[159.483654] Starting subtest: timeline-wait-signaled
[159.484461] Subtest timeline-wait-signaled: SUCCESS (0.000s)
[159.542413] [026/130] (840s left) kms_flip (flip-vs-expired-vblank-interruptible)
[159.774676] Starting subtest: flip-vs-expired-vblank-interruptible
[159.780212] Starting dynamic subtest: A-DP4
[167.588785] Dynamic subtest A-DP4: SUCCESS (7.808s)
[167.599921] Starting dynamic subtest: B-DP4
[175.324255] Dynamic subtest B-DP4: SUCCESS (7.734s)
[175.332125] Starting dynamic subtest: C-DP4
[183.059960] Dynamic subtest C-DP4: SUCCESS (7.735s)
[183.067251] Starting dynamic subtest: D-DP4
[190.795038] Dynamic subtest D-DP4: SUCCESS (7.734s)
[190.795875] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (31.020s)
[191.071550] [027/130] (809s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-pwrite)
[191.310055] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-pwrite
[191.311537] Subtest psr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[191.391183] [028/130] (809s left) kms_chamelium_hpd (hdmi-hpd-after-suspend)
[191.711545] Subtest hdmi-hpd-after-suspend: SKIP (0.000s)
[191.828278] [029/130] (808s left) drm_fdinfo (busy-check-all)
[191.910340] Starting subtest: busy-check-all
[191.911176] Starting dynamic subtest: rcs0
[191.936208] Dynamic subtest rcs0: SKIP (0.025s)
[191.943615] Starting dynamic subtest: bcs0
[191.945166] Dynamic subtest bcs0: SKIP (0.000s)
[191.952216] Starting dynamic subtest: vcs0
[191.953850] Dynamic subtest vcs0: SKIP (0.000s)
[191.960942] Starting dynamic subtest: vcs1
[191.962493] Dynamic subtest vcs1: SKIP (0.000s)
[191.969462] Starting dynamic subtest: vecs0
[191.971009] Dynamic subtest vecs0: SKIP (0.000s)
[191.977852] Starting dynamic subtest: vecs1
[191.979651] Dynamic subtest vecs1: SKIP (0.000s)
[191.986475] Starting dynamic subtest: ccs0
[191.988076] Dynamic subtest ccs0: SKIP (0.001s)
[191.989728] Subtest busy-check-all: SKIP (0.031s)
[192.000444] [030/130] (808s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-cpu)
[192.265271] Starting subtest: fbc-rgb565-draw-mmap-cpu
[194.564054] Subtest fbc-rgb565-draw-mmap-cpu: SUCCESS (2.299s)
[194.693203] [031/130] (805s left) prime_self_import (reimport-vs-gem_close-race)
[194.761672] Starting subtest: reimport-vs-gem_close-race
[199.828457] Subtest reimport-vs-gem_close-race: SUCCESS (5.067s)
[199.903418] [032/130] (800s left) kms_selftest (drm_dp_mst_helper)
[200.087245] Starting subtest: drm_dp_mst_helper
[200.093178] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_mode
[200.171527] Dynamic subtest drm_test_dp_mst_calc_pbn_mode: SUCCESS (0.079s)
[200.193310] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_div
[200.304152] Dynamic subtest drm_test_dp_mst_calc_pbn_div: SUCCESS (0.128s)
[200.325063] Starting dynamic subtest: drm_test_dp_mst_sideband_msg_req_decode
[200.440961] Dynamic subtest drm_test_dp_mst_sideband_msg_req_decode: SUCCESS (0.137s)
[200.442326] Subtest drm_dp_mst_helper: SUCCESS (0.354s)
[200.651396] [033/130] (799s left) kms_atomic (plane-invalid-params)
[200.912254] Starting subtest: plane-invalid-params
[200.914132] Starting dynamic subtest: pipe-A-DP-4
[201.772698] Dynamic subtest pipe-A-DP-4: SUCCESS (0.859s)
[201.773671] Subtest plane-invalid-params: SUCCESS (0.861s)
[202.035131] [034/130] (798s left) gem_exec_schedule (preemptive-hang)
[202.263664] Starting subtest: preemptive-hang
[202.265436] Starting dynamic subtest: rcs0
[212.141236] Dynamic subtest rcs0: SUCCESS (9.877s)
[212.148799] Starting dynamic subtest: bcs0
[221.109854] Dynamic subtest bcs0: SUCCESS (8.967s)
[221.117309] Starting dynamic subtest: vcs0
[230.136393] Dynamic subtest vcs0: SUCCESS (9.026s)
[230.143804] Starting dynamic subtest: vcs1
[239.158457] Dynamic subtest vcs1: SUCCESS (9.021s)
[239.166092] Starting dynamic subtest: vecs0
[248.127312] Dynamic subtest vecs0: SUCCESS (8.967s)
[248.134994] Starting dynamic subtest: vecs1
[257.151423] Dynamic subtest vecs1: SUCCESS (9.023s)
[257.159196] Starting dynamic subtest: ccs0
[266.094292] Dynamic subtest ccs0: SUCCESS (8.942s)
[266.095124] Subtest preemptive-hang: SUCCESS (63.828s)
[266.347266] [035/130] (734s left) syncobj_timeline (multi-wait-available-signaled)
[266.420681] Starting subtest: multi-wait-available-signaled
[266.421492] Subtest multi-wait-available-signaled: SUCCESS (0.001s)
[266.518119] [036/130] (734s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-downscaling)
[266.771887] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-downscaling
[266.772735] Starting dynamic subtest: pipe-A-valid-mode
[266.854203] Dynamic subtest pipe-A-valid-mode: SKIP (0.080s)
[266.855826] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-downscaling: SKIP (0.082s)
[267.040286] [037/130] (733s left) gem_exec_schedule (u-submit-early-slice)
[267.149990] Starting subtest: u-submit-early-slice
[267.150834] Starting dynamic subtest: rcs0
[267.219200] Dynamic subtest rcs0: SUCCESS (0.069s)
[267.226724] Starting dynamic subtest: bcs0
[267.278854] Dynamic subtest bcs0: SUCCESS (0.059s)
[267.286399] Starting dynamic subtest: vcs0
[267.325854] Dynamic subtest vcs0: SUCCESS (0.046s)
[267.333457] Starting dynamic subtest: vcs1
[267.385955] Dynamic subtest vcs1: SUCCESS (0.059s)
[267.395319] Starting dynamic subtest: vecs0
[267.434463] Dynamic subtest vecs0: SUCCESS (0.048s)
[267.442034] Starting dynamic subtest: vecs1
[267.502965] Dynamic subtest vecs1: SUCCESS (0.068s)
[267.514187] Starting dynamic subtest: ccs0
[267.554704] Dynamic subtest ccs0: SUCCESS (0.050s)
[267.556101] Subtest u-submit-early-slice: SUCCESS (0.405s)
[267.977320] [038/130] (732s left) kms_plane (plane-position-covered)
[268.224926] Starting subtest: plane-position-covered
[268.628174] Starting dynamic subtest: pipe-A-plane-1
[269.076425] Dynamic subtest pipe-A-plane-1: SUCCESS (0.450s)
[269.084180] Starting dynamic subtest: pipe-A-plane-2
[269.535900] Dynamic subtest pipe-A-plane-2: SUCCESS (0.458s)
[269.543552] Starting dynamic subtest: pipe-A-plane-3
[269.973477] Dynamic subtest pipe-A-plane-3: SUCCESS (0.436s)
[269.993330] Starting dynamic subtest: pipe-A-plane-4
[270.464659] Dynamic subtest pipe-A-plane-4: SUCCESS (0.491s)
[270.474470] Starting dynamic subtest: pipe-A-plane-5
[270.484332] Dynamic subtest pipe-A-plane-5: SUCCESS (0.019s)
[270.851651] Starting dynamic subtest: pipe-B-plane-1
[271.271258] Dynamic subtest pipe-B-plane-1: SUCCESS (0.428s)
[271.279137] Starting dynamic subtest: pipe-B-plane-2
[271.714027] Dynamic subtest pipe-B-plane-2: SUCCESS (0.442s)
[271.721382] Starting dynamic subtest: pipe-B-plane-3
[272.139109] Dynamic subtest pipe-B-plane-3: SUCCESS (0.425s)
[272.146928] Starting dynamic subtest: pipe-B-plane-4
[272.582089] Dynamic subtest pipe-B-plane-4: SUCCESS (0.442s)
[272.589731] Starting dynamic subtest: pipe-B-plane-5
[272.597119] Dynamic subtest pipe-B-plane-5: SUCCESS (0.015s)
[272.597944] Subtest plane-position-covered: SUCCESS (4.374s)
[272.819945] [039/130] (727s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite)
[273.072432] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite
[273.074006] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[273.140925] [040/130] (727s left) i915_power (sanity)
[273.223131] Starting subtest: sanity
[277.277166] Subtest sanity: SUCCESS (4.053s)
[277.414312] [041/130] (723s left) kms_frontbuffer_tracking (fbc-tiling-y)
[277.646773] Starting subtest: fbc-tiling-y
[277.648335] Subtest fbc-tiling-y: SKIP (0.000s)
[277.731127] [042/130] (723s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-rc-ccs)
[277.981023] Starting subtest: missing-ccs-buffer-y-tiled-gen12-rc-ccs
[277.981840] Starting dynamic subtest: pipe-A-DP-4
[277.989875] Dynamic subtest pipe-A-DP-4: SKIP (0.000s)
[277.997363] Starting dynamic subtest: pipe-B-DP-4
[278.004881] Dynamic subtest pipe-B-DP-4: SKIP (0.000s)
[278.012328] Starting dynamic subtest: pipe-C-DP-4
[278.019900] Dynamic subtest pipe-C-DP-4: SKIP (0.000s)
[278.026864] Starting dynamic subtest: pipe-D-DP-4
[278.034462] Dynamic subtest pipe-D-DP-4: SKIP (0.000s)
[278.035964] Subtest missing-ccs-buffer-y-tiled-gen12-rc-ccs: SKIP (0.006s)
[278.399656] [043/130] (722s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[278.653151] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[278.653938] Starting dynamic subtest: pipe-A-valid-mode
[278.731420] Dynamic subtest pipe-A-valid-mode: SKIP (0.076s)
[278.733054] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.078s)
[278.917700] [044/130] (722s left) gem_userptr_blits (readonly-unsync)
[279.008819] Subtest readonly-unsync: SKIP (0.000s)
[279.170876] [045/130] (721s left) kms_chamelium_color (gamma)
[279.412095] Subtest gamma: SKIP (0.000s)
[279.439746] [046/130] (721s left) gem_mmap_offset (oob-read)
[279.516067] Starting subtest: oob-read
[279.516859] Subtest oob-read: SUCCESS (0.000s)
[279.614594] [047/130] (721s left) kms_content_protection (dp-mst-type-0)
[279.904964] Starting subtest: dp-mst-type-0
[279.907161] Subtest dp-mst-type-0: SKIP (0.000s)
[280.074559] [048/130] (721s left) kms_plane_alpha_blend (constant-alpha-max)
[280.330214] Starting subtest: constant-alpha-max
[280.546151] Starting dynamic subtest: pipe-A-DP-4
[281.486270] Dynamic subtest pipe-A-DP-4: SUCCESS (0.939s)
[281.493771] Starting dynamic subtest: pipe-D-DP-4
[282.488168] Dynamic subtest pipe-D-DP-4: SUCCESS (1.001s)
[282.488979] Subtest constant-alpha-max: SUCCESS (2.159s)
[282.744489] [049/130] (718s left) kms_pm_rpm (dpms-mode-unset-non-lpsp)
[285.138562] Starting subtest: dpms-mode-unset-non-lpsp
[286.568663] Subtest dpms-mode-unset-non-lpsp: SUCCESS (1.430s)
[286.944131] [050/130] (714s left) core_auth (many-magics)
[287.034069] Starting subtest: many-magics
[288.295209] Subtest many-magics: SUCCESS (1.261s)
[288.634714] [051/130] (712s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[288.707249] Starting subtest: single-wait-all-for-submit-available-submitted
[288.708081] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[288.790277] [052/130] (712s left) gem_ctx_persistence (heartbeat-many)
[288.882472] Starting subtest: heartbeat-many
[288.884072] Subtest heartbeat-many: SKIP (0.000s)
[289.011809] [053/130] (712s left) gem_exec_reloc (basic-write-read)
[289.113939] Subtest basic-write-read: SKIP (0.000s)
[289.194754] [054/130] (712s left) kms_joiner (basic-force-big-joiner)
[289.432141] Starting subtest: basic-force-big-joiner
[289.432867] Starting dynamic subtest: single
[290.235234] Dynamic subtest single: SUCCESS (0.805s)
[290.236208] Subtest basic-force-big-joiner: SUCCESS (0.806s)
[290.588641] [055/130] (710s left) gem_mmap_gtt (close-race)
[290.679977] Subtest close-race: SKIP (0.000s)
[290.726221] [056/130] (710s left) drm_fdinfo (virtual-busy-hang-all)
[290.810098] Starting subtest: virtual-busy-hang-all
[290.811703] Subtest virtual-busy-hang-all: SKIP (0.000s)
[290.886756] [057/130] (710s left) kms_universal_plane (cursor-fb-leak)
[291.134228] Starting subtest: cursor-fb-leak
[291.135004] Starting dynamic subtest: pipe-A-DP-4
[291.578087] Dynamic subtest pipe-A-DP-4: SUCCESS (0.443s)
[291.598986] Starting dynamic subtest: pipe-B-DP-4
[292.155852] Dynamic subtest pipe-B-DP-4: SUCCESS (0.571s)
[292.186681] Starting dynamic subtest: pipe-C-DP-4
[292.719355] Dynamic subtest pipe-C-DP-4: SUCCESS (0.558s)
[292.746441] Starting dynamic subtest: pipe-D-DP-4
[293.269354] Dynamic subtest pipe-D-DP-4: SUCCESS (0.542s)
[293.271300] Subtest cursor-fb-leak: SUCCESS (2.137s)
[293.585952] [058/130] (707s left) debugfs_test (basic-hwmon)
[293.680267] Starting subtest: basic-hwmon
[293.692514] Subtest basic-hwmon: SUCCESS (0.011s)
[293.909218] [059/130] (707s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-render)
[294.153550] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-render
[294.155118] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[294.251394] [060/130] (707s left) kms_big_fb (4-tiled-32bpp-rotate-0)
[294.508301] Starting subtest: 4-tiled-32bpp-rotate-0
[296.141867] Subtest 4-tiled-32bpp-rotate-0: SUCCESS (1.633s)
[296.321042] [061/130] (705s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[296.568856] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[296.570454] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[296.660488] [062/130] (704s left) prime_vgem (basic-gtt)
[296.769315] Starting subtest: basic-gtt
[296.770893] Subtest basic-gtt: SKIP (0.000s)
[296.902406] [063/130] (704s left) gem_eio (throttle)
[297.212553] Starting subtest: throttle
[297.682189] Subtest throttle: SUCCESS (0.470s)
[297.890306] [064/130] (703s left) gem_exec_reloc (basic-cpu-read-noreloc)
[297.982566] Subtest basic-cpu-read-noreloc: SKIP (0.000s)
[298.037996] [065/130] (703s left) kms_psr (psr2-cursor-render)
[298.290365] Subtest psr2-cursor-render: SKIP (0.000s)
[298.320464] [066/130] (703s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted)
[298.392725] Starting subtest: invalid-multi-wait-all-unsubmitted
[298.393528] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[298.489036] [067/130] (703s left) gem_ctx_persistence (legacy-engines-cleanup)
[298.608711] Starting subtest: legacy-engines-cleanup
[298.609535] Starting dynamic subtest: render
[298.745378] Dynamic subtest render: SUCCESS (0.136s)
[298.765703] Starting dynamic subtest: bsd1
[298.769135] Dynamic subtest bsd1: SUCCESS (0.021s)
[298.789347] Starting dynamic subtest: bsd2
[298.832562] Dynamic subtest bsd2: SUCCESS (0.061s)
[298.867250] Starting dynamic subtest: blt
[298.898131] Dynamic subtest blt: SUCCESS (0.062s)
[298.935259] Starting dynamic subtest: vebox
[298.967489] Dynamic subtest vebox: SUCCESS (0.065s)
[298.968644] Subtest legacy-engines-cleanup: SUCCESS (0.360s)
[299.122992] [068/130] (702s left) kms_cursor_crc (cursor-onscreen-max-size)
[299.592366] Starting subtest: cursor-onscreen-max-size
[299.596671] Subtest cursor-onscreen-max-size: SKIP (0.000s)
[299.797660] [069/130] (702s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs)
[300.027849] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs
[300.029940] Starting dynamic subtest: pipe-A-DP-4
[300.037830] Dynamic subtest pipe-A-DP-4: SKIP (0.000s)
[300.045991] Starting dynamic subtest: pipe-B-DP-4
[300.053669] Dynamic subtest pipe-B-DP-4: SKIP (0.000s)
[300.061465] Starting dynamic subtest: pipe-C-DP-4
[300.068958] Dynamic subtest pipe-C-DP-4: SKIP (0.000s)
[300.076585] Starting dynamic subtest: pipe-D-DP-4
[300.084224] Dynamic subtest pipe-D-DP-4: SKIP (0.000s)
[300.085780] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs: SKIP (0.007s)
[300.455918] [070/130] (701s left) kms_psr (fbc-psr-sprite-mmap-gtt)
[300.700835] Subtest fbc-psr-sprite-mmap-gtt: SKIP (0.000s)
[300.735891] [071/130] (701s left) perf (mi-rpc)
[301.224197] Starting subtest: mi-rpc
[301.225785] Subtest mi-rpc: SKIP (0.000s)
[301.256846] [072/130] (700s left) kms_big_fb (y-tiled-32bpp-rotate-270)
[301.569406] Starting subtest: y-tiled-32bpp-rotate-270
[301.570995] Subtest y-tiled-32bpp-rotate-270: SKIP (0.000s)
[301.672935] [073/130] (700s left) gem_exec_params (invalid-fence-in-submit)
[301.834920] Starting subtest: invalid-fence-in-submit
[301.837076] Subtest invalid-fence-in-submit: SUCCESS (0.002s)
[301.945422] [074/130] (700s left) prime_busy (before-wait)
[302.095912] Starting subtest: before-wait
[302.102218] Starting dynamic subtest: rcs0
[302.150374] Dynamic subtest rcs0: SUCCESS (0.048s)
[302.160225] Starting dynamic subtest: bcs0
[302.209497] Dynamic subtest bcs0: SUCCESS (0.058s)
[302.230278] Starting dynamic subtest: vcs0
[302.251659] Dynamic subtest vcs0: SUCCESS (0.040s)
[302.274408] Starting dynamic subtest: vcs1
[302.292344] Dynamic subtest vcs1: SUCCESS (0.039s)
[302.310618] Starting dynamic subtest: vecs0
[302.333819] Dynamic subtest vecs0: SUCCESS (0.040s)
[302.350383] Starting dynamic subtest: vecs1
[302.374033] Dynamic subtest vecs1: SUCCESS (0.039s)
[302.389795] Starting dynamic subtest: ccs0
[302.414911] Dynamic subtest ccs0: SUCCESS (0.039s)
[302.524195] Subtest before-wait: SUCCESS (0.428s)
[302.681621] [075/130] (699s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation)
[302.995166] Starting subtest: plane-scaler-unity-scaling-with-rotation
[302.995912] Starting dynamic subtest: pipe-A
[303.375241] Dynamic subtest pipe-A: SUCCESS (0.379s)
[303.408420] Starting dynamic subtest: pipe-B
[303.951898] Dynamic subtest pipe-B: SUCCESS (0.573s)
[303.974972] Starting dynamic subtest: pipe-C
[304.476754] Dynamic subtest pipe-C: SUCCESS (0.522s)
[304.501786] Starting dynamic subtest: pipe-D
[305.002915] Dynamic subtest pipe-D: SUCCESS (0.524s)
[305.004216] Subtest plane-scaler-unity-scaling-with-rotation: SUCCESS (2.009s)
[305.270931] [076/130] (696s left) i915_query (hwconfig_table)
[305.417225] Starting subtest: hwconfig_table
[305.576283] Subtest hwconfig_table: SUCCESS (0.001s)
[305.613790] [077/130] (696s left) kms_psr2_sf (pr-cursor-plane-update-sf)
[305.896261] Subtest pr-cursor-plane-update-sf: SKIP (0.000s)
[305.923314] [078/130] (696s left) kms_cursor_legacy (forked-move)
[306.197947] Starting subtest: forked-move
[306.198755] Starting dynamic subtest: pipe-A
[311.634732] Dynamic subtest pipe-A: SUCCESS (5.436s)
[311.647860] Starting dynamic subtest: pipe-B
[317.083298] Dynamic subtest pipe-B: SUCCESS (5.447s)
[317.101816] Starting dynamic subtest: pipe-C
[322.535552] Dynamic subtest pipe-C: SUCCESS (5.450s)
[322.555247] Starting dynamic subtest: pipe-D
[327.973386] Dynamic subtest pipe-D: SUCCESS (5.435s)
[327.991797] Starting dynamic subtest: all-pipes
[333.415515] Dynamic subtest all-pipes: SUCCESS (5.440s)
[333.416337] Subtest forked-move: SUCCESS (27.217s)
[333.487893] [079/130] (668s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[333.797137] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[333.810987] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.013s)
[333.880307] [080/130] (668s left) kms_flip (2x-dpms-vs-vblank-race)
[334.161662] Starting subtest: 2x-dpms-vs-vblank-race
[334.175572] Subtest 2x-dpms-vs-vblank-race: SKIP (0.013s)
[334.247923] [081/130] (667s left) kms_pm_rpm (modeset-non-lpsp-stress)
[336.994549] Starting subtest: modeset-non-lpsp-stress
[352.355972] Subtest modeset-non-lpsp-stress: SUCCESS (15.359s)
[352.599514] [082/130] (649s left) gem_exec_fence (basic-wait)
[352.871108] Starting subtest: basic-wait
[352.871957] Starting dynamic subtest: rcs0
[352.993186] Dynamic subtest rcs0: SUCCESS (0.120s)
[353.009435] Starting dynamic subtest: bcs0
[353.047596] Dynamic subtest bcs0: SUCCESS (0.051s)
[353.064375] Starting dynamic subtest: vcs0
[353.100586] Dynamic subtest vcs0: SUCCESS (0.051s)
[353.139615] Starting dynamic subtest: vcs1
[353.159546] Dynamic subtest vcs1: SUCCESS (0.056s)
[353.175613] Starting dynamic subtest: vecs0
[353.211184] Dynamic subtest vecs0: SUCCESS (0.050s)
[353.240711] Starting dynamic subtest: vecs1
[353.266674] Dynamic subtest vecs1: SUCCESS (0.053s)
[353.286078] Starting dynamic subtest: ccs0
[353.320036] Dynamic subtest ccs0: SUCCESS (0.051s)
[353.321269] Subtest basic-wait: SUCCESS (0.450s)
[353.937357] [083/130] (648s left) gem_lmem_swapping (random-engines)
[357.579212] Starting subtest: random-engines
[357.580752] Starting dynamic subtest: lmem0
[358.384777] Dynamic subtest lmem0: SUCCESS (0.805s)
[358.385613] Subtest random-engines: SUCCESS (0.806s)
[360.179172] [084/130] (642s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[362.163334] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip
[363.436262] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (1.260s)
[363.577569] [085/130] (638s left) gem_exec_whisper (basic-queues-forked)
[363.666336] Starting subtest: basic-queues-forked
[386.085950] Subtest basic-queues-forked: SUCCESS (22.418s)
[386.266806] [086/130] (616s left) gem_exec_balancer (hog)
[386.367057] Starting subtest: hog
[386.368610] Subtest hog: SKIP (0.000s)
[386.744933] [087/130] (615s left) gem_userptr_blits (relocations)
[386.817275] Starting subtest: relocations
[386.818829] Subtest relocations: SKIP (0.001s)
[386.980748] [088/130] (615s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-pwrite)
[387.209428] Starting subtest: psr-2p-primscrn-cur-indfb-draw-pwrite
[387.210996] Subtest psr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[387.297551] [089/130] (615s left) i915_pm_rpm (debugfs-read)
[390.011778] Starting subtest: debugfs-read
[390.446603] Subtest debugfs-read: SUCCESS (0.429s)
[390.901129] [090/130] (611s left) kms_invalid_mode (int-max-clock)
[391.121915] Starting subtest: int-max-clock
[391.122728] Starting dynamic subtest: pipe-A-DP-4
[391.191227] Dynamic subtest pipe-A-DP-4: SUCCESS (0.069s)
[391.200731] Starting dynamic subtest: pipe-B-DP-4
[391.201526] Dynamic subtest pipe-B-DP-4: SUCCESS (0.001s)
[391.209471] Starting dynamic subtest: pipe-C-DP-4
[391.210260] Dynamic subtest pipe-C-DP-4: SUCCESS (0.001s)
[391.218159] Starting dynamic subtest: pipe-D-DP-4
[391.218945] Dynamic subtest pipe-D-DP-4: SUCCESS (0.001s)
[391.219738] Subtest int-max-clock: SUCCESS (0.077s)
[391.382335] [091/130] (611s left) gem_exec_suspend (basic)
[391.474890] Starting subtest: basic
[391.475805] Starting dynamic subtest: smem
[392.517964] Dynamic subtest smem: SUCCESS (1.042s)
[392.526184] Starting dynamic subtest: lmem0
[393.492182] Dynamic subtest lmem0: SUCCESS (0.973s)
[393.493061] Subtest basic: SUCCESS (2.018s)
[393.766543] [092/130] (608s left) perf_pmu (all-busy-check-all)
[393.836648] Starting subtest: all-busy-check-all
[394.429538] Subtest all-busy-check-all: SUCCESS (0.592s)
[394.547612] [093/130] (608s left) gem_exec_params (invalid-bsd1-flag-on-vebox)
[394.650762] Starting subtest: invalid-bsd1-flag-on-vebox
[394.651610] Subtest invalid-bsd1-flag-on-vebox: SUCCESS (0.000s)
[394.757676] [094/130] (607s left) gem_pxp (create-valid-protected-context)
[397.361484] Subtest create-valid-protected-context: SKIP (0.000s)
[397.426807] [095/130] (605s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-blt)
[397.651507] Starting subtest: psr-1p-offscren-pri-shrfb-draw-blt
[397.653053] Subtest psr-1p-offscren-pri-shrfb-draw-blt: SKIP (0.000s)
[397.749347] [096/130] (604s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-msflip-blt)
[397.997549] Starting subtest: fbcpsr-1p-primscrn-indfb-msflip-blt
[397.999123] Subtest fbcpsr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[398.106999] [097/130] (604s left) gem_exec_params (invalid-bsd2-flag-on-blt)
[398.196451] Starting subtest: invalid-bsd2-flag-on-blt
[398.197263] Subtest invalid-bsd2-flag-on-blt: SUCCESS (0.000s)
[398.334038] [098/130] (604s left) kms_chamelium_color (ctm-green-to-red)
[398.560929] Subtest ctm-green-to-red: SKIP (0.000s)
[398.581640] [099/130] (604s left) kms_getfb (getfb-handle-closed)
[398.650098] Starting subtest: getfb-handle-closed
[398.650910] Subtest getfb-handle-closed: SUCCESS (0.000s)
[398.736370] [100/130] (604s left) gem_eio (in-flight-10ms)
[399.027139] Starting subtest: in-flight-10ms
[404.085669] Subtest in-flight-10ms: SUCCESS (5.059s)
[404.306959] [101/130] (598s left) kms_frontbuffer_tracking (fbc-rgb565-draw-pwrite)
[404.542413] Starting subtest: fbc-rgb565-draw-pwrite
[409.087702] Subtest fbc-rgb565-draw-pwrite: SUCCESS (4.545s)
[409.215557] [102/130] (593s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc)
[409.435094] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc
[409.435909] Starting dynamic subtest: pipe-A-DP-4
[409.443901] Dynamic subtest pipe-A-DP-4: SKIP (0.000s)
[409.453764] Starting dynamic subtest: pipe-B-DP-4
[409.461374] Dynamic subtest pipe-B-DP-4: SKIP (0.000s)
[409.471181] Starting dynamic subtest: pipe-C-DP-4
[409.478649] Dynamic subtest pipe-C-DP-4: SKIP (0.000s)
[409.488057] Starting dynamic subtest: pipe-D-DP-4
[409.495799] Dynamic subtest pipe-D-DP-4: SKIP (0.000s)
[409.497333] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SKIP (0.007s)
[409.854523] [103/130] (593s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0)
[410.104508] Starting subtest: linear-max-hw-stride-64bpp-rotate-0
[411.354218] Subtest linear-max-hw-stride-64bpp-rotate-0: SUCCESS (1.248s)
[411.552389] [104/130] (591s left) gem_exec_flush (basic-uc-rw-default)
[411.643387] Subtest basic-uc-rw-default: SKIP (0.000s)
[411.699799] [105/130] (591s left) kms_frontbuffer_tracking (basic)
[411.927757] Starting subtest: basic
[414.637751] Subtest basic: SUCCESS (2.709s)
[414.812963] [106/130] (588s left) kms_psr (fbc-pr-sprite-render)
[415.045074] Subtest fbc-pr-sprite-render: SKIP (0.000s)
[415.066634] [107/130] (588s left) kms_writeback (writeback-fb-id-xrgb2101010)
[415.296919] Subtest writeback-fb-id-XRGB2101010: SKIP (0.000s)
[415.333686] [108/130] (587s left) gem_caching (writes)
[415.413663] Starting subtest: writes
[419.382453] Subtest writes: SUCCESS (3.968s)
[419.495087] [109/130] (583s left) kms_cursor_edge_walk (128x128-left-edge)
[419.738095] Starting subtest: 128x128-left-edge
[419.738931] Starting dynamic subtest: pipe-A-DP-4
[423.187263] Dynamic subtest pipe-A-DP-4: SUCCESS (3.448s)
[423.204297] Starting dynamic subtest: pipe-D-DP-4
[426.529402] Dynamic subtest pipe-D-DP-4: SUCCESS (3.339s)
[426.530304] Subtest 128x128-left-edge: SUCCESS (6.792s)
[426.803580] [110/130] (576s left) vgem_basic (mmap)
[426.883834] Starting subtest: mmap
[426.905011] Subtest mmap: SUCCESS (0.022s)
[426.950687] [111/130] (576s left) gem_close_race (multigpu-basic-threads)
[427.060054] Starting subtest: multigpu-basic-threads
[427.061646] Subtest multigpu-basic-threads: SKIP (0.000s)
[427.334802] [112/130] (575s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[427.566287] Starting subtest: basic-flip-after-cursor-legacy
[428.592308] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.025s)
[428.860624] [113/130] (574s left) gem_ctx_freq (sysfs)
[428.950324] Starting subtest: sysfs
[428.951112] Starting dynamic subtest: gt0
[434.617819] Dynamic subtest gt0: SUCCESS (5.666s)
[434.620455] Subtest sysfs: SUCCESS (5.669s)
[434.727615] [114/130] (568s left) kms_vblank (wait-idle-hang)
[434.955102] Starting subtest: wait-idle-hang
[434.957173] Starting dynamic subtest: pipe-A-DP-4
[461.566767] Dynamic subtest pipe-A-DP-4: SUCCESS (26.608s)
[461.581169] Starting dynamic subtest: pipe-D-DP-4
[486.583406] Dynamic subtest pipe-D-DP-4: SUCCESS (25.012s)
[486.584212] Subtest wait-idle-hang: SUCCESS (51.627s)
[486.779237] [115/130] (516s left) gem_exec_flush (basic-batch-kernel-default-uc)
[486.866803] Subtest basic-batch-kernel-default-uc: SKIP (0.000s)
[486.945470] [116/130] (516s left) gem_spin_batch (legacy-resubmit)
[487.049962] Starting subtest: legacy-resubmit
[487.050837] Starting dynamic subtest: default
[487.075149] Dynamic subtest default: SUCCESS (0.024s)
[487.087266] Starting dynamic subtest: render
[487.088089] Dynamic subtest render: SUCCESS (0.001s)
[487.098144] Starting dynamic subtest: bsd
[487.098941] Dynamic subtest bsd: SUCCESS (0.002s)
[487.108710] Starting dynamic subtest: bsd1
[487.109484] Dynamic subtest bsd1: SUCCESS (0.001s)
[487.119142] Starting dynamic subtest: bsd2
[487.119975] Dynamic subtest bsd2: SUCCESS (0.002s)
[487.129921] Starting dynamic subtest: blt
[487.130752] Dynamic subtest blt: SUCCESS (0.002s)
[487.140171] Starting dynamic subtest: vebox
[487.141010] Dynamic subtest vebox: SUCCESS (0.001s)
[487.141802] Subtest legacy-resubmit: SUCCESS (0.041s)
[487.353381] [117/130] (516s left) gem_ctx_bad_destroy (invalid-pad)
[487.446206] Starting subtest: invalid-pad
[487.447043] Subtest invalid-pad: SUCCESS (0.000s)
[487.502721] [118/130] (515s left) syncobj_timeline (invalid-single-wait-unsubmitted)
[487.576811] Starting subtest: invalid-single-wait-unsubmitted
[487.577636] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[487.719131] [119/130] (515s left) gen9_exec_parse (bb-start-param)
[487.804978] Subtest bb-start-param: SKIP (0.000s)
[487.845639] [120/130] (515s left) kms_psr (fbc-pr-no-drrs)
[488.066095] Subtest fbc-pr-no-drrs: SKIP (0.000s)
[488.095946] [121/130] (515s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[488.342557] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt
[488.344119] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[488.453190] [122/130] (515s left) perf_pmu (busy-hang)
[488.538113] Starting subtest: busy-hang
[488.538925] Starting dynamic subtest: rcs0
[489.824098] Dynamic subtest rcs0: SUCCESS (1.282s)
[489.834188] Starting dynamic subtest: bcs0
[491.054092] Dynamic subtest bcs0: SUCCESS (1.226s)
[491.064117] Starting dynamic subtest: vcs0
[492.300068] Dynamic subtest vcs0: SUCCESS (1.242s)
[492.309978] Starting dynamic subtest: vcs1
[493.542124] Dynamic subtest vcs1: SUCCESS (1.238s)
[493.552148] Starting dynamic subtest: vecs0
[494.783010] Dynamic subtest vecs0: SUCCESS (1.237s)
[494.793061] Starting dynamic subtest: vecs1
[496.034142] Dynamic subtest vecs1: SUCCESS (1.247s)
[496.044339] Starting dynamic subtest: ccs0
[497.261951] Dynamic subtest ccs0: SUCCESS (1.224s)
[497.262804] Subtest busy-hang: SUCCESS (8.722s)
[497.354866] [123/130] (506s left) debugfs_test (read_all_entries)
[497.435609] Starting subtest: read_all_entries
[498.386533] Subtest read_all_entries: SUCCESS (0.950s)
[498.600483] [124/130] (505s left) gem_softpin (allocator-nopin-reserve)
[498.681799] Starting subtest: allocator-nopin-reserve
[498.890464] Subtest allocator-nopin-reserve: SUCCESS (0.208s)
[499.050154] [125/130] (504s left) kms_dsc (dsc-fractional-bpp-with-bpc)
[499.282157] Starting subtest: dsc-fractional-bpp-with-bpc
[499.283742] Subtest dsc-fractional-bpp-with-bpc: SKIP (0.001s)
[499.351324] [126/130] (504s left) gem_fenced_exec_thrash (no-spare-fences-interruptible)
[499.427756] Subtest no-spare-fences-interruptible: SKIP (0.000s)
[499.488731] [127/130] (504s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted)
[499.591654] Starting subtest: multi-wait-all-for-submit-unsubmitted
[499.694521] Subtest multi-wait-all-for-submit-unsubmitted: SUCCESS (0.103s)
[499.849655] [128/130] (504s left) kms_color (ctm-green-to-red)
[500.103782] Starting subtest: ctm-green-to-red
[500.104557] Starting dynamic subtest: pipe-A-DP-4
[500.721384] Dynamic subtest pipe-A-DP-4: SUCCESS (0.614s)
[500.735871] Starting dynamic subtest: pipe-B-DP-4
[501.300889] Dynamic subtest pipe-B-DP-4: SUCCESS (0.578s)
[501.315553] Starting dynamic subtest: pipe-C-DP-4
[501.886114] Dynamic subtest pipe-C-DP-4: SUCCESS (0.583s)
[501.896507] Starting dynamic subtest: pipe-D-DP-4
[502.483344] Dynamic subtest pipe-D-DP-4: SUCCESS (0.597s)
[502.484289] Subtest ctm-green-to-red: SUCCESS (2.380s)
[502.738172] [129/130] (501s left) gem_wait (await)
[502.842834] Starting subtest: await
[502.843663] Starting dynamic subtest: all-engines
[503.967740] Dynamic subtest all-engines: SUCCESS (1.124s)
[503.981705] Starting dynamic subtest: rcs0
[505.026486] Dynamic subtest rcs0: SUCCESS (1.056s)
[505.040209] Starting dynamic subtest: bcs0
[506.084093] Dynamic subtest bcs0: SUCCESS (1.055s)
[506.098502] Starting dynamic subtest: vcs0
[507.142922] Dynamic subtest vcs0: SUCCESS (1.054s)
[507.157500] Starting dynamic subtest: vcs1
[508.201825] Dynamic subtest vcs1: SUCCESS (1.054s)
[508.216535] Starting dynamic subtest: vecs0
[509.259957] Dynamic subtest vecs0: SUCCESS (1.053s)
[509.275113] Starting dynamic subtest: vecs1
[510.317859] Dynamic subtest vecs1: SUCCESS (1.053s)
[510.336964] Starting dynamic subtest: ccs0
[511.378888] Dynamic subtest ccs0: SUCCESS (1.056s)
[511.385196] Subtest await: SUCCESS (8.537s)
[511.662858] [130/130] (492s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-pwrite)
[511.903123] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-pwrite
[514.791666] Subtest fbc-1p-offscren-pri-shrfb-draw-pwrite: SUCCESS (2.888s)
[514.921889] Closing watchdogs
Done.