[51.581862] Initializing watchdogs
[51.582138]   /dev/watchdog0
[51.598908] [001/138] (960s left) kms_properties (invalid-properties-atomic)
[51.873911] Starting subtest: invalid-properties-atomic
[52.129938] Subtest invalid-properties-atomic: SUCCESS (0.256s)
[52.219519] [002/138] (959s left) kms_plane_scaling (planes-scaler-unity-scaling)
[52.460595] Starting subtest: planes-scaler-unity-scaling
[52.462756] Starting dynamic subtest: pipe-A-DP-1
[52.583256] Dynamic subtest pipe-A-DP-1: SUCCESS (0.119s)
[52.598568] Starting dynamic subtest: pipe-B-DP-1
[52.986834] Dynamic subtest pipe-B-DP-1: SUCCESS (0.402s)
[53.007452] Starting dynamic subtest: pipe-C-DP-1
[53.401923] Dynamic subtest pipe-C-DP-1: SUCCESS (0.413s)
[53.404102] Subtest planes-scaler-unity-scaling: SUCCESS (0.943s)
[53.804614] [003/138] (958s left) kms_ccs (pipe-d-crc-primary-basic-y-tiled-gen12-rc-ccs)
[54.298156] Subtest pipe-D-crc-primary-basic-y-tiled-gen12-rc-ccs: SKIP (0.000s)
[54.603962] [004/138] (957s left) syncobj_timeline (multi-wait-available-signaled)
[54.755445] Starting subtest: multi-wait-available-signaled
[54.757708] Subtest multi-wait-available-signaled: SUCCESS (0.002s)
[54.840840] [005/138] (957s left) kms_ccs (pipe-a-crc-primary-basic-y-tiled-gen12-mc-ccs)
[55.175066] Starting subtest: pipe-A-crc-primary-basic-y-tiled-gen12-mc-ccs
[55.179556] Subtest pipe-A-crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.001s)
[55.628800] [006/138] (956s left) gem_exec_store (cachelines)
[55.807942] Starting subtest: cachelines
[55.810371] Starting dynamic subtest: rcs0
[55.868964] Dynamic subtest rcs0: SUCCESS (0.061s)
[55.887398] Starting dynamic subtest: bcs0
[55.895252] Dynamic subtest bcs0: SUCCESS (0.026s)
[55.909808] Starting dynamic subtest: vcs0
[55.931714] Dynamic subtest vcs0: SUCCESS (0.036s)
[55.946208] Starting dynamic subtest: vecs0
[55.957658] Dynamic subtest vecs0: SUCCESS (0.026s)
[55.959765] Subtest cachelines: SUCCESS (0.150s)
[56.145964] [007/138] (956s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[56.404081] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[56.408122] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[56.496819] [008/138] (955s left) drm_fdinfo (virtual-busy-all)
[56.650797] Starting subtest: virtual-busy-all
[56.653036] Subtest virtual-busy-all: SUCCESS (0.001s)
[56.730456] [009/138] (955s left) perf (global-sseu-config)
[57.026482] Starting subtest: global-sseu-config
[57.028610] Starting dynamic subtest: 0-rcs0
[57.148444] Dynamic subtest 0-rcs0: SUCCESS (0.122s)
[57.150543] Subtest global-sseu-config: SUCCESS (0.123s)
[57.196823] [010/138] (955s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-msflip-blt)
[57.447071] Starting subtest: fbc-2p-scndscrn-shrfb-msflip-blt
[57.450971] Subtest fbc-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[57.529223] [011/138] (954s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-yf-tiled)
[57.692456] Starting subtest: yf-tiled-mc-ccs-to-vebox-yf-tiled
[57.696570] Subtest yf-tiled-mc-ccs-to-vebox-yf-tiled: SKIP (0.000s)
[57.879487] [012/138] (954s left) gem_ctx_shared (q-smoketest-all)
[58.049911] Starting subtest: Q-smoketest-all
[90.365846] Subtest Q-smoketest-all: SUCCESS (32.306s)
[90.586828] [013/138] (921s left) syncobj_basic (test-valid-cycle)
[90.734704] Starting subtest: test-valid-cycle
[90.736902] Subtest test-valid-cycle: SUCCESS (0.000s)
[90.816501] [014/138] (921s left) gem_gtt_cpu_tlb
[91.064168] [015/138] (921s left) gem_ctx_persistence (hang)
[91.228063] Starting subtest: hang
[91.241434] Subtest hang: SKIP (0.010s)
[91.346633] [016/138] (921s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[91.585100] Starting subtest: nonblocking-crc-frame-sequence
[91.587248] Starting dynamic subtest: pipe-A-DP-1
[92.176756] Dynamic subtest pipe-A-DP-1: SUCCESS (0.589s)
[92.192789] Starting dynamic subtest: pipe-B-DP-1
[92.928754] Dynamic subtest pipe-B-DP-1: SUCCESS (0.749s)
[92.946241] Starting dynamic subtest: pipe-C-DP-1
[93.735706] Dynamic subtest pipe-C-DP-1: SUCCESS (0.779s)
[93.738167] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.127s)
[94.075080] [017/138] (918s left) sysfs_preempt_timeout (timeout)
[94.331498] Starting subtest: timeout
[94.354914] Starting dynamic subtest: vecs0
[95.157196] Dynamic subtest vecs0: SUCCESS (0.869s)
[95.173751] Starting dynamic subtest: vcs0
[96.023261] Dynamic subtest vcs0: SUCCESS (0.865s)
[96.040157] Starting dynamic subtest: bcs0
[96.908982] Dynamic subtest bcs0: SUCCESS (0.883s)
[96.925324] Starting dynamic subtest: rcs0
[97.793900] Dynamic subtest rcs0: SUCCESS (0.887s)
[97.796194] Subtest timeout: SUCCESS (3.508s)
[97.874279] [018/138] (914s left) gem_busy (parallel)
[98.185802] Starting subtest: parallel
[98.188196] Starting dynamic subtest: rcs0
[98.239564] Dynamic subtest rcs0: SUCCESS (0.053s)
[98.255550] Starting dynamic subtest: bcs0
[98.275467] Dynamic subtest bcs0: SUCCESS (0.036s)
[98.291992] Starting dynamic subtest: vcs0
[98.310904] Dynamic subtest vcs0: SUCCESS (0.035s)
[98.327985] Starting dynamic subtest: vecs0
[98.349744] Dynamic subtest vecs0: SUCCESS (0.039s)
[98.352562] Subtest parallel: SUCCESS (0.165s)
[98.573851] [019/138] (914s left) gen9_exec_parse (bb-start-out)
[98.746212] Starting subtest: bb-start-out
[98.750815] Subtest bb-start-out: SUCCESS (0.004s)
[98.974203] [020/138] (913s left) kms_ccs (pipe-b-crc-primary-rotation-180-yf-tiled-ccs)
[99.367366] Starting subtest: pipe-B-crc-primary-rotation-180-yf-tiled-ccs
[100.213899] Subtest pipe-B-crc-primary-rotation-180-yf-tiled-ccs: SUCCESS (0.849s)
[100.738118] [021/138] (912s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted)
[100.885311] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[100.887744] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.001s)
[100.968698] [022/138] (911s left) syncobj_wait (signal)
[101.110952] Starting subtest: signal
[101.113130] Subtest signal: SUCCESS (0.000s)
[101.185156] [023/138] (911s left) prime_mmap (test_forked_cpu_write)
[101.328399] Starting subtest: test_forked_cpu_write
[101.330435] Starting dynamic subtest: test_forked_cpu_write-smem
[101.365871] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.036s)
[101.368129] Subtest test_forked_cpu_write: SUCCESS (0.036s)
[101.470391] [024/138] (911s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt)
[101.717817] Starting subtest: psr-2p-primscrn-indfb-msflip-blt
[101.721714] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[101.803218] [025/138] (911s left) syncobj_wait (single-wait-all-for-submit-submitted)
[101.950392] Starting subtest: single-wait-all-for-submit-submitted
[102.056758] Subtest single-wait-all-for-submit-submitted: SUCCESS (0.108s)
[102.135866] [026/138] (910s left) kms_chamelium_frames (hdmi-crc-multiple)
[102.468218] Subtest hdmi-crc-multiple: SKIP (0.000s)
[102.748994] [027/138] (910s left) syncobj_timeline (invalid-multi-wait-available-unsubmitted-submitted)
[102.892468] Starting subtest: invalid-multi-wait-available-unsubmitted-submitted
[102.895310] Subtest invalid-multi-wait-available-unsubmitted-submitted: SUCCESS (0.001s)
[102.976391] [028/138] (910s left) gem_ppgtt (shrink-vs-evict-pinned)
[103.128795] Starting subtest: shrink-vs-evict-pinned
[108.222960] Subtest shrink-vs-evict-pinned: SUCCESS (5.091s)
[108.311390] [029/138] (904s left) kms_getfb (getfb-handle-closed)
[108.454299] Starting subtest: getfb-handle-closed
[108.456621] Subtest getfb-handle-closed: SUCCESS (0.000s)
[108.543453] [030/138] (904s left) kms_cursor_crc (cursor-sliding-128x42)
[108.786350] Starting subtest: cursor-sliding-128x42
[108.798565] Starting dynamic subtest: pipe-A-DP-1
[112.472161] Dynamic subtest pipe-A-DP-1: SUCCESS (3.674s)
[112.550638] Starting dynamic subtest: pipe-C-DP-1
[116.296105] Dynamic subtest pipe-C-DP-1: SUCCESS (3.759s)
[116.298066] Subtest cursor-sliding-128x42: SUCCESS (7.508s)
[116.656470] [031/138] (896s left) kms_ccs (pipe-b-ccs-on-another-bo-4-tiled-mtl-rc-ccs)
[117.074145] Starting subtest: pipe-B-ccs-on-another-bo-4-tiled-mtl-rc-ccs
[117.078188] Subtest pipe-B-ccs-on-another-bo-4-tiled-mtl-rc-ccs: SKIP (0.000s)
[117.448916] [032/138] (895s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[117.702288] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-cpu
[117.706131] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[117.797394] [033/138] (895s left) gem_fenced_exec_thrash (no-spare-fences-interruptible)
[117.945140] Starting subtest: no-spare-fences-interruptible
[120.111881] Subtest no-spare-fences-interruptible: SUCCESS (2.161s)
[120.264262] [034/138] (893s left) tools_test (sysfs_l3_parity)
[120.416340] Starting subtest: sysfs_l3_parity
[120.601999] Subtest sysfs_l3_parity: SKIP (0.194s)
[120.665665] [035/138] (892s left) kms_psr (psr2_no_drrs)
[120.812588] Subtest psr2_no_drrs: SKIP (0.000s)
[120.866505] [036/138] (892s left) gem_spin_batch (resubmit-new)
[121.032479] Starting subtest: resubmit-new
[121.034719] Starting dynamic subtest: rcs0
[121.044890] Dynamic subtest rcs0: SUCCESS (0.009s)
[121.062850] Starting dynamic subtest: bcs0
[121.066115] Dynamic subtest bcs0: SUCCESS (0.011s)
[121.082263] Starting dynamic subtest: vcs0
[121.084197] Dynamic subtest vcs0: SUCCESS (0.008s)
[121.099987] Starting dynamic subtest: vecs0
[121.102149] Dynamic subtest vecs0: SUCCESS (0.008s)
[121.104181] Subtest resubmit-new: SUCCESS (0.039s)
[121.300282] [037/138] (892s left) kms_addfb_basic (invalid-smem-bo-on-discrete)
[121.446704] Starting subtest: invalid-smem-bo-on-discrete
[121.450859] Subtest invalid-smem-bo-on-discrete: SKIP (0.000s)
[121.648453] [038/138] (891s left) kms_psr (cursor_blt)
[121.792475] Subtest cursor_blt: SKIP (0.000s)
[121.832240] [039/138] (891s left) perf_pmu (busy-start)
[121.980632] Starting subtest: busy-start
[121.982935] Starting dynamic subtest: rcs0
[124.510545] Dynamic subtest rcs0: SUCCESS (2.530s)
[124.527231] Starting dynamic subtest: bcs0
[127.034390] Dynamic subtest bcs0: SUCCESS (2.524s)
[127.051139] Starting dynamic subtest: vcs0
[129.558415] Dynamic subtest vcs0: SUCCESS (2.524s)
[129.575264] Starting dynamic subtest: vecs0
[132.082241] Dynamic subtest vecs0: SUCCESS (2.524s)
[132.084490] Subtest busy-start: SUCCESS (10.102s)
[132.166476] [040/138] (881s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-plflip-blt)
[132.416459] Starting subtest: fbcpsr-2p-scndscrn-shrfb-plflip-blt
[132.420525] Subtest fbcpsr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[132.518293] [041/138] (881s left) kms_pm_dc (dc6-psr)
[133.771659] Starting subtest: dc6-psr
[133.777792] Subtest dc6-psr: SKIP (0.000s)
[133.932364] [042/138] (879s left) vc4/vc4_mmap (mmap-bad-handle)
[134.117471] Subtest mmap-bad-handle: SKIP (0.000s)
[134.184960] [043/138] (879s left) gem_exec_reloc (basic-cpu-read-active)
[134.342839] Starting subtest: basic-cpu-read-active
[134.481907] Subtest basic-cpu-read-active: SUCCESS (0.138s)
[134.667292] [044/138] (879s left) syncobj_timeline (etime-single-wait-submitted)
[134.808755] Starting subtest: etime-single-wait-submitted
[134.912593] Subtest etime-single-wait-submitted: SUCCESS (0.102s)
[134.998882] [045/138] (878s left) kms_prime (basic-crc-hybrid)
[137.028279] Subtest basic-crc-hybrid: SKIP (0.000s)
[137.171349] [046/138] (876s left) gem_exec_flush (basic-wb-rw-before-default)
[137.345591] Starting subtest: basic-wb-rw-before-default
[142.815588] Subtest basic-wb-rw-before-default: SUCCESS (5.470s)
[143.018446] [047/138] (870s left) kms_chamelium_hpd (common-hpd-after-suspend)
[143.345206] Subtest common-hpd-after-suspend: SKIP (0.000s)
[143.707949] [048/138] (870s left) kms_plane_scaling (plane-downscale-factor-0-25-with-modifiers)
[144.026439] Starting subtest: plane-downscale-factor-0-25-with-modifiers
[144.049885] Starting dynamic subtest: pipe-A-DP-1
[144.095809] Dynamic subtest pipe-A-DP-1: SKIP (0.048s)
[144.124678] Starting dynamic subtest: pipe-B-DP-1
[144.194781] Dynamic subtest pipe-B-DP-1: SKIP (0.096s)
[144.223753] Starting dynamic subtest: pipe-C-DP-1
[144.292337] Dynamic subtest pipe-C-DP-1: SKIP (0.097s)
[144.338973] Subtest plane-downscale-factor-0-25-with-modifiers: SKIP (0.250s)
[144.450954] [049/138] (869s left) syncobj_timeline (single-wait-all-for-submit-signaled)
[144.649862] Starting subtest: single-wait-all-for-submit-signaled
[144.652046] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.001s)
[144.742501] [050/138] (869s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[144.887434] Starting subtest: single-wait-for-submit-available-submitted
[144.889526] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.001s)
[144.973697] [051/138] (869s left) fbdev (read)
[145.140736] Starting subtest: read
[146.557682] Subtest read: SUCCESS (1.417s)
[146.623948] [052/138] (867s left) vc4/vc4_wait_bo (used-bo-1ns)
[146.825179] Subtest used-bo-1ns: SKIP (0.000s)
[146.893794] [053/138] (867s left) kms_ccs (pipe-c-bad-pixel-format-y-tiled-ccs)
[147.316230] Starting subtest: pipe-C-bad-pixel-format-y-tiled-ccs
[147.320187] Subtest pipe-C-bad-pixel-format-y-tiled-ccs: SKIP (0.000s)
[147.682452] [054/138] (866s left) kms_cursor_legacy (cursor-vs-flip-atomic)
[147.923445] Starting subtest: cursor-vs-flip-atomic
[185.907199] Subtest cursor-vs-flip-atomic: SUCCESS (37.985s)
[186.231142] [055/138] (828s left) kms_feature_discovery (psr1)
[186.481160] Starting subtest: psr1
[186.484997] Subtest psr1: SKIP (0.000s)
[186.561973] [056/138] (827s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-pwrite)
[186.812801] Starting subtest: fbcpsr-rgb565-draw-pwrite
[186.816758] Subtest fbcpsr-rgb565-draw-pwrite: SKIP (0.000s)
[186.911294] [057/138] (827s left) perf (short-reads)
[187.156647] Starting subtest: short-reads
[187.207552] Subtest short-reads: SUCCESS (0.050s)
[187.262253] [058/138] (827s left) v3d/v3d_submit_cl (bad-multisync-pad)
[187.461050] Subtest bad-multisync-pad: SKIP (0.000s)
[187.527296] [059/138] (826s left) syncobj_wait (wait-all-interrupted)
[187.673025] Starting subtest: wait-all-interrupted
[187.776619] Subtest wait-all-interrupted: SUCCESS (0.101s)
[187.860727] [060/138] (826s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-render)
[188.118984] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-render
[188.123087] Subtest fbc-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[188.210438] [061/138] (826s left) kms_ccs (pipe-c-ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[188.665495] Starting subtest: pipe-C-ccs-on-another-bo-y-tiled-gen12-mc-ccs
[188.669725] Subtest pipe-C-ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.000s)
[188.998184] [062/138] (825s left) drm_read (short-buffer-block)
[189.363473] Starting subtest: short-buffer-block
[189.367543] Subtest short-buffer-block: SUCCESS (0.000s)
[189.482131] [063/138] (825s left) kms_big_fb (linear-64bpp-rotate-0)
[189.748554] Starting subtest: linear-64bpp-rotate-0
[198.213472] Subtest linear-64bpp-rotate-0: SUCCESS (8.463s)
[199.133500] [064/138] (815s left) gem_exec_schedule (implicit-read-write)
[199.310343] Starting subtest: implicit-read-write
[199.312536] Starting dynamic subtest: rcs0
[199.450536] Dynamic subtest rcs0: SUCCESS (0.139s)
[199.471417] Starting dynamic subtest: bcs0
[199.592182] Dynamic subtest bcs0: SUCCESS (0.140s)
[199.617261] Starting dynamic subtest: vcs0
[199.717795] Dynamic subtest vcs0: SUCCESS (0.124s)
[199.740032] Starting dynamic subtest: vecs0
[199.841965] Dynamic subtest vecs0: SUCCESS (0.120s)
[199.844156] Subtest implicit-read-write: SUCCESS (0.529s)
[200.198971] [065/138] (814s left) kms_atomic_interruptible (legacy-setmode)
[200.444736] Starting subtest: legacy-setmode
[200.446748] Starting dynamic subtest: pipe-A-DP-1
[206.986079] Dynamic subtest pipe-A-DP-1: SUCCESS (6.539s)
[206.988178] Subtest legacy-setmode: SUCCESS (6.543s)
[207.321280] [066/138] (807s left) gem_exec_nop (basic-series)
[207.494780] Starting subtest: basic-series
[210.554514] Subtest basic-series: SUCCESS (3.059s)
[210.786512] [067/138] (803s left) kms_flip (basic-flip-vs-dpms)
[211.016243] Starting subtest: basic-flip-vs-dpms
[211.018416] Starting dynamic subtest: A-DP1
[212.246396] Dynamic subtest A-DP1: SUCCESS (1.224s)
[212.264807] Starting dynamic subtest: B-DP1
[213.401192] Dynamic subtest B-DP1: SUCCESS (1.157s)
[213.422900] Starting dynamic subtest: C-DP1
[214.585339] Dynamic subtest C-DP1: SUCCESS (1.182s)
[214.588593] Subtest basic-flip-vs-dpms: SUCCESS (3.573s)
[214.956460] [068/138] (799s left) kms_invalid_mode (uint-max-clock)
[215.202235] Starting subtest: uint-max-clock
[215.205412] Starting dynamic subtest: pipe-A-DP-1
[215.296589] Dynamic subtest pipe-A-DP-1: SUCCESS (0.096s)
[215.315212] Starting dynamic subtest: pipe-B-DP-1
[215.317213] Dynamic subtest pipe-B-DP-1: SUCCESS (0.002s)
[215.337036] Starting dynamic subtest: pipe-C-DP-1
[215.340323] Dynamic subtest pipe-C-DP-1: SUCCESS (0.002s)
[215.343109] Subtest uint-max-clock: SUCCESS (0.109s)
[215.643822] [069/138] (799s left) kms_big_fb (x-tiled-8bpp-rotate-180)
[215.904310] Starting subtest: x-tiled-8bpp-rotate-180
[218.772057] Subtest x-tiled-8bpp-rotate-180: SUCCESS (2.867s)
[219.010401] [070/138] (795s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-onoff)
[219.261235] Starting subtest: fbc-2p-scndscrn-spr-indfb-onoff
[219.266165] Subtest fbc-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[219.343861] [071/138] (795s left) prime_mmap (test_correct)
[219.487247] Starting subtest: test_correct
[219.489246] Starting dynamic subtest: test_correct-smem
[219.494491] Dynamic subtest test_correct-smem: SUCCESS (0.007s)
[219.496723] Subtest test_correct: SUCCESS (0.008s)
[219.592948] [072/138] (795s left) syncobj_wait (wait-all-for-submit-snapshot)
[219.738786] Starting subtest: wait-all-for-submit-snapshot
[219.820914] Subtest wait-all-for-submit-snapshot: SUCCESS (0.083s)
[219.911436] [073/138] (795s left) kms_rotation_crc (cursor-rotation-180)
[220.157184] Starting subtest: cursor-rotation-180
[220.966341] Subtest cursor-rotation-180: SUCCESS (0.811s)
[221.384432] [074/138] (793s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-gtt)
[221.640867] Starting subtest: psr-rgb101010-draw-mmap-gtt
[221.644776] Subtest psr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[221.735694] [075/138] (793s left) gem_exec_reloc (basic-gtt)
[221.888832] Starting subtest: basic-gtt
[221.910285] Subtest basic-gtt: SUCCESS (0.021s)
[222.084460] [076/138] (793s left) kms_flip (basic-plain-flip)
[222.310922] Starting subtest: basic-plain-flip
[222.313045] Starting dynamic subtest: A-DP1
[223.497902] Dynamic subtest A-DP1: SUCCESS (1.187s)
[223.516546] Starting dynamic subtest: B-DP1
[224.624701] Dynamic subtest B-DP1: SUCCESS (1.124s)
[224.643203] Starting dynamic subtest: C-DP1
[225.760057] Dynamic subtest C-DP1: SUCCESS (1.134s)
[225.762151] Subtest basic-plain-flip: SUCCESS (3.455s)
[226.177190] [077/138] (788s left) gem_pwrite (basic-self)
[226.324208] Starting subtest: basic-self
[226.581891] Subtest basic-self: SUCCESS (0.258s)
[226.674297] [078/138] (788s left) kms_plane_cursor (viewport)
[226.909802] Starting subtest: viewport
[226.942731] Starting dynamic subtest: pipe-A-DP-1-size-64
[229.368987] Dynamic subtest pipe-A-DP-1-size-64: SUCCESS (2.459s)
[229.435802] Starting dynamic subtest: pipe-A-DP-1-size-128
[231.853443] Dynamic subtest pipe-A-DP-1-size-128: SUCCESS (2.432s)
[231.921838] Starting dynamic subtest: pipe-A-DP-1-size-256
[234.370808] Dynamic subtest pipe-A-DP-1-size-256: SUCCESS (2.466s)
[234.539112] Starting dynamic subtest: pipe-B-DP-1-size-64
[237.190606] Dynamic subtest pipe-B-DP-1-size-64: SUCCESS (2.671s)
[237.257671] Starting dynamic subtest: pipe-B-DP-1-size-128
[239.673889] Dynamic subtest pipe-B-DP-1-size-128: SUCCESS (2.432s)
[239.740941] Starting dynamic subtest: pipe-B-DP-1-size-256
[242.173934] Dynamic subtest pipe-B-DP-1-size-256: SUCCESS (2.449s)
[242.324729] Starting dynamic subtest: pipe-C-DP-1-size-64
[244.998108] Dynamic subtest pipe-C-DP-1-size-64: SUCCESS (2.691s)
[245.066813] Starting dynamic subtest: pipe-C-DP-1-size-128
[247.481558] Dynamic subtest pipe-C-DP-1-size-128: SUCCESS (2.432s)
[247.549756] Starting dynamic subtest: pipe-C-DP-1-size-256
[249.982464] Dynamic subtest pipe-C-DP-1-size-256: SUCCESS (2.449s)
[250.112386] Subtest viewport: SUCCESS (23.204s)
[250.433492] [079/138] (764s left) kms_psr2_su (page_flip-xrgb8888)
[250.592273] Subtest page_flip-XRGB8888: SKIP (0.000s)
[250.634115] [080/138] (764s left) kms_vblank (invalid)
[250.872805] Starting subtest: invalid
[250.874948] Starting dynamic subtest: pipe-A-DP-1
[251.127193] Dynamic subtest pipe-A-DP-1: SUCCESS (0.253s)
[251.131786] Subtest invalid: SUCCESS (0.257s)
[251.472486] [081/138] (763s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-blt)
[251.724232] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-blt
[251.728055] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[251.822594] [082/138] (763s left) kms_ccs (pipe-c-random-ccs-data-y-tiled-ccs)
[252.280465] Starting subtest: pipe-C-random-ccs-data-y-tiled-ccs
[252.286589] Subtest pipe-C-random-ccs-data-y-tiled-ccs: SKIP (0.000s)
[252.629127] [083/138] (762s left) gem_tiled_blits (interruptible)
[254.682509] Starting subtest: interruptible
[305.728065] Subtest interruptible: SUCCESS (51.045s)
[305.850245] [084/138] (709s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-onoff)
[306.233302] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-onoff
[306.237459] Subtest fbcpsr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[306.330666] [085/138] (709s left) kms_busy (extended-modeset-hang-newfb-with-reset)
[306.614891] Starting subtest: extended-modeset-hang-newfb-with-reset
[306.617407] Starting dynamic subtest: pipe-A
[309.944442] Dynamic subtest pipe-A: SUCCESS (3.325s)
[309.972794] Starting dynamic subtest: pipe-C
[313.245937] Dynamic subtest pipe-C: SUCCESS (3.294s)
[313.248402] Subtest extended-modeset-hang-newfb-with-reset: SUCCESS (6.632s)
[313.618761] [086/138] (701s left) kms_content_protection (legacy)
[313.894387] Starting subtest: legacy
[314.092784] Starting dynamic subtest: pipe-A-DP-1
[434.355636] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[435.212225] Closing watchdogs
[435.220216] Initializing watchdogs
[435.220413]   /dev/watchdog0
[435.246502] [087/138] (580s left) kms_ccs (pipe-c-crc-primary-rotation-180-yf-tiled-ccs)
[435.713951] Starting subtest: pipe-C-crc-primary-rotation-180-yf-tiled-ccs
[435.718207] Subtest pipe-C-crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.001s)
[436.060825] [088/138] (579s left) gem_ctx_param (invalid-size-set)
[436.210501] Starting subtest: invalid-size-set
[436.212857] Subtest invalid-size-set: SUCCESS (0.000s)
[436.292842] [089/138] (579s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-cpu)
[436.542683] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-cpu
[438.752176] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (2.214s)
[438.894317] [090/138] (576s left) gem_exec_fence (syncobj-import)
[439.517338] Starting subtest: syncobj-import
[439.523333] Subtest syncobj-import: SUCCESS (0.007s)
[439.893233] [091/138] (575s left) gem_softpin (safe-alignment)
[440.051515] Starting subtest: safe-alignment
[440.078289] Subtest safe-alignment: SUCCESS (0.030s)
[440.193922] [092/138] (575s left) kms_flip (2x-plain-flip-ts-check)
[440.422348] Starting subtest: 2x-plain-flip-ts-check
[440.427069] Subtest 2x-plain-flip-ts-check: SKIP (0.003s)
[440.544627] [093/138] (575s left) i915_suspend (fence-restore-untiled)
[440.687873] Starting subtest: fence-restore-untiled
[460.792862] Subtest fence-restore-untiled: SUCCESS (4.383s)
[463.938757] [094/138] (570s left) gem_lmem_swapping (verify-ccs)
[464.109359] Subtest verify-ccs: SKIP (0.000s)
[464.163572] [095/138] (570s left) kms_ccs (pipe-b-missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[464.568943] Starting subtest: pipe-B-missing-ccs-buffer-4-tiled-mtl-mc-ccs
[464.573028] Subtest pipe-B-missing-ccs-buffer-4-tiled-mtl-mc-ccs: SKIP (0.000s)
[464.953719] [096/138] (569s left) perf (missing-sample-flags)
[465.234757] Starting subtest: missing-sample-flags
[465.236978] Subtest missing-sample-flags: SUCCESS (0.001s)
[465.286616] [097/138] (569s left) i915_pm_rps (waitboost)
[465.441404] Starting subtest: waitboost
[468.014388] Subtest waitboost: SUCCESS (2.572s)
[468.104480] [098/138] (566s left) kms_ccs (pipe-d-bad-rotation-90-4-tiled-dg2-mc-ccs)
[468.580955] Subtest pipe-D-bad-rotation-90-4-tiled-dg2-mc-ccs: SKIP (0.000s)
[468.906691] [099/138] (565s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[469.168963] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[469.173104] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[469.271623] [100/138] (565s left) kms_rotation_crc (primary-y-tiled-reflect-x-180)
[469.525969] Starting subtest: primary-y-tiled-reflect-x-180
[469.530595] Subtest primary-y-tiled-reflect-x-180: SKIP (0.003s)
[469.621610] [101/138] (565s left) kms_ccs (pipe-a-random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[469.975629] Starting subtest: pipe-A-random-ccs-data-4-tiled-dg2-rc-ccs-cc
[469.982094] Subtest pipe-A-random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.000s)
[470.429954] [102/138] (564s left) syncobj_basic (bad-destroy)
[470.579249] Starting subtest: bad-destroy
[470.581584] Subtest bad-destroy: SUCCESS (0.000s)
[470.664555] [103/138] (564s left) gem_exec_fence (basic-wait)
[470.967767] Starting subtest: basic-wait
[470.970289] Starting dynamic subtest: rcs0
[470.997920] Dynamic subtest rcs0: SUCCESS (0.030s)
[471.028887] Starting dynamic subtest: bcs0
[471.031236] Dynamic subtest bcs0: SUCCESS (0.028s)
[471.057392] Starting dynamic subtest: vcs0
[471.064579] Dynamic subtest vcs0: SUCCESS (0.033s)
[471.091118] Starting dynamic subtest: vecs0
[471.093153] Dynamic subtest vecs0: SUCCESS (0.023s)
[471.095233] Subtest basic-wait: SUCCESS (0.123s)
[471.766182] [104/138] (563s left) kms_chamelium_color (ctm-negative)
[472.006447] Subtest ctm-negative: SKIP (0.000s)
[472.066206] [105/138] (563s left) i915_pm_rpm (gem-evict-pwrite)
[473.890439] Starting subtest: gem-evict-pwrite
[475.014267] Subtest gem-evict-pwrite: SUCCESS (1.123s)
[475.495016] [106/138] (559s left) kms_cursor_legacy (cursora-vs-flipb-legacy)
[475.738214] Starting subtest: cursorA-vs-flipB-legacy
[475.742747] Subtest cursorA-vs-flipB-legacy: SKIP (0.002s)
[475.827882] [107/138] (559s left) kms_ccs (pipe-d-crc-sprite-planes-basic-4-tiled-dg2-rc-ccs)
[476.303556] Subtest pipe-D-crc-sprite-planes-basic-4-tiled-dg2-rc-ccs: SKIP (0.000s)
[476.632746] [108/138] (558s left) gem_ctx_engines (independent)
[476.802001] Starting subtest: independent
[476.804187] Starting dynamic subtest: rcs0
[477.025950] Dynamic subtest rcs0: SUCCESS (0.222s)
[477.053242] Starting dynamic subtest: bcs0
[477.296356] Dynamic subtest bcs0: SUCCESS (0.265s)
[477.323835] Starting dynamic subtest: vcs0
[477.501176] Dynamic subtest vcs0: SUCCESS (0.205s)
[477.529456] Starting dynamic subtest: vecs0
[477.710436] Dynamic subtest vecs0: SUCCESS (0.207s)
[477.740520] Starting dynamic subtest: all-engines
[478.153490] Dynamic subtest all-engines: SUCCESS (0.440s)
[478.159109] Subtest independent: SUCCESS (1.353s)
[478.383019] [109/138] (556s left) kms_flip (2x-plain-flip-interruptible)
[478.617410] Starting subtest: 2x-plain-flip-interruptible
[478.625000] Subtest 2x-plain-flip-interruptible: SKIP (0.003s)
[478.733841] [110/138] (556s left) kms_ccs (pipe-a-crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc)
[479.108299] Starting subtest: pipe-A-crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc
[479.115867] Subtest pipe-A-crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SKIP (0.005s)
[479.546029] [111/138] (555s left) api_intel_bb (render)
[479.700118] Starting subtest: render
[479.702779] Starting dynamic subtest: render-none-512
[479.810154] Dynamic subtest render-none-512: SUCCESS (0.109s)
[479.837512] Starting dynamic subtest: render-none-reloc-512
[479.917638] Dynamic subtest render-none-reloc-512: SUCCESS (0.107s)
[479.945825] Starting dynamic subtest: render-none-1024
[480.287998] Dynamic subtest render-none-1024: SUCCESS (0.369s)
[480.315353] Starting dynamic subtest: render-none-reloc-1024
[480.658100] Dynamic subtest render-none-reloc-1024: SUCCESS (0.367s)
[480.686023] Starting dynamic subtest: render-x-512
[480.760207] Dynamic subtest render-x-512: SUCCESS (0.100s)
[480.788642] Starting dynamic subtest: render-x-reloc-512
[480.850983] Dynamic subtest render-x-reloc-512: SUCCESS (0.089s)
[480.878806] Starting dynamic subtest: render-x-1024
[481.165377] Dynamic subtest render-x-1024: SUCCESS (0.311s)
[481.193031] Starting dynamic subtest: render-x-reloc-1024
[481.487440] Dynamic subtest render-x-reloc-1024: SUCCESS (0.319s)
[481.512094] Starting dynamic subtest: render-y-512
[481.582716] Dynamic subtest render-y-512: SUCCESS (0.095s)
[481.607444] Starting dynamic subtest: render-y-reloc-512
[481.673753] Dynamic subtest render-y-reloc-512: SUCCESS (0.089s)
[481.698866] Starting dynamic subtest: render-y-1024
[481.988172] Dynamic subtest render-y-1024: SUCCESS (0.311s)
[482.013299] Starting dynamic subtest: render-y-reloc-1024
[482.310789] Dynamic subtest render-y-reloc-1024: SUCCESS (0.321s)
[482.315223] Subtest render: SUCCESS (2.615s)
[482.427641] [112/138] (553s left) kms_vblank (query-idle-hang)
[482.671321] Starting subtest: query-idle-hang
[482.673447] Starting dynamic subtest: pipe-A-DP-1
[489.678169] Dynamic subtest pipe-A-DP-1: SUCCESS (7.005s)
[489.716450] Starting dynamic subtest: pipe-C-DP-1
[496.722275] Dynamic subtest pipe-C-DP-1: SUCCESS (7.033s)
[496.724535] Subtest query-idle-hang: SUCCESS (14.051s)
[497.062803] [113/138] (538s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[497.304135] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[497.309836] Starting dynamic subtest: pipe-A-DP-1
[497.481528] Dynamic subtest pipe-A-DP-1: SUCCESS (0.174s)
[497.606454] Starting dynamic subtest: pipe-B-DP-1
[498.164135] Dynamic subtest pipe-B-DP-1: SUCCESS (0.584s)
[498.263165] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (0.959s)
[498.613394] [114/138] (536s left) kms_big_fb (yf-tiled-32bpp-rotate-180)
[498.884417] Starting subtest: yf-tiled-32bpp-rotate-180
[503.733588] Subtest yf-tiled-32bpp-rotate-180: SUCCESS (4.847s)
[504.262842] [115/138] (531s left) syncobj_timeline (device-signal-unordered)
[504.418093] Starting subtest: device-signal-unordered
[504.420378] Subtest device-signal-unordered: SUCCESS (0.002s)
[504.510869] [116/138] (531s left) api_intel_allocator (reopen-fork)
[504.654865] Starting subtest: reopen-fork
[507.930751] Subtest reopen-fork: SUCCESS (3.277s)
[508.034921] [117/138] (527s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-pwrite)
[508.288958] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-pwrite
[508.293002] Subtest fbcpsr-1p-offscren-pri-indfb-draw-pwrite: SKIP (0.000s)
[508.424464] [118/138] (527s left) kms_flip (blocking-wf_vblank)
[508.654822] Starting subtest: blocking-wf_vblank
[508.657203] Starting dynamic subtest: A-DP1
[519.639347] Dynamic subtest A-DP1: SUCCESS (10.981s)
[519.667585] Starting dynamic subtest: B-DP1
[530.375509] Dynamic subtest B-DP1: SUCCESS (10.733s)
[530.407484] Starting dynamic subtest: C-DP1
[541.121738] Dynamic subtest C-DP1: SUCCESS (10.743s)
[541.124529] Subtest blocking-wf_vblank: SUCCESS (32.474s)
[541.541374] [119/138] (494s left) v3d/v3d_submit_csd (bad-flag)
[541.745482] Subtest bad-flag: SKIP (0.000s)
[541.822019] [120/138] (494s left) kms_cursor_crc (cursor-onscreen-32x32)
[542.068481] Starting subtest: cursor-onscreen-32x32
[542.188973] Subtest cursor-onscreen-32x32: SKIP (0.118s)
[542.304780] [121/138] (493s left) kms_ccs (pipe-a-bad-rotation-90-4-tiled-dg2-rc-ccs)
[542.639274] Starting subtest: pipe-A-bad-rotation-90-4-tiled-dg2-rc-ccs
[542.643333] Subtest pipe-A-bad-rotation-90-4-tiled-dg2-rc-ccs: SKIP (0.000s)
[543.110727] [122/138] (492s left) kms_big_fb (y-tiled-addfb)
[543.377172] Starting subtest: y-tiled-addfb
[543.379518] Subtest y-tiled-addfb: SUCCESS (0.001s)
[543.493516] [123/138] (492s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-downscaling)
[543.740645] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-downscaling
[543.744372] Starting dynamic subtest: pipe-A-valid-mode
[544.474428] Dynamic subtest pipe-A-valid-mode: SKIP (0.730s)
[544.478587] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-downscaling: SKIP (0.734s)
[544.882731] [124/138] (491s left) kms_frontbuffer_tracking (fbcpsr-2p-indfb-fliptrack-mmap-gtt)
[545.144499] Starting subtest: fbcpsr-2p-indfb-fliptrack-mmap-gtt
[545.148580] Subtest fbcpsr-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[545.248877] [125/138] (490s left) gem_exec_balancer (fairslice)
[545.422002] Starting subtest: fairslice
[545.426405] Subtest fairslice: SKIP (0.000s)
[549.349347] [126/138] (486s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[549.606377] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[549.611181] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[549.700853] [127/138] (486s left) gem_exec_schedule (pi-ringfull)
[550.015944] Starting subtest: pi-ringfull
[550.018404] Starting dynamic subtest: rcs0
[550.109150] Dynamic subtest rcs0: SUCCESS (0.093s)
[550.137151] Starting dynamic subtest: bcs0
[550.203401] Dynamic subtest bcs0: SUCCESS (0.091s)
[550.230978] Starting dynamic subtest: vcs0
[550.296106] Dynamic subtest vcs0: SUCCESS (0.090s)
[550.323132] Starting dynamic subtest: vecs0
[550.392544] Dynamic subtest vecs0: SUCCESS (0.094s)
[550.394706] Subtest pi-ringfull: SUCCESS (0.379s)
[550.603978] [128/138] (485s left) gem_exec_whisper (basic-fds)
[550.774039] Starting subtest: basic-fds
[562.321321] Subtest basic-fds: SUCCESS (11.545s)
[562.534455] [129/138] (473s left) kms_rotation_crc (bad-pixel-format)
[562.781488] Starting subtest: bad-pixel-format
[564.547943] Subtest bad-pixel-format: SUCCESS (1.771s)
[564.984488] [130/138] (471s left) gem_exec_balancer (invalid-balancer)
[565.158842] Starting subtest: invalid-balancer
[568.896612] Subtest invalid-balancer: SUCCESS (3.736s)
[572.833602] [131/138] (463s left) kms_hdr (invalid-hdr)
[573.078894] Starting subtest: invalid-hdr
[575.412336] Starting dynamic subtest: pipe-A-DP-1
[581.974983] Dynamic subtest pipe-A-DP-1: SUCCESS (6.564s)
[581.977402] Subtest invalid-hdr: SUCCESS (8.901s)
[582.354417] [132/138] (454s left) gem_lmem_swapping (parallel-random-engines)
[582.511738] Subtest parallel-random-engines: SKIP (0.000s)
[582.567321] [133/138] (453s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions-varying-s
[582.801355] Starting subtest: short-busy-flip-before-cursor-atomic-transitions-varying-size
[582.805435] Subtest short-busy-flip-before-cursor-atomic-transitions-varying-size: SKIP (0.000s)
[582.899050] [134/138] (453s left) kms_big_fb (4-tiled-32bpp-rotate-90)
[583.163216] Starting subtest: 4-tiled-32bpp-rotate-90
[583.167796] Subtest 4-tiled-32bpp-rotate-90: SKIP (0.000s)
[583.265569] [135/138] (453s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-pgflip-blt)
[583.517644] Starting subtest: psr-2p-primscrn-shrfb-pgflip-blt
[583.521645] Subtest psr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[583.631971] [136/138] (453s left) gem_wait (write-wait)
[583.817238] Starting subtest: write-wait
[583.819657] Starting dynamic subtest: all-engines
[584.867419] Dynamic subtest all-engines: SUCCESS (1.046s)
[584.893968] Starting dynamic subtest: rcs0
[585.906266] Dynamic subtest rcs0: SUCCESS (1.037s)
[585.936520] Starting dynamic subtest: bcs0
[586.943341] Dynamic subtest bcs0: SUCCESS (1.033s)
[586.974454] Starting dynamic subtest: vcs0
[587.974888] Dynamic subtest vcs0: SUCCESS (1.026s)
[588.000759] Starting dynamic subtest: vecs0
[589.008845] Dynamic subtest vecs0: SUCCESS (1.032s)
[589.011191] Subtest write-wait: SUCCESS (5.193s)
[589.265200] [137/138] (447s left) gem_mmap_wc (write-cpu-read-wc-unflushed)
[589.411719] Starting subtest: write-cpu-read-wc-unflushed
[589.689155] Subtest write-cpu-read-wc-unflushed: SUCCESS (0.279s)
[589.781935] [138/138] (447s left) kms_cursor_crc (cursor-rapid-movement-32x10)
[590.029006] Starting subtest: cursor-rapid-movement-32x10
[590.164495] Subtest cursor-rapid-movement-32x10: SKIP (0.132s)
[590.276809] Closing watchdogs
Done.