[56.122846] Initializing watchdogs
[56.122890]   /dev/watchdog0
[56.137654] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[56.142025] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.144190] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.147575] [001/134] (960s left) api_intel_allocator (reopen-fork)
[56.224073] Starting subtest: reopen-fork
[59.566588] Subtest reopen-fork: SUCCESS (3.351s)
[59.665481] [002/134] (957s left) gen9_exec_parse (unaligned-jump)
[59.731966] Subtest unaligned-jump: SKIP (0.000s)
[59.807088] [003/134] (956s left) i915_pm_rc6_residency (rc6-accuracy)
[59.855069] Starting subtest: rc6-accuracy
[59.857383] Starting dynamic subtest: gt0
[63.026217] Dynamic subtest gt0: SUCCESS (3.173s)
[63.035752] Starting dynamic subtest: gt1
[66.196588] Dynamic subtest gt1: SUCCESS (3.172s)
[66.199134] Subtest rc6-accuracy: SUCCESS (6.348s)
[66.280179] [004/134] (950s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[66.400658] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[74.935758] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.542s)
[75.091315] [005/134] (941s left) gem_exec_reloc (basic-cpu-gtt-active)
[75.147926] Subtest basic-cpu-gtt-active: SKIP (0.000s)
[75.223479] [006/134] (941s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[75.325339] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[75.327264] Starting dynamic subtest: pipe-A
[75.662592] Dynamic subtest pipe-A: SUCCESS (0.339s)
[75.674194] Starting dynamic subtest: pipe-B
[77.097777] Dynamic subtest pipe-B: SUCCESS (1.435s)
[77.105801] Starting dynamic subtest: pipe-C
[78.529319] Dynamic subtest pipe-C: SUCCESS (1.431s)
[78.538799] Starting dynamic subtest: pipe-D
[79.954231] Dynamic subtest pipe-D: SUCCESS (1.425s)
[79.956676] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.632s)
[81.128179] [007/134] (935s left) kms_plane_cursor (overlay)
[81.229483] Starting subtest: overlay
[81.234358] Starting dynamic subtest: pipe-A-eDP-1-size-64
[83.694514] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.464s)
[83.752652] Starting dynamic subtest: pipe-A-eDP-1-size-128
[86.295671] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.549s)
[86.353957] Starting dynamic subtest: pipe-A-eDP-1-size-256
[88.875414] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.529s)
[89.230241] Starting dynamic subtest: pipe-B-eDP-1-size-64
[92.451590] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.230s)
[92.509350] Starting dynamic subtest: pipe-B-eDP-1-size-128
[94.975112] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.471s)
[95.032904] Starting dynamic subtest: pipe-B-eDP-1-size-256
[97.587234] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.560s)
[97.942835] Starting dynamic subtest: pipe-C-eDP-1-size-64
[100.805950] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.872s)
[100.845235] Starting dynamic subtest: pipe-C-eDP-1-size-128
[103.038126] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.202s)
[103.075078] Starting dynamic subtest: pipe-C-eDP-1-size-256
[105.205189] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.136s)
[105.532227] Starting dynamic subtest: pipe-D-eDP-1-size-64
[108.412226] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.889s)
[108.447457] Starting dynamic subtest: pipe-D-eDP-1-size-128
[110.533214] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.091s)
[110.569826] Starting dynamic subtest: pipe-D-eDP-1-size-256
[112.835245] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.271s)
[113.154772] Subtest overlay: SUCCESS (31.921s)
[113.969405] [008/134] (902s left) sw_sync (alloc_fence)
[114.008448] Starting subtest: alloc_fence
[114.010603] Subtest alloc_fence: SUCCESS (0.000s)
[114.084349] [009/134] (902s left) gen9_exec_parse (batch-invalid-length)
[114.147344] Subtest batch-invalid-length: SKIP (0.000s)
[114.234625] [010/134] (902s left) gem_mmap_offset (blt-coherency)
[114.301936] Starting subtest: blt-coherency
[114.307305] Subtest blt-coherency: SUCCESS (0.007s)
[114.457825] [011/134] (902s left) kms_psr (pr-primary-page-flip)
[114.559068] Starting subtest: pr-primary-page-flip
[114.563084] Subtest pr-primary-page-flip: SKIP (0.000s)
[114.638673] [012/134] (902s left) kms_vblank (query-busy-hang)
[114.732382] Starting subtest: query-busy-hang
[114.734659] Starting dynamic subtest: pipe-A-eDP-1
[141.651604] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.915s)
[141.663850] Starting dynamic subtest: pipe-D-eDP-1
[166.681879] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.028s)
[166.684388] Subtest query-busy-hang: SUCCESS (51.948s)
[167.521427] [013/134] (849s left) gem_softpin (allocator-evict)
[167.581142] Starting subtest: allocator-evict
[167.583542] Starting dynamic subtest: rcs0
[191.684495] Dynamic subtest rcs0: SUCCESS (24.102s)
[191.694577] Starting dynamic subtest: bcs0
[215.854542] Dynamic subtest bcs0: SUCCESS (24.167s)
[215.863608] Starting dynamic subtest: vcs0
[239.648847] Dynamic subtest vcs0: SUCCESS (23.792s)
[239.658721] Starting dynamic subtest: vcs1
[263.343490] Dynamic subtest vcs1: SUCCESS (23.692s)
[263.354171] Starting dynamic subtest: vecs0
[286.917600] Dynamic subtest vecs0: SUCCESS (23.570s)
[286.928080] Starting dynamic subtest: ccs0
[310.267773] Dynamic subtest ccs0: SUCCESS (23.348s)
[310.270386] Subtest allocator-evict: SUCCESS (142.682s)
[310.362881] [014/134] (706s left) kms_chamelium_color (ctm-max)
[310.502090] Subtest ctm-max: SKIP (0.000s)
[310.543658] [015/134] (706s left) syncobj_timeline (single-wait-signaled)
[310.616814] Starting subtest: single-wait-signaled
[310.619153] Subtest single-wait-signaled: SUCCESS (0.000s)
[310.712620] [016/134] (706s left) kms_frontbuffer_tracking (fbc-shrfb-scaledprimary)
[310.811504] Starting subtest: fbc-shrfb-scaledprimary
[313.798107] Subtest fbc-shrfb-scaledprimary: SUCCESS (2.990s)
[314.992995] [017/134] (702s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[315.104884] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[315.109205] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[315.240555] [018/134] (702s left) prime_mmap (test_correct_cpu_write)
[315.299929] Starting subtest: test_correct_cpu_write
[315.302107] Starting dynamic subtest: test_correct_cpu_write-smem
[315.303933] Dynamic subtest test_correct_cpu_write-smem: SUCCESS (0.000s)
[315.305837] Subtest test_correct_cpu_write: SUCCESS (0.001s)
[315.414867] [019/134] (701s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-wc)
[315.514312] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-wc
[319.464345] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (3.953s)
[320.587226] [020/134] (696s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled-ccs)
[320.657224] Starting subtest: yf-tiled-ccs-to-yf-tiled-ccs
[320.661316] Subtest yf-tiled-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[320.849740] [021/134] (696s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[320.962574] Starting subtest: basic-flip-after-cursor-atomic
[321.901288] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.936s)
[322.703910] [022/134] (694s left) gem_ctx_create (basic-files)
[322.771115] Starting subtest: basic-files
[324.778325] Subtest basic-files: SUCCESS (2.007s)
[325.973740] [023/134] (691s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions-varying-s
[326.092410] Starting subtest: short-busy-flip-before-cursor-atomic-transitions-varying-size
[326.096946] Subtest short-busy-flip-before-cursor-atomic-transitions-varying-size: SKIP (0.000s)
[326.247275] [024/134] (691s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-plflip-blt)
[326.351217] Starting subtest: fbc-2p-scndscrn-indfb-plflip-blt
[326.355227] Subtest fbc-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[326.483037] [025/134] (691s left) syncobj_basic (bad-flags-fd-to-handle)
[326.549390] Starting subtest: bad-flags-fd-to-handle
[326.551633] Subtest bad-flags-fd-to-handle: SUCCESS (0.000s)
[326.680435] [026/134] (690s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-signa
[326.729954] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-signaled
[326.831964] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-signaled: SUCCESS (0.102s)
[326.915337] [027/134] (690s left) kms_pm_dc (dc6-dpms)
[328.030181] Starting subtest: dc6-dpms
[330.078942] Subtest dc6-dpms: SUCCESS (2.048s)
[330.162769] [028/134] (687s left) gem_exec_reloc (basic-write-gtt-noreloc)
[330.224606] Subtest basic-write-gtt-noreloc: SKIP (0.000s)
[330.306060] [029/134] (687s left) kms_big_fb (y-tiled-64bpp-rotate-270)
[330.414474] Starting subtest: y-tiled-64bpp-rotate-270
[330.418598] Subtest y-tiled-64bpp-rotate-270: SKIP (0.000s)
[330.531481] [030/134] (687s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-pgflip-blt)
[330.636365] Starting subtest: fbcpsr-2p-scndscrn-shrfb-pgflip-blt
[330.640668] Subtest fbcpsr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[330.740833] [031/134] (687s left) kms_content_protection (content-type-change)
[330.891898] Starting subtest: content-type-change
[330.896362] Subtest content-type-change: SKIP (0.000s)
[331.046596] [032/134] (686s left) kms_cursor_crc (cursor-sliding-32x32)
[331.192494] Starting subtest: cursor-sliding-32x32
[331.243498] Subtest cursor-sliding-32x32: SKIP (0.046s)
[331.591121] [033/134] (686s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-move)
[331.695357] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-move
[331.698692] Subtest fbcpsr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[331.822236] [034/134] (686s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions)
[331.939296] Starting subtest: short-flip-after-cursor-atomic-transitions
[332.877948] Subtest short-flip-after-cursor-atomic-transitions: SUCCESS (0.935s)
[333.692586] [035/134] (684s left) gem_ctx_shared (disjoint-timelines)
[333.765145] Starting subtest: disjoint-timelines
[333.831060] Subtest disjoint-timelines: SUCCESS (0.066s)
[334.090804] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true
[334.094151] [036/134] (683s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-pgflip-blt)
[334.203815] Starting subtest: fbcpsr-1p-primscrn-shrfb-pgflip-blt
[341.461122] Subtest fbcpsr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.259s)
[341.617296] [037/134] (676s left) prime_busy (hang)
[341.687359] Starting subtest: hang
[341.689296] Starting dynamic subtest: rcs0
[367.404978] Dynamic subtest rcs0: SUCCESS (25.715s)
[367.412567] Starting dynamic subtest: bcs0
[374.561584] Dynamic subtest bcs0: SUCCESS (7.155s)
[374.571258] Starting dynamic subtest: vcs0
[381.538770] Dynamic subtest vcs0: SUCCESS (6.977s)
[381.546080] Starting dynamic subtest: vcs1
[388.575600] Dynamic subtest vcs1: SUCCESS (7.036s)
[388.582668] Starting dynamic subtest: vecs0
[395.548627] Dynamic subtest vecs0: SUCCESS (6.972s)
[395.556033] Starting dynamic subtest: ccs0
[409.455033] Dynamic subtest ccs0: SUCCESS (13.905s)
[409.457380] Subtest hang: SUCCESS (67.766s)
[409.611626] [038/134] (608s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[409.717646] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-cpu
[409.721833] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[409.841535] [039/134] (608s left) sysfs_preempt_timeout (timeout)
[409.905483] Starting subtest: timeout
[409.907864] Starting dynamic subtest: vecs0
[419.944844] Dynamic subtest vecs0: SUCCESS (10.036s)
[419.955594] Starting dynamic subtest: ccs0
[421.262989] Dynamic subtest ccs0: SUCCESS (1.317s)
[421.275450] Starting dynamic subtest: vcs0
[430.951581] Dynamic subtest vcs0: SUCCESS (9.684s)
[430.961904] Starting dynamic subtest: bcs0
[432.142820] Dynamic subtest bcs0: SUCCESS (1.191s)
[432.153342] Starting dynamic subtest: rcs0
[433.429323] Dynamic subtest rcs0: SUCCESS (1.285s)
[433.439825] Starting dynamic subtest: vcs1
[442.906307] Dynamic subtest vcs1: SUCCESS (9.473s)
[442.908675] Subtest timeout: SUCCESS (32.998s)
[442.990586] [040/134] (575s left) gem_pwrite (basic-random)
[443.054748] Subtest basic-random: SKIP (0.000s)
[443.113245] [041/134] (575s left) kms_vblank (ts-continuation-dpms-rpm)
[443.217880] Starting subtest: ts-continuation-dpms-rpm
[443.220096] Starting dynamic subtest: pipe-A-eDP-1
[445.771785] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.554s)
[445.785650] Starting dynamic subtest: pipe-D-eDP-1
[448.038964] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.260s)
[448.041257] Subtest ts-continuation-dpms-rpm: SUCCESS (4.821s)
[448.844440] [042/134] (569s left) kms_chamelium_hpd (vga-hpd-without-ddc)
[449.246667] Subtest vga-hpd-without-ddc: SKIP (0.000s)
[450.039152] [043/134] (568s left) prime_self_import (basic-with_one_bo_two_files)
[450.074385] Starting subtest: basic-with_one_bo_two_files
[450.101684] Subtest basic-with_one_bo_two_files: SUCCESS (0.027s)
[450.248322] [044/134] (568s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[450.359832] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[450.362130] Starting dynamic subtest: pipe-A-default-mode
[450.652392] Dynamic subtest pipe-A-default-mode: SKIP (0.291s)
[450.656848] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SKIP (0.292s)
[451.500089] [045/134] (566s left) gem_exec_reloc (basic-gtt)
[451.557750] Subtest basic-gtt: SKIP (0.000s)
[451.637658] [046/134] (566s left) kms_big_fb (linear-32bpp-rotate-0)
[451.746466] Starting subtest: linear-32bpp-rotate-0
[453.148390] Subtest linear-32bpp-rotate-0: SUCCESS (1.401s)
[453.319355] [047/134] (565s left) kms_multipipe_modeset (basic-max-pipe-crc-check)
[453.414236] Starting subtest: basic-max-pipe-crc-check
[453.417713] Subtest basic-max-pipe-crc-check: SKIP (0.000s)
[453.457074] [048/134] (564s left) kms_psr2_sf (psr2-overlay-primary-update-sf-dmg-area)
[458.480086] Starting subtest: psr2-overlay-primary-update-sf-dmg-area
[458.482366] Starting dynamic subtest: pipe-A-eDP-1
[465.099675] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.619s)
[465.113444] Starting dynamic subtest: pipe-B-eDP-1
[471.736546] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.635s)
[471.738983] Subtest psr2-overlay-primary-update-sf-dmg-area: SUCCESS (13.258s)
[472.627937] [049/134] (545s left) drm_read (short-buffer-block)
[472.830643] Starting subtest: short-buffer-block
[472.833152] Subtest short-buffer-block: SUCCESS (0.000s)
[472.985115] [050/134] (545s left) gem_ctx_persistence (many-contexts)
[473.043139] Starting subtest: many-contexts
[518.254932] Subtest many-contexts: SUCCESS (45.211s)
[518.351968] [051/134] (500s left) prime_mmap (test_correct)
[518.408532] Starting subtest: test_correct
[518.410529] Starting dynamic subtest: test_correct-smem
[518.434285] Dynamic subtest test_correct-smem: SUCCESS (0.028s)
[518.436703] Subtest test_correct: SUCCESS (0.029s)
[518.540816] [052/134] (500s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted)
[518.600087] Starting subtest: invalid-multi-wait-all-unsubmitted
[518.602235] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[518.723870] [053/134] (499s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[518.820360] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip
[519.778835] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (0.957s)
[519.947819] [054/134] (498s left) gem_mmap_gtt (basic-copy)
[520.015567] Subtest basic-copy: SKIP (0.000s)
[520.079860] [055/134] (498s left) gem_exec_suspend (basic)
[520.145124] Starting subtest: basic
[520.147169] Starting dynamic subtest: smem
[521.877619] Dynamic subtest smem: SUCCESS (1.730s)
[521.880648] Subtest basic: SUCCESS (1.732s)
[522.086161] [056/134] (496s left) perf_pmu (busy-accuracy-2)
[522.132986] Starting subtest: busy-accuracy-2
[522.135211] Starting dynamic subtest: rcs0
[525.183163] Dynamic subtest rcs0: SUCCESS (3.049s)
[525.193891] Starting dynamic subtest: bcs0
[528.240669] Dynamic subtest bcs0: SUCCESS (3.055s)
[528.251144] Starting dynamic subtest: vcs0
[531.284199] Dynamic subtest vcs0: SUCCESS (3.041s)
[531.292456] Starting dynamic subtest: vcs1
[534.331226] Dynamic subtest vcs1: SUCCESS (3.044s)
[534.339451] Starting dynamic subtest: vecs0
[537.374526] Dynamic subtest vecs0: SUCCESS (3.041s)
[537.385094] Starting dynamic subtest: ccs0
[540.428899] Dynamic subtest ccs0: SUCCESS (3.053s)
[540.431382] Subtest busy-accuracy-2: SUCCESS (18.295s)
[540.569355] [057/134] (478s left) gem_wait (await)
[540.633383] Starting subtest: await
[540.635778] Starting dynamic subtest: all-engines
[541.804883] Dynamic subtest all-engines: SUCCESS (1.170s)
[541.816641] Starting dynamic subtest: rcs0
[542.871313] Dynamic subtest rcs0: SUCCESS (1.064s)
[542.886023] Starting dynamic subtest: bcs0
[543.933666] Dynamic subtest bcs0: SUCCESS (1.060s)
[543.949551] Starting dynamic subtest: vcs0
[544.989620] Dynamic subtest vcs0: SUCCESS (1.053s)
[545.004840] Starting dynamic subtest: vcs1
[546.042369] Dynamic subtest vcs1: SUCCESS (1.051s)
[546.057782] Starting dynamic subtest: vecs0
[547.095128] Dynamic subtest vecs0: SUCCESS (1.051s)
[547.108972] Starting dynamic subtest: ccs0
[548.153172] Dynamic subtest ccs0: SUCCESS (1.056s)
[548.155707] Subtest await: SUCCESS (7.520s)
[548.363212] [058/134] (470s left) prime_mmap (test_refcounting)
[548.425477] Starting subtest: test_refcounting
[548.427613] Starting dynamic subtest: test_refcounting-smem
[548.470381] Dynamic subtest test_refcounting-smem: SUCCESS (0.047s)
[548.472821] Subtest test_refcounting: SUCCESS (0.048s)
[548.624939] [059/134] (470s left) gem_exec_balancer (individual)
[548.694723] Starting subtest: individual
[550.970075] Subtest individual: SUCCESS (2.276s)
[551.322328] [060/134] (467s left) kms_dp_link_training (uhbr-sst)
[551.424084] Starting subtest: uhbr-sst
[551.432519] Subtest uhbr-sst: SKIP (0.000s)
[551.512574] [061/134] (467s left) gem_busy (busy)
[551.564627] Starting subtest: busy
[551.567035] Starting dynamic subtest: all-engines
[551.636859] Dynamic subtest all-engines: SUCCESS (0.072s)
[551.648237] Starting dynamic subtest: rcs0
[552.923799] Dynamic subtest rcs0: SUCCESS (1.285s)
[552.933952] Starting dynamic subtest: bcs0
[552.986240] Dynamic subtest bcs0: SUCCESS (0.060s)
[552.996496] Starting dynamic subtest: vcs0
[553.025034] Dynamic subtest vcs0: SUCCESS (0.038s)
[553.033496] Starting dynamic subtest: vcs1
[554.905681] Dynamic subtest vcs1: SUCCESS (1.877s)
[554.915535] Starting dynamic subtest: vecs0
[556.890758] Dynamic subtest vecs0: SUCCESS (1.982s)
[556.900688] Starting dynamic subtest: ccs0
[558.938260] Dynamic subtest ccs0: SUCCESS (2.045s)
[558.940745] Subtest busy: SUCCESS (7.370s)
[559.329497] [062/134] (459s left) kms_big_fb (4-tiled-8bpp-rotate-180)
[559.442854] Starting subtest: 4-tiled-8bpp-rotate-180
[560.545764] Subtest 4-tiled-8bpp-rotate-180: SUCCESS (1.101s)
[560.736717] [063/134] (458s left) kms_cursor_crc (cursor-rapid-movement-128x42)
[560.968740] Starting subtest: cursor-rapid-movement-128x42
[561.033993] Subtest cursor-rapid-movement-128x42: SKIP (0.061s)
[561.311170] [064/134] (457s left) kms_joiner (invalid-modeset-force-big-joiner)
[561.403574] Starting subtest: invalid-modeset-force-big-joiner
[561.407229] Subtest invalid-modeset-force-big-joiner: SKIP (0.000s)
[561.505035] [065/134] (457s left) kms_flip (dpms-off-confusion-interruptible)
[561.598048] Starting subtest: dpms-off-confusion-interruptible
[561.599998] Starting dynamic subtest: A-eDP1
[570.313999] Dynamic subtest A-eDP1: SUCCESS (8.713s)
[570.324685] Starting dynamic subtest: B-eDP1
[578.945565] Dynamic subtest B-eDP1: SUCCESS (8.630s)
[578.956782] Starting dynamic subtest: C-eDP1
[587.573258] Dynamic subtest C-eDP1: SUCCESS (8.623s)
[587.581823] Starting dynamic subtest: D-eDP1
[596.205485] Dynamic subtest D-eDP1: SUCCESS (8.630s)
[596.207866] Subtest dpms-off-confusion-interruptible: SUCCESS (34.608s)
[597.400678] [066/134] (421s left) perf_pmu (pmu-read)
[597.436717] Starting subtest: pmu-read
[597.439056] Subtest pmu-read: SUCCESS (0.002s)
[597.568392] [067/134] (421s left) gem_lmem_swapping (random)
[597.621326] Subtest random: SKIP (0.000s)
[597.664281] [068/134] (421s left) kms_ccs (crc-sprite-planes-basic-4-tiled-lnl-ccs)
[597.773902] Starting subtest: crc-sprite-planes-basic-4-tiled-lnl-ccs
[597.778179] Subtest crc-sprite-planes-basic-4-tiled-lnl-ccs: SKIP (0.000s)
[598.925703] [069/134] (420s left) gem_exec_schedule (u-semaphore-user)
[599.007286] Starting subtest: u-semaphore-user
[599.056513] Subtest u-semaphore-user: SUCCESS (0.057s)
[600.964745] [070/134] (418s left) gem_workarounds (basic-read)
[601.034232] Starting subtest: basic-read
[601.046852] Subtest basic-read: SUCCESS (0.012s)
[601.212646] [071/134] (418s left) kms_hdr (bpc-switch-suspend)
[601.296703] Starting subtest: bpc-switch-suspend
[602.391067] Starting dynamic subtest: pipe-A-eDP-1
[622.069847] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.402s)
[622.072109] Subtest bpc-switch-suspend: SUCCESS (6.499s)
[622.199875] [072/134] (397s left) kms_prime (d3hot)
[622.317066] Subtest D3hot: SKIP (0.000s)
[622.423417] [073/134] (397s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[622.518939] Starting subtest: bad-rotation-90-yf-tiled-ccs
[622.521103] Starting dynamic subtest: pipe-A-eDP-1
[622.542821] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[622.554929] Starting dynamic subtest: pipe-B-eDP-1
[622.575176] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[622.584099] Starting dynamic subtest: pipe-C-eDP-1
[622.605156] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[622.615850] Starting dynamic subtest: pipe-D-eDP-1
[622.639006] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[622.643166] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.004s)
[624.789834] [074/134] (394s left) gem_exec_endless (dispatch)
[624.863446] Starting subtest: dispatch
[624.865711] Starting dynamic subtest: rcs0
[624.872552] Dynamic subtest rcs0: SUCCESS (0.005s)
[624.882785] Starting dynamic subtest: bcs0
[624.887233] Dynamic subtest bcs0: SUCCESS (0.004s)
[624.896309] Starting dynamic subtest: vcs0
[624.900500] Dynamic subtest vcs0: SUCCESS (0.004s)
[624.908744] Starting dynamic subtest: vcs1
[624.912609] Dynamic subtest vcs1: SUCCESS (0.005s)
[624.920602] Starting dynamic subtest: vecs0
[624.924529] Dynamic subtest vecs0: SUCCESS (0.005s)
[624.932496] Starting dynamic subtest: ccs0
[624.936425] Dynamic subtest ccs0: SUCCESS (0.005s)
[624.938253] Subtest dispatch: SUCCESS (0.031s)
[626.269732] [075/134] (393s left) kms_async_flips (test-time-stamp-atomic)
[626.372036] Starting subtest: test-time-stamp-atomic
[626.740285] Starting dynamic subtest: pipe-A-eDP-1
[627.566748] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.829s)
[627.941175] Starting dynamic subtest: pipe-B-eDP-1
[628.701073] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.768s)
[629.079613] Starting dynamic subtest: pipe-C-eDP-1
[629.825317] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.758s)
[630.201681] Starting dynamic subtest: pipe-D-eDP-1
[630.967386] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.773s)
[630.969624] Subtest test-time-stamp-atomic: SUCCESS (4.614s)
[632.139309] [076/134] (387s left) dmabuf_sync_file (export-wait-after-attach)
[632.179393] Starting subtest: export-wait-after-attach
[632.181559] Subtest export-wait-after-attach: SUCCESS (0.001s)
[632.253046] [077/134] (387s left) kms_plane (pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping)
[632.354694] Starting subtest: pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping
[632.384536] Starting dynamic subtest: pipe-A-plane-0
[632.816687] Dynamic subtest pipe-A-plane-0: SUCCESS (0.433s)
[632.825822] Starting dynamic subtest: pipe-A-plane-3
[633.071321] Dynamic subtest pipe-A-plane-3: SUCCESS (0.255s)
[633.080715] Starting dynamic subtest: pipe-A-plane-5
[633.084340] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[634.216086] Starting dynamic subtest: pipe-B-plane-0
[634.673789] Dynamic subtest pipe-B-plane-0: SUCCESS (0.466s)
[634.683538] Starting dynamic subtest: pipe-B-plane-3
[634.941263] Dynamic subtest pipe-B-plane-3: SUCCESS (0.266s)
[634.959694] Starting dynamic subtest: pipe-B-plane-5
[634.964714] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[635.261071] Subtest pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping: SUCCESS (2.910s)
[636.096072] [078/134] (383s left) kms_ccs (crc-primary-suspend-4-tiled-lnl-ccs)
[636.203255] Starting subtest: crc-primary-suspend-4-tiled-lnl-ccs
[636.207829] Subtest crc-primary-suspend-4-tiled-lnl-ccs: SKIP (0.000s)
[637.325140] [079/134] (382s left) kms_flip (2x-flip-vs-panning-vs-hang)
[637.432206] Starting subtest: 2x-flip-vs-panning-vs-hang
[637.444023] Subtest 2x-flip-vs-panning-vs-hang: SKIP (0.011s)
[637.544780] [080/134] (382s left) gem_mmap_gtt (basic-small-copy-xy)
[637.614073] Subtest basic-small-copy-XY: SKIP (0.000s)
[637.684203] [081/134] (382s left) kms_cursor_crc (cursor-dpms)
[637.821250] Starting subtest: cursor-dpms
[637.823470] Starting dynamic subtest: pipe-A-eDP-1
[640.891019] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.069s)
[640.900929] Starting dynamic subtest: pipe-D-eDP-1
[644.471022] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.585s)
[644.473149] Subtest cursor-dpms: SUCCESS (6.657s)
[645.321681] [082/134] (374s left) kms_chamelium_frames (hdmi-frame-dump)
[645.710956] Subtest hdmi-frame-dump: SKIP (0.000s)
[646.522076] [083/134] (373s left) gen3_render_linear_blits
[646.660861] [084/134] (373s left) kms_big_fb (yf-tiled-64bpp-rotate-180)
[646.759821] Starting subtest: yf-tiled-64bpp-rotate-180
[646.763982] Subtest yf-tiled-64bpp-rotate-180: SKIP (0.000s)
[646.859938] [085/134] (373s left) kms_properties (invalid-properties-legacy)
[646.953691] Starting subtest: invalid-properties-legacy
[647.054971] Subtest invalid-properties-legacy: SUCCESS (0.103s)
[647.135271] [086/134] (372s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted)
[647.209345] Starting subtest: multi-wait-for-submit-unsubmitted-submitted
[647.314910] Subtest multi-wait-for-submit-unsubmitted-submitted: SUCCESS (0.106s)
[647.401323] [087/134] (372s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[647.504124] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[654.126127] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (6.626s)
[654.267545] [088/134] (365s left) kms_psr (psr-no-drrs)
[654.374280] Starting subtest: psr-no-drrs
[654.376578] Starting dynamic subtest: eDP-1
[656.022134] Dynamic subtest eDP-1: SUCCESS (1.648s)
[656.024392] Subtest psr-no-drrs: SUCCESS (1.649s)
[656.908786] [089/134] (363s left) gem_exec_params (readonly)
[656.970977] Starting subtest: readonly
[656.973244] Subtest readonly: SUCCESS (0.002s)
[657.121217] [090/134] (363s left) i915_hangman (gt-error-state-capture)
[657.193794] Starting subtest: gt-error-state-capture
[657.196034] Starting dynamic subtest: rcs0
[669.181633] Dynamic subtest rcs0: SUCCESS (11.985s)
[669.194253] Starting dynamic subtest: bcs0
[681.148266] Dynamic subtest bcs0: SUCCESS (11.966s)
[681.160838] Starting dynamic subtest: vcs0
[693.117066] Dynamic subtest vcs0: SUCCESS (11.969s)
[693.130076] Starting dynamic subtest: vcs1
[704.125220] Dynamic subtest vcs1: SUCCESS (11.009s)
[704.137198] Starting dynamic subtest: vecs0
[715.133276] Dynamic subtest vecs0: SUCCESS (11.009s)
[715.146512] Starting dynamic subtest: ccs0
[727.164810] Dynamic subtest ccs0: SUCCESS (12.024s)
[727.167483] Subtest gt-error-state-capture: SUCCESS (69.978s)
[727.258323] [091/134] (292s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-downscaling)
[727.362934] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-downscaling
[727.365337] Starting dynamic subtest: pipe-A-default-mode
[728.554837] Dynamic subtest pipe-A-default-mode: SKIP (1.189s)
[728.559315] Subtest flip-32bpp-xtile-to-64bpp-xtile-downscaling: SKIP (1.190s)
[728.713225] [092/134] (291s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-blt)
[728.821429] Starting subtest: psr-2p-primscrn-cur-indfb-draw-blt
[728.825817] Subtest psr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[728.932967] [093/134] (291s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-sf)
[733.897390] Starting subtest: psr2-cursor-plane-move-continuous-sf
[733.899820] Starting dynamic subtest: pipe-A-eDP-1
[744.979252] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.079s)
[744.990591] Starting dynamic subtest: pipe-B-eDP-1
[756.024537] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.043s)
[756.026921] Subtest psr2-cursor-plane-move-continuous-sf: SUCCESS (22.126s)
[756.850535] [094/134] (263s left) gem_ctx_param (invalid-get-no-zeromap)
[756.921301] Starting subtest: invalid-get-no-zeromap
[756.923653] Subtest invalid-get-no-zeromap: SUCCESS (0.001s)
[757.053411] [095/134] (263s left) kms_flip (bo-too-big)
[757.171991] Starting subtest: bo-too-big
[757.176523] Starting dynamic subtest: A-eDP1
[758.474263] Dynamic subtest A-eDP1: SUCCESS (1.299s)
[758.483662] Starting dynamic subtest: B-eDP1
[759.783544] Dynamic subtest B-eDP1: SUCCESS (1.308s)
[759.802368] Starting dynamic subtest: C-eDP1
[761.131919] Dynamic subtest C-eDP1: SUCCESS (1.345s)
[761.141735] Starting dynamic subtest: D-eDP1
[762.466303] Dynamic subtest D-eDP1: SUCCESS (1.334s)
[762.471297] Subtest bo-too-big: SUCCESS (5.301s)
[763.689786] [096/134] (256s left) kms_frontbuffer_tracking (fbcpsr-abgr161616f-draw-render)
[763.789447] Starting subtest: fbcpsr-abgr161616f-draw-render
[763.793535] Subtest fbcpsr-abgr161616f-draw-render: SKIP (0.000s)
[763.961332] [097/134] (256s left) kms_flip (2x-flip-vs-dpms)
[764.048118] Starting subtest: 2x-flip-vs-dpms
[764.059666] Subtest 2x-flip-vs-dpms: SKIP (0.011s)
[764.196365] [098/134] (256s left) core_auth (getclient-master-drop)
[764.236518] Starting subtest: getclient-master-drop
[764.258193] Subtest getclient-master-drop: SUCCESS (0.023s)
[764.379273] [099/134] (256s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-move)
[764.488248] Starting subtest: fbc-1p-primscrn-cur-indfb-move
[768.522967] Subtest fbc-1p-primscrn-cur-indfb-move: SUCCESS (4.038s)
[769.680328] [100/134] (251s left) gem_render_copy (yf-tiled)
[769.748233] Starting subtest: yf-tiled
[769.752270] Subtest yf-tiled: SKIP (0.000s)
[769.941097] [101/134] (250s left) i915_selftest (mock)
[771.838250] Starting subtest: mock
[771.844905] Starting dynamic subtest: sanitycheck
[772.630148] Dynamic subtest sanitycheck: SUCCESS (0.792s)
[772.640126] Starting dynamic subtest: shmem
[773.407776] Dynamic subtest shmem: SUCCESS (0.776s)
[773.417536] Starting dynamic subtest: fence
[781.429071] Dynamic subtest fence: SUCCESS (8.019s)
[781.438787] Starting dynamic subtest: scatterlist
[783.163409] Dynamic subtest scatterlist: SUCCESS (1.732s)
[783.173095] Starting dynamic subtest: syncmap
[786.546901] Dynamic subtest syncmap: SUCCESS (3.382s)
[786.557044] Starting dynamic subtest: uncore
[787.314256] Dynamic subtest uncore: SUCCESS (0.766s)
[787.324710] Starting dynamic subtest: ring
[788.081022] Dynamic subtest ring: SUCCESS (0.765s)
[788.090854] Starting dynamic subtest: engine
[788.807245] Dynamic subtest engine: SUCCESS (0.724s)
[788.817257] Starting dynamic subtest: timelines
[792.717197] Dynamic subtest timelines: SUCCESS (3.908s)
[792.727435] Starting dynamic subtest: requests
[796.311318] Dynamic subtest requests: SUCCESS (3.592s)
[796.321339] Starting dynamic subtest: objects
[797.234079] Dynamic subtest objects: SUCCESS (0.921s)
[797.244020] Starting dynamic subtest: phys
[798.127985] Dynamic subtest phys: SUCCESS (0.892s)
[798.137301] Starting dynamic subtest: dmabuf
[798.988936] Dynamic subtest dmabuf: SUCCESS (0.859s)
[798.998590] Starting dynamic subtest: vma
[804.420953] Dynamic subtest vma: SUCCESS (5.431s)
[804.430932] Starting dynamic subtest: evict
[805.869535] Dynamic subtest evict: SUCCESS (1.447s)
[805.879602] Starting dynamic subtest: gtt
[809.608083] Dynamic subtest gtt: SUCCESS (3.737s)
[809.618266] Starting dynamic subtest: hugepages
[810.606922] Dynamic subtest hugepages: SUCCESS (0.997s)
[810.617002] Starting dynamic subtest: memory_region
[813.201296] Dynamic subtest memory_region: SUCCESS (2.593s)
[813.203854] Subtest mock: SUCCESS (41.372s)
[813.233248] [FACT i915_selftest (mock)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[813.233942] [FACT i915_selftest (mock)] deleted: kernel.kmod_is_loaded.i915: true
[813.237413] [102/134] (207s left) kms_big_fb (x-tiled-8bpp-rotate-180)
[814.805743] Starting subtest: x-tiled-8bpp-rotate-180
[815.978770] Subtest x-tiled-8bpp-rotate-180: SUCCESS (1.173s)
[816.098076] [FACT kms_big_fb (x-tiled-8bpp-rotate-180)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[816.099275] [FACT kms_big_fb (x-tiled-8bpp-rotate-180)] new: kernel.kmod_is_loaded.i915: true
[816.102901] [103/134] (204s left) gem_userptr_blits (relocations)
[816.184031] Starting subtest: relocations
[816.187568] Subtest relocations: SKIP (0.001s)
[816.352165] [104/134] (204s left) gem_exec_fence (submit3)
[816.541010] Starting subtest: submit3
[816.545248] Subtest submit3: SKIP (0.000s)
[818.350437] [105/134] (202s left) kms_psr_stress_test (flip-primary-invalidate-overlay)
[818.451040] Starting subtest: flip-primary-invalidate-overlay
[830.040480] Subtest flip-primary-invalidate-overlay: SUCCESS (11.594s)
[830.225810] [106/134] (190s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted)
[830.289702] Starting subtest: multi-wait-all-for-submit-available-submitted
[830.291630] Subtest multi-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[830.412067] [107/134] (190s left) gem_ctx_engines (invalid-engines)
[830.470350] Starting subtest: invalid-engines
[830.477485] Subtest invalid-engines: SUCCESS (0.007s)
[830.732434] [108/134] (190s left) gem_exec_schedule (pi-ringfull)
[830.922170] Starting subtest: pi-ringfull
[830.924576] Starting dynamic subtest: rcs0
[830.948946] Dynamic subtest rcs0: SUCCESS (0.031s)
[830.962898] Starting dynamic subtest: bcs0
[830.982795] Dynamic subtest bcs0: SUCCESS (0.033s)
[830.995428] Starting dynamic subtest: vcs0
[831.011511] Dynamic subtest vcs0: SUCCESS (0.028s)
[831.025261] Starting dynamic subtest: vcs1
[831.040158] Dynamic subtest vcs1: SUCCESS (0.028s)
[831.053166] Starting dynamic subtest: vecs0
[831.067719] Dynamic subtest vecs0: SUCCESS (0.027s)
[831.082059] Starting dynamic subtest: ccs0
[831.094969] Dynamic subtest ccs0: SUCCESS (0.026s)
[831.097144] Subtest pi-ringfull: SUCCESS (0.178s)
[831.337644] [109/134] (189s left) gem_exec_whisper (basic-fds-all)
[831.399544] Starting subtest: basic-fds-all
[843.135977] Subtest basic-fds-all: SUCCESS (11.735s)
[843.330033] [110/134] (177s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[843.422261] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[843.424655] Starting dynamic subtest: pipe-A
[843.761009] Dynamic subtest pipe-A: SUCCESS (0.339s)
[843.776019] Starting dynamic subtest: pipe-B
[845.210721] Dynamic subtest pipe-B: SUCCESS (1.447s)
[845.223258] Starting dynamic subtest: pipe-C
[846.640100] Dynamic subtest pipe-C: SUCCESS (1.426s)
[846.657866] Starting dynamic subtest: pipe-D
[848.087454] Dynamic subtest pipe-D: SUCCESS (1.444s)
[848.089812] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (4.664s)
[849.502277] [111/134] (171s left) kms_flip (2x-flip-vs-blocking-wf-vblank)
[849.600787] Starting subtest: 2x-flip-vs-blocking-wf-vblank
[849.611735] Subtest 2x-flip-vs-blocking-wf-vblank: SKIP (0.010s)
[849.727075] [112/134] (171s left) kms_plane_multiple (tiling-x)
[849.824926] Starting subtest: tiling-x
[849.827091] Starting dynamic subtest: pipe-A-eDP-1
[853.501135] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.674s)
[853.511137] Starting dynamic subtest: pipe-B-eDP-1
[857.862854] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.360s)
[857.872273] Starting dynamic subtest: pipe-C-eDP-1
[862.188053] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.322s)
[862.199733] Starting dynamic subtest: pipe-D-eDP-1
[866.530584] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.341s)
[866.532847] Subtest tiling-x: SUCCESS (16.703s)
[867.364143] [113/134] (153s left) gem_flink_basic (basic)
[867.428645] Starting subtest: basic
[867.430644] Subtest basic: SUCCESS (0.000s)
[867.554381] [114/134] (153s left) gem_exec_parallel (basic)
[867.617974] Starting subtest: basic
[867.620287] Starting dynamic subtest: rcs0
[869.042101] Dynamic subtest rcs0: SUCCESS (1.423s)
[869.059390] Starting dynamic subtest: bcs0
[870.568157] Dynamic subtest bcs0: SUCCESS (1.522s)
[870.588658] Starting dynamic subtest: vcs0
[872.189209] Dynamic subtest vcs0: SUCCESS (1.618s)
[872.201261] Starting dynamic subtest: vcs1
[874.142381] Dynamic subtest vcs1: SUCCESS (1.950s)
[874.154611] Starting dynamic subtest: vecs0
[876.189907] Dynamic subtest vecs0: SUCCESS (2.045s)
[876.201749] Starting dynamic subtest: ccs0
[877.681631] Dynamic subtest ccs0: SUCCESS (1.490s)
[877.684390] Subtest basic: SUCCESS (10.063s)
[877.883233] [115/134] (143s left) gem_exec_whisper (basic-queues-priority-all)
[877.942789] Starting subtest: basic-queues-priority-all
[901.399909] Subtest basic-queues-priority-all: SUCCESS (23.449s)
[901.605884] [116/134] (119s left) kms_psr (fbc-psr-sprite-plane-move)
[901.709623] Starting subtest: fbc-psr-sprite-plane-move
[901.711927] Starting dynamic subtest: eDP-1
[901.715950] Dynamic subtest eDP-1: SKIP (0.000s)
[901.720026] Subtest fbc-psr-sprite-plane-move: SKIP (0.001s)
[901.794428] [117/134] (119s left) gem_sync (basic-many-each)
[901.867325] Starting subtest: basic-many-each
[904.170225] Subtest basic-many-each: SUCCESS (2.313s)
[904.369056] [118/134] (117s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-blt)
[904.476065] Starting subtest: psr-1p-primscrn-cur-indfb-draw-blt
[911.190234] Subtest psr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (6.716s)
[911.347177] [119/134] (110s left) gem_fence_thrash (bo-write-verify-threaded-x)
[911.388097] Starting subtest: bo-write-verify-threaded-x
[911.417813] Subtest bo-write-verify-threaded-x: SKIP (0.027s)
[911.483969] [120/134] (110s left) syncobj_timeline (multi-wait-available-unsubmitted-signaled)
[911.535709] Starting subtest: multi-wait-available-unsubmitted-signaled
[911.537737] Subtest multi-wait-available-unsubmitted-signaled: SUCCESS (0.000s)
[911.704201] [121/134] (109s left) kms_rotation_crc (primary-yf-tiled-reflect-x-270)
[911.798102] Starting subtest: primary-yf-tiled-reflect-x-270
[911.802456] Subtest primary-yf-tiled-reflect-x-270: SKIP (0.001s)
[911.884418] [122/134] (109s left) kms_psr (fbc-pr-sprite-mmap-gtt)
[911.985954] Starting subtest: fbc-pr-sprite-mmap-gtt
[911.990218] Subtest fbc-pr-sprite-mmap-gtt: SKIP (0.000s)
[912.082587] [123/134] (109s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-fullscreen)
[912.175995] Starting subtest: psr-2p-primscrn-spr-indfb-fullscreen
[912.179679] Subtest psr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[912.302496] [124/134] (109s left) syncobj_wait (multi-wait-for-submit-submitted)
[912.360671] Starting subtest: multi-wait-for-submit-submitted
[912.467199] Subtest multi-wait-for-submit-submitted: SUCCESS (0.105s)
[912.556259] [125/134] (109s left) sw_sync (sync_busy_fork_unixsocket)
[912.593138] Starting subtest: sync_busy_fork_unixsocket
[912.598128] Subtest sync_busy_fork_unixsocket: SUCCESS (0.008s)
[912.680115] [126/134] (109s left) kms_async_flips (crc)
[912.773967] Starting subtest: crc
[913.156950] Starting dynamic subtest: pipe-A-eDP-1
[916.100970] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.944s)
[916.478640] Starting dynamic subtest: pipe-B-eDP-1
[919.409068] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.940s)
[919.791566] Starting dynamic subtest: pipe-C-eDP-1
[922.652053] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.869s)
[923.026401] Starting dynamic subtest: pipe-D-eDP-1
[925.862645] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.847s)
[925.864925] Subtest crc: SUCCESS (13.090s)
[927.050021] [127/134] ( 94s left) kms_cursor_legacy (cursorb-vs-flipa-atomic)
[927.181500] Starting subtest: cursorB-vs-flipA-atomic
[927.186306] Subtest cursorB-vs-flipA-atomic: SKIP (0.001s)
[927.336889] [128/134] ( 94s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-plflip-blt)
[927.435507] Starting subtest: fbcpsr-2p-primscrn-shrfb-plflip-blt
[927.439786] Subtest fbcpsr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[927.563960] [129/134] ( 94s left) gem_exec_flush (basic-wb-rw-before-default)
[927.620055] Starting subtest: basic-wb-rw-before-default
[933.077422] Subtest basic-wb-rw-before-default: SUCCESS (5.458s)
[933.274243] [130/134] ( 88s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs)
[933.372836] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs
[933.375138] Starting dynamic subtest: pipe-A-eDP-1
[933.397828] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[933.409499] Starting dynamic subtest: pipe-B-eDP-1
[933.430785] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[933.439304] Starting dynamic subtest: pipe-C-eDP-1
[933.460241] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[933.468600] Starting dynamic subtest: pipe-D-eDP-1
[933.490115] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[933.494436] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs: SKIP (0.006s)
[935.621306] [131/134] ( 86s left) kms_properties (colorop-properties-legacy)
[935.721980] Starting subtest: colorop-properties-legacy
[935.724280] Starting dynamic subtest: pipe-A-eDP-1
[936.059289] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.336s)
[936.071505] Starting dynamic subtest: pipe-B-eDP-1
[937.175268] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.114s)
[937.184731] Starting dynamic subtest: pipe-C-eDP-1
[938.208222] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.033s)
[938.222495] Starting dynamic subtest: pipe-D-eDP-1
[939.245328] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.036s)
[939.247589] Subtest colorop-properties-legacy: SUCCESS (3.525s)
[940.056764] [132/134] ( 82s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-render)
[940.166071] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-render
[940.170043] Subtest fbc-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[940.302110] [133/134] ( 81s left) kms_busy (extended-modeset-hang-newfb)
[940.409193] Starting subtest: extended-modeset-hang-newfb
[940.411488] Starting dynamic subtest: pipe-A
[975.841251] Dynamic subtest pipe-A: SUCCESS (35.434s)
[975.852306] Starting dynamic subtest: pipe-D
[998.060693] Dynamic subtest pipe-D: SUCCESS (22.220s)
[998.063014] Subtest extended-modeset-hang-newfb: SUCCESS (57.658s)
[998.868716] [134/134] ( 23s left) kms_color_pipeline (plane-lut1d-ctm3x4-lut1d)
[998.973026] Starting subtest: plane-lut1d-ctm3x4-lut1d
[998.986491] Starting dynamic subtest: pipe-A-eDP-1
[999.723847] Dynamic subtest pipe-A-eDP-1: FAIL (0.332s)
[1000.442939] Starting dynamic subtest: pipe-B-eDP-1
[1001.300154] Dynamic subtest pipe-B-eDP-1: FAIL (0.312s)
[1001.871664] Starting dynamic subtest: pipe-C-eDP-1
[1002.690096] Dynamic subtest pipe-C-eDP-1: FAIL (0.235s)
[1003.195833] Starting dynamic subtest: pipe-D-eDP-1
[1004.033802] Dynamic subtest pipe-D-eDP-1: FAIL (0.257s)
[1004.036176] Subtest plane-lut1d-ctm3x4-lut1d: FAIL (4.770s)
[1004.584112] Closing watchdogs
results: parsing output: 0/ for test: api_intel_allocator
results: parsing output: 1/ for test: gen9_exec_parse
results: parsing output: 2/ for test: i915_pm_rc6_residency
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: gem_exec_reloc
results: parsing output: 5/ for test: kms_plane_scaling
results: parsing output: 6/ for test: kms_plane_cursor
results: parsing output: 7/ for test: sw_sync
results: parsing output: 8/ for test: gen9_exec_parse
results: parsing output: 9/ for test: gem_mmap_offset
results: parsing output: 10/ for test: kms_psr
results: parsing output: 11/ for test: kms_vblank
results: parsing output: 12/ for test: gem_softpin
results: parsing output: 13/ for test: kms_chamelium_color
results: parsing output: 14/ for test: syncobj_timeline
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: prime_mmap
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: gem_render_copy
results: parsing output: 20/ for test: kms_cursor_legacy
results: parsing output: 21/ for test: gem_ctx_create
results: parsing output: 22/ for test: kms_cursor_legacy
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: syncobj_basic
results: parsing output: 25/ for test: syncobj_timeline
results: parsing output: 26/ for test: kms_pm_dc
results: parsing output: 27/ for test: gem_exec_reloc
results: parsing output: 28/ for test: kms_big_fb
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_content_protection
results: parsing output: 31/ for test: kms_cursor_crc
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: kms_cursor_legacy
results: parsing output: 34/ for test: gem_ctx_shared
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: prime_busy
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: sysfs_preempt_timeout
results: parsing output: 39/ for test: gem_pwrite
results: parsing output: 40/ for test: kms_vblank
results: parsing output: 41/ for test: kms_chamelium_hpd
results: parsing output: 42/ for test: prime_self_import
results: parsing output: 43/ for test: kms_flip_scaled_crc
results: parsing output: 44/ for test: gem_exec_reloc
results: parsing output: 45/ for test: kms_big_fb
results: parsing output: 46/ for test: kms_multipipe_modeset
results: parsing output: 47/ for test: kms_psr2_sf
results: parsing output: 48/ for test: drm_read
results: parsing output: 49/ for test: gem_ctx_persistence
results: parsing output: 50/ for test: prime_mmap
results: parsing output: 51/ for test: syncobj_timeline
results: parsing output: 52/ for test: kms_big_fb
results: parsing output: 53/ for test: gem_mmap_gtt
results: parsing output: 54/ for test: gem_exec_suspend
results: parsing output: 55/ for test: perf_pmu
results: parsing output: 56/ for test: gem_wait
results: parsing output: 57/ for test: prime_mmap
results: parsing output: 58/ for test: gem_exec_balancer
results: parsing output: 59/ for test: kms_dp_link_training
results: parsing output: 60/ for test: gem_busy
results: parsing output: 61/ for test: kms_big_fb
results: parsing output: 62/ for test: kms_cursor_crc
results: parsing output: 63/ for test: kms_joiner
results: parsing output: 64/ for test: kms_flip
results: parsing output: 65/ for test: perf_pmu
results: parsing output: 66/ for test: gem_lmem_swapping
results: parsing output: 67/ for test: kms_ccs
results: parsing output: 68/ for test: gem_exec_schedule
results: parsing output: 69/ for test: gem_workarounds
results: parsing output: 70/ for test: kms_hdr
results: parsing output: 71/ for test: kms_prime
results: parsing output: 72/ for test: kms_ccs
results: parsing output: 73/ for test: gem_exec_endless
results: parsing output: 74/ for test: kms_async_flips
results: parsing output: 75/ for test: dmabuf_sync_file
results: parsing output: 76/ for test: kms_plane
results: parsing output: 77/ for test: kms_ccs
results: parsing output: 78/ for test: kms_flip
results: parsing output: 79/ for test: gem_mmap_gtt
results: parsing output: 80/ for test: kms_cursor_crc
results: parsing output: 81/ for test: kms_chamelium_frames
results: parsing output: 82/ for test: gen3_render_linear_blits
results: parsing output: 83/ for test: kms_big_fb
results: parsing output: 84/ for test: kms_properties
results: parsing output: 85/ for test: syncobj_wait
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_psr
results: parsing output: 88/ for test: gem_exec_params
results: parsing output: 89/ for test: i915_hangman
results: parsing output: 90/ for test: kms_flip_scaled_crc
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_psr2_sf
results: parsing output: 93/ for test: gem_ctx_param
results: parsing output: 94/ for test: kms_flip
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_flip
results: parsing output: 97/ for test: core_auth
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: gem_render_copy
results: parsing output: 100/ for test: i915_selftest
results: parsing output: 101/ for test: kms_big_fb
results: parsing output: 102/ for test: gem_userptr_blits
results: parsing output: 103/ for test: gem_exec_fence
results: parsing output: 104/ for test: kms_psr_stress_test
results: parsing output: 105/ for test: syncobj_timeline
results: parsing output: 106/ for test: gem_ctx_engines
results: parsing output: 107/ for test: gem_exec_schedule
results: parsing output: 108/ for test: gem_exec_whisper
results: parsing output: 109/ for test: kms_plane_scaling
results: parsing output: 110/ for test: kms_flip
results: parsing output: 111/ for test: kms_plane_multiple
results: parsing output: 112/ for test: gem_flink_basic
results: parsing output: 113/ for test: gem_exec_parallel
results: parsing output: 114/ for test: gem_exec_whisper
results: parsing output: 115/ for test: kms_psr
results: parsing output: 116/ for test: gem_sync
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: gem_fence_thrash
results: parsing output: 119/ for test: syncobj_timeline
results: parsing output: 120/ for test: kms_rotation_crc
results: parsing output: 121/ for test: kms_psr
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: syncobj_wait
results: parsing output: 124/ for test: sw_sync
results: parsing output: 125/ for test: kms_async_flips
results: parsing output: 126/ for test: kms_cursor_legacy
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: gem_exec_flush
results: parsing output: 129/ for test: kms_ccs
results: parsing output: 130/ for test: kms_properties
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: kms_busy
results: parsing output: 133/ for test: kms_color_pipeline
Done.