[56.826928] Initializing watchdogs
[56.826973]   /dev/watchdog0
[56.841094] [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.845575] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.848056] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.851207] [001/133] (960s left) kms_busy (extended-modeset-hang-newfb)
[56.963280] Starting subtest: extended-modeset-hang-newfb
[56.965632] Starting dynamic subtest: pipe-A
[92.834402] Dynamic subtest pipe-A: SUCCESS (35.882s)
[92.842952] Starting dynamic subtest: pipe-D
[115.851838] Dynamic subtest pipe-D: SUCCESS (23.016s)
[115.854167] Subtest extended-modeset-hang-newfb: SUCCESS (58.902s)
[116.657185] [002/133] (900s left) kms_plane_scaling (plane-upscale-factor-0-25-with-rotation)
[116.763048] Starting subtest: plane-upscale-factor-0-25-with-rotation
[116.765406] Starting dynamic subtest: pipe-A
[116.987015] Dynamic subtest pipe-A: SUCCESS (0.227s)
[116.995689] Starting dynamic subtest: pipe-B
[118.244303] Dynamic subtest pipe-B: SUCCESS (1.257s)
[118.252645] Starting dynamic subtest: pipe-C
[119.574411] Dynamic subtest pipe-C: SUCCESS (1.330s)
[119.583646] Starting dynamic subtest: pipe-D
[120.913536] Dynamic subtest pipe-D: SUCCESS (1.339s)
[120.915961] Subtest plane-upscale-factor-0-25-with-rotation: SUCCESS (4.153s)
[122.100907] [003/133] (895s left) gem_pxp (fail-invalid-protected-context)
[122.233679] Starting subtest: fail-invalid-protected-context
[122.235771] Subtest fail-invalid-protected-context: SUCCESS (0.000s)
[122.351266] [004/133] (895s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-wc)
[122.460058] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-wc
[122.464104] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[122.574896] [005/133] (894s left) kms_properties (colorop-properties-legacy)
[122.676063] Starting subtest: colorop-properties-legacy
[122.678383] Starting dynamic subtest: pipe-A-eDP-1
[123.014204] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.338s)
[123.023534] Starting dynamic subtest: pipe-B-eDP-1
[124.071395] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.054s)
[124.080928] Starting dynamic subtest: pipe-C-eDP-1
[125.096572] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.025s)
[125.105742] Starting dynamic subtest: pipe-D-eDP-1
[126.139027] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.043s)
[126.141405] Subtest colorop-properties-legacy: SUCCESS (3.464s)
[126.948934] [006/133] (890s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-move)
[127.051206] Starting subtest: fbc-1p-primscrn-cur-indfb-move
[131.176243] Subtest fbc-1p-primscrn-cur-indfb-move: SUCCESS (4.127s)
[132.366472] [007/133] (885s left) kms_async_flips (basic-modeset-with-all-modifiers-formats)
[132.474662] Starting subtest: basic-modeset-with-all-modifiers-formats
[132.844864] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB8888
[133.588755] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB8888: SUCCESS (0.744s)
[133.597845] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB2101010
[133.610088] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB2101010: SUCCESS (0.021s)
[133.617341] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB16161616F
[133.632299] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB16161616F: SUCCESS (0.022s)
[133.639643] Starting dynamic subtest: pipe-A-eDP-1-4-RGB565
[133.776821] Dynamic subtest pipe-A-eDP-1-4-RGB565: SUCCESS (0.144s)
[133.785852] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB8888
[133.799069] Dynamic subtest pipe-A-eDP-1-4-XRGB8888: SUCCESS (0.022s)
[133.806694] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB2101010
[133.823198] Dynamic subtest pipe-A-eDP-1-4-XRGB2101010: SUCCESS (0.022s)
[133.835396] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB16161616F
[133.843431] Dynamic subtest pipe-A-eDP-1-4-XRGB16161616F: SUCCESS (0.021s)
[133.851949] Starting dynamic subtest: pipe-A-eDP-1-4-YUYV
[133.865764] Dynamic subtest pipe-A-eDP-1-4-YUYV: SUCCESS (0.022s)
[133.874587] Starting dynamic subtest: pipe-A-eDP-1-4-Y210
[133.888092] Dynamic subtest pipe-A-eDP-1-4-Y210: SUCCESS (0.022s)
[133.895262] Starting dynamic subtest: pipe-A-eDP-1-4-XYUV8888
[133.910169] Dynamic subtest pipe-A-eDP-1-4-XYUV8888: SUCCESS (0.022s)
[133.917453] Starting dynamic subtest: pipe-A-eDP-1-4-XV30
[133.932251] Dynamic subtest pipe-A-eDP-1-4-XV30: SUCCESS (0.022s)
[133.939486] Starting dynamic subtest: pipe-A-eDP-1-4-XV36
[133.956247] Dynamic subtest pipe-A-eDP-1-4-XV36: SUCCESS (0.022s)
[133.965326] Starting dynamic subtest: pipe-A-eDP-1-x-RGB565
[134.110206] Dynamic subtest pipe-A-eDP-1-x-RGB565: SUCCESS (0.155s)
[134.121269] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB8888
[134.132339] Dynamic subtest pipe-A-eDP-1-x-XRGB8888: SUCCESS (0.022s)
[134.139829] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB2101010
[134.154249] Dynamic subtest pipe-A-eDP-1-x-XRGB2101010: SUCCESS (0.022s)
[134.161620] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB16161616F
[134.176796] Dynamic subtest pipe-A-eDP-1-x-XRGB16161616F: SUCCESS (0.022s)
[134.183993] Starting dynamic subtest: pipe-A-eDP-1-x-YUYV
[134.199059] Dynamic subtest pipe-A-eDP-1-x-YUYV: SUCCESS (0.022s)
[134.206675] Starting dynamic subtest: pipe-A-eDP-1-x-Y210
[134.221755] Dynamic subtest pipe-A-eDP-1-x-Y210: SUCCESS (0.022s)
[134.231847] Starting dynamic subtest: pipe-A-eDP-1-x-XYUV8888
[134.243388] Dynamic subtest pipe-A-eDP-1-x-XYUV8888: SUCCESS (0.021s)
[134.250775] Starting dynamic subtest: pipe-A-eDP-1-x-XV30
[134.265647] Dynamic subtest pipe-A-eDP-1-x-XV30: SUCCESS (0.022s)
[134.273043] Starting dynamic subtest: pipe-A-eDP-1-x-XV36
[134.287621] Dynamic subtest pipe-A-eDP-1-x-XV36: SUCCESS (0.022s)
[134.295410] Starting dynamic subtest: pipe-A-eDP-1-linear-RGB565
[134.488469] Dynamic subtest pipe-A-eDP-1-linear-RGB565: SUCCESS (0.200s)
[134.496761] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB8888
[134.510509] Dynamic subtest pipe-A-eDP-1-linear-XRGB8888: SUCCESS (0.022s)
[134.520952] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB2101010
[134.532427] Dynamic subtest pipe-A-eDP-1-linear-XRGB2101010: SUCCESS (0.022s)
[134.541697] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB16161616F
[134.554492] Dynamic subtest pipe-A-eDP-1-linear-XRGB16161616F: SUCCESS (0.022s)
[134.563216] Starting dynamic subtest: pipe-A-eDP-1-linear-YUYV
[134.578423] Dynamic subtest pipe-A-eDP-1-linear-YUYV: SUCCESS (0.022s)
[134.585825] Starting dynamic subtest: pipe-A-eDP-1-linear-Y210
[134.600520] Dynamic subtest pipe-A-eDP-1-linear-Y210: SUCCESS (0.022s)
[134.611927] Starting dynamic subtest: pipe-A-eDP-1-linear-XYUV8888
[134.622409] Dynamic subtest pipe-A-eDP-1-linear-XYUV8888: SUCCESS (0.022s)
[134.629355] Starting dynamic subtest: pipe-A-eDP-1-linear-XV30
[134.643473] Dynamic subtest pipe-A-eDP-1-linear-XV30: SUCCESS (0.022s)
[134.650723] Starting dynamic subtest: pipe-A-eDP-1-linear-XV36
[134.665842] Dynamic subtest pipe-A-eDP-1-linear-XV36: SUCCESS (0.022s)
[135.052430] Subtest basic-modeset-with-all-modifiers-formats: SUCCESS (2.580s)
[135.880500] [008/133] (881s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-upscaling)
[135.966639] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-upscaling
[135.971053] Subtest flip-32bpp-yftile-to-64bpp-yftile-upscaling: SKIP (0.000s)
[136.044613] [009/133] (881s left) gem_exec_params (rel-constants-invalid-rel-gen5)
[136.108515] Starting subtest: rel-constants-invalid-rel-gen5
[136.110904] Subtest rel-constants-invalid-rel-gen5: SUCCESS (0.000s)
[136.220846] [010/133] (881s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions)
[136.339169] Starting subtest: short-busy-flip-before-cursor-atomic-transitions
[136.343720] Subtest short-busy-flip-before-cursor-atomic-transitions: SKIP (0.000s)
[136.494884] [011/133] (881s left) kms_frontbuffer_tracking (fbcpsr-indfb-scaledprimary)
[136.600888] Starting subtest: fbcpsr-indfb-scaledprimary
[142.369326] Subtest fbcpsr-indfb-scaledprimary: SUCCESS (5.772s)
[142.513159] [012/133] (875s left) kms_flip (2x-flip-vs-absolute-wf_vblank)
[142.620737] Starting subtest: 2x-flip-vs-absolute-wf_vblank
[142.630045] Subtest 2x-flip-vs-absolute-wf_vblank: SKIP (0.011s)
[142.721521] [013/133] (875s left) gem_exec_schedule (promotion)
[142.787189] Starting subtest: promotion
[142.789437] Starting dynamic subtest: rcs0
[142.897506] Dynamic subtest rcs0: SUCCESS (0.117s)
[142.905472] Starting dynamic subtest: vcs0
[142.995049] Dynamic subtest vcs0: SUCCESS (0.097s)
[143.006033] Starting dynamic subtest: vcs1
[143.074205] Dynamic subtest vcs1: SUCCESS (0.079s)
[143.085090] Starting dynamic subtest: vecs0
[143.151850] Dynamic subtest vecs0: SUCCESS (0.077s)
[143.163024] Starting dynamic subtest: ccs0
[143.234354] Dynamic subtest ccs0: SUCCESS (0.082s)
[143.236835] Subtest promotion: SUCCESS (0.455s)
[145.049228] [014/133] (872s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[145.098750] Subtest heavy-verify-multi-ccs: SKIP (0.000s)
[145.144041] [015/133] (872s left) gem_ctx_shared (disjoint-timelines)
[145.212451] Starting subtest: disjoint-timelines
[145.267174] Subtest disjoint-timelines: SUCCESS (0.055s)
[145.544455] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true
[145.547716] [016/133] (872s left) gem_userptr_blits (map-fixed-invalidate)
[145.621363] Starting subtest: map-fixed-invalidate
[145.625763] Subtest map-fixed-invalidate: SKIP (0.000s)
[145.785700] [017/133] (872s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-downscaling)
[145.891737] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-downscaling
[145.893978] Starting dynamic subtest: pipe-A-default-mode
[147.059574] Dynamic subtest pipe-A-default-mode: SKIP (1.166s)
[147.063634] Subtest flip-64bpp-xtile-to-32bpp-xtile-downscaling: SKIP (1.168s)
[147.238117] [018/133] (870s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite)
[147.335219] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite
[156.663742] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.330s)
[156.833251] [019/133] (861s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[156.931325] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[156.935677] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[157.048871] [020/133] (861s left) gem_ctx_persistence (engines-hang)
[157.112257] Starting subtest: engines-hang
[157.114621] Starting dynamic subtest: rcs0
[157.217732] Dynamic subtest rcs0: SUCCESS (0.105s)
[157.229672] Starting dynamic subtest: bcs0
[157.319572] Dynamic subtest bcs0: SUCCESS (0.100s)
[157.332888] Starting dynamic subtest: vcs0
[157.431788] Dynamic subtest vcs0: SUCCESS (0.111s)
[157.442669] Starting dynamic subtest: vcs1
[157.542524] Dynamic subtest vcs1: SUCCESS (0.110s)
[157.552637] Starting dynamic subtest: vecs0
[157.659911] Dynamic subtest vecs0: SUCCESS (0.116s)
[157.671082] Starting dynamic subtest: ccs0
[157.734080] Dynamic subtest ccs0: SUCCESS (0.074s)
[157.736145] Subtest engines-hang: SUCCESS (0.623s)
[157.877640] [021/133] (860s left) i915_pm_rpm (sysfs-read)
[160.017597] Starting subtest: sysfs-read
[161.189095] Subtest sysfs-read: SUCCESS (1.168s)
[161.575046] [022/133] (856s left) prime_vgem (coherency-blt)
[161.655711] Starting subtest: coherency-blt
[162.724253] Subtest coherency-blt: SUCCESS (1.067s)
[162.865697] [023/133] (855s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[162.926487] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[162.928600] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.000s)
[163.046020] [024/133] (855s left) gen9_exec_parse (batch-without-end)
[163.106589] Subtest batch-without-end: SKIP (0.000s)
[163.194839] [025/133] (855s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-pgflip-blt)
[163.300868] Starting subtest: fbc-2p-primscrn-shrfb-pgflip-blt
[163.305160] Subtest fbc-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[163.429372] [026/133] (854s left) gem_partial_pwrite_pread (writes-after-reads-display)
[163.492022] Subtest writes-after-reads-display: SKIP (0.000s)
[163.574683] [027/133] (854s left) gem_wait (wait)
[163.642967] Starting subtest: wait
[163.645624] Starting dynamic subtest: all-engines
[164.887662] Dynamic subtest all-engines: SUCCESS (1.243s)
[164.898432] Starting dynamic subtest: rcs0
[165.931077] Dynamic subtest rcs0: SUCCESS (1.044s)
[165.941863] Starting dynamic subtest: bcs0
[166.977250] Dynamic subtest bcs0: SUCCESS (1.045s)
[166.987923] Starting dynamic subtest: vcs0
[168.027783] Dynamic subtest vcs0: SUCCESS (1.048s)
[168.038523] Starting dynamic subtest: vcs1
[169.073314] Dynamic subtest vcs1: SUCCESS (1.044s)
[169.083561] Starting dynamic subtest: vecs0
[170.117317] Dynamic subtest vecs0: SUCCESS (1.042s)
[170.127910] Starting dynamic subtest: ccs0
[171.164071] Dynamic subtest ccs0: SUCCESS (1.045s)
[171.166638] Subtest wait: SUCCESS (7.522s)
[171.379851] [028/133] (847s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[171.487602] Starting subtest: 4-tiled-32bpp-rotate-180
[172.934260] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.448s)
[173.102569] [029/133] (845s left) kms_plane_multiple (tiling-yf)
[173.209667] Starting subtest: tiling-yf
[173.213067] Subtest tiling-yf: SKIP (0.000s)
[173.307732] [030/133] (845s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-render)
[173.419984] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-render
[173.423394] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[173.540327] [031/133] (845s left) kms_color (ctm-0-75)
[173.627068] Starting subtest: ctm-0-75
[173.629029] Starting dynamic subtest: pipe-A-eDP-1
[178.587488] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.959s)
[178.595989] Starting dynamic subtest: pipe-B-eDP-1
[184.233058] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.644s)
[184.243250] Starting dynamic subtest: pipe-C-eDP-1
[189.630309] Dynamic subtest pipe-C-eDP-1: SUCCESS (5.398s)
[189.638828] Starting dynamic subtest: pipe-D-eDP-1
[194.956062] Dynamic subtest pipe-D-eDP-1: SUCCESS (5.321s)
[194.957876] Subtest ctm-0-75: SUCCESS (21.326s)
[195.784306] [032/133] (822s left) kms_chamelium_frames (dp-crc-single)
[196.188283] Subtest dp-crc-single: SKIP (0.000s)
[196.993744] [033/133] (821s left) kms_draw_crc (draw-method-render)
[197.089930] Starting subtest: draw-method-render
[197.092201] Starting dynamic subtest: xrgb8888-untiled
[197.321763] Dynamic subtest xrgb8888-untiled: SUCCESS (0.233s)
[197.331340] Starting dynamic subtest: rgb565-untiled
[197.532971] Dynamic subtest rgb565-untiled: SUCCESS (0.210s)
[197.542361] Starting dynamic subtest: xrgb2101010-untiled
[197.744150] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.210s)
[197.752433] Starting dynamic subtest: xbgr16161616f-untiled
[197.977084] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.233s)
[197.985056] Starting dynamic subtest: xrgb8888-xtiled
[198.099219] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.122s)
[198.107525] Starting dynamic subtest: rgb565-xtiled
[198.210305] Dynamic subtest rgb565-xtiled: SUCCESS (0.110s)
[198.218087] Starting dynamic subtest: xrgb2101010-xtiled
[198.332557] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.121s)
[198.340720] Starting dynamic subtest: xbgr16161616f-xtiled
[198.465879] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s)
[198.474017] Starting dynamic subtest: xrgb8888-4tiled
[198.588031] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.122s)
[198.596181] Starting dynamic subtest: rgb565-4tiled
[198.699092] Dynamic subtest rgb565-4tiled: SUCCESS (0.111s)
[198.707392] Starting dynamic subtest: xrgb2101010-4tiled
[198.821611] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.122s)
[198.829728] Starting dynamic subtest: xbgr16161616f-4tiled
[198.954913] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.132s)
[198.956771] Subtest draw-method-render: SUCCESS (1.867s)
[199.106635] [034/133] (819s left) gem_softpin (safe-alignment)
[199.176714] Starting subtest: safe-alignment
[199.195285] Subtest safe-alignment: SUCCESS (0.021s)
[200.973784] [035/133] (817s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render)
[201.076460] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render
[201.080847] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[201.206245] [036/133] (817s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[201.290446] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc
[208.392309] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.104s)
[208.543226] [037/133] (810s left) gem_ctx_persistence (many-contexts)
[208.616075] Starting subtest: many-contexts
[253.835218] Subtest many-contexts: SUCCESS (45.218s)
[253.926567] [038/133] (764s left) gem_reg_read (bad-register)
[253.985390] Starting subtest: bad-register
[253.987721] Subtest bad-register: SUCCESS (0.000s)
[254.113538] [039/133] (764s left) kms_pm_dc (dc5-psr)
[255.215838] Starting subtest: dc5-psr
[258.100118] Subtest dc5-psr: SUCCESS (2.885s)
[258.964239] [040/133] (759s left) kms_cursor_legacy (2x-long-nonblocking-modeset-vs-cursor-atomic)
[259.088301] Starting subtest: 2x-long-nonblocking-modeset-vs-cursor-atomic
[259.092768] Subtest 2x-long-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[259.258651] [041/133] (759s left) gem_ctx_engines (none)
[259.331115] Starting subtest: none
[259.333383] Subtest none: SUCCESS (0.001s)
[259.528233] [042/133] (759s left) kms_addfb_basic (no-handle)
[259.587917] Starting subtest: no-handle
[259.590075] Subtest no-handle: SUCCESS (0.000s)
[259.752881] [043/133] (759s left) i915_drm_fdinfo (busy-idle)
[259.791251] Starting subtest: busy-idle
[259.793148] Starting dynamic subtest: rcs0
[259.809881] Dynamic subtest rcs0: SKIP (0.017s)
[259.819276] Starting dynamic subtest: bcs0
[259.823554] Dynamic subtest bcs0: SKIP (0.010s)
[259.831759] Starting dynamic subtest: vcs0
[259.835902] Dynamic subtest vcs0: SKIP (0.000s)
[259.843424] Starting dynamic subtest: vcs1
[259.847396] Dynamic subtest vcs1: SKIP (0.000s)
[259.855082] Starting dynamic subtest: vecs0
[259.859217] Dynamic subtest vecs0: SKIP (0.001s)
[259.866690] Starting dynamic subtest: ccs0
[259.870981] Dynamic subtest ccs0: SKIP (0.000s)
[259.875373] Subtest busy-idle: SKIP (0.034s)
[259.912221] [044/133] (759s left) perf_pmu (busy-start)
[259.949548] Starting subtest: busy-start
[259.951781] Starting dynamic subtest: rcs0
[262.499489] Dynamic subtest rcs0: SUCCESS (2.549s)
[262.510669] Starting dynamic subtest: bcs0
[265.049629] Dynamic subtest bcs0: SUCCESS (2.548s)
[265.060697] Starting dynamic subtest: vcs0
[268.887928] Dynamic subtest vcs0: SUCCESS (3.834s)
[268.898122] Starting dynamic subtest: vcs1
[272.920085] Dynamic subtest vcs1: SUCCESS (4.029s)
[272.929492] Starting dynamic subtest: vecs0
[276.887224] Dynamic subtest vecs0: SUCCESS (3.964s)
[276.898215] Starting dynamic subtest: ccs0
[279.437809] Dynamic subtest ccs0: SUCCESS (2.549s)
[279.440656] Subtest busy-start: SUCCESS (19.488s)
[279.565294] [045/133] (739s left) kms_selftest (drm_dp_mst_helper)
[279.611899] Starting subtest: drm_dp_mst_helper
[279.641895] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_mode
[279.776160] Dynamic subtest drm_test_dp_mst_calc_pbn_mode: SUCCESS (0.135s)
[279.784501] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_div
[279.928255] Dynamic subtest drm_test_dp_mst_calc_pbn_div: SUCCESS (0.150s)
[279.935999] Starting dynamic subtest: drm_test_dp_mst_sideband_msg_req_decode
[280.070506] Dynamic subtest drm_test_dp_mst_sideband_msg_req_decode: SUCCESS (0.141s)
[280.072431] Subtest drm_dp_mst_helper: SUCCESS (0.458s)
[280.289875] [046/133] (738s left) gem_exec_schedule (pi-userfault)
[280.481293] Starting subtest: pi-userfault
[280.483572] Starting dynamic subtest: rcs0
[280.491099] Dynamic subtest rcs0: SUCCESS (0.013s)
[280.501379] Starting dynamic subtest: bcs0
[280.503731] Dynamic subtest bcs0: SUCCESS (0.010s)
[280.514981] Starting dynamic subtest: vcs0
[280.517286] Dynamic subtest vcs0: SUCCESS (0.012s)
[280.525576] Starting dynamic subtest: vcs1
[280.531122] Dynamic subtest vcs1: SUCCESS (0.014s)
[280.541131] Starting dynamic subtest: vecs0
[280.542988] Dynamic subtest vecs0: SUCCESS (0.010s)
[280.551107] Starting dynamic subtest: ccs0
[280.553458] Dynamic subtest ccs0: SUCCESS (0.011s)
[280.555635] Subtest pi-userfault: SUCCESS (0.074s)
[280.758168] [047/133] (738s left) gem_userptr_blits (input-checking)
[280.831239] Starting subtest: input-checking
[280.833447] Subtest input-checking: SUCCESS (0.000s)
[281.001280] [048/133] (738s left) kms_psr (fbc-pr-primary-page-flip)
[281.106743] Starting subtest: fbc-pr-primary-page-flip
[281.110969] Subtest fbc-pr-primary-page-flip: SKIP (0.000s)
[281.190008] [049/133] (738s left) kms_psr (fbc-psr-sprite-mmap-cpu)
[281.280298] Starting subtest: fbc-psr-sprite-mmap-cpu
[281.282490] Starting dynamic subtest: eDP-1
[281.286645] Dynamic subtest eDP-1: SKIP (0.000s)
[281.290804] Subtest fbc-psr-sprite-mmap-cpu: SKIP (0.001s)
[281.372306] [050/133] (737s left) kms_big_fb (y-tiled-8bpp-rotate-270)
[281.474316] Starting subtest: y-tiled-8bpp-rotate-270
[281.478235] Subtest y-tiled-8bpp-rotate-270: SKIP (0.000s)
[281.603467] [051/133] (737s left) kms_psr (pr-primary-page-flip)
[281.696056] Starting subtest: pr-primary-page-flip
[281.699548] Subtest pr-primary-page-flip: SKIP (0.000s)
[281.780984] [052/133] (737s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[281.886169] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu
[281.889479] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[282.023572] [053/133] (737s left) syncobj_wait (multi-wait-submitted-signaled)
[282.085058] Starting subtest: multi-wait-submitted-signaled
[282.086806] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s)
[282.207696] [054/133] (737s left) gem_flink_basic (basic)
[282.271802] Starting subtest: basic
[282.273551] Subtest basic: SUCCESS (0.000s)
[282.396510] [055/133] (737s left) kms_invalid_mode (zero-clock)
[282.491857] Starting subtest: zero-clock
[282.493842] Starting dynamic subtest: pipe-A-eDP-1
[282.785978] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.292s)
[282.795072] Starting dynamic subtest: pipe-B-eDP-1
[282.797192] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[282.805694] Starting dynamic subtest: pipe-C-eDP-1
[282.807841] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[282.816089] Starting dynamic subtest: pipe-D-eDP-1
[282.818022] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[282.819857] Subtest zero-clock: SUCCESS (0.298s)
[283.608616] [056/133] (735s left) sysfs_preempt_timeout (idempotent)
[283.662875] Starting subtest: idempotent
[283.665370] Starting dynamic subtest: vecs0
[283.667690] Dynamic subtest vecs0: SUCCESS (0.000s)
[283.678075] Starting dynamic subtest: ccs0
[283.680218] Dynamic subtest ccs0: SUCCESS (0.000s)
[283.688651] Starting dynamic subtest: vcs0
[283.690852] Dynamic subtest vcs0: SUCCESS (0.000s)
[283.700780] Starting dynamic subtest: bcs0
[283.702947] Dynamic subtest bcs0: SUCCESS (0.000s)
[283.711068] Starting dynamic subtest: rcs0
[283.713236] Dynamic subtest rcs0: SUCCESS (0.000s)
[283.721168] Starting dynamic subtest: vcs1
[283.723082] Dynamic subtest vcs1: SUCCESS (0.000s)
[283.725033] Subtest idempotent: SUCCESS (0.007s)
[283.786465] [057/133] (735s left) kms_plane (pixel-format-x-tiled-modifier-source-clamping)
[283.877586] Starting subtest: pixel-format-x-tiled-modifier-source-clamping
[283.904450] Starting dynamic subtest: pipe-A-plane-0
[284.582081] Dynamic subtest pipe-A-plane-0: SUCCESS (0.678s)
[284.590548] Starting dynamic subtest: pipe-A-plane-3
[285.004178] Dynamic subtest pipe-A-plane-3: SUCCESS (0.422s)
[286.085456] Starting dynamic subtest: pipe-B-plane-0
[286.747118] Dynamic subtest pipe-B-plane-0: SUCCESS (0.666s)
[286.756804] Starting dynamic subtest: pipe-B-plane-3
[287.133632] Dynamic subtest pipe-B-plane-3: SUCCESS (0.386s)
[287.463686] Subtest pixel-format-x-tiled-modifier-source-clamping: SUCCESS (3.585s)
[288.284623] [058/133] (731s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[288.401929] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[288.406710] Starting dynamic subtest: A-eDP1
[292.005333] Dynamic subtest A-eDP1: SUCCESS (3.599s)
[292.014652] Starting dynamic subtest: B-eDP1
[295.212082] Dynamic subtest B-eDP1: SUCCESS (3.203s)
[295.220943] Starting dynamic subtest: C-eDP1
[298.322278] Dynamic subtest C-eDP1: SUCCESS (3.110s)
[298.330858] Starting dynamic subtest: D-eDP1
[301.436120] Dynamic subtest D-eDP1: SUCCESS (3.111s)
[301.438314] Subtest single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (13.034s)
[302.245289] [059/133] (717s left) gem_basic (bad-close)
[302.301162] Starting subtest: bad-close
[302.305325] Subtest bad-close: SUCCESS (0.000s)
[302.417894] [060/133] (717s left) kms_chamelium_color (ctm-0-75)
[302.518747] Subtest ctm-0-75: SKIP (0.000s)
[302.564202] [061/133] (717s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation)
[302.663819] Starting subtest: plane-scaler-with-clipping-clamping-rotation
[302.666076] Starting dynamic subtest: pipe-A
[302.902199] Dynamic subtest pipe-A: SUCCESS (0.240s)
[302.911654] Starting dynamic subtest: pipe-B
[304.221248] Dynamic subtest pipe-B: SUCCESS (1.318s)
[304.229765] Starting dynamic subtest: pipe-C
[305.563301] Dynamic subtest pipe-C: SUCCESS (1.341s)
[305.572818] Starting dynamic subtest: pipe-D
[306.895962] Dynamic subtest pipe-D: SUCCESS (1.332s)
[306.898248] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (4.234s)
[308.091165] [062/133] (711s left) kms_psr (psr2-no-drrs)
[308.186790] Starting subtest: psr2-no-drrs
[308.189028] Starting dynamic subtest: eDP-1
[309.753608] Dynamic subtest eDP-1: SUCCESS (1.566s)
[309.755771] Subtest psr2-no-drrs: SUCCESS (1.567s)
[310.558793] [063/133] (709s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[310.621602] Starting subtest: single-wait-for-submit-available-submitted
[310.623548] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.000s)
[310.755665] [064/133] (709s left) kms_big_fb (x-tiled-addfb)
[310.861044] Starting subtest: x-tiled-addfb
[310.863191] Subtest x-tiled-addfb: SUCCESS (0.000s)
[310.987026] [065/133] (708s left) gem_exec_reloc (basic-range-active)
[311.036741] Subtest basic-range-active: SKIP (0.000s)
[311.109366] [066/133] (708s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[311.206325] Starting subtest: bad-pixel-format-y-tiled-ccs
[311.208635] Starting dynamic subtest: pipe-A-eDP-1
[311.231611] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[311.243134] Starting dynamic subtest: pipe-B-eDP-1
[311.264700] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[311.273065] Starting dynamic subtest: pipe-C-eDP-1
[311.294570] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[311.302749] Starting dynamic subtest: pipe-D-eDP-1
[311.324343] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[311.328203] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.005s)
[313.434528] [067/133] (706s left) syncobj_timeline (reset-during-wait-for-submit)
[313.494934] Starting subtest: reset-during-wait-for-submit
[313.596595] Subtest reset-during-wait-for-submit: SUCCESS (0.103s)
[313.687453] [068/133] (706s left) api_intel_bb (blit-reloc-purge-cache)
[313.736928] Starting subtest: blit-reloc-purge-cache
[313.741257] Subtest blit-reloc-purge-cache: SKIP (0.001s)
[313.886331] [069/133] (706s left) kms_cursor_legacy (cursora-vs-flipb-varying-size)
[314.012306] Starting subtest: cursorA-vs-flipB-varying-size
[314.016590] Subtest cursorA-vs-flipB-varying-size: SKIP (0.001s)
[314.180327] [070/133] (705s left) kms_joiner (switch-modeset-ultra-joiner-big-joiner)
[314.286278] Starting subtest: switch-modeset-ultra-joiner-big-joiner
[314.290535] Subtest switch-modeset-ultra-joiner-big-joiner: SKIP (0.000s)
[314.369351] [071/133] (705s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-fully-sf)
[314.460970] Starting subtest: pr-overlay-plane-move-continuous-exceed-fully-sf
[314.464449] Subtest pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[314.545155] [072/133] (705s left) dmabuf_sync_file (import-multiple-read-only)
[314.594968] Starting subtest: import-multiple-read-only
[314.599037] Subtest import-multiple-read-only: SUCCESS (0.006s)
[314.659727] [073/133] (705s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[314.785793] Starting subtest: cursorA-vs-flipA-legacy
[325.910402] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.124s)
[326.797050] [074/133] (693s left) kms_flip (2x-plain-flip-ts-check-interruptible)
[326.907890] Starting subtest: 2x-plain-flip-ts-check-interruptible
[326.922879] Subtest 2x-plain-flip-ts-check-interruptible: SKIP (0.014s)
[327.024082] [075/133] (693s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[327.126714] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip
[328.138648] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (1.012s)
[328.276014] [076/133] (692s left) api_intel_bb (object-reloc-purge-cache)
[328.338424] Starting subtest: object-reloc-purge-cache
[328.342349] Subtest object-reloc-purge-cache: SKIP (0.003s)
[328.460483] [077/133] (691s left) syncobj_basic (bad-destroy-pad)
[328.518943] Starting subtest: bad-destroy-pad
[328.520807] Subtest bad-destroy-pad: SUCCESS (0.000s)
[328.629166] [078/133] (691s left) gem_exec_params (invalid-bsd1-flag-on-vebox)
[328.690221] Starting subtest: invalid-bsd1-flag-on-vebox
[328.692224] Subtest invalid-bsd1-flag-on-vebox: SUCCESS (0.000s)
[328.802776] [079/133] (691s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted-signaled)
[328.858689] Starting subtest: etime-multi-wait-all-for-submit-submitted-signaled
[328.963285] Subtest etime-multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.104s)
[329.056232] [080/133] (691s left) syncobj_wait (multi-wait-all-for-submit-submitted-signaled)
[329.118707] Starting subtest: multi-wait-all-for-submit-submitted-signaled
[329.218284] Subtest multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.101s)
[329.310136] [081/133] (691s left) i915_power (sanity)
[329.374016] Starting subtest: sanity
[333.425816] Subtest sanity: SUCCESS (4.045s)
[334.937804] [082/133] (685s left) syncobj_basic (bad-flags-fd-to-handle)
[335.000430] Starting subtest: bad-flags-fd-to-handle
[335.002542] Subtest bad-flags-fd-to-handle: SUCCESS (0.000s)
[335.120973] [083/133] (685s left) kms_cursor_crc (cursor-offscreen-64x64)
[335.309552] Starting subtest: cursor-offscreen-64x64
[335.314416] Starting dynamic subtest: pipe-A-eDP-1
[337.242695] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.929s)
[337.271548] Starting dynamic subtest: pipe-D-eDP-1
[339.865334] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.601s)
[339.867639] Subtest cursor-offscreen-64x64: SUCCESS (4.556s)
[340.723523] [084/133] (679s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[340.841463] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[341.654374] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.814s)
[341.848992] [085/133] (678s left) gem_exec_fence (invalid-timeline-fence-array)
[342.416124] Starting subtest: invalid-timeline-fence-array
[342.418952] Subtest invalid-timeline-fence-array: SUCCESS (0.006s)
[344.002766] [086/133] (676s left) gem_exec_balancer (bonded-sync)
[344.068800] Starting subtest: bonded-sync
[344.073409] Subtest bonded-sync: SKIP (0.000s)
[344.382577] [087/133] (676s left) gem_workarounds (suspend-resume-fd)
[344.460559] Starting subtest: suspend-resume-fd
[361.042107] Subtest suspend-resume-fd: SUCCESS (1.338s)
[361.175080] [088/133] (659s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-onoff)
[361.293634] Starting subtest: psr-1p-primscrn-spr-indfb-onoff
[368.374845] Subtest psr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.120s)
[368.546633] [089/133] (652s left) kms_hdr (invalid-metadata-sizes)
[368.647575] Starting subtest: invalid-metadata-sizes
[368.655554] Subtest invalid-metadata-sizes: SKIP (0.000s)
[368.750433] [090/133] (652s left) gem_softpin (evict-snoop)
[368.819963] Starting subtest: evict-snoop
[368.825717] Subtest evict-snoop: SKIP (0.000s)
[368.968576] [091/133] (652s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-pwrite)
[369.081739] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-pwrite
[369.087494] Subtest fbc-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[369.207785] [092/133] (651s left) gem_pxp (verify-pxp-stale-buf-optout-execution)
[369.311710] Starting subtest: verify-pxp-stale-buf-optout-execution
[370.375193] Subtest verify-pxp-stale-buf-optout-execution: SUCCESS (1.066s)
[370.532623] [093/133] (650s left) kms_cursor_legacy (cursor-vs-flip-toggle)
[370.660367] Starting subtest: cursor-vs-flip-toggle
[401.550320] Subtest cursor-vs-flip-toggle: SUCCESS (30.912s)
[402.383771] [094/133] (618s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[402.504961] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[402.510330] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[402.626414] [095/133] (618s left) gem_userptr_blits (invalid-null-pointer)
[402.698206] Starting subtest: invalid-null-pointer
[402.700175] Subtest invalid-null-pointer: SUCCESS (0.001s)
[402.873476] [096/133] (618s left) kms_cursor_crc (cursor-random-32x10)
[403.037525] Starting subtest: cursor-random-32x10
[403.084437] Subtest cursor-random-32x10: SKIP (0.040s)
[403.380682] [097/133] (617s left) syncobj_wait (single-wait-for-submit-signaled)
[403.426770] Starting subtest: single-wait-for-submit-signaled
[403.429122] Subtest single-wait-for-submit-signaled: SUCCESS (0.000s)
[403.550265] [098/133] (617s left) gem_exec_whisper (basic-fds-priority-all)
[403.623151] Starting subtest: basic-fds-priority-all
[427.513960] Subtest basic-fds-priority-all: SUCCESS (23.894s)
[427.711460] [099/133] (593s left) gem_exec_flush (basic-uc-set-default)
[427.775802] Starting subtest: basic-uc-set-default
[433.240992] Subtest basic-uc-set-default: SUCCESS (5.466s)
[433.439755] [100/133] (587s left) gem_eio (in-flight-contexts-immediate)
[433.690736] Starting subtest: in-flight-contexts-immediate
[447.176366] Subtest in-flight-contexts-immediate: SUCCESS (13.490s)
[447.376105] [101/133] (574s left) kms_vrr (flip-basic-fastset)
[447.476737] Starting subtest: flip-basic-fastset
[447.483865] Subtest flip-basic-fastset: SKIP (0.000s)
[447.558641] [102/133] (573s left) kms_addfb_basic (small-bo)
[447.649964] Starting subtest: small-bo
[447.652750] Subtest small-bo: SUCCESS (0.000s)
[447.761792] [103/133] (573s left) kms_content_protection (atomic-dpms-hdcp14)
[447.955962] Starting subtest: atomic-dpms-hdcp14
[447.961576] Subtest atomic-dpms-hdcp14: SKIP (0.000s)
[448.115936] [104/133] (573s left) gem_exec_suspend (basic-s3)
[448.183508] Starting subtest: basic-S3
[448.185763] Starting dynamic subtest: smem
[468.965011] Dynamic subtest smem: SUCCESS (6.967s)
[468.968134] Subtest basic-S3: SUCCESS (6.970s)
[469.162479] [105/133] (552s left) syncobj_timeline (invalid-signal-bad-pad)
[469.236233] Starting subtest: invalid-signal-bad-pad
[469.238147] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[469.348944] [106/133] (552s left) gem_lmem_swapping (verify-random)
[469.393663] Subtest verify-random: SKIP (0.000s)
[469.443953] [107/133] (552s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180)
[469.537675] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180
[469.542192] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[469.652691] [108/133] (552s left) gem_mmap_offset (perf)
[469.711800] Starting subtest: perf
[469.713996] Starting dynamic subtest: smem0
[470.027397] Dynamic subtest smem0: SUCCESS (0.317s)
[470.029530] Subtest perf: SUCCESS (0.318s)
[470.171866] [109/133] (551s left) kms_prop_blob (basic)
[470.222784] Starting subtest: basic
[470.224684] Subtest basic: SUCCESS (0.000s)
[470.356647] [110/133] (551s left) kms_cursor_edge_walk (128x128-top-bottom)
[470.452609] Starting subtest: 128x128-top-bottom
[470.454865] Starting dynamic subtest: pipe-A-eDP-1
[473.898590] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.462s)
[473.909252] Starting dynamic subtest: pipe-D-eDP-1
[477.013498] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.123s)
[477.015947] Subtest 128x128-top-bottom: SUCCESS (6.588s)
[477.827915] [111/133] (544s left) kms_psr2_su (frontbuffer-xrgb8888)
[477.894143] Subtest frontbuffer-XRGB8888: SKIP (0.000s)
[477.965240] [112/133] (543s left) gem_mmap_gtt (basic-write-cpu-read-gtt)
[478.027850] Subtest basic-write-cpu-read-gtt: SKIP (0.000s)
[478.112807] [113/133] (543s left) kms_cursor_legacy (cursora-vs-flipa-atomic)
[478.228650] Starting subtest: cursorA-vs-flipA-atomic
[489.348785] Subtest cursorA-vs-flipA-atomic: SUCCESS (11.135s)
[490.234429] [114/133] (531s left) kms_pipe_b_c_ivb (pipe-b-dpms-off-modeset-pipe-c)
[490.298780] Subtest pipe-B-dpms-off-modeset-pipe-C: SKIP (0.000s)
[490.367366] [115/133] (531s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs)
[490.462567] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs
[490.464902] Starting dynamic subtest: pipe-A-eDP-1
[507.258775] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.478s)
[507.269942] Starting dynamic subtest: pipe-B-eDP-1
[526.489411] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.687s)
[526.501497] Starting dynamic subtest: pipe-C-eDP-1
[544.211150] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.385s)
[544.224676] Starting dynamic subtest: pipe-D-eDP-1
[561.164792] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.303s)
[561.167181] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs: SUCCESS (10.858s)
[562.336305] [116/133] (459s left) perf (gen12-mi-rpc)
[562.472177] Starting subtest: gen12-mi-rpc
[562.474344] Starting dynamic subtest: rcs0
[562.490098] Dynamic subtest rcs0: SUCCESS (0.018s)
[562.492163] Subtest gen12-mi-rpc: SUCCESS (0.019s)
[562.532840] [117/133] (459s left) gem_busy (parallel)
[562.763066] Starting subtest: parallel
[562.766380] Starting dynamic subtest: rcs0
[564.885811] Dynamic subtest rcs0: SUCCESS (2.132s)
[564.899400] Starting dynamic subtest: bcs0
[566.870535] Dynamic subtest bcs0: SUCCESS (1.989s)
[566.889756] Starting dynamic subtest: vcs0
[568.854721] Dynamic subtest vcs0: SUCCESS (1.987s)
[568.868906] Starting dynamic subtest: vcs1
[570.903106] Dynamic subtest vcs1: SUCCESS (2.050s)
[570.916274] Starting dynamic subtest: vecs0
[572.887963] Dynamic subtest vecs0: SUCCESS (1.986s)
[572.900549] Starting dynamic subtest: ccs0
[574.872508] Dynamic subtest ccs0: SUCCESS (1.985s)
[574.875342] Subtest parallel: SUCCESS (12.146s)
[575.133770] [118/133] (447s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[575.206384] Starting subtest: y-tiled-to-vebox-y-tiled
[575.211520] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s)
[575.406965] [119/133] (446s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-render)
[575.535466] Starting subtest: psr-1p-primscrn-cur-indfb-draw-render
[582.323156] Subtest psr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.796s)
[582.476007] [120/133] (439s left) kms_async_flips (invalid-async-flip-atomic)
[582.581603] Starting subtest: invalid-async-flip-atomic
[582.958223] Starting dynamic subtest: pipe-A-eDP-1
[583.817335] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.860s)
[584.175505] Starting dynamic subtest: pipe-B-eDP-1
[584.954941] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.789s)
[585.319657] Starting dynamic subtest: pipe-C-eDP-1
[586.067360] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.755s)
[586.423478] Starting dynamic subtest: pipe-D-eDP-1
[587.206498] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.794s)
[587.209392] Subtest invalid-async-flip-atomic: SUCCESS (4.631s)
[588.374894] [121/133] (433s left) gem_render_copy_redux (normal)
[588.447369] Starting subtest: normal
[588.700236] Subtest normal: SUCCESS (0.255s)
[588.846716] [122/133] (433s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-blt)
[588.951127] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-blt
[588.956527] Subtest psr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[589.072112] [123/133] (433s left) kms_ccs (crc-primary-basic-y-tiled-gen12-rc-ccs)
[589.165377] Starting subtest: crc-primary-basic-y-tiled-gen12-rc-ccs
[589.167736] Starting dynamic subtest: pipe-A-eDP-1
[589.190485] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[589.202481] Starting dynamic subtest: pipe-B-eDP-1
[589.227505] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[589.237505] Starting dynamic subtest: pipe-C-eDP-1
[589.262498] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[589.272393] Starting dynamic subtest: pipe-D-eDP-1
[589.298231] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[589.302486] Subtest crc-primary-basic-y-tiled-gen12-rc-ccs: SKIP (0.006s)
[591.433441] [124/133] (431s left) gem_exec_params (larger-than-life-batch)
[591.497941] Starting subtest: larger-than-life-batch
[593.821193] Subtest larger-than-life-batch: SUCCESS (2.323s)
[594.972325] [125/133] (427s left) kms_chamelium_hpd (hdmi-hpd)
[595.420607] Subtest hdmi-hpd: SKIP (0.000s)
[596.230148] [126/133] (426s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[596.321133] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[596.323514] Starting dynamic subtest: pipe-A-eDP-1
[596.596093] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.242s)
[596.607252] Starting dynamic subtest: pipe-B-eDP-1
[597.824426] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.226s)
[597.836494] Starting dynamic subtest: pipe-C-eDP-1
[599.025370] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.197s)
[599.037922] Starting dynamic subtest: pipe-D-eDP-1
[600.270301] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.246s)
[600.273295] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (3.915s)
[601.501165] [127/133] (421s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[601.601433] Starting subtest: disable-crc-after-crtc
[601.604373] Starting dynamic subtest: pipe-A-eDP-1
[603.236242] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.632s)
[603.247864] Starting dynamic subtest: pipe-B-eDP-1
[605.505213] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.271s)
[605.517383] Starting dynamic subtest: pipe-C-eDP-1
[607.772041] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.265s)
[607.784284] Starting dynamic subtest: pipe-D-eDP-1
[610.023442] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.250s)
[610.026395] Subtest disable-crc-after-crtc: SUCCESS (8.424s)
[610.872152] [128/133] (411s left) sw_sync (sync_multi_producer_single_consumer)
[610.926476] Starting subtest: sync_multi_producer_single_consumer
[612.000290] Subtest sync_multi_producer_single_consumer: SUCCESS (1.075s)
[612.074069] [129/133] (410s left) kms_psr (psr-primary-mmap-gtt)
[612.162725] Starting subtest: psr-primary-mmap-gtt
[612.165326] Starting dynamic subtest: eDP-1
[613.571773] Dynamic subtest eDP-1: SKIP (1.406s)
[613.577508] Subtest psr-primary-mmap-gtt: SKIP (1.409s)
[613.776760] [130/133] (408s left) gem_exec_capture (capture-recoverable)
[613.840627] Starting subtest: capture-recoverable
[613.843515] Subtest capture-recoverable: SUCCESS (0.003s)
[614.012767] [131/133] (408s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-move)
[614.117381] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-move
[614.122319] Subtest fbcpsr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[614.239433] [132/133] (408s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-blt)
[614.343661] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-blt
[618.432223] Subtest fbc-1p-offscreen-pri-indfb-draw-blt: SUCCESS (4.095s)
[619.592517] [133/133] (403s left) i915_module_load (reload-no-display)
[619.631153] Starting subtest: reload-no-display
[625.800293] Subtest reload-no-display: SUCCESS (6.170s)
[625.832143] [FACT i915_module_load (reload-no-display)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[625.832935] [FACT i915_module_load (reload-no-display)] deleted: kernel.kmod_is_loaded.i915: true
[625.833066] 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.