[59.838538] Initializing watchdogs
[59.838584]   /dev/watchdog0
[59.853241] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[59.858110] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.860431] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.863780] [001/133] (960s left) gem_mmap_wc (copy)
[59.947797] Subtest copy: SKIP (0.000s)
[60.008113] [002/133] (960s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-render)
[60.107761] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-render
[60.111674] Subtest psr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[60.199452] [003/133] (960s left) kms_psr (psr-suspend)
[60.294840] Starting subtest: psr-suspend
[60.297099] Starting dynamic subtest: eDP-1
[78.509912] Dynamic subtest eDP-1: SUCCESS (3.453s)
[78.511937] Subtest psr-suspend: SUCCESS (3.453s)
[87.393515] [004/133] (941s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-onoff)
[87.498801] Starting subtest: fbc-2p-primscrn-spr-indfb-onoff
[87.503628] Subtest fbc-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[87.606463] [005/133] (940s left) kms_psr (fbc-pr-cursor-mmap-cpu)
[87.697651] Starting subtest: fbc-pr-cursor-mmap-cpu
[87.701602] Subtest fbc-pr-cursor-mmap-cpu: SKIP (0.000s)
[87.778610] [006/133] (940s left) template (b)
[87.827836] Starting subtest: B
[87.830053] Subtest B: SUCCESS (0.000s)
[87.917565] [007/133] (940s left) kms_lease (lease-unleased-connector)
[88.003996] Starting subtest: lease-unleased-connector
[88.006394] Starting dynamic subtest: pipe-A-eDP-1
[88.011520] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.007s)
[88.020249] Starting dynamic subtest: pipe-B-eDP-1
[88.022424] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.006s)
[88.030724] Starting dynamic subtest: pipe-C-eDP-1
[88.032909] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.007s)
[88.040489] Starting dynamic subtest: pipe-D-eDP-1
[88.042620] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.006s)
[88.044795] Subtest lease-unleased-connector: SUCCESS (0.030s)
[88.131470] [008/133] (940s left) kms_addfb_basic (invalid-set-prop)
[88.194145] Starting subtest: invalid-set-prop
[88.196190] Subtest invalid-set-prop: SUCCESS (0.000s)
[88.331743] [009/133] (940s left) kms_cursor_crc (cursor-onscreen-32x32)
[88.491285] Starting subtest: cursor-onscreen-32x32
[88.544812] Subtest cursor-onscreen-32x32: SKIP (0.050s)
[88.822978] [010/133] (939s left) gem_vm_create (create-ext)
[88.885511] Starting subtest: create-ext
[88.890576] Subtest create-ext: SUCCESS (0.005s)
[89.002329] [011/133] (939s left) kms_plane_lowres (tiling-yf)
[89.093261] Starting subtest: tiling-yf
[89.096957] Subtest tiling-yf: SKIP (0.000s)
[89.175137] [012/133] (939s left) kms_sequence (get-busy)
[89.259228] Starting subtest: get-busy
[89.261089] Starting dynamic subtest: pipe-A-eDP-1
[91.194721] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.933s)
[91.203594] Starting dynamic subtest: pipe-B-eDP-1
[93.776549] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.581s)
[93.785587] Starting dynamic subtest: pipe-C-eDP-1
[96.373548] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.596s)
[96.382992] Starting dynamic subtest: pipe-D-eDP-1
[98.996577] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.621s)
[98.998796] Subtest get-busy: SUCCESS (9.734s)
[99.795703] [013/133] (929s left) gem_ctx_param (invalid-set-ringsize)
[99.861882] Starting subtest: invalid-set-ringsize
[99.864225] Subtest invalid-set-ringsize: SUCCESS (0.000s)
[99.937906] [014/133] (928s left) syncobj_wait (invalid-signal-one-illegal-handle)
[99.987919] Starting subtest: invalid-signal-one-illegal-handle
[99.989872] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[100.084689] [015/133] (928s left) kms_chamelium_frames (dp-frame-dump)
[100.469084] Subtest dp-frame-dump: SKIP (0.000s)
[101.313669] [016/133] (927s left) kms_content_protection (dp-mst-type-0-hdcp14)
[101.447871] Starting subtest: dp-mst-type-0-hdcp14
[101.452660] Subtest dp-mst-type-0-hdcp14: SKIP (0.000s)
[101.571498] [017/133] (927s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render)
[101.672427] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render
[101.676605] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[101.759554] [018/133] (927s left) gem_pxp (create-valid-protected-context)
[101.875381] Starting subtest: create-valid-protected-context
[101.877752] Subtest create-valid-protected-context: SUCCESS (0.001s)
[101.981935] [019/133] (927s left) syncobj_timeline (single-wait-for-submit-signaled)
[102.035214] Starting subtest: single-wait-for-submit-signaled
[102.037599] Subtest single-wait-for-submit-signaled: SUCCESS (0.001s)
[102.121239] [020/133] (926s left) gem_exec_fence (syncobj-channel)
[102.578563] Starting subtest: syncobj-channel
[103.598314] Subtest syncobj-channel: SUCCESS (1.018s)
[103.885092] [FACT gem_exec_fence (syncobj-channel)] new: kernel.kmod_is_loaded.vgem: true
[103.888182] [021/133] (925s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-move)
[104.005926] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-move
[104.010024] Subtest fbcpsr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[104.087686] [022/133] (925s left) perf_pmu (busy-check-all)
[104.131264] Starting subtest: busy-check-all
[104.133576] Starting dynamic subtest: rcs0
[104.686561] Dynamic subtest rcs0: SUCCESS (0.555s)
[104.698001] Starting dynamic subtest: bcs0
[105.237520] Dynamic subtest bcs0: SUCCESS (0.549s)
[105.249036] Starting dynamic subtest: vcs0
[106.685958] Dynamic subtest vcs0: SUCCESS (1.446s)
[106.697694] Starting dynamic subtest: vcs1
[108.670453] Dynamic subtest vcs1: SUCCESS (1.983s)
[108.681774] Starting dynamic subtest: vecs0
[110.655517] Dynamic subtest vecs0: SUCCESS (1.984s)
[110.665809] Starting dynamic subtest: ccs0
[111.210570] Dynamic subtest ccs0: SUCCESS (0.556s)
[111.213194] Subtest busy-check-all: SUCCESS (7.079s)
[111.307125] [023/133] (917s left) api_intel_bb (blit-reloc-keep-cache)
[111.363400] Starting subtest: blit-reloc-keep-cache
[111.367780] Subtest blit-reloc-keep-cache: SKIP (0.001s)
[111.483836] [024/133] (917s left) gen9_exec_parse (unaligned-access)
[111.545230] Subtest unaligned-access: SKIP (0.000s)
[111.618325] [025/133] (917s left) gem_exec_flush (basic-wb-ro-default)
[111.688771] Starting subtest: basic-wb-ro-default
[117.161887] Subtest basic-wb-ro-default: SUCCESS (5.472s)
[117.353756] [026/133] (911s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[117.410885] Starting subtest: etime-single-wait-all-available-unsubmitted
[117.514539] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.102s)
[117.610617] [027/133] (911s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[117.707280] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[126.447993] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.744s)
[126.585890] [028/133] (902s left) kms_cursor_legacy (torture-move)
[126.715866] Starting subtest: torture-move
[126.718174] Starting dynamic subtest: pipe-A
[132.473673] Dynamic subtest pipe-A: SUCCESS (5.753s)
[132.499773] Starting dynamic subtest: pipe-B
[139.980547] Dynamic subtest pipe-B: SUCCESS (7.507s)
[139.991652] Starting dynamic subtest: pipe-C
[146.583795] Dynamic subtest pipe-C: SUCCESS (6.601s)
[146.592667] Starting dynamic subtest: pipe-D
[155.305877] Dynamic subtest pipe-D: SUCCESS (8.721s)
[155.317009] Starting dynamic subtest: all-pipes
[163.248127] Dynamic subtest all-pipes: SUCCESS (7.940s)
[163.250497] Subtest torture-move: SUCCESS (36.527s)
[163.407383] [029/133] (866s left) syncobj_wait (invalid-wait-illegal-handle)
[163.491399] Starting subtest: invalid-wait-illegal-handle
[163.493825] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[163.590406] [030/133] (865s left) syncobj_timeline (wait-any-snapshot)
[163.638740] Starting subtest: wait-any-snapshot
[163.721089] Subtest wait-any-snapshot: SUCCESS (0.083s)
[163.809478] [031/133] (865s left) gem_mmap_gtt (basic-small-bo)
[163.868308] Subtest basic-small-bo: SKIP (0.000s)
[163.936451] [032/133] (865s left) kms_ccs (bad-aux-stride-4-tiled-mtl-rc-ccs-cc)
[164.021894] Starting subtest: bad-aux-stride-4-tiled-mtl-rc-ccs-cc
[164.024315] Starting dynamic subtest: pipe-A-eDP-1
[164.045425] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[164.057367] Starting dynamic subtest: pipe-B-eDP-1
[164.076606] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[164.084914] Starting dynamic subtest: pipe-C-eDP-1
[164.103680] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[164.111533] Starting dynamic subtest: pipe-D-eDP-1
[164.130041] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[164.131851] Subtest bad-aux-stride-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.007s)
[166.329109] [033/133] (863s left) kms_frontbuffer_tracking (psr-rgb565-draw-render)
[166.438930] Starting subtest: psr-rgb565-draw-render
[173.527214] Subtest psr-rgb565-draw-render: SUCCESS (7.091s)
[173.650423] [034/133] (856s left) kms_vblank (wait-busy)
[173.737235] Starting subtest: wait-busy
[173.739160] Starting dynamic subtest: pipe-A-eDP-1
[175.612662] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.875s)
[175.627595] Starting dynamic subtest: pipe-D-eDP-1
[178.187482] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.573s)
[178.189480] Subtest wait-busy: SUCCESS (4.451s)
[178.991272] [035/133] (850s left) kms_psr (fbc-psr-primary-mmap-gtt)
[179.084401] Starting subtest: fbc-psr-primary-mmap-gtt
[179.086608] Starting dynamic subtest: eDP-1
[179.090961] Dynamic subtest eDP-1: SKIP (0.000s)
[179.095274] Subtest fbc-psr-primary-mmap-gtt: SKIP (0.001s)
[179.156319] [036/133] (850s left) kms_getfb (getfb2-handle-zero)
[179.246294] Starting subtest: getfb2-handle-zero
[179.248591] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[179.342905] [037/133] (850s left) gem_exec_params (rs-invalid)
[179.400202] Starting subtest: rs-invalid
[179.405281] Subtest rs-invalid: SUCCESS (0.003s)
[179.549373] [038/133] (850s left) i915_module_load (resize-bar)
[179.587642] Starting subtest: resize-bar
[179.594405] Subtest resize-bar: SKIP (0.006s)
[179.634199] [039/133] (850s left) gem_exec_params (secure-non-root)
[179.692035] Starting subtest: secure-non-root
[179.696253] Subtest secure-non-root: SKIP (0.000s)
[179.836815] [040/133] (850s left) kms_frontbuffer_tracking (fbc-modesetfrombusy)
[179.941092] Starting subtest: fbc-modesetfrombusy
[183.669709] Subtest fbc-modesetfrombusy: SUCCESS (3.731s)
[184.877317] [041/133] (845s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-rc-ccs)
[184.962385] Starting subtest: missing-ccs-buffer-y-tiled-gen12-rc-ccs
[184.964774] Starting dynamic subtest: pipe-A-eDP-1
[184.988635] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[184.999985] Starting dynamic subtest: pipe-B-eDP-1
[185.022107] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[185.030330] Starting dynamic subtest: pipe-C-eDP-1
[185.052149] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[185.060743] Starting dynamic subtest: pipe-D-eDP-1
[185.083105] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[185.087249] Subtest missing-ccs-buffer-y-tiled-gen12-rc-ccs: SKIP (0.003s)
[187.221571] [042/133] (842s left) gem_ctx_engines (invalid-engines)
[187.279920] Starting subtest: invalid-engines
[187.285691] Subtest invalid-engines: SUCCESS (0.008s)
[187.510547] [043/133] (842s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[187.577471] Starting subtest: mixed-tiled-to-y-tiled-ccs
[187.582120] Subtest mixed-tiled-to-y-tiled-ccs: SKIP (0.000s)
[187.759968] [044/133] (842s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[187.860263] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[188.938610] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.079s)
[189.072100] [045/133] (841s left) gem_exec_whisper (basic-queues-forked)
[189.152420] Starting subtest: basic-queues-forked
[209.881190] Subtest basic-queues-forked: SUCCESS (20.725s)
[210.066674] [046/133] (820s left) kms_chamelium_color (ctm-0-75)
[210.167508] Subtest ctm-0-75: SKIP (0.000s)
[210.214649] [047/133] (820s left) kms_big_fb (y-tiled-32bpp-rotate-90)
[210.312373] Starting subtest: y-tiled-32bpp-rotate-90
[210.316428] Subtest y-tiled-32bpp-rotate-90: SKIP (0.000s)
[210.402622] [048/133] (819s left) gem_exec_schedule (preempt-contexts)
[210.469934] Starting subtest: preempt-contexts
[210.472081] Starting dynamic subtest: rcs0
[210.520710] Dynamic subtest rcs0: SUCCESS (0.057s)
[210.530617] Starting dynamic subtest: bcs0
[210.600033] Dynamic subtest bcs0: SUCCESS (0.079s)
[210.611056] Starting dynamic subtest: vcs0
[210.651563] Dynamic subtest vcs0: SUCCESS (0.051s)
[210.662850] Starting dynamic subtest: vcs1
[210.699210] Dynamic subtest vcs1: SUCCESS (0.047s)
[210.709500] Starting dynamic subtest: vecs0
[210.748376] Dynamic subtest vecs0: SUCCESS (0.048s)
[210.757843] Starting dynamic subtest: ccs0
[210.802166] Dynamic subtest ccs0: SUCCESS (0.052s)
[210.804465] Subtest preempt-contexts: SUCCESS (0.338s)
[212.761934] [049/133] (817s left) syncobj_timeline (invalid-signal-illegal-handle)
[212.828401] Starting subtest: invalid-signal-illegal-handle
[212.830777] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[212.926252] [050/133] (817s left) kms_psr (pr-sprite-mmap-gtt)
[213.021888] Starting subtest: pr-sprite-mmap-gtt
[213.026448] Subtest pr-sprite-mmap-gtt: SKIP (0.000s)
[213.110877] [051/133] (817s left) kms_psr (psr2-primary-blt)
[213.192538] Starting subtest: psr2-primary-blt
[213.194807] Starting dynamic subtest: eDP-1
[214.891575] Dynamic subtest eDP-1: SUCCESS (1.698s)
[214.893900] Subtest psr2-primary-blt: SUCCESS (1.699s)
[215.753182] [052/133] (814s left) kms_rotation_crc (primary-rotation-180)
[215.836139] Starting subtest: primary-rotation-180
[222.834653] Subtest primary-rotation-180: SUCCESS (7.000s)
[224.037088] [053/133] (806s left) gem_exec_parallel (contexts)
[224.097934] Starting subtest: contexts
[224.100454] Starting dynamic subtest: rcs0
[227.055866] Dynamic subtest rcs0: SUCCESS (2.956s)
[227.065373] Starting dynamic subtest: bcs0
[230.443162] Dynamic subtest bcs0: SUCCESS (3.385s)
[230.455354] Starting dynamic subtest: vcs0
[234.214650] Dynamic subtest vcs0: SUCCESS (3.769s)
[234.225855] Starting dynamic subtest: vcs1
[238.169501] Dynamic subtest vcs1: SUCCESS (3.953s)
[238.179709] Starting dynamic subtest: vecs0
[242.222979] Dynamic subtest vecs0: SUCCESS (4.051s)
[242.233333] Starting dynamic subtest: ccs0
[245.909505] Dynamic subtest ccs0: SUCCESS (3.684s)
[245.912451] Subtest contexts: SUCCESS (21.810s)
[246.113231] [054/133] (784s left) gen9_exec_parse (cmd-crossing-page)
[246.171341] Subtest cmd-crossing-page: SKIP (0.000s)
[246.240228] [055/133] (784s left) i915_query (engine-info-invalid)
[246.287254] Starting subtest: engine-info-invalid
[246.289213] Subtest engine-info-invalid: SUCCESS (0.000s)
[246.373433] [056/133] (784s left) kms_chamelium_hpd (vga-hpd-fast)
[246.763077] Subtest vga-hpd-fast: SKIP (0.000s)
[247.554973] [057/133] (783s left) kms_addfb_basic (small-bo)
[247.644102] Starting subtest: small-bo
[247.646289] Subtest small-bo: SUCCESS (0.000s)
[247.752072] [058/133] (783s left) gem_userptr_blits (relocations)
[247.813037] Starting subtest: relocations
[247.817700] Subtest relocations: SKIP (0.000s)
[247.945037] [059/133] (782s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted-signal
[247.997495] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted-signaled
[248.106028] Subtest etime-multi-wait-all-available-unsubmitted-submitted-signaled: SUCCESS (0.108s)
[248.181262] [060/133] (782s left) gem_ctx_persistence (saturated-hostile)
[248.230390] Starting subtest: saturated-hostile
[248.232829] Starting dynamic subtest: rcs0
[249.054375] Dynamic subtest rcs0: SUCCESS (0.822s)
[249.066189] Starting dynamic subtest: bcs0
[249.850778] Dynamic subtest bcs0: SUCCESS (0.796s)
[249.861194] Starting dynamic subtest: vcs0
[250.642650] Dynamic subtest vcs0: SUCCESS (0.791s)
[250.654890] Starting dynamic subtest: vcs1
[251.434712] Dynamic subtest vcs1: SUCCESS (0.791s)
[251.446524] Starting dynamic subtest: vecs0
[252.243490] Dynamic subtest vecs0: SUCCESS (0.804s)
[252.254285] Starting dynamic subtest: ccs0
[253.081902] Dynamic subtest ccs0: SUCCESS (0.840s)
[253.085194] Subtest saturated-hostile: SUCCESS (4.852s)
[253.163777] [061/133] (777s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[253.270741] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[253.274746] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[253.365612] [062/133] (777s left) kms_async_flips (crc)
[253.447097] Starting subtest: crc
[253.834025] Starting dynamic subtest: pipe-A-eDP-1
[256.798631] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.963s)
[257.185237] Starting dynamic subtest: pipe-B-eDP-1
[260.130501] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.957s)
[260.528289] Starting dynamic subtest: pipe-C-eDP-1
[263.444984] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.927s)
[263.820727] Starting dynamic subtest: pipe-D-eDP-1
[266.671000] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.859s)
[266.673351] Subtest crc: SUCCESS (13.226s)
[267.834914] [063/133] (763s left) gem_exec_reloc (basic-wc-gtt)
[267.920456] Subtest basic-wc-gtt: SKIP (0.000s)
[267.980952] [064/133] (763s left) gem_exec_reloc (basic-cpu-gtt)
[268.045777] Subtest basic-cpu-gtt: SKIP (0.000s)
[268.115525] [065/133] (762s left) kms_hdr (static-swap)
[268.219379] Starting subtest: static-swap
[268.225738] Subtest static-swap: SKIP (0.000s)
[268.295291] [066/133] (762s left) gem_lmem_swapping (smem-oom)
[268.340402] Subtest smem-oom: SKIP (0.000s)
[268.394449] [067/133] (762s left) kms_async_flips (test-cursor)
[268.491046] Starting subtest: test-cursor
[268.866782] Starting dynamic subtest: pipe-A-eDP-1
[269.636039] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.771s)
[270.031001] Starting dynamic subtest: pipe-B-eDP-1
[270.763044] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.742s)
[271.130007] Starting dynamic subtest: pipe-C-eDP-1
[271.891830] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.769s)
[272.258183] Starting dynamic subtest: pipe-D-eDP-1
[273.018470] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.769s)
[273.021572] Subtest test-cursor: SUCCESS (4.529s)
[274.183276] [068/133] (757s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-msflip-blt)
[274.287921] Starting subtest: psr-1p-primscrn-shrfb-msflip-blt
[281.679281] Subtest psr-1p-primscrn-shrfb-msflip-blt: SUCCESS (7.394s)
[281.811807] [069/133] (749s left) gem_softpin (allocator-basic-reserve)
[281.865670] Starting subtest: allocator-basic-reserve
[281.934975] Subtest allocator-basic-reserve: SUCCESS (0.069s)
[282.079372] [070/133] (749s left) prime_vgem (sync)
[282.142442] Starting subtest: sync
[282.144715] Starting dynamic subtest: rcs0
[282.195887] Dynamic subtest rcs0: SUCCESS (0.053s)
[282.205491] Starting dynamic subtest: bcs0
[282.259987] Dynamic subtest bcs0: SUCCESS (0.063s)
[282.269401] Starting dynamic subtest: vcs0
[282.314251] Dynamic subtest vcs0: SUCCESS (0.053s)
[282.323947] Starting dynamic subtest: vcs1
[282.368205] Dynamic subtest vcs1: SUCCESS (0.053s)
[282.377743] Starting dynamic subtest: vecs0
[282.415125] Dynamic subtest vecs0: SUCCESS (0.046s)
[282.424769] Starting dynamic subtest: ccs0
[282.465058] Dynamic subtest ccs0: SUCCESS (0.049s)
[282.467387] Subtest sync: SUCCESS (0.324s)
[282.573265] [071/133] (748s left) gem_userptr_blits (sync-unmap-cycles)
[282.632636] Starting subtest: sync-unmap-cycles
[288.004776] Subtest sync-unmap-cycles: SUCCESS (5.372s)
[288.129803] [072/133] (743s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[288.250534] Starting subtest: basic-busy-flip-before-cursor-atomic
[288.254193] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[288.394798] [073/133] (743s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions)
[288.516526] Starting subtest: short-flip-before-cursor-atomic-transitions
[289.475737] Subtest short-flip-before-cursor-atomic-transitions: SUCCESS (0.959s)
[290.329532] [074/133] (741s left) kms_feature_discovery (display)
[290.430197] Starting subtest: display
[290.432389] Subtest display: SUCCESS (0.000s)
[290.506987] [075/133] (740s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[290.588270] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[290.590458] Starting dynamic subtest: pipe-A-default-mode
[293.368513] Dynamic subtest pipe-A-default-mode: SUCCESS (2.778s)
[293.370455] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SUCCESS (2.780s)
[294.239502] [076/133] (737s left) gem_ctx_shared (q-smoketest-all)
[294.302043] Starting subtest: Q-smoketest-all
[326.698663] Subtest Q-smoketest-all: SUCCESS (32.347s)
[327.730583] [077/133] (703s left) gem_mmap_gtt (basic-wc)
[327.807341] Subtest basic-wc: SKIP (0.000s)
[327.870167] [078/133] (703s left) kms_big_fb (yf-tiled-8bpp-rotate-180)
[327.971601] Starting subtest: yf-tiled-8bpp-rotate-180
[327.975763] Subtest yf-tiled-8bpp-rotate-180: SKIP (0.000s)
[328.068276] [079/133] (703s left) gem_exec_suspend (basic-s4-devices)
[328.137509] Starting subtest: basic-S4-devices
[328.139774] Starting dynamic subtest: smem
[339.646257] Dynamic subtest smem: SUCCESS (11.509s)
[339.648949] Subtest basic-S4-devices: SUCCESS (11.511s)
[348.857237] [080/133] (691s left) kms_plane (plane-position-hole)
[348.969954] Starting subtest: plane-position-hole
[349.486708] Starting dynamic subtest: pipe-A-plane-1
[350.763621] Dynamic subtest pipe-A-plane-1: SUCCESS (1.280s)
[350.774444] Starting dynamic subtest: pipe-A-plane-2
[352.094783] Dynamic subtest pipe-A-plane-2: SUCCESS (1.329s)
[352.104707] Starting dynamic subtest: pipe-A-plane-3
[353.424624] Dynamic subtest pipe-A-plane-3: SUCCESS (1.327s)
[353.434811] Starting dynamic subtest: pipe-A-plane-4
[354.757252] Dynamic subtest pipe-A-plane-4: SUCCESS (1.332s)
[354.768623] Starting dynamic subtest: pipe-A-plane-5
[354.785047] Dynamic subtest pipe-A-plane-5: SUCCESS (0.027s)
[356.006856] Starting dynamic subtest: pipe-B-plane-1
[357.341842] Dynamic subtest pipe-B-plane-1: SUCCESS (1.344s)
[357.356193] Starting dynamic subtest: pipe-B-plane-2
[358.664971] Dynamic subtest pipe-B-plane-2: SUCCESS (1.320s)
[358.687077] Starting dynamic subtest: pipe-B-plane-3
[359.980881] Dynamic subtest pipe-B-plane-3: SUCCESS (1.317s)
[359.993163] Starting dynamic subtest: pipe-B-plane-4
[361.259156] Dynamic subtest pipe-B-plane-4: SUCCESS (1.277s)
[361.276635] Starting dynamic subtest: pipe-B-plane-5
[361.284695] Dynamic subtest pipe-B-plane-5: SUCCESS (0.024s)
[361.287040] Subtest plane-position-hole: SUCCESS (12.314s)
[362.129515] [081/133] (678s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-render)
[362.226613] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-render
[369.099175] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-render: SUCCESS (6.874s)
[369.238598] [082/133] (671s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc)
[369.331451] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc
[369.333768] Starting dynamic subtest: pipe-A-eDP-1
[369.357636] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[369.370281] Starting dynamic subtest: pipe-B-eDP-1
[369.391779] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[369.401050] Starting dynamic subtest: pipe-C-eDP-1
[369.422910] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[369.431902] Starting dynamic subtest: pipe-D-eDP-1
[369.453413] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[369.457704] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[371.622403] [083/133] (669s left) kms_cursor_crc (cursor-sliding-128x128)
[371.846309] Starting subtest: cursor-sliding-128x128
[371.848526] Starting dynamic subtest: pipe-A-eDP-1
[375.093563] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.244s)
[375.133838] Starting dynamic subtest: pipe-D-eDP-1
[379.018243] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.893s)
[379.020595] Subtest cursor-sliding-128x128: SUCCESS (7.174s)
[379.879510] [084/133] (661s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-sf)
[379.991163] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-sf
[379.993505] Starting dynamic subtest: pipe-A-eDP-1
[380.087741] Dynamic subtest pipe-A-eDP-1: SKIP (0.100s)
[380.100165] Starting dynamic subtest: pipe-B-eDP-1
[381.258847] Dynamic subtest pipe-B-eDP-1: SKIP (1.168s)
[381.263114] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-sf: SKIP (1.271s)
[382.445016] [085/133] (658s left) kms_addfb_basic (no-handle)
[382.519806] Starting subtest: no-handle
[382.521786] Subtest no-handle: SUCCESS (0.000s)
[382.669916] [086/133] (658s left) gem_mmap_gtt (big-copy)
[382.731094] Subtest big-copy: SKIP (0.000s)
[382.801560] [087/133] (658s left) kms_color (ctm-max)
[382.891608] Starting subtest: ctm-max
[382.893544] Starting dynamic subtest: pipe-A-eDP-1
[383.597685] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.708s)
[383.607859] Starting dynamic subtest: pipe-B-eDP-1
[385.068883] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.470s)
[385.079023] Starting dynamic subtest: pipe-C-eDP-1
[386.436410] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.367s)
[386.447446] Starting dynamic subtest: pipe-D-eDP-1
[387.763894] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.327s)
[387.766193] Subtest ctm-max: SUCCESS (4.874s)
[388.577289] [088/133] (652s left) gem_workarounds (reset-context)
[388.644438] Starting subtest: reset-context
[388.834461] Subtest reset-context: SUCCESS (0.190s)
[388.973597] [089/133] (652s left) kms_big_fb (4-tiled-32bpp-rotate-270)
[389.077225] Starting subtest: 4-tiled-32bpp-rotate-270
[389.096882] Subtest 4-tiled-32bpp-rotate-270: SKIP (0.019s)
[389.215673] [090/133] (651s left) i915_pm_rps (engine-order)
[389.281426] Starting subtest: engine-order
[395.783073] Subtest engine-order: SUCCESS (6.502s)
[395.922033] [091/133] (645s left) gem_exec_whisper (basic-contexts-all)
[395.991773] Starting subtest: basic-contexts-all
[405.704440] Subtest basic-contexts-all: SUCCESS (9.708s)
[405.890161] [092/133] (635s left) i915_query (query-topology-unsupported)
[405.941581] Starting subtest: query-topology-unsupported
[405.946146] Subtest query-topology-unsupported: SKIP (0.000s)
[406.036543] [093/133] (635s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[406.099342] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[406.103508] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[406.302172] [094/133] (635s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[406.401419] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt
[406.405383] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[406.489000] [095/133] (634s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[406.579360] Starting subtest: read-crc-frame-sequence
[406.581625] Starting dynamic subtest: pipe-A-eDP-1
[407.292423] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.713s)
[407.303863] Starting dynamic subtest: pipe-B-eDP-1
[408.641339] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.346s)
[408.652786] Starting dynamic subtest: pipe-C-eDP-1
[409.945353] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.301s)
[409.957084] Starting dynamic subtest: pipe-D-eDP-1
[411.278803] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.333s)
[411.281018] Subtest read-crc-frame-sequence: SUCCESS (4.699s)
[412.112580] [096/133] (629s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-blt)
[412.216190] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-blt
[419.534520] Subtest psr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.323s)
[419.648738] [097/133] (621s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[419.746333] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[419.750796] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.000s)
[419.828900] [098/133] (621s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[419.932445] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[419.934778] Starting dynamic subtest: pipe-A
[419.954770] Dynamic subtest pipe-A: SKIP (0.019s)
[419.965430] Starting dynamic subtest: pipe-B
[419.989496] Dynamic subtest pipe-B: SKIP (0.033s)
[419.999898] Starting dynamic subtest: pipe-C
[420.022776] Dynamic subtest pipe-C: SKIP (0.032s)
[420.033155] Starting dynamic subtest: pipe-D
[420.053284] Dynamic subtest pipe-D: SKIP (0.032s)
[420.057558] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SKIP (0.117s)
[420.162500] [099/133] (621s left) kms_frontbuffer_tracking (fbc-rgb565-draw-pwrite)
[420.277378] Starting subtest: fbc-rgb565-draw-pwrite
[424.903170] Subtest fbc-rgb565-draw-pwrite: SUCCESS (4.628s)
[426.066588] [100/133] (615s left) kms_psr (fbc-psr2-cursor-plane-move)
[426.154138] Starting subtest: fbc-psr2-cursor-plane-move
[426.156273] Starting dynamic subtest: eDP-1
[426.160233] Dynamic subtest eDP-1: SKIP (0.000s)
[426.164201] Subtest fbc-psr2-cursor-plane-move: SKIP (0.001s)
[426.242994] [101/133] (615s left) perf (non-system-wide-paranoid)
[426.359207] Starting subtest: non-system-wide-paranoid
[426.389647] Subtest non-system-wide-paranoid: SUCCESS (0.032s)
[426.442984] [102/133] (615s left) kms_pm_rpm (modeset-lpsp-stress-no-wait)
[427.974504] Starting subtest: modeset-lpsp-stress-no-wait
[438.137206] Subtest modeset-lpsp-stress-no-wait: SUCCESS (10.162s)
[438.242054] [103/133] (603s left) fbdev (write)
[438.286521] Starting subtest: write
[438.862199] Subtest write: SUCCESS (0.574s)
[438.928481] [104/133] (602s left) gem_exec_create (basic)
[438.999279] Starting subtest: basic
[439.001719] Starting dynamic subtest: smem
[442.624178] Dynamic subtest smem: SUCCESS (3.622s)
[442.627169] Subtest basic: SUCCESS (3.624s)
[442.815200] [105/133] (599s left) gem_mmap_offset (bad-flags)
[442.877367] Starting subtest: bad-flags
[442.879714] Subtest bad-flags: SUCCESS (0.000s)
[442.990571] [106/133] (598s left) api_intel_bb (simple-bb)
[443.046570] Starting subtest: simple-bb
[443.053464] Subtest simple-bb: SUCCESS (0.009s)
[443.182419] [107/133] (598s left) kms_flip (flip-vs-dpms-on-nop-interruptible)
[443.282551] Starting subtest: flip-vs-dpms-on-nop-interruptible
[443.284828] Starting dynamic subtest: A-eDP1
[445.081471] Dynamic subtest A-eDP1: SUCCESS (1.798s)
[445.096719] Starting dynamic subtest: B-eDP1
[446.914715] Dynamic subtest B-eDP1: SUCCESS (1.831s)
[446.930101] Starting dynamic subtest: C-eDP1
[448.740905] Dynamic subtest C-eDP1: SUCCESS (1.825s)
[448.751678] Starting dynamic subtest: D-eDP1
[450.574448] Dynamic subtest D-eDP1: SUCCESS (1.833s)
[450.576939] Subtest flip-vs-dpms-on-nop-interruptible: SUCCESS (7.295s)
[451.756087] [108/133] (590s left) gem_exec_reloc (basic-cpu)
[451.822651] Subtest basic-cpu: SKIP (0.000s)
[451.888322] [109/133] (590s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[451.985387] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[452.831812] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (0.848s)
[453.000076] [110/133] (589s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-submitted-signaled)
[453.061824] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[453.063904] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[453.167105] [111/133] (588s left) gem_mmap_gtt (basic-read-write)
[453.233681] Subtest basic-read-write: SKIP (0.000s)
[453.293283] [112/133] (588s left) kms_pm_dc (dc3co-vpb-simulation)
[454.396931] Starting subtest: dc3co-vpb-simulation
[463.407983] Subtest dc3co-vpb-simulation: SKIP (9.007s)
[463.552889] [113/133] (578s left) kms_cursor_crc (cursor-sliding-32x32)
[463.689216] Starting subtest: cursor-sliding-32x32
[463.736990] Subtest cursor-sliding-32x32: SKIP (0.043s)
[464.035064] [114/133] (578s left) kms_flip (plain-flip-ts-check-interruptible)
[464.121368] Starting subtest: plain-flip-ts-check-interruptible
[464.123667] Starting dynamic subtest: A-eDP1
[472.953672] Dynamic subtest A-eDP1: SUCCESS (8.831s)
[472.965920] Starting dynamic subtest: B-eDP1
[481.787201] Dynamic subtest B-eDP1: SUCCESS (8.832s)
[481.798490] Starting dynamic subtest: C-eDP1
[490.608826] Dynamic subtest C-eDP1: SUCCESS (8.819s)
[490.620771] Starting dynamic subtest: D-eDP1
[499.449084] Dynamic subtest D-eDP1: SUCCESS (8.838s)
[499.454785] Subtest plain-flip-ts-check-interruptible: SUCCESS (35.332s)
[500.628401] [115/133] (541s left) kms_big_fb (x-tiled-64bpp-rotate-270)
[500.743574] Starting subtest: x-tiled-64bpp-rotate-270
[500.766905] Subtest x-tiled-64bpp-rotate-270: SKIP (0.020s)
[500.867095] [116/133] (541s left) gem_sync (basic-store-each)
[500.945348] Starting subtest: basic-store-each
[503.479962] Subtest basic-store-each: SUCCESS (2.544s)
[503.668293] [117/133] (538s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[503.781917] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc
[503.785983] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[503.892628] [118/133] (538s left) device_reset (unbind-cold-reset-rebind)
[503.948167] Starting subtest: unbind-cold-reset-rebind
[503.952790] Subtest unbind-cold-reset-rebind: SKIP (0.000s)
[504.022673] [119/133] (538s left) gem_exec_schedule (semaphore-user)
[504.103171] Starting subtest: semaphore-user
[504.154168] Subtest semaphore-user: SUCCESS (0.059s)
[505.702300] [120/133] (536s left) perf_pmu (idle)
[505.747641] Starting subtest: idle
[505.750074] Starting dynamic subtest: rcs0
[506.286916] Dynamic subtest rcs0: SUCCESS (0.539s)
[506.300113] Starting dynamic subtest: bcs0
[506.806490] Dynamic subtest bcs0: SUCCESS (0.512s)
[506.819612] Starting dynamic subtest: vcs0
[507.331608] Dynamic subtest vcs0: SUCCESS (0.526s)
[507.345183] Starting dynamic subtest: vcs1
[507.850237] Dynamic subtest vcs1: SUCCESS (0.512s)
[507.862063] Starting dynamic subtest: vecs0
[508.363501] Dynamic subtest vecs0: SUCCESS (0.514s)
[508.375060] Starting dynamic subtest: ccs0
[508.880067] Dynamic subtest ccs0: SUCCESS (0.513s)
[508.882906] Subtest idle: SUCCESS (3.133s)
[509.007694] [121/133] (533s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[509.108260] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu
[515.755213] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (6.649s)
[515.869576] [122/133] (526s left) kms_ccs (bad-rotation-90-4-tiled-dg2-rc-ccs)
[515.963959] Starting subtest: bad-rotation-90-4-tiled-dg2-rc-ccs
[515.966080] Starting dynamic subtest: pipe-A-eDP-1
[515.987676] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[515.999905] Starting dynamic subtest: pipe-B-eDP-1
[516.020300] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[516.030207] Starting dynamic subtest: pipe-C-eDP-1
[516.050964] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[516.061392] Starting dynamic subtest: pipe-D-eDP-1
[516.083315] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[516.087310] Subtest bad-rotation-90-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[518.246868] [123/133] (524s left) kms_plane (pixel-format-linear-modifier)
[518.344890] Starting subtest: pixel-format-linear-modifier
[518.369523] Starting dynamic subtest: pipe-A-plane-0
[521.935691] Dynamic subtest pipe-A-plane-0: SUCCESS (3.566s)
[521.945957] Starting dynamic subtest: pipe-A-plane-3
[523.646317] Dynamic subtest pipe-A-plane-3: SUCCESS (1.709s)
[523.658523] Starting dynamic subtest: pipe-A-plane-5
[523.857423] Dynamic subtest pipe-A-plane-5: SUCCESS (0.210s)
[524.941656] Starting dynamic subtest: pipe-B-plane-0
[528.663380] Dynamic subtest pipe-B-plane-0: SUCCESS (3.732s)
[528.673557] Starting dynamic subtest: pipe-B-plane-3
[530.486344] Dynamic subtest pipe-B-plane-3: SUCCESS (1.821s)
[530.497019] Starting dynamic subtest: pipe-B-plane-5
[530.697173] Dynamic subtest pipe-B-plane-5: SUCCESS (0.209s)
[531.007584] Subtest pixel-format-linear-modifier: SUCCESS (12.666s)
[531.824917] [124/133] (510s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-msflip-blt)
[531.923979] Starting subtest: psr-2p-scndscrn-shrfb-msflip-blt
[531.927967] Subtest psr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[532.038649] [125/133] (510s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-render)
[532.157141] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-render
[532.161293] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[532.256032] [126/133] (510s left) kms_ccs (crc-primary-rotation-180-4-tiled-bmg-ccs)
[532.347118] Starting subtest: crc-primary-rotation-180-4-tiled-bmg-ccs
[532.351429] Subtest crc-primary-rotation-180-4-tiled-bmg-ccs: SKIP (0.000s)
[533.445987] [127/133] (509s left) syncobj_wait (wait-all-complex)
[533.509831] Starting subtest: wait-all-complex
[533.725448] Subtest wait-all-complex: SUCCESS (0.216s)
[533.813991] [128/133] (509s left) gem_exec_balancer (bonded-chain)
[533.885076] Starting subtest: bonded-chain
[540.888564] Subtest bonded-chain: SUCCESS (7.003s)
[541.231678] [129/133] (501s left) kms_cursor_legacy (cursorb-vs-flipb-atomic)
[541.356808] Starting subtest: cursorB-vs-flipB-atomic
[541.361312] Subtest cursorB-vs-flipB-atomic: SKIP (0.001s)
[541.511275] [130/133] (501s left) kms_properties (colorop-properties-legacy)
[541.612613] Starting subtest: colorop-properties-legacy
[541.614933] Starting dynamic subtest: pipe-A-eDP-1
[541.945990] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.334s)
[541.956977] Starting dynamic subtest: pipe-B-eDP-1
[543.007812] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.060s)
[543.022340] Starting dynamic subtest: pipe-C-eDP-1
[544.030995] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.021s)
[544.042982] Starting dynamic subtest: pipe-D-eDP-1
[545.068690] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.035s)
[545.071120] Subtest colorop-properties-legacy: SUCCESS (3.457s)
[545.874667] [131/133] (497s left) gem_exec_fence (basic-busy)
[546.060036] Starting subtest: basic-busy
[546.062571] Starting dynamic subtest: rcs0
[546.150155] Dynamic subtest rcs0: SUCCESS (0.092s)
[546.164436] Starting dynamic subtest: bcs0
[546.209014] Dynamic subtest bcs0: SUCCESS (0.057s)
[546.222645] Starting dynamic subtest: vcs0
[546.263001] Dynamic subtest vcs0: SUCCESS (0.052s)
[546.274691] Starting dynamic subtest: vcs1
[546.315832] Dynamic subtest vcs1: SUCCESS (0.051s)
[546.330098] Starting dynamic subtest: vecs0
[546.377958] Dynamic subtest vecs0: SUCCESS (0.060s)
[546.391373] Starting dynamic subtest: ccs0
[546.431974] Dynamic subtest ccs0: SUCCESS (0.052s)
[546.434632] Subtest basic-busy: SUCCESS (0.376s)
[548.757937] [132/133] (494s left) gem_eio (wait-10ms)
[548.977098] Starting subtest: wait-10ms
[549.331858] Subtest wait-10ms: SUCCESS (0.355s)
[549.457261] [133/133] (493s left) kms_atomic_transition (plane-all-transition-fencing)
[549.546759] Starting subtest: plane-all-transition-fencing
[549.549040] Starting dynamic subtest: pipe-A-eDP-1
[552.043026] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.497s)
[552.354050] Starting dynamic subtest: pipe-B-eDP-1
[555.608035] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.267s)
[555.916283] Subtest plane-all-transition-fencing: SUCCESS (6.370s)
[556.740072] Closing watchdogs
results: parsing output: 0/ for test: gem_mmap_wc
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: kms_psr
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_psr
results: parsing output: 5/ for test: template
results: parsing output: 6/ for test: kms_lease
results: parsing output: 7/ for test: kms_addfb_basic
results: parsing output: 8/ for test: kms_cursor_crc
results: parsing output: 9/ for test: gem_vm_create
results: parsing output: 10/ for test: kms_plane_lowres
results: parsing output: 11/ for test: kms_sequence
results: parsing output: 12/ for test: gem_ctx_param
results: parsing output: 13/ for test: syncobj_wait
results: parsing output: 14/ for test: kms_chamelium_frames
results: parsing output: 15/ for test: kms_content_protection
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: gem_pxp
results: parsing output: 18/ for test: syncobj_timeline
results: parsing output: 19/ for test: gem_exec_fence
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: perf_pmu
results: parsing output: 22/ for test: api_intel_bb
results: parsing output: 23/ for test: gen9_exec_parse
results: parsing output: 24/ for test: gem_exec_flush
results: parsing output: 25/ for test: syncobj_timeline
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: kms_cursor_legacy
results: parsing output: 28/ for test: syncobj_wait
results: parsing output: 29/ for test: syncobj_timeline
results: parsing output: 30/ for test: gem_mmap_gtt
results: parsing output: 31/ for test: kms_ccs
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: kms_vblank
results: parsing output: 34/ for test: kms_psr
results: parsing output: 35/ for test: kms_getfb
results: parsing output: 36/ for test: gem_exec_params
results: parsing output: 37/ for test: i915_module_load
results: parsing output: 38/ for test: gem_exec_params
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: kms_ccs
results: parsing output: 41/ for test: gem_ctx_engines
results: parsing output: 42/ for test: gem_render_copy
results: parsing output: 43/ for test: kms_big_fb
results: parsing output: 44/ for test: gem_exec_whisper
results: parsing output: 45/ for test: kms_chamelium_color
results: parsing output: 46/ for test: kms_big_fb
results: parsing output: 47/ for test: gem_exec_schedule
results: parsing output: 48/ for test: syncobj_timeline
results: parsing output: 49/ for test: kms_psr
results: parsing output: 50/ for test: kms_psr
results: parsing output: 51/ for test: kms_rotation_crc
results: parsing output: 52/ for test: gem_exec_parallel
results: parsing output: 53/ for test: gen9_exec_parse
results: parsing output: 54/ for test: i915_query
results: parsing output: 55/ for test: kms_chamelium_hpd
results: parsing output: 56/ for test: kms_addfb_basic
results: parsing output: 57/ for test: gem_userptr_blits
results: parsing output: 58/ for test: syncobj_timeline
results: parsing output: 59/ for test: gem_ctx_persistence
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_async_flips
results: parsing output: 62/ for test: gem_exec_reloc
results: parsing output: 63/ for test: gem_exec_reloc
results: parsing output: 64/ for test: kms_hdr
results: parsing output: 65/ for test: gem_lmem_swapping
results: parsing output: 66/ for test: kms_async_flips
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: gem_softpin
results: parsing output: 69/ for test: prime_vgem
results: parsing output: 70/ for test: gem_userptr_blits
results: parsing output: 71/ for test: kms_cursor_legacy
results: parsing output: 72/ for test: kms_cursor_legacy
results: parsing output: 73/ for test: kms_feature_discovery
results: parsing output: 74/ for test: kms_flip_scaled_crc
results: parsing output: 75/ for test: gem_ctx_shared
results: parsing output: 76/ for test: gem_mmap_gtt
results: parsing output: 77/ for test: kms_big_fb
results: parsing output: 78/ for test: gem_exec_suspend
results: parsing output: 79/ for test: kms_plane
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: kms_ccs
results: parsing output: 82/ for test: kms_cursor_crc
results: parsing output: 83/ for test: kms_psr2_sf
results: parsing output: 84/ for test: kms_addfb_basic
results: parsing output: 85/ for test: gem_mmap_gtt
results: parsing output: 86/ for test: kms_color
results: parsing output: 87/ for test: gem_workarounds
results: parsing output: 88/ for test: kms_big_fb
results: parsing output: 89/ for test: i915_pm_rps
results: parsing output: 90/ for test: gem_exec_whisper
results: parsing output: 91/ for test: i915_query
results: parsing output: 92/ for test: gem_render_copy
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: kms_pipe_crc_basic
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_flip_scaled_crc
results: parsing output: 97/ for test: kms_plane_scaling
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: kms_psr
results: parsing output: 100/ for test: perf
results: parsing output: 101/ for test: kms_pm_rpm
results: parsing output: 102/ for test: fbdev
results: parsing output: 103/ for test: gem_exec_create
results: parsing output: 104/ for test: gem_mmap_offset
results: parsing output: 105/ for test: api_intel_bb
results: parsing output: 106/ for test: kms_flip
results: parsing output: 107/ for test: gem_exec_reloc
results: parsing output: 108/ for test: kms_big_fb
results: parsing output: 109/ for test: syncobj_timeline
results: parsing output: 110/ for test: gem_mmap_gtt
results: parsing output: 111/ for test: kms_pm_dc
results: parsing output: 112/ for test: kms_cursor_crc
results: parsing output: 113/ for test: kms_flip
results: parsing output: 114/ for test: kms_big_fb
results: parsing output: 115/ for test: gem_sync
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: device_reset
results: parsing output: 118/ for test: gem_exec_schedule
results: parsing output: 119/ for test: perf_pmu
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_ccs
results: parsing output: 122/ for test: kms_plane
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_ccs
results: parsing output: 126/ for test: syncobj_wait
results: parsing output: 127/ for test: gem_exec_balancer
results: parsing output: 128/ for test: kms_cursor_legacy
results: parsing output: 129/ for test: kms_properties
results: parsing output: 130/ for test: gem_exec_fence
results: parsing output: 131/ for test: gem_eio
results: parsing output: 132/ for test: kms_atomic_transition
Done.