[70.745885] Initializing watchdogs
[70.745969]   /dev/watchdog0
[70.776515] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:4905 Intel Dg1 (Gen12) DG1 [Iris Xe MAX Graphics]
[70.790564] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[70.796979] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[70.800464] [001/134] (960s left) syncobj_timeline (multi-wait-for-submit-signaled)
[70.856032] Starting subtest: multi-wait-for-submit-signaled
[70.857413] Subtest multi-wait-for-submit-signaled: SUCCESS (0.001s)
[70.973073] [002/134] (960s left) gem_wait (write-busy)
[71.051398] Starting subtest: write-busy
[71.052909] Starting dynamic subtest: all-engines
[71.955718] Dynamic subtest all-engines: SUCCESS (0.901s)
[71.964242] Starting dynamic subtest: rcs0
[72.494325] Dynamic subtest rcs0: SUCCESS (0.541s)
[72.502624] Starting dynamic subtest: bcs0
[73.034339] Dynamic subtest bcs0: SUCCESS (0.540s)
[73.042901] Starting dynamic subtest: vcs0
[73.575560] Dynamic subtest vcs0: SUCCESS (0.541s)
[73.584285] Starting dynamic subtest: vcs1
[74.116364] Dynamic subtest vcs1: SUCCESS (0.541s)
[74.124850] Starting dynamic subtest: vecs0
[74.656656] Dynamic subtest vecs0: SUCCESS (0.540s)
[74.658099] Subtest write-busy: SUCCESS (3.606s)
[75.020164] [FACT gem_wait (write-busy)] new: kernel.kmod_is_loaded.vgem: true
[75.025404] [003/134] (956s left) syncobj_timeline (invalid-single-wait-all-unsubmitted)
[75.071008] Starting subtest: invalid-single-wait-all-unsubmitted
[75.072561] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[75.230286] [004/134] (956s left) kms_cursor_edge_walk (64x64-left-edge)
[76.714677] Starting subtest: 64x64-left-edge
[76.716150] Starting dynamic subtest: pipe-A-HDMI-A-4
[80.071828] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (3.356s)
[80.082845] Starting dynamic subtest: pipe-D-HDMI-A-4
[83.296411] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (3.222s)
[83.297937] Subtest 64x64-left-edge: SUCCESS (6.582s)
[83.509188] [005/134] (948s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[85.014725] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip
[86.257948] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (1.244s)
[86.492707] [006/134] (945s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-signaled)
[86.540378] Starting subtest: etime-multi-wait-all-available-unsubmitted-signaled
[86.646502] Subtest etime-multi-wait-all-available-unsubmitted-signaled: SUCCESS (0.104s)
[86.834772] [007/134] (944s left) kms_ccs (ccs-on-another-bo-yf-tiled-ccs)
[88.317654] Starting subtest: ccs-on-another-bo-yf-tiled-ccs
[88.319297] Starting dynamic subtest: pipe-A-HDMI-A-4
[88.343633] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.000s)
[88.352623] Starting dynamic subtest: pipe-B-HDMI-A-4
[88.366505] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.000s)
[88.374931] Starting dynamic subtest: pipe-C-HDMI-A-4
[88.389560] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.000s)
[88.398034] Starting dynamic subtest: pipe-D-HDMI-A-4
[88.413109] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.000s)
[88.416145] Subtest ccs-on-another-bo-yf-tiled-ccs: SKIP (0.006s)
[88.592321] [008/134] (943s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf)
[90.087122] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[90.162134] [009/134] (941s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[91.649783] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[91.651190] Starting dynamic subtest: pipe-A-HDMI-A-4
[91.687182] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.003s)
[91.695892] Starting dynamic subtest: pipe-B-HDMI-A-4
[91.733533] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.003s)
[91.742400] Starting dynamic subtest: pipe-C-HDMI-A-4
[91.786325] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.003s)
[91.795121] Starting dynamic subtest: pipe-D-HDMI-A-4
[91.835534] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.003s)
[91.838571] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SKIP (0.016s)
[91.945095] [010/134] (940s left) perf_pmu (interrupts-sync)
[91.994729] Starting subtest: interrupts-sync
[93.129027] Subtest interrupts-sync: SUCCESS (1.130s)
[93.299961] [011/134] (938s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc)
[94.789955] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc
[94.792772] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[95.037755] [012/134] (937s left) kms_chamelium_hpd (hdmi-hpd)
[96.577232] Subtest hdmi-hpd: SKIP (0.000s)
[96.693521] [013/134] (935s left) kms_pm_rpm (system-suspend-idle)
[101.113132] Starting subtest: system-suspend-idle
[120.659219] Subtest system-suspend-idle: SUCCESS (4.546s)
[124.769397] [014/134] (910s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[126.922089] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-blt
[126.925194] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[127.190441] [015/134] (908s left) syncobj_timeline (wait-all-for-submit-delayed-submit)
[127.236530] Starting subtest: wait-all-for-submit-delayed-submit
[127.342635] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.104s)
[127.531895] [016/134] (908s left) gem_exec_parallel (fds)
[127.590575] Starting subtest: fds
[127.592288] Starting dynamic subtest: rcs0
[132.485329] Dynamic subtest rcs0: SUCCESS (4.890s)
[132.496871] Starting dynamic subtest: bcs0
[136.910546] Dynamic subtest bcs0: SUCCESS (4.424s)
[136.921836] Starting dynamic subtest: vcs0
[141.610449] Dynamic subtest vcs0: SUCCESS (4.702s)
[141.621860] Starting dynamic subtest: vcs1
[145.808666] Dynamic subtest vcs1: SUCCESS (4.194s)
[145.820304] Starting dynamic subtest: vecs0
[150.899432] Dynamic subtest vecs0: SUCCESS (5.090s)
[150.901013] Subtest fds: SUCCESS (23.302s)
[151.210213] [017/134] (884s left) kms_chamelium_frames (dp-crc-single)
[152.742848] Subtest dp-crc-single: SKIP (0.000s)
[152.856428] [018/134] (882s left) api_intel_bb (destroy-bb)
[152.901861] Starting subtest: destroy-bb
[152.928642] Subtest destroy-bb: SUCCESS (0.028s)
[153.144612] [019/134] (882s left) gem_mmap_gtt (basic-small-bo)
[153.193896] Subtest basic-small-bo: SKIP (0.000s)
[153.266770] [020/134] (882s left) gem_pxp (dmabuf-shared-protected-dst-is-context-refcounted)
[155.831511] Starting subtest: dmabuf-shared-protected-dst-is-context-refcounted
[155.834935] Subtest dmabuf-shared-protected-dst-is-context-refcounted: SKIP (0.000s)
[155.975411] [021/134] (880s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-render)
[157.465193] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-render
[157.468082] Subtest fbc-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[157.702468] [022/134] (878s left) i915_query (query-topology-garbage-items)
[157.746817] Starting subtest: query-topology-garbage-items
[157.748328] Subtest query-topology-garbage-items: SUCCESS (0.000s)
[157.921711] [023/134] (878s left) gem_ccs (block-copy-uncompressed)
[157.971561] Starting subtest: block-copy-uncompressed
[157.973155] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem
[158.005769] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.033s)
[158.017170] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-lmem0
[158.021711] Dynamic subtest linear-uncompressed-compfmt0-smem-lmem0: SUCCESS (0.016s)
[158.032960] Starting dynamic subtest: linear-uncompressed-compfmt0-lmem0-smem
[158.082021] Dynamic subtest linear-uncompressed-compfmt0-lmem0-smem: SUCCESS (0.060s)
[158.093305] Starting dynamic subtest: linear-uncompressed-compfmt0-lmem0-lmem0
[158.142098] Dynamic subtest linear-uncompressed-compfmt0-lmem0-lmem0: SUCCESS (0.060s)
[158.152935] Starting dynamic subtest: ymajor-uncompressed-compfmt0-smem-smem
[158.157948] Dynamic subtest ymajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.015s)
[158.168817] Starting dynamic subtest: ymajor-uncompressed-compfmt0-smem-lmem0
[158.172483] Dynamic subtest ymajor-uncompressed-compfmt0-smem-lmem0: SUCCESS (0.014s)
[158.183684] Starting dynamic subtest: ymajor-uncompressed-compfmt0-lmem0-smem
[158.233049] Dynamic subtest ymajor-uncompressed-compfmt0-lmem0-smem: SUCCESS (0.060s)
[158.244251] Starting dynamic subtest: ymajor-uncompressed-compfmt0-lmem0-lmem0
[158.292172] Dynamic subtest ymajor-uncompressed-compfmt0-lmem0-lmem0: SUCCESS (0.059s)
[158.294079] Subtest block-copy-uncompressed: SUCCESS (0.321s)
[158.479797] [024/134] (877s left) gem_exec_balancer (hang)
[158.893398] Starting subtest: hang
[161.724293] Subtest hang: SUCCESS (2.824s)
[161.906847] [025/134] (874s left) kms_psr (fbc-psr-cursor-mmap-cpu)
[163.391441] Subtest fbc-psr-cursor-mmap-cpu: SKIP (0.000s)
[163.494537] [026/134] (873s left) gem_exec_params (invalid-bsd2-flag-on-vebox)
[163.552544] Starting subtest: invalid-bsd2-flag-on-vebox
[163.553909] Subtest invalid-bsd2-flag-on-vebox: SUCCESS (0.000s)
[163.764523] [027/134] (872s left) prime_mmap (test_forked)
[163.811227] Starting subtest: test_forked
[163.812629] Starting dynamic subtest: test_forked-smem
[163.881206] Dynamic subtest test_forked-smem: SUCCESS (0.071s)
[163.892522] Starting dynamic subtest: test_forked-lmem0
[163.954756] Dynamic subtest test_forked-lmem0: SUCCESS (0.073s)
[163.956144] Subtest test_forked: SUCCESS (0.145s)
[164.144250] [028/134] (872s left) gem_gpgpu_fill (basic)
[164.187668] Starting subtest: basic
[164.189134] Starting dynamic subtest: smem
[164.208260] Dynamic subtest smem: SUCCESS (0.021s)
[164.223115] Starting dynamic subtest: lmem0
[164.224584] Dynamic subtest lmem0: SUCCESS (0.011s)
[164.226030] Subtest basic: SUCCESS (0.032s)
[164.432194] [029/134] (872s left) kms_psr (fbc-psr2-sprite-plane-move)
[165.918908] Subtest fbc-psr2-sprite-plane-move: SKIP (0.000s)
[166.002761] [030/134] (870s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-move)
[167.494113] Starting subtest: fbc-1p-primscrn-spr-indfb-move
[170.927396] Subtest fbc-1p-primscrn-spr-indfb-move: SUCCESS (3.434s)
[171.155191] [031/134] (865s left) gem_exec_gttfill (basic)
[171.213254] Starting subtest: basic
[178.066020] Subtest basic: SUCCESS (6.848s)
[178.329740] [032/134] (858s left) kms_frontbuffer_tracking (psr-rgb565-draw-blt)
[179.871257] Starting subtest: psr-rgb565-draw-blt
[179.873891] Subtest psr-rgb565-draw-blt: SKIP (0.000s)
[180.095500] [033/134] (857s left) gem_cs_tlb (engines)
[180.145959] Starting subtest: engines
[180.147347] Starting dynamic subtest: rcs0
[182.305146] Dynamic subtest rcs0: SUCCESS (2.155s)
[182.317198] Starting dynamic subtest: bcs0
[184.459150] Dynamic subtest bcs0: SUCCESS (2.153s)
[184.471245] Starting dynamic subtest: vcs0
[186.612325] Dynamic subtest vcs0: SUCCESS (2.152s)
[186.624504] Starting dynamic subtest: vcs1
[188.765787] Dynamic subtest vcs1: SUCCESS (2.153s)
[188.778451] Starting dynamic subtest: vecs0
[190.920173] Dynamic subtest vecs0: SUCCESS (2.153s)
[190.922060] Subtest engines: SUCCESS (10.769s)
[191.079453] [034/134] (846s left) gem_userptr_blits (forbidden-operations)
[191.132589] Starting subtest: forbidden-operations
[191.135473] Subtest forbidden-operations: SKIP (0.000s)
[191.367932] [035/134] (845s left) gem_exec_reloc (basic-range)
[191.418479] Subtest basic-range: SKIP (0.000s)
[191.550498] [036/134] (845s left) kms_pm_rpm (cursor-dpms)
[195.095616] Starting subtest: cursor-dpms
[195.100358] Subtest cursor-dpms: SKIP (0.000s)
[196.092123] [037/134] (841s left) i915_drm_fdinfo (most-busy-check-all)
[196.154497] Starting subtest: most-busy-check-all
[196.156134] Starting dynamic subtest: rcs0
[196.173624] Dynamic subtest rcs0: SKIP (0.017s)
[196.186708] Starting dynamic subtest: bcs0
[196.190412] Dynamic subtest bcs0: SKIP (0.000s)
[196.202806] Starting dynamic subtest: vcs0
[196.206183] Dynamic subtest vcs0: SKIP (0.000s)
[196.218524] Starting dynamic subtest: vcs1
[196.222142] Dynamic subtest vcs1: SKIP (0.000s)
[196.234364] Starting dynamic subtest: vecs0
[196.238098] Dynamic subtest vecs0: SKIP (0.000s)
[196.244748] Subtest most-busy-check-all: SKIP (0.021s)
[196.307264] [038/134] (841s left) kms_ccs (bad-rotation-90-4-tiled-bmg-ccs)
[197.806137] Starting subtest: bad-rotation-90-4-tiled-bmg-ccs
[197.809652] Subtest bad-rotation-90-4-tiled-bmg-ccs: SKIP (0.000s)
[198.024453] [039/134] (839s left) kms_psr (psr2-cursor-blt)
[199.510778] Subtest psr2-cursor-blt: SKIP (0.000s)
[199.589135] [040/134] (838s left) kms_invalid_mode (uint-max-clock)
[201.074692] Starting subtest: uint-max-clock
[201.076348] Starting dynamic subtest: pipe-A-HDMI-A-4
[201.115826] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.041s)
[201.128522] Starting dynamic subtest: pipe-B-HDMI-A-4
[201.130275] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.001s)
[201.142508] Starting dynamic subtest: pipe-C-HDMI-A-4
[201.146143] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.001s)
[201.158426] Starting dynamic subtest: pipe-D-HDMI-A-4
[201.160121] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.001s)
[201.161790] Subtest uint-max-clock: SUCCESS (0.049s)
[201.341234] [041/134] (836s left) kms_content_protection (lic-type-0-hdcp14)
[202.881553] Starting subtest: lic-type-0-hdcp14
[202.894067] Subtest lic-type-0-hdcp14: SKIP (0.006s)
[203.128282] [042/134] (834s left) gem_exec_nop (basic-parallel)
[203.197024] Starting subtest: basic-parallel
[206.915470] Subtest basic-parallel: SUCCESS (3.731s)
[207.285788] [043/134] (830s left) kms_flip (plain-flip-ts-check)
[208.772532] Starting subtest: plain-flip-ts-check
[208.778672] Starting dynamic subtest: A-HDMI-A4
[216.785985] Dynamic subtest A-HDMI-A4: SUCCESS (8.007s)
[216.799790] Starting dynamic subtest: B-HDMI-A4
[224.751132] Dynamic subtest B-HDMI-A4: SUCCESS (7.963s)
[224.764087] Starting dynamic subtest: C-HDMI-A4
[232.719989] Dynamic subtest C-HDMI-A4: SUCCESS (7.967s)
[232.732936] Starting dynamic subtest: D-HDMI-A4
[240.671041] Dynamic subtest D-HDMI-A4: SUCCESS (7.950s)
[240.672489] Subtest plain-flip-ts-check: SUCCESS (31.898s)
[240.965267] [044/134] (797s left) kms_rotation_crc (bad-pixel-format)
[242.448776] Starting subtest: bad-pixel-format
[244.486676] Subtest bad-pixel-format: SUCCESS (2.038s)
[244.766595] [045/134] (793s left) kms_frontbuffer_tracking (fbcpsr-indfb-scaledprimary)
[246.270224] Starting subtest: fbcpsr-indfb-scaledprimary
[246.273254] Subtest fbcpsr-indfb-scaledprimary: SKIP (0.000s)
[246.534312] [046/134] (791s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[248.043430] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[248.046478] Subtest psr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[248.317064] [047/134] (790s left) kms_flip_scaled_crc (flip-p016-linear-to-p016-linear-reflect-x)
[249.805063] Starting subtest: flip-P016-linear-to-P016-linear-reflect-x
[249.808203] Subtest flip-P016-linear-to-P016-linear-reflect-x: SKIP (0.000s)
[249.994818] [048/134] (788s left) kms_big_fb (linear-64bpp-rotate-90)
[251.504896] Starting subtest: linear-64bpp-rotate-90
[251.582329] Subtest linear-64bpp-rotate-90: SKIP (0.076s)
[251.851578] [049/134] (786s left) syncobj_wait (invalid-signal-illegal-handle)
[251.907041] Starting subtest: invalid-signal-illegal-handle
[251.908648] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[252.052529] [050/134] (786s left) kms_frontbuffer_tracking (psr-shrfb-scaledprimary)
[253.543014] Starting subtest: psr-shrfb-scaledprimary
[253.545900] Subtest psr-shrfb-scaledprimary: SKIP (0.000s)
[253.816537] [051/134] (785s left) gem_mmap_offset (partial-unmap)
[253.863468] Starting subtest: partial-unmap
[253.865003] Starting dynamic subtest: lmem0
[253.869193] Dynamic subtest lmem0: SUCCESS (0.009s)
[253.881832] Starting dynamic subtest: smem0
[253.883510] Dynamic subtest smem0: SUCCESS (0.005s)
[253.885268] Subtest partial-unmap: SUCCESS (0.016s)
[254.079632] [052/134] (785s left) gem_softpin (noreloc)
[254.135216] Starting subtest: noreloc
[259.534145] Subtest noreloc: SUCCESS (5.395s)
[259.698335] [053/134] (779s left) kms_feature_discovery (dp-mst)
[261.184416] Starting subtest: dp-mst
[261.187714] Subtest dp-mst: SKIP (0.000s)
[261.381893] [054/134] (777s left) kms_hdr (brightness-with-hdr)
[262.870106] Starting subtest: brightness-with-hdr
[262.874990] Subtest brightness-with-hdr: SKIP (0.001s)
[263.064560] [055/134] (776s left) gem_mmap_gtt (medium-copy-xy)
[263.113630] Subtest medium-copy-XY: SKIP (0.000s)
[263.189898] [056/134] (776s left) kms_vblank (wait-forked-busy)
[264.693587] Starting subtest: wait-forked-busy
[264.695052] Starting dynamic subtest: pipe-A-HDMI-A-4
[267.267019] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (2.570s)
[267.282477] Starting dynamic subtest: pipe-D-HDMI-A-4
[269.663598] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (2.391s)
[269.665347] Subtest wait-forked-busy: SUCCESS (4.968s)
[269.897364] [057/134] (769s left) syncobj_basic (create-signaled)
[269.941226] Starting subtest: create-signaled
[269.942740] Subtest create-signaled: SUCCESS (0.000s)
[270.070407] [058/134] (769s left) kms_mmap_write_crc (main)
[271.559266] Starting subtest: main
[271.560850] Starting dynamic subtest: pipe-A-HDMI-A-4
[275.325875] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (3.762s)
[275.327351] Subtest main: SUCCESS (3.764s)
[275.609405] [059/134] (764s left) testdisplay
[281.928390] [060/134] (757s left) kms_psr2_sf (pr-plane-move-sf-dmg-area)
[283.429252] Subtest pr-plane-move-sf-dmg-area: SKIP (0.000s)
[283.496959] [061/134] (756s left) gen7_exec_parse (oacontrol-tracking)
[283.604337] Subtest oacontrol-tracking: SKIP (0.000s)
[283.674624] [062/134] (756s left) gem_softpin (evict-snoop-interruptible)
[283.727004] Starting subtest: evict-snoop-interruptible
[283.729657] Subtest evict-snoop-interruptible: SKIP (0.000s)
[283.891351] [063/134] (756s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-fullscreen)
[285.383983] Starting subtest: psr-2p-primscrn-spr-indfb-fullscreen
[285.387249] Subtest psr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[285.636328] [064/134] (754s left) kms_plane_cursor (primary)
[287.139085] Starting subtest: primary
[287.142445] Starting dynamic subtest: pipe-A-HDMI-A-4-size-64
[290.331618] Dynamic subtest pipe-A-HDMI-A-4-size-64: SUCCESS (3.192s)
[290.405262] Starting dynamic subtest: pipe-A-HDMI-A-4-size-128
[293.563936] Dynamic subtest pipe-A-HDMI-A-4-size-128: SUCCESS (3.182s)
[293.657490] Starting dynamic subtest: pipe-A-HDMI-A-4-size-256
[296.848727] Dynamic subtest pipe-A-HDMI-A-4-size-256: SUCCESS (3.231s)
[296.954805] Starting dynamic subtest: pipe-B-HDMI-A-4-size-64
[300.136379] Dynamic subtest pipe-B-HDMI-A-4-size-64: SUCCESS (3.193s)
[300.193822] Starting dynamic subtest: pipe-B-HDMI-A-4-size-128
[303.353228] Dynamic subtest pipe-B-HDMI-A-4-size-128: SUCCESS (3.182s)
[303.412768] Starting dynamic subtest: pipe-B-HDMI-A-4-size-256
[306.587232] Dynamic subtest pipe-B-HDMI-A-4-size-256: SUCCESS (3.198s)
[306.678075] Starting dynamic subtest: pipe-C-HDMI-A-4-size-64
[309.899518] Dynamic subtest pipe-C-HDMI-A-4-size-64: SUCCESS (3.237s)
[309.957346] Starting dynamic subtest: pipe-C-HDMI-A-4-size-128
[313.098835] Dynamic subtest pipe-C-HDMI-A-4-size-128: SUCCESS (3.163s)
[313.170031] Starting dynamic subtest: pipe-C-HDMI-A-4-size-256
[316.366912] Dynamic subtest pipe-C-HDMI-A-4-size-256: SUCCESS (3.230s)
[316.454917] Starting dynamic subtest: pipe-D-HDMI-A-4-size-64
[319.663201] Dynamic subtest pipe-D-HDMI-A-4-size-64: SUCCESS (3.220s)
[319.719482] Starting dynamic subtest: pipe-D-HDMI-A-4-size-128
[322.930187] Dynamic subtest pipe-D-HDMI-A-4-size-128: SUCCESS (3.231s)
[322.987717] Starting dynamic subtest: pipe-D-HDMI-A-4-size-256
[326.130110] Dynamic subtest pipe-D-HDMI-A-4-size-256: SUCCESS (3.164s)
[326.187263] Subtest primary: SUCCESS (39.048s)
[326.438871] [065/134] (713s left) kms_async_flips (invalid-async-flip-atomic)
[327.925433] Starting subtest: invalid-async-flip-atomic
[328.125101] Starting dynamic subtest: pipe-A-HDMI-A-4
[328.550992] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.426s)
[328.734472] Starting dynamic subtest: pipe-B-HDMI-A-4
[328.829674] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.106s)
[328.995056] Starting dynamic subtest: pipe-C-HDMI-A-4
[329.094397] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.110s)
[329.252341] Starting dynamic subtest: pipe-D-HDMI-A-4
[329.344190] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.103s)
[329.345838] Subtest invalid-async-flip-atomic: SUCCESS (1.421s)
[329.766033] [066/134] (710s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[331.257509] Starting subtest: psr-1p-primscrn-spr-indfb-draw-render
[331.260070] Subtest psr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[331.516328] [067/134] (709s left) gem_pxp (reject-modify-context-protection-off-3)
[334.079986] Starting subtest: reject-modify-context-protection-off-3
[334.082746] Subtest reject-modify-context-protection-off-3: SKIP (0.000s)
[334.232384] [068/134] (706s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0)
[335.736110] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0
[335.749156] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[335.959575] [069/134] (704s left) gem_exec_reloc (basic-concurrent0)
[336.010531] Subtest basic-concurrent0: SKIP (0.000s)
[336.104175] [070/134] (704s left) sysfs_timeslice_duration (idempotent)
[336.154776] Starting subtest: idempotent
[336.156261] Starting dynamic subtest: vecs0
[336.157783] Dynamic subtest vecs0: SUCCESS (0.001s)
[336.170535] Starting dynamic subtest: vcs0
[336.172216] Dynamic subtest vcs0: SUCCESS (0.001s)
[336.184501] Starting dynamic subtest: bcs0
[336.186206] Dynamic subtest bcs0: SUCCESS (0.001s)
[336.198199] Starting dynamic subtest: rcs0
[336.199938] Dynamic subtest rcs0: SUCCESS (0.001s)
[336.212236] Starting dynamic subtest: vcs1
[336.214034] Dynamic subtest vcs1: SUCCESS (0.001s)
[336.215686] Subtest idempotent: SUCCESS (0.011s)
[336.344700] [071/134] (704s left) gem_exec_store (dword)
[336.401205] Starting subtest: dword
[336.402801] Starting dynamic subtest: rcs0
[336.490346] Dynamic subtest rcs0: SUCCESS (0.088s)
[336.513806] Starting dynamic subtest: bcs0
[336.533188] Dynamic subtest bcs0: SUCCESS (0.041s)
[336.557266] Starting dynamic subtest: vcs0
[336.577206] Dynamic subtest vcs0: SUCCESS (0.042s)
[336.601361] Starting dynamic subtest: vcs1
[336.621498] Dynamic subtest vcs1: SUCCESS (0.042s)
[336.645352] Starting dynamic subtest: vecs0
[336.665042] Dynamic subtest vecs0: SUCCESS (0.041s)
[336.666848] Subtest dword: SUCCESS (0.265s)
[336.986003] [072/134] (704s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-downscaling)
[338.474751] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-downscaling
[338.477895] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-downscaling: SKIP (0.000s)
[338.657381] [073/134] (702s left) gem_ctx_persistence (heartbeat-hostile)
[338.709863] Starting subtest: heartbeat-hostile
[338.713101] Subtest heartbeat-hostile: SKIP (0.000s)
[338.878816] [074/134] (702s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[340.409329] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[340.976051] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (0.567s)
[341.261881] [075/134] (700s left) sw_sync (sync_busy_fork)
[341.307408] Starting subtest: sync_busy_fork
[342.325752] Subtest sync_busy_fork: SUCCESS (1.015s)
[342.460283] [076/134] (699s left) kms_flip (2x-flip-vs-fences)
[343.943016] Starting subtest: 2x-flip-vs-fences
[343.946183] Subtest 2x-flip-vs-fences: SKIP (0.000s)
[344.153249] [077/134] (697s left) syncobj_wait (single-wait-for-submit-submitted)
[344.202351] Starting subtest: single-wait-for-submit-submitted
[344.311402] Subtest single-wait-for-submit-submitted: SUCCESS (0.107s)
[344.488056] [078/134] (697s left) gem_ctx_isolation (dirty-switch)
[344.546775] Starting subtest: dirty-switch
[344.548165] Starting dynamic subtest: rcs0
[345.285823] Dynamic subtest rcs0: SUCCESS (0.741s)
[345.298546] Starting dynamic subtest: bcs0
[346.080393] Dynamic subtest bcs0: SUCCESS (0.793s)
[346.093327] Starting dynamic subtest: vcs0
[346.897501] Dynamic subtest vcs0: SUCCESS (0.816s)
[346.909900] Starting dynamic subtest: vcs1
[347.692705] Dynamic subtest vcs1: SUCCESS (0.794s)
[347.705528] Starting dynamic subtest: vecs0
[348.483491] Dynamic subtest vecs0: SUCCESS (0.789s)
[348.485471] Subtest dirty-switch: SUCCESS (3.940s)
[348.831690] [079/134] (692s left) kms_big_fb (yf-tiled-32bpp-rotate-90)
[350.336041] Starting subtest: yf-tiled-32bpp-rotate-90
[350.339427] Subtest yf-tiled-32bpp-rotate-90: SKIP (0.000s)
[350.594615] [080/134] (691s left) sw_sync (alloc_timeline)
[350.638227] Starting subtest: alloc_timeline
[350.639900] Subtest alloc_timeline: SUCCESS (0.000s)
[350.828673] [081/134] (691s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[350.879199] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[350.881036] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.001s)
[351.043275] [082/134] (691s left) perf (invalid-oa-metric-set-id)
[351.219230] Starting subtest: invalid-oa-metric-set-id
[351.241960] Subtest invalid-oa-metric-set-id: SUCCESS (0.023s)
[351.334920] [083/134] (690s left) kms_vblank (crtc-id)
[352.819123] Starting subtest: crtc-id
[352.820489] Starting dynamic subtest: pipe-A-HDMI-A-4
[353.214074] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.395s)
[353.231132] Starting dynamic subtest: pipe-D-HDMI-A-4
[353.462092] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.241s)
[353.463630] Subtest crtc-id: SUCCESS (0.644s)
[353.660964] [084/134] (688s left) kms_cursor_crc (cursor-random-256x256)
[355.363351] Starting subtest: cursor-random-256x256
[355.367346] Starting dynamic subtest: pipe-A-HDMI-A-4
[359.952485] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (4.586s)
[359.990443] Starting dynamic subtest: pipe-D-HDMI-A-4
[364.432612] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (4.454s)
[364.434613] Subtest cursor-random-256x256: SUCCESS (9.072s)
[364.886155] [085/134] (677s left) gem_exec_alignment (pi-isolated)
[364.939270] Starting subtest: pi-isolated
[386.347386] Subtest pi-isolated: SUCCESS (21.409s)
[386.991265] [086/134] (655s left) gem_spin_batch (spin-each)
[387.108539] Starting subtest: spin-each
[390.388077] Subtest spin-each: SUCCESS (3.280s)
[390.729598] [087/134] (651s left) gem_ringfill (engines-basic)
[391.198485] Starting subtest: engines-basic
[391.200705] Starting dynamic subtest: rcs0
[391.294099] Dynamic subtest rcs0: SUCCESS (0.098s)
[391.325362] Starting dynamic subtest: bcs0
[391.339959] Dynamic subtest bcs0: SUCCESS (0.043s)
[391.369353] Starting dynamic subtest: vcs0
[391.387537] Dynamic subtest vcs0: SUCCESS (0.044s)
[391.405366] Starting dynamic subtest: vcs1
[391.429549] Dynamic subtest vcs1: SUCCESS (0.040s)
[391.448892] Starting dynamic subtest: vecs0
[391.471621] Dynamic subtest vecs0: SUCCESS (0.040s)
[391.473086] Subtest engines-basic: SUCCESS (0.278s)
[391.628351] [088/134] (651s left) i915_hangman (engine-error-state-capture)
[391.710743] Starting subtest: engine-error-state-capture
[391.712216] Starting dynamic subtest: rcs0
[393.467196] Dynamic subtest rcs0: SUCCESS (1.757s)
[393.484184] Starting dynamic subtest: bcs0
[397.479108] Dynamic subtest bcs0: SUCCESS (4.010s)
[397.492915] Starting dynamic subtest: vcs0
[401.451188] Dynamic subtest vcs0: SUCCESS (3.971s)
[401.468152] Starting dynamic subtest: vcs1
[405.476490] Dynamic subtest vcs1: SUCCESS (4.023s)
[405.489624] Starting dynamic subtest: vecs0
[409.491881] Dynamic subtest vecs0: SUCCESS (4.014s)
[409.493468] Subtest engine-error-state-capture: SUCCESS (17.784s)
[409.721144] [089/134] (633s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-render)
[411.223602] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-render
[411.226773] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-render: SKIP (0.000s)
[411.462700] [090/134] (631s left) perf_pmu (busy-accuracy-2)
[411.514106] Starting subtest: busy-accuracy-2
[411.515745] Starting dynamic subtest: rcs0
[414.569347] Dynamic subtest rcs0: SUCCESS (3.055s)
[414.582229] Starting dynamic subtest: bcs0
[417.622766] Dynamic subtest bcs0: SUCCESS (3.052s)
[417.635336] Starting dynamic subtest: vcs0
[420.678345] Dynamic subtest vcs0: SUCCESS (3.055s)
[420.691086] Starting dynamic subtest: vcs1
[423.734608] Dynamic subtest vcs1: SUCCESS (3.054s)
[423.747425] Starting dynamic subtest: vecs0
[426.787195] Dynamic subtest vecs0: SUCCESS (3.051s)
[426.788584] Subtest busy-accuracy-2: SUCCESS (15.276s)
[426.986992] [091/134] (616s left) i915_pm_rpm (reg-read-ioctl)
[431.038898] Starting subtest: reg-read-ioctl
[432.994852] Subtest reg-read-ioctl: SUCCESS (1.962s)
[434.109249] [092/134] (609s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions)
[435.605910] Starting subtest: cursorB-vs-flipB-atomic-transitions
[435.609085] Subtest cursorB-vs-flipB-atomic-transitions: SKIP (0.002s)
[435.812799] [093/134] (607s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-blt)
[437.305117] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-blt
[440.238418] Subtest fbc-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (2.936s)
[440.441794] [094/134] (602s left) kms_ccs (bad-aux-stride-4-tiled-mtl-mc-ccs)
[441.929204] Starting subtest: bad-aux-stride-4-tiled-mtl-mc-ccs
[441.930902] Starting dynamic subtest: pipe-A-HDMI-A-4
[441.947609] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.000s)
[441.960906] Starting dynamic subtest: pipe-B-HDMI-A-4
[441.974610] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.000s)
[441.986949] Starting dynamic subtest: pipe-C-HDMI-A-4
[441.999828] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.000s)
[442.012193] Starting dynamic subtest: pipe-D-HDMI-A-4
[442.025645] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.000s)
[442.028248] Subtest bad-aux-stride-4-tiled-mtl-mc-ccs: SKIP (0.010s)
[442.208929] [095/134] (601s left) kms_psr (fbc-pr-cursor-plane-move)
[443.694859] Subtest fbc-pr-cursor-plane-move: SKIP (0.000s)
[443.779291] [096/134] (599s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions)
[445.266166] Starting subtest: short-busy-flip-before-cursor-atomic-transitions
[445.268776] Subtest short-busy-flip-before-cursor-atomic-transitions: SKIP (0.000s)
[445.487166] [097/134] (598s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[446.973253] Subtest addfb25-x-tiled-legacy: SKIP (0.000s)
[447.218054] [098/134] (596s left) perf_pmu (semaphore-wait-idle)
[447.266214] Starting subtest: semaphore-wait-idle
[447.268052] Starting dynamic subtest: rcs0
[447.798926] Dynamic subtest rcs0: SUCCESS (0.528s)
[447.811883] Starting dynamic subtest: bcs0
[448.314488] Dynamic subtest bcs0: SUCCESS (0.510s)
[448.327325] Starting dynamic subtest: vcs0
[448.827639] Dynamic subtest vcs0: SUCCESS (0.509s)
[448.840532] Starting dynamic subtest: vcs1
[449.339800] Dynamic subtest vcs1: SUCCESS (0.510s)
[449.352705] Starting dynamic subtest: vecs0
[449.857519] Dynamic subtest vecs0: SUCCESS (0.509s)
[449.866092] Subtest semaphore-wait-idle: SUCCESS (2.589s)
[450.035581] [099/134] (593s left) kms_plane_scaling (plane-downscale-factor-0-5-with-pixel-format)
[451.518950] Starting subtest: plane-downscale-factor-0-5-with-pixel-format
[451.520497] Starting dynamic subtest: pipe-A
[454.090146] Dynamic subtest pipe-A: SUCCESS (2.571s)
[454.102833] Starting dynamic subtest: pipe-B
[456.630127] Dynamic subtest pipe-B: SUCCESS (2.539s)
[456.643532] Starting dynamic subtest: pipe-C
[456.921184] Dynamic subtest pipe-C: SUCCESS (0.289s)
[456.943720] Starting dynamic subtest: pipe-D
[457.227523] Dynamic subtest pipe-D: SUCCESS (0.304s)
[457.229235] Subtest plane-downscale-factor-0-5-with-pixel-format: SUCCESS (5.710s)
[457.496265] [100/134] (586s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180)
[459.010026] Starting subtest: linear-max-hw-stride-64bpp-rotate-180
[460.121206] Subtest linear-max-hw-stride-64bpp-rotate-180: SUCCESS (1.108s)
[460.354054] [101/134] (583s left) kms_universal_plane (universal-plane-functional)
[461.839816] Starting subtest: universal-plane-functional
[461.841443] Starting dynamic subtest: pipe-A-HDMI-A-4
[463.974976] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (2.133s)
[463.988340] Starting dynamic subtest: pipe-B-HDMI-A-4
[465.950139] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (1.971s)
[465.963025] Starting dynamic subtest: pipe-C-HDMI-A-4
[467.959582] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (2.009s)
[467.974836] Starting dynamic subtest: pipe-D-HDMI-A-4
[470.014846] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (2.052s)
[470.016388] Subtest universal-plane-functional: SUCCESS (8.177s)
[470.276217] [102/134] (573s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[471.772417] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[471.775454] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[472.023659] [103/134] (572s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs)
[473.511859] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs
[473.513435] Starting dynamic subtest: pipe-A-HDMI-A-4
[473.530280] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.000s)
[473.543410] Starting dynamic subtest: pipe-B-HDMI-A-4
[473.563555] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.000s)
[473.576402] Starting dynamic subtest: pipe-C-HDMI-A-4
[473.595964] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.000s)
[473.608818] Starting dynamic subtest: pipe-D-HDMI-A-4
[473.627121] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.000s)
[473.629930] Subtest random-ccs-data-4-tiled-mtl-rc-ccs: SKIP (0.011s)
[473.839454] [104/134] (570s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-downscaling)
[475.326281] Starting subtest: flip-64bpp-linear-to-32bpp-linear-downscaling
[475.328016] Starting dynamic subtest: pipe-A-valid-mode
[476.515844] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.189s)
[476.517754] Subtest flip-64bpp-linear-to-32bpp-linear-downscaling: SUCCESS (1.191s)
[476.790265] [105/134] (567s left) prime_mmap_coherency (read)
[476.845243] Starting subtest: read
[482.249182] Subtest read: SUCCESS (5.402s)
[482.428440] [106/134] (562s left) perf (sysctl-defaults)
[482.583248] Starting subtest: sysctl-defaults
[482.584650] Subtest sysctl-defaults: SUCCESS (0.000s)
[482.675211] [107/134] (561s left) prime_self_import (basic-with_two_bos)
[482.718579] Starting subtest: basic-with_two_bos
[482.723244] Subtest basic-with_two_bos: SUCCESS (0.004s)
[482.931209] [108/134] (561s left) syncobj_wait (multi-wait-all-for-submit-submitted)
[482.980684] Starting subtest: multi-wait-all-for-submit-submitted
[483.087179] Subtest multi-wait-all-for-submit-submitted: SUCCESS (0.102s)
[483.282895] [109/134] (561s left) sriov_basic (enable-vfs-bind-unbind-each-numvfs-all)
[483.331982] Subtest enable-vfs-bind-unbind-each-numvfs-all: SKIP (0.000s)
[483.408434] [110/134] (561s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[484.899977] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu
[484.902836] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[485.134172] [111/134] (559s left) kms_cursor_crc (cursor-offscreen-512x512)
[486.880157] Starting subtest: cursor-offscreen-512x512
[486.883157] Subtest cursor-offscreen-512x512: SKIP (0.001s)
[487.186007] [112/134] (557s left) gem_exec_balancer (persistence)
[487.545439] Starting subtest: persistence
[489.502272] Subtest persistence: SUCCESS (1.953s)
[489.691799] [113/134] (555s left) kms_flip (2x-busy-flip)
[491.178918] Starting subtest: 2x-busy-flip
[491.196178] Subtest 2x-busy-flip: SKIP (0.017s)
[491.399996] [114/134] (553s left) core_sysfs (read-all-entries)
[491.446643] Starting subtest: read-all-entries
[491.451560] Subtest read-all-entries: SUCCESS (0.005s)
[491.652795] [115/134] (553s left) kms_plane_scaling (planes-upscale-factor-0-25)
[493.137662] Starting subtest: planes-upscale-factor-0-25
[493.139232] Starting dynamic subtest: pipe-A
[493.243345] Dynamic subtest pipe-A: SUCCESS (0.107s)
[493.256913] Starting dynamic subtest: pipe-B
[493.385821] Dynamic subtest pipe-B: SUCCESS (0.141s)
[493.398745] Starting dynamic subtest: pipe-C
[493.526966] Dynamic subtest pipe-C: SUCCESS (0.140s)
[493.540345] Starting dynamic subtest: pipe-D
[493.668100] Dynamic subtest pipe-D: SUCCESS (0.140s)
[493.669669] Subtest planes-upscale-factor-0-25: SUCCESS (0.533s)
[493.920478] [116/134] (551s left) kms_plane (pixel-format-y-tiled-modifier-source-clamping)
[495.408960] Starting subtest: pixel-format-y-tiled-modifier-source-clamping
[495.440877] Starting dynamic subtest: pipe-A-plane-0
[496.473898] Dynamic subtest pipe-A-plane-0: SUCCESS (1.033s)
[496.518401] Starting dynamic subtest: pipe-A-plane-3
[497.291050] Dynamic subtest pipe-A-plane-3: SUCCESS (0.813s)
[497.340818] Starting dynamic subtest: pipe-A-plane-5
[497.991379] Dynamic subtest pipe-A-plane-5: SUCCESS (0.696s)
[498.020599] Starting dynamic subtest: pipe-A-plane-7
[498.023322] Dynamic subtest pipe-A-plane-7: SKIP (0.000s)
[498.137812] Starting dynamic subtest: pipe-B-plane-0
[499.059744] Dynamic subtest pipe-B-plane-0: SUCCESS (0.933s)
[499.101340] Starting dynamic subtest: pipe-B-plane-3
[499.876529] Dynamic subtest pipe-B-plane-3: SUCCESS (0.813s)
[499.893144] Starting dynamic subtest: pipe-B-plane-5
[500.576537] Dynamic subtest pipe-B-plane-5: SUCCESS (0.696s)
[500.591014] Starting dynamic subtest: pipe-B-plane-7
[500.594253] Dynamic subtest pipe-B-plane-7: SKIP (0.000s)
[500.632345] Subtest pixel-format-y-tiled-modifier-source-clamping: SUCCESS (5.224s)
[500.981764] [117/134] (544s left) gem_exec_schedule (preemptive-hang)
[501.156733] Starting subtest: preemptive-hang
[501.158158] Starting dynamic subtest: rcs0
[510.094002] Dynamic subtest rcs0: SUCCESS (8.938s)
[510.106998] Starting dynamic subtest: bcs0
[519.055165] Dynamic subtest bcs0: SUCCESS (8.958s)
[519.068429] Starting dynamic subtest: vcs0
[528.085179] Dynamic subtest vcs0: SUCCESS (9.029s)
[528.098230] Starting dynamic subtest: vcs1
[537.109347] Dynamic subtest vcs1: SUCCESS (9.022s)
[537.122744] Starting dynamic subtest: vecs0
[546.070527] Dynamic subtest vecs0: SUCCESS (8.950s)
[546.072143] Subtest preemptive-hang: SUCCESS (44.905s)
[546.360622] [118/134] (499s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[547.849582] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[547.852084] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[548.111769] [119/134] (497s left) gem_exec_schedule (u-submit-early-slice)
[548.172035] Starting subtest: u-submit-early-slice
[548.173462] Starting dynamic subtest: rcs0
[548.228982] Dynamic subtest rcs0: SUCCESS (0.056s)
[548.241768] Starting dynamic subtest: bcs0
[548.270006] Dynamic subtest bcs0: SUCCESS (0.040s)
[548.282711] Starting dynamic subtest: vcs0
[548.313336] Dynamic subtest vcs0: SUCCESS (0.042s)
[548.328203] Starting dynamic subtest: vcs1
[548.357195] Dynamic subtest vcs1: SUCCESS (0.042s)
[548.369956] Starting dynamic subtest: vecs0
[548.398107] Dynamic subtest vecs0: SUCCESS (0.039s)
[548.399847] Subtest u-submit-early-slice: SUCCESS (0.227s)
[548.829254] [120/134] (497s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs-cc)
[550.316291] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs-cc
[550.317692] Starting dynamic subtest: pipe-A-HDMI-A-4
[550.323005] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.000s)
[550.336172] Starting dynamic subtest: pipe-B-HDMI-A-4
[550.341711] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.000s)
[550.354792] Starting dynamic subtest: pipe-C-HDMI-A-4
[550.360201] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.000s)
[550.372663] Starting dynamic subtest: pipe-D-HDMI-A-4
[550.378092] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.000s)
[550.380821] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs-cc: SKIP (0.011s)
[550.640512] [121/134] (495s left) kms_plane_multiple (2x-tiling-none)
[552.125019] Starting subtest: 2x-tiling-none
[552.127970] Subtest 2x-tiling-none: SKIP (0.000s)
[552.316718] [122/134] (493s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-render)
[553.822154] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-render
[553.827169] Subtest psr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[554.047498] [123/134] (492s left) kms_frontbuffer_tracking (fbcpsr-abgr161616f-draw-mmap-cpu)
[555.562382] Starting subtest: fbcpsr-abgr161616f-draw-mmap-cpu
[555.564963] Subtest fbcpsr-abgr161616f-draw-mmap-cpu: SKIP (0.000s)
[555.792172] [124/134] (490s left) perf (create-destroy-userspace-config)
[555.938202] Starting subtest: create-destroy-userspace-config
[555.969544] Subtest create-destroy-userspace-config: SUCCESS (0.030s)
[556.051979] [125/134] (490s left) kms_psr (psr-sprite-plane-move)
[557.536648] Subtest psr-sprite-plane-move: SKIP (0.000s)
[557.616890] [126/134] (489s left) api_intel_allocator (alloc-simple)
[557.675415] Starting subtest: alloc-simple
[557.679142] Subtest alloc-simple: SUCCESS (0.015s)
[557.921420] [127/134] (488s left) kms_psr (psr-cursor-plane-onoff)
[559.413206] Subtest psr-cursor-plane-onoff: SKIP (0.000s)
[559.498926] [128/134] (487s left) syncobj_basic (illegal-fd-to-handle)
[559.546768] Starting subtest: illegal-fd-to-handle
[559.548305] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[559.688220] [129/134] (487s left) gem_lmem_swapping (parallel-random-verify)
[564.111729] Starting subtest: parallel-random-verify
[564.113176] Starting dynamic subtest: lmem0
[620.851965] Dynamic subtest lmem0: SUCCESS (56.736s)
[620.853433] Subtest parallel-random-verify: SUCCESS (56.738s)
[621.913070] [FACT gem_lmem_swapping (parallel-random-verify)] deleted: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[621.914800] [FACT gem_lmem_swapping (parallel-random-verify)] deleted: kernel.kmod_is_loaded.i915: true
[621.917865] [130/134] (425s left) kms_color (invalid-gamma-lut-sizes)
[626.649950] Starting subtest: invalid-gamma-lut-sizes
[626.651591] Starting dynamic subtest: pipe-A
[626.691087] Dynamic subtest pipe-A: SUCCESS (0.043s)
[626.703793] Starting dynamic subtest: pipe-B
[626.705484] Dynamic subtest pipe-B: SUCCESS (0.002s)
[626.717467] Starting dynamic subtest: pipe-C
[626.719178] Dynamic subtest pipe-C: SUCCESS (0.001s)
[626.731590] Starting dynamic subtest: pipe-D
[626.733285] Dynamic subtest pipe-D: SUCCESS (0.001s)
[626.735072] Subtest invalid-gamma-lut-sizes: SUCCESS (0.053s)
[626.952837] [FACT kms_color (invalid-gamma-lut-sizes)] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[626.956078] [FACT kms_color (invalid-gamma-lut-sizes)] new: kernel.kmod_is_loaded.i915: true
[626.961794] [131/134] (420s left) kms_getfb (getfb-handle-not-fb)
[628.453411] Starting subtest: getfb-handle-not-fb
[628.454990] Subtest getfb-handle-not-fb: SUCCESS (0.000s)
[628.631426] [132/134] (418s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-render)
[631.183141] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-render
[631.185641] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[631.343183] [133/134] (415s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-wc)
[633.254027] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-wc
[633.257324] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[633.519655] [134/134] (413s left) gem_exec_fence (syncobj-signal)
[633.878248] Starting subtest: syncobj-signal
[633.900090] Subtest syncobj-signal: SUCCESS (0.022s)
[634.329458] Closing watchdogs
results: parsing output: 0/ for test: syncobj_timeline
results: parsing output: 1/ for test: gem_wait
results: parsing output: 2/ for test: syncobj_timeline
results: parsing output: 3/ for test: kms_cursor_edge_walk
results: parsing output: 4/ for test: kms_big_fb
results: parsing output: 5/ for test: syncobj_timeline
results: parsing output: 6/ for test: kms_ccs
results: parsing output: 7/ for test: kms_psr2_sf
results: parsing output: 8/ for test: kms_ccs
results: parsing output: 9/ for test: perf_pmu
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_chamelium_hpd
results: parsing output: 12/ for test: kms_pm_rpm
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: syncobj_timeline
results: parsing output: 15/ for test: gem_exec_parallel
results: parsing output: 16/ for test: kms_chamelium_frames
results: parsing output: 17/ for test: api_intel_bb
results: parsing output: 18/ for test: gem_mmap_gtt
results: parsing output: 19/ for test: gem_pxp
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: i915_query
results: parsing output: 22/ for test: gem_ccs
results: parsing output: 23/ for test: gem_exec_balancer
results: parsing output: 24/ for test: kms_psr
results: parsing output: 25/ for test: gem_exec_params
results: parsing output: 26/ for test: prime_mmap
results: parsing output: 27/ for test: gem_gpgpu_fill
results: parsing output: 28/ for test: kms_psr
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: gem_exec_gttfill
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: gem_cs_tlb
results: parsing output: 33/ for test: gem_userptr_blits
results: parsing output: 34/ for test: gem_exec_reloc
results: parsing output: 35/ for test: kms_pm_rpm
results: parsing output: 36/ for test: i915_drm_fdinfo
results: parsing output: 37/ for test: kms_ccs
results: parsing output: 38/ for test: kms_psr
results: parsing output: 39/ for test: kms_invalid_mode
results: parsing output: 40/ for test: kms_content_protection
results: parsing output: 41/ for test: gem_exec_nop
results: parsing output: 42/ for test: kms_flip
results: parsing output: 43/ for test: kms_rotation_crc
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: kms_flip_scaled_crc
results: parsing output: 47/ for test: kms_big_fb
results: parsing output: 48/ for test: syncobj_wait
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: gem_mmap_offset
results: parsing output: 51/ for test: gem_softpin
results: parsing output: 52/ for test: kms_feature_discovery
results: parsing output: 53/ for test: kms_hdr
results: parsing output: 54/ for test: gem_mmap_gtt
results: parsing output: 55/ for test: kms_vblank
results: parsing output: 56/ for test: syncobj_basic
results: parsing output: 57/ for test: kms_mmap_write_crc
results: parsing output: 58/ for test: testdisplay
results: parsing output: 59/ for test: kms_psr2_sf
results: parsing output: 60/ for test: gen7_exec_parse
results: parsing output: 61/ for test: gem_softpin
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_plane_cursor
results: parsing output: 64/ for test: kms_async_flips
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: gem_pxp
results: parsing output: 67/ for test: kms_big_fb
results: parsing output: 68/ for test: gem_exec_reloc
results: parsing output: 69/ for test: sysfs_timeslice_duration
results: parsing output: 70/ for test: gem_exec_store
results: parsing output: 71/ for test: kms_flip_scaled_crc
results: parsing output: 72/ for test: gem_ctx_persistence
results: parsing output: 73/ for test: kms_big_fb
results: parsing output: 74/ for test: sw_sync
results: parsing output: 75/ for test: kms_flip
results: parsing output: 76/ for test: syncobj_wait
results: parsing output: 77/ for test: gem_ctx_isolation
results: parsing output: 78/ for test: kms_big_fb
results: parsing output: 79/ for test: sw_sync
results: parsing output: 80/ for test: syncobj_timeline
results: parsing output: 81/ for test: perf
results: parsing output: 82/ for test: kms_vblank
results: parsing output: 83/ for test: kms_cursor_crc
results: parsing output: 84/ for test: gem_exec_alignment
results: parsing output: 85/ for test: gem_spin_batch
results: parsing output: 86/ for test: gem_ringfill
results: parsing output: 87/ for test: i915_hangman
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: perf_pmu
results: parsing output: 90/ for test: i915_pm_rpm
results: parsing output: 91/ for test: kms_cursor_legacy
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_ccs
results: parsing output: 94/ for test: kms_psr
results: parsing output: 95/ for test: kms_cursor_legacy
results: parsing output: 96/ for test: kms_addfb_basic
results: parsing output: 97/ for test: perf_pmu
results: parsing output: 98/ for test: kms_plane_scaling
results: parsing output: 99/ for test: kms_big_fb
results: parsing output: 100/ for test: kms_universal_plane
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_ccs
results: parsing output: 103/ for test: kms_flip_scaled_crc
results: parsing output: 104/ for test: prime_mmap_coherency
results: parsing output: 105/ for test: perf
results: parsing output: 106/ for test: prime_self_import
results: parsing output: 107/ for test: syncobj_wait
results: parsing output: 108/ for test: sriov_basic
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: kms_cursor_crc
results: parsing output: 111/ for test: gem_exec_balancer
results: parsing output: 112/ for test: kms_flip
results: parsing output: 113/ for test: core_sysfs
results: parsing output: 114/ for test: kms_plane_scaling
results: parsing output: 115/ for test: kms_plane
results: parsing output: 116/ for test: gem_exec_schedule
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: gem_exec_schedule
results: parsing output: 119/ for test: kms_ccs
results: parsing output: 120/ for test: kms_plane_multiple
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: perf
results: parsing output: 124/ for test: kms_psr
results: parsing output: 125/ for test: api_intel_allocator
results: parsing output: 126/ for test: kms_psr
results: parsing output: 127/ for test: syncobj_basic
results: parsing output: 128/ for test: gem_lmem_swapping
results: parsing output: 129/ for test: kms_color
results: parsing output: 130/ for test: kms_getfb
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: gem_exec_fence
Done.