[66.913204] Initializing watchdogs
[66.913286]   /dev/watchdog0
[66.943798] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:4905 Intel Dg1 (Gen12) DG1 [Iris Xe MAX Graphics]
[66.959187] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[66.964393] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[66.967260] [001/156] (960s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats)
[68.519113] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s)
[68.648809] [002/156] (958s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-msflip-blt)
[70.145616] Starting subtest: fbcpsr-2p-scndscrn-shrfb-msflip-blt
[70.147255] Subtest fbcpsr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[70.296634] [003/156] (957s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[71.802273] Starting subtest: short-busy-flip-before-cursor-toggle
[71.804630] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[71.924983] [004/156] (955s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[73.437123] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[73.438856] Subtest hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[73.615356] [005/156] (954s left) kms_flip (2x-flip-vs-expired-vblank-interruptible)
[75.113510] Starting subtest: 2x-flip-vs-expired-vblank-interruptible
[75.132154] Subtest 2x-flip-vs-expired-vblank-interruptible: SKIP (0.018s)
[75.243337] [006/156] (952s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[76.747904] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu
[76.749969] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[76.920976] [007/156] (951s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt)
[78.418016] Starting subtest: fbc-farfromfence-mmap-gtt
[78.419921] Subtest fbc-farfromfence-mmap-gtt: SKIP (0.000s)
[78.636325] [008/156] (949s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[80.133243] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt
[80.134985] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[80.314768] [009/156] (947s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-pgflip-blt)
[81.816974] Starting subtest: fbcpsr-1p-primscrn-indfb-pgflip-blt
[81.818942] Subtest fbcpsr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[81.964893] [010/156] (946s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[83.477106] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[83.479167] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[83.643162] [011/156] (944s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[85.143332] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[85.145169] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[85.305872] [012/156] (943s left) kms_big_fb (x-tiled-16bpp-rotate-90)
[86.827867] Starting subtest: x-tiled-16bpp-rotate-90
[86.932090] Subtest x-tiled-16bpp-rotate-90: SKIP (0.103s)
[87.094996] [013/156] (941s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[88.584715] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[88.585761] Starting dynamic subtest: pipe-A
[89.432039] Dynamic subtest pipe-A: SUCCESS (0.849s)
[89.441306] Starting dynamic subtest: pipe-B
[90.205678] Dynamic subtest pipe-B: SUCCESS (0.773s)
[90.218115] Starting dynamic subtest: pipe-C
[90.995415] Dynamic subtest pipe-C: SUCCESS (0.789s)
[91.004802] Starting dynamic subtest: pipe-D
[91.769358] Dynamic subtest pipe-D: SUCCESS (0.774s)
[91.770198] Subtest plane-downscale-factor-0-5-with-modifiers: SUCCESS (3.187s)
[92.025684] [014/156] (936s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs-cc)
[93.517426] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs-cc
[93.518319] Starting dynamic subtest: pipe-A-HDMI-A-4
[93.527510] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.001s)
[93.536219] Starting dynamic subtest: pipe-B-HDMI-A-4
[93.547381] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.001s)
[93.555728] Starting dynamic subtest: pipe-C-HDMI-A-4
[93.563529] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.001s)
[93.571771] Starting dynamic subtest: pipe-D-HDMI-A-4
[93.579735] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.001s)
[93.581221] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SKIP (0.009s)
[93.809366] [015/156] (934s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-indfb-draw-mmap-wc)
[95.305984] Starting subtest: hdr-2p-primscrn-pri-indfb-draw-mmap-wc
[95.308044] Subtest hdr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[95.472290] [016/156] (933s left) gem_create (create-ext-set-pat)
[95.655840] Starting subtest: create-ext-set-pat
[95.660312] Subtest create-ext-set-pat: SKIP (0.000s)
[95.836452] [017/156] (933s left) syncobj_timeline (etime-single-wait-for-submit-unsubmitted)
[95.883892] Starting subtest: etime-single-wait-for-submit-unsubmitted
[95.991496] Subtest etime-single-wait-for-submit-unsubmitted: SUCCESS (0.102s)
[96.174393] [018/156] (932s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb565-draw-mmap-cpu)
[97.671320] Starting subtest: fbcpsrhdr-rgb565-draw-mmap-cpu
[97.673098] Subtest fbcpsrhdr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[97.830587] [019/156] (931s left) gem_ctx_param (set-priority-invalid-size)
[97.885405] Starting subtest: set-priority-invalid-size
[97.886418] Subtest set-priority-invalid-size: SUCCESS (0.000s)
[98.005508] [020/156] (931s left) kms_psr (psr2-primary-render)
[99.496475] Subtest psr2-primary-render: SKIP (0.000s)
[99.558368] [021/156] (929s left) gem_userptr_blits (create-destroy-sync)
[99.625519] Starting subtest: create-destroy-sync
[104.649264] Subtest create-destroy-sync: SUCCESS (5.021s)
[104.802675] [022/156] (924s left) i915_query (query-garbage)
[104.847582] Starting subtest: query-garbage
[104.848486] Subtest query-garbage: SUCCESS (0.000s)
[104.954670] [023/156] (924s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[105.008998] Starting subtest: y-tiled-to-vebox-x-tiled
[105.009964] Starting dynamic subtest: smem
[105.098527] Dynamic subtest smem: SUCCESS (0.090s)
[105.107401] Starting dynamic subtest: lmem0
[105.849103] Dynamic subtest lmem0: SUCCESS (0.747s)
[105.850099] Subtest y-tiled-to-vebox-x-tiled: SUCCESS (0.838s)
[106.099566] [024/156] (923s left) gem_exec_reloc (basic-gtt-wc)
[106.151683] Subtest basic-gtt-wc: SKIP (0.000s)
[106.240583] [025/156] (923s left) i915_pm_rpm (gem-pread)
[110.003569] Starting subtest: gem-pread
[111.324232] Subtest gem-pread: SUCCESS (1.322s)
[112.168051] [026/156] (917s left) kms_psr (psr2-basic)
[113.663163] Subtest psr2-basic: SKIP (0.000s)
[113.726931] [027/156] (915s left) gem_tiled_partial_pwrite_pread (reads)
[113.779550] Subtest reads: SKIP (0.000s)
[113.843462] [028/156] (915s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[113.888693] Starting subtest: single-wait-for-submit-available-submitted
[113.889722] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.001s)
[114.002780] [029/156] (915s left) syncobj_wait (multi-wait-submitted)
[114.050567] Starting subtest: multi-wait-submitted
[114.155872] Subtest multi-wait-submitted: SUCCESS (0.104s)
[114.312859] [030/156] (915s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[115.807221] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[115.809039] Subtest fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[115.974418] [031/156] (914s left) gem_exec_parallel (basic)
[116.038217] Starting subtest: basic
[116.039158] Starting dynamic subtest: rcs0
[117.627969] Dynamic subtest rcs0: SUCCESS (1.590s)
[117.637657] Starting dynamic subtest: bcs0
[119.141459] Dynamic subtest bcs0: SUCCESS (1.513s)
[119.152888] Starting dynamic subtest: vcs0
[120.674968] Dynamic subtest vcs0: SUCCESS (1.533s)
[120.683989] Starting dynamic subtest: vcs1
[122.219913] Dynamic subtest vcs1: SUCCESS (1.544s)
[122.228773] Starting dynamic subtest: vecs0
[123.727020] Dynamic subtest vecs0: SUCCESS (1.507s)
[123.728079] Subtest basic: SUCCESS (7.689s)
[124.084793] [032/156] (906s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-pwrite)
[125.580673] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-pwrite
[125.582886] Subtest fbc-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[125.768779] [033/156] (904s left) syncobj_wait (single-wait-for-submit-submitted)
[125.823948] Starting subtest: single-wait-for-submit-submitted
[125.934651] Subtest single-wait-for-submit-submitted: SUCCESS (0.106s)
[126.100659] [034/156] (904s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt)
[127.594591] Starting subtest: fbcpsr-rgb565-draw-blt
[127.596293] Subtest fbcpsr-rgb565-draw-blt: SKIP (0.000s)
[127.738914] [035/156] (902s left) perf_pmu (busy-accuracy-98)
[127.802931] Starting subtest: busy-accuracy-98
[127.803862] Starting dynamic subtest: rcs0
[130.849451] Dynamic subtest rcs0: SUCCESS (3.046s)
[130.859530] Starting dynamic subtest: bcs0
[133.899345] Dynamic subtest bcs0: SUCCESS (3.049s)
[133.909727] Starting dynamic subtest: vcs0
[136.948391] Dynamic subtest vcs0: SUCCESS (3.049s)
[136.958966] Starting dynamic subtest: vcs1
[139.997404] Dynamic subtest vcs1: SUCCESS (3.048s)
[140.007714] Starting dynamic subtest: vecs0
[143.043929] Dynamic subtest vecs0: SUCCESS (3.046s)
[143.044933] Subtest busy-accuracy-98: SUCCESS (15.240s)
[143.187027] [036/156] (887s left) kms_dsc (dsc-fractional-bpp-with-bpc)
[144.675207] Starting subtest: dsc-fractional-bpp-with-bpc
[144.685923] Subtest dsc-fractional-bpp-with-bpc: SKIP (0.001s)
[144.785931] [037/156] (885s left) kms_cursor_legacy (cursorb-vs-flipb-toggle)
[146.280546] Starting subtest: cursorB-vs-flipB-toggle
[146.282285] Subtest cursorB-vs-flipB-toggle: SKIP (0.001s)
[146.386311] [038/156] (884s left) syncobj_wait (wait-all-delayed-signal)
[146.436110] Starting subtest: wait-all-delayed-signal
[146.541600] Subtest wait-all-delayed-signal: SUCCESS (0.103s)
[146.795869] [039/156] (883s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[148.290973] Starting subtest: psr-rgb565-draw-mmap-wc
[148.295204] Subtest psr-rgb565-draw-mmap-wc: SKIP (0.000s)
[148.495118] [040/156] (882s left) gem_wait (busy)
[148.556506] Starting subtest: busy
[148.557344] Starting dynamic subtest: all-engines
[149.129348] Dynamic subtest all-engines: SUCCESS (0.573s)
[149.139360] Starting dynamic subtest: rcs0
[149.671691] Dynamic subtest rcs0: SUCCESS (0.542s)
[149.681597] Starting dynamic subtest: bcs0
[150.209743] Dynamic subtest bcs0: SUCCESS (0.538s)
[150.219639] Starting dynamic subtest: vcs0
[150.747787] Dynamic subtest vcs0: SUCCESS (0.538s)
[150.757700] Starting dynamic subtest: vcs1
[151.285760] Dynamic subtest vcs1: SUCCESS (0.538s)
[151.295597] Starting dynamic subtest: vecs0
[151.823745] Dynamic subtest vecs0: SUCCESS (0.538s)
[151.824580] Subtest busy: SUCCESS (3.268s)
[152.099422] [041/156] (878s left) gem_close_race (basic-threads)
[152.175805] Starting subtest: basic-threads
[153.347582] Subtest basic-threads: SUCCESS (1.172s)
[153.693943] [042/156] (877s left) kms_lease (possible-crtcs-filtering)
[155.182555] Starting subtest: possible-crtcs-filtering
[155.188631] Subtest possible-crtcs-filtering: SUCCESS (0.007s)
[155.302423] [043/156] (875s left) prime_busy (hang)
[155.359566] Starting subtest: hang
[155.361574] Starting dynamic subtest: rcs0
[181.427533] Dynamic subtest rcs0: SUCCESS (26.071s)
[181.437543] Starting dynamic subtest: bcs0
[188.576434] Dynamic subtest bcs0: SUCCESS (7.149s)
[188.586512] Starting dynamic subtest: vcs0
[195.554145] Dynamic subtest vcs0: SUCCESS (6.978s)
[195.564365] Starting dynamic subtest: vcs1
[202.527497] Dynamic subtest vcs1: SUCCESS (6.974s)
[202.537609] Starting dynamic subtest: vecs0
[209.567094] Dynamic subtest vecs0: SUCCESS (7.039s)
[209.567980] Subtest hang: SUCCESS (54.214s)
[209.712100] [044/156] (821s left) kms_getfb (getfb2-handle-zero)
[211.200834] Starting subtest: getfb2-handle-zero
[211.201815] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[211.354532] [045/156] (819s left) gem_close (basic)
[211.414483] Starting subtest: basic
[211.415600] Subtest basic: SUCCESS (0.000s)
[211.618393] [046/156] (819s left) gem_ccs (ctrl-surf-copy)
[211.671223] Starting subtest: ctrl-surf-copy
[211.673482] Subtest ctrl-surf-copy: SKIP (0.000s)
[211.789373] [047/156] (819s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[213.283502] Starting subtest: fbc-indfb-scaledprimary
[214.729481] Subtest fbc-indfb-scaledprimary: SUCCESS (1.446s)
[214.984503] [048/156] (816s left) kms_ccs (crc-primary-suspend-y-tiled-ccs)
[216.473135] Starting subtest: crc-primary-suspend-y-tiled-ccs
[216.475278] Starting dynamic subtest: pipe-A-HDMI-A-4
[216.478527] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.000s)
[216.488985] Starting dynamic subtest: pipe-B-HDMI-A-4
[216.492514] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.000s)
[216.502771] Starting dynamic subtest: pipe-C-HDMI-A-4
[216.506103] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.000s)
[216.516147] Starting dynamic subtest: pipe-D-HDMI-A-4
[216.519592] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.000s)
[216.521302] Subtest crc-primary-suspend-y-tiled-ccs: SKIP (0.006s)
[216.756296] [049/156] (814s left) gem_userptr_blits (readonly-pwrite-unsync)
[216.810144] Subtest readonly-pwrite-unsync: SKIP (0.000s)
[217.010672] [050/156] (814s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-move)
[218.504091] Starting subtest: fbchdr-1p-primscrn-spr-indfb-move
[218.505833] Subtest fbchdr-1p-primscrn-spr-indfb-move: SKIP (0.000s)
[218.691548] [051/156] (813s left) kms_big_fb (4-tiled-64bpp-rotate-0)
[220.202760] Starting subtest: 4-tiled-64bpp-rotate-0
[220.204800] Subtest 4-tiled-64bpp-rotate-0: SKIP (0.000s)
[220.387446] [052/156] (811s left) kms_cursor_crc (cursor-random-512x512)
[222.145141] Starting subtest: cursor-random-512x512
[222.147905] Subtest cursor-random-512x512: SKIP (0.001s)
[222.382997] [053/156] (809s left) core_getclient (basic)
[222.426961] Starting subtest: basic
[222.427932] Subtest basic: SUCCESS (0.000s)
[222.543352] [054/156] (809s left) gem_mmap_gtt (fault-concurrent)
[222.590084] Subtest fault-concurrent: SKIP (0.000s)
[222.664732] [055/156] (809s left) kms_flip (flip-vs-absolute-wf_vblank)
[224.153242] Starting subtest: flip-vs-absolute-wf_vblank
[224.160940] Starting dynamic subtest: A-HDMI-A4
[232.377296] Dynamic subtest A-HDMI-A4: SUCCESS (8.217s)
[232.390552] Starting dynamic subtest: B-HDMI-A4
[240.524627] Dynamic subtest B-HDMI-A4: SUCCESS (8.146s)
[240.537111] Starting dynamic subtest: C-HDMI-A4
[248.676335] Dynamic subtest C-HDMI-A4: SUCCESS (8.151s)
[248.687521] Starting dynamic subtest: D-HDMI-A4
[256.828215] Dynamic subtest D-HDMI-A4: SUCCESS (8.151s)
[256.829178] Subtest flip-vs-absolute-wf_vblank: SUCCESS (32.677s)
[257.066513] [056/156] (775s left) kms_flip (flip-vs-panning-vs-hang)
[258.556137] Starting subtest: flip-vs-panning-vs-hang
[258.559761] Starting dynamic subtest: A-HDMI-A4
[309.463531] Dynamic subtest A-HDMI-A4: SUCCESS (50.906s)
[309.475164] Starting dynamic subtest: D-HDMI-A4
[359.425333] Dynamic subtest D-HDMI-A4: SUCCESS (49.962s)
[359.426201] Subtest flip-vs-panning-vs-hang: SUCCESS (100.874s)
[359.646270] [057/156] (672s left) kms_rotation_crc (primary-x-tiled-reflect-x-0)
[361.135868] Starting subtest: primary-x-tiled-reflect-x-0
[371.761366] Subtest primary-x-tiled-reflect-x-0: SUCCESS (10.626s)
[372.005098] [058/156] (660s left) gem_mmap_gtt (fault-concurrent-y)
[372.052654] Subtest fault-concurrent-Y: SKIP (0.000s)
[372.118656] [059/156] (660s left) gem_huc_copy (huc-copy)
[372.187489] Starting subtest: huc-copy
[372.193729] Subtest huc-copy: SUCCESS (0.006s)
[372.352570] [060/156] (660s left) kms_big_fb (y-tiled-8bpp-rotate-90)
[373.860456] Starting subtest: y-tiled-8bpp-rotate-90
[373.969385] Subtest y-tiled-8bpp-rotate-90: SKIP (0.108s)
[374.129329] [061/156] (658s left) kms_psr (pr-cursor-mmap-cpu)
[375.621825] Subtest pr-cursor-mmap-cpu: SKIP (0.000s)
[375.700775] [062/156] (656s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-cur-indfb-move)
[377.195859] Starting subtest: psrhdr-1p-primscrn-cur-indfb-move
[377.197852] Subtest psrhdr-1p-primscrn-cur-indfb-move: SKIP (0.000s)
[377.364922] [063/156] (655s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-fullscreen)
[378.864853] Starting subtest: psrhdr-2p-primscrn-spr-indfb-fullscreen
[378.866603] Subtest psrhdr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[379.034840] [064/156] (653s left) kms_psr (fbc-pr-cursor-plane-onoff)
[380.530917] Subtest fbc-pr-cursor-plane-onoff: SKIP (0.000s)
[380.601584] [065/156] (652s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-move)
[382.097550] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-move
[382.099161] Subtest fbcpsr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[382.254929] [066/156] (650s left) i915_drm_fdinfo (basics)
[382.311508] Starting subtest: basics
[382.312449] Subtest basics: SUCCESS (0.000s)
[382.438958] [067/156] (650s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-msflip-blt)
[383.932954] Starting subtest: psrhdr-2p-scndscrn-shrfb-msflip-blt
[383.934756] Subtest psrhdr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[384.098993] [068/156] (649s left) gem_mmap_gtt (basic-wc)
[384.150507] Subtest basic-wc: SKIP (0.000s)
[384.217077] [069/156] (649s left) kms_big_fb (linear-16bpp-rotate-0)
[385.726092] Starting subtest: linear-16bpp-rotate-0
[387.915564] Subtest linear-16bpp-rotate-0: SUCCESS (2.185s)
[388.149446] [070/156] (645s left) gem_ctx_persistence (engines-queued)
[388.203160] Starting subtest: engines-queued
[388.204034] Starting dynamic subtest: rcs0
[388.268141] Dynamic subtest rcs0: SUCCESS (0.066s)
[388.279557] Starting dynamic subtest: bcs0
[388.313210] Dynamic subtest bcs0: SUCCESS (0.044s)
[388.328019] Starting dynamic subtest: vcs0
[388.365462] Dynamic subtest vcs0: SUCCESS (0.051s)
[388.388844] Starting dynamic subtest: vcs1
[388.435472] Dynamic subtest vcs1: SUCCESS (0.068s)
[388.476044] Starting dynamic subtest: vecs0
[388.498199] Dynamic subtest vecs0: SUCCESS (0.060s)
[388.499552] Subtest engines-queued: SUCCESS (0.297s)
[388.679501] [071/156] (644s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-render)
[390.174299] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-render
[390.175895] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-render: SKIP (0.000s)
[390.341090] [072/156] (643s left) gem_spin_batch (resubmit-all)
[390.403363] Starting subtest: resubmit-all
[390.404265] Starting dynamic subtest: rcs0
[390.426842] Dynamic subtest rcs0: SUCCESS (0.023s)
[390.438102] Starting dynamic subtest: bcs0
[390.438969] Dynamic subtest bcs0: SUCCESS (0.002s)
[390.449518] Starting dynamic subtest: vcs0
[390.450518] Dynamic subtest vcs0: SUCCESS (0.002s)
[390.460916] Starting dynamic subtest: vcs1
[390.462051] Dynamic subtest vcs1: SUCCESS (0.002s)
[390.472209] Starting dynamic subtest: vecs0
[390.473250] Dynamic subtest vecs0: SUCCESS (0.002s)
[390.474155] Subtest resubmit-all: SUCCESS (0.036s)
[390.817805] [073/156] (642s left) kms_cursor_crc (cursor-offscreen-128x42)
[392.497259] Starting subtest: cursor-offscreen-128x42
[392.503167] Starting dynamic subtest: pipe-A-HDMI-A-4
[395.017016] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (2.514s)
[395.053235] Starting dynamic subtest: pipe-D-HDMI-A-4
[397.447910] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (2.405s)
[397.448884] Subtest cursor-offscreen-128x42: SUCCESS (4.952s)
[397.845192] [074/156] (635s left) perf_pmu (idle-no-semaphores)
[397.896532] Starting subtest: idle-no-semaphores
[397.897457] Starting dynamic subtest: rcs0
[398.432208] Dynamic subtest rcs0: SUCCESS (0.531s)
[398.444970] Starting dynamic subtest: bcs0
[398.951379] Dynamic subtest bcs0: SUCCESS (0.515s)
[398.966264] Starting dynamic subtest: vcs0
[399.470444] Dynamic subtest vcs0: SUCCESS (0.515s)
[399.485216] Starting dynamic subtest: vcs1
[399.989466] Dynamic subtest vcs1: SUCCESS (0.515s)
[400.004213] Starting dynamic subtest: vecs0
[400.506444] Dynamic subtest vecs0: SUCCESS (0.513s)
[400.508625] Subtest idle-no-semaphores: SUCCESS (2.608s)
[400.685333] [075/156] (633s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[402.180449] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-gtt
[402.182050] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[402.328948] [076/156] (631s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-downscaling)
[403.834204] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-downscaling
[403.836138] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-downscaling: SKIP (0.000s)
[403.937625] [077/156] (629s left) kms_chamelium_hpd (dp-hpd-storm)
[405.483053] Subtest dp-hpd-storm: SKIP (0.000s)
[405.614722] [078/156] (628s left) kms_cursor_legacy (2x-long-flip-vs-cursor-legacy)
[407.104230] Starting subtest: 2x-long-flip-vs-cursor-legacy
[407.108258] Subtest 2x-long-flip-vs-cursor-legacy: SKIP (0.002s)
[407.235527] [079/156] (626s left) kms_properties (colorop-properties-legacy)
[408.738025] Starting subtest: colorop-properties-legacy
[408.738944] Starting dynamic subtest: pipe-A-HDMI-A-4
[408.861156] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.122s)
[408.871897] Starting dynamic subtest: pipe-B-HDMI-A-4
[408.988285] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.126s)
[409.000058] Starting dynamic subtest: pipe-C-HDMI-A-4
[409.122595] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.133s)
[409.134529] Starting dynamic subtest: pipe-D-HDMI-A-4
[409.249138] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.125s)
[409.249929] Subtest colorop-properties-legacy: SUCCESS (0.513s)
[409.447378] [080/156] (624s left) gem_mmap_offset (partial-remap)
[409.493191] Starting subtest: partial-remap
[409.494035] Starting dynamic subtest: lmem0
[409.498223] Dynamic subtest lmem0: SUCCESS (0.005s)
[409.509679] Starting dynamic subtest: smem0
[409.511757] Dynamic subtest smem0: SUCCESS (0.006s)
[409.512878] Subtest partial-remap: SUCCESS (0.012s)
[409.684333] [081/156] (624s left) gem_exec_fence (concurrent)
[409.858368] Starting subtest: concurrent
[409.860279] Subtest concurrent: SKIP (0.000s)
[410.634455] [FACT gem_exec_fence (concurrent)] new: kernel.kmod_is_loaded.vgem: true
[410.637165] [082/156] (623s left) sysfs_timeslice_duration (invalid)
[410.687895] Starting subtest: invalid
[410.688871] Starting dynamic subtest: vecs0
[410.689945] Dynamic subtest vecs0: SUCCESS (0.001s)
[410.701549] Starting dynamic subtest: vcs0
[410.702618] Dynamic subtest vcs0: SUCCESS (0.001s)
[410.713522] Starting dynamic subtest: bcs0
[410.714470] Dynamic subtest bcs0: SUCCESS (0.000s)
[410.724940] Starting dynamic subtest: rcs0
[410.726104] Dynamic subtest rcs0: SUCCESS (0.001s)
[410.736855] Starting dynamic subtest: vcs1
[410.738025] Dynamic subtest vcs1: SUCCESS (0.001s)
[410.739207] Subtest invalid: SUCCESS (0.009s)
[410.823871] [083/156] (623s left) gem_exec_fence (nb-await)
[411.042200] Starting subtest: nb-await
[411.043578] Starting dynamic subtest: rcs0
[411.135477] Dynamic subtest rcs0: SUCCESS (0.093s)
[411.149877] Starting dynamic subtest: bcs0
[411.201065] Dynamic subtest bcs0: SUCCESS (0.064s)
[411.216181] Starting dynamic subtest: vcs0
[411.266663] Dynamic subtest vcs0: SUCCESS (0.064s)
[411.300027] Starting dynamic subtest: vcs1
[411.349965] Dynamic subtest vcs1: SUCCESS (0.080s)
[411.370150] Starting dynamic subtest: vecs0
[411.421105] Dynamic subtest vecs0: SUCCESS (0.066s)
[411.422215] Subtest nb-await: SUCCESS (0.380s)
[412.040123] [084/156] (622s left) syncobj_wait (wait-any-complex)
[412.086990] Starting subtest: wait-any-complex
[412.149541] Subtest wait-any-complex: SUCCESS (0.062s)
[412.287638] [085/156] (622s left) kms_vblank (query-idle)
[413.778125] Starting subtest: query-idle
[413.778973] Starting dynamic subtest: pipe-A-HDMI-A-4
[416.130590] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (2.352s)
[416.144358] Starting dynamic subtest: pipe-D-HDMI-A-4
[418.321731] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (2.187s)
[418.322642] Subtest query-idle: SUCCESS (4.545s)
[418.507823] [086/156] (616s left) kms_busy (extended-modeset-hang-newfb)
[420.004503] Starting subtest: extended-modeset-hang-newfb
[420.005444] Starting dynamic subtest: pipe-A
[435.563369] Dynamic subtest pipe-A: SUCCESS (15.559s)
[435.576977] Starting dynamic subtest: pipe-D
[451.122402] Dynamic subtest pipe-D: SUCCESS (15.556s)
[451.123234] Subtest extended-modeset-hang-newfb: SUCCESS (31.121s)
[451.346441] [087/156] (583s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-pwrite)
[452.844862] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-pwrite
[452.846660] Subtest fbc-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[453.016737] [088/156] (581s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[454.514947] Starting subtest: hdr-1p-offscreen-pri-indfb-draw-mmap-gtt
[454.519230] Subtest hdr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[454.689544] [089/156] (580s left) kms_vblank (ts-continuation-dpms-rpm)
[456.179291] Starting subtest: ts-continuation-dpms-rpm
[456.180207] Starting dynamic subtest: pipe-A-HDMI-A-4
[458.633863] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (2.453s)
[458.648442] Starting dynamic subtest: pipe-D-HDMI-A-4
[460.702610] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (2.065s)
[460.703569] Subtest ts-continuation-dpms-rpm: SUCCESS (4.525s)
[460.908429] [090/156] (574s left) kms_addfb_basic (addfb25-4-tiled)
[462.399369] Starting subtest: addfb25-4-tiled
[462.400270] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[462.591443] [091/156] (572s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[464.088122] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[464.089989] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[464.301399] [092/156] (570s left) i915_query (query-regions-unallocated)
[464.364963] Starting subtest: query-regions-unallocated
[464.827305] Subtest query-regions-unallocated: SUCCESS (0.463s)
[464.943712] [093/156] (570s left) syncobj_timeline (multi-wait-all-available-submitted-signaled)
[464.991433] Starting subtest: multi-wait-all-available-submitted-signaled
[464.992349] Subtest multi-wait-all-available-submitted-signaled: SUCCESS (0.001s)
[465.105907] [094/156] (570s left) kms_frontbuffer_tracking (fbchdr-rgb101010-draw-render)
[466.601813] Starting subtest: fbchdr-rgb101010-draw-render
[466.603500] Subtest fbchdr-rgb101010-draw-render: SKIP (0.000s)
[466.792360] [095/156] (568s left) dmabuf_sync_file (export-multiwait)
[466.854177] Starting subtest: export-multiwait
[466.855049] Subtest export-multiwait: SUCCESS (0.001s)
[466.971528] [096/156] (568s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[468.479860] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[469.736866] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.256s)
[469.980638] [097/156] (565s left) kms_atomic (plane-primary-legacy)
[471.470457] Starting subtest: plane-primary-legacy
[471.471311] Starting dynamic subtest: pipe-A-HDMI-A-4
[472.041126] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.570s)
[472.042308] Subtest plane-primary-legacy: SUCCESS (0.573s)
[472.249882] [098/156] (563s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[473.740421] Starting subtest: read-crc-frame-sequence
[473.741329] Starting dynamic subtest: pipe-A-HDMI-A-4
[474.451864] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.711s)
[474.464364] Starting dynamic subtest: pipe-B-HDMI-A-4
[474.997960] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.543s)
[475.010459] Starting dynamic subtest: pipe-C-HDMI-A-4
[475.511876] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.512s)
[475.524786] Starting dynamic subtest: pipe-D-HDMI-A-4
[476.032325] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.518s)
[476.033258] Subtest read-crc-frame-sequence: SUCCESS (2.294s)
[476.245045] [099/156] (559s left) kms_pm_rpm (dpms-non-lpsp)
[480.065304] Starting subtest: dpms-non-lpsp
[480.175803] Subtest dpms-non-lpsp: SUCCESS (0.112s)
[481.023163] [100/156] (554s left) kms_chamelium_audio (dp-audio)
[482.564950] Subtest dp-audio: SKIP (0.000s)
[482.703717] [101/156] (553s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted)
[482.753639] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[482.754749] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s)
[482.896408] [102/156] (553s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[484.391230] Starting subtest: fbc-rgb565-draw-mmap-wc
[484.393232] Subtest fbc-rgb565-draw-mmap-wc: SKIP (0.000s)
[484.556760] [103/156] (551s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[486.054937] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-cpu
[486.056858] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[486.227562] [104/156] (550s left) gem_wait (invalid-flags)
[486.281360] Starting subtest: invalid-flags
[486.282309] Subtest invalid-flags: SUCCESS (0.000s)
[486.601241] [105/156] (549s left) gem_pxp (hw-rejects-pxp-context)
[489.171419] Starting subtest: hw-rejects-pxp-context
[489.172418] Subtest hw-rejects-pxp-context: SUCCESS (0.002s)
[489.297643] [106/156] (547s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[490.792866] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[490.794663] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[490.952676] [107/156] (545s left) kms_content_protection (atomic-dpms)
[492.506927] Starting subtest: atomic-dpms
[492.511253] Subtest atomic-dpms: SKIP (0.002s)
[492.713767] [108/156] (544s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-blt)
[494.208851] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-blt
[494.210619] Subtest fbchdr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[494.370467] [109/156] (542s left) kms_prop_blob (blob-prop-lifetime)
[494.421843] Starting subtest: blob-prop-lifetime
[494.422900] Subtest blob-prop-lifetime: SUCCESS (0.001s)
[494.558413] [110/156] (542s left) gem_exec_schedule (u-semaphore-user)
[494.621833] Starting subtest: u-semaphore-user
[494.652337] Subtest u-semaphore-user: SUCCESS (0.030s)
[495.076974] [111/156] (541s left) gem_exec_balancer (indices)
[495.134705] Starting subtest: indices
[497.214224] Subtest indices: SUCCESS (2.080s)
[497.635841] [112/156] (539s left) gem_exec_params (rel-constants-invalid-ring)
[497.688444] Starting subtest: rel-constants-invalid-ring
[497.689314] Subtest rel-constants-invalid-ring: SUCCESS (0.000s)
[497.828894] [113/156] (539s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-indfb-msflip-blt)
[499.324700] Starting subtest: fbcpsrhdr-2p-primscrn-indfb-msflip-blt
[499.326486] Subtest fbcpsrhdr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[499.488451] [114/156] (537s left) gem_exec_alignment (single)
[499.543261] Starting subtest: single
[499.549870] Subtest single: SUCCESS (0.007s)
[499.757983] [115/156] (537s left) gem_ctx_persistence (legacy-engines-hostile-preempt)
[499.810572] Starting subtest: legacy-engines-hostile-preempt
[499.811471] Starting dynamic subtest: render
[499.879169] Dynamic subtest render: SUCCESS (0.069s)
[499.893146] Starting dynamic subtest: bsd1
[499.917217] Dynamic subtest bsd1: SUCCESS (0.036s)
[499.935633] Starting dynamic subtest: bsd2
[499.967464] Dynamic subtest bsd2: SUCCESS (0.048s)
[499.997572] Starting dynamic subtest: blt
[500.028432] Dynamic subtest blt: SUCCESS (0.058s)
[500.043242] Starting dynamic subtest: vebox
[500.082153] Dynamic subtest vebox: SUCCESS (0.051s)
[500.083286] Subtest legacy-engines-hostile-preempt: SUCCESS (0.274s)
[500.238364] [116/156] (537s left) syncobj_timeline (signal-point-0)
[500.287064] Starting subtest: signal-point-0
[500.288045] Subtest signal-point-0: SUCCESS (0.000s)
[500.427813] [117/156] (537s left) gem_userptr_blits (relocations)
[500.481241] Starting subtest: relocations
[500.483331] Subtest relocations: SKIP (0.001s)
[500.705622] [118/156] (536s left) kms_plane_multiple (tiling-y)
[502.198450] Starting subtest: tiling-y
[502.199266] Starting dynamic subtest: pipe-A-HDMI-A-4
[503.157402] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.959s)
[503.170558] Starting dynamic subtest: pipe-B-HDMI-A-4
[504.004099] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.845s)
[504.017215] Starting dynamic subtest: pipe-C-HDMI-A-4
[504.876253] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.870s)
[504.889483] Starting dynamic subtest: pipe-D-HDMI-A-4
[505.749953] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.871s)
[505.750879] Subtest tiling-y: SUCCESS (3.554s)
[505.967807] [119/156] (531s left) gem_exec_await (wide-all)
[506.114700] Starting subtest: wide-all
[528.364275] Subtest wide-all: SUCCESS (22.250s)
[528.619519] [120/156] (509s left) gem_exec_params (invalid-fence-in-submit)
[528.740784] Starting subtest: invalid-fence-in-submit
[528.745214] Subtest invalid-fence-in-submit: SUCCESS (0.003s)
[528.950904] [121/156] (508s left) kms_flip (2x-wf_vblank-ts-check)
[530.450176] Starting subtest: 2x-wf_vblank-ts-check
[530.466623] Subtest 2x-wf_vblank-ts-check: SKIP (0.017s)
[530.600310] [122/156] (507s left) api_intel_bb (crc32)
[530.654191] Starting subtest: crc32
[530.656414] Subtest crc32: SKIP (0.000s)
[530.827480] [123/156] (507s left) kms_big_fb (yf-tiled-addfb-size-overflow)
[532.341471] Starting subtest: yf-tiled-addfb-size-overflow
[532.343303] Subtest yf-tiled-addfb-size-overflow: SKIP (0.000s)
[532.508869] [124/156] (505s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-plflip-blt)
[534.004835] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-plflip-blt
[534.006538] Subtest fbcpsrhdr-1p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[534.183674] [125/156] (504s left) gem_exec_whisper (basic-fds-forked-all)
[534.246604] Starting subtest: basic-fds-forked-all
[547.849413] Subtest basic-fds-forked-all: SUCCESS (13.596s)
[548.122771] [126/156] (490s left) syncobj_wait (signal)
[548.187116] Starting subtest: signal
[548.189402] Subtest signal: SUCCESS (0.002s)
[548.310431] [127/156] (490s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-cc-modifier-source-clamping)
[549.803799] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-cc-modifier-source-clamping
[549.838530] Starting dynamic subtest: pipe-A-plane-0
[550.405719] Dynamic subtest pipe-A-plane-0: SUCCESS (0.566s)
[550.448034] Starting dynamic subtest: pipe-A-plane-3
[550.756179] Dynamic subtest pipe-A-plane-3: SUCCESS (0.344s)
[550.773180] Starting dynamic subtest: pipe-A-plane-5
[551.105075] Dynamic subtest pipe-A-plane-5: SUCCESS (0.344s)
[551.320910] Starting dynamic subtest: pipe-B-plane-0
[551.694164] Dynamic subtest pipe-B-plane-0: SUCCESS (0.383s)
[551.742730] Starting dynamic subtest: pipe-B-plane-3
[552.026468] Dynamic subtest pipe-B-plane-3: SUCCESS (0.325s)
[552.069390] Starting dynamic subtest: pipe-B-plane-5
[552.393118] Dynamic subtest pipe-B-plane-5: SUCCESS (0.363s)
[552.468399] Subtest pixel-format-y-tiled-gen12-rc-ccs-cc-modifier-source-clamping: SUCCESS (2.665s)
[552.658359] [128/156] (485s left) kms_psr (psr-sprite-mmap-gtt)
[554.151435] Subtest psr-sprite-mmap-gtt: SKIP (0.000s)
[554.227924] [129/156] (484s left) kms_ccs (bad-rotation-90-y-tiled-ccs)
[555.719695] Starting subtest: bad-rotation-90-y-tiled-ccs
[555.720643] Starting dynamic subtest: pipe-A-HDMI-A-4
[555.729542] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.000s)
[555.741633] Starting dynamic subtest: pipe-B-HDMI-A-4
[555.750407] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.000s)
[555.762258] Starting dynamic subtest: pipe-C-HDMI-A-4
[555.771955] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.000s)
[555.783759] Starting dynamic subtest: pipe-D-HDMI-A-4
[555.793107] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.000s)
[555.795027] Subtest bad-rotation-90-y-tiled-ccs: SKIP (0.010s)
[556.021308] [130/156] (482s left) vgem_basic (setversion)
[556.079894] Starting subtest: setversion
[556.081610] Subtest setversion: SUCCESS (0.000s)
[556.202589] [131/156] (482s left) gem_exec_create (madvise)
[556.259769] Starting subtest: madvise
[556.260625] Starting dynamic subtest: smem
[576.390032] Dynamic subtest smem: SUCCESS (20.128s)
[576.403823] Starting dynamic subtest: lmem0
[596.614605] Dynamic subtest lmem0: SUCCESS (20.222s)
[596.615454] Subtest madvise: SUCCESS (40.353s)
[597.728487] [132/156] (441s left) kms_frontbuffer_tracking (hdr-rgb101010-draw-mmap-gtt)
[599.223885] Starting subtest: hdr-rgb101010-draw-mmap-gtt
[599.225785] Subtest hdr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[599.403184] [133/156] (439s left) kms_color_pipeline (plane-lut1d-pre-ctm3x4)
[600.902954] Starting subtest: plane-lut1d-pre-ctm3x4
[601.101737] Starting dynamic subtest: pipe-A-plane-0
[601.380826] Dynamic subtest pipe-A-plane-0: SUCCESS (0.280s)
[601.413717] Starting dynamic subtest: pipe-A-plane-1
[601.582047] Dynamic subtest pipe-A-plane-1: SUCCESS (0.196s)
[601.623248] Starting dynamic subtest: pipe-A-plane-2
[601.798753] Dynamic subtest pipe-A-plane-2: SUCCESS (0.209s)
[602.117101] Starting dynamic subtest: pipe-B-plane-0
[602.295758] Dynamic subtest pipe-B-plane-0: SUCCESS (0.213s)
[602.327115] Starting dynamic subtest: pipe-B-plane-1
[602.495985] Dynamic subtest pipe-B-plane-1: SUCCESS (0.192s)
[602.527166] Starting dynamic subtest: pipe-B-plane-2
[602.696054] Dynamic subtest pipe-B-plane-2: SUCCESS (0.192s)
[603.041229] Starting dynamic subtest: pipe-C-plane-0
[603.212862] Dynamic subtest pipe-C-plane-0: SUCCESS (0.197s)
[603.253227] Starting dynamic subtest: pipe-C-plane-1
[603.427362] Dynamic subtest pipe-C-plane-1: SUCCESS (0.207s)
[603.441280] Starting dynamic subtest: pipe-C-plane-2
[603.612654] Dynamic subtest pipe-C-plane-2: SUCCESS (0.183s)
[603.954485] Starting dynamic subtest: pipe-D-plane-0
[604.105829] Dynamic subtest pipe-D-plane-0: SUCCESS (0.179s)
[604.118129] Starting dynamic subtest: pipe-D-plane-1
[604.289678] Dynamic subtest pipe-D-plane-1: SUCCESS (0.183s)
[604.325533] Starting dynamic subtest: pipe-D-plane-2
[604.506426] Dynamic subtest pipe-D-plane-2: SUCCESS (0.209s)
[604.560209] Subtest plane-lut1d-pre-ctm3x4: SUCCESS (3.660s)
[604.768647] [134/156] (434s left) kms_pm_backlight (fade)
[606.259205] Subtest fade: SKIP (0.000s)
[606.335167] [135/156] (432s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[607.829141] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[607.902519] [136/156] (431s left) i915_pciid
[608.066441] [137/156] (431s left) perf_pmu (all-busy-idle-check-all)
[608.118522] Starting subtest: all-busy-idle-check-all
[608.786656] Subtest all-busy-idle-check-all: SUCCESS (0.668s)
[608.936240] [138/156] (430s left) kms_cursor_legacy (2x-cursor-vs-flip-legacy)
[610.426345] Starting subtest: 2x-cursor-vs-flip-legacy
[610.428717] Subtest 2x-cursor-vs-flip-legacy: SKIP (0.002s)
[610.553333] [139/156] (428s left) kms_joiner (basic-force-big-joiner)
[612.054066] Starting subtest: basic-force-big-joiner
[612.055967] Subtest basic-force-big-joiner: SKIP (0.000s)
[612.191477] [140/156] (427s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[613.698651] Starting subtest: fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[613.700247] Subtest fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[613.897547] [141/156] (425s left) kms_psr (fbc-psr2-suspend)
[615.387751] Subtest fbc-psr2-suspend: SKIP (0.000s)
[615.469780] [142/156] (424s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-onoff)
[616.967269] Starting subtest: hdr-1p-primscrn-cur-indfb-onoff
[616.969517] Subtest hdr-1p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[617.162791] [143/156] (422s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt)
[618.657691] Starting subtest: fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt
[618.659310] Subtest fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[618.911462] [144/156] (421s left) gem_fence_thrash (bo-write-verify-x)
[618.955800] Starting subtest: bo-write-verify-x
[618.958308] Subtest bo-write-verify-x: SKIP (0.003s)
[619.036452] [145/156] (421s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-downscaling)
[620.527833] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-downscaling
[620.529578] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-downscaling: SKIP (0.000s)
[620.652806] [146/156] (419s left) gem_ppgtt (shrink-vs-evict-pinned)
[620.713320] Starting subtest: shrink-vs-evict-pinned
[627.904248] Subtest shrink-vs-evict-pinned: SUCCESS (7.188s)
[628.029141] [147/156] (412s left) kms_ccs (crc-sprite-planes-basic-y-tiled-ccs)
[629.518339] Starting subtest: crc-sprite-planes-basic-y-tiled-ccs
[629.519187] Starting dynamic subtest: pipe-A-HDMI-A-4
[629.541045] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.003s)
[629.553404] Starting dynamic subtest: pipe-B-HDMI-A-4
[629.575140] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.003s)
[629.587337] Starting dynamic subtest: pipe-C-HDMI-A-4
[629.610108] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.003s)
[629.622147] Starting dynamic subtest: pipe-D-HDMI-A-4
[629.646988] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.003s)
[629.648815] Subtest crc-sprite-planes-basic-y-tiled-ccs: SKIP (0.021s)
[629.829489] [148/156] (410s left) gen9_exec_parse (secure-batches)
[629.882853] Subtest secure-batches: SKIP (0.000s)
[629.967186] [149/156] (410s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-render)
[631.468466] Starting subtest: psr-1p-primscrn-cur-indfb-draw-render
[631.470183] Subtest psr-1p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[631.661633] [150/156] (408s left) gem_exec_reloc (basic-gtt-noreloc)
[631.715496] Subtest basic-gtt-noreloc: SKIP (0.000s)
[631.817994] [151/156] (408s left) kms_frontbuffer_tracking (hdr-1p-rte)
[633.312034] Starting subtest: hdr-1p-rte
[633.313876] Subtest hdr-1p-rte: SKIP (0.000s)
[633.502229] [152/156] (407s left) gem_exec_schedule (preempt-hang)
[633.709074] Starting subtest: preempt-hang
[633.711393] Starting dynamic subtest: rcs0
[634.006544] Dynamic subtest rcs0: SUCCESS (0.302s)
[634.019224] Starting dynamic subtest: bcs0
[634.303957] Dynamic subtest bcs0: SUCCESS (0.296s)
[634.316549] Starting dynamic subtest: vcs0
[634.608773] Dynamic subtest vcs0: SUCCESS (0.303s)
[634.621591] Starting dynamic subtest: vcs1
[634.917888] Dynamic subtest vcs1: SUCCESS (0.308s)
[634.930588] Starting dynamic subtest: vecs0
[635.233667] Dynamic subtest vecs0: SUCCESS (0.314s)
[635.234511] Subtest preempt-hang: SUCCESS (1.534s)
[635.575314] [153/156] (405s left) gem_ctx_shared (q-smoketest-all)
[635.638554] Starting subtest: Q-smoketest-all
[667.932791] Subtest Q-smoketest-all: SUCCESS (32.282s)
[668.227641] [154/156] (372s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted)
[668.274864] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted
[668.380231] Subtest etime-multi-wait-all-available-unsubmitted-submitted: SUCCESS (0.103s)
[668.552181] [155/156] (372s left) kms_cursor_edge_walk (64x64-top-edge)
[670.041285] Starting subtest: 64x64-top-edge
[670.042181] Starting dynamic subtest: pipe-A-HDMI-A-4
[673.416961] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (3.374s)
[673.431166] Starting dynamic subtest: pipe-D-HDMI-A-4
[676.663685] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (3.244s)
[676.664616] Subtest 64x64-top-edge: SUCCESS (6.624s)
[676.869189] [156/156] (364s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-cur-indfb-draw-pwrite)
[678.364222] Starting subtest: fbchdr-2p-scndscrn-cur-indfb-draw-pwrite
[678.365864] Subtest fbchdr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[678.516420] Closing watchdogs
results: parsing output: 0/ for test: kms_chamelium_frames
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: kms_cursor_legacy
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_flip
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_big_fb
results: parsing output: 12/ for test: kms_plane_scaling
results: parsing output: 13/ for test: kms_ccs
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: gem_create
results: parsing output: 16/ for test: syncobj_timeline
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: gem_ctx_param
results: parsing output: 19/ for test: kms_psr
results: parsing output: 20/ for test: gem_userptr_blits
results: parsing output: 21/ for test: i915_query
results: parsing output: 22/ for test: gem_render_copy
results: parsing output: 23/ for test: gem_exec_reloc
results: parsing output: 24/ for test: i915_pm_rpm
results: parsing output: 25/ for test: kms_psr
results: parsing output: 26/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 27/ for test: syncobj_timeline
results: parsing output: 28/ for test: syncobj_wait
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: gem_exec_parallel
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: syncobj_wait
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: perf_pmu
results: parsing output: 35/ for test: kms_dsc
results: parsing output: 36/ for test: kms_cursor_legacy
results: parsing output: 37/ for test: syncobj_wait
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: gem_wait
results: parsing output: 40/ for test: gem_close_race
results: parsing output: 41/ for test: kms_lease
results: parsing output: 42/ for test: prime_busy
results: parsing output: 43/ for test: kms_getfb
results: parsing output: 44/ for test: gem_close
results: parsing output: 45/ for test: gem_ccs
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_ccs
results: parsing output: 48/ for test: gem_userptr_blits
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: kms_big_fb
results: parsing output: 51/ for test: kms_cursor_crc
results: parsing output: 52/ for test: core_getclient
results: parsing output: 53/ for test: gem_mmap_gtt
results: parsing output: 54/ for test: kms_flip
results: parsing output: 55/ for test: kms_flip
results: parsing output: 56/ for test: kms_rotation_crc
results: parsing output: 57/ for test: gem_mmap_gtt
results: parsing output: 58/ for test: gem_huc_copy
results: parsing output: 59/ for test: kms_big_fb
results: parsing output: 60/ for test: kms_psr
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_psr
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: i915_drm_fdinfo
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: gem_mmap_gtt
results: parsing output: 68/ for test: kms_big_fb
results: parsing output: 69/ for test: gem_ctx_persistence
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: gem_spin_batch
results: parsing output: 72/ for test: kms_cursor_crc
results: parsing output: 73/ for test: perf_pmu
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_flip_scaled_crc
results: parsing output: 76/ for test: kms_chamelium_hpd
results: parsing output: 77/ for test: kms_cursor_legacy
results: parsing output: 78/ for test: kms_properties
results: parsing output: 79/ for test: gem_mmap_offset
results: parsing output: 80/ for test: gem_exec_fence
results: parsing output: 81/ for test: sysfs_timeslice_duration
results: parsing output: 82/ for test: gem_exec_fence
results: parsing output: 83/ for test: syncobj_wait
results: parsing output: 84/ for test: kms_vblank
results: parsing output: 85/ for test: kms_busy
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: kms_vblank
results: parsing output: 89/ for test: kms_addfb_basic
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: i915_query
results: parsing output: 92/ for test: syncobj_timeline
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: dmabuf_sync_file
results: parsing output: 95/ for test: kms_big_fb
results: parsing output: 96/ for test: kms_atomic
results: parsing output: 97/ for test: kms_pipe_crc_basic
results: parsing output: 98/ for test: kms_pm_rpm
results: parsing output: 99/ for test: kms_chamelium_audio
results: parsing output: 100/ for test: syncobj_wait
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: gem_wait
results: parsing output: 104/ for test: gem_pxp
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_content_protection
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_prop_blob
results: parsing output: 109/ for test: gem_exec_schedule
results: parsing output: 110/ for test: gem_exec_balancer
results: parsing output: 111/ for test: gem_exec_params
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: gem_exec_alignment
results: parsing output: 114/ for test: gem_ctx_persistence
results: parsing output: 115/ for test: syncobj_timeline
results: parsing output: 116/ for test: gem_userptr_blits
results: parsing output: 117/ for test: kms_plane_multiple
results: parsing output: 118/ for test: gem_exec_await
results: parsing output: 119/ for test: gem_exec_params
results: parsing output: 120/ for test: kms_flip
results: parsing output: 121/ for test: api_intel_bb
results: parsing output: 122/ for test: kms_big_fb
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: gem_exec_whisper
results: parsing output: 125/ for test: syncobj_wait
results: parsing output: 126/ for test: kms_plane
results: parsing output: 127/ for test: kms_psr
results: parsing output: 128/ for test: kms_ccs
results: parsing output: 129/ for test: vgem_basic
results: parsing output: 130/ for test: gem_exec_create
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: kms_color_pipeline
results: parsing output: 133/ for test: kms_pm_backlight
results: parsing output: 134/ for test: kms_psr2_sf
results: parsing output: 135/ for test: i915_pciid
results: parsing output: 136/ for test: perf_pmu
results: parsing output: 137/ for test: kms_cursor_legacy
results: parsing output: 138/ for test: kms_joiner
results: parsing output: 139/ for test: kms_frontbuffer_tracking
results: parsing output: 140/ for test: kms_psr
results: parsing output: 141/ for test: kms_frontbuffer_tracking
results: parsing output: 142/ for test: kms_frontbuffer_tracking
results: parsing output: 143/ for test: gem_fence_thrash
results: parsing output: 144/ for test: kms_flip_scaled_crc
results: parsing output: 145/ for test: gem_ppgtt
results: parsing output: 146/ for test: kms_ccs
results: parsing output: 147/ for test: gen9_exec_parse
results: parsing output: 148/ for test: kms_frontbuffer_tracking
results: parsing output: 149/ for test: gem_exec_reloc
results: parsing output: 150/ for test: kms_frontbuffer_tracking
results: parsing output: 151/ for test: gem_exec_schedule
results: parsing output: 152/ for test: gem_ctx_shared
results: parsing output: 153/ for test: syncobj_timeline
results: parsing output: 154/ for test: kms_cursor_edge_walk
results: parsing output: 155/ for test: kms_frontbuffer_tracking
Done.