[60.558948] Initializing watchdogs
[60.558994]   /dev/watchdog0
[60.573027] [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]
[60.577449] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[60.579624] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[60.583030] [001/133] (960s left) kms_busy (extended-modeset-hang-newfb)
[60.728783] Starting subtest: extended-modeset-hang-newfb
[60.731055] Starting dynamic subtest: pipe-A
[94.792431] Dynamic subtest pipe-A: SUCCESS (34.064s)
[94.803814] Starting dynamic subtest: pipe-D
[117.762218] Dynamic subtest pipe-D: SUCCESS (22.970s)
[117.764433] Subtest extended-modeset-hang-newfb: SUCCESS (57.039s)
[118.568906] [002/133] (902s left) kms_plane_scaling (plane-upscale-factor-0-25-with-rotation)
[118.673061] Starting subtest: plane-upscale-factor-0-25-with-rotation
[118.675419] Starting dynamic subtest: pipe-A
[118.898871] Dynamic subtest pipe-A: SUCCESS (0.227s)
[118.907990] Starting dynamic subtest: pipe-B
[120.156054] Dynamic subtest pipe-B: SUCCESS (1.257s)
[120.165542] Starting dynamic subtest: pipe-C
[121.491399] Dynamic subtest pipe-C: SUCCESS (1.335s)
[121.500184] Starting dynamic subtest: pipe-D
[122.832629] Dynamic subtest pipe-D: SUCCESS (1.340s)
[122.835229] Subtest plane-upscale-factor-0-25-with-rotation: SUCCESS (4.161s)
[124.022919] [003/133] (897s left) gem_pxp (fail-invalid-protected-context)
[124.157923] Starting subtest: fail-invalid-protected-context
[124.160725] Subtest fail-invalid-protected-context: SUCCESS (0.000s)
[124.332253] [004/133] (896s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-wc)
[124.440780] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-wc
[124.444691] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[124.557190] [005/133] (896s left) kms_properties (colorop-properties-legacy)
[124.647281] Starting subtest: colorop-properties-legacy
[124.649694] Starting dynamic subtest: pipe-A-eDP-1
[124.986715] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.340s)
[124.995795] Starting dynamic subtest: pipe-B-eDP-1
[126.093951] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.106s)
[126.101332] Starting dynamic subtest: pipe-C-eDP-1
[127.125884] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.031s)
[127.135899] Starting dynamic subtest: pipe-D-eDP-1
[128.161330] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.034s)
[128.163330] Subtest colorop-properties-legacy: SUCCESS (3.515s)
[128.979948] [006/133] (892s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-move)
[129.084689] Starting subtest: fbc-1p-primscrn-cur-indfb-move
[133.149240] Subtest fbc-1p-primscrn-cur-indfb-move: SUCCESS (4.066s)
[134.383339] [007/133] (886s left) kms_async_flips (basic-modeset-with-all-modifiers-formats)
[134.491880] Starting subtest: basic-modeset-with-all-modifiers-formats
[134.875897] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB8888
[135.639616] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB8888: SUCCESS (0.764s)
[135.649127] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB2101010
[135.660118] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB2101010: SUCCESS (0.021s)
[135.667515] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB16161616F
[135.682862] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB16161616F: SUCCESS (0.022s)
[135.690447] Starting dynamic subtest: pipe-A-eDP-1-4-RGB565
[135.827429] Dynamic subtest pipe-A-eDP-1-4-RGB565: SUCCESS (0.144s)
[135.836290] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB8888
[135.849505] Dynamic subtest pipe-A-eDP-1-4-XRGB8888: SUCCESS (0.022s)
[135.856862] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB2101010
[135.871833] Dynamic subtest pipe-A-eDP-1-4-XRGB2101010: SUCCESS (0.022s)
[135.880276] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB16161616F
[135.893925] Dynamic subtest pipe-A-eDP-1-4-XRGB16161616F: SUCCESS (0.022s)
[135.902253] Starting dynamic subtest: pipe-A-eDP-1-4-YUYV
[135.915832] Dynamic subtest pipe-A-eDP-1-4-YUYV: SUCCESS (0.022s)
[135.923220] Starting dynamic subtest: pipe-A-eDP-1-4-Y210
[135.938400] Dynamic subtest pipe-A-eDP-1-4-Y210: SUCCESS (0.022s)
[135.945687] Starting dynamic subtest: pipe-A-eDP-1-4-XYUV8888
[135.960259] Dynamic subtest pipe-A-eDP-1-4-XYUV8888: SUCCESS (0.022s)
[135.967694] Starting dynamic subtest: pipe-A-eDP-1-4-XV30
[135.982985] Dynamic subtest pipe-A-eDP-1-4-XV30: SUCCESS (0.022s)
[135.990284] Starting dynamic subtest: pipe-A-eDP-1-4-XV36
[136.005064] Dynamic subtest pipe-A-eDP-1-4-XV36: SUCCESS (0.022s)
[136.012778] Starting dynamic subtest: pipe-A-eDP-1-x-RGB565
[136.160487] Dynamic subtest pipe-A-eDP-1-x-RGB565: SUCCESS (0.155s)
[136.167854] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB8888
[136.182507] Dynamic subtest pipe-A-eDP-1-x-XRGB8888: SUCCESS (0.022s)
[136.190626] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB2101010
[136.205139] Dynamic subtest pipe-A-eDP-1-x-XRGB2101010: SUCCESS (0.022s)
[136.213481] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB16161616F
[136.227424] Dynamic subtest pipe-A-eDP-1-x-XRGB16161616F: SUCCESS (0.022s)
[136.234479] Starting dynamic subtest: pipe-A-eDP-1-x-YUYV
[136.249555] Dynamic subtest pipe-A-eDP-1-x-YUYV: SUCCESS (0.022s)
[136.258769] Starting dynamic subtest: pipe-A-eDP-1-x-Y210
[136.271656] Dynamic subtest pipe-A-eDP-1-x-Y210: SUCCESS (0.022s)
[136.279439] Starting dynamic subtest: pipe-A-eDP-1-x-XYUV8888
[136.294206] Dynamic subtest pipe-A-eDP-1-x-XYUV8888: SUCCESS (0.022s)
[136.303256] Starting dynamic subtest: pipe-A-eDP-1-x-XV30
[136.316218] Dynamic subtest pipe-A-eDP-1-x-XV30: SUCCESS (0.022s)
[136.324159] Starting dynamic subtest: pipe-A-eDP-1-x-XV36
[136.338391] Dynamic subtest pipe-A-eDP-1-x-XV36: SUCCESS (0.022s)
[136.347420] Starting dynamic subtest: pipe-A-eDP-1-linear-RGB565
[136.505550] Dynamic subtest pipe-A-eDP-1-linear-RGB565: SUCCESS (0.167s)
[136.515208] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB8888
[136.527746] Dynamic subtest pipe-A-eDP-1-linear-XRGB8888: SUCCESS (0.022s)
[136.536638] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB2101010
[136.550071] Dynamic subtest pipe-A-eDP-1-linear-XRGB2101010: SUCCESS (0.022s)
[136.557087] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB16161616F
[136.571791] Dynamic subtest pipe-A-eDP-1-linear-XRGB16161616F: SUCCESS (0.022s)
[136.579382] Starting dynamic subtest: pipe-A-eDP-1-linear-YUYV
[136.594189] Dynamic subtest pipe-A-eDP-1-linear-YUYV: SUCCESS (0.021s)
[136.600891] Starting dynamic subtest: pipe-A-eDP-1-linear-Y210
[136.616152] Dynamic subtest pipe-A-eDP-1-linear-Y210: SUCCESS (0.022s)
[136.622906] Starting dynamic subtest: pipe-A-eDP-1-linear-XYUV8888
[136.638175] Dynamic subtest pipe-A-eDP-1-linear-XYUV8888: SUCCESS (0.022s)
[136.645077] Starting dynamic subtest: pipe-A-eDP-1-linear-XV30
[136.660667] Dynamic subtest pipe-A-eDP-1-linear-XV30: SUCCESS (0.022s)
[136.667521] Starting dynamic subtest: pipe-A-eDP-1-linear-XV36
[136.682647] Dynamic subtest pipe-A-eDP-1-linear-XV36: SUCCESS (0.022s)
[137.088140] Subtest basic-modeset-with-all-modifiers-formats: SUCCESS (2.597s)
[137.929847] [008/133] (883s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-upscaling)
[138.034977] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-upscaling
[138.039436] Subtest flip-32bpp-yftile-to-64bpp-yftile-upscaling: SKIP (0.000s)
[138.130328] [009/133] (883s left) gem_exec_params (rel-constants-invalid-rel-gen5)
[138.182997] Starting subtest: rel-constants-invalid-rel-gen5
[138.185288] Subtest rel-constants-invalid-rel-gen5: SUCCESS (0.000s)
[138.288983] [010/133] (883s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions)
[138.404475] Starting subtest: short-busy-flip-before-cursor-atomic-transitions
[138.409039] Subtest short-busy-flip-before-cursor-atomic-transitions: SKIP (0.000s)
[138.558944] [011/133] (882s left) kms_frontbuffer_tracking (fbcpsr-indfb-scaledprimary)
[138.663383] Starting subtest: fbcpsr-indfb-scaledprimary
[144.471975] Subtest fbcpsr-indfb-scaledprimary: SUCCESS (5.812s)
[144.630623] [012/133] (876s left) kms_flip (2x-flip-vs-absolute-wf_vblank)
[144.723598] Starting subtest: 2x-flip-vs-absolute-wf_vblank
[144.734760] Subtest 2x-flip-vs-absolute-wf_vblank: SKIP (0.011s)
[144.826357] [013/133] (876s left) gem_exec_schedule (promotion)
[144.901589] Starting subtest: promotion
[144.903831] Starting dynamic subtest: rcs0
[144.993845] Dynamic subtest rcs0: SUCCESS (0.099s)
[145.003677] Starting dynamic subtest: vcs0
[145.080655] Dynamic subtest vcs0: SUCCESS (0.086s)
[145.090900] Starting dynamic subtest: vcs1
[145.158651] Dynamic subtest vcs1: SUCCESS (0.078s)
[145.168974] Starting dynamic subtest: vecs0
[145.235980] Dynamic subtest vecs0: SUCCESS (0.076s)
[145.246921] Starting dynamic subtest: ccs0
[145.319795] Dynamic subtest ccs0: SUCCESS (0.084s)
[145.322332] Subtest promotion: SUCCESS (0.426s)
[147.053602] [014/133] (874s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[147.095591] Subtest heavy-verify-multi-ccs: SKIP (0.000s)
[147.137091] [015/133] (874s left) gem_ctx_shared (disjoint-timelines)
[147.205989] Starting subtest: disjoint-timelines
[147.254834] Subtest disjoint-timelines: SUCCESS (0.049s)
[147.518602] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true
[147.522132] [016/133] (874s left) gem_userptr_blits (map-fixed-invalidate)
[147.579497] Starting subtest: map-fixed-invalidate
[147.583661] Subtest map-fixed-invalidate: SKIP (0.000s)
[147.726350] [017/133] (873s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-downscaling)
[147.831608] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-downscaling
[147.833713] Starting dynamic subtest: pipe-A-default-mode
[149.011775] Dynamic subtest pipe-A-default-mode: SKIP (1.178s)
[149.016337] Subtest flip-64bpp-xtile-to-32bpp-xtile-downscaling: SKIP (1.179s)
[149.183684] [018/133] (872s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite)
[149.279266] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite
[158.608380] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.330s)
[158.746759] [019/133] (863s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[158.861416] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[158.865847] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[158.997050] [020/133] (862s left) gem_ctx_persistence (engines-hang)
[159.064041] Starting subtest: engines-hang
[159.066043] Starting dynamic subtest: rcs0
[159.182355] Dynamic subtest rcs0: SUCCESS (0.120s)
[159.193417] Starting dynamic subtest: bcs0
[159.300945] Dynamic subtest bcs0: SUCCESS (0.118s)
[159.311319] Starting dynamic subtest: vcs0
[159.413765] Dynamic subtest vcs0: SUCCESS (0.112s)
[159.424448] Starting dynamic subtest: vcs1
[159.531326] Dynamic subtest vcs1: SUCCESS (0.116s)
[159.542577] Starting dynamic subtest: vecs0
[159.644658] Dynamic subtest vecs0: SUCCESS (0.113s)
[159.654739] Starting dynamic subtest: ccs0
[159.756870] Dynamic subtest ccs0: SUCCESS (0.111s)
[159.759360] Subtest engines-hang: SUCCESS (0.696s)
[159.849066] [021/133] (862s left) i915_pm_rpm (sysfs-read)
[162.068197] Starting subtest: sysfs-read
[163.218464] Subtest sysfs-read: SUCCESS (1.148s)
[163.604284] [022/133] (858s left) prime_vgem (coherency-blt)
[163.683706] Starting subtest: coherency-blt
[164.729917] Subtest coherency-blt: SUCCESS (1.047s)
[164.884875] [023/133] (857s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[164.947882] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[164.950130] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.000s)
[165.065387] [024/133] (856s left) gen9_exec_parse (batch-without-end)
[165.119633] Subtest batch-without-end: SKIP (0.000s)
[165.208791] [025/133] (856s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-pgflip-blt)
[165.304640] Starting subtest: fbc-2p-primscrn-shrfb-pgflip-blt
[165.308724] Subtest fbc-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[165.429947] [026/133] (856s left) gem_partial_pwrite_pread (writes-after-reads-display)
[165.491859] Subtest writes-after-reads-display: SKIP (0.000s)
[165.552078] [027/133] (856s left) gem_wait (wait)
[165.606285] Starting subtest: wait
[165.608607] Starting dynamic subtest: all-engines
[166.931693] Dynamic subtest all-engines: SUCCESS (1.324s)
[166.942720] Starting dynamic subtest: rcs0
[167.977218] Dynamic subtest rcs0: SUCCESS (1.045s)
[167.987418] Starting dynamic subtest: bcs0
[169.023068] Dynamic subtest bcs0: SUCCESS (1.045s)
[169.033133] Starting dynamic subtest: vcs0
[170.065613] Dynamic subtest vcs0: SUCCESS (1.041s)
[170.075858] Starting dynamic subtest: vcs1
[171.111564] Dynamic subtest vcs1: SUCCESS (1.044s)
[171.122519] Starting dynamic subtest: vecs0
[172.157242] Dynamic subtest vecs0: SUCCESS (1.044s)
[172.168284] Starting dynamic subtest: ccs0
[173.204059] Dynamic subtest ccs0: SUCCESS (1.045s)
[173.206526] Subtest wait: SUCCESS (7.598s)
[173.434924] [028/133] (848s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[173.545069] Starting subtest: 4-tiled-32bpp-rotate-180
[174.878400] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.334s)
[175.036163] [029/133] (847s left) kms_plane_multiple (tiling-yf)
[175.138241] Starting subtest: tiling-yf
[175.141783] Subtest tiling-yf: SKIP (0.000s)
[175.234415] [030/133] (847s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-render)
[175.339449] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-render
[175.343065] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[175.447620] [031/133] (846s left) kms_color (ctm-0-75)
[175.543936] Starting subtest: ctm-0-75
[175.545548] Starting dynamic subtest: pipe-A-eDP-1
[180.533577] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.989s)
[180.542540] Starting dynamic subtest: pipe-B-eDP-1
[186.243919] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.708s)
[186.252268] Starting dynamic subtest: pipe-C-eDP-1
[191.630093] Dynamic subtest pipe-C-eDP-1: SUCCESS (5.382s)
[191.638533] Starting dynamic subtest: pipe-D-eDP-1
[196.945510] Dynamic subtest pipe-D-eDP-1: SUCCESS (5.318s)
[196.947850] Subtest ctm-0-75: SUCCESS (21.401s)
[197.776371] [032/133] (824s left) kms_chamelium_frames (dp-crc-single)
[198.163748] Subtest dp-crc-single: SKIP (0.000s)
[198.968032] [033/133] (823s left) kms_draw_crc (draw-method-render)
[199.074558] Starting subtest: draw-method-render
[199.076549] Starting dynamic subtest: xrgb8888-untiled
[199.339992] Dynamic subtest xrgb8888-untiled: SUCCESS (0.267s)
[199.351410] Starting dynamic subtest: rgb565-untiled
[199.584144] Dynamic subtest rgb565-untiled: SUCCESS (0.244s)
[199.592661] Starting dynamic subtest: xrgb2101010-untiled
[199.817709] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.233s)
[199.826255] Starting dynamic subtest: xbgr16161616f-untiled
[200.050549] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.232s)
[200.058528] Starting dynamic subtest: xrgb8888-xtiled
[200.172631] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.122s)
[200.180905] Starting dynamic subtest: rgb565-xtiled
[200.295059] Dynamic subtest rgb565-xtiled: SUCCESS (0.122s)
[200.302795] Starting dynamic subtest: xrgb2101010-xtiled
[200.417330] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.122s)
[200.425633] Starting dynamic subtest: xbgr16161616f-xtiled
[200.550787] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.132s)
[200.561611] Starting dynamic subtest: xrgb8888-4tiled
[200.673347] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.121s)
[200.683039] Starting dynamic subtest: rgb565-4tiled
[200.783978] Dynamic subtest rgb565-4tiled: SUCCESS (0.110s)
[200.791888] Starting dynamic subtest: xrgb2101010-4tiled
[200.906293] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.122s)
[200.919332] Starting dynamic subtest: xbgr16161616f-4tiled
[201.039506] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.132s)
[201.041250] Subtest draw-method-render: SUCCESS (1.967s)
[201.191461] [034/133] (821s left) gem_softpin (safe-alignment)
[201.256998] Starting subtest: safe-alignment
[201.275147] Subtest safe-alignment: SUCCESS (0.018s)
[202.962370] [035/133] (819s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render)
[203.070928] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render
[203.075153] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[203.198777] [036/133] (819s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[203.321671] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc
[210.418345] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.101s)
[210.543580] [037/133] (811s left) gem_ctx_persistence (many-contexts)
[210.613448] Starting subtest: many-contexts
[255.828173] Subtest many-contexts: SUCCESS (45.215s)
[255.931535] [038/133] (766s left) gem_reg_read (bad-register)
[255.988644] Starting subtest: bad-register
[255.990821] Subtest bad-register: SUCCESS (0.000s)
[256.105152] [039/133] (766s left) kms_pm_dc (dc5-psr)
[257.213982] Starting subtest: dc5-psr
[260.100384] Subtest dc5-psr: SUCCESS (2.886s)
[260.950624] [040/133] (761s left) kms_cursor_legacy (2x-long-nonblocking-modeset-vs-cursor-atomic)
[261.083394] Starting subtest: 2x-long-nonblocking-modeset-vs-cursor-atomic
[261.087977] Subtest 2x-long-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[261.251297] [041/133] (761s left) gem_ctx_engines (none)
[261.330717] Starting subtest: none
[261.333065] Subtest none: SUCCESS (0.001s)
[261.538094] [042/133] (761s left) kms_addfb_basic (no-handle)
[261.604802] Starting subtest: no-handle
[261.606735] Subtest no-handle: SUCCESS (0.000s)
[261.775411] [043/133] (760s left) i915_drm_fdinfo (busy-idle)
[261.813845] Starting subtest: busy-idle
[261.816056] Starting dynamic subtest: rcs0
[261.859242] Dynamic subtest rcs0: SKIP (0.046s)
[261.868759] Starting dynamic subtest: bcs0
[261.872729] Dynamic subtest bcs0: SKIP (0.001s)
[261.880777] Starting dynamic subtest: vcs0
[261.885058] Dynamic subtest vcs0: SKIP (0.000s)
[261.892479] Starting dynamic subtest: vcs1
[261.896471] Dynamic subtest vcs1: SKIP (0.000s)
[261.903687] Starting dynamic subtest: vecs0
[261.907667] Dynamic subtest vecs0: SKIP (0.000s)
[261.914654] Starting dynamic subtest: ccs0
[261.918667] Dynamic subtest ccs0: SKIP (0.000s)
[261.922783] Subtest busy-idle: SKIP (0.054s)
[261.959905] [044/133] (760s left) perf_pmu (busy-start)
[262.007079] Starting subtest: busy-start
[262.009059] Starting dynamic subtest: rcs0
[264.555536] Dynamic subtest rcs0: SUCCESS (2.550s)
[264.567071] Starting dynamic subtest: bcs0
[267.105392] Dynamic subtest bcs0: SUCCESS (2.548s)
[267.116897] Starting dynamic subtest: vcs0
[270.932086] Dynamic subtest vcs0: SUCCESS (3.823s)
[270.943832] Starting dynamic subtest: vcs1
[274.900639] Dynamic subtest vcs1: SUCCESS (3.965s)
[274.912530] Starting dynamic subtest: vecs0
[278.932096] Dynamic subtest vecs0: SUCCESS (4.029s)
[278.943631] Starting dynamic subtest: ccs0
[281.481317] Dynamic subtest ccs0: SUCCESS (2.548s)
[281.483801] Subtest busy-start: SUCCESS (19.477s)
[281.616762] [045/133] (741s left) kms_selftest (drm_dp_mst_helper)
[281.654846] Starting subtest: drm_dp_mst_helper
[281.689252] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_mode
[281.826522] Dynamic subtest drm_test_dp_mst_calc_pbn_mode: SUCCESS (0.135s)
[281.839234] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_div
[281.974323] Dynamic subtest drm_test_dp_mst_calc_pbn_div: SUCCESS (0.149s)
[281.982696] Starting dynamic subtest: drm_test_dp_mst_sideband_msg_req_decode
[282.114568] Dynamic subtest drm_test_dp_mst_sideband_msg_req_decode: SUCCESS (0.138s)
[282.116477] Subtest drm_dp_mst_helper: SUCCESS (0.459s)
[282.326901] [046/133] (740s left) gem_exec_schedule (pi-userfault)
[282.517456] Starting subtest: pi-userfault
[282.519513] Starting dynamic subtest: rcs0
[282.530712] Dynamic subtest rcs0: SUCCESS (0.016s)
[282.541287] Starting dynamic subtest: bcs0
[282.543502] Dynamic subtest bcs0: SUCCESS (0.011s)
[282.553496] Starting dynamic subtest: vcs0
[282.555776] Dynamic subtest vcs0: SUCCESS (0.010s)
[282.564397] Starting dynamic subtest: vcs1
[282.566678] Dynamic subtest vcs1: SUCCESS (0.009s)
[282.575373] Starting dynamic subtest: vecs0
[282.577273] Dynamic subtest vecs0: SUCCESS (0.007s)
[282.587017] Starting dynamic subtest: ccs0
[282.589243] Dynamic subtest ccs0: SUCCESS (0.011s)
[282.591427] Subtest pi-userfault: SUCCESS (0.070s)
[282.784683] [047/133] (740s left) gem_userptr_blits (input-checking)
[282.861218] Starting subtest: input-checking
[282.863632] Subtest input-checking: SUCCESS (0.000s)
[283.013111] [048/133] (739s left) kms_psr (fbc-pr-primary-page-flip)
[283.106771] Starting subtest: fbc-pr-primary-page-flip
[283.111137] Subtest fbc-pr-primary-page-flip: SKIP (0.000s)
[283.198982] [049/133] (739s left) kms_psr (fbc-psr-sprite-mmap-cpu)
[283.296134] Starting subtest: fbc-psr-sprite-mmap-cpu
[283.298303] Starting dynamic subtest: eDP-1
[283.302191] Dynamic subtest eDP-1: SKIP (0.000s)
[283.306152] Subtest fbc-psr-sprite-mmap-cpu: SKIP (0.001s)
[283.382868] [050/133] (739s left) kms_big_fb (y-tiled-8bpp-rotate-270)
[283.488110] Starting subtest: y-tiled-8bpp-rotate-270
[283.492573] Subtest y-tiled-8bpp-rotate-270: SKIP (0.000s)
[283.600437] [051/133] (739s left) kms_psr (pr-primary-page-flip)
[283.693597] Starting subtest: pr-primary-page-flip
[283.697441] Subtest pr-primary-page-flip: SKIP (0.000s)
[283.784018] [052/133] (739s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[283.888287] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu
[283.891794] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[284.010602] [053/133] (739s left) syncobj_wait (multi-wait-submitted-signaled)
[284.073253] Starting subtest: multi-wait-submitted-signaled
[284.075113] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s)
[284.180175] [054/133] (739s left) gem_flink_basic (basic)
[284.244433] Starting subtest: basic
[284.246185] Subtest basic: SUCCESS (0.000s)
[284.343559] [055/133] (738s left) kms_invalid_mode (zero-clock)
[284.440370] Starting subtest: zero-clock
[284.442212] Starting dynamic subtest: pipe-A-eDP-1
[284.725732] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.286s)
[284.734678] Starting dynamic subtest: pipe-B-eDP-1
[284.736845] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[284.744890] Starting dynamic subtest: pipe-C-eDP-1
[284.747063] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[284.755023] Starting dynamic subtest: pipe-D-eDP-1
[284.756890] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[284.758817] Subtest zero-clock: SUCCESS (0.292s)
[285.569225] [056/133] (737s left) sysfs_preempt_timeout (idempotent)
[285.623665] Starting subtest: idempotent
[285.626079] Starting dynamic subtest: vecs0
[285.628321] Dynamic subtest vecs0: SUCCESS (0.000s)
[285.638180] Starting dynamic subtest: ccs0
[285.640309] Dynamic subtest ccs0: SUCCESS (0.000s)
[285.648519] Starting dynamic subtest: vcs0
[285.650643] Dynamic subtest vcs0: SUCCESS (0.000s)
[285.658666] Starting dynamic subtest: bcs0
[285.660803] Dynamic subtest bcs0: SUCCESS (0.000s)
[285.668903] Starting dynamic subtest: rcs0
[285.671071] Dynamic subtest rcs0: SUCCESS (0.000s)
[285.678914] Starting dynamic subtest: vcs1
[285.680826] Dynamic subtest vcs1: SUCCESS (0.000s)
[285.682744] Subtest idempotent: SUCCESS (0.006s)
[285.746311] [057/133] (737s left) kms_plane (pixel-format-x-tiled-modifier-source-clamping)
[285.836927] Starting subtest: pixel-format-x-tiled-modifier-source-clamping
[285.863634] Starting dynamic subtest: pipe-A-plane-0
[286.518881] Dynamic subtest pipe-A-plane-0: SUCCESS (0.655s)
[286.527790] Starting dynamic subtest: pipe-A-plane-3
[286.929845] Dynamic subtest pipe-A-plane-3: SUCCESS (0.410s)
[288.053794] Starting dynamic subtest: pipe-B-plane-0
[288.691509] Dynamic subtest pipe-B-plane-0: SUCCESS (0.644s)
[288.703147] Starting dynamic subtest: pipe-B-plane-3
[289.090954] Dynamic subtest pipe-B-plane-3: SUCCESS (0.397s)
[289.412726] Subtest pixel-format-x-tiled-modifier-source-clamping: SUCCESS (3.574s)
[290.251862] [058/133] (733s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[290.365656] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[290.368067] Starting dynamic subtest: A-eDP1
[293.947063] Dynamic subtest A-eDP1: SUCCESS (3.579s)
[293.958534] Starting dynamic subtest: B-eDP1
[297.145285] Dynamic subtest B-eDP1: SUCCESS (3.195s)
[297.154997] Starting dynamic subtest: C-eDP1
[300.226112] Dynamic subtest C-eDP1: SUCCESS (3.080s)
[300.235771] Starting dynamic subtest: D-eDP1
[303.318461] Dynamic subtest D-eDP1: SUCCESS (3.091s)
[303.320825] Subtest single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (12.953s)
[304.117310] [059/133] (719s left) gem_basic (bad-close)
[304.167052] Starting subtest: bad-close
[304.171275] Subtest bad-close: SUCCESS (0.000s)
[304.287448] [060/133] (719s left) kms_chamelium_color (ctm-0-75)
[304.386789] Subtest ctm-0-75: SKIP (0.000s)
[304.430726] [061/133] (719s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation)
[304.535315] Starting subtest: plane-scaler-with-clipping-clamping-rotation
[304.537657] Starting dynamic subtest: pipe-A
[304.762522] Dynamic subtest pipe-A: SUCCESS (0.229s)
[304.775952] Starting dynamic subtest: pipe-B
[306.101822] Dynamic subtest pipe-B: SUCCESS (1.338s)
[306.110328] Starting dynamic subtest: pipe-C
[307.415600] Dynamic subtest pipe-C: SUCCESS (1.313s)
[307.424947] Starting dynamic subtest: pipe-D
[308.742051] Dynamic subtest pipe-D: SUCCESS (1.325s)
[308.744483] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (4.209s)
[309.946334] [062/133] (713s left) kms_psr (psr2-no-drrs)
[310.052213] Starting subtest: psr2-no-drrs
[310.054340] Starting dynamic subtest: eDP-1
[311.685268] Dynamic subtest eDP-1: SUCCESS (1.632s)
[311.687077] Subtest psr2-no-drrs: SUCCESS (1.633s)
[312.500415] [063/133] (711s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[312.560319] Starting subtest: single-wait-for-submit-available-submitted
[312.562572] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.000s)
[312.682533] [064/133] (710s left) kms_big_fb (x-tiled-addfb)
[312.792320] Starting subtest: x-tiled-addfb
[312.794529] Subtest x-tiled-addfb: SUCCESS (0.000s)
[312.923248] [065/133] (710s left) gem_exec_reloc (basic-range-active)
[312.999325] Subtest basic-range-active: SKIP (0.000s)
[313.092268] [066/133] (710s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[313.187273] Starting subtest: bad-pixel-format-y-tiled-ccs
[313.189583] Starting dynamic subtest: pipe-A-eDP-1
[313.212588] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[313.223377] Starting dynamic subtest: pipe-B-eDP-1
[313.244453] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[313.252520] Starting dynamic subtest: pipe-C-eDP-1
[313.273963] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[313.281834] Starting dynamic subtest: pipe-D-eDP-1
[313.303728] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[313.308063] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.006s)
[315.452104] [067/133] (708s left) syncobj_timeline (reset-during-wait-for-submit)
[315.513533] Starting subtest: reset-during-wait-for-submit
[315.615453] Subtest reset-during-wait-for-submit: SUCCESS (0.103s)
[315.707586] [068/133] (708s left) api_intel_bb (blit-reloc-purge-cache)
[315.768817] Starting subtest: blit-reloc-purge-cache
[315.773446] Subtest blit-reloc-purge-cache: SKIP (0.001s)
[315.918333] [069/133] (707s left) kms_cursor_legacy (cursora-vs-flipb-varying-size)
[316.048297] Starting subtest: cursorA-vs-flipB-varying-size
[316.052820] Subtest cursorA-vs-flipB-varying-size: SKIP (0.001s)
[316.206438] [070/133] (707s left) kms_joiner (switch-modeset-ultra-joiner-big-joiner)
[316.310229] Starting subtest: switch-modeset-ultra-joiner-big-joiner
[316.314125] Subtest switch-modeset-ultra-joiner-big-joiner: SKIP (0.000s)
[316.404373] [071/133] (707s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-fully-sf)
[316.508274] Starting subtest: pr-overlay-plane-move-continuous-exceed-fully-sf
[316.512247] Subtest pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[316.590184] [072/133] (707s left) dmabuf_sync_file (import-multiple-read-only)
[316.631364] Starting subtest: import-multiple-read-only
[316.633150] Subtest import-multiple-read-only: SUCCESS (0.003s)
[316.701434] [073/133] (707s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[316.824176] Starting subtest: cursorA-vs-flipA-legacy
[327.952059] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.125s)
[328.779554] [074/133] (695s left) kms_flip (2x-plain-flip-ts-check-interruptible)
[328.891067] Starting subtest: 2x-plain-flip-ts-check-interruptible
[328.903765] Subtest 2x-plain-flip-ts-check-interruptible: SKIP (0.011s)
[329.038899] [075/133] (695s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[329.140702] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip
[330.230203] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (1.089s)
[330.400601] [076/133] (693s left) api_intel_bb (object-reloc-purge-cache)
[330.455877] Starting subtest: object-reloc-purge-cache
[330.459740] Subtest object-reloc-purge-cache: SKIP (0.002s)
[330.588613] [077/133] (693s left) syncobj_basic (bad-destroy-pad)
[330.650494] Starting subtest: bad-destroy-pad
[330.652768] Subtest bad-destroy-pad: SUCCESS (0.000s)
[330.762157] [078/133] (693s left) gem_exec_params (invalid-bsd1-flag-on-vebox)
[330.816396] Starting subtest: invalid-bsd1-flag-on-vebox
[330.818238] Subtest invalid-bsd1-flag-on-vebox: SUCCESS (0.000s)
[330.929257] [079/133] (693s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted-signaled)
[330.977058] Starting subtest: etime-multi-wait-all-for-submit-submitted-signaled
[331.080718] Subtest etime-multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.102s)
[331.183557] [080/133] (693s left) syncobj_wait (multi-wait-all-for-submit-submitted-signaled)
[331.248694] Starting subtest: multi-wait-all-for-submit-submitted-signaled
[331.353391] Subtest multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.104s)
[331.441745] [081/133] (692s left) i915_power (sanity)
[331.499158] Starting subtest: sanity
[335.557641] Subtest sanity: SUCCESS (4.053s)
[336.969153] [082/133] (687s left) syncobj_basic (bad-flags-fd-to-handle)
[337.036438] Starting subtest: bad-flags-fd-to-handle
[337.038736] Subtest bad-flags-fd-to-handle: SUCCESS (0.000s)
[337.161211] [083/133] (687s left) kms_cursor_crc (cursor-offscreen-64x64)
[337.353499] Starting subtest: cursor-offscreen-64x64
[337.355729] Starting dynamic subtest: pipe-A-eDP-1
[339.288960] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.931s)
[339.317926] Starting dynamic subtest: pipe-D-eDP-1
[341.900857] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.589s)
[341.902839] Subtest cursor-offscreen-64x64: SUCCESS (4.548s)
[342.745067] [084/133] (681s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[342.858776] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[343.682748] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.826s)
[343.958795] [085/133] (680s left) gem_exec_fence (invalid-timeline-fence-array)
[344.517790] Starting subtest: invalid-timeline-fence-array
[344.524079] Subtest invalid-timeline-fence-array: SUCCESS (0.006s)
[346.068743] [086/133] (678s left) gem_exec_balancer (bonded-sync)
[346.146369] Starting subtest: bonded-sync
[346.150666] Subtest bonded-sync: SKIP (0.000s)
[346.456086] [087/133] (678s left) gem_workarounds (suspend-resume-fd)
[346.524162] Starting subtest: suspend-resume-fd
[362.805733] Subtest suspend-resume-fd: SUCCESS (2.144s)
[362.965090] [088/133] (661s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-onoff)
[363.096795] Starting subtest: psr-1p-primscrn-spr-indfb-onoff
[370.187672] Subtest psr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.093s)
[370.325533] [089/133] (654s left) kms_hdr (invalid-metadata-sizes)
[370.444213] Starting subtest: invalid-metadata-sizes
[370.450579] Subtest invalid-metadata-sizes: SKIP (0.000s)
[370.529015] [090/133] (654s left) gem_softpin (evict-snoop)
[370.609869] Starting subtest: evict-snoop
[370.614715] Subtest evict-snoop: SKIP (0.000s)
[370.758101] [091/133] (654s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-pwrite)
[370.857589] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-pwrite
[370.861501] Subtest fbc-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[370.976282] [092/133] (653s left) gem_pxp (verify-pxp-stale-buf-optout-execution)
[371.078822] Starting subtest: verify-pxp-stale-buf-optout-execution
[372.138738] Subtest verify-pxp-stale-buf-optout-execution: SUCCESS (1.060s)
[372.286506] [093/133] (652s left) kms_cursor_legacy (cursor-vs-flip-toggle)
[372.408012] Starting subtest: cursor-vs-flip-toggle
[403.173797] Subtest cursor-vs-flip-toggle: SUCCESS (30.762s)
[404.024447] [094/133] (620s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[404.145034] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[404.149437] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[404.260429] [095/133] (620s left) gem_userptr_blits (invalid-null-pointer)
[404.321970] Starting subtest: invalid-null-pointer
[404.323890] Subtest invalid-null-pointer: SUCCESS (0.001s)
[404.478917] [096/133] (620s left) kms_cursor_crc (cursor-random-32x10)
[404.642854] Starting subtest: cursor-random-32x10
[404.690586] Subtest cursor-random-32x10: SKIP (0.041s)
[404.977509] [097/133] (620s left) syncobj_wait (single-wait-for-submit-signaled)
[405.040433] Starting subtest: single-wait-for-submit-signaled
[405.042224] Subtest single-wait-for-submit-signaled: SUCCESS (0.000s)
[405.173339] [098/133] (619s left) gem_exec_whisper (basic-fds-priority-all)
[405.243948] Starting subtest: basic-fds-priority-all
[427.641781] Subtest basic-fds-priority-all: SUCCESS (22.390s)
[427.850589] [099/133] (597s left) gem_exec_flush (basic-uc-set-default)
[427.915619] Starting subtest: basic-uc-set-default
[433.378410] Subtest basic-uc-set-default: SUCCESS (5.463s)
[433.588844] [100/133] (591s left) gem_eio (in-flight-contexts-immediate)
[433.835919] Starting subtest: in-flight-contexts-immediate
[447.066265] Subtest in-flight-contexts-immediate: SUCCESS (13.227s)
[447.262998] [101/133] (577s left) kms_vrr (flip-basic-fastset)
[447.367317] Starting subtest: flip-basic-fastset
[447.374020] Subtest flip-basic-fastset: SKIP (0.000s)
[447.465191] [102/133] (577s left) kms_addfb_basic (small-bo)
[447.562296] Starting subtest: small-bo
[447.564627] Subtest small-bo: SUCCESS (0.000s)
[447.689089] [103/133] (577s left) kms_content_protection (atomic-dpms-hdcp14)
[447.840650] Starting subtest: atomic-dpms-hdcp14
[447.845177] Subtest atomic-dpms-hdcp14: SKIP (0.000s)
[447.993913] [104/133] (577s left) gem_exec_suspend (basic-s3)
[448.051642] Starting subtest: basic-S3
[448.053453] Starting dynamic subtest: smem
[468.684082] Dynamic subtest smem: SUCCESS (6.999s)
[468.687238] Subtest basic-S3: SUCCESS (7.001s)
[468.881175] [105/133] (556s left) syncobj_timeline (invalid-signal-bad-pad)
[468.961875] Starting subtest: invalid-signal-bad-pad
[468.964368] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[469.091600] [106/133] (556s left) gem_lmem_swapping (verify-random)
[469.134460] Subtest verify-random: SKIP (0.000s)
[469.177840] [107/133] (556s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180)
[469.283588] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180
[469.288234] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[469.413488] [108/133] (556s left) gem_mmap_offset (perf)
[469.477860] Starting subtest: perf
[469.480047] Starting dynamic subtest: smem0
[469.844832] Dynamic subtest smem0: SUCCESS (0.364s)
[469.847298] Subtest perf: SUCCESS (0.365s)
[469.991203] [109/133] (555s left) kms_prop_blob (basic)
[470.053992] Starting subtest: basic
[470.056321] Subtest basic: SUCCESS (0.000s)
[470.186796] [110/133] (555s left) kms_cursor_edge_walk (128x128-top-bottom)
[470.291642] Starting subtest: 128x128-top-bottom
[470.293911] Starting dynamic subtest: pipe-A-eDP-1
[473.761970] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.472s)
[473.777431] Starting dynamic subtest: pipe-D-eDP-1
[476.893806] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.129s)
[476.896092] Subtest 128x128-top-bottom: SUCCESS (6.604s)
[477.721101] [111/133] (547s left) kms_psr2_su (frontbuffer-xrgb8888)
[477.787098] Subtest frontbuffer-XRGB8888: SKIP (0.000s)
[477.846604] [112/133] (547s left) gem_mmap_gtt (basic-write-cpu-read-gtt)
[477.904937] Subtest basic-write-cpu-read-gtt: SKIP (0.000s)
[477.962671] [113/133] (547s left) kms_cursor_legacy (cursora-vs-flipa-atomic)
[478.085465] Starting subtest: cursorA-vs-flipA-atomic
[489.200638] Subtest cursorA-vs-flipA-atomic: SUCCESS (11.115s)
[490.045830] [114/133] (535s left) kms_pipe_b_c_ivb (pipe-b-dpms-off-modeset-pipe-c)
[490.108886] Subtest pipe-B-dpms-off-modeset-pipe-C: SKIP (0.000s)
[490.177221] [115/133] (535s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs)
[490.289122] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs
[490.291432] Starting dynamic subtest: pipe-A-eDP-1
[506.941981] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.405s)
[506.952583] Starting dynamic subtest: pipe-B-eDP-1
[523.891165] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.399s)
[523.901499] Starting dynamic subtest: pipe-C-eDP-1
[540.866894] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.317s)
[540.877411] Starting dynamic subtest: pipe-D-eDP-1
[557.885350] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.357s)
[557.888194] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs: SUCCESS (8.484s)
[559.137685] [116/133] (466s left) perf (gen12-mi-rpc)
[559.279647] Starting subtest: gen12-mi-rpc
[559.281884] Starting dynamic subtest: rcs0
[559.297871] Dynamic subtest rcs0: SUCCESS (0.019s)
[559.300060] Subtest gen12-mi-rpc: SUCCESS (0.020s)
[559.353103] [117/133] (466s left) gem_busy (parallel)
[559.578234] Starting subtest: parallel
[559.581175] Starting dynamic subtest: rcs0
[560.814891] Dynamic subtest rcs0: SUCCESS (1.233s)
[560.829364] Starting dynamic subtest: bcs0
[562.798060] Dynamic subtest bcs0: SUCCESS (1.978s)
[562.815666] Starting dynamic subtest: vcs0
[564.783024] Dynamic subtest vcs0: SUCCESS (1.980s)
[564.796438] Starting dynamic subtest: vcs1
[566.766360] Dynamic subtest vcs1: SUCCESS (1.979s)
[566.776442] Starting dynamic subtest: vecs0
[568.815218] Dynamic subtest vecs0: SUCCESS (2.046s)
[568.828208] Starting dynamic subtest: ccs0
[570.799080] Dynamic subtest ccs0: SUCCESS (1.980s)
[570.802399] Subtest parallel: SUCCESS (11.212s)
[571.028640] [118/133] (454s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[571.099611] Starting subtest: y-tiled-to-vebox-y-tiled
[571.104647] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s)
[571.300502] [119/133] (454s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-render)
[571.429062] Starting subtest: psr-1p-primscrn-cur-indfb-draw-render
[578.164442] Subtest psr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.735s)
[578.281712] [120/133] (447s left) kms_async_flips (invalid-async-flip-atomic)
[578.381229] Starting subtest: invalid-async-flip-atomic
[578.756157] Starting dynamic subtest: pipe-A-eDP-1
[579.568975] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.815s)
[579.937699] Starting dynamic subtest: pipe-B-eDP-1
[580.704642] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.774s)
[581.060601] Starting dynamic subtest: pipe-C-eDP-1
[581.830249] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.778s)
[582.187997] Starting dynamic subtest: pipe-D-eDP-1
[582.959576] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.782s)
[582.962182] Subtest invalid-async-flip-atomic: SUCCESS (4.580s)
[584.150183] [121/133] (441s left) gem_render_copy_redux (normal)
[584.218215] Starting subtest: normal
[584.520081] Subtest normal: SUCCESS (0.300s)
[584.670710] [122/133] (441s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-blt)
[584.775758] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-blt
[584.781180] Subtest psr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[584.886658] [123/133] (441s left) kms_ccs (crc-primary-basic-y-tiled-gen12-rc-ccs)
[584.970990] Starting subtest: crc-primary-basic-y-tiled-gen12-rc-ccs
[584.973698] Starting dynamic subtest: pipe-A-eDP-1
[584.999941] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[585.010926] Starting dynamic subtest: pipe-B-eDP-1
[585.034914] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[585.044579] Starting dynamic subtest: pipe-C-eDP-1
[585.063625] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[585.072824] Starting dynamic subtest: pipe-D-eDP-1
[585.091494] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[585.094955] Subtest crc-primary-basic-y-tiled-gen12-rc-ccs: SKIP (0.007s)
[587.203912] [124/133] (439s left) gem_exec_params (larger-than-life-batch)
[587.272050] Starting subtest: larger-than-life-batch
[589.498684] Subtest larger-than-life-batch: SUCCESS (2.225s)
[590.832505] [125/133] (435s left) kms_chamelium_hpd (hdmi-hpd)
[591.283407] Subtest hdmi-hpd: SKIP (0.000s)
[592.106765] [126/133] (434s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[592.204613] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[592.206977] Starting dynamic subtest: pipe-A-eDP-1
[592.487934] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.252s)
[592.499776] Starting dynamic subtest: pipe-B-eDP-1
[593.851745] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.359s)
[593.863875] Starting dynamic subtest: pipe-C-eDP-1
[595.126771] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.278s)
[595.137083] Starting dynamic subtest: pipe-D-eDP-1
[596.339598] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.205s)
[596.342168] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (4.099s)
[597.477460] [127/133] (428s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[597.576198] Starting subtest: disable-crc-after-crtc
[597.578924] Starting dynamic subtest: pipe-A-eDP-1
[599.194940] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.620s)
[599.205606] Starting dynamic subtest: pipe-B-eDP-1
[601.453047] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.257s)
[601.464825] Starting dynamic subtest: pipe-C-eDP-1
[603.701994] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.247s)
[603.719333] Starting dynamic subtest: pipe-D-eDP-1
[605.962970] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.260s)
[605.966061] Subtest disable-crc-after-crtc: SUCCESS (8.390s)
[606.812988] [128/133] (419s left) sw_sync (sync_multi_producer_single_consumer)
[606.868005] Starting subtest: sync_multi_producer_single_consumer
[607.940758] Subtest sync_multi_producer_single_consumer: SUCCESS (1.075s)
[608.013433] [129/133] (418s left) kms_psr (psr-primary-mmap-gtt)
[608.119166] Starting subtest: psr-primary-mmap-gtt
[608.121969] Starting dynamic subtest: eDP-1
[609.586162] Dynamic subtest eDP-1: SKIP (1.465s)
[609.592000] Subtest psr-primary-mmap-gtt: SKIP (1.467s)
[609.772528] [130/133] (416s left) gem_exec_capture (capture-recoverable)
[609.850602] Starting subtest: capture-recoverable
[609.856046] Subtest capture-recoverable: SUCCESS (0.004s)
[610.001708] [131/133] (416s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-move)
[610.123035] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-move
[610.127954] Subtest fbcpsr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[610.224939] [132/133] (416s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-blt)
[610.325996] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-blt
[614.470383] Subtest fbc-1p-offscreen-pri-indfb-draw-blt: SUCCESS (4.147s)
[615.637350] [133/133] (410s left) i915_module_load (reload-no-display)
[615.682365] Starting subtest: reload-no-display
[621.778495] Subtest reload-no-display: SUCCESS (6.097s)
[621.809674] [FACT i915_module_load (reload-no-display)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[621.810409] [FACT i915_module_load (reload-no-display)] deleted: kernel.kmod_is_loaded.i915: true
[621.810549] Closing watchdogs
results: parsing output: 0/ for test: kms_busy
results: parsing output: 1/ for test: kms_plane_scaling
results: parsing output: 2/ for test: gem_pxp
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_properties
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_async_flips
results: parsing output: 7/ for test: kms_flip_scaled_crc
results: parsing output: 8/ for test: gem_exec_params
results: parsing output: 9/ for test: kms_cursor_legacy
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_flip
results: parsing output: 12/ for test: gem_exec_schedule
results: parsing output: 13/ for test: gem_lmem_swapping
results: parsing output: 14/ for test: gem_ctx_shared
results: parsing output: 15/ for test: gem_userptr_blits
results: parsing output: 16/ for test: kms_flip_scaled_crc
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: gem_ctx_persistence
results: parsing output: 20/ for test: i915_pm_rpm
results: parsing output: 21/ for test: prime_vgem
results: parsing output: 22/ for test: syncobj_timeline
results: parsing output: 23/ for test: gen9_exec_parse
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: gem_partial_pwrite_pread
results: parsing output: 26/ for test: gem_wait
results: parsing output: 27/ for test: kms_big_fb
results: parsing output: 28/ for test: kms_plane_multiple
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_color
results: parsing output: 31/ for test: kms_chamelium_frames
results: parsing output: 32/ for test: kms_draw_crc
results: parsing output: 33/ for test: gem_softpin
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: gem_ctx_persistence
results: parsing output: 37/ for test: gem_reg_read
results: parsing output: 38/ for test: kms_pm_dc
results: parsing output: 39/ for test: kms_cursor_legacy
results: parsing output: 40/ for test: gem_ctx_engines
results: parsing output: 41/ for test: kms_addfb_basic
results: parsing output: 42/ for test: i915_drm_fdinfo
results: parsing output: 43/ for test: perf_pmu
results: parsing output: 44/ for test: kms_selftest
results: parsing output: 45/ for test: gem_exec_schedule
results: parsing output: 46/ for test: gem_userptr_blits
results: parsing output: 47/ for test: kms_psr
results: parsing output: 48/ for test: kms_psr
results: parsing output: 49/ for test: kms_big_fb
results: parsing output: 50/ for test: kms_psr
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: syncobj_wait
results: parsing output: 53/ for test: gem_flink_basic
results: parsing output: 54/ for test: kms_invalid_mode
results: parsing output: 55/ for test: sysfs_preempt_timeout
results: parsing output: 56/ for test: kms_plane
results: parsing output: 57/ for test: kms_flip
results: parsing output: 58/ for test: gem_basic
results: parsing output: 59/ for test: kms_chamelium_color
results: parsing output: 60/ for test: kms_plane_scaling
results: parsing output: 61/ for test: kms_psr
results: parsing output: 62/ for test: syncobj_timeline
results: parsing output: 63/ for test: kms_big_fb
results: parsing output: 64/ for test: gem_exec_reloc
results: parsing output: 65/ for test: kms_ccs
results: parsing output: 66/ for test: syncobj_timeline
results: parsing output: 67/ for test: api_intel_bb
results: parsing output: 68/ for test: kms_cursor_legacy
results: parsing output: 69/ for test: kms_joiner
results: parsing output: 70/ for test: kms_psr2_sf
results: parsing output: 71/ for test: dmabuf_sync_file
results: parsing output: 72/ for test: kms_cursor_legacy
results: parsing output: 73/ for test: kms_flip
results: parsing output: 74/ for test: kms_big_fb
results: parsing output: 75/ for test: api_intel_bb
results: parsing output: 76/ for test: syncobj_basic
results: parsing output: 77/ for test: gem_exec_params
results: parsing output: 78/ for test: syncobj_timeline
results: parsing output: 79/ for test: syncobj_wait
results: parsing output: 80/ for test: i915_power
results: parsing output: 81/ for test: syncobj_basic
results: parsing output: 82/ for test: kms_cursor_crc
results: parsing output: 83/ for test: kms_big_fb
results: parsing output: 84/ for test: gem_exec_fence
results: parsing output: 85/ for test: gem_exec_balancer
results: parsing output: 86/ for test: gem_workarounds
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: kms_hdr
results: parsing output: 89/ for test: gem_softpin
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: gem_pxp
results: parsing output: 92/ for test: kms_cursor_legacy
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: gem_userptr_blits
results: parsing output: 95/ for test: kms_cursor_crc
results: parsing output: 96/ for test: syncobj_wait
results: parsing output: 97/ for test: gem_exec_whisper
results: parsing output: 98/ for test: gem_exec_flush
results: parsing output: 99/ for test: gem_eio
results: parsing output: 100/ for test: kms_vrr
results: parsing output: 101/ for test: kms_addfb_basic
results: parsing output: 102/ for test: kms_content_protection
results: parsing output: 103/ for test: gem_exec_suspend
results: parsing output: 104/ for test: syncobj_timeline
results: parsing output: 105/ for test: gem_lmem_swapping
results: parsing output: 106/ for test: kms_big_fb
results: parsing output: 107/ for test: gem_mmap_offset
results: parsing output: 108/ for test: kms_prop_blob
results: parsing output: 109/ for test: kms_cursor_edge_walk
results: parsing output: 110/ for test: kms_psr2_su
results: parsing output: 111/ for test: gem_mmap_gtt
results: parsing output: 112/ for test: kms_cursor_legacy
results: parsing output: 113/ for test: kms_pipe_b_c_ivb
results: parsing output: 114/ for test: kms_ccs
results: parsing output: 115/ for test: perf
results: parsing output: 116/ for test: gem_busy
results: parsing output: 117/ for test: gem_render_copy
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_async_flips
results: parsing output: 120/ for test: gem_render_copy_redux
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_ccs
results: parsing output: 123/ for test: gem_exec_params
results: parsing output: 124/ for test: kms_chamelium_hpd
results: parsing output: 125/ for test: kms_ccs
results: parsing output: 126/ for test: kms_pipe_crc_basic
results: parsing output: 127/ for test: sw_sync
results: parsing output: 128/ for test: kms_psr
results: parsing output: 129/ for test: gem_exec_capture
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: i915_module_load
Done.