[56.852763] Initializing watchdogs
[56.852806]   /dev/watchdog0
[56.866878] [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.871481] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.873561] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.876899] [001/133] (960s left) perf (disabled-read-error)
[57.039328] Starting subtest: disabled-read-error
[57.053420] Subtest disabled-read-error: SUCCESS (0.015s)
[57.096200] [002/133] (960s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-move)
[57.207226] Starting subtest: psr-2p-scndscrn-cur-indfb-move
[57.211206] Subtest psr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[57.314193] [003/133] (960s left) kms_properties (get_properties-sanity-atomic)
[57.407468] Starting subtest: get_properties-sanity-atomic
[57.409644] Subtest get_properties-sanity-atomic: SUCCESS (0.002s)
[57.479742] [004/133] (960s left) gem_fenced_exec_thrash (2-spare-fences)
[57.542981] Subtest 2-spare-fences: SKIP (0.000s)
[57.622334] [005/133] (959s left) api_intel_allocator (gem-pool)
[57.676485] Starting subtest: gem-pool
[57.685112] Subtest gem-pool: SUCCESS (0.009s)
[57.789839] [006/133] (959s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-msflip-blt)
[57.886728] Starting subtest: fbc-2p-primscrn-indfb-msflip-blt
[57.890308] Subtest fbc-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[57.985865] [007/133] (959s left) kms_joiner (switch-modeset-ultra-joiner-big-joiner)
[58.087658] Starting subtest: switch-modeset-ultra-joiner-big-joiner
[58.091500] Subtest switch-modeset-ultra-joiner-big-joiner: SKIP (0.000s)
[58.149323] [008/133] (959s left) kms_chamelium_hpd (vga-hpd-after-suspend)
[58.542629] Subtest vga-hpd-after-suspend: SKIP (0.000s)
[59.341874] [009/133] (958s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[59.442905] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu
[59.447580] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[59.533524] [010/133] (958s left) i915_drm_fdinfo (busy-check-all)
[59.583781] Starting subtest: busy-check-all
[59.586228] Starting dynamic subtest: rcs0
[59.591074] Dynamic subtest rcs0: SKIP (0.004s)
[59.598520] Starting dynamic subtest: bcs0
[59.602971] Dynamic subtest bcs0: SKIP (0.000s)
[59.609597] Starting dynamic subtest: vcs0
[59.613662] Dynamic subtest vcs0: SKIP (0.000s)
[59.620124] Starting dynamic subtest: vcs1
[59.624414] Dynamic subtest vcs1: SKIP (0.000s)
[59.630793] Starting dynamic subtest: vecs0
[59.635174] Dynamic subtest vecs0: SKIP (0.000s)
[59.641417] Starting dynamic subtest: ccs0
[59.645783] Dynamic subtest ccs0: SKIP (0.000s)
[59.650722] Subtest busy-check-all: SKIP (0.006s)
[59.703906] [011/133] (958s left) i915_pm_freq_api (freq-suspend)
[59.804190] Starting subtest: freq-suspend
[59.806192] Starting dynamic subtest: gt0
[75.910970] Dynamic subtest gt0: SUCCESS (2.131s)
[75.918763] Starting dynamic subtest: gt1
[91.925914] Dynamic subtest gt1: SUCCESS (1.214s)
[91.927926] Subtest freq-suspend: SUCCESS (3.347s)
[93.007311] [012/133] (925s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-downscaling)
[93.109680] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-downscaling
[93.112022] Starting dynamic subtest: pipe-A-default-mode
[94.240963] Dynamic subtest pipe-A-default-mode: SKIP (1.129s)
[94.245187] Subtest flip-64bpp-4tile-to-16bpp-4tile-downscaling: SKIP (1.130s)
[94.387744] [013/133] (924s left) gem_pwrite (basic-random)
[94.456724] Subtest basic-random: SKIP (0.000s)
[94.510875] [014/133] (924s left) kms_atomic_transition (plane-all-modeset-transition)
[94.606313] Starting subtest: plane-all-modeset-transition
[94.610289] Subtest plane-all-modeset-transition: SKIP (0.000s)
[94.673634] [015/133] (924s left) syncobj_timeline (etime-multi-wait-submitted)
[94.739954] Starting subtest: etime-multi-wait-submitted
[94.848764] Subtest etime-multi-wait-submitted: SUCCESS (0.108s)
[94.921979] [016/133] (924s left) kms_content_protection (lic-type-0-hdcp14)
[95.052493] Starting subtest: lic-type-0-hdcp14
[95.057269] Subtest lic-type-0-hdcp14: SKIP (0.000s)
[95.189868] [017/133] (923s left) kms_vrr (max-min)
[95.277150] Starting subtest: max-min
[95.283741] Subtest max-min: SKIP (0.000s)
[95.349998] [018/133] (923s left) i915_pm_rpm (system-suspend-execbuf)
[96.903130] Starting subtest: system-suspend-execbuf
[154.811042] Subtest system-suspend-execbuf: SUCCESS (42.933s)
[155.176603] [019/133] (863s left) gem_exec_whisper (basic-normal-all)
[155.237098] Starting subtest: basic-normal-all
[157.488458] Subtest basic-normal-all: SUCCESS (2.253s)
[157.673388] [020/133] (861s left) gem_exec_params (dr1-dirt)
[157.737499] Starting subtest: DR1-dirt
[157.740461] Subtest DR1-dirt: SUCCESS (0.000s)
[157.836622] [021/133] (861s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-5)
[157.932183] Starting subtest: planes-unity-scaling-downscale-factor-0-5
[157.934726] Starting dynamic subtest: pipe-A
[157.953763] Dynamic subtest pipe-A: SKIP (0.017s)
[157.961903] Starting dynamic subtest: pipe-B
[157.990106] Dynamic subtest pipe-B: SKIP (0.034s)
[157.998225] Starting dynamic subtest: pipe-C
[158.023076] Dynamic subtest pipe-C: SKIP (0.034s)
[158.031068] Starting dynamic subtest: pipe-D
[158.055678] Dynamic subtest pipe-D: SKIP (0.033s)
[158.060429] Subtest planes-unity-scaling-downscale-factor-0-5: SKIP (0.119s)
[158.131721] [022/133] (861s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions-varying-size)
[158.258303] Starting subtest: cursorB-vs-flipA-atomic-transitions-varying-size
[158.263450] Subtest cursorB-vs-flipA-atomic-transitions-varying-size: SKIP (0.001s)
[158.411115] [023/133] (860s left) gem_fence_thrash (bo-write-verify-y)
[158.448472] Starting subtest: bo-write-verify-y
[158.471147] Subtest bo-write-verify-y: SKIP (0.017s)
[158.524895] [024/133] (860s left) kms_big_fb (x-tiled-16bpp-rotate-90)
[158.624479] Starting subtest: x-tiled-16bpp-rotate-90
[158.644573] Subtest x-tiled-16bpp-rotate-90: SKIP (0.020s)
[158.742719] [025/133] (860s left) kms_plane (pixel-format-y-tiled-modifier)
[158.837357] Starting subtest: pixel-format-y-tiled-modifier
[158.841860] Subtest pixel-format-y-tiled-modifier: SKIP (0.000s)
[158.906034] [026/133] (860s left) syncobj_timeline (device-signal-unordered)
[158.959034] Starting subtest: device-signal-unordered
[158.961292] Subtest device-signal-unordered: SUCCESS (0.000s)
[159.048090] [027/133] (860s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[159.154877] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[159.159200] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[159.253608] [028/133] (860s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-legacy)
[159.366426] Starting subtest: flip-vs-cursor-busy-crc-legacy
[160.191293] Subtest flip-vs-cursor-busy-crc-legacy: SUCCESS (0.825s)
[161.004775] [029/133] (858s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-pwrite)
[161.113290] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-pwrite
[161.118305] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[161.199576] [030/133] (858s left) kms_plane_lowres (tiling-x)
[161.299213] Starting subtest: tiling-x
[161.301754] Starting dynamic subtest: pipe-A-eDP-1
[161.306337] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[161.596026] Starting dynamic subtest: pipe-B-eDP-1
[161.601451] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[161.609469] Starting dynamic subtest: pipe-C-eDP-1
[161.614942] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[161.623082] Starting dynamic subtest: pipe-D-eDP-1
[161.628389] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[161.634236] Subtest tiling-x: SKIP (0.297s)
[162.430755] [031/133] (857s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-submitted-signaled)
[162.485931] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[162.488461] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[162.596888] [032/133] (856s left) kms_big_fb (y-tiled-64bpp-rotate-270)
[162.696702] Starting subtest: y-tiled-64bpp-rotate-270
[162.701855] Subtest y-tiled-64bpp-rotate-270: SKIP (0.000s)
[162.777092] [033/133] (856s left) kms_big_fb (yf-tiled-16bpp-rotate-270)
[162.880446] Starting subtest: yf-tiled-16bpp-rotate-270
[162.885909] Subtest yf-tiled-16bpp-rotate-270: SKIP (0.000s)
[162.977379] [034/133] (856s left) gem_mmap_gtt (fault-concurrent-x)
[163.032265] Subtest fault-concurrent-X: SKIP (0.000s)
[163.097630] [035/133] (856s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite)
[163.195746] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite
[163.200282] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[163.271646] [036/133] (856s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[163.361749] Starting subtest: bad-pixel-format-y-tiled-ccs
[163.364061] Starting dynamic subtest: pipe-A-eDP-1
[163.386426] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[163.395955] Starting dynamic subtest: pipe-B-eDP-1
[163.415634] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[163.423469] Starting dynamic subtest: pipe-C-eDP-1
[163.444309] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[163.452132] Starting dynamic subtest: pipe-D-eDP-1
[163.473834] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[163.477870] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.003s)
[165.601010] [037/133] (854s left) gem_bad_reloc (negative-reloc-lut)
[165.663524] Subtest negative-reloc-lut: SKIP (0.000s)
[165.731353] [038/133] (854s left) kms_busy (extended-modeset-hang-oldfb)
[165.817615] Starting subtest: extended-modeset-hang-oldfb
[165.819918] Starting dynamic subtest: pipe-A
[177.297340] Dynamic subtest pipe-A: SUCCESS (11.479s)
[177.306157] Starting dynamic subtest: pipe-D
[189.581500] Dynamic subtest pipe-D: SUCCESS (12.280s)
[189.584485] Subtest extended-modeset-hang-oldfb: SUCCESS (23.762s)
[190.386511] [039/133] (829s left) kms_pm_dc (dc6-psr)
[191.480221] Starting subtest: dc6-psr
[194.387377] Subtest dc6-psr: SUCCESS (2.902s)
[195.202998] [040/133] (824s left) perf_pmu (pmu-read)
[195.242341] Starting subtest: pmu-read
[195.245137] Subtest pmu-read: SUCCESS (0.003s)
[195.346010] [041/133] (824s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[195.915249] Starting subtest: syncobj-timeline-invalid-flags
[195.918236] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.000s)
[197.672363] [FACT gem_exec_fence (syncobj-timeline-invalid-flags)] new: kernel.kmod_is_loaded.vgem: true
[197.675825] [042/133] (822s left) gem_softpin (allocator-nopin)
[197.736939] Starting subtest: allocator-nopin
[197.832719] Subtest allocator-nopin: SUCCESS (0.095s)
[197.968662] [043/133] (822s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs)
[198.061061] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs
[198.063673] Starting dynamic subtest: pipe-A-eDP-1
[198.131908] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[198.141221] Starting dynamic subtest: pipe-B-eDP-1
[198.209001] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[198.217769] Starting dynamic subtest: pipe-C-eDP-1
[198.285199] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[198.294249] Starting dynamic subtest: pipe-D-eDP-1
[198.355739] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[198.360466] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs: SKIP (0.007s)
[200.275982] [044/133] (819s left) kms_multipipe_modeset (basic-max-pipe-crc-check)
[200.367970] Starting subtest: basic-max-pipe-crc-check
[200.372696] Subtest basic-max-pipe-crc-check: SKIP (0.000s)
[200.418234] [045/133] (819s left) gem_ctx_isolation (preservation-s3)
[200.482436] Starting subtest: preservation-S3
[200.485343] Starting dynamic subtest: rcs0
[216.934365] Dynamic subtest rcs0: SUCCESS (1.305s)
[216.943185] Starting dynamic subtest: bcs0
[232.957661] Dynamic subtest bcs0: SUCCESS (1.297s)
[232.968650] Starting dynamic subtest: vcs0
[248.940681] Dynamic subtest vcs0: SUCCESS (2.627s)
[248.949651] Starting dynamic subtest: vcs1
[266.971927] Dynamic subtest vcs1: SUCCESS (5.003s)
[266.981205] Starting dynamic subtest: vecs0
[284.965690] Dynamic subtest vecs0: SUCCESS (4.987s)
[284.979172] Starting dynamic subtest: ccs0
[302.985578] Dynamic subtest ccs0: SUCCESS (3.205s)
[302.988496] Subtest preservation-S3: SUCCESS (18.427s)
[303.205783] [046/133] (716s left) kms_big_fb (yf-tiled-64bpp-rotate-0)
[303.317562] Starting subtest: yf-tiled-64bpp-rotate-0
[303.322649] Subtest yf-tiled-64bpp-rotate-0: SKIP (0.000s)
[303.414929] [047/133] (716s left) kms_chamelium_frames (dp-crc-single)
[303.797953] Subtest dp-crc-single: SKIP (0.000s)
[304.569706] [048/133] (715s left) gem_tiled_swapping (non-threaded)
[304.622081] Subtest non-threaded: SKIP (0.000s)
[304.675793] [049/133] (715s left) gem_ctx_shared (disjoint-timelines)
[304.726015] Starting subtest: disjoint-timelines
[304.745906] Subtest disjoint-timelines: SUCCESS (0.020s)
[304.965209] [050/133] (715s left) i915_suspend (forcewake)
[305.006100] Starting subtest: forcewake
[336.927901] Subtest forcewake: SUCCESS (2.258s)
[337.021922] [051/133] (683s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[337.086732] Starting subtest: y-tiled-ccs-to-y-tiled
[337.091926] Subtest y-tiled-ccs-to-y-tiled: SKIP (0.000s)
[337.275091] [052/133] (683s left) kms_flip (2x-nonexisting-fb)
[337.364576] Starting subtest: 2x-nonexisting-fb
[337.372475] Subtest 2x-nonexisting-fb: SKIP (0.010s)
[337.463688] [053/133] (683s left) gem_exec_fence (basic-await)
[337.637788] Starting subtest: basic-await
[337.640666] Starting dynamic subtest: rcs0
[337.714279] Dynamic subtest rcs0: SUCCESS (0.076s)
[337.724651] Starting dynamic subtest: bcs0
[337.790413] Dynamic subtest bcs0: SUCCESS (0.075s)
[337.800939] Starting dynamic subtest: vcs0
[337.861933] Dynamic subtest vcs0: SUCCESS (0.070s)
[337.874349] Starting dynamic subtest: vcs1
[337.935635] Dynamic subtest vcs1: SUCCESS (0.072s)
[337.947001] Starting dynamic subtest: vecs0
[338.008379] Dynamic subtest vecs0: SUCCESS (0.072s)
[338.020364] Starting dynamic subtest: ccs0
[338.080803] Dynamic subtest ccs0: SUCCESS (0.071s)
[338.083777] Subtest basic-await: SUCCESS (0.443s)
[339.388791] [054/133] (681s left) gem_ctx_persistence (legacy-engines-queued)
[339.457766] Starting subtest: legacy-engines-queued
[339.460432] Starting dynamic subtest: render
[339.525491] Dynamic subtest render: SUCCESS (0.068s)
[339.537369] Starting dynamic subtest: bsd1
[341.289721] Dynamic subtest bsd1: SUCCESS (1.758s)
[341.301156] Starting dynamic subtest: bsd2
[343.271836] Dynamic subtest bsd2: SUCCESS (1.976s)
[343.283398] Starting dynamic subtest: blt
[343.321819] Dynamic subtest blt: SUCCESS (0.049s)
[343.330920] Starting dynamic subtest: vebox
[345.318421] Dynamic subtest vebox: SUCCESS (1.992s)
[345.321521] Subtest legacy-engines-queued: SUCCESS (5.852s)
[345.401272] [055/133] (675s left) kms_cursor_legacy (torture-bo)
[345.523211] Starting subtest: torture-bo
[345.525455] Starting dynamic subtest: pipe-A
[351.479411] Dynamic subtest pipe-A: SUCCESS (5.948s)
[351.497936] Starting dynamic subtest: pipe-B
[357.926828] Dynamic subtest pipe-B: SUCCESS (6.443s)
[357.935125] Starting dynamic subtest: pipe-C
[364.346316] Dynamic subtest pipe-C: SUCCESS (6.415s)
[364.360225] Starting dynamic subtest: pipe-D
[370.184878] Dynamic subtest pipe-D: SUCCESS (5.834s)
[370.193431] Starting dynamic subtest: all-pipes
[376.775313] Dynamic subtest all-pipes: SUCCESS (6.585s)
[376.778056] Subtest torture-bo: SUCCESS (31.231s)
[376.931290] [056/133] (643s left) kms_cursor_crc (cursor-random-256x256)
[377.182052] Starting subtest: cursor-random-256x256
[377.187404] Starting dynamic subtest: pipe-A-eDP-1
[380.554069] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.366s)
[380.581970] Starting dynamic subtest: pipe-D-eDP-1
[384.643823] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.063s)
[384.646192] Subtest cursor-random-256x256: SUCCESS (7.455s)
[385.443381] [057/133] (635s left) kms_addfb_basic (addfb25-bad-modifier)
[385.532592] Starting subtest: addfb25-bad-modifier
[385.534845] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[385.630887] [058/133] (635s left) syncobj_wait (wait-zero-handles)
[385.679895] Starting subtest: wait-zero-handles
[385.682368] Subtest wait-zero-handles: SUCCESS (0.000s)
[385.764498] [059/133] (634s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[385.863146] Starting subtest: psr-1p-primscrn-spr-indfb-draw-render
[392.661962] Subtest psr-1p-primscrn-spr-indfb-draw-render: SUCCESS (6.799s)
[392.784991] [060/133] (627s left) kms_async_flips (test-cursor)
[392.888910] Starting subtest: test-cursor
[392.893809] Subtest test-cursor: SKIP (0.000s)
[394.106459] [061/133] (626s left) kms_pm_rpm (pm-caching)
[395.633742] Starting subtest: pm-caching
[395.638694] Subtest pm-caching: SKIP (0.000s)
[396.344394] [062/133] (624s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-pgflip-blt)
[396.436930] Starting subtest: psr-2p-scndscrn-shrfb-pgflip-blt
[396.441425] Subtest psr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[396.533234] [063/133] (624s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf)
[396.633786] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf
[396.636045] Starting dynamic subtest: pipe-A-eDP-1
[396.718691] Dynamic subtest pipe-A-eDP-1: SKIP (0.088s)
[396.729950] Starting dynamic subtest: pipe-B-eDP-1
[397.908341] Dynamic subtest pipe-B-eDP-1: SKIP (1.187s)
[397.912890] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf: SKIP (1.277s)
[399.107387] [064/133] (621s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-plflip-blt)
[399.224086] Starting subtest: fbcpsr-2p-primscrn-indfb-plflip-blt
[399.228924] Subtest fbcpsr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[399.319326] [065/133] (621s left) kms_selftest (drm_format)
[399.360935] Starting subtest: drm_format
[399.383585] Starting dynamic subtest: drm_test_format_block_width_invalid
[399.464763] Dynamic subtest drm_test_format_block_width_invalid: SUCCESS (0.081s)
[399.475503] Starting dynamic subtest: drm_test_format_block_width_one_plane
[399.552001] Dynamic subtest drm_test_format_block_width_one_plane: SUCCESS (0.086s)
[399.562708] Starting dynamic subtest: drm_test_format_block_width_two_plane
[399.644929] Dynamic subtest drm_test_format_block_width_two_plane: SUCCESS (0.092s)
[399.655061] Starting dynamic subtest: drm_test_format_block_width_three_plane
[399.739046] Dynamic subtest drm_test_format_block_width_three_plane: SUCCESS (0.093s)
[399.748824] Starting dynamic subtest: drm_test_format_block_width_tiled
[399.823920] Dynamic subtest drm_test_format_block_width_tiled: SUCCESS (0.085s)
[399.834328] Starting dynamic subtest: drm_test_format_block_height_invalid
[399.908484] Dynamic subtest drm_test_format_block_height_invalid: SUCCESS (0.083s)
[399.918648] Starting dynamic subtest: drm_test_format_block_height_one_plane
[399.985650] Dynamic subtest drm_test_format_block_height_one_plane: SUCCESS (0.077s)
[399.995953] Starting dynamic subtest: drm_test_format_block_height_two_plane
[400.069048] Dynamic subtest drm_test_format_block_height_two_plane: SUCCESS (0.082s)
[400.078725] Starting dynamic subtest: drm_test_format_block_height_three_plane
[400.163049] Dynamic subtest drm_test_format_block_height_three_plane: SUCCESS (0.094s)
[400.173294] Starting dynamic subtest: drm_test_format_block_height_tiled
[400.266356] Dynamic subtest drm_test_format_block_height_tiled: SUCCESS (0.100s)
[400.275505] Starting dynamic subtest: drm_test_format_min_pitch_invalid
[400.345832] Dynamic subtest drm_test_format_min_pitch_invalid: SUCCESS (0.080s)
[400.355736] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_8bpp
[400.433042] Dynamic subtest drm_test_format_min_pitch_one_plane_8bpp: SUCCESS (0.088s)
[400.443394] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_16bpp
[400.515916] Dynamic subtest drm_test_format_min_pitch_one_plane_16bpp: SUCCESS (0.082s)
[400.526028] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_24bpp
[400.599599] Dynamic subtest drm_test_format_min_pitch_one_plane_24bpp: SUCCESS (0.082s)
[400.609807] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_32bpp
[400.691275] Dynamic subtest drm_test_format_min_pitch_one_plane_32bpp: SUCCESS (0.092s)
[400.701755] Starting dynamic subtest: drm_test_format_min_pitch_two_plane
[400.773063] Dynamic subtest drm_test_format_min_pitch_two_plane: SUCCESS (0.081s)
[400.782510] Starting dynamic subtest: drm_test_format_min_pitch_three_plane_8bpp
[400.857256] Dynamic subtest drm_test_format_min_pitch_three_plane_8bpp: SUCCESS (0.083s)
[400.867782] Starting dynamic subtest: drm_test_format_min_pitch_tiled
[400.950595] Dynamic subtest drm_test_format_min_pitch_tiled: SUCCESS (0.090s)
[400.952856] Subtest drm_format: SUCCESS (1.589s)
[401.087971] [066/133] (619s left) kms_psr (fbc-pr-basic)
[401.182047] Starting subtest: fbc-pr-basic
[401.186282] Subtest fbc-pr-basic: SKIP (0.000s)
[401.253029] [067/133] (619s left) kms_hdr (static-swap)
[401.345424] Starting subtest: static-swap
[401.351623] Subtest static-swap: SKIP (0.000s)
[401.415489] [068/133] (619s left) kms_color (ctm-0-50)
[401.507491] Starting subtest: ctm-0-50
[401.509554] Starting dynamic subtest: pipe-A-eDP-1
[405.040497] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.532s)
[405.050722] Starting dynamic subtest: pipe-B-eDP-1
[409.331430] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.287s)
[409.340812] Starting dynamic subtest: pipe-C-eDP-1
[413.417211] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.084s)
[413.426460] Starting dynamic subtest: pipe-D-eDP-1
[417.408814] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.988s)
[417.411203] Subtest ctm-0-50: SUCCESS (15.894s)
[418.227615] [069/133] (602s left) gem_mmap_gtt (basic-small-copy-odd)
[418.289278] Subtest basic-small-copy-odd: SKIP (0.000s)
[418.356300] [070/133] (602s left) kms_dsc (dsc-fractional-bpp-with-bpc)
[418.449902] Starting subtest: dsc-fractional-bpp-with-bpc
[418.485065] Subtest dsc-fractional-bpp-with-bpc: SKIP (0.001s)
[418.525319] [071/133] (602s left) kms_flip (2x-wf_vblank-ts-check)
[418.618909] Starting subtest: 2x-wf_vblank-ts-check
[418.630043] Subtest 2x-wf_vblank-ts-check: SKIP (0.013s)
[418.709819] [072/133] (602s left) gen9_exec_parse (batch-invalid-length)
[418.769978] Subtest batch-invalid-length: SKIP (0.000s)
[418.861513] [073/133] (602s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-onoff)
[418.948942] Starting subtest: psr-1p-primscrn-cur-indfb-onoff
[425.927085] Subtest psr-1p-primscrn-cur-indfb-onoff: SUCCESS (6.978s)
[426.040766] [074/133] (595s left) gem_huc_copy (huc-copy)
[426.102746] Starting subtest: huc-copy
[426.105108] Subtest huc-copy: SUCCESS (0.003s)
[426.235436] [075/133] (595s left) kms_psr (fbc-psr-sprite-plane-move)
[426.318842] Starting subtest: fbc-psr-sprite-plane-move
[426.321369] Starting dynamic subtest: eDP-1
[426.326907] Dynamic subtest eDP-1: SKIP (0.000s)
[426.332079] Subtest fbc-psr-sprite-plane-move: SKIP (0.001s)
[426.391857] [076/133] (595s left) kms_big_fb (4-tiled-16bpp-rotate-90)
[426.482799] Starting subtest: 4-tiled-16bpp-rotate-90
[426.502884] Subtest 4-tiled-16bpp-rotate-90: SKIP (0.019s)
[426.609785] [077/133] (594s left) gem_exec_reloc (basic-gtt-read-active)
[426.667538] Subtest basic-gtt-read-active: SKIP (0.000s)
[426.743986] [078/133] (594s left) gem_set_tiling_vs_blt (tiled-to-untiled)
[426.796893] Subtest tiled-to-untiled: SKIP (0.000s)
[426.867520] [079/133] (594s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[426.958826] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu
[431.244621] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (4.287s)
[432.367120] [080/133] (589s left) gem_madvise (dontneed-before-mmap)
[432.405030] Starting subtest: dontneed-before-mmap
[432.418697] Subtest dontneed-before-mmap: SUCCESS (0.015s)
[432.511999] [081/133] (589s left) kms_async_flips (basic-modeset-with-all-modifiers-formats)
[432.598925] Starting subtest: basic-modeset-with-all-modifiers-formats
[432.971274] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB8888
[433.737458] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB8888: SUCCESS (0.767s)
[433.746830] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB2101010
[433.758634] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB2101010: SUCCESS (0.020s)
[433.767067] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB16161616F
[433.780445] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB16161616F: SUCCESS (0.021s)
[433.792948] Starting dynamic subtest: pipe-A-eDP-1-4-RGB565
[433.958522] Dynamic subtest pipe-A-eDP-1-4-RGB565: SUCCESS (0.177s)
[433.967584] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB8888
[433.980775] Dynamic subtest pipe-A-eDP-1-4-XRGB8888: SUCCESS (0.021s)
[433.989232] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB2101010
[434.002750] Dynamic subtest pipe-A-eDP-1-4-XRGB2101010: SUCCESS (0.021s)
[434.011345] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB16161616F
[434.025075] Dynamic subtest pipe-A-eDP-1-4-XRGB16161616F: SUCCESS (0.022s)
[434.034052] Starting dynamic subtest: pipe-A-eDP-1-4-YUYV
[434.047320] Dynamic subtest pipe-A-eDP-1-4-YUYV: SUCCESS (0.022s)
[434.055823] Starting dynamic subtest: pipe-A-eDP-1-4-Y210
[434.069044] Dynamic subtest pipe-A-eDP-1-4-Y210: SUCCESS (0.021s)
[434.077792] Starting dynamic subtest: pipe-A-eDP-1-4-XYUV8888
[434.091662] Dynamic subtest pipe-A-eDP-1-4-XYUV8888: SUCCESS (0.021s)
[434.100033] Starting dynamic subtest: pipe-A-eDP-1-4-XV30
[434.113785] Dynamic subtest pipe-A-eDP-1-4-XV30: SUCCESS (0.021s)
[434.122403] Starting dynamic subtest: pipe-A-eDP-1-4-XV36
[434.135815] Dynamic subtest pipe-A-eDP-1-4-XV36: SUCCESS (0.022s)
[434.144360] Starting dynamic subtest: pipe-A-eDP-1-x-RGB565
[434.326784] Dynamic subtest pipe-A-eDP-1-x-RGB565: SUCCESS (0.190s)
[434.338046] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB8888
[434.346894] Dynamic subtest pipe-A-eDP-1-x-XRGB8888: SUCCESS (0.018s)
[434.357125] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB2101010
[434.369043] Dynamic subtest pipe-A-eDP-1-x-XRGB2101010: SUCCESS (0.022s)
[434.377919] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB16161616F
[434.391427] Dynamic subtest pipe-A-eDP-1-x-XRGB16161616F: SUCCESS (0.022s)
[434.400458] Starting dynamic subtest: pipe-A-eDP-1-x-YUYV
[434.413303] Dynamic subtest pipe-A-eDP-1-x-YUYV: SUCCESS (0.021s)
[434.424258] Starting dynamic subtest: pipe-A-eDP-1-x-Y210
[434.435443] Dynamic subtest pipe-A-eDP-1-x-Y210: SUCCESS (0.021s)
[434.446789] Starting dynamic subtest: pipe-A-eDP-1-x-XYUV8888
[434.457610] Dynamic subtest pipe-A-eDP-1-x-XYUV8888: SUCCESS (0.021s)
[434.465689] Starting dynamic subtest: pipe-A-eDP-1-x-XV30
[434.479915] Dynamic subtest pipe-A-eDP-1-x-XV30: SUCCESS (0.022s)
[434.487866] Starting dynamic subtest: pipe-A-eDP-1-x-XV36
[434.501844] Dynamic subtest pipe-A-eDP-1-x-XV36: SUCCESS (0.021s)
[434.509937] Starting dynamic subtest: pipe-A-eDP-1-linear-RGB565
[434.668633] Dynamic subtest pipe-A-eDP-1-linear-RGB565: SUCCESS (0.166s)
[434.677867] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB8888
[434.691074] Dynamic subtest pipe-A-eDP-1-linear-XRGB8888: SUCCESS (0.022s)
[434.699533] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB2101010
[434.713470] Dynamic subtest pipe-A-eDP-1-linear-XRGB2101010: SUCCESS (0.021s)
[434.721711] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB16161616F
[434.735332] Dynamic subtest pipe-A-eDP-1-linear-XRGB16161616F: SUCCESS (0.021s)
[434.743674] Starting dynamic subtest: pipe-A-eDP-1-linear-YUYV
[434.757454] Dynamic subtest pipe-A-eDP-1-linear-YUYV: SUCCESS (0.021s)
[434.765984] Starting dynamic subtest: pipe-A-eDP-1-linear-Y210
[434.779610] Dynamic subtest pipe-A-eDP-1-linear-Y210: SUCCESS (0.022s)
[434.787932] Starting dynamic subtest: pipe-A-eDP-1-linear-XYUV8888
[434.801555] Dynamic subtest pipe-A-eDP-1-linear-XYUV8888: SUCCESS (0.022s)
[434.809419] Starting dynamic subtest: pipe-A-eDP-1-linear-XV30
[434.824264] Dynamic subtest pipe-A-eDP-1-linear-XV30: SUCCESS (0.022s)
[434.831733] Starting dynamic subtest: pipe-A-eDP-1-linear-XV36
[434.846080] Dynamic subtest pipe-A-eDP-1-linear-XV36: SUCCESS (0.021s)
[435.229806] Subtest basic-modeset-with-all-modifiers-formats: SUCCESS (2.635s)
[436.007952] [082/133] (585s left) kms_addfb_basic (invalid-get-prop)
[436.069518] Starting subtest: invalid-get-prop
[436.071485] Subtest invalid-get-prop: SUCCESS (0.000s)
[436.195609] [083/133] (585s left) kms_cursor_crc (cursor-sliding-256x85)
[436.463589] Starting subtest: cursor-sliding-256x85
[436.508628] Subtest cursor-sliding-256x85: SKIP (0.040s)
[436.676387] [084/133] (585s left) perf (short-reads)
[436.761739] Starting subtest: short-reads
[436.773949] Subtest short-reads: SUCCESS (0.013s)
[436.815551] [085/133] (584s left) kms_cursor_crc (cursor-onscreen-128x42)
[437.045282] Starting subtest: cursor-onscreen-128x42
[437.090069] Subtest cursor-onscreen-128x42: SKIP (0.041s)
[437.315283] [086/133] (584s left) gem_exec_schedule (u-semaphore-noskip)
[437.380648] Starting subtest: u-semaphore-noskip
[437.788650] Subtest u-semaphore-noskip: SUCCESS (0.415s)
[438.136605] [087/133] (583s left) kms_ccs (crc-primary-basic-4-tiled-lnl-ccs)
[438.238541] Starting subtest: crc-primary-basic-4-tiled-lnl-ccs
[438.243469] Subtest crc-primary-basic-4-tiled-lnl-ccs: SKIP (0.000s)
[439.349627] [088/133] (582s left) gem_exec_reloc (basic-write-cpu-noreloc)
[439.404936] Subtest basic-write-cpu-noreloc: SKIP (0.000s)
[439.482582] [089/133] (582s left) gem_pxp (protected-encrypted-src-copy-not-readible)
[439.606636] Starting subtest: protected-encrypted-src-copy-not-readible
[439.716791] Subtest protected-encrypted-src-copy-not-readible: SUCCESS (0.110s)
[439.836937] [090/133] (582s left) gem_exec_schedule (out-order)
[439.903710] Starting subtest: out-order
[439.905805] Starting dynamic subtest: rcs0
[439.995463] Dynamic subtest rcs0: SUCCESS (0.101s)
[440.008201] Starting dynamic subtest: vcs0
[440.082722] Dynamic subtest vcs0: SUCCESS (0.087s)
[440.094693] Starting dynamic subtest: vcs1
[440.163558] Dynamic subtest vcs1: SUCCESS (0.080s)
[440.175865] Starting dynamic subtest: vecs0
[440.249689] Dynamic subtest vecs0: SUCCESS (0.085s)
[440.260867] Starting dynamic subtest: ccs0
[440.341044] Dynamic subtest ccs0: SUCCESS (0.091s)
[440.343925] Subtest out-order: SUCCESS (0.448s)
[441.448631] [091/133] (580s left) kms_fbcon_fbt (psr)
[441.513900] Starting subtest: psr
[448.858334] Subtest psr: SUCCESS (7.348s)
[448.936600] [092/133] (573s left) kms_joiner (basic-force-big-joiner)
[449.029701] Starting subtest: basic-force-big-joiner
[449.034176] Subtest basic-force-big-joiner: SKIP (0.000s)
[449.096700] [093/133] (573s left) gem_exec_params (larger-than-life-batch)
[449.156752] Starting subtest: larger-than-life-batch
[451.378332] Subtest larger-than-life-batch: SUCCESS (2.222s)
[452.441173] [094/133] (569s left) syncobj_timeline (invalid-query-one-illegal-handle)
[452.555846] Starting subtest: invalid-query-one-illegal-handle
[452.558367] Subtest invalid-query-one-illegal-handle: SUCCESS (0.000s)
[452.629813] [095/133] (569s left) syncobj_basic (bad-flags-fd-to-handle)
[452.696971] Starting subtest: bad-flags-fd-to-handle
[452.699868] Subtest bad-flags-fd-to-handle: SUCCESS (0.000s)
[452.810899] [096/133] (569s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[452.923310] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip
[453.913687] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip: SUCCESS (0.991s)
[454.039329] [097/133] (568s left) kms_psr (fbc-pr-sprite-plane-onoff)
[454.128850] Starting subtest: fbc-pr-sprite-plane-onoff
[454.133855] Subtest fbc-pr-sprite-plane-onoff: SKIP (0.000s)
[454.203363] [098/133] (568s left) core_getversion (basic)
[454.249246] Starting subtest: basic
[454.253497] Subtest basic: SUCCESS (0.000s)
[454.311467] [099/133] (568s left) kms_frontbuffer_tracking (fbcpsr-tiling-linear)
[454.403526] Starting subtest: fbcpsr-tiling-linear
[460.944958] Subtest fbcpsr-tiling-linear: SUCCESS (6.544s)
[461.070544] [100/133] (561s left) kms_flip (2x-flip-vs-suspend)
[461.158439] Starting subtest: 2x-flip-vs-suspend
[461.169735] Subtest 2x-flip-vs-suspend: SKIP (0.011s)
[461.259469] [101/133] (561s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping)
[461.357043] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping
[461.382788] Starting dynamic subtest: pipe-A-plane-0
[461.670970] Dynamic subtest pipe-A-plane-0: SUCCESS (0.288s)
[461.681983] Starting dynamic subtest: pipe-A-plane-3
[461.904048] Dynamic subtest pipe-A-plane-3: SUCCESS (0.232s)
[462.976839] Starting dynamic subtest: pipe-B-plane-0
[463.280463] Dynamic subtest pipe-B-plane-0: SUCCESS (0.311s)
[463.291889] Starting dynamic subtest: pipe-B-plane-3
[463.526375] Dynamic subtest pipe-B-plane-3: SUCCESS (0.244s)
[463.832640] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping: SUCCESS (2.478s)
[464.660454] [102/133] (557s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[464.732340] Starting subtest: yf-tiled-to-vebox-yf-tiled
[464.737044] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[464.923953] [103/133] (557s left) kms_psr (psr2-cursor-plane-onoff)
[465.022944] Starting subtest: psr2-cursor-plane-onoff
[465.025925] Starting dynamic subtest: eDP-1
[466.733976] Dynamic subtest eDP-1: SUCCESS (1.712s)
[466.736910] Subtest psr2-cursor-plane-onoff: SUCCESS (1.713s)
[467.520824] [104/133] (555s left) gem_exec_reloc (basic-gtt)
[467.589527] Subtest basic-gtt: SKIP (0.000s)
[467.663693] [105/133] (555s left) gem_partial_pwrite_pread (writes-after-reads-snoop)
[467.728404] Subtest writes-after-reads-snoop: SKIP (0.000s)
[467.803764] [106/133] (554s left) syncobj_wait (wait-all-snapshot)
[467.857948] Starting subtest: wait-all-snapshot
[467.938929] Subtest wait-all-snapshot: SUCCESS (0.082s)
[468.024794] [107/133] (554s left) prime_vgem (wait)
[468.102640] Starting subtest: wait
[468.105319] Starting dynamic subtest: rcs0
[468.172324] Dynamic subtest rcs0: SUCCESS (0.073s)
[468.182240] Starting dynamic subtest: bcs0
[468.238935] Dynamic subtest bcs0: SUCCESS (0.066s)
[468.250800] Starting dynamic subtest: vcs0
[468.283964] Dynamic subtest vcs0: SUCCESS (0.042s)
[468.294076] Starting dynamic subtest: vcs1
[468.331613] Dynamic subtest vcs1: SUCCESS (0.047s)
[468.340808] Starting dynamic subtest: vecs0
[468.378690] Dynamic subtest vecs0: SUCCESS (0.046s)
[468.387873] Starting dynamic subtest: ccs0
[468.425678] Dynamic subtest ccs0: SUCCESS (0.046s)
[468.428315] Subtest wait: SUCCESS (0.327s)
[468.557424] [108/133] (554s left) kms_vblank (query-idle)
[468.642582] Starting subtest: query-idle
[468.644554] Starting dynamic subtest: pipe-A-eDP-1
[470.383991] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.742s)
[470.398282] Starting dynamic subtest: pipe-D-eDP-1
[472.842065] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.453s)
[472.844953] Subtest query-idle: SUCCESS (4.199s)
[473.678069] [109/133] (549s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-render)
[473.766767] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-render
[477.885603] Subtest fbc-1p-offscreen-pri-indfb-draw-render: SUCCESS (4.124s)
[479.069552] [110/133] (543s left) syncobj_basic (bad-handle-to-fd)
[479.120649] Starting subtest: bad-handle-to-fd
[479.122832] Subtest bad-handle-to-fd: SUCCESS (0.000s)
[479.216051] [111/133] (543s left) gem_mmap_offset (basic-uaf)
[479.269203] Starting subtest: basic-uaf
[479.271829] Subtest basic-uaf: SUCCESS (0.001s)
[479.372656] [112/133] (543s left) gem_eio (suspend)
[479.609501] Starting subtest: suspend
[491.861557] Subtest suspend: SUCCESS (12.253s)
[491.969630] [113/133] (531s left) api_intel_bb (intel-bb-blit-x)
[492.035065] Starting subtest: intel-bb-blit-x
[495.451193] Subtest intel-bb-blit-x: SUCCESS (3.417s)
[495.571352] [114/133] (527s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-msflip-blt)
[495.675904] Starting subtest: fbc-2p-scndscrn-shrfb-msflip-blt
[495.680900] Subtest fbc-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[495.782380] [115/133] (527s left) gem_fenced_exec_thrash (too-many-fences)
[495.836881] Subtest too-many-fences: SKIP (0.000s)
[495.906666] [116/133] (527s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-blt)
[496.000999] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-blt
[499.706901] Subtest fbc-1p-primscrn-spr-indfb-draw-blt: SUCCESS (3.708s)
[500.870130] [117/133] (522s left) kms_flip (2x-flip-vs-blocking-wf-vblank)
[500.970807] Starting subtest: 2x-flip-vs-blocking-wf-vblank
[500.978728] Subtest 2x-flip-vs-blocking-wf-vblank: SKIP (0.010s)
[501.057209] [118/133] (522s left) gem_wait (write-wait)
[501.142658] Starting subtest: write-wait
[501.145303] Starting dynamic subtest: all-engines
[502.231275] Dynamic subtest all-engines: SUCCESS (1.088s)
[502.248409] Starting dynamic subtest: rcs0
[503.292842] Dynamic subtest rcs0: SUCCESS (1.059s)
[503.309626] Starting dynamic subtest: bcs0
[504.354553] Dynamic subtest bcs0: SUCCESS (1.059s)
[504.371638] Starting dynamic subtest: vcs0
[505.414609] Dynamic subtest vcs0: SUCCESS (1.057s)
[505.431703] Starting dynamic subtest: vcs1
[506.472023] Dynamic subtest vcs1: SUCCESS (1.055s)
[506.488874] Starting dynamic subtest: vecs0
[507.529464] Dynamic subtest vecs0: SUCCESS (1.055s)
[507.546083] Starting dynamic subtest: ccs0
[508.592342] Dynamic subtest ccs0: SUCCESS (1.060s)
[508.595076] Subtest write-wait: SUCCESS (7.449s)
[508.794705] [119/133] (514s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-sf-dmg-area)
[508.898491] Starting subtest: fbc-psr2-overlay-plane-update-sf-dmg-area
[508.900905] Starting dynamic subtest: pipe-A-eDP-1
[508.992708] Dynamic subtest pipe-A-eDP-1: SKIP (0.099s)
[509.002983] Starting dynamic subtest: pipe-B-eDP-1
[510.149048] Dynamic subtest pipe-B-eDP-1: SKIP (1.153s)
[510.154408] Subtest fbc-psr2-overlay-plane-update-sf-dmg-area: SKIP (1.255s)
[511.341207] [120/133] (511s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[511.444312] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[511.446928] Starting dynamic subtest: pipe-A-eDP-1
[512.249348] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.794s)
[512.267776] Starting dynamic subtest: pipe-B-eDP-1
[513.903320] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.654s)
[513.913061] Starting dynamic subtest: pipe-C-eDP-1
[515.401980] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.497s)
[515.430330] Starting dynamic subtest: pipe-D-eDP-1
[516.920595] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.515s)
[516.923065] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.468s)
[518.085748] [121/133] (505s left) gem_mmap_gtt (basic-read)
[518.141893] Subtest basic-read: SKIP (0.000s)
[518.201366] [122/133] (505s left) kms_cdclk (mode-transition-all-outputs)
[518.293259] Starting subtest: mode-transition-all-outputs
[518.585217] Subtest mode-transition-all-outputs: SKIP (0.290s)
[519.412596] [123/133] (504s left) gem_exec_nop (basic-parallel)
[519.480338] Starting subtest: basic-parallel
[535.305700] Subtest basic-parallel: SUCCESS (15.833s)
[535.556602] [124/133] (487s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[535.688643] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[535.693826] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[535.802426] [125/133] (487s left) syncobj_timeline (wait-all-for-submit-snapshot)
[535.859855] Starting subtest: wait-all-for-submit-snapshot
[535.940523] Subtest wait-all-for-submit-snapshot: SUCCESS (0.082s)
[536.019560] [126/133] (487s left) gem_exec_balancer (full-late)
[536.082314] Starting subtest: full-late
[541.176418] Subtest full-late: SUCCESS (5.092s)
[541.499348] [127/133] (482s left) kms_flip (flip-vs-dpms-off-vs-modeset)
[541.606134] Starting subtest: flip-vs-dpms-off-vs-modeset
[541.608825] Starting dynamic subtest: A-eDP1
[545.235349] Dynamic subtest A-eDP1: SUCCESS (3.631s)
[545.245640] Starting dynamic subtest: B-eDP1
[548.421443] Dynamic subtest B-eDP1: SUCCESS (3.184s)
[548.432971] Starting dynamic subtest: C-eDP1
[551.543712] Dynamic subtest C-eDP1: SUCCESS (3.117s)
[551.553784] Starting dynamic subtest: D-eDP1
[554.635176] Dynamic subtest D-eDP1: SUCCESS (3.091s)
[554.637958] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (13.031s)
[555.437607] [128/133] (468s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-blt)
[555.535937] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-blt
[562.820273] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.286s)
[562.947920] [129/133] (460s left) kms_psr_stress_test (flip-primary-invalidate-overlay)
[563.033351] Starting subtest: flip-primary-invalidate-overlay
[574.740490] Subtest flip-primary-invalidate-overlay: SUCCESS (11.705s)
[574.888793] [130/133] (448s left) kms_psr (psr2-cursor-blt)
[574.987444] Starting subtest: psr2-cursor-blt
[574.989693] Starting dynamic subtest: eDP-1
[576.744691] Dynamic subtest eDP-1: SUCCESS (1.756s)
[576.746861] Subtest psr2-cursor-blt: SUCCESS (1.757s)
[577.538330] [131/133] (446s left) gem_ctx_exec (basic-invalid-context)
[577.605342] Starting subtest: basic-invalid-context
[577.610922] Subtest basic-invalid-context: SUCCESS (0.006s)
[577.731827] [132/133] (446s left) kms_rotation_crc (primary-y-tiled-reflect-x-180)
[577.820343] Starting subtest: primary-y-tiled-reflect-x-180
[577.880942] Subtest primary-y-tiled-reflect-x-180: SKIP (0.060s)
[577.986328] [133/133] (445s left) gem_eio (wait-10ms)
[578.213625] Starting subtest: wait-10ms
[578.581246] Subtest wait-10ms: SUCCESS (0.368s)
[578.721992] Closing watchdogs
results: parsing output: 0/ for test: perf
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: kms_properties
results: parsing output: 3/ for test: gem_fenced_exec_thrash
results: parsing output: 4/ for test: api_intel_allocator
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_joiner
results: parsing output: 7/ for test: kms_chamelium_hpd
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: i915_drm_fdinfo
results: parsing output: 10/ for test: i915_pm_freq_api
results: parsing output: 11/ for test: kms_flip_scaled_crc
results: parsing output: 12/ for test: gem_pwrite
results: parsing output: 13/ for test: kms_atomic_transition
results: parsing output: 14/ for test: syncobj_timeline
results: parsing output: 15/ for test: kms_content_protection
results: parsing output: 16/ for test: kms_vrr
results: parsing output: 17/ for test: i915_pm_rpm
results: parsing output: 18/ for test: gem_exec_whisper
results: parsing output: 19/ for test: gem_exec_params
results: parsing output: 20/ for test: kms_plane_scaling
results: parsing output: 21/ for test: kms_cursor_legacy
results: parsing output: 22/ for test: gem_fence_thrash
results: parsing output: 23/ for test: kms_big_fb
results: parsing output: 24/ for test: kms_plane
results: parsing output: 25/ for test: syncobj_timeline
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: kms_cursor_legacy
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: kms_plane_lowres
results: parsing output: 30/ for test: syncobj_timeline
results: parsing output: 31/ for test: kms_big_fb
results: parsing output: 32/ for test: kms_big_fb
results: parsing output: 33/ for test: gem_mmap_gtt
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: kms_ccs
results: parsing output: 36/ for test: gem_bad_reloc
results: parsing output: 37/ for test: kms_busy
results: parsing output: 38/ for test: kms_pm_dc
results: parsing output: 39/ for test: perf_pmu
results: parsing output: 40/ for test: gem_exec_fence
results: parsing output: 41/ for test: gem_softpin
results: parsing output: 42/ for test: kms_ccs
results: parsing output: 43/ for test: kms_multipipe_modeset
results: parsing output: 44/ for test: gem_ctx_isolation
results: parsing output: 45/ for test: kms_big_fb
results: parsing output: 46/ for test: kms_chamelium_frames
results: parsing output: 47/ for test: gem_tiled_swapping
results: parsing output: 48/ for test: gem_ctx_shared
results: parsing output: 49/ for test: i915_suspend
results: parsing output: 50/ for test: gem_render_copy
results: parsing output: 51/ for test: kms_flip
results: parsing output: 52/ for test: gem_exec_fence
results: parsing output: 53/ for test: gem_ctx_persistence
results: parsing output: 54/ for test: kms_cursor_legacy
results: parsing output: 55/ for test: kms_cursor_crc
results: parsing output: 56/ for test: kms_addfb_basic
results: parsing output: 57/ for test: syncobj_wait
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: kms_async_flips
results: parsing output: 60/ for test: kms_pm_rpm
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: kms_psr2_sf
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: kms_selftest
results: parsing output: 65/ for test: kms_psr
results: parsing output: 66/ for test: kms_hdr
results: parsing output: 67/ for test: kms_color
results: parsing output: 68/ for test: gem_mmap_gtt
results: parsing output: 69/ for test: kms_dsc
results: parsing output: 70/ for test: kms_flip
results: parsing output: 71/ for test: gen9_exec_parse
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: gem_huc_copy
results: parsing output: 74/ for test: kms_psr
results: parsing output: 75/ for test: kms_big_fb
results: parsing output: 76/ for test: gem_exec_reloc
results: parsing output: 77/ for test: gem_set_tiling_vs_blt
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: gem_madvise
results: parsing output: 80/ for test: kms_async_flips
results: parsing output: 81/ for test: kms_addfb_basic
results: parsing output: 82/ for test: kms_cursor_crc
results: parsing output: 83/ for test: perf
results: parsing output: 84/ for test: kms_cursor_crc
results: parsing output: 85/ for test: gem_exec_schedule
results: parsing output: 86/ for test: kms_ccs
results: parsing output: 87/ for test: gem_exec_reloc
results: parsing output: 88/ for test: gem_pxp
results: parsing output: 89/ for test: gem_exec_schedule
results: parsing output: 90/ for test: kms_fbcon_fbt
results: parsing output: 91/ for test: kms_joiner
results: parsing output: 92/ for test: gem_exec_params
results: parsing output: 93/ for test: syncobj_timeline
results: parsing output: 94/ for test: syncobj_basic
results: parsing output: 95/ for test: kms_big_fb
results: parsing output: 96/ for test: kms_psr
results: parsing output: 97/ for test: core_getversion
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: kms_flip
results: parsing output: 100/ for test: kms_plane
results: parsing output: 101/ for test: gem_render_copy
results: parsing output: 102/ for test: kms_psr
results: parsing output: 103/ for test: gem_exec_reloc
results: parsing output: 104/ for test: gem_partial_pwrite_pread
results: parsing output: 105/ for test: syncobj_wait
results: parsing output: 106/ for test: prime_vgem
results: parsing output: 107/ for test: kms_vblank
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: syncobj_basic
results: parsing output: 110/ for test: gem_mmap_offset
results: parsing output: 111/ for test: gem_eio
results: parsing output: 112/ for test: api_intel_bb
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: gem_fenced_exec_thrash
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_flip
results: parsing output: 117/ for test: gem_wait
results: parsing output: 118/ for test: kms_psr2_sf
results: parsing output: 119/ for test: kms_ccs
results: parsing output: 120/ for test: gem_mmap_gtt
results: parsing output: 121/ for test: kms_cdclk
results: parsing output: 122/ for test: gem_exec_nop
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: syncobj_timeline
results: parsing output: 125/ for test: gem_exec_balancer
results: parsing output: 126/ for test: kms_flip
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_psr_stress_test
results: parsing output: 129/ for test: kms_psr
results: parsing output: 130/ for test: gem_ctx_exec
results: parsing output: 131/ for test: kms_rotation_crc
results: parsing output: 132/ for test: gem_eio
Done.