[56.915919] Initializing watchdogs
[56.916418]   /dev/watchdog0
[56.933721] [001/137] (960s left) v3d/v3d_wait_bo (used-bo-0ns)
[57.212369] Subtest used-bo-0ns: SKIP (0.000s)
[57.280616] [002/137] (960s left) gem_eio (context-create)
[57.524563] Starting subtest: context-create
[57.580516] Subtest context-create: SUCCESS (0.057s)
[57.759237] [003/137] (959s left) gem_mmap_gtt (basic-write-read)
[57.958703] Starting subtest: basic-write-read
[57.992882] Subtest basic-write-read: SUCCESS (0.035s)
[58.109942] [004/137] (959s left) gem_render_copy (y-tiled-mc-ccs-to-yf-tiled-ccs)
[58.330235] Starting subtest: y-tiled-mc-ccs-to-yf-tiled-ccs
[58.332919] Subtest y-tiled-mc-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[58.527805] [005/137] (959s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[58.837977] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt
[58.840663] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[58.926482] [006/137] (958s left) syncobj_basic (bad-handle-to-fd)
[59.127381] Starting subtest: bad-handle-to-fd
[59.128991] Subtest bad-handle-to-fd: SUCCESS (0.000s)
[59.209985] [007/137] (958s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-pgflip-blt)
[59.521868] Starting subtest: fbcpsr-2p-scndscrn-shrfb-pgflip-blt
[59.524485] Subtest fbcpsr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[59.610031] [008/137] (958s left) kms_ccs (pipe-b-ccs-on-another-bo-yf_tiled_ccs)
[60.215141] Starting subtest: pipe-B-ccs-on-another-bo-yf_tiled_ccs
[60.219300] Subtest pipe-B-ccs-on-another-bo-yf_tiled_ccs: SUCCESS (0.001s)
[60.750441] [009/137] (956s left) kms_flip (2x-flip-vs-fences-interruptible)
[61.170866] Starting subtest: 2x-flip-vs-fences-interruptible
[61.178672] Subtest 2x-flip-vs-fences-interruptible: SKIP (0.006s)
[61.307059] [010/137] (956s left) kms_busy (extended-modeset-hang-newfb-with-reset)
[61.721043] Starting subtest: extended-modeset-hang-newfb-with-reset
[61.727579] Starting dynamic subtest: pipe-A
[65.155663] Dynamic subtest pipe-A: SUCCESS (3.428s)
[65.178625] Starting dynamic subtest: pipe-C
[68.447408] Dynamic subtest pipe-C: SUCCESS (3.281s)
[68.449554] Subtest extended-modeset-hang-newfb-with-reset: SUCCESS (6.725s)
[68.800569] [011/137] (948s left) gen9_exec_parse (shadow-peek)
[69.027626] Starting subtest: shadow-peek
[69.033520] Subtest shadow-peek: SUCCESS (0.007s)
[69.248611] [012/137] (948s left) kms_universal_plane (universal-plane-pipe-d-sanity)
[69.545334] Subtest universal-plane-pipe-D-sanity: SKIP (0.000s)
[69.632319] [013/137] (948s left) api_intel_bb (bb-with-allocator)
[69.836443] Starting subtest: bb-with-allocator
[69.846321] Subtest bb-with-allocator: SUCCESS (0.011s)
[69.964429] [014/137] (947s left) gem_basic (multigpu-create-close)
[70.164774] Starting subtest: multigpu-create-close
[70.167329] Subtest multigpu-create-close: SKIP (0.000s)
[70.247904] [015/137] (947s left) gem_exec_schedule (reorder-wide)
[70.610246] Starting subtest: reorder-wide
[70.611782] Starting dynamic subtest: rcs0
[70.852804] Dynamic subtest rcs0: SUCCESS (0.241s)
[70.872090] Starting dynamic subtest: bcs0
[71.193540] Dynamic subtest bcs0: SUCCESS (0.341s)
[71.213171] Starting dynamic subtest: vcs0
[71.360826] Dynamic subtest vcs0: SUCCESS (0.167s)
[71.378831] Starting dynamic subtest: vecs0
[71.526778] Dynamic subtest vecs0: SUCCESS (0.165s)
[71.528564] Subtest reorder-wide: SUCCESS (0.916s)
[71.732920] [016/137] (946s left) kms_cursor_edge_walk (64x64-right-edge)
[72.030527] Starting subtest: 64x64-right-edge
[72.036441] Starting dynamic subtest: pipe-A-DP-1
[75.666215] Dynamic subtest pipe-A-DP-1: SUCCESS (3.632s)
[75.682224] Starting dynamic subtest: pipe-C-DP-1
[79.134231] Dynamic subtest pipe-C-DP-1: SUCCESS (3.464s)
[79.135959] Subtest 64x64-right-edge: SUCCESS (7.104s)
[79.443928] [017/137] (938s left) gem_exec_schedule (out-order)
[79.680542] Starting subtest: out-order
[79.682101] Starting dynamic subtest: rcs0
[79.816820] Dynamic subtest rcs0: SUCCESS (0.134s)
[79.837394] Starting dynamic subtest: bcs0
[79.960389] Dynamic subtest bcs0: SUCCESS (0.142s)
[79.980243] Starting dynamic subtest: vcs0
[80.085791] Dynamic subtest vcs0: SUCCESS (0.125s)
[80.105542] Starting dynamic subtest: vecs0
[80.213433] Dynamic subtest vecs0: SUCCESS (0.129s)
[80.214747] Subtest out-order: SUCCESS (0.531s)
[80.559711] [018/137] (937s left) gen7_exec_parse (basic-allocation)
[80.768203] Subtest basic-allocation: SKIP (0.000s)
[80.825302] [019/137] (937s left) kms_big_fb (4-tiled-addfb-size-overflow)
[81.152617] Starting subtest: 4-tiled-addfb-size-overflow
[81.155908] Subtest 4-tiled-addfb-size-overflow: SKIP (0.000s)
[81.241291] [020/137] (936s left) kms_flip (flip-vs-suspend-interruptible)
[81.531512] Starting subtest: flip-vs-suspend-interruptible
[81.537073] Starting dynamic subtest: A-DP1
[133.714625] Dynamic subtest A-DP1: SUCCESS (4.941s)
[133.740085] Starting dynamic subtest: B-DP1
[186.684608] Dynamic subtest B-DP1: SUCCESS (4.804s)
[186.702989] Starting dynamic subtest: C-DP1
[238.696990] Dynamic subtest C-DP1: SUCCESS (4.751s)
[238.698526] Subtest flip-vs-suspend-interruptible: SUCCESS (14.504s)
[243.091441] [021/137] (921s left) gem_ctx_param (invalid-param-set)
[243.288383] Starting subtest: invalid-param-set
[243.289865] Subtest invalid-param-set: SUCCESS (0.000s)
[243.367168] [022/137] (921s left) kms_lease (master-vs-lease)
[243.661207] Starting subtest: master-vs-lease
[243.664044] Subtest master-vs-lease: SUCCESS (0.003s)
[243.750327] [023/137] (921s left) i915_pm_dc (dc5-psr)
[245.051222] Starting subtest: dc5-psr
[245.055747] Subtest dc5-psr: SKIP (0.000s)
[245.219099] [024/137] (919s left) perf_pmu (semaphore-busy)
[245.419024] Starting subtest: semaphore-busy
[245.421271] Starting dynamic subtest: rcs0
[246.985446] Dynamic subtest rcs0: SUCCESS (1.564s)
[247.005638] Starting dynamic subtest: bcs0
[248.545634] Dynamic subtest bcs0: SUCCESS (1.559s)
[248.566496] Starting dynamic subtest: vcs0
[250.103492] Dynamic subtest vcs0: SUCCESS (1.558s)
[250.123487] Starting dynamic subtest: vecs0
[251.659200] Dynamic subtest vecs0: SUCCESS (1.554s)
[251.660890] Subtest semaphore-busy: SUCCESS (6.237s)
[251.769032] [025/137] (913s left) kms_chamelium_hpd (dp-hpd-storm-disable)
[252.160992] Subtest dp-hpd-storm-disable: SKIP (0.000s)
[252.455688] [026/137] (912s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[252.771546] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[254.010529] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (1.238s)
[254.335327] [027/137] (910s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[254.656595] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[254.663830] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.006s)
[254.750813] [028/137] (910s left) v3d/v3d_submit_cl (bad-perfmon)
[255.011765] Subtest bad-perfmon: SKIP (0.000s)
[255.083388] [029/137] (910s left) kms_cursor_legacy (cursorb-vs-flipb-atomic)
[255.395448] Starting subtest: cursorB-vs-flipB-atomic
[255.398432] Subtest cursorB-vs-flipB-atomic: SKIP (0.003s)
[255.484395] [030/137] (909s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0)
[255.809845] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0
[255.813133] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[255.916996] [031/137] (909s left) kms_color (deep-color)
[256.220193] Starting subtest: deep-color
[256.222795] Subtest deep-color: SKIP (0.000s)
[256.304558] [032/137] (908s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt)
[256.615214] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt
[256.617968] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[256.700391] [033/137] (908s left) kms_psr (primary_page_flip)
[256.899180] Subtest primary_page_flip: SKIP (0.000s)
[256.949940] [034/137] (908s left) sysfs_preempt_timeout (timeout)
[257.155188] Starting subtest: timeout
[257.159531] Starting dynamic subtest: vecs0
[258.063906] Dynamic subtest vecs0: SUCCESS (0.907s)
[258.090430] Starting dynamic subtest: vcs0
[258.938880] Dynamic subtest vcs0: SUCCESS (0.874s)
[258.960060] Starting dynamic subtest: bcs0
[259.812642] Dynamic subtest bcs0: SUCCESS (0.872s)
[259.834381] Starting dynamic subtest: rcs0
[260.684572] Dynamic subtest rcs0: SUCCESS (0.871s)
[260.686109] Subtest timeout: SUCCESS (3.529s)
[260.767077] [035/137] (904s left) gem_exec_fence (syncobj-timeline-repeat)
[261.555067] Starting subtest: syncobj-timeline-repeat
[262.693383] Subtest syncobj-timeline-repeat: SUCCESS (1.138s)
[262.901453] [036/137] (902s left) kms_ccs (pipe-b-crc-primary-rotation-180-y_tiled_gen12_rc_ccs)
[263.423853] Starting subtest: pipe-B-crc-primary-rotation-180-y_tiled_gen12_rc_ccs
[263.426494] Subtest pipe-B-crc-primary-rotation-180-y_tiled_gen12_rc_ccs: SKIP (0.001s)
[263.927787] [037/137] (901s left) gem_softpin (reverse)
[264.139222] Starting subtest: reverse
[264.159769] Subtest reverse: SUCCESS (0.020s)
[264.276969] [038/137] (901s left) gem_fence_thrash (bo-write-verify-threaded-x)
[264.472636] Starting subtest: bo-write-verify-threaded-x
[267.161789] Subtest bo-write-verify-threaded-x: SUCCESS (2.688s)
[267.263291] [039/137] (898s left) dumb_buffer (map-invalid-size)
[267.462685] Starting subtest: map-invalid-size
[267.464228] Subtest map-invalid-size: SUCCESS (0.000s)
[267.560231] [040/137] (897s left) api_intel_bb (simple-bb)
[267.762122] Starting subtest: simple-bb
[267.767247] Subtest simple-bb: SUCCESS (0.007s)
[267.876680] [041/137] (897s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[268.173248] Starting subtest: read-crc-frame-sequence
[268.176688] Starting dynamic subtest: pipe-A-DP-1
[268.722829] Dynamic subtest pipe-A-DP-1: SUCCESS (0.546s)
[268.744402] Starting dynamic subtest: pipe-B-DP-1
[269.553543] Dynamic subtest pipe-B-DP-1: SUCCESS (0.827s)
[269.578608] Starting dynamic subtest: pipe-C-DP-1
[270.581324] Dynamic subtest pipe-C-DP-1: SUCCESS (1.022s)
[270.582895] Subtest read-crc-frame-sequence: SUCCESS (2.409s)
[270.895617] [042/137] (894s left) kms_hdmi_inject (inject-audio)
[271.095097] Subtest inject-audio: SKIP (0.000s)
[271.145126] [043/137] (894s left) kms_frontbuffer_tracking (fbc-stridechange)
[271.453857] Starting subtest: fbc-stridechange
[273.072812] Subtest fbc-stridechange: SUCCESS (1.619s)
[273.213618] [044/137] (892s left) kms_content_protection (legacy)
[273.542413] Starting subtest: legacy
[273.742148] Starting dynamic subtest: pipe-A-DP-1
[394.255299] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[394.995898] Closing watchdogs
[395.003262] Initializing watchdogs
[395.003444]   /dev/watchdog0
[395.028023] [045/137] (770s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[395.335626] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-gtt
[395.338369] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[395.432004] [046/137] (770s left) kms_ccs (pipe-a-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc)
[395.845861] Starting subtest: pipe-A-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc
[395.848583] Subtest pipe-A-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc: SKIP (0.001s)
[396.458199] [047/137] (769s left) kms_fence_pin_leak
[400.521352] [048/137] (765s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[400.740516] Starting subtest: yf-tiled-to-vebox-yf-tiled
[400.743192] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[400.938109] [049/137] (764s left) gem_exec_fence (basic-await)
[401.292294] Starting subtest: basic-await
[401.293788] Starting dynamic subtest: rcs0
[401.422310] Dynamic subtest rcs0: SUCCESS (0.129s)
[401.446077] Starting dynamic subtest: bcs0
[401.550177] Dynamic subtest bcs0: SUCCESS (0.125s)
[401.574159] Starting dynamic subtest: vcs0
[401.677211] Dynamic subtest vcs0: SUCCESS (0.127s)
[401.700791] Starting dynamic subtest: vecs0
[401.801661] Dynamic subtest vecs0: SUCCESS (0.124s)
[401.804310] Subtest basic-await: SUCCESS (0.510s)
[402.455976] [050/137] (763s left) vc4/vc4_perfmon (destroy-valid-perfmon)
[402.714268] Subtest destroy-valid-perfmon: SKIP (0.000s)
[402.787768] [051/137] (763s left) kms_rotation_crc (primary-y-tiled-reflect-x-180)
[403.090105] Starting subtest: primary-y-tiled-reflect-x-180
[403.092940] Subtest primary-y-tiled-reflect-x-180: SKIP (0.003s)
[403.171607] [052/137] (762s left) gem_mmap_gtt (flink-race)
[403.373301] Starting subtest: flink-race
[423.483471] Subtest flink-race: SUCCESS (20.110s)
[423.588486] [053/137] (742s left) gem_lmem_swapping (parallel-multi)
[423.799817] Subtest parallel-multi: SKIP (0.000s)
[423.855770] [054/137] (742s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[424.154298] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu
[426.379571] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (2.228s)
[426.521618] [055/137] (739s left) kms_invalid_mode (bad-hsync-start)
[426.820583] Starting subtest: bad-hsync-start
[426.822634] Starting dynamic subtest: DP-1-pipe-A
[426.924466] Dynamic subtest DP-1-pipe-A: SUCCESS (0.102s)
[426.955297] Starting dynamic subtest: DP-1-pipe-B
[426.957806] Dynamic subtest DP-1-pipe-B: SUCCESS (0.014s)
[426.984043] Starting dynamic subtest: DP-1-pipe-C
[426.986576] Dynamic subtest DP-1-pipe-C: SUCCESS (0.014s)
[426.989126] Subtest bad-hsync-start: SUCCESS (0.145s)
[427.296815] [056/137] (738s left) kms_cursor_crc (cursor-sliding-512x170)
[427.607899] Starting subtest: cursor-sliding-512x170
[427.613136] Subtest cursor-sliding-512x170: SKIP (0.003s)
[427.712471] [057/137] (738s left) kms_writeback (writeback-pixel-formats)
[428.106379] Subtest writeback-pixel-formats: SKIP (0.000s)
[428.161506] [058/137] (738s left) gem_softpin (evict-active)
[428.366368] Starting subtest: evict-active
[428.393073] Subtest evict-active: SUCCESS (0.024s)
[428.512187] [059/137] (737s left) kms_plane_multiple (tiling-4)
[428.817899] Starting subtest: tiling-4
[428.820530] Subtest tiling-4: SKIP (0.000s)
[428.912682] [060/137] (737s left) kms_setmode (basic)
[429.117182] Starting subtest: basic
[429.320879] Starting dynamic subtest: pipe-A-DP-1
[431.632545] Dynamic subtest pipe-A-DP-1: SUCCESS (2.308s)
[431.793328] Starting dynamic subtest: pipe-B-DP-1
[434.069979] Dynamic subtest pipe-B-DP-1: SUCCESS (2.293s)
[434.171035] Subtest basic: SUCCESS (5.049s)
[434.496267] [061/137] (731s left) gem_ctx_persistence (heartbeat-stop)
[434.705584] Starting subtest: heartbeat-stop
[434.863502] Subtest heartbeat-stop: SUCCESS (0.158s)
[434.961818] [062/137] (731s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-blt)
[435.268885] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-blt
[435.271774] Subtest psr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[435.378841] [063/137] (731s left) kms_vblank (pipe-c-query-idle-hang)
[435.684882] Starting subtest: pipe-C-query-idle-hang
[443.176116] Subtest pipe-C-query-idle-hang: SUCCESS (7.494s)
[443.524840] [064/137] (723s left) kms_ccs (pipe-b-random-ccs-data-y_tiled_ccs)
[444.058400] Starting subtest: pipe-B-random-ccs-data-y_tiled_ccs
[444.497561] Subtest pipe-B-random-ccs-data-y_tiled_ccs: SUCCESS (0.438s)
[445.052839] [065/137] (721s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-upscaling)
[445.360429] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-upscaling
[445.361874] Starting dynamic subtest: pipe-A-valid-mode
[445.460584] Dynamic subtest pipe-A-valid-mode: SKIP (0.100s)
[445.464372] Subtest flip-64bpp-yftile-to-16bpp-yftile-upscaling: SKIP (0.103s)
[445.781572] [066/137] (720s left) kms_ccs (pipe-d-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs)
[446.507240] Subtest pipe-D-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[446.831503] [067/137] (719s left) gen3_render_linear_blits
[447.079695] [068/137] (719s left) kms_vblank (pipe-d-ts-continuation-modeset-rpm)
[447.379605] Subtest pipe-D-ts-continuation-modeset-rpm: SKIP (0.000s)
[447.464553] [069/137] (719s left) syncobj_wait (wait-all-for-submit-delayed-submit)
[447.670099] Starting subtest: wait-all-for-submit-delayed-submit
[447.772554] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.103s)
[447.865482] [070/137] (718s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[448.175820] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[448.178757] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[448.281130] [071/137] (718s left) kms_ccs (pipe-c-ccs-on-another-bo-y_tiled_ccs)
[448.992282] Starting subtest: pipe-C-ccs-on-another-bo-y_tiled_ccs
[448.995429] Subtest pipe-C-ccs-on-another-bo-y_tiled_ccs: SKIP (0.000s)
[449.344818] [072/137] (717s left) gem_exec_balancer (nop)
[449.572956] Starting subtest: nop
[489.380547] Subtest nop: SUCCESS (39.807s)
[493.328882] [073/137] (673s left) kms_frontbuffer_tracking (fbc-tiling-y)
[493.632593] Starting subtest: fbc-tiling-y
[495.869706] Subtest fbc-tiling-y: SUCCESS (2.239s)
[495.995971] [074/137] (670s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[496.296500] Starting subtest: basic-flip-after-cursor-varying-size
[497.369111] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.073s)
[497.715185] [075/137] (669s left) gem_exec_reloc (basic-write-wc-noreloc)
[497.928733] Starting subtest: basic-write-wc-noreloc
[497.939319] Subtest basic-write-wc-noreloc: SUCCESS (0.011s)
[498.130286] [076/137] (668s left) kms_flip (2x-nonexisting-fb-interruptible)
[498.426258] Starting subtest: 2x-nonexisting-fb-interruptible
[498.431491] Subtest 2x-nonexisting-fb-interruptible: SKIP (0.003s)
[498.530726] [077/137] (668s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[498.840183] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-cpu
[498.843181] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[498.947172] [078/137] (668s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-blt)
[499.256498] Starting subtest: psr-1p-primscrn-cur-indfb-draw-blt
[499.259071] Subtest psr-1p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[499.347207] [079/137] (667s left) v3d/v3d_get_param (get-bad-flags)
[499.605409] Subtest get-bad-flags: SKIP (0.000s)
[499.680039] [080/137] (667s left) kms_ccs (pipe-d-random-ccs-data-y_tiled_gen12_rc_ccs_cc)
[500.389103] Subtest pipe-D-random-ccs-data-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[500.708483] [081/137] (666s left) gem_pread (uncached)
[500.912308] Starting subtest: uncached
[505.535727] Subtest uncached: SUCCESS (4.622s)
[505.625601] [082/137] (661s left) gem_exec_schedule (implicit-boths)
[505.855213] Starting subtest: implicit-boths
[505.856878] Starting dynamic subtest: rcs0
[506.006357] Dynamic subtest rcs0: SUCCESS (0.148s)
[506.031986] Starting dynamic subtest: bcs0
[506.148337] Dynamic subtest bcs0: SUCCESS (0.140s)
[506.172632] Starting dynamic subtest: vcs0
[506.280424] Dynamic subtest vcs0: SUCCESS (0.128s)
[506.305766] Starting dynamic subtest: vecs0
[506.408205] Dynamic subtest vecs0: SUCCESS (0.129s)
[506.410022] Subtest implicit-boths: SUCCESS (0.552s)
[506.776155] [083/137] (660s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-pixel-formats)
[507.074431] Starting subtest: plane-scaler-with-clipping-clamping-pixel-formats
[507.075819] Starting dynamic subtest: pipe-A-DP-1
[509.365493] Dynamic subtest pipe-A-DP-1: SUCCESS (2.290s)
[509.394254] Starting dynamic subtest: pipe-B-DP-1
[511.918136] Dynamic subtest pipe-B-DP-1: SUCCESS (2.548s)
[511.948364] Starting dynamic subtest: pipe-C-DP-1
[512.274320] Dynamic subtest pipe-C-DP-1: SUCCESS (0.352s)
[512.275716] Subtest plane-scaler-with-clipping-clamping-pixel-formats: SUCCESS (5.203s)
[512.663747] [084/137] (654s left) gem_tiled_partial_pwrite_pread (reads)
[512.877605] Starting subtest: reads
[514.301741] Subtest reads: SUCCESS (1.424s)
[514.411501] [085/137] (652s left) gem_render_copy_redux (flink-interruptible)
[514.641231] Starting subtest: flink-interruptible
[519.336430] Subtest flink-interruptible: SUCCESS (4.695s)
[519.447271] [086/137] (648s left) testdisplay
[542.988265] [087/137] (624s left) kms_draw_crc (fill-fb)
[543.302181] Starting subtest: fill-fb
[543.725770] Subtest fill-fb: SUCCESS (0.426s)
[543.837183] [088/137] (623s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[544.143765] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[544.146660] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[544.253306] [089/137] (623s left) syncobj_timeline (single-wait-signaled)
[544.454990] Starting subtest: single-wait-signaled
[544.456543] Subtest single-wait-signaled: SUCCESS (0.001s)
[544.553594] [090/137] (623s left) prime_vgem (sync)
[544.784253] Starting subtest: sync
[544.785993] Starting dynamic subtest: rcs0
[544.820181] Dynamic subtest rcs0: SUCCESS (0.035s)
[544.843367] Starting dynamic subtest: vcs0
[544.844688] Dynamic subtest vcs0: SUCCESS (0.018s)
[544.868301] Starting dynamic subtest: vecs0
[544.869616] Dynamic subtest vecs0: SUCCESS (0.017s)
[544.870869] Subtest sync: SUCCESS (0.075s)
[544.970165] [091/137] (622s left) kms_cursor_crc (cursor-random-64x64)
[545.275392] Starting subtest: cursor-random-64x64
[545.288631] Starting dynamic subtest: pipe-A-DP-1
[549.074524] Dynamic subtest pipe-A-DP-1: SUCCESS (3.786s)
[549.169006] Starting dynamic subtest: pipe-C-DP-1
[553.307595] Dynamic subtest pipe-C-DP-1: SUCCESS (4.167s)
[553.308965] Subtest cursor-random-64x64: SUCCESS (8.030s)
[553.666736] [092/137] (614s left) kms_ccs (pipe-d-bad-rotation-90-y_tiled_gen12_rc_ccs)
[554.391671] Subtest pipe-D-bad-rotation-90-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[554.708293] [093/137] (613s left) kms_getfb (getfb-handle-valid)
[554.909238] Starting subtest: getfb-handle-valid
[554.910768] Subtest getfb-handle-valid: SUCCESS (0.000s)
[555.007296] [094/137] (612s left) syncobj_timeline (multi-wait-for-submit-available-signaled)
[555.217363] Starting subtest: multi-wait-for-submit-available-signaled
[555.219093] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.002s)
[555.307537] [095/137] (612s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-mmap-cpu)
[555.618268] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-mmap-cpu
[557.434126] Subtest fbc-1p-offscren-pri-shrfb-draw-mmap-cpu: SUCCESS (1.818s)
[557.574176] [096/137] (610s left) kms_rotation_crc (primary-4-tiled-reflect-x-0)
[557.870243] Starting subtest: primary-4-tiled-reflect-x-0
[557.872972] Subtest primary-4-tiled-reflect-x-0: SKIP (0.003s)
[557.957738] [097/137] (609s left) kms_cursor_legacy (flip-vs-cursor-legacy)
[558.261861] Starting subtest: flip-vs-cursor-legacy
[573.827248] Subtest flip-vs-cursor-legacy: SUCCESS (15.569s)
[574.166080] [098/137] (593s left) gem_eio (wait-1us)
[574.390455] Starting subtest: wait-1us
[574.476587] Subtest wait-1us: SUCCESS (0.087s)
[574.647007] [099/137] (593s left) kms_addfb_basic (legacy-format)
[574.850908] Starting subtest: legacy-format
[575.010111] Subtest legacy-format: SUCCESS (0.120s)
[575.348620] [100/137] (592s left) kms_prop_blob (invalid-get-prop-any)
[575.557261] Starting subtest: invalid-get-prop-any
[575.558656] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[575.646388] [101/137] (592s left) gem_ctx_param (non-root-set)
[575.846142] Starting subtest: non-root-set
[575.878395] Subtest non-root-set: SUCCESS (0.033s)
[575.963909] [102/137] (592s left) gem_exec_reloc (basic-write-gtt)
[576.176126] Starting subtest: basic-write-gtt
[576.195429] Subtest basic-write-gtt: SUCCESS (0.019s)
[576.397572] [103/137] (591s left) kms_flip (wf_vblank-ts-check)
[576.685788] Starting subtest: wf_vblank-ts-check
[576.687437] Starting dynamic subtest: A-DP1
[587.503142] Dynamic subtest A-DP1: SUCCESS (10.816s)
[587.526017] Starting dynamic subtest: B-DP1
[598.263701] Dynamic subtest B-DP1: SUCCESS (10.760s)
[598.291371] Starting dynamic subtest: C-DP1
[609.178807] Dynamic subtest C-DP1: SUCCESS (10.913s)
[609.180279] Subtest wf_vblank-ts-check: SUCCESS (32.499s)
[609.604692] [104/137] (558s left) kms_chamelium_color (ctm-max)
[609.910618] Subtest ctm-max: SKIP (0.000s)
[609.969461] [105/137] (558s left) gem_exec_whisper (basic-contexts-all)
[610.195110] Starting subtest: basic-contexts-all
[623.156753] Subtest basic-contexts-all: SUCCESS (12.960s)
[623.371309] [106/137] (544s left) gem_exec_nop (basic-series)
[623.597755] Starting subtest: basic-series
[626.660179] Subtest basic-series: SUCCESS (3.061s)
[626.886641] [107/137] (541s left) gem_mmap_gtt (isolation)
[627.092558] Starting subtest: isolation
[627.097089] Subtest isolation: SUCCESS (0.003s)
[627.202775] [108/137] (541s left) gem_ctx_engines (none)
[627.428495] Starting subtest: none
[627.432550] Subtest none: SUCCESS (0.003s)
[627.637504] [109/137] (540s left) kms_ccs (pipe-c-crc-sprite-planes-basic-y_tiled_ccs)
[628.335544] Starting subtest: pipe-C-crc-sprite-planes-basic-y_tiled_ccs
[628.340979] Subtest pipe-C-crc-sprite-planes-basic-y_tiled_ccs: SKIP (0.004s)
[628.673686] [110/137] (539s left) kms_plane_scaling (2x-scaler-multi-pipe)
[628.976333] Starting subtest: 2x-scaler-multi-pipe
[628.981870] Subtest 2x-scaler-multi-pipe: SKIP (0.005s)
[629.073278] [111/137] (539s left) i915_pm_rpm (sysfs-read)
[630.863692] Starting subtest: sysfs-read
[631.412433] Subtest sysfs-read: SUCCESS (0.547s)
[631.908705] [112/137] (536s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-blt)
[632.221845] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-blt
[632.224933] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[632.324139] [113/137] (536s left) kms_vblank (pipe-a-wait-forked)
[632.618335] Starting subtest: pipe-A-wait-forked
[634.964731] Subtest pipe-A-wait-forked: SUCCESS (2.345s)
[635.304600] [114/137] (533s left) gem_exec_flush (basic-uc-rw-default)
[635.528594] Starting subtest: basic-uc-rw-default
[641.024390] Subtest basic-uc-rw-default: SUCCESS (5.496s)
[641.238346] [115/137] (527s left) drm_fdinfo (most-busy-check-all)
[641.448676] Starting subtest: most-busy-check-all
[641.450367] Starting dynamic subtest: rcs0
[641.977655] Dynamic subtest rcs0: SUCCESS (0.528s)
[642.004653] Starting dynamic subtest: bcs0
[642.501638] Dynamic subtest bcs0: SUCCESS (0.523s)
[642.527991] Starting dynamic subtest: vcs0
[643.024403] Dynamic subtest vcs0: SUCCESS (0.522s)
[643.049651] Starting dynamic subtest: vecs0
[643.548412] Dynamic subtest vecs0: SUCCESS (0.523s)
[643.549675] Subtest most-busy-check-all: SUCCESS (2.100s)
[643.636853] [116/137] (525s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted-submitted)
[643.838589] Starting subtest: multi-wait-all-for-submit-unsubmitted-submitted
[643.947698] Subtest multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.107s)
[644.037159] [117/137] (524s left) syncobj_timeline (invalid-transfer-illegal-handle)
[644.239937] Starting subtest: invalid-transfer-illegal-handle
[644.241358] Subtest invalid-transfer-illegal-handle: SUCCESS (0.000s)
[644.337549] [118/137] (524s left) syncobj_wait (multi-wait-submitted)
[644.542863] Starting subtest: multi-wait-submitted
[644.651644] Subtest multi-wait-submitted: SUCCESS (0.107s)
[644.737023] [119/137] (524s left) gem_ctx_persistence (legacy-engines-persistence)
[644.947451] Starting subtest: legacy-engines-persistence
[644.949163] Starting dynamic subtest: render
[645.741995] Dynamic subtest render: SUCCESS (0.792s)
[645.765657] Starting dynamic subtest: bsd
[646.507904] Dynamic subtest bsd: SUCCESS (0.763s)
[646.531390] Starting dynamic subtest: blt
[647.283584] Dynamic subtest blt: SUCCESS (0.773s)
[647.306565] Starting dynamic subtest: vebox
[648.045621] Dynamic subtest vebox: SUCCESS (0.759s)
[648.047066] Subtest legacy-engines-persistence: SUCCESS (3.098s)
[648.137764] [120/137] (520s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-wc)
[648.448160] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-wc
[648.451093] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[648.553860] [121/137] (520s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-onoff)
[648.867537] Starting subtest: psr-2p-scndscrn-cur-indfb-onoff
[648.870339] Subtest psr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[648.970670] [122/137] (520s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-render)
[649.282581] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-render
[649.285322] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[649.387663] [123/137] (519s left) kms_big_fb (yf-tiled-16bpp-rotate-0)
[649.704147] Starting subtest: yf-tiled-16bpp-rotate-0
[653.311520] Subtest yf-tiled-16bpp-rotate-0: SUCCESS (3.607s)
[653.638484] [124/137] (515s left) gem_exec_reloc (basic-wc-noreloc)
[653.846311] Starting subtest: basic-wc-noreloc
[653.855874] Subtest basic-wc-noreloc: SUCCESS (0.010s)
[654.187436] [125/137] (514s left) kms_big_fb (4-tiled-16bpp-rotate-90)
[654.509367] Starting subtest: 4-tiled-16bpp-rotate-90
[654.512414] Subtest 4-tiled-16bpp-rotate-90: SKIP (0.000s)
[654.604843] [126/137] (514s left) vgem_basic (busy-fence)
[654.810086] Starting subtest: busy-fence
[654.811707] Subtest busy-fence: SUCCESS (0.000s)
[654.887428] [127/137] (514s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[655.200009] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[657.310079] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (2.113s)
[657.453534] [128/137] (511s left) kms_big_fb (x-tiled-16bpp-rotate-270)
[657.773147] Starting subtest: x-tiled-16bpp-rotate-270
[657.843176] Subtest x-tiled-16bpp-rotate-270: SKIP (0.070s)
[657.954077] [129/137] (511s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted)
[658.157301] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[658.159067] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.001s)
[658.253817] [130/137] (511s left) kms_flip (flip-vs-expired-vblank)
[658.540617] Starting subtest: flip-vs-expired-vblank
[658.542252] Starting dynamic subtest: A-DP1
[669.058343] Dynamic subtest A-DP1: SUCCESS (10.517s)
[669.083569] Starting dynamic subtest: B-DP1
[679.517467] Dynamic subtest B-DP1: SUCCESS (10.457s)
[679.541728] Starting dynamic subtest: C-DP1
[689.979800] Dynamic subtest C-DP1: SUCCESS (10.461s)
[689.981144] Subtest flip-vs-expired-vblank: SUCCESS (31.444s)
[690.398315] [131/137] (478s left) gem_pxp (reject-modify-context-protection-off-2)
[693.125131] Subtest reject-modify-context-protection-off-2: SKIP (0.000s)
[693.230775] [132/137] (476s left) kms_ccs (pipe-a-random-ccs-data-yf_tiled_ccs)
[693.655488] Starting subtest: pipe-A-random-ccs-data-yf_tiled_ccs
[694.093316] Subtest pipe-A-random-ccs-data-yf_tiled_ccs: SUCCESS (0.438s)
[694.809426] [133/137] (474s left) kms_frontbuffer_tracking (psr-slowdraw)
[695.115403] Starting subtest: psr-slowdraw
[695.118183] Subtest psr-slowdraw: SKIP (0.000s)
[695.225880] [134/137] (474s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-25)
[695.527199] Starting subtest: planes-upscale-20x20-downscale-factor-0-25
[695.528632] Starting dynamic subtest: pipe-A-DP-1
[695.557223] Dynamic subtest pipe-A-DP-1: SKIP (0.027s)
[695.580279] Starting dynamic subtest: pipe-B-DP-1
[695.635584] Dynamic subtest pipe-B-DP-1: SKIP (0.073s)
[695.663301] Starting dynamic subtest: pipe-C-DP-1
[695.709944] Dynamic subtest pipe-C-DP-1: SKIP (0.072s)
[695.712641] Subtest planes-upscale-20x20-downscale-factor-0-25: SKIP (0.184s)
[695.811281] [135/137] (473s left) i915_pm_rc6_residency (rc6-accuracy)
[696.181219] Starting subtest: rc6-accuracy
[699.184210] Subtest rc6-accuracy: SUCCESS (3.001s)
[699.275827] [136/137] (470s left) i915_pm_rps (waitboost)
[699.486538] Starting subtest: waitboost
[702.058942] Subtest waitboost: SUCCESS (2.573s)
[702.161749] [137/137] (467s left) gem_ctx_param (invalid-param-get)
[702.362625] Starting subtest: invalid-param-get
[702.364077] Subtest invalid-param-get: SUCCESS (0.000s)
[702.455118] Closing watchdogs
Done.