[61.501737] Initializing watchdogs
[61.501823]   /dev/watchdog0
[61.532153] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:4905 Intel Dg1 (Gen12) DG1 [Iris Xe MAX Graphics]
[61.546405] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[61.551526] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[61.554791] [001/132] (960s left) syncobj_timeline (single-wait-for-submit-available-signaled)
[61.603578] Starting subtest: single-wait-for-submit-available-signaled
[61.604473] Subtest single-wait-for-submit-available-signaled: SUCCESS (0.000s)
[61.733859] [002/132] (960s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-cpu)
[63.726429] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-cpu
[63.728169] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[63.980204] [003/132] (958s left) gem_exec_schedule (submit-late-slice)
[64.044750] Starting subtest: submit-late-slice
[64.045704] Starting dynamic subtest: rcs0
[64.105709] Dynamic subtest rcs0: SUCCESS (0.061s)
[64.113638] Starting dynamic subtest: bcs0
[64.147930] Dynamic subtest bcs0: SUCCESS (0.042s)
[64.155452] Starting dynamic subtest: vcs0
[64.189509] Dynamic subtest vcs0: SUCCESS (0.041s)
[64.197139] Starting dynamic subtest: vcs1
[64.231887] Dynamic subtest vcs1: SUCCESS (0.042s)
[64.239834] Starting dynamic subtest: vecs0
[64.274764] Dynamic subtest vecs0: SUCCESS (0.042s)
[64.275751] Subtest submit-late-slice: SUCCESS (0.230s)
[64.791588] [004/132] (957s left) syncobj_wait (wait-any-interrupted)
[64.835031] Starting subtest: wait-any-interrupted
[64.939919] Subtest wait-any-interrupted: SUCCESS (0.101s)
[65.089740] [005/132] (957s left) gem_exec_params (rsvd2-dirt)
[65.138321] Starting subtest: rsvd2-dirt
[65.140139] Subtest rsvd2-dirt: SKIP (0.000s)
[65.378172] [006/132] (957s left) kms_draw_crc (draw-method-render)
[67.367108] Starting subtest: draw-method-render
[67.368230] Starting dynamic subtest: xrgb8888-untiled
[67.729354] Dynamic subtest xrgb8888-untiled: SUCCESS (0.363s)
[67.737051] Starting dynamic subtest: rgb565-untiled
[68.029458] Dynamic subtest rgb565-untiled: SUCCESS (0.299s)
[68.037383] Starting dynamic subtest: xrgb2101010-untiled
[68.313051] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.283s)
[68.321040] Starting dynamic subtest: xbgr16161616f-untiled
[68.665178] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.350s)
[68.696067] Starting dynamic subtest: xrgb8888-xtiled
[68.864012] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.198s)
[68.875863] Starting dynamic subtest: rgb565-xtiled
[69.030415] Dynamic subtest rgb565-xtiled: SUCCESS (0.166s)
[69.040073] Starting dynamic subtest: xrgb2101010-xtiled
[69.182412] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.150s)
[69.212319] Starting dynamic subtest: xbgr16161616f-xtiled
[69.382335] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.198s)
[69.409748] Starting dynamic subtest: xrgb8888-ytiled
[69.582646] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.199s)
[69.617566] Starting dynamic subtest: rgb565-ytiled
[69.782639] Dynamic subtest rgb565-ytiled: SUCCESS (0.199s)
[69.794747] Starting dynamic subtest: xrgb2101010-ytiled
[69.932631] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.148s)
[69.963034] Starting dynamic subtest: xbgr16161616f-ytiled
[70.132898] Dynamic subtest xbgr16161616f-ytiled: SUCCESS (0.199s)
[70.135190] Subtest draw-method-render: SUCCESS (2.765s)
[70.363669] [007/132] (952s left) kms_content_protection (content-type-change)
[72.406890] Starting subtest: content-type-change
[72.414036] Subtest content-type-change: SKIP (0.006s)
[72.634044] [008/132] (950s left) api_intel_bb (object-noreloc-purge-cache-simple)
[72.684514] Starting subtest: object-noreloc-purge-cache-simple
[72.709148] Subtest object-noreloc-purge-cache-simple: SUCCESS (0.025s)
[72.885189] [009/132] (949s left) kms_flip (2x-dpms-vs-vblank-race)
[74.869087] Starting subtest: 2x-dpms-vs-vblank-race
[74.889209] Subtest 2x-dpms-vs-vblank-race: SKIP (0.020s)
[75.073432] [010/132] (947s left) gem_mmap_gtt (hang)
[75.120193] Subtest hang: SKIP (0.000s)
[75.188999] [011/132] (947s left) kms_dirtyfb (fbc-dirtyfb-ioctl)
[77.176607] Starting subtest: fbc-dirtyfb-ioctl
[77.178603] Starting dynamic subtest: A-HDMI-A-3
[78.675431] Dynamic subtest A-HDMI-A-3: SUCCESS (1.497s)
[78.676351] Subtest fbc-dirtyfb-ioctl: SUCCESS (1.498s)
[78.895687] [012/132] (944s left) kms_cursor_crc (cursor-onscreen-256x256)
[81.107269] Starting subtest: cursor-onscreen-256x256
[81.114080] Starting dynamic subtest: pipe-A-HDMI-A-3
[83.871808] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (2.757s)
[83.904389] Starting dynamic subtest: pipe-D-HDMI-A-3
[86.553471] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (2.656s)
[86.554439] Subtest cursor-onscreen-256x256: SUCCESS (5.445s)
[86.936833] [013/132] (936s left) kms_flip (2x-flip-vs-modeset)
[88.922598] Starting subtest: 2x-flip-vs-modeset
[88.942887] Subtest 2x-flip-vs-modeset: SKIP (0.020s)
[89.109684] [014/132] (934s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt)
[91.103637] Starting subtest: fbc-farfromfence-mmap-gtt
[91.105312] Subtest fbc-farfromfence-mmap-gtt: SKIP (0.000s)
[91.370435] [015/132] (932s left) kms_chamelium_hpd (hdmi-hpd-storm-disable)
[93.405225] Subtest hdmi-hpd-storm-disable: SKIP (0.000s)
[93.517676] [016/132] (930s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[95.528918] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[96.126758] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.598s)
[96.373560] [017/132] (927s left) gem_cs_tlb (engines)
[96.422331] Starting subtest: engines
[96.423235] Starting dynamic subtest: rcs0
[98.580230] Dynamic subtest rcs0: SUCCESS (2.154s)
[98.588503] Starting dynamic subtest: bcs0
[100.734410] Dynamic subtest bcs0: SUCCESS (2.153s)
[100.742544] Starting dynamic subtest: vcs0
[102.888555] Dynamic subtest vcs0: SUCCESS (2.154s)
[102.896621] Starting dynamic subtest: vcs1
[105.042644] Dynamic subtest vcs1: SUCCESS (2.154s)
[105.050892] Starting dynamic subtest: vecs0
[107.197134] Dynamic subtest vecs0: SUCCESS (2.154s)
[107.198378] Subtest engines: SUCCESS (10.770s)
[107.374360] [018/132] (916s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-upscaling)
[109.359362] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-upscaling
[109.361138] Starting dynamic subtest: pipe-A-valid-mode
[109.403031] Dynamic subtest pipe-A-valid-mode: SKIP (0.042s)
[109.404950] Subtest flip-64bpp-yftile-to-32bpp-yftile-upscaling: SKIP (0.043s)
[109.597236] [019/132] (914s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[111.599102] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[111.601126] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[111.836070] [020/132] (912s left) kms_psr2_su (page_flip-xrgb8888)
[111.886277] Subtest page_flip-XRGB8888: SKIP (0.000s)
[111.949588] [021/132] (912s left) syncobj_wait (wait-delayed-signal)
[111.993376] Starting subtest: wait-delayed-signal
[112.098048] Subtest wait-delayed-signal: SUCCESS (0.103s)
[112.233123] [022/132] (911s left) gem_ctx_shared (q-out-order)
[112.287501] Starting subtest: Q-out-order
[112.288315] Starting dynamic subtest: rcs0
[112.540646] Dynamic subtest rcs0: SUCCESS (0.250s)
[112.548795] Starting dynamic subtest: bcs0
[112.659266] Dynamic subtest bcs0: SUCCESS (0.118s)
[112.667401] Starting dynamic subtest: vcs0
[112.778499] Dynamic subtest vcs0: SUCCESS (0.119s)
[112.786860] Starting dynamic subtest: vcs1
[112.899432] Dynamic subtest vcs1: SUCCESS (0.121s)
[112.907467] Starting dynamic subtest: vecs0
[113.032251] Dynamic subtest vecs0: SUCCESS (0.132s)
[113.033373] Subtest Q-out-order: SUCCESS (0.742s)
[113.361537] [FACT gem_ctx_shared (q-out-order)] new: kernel.kmod_is_loaded.vgem: true
[113.363631] [023/132] (910s left) kms_cursor_crc (cursor-random-32x10)
[115.451639] Starting subtest: cursor-random-32x10
[115.505259] Subtest cursor-random-32x10: SKIP (0.052s)
[115.931025] [024/132] (908s left) sysfs_timeslice_duration (invalid)
[115.979574] Starting subtest: invalid
[115.980447] Starting dynamic subtest: vecs0
[115.981398] Dynamic subtest vecs0: SUCCESS (0.000s)
[115.989477] Starting dynamic subtest: vcs0
[115.990316] Dynamic subtest vcs0: SUCCESS (0.000s)
[115.998440] Starting dynamic subtest: bcs0
[115.999562] Dynamic subtest bcs0: SUCCESS (0.000s)
[116.007694] Starting dynamic subtest: rcs0
[116.008762] Dynamic subtest rcs0: SUCCESS (0.001s)
[116.016699] Starting dynamic subtest: vcs1
[116.017766] Dynamic subtest vcs1: SUCCESS (0.000s)
[116.018812] Subtest invalid: SUCCESS (0.005s)
[116.114409] [025/132] (908s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[118.112810] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip
[119.364071] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (1.250s)
[119.631066] [026/132] (904s left) gem_eio (reset-stress)
[119.820900] Starting subtest: reset-stress
[148.129263] Subtest reset-stress: SUCCESS (28.308s)
[150.335243] [027/132] (874s left) i915_drm_fdinfo (memory-info-purgeable)
[150.382406] Starting subtest: memory-info-purgeable
[150.383266] Starting dynamic subtest: lmem0
[154.340688] Dynamic subtest lmem0: SUCCESS (3.958s)
[154.349799] Starting dynamic subtest: smem0
[158.670410] Dynamic subtest smem0: SUCCESS (4.326s)
[158.671578] Subtest memory-info-purgeable: SUCCESS (8.285s)
[158.834597] [028/132] (865s left) kms_frontbuffer_tracking (fbc-2p-rte)
[160.827583] Starting subtest: fbc-2p-rte
[160.829246] Subtest fbc-2p-rte: SKIP (0.000s)
[161.083214] [029/132] (863s left) gem_exec_schedule (preempt-other)
[161.143191] Starting subtest: preempt-other
[161.144155] Starting dynamic subtest: rcs0
[161.269475] Dynamic subtest rcs0: SUCCESS (0.126s)
[161.278490] Starting dynamic subtest: bcs0
[161.377955] Dynamic subtest bcs0: SUCCESS (0.109s)
[161.387032] Starting dynamic subtest: vcs0
[161.491121] Dynamic subtest vcs0: SUCCESS (0.109s)
[161.500116] Starting dynamic subtest: vcs1
[161.597299] Dynamic subtest vcs1: SUCCESS (0.110s)
[161.606129] Starting dynamic subtest: vecs0
[161.705331] Dynamic subtest vecs0: SUCCESS (0.108s)
[161.706232] Subtest preempt-other: SUCCESS (0.562s)
[162.114619] [030/132] (862s left) gem_exec_params (invalid-fence-in)
[162.162974] Starting subtest: invalid-fence-in
[162.164100] Subtest invalid-fence-in: SUCCESS (0.000s)
[162.372220] [031/132] (862s left) kms_atomic (plane-immutable-zpos)
[164.358334] Starting subtest: plane-immutable-zpos
[164.360075] Starting dynamic subtest: pipe-A-HDMI-A-3
[165.136180] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (0.776s)
[165.137041] Subtest plane-immutable-zpos: SUCCESS (0.778s)
[165.399380] [032/132] (859s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0)
[167.400953] Starting subtest: linear-max-hw-stride-32bpp-rotate-0
[168.672075] Subtest linear-max-hw-stride-32bpp-rotate-0: SUCCESS (1.267s)
[168.917761] [033/132] (856s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted)
[168.962343] Starting subtest: invalid-multi-wait-all-unsubmitted
[168.963179] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[169.116286] [034/132] (856s left) gem_exec_fence (parallel)
[169.327252] Starting subtest: parallel
[169.328595] Starting dynamic subtest: rcs0
[171.482499] Dynamic subtest rcs0: SUCCESS (2.155s)
[171.491513] Starting dynamic subtest: bcs0
[173.636303] Dynamic subtest bcs0: SUCCESS (2.153s)
[173.645425] Starting dynamic subtest: vcs0
[175.787747] Dynamic subtest vcs0: SUCCESS (2.151s)
[175.797644] Starting dynamic subtest: vcs1
[177.935840] Dynamic subtest vcs1: SUCCESS (2.148s)
[177.945240] Starting dynamic subtest: vecs0
[180.088935] Dynamic subtest vecs0: SUCCESS (2.153s)
[180.090135] Subtest parallel: SUCCESS (10.762s)
[180.870072] [035/132] (844s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats)
[182.892758] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s)
[183.021486] [036/132] (842s left) syncobj_wait (single-wait-all-for-submit-unsubmitted)
[183.066680] Starting subtest: single-wait-all-for-submit-unsubmitted
[183.175615] Subtest single-wait-all-for-submit-unsubmitted: SUCCESS (0.105s)
[183.345052] [037/132] (842s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-pgflip-blt)
[185.336915] Starting subtest: psr-2p-scndscrn-indfb-pgflip-blt
[185.338681] Subtest psr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[185.564769] [038/132] (839s left) gem_render_copy (y-tiled-to-vebox-yf-tiled)
[185.634820] Starting subtest: y-tiled-to-vebox-yf-tiled
[185.635738] Starting dynamic subtest: smem
[185.721885] Dynamic subtest smem: SUCCESS (0.087s)
[185.730930] Starting dynamic subtest: lmem0
[186.486888] Dynamic subtest lmem0: SUCCESS (0.761s)
[186.488144] Subtest y-tiled-to-vebox-yf-tiled: SUCCESS (0.849s)
[186.776436] [039/132] (838s left) kms_plane_multiple (2x-tiling-4)
[188.760437] Starting subtest: 2x-tiling-4
[188.762252] Subtest 2x-tiling-4: SKIP (0.000s)
[188.975656] [040/132] (836s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-render)
[190.968519] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-render
[190.970165] Subtest psr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[191.198547] [041/132] (834s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[193.206948] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt
[193.208884] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[193.459308] [042/132] (832s left) kms_big_fb (4-tiled-32bpp-rotate-270)
[195.463989] Starting subtest: 4-tiled-32bpp-rotate-270
[195.466045] Subtest 4-tiled-32bpp-rotate-270: SKIP (0.000s)
[195.724714] [043/132] (830s left) kms_frontbuffer_tracking (fbcpsr-2p-pri-indfb-multidraw)
[197.714858] Starting subtest: fbcpsr-2p-pri-indfb-multidraw
[197.716803] Subtest fbcpsr-2p-pri-indfb-multidraw: SKIP (0.000s)
[197.957893] [044/132] (828s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[199.938515] Starting subtest: compare-crc-sanitycheck-nv12
[199.940378] Starting dynamic subtest: pipe-A-HDMI-A-3
[200.616572] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (0.676s)
[200.627864] Starting dynamic subtest: pipe-B-HDMI-A-3
[201.158210] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (0.539s)
[201.169304] Starting dynamic subtest: pipe-C-HDMI-A-3
[201.689297] Dynamic subtest pipe-C-HDMI-A-3: SUCCESS (0.529s)
[201.699348] Starting dynamic subtest: pipe-D-HDMI-A-3
[202.210279] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (0.520s)
[202.211244] Subtest compare-crc-sanitycheck-nv12: SUCCESS (2.272s)
[202.427389] [045/132] (824s left) kms_cdclk (plane-scaling)
[202.473886] Subtest plane-scaling: SKIP (0.000s)
[202.540266] [046/132] (823s left) gem_exec_fence (syncobj-wait)
[202.908484] Starting subtest: syncobj-wait
[202.960180] Subtest syncobj-wait: SUCCESS (0.051s)
[203.405512] [047/132] (823s left) kms_sharpness_filter (filter-dpms)
[205.379488] Starting subtest: filter-dpms
[205.384743] Subtest filter-dpms: SKIP (0.002s)
[205.599807] [048/132] (821s left) kms_pipe_b_c_ivb (disable-pipe-b-enable-pipe-c)
[205.644673] Subtest disable-pipe-B-enable-pipe-C: SKIP (0.000s)
[205.721889] [049/132] (821s left) kms_vrr (flip-basic-fastset)
[207.705563] Starting subtest: flip-basic-fastset
[207.708271] Subtest flip-basic-fastset: SKIP (0.000s)
[207.907332] [050/132] (818s left) api_intel_allocator (simple-allocator)
[207.952213] Starting subtest: simple-allocator
[207.953287] Starting dynamic subtest: basic
[207.969802] Dynamic subtest basic: SUCCESS (0.018s)
[207.978998] Starting dynamic subtest: parallel-one
[208.168325] Dynamic subtest parallel-one: SUCCESS (0.195s)
[208.178191] Starting dynamic subtest: print
[208.179306] Dynamic subtest print: SUCCESS (0.000s)
[208.189229] Starting dynamic subtest: reuse
[208.190410] Dynamic subtest reuse: SUCCESS (0.000s)
[208.200498] Starting dynamic subtest: reserve
[208.201748] Dynamic subtest reserve: SUCCESS (0.000s)
[208.211273] Starting dynamic subtest: fork-reopen-allocator
[208.212545] Dynamic subtest fork-reopen-allocator: SUCCESS (0.018s)
[208.213808] Subtest simple-allocator: SUCCESS (0.234s)
[208.421992] [051/132] (818s left) kms_getfb (getfb2-accept-nv12)
[210.406932] Starting subtest: getfb2-accept-nv12
[210.409275] Subtest getfb2-accept-nv12: SUCCESS (0.002s)
[210.690432] [052/132] (816s left) perf_pmu (busy-accuracy-50)
[210.741047] Starting subtest: busy-accuracy-50
[210.742043] Starting dynamic subtest: rcs0
[212.286323] Dynamic subtest rcs0: SUCCESS (1.545s)
[212.296518] Starting dynamic subtest: bcs0
[213.830495] Dynamic subtest bcs0: SUCCESS (1.544s)
[213.840501] Starting dynamic subtest: vcs0
[215.373653] Dynamic subtest vcs0: SUCCESS (1.542s)
[215.383489] Starting dynamic subtest: vcs1
[216.918856] Dynamic subtest vcs1: SUCCESS (1.545s)
[216.928943] Starting dynamic subtest: vecs0
[218.464577] Dynamic subtest vecs0: SUCCESS (1.545s)
[218.465492] Subtest busy-accuracy-50: SUCCESS (7.724s)
[218.668531] [053/132] (808s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[220.669680] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[220.671302] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[220.918324] [054/132] (806s left) gem_exec_reloc (basic-cpu-read)
[220.967285] Subtest basic-cpu-read: SKIP (0.000s)
[221.076770] [055/132] (806s left) gem_workarounds (basic-read-fd)
[221.143895] Starting subtest: basic-read-fd
[221.173584] Subtest basic-read-fd: SUCCESS (0.029s)
[221.352071] [056/132] (806s left) i915_pm_rpm (gem-execbuf)
[226.026322] Starting subtest: gem-execbuf
[226.027811] Starting dynamic subtest: lmem0
[230.553138] Dynamic subtest lmem0: SUCCESS (4.524s)
[230.604958] Starting dynamic subtest: smem0
[234.406884] Dynamic subtest smem0: SUCCESS (3.849s)
[234.409083] Subtest gem-execbuf: SUCCESS (8.379s)
[235.718583] [057/132] (791s left) perf_pmu (semaphore-wait)
[235.767850] Starting subtest: semaphore-wait
[235.768830] Starting dynamic subtest: rcs0
[236.298661] Dynamic subtest rcs0: SUCCESS (0.526s)
[236.309180] Starting dynamic subtest: bcs0
[236.811295] Dynamic subtest bcs0: SUCCESS (0.510s)
[236.821997] Starting dynamic subtest: vcs0
[237.322294] Dynamic subtest vcs0: SUCCESS (0.509s)
[237.332604] Starting dynamic subtest: vcs1
[237.836479] Dynamic subtest vcs1: SUCCESS (0.511s)
[237.846607] Starting dynamic subtest: vecs0
[238.350542] Dynamic subtest vecs0: SUCCESS (0.511s)
[238.351569] Subtest semaphore-wait: SUCCESS (2.581s)
[238.527633] [058/132] (789s left) gen7_exec_parse (basic-offset)
[238.577120] Subtest basic-offset: SKIP (0.000s)
[238.650668] [059/132] (789s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-plflip-blt)
[240.640575] Starting subtest: psr-1p-primscrn-indfb-plflip-blt
[240.642200] Subtest psr-1p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[240.891028] [060/132] (787s left) drm_read (invalid-buffer)
[242.941181] Starting subtest: invalid-buffer
[242.942130] Subtest invalid-buffer: SUCCESS (0.000s)
[243.199908] [061/132] (784s left) syncobj_wait (single-wait-for-submit-unsubmitted)
[243.245864] Starting subtest: single-wait-for-submit-unsubmitted
[243.351275] Subtest single-wait-for-submit-unsubmitted: SUCCESS (0.101s)
[243.525850] [062/132] (784s left) gem_gtt_cpu_tlb
[243.655955] [063/132] (784s left) kms_joiner (invalid-modeset-big-joiner)
[245.643928] Subtest invalid-modeset-big-joiner: SKIP (0.000s)
[245.857521] [064/132] (782s left) gem_exec_whisper (basic-fds-forked)
[245.915246] Starting subtest: basic-fds-forked
[259.313403] Subtest basic-fds-forked: SUCCESS (13.394s)
[259.616427] [065/132] (768s left) gem_eio (in-flight-immediate)
[259.806930] Starting subtest: in-flight-immediate
[264.263965] Subtest in-flight-immediate: SUCCESS (4.457s)
[266.437889] [066/132] (762s left) kms_cursor_crc (cursor-sliding-256x256)
[268.645776] Starting subtest: cursor-sliding-256x256
[268.652184] Starting dynamic subtest: pipe-A-HDMI-A-3
[273.064717] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (4.412s)
[273.099986] Starting dynamic subtest: pipe-D-HDMI-A-3
[277.394879] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (4.305s)
[277.395849] Subtest cursor-sliding-256x256: SUCCESS (8.749s)
[277.783981] [067/132] (750s left) kms_rotation_crc (primary-rotation-270)
[279.770685] Starting subtest: primary-rotation-270
[290.330928] Subtest primary-rotation-270: SUCCESS (10.559s)
[290.682227] [068/132] (738s left) gem_eio (wait-wedge-10ms)
[290.871684] Starting subtest: wait-wedge-10ms
[291.872246] Subtest wait-wedge-10ms: SUCCESS (1.000s)
[294.043725] [069/132] (734s left) gem_userptr_blits (vma-merge)
[294.113238] Starting subtest: vma-merge
[294.198882] Subtest vma-merge: SUCCESS (0.087s)
[294.403163] [070/132] (734s left) kms_psr (pr-cursor-plane-move)
[296.389093] Subtest pr-cursor-plane-move: SKIP (0.000s)
[296.473841] [071/132] (732s left) gem_eio (wait-10ms)
[296.662997] Starting subtest: wait-10ms
[297.184530] Subtest wait-10ms: SUCCESS (0.522s)
[299.324096] [072/132] (729s left) gem_ctx_param (non-root-set)
[299.368508] Starting subtest: non-root-set
[299.388646] Subtest non-root-set: SUCCESS (0.021s)
[299.586778] [073/132] (729s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-mc-ccs)
[301.568684] Starting subtest: crc-primary-suspend-4-tiled-mtl-mc-ccs
[301.570873] Starting dynamic subtest: pipe-A-HDMI-A-3
[301.574294] Dynamic subtest pipe-A-HDMI-A-3: SKIP (0.000s)
[301.585185] Starting dynamic subtest: pipe-B-HDMI-A-3
[301.588767] Dynamic subtest pipe-B-HDMI-A-3: SKIP (0.000s)
[301.599633] Starting dynamic subtest: pipe-C-HDMI-A-3
[301.602977] Dynamic subtest pipe-C-HDMI-A-3: SKIP (0.000s)
[301.613756] Starting dynamic subtest: pipe-D-HDMI-A-3
[301.617153] Dynamic subtest pipe-D-HDMI-A-3: SKIP (0.000s)
[301.618918] Subtest crc-primary-suspend-4-tiled-mtl-mc-ccs: SKIP (0.009s)
[301.881080] [074/132] (727s left) gem_exec_balancer (parallel-keep-submit-fence)
[302.292299] Starting subtest: parallel-keep-submit-fence
[304.570266] Subtest parallel-keep-submit-fence: SUCCESS (2.278s)
[304.734876] [075/132] (724s left) kms_psr2_sf (psr2-overlay-primary-update-sf-dmg-area)
[306.726862] Subtest psr2-overlay-primary-update-sf-dmg-area: SKIP (0.000s)
[306.804681] [076/132] (722s left) kms_vblank (wait-busy-hang)
[308.785606] Starting subtest: wait-busy-hang
[308.787897] Starting dynamic subtest: pipe-A-HDMI-A-3
[335.467936] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (26.679s)
[335.482249] Starting dynamic subtest: pipe-D-HDMI-A-3
[360.461911] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (24.988s)
[360.462887] Subtest wait-busy-hang: SUCCESS (51.675s)
[360.730360] [077/132] (668s left) gem_linear_blits (normal)
[361.144697] Starting subtest: normal
[369.928740] Subtest normal: SUCCESS (8.781s)
[370.566064] [078/132] (659s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[372.605734] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[372.608102] Starting dynamic subtest: pipe-A-valid-mode
[373.864239] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.256s)
[373.865118] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SUCCESS (1.259s)
[374.112674] [079/132] (655s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-5)
[376.092652] Starting subtest: planes-upscale-20x20-downscale-factor-0-5
[376.093609] Starting dynamic subtest: pipe-A
[376.257534] Dynamic subtest pipe-A: SUCCESS (0.164s)
[376.274543] Starting dynamic subtest: pipe-B
[376.417856] Dynamic subtest pipe-B: SUCCESS (0.159s)
[376.429315] Starting dynamic subtest: pipe-C
[376.559057] Dynamic subtest pipe-C: SUCCESS (0.140s)
[376.570450] Starting dynamic subtest: pipe-D
[376.700160] Dynamic subtest pipe-D: SUCCESS (0.140s)
[376.701089] Subtest planes-upscale-20x20-downscale-factor-0-5: SUCCESS (0.608s)
[376.912737] [080/132] (652s left) gem_ctx_param (invalid-get-engines)
[376.959133] Starting subtest: invalid-get-engines
[376.960163] Subtest invalid-get-engines: SUCCESS (0.001s)
[377.101426] [081/132] (652s left) kms_prime (basic-crc-vgem)
[379.117599] Subtest basic-crc-vgem: SKIP (0.000s)
[379.365137] [082/132] (650s left) gem_softpin (safe-alignment)
[379.417717] Starting subtest: safe-alignment
[379.460970] Subtest safe-alignment: SUCCESS (0.043s)
[379.696626] [083/132] (650s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy)
[381.690376] Starting subtest: fbcpsr-modesetfrombusy
[381.692214] Subtest fbcpsr-modesetfrombusy: SKIP (0.000s)
[381.950447] [084/132] (648s left) kms_psr (psr-cursor-plane-move)
[383.937094] Subtest psr-cursor-plane-move: SKIP (0.000s)
[384.018402] [085/132] (646s left) gem_ccs (block-copy-compressed)
[384.069465] Starting subtest: block-copy-compressed
[384.071189] Subtest block-copy-compressed: SKIP (0.000s)
[384.235290] [086/132] (646s left) kms_async_flips (async-flip-suspend-resume)
[386.219962] Starting subtest: async-flip-suspend-resume
[386.447573] Starting dynamic subtest: pipe-A-HDMI-A-3
[413.514296] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (11.185s)
[413.718122] Starting dynamic subtest: pipe-B-HDMI-A-3
[441.494760] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (10.684s)
[441.684901] Starting dynamic subtest: pipe-C-HDMI-A-3
[468.478489] Dynamic subtest pipe-C-HDMI-A-3: SUCCESS (10.577s)
[468.670036] Starting dynamic subtest: pipe-D-HDMI-A-3
[495.497045] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (10.619s)
[495.497951] Subtest async-flip-suspend-resume: SUCCESS (43.860s)
[501.928458] [087/132] (534s left) kms_pwrite_crc
[504.868379] [088/132] (531s left) kms_flip (dpms-vs-vblank-race-interruptible)
[506.862917] Starting subtest: dpms-vs-vblank-race-interruptible
[506.870554] Starting dynamic subtest: A-HDMI-A3
[509.871814] Dynamic subtest A-HDMI-A3: SUCCESS (3.000s)
[509.885418] Starting dynamic subtest: B-HDMI-A3
[512.841558] Dynamic subtest B-HDMI-A3: SUCCESS (2.968s)
[512.854863] Starting dynamic subtest: C-HDMI-A3
[515.814874] Dynamic subtest C-HDMI-A3: SUCCESS (2.972s)
[515.829968] Starting dynamic subtest: D-HDMI-A3
[518.744024] Dynamic subtest D-HDMI-A3: SUCCESS (2.927s)
[518.748230] Subtest dpms-vs-vblank-race-interruptible: SUCCESS (11.884s)
[519.028820] [089/132] (517s left) gem_render_copy_redux (flink)
[519.087245] Starting subtest: flink
[521.111819] Subtest flink: SUCCESS (2.024s)
[521.357266] [090/132] (515s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions-varying-size)
[523.350568] Starting subtest: short-flip-before-cursor-atomic-transitions-varying-size
[524.381162] Subtest short-flip-before-cursor-atomic-transitions-varying-size: SUCCESS (1.031s)
[524.633365] [091/132] (512s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-plflip-blt)
[526.624610] Starting subtest: fbc-2p-primscrn-shrfb-plflip-blt
[526.626406] Subtest fbc-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[526.870689] [092/132] (510s left) i915_suspend (basic-s3-without-i915)
[526.913354] Starting subtest: basic-s3-without-i915
[558.085892] Subtest basic-s3-without-i915: SUCCESS (14.923s)
[560.206080] [093/132] (479s left) kms_psr (pr-primary-mmap-gtt)
[563.090700] Subtest pr-primary-mmap-gtt: SKIP (0.000s)
[563.171109] [094/132] (476s left) kms_pm_rpm (fences-dpms)
[568.706369] Starting subtest: fences-dpms
[568.708790] Subtest fences-dpms: SKIP (0.000s)
[569.977524] [095/132] (469s left) gem_ctx_persistence (process)
[570.033145] Starting subtest: process
[570.227878] Subtest process: SUCCESS (0.193s)
[570.426222] [096/132] (469s left) gem_exec_balancer (hang)
[570.789878] Starting subtest: hang
[573.570543] Subtest hang: SUCCESS (2.785s)
[573.737277] [097/132] (465s left) gem_mmap_gtt (cpuset-big-copy-odd)
[573.783353] Subtest cpuset-big-copy-odd: SKIP (0.000s)
[573.897050] [098/132] (465s left) kms_psr (fbc-psr2-cursor-plane-move)
[575.882602] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s)
[575.964967] [099/132] (463s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[577.948182] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[577.950654] Starting dynamic subtest: pipe-A-valid-mode
[580.573304] Dynamic subtest pipe-A-valid-mode: SUCCESS (2.623s)
[580.574229] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SUCCESS (2.626s)
[580.814193] [100/132] (459s left) vgem_basic (debugfs)
[580.872782] Starting subtest: debugfs
[580.873646] Subtest debugfs: SUCCESS (0.001s)
[581.027304] [101/132] (458s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-pwrite)
[583.019103] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-pwrite
[583.021495] Subtest psr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[583.305170] [102/132] (456s left) sysfs_heartbeat_interval (precise)
[583.356753] Starting subtest: precise
[583.359352] Starting dynamic subtest: vecs0
[585.560263] Dynamic subtest vecs0: SUCCESS (2.202s)
[585.574344] Starting dynamic subtest: vcs0
[586.747429] Dynamic subtest vcs0: SUCCESS (1.186s)
[586.761506] Starting dynamic subtest: bcs0
[588.019326] Dynamic subtest bcs0: SUCCESS (1.270s)
[588.034214] Starting dynamic subtest: rcs0
[590.597965] Dynamic subtest rcs0: SUCCESS (2.577s)
[590.615858] Starting dynamic subtest: vcs1
[593.390401] Dynamic subtest vcs1: SUCCESS (2.789s)
[593.391293] Subtest precise: SUCCESS (10.034s)
[593.633489] [103/132] (446s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-blt)
[595.621296] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-blt
[595.623256] Subtest fbc-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[595.840593] [104/132] (444s left) gem_mmap_gtt (zero-extend)
[595.889024] Subtest zero-extend: SKIP (0.000s)
[595.967942] [105/132] (444s left) kms_atomic_interruptible (atomic-setmode)
[597.949706] Starting subtest: atomic-setmode
[597.952130] Starting dynamic subtest: pipe-A-HDMI-A-3
[604.178213] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (6.225s)
[604.179216] Subtest atomic-setmode: SUCCESS (6.228s)
[604.397628] [106/132] (436s left) kms_ccs (bad-pixel-format-y-tiled-gen12-mc-ccs)
[606.385130] Starting subtest: bad-pixel-format-y-tiled-gen12-mc-ccs
[606.387559] Starting dynamic subtest: pipe-A-HDMI-A-3
[606.395775] Dynamic subtest pipe-A-HDMI-A-3: SKIP (0.000s)
[606.408051] Starting dynamic subtest: pipe-B-HDMI-A-3
[606.416190] Dynamic subtest pipe-B-HDMI-A-3: SKIP (0.000s)
[606.428354] Starting dynamic subtest: pipe-C-HDMI-A-3
[606.437185] Dynamic subtest pipe-C-HDMI-A-3: SKIP (0.000s)
[606.449115] Starting dynamic subtest: pipe-D-HDMI-A-3
[606.457921] Dynamic subtest pipe-D-HDMI-A-3: SKIP (0.000s)
[606.459687] Subtest bad-pixel-format-y-tiled-gen12-mc-ccs: SKIP (0.011s)
[606.702166] [107/132] (433s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[608.693131] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc
[608.694722] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[608.958045] [108/132] (431s left) kms_flip (plain-flip-ts-check)
[610.946262] Starting subtest: plain-flip-ts-check
[610.952479] Starting dynamic subtest: A-HDMI-A3
[619.377099] Dynamic subtest A-HDMI-A3: SUCCESS (8.424s)
[619.392728] Starting dynamic subtest: B-HDMI-A3
[627.758884] Dynamic subtest B-HDMI-A3: SUCCESS (8.379s)
[627.773189] Starting dynamic subtest: C-HDMI-A3
[636.145279] Dynamic subtest C-HDMI-A3: SUCCESS (8.385s)
[636.160430] Starting dynamic subtest: D-HDMI-A3
[644.546228] Dynamic subtest D-HDMI-A3: SUCCESS (8.399s)
[644.548781] Subtest plain-flip-ts-check: SUCCESS (33.603s)
[644.841197] [109/132] (396s left) dmabuf (all-tests)
[644.886247] Starting subtest: all-tests
[644.887269] Starting dynamic subtest: sanitycheck
[644.985118] Dynamic subtest sanitycheck: SUCCESS (0.098s)
[645.014772] Starting dynamic subtest: dma_fence
[645.222425] Dynamic subtest dma_fence: SUCCESS (0.230s)
[645.266363] Starting dynamic subtest: dma_fence_chain
[655.931421] Dynamic subtest dma_fence_chain: SUCCESS (10.701s)
[655.983260] Starting dynamic subtest: dma_fence_unwrap
[656.028866] Dynamic subtest dma_fence_unwrap: SUCCESS (0.094s)
[656.044914] Starting dynamic subtest: dma_resv
[656.096956] Dynamic subtest dma_resv: SUCCESS (0.065s)
[656.100828] Subtest all-tests: SUCCESS (11.212s)
[656.259285] [110/132] (384s left) gem_pxp (hw-rejects-pxp-context)
[658.828770] Starting subtest: hw-rejects-pxp-context
[658.829855] Subtest hw-rejects-pxp-context: SUCCESS (0.002s)
[658.968702] [111/132] (382s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-pwrite)
[660.957862] Starting subtest: psr-2p-primscrn-cur-indfb-draw-pwrite
[660.959535] Subtest psr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[661.222680] [112/132] (380s left) kms_psr2_sf (fbc-psr2-primary-plane-update-sf-dmg-area)
[663.214346] Subtest fbc-psr2-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[663.282605] [113/132] (378s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[668.472442] Starting subtest: heavy-verify-multi-ccs
[668.473304] Starting dynamic subtest: lmem0
[668.474819] Dynamic subtest lmem0: SKIP (0.000s)
[668.476426] Subtest heavy-verify-multi-ccs: SKIP (0.002s)
[672.674067] [FACT gem_lmem_swapping (heavy-verify-multi-ccs)] deleted: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[672.675187] [FACT gem_lmem_swapping (heavy-verify-multi-ccs)] deleted: kernel.kmod_is_loaded.i915: true
[672.677428] [114/132] (368s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions)
[678.604593] Starting subtest: short-flip-after-cursor-atomic-transitions
[679.644730] Subtest short-flip-after-cursor-atomic-transitions: SUCCESS (1.040s)
[680.005198] [FACT kms_cursor_legacy (short-flip-after-cursor-atomic-transitions)] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[680.008428] [FACT kms_cursor_legacy (short-flip-after-cursor-atomic-transitions)] new: kernel.kmod_is_loaded.i915: true
[680.014805] [115/132] (361s left) kms_ccs (bad-aux-stride-y-tiled-gen12-rc-ccs)
[683.770059] Starting subtest: bad-aux-stride-y-tiled-gen12-rc-ccs
[683.772387] Starting dynamic subtest: pipe-A-HDMI-A-3
[683.779795] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (0.002s)
[683.791465] Starting dynamic subtest: pipe-B-HDMI-A-3
[683.798724] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (0.002s)
[683.810226] Starting dynamic subtest: pipe-C-HDMI-A-3
[683.817528] Dynamic subtest pipe-C-HDMI-A-3: SUCCESS (0.002s)
[683.829345] Starting dynamic subtest: pipe-D-HDMI-A-3
[683.837040] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (0.002s)
[683.837930] Subtest bad-aux-stride-y-tiled-gen12-rc-ccs: SUCCESS (0.017s)
[684.053189] [116/132] (357s left) kms_setmode (basic)
[684.099259] Starting subtest: basic
[684.191364] Starting dynamic subtest: pipe-A-HDMI-A-3
[686.307095] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (2.110s)
[686.395093] Starting dynamic subtest: pipe-B-HDMI-A-3
[688.497201] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (2.108s)
[688.533144] Subtest basic: SUCCESS (4.433s)
[688.781797] [117/132] (352s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[688.833208] Starting subtest: etime-multi-wait-all-for-submit-submitted
[688.938966] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.103s)
[689.152272] [118/132] (352s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[691.145710] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc
[691.147555] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[691.408500] [119/132] (350s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-mc-ccs)
[693.393169] Starting subtest: missing-ccs-buffer-y-tiled-gen12-mc-ccs
[693.395630] Starting dynamic subtest: pipe-A-HDMI-A-3
[693.404193] Dynamic subtest pipe-A-HDMI-A-3: SKIP (0.000s)
[693.415912] Starting dynamic subtest: pipe-B-HDMI-A-3
[693.424255] Dynamic subtest pipe-B-HDMI-A-3: SKIP (0.000s)
[693.436080] Starting dynamic subtest: pipe-C-HDMI-A-3
[693.444792] Dynamic subtest pipe-C-HDMI-A-3: SKIP (0.000s)
[693.456515] Starting dynamic subtest: pipe-D-HDMI-A-3
[693.465461] Dynamic subtest pipe-D-HDMI-A-3: SKIP (0.000s)
[693.467150] Subtest missing-ccs-buffer-y-tiled-gen12-mc-ccs: SKIP (0.010s)
[693.686175] [120/132] (348s left) gem_pread (bench)
[693.732771] Subtest bench: SKIP (0.000s)
[693.832395] [121/132] (348s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-render)
[695.824986] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-render
[695.826697] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[696.075988] [122/132] (346s left) kms_ccs (crc-primary-rotation-180-4-tiled-bmg-ccs)
[698.061264] Starting subtest: crc-primary-rotation-180-4-tiled-bmg-ccs
[698.063098] Subtest crc-primary-rotation-180-4-tiled-bmg-ccs: SKIP (0.000s)
[698.289543] [123/132] (344s left) gem_exec_capture (userptr)
[698.346411] Starting subtest: userptr
[699.216218] Subtest userptr: SUCCESS (0.867s)
[699.450697] [124/132] (342s left) kms_plane (plane-position-hole-dpms)
[701.443764] Starting subtest: plane-position-hole-dpms
[701.903398] Starting dynamic subtest: pipe-A-plane-1
[702.392782] Dynamic subtest pipe-A-plane-1: SUCCESS (0.490s)
[702.406404] Starting dynamic subtest: pipe-A-plane-2
[702.901345] Dynamic subtest pipe-A-plane-2: SUCCESS (0.507s)
[702.913358] Starting dynamic subtest: pipe-A-plane-3
[703.392193] Dynamic subtest pipe-A-plane-3: SUCCESS (0.490s)
[703.406178] Starting dynamic subtest: pipe-A-plane-4
[703.884703] Dynamic subtest pipe-A-plane-4: SUCCESS (0.491s)
[703.898710] Starting dynamic subtest: pipe-A-plane-5
[704.395775] Dynamic subtest pipe-A-plane-5: SUCCESS (0.508s)
[704.408149] Starting dynamic subtest: pipe-A-plane-6
[704.900611] Dynamic subtest pipe-A-plane-6: SUCCESS (0.504s)
[704.913053] Starting dynamic subtest: pipe-A-plane-7
[704.922680] Dynamic subtest pipe-A-plane-7: SUCCESS (0.020s)
[705.215230] Starting dynamic subtest: pipe-B-plane-1
[705.679184] Dynamic subtest pipe-B-plane-1: SUCCESS (0.476s)
[705.691567] Starting dynamic subtest: pipe-B-plane-2
[706.164924] Dynamic subtest pipe-B-plane-2: SUCCESS (0.483s)
[706.177427] Starting dynamic subtest: pipe-B-plane-3
[706.635977] Dynamic subtest pipe-B-plane-3: SUCCESS (0.470s)
[706.650101] Starting dynamic subtest: pipe-B-plane-4
[707.129799] Dynamic subtest pipe-B-plane-4: SUCCESS (0.492s)
[707.142142] Starting dynamic subtest: pipe-B-plane-5
[707.590359] Dynamic subtest pipe-B-plane-5: SUCCESS (0.460s)
[707.606855] Starting dynamic subtest: pipe-B-plane-6
[708.052253] Dynamic subtest pipe-B-plane-6: SUCCESS (0.459s)
[708.066501] Starting dynamic subtest: pipe-B-plane-7
[708.081000] Dynamic subtest pipe-B-plane-7: SUCCESS (0.028s)
[708.081830] Subtest plane-position-hole-dpms: SUCCESS (6.639s)
[708.358402] [125/132] (334s left) kms_invalid_mode (bad-hsync-start)
[710.349448] Starting subtest: bad-hsync-start
[710.351742] Starting dynamic subtest: pipe-A-HDMI-A-3
[710.395235] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (0.044s)
[710.408942] Starting dynamic subtest: pipe-B-HDMI-A-3
[710.410039] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (0.001s)
[710.422891] Starting dynamic subtest: pipe-C-HDMI-A-3
[710.423967] Dynamic subtest pipe-C-HDMI-A-3: SUCCESS (0.001s)
[710.435828] Starting dynamic subtest: pipe-D-HDMI-A-3
[710.436738] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (0.001s)
[710.437654] Subtest bad-hsync-start: SUCCESS (0.057s)
[710.643231] [126/132] (331s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted-signaled)
[710.688616] Starting subtest: multi-wait-all-for-submit-available-submitted-signaled
[710.689528] Subtest multi-wait-all-for-submit-available-submitted-signaled: SUCCESS (0.001s)
[710.850061] [127/132] (331s left) kms_psr (psr-sprite-render)
[712.835528] Subtest psr-sprite-render: SKIP (0.000s)
[712.912490] [128/132] (329s left) i915_query (query-topology-coherent-slice-mask)
[712.957184] Starting subtest: query-topology-coherent-slice-mask
[712.958242] Subtest query-topology-coherent-slice-mask: SUCCESS (0.000s)
[713.141601] [129/132] (329s left) i915_hangman (engine-engine-hang)
[713.200800] Starting subtest: engine-engine-hang
[713.201675] Starting dynamic subtest: rcs0
[713.917687] Dynamic subtest rcs0: SUCCESS (0.716s)
[713.938957] Starting dynamic subtest: bcs0
[714.595555] Dynamic subtest bcs0: SUCCESS (0.676s)
[714.610051] Starting dynamic subtest: vcs0
[715.264610] Dynamic subtest vcs0: SUCCESS (0.667s)
[715.280449] Starting dynamic subtest: vcs1
[715.931568] Dynamic subtest vcs1: SUCCESS (0.665s)
[715.946252] Starting dynamic subtest: vecs0
[716.606595] Dynamic subtest vecs0: SUCCESS (0.673s)
[716.607560] Subtest engine-engine-hang: SUCCESS (3.407s)
[716.795091] [130/132] (326s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[718.783839] Starting subtest: plane-use-after-nonblocking-unbind
[718.784809] Starting dynamic subtest: pipe-A-HDMI-A-3
[718.865980] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (0.081s)
[718.934728] Starting dynamic subtest: pipe-B-HDMI-A-3
[719.105959] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (0.183s)
[719.129214] Subtest plane-use-after-nonblocking-unbind: SUCCESS (0.346s)
[719.332713] [131/132] (323s left) prime_vgem (busy)
[719.417037] Starting subtest: busy
[719.418115] Starting dynamic subtest: rcs0
[719.503480] Dynamic subtest rcs0: SUCCESS (0.087s)
[719.519436] Starting dynamic subtest: bcs0
[719.557737] Dynamic subtest bcs0: SUCCESS (0.053s)
[719.574677] Starting dynamic subtest: vcs0
[719.600042] Dynamic subtest vcs0: SUCCESS (0.040s)
[719.621768] Starting dynamic subtest: vcs1
[719.642069] Dynamic subtest vcs1: SUCCESS (0.040s)
[719.663759] Starting dynamic subtest: vecs0
[719.684792] Dynamic subtest vecs0: SUCCESS (0.040s)
[719.685951] Subtest busy: SUCCESS (0.270s)
[719.908257] [132/132] (323s left) kms_flip (2x-plain-flip-ts-check)
[721.894726] Starting subtest: 2x-plain-flip-ts-check
[721.914521] Subtest 2x-plain-flip-ts-check: SKIP (0.020s)
[722.120625] Closing watchdogs
results: parsing output: 0/ for test: syncobj_timeline
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: gem_exec_schedule
results: parsing output: 3/ for test: syncobj_wait
results: parsing output: 4/ for test: gem_exec_params
results: parsing output: 5/ for test: kms_draw_crc
results: parsing output: 6/ for test: kms_content_protection
results: parsing output: 7/ for test: api_intel_bb
results: parsing output: 8/ for test: kms_flip
results: parsing output: 9/ for test: gem_mmap_gtt
results: parsing output: 10/ for test: kms_dirtyfb
results: parsing output: 11/ for test: kms_cursor_crc
results: parsing output: 12/ for test: kms_flip
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: kms_chamelium_hpd
results: parsing output: 15/ for test: kms_big_fb
results: parsing output: 16/ for test: gem_cs_tlb
results: parsing output: 17/ for test: kms_flip_scaled_crc
results: parsing output: 18/ for test: kms_big_fb
results: parsing output: 19/ for test: kms_psr2_su
results: parsing output: 20/ for test: syncobj_wait
results: parsing output: 21/ for test: gem_ctx_shared
results: parsing output: 22/ for test: kms_cursor_crc
results: parsing output: 23/ for test: sysfs_timeslice_duration
results: parsing output: 24/ for test: kms_big_fb
results: parsing output: 25/ for test: gem_eio
results: parsing output: 26/ for test: i915_drm_fdinfo
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: gem_exec_schedule
results: parsing output: 29/ for test: gem_exec_params
results: parsing output: 30/ for test: kms_atomic
results: parsing output: 31/ for test: kms_big_fb
results: parsing output: 32/ for test: syncobj_timeline
results: parsing output: 33/ for test: gem_exec_fence
results: parsing output: 34/ for test: kms_chamelium_frames
results: parsing output: 35/ for test: syncobj_wait
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: gem_render_copy
results: parsing output: 38/ for test: kms_plane_multiple
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: kms_big_fb
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: kms_pipe_crc_basic
results: parsing output: 44/ for test: kms_cdclk
results: parsing output: 45/ for test: gem_exec_fence
results: parsing output: 46/ for test: kms_sharpness_filter
results: parsing output: 47/ for test: kms_pipe_b_c_ivb
results: parsing output: 48/ for test: kms_vrr
results: parsing output: 49/ for test: api_intel_allocator
results: parsing output: 50/ for test: kms_getfb
results: parsing output: 51/ for test: perf_pmu
results: parsing output: 52/ for test: kms_big_fb
results: parsing output: 53/ for test: gem_exec_reloc
results: parsing output: 54/ for test: gem_workarounds
results: parsing output: 55/ for test: i915_pm_rpm
results: parsing output: 56/ for test: perf_pmu
results: parsing output: 57/ for test: gen7_exec_parse
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: drm_read
results: parsing output: 60/ for test: syncobj_wait
results: parsing output: 61/ for test: gem_gtt_cpu_tlb
results: parsing output: 62/ for test: kms_joiner
results: parsing output: 63/ for test: gem_exec_whisper
results: parsing output: 64/ for test: gem_eio
results: parsing output: 65/ for test: kms_cursor_crc
results: parsing output: 66/ for test: kms_rotation_crc
results: parsing output: 67/ for test: gem_eio
results: parsing output: 68/ for test: gem_userptr_blits
results: parsing output: 69/ for test: kms_psr
results: parsing output: 70/ for test: gem_eio
results: parsing output: 71/ for test: gem_ctx_param
results: parsing output: 72/ for test: kms_ccs
results: parsing output: 73/ for test: gem_exec_balancer
results: parsing output: 74/ for test: kms_psr2_sf
results: parsing output: 75/ for test: kms_vblank
results: parsing output: 76/ for test: gem_linear_blits
results: parsing output: 77/ for test: kms_flip_scaled_crc
results: parsing output: 78/ for test: kms_plane_scaling
results: parsing output: 79/ for test: gem_ctx_param
results: parsing output: 80/ for test: kms_prime
results: parsing output: 81/ for test: gem_softpin
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: kms_psr
results: parsing output: 84/ for test: gem_ccs
results: parsing output: 85/ for test: kms_async_flips
results: parsing output: 86/ for test: kms_pwrite_crc
results: parsing output: 87/ for test: kms_flip
results: parsing output: 88/ for test: gem_render_copy_redux
results: parsing output: 89/ for test: kms_cursor_legacy
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: i915_suspend
results: parsing output: 92/ for test: kms_psr
results: parsing output: 93/ for test: kms_pm_rpm
results: parsing output: 94/ for test: gem_ctx_persistence
results: parsing output: 95/ for test: gem_exec_balancer
results: parsing output: 96/ for test: gem_mmap_gtt
results: parsing output: 97/ for test: kms_psr
results: parsing output: 98/ for test: kms_flip_scaled_crc
results: parsing output: 99/ for test: vgem_basic
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: sysfs_heartbeat_interval
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: gem_mmap_gtt
results: parsing output: 104/ for test: kms_atomic_interruptible
results: parsing output: 105/ for test: kms_ccs
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_flip
results: parsing output: 108/ for test: dmabuf
results: parsing output: 109/ for test: gem_pxp
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_psr2_sf
results: parsing output: 112/ for test: gem_lmem_swapping
results: parsing output: 113/ for test: kms_cursor_legacy
results: parsing output: 114/ for test: kms_ccs
results: parsing output: 115/ for test: kms_setmode
results: parsing output: 116/ for test: syncobj_timeline
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_ccs
results: parsing output: 119/ for test: gem_pread
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_ccs
results: parsing output: 122/ for test: gem_exec_capture
results: parsing output: 123/ for test: kms_plane
results: parsing output: 124/ for test: kms_invalid_mode
results: parsing output: 125/ for test: syncobj_timeline
results: parsing output: 126/ for test: kms_psr
results: parsing output: 127/ for test: i915_query
results: parsing output: 128/ for test: i915_hangman
results: parsing output: 129/ for test: kms_atomic_transition
results: parsing output: 130/ for test: prime_vgem
results: parsing output: 131/ for test: kms_flip
Done.