[43.380309] Initializing watchdogs
[43.380552]   /dev/watchdog0
[43.418059] [001/141] (960s left) kms_cursor_crc (cursor-sliding-64x64)
[43.872802] Starting subtest: cursor-sliding-64x64
[43.916214] Starting dynamic subtest: pipe-A-DP-1
[47.883634] Dynamic subtest pipe-A-DP-1: SUCCESS (3.968s)
[47.974541] Starting dynamic subtest: pipe-C-DP-1
[51.965759] Dynamic subtest pipe-C-DP-1: SUCCESS (4.019s)
[51.984717] Subtest cursor-sliding-64x64: SUCCESS (8.092s)
[52.358493] [002/141] (951s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff)
[52.811075] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff
[52.853046] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[52.950597] [003/141] (951s left) gem_eio (throttle)
[53.295735] Starting subtest: throttle
[53.343831] Subtest throttle: SUCCESS (0.063s)
[53.595394] [004/141] (950s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[54.033554] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu
[54.073926] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[54.177842] [005/141] (950s left) i915_pm_rpm (dpms-lpsp)
[56.135389] Starting subtest: dpms-lpsp
[56.180522] Subtest dpms-lpsp: SKIP (0.000s)
[56.673105] [006/141] (947s left) sysfs_heartbeat_interval (nopreempt)
[56.970739] Starting subtest: nopreempt
[56.992866] Starting dynamic subtest: vecs0
[60.500177] Dynamic subtest vecs0: SUCCESS (3.511s)
[60.533424] Starting dynamic subtest: vcs0
[63.979311] Dynamic subtest vcs0: SUCCESS (3.488s)
[64.013707] Starting dynamic subtest: bcs0
[67.640318] Dynamic subtest bcs0: SUCCESS (3.495s)
[67.677284] Starting dynamic subtest: rcs0
[71.151713] Dynamic subtest rcs0: SUCCESS (3.501s)
[71.170500] Subtest nopreempt: SUCCESS (13.999s)
[71.278479] [007/141] (933s left) kms_cursor_legacy (short-flip-after-cursor-toggle)
[71.645747] Starting subtest: short-flip-after-cursor-toggle
[72.728900] Subtest short-flip-after-cursor-toggle: SUCCESS (1.079s)
[73.084064] [008/141] (931s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180)
[73.515859] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180
[74.824322] Subtest y-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (1.357s)
[74.994423] [009/141] (929s left) kms_cursor_crc (cursor-sliding-32x32)
[75.380514] Starting subtest: cursor-sliding-32x32
[75.526177] Subtest cursor-sliding-32x32: SKIP (0.118s)
[75.640207] [010/141] (929s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted)
[75.946487] Starting subtest: multi-wait-all-for-submit-unsubmitted
[76.017316] Subtest multi-wait-all-for-submit-unsubmitted: SUCCESS (0.104s)
[76.156308] [011/141] (928s left) gem_userptr_blits (sync-unmap)
[76.533912] Starting subtest: sync-unmap
[76.552784] Subtest sync-unmap: SUCCESS (0.048s)
[76.690318] [012/141] (928s left) gem_exec_suspend (basic-s3)
[76.985469] Starting subtest: basic-S3
[77.006322] Starting dynamic subtest: smem
[97.651630] Dynamic subtest smem: SUCCESS (5.897s)
[97.671551] Subtest basic-S3: SUCCESS (5.897s)
[103.885943] [013/141] (922s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[104.325629] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[104.366690] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[104.479587] [014/141] (921s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[104.837878] Starting subtest: basic-flip-after-cursor-varying-size
[105.923872] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.076s)
[106.276336] [015/141] (919s left) kms_psr (psr2_primary_mmap_cpu)
[106.596938] Subtest psr2_primary_mmap_cpu: SKIP (0.000s)
[106.703991] [016/141] (919s left) gem_exec_fence (keep-in-fence)
[107.128495] Starting subtest: keep-in-fence
[107.149884] Starting dynamic subtest: rcs0
[107.168925] Dynamic subtest rcs0: SUCCESS (0.037s)
[107.205061] Starting dynamic subtest: bcs0
[107.308432] Dynamic subtest bcs0: SUCCESS (0.142s)
[107.348713] Starting dynamic subtest: vcs0
[107.369520] Dynamic subtest vcs0: SUCCESS (0.040s)
[107.407839] Starting dynamic subtest: vecs0
[107.426944] Dynamic subtest vecs0: SUCCESS (0.042s)
[107.446042] Subtest keep-in-fence: SUCCESS (0.263s)
[108.194061] [017/141] (918s left) sw_sync (alloc_merge_fence)
[108.473143] Starting subtest: alloc_merge_fence
[108.495085] Subtest alloc_merge_fence: SUCCESS (0.001s)
[108.583253] [018/141] (917s left) gem_create (create-ext-set-pat)
[108.975337] Starting subtest: create-ext-set-pat
[109.013519] Subtest create-ext-set-pat: SKIP (0.000s)
[109.100995] [019/141] (917s left) gem_ctx_param (root-set)
[109.399094] Starting subtest: root-set
[109.418125] Subtest root-set: SUCCESS (0.000s)
[109.511201] [020/141] (917s left) kms_cursor_crc (cursor-onscreen-128x128)
[109.891034] Starting subtest: cursor-onscreen-128x128
[109.916680] Starting dynamic subtest: pipe-A-DP-1
[112.235053] Dynamic subtest pipe-A-DP-1: SUCCESS (2.341s)
[112.329899] Starting dynamic subtest: pipe-C-DP-1
[114.979888] Dynamic subtest pipe-C-DP-1: SUCCESS (2.684s)
[115.000275] Subtest cursor-onscreen-128x128: SUCCESS (5.100s)
[115.373406] [021/141] (911s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[115.798546] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[115.837833] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[115.948882] [022/141] (910s left) kms_ccs (pipe-d-crc-primary-rotation-180-4_tiled_dg2_mc_ccs)
[116.701986] Subtest pipe-D-crc-primary-rotation-180-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[117.042948] [023/141] (909s left) gem_vm_create (invalid-destroy)
[117.337757] Starting subtest: invalid-destroy
[117.359475] Subtest invalid-destroy: SUCCESS (0.001s)
[117.473527] [024/141] (909s left) kms_scaling_modes (scaling-mode-full)
[117.837387] Starting subtest: scaling-mode-full
[117.876121] Subtest scaling-mode-full: SKIP (0.001s)
[117.990186] [025/141] (909s left) kms_ccs (pipe-a-ccs-on-another-bo-y_tiled_ccs)
[118.499791] Starting subtest: pipe-A-ccs-on-another-bo-y_tiled_ccs
[118.518547] Subtest pipe-A-ccs-on-another-bo-y_tiled_ccs: SUCCESS (0.001s)
[119.073840] [026/141] (908s left) kms_atomic_interruptible (universal-setplane-primary)
[119.446097] Starting subtest: universal-setplane-primary
[119.465221] Starting dynamic subtest: DP-1-pipe-A
[126.005650] Dynamic subtest DP-1-pipe-A: SUCCESS (6.548s)
[126.031662] Subtest universal-setplane-primary: SUCCESS (6.551s)
[126.392331] [027/141] (900s left) kms_plane_alpha_blend (alpha-opaque-fb)
[126.788918] Starting subtest: alpha-opaque-fb
[127.098613] Starting dynamic subtest: pipe-A-DP-1
[133.212778] Dynamic subtest pipe-A-DP-1: FAIL (4.792s)
[133.251486] Starting dynamic subtest: pipe-C-DP-1
[139.141463] Dynamic subtest pipe-C-DP-1: FAIL (4.505s)
[139.164141] Subtest alpha-opaque-fb: FAIL (9.609s)
[139.293899] [028/141] (888s left) kms_ccs (pipe-a-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc)
[139.814469] Starting subtest: pipe-A-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc
[139.862382] Subtest pipe-A-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s)
[140.412280] [029/141] (887s left) kms_ccs (pipe-b-bad-pixel-format-y_tiled_gen12_rc_ccs)
[140.963236] Starting subtest: pipe-B-bad-pixel-format-y_tiled_gen12_rc_ccs
[141.014008] Subtest pipe-B-bad-pixel-format-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[141.650550] [030/141] (886s left) prime_self_import (export-vs-gem_close-race)
[141.982671] Starting subtest: export-vs-gem_close-race
[147.205699] Subtest export-vs-gem_close-race: SUCCESS (5.027s)
[147.303970] [031/141] (880s left) i915_pm_backlight (fade-with-suspend)
[147.723303] Subtest fade-with-suspend: SKIP (0.000s)
[147.864161] [032/141] (880s left) kms_big_fb (linear-32bpp-rotate-180)
[148.286340] Starting subtest: linear-32bpp-rotate-180
[154.006241] Subtest linear-32bpp-rotate-180: SUCCESS (5.750s)
[154.560645] [033/141] (873s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[154.970551] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[155.014665] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[155.137924] [034/141] (873s left) kms_addfb_basic (bad-pitch-128)
[155.428026] Starting subtest: bad-pitch-128
[155.447636] Subtest bad-pitch-128: SUCCESS (0.000s)
[155.686945] [035/141] (872s left) kms_content_protection (srm)
[156.122316] Starting subtest: srm
[156.180621] Starting dynamic subtest: pipe-A-DP-1
[277.124491] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[284.412299] Closing watchdogs
[284.420186] Initializing watchdogs
[284.420364]   /dev/watchdog0
[284.478065] [036/141] (743s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[284.926980] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu
[284.964442] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[285.076428] [037/141] (743s left) gem_exec_whisper (basic-forked)
[285.411223] Starting subtest: basic-forked
[298.147392] Subtest basic-forked: SUCCESS (12.757s)
[298.355555] [038/141] (730s left) kms_big_fb (x-tiled-32bpp-rotate-0)
[298.778352] Starting subtest: x-tiled-32bpp-rotate-0
[303.637182] Subtest x-tiled-32bpp-rotate-0: SUCCESS (4.901s)
[304.192261] [039/141] (724s left) vc4/vc4_perfmon (create-two-perfmon)
[304.543933] Subtest create-two-perfmon: SKIP (0.000s)
[304.638696] [040/141] (724s left) kms_cursor_legacy (cursor-vs-flip-atomic)
[304.992706] Starting subtest: cursor-vs-flip-atomic
[343.039039] Subtest cursor-vs-flip-atomic: SUCCESS (37.945s)
[343.313916] [041/141] (685s left) kms_invalid_mode (int-max-clock)
[343.698649] Starting subtest: int-max-clock
[343.717579] Starting dynamic subtest: DP-1-pipe-A
[343.788538] Dynamic subtest DP-1-pipe-A: SUCCESS (0.099s)
[343.829892] Starting dynamic subtest: DP-1-pipe-B
[343.851175] Dynamic subtest DP-1-pipe-B: SUCCESS (0.014s)
[343.902351] Starting dynamic subtest: DP-1-pipe-C
[343.921458] Dynamic subtest DP-1-pipe-C: SUCCESS (0.014s)
[343.940806] Subtest int-max-clock: SUCCESS (0.137s)
[344.190814] [042/141] (684s left) gem_exec_async (concurrent-writes)
[344.509358] Starting subtest: concurrent-writes
[344.531316] Starting dynamic subtest: rcs0
[344.553249] Dynamic subtest rcs0: SUCCESS (0.019s)
[344.594095] Starting dynamic subtest: bcs0
[344.613824] Dynamic subtest bcs0: SUCCESS (0.014s)
[344.651984] Starting dynamic subtest: vcs0
[344.670894] Dynamic subtest vcs0: SUCCESS (0.016s)
[344.709799] Starting dynamic subtest: vecs0
[344.729706] Dynamic subtest vecs0: SUCCESS (0.014s)
[344.751473] Subtest concurrent-writes: SUCCESS (0.069s)
[344.864931] [043/141] (684s left) kms_psr2_sf (overlay-plane-move-continuous-sf)
[345.183033] Subtest overlay-plane-move-continuous-sf: SKIP (0.000s)
[345.292099] [044/141] (683s left) kms_psr (sprite_render)
[345.603016] Subtest sprite_render: SKIP (0.000s)
[345.718845] [045/141] (683s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[346.130372] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[346.174065] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[346.287630] [046/141] (683s left) v3d/v3d_submit_cl (bad-flag)
[346.625960] Subtest bad-flag: SKIP (0.000s)
[346.716806] [047/141] (682s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-render)
[347.158511] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-render
[347.195860] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[347.311591] [048/141] (682s left) kms_frontbuffer_tracking (fbc-2p-shrfb-fliptrack-mmap-gtt)
[347.727106] Starting subtest: fbc-2p-shrfb-fliptrack-mmap-gtt
[347.770322] Subtest fbc-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[347.879942] [049/141] (681s left) gem_eio (in-flight-immediate)
[348.210299] Starting subtest: in-flight-immediate
[349.073151] Subtest in-flight-immediate: SUCCESS (0.917s)
[349.336263] [050/141] (680s left) gem_exec_balancer (parallel-out-fence)
[353.683524] Subtest parallel-out-fence: SKIP (0.000s)
[353.781973] [051/141] (676s left) drm_fdinfo (busy-hang)
[354.056286] Starting subtest: busy-hang
[354.078111] Starting dynamic subtest: rcs0
[355.078379] Dynamic subtest rcs0: SUCCESS (1.037s)
[355.116714] Starting dynamic subtest: bcs0
[356.124158] Dynamic subtest bcs0: SUCCESS (1.041s)
[356.175457] Starting dynamic subtest: vcs0
[357.171043] Dynamic subtest vcs0: SUCCESS (1.038s)
[357.215424] Starting dynamic subtest: vecs0
[358.213072] Dynamic subtest vecs0: SUCCESS (1.033s)
[358.240125] Subtest busy-hang: SUCCESS (4.158s)
[358.357136] [052/141] (671s left) kms_ccs (pipe-c-crc-primary-rotation-180-4_tiled_mtl_rc_ccs)
[359.061638] Starting subtest: pipe-C-crc-primary-rotation-180-4_tiled_mtl_rc_ccs
[359.111382] Subtest pipe-C-crc-primary-rotation-180-4_tiled_mtl_rc_ccs: SKIP (0.001s)
[359.496528] [053/141] (670s left) gem_ctx_param (non-root-set)
[359.814707] Starting subtest: non-root-set
[359.842275] Subtest non-root-set: SUCCESS (0.034s)
[359.984425] [054/141] (670s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-upscaling)
[360.354054] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-upscaling
[360.375432] Starting dynamic subtest: pipe-A-valid-mode
[360.484017] Dynamic subtest pipe-A-valid-mode: SKIP (0.090s)
[360.526704] Subtest flip-32bpp-4tile-to-64bpp-4tile-upscaling: SKIP (0.094s)
[360.824565] [055/141] (669s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-render)
[361.241744] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-render
[361.283274] Subtest fbc-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[361.394834] [056/141] (669s left) kms_universal_plane (universal-plane-pipe-b-functional)
[361.757689] Starting subtest: universal-plane-pipe-B-functional
[364.694923] Subtest universal-plane-pipe-B-functional: SUCCESS (2.921s)
[365.043908] [057/141] (665s left) gem_ctx_persistence (smoketest)
[365.343314] Starting subtest: smoketest
[396.460007] Subtest smoketest: SUCCESS (31.126s)
[396.590088] [058/141] (634s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-upscaling)
[396.942420] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-upscaling
[396.963297] Starting dynamic subtest: pipe-A-valid-mode
[397.381652] Dynamic subtest pipe-A-valid-mode: SKIP (0.389s)
[397.424563] Subtest flip-64bpp-ytile-to-16bpp-ytile-upscaling: SKIP (0.393s)
[397.786981] [059/141] (633s left) api_intel_bb (full-batch)
[398.243359] Starting subtest: full-batch
[398.271020] Subtest full-batch: SUCCESS (0.006s)
[398.404715] [060/141] (632s left) gem_exec_schedule (preempt-self)
[398.893993] Starting subtest: preempt-self
[398.912983] Starting dynamic subtest: rcs0
[398.931861] Dynamic subtest rcs0: SUCCESS (0.033s)
[398.970924] Starting dynamic subtest: bcs0
[398.989944] Dynamic subtest bcs0: SUCCESS (0.035s)
[399.032362] Starting dynamic subtest: vcs0
[399.053907] Dynamic subtest vcs0: SUCCESS (0.033s)
[399.095006] Starting dynamic subtest: vecs0
[399.115940] Dynamic subtest vecs0: SUCCESS (0.033s)
[399.134970] Subtest preempt-self: SUCCESS (0.146s)
[399.269547] [061/141] (631s left) kms_vblank (pipe-c-wait-busy)
[399.630425] Starting subtest: pipe-C-wait-busy
[402.591089] Subtest pipe-C-wait-busy: SUCCESS (2.953s)
[402.959648] [062/141] (628s left) kms_addfb_basic (bad-pitch-32)
[403.226155] Starting subtest: bad-pitch-32
[403.247643] Subtest bad-pitch-32: SUCCESS (0.000s)
[403.452629] [063/141] (627s left) syncobj_wait (wait-for-submit-delayed-submit)
[403.739330] Starting subtest: wait-for-submit-delayed-submit
[403.814274] Subtest wait-for-submit-delayed-submit: SUCCESS (0.103s)
[403.955367] [064/141] (627s left) kms_cursor_legacy (cursora-vs-flipa-varying-size)
[404.290415] Starting subtest: cursorA-vs-flipA-varying-size
[415.348272] Subtest cursorA-vs-flipA-varying-size: SUCCESS (11.048s)
[415.730775] [065/141] (615s left) kms_ccs (pipe-b-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc)
[416.325434] Starting subtest: pipe-B-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc
[416.364398] Subtest pipe-B-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s)
[416.820471] [066/141] (614s left) i915_query (query-topology-unsupported)
[417.114541] Starting subtest: query-topology-unsupported
[417.154555] Subtest query-topology-unsupported: SKIP (0.000s)
[417.269484] [067/141] (614s left) v3d/v3d_submit_cl (single-in-sync)
[417.606946] Subtest single-in-sync: SKIP (0.000s)
[417.718458] [068/141] (614s left) kms_universal_plane (universal-plane-pipe-c-sanity)
[418.074652] Starting subtest: universal-plane-pipe-C-sanity
[419.037877] Subtest universal-plane-pipe-C-sanity: SUCCESS (0.951s)
[419.395487] [069/141] (612s left) gen3_render_tiledx_blits
[419.834598] [070/141] (612s left) gen9_exec_parse (bb-large)
[420.130974] Starting subtest: bb-large
[503.710593] Subtest bb-large: SUCCESS (83.465s)
[503.869241] [071/141] (528s left) gem_exec_reloc (basic-wc-cpu)
[504.279131] Starting subtest: basic-wc-cpu
[504.298580] Subtest basic-wc-cpu: SUCCESS (0.021s)
[504.577150] [072/141] (527s left) gem_exec_fence (submit-chain)
[505.004754] Starting subtest: submit-chain
[505.074176] Subtest submit-chain: SUCCESS (0.070s)
[505.770807] [073/141] (526s left) kms_rotation_crc (primary-y-tiled-reflect-x-180)
[506.113979] Starting subtest: primary-y-tiled-reflect-x-180
[506.151238] Subtest primary-y-tiled-reflect-x-180: SKIP (0.003s)
[506.274594] [074/141] (526s left) gem_exec_schedule (implicit-read-write)
[506.727999] Starting subtest: implicit-read-write
[506.748919] Starting dynamic subtest: rcs0
[506.769688] Dynamic subtest rcs0: SUCCESS (0.151s)
[506.812220] Starting dynamic subtest: bcs0
[506.859504] Dynamic subtest bcs0: SUCCESS (0.141s)
[506.902777] Starting dynamic subtest: vcs0
[506.995663] Dynamic subtest vcs0: SUCCESS (0.130s)
[507.040617] Starting dynamic subtest: vecs0
[507.128852] Dynamic subtest vecs0: SUCCESS (0.130s)
[507.147744] Subtest implicit-read-write: SUCCESS (0.565s)
[507.528557] [075/141] (525s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[507.948254] Starting subtest: yf-tiled-64bpp-rotate-90
[507.988182] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.003s)
[508.107333] [076/141] (524s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[508.557069] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[508.597114] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[508.708551] [077/141] (524s left) fbdev (pan)
[508.985979] Starting subtest: pan
[509.025549] Subtest pan: SUCCESS (0.048s)
[509.145106] [078/141] (523s left) kms_vblank (pipe-d-wait-busy)
[509.512750] Subtest pipe-D-wait-busy: SKIP (0.000s)
[509.711714] [079/141] (523s left) gem_pread (exhaustion)
[510.001322] Starting subtest: exhaustion
[531.046217] Subtest exhaustion: SUCCESS (21.075s)
[531.176619] [080/141] (501s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted-submitted)
[531.472166] Starting subtest: etime-multi-wait-for-submit-unsubmitted-submitted
[531.543170] Subtest etime-multi-wait-for-submit-unsubmitted-submitted: SUCCESS (0.104s)
[531.665167] [081/141] (501s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[532.085490] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu
[532.124369] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[532.243558] [082/141] (501s left) i915_pm_rps (basic-api)
[532.537922] Starting subtest: basic-api
[532.558898] Subtest basic-api: SUCCESS (0.008s)
[532.672015] [083/141] (500s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-plflip-blt)
[533.074923] Starting subtest: fbc-1p-primscrn-shrfb-plflip-blt
[535.604078] Subtest fbc-1p-primscrn-shrfb-plflip-blt: SUCCESS (2.589s)
[535.781704] [084/141] (497s left) kms_flip (dpms-off-confusion)
[536.165868] Starting subtest: dpms-off-confusion
[536.186509] Starting dynamic subtest: A-DP1
[547.132741] Dynamic subtest A-DP1: SUCCESS (10.815s)
[547.175970] Starting dynamic subtest: B-DP1
[557.600927] Dynamic subtest B-DP1: SUCCESS (10.461s)
[557.659055] Starting dynamic subtest: C-DP1
[567.892040] Dynamic subtest C-DP1: SUCCESS (10.460s)
[567.911023] Subtest dpms-off-confusion: SUCCESS (31.750s)
[568.357514] [085/141] (465s left) perf_pmu (busy-accuracy-98)
[568.661646] Starting subtest: busy-accuracy-98
[568.680899] Starting dynamic subtest: rcs0
[571.738559] Dynamic subtest rcs0: SUCCESS (3.059s)
[571.781097] Starting dynamic subtest: bcs0
[574.802151] Dynamic subtest bcs0: SUCCESS (3.061s)
[574.844813] Starting dynamic subtest: vcs0
[577.856837] Dynamic subtest vcs0: SUCCESS (3.055s)
[577.900629] Starting dynamic subtest: vecs0
[580.912567] Dynamic subtest vecs0: SUCCESS (3.055s)
[580.934417] Subtest busy-accuracy-98: SUCCESS (12.243s)
[581.053022] [086/141] (452s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4_tiled_dg2_mc_ccs)
[581.763243] Starting subtest: pipe-C-crc-sprite-planes-basic-4_tiled_dg2_mc_ccs
[581.801273] Subtest pipe-C-crc-sprite-planes-basic-4_tiled_dg2_mc_ccs: SKIP (0.004s)
[582.140304] [087/141] (451s left) kms_tiled_display (basic-test-pattern-with-chamelium)
[582.596936] Subtest basic-test-pattern-with-chamelium: SKIP (0.000s)
[582.713525] [088/141] (451s left) perf_pmu (invalid-open)
[583.004246] Starting subtest: invalid-open
[583.023230] Subtest invalid-open: SUCCESS (0.001s)
[583.161010] [089/141] (450s left) kms_chamelium_color (gamma)
[583.544105] Subtest gamma: SKIP (0.000s)
[583.662025] [090/141] (450s left) gem_ctx_persistence (heartbeat-close)
[583.954791] Starting subtest: heartbeat-close
[584.125090] Subtest heartbeat-close: SUCCESS (0.175s)
[584.253502] [091/141] (450s left) gem_partial_pwrite_pread (reads-display)
[584.546408] Starting subtest: reads-display
[591.221338] Subtest reads-display: SUCCESS (6.688s)
[591.375446] [092/141] (442s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[591.850624] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[591.905589] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[592.047305] [093/141] (442s left) kms_ccs (pipe-c-random-ccs-data-y_tiled_ccs)
[592.735073] Starting subtest: pipe-C-random-ccs-data-y_tiled_ccs
[592.799255] Subtest pipe-C-random-ccs-data-y_tiled_ccs: SKIP (0.000s)
[593.160844] [094/141] (441s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[593.580468] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[593.623749] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[593.745809] [095/141] (440s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[594.147755] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip
[594.232620] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.025s)
[594.352149] [096/141] (440s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[594.722530] Starting subtest: bo-too-small-due-to-tiling
[594.744066] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[594.872254] [097/141] (440s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[595.237623] Starting subtest: cursor-vs-flip-legacy
[633.432219] Subtest cursor-vs-flip-legacy: SUCCESS (38.195s)
[633.822520] [098/141] (401s left) prime_vgem (fence-wait)
[634.175922] Starting subtest: fence-wait
[634.197565] Starting dynamic subtest: rcs0
[635.172065] Dynamic subtest rcs0: SUCCESS (1.039s)
[635.216038] Starting dynamic subtest: vcs0
[636.208803] Dynamic subtest vcs0: SUCCESS (1.036s)
[636.250741] Starting dynamic subtest: vecs0
[637.247323] Dynamic subtest vecs0: SUCCESS (1.033s)
[637.268899] Subtest fence-wait: SUCCESS (3.117s)
[637.401456] [099/141] (397s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[637.698810] Starting subtest: y-tiled-ccs-to-y-tiled
[637.717305] Starting dynamic subtest: smem
[637.984237] Dynamic subtest smem: SUCCESS (0.300s)
[638.003134] Subtest y-tiled-ccs-to-y-tiled: SUCCESS (0.302s)
[638.223625] [100/141] (396s left) kms_flip (2x-blocking-absolute-wf_vblank)
[638.576419] Starting subtest: 2x-blocking-absolute-wf_vblank
[638.617157] Subtest 2x-blocking-absolute-wf_vblank: SKIP (0.003s)
[638.739357] [101/141] (396s left) prime_mmap_coherency (ioctl-errors)
[639.036692] Starting subtest: ioctl-errors
[654.619192] Subtest ioctl-errors: SUCCESS (15.607s)
[654.780506] [102/141] (380s left) kms_vblank (pipe-c-ts-continuation-dpms-suspend)
[655.404862] Starting subtest: pipe-C-ts-continuation-dpms-suspend
[674.276786] Subtest pipe-C-ts-continuation-dpms-suspend: SUCCESS (2.608s)
[680.048120] [103/141] (376s left) gem_mmap_gtt (cpuset-big-copy-odd)
[680.355726] Starting subtest: cpuset-big-copy-odd
[703.476495] Subtest cpuset-big-copy-odd: SUCCESS (23.153s)
[703.611520] [104/141] (353s left) kms_ccs (pipe-d-random-ccs-data-y_tiled_gen12_mc_ccs)
[704.556651] Subtest pipe-D-random-ccs-data-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[704.896849] [105/141] (352s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-25)
[705.266507] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-25
[705.287613] Starting dynamic subtest: pipe-A-DP-1
[705.331511] Dynamic subtest pipe-A-DP-1: SKIP (0.032s)
[705.394850] Starting dynamic subtest: pipe-B-DP-1
[705.439643] Dynamic subtest pipe-B-DP-1: SKIP (0.087s)
[705.492148] Starting dynamic subtest: pipe-C-DP-1
[705.530167] Dynamic subtest pipe-C-DP-1: SKIP (0.082s)
[705.570899] Subtest planes-upscale-factor-0-25-downscale-factor-0-25: SKIP (0.217s)
[705.709636] [106/141] (351s left) kms_ccs (pipe-b-crc-primary-basic-y_tiled_gen12_mc_ccs)
[706.270653] Starting subtest: pipe-B-crc-primary-basic-y_tiled_gen12_mc_ccs
[706.308889] Subtest pipe-B-crc-primary-basic-y_tiled_gen12_mc_ccs: SKIP (0.001s)
[706.821747] [107/141] (350s left) gem_softpin (overlap)
[707.119050] Starting subtest: overlap
[710.123624] Subtest overlap: SUCCESS (2.827s)
[710.243621] [108/141] (347s left) kms_atomic_transition (modeset-transition-fencing)
[710.603548] Starting subtest: modeset-transition-fencing
[710.636551] Starting dynamic subtest: 1x-outputs
[715.728092] Dynamic subtest 1x-outputs: SUCCESS (5.126s)
[715.863220] Subtest modeset-transition-fencing: SUCCESS (5.249s)
[716.242815] [109/141] (341s left) kms_ccs (pipe-d-bad-rotation-90-4_tiled_mtl_rc_ccs)
[717.014846] Subtest pipe-D-bad-rotation-90-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[717.344132] [110/141] (340s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[717.804410] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[717.842223] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[717.961331] [111/141] (339s left) syncobj_timeline (multi-wait-available-signaled)
[718.287955] Starting subtest: multi-wait-available-signaled
[718.309038] Subtest multi-wait-available-signaled: SUCCESS (0.002s)
[718.424888] [112/141] (339s left) i915_pm_rpm (system-suspend)
[720.286114] Starting subtest: system-suspend
[737.922728] Subtest system-suspend: SUCCESS (1.716s)
[744.671860] [113/141] (335s left) kms_plane_scaling (plane-downscale-with-pixel-format-factor-0-5)
[745.032442] Starting subtest: plane-downscale-with-pixel-format-factor-0-5
[745.053258] Starting dynamic subtest: pipe-A-DP-1
[747.707246] Dynamic subtest pipe-A-DP-1: SUCCESS (2.659s)
[747.762427] Starting dynamic subtest: pipe-B-DP-1
[750.336419] Dynamic subtest pipe-B-DP-1: SUCCESS (2.623s)
[750.393778] Starting dynamic subtest: pipe-C-DP-1
[750.689206] Dynamic subtest pipe-C-DP-1: SUCCESS (0.355s)
[750.710182] Subtest plane-downscale-with-pixel-format-factor-0-5: SUCCESS (5.654s)
[751.147554] [114/141] (328s left) kms_ccs (pipe-c-bad-rotation-90-4_tiled_mtl_mc_ccs)
[751.813275] Starting subtest: pipe-C-bad-rotation-90-4_tiled_mtl_mc_ccs
[751.851010] Subtest pipe-C-bad-rotation-90-4_tiled_mtl_mc_ccs: SKIP (0.000s)
[752.237325] [115/141] (327s left) v3d/v3d_wait_bo (map-bo-1ns)
[752.595256] Subtest map-bo-1ns: SKIP (0.000s)
[752.717804] [116/141] (327s left) kms_chamelium_frames (hdmi-crc-fast)
[753.452685] Subtest hdmi-crc-fast: SKIP (0.000s)
[753.812644] [117/141] (326s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-wc)
[754.254623] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-wc
[756.423904] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (2.242s)
[756.641758] [118/141] (323s left) syncobj_wait (invalid-reset-zero-handles)
[756.950296] Starting subtest: invalid-reset-zero-handles
[756.971466] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[757.092374] [119/141] (323s left) syncobj_timeline (invalid-query-illegal-handle)
[757.390114] Starting subtest: invalid-query-illegal-handle
[757.409615] Subtest invalid-query-illegal-handle: SUCCESS (0.000s)
[757.532749] [120/141] (323s left) kms_fbcon_fbt (fbc)
[757.834041] Starting subtest: fbc
[761.760384] Subtest fbc: SUCCESS (3.909s)
[761.883377] [121/141] (319s left) gem_spin_batch (resubmit-new)
[762.183652] Starting subtest: resubmit-new
[762.205286] Starting dynamic subtest: rcs0
[762.225106] Dynamic subtest rcs0: SUCCESS (0.009s)
[762.267987] Starting dynamic subtest: bcs0
[762.286679] Dynamic subtest bcs0: SUCCESS (0.009s)
[762.329859] Starting dynamic subtest: vcs0
[762.350085] Dynamic subtest vcs0: SUCCESS (0.007s)
[762.397020] Starting dynamic subtest: vecs0
[762.418242] Dynamic subtest vecs0: SUCCESS (0.007s)
[762.438907] Subtest resubmit-new: SUCCESS (0.044s)
[762.564841] [122/141] (318s left) gem_exec_schedule (u-semaphore-user)
[763.026927] Starting subtest: u-semaphore-user
[763.045752] Subtest u-semaphore-user: SUCCESS (0.021s)
[763.302136] [123/141] (317s left) kms_dither (fb-8bpc-vs-panel-8bpc)
[763.669058] Starting subtest: FB-8BPC-Vs-Panel-8BPC
[763.688106] Starting dynamic subtest: pipe-A-DP-1
[764.127555] Dynamic subtest pipe-A-DP-1: SUCCESS (0.453s)
[764.149191] Subtest FB-8BPC-Vs-Panel-8BPC: SUCCESS (0.456s)
[764.495438] [124/141] (316s left) kms_lease (lease-uevent)
[764.865266] Starting subtest: lease-uevent
[765.878500] Subtest lease-uevent: SUCCESS (1.020s)
[766.024502] [125/141] (315s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[766.459089] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[766.499655] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[766.613044] [126/141] (314s left) api_intel_bb (offset-control)
[766.914105] Starting subtest: offset-control
[766.932954] Subtest offset-control: SUCCESS (0.018s)
[767.078816] [127/141] (314s left) gem_lmem_swapping (smem-oom)
[767.397666] Subtest smem-oom: SKIP (0.000s)
[767.512492] [128/141] (314s left) gem_exec_flush (basic-wb-ro-before-default)
[767.850354] Starting subtest: basic-wb-ro-before-default
[773.302256] Subtest basic-wb-ro-before-default: SUCCESS (5.477s)
[773.524727] [129/141] (308s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-pwrite)
[773.971214] Starting subtest: fbc-1p-offscren-pri-indfb-draw-pwrite
[775.769103] Subtest fbc-1p-offscren-pri-indfb-draw-pwrite: SUCCESS (1.877s)
[775.938605] [130/141] (306s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[776.238542] Starting subtest: single-wait-all-for-submit-available-submitted
[776.257439] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.001s)
[776.357524] [131/141] (305s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-onoff)
[776.796471] Starting subtest: psr-2p-scndscrn-spr-indfb-onoff
[776.836562] Subtest psr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[776.955837] [132/141] (305s left) gem_ringfill (engines-basic)
[777.523044] Starting subtest: engines-basic
[777.544037] Starting dynamic subtest: rcs0
[777.563513] Dynamic subtest rcs0: SUCCESS (0.024s)
[777.608317] Starting dynamic subtest: bcs0
[777.627186] Dynamic subtest bcs0: SUCCESS (0.028s)
[777.670633] Starting dynamic subtest: vcs0
[777.692123] Dynamic subtest vcs0: SUCCESS (0.023s)
[777.737785] Starting dynamic subtest: vecs0
[777.758439] Dynamic subtest vecs0: SUCCESS (0.024s)
[777.779047] Subtest engines-basic: SUCCESS (0.111s)
[777.873814] [133/141] (304s left) gem_ctx_exec (basic-close-race)
[778.175160] Starting subtest: basic-close-race
[783.809144] Subtest basic-close-race: SUCCESS (5.485s)
[784.072305] [134/141] (298s left) kms_ccs (pipe-c-bad-aux-stride-4_tiled_mtl_rc_ccs)
[784.788218] Starting subtest: pipe-C-bad-aux-stride-4_tiled_mtl_rc_ccs
[784.829714] Subtest pipe-C-bad-aux-stride-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[785.179605] [135/141] (297s left) kms_ccs (pipe-a-crc-sprite-planes-basic-y_tiled_ccs)
[785.704458] Starting subtest: pipe-A-crc-sprite-planes-basic-y_tiled_ccs
[786.235129] Subtest pipe-A-crc-sprite-planes-basic-y_tiled_ccs: SUCCESS (0.530s)
[786.907882] [136/141] (295s left) kms_properties (connector-properties-atomic)
[787.281152] Starting subtest: connector-properties-atomic
[787.765040] Subtest connector-properties-atomic: SUCCESS (0.459s)
[788.121827] [137/141] (294s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled-ccs)
[788.421621] Starting subtest: yf-tiled-ccs-to-yf-tiled-ccs
[788.440726] Starting dynamic subtest: smem
[788.868395] Dynamic subtest smem: SUCCESS (0.452s)
[788.889057] Subtest yf-tiled-ccs-to-yf-tiled-ccs: SUCCESS (0.454s)
[789.123133] [138/141] (293s left) prime_vgem (basic-read)
[789.450655] Starting subtest: basic-read
[789.472550] Subtest basic-read: SUCCESS (0.050s)
[789.616569] [139/141] (293s left) i915_suspend (basic-s3-without-i915)
[789.902604] Starting subtest: basic-s3-without-i915
[811.110119] Subtest basic-s3-without-i915: SUCCESS (5.120s)
[816.033867] [140/141] (287s left) gem_mmap_wc (bad-object)
[816.348574] Starting subtest: bad-object
[816.367796] Subtest bad-object: SUCCESS (0.000s)
[816.459397] [141/141] (287s left) kms_color (ctm-negative)
[816.832217] Starting subtest: ctm-negative
[816.853250] Starting dynamic subtest: pipe-A
[817.600737] Dynamic subtest pipe-A: SUCCESS (0.767s)
[817.644903] Starting dynamic subtest: pipe-B
[818.636916] Dynamic subtest pipe-B: SUCCESS (1.043s)
[818.678234] Starting dynamic subtest: pipe-C
[819.593608] Dynamic subtest pipe-C: SUCCESS (0.955s)
[819.613882] Subtest ctm-negative: SUCCESS (2.773s)
[819.936133] Closing watchdogs
Done.