[54.367064] Initializing watchdogs
[54.367490]   /dev/watchdog0
[54.400897] [001/142] (960s left) kms_color (ctm-max)
[54.931394] Starting subtest: ctm-max
[54.934305] Starting dynamic subtest: pipe-A
[56.344557] Dynamic subtest pipe-A: SUCCESS (1.411s)
[56.382811] Starting dynamic subtest: pipe-B
[57.986766] Dynamic subtest pipe-B: SUCCESS (1.644s)
[58.016128] Starting dynamic subtest: pipe-C
[59.435343] Dynamic subtest pipe-C: SUCCESS (1.445s)
[59.467554] Starting dynamic subtest: pipe-D
[60.901350] Dynamic subtest pipe-D: SUCCESS (1.466s)
[60.905197] Subtest ctm-max: SUCCESS (5.968s)
[61.834144] [002/142] (953s left) kms_plane_scaling (planes-downscale-factor-0-25-upscale-factor-0-25)
[62.323443] Starting subtest: planes-downscale-factor-0-25-upscale-factor-0-25
[62.331237] Starting dynamic subtest: pipe-A-eDP-1
[62.426150] Dynamic subtest pipe-A-eDP-1: SKIP (0.091s)
[62.455787] Starting dynamic subtest: pipe-B-eDP-1
[62.624089] Dynamic subtest pipe-B-eDP-1: SKIP (0.190s)
[62.653737] Starting dynamic subtest: pipe-C-eDP-1
[62.823895] Dynamic subtest pipe-C-eDP-1: SKIP (0.191s)
[62.855066] Starting dynamic subtest: pipe-D-eDP-1
[63.024844] Dynamic subtest pipe-D-eDP-1: SKIP (0.192s)
[63.031270] Subtest planes-downscale-factor-0-25-upscale-factor-0-25: SKIP (0.695s)
[63.197362] [003/142] (951s left) kms_flip (2x-flip-vs-rmfb)
[63.585243] Starting subtest: 2x-flip-vs-rmfb
[63.745930] Subtest 2x-flip-vs-rmfb: SKIP (0.161s)
[63.927328] [004/142] (951s left) gem_exec_fair (basic-deadline)
[64.347264] Subtest basic-deadline: SKIP (0.000s)
[64.465963] [005/142] (950s left) gem_exec_reloc (basic-write-wc)
[64.858281] Subtest basic-write-wc: SKIP (0.000s)
[64.977699] [006/142] (950s left) gem_exec_whisper (basic-contexts-forked-all)
[65.407624] Starting subtest: basic-contexts-forked-all
[186.578289] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[197.644580] Closing watchdogs
[197.661748] Initializing watchdogs
[197.662089]   /dev/watchdog0
[197.705806] [007/142] (817s left) kms_big_fb (y-tiled-addfb-size-offset-overflow)
[198.278505] Starting subtest: y-tiled-addfb-size-offset-overflow
[198.285736] Subtest y-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[198.442429] [008/142] (817s left) gem_create (create-size-update)
[198.808300] Starting subtest: create-size-update
[198.811895] Subtest create-size-update: SUCCESS (0.000s)
[199.114622] [009/142] (816s left) gem_ctx_shared (disjoint-timelines)
[199.530801] Starting subtest: disjoint-timelines
[199.706057] Subtest disjoint-timelines: SUCCESS (0.175s)
[200.029621] [010/142] (815s left) kms_prop_blob (blob-prop-validate)
[200.410483] Starting subtest: blob-prop-validate
[200.414866] Subtest blob-prop-validate: SUCCESS (0.000s)
[200.531618] [011/142] (815s left) gem_linear_blits (basic)
[203.855781] Starting subtest: basic
[203.888309] Subtest basic: SUCCESS (0.034s)
[204.132776] [012/142] (811s left) gem_ringfill (legacy-basic)
[210.520303] Starting subtest: legacy-basic
[210.524659] Starting dynamic subtest: default
[210.584932] Dynamic subtest default: SUCCESS (0.063s)
[210.625373] Starting dynamic subtest: render
[210.640852] Dynamic subtest render: SUCCESS (0.053s)
[210.681443] Starting dynamic subtest: bsd
[212.551192] Dynamic subtest bsd: SUCCESS (1.906s)
[212.590541] Starting dynamic subtest: bsd1
[214.534845] Dynamic subtest bsd1: SUCCESS (1.982s)
[214.578690] Starting dynamic subtest: bsd2
[216.518657] Dynamic subtest bsd2: SUCCESS (1.982s)
[216.563220] Starting dynamic subtest: blt
[216.572112] Dynamic subtest blt: SUCCESS (0.051s)
[216.611361] Starting dynamic subtest: vebox
[218.566080] Dynamic subtest vebox: SUCCESS (1.991s)
[218.569892] Subtest legacy-basic: SUCCESS (8.045s)
[218.741241] [013/142] (797s left) gem_vm_create (create-ext)
[219.130788] Starting subtest: create-ext
[219.146594] Subtest create-ext: SUCCESS (0.015s)
[219.345608] [014/142] (796s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[219.878057] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[232.127520] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SUCCESS (12.250s)
[232.423359] [015/142] (783s left) kms_ccs (pipe-a-random-ccs-data-yf_tiled_ccs)
[233.396148] Starting subtest: pipe-A-random-ccs-data-yf_tiled_ccs
[233.406626] Subtest pipe-A-random-ccs-data-yf_tiled_ccs: SKIP (0.000s)
[234.792979] [016/142] (781s left) kms_cursor_legacy (nonblocking-modeset-vs-cursor-atomic)
[235.371218] Starting subtest: nonblocking-modeset-vs-cursor-atomic
[237.770333] Subtest nonblocking-modeset-vs-cursor-atomic: SUCCESS (2.402s)
[238.702334] [017/142] (777s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[239.185825] Starting subtest: primary-4-tiled-reflect-x-180
[252.602474] Subtest primary-4-tiled-reflect-x-180: SUCCESS (13.418s)
[254.049347] [018/142] (762s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[254.590105] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[258.027117] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: FAIL (2.720s)
[258.094648] [019/142] (758s left) gem_ppgtt (blt-vs-render-ctx0)
[258.483162] Starting subtest: blt-vs-render-ctx0
[292.272435] Subtest blt-vs-render-ctx0: SUCCESS (33.786s)
[292.487745] [020/142] (723s left) vc4/vc4_perfmon (create-perfmon-0)
[293.146176] Subtest create-perfmon-0: SKIP (0.000s)
[293.290215] [021/142] (723s left) kms_plane_alpha_blend (alpha-7efc)
[293.811413] Starting subtest: alpha-7efc
[295.554304] Starting dynamic subtest: pipe-A-eDP-1
[296.616256] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.059s)
[296.765740] Starting dynamic subtest: pipe-D-eDP-1
[298.877924] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.142s)
[298.882189] Subtest alpha-7efc: SUCCESS (5.075s)
[299.822397] [022/142] (716s left) v3d/v3d_submit_cl (bad-in-sync)
[300.285162] Subtest bad-in-sync: SKIP (0.000s)
[300.414986] [023/142] (716s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-blt)
[300.926747] Starting subtest: psr-1p-primscrn-spr-indfb-draw-blt
[311.421756] Subtest psr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (10.503s)
[311.719598] [024/142] (705s left) gem_exec_balancer (bonded-sync)
[312.162983] Starting subtest: bonded-sync
[312.169747] Subtest bonded-sync: SKIP (0.001s)
[313.162654] [025/142] (703s left) gem_ctx_persistence (file)
[313.563417] Starting subtest: file
[313.656696] Subtest file: SUCCESS (0.093s)
[313.839156] [026/142] (703s left) kms_vblank (pipe-a-ts-continuation-suspend)
[314.315866] Starting subtest: pipe-A-ts-continuation-suspend
[331.833990] Subtest pipe-A-ts-continuation-suspend: SUCCESS (17.521s)
[332.803654] [027/142] (684s left) gem_exec_basic (basic)
[333.188832] Starting subtest: basic
[333.192147] Starting dynamic subtest: rcs0-smem
[333.195598] Dynamic subtest rcs0-smem: SUCCESS (0.004s)
[333.249990] Starting dynamic subtest: bcs0-smem
[333.253605] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[333.299794] Starting dynamic subtest: vcs0-smem
[333.303353] Dynamic subtest vcs0-smem: SUCCESS (0.002s)
[333.356326] Starting dynamic subtest: vcs1-smem
[333.360469] Dynamic subtest vcs1-smem: SUCCESS (0.002s)
[333.408388] Starting dynamic subtest: vecs0-smem
[333.411949] Dynamic subtest vecs0-smem: SUCCESS (0.002s)
[333.457888] Starting dynamic subtest: ccs0-smem
[333.461538] Dynamic subtest ccs0-smem: SUCCESS (0.002s)
[333.464980] Subtest basic: SUCCESS (0.041s)
[334.680659] [028/142] (682s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-pwrite)
[335.148243] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-pwrite
[348.227197] Subtest fbcpsr-1p-offscren-pri-indfb-draw-pwrite: SUCCESS (13.087s)
[348.516264] [029/142] (668s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted)
[348.892317] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[348.895995] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.003s)
[349.044663] [030/142] (668s left) kms_cursor_crc (cursor-sliding-32x10)
[349.727348] Starting subtest: cursor-sliding-32x10
[350.048359] Subtest cursor-sliding-32x10: SKIP (0.310s)
[350.909334] [031/142] (666s left) kms_hdr (bpc-switch)
[351.394550] Starting subtest: bpc-switch
[352.654534] Starting dynamic subtest: pipe-A-eDP-1
[357.318491] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.669s)
[357.323096] Subtest bpc-switch: SUCCESS (5.927s)
[357.533523] [032/142] (659s left) vgem_basic (unload)
[357.918475] Starting subtest: unload
[387.144073] Subtest unload: SUCCESS (29.225s)
[387.418472] [033/142] (630s left) dumb_buffer (map-invalid-size)
[387.783552] Starting subtest: map-invalid-size
[387.787218] Subtest map-invalid-size: SUCCESS (0.001s)
[387.980869] [034/142] (629s left) gem_cs_tlb (engines)
[388.388492] Starting subtest: engines
[388.391957] Starting dynamic subtest: rcs0
[390.561325] Dynamic subtest rcs0: SUCCESS (2.172s)
[390.612151] Starting dynamic subtest: bcs0
[392.733604] Dynamic subtest bcs0: SUCCESS (2.168s)
[392.785062] Starting dynamic subtest: vcs0
[394.905857] Dynamic subtest vcs0: SUCCESS (2.169s)
[394.955553] Starting dynamic subtest: vcs1
[397.077488] Dynamic subtest vcs1: SUCCESS (2.167s)
[397.124760] Starting dynamic subtest: vecs0
[399.250588] Dynamic subtest vecs0: SUCCESS (2.168s)
[399.298213] Starting dynamic subtest: ccs0
[401.425783] Dynamic subtest ccs0: SUCCESS (2.170s)
[401.429457] Subtest engines: SUCCESS (13.041s)
[401.644147] [035/142] (616s left) kms_cursor_legacy (2x-flip-vs-cursor-atomic)
[402.231545] Starting subtest: 2x-flip-vs-cursor-atomic
[402.247674] Subtest 2x-flip-vs-cursor-atomic: SKIP (0.016s)
[402.595987] [036/142] (615s left) kms_ccs (pipe-c-random-ccs-data-yf_tiled_ccs)
[404.047643] Starting subtest: pipe-C-random-ccs-data-yf_tiled_ccs
[404.057299] Subtest pipe-C-random-ccs-data-yf_tiled_ccs: SKIP (0.000s)
[404.998412] [037/142] (612s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[405.478317] Starting subtest: read-crc-frame-sequence
[405.487151] Starting dynamic subtest: pipe-A-eDP-1
[406.762771] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.272s)
[406.816823] Starting dynamic subtest: pipe-B-eDP-1
[408.670641] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.895s)
[408.726459] Starting dynamic subtest: pipe-C-eDP-1
[410.800944] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.124s)
[410.858534] Starting dynamic subtest: pipe-D-eDP-1
[413.314868] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.498s)
[413.318591] Subtest read-crc-frame-sequence: SUCCESS (7.836s)
[414.264895] [038/142] (603s left) kms_cursor_crc (cursor-offscreen-512x512)
[415.412317] Starting subtest: cursor-offscreen-512x512
[415.424600] Subtest cursor-offscreen-512x512: SKIP (0.007s)
[415.829335] [039/142] (602s left) syncobj_wait (reset-during-wait-for-submit)
[416.212286] Starting subtest: reset-during-wait-for-submit
[416.317922] Subtest reset-during-wait-for-submit: SUCCESS (0.106s)
[416.470154] [040/142] (601s left) kms_frontbuffer_tracking (fbcpsr-1p-pri-indfb-multidraw)
[416.986337] Starting subtest: fbcpsr-1p-pri-indfb-multidraw
[461.233215] Subtest fbcpsr-1p-pri-indfb-multidraw: SUCCESS (44.249s)
[461.507870] [041/142] (556s left) gem_exec_params (rel-constants-invalid)
[461.898659] Starting subtest: rel-constants-invalid
[461.902980] Subtest rel-constants-invalid: SUCCESS (0.001s)
[462.074547] [042/142] (556s left) drm_read (fault-buffer)
[462.913749] Starting subtest: fault-buffer
[462.917697] Subtest fault-buffer: SUCCESS (0.003s)
[463.150637] [043/142] (555s left) kms_ccs (pipe-c-bad-pixel-format-4_tiled_dg2_rc_ccs_cc)
[464.500569] Starting subtest: pipe-C-bad-pixel-format-4_tiled_dg2_rc_ccs_cc
[464.507091] Subtest pipe-C-bad-pixel-format-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[465.545076] [044/142] (552s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-signa
[465.923974] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-signaled
[466.028225] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-signaled: SUCCESS (0.105s)
[466.186554] [045/142] (552s left) kms_cursor_legacy (single-bo)
[466.759442] Starting subtest: single-bo
[466.763055] Starting dynamic subtest: pipe-A
[472.208737] Dynamic subtest pipe-A: SUCCESS (5.445s)
[472.257122] Starting dynamic subtest: pipe-B
[477.662477] Dynamic subtest pipe-B: SUCCESS (5.450s)
[477.713098] Starting dynamic subtest: pipe-C
[483.126209] Dynamic subtest pipe-C: SUCCESS (5.459s)
[483.176843] Starting dynamic subtest: pipe-D
[488.585241] Dynamic subtest pipe-D: SUCCESS (5.453s)
[488.642205] Starting dynamic subtest: all-pipes
[494.062625] Dynamic subtest all-pipes: SUCCESS (5.473s)
[494.065995] Subtest single-bo: SUCCESS (27.304s)
[494.424215] [046/142] (524s left) gem_mmap_wc (fault-concurrent)
[494.821470] Subtest fault-concurrent: SKIP (0.000s)
[494.941834] [047/142] (523s left) vgem_basic (busy-fence)
[495.319392] Starting subtest: busy-fence
[495.323226] Subtest busy-fence: SUCCESS (0.002s)
[495.452901] [048/142] (523s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[495.960866] Starting subtest: psr-1p-primscrn-spr-indfb-draw-render
[506.478121] Subtest psr-1p-primscrn-spr-indfb-draw-render: SUCCESS (10.523s)
[506.781275] [049/142] (512s left) kms_big_fb (x-tiled-16bpp-rotate-270)
[507.333742] Starting subtest: x-tiled-16bpp-rotate-270
[507.399291] Subtest x-tiled-16bpp-rotate-270: SKIP (0.059s)
[507.578883] [050/142] (511s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[508.100560] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[520.284309] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (12.186s)
[520.578997] [051/142] (498s left) perf_pmu (busy-idle-no-semaphores)
[520.978535] Starting subtest: busy-idle-no-semaphores
[520.982400] Starting dynamic subtest: rcs0
[521.653598] Dynamic subtest rcs0: SUCCESS (0.672s)
[521.704856] Starting dynamic subtest: bcs0
[522.335623] Dynamic subtest bcs0: SUCCESS (0.677s)
[522.387503] Starting dynamic subtest: vcs0
[523.021364] Dynamic subtest vcs0: SUCCESS (0.681s)
[523.067871] Starting dynamic subtest: vcs1
[524.157744] Dynamic subtest vcs1: SUCCESS (1.132s)
[524.204509] Starting dynamic subtest: vecs0
[526.143301] Dynamic subtest vecs0: SUCCESS (1.981s)
[526.191730] Starting dynamic subtest: ccs0
[528.190923] Dynamic subtest ccs0: SUCCESS (2.044s)
[528.194768] Subtest busy-idle-no-semaphores: SUCCESS (7.213s)
[528.421729] [052/142] (490s left) i915_pm_freq_api (freq-reset)
[528.834618] Starting subtest: freq-reset
[528.838477] Starting dynamic subtest: gt0
[529.674164] Dynamic subtest gt0: SUCCESS (0.836s)
[529.724807] Starting dynamic subtest: gt1
[530.377432] Dynamic subtest gt1: SUCCESS (0.695s)
[530.380857] Subtest freq-reset: SUCCESS (1.539s)
[530.571903] [053/142] (488s left) gem_mmap_wc (bad-size)
[530.954143] Subtest bad-size: SKIP (0.000s)
[531.094698] [054/142] (488s left) api_intel_bb (blit-reloc-purge-cache)
[531.483933] Starting subtest: blit-reloc-purge-cache
[531.490884] Subtest blit-reloc-purge-cache: SKIP (0.002s)
[531.702205] [055/142] (487s left) perf_pmu (pmu-read)
[532.098501] Starting subtest: pmu-read
[532.116278] Subtest pmu-read: SUCCESS (0.018s)
[532.308483] [056/142] (487s left) kms_vblank (pipe-c-ts-continuation-dpms-rpm)
[532.797759] Starting subtest: pipe-C-ts-continuation-dpms-rpm
[536.681913] Subtest pipe-C-ts-continuation-dpms-rpm: SUCCESS (3.883s)
[537.634154] [057/142] (482s left) gem_mmap_gtt (big-copy-xy)
[538.004895] Subtest big-copy-XY: SKIP (0.000s)
[538.137196] [058/142] (481s left) kms_vblank (pipe-d-wait-idle)
[538.613580] Starting subtest: pipe-D-wait-idle
[542.541937] Subtest pipe-D-wait-idle: SUCCESS (3.929s)
[543.504722] [059/142] (476s left) kms_psr (cursor_mmap_cpu)
[543.979340] Starting subtest: cursor_mmap_cpu
[547.087079] Subtest cursor_mmap_cpu: SUCCESS (3.104s)
[548.034376] [060/142] (471s left) gem_render_copy (y-tiled-ccs-to-linear)
[548.445991] Starting subtest: y-tiled-ccs-to-linear
[548.451901] Subtest y-tiled-ccs-to-linear: SKIP (0.000s)
[548.745692] [061/142] (471s left) drm_fdinfo (busy-check-all)
[549.136244] Starting subtest: busy-check-all
[549.139945] Starting dynamic subtest: rcs0
[549.154945] Dynamic subtest rcs0: SKIP (0.013s)
[549.199844] Starting dynamic subtest: bcs0
[549.206718] Dynamic subtest bcs0: SKIP (0.003s)
[549.257809] Starting dynamic subtest: vcs0
[549.264204] Dynamic subtest vcs0: SKIP (0.002s)
[549.313943] Starting dynamic subtest: vcs1
[549.320310] Dynamic subtest vcs1: SKIP (0.003s)
[549.370160] Starting dynamic subtest: vecs0
[549.376510] Dynamic subtest vecs0: SKIP (0.002s)
[549.426149] Starting dynamic subtest: ccs0
[549.432743] Dynamic subtest ccs0: SKIP (0.002s)
[549.438992] Subtest busy-check-all: SKIP (0.048s)
[549.508729] [062/142] (470s left) sysfs_heartbeat_interval (precise)
[549.933855] Starting subtest: precise
[549.946780] Starting dynamic subtest: vecs0
[557.536746] Dynamic subtest vecs0: SUCCESS (7.598s)
[557.600740] Starting dynamic subtest: ccs0
[561.054519] Dynamic subtest ccs0: SUCCESS (3.511s)
[561.111242] Starting dynamic subtest: vcs0
[568.482171] Dynamic subtest vcs0: SUCCESS (7.426s)
[568.541738] Starting dynamic subtest: bcs0
[572.393472] Dynamic subtest bcs0: SUCCESS (3.907s)
[572.453752] Starting dynamic subtest: rcs0
[575.710647] Dynamic subtest rcs0: SUCCESS (3.313s)
[575.776328] Starting dynamic subtest: vcs1
[583.586575] Dynamic subtest vcs1: SUCCESS (7.870s)
[583.590981] Subtest precise: SUCCESS (33.654s)
[583.780090] [063/142] (436s left) kms_draw_crc (draw-method-mmap-wc)
[584.279364] Starting subtest: draw-method-mmap-wc
[584.283796] Starting dynamic subtest: xrgb8888-untiled
[584.942627] Dynamic subtest xrgb8888-untiled: SUCCESS (0.659s)
[584.995076] Starting dynamic subtest: rgb565-untiled
[585.574665] Dynamic subtest rgb565-untiled: SUCCESS (0.631s)
[585.636779] Starting dynamic subtest: xrgb2101010-untiled
[586.240985] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.662s)
[586.297851] Starting dynamic subtest: xrgb8888-xtiled
[586.698939] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.452s)
[586.767470] Starting dynamic subtest: rgb565-xtiled
[587.176432] Dynamic subtest rgb565-xtiled: SUCCESS (0.473s)
[587.228427] Starting dynamic subtest: xrgb2101010-xtiled
[587.619651] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.441s)
[587.671224] Starting dynamic subtest: xrgb8888-4tiled
[588.154672] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.529s)
[588.212413] Starting dynamic subtest: rgb565-4tiled
[588.654736] Dynamic subtest rgb565-4tiled: SUCCESS (0.498s)
[588.710196] Starting dynamic subtest: xrgb2101010-4tiled
[589.186834] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.530s)
[589.190515] Subtest draw-method-mmap-wc: SUCCESS (4.909s)
[589.382136] [064/142] (430s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[589.896776] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[589.904142] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[590.074085] [065/142] (430s left) kms_plane_scaling (plane-scaler-with-pixel-format-unity-scaling)
[590.552138] Starting subtest: plane-scaler-with-pixel-format-unity-scaling
[590.556629] Starting dynamic subtest: pipe-A-eDP-1
[668.355221] Dynamic subtest pipe-A-eDP-1: SUCCESS (77.798s)
[668.412075] Starting dynamic subtest: pipe-B-eDP-1
[747.779531] Dynamic subtest pipe-B-eDP-1: SUCCESS (79.413s)
[747.835981] Starting dynamic subtest: pipe-C-eDP-1
[749.585192] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.793s)
[749.626173] Starting dynamic subtest: pipe-D-eDP-1
[751.311335] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.722s)
[751.314784] Subtest plane-scaler-with-pixel-format-unity-scaling: SUCCESS (160.765s)
[756.229897] [066/142] (264s left) kms_chamelium_hpd (dp-hpd-for-each-pipe)
[757.126601] Subtest dp-hpd-for-each-pipe: SKIP (0.000s)
[758.007678] [067/142] (262s left) v3d/v3d_submit_cl (multi-and-single-sync)
[758.499382] Subtest multi-and-single-sync: SKIP (0.000s)
[758.642521] [068/142] (262s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions-varying-size)
[759.214854] Starting subtest: short-flip-before-cursor-atomic-transitions-varying-size
[764.014340] Subtest short-flip-before-cursor-atomic-transitions-varying-size: SUCCESS (4.801s)
[764.971601] [069/142] (255s left) gem_userptr_blits (unsync-overlap)
[765.367342] Subtest unsync-overlap: SKIP (0.000s)
[765.661953] [070/142] (255s left) kms_atomic (plane-immutable-zpos)
[766.435528] Starting subtest: plane-immutable-zpos
[767.184365] Subtest plane-immutable-zpos: SUCCESS (0.749s)
[768.686557] [071/142] (252s left) gem_exec_params (invalid-bsd1-flag-on-vebox)
[769.079453] Starting subtest: invalid-bsd1-flag-on-vebox
[769.083352] Subtest invalid-bsd1-flag-on-vebox: SUCCESS (0.001s)
[769.259243] [072/142] (251s left) gem_huc_copy (huc-copy)
[769.658604] Starting subtest: huc-copy
[769.673060] Subtest huc-copy: SUCCESS (0.014s)
[771.674748] [073/142] (249s left) gem_pxp (create-regular-buffer)
[774.581836] Subtest create-regular-buffer: SKIP (0.000s)
[774.766747] [074/142] (246s left) kms_ccs (pipe-a-crc-primary-basic-y_tiled_ccs)
[775.698206] Starting subtest: pipe-A-crc-primary-basic-y_tiled_ccs
[775.704443] Subtest pipe-A-crc-primary-basic-y_tiled_ccs: SKIP (0.002s)
[777.155091] [075/142] (244s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[777.723603] Starting subtest: cursorB-vs-flipB-legacy
[777.735868] Subtest cursorB-vs-flipB-legacy: SKIP (0.008s)
[778.094980] [076/142] (243s left) gem_render_copy (y-tiled-ccs-to-x-tiled)
[778.498789] Starting subtest: y-tiled-ccs-to-x-tiled
[778.505937] Subtest y-tiled-ccs-to-x-tiled: SKIP (0.000s)
[778.790515] [077/142] (242s left) gem_exec_schedule (preemptive-hang)
[779.357809] Starting subtest: preemptive-hang
[779.361739] Starting dynamic subtest: rcs0
[789.298156] Dynamic subtest rcs0: SUCCESS (9.938s)
[789.361599] Starting dynamic subtest: bcs0
[799.176163] Dynamic subtest bcs0: SUCCESS (9.872s)
[799.209592] Starting dynamic subtest: vcs0
[809.177917] Dynamic subtest vcs0: SUCCESS (9.997s)
[809.225844] Starting dynamic subtest: vcs1
[819.267714] Dynamic subtest vcs1: SUCCESS (10.084s)
[819.329489] Starting dynamic subtest: vecs0
[828.825326] Dynamic subtest vecs0: SUCCESS (9.553s)
[828.878945] Starting dynamic subtest: ccs0
[838.243741] Dynamic subtest ccs0: SUCCESS (9.414s)
[838.247381] Subtest preemptive-hang: SUCCESS (58.887s)
[838.661200] [078/142] (183s left) gem_shrink (reclaim)
[839.064757] Starting subtest: reclaim
[841.748240] Subtest reclaim: SUCCESS (2.684s)
[841.960173] [079/142] (179s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-pwrite)
[842.480603] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-pwrite
[842.487442] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[842.662664] [080/142] (179s left) kms_addfb_basic (invalid-set-prop-any)
[843.053537] Starting subtest: invalid-set-prop-any
[843.057578] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[843.346968] [081/142] (178s left) gem_userptr_blits (sync-unmap-after-close)
[843.809210] Starting subtest: sync-unmap-after-close
[843.863488] Subtest sync-unmap-after-close: SUCCESS (0.058s)
[844.112257] [082/142] (178s left) gem_exec_params (invalid-fence-in-submit)
[844.513995] Starting subtest: invalid-fence-in-submit
[844.531875] Subtest invalid-fence-in-submit: SUCCESS (0.015s)
[844.705557] [083/142] (177s left) gem_render_copy (yf-tiled-to-vebox-linear)
[845.118063] Starting subtest: yf-tiled-to-vebox-linear
[845.124450] Subtest yf-tiled-to-vebox-linear: SKIP (0.000s)
[845.409287] [084/142] (176s left) kms_cursor_legacy (cursora-vs-flipb-toggle)
[845.984493] Starting subtest: cursorA-vs-flipB-toggle
[845.992854] Subtest cursorA-vs-flipB-toggle: SKIP (0.008s)
[846.356057] [085/142] (176s left) kms_ccs (pipe-c-crc-primary-basic-4_tiled_mtl_rc_ccs_cc)
[847.782938] Starting subtest: pipe-C-crc-primary-basic-4_tiled_mtl_rc_ccs_cc
[848.781237] Subtest pipe-C-crc-primary-basic-4_tiled_mtl_rc_ccs_cc: SUCCESS (0.998s)
[850.260065] [086/142] (172s left) perf_pmu (cpu-hotplug)
[850.664496] Starting subtest: cpu-hotplug
[850.679605] Subtest cpu-hotplug: SKIP (0.012s)
[850.880656] [087/142] (171s left) gem_vm_create (isolation)
[851.277680] Starting subtest: isolation
[851.309371] Subtest isolation: SUCCESS (0.031s)
[851.505719] [088/142] (171s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-plflip-blt)
[852.017748] Starting subtest: fbcpsr-2p-scndscrn-indfb-plflip-blt
[852.024583] Subtest fbcpsr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[852.212686] [089/142] (170s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[852.770959] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[858.254213] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: FAIL (4.682s)
[858.306505] [090/142] (164s left) kms_vblank (pipe-c-wait-forked-busy-hang)
[858.786900] Starting subtest: pipe-C-wait-forked-busy-hang
[874.597631] Subtest pipe-C-wait-forked-busy-hang: SUCCESS (15.808s)
[875.559681] [091/142] (147s left) gem_exec_fence (syncobj-channel)
[877.664656] Starting subtest: syncobj-channel
[878.780945] Subtest syncobj-channel: SUCCESS (1.116s)
[879.251733] [092/142] (143s left) kms_invalid_mode (zero-vdisplay)
[879.747143] Starting subtest: zero-vdisplay
[879.758063] Starting dynamic subtest: eDP-1-pipe-A
[880.190592] Dynamic subtest eDP-1-pipe-A: SUCCESS (0.433s)
[880.255155] Starting dynamic subtest: eDP-1-pipe-B
[880.258980] Dynamic subtest eDP-1-pipe-B: SUCCESS (0.042s)
[880.313651] Starting dynamic subtest: eDP-1-pipe-C
[880.318553] Dynamic subtest eDP-1-pipe-C: SUCCESS (0.040s)
[880.374944] Starting dynamic subtest: eDP-1-pipe-D
[880.379441] Dynamic subtest eDP-1-pipe-D: SUCCESS (0.043s)
[880.383832] Subtest zero-vdisplay: SUCCESS (0.596s)
[881.115429] [093/142] (142s left) gem_mmap_gtt (basic-small-bo-tiledx)
[881.499348] Subtest basic-small-bo-tiledX: SKIP (0.000s)
[881.622368] [094/142] (141s left) gem_mmap_gtt (close-race)
[881.989128] Subtest close-race: SKIP (0.000s)
[882.120113] [095/142] (141s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[882.688514] Starting subtest: flip-vs-cursor-crc-atomic
[884.355795] Subtest flip-vs-cursor-crc-atomic: SUCCESS (1.668s)
[885.335369] [096/142] (138s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-render)
[885.850756] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-render
[885.857588] Subtest fbc-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[886.040174] [097/142] (137s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-blt)
[886.562422] Starting subtest: psr-1p-offscren-pri-indfb-draw-blt
[899.525185] Subtest psr-1p-offscren-pri-indfb-draw-blt: SUCCESS (12.971s)
[899.817647] [098/142] (123s left) kms_vblank (pipe-a-wait-forked-busy)
[900.312358] Starting subtest: pipe-A-wait-forked-busy
[902.871973] Subtest pipe-A-wait-forked-busy: SUCCESS (2.560s)
[903.840985] [099/142] (119s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[904.340539] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[904.347125] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[904.519525] [100/142] (119s left) syncobj_basic (bad-flags-fd-to-handle)
[904.902588] Starting subtest: bad-flags-fd-to-handle
[904.906238] Subtest bad-flags-fd-to-handle: SUCCESS (0.000s)
[905.058539] [101/142] (118s left) kms_ccs (pipe-b-ccs-on-another-bo-y_tiled_gen12_rc_ccs)
[906.317741] Starting subtest: pipe-B-ccs-on-another-bo-y_tiled_gen12_rc_ccs
[906.324220] Subtest pipe-B-ccs-on-another-bo-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[907.441742] [102/142] (116s left) kms_ccs (pipe-d-random-ccs-data-y_tiled_ccs)
[909.124326] Starting subtest: pipe-D-random-ccs-data-y_tiled_ccs
[909.134160] Subtest pipe-D-random-ccs-data-y_tiled_ccs: SKIP (0.000s)
[909.825058] [103/142] (114s left) vc4/vc4_label_bo (set-bad-handle)
[910.298845] Subtest set-bad-handle: SKIP (0.000s)
[910.439946] [104/142] (113s left) kms_ccs (pipe-a-bad-rotation-90-4_tiled_dg2_rc_ccs)
[911.342236] Starting subtest: pipe-A-bad-rotation-90-4_tiled_dg2_rc_ccs
[911.348866] Subtest pipe-A-bad-rotation-90-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[912.839457] [105/142] (111s left) kms_cdclk (plane-scaling)
[913.335526] Starting subtest: plane-scaling
[913.338663] Starting dynamic subtest: pipe-A-eDP-1
[913.760919] Dynamic subtest pipe-A-eDP-1: SKIP (0.422s)
[913.817715] Starting dynamic subtest: pipe-B-eDP-1
[913.823486] Dynamic subtest pipe-B-eDP-1: SKIP (0.028s)
[913.879296] Starting dynamic subtest: pipe-C-eDP-1
[913.885329] Dynamic subtest pipe-C-eDP-1: SKIP (0.028s)
[913.941748] Starting dynamic subtest: pipe-D-eDP-1
[913.947985] Dynamic subtest pipe-D-eDP-1: SKIP (0.027s)
[913.954107] Subtest plane-scaling: SKIP (0.531s)
[914.705572] [106/142] (109s left) syncobj_timeline (single-wait-available-submitted)
[915.096315] Starting subtest: single-wait-available-submitted
[915.099801] Subtest single-wait-available-submitted: SUCCESS (0.001s)
[915.259514] [107/142] (109s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-move)
[915.750567] Starting subtest: psr-2p-primscrn-spr-indfb-move
[915.757721] Subtest psr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[915.936702] [108/142] (108s left) syncobj_timeline (multi-wait-for-submit-available-signaled)
[916.317357] Starting subtest: multi-wait-for-submit-available-signaled
[916.321162] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.005s)
[916.474741] [109/142] (108s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-render)
[916.974631] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-render
[916.981322] Subtest fbc-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[917.150100] [110/142] (107s left) gem_exec_flush (basic-wb-set-default)
[917.588606] Starting subtest: basic-wb-set-default
[923.494508] Subtest basic-wb-set-default: SUCCESS (5.904s)
[923.828918] [111/142] (101s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs)
[925.396837] Starting subtest: pipe-C-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs
[925.414945] Subtest pipe-C-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs: SKIP (0.016s)
[926.237146] [112/142] ( 98s left) gen9_exec_parse (batch-zero-length)
[926.631984] Subtest batch-zero-length: SKIP (0.000s)
[926.764210] [113/142] ( 98s left) kms_async_flips (invalid-async-flip)
[927.169759] Starting subtest: invalid-async-flip
[927.175986] Subtest invalid-async-flip: SKIP (0.000s)
[928.538293] [114/142] ( 96s left) kms_ccs (pipe-a-bad-aux-stride-y_tiled_gen12_rc_ccs)
[929.511312] Starting subtest: pipe-A-bad-aux-stride-y_tiled_gen12_rc_ccs
[929.518048] Subtest pipe-A-bad-aux-stride-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[930.890192] [115/142] ( 94s left) kms_ccs (pipe-d-bad-pixel-format-y_tiled_gen12_rc_ccs)
[932.489318] Starting subtest: pipe-D-bad-pixel-format-y_tiled_gen12_rc_ccs
[932.496094] Subtest pipe-D-bad-pixel-format-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[933.318164] [116/142] ( 92s left) gem_exec_params (cliprects-invalid)
[933.711155] Starting subtest: cliprects-invalid
[933.715223] Subtest cliprects-invalid: SUCCESS (0.002s)
[933.892771] [117/142] ( 91s left) kms_ccs (pipe-d-crc-primary-basic-y_tiled_gen12_rc_ccs)
[935.593659] Starting subtest: pipe-D-crc-primary-basic-y_tiled_gen12_rc_ccs
[935.600305] Subtest pipe-D-crc-primary-basic-y_tiled_gen12_rc_ccs: SKIP (0.002s)
[936.356650] [118/142] ( 89s left) kms_vblank (pipe-d-accuracy-idle)
[936.848146] Starting subtest: pipe-D-accuracy-idle
[940.796485] Subtest pipe-D-accuracy-idle: SUCCESS (3.948s)
[941.756521] [119/142] ( 83s left) gem_exec_flush (basic-batch-kernel-default-uc)
[942.197993] Starting subtest: basic-batch-kernel-default-uc
[950.264750] Subtest basic-batch-kernel-default-uc: SUCCESS (8.062s)
[950.587267] [120/142] ( 75s left) kms_ccs (pipe-b-random-ccs-data-y_tiled_gen12_rc_ccs)
[951.740118] Starting subtest: pipe-B-random-ccs-data-y_tiled_gen12_rc_ccs
[951.750400] Subtest pipe-B-random-ccs-data-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[952.778867] [121/142] ( 73s left) kms_psr2_su (frontbuffer-xrgb8888)
[953.130711] Subtest frontbuffer-XRGB8888: SKIP (0.000s)
[953.259578] [122/142] ( 72s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180)
[953.777872] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180
[953.785799] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[953.939536] [123/142] ( 72s left) kms_ccs (pipe-b-crc-sprite-planes-basic-y_tiled_ccs)
[955.220370] Starting subtest: pipe-B-crc-sprite-planes-basic-y_tiled_ccs
[955.235263] Subtest pipe-B-crc-sprite-planes-basic-y_tiled_ccs: SKIP (0.012s)
[956.277246] [124/142] ( 69s left) api_intel_bb (render)
[956.654785] Starting subtest: render
[956.658408] Starting dynamic subtest: render-none-512
[957.106592] Dynamic subtest render-none-512: SUCCESS (0.454s)
[957.150752] Starting dynamic subtest: render-none-1024
[958.319724] Dynamic subtest render-none-1024: SUCCESS (1.210s)
[958.371335] Starting dynamic subtest: render-x-512
[958.663345] Dynamic subtest render-x-512: SUCCESS (0.340s)
[958.715849] Starting dynamic subtest: render-x-1024
[959.784249] Dynamic subtest render-x-1024: SUCCESS (1.117s)
[959.836349] Starting dynamic subtest: render-y-512
[960.102649] Dynamic subtest render-y-512: SUCCESS (0.314s)
[960.154600] Starting dynamic subtest: render-y-1024
[961.230501] Dynamic subtest render-y-1024: SUCCESS (1.123s)
[961.234056] Subtest render: SUCCESS (4.582s)
[961.437319] [125/142] ( 64s left) i915_pm_rps (fence-order)
[961.847237] Starting subtest: fence-order
[964.272296] Subtest fence-order: SUCCESS (2.421s)
[964.447387] [126/142] ( 61s left) kms_ccs (pipe-a-crc-sprite-planes-basic-yf_tiled_ccs)
[965.535271] Starting subtest: pipe-A-crc-sprite-planes-basic-yf_tiled_ccs
[965.542568] Subtest pipe-A-crc-sprite-planes-basic-yf_tiled_ccs: SKIP (0.004s)
[966.313156] [127/142] ( 60s left) i915_pm_rpm (gem-execbuf)
[968.758581] Starting subtest: gem-execbuf
[968.762172] Starting dynamic subtest: smem0
[974.762364] Dynamic subtest smem0: SUCCESS (6.007s)
[974.765486] Subtest gem-execbuf: SUCCESS (6.012s)
[974.970310] [128/142] ( 51s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[975.448343] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[975.463792] Starting dynamic subtest: pipe-A-default-mode
[975.849364] Dynamic subtest pipe-A-default-mode: SKIP (0.381s)
[975.856252] Subtest flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SKIP (0.396s)
[976.786187] [129/142] ( 49s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-pgflip-blt)
[977.086693] Starting subtest: psr-2p-scndscrn-shrfb-pgflip-blt
[977.092161] Subtest psr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[977.203571] [130/142] ( 49s left) i915_pm_backlight (fade)
[977.376600] Starting subtest: fade
[977.613598] Starting dynamic subtest: eDP-1
[980.408905] Dynamic subtest eDP-1: SUCCESS (2.797s)
[980.412359] Subtest fade: SUCCESS (3.032s)
[981.384464] [131/142] ( 45s left) kms_flip (2x-plain-flip-ts-check)
[981.777221] Starting subtest: 2x-plain-flip-ts-check
[981.936553] Subtest 2x-plain-flip-ts-check: SKIP (0.161s)
[982.150474] [132/142] ( 44s left) api_intel_allocator (fork-simple-once)
[982.526105] Starting subtest: fork-simple-once
[982.609381] Subtest fork-simple-once: SUCCESS (0.083s)
[982.806867] [133/142] ( 44s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-wc)
[983.324273] Starting subtest: psr-rgb101010-draw-mmap-wc
[996.315763] Subtest psr-rgb101010-draw-mmap-wc: SUCCESS (12.993s)
[996.513912] [134/142] ( 30s left) gem_exec_gttfill (basic)
[996.867598] Starting subtest: basic
[1008.546262] Subtest basic: SUCCESS (11.677s)
[1008.864994] [135/142] ( 18s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-downscaling)
[1009.476171] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-downscaling
[1009.480415] Starting dynamic subtest: pipe-A-default-mode
[1009.801386] Dynamic subtest pipe-A-default-mode: SKIP (0.320s)
[1009.808830] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-downscaling: SKIP (0.328s)
[1010.798816] [136/142] ( 16s left) gem_exec_schedule (wide)
[1011.416345] Starting subtest: wide
[1011.419889] Starting dynamic subtest: rcs0
[1045.171451] Dynamic subtest rcs0: SUCCESS (33.752s)
[1045.231057] Starting dynamic subtest: bcs0
[1077.364550] Dynamic subtest bcs0: SUCCESS (32.190s)
[1077.412126] Starting dynamic subtest: vcs0
[1108.928967] Dynamic subtest vcs0: SUCCESS (31.559s)
[1108.979130] Starting dynamic subtest: vcs1
[1140.599052] Dynamic subtest vcs1: SUCCESS (31.667s)
[1140.643850] Starting dynamic subtest: vecs0
[1172.613121] Dynamic subtest vecs0: SUCCESS (32.010s)
[1172.655050] Starting dynamic subtest: ccs0
[1205.847534] Dynamic subtest ccs0: SUCCESS (33.231s)
[1205.851985] Subtest wide: SUCCESS (194.432s)
[1208.658564] Overall timeout time exceeded, stopping.
[1208.659877] Closing watchdogs
Done.