[56.260238] Initializing watchdogs
[56.260295]   /dev/watchdog0
[56.274483] [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.279378] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.281815] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.285384] [001/133] (960s left) perf (disabled-read-error)
[56.451345] Starting subtest: disabled-read-error
[56.464727] Subtest disabled-read-error: SUCCESS (0.014s)
[56.503204] [002/133] (960s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-move)
[56.607201] Starting subtest: psr-2p-scndscrn-cur-indfb-move
[56.611305] Subtest psr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[56.719742] [003/133] (960s left) kms_properties (get_properties-sanity-atomic)
[56.815603] Starting subtest: get_properties-sanity-atomic
[56.817952] Subtest get_properties-sanity-atomic: SUCCESS (0.002s)
[56.885180] [004/133] (960s left) gem_fenced_exec_thrash (2-spare-fences)
[56.946955] Subtest 2-spare-fences: SKIP (0.000s)
[57.017562] [005/133] (959s left) api_intel_allocator (gem-pool)
[57.071916] Starting subtest: gem-pool
[57.077829] Subtest gem-pool: SUCCESS (0.009s)
[57.216148] [006/133] (959s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-msflip-blt)
[57.319098] Starting subtest: fbc-2p-primscrn-indfb-msflip-blt
[57.322605] Subtest fbc-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[57.400811] [007/133] (959s left) kms_joiner (switch-modeset-ultra-joiner-big-joiner)
[57.492180] Starting subtest: switch-modeset-ultra-joiner-big-joiner
[57.495910] Subtest switch-modeset-ultra-joiner-big-joiner: SKIP (0.000s)
[57.555611] [008/133] (959s left) kms_chamelium_hpd (vga-hpd-after-suspend)
[57.954283] Subtest vga-hpd-after-suspend: SKIP (0.000s)
[58.743523] [009/133] (958s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[58.841550] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu
[58.846093] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[58.938479] [010/133] (958s left) i915_drm_fdinfo (busy-check-all)
[58.982646] Starting subtest: busy-check-all
[58.984943] Starting dynamic subtest: rcs0
[58.989626] Dynamic subtest rcs0: SKIP (0.003s)
[58.997939] Starting dynamic subtest: bcs0
[59.002427] Dynamic subtest bcs0: SKIP (0.000s)
[59.008812] Starting dynamic subtest: vcs0
[59.013256] Dynamic subtest vcs0: SKIP (0.000s)
[59.019787] Starting dynamic subtest: vcs1
[59.024061] Dynamic subtest vcs1: SKIP (0.000s)
[59.030545] Starting dynamic subtest: vecs0
[59.034708] Dynamic subtest vecs0: SKIP (0.000s)
[59.041208] Starting dynamic subtest: ccs0
[59.045448] Dynamic subtest ccs0: SKIP (0.000s)
[59.049737] Subtest busy-check-all: SKIP (0.006s)
[59.085826] [011/133] (958s left) i915_pm_freq_api (freq-suspend)
[59.165238] Starting subtest: freq-suspend
[59.167616] Starting dynamic subtest: gt0
[75.422556] Dynamic subtest gt0: SUCCESS (2.161s)
[75.433670] Starting dynamic subtest: gt1
[91.418357] Dynamic subtest gt1: SUCCESS (1.238s)
[91.427484] Subtest freq-suspend: SUCCESS (3.401s)
[91.499743] [012/133] (925s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-downscaling)
[91.597519] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-downscaling
[91.599888] Starting dynamic subtest: pipe-A-default-mode
[92.692732] Dynamic subtest pipe-A-default-mode: SKIP (1.132s)
[92.696699] Subtest flip-64bpp-4tile-to-16bpp-4tile-downscaling: SKIP (1.134s)
[92.819243] [013/133] (924s left) gem_pwrite (basic-random)
[92.875162] Subtest basic-random: SKIP (0.000s)
[92.943299] [014/133] (924s left) kms_atomic_transition (plane-all-modeset-transition)
[93.031746] Starting subtest: plane-all-modeset-transition
[93.036048] Subtest plane-all-modeset-transition: SKIP (0.000s)
[93.096620] [015/133] (924s left) syncobj_timeline (etime-multi-wait-submitted)
[93.151647] Starting subtest: etime-multi-wait-submitted
[93.259845] Subtest etime-multi-wait-submitted: SUCCESS (0.110s)
[93.334140] [016/133] (924s left) kms_content_protection (lic-type-0-hdcp14)
[93.478617] Starting subtest: lic-type-0-hdcp14
[93.482871] Subtest lic-type-0-hdcp14: SKIP (0.000s)
[93.592888] [017/133] (923s left) kms_vrr (max-min)
[93.676182] Starting subtest: max-min
[93.682267] Subtest max-min: SKIP (0.000s)
[93.743493] [018/133] (923s left) i915_pm_rpm (system-suspend-execbuf)
[95.905459] Starting subtest: system-suspend-execbuf
[154.463395] Subtest system-suspend-execbuf: SUCCESS (43.476s)
[154.848598] [019/133] (862s left) gem_exec_whisper (basic-normal-all)
[154.920170] Starting subtest: basic-normal-all
[157.188353] Subtest basic-normal-all: SUCCESS (2.269s)
[157.389357] [020/133] (860s left) gem_exec_params (dr1-dirt)
[157.453099] Starting subtest: DR1-dirt
[157.456233] Subtest DR1-dirt: SUCCESS (0.000s)
[157.559495] [021/133] (860s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-5)
[157.661131] Starting subtest: planes-unity-scaling-downscale-factor-0-5
[157.663870] Starting dynamic subtest: pipe-A
[157.683015] Dynamic subtest pipe-A: SKIP (0.017s)
[157.691171] Starting dynamic subtest: pipe-B
[157.717622] Dynamic subtest pipe-B: SKIP (0.035s)
[157.726370] Starting dynamic subtest: pipe-C
[157.751927] Dynamic subtest pipe-C: SKIP (0.035s)
[157.759798] Starting dynamic subtest: pipe-D
[157.788612] Dynamic subtest pipe-D: SKIP (0.036s)
[157.792587] Subtest planes-unity-scaling-downscale-factor-0-5: SKIP (0.124s)
[157.879572] [022/133] (859s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions-varying-size)
[158.019297] Starting subtest: cursorB-vs-flipA-atomic-transitions-varying-size
[158.024141] Subtest cursorB-vs-flipA-atomic-transitions-varying-size: SKIP (0.001s)
[158.175460] [023/133] (859s left) gem_fence_thrash (bo-write-verify-y)
[158.217818] Starting subtest: bo-write-verify-y
[158.236584] Subtest bo-write-verify-y: SKIP (0.015s)
[158.297667] [024/133] (859s left) kms_big_fb (x-tiled-16bpp-rotate-90)
[158.407212] Starting subtest: x-tiled-16bpp-rotate-90
[158.428299] Subtest x-tiled-16bpp-rotate-90: SKIP (0.020s)
[158.538844] [025/133] (859s left) kms_plane (pixel-format-y-tiled-modifier)
[158.635568] Starting subtest: pixel-format-y-tiled-modifier
[158.639769] Subtest pixel-format-y-tiled-modifier: SKIP (0.000s)
[158.707296] [026/133] (859s left) syncobj_timeline (device-signal-unordered)
[158.758135] Starting subtest: device-signal-unordered
[158.760765] Subtest device-signal-unordered: SUCCESS (0.000s)
[158.856036] [027/133] (858s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[158.953914] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[158.957968] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[159.041810] [028/133] (858s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-legacy)
[159.166096] Starting subtest: flip-vs-cursor-busy-crc-legacy
[159.997330] Subtest flip-vs-cursor-busy-crc-legacy: SUCCESS (0.831s)
[160.869175] [029/133] (857s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-pwrite)
[160.966118] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-pwrite
[160.971253] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[161.059824] [030/133] (856s left) kms_plane_lowres (tiling-x)
[161.158217] Starting subtest: tiling-x
[161.160768] Starting dynamic subtest: pipe-A-eDP-1
[161.165785] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[161.456929] Starting dynamic subtest: pipe-B-eDP-1
[161.462383] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[161.470508] Starting dynamic subtest: pipe-C-eDP-1
[161.476298] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[161.484506] Starting dynamic subtest: pipe-D-eDP-1
[161.490174] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[161.495883] Subtest tiling-x: SKIP (0.298s)
[162.302165] [031/133] (855s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-submitted-signaled)
[162.368162] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[162.370917] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[162.461320] [032/133] (855s left) kms_big_fb (y-tiled-64bpp-rotate-270)
[162.562630] Starting subtest: y-tiled-64bpp-rotate-270
[162.567944] Subtest y-tiled-64bpp-rotate-270: SKIP (0.000s)
[162.655172] [033/133] (855s left) kms_big_fb (yf-tiled-16bpp-rotate-270)
[162.748701] Starting subtest: yf-tiled-16bpp-rotate-270
[162.753832] Subtest yf-tiled-16bpp-rotate-270: SKIP (0.000s)
[162.837521] [034/133] (855s left) gem_mmap_gtt (fault-concurrent-x)
[162.902273] Subtest fault-concurrent-X: SKIP (0.000s)
[162.953983] [035/133] (855s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite)
[163.063759] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite
[163.068124] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[163.147780] [036/133] (855s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[163.239860] Starting subtest: bad-pixel-format-y-tiled-ccs
[163.242192] Starting dynamic subtest: pipe-A-eDP-1
[163.265115] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[163.274805] Starting dynamic subtest: pipe-B-eDP-1
[163.296501] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[163.303968] Starting dynamic subtest: pipe-C-eDP-1
[163.326728] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[163.334003] Starting dynamic subtest: pipe-D-eDP-1
[163.356549] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[163.361110] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.003s)
[165.470445] [037/133] (852s left) gem_bad_reloc (negative-reloc-lut)
[165.544227] Subtest negative-reloc-lut: SKIP (0.000s)
[165.611220] [038/133] (852s left) kms_busy (extended-modeset-hang-oldfb)
[165.710080] Starting subtest: extended-modeset-hang-oldfb
[165.712821] Starting dynamic subtest: pipe-A
[177.354879] Dynamic subtest pipe-A: SUCCESS (11.643s)
[177.363509] Starting dynamic subtest: pipe-D
[189.647635] Dynamic subtest pipe-D: SUCCESS (12.292s)
[189.650361] Subtest extended-modeset-hang-oldfb: SUCCESS (23.940s)
[190.539534] [039/133] (827s left) kms_pm_dc (dc6-psr)
[191.636976] Starting subtest: dc6-psr
[194.537036] Subtest dc6-psr: SUCCESS (2.900s)
[195.358552] [040/133] (822s left) perf_pmu (pmu-read)
[195.402563] Starting subtest: pmu-read
[195.404827] Subtest pmu-read: SUCCESS (0.003s)
[195.498207] [041/133] (822s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[196.073163] Starting subtest: syncobj-timeline-invalid-flags
[196.075929] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.000s)
[197.264804] [FACT gem_exec_fence (syncobj-timeline-invalid-flags)] new: kernel.kmod_is_loaded.vgem: true
[197.268070] [042/133] (821s left) gem_softpin (allocator-nopin)
[197.335224] Starting subtest: allocator-nopin
[197.419769] Subtest allocator-nopin: SUCCESS (0.087s)
[197.551872] [043/133] (820s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs)
[197.650200] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs
[197.652945] Starting dynamic subtest: pipe-A-eDP-1
[197.721776] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[197.731746] Starting dynamic subtest: pipe-B-eDP-1
[197.797793] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[197.808295] Starting dynamic subtest: pipe-C-eDP-1
[197.875021] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[197.884188] Starting dynamic subtest: pipe-D-eDP-1
[197.950146] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[197.955395] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs: SKIP (0.007s)
[199.932081] [044/133] (818s left) kms_multipipe_modeset (basic-max-pipe-crc-check)
[200.020917] Starting subtest: basic-max-pipe-crc-check
[200.026123] Subtest basic-max-pipe-crc-check: SKIP (0.000s)
[200.071189] [045/133] (818s left) gem_ctx_isolation (preservation-s3)
[200.150406] Starting subtest: preservation-S3
[200.153140] Starting dynamic subtest: rcs0
[216.471242] Dynamic subtest rcs0: SUCCESS (1.324s)
[216.482743] Starting dynamic subtest: bcs0
[232.454661] Dynamic subtest bcs0: SUCCESS (1.315s)
[232.465960] Starting dynamic subtest: vcs0
[248.466814] Dynamic subtest vcs0: SUCCESS (2.523s)
[248.476726] Starting dynamic subtest: vcs1
[266.447260] Dynamic subtest vcs1: SUCCESS (5.117s)
[266.456388] Starting dynamic subtest: vecs0
[284.441230] Dynamic subtest vecs0: SUCCESS (5.226s)
[284.450056] Starting dynamic subtest: ccs0
[302.436872] Dynamic subtest ccs0: SUCCESS (3.185s)
[302.439243] Subtest preservation-S3: SUCCESS (18.695s)
[302.660078] [046/133] (715s left) kms_big_fb (yf-tiled-64bpp-rotate-0)
[302.771954] Starting subtest: yf-tiled-64bpp-rotate-0
[302.776099] Subtest yf-tiled-64bpp-rotate-0: SKIP (0.000s)
[302.853346] [047/133] (715s left) kms_chamelium_frames (dp-crc-single)
[303.245015] Subtest dp-crc-single: SKIP (0.000s)
[304.014515] [048/133] (714s left) gem_tiled_swapping (non-threaded)
[304.066263] Subtest non-threaded: SKIP (0.000s)
[304.132468] [049/133] (714s left) gem_ctx_shared (disjoint-timelines)
[304.181569] Starting subtest: disjoint-timelines
[304.200157] Subtest disjoint-timelines: SUCCESS (0.019s)
[304.424380] [050/133] (714s left) i915_suspend (forcewake)
[304.485691] Starting subtest: forcewake
[336.416096] Subtest forcewake: SUCCESS (2.257s)
[336.493097] [051/133] (682s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[336.558865] Starting subtest: y-tiled-ccs-to-y-tiled
[336.563440] Subtest y-tiled-ccs-to-y-tiled: SKIP (0.000s)
[336.752082] [052/133] (682s left) kms_flip (2x-nonexisting-fb)
[336.846600] Starting subtest: 2x-nonexisting-fb
[336.855032] Subtest 2x-nonexisting-fb: SKIP (0.011s)
[336.929637] [053/133] (681s left) gem_exec_fence (basic-await)
[337.109360] Starting subtest: basic-await
[337.111643] Starting dynamic subtest: rcs0
[337.187090] Dynamic subtest rcs0: SUCCESS (0.080s)
[337.200142] Starting dynamic subtest: bcs0
[337.263850] Dynamic subtest bcs0: SUCCESS (0.079s)
[337.274392] Starting dynamic subtest: vcs0
[337.336212] Dynamic subtest vcs0: SUCCESS (0.075s)
[337.347018] Starting dynamic subtest: vcs1
[337.409168] Dynamic subtest vcs1: SUCCESS (0.074s)
[337.425683] Starting dynamic subtest: vecs0
[337.481429] Dynamic subtest vecs0: SUCCESS (0.073s)
[337.492372] Starting dynamic subtest: ccs0
[337.553087] Dynamic subtest ccs0: SUCCESS (0.073s)
[337.555495] Subtest basic-await: SUCCESS (0.462s)
[339.032749] [054/133] (679s left) gem_ctx_persistence (legacy-engines-queued)
[339.086860] Starting subtest: legacy-engines-queued
[339.089108] Starting dynamic subtest: render
[339.152512] Dynamic subtest render: SUCCESS (0.067s)
[339.166786] Starting dynamic subtest: bsd1
[340.968915] Dynamic subtest bsd1: SUCCESS (1.862s)
[340.980337] Starting dynamic subtest: bsd2
[342.951873] Dynamic subtest bsd2: SUCCESS (2.021s)
[342.963299] Starting dynamic subtest: blt
[342.984076] Dynamic subtest blt: SUCCESS (0.032s)
[342.992358] Starting dynamic subtest: vebox
[344.935896] Dynamic subtest vebox: SUCCESS (1.979s)
[344.938596] Subtest legacy-engines-queued: SUCCESS (5.972s)
[345.026933] [055/133] (673s left) kms_cursor_legacy (torture-bo)
[345.149921] Starting subtest: torture-bo
[345.152047] Starting dynamic subtest: pipe-A
[350.848893] Dynamic subtest pipe-A: SUCCESS (5.751s)
[350.863914] Starting dynamic subtest: pipe-B
[358.041018] Dynamic subtest pipe-B: SUCCESS (7.223s)
[358.050567] Starting dynamic subtest: pipe-C
[364.306650] Dynamic subtest pipe-C: SUCCESS (6.276s)
[364.322782] Starting dynamic subtest: pipe-D
[371.806060] Dynamic subtest pipe-D: SUCCESS (7.503s)
[371.821946] Starting dynamic subtest: all-pipes
[380.031118] Dynamic subtest all-pipes: SUCCESS (8.220s)
[380.037558] Subtest torture-bo: SUCCESS (34.981s)
[380.189645] [056/133] (638s left) kms_cursor_crc (cursor-random-256x256)
[380.445470] Starting subtest: cursor-random-256x256
[380.450864] Starting dynamic subtest: pipe-A-eDP-1
[383.818377] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.368s)
[383.852721] Starting dynamic subtest: pipe-D-eDP-1
[387.838467] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.995s)
[387.840799] Subtest cursor-random-256x256: SUCCESS (7.391s)
[388.686265] [057/133] (630s left) kms_addfb_basic (addfb25-bad-modifier)
[388.781746] Starting subtest: addfb25-bad-modifier
[388.783979] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[388.874377] [058/133] (630s left) syncobj_wait (wait-zero-handles)
[388.922993] Starting subtest: wait-zero-handles
[388.925232] Subtest wait-zero-handles: SUCCESS (0.000s)
[389.017734] [059/133] (630s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[389.112858] Starting subtest: psr-1p-primscrn-spr-indfb-draw-render
[395.920933] Subtest psr-1p-primscrn-spr-indfb-draw-render: SUCCESS (6.812s)
[396.047190] [060/133] (623s left) kms_async_flips (test-cursor)
[396.149762] Starting subtest: test-cursor
[396.154038] Subtest test-cursor: SKIP (0.000s)
[397.349389] [061/133] (621s left) kms_pm_rpm (pm-caching)
[399.167348] Starting subtest: pm-caching
[399.172889] Subtest pm-caching: SKIP (0.000s)
[399.583036] [062/133] (619s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-pgflip-blt)
[399.695117] Starting subtest: psr-2p-scndscrn-shrfb-pgflip-blt
[399.699215] Subtest psr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[399.785776] [063/133] (619s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf)
[399.891545] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf
[399.893881] Starting dynamic subtest: pipe-A-eDP-1
[399.966049] Dynamic subtest pipe-A-eDP-1: SKIP (0.078s)
[399.976180] Starting dynamic subtest: pipe-B-eDP-1
[401.152126] Dynamic subtest pipe-B-eDP-1: SKIP (1.185s)
[401.156869] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf: SKIP (1.265s)
[402.339482] [064/133] (616s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-plflip-blt)
[402.441928] Starting subtest: fbcpsr-2p-primscrn-indfb-plflip-blt
[402.446105] Subtest fbcpsr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[402.536765] [065/133] (616s left) kms_selftest (drm_format)
[402.575793] Starting subtest: drm_format
[402.608447] Starting dynamic subtest: drm_test_format_block_width_invalid
[402.708836] Dynamic subtest drm_test_format_block_width_invalid: SUCCESS (0.098s)
[402.718703] Starting dynamic subtest: drm_test_format_block_width_one_plane
[402.804742] Dynamic subtest drm_test_format_block_width_one_plane: SUCCESS (0.094s)
[402.814672] Starting dynamic subtest: drm_test_format_block_width_two_plane
[402.901945] Dynamic subtest drm_test_format_block_width_two_plane: SUCCESS (0.096s)
[402.911602] Starting dynamic subtest: drm_test_format_block_width_three_plane
[402.986877] Dynamic subtest drm_test_format_block_width_three_plane: SUCCESS (0.085s)
[402.995354] Starting dynamic subtest: drm_test_format_block_width_tiled
[403.070579] Dynamic subtest drm_test_format_block_width_tiled: SUCCESS (0.083s)
[403.079172] Starting dynamic subtest: drm_test_format_block_height_invalid
[403.166565] Dynamic subtest drm_test_format_block_height_invalid: SUCCESS (0.093s)
[403.176615] Starting dynamic subtest: drm_test_format_block_height_one_plane
[403.264118] Dynamic subtest drm_test_format_block_height_one_plane: SUCCESS (0.096s)
[403.273860] Starting dynamic subtest: drm_test_format_block_height_two_plane
[403.355517] Dynamic subtest drm_test_format_block_height_two_plane: SUCCESS (0.091s)
[403.365300] Starting dynamic subtest: drm_test_format_block_height_three_plane
[403.465762] Dynamic subtest drm_test_format_block_height_three_plane: SUCCESS (0.111s)
[403.473691] Starting dynamic subtest: drm_test_format_block_height_tiled
[403.551978] Dynamic subtest drm_test_format_block_height_tiled: SUCCESS (0.085s)
[403.560017] Starting dynamic subtest: drm_test_format_min_pitch_invalid
[403.643260] Dynamic subtest drm_test_format_min_pitch_invalid: SUCCESS (0.091s)
[403.651010] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_8bpp
[403.757314] Dynamic subtest drm_test_format_min_pitch_one_plane_8bpp: SUCCESS (0.112s)
[403.765849] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_16bpp
[403.851450] Dynamic subtest drm_test_format_min_pitch_one_plane_16bpp: SUCCESS (0.094s)
[403.859884] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_24bpp
[403.952038] Dynamic subtest drm_test_format_min_pitch_one_plane_24bpp: SUCCESS (0.099s)
[403.960880] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_32bpp
[404.060419] Dynamic subtest drm_test_format_min_pitch_one_plane_32bpp: SUCCESS (0.108s)
[404.069380] Starting dynamic subtest: drm_test_format_min_pitch_two_plane
[404.148149] Dynamic subtest drm_test_format_min_pitch_two_plane: SUCCESS (0.087s)
[404.157565] Starting dynamic subtest: drm_test_format_min_pitch_three_plane_8bpp
[404.238504] Dynamic subtest drm_test_format_min_pitch_three_plane_8bpp: SUCCESS (0.091s)
[404.247150] Starting dynamic subtest: drm_test_format_min_pitch_tiled
[404.344187] Dynamic subtest drm_test_format_min_pitch_tiled: SUCCESS (0.105s)
[404.346462] Subtest drm_format: SUCCESS (1.768s)
[404.522329] [066/133] (614s left) kms_psr (fbc-pr-basic)
[404.613313] Starting subtest: fbc-pr-basic
[404.617837] Subtest fbc-pr-basic: SKIP (0.000s)
[404.684345] [067/133] (614s left) kms_hdr (static-swap)
[404.773254] Starting subtest: static-swap
[404.779892] Subtest static-swap: SKIP (0.000s)
[404.860553] [068/133] (614s left) kms_color (ctm-0-50)
[404.956379] Starting subtest: ctm-0-50
[404.958389] Starting dynamic subtest: pipe-A-eDP-1
[408.440399] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.485s)
[408.449568] Starting dynamic subtest: pipe-B-eDP-1
[412.652074] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.214s)
[412.661994] Starting dynamic subtest: pipe-C-eDP-1
[416.801389] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.148s)
[416.809978] Starting dynamic subtest: pipe-D-eDP-1
[420.781987] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.983s)
[420.784149] Subtest ctm-0-50: SUCCESS (15.834s)
[421.608386] [069/133] (597s left) gem_mmap_gtt (basic-small-copy-odd)
[421.669586] Subtest basic-small-copy-odd: SKIP (0.000s)
[421.731787] [070/133] (597s left) kms_dsc (dsc-fractional-bpp-with-bpc)
[421.836287] Starting subtest: dsc-fractional-bpp-with-bpc
[421.866321] Subtest dsc-fractional-bpp-with-bpc: SKIP (0.001s)
[421.909534] [071/133] (597s left) kms_flip (2x-wf_vblank-ts-check)
[422.015771] Starting subtest: 2x-wf_vblank-ts-check
[422.027247] Subtest 2x-wf_vblank-ts-check: SKIP (0.011s)
[422.109392] [072/133] (597s left) gen9_exec_parse (batch-invalid-length)
[422.165504] Subtest batch-invalid-length: SKIP (0.000s)
[422.239619] [073/133] (597s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-onoff)
[422.329607] Starting subtest: psr-1p-primscrn-cur-indfb-onoff
[429.330266] Subtest psr-1p-primscrn-cur-indfb-onoff: SUCCESS (7.005s)
[429.443507] [074/133] (590s left) gem_huc_copy (huc-copy)
[429.495732] Starting subtest: huc-copy
[429.498058] Subtest huc-copy: SUCCESS (0.003s)
[429.623851] [075/133] (590s left) kms_psr (fbc-psr-sprite-plane-move)
[429.723042] Starting subtest: fbc-psr-sprite-plane-move
[429.725453] Starting dynamic subtest: eDP-1
[429.730026] Dynamic subtest eDP-1: SKIP (0.000s)
[429.734563] Subtest fbc-psr-sprite-plane-move: SKIP (0.001s)
[429.810327] [076/133] (590s left) kms_big_fb (4-tiled-16bpp-rotate-90)
[429.920125] Starting subtest: 4-tiled-16bpp-rotate-90
[429.940456] Subtest 4-tiled-16bpp-rotate-90: SKIP (0.020s)
[430.057407] [077/133] (589s left) gem_exec_reloc (basic-gtt-read-active)
[430.129935] Subtest basic-gtt-read-active: SKIP (0.000s)
[430.202732] [078/133] (589s left) gem_set_tiling_vs_blt (tiled-to-untiled)
[430.260109] Subtest tiled-to-untiled: SKIP (0.000s)
[430.327768] [079/133] (589s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[430.422084] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu
[434.746766] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (4.327s)
[435.923331] [080/133] (584s left) gem_madvise (dontneed-before-mmap)
[435.973357] Starting subtest: dontneed-before-mmap
[436.004135] Subtest dontneed-before-mmap: SUCCESS (0.033s)
[436.107018] [081/133] (583s left) kms_async_flips (basic-modeset-with-all-modifiers-formats)
[436.195575] Starting subtest: basic-modeset-with-all-modifiers-formats
[436.571667] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB8888
[437.386011] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB8888: SUCCESS (0.815s)
[437.395346] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB2101010
[437.408255] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB2101010: SUCCESS (0.021s)
[437.418484] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB16161616F
[437.430394] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB16161616F: SUCCESS (0.022s)
[437.439298] Starting dynamic subtest: pipe-A-eDP-1-4-RGB565
[437.585921] Dynamic subtest pipe-A-eDP-1-4-RGB565: SUCCESS (0.155s)
[437.595351] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB8888
[437.608279] Dynamic subtest pipe-A-eDP-1-4-XRGB8888: SUCCESS (0.021s)
[437.619622] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB2101010
[437.630433] Dynamic subtest pipe-A-eDP-1-4-XRGB2101010: SUCCESS (0.019s)
[437.644648] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB16161616F
[437.652652] Dynamic subtest pipe-A-eDP-1-4-XRGB16161616F: SUCCESS (0.020s)
[437.661197] Starting dynamic subtest: pipe-A-eDP-1-4-YUYV
[437.674516] Dynamic subtest pipe-A-eDP-1-4-YUYV: SUCCESS (0.020s)
[437.683054] Starting dynamic subtest: pipe-A-eDP-1-4-Y210
[437.696905] Dynamic subtest pipe-A-eDP-1-4-Y210: SUCCESS (0.021s)
[437.704982] Starting dynamic subtest: pipe-A-eDP-1-4-XYUV8888
[437.719042] Dynamic subtest pipe-A-eDP-1-4-XYUV8888: SUCCESS (0.021s)
[437.727400] Starting dynamic subtest: pipe-A-eDP-1-4-XV30
[437.741415] Dynamic subtest pipe-A-eDP-1-4-XV30: SUCCESS (0.021s)
[437.749758] Starting dynamic subtest: pipe-A-eDP-1-4-XV36
[437.763597] Dynamic subtest pipe-A-eDP-1-4-XV36: SUCCESS (0.021s)
[437.772030] Starting dynamic subtest: pipe-A-eDP-1-x-RGB565
[437.919286] Dynamic subtest pipe-A-eDP-1-x-RGB565: SUCCESS (0.155s)
[437.928056] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB8888
[437.941436] Dynamic subtest pipe-A-eDP-1-x-XRGB8888: SUCCESS (0.022s)
[437.949985] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB2101010
[437.963708] Dynamic subtest pipe-A-eDP-1-x-XRGB2101010: SUCCESS (0.021s)
[437.972684] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB16161616F
[437.986008] Dynamic subtest pipe-A-eDP-1-x-XRGB16161616F: SUCCESS (0.021s)
[437.994792] Starting dynamic subtest: pipe-A-eDP-1-x-YUYV
[438.008245] Dynamic subtest pipe-A-eDP-1-x-YUYV: SUCCESS (0.021s)
[438.019713] Starting dynamic subtest: pipe-A-eDP-1-x-Y210
[438.030538] Dynamic subtest pipe-A-eDP-1-x-Y210: SUCCESS (0.022s)
[438.039134] Starting dynamic subtest: pipe-A-eDP-1-x-XYUV8888
[438.052774] Dynamic subtest pipe-A-eDP-1-x-XYUV8888: SUCCESS (0.021s)
[438.061502] Starting dynamic subtest: pipe-A-eDP-1-x-XV30
[438.075127] Dynamic subtest pipe-A-eDP-1-x-XV30: SUCCESS (0.021s)
[438.085400] Starting dynamic subtest: pipe-A-eDP-1-x-XV36
[438.097109] Dynamic subtest pipe-A-eDP-1-x-XV36: SUCCESS (0.021s)
[438.105733] Starting dynamic subtest: pipe-A-eDP-1-linear-RGB565
[438.253401] Dynamic subtest pipe-A-eDP-1-linear-RGB565: SUCCESS (0.155s)
[438.263549] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB8888
[438.275428] Dynamic subtest pipe-A-eDP-1-linear-XRGB8888: SUCCESS (0.021s)
[438.283908] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB2101010
[438.297679] Dynamic subtest pipe-A-eDP-1-linear-XRGB2101010: SUCCESS (0.021s)
[438.306224] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB16161616F
[438.319896] Dynamic subtest pipe-A-eDP-1-linear-XRGB16161616F: SUCCESS (0.022s)
[438.328297] Starting dynamic subtest: pipe-A-eDP-1-linear-YUYV
[438.342071] Dynamic subtest pipe-A-eDP-1-linear-YUYV: SUCCESS (0.021s)
[438.350295] Starting dynamic subtest: pipe-A-eDP-1-linear-Y210
[438.364502] Dynamic subtest pipe-A-eDP-1-linear-Y210: SUCCESS (0.022s)
[438.375553] Starting dynamic subtest: pipe-A-eDP-1-linear-XYUV8888
[438.386271] Dynamic subtest pipe-A-eDP-1-linear-XYUV8888: SUCCESS (0.021s)
[438.394465] Starting dynamic subtest: pipe-A-eDP-1-linear-XV30
[438.408435] Dynamic subtest pipe-A-eDP-1-linear-XV30: SUCCESS (0.021s)
[438.416279] Starting dynamic subtest: pipe-A-eDP-1-linear-XV36
[438.430843] Dynamic subtest pipe-A-eDP-1-linear-XV36: SUCCESS (0.022s)
[438.818474] Subtest basic-modeset-with-all-modifiers-formats: SUCCESS (2.622s)
[439.670401] [082/133] (580s left) kms_addfb_basic (invalid-get-prop)
[439.739507] Starting subtest: invalid-get-prop
[439.741853] Subtest invalid-get-prop: SUCCESS (0.000s)
[439.873178] [083/133] (580s left) kms_cursor_crc (cursor-sliding-256x85)
[440.170723] Starting subtest: cursor-sliding-256x85
[440.220021] Subtest cursor-sliding-256x85: SKIP (0.044s)
[440.396833] [084/133] (579s left) perf (short-reads)
[440.495086] Starting subtest: short-reads
[440.507735] Subtest short-reads: SUCCESS (0.013s)
[440.564660] [085/133] (579s left) kms_cursor_crc (cursor-onscreen-128x42)
[440.799227] Starting subtest: cursor-onscreen-128x42
[440.862595] Subtest cursor-onscreen-128x42: SKIP (0.058s)
[441.064940] [086/133] (579s left) gem_exec_schedule (u-semaphore-noskip)
[441.133449] Starting subtest: u-semaphore-noskip
[441.533178] Subtest u-semaphore-noskip: SUCCESS (0.403s)
[441.874856] [087/133] (578s left) kms_ccs (crc-primary-basic-4-tiled-lnl-ccs)
[441.972237] Starting subtest: crc-primary-basic-4-tiled-lnl-ccs
[441.976784] Subtest crc-primary-basic-4-tiled-lnl-ccs: SKIP (0.000s)
[443.121062] [088/133] (577s left) gem_exec_reloc (basic-write-cpu-noreloc)
[443.181547] Subtest basic-write-cpu-noreloc: SKIP (0.000s)
[443.250165] [089/133] (577s left) gem_pxp (protected-encrypted-src-copy-not-readible)
[443.362314] Starting subtest: protected-encrypted-src-copy-not-readible
[443.473230] Subtest protected-encrypted-src-copy-not-readible: SUCCESS (0.112s)
[443.586236] [090/133] (576s left) gem_exec_schedule (out-order)
[443.661882] Starting subtest: out-order
[443.664168] Starting dynamic subtest: rcs0
[443.758772] Dynamic subtest rcs0: SUCCESS (0.104s)
[443.773884] Starting dynamic subtest: vcs0
[443.836404] Dynamic subtest vcs0: SUCCESS (0.077s)
[443.846951] Starting dynamic subtest: vcs1
[443.914848] Dynamic subtest vcs1: SUCCESS (0.078s)
[443.926503] Starting dynamic subtest: vecs0
[444.000011] Dynamic subtest vecs0: SUCCESS (0.083s)
[444.013904] Starting dynamic subtest: ccs0
[444.085167] Dynamic subtest ccs0: SUCCESS (0.083s)
[444.087857] Subtest out-order: SUCCESS (0.430s)
[445.092894] [091/133] (575s left) kms_fbcon_fbt (psr)
[445.154930] Starting subtest: psr
[452.479663] Subtest psr: SUCCESS (7.321s)
[452.554335] [092/133] (567s left) kms_joiner (basic-force-big-joiner)
[452.667610] Starting subtest: basic-force-big-joiner
[452.671643] Subtest basic-force-big-joiner: SKIP (0.000s)
[452.761743] [093/133] (567s left) gem_exec_params (larger-than-life-batch)
[452.825507] Starting subtest: larger-than-life-batch
[455.369377] Subtest larger-than-life-batch: SUCCESS (2.543s)
[456.075996] [094/133] (564s left) syncobj_timeline (invalid-query-one-illegal-handle)
[456.182130] Starting subtest: invalid-query-one-illegal-handle
[456.184496] Subtest invalid-query-one-illegal-handle: SUCCESS (0.000s)
[456.260897] [095/133] (564s left) syncobj_basic (bad-flags-fd-to-handle)
[456.321906] Starting subtest: bad-flags-fd-to-handle
[456.324169] Subtest bad-flags-fd-to-handle: SUCCESS (0.000s)
[456.438263] [096/133] (564s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[456.560083] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip
[457.573786] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip: SUCCESS (1.012s)
[457.707061] [097/133] (562s left) kms_psr (fbc-pr-sprite-plane-onoff)
[457.799938] Starting subtest: fbc-pr-sprite-plane-onoff
[457.804347] Subtest fbc-pr-sprite-plane-onoff: SKIP (0.000s)
[457.909148] [098/133] (562s left) core_getversion (basic)
[457.950248] Starting subtest: basic
[457.954051] Subtest basic: SUCCESS (0.000s)
[458.019822] [099/133] (562s left) kms_frontbuffer_tracking (fbcpsr-tiling-linear)
[458.123485] Starting subtest: fbcpsr-tiling-linear
[464.715933] Subtest fbcpsr-tiling-linear: SUCCESS (6.593s)
[464.864478] [100/133] (555s left) kms_flip (2x-flip-vs-suspend)
[464.953022] Starting subtest: 2x-flip-vs-suspend
[464.961989] Subtest 2x-flip-vs-suspend: SKIP (0.011s)
[465.056212] [101/133] (555s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping)
[465.148766] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping
[465.175609] Starting dynamic subtest: pipe-A-plane-0
[465.464237] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[465.476112] Starting dynamic subtest: pipe-A-plane-3
[465.696949] Dynamic subtest pipe-A-plane-3: SUCCESS (0.233s)
[466.797982] Starting dynamic subtest: pipe-B-plane-0
[467.101372] Dynamic subtest pipe-B-plane-0: SUCCESS (0.311s)
[467.111051] Starting dynamic subtest: pipe-B-plane-3
[467.356709] Dynamic subtest pipe-B-plane-3: SUCCESS (0.253s)
[467.673470] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping: SUCCESS (2.524s)
[468.471784] [102/133] (552s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[468.541477] Starting subtest: yf-tiled-to-vebox-yf-tiled
[468.546515] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[468.727328] [103/133] (552s left) kms_psr (psr2-cursor-plane-onoff)
[468.823008] Starting subtest: psr2-cursor-plane-onoff
[468.825465] Starting dynamic subtest: eDP-1
[470.543932] Dynamic subtest eDP-1: SUCCESS (1.721s)
[470.546270] Subtest psr2-cursor-plane-onoff: SUCCESS (1.722s)
[471.340533] [104/133] (549s left) gem_exec_reloc (basic-gtt)
[471.400916] Subtest basic-gtt: SKIP (0.000s)
[471.479463] [105/133] (549s left) gem_partial_pwrite_pread (writes-after-reads-snoop)
[471.536886] Subtest writes-after-reads-snoop: SKIP (0.000s)
[471.617191] [106/133] (549s left) syncobj_wait (wait-all-snapshot)
[471.684122] Starting subtest: wait-all-snapshot
[471.763858] Subtest wait-all-snapshot: SUCCESS (0.082s)
[471.853782] [107/133] (549s left) prime_vgem (wait)
[471.925805] Starting subtest: wait
[471.928148] Starting dynamic subtest: rcs0
[472.027496] Dynamic subtest rcs0: SUCCESS (0.097s)
[472.039656] Starting dynamic subtest: bcs0
[472.077297] Dynamic subtest bcs0: SUCCESS (0.053s)
[472.086217] Starting dynamic subtest: vcs0
[472.126310] Dynamic subtest vcs0: SUCCESS (0.048s)
[472.135428] Starting dynamic subtest: vcs1
[472.172408] Dynamic subtest vcs1: SUCCESS (0.045s)
[472.181560] Starting dynamic subtest: vecs0
[472.219288] Dynamic subtest vecs0: SUCCESS (0.046s)
[472.228285] Starting dynamic subtest: ccs0
[472.268032] Dynamic subtest ccs0: SUCCESS (0.048s)
[472.270116] Subtest wait: SUCCESS (0.343s)
[472.384511] [108/133] (548s left) kms_vblank (query-idle)
[472.470295] Starting subtest: query-idle
[472.472101] Starting dynamic subtest: pipe-A-eDP-1
[474.225072] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.753s)
[474.238610] Starting dynamic subtest: pipe-D-eDP-1
[476.669213] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.441s)
[476.671396] Subtest query-idle: SUCCESS (4.198s)
[477.493630] [109/133] (543s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-render)
[477.592013] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-render
[481.765283] Subtest fbc-1p-offscreen-pri-indfb-draw-render: SUCCESS (4.176s)
[482.884048] [110/133] (538s left) syncobj_basic (bad-handle-to-fd)
[482.948281] Starting subtest: bad-handle-to-fd
[482.950489] Subtest bad-handle-to-fd: SUCCESS (0.000s)
[483.058154] [111/133] (538s left) gem_mmap_offset (basic-uaf)
[483.107428] Starting subtest: basic-uaf
[483.109758] Subtest basic-uaf: SUCCESS (0.001s)
[483.228368] [112/133] (538s left) gem_eio (suspend)
[483.480214] Starting subtest: suspend
[495.721765] Subtest suspend: SUCCESS (12.249s)
[495.835685] [113/133] (525s left) api_intel_bb (intel-bb-blit-x)
[495.906068] Starting subtest: intel-bb-blit-x
[499.368521] Subtest intel-bb-blit-x: SUCCESS (3.463s)
[499.487360] [114/133] (521s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-msflip-blt)
[499.577880] Starting subtest: fbc-2p-scndscrn-shrfb-msflip-blt
[499.581969] Subtest fbc-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[499.698456] [115/133] (521s left) gem_fenced_exec_thrash (too-many-fences)
[499.764714] Subtest too-many-fences: SKIP (0.000s)
[499.844061] [116/133] (521s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-blt)
[499.945625] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-blt
[503.726031] Subtest fbc-1p-primscrn-spr-indfb-draw-blt: SUCCESS (3.785s)
[504.882604] [117/133] (516s left) kms_flip (2x-flip-vs-blocking-wf-vblank)
[504.988089] Starting subtest: 2x-flip-vs-blocking-wf-vblank
[504.997173] Subtest 2x-flip-vs-blocking-wf-vblank: SKIP (0.011s)
[505.097001] [118/133] (516s left) gem_wait (write-wait)
[505.170872] Starting subtest: write-wait
[505.173270] Starting dynamic subtest: all-engines
[506.266119] Dynamic subtest all-engines: SUCCESS (1.095s)
[506.283433] Starting dynamic subtest: rcs0
[507.321605] Dynamic subtest rcs0: SUCCESS (1.053s)
[507.338543] Starting dynamic subtest: bcs0
[508.378063] Dynamic subtest bcs0: SUCCESS (1.054s)
[508.396858] Starting dynamic subtest: vcs0
[509.432856] Dynamic subtest vcs0: SUCCESS (1.052s)
[509.447787] Starting dynamic subtest: vcs1
[510.485514] Dynamic subtest vcs1: SUCCESS (1.050s)
[510.502542] Starting dynamic subtest: vecs0
[511.538871] Dynamic subtest vecs0: SUCCESS (1.052s)
[511.556276] Starting dynamic subtest: ccs0
[512.595191] Dynamic subtest ccs0: SUCCESS (1.054s)
[512.597712] Subtest write-wait: SUCCESS (7.427s)
[512.800801] [119/133] (508s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-sf-dmg-area)
[512.920633] Starting subtest: fbc-psr2-overlay-plane-update-sf-dmg-area
[512.922863] Starting dynamic subtest: pipe-A-eDP-1
[513.006710] Dynamic subtest pipe-A-eDP-1: SKIP (0.089s)
[513.016651] Starting dynamic subtest: pipe-B-eDP-1
[514.153466] Dynamic subtest pipe-B-eDP-1: SKIP (1.146s)
[514.158073] Subtest fbc-psr2-overlay-plane-update-sf-dmg-area: SKIP (1.237s)
[515.355082] [120/133] (506s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[515.456043] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[515.458325] Starting dynamic subtest: pipe-A-eDP-1
[516.342865] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.880s)
[516.352766] Starting dynamic subtest: pipe-B-eDP-1
[517.990918] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.645s)
[518.001479] Starting dynamic subtest: pipe-C-eDP-1
[519.503705] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.513s)
[519.514058] Starting dynamic subtest: pipe-D-eDP-1
[521.030981] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.526s)
[521.033152] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.569s)
[522.176314] [121/133] (499s left) gem_mmap_gtt (basic-read)
[522.242751] Subtest basic-read: SKIP (0.000s)
[522.298963] [122/133] (499s left) kms_cdclk (mode-transition-all-outputs)
[522.397848] Starting subtest: mode-transition-all-outputs
[522.685504] Subtest mode-transition-all-outputs: SKIP (0.287s)
[523.502591] [123/133] (498s left) gem_exec_nop (basic-parallel)
[523.584610] Starting subtest: basic-parallel
[538.956158] Subtest basic-parallel: SUCCESS (15.384s)
[539.189249] [124/133] (482s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[539.304764] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[539.309156] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[539.403490] [125/133] (482s left) syncobj_timeline (wait-all-for-submit-snapshot)
[539.459297] Starting subtest: wait-all-for-submit-snapshot
[539.540834] Subtest wait-all-for-submit-snapshot: SUCCESS (0.082s)
[539.633043] [126/133] (482s left) gem_exec_balancer (full-late)
[539.688425] Starting subtest: full-late
[543.833590] Subtest full-late: SUCCESS (4.145s)
[544.160703] [127/133] (477s left) kms_flip (flip-vs-dpms-off-vs-modeset)
[544.255037] Starting subtest: flip-vs-dpms-off-vs-modeset
[544.257323] Starting dynamic subtest: A-eDP1
[547.839001] Dynamic subtest A-eDP1: SUCCESS (3.581s)
[547.848829] Starting dynamic subtest: B-eDP1
[551.022921] Dynamic subtest B-eDP1: SUCCESS (3.185s)
[551.032866] Starting dynamic subtest: C-eDP1
[554.195899] Dynamic subtest C-eDP1: SUCCESS (3.172s)
[554.206283] Starting dynamic subtest: D-eDP1
[557.403452] Dynamic subtest D-eDP1: SUCCESS (3.207s)
[557.408383] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (13.155s)
[558.190696] [128/133] (463s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-blt)
[558.306283] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-blt
[565.639221] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.339s)
[565.769494] [129/133] (456s left) kms_psr_stress_test (flip-primary-invalidate-overlay)
[565.856053] Starting subtest: flip-primary-invalidate-overlay
[577.503549] Subtest flip-primary-invalidate-overlay: SUCCESS (11.652s)
[577.653655] [130/133] (444s left) kms_psr (psr2-cursor-blt)
[577.743299] Starting subtest: psr2-cursor-blt
[577.745193] Starting dynamic subtest: eDP-1
[579.480427] Dynamic subtest eDP-1: SUCCESS (1.739s)
[579.482868] Subtest psr2-cursor-blt: SUCCESS (1.740s)
[580.272288] [131/133] (441s left) gem_ctx_exec (basic-invalid-context)
[580.329069] Starting subtest: basic-invalid-context
[580.334170] Subtest basic-invalid-context: SUCCESS (0.005s)
[580.460210] [132/133] (441s left) kms_rotation_crc (primary-y-tiled-reflect-x-180)
[580.549979] Starting subtest: primary-y-tiled-reflect-x-180
[580.610064] Subtest primary-y-tiled-reflect-x-180: SKIP (0.060s)
[580.729000] [133/133] (441s left) gem_eio (wait-10ms)
[580.967005] Starting subtest: wait-10ms
[581.344005] Subtest wait-10ms: SUCCESS (0.377s)
[581.487955] 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.